builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0233 starttime: 1485943724.46 results: success (0) buildid: 20170201004003 builduid: 5ce995ad18c248229a7dc0577478b497 revision: 0d5fd88afc55a306466f7d98f895ea9181cc5526 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-01 02:08:44.460801) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-01 02:08:44.461317) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-01 02:08:44.461633) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-01 02:08:44.970136) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 02:08:44.970445) ========= 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.ykn1CfQbAK/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.fv5Fnjp5xu/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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-01 02:08:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.20M=0.001s 2017-02-01 02:08:45 (8.20 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.819732 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 02:08:45.815903) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 02:08:45.816221) ========= 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.ykn1CfQbAK/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.fv5Fnjp5xu/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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.020408 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 02:08:45.867019) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-01 02:08:45.867387) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0d5fd88afc55a306466f7d98f895ea9181cc5526 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0d5fd88afc55a306466f7d98f895ea9181cc5526 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ykn1CfQbAK/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.fv5Fnjp5xu/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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-01 02:08:45,961 truncating revision to first 12 chars 2017-02-01 02:08:45,961 Setting DEBUG logging. 2017-02-01 02:08:45,961 attempt 1/10 2017-02-01 02:08:45,961 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/0d5fd88afc55?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-01 02:08:46,796 unpacking tar archive at: mozilla-aurora-0d5fd88afc55/testing/mozharness/ program finished with exit code 0 elapsedTime=1.100113 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-01 02:08:46.991570) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-01 02:08:46.991921) ========= script_repo_revision: 0d5fd88afc55a306466f7d98f895ea9181cc5526 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-01 02:08:46.992403) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-01 02:08:46.992673) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-01 02:08:47.012794) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-02-01 02:08:47.013387) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ykn1CfQbAK/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.fv5Fnjp5xu/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:08:47 INFO - MultiFileLogger online at 20170201 02:08:47 in /builds/slave/test 02:08:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:08:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:08:47 INFO - {'append_to_log': False, 02:08:47 INFO - 'base_work_dir': '/builds/slave/test', 02:08:47 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:08:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:08:47 INFO - 'branch': 'Mozilla-Aurora', 02:08:47 INFO - 'buildbot_json_path': 'buildprops.json', 02:08:47 INFO - 'config_files': ('talos/mac_config.py',), 02:08:47 INFO - 'default_actions': ('clobber', 02:08:47 INFO - 'read-buildbot-config', 02:08:47 INFO - 'download-and-extract', 02:08:47 INFO - 'populate-webroot', 02:08:47 INFO - 'create-virtualenv', 02:08:47 INFO - 'install', 02:08:47 INFO - 'run-tests'), 02:08:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:08:47 INFO - 'download_minidump_stackwalk': True, 02:08:47 INFO - 'download_symbols': 'ondemand', 02:08:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:08:47 INFO - 'tooltool.py': '/tools/tooltool.py', 02:08:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:08:47 INFO - '/tools/misc-python/virtualenv.py')}, 02:08:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:08:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:08:47 INFO - 'installer_path': 'installer.exe', 02:08:47 INFO - 'log_level': 'info', 02:08:47 INFO - 'log_name': 'talos', 02:08:47 INFO - 'log_to_console': True, 02:08:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:08:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:08:47 INFO - 'opt_config_files': (), 02:08:47 INFO - 'pip_index': False, 02:08:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:08:47 INFO - 'cmd': ('bash', 02:08:47 INFO - '-c', 02:08:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:08:47 INFO - 'enabled': True, 02:08:47 INFO - 'halt_on_failure': False, 02:08:47 INFO - 'name': 'check_screen_resolution'},), 02:08:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:08:47 INFO - 'cmd': ('bash', 02:08:47 INFO - '-c', 02:08:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:08:47 INFO - 'enabled': True, 02:08:47 INFO - 'halt_on_failure': False, 02:08:47 INFO - 'name': 'check_screen_resolution'},), 02:08:47 INFO - 'run_cmd_checks_enabled': True, 02:08:47 INFO - 'sps_profile': False, 02:08:47 INFO - 'sps_profile_interval': 0, 02:08:47 INFO - 'suite': 'dromaeojs-e10s', 02:08:47 INFO - 'system_bits': '32', 02:08:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:08:47 INFO - 'title': 't-yosemite-r7-0233', 02:08:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:08:47 INFO - 'use_talos_json': True, 02:08:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:08:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:08:47 INFO - 'work_dir': 'build'} 02:08:47 INFO - [mozharness: 2017-02-01 10:08:47.228680Z] Running clobber step. 02:08:47 INFO - Running pre-action listener: _resource_record_pre_action 02:08:47 INFO - Running main action method: clobber 02:08:47 INFO - rmtree: /builds/slave/test/build 02:08:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:08:47 INFO - Running post-action listener: _resource_record_post_action 02:08:47 INFO - [mozharness: 2017-02-01 10:08:47.848918Z] Finished clobber step (success) 02:08:47 INFO - [mozharness: 2017-02-01 10:08:47.849051Z] Running read-buildbot-config step. 02:08:47 INFO - Running pre-action listener: _resource_record_pre_action 02:08:47 INFO - Running main action method: read_buildbot_config 02:08:47 INFO - Using buildbot properties: 02:08:47 INFO - { 02:08:47 INFO - "project": "", 02:08:47 INFO - "product": "firefox", 02:08:47 INFO - "script_repo_revision": "production", 02:08:47 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:08:47 INFO - "repository": "", 02:08:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:08:47 INFO - "buildid": "20170201004003", 02:08:47 INFO - "stage_platform": "macosx64", 02:08:47 INFO - "basedir": "/builds/slave/test", 02:08:47 INFO - "nightly_build": "True", 02:08:47 INFO - "buildnumber": 44, 02:08:47 INFO - "slavename": "t-yosemite-r7-0233", 02:08:47 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 02:08:47 INFO - "platform": "yosemite_r7", 02:08:47 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:08:47 INFO - "branch": "mozilla-aurora", 02:08:47 INFO - "repo_path": "releases/mozilla-aurora", 02:08:47 INFO - "revision": "0d5fd88afc55a306466f7d98f895ea9181cc5526", 02:08:47 INFO - "pgo_build": "False", 02:08:47 INFO - "builduid": "5ce995ad18c248229a7dc0577478b497", 02:08:47 INFO - "slavebuilddir": "test" 02:08:47 INFO - } 02:08:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZUO_w9SpR4Gcgw6n8Uv28w/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 02:08:47 INFO - Running post-action listener: _resource_record_post_action 02:08:47 INFO - [mozharness: 2017-02-01 10:08:47.852487Z] Finished read-buildbot-config step (success) 02:08:47 INFO - [mozharness: 2017-02-01 10:08:47.852601Z] Running download-and-extract step. 02:08:47 INFO - Running pre-action listener: _resource_record_pre_action 02:08:47 INFO - Running main action method: download_and_extract 02:08:47 INFO - mkdir: /builds/slave/test/build/tests 02:08:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:47 INFO - trying https://queue.taskcluster.net/v1/task/ZUO_w9SpR4Gcgw6n8Uv28w/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 02:08:47 INFO - Downloading https://queue.taskcluster.net/v1/task/ZUO_w9SpR4Gcgw6n8Uv28w/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 02:08:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZUO_w9SpR4Gcgw6n8Uv28w/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 02:08:49 INFO - Downloaded 1199 bytes. 02:08:49 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 02:08:49 INFO - Using the following test package requirements: 02:08:49 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 02:08:49 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:08:49 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 02:08:49 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:08:49 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 02:08:49 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:08:49 INFO - u'jsshell-mac.zip'], 02:08:49 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:08:49 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 02:08:49 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 02:08:49 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:08:49 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 02:08:49 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:08:49 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 02:08:49 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:08:49 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 02:08:49 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:08:49 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 02:08:49 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:08:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZUO_w9SpR4Gcgw6n8Uv28w/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 02:08:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZUO_w9SpR4Gcgw6n8Uv28w/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:08:49 INFO - Fetch https://queue.taskcluster.net/v1/task/ZUO_w9SpR4Gcgw6n8Uv28w/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 02:08:50 INFO - Expected file size: 16353563 02:08:50 INFO - Obtained file size: 16353563 02:08:57 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:08:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZUO_w9SpR4Gcgw6n8Uv28w/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 02:08:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZUO_w9SpR4Gcgw6n8Uv28w/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:08:57 INFO - Fetch https://queue.taskcluster.net/v1/task/ZUO_w9SpR4Gcgw6n8Uv28w/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 02:08:58 INFO - Expected file size: 16353563 02:08:58 INFO - Obtained file size: 16353563 02:09:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZUO_w9SpR4Gcgw6n8Uv28w/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 02:09:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZUO_w9SpR4Gcgw6n8Uv28w/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:09:04 INFO - Fetch https://queue.taskcluster.net/v1/task/ZUO_w9SpR4Gcgw6n8Uv28w/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 02:09:10 INFO - Expected file size: 14403485 02:09:10 INFO - Obtained file size: 14403485 02:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:10 INFO - trying https://queue.taskcluster.net/v1/task/ZUO_w9SpR4Gcgw6n8Uv28w/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 02:09:10 INFO - Downloading https://queue.taskcluster.net/v1/task/ZUO_w9SpR4Gcgw6n8Uv28w/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 02:09:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZUO_w9SpR4Gcgw6n8Uv28w/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 02:09:13 INFO - Downloaded 73967559 bytes. 02:09:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZUO_w9SpR4Gcgw6n8Uv28w/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 02:09:13 INFO - mkdir: /builds/slave/test/properties 02:09:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:09:13 INFO - Writing to file /builds/slave/test/properties/build_url 02:09:13 INFO - Contents: 02:09:13 INFO - build_url:https://queue.taskcluster.net/v1/task/ZUO_w9SpR4Gcgw6n8Uv28w/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 02:09:14 INFO - Running post-action listener: _resource_record_post_action 02:09:14 INFO - Running post-action listener: set_extra_try_arguments 02:09:14 INFO - [mozharness: 2017-02-01 10:09:14.381264Z] Finished download-and-extract step (success) 02:09:14 INFO - [mozharness: 2017-02-01 10:09:14.381397Z] Running populate-webroot step. 02:09:14 INFO - Running pre-action listener: _resource_record_pre_action 02:09:14 INFO - Running main action method: populate_webroot 02:09:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:09:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:09:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:09:14 INFO - '--tppagecycles', 02:09:14 INFO - '7'], 02:09:14 INFO - 'tests': ['tsvgm']}}, 02:09:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:09:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:09:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:09:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:09:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:09:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:09:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:09:14 INFO - 'talos_options': ['--disable-e10s'], 02:09:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:09:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:09:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:09:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:09:14 INFO - 'talos_options': ['--disable-e10s'], 02:09:14 INFO - 'tests': ['damp', 'tps']}, 02:09:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:09:14 INFO - 'tests': ['damp', 'tps']}, 02:09:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:09:14 INFO - 'tests': ['dromaeo_dom']}, 02:09:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:09:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:09:14 INFO - 'tests': ['basic_compositor_video']}, 02:09:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:09:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:09:14 INFO - 'tests': ['a11yr', 02:09:14 INFO - 'ts_paint', 02:09:14 INFO - 'tpaint', 02:09:14 INFO - 'sessionrestore', 02:09:14 INFO - 'sessionrestore_no_auto_restore', 02:09:14 INFO - 'tabpaint']}, 02:09:14 INFO - 'other-e10s': {'tests': ['a11yr', 02:09:14 INFO - 'ts_paint', 02:09:14 INFO - 'tpaint', 02:09:14 INFO - 'sessionrestore', 02:09:14 INFO - 'sessionrestore_no_auto_restore', 02:09:14 INFO - 'tabpaint']}, 02:09:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:09:14 INFO - 'ts_paint', 02:09:14 INFO - 'tpaint', 02:09:14 INFO - 'sessionrestore', 02:09:14 INFO - 'sessionrestore_no_auto_restore', 02:09:14 INFO - 'tabpaint']}, 02:09:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:09:14 INFO - 'ts_paint', 02:09:14 INFO - 'tpaint', 02:09:14 INFO - 'sessionrestore', 02:09:14 INFO - 'sessionrestore_no_auto_restore', 02:09:14 INFO - 'tabpaint']}, 02:09:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:09:14 INFO - 'tests': ['a11yr', 02:09:14 INFO - 'ts_paint', 02:09:14 INFO - 'tpaint', 02:09:14 INFO - 'sessionrestore', 02:09:14 INFO - 'sessionrestore_no_auto_restore', 02:09:14 INFO - 'tabpaint']}, 02:09:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:09:14 INFO - 'tests': ['a11yr', 02:09:14 INFO - 'ts_paint', 02:09:14 INFO - 'tpaint', 02:09:14 INFO - 'sessionrestore', 02:09:14 INFO - 'sessionrestore_no_auto_restore', 02:09:14 INFO - 'tabpaint']}, 02:09:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:09:14 INFO - 'tests': ['tsvgx', 02:09:14 INFO - 'tsvgr_opacity', 02:09:14 INFO - 'tart', 02:09:14 INFO - 'tscrollx', 02:09:14 INFO - 'cart', 02:09:14 INFO - 'tsvg_static']}, 02:09:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:09:14 INFO - 'tsvgr_opacity', 02:09:14 INFO - 'tart', 02:09:14 INFO - 'tscrollx', 02:09:14 INFO - 'cart', 02:09:14 INFO - 'tsvg_static']}, 02:09:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:09:14 INFO - 'talos_options': ['--disable-e10s'], 02:09:14 INFO - 'tests': ['tp5o']}, 02:09:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:09:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:09:14 INFO - 'talos_options': ['--disable-e10s', 02:09:14 INFO - '--xperf_path', 02:09:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:09:14 INFO - 'tests': ['tp5n']}, 02:09:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:09:14 INFO - 'talos_options': ['--xperf_path', 02:09:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:09:14 INFO - 'tests': ['tp5n']}}, 02:09:14 INFO - 'talos.zip': {'path': '', 02:09:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:09:14 INFO - Running post-action listener: _resource_record_post_action 02:09:14 INFO - [mozharness: 2017-02-01 10:09:14.399463Z] Finished populate-webroot step (success) 02:09:14 INFO - [mozharness: 2017-02-01 10:09:14.399596Z] Running create-virtualenv step. 02:09:14 INFO - Running pre-action listener: _resource_record_pre_action 02:09:14 INFO - Running main action method: create_virtualenv 02:09:14 INFO - Creating virtualenv /builds/slave/test/build/venv 02:09:14 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:09:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:09:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:09:14 INFO - Using real prefix '/tools/python27' 02:09:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:09:15 INFO - Installing distribute.............................................................................................................................................................................................done. 02:09:18 INFO - Installing pip.................done. 02:09:18 INFO - Return code: 0 02:09:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:09:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:09:18 INFO - Reading from file tmpfile_stdout 02:09:18 INFO - Output received: 02:09:18 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 02:09:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:09:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:18 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 0x10eac8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eacb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94bbd675f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8a6d30>, '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 0x10e7cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e89bbe8>, '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.ykn1CfQbAK/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.fv5Fnjp5xu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:09:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:09:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:09:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ykn1CfQbAK/Render', 02:09:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:18 INFO - 'HOME': '/Users/cltbld', 02:09:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:18 INFO - 'LOGNAME': 'cltbld', 02:09:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:18 INFO - 'MOZ_NO_REMOTE': '1', 02:09:18 INFO - 'NO_EM_RESTART': '1', 02:09:18 INFO - 'PAGER': '/bin/cat', 02:09:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:18 INFO - 'PWD': '/builds/slave/test', 02:09:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:18 INFO - 'SHELL': '/bin/bash', 02:09:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fv5Fnjp5xu/Listeners', 02:09:18 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 02:09:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:18 INFO - 'USER': 'cltbld', 02:09:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:18 INFO - 'XPC_FLAGS': '0x0', 02:09:18 INFO - 'XPC_SERVICE_NAME': '0', 02:09:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:18 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:09:18 INFO - Cleaning up... 02:09:18 INFO - Return code: 0 02:09:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:09:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:18 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 0x10eac8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eacb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94bbd675f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8a6d30>, '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 0x10e7cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e89bbe8>, '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.ykn1CfQbAK/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.fv5Fnjp5xu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:09:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:09:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:09:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ykn1CfQbAK/Render', 02:09:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:18 INFO - 'HOME': '/Users/cltbld', 02:09:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:18 INFO - 'LOGNAME': 'cltbld', 02:09:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:18 INFO - 'MOZ_NO_REMOTE': '1', 02:09:18 INFO - 'NO_EM_RESTART': '1', 02:09:18 INFO - 'PAGER': '/bin/cat', 02:09:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:18 INFO - 'PWD': '/builds/slave/test', 02:09:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:18 INFO - 'SHELL': '/bin/bash', 02:09:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fv5Fnjp5xu/Listeners', 02:09:18 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 02:09:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:18 INFO - 'USER': 'cltbld', 02:09:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:18 INFO - 'XPC_FLAGS': '0x0', 02:09:18 INFO - 'XPC_SERVICE_NAME': '0', 02:09:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:19 INFO - Downloading/unpacking psutil>=3.1.1 02:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:09:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:09:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:09:25 INFO - Installing collected packages: psutil 02:09:25 INFO - Running setup.py install for psutil 02:09:25 INFO - building 'psutil._psutil_osx' extension 02:09:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:09:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:09:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:09:25 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:09:25 INFO - building 'psutil._psutil_posix' extension 02:09:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:09:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:09:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:09:25 INFO - ^ 02:09:25 INFO - 1 warning generated. 02:09:25 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:09:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:09:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:09:26 INFO - Successfully installed psutil 02:09:26 INFO - Cleaning up... 02:09:26 INFO - Return code: 0 02:09:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:09: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')]} 02:09:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09: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')]} 02:09:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eac8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eacb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94bbd675f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8a6d30>, '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 0x10e7cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e89bbe8>, '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.ykn1CfQbAK/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.fv5Fnjp5xu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:09:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:09:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:09:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ykn1CfQbAK/Render', 02:09:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:26 INFO - 'HOME': '/Users/cltbld', 02:09:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:26 INFO - 'LOGNAME': 'cltbld', 02:09:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:26 INFO - 'MOZ_NO_REMOTE': '1', 02:09:26 INFO - 'NO_EM_RESTART': '1', 02:09:26 INFO - 'PAGER': '/bin/cat', 02:09:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:26 INFO - 'PWD': '/builds/slave/test', 02:09:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:26 INFO - 'SHELL': '/bin/bash', 02:09:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fv5Fnjp5xu/Listeners', 02:09:26 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 02:09:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:26 INFO - 'USER': 'cltbld', 02:09:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:26 INFO - 'XPC_FLAGS': '0x0', 02:09:26 INFO - 'XPC_SERVICE_NAME': '0', 02:09:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:09:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:09:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:09:29 INFO - Installing collected packages: mozsystemmonitor 02:09:29 INFO - Running setup.py install for mozsystemmonitor 02:09:29 INFO - Successfully installed mozsystemmonitor 02:09:29 INFO - Cleaning up... 02:09:29 INFO - Return code: 0 02:09:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:29 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 0x10eac8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eacb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94bbd675f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8a6d30>, '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 0x10e7cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e89bbe8>, '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.ykn1CfQbAK/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.fv5Fnjp5xu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:09:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:09:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:09:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ykn1CfQbAK/Render', 02:09:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:29 INFO - 'HOME': '/Users/cltbld', 02:09:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:29 INFO - 'LOGNAME': 'cltbld', 02:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:29 INFO - 'MOZ_NO_REMOTE': '1', 02:09:29 INFO - 'NO_EM_RESTART': '1', 02:09:29 INFO - 'PAGER': '/bin/cat', 02:09:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:29 INFO - 'PWD': '/builds/slave/test', 02:09:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:29 INFO - 'SHELL': '/bin/bash', 02:09:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fv5Fnjp5xu/Listeners', 02:09:29 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 02:09:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:29 INFO - 'USER': 'cltbld', 02:09:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:29 INFO - 'XPC_FLAGS': '0x0', 02:09:29 INFO - 'XPC_SERVICE_NAME': '0', 02:09:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:30 INFO - Downloading/unpacking jsonschema==2.5.1 02:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:09:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:33 INFO - Downloading functools32-3.2.3-2.tar.gz 02:09:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:09:33 INFO - warning: no files found matching '*.txt' 02:09:33 INFO - no previously-included directories found matching 'build' 02:09:33 INFO - no previously-included directories found matching 'dist' 02:09:33 INFO - no previously-included directories found matching '.git*' 02:09:33 INFO - Installing collected packages: jsonschema, functools32 02:09:33 INFO - Running setup.py install for functools32 02:09:33 INFO - warning: no files found matching '*.txt' 02:09:33 INFO - no previously-included directories found matching 'build' 02:09:33 INFO - no previously-included directories found matching 'dist' 02:09:33 INFO - no previously-included directories found matching '.git*' 02:09:33 INFO - Successfully installed jsonschema functools32 02:09:33 INFO - Cleaning up... 02:09:33 INFO - Return code: 0 02:09:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:09: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')]} 02:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09: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')]} 02:09:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09: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', '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 0x10eac8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eacb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94bbd675f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8a6d30>, '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 0x10e7cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e89bbe8>, '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.ykn1CfQbAK/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.fv5Fnjp5xu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:09: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:09: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 functools32==3.2.3-2 02:09:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ykn1CfQbAK/Render', 02:09:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:33 INFO - 'HOME': '/Users/cltbld', 02:09:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:33 INFO - 'LOGNAME': 'cltbld', 02:09:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:33 INFO - 'MOZ_NO_REMOTE': '1', 02:09:33 INFO - 'NO_EM_RESTART': '1', 02:09:33 INFO - 'PAGER': '/bin/cat', 02:09:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:33 INFO - 'PWD': '/builds/slave/test', 02:09:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:33 INFO - 'SHELL': '/bin/bash', 02:09:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fv5Fnjp5xu/Listeners', 02:09:33 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 02:09:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:33 INFO - 'USER': 'cltbld', 02:09:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:33 INFO - 'XPC_FLAGS': '0x0', 02:09:33 INFO - 'XPC_SERVICE_NAME': '0', 02:09:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:09:33 INFO - Cleaning up... 02:09:33 INFO - Return code: 0 02:09:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:09: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')]} 02:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09: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')]} 02:09:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09: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', '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 0x10eac8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eacb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94bbd675f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8a6d30>, '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 0x10e7cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e89bbe8>, '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.ykn1CfQbAK/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.fv5Fnjp5xu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:09: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:09: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 blobuploader==1.2.4 02:09:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ykn1CfQbAK/Render', 02:09:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:33 INFO - 'HOME': '/Users/cltbld', 02:09:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:33 INFO - 'LOGNAME': 'cltbld', 02:09:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:33 INFO - 'MOZ_NO_REMOTE': '1', 02:09:33 INFO - 'NO_EM_RESTART': '1', 02:09:33 INFO - 'PAGER': '/bin/cat', 02:09:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:33 INFO - 'PWD': '/builds/slave/test', 02:09:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:33 INFO - 'SHELL': '/bin/bash', 02:09:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fv5Fnjp5xu/Listeners', 02:09:33 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 02:09:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:33 INFO - 'USER': 'cltbld', 02:09:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:33 INFO - 'XPC_FLAGS': '0x0', 02:09:33 INFO - 'XPC_SERVICE_NAME': '0', 02:09:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:34 INFO - Downloading/unpacking blobuploader==1.2.4 02:09: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 02:09: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 02:09: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 02:09: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 02:09: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 02:09: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 02:09:36 INFO - Downloading blobuploader-1.2.4.tar.gz 02:09:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:09:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:09:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:09:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:37 INFO - Downloading docopt-0.6.1.tar.gz 02:09:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:09:37 INFO - Installing collected packages: blobuploader, requests, docopt 02:09:37 INFO - Running setup.py install for blobuploader 02:09:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:09:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:09:38 INFO - Running setup.py install for requests 02:09:38 INFO - Running setup.py install for docopt 02:09:38 INFO - Successfully installed blobuploader requests docopt 02:09:38 INFO - Cleaning up... 02:09:38 INFO - Return code: 0 02:09:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:09: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')]} 02:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09: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')]} 02:09:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:38 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 0x10eac8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eacb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94bbd675f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8a6d30>, '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 0x10e7cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e89bbe8>, '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.ykn1CfQbAK/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.fv5Fnjp5xu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:09:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:09:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:09:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ykn1CfQbAK/Render', 02:09:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:38 INFO - 'HOME': '/Users/cltbld', 02:09:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:38 INFO - 'LOGNAME': 'cltbld', 02:09:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:38 INFO - 'MOZ_NO_REMOTE': '1', 02:09:38 INFO - 'NO_EM_RESTART': '1', 02:09:38 INFO - 'PAGER': '/bin/cat', 02:09:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:38 INFO - 'PWD': '/builds/slave/test', 02:09:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:38 INFO - 'SHELL': '/bin/bash', 02:09:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fv5Fnjp5xu/Listeners', 02:09:38 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 02:09:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:38 INFO - 'USER': 'cltbld', 02:09:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:38 INFO - 'XPC_FLAGS': '0x0', 02:09:38 INFO - 'XPC_SERVICE_NAME': '0', 02:09:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:09:39 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-VQsFng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:09:39 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-JWuWYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:09:39 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-R0BeQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:09:39 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-0HS59O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:09:39 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-RJtVll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:09:39 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-3CQxoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:09:39 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-wN_2Xn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:09:39 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-Fzz62f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:09:39 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-_BKwcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:09:39 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-pmxHg0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:09:40 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-zNdJnJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:09:40 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-8DjMEC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:09:40 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-Ywp8Tw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:09:40 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-TK7c7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:09:40 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-cLcSBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:09:40 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-X3Yx81-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:09:40 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-Wzaiha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:09:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:09:40 INFO - Running setup.py install for manifestparser 02:09:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:09:40 INFO - Running setup.py install for mozcrash 02:09:41 INFO - Running setup.py install for mozdebug 02:09:41 INFO - Running setup.py install for mozdevice 02:09:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:09:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:09:41 INFO - Running setup.py install for mozfile 02:09:41 INFO - Running setup.py install for mozhttpd 02:09:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:09:41 INFO - Running setup.py install for mozinfo 02:09:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:09:41 INFO - Running setup.py install for mozInstall 02:09:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:09:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:09:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:09:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:09:41 INFO - Running setup.py install for mozleak 02:09:42 INFO - Running setup.py install for mozlog 02:09:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:09:42 INFO - Running setup.py install for moznetwork 02:09:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:09:42 INFO - Running setup.py install for mozprocess 02:09:42 INFO - Running setup.py install for mozprofile 02:09:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:09:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:09:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:09:42 INFO - Running setup.py install for mozrunner 02:09:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:09:42 INFO - Running setup.py install for mozscreenshot 02:09:43 INFO - Running setup.py install for moztest 02:09:43 INFO - Running setup.py install for mozversion 02:09:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:09:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:09:43 INFO - Cleaning up... 02:09:43 INFO - Return code: 0 02:09:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:09:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:43 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 0x10eac8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eacb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94bbd675f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8a6d30>, '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 0x10e7cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e89bbe8>, '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.ykn1CfQbAK/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.fv5Fnjp5xu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:09:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:09:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:09:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ykn1CfQbAK/Render', 02:09:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:43 INFO - 'HOME': '/Users/cltbld', 02:09:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:43 INFO - 'LOGNAME': 'cltbld', 02:09:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:43 INFO - 'MOZ_NO_REMOTE': '1', 02:09:43 INFO - 'NO_EM_RESTART': '1', 02:09:43 INFO - 'PAGER': '/bin/cat', 02:09:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:43 INFO - 'PWD': '/builds/slave/test', 02:09:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:43 INFO - 'SHELL': '/bin/bash', 02:09:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fv5Fnjp5xu/Listeners', 02:09:43 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 02:09:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:43 INFO - 'USER': 'cltbld', 02:09:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:43 INFO - 'XPC_FLAGS': '0x0', 02:09:43 INFO - 'XPC_SERVICE_NAME': '0', 02:09:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:09:43 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-qsp0_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:09:43 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:09:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:09:43 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-WXRh3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:09:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:09:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:09:43 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-qT30FD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:09:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:09:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:09:43 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-yq4lFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:09:44 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-7xE3AS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:09:44 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-_HGN99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:09:44 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-IYqdyZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:09:44 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-ZFAFVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:09:44 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-jSRE3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:09:44 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-zqLHBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:09:44 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-Mi2Am1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:09:44 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-cdmXCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:09:44 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)) 02:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:09:44 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-RjW1XG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:09:45 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-Hr_j8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:09:45 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-8aGTjC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:09:45 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-dOddWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:09:45 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-Co5BKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:09:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:48 INFO - Downloading blessings-1.6.tar.gz 02:09:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:09:48 INFO - Installing collected packages: blessings 02:09:48 INFO - Running setup.py install for blessings 02:09:48 INFO - Successfully installed blessings 02:09:48 INFO - Cleaning up... 02:09:48 INFO - Return code: 0 02:09:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:09:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:09:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:09:48 INFO - Reading from file tmpfile_stdout 02:09:48 INFO - Current package versions: 02:09:48 INFO - blessings == 1.6 02:09:48 INFO - blobuploader == 1.2.4 02:09:48 INFO - docopt == 0.6.1 02:09:48 INFO - functools32 == 3.2.3-2 02:09:48 INFO - jsonschema == 2.5.1 02:09:48 INFO - manifestparser == 1.1 02:09:48 INFO - mozInstall == 1.12 02:09:48 INFO - mozcrash == 1.0 02:09:48 INFO - mozdebug == 0.1 02:09:48 INFO - mozdevice == 0.49 02:09:48 INFO - mozfile == 1.2 02:09:48 INFO - mozhttpd == 0.7 02:09:48 INFO - mozinfo == 0.9 02:09:48 INFO - mozleak == 0.1 02:09:48 INFO - mozlog == 3.4 02:09:48 INFO - moznetwork == 0.27 02:09:48 INFO - mozprocess == 0.24 02:09:48 INFO - mozprofile == 0.28 02:09:48 INFO - mozrunner == 6.13 02:09:48 INFO - mozscreenshot == 0.1 02:09:48 INFO - mozsystemmonitor == 0.3 02:09:48 INFO - moztest == 0.8 02:09:48 INFO - mozversion == 1.4 02:09:48 INFO - psutil == 3.1.1 02:09:48 INFO - requests == 1.2.3 02:09:48 INFO - wsgiref == 0.1.2 02:09:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:48 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 0x10eac8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eacb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94bbd675f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8a6d30>, '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 0x10e7cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e89bbe8>, '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.ykn1CfQbAK/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.fv5Fnjp5xu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:09:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:09:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:09:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ykn1CfQbAK/Render', 02:09:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:48 INFO - 'HOME': '/Users/cltbld', 02:09:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:48 INFO - 'LOGNAME': 'cltbld', 02:09:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:48 INFO - 'MOZ_NO_REMOTE': '1', 02:09:48 INFO - 'NO_EM_RESTART': '1', 02:09:48 INFO - 'PAGER': '/bin/cat', 02:09:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:48 INFO - 'PWD': '/builds/slave/test', 02:09:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:48 INFO - 'SHELL': '/bin/bash', 02:09:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fv5Fnjp5xu/Listeners', 02:09:48 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 02:09:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:48 INFO - 'USER': 'cltbld', 02:09:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:48 INFO - 'XPC_FLAGS': '0x0', 02:09:48 INFO - 'XPC_SERVICE_NAME': '0', 02:09:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:09:48 INFO - Cleaning up... 02:09:48 INFO - Return code: 0 02:09:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:48 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 0x10eac8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eacb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94bbd675f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8a6d30>, '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 0x10e7cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e89bbe8>, '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.ykn1CfQbAK/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.fv5Fnjp5xu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:09:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:09:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:09:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ykn1CfQbAK/Render', 02:09:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:48 INFO - 'HOME': '/Users/cltbld', 02:09:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:48 INFO - 'LOGNAME': 'cltbld', 02:09:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:48 INFO - 'MOZ_NO_REMOTE': '1', 02:09:48 INFO - 'NO_EM_RESTART': '1', 02:09:48 INFO - 'PAGER': '/bin/cat', 02:09:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:48 INFO - 'PWD': '/builds/slave/test', 02:09:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:48 INFO - 'SHELL': '/bin/bash', 02:09:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fv5Fnjp5xu/Listeners', 02:09:48 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 02:09:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:48 INFO - 'USER': 'cltbld', 02:09:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:48 INFO - 'XPC_FLAGS': '0x0', 02:09:48 INFO - 'XPC_SERVICE_NAME': '0', 02:09:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:09:49 INFO - Cleaning up... 02:09:49 INFO - Return code: 0 02:09:49 INFO - Running post-action listener: _resource_record_post_action 02:09:49 INFO - Running post-action listener: _start_resource_monitoring 02:09:49 INFO - Starting resource monitoring. 02:09:49 INFO - [mozharness: 2017-02-01 10:09:49.277743Z] Finished create-virtualenv step (success) 02:09:49 INFO - [mozharness: 2017-02-01 10:09:49.278719Z] Running install step. 02:09:49 INFO - Running pre-action listener: _resource_record_pre_action 02:09:49 INFO - Running main action method: install 02:09:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:09:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:09:49 INFO - Reading from file tmpfile_stdout 02:09:49 INFO - Detecting whether we're running mozinstall >=1.0... 02:09:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:09:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:09:49 INFO - Reading from file tmpfile_stdout 02:09:49 INFO - Output received: 02:09:49 INFO - Usage: mozinstall [options] installer 02:09:49 INFO - Options: 02:09:49 INFO - -h, --help show this help message and exit 02:09:49 INFO - -d DEST, --destination=DEST 02:09:49 INFO - Directory to install application into. [default: 02:09:49 INFO - "/builds/slave/test"] 02:09:49 INFO - --app=APP Application being installed. [default: firefox] 02:09:49 INFO - mkdir: /builds/slave/test/build/application 02:09:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:09:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:10:09 INFO - Reading from file tmpfile_stdout 02:10:09 INFO - Output received: 02:10:09 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:10:09 INFO - Running post-action listener: _resource_record_post_action 02:10:09 INFO - [mozharness: 2017-02-01 10:10:09.800898Z] Finished install step (success) 02:10:09 INFO - [mozharness: 2017-02-01 10:10:09.801106Z] Running run-tests step. 02:10:09 INFO - Running pre-action listener: _resource_record_pre_action 02:10:09 INFO - Running main action method: run_tests 02:10:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:10:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:10:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:10:09 INFO - 2017-02-01 02:10:09.815 screenresolution[6432:121801] starting screenresolution argv=screenresolution get 02:10:09 INFO - 2017-02-01 02:10:09.818 screenresolution[6432:121801] Display 0: 1600x1200x32@60 02:10:09 INFO - 2017-02-01 02:10:09.824 screenresolution[6433:121803] starting screenresolution argv=screenresolution list 02:10:09 INFO - Available Modes on Display 0 02:10:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:10:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:10:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:10:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:10:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:10:10 INFO - Intel Iris: 02:10:10 INFO - Chipset Model: Intel Iris 02:10:10 INFO - Type: GPU 02:10:10 INFO - Bus: Built-In 02:10:10 INFO - VRAM (Dynamic, Max): 1536 MB 02:10:10 INFO - Vendor: Intel (0x8086) 02:10:10 INFO - Device ID: 0x0a2e 02:10:10 INFO - Revision ID: 0x0009 02:10:10 INFO - Displays: 02:10:10 INFO - Display: 02:10:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:10:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:10:10 INFO - Display Serial Number: 1600x1200 60 02:10:10 INFO - Main Display: Yes 02:10:10 INFO - Mirror: Off 02:10:10 INFO - Online: Yes 02:10:10 INFO - Rotation: Supported 02:10:10 INFO - Return code: 0 02:10:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:10:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:10:10 INFO - Python 2.7.3 02:10:10 INFO - Return code: 0 02:10:10 INFO - grabbing minidump binary from tooltool 02:10:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:10:10 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 0x10e8a6d30>, '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 0x10e7cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e89bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:10:10 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:10:10 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:10:10 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:10:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:10:10 INFO - Return code: 0 02:10:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:10:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:10:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:10:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:10:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:10:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZUO_w9SpR4Gcgw6n8Uv28w/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0233', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:10:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZUO_w9SpR4Gcgw6n8Uv28w/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0233 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:10:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ykn1CfQbAK/Render', 02:10:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:10:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:10:10 INFO - 'HOME': '/Users/cltbld', 02:10:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:10:10 INFO - 'LOGNAME': 'cltbld', 02:10:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:10:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:10:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:10:10 INFO - 'MOZ_NO_REMOTE': '1', 02:10:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:10:10 INFO - 'NO_EM_RESTART': '1', 02:10:10 INFO - 'PAGER': '/bin/cat', 02:10:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:10:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:10:10 INFO - 'PWD': '/builds/slave/test', 02:10:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:10:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:10:10 INFO - 'SHELL': '/bin/bash', 02:10:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fv5Fnjp5xu/Listeners', 02:10:10 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 02:10:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:10:10 INFO - 'USER': 'cltbld', 02:10:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:10:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:10:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:10:10 INFO - 'XPC_FLAGS': '0x0', 02:10:10 INFO - 'XPC_SERVICE_NAME': '0', 02:10:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZUO_w9SpR4Gcgw6n8Uv28w/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0233', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:10:10 INFO - mozversion application_buildid: 20170201004003 02:10:10 INFO - mozversion application_changeset: 0d5fd88afc55a306466f7d98f895ea9181cc5526 02:10:10 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:10:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:10:10 INFO - mozversion application_name: Firefox 02:10:10 INFO - mozversion application_remotingname: firefox-dev 02:10:10 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:10:10 INFO - mozversion application_vendor: Mozilla 02:10:10 INFO - mozversion application_version: 53.0a2 02:10:10 INFO - mozversion platform_buildid: 20170201004003 02:10:10 INFO - mozversion platform_changeset: 0d5fd88afc55a306466f7d98f895ea9181cc5526 02:10:10 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:10:10 INFO - mozversion platform_version: 53.0a2 02:10:10 INFO - using testdate: 1485943810 02:10:10 INFO - actual date: 1485943810 02:10:10 INFO - starting webserver on 'localhost:56768' 02:10:10 INFO - SUITE-START | Running 2 tests 02:10:10 INFO - TEST-START | dromaeo_css 02:10:10 INFO - operating with platform_type : mac_ 02:10:10 INFO - Initialising browser for dromaeo_css test... 02:10:10 INFO - TEST-INFO | started process 6443 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpBwRun2/profile http://localhost:56768/getInfo.html) 02:10:13 INFO - PROCESS | 6443 | 2017-02-01 02:10:13.450 plugin-container[6446:121976] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9043, name = 'com.apple.tsm.portname' 02:10:13 INFO - PROCESS | 6443 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:10:13 INFO - PROCESS | 6443 | 2017-02-01 02:10:13.451 plugin-container[6446:121976] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4e07, name = 'com.apple.CFPasteboardClient' 02:10:13 INFO - PROCESS | 6443 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:10:13 INFO - PROCESS | 6443 | 2017-02-01 02:10:13.451 plugin-container[6446:121976] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:10:13 INFO - PROCESS | 6443 | __metrics Screen width/height:1600/1200 02:10:13 INFO - PROCESS | 6443 | colorDepth:24 02:10:13 INFO - PROCESS | 6443 | Browser inner width/height: 1024/689 02:10:13 INFO - PROCESS | 6443 | __metrics 02:10:13 ERROR - PROCESS | 6443 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 02:10:14 INFO - TEST-INFO | 6443: exit 0 02:10:14 INFO - Browser initialized. 02:10:14 INFO - Running cycle 1/1 for dromaeo_css test... 02:10:14 INFO - TEST-INFO | started process 6448 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpBwRun2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:10:16 INFO - PROCESS | 6448 | 2017-02-01 02:10:16.765 plugin-container[6449:122114] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 02:10:16 INFO - PROCESS | 6448 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:10:16 INFO - PROCESS | 6448 | 2017-02-01 02:10:16.766 plugin-container[6449:122114] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f03, name = 'com.apple.CFPasteboardClient' 02:10:16 INFO - PROCESS | 6448 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:10:16 INFO - PROCESS | 6448 | 2017-02-01 02:10:16.766 plugin-container[6449:122114] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:10:26 INFO - PROCESS | 6448 | RSS: Main: 218656768 02:10:26 INFO - PROCESS | 6448 | 02:13:28 INFO - PROCESS | 6448 | Cycle 1(1): loaded http://localhost:56768/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56768/tests/dromaeo/cssquery-ext.html) 02:13:30 INFO - PROCESS | 6448 | RSS: Main: 236580864 02:13:30 INFO - PROCESS | 6448 | 02:15:14 INFO - PROCESS | 6448 | ************************* 02:15:14 INFO - PROCESS | 6448 | A coding exception was thrown and uncaught in a Task. 02:15:14 INFO - PROCESS | 6448 | 02:15:14 ERROR - PROCESS | 6448 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:15:14 INFO - PROCESS | 6448 | Full stack: 02:15:14 INFO - PROCESS | 6448 | ************************* 02:16:32 INFO - PROCESS | 6448 | Cycle 1(1): loaded http://localhost:56768/tests/dromaeo/cssquery-ext.html (next: http://localhost:56768/tests/dromaeo/cssquery-jquery.html) 02:16:33 INFO - PROCESS | 6448 | RSS: Main: 244367360 02:16:33 INFO - PROCESS | 6448 | 02:19:35 INFO - PROCESS | 6448 | Cycle 1(1): loaded http://localhost:56768/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56768/tests/dromaeo/cssquery-mootools.html) 02:19:35 INFO - PROCESS | 6448 | RSS: Main: 253968384 02:19:35 INFO - PROCESS | 6448 | 02:22:37 INFO - PROCESS | 6448 | Cycle 1(1): loaded http://localhost:56768/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56768/tests/dromaeo/cssquery-prototype.html) 02:22:38 INFO - PROCESS | 6448 | RSS: Main: 250687488 02:22:38 INFO - PROCESS | 6448 | 02:25:41 INFO - PROCESS | 6448 | Cycle 1(1): loaded http://localhost:56768/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56768/tests/dromaeo/cssquery-yui.html) 02:25:41 INFO - PROCESS | 6448 | RSS: Main: 254750720 02:25:41 INFO - PROCESS | 6448 | 02:28:44 INFO - PROCESS | 6448 | Cycle 1(1): loaded http://localhost:56768/tests/dromaeo/cssquery-yui.html (next: http://localhost:56768/tests/dromaeo/cssquery-dojo.html) 02:28:44 INFO - PROCESS | 6448 | RSS: Main: 255823872 02:28:44 INFO - PROCESS | 6448 | 02:28:44 INFO - PROCESS | 6448 | __start_tp_report 02:28:44 INFO - PROCESS | 6448 | _x_x_mozilla_page_load 02:28:44 INFO - PROCESS | 6448 | _x_x_mozilla_page_load_details 02:28:44 INFO - PROCESS | 6448 | |i|pagename|runs| 02:28:44 INFO - PROCESS | 6448 | |0;dojo.html;2984.1942675477635;3268.3463307338525;3297.521859330397;3323.2854936188714;3331.883384081558;5623.634463759858;5651.745671444784;5692.203091567184;5700;5737.7991770288045;5620.747066382012;5663.575231857607;5686.488216060554;5697.287839020122;5745.080787074069;5149.897002059956;5168.5606723428555;5172.922406163911;5194.220866869962;5214.869628259305;4514.593686568208;5241.973790131063;5243.764030618631;5252.212168174766;5310.548603368718;5100.591952643779;5220.347456567929;5231.607629427789;5246.186841039645;5262.578993680516;5113.51421614946;5192.766325515323;5205.037068142406;5216.634835561512;5331.920021199685;5090.847274581768;5189.948100518985;5204.661696989683;5205.9219111713355;5280.49835265653;5625.887482250373;5677.17680936262;5739.7991070312755;5742.856428589277;5765.942340576583;5964.701764911781;6026.487748541379;6118.265808103011;6136.570440069197;6145.078238264251;6232.719527621268;6248.687565621701;6255.343188965138;6261.46777523911;6270.372962703739;5718;5749.252597162342;5757.942420575782;5760.36635970043;5787.189793428924;6155.692215389213;6194.4115309045565;6201.720922558496;6238.937610623926;6250.9062364064575;6024.668643224577;6064.272287325461;6075.088736689444;6078.726457309432;6098.359672234352;6119.265688117365;6184.721687524072;6199.473044791243;6206.379362063848;6222.191115155001;5968.612040217459;6025.78909738157;6071.544634152457;6163.075538669235;6176.258848938156;5192.9740351298005;5279.868003299948;5383.084875571162;6050.092486127028;6238.563300568917;4932.630052746058;4942.481039490874;4944.060628480577;4971.1549036663855;4979.57673597741;3596.6583174598372;3765.792881391495;3985.6412922837085;4435.667324950641;4474.955250447519;4620.676552641283;5768.884622307531;5798.9420105799245;5806.738696758656;5892.469677729025;6144.5084393248435;6168.290646575611;6190.288116866528;6236.968815155895;6248.218972628421;5614.494695477427;5704.914426283609;5775.6823374714795;5806.187133801272;5869.2956845178;5450.264214331015;5455.61810673257;5462.153366228241;5467.1799230115;5498.80754173602;6247.593906395979;6266.122742816101;6267.341929097471;6271.247450305992;6394.104825324458;5440.775825439245;5971.223740913653;6207.6275423474735;6232.439080482778;6234.314225435108;6183.103449999815;6185.628862268279;6198.504119670506;6237.12680224778;6264.248290205204;6112.419320164755;6177.19696439478;6186.783462578788;6191.349908259748;6209.689515524296;5187.688738675692;6050.606710563799;6113.388661133851;6197.938020619736;6241.78153764616;5964.194833697396;6020.307664618625;6025.78909738157;6046.879062418815;6142.140100386039;6084.60450070744;6085.2393450818645;6098.75604975796;6099.146119543356;6113.755449781959;5736.885262294814;5865.178874957424;5875.882482350415;5930.7924222652;5980.730867111078;5843.415658434293;5957.642541447527;6001.969990150022;6006.849828754316;6041.577089603686;5869.354371019099;5899.085641725539;5912.674802885883;5915.112733090071;5924.822255332347 02:28:44 INFO - PROCESS | 6448 | |1;ext.html;438.132497654644;453.5170043903243;497.30874085021804;499.3433634770277;506.9430237953867;40339.596604033984;43320.78339608302;43535.12929741403;43588.78205608975;43710.56289437105;44525.77737111318;44925.7753711231;44934.32598511025;45022.549774502324;45048.32427513582;9166.954165229197;9188.081191880792;9413.435193888354;9413.623455061823;9435.811283774321;8659.870101948476;10899.891001090007;11175.994160525552;11188.776224475505;11203.887961120367;11106.111511079094;11116.999470047695;11130.109591232682;11204.495797689124;11219.990200881919;11119.499622517005;11209.159313051554;11224.607138750105;11227.27022743519;11236.887631123665;9484.95257523708;10881.510332035077;10930.617428390047;10941.617043403443;10947.507362168642;38304.42543361852;38351.61648383508;38400.42399364012;38456;38654.613453865386;3936.665383442411;3990.5484392488497;4039.4950631171105;4053.1893621275863;4101.651359634405;27978.440431191262;28710.425791484264;28767;30035.399292014255;30214.848925755232;2816.4930312543734;3171.6986886246;3263.869445222212;3290.4899740540254;3402.0474267205223;516549;519245;521441;524166;525315;56769.580760480494;57013.71493142591;57093;57473.712631436574;57618.711906441036;51891.22163167556;59293.70353148207;59435.51411214668;59685.104723429184;59832.40167598355;14177.929110354382;15623.062616243062;15631.921840390953;16092.517224483285;16123.596910077107;11255;11472.426378681033;12050.096242781827;12302.630921072383;12512.186707863959;18070.457886263433;18113.728294075598;18153.909230454028;18173.63652726938;18512.44462666122;7421.666025028887;7587.127480339721;7632.855071739284;7662.8084297893;7730.690772369155;894.673444192865;905.2848249882651;905.8777065096259;918.9448633082036;938.7090002099371;31093.53359699606;31241.843790780902;31735;32182.67817321797;32717.67282327194;911.3210658059788;960.644561512231;1075.0808059109506;1128.441421496348;1240.3612139748036;15564.844351556567;16227.594310142342;16513.6697266054;16576.00543967366;16682.249298781582;649344;652568;653209;654457;655289.7235513792;64320;64765;64875.702485950016;64907;64979.67510162419;1423.978640320396;1456.3592019511593;1474.5576327102042;1474.8672619464085;1475.9852401476062;27273.86363068172;29358.266043349086;31275.374492510477;31692.841535792173;31876.840615796773;54414.911701766534;54948.45051549433;55328.17007744806;55347;55483.72258138845;465269.3473065226;466272;468418;468512.6574367106;469465;25253.873730631964;26043.609345859448;26118;26433.33916652099;27091.1872643821;44039.339409908236;44083;44755.55244447644;44775.32837007513;45402.77298613618;35551;36109.63890361063;36208.456873146395;36256.27487450183;36462.453063203546;36007.44597190813;36325.818370907975;37433.25133497369;37512.87461376163;37712.43431348587;35251;35505.93482195538;35983.64016359803;36314.092147696516;36366.72716454911;28325.716742833134;30795.384092318476;32336.67663323337;32453;32556;24298.75701242965;24630.753692462848;24647.260582182564;24654.630180547665;25144.62283065719 02:28:44 INFO - PROCESS | 6448 | |2;jquery.html;383.82727772502375;386.3104358719686;388.58227405539054;397.13424734079706;417.8767263657221;16109.952853064542;19968.301109461172;20743.273985410513;20978;20999.055042523087;19673.803261967376;19931.800681993176;21251.6812247816;21268.255611053617;21422.57154856902;5542.61201715879;5929.495992840613;5955.672438015908;5996.760129594811;5999.85000374992;5644.717764111778;5845.298564172305;5896.174535565025;5923.259592550931;5964.850878728045;5912.231409916705;5930.584859059867;5950.077737950603;5956.70216489176;5996;5077.949220507785;5750.539956803445;5819.068948968166;5845.824625261249;5925.288965324167;5793.391693872167;5806;5810.535157187415;5816.796412125597;5821.359650438449;12925.418356173996;12933.482660693655;13137.27744974026;13239.073264871462;13247;2660.414708764069;2704.4050308932005;2733.7129601391766;2829.688734239232;2960.171152077422;9567.808643827084;9598.808023839483;9748.26887983404;9995.25035622324;10074.294799364048;1633.9101349425778;1832.3495159218419;1894.8768330058501;1904.5905130396893;1915.6839121544929;127400;134167;136568.31715841359;143926.56073439238;145134.27432862873;10039.498025098861;10161.237907156992;11939.104567157498;12001.63995080149;12191.756164876653;13553.119047261896;13572.1856688599;13684.247366394744;14489.130652160904;15732.134732589588;7961.721339753197;8179;8368.163183681583;8432.65077587583;8768.956155219183;8021.919780802234;8028.598570071473;8075.475094118981;8638.740837774876;8794.560271986375;4613.5847773700525;4664.836730714476;4675.883102922386;4721.716696998191;4874.5856602189215;1926.1813729165049;1974;1984.9404517864486;2033.9389818305474;2047.0583531575344;889.1464194373474;889.6708217959267;917.6100157433065;919.9310051746146;945.1871390604213;18269.17788699485;21157;21537.56924861494;21732.891335543536;21770.78229217719;821;932.6875496708543;938.3244064273712;953.4136506048869;959.1128206409044;9234.492102934402;10778.622748203776;11023.283486573348;11039.503222354853;11062.778744425066;779178;779466;786839;789704;803185;150262;155170;155179;155928;159257;985.4678473624163;1031.6904928521415;1055.145332280855;1056.9365838049741;1057.4606950455322;16578.253978570752;19112.426627201206;19554.804451955868;19633.60732785365;19795.802041979972;63744.725105496706;64468;65205.67397162984;65867.68264634584;66161.67676646536;83363.33273334621;94679;94984.52507737417;98041;98511.01488985305;7294.416446684359;7540.509866858637;7558.58427786477;7565.243475652391;7928.524288542706;11383;13304.13523120994;13342.466301347837;13411.262380569162;13487.662808429868;9571.425714457155;11218;11300.547978081113;11408.714782130513;11732.589359372381;8718.476891386537;11049.005589496985;11096;11315.943420282845;11439.885601143882;11469.770604588028;11513.712157195801;11553.942230288794;11712.824307635221;11941.223820451643;7329.413646908341;7410.666520006721;7421.0723659542555;7581.431392645684;7979.321757650432;8014;10580.994805493467;11514.76970460603;11581.420928953687;11636.185467017227 02:28:44 INFO - PROCESS | 6448 | |3;mootools.html;1396.3088271305705;1418.8723014928655;1422.1680317014545;1435.2536680925912;1450.9274536273188;6050.60671056381;7796.142424333319;7872.409569282306;8054.114047454776;8504.574771261443;7827.882581761278;8089.393295502831;8108.229718176778;8183.6726530938695;8193.46742461741;1996.4908948218256;2115.851890367675;2170.934871953844;2276.647119696441;2277.8974946127464;1640.6882692288486;2141.9357419277367;2270.545890821834;2291.2667946257206;2406.470576473173;2200.3398980305924;2273.6816845641542;2281.0989659084653;2282.988585057072;2307.377008207783;1989.4031790462875;2289.919852805144;2310.8382413231166;2313.2597568777996;2314.710661167354;2282.098571064437;2289.4047547637733;2336.913335299094;2361.7992470639842;2365.935329101911;8423.15768423159;8444.57777111142;8478.74563763088;8479.321654267644;8488.278496327819;5043.495650434964;5165.302684137669;5194.974025129888;5207.297014902978;5215.269862219292;3230.5154226866157;3237.43344914639;3374.190194353362;3443.345764304774;3462.2556150427527;1205.427421974564;1218.4421489251408;1259.4710221706905;1265.9683507912284;1277.2400421749044;10711.83932241017;10802.081823044971;10833.566657333697;10835.729106772314;10860.891391086147;3951.0912490126857;4337.501187363494;4630.53694630541;4636.652251081115;4665.743384113838;9468.621255149717;9567.569459374345;9577.856332155023;9584.28117891161;9609.039096090335;5075.6193285502995;5190.6107041972;5195.9740201298755;5196.45437229093;5203.401608814959;4579.748113853703;4682.74244916526;4722.173619616553;4753.263244197085;4753.382060332135;3349.2464195555804;3880.8835734927998;3910.2179564087296;3965.167314863911;3982.7653427437585;1176.2942234659306;1228.9815652765217;1272.929988850622;1286.459686931491;1301.5509649171022;765.9846803064019;793.797581616684;800.5276886636796;826.2398593294183;852.9445586036887;4917.704937703749;5609.467100625434;5777.335606405233;5812.128180772918;5834.299884013945;884.5488800711684;976.4531862157214;1006.8238058339762;1007.4308015970953;1029.4389557691077;3405.8297085145646;3861;3867.8839634811;3947.940780888289;3964.60353964607;9524.428534287965;10010.299279050541;10065.547050382751;10067.144292735053;10112.241581881388;10053.195744340435;10059.346142500714;10078;10092.899071009342;10093.192544596415;7480.812979675552;7868.881966770503;7902.762917112495;7945.841083178304;8013.278804907703;5773.336066352424;5798.82603521895;5834;5874.060150375919;5878.353381127987;7780.377569794516;8944.552772361225;9087.818243635222;9107.453552786854;9403.388779729295;9114.954425227832;9261.814763704553;9342.906570934203;9348.906510934803;9394.295427843075;7011.193712723102;7085.468589855885;7123.572585644671;7136.571805691675;7177.784666460015;7792.259735325135;7922.841543169219;7936.246056624612;7954;7961.800954976172;7052.8589428210125;7169.713211471482;7172.13934327884;7190.245024272376;7308.04995350601;7093.467989900882;7127.358537731629;7151.42788576923;7158.749443769443;7190.568565885855;6374.426301632875;6380.7447702091395;6428.164338635947;6472.126262954441;6475.417212450714;4501.77491125449;5045.949540504548;5051.040302342543;5067.366579177603;5150.845474635767;5029.484041731154;5074.7716352764955;5091.930694554185;5093.108705976513;5182.714950677749 02:28:44 INFO - PROCESS | 6448 | |4;prototype.html;323.41946206559226;350.2277478160656;367.79771125880757;368.34250862210945;372.94965179700733;5307.973460132699;5311.203319502072;5320.973395133029;5382.300300960879;5409.323834520685;5122.436531981479;5257.526822585966;5335.173048177529;5336.17289320155;5347.893042139155;3821.73247872649;3925.3719404895223;4130.6988298685965;4181.623653871151;4187.413762073312;3305.933881322372;3439.982800085996;3736.2252929858373;3926.430667553203;4226.767527785971;3683.8710645127426;4130.876073717794;4210.51579068408;4213.936790948138;4216.831326746927;4210.32634778434;4218.5148707898525;4231.301835197189;4255.425517555122;4270.530241673413;3603.837827297778;3696.796676182809;3720.4977328060636;3724.42271447926;3748.850045998157;5123.666961647481;5157.8452646420665;5161.509656582619;5193.480651934777;5261.684298942076;4113.218488487208;4761.500212388489;4872.585830204437;4882.607522314041;4894.119058569456;5503.550919840673;5638.323401191883;5694.544436445074;5720.170575266563;5782.277215348082;3671.8714844980295;4135.338345864648;4153.439285696452;4162.313218318974;4170.645495132917;5937.33754548727;5953.226080609536;6037.486813620848;6045.430691955945;6049.606775559574;3598.1724203433355;3873.4189871519498;3890;4199.286121359315;4215.199112168678;3669.871474544758;4279.935800962988;4293.9603688247835;4298.76356800379;4313.439252897103;4343.869683909487;4725.9527404726205;4875.43932447773;4879.365682461328;4920.507949205051;4881.267809828554;5140.9361778648;5506;5709.3434255060365;5801.7099145042575;1385.1688986608156;1392.9651758706113;1400.1867581482006;1411.125102436496;1415.6956254405352;920.2683866326287;958.7219706285239;966.3766870368713;977.4428575711918;985.69936169469;4888.291197776302;5101.846944591668;5147.7683504242395;5188.610854185876;5188.740562971836;5535.750891209906;5675.5459563234845;5704.4295570443455;5722.599418040697;5884.675947911685;4878.975605121952;5000.199968005175;5374.355077390738;5455.5908306877145;5472.425395333512;2653.4162484253434;2764.087851009162;2823.9410221167063;2852.3867368515866;2897.0444949658895;4970.925436118461;5094.414142373675;5160.174372100521;5178.533931946143;5646.447227012604;5221.477852214748;5668.064769313017;5771.653700777967;5819.359870414251;5882.823515294548;5166.044281807877;5490.423505531861;5634.478690753555;5836.737346819404;5846.590738648254;4993.4257560379565;5180.7409629519125;5428.918566221589;5607.691576963306;5671.234383358194;5183.611229157904;5211.661242019257;5224.955008998139;5562.582806289463;5607.831765047055;5587.972060139836;5699.8860022800145;5820.70896455184;5831.329397119216;5863.853403664943;3706.5552133744122;3944.684425246031;3989.900252493711;3990.9822995136055;4022.5172979241506;4382.049987755418;4830.565249127595;4989.426215985063;5009.974950125226;5831.067029275296;4712.505186955457;4932.854208419349;5474.959757646035;5609;5696.11710184922;4832.710037397767;5682.715864206856;5694.202811606296;5718.571107166896;5726.599138060295;5356.384015838073;5383.380911195262;5537.612367134262;5565.526930210977;5583.916241256303;5369.865753356197;5646.971765141148;5679.318481782213;5686.459786320293;5754.424557544212;5004.224345226496;5260;5405.86485337854;5482.369527504388;5581.6371935824045 02:28:44 INFO - PROCESS | 6448 | |5;yui.html;667.526056499885;719.5646633786562;766.1074847802307;823.1439303124749;832.8209434971479;14751.262436878153;15678.059316440993;15803.683926321466;15805.683886322267;15893;11771.176017678745;14197.432102715904;14211.934104942118;14361.210133442657;14365.856341436609;608.9191684759543;678.8065401360614;832.2135581875131;857.8112815180666;869.1308691308691;669.6919417068154;754.7547047209652;815.2255357410454;877.5436772878099;881.532787622561;802.2378740196807;931.7624005878491;934.0286102453474;940.5203346293371;942.5428667096487;908.7092130518237;935.1256575102301;936.8875734911786;937;961.5528779117674;541.4991133201772;652.2010537092025;674.0765151742096;692.6848284030754;695.6035060015793;15010.624734381729;15054.924725376302;15391.076535407912;15697.058176509447;15841.603959901095;14557.490487832982;14576;14779.926100369537;14956.102633842005;14956.925215373854;11693.766124677544;11834.940825295818;11868.703282417922;11873.287602743776;12142.150049496538;3744.2885851688093;3773.2264885698364;3782.224643947984;3810.4474851146706;3829.942550861739;100486.99513004922;102658.48670756599;104475;106948.93051069467;108915;9277.536123193948;10148.492575371201;11028.558857645765;11422.94288528552;11714.414279286002;21404.464888377915;21465.141394344362;21655.891720541298;21762.673559896615;21781.346559603237;7605.125410577743;7663.945483068425;7672.808179795438;7711.807204819925;7772.222777722295;4278.55075217104;4319.049809042006;4368.650507959355;4395.582419670126;4422.226110430662;3360.2103505676146;3413.7951722896705;3419.9316013679586;3451.11996440911;3473.6700013498676;623.607127509675;623.8253289078974;627.97802076928;630.149298447102;640.60282624772;631.8325643704422;634.5621521150391;648.3225029843825;653.9444147247538;677.1569396101885;9640.855387169198;9902.504874756234;9941.701748947544;9991.450470224063;9996.300258981946;637.6301744987896;683.8153698501476;687.8624275144991;689.3933338661946;700.3626699703245;4019.678425725935;4439.733615983051;4751.881202969884;4752.310914917318;4832.830850920201;94679.05320946702;100718.48203794964;106642.46678766556;109609.06759195855;112143;78583.60708196537;78655.60672196717;82677;87258.56370718233;94668;70706;72334;72733.90899136517;73519.26480735232;76048.61975690197;10726.517306721373;10850.620228291971;11020.393878336768;11223.66329010131;11306.265092768943;45901.54098458973;46810;51029;51866.44400667835;53075;67207.32792672206;67640;70030.93097744952;70525.94211086941;70740.64629676819;13019.674508137372;13128.60614181576;13151.0136739743;13200.33998300062;13460.46158153702;14263.572092837232;14299.21354325522;14443.988920775444;14606.488772892897;14718.55844324672;13068.150570212905;13414.731705366034;13685.452581896612;13754.724905502035;14097.929510352793;11684.415779211175;12883.098183127091;13429.731405371642;14152;14160.291985400483;11560.94219528897;12990.675233118869;13551;13755;14098.721816449764;10701.250912436366;10936.179786516203;10963.342199468058;11344.092472602337;11505.309681419141;8826.5586720665;11065.889341106807;11083.72290692739;11086.168537359568;11113.944430277797 02:28:44 INFO - PROCESS | 6448 | __end_tp_report 02:28:44 INFO - PROCESS | 6448 | __start_cc_report 02:28:44 INFO - PROCESS | 6448 | _x_x_mozilla_cycle_collect,380 02:28:44 INFO - PROCESS | 6448 | __end_cc_report 02:28:44 INFO - PROCESS | 6448 | __startTimestamp1485944924527__endTimestamp 02:28:44 INFO - PROCESS | 6448 | 02:28:44 INFO - PROCESS | 6448 | ------- Summary: start ------- 02:28:44 INFO - PROCESS | 6448 | Number of tests: 6 02:28:44 INFO - PROCESS | 6448 | 02:28:44 INFO - PROCESS | 6448 | [#0] dojo.html Cycles:165 Average:5667.58 Median:5869.35 stddev:666.37 (11.4%) stddev-sans-first:634.29 02:28:44 INFO - PROCESS | 6448 | Values: 2984.2 3268.3 3297.5 3323.3 3331.9 5623.6 5651.7 5692.2 5700.0 5737.8 5620.7 5663.6 5686.5 5697.3 5745.1 5149.9 5168.6 5172.9 5194.2 5214.9 4514.6 5242.0 5243.8 5252.2 5310.5 5100.6 5220.3 5231.6 5246.2 5262.6 5113.5 5192.8 5205.0 5216.6 5331.9 5090.8 5189.9 5204.7 5205.9 5280.5 5625.9 5677.2 5739.8 5742.9 5765.9 5964.7 6026.5 6118.3 6136.6 6145.1 6232.7 6248.7 6255.3 6261.5 6270.4 5718.0 5749.3 5757.9 5760.4 5787.2 6155.7 6194.4 6201.7 6238.9 6250.9 6024.7 6064.3 6075.1 6078.7 6098.4 6119.3 6184.7 6199.5 6206.4 6222.2 5968.6 6025.8 6071.5 6163.1 6176.3 5193.0 5279.9 5383.1 6050.1 6238.6 4932.6 4942.5 4944.1 4971.2 4979.6 3596.7 3765.8 3985.6 4435.7 4475.0 4620.7 5768.9 5798.9 5806.7 5892.5 6144.5 6168.3 6190.3 6237.0 6248.2 5614.5 5704.9 5775.7 5806.2 5869.3 5450.3 5455.6 5462.2 5467.2 5498.8 6247.6 6266.1 6267.3 6271.2 6394.1 5440.8 5971.2 6207.6 6232.4 6234.3 6183.1 6185.6 6198.5 6237.1 6264.2 6112.4 6177.2 6186.8 6191.3 6209.7 5187.7 6050.6 6113.4 6197.9 6241.8 5964.2 6020.3 6025.8 6046.9 6142.1 6084.6 6085.2 6098.8 6099.1 6113.8 5736.9 5865.2 5875.9 5930.8 5980.7 5843.4 5957.6 6002.0 6006.8 6041.6 5869.4 5899.1 5912.7 5915.1 5924.8 02:28:44 INFO - PROCESS | 6448 | 02:28:44 INFO - PROCESS | 6448 | [#1] ext.html Cycles:180 Average:68440.83 Median:36346.27 stddev:147612.15 (406.1%) stddev-sans-first:147937.94 02:28:44 INFO - PROCESS | 6448 | Values: 438.1 453.5 497.3 499.3 506.9 40339.6 43320.8 43535.1 43588.8 43710.6 44525.8 44925.8 44934.3 45022.5 45048.3 9167.0 9188.1 9413.4 9413.6 9435.8 8659.9 10899.9 11176.0 11188.8 11203.9 11106.1 11117.0 11130.1 11204.5 11220.0 11119.5 11209.2 11224.6 11227.3 11236.9 9485.0 10881.5 10930.6 10941.6 10947.5 38304.4 38351.6 38400.4 38456.0 38654.6 3936.7 3990.5 4039.5 4053.2 4101.7 27978.4 28710.4 28767.0 30035.4 30214.8 2816.5 3171.7 3263.9 3290.5 3402.0 516549.0 519245.0 521441.0 524166.0 525315.0 56769.6 57013.7 57093.0 57473.7 57618.7 51891.2 59293.7 59435.5 59685.1 59832.4 14177.9 15623.1 15631.9 16092.5 16123.6 11255.0 11472.4 12050.1 12302.6 12512.2 18070.5 18113.7 18153.9 18173.6 18512.4 7421.7 7587.1 7632.9 7662.8 7730.7 894.7 905.3 905.9 918.9 938.7 31093.5 31241.8 31735.0 32182.7 32717.7 911.3 960.6 1075.1 1128.4 1240.4 15564.8 16227.6 16513.7 16576.0 16682.2 649344.0 652568.0 653209.0 654457.0 655289.7 64320.0 64765.0 64875.7 64907.0 64979.7 1424.0 1456.4 1474.6 1474.9 1476.0 27273.9 29358.3 31275.4 31692.8 31876.8 54414.9 54948.5 55328.2 55347.0 55483.7 465269.3 466272.0 468418.0 468512.7 469465.0 25253.9 26043.6 26118.0 26433.3 27091.2 44039.3 44083.0 44755.6 44775.3 45402.8 35551.0 36109.6 36208.5 36256.3 36462.5 36007.4 36325.8 37433.3 37512.9 37712.4 35251.0 35505.9 35983.6 36314.1 36366.7 28325.7 30795.4 32336.7 32453.0 32556.0 24298.8 24630.8 24647.3 24654.6 25144.6 02:28:44 INFO - PROCESS | 6448 | 02:28:44 INFO - PROCESS | 6448 | [#2] jquery.html Cycles:180 Average:41976.95 Median:1672.00 stddev:131241.65 (7849.4%) stddev-sans-first:131572.65 02:28:44 INFO - PROCESS | 6448 | Values: 383.8 386.3 388.6 397.1 417.9 16110.0 19968.3 20743.3 20978.0 20999.1 19673.8 19931.8 21251.7 21268.3 21422.6 5542.6 5929.5 5955.7 5996.8 5999.9 5644.7 5845.3 5896.2 5923.3 5964.9 5912.2 5930.6 5950.1 5956.7 5996.0 5077.9 5750.5 5819.1 5845.8 5925.3 5793.4 5806.0 5810.5 5816.8 5821.4 12925.4 12933.5 13137.3 13239.1 13247.0 2660.4 2704.4 2733.7 2829.7 2960.2 9567.8 9598.8 9748.3 9995.3 10074.3 1633.9 1832.3 1894.9 1904.6 1915.7 127400.0 134167.0 136568.3 143926.6 145134.3 10039.5 10161.2 11939.1 12001.6 12191.8 13553.1 13572.2 13684.2 14489.1 15732.1 7961.7 8179.0 8368.2 8432.7 8769.0 8021.9 8028.6 8075.5 8638.7 8794.6 4613.6 4664.8 4675.9 4721.7 4874.6 1926.2 1974.0 1984.9 2033.9 2047.1 889.1 889.7 917.6 919.9 945.2 18269.2 21157.0 21537.6 21732.9 21770.8 821.0 932.7 938.3 953.4 959.1 9234.5 10778.6 11023.3 11039.5 11062.8 779178.0 779466.0 786839.0 789704.0 803185.0 150262.0 155170.0 155179.0 155928.0 159257.0 985.5 1031.7 1055.1 1056.9 1057.5 16578.3 19112.4 19554.8 19633.6 19795.8 63744.7 64468.0 65205.7 65867.7 66161.7 83363.3 94679.0 94984.5 98041.0 98511.0 7294.4 7540.5 7558.6 7565.2 7928.5 11383.0 13304.1 13342.5 13411.3 13487.7 9571.4 11218.0 11300.5 11408.7 11732.6 8718.5 11049.0 11096.0 11315.9 11439.9 11469.8 11513.7 11553.9 11712.8 11941.2 7329.4 7410.7 7421.1 7581.4 7979.3 8014.0 10581.0 11514.8 11581.4 11636.2 02:28:44 INFO - PROCESS | 6448 | 02:28:44 INFO - PROCESS | 6448 | [#3] mootools.html Cycles:180 Average:5421.55 Median:5083.78 stddev:2963.56 (58.3%) stddev-sans-first:2956.43 02:28:44 INFO - PROCESS | 6448 | Values: 1396.3 1418.9 1422.2 1435.3 1450.9 6050.6 7796.1 7872.4 8054.1 8504.6 7827.9 8089.4 8108.2 8183.7 8193.5 1996.5 2115.9 2170.9 2276.6 2277.9 1640.7 2141.9 2270.5 2291.3 2406.5 2200.3 2273.7 2281.1 2283.0 2307.4 1989.4 2289.9 2310.8 2313.3 2314.7 2282.1 2289.4 2336.9 2361.8 2365.9 8423.2 8444.6 8478.7 8479.3 8488.3 5043.5 5165.3 5195.0 5207.3 5215.3 3230.5 3237.4 3374.2 3443.3 3462.3 1205.4 1218.4 1259.5 1266.0 1277.2 10711.8 10802.1 10833.6 10835.7 10860.9 3951.1 4337.5 4630.5 4636.7 4665.7 9468.6 9567.6 9577.9 9584.3 9609.0 5075.6 5190.6 5196.0 5196.5 5203.4 4579.7 4682.7 4722.2 4753.3 4753.4 3349.2 3880.9 3910.2 3965.2 3982.8 1176.3 1229.0 1272.9 1286.5 1301.6 766.0 793.8 800.5 826.2 852.9 4917.7 5609.5 5777.3 5812.1 5834.3 884.5 976.5 1006.8 1007.4 1029.4 3405.8 3861.0 3867.9 3947.9 3964.6 9524.4 10010.3 10065.5 10067.1 10112.2 10053.2 10059.3 10078.0 10092.9 10093.2 7480.8 7868.9 7902.8 7945.8 8013.3 5773.3 5798.8 5834.0 5874.1 5878.4 7780.4 8944.6 9087.8 9107.5 9403.4 9115.0 9261.8 9342.9 9348.9 9394.3 7011.2 7085.5 7123.6 7136.6 7177.8 7792.3 7922.8 7936.2 7954.0 7961.8 7052.9 7169.7 7172.1 7190.2 7308.0 7093.5 7127.4 7151.4 7158.7 7190.6 6374.4 6380.7 6428.2 6472.1 6475.4 4501.8 5045.9 5051.0 5067.4 5150.8 5029.5 5074.8 5091.9 5093.1 5182.7 02:28:44 INFO - PROCESS | 6448 | 02:28:44 INFO - PROCESS | 6448 | [#4] prototype.html Cycles:180 Average:4585.51 Median:5159.01 stddev:1356.31 (26.3%) stddev-sans-first:1321.85 02:28:44 INFO - PROCESS | 6448 | Values: 323.4 350.2 367.8 368.3 372.9 5308.0 5311.2 5321.0 5382.3 5409.3 5122.4 5257.5 5335.2 5336.2 5347.9 3821.7 3925.4 4130.7 4181.6 4187.4 3305.9 3440.0 3736.2 3926.4 4226.8 3683.9 4130.9 4210.5 4213.9 4216.8 4210.3 4218.5 4231.3 4255.4 4270.5 3603.8 3696.8 3720.5 3724.4 3748.9 5123.7 5157.8 5161.5 5193.5 5261.7 4113.2 4761.5 4872.6 4882.6 4894.1 5503.6 5638.3 5694.5 5720.2 5782.3 3671.9 4135.3 4153.4 4162.3 4170.6 5937.3 5953.2 6037.5 6045.4 6049.6 3598.2 3873.4 3890.0 4199.3 4215.2 3669.9 4279.9 4294.0 4298.8 4313.4 4343.9 4726.0 4875.4 4879.4 4920.5 4881.3 5140.9 5506.0 5709.3 5801.7 1385.2 1393.0 1400.2 1411.1 1415.7 920.3 958.7 966.4 977.4 985.7 4888.3 5101.8 5147.8 5188.6 5188.7 5535.8 5675.5 5704.4 5722.6 5884.7 4879.0 5000.2 5374.4 5455.6 5472.4 2653.4 2764.1 2823.9 2852.4 2897.0 4970.9 5094.4 5160.2 5178.5 5646.4 5221.5 5668.1 5771.7 5819.4 5882.8 5166.0 5490.4 5634.5 5836.7 5846.6 4993.4 5180.7 5428.9 5607.7 5671.2 5183.6 5211.7 5225.0 5562.6 5607.8 5588.0 5699.9 5820.7 5831.3 5863.9 3706.6 3944.7 3989.9 3991.0 4022.5 4382.0 4830.6 4989.4 5010.0 5831.1 4712.5 4932.9 5475.0 5609.0 5696.1 4832.7 5682.7 5694.2 5718.6 5726.6 5356.4 5383.4 5537.6 5565.5 5583.9 5369.9 5647.0 5679.3 5686.5 5754.4 5004.2 5260.0 5405.9 5482.4 5581.6 02:28:44 INFO - PROCESS | 6448 | 02:28:44 INFO - PROCESS | 6448 | [#5] yui.html Cycles:180 Average:20293.48 Median:3820.20 stddev:28973.46 (758.4%) stddev-sans-first:29017.26 02:28:44 INFO - PROCESS | 6448 | Values: 667.5 719.6 766.1 823.1 832.8 14751.3 15678.1 15803.7 15805.7 15893.0 11771.2 14197.4 14211.9 14361.2 14365.9 608.9 678.8 832.2 857.8 869.1 669.7 754.8 815.2 877.5 881.5 802.2 931.8 934.0 940.5 942.5 908.7 935.1 936.9 937.0 961.6 541.5 652.2 674.1 692.7 695.6 15010.6 15054.9 15391.1 15697.1 15841.6 14557.5 14576.0 14779.9 14956.1 14956.9 11693.8 11834.9 11868.7 11873.3 12142.2 3744.3 3773.2 3782.2 3810.4 3829.9 100487.0 102658.5 104475.0 106948.9 108915.0 9277.5 10148.5 11028.6 11422.9 11714.4 21404.5 21465.1 21655.9 21762.7 21781.3 7605.1 7663.9 7672.8 7711.8 7772.2 4278.6 4319.0 4368.7 4395.6 4422.2 3360.2 3413.8 3419.9 3451.1 3473.7 623.6 623.8 628.0 630.1 640.6 631.8 634.6 648.3 653.9 677.2 9640.9 9902.5 9941.7 9991.5 9996.3 637.6 683.8 687.9 689.4 700.4 4019.7 4439.7 4751.9 4752.3 4832.8 94679.1 100718.5 106642.5 109609.1 112143.0 78583.6 78655.6 82677.0 87258.6 94668.0 70706.0 72334.0 72733.9 73519.3 76048.6 10726.5 10850.6 11020.4 11223.7 11306.3 45901.5 46810.0 51029.0 51866.4 53075.0 67207.3 67640.0 70030.9 70525.9 70740.6 13019.7 13128.6 13151.0 13200.3 13460.5 14263.6 14299.2 14444.0 14606.5 14718.6 13068.2 13414.7 13685.5 13754.7 14097.9 11684.4 12883.1 13429.7 14152.0 14160.3 11560.9 12990.7 13551.0 13755.0 14098.7 10701.3 10936.2 10963.3 11344.1 11505.3 8826.6 11065.9 11083.7 11086.2 11113.9 02:28:44 INFO - PROCESS | 6448 | -------- Summary: end -------- 02:28:44 INFO - PROCESS | 6448 | 02:28:45 INFO - TEST-INFO | 6448: exit 0 02:28:45 INFO - TEST-OK | dromaeo_css | took 1114803ms 02:28:45 INFO - TEST-START | kraken 02:28:45 INFO - operating with platform_type : mac_ 02:28:45 INFO - Initialising browser for kraken test... 02:28:45 INFO - TEST-INFO | started process 6452 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpYC8ONJ/profile http://localhost:56768/getInfo.html) 02:28:48 INFO - PROCESS | 6452 | 2017-02-01 02:28:48.237 plugin-container[6454:123995] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 02:28:48 INFO - PROCESS | 6452 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:48 INFO - PROCESS | 6452 | 2017-02-01 02:28:48.238 plugin-container[6454:123995] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4527, name = 'com.apple.CFPasteboardClient' 02:28:48 INFO - PROCESS | 6452 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:48 INFO - PROCESS | 6452 | 2017-02-01 02:28:48.238 plugin-container[6454:123995] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:28:48 INFO - PROCESS | 6452 | __metrics Screen width/height:1600/1200 02:28:48 INFO - PROCESS | 6452 | colorDepth:24 02:28:48 INFO - PROCESS | 6452 | Browser inner width/height: 1024/689 02:28:48 INFO - PROCESS | 6452 | __metrics 02:28:48 ERROR - PROCESS | 6452 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 02:28:48 INFO - TEST-INFO | 6452: exit 0 02:28:48 INFO - Browser initialized. 02:28:48 INFO - Running cycle 1/1 for kraken test... 02:28:48 INFO - TEST-INFO | started process 6455 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpYC8ONJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:28:51 INFO - PROCESS | 6455 | 2017-02-01 02:28:51.635 plugin-container[6456:124122] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4f, name = 'com.apple.tsm.portname' 02:28:51 INFO - PROCESS | 6455 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:51 INFO - PROCESS | 6455 | 2017-02-01 02:28:51.636 plugin-container[6456:124122] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x440b, name = 'com.apple.CFPasteboardClient' 02:28:51 INFO - PROCESS | 6455 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:51 INFO - PROCESS | 6455 | 2017-02-01 02:28:51.636 plugin-container[6456:124122] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:29:01 INFO - PROCESS | 6455 | RSS: Main: 219131904 02:29:01 INFO - PROCESS | 6455 | 02:29:04 INFO - PROCESS | 6455 | Cycle 1(1): loaded http://localhost:56768/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56768/tests/kraken/driver.html?testName=audio-beat-detection) 02:29:05 INFO - PROCESS | 6455 | RSS: Main: 233103360 02:29:05 INFO - PROCESS | 6455 | 02:29:07 INFO - PROCESS | 6455 | Cycle 1(1): loaded http://localhost:56768/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56768/tests/kraken/driver.html?testName=audio-dft) 02:29:08 INFO - PROCESS | 6455 | RSS: Main: 240947200 02:29:08 INFO - PROCESS | 6455 | 02:29:11 INFO - PROCESS | 6455 | Cycle 1(1): loaded http://localhost:56768/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56768/tests/kraken/driver.html?testName=audio-fft) 02:29:12 INFO - PROCESS | 6455 | RSS: Main: 242483200 02:29:12 INFO - PROCESS | 6455 | 02:29:14 INFO - PROCESS | 6455 | Cycle 1(1): loaded http://localhost:56768/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56768/tests/kraken/driver.html?testName=audio-oscillator) 02:29:15 INFO - PROCESS | 6455 | RSS: Main: 244207616 02:29:15 INFO - PROCESS | 6455 | 02:29:17 INFO - PROCESS | 6455 | Cycle 1(1): loaded http://localhost:56768/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56768/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:29:17 INFO - PROCESS | 6455 | RSS: Main: 245071872 02:29:17 INFO - PROCESS | 6455 | 02:29:20 INFO - PROCESS | 6455 | Cycle 1(1): loaded http://localhost:56768/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56768/tests/kraken/driver.html?testName=imaging-darkroom) 02:29:21 INFO - PROCESS | 6455 | RSS: Main: 248967168 02:29:21 INFO - PROCESS | 6455 | 02:29:24 INFO - PROCESS | 6455 | Cycle 1(1): loaded http://localhost:56768/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56768/tests/kraken/driver.html?testName=imaging-desaturate) 02:29:25 INFO - PROCESS | 6455 | RSS: Main: 248160256 02:29:25 INFO - PROCESS | 6455 | 02:29:28 INFO - PROCESS | 6455 | Cycle 1(1): loaded http://localhost:56768/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56768/tests/kraken/driver.html?testName=json-parse-financial) 02:29:28 INFO - PROCESS | 6455 | RSS: Main: 249266176 02:29:28 INFO - PROCESS | 6455 | 02:29:30 INFO - PROCESS | 6455 | Cycle 1(1): loaded http://localhost:56768/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56768/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:29:30 INFO - PROCESS | 6455 | RSS: Main: 248754176 02:29:30 INFO - PROCESS | 6455 | 02:29:32 INFO - PROCESS | 6455 | Cycle 1(1): loaded http://localhost:56768/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56768/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:29:33 INFO - PROCESS | 6455 | RSS: Main: 248795136 02:29:33 INFO - PROCESS | 6455 | 02:29:35 INFO - PROCESS | 6455 | Cycle 1(1): loaded http://localhost:56768/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56768/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:29:36 INFO - PROCESS | 6455 | RSS: Main: 248872960 02:29:36 INFO - PROCESS | 6455 | 02:29:39 INFO - PROCESS | 6455 | Cycle 1(1): loaded http://localhost:56768/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56768/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:29:40 INFO - PROCESS | 6455 | RSS: Main: 249200640 02:29:40 INFO - PROCESS | 6455 | 02:29:43 INFO - PROCESS | 6455 | Cycle 1(1): loaded http://localhost:56768/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56768/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:29:43 INFO - PROCESS | 6455 | RSS: Main: 249004032 02:29:43 INFO - PROCESS | 6455 | 02:29:45 INFO - PROCESS | 6455 | Cycle 1(1): loaded http://localhost:56768/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56768/tests/kraken/driver.html?testName=ai-astar) 02:29:45 INFO - PROCESS | 6455 | RSS: Main: 250986496 02:29:45 INFO - PROCESS | 6455 | 02:29:45 INFO - PROCESS | 6455 | __start_tp_report 02:29:45 INFO - PROCESS | 6455 | _x_x_mozilla_page_load 02:29:45 INFO - PROCESS | 6455 | _x_x_mozilla_page_load_details 02:29:45 INFO - PROCESS | 6455 | |i|pagename|runs| 02:29:45 INFO - PROCESS | 6455 | |0;ai-astar;106;119;118;120;118;119;119;119;119;121 02:29:45 INFO - PROCESS | 6455 | |1;audio-beat-detection;140;116;111;146;114;127;100;136;158;133 02:29:45 INFO - PROCESS | 6455 | |2;audio-dft;201;198;199;195;234;241;195;265;262;196 02:29:45 INFO - PROCESS | 6455 | |3;audio-fft;85;58;60;85;63;68;59;59;78;51 02:29:45 INFO - PROCESS | 6455 | |4;audio-oscillator;84;70;102;63;74;93;107;55;95;105 02:29:45 INFO - PROCESS | 6455 | |5;imaging-gaussian-blur;91;101;91;86;94;116;95;84;91;87 02:29:45 INFO - PROCESS | 6455 | |6;imaging-darkroom;124;97;97;97;98;112;146;113;96;104 02:29:45 INFO - PROCESS | 6455 | |7;imaging-desaturate;92;90;126;108;84;140;148;123;94;90 02:29:45 INFO - PROCESS | 6455 | |8;json-parse-financial;56;61;60;64;63;63;64;63;64;65 02:29:45 INFO - PROCESS | 6455 | |9;json-stringify-tinderbox;72;77;72;80;78;71;74;73;95;75 02:29:45 INFO - PROCESS | 6455 | |10;stanford-crypto-aes;97;98;111;101;86;82;87;96;80;101 02:29:45 INFO - PROCESS | 6455 | |11;stanford-crypto-ccm;142;141;139;142;139;185;141;146;211;174 02:29:45 INFO - PROCESS | 6455 | |12;stanford-crypto-pbkdf2;176;172;172;174;175;174;207;174;173;221 02:29:45 INFO - PROCESS | 6455 | |13;stanford-crypto-sha256-iterative;67;59;79;84;84;86;81;68;69;68 02:29:45 INFO - PROCESS | 6455 | __end_tp_report 02:29:45 INFO - PROCESS | 6455 | __start_cc_report 02:29:45 INFO - PROCESS | 6455 | _x_x_mozilla_cycle_collect,931 02:29:45 INFO - PROCESS | 6455 | __end_cc_report 02:29:45 INFO - PROCESS | 6455 | __startTimestamp1485944985835__endTimestamp 02:29:45 INFO - PROCESS | 6455 | 02:29:45 INFO - PROCESS | 6455 | ------- Summary: start ------- 02:29:45 INFO - PROCESS | 6455 | Number of tests: 14 02:29:45 INFO - PROCESS | 6455 | 02:29:45 INFO - PROCESS | 6455 | [#0] ai-astar Cycles:10 Average:117.80 Median:119.00 stddev:4.24 (3.6%) stddev-sans-first:0.93 02:29:45 INFO - PROCESS | 6455 | Values: 106.0 119.0 118.0 120.0 118.0 119.0 119.0 119.0 119.0 121.0 02:29:45 INFO - PROCESS | 6455 | 02:29:45 INFO - PROCESS | 6455 | [#1] audio-beat-detection Cycles:10 Average:128.10 Median:134.50 stddev:17.86 (13.3%) stddev-sans-first:18.42 02:29:45 INFO - PROCESS | 6455 | Values: 140.0 116.0 111.0 146.0 114.0 127.0 100.0 136.0 158.0 133.0 02:29:45 INFO - PROCESS | 6455 | 02:29:45 INFO - PROCESS | 6455 | [#2] audio-dft Cycles:10 Average:218.60 Median:217.50 stddev:28.90 (13.3%) stddev-sans-first:29.95 02:29:45 INFO - PROCESS | 6455 | Values: 201.0 198.0 199.0 195.0 234.0 241.0 195.0 265.0 262.0 196.0 02:29:45 INFO - PROCESS | 6455 | 02:29:45 INFO - PROCESS | 6455 | [#3] audio-fft Cycles:10 Average:66.60 Median:65.50 stddev:12.01 (18.3%) stddev-sans-first:10.74 02:29:45 INFO - PROCESS | 6455 | Values: 85.0 58.0 60.0 85.0 63.0 68.0 59.0 59.0 78.0 51.0 02:29:45 INFO - PROCESS | 6455 | 02:29:45 INFO - PROCESS | 6455 | [#4] audio-oscillator Cycles:10 Average:84.80 Median:72.00 stddev:18.46 (25.6%) stddev-sans-first:19.58 02:29:45 INFO - PROCESS | 6455 | Values: 84.0 70.0 102.0 63.0 74.0 93.0 107.0 55.0 95.0 105.0 02:29:45 INFO - PROCESS | 6455 | 02:29:45 INFO - PROCESS | 6455 | [#5] imaging-gaussian-blur Cycles:10 Average:93.60 Median:91.00 stddev:9.26 (10.2%) stddev-sans-first:9.78 02:29:45 INFO - PROCESS | 6455 | Values: 91.0 101.0 91.0 86.0 94.0 116.0 95.0 84.0 91.0 87.0 02:29:45 INFO - PROCESS | 6455 | 02:29:45 INFO - PROCESS | 6455 | [#6] imaging-darkroom Cycles:10 Average:108.40 Median:96.50 stddev:16.20 (16.8%) stddev-sans-first:16.17 02:29:45 INFO - PROCESS | 6455 | Values: 124.0 97.0 97.0 97.0 98.0 112.0 146.0 113.0 96.0 104.0 02:29:45 INFO - PROCESS | 6455 | 02:29:45 INFO - PROCESS | 6455 | [#7] imaging-desaturate Cycles:10 Average:109.50 Median:87.00 stddev:23.16 (26.6%) stddev-sans-first:23.68 02:29:45 INFO - PROCESS | 6455 | Values: 92.0 90.0 126.0 108.0 84.0 140.0 148.0 123.0 94.0 90.0 02:29:45 INFO - PROCESS | 6455 | 02:29:45 INFO - PROCESS | 6455 | [#8] json-parse-financial Cycles:10 Average:62.30 Median:63.50 stddev:2.67 (4.2%) stddev-sans-first:1.58 02:29:45 INFO - PROCESS | 6455 | Values: 56.0 61.0 60.0 64.0 63.0 63.0 64.0 63.0 64.0 65.0 02:29:45 INFO - PROCESS | 6455 | 02:29:45 INFO - PROCESS | 6455 | [#9] json-stringify-tinderbox Cycles:10 Average:76.70 Median:76.00 stddev:7.06 (9.3%) stddev-sans-first:7.28 02:29:45 INFO - PROCESS | 6455 | Values: 72.0 77.0 72.0 80.0 78.0 71.0 74.0 73.0 95.0 75.0 02:29:45 INFO - PROCESS | 6455 | 02:29:45 INFO - PROCESS | 6455 | [#10] stanford-crypto-aes Cycles:10 Average:93.90 Median:86.50 stddev:9.83 (11.4%) stddev-sans-first:10.36 02:29:45 INFO - PROCESS | 6455 | Values: 97.0 98.0 111.0 101.0 86.0 82.0 87.0 96.0 80.0 101.0 02:29:45 INFO - PROCESS | 6455 | 02:29:45 INFO - PROCESS | 6455 | [#11] stanford-crypto-ccm Cycles:10 Average:156.00 Median:144.00 stddev:25.19 (17.5%) stddev-sans-first:26.20 02:29:45 INFO - PROCESS | 6455 | Values: 142.0 141.0 139.0 142.0 139.0 185.0 141.0 146.0 211.0 174.0 02:29:45 INFO - PROCESS | 6455 | 02:29:45 INFO - PROCESS | 6455 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.80 Median:174.50 stddev:17.33 (9.9%) stddev-sans-first:18.26 02:29:45 INFO - PROCESS | 6455 | Values: 176.0 172.0 172.0 174.0 175.0 174.0 207.0 174.0 173.0 221.0 02:29:45 INFO - PROCESS | 6455 | 02:29:45 INFO - PROCESS | 6455 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.50 Median:80.00 stddev:9.35 (11.7%) stddev-sans-first:9.51 02:29:45 INFO - PROCESS | 6455 | Values: 67.0 59.0 79.0 84.0 84.0 86.0 81.0 68.0 69.0 68.0 02:29:45 INFO - PROCESS | 6455 | -------- Summary: end -------- 02:29:45 INFO - PROCESS | 6455 | 02:29:46 INFO - TEST-INFO | 6455: exit 0 02:29:46 INFO - TEST-OK | kraken | took 61526ms 02:29:46 INFO - SUITE-END | took 1176s 02:29:47 INFO - Completed test suite (00:19:36) 02:29:47 INFO - javascript benchmark 02:29:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7285.8433655515755, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2984.1942675477635, 3268.3463307338525, 3297.521859330397, 3323.2854936188714, 3331.883384081558, 5623.634463759858, 5651.745671444784, 5692.203091567184, 5700.0, 5737.7991770288045, 5620.747066382012, 5663.575231857607, 5686.488216060554, 5697.287839020122, 5745.080787074069, 5149.897002059956, 5168.5606723428555, 5172.922406163911, 5194.220866869962, 5214.869628259305, 4514.593686568208, 5241.973790131063, 5243.764030618631, 5252.212168174766, 5310.548603368718, 5100.591952643779, 5220.347456567929, 5231.607629427789, 5246.186841039645, 5262.578993680516, 5113.51421614946, 5192.766325515323, 5205.037068142406, 5216.634835561512, 5331.920021199685, 5090.847274581768, 5189.948100518985, 5204.661696989683, 5205.9219111713355, 5280.49835265653, 5625.887482250373, 5677.17680936262, 5739.7991070312755, 5742.856428589277, 5765.942340576583, 5964.701764911781, 6026.487748541379, 6118.265808103011, 6136.570440069197, 6145.078238264251, 6232.719527621268, 6248.687565621701, 6255.343188965138, 6261.46777523911, 6270.372962703739, 5718.0, 5749.252597162342, 5757.942420575782, 5760.36635970043, 5787.189793428924, 6155.692215389213, 6194.4115309045565, 6201.720922558496, 6238.937610623926, 6250.9062364064575, 6024.668643224577, 6064.272287325461, 6075.088736689444, 6078.726457309432, 6098.359672234352, 6119.265688117365, 6184.721687524072, 6199.473044791243, 6206.379362063848, 6222.191115155001, 5968.612040217459, 6025.78909738157, 6071.544634152457, 6163.075538669235, 6176.258848938156, 5192.9740351298005, 5279.868003299948, 5383.084875571162, 6050.092486127028, 6238.563300568917, 4932.630052746058, 4942.481039490874, 4944.060628480577, 4971.1549036663855, 4979.57673597741, 3596.6583174598372, 3765.792881391495, 3985.6412922837085, 4435.667324950641, 4474.955250447519, 4620.676552641283, 5768.884622307531, 5798.9420105799245, 5806.738696758656, 5892.469677729025, 6144.5084393248435, 6168.290646575611, 6190.288116866528, 6236.968815155895, 6248.218972628421, 5614.494695477427, 5704.914426283609, 5775.6823374714795, 5806.187133801272, 5869.2956845178, 5450.264214331015, 5455.61810673257, 5462.153366228241, 5467.1799230115, 5498.80754173602, 6247.593906395979, 6266.122742816101, 6267.341929097471, 6271.247450305992, 6394.104825324458, 5440.775825439245, 5971.223740913653, 6207.6275423474735, 6232.439080482778, 6234.314225435108, 6183.103449999815, 6185.628862268279, 6198.504119670506, 6237.12680224778, 6264.248290205204, 6112.419320164755, 6177.19696439478, 6186.783462578788, 6191.349908259748, 6209.689515524296, 5187.688738675692, 6050.606710563799, 6113.388661133851, 6197.938020619736, 6241.78153764616, 5964.194833697396, 6020.307664618625, 6025.78909738157, 6046.879062418815, 6142.140100386039, 6084.60450070744, 6085.2393450818645, 6098.75604975796, 6099.146119543356, 6113.755449781959, 5736.885262294814, 5865.178874957424, 5875.882482350415, 5930.7924222652, 5980.730867111078, 5843.415658434293, 5957.642541447527, 6001.969990150022, 6006.849828754316, 6041.577089603686, 5869.354371019099, 5899.085641725539, 5912.674802885883, 5915.112733090071, 5924.822255332347], "value": 5623.13717470573, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [438.132497654644, 453.5170043903243, 497.30874085021804, 499.3433634770277, 506.9430237953867, 40339.596604033984, 43320.78339608302, 43535.12929741403, 43588.78205608975, 43710.56289437105, 44525.77737111318, 44925.7753711231, 44934.32598511025, 45022.549774502324, 45048.32427513582, 9166.954165229197, 9188.081191880792, 9413.435193888354, 9413.623455061823, 9435.811283774321, 8659.870101948476, 10899.891001090007, 11175.994160525552, 11188.776224475505, 11203.887961120367, 11106.111511079094, 11116.999470047695, 11130.109591232682, 11204.495797689124, 11219.990200881919, 11119.499622517005, 11209.159313051554, 11224.607138750105, 11227.27022743519, 11236.887631123665, 9484.95257523708, 10881.510332035077, 10930.617428390047, 10941.617043403443, 10947.507362168642, 38304.42543361852, 38351.61648383508, 38400.42399364012, 38456.0, 38654.613453865386, 3936.665383442411, 3990.5484392488497, 4039.4950631171105, 4053.1893621275863, 4101.651359634405, 27978.440431191262, 28710.425791484264, 28767.0, 30035.399292014255, 30214.848925755232, 2816.4930312543734, 3171.6986886246, 3263.869445222212, 3290.4899740540254, 3402.0474267205223, 516549.0, 519245.0, 521441.0, 524166.0, 525315.0, 56769.580760480494, 57013.71493142591, 57093.0, 57473.712631436574, 57618.711906441036, 51891.22163167556, 59293.70353148207, 59435.51411214668, 59685.104723429184, 59832.40167598355, 14177.929110354382, 15623.062616243062, 15631.921840390953, 16092.517224483285, 16123.596910077107, 11255.0, 11472.426378681033, 12050.096242781827, 12302.630921072383, 12512.186707863959, 18070.457886263433, 18113.728294075598, 18153.909230454028, 18173.63652726938, 18512.44462666122, 7421.666025028887, 7587.127480339721, 7632.855071739284, 7662.8084297893, 7730.690772369155, 894.673444192865, 905.2848249882651, 905.8777065096259, 918.9448633082036, 938.7090002099371, 31093.53359699606, 31241.843790780902, 31735.0, 32182.67817321797, 32717.67282327194, 911.3210658059788, 960.644561512231, 1075.0808059109506, 1128.441421496348, 1240.3612139748036, 15564.844351556567, 16227.594310142342, 16513.6697266054, 16576.00543967366, 16682.249298781582, 649344.0, 652568.0, 653209.0, 654457.0, 655289.7235513792, 64320.0, 64765.0, 64875.702485950016, 64907.0, 64979.67510162419, 1423.978640320396, 1456.3592019511593, 1474.5576327102042, 1474.8672619464085, 1475.9852401476062, 27273.86363068172, 29358.266043349086, 31275.374492510477, 31692.841535792173, 31876.840615796773, 54414.911701766534, 54948.45051549433, 55328.17007744806, 55347.0, 55483.72258138845, 465269.3473065226, 466272.0, 468418.0, 468512.6574367106, 469465.0, 25253.873730631964, 26043.609345859448, 26118.0, 26433.33916652099, 27091.1872643821, 44039.339409908236, 44083.0, 44755.55244447644, 44775.32837007513, 45402.77298613618, 35551.0, 36109.63890361063, 36208.456873146395, 36256.27487450183, 36462.453063203546, 36007.44597190813, 36325.818370907975, 37433.25133497369, 37512.87461376163, 37712.43431348587, 35251.0, 35505.93482195538, 35983.64016359803, 36314.092147696516, 36366.72716454911, 28325.716742833134, 30795.384092318476, 32336.67663323337, 32453.0, 32556.0, 24298.75701242965, 24630.753692462848, 24647.260582182564, 24654.630180547665, 25144.62283065719], "value": 20096.632099415634, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.82727772502375, 386.3104358719686, 388.58227405539054, 397.13424734079706, 417.8767263657221, 16109.952853064542, 19968.301109461172, 20743.273985410513, 20978.0, 20999.055042523087, 19673.803261967376, 19931.800681993176, 21251.6812247816, 21268.255611053617, 21422.57154856902, 5542.61201715879, 5929.495992840613, 5955.672438015908, 5996.760129594811, 5999.85000374992, 5644.717764111778, 5845.298564172305, 5896.174535565025, 5923.259592550931, 5964.850878728045, 5912.231409916705, 5930.584859059867, 5950.077737950603, 5956.70216489176, 5996.0, 5077.949220507785, 5750.539956803445, 5819.068948968166, 5845.824625261249, 5925.288965324167, 5793.391693872167, 5806.0, 5810.535157187415, 5816.796412125597, 5821.359650438449, 12925.418356173996, 12933.482660693655, 13137.27744974026, 13239.073264871462, 13247.0, 2660.414708764069, 2704.4050308932005, 2733.7129601391766, 2829.688734239232, 2960.171152077422, 9567.808643827084, 9598.808023839483, 9748.26887983404, 9995.25035622324, 10074.294799364048, 1633.9101349425778, 1832.3495159218419, 1894.8768330058501, 1904.5905130396893, 1915.6839121544929, 127400.0, 134167.0, 136568.31715841359, 143926.56073439238, 145134.27432862873, 10039.498025098861, 10161.237907156992, 11939.104567157498, 12001.63995080149, 12191.756164876653, 13553.119047261896, 13572.1856688599, 13684.247366394744, 14489.130652160904, 15732.134732589588, 7961.721339753197, 8179.0, 8368.163183681583, 8432.65077587583, 8768.956155219183, 8021.919780802234, 8028.598570071473, 8075.475094118981, 8638.740837774876, 8794.560271986375, 4613.5847773700525, 4664.836730714476, 4675.883102922386, 4721.716696998191, 4874.5856602189215, 1926.1813729165049, 1974.0, 1984.9404517864486, 2033.9389818305474, 2047.0583531575344, 889.1464194373474, 889.6708217959267, 917.6100157433065, 919.9310051746146, 945.1871390604213, 18269.17788699485, 21157.0, 21537.56924861494, 21732.891335543536, 21770.78229217719, 821.0, 932.6875496708543, 938.3244064273712, 953.4136506048869, 959.1128206409044, 9234.492102934402, 10778.622748203776, 11023.283486573348, 11039.503222354853, 11062.778744425066, 779178.0, 779466.0, 786839.0, 789704.0, 803185.0, 150262.0, 155170.0, 155179.0, 155928.0, 159257.0, 985.4678473624163, 1031.6904928521415, 1055.145332280855, 1056.9365838049741, 1057.4606950455322, 16578.253978570752, 19112.426627201206, 19554.804451955868, 19633.60732785365, 19795.802041979972, 63744.725105496706, 64468.0, 65205.67397162984, 65867.68264634584, 66161.67676646536, 83363.33273334621, 94679.0, 94984.52507737417, 98041.0, 98511.01488985305, 7294.416446684359, 7540.509866858637, 7558.58427786477, 7565.243475652391, 7928.524288542706, 11383.0, 13304.13523120994, 13342.466301347837, 13411.262380569162, 13487.662808429868, 9571.425714457155, 11218.0, 11300.547978081113, 11408.714782130513, 11732.589359372381, 8718.476891386537, 11049.005589496985, 11096.0, 11315.943420282845, 11439.885601143882, 11469.770604588028, 11513.712157195801, 11553.942230288794, 11712.824307635221, 11941.223820451643, 7329.413646908341, 7410.666520006721, 7421.0723659542555, 7581.431392645684, 7979.321757650432, 8014.0, 10580.994805493467, 11514.76970460603, 11581.420928953687, 11636.185467017227], "value": 9678.203038829228, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1396.3088271305705, 1418.8723014928655, 1422.1680317014545, 1435.2536680925912, 1450.9274536273188, 6050.60671056381, 7796.142424333319, 7872.409569282306, 8054.114047454776, 8504.574771261443, 7827.882581761278, 8089.393295502831, 8108.229718176778, 8183.6726530938695, 8193.46742461741, 1996.4908948218256, 2115.851890367675, 2170.934871953844, 2276.647119696441, 2277.8974946127464, 1640.6882692288486, 2141.9357419277367, 2270.545890821834, 2291.2667946257206, 2406.470576473173, 2200.3398980305924, 2273.6816845641542, 2281.0989659084653, 2282.988585057072, 2307.377008207783, 1989.4031790462875, 2289.919852805144, 2310.8382413231166, 2313.2597568777996, 2314.710661167354, 2282.098571064437, 2289.4047547637733, 2336.913335299094, 2361.7992470639842, 2365.935329101911, 8423.15768423159, 8444.57777111142, 8478.74563763088, 8479.321654267644, 8488.278496327819, 5043.495650434964, 5165.302684137669, 5194.974025129888, 5207.297014902978, 5215.269862219292, 3230.5154226866157, 3237.43344914639, 3374.190194353362, 3443.345764304774, 3462.2556150427527, 1205.427421974564, 1218.4421489251408, 1259.4710221706905, 1265.9683507912284, 1277.2400421749044, 10711.83932241017, 10802.081823044971, 10833.566657333697, 10835.729106772314, 10860.891391086147, 3951.0912490126857, 4337.501187363494, 4630.53694630541, 4636.652251081115, 4665.743384113838, 9468.621255149717, 9567.569459374345, 9577.856332155023, 9584.28117891161, 9609.039096090335, 5075.6193285502995, 5190.6107041972, 5195.9740201298755, 5196.45437229093, 5203.401608814959, 4579.748113853703, 4682.74244916526, 4722.173619616553, 4753.263244197085, 4753.382060332135, 3349.2464195555804, 3880.8835734927998, 3910.2179564087296, 3965.167314863911, 3982.7653427437585, 1176.2942234659306, 1228.9815652765217, 1272.929988850622, 1286.459686931491, 1301.5509649171022, 765.9846803064019, 793.797581616684, 800.5276886636796, 826.2398593294183, 852.9445586036887, 4917.704937703749, 5609.467100625434, 5777.335606405233, 5812.128180772918, 5834.299884013945, 884.5488800711684, 976.4531862157214, 1006.8238058339762, 1007.4308015970953, 1029.4389557691077, 3405.8297085145646, 3861.0, 3867.8839634811, 3947.940780888289, 3964.60353964607, 9524.428534287965, 10010.299279050541, 10065.547050382751, 10067.144292735053, 10112.241581881388, 10053.195744340435, 10059.346142500714, 10078.0, 10092.899071009342, 10093.192544596415, 7480.812979675552, 7868.881966770503, 7902.762917112495, 7945.841083178304, 8013.278804907703, 5773.336066352424, 5798.82603521895, 5834.0, 5874.060150375919, 5878.353381127987, 7780.377569794516, 8944.552772361225, 9087.818243635222, 9107.453552786854, 9403.388779729295, 9114.954425227832, 9261.814763704553, 9342.906570934203, 9348.906510934803, 9394.295427843075, 7011.193712723102, 7085.468589855885, 7123.572585644671, 7136.571805691675, 7177.784666460015, 7792.259735325135, 7922.841543169219, 7936.246056624612, 7954.0, 7961.800954976172, 7052.8589428210125, 7169.713211471482, 7172.13934327884, 7190.245024272376, 7308.04995350601, 7093.467989900882, 7127.358537731629, 7151.42788576923, 7158.749443769443, 7190.568565885855, 6374.426301632875, 6380.7447702091395, 6428.164338635947, 6472.126262954441, 6475.417212450714, 4501.77491125449, 5045.949540504548, 5051.040302342543, 5067.366579177603, 5150.845474635767, 5029.484041731154, 5074.7716352764955, 5091.930694554185, 5093.108705976513, 5182.714950677749], "value": 4396.665511826852, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.41946206559226, 350.2277478160656, 367.79771125880757, 368.34250862210945, 372.94965179700733, 5307.973460132699, 5311.203319502072, 5320.973395133029, 5382.300300960879, 5409.323834520685, 5122.436531981479, 5257.526822585966, 5335.173048177529, 5336.17289320155, 5347.893042139155, 3821.73247872649, 3925.3719404895223, 4130.6988298685965, 4181.623653871151, 4187.413762073312, 3305.933881322372, 3439.982800085996, 3736.2252929858373, 3926.430667553203, 4226.767527785971, 3683.8710645127426, 4130.876073717794, 4210.51579068408, 4213.936790948138, 4216.831326746927, 4210.32634778434, 4218.5148707898525, 4231.301835197189, 4255.425517555122, 4270.530241673413, 3603.837827297778, 3696.796676182809, 3720.4977328060636, 3724.42271447926, 3748.850045998157, 5123.666961647481, 5157.8452646420665, 5161.509656582619, 5193.480651934777, 5261.684298942076, 4113.218488487208, 4761.500212388489, 4872.585830204437, 4882.607522314041, 4894.119058569456, 5503.550919840673, 5638.323401191883, 5694.544436445074, 5720.170575266563, 5782.277215348082, 3671.8714844980295, 4135.338345864648, 4153.439285696452, 4162.313218318974, 4170.645495132917, 5937.33754548727, 5953.226080609536, 6037.486813620848, 6045.430691955945, 6049.606775559574, 3598.1724203433355, 3873.4189871519498, 3890.0, 4199.286121359315, 4215.199112168678, 3669.871474544758, 4279.935800962988, 4293.9603688247835, 4298.76356800379, 4313.439252897103, 4343.869683909487, 4725.9527404726205, 4875.43932447773, 4879.365682461328, 4920.507949205051, 4881.267809828554, 5140.9361778648, 5506.0, 5709.3434255060365, 5801.7099145042575, 1385.1688986608156, 1392.9651758706113, 1400.1867581482006, 1411.125102436496, 1415.6956254405352, 920.2683866326287, 958.7219706285239, 966.3766870368713, 977.4428575711918, 985.69936169469, 4888.291197776302, 5101.846944591668, 5147.7683504242395, 5188.610854185876, 5188.740562971836, 5535.750891209906, 5675.5459563234845, 5704.4295570443455, 5722.599418040697, 5884.675947911685, 4878.975605121952, 5000.199968005175, 5374.355077390738, 5455.5908306877145, 5472.425395333512, 2653.4162484253434, 2764.087851009162, 2823.9410221167063, 2852.3867368515866, 2897.0444949658895, 4970.925436118461, 5094.414142373675, 5160.174372100521, 5178.533931946143, 5646.447227012604, 5221.477852214748, 5668.064769313017, 5771.653700777967, 5819.359870414251, 5882.823515294548, 5166.044281807877, 5490.423505531861, 5634.478690753555, 5836.737346819404, 5846.590738648254, 4993.4257560379565, 5180.7409629519125, 5428.918566221589, 5607.691576963306, 5671.234383358194, 5183.611229157904, 5211.661242019257, 5224.955008998139, 5562.582806289463, 5607.831765047055, 5587.972060139836, 5699.8860022800145, 5820.70896455184, 5831.329397119216, 5863.853403664943, 3706.5552133744122, 3944.684425246031, 3989.900252493711, 3990.9822995136055, 4022.5172979241506, 4382.049987755418, 4830.565249127595, 4989.426215985063, 5009.974950125226, 5831.067029275296, 4712.505186955457, 4932.854208419349, 5474.959757646035, 5609.0, 5696.11710184922, 4832.710037397767, 5682.715864206856, 5694.202811606296, 5718.571107166896, 5726.599138060295, 5356.384015838073, 5383.380911195262, 5537.612367134262, 5565.526930210977, 5583.916241256303, 5369.865753356197, 5646.971765141148, 5679.318481782213, 5686.459786320293, 5754.424557544212, 5004.224345226496, 5260.0, 5405.86485337854, 5482.369527504388, 5581.6371935824045], "value": 4168.558770244804, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [667.526056499885, 719.5646633786562, 766.1074847802307, 823.1439303124749, 832.8209434971479, 14751.262436878153, 15678.059316440993, 15803.683926321466, 15805.683886322267, 15893.0, 11771.176017678745, 14197.432102715904, 14211.934104942118, 14361.210133442657, 14365.856341436609, 608.9191684759543, 678.8065401360614, 832.2135581875131, 857.8112815180666, 869.1308691308691, 669.6919417068154, 754.7547047209652, 815.2255357410454, 877.5436772878099, 881.532787622561, 802.2378740196807, 931.7624005878491, 934.0286102453474, 940.5203346293371, 942.5428667096487, 908.7092130518237, 935.1256575102301, 936.8875734911786, 937.0, 961.5528779117674, 541.4991133201772, 652.2010537092025, 674.0765151742096, 692.6848284030754, 695.6035060015793, 15010.624734381729, 15054.924725376302, 15391.076535407912, 15697.058176509447, 15841.603959901095, 14557.490487832982, 14576.0, 14779.926100369537, 14956.102633842005, 14956.925215373854, 11693.766124677544, 11834.940825295818, 11868.703282417922, 11873.287602743776, 12142.150049496538, 3744.2885851688093, 3773.2264885698364, 3782.224643947984, 3810.4474851146706, 3829.942550861739, 100486.99513004922, 102658.48670756599, 104475.0, 106948.93051069467, 108915.0, 9277.536123193948, 10148.492575371201, 11028.558857645765, 11422.94288528552, 11714.414279286002, 21404.464888377915, 21465.141394344362, 21655.891720541298, 21762.673559896615, 21781.346559603237, 7605.125410577743, 7663.945483068425, 7672.808179795438, 7711.807204819925, 7772.222777722295, 4278.55075217104, 4319.049809042006, 4368.650507959355, 4395.582419670126, 4422.226110430662, 3360.2103505676146, 3413.7951722896705, 3419.9316013679586, 3451.11996440911, 3473.6700013498676, 623.607127509675, 623.8253289078974, 627.97802076928, 630.149298447102, 640.60282624772, 631.8325643704422, 634.5621521150391, 648.3225029843825, 653.9444147247538, 677.1569396101885, 9640.855387169198, 9902.504874756234, 9941.701748947544, 9991.450470224063, 9996.300258981946, 637.6301744987896, 683.8153698501476, 687.8624275144991, 689.3933338661946, 700.3626699703245, 4019.678425725935, 4439.733615983051, 4751.881202969884, 4752.310914917318, 4832.830850920201, 94679.05320946702, 100718.48203794964, 106642.46678766556, 109609.06759195855, 112143.0, 78583.60708196537, 78655.60672196717, 82677.0, 87258.56370718233, 94668.0, 70706.0, 72334.0, 72733.90899136517, 73519.26480735232, 76048.61975690197, 10726.517306721373, 10850.620228291971, 11020.393878336768, 11223.66329010131, 11306.265092768943, 45901.54098458973, 46810.0, 51029.0, 51866.44400667835, 53075.0, 67207.32792672206, 67640.0, 70030.93097744952, 70525.94211086941, 70740.64629676819, 13019.674508137372, 13128.60614181576, 13151.0136739743, 13200.33998300062, 13460.46158153702, 14263.572092837232, 14299.21354325522, 14443.988920775444, 14606.488772892897, 14718.55844324672, 13068.150570212905, 13414.731705366034, 13685.452581896612, 13754.724905502035, 14097.929510352793, 11684.415779211175, 12883.098183127091, 13429.731405371642, 14152.0, 14160.291985400483, 11560.94219528897, 12990.675233118869, 13551.0, 13755.0, 14098.721816449764, 10701.250912436366, 10936.179786516203, 10963.342199468058, 11344.092472602337, 11505.309681419141, 8826.5586720665, 11065.889341106807, 11083.72290692739, 11086.168537359568, 11113.944430277797], "value": 7461.492633552682, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1572.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 119.0, 118.0, 120.0, 118.0, 119.0, 119.0, 119.0, 119.0, 121.0], "value": 117.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 116.0, 111.0, 146.0, 114.0, 127.0, 100.0, 136.0, 158.0, 133.0], "value": 128.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 198.0, 199.0, 195.0, 234.0, 241.0, 195.0, 265.0, 262.0, 196.0], "value": 218.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 58.0, 60.0, 85.0, 63.0, 68.0, 59.0, 59.0, 78.0, 51.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 70.0, 102.0, 63.0, 74.0, 93.0, 107.0, 55.0, 95.0, 105.0], "value": 84.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 101.0, 91.0, 86.0, 94.0, 116.0, 95.0, 84.0, 91.0, 87.0], "value": 93.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 97.0, 97.0, 97.0, 98.0, 112.0, 146.0, 113.0, 96.0, 104.0], "value": 108.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 126.0, 108.0, 84.0, 140.0, 148.0, 123.0, 94.0, 90.0], "value": 109.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 61.0, 60.0, 64.0, 63.0, 63.0, 64.0, 63.0, 64.0, 65.0], "value": 62.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 77.0, 72.0, 80.0, 78.0, 71.0, 74.0, 73.0, 95.0, 75.0], "value": 76.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 111.0, 101.0, 86.0, 82.0, 87.0, 96.0, 80.0, 101.0], "value": 93.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 141.0, 139.0, 142.0, 139.0, 185.0, 141.0, 146.0, 211.0, 174.0], "value": 156.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 172.0, 172.0, 174.0, 175.0, 174.0, 207.0, 174.0, 173.0, 221.0], "value": 181.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 59.0, 79.0, 84.0, 84.0, 86.0, 81.0, 68.0, 69.0, 68.0], "value": 74.5, "unit": "score"}]}]} 02:29:47 INFO - Return code: 0 02:29:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:29:47 ERROR - # TBPL SUCCESS # 02:29:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:29:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:29:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:29:47 INFO - 2017-02-01 02:29:47.159 screenresolution[6460:124497] starting screenresolution argv=screenresolution get 02:29:47 INFO - 2017-02-01 02:29:47.162 screenresolution[6460:124497] Display 0: 1600x1200x32@60 02:29:47 INFO - 2017-02-01 02:29:47.168 screenresolution[6461:124500] starting screenresolution argv=screenresolution list 02:29:47 INFO - Available Modes on Display 0 02:29:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:29:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:29:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:29:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:29:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:29:47 INFO - Intel Iris: 02:29:47 INFO - Chipset Model: Intel Iris 02:29:47 INFO - Type: GPU 02:29:47 INFO - Bus: Built-In 02:29:47 INFO - VRAM (Dynamic, Max): 1536 MB 02:29:47 INFO - Vendor: Intel (0x8086) 02:29:47 INFO - Device ID: 0x0a2e 02:29:47 INFO - Revision ID: 0x0009 02:29:47 INFO - Displays: 02:29:47 INFO - Display: 02:29:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:29:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:29:47 INFO - Display Serial Number: 1600x1200 60 02:29:47 INFO - Main Display: Yes 02:29:47 INFO - Mirror: Off 02:29:47 INFO - Online: Yes 02:29:47 INFO - Rotation: Supported 02:29:47 INFO - Return code: 0 02:29:47 INFO - Running post-action listener: _resource_record_post_action 02:29:47 INFO - [mozharness: 2017-02-01 10:29:47.402828Z] Finished run-tests step (success) 02:29:47 INFO - Running post-run listener: _resource_record_post_run 02:29:47 INFO - Total resource usage - Wall time: 1197s; CPU: 55.0%; Read bytes: 1817600; Write bytes: 509871616; Read time: 18; Write time: 1599 02:29:47 INFO - TinderboxPrint: CPU usage
54.8% 02:29:47 INFO - TinderboxPrint: I/O read bytes / time
1,817,600 / 18 02:29:47 INFO - TinderboxPrint: I/O write bytes / time
509,871,616 / 1,599 02:29:47 INFO - TinderboxPrint: CPU idle
2,163.6 (45.2%) 02:29:47 INFO - TinderboxPrint: CPU system
970.9 (20.3%) 02:29:47 INFO - TinderboxPrint: CPU user
1,655.2 (34.6%) 02:29:47 INFO - TinderboxPrint: Swap in / out
209,838,080 / 0 02:29:47 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 202953216; Write bytes: 200747008; Read time: 15775; Write time: 436 02:29:47 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 1817600; Write bytes: 298438144; Read time: 18; Write time: 1126 02:29:47 INFO - Running post-run listener: _upload_blobber_files 02:29:47 INFO - Blob upload gear active. 02:29:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:29:47 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:29:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:29:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:29:48 INFO - (blobuploader) - INFO - Open directory for files ... 02:29:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:29:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:29:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:29:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:29:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:29:49 INFO - (blobuploader) - INFO - Done attempting. 02:29:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:29:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:29:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:29:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:29:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:29:50 INFO - (blobuploader) - INFO - Done attempting. 02:29:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:29:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:29:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:29:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:29:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:29:51 INFO - (blobuploader) - INFO - Done attempting. 02:29:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:29:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:29:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:29:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:29:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:29:53 INFO - (blobuploader) - INFO - Done attempting. 02:29:53 INFO - (blobuploader) - INFO - Iteration through files over. 02:29:53 INFO - Return code: 0 02:29:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:29:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:29:53 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a820f5ee7ad20a74945d2b209e27af399c61afc6a3d75cec622790af7f6141224734a6d45d17fc1a842a9d2528d22eee2fcb2d7caa01cde9faa2a5d9e675a089", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/53b21624dd64807dbacbe955419db6630afe0f9999421de992522ea40566dc2fc980141adf4f113f3e0400290cc7317e59d063183d24f0af4d15b55faa951c53", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9b747c82e88a68f435a0c35a440ebe12d9e5a457b019828a8c17ff564906a902c2c56e6d74634981d9c70493a1bf66cf4b06979730a5f5d02259bda1eddafa23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6ce74efb59166be567ad0928a098d1faea4b26b82bc2a2e048f9b42f09ae3a655ab3157ac4603dd20adb3be058628eec886569f21a81702d838fbe9324622c7b"} 02:29:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:29:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:29:53 INFO - Contents: 02:29:53 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a820f5ee7ad20a74945d2b209e27af399c61afc6a3d75cec622790af7f6141224734a6d45d17fc1a842a9d2528d22eee2fcb2d7caa01cde9faa2a5d9e675a089", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/53b21624dd64807dbacbe955419db6630afe0f9999421de992522ea40566dc2fc980141adf4f113f3e0400290cc7317e59d063183d24f0af4d15b55faa951c53", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9b747c82e88a68f435a0c35a440ebe12d9e5a457b019828a8c17ff564906a902c2c56e6d74634981d9c70493a1bf66cf4b06979730a5f5d02259bda1eddafa23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6ce74efb59166be567ad0928a098d1faea4b26b82bc2a2e048f9b42f09ae3a655ab3157ac4603dd20adb3be058628eec886569f21a81702d838fbe9324622c7b"} 02:29:53 INFO - Running post-run listener: copy_logs_to_upload_dir 02:29:53 INFO - Copying logs to upload dir... 02:29:53 INFO - mkdir: /builds/slave/test/build/upload/logs 02:29:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.805136 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-02-01 02:29:53.859844) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-01 02:29:53.863314) ========= 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.ykn1CfQbAK/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.fv5Fnjp5xu/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a820f5ee7ad20a74945d2b209e27af399c61afc6a3d75cec622790af7f6141224734a6d45d17fc1a842a9d2528d22eee2fcb2d7caa01cde9faa2a5d9e675a089", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/53b21624dd64807dbacbe955419db6630afe0f9999421de992522ea40566dc2fc980141adf4f113f3e0400290cc7317e59d063183d24f0af4d15b55faa951c53", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9b747c82e88a68f435a0c35a440ebe12d9e5a457b019828a8c17ff564906a902c2c56e6d74634981d9c70493a1bf66cf4b06979730a5f5d02259bda1eddafa23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6ce74efb59166be567ad0928a098d1faea4b26b82bc2a2e048f9b42f09ae3a655ab3157ac4603dd20adb3be058628eec886569f21a81702d838fbe9324622c7b"} build_url:https://queue.taskcluster.net/v1/task/ZUO_w9SpR4Gcgw6n8Uv28w/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011391 build_url: 'https://queue.taskcluster.net/v1/task/ZUO_w9SpR4Gcgw6n8Uv28w/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a820f5ee7ad20a74945d2b209e27af399c61afc6a3d75cec622790af7f6141224734a6d45d17fc1a842a9d2528d22eee2fcb2d7caa01cde9faa2a5d9e675a089", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/53b21624dd64807dbacbe955419db6630afe0f9999421de992522ea40566dc2fc980141adf4f113f3e0400290cc7317e59d063183d24f0af4d15b55faa951c53", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9b747c82e88a68f435a0c35a440ebe12d9e5a457b019828a8c17ff564906a902c2c56e6d74634981d9c70493a1bf66cf4b06979730a5f5d02259bda1eddafa23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6ce74efb59166be567ad0928a098d1faea4b26b82bc2a2e048f9b42f09ae3a655ab3157ac4603dd20adb3be058628eec886569f21a81702d838fbe9324622c7b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-01 02:29:53.898139) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 02:29:53.898465) ========= 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.ykn1CfQbAK/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.fv5Fnjp5xu/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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.005356 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 02:29:53.946792) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-01 02:29:53.947164) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-01 02:29:53.947477) ========= ========= Total master_lag: 0.19 =========