builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0008 starttime: 1490432922.32 results: success (0) buildid: 20170325004004 builduid: b53c9a826feb49d09b2321e725d92683 revision: 36f165ceceba72d12ea746313195ae35cf817778 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-25 02:08:42.320988) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-25 02:08:42.321527) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-25 02:08:42.321828) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-25 02:08:42.347143) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 02:08:42.347489) ========= 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.HnAANJEr6z/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.CQBPHu2r8Z/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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-03-25 02:08:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.4M=0.001s 2017-03-25 02:08:42 (12.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.113124 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 02:08:42.501023) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 02:08:42.501409) ========= 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.HnAANJEr6z/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.CQBPHu2r8Z/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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.020999 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 02:08:42.562619) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-25 02:08:42.562995) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 36f165ceceba72d12ea746313195ae35cf817778 --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-aurora --rev 36f165ceceba72d12ea746313195ae35cf817778 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HnAANJEr6z/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.CQBPHu2r8Z/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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-03-25 02:08:42,645 truncating revision to first 12 chars 2017-03-25 02:08:42,645 Setting DEBUG logging. 2017-03-25 02:08:42,645 attempt 1/10 2017-03-25 02:08:42,646 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/36f165ceceba?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-25 02:08:43,438 unpacking tar archive at: mozilla-aurora-36f165ceceba/testing/mozharness/ program finished with exit code 0 elapsedTime=1.049564 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-25 02:08:43.636960) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-25 02:08:43.637295) ========= script_repo_revision: 36f165ceceba72d12ea746313195ae35cf817778 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-25 02:08:43.637729) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-25 02:08:43.638008) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-25 02:08:43.659237) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-25 02:08:43.659947) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HnAANJEr6z/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.CQBPHu2r8Z/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 02:08:43 INFO - MultiFileLogger online at 20170325 02:08:43 in /builds/slave/test 02:08:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:08:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:08:43 INFO - {'append_to_log': False, 02:08:43 INFO - 'base_work_dir': '/builds/slave/test', 02:08:43 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:08:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:08:43 INFO - 'branch': 'Mozilla-Aurora', 02:08:43 INFO - 'buildbot_json_path': 'buildprops.json', 02:08:43 INFO - 'config_files': ('talos/mac_config.py',), 02:08:43 INFO - 'default_actions': ('clobber', 02:08:43 INFO - 'read-buildbot-config', 02:08:43 INFO - 'download-and-extract', 02:08:43 INFO - 'populate-webroot', 02:08:43 INFO - 'create-virtualenv', 02:08:43 INFO - 'install', 02:08:43 INFO - 'run-tests'), 02:08:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:08:43 INFO - 'download_minidump_stackwalk': True, 02:08:43 INFO - 'download_symbols': 'ondemand', 02:08:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:08:43 INFO - 'tooltool.py': '/tools/tooltool.py', 02:08:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:08:43 INFO - '/tools/misc-python/virtualenv.py')}, 02:08:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:08:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:08:43 INFO - 'gecko_profile': False, 02:08:43 INFO - 'gecko_profile_interval': 0, 02:08:43 INFO - 'installer_path': 'installer.exe', 02:08:43 INFO - 'log_level': 'info', 02:08:43 INFO - 'log_name': 'talos', 02:08:43 INFO - 'log_to_console': True, 02:08:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:08:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:08:43 INFO - 'opt_config_files': (), 02:08:43 INFO - 'pip_index': False, 02:08:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:08:43 INFO - 'cmd': ('bash', 02:08:43 INFO - '-c', 02:08:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:08:43 INFO - 'enabled': True, 02:08:43 INFO - 'halt_on_failure': False, 02:08:43 INFO - 'name': 'check_screen_resolution'},), 02:08:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:08:43 INFO - 'cmd': ('bash', 02:08:43 INFO - '-c', 02:08:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:08:43 INFO - 'enabled': True, 02:08:43 INFO - 'halt_on_failure': False, 02:08:43 INFO - 'name': 'check_screen_resolution'},), 02:08:43 INFO - 'run_cmd_checks_enabled': True, 02:08:43 INFO - 'suite': 'dromaeojs-e10s', 02:08:43 INFO - 'system_bits': '32', 02:08:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:08:43 INFO - 'title': 't-yosemite-r7-0008', 02:08:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:08:43 INFO - 'use_talos_json': True, 02:08:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:08:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:08:43 INFO - 'work_dir': 'build'} 02:08:43 INFO - [mozharness: 2017-03-25 09:08:43.864982Z] Running clobber step. 02:08:43 INFO - Running pre-action listener: _resource_record_pre_action 02:08:43 INFO - Running main action method: clobber 02:08:43 INFO - rmtree: /builds/slave/test/build 02:08:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:08:45 INFO - Running post-action listener: _resource_record_post_action 02:08:45 INFO - [mozharness: 2017-03-25 09:08:45.717886Z] Finished clobber step (success) 02:08:45 INFO - [mozharness: 2017-03-25 09:08:45.718051Z] Running read-buildbot-config step. 02:08:45 INFO - Running pre-action listener: _resource_record_pre_action 02:08:45 INFO - Running main action method: read_buildbot_config 02:08:45 INFO - Using buildbot properties: 02:08:45 INFO - { 02:08:45 INFO - "project": "", 02:08:45 INFO - "product": "firefox", 02:08:45 INFO - "script_repo_revision": "production", 02:08:45 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:08:45 INFO - "repository": "", 02:08:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:08:45 INFO - "buildid": "20170325004004", 02:08:45 INFO - "stage_platform": "macosx64", 02:08:45 INFO - "basedir": "/builds/slave/test", 02:08:45 INFO - "nightly_build": "True", 02:08:45 INFO - "buildnumber": 7, 02:08:45 INFO - "slavename": "t-yosemite-r7-0008", 02:08:45 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 02:08:45 INFO - "platform": "yosemite_r7", 02:08:45 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:08:45 INFO - "branch": "mozilla-aurora", 02:08:45 INFO - "repo_path": "releases/mozilla-aurora", 02:08:45 INFO - "revision": "36f165ceceba72d12ea746313195ae35cf817778", 02:08:45 INFO - "pgo_build": "False", 02:08:45 INFO - "builduid": "b53c9a826feb49d09b2321e725d92683", 02:08:45 INFO - "slavebuilddir": "test" 02:08:45 INFO - } 02:08:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/HwTbpN5dSCGCWzDtgw8z4Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 02:08:45 INFO - Running post-action listener: _resource_record_post_action 02:08:45 INFO - [mozharness: 2017-03-25 09:08:45.721616Z] Finished read-buildbot-config step (success) 02:08:45 INFO - [mozharness: 2017-03-25 09:08:45.721751Z] Running download-and-extract step. 02:08:45 INFO - Running pre-action listener: _resource_record_pre_action 02:08:45 INFO - Running main action method: download_and_extract 02:08:45 INFO - mkdir: /builds/slave/test/build/tests 02:08:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:08:45 INFO - trying https://queue.taskcluster.net/v1/task/HwTbpN5dSCGCWzDtgw8z4Q/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 02:08:45 INFO - Downloading https://queue.taskcluster.net/v1/task/HwTbpN5dSCGCWzDtgw8z4Q/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:08:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HwTbpN5dSCGCWzDtgw8z4Q/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 02:08:46 INFO - Downloaded 1199 bytes. 02:08:46 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:08:46 INFO - Using the following test package requirements: 02:08:46 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:08:46 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:08:46 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 02:08:46 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:08:46 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 02:08:46 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:08:46 INFO - u'jsshell-mac.zip'], 02:08:46 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:08:46 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 02:08:46 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:08:46 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:08:46 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 02:08:46 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:08:46 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 02:08:46 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:08:46 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 02:08:46 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:08:46 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 02:08:46 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:08:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HwTbpN5dSCGCWzDtgw8z4Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:08:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HwTbpN5dSCGCWzDtgw8z4Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:08:46 INFO - Fetch https://queue.taskcluster.net/v1/task/HwTbpN5dSCGCWzDtgw8z4Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:08:50 INFO - Expected file size: 51787183 02:08:50 INFO - Obtained file size: 51787183 02:09:06 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:09:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HwTbpN5dSCGCWzDtgw8z4Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:09:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HwTbpN5dSCGCWzDtgw8z4Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:09:06 INFO - Fetch https://queue.taskcluster.net/v1/task/HwTbpN5dSCGCWzDtgw8z4Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:09:08 INFO - Expected file size: 51787183 02:09:08 INFO - Obtained file size: 51787183 02:09:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HwTbpN5dSCGCWzDtgw8z4Q/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 02:09:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HwTbpN5dSCGCWzDtgw8z4Q/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:09:23 INFO - Fetch https://queue.taskcluster.net/v1/task/HwTbpN5dSCGCWzDtgw8z4Q/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 02:09:24 INFO - Expected file size: 14404438 02:09:24 INFO - Obtained file size: 14404438 02:09:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:09:25 INFO - trying https://queue.taskcluster.net/v1/task/HwTbpN5dSCGCWzDtgw8z4Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:09:25 INFO - Downloading https://queue.taskcluster.net/v1/task/HwTbpN5dSCGCWzDtgw8z4Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 02:09:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HwTbpN5dSCGCWzDtgw8z4Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 02:09:27 INFO - Downloaded 74737273 bytes. 02:09:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HwTbpN5dSCGCWzDtgw8z4Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:09:27 INFO - mkdir: /builds/slave/test/properties 02:09:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:09:27 INFO - Writing to file /builds/slave/test/properties/build_url 02:09:27 INFO - Contents: 02:09:27 INFO - build_url:https://queue.taskcluster.net/v1/task/HwTbpN5dSCGCWzDtgw8z4Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:09:28 INFO - Running post-action listener: _resource_record_post_action 02:09:28 INFO - Running post-action listener: set_extra_try_arguments 02:09:28 INFO - [mozharness: 2017-03-25 09:09:28.109338Z] Finished download-and-extract step (success) 02:09:28 INFO - [mozharness: 2017-03-25 09:09:28.109446Z] Running populate-webroot step. 02:09:28 INFO - Running pre-action listener: _resource_record_pre_action 02:09:28 INFO - Running main action method: populate_webroot 02:09:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:09:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:09:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:09:28 INFO - '--tppagecycles', 02:09:28 INFO - '7'], 02:09:28 INFO - 'tests': ['tsvgm']}}, 02:09:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:09:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:09:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:09:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:09:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:09:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:09:28 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:09:28 INFO - 'talos_options': ['--disable-e10s'], 02:09:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:09:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:09:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:09:28 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:09:28 INFO - 'talos_options': ['--disable-e10s'], 02:09:28 INFO - 'tests': ['damp', 'tps']}, 02:09:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:09:28 INFO - 'tests': ['damp', 'tps']}, 02:09:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:09:28 INFO - 'tests': ['dromaeo_dom']}, 02:09:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:09:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:09:28 INFO - 'tests': ['basic_compositor_video']}, 02:09:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:09:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:09:28 INFO - 'tests': ['a11yr', 02:09:28 INFO - 'ts_paint', 02:09:28 INFO - 'tpaint', 02:09:28 INFO - 'sessionrestore', 02:09:28 INFO - 'sessionrestore_no_auto_restore', 02:09:28 INFO - 'tabpaint']}, 02:09:28 INFO - 'other-e10s': {'tests': ['a11yr', 02:09:28 INFO - 'ts_paint', 02:09:28 INFO - 'tpaint', 02:09:28 INFO - 'sessionrestore', 02:09:28 INFO - 'sessionrestore_no_auto_restore', 02:09:28 INFO - 'tabpaint']}, 02:09:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:09:28 INFO - 'ts_paint', 02:09:28 INFO - 'tpaint', 02:09:28 INFO - 'sessionrestore', 02:09:28 INFO - 'sessionrestore_no_auto_restore', 02:09:28 INFO - 'tabpaint']}, 02:09:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:09:28 INFO - 'ts_paint', 02:09:28 INFO - 'tpaint', 02:09:28 INFO - 'sessionrestore', 02:09:28 INFO - 'sessionrestore_no_auto_restore', 02:09:28 INFO - 'tabpaint']}, 02:09:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:09:28 INFO - 'tests': ['a11yr', 02:09:28 INFO - 'ts_paint', 02:09:28 INFO - 'tpaint', 02:09:28 INFO - 'sessionrestore', 02:09:28 INFO - 'sessionrestore_no_auto_restore', 02:09:28 INFO - 'tabpaint']}, 02:09:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:09:28 INFO - 'tests': ['a11yr', 02:09:28 INFO - 'ts_paint', 02:09:28 INFO - 'tpaint', 02:09:28 INFO - 'sessionrestore', 02:09:28 INFO - 'sessionrestore_no_auto_restore', 02:09:28 INFO - 'tabpaint']}, 02:09:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:09:28 INFO - 'tests': ['tsvgx', 02:09:28 INFO - 'tsvgr_opacity', 02:09:28 INFO - 'tart', 02:09:28 INFO - 'tscrollx', 02:09:28 INFO - 'cart', 02:09:28 INFO - 'tsvg_static']}, 02:09:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:09:28 INFO - 'tsvgr_opacity', 02:09:28 INFO - 'tart', 02:09:28 INFO - 'tscrollx', 02:09:28 INFO - 'cart', 02:09:28 INFO - 'tsvg_static']}, 02:09:28 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:09:28 INFO - 'talos_options': ['--disable-e10s'], 02:09:28 INFO - 'tests': ['tp5o']}, 02:09:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:09:28 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:09:28 INFO - 'talos_options': ['--disable-e10s', 02:09:28 INFO - '--xperf_path', 02:09:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:09:28 INFO - 'tests': ['tp5n']}, 02:09:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:09:28 INFO - 'talos_options': ['--xperf_path', 02:09:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:09:28 INFO - 'tests': ['tp5n']}}, 02:09:28 INFO - 'talos.zip': {'path': '', 02:09:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:09:28 INFO - Running post-action listener: _resource_record_post_action 02:09:28 INFO - [mozharness: 2017-03-25 09:09:28.127625Z] Finished populate-webroot step (success) 02:09:28 INFO - [mozharness: 2017-03-25 09:09:28.127759Z] Running create-virtualenv step. 02:09:28 INFO - Running pre-action listener: _resource_record_pre_action 02:09:28 INFO - Running main action method: create_virtualenv 02:09:28 INFO - Creating virtualenv /builds/slave/test/build/venv 02:09:28 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 02:09:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:09:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:09:28 INFO - Using real prefix '/tools/python27' 02:09:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:09:28 INFO - Installing distribute.............................................................................................................................................................................................done. 02:09:32 INFO - Installing pip.................done. 02:09:32 INFO - Return code: 0 02:09:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:09:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:09:32 INFO - Reading from file tmpfile_stdout 02:09:32 INFO - Output received: 02:09:32 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) 02:09:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:09:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x109750b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109753768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcae2582ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10952ed30>, '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 0x109455db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109523be8>, '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.HnAANJEr6z/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.CQBPHu2r8Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 02:09:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 02:09:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:09:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HnAANJEr6z/Render', 02:09:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:32 INFO - 'HOME': '/Users/cltbld', 02:09:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:32 INFO - 'LOGNAME': 'cltbld', 02:09:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:32 INFO - 'MOZ_NO_REMOTE': '1', 02:09:32 INFO - 'NO_EM_RESTART': '1', 02:09:32 INFO - 'PAGER': '/bin/cat', 02:09:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:32 INFO - 'PWD': '/builds/slave/test', 02:09:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:32 INFO - 'SHELL': '/bin/bash', 02:09:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CQBPHu2r8Z/Listeners', 02:09:32 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 02:09:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:32 INFO - 'USER': 'cltbld', 02:09:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:32 INFO - 'XPC_FLAGS': '0x0', 02:09:32 INFO - 'XPC_SERVICE_NAME': '0', 02:09:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:32 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 02:09:32 INFO - Cleaning up... 02:09:32 INFO - Return code: 0 02:09:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:09:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x109750b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109753768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcae2582ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10952ed30>, '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 0x109455db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109523be8>, '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.HnAANJEr6z/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.CQBPHu2r8Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 02:09:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 02:09:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:09:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HnAANJEr6z/Render', 02:09:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:32 INFO - 'HOME': '/Users/cltbld', 02:09:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:32 INFO - 'LOGNAME': 'cltbld', 02:09:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:32 INFO - 'MOZ_NO_REMOTE': '1', 02:09:32 INFO - 'NO_EM_RESTART': '1', 02:09:32 INFO - 'PAGER': '/bin/cat', 02:09:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:32 INFO - 'PWD': '/builds/slave/test', 02:09:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:32 INFO - 'SHELL': '/bin/bash', 02:09:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CQBPHu2r8Z/Listeners', 02:09:32 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 02:09:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:32 INFO - 'USER': 'cltbld', 02:09:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:32 INFO - 'XPC_FLAGS': '0x0', 02:09:32 INFO - 'XPC_SERVICE_NAME': '0', 02:09:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:32 INFO - Downloading/unpacking psutil>=3.1.1 02:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:09:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:09:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:09:36 INFO - Installing collected packages: psutil 02:09:36 INFO - Running setup.py install for psutil 02:09:36 INFO - building 'psutil._psutil_osx' extension 02:09:36 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 02:09:37 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 02:09:37 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 02:09:37 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 02:09:37 INFO - building 'psutil._psutil_posix' extension 02:09:37 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 02:09:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:09:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:09:37 INFO - ^ 02:09:37 INFO - 1 warning generated. 02:09:37 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 02:09:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:09:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:09:37 INFO - Successfully installed psutil 02:09:37 INFO - Cleaning up... 02:09:37 INFO - Return code: 0 02:09:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:09:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:09:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:37 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x109750b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109753768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcae2582ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10952ed30>, '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 0x109455db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109523be8>, '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.HnAANJEr6z/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.CQBPHu2r8Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 02:09:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 02:09:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:09:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HnAANJEr6z/Render', 02:09:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:37 INFO - 'HOME': '/Users/cltbld', 02:09:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:37 INFO - 'LOGNAME': 'cltbld', 02:09:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:37 INFO - 'MOZ_NO_REMOTE': '1', 02:09:37 INFO - 'NO_EM_RESTART': '1', 02:09:37 INFO - 'PAGER': '/bin/cat', 02:09:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:37 INFO - 'PWD': '/builds/slave/test', 02:09:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:37 INFO - 'SHELL': '/bin/bash', 02:09:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CQBPHu2r8Z/Listeners', 02:09:37 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 02:09:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:37 INFO - 'USER': 'cltbld', 02:09:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:37 INFO - 'XPC_FLAGS': '0x0', 02:09:37 INFO - 'XPC_SERVICE_NAME': '0', 02:09:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:09:38 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 02:09:38 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 02:09:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:38 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 02:09:38 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 02:09:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:09:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:09:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:09:40 INFO - Installing collected packages: mozsystemmonitor 02:09:40 INFO - Running setup.py install for mozsystemmonitor 02:09:40 INFO - Successfully installed mozsystemmonitor 02:09:40 INFO - Cleaning up... 02:09:40 INFO - Return code: 0 02:09:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:09:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:09:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:40 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x109750b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109753768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcae2582ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10952ed30>, '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 0x109455db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109523be8>, '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.HnAANJEr6z/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.CQBPHu2r8Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 02:09:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 02:09:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:09:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HnAANJEr6z/Render', 02:09:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:40 INFO - 'HOME': '/Users/cltbld', 02:09:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:40 INFO - 'LOGNAME': 'cltbld', 02:09:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:40 INFO - 'MOZ_NO_REMOTE': '1', 02:09:40 INFO - 'NO_EM_RESTART': '1', 02:09:40 INFO - 'PAGER': '/bin/cat', 02:09:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:40 INFO - 'PWD': '/builds/slave/test', 02:09:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:40 INFO - 'SHELL': '/bin/bash', 02:09:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CQBPHu2r8Z/Listeners', 02:09:40 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 02:09:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:40 INFO - 'USER': 'cltbld', 02:09:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:40 INFO - 'XPC_FLAGS': '0x0', 02:09:40 INFO - 'XPC_SERVICE_NAME': '0', 02:09:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:41 INFO - Downloading/unpacking jsonschema==2.5.1 02:09:41 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 02:09:41 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 02:09:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:41 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 02:09:41 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 02:09:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:09:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:09:44 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 02:09:44 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 02:09:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:44 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 02:09:44 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 02:09:44 INFO - Downloading functools32-3.2.3-2.tar.gz 02:09:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:09:44 INFO - warning: no files found matching '*.txt' 02:09:44 INFO - no previously-included directories found matching 'build' 02:09:44 INFO - no previously-included directories found matching 'dist' 02:09:44 INFO - no previously-included directories found matching '.git*' 02:09:44 INFO - Installing collected packages: jsonschema, functools32 02:09:44 INFO - Running setup.py install for functools32 02:09:44 INFO - warning: no files found matching '*.txt' 02:09:44 INFO - no previously-included directories found matching 'build' 02:09:44 INFO - no previously-included directories found matching 'dist' 02:09:44 INFO - no previously-included directories found matching '.git*' 02:09:44 INFO - Successfully installed jsonschema functools32 02:09:44 INFO - Cleaning up... 02:09:44 INFO - Return code: 0 02:09:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:09:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:09:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x109750b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109753768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcae2582ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10952ed30>, '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 0x109455db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109523be8>, '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.HnAANJEr6z/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.CQBPHu2r8Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 02:09:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 02:09:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:09:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HnAANJEr6z/Render', 02:09:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:44 INFO - 'HOME': '/Users/cltbld', 02:09:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:44 INFO - 'LOGNAME': 'cltbld', 02:09:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:44 INFO - 'MOZ_NO_REMOTE': '1', 02:09:44 INFO - 'NO_EM_RESTART': '1', 02:09:44 INFO - 'PAGER': '/bin/cat', 02:09:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:44 INFO - 'PWD': '/builds/slave/test', 02:09:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:44 INFO - 'SHELL': '/bin/bash', 02:09:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CQBPHu2r8Z/Listeners', 02:09:44 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 02:09:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:44 INFO - 'USER': 'cltbld', 02:09:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:44 INFO - 'XPC_FLAGS': '0x0', 02:09:44 INFO - 'XPC_SERVICE_NAME': '0', 02:09:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:09:45 INFO - Cleaning up... 02:09:45 INFO - Return code: 0 02:09:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:09:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:45 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x109750b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109753768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcae2582ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10952ed30>, '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 0x109455db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109523be8>, '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.HnAANJEr6z/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.CQBPHu2r8Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 02:09:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 02:09:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:09:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HnAANJEr6z/Render', 02:09:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:45 INFO - 'HOME': '/Users/cltbld', 02:09:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:45 INFO - 'LOGNAME': 'cltbld', 02:09:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:45 INFO - 'MOZ_NO_REMOTE': '1', 02:09:45 INFO - 'NO_EM_RESTART': '1', 02:09:45 INFO - 'PAGER': '/bin/cat', 02:09:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:45 INFO - 'PWD': '/builds/slave/test', 02:09:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:45 INFO - 'SHELL': '/bin/bash', 02:09:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CQBPHu2r8Z/Listeners', 02:09:45 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 02:09:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:45 INFO - 'USER': 'cltbld', 02:09:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:45 INFO - 'XPC_FLAGS': '0x0', 02:09:45 INFO - 'XPC_SERVICE_NAME': '0', 02:09:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:45 INFO - Downloading/unpacking blobuploader==1.2.4 02:09:45 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 02:09:45 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 02:09:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:45 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 02:09:45 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 02:09:47 INFO - Downloading blobuploader-1.2.4.tar.gz 02:09:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:09:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:09:48 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 02:09:48 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 02:09:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:48 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 02:09:48 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 02:09:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:09:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:09:48 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 02:09:48 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 02:09:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:48 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 02:09:48 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 02:09:49 INFO - Downloading docopt-0.6.1.tar.gz 02:09:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:09:49 INFO - Installing collected packages: blobuploader, requests, docopt 02:09:49 INFO - Running setup.py install for blobuploader 02:09:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:09:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:09:49 INFO - Running setup.py install for requests 02:09:49 INFO - Running setup.py install for docopt 02:09:50 INFO - Successfully installed blobuploader requests docopt 02:09:50 INFO - Cleaning up... 02:09:50 INFO - Return code: 0 02:09:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:09:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:50 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x109750b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109753768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcae2582ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10952ed30>, '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 0x109455db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109523be8>, '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.HnAANJEr6z/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.CQBPHu2r8Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 02:09:50 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 02:09:50 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:09:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HnAANJEr6z/Render', 02:09:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:50 INFO - 'HOME': '/Users/cltbld', 02:09:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:50 INFO - 'LOGNAME': 'cltbld', 02:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:50 INFO - 'MOZ_NO_REMOTE': '1', 02:09:50 INFO - 'NO_EM_RESTART': '1', 02:09:50 INFO - 'PAGER': '/bin/cat', 02:09:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:50 INFO - 'PWD': '/builds/slave/test', 02:09:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:50 INFO - 'SHELL': '/bin/bash', 02:09:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CQBPHu2r8Z/Listeners', 02:09:50 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 02:09:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:50 INFO - 'USER': 'cltbld', 02:09:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:50 INFO - 'XPC_FLAGS': '0x0', 02:09:50 INFO - 'XPC_SERVICE_NAME': '0', 02:09:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:09:50 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-_iX6cs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:09:50 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-73Z5ba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:09:50 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-zF0pVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:09:50 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-If82EQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:09:50 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-fDeQb2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:09:51 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-nKoS6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:09:51 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-0fE9BM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:09:51 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-UENk6J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:09:51 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-fuVN7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:09:51 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-W2l_hg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:09:51 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-M2w1Gj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:09:51 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-OBVT0l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:09:51 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-tGDfmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:09:51 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-DRIRfP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:09:51 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-MpZ6wA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:09:52 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-YZxAAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:09:52 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-skuMCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:09:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:09:52 INFO - Running setup.py install for manifestparser 02:09:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:09:52 INFO - Running setup.py install for mozcrash 02:09:52 INFO - Running setup.py install for mozdebug 02:09:52 INFO - Running setup.py install for mozdevice 02:09:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:09:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:09:52 INFO - Running setup.py install for mozfile 02:09:53 INFO - Running setup.py install for mozhttpd 02:09:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:09:53 INFO - Running setup.py install for mozinfo 02:09:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:09:53 INFO - Running setup.py install for mozInstall 02:09:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:09:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:09:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:09:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:09:53 INFO - Running setup.py install for mozleak 02:09:53 INFO - Running setup.py install for mozlog 02:09:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:09:53 INFO - Running setup.py install for moznetwork 02:09:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:09:53 INFO - Running setup.py install for mozprocess 02:09:54 INFO - Running setup.py install for mozprofile 02:09:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:09:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:09:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:09:54 INFO - Running setup.py install for mozrunner 02:09:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:09:54 INFO - Running setup.py install for mozscreenshot 02:09:54 INFO - Running setup.py install for moztest 02:09:54 INFO - Running setup.py install for mozversion 02:09:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:09:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:09:54 INFO - Cleaning up... 02:09:54 INFO - Return code: 0 02:09:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:09:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:09:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:54 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x109750b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109753768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcae2582ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10952ed30>, '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 0x109455db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109523be8>, '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.HnAANJEr6z/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.CQBPHu2r8Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 02:09:54 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 02:09:54 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:09:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HnAANJEr6z/Render', 02:09:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:54 INFO - 'HOME': '/Users/cltbld', 02:09:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:54 INFO - 'LOGNAME': 'cltbld', 02:09:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:54 INFO - 'MOZ_NO_REMOTE': '1', 02:09:54 INFO - 'NO_EM_RESTART': '1', 02:09:54 INFO - 'PAGER': '/bin/cat', 02:09:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:54 INFO - 'PWD': '/builds/slave/test', 02:09:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:54 INFO - 'SHELL': '/bin/bash', 02:09:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CQBPHu2r8Z/Listeners', 02:09:54 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 02:09:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:54 INFO - 'USER': 'cltbld', 02:09:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:54 INFO - 'XPC_FLAGS': '0x0', 02:09:54 INFO - 'XPC_SERVICE_NAME': '0', 02:09:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:09:55 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-MMvst3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:09:55 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)) 02:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:09:55 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-6Xsske-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:09:55 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)) 02:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:09:55 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-ypdULP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:09:55 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)) 02:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:09:55 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-ZJEXHD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:09:55 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)) 02:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:09:55 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-fRhJC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:09:55 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)) 02:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:09:55 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-0zYTCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:09:55 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)) 02:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:09:55 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-0ARO_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:09:55 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)) 02:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:09:55 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-Bn6aQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:09:55 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)) 02:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:09:55 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-viQuJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:09:56 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)) 02:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:09:56 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-WGdWPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:09:56 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)) 02:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:09:56 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-aerQvX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:09:56 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)) 02:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:09:56 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-s24FBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:09:56 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)) 02:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:09:56 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-EkBk8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:09:56 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)) 02:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:09:56 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-gqs6n8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:09:56 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)) 02:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:09:56 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-jO2WnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:09:56 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)) 02:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:09:56 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-9VbWTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:09:56 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)) 02:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:09:56 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-I5uz4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:09:56 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)) 02:09:56 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)) 02:09:56 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)) 02:09:56 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)) 02:09:56 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)) 02:09:56 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)) 02:09:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:59 INFO - Downloading blessings-1.6.tar.gz 02:09:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:09:59 INFO - Installing collected packages: blessings 02:09:59 INFO - Running setup.py install for blessings 02:09:59 INFO - Successfully installed blessings 02:09:59 INFO - Cleaning up... 02:10:00 INFO - Return code: 0 02:10:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:10:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:10:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:10:00 INFO - Reading from file tmpfile_stdout 02:10:00 INFO - Current package versions: 02:10:00 INFO - blessings == 1.6 02:10:00 INFO - blobuploader == 1.2.4 02:10:00 INFO - docopt == 0.6.1 02:10:00 INFO - functools32 == 3.2.3-2 02:10:00 INFO - jsonschema == 2.5.1 02:10:00 INFO - manifestparser == 1.1 02:10:00 INFO - mozInstall == 1.12 02:10:00 INFO - mozcrash == 1.0 02:10:00 INFO - mozdebug == 0.1 02:10:00 INFO - mozdevice == 0.49 02:10:00 INFO - mozfile == 1.2 02:10:00 INFO - mozhttpd == 0.7 02:10:00 INFO - mozinfo == 0.9 02:10:00 INFO - mozleak == 0.1 02:10:00 INFO - mozlog == 3.4 02:10:00 INFO - moznetwork == 0.27 02:10:00 INFO - mozprocess == 0.25 02:10:00 INFO - mozprofile == 0.28 02:10:00 INFO - mozrunner == 6.13 02:10:00 INFO - mozscreenshot == 0.1 02:10:00 INFO - mozsystemmonitor == 0.3 02:10:00 INFO - moztest == 0.8 02:10:00 INFO - mozversion == 1.4 02:10:00 INFO - psutil == 3.1.1 02:10:00 INFO - requests == 1.2.3 02:10:00 INFO - wsgiref == 0.1.2 02:10:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:10:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:10:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:10:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:10:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:10:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:10:00 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x109750b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109753768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcae2582ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10952ed30>, '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 0x109455db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109523be8>, '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.HnAANJEr6z/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.CQBPHu2r8Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 02:10:00 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 02:10:00 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:10:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HnAANJEr6z/Render', 02:10:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:10:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:10:00 INFO - 'HOME': '/Users/cltbld', 02:10:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:10:00 INFO - 'LOGNAME': 'cltbld', 02:10:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:10:00 INFO - 'MOZ_NO_REMOTE': '1', 02:10:00 INFO - 'NO_EM_RESTART': '1', 02:10:00 INFO - 'PAGER': '/bin/cat', 02:10:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:10:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:10:00 INFO - 'PWD': '/builds/slave/test', 02:10:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:10:00 INFO - 'SHELL': '/bin/bash', 02:10:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CQBPHu2r8Z/Listeners', 02:10:00 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 02:10:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:10:00 INFO - 'USER': 'cltbld', 02:10:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:10:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:10:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:10:00 INFO - 'XPC_FLAGS': '0x0', 02:10:00 INFO - 'XPC_SERVICE_NAME': '0', 02:10:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:10:00 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)) 02:10:00 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)) 02:10:00 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)) 02:10:00 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)) 02:10:00 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)) 02:10:00 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)) 02:10:00 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)) 02:10:00 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)) 02:10:00 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)) 02:10:00 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)) 02:10:00 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)) 02:10:00 INFO - Cleaning up... 02:10:00 INFO - Return code: 0 02:10:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:10:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:10:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:10:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:10:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:10:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:10:00 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x109750b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109753768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcae2582ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10952ed30>, '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 0x109455db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109523be8>, '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.HnAANJEr6z/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.CQBPHu2r8Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 02:10:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:10:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:10:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HnAANJEr6z/Render', 02:10:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:10:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:10:00 INFO - 'HOME': '/Users/cltbld', 02:10:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:10:00 INFO - 'LOGNAME': 'cltbld', 02:10:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:10:00 INFO - 'MOZ_NO_REMOTE': '1', 02:10:00 INFO - 'NO_EM_RESTART': '1', 02:10:00 INFO - 'PAGER': '/bin/cat', 02:10:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:10:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:10:00 INFO - 'PWD': '/builds/slave/test', 02:10:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:10:00 INFO - 'SHELL': '/bin/bash', 02:10:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CQBPHu2r8Z/Listeners', 02:10:00 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 02:10:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:10:00 INFO - 'USER': 'cltbld', 02:10:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:10:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:10:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:10:00 INFO - 'XPC_FLAGS': '0x0', 02:10:00 INFO - 'XPC_SERVICE_NAME': '0', 02:10:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:10:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:10:00 INFO - Cleaning up... 02:10:00 INFO - Return code: 0 02:10:00 INFO - Running post-action listener: _resource_record_post_action 02:10:00 INFO - Running post-action listener: _start_resource_monitoring 02:10:00 INFO - Starting resource monitoring. 02:10:00 INFO - [mozharness: 2017-03-25 09:10:00.851144Z] Finished create-virtualenv step (success) 02:10:00 INFO - [mozharness: 2017-03-25 09:10:00.852193Z] Running install step. 02:10:00 INFO - Running pre-action listener: _resource_record_pre_action 02:10:00 INFO - Running main action method: install 02:10:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:10:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:10:01 INFO - Reading from file tmpfile_stdout 02:10:01 INFO - Detecting whether we're running mozinstall >=1.0... 02:10:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:10:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:10:01 INFO - Reading from file tmpfile_stdout 02:10:01 INFO - Output received: 02:10:01 INFO - Usage: mozinstall [options] installer 02:10:01 INFO - Options: 02:10:01 INFO - -h, --help show this help message and exit 02:10:01 INFO - -d DEST, --destination=DEST 02:10:01 INFO - Directory to install application into. [default: 02:10:01 INFO - "/builds/slave/test"] 02:10:01 INFO - --app=APP Application being installed. [default: firefox] 02:10:01 INFO - mkdir: /builds/slave/test/build/application 02:10:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:10:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:10:21 INFO - Reading from file tmpfile_stdout 02:10:21 INFO - Output received: 02:10:21 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:10:21 INFO - Running post-action listener: _resource_record_post_action 02:10:21 INFO - [mozharness: 2017-03-25 09:10:21.563675Z] Finished install step (success) 02:10:21 INFO - [mozharness: 2017-03-25 09:10:21.563852Z] Running run-tests step. 02:10:21 INFO - Running pre-action listener: _resource_record_pre_action 02:10:21 INFO - Running main action method: run_tests 02:10:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:10:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:10:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:10:21 INFO - 2017-03-25 02:10:21.578 screenresolution[1925:18744] starting screenresolution argv=screenresolution get 02:10:21 INFO - 2017-03-25 02:10:21.581 screenresolution[1925:18744] Display 0: 1600x1200x32@60 02:10:21 INFO - 2017-03-25 02:10:21.586 screenresolution[1926:18747] starting screenresolution argv=screenresolution list 02:10:21 INFO - Available Modes on Display 0 02:10:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:10:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:10:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:10:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:10:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:10:21 INFO - Intel Iris: 02:10:21 INFO - Chipset Model: Intel Iris 02:10:21 INFO - Type: GPU 02:10:21 INFO - Bus: Built-In 02:10:21 INFO - VRAM (Dynamic, Max): 1536 MB 02:10:21 INFO - Vendor: Intel (0x8086) 02:10:21 INFO - Device ID: 0x0a2e 02:10:21 INFO - Revision ID: 0x0009 02:10:21 INFO - Displays: 02:10:21 INFO - Display: 02:10:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:10:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:10:21 INFO - Display Serial Number: 1600x1200 60 02:10:21 INFO - Main Display: Yes 02:10:21 INFO - Mirror: Off 02:10:21 INFO - Online: Yes 02:10:21 INFO - Rotation: Supported 02:10:21 INFO - Return code: 0 02:10:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:10:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:10:21 INFO - Python 2.7.3 02:10:21 INFO - Return code: 0 02:10:21 INFO - grabbing minidump binary from tooltool 02:10:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 02:10:21 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 0x10952ed30>, '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 0x109455db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109523be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:10:21 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 02:10:21 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 02:10:21 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 02:10:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:10:21 INFO - Return code: 0 02:10:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:10:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:10:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:10:21 INFO - ENV: RUST_BACKTRACE is now 1 02:10:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:10:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:10:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HwTbpN5dSCGCWzDtgw8z4Q/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0008', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:10:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HwTbpN5dSCGCWzDtgw8z4Q/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0008 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:10:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HnAANJEr6z/Render', 02:10:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:10:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:10:21 INFO - 'HOME': '/Users/cltbld', 02:10:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:10:21 INFO - 'LOGNAME': 'cltbld', 02:10:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:10:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:10:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:10:21 INFO - 'MOZ_NO_REMOTE': '1', 02:10:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:10:21 INFO - 'NO_EM_RESTART': '1', 02:10:21 INFO - 'PAGER': '/bin/cat', 02:10:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:10:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:10:21 INFO - 'PWD': '/builds/slave/test', 02:10:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:10:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:10:21 INFO - 'RUST_BACKTRACE': '1', 02:10:21 INFO - 'SHELL': '/bin/bash', 02:10:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CQBPHu2r8Z/Listeners', 02:10:21 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 02:10:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:10:21 INFO - 'USER': 'cltbld', 02:10:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:10:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:10:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:10:21 INFO - 'XPC_FLAGS': '0x0', 02:10:21 INFO - 'XPC_SERVICE_NAME': '0', 02:10:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HwTbpN5dSCGCWzDtgw8z4Q/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0008', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:10:22 INFO - mozversion application_buildid: 20170325004004 02:10:22 INFO - mozversion application_changeset: 36f165ceceba72d12ea746313195ae35cf817778 02:10:22 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:10:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:10:22 INFO - mozversion application_name: Firefox 02:10:22 INFO - mozversion application_remotingname: firefox-dev 02:10:22 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:10:22 INFO - mozversion application_vendor: Mozilla 02:10:22 INFO - mozversion application_version: 54.0a2 02:10:22 INFO - mozversion platform_buildid: 20170325004004 02:10:22 INFO - mozversion platform_changeset: 36f165ceceba72d12ea746313195ae35cf817778 02:10:22 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:10:22 INFO - mozversion platform_version: 54.0a2 02:10:22 INFO - using testdate: 1490433022 02:10:22 INFO - actual date: 1490433022 02:10:22 INFO - starting webserver on 'localhost:52910' 02:10:22 INFO - SUITE-START | Running 2 tests 02:10:22 INFO - TEST-START | dromaeo_css 02:10:22 INFO - operating with platform_type : mac_ 02:10:22 INFO - Initialising browser for dromaeo_css test... 02:10:22 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmpFW5bis/profile http://localhost:52910/getInfo.html) 02:10:25 INFO - PROCESS | 1936 | 2017-03-25 02:10:25.054 plugin-container[1939:18930] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x944b, name = 'com.apple.tsm.portname' 02:10:25 INFO - PROCESS | 1936 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:10:25 INFO - PROCESS | 1936 | 2017-03-25 02:10:25.055 plugin-container[1939:18930] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c23, name = 'com.apple.CFPasteboardClient' 02:10:25 INFO - PROCESS | 1936 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:10:25 INFO - PROCESS | 1936 | 2017-03-25 02:10:25.055 plugin-container[1939:18930] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:10:25 INFO - PROCESS | 1936 | __metrics Screen width/height:1600/1200 02:10:25 INFO - PROCESS | 1936 | colorDepth:24 02:10:25 INFO - PROCESS | 1936 | Browser inner width/height: 1024/689 02:10:25 INFO - PROCESS | 1936 | __metrics 02:10:25 ERROR - PROCESS | 1936 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 02:10:25 INFO - TEST-INFO | 1936: exit 0 02:10:25 INFO - Browser initialized. 02:10:25 INFO - Running cycle 1/1 for dromaeo_css test... 02:10:25 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmpFW5bis/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:10:28 INFO - PROCESS | 1940 | 2017-03-25 02:10:28.269 plugin-container[1941:19054] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 02:10:28 INFO - PROCESS | 1940 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:10:28 INFO - PROCESS | 1940 | 2017-03-25 02:10:28.269 plugin-container[1941:19054] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 02:10:28 INFO - PROCESS | 1940 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:10:28 INFO - PROCESS | 1940 | 2017-03-25 02:10:28.269 plugin-container[1941:19054] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:10:37 INFO - PROCESS | 1940 | RSS: Main: 220815360 02:10:37 INFO - PROCESS | 1940 | 02:13:41 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:52910/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52910/tests/dromaeo/cssquery-ext.html) 02:13:43 INFO - PROCESS | 1940 | RSS: Main: 238612480 02:13:43 INFO - PROCESS | 1940 | 02:15:26 INFO - PROCESS | 1940 | ************************* 02:15:26 INFO - PROCESS | 1940 | A coding exception was thrown and uncaught in a Task. 02:15:26 INFO - PROCESS | 1940 | 02:15:26 ERROR - PROCESS | 1940 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:15:26 INFO - PROCESS | 1940 | Full stack: 02:15:26 INFO - PROCESS | 1940 | ************************* 02:16:45 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:52910/tests/dromaeo/cssquery-ext.html (next: http://localhost:52910/tests/dromaeo/cssquery-jquery.html) 02:16:46 INFO - PROCESS | 1940 | RSS: Main: 249151488 02:16:46 INFO - PROCESS | 1940 | 02:19:48 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:52910/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52910/tests/dromaeo/cssquery-mootools.html) 02:19:49 INFO - PROCESS | 1940 | RSS: Main: 256897024 02:19:49 INFO - PROCESS | 1940 | 02:22:51 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:52910/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52910/tests/dromaeo/cssquery-prototype.html) 02:22:51 INFO - PROCESS | 1940 | RSS: Main: 253235200 02:22:51 INFO - PROCESS | 1940 | 02:25:54 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:52910/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52910/tests/dromaeo/cssquery-yui.html) 02:25:55 INFO - PROCESS | 1940 | RSS: Main: 258093056 02:25:55 INFO - PROCESS | 1940 | 02:28:57 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:52910/tests/dromaeo/cssquery-yui.html (next: http://localhost:52910/tests/dromaeo/cssquery-dojo.html) 02:28:58 INFO - PROCESS | 1940 | RSS: Main: 254296064 02:28:58 INFO - PROCESS | 1940 | 02:28:58 INFO - PROCESS | 1940 | __start_tp_report 02:28:58 INFO - PROCESS | 1940 | _x_x_mozilla_page_load 02:28:58 INFO - PROCESS | 1940 | _x_x_mozilla_page_load_details 02:28:58 INFO - PROCESS | 1940 | |i|pagename|runs| 02:28:58 INFO - PROCESS | 1940 | |0;dojo.html;3456.8444420001097;3460.7404444666654;3480.634533373994;3492.877749278776;3512.490688850117;6001.909971350433;6007;6037.245344331958;6089.238845144357;6124.081387791834;6046.667433291167;6057;6118;6126.203593532835;6138.815835524931;5401.837944861661;5444.047291723952;5489.478499542537;5551;5555.805546805863;5475.616706830523;5560.833175004756;5563.554915606742;5567.276254086963;5619.6066275360745;5294.92057619136;5343.919841202385;5498.395176530578;5523.6685798851995;5525.475079867406;5337.172738225542;5381.67709937405;5383.515483606494;5403.594730395197;5409.83770486886;5398.406175320712;5402.973435047328;5424.240606315119;5425.430329815392;5469.0702580561;5794.1308803679785;5871.148683440877;6055.061465472859;6062.272527296708;6077.6353418795015;6247.969085100953;6401.359864013608;6421.197350331208;6428.357164283581;6457.192850893638;6299.055141728777;6358.968205158991;6444.838879028015;6517.413432791024;6530.955047192427;6174.783882564088;6176.320604733476;6181.536384771161;6196.504279657616;6205.410486003822;5817.680027598522;6058.242719660043;6605.174353205849;6614.272430032693;6631.403173714389;6110.862540392742;6205.565610407228;6221.222347206599;6226.688665566703;6270.498360131179;6476.481881449473;6477.028445733084;6477.935220647733;6507.544471886922;6508.153939987771;5583.944160558342;6295.9296919523795;6401.967990160019;6409.455196308273;6447.258565265054;6137.131844484766;6236.594621349598;6292.622442653455;6296.181496405438;6347.96826015867;5133.820316288912;5160.948390516121;5165.535101840853;5177.8446646599905;5177.922331165036;3703.5555733312176;3908.4528166056775;4605.98668292975;4625.444946606429;4633.9536604634195;5755.251817263729;5814.854628634318;5851.882962340729;5853.209816674695;5923.022701254245;5481.424450432727;5591.52472039873;6529.738810447624;6541.34586541342;6541.8364540885905;5279.52484276417;6056.57603967718;6059.242594675666;6076.574639775174;6112.51099912006;5460.099083651233;5563.721813909288;5695.629784064023;5700.686462244616;5757.539396848242;6513.348665133449;6596.142501474777;6710.295418981132;6711.429528490133;6714.664266786739;6429.935700642934;6495.837604059936;6500.544961852814;6512.3162067982175;6520.543561950808;6314.463270622048;6652.334766523502;6719.798406047827;6740.66296685154;6749.088873002278;5226.581873450039;6262.2172228471445;6280.780172693934;6292.968535157295;6357.554971151975;4894.755262236803;6427.3251308611925;6469.514786390945;6478.740850365933;6519.543631945908;6179.223152332165;6343.492520598249;6343.5876668016435;6355.205599300088;6362.395572420613;6137.560853825989;6139.308154111861;6213.5339849510565;6225.8132256032395;6332.891743944699;6136.94800169746;6142.897836592492;6217.316095229611;6223.595466294857;6241.687915604292;6270.811875643738;6334.588251763621;6344.619322840645;6359.777407790705;6359.8092057238355;5473.0969390050195;5928.318243402064;6072.878542429038;6107.938920610737;6135.969320153371 02:28:58 INFO - PROCESS | 1940 | |1;ext.html;739.423249865105;771.1786946901551;806.9152738962413;825.5294482145176;956.3783540698545;32902.67097329026;38371;40780.184396312055;40834.59165408345;41038.794806026;38678.806605967;38844.61155388445;38861;38911.221775564474;38935.61064389355;7886.132525422199;8056.8388632227325;10465.372077675363;10725.570977160904;10910.345379277229;10645.733856653567;10745.78508429831;10749.62376316829;10752.462376881163;10777.461126943661;10728.73178170548;10759.085477734401;10760.40817755023;10792.73018174544;10810.72973175673;10748.355098694065;10755.516001779939;10758.354498730101;10779.245452818306;10782.191335649857;10379.688609341732;10402.167826573932;10436.008579184965;10450.947745261225;10461.68614941553;33649;33792.32415351679;33795.662043379496;33827.4925876112;33914.49128263078;3739.8504059837574;3799.449079883429;3801;3808.5620153682125;3822.827972741233;24628.891699873548;24990.750092499024;25178.874105629537;25479.108231212005;26224.344391390176;3087.8919237826567;3088.3823235352793;3095.1952492351925;3108.3317086826214;3206.7274281686086;259819.4018059814;278354;366914;372569;373026;42887.571124288355;42959;43009.13981720348;43231;43345.34981975273;45524;45554.54445455503;45574.31638525425;45627;45637.54362456333;13128.934355328161;13207.66980825487;13208.207507549578;13243.536476223479;13265.867341326657;9847.655332063487;11695.356755378536;11712.238704484182;11726.94136529312;11748.001419879405;9546.331756777097;9614;11626.825597616042;11692.88307116935;11742.884425979519;4364.279893817485;4811.759412029384;5024.949750502521;5598.188262701886;5756.194132821408;1107.8227483602584;1111.5942680921405;1123.9831402528969;1150.8906653867868;1183.7928362536522;22114.88942555309;26877;27269.318267043484;27582.44835103287;27598.862005689843;1237.55448038706;1255.0541167853562;1260.8550016748009;1264.993675031619;1295.313483853559;13575.728485430236;13927.651808704863;13955.930220349037;14290.857091428952;14303.356348964322;378237.1088144542;492312;494003;495741.52129239123;503919;57377;57590.42409575934;57598.7120064397;57765;58057;1320.7886738121854;1400.7478653842202;1459.5986103821533;1461.4738694070127;1462.6416527950507;26248.475030499663;26471.735282647696;26541.469170616867;27079.72920270772;27136.050238241565;46314;46483.767581161876;46545.53454465547;46620;46732.53267467282;409562;411545;412265;412907;413557.932210337;31653.733850645716;31744.841275793475;31938.88213912502;32029.558669860377;32113;43461;43937.901552461444;44346.778266108464;44937.77531112324;44971.32543011923;29883.402331953675;30119;30208.54687179738;30332.393352133276;30893.69106308908;24609.261722148363;25592.360190995376;25676.614850777627;28442.288942776595;31071.84464077665;29234.853825730734;31804.68195318017;32786.67213327836;36025.27949440944;36554.634453655126;27855;28321.291967700974;28367.43265134727;28611.856940715163;29234.561481578217;20898.58202835965;23341.41646458852;23347.649785253576;23563.41091472727;24267.635985459878 02:28:58 INFO - PROCESS | 1940 | |2;jquery.html;403.5077205808912;405.36155555000863;411.47947845974835;431.0968520948612;443.1181947923633;15914.363425462981;20312.085956131974;20458.56790024697;21095.050722717475;21210.833404162764;21600.459988500257;21668.566628667417;21668.8916555417;22227.44431389216;22484.775152248472;5054.84835454936;6077.78727744529;6861.897071543931;6903.240643529208;6914;5829.970850145764;6311.93688063118;6538.05198246254;6709.09427227323;6808.829779255509;6308.4322410983;6799.456043516507;6807.047013418125;6819.977003449472;6828.180618325783;6827.931720682779;7127.572345659277;7272.854542909112;7289.380402665806;7294.051773269494;7045.18980317265;7107;7165.99676045354;7215.278472152795;7223.422126229889;24809.379765505826;24877;25629.359266018313;25882.352941176432;26177.869110654327;2844.4026754381603;2855.328997685542;2948.0418863869327;2957.1276473440385;2986.7909246352765;15753.291101900442;15964.680706385923;16152.757708634379;16350.591235219095;16637.084960327178;2029.7767245602972;2059.5263089489354;2062.2885104638876;2067.576146889884;2070.33749200256;154993;164460;168404;170780;172472;16484.093374864497;19184.71222931657;20351.69472457914;20553;20719.58560828775;31002.68997309998;31014.534781978287;31463.685363146535;31483.370332593677;31641;10084.243681723754;10286.537105830255;10412;10430.478476076165;10557.049865511986;8518.531480768494;8681.175288347597;8689.826203476021;8809.295256379603;8844.611954772176;9474.194693450996;9546.520289355161;9556.808863822685;9591.712248632413;9602.08780165883;3457.377672019061;4000.179963107556;4112.239235741337;4121.361189015647;4137.710360274752;1009.137187704502;1017.1049476460668;1020.2399212586671;1027.9383237005804;1056.1656291529605;20445.48886277855;20651;20735.792642073688;20877;20994.685079723815;933.8552524358736;1008.8549496321581;1044.3316277582353;1046.5447530324334;1049.8110340138849;10509.474526273656;10855.620053298255;10862.076723478593;10867.78264434707;10929.617463388744;651142;689733;734515;741402;759921;113374;142104;142415.5758442423;148295.2585237067;155154;1201.8077107662907;1257.5724253753767;1284.3834959219628;1297.1827748518374;1307.2287350463082;15326.233688315317;19397.166302732563;20112.89379084106;20159;20197.49506262355;64938.67530662316;65535.01697474637;65590.67204663946;65642.67178664076;66937.66531167313;67549;91325.54337228271;95585;96208;96519.55220671836;7668.923310766821;9737.880143783272;9837.360571562825;11188.272762270426;11518.827217591575;12823.230606163661;12905.096643234883;12907.741845162856;12929.224246545238;13253.204807711569;11257.887421125684;11341.376224307742;11418.0865530759;11419.714507137389;11435.828462572901;10395.896041039492;10575.418351990715;10591.788164236827;10702.357858528514;10912.781744365227;9479.625454309224;9787.804243915225;11407.600733974272;11434.771304574027;11647.825282620937;11558;11606.593769218374;11650.592229272273;11876;11953.94023029914;11139.944300278446;11375.089992800722;11523.596674116367;11538.711532211762;11549.942250288696 02:28:58 INFO - PROCESS | 1940 | |3;mootools.html;1415.433826469412;1497.9925100374496;1552.5497605694356;1568.4667213147527;1569.5605230535455;10583;11146.164037697165;11205.495752691118;11237.494312755925;11433.942830285858;11028.669139925814;11215.719607009809;11245.83131253032;11256.54973801049;11301;1976.4781118538717;2273.454370950968;2317.2353123469215;2319.2926157521947;2325.790678838904;2239.193890199527;2294.0135741631093;2305.170138750049;2317.5249073939804;2318.7217533895955;2259.570681570496;2279.293419040103;2280.64301740465;2292.5644127615783;2329.580675478413;2225.0654725015534;2280.8859557022165;2282.2582660635276;2288.233441797;2294.128231272123;2270.6821045053707;2284.657301404786;2285.8857057147075;2287.3709729824263;2288.8397812153157;10931;10959.561617535368;11029.558817647285;11057.834132488018;11066.667999960015;4613.331066995267;5305.814296499604;5494.725263736797;5496.065668836308;5516.91724624131;3174.984125079383;3235.9838200808845;3361.915952101217;3393.4570468724887;3400.5579274694132;1092.2954694222262;1098.1379617000616;1131.5021390588115;1139.0944199361527;1147.219890474477;12843.743125137446;13698.315084245847;13947.232902190375;14888.404463821435;15440.613984650474;4019.954811748908;4193.287141186006;4301.268784306612;4386.605205531518;4490.57339552742;11751.412429378497;11778.469968851423;11801.173917825841;11825.408729563487;11841.703957401134;5441.156620723794;5441.238226648273;5441.83674489766;5452.372977107683;5459.808906688247;4898.458721803789;4974.502549744997;4979.825706100269;5016.398032236155;5050.671706339076;3314.2045908982154;3375.696187343151;3631.94552081719;3642.9817850911104;3734.477173195755;889.2441424789007;890.0476490155471;899.7525680437932;907.773056735816;909.0591238068567;778.5289899610767;822.7778499805019;889.786451251695;894.3784070071238;922.0871337376084;6223.875522489616;6287.779927702509;6334.1765570475545;6395.104685344056;6520.217573891068;1010.5604062232952;1032.9431881246453;1051.7948999944938;1052.2581579986092;1086.684861390188;3992.7803970781306;4005.679545636342;4094.2630326541507;4137.565555616617;4261.424707664426;13486.12340197884;13937.233452160228;13972.580822575339;13982.440702371994;14003.719925601634;12866.678333041562;13790.586282411543;13848.238346890816;13871.861281387259;13877.791833122512;8353.749387518263;9987.80024399508;10261.384316941007;10263.281570290157;10271.640492582725;5722.484976352148;6535.542512024303;6683.398494135551;6715.832104197434;6750.898736518858;12117.091218158497;12128.818067728815;12217.389130543259;12246.142770006014;12410.441530130985;12117.030637548798;12221.816672750094;12547.498100075894;12564.246145231315;12614.117011809085;7990.560519171268;9020.00847183132;9384.718458446258;9431.575579099093;9438.433693978383;10539.578416863545;10620.575176992834;10646.627368042082;10671.719393672809;10712.089472394933;8625.353098517511;8773.166549178073;8825.073367296598;8871.512066836385;8878.422902511316;8535.231829135408;8581.442206256574;8685.001224859223;8763.912360876564;8774.38579299443;7623.932649429195;7783.221677832172;7788.571628560484;7789.4157938155995;7833.373330133461;5570.749316280696;5621.494065534122;5653.265075540154;5764.991126552753;5849.064149736022;5670.659760414388;5680.829575112753;5746.396628353963;5762.2221000164445;5866.208061911588 02:28:58 INFO - PROCESS | 1940 | |4;prototype.html;364.3878284482069;375.69193261525544;378.47770077293336;385.0681351130265;387.25260247721883;4265.63742081923;5088.949110508894;5186.299849520314;5216.347956505437;5268.841934741959;4626.571050198648;4643.442786865572;4867.783054236441;5198.584113270938;5232.502912223342;3876.023228153648;4218.852340168095;4277.679174061957;4353.368761529577;4370.3007518797;3836.6163383661687;3875.8837234883;4344.370066340379;4349.565043495641;4358.063016451462;3641.799701016443;4076.3681629347498;4372.873395522217;4384.912301753979;4389.561043895616;3640.9974514017185;3741.625837416264;4187.91624167518;4385.758783266903;4406.413691071211;3381.7970921744527;3409.3863104641155;3471.2536804587135;3971.0667993021616;4014.598540145962;4560.384348113029;4605.608523275526;4637.930431043537;5131.384233891919;5219.73901304937;4483.977580112079;4578.35902973584;4923.4091908970795;5085.771140298695;5111.437741848394;4947.876303092416;5478.945580270571;5564.443555644403;5639.774409023634;5703.287089113861;3653.8903832885057;4255.829766809324;4547.15877562652;4551.270517203441;4563.703359281636;4385.846495372647;5271.86820329491;5985.461308482258;6003.609765365237;6036.094585812163;3966.464527288779;4349.3475978603465;4524.841630542981;4587.6788624795945;4623.352730617717;4192.392103145027;4309;4382.663287697219;4628.981624042773;4650.67445278834;4767.308740232646;4828.806847726122;4867.63492738039;5037.370328708911;5071.87320316995;5272.154745838926;5583.972080139573;5584.776608935597;5612.887742245132;5721.170430287585;1376.986230137706;1490.6496973211288;1542.4524293875736;1543.2515230113386;1555.4555905433008;1108.6175269531998;1154.7355645568089;1189.3280296632374;1194.2118201986648;1211.4427363412929;4859.854204373803;4865.416150061945;5215.817446389358;5220.243064755665;5251.789928402897;4995.400551933792;5233.816816411408;5495.1865884258095;5733;5774.740136693859;4280.443542339476;4488.438945131858;4825.5898248649255;5050.772715227824;5104.668196567211;2588.6893572771387;2735.302497863072;2882.524383476745;3024;3025.455418024733;5503.862403439946;5761.683107429048;6040.939590604038;6056.606320589148;6107.633541987495;4551.8634440966825;5292;5638.661680299195;5690.630109042899;5886.676232807151;5094.083065048327;5541.639793413416;5807.519082633901;6120;6123.938760612515;4914.877128071684;5789.118536475747;5797.739101740347;5839.182514448065;5862.48251049367;5210;5366.436524164907;5563.368341262454;5714.828555143353;5756.56825738063;5072.909324495251;5528.55771538284;5793.1172368979405;6007.789727359522;6047.304559975483;4276.422682937764;4295.591918767712;4346.457951440815;4564.338170965258;4567.629711086594;4845.903081938412;5303.522682958553;5350.324837581209;5364.210610482605;5782.653040817564;4861.322843618946;5613.859653508695;5626.634268772516;5641.417699760599;5648.63283886546;4627.5603817637275;5269.683818970874;5439.836804895859;5699.914501282401;5702.714864256687;5134.255532947776;5253.605979551472;5398.784048638167;5513.751881165277;5560.666360018412;4617.8383756568355;5251.737413129405;5478.91781623284;5676.489115979582;5698.487136157766;4773.689710168828;5038.420581633012;5345.33183352081;5408.323959505062;5528.9723551381985 02:28:58 INFO - PROCESS | 1940 | |5;yui.html;715.3061530315592;716.4483347822177;718.2350796401835;764.7208768799386;828.9212524810143;15129.621759456018;15592.298346574404;15635.530934071967;15637.92181039096;15710;14549.854501455009;14864.479743208989;15272.083674979509;15486.61283467914;15564.066156030645;946.347020555816;968.4092703450889;971.3928794503436;971.6696323250094;973.9074787895139;919.4161707315869;970.6699722094487;983.6557204978236;984.601236499219;985.4087547471531;977.0376179463223;1004.0712341084504;1025.6564051042872;1029.7322696098993;1029.7425643589104;1016.567958617592;1020.5203554329453;1023.52406131149;1025.0518270599703;1025.66665833604;757.6817736550662;761.4137114421919;761.7752762934947;762.6911101004102;763.6181909045478;16002.799790015795;16244.187790610422;16293.755593665986;16870.83129168705;17054.914725426417;13553;13670.11144275619;13827.930860345634;13909.165450073027;13936.651583710489;11785.587504437304;11855.229410088317;11875.0499960003;11945.223560468628;11995.16033877629;3564.3049605326974;3598.748087633867;3629.237860049393;3660.670539651444;3761.6614504694708;106422.80731578065;106472.99999999923;116481;117099;122264.38867805604;9246.815063698688;10272.897271027343;10474.528646210938;10732.785344293226;10868.349576609657;19251;21197.89401052985;21260.043298051623;21433.46416339604;21454.570908582053;7177.605231712306;7237.240089790602;7370.668319925616;7530.246975302535;7544.132424771112;4184.518780340239;4213.389058586549;4299.548547402478;4303.935440968388;4347.195768782785;3280.852361643732;3340.8329583521213;3361.2941282330985;3365.192353835111;3581.122624956902;673.6575145009679;679.864027194563;683.3132701634826;684.165318311659;691.1395312835552;617.2253821454046;661.1834384535096;661.6823924516259;680.2653134614604;703.3739971425435;7773.766786996399;9654.239580691554;10020.949895250476;10078.647247346455;10097.951024487757;680.669875110571;705.6718625838907;706.6431452116751;710.7867639708066;714.6319645382632;4819.469858315582;5000.824971125993;5004.724740139254;5027.757410704945;5041.79832806691;85567.57216214003;91127.54436227777;101611;109727.45136274268;112381.43809281065;66283;81156.59421702854;92550.61174082394;93735.53132234295;102693;62785.80249691239;80782;83863.16136838554;87053;88286.11713882907;10655.480125100186;10709.732256693645;10901.855305193158;10921.126309895348;10977.670669879917;52263.47736522586;52636;52790;53034.73482632562;53629.7318513405;66258.34354141186;68551.31448685649;68601;68695.65652171707;69822;11913.642590722293;11978.735737535788;12115.333656648969;12161.69595760113;12428.005759539396;13841.30793460343;13932.860671393157;14006.369713363127;14238.430462781373;14659;13455.327233638474;13710.588682339147;13929.442822287401;13996.580102596938;14093.942954278675;12728.554500592434;12958.676033099247;13301.733965320833;13954.302284885918;13977.930110349384;13016.349182540645;13502.392392342172;13642.522511712043;13912.304384780922;14132.586741325786;9224.03147669485;9298;9426.622935082794;9440.615323144486;9732.41605503672;8917.381615876473;9847.704568862946;10944.562217511211;11160.162987775788;11160.49777760019 02:28:58 INFO - PROCESS | 1940 | __end_tp_report 02:28:58 INFO - PROCESS | 1940 | __start_cc_report 02:28:58 INFO - PROCESS | 1940 | _x_x_mozilla_cycle_collect,420 02:28:58 INFO - PROCESS | 1940 | __end_cc_report 02:28:58 INFO - PROCESS | 1940 | __startTimestamp1490434138202__endTimestamp 02:28:58 INFO - PROCESS | 1940 | 02:28:58 INFO - PROCESS | 1940 | ------- Summary: start ------- 02:28:58 INFO - PROCESS | 1940 | Number of tests: 6 02:28:58 INFO - PROCESS | 1940 | 02:28:58 INFO - PROCESS | 1940 | [#0] dojo.html Cycles:165 Average:5922.27 Median:6136.95 stddev:681.22 (11.1%) stddev-sans-first:655.28 02:28:58 INFO - PROCESS | 1940 | Values: 3456.8 3460.7 3480.6 3492.9 3512.5 6001.9 6007.0 6037.2 6089.2 6124.1 6046.7 6057.0 6118.0 6126.2 6138.8 5401.8 5444.0 5489.5 5551.0 5555.8 5475.6 5560.8 5563.6 5567.3 5619.6 5294.9 5343.9 5498.4 5523.7 5525.5 5337.2 5381.7 5383.5 5403.6 5409.8 5398.4 5403.0 5424.2 5425.4 5469.1 5794.1 5871.1 6055.1 6062.3 6077.6 6248.0 6401.4 6421.2 6428.4 6457.2 6299.1 6359.0 6444.8 6517.4 6531.0 6174.8 6176.3 6181.5 6196.5 6205.4 5817.7 6058.2 6605.2 6614.3 6631.4 6110.9 6205.6 6221.2 6226.7 6270.5 6476.5 6477.0 6477.9 6507.5 6508.2 5583.9 6295.9 6402.0 6409.5 6447.3 6137.1 6236.6 6292.6 6296.2 6348.0 5133.8 5160.9 5165.5 5177.8 5177.9 3703.6 3908.5 4606.0 4625.4 4634.0 5755.3 5814.9 5851.9 5853.2 5923.0 5481.4 5591.5 6529.7 6541.3 6541.8 5279.5 6056.6 6059.2 6076.6 6112.5 5460.1 5563.7 5695.6 5700.7 5757.5 6513.3 6596.1 6710.3 6711.4 6714.7 6429.9 6495.8 6500.5 6512.3 6520.5 6314.5 6652.3 6719.8 6740.7 6749.1 5226.6 6262.2 6280.8 6293.0 6357.6 4894.8 6427.3 6469.5 6478.7 6519.5 6179.2 6343.5 6343.6 6355.2 6362.4 6137.6 6139.3 6213.5 6225.8 6332.9 6136.9 6142.9 6217.3 6223.6 6241.7 6270.8 6334.6 6344.6 6359.8 6359.8 5473.1 5928.3 6072.9 6107.9 6136.0 02:28:58 INFO - PROCESS | 1940 | 02:28:58 INFO - PROCESS | 1940 | [#1] ext.html Cycles:180 Average:53732.20 Median:16710.14 stddev:109224.58 (653.6%) stddev-sans-first:109458.51 02:28:58 INFO - PROCESS | 1940 | Values: 739.4 771.2 806.9 825.5 956.4 32902.7 38371.0 40780.2 40834.6 41038.8 38678.8 38844.6 38861.0 38911.2 38935.6 7886.1 8056.8 10465.4 10725.6 10910.3 10645.7 10745.8 10749.6 10752.5 10777.5 10728.7 10759.1 10760.4 10792.7 10810.7 10748.4 10755.5 10758.4 10779.2 10782.2 10379.7 10402.2 10436.0 10450.9 10461.7 33649.0 33792.3 33795.7 33827.5 33914.5 3739.9 3799.4 3801.0 3808.6 3822.8 24628.9 24990.8 25178.9 25479.1 26224.3 3087.9 3088.4 3095.2 3108.3 3206.7 259819.4 278354.0 366914.0 372569.0 373026.0 42887.6 42959.0 43009.1 43231.0 43345.3 45524.0 45554.5 45574.3 45627.0 45637.5 13128.9 13207.7 13208.2 13243.5 13265.9 9847.7 11695.4 11712.2 11726.9 11748.0 9546.3 9614.0 11626.8 11692.9 11742.9 4364.3 4811.8 5024.9 5598.2 5756.2 1107.8 1111.6 1124.0 1150.9 1183.8 22114.9 26877.0 27269.3 27582.4 27598.9 1237.6 1255.1 1260.9 1265.0 1295.3 13575.7 13927.7 13955.9 14290.9 14303.4 378237.1 492312.0 494003.0 495741.5 503919.0 57377.0 57590.4 57598.7 57765.0 58057.0 1320.8 1400.7 1459.6 1461.5 1462.6 26248.5 26471.7 26541.5 27079.7 27136.1 46314.0 46483.8 46545.5 46620.0 46732.5 409562.0 411545.0 412265.0 412907.0 413557.9 31653.7 31744.8 31938.9 32029.6 32113.0 43461.0 43937.9 44346.8 44937.8 44971.3 29883.4 30119.0 30208.5 30332.4 30893.7 24609.3 25592.4 25676.6 28442.3 31071.8 29234.9 31804.7 32786.7 36025.3 36554.6 27855.0 28321.3 28367.4 28611.9 29234.6 20898.6 23341.4 23347.6 23563.4 24267.6 02:28:58 INFO - PROCESS | 1940 | 02:28:58 INFO - PROCESS | 1940 | [#2] jquery.html Cycles:180 Average:42103.53 Median:20935.84 stddev:119933.29 (572.9%) stddev-sans-first:120228.86 02:28:58 INFO - PROCESS | 1940 | Values: 403.5 405.4 411.5 431.1 443.1 15914.4 20312.1 20458.6 21095.1 21210.8 21600.5 21668.6 21668.9 22227.4 22484.8 5054.8 6077.8 6861.9 6903.2 6914.0 5830.0 6311.9 6538.1 6709.1 6808.8 6308.4 6799.5 6807.0 6820.0 6828.2 6827.9 7127.6 7272.9 7289.4 7294.1 7045.2 7107.0 7166.0 7215.3 7223.4 24809.4 24877.0 25629.4 25882.4 26177.9 2844.4 2855.3 2948.0 2957.1 2986.8 15753.3 15964.7 16152.8 16350.6 16637.1 2029.8 2059.5 2062.3 2067.6 2070.3 154993.0 164460.0 168404.0 170780.0 172472.0 16484.1 19184.7 20351.7 20553.0 20719.6 31002.7 31014.5 31463.7 31483.4 31641.0 10084.2 10286.5 10412.0 10430.5 10557.0 8518.5 8681.2 8689.8 8809.3 8844.6 9474.2 9546.5 9556.8 9591.7 9602.1 3457.4 4000.2 4112.2 4121.4 4137.7 1009.1 1017.1 1020.2 1027.9 1056.2 20445.5 20651.0 20735.8 20877.0 20994.7 933.9 1008.9 1044.3 1046.5 1049.8 10509.5 10855.6 10862.1 10867.8 10929.6 651142.0 689733.0 734515.0 741402.0 759921.0 113374.0 142104.0 142415.6 148295.3 155154.0 1201.8 1257.6 1284.4 1297.2 1307.2 15326.2 19397.2 20112.9 20159.0 20197.5 64938.7 65535.0 65590.7 65642.7 66937.7 67549.0 91325.5 95585.0 96208.0 96519.6 7668.9 9737.9 9837.4 11188.3 11518.8 12823.2 12905.1 12907.7 12929.2 13253.2 11257.9 11341.4 11418.1 11419.7 11435.8 10395.9 10575.4 10591.8 10702.4 10912.8 9479.6 9787.8 11407.6 11434.8 11647.8 11558.0 11606.6 11650.6 11876.0 11953.9 11139.9 11375.1 11523.6 11538.7 11549.9 02:28:58 INFO - PROCESS | 1940 | 02:28:58 INFO - PROCESS | 1940 | [#3] mootools.html Cycles:180 Average:6618.43 Median:2322.54 stddev:4240.46 (182.6%) stddev-sans-first:4234.33 02:28:58 INFO - PROCESS | 1940 | Values: 1415.4 1498.0 1552.5 1568.5 1569.6 10583.0 11146.2 11205.5 11237.5 11433.9 11028.7 11215.7 11245.8 11256.5 11301.0 1976.5 2273.5 2317.2 2319.3 2325.8 2239.2 2294.0 2305.2 2317.5 2318.7 2259.6 2279.3 2280.6 2292.6 2329.6 2225.1 2280.9 2282.3 2288.2 2294.1 2270.7 2284.7 2285.9 2287.4 2288.8 10931.0 10959.6 11029.6 11057.8 11066.7 4613.3 5305.8 5494.7 5496.1 5516.9 3175.0 3236.0 3361.9 3393.5 3400.6 1092.3 1098.1 1131.5 1139.1 1147.2 12843.7 13698.3 13947.2 14888.4 15440.6 4020.0 4193.3 4301.3 4386.6 4490.6 11751.4 11778.5 11801.2 11825.4 11841.7 5441.2 5441.2 5441.8 5452.4 5459.8 4898.5 4974.5 4979.8 5016.4 5050.7 3314.2 3375.7 3631.9 3643.0 3734.5 889.2 890.0 899.8 907.8 909.1 778.5 822.8 889.8 894.4 922.1 6223.9 6287.8 6334.2 6395.1 6520.2 1010.6 1032.9 1051.8 1052.3 1086.7 3992.8 4005.7 4094.3 4137.6 4261.4 13486.1 13937.2 13972.6 13982.4 14003.7 12866.7 13790.6 13848.2 13871.9 13877.8 8353.7 9987.8 10261.4 10263.3 10271.6 5722.5 6535.5 6683.4 6715.8 6750.9 12117.1 12128.8 12217.4 12246.1 12410.4 12117.0 12221.8 12547.5 12564.2 12614.1 7990.6 9020.0 9384.7 9431.6 9438.4 10539.6 10620.6 10646.6 10671.7 10712.1 8625.4 8773.2 8825.1 8871.5 8878.4 8535.2 8581.4 8685.0 8763.9 8774.4 7623.9 7783.2 7788.6 7789.4 7833.4 5570.7 5621.5 5653.3 5765.0 5849.1 5670.7 5680.8 5746.4 5762.2 5866.2 02:28:58 INFO - PROCESS | 1940 | 02:28:58 INFO - PROCESS | 1940 | [#4] prototype.html Cycles:180 Average:4612.90 Median:4935.64 stddev:1308.57 (26.5%) stddev-sans-first:1272.79 02:28:58 INFO - PROCESS | 1940 | Values: 364.4 375.7 378.5 385.1 387.3 4265.6 5088.9 5186.3 5216.3 5268.8 4626.6 4643.4 4867.8 5198.6 5232.5 3876.0 4218.9 4277.7 4353.4 4370.3 3836.6 3875.9 4344.4 4349.6 4358.1 3641.8 4076.4 4372.9 4384.9 4389.6 3641.0 3741.6 4187.9 4385.8 4406.4 3381.8 3409.4 3471.3 3971.1 4014.6 4560.4 4605.6 4637.9 5131.4 5219.7 4484.0 4578.4 4923.4 5085.8 5111.4 4947.9 5478.9 5564.4 5639.8 5703.3 3653.9 4255.8 4547.2 4551.3 4563.7 4385.8 5271.9 5985.5 6003.6 6036.1 3966.5 4349.3 4524.8 4587.7 4623.4 4192.4 4309.0 4382.7 4629.0 4650.7 4767.3 4828.8 4867.6 5037.4 5071.9 5272.2 5584.0 5584.8 5612.9 5721.2 1377.0 1490.6 1542.5 1543.3 1555.5 1108.6 1154.7 1189.3 1194.2 1211.4 4859.9 4865.4 5215.8 5220.2 5251.8 4995.4 5233.8 5495.2 5733.0 5774.7 4280.4 4488.4 4825.6 5050.8 5104.7 2588.7 2735.3 2882.5 3024.0 3025.5 5503.9 5761.7 6040.9 6056.6 6107.6 4551.9 5292.0 5638.7 5690.6 5886.7 5094.1 5541.6 5807.5 6120.0 6123.9 4914.9 5789.1 5797.7 5839.2 5862.5 5210.0 5366.4 5563.4 5714.8 5756.6 5072.9 5528.6 5793.1 6007.8 6047.3 4276.4 4295.6 4346.5 4564.3 4567.6 4845.9 5303.5 5350.3 5364.2 5782.7 4861.3 5613.9 5626.6 5641.4 5648.6 4627.6 5269.7 5439.8 5699.9 5702.7 5134.3 5253.6 5398.8 5513.8 5560.7 4617.8 5251.7 5478.9 5676.5 5698.5 4773.7 5038.4 5345.3 5408.3 5529.0 02:28:58 INFO - PROCESS | 1940 | 02:28:58 INFO - PROCESS | 1940 | [#5] yui.html Cycles:180 Average:20708.43 Median:3363.24 stddev:30151.84 (896.5%) stddev-sans-first:30199.06 02:28:58 INFO - PROCESS | 1940 | Values: 715.3 716.4 718.2 764.7 828.9 15129.6 15592.3 15635.5 15637.9 15710.0 14549.9 14864.5 15272.1 15486.6 15564.1 946.3 968.4 971.4 971.7 973.9 919.4 970.7 983.7 984.6 985.4 977.0 1004.1 1025.7 1029.7 1029.7 1016.6 1020.5 1023.5 1025.1 1025.7 757.7 761.4 761.8 762.7 763.6 16002.8 16244.2 16293.8 16870.8 17054.9 13553.0 13670.1 13827.9 13909.2 13936.7 11785.6 11855.2 11875.0 11945.2 11995.2 3564.3 3598.7 3629.2 3660.7 3761.7 106422.8 106473.0 116481.0 117099.0 122264.4 9246.8 10272.9 10474.5 10732.8 10868.3 19251.0 21197.9 21260.0 21433.5 21454.6 7177.6 7237.2 7370.7 7530.2 7544.1 4184.5 4213.4 4299.5 4303.9 4347.2 3280.9 3340.8 3361.3 3365.2 3581.1 673.7 679.9 683.3 684.2 691.1 617.2 661.2 661.7 680.3 703.4 7773.8 9654.2 10020.9 10078.6 10098.0 680.7 705.7 706.6 710.8 714.6 4819.5 5000.8 5004.7 5027.8 5041.8 85567.6 91127.5 101611.0 109727.5 112381.4 66283.0 81156.6 92550.6 93735.5 102693.0 62785.8 80782.0 83863.2 87053.0 88286.1 10655.5 10709.7 10901.9 10921.1 10977.7 52263.5 52636.0 52790.0 53034.7 53629.7 66258.3 68551.3 68601.0 68695.7 69822.0 11913.6 11978.7 12115.3 12161.7 12428.0 13841.3 13932.9 14006.4 14238.4 14659.0 13455.3 13710.6 13929.4 13996.6 14093.9 12728.6 12958.7 13301.7 13954.3 13977.9 13016.3 13502.4 13642.5 13912.3 14132.6 9224.0 9298.0 9426.6 9440.6 9732.4 8917.4 9847.7 10944.6 11160.2 11160.5 02:28:58 INFO - PROCESS | 1940 | -------- Summary: end -------- 02:28:58 INFO - PROCESS | 1940 | 02:28:58 INFO - TEST-INFO | 1940: exit 0 02:28:58 INFO - TEST-OK | dromaeo_css | took 1116765ms 02:28:58 INFO - TEST-START | kraken 02:28:58 INFO - operating with platform_type : mac_ 02:28:58 INFO - Initialising browser for kraken test... 02:28:58 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmpwaAj9p/profile http://localhost:52910/getInfo.html) 02:29:01 INFO - PROCESS | 1945 | 2017-03-25 02:29:01.684 plugin-container[1947:21183] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.tsm.portname' 02:29:01 INFO - PROCESS | 1945 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:29:01 INFO - PROCESS | 1945 | 2017-03-25 02:29:01.684 plugin-container[1947:21183] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.CFPasteboardClient' 02:29:01 INFO - PROCESS | 1945 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:29:01 INFO - PROCESS | 1945 | 2017-03-25 02:29:01.685 plugin-container[1947:21183] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:29:01 INFO - PROCESS | 1945 | __metrics Screen width/height:1600/1200 02:29:01 INFO - PROCESS | 1945 | colorDepth:24 02:29:01 INFO - PROCESS | 1945 | Browser inner width/height: 1024/689 02:29:01 INFO - PROCESS | 1945 | __metrics 02:29:01 ERROR - PROCESS | 1945 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 02:29:02 INFO - TEST-INFO | 1945: exit 0 02:29:02 INFO - Browser initialized. 02:29:02 INFO - Running cycle 1/1 for kraken test... 02:29:02 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmpwaAj9p/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:29:05 INFO - PROCESS | 1948 | 2017-03-25 02:29:05.118 plugin-container[1949:21302] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 02:29:05 INFO - PROCESS | 1948 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:29:05 INFO - PROCESS | 1948 | 2017-03-25 02:29:05.119 plugin-container[1949:21302] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f13, name = 'com.apple.CFPasteboardClient' 02:29:05 INFO - PROCESS | 1948 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:29:05 INFO - PROCESS | 1948 | 2017-03-25 02:29:05.119 plugin-container[1949:21302] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:29:14 INFO - PROCESS | 1948 | RSS: Main: 220696576 02:29:14 INFO - PROCESS | 1948 | 02:29:18 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52910/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52910/tests/kraken/driver.html?testName=audio-beat-detection) 02:29:19 INFO - PROCESS | 1948 | RSS: Main: 235606016 02:29:19 INFO - PROCESS | 1948 | 02:29:21 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52910/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52910/tests/kraken/driver.html?testName=audio-dft) 02:29:22 INFO - PROCESS | 1948 | RSS: Main: 243650560 02:29:22 INFO - PROCESS | 1948 | 02:29:25 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52910/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52910/tests/kraken/driver.html?testName=audio-fft) 02:29:26 INFO - PROCESS | 1948 | RSS: Main: 245043200 02:29:26 INFO - PROCESS | 1948 | 02:29:28 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52910/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52910/tests/kraken/driver.html?testName=audio-oscillator) 02:29:29 INFO - PROCESS | 1948 | RSS: Main: 246730752 02:29:29 INFO - PROCESS | 1948 | 02:29:31 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52910/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52910/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:29:31 INFO - PROCESS | 1948 | RSS: Main: 248401920 02:29:31 INFO - PROCESS | 1948 | 02:29:34 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52910/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52910/tests/kraken/driver.html?testName=imaging-darkroom) 02:29:35 INFO - PROCESS | 1948 | RSS: Main: 252297216 02:29:35 INFO - PROCESS | 1948 | 02:29:38 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52910/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52910/tests/kraken/driver.html?testName=imaging-desaturate) 02:29:39 INFO - PROCESS | 1948 | RSS: Main: 252424192 02:29:39 INFO - PROCESS | 1948 | 02:29:42 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52910/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52910/tests/kraken/driver.html?testName=json-parse-financial) 02:29:43 INFO - PROCESS | 1948 | RSS: Main: 251691008 02:29:43 INFO - PROCESS | 1948 | 02:29:45 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52910/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52910/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:29:45 INFO - PROCESS | 1948 | RSS: Main: 251346944 02:29:45 INFO - PROCESS | 1948 | 02:29:47 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52910/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52910/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:29:48 INFO - PROCESS | 1948 | RSS: Main: 251432960 02:29:48 INFO - PROCESS | 1948 | 02:29:50 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52910/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52910/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:29:51 INFO - PROCESS | 1948 | RSS: Main: 251785216 02:29:51 INFO - PROCESS | 1948 | 02:29:53 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52910/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52910/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:29:54 INFO - PROCESS | 1948 | RSS: Main: 252215296 02:29:54 INFO - PROCESS | 1948 | 02:29:57 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52910/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52910/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:29:58 INFO - PROCESS | 1948 | RSS: Main: 251760640 02:29:58 INFO - PROCESS | 1948 | 02:29:59 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52910/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52910/tests/kraken/driver.html?testName=ai-astar) 02:30:00 INFO - PROCESS | 1948 | RSS: Main: 255434752 02:30:00 INFO - PROCESS | 1948 | 02:30:00 INFO - PROCESS | 1948 | __start_tp_report 02:30:00 INFO - PROCESS | 1948 | _x_x_mozilla_page_load 02:30:00 INFO - PROCESS | 1948 | _x_x_mozilla_page_load_details 02:30:00 INFO - PROCESS | 1948 | |i|pagename|runs| 02:30:00 INFO - PROCESS | 1948 | |0;ai-astar;155;180;154;120;99;174;176;176;119;120 02:30:00 INFO - PROCESS | 1948 | |1;audio-beat-detection;166;143;122;150;122;146;114;115;127;97 02:30:00 INFO - PROCESS | 1948 | |2;audio-dft;195;200;196;199;202;236;200;225;289;194 02:30:00 INFO - PROCESS | 1948 | |3;audio-fft;90;60;60;85;63;64;82;88;97;82 02:30:00 INFO - PROCESS | 1948 | |4;audio-oscillator;89;72;86;75;72;72;82;74;102;101 02:30:00 INFO - PROCESS | 1948 | |5;imaging-gaussian-blur;136;110;94;136;132;79;76;89;79;87 02:30:00 INFO - PROCESS | 1948 | |6;imaging-darkroom;99;123;151;136;153;152;97;108;97;107 02:30:00 INFO - PROCESS | 1948 | |7;imaging-desaturate;99;143;137;91;91;92;98;91;91;96 02:30:00 INFO - PROCESS | 1948 | |8;json-parse-financial;66;63;66;67;83;70;67;69;71;72 02:30:00 INFO - PROCESS | 1948 | |9;json-stringify-tinderbox;57;60;68;74;62;57;58;58;59;70 02:30:00 INFO - PROCESS | 1948 | |10;stanford-crypto-aes;87;85;84;85;84;84;84;84;91;89 02:30:00 INFO - PROCESS | 1948 | |11;stanford-crypto-ccm;138;140;136;144;149;147;164;141;181;142 02:30:00 INFO - PROCESS | 1948 | |12;stanford-crypto-pbkdf2;168;169;168;176;166;169;167;167;210;224 02:30:00 INFO - PROCESS | 1948 | |13;stanford-crypto-sha256-iterative;67;65;67;64;65;68;66;68;86;83 02:30:00 INFO - PROCESS | 1948 | __end_tp_report 02:30:00 INFO - PROCESS | 1948 | __start_cc_report 02:30:00 INFO - PROCESS | 1948 | _x_x_mozilla_cycle_collect,1012 02:30:00 INFO - PROCESS | 1948 | __end_cc_report 02:30:00 INFO - PROCESS | 1948 | __startTimestamp1490434200245__endTimestamp 02:30:00 INFO - PROCESS | 1948 | 02:30:00 INFO - PROCESS | 1948 | ------- Summary: start ------- 02:30:00 INFO - PROCESS | 1948 | Number of tests: 14 02:30:00 INFO - PROCESS | 1948 | 02:30:00 INFO - PROCESS | 1948 | [#0] ai-astar Cycles:10 Average:147.30 Median:175.00 stddev:30.11 (17.2%) stddev-sans-first:31.80 02:30:00 INFO - PROCESS | 1948 | Values: 155.0 180.0 154.0 120.0 99.0 174.0 176.0 176.0 119.0 120.0 02:30:00 INFO - PROCESS | 1948 | 02:30:00 INFO - PROCESS | 1948 | [#1] audio-beat-detection Cycles:10 Average:130.20 Median:144.50 stddev:20.62 (14.3%) stddev-sans-first:17.33 02:30:00 INFO - PROCESS | 1948 | Values: 166.0 143.0 122.0 150.0 122.0 146.0 114.0 115.0 127.0 97.0 02:30:00 INFO - PROCESS | 1948 | 02:30:00 INFO - PROCESS | 1948 | [#2] audio-dft Cycles:10 Average:213.60 Median:201.00 stddev:29.95 (14.9%) stddev-sans-first:31.00 02:30:00 INFO - PROCESS | 1948 | Values: 195.0 200.0 196.0 199.0 202.0 236.0 200.0 225.0 289.0 194.0 02:30:00 INFO - PROCESS | 1948 | 02:30:00 INFO - PROCESS | 1948 | [#3] audio-fft Cycles:10 Average:77.10 Median:83.50 stddev:13.93 (16.7%) stddev-sans-first:13.97 02:30:00 INFO - PROCESS | 1948 | Values: 90.0 60.0 60.0 85.0 63.0 64.0 82.0 88.0 97.0 82.0 02:30:00 INFO - PROCESS | 1948 | 02:30:00 INFO - PROCESS | 1948 | [#4] audio-oscillator Cycles:10 Average:82.50 Median:74.50 stddev:11.72 (15.7%) stddev-sans-first:12.19 02:30:00 INFO - PROCESS | 1948 | Values: 89.0 72.0 86.0 75.0 72.0 72.0 82.0 74.0 102.0 101.0 02:30:00 INFO - PROCESS | 1948 | 02:30:00 INFO - PROCESS | 1948 | [#5] imaging-gaussian-blur Cycles:10 Average:101.80 Median:79.00 stddev:24.65 (31.2%) stddev-sans-first:22.83 02:30:00 INFO - PROCESS | 1948 | Values: 136.0 110.0 94.0 136.0 132.0 79.0 76.0 89.0 79.0 87.0 02:30:00 INFO - PROCESS | 1948 | 02:30:00 INFO - PROCESS | 1948 | [#6] imaging-darkroom Cycles:10 Average:122.30 Median:152.50 stddev:23.80 (15.6%) stddev-sans-first:23.70 02:30:00 INFO - PROCESS | 1948 | Values: 99.0 123.0 151.0 136.0 153.0 152.0 97.0 108.0 97.0 107.0 02:30:00 INFO - PROCESS | 1948 | 02:30:00 INFO - PROCESS | 1948 | [#7] imaging-desaturate Cycles:10 Average:102.90 Median:91.50 stddev:19.84 (21.7%) stddev-sans-first:20.99 02:30:00 INFO - PROCESS | 1948 | Values: 99.0 143.0 137.0 91.0 91.0 92.0 98.0 91.0 91.0 96.0 02:30:00 INFO - PROCESS | 1948 | 02:30:00 INFO - PROCESS | 1948 | [#8] json-parse-financial Cycles:10 Average:69.40 Median:69.50 stddev:5.48 (7.9%) stddev-sans-first:5.67 02:30:00 INFO - PROCESS | 1948 | Values: 66.0 63.0 66.0 67.0 83.0 70.0 67.0 69.0 71.0 72.0 02:30:00 INFO - PROCESS | 1948 | 02:30:00 INFO - PROCESS | 1948 | [#9] json-stringify-tinderbox Cycles:10 Average:62.30 Median:61.00 stddev:6.13 (10.0%) stddev-sans-first:6.19 02:30:00 INFO - PROCESS | 1948 | Values: 57.0 60.0 68.0 74.0 62.0 57.0 58.0 58.0 59.0 70.0 02:30:00 INFO - PROCESS | 1948 | 02:30:00 INFO - PROCESS | 1948 | [#10] stanford-crypto-aes Cycles:10 Average:85.70 Median:85.00 stddev:2.50 (2.9%) stddev-sans-first:2.60 02:30:00 INFO - PROCESS | 1948 | Values: 87.0 85.0 84.0 85.0 84.0 84.0 84.0 84.0 91.0 89.0 02:30:00 INFO - PROCESS | 1948 | 02:30:00 INFO - PROCESS | 1948 | [#11] stanford-crypto-ccm Cycles:10 Average:148.20 Median:145.50 stddev:13.97 (9.6%) stddev-sans-first:14.32 02:30:00 INFO - PROCESS | 1948 | Values: 138.0 140.0 136.0 144.0 149.0 147.0 164.0 141.0 181.0 142.0 02:30:00 INFO - PROCESS | 1948 | 02:30:00 INFO - PROCESS | 1948 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.40 Median:169.00 stddev:20.79 (12.3%) stddev-sans-first:21.71 02:30:00 INFO - PROCESS | 1948 | Values: 168.0 169.0 168.0 176.0 166.0 169.0 167.0 167.0 210.0 224.0 02:30:00 INFO - PROCESS | 1948 | 02:30:00 INFO - PROCESS | 1948 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.90 Median:67.50 stddev:7.84 (11.6%) stddev-sans-first:8.24 02:30:00 INFO - PROCESS | 1948 | Values: 67.0 65.0 67.0 64.0 65.0 68.0 66.0 68.0 86.0 83.0 02:30:00 INFO - PROCESS | 1948 | -------- Summary: end -------- 02:30:00 INFO - PROCESS | 1948 | 02:30:01 INFO - TEST-INFO | 1948: exit 0 02:30:01 INFO - TEST-OK | kraken | took 62209ms 02:30:01 INFO - SUITE-END | took 1178s 02:30:01 INFO - Completed test suite (00:19:39) 02:30:01 INFO - javascript benchmark 02:30:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7636.312482224849, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3456.8444420001097, 3460.7404444666654, 3480.634533373994, 3492.877749278776, 3512.490688850117, 6001.909971350433, 6007.0, 6037.245344331958, 6089.238845144357, 6124.081387791834, 6046.667433291167, 6057.0, 6118.0, 6126.203593532835, 6138.815835524931, 5401.837944861661, 5444.047291723952, 5489.478499542537, 5551.0, 5555.805546805863, 5475.616706830523, 5560.833175004756, 5563.554915606742, 5567.276254086963, 5619.6066275360745, 5294.92057619136, 5343.919841202385, 5498.395176530578, 5523.6685798851995, 5525.475079867406, 5337.172738225542, 5381.67709937405, 5383.515483606494, 5403.594730395197, 5409.83770486886, 5398.406175320712, 5402.973435047328, 5424.240606315119, 5425.430329815392, 5469.0702580561, 5794.1308803679785, 5871.148683440877, 6055.061465472859, 6062.272527296708, 6077.6353418795015, 6247.969085100953, 6401.359864013608, 6421.197350331208, 6428.357164283581, 6457.192850893638, 6299.055141728777, 6358.968205158991, 6444.838879028015, 6517.413432791024, 6530.955047192427, 6174.783882564088, 6176.320604733476, 6181.536384771161, 6196.504279657616, 6205.410486003822, 5817.680027598522, 6058.242719660043, 6605.174353205849, 6614.272430032693, 6631.403173714389, 6110.862540392742, 6205.565610407228, 6221.222347206599, 6226.688665566703, 6270.498360131179, 6476.481881449473, 6477.028445733084, 6477.935220647733, 6507.544471886922, 6508.153939987771, 5583.944160558342, 6295.9296919523795, 6401.967990160019, 6409.455196308273, 6447.258565265054, 6137.131844484766, 6236.594621349598, 6292.622442653455, 6296.181496405438, 6347.96826015867, 5133.820316288912, 5160.948390516121, 5165.535101840853, 5177.8446646599905, 5177.922331165036, 3703.5555733312176, 3908.4528166056775, 4605.98668292975, 4625.444946606429, 4633.9536604634195, 5755.251817263729, 5814.854628634318, 5851.882962340729, 5853.209816674695, 5923.022701254245, 5481.424450432727, 5591.52472039873, 6529.738810447624, 6541.34586541342, 6541.8364540885905, 5279.52484276417, 6056.57603967718, 6059.242594675666, 6076.574639775174, 6112.51099912006, 5460.099083651233, 5563.721813909288, 5695.629784064023, 5700.686462244616, 5757.539396848242, 6513.348665133449, 6596.142501474777, 6710.295418981132, 6711.429528490133, 6714.664266786739, 6429.935700642934, 6495.837604059936, 6500.544961852814, 6512.3162067982175, 6520.543561950808, 6314.463270622048, 6652.334766523502, 6719.798406047827, 6740.66296685154, 6749.088873002278, 5226.581873450039, 6262.2172228471445, 6280.780172693934, 6292.968535157295, 6357.554971151975, 4894.755262236803, 6427.3251308611925, 6469.514786390945, 6478.740850365933, 6519.543631945908, 6179.223152332165, 6343.492520598249, 6343.5876668016435, 6355.205599300088, 6362.395572420613, 6137.560853825989, 6139.308154111861, 6213.5339849510565, 6225.8132256032395, 6332.891743944699, 6136.94800169746, 6142.897836592492, 6217.316095229611, 6223.595466294857, 6241.687915604292, 6270.811875643738, 6334.588251763621, 6344.619322840645, 6359.777407790705, 6359.8092057238355, 5473.0969390050195, 5928.318243402064, 6072.878542429038, 6107.938920610737, 6135.969320153371], "value": 5879.766375473231, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [739.423249865105, 771.1786946901551, 806.9152738962413, 825.5294482145176, 956.3783540698545, 32902.67097329026, 38371.0, 40780.184396312055, 40834.59165408345, 41038.794806026, 38678.806605967, 38844.61155388445, 38861.0, 38911.221775564474, 38935.61064389355, 7886.132525422199, 8056.8388632227325, 10465.372077675363, 10725.570977160904, 10910.345379277229, 10645.733856653567, 10745.78508429831, 10749.62376316829, 10752.462376881163, 10777.461126943661, 10728.73178170548, 10759.085477734401, 10760.40817755023, 10792.73018174544, 10810.72973175673, 10748.355098694065, 10755.516001779939, 10758.354498730101, 10779.245452818306, 10782.191335649857, 10379.688609341732, 10402.167826573932, 10436.008579184965, 10450.947745261225, 10461.68614941553, 33649.0, 33792.32415351679, 33795.662043379496, 33827.4925876112, 33914.49128263078, 3739.8504059837574, 3799.449079883429, 3801.0, 3808.5620153682125, 3822.827972741233, 24628.891699873548, 24990.750092499024, 25178.874105629537, 25479.108231212005, 26224.344391390176, 3087.8919237826567, 3088.3823235352793, 3095.1952492351925, 3108.3317086826214, 3206.7274281686086, 259819.4018059814, 278354.0, 366914.0, 372569.0, 373026.0, 42887.571124288355, 42959.0, 43009.13981720348, 43231.0, 43345.34981975273, 45524.0, 45554.54445455503, 45574.31638525425, 45627.0, 45637.54362456333, 13128.934355328161, 13207.66980825487, 13208.207507549578, 13243.536476223479, 13265.867341326657, 9847.655332063487, 11695.356755378536, 11712.238704484182, 11726.94136529312, 11748.001419879405, 9546.331756777097, 9614.0, 11626.825597616042, 11692.88307116935, 11742.884425979519, 4364.279893817485, 4811.759412029384, 5024.949750502521, 5598.188262701886, 5756.194132821408, 1107.8227483602584, 1111.5942680921405, 1123.9831402528969, 1150.8906653867868, 1183.7928362536522, 22114.88942555309, 26877.0, 27269.318267043484, 27582.44835103287, 27598.862005689843, 1237.55448038706, 1255.0541167853562, 1260.8550016748009, 1264.993675031619, 1295.313483853559, 13575.728485430236, 13927.651808704863, 13955.930220349037, 14290.857091428952, 14303.356348964322, 378237.1088144542, 492312.0, 494003.0, 495741.52129239123, 503919.0, 57377.0, 57590.42409575934, 57598.7120064397, 57765.0, 58057.0, 1320.7886738121854, 1400.7478653842202, 1459.5986103821533, 1461.4738694070127, 1462.6416527950507, 26248.475030499663, 26471.735282647696, 26541.469170616867, 27079.72920270772, 27136.050238241565, 46314.0, 46483.767581161876, 46545.53454465547, 46620.0, 46732.53267467282, 409562.0, 411545.0, 412265.0, 412907.0, 413557.932210337, 31653.733850645716, 31744.841275793475, 31938.88213912502, 32029.558669860377, 32113.0, 43461.0, 43937.901552461444, 44346.778266108464, 44937.77531112324, 44971.32543011923, 29883.402331953675, 30119.0, 30208.54687179738, 30332.393352133276, 30893.69106308908, 24609.261722148363, 25592.360190995376, 25676.614850777627, 28442.288942776595, 31071.84464077665, 29234.853825730734, 31804.68195318017, 32786.67213327836, 36025.27949440944, 36554.634453655126, 27855.0, 28321.291967700974, 28367.43265134727, 28611.856940715163, 29234.561481578217, 20898.58202835965, 23341.41646458852, 23347.649785253576, 23563.41091472727, 24267.635985459878], "value": 18185.377617537408, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [403.5077205808912, 405.36155555000863, 411.47947845974835, 431.0968520948612, 443.1181947923633, 15914.363425462981, 20312.085956131974, 20458.56790024697, 21095.050722717475, 21210.833404162764, 21600.459988500257, 21668.566628667417, 21668.8916555417, 22227.44431389216, 22484.775152248472, 5054.84835454936, 6077.78727744529, 6861.897071543931, 6903.240643529208, 6914.0, 5829.970850145764, 6311.93688063118, 6538.05198246254, 6709.09427227323, 6808.829779255509, 6308.4322410983, 6799.456043516507, 6807.047013418125, 6819.977003449472, 6828.180618325783, 6827.931720682779, 7127.572345659277, 7272.854542909112, 7289.380402665806, 7294.051773269494, 7045.18980317265, 7107.0, 7165.99676045354, 7215.278472152795, 7223.422126229889, 24809.379765505826, 24877.0, 25629.359266018313, 25882.352941176432, 26177.869110654327, 2844.4026754381603, 2855.328997685542, 2948.0418863869327, 2957.1276473440385, 2986.7909246352765, 15753.291101900442, 15964.680706385923, 16152.757708634379, 16350.591235219095, 16637.084960327178, 2029.7767245602972, 2059.5263089489354, 2062.2885104638876, 2067.576146889884, 2070.33749200256, 154993.0, 164460.0, 168404.0, 170780.0, 172472.0, 16484.093374864497, 19184.71222931657, 20351.69472457914, 20553.0, 20719.58560828775, 31002.68997309998, 31014.534781978287, 31463.685363146535, 31483.370332593677, 31641.0, 10084.243681723754, 10286.537105830255, 10412.0, 10430.478476076165, 10557.049865511986, 8518.531480768494, 8681.175288347597, 8689.826203476021, 8809.295256379603, 8844.611954772176, 9474.194693450996, 9546.520289355161, 9556.808863822685, 9591.712248632413, 9602.08780165883, 3457.377672019061, 4000.179963107556, 4112.239235741337, 4121.361189015647, 4137.710360274752, 1009.137187704502, 1017.1049476460668, 1020.2399212586671, 1027.9383237005804, 1056.1656291529605, 20445.48886277855, 20651.0, 20735.792642073688, 20877.0, 20994.685079723815, 933.8552524358736, 1008.8549496321581, 1044.3316277582353, 1046.5447530324334, 1049.8110340138849, 10509.474526273656, 10855.620053298255, 10862.076723478593, 10867.78264434707, 10929.617463388744, 651142.0, 689733.0, 734515.0, 741402.0, 759921.0, 113374.0, 142104.0, 142415.5758442423, 148295.2585237067, 155154.0, 1201.8077107662907, 1257.5724253753767, 1284.3834959219628, 1297.1827748518374, 1307.2287350463082, 15326.233688315317, 19397.166302732563, 20112.89379084106, 20159.0, 20197.49506262355, 64938.67530662316, 65535.01697474637, 65590.67204663946, 65642.67178664076, 66937.66531167313, 67549.0, 91325.54337228271, 95585.0, 96208.0, 96519.55220671836, 7668.923310766821, 9737.880143783272, 9837.360571562825, 11188.272762270426, 11518.827217591575, 12823.230606163661, 12905.096643234883, 12907.741845162856, 12929.224246545238, 13253.204807711569, 11257.887421125684, 11341.376224307742, 11418.0865530759, 11419.714507137389, 11435.828462572901, 10395.896041039492, 10575.418351990715, 10591.788164236827, 10702.357858528514, 10912.781744365227, 9479.625454309224, 9787.804243915225, 11407.600733974272, 11434.771304574027, 11647.825282620937, 11558.0, 11606.593769218374, 11650.592229272273, 11876.0, 11953.94023029914, 11139.944300278446, 11375.089992800722, 11523.596674116367, 11538.711532211762, 11549.942250288696], "value": 11525.722912899324, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1415.433826469412, 1497.9925100374496, 1552.5497605694356, 1568.4667213147527, 1569.5605230535455, 10583.0, 11146.164037697165, 11205.495752691118, 11237.494312755925, 11433.942830285858, 11028.669139925814, 11215.719607009809, 11245.83131253032, 11256.54973801049, 11301.0, 1976.4781118538717, 2273.454370950968, 2317.2353123469215, 2319.2926157521947, 2325.790678838904, 2239.193890199527, 2294.0135741631093, 2305.170138750049, 2317.5249073939804, 2318.7217533895955, 2259.570681570496, 2279.293419040103, 2280.64301740465, 2292.5644127615783, 2329.580675478413, 2225.0654725015534, 2280.8859557022165, 2282.2582660635276, 2288.233441797, 2294.128231272123, 2270.6821045053707, 2284.657301404786, 2285.8857057147075, 2287.3709729824263, 2288.8397812153157, 10931.0, 10959.561617535368, 11029.558817647285, 11057.834132488018, 11066.667999960015, 4613.331066995267, 5305.814296499604, 5494.725263736797, 5496.065668836308, 5516.91724624131, 3174.984125079383, 3235.9838200808845, 3361.915952101217, 3393.4570468724887, 3400.5579274694132, 1092.2954694222262, 1098.1379617000616, 1131.5021390588115, 1139.0944199361527, 1147.219890474477, 12843.743125137446, 13698.315084245847, 13947.232902190375, 14888.404463821435, 15440.613984650474, 4019.954811748908, 4193.287141186006, 4301.268784306612, 4386.605205531518, 4490.57339552742, 11751.412429378497, 11778.469968851423, 11801.173917825841, 11825.408729563487, 11841.703957401134, 5441.156620723794, 5441.238226648273, 5441.83674489766, 5452.372977107683, 5459.808906688247, 4898.458721803789, 4974.502549744997, 4979.825706100269, 5016.398032236155, 5050.671706339076, 3314.2045908982154, 3375.696187343151, 3631.94552081719, 3642.9817850911104, 3734.477173195755, 889.2441424789007, 890.0476490155471, 899.7525680437932, 907.773056735816, 909.0591238068567, 778.5289899610767, 822.7778499805019, 889.786451251695, 894.3784070071238, 922.0871337376084, 6223.875522489616, 6287.779927702509, 6334.1765570475545, 6395.104685344056, 6520.217573891068, 1010.5604062232952, 1032.9431881246453, 1051.7948999944938, 1052.2581579986092, 1086.684861390188, 3992.7803970781306, 4005.679545636342, 4094.2630326541507, 4137.565555616617, 4261.424707664426, 13486.12340197884, 13937.233452160228, 13972.580822575339, 13982.440702371994, 14003.719925601634, 12866.678333041562, 13790.586282411543, 13848.238346890816, 13871.861281387259, 13877.791833122512, 8353.749387518263, 9987.80024399508, 10261.384316941007, 10263.281570290157, 10271.640492582725, 5722.484976352148, 6535.542512024303, 6683.398494135551, 6715.832104197434, 6750.898736518858, 12117.091218158497, 12128.818067728815, 12217.389130543259, 12246.142770006014, 12410.441530130985, 12117.030637548798, 12221.816672750094, 12547.498100075894, 12564.246145231315, 12614.117011809085, 7990.560519171268, 9020.00847183132, 9384.718458446258, 9431.575579099093, 9438.433693978383, 10539.578416863545, 10620.575176992834, 10646.627368042082, 10671.719393672809, 10712.089472394933, 8625.353098517511, 8773.166549178073, 8825.073367296598, 8871.512066836385, 8878.422902511316, 8535.231829135408, 8581.442206256574, 8685.001224859223, 8763.912360876564, 8774.38579299443, 7623.932649429195, 7783.221677832172, 7788.571628560484, 7789.4157938155995, 7833.373330133461, 5570.749316280696, 5621.494065534122, 5653.265075540154, 5764.991126552753, 5849.064149736022, 5670.659760414388, 5680.829575112753, 5746.396628353963, 5762.2221000164445, 5866.208061911588], "value": 4978.439592039276, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.3878284482069, 375.69193261525544, 378.47770077293336, 385.0681351130265, 387.25260247721883, 4265.63742081923, 5088.949110508894, 5186.299849520314, 5216.347956505437, 5268.841934741959, 4626.571050198648, 4643.442786865572, 4867.783054236441, 5198.584113270938, 5232.502912223342, 3876.023228153648, 4218.852340168095, 4277.679174061957, 4353.368761529577, 4370.3007518797, 3836.6163383661687, 3875.8837234883, 4344.370066340379, 4349.565043495641, 4358.063016451462, 3641.799701016443, 4076.3681629347498, 4372.873395522217, 4384.912301753979, 4389.561043895616, 3640.9974514017185, 3741.625837416264, 4187.91624167518, 4385.758783266903, 4406.413691071211, 3381.7970921744527, 3409.3863104641155, 3471.2536804587135, 3971.0667993021616, 4014.598540145962, 4560.384348113029, 4605.608523275526, 4637.930431043537, 5131.384233891919, 5219.73901304937, 4483.977580112079, 4578.35902973584, 4923.4091908970795, 5085.771140298695, 5111.437741848394, 4947.876303092416, 5478.945580270571, 5564.443555644403, 5639.774409023634, 5703.287089113861, 3653.8903832885057, 4255.829766809324, 4547.15877562652, 4551.270517203441, 4563.703359281636, 4385.846495372647, 5271.86820329491, 5985.461308482258, 6003.609765365237, 6036.094585812163, 3966.464527288779, 4349.3475978603465, 4524.841630542981, 4587.6788624795945, 4623.352730617717, 4192.392103145027, 4309.0, 4382.663287697219, 4628.981624042773, 4650.67445278834, 4767.308740232646, 4828.806847726122, 4867.63492738039, 5037.370328708911, 5071.87320316995, 5272.154745838926, 5583.972080139573, 5584.776608935597, 5612.887742245132, 5721.170430287585, 1376.986230137706, 1490.6496973211288, 1542.4524293875736, 1543.2515230113386, 1555.4555905433008, 1108.6175269531998, 1154.7355645568089, 1189.3280296632374, 1194.2118201986648, 1211.4427363412929, 4859.854204373803, 4865.416150061945, 5215.817446389358, 5220.243064755665, 5251.789928402897, 4995.400551933792, 5233.816816411408, 5495.1865884258095, 5733.0, 5774.740136693859, 4280.443542339476, 4488.438945131858, 4825.5898248649255, 5050.772715227824, 5104.668196567211, 2588.6893572771387, 2735.302497863072, 2882.524383476745, 3024.0, 3025.455418024733, 5503.862403439946, 5761.683107429048, 6040.939590604038, 6056.606320589148, 6107.633541987495, 4551.8634440966825, 5292.0, 5638.661680299195, 5690.630109042899, 5886.676232807151, 5094.083065048327, 5541.639793413416, 5807.519082633901, 6120.0, 6123.938760612515, 4914.877128071684, 5789.118536475747, 5797.739101740347, 5839.182514448065, 5862.48251049367, 5210.0, 5366.436524164907, 5563.368341262454, 5714.828555143353, 5756.56825738063, 5072.909324495251, 5528.55771538284, 5793.1172368979405, 6007.789727359522, 6047.304559975483, 4276.422682937764, 4295.591918767712, 4346.457951440815, 4564.338170965258, 4567.629711086594, 4845.903081938412, 5303.522682958553, 5350.324837581209, 5364.210610482605, 5782.653040817564, 4861.322843618946, 5613.859653508695, 5626.634268772516, 5641.417699760599, 5648.63283886546, 4627.5603817637275, 5269.683818970874, 5439.836804895859, 5699.914501282401, 5702.714864256687, 5134.255532947776, 5253.605979551472, 5398.784048638167, 5513.751881165277, 5560.666360018412, 4617.8383756568355, 5251.737413129405, 5478.91781623284, 5676.489115979582, 5698.487136157766, 4773.689710168828, 5038.420581633012, 5345.33183352081, 5408.323959505062, 5528.9723551381985], "value": 4233.599465380935, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.3061530315592, 716.4483347822177, 718.2350796401835, 764.7208768799386, 828.9212524810143, 15129.621759456018, 15592.298346574404, 15635.530934071967, 15637.92181039096, 15710.0, 14549.854501455009, 14864.479743208989, 15272.083674979509, 15486.61283467914, 15564.066156030645, 946.347020555816, 968.4092703450889, 971.3928794503436, 971.6696323250094, 973.9074787895139, 919.4161707315869, 970.6699722094487, 983.6557204978236, 984.601236499219, 985.4087547471531, 977.0376179463223, 1004.0712341084504, 1025.6564051042872, 1029.7322696098993, 1029.7425643589104, 1016.567958617592, 1020.5203554329453, 1023.52406131149, 1025.0518270599703, 1025.66665833604, 757.6817736550662, 761.4137114421919, 761.7752762934947, 762.6911101004102, 763.6181909045478, 16002.799790015795, 16244.187790610422, 16293.755593665986, 16870.83129168705, 17054.914725426417, 13553.0, 13670.11144275619, 13827.930860345634, 13909.165450073027, 13936.651583710489, 11785.587504437304, 11855.229410088317, 11875.0499960003, 11945.223560468628, 11995.16033877629, 3564.3049605326974, 3598.748087633867, 3629.237860049393, 3660.670539651444, 3761.6614504694708, 106422.80731578065, 106472.99999999923, 116481.0, 117099.0, 122264.38867805604, 9246.815063698688, 10272.897271027343, 10474.528646210938, 10732.785344293226, 10868.349576609657, 19251.0, 21197.89401052985, 21260.043298051623, 21433.46416339604, 21454.570908582053, 7177.605231712306, 7237.240089790602, 7370.668319925616, 7530.246975302535, 7544.132424771112, 4184.518780340239, 4213.389058586549, 4299.548547402478, 4303.935440968388, 4347.195768782785, 3280.852361643732, 3340.8329583521213, 3361.2941282330985, 3365.192353835111, 3581.122624956902, 673.6575145009679, 679.864027194563, 683.3132701634826, 684.165318311659, 691.1395312835552, 617.2253821454046, 661.1834384535096, 661.6823924516259, 680.2653134614604, 703.3739971425435, 7773.766786996399, 9654.239580691554, 10020.949895250476, 10078.647247346455, 10097.951024487757, 680.669875110571, 705.6718625838907, 706.6431452116751, 710.7867639708066, 714.6319645382632, 4819.469858315582, 5000.824971125993, 5004.724740139254, 5027.757410704945, 5041.79832806691, 85567.57216214003, 91127.54436227777, 101611.0, 109727.45136274268, 112381.43809281065, 66283.0, 81156.59421702854, 92550.61174082394, 93735.53132234295, 102693.0, 62785.80249691239, 80782.0, 83863.16136838554, 87053.0, 88286.11713882907, 10655.480125100186, 10709.732256693645, 10901.855305193158, 10921.126309895348, 10977.670669879917, 52263.47736522586, 52636.0, 52790.0, 53034.73482632562, 53629.7318513405, 66258.34354141186, 68551.31448685649, 68601.0, 68695.65652171707, 69822.0, 11913.642590722293, 11978.735737535788, 12115.333656648969, 12161.69595760113, 12428.005759539396, 13841.30793460343, 13932.860671393157, 14006.369713363127, 14238.430462781373, 14659.0, 13455.327233638474, 13710.588682339147, 13929.442822287401, 13996.580102596938, 14093.942954278675, 12728.554500592434, 12958.676033099247, 13301.733965320833, 13954.302284885918, 13977.930110349384, 13016.349182540645, 13502.392392342172, 13642.522511712043, 13912.304384780922, 14132.586741325786, 9224.03147669485, 9298.0, 9426.622935082794, 9440.615323144486, 9732.41605503672, 8917.381615876473, 9847.704568862946, 10944.562217511211, 11160.162987775788, 11160.49777760019], "value": 7633.218229488651, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1591.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 180.0, 154.0, 120.0, 99.0, 174.0, 176.0, 176.0, 119.0, 120.0], "value": 147.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 143.0, 122.0, 150.0, 122.0, 146.0, 114.0, 115.0, 127.0, 97.0], "value": 130.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 200.0, 196.0, 199.0, 202.0, 236.0, 200.0, 225.0, 289.0, 194.0], "value": 213.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 60.0, 60.0, 85.0, 63.0, 64.0, 82.0, 88.0, 97.0, 82.0], "value": 77.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 72.0, 86.0, 75.0, 72.0, 72.0, 82.0, 74.0, 102.0, 101.0], "value": 82.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 110.0, 94.0, 136.0, 132.0, 79.0, 76.0, 89.0, 79.0, 87.0], "value": 101.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 123.0, 151.0, 136.0, 153.0, 152.0, 97.0, 108.0, 97.0, 107.0], "value": 122.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 143.0, 137.0, 91.0, 91.0, 92.0, 98.0, 91.0, 91.0, 96.0], "value": 102.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 63.0, 66.0, 67.0, 83.0, 70.0, 67.0, 69.0, 71.0, 72.0], "value": 69.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 60.0, 68.0, 74.0, 62.0, 57.0, 58.0, 58.0, 59.0, 70.0], "value": 62.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 84.0, 85.0, 84.0, 84.0, 84.0, 84.0, 91.0, 89.0], "value": 85.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 140.0, 136.0, 144.0, 149.0, 147.0, 164.0, 141.0, 181.0, 142.0], "value": 148.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 169.0, 168.0, 176.0, 166.0, 169.0, 167.0, 167.0, 210.0, 224.0], "value": 178.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 67.0, 64.0, 65.0, 68.0, 66.0, 68.0, 86.0, 83.0], "value": 69.9, "unit": "score"}]}]} 02:30:01 INFO - Return code: 0 02:30:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:30:01 ERROR - # TBPL SUCCESS # 02:30:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:30:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:30:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:30:01 INFO - 2017-03-25 02:30:01.548 screenresolution[1954:21698] starting screenresolution argv=screenresolution get 02:30:01 INFO - 2017-03-25 02:30:01.551 screenresolution[1954:21698] Display 0: 1600x1200x32@60 02:30:01 INFO - 2017-03-25 02:30:01.557 screenresolution[1955:21701] starting screenresolution argv=screenresolution list 02:30:01 INFO - Available Modes on Display 0 02:30:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:30:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:30:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:30:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:30:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:30:01 INFO - Intel Iris: 02:30:01 INFO - Chipset Model: Intel Iris 02:30:01 INFO - Type: GPU 02:30:01 INFO - Bus: Built-In 02:30:01 INFO - VRAM (Dynamic, Max): 1536 MB 02:30:01 INFO - Vendor: Intel (0x8086) 02:30:01 INFO - Device ID: 0x0a2e 02:30:01 INFO - Revision ID: 0x0009 02:30:01 INFO - Displays: 02:30:01 INFO - Display: 02:30:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:30:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:30:01 INFO - Display Serial Number: 1600x1200 60 02:30:01 INFO - Main Display: Yes 02:30:01 INFO - Mirror: Off 02:30:01 INFO - Online: Yes 02:30:01 INFO - Rotation: Supported 02:30:01 INFO - Return code: 0 02:30:01 INFO - Running post-action listener: _resource_record_post_action 02:30:01 INFO - [mozharness: 2017-03-25 09:30:01.794297Z] Finished run-tests step (success) 02:30:01 INFO - Running post-run listener: _resource_record_post_run 02:30:01 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 1134592; Write bytes: 514775040; Read time: 11; Write time: 1776 02:30:01 INFO - TinderboxPrint: CPU usage
54.6% 02:30:01 INFO - TinderboxPrint: I/O read bytes / time
1,134,592 / 11 02:30:01 INFO - TinderboxPrint: I/O write bytes / time
514,775,040 / 1,776 02:30:01 INFO - TinderboxPrint: CPU idle
2,180.3 (45.4%) 02:30:01 INFO - TinderboxPrint: CPU system
964.5 (20.1%) 02:30:01 INFO - TinderboxPrint: CPU user
1,657.0 (34.5%) 02:30:01 INFO - TinderboxPrint: Swap in / out
208,781,312 / 0 02:30:01 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201320960; Write bytes: 199196672; Read time: 15912; Write time: 375 02:30:01 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 1130496; Write bytes: 305674240; Read time: 11; Write time: 1367 02:30:02 INFO - Running post-run listener: _upload_blobber_files 02:30:02 INFO - Blob upload gear active. 02:30:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:30:02 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 02:30:02 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:30:02 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:30:02 INFO - (blobuploader) - INFO - Open directory for files ... 02:30:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:30:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:30:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:30:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:30:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:30:03 INFO - (blobuploader) - INFO - Done attempting. 02:30:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:30:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:30:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:30:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:30:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:30:04 INFO - (blobuploader) - INFO - Done attempting. 02:30:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:30:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:30:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:30:05 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:30:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:30:05 INFO - (blobuploader) - INFO - Done attempting. 02:30:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:30:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:30:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:30:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:30:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:30:08 INFO - (blobuploader) - INFO - Done attempting. 02:30:08 INFO - (blobuploader) - INFO - Iteration through files over. 02:30:08 INFO - Return code: 0 02:30:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:30:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:30:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e40ef75714a1e935523deae6d4566c8d0e7a7bcfff3c29980bb93b79d9dd1ebb82623aca81d17676a72c2789badad70edca911c855a6109c18b7c17826a1e1c3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d2d08d715ed0b1d1f09cc3c7eafd4841a885e45d7420079fb207649c0d19fed95e9764be6341901f34bb51aacc14891b725416ee82f19910a77a6660437d6332", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d9010cb02666f18f7d35d72398a34224fe16ce8f855ff0672548495dbdd075a0d9a07b8b39e3983254201b2ee699a8721ad7d26efb3b05fd4f40ecfd1f830d90", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1734c7031f8bd629e06b3b59484e0ec8af742355212839d4eb6e3aa494ecb2c1e9ef0a4a251146fc69f725570c2d10e28a3cb56dac419a8fa3c90e19b71a5223"} 02:30:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:30:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:30:08 INFO - Contents: 02:30:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e40ef75714a1e935523deae6d4566c8d0e7a7bcfff3c29980bb93b79d9dd1ebb82623aca81d17676a72c2789badad70edca911c855a6109c18b7c17826a1e1c3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d2d08d715ed0b1d1f09cc3c7eafd4841a885e45d7420079fb207649c0d19fed95e9764be6341901f34bb51aacc14891b725416ee82f19910a77a6660437d6332", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d9010cb02666f18f7d35d72398a34224fe16ce8f855ff0672548495dbdd075a0d9a07b8b39e3983254201b2ee699a8721ad7d26efb3b05fd4f40ecfd1f830d90", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1734c7031f8bd629e06b3b59484e0ec8af742355212839d4eb6e3aa494ecb2c1e9ef0a4a251146fc69f725570c2d10e28a3cb56dac419a8fa3c90e19b71a5223"} 02:30:08 INFO - Running post-run listener: copy_logs_to_upload_dir 02:30:08 INFO - Copying logs to upload dir... 02:30:08 INFO - mkdir: /builds/slave/test/build/upload/logs 02:30:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.676507 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-25 02:30:08.380525) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-25 02:30:08.384062) ========= 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.HnAANJEr6z/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.CQBPHu2r8Z/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e40ef75714a1e935523deae6d4566c8d0e7a7bcfff3c29980bb93b79d9dd1ebb82623aca81d17676a72c2789badad70edca911c855a6109c18b7c17826a1e1c3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d2d08d715ed0b1d1f09cc3c7eafd4841a885e45d7420079fb207649c0d19fed95e9764be6341901f34bb51aacc14891b725416ee82f19910a77a6660437d6332", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d9010cb02666f18f7d35d72398a34224fe16ce8f855ff0672548495dbdd075a0d9a07b8b39e3983254201b2ee699a8721ad7d26efb3b05fd4f40ecfd1f830d90", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1734c7031f8bd629e06b3b59484e0ec8af742355212839d4eb6e3aa494ecb2c1e9ef0a4a251146fc69f725570c2d10e28a3cb56dac419a8fa3c90e19b71a5223"} build_url:https://queue.taskcluster.net/v1/task/HwTbpN5dSCGCWzDtgw8z4Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011233 build_url: 'https://queue.taskcluster.net/v1/task/HwTbpN5dSCGCWzDtgw8z4Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e40ef75714a1e935523deae6d4566c8d0e7a7bcfff3c29980bb93b79d9dd1ebb82623aca81d17676a72c2789badad70edca911c855a6109c18b7c17826a1e1c3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d2d08d715ed0b1d1f09cc3c7eafd4841a885e45d7420079fb207649c0d19fed95e9764be6341901f34bb51aacc14891b725416ee82f19910a77a6660437d6332", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d9010cb02666f18f7d35d72398a34224fe16ce8f855ff0672548495dbdd075a0d9a07b8b39e3983254201b2ee699a8721ad7d26efb3b05fd4f40ecfd1f830d90", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1734c7031f8bd629e06b3b59484e0ec8af742355212839d4eb6e3aa494ecb2c1e9ef0a4a251146fc69f725570c2d10e28a3cb56dac419a8fa3c90e19b71a5223"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-25 02:30:08.423861) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 02:30:08.424187) ========= 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.HnAANJEr6z/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.CQBPHu2r8Z/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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.005347 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 02:30:08.473863) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-25 02:30:08.474373) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-25 02:30:08.474938) ========= ========= Total master_lag: 0.22 =========