builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0352 starttime: 1492936839.91 results: success (0) buildid: 20170423004011 builduid: 394f09135bed4aaf80b4c0b7040cceb3 revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-23 01:40:39.910467) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-23 01:40:39.910921) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-23 01:40:39.911211) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-23 01:40:39.949258) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-23 01:40:39.949569) ========= 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.ITidXcYLed/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.Q4l8rMHFij/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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-04-23 01:40:39-- 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% 173M=0s 2017-04-23 01:40:40 (173 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.569065 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-23 01:40:40.542909) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-23 01:40:40.543226) ========= 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.ITidXcYLed/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.Q4l8rMHFij/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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.021710 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-23 01:40:40.591932) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-23 01:40:40.592244) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --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 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ITidXcYLed/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.Q4l8rMHFij/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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-04-23 01:40:40,712 truncating revision to first 12 chars 2017-04-23 01:40:40,712 Setting DEBUG logging. 2017-04-23 01:40:40,712 attempt 1/10 2017-04-23 01:40:40,712 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/94b7e538af7d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-23 01:40:41,375 unpacking tar archive at: mozilla-aurora-94b7e538af7d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.923728 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-23 01:40:41.540474) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-23 01:40:41.540802) ========= script_repo_revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-23 01:40:41.541184) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-23 01:40:41.541468) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-23 01:40:41.561869) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-23 01:40:41.562142) ========= /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.ITidXcYLed/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.Q4l8rMHFij/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 01:40:41 INFO - MultiFileLogger online at 20170423 01:40:41 in /builds/slave/test 01:40:41 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 01:40:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:40:41 INFO - {'append_to_log': False, 01:40:41 INFO - 'base_work_dir': '/builds/slave/test', 01:40:41 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 01:40:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:40:41 INFO - 'branch': 'Mozilla-Aurora', 01:40:41 INFO - 'buildbot_json_path': 'buildprops.json', 01:40:41 INFO - 'config_files': ('talos/mac_config.py',), 01:40:41 INFO - 'default_actions': ('clobber', 01:40:41 INFO - 'read-buildbot-config', 01:40:41 INFO - 'download-and-extract', 01:40:41 INFO - 'populate-webroot', 01:40:41 INFO - 'create-virtualenv', 01:40:41 INFO - 'install', 01:40:41 INFO - 'run-tests'), 01:40:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:40:41 INFO - 'download_minidump_stackwalk': True, 01:40:41 INFO - 'download_symbols': 'ondemand', 01:40:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:40:41 INFO - 'tooltool.py': '/tools/tooltool.py', 01:40:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:40:41 INFO - '/tools/misc-python/virtualenv.py')}, 01:40:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:40:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:40:41 INFO - 'gecko_profile': False, 01:40:41 INFO - 'gecko_profile_interval': 0, 01:40:41 INFO - 'installer_path': 'installer.exe', 01:40:41 INFO - 'log_level': 'info', 01:40:41 INFO - 'log_name': 'talos', 01:40:41 INFO - 'log_to_console': True, 01:40:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:40:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:40:41 INFO - 'opt_config_files': (), 01:40:41 INFO - 'pip_index': False, 01:40:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:40:41 INFO - 'cmd': ('bash', 01:40:41 INFO - '-c', 01:40:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:40:41 INFO - 'enabled': True, 01:40:41 INFO - 'halt_on_failure': False, 01:40:41 INFO - 'name': 'check_screen_resolution'},), 01:40:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:40:41 INFO - 'cmd': ('bash', 01:40:41 INFO - '-c', 01:40:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:40:41 INFO - 'enabled': True, 01:40:41 INFO - 'halt_on_failure': False, 01:40:41 INFO - 'name': 'check_screen_resolution'},), 01:40:41 INFO - 'run_cmd_checks_enabled': True, 01:40:41 INFO - 'suite': 'dromaeojs-e10s', 01:40:41 INFO - 'system_bits': '32', 01:40:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:40:41 INFO - 'title': 't-yosemite-r7-0352', 01:40:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:40:41 INFO - 'use_talos_json': True, 01:40:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:40:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:40:41 INFO - 'work_dir': 'build'} 01:40:41 INFO - [mozharness: 2017-04-23 08:40:41.802055Z] Running clobber step. 01:40:41 INFO - Running pre-action listener: _resource_record_pre_action 01:40:41 INFO - Running main action method: clobber 01:40:41 INFO - rmtree: /builds/slave/test/build 01:40:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:40:41 INFO - Running post-action listener: _resource_record_post_action 01:40:41 INFO - [mozharness: 2017-04-23 08:40:41.998841Z] Finished clobber step (success) 01:40:41 INFO - [mozharness: 2017-04-23 08:40:41.998985Z] Running read-buildbot-config step. 01:40:41 INFO - Running pre-action listener: _resource_record_pre_action 01:40:41 INFO - Running main action method: read_buildbot_config 01:40:41 INFO - Using buildbot properties: 01:40:41 INFO - { 01:40:42 INFO - "project": "", 01:40:42 INFO - "product": "firefox", 01:40:42 INFO - "script_repo_revision": "production", 01:40:42 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 01:40:42 INFO - "repository": "", 01:40:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 01:40:42 INFO - "buildid": "20170423004011", 01:40:42 INFO - "stage_platform": "macosx64", 01:40:42 INFO - "basedir": "/builds/slave/test", 01:40:42 INFO - "nightly_build": "True", 01:40:42 INFO - "buildnumber": 18, 01:40:42 INFO - "slavename": "t-yosemite-r7-0352", 01:40:42 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 01:40:42 INFO - "platform": "yosemite_r7", 01:40:42 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 01:40:42 INFO - "branch": "mozilla-aurora", 01:40:42 INFO - "repo_path": "releases/mozilla-aurora", 01:40:42 INFO - "revision": "94b7e538af7dbb9a6567cad9125c2f5411e7fb80", 01:40:42 INFO - "pgo_build": "False", 01:40:42 INFO - "builduid": "394f09135bed4aaf80b4c0b7040cceb3", 01:40:42 INFO - "slavebuilddir": "test" 01:40:42 INFO - } 01:40:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/Weo5WpOATtO__VTGyv8-1A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 01:40:42 INFO - Running post-action listener: _resource_record_post_action 01:40:42 INFO - [mozharness: 2017-04-23 08:40:42.002544Z] Finished read-buildbot-config step (success) 01:40:42 INFO - [mozharness: 2017-04-23 08:40:42.002675Z] Running download-and-extract step. 01:40:42 INFO - Running pre-action listener: _resource_record_pre_action 01:40:42 INFO - Running main action method: download_and_extract 01:40:42 INFO - mkdir: /builds/slave/test/build/tests 01:40:42 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')]} 01:40:42 INFO - trying https://queue.taskcluster.net/v1/task/Weo5WpOATtO__VTGyv8-1A/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 01:40:42 INFO - Downloading https://queue.taskcluster.net/v1/task/Weo5WpOATtO__VTGyv8-1A/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 01:40:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Weo5WpOATtO__VTGyv8-1A/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 01:40:43 INFO - Downloaded 1199 bytes. 01:40:43 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 01:40:43 INFO - Using the following test package requirements: 01:40:43 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 01:40:43 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:40:43 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 01:40:43 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:40:43 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 01:40:43 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:40:43 INFO - u'jsshell-mac.zip'], 01:40:43 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:40:43 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 01:40:43 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 01:40:43 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:40:43 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 01:40:43 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:40:43 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 01:40:43 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:40:43 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 01:40:43 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:40:43 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 01:40:43 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 01:40:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Weo5WpOATtO__VTGyv8-1A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 01:40:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Weo5WpOATtO__VTGyv8-1A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:40:43 INFO - Fetch https://queue.taskcluster.net/v1/task/Weo5WpOATtO__VTGyv8-1A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 01:40:44 INFO - Expected file size: 51791271 01:40:44 INFO - Obtained file size: 51791271 01:41:00 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 01:41:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Weo5WpOATtO__VTGyv8-1A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 01:41:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Weo5WpOATtO__VTGyv8-1A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:41:00 INFO - Fetch https://queue.taskcluster.net/v1/task/Weo5WpOATtO__VTGyv8-1A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 01:41:02 INFO - Expected file size: 51791271 01:41:02 INFO - Obtained file size: 51791271 01:41:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Weo5WpOATtO__VTGyv8-1A/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 01:41:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Weo5WpOATtO__VTGyv8-1A/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 01:41:17 INFO - Fetch https://queue.taskcluster.net/v1/task/Weo5WpOATtO__VTGyv8-1A/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 01:41:19 INFO - Expected file size: 14404424 01:41:19 INFO - Obtained file size: 14404424 01:41:19 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')]} 01:41:19 INFO - trying https://queue.taskcluster.net/v1/task/Weo5WpOATtO__VTGyv8-1A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:41:19 INFO - Downloading https://queue.taskcluster.net/v1/task/Weo5WpOATtO__VTGyv8-1A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 01:41:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Weo5WpOATtO__VTGyv8-1A/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 01:41:21 INFO - Downloaded 74781078 bytes. 01:41:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Weo5WpOATtO__VTGyv8-1A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:41:21 INFO - mkdir: /builds/slave/test/properties 01:41:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:41:21 INFO - Writing to file /builds/slave/test/properties/build_url 01:41:21 INFO - Contents: 01:41:21 INFO - build_url:https://queue.taskcluster.net/v1/task/Weo5WpOATtO__VTGyv8-1A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:41:22 INFO - Running post-action listener: _resource_record_post_action 01:41:22 INFO - Running post-action listener: set_extra_try_arguments 01:41:22 INFO - [mozharness: 2017-04-23 08:41:22.294949Z] Finished download-and-extract step (success) 01:41:22 INFO - [mozharness: 2017-04-23 08:41:22.295091Z] Running populate-webroot step. 01:41:22 INFO - Running pre-action listener: _resource_record_pre_action 01:41:22 INFO - Running main action method: populate_webroot 01:41:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:41:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:41:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:41:22 INFO - '--tppagecycles', 01:41:22 INFO - '7'], 01:41:22 INFO - 'tests': ['tsvgm']}}, 01:41:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:41:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:41:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:41:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:41:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:41:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:41:22 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:41:22 INFO - 'talos_options': ['--disable-e10s'], 01:41:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:41:22 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:41:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:41:22 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:41:22 INFO - 'talos_options': ['--disable-e10s'], 01:41:22 INFO - 'tests': ['damp', 'tps']}, 01:41:22 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:41:22 INFO - 'tests': ['damp', 'tps']}, 01:41:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:41:22 INFO - 'tests': ['dromaeo_dom']}, 01:41:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:41:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:41:22 INFO - 'tests': ['basic_compositor_video']}, 01:41:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:41:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:41:22 INFO - 'tests': ['a11yr', 01:41:22 INFO - 'ts_paint', 01:41:22 INFO - 'tpaint', 01:41:22 INFO - 'sessionrestore', 01:41:22 INFO - 'sessionrestore_no_auto_restore', 01:41:22 INFO - 'tabpaint']}, 01:41:22 INFO - 'other-e10s': {'tests': ['a11yr', 01:41:22 INFO - 'ts_paint', 01:41:22 INFO - 'tpaint', 01:41:22 INFO - 'sessionrestore', 01:41:22 INFO - 'sessionrestore_no_auto_restore', 01:41:22 INFO - 'tabpaint']}, 01:41:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:41:22 INFO - 'ts_paint', 01:41:22 INFO - 'tpaint', 01:41:22 INFO - 'sessionrestore', 01:41:22 INFO - 'sessionrestore_no_auto_restore', 01:41:22 INFO - 'tabpaint']}, 01:41:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:41:22 INFO - 'ts_paint', 01:41:22 INFO - 'tpaint', 01:41:22 INFO - 'sessionrestore', 01:41:22 INFO - 'sessionrestore_no_auto_restore', 01:41:22 INFO - 'tabpaint']}, 01:41:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:41:22 INFO - 'tests': ['a11yr', 01:41:22 INFO - 'ts_paint', 01:41:22 INFO - 'tpaint', 01:41:22 INFO - 'sessionrestore', 01:41:22 INFO - 'sessionrestore_no_auto_restore', 01:41:22 INFO - 'tabpaint']}, 01:41:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:41:22 INFO - 'tests': ['a11yr', 01:41:22 INFO - 'ts_paint', 01:41:22 INFO - 'tpaint', 01:41:22 INFO - 'sessionrestore', 01:41:22 INFO - 'sessionrestore_no_auto_restore', 01:41:22 INFO - 'tabpaint']}, 01:41:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:41:22 INFO - 'tests': ['tsvgx', 01:41:22 INFO - 'tsvgr_opacity', 01:41:22 INFO - 'tart', 01:41:22 INFO - 'tscrollx', 01:41:22 INFO - 'cart', 01:41:22 INFO - 'tsvg_static']}, 01:41:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:41:22 INFO - 'tsvgr_opacity', 01:41:22 INFO - 'tart', 01:41:22 INFO - 'tscrollx', 01:41:22 INFO - 'cart', 01:41:22 INFO - 'tsvg_static']}, 01:41:22 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:41:22 INFO - 'talos_options': ['--disable-e10s'], 01:41:22 INFO - 'tests': ['tp5o']}, 01:41:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:41:22 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:41:22 INFO - 'talos_options': ['--disable-e10s', 01:41:22 INFO - '--xperf_path', 01:41:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:41:22 INFO - 'tests': ['tp5n']}, 01:41:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:41:22 INFO - 'talos_options': ['--xperf_path', 01:41:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:41:22 INFO - 'tests': ['tp5n']}}, 01:41:22 INFO - 'talos.zip': {'path': '', 01:41:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:41:22 INFO - Running post-action listener: _resource_record_post_action 01:41:22 INFO - [mozharness: 2017-04-23 08:41:22.313645Z] Finished populate-webroot step (success) 01:41:22 INFO - [mozharness: 2017-04-23 08:41:22.313781Z] Running create-virtualenv step. 01:41:22 INFO - Running pre-action listener: _resource_record_pre_action 01:41:22 INFO - Running main action method: create_virtualenv 01:41:22 INFO - Creating virtualenv /builds/slave/test/build/venv 01:41:22 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 01:41:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:41:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:41:22 INFO - Using real prefix '/tools/python27' 01:41:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:41:23 INFO - Installing distribute.............................................................................................................................................................................................done. 01:41:26 INFO - Installing pip.................done. 01:41:26 INFO - Return code: 0 01:41:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:41:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:41:26 INFO - Reading from file tmpfile_stdout 01:41:26 INFO - Output received: 01:41:26 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) 01:41:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:41:26 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')]} 01:41:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:26 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')]} 01:41:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:26 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 0x105a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8323551120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105820d30>, '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 0x105747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105815be8>, '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.ITidXcYLed/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.Q4l8rMHFij/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 01:41:26 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 01:41:26 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 01:41:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITidXcYLed/Render', 01:41:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:26 INFO - 'HOME': '/Users/cltbld', 01:41:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:26 INFO - 'LOGNAME': 'cltbld', 01:41:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:26 INFO - 'MOZ_NO_REMOTE': '1', 01:41:26 INFO - 'NO_EM_RESTART': '1', 01:41:26 INFO - 'PAGER': '/bin/cat', 01:41:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:26 INFO - 'PWD': '/builds/slave/test', 01:41:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:26 INFO - 'SHELL': '/bin/bash', 01:41:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q4l8rMHFij/Listeners', 01:41:26 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 01:41:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:26 INFO - 'USER': 'cltbld', 01:41:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:26 INFO - 'XPC_FLAGS': '0x0', 01:41:26 INFO - 'XPC_SERVICE_NAME': '0', 01:41:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:26 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 01:41:26 INFO - Cleaning up... 01:41:26 INFO - Return code: 0 01:41:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:41:26 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')]} 01:41:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:26 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')]} 01:41:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:26 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 0x105a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8323551120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105820d30>, '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 0x105747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105815be8>, '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.ITidXcYLed/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.Q4l8rMHFij/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 01:41:26 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 01:41:26 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 01:41:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITidXcYLed/Render', 01:41:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:26 INFO - 'HOME': '/Users/cltbld', 01:41:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:26 INFO - 'LOGNAME': 'cltbld', 01:41:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:26 INFO - 'MOZ_NO_REMOTE': '1', 01:41:26 INFO - 'NO_EM_RESTART': '1', 01:41:26 INFO - 'PAGER': '/bin/cat', 01:41:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:26 INFO - 'PWD': '/builds/slave/test', 01:41:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:26 INFO - 'SHELL': '/bin/bash', 01:41:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q4l8rMHFij/Listeners', 01:41:26 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 01:41:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:26 INFO - 'USER': 'cltbld', 01:41:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:26 INFO - 'XPC_FLAGS': '0x0', 01:41:26 INFO - 'XPC_SERVICE_NAME': '0', 01:41:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:26 INFO - Downloading/unpacking psutil>=3.1.1 01:41:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:26 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 01:41:26 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 01:41:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:41:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:41:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:41:30 INFO - Installing collected packages: psutil 01:41:30 INFO - Running setup.py install for psutil 01:41:30 INFO - building 'psutil._psutil_osx' extension 01:41:30 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 01:41:31 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 01:41:31 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 01:41:31 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 01:41:31 INFO - building 'psutil._psutil_posix' extension 01:41:31 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 01:41:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:41:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:41:31 INFO - ^ 01:41:31 INFO - 1 warning generated. 01:41:31 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 01:41:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:41:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:41:31 INFO - Successfully installed psutil 01:41:31 INFO - Cleaning up... 01:41:31 INFO - Return code: 0 01:41:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:41:31 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')]} 01:41:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:31 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')]} 01:41:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x105a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8323551120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105820d30>, '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 0x105747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105815be8>, '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.ITidXcYLed/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.Q4l8rMHFij/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 01:41:31 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 01:41:31 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 01:41:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITidXcYLed/Render', 01:41:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:31 INFO - 'HOME': '/Users/cltbld', 01:41:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:31 INFO - 'LOGNAME': 'cltbld', 01:41:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:31 INFO - 'MOZ_NO_REMOTE': '1', 01:41:31 INFO - 'NO_EM_RESTART': '1', 01:41:31 INFO - 'PAGER': '/bin/cat', 01:41:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:31 INFO - 'PWD': '/builds/slave/test', 01:41:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:31 INFO - 'SHELL': '/bin/bash', 01:41:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q4l8rMHFij/Listeners', 01:41:31 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 01:41:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:31 INFO - 'USER': 'cltbld', 01:41:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:31 INFO - 'XPC_FLAGS': '0x0', 01:41:31 INFO - 'XPC_SERVICE_NAME': '0', 01:41:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:41:31 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 01:41:31 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 01:41:31 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 01:41:31 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 01:41:31 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 01:41:31 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 01:41:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:41:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:41:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:41:34 INFO - Installing collected packages: mozsystemmonitor 01:41:34 INFO - Running setup.py install for mozsystemmonitor 01:41:34 INFO - Successfully installed mozsystemmonitor 01:41:34 INFO - Cleaning up... 01:41:34 INFO - Return code: 0 01:41:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:41:34 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')]} 01:41:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:34 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')]} 01:41:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x105a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8323551120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105820d30>, '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 0x105747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105815be8>, '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.ITidXcYLed/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.Q4l8rMHFij/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 01:41:34 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 01:41:34 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 01:41:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITidXcYLed/Render', 01:41:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:34 INFO - 'HOME': '/Users/cltbld', 01:41:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:34 INFO - 'LOGNAME': 'cltbld', 01:41:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:34 INFO - 'MOZ_NO_REMOTE': '1', 01:41:34 INFO - 'NO_EM_RESTART': '1', 01:41:34 INFO - 'PAGER': '/bin/cat', 01:41:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:34 INFO - 'PWD': '/builds/slave/test', 01:41:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:34 INFO - 'SHELL': '/bin/bash', 01:41:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q4l8rMHFij/Listeners', 01:41:34 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 01:41:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:34 INFO - 'USER': 'cltbld', 01:41:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:34 INFO - 'XPC_FLAGS': '0x0', 01:41:34 INFO - 'XPC_SERVICE_NAME': '0', 01:41:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:35 INFO - Downloading/unpacking jsonschema==2.5.1 01:41:35 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 01:41:35 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 01:41:35 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 01:41:35 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 01:41:35 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 01:41:35 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 01:41:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:41:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:41:37 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 01:41:37 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 01:41:37 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 01:41:37 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 01:41:37 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 01:41:37 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 01:41:38 INFO - Downloading functools32-3.2.3-2.tar.gz 01:41:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:41:38 INFO - warning: no files found matching '*.txt' 01:41:38 INFO - no previously-included directories found matching 'build' 01:41:38 INFO - no previously-included directories found matching 'dist' 01:41:38 INFO - no previously-included directories found matching '.git*' 01:41:38 INFO - Installing collected packages: jsonschema, functools32 01:41:38 INFO - Running setup.py install for functools32 01:41:38 INFO - warning: no files found matching '*.txt' 01:41:38 INFO - no previously-included directories found matching 'build' 01:41:38 INFO - no previously-included directories found matching 'dist' 01:41:38 INFO - no previously-included directories found matching '.git*' 01:41:38 INFO - Successfully installed jsonschema functools32 01:41:38 INFO - Cleaning up... 01:41:38 INFO - Return code: 0 01:41:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:41:38 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')]} 01:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:38 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')]} 01:41:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:38 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 0x105a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8323551120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105820d30>, '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 0x105747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105815be8>, '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.ITidXcYLed/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.Q4l8rMHFij/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 01:41:38 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 01:41:38 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 01:41:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITidXcYLed/Render', 01:41:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:38 INFO - 'HOME': '/Users/cltbld', 01:41:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:38 INFO - 'LOGNAME': 'cltbld', 01:41:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:38 INFO - 'MOZ_NO_REMOTE': '1', 01:41:38 INFO - 'NO_EM_RESTART': '1', 01:41:38 INFO - 'PAGER': '/bin/cat', 01:41:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:38 INFO - 'PWD': '/builds/slave/test', 01:41:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:38 INFO - 'SHELL': '/bin/bash', 01:41:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q4l8rMHFij/Listeners', 01:41:38 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 01:41:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:38 INFO - 'USER': 'cltbld', 01:41:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:38 INFO - 'XPC_FLAGS': '0x0', 01:41:38 INFO - 'XPC_SERVICE_NAME': '0', 01:41:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:41:38 INFO - Cleaning up... 01:41:38 INFO - Return code: 0 01:41:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:41:38 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')]} 01:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:38 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')]} 01:41:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:38 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 0x105a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8323551120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105820d30>, '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 0x105747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105815be8>, '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.ITidXcYLed/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.Q4l8rMHFij/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 01:41:38 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 01:41:38 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 01:41:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITidXcYLed/Render', 01:41:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:38 INFO - 'HOME': '/Users/cltbld', 01:41:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:38 INFO - 'LOGNAME': 'cltbld', 01:41:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:38 INFO - 'MOZ_NO_REMOTE': '1', 01:41:38 INFO - 'NO_EM_RESTART': '1', 01:41:38 INFO - 'PAGER': '/bin/cat', 01:41:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:38 INFO - 'PWD': '/builds/slave/test', 01:41:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:38 INFO - 'SHELL': '/bin/bash', 01:41:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q4l8rMHFij/Listeners', 01:41:38 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 01:41:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:38 INFO - 'USER': 'cltbld', 01:41:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:38 INFO - 'XPC_FLAGS': '0x0', 01:41:38 INFO - 'XPC_SERVICE_NAME': '0', 01:41:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:39 INFO - Downloading/unpacking blobuploader==1.2.4 01:41:39 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 01:41:39 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 01:41:39 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 01:41:39 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 01:41:39 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 01:41:39 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 01:41:41 INFO - Downloading blobuploader-1.2.4.tar.gz 01:41:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:41:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:41: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 01:41: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 01:41: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 01:41: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 01:41: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 01:41: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 01:41:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:41:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:42 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 01:41:42 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 01:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:43 INFO - Downloading docopt-0.6.1.tar.gz 01:41:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:41:43 INFO - Installing collected packages: blobuploader, requests, docopt 01:41:43 INFO - Running setup.py install for blobuploader 01:41:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:41:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:41:43 INFO - Running setup.py install for requests 01:41:43 INFO - Running setup.py install for docopt 01:41:43 INFO - Successfully installed blobuploader requests docopt 01:41:43 INFO - Cleaning up... 01:41:44 INFO - Return code: 0 01:41:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:41: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')]} 01:41:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41: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')]} 01:41:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:44 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 0x105a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8323551120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105820d30>, '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 0x105747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105815be8>, '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.ITidXcYLed/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.Q4l8rMHFij/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 01:41:44 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 01:41:44 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 01:41:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITidXcYLed/Render', 01:41:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:44 INFO - 'HOME': '/Users/cltbld', 01:41:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:44 INFO - 'LOGNAME': 'cltbld', 01:41:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:44 INFO - 'MOZ_NO_REMOTE': '1', 01:41:44 INFO - 'NO_EM_RESTART': '1', 01:41:44 INFO - 'PAGER': '/bin/cat', 01:41:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:44 INFO - 'PWD': '/builds/slave/test', 01:41:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:44 INFO - 'SHELL': '/bin/bash', 01:41:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q4l8rMHFij/Listeners', 01:41:44 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 01:41:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:44 INFO - 'USER': 'cltbld', 01:41:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:44 INFO - 'XPC_FLAGS': '0x0', 01:41:44 INFO - 'XPC_SERVICE_NAME': '0', 01:41:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:41:44 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-26jVSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:41:44 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-IeBpXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:41:44 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-hLskvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:41:44 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-uISXr2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:41:44 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-uk_i3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:41:44 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-FdK3im-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:41:44 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-KEm0O8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:41:45 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-p3nXQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:41:45 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-LMWUEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:41:45 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-hQmdpM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:41:45 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-9fecuz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:41:45 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-7Anbf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:41:45 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-do8yXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:41:45 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-HK61vm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:41:45 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-SgMkbY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:41:45 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-c159d6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:41:45 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-dfFUJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:41:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:41:46 INFO - Running setup.py install for manifestparser 01:41:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:41:46 INFO - Running setup.py install for mozcrash 01:41:46 INFO - Running setup.py install for mozdebug 01:41:46 INFO - Running setup.py install for mozdevice 01:41:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:41:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:41:46 INFO - Running setup.py install for mozfile 01:41:46 INFO - Running setup.py install for mozhttpd 01:41:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:41:46 INFO - Running setup.py install for mozinfo 01:41:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:41:47 INFO - Running setup.py install for mozInstall 01:41:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:41:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:41:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:41:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:41:47 INFO - Running setup.py install for mozleak 01:41:47 INFO - Running setup.py install for mozlog 01:41:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:41:47 INFO - Running setup.py install for moznetwork 01:41:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:41:47 INFO - Running setup.py install for mozprocess 01:41:47 INFO - Running setup.py install for mozprofile 01:41:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:41:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:41:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:41:48 INFO - Running setup.py install for mozrunner 01:41:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:41:48 INFO - Running setup.py install for mozscreenshot 01:41:48 INFO - Running setup.py install for moztest 01:41:48 INFO - Running setup.py install for mozversion 01:41:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:41:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:41:48 INFO - Cleaning up... 01:41:48 INFO - Return code: 0 01:41:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:41:48 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')]} 01:41:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:48 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')]} 01:41:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:48 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 0x105a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8323551120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105820d30>, '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 0x105747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105815be8>, '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.ITidXcYLed/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.Q4l8rMHFij/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 01:41:48 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 01:41:48 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 01:41:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITidXcYLed/Render', 01:41:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:48 INFO - 'HOME': '/Users/cltbld', 01:41:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:48 INFO - 'LOGNAME': 'cltbld', 01:41:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:48 INFO - 'MOZ_NO_REMOTE': '1', 01:41:48 INFO - 'NO_EM_RESTART': '1', 01:41:48 INFO - 'PAGER': '/bin/cat', 01:41:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:48 INFO - 'PWD': '/builds/slave/test', 01:41:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:48 INFO - 'SHELL': '/bin/bash', 01:41:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q4l8rMHFij/Listeners', 01:41:48 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 01:41:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:48 INFO - 'USER': 'cltbld', 01:41:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:48 INFO - 'XPC_FLAGS': '0x0', 01:41:48 INFO - 'XPC_SERVICE_NAME': '0', 01:41:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:41:48 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-v1QsSN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:41:49 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)) 01:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:41:49 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-a_UIuu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:41:49 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)) 01:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:41:49 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-ahaUCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:41:49 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)) 01:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:41:49 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-p2QkpP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:41:49 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)) 01:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:41:49 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-7NiH6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:41:49 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)) 01:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:41:49 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-isAJYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:41:49 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)) 01:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:41:49 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-wnn7ql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:41:49 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)) 01:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:41:49 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-uIw7eX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:41:49 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)) 01:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:41:49 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-dibkGu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:41:49 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)) 01:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:41:49 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-qklkvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:41:50 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)) 01:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:41:50 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-cYkK73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:41:50 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)) 01:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:41:50 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-tWJU53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:41:50 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)) 01:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:41:50 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-Wn1e6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:41:50 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)) 01:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:41:50 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-w64Xh3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:41:50 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)) 01:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:41:50 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-2uvbva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:41:50 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)) 01:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:41:50 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-94eRcy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:41:50 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)) 01:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:41:50 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-Wd0bBq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:41:50 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)) 01:41:50 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)) 01:41:50 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)) 01:41:50 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)) 01:41:50 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)) 01:41:50 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)) 01:41:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:41:50 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 01:41:50 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 01:41:50 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 01:41:50 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 01:41:50 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 01:41:50 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 01:41:53 INFO - Downloading blessings-1.6.tar.gz 01:41:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:41:53 INFO - Installing collected packages: blessings 01:41:53 INFO - Running setup.py install for blessings 01:41:53 INFO - Successfully installed blessings 01:41:53 INFO - Cleaning up... 01:41:53 INFO - Return code: 0 01:41:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:41:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:41:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:41:54 INFO - Reading from file tmpfile_stdout 01:41:54 INFO - Current package versions: 01:41:54 INFO - blessings == 1.6 01:41:54 INFO - blobuploader == 1.2.4 01:41:54 INFO - docopt == 0.6.1 01:41:54 INFO - functools32 == 3.2.3-2 01:41:54 INFO - jsonschema == 2.5.1 01:41:54 INFO - manifestparser == 1.1 01:41:54 INFO - mozInstall == 1.12 01:41:54 INFO - mozcrash == 1.0 01:41:54 INFO - mozdebug == 0.1 01:41:54 INFO - mozdevice == 0.49 01:41:54 INFO - mozfile == 1.2 01:41:54 INFO - mozhttpd == 0.7 01:41:54 INFO - mozinfo == 0.9 01:41:54 INFO - mozleak == 0.1 01:41:54 INFO - mozlog == 3.4 01:41:54 INFO - moznetwork == 0.27 01:41:54 INFO - mozprocess == 0.25 01:41:54 INFO - mozprofile == 0.28 01:41:54 INFO - mozrunner == 6.13 01:41:54 INFO - mozscreenshot == 0.1 01:41:54 INFO - mozsystemmonitor == 0.3 01:41:54 INFO - moztest == 0.8 01:41:54 INFO - mozversion == 1.4 01:41:54 INFO - psutil == 3.1.1 01:41:54 INFO - requests == 1.2.3 01:41:54 INFO - wsgiref == 0.1.2 01:41:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:41: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')]} 01:41:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41: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')]} 01:41:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:54 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 0x105a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8323551120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105820d30>, '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 0x105747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105815be8>, '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.ITidXcYLed/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.Q4l8rMHFij/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 01:41:54 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 01:41:54 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 01:41:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITidXcYLed/Render', 01:41:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:54 INFO - 'HOME': '/Users/cltbld', 01:41:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:54 INFO - 'LOGNAME': 'cltbld', 01:41:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:54 INFO - 'MOZ_NO_REMOTE': '1', 01:41:54 INFO - 'NO_EM_RESTART': '1', 01:41:54 INFO - 'PAGER': '/bin/cat', 01:41:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:54 INFO - 'PWD': '/builds/slave/test', 01:41:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:54 INFO - 'SHELL': '/bin/bash', 01:41:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q4l8rMHFij/Listeners', 01:41:54 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 01:41:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:54 INFO - 'USER': 'cltbld', 01:41:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:54 INFO - 'XPC_FLAGS': '0x0', 01:41:54 INFO - 'XPC_SERVICE_NAME': '0', 01:41:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:54 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)) 01:41:54 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)) 01:41:54 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)) 01:41:54 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)) 01:41:54 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)) 01:41:54 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)) 01:41:54 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)) 01:41:54 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)) 01:41:54 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)) 01:41:54 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)) 01:41:54 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)) 01:41:54 INFO - Cleaning up... 01:41:54 INFO - Return code: 0 01:41:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:41: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')]} 01:41:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41: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')]} 01:41:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:54 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 0x105a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8323551120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105820d30>, '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 0x105747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105815be8>, '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.ITidXcYLed/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.Q4l8rMHFij/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 01:41:54 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 01:41:54 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 01:41:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITidXcYLed/Render', 01:41:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:54 INFO - 'HOME': '/Users/cltbld', 01:41:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:54 INFO - 'LOGNAME': 'cltbld', 01:41:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:54 INFO - 'MOZ_NO_REMOTE': '1', 01:41:54 INFO - 'NO_EM_RESTART': '1', 01:41:54 INFO - 'PAGER': '/bin/cat', 01:41:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:54 INFO - 'PWD': '/builds/slave/test', 01:41:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:54 INFO - 'SHELL': '/bin/bash', 01:41:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q4l8rMHFij/Listeners', 01:41:54 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 01:41:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:54 INFO - 'USER': 'cltbld', 01:41:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:54 INFO - 'XPC_FLAGS': '0x0', 01:41:54 INFO - 'XPC_SERVICE_NAME': '0', 01:41:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:41:54 INFO - Cleaning up... 01:41:54 INFO - Return code: 0 01:41:54 INFO - Running post-action listener: _resource_record_post_action 01:41:54 INFO - Running post-action listener: _start_resource_monitoring 01:41:54 INFO - Starting resource monitoring. 01:41:54 INFO - [mozharness: 2017-04-23 08:41:54.663184Z] Finished create-virtualenv step (success) 01:41:54 INFO - [mozharness: 2017-04-23 08:41:54.664159Z] Running install step. 01:41:54 INFO - Running pre-action listener: _resource_record_pre_action 01:41:54 INFO - Running main action method: install 01:41:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:41:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:41:54 INFO - Reading from file tmpfile_stdout 01:41:54 INFO - Detecting whether we're running mozinstall >=1.0... 01:41:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:41:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:41:55 INFO - Reading from file tmpfile_stdout 01:41:55 INFO - Output received: 01:41:55 INFO - Usage: mozinstall [options] installer 01:41:55 INFO - Options: 01:41:55 INFO - -h, --help show this help message and exit 01:41:55 INFO - -d DEST, --destination=DEST 01:41:55 INFO - Directory to install application into. [default: 01:41:55 INFO - "/builds/slave/test"] 01:41:55 INFO - --app=APP Application being installed. [default: firefox] 01:41:55 INFO - mkdir: /builds/slave/test/build/application 01:41:55 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'] 01:41:55 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 01:42:15 INFO - Reading from file tmpfile_stdout 01:42:15 INFO - Output received: 01:42:15 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 01:42:15 INFO - Running post-action listener: _resource_record_post_action 01:42:15 INFO - [mozharness: 2017-04-23 08:42:15.843432Z] Finished install step (success) 01:42:15 INFO - [mozharness: 2017-04-23 08:42:15.843562Z] Running run-tests step. 01:42:15 INFO - Running pre-action listener: _resource_record_pre_action 01:42:15 INFO - Running main action method: run_tests 01:42:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:42:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:42:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:42:15 INFO - 2017-04-23 01:42:15.857 screenresolution[1903:12040] starting screenresolution argv=screenresolution get 01:42:15 INFO - 2017-04-23 01:42:15.860 screenresolution[1903:12040] Display 0: 1600x1200x32@60 01:42:15 INFO - 2017-04-23 01:42:15.866 screenresolution[1904:12043] starting screenresolution argv=screenresolution list 01:42:15 INFO - Available Modes on Display 0 01:42:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:42:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:42:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:42:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:42:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:42:16 INFO - Intel Iris: 01:42:16 INFO - Chipset Model: Intel Iris 01:42:16 INFO - Type: GPU 01:42:16 INFO - Bus: Built-In 01:42:16 INFO - VRAM (Dynamic, Max): 1536 MB 01:42:16 INFO - Vendor: Intel (0x8086) 01:42:16 INFO - Device ID: 0x0a2e 01:42:16 INFO - Revision ID: 0x0009 01:42:16 INFO - Displays: 01:42:16 INFO - Display: 01:42:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:42:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:42:16 INFO - Display Serial Number: 1600x1200 60 01:42:16 INFO - Main Display: Yes 01:42:16 INFO - Mirror: Off 01:42:16 INFO - Online: Yes 01:42:16 INFO - Rotation: Supported 01:42:16 INFO - Return code: 0 01:42:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:42:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:42:16 INFO - Python 2.7.3 01:42:16 INFO - Return code: 0 01:42:16 INFO - grabbing minidump binary from tooltool 01:42:16 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')]} 01:42:16 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 0x105820d30>, '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 0x105747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105815be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:42:16 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 01:42:16 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 01:42:16 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 01:42:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:42:16 INFO - Return code: 0 01:42:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:42:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:42:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:42:16 INFO - ENV: RUST_BACKTRACE is now 1 01:42:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:42:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:42:16 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/Weo5WpOATtO__VTGyv8-1A/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0352', '--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 01:42:16 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/Weo5WpOATtO__VTGyv8-1A/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0352 --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 01:42:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITidXcYLed/Render', 01:42:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:16 INFO - 'HOME': '/Users/cltbld', 01:42:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:16 INFO - 'LOGNAME': 'cltbld', 01:42:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:42:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:42:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:16 INFO - 'MOZ_NO_REMOTE': '1', 01:42:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:42:16 INFO - 'NO_EM_RESTART': '1', 01:42:16 INFO - 'PAGER': '/bin/cat', 01:42:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:16 INFO - 'PWD': '/builds/slave/test', 01:42:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:42:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:16 INFO - 'RUST_BACKTRACE': '1', 01:42:16 INFO - 'SHELL': '/bin/bash', 01:42:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q4l8rMHFij/Listeners', 01:42:16 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 01:42:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:16 INFO - 'USER': 'cltbld', 01:42:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:16 INFO - 'XPC_FLAGS': '0x0', 01:42:16 INFO - 'XPC_SERVICE_NAME': '0', 01:42:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:16 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/Weo5WpOATtO__VTGyv8-1A/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0352', '--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 01:42:16 INFO - mozversion application_buildid: 20170423004011 01:42:16 INFO - mozversion application_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 01:42:16 INFO - mozversion application_display_name: FirefoxDeveloperEdition 01:42:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:42:16 INFO - mozversion application_name: Firefox 01:42:16 INFO - mozversion application_remotingname: firefox-dev 01:42:16 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:42:16 INFO - mozversion application_vendor: Mozilla 01:42:16 INFO - mozversion application_version: 54.0a2 01:42:16 INFO - mozversion platform_buildid: 20170423004011 01:42:16 INFO - mozversion platform_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 01:42:16 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:42:16 INFO - mozversion platform_version: 54.0a2 01:42:16 INFO - using testdate: 1492936936 01:42:16 INFO - actual date: 1492936936 01:42:16 INFO - starting webserver on 'localhost:49425' 01:42:16 INFO - SUITE-START | Running 2 tests 01:42:16 INFO - TEST-START | dromaeo_css 01:42:16 INFO - operating with platform_type : mac_ 01:42:16 INFO - Initialising browser for dromaeo_css test... 01:42:16 INFO - TEST-INFO | started process 1914 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/tmpWncQRj/profile http://localhost:49425/getInfo.html) 01:42:19 INFO - PROCESS | 1914 | 2017-04-23 01:42:19.441 plugin-container[1917:12203] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4e47, name = 'com.apple.tsm.portname' 01:42:19 INFO - PROCESS | 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:42:19 INFO - PROCESS | 1914 | 2017-04-23 01:42:19.442 plugin-container[1917:12203] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9523, name = 'com.apple.CFPasteboardClient' 01:42:19 INFO - PROCESS | 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:42:19 INFO - PROCESS | 1914 | 2017-04-23 01:42:19.442 plugin-container[1917:12203] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:42:19 INFO - PROCESS | 1914 | __metrics Screen width/height:1600/1200 01:42:19 INFO - PROCESS | 1914 | colorDepth:24 01:42:19 INFO - PROCESS | 1914 | Browser inner width/height: 1024/689 01:42:19 INFO - PROCESS | 1914 | __metrics 01:42:19 ERROR - PROCESS | 1914 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 01:42:20 INFO - TEST-INFO | 1914: exit 0 01:42:20 INFO - Browser initialized. 01:42:20 INFO - Running cycle 1/1 for dromaeo_css test... 01:42:20 INFO - TEST-INFO | started process 1918 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/tmpWncQRj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:42:22 INFO - PROCESS | 1918 | 2017-04-23 01:42:22.803 plugin-container[1919:12332] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x453f, name = 'com.apple.tsm.portname' 01:42:22 INFO - PROCESS | 1918 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:42:22 INFO - PROCESS | 1918 | 2017-04-23 01:42:22.804 plugin-container[1919:12332] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 01:42:22 INFO - PROCESS | 1918 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:42:22 INFO - PROCESS | 1918 | 2017-04-23 01:42:22.804 plugin-container[1919:12332] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:42:32 INFO - PROCESS | 1918 | RSS: Main: 220581888 01:42:32 INFO - PROCESS | 1918 | 01:45:36 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:49425/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49425/tests/dromaeo/cssquery-ext.html) 01:45:38 INFO - PROCESS | 1918 | RSS: Main: 242200576 01:45:38 INFO - PROCESS | 1918 | 01:47:20 INFO - PROCESS | 1918 | ************************* 01:47:20 INFO - PROCESS | 1918 | A coding exception was thrown and uncaught in a Task. 01:47:20 INFO - PROCESS | 1918 | 01:47:20 ERROR - PROCESS | 1918 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:47:20 INFO - PROCESS | 1918 | Full stack: 01:47:20 INFO - PROCESS | 1918 | ************************* 01:47:20 INFO - PROCESS | 1918 | ************************* 01:47:20 INFO - PROCESS | 1918 | A coding exception was thrown and uncaught in a Task. 01:47:20 INFO - PROCESS | 1918 | 01:47:20 ERROR - PROCESS | 1918 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:47:20 INFO - PROCESS | 1918 | Full stack: 01:47:20 INFO - PROCESS | 1918 | ************************* 01:47:20 INFO - PROCESS | 1918 | ************************* 01:47:20 INFO - PROCESS | 1918 | A coding exception was thrown and uncaught in a Task. 01:47:20 INFO - PROCESS | 1918 | 01:47:20 ERROR - PROCESS | 1918 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:47:20 INFO - PROCESS | 1918 | Full stack: 01:47:20 INFO - PROCESS | 1918 | ************************* 01:47:20 INFO - PROCESS | 1918 | ************************* 01:47:20 INFO - PROCESS | 1918 | A coding exception was thrown and uncaught in a Task. 01:47:20 INFO - PROCESS | 1918 | 01:47:20 ERROR - PROCESS | 1918 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:47:20 INFO - PROCESS | 1918 | Full stack: 01:47:20 INFO - PROCESS | 1918 | ************************* 01:48:40 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:49425/tests/dromaeo/cssquery-ext.html (next: http://localhost:49425/tests/dromaeo/cssquery-jquery.html) 01:48:41 INFO - PROCESS | 1918 | RSS: Main: 249085952 01:48:41 INFO - PROCESS | 1918 | 01:51:43 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:49425/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49425/tests/dromaeo/cssquery-mootools.html) 01:51:44 INFO - PROCESS | 1918 | RSS: Main: 255729664 01:51:44 INFO - PROCESS | 1918 | 01:54:45 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:49425/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49425/tests/dromaeo/cssquery-prototype.html) 01:54:46 INFO - PROCESS | 1918 | RSS: Main: 251912192 01:54:46 INFO - PROCESS | 1918 | 01:57:49 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:49425/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49425/tests/dromaeo/cssquery-yui.html) 01:57:50 INFO - PROCESS | 1918 | RSS: Main: 257187840 01:57:50 INFO - PROCESS | 1918 | 02:00:53 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:49425/tests/dromaeo/cssquery-yui.html (next: http://localhost:49425/tests/dromaeo/cssquery-dojo.html) 02:00:53 INFO - PROCESS | 1918 | RSS: Main: 253317120 02:00:53 INFO - PROCESS | 1918 | 02:00:53 INFO - PROCESS | 1918 | __start_tp_report 02:00:53 INFO - PROCESS | 1918 | _x_x_mozilla_page_load 02:00:53 INFO - PROCESS | 1918 | _x_x_mozilla_page_load_details 02:00:53 INFO - PROCESS | 1918 | |i|pagename|runs| 02:00:53 INFO - PROCESS | 1918 | |0;dojo.html;3432.708219801317;3451.585809702836;3453.7064349530306;3457.7925324480516;3465.9653403465963;5951.345352011281;6013.188219590354;6018.037114061751;6086.391360863911;6093.542984276186;5984.222051133346;6090.48230900374;6092;6115.694215289229;6117.785877494289;5326.121190003654;5434.646747961389;5441.891162176754;5455.536279416255;5529.695866727348;4565.634749220071;5508.338999320067;5527.088030474987;5530.308711411074;5566.443355664442;5096;5279.736013199344;5306.09796334624;5401.297831281928;5415.864603384907;4969.683033995976;5216.973915130438;5485.451454854522;5489.231507588941;5493.890122197534;5348.491893270172;5414.756335964891;5424.566034717213;5455.263539422167;5482.5613950883835;5718.428157184248;5778.3066032076;5779.248697669276;5781.682007489587;5910.7931222407005;5149.922751158698;6234.563580549363;6239.906401403982;6410.038494225857;6440.4847612190915;6498.9350106498805;6506.967465162644;6507.3492650735025;6521.282658907564;6551.508636852208;6142.324344322121;6200.751969921198;6205.689715514206;6226.439620434183;6236.96881515594;6184.5052395808225;6312.621242725451;6382.266039405435;6462.838429039311;6480.773172938924;6301.590396624205;6306.3378345274;6313.273973493107;6317.273513545909;6337.6197428154455;6354.872902542016;6437.742490300336;6497.317781632955;6497.837554061091;6507.804765857032;6220.720067596969;6239.500839932794;6247.0629405589525;6261.686915654199;6369.33122022181;6225.470834978987;6242.531810114259;6275.246970363585;6364.968175159094;6406.615603063831;5165.535101840853;5167.586593072545;5176.5858731301405;5179.093658609803;5210.062188806051;4563.634909207255;4578.931316030262;4610.861674149781;4628.652851036186;4633.536646335407;5930.1994230779155;5943.078822782476;6029.427204415573;6059.787907423219;6137.232845894263;5429.782808687687;6362.363763623694;6366.108744775735;6493.805185844432;6497.415232629086;5058.696478211319;6154.415330543591;6159.1069294951985;6171.290301615371;6190.876182476325;5511.311086114236;5694.373618901917;5718.428157184248;5728.627639203439;5732.656040637575;6460.773872914519;6475.028745687996;6492.220933488012;6609.272979972103;6625.238097618835;6316;6444.677766111582;6449.96775016122;6453.806385808433;6481.449076828522;6639.568428052161;6656.134702488645;6664.900026499509;6668.699908504226;6675.599464032173;6181.072839074175;6231.22109736283;6252.968735156295;6264.029075493306;6296.968515157395;6447.0329450582785;6461.903071454026;6470.255920569195;6475.7733479328;6495.025746138117;5651.88696226065;6235.688215589294;6273.215848018997;6275.121482992294;6317.084022816574;5129;6179.351168127461;6198.81403557894;6204.7828326008375;6285.43431091204;5645.4072322405555;5869.647821130746;6117.694115294306;6159.599626024294;6265.686715664289;6188.319284878569;6271.905921411091;6285.371462853678;6296.84257893538;6308.558400912076;6112.442689633966;6150.846228844136;6186.752529898754;6241.188645476241;6256.124142620128 02:00:53 INFO - PROCESS | 1918 | |1;ext.html;701.9438444924407;729.5549714674048;807.2895851650549;936.5691781780381;946.0633972367358;38416.42375364372;39452;39472.407913881245;39611.80194099033;39697.404538931936;38712.83861484153;39051.804740976324;39084.80457597715;39145.21709565814;39215.80392098043;7909.525428474296;8050.476719013274;9642.807143857119;9956.900430995669;11003.559857605685;10760.73098172548;10783.406912619803;10857;10867.45662716861;10906.291091079094;10799.460026998659;10861.891381086167;10899.073578745776;10913.945430272837;10920.453977301142;10718.839217411705;10790.136789056896;10826.296290741117;10826.67519974402;10829.350238985608;10554.208434367452;10564.630237941636;10621.362718236931;10632.57469701211;10644.148468122532;33808.6619133808;33947.98156055322;34020.659793401996;34037;34172;3622.275544891006;3678.44823276508;3736.1220113273357;3751.1372384351753;3754.155315054118;18708;24631.87684061586;25525.744742552524;26384.340391490176;26418.339541511425;2952.8228306301476;3029.424409362214;3078.937766470564;3182.98408507956;3193.1218914798615;353957;354948;411333;421016;445205.7739711313;43420;43765.124697505875;43812.780936095754;43870.122597547874;43899;44766;44809.32786008212;44858;44882;45094.77452612716;13214.33928303581;13380.6654833628;13472.461101555828;13474.124181928339;13508.243928289456;11529.192956492965;11671.35807530577;11734;11767.88232117685;11784.351860647746;11842.28946263227;11887.227330223508;11908.404579770977;11970;12009.339486328336;5705.74324155414;5747.5114615257335;5751.734618383949;5765.827025189251;5785.739641716133;931.2549960031947;1056;1117.932924024545;1125.1561329003248;1189.7501524679742;19906.60186796285;26266.080687175858;29037.274068148043;29245.415091698047;29399.97100101529;960.1694534227843;989.5744829723144;1049.7270709615402;1059.1156384418941;1158.675570840166;14596.781048284285;15615.765763513557;15733.921330393276;15822.588860369993;16004.359825606845;454689.7265513651;457259;458629;459141;460209;57297.42702572921;57322.713386433636;57710.42289577134;57733.26800196001;57848.71075644679;1250.4060571228777;1417.9432822687183;1459.3870574358796;1461.123326004389;1461.817272840895;28349.574756378257;28657.996970105945;28666.283342916595;29546.556801647563;29730.554041689826;45956.31065533953;46364.536354636024;47484.812879678284;49381;49413.77638006226;415604.9219753882;417713;418415;418972;421363;26171.607425888247;26727.732722673303;30817;31960;32310;37038.44442333313;37148;37454.876353709435;38403.03992400101;38489.80755096319;26111;27341.589876152273;27510.587341189497;28129.296767580974;28594.285142871595;27481.45037099287;27823.721762782112;27985.720142799128;28323.291917702223;28477.288067798472;24748;32370.514442283857;32460.83769581137;32978.67021329756;33099.83450082734;28283.575746364233;29846.806127755513;30071;30076.398472030876;30646.693533065274;18904.109589040985;21159.98466234812;21769.78230217741;23222.651660224772;23464.882675586512 02:00:53 INFO - PROCESS | 1918 | |2;jquery.html;382.9430771071841;391.4519672458558;392.85482817586734;408.47918903398164;418.96857735669835;18731.719024214646;21253.36239912801;21330.46673833155;21432.03555839987;21542.676859847114;16959.915200424013;19538.90230548849;20991.895040524814;21111.261105861297;21327.146914123456;5606.299212598426;6004.429579189981;6437.2275326960835;6492.57798243115;6503.609783412987;6254.3120256771945;6391.616503009811;6468.644224567647;6545.869082618345;6588.868222635569;6506.479481641481;6512.837179070514;6513.967430162843;6523.249826269968;6549.181352330958;6179.691015449232;6450.4194622483965;6484.1894763154605;6502.804915852532;6519.9674001630165;5650.463205995424;6121.571489995702;6282.591631543981;6331;6411.3909178627955;25994.74005259942;25998.22005339843;26113.73886261151;26367.34081647955;26419.075332363467;2837.1630369040977;2934.5598160275918;2937.2803663102677;2938.14793709826;2950.5279155335256;16387.180640967905;16410.015399075975;16477.258523366476;16506.58733531659;16638.417655382003;1972.7336809530673;1995.3814317561603;2127.6702111172795;2144.3995681209285;2147.978520214809;130590;134625.326873366;138672;140698;142293.57706422906;22508;22663.660045099336;23194.188203413138;23236.883815580815;23317.88341058284;35677.64322356795;35883.28233435316;36227.09432264162;36351.091222719115;36453.08867278339;8967.730968070968;10912.345259284468;11180.60867869637;11291.040261577693;11501.13741469393;9055.547222638841;9059.320550958704;9069.09309069101;9226.26189904806;9248.16766491019;10869.891301086887;11180.329180249211;11235.71910702239;11316.490757915913;11337.319760814378;3579.928401431957;4323.2650449423045;4360.563943605614;4395.978020109943;4398.73607583546;1012.9828816828018;1021.3055122516612;1077.1167642533205;1095.271644356494;1099.6206308823444;18597.024476084032;22869.885650571974;22905.770942290364;23262.720550369893;23390.7660923392;1112.9109671226283;1117.8155604325195;1124.9550017999188;1145.799485090106;1158.5481662151767;12229.449674764657;12301.138920275675;12462.937685311515;12513.061520386007;12527.373631318398;595879;601472;721851;770313;779632;141622.87565686522;164528;168568;172022;175996;1188.5008296515484;1238.8017917133216;1268.3277862732766;1284.28722059258;1286;20008;20148.697769533763;20473.181072756925;20598.27906023282;20624.865632390363;60286.69856650864;60347.39652603418;60953;61350.386496134466;61392.38607614045;90819.54590227007;91470;92885.5355723217;93514.06485935325;94198;11440.313581185155;11457.885421145682;11816;12011.63965081049;12101.394930253628;12850.742985140432;12868.871311287141;13122.278274694983;13122.60632181036;13245.403956821774;8475.94050743657;8770.473771573726;11519.88480115188;11581.420928953687;11599.594014209462;10423.739406514595;10425;10465.581376744845;10719.785604288027;10753.677389678322;9537.952310238405;9901.475123843375;10121.544530495998;11701.824472633087;11780;11439.027682647069;11790.528378865092;11870.881291186977;11890.881091188978;11973.521059157885;11031.393273370042;11212.15908806859;11348.262362946381;11356.488957996744;11523.423828808694 02:00:53 INFO - PROCESS | 1918 | |3;mootools.html;1322.629663694166;1340.0284793524695;1465.7947887295777;1555.8599726024656;1604.2219523531085;10545.787234468029;11184.657841059074;11216.495257713403;11267.2676276042;11468.139889508278;11063.778724425507;11224.045956093742;11230.719232019204;11255.606053788119;11293.49179286932;2347.800436962856;2380.6310021946624;2405.7353691093967;2410.6986626671664;2412.7345991940874;1894.9956523042808;1947.9513012174739;2045.826104781088;2198.835087368454;2368.7157541095007;2305.4582173189287;2314.317276403465;2384.0225507541913;2387.224152150558;2397.0891061396646;1933.3523269704665;2275.9544809103727;2348.8473249238746;2352.6118190498546;2357.646353047031;2376.524695060995;2393.222202784102;2394.760523947609;2395.1257790906366;2403.71155461344;11030.724231894186;11030.779384412346;11079.446027698583;11092.889071109346;11160.944195279053;5775.711214439303;5836.795712150054;5939.46544810969;5948.672822994734;5949.494292985101;3320.020593924798;3378.6283508814013;3397.6602339766073;3491.1970246843116;3592.479090531858;1163.1625229834506;1184.3545267829054;1202.2666173634077;1208.6615747590602;1235.7157853693611;12650.493980240779;15450.227488625524;15478.303476343479;15550.922245388814;15641.921790391089;4435;4543.909121817611;4695.131400690815;4730.385049943486;4740.928886066643;11493.195476316578;11519.366434845997;11522.596709115141;11538.59614913474;11542.365169915567;5701.344345400249;5705.400932902068;5706.3722990471015;5713.314402271754;5713.74288157034;5042.369703787027;5075.8984820303385;5216.374035115735;5236.188390799432;5329.040772660958;3835.0412396900774;3934.15415685629;3945.3095708251517;3949.5063117110362;3999.1601763629383;887.3123329419751;1031.2368847053126;1031.7781676939494;1043.222799014739;1044.603050840691;781.4803155901262;797.6649807081039;809.4778867630463;815.5392203405057;817.9263866252065;6565.540412171198;6581;6679.9332006679315;6760;6780;836.2891542189091;867.2714919467677;976.3556053004983;986.2405947420447;989.2135752077116;4394.780260986938;4436.6672499562665;4489.573490518395;4626.4910859805395;4688.976555117202;13753;14015.159090454605;14182.219977901315;14194.219317937612;14219.573412797632;11872.643820685393;12312.938435307946;13010.154339967872;13480.325983700775;14145.858541414455;9729.027097290356;9850.802983940424;9980.750481238027;10059.19526437898;10065.849012264822;5806.9419305806405;6799.966000169968;6859.965700171667;7120.216776154515;7143.535670181422;12182.025437964765;12416;12487.375631218585;12492.937535312265;12665.493380264686;11905.345206013752;12199.390030498262;12805.743885122432;12881.227126372447;12948.352582370655;9127.863082053907;9256.25949924018;9291.256699464162;9319.254459643076;9357.85963210566;8689.478631282143;10194.235432342455;10216.642417515352;10225.335353202017;10296.17630589536;7441.367483763941;8756.211940925346;8827.558622068742;8858.379913406;8932.910670893469;8524.232819046067;8528.74413767588;8531.76684499461;8578.699745508877;8737.737867863974;7799.220077992155;7944.165862584346;7950.7217247396065;7981.281684648409;7981.561014144278;5807.622504537191;5819.0689489681445;5828.766849326149;5992.610480318765;6046.516278697782;5913.251689821097;5929.851753706192;6111.694415279129;6176.81469555914;6212.72042758086 02:00:53 INFO - PROCESS | 1918 | |4;prototype.html;365.3897990356105;367.5057048270076;369.07546595777575;384.66149788186374;385.8263781298415;4213.873583792491;5161.587073034158;5188;5241.633085683999;5307.911878064997;4386.903274181454;4723.622110231182;5171.456997015315;5191.5846732261425;5231.895362092756;3854.5952674969144;3865.246276975991;3972.8410863565423;4330.198913201052;4347.86956391308;3789.905252368685;3887.844486220548;4275.593818587229;4366.388705581221;4376.846810361638;3869.8258578364043;3978.7214894957365;4055.6147166019327;4348.456442944632;4400.493943196525;3528.594211665653;3783.67304107973;4096.56986016467;4220.261454245526;4249.9800047988565;3966.3058964681063;3978.5225772907147;3990.946925601917;3995.2409042282166;4012.4583181270445;5101.387833459971;5127.461616530285;5127.4872512748425;5130.640855140142;5177.404598471148;4179.352200408942;4376.7811609419405;4528.705634133771;5019.372578427697;5036.194208926554;4878.487758785312;5042.571381432583;5182.844514664566;5553.444655534455;5563.833085007415;3816.542014958195;4472.718913354181;4474.821007159742;4492.663050271242;4504.279315309567;4664.650151238637;5037.445880953129;5529.281193444866;5794.536437085065;5892.234009578728;4332.656876368336;4488.865334039984;4505.774711264424;4533.297338912474;4545.36364908913;4660.951285960632;4662.487126416091;4687.296905464208;4694.3427920090535;4706.929396059062;4109.438523239202;4249.383839343278;4668.4631267404;4767.189577771787;5056.241563765391;4795.568398844121;5163.819266325736;5509.32797593167;5601.6027418477115;5693.946619875336;1298.5909438526835;1307.3136603283162;1374.7044385457175;1466.1349803615917;1559.1190977097904;1112.8497652816766;1180.7874582575218;1186.463529729003;1187.1630500497129;1199.2684462477882;4695.389599352063;5006.595515049801;5022.522860328336;5194.558462530652;5221.530062294336;4678.1813182692895;4990.975045124751;5514.6691198528215;5784.248047753765;5785.5286827903765;4711;4857.247126695368;5161.27742116104;5184.429712731597;5244.9737751310995;2552.961705574418;2954.4386566552284;2991.790574659753;2997.4604571177397;3042.6500952390315;5326.227696983916;5369.657585603599;5934.970325148347;5974.61165024272;5997.460228579449;4756.809727610926;4957.082939656103;5399.6490228135835;5835.270591176103;5911.4975227105315;5255.973720131375;5579.9163012554845;5953.2735506702575;6043.969780151071;6067.362926892701;5012.172991456368;5362.544183744425;5734.913976290276;5839.854003649943;5865.5600829937075;5238.371395432572;5337.919931201112;5385.6230063895155;5723.627964182315;5768.365479797135;5987.850303742442;6078.848028799315;6079.878402432015;6101.908471372844;6173;4045.7167998239843;4560.726356418625;4627.7686115693405;4629.398178236807;4647.883802904955;5025.738417712118;5307.708076055854;5530.585206109477;5764.913526297193;5769;4764;5149.87125321855;5306.548943339705;5600.85997850057;5672.461116193955;5371.277798718513;5467.535259502987;5571.359293681278;5608.7195640217005;5610.783740422428;4380.634457392972;5097.719625420637;5255.13290307095;5365.651232669864;5421.5391691706645;5003.246266793217;5264.131048578611;5595.692236926845;5610.49505544503;5672.347680016685;4906.779194936165;5166.560842328444;5356.018273238927;5428;5468.589855760754 02:00:53 INFO - PROCESS | 1918 | |5;yui.html;658.5686375424099;696.7839969609421;708.2421808664731;806.1696452653766;827.2761333832897;15230.695386092286;15958.202089895502;16198.83801161988;16360.018398896074;16383.09892955887;14648.414063437476;14976.251187440612;15002.174880381575;15160.317785699643;15213.391464341414;764.8355603545236;895.5119459894373;907.310444062514;909.9226565741919;933.995330023349;918.3158546882581;928.972130836076;931.2596485793777;932.1796818799447;933.8412469880103;980.4264505264429;982.228950274035;982.990170098297;983.9508024598778;985.3250523391464;962.6101428921298;970.1026550440849;976.2385339435181;978.3787295067613;980.2599037726542;721.4264659595634;725.8004048886546;725.8548290341953;726.8800647893091;727.439871299102;16046.35814567416;16087.356505739757;16331.265093070722;16690.998540087632;16867;13818;13945.093568917988;13979.860201397958;14213.644658883508;14241.78637320441;9478.957314695377;9698.806023879553;9774;10599.47002649872;11916.940415297955;4410.451372777932;4452.443444569429;4485.641148708128;4497.595216430537;4497.707649002837;99954.51114476928;102549.48725256401;105327.47336263346;106282;106541.46729266382;10086.193104551618;10292.030771528836;10366.740831479274;10446.791064178673;10462.738431539121;20761.273355432488;21038.368848934555;21371.35885923425;21383.893080534497;21445.67831482529;7511.06111736033;7586.431017673696;7687.308142267223;7697.807554811175;7868.527888326718;4249.787510624456;4251.723637963522;4318.5681431856565;4377.015171586368;4519.796609152596;3127.5465057566526;3402.0984439123654;3426.3661222673886;3454.7581669283413;3462.79223246606;602.9547783916224;606.6481440764435;614.9723262453111;624.2976651267325;627.028106435033;504.4955044955045;506.70864253053907;524.6511070138315;528.7152220105181;543.2774410034644;7788.493747906368;9589.952050239704;9861.901380986243;9868.506574671237;9908.25688073383;624.8125562331282;655.3151956205777;659.1134923527943;662.3906006474056;682.8941514065327;4219.430376899079;4373.431453910971;4543.750093744809;4808.3027960945465;4815.012922350909;100326.4983675077;100544.49727751315;101336.49331753442;103419;105151;68549.65725171342;69291.65354173197;81593;84641.57679211687;89708.10291897129;62964.05553916695;67868.3213167862;68704;70153.94769078468;77756.61121694469;8010.919890801018;8644.394892357726;10272.178225741773;10661.200409969439;10809.783804323712;40511.3923291145;43184.35223471588;43488;47000.36456070359;49954.50045499499;53862.576184071026;58621.70689146527;60940.695296523234;62048.75902482015;63549.68225158845;12493.125481216228;12689.619311420673;12969.675758105745;13071;13639.181649101085;14417.42330306776;14651.853481465048;14798.408063677332;15030.248487575358;15040.849591503946;11489;12819.974402047626;13064.54274100402;13492.257925813781;13538.458461661423;12540.623781286577;13304.46782128704;13891.583252502442;13898.444062237802;14151.43394264218;12395.566155184526;13277.203367797963;13349.998750093588;13434.261115638734;13446.93276533611;9682;9996.600135994478;10304.587816487257;10394.22043346737;10782.892171078503;8953.746475493306;10527.578896844347;11108.222424430212;11142.554297827995;11146.386948717574 02:00:53 INFO - PROCESS | 1918 | __end_tp_report 02:00:53 INFO - PROCESS | 1918 | __start_cc_report 02:00:53 INFO - PROCESS | 1918 | _x_x_mozilla_cycle_collect,412 02:00:53 INFO - PROCESS | 1918 | __end_cc_report 02:00:53 INFO - PROCESS | 1918 | __startTimestamp1492938053477__endTimestamp 02:00:53 INFO - PROCESS | 1918 | 02:00:53 INFO - PROCESS | 1918 | ------- Summary: start ------- 02:00:53 INFO - PROCESS | 1918 | Number of tests: 6 02:00:53 INFO - PROCESS | 1918 | 02:00:53 INFO - PROCESS | 1918 | [#0] dojo.html Cycles:165 Average:5919.15 Median:6184.51 stddev:665.86 (10.8%) stddev-sans-first:638.69 02:00:53 INFO - PROCESS | 1918 | Values: 3432.7 3451.6 3453.7 3457.8 3466.0 5951.3 6013.2 6018.0 6086.4 6093.5 5984.2 6090.5 6092.0 6115.7 6117.8 5326.1 5434.6 5441.9 5455.5 5529.7 4565.6 5508.3 5527.1 5530.3 5566.4 5096.0 5279.7 5306.1 5401.3 5415.9 4969.7 5217.0 5485.5 5489.2 5493.9 5348.5 5414.8 5424.6 5455.3 5482.6 5718.4 5778.3 5779.2 5781.7 5910.8 5149.9 6234.6 6239.9 6410.0 6440.5 6498.9 6507.0 6507.3 6521.3 6551.5 6142.3 6200.8 6205.7 6226.4 6237.0 6184.5 6312.6 6382.3 6462.8 6480.8 6301.6 6306.3 6313.3 6317.3 6337.6 6354.9 6437.7 6497.3 6497.8 6507.8 6220.7 6239.5 6247.1 6261.7 6369.3 6225.5 6242.5 6275.2 6365.0 6406.6 5165.5 5167.6 5176.6 5179.1 5210.1 4563.6 4578.9 4610.9 4628.7 4633.5 5930.2 5943.1 6029.4 6059.8 6137.2 5429.8 6362.4 6366.1 6493.8 6497.4 5058.7 6154.4 6159.1 6171.3 6190.9 5511.3 5694.4 5718.4 5728.6 5732.7 6460.8 6475.0 6492.2 6609.3 6625.2 6316.0 6444.7 6450.0 6453.8 6481.4 6639.6 6656.1 6664.9 6668.7 6675.6 6181.1 6231.2 6253.0 6264.0 6297.0 6447.0 6461.9 6470.3 6475.8 6495.0 5651.9 6235.7 6273.2 6275.1 6317.1 5129.0 6179.4 6198.8 6204.8 6285.4 5645.4 5869.6 6117.7 6159.6 6265.7 6188.3 6271.9 6285.4 6296.8 6308.6 6112.4 6150.8 6186.8 6241.2 6256.1 02:00:53 INFO - PROCESS | 1918 | 02:00:53 INFO - PROCESS | 1918 | [#1] ext.html Cycles:180 Average:55208.24 Median:16552.91 stddev:113072.31 (683.1%) stddev-sans-first:113315.45 02:00:53 INFO - PROCESS | 1918 | Values: 701.9 729.6 807.3 936.6 946.1 38416.4 39452.0 39472.4 39611.8 39697.4 38712.8 39051.8 39084.8 39145.2 39215.8 7909.5 8050.5 9642.8 9956.9 11003.6 10760.7 10783.4 10857.0 10867.5 10906.3 10799.5 10861.9 10899.1 10913.9 10920.5 10718.8 10790.1 10826.3 10826.7 10829.4 10554.2 10564.6 10621.4 10632.6 10644.1 33808.7 33948.0 34020.7 34037.0 34172.0 3622.3 3678.4 3736.1 3751.1 3754.2 18708.0 24631.9 25525.7 26384.3 26418.3 2952.8 3029.4 3078.9 3183.0 3193.1 353957.0 354948.0 411333.0 421016.0 445205.8 43420.0 43765.1 43812.8 43870.1 43899.0 44766.0 44809.3 44858.0 44882.0 45094.8 13214.3 13380.7 13472.5 13474.1 13508.2 11529.2 11671.4 11734.0 11767.9 11784.4 11842.3 11887.2 11908.4 11970.0 12009.3 5705.7 5747.5 5751.7 5765.8 5785.7 931.3 1056.0 1117.9 1125.2 1189.8 19906.6 26266.1 29037.3 29245.4 29400.0 960.2 989.6 1049.7 1059.1 1158.7 14596.8 15615.8 15733.9 15822.6 16004.4 454689.7 457259.0 458629.0 459141.0 460209.0 57297.4 57322.7 57710.4 57733.3 57848.7 1250.4 1417.9 1459.4 1461.1 1461.8 28349.6 28658.0 28666.3 29546.6 29730.6 45956.3 46364.5 47484.8 49381.0 49413.8 415604.9 417713.0 418415.0 418972.0 421363.0 26171.6 26727.7 30817.0 31960.0 32310.0 37038.4 37148.0 37454.9 38403.0 38489.8 26111.0 27341.6 27510.6 28129.3 28594.3 27481.5 27823.7 27985.7 28323.3 28477.3 24748.0 32370.5 32460.8 32978.7 33099.8 28283.6 29846.8 30071.0 30076.4 30646.7 18904.1 21160.0 21769.8 23222.7 23464.9 02:00:53 INFO - PROCESS | 1918 | 02:00:53 INFO - PROCESS | 1918 | [#2] jquery.html Cycles:180 Average:41726.36 Median:21328.81 stddev:116956.22 (548.3%) stddev-sans-first:117243.12 02:00:53 INFO - PROCESS | 1918 | Values: 382.9 391.5 392.9 408.5 419.0 18731.7 21253.4 21330.5 21432.0 21542.7 16959.9 19538.9 20991.9 21111.3 21327.1 5606.3 6004.4 6437.2 6492.6 6503.6 6254.3 6391.6 6468.6 6545.9 6588.9 6506.5 6512.8 6514.0 6523.2 6549.2 6179.7 6450.4 6484.2 6502.8 6520.0 5650.5 6121.6 6282.6 6331.0 6411.4 25994.7 25998.2 26113.7 26367.3 26419.1 2837.2 2934.6 2937.3 2938.1 2950.5 16387.2 16410.0 16477.3 16506.6 16638.4 1972.7 1995.4 2127.7 2144.4 2148.0 130590.0 134625.3 138672.0 140698.0 142293.6 22508.0 22663.7 23194.2 23236.9 23317.9 35677.6 35883.3 36227.1 36351.1 36453.1 8967.7 10912.3 11180.6 11291.0 11501.1 9055.5 9059.3 9069.1 9226.3 9248.2 10869.9 11180.3 11235.7 11316.5 11337.3 3579.9 4323.3 4360.6 4396.0 4398.7 1013.0 1021.3 1077.1 1095.3 1099.6 18597.0 22869.9 22905.8 23262.7 23390.8 1112.9 1117.8 1125.0 1145.8 1158.5 12229.4 12301.1 12462.9 12513.1 12527.4 595879.0 601472.0 721851.0 770313.0 779632.0 141622.9 164528.0 168568.0 172022.0 175996.0 1188.5 1238.8 1268.3 1284.3 1286.0 20008.0 20148.7 20473.2 20598.3 20624.9 60286.7 60347.4 60953.0 61350.4 61392.4 90819.5 91470.0 92885.5 93514.1 94198.0 11440.3 11457.9 11816.0 12011.6 12101.4 12850.7 12868.9 13122.3 13122.6 13245.4 8475.9 8770.5 11519.9 11581.4 11599.6 10423.7 10425.0 10465.6 10719.8 10753.7 9538.0 9901.5 10121.5 11701.8 11780.0 11439.0 11790.5 11870.9 11890.9 11973.5 11031.4 11212.2 11348.3 11356.5 11523.4 02:00:53 INFO - PROCESS | 1918 | 02:00:53 INFO - PROCESS | 1918 | [#3] mootools.html Cycles:180 Average:6714.56 Median:3349.32 stddev:4222.92 (126.1%) stddev-sans-first:4215.33 02:00:53 INFO - PROCESS | 1918 | Values: 1322.6 1340.0 1465.8 1555.9 1604.2 10545.8 11184.7 11216.5 11267.3 11468.1 11063.8 11224.0 11230.7 11255.6 11293.5 2347.8 2380.6 2405.7 2410.7 2412.7 1895.0 1948.0 2045.8 2198.8 2368.7 2305.5 2314.3 2384.0 2387.2 2397.1 1933.4 2276.0 2348.8 2352.6 2357.6 2376.5 2393.2 2394.8 2395.1 2403.7 11030.7 11030.8 11079.4 11092.9 11160.9 5775.7 5836.8 5939.5 5948.7 5949.5 3320.0 3378.6 3397.7 3491.2 3592.5 1163.2 1184.4 1202.3 1208.7 1235.7 12650.5 15450.2 15478.3 15550.9 15641.9 4435.0 4543.9 4695.1 4730.4 4740.9 11493.2 11519.4 11522.6 11538.6 11542.4 5701.3 5705.4 5706.4 5713.3 5713.7 5042.4 5075.9 5216.4 5236.2 5329.0 3835.0 3934.2 3945.3 3949.5 3999.2 887.3 1031.2 1031.8 1043.2 1044.6 781.5 797.7 809.5 815.5 817.9 6565.5 6581.0 6679.9 6760.0 6780.0 836.3 867.3 976.4 986.2 989.2 4394.8 4436.7 4489.6 4626.5 4689.0 13753.0 14015.2 14182.2 14194.2 14219.6 11872.6 12312.9 13010.2 13480.3 14145.9 9729.0 9850.8 9980.8 10059.2 10065.8 5806.9 6800.0 6860.0 7120.2 7143.5 12182.0 12416.0 12487.4 12492.9 12665.5 11905.3 12199.4 12805.7 12881.2 12948.4 9127.9 9256.3 9291.3 9319.3 9357.9 8689.5 10194.2 10216.6 10225.3 10296.2 7441.4 8756.2 8827.6 8858.4 8932.9 8524.2 8528.7 8531.8 8578.7 8737.7 7799.2 7944.2 7950.7 7981.3 7981.6 5807.6 5819.1 5828.8 5992.6 6046.5 5913.3 5929.9 6111.7 6176.8 6212.7 02:00:53 INFO - PROCESS | 1918 | 02:00:53 INFO - PROCESS | 1918 | [#4] prototype.html Cycles:180 Average:4610.65 Median:5015.77 stddev:1301.55 (25.9%) stddev-sans-first:1265.59 02:00:53 INFO - PROCESS | 1918 | Values: 365.4 367.5 369.1 384.7 385.8 4213.9 5161.6 5188.0 5241.6 5307.9 4386.9 4723.6 5171.5 5191.6 5231.9 3854.6 3865.2 3972.8 4330.2 4347.9 3789.9 3887.8 4275.6 4366.4 4376.8 3869.8 3978.7 4055.6 4348.5 4400.5 3528.6 3783.7 4096.6 4220.3 4250.0 3966.3 3978.5 3990.9 3995.2 4012.5 5101.4 5127.5 5127.5 5130.6 5177.4 4179.4 4376.8 4528.7 5019.4 5036.2 4878.5 5042.6 5182.8 5553.4 5563.8 3816.5 4472.7 4474.8 4492.7 4504.3 4664.7 5037.4 5529.3 5794.5 5892.2 4332.7 4488.9 4505.8 4533.3 4545.4 4661.0 4662.5 4687.3 4694.3 4706.9 4109.4 4249.4 4668.5 4767.2 5056.2 4795.6 5163.8 5509.3 5601.6 5693.9 1298.6 1307.3 1374.7 1466.1 1559.1 1112.8 1180.8 1186.5 1187.2 1199.3 4695.4 5006.6 5022.5 5194.6 5221.5 4678.2 4991.0 5514.7 5784.2 5785.5 4711.0 4857.2 5161.3 5184.4 5245.0 2553.0 2954.4 2991.8 2997.5 3042.7 5326.2 5369.7 5935.0 5974.6 5997.5 4756.8 4957.1 5399.6 5835.3 5911.5 5256.0 5579.9 5953.3 6044.0 6067.4 5012.2 5362.5 5734.9 5839.9 5865.6 5238.4 5337.9 5385.6 5723.6 5768.4 5987.9 6078.8 6079.9 6101.9 6173.0 4045.7 4560.7 4627.8 4629.4 4647.9 5025.7 5307.7 5530.6 5764.9 5769.0 4764.0 5149.9 5306.5 5600.9 5672.5 5371.3 5467.5 5571.4 5608.7 5610.8 4380.6 5097.7 5255.1 5365.7 5421.5 5003.2 5264.1 5595.7 5610.5 5672.3 4906.8 5166.6 5356.0 5428.0 5468.6 02:00:53 INFO - PROCESS | 1918 | 02:00:53 INFO - PROCESS | 1918 | [#5] yui.html Cycles:180 Average:19476.83 Median:4431.45 stddev:27678.55 (624.6%) stddev-sans-first:27720.13 02:00:53 INFO - PROCESS | 1918 | Values: 658.6 696.8 708.2 806.2 827.3 15230.7 15958.2 16198.8 16360.0 16383.1 14648.4 14976.3 15002.2 15160.3 15213.4 764.8 895.5 907.3 909.9 934.0 918.3 929.0 931.3 932.2 933.8 980.4 982.2 983.0 984.0 985.3 962.6 970.1 976.2 978.4 980.3 721.4 725.8 725.9 726.9 727.4 16046.4 16087.4 16331.3 16691.0 16867.0 13818.0 13945.1 13979.9 14213.6 14241.8 9479.0 9698.8 9774.0 10599.5 11916.9 4410.5 4452.4 4485.6 4497.6 4497.7 99954.5 102549.5 105327.5 106282.0 106541.5 10086.2 10292.0 10366.7 10446.8 10462.7 20761.3 21038.4 21371.4 21383.9 21445.7 7511.1 7586.4 7687.3 7697.8 7868.5 4249.8 4251.7 4318.6 4377.0 4519.8 3127.5 3402.1 3426.4 3454.8 3462.8 603.0 606.6 615.0 624.3 627.0 504.5 506.7 524.7 528.7 543.3 7788.5 9590.0 9861.9 9868.5 9908.3 624.8 655.3 659.1 662.4 682.9 4219.4 4373.4 4543.8 4808.3 4815.0 100326.5 100544.5 101336.5 103419.0 105151.0 68549.7 69291.7 81593.0 84641.6 89708.1 62964.1 67868.3 68704.0 70153.9 77756.6 8010.9 8644.4 10272.2 10661.2 10809.8 40511.4 43184.4 43488.0 47000.4 49954.5 53862.6 58621.7 60940.7 62048.8 63549.7 12493.1 12689.6 12969.7 13071.0 13639.2 14417.4 14651.9 14798.4 15030.2 15040.8 11489.0 12820.0 13064.5 13492.3 13538.5 12540.6 13304.5 13891.6 13898.4 14151.4 12395.6 13277.2 13350.0 13434.3 13446.9 9682.0 9996.6 10304.6 10394.2 10782.9 8953.7 10527.6 11108.2 11142.6 11146.4 02:00:53 INFO - PROCESS | 1918 | -------- Summary: end -------- 02:00:53 INFO - PROCESS | 1918 | 02:00:54 INFO - TEST-INFO | 1918: exit 0 02:00:54 INFO - TEST-OK | dromaeo_css | took 1117691ms 02:00:54 INFO - TEST-START | kraken 02:00:54 INFO - operating with platform_type : mac_ 02:00:54 INFO - Initialising browser for kraken test... 02:00:54 INFO - TEST-INFO | started process 1926 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/tmpQKSPY7/profile http://localhost:49425/getInfo.html) 02:00:57 INFO - PROCESS | 1926 | 2017-04-23 02:00:57.172 plugin-container[1928:14662] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 02:00:57 INFO - PROCESS | 1926 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:00:57 INFO - PROCESS | 1926 | 2017-04-23 02:00:57.173 plugin-container[1928:14662] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461f, name = 'com.apple.CFPasteboardClient' 02:00:57 INFO - PROCESS | 1926 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:00:57 INFO - PROCESS | 1926 | 2017-04-23 02:00:57.173 plugin-container[1928:14662] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:00:57 INFO - PROCESS | 1926 | __metrics Screen width/height:1600/1200 02:00:57 INFO - PROCESS | 1926 | colorDepth:24 02:00:57 INFO - PROCESS | 1926 | Browser inner width/height: 1024/689 02:00:57 INFO - PROCESS | 1926 | __metrics 02:00:57 ERROR - PROCESS | 1926 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 02:00:58 INFO - TEST-INFO | 1926: exit 0 02:00:58 INFO - Browser initialized. 02:00:58 INFO - Running cycle 1/1 for kraken test... 02:00:58 INFO - TEST-INFO | started process 1929 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/tmpQKSPY7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:01:00 INFO - PROCESS | 1929 | 2017-04-23 02:01:00.752 plugin-container[1930:14783] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 02:01:00 INFO - PROCESS | 1929 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:01:00 INFO - PROCESS | 1929 | 2017-04-23 02:01:00.753 plugin-container[1930:14783] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9013, name = 'com.apple.CFPasteboardClient' 02:01:00 INFO - PROCESS | 1929 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:01:00 INFO - PROCESS | 1929 | 2017-04-23 02:01:00.753 plugin-container[1930:14783] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:01:10 INFO - PROCESS | 1929 | RSS: Main: 221216768 02:01:10 INFO - PROCESS | 1929 | 02:01:14 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49425/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49425/tests/kraken/driver.html?testName=audio-beat-detection) 02:01:15 INFO - PROCESS | 1929 | RSS: Main: 235614208 02:01:15 INFO - PROCESS | 1929 | 02:01:17 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49425/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49425/tests/kraken/driver.html?testName=audio-dft) 02:01:18 INFO - PROCESS | 1929 | RSS: Main: 243523584 02:01:18 INFO - PROCESS | 1929 | 02:01:21 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49425/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49425/tests/kraken/driver.html?testName=audio-fft) 02:01:22 INFO - PROCESS | 1929 | RSS: Main: 245231616 02:01:22 INFO - PROCESS | 1929 | 02:01:24 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49425/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49425/tests/kraken/driver.html?testName=audio-oscillator) 02:01:25 INFO - PROCESS | 1929 | RSS: Main: 247812096 02:01:25 INFO - PROCESS | 1929 | 02:01:27 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49425/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49425/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:01:27 INFO - PROCESS | 1929 | RSS: Main: 248590336 02:01:27 INFO - PROCESS | 1929 | 02:01:30 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49425/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49425/tests/kraken/driver.html?testName=imaging-darkroom) 02:01:31 INFO - PROCESS | 1929 | RSS: Main: 252149760 02:01:31 INFO - PROCESS | 1929 | 02:01:34 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49425/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49425/tests/kraken/driver.html?testName=imaging-desaturate) 02:01:35 INFO - PROCESS | 1929 | RSS: Main: 251768832 02:01:35 INFO - PROCESS | 1929 | 02:01:38 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49425/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49425/tests/kraken/driver.html?testName=json-parse-financial) 02:01:39 INFO - PROCESS | 1929 | RSS: Main: 251559936 02:01:39 INFO - PROCESS | 1929 | 02:01:41 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49425/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49425/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:01:41 INFO - PROCESS | 1929 | RSS: Main: 251609088 02:01:41 INFO - PROCESS | 1929 | 02:01:43 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49425/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49425/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:01:44 INFO - PROCESS | 1929 | RSS: Main: 251613184 02:01:44 INFO - PROCESS | 1929 | 02:01:46 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49425/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49425/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:01:46 INFO - PROCESS | 1929 | RSS: Main: 251662336 02:01:46 INFO - PROCESS | 1929 | 02:01:49 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49425/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49425/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:01:50 INFO - PROCESS | 1929 | RSS: Main: 252030976 02:01:50 INFO - PROCESS | 1929 | 02:01:53 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49425/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49425/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:01:54 INFO - PROCESS | 1929 | RSS: Main: 251797504 02:01:54 INFO - PROCESS | 1929 | 02:01:56 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49425/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49425/tests/kraken/driver.html?testName=ai-astar) 02:01:56 INFO - PROCESS | 1929 | RSS: Main: 255397888 02:01:56 INFO - PROCESS | 1929 | 02:01:56 INFO - PROCESS | 1929 | __start_tp_report 02:01:56 INFO - PROCESS | 1929 | _x_x_mozilla_page_load 02:01:56 INFO - PROCESS | 1929 | _x_x_mozilla_page_load_details 02:01:56 INFO - PROCESS | 1929 | |i|pagename|runs| 02:01:56 INFO - PROCESS | 1929 | |0;ai-astar;111;181;108;175;182;182;111;167;123;183 02:01:56 INFO - PROCESS | 1929 | |1;audio-beat-detection;138;112;114;142;114;140;153;118;121;97 02:01:56 INFO - PROCESS | 1929 | |2;audio-dft;193;209;198;201;200;238;277;228;289;206 02:01:56 INFO - PROCESS | 1929 | |3;audio-fft;83;61;61;86;63;72;85;89;100;66 02:01:56 INFO - PROCESS | 1929 | |4;audio-oscillator;81;65;102;57;77;100;72;76;97;71 02:01:56 INFO - PROCESS | 1929 | |5;imaging-gaussian-blur;116;94;93;94;94;85;96;93;94;93 02:01:56 INFO - PROCESS | 1929 | |6;imaging-darkroom;100;143;155;99;99;120;154;140;100;101 02:01:56 INFO - PROCESS | 1929 | |7;imaging-desaturate;143;91;91;93;90;89;93;88;145;138 02:01:56 INFO - PROCESS | 1929 | |8;json-parse-financial;67;65;88;99;106;83;69;70;60;90 02:01:56 INFO - PROCESS | 1929 | |9;json-stringify-tinderbox;61;58;63;60;65;62;59;59;59;59 02:01:56 INFO - PROCESS | 1929 | |10;stanford-crypto-aes;84;82;83;86;84;84;88;89;84;92 02:01:56 INFO - PROCESS | 1929 | |11;stanford-crypto-ccm;142;141;142;139;140;141;144;156;211;180 02:01:56 INFO - PROCESS | 1929 | |12;stanford-crypto-pbkdf2;170;188;223;221;173;226;207;183;214;151 02:01:56 INFO - PROCESS | 1929 | |13;stanford-crypto-sha256-iterative;66;69;69;95;96;88;87;85;60;76 02:01:56 INFO - PROCESS | 1929 | __end_tp_report 02:01:56 INFO - PROCESS | 1929 | __start_cc_report 02:01:56 INFO - PROCESS | 1929 | _x_x_mozilla_cycle_collect,1015 02:01:56 INFO - PROCESS | 1929 | __end_cc_report 02:01:56 INFO - PROCESS | 1929 | __startTimestamp1492938116636__endTimestamp 02:01:56 INFO - PROCESS | 1929 | 02:01:56 INFO - PROCESS | 1929 | ------- Summary: start ------- 02:01:56 INFO - PROCESS | 1929 | Number of tests: 14 02:01:56 INFO - PROCESS | 1929 | 02:01:56 INFO - PROCESS | 1929 | [#0] ai-astar Cycles:10 Average:152.30 Median:178.00 stddev:34.15 (19.2%) stddev-sans-first:32.79 02:01:56 INFO - PROCESS | 1929 | Values: 111.0 181.0 108.0 175.0 182.0 182.0 111.0 167.0 123.0 183.0 02:01:56 INFO - PROCESS | 1929 | 02:01:56 INFO - PROCESS | 1929 | [#1] audio-beat-detection Cycles:10 Average:124.90 Median:139.00 stddev:17.41 (12.5%) stddev-sans-first:17.81 02:01:56 INFO - PROCESS | 1929 | Values: 138.0 112.0 114.0 142.0 114.0 140.0 153.0 118.0 121.0 97.0 02:01:56 INFO - PROCESS | 1929 | 02:01:56 INFO - PROCESS | 1929 | [#2] audio-dft Cycles:10 Average:223.90 Median:218.50 stddev:34.22 (15.7%) stddev-sans-first:34.42 02:01:56 INFO - PROCESS | 1929 | Values: 193.0 209.0 198.0 201.0 200.0 238.0 277.0 228.0 289.0 206.0 02:01:56 INFO - PROCESS | 1929 | 02:01:56 INFO - PROCESS | 1929 | [#3] audio-fft Cycles:10 Average:76.60 Median:77.50 stddev:13.77 (17.8%) stddev-sans-first:14.41 02:01:56 INFO - PROCESS | 1929 | Values: 83.0 61.0 61.0 86.0 63.0 72.0 85.0 89.0 100.0 66.0 02:01:56 INFO - PROCESS | 1929 | 02:01:56 INFO - PROCESS | 1929 | [#4] audio-oscillator Cycles:10 Average:79.80 Median:74.00 stddev:15.27 (20.6%) stddev-sans-first:16.19 02:01:56 INFO - PROCESS | 1929 | Values: 81.0 65.0 102.0 57.0 77.0 100.0 72.0 76.0 97.0 71.0 02:01:56 INFO - PROCESS | 1929 | 02:01:56 INFO - PROCESS | 1929 | [#5] imaging-gaussian-blur Cycles:10 Average:95.20 Median:94.00 stddev:7.87 (8.4%) stddev-sans-first:3.10 02:01:56 INFO - PROCESS | 1929 | Values: 116.0 94.0 93.0 94.0 94.0 85.0 96.0 93.0 94.0 93.0 02:01:56 INFO - PROCESS | 1929 | 02:01:56 INFO - PROCESS | 1929 | [#6] imaging-darkroom Cycles:10 Average:121.10 Median:148.50 stddev:24.36 (16.4%) stddev-sans-first:24.61 02:01:56 INFO - PROCESS | 1929 | Values: 100.0 143.0 155.0 99.0 99.0 120.0 154.0 140.0 100.0 101.0 02:01:56 INFO - PROCESS | 1929 | 02:01:56 INFO - PROCESS | 1929 | [#7] imaging-desaturate Cycles:10 Average:106.10 Median:90.50 stddev:24.88 (27.5%) stddev-sans-first:22.52 02:01:56 INFO - PROCESS | 1929 | Values: 143.0 91.0 91.0 93.0 90.0 89.0 93.0 88.0 145.0 138.0 02:01:56 INFO - PROCESS | 1929 | 02:01:56 INFO - PROCESS | 1929 | [#8] json-parse-financial Cycles:10 Average:79.70 Median:76.50 stddev:15.72 (20.5%) stddev-sans-first:15.99 02:01:56 INFO - PROCESS | 1929 | Values: 67.0 65.0 88.0 99.0 106.0 83.0 69.0 70.0 60.0 90.0 02:01:56 INFO - PROCESS | 1929 | 02:01:56 INFO - PROCESS | 1929 | [#9] json-stringify-tinderbox Cycles:10 Average:60.50 Median:60.50 stddev:2.22 (3.7%) stddev-sans-first:2.35 02:01:56 INFO - PROCESS | 1929 | Values: 61.0 58.0 63.0 60.0 65.0 62.0 59.0 59.0 59.0 59.0 02:01:56 INFO - PROCESS | 1929 | 02:01:56 INFO - PROCESS | 1929 | [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:85.00 stddev:3.13 (3.7%) stddev-sans-first:3.27 02:01:56 INFO - PROCESS | 1929 | Values: 84.0 82.0 83.0 86.0 84.0 84.0 88.0 89.0 84.0 92.0 02:01:56 INFO - PROCESS | 1929 | 02:01:56 INFO - PROCESS | 1929 | [#11] stanford-crypto-ccm Cycles:10 Average:153.60 Median:143.00 stddev:23.74 (16.6%) stddev-sans-first:24.81 02:01:56 INFO - PROCESS | 1929 | Values: 142.0 141.0 142.0 139.0 140.0 141.0 144.0 156.0 211.0 180.0 02:01:56 INFO - PROCESS | 1929 | 02:01:56 INFO - PROCESS | 1929 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.60 Median:210.50 stddev:26.16 (12.4%) stddev-sans-first:26.06 02:01:56 INFO - PROCESS | 1929 | Values: 170.0 188.0 223.0 221.0 173.0 226.0 207.0 183.0 214.0 151.0 02:01:56 INFO - PROCESS | 1929 | 02:01:56 INFO - PROCESS | 1929 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.10 Median:86.00 stddev:12.76 (14.8%) stddev-sans-first:12.62 02:01:56 INFO - PROCESS | 1929 | Values: 66.0 69.0 69.0 95.0 96.0 88.0 87.0 85.0 60.0 76.0 02:01:56 INFO - PROCESS | 1929 | -------- Summary: end -------- 02:01:56 INFO - PROCESS | 1929 | 02:01:57 INFO - TEST-INFO | 1929: exit 0 02:01:57 INFO - TEST-OK | kraken | took 63550ms 02:01:57 INFO - SUITE-END | took 1181s 02:01:57 INFO - Completed test suite (00:19:41) 02:01:57 INFO - javascript benchmark 02:01:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7635.763658683996, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3432.708219801317, 3451.585809702836, 3453.7064349530306, 3457.7925324480516, 3465.9653403465963, 5951.345352011281, 6013.188219590354, 6018.037114061751, 6086.391360863911, 6093.542984276186, 5984.222051133346, 6090.48230900374, 6092.0, 6115.694215289229, 6117.785877494289, 5326.121190003654, 5434.646747961389, 5441.891162176754, 5455.536279416255, 5529.695866727348, 4565.634749220071, 5508.338999320067, 5527.088030474987, 5530.308711411074, 5566.443355664442, 5096.0, 5279.736013199344, 5306.09796334624, 5401.297831281928, 5415.864603384907, 4969.683033995976, 5216.973915130438, 5485.451454854522, 5489.231507588941, 5493.890122197534, 5348.491893270172, 5414.756335964891, 5424.566034717213, 5455.263539422167, 5482.5613950883835, 5718.428157184248, 5778.3066032076, 5779.248697669276, 5781.682007489587, 5910.7931222407005, 5149.922751158698, 6234.563580549363, 6239.906401403982, 6410.038494225857, 6440.4847612190915, 6498.9350106498805, 6506.967465162644, 6507.3492650735025, 6521.282658907564, 6551.508636852208, 6142.324344322121, 6200.751969921198, 6205.689715514206, 6226.439620434183, 6236.96881515594, 6184.5052395808225, 6312.621242725451, 6382.266039405435, 6462.838429039311, 6480.773172938924, 6301.590396624205, 6306.3378345274, 6313.273973493107, 6317.273513545909, 6337.6197428154455, 6354.872902542016, 6437.742490300336, 6497.317781632955, 6497.837554061091, 6507.804765857032, 6220.720067596969, 6239.500839932794, 6247.0629405589525, 6261.686915654199, 6369.33122022181, 6225.470834978987, 6242.531810114259, 6275.246970363585, 6364.968175159094, 6406.615603063831, 5165.535101840853, 5167.586593072545, 5176.5858731301405, 5179.093658609803, 5210.062188806051, 4563.634909207255, 4578.931316030262, 4610.861674149781, 4628.652851036186, 4633.536646335407, 5930.1994230779155, 5943.078822782476, 6029.427204415573, 6059.787907423219, 6137.232845894263, 5429.782808687687, 6362.363763623694, 6366.108744775735, 6493.805185844432, 6497.415232629086, 5058.696478211319, 6154.415330543591, 6159.1069294951985, 6171.290301615371, 6190.876182476325, 5511.311086114236, 5694.373618901917, 5718.428157184248, 5728.627639203439, 5732.656040637575, 6460.773872914519, 6475.028745687996, 6492.220933488012, 6609.272979972103, 6625.238097618835, 6316.0, 6444.677766111582, 6449.96775016122, 6453.806385808433, 6481.449076828522, 6639.568428052161, 6656.134702488645, 6664.900026499509, 6668.699908504226, 6675.599464032173, 6181.072839074175, 6231.22109736283, 6252.968735156295, 6264.029075493306, 6296.968515157395, 6447.0329450582785, 6461.903071454026, 6470.255920569195, 6475.7733479328, 6495.025746138117, 5651.88696226065, 6235.688215589294, 6273.215848018997, 6275.121482992294, 6317.084022816574, 5129.0, 6179.351168127461, 6198.81403557894, 6204.7828326008375, 6285.43431091204, 5645.4072322405555, 5869.647821130746, 6117.694115294306, 6159.599626024294, 6265.686715664289, 6188.319284878569, 6271.905921411091, 6285.371462853678, 6296.84257893538, 6308.558400912076, 6112.442689633966, 6150.846228844136, 6186.752529898754, 6241.188645476241, 6256.124142620128], "value": 5877.9696445456, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.9438444924407, 729.5549714674048, 807.2895851650549, 936.5691781780381, 946.0633972367358, 38416.42375364372, 39452.0, 39472.407913881245, 39611.80194099033, 39697.404538931936, 38712.83861484153, 39051.804740976324, 39084.80457597715, 39145.21709565814, 39215.80392098043, 7909.525428474296, 8050.476719013274, 9642.807143857119, 9956.900430995669, 11003.559857605685, 10760.73098172548, 10783.406912619803, 10857.0, 10867.45662716861, 10906.291091079094, 10799.460026998659, 10861.891381086167, 10899.073578745776, 10913.945430272837, 10920.453977301142, 10718.839217411705, 10790.136789056896, 10826.296290741117, 10826.67519974402, 10829.350238985608, 10554.208434367452, 10564.630237941636, 10621.362718236931, 10632.57469701211, 10644.148468122532, 33808.6619133808, 33947.98156055322, 34020.659793401996, 34037.0, 34172.0, 3622.275544891006, 3678.44823276508, 3736.1220113273357, 3751.1372384351753, 3754.155315054118, 18708.0, 24631.87684061586, 25525.744742552524, 26384.340391490176, 26418.339541511425, 2952.8228306301476, 3029.424409362214, 3078.937766470564, 3182.98408507956, 3193.1218914798615, 353957.0, 354948.0, 411333.0, 421016.0, 445205.7739711313, 43420.0, 43765.124697505875, 43812.780936095754, 43870.122597547874, 43899.0, 44766.0, 44809.32786008212, 44858.0, 44882.0, 45094.77452612716, 13214.33928303581, 13380.6654833628, 13472.461101555828, 13474.124181928339, 13508.243928289456, 11529.192956492965, 11671.35807530577, 11734.0, 11767.88232117685, 11784.351860647746, 11842.28946263227, 11887.227330223508, 11908.404579770977, 11970.0, 12009.339486328336, 5705.74324155414, 5747.5114615257335, 5751.734618383949, 5765.827025189251, 5785.739641716133, 931.2549960031947, 1056.0, 1117.932924024545, 1125.1561329003248, 1189.7501524679742, 19906.60186796285, 26266.080687175858, 29037.274068148043, 29245.415091698047, 29399.97100101529, 960.1694534227843, 989.5744829723144, 1049.7270709615402, 1059.1156384418941, 1158.675570840166, 14596.781048284285, 15615.765763513557, 15733.921330393276, 15822.588860369993, 16004.359825606845, 454689.7265513651, 457259.0, 458629.0, 459141.0, 460209.0, 57297.42702572921, 57322.713386433636, 57710.42289577134, 57733.26800196001, 57848.71075644679, 1250.4060571228777, 1417.9432822687183, 1459.3870574358796, 1461.123326004389, 1461.817272840895, 28349.574756378257, 28657.996970105945, 28666.283342916595, 29546.556801647563, 29730.554041689826, 45956.31065533953, 46364.536354636024, 47484.812879678284, 49381.0, 49413.77638006226, 415604.9219753882, 417713.0, 418415.0, 418972.0, 421363.0, 26171.607425888247, 26727.732722673303, 30817.0, 31960.0, 32310.0, 37038.44442333313, 37148.0, 37454.876353709435, 38403.03992400101, 38489.80755096319, 26111.0, 27341.589876152273, 27510.587341189497, 28129.296767580974, 28594.285142871595, 27481.45037099287, 27823.721762782112, 27985.720142799128, 28323.291917702223, 28477.288067798472, 24748.0, 32370.514442283857, 32460.83769581137, 32978.67021329756, 33099.83450082734, 28283.575746364233, 29846.806127755513, 30071.0, 30076.398472030876, 30646.693533065274, 18904.109589040985, 21159.98466234812, 21769.78230217741, 23222.651660224772, 23464.882675586512], "value": 18183.527678443403, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.9430771071841, 391.4519672458558, 392.85482817586734, 408.47918903398164, 418.96857735669835, 18731.719024214646, 21253.36239912801, 21330.46673833155, 21432.03555839987, 21542.676859847114, 16959.915200424013, 19538.90230548849, 20991.895040524814, 21111.261105861297, 21327.146914123456, 5606.299212598426, 6004.429579189981, 6437.2275326960835, 6492.57798243115, 6503.609783412987, 6254.3120256771945, 6391.616503009811, 6468.644224567647, 6545.869082618345, 6588.868222635569, 6506.479481641481, 6512.837179070514, 6513.967430162843, 6523.249826269968, 6549.181352330958, 6179.691015449232, 6450.4194622483965, 6484.1894763154605, 6502.804915852532, 6519.9674001630165, 5650.463205995424, 6121.571489995702, 6282.591631543981, 6331.0, 6411.3909178627955, 25994.74005259942, 25998.22005339843, 26113.73886261151, 26367.34081647955, 26419.075332363467, 2837.1630369040977, 2934.5598160275918, 2937.2803663102677, 2938.14793709826, 2950.5279155335256, 16387.180640967905, 16410.015399075975, 16477.258523366476, 16506.58733531659, 16638.417655382003, 1972.7336809530673, 1995.3814317561603, 2127.6702111172795, 2144.3995681209285, 2147.978520214809, 130590.0, 134625.326873366, 138672.0, 140698.0, 142293.57706422906, 22508.0, 22663.660045099336, 23194.188203413138, 23236.883815580815, 23317.88341058284, 35677.64322356795, 35883.28233435316, 36227.09432264162, 36351.091222719115, 36453.08867278339, 8967.730968070968, 10912.345259284468, 11180.60867869637, 11291.040261577693, 11501.13741469393, 9055.547222638841, 9059.320550958704, 9069.09309069101, 9226.26189904806, 9248.16766491019, 10869.891301086887, 11180.329180249211, 11235.71910702239, 11316.490757915913, 11337.319760814378, 3579.928401431957, 4323.2650449423045, 4360.563943605614, 4395.978020109943, 4398.73607583546, 1012.9828816828018, 1021.3055122516612, 1077.1167642533205, 1095.271644356494, 1099.6206308823444, 18597.024476084032, 22869.885650571974, 22905.770942290364, 23262.720550369893, 23390.7660923392, 1112.9109671226283, 1117.8155604325195, 1124.9550017999188, 1145.799485090106, 1158.5481662151767, 12229.449674764657, 12301.138920275675, 12462.937685311515, 12513.061520386007, 12527.373631318398, 595879.0, 601472.0, 721851.0, 770313.0, 779632.0, 141622.87565686522, 164528.0, 168568.0, 172022.0, 175996.0, 1188.5008296515484, 1238.8017917133216, 1268.3277862732766, 1284.28722059258, 1286.0, 20008.0, 20148.697769533763, 20473.181072756925, 20598.27906023282, 20624.865632390363, 60286.69856650864, 60347.39652603418, 60953.0, 61350.386496134466, 61392.38607614045, 90819.54590227007, 91470.0, 92885.5355723217, 93514.06485935325, 94198.0, 11440.313581185155, 11457.885421145682, 11816.0, 12011.63965081049, 12101.394930253628, 12850.742985140432, 12868.871311287141, 13122.278274694983, 13122.60632181036, 13245.403956821774, 8475.94050743657, 8770.473771573726, 11519.88480115188, 11581.420928953687, 11599.594014209462, 10423.739406514595, 10425.0, 10465.581376744845, 10719.785604288027, 10753.677389678322, 9537.952310238405, 9901.475123843375, 10121.544530495998, 11701.824472633087, 11780.0, 11439.027682647069, 11790.528378865092, 11870.881291186977, 11890.881091188978, 11973.521059157885, 11031.393273370042, 11212.15908806859, 11348.262362946381, 11356.488957996744, 11523.423828808694], "value": 11726.522803350672, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1322.629663694166, 1340.0284793524695, 1465.7947887295777, 1555.8599726024656, 1604.2219523531085, 10545.787234468029, 11184.657841059074, 11216.495257713403, 11267.2676276042, 11468.139889508278, 11063.778724425507, 11224.045956093742, 11230.719232019204, 11255.606053788119, 11293.49179286932, 2347.800436962856, 2380.6310021946624, 2405.7353691093967, 2410.6986626671664, 2412.7345991940874, 1894.9956523042808, 1947.9513012174739, 2045.826104781088, 2198.835087368454, 2368.7157541095007, 2305.4582173189287, 2314.317276403465, 2384.0225507541913, 2387.224152150558, 2397.0891061396646, 1933.3523269704665, 2275.9544809103727, 2348.8473249238746, 2352.6118190498546, 2357.646353047031, 2376.524695060995, 2393.222202784102, 2394.760523947609, 2395.1257790906366, 2403.71155461344, 11030.724231894186, 11030.779384412346, 11079.446027698583, 11092.889071109346, 11160.944195279053, 5775.711214439303, 5836.795712150054, 5939.46544810969, 5948.672822994734, 5949.494292985101, 3320.020593924798, 3378.6283508814013, 3397.6602339766073, 3491.1970246843116, 3592.479090531858, 1163.1625229834506, 1184.3545267829054, 1202.2666173634077, 1208.6615747590602, 1235.7157853693611, 12650.493980240779, 15450.227488625524, 15478.303476343479, 15550.922245388814, 15641.921790391089, 4435.0, 4543.909121817611, 4695.131400690815, 4730.385049943486, 4740.928886066643, 11493.195476316578, 11519.366434845997, 11522.596709115141, 11538.59614913474, 11542.365169915567, 5701.344345400249, 5705.400932902068, 5706.3722990471015, 5713.314402271754, 5713.74288157034, 5042.369703787027, 5075.8984820303385, 5216.374035115735, 5236.188390799432, 5329.040772660958, 3835.0412396900774, 3934.15415685629, 3945.3095708251517, 3949.5063117110362, 3999.1601763629383, 887.3123329419751, 1031.2368847053126, 1031.7781676939494, 1043.222799014739, 1044.603050840691, 781.4803155901262, 797.6649807081039, 809.4778867630463, 815.5392203405057, 817.9263866252065, 6565.540412171198, 6581.0, 6679.9332006679315, 6760.0, 6780.0, 836.2891542189091, 867.2714919467677, 976.3556053004983, 986.2405947420447, 989.2135752077116, 4394.780260986938, 4436.6672499562665, 4489.573490518395, 4626.4910859805395, 4688.976555117202, 13753.0, 14015.159090454605, 14182.219977901315, 14194.219317937612, 14219.573412797632, 11872.643820685393, 12312.938435307946, 13010.154339967872, 13480.325983700775, 14145.858541414455, 9729.027097290356, 9850.802983940424, 9980.750481238027, 10059.19526437898, 10065.849012264822, 5806.9419305806405, 6799.966000169968, 6859.965700171667, 7120.216776154515, 7143.535670181422, 12182.025437964765, 12416.0, 12487.375631218585, 12492.937535312265, 12665.493380264686, 11905.345206013752, 12199.390030498262, 12805.743885122432, 12881.227126372447, 12948.352582370655, 9127.863082053907, 9256.25949924018, 9291.256699464162, 9319.254459643076, 9357.85963210566, 8689.478631282143, 10194.235432342455, 10216.642417515352, 10225.335353202017, 10296.17630589536, 7441.367483763941, 8756.211940925346, 8827.558622068742, 8858.379913406, 8932.910670893469, 8524.232819046067, 8528.74413767588, 8531.76684499461, 8578.699745508877, 8737.737867863974, 7799.220077992155, 7944.165862584346, 7950.7217247396065, 7981.281684648409, 7981.561014144278, 5807.622504537191, 5819.0689489681445, 5828.766849326149, 5992.610480318765, 6046.516278697782, 5913.251689821097, 5929.851753706192, 6111.694415279129, 6176.81469555914, 6212.72042758086], "value": 5071.213876019956, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.3897990356105, 367.5057048270076, 369.07546595777575, 384.66149788186374, 385.8263781298415, 4213.873583792491, 5161.587073034158, 5188.0, 5241.633085683999, 5307.911878064997, 4386.903274181454, 4723.622110231182, 5171.456997015315, 5191.5846732261425, 5231.895362092756, 3854.5952674969144, 3865.246276975991, 3972.8410863565423, 4330.198913201052, 4347.86956391308, 3789.905252368685, 3887.844486220548, 4275.593818587229, 4366.388705581221, 4376.846810361638, 3869.8258578364043, 3978.7214894957365, 4055.6147166019327, 4348.456442944632, 4400.493943196525, 3528.594211665653, 3783.67304107973, 4096.56986016467, 4220.261454245526, 4249.9800047988565, 3966.3058964681063, 3978.5225772907147, 3990.946925601917, 3995.2409042282166, 4012.4583181270445, 5101.387833459971, 5127.461616530285, 5127.4872512748425, 5130.640855140142, 5177.404598471148, 4179.352200408942, 4376.7811609419405, 4528.705634133771, 5019.372578427697, 5036.194208926554, 4878.487758785312, 5042.571381432583, 5182.844514664566, 5553.444655534455, 5563.833085007415, 3816.542014958195, 4472.718913354181, 4474.821007159742, 4492.663050271242, 4504.279315309567, 4664.650151238637, 5037.445880953129, 5529.281193444866, 5794.536437085065, 5892.234009578728, 4332.656876368336, 4488.865334039984, 4505.774711264424, 4533.297338912474, 4545.36364908913, 4660.951285960632, 4662.487126416091, 4687.296905464208, 4694.3427920090535, 4706.929396059062, 4109.438523239202, 4249.383839343278, 4668.4631267404, 4767.189577771787, 5056.241563765391, 4795.568398844121, 5163.819266325736, 5509.32797593167, 5601.6027418477115, 5693.946619875336, 1298.5909438526835, 1307.3136603283162, 1374.7044385457175, 1466.1349803615917, 1559.1190977097904, 1112.8497652816766, 1180.7874582575218, 1186.463529729003, 1187.1630500497129, 1199.2684462477882, 4695.389599352063, 5006.595515049801, 5022.522860328336, 5194.558462530652, 5221.530062294336, 4678.1813182692895, 4990.975045124751, 5514.6691198528215, 5784.248047753765, 5785.5286827903765, 4711.0, 4857.247126695368, 5161.27742116104, 5184.429712731597, 5244.9737751310995, 2552.961705574418, 2954.4386566552284, 2991.790574659753, 2997.4604571177397, 3042.6500952390315, 5326.227696983916, 5369.657585603599, 5934.970325148347, 5974.61165024272, 5997.460228579449, 4756.809727610926, 4957.082939656103, 5399.6490228135835, 5835.270591176103, 5911.4975227105315, 5255.973720131375, 5579.9163012554845, 5953.2735506702575, 6043.969780151071, 6067.362926892701, 5012.172991456368, 5362.544183744425, 5734.913976290276, 5839.854003649943, 5865.5600829937075, 5238.371395432572, 5337.919931201112, 5385.6230063895155, 5723.627964182315, 5768.365479797135, 5987.850303742442, 6078.848028799315, 6079.878402432015, 6101.908471372844, 6173.0, 4045.7167998239843, 4560.726356418625, 4627.7686115693405, 4629.398178236807, 4647.883802904955, 5025.738417712118, 5307.708076055854, 5530.585206109477, 5764.913526297193, 5769.0, 4764.0, 5149.87125321855, 5306.548943339705, 5600.85997850057, 5672.461116193955, 5371.277798718513, 5467.535259502987, 5571.359293681278, 5608.7195640217005, 5610.783740422428, 4380.634457392972, 5097.719625420637, 5255.13290307095, 5365.651232669864, 5421.5391691706645, 5003.246266793217, 5264.131048578611, 5595.692236926845, 5610.49505544503, 5672.347680016685, 4906.779194936165, 5166.560842328444, 5356.018273238927, 5428.0, 5468.589855760754], "value": 4227.946671389767, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [658.5686375424099, 696.7839969609421, 708.2421808664731, 806.1696452653766, 827.2761333832897, 15230.695386092286, 15958.202089895502, 16198.83801161988, 16360.018398896074, 16383.09892955887, 14648.414063437476, 14976.251187440612, 15002.174880381575, 15160.317785699643, 15213.391464341414, 764.8355603545236, 895.5119459894373, 907.310444062514, 909.9226565741919, 933.995330023349, 918.3158546882581, 928.972130836076, 931.2596485793777, 932.1796818799447, 933.8412469880103, 980.4264505264429, 982.228950274035, 982.990170098297, 983.9508024598778, 985.3250523391464, 962.6101428921298, 970.1026550440849, 976.2385339435181, 978.3787295067613, 980.2599037726542, 721.4264659595634, 725.8004048886546, 725.8548290341953, 726.8800647893091, 727.439871299102, 16046.35814567416, 16087.356505739757, 16331.265093070722, 16690.998540087632, 16867.0, 13818.0, 13945.093568917988, 13979.860201397958, 14213.644658883508, 14241.78637320441, 9478.957314695377, 9698.806023879553, 9774.0, 10599.47002649872, 11916.940415297955, 4410.451372777932, 4452.443444569429, 4485.641148708128, 4497.595216430537, 4497.707649002837, 99954.51114476928, 102549.48725256401, 105327.47336263346, 106282.0, 106541.46729266382, 10086.193104551618, 10292.030771528836, 10366.740831479274, 10446.791064178673, 10462.738431539121, 20761.273355432488, 21038.368848934555, 21371.35885923425, 21383.893080534497, 21445.67831482529, 7511.06111736033, 7586.431017673696, 7687.308142267223, 7697.807554811175, 7868.527888326718, 4249.787510624456, 4251.723637963522, 4318.5681431856565, 4377.015171586368, 4519.796609152596, 3127.5465057566526, 3402.0984439123654, 3426.3661222673886, 3454.7581669283413, 3462.79223246606, 602.9547783916224, 606.6481440764435, 614.9723262453111, 624.2976651267325, 627.028106435033, 504.4955044955045, 506.70864253053907, 524.6511070138315, 528.7152220105181, 543.2774410034644, 7788.493747906368, 9589.952050239704, 9861.901380986243, 9868.506574671237, 9908.25688073383, 624.8125562331282, 655.3151956205777, 659.1134923527943, 662.3906006474056, 682.8941514065327, 4219.430376899079, 4373.431453910971, 4543.750093744809, 4808.3027960945465, 4815.012922350909, 100326.4983675077, 100544.49727751315, 101336.49331753442, 103419.0, 105151.0, 68549.65725171342, 69291.65354173197, 81593.0, 84641.57679211687, 89708.10291897129, 62964.05553916695, 67868.3213167862, 68704.0, 70153.94769078468, 77756.61121694469, 8010.919890801018, 8644.394892357726, 10272.178225741773, 10661.200409969439, 10809.783804323712, 40511.3923291145, 43184.35223471588, 43488.0, 47000.36456070359, 49954.50045499499, 53862.576184071026, 58621.70689146527, 60940.695296523234, 62048.75902482015, 63549.68225158845, 12493.125481216228, 12689.619311420673, 12969.675758105745, 13071.0, 13639.181649101085, 14417.42330306776, 14651.853481465048, 14798.408063677332, 15030.248487575358, 15040.849591503946, 11489.0, 12819.974402047626, 13064.54274100402, 13492.257925813781, 13538.458461661423, 12540.623781286577, 13304.46782128704, 13891.583252502442, 13898.444062237802, 14151.43394264218, 12395.566155184526, 13277.203367797963, 13349.998750093588, 13434.261115638734, 13446.93276533611, 9682.0, 9996.600135994478, 10304.587816487257, 10394.22043346737, 10782.892171078503, 8953.746475493306, 10527.578896844347, 11108.222424430212, 11142.554297827995, 11146.386948717574], "value": 7374.930341094344, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1633.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 181.0, 108.0, 175.0, 182.0, 182.0, 111.0, 167.0, 123.0, 183.0], "value": 152.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 112.0, 114.0, 142.0, 114.0, 140.0, 153.0, 118.0, 121.0, 97.0], "value": 124.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 209.0, 198.0, 201.0, 200.0, 238.0, 277.0, 228.0, 289.0, 206.0], "value": 223.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 61.0, 61.0, 86.0, 63.0, 72.0, 85.0, 89.0, 100.0, 66.0], "value": 76.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 65.0, 102.0, 57.0, 77.0, 100.0, 72.0, 76.0, 97.0, 71.0], "value": 79.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 94.0, 93.0, 94.0, 94.0, 85.0, 96.0, 93.0, 94.0, 93.0], "value": 95.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 143.0, 155.0, 99.0, 99.0, 120.0, 154.0, 140.0, 100.0, 101.0], "value": 121.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 91.0, 91.0, 93.0, 90.0, 89.0, 93.0, 88.0, 145.0, 138.0], "value": 106.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 88.0, 99.0, 106.0, 83.0, 69.0, 70.0, 60.0, 90.0], "value": 79.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 58.0, 63.0, 60.0, 65.0, 62.0, 59.0, 59.0, 59.0, 59.0], "value": 60.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 83.0, 86.0, 84.0, 84.0, 88.0, 89.0, 84.0, 92.0], "value": 85.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 141.0, 142.0, 139.0, 140.0, 141.0, 144.0, 156.0, 211.0, 180.0], "value": 153.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 188.0, 223.0, 221.0, 173.0, 226.0, 207.0, 183.0, 214.0, 151.0], "value": 195.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 69.0, 95.0, 96.0, 88.0, 87.0, 85.0, 60.0, 76.0], "value": 79.1, "unit": "score"}]}]} 02:01:57 INFO - Return code: 0 02:01:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:01:57 ERROR - # TBPL SUCCESS # 02:01:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:01:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:01:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:01:57 INFO - 2017-04-23 02:01:57.947 screenresolution[1934:15165] starting screenresolution argv=screenresolution get 02:01:57 INFO - 2017-04-23 02:01:57.949 screenresolution[1934:15165] Display 0: 1600x1200x32@60 02:01:57 INFO - 2017-04-23 02:01:57.955 screenresolution[1935:15168] starting screenresolution argv=screenresolution list 02:01:57 INFO - Available Modes on Display 0 02:01:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:01:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:01:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:01:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:01:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:01:58 INFO - Intel Iris: 02:01:58 INFO - Chipset Model: Intel Iris 02:01:58 INFO - Type: GPU 02:01:58 INFO - Bus: Built-In 02:01:58 INFO - VRAM (Dynamic, Max): 1536 MB 02:01:58 INFO - Vendor: Intel (0x8086) 02:01:58 INFO - Device ID: 0x0a2e 02:01:58 INFO - Revision ID: 0x0009 02:01:58 INFO - Displays: 02:01:58 INFO - Display: 02:01:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:01:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:01:58 INFO - Display Serial Number: 1600x1200 60 02:01:58 INFO - Main Display: Yes 02:01:58 INFO - Mirror: Off 02:01:58 INFO - Online: Yes 02:01:58 INFO - Rotation: Supported 02:01:58 INFO - Return code: 0 02:01:58 INFO - Running post-action listener: _resource_record_post_action 02:01:58 INFO - [mozharness: 2017-04-23 09:01:58.200237Z] Finished run-tests step (success) 02:01:58 INFO - Running post-run listener: _resource_record_post_run 02:01:58 INFO - Total resource usage - Wall time: 1203s; CPU: 55.0%; Read bytes: 3355648; Write bytes: 514242560; Read time: 28; Write time: 1634 02:01:58 INFO - TinderboxPrint: CPU usage
54.5% 02:01:58 INFO - TinderboxPrint: I/O read bytes / time
3,355,648 / 28 02:01:58 INFO - TinderboxPrint: I/O write bytes / time
514,242,560 / 1,634 02:01:58 INFO - TinderboxPrint: CPU idle
2,189.0 (45.5%) 02:01:58 INFO - TinderboxPrint: CPU system
970.8 (20.2%) 02:01:58 INFO - TinderboxPrint: CPU user
1,653.9 (34.4%) 02:01:58 INFO - TinderboxPrint: Swap in / out
212,230,144 / 0 02:01:58 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 207823360; Write bytes: 200491008; Read time: 16592; Write time: 427 02:01:58 INFO - run-tests - Wall time: 1182s; CPU: 55.0%; Read bytes: 2368512; Write bytes: 303605760; Read time: 24; Write time: 1168 02:01:58 INFO - Running post-run listener: _upload_blobber_files 02:01:58 INFO - Blob upload gear active. 02:01:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:01:58 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:01:58 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:01:58 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:01:59 INFO - (blobuploader) - INFO - Open directory for files ... 02:01:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:01:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:01:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:01:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:01:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:01:59 INFO - (blobuploader) - INFO - Done attempting. 02:01:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:01:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:01:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:02:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:02:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:02:00 INFO - (blobuploader) - INFO - Done attempting. 02:02:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:02:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:02:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:02:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:02:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:02:01 INFO - (blobuploader) - INFO - Done attempting. 02:02:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:02:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:02:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:02:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:02:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:02:04 INFO - (blobuploader) - INFO - Done attempting. 02:02:04 INFO - (blobuploader) - INFO - Iteration through files over. 02:02:04 INFO - Return code: 0 02:02:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:02:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:02:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c24f400b3f2c380f4d05798741b4a5f96e9e3dd56deea0421dd199093f7696e6f344e747defd739cbea3d9b63ec22dba8300a6a6570716a3aa2741508cf156ea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6b86fa10eeb14bfeb3e3c009771bc5f6d8b72038e04c38e29c5689c1e73475a1efd575f1e9d854163da677264186024724d70665fedb719d41754531187b3dc9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b716c196247f1defe381a69029ec7ecebd009f0ef2820ff957071f25045a0215d79963e666c90e8ddd355d0fa6cd8ec648d11af8ee0912f2cd6304645ffccbe9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b30e1836a1cd780f4bb15fa1d0657120a948fccc905411a5677bc5f2d3f5031e513a3aaaa7e78707afd1895ca372f499220069bb1218d5f7f43b711904b92146"} 02:02:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:02:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:02:04 INFO - Contents: 02:02:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c24f400b3f2c380f4d05798741b4a5f96e9e3dd56deea0421dd199093f7696e6f344e747defd739cbea3d9b63ec22dba8300a6a6570716a3aa2741508cf156ea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6b86fa10eeb14bfeb3e3c009771bc5f6d8b72038e04c38e29c5689c1e73475a1efd575f1e9d854163da677264186024724d70665fedb719d41754531187b3dc9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b716c196247f1defe381a69029ec7ecebd009f0ef2820ff957071f25045a0215d79963e666c90e8ddd355d0fa6cd8ec648d11af8ee0912f2cd6304645ffccbe9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b30e1836a1cd780f4bb15fa1d0657120a948fccc905411a5677bc5f2d3f5031e513a3aaaa7e78707afd1895ca372f499220069bb1218d5f7f43b711904b92146"} 02:02:04 INFO - Running post-run listener: copy_logs_to_upload_dir 02:02:04 INFO - Copying logs to upload dir... 02:02:04 INFO - mkdir: /builds/slave/test/build/upload/logs 02:02:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.599878 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-23 02:02:04.224486) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-23 02:02:04.227847) ========= 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.ITidXcYLed/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.Q4l8rMHFij/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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/c24f400b3f2c380f4d05798741b4a5f96e9e3dd56deea0421dd199093f7696e6f344e747defd739cbea3d9b63ec22dba8300a6a6570716a3aa2741508cf156ea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6b86fa10eeb14bfeb3e3c009771bc5f6d8b72038e04c38e29c5689c1e73475a1efd575f1e9d854163da677264186024724d70665fedb719d41754531187b3dc9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b716c196247f1defe381a69029ec7ecebd009f0ef2820ff957071f25045a0215d79963e666c90e8ddd355d0fa6cd8ec648d11af8ee0912f2cd6304645ffccbe9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b30e1836a1cd780f4bb15fa1d0657120a948fccc905411a5677bc5f2d3f5031e513a3aaaa7e78707afd1895ca372f499220069bb1218d5f7f43b711904b92146"} build_url:https://queue.taskcluster.net/v1/task/Weo5WpOATtO__VTGyv8-1A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011079 build_url: 'https://queue.taskcluster.net/v1/task/Weo5WpOATtO__VTGyv8-1A/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/c24f400b3f2c380f4d05798741b4a5f96e9e3dd56deea0421dd199093f7696e6f344e747defd739cbea3d9b63ec22dba8300a6a6570716a3aa2741508cf156ea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6b86fa10eeb14bfeb3e3c009771bc5f6d8b72038e04c38e29c5689c1e73475a1efd575f1e9d854163da677264186024724d70665fedb719d41754531187b3dc9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b716c196247f1defe381a69029ec7ecebd009f0ef2820ff957071f25045a0215d79963e666c90e8ddd355d0fa6cd8ec648d11af8ee0912f2cd6304645ffccbe9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b30e1836a1cd780f4bb15fa1d0657120a948fccc905411a5677bc5f2d3f5031e513a3aaaa7e78707afd1895ca372f499220069bb1218d5f7f43b711904b92146"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-23 02:02:04.263991) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-23 02:02:04.264362) ========= 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.ITidXcYLed/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.Q4l8rMHFij/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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.005138 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-23 02:02:04.315773) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-23 02:02:04.316356) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-23 02:02:04.317037) ========= ========= Total master_lag: 0.21 =========