builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0357 starttime: 1474406361.07 results: success (0) buildid: 20160920141831 builduid: e4b4f311b8a8466e8a491f379612ed22 revision: 6be9a5c9c8cd25d2082376495b788d85ce3cca98 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 14:19:21.074648) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 14:19:21.075066) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 14:19:21.075350) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 14:19:21.098578) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:19:21.098847) ========= 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.8avJsBmJJa/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.6iiuZwnBbT/Listeners TMPDIR=/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 --2016-09-20 14:19:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 63.6M=0s 2016-09-20 14:19:21 (63.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.144949 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:19:21.265886) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:19:21.269213) ========= 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.8avJsBmJJa/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.6iiuZwnBbT/Listeners TMPDIR=/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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.021035 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:19:21.317750) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-20 14:19:21.318104) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6be9a5c9c8cd25d2082376495b788d85ce3cca98 --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-beta --rev 6be9a5c9c8cd25d2082376495b788d85ce3cca98 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8avJsBmJJa/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.6iiuZwnBbT/Listeners TMPDIR=/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 2016-09-20 14:19:21,406 truncating revision to first 12 chars 2016-09-20 14:19:21,406 Setting DEBUG logging. 2016-09-20 14:19:21,406 attempt 1/10 2016-09-20 14:19:21,406 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/6be9a5c9c8cd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-20 14:19:22,317 unpacking tar archive at: mozilla-beta-6be9a5c9c8cd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.164724 ========= master_lag: 1.00 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-20 14:19:23.487492) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 14:19:23.487756) ========= script_repo_revision: 6be9a5c9c8cd25d2082376495b788d85ce3cca98 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 14:19:23.488099) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 2 secs) (at 2016-09-20 14:19:23.488374) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 2 secs) (at 2016-09-20 14:19:25.576971) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-09-20 14:19:25.580318) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8avJsBmJJa/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.6iiuZwnBbT/Listeners TMPDIR=/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 14:19:25 INFO - MultiFileLogger online at 20160920 14:19:25 in /builds/slave/test 14:19:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 14:19:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:19:25 INFO - {'append_to_log': False, 14:19:25 INFO - 'base_work_dir': '/builds/slave/test', 14:19:25 INFO - 'blob_upload_branch': 'Mozilla-Beta', 14:19:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:19:25 INFO - 'branch': 'Mozilla-Beta', 14:19:25 INFO - 'buildbot_json_path': 'buildprops.json', 14:19:25 INFO - 'config_files': ('talos/mac_config.py',), 14:19:25 INFO - 'default_actions': ('clobber', 14:19:25 INFO - 'read-buildbot-config', 14:19:25 INFO - 'download-and-extract', 14:19:25 INFO - 'populate-webroot', 14:19:25 INFO - 'create-virtualenv', 14:19:25 INFO - 'install', 14:19:25 INFO - 'run-tests'), 14:19:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:19:25 INFO - 'download_minidump_stackwalk': True, 14:19:25 INFO - 'download_symbols': 'ondemand', 14:19:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:19:25 INFO - 'tooltool.py': '/tools/tooltool.py', 14:19:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:19:25 INFO - '/tools/misc-python/virtualenv.py')}, 14:19:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:19:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:19:25 INFO - 'installer_path': 'installer.exe', 14:19:25 INFO - 'log_level': 'info', 14:19:25 INFO - 'log_name': 'talos', 14:19:25 INFO - 'log_to_console': True, 14:19:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:19:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:19:25 INFO - 'opt_config_files': (), 14:19:25 INFO - 'pip_index': False, 14:19:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:19:25 INFO - 'cmd': ('bash', 14:19:25 INFO - '-c', 14:19:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:19:25 INFO - 'enabled': True, 14:19:25 INFO - 'halt_on_failure': False, 14:19:25 INFO - 'name': 'check_screen_resolution'},), 14:19:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:19:25 INFO - 'cmd': ('bash', 14:19:25 INFO - '-c', 14:19:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:19:25 INFO - 'enabled': True, 14:19:25 INFO - 'halt_on_failure': False, 14:19:25 INFO - 'name': 'check_screen_resolution'},), 14:19:25 INFO - 'run_cmd_checks_enabled': True, 14:19:25 INFO - 'sps_profile': False, 14:19:25 INFO - 'sps_profile_interval': 0, 14:19:25 INFO - 'suite': 'dromaeojs-e10s', 14:19:25 INFO - 'system_bits': '32', 14:19:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:19:25 INFO - 'title': 't-yosemite-r7-0357', 14:19:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:19:25 INFO - 'use_talos_json': True, 14:19:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:19:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:19:25 INFO - 'work_dir': 'build'} 14:19:25 INFO - [mozharness: 2016-09-20 21:19:25.775487Z] Running clobber step. 14:19:25 INFO - Running pre-action listener: _resource_record_pre_action 14:19:25 INFO - Running main action method: clobber 14:19:25 INFO - rmtree: /builds/slave/test/build 14:19:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:19:27 INFO - Running post-action listener: _resource_record_post_action 14:19:27 INFO - [mozharness: 2016-09-20 21:19:27.898039Z] Finished clobber step (success) 14:19:27 INFO - [mozharness: 2016-09-20 21:19:27.898168Z] Running read-buildbot-config step. 14:19:27 INFO - Running pre-action listener: _resource_record_pre_action 14:19:27 INFO - Running main action method: read_buildbot_config 14:19:27 INFO - Using buildbot properties: 14:19:27 INFO - { 14:19:27 INFO - "project": "", 14:19:27 INFO - "product": "firefox", 14:19:27 INFO - "script_repo_revision": "production", 14:19:27 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 14:19:27 INFO - "repository": "", 14:19:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 14:19:27 INFO - "buildid": "20160920141831", 14:19:27 INFO - "stage_platform": "macosx64", 14:19:27 INFO - "basedir": "/builds/slave/test", 14:19:27 INFO - "buildnumber": 5, 14:19:27 INFO - "platform": "yosemite_r7", 14:19:27 INFO - "mozci_request": { 14:19:27 INFO - "priority": -1, 14:19:27 INFO - "type": "trigger_all_talos_jobs", 14:19:27 INFO - "times": 6 14:19:27 INFO - }, 14:19:27 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 14:19:27 INFO - "slavebuilddir": "test", 14:19:27 INFO - "branch": "mozilla-beta", 14:19:27 INFO - "revision": "6be9a5c9c8cd25d2082376495b788d85ce3cca98", 14:19:27 INFO - "slavename": "t-yosemite-r7-0357", 14:19:27 INFO - "builduid": "e4b4f311b8a8466e8a491f379612ed22", 14:19:27 INFO - "repo_path": "releases/mozilla-beta" 14:19:27 INFO - } 14:19:27 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.dmg. 14:19:27 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.test_packages.json. 14:19:27 INFO - Running post-action listener: _resource_record_post_action 14:19:27 INFO - [mozharness: 2016-09-20 21:19:27.902205Z] Finished read-buildbot-config step (success) 14:19:27 INFO - [mozharness: 2016-09-20 21:19:27.902354Z] Running download-and-extract step. 14:19:27 INFO - Running pre-action listener: _resource_record_pre_action 14:19:27 INFO - Running main action method: download_and_extract 14:19:27 INFO - mkdir: /builds/slave/test/build/tests 14:19:27 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:27 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.test_packages.json 14:19:27 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.mac.test_packages.json 14:19:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.mac.test_packages.json'}, attempt #1 14:19:28 INFO - Downloaded 1165 bytes. 14:19:28 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.mac.test_packages.json 14:19:28 INFO - Using the following test package requirements: 14:19:28 INFO - {u'common': [u'firefox-50.0.en-US.mac.common.tests.zip'], 14:19:28 INFO - u'cppunittest': [u'firefox-50.0.en-US.mac.common.tests.zip', 14:19:28 INFO - u'firefox-50.0.en-US.mac.cppunittest.tests.zip'], 14:19:28 INFO - u'gtest': [u'firefox-50.0.en-US.mac.common.tests.zip', 14:19:28 INFO - u'firefox-50.0.en-US.mac.gtest.tests.zip'], 14:19:28 INFO - u'jittest': [u'firefox-50.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 14:19:28 INFO - u'mochitest': [u'firefox-50.0.en-US.mac.common.tests.zip', 14:19:28 INFO - u'firefox-50.0.en-US.mac.mochitest.tests.zip'], 14:19:28 INFO - u'mozbase': [u'firefox-50.0.en-US.mac.common.tests.zip'], 14:19:28 INFO - u'reftest': [u'firefox-50.0.en-US.mac.common.tests.zip', 14:19:28 INFO - u'firefox-50.0.en-US.mac.reftest.tests.zip'], 14:19:28 INFO - u'talos': [u'firefox-50.0.en-US.mac.common.tests.zip', 14:19:28 INFO - u'firefox-50.0.en-US.mac.talos.tests.zip'], 14:19:28 INFO - u'web-platform': [u'firefox-50.0.en-US.mac.common.tests.zip', 14:19:28 INFO - u'firefox-50.0.en-US.mac.web-platform.tests.zip'], 14:19:28 INFO - u'xpcshell': [u'firefox-50.0.en-US.mac.common.tests.zip', 14:19:28 INFO - u'firefox-50.0.en-US.mac.xpcshell.tests.zip']} 14:19:28 INFO - Downloading packages: [u'firefox-50.0.en-US.mac.common.tests.zip'] for test suite category: common 14:19:28 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:28 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.common.tests.zip 14:19:28 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.mac.common.tests.zip 14:19:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.mac.common.tests.zip'}, attempt #1 14:19:28 INFO - Downloaded 16113415 bytes. 14:19:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:19:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:19:31 INFO - Return code: 0 14:19:31 INFO - Downloading packages: [u'firefox-50.0.en-US.mac.common.tests.zip', u'firefox-50.0.en-US.mac.talos.tests.zip'] for test suite category: talos 14:19:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:31 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.common.tests.zip 14:19:31 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.mac.common.tests.zip 14:19:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.mac.common.tests.zip'}, attempt #1 14:19:31 INFO - Downloaded 16113415 bytes. 14:19:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:19:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:19:35 INFO - Return code: 0 14:19:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:35 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.talos.tests.zip 14:19:35 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.mac.talos.tests.zip 14:19:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.mac.talos.tests.zip'}, attempt #1 14:19:35 INFO - Downloaded 14413740 bytes. 14:19:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:19:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 14:19:36 INFO - Return code: 0 14:19:36 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:36 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.dmg 14:19:36 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0.en-US.mac.dmg 14:19:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.mac.dmg'}, attempt #1 14:19:37 INFO - Downloaded 86213837 bytes. 14:19:37 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.dmg 14:19:37 INFO - mkdir: /builds/slave/test/properties 14:19:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:19:37 INFO - Writing to file /builds/slave/test/properties/build_url 14:19:37 INFO - Contents: 14:19:37 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.dmg 14:19:37 INFO - Running post-action listener: _resource_record_post_action 14:19:37 INFO - Running post-action listener: set_extra_try_arguments 14:19:37 INFO - [mozharness: 2016-09-20 21:19:37.946191Z] Finished download-and-extract step (success) 14:19:37 INFO - [mozharness: 2016-09-20 21:19:37.946353Z] Running populate-webroot step. 14:19:37 INFO - Running pre-action listener: _resource_record_pre_action 14:19:37 INFO - Running main action method: populate_webroot 14:19:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:19:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:19:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:19:37 INFO - '--tppagecycles', 14:19:37 INFO - '7'], 14:19:37 INFO - 'tests': ['tsvgm']}}, 14:19:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:19:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:19:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:19:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:19:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:19:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:19:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:37 INFO - 'talos_options': ['--disable-e10s'], 14:19:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:19:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:19:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:37 INFO - 'talos_options': ['--disable-e10s'], 14:19:37 INFO - 'tests': ['damp', 'tps']}, 14:19:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:37 INFO - 'tests': ['damp', 'tps']}, 14:19:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:19:37 INFO - 'tests': ['dromaeo_dom']}, 14:19:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:19:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:19:37 INFO - 'tests': ['basic_compositor_video']}, 14:19:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:19:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:19:37 INFO - 'tests': ['a11yr', 14:19:37 INFO - 'ts_paint', 14:19:37 INFO - 'tpaint', 14:19:37 INFO - 'sessionrestore', 14:19:37 INFO - 'sessionrestore_no_auto_restore', 14:19:37 INFO - 'tabpaint']}, 14:19:37 INFO - 'other-e10s': {'tests': ['a11yr', 14:19:37 INFO - 'ts_paint', 14:19:37 INFO - 'tpaint', 14:19:37 INFO - 'sessionrestore', 14:19:37 INFO - 'sessionrestore_no_auto_restore', 14:19:37 INFO - 'tabpaint']}, 14:19:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:19:37 INFO - 'ts_paint', 14:19:37 INFO - 'tpaint', 14:19:37 INFO - 'sessionrestore', 14:19:37 INFO - 'sessionrestore_no_auto_restore', 14:19:37 INFO - 'tabpaint']}, 14:19:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:19:37 INFO - 'ts_paint', 14:19:37 INFO - 'tpaint', 14:19:37 INFO - 'sessionrestore', 14:19:37 INFO - 'sessionrestore_no_auto_restore', 14:19:37 INFO - 'tabpaint']}, 14:19:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:19:37 INFO - 'tests': ['a11yr', 14:19:37 INFO - 'ts_paint', 14:19:37 INFO - 'tpaint', 14:19:37 INFO - 'sessionrestore', 14:19:37 INFO - 'sessionrestore_no_auto_restore', 14:19:37 INFO - 'tabpaint']}, 14:19:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:19:37 INFO - 'tests': ['a11yr', 14:19:37 INFO - 'ts_paint', 14:19:37 INFO - 'tpaint', 14:19:37 INFO - 'sessionrestore', 14:19:37 INFO - 'sessionrestore_no_auto_restore', 14:19:37 INFO - 'tabpaint']}, 14:19:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:19:37 INFO - 'tests': ['tsvgx', 14:19:37 INFO - 'tsvgr_opacity', 14:19:37 INFO - 'tart', 14:19:37 INFO - 'tscrollx', 14:19:37 INFO - 'cart']}, 14:19:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:19:37 INFO - 'tsvgr_opacity', 14:19:37 INFO - 'tart', 14:19:37 INFO - 'tscrollx', 14:19:37 INFO - 'cart']}, 14:19:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:37 INFO - 'talos_options': ['--disable-e10s'], 14:19:37 INFO - 'tests': ['tp5o']}, 14:19:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:37 INFO - 'tests': ['tp5o']}, 14:19:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:37 INFO - 'talos_options': ['--disable-e10s', 14:19:37 INFO - '--xperf_path', 14:19:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:19:37 INFO - 'tests': ['tp5n']}, 14:19:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:37 INFO - 'talos_options': ['--xperf_path', 14:19:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:19:37 INFO - 'tests': ['tp5n']}}, 14:19:37 INFO - 'talos.zip': {'path': '', 14:19:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:19:37 INFO - Running post-action listener: _resource_record_post_action 14:19:37 INFO - [mozharness: 2016-09-20 21:19:37.966806Z] Finished populate-webroot step (success) 14:19:37 INFO - [mozharness: 2016-09-20 21:19:37.966949Z] Running create-virtualenv step. 14:19:37 INFO - Running pre-action listener: _resource_record_pre_action 14:19:37 INFO - Running main action method: create_virtualenv 14:19:37 INFO - Creating virtualenv /builds/slave/test/build/venv 14:19:37 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 14:19:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:19:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:19:38 INFO - Using real prefix '/tools/python27' 14:19:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:19:38 INFO - Installing distribute.............................................................................................................................................................................................done. 14:19:41 INFO - Installing pip.................done. 14:19:41 INFO - Return code: 0 14:19:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:19:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10495aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc832c75360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048bed30>, '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 0x104663db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048b6b20>, '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.8avJsBmJJa/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.6iiuZwnBbT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 14:19:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:19:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:19:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8avJsBmJJa/Render', 14:19:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:41 INFO - 'HOME': '/Users/cltbld', 14:19:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:41 INFO - 'LOGNAME': 'cltbld', 14:19:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:41 INFO - 'MOZ_NO_REMOTE': '1', 14:19:41 INFO - 'NO_EM_RESTART': '1', 14:19:41 INFO - 'PAGER': '/bin/cat', 14:19:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:41 INFO - 'PWD': '/builds/slave/test', 14:19:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:41 INFO - 'SHELL': '/bin/bash', 14:19:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6iiuZwnBbT/Listeners', 14:19:41 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 14:19:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:41 INFO - 'USER': 'cltbld', 14:19:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:41 INFO - 'XPC_FLAGS': '0x0', 14:19:41 INFO - 'XPC_SERVICE_NAME': '0', 14:19:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:41 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 14:19:41 INFO - Cleaning up... 14:19:41 INFO - Return code: 0 14:19:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:19:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10495aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc832c75360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048bed30>, '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 0x104663db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048b6b20>, '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.8avJsBmJJa/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.6iiuZwnBbT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 14:19:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:19:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:19:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8avJsBmJJa/Render', 14:19:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:41 INFO - 'HOME': '/Users/cltbld', 14:19:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:41 INFO - 'LOGNAME': 'cltbld', 14:19:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:41 INFO - 'MOZ_NO_REMOTE': '1', 14:19:41 INFO - 'NO_EM_RESTART': '1', 14:19:41 INFO - 'PAGER': '/bin/cat', 14:19:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:41 INFO - 'PWD': '/builds/slave/test', 14:19:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:41 INFO - 'SHELL': '/bin/bash', 14:19:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6iiuZwnBbT/Listeners', 14:19:41 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 14:19:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:41 INFO - 'USER': 'cltbld', 14:19:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:41 INFO - 'XPC_FLAGS': '0x0', 14:19:41 INFO - 'XPC_SERVICE_NAME': '0', 14:19:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:42 INFO - Downloading/unpacking psutil>=3.1.1 14:19:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:19:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:19:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:19:45 INFO - Installing collected packages: psutil 14:19:45 INFO - Running setup.py install for psutil 14:19:45 INFO - building 'psutil._psutil_osx' extension 14:19:45 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 14:19:45 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 14:19:45 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 14:19:46 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 14:19:46 INFO - building 'psutil._psutil_posix' extension 14:19:46 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 14:19:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:19:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:19:46 INFO - ^ 14:19:46 INFO - 1 warning generated. 14:19:46 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 14:19:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:19:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:19:46 INFO - Successfully installed psutil 14:19:46 INFO - Cleaning up... 14:19:46 INFO - Return code: 0 14:19:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:19:46 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:46 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:46 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 0x10495aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc832c75360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048bed30>, '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 0x104663db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048b6b20>, '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.8avJsBmJJa/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.6iiuZwnBbT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 14:19:46 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 14:19:46 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 14:19:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8avJsBmJJa/Render', 14:19:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:46 INFO - 'HOME': '/Users/cltbld', 14:19:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:46 INFO - 'LOGNAME': 'cltbld', 14:19:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:46 INFO - 'MOZ_NO_REMOTE': '1', 14:19:46 INFO - 'NO_EM_RESTART': '1', 14:19:46 INFO - 'PAGER': '/bin/cat', 14:19:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:46 INFO - 'PWD': '/builds/slave/test', 14:19:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:46 INFO - 'SHELL': '/bin/bash', 14:19:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6iiuZwnBbT/Listeners', 14:19:46 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 14:19:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:46 INFO - 'USER': 'cltbld', 14:19:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:46 INFO - 'XPC_FLAGS': '0x0', 14:19:46 INFO - 'XPC_SERVICE_NAME': '0', 14:19:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:19:46 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 14:19:46 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 14:19:46 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 14:19:46 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 14:19:46 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 14:19:46 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 14:19:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:19:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:19:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:19:49 INFO - Installing collected packages: mozsystemmonitor 14:19:49 INFO - Running setup.py install for mozsystemmonitor 14:19:49 INFO - Successfully installed mozsystemmonitor 14:19:49 INFO - Cleaning up... 14:19:49 INFO - Return code: 0 14:19:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:19:49 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:49 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:49 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 0x10495aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc832c75360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048bed30>, '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 0x104663db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048b6b20>, '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.8avJsBmJJa/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.6iiuZwnBbT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 14:19:49 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 14:19:49 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 14:19:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8avJsBmJJa/Render', 14:19:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:49 INFO - 'HOME': '/Users/cltbld', 14:19:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:49 INFO - 'LOGNAME': 'cltbld', 14:19:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:49 INFO - 'MOZ_NO_REMOTE': '1', 14:19:49 INFO - 'NO_EM_RESTART': '1', 14:19:49 INFO - 'PAGER': '/bin/cat', 14:19:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:49 INFO - 'PWD': '/builds/slave/test', 14:19:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:49 INFO - 'SHELL': '/bin/bash', 14:19:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6iiuZwnBbT/Listeners', 14:19:49 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 14:19:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:49 INFO - 'USER': 'cltbld', 14:19:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:49 INFO - 'XPC_FLAGS': '0x0', 14:19:49 INFO - 'XPC_SERVICE_NAME': '0', 14:19:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:49 INFO - Downloading/unpacking jsonschema==2.5.1 14:19:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:19:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:19:52 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 14:19:52 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 14:19:52 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 14:19:52 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 14:19:52 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 14:19:52 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 14:19:52 INFO - Downloading functools32-3.2.3-2.tar.gz 14:19:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:19:52 INFO - warning: no files found matching '*.txt' 14:19:52 INFO - no previously-included directories found matching 'build' 14:19:52 INFO - no previously-included directories found matching 'dist' 14:19:52 INFO - no previously-included directories found matching '.git*' 14:19:52 INFO - Installing collected packages: jsonschema, functools32 14:19:52 INFO - Running setup.py install for functools32 14:19:52 INFO - warning: no files found matching '*.txt' 14:19:52 INFO - no previously-included directories found matching 'build' 14:19:52 INFO - no previously-included directories found matching 'dist' 14:19:52 INFO - no previously-included directories found matching '.git*' 14:19:52 INFO - Successfully installed jsonschema functools32 14:19:52 INFO - Cleaning up... 14:19:53 INFO - Return code: 0 14:19:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:19:53 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:53 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:53 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 0x10495aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc832c75360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048bed30>, '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 0x104663db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048b6b20>, '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.8avJsBmJJa/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.6iiuZwnBbT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 14:19:53 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 14:19:53 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 14:19:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8avJsBmJJa/Render', 14:19:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:53 INFO - 'HOME': '/Users/cltbld', 14:19:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:53 INFO - 'LOGNAME': 'cltbld', 14:19:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:53 INFO - 'MOZ_NO_REMOTE': '1', 14:19:53 INFO - 'NO_EM_RESTART': '1', 14:19:53 INFO - 'PAGER': '/bin/cat', 14:19:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:53 INFO - 'PWD': '/builds/slave/test', 14:19:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:53 INFO - 'SHELL': '/bin/bash', 14:19:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6iiuZwnBbT/Listeners', 14:19:53 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 14:19:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:53 INFO - 'USER': 'cltbld', 14:19:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:53 INFO - 'XPC_FLAGS': '0x0', 14:19:53 INFO - 'XPC_SERVICE_NAME': '0', 14:19:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:19:53 INFO - Cleaning up... 14:19:53 INFO - Return code: 0 14:19:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:19:53 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:53 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:53 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 0x10495aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc832c75360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048bed30>, '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 0x104663db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048b6b20>, '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.8avJsBmJJa/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.6iiuZwnBbT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 14:19:53 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 14:19:53 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 14:19:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8avJsBmJJa/Render', 14:19:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:53 INFO - 'HOME': '/Users/cltbld', 14:19:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:53 INFO - 'LOGNAME': 'cltbld', 14:19:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:53 INFO - 'MOZ_NO_REMOTE': '1', 14:19:53 INFO - 'NO_EM_RESTART': '1', 14:19:53 INFO - 'PAGER': '/bin/cat', 14:19:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:53 INFO - 'PWD': '/builds/slave/test', 14:19:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:53 INFO - 'SHELL': '/bin/bash', 14:19:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6iiuZwnBbT/Listeners', 14:19:53 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 14:19:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:53 INFO - 'USER': 'cltbld', 14:19:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:53 INFO - 'XPC_FLAGS': '0x0', 14:19:53 INFO - 'XPC_SERVICE_NAME': '0', 14:19:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:53 INFO - Downloading/unpacking blobuploader==1.2.4 14:19:53 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 14:19:53 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 14:19:53 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 14:19:53 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 14:19:53 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 14:19:53 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 14:19:57 INFO - Downloading blobuploader-1.2.4.tar.gz 14:19:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:19:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:19:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:58 INFO - Downloading docopt-0.6.1.tar.gz 14:19:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:19:58 INFO - Installing collected packages: blobuploader, requests, docopt 14:19:58 INFO - Running setup.py install for blobuploader 14:19:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:19:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:19:58 INFO - Running setup.py install for requests 14:19:59 INFO - Running setup.py install for docopt 14:19:59 INFO - Successfully installed blobuploader requests docopt 14:19:59 INFO - Cleaning up... 14:19:59 INFO - Return code: 0 14:19:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:19:59 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:59 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:59 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 0x10495aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc832c75360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048bed30>, '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 0x104663db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048b6b20>, '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.8avJsBmJJa/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.6iiuZwnBbT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 14:19:59 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 14:19:59 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 14:19:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8avJsBmJJa/Render', 14:19:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:59 INFO - 'HOME': '/Users/cltbld', 14:19:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:59 INFO - 'LOGNAME': 'cltbld', 14:19:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:59 INFO - 'MOZ_NO_REMOTE': '1', 14:19:59 INFO - 'NO_EM_RESTART': '1', 14:19:59 INFO - 'PAGER': '/bin/cat', 14:19:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:59 INFO - 'PWD': '/builds/slave/test', 14:19:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:59 INFO - 'SHELL': '/bin/bash', 14:19:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6iiuZwnBbT/Listeners', 14:19:59 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 14:19:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:59 INFO - 'USER': 'cltbld', 14:19:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:59 INFO - 'XPC_FLAGS': '0x0', 14:19:59 INFO - 'XPC_SERVICE_NAME': '0', 14:19:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:19:59 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-DqDW53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:20:00 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-nXgrDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:20:00 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-EMBx3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:20:00 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-8FemLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:20:00 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-qwzx9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:20:00 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-xHitPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:20:00 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-ABYxdT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:20:00 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-nJe0Rp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:20:00 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-DKlW7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:20:00 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-xPtErv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:20:00 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-kY5FKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:20:01 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-bT7v1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:20:01 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-eLpGyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:20:01 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-pPvn3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:20:01 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-3llgqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:20:01 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-gLr5Ly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:20:01 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-HPseTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:20:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:20:01 INFO - Running setup.py install for manifestparser 14:20:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:20:01 INFO - Running setup.py install for mozcrash 14:20:02 INFO - Running setup.py install for mozdebug 14:20:02 INFO - Running setup.py install for mozdevice 14:20:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:20:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:20:02 INFO - Running setup.py install for mozfile 14:20:02 INFO - Running setup.py install for mozhttpd 14:20:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:20:02 INFO - Running setup.py install for mozinfo 14:20:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:20:02 INFO - Running setup.py install for mozInstall 14:20:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:20:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:20:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:20:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:20:02 INFO - Running setup.py install for mozleak 14:20:03 INFO - Running setup.py install for mozlog 14:20:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:20:03 INFO - Running setup.py install for moznetwork 14:20:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:20:03 INFO - Running setup.py install for mozprocess 14:20:03 INFO - Running setup.py install for mozprofile 14:20:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:20:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:20:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:20:03 INFO - Running setup.py install for mozrunner 14:20:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:20:03 INFO - Running setup.py install for mozscreenshot 14:20:04 INFO - Running setup.py install for moztest 14:20:04 INFO - Running setup.py install for mozversion 14:20:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:20:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:20:04 INFO - Cleaning up... 14:20:04 INFO - Return code: 0 14:20:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:20:04 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:20:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:04 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:20:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:20:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:04 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 0x10495aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc832c75360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048bed30>, '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 0x104663db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048b6b20>, '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.8avJsBmJJa/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.6iiuZwnBbT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 14:20:04 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 14:20:04 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 14:20:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8avJsBmJJa/Render', 14:20:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:20:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:20:04 INFO - 'HOME': '/Users/cltbld', 14:20:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:20:04 INFO - 'LOGNAME': 'cltbld', 14:20:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:20:04 INFO - 'MOZ_NO_REMOTE': '1', 14:20:04 INFO - 'NO_EM_RESTART': '1', 14:20:04 INFO - 'PAGER': '/bin/cat', 14:20:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:20:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:20:04 INFO - 'PWD': '/builds/slave/test', 14:20:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:20:04 INFO - 'SHELL': '/bin/bash', 14:20:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6iiuZwnBbT/Listeners', 14:20:04 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 14:20:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:20:04 INFO - 'USER': 'cltbld', 14:20:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:20:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:20:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:20:04 INFO - 'XPC_FLAGS': '0x0', 14:20:04 INFO - 'XPC_SERVICE_NAME': '0', 14:20:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:20:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:20:04 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-71SSs3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:20:05 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)) 14:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:20:05 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-k4xUB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:20:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 14:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:20:05 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-PBoSxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:20:05 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)) 14:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:20:05 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-CBXOBQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:20:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 14:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:20:05 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-wQJUde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:20:05 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)) 14:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:20:05 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-nTnmrC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:20:05 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)) 14:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:20:05 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-nOl2P6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:20:05 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)) 14:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:20:05 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-iczkPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:20:05 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)) 14:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:20:05 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-gNpXoV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:20:05 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)) 14:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:20:05 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-COFZew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:20:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 14:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:20:06 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-9sqp31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:20:06 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)) 14:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:20:06 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-vRjiIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:20:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 14:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:20:06 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-IAnC9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:20:06 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)) 14:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:20:06 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-WZN4Ei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:20:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 14:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:20:06 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-KWEOaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:20:06 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)) 14:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:20:06 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-SQvE9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:20:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 14:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:20:06 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-CjNSip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:20:06 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)) 14:20:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:20:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:20:06 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)) 14:20:06 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:20:06 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:20:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:20:06 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 14:20:06 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 14:20:06 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 14:20:06 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 14:20:06 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 14:20:06 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 14:20:09 INFO - Downloading blessings-1.6.tar.gz 14:20:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:20:09 INFO - Installing collected packages: blessings 14:20:09 INFO - Running setup.py install for blessings 14:20:10 INFO - Successfully installed blessings 14:20:10 INFO - Cleaning up... 14:20:10 INFO - Return code: 0 14:20:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:20:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:20:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:20:10 INFO - Reading from file tmpfile_stdout 14:20:10 INFO - Current package versions: 14:20:10 INFO - blessings == 1.6 14:20:10 INFO - blobuploader == 1.2.4 14:20:10 INFO - docopt == 0.6.1 14:20:10 INFO - functools32 == 3.2.3-2 14:20:10 INFO - jsonschema == 2.5.1 14:20:10 INFO - manifestparser == 1.1 14:20:10 INFO - mozInstall == 1.12 14:20:10 INFO - mozcrash == 0.17 14:20:10 INFO - mozdebug == 0.1 14:20:10 INFO - mozdevice == 0.48 14:20:10 INFO - mozfile == 1.2 14:20:10 INFO - mozhttpd == 0.7 14:20:10 INFO - mozinfo == 0.9 14:20:10 INFO - mozleak == 0.1 14:20:10 INFO - mozlog == 3.3 14:20:10 INFO - moznetwork == 0.27 14:20:10 INFO - mozprocess == 0.23 14:20:10 INFO - mozprofile == 0.28 14:20:10 INFO - mozrunner == 6.12 14:20:10 INFO - mozscreenshot == 0.1 14:20:10 INFO - mozsystemmonitor == 0.3 14:20:10 INFO - moztest == 0.7 14:20:10 INFO - mozversion == 1.4 14:20:10 INFO - psutil == 3.1.1 14:20:10 INFO - requests == 1.2.3 14:20:10 INFO - wsgiref == 0.1.2 14:20:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:20: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:20:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:20:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:20:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:20:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:10 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 0x10495aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc832c75360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048bed30>, '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 0x104663db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048b6b20>, '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.8avJsBmJJa/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.6iiuZwnBbT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 14:20:10 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 14:20:10 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 14:20:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8avJsBmJJa/Render', 14:20:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:20:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:20:10 INFO - 'HOME': '/Users/cltbld', 14:20:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:20:10 INFO - 'LOGNAME': 'cltbld', 14:20:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:20:10 INFO - 'MOZ_NO_REMOTE': '1', 14:20:10 INFO - 'NO_EM_RESTART': '1', 14:20:10 INFO - 'PAGER': '/bin/cat', 14:20:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:20:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:20:10 INFO - 'PWD': '/builds/slave/test', 14:20:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:20:10 INFO - 'SHELL': '/bin/bash', 14:20:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6iiuZwnBbT/Listeners', 14:20:10 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 14:20:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:20:10 INFO - 'USER': 'cltbld', 14:20:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:20:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:20:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:20:10 INFO - 'XPC_FLAGS': '0x0', 14:20:10 INFO - 'XPC_SERVICE_NAME': '0', 14:20:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:20:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:20:10 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)) 14:20:10 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)) 14:20:10 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)) 14:20:10 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)) 14:20:10 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)) 14:20:10 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)) 14:20:10 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)) 14:20:10 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)) 14:20:10 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)) 14:20:10 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)) 14:20:10 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)) 14:20:10 INFO - Cleaning up... 14:20:10 INFO - Return code: 0 14:20:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:20: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:20:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:20:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:20:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:20:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:10 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 0x10495aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc832c75360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048bed30>, '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 0x104663db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048b6b20>, '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.8avJsBmJJa/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.6iiuZwnBbT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 14:20:10 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 14:20:10 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 14:20:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8avJsBmJJa/Render', 14:20:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:20:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:20:10 INFO - 'HOME': '/Users/cltbld', 14:20:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:20:10 INFO - 'LOGNAME': 'cltbld', 14:20:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:20:10 INFO - 'MOZ_NO_REMOTE': '1', 14:20:10 INFO - 'NO_EM_RESTART': '1', 14:20:10 INFO - 'PAGER': '/bin/cat', 14:20:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:20:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:20:10 INFO - 'PWD': '/builds/slave/test', 14:20:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:20:10 INFO - 'SHELL': '/bin/bash', 14:20:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6iiuZwnBbT/Listeners', 14:20:10 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 14:20:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:20:10 INFO - 'USER': 'cltbld', 14:20:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:20:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:20:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:20:10 INFO - 'XPC_FLAGS': '0x0', 14:20:10 INFO - 'XPC_SERVICE_NAME': '0', 14:20:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:20:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:20:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:20:10 INFO - Cleaning up... 14:20:11 INFO - Return code: 0 14:20:11 INFO - Running post-action listener: _resource_record_post_action 14:20:11 INFO - Running post-action listener: _start_resource_monitoring 14:20:11 INFO - Starting resource monitoring. 14:20:11 INFO - [mozharness: 2016-09-20 21:20:11.026755Z] Finished create-virtualenv step (success) 14:20:11 INFO - [mozharness: 2016-09-20 21:20:11.027710Z] Running install step. 14:20:11 INFO - Running pre-action listener: _resource_record_pre_action 14:20:11 INFO - Running main action method: install 14:20:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:20:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:20:11 INFO - Reading from file tmpfile_stdout 14:20:11 INFO - Detecting whether we're running mozinstall >=1.0... 14:20:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:20:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:20:11 INFO - Reading from file tmpfile_stdout 14:20:11 INFO - Output received: 14:20:11 INFO - Usage: mozinstall [options] installer 14:20:11 INFO - Options: 14:20:11 INFO - -h, --help show this help message and exit 14:20:11 INFO - -d DEST, --destination=DEST 14:20:11 INFO - Directory to install application into. [default: 14:20:11 INFO - "/builds/slave/test"] 14:20:11 INFO - --app=APP Application being installed. [default: firefox] 14:20:11 INFO - mkdir: /builds/slave/test/build/application 14:20:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:20:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.mac.dmg --destination /builds/slave/test/build/application 14:20:34 INFO - Reading from file tmpfile_stdout 14:20:34 INFO - Output received: 14:20:34 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 14:20:34 INFO - Running post-action listener: _resource_record_post_action 14:20:34 INFO - [mozharness: 2016-09-20 21:20:34.003585Z] Finished install step (success) 14:20:34 INFO - [mozharness: 2016-09-20 21:20:34.003718Z] Running run-tests step. 14:20:34 INFO - Running pre-action listener: _resource_record_pre_action 14:20:34 INFO - Running main action method: run_tests 14:20:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:20:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:20:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:20:34 INFO - 2016-09-20 14:20:34.018 screenresolution[1953:65049] starting screenresolution argv=screenresolution get 14:20:34 INFO - 2016-09-20 14:20:34.021 screenresolution[1953:65049] Display 0: 1600x1200x32@60 14:20:34 INFO - 2016-09-20 14:20:34.027 screenresolution[1954:65052] starting screenresolution argv=screenresolution list 14:20:34 INFO - Available Modes on Display 0 14:20:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:20:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:20:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:20:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:20:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:20:34 INFO - Intel Iris: 14:20:34 INFO - Chipset Model: Intel Iris 14:20:34 INFO - Type: GPU 14:20:34 INFO - Bus: Built-In 14:20:34 INFO - VRAM (Dynamic, Max): 1536 MB 14:20:34 INFO - Vendor: Intel (0x8086) 14:20:34 INFO - Device ID: 0x0a2e 14:20:34 INFO - Revision ID: 0x0009 14:20:34 INFO - Displays: 14:20:34 INFO - Display: 14:20:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:20:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:20:34 INFO - Display Serial Number: 1600x1200 60 14:20:34 INFO - Main Display: Yes 14:20:34 INFO - Mirror: Off 14:20:34 INFO - Online: Yes 14:20:34 INFO - Rotation: Supported 14:20:34 INFO - Return code: 0 14:20:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:20:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:20:34 INFO - Python 2.7.3 14:20:34 INFO - Return code: 0 14:20:34 INFO - grabbing minidump binary from tooltool 14:20:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:20:34 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 0x1048bed30>, '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 0x104663db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048b6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:20:34 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 14:20:34 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 14:20:34 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 14:20:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:20:34 INFO - Return code: 0 14:20:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:20:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:20:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:20:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:20:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:20:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0357', '--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 14:20:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0357 --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 14:20:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8avJsBmJJa/Render', 14:20:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:20:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:20:34 INFO - 'HOME': '/Users/cltbld', 14:20:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:20:34 INFO - 'LOGNAME': 'cltbld', 14:20:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:20:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:20:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:20:34 INFO - 'MOZ_NO_REMOTE': '1', 14:20:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:20:34 INFO - 'NO_EM_RESTART': '1', 14:20:34 INFO - 'PAGER': '/bin/cat', 14:20:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:20:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:20:34 INFO - 'PWD': '/builds/slave/test', 14:20:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:20:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:20:34 INFO - 'SHELL': '/bin/bash', 14:20:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6iiuZwnBbT/Listeners', 14:20:34 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 14:20:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:20:34 INFO - 'USER': 'cltbld', 14:20:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:20:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:20:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:20:34 INFO - 'XPC_FLAGS': '0x0', 14:20:34 INFO - 'XPC_SERVICE_NAME': '0', 14:20:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:20:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0357', '--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 14:20:34 INFO - mozversion application_buildid: 20160920092516 14:20:34 INFO - mozversion application_changeset: 6be9a5c9c8cd25d2082376495b788d85ce3cca98 14:20:34 INFO - mozversion application_display_name: Firefox 14:20:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:20:34 INFO - mozversion application_name: Firefox 14:20:34 INFO - mozversion application_remotingname: firefox 14:20:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 14:20:34 INFO - mozversion application_vendor: Mozilla 14:20:34 INFO - mozversion application_version: 50.0 14:20:34 INFO - mozversion platform_buildid: 20160920092516 14:20:34 INFO - mozversion platform_changeset: 6be9a5c9c8cd25d2082376495b788d85ce3cca98 14:20:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 14:20:34 INFO - mozversion platform_version: 50.0 14:20:34 INFO - using testdate: 1474406434 14:20:34 INFO - actual date: 1474406434 14:20:34 INFO - starting webserver on 'localhost:53756' 14:20:34 INFO - SUITE-START | Running 2 tests 14:20:34 INFO - TEST-START | dromaeo_css 14:20:34 INFO - operating with platform_type : mac_ 14:20:34 INFO - Initialising browser for dromaeo_css test... 14:20:34 INFO - TEST-INFO | started process 1964 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/tmpIuq_as/profile http://localhost:53756/getInfo.html) 14:20:37 INFO - PROCESS | 1964 | __metrics Screen width/height:1600/1200 14:20:37 INFO - PROCESS | 1964 | colorDepth:24 14:20:37 INFO - PROCESS | 1964 | Browser inner width/height: 1024/689 14:20:37 INFO - PROCESS | 1964 | __metrics 14:20:37 ERROR - PROCESS | 1964 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 14:20:37 INFO - TEST-INFO | 1964: exit 0 14:20:37 INFO - Browser initialized. 14:20:37 INFO - Running cycle 1/1 for dromaeo_css test... 14:20:37 INFO - TEST-INFO | started process 1968 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/tmpIuq_as/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:20:49 INFO - PROCESS | 1968 | RSS: Main: 174731264 14:20:49 INFO - PROCESS | 1968 | 14:21:39 INFO - PROCESS | 1968 | 1474406499402 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 14:23:49 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:53756/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53756/tests/dromaeo/cssquery-ext.html) 14:23:51 INFO - PROCESS | 1968 | RSS: Main: 187932672 14:23:51 INFO - PROCESS | 1968 | 14:26:53 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:53756/tests/dromaeo/cssquery-ext.html (next: http://localhost:53756/tests/dromaeo/cssquery-jquery.html) 14:26:53 INFO - PROCESS | 1968 | RSS: Main: 198279168 14:26:53 INFO - PROCESS | 1968 | 14:29:55 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:53756/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53756/tests/dromaeo/cssquery-mootools.html) 14:29:56 INFO - PROCESS | 1968 | RSS: Main: 200679424 14:29:56 INFO - PROCESS | 1968 | 14:32:58 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:53756/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53756/tests/dromaeo/cssquery-prototype.html) 14:32:58 INFO - PROCESS | 1968 | RSS: Main: 204881920 14:32:58 INFO - PROCESS | 1968 | 14:36:01 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:53756/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53756/tests/dromaeo/cssquery-yui.html) 14:36:01 INFO - PROCESS | 1968 | RSS: Main: 206925824 14:36:01 INFO - PROCESS | 1968 | 14:39:04 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:53756/tests/dromaeo/cssquery-yui.html (next: http://localhost:53756/tests/dromaeo/cssquery-dojo.html) 14:39:04 INFO - PROCESS | 1968 | RSS: Main: 206090240 14:39:04 INFO - PROCESS | 1968 | 14:39:04 INFO - PROCESS | 1968 | __start_tp_report 14:39:04 INFO - PROCESS | 1968 | _x_x_mozilla_page_load 14:39:04 INFO - PROCESS | 1968 | _x_x_mozilla_page_load_details 14:39:04 INFO - PROCESS | 1968 | |i|pagename|runs| 14:39:04 INFO - PROCESS | 1968 | |0;dojo.html;3001.8949336773217;3031.242189452637;3308.206030552667;3602.2615363850414;3615.9096022599438;5992.850178745528;6052.909206361908;6083.908741368883;6103.786367477139;6123.908141377878;5987.670678112702;6069.241344831896;6069.908951365733;6070.484008859252;6078.088286756989;5368.892622147555;5384.973075134619;5406;5456.508914197721;5540.529055030327;5349.545288650469;5380.91928621071;5419.3225846769155;5458.426865179159;5488.91766623501;5457.508824205821;5457.754401051942;5466.01611709892;5479.068558345091;5481.835544933639;5331.067063263952;5453.182022696627;5457.426970168154;5458.017556839767;5460.918086228709;5272.736363181825;5305.4164041955355;5330.946690533123;5356.9732151339385;5391.649542779707;6021.42796434338;6035.6076855004285;6048.909266361008;6122.4183702548635;6128.754849806002;6175.104609831549;6183.04193715405;6265.96867015662;6288.308286088527;6408.775692850728;5288.947110528884;6193.411625895577;6236.43872051513;6271.435570798604;6353.491720662336;6087.360827113133;6093.908591371133;6094.451499365034;6109;6126.663033533154;6407.199100112486;6454.935450645527;6457.2574153971955;6463.48292136628;6477.060826180177;6145.955187618116;6163.630182189083;6173.166622505994;6206.131141640174;6233.719482623293;6256;6256.343083976208;6263;6265.185525881606;6269;6220;6244.46922011625;6255.093011513305;6255.343188965184;6276.623402595859;5701.688611619268;6147.692615369214;6162.506999440034;6186.566940314225;6237.220347456568;4812.446568644581;5087;5100.948990510122;5102.948970510248;5104.3109180260935;4572.337011133433;4573.336866154389;4577;4582.95417045832;4587.885302867455;5994.190784244159;6081.118237855486;6119.5104391648565;6142.682951206299;6145.801568694151;6342.619442833445;6342.904856427157;6356.14192084072;6358.33237510064;6372.203474565679;6117.938820611826;6202.627842329474;6207.689615519206;6215.906761398583;6218.533609979269;5532.6680399175375;5569;5627.690477023803;5657.490825825613;5727.570432217601;6579.111819904348;6626.53614246998;6628.337166283333;6658.367455091856;6750.628715420503;6334.113224148531;6380.170577824853;6389.201349831271;6409.487241020801;6424.100625912282;6310.085037669604;6377.202849643794;6515.12045873801;6519.217693876765;6592.110065141144;6298.1812364392335;6307.400796924285;6325.240971083499;6337.96831015842;6338.492920566436;6208.161898143873;6309;6366.076918846834;6377.394147555975;6380.170577824853;6146.170267013896;6176.66028368444;6213.409726076015;6258.155149055002;6273.65494897785;6201.6899155041165;6234.875302493834;6244.625322480666;6247.531435142291;6373.968130159319;4659.858334708018;6175.228096487939;6177.289611694712;6212.844678883064;6224.128621993015;6157.261128664589;6158.476529495043;6168.599041062316;6202.906956395567;6272.529560282906;6153.077038444269;6194.039923811816;6202.162708034358;6204.875902482016;6208.937910620836 14:39:04 INFO - PROCESS | 1968 | |1;ext.html;506.79888663866643;523.7041071794436;532.6484520216658;541.7101850509978;549.7718446844558;38049.23901521971;38277.04307392313;40228.59771402285;42194.36708449372;42398.36402453958;42591;42763;43055;43284.783576082074;43365.7831710841;8382.706605268817;9811.362261453018;9824.656137035205;9832.803343933118;9900.108990190882;9761.219102471785;9854.852177217348;9856.605735770561;9885.110340069392;9895.406275623485;9832.950835245814;9849.606015759397;9860.60557577696;9880.308378413514;9946.950265248699;9786.657466988658;9836.114749672564;9846.507674616241;9868.259880508955;9951.950240248752;9720.270979676552;9770.9511452443;9794.363366381162;9799.90200097997;9842.753931151707;41513.1697366051;41869.581304186875;41873.79063104665;41888.581114188775;42010.57989420097;3582.7671201372086;3696.0390298522307;3912.68698504119;3969.0474286171398;4414.955850441519;31520.684793152006;32498.675013249802;33200.501992470134;33243.833780831184;34267.80062697818;3162.826044567548;3199.2641692410643;3212.3093534890113;3307.8676852925855;3321.70104690579;484640;524204.3789781065;527465;528680;529948;64225;64647.67676161683;64745.676271618344;64828;65163.67418162879;36668.44997325042;37027.25945481129;37189.88430347094;38522.80738596289;38540.84377468681;17824.55438614045;17843.197056132507;17950.461486155436;17965.101744912703;17977.910110449364;13423.26172060546;13559.66100847467;13590.660233494242;13608.047436679535;13612.523561675294;20140.89929550343;20167.394978150678;20334.288299909433;20364;20396.0821763021;8072.677092916335;8441.198086181803;8506.191911768372;8518.787030324167;8542.273906718;919.6965001549472;940.6707652321633;1070.347088276151;1101.4712937789907;1141.4863311509853;31683.366332673217;31798.523022154684;31814.363712725615;32367;32419.865304714695;1077.2190162132392;1106.5076041161606;1138.2658185470325;1198.4127777389017;1232.1251911143188;15774.447894323874;16658;16710.415135470445;16719.749203761952;16884.324627014783;709925;714479;720165;722538;722960;61811;62391;62501.687491562254;62629.74740505165;62731.37268627347;1470.6396932751331;1477.4828809916444;1501.5645462815662;1508.2760275068028;1524.9695006099817;30279.69720302769;31293;31414.528782068744;31522;31855.36289274248;52915.73542132265;52921;53101.7344913273;53382.73308633432;53452.198217025994;571322;576685;582177;585537;586260;27899;27912.883484661197;28270.434591307647;28416.147515574565;28698.282542936595;49105.508944911526;49736.005279894925;50108;50771.74614126905;50844.74577627088;40657.39013914735;40951.38572921349;41950.160996780505;42519.362209566265;42560.57439425566;40947.38578921378;41789.37315940203;41857.581424185366;42178;42330.78834605807;36791.81604091962;39046.80476597599;39279.83960882179;39536.802315988236;39982.800085999384;35073.1231719209;36389;36940;37080;37152;24304.635430468203;26635.60046599341;26940.865295673397;27142;27198 14:39:04 INFO - PROCESS | 1968 | |2;jquery.html;354.19361257586297;382.91634673872943;398.8763483320171;407.3848488781939;420.1366192474464;19085.809141908576;20557.486062848435;20650.6902396464;21876.1249550018;22516.887415562942;21725.13099476019;21773.346799595998;22256.66615000772;22276.777232227672;22510.54978900421;5933.940660593382;6084.847878803043;6108.725107370156;6199.69001549923;6218.9427797274575;6000.129981152731;6065.120557519157;6071.969640151793;6076.997295446246;6115.143879856823;5955.731992060368;6039.1545183674325;6098.908516372258;6112.358202388752;6112.969435152818;5951.880962380771;5952.255968003999;5952.285725712899;6029.155918171459;6103.176071230394;5214.582833373359;5244.921326180111;5257.13257312543;5259.132243179871;5305.31030965972;27163;27291.45417091667;27368.863155684292;27387.31531711723;27429.17712468629;2339.3449834046496;2366.5503554324623;2697.2312890826215;3231.305269367074;3250.934981300361;15739.68520629581;16330.101844398554;16379.508614741577;16433.506994790056;16550.917245413817;1843.3456123076248;1932.478230877655;1957.0214892553722;1977.031254685194;2061.134323584083;92954;123033;123402.38298808539;131937;140830;10620.787584248426;11072.778544429066;11651;12190.451429685685;12259.877401225873;12436.75632436749;15009.849901500846;15145.091294522364;15362.231888405535;15367.61580960485;8585.742427727178;8920.330975176757;8923.598438070303;9022.819543609223;9072.183503484717;7707.421943354271;8802.339824513187;8835.734927952171;8865.955670221736;9043.050479699668;4141.716068018924;4339.34909763538;4638.1187574360765;4653.255479123324;4766.547178018082;1997.4007797660643;2065.0810389376584;2073.02567793137;2109.093089971296;2125.203048856679;815.8205194857122;853.2022558907317;946.7822400847843;1008.4856723071287;1106.9391183484986;20817.479563011046;21838.45403864916;21885.562288754136;21923.78076219249;22138.335849924206;1000.799840031982;1032.2774058159318;1088.1240601316017;1107.5901916290863;1112.1714322829544;10034.899651003543;10972.670819875417;11662;11682.007029402426;11693.532258709556;641265;647612;652800;653048;658668;78808.81786773202;116398.8360116405;136144.63855361516;136892;137685;967.7483854197818;967.8596603492454;995.64654547636;1002.774375765447;1005.2058873490007;14576.85423145755;15946.601334966255;16039.919800400923;17231.53531949748;17796.733049004015;45609.71951402483;53337.73331133319;55673;58176.70911645584;58514.70742646259;86595;88052.11947880696;89688;90325.64511532201;91803.54098229467;7104.786856394317;7673.808154796175;7733.033370828646;7813.1796161402235;7995;10951.397673128055;11124.721881953015;13089.018323625956;13201.471941122247;13486.05597608158;11663.367715368526;11759.412029398325;11894.583689570823;11940.701482463008;11979.101567382655;9613.51932403363;9871.851922221029;11493.597724079616;11495.827562586735;11588.6523404298;9576.808463830825;11082.44587770592;11214.551417943518;11477.196596238184;11994.340311282614;7620.542767433972;7770.26182512683;7789.376849851885;7802.024746906637;7894.6842126314305;10920.781584368428;11526.135539834379;12094.879051209375;12135.968442682472;12169.81745273804 14:39:04 INFO - PROCESS | 1968 | |3;mootools.html;1402.9438822447103;1621.2380181314786;1658.4692898272554;1949.0936714427787;2025.6455120353944;7073.434125269979;8267.503949763019;8858.77853053674;9386.296027797916;9423.34036617437;8926.910730892705;9061.32040096992;9142;9149.496777677225;9149.634014639423;2410.9156179533766;2448.7673671001226;2452.3010941881566;2452.3869032741813;2462.642916777066;2054.219396629279;2390.5935990881594;2417.601095819188;2434.9634755478683;2440.170342083691;2418.9879050604723;2422.0190822716827;2430.635404689302;2431.4650776829158;2445.0464318915638;2377.512609914972;2383.8688872112025;2386.976130238693;2410.5419970205694;2421.019487107724;2384.1536254629705;2425.345156807652;2427.186892391051;2428.4171798768343;2438.451348446603;9273.350865439423;9288.582013809328;9315.767105822408;9353.953230233874;9381.061893810633;4605.792739326738;5343.866403339909;5404.1083221268445;5490.890182196334;5493.7802487900435;3609.9819500902327;3706.4625629283873;3719.516462859838;3759.0602349412648;3768.2275133597545;1085.6905781852133;1092.5247517329988;1093.7101668057971;1105.7125147461638;1110.211749657744;10398.428086455242;12224.572139975147;12362;12376.62870113898;12384.81422778659;4120.670346372283;4462.821487140478;4662.906741865144;4695.413073365829;4743.66794324394;9476.241900647932;9478.857817132748;9540.952295238618;9566.426014439017;9597.568109434953;5379.166229234396;5394.0290747665795;5395.78416863321;5414.945850541444;5443.1563107717575;4601.28680054592;4906.852794416173;5015.899682006339;5053.46938571452;5069.264956581349;3954.2289253595272;3968.464257325282;3968.722189446711;4077.3680079587116;4090.2024105299774;1197.8562572491358;1205.764875849201;1208.7824191645589;1210.9576164834189;1212.8483939507562;881.5460038080394;964.8745663063756;976.7753416714196;997.7256254529917;1021.2902033087082;6086.513078953762;6145.4161854623735;6172.45995933948;6257.155284036597;6265;429.46102641185115;431.9784010799447;439.2269605494353;1059.2638116509106;1114.2924243105685;4199.958000420018;4216.789160541961;4252.744835309892;4262.829486820555;4270.145970805789;10822.621208257673;11050;11160.553577856796;11315.603953861575;11345.319280843176;11054.392008439612;11115.555377784798;11136.610218642309;11202.159838012181;11272.943635281772;8169.387295952827;8435.915640843636;8586.227239548345;8624.96500419941;8689.217970382695;5199.272101905661;6300.495960323255;6332.778352757631;6363.459105976044;6399.64801935898;10235.232357573062;10508.684739457523;10509.842352364874;10729.409882456539;10763.946180269362;10625.681229562817;10763.677089687322;10860.945695271474;10897.727556811144;11018.063464605599;6891.173059232924;7316.926830731624;7770.92229077702;7958.442908996315;8060.919390806252;7740.651670674721;8417.284530814704;8771.780705482159;8950.060243674321;8965.417247879128;6783.287754785873;7678.001859758196;7691.038620172479;7848.686052557834;7953.6818527258265;7366.152892417441;7533.209013053551;7549.9245007549225;7568.3945284378215;7570.280823321776;7347.302006309392;7482.438817088849;7503.7748867534065;7517.511361761468;7530.510516816389;5152.227165925141;5220.3735551734035;5246.921296180484;5299.735013249399;5378.596605254544;5197.0905091608065;5295.152775555893;5364.490373414519;5413.918791218056;5471.398146203835 14:39:04 INFO - PROCESS | 1968 | |4;prototype.html;286.57872926797614;293.31510921997136;300.09670890619236;300.8089862937037;303.19199333775487;4619.422572178478;5080.882205914702;5246.632735708502;5319.175527793193;5575.470330318613;4439.689221754478;5039.748012599373;5231.848993221494;5312.362516498016;5467.726613669321;3510.5436293281837;3626.9455958160647;3718.2670737008343;4053.29067413203;4127.215828992482;3296.390167818949;3528.5060091587197;4058.8376464941366;4110.568390319018;4138.503379594453;3777.6589310794684;4013.1171142348635;4049.048473608714;4057.6145266199665;4085.2442298174783;3008.3381656035635;4033.596640335972;4086.8569600063856;4093.406456063854;4106.650934670557;2958.4526862530283;3394.2023624448234;3532.5054492371087;3533.452314891196;3561.821908904544;4877.244027175758;4943.678660887031;5318.787248510055;5338.62629615927;5341.385740639837;4641.955559999007;4739.668223224376;4744.667873248839;4745.359376484165;4782.473927867932;5016.698998060128;5551.639143455663;5820.156077368758;5868.855573163235;5899.498542623882;2923.985380073107;3363.4954756786433;3745.6141227745634;3836.232753449321;3907.8436862525464;5256.7371631418655;5752.36723960364;5823.854403639901;5826.446487583673;6066.4530544710815;3020.2902317955263;3863.632954869283;3897.2205558888336;3920.3727403615285;3926.293267211929;3323.4682450808236;3579.4093974493917;3966.6033396660105;3976.562578116405;4001.359782434855;4620.584147426748;4633.142868569325;4663.580277775017;4676.228422310349;4701.882452938636;4844.8304309349705;5867.325257595346;5887.970560147171;5893.882122357529;5899.7050147492455;1048.1457612046158;1291.9418626161846;1324.6754545136503;1341.9597412077653;1360.4830164537611;730.2952650692108;879.9648014079493;880.238593616517;896.852019416802;925.379995403068;4637.652176086807;5203;5434.673919564839;5487.5609951203805;5539.529140023144;5519.896020795776;5556;5608.074667679787;5980.700964951735;6023.879522409527;5191.610629202825;5474.507294343449;5492.340919089735;5503.147012213033;5582.441755824385;2776.805623606328;2792.762615177692;3013.9903132450436;3188.1073299476184;3192.952105718416;4984.601231901512;5975.67133807636;6276.399518122941;6313.747450102036;6361.427471527492;5481.479259470344;5725.2843394575675;5819.82540523785;5820.594951953155;6083.7566497340495;5752.33848107473;6283.371662833772;6287.685615719196;6292.276388215414;6298.968505157445;5122.487751224848;5263.605229607718;5713.857153571194;6006.219191505251;6019.110826803071;4945.703257804544;5249.763760630857;5967.910481342697;5978.671173085348;6011.519078473799;5166.535011848951;5446.945530544803;6219.0982307566055;6233.937660623335;6275.74897004133;3032.363203727242;3235.8705651773666;3718.888433347004;3746.007308063311;3750.699944004528;4875.000624871964;5235.063026680153;5705.543556515386;5943.346231914399;5955.940440595538;5348.358197016383;5711.371749107512;5843.561732869966;5877.4122587740885;5955.225820643289;4656.346996816082;5575.77696892136;5882.5293376781;5891.49922256613;5906.552894540693;4415.4259946206785;5646.131099063578;5711.771529138788;5763.913541296968;5774.249347584787;5230.875361797232;5855.2973643163095;5889.705514724161;5914.704264786657;5916.816636672769;5279.1817268323475;5560.7219639018695;5738.799142030176;5769.79805706815;5798.6810725410505 14:39:04 INFO - PROCESS | 1968 | |5;yui.html;756.7351427000553;774.9651265693043;784.8312612788251;803.2850762821093;882.5013867165052;15454.07275563467;16631.916840415783;16814.99110053396;16882.831171688278;16969.406070787525;15435.69128617427;15468.922655386707;15571.98782079164;15578.766318505202;15614.453494127707;884.4781578868483;909.9590518426653;943.9575219115156;945.078548415293;949.155251825872;932.6409332407004;943.9480828554426;946.507815935713;948.1892981500821;949.3164921256719;963.6819849449665;964.7491652170417;994.3437331361303;996.3922007575374;996.4818294486862;971.4317124482186;976.0483528559633;980.9558569864338;989.3223142147652;990.1979396688705;722.989155162673;728.3372131360436;729.194240364395;743.3087228877142;743.8995735575683;15481;15711.057336559843;15931.681366372608;15946.043237405793;16047.35810567576;14839.925800371037;14936.32786524609;14971.850281497263;14986.175760333177;14991.100533967889;12310.13829031968;12487.625371238877;12562.74874502514;12804.103712740112;12833.101682882201;3892.961070389288;3964.5638979712207;4016.9397459038137;4023.6579890709327;4038.676905847525;105990;107155;108519.91480085177;109448.35827462595;110597;10234.64178753755;10418.479076046167;11381.975622194042;11494.482748276349;11892.524299028115;22107.557848842935;22874.97577435094;22974.65538016931;23119.844007799544;23168.65247021296;8441.493510389397;8492.872606910902;8559.95720021396;8608.182222688834;8647.956760216159;3998.4801975742967;4443.4445694288215;4611.492735799059;4670.322803193586;4820.421549414093;2953;3693.1874987502706;3725.683316918038;3734.9626503735158;3735.7384983050924;542.4819297570889;645.9870802583922;649.9837504062436;659.7558903205845;661.1372159332118;620.5749061892528;705.6189657584949;713.7894321175265;714.7641278378122;736.5543845973216;9556.426614403159;10158.898411015794;10230.079292863678;10410.739731506774;10411.94794026025;574.3395095639964;594.5362617158623;701.8806802843529;715.9856802864018;725.1841678112124;4732.503087175865;5002.2496625506465;5058.266551350033;5065.8733531662;5070.797168113308;173083.40374894388;177303;178431;178469;182368;113890;114477;114540;133665;134584;106717.46641266743;109350;115519;117939.41030294965;118780.81219187871;9393.154616084585;10108.685870836744;10208.081272685495;10667.733306667395;10796.946015269874;63097.16062777094;63732;64258.678706606166;65655.0303490896;66440;95427;97765.51117244369;101584;101799;103709.48145259226;13364.799528006894;13548.254845983565;13745.518906838213;13756.724865502434;13805.240711760547;15144.697106057596;15724.685506289581;15853.762193566876;15907.840921590636;15943.202839858193;14109.929450353093;14440.494582689555;14549.272536373352;14854.77717834255;15488.922555387602;14295.422746352106;14465.13209207451;14631.92684036573;15010.324535396152;15209.239538023276;11450.37022963745;13765.38055787472;14311.141331520143;15024.924875375553;15151.848481515484;10523.947380263357;11551.249168804;12280.631581052583;12373.443195056065;12509.687257818627;12012.159148859497;12232;12253.754924901274;12503.249805011728;12520.624381268577 14:39:04 INFO - PROCESS | 1968 | __end_tp_report 14:39:04 INFO - PROCESS | 1968 | __start_cc_report 14:39:04 INFO - PROCESS | 1968 | _x_x_mozilla_cycle_collect,422 14:39:04 INFO - PROCESS | 1968 | __end_cc_report 14:39:04 INFO - PROCESS | 1968 | __startTimestamp1474407544676__endTimestamp 14:39:04 INFO - PROCESS | 1968 | 14:39:04 INFO - PROCESS | 1968 | ------- Summary: start ------- 14:39:04 INFO - PROCESS | 1968 | Number of tests: 6 14:39:04 INFO - PROCESS | 1968 | 14:39:04 INFO - PROCESS | 1968 | [#0] dojo.html Cycles:165 Average:5910.53 Median:6168.60 stddev:660.63 (10.7%) stddev-sans-first:622.01 14:39:04 INFO - PROCESS | 1968 | Values: 3001.9 3031.2 3308.2 3602.3 3615.9 5992.9 6052.9 6083.9 6103.8 6123.9 5987.7 6069.2 6069.9 6070.5 6078.1 5368.9 5385.0 5406.0 5456.5 5540.5 5349.5 5380.9 5419.3 5458.4 5488.9 5457.5 5457.8 5466.0 5479.1 5481.8 5331.1 5453.2 5457.4 5458.0 5460.9 5272.7 5305.4 5330.9 5357.0 5391.6 6021.4 6035.6 6048.9 6122.4 6128.8 6175.1 6183.0 6266.0 6288.3 6408.8 5288.9 6193.4 6236.4 6271.4 6353.5 6087.4 6093.9 6094.5 6109.0 6126.7 6407.2 6454.9 6457.3 6463.5 6477.1 6146.0 6163.6 6173.2 6206.1 6233.7 6256.0 6256.3 6263.0 6265.2 6269.0 6220.0 6244.5 6255.1 6255.3 6276.6 5701.7 6147.7 6162.5 6186.6 6237.2 4812.4 5087.0 5100.9 5102.9 5104.3 4572.3 4573.3 4577.0 4583.0 4587.9 5994.2 6081.1 6119.5 6142.7 6145.8 6342.6 6342.9 6356.1 6358.3 6372.2 6117.9 6202.6 6207.7 6215.9 6218.5 5532.7 5569.0 5627.7 5657.5 5727.6 6579.1 6626.5 6628.3 6658.4 6750.6 6334.1 6380.2 6389.2 6409.5 6424.1 6310.1 6377.2 6515.1 6519.2 6592.1 6298.2 6307.4 6325.2 6338.0 6338.5 6208.2 6309.0 6366.1 6377.4 6380.2 6146.2 6176.7 6213.4 6258.2 6273.7 6201.7 6234.9 6244.6 6247.5 6374.0 4659.9 6175.2 6177.3 6212.8 6224.1 6157.3 6158.5 6168.6 6202.9 6272.5 6153.1 6194.0 6202.2 6204.9 6208.9 14:39:04 INFO - PROCESS | 1968 | 14:39:04 INFO - PROCESS | 1968 | [#1] ext.html Cycles:180 Average:73816.36 Median:41863.58 stddev:163644.46 (390.9%) stddev-sans-first:164010.96 14:39:04 INFO - PROCESS | 1968 | Values: 506.8 523.7 532.6 541.7 549.8 38049.2 38277.0 40228.6 42194.4 42398.4 42591.0 42763.0 43055.0 43284.8 43365.8 8382.7 9811.4 9824.7 9832.8 9900.1 9761.2 9854.9 9856.6 9885.1 9895.4 9833.0 9849.6 9860.6 9880.3 9947.0 9786.7 9836.1 9846.5 9868.3 9952.0 9720.3 9771.0 9794.4 9799.9 9842.8 41513.2 41869.6 41873.8 41888.6 42010.6 3582.8 3696.0 3912.7 3969.0 4415.0 31520.7 32498.7 33200.5 33243.8 34267.8 3162.8 3199.3 3212.3 3307.9 3321.7 484640.0 524204.4 527465.0 528680.0 529948.0 64225.0 64647.7 64745.7 64828.0 65163.7 36668.4 37027.3 37189.9 38522.8 38540.8 17824.6 17843.2 17950.5 17965.1 17977.9 13423.3 13559.7 13590.7 13608.0 13612.5 20140.9 20167.4 20334.3 20364.0 20396.1 8072.7 8441.2 8506.2 8518.8 8542.3 919.7 940.7 1070.3 1101.5 1141.5 31683.4 31798.5 31814.4 32367.0 32419.9 1077.2 1106.5 1138.3 1198.4 1232.1 15774.4 16658.0 16710.4 16719.7 16884.3 709925.0 714479.0 720165.0 722538.0 722960.0 61811.0 62391.0 62501.7 62629.7 62731.4 1470.6 1477.5 1501.6 1508.3 1525.0 30279.7 31293.0 31414.5 31522.0 31855.4 52915.7 52921.0 53101.7 53382.7 53452.2 571322.0 576685.0 582177.0 585537.0 586260.0 27899.0 27912.9 28270.4 28416.1 28698.3 49105.5 49736.0 50108.0 50771.7 50844.7 40657.4 40951.4 41950.2 42519.4 42560.6 40947.4 41789.4 41857.6 42178.0 42330.8 36791.8 39046.8 39279.8 39536.8 39982.8 35073.1 36389.0 36940.0 37080.0 37152.0 24304.6 26635.6 26940.9 27142.0 27198.0 14:39:04 INFO - PROCESS | 1968 | 14:39:04 INFO - PROCESS | 1968 | [#2] jquery.html Cycles:180 Average:37084.67 Median:2531.89 stddev:108264.18 (4276.0%) stddev-sans-first:108532.76 14:39:04 INFO - PROCESS | 1968 | Values: 354.2 382.9 398.9 407.4 420.1 19085.8 20557.5 20650.7 21876.1 22516.9 21725.1 21773.3 22256.7 22276.8 22510.5 5933.9 6084.8 6108.7 6199.7 6218.9 6000.1 6065.1 6072.0 6077.0 6115.1 5955.7 6039.2 6098.9 6112.4 6113.0 5951.9 5952.3 5952.3 6029.2 6103.2 5214.6 5244.9 5257.1 5259.1 5305.3 27163.0 27291.5 27368.9 27387.3 27429.2 2339.3 2366.6 2697.2 3231.3 3250.9 15739.7 16330.1 16379.5 16433.5 16550.9 1843.3 1932.5 1957.0 1977.0 2061.1 92954.0 123033.0 123402.4 131937.0 140830.0 10620.8 11072.8 11651.0 12190.5 12259.9 12436.8 15009.8 15145.1 15362.2 15367.6 8585.7 8920.3 8923.6 9022.8 9072.2 7707.4 8802.3 8835.7 8866.0 9043.1 4141.7 4339.3 4638.1 4653.3 4766.5 1997.4 2065.1 2073.0 2109.1 2125.2 815.8 853.2 946.8 1008.5 1106.9 20817.5 21838.5 21885.6 21923.8 22138.3 1000.8 1032.3 1088.1 1107.6 1112.2 10034.9 10972.7 11662.0 11682.0 11693.5 641265.0 647612.0 652800.0 653048.0 658668.0 78808.8 116398.8 136144.6 136892.0 137685.0 967.7 967.9 995.6 1002.8 1005.2 14576.9 15946.6 16039.9 17231.5 17796.7 45609.7 53337.7 55673.0 58176.7 58514.7 86595.0 88052.1 89688.0 90325.6 91803.5 7104.8 7673.8 7733.0 7813.2 7995.0 10951.4 11124.7 13089.0 13201.5 13486.1 11663.4 11759.4 11894.6 11940.7 11979.1 9613.5 9871.9 11493.6 11495.8 11588.7 9576.8 11082.4 11214.6 11477.2 11994.3 7620.5 7770.3 7789.4 7802.0 7894.7 10920.8 11526.1 12094.9 12136.0 12169.8 14:39:04 INFO - PROCESS | 1968 | 14:39:04 INFO - PROCESS | 1968 | [#3] mootools.html Cycles:180 Average:5893.88 Median:4679.16 stddev:3332.89 (71.2%) stddev-sans-first:3325.15 14:39:04 INFO - PROCESS | 1968 | Values: 1402.9 1621.2 1658.5 1949.1 2025.6 7073.4 8267.5 8858.8 9386.3 9423.3 8926.9 9061.3 9142.0 9149.5 9149.6 2410.9 2448.8 2452.3 2452.4 2462.6 2054.2 2390.6 2417.6 2435.0 2440.2 2419.0 2422.0 2430.6 2431.5 2445.0 2377.5 2383.9 2387.0 2410.5 2421.0 2384.2 2425.3 2427.2 2428.4 2438.5 9273.4 9288.6 9315.8 9354.0 9381.1 4605.8 5343.9 5404.1 5490.9 5493.8 3610.0 3706.5 3719.5 3759.1 3768.2 1085.7 1092.5 1093.7 1105.7 1110.2 10398.4 12224.6 12362.0 12376.6 12384.8 4120.7 4462.8 4662.9 4695.4 4743.7 9476.2 9478.9 9541.0 9566.4 9597.6 5379.2 5394.0 5395.8 5414.9 5443.2 4601.3 4906.9 5015.9 5053.5 5069.3 3954.2 3968.5 3968.7 4077.4 4090.2 1197.9 1205.8 1208.8 1211.0 1212.8 881.5 964.9 976.8 997.7 1021.3 6086.5 6145.4 6172.5 6257.2 6265.0 429.5 432.0 439.2 1059.3 1114.3 4200.0 4216.8 4252.7 4262.8 4270.1 10822.6 11050.0 11160.6 11315.6 11345.3 11054.4 11115.6 11136.6 11202.2 11272.9 8169.4 8435.9 8586.2 8625.0 8689.2 5199.3 6300.5 6332.8 6363.5 6399.6 10235.2 10508.7 10509.8 10729.4 10763.9 10625.7 10763.7 10860.9 10897.7 11018.1 6891.2 7316.9 7770.9 7958.4 8060.9 7740.7 8417.3 8771.8 8950.1 8965.4 6783.3 7678.0 7691.0 7848.7 7953.7 7366.2 7533.2 7549.9 7568.4 7570.3 7347.3 7482.4 7503.8 7517.5 7530.5 5152.2 5220.4 5246.9 5299.7 5378.6 5197.1 5295.2 5364.5 5413.9 5471.4 14:39:04 INFO - PROCESS | 1968 | 14:39:04 INFO - PROCESS | 1968 | [#4] prototype.html Cycles:180 Average:4610.82 Median:5240.85 stddev:1492.03 (28.5%) stddev-sans-first:1460.48 14:39:04 INFO - PROCESS | 1968 | Values: 286.6 293.3 300.1 300.8 303.2 4619.4 5080.9 5246.6 5319.2 5575.5 4439.7 5039.7 5231.8 5312.4 5467.7 3510.5 3626.9 3718.3 4053.3 4127.2 3296.4 3528.5 4058.8 4110.6 4138.5 3777.7 4013.1 4049.0 4057.6 4085.2 3008.3 4033.6 4086.9 4093.4 4106.7 2958.5 3394.2 3532.5 3533.5 3561.8 4877.2 4943.7 5318.8 5338.6 5341.4 4642.0 4739.7 4744.7 4745.4 4782.5 5016.7 5551.6 5820.2 5868.9 5899.5 2924.0 3363.5 3745.6 3836.2 3907.8 5256.7 5752.4 5823.9 5826.4 6066.5 3020.3 3863.6 3897.2 3920.4 3926.3 3323.5 3579.4 3966.6 3976.6 4001.4 4620.6 4633.1 4663.6 4676.2 4701.9 4844.8 5867.3 5888.0 5893.9 5899.7 1048.1 1291.9 1324.7 1342.0 1360.5 730.3 880.0 880.2 896.9 925.4 4637.7 5203.0 5434.7 5487.6 5539.5 5519.9 5556.0 5608.1 5980.7 6023.9 5191.6 5474.5 5492.3 5503.1 5582.4 2776.8 2792.8 3014.0 3188.1 3193.0 4984.6 5975.7 6276.4 6313.7 6361.4 5481.5 5725.3 5819.8 5820.6 6083.8 5752.3 6283.4 6287.7 6292.3 6299.0 5122.5 5263.6 5713.9 6006.2 6019.1 4945.7 5249.8 5967.9 5978.7 6011.5 5166.5 5446.9 6219.1 6233.9 6275.7 3032.4 3235.9 3718.9 3746.0 3750.7 4875.0 5235.1 5705.5 5943.3 5955.9 5348.4 5711.4 5843.6 5877.4 5955.2 4656.3 5575.8 5882.5 5891.5 5906.6 4415.4 5646.1 5711.8 5763.9 5774.2 5230.9 5855.3 5889.7 5914.7 5916.8 5279.2 5560.7 5738.8 5769.8 5798.7 14:39:04 INFO - PROCESS | 1968 | 14:39:04 INFO - PROCESS | 1968 | [#5] yui.html Cycles:180 Average:26290.54 Median:95026.40 stddev:42351.39 (44.6%) stddev-sans-first:42426.80 14:39:04 INFO - PROCESS | 1968 | Values: 756.7 775.0 784.8 803.3 882.5 15454.1 16631.9 16815.0 16882.8 16969.4 15435.7 15468.9 15572.0 15578.8 15614.5 884.5 910.0 944.0 945.1 949.2 932.6 943.9 946.5 948.2 949.3 963.7 964.7 994.3 996.4 996.5 971.4 976.0 981.0 989.3 990.2 723.0 728.3 729.2 743.3 743.9 15481.0 15711.1 15931.7 15946.0 16047.4 14839.9 14936.3 14971.9 14986.2 14991.1 12310.1 12487.6 12562.7 12804.1 12833.1 3893.0 3964.6 4016.9 4023.7 4038.7 105990.0 107155.0 108519.9 109448.4 110597.0 10234.6 10418.5 11382.0 11494.5 11892.5 22107.6 22875.0 22974.7 23119.8 23168.7 8441.5 8492.9 8560.0 8608.2 8648.0 3998.5 4443.4 4611.5 4670.3 4820.4 2953.0 3693.2 3725.7 3735.0 3735.7 542.5 646.0 650.0 659.8 661.1 620.6 705.6 713.8 714.8 736.6 9556.4 10158.9 10230.1 10410.7 10411.9 574.3 594.5 701.9 716.0 725.2 4732.5 5002.2 5058.3 5065.9 5070.8 173083.4 177303.0 178431.0 178469.0 182368.0 113890.0 114477.0 114540.0 133665.0 134584.0 106717.5 109350.0 115519.0 117939.4 118780.8 9393.2 10108.7 10208.1 10667.7 10796.9 63097.2 63732.0 64258.7 65655.0 66440.0 95427.0 97765.5 101584.0 101799.0 103709.5 13364.8 13548.3 13745.5 13756.7 13805.2 15144.7 15724.7 15853.8 15907.8 15943.2 14109.9 14440.5 14549.3 14854.8 15488.9 14295.4 14465.1 14631.9 15010.3 15209.2 11450.4 13765.4 14311.1 15024.9 15151.8 10523.9 11551.2 12280.6 12373.4 12509.7 12012.2 12232.0 12253.8 12503.2 12520.6 14:39:04 INFO - PROCESS | 1968 | -------- Summary: end -------- 14:39:04 INFO - PROCESS | 1968 | 14:39:05 INFO - TEST-INFO | 1968: exit 0 14:39:05 INFO - TEST-OK | dromaeo_css | took 1110588ms 14:39:05 INFO - TEST-START | kraken 14:39:05 INFO - operating with platform_type : mac_ 14:39:05 INFO - Initialising browser for kraken test... 14:39:05 INFO - TEST-INFO | started process 1973 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/tmpDQ5k6V/profile http://localhost:53756/getInfo.html) 14:39:07 INFO - PROCESS | 1973 | __metrics Screen width/height:1600/1200 14:39:07 INFO - PROCESS | 1973 | colorDepth:24 14:39:07 INFO - PROCESS | 1973 | Browser inner width/height: 1024/689 14:39:07 INFO - PROCESS | 1973 | __metrics 14:39:07 ERROR - PROCESS | 1973 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 14:39:08 INFO - TEST-INFO | 1973: exit 0 14:39:08 INFO - Browser initialized. 14:39:08 INFO - Running cycle 1/1 for kraken test... 14:39:08 INFO - TEST-INFO | started process 1976 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/tmpDQ5k6V/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:39:20 INFO - PROCESS | 1976 | RSS: Main: 174612480 14:39:20 INFO - PROCESS | 1976 | 14:39:22 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:53756/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53756/tests/kraken/driver.html?testName=audio-beat-detection) 14:39:23 INFO - PROCESS | 1976 | RSS: Main: 187404288 14:39:23 INFO - PROCESS | 1976 | 14:39:26 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:53756/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53756/tests/kraken/driver.html?testName=audio-dft) 14:39:26 INFO - PROCESS | 1976 | RSS: Main: 196018176 14:39:26 INFO - PROCESS | 1976 | 14:39:30 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:53756/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53756/tests/kraken/driver.html?testName=audio-fft) 14:39:31 INFO - PROCESS | 1976 | RSS: Main: 197312512 14:39:31 INFO - PROCESS | 1976 | 14:39:33 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:53756/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53756/tests/kraken/driver.html?testName=audio-oscillator) 14:39:33 INFO - PROCESS | 1976 | RSS: Main: 200462336 14:39:33 INFO - PROCESS | 1976 | 14:39:35 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:53756/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53756/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:39:36 INFO - PROCESS | 1976 | RSS: Main: 200908800 14:39:36 INFO - PROCESS | 1976 | 14:39:39 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:53756/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53756/tests/kraken/driver.html?testName=imaging-darkroom) 14:39:39 INFO - PROCESS | 1976 | RSS: Main: 202043392 14:39:39 INFO - PROCESS | 1976 | 14:39:42 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:53756/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53756/tests/kraken/driver.html?testName=imaging-desaturate) 14:39:43 INFO - PROCESS | 1976 | RSS: Main: 201973760 14:39:43 INFO - PROCESS | 1976 | 14:39:46 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:53756/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53756/tests/kraken/driver.html?testName=json-parse-financial) 14:39:47 INFO - PROCESS | 1976 | RSS: Main: 202244096 14:39:47 INFO - PROCESS | 1976 | 14:39:48 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:53756/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53756/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:39:49 INFO - PROCESS | 1976 | RSS: Main: 201658368 14:39:49 INFO - PROCESS | 1976 | 14:39:51 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:53756/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53756/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:39:51 INFO - PROCESS | 1976 | RSS: Main: 201605120 14:39:51 INFO - PROCESS | 1976 | 14:39:54 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:53756/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53756/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:39:54 INFO - PROCESS | 1976 | RSS: Main: 201904128 14:39:54 INFO - PROCESS | 1976 | 14:39:57 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:53756/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53756/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:39:58 INFO - PROCESS | 1976 | RSS: Main: 202383360 14:39:58 INFO - PROCESS | 1976 | 14:40:00 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:53756/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53756/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:40:01 INFO - PROCESS | 1976 | RSS: Main: 202399744 14:40:01 INFO - PROCESS | 1976 | 14:40:03 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:53756/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53756/tests/kraken/driver.html?testName=ai-astar) 14:40:03 INFO - PROCESS | 1976 | RSS: Main: 204541952 14:40:03 INFO - PROCESS | 1976 | 14:40:03 INFO - PROCESS | 1976 | __start_tp_report 14:40:03 INFO - PROCESS | 1976 | _x_x_mozilla_page_load 14:40:03 INFO - PROCESS | 1976 | _x_x_mozilla_page_load_details 14:40:03 INFO - PROCESS | 1976 | |i|pagename|runs| 14:40:03 INFO - PROCESS | 1976 | |0;ai-astar;103;119;110;116;121;118;117;118;117;118 14:40:03 INFO - PROCESS | 1976 | |1;audio-beat-detection;149;135;166;160;111;128;133;95;183;92 14:40:03 INFO - PROCESS | 1976 | |2;audio-dft;197;203;197;229;263;209;281;202;189;202 14:40:03 INFO - PROCESS | 1976 | |3;audio-fft;72;79;76;152;60;70;60;61;139;59 14:40:03 INFO - PROCESS | 1976 | |4;audio-oscillator;59;81;143;93;93;77;113;72;73;115 14:40:03 INFO - PROCESS | 1976 | |5;imaging-gaussian-blur;106;127;90;89;107;112;90;88;91;88 14:40:03 INFO - PROCESS | 1976 | |6;imaging-darkroom;96;96;97;97;97;96;118;99;140;96 14:40:03 INFO - PROCESS | 1976 | |7;imaging-desaturate;108;93;99;127;84;143;135;95;145;101 14:40:03 INFO - PROCESS | 1976 | |8;json-parse-financial;55;55;58;57;58;59;58;58;57;58 14:40:03 INFO - PROCESS | 1976 | |9;json-stringify-tinderbox;67;71;73;72;81;80;76;73;75;75 14:40:03 INFO - PROCESS | 1976 | |10;stanford-crypto-aes;84;85;86;85;86;90;83;80;87;108 14:40:03 INFO - PROCESS | 1976 | |11;stanford-crypto-ccm;137;134;146;150;163;135;145;157;169;141 14:40:03 INFO - PROCESS | 1976 | |12;stanford-crypto-pbkdf2;168;165;165;164;166;162;164;171;164;166 14:40:03 INFO - PROCESS | 1976 | |13;stanford-crypto-sha256-iterative;65;67;68;69;74;82;81;81;86;86 14:40:03 INFO - PROCESS | 1976 | __end_tp_report 14:40:03 INFO - PROCESS | 1976 | __start_cc_report 14:40:03 INFO - PROCESS | 1976 | _x_x_mozilla_cycle_collect,1039 14:40:03 INFO - PROCESS | 1976 | __end_cc_report 14:40:03 INFO - PROCESS | 1976 | __startTimestamp1474407603707__endTimestamp 14:40:03 INFO - PROCESS | 1976 | 14:40:03 INFO - PROCESS | 1976 | ------- Summary: start ------- 14:40:03 INFO - PROCESS | 1976 | Number of tests: 14 14:40:03 INFO - PROCESS | 1976 | 14:40:03 INFO - PROCESS | 1976 | [#0] ai-astar Cycles:10 Average:115.70 Median:118.00 stddev:5.29 (4.5%) stddev-sans-first:3.02 14:40:03 INFO - PROCESS | 1976 | Values: 103.0 119.0 110.0 116.0 121.0 118.0 117.0 118.0 117.0 118.0 14:40:03 INFO - PROCESS | 1976 | 14:40:03 INFO - PROCESS | 1976 | [#1] audio-beat-detection Cycles:10 Average:135.20 Median:163.00 stddev:30.12 (18.5%) stddev-sans-first:31.53 14:40:03 INFO - PROCESS | 1976 | Values: 149.0 135.0 166.0 160.0 111.0 128.0 133.0 95.0 183.0 92.0 14:40:03 INFO - PROCESS | 1976 | 14:40:03 INFO - PROCESS | 1976 | [#2] audio-dft Cycles:10 Average:217.20 Median:206.00 stddev:31.00 (15.0%) stddev-sans-first:32.01 14:40:03 INFO - PROCESS | 1976 | Values: 197.0 203.0 197.0 229.0 263.0 209.0 281.0 202.0 189.0 202.0 14:40:03 INFO - PROCESS | 1976 | 14:40:03 INFO - PROCESS | 1976 | [#3] audio-fft Cycles:10 Average:82.80 Median:65.50 stddev:33.94 (51.8%) stddev-sans-first:35.78 14:40:03 INFO - PROCESS | 1976 | Values: 72.0 79.0 76.0 152.0 60.0 70.0 60.0 61.0 139.0 59.0 14:40:03 INFO - PROCESS | 1976 | 14:40:03 INFO - PROCESS | 1976 | [#4] audio-oscillator Cycles:10 Average:91.90 Median:75.00 stddev:25.32 (33.8%) stddev-sans-first:23.89 14:40:03 INFO - PROCESS | 1976 | Values: 59.0 81.0 143.0 93.0 93.0 77.0 113.0 72.0 73.0 115.0 14:40:03 INFO - PROCESS | 1976 | 14:40:03 INFO - PROCESS | 1976 | [#5] imaging-gaussian-blur Cycles:10 Average:98.80 Median:88.50 stddev:13.47 (15.2%) stddev-sans-first:14.04 14:40:03 INFO - PROCESS | 1976 | Values: 106.0 127.0 90.0 89.0 107.0 112.0 90.0 88.0 91.0 88.0 14:40:03 INFO - PROCESS | 1976 | 14:40:03 INFO - PROCESS | 1976 | [#6] imaging-darkroom Cycles:10 Average:103.20 Median:96.50 stddev:14.58 (15.1%) stddev-sans-first:15.23 14:40:03 INFO - PROCESS | 1976 | Values: 96.0 96.0 97.0 97.0 97.0 96.0 118.0 99.0 140.0 96.0 14:40:03 INFO - PROCESS | 1976 | 14:40:03 INFO - PROCESS | 1976 | [#7] imaging-desaturate Cycles:10 Average:113.00 Median:114.50 stddev:22.44 (19.6%) stddev-sans-first:23.73 14:40:03 INFO - PROCESS | 1976 | Values: 108.0 93.0 99.0 127.0 84.0 143.0 135.0 95.0 145.0 101.0 14:40:03 INFO - PROCESS | 1976 | 14:40:03 INFO - PROCESS | 1976 | [#8] json-parse-financial Cycles:10 Average:57.30 Median:58.00 stddev:1.34 (2.3%) stddev-sans-first:1.13 14:40:03 INFO - PROCESS | 1976 | Values: 55.0 55.0 58.0 57.0 58.0 59.0 58.0 58.0 57.0 58.0 14:40:03 INFO - PROCESS | 1976 | 14:40:03 INFO - PROCESS | 1976 | [#9] json-stringify-tinderbox Cycles:10 Average:74.30 Median:75.00 stddev:4.14 (5.5%) stddev-sans-first:3.44 14:40:03 INFO - PROCESS | 1976 | Values: 67.0 71.0 73.0 72.0 81.0 80.0 76.0 73.0 75.0 75.0 14:40:03 INFO - PROCESS | 1976 | 14:40:03 INFO - PROCESS | 1976 | [#10] stanford-crypto-aes Cycles:10 Average:87.40 Median:85.50 stddev:7.69 (9.0%) stddev-sans-first:8.06 14:40:03 INFO - PROCESS | 1976 | Values: 84.0 85.0 86.0 85.0 86.0 90.0 83.0 80.0 87.0 108.0 14:40:03 INFO - PROCESS | 1976 | 14:40:03 INFO - PROCESS | 1976 | [#11] stanford-crypto-ccm Cycles:10 Average:147.70 Median:148.00 stddev:12.01 (8.1%) stddev-sans-first:12.10 14:40:03 INFO - PROCESS | 1976 | Values: 137.0 134.0 146.0 150.0 163.0 135.0 145.0 157.0 169.0 141.0 14:40:03 INFO - PROCESS | 1976 | 14:40:03 INFO - PROCESS | 1976 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.50 Median:165.50 stddev:2.51 (1.5%) stddev-sans-first:2.49 14:40:03 INFO - PROCESS | 1976 | Values: 168.0 165.0 165.0 164.0 166.0 162.0 164.0 171.0 164.0 166.0 14:40:03 INFO - PROCESS | 1976 | 14:40:03 INFO - PROCESS | 1976 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.90 Median:81.00 stddev:8.20 (10.1%) stddev-sans-first:7.69 14:40:03 INFO - PROCESS | 1976 | Values: 65.0 67.0 68.0 69.0 74.0 82.0 81.0 81.0 86.0 86.0 14:40:03 INFO - PROCESS | 1976 | -------- Summary: end -------- 14:40:03 INFO - PROCESS | 1976 | 14:40:04 INFO - TEST-INFO | 1976: exit 0 14:40:04 INFO - TEST-OK | kraken | took 59276ms 14:40:04 INFO - SUITE-END | took 1169s 14:40:04 INFO - Completed test suite (00:19:30) 14:40:04 INFO - javascript benchmark 14:40:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7622.136673810277, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3001.8949336773217, 3031.242189452637, 3308.206030552667, 3602.2615363850414, 3615.9096022599438, 5992.850178745528, 6052.909206361908, 6083.908741368883, 6103.786367477139, 6123.908141377878, 5987.670678112702, 6069.241344831896, 6069.908951365733, 6070.484008859252, 6078.088286756989, 5368.892622147555, 5384.973075134619, 5406.0, 5456.508914197721, 5540.529055030327, 5349.545288650469, 5380.91928621071, 5419.3225846769155, 5458.426865179159, 5488.91766623501, 5457.508824205821, 5457.754401051942, 5466.01611709892, 5479.068558345091, 5481.835544933639, 5331.067063263952, 5453.182022696627, 5457.426970168154, 5458.017556839767, 5460.918086228709, 5272.736363181825, 5305.4164041955355, 5330.946690533123, 5356.9732151339385, 5391.649542779707, 6021.42796434338, 6035.6076855004285, 6048.909266361008, 6122.4183702548635, 6128.754849806002, 6175.104609831549, 6183.04193715405, 6265.96867015662, 6288.308286088527, 6408.775692850728, 5288.947110528884, 6193.411625895577, 6236.43872051513, 6271.435570798604, 6353.491720662336, 6087.360827113133, 6093.908591371133, 6094.451499365034, 6109.0, 6126.663033533154, 6407.199100112486, 6454.935450645527, 6457.2574153971955, 6463.48292136628, 6477.060826180177, 6145.955187618116, 6163.630182189083, 6173.166622505994, 6206.131141640174, 6233.719482623293, 6256.0, 6256.343083976208, 6263.0, 6265.185525881606, 6269.0, 6220.0, 6244.46922011625, 6255.093011513305, 6255.343188965184, 6276.623402595859, 5701.688611619268, 6147.692615369214, 6162.506999440034, 6186.566940314225, 6237.220347456568, 4812.446568644581, 5087.0, 5100.948990510122, 5102.948970510248, 5104.3109180260935, 4572.337011133433, 4573.336866154389, 4577.0, 4582.95417045832, 4587.885302867455, 5994.190784244159, 6081.118237855486, 6119.5104391648565, 6142.682951206299, 6145.801568694151, 6342.619442833445, 6342.904856427157, 6356.14192084072, 6358.33237510064, 6372.203474565679, 6117.938820611826, 6202.627842329474, 6207.689615519206, 6215.906761398583, 6218.533609979269, 5532.6680399175375, 5569.0, 5627.690477023803, 5657.490825825613, 5727.570432217601, 6579.111819904348, 6626.53614246998, 6628.337166283333, 6658.367455091856, 6750.628715420503, 6334.113224148531, 6380.170577824853, 6389.201349831271, 6409.487241020801, 6424.100625912282, 6310.085037669604, 6377.202849643794, 6515.12045873801, 6519.217693876765, 6592.110065141144, 6298.1812364392335, 6307.400796924285, 6325.240971083499, 6337.96831015842, 6338.492920566436, 6208.161898143873, 6309.0, 6366.076918846834, 6377.394147555975, 6380.170577824853, 6146.170267013896, 6176.66028368444, 6213.409726076015, 6258.155149055002, 6273.65494897785, 6201.6899155041165, 6234.875302493834, 6244.625322480666, 6247.531435142291, 6373.968130159319, 4659.858334708018, 6175.228096487939, 6177.289611694712, 6212.844678883064, 6224.128621993015, 6157.261128664589, 6158.476529495043, 6168.599041062316, 6202.906956395567, 6272.529560282906, 6153.077038444269, 6194.039923811816, 6202.162708034358, 6204.875902482016, 6208.937910620836], "value": 5867.301026943302, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [506.79888663866643, 523.7041071794436, 532.6484520216658, 541.7101850509978, 549.7718446844558, 38049.23901521971, 38277.04307392313, 40228.59771402285, 42194.36708449372, 42398.36402453958, 42591.0, 42763.0, 43055.0, 43284.783576082074, 43365.7831710841, 8382.706605268817, 9811.362261453018, 9824.656137035205, 9832.803343933118, 9900.108990190882, 9761.219102471785, 9854.852177217348, 9856.605735770561, 9885.110340069392, 9895.406275623485, 9832.950835245814, 9849.606015759397, 9860.60557577696, 9880.308378413514, 9946.950265248699, 9786.657466988658, 9836.114749672564, 9846.507674616241, 9868.259880508955, 9951.950240248752, 9720.270979676552, 9770.9511452443, 9794.363366381162, 9799.90200097997, 9842.753931151707, 41513.1697366051, 41869.581304186875, 41873.79063104665, 41888.581114188775, 42010.57989420097, 3582.7671201372086, 3696.0390298522307, 3912.68698504119, 3969.0474286171398, 4414.955850441519, 31520.684793152006, 32498.675013249802, 33200.501992470134, 33243.833780831184, 34267.80062697818, 3162.826044567548, 3199.2641692410643, 3212.3093534890113, 3307.8676852925855, 3321.70104690579, 484640.0, 524204.3789781065, 527465.0, 528680.0, 529948.0, 64225.0, 64647.67676161683, 64745.676271618344, 64828.0, 65163.67418162879, 36668.44997325042, 37027.25945481129, 37189.88430347094, 38522.80738596289, 38540.84377468681, 17824.55438614045, 17843.197056132507, 17950.461486155436, 17965.101744912703, 17977.910110449364, 13423.26172060546, 13559.66100847467, 13590.660233494242, 13608.047436679535, 13612.523561675294, 20140.89929550343, 20167.394978150678, 20334.288299909433, 20364.0, 20396.0821763021, 8072.677092916335, 8441.198086181803, 8506.191911768372, 8518.787030324167, 8542.273906718, 919.6965001549472, 940.6707652321633, 1070.347088276151, 1101.4712937789907, 1141.4863311509853, 31683.366332673217, 31798.523022154684, 31814.363712725615, 32367.0, 32419.865304714695, 1077.2190162132392, 1106.5076041161606, 1138.2658185470325, 1198.4127777389017, 1232.1251911143188, 15774.447894323874, 16658.0, 16710.415135470445, 16719.749203761952, 16884.324627014783, 709925.0, 714479.0, 720165.0, 722538.0, 722960.0, 61811.0, 62391.0, 62501.687491562254, 62629.74740505165, 62731.37268627347, 1470.6396932751331, 1477.4828809916444, 1501.5645462815662, 1508.2760275068028, 1524.9695006099817, 30279.69720302769, 31293.0, 31414.528782068744, 31522.0, 31855.36289274248, 52915.73542132265, 52921.0, 53101.7344913273, 53382.73308633432, 53452.198217025994, 571322.0, 576685.0, 582177.0, 585537.0, 586260.0, 27899.0, 27912.883484661197, 28270.434591307647, 28416.147515574565, 28698.282542936595, 49105.508944911526, 49736.005279894925, 50108.0, 50771.74614126905, 50844.74577627088, 40657.39013914735, 40951.38572921349, 41950.160996780505, 42519.362209566265, 42560.57439425566, 40947.38578921378, 41789.37315940203, 41857.581424185366, 42178.0, 42330.78834605807, 36791.81604091962, 39046.80476597599, 39279.83960882179, 39536.802315988236, 39982.800085999384, 35073.1231719209, 36389.0, 36940.0, 37080.0, 37152.0, 24304.635430468203, 26635.60046599341, 26940.865295673397, 27142.0, 27198.0], "value": 20809.846448972658, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.19361257586297, 382.91634673872943, 398.8763483320171, 407.3848488781939, 420.1366192474464, 19085.809141908576, 20557.486062848435, 20650.6902396464, 21876.1249550018, 22516.887415562942, 21725.13099476019, 21773.346799595998, 22256.66615000772, 22276.777232227672, 22510.54978900421, 5933.940660593382, 6084.847878803043, 6108.725107370156, 6199.69001549923, 6218.9427797274575, 6000.129981152731, 6065.120557519157, 6071.969640151793, 6076.997295446246, 6115.143879856823, 5955.731992060368, 6039.1545183674325, 6098.908516372258, 6112.358202388752, 6112.969435152818, 5951.880962380771, 5952.255968003999, 5952.285725712899, 6029.155918171459, 6103.176071230394, 5214.582833373359, 5244.921326180111, 5257.13257312543, 5259.132243179871, 5305.31030965972, 27163.0, 27291.45417091667, 27368.863155684292, 27387.31531711723, 27429.17712468629, 2339.3449834046496, 2366.5503554324623, 2697.2312890826215, 3231.305269367074, 3250.934981300361, 15739.68520629581, 16330.101844398554, 16379.508614741577, 16433.506994790056, 16550.917245413817, 1843.3456123076248, 1932.478230877655, 1957.0214892553722, 1977.031254685194, 2061.134323584083, 92954.0, 123033.0, 123402.38298808539, 131937.0, 140830.0, 10620.787584248426, 11072.778544429066, 11651.0, 12190.451429685685, 12259.877401225873, 12436.75632436749, 15009.849901500846, 15145.091294522364, 15362.231888405535, 15367.61580960485, 8585.742427727178, 8920.330975176757, 8923.598438070303, 9022.819543609223, 9072.183503484717, 7707.421943354271, 8802.339824513187, 8835.734927952171, 8865.955670221736, 9043.050479699668, 4141.716068018924, 4339.34909763538, 4638.1187574360765, 4653.255479123324, 4766.547178018082, 1997.4007797660643, 2065.0810389376584, 2073.02567793137, 2109.093089971296, 2125.203048856679, 815.8205194857122, 853.2022558907317, 946.7822400847843, 1008.4856723071287, 1106.9391183484986, 20817.479563011046, 21838.45403864916, 21885.562288754136, 21923.78076219249, 22138.335849924206, 1000.799840031982, 1032.2774058159318, 1088.1240601316017, 1107.5901916290863, 1112.1714322829544, 10034.899651003543, 10972.670819875417, 11662.0, 11682.007029402426, 11693.532258709556, 641265.0, 647612.0, 652800.0, 653048.0, 658668.0, 78808.81786773202, 116398.8360116405, 136144.63855361516, 136892.0, 137685.0, 967.7483854197818, 967.8596603492454, 995.64654547636, 1002.774375765447, 1005.2058873490007, 14576.85423145755, 15946.601334966255, 16039.919800400923, 17231.53531949748, 17796.733049004015, 45609.71951402483, 53337.73331133319, 55673.0, 58176.70911645584, 58514.70742646259, 86595.0, 88052.11947880696, 89688.0, 90325.64511532201, 91803.54098229467, 7104.786856394317, 7673.808154796175, 7733.033370828646, 7813.1796161402235, 7995.0, 10951.397673128055, 11124.721881953015, 13089.018323625956, 13201.471941122247, 13486.05597608158, 11663.367715368526, 11759.412029398325, 11894.583689570823, 11940.701482463008, 11979.101567382655, 9613.51932403363, 9871.851922221029, 11493.597724079616, 11495.827562586735, 11588.6523404298, 9576.808463830825, 11082.44587770592, 11214.551417943518, 11477.196596238184, 11994.340311282614, 7620.542767433972, 7770.26182512683, 7789.376849851885, 7802.024746906637, 7894.6842126314305, 10920.781584368428, 11526.135539834379, 12094.879051209375, 12135.968442682472, 12169.81745273804], "value": 9962.013191092243, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1402.9438822447103, 1621.2380181314786, 1658.4692898272554, 1949.0936714427787, 2025.6455120353944, 7073.434125269979, 8267.503949763019, 8858.77853053674, 9386.296027797916, 9423.34036617437, 8926.910730892705, 9061.32040096992, 9142.0, 9149.496777677225, 9149.634014639423, 2410.9156179533766, 2448.7673671001226, 2452.3010941881566, 2452.3869032741813, 2462.642916777066, 2054.219396629279, 2390.5935990881594, 2417.601095819188, 2434.9634755478683, 2440.170342083691, 2418.9879050604723, 2422.0190822716827, 2430.635404689302, 2431.4650776829158, 2445.0464318915638, 2377.512609914972, 2383.8688872112025, 2386.976130238693, 2410.5419970205694, 2421.019487107724, 2384.1536254629705, 2425.345156807652, 2427.186892391051, 2428.4171798768343, 2438.451348446603, 9273.350865439423, 9288.582013809328, 9315.767105822408, 9353.953230233874, 9381.061893810633, 4605.792739326738, 5343.866403339909, 5404.1083221268445, 5490.890182196334, 5493.7802487900435, 3609.9819500902327, 3706.4625629283873, 3719.516462859838, 3759.0602349412648, 3768.2275133597545, 1085.6905781852133, 1092.5247517329988, 1093.7101668057971, 1105.7125147461638, 1110.211749657744, 10398.428086455242, 12224.572139975147, 12362.0, 12376.62870113898, 12384.81422778659, 4120.670346372283, 4462.821487140478, 4662.906741865144, 4695.413073365829, 4743.66794324394, 9476.241900647932, 9478.857817132748, 9540.952295238618, 9566.426014439017, 9597.568109434953, 5379.166229234396, 5394.0290747665795, 5395.78416863321, 5414.945850541444, 5443.1563107717575, 4601.28680054592, 4906.852794416173, 5015.899682006339, 5053.46938571452, 5069.264956581349, 3954.2289253595272, 3968.464257325282, 3968.722189446711, 4077.3680079587116, 4090.2024105299774, 1197.8562572491358, 1205.764875849201, 1208.7824191645589, 1210.9576164834189, 1212.8483939507562, 881.5460038080394, 964.8745663063756, 976.7753416714196, 997.7256254529917, 1021.2902033087082, 6086.513078953762, 6145.4161854623735, 6172.45995933948, 6257.155284036597, 6265.0, 429.46102641185115, 431.9784010799447, 439.2269605494353, 1059.2638116509106, 1114.2924243105685, 4199.958000420018, 4216.789160541961, 4252.744835309892, 4262.829486820555, 4270.145970805789, 10822.621208257673, 11050.0, 11160.553577856796, 11315.603953861575, 11345.319280843176, 11054.392008439612, 11115.555377784798, 11136.610218642309, 11202.159838012181, 11272.943635281772, 8169.387295952827, 8435.915640843636, 8586.227239548345, 8624.96500419941, 8689.217970382695, 5199.272101905661, 6300.495960323255, 6332.778352757631, 6363.459105976044, 6399.64801935898, 10235.232357573062, 10508.684739457523, 10509.842352364874, 10729.409882456539, 10763.946180269362, 10625.681229562817, 10763.677089687322, 10860.945695271474, 10897.727556811144, 11018.063464605599, 6891.173059232924, 7316.926830731624, 7770.92229077702, 7958.442908996315, 8060.919390806252, 7740.651670674721, 8417.284530814704, 8771.780705482159, 8950.060243674321, 8965.417247879128, 6783.287754785873, 7678.001859758196, 7691.038620172479, 7848.686052557834, 7953.6818527258265, 7366.152892417441, 7533.209013053551, 7549.9245007549225, 7568.3945284378215, 7570.280823321776, 7347.302006309392, 7482.438817088849, 7503.7748867534065, 7517.511361761468, 7530.510516816389, 5152.227165925141, 5220.3735551734035, 5246.921296180484, 5299.735013249399, 5378.596605254544, 5197.0905091608065, 5295.152775555893, 5364.490373414519, 5413.918791218056, 5471.398146203835], "value": 4693.592130751899, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.57872926797614, 293.31510921997136, 300.09670890619236, 300.8089862937037, 303.19199333775487, 4619.422572178478, 5080.882205914702, 5246.632735708502, 5319.175527793193, 5575.470330318613, 4439.689221754478, 5039.748012599373, 5231.848993221494, 5312.362516498016, 5467.726613669321, 3510.5436293281837, 3626.9455958160647, 3718.2670737008343, 4053.29067413203, 4127.215828992482, 3296.390167818949, 3528.5060091587197, 4058.8376464941366, 4110.568390319018, 4138.503379594453, 3777.6589310794684, 4013.1171142348635, 4049.048473608714, 4057.6145266199665, 4085.2442298174783, 3008.3381656035635, 4033.596640335972, 4086.8569600063856, 4093.406456063854, 4106.650934670557, 2958.4526862530283, 3394.2023624448234, 3532.5054492371087, 3533.452314891196, 3561.821908904544, 4877.244027175758, 4943.678660887031, 5318.787248510055, 5338.62629615927, 5341.385740639837, 4641.955559999007, 4739.668223224376, 4744.667873248839, 4745.359376484165, 4782.473927867932, 5016.698998060128, 5551.639143455663, 5820.156077368758, 5868.855573163235, 5899.498542623882, 2923.985380073107, 3363.4954756786433, 3745.6141227745634, 3836.232753449321, 3907.8436862525464, 5256.7371631418655, 5752.36723960364, 5823.854403639901, 5826.446487583673, 6066.4530544710815, 3020.2902317955263, 3863.632954869283, 3897.2205558888336, 3920.3727403615285, 3926.293267211929, 3323.4682450808236, 3579.4093974493917, 3966.6033396660105, 3976.562578116405, 4001.359782434855, 4620.584147426748, 4633.142868569325, 4663.580277775017, 4676.228422310349, 4701.882452938636, 4844.8304309349705, 5867.325257595346, 5887.970560147171, 5893.882122357529, 5899.7050147492455, 1048.1457612046158, 1291.9418626161846, 1324.6754545136503, 1341.9597412077653, 1360.4830164537611, 730.2952650692108, 879.9648014079493, 880.238593616517, 896.852019416802, 925.379995403068, 4637.652176086807, 5203.0, 5434.673919564839, 5487.5609951203805, 5539.529140023144, 5519.896020795776, 5556.0, 5608.074667679787, 5980.700964951735, 6023.879522409527, 5191.610629202825, 5474.507294343449, 5492.340919089735, 5503.147012213033, 5582.441755824385, 2776.805623606328, 2792.762615177692, 3013.9903132450436, 3188.1073299476184, 3192.952105718416, 4984.601231901512, 5975.67133807636, 6276.399518122941, 6313.747450102036, 6361.427471527492, 5481.479259470344, 5725.2843394575675, 5819.82540523785, 5820.594951953155, 6083.7566497340495, 5752.33848107473, 6283.371662833772, 6287.685615719196, 6292.276388215414, 6298.968505157445, 5122.487751224848, 5263.605229607718, 5713.857153571194, 6006.219191505251, 6019.110826803071, 4945.703257804544, 5249.763760630857, 5967.910481342697, 5978.671173085348, 6011.519078473799, 5166.535011848951, 5446.945530544803, 6219.0982307566055, 6233.937660623335, 6275.74897004133, 3032.363203727242, 3235.8705651773666, 3718.888433347004, 3746.007308063311, 3750.699944004528, 4875.000624871964, 5235.063026680153, 5705.543556515386, 5943.346231914399, 5955.940440595538, 5348.358197016383, 5711.371749107512, 5843.561732869966, 5877.4122587740885, 5955.225820643289, 4656.346996816082, 5575.77696892136, 5882.5293376781, 5891.49922256613, 5906.552894540693, 4415.4259946206785, 5646.131099063578, 5711.771529138788, 5763.913541296968, 5774.249347584787, 5230.875361797232, 5855.2973643163095, 5889.705514724161, 5914.704264786657, 5916.816636672769, 5279.1817268323475, 5560.7219639018695, 5738.799142030176, 5769.79805706815, 5798.6810725410505], "value": 4129.288659997225, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [756.7351427000553, 774.9651265693043, 784.8312612788251, 803.2850762821093, 882.5013867165052, 15454.07275563467, 16631.916840415783, 16814.99110053396, 16882.831171688278, 16969.406070787525, 15435.69128617427, 15468.922655386707, 15571.98782079164, 15578.766318505202, 15614.453494127707, 884.4781578868483, 909.9590518426653, 943.9575219115156, 945.078548415293, 949.155251825872, 932.6409332407004, 943.9480828554426, 946.507815935713, 948.1892981500821, 949.3164921256719, 963.6819849449665, 964.7491652170417, 994.3437331361303, 996.3922007575374, 996.4818294486862, 971.4317124482186, 976.0483528559633, 980.9558569864338, 989.3223142147652, 990.1979396688705, 722.989155162673, 728.3372131360436, 729.194240364395, 743.3087228877142, 743.8995735575683, 15481.0, 15711.057336559843, 15931.681366372608, 15946.043237405793, 16047.35810567576, 14839.925800371037, 14936.32786524609, 14971.850281497263, 14986.175760333177, 14991.100533967889, 12310.13829031968, 12487.625371238877, 12562.74874502514, 12804.103712740112, 12833.101682882201, 3892.961070389288, 3964.5638979712207, 4016.9397459038137, 4023.6579890709327, 4038.676905847525, 105990.0, 107155.0, 108519.91480085177, 109448.35827462595, 110597.0, 10234.64178753755, 10418.479076046167, 11381.975622194042, 11494.482748276349, 11892.524299028115, 22107.557848842935, 22874.97577435094, 22974.65538016931, 23119.844007799544, 23168.65247021296, 8441.493510389397, 8492.872606910902, 8559.95720021396, 8608.182222688834, 8647.956760216159, 3998.4801975742967, 4443.4445694288215, 4611.492735799059, 4670.322803193586, 4820.421549414093, 2953.0, 3693.1874987502706, 3725.683316918038, 3734.9626503735158, 3735.7384983050924, 542.4819297570889, 645.9870802583922, 649.9837504062436, 659.7558903205845, 661.1372159332118, 620.5749061892528, 705.6189657584949, 713.7894321175265, 714.7641278378122, 736.5543845973216, 9556.426614403159, 10158.898411015794, 10230.079292863678, 10410.739731506774, 10411.94794026025, 574.3395095639964, 594.5362617158623, 701.8806802843529, 715.9856802864018, 725.1841678112124, 4732.503087175865, 5002.2496625506465, 5058.266551350033, 5065.8733531662, 5070.797168113308, 173083.40374894388, 177303.0, 178431.0, 178469.0, 182368.0, 113890.0, 114477.0, 114540.0, 133665.0, 134584.0, 106717.46641266743, 109350.0, 115519.0, 117939.41030294965, 118780.81219187871, 9393.154616084585, 10108.685870836744, 10208.081272685495, 10667.733306667395, 10796.946015269874, 63097.16062777094, 63732.0, 64258.678706606166, 65655.0303490896, 66440.0, 95427.0, 97765.51117244369, 101584.0, 101799.0, 103709.48145259226, 13364.799528006894, 13548.254845983565, 13745.518906838213, 13756.724865502434, 13805.240711760547, 15144.697106057596, 15724.685506289581, 15853.762193566876, 15907.840921590636, 15943.202839858193, 14109.929450353093, 14440.494582689555, 14549.272536373352, 14854.77717834255, 15488.922555387602, 14295.422746352106, 14465.13209207451, 14631.92684036573, 15010.324535396152, 15209.239538023276, 11450.37022963745, 13765.38055787472, 14311.141331520143, 15024.924875375553, 15151.848481515484, 10523.947380263357, 11551.249168804, 12280.631581052583, 12373.443195056065, 12509.687257818627, 12012.159148859497, 12232.0, 12253.754924901274, 12503.249805011728, 12520.624381268577], "value": 8317.233974752917, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1565.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 119.0, 110.0, 116.0, 121.0, 118.0, 117.0, 118.0, 117.0, 118.0], "value": 115.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 135.0, 166.0, 160.0, 111.0, 128.0, 133.0, 95.0, 183.0, 92.0], "value": 135.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 203.0, 197.0, 229.0, 263.0, 209.0, 281.0, 202.0, 189.0, 202.0], "value": 217.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 79.0, 76.0, 152.0, 60.0, 70.0, 60.0, 61.0, 139.0, 59.0], "value": 82.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 81.0, 143.0, 93.0, 93.0, 77.0, 113.0, 72.0, 73.0, 115.0], "value": 91.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 127.0, 90.0, 89.0, 107.0, 112.0, 90.0, 88.0, 91.0, 88.0], "value": 98.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 97.0, 97.0, 97.0, 96.0, 118.0, 99.0, 140.0, 96.0], "value": 103.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 93.0, 99.0, 127.0, 84.0, 143.0, 135.0, 95.0, 145.0, 101.0], "value": 113.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 58.0, 57.0, 58.0, 59.0, 58.0, 58.0, 57.0, 58.0], "value": 57.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 71.0, 73.0, 72.0, 81.0, 80.0, 76.0, 73.0, 75.0, 75.0], "value": 74.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 86.0, 85.0, 86.0, 90.0, 83.0, 80.0, 87.0, 108.0], "value": 87.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 134.0, 146.0, 150.0, 163.0, 135.0, 145.0, 157.0, 169.0, 141.0], "value": 147.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 165.0, 165.0, 164.0, 166.0, 162.0, 164.0, 171.0, 164.0, 166.0], "value": 165.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 68.0, 69.0, 74.0, 82.0, 81.0, 81.0, 86.0, 86.0], "value": 75.9, "unit": "score"}]}]} 14:40:04 INFO - Return code: 0 14:40:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:40:04 ERROR - # TBPL SUCCESS # 14:40:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:40:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:40:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:40:04 INFO - 2016-09-20 14:40:04.978 screenresolution[1981:67603] starting screenresolution argv=screenresolution get 14:40:04 INFO - 2016-09-20 14:40:04.980 screenresolution[1981:67603] Display 0: 1600x1200x32@60 14:40:04 INFO - 2016-09-20 14:40:04.987 screenresolution[1982:67607] starting screenresolution argv=screenresolution list 14:40:04 INFO - Available Modes on Display 0 14:40:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:40:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:40:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:40:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:40:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:40:05 INFO - Intel Iris: 14:40:05 INFO - Chipset Model: Intel Iris 14:40:05 INFO - Type: GPU 14:40:05 INFO - Bus: Built-In 14:40:05 INFO - VRAM (Dynamic, Max): 1536 MB 14:40:05 INFO - Vendor: Intel (0x8086) 14:40:05 INFO - Device ID: 0x0a2e 14:40:05 INFO - Revision ID: 0x0009 14:40:05 INFO - Displays: 14:40:05 INFO - Display: 14:40:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:40:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:40:05 INFO - Display Serial Number: 1600x1200 60 14:40:05 INFO - Main Display: Yes 14:40:05 INFO - Mirror: Off 14:40:05 INFO - Online: Yes 14:40:05 INFO - Rotation: Supported 14:40:05 INFO - Return code: 0 14:40:05 INFO - Running post-action listener: _resource_record_post_action 14:40:05 INFO - [mozharness: 2016-09-20 21:40:05.227617Z] Finished run-tests step (success) 14:40:05 INFO - Running post-run listener: _resource_record_post_run 14:40:05 INFO - Total resource usage - Wall time: 1193s; CPU: 54.0%; Read bytes: 753664; Write bytes: 497907200; Read time: 7; Write time: 1578 14:40:05 INFO - TinderboxPrint: CPU usage
54.2% 14:40:05 INFO - TinderboxPrint: I/O read bytes / time
753,664 / 7 14:40:05 INFO - TinderboxPrint: I/O write bytes / time
497,907,200 / 1,578 14:40:05 INFO - TinderboxPrint: CPU idle
2,187.8 (45.8%) 14:40:05 INFO - TinderboxPrint: CPU system
965.9 (20.2%) 14:40:05 INFO - TinderboxPrint: CPU user
1,620.0 (33.9%) 14:40:05 INFO - TinderboxPrint: Swap in / out
197,074,944 / 0 14:40:05 INFO - install - Wall time: 23s; CPU: 55.0%; Read bytes: 185364992; Write bytes: 181344256; Read time: 17367; Write time: 407 14:40:05 INFO - run-tests - Wall time: 1171s; CPU: 54.0%; Read bytes: 753664; Write bytes: 296693248; Read time: 7; Write time: 1113 14:40:05 INFO - Running post-run listener: _upload_blobber_files 14:40:05 INFO - Blob upload gear active. 14:40:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:40:05 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 14:40:05 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:40:05 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:40:06 INFO - (blobuploader) - INFO - Open directory for files ... 14:40:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:40:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:40:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:40:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:40:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:40:07 INFO - (blobuploader) - INFO - Done attempting. 14:40:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:40:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:40:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:40:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:40:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:40:08 INFO - (blobuploader) - INFO - Done attempting. 14:40:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:40:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:40:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:40:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:40:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:40:10 INFO - (blobuploader) - INFO - Done attempting. 14:40:10 INFO - (blobuploader) - INFO - Iteration through files over. 14:40:10 INFO - Return code: 0 14:40:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:40:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:40:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f90db0299a839095d4af7db85cced7eed38486de9a7c188ffc88c336806754a7cb9f6d5c2153bce541d12d575c1353f6193007a5afd8d29f12e42118fada9595", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b91579abb532639f1214f39c1f9b75f7edd53698f944d5accb70a6108a961d789eebaafaf876045a05b4b232becb50a176511086c61293ebe1ccaef3789ce8f4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/af0caaf63e67b22f75194bef0e943e0924d3b3b3d1b2980b27e067cfd171da1687e01ba75281c150c26a9a88bf884df53196798f9101ed69ad4966e8b6b37c94"} 14:40:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:40:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:40:10 INFO - Contents: 14:40:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f90db0299a839095d4af7db85cced7eed38486de9a7c188ffc88c336806754a7cb9f6d5c2153bce541d12d575c1353f6193007a5afd8d29f12e42118fada9595", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b91579abb532639f1214f39c1f9b75f7edd53698f944d5accb70a6108a961d789eebaafaf876045a05b4b232becb50a176511086c61293ebe1ccaef3789ce8f4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/af0caaf63e67b22f75194bef0e943e0924d3b3b3d1b2980b27e067cfd171da1687e01ba75281c150c26a9a88bf884df53196798f9101ed69ad4966e8b6b37c94"} 14:40:10 INFO - Running post-run listener: copy_logs_to_upload_dir 14:40:10 INFO - Copying logs to upload dir... 14:40:10 INFO - mkdir: /builds/slave/test/build/upload/logs 14:40:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.986798 ========= master_lag: 11.73 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-09-20 14:40:22.301227) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-09-20 14:40:22.308681) ========= 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.8avJsBmJJa/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.6iiuZwnBbT/Listeners TMPDIR=/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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-Beta/sha512/f90db0299a839095d4af7db85cced7eed38486de9a7c188ffc88c336806754a7cb9f6d5c2153bce541d12d575c1353f6193007a5afd8d29f12e42118fada9595", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b91579abb532639f1214f39c1f9b75f7edd53698f944d5accb70a6108a961d789eebaafaf876045a05b4b232becb50a176511086c61293ebe1ccaef3789ce8f4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/af0caaf63e67b22f75194bef0e943e0924d3b3b3d1b2980b27e067cfd171da1687e01ba75281c150c26a9a88bf884df53196798f9101ed69ad4966e8b6b37c94"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011379 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f90db0299a839095d4af7db85cced7eed38486de9a7c188ffc88c336806754a7cb9f6d5c2153bce541d12d575c1353f6193007a5afd8d29f12e42118fada9595", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b91579abb532639f1214f39c1f9b75f7edd53698f944d5accb70a6108a961d789eebaafaf876045a05b4b232becb50a176511086c61293ebe1ccaef3789ce8f4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/af0caaf63e67b22f75194bef0e943e0924d3b3b3d1b2980b27e067cfd171da1687e01ba75281c150c26a9a88bf884df53196798f9101ed69ad4966e8b6b37c94"}' ========= master_lag: 1.48 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-09-20 14:40:23.802234) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-09-20 14:40:23.802563) ========= 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.8avJsBmJJa/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.6iiuZwnBbT/Listeners TMPDIR=/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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.005373 ========= master_lag: 5.70 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-09-20 14:40:29.504292) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 14:40:29.504841) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 14:40:29.518866) ========= ========= Total master_lag: 19.97 =========