builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0386 starttime: 1491516813.77 results: success (0) buildid: 20170406140533 builduid: f46dd91c189a464a9ee9ecbc0e616314 revision: 0578378a87248e250d3f15fa3ae0943dc21de6a2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 15:13:33.769628) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 15:13:33.770186) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 15:13:33.770527) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 15:13:33.796823) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 15:13:33.797238) ========= 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.e4Zs3CjQSb/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.zr8ndD2sXE/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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-06 15:13:33-- 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% 145M=0s 2017-04-06 15:13:35 (145 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.321639 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 15:13:35.143400) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 15:13:35.143818) ========= 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.e4Zs3CjQSb/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.zr8ndD2sXE/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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.020774 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 15:13:35.193075) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-06 15:13:35.193505) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0578378a87248e250d3f15fa3ae0943dc21de6a2 --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 0578378a87248e250d3f15fa3ae0943dc21de6a2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.e4Zs3CjQSb/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.zr8ndD2sXE/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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-06 15:13:35,291 truncating revision to first 12 chars 2017-04-06 15:13:35,291 Setting DEBUG logging. 2017-04-06 15:13:35,291 attempt 1/10 2017-04-06 15:13:35,291 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/0578378a8724?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-06 15:13:37,831 unpacking tar archive at: mozilla-aurora-0578378a8724/testing/mozharness/ program finished with exit code 0 elapsedTime=2.804589 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-06 15:13:38.025949) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 15:13:38.026466) ========= script_repo_revision: 0578378a87248e250d3f15fa3ae0943dc21de6a2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 15:13:38.026968) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 15:13:38.027292) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 15:13:38.048195) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-06 15:13:38.048965) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.e4Zs3CjQSb/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.zr8ndD2sXE/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 15:13:38 INFO - MultiFileLogger online at 20170406 15:13:38 in /builds/slave/test 15:13:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:13:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:13:38 INFO - {'append_to_log': False, 15:13:38 INFO - 'base_work_dir': '/builds/slave/test', 15:13:38 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:13:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:13:38 INFO - 'branch': 'Mozilla-Aurora', 15:13:38 INFO - 'buildbot_json_path': 'buildprops.json', 15:13:38 INFO - 'config_files': ('talos/mac_config.py',), 15:13:38 INFO - 'default_actions': ('clobber', 15:13:38 INFO - 'read-buildbot-config', 15:13:38 INFO - 'download-and-extract', 15:13:38 INFO - 'populate-webroot', 15:13:38 INFO - 'create-virtualenv', 15:13:38 INFO - 'install', 15:13:38 INFO - 'run-tests'), 15:13:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:13:38 INFO - 'download_minidump_stackwalk': True, 15:13:38 INFO - 'download_symbols': 'ondemand', 15:13:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:13:38 INFO - 'tooltool.py': '/tools/tooltool.py', 15:13:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:13:38 INFO - '/tools/misc-python/virtualenv.py')}, 15:13:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:13:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:13:38 INFO - 'gecko_profile': False, 15:13:38 INFO - 'gecko_profile_interval': 0, 15:13:38 INFO - 'installer_path': 'installer.exe', 15:13:38 INFO - 'log_level': 'info', 15:13:38 INFO - 'log_name': 'talos', 15:13:38 INFO - 'log_to_console': True, 15:13:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:13:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:13:38 INFO - 'opt_config_files': (), 15:13:38 INFO - 'pip_index': False, 15:13:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:13:38 INFO - 'cmd': ('bash', 15:13:38 INFO - '-c', 15:13:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:13:38 INFO - 'enabled': True, 15:13:38 INFO - 'halt_on_failure': False, 15:13:38 INFO - 'name': 'check_screen_resolution'},), 15:13:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:13:38 INFO - 'cmd': ('bash', 15:13:38 INFO - '-c', 15:13:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:13:38 INFO - 'enabled': True, 15:13:38 INFO - 'halt_on_failure': False, 15:13:38 INFO - 'name': 'check_screen_resolution'},), 15:13:38 INFO - 'run_cmd_checks_enabled': True, 15:13:38 INFO - 'suite': 'dromaeojs', 15:13:38 INFO - 'system_bits': '32', 15:13:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:13:38 INFO - 'title': 't-yosemite-r7-0386', 15:13:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:13:38 INFO - 'use_talos_json': True, 15:13:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:13:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:13:38 INFO - 'work_dir': 'build'} 15:13:38 INFO - [mozharness: 2017-04-06 22:13:38.272888Z] Running clobber step. 15:13:38 INFO - Running pre-action listener: _resource_record_pre_action 15:13:38 INFO - Running main action method: clobber 15:13:38 INFO - rmtree: /builds/slave/test/build 15:13:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:13:39 INFO - Running post-action listener: _resource_record_post_action 15:13:39 INFO - [mozharness: 2017-04-06 22:13:39.941923Z] Finished clobber step (success) 15:13:39 INFO - [mozharness: 2017-04-06 22:13:39.942174Z] Running read-buildbot-config step. 15:13:39 INFO - Running pre-action listener: _resource_record_pre_action 15:13:39 INFO - Running main action method: read_buildbot_config 15:13:39 INFO - Using buildbot properties: 15:13:39 INFO - { 15:13:39 INFO - "project": "", 15:13:39 INFO - "product": "firefox", 15:13:39 INFO - "script_repo_revision": "production", 15:13:39 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 15:13:39 INFO - "repository": "", 15:13:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 15:13:39 INFO - "buildid": "20170406140533", 15:13:39 INFO - "pgo_build": "False", 15:13:39 INFO - "basedir": "/builds/slave/test", 15:13:39 INFO - "buildnumber": 89, 15:13:39 INFO - "slavename": "t-yosemite-r7-0386", 15:13:39 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 15:13:39 INFO - "platform": "yosemite_r7", 15:13:39 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 15:13:39 INFO - "branch": "mozilla-aurora", 15:13:39 INFO - "repo_path": "releases/mozilla-aurora", 15:13:39 INFO - "revision": "0578378a87248e250d3f15fa3ae0943dc21de6a2", 15:13:39 INFO - "stage_platform": "macosx64", 15:13:39 INFO - "builduid": "f46dd91c189a464a9ee9ecbc0e616314", 15:13:39 INFO - "slavebuilddir": "test" 15:13:39 INFO - } 15:13:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/IillTN3VQh-hsDy-r7CdBg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 15:13:39 INFO - Running post-action listener: _resource_record_post_action 15:13:39 INFO - [mozharness: 2017-04-06 22:13:39.947592Z] Finished read-buildbot-config step (success) 15:13:39 INFO - [mozharness: 2017-04-06 22:13:39.947806Z] Running download-and-extract step. 15:13:39 INFO - Running pre-action listener: _resource_record_pre_action 15:13:39 INFO - Running main action method: download_and_extract 15:13:39 INFO - mkdir: /builds/slave/test/build/tests 15:13:39 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')]} 15:13:39 INFO - trying https://queue.taskcluster.net/v1/task/IillTN3VQh-hsDy-r7CdBg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 15:13:39 INFO - Downloading https://queue.taskcluster.net/v1/task/IillTN3VQh-hsDy-r7CdBg/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 15:13:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IillTN3VQh-hsDy-r7CdBg/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 15:13:41 INFO - Downloaded 1199 bytes. 15:13:41 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 15:13:41 INFO - Using the following test package requirements: 15:13:41 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 15:13:41 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:13:41 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 15:13:41 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:13:41 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 15:13:41 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:13:41 INFO - u'jsshell-mac.zip'], 15:13:41 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:13:41 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 15:13:41 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 15:13:41 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:13:41 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 15:13:41 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:13:41 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 15:13:41 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:13:41 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 15:13:41 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:13:41 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 15:13:41 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 15:13:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IillTN3VQh-hsDy-r7CdBg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 15:13:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IillTN3VQh-hsDy-r7CdBg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:13:41 INFO - Fetch https://queue.taskcluster.net/v1/task/IillTN3VQh-hsDy-r7CdBg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 15:13:43 INFO - Expected file size: 51790493 15:13:43 INFO - Obtained file size: 51790493 15:13:59 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 15:13:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IillTN3VQh-hsDy-r7CdBg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 15:13:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IillTN3VQh-hsDy-r7CdBg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:13:59 INFO - Fetch https://queue.taskcluster.net/v1/task/IillTN3VQh-hsDy-r7CdBg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 15:14:01 INFO - Expected file size: 51790493 15:14:01 INFO - Obtained file size: 51790493 15:14:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IillTN3VQh-hsDy-r7CdBg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 15:14:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IillTN3VQh-hsDy-r7CdBg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 15:14:16 INFO - Fetch https://queue.taskcluster.net/v1/task/IillTN3VQh-hsDy-r7CdBg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 15:14:17 INFO - Expected file size: 14404424 15:14:17 INFO - Obtained file size: 14404424 15:14:17 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')]} 15:14:17 INFO - trying https://queue.taskcluster.net/v1/task/IillTN3VQh-hsDy-r7CdBg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 15:14:17 INFO - Downloading https://queue.taskcluster.net/v1/task/IillTN3VQh-hsDy-r7CdBg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 15:14:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IillTN3VQh-hsDy-r7CdBg/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 15:14:19 INFO - Downloaded 74686679 bytes. 15:14:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IillTN3VQh-hsDy-r7CdBg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 15:14:19 INFO - mkdir: /builds/slave/test/properties 15:14:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:14:19 INFO - Writing to file /builds/slave/test/properties/build_url 15:14:19 INFO - Contents: 15:14:19 INFO - build_url:https://queue.taskcluster.net/v1/task/IillTN3VQh-hsDy-r7CdBg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 15:14:20 INFO - Running post-action listener: _resource_record_post_action 15:14:20 INFO - Running post-action listener: set_extra_try_arguments 15:14:20 INFO - [mozharness: 2017-04-06 22:14:20.621372Z] Finished download-and-extract step (success) 15:14:20 INFO - [mozharness: 2017-04-06 22:14:20.621517Z] Running populate-webroot step. 15:14:20 INFO - Running pre-action listener: _resource_record_pre_action 15:14:20 INFO - Running main action method: populate_webroot 15:14:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:14:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:14:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:14:20 INFO - '--tppagecycles', 15:14:20 INFO - '7'], 15:14:20 INFO - 'tests': ['tsvgm']}}, 15:14:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:14:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:14:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:14:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:14:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:14:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:14:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:14:20 INFO - 'talos_options': ['--disable-e10s'], 15:14:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:14:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:14:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:14:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:14:20 INFO - 'talos_options': ['--disable-e10s'], 15:14:20 INFO - 'tests': ['damp', 'tps']}, 15:14:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:14:20 INFO - 'tests': ['damp', 'tps']}, 15:14:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:14:20 INFO - 'tests': ['dromaeo_dom']}, 15:14:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:14:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:14:20 INFO - 'tests': ['basic_compositor_video']}, 15:14:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:14:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:14:20 INFO - 'tests': ['a11yr', 15:14:20 INFO - 'ts_paint', 15:14:20 INFO - 'tpaint', 15:14:20 INFO - 'sessionrestore', 15:14:20 INFO - 'sessionrestore_no_auto_restore', 15:14:20 INFO - 'tabpaint']}, 15:14:20 INFO - 'other-e10s': {'tests': ['a11yr', 15:14:20 INFO - 'ts_paint', 15:14:20 INFO - 'tpaint', 15:14:20 INFO - 'sessionrestore', 15:14:20 INFO - 'sessionrestore_no_auto_restore', 15:14:20 INFO - 'tabpaint']}, 15:14:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:14:20 INFO - 'ts_paint', 15:14:20 INFO - 'tpaint', 15:14:20 INFO - 'sessionrestore', 15:14:20 INFO - 'sessionrestore_no_auto_restore', 15:14:20 INFO - 'tabpaint']}, 15:14:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:14:20 INFO - 'ts_paint', 15:14:20 INFO - 'tpaint', 15:14:20 INFO - 'sessionrestore', 15:14:20 INFO - 'sessionrestore_no_auto_restore', 15:14:20 INFO - 'tabpaint']}, 15:14:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:14:20 INFO - 'tests': ['a11yr', 15:14:20 INFO - 'ts_paint', 15:14:20 INFO - 'tpaint', 15:14:20 INFO - 'sessionrestore', 15:14:20 INFO - 'sessionrestore_no_auto_restore', 15:14:20 INFO - 'tabpaint']}, 15:14:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:14:20 INFO - 'tests': ['a11yr', 15:14:20 INFO - 'ts_paint', 15:14:20 INFO - 'tpaint', 15:14:20 INFO - 'sessionrestore', 15:14:20 INFO - 'sessionrestore_no_auto_restore', 15:14:20 INFO - 'tabpaint']}, 15:14:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:14:20 INFO - 'tests': ['tsvgx', 15:14:20 INFO - 'tsvgr_opacity', 15:14:20 INFO - 'tart', 15:14:20 INFO - 'tscrollx', 15:14:20 INFO - 'cart', 15:14:20 INFO - 'tsvg_static']}, 15:14:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:14:20 INFO - 'tsvgr_opacity', 15:14:20 INFO - 'tart', 15:14:20 INFO - 'tscrollx', 15:14:20 INFO - 'cart', 15:14:20 INFO - 'tsvg_static']}, 15:14:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:14:20 INFO - 'talos_options': ['--disable-e10s'], 15:14:20 INFO - 'tests': ['tp5o']}, 15:14:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:14:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:14:20 INFO - 'talos_options': ['--disable-e10s', 15:14:20 INFO - '--xperf_path', 15:14:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:14:20 INFO - 'tests': ['tp5n']}, 15:14:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:14:20 INFO - 'talos_options': ['--xperf_path', 15:14:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:14:20 INFO - 'tests': ['tp5n']}}, 15:14:20 INFO - 'talos.zip': {'path': '', 15:14:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:14:20 INFO - Running post-action listener: _resource_record_post_action 15:14:20 INFO - [mozharness: 2017-04-06 22:14:20.640123Z] Finished populate-webroot step (success) 15:14:20 INFO - [mozharness: 2017-04-06 22:14:20.640258Z] Running create-virtualenv step. 15:14:20 INFO - Running pre-action listener: _resource_record_pre_action 15:14:20 INFO - Running main action method: create_virtualenv 15:14:20 INFO - Creating virtualenv /builds/slave/test/build/venv 15:14:20 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 15:14:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:14:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:14:20 INFO - Using real prefix '/tools/python27' 15:14:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:14:21 INFO - Installing distribute.............................................................................................................................................................................................done. 15:14:24 INFO - Installing pip.................done. 15:14:24 INFO - Return code: 0 15:14:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:14:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:14:24 INFO - Reading from file tmpfile_stdout 15:14:24 INFO - Output received: 15:14:24 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) 15:14:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:14:24 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')]} 15:14:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:24 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')]} 15:14:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:24 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 0x103989b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10398c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa845f3c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103767d30>, '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 0x10368edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10375cbe8>, '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.e4Zs3CjQSb/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.zr8ndD2sXE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 15:14:24 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 15:14:24 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 15:14:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e4Zs3CjQSb/Render', 15:14:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:14:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:14:24 INFO - 'HOME': '/Users/cltbld', 15:14:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:14:24 INFO - 'LOGNAME': 'cltbld', 15:14:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:24 INFO - 'MOZ_NO_REMOTE': '1', 15:14:24 INFO - 'NO_EM_RESTART': '1', 15:14:24 INFO - 'PAGER': '/bin/cat', 15:14:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:14:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:24 INFO - 'PWD': '/builds/slave/test', 15:14:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:14:24 INFO - 'SHELL': '/bin/bash', 15:14:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zr8ndD2sXE/Listeners', 15:14:24 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 15:14:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:14:24 INFO - 'USER': 'cltbld', 15:14:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:14:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:14:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:24 INFO - 'XPC_FLAGS': '0x0', 15:14:24 INFO - 'XPC_SERVICE_NAME': '0', 15:14:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:14:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:25 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 15:14:25 INFO - Cleaning up... 15:14:25 INFO - Return code: 0 15:14:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:14:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:25 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 0x103989b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10398c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa845f3c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103767d30>, '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 0x10368edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10375cbe8>, '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.e4Zs3CjQSb/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.zr8ndD2sXE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 15:14:25 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 15:14:25 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 15:14:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e4Zs3CjQSb/Render', 15:14:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:14:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:14:25 INFO - 'HOME': '/Users/cltbld', 15:14:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:14:25 INFO - 'LOGNAME': 'cltbld', 15:14:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:25 INFO - 'MOZ_NO_REMOTE': '1', 15:14:25 INFO - 'NO_EM_RESTART': '1', 15:14:25 INFO - 'PAGER': '/bin/cat', 15:14:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:14:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:25 INFO - 'PWD': '/builds/slave/test', 15:14:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:14:25 INFO - 'SHELL': '/bin/bash', 15:14:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zr8ndD2sXE/Listeners', 15:14:25 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 15:14:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:14:25 INFO - 'USER': 'cltbld', 15:14:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:14:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:14:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:25 INFO - 'XPC_FLAGS': '0x0', 15:14:25 INFO - 'XPC_SERVICE_NAME': '0', 15:14:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:14:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:25 INFO - Downloading/unpacking psutil>=3.1.1 15:14:25 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 15:14:25 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 15:14:25 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 15:14:25 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 15:14:25 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 15:14:25 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 15:14:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:14:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:14:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:14:29 INFO - Installing collected packages: psutil 15:14:29 INFO - Running setup.py install for psutil 15:14:29 INFO - building 'psutil._psutil_osx' extension 15:14:29 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 15:14:29 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 15:14:29 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 15:14:30 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 15:14:30 INFO - building 'psutil._psutil_posix' extension 15:14:30 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 15:14:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:14:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:14:30 INFO - ^ 15:14:30 INFO - 1 warning generated. 15:14:30 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 15:14:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:14:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:14:30 INFO - Successfully installed psutil 15:14:30 INFO - Cleaning up... 15:14:30 INFO - Return code: 0 15:14:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:14:30 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')]} 15:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:30 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')]} 15:14:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x103989b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10398c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa845f3c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103767d30>, '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 0x10368edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10375cbe8>, '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.e4Zs3CjQSb/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.zr8ndD2sXE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 15:14:30 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 15:14:30 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 15:14:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e4Zs3CjQSb/Render', 15:14:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:14:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:14:30 INFO - 'HOME': '/Users/cltbld', 15:14:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:14:30 INFO - 'LOGNAME': 'cltbld', 15:14:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:30 INFO - 'MOZ_NO_REMOTE': '1', 15:14:30 INFO - 'NO_EM_RESTART': '1', 15:14:30 INFO - 'PAGER': '/bin/cat', 15:14:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:14:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:30 INFO - 'PWD': '/builds/slave/test', 15:14:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:14:30 INFO - 'SHELL': '/bin/bash', 15:14:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zr8ndD2sXE/Listeners', 15:14:30 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 15:14:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:14:30 INFO - 'USER': 'cltbld', 15:14:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:14:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:14:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:30 INFO - 'XPC_FLAGS': '0x0', 15:14:30 INFO - 'XPC_SERVICE_NAME': '0', 15:14:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:14:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:14:30 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 15:14:30 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 15:14:30 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 15:14:30 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 15:14:30 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 15:14:30 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 15:14:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:14:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:14:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:14:33 INFO - Installing collected packages: mozsystemmonitor 15:14:33 INFO - Running setup.py install for mozsystemmonitor 15:14:33 INFO - Successfully installed mozsystemmonitor 15:14:33 INFO - Cleaning up... 15:14:33 INFO - Return code: 0 15:14:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:14:33 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')]} 15:14:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:33 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')]} 15:14:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:33 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 0x103989b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10398c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa845f3c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103767d30>, '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 0x10368edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10375cbe8>, '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.e4Zs3CjQSb/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.zr8ndD2sXE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 15:14:33 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 15:14:33 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 15:14:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e4Zs3CjQSb/Render', 15:14:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:14:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:14:33 INFO - 'HOME': '/Users/cltbld', 15:14:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:14:33 INFO - 'LOGNAME': 'cltbld', 15:14:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:33 INFO - 'MOZ_NO_REMOTE': '1', 15:14:33 INFO - 'NO_EM_RESTART': '1', 15:14:33 INFO - 'PAGER': '/bin/cat', 15:14:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:14:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:33 INFO - 'PWD': '/builds/slave/test', 15:14:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:14:33 INFO - 'SHELL': '/bin/bash', 15:14:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zr8ndD2sXE/Listeners', 15:14:33 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 15:14:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:14:33 INFO - 'USER': 'cltbld', 15:14:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:14:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:14:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:33 INFO - 'XPC_FLAGS': '0x0', 15:14:33 INFO - 'XPC_SERVICE_NAME': '0', 15:14:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:14:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:33 INFO - Downloading/unpacking jsonschema==2.5.1 15:14:33 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 15:14:33 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 15:14:33 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 15:14:33 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 15:14:33 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 15:14:33 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 15:14:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:14:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:14:36 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 15:14:36 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 15:14:36 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 15:14:36 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 15:14:36 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 15:14:36 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 15:14:36 INFO - Downloading functools32-3.2.3-2.tar.gz 15:14:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:14:36 INFO - warning: no files found matching '*.txt' 15:14:36 INFO - no previously-included directories found matching 'build' 15:14:36 INFO - no previously-included directories found matching 'dist' 15:14:36 INFO - no previously-included directories found matching '.git*' 15:14:36 INFO - Installing collected packages: jsonschema, functools32 15:14:36 INFO - Running setup.py install for functools32 15:14:37 INFO - warning: no files found matching '*.txt' 15:14:37 INFO - no previously-included directories found matching 'build' 15:14:37 INFO - no previously-included directories found matching 'dist' 15:14:37 INFO - no previously-included directories found matching '.git*' 15:14:37 INFO - Successfully installed jsonschema functools32 15:14:37 INFO - Cleaning up... 15:14:37 INFO - Return code: 0 15:14:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:14:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103989b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10398c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa845f3c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103767d30>, '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 0x10368edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10375cbe8>, '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.e4Zs3CjQSb/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.zr8ndD2sXE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 15:14:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:14:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:14:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e4Zs3CjQSb/Render', 15:14:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:14:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:14:37 INFO - 'HOME': '/Users/cltbld', 15:14:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:14:37 INFO - 'LOGNAME': 'cltbld', 15:14:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:37 INFO - 'MOZ_NO_REMOTE': '1', 15:14:37 INFO - 'NO_EM_RESTART': '1', 15:14:37 INFO - 'PAGER': '/bin/cat', 15:14:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:14:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:37 INFO - 'PWD': '/builds/slave/test', 15:14:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:14:37 INFO - 'SHELL': '/bin/bash', 15:14:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zr8ndD2sXE/Listeners', 15:14:37 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 15:14:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:14:37 INFO - 'USER': 'cltbld', 15:14:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:14:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:14:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:37 INFO - 'XPC_FLAGS': '0x0', 15:14:37 INFO - 'XPC_SERVICE_NAME': '0', 15:14:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:14:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:14:37 INFO - Cleaning up... 15:14:37 INFO - Return code: 0 15:14:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:14:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103989b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10398c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa845f3c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103767d30>, '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 0x10368edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10375cbe8>, '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.e4Zs3CjQSb/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.zr8ndD2sXE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 15:14:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:14:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:14:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e4Zs3CjQSb/Render', 15:14:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:14:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:14:37 INFO - 'HOME': '/Users/cltbld', 15:14:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:14:37 INFO - 'LOGNAME': 'cltbld', 15:14:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:37 INFO - 'MOZ_NO_REMOTE': '1', 15:14:37 INFO - 'NO_EM_RESTART': '1', 15:14:37 INFO - 'PAGER': '/bin/cat', 15:14:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:14:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:37 INFO - 'PWD': '/builds/slave/test', 15:14:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:14:37 INFO - 'SHELL': '/bin/bash', 15:14:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zr8ndD2sXE/Listeners', 15:14:37 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 15:14:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:14:37 INFO - 'USER': 'cltbld', 15:14:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:14:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:14:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:37 INFO - 'XPC_FLAGS': '0x0', 15:14:37 INFO - 'XPC_SERVICE_NAME': '0', 15:14:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:14:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:37 INFO - Downloading/unpacking blobuploader==1.2.4 15:14: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 15:14: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 15:14: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 15:14: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 15:14: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 15:14: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 15:14:40 INFO - Downloading blobuploader-1.2.4.tar.gz 15:14:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:14:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:14:40 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 15:14:40 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 15:14:40 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 15:14:40 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 15:14:40 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 15:14:40 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 15:14:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:14:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:14: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 15:14: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 15:14: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 15:14: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 15:14: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 15:14: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 15:14:41 INFO - Downloading docopt-0.6.1.tar.gz 15:14:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:14:41 INFO - Installing collected packages: blobuploader, requests, docopt 15:14:41 INFO - Running setup.py install for blobuploader 15:14:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:14:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:14:41 INFO - Running setup.py install for requests 15:14:42 INFO - Running setup.py install for docopt 15:14:42 INFO - Successfully installed blobuploader requests docopt 15:14:42 INFO - Cleaning up... 15:14:42 INFO - Return code: 0 15:14:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:14: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')]} 15:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14: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')]} 15:14:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:42 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 0x103989b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10398c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa845f3c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103767d30>, '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 0x10368edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10375cbe8>, '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.e4Zs3CjQSb/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.zr8ndD2sXE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 15:14:42 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 15:14:42 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 15:14:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e4Zs3CjQSb/Render', 15:14:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:14:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:14:42 INFO - 'HOME': '/Users/cltbld', 15:14:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:14:42 INFO - 'LOGNAME': 'cltbld', 15:14:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:42 INFO - 'MOZ_NO_REMOTE': '1', 15:14:42 INFO - 'NO_EM_RESTART': '1', 15:14:42 INFO - 'PAGER': '/bin/cat', 15:14:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:14:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:42 INFO - 'PWD': '/builds/slave/test', 15:14:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:14:42 INFO - 'SHELL': '/bin/bash', 15:14:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zr8ndD2sXE/Listeners', 15:14:42 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 15:14:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:14:42 INFO - 'USER': 'cltbld', 15:14:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:14:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:14:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:42 INFO - 'XPC_FLAGS': '0x0', 15:14:42 INFO - 'XPC_SERVICE_NAME': '0', 15:14:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:14:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:14:42 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-tXHQ6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:14:42 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-xt11Az-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:14:43 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-qBLacO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:14:43 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-NkZeJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:14:43 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-mCojLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:14:43 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-KP8uAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:14:43 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-ZKKIUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:14:43 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-aziW9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:14:43 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-ECunby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:14:43 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-DHSTQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:14:43 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-I9l1fN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:14:44 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-54S0Jv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:14:44 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-56mI4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:14:44 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-G5wa1L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:14:44 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-oTlYvo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:14:44 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-docpjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:14:44 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-0O9SOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:14:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:14:44 INFO - Running setup.py install for manifestparser 15:14:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:14:44 INFO - Running setup.py install for mozcrash 15:14:44 INFO - Running setup.py install for mozdebug 15:14:45 INFO - Running setup.py install for mozdevice 15:14:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:14:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:14:45 INFO - Running setup.py install for mozfile 15:14:45 INFO - Running setup.py install for mozhttpd 15:14:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:14:45 INFO - Running setup.py install for mozinfo 15:14:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:14:45 INFO - Running setup.py install for mozInstall 15:14:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:14:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:14:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:14:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:14:45 INFO - Running setup.py install for mozleak 15:14:45 INFO - Running setup.py install for mozlog 15:14:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:14:46 INFO - Running setup.py install for moznetwork 15:14:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:14:46 INFO - Running setup.py install for mozprocess 15:14:46 INFO - Running setup.py install for mozprofile 15:14:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:14:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:14:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:14:46 INFO - Running setup.py install for mozrunner 15:14:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:14:46 INFO - Running setup.py install for mozscreenshot 15:14:46 INFO - Running setup.py install for moztest 15:14:47 INFO - Running setup.py install for mozversion 15:14:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:14:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:14:47 INFO - Cleaning up... 15:14:47 INFO - Return code: 0 15:14:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:14:47 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')]} 15:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:47 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')]} 15:14:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:47 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 0x103989b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10398c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa845f3c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103767d30>, '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 0x10368edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10375cbe8>, '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.e4Zs3CjQSb/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.zr8ndD2sXE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 15:14:47 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 15:14:47 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 15:14:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e4Zs3CjQSb/Render', 15:14:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:14:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:14:47 INFO - 'HOME': '/Users/cltbld', 15:14:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:14:47 INFO - 'LOGNAME': 'cltbld', 15:14:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:47 INFO - 'MOZ_NO_REMOTE': '1', 15:14:47 INFO - 'NO_EM_RESTART': '1', 15:14:47 INFO - 'PAGER': '/bin/cat', 15:14:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:14:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:47 INFO - 'PWD': '/builds/slave/test', 15:14:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:14:47 INFO - 'SHELL': '/bin/bash', 15:14:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zr8ndD2sXE/Listeners', 15:14:47 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 15:14:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:14:47 INFO - 'USER': 'cltbld', 15:14:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:14:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:14:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:47 INFO - 'XPC_FLAGS': '0x0', 15:14:47 INFO - 'XPC_SERVICE_NAME': '0', 15:14:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:14:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:14:47 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-STEKkv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:14:47 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)) 15:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:14:47 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-p7blTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:14:47 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)) 15:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:14:47 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-7jP6FC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:14:47 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)) 15:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:14:47 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-vvt9J3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:14:47 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)) 15:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:14:47 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-hI2aqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:14:48 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)) 15:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:14:48 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-mCgPot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:14:48 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)) 15:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:14:48 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-XxJGv6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:14:48 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)) 15:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:14:48 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-2odf85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:14:48 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)) 15:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:14:48 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-eqHTrc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:14:48 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)) 15:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:14:48 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-7al0Rn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:14:48 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)) 15:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:14:48 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-aNelkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:14:48 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)) 15:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:14:48 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-WR2s8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:14:48 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)) 15:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:14:48 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-tFRb2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:14:48 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)) 15:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:14:48 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-nHxMEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:14:49 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)) 15:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:14:49 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-4jsqw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:14:49 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)) 15:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:14:49 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-q4zw5q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:14:49 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)) 15:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:14:49 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-M9DZ7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:14:49 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)) 15:14:49 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)) 15:14:49 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)) 15:14:49 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)) 15:14:49 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)) 15:14:49 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)) 15:14:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:14:49 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 15:14:49 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 15:14:49 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 15:14:49 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 15:14:49 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 15:14:49 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 15:14:51 INFO - Downloading blessings-1.6.tar.gz 15:14:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:14:52 INFO - Installing collected packages: blessings 15:14:52 INFO - Running setup.py install for blessings 15:14:52 INFO - Successfully installed blessings 15:14:52 INFO - Cleaning up... 15:14:52 INFO - Return code: 0 15:14:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:14:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:14:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:14:52 INFO - Reading from file tmpfile_stdout 15:14:52 INFO - Current package versions: 15:14:52 INFO - blessings == 1.6 15:14:52 INFO - blobuploader == 1.2.4 15:14:52 INFO - docopt == 0.6.1 15:14:52 INFO - functools32 == 3.2.3-2 15:14:52 INFO - jsonschema == 2.5.1 15:14:52 INFO - manifestparser == 1.1 15:14:52 INFO - mozInstall == 1.12 15:14:52 INFO - mozcrash == 1.0 15:14:52 INFO - mozdebug == 0.1 15:14:52 INFO - mozdevice == 0.49 15:14:52 INFO - mozfile == 1.2 15:14:52 INFO - mozhttpd == 0.7 15:14:52 INFO - mozinfo == 0.9 15:14:52 INFO - mozleak == 0.1 15:14:52 INFO - mozlog == 3.4 15:14:52 INFO - moznetwork == 0.27 15:14:52 INFO - mozprocess == 0.25 15:14:52 INFO - mozprofile == 0.28 15:14:52 INFO - mozrunner == 6.13 15:14:52 INFO - mozscreenshot == 0.1 15:14:52 INFO - mozsystemmonitor == 0.3 15:14:52 INFO - moztest == 0.8 15:14:52 INFO - mozversion == 1.4 15:14:52 INFO - psutil == 3.1.1 15:14:52 INFO - requests == 1.2.3 15:14:52 INFO - wsgiref == 0.1.2 15:14:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:14:52 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')]} 15:14:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:52 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')]} 15:14:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:52 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 0x103989b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10398c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa845f3c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103767d30>, '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 0x10368edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10375cbe8>, '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.e4Zs3CjQSb/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.zr8ndD2sXE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 15:14:52 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 15:14:52 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 15:14:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e4Zs3CjQSb/Render', 15:14:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:14:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:14:52 INFO - 'HOME': '/Users/cltbld', 15:14:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:14:52 INFO - 'LOGNAME': 'cltbld', 15:14:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:52 INFO - 'MOZ_NO_REMOTE': '1', 15:14:52 INFO - 'NO_EM_RESTART': '1', 15:14:52 INFO - 'PAGER': '/bin/cat', 15:14:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:14:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:52 INFO - 'PWD': '/builds/slave/test', 15:14:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:14:52 INFO - 'SHELL': '/bin/bash', 15:14:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zr8ndD2sXE/Listeners', 15:14:52 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 15:14:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:14:52 INFO - 'USER': 'cltbld', 15:14:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:14:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:14:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:52 INFO - 'XPC_FLAGS': '0x0', 15:14:52 INFO - 'XPC_SERVICE_NAME': '0', 15:14:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:14:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:52 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)) 15:14:52 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)) 15:14:52 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)) 15:14:52 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)) 15:14:52 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)) 15:14:52 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)) 15:14:52 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)) 15:14:52 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)) 15:14:52 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)) 15:14:52 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)) 15:14:52 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)) 15:14:52 INFO - Cleaning up... 15:14:52 INFO - Return code: 0 15:14:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:14:52 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')]} 15:14:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:52 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')]} 15:14:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:52 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 0x103989b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10398c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa845f3c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103767d30>, '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 0x10368edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10375cbe8>, '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.e4Zs3CjQSb/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.zr8ndD2sXE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 15:14:52 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 15:14:52 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 15:14:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e4Zs3CjQSb/Render', 15:14:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:14:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:14:52 INFO - 'HOME': '/Users/cltbld', 15:14:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:14:52 INFO - 'LOGNAME': 'cltbld', 15:14:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:52 INFO - 'MOZ_NO_REMOTE': '1', 15:14:52 INFO - 'NO_EM_RESTART': '1', 15:14:52 INFO - 'PAGER': '/bin/cat', 15:14:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:14:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:52 INFO - 'PWD': '/builds/slave/test', 15:14:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:14:52 INFO - 'SHELL': '/bin/bash', 15:14:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zr8ndD2sXE/Listeners', 15:14:52 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 15:14:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:14:52 INFO - 'USER': 'cltbld', 15:14:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:14:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:14:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:52 INFO - 'XPC_FLAGS': '0x0', 15:14:52 INFO - 'XPC_SERVICE_NAME': '0', 15:14:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:14:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:14:53 INFO - Cleaning up... 15:14:53 INFO - Return code: 0 15:14:53 INFO - Running post-action listener: _resource_record_post_action 15:14:53 INFO - Running post-action listener: _start_resource_monitoring 15:14:53 INFO - Starting resource monitoring. 15:14:53 INFO - [mozharness: 2017-04-06 22:14:53.214545Z] Finished create-virtualenv step (success) 15:14:53 INFO - [mozharness: 2017-04-06 22:14:53.215524Z] Running install step. 15:14:53 INFO - Running pre-action listener: _resource_record_pre_action 15:14:53 INFO - Running main action method: install 15:14:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:14:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:14:53 INFO - Reading from file tmpfile_stdout 15:14:53 INFO - Detecting whether we're running mozinstall >=1.0... 15:14:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:14:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:14:53 INFO - Reading from file tmpfile_stdout 15:14:53 INFO - Output received: 15:14:53 INFO - Usage: mozinstall [options] installer 15:14:53 INFO - Options: 15:14:53 INFO - -h, --help show this help message and exit 15:14:53 INFO - -d DEST, --destination=DEST 15:14:53 INFO - Directory to install application into. [default: 15:14:53 INFO - "/builds/slave/test"] 15:14:53 INFO - --app=APP Application being installed. [default: firefox] 15:14:53 INFO - mkdir: /builds/slave/test/build/application 15:14:53 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'] 15:14:53 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 15:15:13 INFO - Reading from file tmpfile_stdout 15:15:13 INFO - Output received: 15:15:13 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:15:13 INFO - Running post-action listener: _resource_record_post_action 15:15:13 INFO - [mozharness: 2017-04-06 22:15:13.628270Z] Finished install step (success) 15:15:13 INFO - [mozharness: 2017-04-06 22:15:13.628414Z] Running run-tests step. 15:15:13 INFO - Running pre-action listener: _resource_record_pre_action 15:15:13 INFO - Running main action method: run_tests 15:15:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:15:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:15:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:15:13 INFO - 2017-04-06 15:15:13.643 screenresolution[2790:43170] starting screenresolution argv=screenresolution get 15:15:13 INFO - 2017-04-06 15:15:13.646 screenresolution[2790:43170] Display 0: 1600x1200x32@60 15:15:13 INFO - 2017-04-06 15:15:13.651 screenresolution[2791:43173] starting screenresolution argv=screenresolution list 15:15:13 INFO - Available Modes on Display 0 15:15:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:15:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:15:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:15:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:15:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:15:13 INFO - Intel Iris: 15:15:13 INFO - Chipset Model: Intel Iris 15:15:13 INFO - Type: GPU 15:15:13 INFO - Bus: Built-In 15:15:13 INFO - VRAM (Dynamic, Max): 1536 MB 15:15:13 INFO - Vendor: Intel (0x8086) 15:15:13 INFO - Device ID: 0x0a2e 15:15:13 INFO - Revision ID: 0x0009 15:15:13 INFO - Displays: 15:15:13 INFO - Display: 15:15:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:15:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:15:13 INFO - Display Serial Number: 1600x1200 60 15:15:13 INFO - Main Display: Yes 15:15:13 INFO - Mirror: Off 15:15:13 INFO - Online: Yes 15:15:13 INFO - Rotation: Supported 15:15:13 INFO - Return code: 0 15:15:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:15:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:15:13 INFO - Python 2.7.3 15:15:13 INFO - Return code: 0 15:15:13 INFO - grabbing minidump binary from tooltool 15:15:13 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')]} 15:15:13 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 0x103767d30>, '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 0x10368edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10375cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:15:13 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 15:15:13 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 15:15:13 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 15:15:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:15:14 INFO - Return code: 0 15:15:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:15:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:15:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:15:14 INFO - ENV: RUST_BACKTRACE is now 1 15:15:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:15:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:15:14 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', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IillTN3VQh-hsDy-r7CdBg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0386', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:15:14 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 --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IillTN3VQh-hsDy-r7CdBg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0386 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:15:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e4Zs3CjQSb/Render', 15:15:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:15:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:15:14 INFO - 'HOME': '/Users/cltbld', 15:15:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:15:14 INFO - 'LOGNAME': 'cltbld', 15:15:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:15:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:15:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:14 INFO - 'MOZ_NO_REMOTE': '1', 15:15:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:15:14 INFO - 'NO_EM_RESTART': '1', 15:15:14 INFO - 'PAGER': '/bin/cat', 15:15:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:15:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:14 INFO - 'PWD': '/builds/slave/test', 15:15:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:15:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:15:14 INFO - 'RUST_BACKTRACE': '1', 15:15:14 INFO - 'SHELL': '/bin/bash', 15:15:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zr8ndD2sXE/Listeners', 15:15:14 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 15:15:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:15:14 INFO - 'USER': 'cltbld', 15:15:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:15:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:15:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:14 INFO - 'XPC_FLAGS': '0x0', 15:15:14 INFO - 'XPC_SERVICE_NAME': '0', 15:15:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:15:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IillTN3VQh-hsDy-r7CdBg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0386', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:15:14 INFO - mozversion application_buildid: 20170406140533 15:15:14 INFO - mozversion application_changeset: 0578378a87248e250d3f15fa3ae0943dc21de6a2 15:15:14 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:15:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:15:14 INFO - mozversion application_name: Firefox 15:15:14 INFO - mozversion application_remotingname: firefox-dev 15:15:14 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:15:14 INFO - mozversion application_vendor: Mozilla 15:15:14 INFO - mozversion application_version: 54.0a2 15:15:14 INFO - mozversion platform_buildid: 20170406140533 15:15:14 INFO - mozversion platform_changeset: 0578378a87248e250d3f15fa3ae0943dc21de6a2 15:15:14 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:15:14 INFO - mozversion platform_version: 54.0a2 15:15:14 INFO - using testdate: 1491516914 15:15:14 INFO - actual date: 1491516914 15:15:14 INFO - starting webserver on 'localhost:61600' 15:15:14 INFO - SUITE-START | Running 2 tests 15:15:14 INFO - TEST-START | dromaeo_css 15:15:14 INFO - operating with platform_type : mac_ 15:15:14 INFO - Initialising browser for dromaeo_css test... 15:15:14 INFO - TEST-INFO | started process 2801 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmpUICH55/profile http://localhost:61600/getInfo.html) 15:15:14 INFO - PROCESS | 2801 | 1491516914781 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 15:15:14 INFO - PROCESS | 2801 | 1491516914782 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method install 15:15:14 INFO - PROCESS | 2801 | 1491516914819 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 15:15:16 INFO - PROCESS | 2801 | __metrics Screen width/height:1600/1200 15:15:16 INFO - PROCESS | 2801 | colorDepth:24 15:15:16 INFO - PROCESS | 2801 | Browser inner width/height: 1024/689 15:15:16 INFO - PROCESS | 2801 | __metrics 15:15:16 INFO - PROCESS | 2801 | 1491516916782 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 15:15:16 INFO - PROCESS | 2801 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:15:16 INFO - PROCESS | 2801 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:15:17 INFO - TEST-INFO | 2801: exit 0 15:15:17 INFO - Browser initialized. 15:15:17 INFO - Running cycle 1/1 for dromaeo_css test... 15:15:17 INFO - TEST-INFO | started process 2803 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmpUICH55/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:15:17 INFO - PROCESS | 2803 | 1491516917875 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 15:15:17 INFO - PROCESS | 2803 | 1491516917876 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 15:15:29 INFO - PROCESS | 2803 | RSS: Main: 215060480 15:15:29 INFO - PROCESS | 2803 | 15:18:33 INFO - PROCESS | 2803 | Cycle 1(1): loaded http://localhost:61600/tests/dromaeo/cssquery-dojo.html (next: http://localhost:61600/tests/dromaeo/cssquery-ext.html) 15:18:35 INFO - PROCESS | 2803 | RSS: Main: 338927616 15:18:35 INFO - PROCESS | 2803 | 15:20:19 INFO - PROCESS | 2803 | ************************* 15:20:19 INFO - PROCESS | 2803 | A coding exception was thrown and uncaught in a Task. 15:20:19 INFO - PROCESS | 2803 | 15:20:19 ERROR - PROCESS | 2803 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:20:19 INFO - PROCESS | 2803 | Full stack: 15:20:19 INFO - PROCESS | 2803 | ************************* 15:20:19 INFO - PROCESS | 2803 | ************************* 15:20:19 INFO - PROCESS | 2803 | A coding exception was thrown and uncaught in a Task. 15:20:19 INFO - PROCESS | 2803 | 15:20:19 ERROR - PROCESS | 2803 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:20:19 INFO - PROCESS | 2803 | Full stack: 15:20:19 INFO - PROCESS | 2803 | ************************* 15:20:19 INFO - PROCESS | 2803 | ************************* 15:20:19 INFO - PROCESS | 2803 | A coding exception was thrown and uncaught in a Task. 15:20:19 INFO - PROCESS | 2803 | 15:20:19 ERROR - PROCESS | 2803 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:20:19 INFO - PROCESS | 2803 | Full stack: 15:20:19 INFO - PROCESS | 2803 | ************************* 15:20:19 INFO - PROCESS | 2803 | ************************* 15:20:19 INFO - PROCESS | 2803 | A coding exception was thrown and uncaught in a Task. 15:20:19 INFO - PROCESS | 2803 | 15:20:19 ERROR - PROCESS | 2803 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:20:19 INFO - PROCESS | 2803 | Full stack: 15:20:19 INFO - PROCESS | 2803 | ************************* 15:21:38 INFO - PROCESS | 2803 | Cycle 1(1): loaded http://localhost:61600/tests/dromaeo/cssquery-ext.html (next: http://localhost:61600/tests/dromaeo/cssquery-jquery.html) 15:21:38 INFO - PROCESS | 2803 | RSS: Main: 354385920 15:21:38 INFO - PROCESS | 2803 | 15:24:41 INFO - PROCESS | 2803 | Cycle 1(1): loaded http://localhost:61600/tests/dromaeo/cssquery-jquery.html (next: http://localhost:61600/tests/dromaeo/cssquery-mootools.html) 15:24:41 INFO - PROCESS | 2803 | RSS: Main: 328536064 15:24:41 INFO - PROCESS | 2803 | 15:27:44 INFO - PROCESS | 2803 | Cycle 1(1): loaded http://localhost:61600/tests/dromaeo/cssquery-mootools.html (next: http://localhost:61600/tests/dromaeo/cssquery-prototype.html) 15:27:44 INFO - PROCESS | 2803 | RSS: Main: 312418304 15:27:44 INFO - PROCESS | 2803 | 15:30:48 INFO - PROCESS | 2803 | Cycle 1(1): loaded http://localhost:61600/tests/dromaeo/cssquery-prototype.html (next: http://localhost:61600/tests/dromaeo/cssquery-yui.html) 15:30:49 INFO - PROCESS | 2803 | RSS: Main: 316170240 15:30:49 INFO - PROCESS | 2803 | 15:33:52 INFO - PROCESS | 2803 | Cycle 1(1): loaded http://localhost:61600/tests/dromaeo/cssquery-yui.html (next: http://localhost:61600/tests/dromaeo/cssquery-dojo.html) 15:33:53 INFO - PROCESS | 2803 | RSS: Main: 453353472 15:33:53 INFO - PROCESS | 2803 | 15:33:53 INFO - PROCESS | 2803 | __start_tp_report 15:33:53 INFO - PROCESS | 2803 | _x_x_mozilla_page_load 15:33:53 INFO - PROCESS | 2803 | _x_x_mozilla_page_load_details 15:33:53 INFO - PROCESS | 2803 | |i|pagename|runs| 15:33:53 INFO - PROCESS | 2803 | |0;dojo.html;2834.4331133773244;2860.1705505403434;3367.444371678673;3382.47571626398;3416.367971925194;4815.9277610835825;5887.85280367991;5900.055991041434;5921.911171332428;5980.970095149523;5853.970730146343;5967.731452084656;5973.731182096805;5994.520438364932;5998;4462.308342206963;5252.52727254548;5336.146216605344;5442.673439593617;5459.099248623972;5247.9212811807665;5259.684418934876;5292.36491621006;5473.261109750192;5479.917801232965;5337.386200586924;5349.919751203735;5379.542738867201;5428.782848686048;5434.8913021739545;5387.973060134714;5401.7299135043095;5413.106837371829;5418.187271909206;5436.048691478975;5173.042987047408;5297.152455607084;5329.147336426192;5347.572194224453;5416.322959630046;5723.313202415695;5730.914036289459;5738.741756620962;5848.912266316009;5922.940770592325;6243.937560624381;6332.556721029529;6339.936600633981;6343.302236754;6345.333739957284;5954.7915822946425;6165.815025549241;6406.711697973602;6418.486521078303;6450.0647406125845;5843.883122337615;5881.617694849821;6063.969680151615;6105.297890742532;6133.815985520441;6298;6398.424141827165;6437.710303036374;6504;6602.7689030883685;5166.276721259027;6332.715027823759;6334.398232167937;6335.904961425582;6354.046892966092;6331.113644089831;6362.9045564316575;6388.936110638927;6413.070104834773;6415.711292991827;6190.969045154746;6242.001279795211;6242.063690446378;6253.374662533801;6336.366363363719;5323.866903327448;6303.936960630427;6327.715252813635;6342.968285158545;6348.746050158034;5065.898682026339;5068.214426763858;5089.440161582223;5105.055564720538;5109.08036553424;4462.48681401643;4572.337011133433;4573.817047318136;4583.495815460296;4590.655700822452;5757.309122905194;5776.191333213354;5843.941560584425;5909.852253693692;5971.79098731551;5419.620626556103;6342.207224096988;6364.586301890455;6380.298167201604;6400.647964362005;5841.4450627190345;5892;5910.822675319747;5920.585559010914;5933.258342707161;5387.703676297763;5519.862003449946;5585.134304182858;5600.0759874620235;5608.990381731328;5895.702945352015;6097.359777223505;6312.274088479883;6337.493000559944;6549.967250163719;6332.810015699536;6334.619922804646;6341.90487142684;6348.269948955929;6355.110284560073;6452.9675251961335;6517.185351831021;6534.738610455719;6558.245801732671;6559.376859198369;6145.279321789906;6185.072239164161;6191.1641928339095;6293.3391993841815;6339.904901426575;6212.9068063979985;6269.905951410641;6377.585456945284;6378.043293506012;6382.936170638234;6135.7839197735275;6182.072689096672;6233.594816337105;6261.123442718114;6265.906011409924;4935.580475659465;6196.721147548281;6287.2769631492965;6290.402411770874;6302.527310451643;5382.192671099367;5604.551635869202;5917.526597872074;6024.578279520391;6125.84685382869;6226.283977342482;6255.124282600348;6275.246970363585;6276.089966954857;6296.2759282681245;6096.512279017579;6099;6107.480864126599;6111.694415279308;6118.816435506942 15:33:53 INFO - PROCESS | 2803 | |1;ext.html;710.2968061618997;812.2649002652602;822.1244374740902;832.6669332267093;835.4527784301281;37881.43177852331;38450.80774596127;40138.595149169785;40149;40151.59848401519;38512.03719907004;38780.03049923753;38782.224355512946;38816;38945.61054389455;7443.962780186119;7638.809029774245;7850.371970242381;7993.400494962872;8011.359091272713;10953.780924381508;11216.20540713486;11323.32060076398;11331.206815522917;11362.943185284103;11190.160737944647;11193.27243729159;11196.720081997933;11209.551617935314;11217.99038086572;7368.521046131984;7551.358134558569;9295.674651387237;11129.054030407384;11140.387278699627;10830.241883068189;10912.017918387304;10926.945365273203;10935.945320273348;10937;39572.40641390382;39894;39917.20165596701;40033.19933601312;40097;3736.1967177057063;3857.2092720992127;3860.6332398422105;3869.2648396804516;3908.101136738566;27545;29282.707172928425;29828;29971.70028299711;29978.25054373636;2952.5571164325306;2993.206800197949;3003.38430621722;3211.1169428407143;3227.7579181561478;340860;341431;342056;342574.57425425673;345740;51472.970540588976;51775.48224517782;51895.221571676455;51916;52035;54199.72900135474;54240.915181696146;54241.72879135579;54277.45722542724;54320.913581728935;13606.9794765393;13716.177029378268;13748.312584370742;13771.104878182887;13796.310184490736;11780.469878855472;11802.822957655642;11819.468123934443;11821.231619944676;11846.822297665542;10925.781484370267;12269.07981901361;12291.201071930474;12340.50637974489;12412;4103.199876024147;4583.931241031387;5060.873478163076;5282.577393808486;5400.162974738922;1094.7591529863416;1107.8781334053247;1150.292570069418;1193.26614132308;1193.3198077096145;25796;26271.737282627313;26471.867640661672;26479.735202648113;26516;1212.5998420521207;1230.5385480444834;1243.5253844848687;1256.7612153690952;1285.755706415778;12613.810792838114;13473.223608970371;13568.761154409609;13653.317334133253;13697.657558560917;459287;459977;460009.39990599663;460728;461142;54672.17991730127;54699;54751;54900;54975.450245497035;1399.5451478269604;1448.601634550507;1450.0284809177665;1456.9198694071715;1460.1968917095555;20827.68758468594;28065.017724379548;30499.390012199186;31022.844885775427;31249;50871;51272.74363628307;51332.74333628308;51347;51360.74319628378;390052.0994790016;394419;394682;394736;395867;32981.340373192885;33761.66238337585;33819;33855;34369.82815085909;41826.79086604548;42629.786851065546;42655.36016959686;42992.785036074616;43823;29779.55330670085;30076;30324.393512129194;30445.391092178474;30537.694623053485;23594.882025589763;29026.709732903244;29694.406111878074;30034.84982575073;30060.84969575138;35032.64967350396;36099;36143.27713445769;36213.63786362103;36236.63763362438;29177.562336564544;29729.702702972692;30557.54163687502;30642.540361894142;30953.226169345948;22792.658110128028;23735;23982.640260396456;24263.757362426855;24343.756562434148 15:33:53 INFO - PROCESS | 2803 | |2;jquery.html;385.73384364788313;424.64966402717755;426.6396023042297;430.6404152532635;439.90981848721;15568.45510407136;20551.686262762436;21146.788532114675;21320.680189797167;21334.57330853382;21573;22065.448363790914;22554;23190.30429087126;23305.533889322203;5202.6358154928985;6538.692261547686;6543.411093001629;6734.56225345351;6756.49326300527;5814.912776308338;6607.933920660804;6637.535372523926;6675.532712710112;6682.231543372502;6321.620702757849;6551.541392102555;6614.966925165368;6618.238902526223;6670.232923213844;6381.3937675920715;6538.280789113193;6541.182352205974;6599.70301336441;6607.141071660653;6478.676066196718;6487.870242595169;6495.188101487314;6500.9349906501275;6507.154069970921;25973.350666233495;26669.73330266692;26689.866550667317;26696;27091.45817083647;2488.489859578782;2915.50436425806;2916.7958242923005;2918.212082737649;2925.1809493341684;16088.436904708278;16824.07467589294;16962.49112526626;17173;17464.650706985914;1988.3836010836687;2032.1972820735728;2044.9386518404472;2060.8660437071694;2069.6688529835305;126363.73636263704;134257.2871356438;144932.27533862262;144957;146071;14427.286261060985;17202.31190752381;20821.854797985954;21941.561168776854;22243.11027558912;34350.62597496072;35936;35961.280774384366;36976.44535331972;37011.07472313214;7929.603519823986;9416.95291523538;9792.902070979342;10099.14157296638;10168.69493915169;8109.959450202829;8111.797205069921;8149;8272.766353084753;8291.33669306454;7875;9571.33000689945;9632.807343853083;9764.853527197098;9768.267379946532;3497.877574284888;3911.381971676188;3967.583403742623;4027.4764280643913;4052.77709725962;881.3345923827469;892.8883889513811;904.1139683110589;928.0997432490474;942.1285311087217;18138.81861181398;21116.993885580847;22227.11091556352;22330.55338893213;22355.764423557514;897.7665806890255;914.8902131744234;916.486767410239;924.1820988425292;927.3601215161517;11310.886891131147;11501.36742479155;11527.193096483166;11706.063514918786;11789;729600;746281;746959;758999;764744;146418.26790866192;147509;148691.51308486777;151504;154737.22631386772;1199.6700907250577;1217.1844863941003;1238.5169783784331;1239.4794186441716;1245.6076335954147;18272.634547309244;19415.902920485307;19636.018199090275;19650.901745491752;19662.606747865248;46512.767436162605;58198;61243.693781530805;61714.76570468476;62027.6898615504;72876.63561682157;76786.92852286069;81697;89474.55262723645;90178.64732028893;10028.69913902584;11366.033887119687;11496.425178741198;11787.941060294987;11792.587259445878;11632.12759043092;12792.296423696785;12804;12863.356832158543;12908.16096953695;10945.452727363441;11055.61305354341;11083.612073577386;11434.199606027498;11444.656660300205;10059.849102263619;10153.39079655223;10154.593816247267;10172.898271017491;10219.7445063874;10982.670519884416;11064.39145846954;11067;11246.831297530707;11335.149863760085;11461.541538338373;11473.483693233991;11496.080313575061;11506;11772.470238839102;10798.514066867167;10941.343519388862;10952.56189752401;11076.840016196764;11185.272957257752 15:33:53 INFO - PROCESS | 2803 | |3;mootools.html;1546.5283088657955;1554.5802633289013;1583.8812089093321;1590.8806839487038;1592.6098105964038;10638.893611063888;11247.55009799608;11251.268667536624;11253.54985800567;11301.604443844457;10720.463976801168;11153.609623663173;11321.433928303593;11330.886691133086;11349.829752553698;2421.515696860626;2456.3981824453035;2457.3610861176135;2460.520198561281;2463;2307.3770082077913;2352.776486233805;2362.3031205794246;2362.5393048355577;2439.256026911791;2346.436855154759;2366.2191476812695;2391.0555330644384;2443.9755602443925;2444.6699695541142;2342.8360014798973;2394.6288325309606;2402.735699073109;2405.2423486601665;2410.1203060882654;2365.349528879555;2388.8924998375114;2395.808335333169;2395.9520809583887;2402.087206861399;10818.567257309698;11082.556697732081;11104.722381940515;11105.223164293755;11112.388818614972;5796.449337312949;5967.49276311514;5969.224000879858;5977.820665380045;5980.372060933583;3573.874914377984;3637.3270944875285;3650.9269814603826;3710.9443358349645;3717.5353080864893;1196.234409977615;1202.807550791878;1234.7098431868503;1259.93070381129;1260.602910083321;13653.522126725617;13969.79045314321;14260.001799874117;15718.921405392899;15904.840951590451;4625.259958406638;4810.037992401533;4812.687175333592;4829.468758436569;4859.271109333628;11742.94313511771;11752.882471175179;11758.823617645741;11771.764564708657;11796.646100616996;4933.383327084114;5098.980203959149;5759.798407055733;6026.246719160105;6069.393060693984;5138.3577052868395;5308.177232528887;5374.91937620936;5426.593005524602;5435.918461223085;4100.794960251976;4107.856225032109;4163.604457576525;4194.874153775391;4225.68307376948;1150.246588484544;1154.6766905266538;1161.355447726503;1173.7945859474557;1177.9823302650468;924.2513564013036;926.5413620257881;941.8540126280437;949.292776881228;993.3493561717087;6501.1548498694865;6539.607623542603;6719.361660642231;6788.083608712859;6793.150856142982;831.8294749576322;979.8628192053118;994.4480813148772;1003.9498025098716;1036.6164519127972;4109.712320137562;4116.9794151029655;4259.552746961586;4263.232618128766;4325.9134817303475;12455.688607784698;13773.862261377459;13783.448662053606;13801.51694690681;13820.930895345458;12938.417771200318;13633.727325453436;13719.725605487834;13724.93137534306;13779.931100344435;9914.702558923243;10218.437985910847;10219.386836789816;10225.59097636101;10231.386116833013;6787.359485096858;6822.556533825285;6891.689873955584;6946.097007389007;7017.403520700594;10608.04378867195;12561.183523070971;12578;12604.306763128116;12709.80935285953;10630.787384252426;12404.069694772748;12437.067219958359;12619.87380126187;12733.87266127364;9944.900550994398;10199.337043092175;10331.915148909557;10361;10365.481725913825;10211.285210035227;10398.792024159626;10580.682579522627;10728.034476897117;10733.409662468326;8765.254953328784;8810.735677929672;8889.955550222208;8922.86615700778;9248.630054797733;7766.728164514441;8352.958235208785;8399.942588184093;8730.301575874042;8815.779605509913;6833.555818871758;7758.728444504415;8025.07711613172;8186.058603260702;8197.385196110195;5801.723620803416;5995.730192141451;6003.8198854034445;6034.275886893601;6197.287311959182;5902.439268269508;6002.249718785152;6101.725422356093;6212.47193988516;6277.435030847246 15:33:53 INFO - PROCESS | 2803 | |4;prototype.html;321.96941290577394;366.39178962921557;383.07105269720927;384.7941351377014;385.2968332792654;4545.545445455453;4804.83183088592;5015.824446144386;5237.659552129109;5290.947090529094;4538.00327855744;4923.753812309379;4938.777550102451;4974.751262436882;4980.676256043354;3672.2740312053293;4183.7657890922255;4198.004374794001;4204.1801848639525;4257.659387249028;3477.6000759912554;3618.684042212986;3719.6466335698065;3932.233214523169;4232.3228283474655;3737.8317975691025;3918.471006414126;4140.958590414088;4146.896327591804;4222.59885310895;4094.7747873866924;4103.466549348595;4120.855770048064;4151.937720934188;4176.519700234481;3868.2650296443585;3875.844966201377;3876.7480113792512;3923.2545816294814;3926.293267211901;4044.898877528056;4196.979015104905;4479.641628669731;4603.884902877421;5054.898902021939;4183.895402614959;4189.91620167598;4261.808218630138;4743.288506723985;4763.785629646674;4661.930071048937;4789.3294938708605;5171.50870667286;5271.789128434858;5511.53151982082;3822.827972741233;4002.599740026003;4264.552222016706;4372.103718737651;4379.627731642814;4644.628429725613;5391.433899440542;5439.836804895859;5578.704397613855;5733.655980641175;3803.410471376941;4051.2707712611423;4233.364995250737;4320.583932859701;4334;3996.760194388346;4070.816813243352;4403.581659742319;4416.867493975186;4467.463904331501;4094.706964100054;4459.487158976694;4469;4549.270437737331;4772.737499437564;4980.900381992412;5054.9241761373605;5319.361676598755;5321.897850349168;5368.275282836845;1387.9028468007143;1392.7771556550904;1406.542873566095;1469.9338529766187;1511.7430036893534;1027.7893031928436;1045.0542259255385;1060.1465820014807;1126.4680035152187;1160.988390116088;4336.934945975813;4793.202549044108;4835.58897493717;4907.582855457326;4955.529224723646;4479.865604031885;4597.896504838882;4699.835505757282;5515.159692758232;5631.493165615114;4633.745144017112;4750.691205071728;4819.542143496362;4967.503249675004;5005.698518385173;2677.6251324814543;2712.0372267845028;2720.5988915708426;2761.5995680626197;2881;4804.351412559329;4826.8069277228515;5630.0991841305195;5716.5998380113;5747.741351639186;4820.156472617278;5418.972905135528;5577.693226872561;5645.23789288449;5646.842397308542;5059.30513278056;5506.5870059744875;5636.336381352154;5654.802081927113;5666.575006874501;4762.476127625889;5283.5773138149625;5321.6540924839765;5510;5634.85912852182;4992.700437973734;5340.813071542478;5404.5406140476925;5405.540529055074;5422.322209723785;4980.352554167935;5155.845324640267;5206.713630750345;5407.729183641763;5501.092319767194;3649.1571756262915;3739.691108112139;3862.9227415452096;3963.742356746802;4356.782160891879;4794.5924596409695;4974.980129073459;5464.28964234647;5537.667739935617;5554.833354999357;5259.764600045042;5271.710055946806;5318.946810531846;5427.6472029317965;5439.456054394529;5204.375474943031;5321.441248668834;5323.574114070788;5350.919736204038;5353.812616558402;4627.8611641650805;4788.784504697367;4930.654854160174;5233.947660523346;5256.553192978639;4787.736674482937;4799.616030717464;5131.615128865275;5281.841544753664;5323.760430780701;4763.190257656138;4905.632077594123;4965.900681986413;5191.974040129775;5236.345456817898 15:33:53 INFO - PROCESS | 2803 | |5;yui.html;710.5878590417558;722.4328901812075;739.7078154129117;750.3546949623325;790.1505881177735;15052.397904083837;15804.683906321867;15813.446529371475;15876.841231587696;15892.12593307369;14552.126872387638;14563;14581.854181458182;14743.705125897475;14743.77884331736;927.726320735383;961.9470929098896;985.1232403161157;991.8016396720685;1002.939823610582;977.3940157102571;980.5783513089368;980.6126580000931;981.5386768218925;981.8723565936418;1026.1380440430037;1026.4508487958944;1027.6762819711766;1028.408665017614;1029.9691009269734;1019.887812340642;1021.4024795494645;1021.9284650074497;1022.8823685276215;1023.5087158164049;763.3053920931925;764.0754686828943;764.0945479606684;765.1124695353419;768.166539304855;15826.762598560916;15906.920465397714;15956.840431595652;16348.100854453;16593.087380194083;13790.931045344709;13891.79162312566;14022.71954560903;14074.577762667033;14088;11578.189526733133;11597;11690.47392867323;11840.703982400422;11858;3519.454484554898;3609.873654422083;3679.5032670589394;3707.295613833363;3807.866724664651;110393.44803276012;112171;112664.43667781609;113316;116537;9722.805543889224;10618.787624247472;10742.140628749681;10745.194110441747;11034.117270618332;22423;22560.435989100075;22730.545389092455;22742.8862855688;22976.540469190524;7607.847843043108;7694.769156925301;7719.305262526287;7769.3784497240085;7785.523745301984;4228.577142285747;4314.848980285675;4367.665906971404;4476.552344765498;4512.887177820515;3471.756977011585;3542.0613537412605;3546.5921419036627;3565.3938830398893;3575;614.7110857896781;619;627.6140173793084;646.6734299178885;649.2890285137767;660.7489154121403;679.6029687919155;697.0972590495321;703.4794252253398;721.7401735375261;9046.502442365601;9577.185939195107;9986.10125088731;10114.53339265817;10213.387196768217;564.5060571999501;583.6060659055206;589.1810383566846;593.0866465642948;593.3117583603;4150.3566947123245;4542.295944128599;4988.825391111366;4997.9750101249265;5024.698518088926;101037;101539.49230253801;101801;102554;102653;54062;59084.70457647684;81566;82764.58617706873;85420.7186892197;55325.446745532834;66680.33319666742;67611;68262;69102;10515.421651809223;10623.734406639896;10765.3540787553;10859.51132199037;10866.945665271624;46504.507907475636;46523.53476465192;46696.53303466922;47091.17452952834;47610;45849.77075114603;59403.40596593979;60002;61655.76688466295;61987.1403857885;11580.826287605862;12156.331401772633;12160.513579456723;12298.385080745747;12827.615171544869;13768.449262029406;14265.215413152377;14267.143971361751;14409.639759006108;14620.342084606431;12792.61622151337;13512.864871351554;13707.750597314864;13874.861251387762;13920.443182273;12553.623391298275;13453.46186152582;13494.32528373597;13503;13869.722605548035;12142.635720928387;13137.802932956205;13407.061505694897;13584.932075339955;13828.654283642989;9284.35009549352;9378.718638440858;9670;9691.854622180814;9926.25553083536;8796.428232164888;10526.947365263431;10970.890291097307;11144.275622084539;11207.383593902414 15:33:53 INFO - PROCESS | 2803 | __end_tp_report 15:33:53 INFO - PROCESS | 2803 | __start_cc_report 15:33:53 INFO - PROCESS | 2803 | _x_x_mozilla_cycle_collect,1640 15:33:53 INFO - PROCESS | 2803 | __end_cc_report 15:33:53 INFO - PROCESS | 2803 | __startTimestamp1491518033135__endTimestamp 15:33:53 INFO - PROCESS | 2803 | 15:33:53 INFO - PROCESS | 2803 | ------- Summary: start ------- 15:33:53 INFO - PROCESS | 2803 | Number of tests: 6 15:33:53 INFO - PROCESS | 2803 | 15:33:53 INFO - PROCESS | 2803 | [#0] dojo.html Cycles:165 Average:5829.66 Median:6063.97 stddev:687.48 (11.3%) stddev-sans-first:648.20 15:33:53 INFO - PROCESS | 2803 | Values: 2834.4 2860.2 3367.4 3382.5 3416.4 4815.9 5887.9 5900.1 5921.9 5981.0 5854.0 5967.7 5973.7 5994.5 5998.0 4462.3 5252.5 5336.1 5442.7 5459.1 5247.9 5259.7 5292.4 5473.3 5479.9 5337.4 5349.9 5379.5 5428.8 5434.9 5388.0 5401.7 5413.1 5418.2 5436.0 5173.0 5297.2 5329.1 5347.6 5416.3 5723.3 5730.9 5738.7 5848.9 5922.9 6243.9 6332.6 6339.9 6343.3 6345.3 5954.8 6165.8 6406.7 6418.5 6450.1 5843.9 5881.6 6064.0 6105.3 6133.8 6298.0 6398.4 6437.7 6504.0 6602.8 5166.3 6332.7 6334.4 6335.9 6354.0 6331.1 6362.9 6388.9 6413.1 6415.7 6191.0 6242.0 6242.1 6253.4 6336.4 5323.9 6303.9 6327.7 6343.0 6348.7 5065.9 5068.2 5089.4 5105.1 5109.1 4462.5 4572.3 4573.8 4583.5 4590.7 5757.3 5776.2 5843.9 5909.9 5971.8 5419.6 6342.2 6364.6 6380.3 6400.6 5841.4 5892.0 5910.8 5920.6 5933.3 5387.7 5519.9 5585.1 5600.1 5609.0 5895.7 6097.4 6312.3 6337.5 6550.0 6332.8 6334.6 6341.9 6348.3 6355.1 6453.0 6517.2 6534.7 6558.2 6559.4 6145.3 6185.1 6191.2 6293.3 6339.9 6212.9 6269.9 6377.6 6378.0 6382.9 6135.8 6182.1 6233.6 6261.1 6265.9 4935.6 6196.7 6287.3 6290.4 6302.5 5382.2 5604.6 5917.5 6024.6 6125.8 6226.3 6255.1 6275.2 6276.1 6296.3 6096.5 6099.0 6107.5 6111.7 6118.8 15:33:53 INFO - PROCESS | 2803 | 15:33:53 INFO - PROCESS | 2803 | [#1] ext.html Cycles:180 Average:54275.55 Median:3219.44 stddev:106397.33 (3304.8%) stddev-sans-first:106619.79 15:33:53 INFO - PROCESS | 2803 | Values: 710.3 812.3 822.1 832.7 835.5 37881.4 38450.8 40138.6 40149.0 40151.6 38512.0 38780.0 38782.2 38816.0 38945.6 7444.0 7638.8 7850.4 7993.4 8011.4 10953.8 11216.2 11323.3 11331.2 11362.9 11190.2 11193.3 11196.7 11209.6 11218.0 7368.5 7551.4 9295.7 11129.1 11140.4 10830.2 10912.0 10926.9 10935.9 10937.0 39572.4 39894.0 39917.2 40033.2 40097.0 3736.2 3857.2 3860.6 3869.3 3908.1 27545.0 29282.7 29828.0 29971.7 29978.3 2952.6 2993.2 3003.4 3211.1 3227.8 340860.0 341431.0 342056.0 342574.6 345740.0 51473.0 51775.5 51895.2 51916.0 52035.0 54199.7 54240.9 54241.7 54277.5 54320.9 13607.0 13716.2 13748.3 13771.1 13796.3 11780.5 11802.8 11819.5 11821.2 11846.8 10925.8 12269.1 12291.2 12340.5 12412.0 4103.2 4583.9 5060.9 5282.6 5400.2 1094.8 1107.9 1150.3 1193.3 1193.3 25796.0 26271.7 26471.9 26479.7 26516.0 1212.6 1230.5 1243.5 1256.8 1285.8 12613.8 13473.2 13568.8 13653.3 13697.7 459287.0 459977.0 460009.4 460728.0 461142.0 54672.2 54699.0 54751.0 54900.0 54975.5 1399.5 1448.6 1450.0 1456.9 1460.2 20827.7 28065.0 30499.4 31022.8 31249.0 50871.0 51272.7 51332.7 51347.0 51360.7 390052.1 394419.0 394682.0 394736.0 395867.0 32981.3 33761.7 33819.0 33855.0 34369.8 41826.8 42629.8 42655.4 42992.8 43823.0 29779.6 30076.0 30324.4 30445.4 30537.7 23594.9 29026.7 29694.4 30034.8 30060.8 35032.6 36099.0 36143.3 36213.6 36236.6 29177.6 29729.7 30557.5 30642.5 30953.2 22792.7 23735.0 23982.6 24263.8 24343.8 15:33:53 INFO - PROCESS | 2803 | 15:33:53 INFO - PROCESS | 2803 | [#2] jquery.html Cycles:180 Average:42296.14 Median:23247.92 stddev:124539.06 (535.7%) stddev-sans-first:124848.66 15:33:53 INFO - PROCESS | 2803 | Values: 385.7 424.6 426.6 430.6 439.9 15568.5 20551.7 21146.8 21320.7 21334.6 21573.0 22065.4 22554.0 23190.3 23305.5 5202.6 6538.7 6543.4 6734.6 6756.5 5814.9 6607.9 6637.5 6675.5 6682.2 6321.6 6551.5 6615.0 6618.2 6670.2 6381.4 6538.3 6541.2 6599.7 6607.1 6478.7 6487.9 6495.2 6500.9 6507.2 25973.4 26669.7 26689.9 26696.0 27091.5 2488.5 2915.5 2916.8 2918.2 2925.2 16088.4 16824.1 16962.5 17173.0 17464.7 1988.4 2032.2 2044.9 2060.9 2069.7 126363.7 134257.3 144932.3 144957.0 146071.0 14427.3 17202.3 20821.9 21941.6 22243.1 34350.6 35936.0 35961.3 36976.4 37011.1 7929.6 9417.0 9792.9 10099.1 10168.7 8110.0 8111.8 8149.0 8272.8 8291.3 7875.0 9571.3 9632.8 9764.9 9768.3 3497.9 3911.4 3967.6 4027.5 4052.8 881.3 892.9 904.1 928.1 942.1 18138.8 21117.0 22227.1 22330.6 22355.8 897.8 914.9 916.5 924.2 927.4 11310.9 11501.4 11527.2 11706.1 11789.0 729600.0 746281.0 746959.0 758999.0 764744.0 146418.3 147509.0 148691.5 151504.0 154737.2 1199.7 1217.2 1238.5 1239.5 1245.6 18272.6 19415.9 19636.0 19650.9 19662.6 46512.8 58198.0 61243.7 61714.8 62027.7 72876.6 76786.9 81697.0 89474.6 90178.6 10028.7 11366.0 11496.4 11787.9 11792.6 11632.1 12792.3 12804.0 12863.4 12908.2 10945.5 11055.6 11083.6 11434.2 11444.7 10059.8 10153.4 10154.6 10172.9 10219.7 10982.7 11064.4 11067.0 11246.8 11335.1 11461.5 11473.5 11496.1 11506.0 11772.5 10798.5 10941.3 10952.6 11076.8 11185.3 15:33:53 INFO - PROCESS | 2803 | 15:33:53 INFO - PROCESS | 2803 | [#3] mootools.html Cycles:180 Average:6793.92 Median:2407.68 stddev:4191.74 (174.1%) stddev-sans-first:4184.95 15:33:53 INFO - PROCESS | 2803 | Values: 1546.5 1554.6 1583.9 1590.9 1592.6 10638.9 11247.6 11251.3 11253.5 11301.6 10720.5 11153.6 11321.4 11330.9 11349.8 2421.5 2456.4 2457.4 2460.5 2463.0 2307.4 2352.8 2362.3 2362.5 2439.3 2346.4 2366.2 2391.1 2444.0 2444.7 2342.8 2394.6 2402.7 2405.2 2410.1 2365.3 2388.9 2395.8 2396.0 2402.1 10818.6 11082.6 11104.7 11105.2 11112.4 5796.4 5967.5 5969.2 5977.8 5980.4 3573.9 3637.3 3650.9 3710.9 3717.5 1196.2 1202.8 1234.7 1259.9 1260.6 13653.5 13969.8 14260.0 15718.9 15904.8 4625.3 4810.0 4812.7 4829.5 4859.3 11742.9 11752.9 11758.8 11771.8 11796.6 4933.4 5099.0 5759.8 6026.2 6069.4 5138.4 5308.2 5374.9 5426.6 5435.9 4100.8 4107.9 4163.6 4194.9 4225.7 1150.2 1154.7 1161.4 1173.8 1178.0 924.3 926.5 941.9 949.3 993.3 6501.2 6539.6 6719.4 6788.1 6793.2 831.8 979.9 994.4 1003.9 1036.6 4109.7 4117.0 4259.6 4263.2 4325.9 12455.7 13773.9 13783.4 13801.5 13820.9 12938.4 13633.7 13719.7 13724.9 13779.9 9914.7 10218.4 10219.4 10225.6 10231.4 6787.4 6822.6 6891.7 6946.1 7017.4 10608.0 12561.2 12578.0 12604.3 12709.8 10630.8 12404.1 12437.1 12619.9 12733.9 9944.9 10199.3 10331.9 10361.0 10365.5 10211.3 10398.8 10580.7 10728.0 10733.4 8765.3 8810.7 8890.0 8922.9 9248.6 7766.7 8353.0 8399.9 8730.3 8815.8 6833.6 7758.7 8025.1 8186.1 8197.4 5801.7 5995.7 6003.8 6034.3 6197.3 5902.4 6002.2 6101.7 6212.5 6277.4 15:33:53 INFO - PROCESS | 2803 | 15:33:53 INFO - PROCESS | 2803 | [#4] prototype.html Cycles:180 Average:4407.10 Median:4768.26 stddev:1233.47 (25.9%) stddev-sans-first:1198.22 15:33:53 INFO - PROCESS | 2803 | Values: 322.0 366.4 383.1 384.8 385.3 4545.5 4804.8 5015.8 5237.7 5290.9 4538.0 4923.8 4938.8 4974.8 4980.7 3672.3 4183.8 4198.0 4204.2 4257.7 3477.6 3618.7 3719.6 3932.2 4232.3 3737.8 3918.5 4141.0 4146.9 4222.6 4094.8 4103.5 4120.9 4151.9 4176.5 3868.3 3875.8 3876.7 3923.3 3926.3 4044.9 4197.0 4479.6 4603.9 5054.9 4183.9 4189.9 4261.8 4743.3 4763.8 4661.9 4789.3 5171.5 5271.8 5511.5 3822.8 4002.6 4264.6 4372.1 4379.6 4644.6 5391.4 5439.8 5578.7 5733.7 3803.4 4051.3 4233.4 4320.6 4334.0 3996.8 4070.8 4403.6 4416.9 4467.5 4094.7 4459.5 4469.0 4549.3 4772.7 4980.9 5054.9 5319.4 5321.9 5368.3 1387.9 1392.8 1406.5 1469.9 1511.7 1027.8 1045.1 1060.1 1126.5 1161.0 4336.9 4793.2 4835.6 4907.6 4955.5 4479.9 4597.9 4699.8 5515.2 5631.5 4633.7 4750.7 4819.5 4967.5 5005.7 2677.6 2712.0 2720.6 2761.6 2881.0 4804.4 4826.8 5630.1 5716.6 5747.7 4820.2 5419.0 5577.7 5645.2 5646.8 5059.3 5506.6 5636.3 5654.8 5666.6 4762.5 5283.6 5321.7 5510.0 5634.9 4992.7 5340.8 5404.5 5405.5 5422.3 4980.4 5155.8 5206.7 5407.7 5501.1 3649.2 3739.7 3862.9 3963.7 4356.8 4794.6 4975.0 5464.3 5537.7 5554.8 5259.8 5271.7 5318.9 5427.6 5439.5 5204.4 5321.4 5323.6 5350.9 5353.8 4627.9 4788.8 4930.7 5233.9 5256.6 4787.7 4799.6 5131.6 5281.8 5323.8 4763.2 4905.6 4965.9 5192.0 5236.3 15:33:53 INFO - PROCESS | 2803 | 15:33:53 INFO - PROCESS | 2803 | [#5] yui.html Cycles:180 Average:19491.47 Median:13224.15 stddev:27929.01 (211.2%) stddev-sans-first:27971.75 15:33:53 INFO - PROCESS | 2803 | Values: 710.6 722.4 739.7 750.4 790.2 15052.4 15804.7 15813.4 15876.8 15892.1 14552.1 14563.0 14581.9 14743.7 14743.8 927.7 961.9 985.1 991.8 1002.9 977.4 980.6 980.6 981.5 981.9 1026.1 1026.5 1027.7 1028.4 1030.0 1019.9 1021.4 1021.9 1022.9 1023.5 763.3 764.1 764.1 765.1 768.2 15826.8 15906.9 15956.8 16348.1 16593.1 13790.9 13891.8 14022.7 14074.6 14088.0 11578.2 11597.0 11690.5 11840.7 11858.0 3519.5 3609.9 3679.5 3707.3 3807.9 110393.4 112171.0 112664.4 113316.0 116537.0 9722.8 10618.8 10742.1 10745.2 11034.1 22423.0 22560.4 22730.5 22742.9 22976.5 7607.8 7694.8 7719.3 7769.4 7785.5 4228.6 4314.8 4367.7 4476.6 4512.9 3471.8 3542.1 3546.6 3565.4 3575.0 614.7 619.0 627.6 646.7 649.3 660.7 679.6 697.1 703.5 721.7 9046.5 9577.2 9986.1 10114.5 10213.4 564.5 583.6 589.2 593.1 593.3 4150.4 4542.3 4988.8 4998.0 5024.7 101037.0 101539.5 101801.0 102554.0 102653.0 54062.0 59084.7 81566.0 82764.6 85420.7 55325.4 66680.3 67611.0 68262.0 69102.0 10515.4 10623.7 10765.4 10859.5 10866.9 46504.5 46523.5 46696.5 47091.2 47610.0 45849.8 59403.4 60002.0 61655.8 61987.1 11580.8 12156.3 12160.5 12298.4 12827.6 13768.4 14265.2 14267.1 14409.6 14620.3 12792.6 13512.9 13707.8 13874.9 13920.4 12553.6 13453.5 13494.3 13503.0 13869.7 12142.6 13137.8 13407.1 13584.9 13828.7 9284.4 9378.7 9670.0 9691.9 9926.3 8796.4 10526.9 10970.9 11144.3 11207.4 15:33:53 INFO - PROCESS | 2803 | -------- Summary: end -------- 15:33:53 INFO - PROCESS | 2803 | 15:33:53 INFO - PROCESS | 2803 | 1491518033158 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 15:33:53 INFO - TEST-INFO | 2803: exit 0 15:33:53 INFO - TEST-OK | dromaeo_css | took 1119560ms 15:33:53 INFO - TEST-START | kraken 15:33:53 INFO - operating with platform_type : mac_ 15:33:53 INFO - Initialising browser for kraken test... 15:33:53 INFO - TEST-INFO | started process 2807 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmpM0B8CP/profile http://localhost:61600/getInfo.html) 15:33:54 INFO - PROCESS | 2807 | 1491518034322 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 15:33:54 INFO - PROCESS | 2807 | 1491518034323 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method install 15:33:54 INFO - PROCESS | 2807 | 1491518034359 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 15:33:56 INFO - PROCESS | 2807 | __metrics Screen width/height:1600/1200 15:33:56 INFO - PROCESS | 2807 | colorDepth:24 15:33:56 INFO - PROCESS | 2807 | Browser inner width/height: 1024/689 15:33:56 INFO - PROCESS | 2807 | __metrics 15:33:56 INFO - PROCESS | 2807 | 1491518036150 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 15:33:56 INFO - PROCESS | 2807 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:33:56 INFO - PROCESS | 2807 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:33:56 INFO - TEST-INFO | 2807: exit 0 15:33:56 INFO - Browser initialized. 15:33:56 INFO - Running cycle 1/1 for kraken test... 15:33:56 INFO - TEST-INFO | started process 2808 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmpM0B8CP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:33:57 INFO - PROCESS | 2808 | 1491518037293 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 15:33:57 INFO - PROCESS | 2808 | 1491518037294 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 15:34:09 INFO - PROCESS | 2808 | RSS: Main: 211288064 15:34:09 INFO - PROCESS | 2808 | 15:34:12 INFO - PROCESS | 2808 | Cycle 1(1): loaded http://localhost:61600/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:61600/tests/kraken/driver.html?testName=audio-beat-detection) 15:34:13 INFO - PROCESS | 2808 | RSS: Main: 264478720 15:34:13 INFO - PROCESS | 2808 | 15:34:15 INFO - PROCESS | 2808 | Cycle 1(1): loaded http://localhost:61600/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:61600/tests/kraken/driver.html?testName=audio-dft) 15:34:16 INFO - PROCESS | 2808 | RSS: Main: 277602304 15:34:16 INFO - PROCESS | 2808 | 15:34:19 INFO - PROCESS | 2808 | Cycle 1(1): loaded http://localhost:61600/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:61600/tests/kraken/driver.html?testName=audio-fft) 15:34:20 INFO - PROCESS | 2808 | RSS: Main: 374546432 15:34:20 INFO - PROCESS | 2808 | 15:34:22 INFO - PROCESS | 2808 | Cycle 1(1): loaded http://localhost:61600/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:61600/tests/kraken/driver.html?testName=audio-oscillator) 15:34:22 INFO - PROCESS | 2808 | RSS: Main: 277729280 15:34:22 INFO - PROCESS | 2808 | 15:34:24 INFO - PROCESS | 2808 | Cycle 1(1): loaded http://localhost:61600/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:61600/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:34:25 INFO - PROCESS | 2808 | RSS: Main: 278900736 15:34:25 INFO - PROCESS | 2808 | 15:34:28 INFO - PROCESS | 2808 | Cycle 1(1): loaded http://localhost:61600/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:61600/tests/kraken/driver.html?testName=imaging-darkroom) 15:34:28 INFO - PROCESS | 2808 | RSS: Main: 321556480 15:34:28 INFO - PROCESS | 2808 | 15:34:31 INFO - PROCESS | 2808 | Cycle 1(1): loaded http://localhost:61600/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:61600/tests/kraken/driver.html?testName=imaging-desaturate) 15:34:32 INFO - PROCESS | 2808 | RSS: Main: 320983040 15:34:32 INFO - PROCESS | 2808 | 15:34:35 INFO - PROCESS | 2808 | Cycle 1(1): loaded http://localhost:61600/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:61600/tests/kraken/driver.html?testName=json-parse-financial) 15:34:36 INFO - PROCESS | 2808 | RSS: Main: 318795776 15:34:36 INFO - PROCESS | 2808 | 15:34:37 INFO - PROCESS | 2808 | Cycle 1(1): loaded http://localhost:61600/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:61600/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:34:38 INFO - PROCESS | 2808 | RSS: Main: 295907328 15:34:38 INFO - PROCESS | 2808 | 15:34:40 INFO - PROCESS | 2808 | Cycle 1(1): loaded http://localhost:61600/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:61600/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:34:40 INFO - PROCESS | 2808 | RSS: Main: 298582016 15:34:40 INFO - PROCESS | 2808 | 15:34:42 INFO - PROCESS | 2808 | Cycle 1(1): loaded http://localhost:61600/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:61600/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:34:43 INFO - PROCESS | 2808 | RSS: Main: 302231552 15:34:43 INFO - PROCESS | 2808 | 15:34:46 INFO - PROCESS | 2808 | Cycle 1(1): loaded http://localhost:61600/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:61600/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:34:47 INFO - PROCESS | 2808 | RSS: Main: 319303680 15:34:47 INFO - PROCESS | 2808 | 15:34:50 INFO - PROCESS | 2808 | Cycle 1(1): loaded http://localhost:61600/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:61600/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:34:50 INFO - PROCESS | 2808 | RSS: Main: 312647680 15:34:50 INFO - PROCESS | 2808 | 15:34:52 INFO - PROCESS | 2808 | Cycle 1(1): loaded http://localhost:61600/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:61600/tests/kraken/driver.html?testName=ai-astar) 15:34:52 INFO - PROCESS | 2808 | RSS: Main: 357044224 15:34:52 INFO - PROCESS | 2808 | 15:34:52 INFO - PROCESS | 2808 | __start_tp_report 15:34:52 INFO - PROCESS | 2808 | _x_x_mozilla_page_load 15:34:52 INFO - PROCESS | 2808 | _x_x_mozilla_page_load_details 15:34:52 INFO - PROCESS | 2808 | |i|pagename|runs| 15:34:52 INFO - PROCESS | 2808 | |0;ai-astar;139;168;158;115;115;114;114;130;155;114 15:34:52 INFO - PROCESS | 2808 | |1;audio-beat-detection;144;108;107;143;110;109;109;108;128;106 15:34:52 INFO - PROCESS | 2808 | |2;audio-dft;183;184;186;184;184;181;185;204;255;208 15:34:52 INFO - PROCESS | 2808 | |3;audio-fft;81;61;62;91;62;59;68;59;75;62 15:34:52 INFO - PROCESS | 2808 | |4;audio-oscillator;86;68;87;71;69;69;93;98;54;104 15:34:52 INFO - PROCESS | 2808 | |5;imaging-gaussian-blur;87;86;86;86;87;86;87;86;86;86 15:34:52 INFO - PROCESS | 2808 | |6;imaging-darkroom;94;93;108;147;93;95;94;95;93;93 15:34:52 INFO - PROCESS | 2808 | |7;imaging-desaturate;83;85;83;82;86;83;84;85;84;85 15:34:52 INFO - PROCESS | 2808 | |8;json-parse-financial;62;60;60;60;64;64;66;63;64;64 15:34:52 INFO - PROCESS | 2808 | |9;json-stringify-tinderbox;55;65;70;60;59;57;58;56;58;58 15:34:52 INFO - PROCESS | 2808 | |10;stanford-crypto-aes;83;83;82;82;82;83;84;84;85;89 15:34:52 INFO - PROCESS | 2808 | |11;stanford-crypto-ccm;137;140;136;135;135;138;140;165;203;145 15:34:52 INFO - PROCESS | 2808 | |12;stanford-crypto-pbkdf2;205;203;162;166;162;164;163;162;162;163 15:34:52 INFO - PROCESS | 2808 | |13;stanford-crypto-sha256-iterative;63;65;67;65;65;77;80;82;78;71 15:34:52 INFO - PROCESS | 2808 | __end_tp_report 15:34:52 INFO - PROCESS | 2808 | __start_cc_report 15:34:52 INFO - PROCESS | 2808 | _x_x_mozilla_cycle_collect,2147 15:34:52 INFO - PROCESS | 2808 | __end_cc_report 15:34:52 INFO - PROCESS | 2808 | __startTimestamp1491518092870__endTimestamp 15:34:52 INFO - PROCESS | 2808 | 15:34:52 INFO - PROCESS | 2808 | ------- Summary: start ------- 15:34:52 INFO - PROCESS | 2808 | Number of tests: 14 15:34:52 INFO - PROCESS | 2808 | 15:34:52 INFO - PROCESS | 2808 | [#0] ai-astar Cycles:10 Average:132.20 Median:134.50 stddev:21.35 (15.9%) stddev-sans-first:22.51 15:34:52 INFO - PROCESS | 2808 | Values: 139.0 168.0 158.0 115.0 115.0 114.0 114.0 130.0 155.0 114.0 15:34:52 INFO - PROCESS | 2808 | 15:34:52 INFO - PROCESS | 2808 | [#1] audio-beat-detection Cycles:10 Average:117.20 Median:109.50 stddev:15.22 (13.9%) stddev-sans-first:12.69 15:34:52 INFO - PROCESS | 2808 | Values: 144.0 108.0 107.0 143.0 110.0 109.0 109.0 108.0 128.0 106.0 15:34:52 INFO - PROCESS | 2808 | 15:34:52 INFO - PROCESS | 2808 | [#2] audio-dft Cycles:10 Average:195.40 Median:185.50 stddev:22.93 (12.4%) stddev-sans-first:23.88 15:34:52 INFO - PROCESS | 2808 | Values: 183.0 184.0 186.0 184.0 184.0 181.0 185.0 204.0 255.0 208.0 15:34:52 INFO - PROCESS | 2808 | 15:34:52 INFO - PROCESS | 2808 | [#3] audio-fft Cycles:10 Average:68.00 Median:65.00 stddev:10.88 (16.7%) stddev-sans-first:10.48 15:34:52 INFO - PROCESS | 2808 | Values: 81.0 61.0 62.0 91.0 62.0 59.0 68.0 59.0 75.0 62.0 15:34:52 INFO - PROCESS | 2808 | 15:34:52 INFO - PROCESS | 2808 | [#4] audio-oscillator Cycles:10 Average:79.90 Median:78.50 stddev:15.98 (20.4%) stddev-sans-first:16.79 15:34:52 INFO - PROCESS | 2808 | Values: 86.0 68.0 87.0 71.0 69.0 69.0 93.0 98.0 54.0 104.0 15:34:52 INFO - PROCESS | 2808 | 15:34:52 INFO - PROCESS | 2808 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:86.00 stddev:0.48 (0.6%) stddev-sans-first:0.44 15:34:52 INFO - PROCESS | 2808 | Values: 87.0 86.0 86.0 86.0 87.0 86.0 87.0 86.0 86.0 86.0 15:34:52 INFO - PROCESS | 2808 | 15:34:52 INFO - PROCESS | 2808 | [#6] imaging-darkroom Cycles:10 Average:100.50 Median:93.50 stddev:16.96 (18.1%) stddev-sans-first:17.82 15:34:52 INFO - PROCESS | 2808 | Values: 94.0 93.0 108.0 147.0 93.0 95.0 94.0 95.0 93.0 93.0 15:34:52 INFO - PROCESS | 2808 | 15:34:52 INFO - PROCESS | 2808 | [#7] imaging-desaturate Cycles:10 Average:84.00 Median:84.50 stddev:1.25 (1.5%) stddev-sans-first:1.27 15:34:52 INFO - PROCESS | 2808 | Values: 83.0 85.0 83.0 82.0 86.0 83.0 84.0 85.0 84.0 85.0 15:34:52 INFO - PROCESS | 2808 | 15:34:52 INFO - PROCESS | 2808 | [#8] json-parse-financial Cycles:10 Average:62.70 Median:64.00 stddev:2.11 (3.3%) stddev-sans-first:2.22 15:34:52 INFO - PROCESS | 2808 | Values: 62.0 60.0 60.0 60.0 64.0 64.0 66.0 63.0 64.0 64.0 15:34:52 INFO - PROCESS | 2808 | 15:34:52 INFO - PROCESS | 2808 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:58.50 stddev:4.55 (7.8%) stddev-sans-first:4.51 15:34:52 INFO - PROCESS | 2808 | Values: 55.0 65.0 70.0 60.0 59.0 57.0 58.0 56.0 58.0 58.0 15:34:52 INFO - PROCESS | 2808 | 15:34:52 INFO - PROCESS | 2808 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:83.50 stddev:2.11 (2.5%) stddev-sans-first:2.22 15:34:52 INFO - PROCESS | 2808 | Values: 83.0 83.0 82.0 82.0 82.0 83.0 84.0 84.0 85.0 89.0 15:34:52 INFO - PROCESS | 2808 | 15:34:52 INFO - PROCESS | 2808 | [#11] stanford-crypto-ccm Cycles:10 Average:147.40 Median:140.00 stddev:21.47 (15.3%) stddev-sans-first:22.44 15:34:52 INFO - PROCESS | 2808 | Values: 137.0 140.0 136.0 135.0 135.0 138.0 140.0 165.0 203.0 145.0 15:34:52 INFO - PROCESS | 2808 | 15:34:52 INFO - PROCESS | 2808 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.20 Median:163.50 stddev:17.34 (10.6%) stddev-sans-first:13.40 15:34:52 INFO - PROCESS | 2808 | Values: 205.0 203.0 162.0 166.0 162.0 164.0 163.0 162.0 162.0 163.0 15:34:52 INFO - PROCESS | 2808 | 15:34:52 INFO - PROCESS | 2808 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.30 Median:74.00 stddev:7.26 (9.8%) stddev-sans-first:7.05 15:34:52 INFO - PROCESS | 2808 | Values: 63.0 65.0 67.0 65.0 65.0 77.0 80.0 82.0 78.0 71.0 15:34:52 INFO - PROCESS | 2808 | -------- Summary: end -------- 15:34:52 INFO - PROCESS | 2808 | 15:34:52 INFO - PROCESS | 2808 | 1491518092892 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 15:34:53 INFO - TEST-INFO | 2808: exit 0 15:34:53 INFO - TEST-OK | kraken | took 59982ms 15:34:53 INFO - SUITE-END | took 1179s 15:34:54 INFO - Completed test suite (00:19:39) 15:34:54 INFO - javascript benchmark 15:34:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7599.110100897366, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2834.4331133773244, 2860.1705505403434, 3367.444371678673, 3382.47571626398, 3416.367971925194, 4815.9277610835825, 5887.85280367991, 5900.055991041434, 5921.911171332428, 5980.970095149523, 5853.970730146343, 5967.731452084656, 5973.731182096805, 5994.520438364932, 5998.0, 4462.308342206963, 5252.52727254548, 5336.146216605344, 5442.673439593617, 5459.099248623972, 5247.9212811807665, 5259.684418934876, 5292.36491621006, 5473.261109750192, 5479.917801232965, 5337.386200586924, 5349.919751203735, 5379.542738867201, 5428.782848686048, 5434.8913021739545, 5387.973060134714, 5401.7299135043095, 5413.106837371829, 5418.187271909206, 5436.048691478975, 5173.042987047408, 5297.152455607084, 5329.147336426192, 5347.572194224453, 5416.322959630046, 5723.313202415695, 5730.914036289459, 5738.741756620962, 5848.912266316009, 5922.940770592325, 6243.937560624381, 6332.556721029529, 6339.936600633981, 6343.302236754, 6345.333739957284, 5954.7915822946425, 6165.815025549241, 6406.711697973602, 6418.486521078303, 6450.0647406125845, 5843.883122337615, 5881.617694849821, 6063.969680151615, 6105.297890742532, 6133.815985520441, 6298.0, 6398.424141827165, 6437.710303036374, 6504.0, 6602.7689030883685, 5166.276721259027, 6332.715027823759, 6334.398232167937, 6335.904961425582, 6354.046892966092, 6331.113644089831, 6362.9045564316575, 6388.936110638927, 6413.070104834773, 6415.711292991827, 6190.969045154746, 6242.001279795211, 6242.063690446378, 6253.374662533801, 6336.366363363719, 5323.866903327448, 6303.936960630427, 6327.715252813635, 6342.968285158545, 6348.746050158034, 5065.898682026339, 5068.214426763858, 5089.440161582223, 5105.055564720538, 5109.08036553424, 4462.48681401643, 4572.337011133433, 4573.817047318136, 4583.495815460296, 4590.655700822452, 5757.309122905194, 5776.191333213354, 5843.941560584425, 5909.852253693692, 5971.79098731551, 5419.620626556103, 6342.207224096988, 6364.586301890455, 6380.298167201604, 6400.647964362005, 5841.4450627190345, 5892.0, 5910.822675319747, 5920.585559010914, 5933.258342707161, 5387.703676297763, 5519.862003449946, 5585.134304182858, 5600.0759874620235, 5608.990381731328, 5895.702945352015, 6097.359777223505, 6312.274088479883, 6337.493000559944, 6549.967250163719, 6332.810015699536, 6334.619922804646, 6341.90487142684, 6348.269948955929, 6355.110284560073, 6452.9675251961335, 6517.185351831021, 6534.738610455719, 6558.245801732671, 6559.376859198369, 6145.279321789906, 6185.072239164161, 6191.1641928339095, 6293.3391993841815, 6339.904901426575, 6212.9068063979985, 6269.905951410641, 6377.585456945284, 6378.043293506012, 6382.936170638234, 6135.7839197735275, 6182.072689096672, 6233.594816337105, 6261.123442718114, 6265.906011409924, 4935.580475659465, 6196.721147548281, 6287.2769631492965, 6290.402411770874, 6302.527310451643, 5382.192671099367, 5604.551635869202, 5917.526597872074, 6024.578279520391, 6125.84685382869, 6226.283977342482, 6255.124282600348, 6275.246970363585, 6276.089966954857, 6296.2759282681245, 6096.512279017579, 6099.0, 6107.480864126599, 6111.694415279308, 6118.816435506942], "value": 5784.142950620484, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [710.2968061618997, 812.2649002652602, 822.1244374740902, 832.6669332267093, 835.4527784301281, 37881.43177852331, 38450.80774596127, 40138.595149169785, 40149.0, 40151.59848401519, 38512.03719907004, 38780.03049923753, 38782.224355512946, 38816.0, 38945.61054389455, 7443.962780186119, 7638.809029774245, 7850.371970242381, 7993.400494962872, 8011.359091272713, 10953.780924381508, 11216.20540713486, 11323.32060076398, 11331.206815522917, 11362.943185284103, 11190.160737944647, 11193.27243729159, 11196.720081997933, 11209.551617935314, 11217.99038086572, 7368.521046131984, 7551.358134558569, 9295.674651387237, 11129.054030407384, 11140.387278699627, 10830.241883068189, 10912.017918387304, 10926.945365273203, 10935.945320273348, 10937.0, 39572.40641390382, 39894.0, 39917.20165596701, 40033.19933601312, 40097.0, 3736.1967177057063, 3857.2092720992127, 3860.6332398422105, 3869.2648396804516, 3908.101136738566, 27545.0, 29282.707172928425, 29828.0, 29971.70028299711, 29978.25054373636, 2952.5571164325306, 2993.206800197949, 3003.38430621722, 3211.1169428407143, 3227.7579181561478, 340860.0, 341431.0, 342056.0, 342574.57425425673, 345740.0, 51472.970540588976, 51775.48224517782, 51895.221571676455, 51916.0, 52035.0, 54199.72900135474, 54240.915181696146, 54241.72879135579, 54277.45722542724, 54320.913581728935, 13606.9794765393, 13716.177029378268, 13748.312584370742, 13771.104878182887, 13796.310184490736, 11780.469878855472, 11802.822957655642, 11819.468123934443, 11821.231619944676, 11846.822297665542, 10925.781484370267, 12269.07981901361, 12291.201071930474, 12340.50637974489, 12412.0, 4103.199876024147, 4583.931241031387, 5060.873478163076, 5282.577393808486, 5400.162974738922, 1094.7591529863416, 1107.8781334053247, 1150.292570069418, 1193.26614132308, 1193.3198077096145, 25796.0, 26271.737282627313, 26471.867640661672, 26479.735202648113, 26516.0, 1212.5998420521207, 1230.5385480444834, 1243.5253844848687, 1256.7612153690952, 1285.755706415778, 12613.810792838114, 13473.223608970371, 13568.761154409609, 13653.317334133253, 13697.657558560917, 459287.0, 459977.0, 460009.39990599663, 460728.0, 461142.0, 54672.17991730127, 54699.0, 54751.0, 54900.0, 54975.450245497035, 1399.5451478269604, 1448.601634550507, 1450.0284809177665, 1456.9198694071715, 1460.1968917095555, 20827.68758468594, 28065.017724379548, 30499.390012199186, 31022.844885775427, 31249.0, 50871.0, 51272.74363628307, 51332.74333628308, 51347.0, 51360.74319628378, 390052.0994790016, 394419.0, 394682.0, 394736.0, 395867.0, 32981.340373192885, 33761.66238337585, 33819.0, 33855.0, 34369.82815085909, 41826.79086604548, 42629.786851065546, 42655.36016959686, 42992.785036074616, 43823.0, 29779.55330670085, 30076.0, 30324.393512129194, 30445.391092178474, 30537.694623053485, 23594.882025589763, 29026.709732903244, 29694.406111878074, 30034.84982575073, 30060.84969575138, 35032.64967350396, 36099.0, 36143.27713445769, 36213.63786362103, 36236.63763362438, 29177.562336564544, 29729.702702972692, 30557.54163687502, 30642.540361894142, 30953.226169345948, 22792.658110128028, 23735.0, 23982.640260396456, 24263.757362426855, 24343.756562434148], "value": 18621.42032064633, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.73384364788313, 424.64966402717755, 426.6396023042297, 430.6404152532635, 439.90981848721, 15568.45510407136, 20551.686262762436, 21146.788532114675, 21320.680189797167, 21334.57330853382, 21573.0, 22065.448363790914, 22554.0, 23190.30429087126, 23305.533889322203, 5202.6358154928985, 6538.692261547686, 6543.411093001629, 6734.56225345351, 6756.49326300527, 5814.912776308338, 6607.933920660804, 6637.535372523926, 6675.532712710112, 6682.231543372502, 6321.620702757849, 6551.541392102555, 6614.966925165368, 6618.238902526223, 6670.232923213844, 6381.3937675920715, 6538.280789113193, 6541.182352205974, 6599.70301336441, 6607.141071660653, 6478.676066196718, 6487.870242595169, 6495.188101487314, 6500.9349906501275, 6507.154069970921, 25973.350666233495, 26669.73330266692, 26689.866550667317, 26696.0, 27091.45817083647, 2488.489859578782, 2915.50436425806, 2916.7958242923005, 2918.212082737649, 2925.1809493341684, 16088.436904708278, 16824.07467589294, 16962.49112526626, 17173.0, 17464.650706985914, 1988.3836010836687, 2032.1972820735728, 2044.9386518404472, 2060.8660437071694, 2069.6688529835305, 126363.73636263704, 134257.2871356438, 144932.27533862262, 144957.0, 146071.0, 14427.286261060985, 17202.31190752381, 20821.854797985954, 21941.561168776854, 22243.11027558912, 34350.62597496072, 35936.0, 35961.280774384366, 36976.44535331972, 37011.07472313214, 7929.603519823986, 9416.95291523538, 9792.902070979342, 10099.14157296638, 10168.69493915169, 8109.959450202829, 8111.797205069921, 8149.0, 8272.766353084753, 8291.33669306454, 7875.0, 9571.33000689945, 9632.807343853083, 9764.853527197098, 9768.267379946532, 3497.877574284888, 3911.381971676188, 3967.583403742623, 4027.4764280643913, 4052.77709725962, 881.3345923827469, 892.8883889513811, 904.1139683110589, 928.0997432490474, 942.1285311087217, 18138.81861181398, 21116.993885580847, 22227.11091556352, 22330.55338893213, 22355.764423557514, 897.7665806890255, 914.8902131744234, 916.486767410239, 924.1820988425292, 927.3601215161517, 11310.886891131147, 11501.36742479155, 11527.193096483166, 11706.063514918786, 11789.0, 729600.0, 746281.0, 746959.0, 758999.0, 764744.0, 146418.26790866192, 147509.0, 148691.51308486777, 151504.0, 154737.22631386772, 1199.6700907250577, 1217.1844863941003, 1238.5169783784331, 1239.4794186441716, 1245.6076335954147, 18272.634547309244, 19415.902920485307, 19636.018199090275, 19650.901745491752, 19662.606747865248, 46512.767436162605, 58198.0, 61243.693781530805, 61714.76570468476, 62027.6898615504, 72876.63561682157, 76786.92852286069, 81697.0, 89474.55262723645, 90178.64732028893, 10028.69913902584, 11366.033887119687, 11496.425178741198, 11787.941060294987, 11792.587259445878, 11632.12759043092, 12792.296423696785, 12804.0, 12863.356832158543, 12908.16096953695, 10945.452727363441, 11055.61305354341, 11083.612073577386, 11434.199606027498, 11444.656660300205, 10059.849102263619, 10153.39079655223, 10154.593816247267, 10172.898271017491, 10219.7445063874, 10982.670519884416, 11064.39145846954, 11067.0, 11246.831297530707, 11335.149863760085, 11461.541538338373, 11473.483693233991, 11496.080313575061, 11506.0, 11772.470238839102, 10798.514066867167, 10941.343519388862, 10952.56189752401, 11076.840016196764, 11185.272957257752], "value": 11339.196940144808, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1546.5283088657955, 1554.5802633289013, 1583.8812089093321, 1590.8806839487038, 1592.6098105964038, 10638.893611063888, 11247.55009799608, 11251.268667536624, 11253.54985800567, 11301.604443844457, 10720.463976801168, 11153.609623663173, 11321.433928303593, 11330.886691133086, 11349.829752553698, 2421.515696860626, 2456.3981824453035, 2457.3610861176135, 2460.520198561281, 2463.0, 2307.3770082077913, 2352.776486233805, 2362.3031205794246, 2362.5393048355577, 2439.256026911791, 2346.436855154759, 2366.2191476812695, 2391.0555330644384, 2443.9755602443925, 2444.6699695541142, 2342.8360014798973, 2394.6288325309606, 2402.735699073109, 2405.2423486601665, 2410.1203060882654, 2365.349528879555, 2388.8924998375114, 2395.808335333169, 2395.9520809583887, 2402.087206861399, 10818.567257309698, 11082.556697732081, 11104.722381940515, 11105.223164293755, 11112.388818614972, 5796.449337312949, 5967.49276311514, 5969.224000879858, 5977.820665380045, 5980.372060933583, 3573.874914377984, 3637.3270944875285, 3650.9269814603826, 3710.9443358349645, 3717.5353080864893, 1196.234409977615, 1202.807550791878, 1234.7098431868503, 1259.93070381129, 1260.602910083321, 13653.522126725617, 13969.79045314321, 14260.001799874117, 15718.921405392899, 15904.840951590451, 4625.259958406638, 4810.037992401533, 4812.687175333592, 4829.468758436569, 4859.271109333628, 11742.94313511771, 11752.882471175179, 11758.823617645741, 11771.764564708657, 11796.646100616996, 4933.383327084114, 5098.980203959149, 5759.798407055733, 6026.246719160105, 6069.393060693984, 5138.3577052868395, 5308.177232528887, 5374.91937620936, 5426.593005524602, 5435.918461223085, 4100.794960251976, 4107.856225032109, 4163.604457576525, 4194.874153775391, 4225.68307376948, 1150.246588484544, 1154.6766905266538, 1161.355447726503, 1173.7945859474557, 1177.9823302650468, 924.2513564013036, 926.5413620257881, 941.8540126280437, 949.292776881228, 993.3493561717087, 6501.1548498694865, 6539.607623542603, 6719.361660642231, 6788.083608712859, 6793.150856142982, 831.8294749576322, 979.8628192053118, 994.4480813148772, 1003.9498025098716, 1036.6164519127972, 4109.712320137562, 4116.9794151029655, 4259.552746961586, 4263.232618128766, 4325.9134817303475, 12455.688607784698, 13773.862261377459, 13783.448662053606, 13801.51694690681, 13820.930895345458, 12938.417771200318, 13633.727325453436, 13719.725605487834, 13724.93137534306, 13779.931100344435, 9914.702558923243, 10218.437985910847, 10219.386836789816, 10225.59097636101, 10231.386116833013, 6787.359485096858, 6822.556533825285, 6891.689873955584, 6946.097007389007, 7017.403520700594, 10608.04378867195, 12561.183523070971, 12578.0, 12604.306763128116, 12709.80935285953, 10630.787384252426, 12404.069694772748, 12437.067219958359, 12619.87380126187, 12733.87266127364, 9944.900550994398, 10199.337043092175, 10331.915148909557, 10361.0, 10365.481725913825, 10211.285210035227, 10398.792024159626, 10580.682579522627, 10728.034476897117, 10733.409662468326, 8765.254953328784, 8810.735677929672, 8889.955550222208, 8922.86615700778, 9248.630054797733, 7766.728164514441, 8352.958235208785, 8399.942588184093, 8730.301575874042, 8815.779605509913, 6833.555818871758, 7758.728444504415, 8025.07711613172, 8186.058603260702, 8197.385196110195, 5801.723620803416, 5995.730192141451, 6003.8198854034445, 6034.275886893601, 6197.287311959182, 5902.439268269508, 6002.249718785152, 6101.725422356093, 6212.47193988516, 6277.435030847246], "value": 5206.998444973135, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.96941290577394, 366.39178962921557, 383.07105269720927, 384.7941351377014, 385.2968332792654, 4545.545445455453, 4804.83183088592, 5015.824446144386, 5237.659552129109, 5290.947090529094, 4538.00327855744, 4923.753812309379, 4938.777550102451, 4974.751262436882, 4980.676256043354, 3672.2740312053293, 4183.7657890922255, 4198.004374794001, 4204.1801848639525, 4257.659387249028, 3477.6000759912554, 3618.684042212986, 3719.6466335698065, 3932.233214523169, 4232.3228283474655, 3737.8317975691025, 3918.471006414126, 4140.958590414088, 4146.896327591804, 4222.59885310895, 4094.7747873866924, 4103.466549348595, 4120.855770048064, 4151.937720934188, 4176.519700234481, 3868.2650296443585, 3875.844966201377, 3876.7480113792512, 3923.2545816294814, 3926.293267211901, 4044.898877528056, 4196.979015104905, 4479.641628669731, 4603.884902877421, 5054.898902021939, 4183.895402614959, 4189.91620167598, 4261.808218630138, 4743.288506723985, 4763.785629646674, 4661.930071048937, 4789.3294938708605, 5171.50870667286, 5271.789128434858, 5511.53151982082, 3822.827972741233, 4002.599740026003, 4264.552222016706, 4372.103718737651, 4379.627731642814, 4644.628429725613, 5391.433899440542, 5439.836804895859, 5578.704397613855, 5733.655980641175, 3803.410471376941, 4051.2707712611423, 4233.364995250737, 4320.583932859701, 4334.0, 3996.760194388346, 4070.816813243352, 4403.581659742319, 4416.867493975186, 4467.463904331501, 4094.706964100054, 4459.487158976694, 4469.0, 4549.270437737331, 4772.737499437564, 4980.900381992412, 5054.9241761373605, 5319.361676598755, 5321.897850349168, 5368.275282836845, 1387.9028468007143, 1392.7771556550904, 1406.542873566095, 1469.9338529766187, 1511.7430036893534, 1027.7893031928436, 1045.0542259255385, 1060.1465820014807, 1126.4680035152187, 1160.988390116088, 4336.934945975813, 4793.202549044108, 4835.58897493717, 4907.582855457326, 4955.529224723646, 4479.865604031885, 4597.896504838882, 4699.835505757282, 5515.159692758232, 5631.493165615114, 4633.745144017112, 4750.691205071728, 4819.542143496362, 4967.503249675004, 5005.698518385173, 2677.6251324814543, 2712.0372267845028, 2720.5988915708426, 2761.5995680626197, 2881.0, 4804.351412559329, 4826.8069277228515, 5630.0991841305195, 5716.5998380113, 5747.741351639186, 4820.156472617278, 5418.972905135528, 5577.693226872561, 5645.23789288449, 5646.842397308542, 5059.30513278056, 5506.5870059744875, 5636.336381352154, 5654.802081927113, 5666.575006874501, 4762.476127625889, 5283.5773138149625, 5321.6540924839765, 5510.0, 5634.85912852182, 4992.700437973734, 5340.813071542478, 5404.5406140476925, 5405.540529055074, 5422.322209723785, 4980.352554167935, 5155.845324640267, 5206.713630750345, 5407.729183641763, 5501.092319767194, 3649.1571756262915, 3739.691108112139, 3862.9227415452096, 3963.742356746802, 4356.782160891879, 4794.5924596409695, 4974.980129073459, 5464.28964234647, 5537.667739935617, 5554.833354999357, 5259.764600045042, 5271.710055946806, 5318.946810531846, 5427.6472029317965, 5439.456054394529, 5204.375474943031, 5321.441248668834, 5323.574114070788, 5350.919736204038, 5353.812616558402, 4627.8611641650805, 4788.784504697367, 4930.654854160174, 5233.947660523346, 5256.553192978639, 4787.736674482937, 4799.616030717464, 5131.615128865275, 5281.841544753664, 5323.760430780701, 4763.190257656138, 4905.632077594123, 4965.900681986413, 5191.974040129775, 5236.345456817898], "value": 4046.770548232805, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [710.5878590417558, 722.4328901812075, 739.7078154129117, 750.3546949623325, 790.1505881177735, 15052.397904083837, 15804.683906321867, 15813.446529371475, 15876.841231587696, 15892.12593307369, 14552.126872387638, 14563.0, 14581.854181458182, 14743.705125897475, 14743.77884331736, 927.726320735383, 961.9470929098896, 985.1232403161157, 991.8016396720685, 1002.939823610582, 977.3940157102571, 980.5783513089368, 980.6126580000931, 981.5386768218925, 981.8723565936418, 1026.1380440430037, 1026.4508487958944, 1027.6762819711766, 1028.408665017614, 1029.9691009269734, 1019.887812340642, 1021.4024795494645, 1021.9284650074497, 1022.8823685276215, 1023.5087158164049, 763.3053920931925, 764.0754686828943, 764.0945479606684, 765.1124695353419, 768.166539304855, 15826.762598560916, 15906.920465397714, 15956.840431595652, 16348.100854453, 16593.087380194083, 13790.931045344709, 13891.79162312566, 14022.71954560903, 14074.577762667033, 14088.0, 11578.189526733133, 11597.0, 11690.47392867323, 11840.703982400422, 11858.0, 3519.454484554898, 3609.873654422083, 3679.5032670589394, 3707.295613833363, 3807.866724664651, 110393.44803276012, 112171.0, 112664.43667781609, 113316.0, 116537.0, 9722.805543889224, 10618.787624247472, 10742.140628749681, 10745.194110441747, 11034.117270618332, 22423.0, 22560.435989100075, 22730.545389092455, 22742.8862855688, 22976.540469190524, 7607.847843043108, 7694.769156925301, 7719.305262526287, 7769.3784497240085, 7785.523745301984, 4228.577142285747, 4314.848980285675, 4367.665906971404, 4476.552344765498, 4512.887177820515, 3471.756977011585, 3542.0613537412605, 3546.5921419036627, 3565.3938830398893, 3575.0, 614.7110857896781, 619.0, 627.6140173793084, 646.6734299178885, 649.2890285137767, 660.7489154121403, 679.6029687919155, 697.0972590495321, 703.4794252253398, 721.7401735375261, 9046.502442365601, 9577.185939195107, 9986.10125088731, 10114.53339265817, 10213.387196768217, 564.5060571999501, 583.6060659055206, 589.1810383566846, 593.0866465642948, 593.3117583603, 4150.3566947123245, 4542.295944128599, 4988.825391111366, 4997.9750101249265, 5024.698518088926, 101037.0, 101539.49230253801, 101801.0, 102554.0, 102653.0, 54062.0, 59084.70457647684, 81566.0, 82764.58617706873, 85420.7186892197, 55325.446745532834, 66680.33319666742, 67611.0, 68262.0, 69102.0, 10515.421651809223, 10623.734406639896, 10765.3540787553, 10859.51132199037, 10866.945665271624, 46504.507907475636, 46523.53476465192, 46696.53303466922, 47091.17452952834, 47610.0, 45849.77075114603, 59403.40596593979, 60002.0, 61655.76688466295, 61987.1403857885, 11580.826287605862, 12156.331401772633, 12160.513579456723, 12298.385080745747, 12827.615171544869, 13768.449262029406, 14265.215413152377, 14267.143971361751, 14409.639759006108, 14620.342084606431, 12792.61622151337, 13512.864871351554, 13707.750597314864, 13874.861251387762, 13920.443182273, 12553.623391298275, 13453.46186152582, 13494.32528373597, 13503.0, 13869.722605548035, 12142.635720928387, 13137.802932956205, 13407.061505694897, 13584.932075339955, 13828.654283642989, 9284.35009549352, 9378.718638440858, 9670.0, 9691.854622180814, 9926.25553083536, 8796.428232164888, 10526.947365263431, 10970.890291097307, 11144.275622084539, 11207.383593902414], "value": 7481.7589429751015, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1459.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 168.0, 158.0, 115.0, 115.0, 114.0, 114.0, 130.0, 155.0, 114.0], "value": 132.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 108.0, 107.0, 143.0, 110.0, 109.0, 109.0, 108.0, 128.0, 106.0], "value": 117.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 186.0, 184.0, 184.0, 181.0, 185.0, 204.0, 255.0, 208.0], "value": 195.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 61.0, 62.0, 91.0, 62.0, 59.0, 68.0, 59.0, 75.0, 62.0], "value": 68.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 68.0, 87.0, 71.0, 69.0, 69.0, 93.0, 98.0, 54.0, 104.0], "value": 79.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 86.0, 86.0, 87.0, 86.0, 87.0, 86.0, 86.0, 86.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 108.0, 147.0, 93.0, 95.0, 94.0, 95.0, 93.0, 93.0], "value": 100.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 83.0, 82.0, 86.0, 83.0, 84.0, 85.0, 84.0, 85.0], "value": 84.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 60.0, 60.0, 64.0, 64.0, 66.0, 63.0, 64.0, 64.0], "value": 62.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 65.0, 70.0, 60.0, 59.0, 57.0, 58.0, 56.0, 58.0, 58.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 82.0, 82.0, 82.0, 83.0, 84.0, 84.0, 85.0, 89.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 140.0, 136.0, 135.0, 135.0, 138.0, 140.0, 165.0, 203.0, 145.0], "value": 147.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 203.0, 162.0, 166.0, 162.0, 164.0, 163.0, 162.0, 162.0, 163.0], "value": 171.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 67.0, 65.0, 65.0, 77.0, 80.0, 82.0, 78.0, 71.0], "value": 71.3, "unit": "score"}]}]} 15:34:54 INFO - Return code: 0 15:34:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:34:54 ERROR - # TBPL SUCCESS # 15:34:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:34:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:34:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:34:54 INFO - 2017-04-06 15:34:54.177 screenresolution[2812:45408] starting screenresolution argv=screenresolution get 15:34:54 INFO - 2017-04-06 15:34:54.181 screenresolution[2812:45408] Display 0: 1600x1200x32@60 15:34:54 INFO - 2017-04-06 15:34:54.187 screenresolution[2813:45411] starting screenresolution argv=screenresolution list 15:34:54 INFO - Available Modes on Display 0 15:34:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:34:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:34:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:34:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:34:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:34:54 INFO - Intel Iris: 15:34:54 INFO - Chipset Model: Intel Iris 15:34:54 INFO - Type: GPU 15:34:54 INFO - Bus: Built-In 15:34:54 INFO - VRAM (Dynamic, Max): 1536 MB 15:34:54 INFO - Vendor: Intel (0x8086) 15:34:54 INFO - Device ID: 0x0a2e 15:34:54 INFO - Revision ID: 0x0009 15:34:54 INFO - Displays: 15:34:54 INFO - Display: 15:34:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:34:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:34:54 INFO - Display Serial Number: 1600x1200 60 15:34:54 INFO - Main Display: Yes 15:34:54 INFO - Mirror: Off 15:34:54 INFO - Online: Yes 15:34:54 INFO - Rotation: Supported 15:34:54 INFO - Return code: 0 15:34:54 INFO - Running post-action listener: _resource_record_post_action 15:34:54 INFO - [mozharness: 2017-04-06 22:34:54.430333Z] Finished run-tests step (success) 15:34:54 INFO - Running post-run listener: _resource_record_post_run 15:34:54 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 131072; Write bytes: 509902336; Read time: 3; Write time: 1555 15:34:54 INFO - TinderboxPrint: CPU usage
53.9% 15:34:54 INFO - TinderboxPrint: I/O read bytes / time
131,072 / 3 15:34:54 INFO - TinderboxPrint: I/O write bytes / time
509,902,336 / 1,555 15:34:54 INFO - TinderboxPrint: CPU idle
2,213.8 (46.1%) 15:34:54 INFO - TinderboxPrint: CPU system
959.1 (20.0%) 15:34:54 INFO - TinderboxPrint: CPU user
1,628.7 (33.9%) 15:34:54 INFO - TinderboxPrint: Swap in / out
207,753,216 / 0 15:34:54 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205705728; Write bytes: 202668032; Read time: 15718; Write time: 416 15:34:54 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 131072; Write bytes: 296310272; Read time: 3; Write time: 1100 15:34:54 INFO - Running post-run listener: _upload_blobber_files 15:34:54 INFO - Blob upload gear active. 15:34:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:34:54 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 15:34:54 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'] 15:34:54 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 15:34:55 INFO - (blobuploader) - INFO - Open directory for files ... 15:34:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:34:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:34:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:34:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:34:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:34:56 INFO - (blobuploader) - INFO - Done attempting. 15:34:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:34:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:34:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:34:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:34:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:34:57 INFO - (blobuploader) - INFO - Done attempting. 15:34:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:34:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:34:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:34:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:34:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:34:58 INFO - (blobuploader) - INFO - Done attempting. 15:34:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:34:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:34:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:35:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:35:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:35:00 INFO - (blobuploader) - INFO - Done attempting. 15:35:00 INFO - (blobuploader) - INFO - Iteration through files over. 15:35:00 INFO - Return code: 0 15:35:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:35:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:35:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3acff0ec6f7d6ec0dfde4d551a3d38cc6c37692aec8b5a091a33b8cb2bc69db00143cebc9f18e845828f06cdd8601b99c9d463415acb31e88200cb601b981296", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6502c3ea159d7f3f08d1e3b7f2ff5731268930663e9406600efa12be1e8e7574d3466e019360a33cdbffd7c810281b6fb0e99d6dc110494baf9edebea197555e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a523581f106bd32b99e0c8ed45780b0c9e745884c306a54d3fb986b9f95b4dff4d634fe2ceb3cec7cd325cf869126b356125dd27032faabebd35cd7d35b1c481", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb61d9f592cde686a529888c10c9592b77e2312c708ef79eee0730427a752f7ea97fe49ca262848de89496c9a62e63e57e120b019bb2512148a667194428ee2c"} 15:35:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:35:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:35:00 INFO - Contents: 15:35:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3acff0ec6f7d6ec0dfde4d551a3d38cc6c37692aec8b5a091a33b8cb2bc69db00143cebc9f18e845828f06cdd8601b99c9d463415acb31e88200cb601b981296", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6502c3ea159d7f3f08d1e3b7f2ff5731268930663e9406600efa12be1e8e7574d3466e019360a33cdbffd7c810281b6fb0e99d6dc110494baf9edebea197555e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a523581f106bd32b99e0c8ed45780b0c9e745884c306a54d3fb986b9f95b4dff4d634fe2ceb3cec7cd325cf869126b356125dd27032faabebd35cd7d35b1c481", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb61d9f592cde686a529888c10c9592b77e2312c708ef79eee0730427a752f7ea97fe49ca262848de89496c9a62e63e57e120b019bb2512148a667194428ee2c"} 15:35:00 INFO - Running post-run listener: copy_logs_to_upload_dir 15:35:00 INFO - Copying logs to upload dir... 15:35:00 INFO - mkdir: /builds/slave/test/build/upload/logs 15:35:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.557458 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-06 15:35:00.659216) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 15:35:00.662410) ========= 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.e4Zs3CjQSb/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.zr8ndD2sXE/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3acff0ec6f7d6ec0dfde4d551a3d38cc6c37692aec8b5a091a33b8cb2bc69db00143cebc9f18e845828f06cdd8601b99c9d463415acb31e88200cb601b981296", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6502c3ea159d7f3f08d1e3b7f2ff5731268930663e9406600efa12be1e8e7574d3466e019360a33cdbffd7c810281b6fb0e99d6dc110494baf9edebea197555e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a523581f106bd32b99e0c8ed45780b0c9e745884c306a54d3fb986b9f95b4dff4d634fe2ceb3cec7cd325cf869126b356125dd27032faabebd35cd7d35b1c481", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb61d9f592cde686a529888c10c9592b77e2312c708ef79eee0730427a752f7ea97fe49ca262848de89496c9a62e63e57e120b019bb2512148a667194428ee2c"} build_url:https://queue.taskcluster.net/v1/task/IillTN3VQh-hsDy-r7CdBg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011409 build_url: 'https://queue.taskcluster.net/v1/task/IillTN3VQh-hsDy-r7CdBg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3acff0ec6f7d6ec0dfde4d551a3d38cc6c37692aec8b5a091a33b8cb2bc69db00143cebc9f18e845828f06cdd8601b99c9d463415acb31e88200cb601b981296", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6502c3ea159d7f3f08d1e3b7f2ff5731268930663e9406600efa12be1e8e7574d3466e019360a33cdbffd7c810281b6fb0e99d6dc110494baf9edebea197555e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a523581f106bd32b99e0c8ed45780b0c9e745884c306a54d3fb986b9f95b4dff4d634fe2ceb3cec7cd325cf869126b356125dd27032faabebd35cd7d35b1c481", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb61d9f592cde686a529888c10c9592b77e2312c708ef79eee0730427a752f7ea97fe49ca262848de89496c9a62e63e57e120b019bb2512148a667194428ee2c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 15:35:00.699184) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 15:35:00.699619) ========= 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.e4Zs3CjQSb/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.zr8ndD2sXE/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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.005399 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 15:35:00.751872) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 15:35:00.752644) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 15:35:00.753139) ========= ========= Total master_lag: 0.21 =========