builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs slave: t-yosemite-r7-0136 starttime: 1496629577.82 results: success (0) buildid: 20170604183818 builduid: ce4c518c2a864e8f87120ac7258c17ad revision: 09f9827c36d63365507fa3999e66c298eed81808 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-04 19:26:17.816147) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-04 19:26:17.816565) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-04 19:26:17.816850) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-04 19:26:17.901735) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 19:26:17.902055) ========= 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.Yrjj5qEzyC/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.kH12Ds43ib/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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-06-04 19:26:17-- 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% 15.5M=0.001s 2017-06-04 19:26:18 (15.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.583586 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 19:26:18.511247) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 19:26:18.511556) ========= 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.Yrjj5qEzyC/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.kH12Ds43ib/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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.028405 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 19:26:18.563558) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-04 19:26:18.563930) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 09f9827c36d63365507fa3999e66c298eed81808 --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 09f9827c36d63365507fa3999e66c298eed81808 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Yrjj5qEzyC/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.kH12Ds43ib/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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-06-04 19:26:18,658 truncating revision to first 12 chars 2017-06-04 19:26:18,658 Setting DEBUG logging. 2017-06-04 19:26:18,658 attempt 1/10 2017-06-04 19:26:18,658 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/09f9827c36d6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-04 19:26:19,706 unpacking tar archive at: mozilla-beta-09f9827c36d6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.494561 ========= master_lag: 0.43 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-04 19:26:20.490041) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-04 19:26:20.492557) ========= script_repo_revision: 09f9827c36d63365507fa3999e66c298eed81808 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-04 19:26:20.492952) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 3 secs) (at 2017-06-04 19:26:20.494678) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 3 secs) (at 2017-06-04 19:26:23.888104) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-06-04 19:26:23.888380) ========= /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.Yrjj5qEzyC/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.kH12Ds43ib/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 19:26:24 INFO - MultiFileLogger online at 20170604 19:26:24 in /builds/slave/test 19:26:24 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 19:26:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:26:24 INFO - {'append_to_log': False, 19:26:24 INFO - 'base_work_dir': '/builds/slave/test', 19:26:24 INFO - 'blob_upload_branch': 'Mozilla-Beta', 19:26:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:26:24 INFO - 'branch': 'Mozilla-Beta', 19:26:24 INFO - 'buildbot_json_path': 'buildprops.json', 19:26:24 INFO - 'config_files': ('talos/mac_config.py',), 19:26:24 INFO - 'default_actions': ('clobber', 19:26:24 INFO - 'read-buildbot-config', 19:26:24 INFO - 'download-and-extract', 19:26:24 INFO - 'populate-webroot', 19:26:24 INFO - 'create-virtualenv', 19:26:24 INFO - 'install', 19:26:24 INFO - 'run-tests'), 19:26:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:26:24 INFO - 'download_minidump_stackwalk': True, 19:26:24 INFO - 'download_symbols': 'ondemand', 19:26:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:26:24 INFO - 'tooltool.py': '/tools/tooltool.py', 19:26:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:26:24 INFO - '/tools/misc-python/virtualenv.py')}, 19:26:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:26:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:26:24 INFO - 'gecko_profile': False, 19:26:24 INFO - 'gecko_profile_interval': 0, 19:26:24 INFO - 'installer_path': 'installer.exe', 19:26:24 INFO - 'log_level': 'info', 19:26:24 INFO - 'log_name': 'talos', 19:26:24 INFO - 'log_to_console': True, 19:26:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:26:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:26:24 INFO - 'opt_config_files': (), 19:26:24 INFO - 'pip_index': False, 19:26:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:26:24 INFO - 'cmd': ('bash', 19:26:24 INFO - '-c', 19:26:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:26:24 INFO - 'enabled': True, 19:26:24 INFO - 'halt_on_failure': False, 19:26:24 INFO - 'name': 'check_screen_resolution'},), 19:26:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:26:24 INFO - 'cmd': ('bash', 19:26:24 INFO - '-c', 19:26:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:26:24 INFO - 'enabled': True, 19:26:24 INFO - 'halt_on_failure': False, 19:26:24 INFO - 'name': 'check_screen_resolution'},), 19:26:24 INFO - 'run_cmd_checks_enabled': True, 19:26:24 INFO - 'suite': 'dromaeojs', 19:26:24 INFO - 'system_bits': '32', 19:26:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:26:24 INFO - 'title': 't-yosemite-r7-0136', 19:26:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:26:24 INFO - 'use_talos_json': True, 19:26:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:26:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:26:24 INFO - 'work_dir': 'build'} 19:26:24 INFO - [mozharness: 2017-06-05 02:26:24.209307Z] Running clobber step. 19:26:24 INFO - Running pre-action listener: _resource_record_pre_action 19:26:24 INFO - Running main action method: clobber 19:26:24 INFO - rmtree: /builds/slave/test/build 19:26:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:26:26 INFO - Running post-action listener: _resource_record_post_action 19:26:26 INFO - [mozharness: 2017-06-05 02:26:26.659858Z] Finished clobber step (success) 19:26:26 INFO - [mozharness: 2017-06-05 02:26:26.659989Z] Running read-buildbot-config step. 19:26:26 INFO - Running pre-action listener: _resource_record_pre_action 19:26:26 INFO - Running main action method: read_buildbot_config 19:26:26 INFO - Using buildbot properties: 19:26:26 INFO - { 19:26:26 INFO - "project": "", 19:26:26 INFO - "product": "firefox", 19:26:26 INFO - "script_repo_revision": "production", 19:26:26 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs", 19:26:26 INFO - "repository": "", 19:26:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs", 19:26:26 INFO - "buildid": "20170604183818", 19:26:26 INFO - "pgo_build": "False", 19:26:26 INFO - "basedir": "/builds/slave/test", 19:26:26 INFO - "buildnumber": 2, 19:26:26 INFO - "slavename": "t-yosemite-r7-0136", 19:26:26 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 19:26:26 INFO - "platform": "yosemite_r7_devedition", 19:26:26 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 19:26:26 INFO - "branch": "mozilla-beta", 19:26:26 INFO - "repo_path": "releases/mozilla-beta", 19:26:26 INFO - "revision": "09f9827c36d63365507fa3999e66c298eed81808", 19:26:26 INFO - "stage_platform": "macosx64-devedition", 19:26:26 INFO - "builduid": "ce4c518c2a864e8f87120ac7258c17ad", 19:26:26 INFO - "slavebuilddir": "test" 19:26:26 INFO - } 19:26:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 19:26:26 INFO - Running post-action listener: _resource_record_post_action 19:26:26 INFO - [mozharness: 2017-06-05 02:26:26.663221Z] Finished read-buildbot-config step (success) 19:26:26 INFO - [mozharness: 2017-06-05 02:26:26.663357Z] Running download-and-extract step. 19:26:26 INFO - Running pre-action listener: _resource_record_pre_action 19:26:26 INFO - Running main action method: download_and_extract 19:26:26 INFO - mkdir: /builds/slave/test/build/tests 19:26:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:26 INFO - trying https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 19:26:26 INFO - Downloading https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/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 19:26:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/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 19:26:27 INFO - Downloaded 1287 bytes. 19:26:27 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 19:26:27 INFO - Using the following test package requirements: 19:26:27 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:26:27 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 19:26:27 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 19:26:27 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:26:27 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 19:26:27 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:26:27 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 19:26:27 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 19:26:27 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:26:27 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 19:26:27 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 19:26:27 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:26:27 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 19:26:27 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:26:27 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 19:26:27 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:26:27 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 19:26:27 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:26:27 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 19:26:27 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 19:26:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 19:26:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 19:26:27 INFO - Fetch https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 19:26:30 INFO - Expected file size: 51966615 19:26:30 INFO - Obtained file size: 51966615 19:26:46 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 19:26:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 19:26:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 19:26:46 INFO - Fetch https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 19:26:47 INFO - Expected file size: 51966615 19:26:47 INFO - Obtained file size: 51966615 19:27:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 19:27:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 19:27:02 INFO - Fetch https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 19:27:03 INFO - Expected file size: 14404461 19:27:03 INFO - Obtained file size: 14404461 19:27:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:27:04 INFO - trying https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.dmg 19:27:04 INFO - Downloading https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 19:27:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/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 19:27:06 INFO - Downloaded 74895688 bytes. 19:27:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.dmg 19:27:06 INFO - mkdir: /builds/slave/test/properties 19:27:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:27:06 INFO - Writing to file /builds/slave/test/properties/build_url 19:27:06 INFO - Contents: 19:27:06 INFO - build_url:https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.dmg 19:27:07 INFO - Running post-action listener: _resource_record_post_action 19:27:07 INFO - Running post-action listener: set_extra_try_arguments 19:27:07 INFO - [mozharness: 2017-06-05 02:27:07.231453Z] Finished download-and-extract step (success) 19:27:07 INFO - [mozharness: 2017-06-05 02:27:07.231601Z] Running populate-webroot step. 19:27:07 INFO - Running pre-action listener: _resource_record_pre_action 19:27:07 INFO - Running main action method: populate_webroot 19:27:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:27:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:27:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:27:07 INFO - '--tppagecycles', 19:27:07 INFO - '7'], 19:27:07 INFO - 'tests': ['tsvgm']}}, 19:27:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:27:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:27:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:27:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:27:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:27:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:27:07 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:27:07 INFO - 'talos_options': ['--disable-e10s'], 19:27:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:27:07 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:27:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:27:07 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:27:07 INFO - 'talos_options': ['--disable-e10s'], 19:27:07 INFO - 'tests': ['damp', 'tps']}, 19:27:07 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:27:07 INFO - 'tests': ['damp', 'tps']}, 19:27:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:27:07 INFO - 'tests': ['dromaeo_dom']}, 19:27:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:27:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:27:07 INFO - 'tests': ['basic_compositor_video']}, 19:27:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:27:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:27:07 INFO - 'tests': ['a11yr', 19:27:07 INFO - 'ts_paint', 19:27:07 INFO - 'tpaint', 19:27:07 INFO - 'sessionrestore', 19:27:07 INFO - 'sessionrestore_no_auto_restore', 19:27:07 INFO - 'tabpaint']}, 19:27:07 INFO - 'other-e10s': {'tests': ['a11yr', 19:27:07 INFO - 'ts_paint', 19:27:07 INFO - 'tpaint', 19:27:07 INFO - 'sessionrestore', 19:27:07 INFO - 'sessionrestore_no_auto_restore', 19:27:07 INFO - 'tabpaint']}, 19:27:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:27:07 INFO - 'ts_paint', 19:27:07 INFO - 'tpaint', 19:27:07 INFO - 'sessionrestore', 19:27:07 INFO - 'sessionrestore_no_auto_restore', 19:27:07 INFO - 'tabpaint']}, 19:27:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:27:07 INFO - 'ts_paint', 19:27:07 INFO - 'tpaint', 19:27:07 INFO - 'sessionrestore', 19:27:07 INFO - 'sessionrestore_no_auto_restore', 19:27:07 INFO - 'tabpaint']}, 19:27:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:27:07 INFO - 'tests': ['a11yr', 19:27:07 INFO - 'ts_paint', 19:27:07 INFO - 'tpaint', 19:27:07 INFO - 'sessionrestore', 19:27:07 INFO - 'sessionrestore_no_auto_restore', 19:27:07 INFO - 'tabpaint']}, 19:27:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:27:07 INFO - 'tests': ['a11yr', 19:27:07 INFO - 'ts_paint', 19:27:07 INFO - 'tpaint', 19:27:07 INFO - 'sessionrestore', 19:27:07 INFO - 'sessionrestore_no_auto_restore', 19:27:07 INFO - 'tabpaint']}, 19:27:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:27:07 INFO - 'tests': ['tsvgx', 19:27:07 INFO - 'tsvgr_opacity', 19:27:07 INFO - 'tart', 19:27:07 INFO - 'tscrollx', 19:27:07 INFO - 'cart', 19:27:07 INFO - 'tsvg_static']}, 19:27:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:27:07 INFO - 'tsvgr_opacity', 19:27:07 INFO - 'tart', 19:27:07 INFO - 'tscrollx', 19:27:07 INFO - 'cart', 19:27:07 INFO - 'tsvg_static']}, 19:27:07 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:27:07 INFO - 'talos_options': ['--disable-e10s'], 19:27:07 INFO - 'tests': ['tp5o']}, 19:27:07 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:27:07 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:27:07 INFO - 'talos_options': ['--disable-e10s', 19:27:07 INFO - '--xperf_path', 19:27:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:27:07 INFO - 'tests': ['tp5n']}, 19:27:07 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:27:07 INFO - 'talos_options': ['--xperf_path', 19:27:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:27:07 INFO - 'tests': ['tp5n']}}, 19:27:07 INFO - 'talos.zip': {'path': '', 19:27:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:27:07 INFO - Running post-action listener: _resource_record_post_action 19:27:07 INFO - [mozharness: 2017-06-05 02:27:07.250539Z] Finished populate-webroot step (success) 19:27:07 INFO - [mozharness: 2017-06-05 02:27:07.250669Z] Running create-virtualenv step. 19:27:07 INFO - Running pre-action listener: _resource_record_pre_action 19:27:07 INFO - Running main action method: create_virtualenv 19:27:07 INFO - Creating virtualenv /builds/slave/test/build/venv 19:27:07 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 19:27:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:27:07 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 19:27:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:27:07 INFO - Using real prefix '/tools/python27' 19:27:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:27:08 INFO - Installing distribute.............................................................................................................................................................................................done. 19:27:10 INFO - Installing pip.................done. 19:27:10 INFO - Return code: 0 19:27:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:27:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:27:11 INFO - Reading from file tmpfile_stdout 19:27:11 INFO - Output received: 19:27:11 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) 19:27:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:27:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:27:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:27:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:11 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 0x103dd5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dd8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdca346ce40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bb3d30>, '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 0x103adadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ba8be8>, '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.Yrjj5qEzyC/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.kH12Ds43ib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 19:27:11 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 19:27:11 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 19:27:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Yrjj5qEzyC/Render', 19:27:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:27:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:27:11 INFO - 'HOME': '/Users/cltbld', 19:27:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:27:11 INFO - 'LOGNAME': 'cltbld', 19:27:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:11 INFO - 'MOZ_NO_REMOTE': '1', 19:27:11 INFO - 'NO_EM_RESTART': '1', 19:27:11 INFO - 'PAGER': '/bin/cat', 19:27:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:27:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:11 INFO - 'PWD': '/builds/slave/test', 19:27:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:27:11 INFO - 'SHELL': '/bin/bash', 19:27:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kH12Ds43ib/Listeners', 19:27:11 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 19:27:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:27:11 INFO - 'USER': 'cltbld', 19:27:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:27:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:27:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:11 INFO - 'XPC_FLAGS': '0x0', 19:27:11 INFO - 'XPC_SERVICE_NAME': '0', 19:27:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:27:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:11 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 19:27:11 INFO - Cleaning up... 19:27:11 INFO - Return code: 0 19:27:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:27:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:27:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:27:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:11 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 0x103dd5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dd8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdca346ce40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bb3d30>, '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 0x103adadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ba8be8>, '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.Yrjj5qEzyC/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.kH12Ds43ib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 19:27:11 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 19:27:11 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 19:27:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Yrjj5qEzyC/Render', 19:27:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:27:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:27:11 INFO - 'HOME': '/Users/cltbld', 19:27:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:27:11 INFO - 'LOGNAME': 'cltbld', 19:27:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:11 INFO - 'MOZ_NO_REMOTE': '1', 19:27:11 INFO - 'NO_EM_RESTART': '1', 19:27:11 INFO - 'PAGER': '/bin/cat', 19:27:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:27:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:11 INFO - 'PWD': '/builds/slave/test', 19:27:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:27:11 INFO - 'SHELL': '/bin/bash', 19:27:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kH12Ds43ib/Listeners', 19:27:11 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 19:27:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:27:11 INFO - 'USER': 'cltbld', 19:27:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:27:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:27:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:11 INFO - 'XPC_FLAGS': '0x0', 19:27:11 INFO - 'XPC_SERVICE_NAME': '0', 19:27:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:27:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:11 INFO - Downloading/unpacking psutil>=3.1.1 19:27:11 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 19:27:11 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 19:27:11 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 19:27:11 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 19:27:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:27:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:27:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:27:14 INFO - Installing collected packages: psutil 19:27:14 INFO - Running setup.py install for psutil 19:27:14 INFO - building 'psutil._psutil_osx' extension 19:27:14 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 19:27:14 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 19:27:14 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 19:27:14 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 19:27:14 INFO - building 'psutil._psutil_posix' extension 19:27:14 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 19:27:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:27:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:27:15 INFO - ^ 19:27:15 INFO - 1 warning generated. 19:27:15 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 19:27:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:27:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:27:15 INFO - Successfully installed psutil 19:27:15 INFO - Cleaning up... 19:27:15 INFO - Return code: 0 19:27:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:27:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:27:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:27:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:15 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 0x103dd5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dd8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdca346ce40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bb3d30>, '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 0x103adadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ba8be8>, '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.Yrjj5qEzyC/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.kH12Ds43ib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 19:27:15 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 19:27:15 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 19:27:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Yrjj5qEzyC/Render', 19:27:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:27:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:27:15 INFO - 'HOME': '/Users/cltbld', 19:27:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:27:15 INFO - 'LOGNAME': 'cltbld', 19:27:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:15 INFO - 'MOZ_NO_REMOTE': '1', 19:27:15 INFO - 'NO_EM_RESTART': '1', 19:27:15 INFO - 'PAGER': '/bin/cat', 19:27:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:27:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:15 INFO - 'PWD': '/builds/slave/test', 19:27:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:27:15 INFO - 'SHELL': '/bin/bash', 19:27:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kH12Ds43ib/Listeners', 19:27:15 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 19:27:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:27:15 INFO - 'USER': 'cltbld', 19:27:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:27:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:27:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:15 INFO - 'XPC_FLAGS': '0x0', 19:27:15 INFO - 'XPC_SERVICE_NAME': '0', 19:27:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:27:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:27:15 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 19:27:15 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 19:27:15 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 19:27:15 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 19:27:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:27:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:27:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:27:17 INFO - Installing collected packages: mozsystemmonitor 19:27:17 INFO - Running setup.py install for mozsystemmonitor 19:27:17 INFO - Successfully installed mozsystemmonitor 19:27:17 INFO - Cleaning up... 19:27:17 INFO - Return code: 0 19:27:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:27: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')]} 19:27:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27: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')]} 19:27:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27: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', '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 0x103dd5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dd8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdca346ce40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bb3d30>, '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 0x103adadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ba8be8>, '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.Yrjj5qEzyC/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.kH12Ds43ib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 19:27: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:27: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 jsonschema==2.5.1 19:27:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Yrjj5qEzyC/Render', 19:27:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:27:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:27:17 INFO - 'HOME': '/Users/cltbld', 19:27:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:27:17 INFO - 'LOGNAME': 'cltbld', 19:27:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:17 INFO - 'MOZ_NO_REMOTE': '1', 19:27:17 INFO - 'NO_EM_RESTART': '1', 19:27:17 INFO - 'PAGER': '/bin/cat', 19:27:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:27:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:17 INFO - 'PWD': '/builds/slave/test', 19:27:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:27:17 INFO - 'SHELL': '/bin/bash', 19:27:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kH12Ds43ib/Listeners', 19:27:17 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 19:27:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:27:17 INFO - 'USER': 'cltbld', 19:27:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:27:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:27:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:17 INFO - 'XPC_FLAGS': '0x0', 19:27:17 INFO - 'XPC_SERVICE_NAME': '0', 19:27:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:27:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:17 INFO - Downloading/unpacking jsonschema==2.5.1 19:27: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 19:27: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 19:27: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 19:27: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 19:27:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:27:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:27:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:27:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:27:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:27:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:27:19 INFO - Downloading functools32-3.2.3-2.tar.gz 19:27:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:27:19 INFO - warning: no files found matching '*.txt' 19:27:19 INFO - no previously-included directories found matching 'build' 19:27:19 INFO - no previously-included directories found matching 'dist' 19:27:19 INFO - no previously-included directories found matching '.git*' 19:27:19 INFO - Installing collected packages: jsonschema, functools32 19:27:19 INFO - Running setup.py install for functools32 19:27:19 INFO - warning: no files found matching '*.txt' 19:27:19 INFO - no previously-included directories found matching 'build' 19:27:19 INFO - no previously-included directories found matching 'dist' 19:27:19 INFO - no previously-included directories found matching '.git*' 19:27:19 INFO - Successfully installed jsonschema functools32 19:27:19 INFO - Cleaning up... 19:27:19 INFO - Return code: 0 19:27:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:27: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')]} 19:27:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27: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')]} 19:27:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27: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', '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 0x103dd5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dd8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdca346ce40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bb3d30>, '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 0x103adadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ba8be8>, '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.Yrjj5qEzyC/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.kH12Ds43ib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 19:27: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:27: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 functools32==3.2.3-2 19:27:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Yrjj5qEzyC/Render', 19:27:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:27:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:27:19 INFO - 'HOME': '/Users/cltbld', 19:27:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:27:19 INFO - 'LOGNAME': 'cltbld', 19:27:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:19 INFO - 'MOZ_NO_REMOTE': '1', 19:27:19 INFO - 'NO_EM_RESTART': '1', 19:27:19 INFO - 'PAGER': '/bin/cat', 19:27:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:27:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:19 INFO - 'PWD': '/builds/slave/test', 19:27:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:27:19 INFO - 'SHELL': '/bin/bash', 19:27:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kH12Ds43ib/Listeners', 19:27:19 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 19:27:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:27:19 INFO - 'USER': 'cltbld', 19:27:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:27:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:27:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:19 INFO - 'XPC_FLAGS': '0x0', 19:27:19 INFO - 'XPC_SERVICE_NAME': '0', 19:27:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:27:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:27:19 INFO - Cleaning up... 19:27:19 INFO - Return code: 0 19:27:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:27: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')]} 19:27:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27: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')]} 19:27:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27: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', '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 0x103dd5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dd8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdca346ce40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bb3d30>, '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 0x103adadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ba8be8>, '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.Yrjj5qEzyC/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.kH12Ds43ib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 19:27: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:27: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 blobuploader==1.2.4 19:27:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Yrjj5qEzyC/Render', 19:27:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:27:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:27:19 INFO - 'HOME': '/Users/cltbld', 19:27:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:27:19 INFO - 'LOGNAME': 'cltbld', 19:27:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:19 INFO - 'MOZ_NO_REMOTE': '1', 19:27:19 INFO - 'NO_EM_RESTART': '1', 19:27:19 INFO - 'PAGER': '/bin/cat', 19:27:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:27:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:19 INFO - 'PWD': '/builds/slave/test', 19:27:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:27:19 INFO - 'SHELL': '/bin/bash', 19:27:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kH12Ds43ib/Listeners', 19:27:19 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 19:27:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:27:19 INFO - 'USER': 'cltbld', 19:27:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:27:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:27:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:19 INFO - 'XPC_FLAGS': '0x0', 19:27:19 INFO - 'XPC_SERVICE_NAME': '0', 19:27:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:27:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:20 INFO - Downloading/unpacking blobuploader==1.2.4 19:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:27:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:27:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:27:21 INFO - Downloading blobuploader-1.2.4.tar.gz 19:27:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:27:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:27: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 19:27: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 19:27: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 19:27: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 19:27:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:27:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:27: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 19:27: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 19:27: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 19:27: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 19:27:22 INFO - Downloading docopt-0.6.1.tar.gz 19:27:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:27:22 INFO - Installing collected packages: blobuploader, requests, docopt 19:27:22 INFO - Running setup.py install for blobuploader 19:27:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:27:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:27:22 INFO - Running setup.py install for requests 19:27:23 INFO - Running setup.py install for docopt 19:27:23 INFO - Successfully installed blobuploader requests docopt 19:27:23 INFO - Cleaning up... 19:27:23 INFO - Return code: 0 19:27:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:27:23 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')]} 19:27:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:23 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')]} 19:27:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:23 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 0x103dd5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dd8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdca346ce40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bb3d30>, '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 0x103adadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ba8be8>, '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.Yrjj5qEzyC/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.kH12Ds43ib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 19:27:23 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 19:27:23 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 19:27:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Yrjj5qEzyC/Render', 19:27:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:27:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:27:23 INFO - 'HOME': '/Users/cltbld', 19:27:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:27:23 INFO - 'LOGNAME': 'cltbld', 19:27:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:23 INFO - 'MOZ_NO_REMOTE': '1', 19:27:23 INFO - 'NO_EM_RESTART': '1', 19:27:23 INFO - 'PAGER': '/bin/cat', 19:27:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:27:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:23 INFO - 'PWD': '/builds/slave/test', 19:27:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:27:23 INFO - 'SHELL': '/bin/bash', 19:27:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kH12Ds43ib/Listeners', 19:27:23 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 19:27:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:27:23 INFO - 'USER': 'cltbld', 19:27:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:27:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:27:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:23 INFO - 'XPC_FLAGS': '0x0', 19:27:23 INFO - 'XPC_SERVICE_NAME': '0', 19:27:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:27:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:27:23 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-i8QqhN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:27:23 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-sPGLUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:27:23 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-2XYVWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:27:23 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-Nyfi97-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:27:23 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-HrQItC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:27:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:27:24 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-f9LGpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:27:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:27:24 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-mDVS3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:27:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:27:24 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-1kTCbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:27:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:27:24 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-8yd7aJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:27:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:27:24 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-eQUUra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:27:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:27:24 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-426QLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:27:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:27:24 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-P7WMUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:27:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:27:24 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-FIZv_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:27:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:27:24 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-YH3UgT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:27:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:27:24 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-TlbcG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:27:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:27:25 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-vsc9KI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:27:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:27:25 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-IgMqd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:27:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:27:25 INFO - Running setup.py install for manifestparser 19:27:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:27:25 INFO - Running setup.py install for mozcrash 19:27:25 INFO - Running setup.py install for mozdebug 19:27:25 INFO - Running setup.py install for mozdevice 19:27:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:27:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:27:25 INFO - Running setup.py install for mozfile 19:27:26 INFO - Running setup.py install for mozhttpd 19:27:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:27:26 INFO - Running setup.py install for mozinfo 19:27:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:27:26 INFO - Running setup.py install for mozInstall 19:27:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:27:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:27:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:27:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:27:26 INFO - Running setup.py install for mozleak 19:27:26 INFO - Running setup.py install for mozlog 19:27:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:27:26 INFO - Running setup.py install for moznetwork 19:27:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:27:26 INFO - Running setup.py install for mozprocess 19:27:27 INFO - Running setup.py install for mozprofile 19:27:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:27:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:27:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:27:27 INFO - Running setup.py install for mozrunner 19:27:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:27:27 INFO - Running setup.py install for mozscreenshot 19:27:27 INFO - Running setup.py install for moztest 19:27:27 INFO - Running setup.py install for mozversion 19:27:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:27:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:27:27 INFO - Cleaning up... 19:27:27 INFO - Return code: 0 19:27:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:27:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:27:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:27:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:27 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 0x103dd5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dd8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdca346ce40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bb3d30>, '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 0x103adadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ba8be8>, '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.Yrjj5qEzyC/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.kH12Ds43ib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 19:27:27 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 19:27:27 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 19:27:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Yrjj5qEzyC/Render', 19:27:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:27:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:27:27 INFO - 'HOME': '/Users/cltbld', 19:27:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:27:27 INFO - 'LOGNAME': 'cltbld', 19:27:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:27 INFO - 'MOZ_NO_REMOTE': '1', 19:27:27 INFO - 'NO_EM_RESTART': '1', 19:27:27 INFO - 'PAGER': '/bin/cat', 19:27:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:27:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:27 INFO - 'PWD': '/builds/slave/test', 19:27:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:27:27 INFO - 'SHELL': '/bin/bash', 19:27:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kH12Ds43ib/Listeners', 19:27:27 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 19:27:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:27:27 INFO - 'USER': 'cltbld', 19:27:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:27:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:27:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:27 INFO - 'XPC_FLAGS': '0x0', 19:27:27 INFO - 'XPC_SERVICE_NAME': '0', 19:27:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:27:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:27:28 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-nX5fgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:27:28 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)) 19:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:27:28 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-GH58ax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:27:28 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)) 19:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:27:28 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-clHWsE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:27:28 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)) 19:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:27:28 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-fOQ3TW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:27:28 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)) 19:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:27:28 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-_yHrH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:27:28 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)) 19:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:27:28 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-yJkCzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:27:28 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)) 19:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:27:28 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-dJBwsZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:27:28 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)) 19:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:27:28 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-Iw6pqz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:27:28 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)) 19:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:27:28 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-FP5zep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:27:29 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)) 19:27:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:27:29 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-nWcY0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:27:29 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)) 19:27:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:27:29 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-l9ocQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:27:29 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)) 19:27:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:27:29 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-EwpaKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:27:29 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)) 19:27:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:27:29 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-GpGNOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:27:29 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)) 19:27:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:27:29 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-7Mp8Vt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:27:29 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)) 19:27:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:27:29 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-JujjAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:27:29 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)) 19:27:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:27:29 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-oPbMmb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:27:29 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)) 19:27:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:27:29 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-BwojSP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:27:29 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)) 19:27:29 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)) 19:27:29 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)) 19:27:29 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)) 19:27:29 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)) 19:27:29 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)) 19:27:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:27:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:27:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:27:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:27:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:27:31 INFO - Downloading blessings-1.6.tar.gz 19:27:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:27:31 INFO - Installing collected packages: blessings 19:27:31 INFO - Running setup.py install for blessings 19:27:31 INFO - Successfully installed blessings 19:27:31 INFO - Cleaning up... 19:27:31 INFO - Return code: 0 19:27:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:27:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:27:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:27:32 INFO - Reading from file tmpfile_stdout 19:27:32 INFO - Current package versions: 19:27:32 INFO - blessings == 1.6 19:27:32 INFO - blobuploader == 1.2.4 19:27:32 INFO - docopt == 0.6.1 19:27:32 INFO - functools32 == 3.2.3-2 19:27:32 INFO - jsonschema == 2.5.1 19:27:32 INFO - manifestparser == 1.1 19:27:32 INFO - mozInstall == 1.12 19:27:32 INFO - mozcrash == 1.0 19:27:32 INFO - mozdebug == 0.1 19:27:32 INFO - mozdevice == 0.49 19:27:32 INFO - mozfile == 1.2 19:27:32 INFO - mozhttpd == 0.7 19:27:32 INFO - mozinfo == 0.9 19:27:32 INFO - mozleak == 0.1 19:27:32 INFO - mozlog == 3.4 19:27:32 INFO - moznetwork == 0.27 19:27:32 INFO - mozprocess == 0.25 19:27:32 INFO - mozprofile == 0.28 19:27:32 INFO - mozrunner == 6.13 19:27:32 INFO - mozscreenshot == 0.1 19:27:32 INFO - mozsystemmonitor == 0.3 19:27:32 INFO - moztest == 0.8 19:27:32 INFO - mozversion == 1.4 19:27:32 INFO - psutil == 3.1.1 19:27:32 INFO - requests == 1.2.3 19:27:32 INFO - wsgiref == 0.1.2 19:27:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:27:32 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')]} 19:27:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:32 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')]} 19:27:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:32 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 0x103dd5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dd8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdca346ce40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bb3d30>, '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 0x103adadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ba8be8>, '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.Yrjj5qEzyC/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.kH12Ds43ib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 19:27:32 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 19:27:32 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 19:27:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Yrjj5qEzyC/Render', 19:27:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:27:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:27:32 INFO - 'HOME': '/Users/cltbld', 19:27:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:27:32 INFO - 'LOGNAME': 'cltbld', 19:27:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:32 INFO - 'MOZ_NO_REMOTE': '1', 19:27:32 INFO - 'NO_EM_RESTART': '1', 19:27:32 INFO - 'PAGER': '/bin/cat', 19:27:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:27:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:32 INFO - 'PWD': '/builds/slave/test', 19:27:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:27:32 INFO - 'SHELL': '/bin/bash', 19:27:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kH12Ds43ib/Listeners', 19:27:32 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 19:27:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:27:32 INFO - 'USER': 'cltbld', 19:27:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:27:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:27:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:32 INFO - 'XPC_FLAGS': '0x0', 19:27:32 INFO - 'XPC_SERVICE_NAME': '0', 19:27:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:27:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:32 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)) 19:27:32 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)) 19:27:32 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)) 19:27:32 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)) 19:27:32 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)) 19:27:32 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)) 19:27:32 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)) 19:27:32 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)) 19:27:32 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)) 19:27:32 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)) 19:27:32 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)) 19:27:32 INFO - Cleaning up... 19:27:32 INFO - Return code: 0 19:27:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:27:32 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')]} 19:27:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:32 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')]} 19:27:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:32 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 0x103dd5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dd8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdca346ce40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103bb3d30>, '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 0x103adadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ba8be8>, '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.Yrjj5qEzyC/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.kH12Ds43ib/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 19:27:32 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 19:27:32 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 19:27:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Yrjj5qEzyC/Render', 19:27:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:27:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:27:32 INFO - 'HOME': '/Users/cltbld', 19:27:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:27:32 INFO - 'LOGNAME': 'cltbld', 19:27:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:32 INFO - 'MOZ_NO_REMOTE': '1', 19:27:32 INFO - 'NO_EM_RESTART': '1', 19:27:32 INFO - 'PAGER': '/bin/cat', 19:27:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:27:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:32 INFO - 'PWD': '/builds/slave/test', 19:27:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:27:32 INFO - 'SHELL': '/bin/bash', 19:27:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kH12Ds43ib/Listeners', 19:27:32 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 19:27:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:27:32 INFO - 'USER': 'cltbld', 19:27:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:27:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:27:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:32 INFO - 'XPC_FLAGS': '0x0', 19:27:32 INFO - 'XPC_SERVICE_NAME': '0', 19:27:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:27:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:27:32 INFO - Cleaning up... 19:27:32 INFO - Return code: 0 19:27:32 INFO - Running post-action listener: _resource_record_post_action 19:27:32 INFO - Running post-action listener: _start_resource_monitoring 19:27:32 INFO - Starting resource monitoring. 19:27:32 INFO - [mozharness: 2017-06-05 02:27:32.679937Z] Finished create-virtualenv step (success) 19:27:32 INFO - [mozharness: 2017-06-05 02:27:32.680870Z] Running install step. 19:27:32 INFO - Running pre-action listener: _resource_record_pre_action 19:27:32 INFO - Running main action method: install 19:27:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:27:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:27:32 INFO - Reading from file tmpfile_stdout 19:27:32 INFO - Detecting whether we're running mozinstall >=1.0... 19:27:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:27:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:27:33 INFO - Reading from file tmpfile_stdout 19:27:33 INFO - Output received: 19:27:33 INFO - Usage: mozinstall [options] installer 19:27:33 INFO - Options: 19:27:33 INFO - -h, --help show this help message and exit 19:27:33 INFO - -d DEST, --destination=DEST 19:27:33 INFO - Directory to install application into. [default: 19:27:33 INFO - "/builds/slave/test"] 19:27:33 INFO - --app=APP Application being installed. [default: firefox] 19:27:33 INFO - mkdir: /builds/slave/test/build/application 19:27:33 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'] 19:27:33 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 19:27:53 INFO - Reading from file tmpfile_stdout 19:27:53 INFO - Output received: 19:27:53 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 19:27:53 INFO - Running post-action listener: _resource_record_post_action 19:27:53 INFO - [mozharness: 2017-06-05 02:27:53.553386Z] Finished install step (success) 19:27:53 INFO - [mozharness: 2017-06-05 02:27:53.553537Z] Running run-tests step. 19:27:53 INFO - Running pre-action listener: _resource_record_pre_action 19:27:53 INFO - Running main action method: run_tests 19:27:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:27:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:27:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:27:53 INFO - 2017-06-04 19:27:53.565 screenresolution[1633:7807] starting screenresolution argv=screenresolution get 19:27:53 INFO - 2017-06-04 19:27:53.568 screenresolution[1633:7807] Display 0: 1600x1200x32@60 19:27:53 INFO - 2017-06-04 19:27:53.572 screenresolution[1634:7809] starting screenresolution argv=screenresolution list 19:27:53 INFO - Available Modes on Display 0 19:27:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:27:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:27:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:27:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:27:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:27:53 INFO - Intel Iris: 19:27:53 INFO - Chipset Model: Intel Iris 19:27:53 INFO - Type: GPU 19:27:53 INFO - Bus: Built-In 19:27:53 INFO - VRAM (Dynamic, Max): 1536 MB 19:27:53 INFO - Vendor: Intel (0x8086) 19:27:53 INFO - Device ID: 0x0a2e 19:27:53 INFO - Revision ID: 0x0009 19:27:53 INFO - Displays: 19:27:53 INFO - Display: 19:27:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:27:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:27:53 INFO - Display Serial Number: 1600x1200 60 19:27:53 INFO - Main Display: Yes 19:27:53 INFO - Mirror: Off 19:27:53 INFO - Online: Yes 19:27:53 INFO - Rotation: Supported 19:27:53 INFO - Return code: 0 19:27:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:27:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:27:53 INFO - Python 2.7.3 19:27:53 INFO - Return code: 0 19:27:53 INFO - grabbing minidump binary from tooltool 19:27:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:27:53 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 0x103bb3d30>, '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 0x103adadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ba8be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:27:53 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 19:27:53 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 19:27:53 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 19:27:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:27:53 INFO - Return code: 0 19:27:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:27:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:27:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:27:53 INFO - ENV: RUST_BACKTRACE is now 1 19:27:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:27:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:27:53 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/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0136', '--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 19:27:53 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/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0136 --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 19:27:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Yrjj5qEzyC/Render', 19:27:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:27:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:27:53 INFO - 'HOME': '/Users/cltbld', 19:27:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:27:53 INFO - 'LOGNAME': 'cltbld', 19:27:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:27:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:27:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:53 INFO - 'MOZ_NO_REMOTE': '1', 19:27:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:27:53 INFO - 'NO_EM_RESTART': '1', 19:27:53 INFO - 'PAGER': '/bin/cat', 19:27:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:27:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:53 INFO - 'PWD': '/builds/slave/test', 19:27:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:27:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:27:53 INFO - 'RUST_BACKTRACE': '1', 19:27:53 INFO - 'SHELL': '/bin/bash', 19:27:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kH12Ds43ib/Listeners', 19:27:53 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 19:27:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:27:53 INFO - 'USER': 'cltbld', 19:27:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:27:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:27:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:53 INFO - 'XPC_FLAGS': '0x0', 19:27:53 INFO - 'XPC_SERVICE_NAME': '0', 19:27:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:27:53 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/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0136', '--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 19:27:54 INFO - mozversion application_buildid: 20170604183818 19:27:54 INFO - mozversion application_changeset: 09f9827c36d63365507fa3999e66c298eed81808 19:27:54 INFO - mozversion application_display_name: FirefoxDeveloperEdition 19:27:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:27:54 INFO - mozversion application_name: Firefox 19:27:54 INFO - mozversion application_remotingname: firefox-dev 19:27:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 19:27:54 INFO - mozversion application_vendor: Mozilla 19:27:54 INFO - mozversion application_version: 54.0 19:27:54 INFO - mozversion platform_buildid: 20170604183818 19:27:54 INFO - mozversion platform_changeset: 09f9827c36d63365507fa3999e66c298eed81808 19:27:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 19:27:54 INFO - mozversion platform_version: 54.0 19:27:54 INFO - using testdate: 1496629674 19:27:54 INFO - actual date: 1496629674 19:27:54 INFO - starting webserver on 'localhost:49231' 19:27:54 INFO - SUITE-START | Running 2 tests 19:27:54 INFO - TEST-START | dromaeo_css 19:27:54 INFO - operating with platform_type : mac_ 19:27:54 INFO - Initialising browser for dromaeo_css test... 19:27:54 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/tmpTB1Qd2/profile http://localhost:49231/getInfo.html) 19:27:56 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 19:27:56 INFO - PROCESS | 1644 | colorDepth:24 19:27:56 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 19:27:56 INFO - PROCESS | 1644 | __metrics 19:27:56 INFO - PROCESS | 1644 | 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" 19:27:56 INFO - PROCESS | 1644 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:27:57 INFO - TEST-INFO | 1644: exit 0 19:27:57 INFO - Browser initialized. 19:27:57 INFO - Running cycle 1/1 for dromaeo_css test... 19:27:57 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/tmpTB1Qd2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:28:09 INFO - PROCESS | 1647 | RSS: Main: 193863680 19:28:09 INFO - PROCESS | 1647 | 19:31:14 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49231/tests/dromaeo/cssquery-ext.html) 19:31:16 INFO - PROCESS | 1647 | RSS: Main: 338309120 19:31:16 INFO - PROCESS | 1647 | 19:34:18 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-ext.html (next: http://localhost:49231/tests/dromaeo/cssquery-jquery.html) 19:34:19 INFO - PROCESS | 1647 | RSS: Main: 328884224 19:34:19 INFO - PROCESS | 1647 | 19:37:21 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49231/tests/dromaeo/cssquery-mootools.html) 19:37:22 INFO - PROCESS | 1647 | RSS: Main: 305319936 19:37:22 INFO - PROCESS | 1647 | 19:40:25 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49231/tests/dromaeo/cssquery-prototype.html) 19:40:25 INFO - PROCESS | 1647 | RSS: Main: 322232320 19:40:25 INFO - PROCESS | 1647 | 19:43:29 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49231/tests/dromaeo/cssquery-yui.html) 19:43:29 INFO - PROCESS | 1647 | RSS: Main: 289103872 19:43:29 INFO - PROCESS | 1647 | 19:46:33 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-yui.html (next: http://localhost:49231/tests/dromaeo/cssquery-dojo.html) 19:46:33 INFO - PROCESS | 1647 | RSS: Main: 478502912 19:46:33 INFO - PROCESS | 1647 | 19:46:33 INFO - PROCESS | 1647 | __start_tp_report 19:46:33 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 19:46:33 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 19:46:33 INFO - PROCESS | 1647 | |i|pagename|runs| 19:46:33 INFO - PROCESS | 1647 | |0;dojo.html;3392.3045775615997;3396.5075064115704;3405.9318813623713;3442.7073698735608;3471.600765911919;5752.626079304842;5827.213326200967;5849.502792262663;5850.648961062339;5854.3267524234725;5782.826515204551;5814.970925145369;5822.796202132926;5845.766169353232;5848.853778655536;5281.313429254192;5342.1452567589195;5343.599230057742;5353.464653534655;5391.81128660497;5248.921266180992;5263.473652634725;5281.181416880389;5429.864253393657;5432.891342173154;5378.435264297251;5385.2999110115825;5399.62202645815;5443;5466.699331536764;5432.076546987022;5434.239206511072;5441.075017247077;5444.101723215705;5451.809186678447;4425.291953287491;5200.453952334989;5299.284596579451;5346.812861549866;5353.304070470814;5761.654300741927;5763.049096899007;5766.394528574481;5767.1349297605275;5807.883842323172;6369.93630063698;6374;6391.872162556723;6402.359764023607;6403.423691867754;6366.267879193906;6376.968115159394;6380.106785050097;6385.361463853624;6394.712237949303;5742.54059675225;5806.04200306953;5844.152597873284;5936.317323507808;6000.309964354112;6390.169277993831;6413.743450262031;6414.807555773335;6426.939554973377;6432.967835160888;6323.652199129;6331.873362532723;6333.113364129025;6333.714982825784;6369.26753423353;6248.125262463259;6271.749130034838;6276.058591211355;6417.197850268716;6424.3254458282145;5262.578993680497;6189.164462797555;6319.431251187415;6352.872942541216;6379.234491860913;6268.686565671698;6274.090256912722;6346.650934198571;6393.328700486475;6407.423331900058;4768.213244814567;4839.2983017462975;4872.51274872517;4902.240152776325;4930.950690493121;4407.184670835905;4416.492103408085;4433;4439.200943830077;4453.710508816916;5556.777728890799;5613.073842815971;5653.349864765522;5653.406392328829;5666.348369937427;6343.270523889812;6347.047942808524;6350.491960643138;6352.333005034498;6356.682165891687;5627.606067575312;5639.464250896158;5641.12562552805;5649.971750141223;5652.378238393773;4576.8626941191815;5383.111786555253;5407;5422.864428389322;5427;6361.363863613602;6370.426661600478;6395.912694841795;6402.35976402356;6472.546921715435;6339.524535659752;6342.270638876588;6358.205224346957;6368.5860419072615;6372.490200783834;6468.223813142453;6494.64279464634;6512.706928188338;6525;6527.836804079936;6132.967444181115;6161.938380616137;6280.466160376419;6368.267649220214;6373.426391624776;6311.958526843019;6314.652694101685;6336.049592561153;6345.555811093179;6367.299597044229;6250.062490626442;6276.995680691068;6289.654069026247;6289.811305660837;6302.65335406557;5946.375630558729;6123.908141377794;6196.1015652731;6289.245290565162;6294.244690637152;6075.969620151871;6077.4834139098675;6086.482648974887;6104.542159337979;6117.571769976059;5172.793088276427;6213.615420593393;6250.218722659668;6279.591826531261;6287.899617566999;5823.854403639773;5863.648181109147;6094.299155597163;6112.449879510866;6119.265688117454 19:46:33 INFO - PROCESS | 1647 | |1;ext.html;754.3399525415261;783.8980932478779;808.6320724070547;877.8376000439923;887.0774394629585;27833.025844095435;39210.60789392105;39613.2077358453;41066.58933410665;41852;33423.997280081574;41141.38287925684;41213.17573648525;41295;41356.58643413565;7582.2038685938005;7722.498037627564;9467.715968520955;9642.566084526214;9651.758706032335;11343.829842552368;11611.94194029033;11719.414029298501;11742.295462272248;11759.412029398538;11546.884531154665;11564.363959982198;11584.536618535249;11591.24656897299;11633.243839150427;11462.025727813147;11486.483108260065;11503.769924601545;11504.25222360544;11515.424228788526;11338.829917551244;11411.94294028533;11413.372264525447;11417.200795944287;11424.257423267461;39104.413433798516;39304.01739956495;39315.410268845706;39366.803165984275;39411.8029409854;3351.614564325085;3960.6237407446247;3963.7225394222414;3980.840766369342;4023.839046438139;27715.168544943685;28128.437431251466;29272.268193295124;29676.554851677243;29693.703062969525;3162.4623813951453;3164.556962025318;3169.2710676544293;3170.239142605781;3249.6262929762906;372890;375450;376372;376687.99999999726;376910;53174;53269.73365133227;53324.73337633287;53429;53505.732471338175;54813.725931370085;56032;56110.438895610525;56187.87624247574;56239;14882.851171488146;14948.252587370587;14964.850351496345;14967.326470308863;14972.401103955719;10989.340639561651;11176.385298808482;11205.383703896365;12976.091673582947;12977.35113244334;10041.899581004243;12080.335581542924;12120.45457954394;12284.754304913851;12328.383580821102;5542.63972841764;5548.667079975215;5550.69471179089;5561.360443549043;5584.52531534816;941.3222479814523;1097.8023955687893;1112.477135746198;1112.7607564373698;1141.7545227776068;28026.159215223575;28253.01114460984;28299.575506367422;28462;28576.571351429746;1009.4397609326747;1118.8978855827004;1181.4565299962114;1191.5769901684778;1212.496813822254;12734.745305094031;13182.934085329513;14215.928920355333;14473.565793026226;14723.969322147346;440353;440842;440971;441286;442122;57180.71409642925;57303.71348143233;57326.14010789841;57378.71310643503;57406;1400.6218321053461;1431.7351290011381;1435.4258296681412;1481.5999680086531;1491.6270932266934;26790.732092679602;27277.454450910474;27809.58285625677;27934.58098128489;27950.58074128849;37663.81168094142;37854.81072594619;37903.24193516169;37964.810175948944;38028;422426;422683;423062;423076;423192;29236.56145157867;33230.504627292314;39746.80126599348;39800.60199397969;40501.1899762009;31812.84093579517;43896.56103439053;47294.290585640556;52299.95400092053;53225;32652;33010.33979320448;33030.009099726085;33184.336313274085;34184;32917.835410822794;33349.66650333466;34160;34411;34524.30951380908;39975.80012099921;40278.39582406325;40497.1900561993;40687.59312406838;41413.96465088397;33015;33824.83087584645;35251.82374088113;35305.82347088248;35438.29123417569;26108.086216982316;26555.7344426561;26574.335641609116;26665;27206.911723530837 19:46:33 INFO - PROCESS | 1647 | |2;jquery.html;400.98596549120805;405.7099174090528;421.30695006713955;426.08604543254717;443.63178561793717;17437.65124697507;20296.98515074246;21836.344909652737;22274.554508909852;22388.328350149503;20700.8964955175;21397;21804.45488862779;22179;22636.660450093263;6454.160959075325;6697.229818570878;6736.157980252468;6744;6756.425703815182;6660.70026848793;7374.705011799521;7711.614419279042;7843.254890785366;7865;7761.45669803114;7784.104827944774;7857.6856925722905;7858.057033156029;7880.211978802103;7547.547147171133;7563.357114645261;7795.103563090203;7797.454178207556;7806.024246969129;7561.621918904088;7640;7643.656035478361;7647.120581133129;7653.311201991847;25436.23691289246;26134.34664133393;26270.47459050808;26292;26568;2916.416716656656;3024.561438591392;3039.4984827503436;3040.528718048706;3042.1938186380626;15198.240087995666;16380.344786208656;16488.186299507044;16617.91691041549;17002;1946.13397038318;2022.3730643500414;2028.2191356327774;2031.1570698160247;2033.186725309873;108192;117150;128205;130472.34763826214;162295.3770462292;15032.323545440482;18786.342478013477;20022.596158652774;20157.99210039492;20202.59594808125;24919.127830525846;31432.52851207234;31599;31632.683673163436;31809.363812723615;10085.798284034277;10366.481675916173;10504.527296271686;10675.786484270271;10677.572897084185;8738.257248133852;8766.824663506823;8774.473531588124;8906.821863562693;8923.15230053144;8914.064023277462;9245.785534675464;9318.207952323994;9357.298202634829;9357.6954600883;3988.4216788565495;4056.9797151014645;4063.6139566741113;4073.7759423231414;4081.285774989365;836.916193529381;854.1714536899322;890.8396488632109;892.7812685891998;915.1534830281962;18365.81634183668;20308.69536956947;21404;21446;21469.355919322446;906.777839429344;907.2333877873186;922.617113897725;925.4678559828126;935.4901578639658;10545.367277963347;11004.889951100547;11018.614348497924;11112.277701949348;11154.609588664358;615660;727020;728998;729430;744700;114397.42801286107;135878.6412135866;147778;148869;150386.24806875896;1249.2005116725304;1274.4519856461811;1278.910476266671;1369.5480491437802;1376.6351916742071;19192.232310707976;19299.324523641604;19866.701999470308;19868.304609339182;19902.90048549748;50983.98032039413;55759.884802304536;61888.38111619006;62234;62920.37079629145;86267.5686621588;88595.55702221705;89695.13353531237;89754.55122724344;91921.7019574516;11233.719157020812;11311.490982905625;11445.942770286096;11536.884631153916;11729.941350293195;10229.283950123718;12392.318422486489;12731.172473789175;12816.487340506275;12923.612291631267;10781.676549703521;10830.67507974762;10961.397123157984;11234.775304493702;11242.606508772154;10013.39919604826;10161.695149145537;10322.225833062701;10338.379697218192;10357.223208259562;10461.843072353768;11029.007389334998;11047.558097676325;11176.329420234812;11320.830187547028;9656.130948214695;11128.054115399958;11128.998390144925;11376.0330371916;11404.429778511207;8766.517841518778;8835.779105522413;10818.080463160404;10883.183761217782;10910.72723181895 19:46:33 INFO - PROCESS | 1647 | |3;mootools.html;1487.4793822162244;1568.1531972734724;1578.6605879735855;1608.5254849819296;1610.8067031956164;10602.575896964121;10823.24237303389;10825;10843.728906777334;10845.457727113651;10628.415437150954;10873.238873278853;11202.159838012141;11244.718882027953;11245.831312530298;2291.404234898926;2349.5653304138705;2350.7766762157567;2351.6825228594175;2354.682117914077;2270;2341.4497593065616;2354.81161507079;2361.480474295652;2363.2555744940373;2289.3360925331635;2333.6149535326736;2343.7304709958403;2347.366211122996;2350.9177178798745;2281.8060464860505;2286.474110954473;2316.664083707861;2316.81465482761;2319.6404557293645;2308.8845557722216;2310.2491690200586;2310.572544079351;2312.052058655943;2314.93055208344;9067.909320906772;10937.671869843918;10948.014678678877;10950.288231264942;10958.780824383548;5423.810166644149;5567.387587365387;5569.749361278752;5572.693501857396;5574.9721251393485;3247.1232767152737;3319.6139937263856;3454.291870166635;3502.474628805674;3506.5266189064405;970.6166064404521;981.8855598895234;1099.6700989703056;1133.4616057372764;1188.7979043562616;13952.093113947562;14128.011039227258;14301.14193148404;14934.775978360334;15316.463923762614;4587.9541204588195;4727.149113159664;4739.573438390493;4746.335513028179;4751.477337492873;12866.806997895039;12869.549565765154;12874.16317939331;12876.613701588967;12886.613401597968;4817.78319975602;4993.375828021497;5018.899622007612;5665.9716701417055;5667.603267771299;4261.232978063978;5111.437741848394;5153.2270159476375;5163.948360516347;5184.222366644958;3286.375588638151;3806.238752249561;3819.1216020315483;3879.1465877506903;3901.49280593521;996.3822430093388;1008.6570566007592;1025.8922813104666;1027.984580231297;1032.2155162076876;942.5617088054088;952.6665667016489;968.2689569375218;972.1590823937385;996.5515518016921;6316.463100636497;6329.746810127635;6474.579152355176;6543.0185472179555;6652.634105124168;861.521855370263;989.737719504332;994.3238597753452;1019.7603563162651;1047.5181416548473;4092.7748973806724;4123.381492776108;4150.066235097079;4235.25882970479;4310.116426132635;12919.806202906964;13535.39090740919;13547.729045419037;13548.729025419436;13553.728925421436;13437.395317210749;13466.394012269471;13476.797848032287;13483.98870084728;13488.595342139752;9776.755581110529;10079.748006299755;10083.042110999444;10116.34243774167;10184.032516910882;6359.618422894641;6586.40722334973;6610.438112760469;6769.221539522821;6876.381125698711;9675.419474831533;11853.822192666945;12035.217710848767;12175.817362739743;12353.752924941271;11379.317240965569;11746.236494627821;12291.631251062483;12326.50693972231;12337.383130843242;8315;9165.312601554777;9181;9215.308851836005;9305.906940930505;10227;10231.590736370461;10345.27583069208;10369.274150809371;10382.532786024496;8608.569571521524;8744.431611945203;8803.647854085764;8828.38201325901;8862.512561809162;8527.957360213408;8528.275096616857;8611.268042216481;8612.397132200686;8686;7725.961370193113;7854.646540905559;7869;7874.84250314979;7890.960545197467;4594.425696787902;5644.294463192101;5733.483986441241;5833.037548804569;5906.645601263938;5757.539396848326;5874.17761513397;5884;5890.676012819336;5923.526117910471 19:46:33 INFO - PROCESS | 1647 | |4;prototype.html;365.63984475291835;373.86914579897035;379.60900272719107;381.8892521168861;384.68455866189726;4500.199920031989;4724.999999999995;5135.512126347996;5144.202648589465;5277.208418737191;4616.399868017162;4704.153252414564;4793.808247670089;4974.6669731280035;5096.592272618191;3986.5614782373914;4213.705040647156;4220.388043733657;4242.363645453192;4244.797650326186;4268.658507319423;4271.359296105594;4275.700700950934;4307.698461107724;4320.503142138663;3269.88555400561;4176.45706058212;4190.308599081148;4270.893227669302;4301.311790113583;3554.8044857532695;4020.778857162855;4173.937390939138;4182.218824481716;4240.363945408198;3743.5507739071213;3894.7273690841653;3912.56961734209;3919.2357490289405;3934.2328245991757;4633.003904160588;4980.452150263468;5017.84946451607;5030.647854650176;5083.822066227664;3815.3704638734575;4704.882377940579;4753.904921901542;4853.781579828917;4857.348501509468;4731.077439899221;4770.666053376265;5148.974255128738;5363.6245462817615;5512.862178445571;3815.637514436124;3868.170511193926;4133.545310015896;4136.648384887289;4386.583274588909;5138.434772175057;5303.761330740126;5306.885554033696;5494.59335212167;5641.802536911188;3919.0594257378593;4218.767967761742;4394.406755088086;4436.6672499562665;4465.531119232499;3957.821898014596;4016.9196616068098;4329.956700433018;4500.054988452396;4505.909881802346;3851.6340947609933;4482.551744825557;4729.0207169483;4827.62066553414;4885.899063402771;4546.909061818745;5010.098182327143;5384.353877534721;5441.59188060897;5447.945520544744;1361.6323592629935;1380.9447622095204;1448.1455940994654;1461.9547023877979;1548.5122186511212;930.2883294279882;985.8416360776203;1011.0748664971616;1167.4104577188466;1196.4556126962211;4329.458817647794;4590.954090459053;4722.787474563653;4920.8769780755765;4926.852194434173;5338.599605029638;5570.832875013756;5580.888382232332;5613.7193140342815;5654.688992105392;3731.738778285548;4169.10188973311;4453.64163929998;4864.221724524059;4941.777620007108;2684.9194524164304;2685.87913543891;2738;2919.489089409345;2921.5617657351568;4728.787204575802;5049.722265275445;5505.935274272148;5589.745639099315;5869.354371019184;4985.152524070917;5044.166433510485;5218.582513398919;5686.914696279559;5708.80019199326;4844.442889067732;5113.38928237604;5793.8551536211935;5795.580607441714;5843.2403787507355;4592.346755168203;4834.371531700856;5134.460881607527;5566.218809980929;5601.80393686219;4965.45380008206;5153.587712982878;5163.845084647466;5259.237410575369;5530.695811730394;4832.468428472795;5644.322681278109;5674.716264186856;5729.570282228766;5750.971245143747;4390.6169556589575;4402.84590039347;4410.911781764283;4426.623736982264;4431.756253405964;4682.555157260055;5494.983428065821;5575.773329867423;5579.665220086646;5616.269884914935;4776.928346074742;4992.875178120431;5077.720725360141;5378.085725426765;5413.621046526863;5335.33308336458;5391.605841239378;5405.72971351423;5424.5660347172925;5434.483724046209;4607.765233861642;4921.90818901958;5100.821471248489;5168.250603662523;5252.65857719247;4948.307236986752;5028.145215313332;5351.277577526984;5437.8368648940595;5485.451454854483;4378.343248512749;4981.658620448384;5114.181730923035;5329.7068661224;5337.572994160536 19:46:33 INFO - PROCESS | 1647 | |5;yui.html;720.3912693773761;766.057748968768;801.1587832775584;848.1264297773288;852.4075767341698;16194.514164575066;16222.351105955777;16350.75473867893;16473.093979831105;16486;12382.690432739186;15121.924390378033;15125.621859453518;15125.848741512582;15130.848691513082;956.3974695941547;961.9903800962006;990.2968892086627;992.0971915556845;993.7267251505822;989.3618615992681;994.4182653147881;999.5252255178768;1000.6697789729371;1002.1081237698454;862.8921384826897;1052.773653664462;1053.020690757595;1054.251481448169;1054.3673795722582;1049.0401282826206;1049.2235745548317;1049.548694061557;1050.317293759055;1050.4695128959827;781.7498400511838;782.9334506566948;783.3928705253417;783.9333656639193;785.2461636828651;16451.25969331383;16481;16505;17027.659446811118;17345.306187752474;14243.78634320486;14399.78400323996;14448.71102577953;14459.78310325346;14483.131012139305;11661.24201926872;11743.236689615147;11817.468213930308;11919.582814601446;12281.324527151002;3531.2937412517344;3753.418220175877;3774.50931378919;3793.1275806564367;3878.6897048236074;105604;115738;115865.28677437802;118500;119414.4029279848;10375.377477351383;10901.400422976812;11051;11331.830022549668;11519.25124866881;22800.087996479953;23242.186523471937;23534.882325588263;23594.29217123489;23622;6268.529860260499;7760.611969401507;7814.452988290879;7923.722669706648;8078.394120440873;4356.433663623772;4412.852658308799;4457.821687132543;4554.840580579664;4600.378948841863;3413.9829300853335;3471.218975730491;3508.210652603195;3515.9296814064087;3542.8405721742583;649.3409189672486;652.885744994624;655.7803135949415;656.8554917918051;657.9111819904251;654.1594051643615;660.8810414125503;680.1973671068088;693.5076095971916;715.377621469325;10043.648472303434;10462.215333849992;10595.576176952989;10717.35695858251;10765.461726913623;674.6323253826578;679.942204912588;711.7970629635898;725.3109545931386;737.3695490355776;4822.95177048225;5137.691738495702;5145.305383773143;5148.227765835155;5149.7940082397035;106864;107657;109714;110147.34778978322;111525;84545.73181402285;88369;89273.53632318358;92591;92672.60991085139;73076.63461682764;75514;79819.60090199512;80860.59569702114;80861.59569202234;9648.614055437703;9674.467904265333;9726.270529710442;9733;9739.074787895139;52236;53274.73362633317;53528.73235633797;54549.72725136349;55692.44307557034;57547.424525754206;63063.36936630575;66044;66544.00183997148;72932.17669558304;12676;12914;12936.619613263774;13100.672483187996;13570.728585428433;14745.185051922499;14927.776083358542;15128;15213.923930380277;15799.842001579837;13745.926076582351;13806.641093263295;14102.788458173341;14104.435822566982;14336;13324.733505329645;13558.389872455566;14059.111837219907;14319.28403579796;14528.418863245775;11356.217726656692;13314.40085196149;13710.931445342709;13739.450421983009;14185.645358865699;9195.724128276162;9259.67591134307;9495.477748723886;9678.467684277432;9870.358426702242;10022.198224142197;10046.648367307109;11400.201985860911;11411.486483108114;11520.769584608428 19:46:33 INFO - PROCESS | 1647 | __end_tp_report 19:46:33 INFO - PROCESS | 1647 | __start_cc_report 19:46:33 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1469 19:46:33 INFO - PROCESS | 1647 | __end_cc_report 19:46:33 INFO - PROCESS | 1647 | __startTimestamp1496630793269__endTimestamp 19:46:33 INFO - PROCESS | 1647 | 19:46:33 INFO - PROCESS | 1647 | ------- Summary: start ------- 19:46:33 INFO - PROCESS | 1647 | Number of tests: 6 19:46:33 INFO - PROCESS | 1647 | 19:46:33 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5826.04 Median:6086.48 stddev:679.54 (11.2%) stddev-sans-first:654.25 19:46:33 INFO - PROCESS | 1647 | Values: 3392.3 3396.5 3405.9 3442.7 3471.6 5752.6 5827.2 5849.5 5850.6 5854.3 5782.8 5815.0 5822.8 5845.8 5848.9 5281.3 5342.1 5343.6 5353.5 5391.8 5248.9 5263.5 5281.2 5429.9 5432.9 5378.4 5385.3 5399.6 5443.0 5466.7 5432.1 5434.2 5441.1 5444.1 5451.8 4425.3 5200.5 5299.3 5346.8 5353.3 5761.7 5763.0 5766.4 5767.1 5807.9 6369.9 6374.0 6391.9 6402.4 6403.4 6366.3 6377.0 6380.1 6385.4 6394.7 5742.5 5806.0 5844.2 5936.3 6000.3 6390.2 6413.7 6414.8 6426.9 6433.0 6323.7 6331.9 6333.1 6333.7 6369.3 6248.1 6271.7 6276.1 6417.2 6424.3 5262.6 6189.2 6319.4 6352.9 6379.2 6268.7 6274.1 6346.7 6393.3 6407.4 4768.2 4839.3 4872.5 4902.2 4931.0 4407.2 4416.5 4433.0 4439.2 4453.7 5556.8 5613.1 5653.3 5653.4 5666.3 6343.3 6347.0 6350.5 6352.3 6356.7 5627.6 5639.5 5641.1 5650.0 5652.4 4576.9 5383.1 5407.0 5422.9 5427.0 6361.4 6370.4 6395.9 6402.4 6472.5 6339.5 6342.3 6358.2 6368.6 6372.5 6468.2 6494.6 6512.7 6525.0 6527.8 6133.0 6161.9 6280.5 6368.3 6373.4 6312.0 6314.7 6336.0 6345.6 6367.3 6250.1 6277.0 6289.7 6289.8 6302.7 5946.4 6123.9 6196.1 6289.2 6294.2 6076.0 6077.5 6086.5 6104.5 6117.6 5172.8 6213.6 6250.2 6279.6 6287.9 5823.9 5863.6 6094.3 6112.4 6119.3 19:46:33 INFO - PROCESS | 1647 | 19:46:33 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:56146.70 Median:18387.81 stddev:109519.47 (595.6%) stddev-sans-first:109747.73 19:46:33 INFO - PROCESS | 1647 | Values: 754.3 783.9 808.6 877.8 887.1 27833.0 39210.6 39613.2 41066.6 41852.0 33424.0 41141.4 41213.2 41295.0 41356.6 7582.2 7722.5 9467.7 9642.6 9651.8 11343.8 11611.9 11719.4 11742.3 11759.4 11546.9 11564.4 11584.5 11591.2 11633.2 11462.0 11486.5 11503.8 11504.3 11515.4 11338.8 11411.9 11413.4 11417.2 11424.3 39104.4 39304.0 39315.4 39366.8 39411.8 3351.6 3960.6 3963.7 3980.8 4023.8 27715.2 28128.4 29272.3 29676.6 29693.7 3162.5 3164.6 3169.3 3170.2 3249.6 372890.0 375450.0 376372.0 376688.0 376910.0 53174.0 53269.7 53324.7 53429.0 53505.7 54813.7 56032.0 56110.4 56187.9 56239.0 14882.9 14948.3 14964.9 14967.3 14972.4 10989.3 11176.4 11205.4 12976.1 12977.4 10041.9 12080.3 12120.5 12284.8 12328.4 5542.6 5548.7 5550.7 5561.4 5584.5 941.3 1097.8 1112.5 1112.8 1141.8 28026.2 28253.0 28299.6 28462.0 28576.6 1009.4 1118.9 1181.5 1191.6 1212.5 12734.7 13182.9 14215.9 14473.6 14724.0 440353.0 440842.0 440971.0 441286.0 442122.0 57180.7 57303.7 57326.1 57378.7 57406.0 1400.6 1431.7 1435.4 1481.6 1491.6 26790.7 27277.5 27809.6 27934.6 27950.6 37663.8 37854.8 37903.2 37964.8 38028.0 422426.0 422683.0 423062.0 423076.0 423192.0 29236.6 33230.5 39746.8 39800.6 40501.2 31812.8 43896.6 47294.3 52300.0 53225.0 32652.0 33010.3 33030.0 33184.3 34184.0 32917.8 33349.7 34160.0 34411.0 34524.3 39975.8 40278.4 40497.2 40687.6 41414.0 33015.0 33824.8 35251.8 35305.8 35438.3 26108.1 26555.7 26574.3 26665.0 27206.9 19:46:33 INFO - PROCESS | 1647 | 19:46:33 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:40739.78 Median:22512.49 stddev:118063.96 (524.4%) stddev-sans-first:118356.31 19:46:33 INFO - PROCESS | 1647 | Values: 401.0 405.7 421.3 426.1 443.6 17437.7 20297.0 21836.3 22274.6 22388.3 20700.9 21397.0 21804.5 22179.0 22636.7 6454.2 6697.2 6736.2 6744.0 6756.4 6660.7 7374.7 7711.6 7843.3 7865.0 7761.5 7784.1 7857.7 7858.1 7880.2 7547.5 7563.4 7795.1 7797.5 7806.0 7561.6 7640.0 7643.7 7647.1 7653.3 25436.2 26134.3 26270.5 26292.0 26568.0 2916.4 3024.6 3039.5 3040.5 3042.2 15198.2 16380.3 16488.2 16617.9 17002.0 1946.1 2022.4 2028.2 2031.2 2033.2 108192.0 117150.0 128205.0 130472.3 162295.4 15032.3 18786.3 20022.6 20158.0 20202.6 24919.1 31432.5 31599.0 31632.7 31809.4 10085.8 10366.5 10504.5 10675.8 10677.6 8738.3 8766.8 8774.5 8906.8 8923.2 8914.1 9245.8 9318.2 9357.3 9357.7 3988.4 4057.0 4063.6 4073.8 4081.3 836.9 854.2 890.8 892.8 915.2 18365.8 20308.7 21404.0 21446.0 21469.4 906.8 907.2 922.6 925.5 935.5 10545.4 11004.9 11018.6 11112.3 11154.6 615660.0 727020.0 728998.0 729430.0 744700.0 114397.4 135878.6 147778.0 148869.0 150386.2 1249.2 1274.5 1278.9 1369.5 1376.6 19192.2 19299.3 19866.7 19868.3 19902.9 50984.0 55759.9 61888.4 62234.0 62920.4 86267.6 88595.6 89695.1 89754.6 91921.7 11233.7 11311.5 11445.9 11536.9 11729.9 10229.3 12392.3 12731.2 12816.5 12923.6 10781.7 10830.7 10961.4 11234.8 11242.6 10013.4 10161.7 10322.2 10338.4 10357.2 10461.8 11029.0 11047.6 11176.3 11320.8 9656.1 11128.1 11129.0 11376.0 11404.4 8766.5 8835.8 10818.1 10883.2 10910.7 19:46:33 INFO - PROCESS | 1647 | 19:46:33 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:6624.07 Median:2805.19 stddev:4178.05 (148.9%) stddev-sans-first:4171.94 19:46:33 INFO - PROCESS | 1647 | Values: 1487.5 1568.2 1578.7 1608.5 1610.8 10602.6 10823.2 10825.0 10843.7 10845.5 10628.4 10873.2 11202.2 11244.7 11245.8 2291.4 2349.6 2350.8 2351.7 2354.7 2270.0 2341.4 2354.8 2361.5 2363.3 2289.3 2333.6 2343.7 2347.4 2350.9 2281.8 2286.5 2316.7 2316.8 2319.6 2308.9 2310.2 2310.6 2312.1 2314.9 9067.9 10937.7 10948.0 10950.3 10958.8 5423.8 5567.4 5569.7 5572.7 5575.0 3247.1 3319.6 3454.3 3502.5 3506.5 970.6 981.9 1099.7 1133.5 1188.8 13952.1 14128.0 14301.1 14934.8 15316.5 4588.0 4727.1 4739.6 4746.3 4751.5 12866.8 12869.5 12874.2 12876.6 12886.6 4817.8 4993.4 5018.9 5666.0 5667.6 4261.2 5111.4 5153.2 5163.9 5184.2 3286.4 3806.2 3819.1 3879.1 3901.5 996.4 1008.7 1025.9 1028.0 1032.2 942.6 952.7 968.3 972.2 996.6 6316.5 6329.7 6474.6 6543.0 6652.6 861.5 989.7 994.3 1019.8 1047.5 4092.8 4123.4 4150.1 4235.3 4310.1 12919.8 13535.4 13547.7 13548.7 13553.7 13437.4 13466.4 13476.8 13484.0 13488.6 9776.8 10079.7 10083.0 10116.3 10184.0 6359.6 6586.4 6610.4 6769.2 6876.4 9675.4 11853.8 12035.2 12175.8 12353.8 11379.3 11746.2 12291.6 12326.5 12337.4 8315.0 9165.3 9181.0 9215.3 9305.9 10227.0 10231.6 10345.3 10369.3 10382.5 8608.6 8744.4 8803.6 8828.4 8862.5 8528.0 8528.3 8611.3 8612.4 8686.0 7726.0 7854.6 7869.0 7874.8 7891.0 4594.4 5644.3 5733.5 5833.0 5906.6 5757.5 5874.2 5884.0 5890.7 5923.5 19:46:33 INFO - PROCESS | 1647 | 19:46:33 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4460.99 Median:4830.04 stddev:1245.59 (25.8%) stddev-sans-first:1210.57 19:46:33 INFO - PROCESS | 1647 | Values: 365.6 373.9 379.6 381.9 384.7 4500.2 4725.0 5135.5 5144.2 5277.2 4616.4 4704.2 4793.8 4974.7 5096.6 3986.6 4213.7 4220.4 4242.4 4244.8 4268.7 4271.4 4275.7 4307.7 4320.5 3269.9 4176.5 4190.3 4270.9 4301.3 3554.8 4020.8 4173.9 4182.2 4240.4 3743.6 3894.7 3912.6 3919.2 3934.2 4633.0 4980.5 5017.8 5030.6 5083.8 3815.4 4704.9 4753.9 4853.8 4857.3 4731.1 4770.7 5149.0 5363.6 5512.9 3815.6 3868.2 4133.5 4136.6 4386.6 5138.4 5303.8 5306.9 5494.6 5641.8 3919.1 4218.8 4394.4 4436.7 4465.5 3957.8 4016.9 4330.0 4500.1 4505.9 3851.6 4482.6 4729.0 4827.6 4885.9 4546.9 5010.1 5384.4 5441.6 5447.9 1361.6 1380.9 1448.1 1462.0 1548.5 930.3 985.8 1011.1 1167.4 1196.5 4329.5 4591.0 4722.8 4920.9 4926.9 5338.6 5570.8 5580.9 5613.7 5654.7 3731.7 4169.1 4453.6 4864.2 4941.8 2684.9 2685.9 2738.0 2919.5 2921.6 4728.8 5049.7 5505.9 5589.7 5869.4 4985.2 5044.2 5218.6 5686.9 5708.8 4844.4 5113.4 5793.9 5795.6 5843.2 4592.3 4834.4 5134.5 5566.2 5601.8 4965.5 5153.6 5163.8 5259.2 5530.7 4832.5 5644.3 5674.7 5729.6 5751.0 4390.6 4402.8 4410.9 4426.6 4431.8 4682.6 5495.0 5575.8 5579.7 5616.3 4776.9 4992.9 5077.7 5378.1 5413.6 5335.3 5391.6 5405.7 5424.6 5434.5 4607.8 4921.9 5100.8 5168.3 5252.7 4948.3 5028.1 5351.3 5437.8 5485.5 4378.3 4981.7 5114.2 5329.7 5337.6 19:46:33 INFO - PROCESS | 1647 | 19:46:33 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:21145.69 Median:13517.99 stddev:30670.45 (226.9%) stddev-sans-first:30718.14 19:46:33 INFO - PROCESS | 1647 | Values: 720.4 766.1 801.2 848.1 852.4 16194.5 16222.4 16350.8 16473.1 16486.0 12382.7 15121.9 15125.6 15125.8 15130.8 956.4 962.0 990.3 992.1 993.7 989.4 994.4 999.5 1000.7 1002.1 862.9 1052.8 1053.0 1054.3 1054.4 1049.0 1049.2 1049.5 1050.3 1050.5 781.7 782.9 783.4 783.9 785.2 16451.3 16481.0 16505.0 17027.7 17345.3 14243.8 14399.8 14448.7 14459.8 14483.1 11661.2 11743.2 11817.5 11919.6 12281.3 3531.3 3753.4 3774.5 3793.1 3878.7 105604.0 115738.0 115865.3 118500.0 119414.4 10375.4 10901.4 11051.0 11331.8 11519.3 22800.1 23242.2 23534.9 23594.3 23622.0 6268.5 7760.6 7814.5 7923.7 8078.4 4356.4 4412.9 4457.8 4554.8 4600.4 3414.0 3471.2 3508.2 3515.9 3542.8 649.3 652.9 655.8 656.9 657.9 654.2 660.9 680.2 693.5 715.4 10043.6 10462.2 10595.6 10717.4 10765.5 674.6 679.9 711.8 725.3 737.4 4823.0 5137.7 5145.3 5148.2 5149.8 106864.0 107657.0 109714.0 110147.3 111525.0 84545.7 88369.0 89273.5 92591.0 92672.6 73076.6 75514.0 79819.6 80860.6 80861.6 9648.6 9674.5 9726.3 9733.0 9739.1 52236.0 53274.7 53528.7 54549.7 55692.4 57547.4 63063.4 66044.0 66544.0 72932.2 12676.0 12914.0 12936.6 13100.7 13570.7 14745.2 14927.8 15128.0 15213.9 15799.8 13745.9 13806.6 14102.8 14104.4 14336.0 13324.7 13558.4 14059.1 14319.3 14528.4 11356.2 13314.4 13710.9 13739.5 14185.6 9195.7 9259.7 9495.5 9678.5 9870.4 10022.2 10046.6 11400.2 11411.5 11520.8 19:46:33 INFO - PROCESS | 1647 | -------- Summary: end -------- 19:46:33 INFO - PROCESS | 1647 | 19:46:33 INFO - TEST-INFO | 1647: exit 0 19:46:33 INFO - TEST-OK | dromaeo_css | took 1119643ms 19:46:33 INFO - TEST-START | kraken 19:46:33 INFO - operating with platform_type : mac_ 19:46:33 INFO - Initialising browser for kraken test... 19:46:33 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/tmprlJrC4/profile http://localhost:49231/getInfo.html) 19:46:35 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 19:46:35 INFO - PROCESS | 1655 | colorDepth:24 19:46:35 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 19:46:35 INFO - PROCESS | 1655 | __metrics 19:46:36 INFO - PROCESS | 1655 | 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" 19:46:36 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:46:36 INFO - TEST-INFO | 1655: exit 0 19:46:36 INFO - Browser initialized. 19:46:36 INFO - Running cycle 1/1 for kraken test... 19:46:36 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/tmprlJrC4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:46:48 INFO - PROCESS | 1657 | RSS: Main: 194056192 19:46:48 INFO - PROCESS | 1657 | 19:46:51 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-beat-detection) 19:46:52 INFO - PROCESS | 1657 | RSS: Main: 244551680 19:46:52 INFO - PROCESS | 1657 | 19:46:54 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-dft) 19:46:55 INFO - PROCESS | 1657 | RSS: Main: 258895872 19:46:55 INFO - PROCESS | 1657 | 19:46:58 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-fft) 19:46:59 INFO - PROCESS | 1657 | RSS: Main: 357617664 19:46:59 INFO - PROCESS | 1657 | 19:47:01 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-oscillator) 19:47:01 INFO - PROCESS | 1657 | RSS: Main: 257839104 19:47:01 INFO - PROCESS | 1657 | 19:47:03 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49231/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:47:04 INFO - PROCESS | 1657 | RSS: Main: 260722688 19:47:04 INFO - PROCESS | 1657 | 19:47:07 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49231/tests/kraken/driver.html?testName=imaging-darkroom) 19:47:08 INFO - PROCESS | 1657 | RSS: Main: 300097536 19:47:08 INFO - PROCESS | 1657 | 19:47:11 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49231/tests/kraken/driver.html?testName=imaging-desaturate) 19:47:11 INFO - PROCESS | 1657 | RSS: Main: 302161920 19:47:11 INFO - PROCESS | 1657 | 19:47:14 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49231/tests/kraken/driver.html?testName=json-parse-financial) 19:47:15 INFO - PROCESS | 1657 | RSS: Main: 299773952 19:47:15 INFO - PROCESS | 1657 | 19:47:17 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49231/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:47:17 INFO - PROCESS | 1657 | RSS: Main: 276336640 19:47:17 INFO - PROCESS | 1657 | 19:47:19 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:47:20 INFO - PROCESS | 1657 | RSS: Main: 280530944 19:47:20 INFO - PROCESS | 1657 | 19:47:22 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:47:22 INFO - PROCESS | 1657 | RSS: Main: 288292864 19:47:22 INFO - PROCESS | 1657 | 19:47:25 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:47:26 INFO - PROCESS | 1657 | RSS: Main: 301813760 19:47:26 INFO - PROCESS | 1657 | 19:47:29 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:47:30 INFO - PROCESS | 1657 | RSS: Main: 295481344 19:47:30 INFO - PROCESS | 1657 | 19:47:31 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49231/tests/kraken/driver.html?testName=ai-astar) 19:47:32 INFO - PROCESS | 1657 | RSS: Main: 340217856 19:47:32 INFO - PROCESS | 1657 | 19:47:32 INFO - PROCESS | 1657 | __start_tp_report 19:47:32 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 19:47:32 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 19:47:32 INFO - PROCESS | 1657 | |i|pagename|runs| 19:47:32 INFO - PROCESS | 1657 | |0;ai-astar;103;115;115;113;112;114;113;114;120;168 19:47:32 INFO - PROCESS | 1657 | |1;audio-beat-detection;144;109;109;145;110;108;109;105;130;108 19:47:32 INFO - PROCESS | 1657 | |2;audio-dft;186;182;184;188;183;185;179;184;186;184 19:47:32 INFO - PROCESS | 1657 | |3;audio-fft;81;61;62;91;62;63;61;62;79;83 19:47:32 INFO - PROCESS | 1657 | |4;audio-oscillator;85;67;87;68;69;69;81;69;69;82 19:47:32 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;91;90;91;90;91;91;91;89;91;93 19:47:32 INFO - PROCESS | 1657 | |6;imaging-darkroom;97;96;98;97;98;97;114;149;97;96 19:47:32 INFO - PROCESS | 1657 | |7;imaging-desaturate;85;86;83;83;86;83;85;81;85;84 19:47:32 INFO - PROCESS | 1657 | |8;json-parse-financial;61;60;61;61;64;65;63;63;64;63 19:47:32 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;54;60;64;64;60;57;59;57;57;57 19:47:32 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;84;86;85;81;82;79;85;85;86;90 19:47:32 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;136;137;136;135;135;136;134;157;210;146 19:47:32 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;163;159;157;162;163;162;168;164;160;142 19:47:32 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;65;89;83;82;70;63;65;64;64;63 19:47:32 INFO - PROCESS | 1657 | __end_tp_report 19:47:32 INFO - PROCESS | 1657 | __start_cc_report 19:47:32 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,2158 19:47:32 INFO - PROCESS | 1657 | __end_cc_report 19:47:32 INFO - PROCESS | 1657 | __startTimestamp1496630852062__endTimestamp 19:47:32 INFO - PROCESS | 1657 | 19:47:32 INFO - PROCESS | 1657 | ------- Summary: start ------- 19:47:32 INFO - PROCESS | 1657 | Number of tests: 14 19:47:32 INFO - PROCESS | 1657 | 19:47:32 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:118.70 Median:114.50 stddev:17.83 (15.6%) stddev-sans-first:17.98 19:47:32 INFO - PROCESS | 1657 | Values: 103.0 115.0 115.0 113.0 112.0 114.0 113.0 114.0 120.0 168.0 19:47:32 INFO - PROCESS | 1657 | 19:47:32 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:117.70 Median:109.50 stddev:15.72 (14.4%) stddev-sans-first:13.49 19:47:32 INFO - PROCESS | 1657 | Values: 144.0 109.0 109.0 145.0 110.0 108.0 109.0 105.0 130.0 108.0 19:47:32 INFO - PROCESS | 1657 | 19:47:32 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:184.10 Median:184.50 stddev:2.47 (1.3%) stddev-sans-first:2.52 19:47:32 INFO - PROCESS | 1657 | Values: 186.0 182.0 184.0 188.0 183.0 185.0 179.0 184.0 186.0 184.0 19:47:32 INFO - PROCESS | 1657 | 19:47:32 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:70.50 Median:71.00 stddev:11.61 (16.3%) stddev-sans-first:11.67 19:47:32 INFO - PROCESS | 1657 | Values: 81.0 61.0 62.0 91.0 62.0 63.0 61.0 62.0 79.0 83.0 19:47:32 INFO - PROCESS | 1657 | 19:47:32 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:74.60 Median:75.00 stddev:8.06 (10.7%) stddev-sans-first:7.62 19:47:32 INFO - PROCESS | 1657 | Values: 85.0 67.0 87.0 68.0 69.0 69.0 81.0 69.0 69.0 82.0 19:47:32 INFO - PROCESS | 1657 | 19:47:32 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:90.80 Median:91.00 stddev:1.03 (1.1%) stddev-sans-first:1.09 19:47:32 INFO - PROCESS | 1657 | Values: 91.0 90.0 91.0 90.0 91.0 91.0 91.0 89.0 91.0 93.0 19:47:32 INFO - PROCESS | 1657 | 19:47:32 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:103.90 Median:97.00 stddev:16.74 (17.3%) stddev-sans-first:17.56 19:47:32 INFO - PROCESS | 1657 | Values: 97.0 96.0 98.0 97.0 98.0 97.0 114.0 149.0 97.0 96.0 19:47:32 INFO - PROCESS | 1657 | 19:47:32 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:84.10 Median:85.00 stddev:1.60 (1.9%) stddev-sans-first:1.66 19:47:32 INFO - PROCESS | 1657 | Values: 85.0 86.0 83.0 83.0 86.0 83.0 85.0 81.0 85.0 84.0 19:47:32 INFO - PROCESS | 1657 | 19:47:32 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:62.50 Median:63.00 stddev:1.65 (2.6%) stddev-sans-first:1.66 19:47:32 INFO - PROCESS | 1657 | Values: 61.0 60.0 61.0 61.0 64.0 65.0 63.0 63.0 64.0 63.0 19:47:32 INFO - PROCESS | 1657 | 19:47:32 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:58.90 Median:59.50 stddev:3.21 (5.4%) stddev-sans-first:2.88 19:47:32 INFO - PROCESS | 1657 | Values: 54.0 60.0 64.0 64.0 60.0 57.0 59.0 57.0 57.0 57.0 19:47:32 INFO - PROCESS | 1657 | 19:47:32 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:85.00 stddev:3.06 (3.6%) stddev-sans-first:3.24 19:47:32 INFO - PROCESS | 1657 | Values: 84.0 86.0 85.0 81.0 82.0 79.0 85.0 85.0 86.0 90.0 19:47:32 INFO - PROCESS | 1657 | 19:47:32 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:146.20 Median:136.50 stddev:23.53 (17.2%) stddev-sans-first:24.67 19:47:32 INFO - PROCESS | 1657 | Values: 136.0 137.0 136.0 135.0 135.0 136.0 134.0 157.0 210.0 146.0 19:47:32 INFO - PROCESS | 1657 | 19:47:32 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.00 Median:162.50 stddev:6.99 (4.3%) stddev-sans-first:7.33 19:47:32 INFO - PROCESS | 1657 | Values: 163.0 159.0 157.0 162.0 163.0 162.0 168.0 164.0 160.0 142.0 19:47:32 INFO - PROCESS | 1657 | 19:47:32 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.80 Median:67.50 stddev:9.93 (14.7%) stddev-sans-first:10.31 19:47:32 INFO - PROCESS | 1657 | Values: 65.0 89.0 83.0 82.0 70.0 63.0 65.0 64.0 64.0 63.0 19:47:32 INFO - PROCESS | 1657 | -------- Summary: end -------- 19:47:32 INFO - PROCESS | 1657 | 19:47:32 INFO - TEST-INFO | 1657: exit 0 19:47:32 INFO - TEST-OK | kraken | took 59084ms 19:47:32 INFO - SUITE-END | took 1178s 19:47:33 INFO - Completed test suite (00:19:39) 19:47:33 INFO - javascript benchmark 19:47:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7684.87219407887, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3392.3045775615997, 3396.5075064115704, 3405.9318813623713, 3442.7073698735608, 3471.600765911919, 5752.626079304842, 5827.213326200967, 5849.502792262663, 5850.648961062339, 5854.3267524234725, 5782.826515204551, 5814.970925145369, 5822.796202132926, 5845.766169353232, 5848.853778655536, 5281.313429254192, 5342.1452567589195, 5343.599230057742, 5353.464653534655, 5391.81128660497, 5248.921266180992, 5263.473652634725, 5281.181416880389, 5429.864253393657, 5432.891342173154, 5378.435264297251, 5385.2999110115825, 5399.62202645815, 5443.0, 5466.699331536764, 5432.076546987022, 5434.239206511072, 5441.075017247077, 5444.101723215705, 5451.809186678447, 4425.291953287491, 5200.453952334989, 5299.284596579451, 5346.812861549866, 5353.304070470814, 5761.654300741927, 5763.049096899007, 5766.394528574481, 5767.1349297605275, 5807.883842323172, 6369.93630063698, 6374.0, 6391.872162556723, 6402.359764023607, 6403.423691867754, 6366.267879193906, 6376.968115159394, 6380.106785050097, 6385.361463853624, 6394.712237949303, 5742.54059675225, 5806.04200306953, 5844.152597873284, 5936.317323507808, 6000.309964354112, 6390.169277993831, 6413.743450262031, 6414.807555773335, 6426.939554973377, 6432.967835160888, 6323.652199129, 6331.873362532723, 6333.113364129025, 6333.714982825784, 6369.26753423353, 6248.125262463259, 6271.749130034838, 6276.058591211355, 6417.197850268716, 6424.3254458282145, 5262.578993680497, 6189.164462797555, 6319.431251187415, 6352.872942541216, 6379.234491860913, 6268.686565671698, 6274.090256912722, 6346.650934198571, 6393.328700486475, 6407.423331900058, 4768.213244814567, 4839.2983017462975, 4872.51274872517, 4902.240152776325, 4930.950690493121, 4407.184670835905, 4416.492103408085, 4433.0, 4439.200943830077, 4453.710508816916, 5556.777728890799, 5613.073842815971, 5653.349864765522, 5653.406392328829, 5666.348369937427, 6343.270523889812, 6347.047942808524, 6350.491960643138, 6352.333005034498, 6356.682165891687, 5627.606067575312, 5639.464250896158, 5641.12562552805, 5649.971750141223, 5652.378238393773, 4576.8626941191815, 5383.111786555253, 5407.0, 5422.864428389322, 5427.0, 6361.363863613602, 6370.426661600478, 6395.912694841795, 6402.35976402356, 6472.546921715435, 6339.524535659752, 6342.270638876588, 6358.205224346957, 6368.5860419072615, 6372.490200783834, 6468.223813142453, 6494.64279464634, 6512.706928188338, 6525.0, 6527.836804079936, 6132.967444181115, 6161.938380616137, 6280.466160376419, 6368.267649220214, 6373.426391624776, 6311.958526843019, 6314.652694101685, 6336.049592561153, 6345.555811093179, 6367.299597044229, 6250.062490626442, 6276.995680691068, 6289.654069026247, 6289.811305660837, 6302.65335406557, 5946.375630558729, 6123.908141377794, 6196.1015652731, 6289.245290565162, 6294.244690637152, 6075.969620151871, 6077.4834139098675, 6086.482648974887, 6104.542159337979, 6117.571769976059, 5172.793088276427, 6213.615420593393, 6250.218722659668, 6279.591826531261, 6287.899617566999, 5823.854403639773, 5863.648181109147, 6094.299155597163, 6112.449879510866, 6119.265688117454], "value": 5781.567081268128, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [754.3399525415261, 783.8980932478779, 808.6320724070547, 877.8376000439923, 887.0774394629585, 27833.025844095435, 39210.60789392105, 39613.2077358453, 41066.58933410665, 41852.0, 33423.997280081574, 41141.38287925684, 41213.17573648525, 41295.0, 41356.58643413565, 7582.2038685938005, 7722.498037627564, 9467.715968520955, 9642.566084526214, 9651.758706032335, 11343.829842552368, 11611.94194029033, 11719.414029298501, 11742.295462272248, 11759.412029398538, 11546.884531154665, 11564.363959982198, 11584.536618535249, 11591.24656897299, 11633.243839150427, 11462.025727813147, 11486.483108260065, 11503.769924601545, 11504.25222360544, 11515.424228788526, 11338.829917551244, 11411.94294028533, 11413.372264525447, 11417.200795944287, 11424.257423267461, 39104.413433798516, 39304.01739956495, 39315.410268845706, 39366.803165984275, 39411.8029409854, 3351.614564325085, 3960.6237407446247, 3963.7225394222414, 3980.840766369342, 4023.839046438139, 27715.168544943685, 28128.437431251466, 29272.268193295124, 29676.554851677243, 29693.703062969525, 3162.4623813951453, 3164.556962025318, 3169.2710676544293, 3170.239142605781, 3249.6262929762906, 372890.0, 375450.0, 376372.0, 376687.99999999726, 376910.0, 53174.0, 53269.73365133227, 53324.73337633287, 53429.0, 53505.732471338175, 54813.725931370085, 56032.0, 56110.438895610525, 56187.87624247574, 56239.0, 14882.851171488146, 14948.252587370587, 14964.850351496345, 14967.326470308863, 14972.401103955719, 10989.340639561651, 11176.385298808482, 11205.383703896365, 12976.091673582947, 12977.35113244334, 10041.899581004243, 12080.335581542924, 12120.45457954394, 12284.754304913851, 12328.383580821102, 5542.63972841764, 5548.667079975215, 5550.69471179089, 5561.360443549043, 5584.52531534816, 941.3222479814523, 1097.8023955687893, 1112.477135746198, 1112.7607564373698, 1141.7545227776068, 28026.159215223575, 28253.01114460984, 28299.575506367422, 28462.0, 28576.571351429746, 1009.4397609326747, 1118.8978855827004, 1181.4565299962114, 1191.5769901684778, 1212.496813822254, 12734.745305094031, 13182.934085329513, 14215.928920355333, 14473.565793026226, 14723.969322147346, 440353.0, 440842.0, 440971.0, 441286.0, 442122.0, 57180.71409642925, 57303.71348143233, 57326.14010789841, 57378.71310643503, 57406.0, 1400.6218321053461, 1431.7351290011381, 1435.4258296681412, 1481.5999680086531, 1491.6270932266934, 26790.732092679602, 27277.454450910474, 27809.58285625677, 27934.58098128489, 27950.58074128849, 37663.81168094142, 37854.81072594619, 37903.24193516169, 37964.810175948944, 38028.0, 422426.0, 422683.0, 423062.0, 423076.0, 423192.0, 29236.56145157867, 33230.504627292314, 39746.80126599348, 39800.60199397969, 40501.1899762009, 31812.84093579517, 43896.56103439053, 47294.290585640556, 52299.95400092053, 53225.0, 32652.0, 33010.33979320448, 33030.009099726085, 33184.336313274085, 34184.0, 32917.835410822794, 33349.66650333466, 34160.0, 34411.0, 34524.30951380908, 39975.80012099921, 40278.39582406325, 40497.1900561993, 40687.59312406838, 41413.96465088397, 33015.0, 33824.83087584645, 35251.82374088113, 35305.82347088248, 35438.29123417569, 26108.086216982316, 26555.7344426561, 26574.335641609116, 26665.0, 27206.911723530837], "value": 19305.267715561644, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.98596549120805, 405.7099174090528, 421.30695006713955, 426.08604543254717, 443.63178561793717, 17437.65124697507, 20296.98515074246, 21836.344909652737, 22274.554508909852, 22388.328350149503, 20700.8964955175, 21397.0, 21804.45488862779, 22179.0, 22636.660450093263, 6454.160959075325, 6697.229818570878, 6736.157980252468, 6744.0, 6756.425703815182, 6660.70026848793, 7374.705011799521, 7711.614419279042, 7843.254890785366, 7865.0, 7761.45669803114, 7784.104827944774, 7857.6856925722905, 7858.057033156029, 7880.211978802103, 7547.547147171133, 7563.357114645261, 7795.103563090203, 7797.454178207556, 7806.024246969129, 7561.621918904088, 7640.0, 7643.656035478361, 7647.120581133129, 7653.311201991847, 25436.23691289246, 26134.34664133393, 26270.47459050808, 26292.0, 26568.0, 2916.416716656656, 3024.561438591392, 3039.4984827503436, 3040.528718048706, 3042.1938186380626, 15198.240087995666, 16380.344786208656, 16488.186299507044, 16617.91691041549, 17002.0, 1946.13397038318, 2022.3730643500414, 2028.2191356327774, 2031.1570698160247, 2033.186725309873, 108192.0, 117150.0, 128205.0, 130472.34763826214, 162295.3770462292, 15032.323545440482, 18786.342478013477, 20022.596158652774, 20157.99210039492, 20202.59594808125, 24919.127830525846, 31432.52851207234, 31599.0, 31632.683673163436, 31809.363812723615, 10085.798284034277, 10366.481675916173, 10504.527296271686, 10675.786484270271, 10677.572897084185, 8738.257248133852, 8766.824663506823, 8774.473531588124, 8906.821863562693, 8923.15230053144, 8914.064023277462, 9245.785534675464, 9318.207952323994, 9357.298202634829, 9357.6954600883, 3988.4216788565495, 4056.9797151014645, 4063.6139566741113, 4073.7759423231414, 4081.285774989365, 836.916193529381, 854.1714536899322, 890.8396488632109, 892.7812685891998, 915.1534830281962, 18365.81634183668, 20308.69536956947, 21404.0, 21446.0, 21469.355919322446, 906.777839429344, 907.2333877873186, 922.617113897725, 925.4678559828126, 935.4901578639658, 10545.367277963347, 11004.889951100547, 11018.614348497924, 11112.277701949348, 11154.609588664358, 615660.0, 727020.0, 728998.0, 729430.0, 744700.0, 114397.42801286107, 135878.6412135866, 147778.0, 148869.0, 150386.24806875896, 1249.2005116725304, 1274.4519856461811, 1278.910476266671, 1369.5480491437802, 1376.6351916742071, 19192.232310707976, 19299.324523641604, 19866.701999470308, 19868.304609339182, 19902.90048549748, 50983.98032039413, 55759.884802304536, 61888.38111619006, 62234.0, 62920.37079629145, 86267.5686621588, 88595.55702221705, 89695.13353531237, 89754.55122724344, 91921.7019574516, 11233.719157020812, 11311.490982905625, 11445.942770286096, 11536.884631153916, 11729.941350293195, 10229.283950123718, 12392.318422486489, 12731.172473789175, 12816.487340506275, 12923.612291631267, 10781.676549703521, 10830.67507974762, 10961.397123157984, 11234.775304493702, 11242.606508772154, 10013.39919604826, 10161.695149145537, 10322.225833062701, 10338.379697218192, 10357.223208259562, 10461.843072353768, 11029.007389334998, 11047.558097676325, 11176.329420234812, 11320.830187547028, 9656.130948214695, 11128.054115399958, 11128.998390144925, 11376.0330371916, 11404.429778511207, 8766.517841518778, 8835.779105522413, 10818.080463160404, 10883.183761217782, 10910.72723181895], "value": 11458.822196117024, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1487.4793822162244, 1568.1531972734724, 1578.6605879735855, 1608.5254849819296, 1610.8067031956164, 10602.575896964121, 10823.24237303389, 10825.0, 10843.728906777334, 10845.457727113651, 10628.415437150954, 10873.238873278853, 11202.159838012141, 11244.718882027953, 11245.831312530298, 2291.404234898926, 2349.5653304138705, 2350.7766762157567, 2351.6825228594175, 2354.682117914077, 2270.0, 2341.4497593065616, 2354.81161507079, 2361.480474295652, 2363.2555744940373, 2289.3360925331635, 2333.6149535326736, 2343.7304709958403, 2347.366211122996, 2350.9177178798745, 2281.8060464860505, 2286.474110954473, 2316.664083707861, 2316.81465482761, 2319.6404557293645, 2308.8845557722216, 2310.2491690200586, 2310.572544079351, 2312.052058655943, 2314.93055208344, 9067.909320906772, 10937.671869843918, 10948.014678678877, 10950.288231264942, 10958.780824383548, 5423.810166644149, 5567.387587365387, 5569.749361278752, 5572.693501857396, 5574.9721251393485, 3247.1232767152737, 3319.6139937263856, 3454.291870166635, 3502.474628805674, 3506.5266189064405, 970.6166064404521, 981.8855598895234, 1099.6700989703056, 1133.4616057372764, 1188.7979043562616, 13952.093113947562, 14128.011039227258, 14301.14193148404, 14934.775978360334, 15316.463923762614, 4587.9541204588195, 4727.149113159664, 4739.573438390493, 4746.335513028179, 4751.477337492873, 12866.806997895039, 12869.549565765154, 12874.16317939331, 12876.613701588967, 12886.613401597968, 4817.78319975602, 4993.375828021497, 5018.899622007612, 5665.9716701417055, 5667.603267771299, 4261.232978063978, 5111.437741848394, 5153.2270159476375, 5163.948360516347, 5184.222366644958, 3286.375588638151, 3806.238752249561, 3819.1216020315483, 3879.1465877506903, 3901.49280593521, 996.3822430093388, 1008.6570566007592, 1025.8922813104666, 1027.984580231297, 1032.2155162076876, 942.5617088054088, 952.6665667016489, 968.2689569375218, 972.1590823937385, 996.5515518016921, 6316.463100636497, 6329.746810127635, 6474.579152355176, 6543.0185472179555, 6652.634105124168, 861.521855370263, 989.737719504332, 994.3238597753452, 1019.7603563162651, 1047.5181416548473, 4092.7748973806724, 4123.381492776108, 4150.066235097079, 4235.25882970479, 4310.116426132635, 12919.806202906964, 13535.39090740919, 13547.729045419037, 13548.729025419436, 13553.728925421436, 13437.395317210749, 13466.394012269471, 13476.797848032287, 13483.98870084728, 13488.595342139752, 9776.755581110529, 10079.748006299755, 10083.042110999444, 10116.34243774167, 10184.032516910882, 6359.618422894641, 6586.40722334973, 6610.438112760469, 6769.221539522821, 6876.381125698711, 9675.419474831533, 11853.822192666945, 12035.217710848767, 12175.817362739743, 12353.752924941271, 11379.317240965569, 11746.236494627821, 12291.631251062483, 12326.50693972231, 12337.383130843242, 8315.0, 9165.312601554777, 9181.0, 9215.308851836005, 9305.906940930505, 10227.0, 10231.590736370461, 10345.27583069208, 10369.274150809371, 10382.532786024496, 8608.569571521524, 8744.431611945203, 8803.647854085764, 8828.38201325901, 8862.512561809162, 8527.957360213408, 8528.275096616857, 8611.268042216481, 8612.397132200686, 8686.0, 7725.961370193113, 7854.646540905559, 7869.0, 7874.84250314979, 7890.960545197467, 4594.425696787902, 5644.294463192101, 5733.483986441241, 5833.037548804569, 5906.645601263938, 5757.539396848326, 5874.17761513397, 5884.0, 5890.676012819336, 5923.526117910471], "value": 5033.366016900215, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.63984475291835, 373.86914579897035, 379.60900272719107, 381.8892521168861, 384.68455866189726, 4500.199920031989, 4724.999999999995, 5135.512126347996, 5144.202648589465, 5277.208418737191, 4616.399868017162, 4704.153252414564, 4793.808247670089, 4974.6669731280035, 5096.592272618191, 3986.5614782373914, 4213.705040647156, 4220.388043733657, 4242.363645453192, 4244.797650326186, 4268.658507319423, 4271.359296105594, 4275.700700950934, 4307.698461107724, 4320.503142138663, 3269.88555400561, 4176.45706058212, 4190.308599081148, 4270.893227669302, 4301.311790113583, 3554.8044857532695, 4020.778857162855, 4173.937390939138, 4182.218824481716, 4240.363945408198, 3743.5507739071213, 3894.7273690841653, 3912.56961734209, 3919.2357490289405, 3934.2328245991757, 4633.003904160588, 4980.452150263468, 5017.84946451607, 5030.647854650176, 5083.822066227664, 3815.3704638734575, 4704.882377940579, 4753.904921901542, 4853.781579828917, 4857.348501509468, 4731.077439899221, 4770.666053376265, 5148.974255128738, 5363.6245462817615, 5512.862178445571, 3815.637514436124, 3868.170511193926, 4133.545310015896, 4136.648384887289, 4386.583274588909, 5138.434772175057, 5303.761330740126, 5306.885554033696, 5494.59335212167, 5641.802536911188, 3919.0594257378593, 4218.767967761742, 4394.406755088086, 4436.6672499562665, 4465.531119232499, 3957.821898014596, 4016.9196616068098, 4329.956700433018, 4500.054988452396, 4505.909881802346, 3851.6340947609933, 4482.551744825557, 4729.0207169483, 4827.62066553414, 4885.899063402771, 4546.909061818745, 5010.098182327143, 5384.353877534721, 5441.59188060897, 5447.945520544744, 1361.6323592629935, 1380.9447622095204, 1448.1455940994654, 1461.9547023877979, 1548.5122186511212, 930.2883294279882, 985.8416360776203, 1011.0748664971616, 1167.4104577188466, 1196.4556126962211, 4329.458817647794, 4590.954090459053, 4722.787474563653, 4920.8769780755765, 4926.852194434173, 5338.599605029638, 5570.832875013756, 5580.888382232332, 5613.7193140342815, 5654.688992105392, 3731.738778285548, 4169.10188973311, 4453.64163929998, 4864.221724524059, 4941.777620007108, 2684.9194524164304, 2685.87913543891, 2738.0, 2919.489089409345, 2921.5617657351568, 4728.787204575802, 5049.722265275445, 5505.935274272148, 5589.745639099315, 5869.354371019184, 4985.152524070917, 5044.166433510485, 5218.582513398919, 5686.914696279559, 5708.80019199326, 4844.442889067732, 5113.38928237604, 5793.8551536211935, 5795.580607441714, 5843.2403787507355, 4592.346755168203, 4834.371531700856, 5134.460881607527, 5566.218809980929, 5601.80393686219, 4965.45380008206, 5153.587712982878, 5163.845084647466, 5259.237410575369, 5530.695811730394, 4832.468428472795, 5644.322681278109, 5674.716264186856, 5729.570282228766, 5750.971245143747, 4390.6169556589575, 4402.84590039347, 4410.911781764283, 4426.623736982264, 4431.756253405964, 4682.555157260055, 5494.983428065821, 5575.773329867423, 5579.665220086646, 5616.269884914935, 4776.928346074742, 4992.875178120431, 5077.720725360141, 5378.085725426765, 5413.621046526863, 5335.33308336458, 5391.605841239378, 5405.72971351423, 5424.5660347172925, 5434.483724046209, 4607.765233861642, 4921.90818901958, 5100.821471248489, 5168.250603662523, 5252.65857719247, 4948.307236986752, 5028.145215313332, 5351.277577526984, 5437.8368648940595, 5485.451454854483, 4378.343248512749, 4981.658620448384, 5114.181730923035, 5329.7068661224, 5337.572994160536], "value": 4096.502733075986, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [720.3912693773761, 766.057748968768, 801.1587832775584, 848.1264297773288, 852.4075767341698, 16194.514164575066, 16222.351105955777, 16350.75473867893, 16473.093979831105, 16486.0, 12382.690432739186, 15121.924390378033, 15125.621859453518, 15125.848741512582, 15130.848691513082, 956.3974695941547, 961.9903800962006, 990.2968892086627, 992.0971915556845, 993.7267251505822, 989.3618615992681, 994.4182653147881, 999.5252255178768, 1000.6697789729371, 1002.1081237698454, 862.8921384826897, 1052.773653664462, 1053.020690757595, 1054.251481448169, 1054.3673795722582, 1049.0401282826206, 1049.2235745548317, 1049.548694061557, 1050.317293759055, 1050.4695128959827, 781.7498400511838, 782.9334506566948, 783.3928705253417, 783.9333656639193, 785.2461636828651, 16451.25969331383, 16481.0, 16505.0, 17027.659446811118, 17345.306187752474, 14243.78634320486, 14399.78400323996, 14448.71102577953, 14459.78310325346, 14483.131012139305, 11661.24201926872, 11743.236689615147, 11817.468213930308, 11919.582814601446, 12281.324527151002, 3531.2937412517344, 3753.418220175877, 3774.50931378919, 3793.1275806564367, 3878.6897048236074, 105604.0, 115738.0, 115865.28677437802, 118500.0, 119414.4029279848, 10375.377477351383, 10901.400422976812, 11051.0, 11331.830022549668, 11519.25124866881, 22800.087996479953, 23242.186523471937, 23534.882325588263, 23594.29217123489, 23622.0, 6268.529860260499, 7760.611969401507, 7814.452988290879, 7923.722669706648, 8078.394120440873, 4356.433663623772, 4412.852658308799, 4457.821687132543, 4554.840580579664, 4600.378948841863, 3413.9829300853335, 3471.218975730491, 3508.210652603195, 3515.9296814064087, 3542.8405721742583, 649.3409189672486, 652.885744994624, 655.7803135949415, 656.8554917918051, 657.9111819904251, 654.1594051643615, 660.8810414125503, 680.1973671068088, 693.5076095971916, 715.377621469325, 10043.648472303434, 10462.215333849992, 10595.576176952989, 10717.35695858251, 10765.461726913623, 674.6323253826578, 679.942204912588, 711.7970629635898, 725.3109545931386, 737.3695490355776, 4822.95177048225, 5137.691738495702, 5145.305383773143, 5148.227765835155, 5149.7940082397035, 106864.0, 107657.0, 109714.0, 110147.34778978322, 111525.0, 84545.73181402285, 88369.0, 89273.53632318358, 92591.0, 92672.60991085139, 73076.63461682764, 75514.0, 79819.60090199512, 80860.59569702114, 80861.59569202234, 9648.614055437703, 9674.467904265333, 9726.270529710442, 9733.0, 9739.074787895139, 52236.0, 53274.73362633317, 53528.73235633797, 54549.72725136349, 55692.44307557034, 57547.424525754206, 63063.36936630575, 66044.0, 66544.00183997148, 72932.17669558304, 12676.0, 12914.0, 12936.619613263774, 13100.672483187996, 13570.728585428433, 14745.185051922499, 14927.776083358542, 15128.0, 15213.923930380277, 15799.842001579837, 13745.926076582351, 13806.641093263295, 14102.788458173341, 14104.435822566982, 14336.0, 13324.733505329645, 13558.389872455566, 14059.111837219907, 14319.28403579796, 14528.418863245775, 11356.217726656692, 13314.40085196149, 13710.931445342709, 13739.450421983009, 14185.645358865699, 9195.724128276162, 9259.67591134307, 9495.477748723886, 9678.467684277432, 9870.358426702242, 10022.198224142197, 10046.648367307109, 11400.201985860911, 11411.486483108114, 11520.769584608428], "value": 7809.845157936683, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1427.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 115.0, 113.0, 112.0, 114.0, 113.0, 114.0, 120.0, 168.0], "value": 118.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 109.0, 109.0, 145.0, 110.0, 108.0, 109.0, 105.0, 130.0, 108.0], "value": 117.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 182.0, 184.0, 188.0, 183.0, 185.0, 179.0, 184.0, 186.0, 184.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 61.0, 62.0, 91.0, 62.0, 63.0, 61.0, 62.0, 79.0, 83.0], "value": 70.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 67.0, 87.0, 68.0, 69.0, 69.0, 81.0, 69.0, 69.0, 82.0], "value": 74.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 91.0, 90.0, 91.0, 91.0, 91.0, 89.0, 91.0, 93.0], "value": 90.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 98.0, 97.0, 98.0, 97.0, 114.0, 149.0, 97.0, 96.0], "value": 103.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 83.0, 83.0, 86.0, 83.0, 85.0, 81.0, 85.0, 84.0], "value": 84.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 61.0, 61.0, 64.0, 65.0, 63.0, 63.0, 64.0, 63.0], "value": 62.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 60.0, 64.0, 64.0, 60.0, 57.0, 59.0, 57.0, 57.0, 57.0], "value": 58.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 85.0, 81.0, 82.0, 79.0, 85.0, 85.0, 86.0, 90.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 137.0, 136.0, 135.0, 135.0, 136.0, 134.0, 157.0, 210.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 159.0, 157.0, 162.0, 163.0, 162.0, 168.0, 164.0, 160.0, 142.0], "value": 160.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 89.0, 83.0, 82.0, 70.0, 63.0, 65.0, 64.0, 64.0, 63.0], "value": 70.8, "unit": "score"}]}]} 19:47:33 INFO - Return code: 0 19:47:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:47:33 INFO - # TBPL SUCCESS # 19:47:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:47:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:47:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:47:33 INFO - 2017-06-04 19:47:33.384 screenresolution[1661:10064] starting screenresolution argv=screenresolution get 19:47:33 INFO - 2017-06-04 19:47:33.386 screenresolution[1661:10064] Display 0: 1600x1200x32@60 19:47:33 INFO - 2017-06-04 19:47:33.392 screenresolution[1662:10067] starting screenresolution argv=screenresolution list 19:47:33 INFO - Available Modes on Display 0 19:47:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:47:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:47:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:47:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:47:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:47:33 INFO - Intel Iris: 19:47:33 INFO - Chipset Model: Intel Iris 19:47:33 INFO - Type: GPU 19:47:33 INFO - Bus: Built-In 19:47:33 INFO - VRAM (Dynamic, Max): 1536 MB 19:47:33 INFO - Vendor: Intel (0x8086) 19:47:33 INFO - Device ID: 0x0a2e 19:47:33 INFO - Revision ID: 0x0009 19:47:33 INFO - Displays: 19:47:33 INFO - Display: 19:47:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:47:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:47:33 INFO - Display Serial Number: 1600x1200 60 19:47:33 INFO - Main Display: Yes 19:47:33 INFO - Mirror: Off 19:47:33 INFO - Online: Yes 19:47:33 INFO - Rotation: Supported 19:47:33 INFO - Return code: 0 19:47:33 INFO - Running post-action listener: _resource_record_post_action 19:47:33 INFO - [mozharness: 2017-06-05 02:47:33.626794Z] Finished run-tests step (success) 19:47:33 INFO - Running post-run listener: _resource_record_post_run 19:47:33 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 34343936; Write bytes: 509948928; Read time: 315; Write time: 1630 19:47:33 INFO - TinderboxPrint: CPU usage
53.7% 19:47:33 INFO - TinderboxPrint: I/O read bytes / time
34,343,936 / 315 19:47:33 INFO - TinderboxPrint: I/O write bytes / time
509,948,928 / 1,630 19:47:33 INFO - TinderboxPrint: CPU idle
2,221.9 (46.3%) 19:47:33 INFO - TinderboxPrint: CPU system
957.0 (19.9%) 19:47:33 INFO - TinderboxPrint: CPU user
1,622.7 (33.8%) 19:47:33 INFO - TinderboxPrint: Swap in / out
239,058,944 / 0 19:47:33 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 200583680; Write bytes: 203229184; Read time: 15645; Write time: 446 19:47:33 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 33364992; Write bytes: 296791040; Read time: 296; Write time: 1152 19:47:34 INFO - Running post-run listener: _upload_blobber_files 19:47:34 INFO - Blob upload gear active. 19:47:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:47:34 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 19:47:34 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'] 19:47:34 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 19:47:34 INFO - (blobuploader) - INFO - Open directory for files ... 19:47:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:47:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:47:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:47:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:47:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:47:35 INFO - (blobuploader) - INFO - Done attempting. 19:47:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:47:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:47:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:47:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:47:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:47:36 INFO - (blobuploader) - INFO - Done attempting. 19:47:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:47:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:47:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:47:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:47:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:47:37 INFO - (blobuploader) - INFO - Done attempting. 19:47:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:47:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:47:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:47:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:47:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:47:39 INFO - (blobuploader) - INFO - Done attempting. 19:47:39 INFO - (blobuploader) - INFO - Iteration through files over. 19:47:39 INFO - Return code: 0 19:47:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:47:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:47:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/409e7b7b221c8dee180db226ac7489674a13d7146349884459a1594e28e8337aa9b3603b7f90c3df67b2a67c716a1c01d20a122a2b3aec9a613294703cbe8339", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c8e47f2bf1e42e9d3eebc2efe9d6df098c45c72422d191bb1334e658ac2942b66209d25ef56aa60df6a7109eeeac939bcd8e15e92508e688f908f55f6e9e9f09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1fbeece47136d6f74a7171f4bd3094dbaf7982c4f3af1ab5e4d06a3c50827609658211b602224c5264a1120b0c6e113e13fbd82ada07a8687be5fd59e5d70963", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3d9992a5a140c6d8b0f77b63cc5be71096ff7182a06fc7c8e68b968b863f434e697ac86f2e2de70661f94e746e00e88175c4f3e40cb2d6b8a6148603e4abb993"} 19:47:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:47:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:47:39 INFO - Contents: 19:47:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/409e7b7b221c8dee180db226ac7489674a13d7146349884459a1594e28e8337aa9b3603b7f90c3df67b2a67c716a1c01d20a122a2b3aec9a613294703cbe8339", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c8e47f2bf1e42e9d3eebc2efe9d6df098c45c72422d191bb1334e658ac2942b66209d25ef56aa60df6a7109eeeac939bcd8e15e92508e688f908f55f6e9e9f09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1fbeece47136d6f74a7171f4bd3094dbaf7982c4f3af1ab5e4d06a3c50827609658211b602224c5264a1120b0c6e113e13fbd82ada07a8687be5fd59e5d70963", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3d9992a5a140c6d8b0f77b63cc5be71096ff7182a06fc7c8e68b968b863f434e697ac86f2e2de70661f94e746e00e88175c4f3e40cb2d6b8a6148603e4abb993"} 19:47:39 INFO - Running post-run listener: copy_logs_to_upload_dir 19:47:39 INFO - Copying logs to upload dir... 19:47:39 INFO - mkdir: /builds/slave/test/build/upload/logs 19:47:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.339769 ========= master_lag: 0.47 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-06-04 19:47:39.694541) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-04 19:47:39.698109) ========= 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.Yrjj5qEzyC/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.kH12Ds43ib/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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/409e7b7b221c8dee180db226ac7489674a13d7146349884459a1594e28e8337aa9b3603b7f90c3df67b2a67c716a1c01d20a122a2b3aec9a613294703cbe8339", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c8e47f2bf1e42e9d3eebc2efe9d6df098c45c72422d191bb1334e658ac2942b66209d25ef56aa60df6a7109eeeac939bcd8e15e92508e688f908f55f6e9e9f09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1fbeece47136d6f74a7171f4bd3094dbaf7982c4f3af1ab5e4d06a3c50827609658211b602224c5264a1120b0c6e113e13fbd82ada07a8687be5fd59e5d70963", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3d9992a5a140c6d8b0f77b63cc5be71096ff7182a06fc7c8e68b968b863f434e697ac86f2e2de70661f94e746e00e88175c4f3e40cb2d6b8a6148603e4abb993"} build_url:https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011107 build_url: 'https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/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/409e7b7b221c8dee180db226ac7489674a13d7146349884459a1594e28e8337aa9b3603b7f90c3df67b2a67c716a1c01d20a122a2b3aec9a613294703cbe8339", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c8e47f2bf1e42e9d3eebc2efe9d6df098c45c72422d191bb1334e658ac2942b66209d25ef56aa60df6a7109eeeac939bcd8e15e92508e688f908f55f6e9e9f09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1fbeece47136d6f74a7171f4bd3094dbaf7982c4f3af1ab5e4d06a3c50827609658211b602224c5264a1120b0c6e113e13fbd82ada07a8687be5fd59e5d70963", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3d9992a5a140c6d8b0f77b63cc5be71096ff7182a06fc7c8e68b968b863f434e697ac86f2e2de70661f94e746e00e88175c4f3e40cb2d6b8a6148603e4abb993"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-04 19:47:39.731398) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 19:47:39.731715) ========= 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.Yrjj5qEzyC/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.kH12Ds43ib/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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.005137 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 19:47:39.780563) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-04 19:47:39.780882) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-04 19:47:39.781223) ========= ========= Total master_lag: 1.01 =========