builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0094 starttime: 1487156525.77 results: success (0) buildid: 20170210030206 builduid: 1bfbb0aef7d5463999819ddc6fd6d7ad revision: 25a94c1047e793ef096d8556fa3c26dd72bd37d7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-15 03:02:05.767557) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-15 03:02:05.768017) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-15 03:02:05.768289) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-15 03:02:05.792902) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 03:02:05.793226) ========= 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.NzXRtVlSXV/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.KUtZt9Arzz/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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-02-15 03:02:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.04M=0.001s 2017-02-15 03:02:05 (8.04 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.101748 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 03:02:05.918356) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 03:02:05.918677) ========= 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.NzXRtVlSXV/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.KUtZt9Arzz/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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.021445 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 03:02:05.967079) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-15 03:02:05.967394) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 25a94c1047e793ef096d8556fa3c26dd72bd37d7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 25a94c1047e793ef096d8556fa3c26dd72bd37d7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NzXRtVlSXV/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.KUtZt9Arzz/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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-02-15 03:02:06,043 truncating revision to first 12 chars 2017-02-15 03:02:06,044 Setting DEBUG logging. 2017-02-15 03:02:06,044 attempt 1/10 2017-02-15 03:02:06,044 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/25a94c1047e7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-15 03:02:06,782 unpacking tar archive at: mozilla-central-25a94c1047e7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.979995 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-15 03:02:06.970877) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-15 03:02:06.971197) ========= script_repo_revision: 25a94c1047e793ef096d8556fa3c26dd72bd37d7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-15 03:02:06.971586) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-15 03:02:06.971877) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-15 03:02:06.991550) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-15 03:02:06.991846) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NzXRtVlSXV/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.KUtZt9Arzz/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 03:02:07 INFO - MultiFileLogger online at 20170215 03:02:07 in /builds/slave/test 03:02:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:02:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:02:07 INFO - {'append_to_log': False, 03:02:07 INFO - 'base_work_dir': '/builds/slave/test', 03:02:07 INFO - 'blob_upload_branch': 'Firefox', 03:02:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:02:07 INFO - 'branch': 'Firefox', 03:02:07 INFO - 'buildbot_json_path': 'buildprops.json', 03:02:07 INFO - 'config_files': ('talos/mac_config.py',), 03:02:07 INFO - 'default_actions': ('clobber', 03:02:07 INFO - 'read-buildbot-config', 03:02:07 INFO - 'download-and-extract', 03:02:07 INFO - 'populate-webroot', 03:02:07 INFO - 'create-virtualenv', 03:02:07 INFO - 'install', 03:02:07 INFO - 'run-tests'), 03:02:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:02:07 INFO - 'download_minidump_stackwalk': True, 03:02:07 INFO - 'download_symbols': 'ondemand', 03:02:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:02:07 INFO - 'tooltool.py': '/tools/tooltool.py', 03:02:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:02:07 INFO - '/tools/misc-python/virtualenv.py')}, 03:02:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:02:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:02:07 INFO - 'installer_path': 'installer.exe', 03:02:07 INFO - 'log_level': 'info', 03:02:07 INFO - 'log_name': 'talos', 03:02:07 INFO - 'log_to_console': True, 03:02:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:02:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:02:07 INFO - 'opt_config_files': (), 03:02:07 INFO - 'pip_index': False, 03:02:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:02:07 INFO - 'cmd': ('bash', 03:02:07 INFO - '-c', 03:02:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:02:07 INFO - 'enabled': True, 03:02:07 INFO - 'halt_on_failure': False, 03:02:07 INFO - 'name': 'check_screen_resolution'},), 03:02:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:02:07 INFO - 'cmd': ('bash', 03:02:07 INFO - '-c', 03:02:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:02:07 INFO - 'enabled': True, 03:02:07 INFO - 'halt_on_failure': False, 03:02:07 INFO - 'name': 'check_screen_resolution'},), 03:02:07 INFO - 'run_cmd_checks_enabled': True, 03:02:07 INFO - 'sps_profile': False, 03:02:07 INFO - 'sps_profile_interval': 0, 03:02:07 INFO - 'suite': 'dromaeojs-e10s', 03:02:07 INFO - 'system_bits': '32', 03:02:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:02:07 INFO - 'title': 't-yosemite-r7-0094', 03:02:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:02:07 INFO - 'use_talos_json': True, 03:02:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:02:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:02:07 INFO - 'work_dir': 'build'} 03:02:07 INFO - [mozharness: 2017-02-15 11:02:07.192756Z] Running clobber step. 03:02:07 INFO - Running pre-action listener: _resource_record_pre_action 03:02:07 INFO - Running main action method: clobber 03:02:07 INFO - rmtree: /builds/slave/test/build 03:02:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:02:08 INFO - Running post-action listener: _resource_record_post_action 03:02:08 INFO - [mozharness: 2017-02-15 11:02:08.145585Z] Finished clobber step (success) 03:02:08 INFO - [mozharness: 2017-02-15 11:02:08.145712Z] Running read-buildbot-config step. 03:02:08 INFO - Running pre-action listener: _resource_record_pre_action 03:02:08 INFO - Running main action method: read_buildbot_config 03:02:08 INFO - Using buildbot properties: 03:02:08 INFO - { 03:02:08 INFO - "project": "", 03:02:08 INFO - "product": "firefox", 03:02:08 INFO - "script_repo_revision": "production", 03:02:08 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 03:02:08 INFO - "repository": "", 03:02:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 03:02:08 INFO - "buildid": "20170210030206", 03:02:08 INFO - "stage_platform": "macosx64", 03:02:08 INFO - "basedir": "/builds/slave/test", 03:02:08 INFO - "nightly_build": "True", 03:02:08 INFO - "buildnumber": 87, 03:02:08 INFO - "slavename": "t-yosemite-r7-0094", 03:02:08 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 03:02:08 INFO - "platform": "yosemite_r7", 03:02:08 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 03:02:08 INFO - "branch": "mozilla-central", 03:02:08 INFO - "repo_path": "mozilla-central", 03:02:08 INFO - "revision": "25a94c1047e793ef096d8556fa3c26dd72bd37d7", 03:02:08 INFO - "pgo_build": "False", 03:02:08 INFO - "builduid": "1bfbb0aef7d5463999819ddc6fd6d7ad", 03:02:08 INFO - "slavebuilddir": "test" 03:02:08 INFO - } 03:02:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 03:02:08 INFO - Running post-action listener: _resource_record_post_action 03:02:08 INFO - [mozharness: 2017-02-15 11:02:08.150092Z] Finished read-buildbot-config step (success) 03:02:08 INFO - [mozharness: 2017-02-15 11:02:08.150258Z] Running download-and-extract step. 03:02:08 INFO - Running pre-action listener: _resource_record_pre_action 03:02:08 INFO - Running main action method: download_and_extract 03:02:08 INFO - mkdir: /builds/slave/test/build/tests 03:02:08 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')]} 03:02:08 INFO - trying https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 03:02:08 INFO - Downloading https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 03:02:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 03:02:09 INFO - Downloaded 1199 bytes. 03:02:09 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 03:02:09 INFO - Using the following test package requirements: 03:02:09 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 03:02:09 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:02:09 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 03:02:09 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:02:09 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 03:02:09 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:02:09 INFO - u'jsshell-mac.zip'], 03:02:09 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:02:09 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 03:02:09 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 03:02:09 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:02:09 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 03:02:09 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:02:09 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 03:02:09 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:02:09 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 03:02:09 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:02:09 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 03:02:09 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:02:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 03:02:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:02:09 INFO - Fetch https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 03:02:10 INFO - Expected file size: 34945452 03:02:10 INFO - Obtained file size: 34945452 03:02:17 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 03:02:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 03:02:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:02:17 INFO - Fetch https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 03:02:19 INFO - Expected file size: 34945452 03:02:19 INFO - Obtained file size: 34945452 03:02:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 03:02:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:02:25 INFO - Fetch https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 03:02:26 INFO - Expected file size: 14403454 03:02:26 INFO - Obtained file size: 14403454 03:02:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:02:26 INFO - trying https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 03:02:26 INFO - Downloading https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 03:02:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 03:02:28 INFO - Downloaded 70413587 bytes. 03:02:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 03:02:28 INFO - mkdir: /builds/slave/test/properties 03:02:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:02:28 INFO - Writing to file /builds/slave/test/properties/build_url 03:02:28 INFO - Contents: 03:02:28 INFO - build_url:https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 03:02:29 INFO - Running post-action listener: _resource_record_post_action 03:02:29 INFO - Running post-action listener: set_extra_try_arguments 03:02:29 INFO - [mozharness: 2017-02-15 11:02:29.543018Z] Finished download-and-extract step (success) 03:02:29 INFO - [mozharness: 2017-02-15 11:02:29.543171Z] Running populate-webroot step. 03:02:29 INFO - Running pre-action listener: _resource_record_pre_action 03:02:29 INFO - Running main action method: populate_webroot 03:02:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:02:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:02:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:02:29 INFO - '--tppagecycles', 03:02:29 INFO - '7'], 03:02:29 INFO - 'tests': ['tsvgm']}}, 03:02:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:02:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:02:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:02:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:02:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:02:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:02:29 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:02:29 INFO - 'talos_options': ['--disable-e10s'], 03:02:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:02:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:02:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:02:29 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:02:29 INFO - 'talos_options': ['--disable-e10s'], 03:02:29 INFO - 'tests': ['damp', 'tps']}, 03:02:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:02:29 INFO - 'tests': ['damp', 'tps']}, 03:02:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:02:29 INFO - 'tests': ['dromaeo_dom']}, 03:02:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:02:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:02:29 INFO - 'tests': ['basic_compositor_video']}, 03:02:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:02:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:02:29 INFO - 'tests': ['a11yr', 03:02:29 INFO - 'ts_paint', 03:02:29 INFO - 'tpaint', 03:02:29 INFO - 'sessionrestore', 03:02:29 INFO - 'sessionrestore_no_auto_restore', 03:02:29 INFO - 'tabpaint']}, 03:02:29 INFO - 'other-e10s': {'tests': ['a11yr', 03:02:29 INFO - 'ts_paint', 03:02:29 INFO - 'tpaint', 03:02:29 INFO - 'sessionrestore', 03:02:29 INFO - 'sessionrestore_no_auto_restore', 03:02:29 INFO - 'tabpaint']}, 03:02:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:02:29 INFO - 'ts_paint', 03:02:29 INFO - 'tpaint', 03:02:29 INFO - 'sessionrestore', 03:02:29 INFO - 'sessionrestore_no_auto_restore', 03:02:29 INFO - 'tabpaint']}, 03:02:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:02:29 INFO - 'ts_paint', 03:02:29 INFO - 'tpaint', 03:02:29 INFO - 'sessionrestore', 03:02:29 INFO - 'sessionrestore_no_auto_restore', 03:02:29 INFO - 'tabpaint']}, 03:02:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:02:29 INFO - 'tests': ['a11yr', 03:02:29 INFO - 'ts_paint', 03:02:29 INFO - 'tpaint', 03:02:29 INFO - 'sessionrestore', 03:02:29 INFO - 'sessionrestore_no_auto_restore', 03:02:29 INFO - 'tabpaint']}, 03:02:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:02:29 INFO - 'tests': ['a11yr', 03:02:29 INFO - 'ts_paint', 03:02:29 INFO - 'tpaint', 03:02:29 INFO - 'sessionrestore', 03:02:29 INFO - 'sessionrestore_no_auto_restore', 03:02:29 INFO - 'tabpaint']}, 03:02:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:02:29 INFO - 'tests': ['tsvgx', 03:02:29 INFO - 'tsvgr_opacity', 03:02:29 INFO - 'tart', 03:02:29 INFO - 'tscrollx', 03:02:29 INFO - 'cart', 03:02:29 INFO - 'tsvg_static']}, 03:02:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:02:29 INFO - 'tsvgr_opacity', 03:02:29 INFO - 'tart', 03:02:29 INFO - 'tscrollx', 03:02:29 INFO - 'cart', 03:02:29 INFO - 'tsvg_static']}, 03:02:29 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:02:29 INFO - 'talos_options': ['--disable-e10s'], 03:02:29 INFO - 'tests': ['tp5o']}, 03:02:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:02:29 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:02:29 INFO - 'talos_options': ['--disable-e10s', 03:02:29 INFO - '--xperf_path', 03:02:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:02:29 INFO - 'tests': ['tp5n']}, 03:02:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:02:29 INFO - 'talos_options': ['--xperf_path', 03:02:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:02:29 INFO - 'tests': ['tp5n']}}, 03:02:29 INFO - 'talos.zip': {'path': '', 03:02:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:02:29 INFO - Running post-action listener: _resource_record_post_action 03:02:29 INFO - [mozharness: 2017-02-15 11:02:29.561230Z] Finished populate-webroot step (success) 03:02:29 INFO - [mozharness: 2017-02-15 11:02:29.561331Z] Running create-virtualenv step. 03:02:29 INFO - Running pre-action listener: _resource_record_pre_action 03:02:29 INFO - Running main action method: create_virtualenv 03:02:29 INFO - Creating virtualenv /builds/slave/test/build/venv 03:02:29 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 03:02:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:02:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:02:29 INFO - Using real prefix '/tools/python27' 03:02:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:02:30 INFO - Installing distribute.............................................................................................................................................................................................done. 03:02:33 INFO - Installing pip.................done. 03:02:33 INFO - Return code: 0 03:02:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:02:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:02:33 INFO - Reading from file tmpfile_stdout 03:02:33 INFO - Output received: 03:02:33 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) 03:02:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:02: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')]} 03:02:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:02:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02: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')]} 03:02:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:02:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104ab9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104abc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf51c9c050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104897d30>, '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 0x1047bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10488cbe8>, '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.NzXRtVlSXV/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.KUtZt9Arzz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 03:02: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', 'pip>=1.5'] in /builds/slave/test/build 03:02: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 pip>=1.5 03:02:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NzXRtVlSXV/Render', 03:02:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:33 INFO - 'HOME': '/Users/cltbld', 03:02:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:33 INFO - 'LOGNAME': 'cltbld', 03:02:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:33 INFO - 'MOZ_NO_REMOTE': '1', 03:02:33 INFO - 'NO_EM_RESTART': '1', 03:02:33 INFO - 'PAGER': '/bin/cat', 03:02:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:33 INFO - 'PWD': '/builds/slave/test', 03:02:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:33 INFO - 'SHELL': '/bin/bash', 03:02:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KUtZt9Arzz/Listeners', 03:02:33 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 03:02:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:33 INFO - 'USER': 'cltbld', 03:02:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:33 INFO - 'XPC_FLAGS': '0x0', 03:02:33 INFO - 'XPC_SERVICE_NAME': '0', 03:02:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:02:33 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 03:02:33 INFO - Cleaning up... 03:02:33 INFO - Return code: 0 03:02:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:02: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')]} 03:02:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:02:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02: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')]} 03:02:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:02:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02: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', '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 0x104ab9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104abc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf51c9c050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104897d30>, '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 0x1047bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10488cbe8>, '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.NzXRtVlSXV/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.KUtZt9Arzz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 03:02: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', 'psutil>=3.1.1'] in /builds/slave/test/build 03:02: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 psutil>=3.1.1 03:02:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NzXRtVlSXV/Render', 03:02:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:33 INFO - 'HOME': '/Users/cltbld', 03:02:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:33 INFO - 'LOGNAME': 'cltbld', 03:02:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:33 INFO - 'MOZ_NO_REMOTE': '1', 03:02:33 INFO - 'NO_EM_RESTART': '1', 03:02:33 INFO - 'PAGER': '/bin/cat', 03:02:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:33 INFO - 'PWD': '/builds/slave/test', 03:02:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:33 INFO - 'SHELL': '/bin/bash', 03:02:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KUtZt9Arzz/Listeners', 03:02:33 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 03:02:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:33 INFO - 'USER': 'cltbld', 03:02:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:33 INFO - 'XPC_FLAGS': '0x0', 03:02:33 INFO - 'XPC_SERVICE_NAME': '0', 03:02:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:02:34 INFO - Downloading/unpacking psutil>=3.1.1 03:02:34 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 03:02:34 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 03:02:34 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 03:02:34 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 03:02:34 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 03:02:34 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 03:02:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:02:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:02:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:02:37 INFO - Installing collected packages: psutil 03:02:37 INFO - Running setup.py install for psutil 03:02:37 INFO - building 'psutil._psutil_osx' extension 03:02:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 03:02:38 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 03:02:38 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 03:02:38 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 03:02:38 INFO - building 'psutil._psutil_posix' extension 03:02:38 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 03:02:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:02:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:02:38 INFO - ^ 03:02:38 INFO - 1 warning generated. 03:02:38 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 03:02:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:02:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:02:38 INFO - Successfully installed psutil 03:02:38 INFO - Cleaning up... 03:02:38 INFO - Return code: 0 03:02:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:02:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:02:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:02:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:02:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:02:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104ab9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104abc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf51c9c050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104897d30>, '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 0x1047bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10488cbe8>, '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.NzXRtVlSXV/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.KUtZt9Arzz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 03:02:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:02:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:02:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NzXRtVlSXV/Render', 03:02:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:38 INFO - 'HOME': '/Users/cltbld', 03:02:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:38 INFO - 'LOGNAME': 'cltbld', 03:02:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:38 INFO - 'MOZ_NO_REMOTE': '1', 03:02:38 INFO - 'NO_EM_RESTART': '1', 03:02:38 INFO - 'PAGER': '/bin/cat', 03:02:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:38 INFO - 'PWD': '/builds/slave/test', 03:02:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:38 INFO - 'SHELL': '/bin/bash', 03:02:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KUtZt9Arzz/Listeners', 03:02:38 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 03:02:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:38 INFO - 'USER': 'cltbld', 03:02:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:38 INFO - 'XPC_FLAGS': '0x0', 03:02:38 INFO - 'XPC_SERVICE_NAME': '0', 03:02:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:02:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:02:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:02:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:02:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:02:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:02:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:02:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:02:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:02:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:02:41 INFO - Installing collected packages: mozsystemmonitor 03:02:41 INFO - Running setup.py install for mozsystemmonitor 03:02:41 INFO - Successfully installed mozsystemmonitor 03:02:41 INFO - Cleaning up... 03:02:41 INFO - Return code: 0 03:02:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:02:41 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')]} 03:02:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:02:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:41 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')]} 03:02:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:02:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:41 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 0x104ab9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104abc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf51c9c050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104897d30>, '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 0x1047bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10488cbe8>, '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.NzXRtVlSXV/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.KUtZt9Arzz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 03:02:41 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 03:02:41 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 03:02:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NzXRtVlSXV/Render', 03:02:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:41 INFO - 'HOME': '/Users/cltbld', 03:02:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:41 INFO - 'LOGNAME': 'cltbld', 03:02:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:41 INFO - 'MOZ_NO_REMOTE': '1', 03:02:41 INFO - 'NO_EM_RESTART': '1', 03:02:41 INFO - 'PAGER': '/bin/cat', 03:02:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:41 INFO - 'PWD': '/builds/slave/test', 03:02:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:41 INFO - 'SHELL': '/bin/bash', 03:02:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KUtZt9Arzz/Listeners', 03:02:41 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 03:02:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:41 INFO - 'USER': 'cltbld', 03:02:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:41 INFO - 'XPC_FLAGS': '0x0', 03:02:41 INFO - 'XPC_SERVICE_NAME': '0', 03:02:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:02:42 INFO - Downloading/unpacking jsonschema==2.5.1 03:02:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:02:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:02:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:02:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:02:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:02:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:02:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:02:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:02:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:02:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:02:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:02:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:02:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:02:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:02:45 INFO - Downloading functools32-3.2.3-2.tar.gz 03:02:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:02:45 INFO - warning: no files found matching '*.txt' 03:02:45 INFO - no previously-included directories found matching 'build' 03:02:45 INFO - no previously-included directories found matching 'dist' 03:02:45 INFO - no previously-included directories found matching '.git*' 03:02:45 INFO - Installing collected packages: jsonschema, functools32 03:02:45 INFO - Running setup.py install for functools32 03:02:45 INFO - warning: no files found matching '*.txt' 03:02:45 INFO - no previously-included directories found matching 'build' 03:02:45 INFO - no previously-included directories found matching 'dist' 03:02:45 INFO - no previously-included directories found matching '.git*' 03:02:45 INFO - Successfully installed jsonschema functools32 03:02:45 INFO - Cleaning up... 03:02:45 INFO - Return code: 0 03:02:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:02:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:02:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:02:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:02:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:02:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104ab9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104abc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf51c9c050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104897d30>, '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 0x1047bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10488cbe8>, '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.NzXRtVlSXV/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.KUtZt9Arzz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 03:02:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:02:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:02:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NzXRtVlSXV/Render', 03:02:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:45 INFO - 'HOME': '/Users/cltbld', 03:02:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:45 INFO - 'LOGNAME': 'cltbld', 03:02:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:45 INFO - 'MOZ_NO_REMOTE': '1', 03:02:45 INFO - 'NO_EM_RESTART': '1', 03:02:45 INFO - 'PAGER': '/bin/cat', 03:02:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:45 INFO - 'PWD': '/builds/slave/test', 03:02:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:45 INFO - 'SHELL': '/bin/bash', 03:02:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KUtZt9Arzz/Listeners', 03:02:45 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 03:02:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:45 INFO - 'USER': 'cltbld', 03:02:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:45 INFO - 'XPC_FLAGS': '0x0', 03:02:45 INFO - 'XPC_SERVICE_NAME': '0', 03:02:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:02:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:02:45 INFO - Cleaning up... 03:02:45 INFO - Return code: 0 03:02:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:02:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:02:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:02:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:02:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:02:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104ab9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104abc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf51c9c050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104897d30>, '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 0x1047bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10488cbe8>, '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.NzXRtVlSXV/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.KUtZt9Arzz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 03:02:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:02:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:02:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NzXRtVlSXV/Render', 03:02:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:45 INFO - 'HOME': '/Users/cltbld', 03:02:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:45 INFO - 'LOGNAME': 'cltbld', 03:02:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:45 INFO - 'MOZ_NO_REMOTE': '1', 03:02:45 INFO - 'NO_EM_RESTART': '1', 03:02:45 INFO - 'PAGER': '/bin/cat', 03:02:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:45 INFO - 'PWD': '/builds/slave/test', 03:02:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:45 INFO - 'SHELL': '/bin/bash', 03:02:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KUtZt9Arzz/Listeners', 03:02:45 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 03:02:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:45 INFO - 'USER': 'cltbld', 03:02:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:45 INFO - 'XPC_FLAGS': '0x0', 03:02:45 INFO - 'XPC_SERVICE_NAME': '0', 03:02:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:02:45 INFO - Downloading/unpacking blobuploader==1.2.4 03:02:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:02:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:02:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:02:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:02:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:02:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:02:48 INFO - Downloading blobuploader-1.2.4.tar.gz 03:02:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:02:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:02:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:02:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:02:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:02:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:02:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:02:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:02:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:02:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:02: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 03:02: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 03:02: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 03:02: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 03:02: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 03:02: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 03:02:49 INFO - Downloading docopt-0.6.1.tar.gz 03:02:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:02:49 INFO - Installing collected packages: blobuploader, requests, docopt 03:02:49 INFO - Running setup.py install for blobuploader 03:02:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:02:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:02:50 INFO - Running setup.py install for requests 03:02:50 INFO - Running setup.py install for docopt 03:02:50 INFO - Successfully installed blobuploader requests docopt 03:02:50 INFO - Cleaning up... 03:02:50 INFO - Return code: 0 03:02:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:02:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:02:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:02:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:02:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:02:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104ab9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104abc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf51c9c050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104897d30>, '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 0x1047bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10488cbe8>, '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.NzXRtVlSXV/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.KUtZt9Arzz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 03:02:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:02:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:02:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NzXRtVlSXV/Render', 03:02:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:50 INFO - 'HOME': '/Users/cltbld', 03:02:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:50 INFO - 'LOGNAME': 'cltbld', 03:02:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:50 INFO - 'MOZ_NO_REMOTE': '1', 03:02:50 INFO - 'NO_EM_RESTART': '1', 03:02:50 INFO - 'PAGER': '/bin/cat', 03:02:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:50 INFO - 'PWD': '/builds/slave/test', 03:02:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:50 INFO - 'SHELL': '/bin/bash', 03:02:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KUtZt9Arzz/Listeners', 03:02:50 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 03:02:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:50 INFO - 'USER': 'cltbld', 03:02:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:50 INFO - 'XPC_FLAGS': '0x0', 03:02:50 INFO - 'XPC_SERVICE_NAME': '0', 03:02:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:02:51 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-4QobXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:02:51 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-xm3P2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:02:51 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-SElbIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:02:51 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-2HjOPs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:02:51 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-M2_vvK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:02:51 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-Vrg8KZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:02:51 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-MtMTgW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:02:51 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-F_GgLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:02:51 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-_WKQcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:02:52 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-ISM5ys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:02:52 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-9z3KHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:02:52 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-su9XCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:02:52 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-OpQeTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:02:52 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-bhI9KI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:02:52 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-dttkPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:02:52 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-wOT8nv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:02:52 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-euWGFS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:02:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:02:52 INFO - Running setup.py install for manifestparser 03:02:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:02:53 INFO - Running setup.py install for mozcrash 03:02:53 INFO - Running setup.py install for mozdebug 03:02:53 INFO - Running setup.py install for mozdevice 03:02:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:02:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:02:53 INFO - Running setup.py install for mozfile 03:02:53 INFO - Running setup.py install for mozhttpd 03:02:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:02:53 INFO - Running setup.py install for mozinfo 03:02:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:02:53 INFO - Running setup.py install for mozInstall 03:02:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:02:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:02:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:02:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:02:54 INFO - Running setup.py install for mozleak 03:02:54 INFO - Running setup.py install for mozlog 03:02:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:02:54 INFO - Running setup.py install for moznetwork 03:02:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:02:54 INFO - Running setup.py install for mozprocess 03:02:54 INFO - Running setup.py install for mozprofile 03:02:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:02:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:02:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:02:54 INFO - Running setup.py install for mozrunner 03:02:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:02:55 INFO - Running setup.py install for mozscreenshot 03:02:55 INFO - Running setup.py install for moztest 03:02:55 INFO - Running setup.py install for mozversion 03:02:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:02:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:02:55 INFO - Cleaning up... 03:02:55 INFO - Return code: 0 03:02:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:02:55 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')]} 03:02:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:02:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:55 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')]} 03:02:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:02:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:55 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 0x104ab9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104abc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf51c9c050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104897d30>, '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 0x1047bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10488cbe8>, '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.NzXRtVlSXV/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.KUtZt9Arzz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 03:02:55 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 03:02:55 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 03:02:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NzXRtVlSXV/Render', 03:02:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:55 INFO - 'HOME': '/Users/cltbld', 03:02:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:55 INFO - 'LOGNAME': 'cltbld', 03:02:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:55 INFO - 'MOZ_NO_REMOTE': '1', 03:02:55 INFO - 'NO_EM_RESTART': '1', 03:02:55 INFO - 'PAGER': '/bin/cat', 03:02:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:55 INFO - 'PWD': '/builds/slave/test', 03:02:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:55 INFO - 'SHELL': '/bin/bash', 03:02:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KUtZt9Arzz/Listeners', 03:02:55 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 03:02:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:55 INFO - 'USER': 'cltbld', 03:02:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:55 INFO - 'XPC_FLAGS': '0x0', 03:02:55 INFO - 'XPC_SERVICE_NAME': '0', 03:02:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:02:55 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-uukNC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:02:55 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-Z9THps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:02:55 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-3A86Qr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:02:56 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)) 03:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:02:56 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-yTvgBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:02:56 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)) 03:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:02:56 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-pf6fgD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:02:56 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)) 03:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:02:56 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-etVYfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:02:56 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)) 03:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:02:56 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-ALhnmF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:02:56 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)) 03:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:02:56 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-DCInom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:02:56 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)) 03:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:02:56 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-506hQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:02:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:02:56 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-gqYs65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:02:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:02:56 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-A5I69D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:02:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:02:56 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-LmhIyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:02:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 03:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:02:57 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-yTTsYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:02:57 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)) 03:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:02:57 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-FKk2B4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:02:57 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)) 03:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:02:57 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-g5ZlDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:02:57 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)) 03:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:02:57 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-otF758-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:02:57 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)) 03:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:02:57 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-RBROjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:02:57 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)) 03:02:57 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)) 03:02:57 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)) 03:02:57 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)) 03:02:57 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)) 03:02:57 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)) 03:02:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:02:57 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 03:02:57 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 03:02:57 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 03:02:57 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 03:02:57 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 03:02:57 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 03:03:00 INFO - Downloading blessings-1.6.tar.gz 03:03:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:03:00 INFO - Installing collected packages: blessings 03:03:00 INFO - Running setup.py install for blessings 03:03:00 INFO - Successfully installed blessings 03:03:00 INFO - Cleaning up... 03:03:00 INFO - Return code: 0 03:03:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:03:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:03:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:03:00 INFO - Reading from file tmpfile_stdout 03:03:00 INFO - Current package versions: 03:03:00 INFO - blessings == 1.6 03:03:00 INFO - blobuploader == 1.2.4 03:03:00 INFO - docopt == 0.6.1 03:03:00 INFO - functools32 == 3.2.3-2 03:03:00 INFO - jsonschema == 2.5.1 03:03:00 INFO - manifestparser == 1.1 03:03:00 INFO - mozInstall == 1.12 03:03:00 INFO - mozcrash == 1.0 03:03:00 INFO - mozdebug == 0.1 03:03:00 INFO - mozdevice == 0.49 03:03:00 INFO - mozfile == 1.2 03:03:00 INFO - mozhttpd == 0.7 03:03:00 INFO - mozinfo == 0.9 03:03:00 INFO - mozleak == 0.1 03:03:00 INFO - mozlog == 3.4 03:03:00 INFO - moznetwork == 0.27 03:03:00 INFO - mozprocess == 0.24 03:03:00 INFO - mozprofile == 0.28 03:03:00 INFO - mozrunner == 6.13 03:03:00 INFO - mozscreenshot == 0.1 03:03:00 INFO - mozsystemmonitor == 0.3 03:03:00 INFO - moztest == 0.8 03:03:00 INFO - mozversion == 1.4 03:03:00 INFO - psutil == 3.1.1 03:03:00 INFO - requests == 1.2.3 03:03:00 INFO - wsgiref == 0.1.2 03:03:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:03:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:03:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104ab9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104abc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf51c9c050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104897d30>, '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 0x1047bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10488cbe8>, '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.NzXRtVlSXV/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.KUtZt9Arzz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 03:03:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:03:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:03:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NzXRtVlSXV/Render', 03:03:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:00 INFO - 'HOME': '/Users/cltbld', 03:03:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:00 INFO - 'LOGNAME': 'cltbld', 03:03:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:00 INFO - 'MOZ_NO_REMOTE': '1', 03:03:00 INFO - 'NO_EM_RESTART': '1', 03:03:00 INFO - 'PAGER': '/bin/cat', 03:03:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:00 INFO - 'PWD': '/builds/slave/test', 03:03:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:00 INFO - 'SHELL': '/bin/bash', 03:03:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KUtZt9Arzz/Listeners', 03:03:00 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 03:03:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:00 INFO - 'USER': 'cltbld', 03:03:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:00 INFO - 'XPC_FLAGS': '0x0', 03:03:00 INFO - 'XPC_SERVICE_NAME': '0', 03:03:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:01 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)) 03:03:01 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)) 03:03:01 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)) 03:03:01 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)) 03:03:01 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)) 03:03:01 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)) 03:03:01 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)) 03:03:01 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)) 03:03:01 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)) 03:03:01 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)) 03:03:01 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)) 03:03:01 INFO - Cleaning up... 03:03:01 INFO - Return code: 0 03:03:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:03:01 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')]} 03:03:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:01 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')]} 03:03:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:01 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 0x104ab9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104abc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf51c9c050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104897d30>, '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 0x1047bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10488cbe8>, '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.NzXRtVlSXV/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.KUtZt9Arzz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 03:03:01 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 03:03:01 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 03:03:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NzXRtVlSXV/Render', 03:03:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:01 INFO - 'HOME': '/Users/cltbld', 03:03:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:01 INFO - 'LOGNAME': 'cltbld', 03:03:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:01 INFO - 'MOZ_NO_REMOTE': '1', 03:03:01 INFO - 'NO_EM_RESTART': '1', 03:03:01 INFO - 'PAGER': '/bin/cat', 03:03:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:01 INFO - 'PWD': '/builds/slave/test', 03:03:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:01 INFO - 'SHELL': '/bin/bash', 03:03:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KUtZt9Arzz/Listeners', 03:03:01 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 03:03:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:01 INFO - 'USER': 'cltbld', 03:03:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:01 INFO - 'XPC_FLAGS': '0x0', 03:03:01 INFO - 'XPC_SERVICE_NAME': '0', 03:03:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:03:01 INFO - Cleaning up... 03:03:01 INFO - Return code: 0 03:03:01 INFO - Running post-action listener: _resource_record_post_action 03:03:01 INFO - Running post-action listener: _start_resource_monitoring 03:03:01 INFO - Starting resource monitoring. 03:03:01 INFO - [mozharness: 2017-02-15 11:03:01.407319Z] Finished create-virtualenv step (success) 03:03:01 INFO - [mozharness: 2017-02-15 11:03:01.408316Z] Running install step. 03:03:01 INFO - Running pre-action listener: _resource_record_pre_action 03:03:01 INFO - Running main action method: install 03:03:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:03:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:03:01 INFO - Reading from file tmpfile_stdout 03:03:01 INFO - Detecting whether we're running mozinstall >=1.0... 03:03:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:03:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:03:01 INFO - Reading from file tmpfile_stdout 03:03:01 INFO - Output received: 03:03:01 INFO - Usage: mozinstall [options] installer 03:03:01 INFO - Options: 03:03:01 INFO - -h, --help show this help message and exit 03:03:01 INFO - -d DEST, --destination=DEST 03:03:01 INFO - Directory to install application into. [default: 03:03:01 INFO - "/builds/slave/test"] 03:03:01 INFO - --app=APP Application being installed. [default: firefox] 03:03:01 INFO - mkdir: /builds/slave/test/build/application 03:03:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:03:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 03:03:23 INFO - Reading from file tmpfile_stdout 03:03:23 INFO - Output received: 03:03:23 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 03:03:23 INFO - Running post-action listener: _resource_record_post_action 03:03:23 INFO - [mozharness: 2017-02-15 11:03:23.799838Z] Finished install step (success) 03:03:23 INFO - [mozharness: 2017-02-15 11:03:23.799968Z] Running run-tests step. 03:03:23 INFO - Running pre-action listener: _resource_record_pre_action 03:03:23 INFO - Running main action method: run_tests 03:03:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:03:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:03:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:03:23 INFO - 2017-02-15 03:03:23.814 screenresolution[2669:38110] starting screenresolution argv=screenresolution get 03:03:23 INFO - 2017-02-15 03:03:23.817 screenresolution[2669:38110] Display 0: 1600x1200x32@60 03:03:23 INFO - 2017-02-15 03:03:23.823 screenresolution[2670:38112] starting screenresolution argv=screenresolution list 03:03:23 INFO - Available Modes on Display 0 03:03:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:03:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:03:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:03:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:03:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:03:24 INFO - Intel Iris: 03:03:24 INFO - Chipset Model: Intel Iris 03:03:24 INFO - Type: GPU 03:03:24 INFO - Bus: Built-In 03:03:24 INFO - VRAM (Dynamic, Max): 1536 MB 03:03:24 INFO - Vendor: Intel (0x8086) 03:03:24 INFO - Device ID: 0x0a2e 03:03:24 INFO - Revision ID: 0x0009 03:03:24 INFO - Displays: 03:03:24 INFO - Display: 03:03:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:03:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:03:24 INFO - Display Serial Number: 1600x1200 60 03:03:24 INFO - Main Display: Yes 03:03:24 INFO - Mirror: Off 03:03:24 INFO - Online: Yes 03:03:24 INFO - Rotation: Supported 03:03:24 INFO - Return code: 0 03:03:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:03:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:03:24 INFO - Python 2.7.3 03:03:24 INFO - Return code: 0 03:03:24 INFO - grabbing minidump binary from tooltool 03:03: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')]} 03:03:24 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 0x104897d30>, '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 0x1047bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10488cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:03:24 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 03:03:24 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 03:03:24 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 03:03:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:03:24 INFO - Return code: 0 03:03:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:03:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:03:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:03:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:03:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:03:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0094', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:03:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0094 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:03:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NzXRtVlSXV/Render', 03:03:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:24 INFO - 'HOME': '/Users/cltbld', 03:03:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:24 INFO - 'LOGNAME': 'cltbld', 03:03:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:03:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:03:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:24 INFO - 'MOZ_NO_REMOTE': '1', 03:03:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:03:24 INFO - 'NO_EM_RESTART': '1', 03:03:24 INFO - 'PAGER': '/bin/cat', 03:03:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:24 INFO - 'PWD': '/builds/slave/test', 03:03:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:03:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:24 INFO - 'SHELL': '/bin/bash', 03:03:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KUtZt9Arzz/Listeners', 03:03:24 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 03:03:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:24 INFO - 'USER': 'cltbld', 03:03:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:24 INFO - 'XPC_FLAGS': '0x0', 03:03:24 INFO - 'XPC_SERVICE_NAME': '0', 03:03:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0094', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:03:24 INFO - mozversion application_buildid: 20170210030206 03:03:24 INFO - mozversion application_changeset: 25a94c1047e793ef096d8556fa3c26dd72bd37d7 03:03:24 INFO - mozversion application_display_name: Nightly 03:03:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:03:24 INFO - mozversion application_name: Firefox 03:03:24 INFO - mozversion application_remotingname: firefox 03:03:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:03:24 INFO - mozversion application_vendor: Mozilla 03:03:24 INFO - mozversion application_version: 54.0a1 03:03:24 INFO - mozversion platform_buildid: 20170210030206 03:03:24 INFO - mozversion platform_changeset: 25a94c1047e793ef096d8556fa3c26dd72bd37d7 03:03:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:03:24 INFO - mozversion platform_version: 54.0a1 03:03:24 INFO - using testdate: 1487156604 03:03:24 INFO - actual date: 1487156604 03:03:24 INFO - starting webserver on 'localhost:62408' 03:03:24 INFO - SUITE-START | Running 2 tests 03:03:24 INFO - TEST-START | dromaeo_css 03:03:24 INFO - operating with platform_type : mac_ 03:03:24 INFO - Initialising browser for dromaeo_css test... 03:03:24 INFO - TEST-INFO | started process 2680 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpxT4P00/profile http://localhost:62408/getInfo.html) 03:03:27 INFO - PROCESS | 2680 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 03:03:27 INFO - PROCESS | 2680 | 2017-02-15 03:03:27.518 plugin-container[2683:38274] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f43, name = 'com.apple.tsm.portname' 03:03:27 INFO - PROCESS | 2680 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:03:27 INFO - PROCESS | 2680 | 2017-02-15 03:03:27.518 plugin-container[2683:38274] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d07, name = 'com.apple.CFPasteboardClient' 03:03:27 INFO - PROCESS | 2680 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:03:27 INFO - PROCESS | 2680 | 2017-02-15 03:03:27.518 plugin-container[2683:38274] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:03:27 INFO - PROCESS | 2680 | __metrics Screen width/height:1600/1200 03:03:27 INFO - PROCESS | 2680 | colorDepth:24 03:03:27 INFO - PROCESS | 2680 | Browser inner width/height: 1024/689 03:03:27 INFO - PROCESS | 2680 | __metrics 03:03:27 ERROR - PROCESS | 2680 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 03:03:28 INFO - TEST-INFO | 2680: exit 0 03:03:28 INFO - Browser initialized. 03:03:28 INFO - Running cycle 1/1 for dromaeo_css test... 03:03:28 INFO - TEST-INFO | started process 2684 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpxT4P00/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:03:30 INFO - PROCESS | 2684 | 2017-02-15 03:03:30.935 plugin-container[2685:38395] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a47, name = 'com.apple.tsm.portname' 03:03:30 INFO - PROCESS | 2684 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:03:30 INFO - PROCESS | 2684 | 2017-02-15 03:03:30.935 plugin-container[2685:38395] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x430f, name = 'com.apple.CFPasteboardClient' 03:03:30 INFO - PROCESS | 2684 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:03:30 INFO - PROCESS | 2684 | 2017-02-15 03:03:30.935 plugin-container[2685:38395] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:03:40 INFO - PROCESS | 2684 | RSS: Main: 234315776 03:03:40 INFO - PROCESS | 2684 | 03:06:43 INFO - PROCESS | 2684 | Cycle 1(1): loaded http://localhost:62408/tests/dromaeo/cssquery-dojo.html (next: http://localhost:62408/tests/dromaeo/cssquery-ext.html) 03:06:45 INFO - PROCESS | 2684 | RSS: Main: 256053248 03:06:45 INFO - PROCESS | 2684 | 03:08:28 INFO - PROCESS | 2684 | ************************* 03:08:28 INFO - PROCESS | 2684 | A coding exception was thrown and uncaught in a Task. 03:08:28 INFO - PROCESS | 2684 | 03:08:28 ERROR - PROCESS | 2684 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:08:28 INFO - PROCESS | 2684 | Full stack: 03:08:28 INFO - PROCESS | 2684 | ************************* 03:09:47 INFO - PROCESS | 2684 | Cycle 1(1): loaded http://localhost:62408/tests/dromaeo/cssquery-ext.html (next: http://localhost:62408/tests/dromaeo/cssquery-jquery.html) 03:09:48 INFO - PROCESS | 2684 | RSS: Main: 264155136 03:09:48 INFO - PROCESS | 2684 | 03:12:50 INFO - PROCESS | 2684 | Cycle 1(1): loaded http://localhost:62408/tests/dromaeo/cssquery-jquery.html (next: http://localhost:62408/tests/dromaeo/cssquery-mootools.html) 03:12:50 INFO - PROCESS | 2684 | RSS: Main: 275075072 03:12:50 INFO - PROCESS | 2684 | 03:15:52 INFO - PROCESS | 2684 | Cycle 1(1): loaded http://localhost:62408/tests/dromaeo/cssquery-mootools.html (next: http://localhost:62408/tests/dromaeo/cssquery-prototype.html) 03:15:53 INFO - PROCESS | 2684 | RSS: Main: 268808192 03:15:53 INFO - PROCESS | 2684 | 03:18:56 INFO - PROCESS | 2684 | Cycle 1(1): loaded http://localhost:62408/tests/dromaeo/cssquery-prototype.html (next: http://localhost:62408/tests/dromaeo/cssquery-yui.html) 03:18:56 INFO - PROCESS | 2684 | RSS: Main: 273793024 03:18:56 INFO - PROCESS | 2684 | 03:21:59 INFO - PROCESS | 2684 | Cycle 1(1): loaded http://localhost:62408/tests/dromaeo/cssquery-yui.html (next: http://localhost:62408/tests/dromaeo/cssquery-dojo.html) 03:21:59 INFO - PROCESS | 2684 | RSS: Main: 277970944 03:21:59 INFO - PROCESS | 2684 | 03:21:59 INFO - PROCESS | 2684 | __start_tp_report 03:21:59 INFO - PROCESS | 2684 | _x_x_mozilla_page_load 03:21:59 INFO - PROCESS | 2684 | _x_x_mozilla_page_load_details 03:21:59 INFO - PROCESS | 2684 | |i|pagename|runs| 03:21:59 INFO - PROCESS | 2684 | |0;dojo.html;2809.5785632155175;3070.554769558414;3308.305255896262;3320.8339583020843;3324.119108436264;4962.826301079463;5447.782088716446;5567.526760225381;5594.160875868617;5634.859128521789;5460.808871689492;5497.47773961474;5507.284053073095;5538.584606154534;5544.140658197975;4985.975070124644;5000.699958002531;5112.207607820775;5134.460881607433;5134.460881607433;5082.2122571001555;5137.229415587654;5159.948400516003;5185.403678576974;5186.3257776489;5135.152699804527;5149.948500515003;5156.664816786897;5157.071727089123;5163.199704045879;5128.8461346159675;5136.717480538568;5146.3824341078935;5149.0731668299695;5153.020926024062;3911.7457365271166;4459.617518569254;5002;5106.183010718267;5122.513361230677;5630.943690563083;5703.429657034305;5708.115242137434;5732.140178973145;5784.855378615568;5929.199558059693;6069.939300606981;6140.171076904606;6163.383661633846;6182.412670796267;6049.092636104576;6074.817755467343;6101.641069425254;6155.169052177944;6203.937960620381;5797.652140871562;5798.565107616903;5811.128330750336;5823.068309070529;5833.970830145865;6150.692465376803;6206.975848984957;6226.782062627786;6248.375162483806;6264.373562643699;5770.451807078321;5812.534997200214;5996.580239383201;6097.085437184458;6106.603070800473;6084.939150608438;6172.321044685081;6188.2883468400805;6218.31598524162;6220.595661282093;6118.1434599156155;6149.016157414792;6165.106059621419;6176.8764624707255;6184.103305020747;5839.532837373;5938.138969849348;6181.35095814933;6183.628982261079;6210.689465526705;4892.119418504703;4898.02039592083;4916.0905232532095;4926.507349265116;4997.475265097112;4365.934510982338;4388.495323037828;4404.7357158570585;4413.3821265022925;4428.2914733642465;5555.999920014356;5846.064629659234;5864.560157988168;5867.735951882176;5892.499137573354;5042.394912610437;6098.664573448503;6132.967444181115;6162.568620196544;6226.782062627877;5285.2600635911;5375.247465354853;5772.538196944234;5813.215215945877;5822.941770582324;5251.133562962144;5305.65513241638;5353.598480114007;5377.193420986883;5387.488188622075;6247.312795592481;6264.968675156686;6266.68666566679;6280.277768056732;6283.434490895841;5170.896582068262;6178.907316390168;6196.535259855439;6199.039148931923;6224.439800417984;6130.887362651087;6177.907331390123;6200.658963756856;6223.627119149658;6275.52933529978;6106.236720409949;6155.353687862889;6156.415140561639;6158.538109641705;6198.2252218472695;6213.844653883689;6217.96891015542;6223.844403889939;6233.314335423191;6239;5758.3090029196765;5894.852628684145;6072.969635151796;6082.11809287642;6100.969495152496;5872.794452194153;6009.158717779427;6073.058675905242;6085.391460853879;6099;4673.369095172108;5670.206171136127;5825.737841797045;5927.644341339361;5933.584649074696;4959.62802789785;6058.4850287724275;6084.239470066242;6088.847778805565;6103.908441373472;5542.445755424425;5838.4453476919625;5852.561057920588;5886.646801191942;5977.760889564543 03:21:59 INFO - PROCESS | 2684 | |1;ext.html;437.4553680667569;455.6491501543811;495.4178839863161;506.3898002906498;511.3965520685594;29468.85265573672;36114.27771444569;38743.41884871726;41329.7933510332;41423.58576414235;30530.69469305306;33158.834205829;40269.19461610766;40378.596214037854;40545.78362649118;7848.960755196216;9395;9442.905570944306;9447.858282125773;10549.314294570828;8943.418677785956;10844.349339039683;10844.566217351297;10854.782904341908;10875;10873.401962892038;10893.673189804318;10900.018998290152;10911.83632245513;10915.290506117117;10794.676159715222;10867.728306792314;10871.510782014828;10881.455927203608;10882.40146791926;10676.306040107369;10738.946305268424;10753.569857205703;10755.731106722316;10773.976472235125;34203.144921376916;34313.48529772055;34527.82736086303;34537.654623453695;34558.48162277568;3037.9088627341216;3246.513023046562;3269.1336795749144;3274.4106060909025;3327.351166522529;25966.221013369628;26779.598306025426;26888.59667104995;27003.594946075824;27026.729732702617;3070.355225402668;3143.4341818472667;3179.0780673604627;3211.229304966791;3217.549543063973;416369;421007;421730;423125;425352.87323563494;45579.77210113928;46628.3005754914;52743.47256527462;53027.28808983334;53046;67690;68583.65708171428;68664.28339291557;68909.62180756357;69130.30869691238;14748.63128421798;14949.402023918921;15338.233088345762;15339.309731062129;15379.38482460689;10043.748906277255;11515.942420287845;11559.711007224887;11742;11757;19027.044168508324;19484.083970604388;19520.578653196517;19603.21587136527;19663.542057242845;6908.585484870924;7459.36595389398;7855.450118491765;7917.485363451357;8092.473989190801;660.2506155513527;749.8387846613005;755.524019867491;761.3300295739714;763.2634507700096;27698;28069.859650701615;28963.986260480782;29129.27176820554;29737.553936690965;1011.3577878047493;1020.9846852297221;1034.1726618705156;1035.3322107240788;1169.1757311095657;14737.11577305365;15069.47256846027;15268.480700684262;15357.462488812838;15437.845621543865;534110;537027;540254;541137;541538;55507.444925550226;55689.721551392795;55854;55859.44140558542;56100.43899561034;1296.0797833538293;1339.377189606818;1342.02703040295;1342.9261390623415;1350.2910971739916;27601;28279.192537269126;28884.27789305284;28963.565546516398;29148.7085129146;35480.82259588686;46552.06895861996;48536.51463485461;48618.75690621524;49036.26445603248;333030;345616;387007;413070;414086;30092.24769380783;39515.209695805344;40382.79808600938;40429.19141617092;40502;43299;43356;43832.12335753203;44547.77726111478;44696;29626.703732962393;34174.65825341715;36337.818310908275;37463.62536374601;37467;35877.92366229017;36424.90725278246;36820.63179368172;37044.81477592703;37948.24103517969;33126;33254.50118248277;33370.33259334848;34952.082702625026;35300.82349588236;27537.449251015267;28934.565981509873;30616;30671.846640766653;30967.225819353796;23008.309750707507;23196.536069278183;23342.299731008097;23570.646440303753;23627.527449450572 03:21:59 INFO - PROCESS | 2684 | |2;jquery.html;347.1858491836643;383.24500733554885;392.8193031205644;402.61147992187534;415.8252935457332;17661.911690441546;19513.51216219595;20112.59774804505;20390.592188156228;20397.99999999998;19672.721273117237;19961.401157965247;20007.799922000777;20364;20469.48826279344;5567.164925261223;5587.99999999999;5696.120280307509;5768.509676677488;5863.208466856962;5659.717014149276;5777.971110144465;5786.392428794979;5790.68151251681;5791.07342825148;5521.917171242435;5616.859578510529;5696.405006598138;5698.430156984289;5730;5605.943940560583;5622.3253209614695;5658.12299093637;5671.716414179275;5679.460451257145;4258.446401967724;4955.504449555052;4990.2764099205415;5003.924941125886;5012.398512178562;23581;23862.494438083926;23928.880355598285;23955.88022059896;24084.759152408602;2678.3132409983277;2693.8114331996767;2703.972960270392;2712.7016028236876;2737.288305040684;11135.777284454265;14412.711745765131;14416.783748243784;15103.622409439851;15161.393544258217;1580.517942027681;1607.7700559072318;1701.948941531756;1765.479183640829;1812.103009010535;78805.60597197035;104779.47610261977;105184;105576.47211763969;106446;9966.551505182284;11330.716732081599;11506.827397589042;11813.291202527877;11946;15139.394424222908;15170.924145379202;15228.619284517754;15264.313105910262;15265.542033739006;7966.442349035628;8198.221168988937;8213.671453141807;8219.589020548949;8308.916910830936;6495.512836537278;7791.844163116706;7798.1811909749795;7943.563104029334;7944.721934732257;3988.461557689675;4520.751358675238;4557.95442045582;4628.514006029319;4767.165745994506;1688.6622675464955;1711.7432385142329;1985.463924740312;2008.4577164165594;2029.482481967089;918.6968300460832;933.0296491648745;954.5275088830934;972.6644307713827;975.770693886936;18917.243310267437;20145.597088058155;20161;20380;20488.89755551213;926.3098991251426;941.684535680541;943.9292053095908;945.8770359853175;953.0421925964362;8967.955160224157;10257.692269231935;10566.101881340172;10599.679549186205;10626.946865265625;651201;657656;662527;666262;681467;107015.46492267644;120440;143388;144085.27957360356;144703.5529644711;847.9236868681725;953.7234202081319;962.485070487286;983.095552092077;986.2455221755363;18165.455036348932;18870.33953811665;18879.43361699151;18889.90555047216;18961.810381896004;43671;47645.80885477752;48088.519114808405;48294.51705483041;53122;70561;74502.62748686221;75175.62412187904;75273;77317.4536509278;6396.488280937607;7158.570485770871;7874;8028.197180281925;8075.192480751878;11752.764944700886;11926.940365298118;11976.107417235504;12086.939565302117;12232.388380581113;10268.127209187303;10270.640527581798;10583.31208471447;10728.624498142528;10797.892021079688;10193.847092293474;10241.897581024094;10244.743881403025;10489.265751397328;10643.254972151875;10266.897331026892;10572.101371383518;10746.516406761559;10860.67417977462;10879.836802447811;7891.367142134;7962.6814927404575;8087.150849160988;8156.265936065783;8259.132791057093;8030.638621262174;10421.791564168825;10460.110890574386;10817.134629229486;10926.890731092906 03:21:59 INFO - PROCESS | 2684 | |3;mootools.html;1382.84788673246;1384.8753612174903;1403.0529392659955;1405.6345350208942;1406.6905280838218;8119.87820182697;8259.463134896228;8524.06235314115;8581.914180858197;8592;8358;8497.787555311104;8516.31869450444;8516.61675224615;8540.786480337994;1971.3297478857185;2205.6691496275525;2215.7784221577876;2269;2270.5231901300745;1984.7496077471174;2240.3838944290287;2279.0314116500504;2283.942901427461;2286.211257116292;2180.8800515971616;2230.063373383183;2261.4459457432954;2263.9660405093937;2265.286434773041;2169.3275084723628;2227.8774667393286;2250;2258.141906075689;2258.6950761647213;1904.7047707605343;1955.3938279133515;2026.3313106674761;2231.274835678395;2291.842619477153;8309.210624990677;8489.108643592395;8511.361647876372;8517.957410212972;8595.44129631572;4692.343071969927;4894.7063176209185;5021.799128034874;5170.250313704491;5186.5850731941355;3351.8324083795956;3440.3119376124623;3497.930041399183;3507.57909050913;3531.7351198659944;1026.845973104033;1161.5005547614523;1190.3631557116987;1195.0439648281426;1219.7072702551413;8917;10842.566297348176;10992.780144397067;10995.670129896196;11026.283291586022;4166.979165104155;4401.361802538614;4462.509123996358;4468.151051300242;4481.484629267611;8763.693270735494;8819.162179592928;8827.073157318517;8830.293576513863;8852.822943541094;5061.645684802103;5066.037452884014;5069.949300507022;5079.161938280216;5107.948920510822;4503.549645035536;4633.9536604634195;4653.092646933816;4673.119880863738;4711.811527538928;3604.693601043888;3614.0784100054843;3686.1337585667347;3716.2567486502267;3743.644353786386;1088.77135801481;1097.7091070866227;1099.8680158381046;1108.933463992163;1109.9667009989714;798.4650284309527;865.6321063547967;867.2021739999216;882.7087061269766;912.7763697894059;5538.944610553924;5603.607747457639;5638.887222255532;5783.334916484574;6025.975584150705;910.8041771019131;946.8485042393185;954.8042651256476;968.8982656821073;991.591939445974;3398.3033478137418;3567.2532150244165;3620.87326943561;3894.247588585148;3914.8042597869994;8373.748787536384;9067.002629710727;9792.755181120529;10131.898681013095;10154.136898363722;10083;10085.596576137019;10115.494225288707;10117.544710488046;10139.290249682452;7500.36246919018;7749.380049596019;7884.881726774103;7901.486403383647;7910.723124690723;5009.5491405773655;5231.346081739783;5455.890882182255;5496.31296087989;5614.2701448811395;9050.230730387728;9138.63445462174;9161.954190229006;9204.21764150028;9260.30547708911;8107.4324797263625;9107.225885799517;9185.49479778592;9199.448033118035;9421.576029078571;6618.271990080991;6809.761658342132;6855.725770969106;6861.210960739579;6868.725250989904;7684.731034413769;7839.960800195962;7926;7943.44395892282;7966.322862556747;7112.253213412724;7159.427245820426;7187.496875218893;7221.169565499825;7270.309320614533;5651.954388437987;6873.896891546731;6975.9302406975285;7025.964870175821;7046.647667616701;6303.621782693053;6406.807795766134;6435.646039467874;6442.742290308335;6467.223933128054;4894.828680996148;4920.778564964514;5004.349434573628;5014.874628134326;5130.230465430215;4298.591633794785;4939.308496810378;5094.617903657167;5105.795768169231;5171.81898633546 03:21:59 INFO - PROCESS | 2684 | |4;prototype.html;322.5580954092893;346.8317865835071;360.9783412995222;363.106757376853;368.296553582657;4731.243001119821;4745.501722319159;4864.854054378375;5294.57643388528;5312.415634280231;5087.872803179922;5375.623706340558;5405.5135037846685;5418.647787893784;5460.890782184354;4273.93151712072;4296.785160741958;4298.441202643652;4309.396684464177;4335.978320108395;3577.406380374234;3649.5620525536974;4131.9463536798075;4141.523724771645;4282.779407868758;3184.251700850298;3729.8064619321826;3979.4030895365636;4106.507219133693;4176.143890502455;3628.600853906068;4073.002114481941;4135.828689937776;4229.3355186232475;4233.814531931049;3194.808311501294;3297.4831577474392;3306.826076742746;3473.776782858972;3742.176721121349;4209.473815773028;5063;5151.716655583941;5173.663711858717;5191.351081114861;4884.438289596671;4885.218365061573;4887.828925987609;4889.413270407538;4890.462049174588;4858.246971719389;5278.736063196863;5732.3407808101765;5755.453231942959;5769.1923130761725;3295.6045274567205;3831.3949473725625;4073.9185216295805;4156.8129434175235;4189.224993376235;5846.070796637099;5937.67342796146;5983.551233657449;6119.418655227746;6120.663363515005;3384.1539615096226;3404.5914490261325;3906.609339066071;4191.371294305817;4213.904384859959;3790.5830358660523;3824.6175382461533;4279.657627389802;4286.442762440863;4298.677599180074;4681.50844161365;4914.525642307293;4926.901461970812;4969.751512424365;4970.776315065831;4943.90112197754;5053.772580233898;5676.545876329884;5753.827385178452;5778.104393818965;1255.3911352993791;1351.148776270943;1380.7652699041187;1383.840858301288;1387.7155183187422;865.5455885660078;877.6270085213758;942.1909932928793;976.9546585153959;982.5360213282158;4513.909721805611;4565.995480994243;4928.841722195281;4966.255061740768;5132.023426412672;5141.074606570854;5362.329708786402;5468.945310546923;5699.544036477155;5708.942910570924;4554.908901822011;4654.4414670239785;4859.902801943941;5093.363329583802;5096.541311282002;2439.6706444630104;2678.1296078774476;2784.2482529716863;2924.9707502925125;2933.9853300733785;5039.807683657621;5225.5819534437915;5707.944030354398;5866.354700982888;5981.880362392728;4829.879253018632;5277.366715994105;5346.8663283417445;5530.326469913026;5936.22829032223;5818.650880947157;5868.94131058684;5907.881842363128;5917.993941030035;5940.643561386244;4719.7492664444635;5133.054301682609;5576.86057848557;5635.6618602883955;5677.829665110054;4958.652894297509;5052.924206136838;5121.257417674342;5551.722413879209;5668.801591944262;5018.874528136826;5044.8234311798915;5851.8244452664785;5907.17299578068;5918.35409203773;3968.7420317679257;4213.441026820096;4228.906691187882;4254.255505286625;4260.957390426056;5408.8377348679605;5487.9298536784945;5930.644161350333;5938.762449502145;5966.19456373384;4326.826926923014;4651.790669419952;5674.319081710221;5690.089585666274;5759.539236861125;5293;5691.861627674399;5749.425057494217;5753.223314852608;5808.30300363959;5311.707856067799;5383.734822316674;5572.60991730575;5615.410381909841;5646.943530564642;4725.621950244041;5694.747155625756;5709.571782116275;5709.857253568694;5781.537477001913;4683.765811709332;5241.741981924387;5652.971735141463;5655.406182350958;5660.830175094754 03:21:59 INFO - PROCESS | 2684 | |5;yui.html;684.7603338831407;690.7168061094952;693.732912828561;771.2326235395782;781.1485480825899;12981.75456135966;13153.868461315384;13615.183089014654;13644.590662280123;13663.521776737816;12453.750924981496;12648.430820613072;12654.746905061893;12670.113092083559;12714.936425317861;812.5287333346645;839.1314988986401;856.1609622569886;856.6701819799391;858.4205661178711;820.9179082091773;843.6541018182546;848.7326492154982;858.0389963241147;860.096898256831;898.7483504618717;902.4765635931177;906.0893801729253;906.4153620914507;907.2061945797427;883.3816328569994;895.1496078725237;897.1835629577087;899.9875140466975;901.7610333261658;654.571255827429;661.4146480364864;662.3343539742527;662.5792621685216;663.7842701122155;13145.60563183106;13450.260235687034;13450.529231476852;13463.86536134636;13670.52153174634;12129.514819407214;12155.270683758914;12210.145289829716;12234.204776689576;12504.31226282554;9720.368176068603;11560.421978901022;11679.416029198506;11804.940975295154;12142.939285303606;3537.995800687257;3598.5861625913094;3720.627937206285;3766.0020094674933;3766.1902690921584;68010.29974250584;89982.5500872498;92593;96157;96177;8900.243479304203;9272.907270927204;9339.673111441067;9360.391574547633;9522.238220942309;20789.1684332625;21131.47171320729;21176.152953881672;21186.78813211879;21342.466438339165;5823.56323275756;5866.941330586725;6627.768028118992;6735.528513004141;6903.378695917291;3264.5919260092487;3377.6960073593496;3608;3768.2651882883124;3795.411711184771;2962.4371369439737;3035.1501579557757;3565.1978304881195;3599.1721903962234;3607.2244467439627;557.2560631556917;586.7770247305997;593.2554643921936;599.4694695194784;638.1608185236493;642.9038489375604;646.4892734739597;670.909427227318;676.577139287945;689.7274528495019;7529.623518824037;8413.368997325102;9545.809083818285;9764.51177441125;9979;537.3739593373676;541.6722882655937;627.6296984779002;647.7247169952846;675.7702381190419;4293.042037483553;4874.2444921037295;4894.461609222983;5060.2156665716875;5310.097283461821;72822;90238.54880725555;92134;93130;96201.51899240458;66614.66770664559;66731;68224;68827.62344753077;73952.26047739561;63715;64407.03389449162;65052;65178.999999999054;66625.33374666289;10561.367759250272;10600.787984240425;10672.252942293964;10720.60632117851;10842.891571084503;42314;43175.56824431715;43292.134157316046;43379.349309759746;43812;55240;56651;57705;59141.704291478265;63962.68018659877;13742.175469471864;13753.587392378246;13774.173549587056;13787.51743688966;13805.102668326526;13268.734625307632;14400.639984000483;14448.711025779636;14494.927525362305;14536.345864435914;12931.482740690268;13084.738305234032;13412.463501459832;13662.248576328397;13986.510472133426;13174.47302107905;13197.288378195592;13476.056676032584;13757.31213439344;13813.309334533033;10714.444934291574;12997.480100795863;13055.543055992996;13173.868261317264;13675.79486307726;8393.874091888749;10532.05211530966;10867.130629549774;11293.322400655987;11372.621540706721;7691.269329413697;9183.403078799856;10270.119299365706;10562.207834412297;10613.9469302653 03:21:59 INFO - PROCESS | 2684 | __end_tp_report 03:21:59 INFO - PROCESS | 2684 | __start_cc_report 03:21:59 INFO - PROCESS | 2684 | _x_x_mozilla_cycle_collect,406 03:21:59 INFO - PROCESS | 2684 | __end_cc_report 03:21:59 INFO - PROCESS | 2684 | __startTimestamp1487157719864__endTimestamp 03:21:59 INFO - PROCESS | 2684 | 03:21:59 INFO - PROCESS | 2684 | ------- Summary: start ------- 03:21:59 INFO - PROCESS | 2684 | Number of tests: 6 03:21:59 INFO - PROCESS | 2684 | 03:21:59 INFO - PROCESS | 2684 | [#0] dojo.html Cycles:165 Average:5631.23 Median:5839.53 stddev:677.96 (11.6%) stddev-sans-first:642.88 03:21:59 INFO - PROCESS | 2684 | Values: 2809.6 3070.6 3308.3 3320.8 3324.1 4962.8 5447.8 5567.5 5594.2 5634.9 5460.8 5497.5 5507.3 5538.6 5544.1 4986.0 5000.7 5112.2 5134.5 5134.5 5082.2 5137.2 5159.9 5185.4 5186.3 5135.2 5149.9 5156.7 5157.1 5163.2 5128.8 5136.7 5146.4 5149.1 5153.0 3911.7 4459.6 5002.0 5106.2 5122.5 5630.9 5703.4 5708.1 5732.1 5784.9 5929.2 6069.9 6140.2 6163.4 6182.4 6049.1 6074.8 6101.6 6155.2 6203.9 5797.7 5798.6 5811.1 5823.1 5834.0 6150.7 6207.0 6226.8 6248.4 6264.4 5770.5 5812.5 5996.6 6097.1 6106.6 6084.9 6172.3 6188.3 6218.3 6220.6 6118.1 6149.0 6165.1 6176.9 6184.1 5839.5 5938.1 6181.4 6183.6 6210.7 4892.1 4898.0 4916.1 4926.5 4997.5 4365.9 4388.5 4404.7 4413.4 4428.3 5556.0 5846.1 5864.6 5867.7 5892.5 5042.4 6098.7 6133.0 6162.6 6226.8 5285.3 5375.2 5772.5 5813.2 5822.9 5251.1 5305.7 5353.6 5377.2 5387.5 6247.3 6265.0 6266.7 6280.3 6283.4 5170.9 6178.9 6196.5 6199.0 6224.4 6130.9 6177.9 6200.7 6223.6 6275.5 6106.2 6155.4 6156.4 6158.5 6198.2 6213.8 6218.0 6223.8 6233.3 6239.0 5758.3 5894.9 6073.0 6082.1 6101.0 5872.8 6009.2 6073.1 6085.4 6099.0 4673.4 5670.2 5825.7 5927.6 5933.6 4959.6 6058.5 6084.2 6088.8 6103.9 5542.4 5838.4 5852.6 5886.6 5977.8 03:21:59 INFO - PROCESS | 2684 | 03:21:59 INFO - PROCESS | 2684 | [#1] ext.html Cycles:180 Average:59003.24 Median:18290.93 stddev:120070.26 (656.4%) stddev-sans-first:120326.57 03:21:59 INFO - PROCESS | 2684 | Values: 437.5 455.6 495.4 506.4 511.4 29468.9 36114.3 38743.4 41329.8 41423.6 30530.7 33158.8 40269.2 40378.6 40545.8 7849.0 9395.0 9442.9 9447.9 10549.3 8943.4 10844.3 10844.6 10854.8 10875.0 10873.4 10893.7 10900.0 10911.8 10915.3 10794.7 10867.7 10871.5 10881.5 10882.4 10676.3 10738.9 10753.6 10755.7 10774.0 34203.1 34313.5 34527.8 34537.7 34558.5 3037.9 3246.5 3269.1 3274.4 3327.4 25966.2 26779.6 26888.6 27003.6 27026.7 3070.4 3143.4 3179.1 3211.2 3217.5 416369.0 421007.0 421730.0 423125.0 425352.9 45579.8 46628.3 52743.5 53027.3 53046.0 67690.0 68583.7 68664.3 68909.6 69130.3 14748.6 14949.4 15338.2 15339.3 15379.4 10043.7 11515.9 11559.7 11742.0 11757.0 19027.0 19484.1 19520.6 19603.2 19663.5 6908.6 7459.4 7855.5 7917.5 8092.5 660.3 749.8 755.5 761.3 763.3 27698.0 28069.9 28964.0 29129.3 29737.6 1011.4 1021.0 1034.2 1035.3 1169.2 14737.1 15069.5 15268.5 15357.5 15437.8 534110.0 537027.0 540254.0 541137.0 541538.0 55507.4 55689.7 55854.0 55859.4 56100.4 1296.1 1339.4 1342.0 1342.9 1350.3 27601.0 28279.2 28884.3 28963.6 29148.7 35480.8 46552.1 48536.5 48618.8 49036.3 333030.0 345616.0 387007.0 413070.0 414086.0 30092.2 39515.2 40382.8 40429.2 40502.0 43299.0 43356.0 43832.1 44547.8 44696.0 29626.7 34174.7 36337.8 37463.6 37467.0 35877.9 36424.9 36820.6 37044.8 37948.2 33126.0 33254.5 33370.3 34952.1 35300.8 27537.4 28934.6 30616.0 30671.8 30967.2 23008.3 23196.5 23342.3 23570.6 23627.5 03:21:59 INFO - PROCESS | 2684 | 03:21:59 INFO - PROCESS | 2684 | [#2] jquery.html Cycles:180 Average:36067.30 Median:1542.24 stddev:110029.20 (7134.4%) stddev-sans-first:110305.17 03:21:59 INFO - PROCESS | 2684 | Values: 347.2 383.2 392.8 402.6 415.8 17661.9 19513.5 20112.6 20390.6 20398.0 19672.7 19961.4 20007.8 20364.0 20469.5 5567.2 5588.0 5696.1 5768.5 5863.2 5659.7 5778.0 5786.4 5790.7 5791.1 5521.9 5616.9 5696.4 5698.4 5730.0 5605.9 5622.3 5658.1 5671.7 5679.5 4258.4 4955.5 4990.3 5003.9 5012.4 23581.0 23862.5 23928.9 23955.9 24084.8 2678.3 2693.8 2704.0 2712.7 2737.3 11135.8 14412.7 14416.8 15103.6 15161.4 1580.5 1607.8 1701.9 1765.5 1812.1 78805.6 104779.5 105184.0 105576.5 106446.0 9966.6 11330.7 11506.8 11813.3 11946.0 15139.4 15170.9 15228.6 15264.3 15265.5 7966.4 8198.2 8213.7 8219.6 8308.9 6495.5 7791.8 7798.2 7943.6 7944.7 3988.5 4520.8 4558.0 4628.5 4767.2 1688.7 1711.7 1985.5 2008.5 2029.5 918.7 933.0 954.5 972.7 975.8 18917.2 20145.6 20161.0 20380.0 20488.9 926.3 941.7 943.9 945.9 953.0 8968.0 10257.7 10566.1 10599.7 10626.9 651201.0 657656.0 662527.0 666262.0 681467.0 107015.5 120440.0 143388.0 144085.3 144703.6 847.9 953.7 962.5 983.1 986.2 18165.5 18870.3 18879.4 18889.9 18961.8 43671.0 47645.8 48088.5 48294.5 53122.0 70561.0 74502.6 75175.6 75273.0 77317.5 6396.5 7158.6 7874.0 8028.2 8075.2 11752.8 11926.9 11976.1 12086.9 12232.4 10268.1 10270.6 10583.3 10728.6 10797.9 10193.8 10241.9 10244.7 10489.3 10643.3 10266.9 10572.1 10746.5 10860.7 10879.8 7891.4 7962.7 8087.2 8156.3 8259.1 8030.6 10421.8 10460.1 10817.1 10926.9 03:21:59 INFO - PROCESS | 2684 | 03:21:59 INFO - PROCESS | 2684 | [#3] mootools.html Cycles:180 Average:5351.83 Median:5100.21 stddev:2945.83 (57.8%) stddev-sans-first:2938.99 03:21:59 INFO - PROCESS | 2684 | Values: 1382.8 1384.9 1403.1 1405.6 1406.7 8119.9 8259.5 8524.1 8581.9 8592.0 8358.0 8497.8 8516.3 8516.6 8540.8 1971.3 2205.7 2215.8 2269.0 2270.5 1984.7 2240.4 2279.0 2283.9 2286.2 2180.9 2230.1 2261.4 2264.0 2265.3 2169.3 2227.9 2250.0 2258.1 2258.7 1904.7 1955.4 2026.3 2231.3 2291.8 8309.2 8489.1 8511.4 8518.0 8595.4 4692.3 4894.7 5021.8 5170.3 5186.6 3351.8 3440.3 3497.9 3507.6 3531.7 1026.8 1161.5 1190.4 1195.0 1219.7 8917.0 10842.6 10992.8 10995.7 11026.3 4167.0 4401.4 4462.5 4468.2 4481.5 8763.7 8819.2 8827.1 8830.3 8852.8 5061.6 5066.0 5069.9 5079.2 5107.9 4503.5 4634.0 4653.1 4673.1 4711.8 3604.7 3614.1 3686.1 3716.3 3743.6 1088.8 1097.7 1099.9 1108.9 1110.0 798.5 865.6 867.2 882.7 912.8 5538.9 5603.6 5638.9 5783.3 6026.0 910.8 946.8 954.8 968.9 991.6 3398.3 3567.3 3620.9 3894.2 3914.8 8373.7 9067.0 9792.8 10131.9 10154.1 10083.0 10085.6 10115.5 10117.5 10139.3 7500.4 7749.4 7884.9 7901.5 7910.7 5009.5 5231.3 5455.9 5496.3 5614.3 9050.2 9138.6 9162.0 9204.2 9260.3 8107.4 9107.2 9185.5 9199.4 9421.6 6618.3 6809.8 6855.7 6861.2 6868.7 7684.7 7840.0 7926.0 7943.4 7966.3 7112.3 7159.4 7187.5 7221.2 7270.3 5652.0 6873.9 6975.9 7026.0 7046.6 6303.6 6406.8 6435.6 6442.7 6467.2 4894.8 4920.8 5004.3 5014.9 5130.2 4298.6 4939.3 5094.6 5105.8 5171.8 03:21:59 INFO - PROCESS | 2684 | 03:21:59 INFO - PROCESS | 2684 | [#4] prototype.html Cycles:180 Average:4559.57 Median:5029.34 stddev:1378.90 (27.4%) stddev-sans-first:1345.60 03:21:59 INFO - PROCESS | 2684 | Values: 322.6 346.8 361.0 363.1 368.3 4731.2 4745.5 4864.9 5294.6 5312.4 5087.9 5375.6 5405.5 5418.6 5460.9 4273.9 4296.8 4298.4 4309.4 4336.0 3577.4 3649.6 4131.9 4141.5 4282.8 3184.3 3729.8 3979.4 4106.5 4176.1 3628.6 4073.0 4135.8 4229.3 4233.8 3194.8 3297.5 3306.8 3473.8 3742.2 4209.5 5063.0 5151.7 5173.7 5191.4 4884.4 4885.2 4887.8 4889.4 4890.5 4858.2 5278.7 5732.3 5755.5 5769.2 3295.6 3831.4 4073.9 4156.8 4189.2 5846.1 5937.7 5983.6 6119.4 6120.7 3384.2 3404.6 3906.6 4191.4 4213.9 3790.6 3824.6 4279.7 4286.4 4298.7 4681.5 4914.5 4926.9 4969.8 4970.8 4943.9 5053.8 5676.5 5753.8 5778.1 1255.4 1351.1 1380.8 1383.8 1387.7 865.5 877.6 942.2 977.0 982.5 4513.9 4566.0 4928.8 4966.3 5132.0 5141.1 5362.3 5468.9 5699.5 5708.9 4554.9 4654.4 4859.9 5093.4 5096.5 2439.7 2678.1 2784.2 2925.0 2934.0 5039.8 5225.6 5707.9 5866.4 5981.9 4829.9 5277.4 5346.9 5530.3 5936.2 5818.7 5868.9 5907.9 5918.0 5940.6 4719.7 5133.1 5576.9 5635.7 5677.8 4958.7 5052.9 5121.3 5551.7 5668.8 5018.9 5044.8 5851.8 5907.2 5918.4 3968.7 4213.4 4228.9 4254.3 4261.0 5408.8 5487.9 5930.6 5938.8 5966.2 4326.8 4651.8 5674.3 5690.1 5759.5 5293.0 5691.9 5749.4 5753.2 5808.3 5311.7 5383.7 5572.6 5615.4 5646.9 4725.6 5694.7 5709.6 5709.9 5781.5 4683.8 5241.7 5653.0 5655.4 5660.8 03:21:59 INFO - PROCESS | 2684 | 03:21:59 INFO - PROCESS | 2684 | [#5] yui.html Cycles:180 Average:17853.33 Median:2923.74 stddev:24503.09 (838.1%) stddev-sans-first:24537.91 03:21:59 INFO - PROCESS | 2684 | Values: 684.8 690.7 693.7 771.2 781.1 12981.8 13153.9 13615.2 13644.6 13663.5 12453.8 12648.4 12654.7 12670.1 12714.9 812.5 839.1 856.2 856.7 858.4 820.9 843.7 848.7 858.0 860.1 898.7 902.5 906.1 906.4 907.2 883.4 895.1 897.2 900.0 901.8 654.6 661.4 662.3 662.6 663.8 13145.6 13450.3 13450.5 13463.9 13670.5 12129.5 12155.3 12210.1 12234.2 12504.3 9720.4 11560.4 11679.4 11804.9 12142.9 3538.0 3598.6 3720.6 3766.0 3766.2 68010.3 89982.6 92593.0 96157.0 96177.0 8900.2 9272.9 9339.7 9360.4 9522.2 20789.2 21131.5 21176.2 21186.8 21342.5 5823.6 5866.9 6627.8 6735.5 6903.4 3264.6 3377.7 3608.0 3768.3 3795.4 2962.4 3035.2 3565.2 3599.2 3607.2 557.3 586.8 593.3 599.5 638.2 642.9 646.5 670.9 676.6 689.7 7529.6 8413.4 9545.8 9764.5 9979.0 537.4 541.7 627.6 647.7 675.8 4293.0 4874.2 4894.5 5060.2 5310.1 72822.0 90238.5 92134.0 93130.0 96201.5 66614.7 66731.0 68224.0 68827.6 73952.3 63715.0 64407.0 65052.0 65179.0 66625.3 10561.4 10600.8 10672.3 10720.6 10842.9 42314.0 43175.6 43292.1 43379.3 43812.0 55240.0 56651.0 57705.0 59141.7 63962.7 13742.2 13753.6 13774.2 13787.5 13805.1 13268.7 14400.6 14448.7 14494.9 14536.3 12931.5 13084.7 13412.5 13662.2 13986.5 13174.5 13197.3 13476.1 13757.3 13813.3 10714.4 12997.5 13055.5 13173.9 13675.8 8393.9 10532.1 10867.1 11293.3 11372.6 7691.3 9183.4 10270.1 10562.2 10613.9 03:21:59 INFO - PROCESS | 2684 | -------- Summary: end -------- 03:21:59 INFO - PROCESS | 2684 | 03:22:00 INFO - TEST-INFO | 2684: exit 0 03:22:00 INFO - TEST-OK | dromaeo_css | took 1116255ms 03:22:00 INFO - TEST-START | kraken 03:22:00 INFO - operating with platform_type : mac_ 03:22:00 INFO - Initialising browser for kraken test... 03:22:00 INFO - TEST-INFO | started process 2688 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpcW9QAU/profile http://localhost:62408/getInfo.html) 03:22:03 INFO - PROCESS | 2688 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 03:22:03 INFO - PROCESS | 2688 | 2017-02-15 03:22:03.704 plugin-container[2690:40242] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 03:22:03 INFO - PROCESS | 2688 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:22:03 INFO - PROCESS | 2688 | 2017-02-15 03:22:03.705 plugin-container[2690:40242] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 03:22:03 INFO - PROCESS | 2688 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:22:03 INFO - PROCESS | 2688 | 2017-02-15 03:22:03.705 plugin-container[2690:40242] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:22:03 INFO - PROCESS | 2688 | __metrics Screen width/height:1600/1200 03:22:03 INFO - PROCESS | 2688 | colorDepth:24 03:22:03 INFO - PROCESS | 2688 | Browser inner width/height: 1024/689 03:22:03 INFO - PROCESS | 2688 | __metrics 03:22:03 ERROR - PROCESS | 2688 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 03:22:04 INFO - TEST-INFO | 2688: exit 0 03:22:04 INFO - Browser initialized. 03:22:04 INFO - Running cycle 1/1 for kraken test... 03:22:04 INFO - TEST-INFO | started process 2691 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpcW9QAU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:22:07 INFO - PROCESS | 2691 | 2017-02-15 03:22:07.177 plugin-container[2692:40357] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 03:22:07 INFO - PROCESS | 2691 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:22:07 INFO - PROCESS | 2691 | 2017-02-15 03:22:07.177 plugin-container[2692:40357] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d1b, name = 'com.apple.CFPasteboardClient' 03:22:07 INFO - PROCESS | 2691 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:22:07 INFO - PROCESS | 2691 | 2017-02-15 03:22:07.177 plugin-container[2692:40357] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:22:16 INFO - PROCESS | 2691 | RSS: Main: 233877504 03:22:16 INFO - PROCESS | 2691 | 03:22:20 INFO - PROCESS | 2691 | Cycle 1(1): loaded http://localhost:62408/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:62408/tests/kraken/driver.html?testName=audio-beat-detection) 03:22:21 INFO - PROCESS | 2691 | RSS: Main: 247595008 03:22:21 INFO - PROCESS | 2691 | 03:22:23 INFO - PROCESS | 2691 | Cycle 1(1): loaded http://localhost:62408/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:62408/tests/kraken/driver.html?testName=audio-dft) 03:22:24 INFO - PROCESS | 2691 | RSS: Main: 255328256 03:22:24 INFO - PROCESS | 2691 | 03:22:27 INFO - PROCESS | 2691 | Cycle 1(1): loaded http://localhost:62408/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:62408/tests/kraken/driver.html?testName=audio-fft) 03:22:28 INFO - PROCESS | 2691 | RSS: Main: 258134016 03:22:28 INFO - PROCESS | 2691 | 03:22:30 INFO - PROCESS | 2691 | Cycle 1(1): loaded http://localhost:62408/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:62408/tests/kraken/driver.html?testName=audio-oscillator) 03:22:30 INFO - PROCESS | 2691 | RSS: Main: 259203072 03:22:30 INFO - PROCESS | 2691 | 03:22:32 INFO - PROCESS | 2691 | Cycle 1(1): loaded http://localhost:62408/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:62408/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:22:33 INFO - PROCESS | 2691 | RSS: Main: 259493888 03:22:33 INFO - PROCESS | 2691 | 03:22:36 INFO - PROCESS | 2691 | Cycle 1(1): loaded http://localhost:62408/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:62408/tests/kraken/driver.html?testName=imaging-darkroom) 03:22:37 INFO - PROCESS | 2691 | RSS: Main: 265621504 03:22:37 INFO - PROCESS | 2691 | 03:22:40 INFO - PROCESS | 2691 | Cycle 1(1): loaded http://localhost:62408/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:62408/tests/kraken/driver.html?testName=imaging-desaturate) 03:22:41 INFO - PROCESS | 2691 | RSS: Main: 266084352 03:22:41 INFO - PROCESS | 2691 | 03:22:44 INFO - PROCESS | 2691 | Cycle 1(1): loaded http://localhost:62408/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:62408/tests/kraken/driver.html?testName=json-parse-financial) 03:22:44 INFO - PROCESS | 2691 | RSS: Main: 265797632 03:22:44 INFO - PROCESS | 2691 | 03:22:46 INFO - PROCESS | 2691 | Cycle 1(1): loaded http://localhost:62408/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:62408/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:22:47 INFO - PROCESS | 2691 | RSS: Main: 265383936 03:22:47 INFO - PROCESS | 2691 | 03:22:49 INFO - PROCESS | 2691 | Cycle 1(1): loaded http://localhost:62408/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:62408/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:22:49 INFO - PROCESS | 2691 | RSS: Main: 265469952 03:22:49 INFO - PROCESS | 2691 | 03:22:51 INFO - PROCESS | 2691 | Cycle 1(1): loaded http://localhost:62408/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:62408/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:22:52 INFO - PROCESS | 2691 | RSS: Main: 265490432 03:22:52 INFO - PROCESS | 2691 | 03:22:55 INFO - PROCESS | 2691 | Cycle 1(1): loaded http://localhost:62408/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:62408/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:22:56 INFO - PROCESS | 2691 | RSS: Main: 265834496 03:22:56 INFO - PROCESS | 2691 | 03:22:59 INFO - PROCESS | 2691 | Cycle 1(1): loaded http://localhost:62408/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:62408/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:23:00 INFO - PROCESS | 2691 | RSS: Main: 265641984 03:23:00 INFO - PROCESS | 2691 | 03:23:01 INFO - PROCESS | 2691 | Cycle 1(1): loaded http://localhost:62408/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:62408/tests/kraken/driver.html?testName=ai-astar) 03:23:02 INFO - PROCESS | 2691 | RSS: Main: 267730944 03:23:02 INFO - PROCESS | 2691 | 03:23:02 INFO - PROCESS | 2691 | __start_tp_report 03:23:02 INFO - PROCESS | 2691 | _x_x_mozilla_page_load 03:23:02 INFO - PROCESS | 2691 | _x_x_mozilla_page_load_details 03:23:02 INFO - PROCESS | 2691 | |i|pagename|runs| 03:23:02 INFO - PROCESS | 2691 | |0;ai-astar;127;174;149;116;116;120;116;117;117;116 03:23:02 INFO - PROCESS | 2691 | |1;audio-beat-detection;151;116;116;153;114;117;114;122;172;123 03:23:02 INFO - PROCESS | 2691 | |2;audio-dft;212;210;203;231;192;184;180;259;213;193 03:23:02 INFO - PROCESS | 2691 | |3;audio-fft;88;51;59;108;51;72;62;74;69;52 03:23:02 INFO - PROCESS | 2691 | |4;audio-oscillator;89;75;109;57;71;63;100;68;61;80 03:23:02 INFO - PROCESS | 2691 | |5;imaging-gaussian-blur;89;95;117;101;136;130;91;89;90;95 03:23:02 INFO - PROCESS | 2691 | |6;imaging-darkroom;96;106;148;152;97;121;153;96;99;96 03:23:02 INFO - PROCESS | 2691 | |7;imaging-desaturate;145;146;92;96;101;127;94;93;93;134 03:23:02 INFO - PROCESS | 2691 | |8;json-parse-financial;64;64;65;64;66;64;62;64;64;67 03:23:02 INFO - PROCESS | 2691 | |9;json-stringify-tinderbox;70;71;80;79;85;76;70;69;83;106 03:23:02 INFO - PROCESS | 2691 | |10;stanford-crypto-aes;91;89;91;90;90;90;87;97;108;125 03:23:02 INFO - PROCESS | 2691 | |11;stanford-crypto-ccm;144;145;144;145;145;150;146;151;191;171 03:23:02 INFO - PROCESS | 2691 | |12;stanford-crypto-pbkdf2;177;205;240;192;180;177;179;176;179;180 03:23:02 INFO - PROCESS | 2691 | |13;stanford-crypto-sha256-iterative;71;71;69;70;70;68;66;67;69;67 03:23:02 INFO - PROCESS | 2691 | __end_tp_report 03:23:02 INFO - PROCESS | 2691 | __start_cc_report 03:23:02 INFO - PROCESS | 2691 | _x_x_mozilla_cycle_collect,990 03:23:02 INFO - PROCESS | 2691 | __end_cc_report 03:23:02 INFO - PROCESS | 2691 | __startTimestamp1487157782082__endTimestamp 03:23:02 INFO - PROCESS | 2691 | 03:23:02 INFO - PROCESS | 2691 | ------- Summary: start ------- 03:23:02 INFO - PROCESS | 2691 | Number of tests: 14 03:23:02 INFO - PROCESS | 2691 | 03:23:02 INFO - PROCESS | 2691 | [#0] ai-astar Cycles:10 Average:126.80 Median:118.50 stddev:19.51 (16.5%) stddev-sans-first:20.69 03:23:02 INFO - PROCESS | 2691 | Values: 127.0 174.0 149.0 116.0 116.0 120.0 116.0 117.0 117.0 116.0 03:23:02 INFO - PROCESS | 2691 | 03:23:02 INFO - PROCESS | 2691 | [#1] audio-beat-detection Cycles:10 Average:129.80 Median:122.50 stddev:20.87 (17.0%) stddev-sans-first:20.68 03:23:02 INFO - PROCESS | 2691 | Values: 151.0 116.0 116.0 153.0 114.0 117.0 114.0 122.0 172.0 123.0 03:23:02 INFO - PROCESS | 2691 | 03:23:02 INFO - PROCESS | 2691 | [#2] audio-dft Cycles:10 Average:207.70 Median:211.00 stddev:23.66 (11.2%) stddev-sans-first:25.05 03:23:02 INFO - PROCESS | 2691 | Values: 212.0 210.0 203.0 231.0 192.0 184.0 180.0 259.0 213.0 193.0 03:23:02 INFO - PROCESS | 2691 | 03:23:02 INFO - PROCESS | 2691 | [#3] audio-fft Cycles:10 Average:68.60 Median:65.50 stddev:18.26 (27.9%) stddev-sans-first:17.97 03:23:02 INFO - PROCESS | 2691 | Values: 88.0 51.0 59.0 108.0 51.0 72.0 62.0 74.0 69.0 52.0 03:23:02 INFO - PROCESS | 2691 | 03:23:02 INFO - PROCESS | 2691 | [#4] audio-oscillator Cycles:10 Average:77.30 Median:69.50 stddev:17.25 (24.8%) stddev-sans-first:17.77 03:23:02 INFO - PROCESS | 2691 | Values: 89.0 75.0 109.0 57.0 71.0 63.0 100.0 68.0 61.0 80.0 03:23:02 INFO - PROCESS | 2691 | 03:23:02 INFO - PROCESS | 2691 | [#5] imaging-gaussian-blur Cycles:10 Average:103.30 Median:89.50 stddev:17.80 (19.9%) stddev-sans-first:18.11 03:23:02 INFO - PROCESS | 2691 | Values: 89.0 95.0 117.0 101.0 136.0 130.0 91.0 89.0 90.0 95.0 03:23:02 INFO - PROCESS | 2691 | 03:23:02 INFO - PROCESS | 2691 | [#6] imaging-darkroom Cycles:10 Average:116.40 Median:96.00 stddev:25.08 (26.1%) stddev-sans-first:25.50 03:23:02 INFO - PROCESS | 2691 | Values: 96.0 106.0 148.0 152.0 97.0 121.0 153.0 96.0 99.0 96.0 03:23:02 INFO - PROCESS | 2691 | 03:23:02 INFO - PROCESS | 2691 | [#7] imaging-desaturate Cycles:10 Average:112.10 Median:92.50 stddev:23.04 (24.9%) stddev-sans-first:21.14 03:23:02 INFO - PROCESS | 2691 | Values: 145.0 146.0 92.0 96.0 101.0 127.0 94.0 93.0 93.0 134.0 03:23:02 INFO - PROCESS | 2691 | 03:23:02 INFO - PROCESS | 2691 | [#8] json-parse-financial Cycles:10 Average:64.40 Median:64.00 stddev:1.35 (2.1%) stddev-sans-first:1.42 03:23:02 INFO - PROCESS | 2691 | Values: 64.0 64.0 65.0 64.0 66.0 64.0 62.0 64.0 64.0 67.0 03:23:02 INFO - PROCESS | 2691 | 03:23:02 INFO - PROCESS | 2691 | [#9] json-stringify-tinderbox Cycles:10 Average:78.90 Median:77.50 stddev:11.14 (14.4%) stddev-sans-first:11.34 03:23:02 INFO - PROCESS | 2691 | Values: 70.0 71.0 80.0 79.0 85.0 76.0 70.0 69.0 83.0 106.0 03:23:02 INFO - PROCESS | 2691 | 03:23:02 INFO - PROCESS | 2691 | [#10] stanford-crypto-aes Cycles:10 Average:95.80 Median:90.00 stddev:11.90 (13.2%) stddev-sans-first:12.49 03:23:02 INFO - PROCESS | 2691 | Values: 91.0 89.0 91.0 90.0 90.0 90.0 87.0 97.0 108.0 125.0 03:23:02 INFO - PROCESS | 2691 | 03:23:02 INFO - PROCESS | 2691 | [#11] stanford-crypto-ccm Cycles:10 Average:153.20 Median:148.00 stddev:15.58 (10.5%) stddev-sans-first:16.16 03:23:02 INFO - PROCESS | 2691 | Values: 144.0 145.0 144.0 145.0 145.0 150.0 146.0 151.0 191.0 171.0 03:23:02 INFO - PROCESS | 2691 | 03:23:02 INFO - PROCESS | 2691 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.50 Median:180.00 stddev:20.23 (11.2%) stddev-sans-first:21.02 03:23:02 INFO - PROCESS | 2691 | Values: 177.0 205.0 240.0 192.0 180.0 177.0 179.0 176.0 179.0 180.0 03:23:02 INFO - PROCESS | 2691 | 03:23:02 INFO - PROCESS | 2691 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:69.50 stddev:1.75 (2.5%) stddev-sans-first:1.67 03:23:02 INFO - PROCESS | 2691 | Values: 71.0 71.0 69.0 70.0 70.0 68.0 66.0 67.0 69.0 67.0 03:23:02 INFO - PROCESS | 2691 | -------- Summary: end -------- 03:23:02 INFO - PROCESS | 2691 | 03:23:02 INFO - TEST-INFO | 2691: exit 0 03:23:03 INFO - TEST-OK | kraken | took 62393ms 03:23:03 INFO - SUITE-END | took 1178s 03:23:03 INFO - Completed test suite (00:19:38) 03:23:03 INFO - javascript benchmark 03:23:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7058.272842313323, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2809.5785632155175, 3070.554769558414, 3308.305255896262, 3320.8339583020843, 3324.119108436264, 4962.826301079463, 5447.782088716446, 5567.526760225381, 5594.160875868617, 5634.859128521789, 5460.808871689492, 5497.47773961474, 5507.284053073095, 5538.584606154534, 5544.140658197975, 4985.975070124644, 5000.699958002531, 5112.207607820775, 5134.460881607433, 5134.460881607433, 5082.2122571001555, 5137.229415587654, 5159.948400516003, 5185.403678576974, 5186.3257776489, 5135.152699804527, 5149.948500515003, 5156.664816786897, 5157.071727089123, 5163.199704045879, 5128.8461346159675, 5136.717480538568, 5146.3824341078935, 5149.0731668299695, 5153.020926024062, 3911.7457365271166, 4459.617518569254, 5002.0, 5106.183010718267, 5122.513361230677, 5630.943690563083, 5703.429657034305, 5708.115242137434, 5732.140178973145, 5784.855378615568, 5929.199558059693, 6069.939300606981, 6140.171076904606, 6163.383661633846, 6182.412670796267, 6049.092636104576, 6074.817755467343, 6101.641069425254, 6155.169052177944, 6203.937960620381, 5797.652140871562, 5798.565107616903, 5811.128330750336, 5823.068309070529, 5833.970830145865, 6150.692465376803, 6206.975848984957, 6226.782062627786, 6248.375162483806, 6264.373562643699, 5770.451807078321, 5812.534997200214, 5996.580239383201, 6097.085437184458, 6106.603070800473, 6084.939150608438, 6172.321044685081, 6188.2883468400805, 6218.31598524162, 6220.595661282093, 6118.1434599156155, 6149.016157414792, 6165.106059621419, 6176.8764624707255, 6184.103305020747, 5839.532837373, 5938.138969849348, 6181.35095814933, 6183.628982261079, 6210.689465526705, 4892.119418504703, 4898.02039592083, 4916.0905232532095, 4926.507349265116, 4997.475265097112, 4365.934510982338, 4388.495323037828, 4404.7357158570585, 4413.3821265022925, 4428.2914733642465, 5555.999920014356, 5846.064629659234, 5864.560157988168, 5867.735951882176, 5892.499137573354, 5042.394912610437, 6098.664573448503, 6132.967444181115, 6162.568620196544, 6226.782062627877, 5285.2600635911, 5375.247465354853, 5772.538196944234, 5813.215215945877, 5822.941770582324, 5251.133562962144, 5305.65513241638, 5353.598480114007, 5377.193420986883, 5387.488188622075, 6247.312795592481, 6264.968675156686, 6266.68666566679, 6280.277768056732, 6283.434490895841, 5170.896582068262, 6178.907316390168, 6196.535259855439, 6199.039148931923, 6224.439800417984, 6130.887362651087, 6177.907331390123, 6200.658963756856, 6223.627119149658, 6275.52933529978, 6106.236720409949, 6155.353687862889, 6156.415140561639, 6158.538109641705, 6198.2252218472695, 6213.844653883689, 6217.96891015542, 6223.844403889939, 6233.314335423191, 6239.0, 5758.3090029196765, 5894.852628684145, 6072.969635151796, 6082.11809287642, 6100.969495152496, 5872.794452194153, 6009.158717779427, 6073.058675905242, 6085.391460853879, 6099.0, 4673.369095172108, 5670.206171136127, 5825.737841797045, 5927.644341339361, 5933.584649074696, 4959.62802789785, 6058.4850287724275, 6084.239470066242, 6088.847778805565, 6103.908441373472, 5542.445755424425, 5838.4453476919625, 5852.561057920588, 5886.646801191942, 5977.760889564543], "value": 5586.449632117736, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [437.4553680667569, 455.6491501543811, 495.4178839863161, 506.3898002906498, 511.3965520685594, 29468.85265573672, 36114.27771444569, 38743.41884871726, 41329.7933510332, 41423.58576414235, 30530.69469305306, 33158.834205829, 40269.19461610766, 40378.596214037854, 40545.78362649118, 7848.960755196216, 9395.0, 9442.905570944306, 9447.858282125773, 10549.314294570828, 8943.418677785956, 10844.349339039683, 10844.566217351297, 10854.782904341908, 10875.0, 10873.401962892038, 10893.673189804318, 10900.018998290152, 10911.83632245513, 10915.290506117117, 10794.676159715222, 10867.728306792314, 10871.510782014828, 10881.455927203608, 10882.40146791926, 10676.306040107369, 10738.946305268424, 10753.569857205703, 10755.731106722316, 10773.976472235125, 34203.144921376916, 34313.48529772055, 34527.82736086303, 34537.654623453695, 34558.48162277568, 3037.9088627341216, 3246.513023046562, 3269.1336795749144, 3274.4106060909025, 3327.351166522529, 25966.221013369628, 26779.598306025426, 26888.59667104995, 27003.594946075824, 27026.729732702617, 3070.355225402668, 3143.4341818472667, 3179.0780673604627, 3211.229304966791, 3217.549543063973, 416369.0, 421007.0, 421730.0, 423125.0, 425352.87323563494, 45579.77210113928, 46628.3005754914, 52743.47256527462, 53027.28808983334, 53046.0, 67690.0, 68583.65708171428, 68664.28339291557, 68909.62180756357, 69130.30869691238, 14748.63128421798, 14949.402023918921, 15338.233088345762, 15339.309731062129, 15379.38482460689, 10043.748906277255, 11515.942420287845, 11559.711007224887, 11742.0, 11757.0, 19027.044168508324, 19484.083970604388, 19520.578653196517, 19603.21587136527, 19663.542057242845, 6908.585484870924, 7459.36595389398, 7855.450118491765, 7917.485363451357, 8092.473989190801, 660.2506155513527, 749.8387846613005, 755.524019867491, 761.3300295739714, 763.2634507700096, 27698.0, 28069.859650701615, 28963.986260480782, 29129.27176820554, 29737.553936690965, 1011.3577878047493, 1020.9846852297221, 1034.1726618705156, 1035.3322107240788, 1169.1757311095657, 14737.11577305365, 15069.47256846027, 15268.480700684262, 15357.462488812838, 15437.845621543865, 534110.0, 537027.0, 540254.0, 541137.0, 541538.0, 55507.444925550226, 55689.721551392795, 55854.0, 55859.44140558542, 56100.43899561034, 1296.0797833538293, 1339.377189606818, 1342.02703040295, 1342.9261390623415, 1350.2910971739916, 27601.0, 28279.192537269126, 28884.27789305284, 28963.565546516398, 29148.7085129146, 35480.82259588686, 46552.06895861996, 48536.51463485461, 48618.75690621524, 49036.26445603248, 333030.0, 345616.0, 387007.0, 413070.0, 414086.0, 30092.24769380783, 39515.209695805344, 40382.79808600938, 40429.19141617092, 40502.0, 43299.0, 43356.0, 43832.12335753203, 44547.77726111478, 44696.0, 29626.703732962393, 34174.65825341715, 36337.818310908275, 37463.62536374601, 37467.0, 35877.92366229017, 36424.90725278246, 36820.63179368172, 37044.81477592703, 37948.24103517969, 33126.0, 33254.50118248277, 33370.33259334848, 34952.082702625026, 35300.82349588236, 27537.449251015267, 28934.565981509873, 30616.0, 30671.846640766653, 30967.225819353796, 23008.309750707507, 23196.536069278183, 23342.299731008097, 23570.646440303753, 23627.527449450572], "value": 18954.921561617623, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.1858491836643, 383.24500733554885, 392.8193031205644, 402.61147992187534, 415.8252935457332, 17661.911690441546, 19513.51216219595, 20112.59774804505, 20390.592188156228, 20397.99999999998, 19672.721273117237, 19961.401157965247, 20007.799922000777, 20364.0, 20469.48826279344, 5567.164925261223, 5587.99999999999, 5696.120280307509, 5768.509676677488, 5863.208466856962, 5659.717014149276, 5777.971110144465, 5786.392428794979, 5790.68151251681, 5791.07342825148, 5521.917171242435, 5616.859578510529, 5696.405006598138, 5698.430156984289, 5730.0, 5605.943940560583, 5622.3253209614695, 5658.12299093637, 5671.716414179275, 5679.460451257145, 4258.446401967724, 4955.504449555052, 4990.2764099205415, 5003.924941125886, 5012.398512178562, 23581.0, 23862.494438083926, 23928.880355598285, 23955.88022059896, 24084.759152408602, 2678.3132409983277, 2693.8114331996767, 2703.972960270392, 2712.7016028236876, 2737.288305040684, 11135.777284454265, 14412.711745765131, 14416.783748243784, 15103.622409439851, 15161.393544258217, 1580.517942027681, 1607.7700559072318, 1701.948941531756, 1765.479183640829, 1812.103009010535, 78805.60597197035, 104779.47610261977, 105184.0, 105576.47211763969, 106446.0, 9966.551505182284, 11330.716732081599, 11506.827397589042, 11813.291202527877, 11946.0, 15139.394424222908, 15170.924145379202, 15228.619284517754, 15264.313105910262, 15265.542033739006, 7966.442349035628, 8198.221168988937, 8213.671453141807, 8219.589020548949, 8308.916910830936, 6495.512836537278, 7791.844163116706, 7798.1811909749795, 7943.563104029334, 7944.721934732257, 3988.461557689675, 4520.751358675238, 4557.95442045582, 4628.514006029319, 4767.165745994506, 1688.6622675464955, 1711.7432385142329, 1985.463924740312, 2008.4577164165594, 2029.482481967089, 918.6968300460832, 933.0296491648745, 954.5275088830934, 972.6644307713827, 975.770693886936, 18917.243310267437, 20145.597088058155, 20161.0, 20380.0, 20488.89755551213, 926.3098991251426, 941.684535680541, 943.9292053095908, 945.8770359853175, 953.0421925964362, 8967.955160224157, 10257.692269231935, 10566.101881340172, 10599.679549186205, 10626.946865265625, 651201.0, 657656.0, 662527.0, 666262.0, 681467.0, 107015.46492267644, 120440.0, 143388.0, 144085.27957360356, 144703.5529644711, 847.9236868681725, 953.7234202081319, 962.485070487286, 983.095552092077, 986.2455221755363, 18165.455036348932, 18870.33953811665, 18879.43361699151, 18889.90555047216, 18961.810381896004, 43671.0, 47645.80885477752, 48088.519114808405, 48294.51705483041, 53122.0, 70561.0, 74502.62748686221, 75175.62412187904, 75273.0, 77317.4536509278, 6396.488280937607, 7158.570485770871, 7874.0, 8028.197180281925, 8075.192480751878, 11752.764944700886, 11926.940365298118, 11976.107417235504, 12086.939565302117, 12232.388380581113, 10268.127209187303, 10270.640527581798, 10583.31208471447, 10728.624498142528, 10797.892021079688, 10193.847092293474, 10241.897581024094, 10244.743881403025, 10489.265751397328, 10643.254972151875, 10266.897331026892, 10572.101371383518, 10746.516406761559, 10860.67417977462, 10879.836802447811, 7891.367142134, 7962.6814927404575, 8087.150849160988, 8156.265936065783, 8259.132791057093, 8030.638621262174, 10421.791564168825, 10460.110890574386, 10817.134629229486, 10926.890731092906], "value": 9368.151739972367, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1382.84788673246, 1384.8753612174903, 1403.0529392659955, 1405.6345350208942, 1406.6905280838218, 8119.87820182697, 8259.463134896228, 8524.06235314115, 8581.914180858197, 8592.0, 8358.0, 8497.787555311104, 8516.31869450444, 8516.61675224615, 8540.786480337994, 1971.3297478857185, 2205.6691496275525, 2215.7784221577876, 2269.0, 2270.5231901300745, 1984.7496077471174, 2240.3838944290287, 2279.0314116500504, 2283.942901427461, 2286.211257116292, 2180.8800515971616, 2230.063373383183, 2261.4459457432954, 2263.9660405093937, 2265.286434773041, 2169.3275084723628, 2227.8774667393286, 2250.0, 2258.141906075689, 2258.6950761647213, 1904.7047707605343, 1955.3938279133515, 2026.3313106674761, 2231.274835678395, 2291.842619477153, 8309.210624990677, 8489.108643592395, 8511.361647876372, 8517.957410212972, 8595.44129631572, 4692.343071969927, 4894.7063176209185, 5021.799128034874, 5170.250313704491, 5186.5850731941355, 3351.8324083795956, 3440.3119376124623, 3497.930041399183, 3507.57909050913, 3531.7351198659944, 1026.845973104033, 1161.5005547614523, 1190.3631557116987, 1195.0439648281426, 1219.7072702551413, 8917.0, 10842.566297348176, 10992.780144397067, 10995.670129896196, 11026.283291586022, 4166.979165104155, 4401.361802538614, 4462.509123996358, 4468.151051300242, 4481.484629267611, 8763.693270735494, 8819.162179592928, 8827.073157318517, 8830.293576513863, 8852.822943541094, 5061.645684802103, 5066.037452884014, 5069.949300507022, 5079.161938280216, 5107.948920510822, 4503.549645035536, 4633.9536604634195, 4653.092646933816, 4673.119880863738, 4711.811527538928, 3604.693601043888, 3614.0784100054843, 3686.1337585667347, 3716.2567486502267, 3743.644353786386, 1088.77135801481, 1097.7091070866227, 1099.8680158381046, 1108.933463992163, 1109.9667009989714, 798.4650284309527, 865.6321063547967, 867.2021739999216, 882.7087061269766, 912.7763697894059, 5538.944610553924, 5603.607747457639, 5638.887222255532, 5783.334916484574, 6025.975584150705, 910.8041771019131, 946.8485042393185, 954.8042651256476, 968.8982656821073, 991.591939445974, 3398.3033478137418, 3567.2532150244165, 3620.87326943561, 3894.247588585148, 3914.8042597869994, 8373.748787536384, 9067.002629710727, 9792.755181120529, 10131.898681013095, 10154.136898363722, 10083.0, 10085.596576137019, 10115.494225288707, 10117.544710488046, 10139.290249682452, 7500.36246919018, 7749.380049596019, 7884.881726774103, 7901.486403383647, 7910.723124690723, 5009.5491405773655, 5231.346081739783, 5455.890882182255, 5496.31296087989, 5614.2701448811395, 9050.230730387728, 9138.63445462174, 9161.954190229006, 9204.21764150028, 9260.30547708911, 8107.4324797263625, 9107.225885799517, 9185.49479778592, 9199.448033118035, 9421.576029078571, 6618.271990080991, 6809.761658342132, 6855.725770969106, 6861.210960739579, 6868.725250989904, 7684.731034413769, 7839.960800195962, 7926.0, 7943.44395892282, 7966.322862556747, 7112.253213412724, 7159.427245820426, 7187.496875218893, 7221.169565499825, 7270.309320614533, 5651.954388437987, 6873.896891546731, 6975.9302406975285, 7025.964870175821, 7046.647667616701, 6303.621782693053, 6406.807795766134, 6435.646039467874, 6442.742290308335, 6467.223933128054, 4894.828680996148, 4920.778564964514, 5004.349434573628, 5014.874628134326, 5130.230465430215, 4298.591633794785, 4939.308496810378, 5094.617903657167, 5105.795768169231, 5171.81898633546], "value": 4324.552279493118, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.5580954092893, 346.8317865835071, 360.9783412995222, 363.106757376853, 368.296553582657, 4731.243001119821, 4745.501722319159, 4864.854054378375, 5294.57643388528, 5312.415634280231, 5087.872803179922, 5375.623706340558, 5405.5135037846685, 5418.647787893784, 5460.890782184354, 4273.93151712072, 4296.785160741958, 4298.441202643652, 4309.396684464177, 4335.978320108395, 3577.406380374234, 3649.5620525536974, 4131.9463536798075, 4141.523724771645, 4282.779407868758, 3184.251700850298, 3729.8064619321826, 3979.4030895365636, 4106.507219133693, 4176.143890502455, 3628.600853906068, 4073.002114481941, 4135.828689937776, 4229.3355186232475, 4233.814531931049, 3194.808311501294, 3297.4831577474392, 3306.826076742746, 3473.776782858972, 3742.176721121349, 4209.473815773028, 5063.0, 5151.716655583941, 5173.663711858717, 5191.351081114861, 4884.438289596671, 4885.218365061573, 4887.828925987609, 4889.413270407538, 4890.462049174588, 4858.246971719389, 5278.736063196863, 5732.3407808101765, 5755.453231942959, 5769.1923130761725, 3295.6045274567205, 3831.3949473725625, 4073.9185216295805, 4156.8129434175235, 4189.224993376235, 5846.070796637099, 5937.67342796146, 5983.551233657449, 6119.418655227746, 6120.663363515005, 3384.1539615096226, 3404.5914490261325, 3906.609339066071, 4191.371294305817, 4213.904384859959, 3790.5830358660523, 3824.6175382461533, 4279.657627389802, 4286.442762440863, 4298.677599180074, 4681.50844161365, 4914.525642307293, 4926.901461970812, 4969.751512424365, 4970.776315065831, 4943.90112197754, 5053.772580233898, 5676.545876329884, 5753.827385178452, 5778.104393818965, 1255.3911352993791, 1351.148776270943, 1380.7652699041187, 1383.840858301288, 1387.7155183187422, 865.5455885660078, 877.6270085213758, 942.1909932928793, 976.9546585153959, 982.5360213282158, 4513.909721805611, 4565.995480994243, 4928.841722195281, 4966.255061740768, 5132.023426412672, 5141.074606570854, 5362.329708786402, 5468.945310546923, 5699.544036477155, 5708.942910570924, 4554.908901822011, 4654.4414670239785, 4859.902801943941, 5093.363329583802, 5096.541311282002, 2439.6706444630104, 2678.1296078774476, 2784.2482529716863, 2924.9707502925125, 2933.9853300733785, 5039.807683657621, 5225.5819534437915, 5707.944030354398, 5866.354700982888, 5981.880362392728, 4829.879253018632, 5277.366715994105, 5346.8663283417445, 5530.326469913026, 5936.22829032223, 5818.650880947157, 5868.94131058684, 5907.881842363128, 5917.993941030035, 5940.643561386244, 4719.7492664444635, 5133.054301682609, 5576.86057848557, 5635.6618602883955, 5677.829665110054, 4958.652894297509, 5052.924206136838, 5121.257417674342, 5551.722413879209, 5668.801591944262, 5018.874528136826, 5044.8234311798915, 5851.8244452664785, 5907.17299578068, 5918.35409203773, 3968.7420317679257, 4213.441026820096, 4228.906691187882, 4254.255505286625, 4260.957390426056, 5408.8377348679605, 5487.9298536784945, 5930.644161350333, 5938.762449502145, 5966.19456373384, 4326.826926923014, 4651.790669419952, 5674.319081710221, 5690.089585666274, 5759.539236861125, 5293.0, 5691.861627674399, 5749.425057494217, 5753.223314852608, 5808.30300363959, 5311.707856067799, 5383.734822316674, 5572.60991730575, 5615.410381909841, 5646.943530564642, 4725.621950244041, 5694.747155625756, 5709.571782116275, 5709.857253568694, 5781.537477001913, 4683.765811709332, 5241.741981924387, 5652.971735141463, 5655.406182350958, 5660.830175094754], "value": 4135.643265551597, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [684.7603338831407, 690.7168061094952, 693.732912828561, 771.2326235395782, 781.1485480825899, 12981.75456135966, 13153.868461315384, 13615.183089014654, 13644.590662280123, 13663.521776737816, 12453.750924981496, 12648.430820613072, 12654.746905061893, 12670.113092083559, 12714.936425317861, 812.5287333346645, 839.1314988986401, 856.1609622569886, 856.6701819799391, 858.4205661178711, 820.9179082091773, 843.6541018182546, 848.7326492154982, 858.0389963241147, 860.096898256831, 898.7483504618717, 902.4765635931177, 906.0893801729253, 906.4153620914507, 907.2061945797427, 883.3816328569994, 895.1496078725237, 897.1835629577087, 899.9875140466975, 901.7610333261658, 654.571255827429, 661.4146480364864, 662.3343539742527, 662.5792621685216, 663.7842701122155, 13145.60563183106, 13450.260235687034, 13450.529231476852, 13463.86536134636, 13670.52153174634, 12129.514819407214, 12155.270683758914, 12210.145289829716, 12234.204776689576, 12504.31226282554, 9720.368176068603, 11560.421978901022, 11679.416029198506, 11804.940975295154, 12142.939285303606, 3537.995800687257, 3598.5861625913094, 3720.627937206285, 3766.0020094674933, 3766.1902690921584, 68010.29974250584, 89982.5500872498, 92593.0, 96157.0, 96177.0, 8900.243479304203, 9272.907270927204, 9339.673111441067, 9360.391574547633, 9522.238220942309, 20789.1684332625, 21131.47171320729, 21176.152953881672, 21186.78813211879, 21342.466438339165, 5823.56323275756, 5866.941330586725, 6627.768028118992, 6735.528513004141, 6903.378695917291, 3264.5919260092487, 3377.6960073593496, 3608.0, 3768.2651882883124, 3795.411711184771, 2962.4371369439737, 3035.1501579557757, 3565.1978304881195, 3599.1721903962234, 3607.2244467439627, 557.2560631556917, 586.7770247305997, 593.2554643921936, 599.4694695194784, 638.1608185236493, 642.9038489375604, 646.4892734739597, 670.909427227318, 676.577139287945, 689.7274528495019, 7529.623518824037, 8413.368997325102, 9545.809083818285, 9764.51177441125, 9979.0, 537.3739593373676, 541.6722882655937, 627.6296984779002, 647.7247169952846, 675.7702381190419, 4293.042037483553, 4874.2444921037295, 4894.461609222983, 5060.2156665716875, 5310.097283461821, 72822.0, 90238.54880725555, 92134.0, 93130.0, 96201.51899240458, 66614.66770664559, 66731.0, 68224.0, 68827.62344753077, 73952.26047739561, 63715.0, 64407.03389449162, 65052.0, 65178.999999999054, 66625.33374666289, 10561.367759250272, 10600.787984240425, 10672.252942293964, 10720.60632117851, 10842.891571084503, 42314.0, 43175.56824431715, 43292.134157316046, 43379.349309759746, 43812.0, 55240.0, 56651.0, 57705.0, 59141.704291478265, 63962.68018659877, 13742.175469471864, 13753.587392378246, 13774.173549587056, 13787.51743688966, 13805.102668326526, 13268.734625307632, 14400.639984000483, 14448.711025779636, 14494.927525362305, 14536.345864435914, 12931.482740690268, 13084.738305234032, 13412.463501459832, 13662.248576328397, 13986.510472133426, 13174.47302107905, 13197.288378195592, 13476.056676032584, 13757.31213439344, 13813.309334533033, 10714.444934291574, 12997.480100795863, 13055.543055992996, 13173.868261317264, 13675.79486307726, 8393.874091888749, 10532.05211530966, 10867.130629549774, 11293.322400655987, 11372.621540706721, 7691.269329413697, 9183.403078799856, 10270.119299365706, 10562.207834412297, 10613.9469302653], "value": 6968.6565021648275, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1591.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 174.0, 149.0, 116.0, 116.0, 120.0, 116.0, 117.0, 117.0, 116.0], "value": 126.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 116.0, 116.0, 153.0, 114.0, 117.0, 114.0, 122.0, 172.0, 123.0], "value": 129.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [212.0, 210.0, 203.0, 231.0, 192.0, 184.0, 180.0, 259.0, 213.0, 193.0], "value": 207.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 51.0, 59.0, 108.0, 51.0, 72.0, 62.0, 74.0, 69.0, 52.0], "value": 68.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 75.0, 109.0, 57.0, 71.0, 63.0, 100.0, 68.0, 61.0, 80.0], "value": 77.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 95.0, 117.0, 101.0, 136.0, 130.0, 91.0, 89.0, 90.0, 95.0], "value": 103.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 106.0, 148.0, 152.0, 97.0, 121.0, 153.0, 96.0, 99.0, 96.0], "value": 116.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 92.0, 96.0, 101.0, 127.0, 94.0, 93.0, 93.0, 134.0], "value": 112.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 64.0, 66.0, 64.0, 62.0, 64.0, 64.0, 67.0], "value": 64.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 80.0, 79.0, 85.0, 76.0, 70.0, 69.0, 83.0, 106.0], "value": 78.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 91.0, 90.0, 90.0, 90.0, 87.0, 97.0, 108.0, 125.0], "value": 95.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 145.0, 144.0, 145.0, 145.0, 150.0, 146.0, 151.0, 191.0, 171.0], "value": 153.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 205.0, 240.0, 192.0, 180.0, 177.0, 179.0, 176.0, 179.0, 180.0], "value": 188.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 69.0, 70.0, 70.0, 68.0, 66.0, 67.0, 69.0, 67.0], "value": 68.8, "unit": "score"}]}]} 03:23:03 INFO - Return code: 0 03:23:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:23:03 ERROR - # TBPL SUCCESS # 03:23:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:23:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:23:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:23:03 INFO - 2017-02-15 03:23:03.402 screenresolution[2696:40732] starting screenresolution argv=screenresolution get 03:23:03 INFO - 2017-02-15 03:23:03.405 screenresolution[2696:40732] Display 0: 1600x1200x32@60 03:23:03 INFO - 2017-02-15 03:23:03.411 screenresolution[2697:40735] starting screenresolution argv=screenresolution list 03:23:03 INFO - Available Modes on Display 0 03:23:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:23:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:23:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:23:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:23:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:23:03 INFO - Intel Iris: 03:23:03 INFO - Chipset Model: Intel Iris 03:23:03 INFO - Type: GPU 03:23:03 INFO - Bus: Built-In 03:23:03 INFO - VRAM (Dynamic, Max): 1536 MB 03:23:03 INFO - Vendor: Intel (0x8086) 03:23:03 INFO - Device ID: 0x0a2e 03:23:03 INFO - Revision ID: 0x0009 03:23:03 INFO - Displays: 03:23:03 INFO - Display: 03:23:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:23:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:23:03 INFO - Display Serial Number: 1600x1200 60 03:23:03 INFO - Main Display: Yes 03:23:03 INFO - Mirror: Off 03:23:03 INFO - Online: Yes 03:23:03 INFO - Rotation: Supported 03:23:03 INFO - Return code: 0 03:23:03 INFO - Running post-action listener: _resource_record_post_action 03:23:03 INFO - [mozharness: 2017-02-15 11:23:03.647759Z] Finished run-tests step (success) 03:23:03 INFO - Running post-run listener: _resource_record_post_run 03:23:03 INFO - Total resource usage - Wall time: 1201s; CPU: 55.0%; Read bytes: 1433600; Write bytes: 554118656; Read time: 13; Write time: 1926 03:23:03 INFO - TinderboxPrint: CPU usage
54.6% 03:23:03 INFO - TinderboxPrint: I/O read bytes / time
1,433,600 / 13 03:23:03 INFO - TinderboxPrint: I/O write bytes / time
554,118,656 / 1,926 03:23:03 INFO - TinderboxPrint: CPU idle
2,183.6 (45.4%) 03:23:03 INFO - TinderboxPrint: CPU system
971.7 (20.2%) 03:23:03 INFO - TinderboxPrint: CPU user
1,650.5 (34.3%) 03:23:03 INFO - TinderboxPrint: Swap in / out
252,989,440 / 0 03:23:03 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 246344192; Write bytes: 240412160; Read time: 17800; Write time: 441 03:23:03 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 1425408; Write bytes: 298285056; Read time: 13; Write time: 1446 03:23:04 INFO - Running post-run listener: _upload_blobber_files 03:23:04 INFO - Blob upload gear active. 03:23:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:23:04 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 03:23:04 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:23:04 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:23:04 INFO - (blobuploader) - INFO - Open directory for files ... 03:23:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:23:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:23:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:23:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:23:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:23:05 INFO - (blobuploader) - INFO - Done attempting. 03:23:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:23:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:23:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:23:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:23:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:23:06 INFO - (blobuploader) - INFO - Done attempting. 03:23:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:23:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:23:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:23:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:23:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:23:07 INFO - (blobuploader) - INFO - Done attempting. 03:23:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:23:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:23:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:23:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:23:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:23:09 INFO - (blobuploader) - INFO - Done attempting. 03:23:09 INFO - (blobuploader) - INFO - Iteration through files over. 03:23:09 INFO - Return code: 0 03:23:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:23:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:23:09 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6dee597f189452e2aa598c4a22df5e2cd0fa7c3e7c51d8c9b8aa1277734956e5d28d0ed1bb60fc04f69b3e579871a049d9d98bc28565eb0cb0b064c7acf4c6b1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a6078b5eaecfef464ac1494c657c62dbed6c1e6f2b99d144e1e609e75c7d7ff6c36a144e81f044ef089e1243cd3bbde1e4dee9fe7ffac589e05936ca0543939", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1002b84a369ae17612ed4ef4fe31983b18ecfa3bfeccc4527dd77ac3d3ddc1a3ea828143a0f9d8d09887563e824076c5aedcf02be8724c4c4a740ede1944b802", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/376541ace7224e9be0abfa6c3eefa25f921306db906f37cf7a1719f5eb3fad0fcccf74d03df88acb8bd45139a164ffd91853ff29a4b515681cb44dae4a86c5a9"} 03:23:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:23:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:23:09 INFO - Contents: 03:23:09 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6dee597f189452e2aa598c4a22df5e2cd0fa7c3e7c51d8c9b8aa1277734956e5d28d0ed1bb60fc04f69b3e579871a049d9d98bc28565eb0cb0b064c7acf4c6b1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a6078b5eaecfef464ac1494c657c62dbed6c1e6f2b99d144e1e609e75c7d7ff6c36a144e81f044ef089e1243cd3bbde1e4dee9fe7ffac589e05936ca0543939", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1002b84a369ae17612ed4ef4fe31983b18ecfa3bfeccc4527dd77ac3d3ddc1a3ea828143a0f9d8d09887563e824076c5aedcf02be8724c4c4a740ede1944b802", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/376541ace7224e9be0abfa6c3eefa25f921306db906f37cf7a1719f5eb3fad0fcccf74d03df88acb8bd45139a164ffd91853ff29a4b515681cb44dae4a86c5a9"} 03:23:09 INFO - Running post-run listener: copy_logs_to_upload_dir 03:23:09 INFO - Copying logs to upload dir... 03:23:09 INFO - mkdir: /builds/slave/test/build/upload/logs 03:23:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.933250 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-15 03:23:09.962890) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-15 03:23:09.966047) ========= 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.NzXRtVlSXV/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.KUtZt9Arzz/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6dee597f189452e2aa598c4a22df5e2cd0fa7c3e7c51d8c9b8aa1277734956e5d28d0ed1bb60fc04f69b3e579871a049d9d98bc28565eb0cb0b064c7acf4c6b1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a6078b5eaecfef464ac1494c657c62dbed6c1e6f2b99d144e1e609e75c7d7ff6c36a144e81f044ef089e1243cd3bbde1e4dee9fe7ffac589e05936ca0543939", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1002b84a369ae17612ed4ef4fe31983b18ecfa3bfeccc4527dd77ac3d3ddc1a3ea828143a0f9d8d09887563e824076c5aedcf02be8724c4c4a740ede1944b802", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/376541ace7224e9be0abfa6c3eefa25f921306db906f37cf7a1719f5eb3fad0fcccf74d03df88acb8bd45139a164ffd91853ff29a4b515681cb44dae4a86c5a9"} build_url:https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010827 build_url: 'https://queue.taskcluster.net/v1/task/fMdwOL15TlCRloETnnxhYA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6dee597f189452e2aa598c4a22df5e2cd0fa7c3e7c51d8c9b8aa1277734956e5d28d0ed1bb60fc04f69b3e579871a049d9d98bc28565eb0cb0b064c7acf4c6b1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a6078b5eaecfef464ac1494c657c62dbed6c1e6f2b99d144e1e609e75c7d7ff6c36a144e81f044ef089e1243cd3bbde1e4dee9fe7ffac589e05936ca0543939", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1002b84a369ae17612ed4ef4fe31983b18ecfa3bfeccc4527dd77ac3d3ddc1a3ea828143a0f9d8d09887563e824076c5aedcf02be8724c4c4a740ede1944b802", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/376541ace7224e9be0abfa6c3eefa25f921306db906f37cf7a1719f5eb3fad0fcccf74d03df88acb8bd45139a164ffd91853ff29a4b515681cb44dae4a86c5a9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-15 03:23:09.999724) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 03:23:10.000037) ========= 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.NzXRtVlSXV/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.KUtZt9Arzz/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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.005236 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 03:23:10.048280) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-15 03:23:10.048582) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-15 03:23:10.048907) ========= ========= Total master_lag: 0.18 =========