builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0367 starttime: 1473692780.05 results: success (0) buildid: 20160912062116 builduid: 7aeaaab3b5b9448da74dfb8fb58a8c25 revision: b09dd5013cefe5d66700ca0d25ceaf821509069e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 08:06:20.055176) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 08:06:20.055631) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 08:06:20.055919) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 08:06:20.083240) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 08:06:20.083608) ========= 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.schOEClg87/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.yItXNKBddG/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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-12 08:06:20-- 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% 19.1M=0.001s 2016-09-12 08:06:20 (19.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.146084 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 08:06:20.276487) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 08:06:20.276856) ========= 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.schOEClg87/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.yItXNKBddG/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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.033781 ========= master_lag: 0.12 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 08:06:20.427035) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 08:06:20.427430) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev b09dd5013cefe5d66700ca0d25ceaf821509069e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev b09dd5013cefe5d66700ca0d25ceaf821509069e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.schOEClg87/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.yItXNKBddG/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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-12 08:06:20,517 truncating revision to first 12 chars 2016-09-12 08:06:20,517 Setting DEBUG logging. 2016-09-12 08:06:20,517 attempt 1/10 2016-09-12 08:06:20,517 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b09dd5013cef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-12 08:06:20,819 unpacking tar archive at: fx-team-b09dd5013cef/testing/mozharness/ program finished with exit code 0 elapsedTime=0.571592 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 08:06:21.050343) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 08:06:21.050675) ========= script_repo_revision: b09dd5013cefe5d66700ca0d25ceaf821509069e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 08:06:21.051069) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 08:06:21.051346) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 08:06:21.213297) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-12 08:06:21.213586) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.schOEClg87/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.yItXNKBddG/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 08:06:21 INFO - MultiFileLogger online at 20160912 08:06:21 in /builds/slave/test 08:06:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 08:06:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:06:21 INFO - {'append_to_log': False, 08:06:21 INFO - 'base_work_dir': '/builds/slave/test', 08:06:21 INFO - 'blob_upload_branch': 'Fx-Team', 08:06:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:06:21 INFO - 'branch': 'Fx-Team', 08:06:21 INFO - 'buildbot_json_path': 'buildprops.json', 08:06:21 INFO - 'config_files': ('talos/mac_config.py',), 08:06:21 INFO - 'default_actions': ('clobber', 08:06:21 INFO - 'read-buildbot-config', 08:06:21 INFO - 'download-and-extract', 08:06:21 INFO - 'populate-webroot', 08:06:21 INFO - 'create-virtualenv', 08:06:21 INFO - 'install', 08:06:21 INFO - 'run-tests'), 08:06:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:06:21 INFO - 'download_minidump_stackwalk': True, 08:06:21 INFO - 'download_symbols': 'ondemand', 08:06:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:06:21 INFO - 'tooltool.py': '/tools/tooltool.py', 08:06:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:06:21 INFO - '/tools/misc-python/virtualenv.py')}, 08:06:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:06:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:06:21 INFO - 'installer_path': 'installer.exe', 08:06:21 INFO - 'log_level': 'info', 08:06:21 INFO - 'log_name': 'talos', 08:06:21 INFO - 'log_to_console': True, 08:06:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:06:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:06:21 INFO - 'opt_config_files': (), 08:06:21 INFO - 'pip_index': False, 08:06:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:06:21 INFO - 'cmd': ('bash', 08:06:21 INFO - '-c', 08:06:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:06:21 INFO - 'enabled': True, 08:06:21 INFO - 'halt_on_failure': False, 08:06:21 INFO - 'name': 'check_screen_resolution'},), 08:06:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:06:21 INFO - 'cmd': ('bash', 08:06:21 INFO - '-c', 08:06:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:06:21 INFO - 'enabled': True, 08:06:21 INFO - 'halt_on_failure': False, 08:06:21 INFO - 'name': 'check_screen_resolution'},), 08:06:21 INFO - 'run_cmd_checks_enabled': True, 08:06:21 INFO - 'sps_profile': False, 08:06:21 INFO - 'sps_profile_interval': 0, 08:06:21 INFO - 'suite': 'dromaeojs', 08:06:21 INFO - 'system_bits': '32', 08:06:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:06:21 INFO - 'title': 't-yosemite-r7-0367', 08:06:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:06:21 INFO - 'use_talos_json': True, 08:06:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:06:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:06:21 INFO - 'work_dir': 'build'} 08:06:21 INFO - [mozharness: 2016-09-12 15:06:21.422930Z] Running clobber step. 08:06:21 INFO - Running pre-action listener: _resource_record_pre_action 08:06:21 INFO - Running main action method: clobber 08:06:21 INFO - rmtree: /builds/slave/test/build 08:06:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:06:23 INFO - Running post-action listener: _resource_record_post_action 08:06:23 INFO - [mozharness: 2016-09-12 15:06:23.566730Z] Finished clobber step (success) 08:06:23 INFO - [mozharness: 2016-09-12 15:06:23.566866Z] Running read-buildbot-config step. 08:06:23 INFO - Running pre-action listener: _resource_record_pre_action 08:06:23 INFO - Running main action method: read_buildbot_config 08:06:23 INFO - Using buildbot properties: 08:06:23 INFO - { 08:06:23 INFO - "project": "", 08:06:23 INFO - "product": "firefox", 08:06:23 INFO - "script_repo_revision": "production", 08:06:23 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 08:06:23 INFO - "repository": "", 08:06:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 08:06:23 INFO - "buildid": "20160912062116", 08:06:23 INFO - "pgo_build": "False", 08:06:23 INFO - "basedir": "/builds/slave/test", 08:06:23 INFO - "buildnumber": 154, 08:06:23 INFO - "slavename": "t-yosemite-r7-0367", 08:06:23 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 08:06:23 INFO - "platform": "yosemite_r7", 08:06:23 INFO - "scheduler": "tests-fx-team-macosx64-talos", 08:06:23 INFO - "branch": "fx-team", 08:06:23 INFO - "repo_path": "integration/fx-team", 08:06:23 INFO - "revision": "b09dd5013cefe5d66700ca0d25ceaf821509069e", 08:06:23 INFO - "stage_platform": "macosx64", 08:06:23 INFO - "builduid": "7aeaaab3b5b9448da74dfb8fb58a8c25", 08:06:23 INFO - "slavebuilddir": "test" 08:06:23 INFO - } 08:06:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 08:06:23 INFO - Running post-action listener: _resource_record_post_action 08:06:23 INFO - [mozharness: 2016-09-12 15:06:23.570006Z] Finished read-buildbot-config step (success) 08:06:23 INFO - [mozharness: 2016-09-12 15:06:23.570140Z] Running download-and-extract step. 08:06:23 INFO - Running pre-action listener: _resource_record_pre_action 08:06:23 INFO - Running main action method: download_and_extract 08:06:23 INFO - mkdir: /builds/slave/test/build/tests 08:06:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:06:23 INFO - trying https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 08:06:23 INFO - Downloading https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 08:06:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 08:06:24 INFO - Downloaded 1199 bytes. 08:06:24 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 08:06:24 INFO - Using the following test package requirements: 08:06:24 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:06:24 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:06:24 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 08:06:24 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:06:24 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 08:06:24 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:06:24 INFO - u'jsshell-mac.zip'], 08:06:24 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:06:24 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 08:06:24 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:06:24 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:06:24 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 08:06:24 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:06:24 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 08:06:24 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:06:24 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 08:06:24 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:06:24 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 08:06:24 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:06:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:06:25 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 08:06:33 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:06:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:06:33 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 08:06:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 08:06:41 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 08:06:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:06:41 INFO - trying https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:06:41 INFO - Downloading https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 08:06:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 08:06:44 INFO - Downloaded 111633686 bytes. 08:06:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:06:44 INFO - mkdir: /builds/slave/test/properties 08:06:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:06:44 INFO - Writing to file /builds/slave/test/properties/build_url 08:06:44 INFO - Contents: 08:06:44 INFO - build_url:https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:06:45 INFO - Running post-action listener: _resource_record_post_action 08:06:45 INFO - Running post-action listener: set_extra_try_arguments 08:06:45 INFO - [mozharness: 2016-09-12 15:06:45.491641Z] Finished download-and-extract step (success) 08:06:45 INFO - [mozharness: 2016-09-12 15:06:45.491776Z] Running populate-webroot step. 08:06:45 INFO - Running pre-action listener: _resource_record_pre_action 08:06:45 INFO - Running main action method: populate_webroot 08:06:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:06:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:06:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:06:45 INFO - '--tppagecycles', 08:06:45 INFO - '7'], 08:06:45 INFO - 'tests': ['tsvgm']}}, 08:06:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:06:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:06:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:06:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:06:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:06:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:06:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:06:45 INFO - 'talos_options': ['--disable-e10s'], 08:06:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:06:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:06:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:06:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:06:45 INFO - 'talos_options': ['--disable-e10s'], 08:06:45 INFO - 'tests': ['damp', 'tps']}, 08:06:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:06:45 INFO - 'tests': ['damp', 'tps']}, 08:06:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:06:45 INFO - 'tests': ['dromaeo_dom']}, 08:06:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:06:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:06:45 INFO - 'tests': ['basic_compositor_video']}, 08:06:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:06:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:06:45 INFO - 'tests': ['a11yr', 08:06:45 INFO - 'ts_paint', 08:06:45 INFO - 'tpaint', 08:06:45 INFO - 'sessionrestore', 08:06:45 INFO - 'sessionrestore_no_auto_restore', 08:06:45 INFO - 'tabpaint']}, 08:06:45 INFO - 'other-e10s': {'tests': ['a11yr', 08:06:45 INFO - 'ts_paint', 08:06:45 INFO - 'tpaint', 08:06:45 INFO - 'sessionrestore', 08:06:45 INFO - 'sessionrestore_no_auto_restore', 08:06:45 INFO - 'tabpaint']}, 08:06:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:06:45 INFO - 'ts_paint', 08:06:45 INFO - 'tpaint', 08:06:45 INFO - 'sessionrestore', 08:06:45 INFO - 'sessionrestore_no_auto_restore', 08:06:45 INFO - 'tabpaint']}, 08:06:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:06:45 INFO - 'ts_paint', 08:06:45 INFO - 'tpaint', 08:06:45 INFO - 'sessionrestore', 08:06:45 INFO - 'sessionrestore_no_auto_restore', 08:06:45 INFO - 'tabpaint']}, 08:06:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:06:45 INFO - 'tests': ['a11yr', 08:06:45 INFO - 'ts_paint', 08:06:45 INFO - 'tpaint', 08:06:45 INFO - 'sessionrestore', 08:06:45 INFO - 'sessionrestore_no_auto_restore', 08:06:45 INFO - 'tabpaint']}, 08:06:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:06:45 INFO - 'tests': ['a11yr', 08:06:45 INFO - 'ts_paint', 08:06:45 INFO - 'tpaint', 08:06:45 INFO - 'sessionrestore', 08:06:45 INFO - 'sessionrestore_no_auto_restore', 08:06:45 INFO - 'tabpaint']}, 08:06:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:06:45 INFO - 'tests': ['tsvgx', 08:06:45 INFO - 'tsvgr_opacity', 08:06:45 INFO - 'tart', 08:06:45 INFO - 'tscrollx', 08:06:45 INFO - 'cart']}, 08:06:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:06:45 INFO - 'tsvgr_opacity', 08:06:45 INFO - 'tart', 08:06:45 INFO - 'tscrollx', 08:06:45 INFO - 'cart']}, 08:06:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:06:45 INFO - 'talos_options': ['--disable-e10s'], 08:06:45 INFO - 'tests': ['tp5o']}, 08:06:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:06:45 INFO - 'tests': ['tp5o']}, 08:06:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:06:45 INFO - 'talos_options': ['--disable-e10s', 08:06:45 INFO - '--xperf_path', 08:06:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:06:45 INFO - 'tests': ['tp5n']}, 08:06:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:06:45 INFO - 'talos_options': ['--xperf_path', 08:06:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:06:45 INFO - 'tests': ['tp5n']}}, 08:06:45 INFO - 'talos.zip': {'path': '', 08:06:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:06:45 INFO - Running post-action listener: _resource_record_post_action 08:06:45 INFO - [mozharness: 2016-09-12 15:06:45.510774Z] Finished populate-webroot step (success) 08:06:45 INFO - [mozharness: 2016-09-12 15:06:45.510908Z] Running create-virtualenv step. 08:06:45 INFO - Running pre-action listener: _resource_record_pre_action 08:06:45 INFO - Running main action method: create_virtualenv 08:06:45 INFO - Creating virtualenv /builds/slave/test/build/venv 08:06:45 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 08:06:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:06:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:06:45 INFO - Using real prefix '/tools/python27' 08:06:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:06:46 INFO - Installing distribute.............................................................................................................................................................................................done. 08:06:49 INFO - Installing pip.................done. 08:06:49 INFO - Return code: 0 08:06:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:06: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:06:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:06:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:06: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:06:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:06:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:06: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b244570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd408c950d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0e2d30>, '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 0x10af08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0dab20>, '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.schOEClg87/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.yItXNKBddG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 08:06: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', 'pip>=1.5'] in /builds/slave/test/build 08:06: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 pip>=1.5 08:06:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.schOEClg87/Render', 08:06:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:06:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:06:49 INFO - 'HOME': '/Users/cltbld', 08:06:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:06:49 INFO - 'LOGNAME': 'cltbld', 08:06:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:06:49 INFO - 'MOZ_NO_REMOTE': '1', 08:06:49 INFO - 'NO_EM_RESTART': '1', 08:06:49 INFO - 'PAGER': '/bin/cat', 08:06:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:06:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:06:49 INFO - 'PWD': '/builds/slave/test', 08:06:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:06:49 INFO - 'SHELL': '/bin/bash', 08:06:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yItXNKBddG/Listeners', 08:06:49 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 08:06:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:06:49 INFO - 'USER': 'cltbld', 08:06:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:06:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:06:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:06:49 INFO - 'XPC_FLAGS': '0x0', 08:06:49 INFO - 'XPC_SERVICE_NAME': '0', 08:06:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:06:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:06:49 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 08:06:49 INFO - Cleaning up... 08:06:49 INFO - Return code: 0 08:06:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:06: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:06:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:06:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:06: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:06:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:06:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:06: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', '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 0x10b2ce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b244570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd408c950d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0e2d30>, '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 0x10af08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0dab20>, '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.schOEClg87/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.yItXNKBddG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 08:06: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', 'psutil>=3.1.1'] in /builds/slave/test/build 08:06: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 psutil>=3.1.1 08:06:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.schOEClg87/Render', 08:06:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:06:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:06:49 INFO - 'HOME': '/Users/cltbld', 08:06:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:06:49 INFO - 'LOGNAME': 'cltbld', 08:06:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:06:49 INFO - 'MOZ_NO_REMOTE': '1', 08:06:49 INFO - 'NO_EM_RESTART': '1', 08:06:49 INFO - 'PAGER': '/bin/cat', 08:06:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:06:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:06:49 INFO - 'PWD': '/builds/slave/test', 08:06:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:06:49 INFO - 'SHELL': '/bin/bash', 08:06:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yItXNKBddG/Listeners', 08:06:49 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 08:06:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:06:49 INFO - 'USER': 'cltbld', 08:06:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:06:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:06:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:06:49 INFO - 'XPC_FLAGS': '0x0', 08:06:49 INFO - 'XPC_SERVICE_NAME': '0', 08:06:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:06:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:06:49 INFO - Downloading/unpacking psutil>=3.1.1 08:06: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 08:06: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 08:06: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 08:06: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 08:06: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 08:06: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 08:06:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:06:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:06:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:06:53 INFO - Installing collected packages: psutil 08:06:53 INFO - Running setup.py install for psutil 08:06:53 INFO - building 'psutil._psutil_osx' extension 08:06:53 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 08:06:53 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 08:06:53 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 08:06:53 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 08:06:53 INFO - building 'psutil._psutil_posix' extension 08:06:53 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 08:06:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:06:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:06:54 INFO - ^ 08:06:54 INFO - 1 warning generated. 08:06:54 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 08:06:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:06:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:06:54 INFO - Successfully installed psutil 08:06:54 INFO - Cleaning up... 08:06:54 INFO - Return code: 0 08:06:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:06:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:06:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:06:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:06:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:06:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:06:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b244570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd408c950d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0e2d30>, '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 0x10af08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0dab20>, '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.schOEClg87/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.yItXNKBddG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 08:06:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:06:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:06:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.schOEClg87/Render', 08:06:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:06:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:06:54 INFO - 'HOME': '/Users/cltbld', 08:06:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:06:54 INFO - 'LOGNAME': 'cltbld', 08:06:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:06:54 INFO - 'MOZ_NO_REMOTE': '1', 08:06:54 INFO - 'NO_EM_RESTART': '1', 08:06:54 INFO - 'PAGER': '/bin/cat', 08:06:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:06:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:06:54 INFO - 'PWD': '/builds/slave/test', 08:06:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:06:54 INFO - 'SHELL': '/bin/bash', 08:06:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yItXNKBddG/Listeners', 08:06:54 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 08:06:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:06:54 INFO - 'USER': 'cltbld', 08:06:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:06:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:06:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:06:54 INFO - 'XPC_FLAGS': '0x0', 08:06:54 INFO - 'XPC_SERVICE_NAME': '0', 08:06:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:06:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:06:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:06:54 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 08:06:54 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 08:06:54 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 08:06:54 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 08:06:54 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 08:06:54 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 08:06:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:06:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:06:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:06:57 INFO - Installing collected packages: mozsystemmonitor 08:06:57 INFO - Running setup.py install for mozsystemmonitor 08:06:57 INFO - Successfully installed mozsystemmonitor 08:06:57 INFO - Cleaning up... 08:06:57 INFO - Return code: 0 08:06:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:06:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:06:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:06:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:06:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:06:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:06:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:06:57 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 0x10b2ce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b244570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd408c950d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0e2d30>, '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 0x10af08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0dab20>, '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.schOEClg87/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.yItXNKBddG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 08:06:57 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 08:06:57 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 08:06:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.schOEClg87/Render', 08:06:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:06:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:06:57 INFO - 'HOME': '/Users/cltbld', 08:06:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:06:57 INFO - 'LOGNAME': 'cltbld', 08:06:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:06:57 INFO - 'MOZ_NO_REMOTE': '1', 08:06:57 INFO - 'NO_EM_RESTART': '1', 08:06:57 INFO - 'PAGER': '/bin/cat', 08:06:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:06:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:06:57 INFO - 'PWD': '/builds/slave/test', 08:06:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:06:57 INFO - 'SHELL': '/bin/bash', 08:06:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yItXNKBddG/Listeners', 08:06:57 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 08:06:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:06:57 INFO - 'USER': 'cltbld', 08:06:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:06:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:06:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:06:57 INFO - 'XPC_FLAGS': '0x0', 08:06:57 INFO - 'XPC_SERVICE_NAME': '0', 08:06:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:06:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:06:57 INFO - Downloading/unpacking jsonschema==2.5.1 08:06: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 08:06: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 08:06: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 08:06: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 08:06: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 08:06: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 08:06:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:06:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:06:59 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 08:06:59 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 08:06:59 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 08:06:59 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 08:06:59 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 08:06:59 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 08:07:00 INFO - Downloading functools32-3.2.3-2.tar.gz 08:07:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:07:00 INFO - warning: no files found matching '*.txt' 08:07:00 INFO - no previously-included directories found matching 'build' 08:07:00 INFO - no previously-included directories found matching 'dist' 08:07:00 INFO - no previously-included directories found matching '.git*' 08:07:00 INFO - Installing collected packages: jsonschema, functools32 08:07:00 INFO - Running setup.py install for functools32 08:07:00 INFO - warning: no files found matching '*.txt' 08:07:00 INFO - no previously-included directories found matching 'build' 08:07:00 INFO - no previously-included directories found matching 'dist' 08:07:00 INFO - no previously-included directories found matching '.git*' 08:07:00 INFO - Successfully installed jsonschema functools32 08:07:00 INFO - Cleaning up... 08:07:00 INFO - Return code: 0 08:07:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:07:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:00 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 0x10b2ce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b244570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd408c950d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0e2d30>, '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 0x10af08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0dab20>, '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.schOEClg87/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.yItXNKBddG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 08:07:00 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 08:07:00 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 08:07:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.schOEClg87/Render', 08:07:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:00 INFO - 'HOME': '/Users/cltbld', 08:07:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:00 INFO - 'LOGNAME': 'cltbld', 08:07:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:00 INFO - 'MOZ_NO_REMOTE': '1', 08:07:00 INFO - 'NO_EM_RESTART': '1', 08:07:00 INFO - 'PAGER': '/bin/cat', 08:07:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:00 INFO - 'PWD': '/builds/slave/test', 08:07:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:00 INFO - 'SHELL': '/bin/bash', 08:07:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yItXNKBddG/Listeners', 08:07:00 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 08:07:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:00 INFO - 'USER': 'cltbld', 08:07:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:00 INFO - 'XPC_FLAGS': '0x0', 08:07:00 INFO - 'XPC_SERVICE_NAME': '0', 08:07:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:07:00 INFO - Cleaning up... 08:07:00 INFO - Return code: 0 08:07:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:07:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:00 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 0x10b2ce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b244570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd408c950d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0e2d30>, '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 0x10af08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0dab20>, '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.schOEClg87/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.yItXNKBddG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 08:07:00 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 08:07:00 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 08:07:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.schOEClg87/Render', 08:07:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:00 INFO - 'HOME': '/Users/cltbld', 08:07:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:00 INFO - 'LOGNAME': 'cltbld', 08:07:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:00 INFO - 'MOZ_NO_REMOTE': '1', 08:07:00 INFO - 'NO_EM_RESTART': '1', 08:07:00 INFO - 'PAGER': '/bin/cat', 08:07:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:00 INFO - 'PWD': '/builds/slave/test', 08:07:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:01 INFO - 'SHELL': '/bin/bash', 08:07:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yItXNKBddG/Listeners', 08:07:01 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 08:07:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:01 INFO - 'USER': 'cltbld', 08:07:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:01 INFO - 'XPC_FLAGS': '0x0', 08:07:01 INFO - 'XPC_SERVICE_NAME': '0', 08:07:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:01 INFO - Downloading/unpacking blobuploader==1.2.4 08:07:01 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 08:07:01 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 08:07:01 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 08:07:01 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 08:07:01 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 08:07:01 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 08:07:03 INFO - Downloading blobuploader-1.2.4.tar.gz 08:07:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:07:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:07:03 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 08:07:03 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 08:07:03 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 08:07:03 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 08:07:03 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 08:07:03 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 08:07:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:07:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:07:04 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 08:07:04 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 08:07:04 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 08:07:04 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 08:07:04 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 08:07:04 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 08:07:04 INFO - Downloading docopt-0.6.1.tar.gz 08:07:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:07:04 INFO - Installing collected packages: blobuploader, requests, docopt 08:07:04 INFO - Running setup.py install for blobuploader 08:07:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:07:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:07:05 INFO - Running setup.py install for requests 08:07:05 INFO - Running setup.py install for docopt 08:07:05 INFO - Successfully installed blobuploader requests docopt 08:07:05 INFO - Cleaning up... 08:07:05 INFO - Return code: 0 08:07:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:07:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:05 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 0x10b2ce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b244570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd408c950d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0e2d30>, '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 0x10af08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0dab20>, '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.schOEClg87/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.yItXNKBddG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 08:07:05 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 08:07:05 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 08:07:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.schOEClg87/Render', 08:07:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:05 INFO - 'HOME': '/Users/cltbld', 08:07:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:05 INFO - 'LOGNAME': 'cltbld', 08:07:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:05 INFO - 'MOZ_NO_REMOTE': '1', 08:07:05 INFO - 'NO_EM_RESTART': '1', 08:07:05 INFO - 'PAGER': '/bin/cat', 08:07:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:05 INFO - 'PWD': '/builds/slave/test', 08:07:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:05 INFO - 'SHELL': '/bin/bash', 08:07:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yItXNKBddG/Listeners', 08:07:05 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 08:07:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:05 INFO - 'USER': 'cltbld', 08:07:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:05 INFO - 'XPC_FLAGS': '0x0', 08:07:05 INFO - 'XPC_SERVICE_NAME': '0', 08:07:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:07:06 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-eI447i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:07:06 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-W1V62l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:07:06 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-w2SPdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:07:06 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-pim4Rx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:07:06 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-DG2IGn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:07:06 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-YD3fgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:07:06 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-i3Zq2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:07:06 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-r5LMWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:07:06 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-14FjiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:07:06 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-bNQjbb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:07:07 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-ScgqkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:07:07 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-vC5XxI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:07:07 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-EYTcSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:07:07 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-BuoBia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:07:07 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-Dg4Ciz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:07:07 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-GSK7rb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:07:07 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-P15oM5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:07:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:07:07 INFO - Running setup.py install for manifestparser 08:07:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:07:07 INFO - Running setup.py install for mozcrash 08:07:08 INFO - Running setup.py install for mozdebug 08:07:08 INFO - Running setup.py install for mozdevice 08:07:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:07:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:07:08 INFO - Running setup.py install for mozfile 08:07:08 INFO - Running setup.py install for mozhttpd 08:07:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:07:08 INFO - Running setup.py install for mozinfo 08:07:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:07:08 INFO - Running setup.py install for mozInstall 08:07:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:07:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:07:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:07:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:07:08 INFO - Running setup.py install for mozleak 08:07:09 INFO - Running setup.py install for mozlog 08:07:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:07:09 INFO - Running setup.py install for moznetwork 08:07:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:07:09 INFO - Running setup.py install for mozprocess 08:07:09 INFO - Running setup.py install for mozprofile 08:07:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:07:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:07:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:07:09 INFO - Running setup.py install for mozrunner 08:07:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:07:09 INFO - Running setup.py install for mozscreenshot 08:07:10 INFO - Running setup.py install for moztest 08:07:10 INFO - Running setup.py install for mozversion 08:07:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:07:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:07:10 INFO - Cleaning up... 08:07:10 INFO - Return code: 0 08:07:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:07:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:10 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 0x10b2ce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b244570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd408c950d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0e2d30>, '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 0x10af08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0dab20>, '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.schOEClg87/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.yItXNKBddG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 08:07:10 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 08:07:10 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 08:07:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.schOEClg87/Render', 08:07:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:10 INFO - 'HOME': '/Users/cltbld', 08:07:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:10 INFO - 'LOGNAME': 'cltbld', 08:07:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:10 INFO - 'MOZ_NO_REMOTE': '1', 08:07:10 INFO - 'NO_EM_RESTART': '1', 08:07:10 INFO - 'PAGER': '/bin/cat', 08:07:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:10 INFO - 'PWD': '/builds/slave/test', 08:07:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:10 INFO - 'SHELL': '/bin/bash', 08:07:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yItXNKBddG/Listeners', 08:07:10 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 08:07:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:10 INFO - 'USER': 'cltbld', 08:07:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:10 INFO - 'XPC_FLAGS': '0x0', 08:07:10 INFO - 'XPC_SERVICE_NAME': '0', 08:07:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:07:10 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-0_ZXfQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:07:10 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)) 08:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:07:10 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-qglLId-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:07:10 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)) 08:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:07:10 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-R_hrdU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:07:10 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)) 08:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:07:10 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-r65bLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:07:11 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)) 08:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:07:11 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-yqfxuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:07:11 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)) 08:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:07:11 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-FnGm2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:07:11 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)) 08:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:07:11 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-LKfCLH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:07:11 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)) 08:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:07:11 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-PvJHsM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:07:11 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)) 08:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:07:11 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-dYTJl5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:07:11 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)) 08:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:07:11 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-X32z3w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:07:11 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)) 08:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:07:11 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-SCvpQu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:07:11 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)) 08:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:07:11 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-tT9owB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:07:11 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)) 08:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:07:11 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-2Ci90I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:07:12 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)) 08:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:07:12 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-OBcltg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:07:12 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)) 08:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:07:12 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-Plr1zZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:07:12 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)) 08:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:07:12 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-_xJ8iD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:07:12 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)) 08:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:07:12 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-Y6mKDd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:07:12 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)) 08:07:12 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)) 08:07:12 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)) 08:07:12 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)) 08:07:12 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)) 08:07:12 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)) 08:07:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:07:12 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 08:07:12 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 08:07:12 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 08:07:12 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 08:07:12 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 08:07:12 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 08:07:14 INFO - Downloading blessings-1.6.tar.gz 08:07:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:07:15 INFO - Installing collected packages: blessings 08:07:15 INFO - Running setup.py install for blessings 08:07:15 INFO - Successfully installed blessings 08:07:15 INFO - Cleaning up... 08:07:15 INFO - Return code: 0 08:07:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:07:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:07:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:07:15 INFO - Reading from file tmpfile_stdout 08:07:15 INFO - Current package versions: 08:07:15 INFO - blessings == 1.6 08:07:15 INFO - blobuploader == 1.2.4 08:07:15 INFO - docopt == 0.6.1 08:07:15 INFO - functools32 == 3.2.3-2 08:07:15 INFO - jsonschema == 2.5.1 08:07:15 INFO - manifestparser == 1.1 08:07:15 INFO - mozInstall == 1.12 08:07:15 INFO - mozcrash == 0.17 08:07:15 INFO - mozdebug == 0.1 08:07:15 INFO - mozdevice == 0.48 08:07:15 INFO - mozfile == 1.2 08:07:15 INFO - mozhttpd == 0.7 08:07:15 INFO - mozinfo == 0.9 08:07:15 INFO - mozleak == 0.1 08:07:15 INFO - mozlog == 3.3 08:07:15 INFO - moznetwork == 0.27 08:07:15 INFO - mozprocess == 0.23 08:07:15 INFO - mozprofile == 0.28 08:07:15 INFO - mozrunner == 6.12 08:07:15 INFO - mozscreenshot == 0.1 08:07:15 INFO - mozsystemmonitor == 0.3 08:07:15 INFO - moztest == 0.7 08:07:15 INFO - mozversion == 1.4 08:07:15 INFO - psutil == 3.1.1 08:07:15 INFO - requests == 1.2.3 08:07:15 INFO - wsgiref == 0.1.2 08:07:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:07:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:15 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 0x10b2ce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b244570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd408c950d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0e2d30>, '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 0x10af08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0dab20>, '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.schOEClg87/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.yItXNKBddG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 08:07:15 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 08:07:15 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 08:07:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.schOEClg87/Render', 08:07:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:15 INFO - 'HOME': '/Users/cltbld', 08:07:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:15 INFO - 'LOGNAME': 'cltbld', 08:07:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:15 INFO - 'MOZ_NO_REMOTE': '1', 08:07:15 INFO - 'NO_EM_RESTART': '1', 08:07:15 INFO - 'PAGER': '/bin/cat', 08:07:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:15 INFO - 'PWD': '/builds/slave/test', 08:07:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:15 INFO - 'SHELL': '/bin/bash', 08:07:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yItXNKBddG/Listeners', 08:07:15 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 08:07:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:15 INFO - 'USER': 'cltbld', 08:07:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:15 INFO - 'XPC_FLAGS': '0x0', 08:07:15 INFO - 'XPC_SERVICE_NAME': '0', 08:07:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:15 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)) 08:07:15 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)) 08:07:15 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)) 08:07:15 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)) 08:07:15 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)) 08:07:15 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)) 08:07:15 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)) 08:07:15 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)) 08:07:15 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)) 08:07:15 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)) 08:07:15 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)) 08:07:15 INFO - Cleaning up... 08:07:15 INFO - Return code: 0 08:07:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:07:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:15 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 0x10b2ce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b244570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd408c950d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0e2d30>, '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 0x10af08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0dab20>, '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.schOEClg87/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.yItXNKBddG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 08:07:15 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 08:07:15 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 08:07:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.schOEClg87/Render', 08:07:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:15 INFO - 'HOME': '/Users/cltbld', 08:07:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:15 INFO - 'LOGNAME': 'cltbld', 08:07:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:15 INFO - 'MOZ_NO_REMOTE': '1', 08:07:15 INFO - 'NO_EM_RESTART': '1', 08:07:15 INFO - 'PAGER': '/bin/cat', 08:07:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:15 INFO - 'PWD': '/builds/slave/test', 08:07:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:15 INFO - 'SHELL': '/bin/bash', 08:07:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yItXNKBddG/Listeners', 08:07:15 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 08:07:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:15 INFO - 'USER': 'cltbld', 08:07:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:15 INFO - 'XPC_FLAGS': '0x0', 08:07:15 INFO - 'XPC_SERVICE_NAME': '0', 08:07:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:07:16 INFO - Cleaning up... 08:07:16 INFO - Return code: 0 08:07:16 INFO - Running post-action listener: _resource_record_post_action 08:07:16 INFO - Running post-action listener: _start_resource_monitoring 08:07:16 INFO - Starting resource monitoring. 08:07:16 INFO - [mozharness: 2016-09-12 15:07:16.139728Z] Finished create-virtualenv step (success) 08:07:16 INFO - [mozharness: 2016-09-12 15:07:16.140734Z] Running install step. 08:07:16 INFO - Running pre-action listener: _resource_record_pre_action 08:07:16 INFO - Running main action method: install 08:07:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:07:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:07:16 INFO - Reading from file tmpfile_stdout 08:07:16 INFO - Detecting whether we're running mozinstall >=1.0... 08:07:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:07:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:07:16 INFO - Reading from file tmpfile_stdout 08:07:16 INFO - Output received: 08:07:16 INFO - Usage: mozinstall [options] installer 08:07:16 INFO - Options: 08:07:16 INFO - -h, --help show this help message and exit 08:07:16 INFO - -d DEST, --destination=DEST 08:07:16 INFO - Directory to install application into. [default: 08:07:16 INFO - "/builds/slave/test"] 08:07:16 INFO - --app=APP Application being installed. [default: firefox] 08:07:16 INFO - mkdir: /builds/slave/test/build/application 08:07:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:07:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:07:50 INFO - Reading from file tmpfile_stdout 08:07:50 INFO - Output received: 08:07:50 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:07:50 INFO - Running post-action listener: _resource_record_post_action 08:07:50 INFO - [mozharness: 2016-09-12 15:07:50.475703Z] Finished install step (success) 08:07:50 INFO - [mozharness: 2016-09-12 15:07:50.475838Z] Running run-tests step. 08:07:50 INFO - Running pre-action listener: _resource_record_pre_action 08:07:50 INFO - Running main action method: run_tests 08:07:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:07:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:07:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:07:50 INFO - 2016-09-12 08:07:50.490 screenresolution[1652:9264] starting screenresolution argv=screenresolution get 08:07:50 INFO - 2016-09-12 08:07:50.492 screenresolution[1652:9264] Display 0: 1600x1200x32@60 08:07:50 INFO - 2016-09-12 08:07:50.498 screenresolution[1653:9266] starting screenresolution argv=screenresolution list 08:07:50 INFO - Available Modes on Display 0 08:07:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:07:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:07:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:07:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:07:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:07:50 INFO - Intel Iris: 08:07:50 INFO - Chipset Model: Intel Iris 08:07:50 INFO - Type: GPU 08:07:50 INFO - Bus: Built-In 08:07:50 INFO - VRAM (Dynamic, Max): 1536 MB 08:07:50 INFO - Vendor: Intel (0x8086) 08:07:50 INFO - Device ID: 0x0a2e 08:07:50 INFO - Revision ID: 0x0009 08:07:50 INFO - Displays: 08:07:50 INFO - Display: 08:07:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:07:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:07:50 INFO - Display Serial Number: 1600x1200 60 08:07:50 INFO - Main Display: Yes 08:07:50 INFO - Mirror: Off 08:07:50 INFO - Online: Yes 08:07:50 INFO - Rotation: Supported 08:07:50 INFO - Return code: 0 08:07:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:07:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:07:50 INFO - Python 2.7.3 08:07:50 INFO - Return code: 0 08:07:50 INFO - grabbing minidump binary from tooltool 08:07:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:50 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 0x10b0e2d30>, '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 0x10af08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0dab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:07:50 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 08:07:50 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 08:07:50 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 08:07:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:07:50 INFO - Return code: 0 08:07:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:07:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:07:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:07:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:07:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:07:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0367', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:07:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0367 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:07:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.schOEClg87/Render', 08:07:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:50 INFO - 'HOME': '/Users/cltbld', 08:07:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:50 INFO - 'LOGNAME': 'cltbld', 08:07:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:07:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:07:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:50 INFO - 'MOZ_NO_REMOTE': '1', 08:07:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:07:50 INFO - 'NO_EM_RESTART': '1', 08:07:50 INFO - 'PAGER': '/bin/cat', 08:07:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:50 INFO - 'PWD': '/builds/slave/test', 08:07:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:07:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:50 INFO - 'SHELL': '/bin/bash', 08:07:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yItXNKBddG/Listeners', 08:07:50 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 08:07:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:50 INFO - 'USER': 'cltbld', 08:07:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:50 INFO - 'XPC_FLAGS': '0x0', 08:07:50 INFO - 'XPC_SERVICE_NAME': '0', 08:07:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0367', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:07:51 INFO - mozversion application_buildid: 20160912062116 08:07:51 INFO - mozversion application_changeset: b09dd5013cefe5d66700ca0d25ceaf821509069e 08:07:51 INFO - mozversion application_display_name: Nightly 08:07:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:07:51 INFO - mozversion application_name: Firefox 08:07:51 INFO - mozversion application_remotingname: firefox 08:07:51 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:07:51 INFO - mozversion application_vendor: Mozilla 08:07:51 INFO - mozversion application_version: 51.0a1 08:07:51 INFO - mozversion platform_buildid: 20160912062116 08:07:51 INFO - mozversion platform_changeset: b09dd5013cefe5d66700ca0d25ceaf821509069e 08:07:51 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:07:51 INFO - mozversion platform_version: 51.0a1 08:07:51 INFO - using testdate: 1473692871 08:07:51 INFO - actual date: 1473692871 08:07:51 INFO - starting webserver on 'localhost:49272' 08:07:51 INFO - SUITE-START | Running 2 tests 08:07:51 INFO - TEST-START | dromaeo_css 08:07:51 INFO - operating with platform_type : mac_ 08:07:51 INFO - Initialising browser for dromaeo_css test... 08:07:51 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmpGaNor3/profile http://localhost:49272/getInfo.html) 08:07:53 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 08:07:53 INFO - PROCESS | 1663 | colorDepth:24 08:07:53 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 08:07:53 INFO - PROCESS | 1663 | __metrics 08:07:53 INFO - PROCESS | 1663 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:07:53 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:07:54 INFO - TEST-INFO | 1663: exit 0 08:07:54 INFO - Browser initialized. 08:07:54 INFO - Running cycle 1/1 for dromaeo_css test... 08:07:54 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmpGaNor3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:08:06 INFO - PROCESS | 1666 | RSS: Main: 232644608 08:08:06 INFO - PROCESS | 1666 | 08:08:56 INFO - PROCESS | 1666 | 1473692936954 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] 08:11:09 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 08:11:10 INFO - PROCESS | 1666 | RSS: Main: 354529280 08:11:10 INFO - PROCESS | 1666 | 08:14:13 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 08:14:14 INFO - PROCESS | 1666 | RSS: Main: 370835456 08:14:14 INFO - PROCESS | 1666 | 08:17:16 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 08:17:17 INFO - PROCESS | 1666 | RSS: Main: 333103104 08:17:17 INFO - PROCESS | 1666 | 08:20:19 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 08:20:20 INFO - PROCESS | 1666 | RSS: Main: 342687744 08:20:20 INFO - PROCESS | 1666 | 08:23:23 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 08:23:24 INFO - PROCESS | 1666 | RSS: Main: 328949760 08:23:24 INFO - PROCESS | 1666 | 08:26:27 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 08:26:27 INFO - PROCESS | 1666 | RSS: Main: 404738048 08:26:27 INFO - PROCESS | 1666 | 08:26:27 INFO - PROCESS | 1666 | __start_tp_report 08:26:27 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 08:26:27 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 08:26:27 INFO - PROCESS | 1666 | |i|pagename|runs| 08:26:27 INFO - PROCESS | 1666 | |0;dojo.html;3362.344342853143;3367.3770352484794;3392.88124915628;3421.46967220081;3460.5847298324206;5620.662760234389;5742.79900203493;5767.452092051254;5884.382139875315;5924.377940316264;5753.568482363829;5802.825915222539;5817.912731309034;5894.292684877821;5913.7338819752995;5211.45279745627;5213.635045546814;5213.921791173136;5271.683698978073;5319.228711836781;4465.821367145327;5380.91928621071;5392.164214546732;5392.4337944515655;5451.536619387337;5185.870353241199;5309.893802123975;5312.2828418163435;5344.599155063386;5370.006548788467;5215.269862219292;5279.841604751864;5286.233496143038;5291.814786482493;5297.020051290524;5197.870053248661;5214.843554693365;5218.712970786605;5225.843224703265;5249.947500524984;5776.306843178803;5809.302883653989;5833.679147646878;5835.241418615596;5855.151003104568;6169.7532098716;6259.217597800275;6264.3422440643535;6277.058441233806;6285.748570057192;6177.66022868742;6200.131981522591;6243.7814676486105;6256.78101266458;6273.686315684197;5739.598228124033;5793.855153621151;5802.0426629605645;5807.157962095473;5837.6497410155525;6324.272708638474;6340.175777148941;6354.046892965999;6358.650274234869;6363.300036995926;6186.566940314135;6208.410201030895;6209.2548894133;6217.595856269328;6221.782237621662;6105.732692890381;6149;6212.782552610637;6236.594621349598;6248.4688801452385;6282.246130464282;6284.497240220771;6290.874182516415;6290.968545157337;6304.873902521924;6298;6316.905246421307;6320.62076275425;6328.71520781566;6336.20797400325;4942.481039490874;4943.728094954812;4968.056069346812;4983.252512123138;4994.700317980933;4239.364095385717;4454.977725111354;4484.0583477469445;4526.388937493462;4545.5908968193025;5673.3475650299915;5736.799212027559;5750.913736293959;5753.568482363839;5807.245058142415;5193.948060519347;6153.753849846045;6332.4934005279465;6355.713992870332;6360.7137678804565;5696.800611978644;5714.399988001283;5718.942810571924;5777.364489906107;5810.12848072784;5460.5085542301385;5493.725313734298;5512.035393806068;5517.862053448615;5528.723563821793;6226.283977342664;6421.197350331208;6422;6426.550141490051;6448.29068802422;6221.253449586078;6241.375862413723;6272.780452684134;6282.120503129474;6345.777897773556;6407.10300557913;6417.8716425672155;6422.614643121427;6423.871522569616;6458.38645328693;6211.130441738251;6279.434850863444;6287.811365659038;6288.748450061947;6290.46531044848;6035.1249068885645;6036.245469316335;6238.282597501346;6244.219472565929;6270.874582508415;6217.098520714561;6219.222597175353;6224.782132625336;6231.345708700521;6241.687915604292;6021.036634138517;6147.415995480422;6209.503239740719;6210.378962103753;6217.813465596039;5875.6474611521735;5890.410958904075;5896.528277737685;5962.37395073511;5990.101484777144;6122.571420000735;6166.352532984151;6213.285472170577;6222.81331560054;6243.188385509855;5838.766449341979;6043.00290452088;6045;6060.4550821828825;6118.082287656887 08:26:27 INFO - PROCESS | 1666 | |1;ext.html;484.76488902882096;498.860319110649;528.9523942845145;536.691402443595;555.5971920357739;29763;38402;39270;39296.41055384168;39389.212215755666;41075.38386924199;41495.17009659805;41609.37585936213;41618.58381416185;41706.58293417065;8615.827683446327;9654.082862128087;9836.458994755285;9841.90158098417;9851.802963940718;8568;11761.176717629769;11807.468663910102;11812.881871181307;12099.213551119194;11920.7615847683;12007.519699212022;12022.819657705142;12035.097367697414;12068.758624827498;11971.162018658697;12037;12040.518379264819;12067.939660301643;12108.455119519598;11747.060235181165;11846.289222646583;11854.05167586591;11855.881441185564;11866.050715942703;39121.80439097815;39319.21361572752;39329.213415731814;39363;39398.40902386467;3296.225387034045;3336.4494858348344;3613.4218525035867;3910.980445097785;3990.940135897964;29912;30643.387132257452;30681.232969175726;30700.078997630106;31287.530687039714;3188.075458117143;3201.2317043909525;3222.661620529834;3227.3222623249144;3335.5997280326515;380377;382966;383393;383456;390813;32405;33115.66884331174;56845.43154568484;57049;57146.714266428404;34246.14384640404;34465.483017754756;34493.310133797684;34501;34630.480542791876;14403.567892963229;14512.201828899511;14525.346359413852;14532.63668408277;14541.27293635314;11141.164412669083;11348.716282093013;12389.814152787716;12515.499380024698;12522.872941435315;6482.57863238888;7463.9626801866725;7609.619519024027;7658.387329013665;7721.691132354756;2786.651668541432;3236.7410607151373;3247.2206670399405;3283.5074738788894;3284.917877053045;918.3938600523622;939.6570251858159;940.3370623710268;990.9207263418909;1008.6419321141036;27150.5927411089;29387.971421000162;29453.55819662707;30191;30324.393512129634;1069.9625513107003;1100.8073587122383;1123.528118190362;1150.3558007515817;1210.7578484303174;12709.428075736614;14245.430182792572;15166.848331516765;15225.390984360502;15502.224888755518;701014;707887;709293;709724;711335.7631753919;54876;55473.72263138658;55552;55657.72171139118;55659.443405566235;1232.5439587352557;1252.630474010169;1360.9455621775019;1371.35546293242;1387;29130.708692912798;30681.079567613007;30706.539401908543;30928.226794330323;31411.52882706807;45867.77066114648;46485;46490.90790914349;46588.53411465978;46827.29759053685;357068;357276;357899;358047;359557;25283.494330113663;25960.09139680102;26569.867150664122;26597.468050639265;27207.72792272131;45076.549234507234;45157.54842451665;45807.85480363017;46272;46413;31066.53400199044;36668.44997324989;36996.26007479889;37766.811165944;37992.620073798906;35902.820485897406;36256.281032400264;36649.08377290589;36789.81605091957;37350.06624834292;35460;35748;36521.63478365289;36555.81722091479;36724;31813.5227971576;33226.833865830515;33251.33497329991;33527.497087544194;33539.83230083834;23543.76456235484;24208.75791242065;24438.633420499064;24967.500649986534;25025.749742502343 08:26:27 INFO - PROCESS | 1666 | |2;jquery.html;350.66686647684696;353.2793102071774;373.55173791450267;379.3248018527022;402.27791114948667;15273;15446.613834654125;16321.347146114142;20727.963601819887;21083.68374474384;19607.70588441171;20483;20519.794802051976;20703.585928281424;20833.166673333086;5312.415634280226;5936.198613187229;6221.408966148231;6229.408206220402;6229.719662615194;5881.147233651118;6228.501719862423;6252.781152659658;6253.530985176108;6254.968725156368;3938.4683067785913;4006.699497537682;4146.419501269825;4146.771927543984;4499.572540608653;4467.910641787179;6879.346462086119;6892.793216203472;6899.344562266577;6899.965500172517;5846.941530584682;5986.161937328778;6045.909311360333;6052.788152414644;6087.147799308101;12873.163244389085;13038;13101.672458188526;17455;24960.375990600198;2130.659094544865;2151.0427859602482;2315.8610483371053;2491.4145175883655;2637.8261673555276;10326.380417174918;10416.843747343795;11738.002269807077;12365.072619553479;14801.11193328393;1787.4101546489758;1844.7325137854937;1890.9810901890942;1920.0591710062108;1951.8581629746616;112151.87848121495;121943;123400.9999999991;126506.36746816208;131083;9247.306452016126;9715.951420242853;9723.319367644197;9843.803123937625;11299.039581635489;12300.384980750927;12389.946854517286;12394.938025309995;12395.318257495745;12405.503779848885;7630.351420129351;7751.3411360035025;7781.9610901945125;7842.882356764653;7852.332551733052;7915.722949696733;7929.207079292025;7980.082290536547;8087.797805054921;8101.878471822927;3182.4908014717535;3815.1797363566966;3825.84696612138;3859.671927886161;3922.6077392260545;1624.3746157729122;1867.2624313396132;1919.1172060851886;1927.4506765572025;1930.9806901930801;776.7087342246657;904.7828521154876;946.2619157057507;958.4441024205944;990.6285143071349;20695.79304206939;21535.569288614453;21556.89221553882;21558.353249402542;21598.920053997237;893.6336102198068;895.6686026170229;900.7793090692691;928.5682157796522;934.0566028311318;10622.521986510626;11341.489632966537;11358.60244891425;11360.943195283971;11430.25703329281;598013;599346;602672;616818;617836;92968;111913.76172476505;113459.43270283761;125878;133726.95866934027;865.6191275838611;887.729242581019;949.1647350331664;972.1687956796978;973.5910917414703;19226.134823932676;19331.806681933;19617.92101434435;19638.509037274183;19683.901580492005;56547;56914;57074.85850282888;57522.13716794335;57832.71083644555;78599.82100268386;79920;80741.59629201816;90046;92044;7108.857822843411;7297.489175757646;7332.81667958284;7434.7769566913075;7909.288164065031;8890.444172269956;12556.87443125557;12960;12977.545785897448;13079.215247085205;9321.953390233006;10788.892111078789;10882.347059176158;11227.157963152633;11361.091112710797;9524.619015239312;10931.01620854127;11005.779884402427;11312.925272099137;11348.602798901999;11126.05428538551;11255.908777470142;11477.598284060017;11585.768284634429;11774.587889423829;6085.543584231112;7168.8924666129005;7327.63361831917;7507.024086868672;7512.098548174254;7998.48009879356;9745.171660408945;11130.220884538005;11297.661070167907;11308.49111789955 08:26:27 INFO - PROCESS | 1666 | |3;mootools.html;1504.112573581587;1548.2104126895283;1739.512936377814;1881.6142690748397;1909.780375256845;7806.180351063142;8607.13928607139;9025.593848276827;9044.5929933153;9078.773030674236;7990.520568765878;8170.264676179143;8196.180381961802;8725.43284686495;8851.070637583058;2389.2593296078185;2420.6611074449593;2422.1401402502124;2424.4302588891596;2425.5876500994873;2397.30478161333;2402.9759702402926;2419.6128619420897;2435.9391015224583;2440.5240978009297;1797.9910100449545;2416.2871952773976;2429.4047958250253;2431.0397393029743;2431.173401043645;2369.2655276864225;2379.4051487128218;2386.2244770449674;2387.069043073203;2388.128333158393;2383;2387.820913431482;2388.534235824015;2389.2115601851347;2389.928302150938;8921.643134274622;8989.595468203881;9037.367384283103;9042.728718138467;9048.864267036;5285.973570132125;5452.345718513764;5453.454654534555;5467.261919640838;5490.533304669108;3554.449060395617;3595.0832537702718;3604.441311596707;3691.483192353073;3751.737378383514;945.9243260539209;1056.5863910521277;1090.487470888681;1095.3811096730321;1111.372074777748;11926.701832454171;11992.940035299767;11993.340366279852;12020.09849261309;12036.81944770838;4730.5813770284985;4741.122892264942;4797.352357431702;4871.610271178368;4880.243562247857;10084.546195421224;10088.344257623232;10094;10097.343672661253;10141.036601522843;5462.7268636568015;5465.808696695596;5470.398256191816;5471.863203419866;5478.123500240023;4502.527234640381;4610.7694615269775;4961.305417241589;5008.223725322581;5020.5732512736095;3417.504461853067;3907.531096268466;3916.4516967624554;4017.397390391406;4077.898052548651;1114.849495318138;1296.5332480307081;1304.9804252936215;1307.66654503103;1317.5915466205506;813.3818298093491;877.3069275272591;891.808261223827;927.800522887569;937.4843835890233;5642;5768.798092066758;5971.820845374559;5979.222701048923;6105.084237364431;374.43647310797076;426.86767102198417;433.38892162051354;443.8513098112104;446.87040758180416;3564.465330200491;3912.68698504119;4015.437838702584;4035.3745169498775;4174.666026717916;11009.669709908716;11024.669259922215;11149.331040137618;11190.32858028521;11238.550457981753;10765.30025548337;10948.616798412017;11114.277571957797;11173.938475844781;11184.944075279735;6905.343992320822;8422;8430.704925327584;8440.62017209227;8467.618957147066;5956.136360227656;6019.307779605227;6079.696015199134;6159.568830182024;6180.6291622502795;9840.261980351359;10291.433971131659;10297.3821570706;10337.37975721459;10345.482725863827;10437.373757574569;10461.738456538647;10467.371957682564;10472.21458390639;10486.16110711126;7023.543469674455;7023.578585284899;7432.479726419098;7558.13081495635;7725.49784264021;8387.83224335522;8616.612252448529;8617.784555386166;8620.353473489638;8623.784405389664;7405.074365704287;7483.476156669199;7570;7678.040244969379;7707.499012564165;7282.708691652275;7396.593187374746;7519.097708275042;7562.0925488941675;7587.962060189664;7018.192907815666;7149.9285007149265;7152.713891444284;7166.355028047501;7171.677274522764;4933.876653083558;4993.5755460784785;5097.08252514551;5115.87210319745;5125.897482050413;4808.519148085164;4830.106430310404;5014.0974624567925;5052.292679025013;5158.97420512895 08:26:27 INFO - PROCESS | 1666 | |4;prototype.html;304.5599109287081;306.94168108059466;312.403309678514;317.49835260288745;321.53056537455313;5315.309009828722;5368.409474957752;5421.945780542198;5455.1544510600825;5483.890322193553;4706.882327941803;4852.078105160017;5113.309703190068;5127.205283181103;5418.593605479585;3970.6227908348806;4121.752694838305;4133.0700592366775;4139.586041395852;4186.79066046698;3410.420228561138;3628.1473853644375;4091.24312002279;4127.215828992496;4152.626263636272;3280.6555311692287;3452.5339079224236;4024.0282261691855;4101.610347017044;4109.075458021951;3401.9489707654407;4067.633912947819;4077;4083.8774836754797;4110.362893751459;3138.2938838761097;3249.821259830708;3382.374260761767;3489.91275218119;3491.2768040927863;5101.821436249714;5190.766415511311;5246.186841039645;5247.344081989751;5295.973520132375;4121.319982202933;4469.955300446986;4660.836870709509;4661.533846615345;4693.694909830884;5651.32184137902;5665.082482521505;5687.7156142193135;5688.60179787415;5714.771409143629;3837.6737977271964;3838.4434257032854;3855.4939289461795;3858.6334298241622;3878.70909681775;5101.923471147936;5528.723563821793;5946.375630558816;5969.701664859061;5975.312839023481;3294.331591410965;3860.613938606117;3890.2997460456822;3890.9416358754643;4001.519817621904;3683.4843121962945;3947.822347994347;3955.4066889966734;3957.465742124776;3971.801409929492;4501.414816073955;4619.69971951822;4653.60444362233;4657.394538710014;4665.790039448233;5681.920435117315;5687.2701103469735;5688.658680479184;5726.627769194989;5737.225474560881;1155.4973586489905;1257.3273298785107;1262.8926541243914;1318.905308593865;1349.8245228120281;697.4629535257823;845.4631309118631;850.8173638642293;882.4881568690273;912.885889263842;5185.844424667266;5189.584833213334;5197.324347834758;5214.191800270964;5231.659942103751;4837.540433658797;5692.8861422771315;5740.712964351849;5752.539796816244;5755.165501002331;4738.8341408050555;5087.033463641897;5141.974290128525;5177.611679124081;5209.609279304067;2627.3431642089477;2789.4700006998605;2812.479691257124;2990.8355040472984;3001.8799248029836;4879.316895634614;5031.119554078022;5666.433356664301;5937.495312898365;6000.249968753906;5106.693598384109;5189.740512974412;5557.610597350662;5585.804496842591;6006.759729610767;5226.902350506436;5370.892582148335;5509.898020396017;6095.744574507461;6255.6872156392;4868.878278043077;5352.571794256528;5490.80782172638;5907.675077870586;5933.122435618808;5810.593258471867;5821.621594596507;5831.825045248649;5835.045259837832;5873.676947767914;5769.384572319757;5805.477507024388;6086.360932102066;6113.84715382119;6166.445019948226;3848.7113466490687;3913.1195481016543;3913.471681323099;3929.9017524562114;3931.3906344516645;4957.380327459067;5380.515753582196;5427.3215848019;5906.911396328973;5923.378045305353;5571.229882773661;5689.487946084872;5726.341470730753;5757.625754325955;5761.913571296518;4859.684120532154;5053.8736531587;5711.600187986801;5727.828165155052;5773.16289138081;5263.736813159403;5514.007478653881;5580.525655319343;5599.720013999202;5613.635113717595;5282.339707536558;5562.3881373048125;5634.605577609692;5666.291713535808;5725.599208055396;5581.6371935824045;5593.440655934374;5613.438656134354;5615.354234262949;5639.605227634191 08:26:27 INFO - PROCESS | 1666 | |5;yui.html;687.5702685821361;720.0818955831318;739.9075115610547;772.3435080181843;777.1839568453123;14653.780193297109;16028.038317700946;16041.358345666189;16066.598335041615;16107.275172617232;12057.397130143501;14587.635309117251;14600.634984125401;14600.780988285183;14604.977651564395;659.3735950846715;679.2155060904663;827.9917200828005;840.7519781664394;862.4092496639822;650.2684479960045;819.2831272636444;829.8174401631632;831.6423937706784;836.0803116571783;897.7800438892481;897.8428774964387;899.4468401932829;902.7111324376202;903.3089686389884;708.3341658840674;728.2862794461457;892.7768057985536;894.9955250223773;895.6372669069004;655.4756195043993;666.6100331306192;668.9431398331147;673.4444083631024;674.1741366825648;14457.927710361486;14515.782263266059;14519.346629401702;14525.78211326831;14533.491327803582;13247.470101196037;13610.727785444335;13679.179249245077;13683.931580342034;13956.651083722887;11985;12149.574764883275;12345.5061797528;12714.682132946751;12890.162139460905;4019.2765302245584;4360.803763830635;4390.670699697504;4405.845795397177;4542.227821270392;126334;129931.7006829929;131813.34093329473;132696;134092;10093.596256149818;10557.366558006544;10596.099331556905;10638.840417393745;10765;20966.37100886976;21113.894430528057;21165.470863228235;21175.36473905785;21178.364649060553;7634.160242373335;7634.351080158249;7721.806954826062;7723.073231212291;7837.529748215125;4166.0834616384345;4210.7684077375425;4239.639630631369;4247.915041699149;4378.759168245777;3301.8018918864946;3405.267867408519;3406.5060566217758;3406.982965085159;3411.846466908995;620.5283984172062;621.273110460762;624.8750249950028;634.2642534659773;644.7292137302344;525.8317338451659;599.484443378694;612.1368869893429;620.5532016947791;637.7672149665339;8470.15298470148;8488.915110848937;8552.61513231906;8815;9001.639934402681;526.3447008474398;532.2973674749371;611.9326874043852;616.0728104203146;625.8028720952885;4200.894977625584;4336.414584031116;4349.804258808361;4442.669595167934;4471.731696098244;99799;145348.81976770356;168075.1596242011;169151.154244228;170691;136766;137837;141338.43174157434;144185;153549.46450535575;115479;116564;116727.41636291765;118467.40766296114;119526;8336.499430102398;8356.080831108451;8380.622871970654;8517.23344898962;8661.783455413664;64627.35372646401;64697;64870;67027.32972670211;68541.65729171323;85944.57027714822;103054;104164.47917760363;106801.92183664476;107439.46280268549;10777.059661212605;10788.298760580537;10882.551425929103;10885.782284354427;11319.264247823865;11273.605423810128;11649.359285239054;11904.239838937989;12195;12369.196002259825;9167.862482062908;9534.380265282734;9620.37467564606;9681.273904457054;9797.265205109788;11201.775964480828;11222.831657525307;11288.468650361428;11311.943440282746;11566.30602163873;11021.504032318726;11188.328700278009;11288.435578220891;11424.257423267461;11625.418729063344;9425.245980321695;9532.23742100644;9602.759931001781;9834.60661573529;9891.851622225817;9403.623855045722;9578.473183974947;9588.328816982745;9596.856047159437;9607.663731769355 08:26:27 INFO - PROCESS | 1666 | __end_tp_report 08:26:27 INFO - PROCESS | 1666 | __start_cc_report 08:26:27 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1194 08:26:27 INFO - PROCESS | 1666 | __end_cc_report 08:26:27 INFO - PROCESS | 1666 | __startTimestamp1473693987736__endTimestamp 08:26:27 INFO - PROCESS | 1666 | 08:26:27 INFO - PROCESS | 1666 | ------- Summary: start ------- 08:26:27 INFO - PROCESS | 1666 | Number of tests: 6 08:26:27 INFO - PROCESS | 1666 | 08:26:27 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5796.41 Median:6021.04 stddev:647.51 (10.8%) stddev-sans-first:620.70 08:26:27 INFO - PROCESS | 1666 | Values: 3362.3 3367.4 3392.9 3421.5 3460.6 5620.7 5742.8 5767.5 5884.4 5924.4 5753.6 5802.8 5817.9 5894.3 5913.7 5211.5 5213.6 5213.9 5271.7 5319.2 4465.8 5380.9 5392.2 5392.4 5451.5 5185.9 5309.9 5312.3 5344.6 5370.0 5215.3 5279.8 5286.2 5291.8 5297.0 5197.9 5214.8 5218.7 5225.8 5249.9 5776.3 5809.3 5833.7 5835.2 5855.2 6169.8 6259.2 6264.3 6277.1 6285.7 6177.7 6200.1 6243.8 6256.8 6273.7 5739.6 5793.9 5802.0 5807.2 5837.6 6324.3 6340.2 6354.0 6358.7 6363.3 6186.6 6208.4 6209.3 6217.6 6221.8 6105.7 6149.0 6212.8 6236.6 6248.5 6282.2 6284.5 6290.9 6291.0 6304.9 6298.0 6316.9 6320.6 6328.7 6336.2 4942.5 4943.7 4968.1 4983.3 4994.7 4239.4 4455.0 4484.1 4526.4 4545.6 5673.3 5736.8 5750.9 5753.6 5807.2 5193.9 6153.8 6332.5 6355.7 6360.7 5696.8 5714.4 5718.9 5777.4 5810.1 5460.5 5493.7 5512.0 5517.9 5528.7 6226.3 6421.2 6422.0 6426.6 6448.3 6221.3 6241.4 6272.8 6282.1 6345.8 6407.1 6417.9 6422.6 6423.9 6458.4 6211.1 6279.4 6287.8 6288.7 6290.5 6035.1 6036.2 6238.3 6244.2 6270.9 6217.1 6219.2 6224.8 6231.3 6241.7 6021.0 6147.4 6209.5 6210.4 6217.8 5875.6 5890.4 5896.5 5962.4 5990.1 6122.6 6166.4 6213.3 6222.8 6243.2 5838.8 6043.0 6045.0 6060.5 6118.1 08:26:27 INFO - PROCESS | 1666 | 08:26:27 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:61105.05 Median:33892.99 stddev:136635.63 (403.1%) stddev-sans-first:136943.12 08:26:27 INFO - PROCESS | 1666 | Values: 484.8 498.9 529.0 536.7 555.6 29763.0 38402.0 39270.0 39296.4 39389.2 41075.4 41495.2 41609.4 41618.6 41706.6 8615.8 9654.1 9836.5 9841.9 9851.8 8568.0 11761.2 11807.5 11812.9 12099.2 11920.8 12007.5 12022.8 12035.1 12068.8 11971.2 12037.0 12040.5 12067.9 12108.5 11747.1 11846.3 11854.1 11855.9 11866.1 39121.8 39319.2 39329.2 39363.0 39398.4 3296.2 3336.4 3613.4 3911.0 3990.9 29912.0 30643.4 30681.2 30700.1 31287.5 3188.1 3201.2 3222.7 3227.3 3335.6 380377.0 382966.0 383393.0 383456.0 390813.0 32405.0 33115.7 56845.4 57049.0 57146.7 34246.1 34465.5 34493.3 34501.0 34630.5 14403.6 14512.2 14525.3 14532.6 14541.3 11141.2 11348.7 12389.8 12515.5 12522.9 6482.6 7464.0 7609.6 7658.4 7721.7 2786.7 3236.7 3247.2 3283.5 3284.9 918.4 939.7 940.3 990.9 1008.6 27150.6 29388.0 29453.6 30191.0 30324.4 1070.0 1100.8 1123.5 1150.4 1210.8 12709.4 14245.4 15166.8 15225.4 15502.2 701014.0 707887.0 709293.0 709724.0 711335.8 54876.0 55473.7 55552.0 55657.7 55659.4 1232.5 1252.6 1360.9 1371.4 1387.0 29130.7 30681.1 30706.5 30928.2 31411.5 45867.8 46485.0 46490.9 46588.5 46827.3 357068.0 357276.0 357899.0 358047.0 359557.0 25283.5 25960.1 26569.9 26597.5 27207.7 45076.5 45157.5 45807.9 46272.0 46413.0 31066.5 36668.4 36996.3 37766.8 37992.6 35902.8 36256.3 36649.1 36789.8 37350.1 35460.0 35748.0 36521.6 36555.8 36724.0 31813.5 33226.8 33251.3 33527.5 33539.8 23543.8 24208.8 24438.6 24967.5 25025.7 08:26:27 INFO - PROCESS | 1666 | 08:26:27 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:34866.46 Median:3820.51 stddev:101283.67 (2651.0%) stddev-sans-first:101534.64 08:26:27 INFO - PROCESS | 1666 | Values: 350.7 353.3 373.6 379.3 402.3 15273.0 15446.6 16321.3 20728.0 21083.7 19607.7 20483.0 20519.8 20703.6 20833.2 5312.4 5936.2 6221.4 6229.4 6229.7 5881.1 6228.5 6252.8 6253.5 6255.0 3938.5 4006.7 4146.4 4146.8 4499.6 4467.9 6879.3 6892.8 6899.3 6900.0 5846.9 5986.2 6045.9 6052.8 6087.1 12873.2 13038.0 13101.7 17455.0 24960.4 2130.7 2151.0 2315.9 2491.4 2637.8 10326.4 10416.8 11738.0 12365.1 14801.1 1787.4 1844.7 1891.0 1920.1 1951.9 112151.9 121943.0 123401.0 126506.4 131083.0 9247.3 9716.0 9723.3 9843.8 11299.0 12300.4 12389.9 12394.9 12395.3 12405.5 7630.4 7751.3 7782.0 7842.9 7852.3 7915.7 7929.2 7980.1 8087.8 8101.9 3182.5 3815.2 3825.8 3859.7 3922.6 1624.4 1867.3 1919.1 1927.5 1931.0 776.7 904.8 946.3 958.4 990.6 20695.8 21535.6 21556.9 21558.4 21598.9 893.6 895.7 900.8 928.6 934.1 10622.5 11341.5 11358.6 11360.9 11430.3 598013.0 599346.0 602672.0 616818.0 617836.0 92968.0 111913.8 113459.4 125878.0 133727.0 865.6 887.7 949.2 972.2 973.6 19226.1 19331.8 19617.9 19638.5 19683.9 56547.0 56914.0 57074.9 57522.1 57832.7 78599.8 79920.0 80741.6 90046.0 92044.0 7108.9 7297.5 7332.8 7434.8 7909.3 8890.4 12556.9 12960.0 12977.5 13079.2 9322.0 10788.9 10882.3 11227.2 11361.1 9524.6 10931.0 11005.8 11312.9 11348.6 11126.1 11255.9 11477.6 11585.8 11774.6 6085.5 7168.9 7327.6 7507.0 7512.1 7998.5 9745.2 11130.2 11297.7 11308.5 08:26:27 INFO - PROCESS | 1666 | 08:26:27 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5792.10 Median:4735.85 stddev:3302.26 (69.7%) stddev-sans-first:3295.80 08:26:27 INFO - PROCESS | 1666 | Values: 1504.1 1548.2 1739.5 1881.6 1909.8 7806.2 8607.1 9025.6 9044.6 9078.8 7990.5 8170.3 8196.2 8725.4 8851.1 2389.3 2420.7 2422.1 2424.4 2425.6 2397.3 2403.0 2419.6 2435.9 2440.5 1798.0 2416.3 2429.4 2431.0 2431.2 2369.3 2379.4 2386.2 2387.1 2388.1 2383.0 2387.8 2388.5 2389.2 2389.9 8921.6 8989.6 9037.4 9042.7 9048.9 5286.0 5452.3 5453.5 5467.3 5490.5 3554.4 3595.1 3604.4 3691.5 3751.7 945.9 1056.6 1090.5 1095.4 1111.4 11926.7 11992.9 11993.3 12020.1 12036.8 4730.6 4741.1 4797.4 4871.6 4880.2 10084.5 10088.3 10094.0 10097.3 10141.0 5462.7 5465.8 5470.4 5471.9 5478.1 4502.5 4610.8 4961.3 5008.2 5020.6 3417.5 3907.5 3916.5 4017.4 4077.9 1114.8 1296.5 1305.0 1307.7 1317.6 813.4 877.3 891.8 927.8 937.5 5642.0 5768.8 5971.8 5979.2 6105.1 374.4 426.9 433.4 443.9 446.9 3564.5 3912.7 4015.4 4035.4 4174.7 11009.7 11024.7 11149.3 11190.3 11238.6 10765.3 10948.6 11114.3 11173.9 11184.9 6905.3 8422.0 8430.7 8440.6 8467.6 5956.1 6019.3 6079.7 6159.6 6180.6 9840.3 10291.4 10297.4 10337.4 10345.5 10437.4 10461.7 10467.4 10472.2 10486.2 7023.5 7023.6 7432.5 7558.1 7725.5 8387.8 8616.6 8617.8 8620.4 8623.8 7405.1 7483.5 7570.0 7678.0 7707.5 7282.7 7396.6 7519.1 7562.1 7588.0 7018.2 7149.9 7152.7 7166.4 7171.7 4933.9 4993.6 5097.1 5115.9 5125.9 4808.5 4830.1 5014.1 5052.3 5159.0 08:26:27 INFO - PROCESS | 1666 | 08:26:27 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:4580.31 Median:5190.25 stddev:1428.55 (27.5%) stddev-sans-first:1396.04 08:26:27 INFO - PROCESS | 1666 | Values: 304.6 306.9 312.4 317.5 321.5 5315.3 5368.4 5421.9 5455.2 5483.9 4706.9 4852.1 5113.3 5127.2 5418.6 3970.6 4121.8 4133.1 4139.6 4186.8 3410.4 3628.1 4091.2 4127.2 4152.6 3280.7 3452.5 4024.0 4101.6 4109.1 3401.9 4067.6 4077.0 4083.9 4110.4 3138.3 3249.8 3382.4 3489.9 3491.3 5101.8 5190.8 5246.2 5247.3 5296.0 4121.3 4470.0 4660.8 4661.5 4693.7 5651.3 5665.1 5687.7 5688.6 5714.8 3837.7 3838.4 3855.5 3858.6 3878.7 5101.9 5528.7 5946.4 5969.7 5975.3 3294.3 3860.6 3890.3 3890.9 4001.5 3683.5 3947.8 3955.4 3957.5 3971.8 4501.4 4619.7 4653.6 4657.4 4665.8 5681.9 5687.3 5688.7 5726.6 5737.2 1155.5 1257.3 1262.9 1318.9 1349.8 697.5 845.5 850.8 882.5 912.9 5185.8 5189.6 5197.3 5214.2 5231.7 4837.5 5692.9 5740.7 5752.5 5755.2 4738.8 5087.0 5142.0 5177.6 5209.6 2627.3 2789.5 2812.5 2990.8 3001.9 4879.3 5031.1 5666.4 5937.5 6000.2 5106.7 5189.7 5557.6 5585.8 6006.8 5226.9 5370.9 5509.9 6095.7 6255.7 4868.9 5352.6 5490.8 5907.7 5933.1 5810.6 5821.6 5831.8 5835.0 5873.7 5769.4 5805.5 6086.4 6113.8 6166.4 3848.7 3913.1 3913.5 3929.9 3931.4 4957.4 5380.5 5427.3 5906.9 5923.4 5571.2 5689.5 5726.3 5757.6 5761.9 4859.7 5053.9 5711.6 5727.8 5773.2 5263.7 5514.0 5580.5 5599.7 5613.6 5282.3 5562.4 5634.6 5666.3 5725.6 5581.6 5593.4 5613.4 5615.4 5639.6 08:26:27 INFO - PROCESS | 1666 | 08:26:27 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:25918.75 Median:4355.30 stddev:43556.41 (1000.1%) stddev-sans-first:43637.39 08:26:27 INFO - PROCESS | 1666 | Values: 687.6 720.1 739.9 772.3 777.2 14653.8 16028.0 16041.4 16066.6 16107.3 12057.4 14587.6 14600.6 14600.8 14605.0 659.4 679.2 828.0 840.8 862.4 650.3 819.3 829.8 831.6 836.1 897.8 897.8 899.4 902.7 903.3 708.3 728.3 892.8 895.0 895.6 655.5 666.6 668.9 673.4 674.2 14457.9 14515.8 14519.3 14525.8 14533.5 13247.5 13610.7 13679.2 13683.9 13956.7 11985.0 12149.6 12345.5 12714.7 12890.2 4019.3 4360.8 4390.7 4405.8 4542.2 126334.0 129931.7 131813.3 132696.0 134092.0 10093.6 10557.4 10596.1 10638.8 10765.0 20966.4 21113.9 21165.5 21175.4 21178.4 7634.2 7634.4 7721.8 7723.1 7837.5 4166.1 4210.8 4239.6 4247.9 4378.8 3301.8 3405.3 3406.5 3407.0 3411.8 620.5 621.3 624.9 634.3 644.7 525.8 599.5 612.1 620.6 637.8 8470.2 8488.9 8552.6 8815.0 9001.6 526.3 532.3 611.9 616.1 625.8 4200.9 4336.4 4349.8 4442.7 4471.7 99799.0 145348.8 168075.2 169151.2 170691.0 136766.0 137837.0 141338.4 144185.0 153549.5 115479.0 116564.0 116727.4 118467.4 119526.0 8336.5 8356.1 8380.6 8517.2 8661.8 64627.4 64697.0 64870.0 67027.3 68541.7 85944.6 103054.0 104164.5 106801.9 107439.5 10777.1 10788.3 10882.6 10885.8 11319.3 11273.6 11649.4 11904.2 12195.0 12369.2 9167.9 9534.4 9620.4 9681.3 9797.3 11201.8 11222.8 11288.5 11311.9 11566.3 11021.5 11188.3 11288.4 11424.3 11625.4 9425.2 9532.2 9602.8 9834.6 9891.9 9403.6 9578.5 9588.3 9596.9 9607.7 08:26:27 INFO - PROCESS | 1666 | -------- Summary: end -------- 08:26:27 INFO - PROCESS | 1666 | 08:26:28 INFO - TEST-INFO | 1666: exit 0 08:26:28 INFO - TEST-OK | dromaeo_css | took 1117265ms 08:26:28 INFO - TEST-START | kraken 08:26:28 INFO - operating with platform_type : mac_ 08:26:28 INFO - Initialising browser for kraken test... 08:26:28 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmpc5454J/profile http://localhost:49272/getInfo.html) 08:26:30 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 08:26:30 INFO - PROCESS | 1672 | colorDepth:24 08:26:30 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 08:26:30 INFO - PROCESS | 1672 | __metrics 08:26:30 INFO - PROCESS | 1672 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:26:30 INFO - PROCESS | 1672 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:26:31 INFO - TEST-INFO | 1672: exit 0 08:26:31 INFO - Browser initialized. 08:26:31 INFO - Running cycle 1/1 for kraken test... 08:26:31 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmpc5454J/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:26:43 INFO - PROCESS | 1673 | RSS: Main: 232386560 08:26:43 INFO - PROCESS | 1673 | 08:26:45 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 08:26:46 INFO - PROCESS | 1673 | RSS: Main: 291438592 08:26:46 INFO - PROCESS | 1673 | 08:26:49 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 08:26:50 INFO - PROCESS | 1673 | RSS: Main: 287891456 08:26:50 INFO - PROCESS | 1673 | 08:26:53 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 08:26:54 INFO - PROCESS | 1673 | RSS: Main: 377454592 08:26:54 INFO - PROCESS | 1673 | 08:26:56 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 08:26:56 INFO - PROCESS | 1673 | RSS: Main: 277090304 08:26:56 INFO - PROCESS | 1673 | 08:26:58 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:26:59 INFO - PROCESS | 1673 | RSS: Main: 281014272 08:26:59 INFO - PROCESS | 1673 | 08:27:02 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 08:27:02 INFO - PROCESS | 1673 | RSS: Main: 322011136 08:27:02 INFO - PROCESS | 1673 | 08:27:05 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 08:27:06 INFO - PROCESS | 1673 | RSS: Main: 322867200 08:27:06 INFO - PROCESS | 1673 | 08:27:09 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 08:27:09 INFO - PROCESS | 1673 | RSS: Main: 321462272 08:27:09 INFO - PROCESS | 1673 | 08:27:11 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:27:11 INFO - PROCESS | 1673 | RSS: Main: 298364928 08:27:11 INFO - PROCESS | 1673 | 08:27:13 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:27:14 INFO - PROCESS | 1673 | RSS: Main: 303550464 08:27:14 INFO - PROCESS | 1673 | 08:27:16 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:27:17 INFO - PROCESS | 1673 | RSS: Main: 301973504 08:27:17 INFO - PROCESS | 1673 | 08:27:20 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:27:20 INFO - PROCESS | 1673 | RSS: Main: 312086528 08:27:20 INFO - PROCESS | 1673 | 08:27:23 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:27:24 INFO - PROCESS | 1673 | RSS: Main: 314200064 08:27:24 INFO - PROCESS | 1673 | 08:27:26 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 08:27:26 INFO - PROCESS | 1673 | RSS: Main: 358277120 08:27:26 INFO - PROCESS | 1673 | 08:27:26 INFO - PROCESS | 1673 | __start_tp_report 08:27:26 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 08:27:26 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 08:27:26 INFO - PROCESS | 1673 | |i|pagename|runs| 08:27:26 INFO - PROCESS | 1673 | |0;ai-astar;105;113;95;152;167;118;112;112;113;123 08:27:26 INFO - PROCESS | 1673 | |1;audio-beat-detection;145;115;132;161;109;132;128;106;162;106 08:27:26 INFO - PROCESS | 1673 | |2;audio-dft;187;185;185;184;183;184;181;184;184;184 08:27:26 INFO - PROCESS | 1673 | |3;audio-fft;77;71;74;152;57;57;57;63;139;57 08:27:26 INFO - PROCESS | 1673 | |4;audio-oscillator;82;82;134;71;71;88;120;68;69;117 08:27:26 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;88;87;134;85;87;83;88;85;87;84 08:27:26 INFO - PROCESS | 1673 | |6;imaging-darkroom;105;150;122;94;92;92;93;93;93;94 08:27:26 INFO - PROCESS | 1673 | |7;imaging-desaturate;86;88;85;84;86;86;86;84;85;86 08:27:26 INFO - PROCESS | 1673 | |8;json-parse-financial;54;55;56;55;55;54;53;54;53;55 08:27:26 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;64;64;75;76;71;71;69;70;70;71 08:27:26 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;85;85;85;86;86;84;85;87;87;85 08:27:26 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;139;139;141;145;148;151;137;139;222;135 08:27:26 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;167;173;229;203;169;165;169;167;167;167 08:27:26 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;68;69;69;69;67;67;66;67;65;67 08:27:26 INFO - PROCESS | 1673 | __end_tp_report 08:27:26 INFO - PROCESS | 1673 | __start_cc_report 08:27:26 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1953 08:27:26 INFO - PROCESS | 1673 | __end_cc_report 08:27:26 INFO - PROCESS | 1673 | __startTimestamp1473694046359__endTimestamp 08:27:26 INFO - PROCESS | 1673 | 08:27:26 INFO - PROCESS | 1673 | ------- Summary: start ------- 08:27:26 INFO - PROCESS | 1673 | Number of tests: 14 08:27:26 INFO - PROCESS | 1673 | 08:27:26 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:121.00 Median:120.50 stddev:21.89 (18.2%) stddev-sans-first:22.44 08:27:26 INFO - PROCESS | 1673 | Values: 105.0 113.0 95.0 152.0 167.0 118.0 112.0 112.0 113.0 123.0 08:27:26 INFO - PROCESS | 1673 | 08:27:26 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:129.60 Median:132.00 stddev:21.18 (16.0%) stddev-sans-first:21.72 08:27:26 INFO - PROCESS | 1673 | Values: 145.0 115.0 132.0 161.0 109.0 132.0 128.0 106.0 162.0 106.0 08:27:26 INFO - PROCESS | 1673 | 08:27:26 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:184.10 Median:184.00 stddev:1.52 (0.8%) stddev-sans-first:1.20 08:27:26 INFO - PROCESS | 1673 | Values: 187.0 185.0 185.0 184.0 183.0 184.0 181.0 184.0 184.0 184.0 08:27:26 INFO - PROCESS | 1673 | 08:27:26 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:80.40 Median:60.00 stddev:35.27 (58.8%) stddev-sans-first:37.39 08:27:26 INFO - PROCESS | 1673 | Values: 77.0 71.0 74.0 152.0 57.0 57.0 57.0 63.0 139.0 57.0 08:27:26 INFO - PROCESS | 1673 | 08:27:26 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:90.20 Median:71.00 stddev:24.37 (34.3%) stddev-sans-first:25.66 08:27:26 INFO - PROCESS | 1673 | Values: 82.0 82.0 134.0 71.0 71.0 88.0 120.0 68.0 69.0 117.0 08:27:26 INFO - PROCESS | 1673 | 08:27:26 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:90.80 Median:87.00 stddev:15.27 (17.6%) stddev-sans-first:16.17 08:27:26 INFO - PROCESS | 1673 | Values: 88.0 87.0 134.0 85.0 87.0 83.0 88.0 85.0 87.0 84.0 08:27:26 INFO - PROCESS | 1673 | 08:27:26 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:102.80 Median:93.00 stddev:19.08 (20.5%) stddev-sans-first:20.22 08:27:26 INFO - PROCESS | 1673 | Values: 105.0 150.0 122.0 94.0 92.0 92.0 93.0 93.0 93.0 94.0 08:27:26 INFO - PROCESS | 1673 | 08:27:26 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:85.60 Median:86.00 stddev:1.17 (1.4%) stddev-sans-first:1.24 08:27:26 INFO - PROCESS | 1673 | Values: 86.0 88.0 85.0 84.0 86.0 86.0 86.0 84.0 85.0 86.0 08:27:26 INFO - PROCESS | 1673 | 08:27:26 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:55.00 stddev:0.97 (1.8%) stddev-sans-first:1.01 08:27:26 INFO - PROCESS | 1673 | Values: 54.0 55.0 56.0 55.0 55.0 54.0 53.0 54.0 53.0 55.0 08:27:26 INFO - PROCESS | 1673 | 08:27:26 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:71.00 stddev:3.90 (5.5%) stddev-sans-first:3.46 08:27:26 INFO - PROCESS | 1673 | Values: 64.0 64.0 75.0 76.0 71.0 71.0 69.0 70.0 70.0 71.0 08:27:26 INFO - PROCESS | 1673 | 08:27:26 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:85.50 Median:85.50 stddev:0.97 (1.1%) stddev-sans-first:1.01 08:27:26 INFO - PROCESS | 1673 | Values: 85.0 85.0 85.0 86.0 86.0 84.0 85.0 87.0 87.0 85.0 08:27:26 INFO - PROCESS | 1673 | 08:27:26 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:149.60 Median:143.00 stddev:25.93 (18.1%) stddev-sans-first:27.22 08:27:26 INFO - PROCESS | 1673 | Values: 139.0 139.0 141.0 145.0 148.0 151.0 137.0 139.0 222.0 135.0 08:27:26 INFO - PROCESS | 1673 | 08:27:26 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.60 Median:169.00 stddev:21.25 (12.6%) stddev-sans-first:22.19 08:27:26 INFO - PROCESS | 1673 | Values: 167.0 173.0 229.0 203.0 169.0 165.0 169.0 167.0 167.0 167.0 08:27:26 INFO - PROCESS | 1673 | 08:27:26 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.50 stddev:1.35 (2.0%) stddev-sans-first:1.41 08:27:26 INFO - PROCESS | 1673 | Values: 68.0 69.0 69.0 69.0 67.0 67.0 66.0 67.0 65.0 67.0 08:27:26 INFO - PROCESS | 1673 | -------- Summary: end -------- 08:27:26 INFO - PROCESS | 1673 | 08:27:27 INFO - TEST-INFO | 1673: exit 0 08:27:27 INFO - TEST-OK | kraken | took 58855ms 08:27:27 INFO - SUITE-END | took 1176s 08:27:27 INFO - Completed test suite (00:19:36) 08:27:27 INFO - javascript benchmark 08:27:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3362.344342853143, 3367.3770352484794, 3392.88124915628, 3421.46967220081, 3460.5847298324206, 5620.662760234389, 5742.79900203493, 5767.452092051254, 5884.382139875315, 5924.377940316264, 5753.568482363829, 5802.825915222539, 5817.912731309034, 5894.292684877821, 5913.7338819752995, 5211.45279745627, 5213.635045546814, 5213.921791173136, 5271.683698978073, 5319.228711836781, 4465.821367145327, 5380.91928621071, 5392.164214546732, 5392.4337944515655, 5451.536619387337, 5185.870353241199, 5309.893802123975, 5312.2828418163435, 5344.599155063386, 5370.006548788467, 5215.269862219292, 5279.841604751864, 5286.233496143038, 5291.814786482493, 5297.020051290524, 5197.870053248661, 5214.843554693365, 5218.712970786605, 5225.843224703265, 5249.947500524984, 5776.306843178803, 5809.302883653989, 5833.679147646878, 5835.241418615596, 5855.151003104568, 6169.7532098716, 6259.217597800275, 6264.3422440643535, 6277.058441233806, 6285.748570057192, 6177.66022868742, 6200.131981522591, 6243.7814676486105, 6256.78101266458, 6273.686315684197, 5739.598228124033, 5793.855153621151, 5802.0426629605645, 5807.157962095473, 5837.6497410155525, 6324.272708638474, 6340.175777148941, 6354.046892965999, 6358.650274234869, 6363.300036995926, 6186.566940314135, 6208.410201030895, 6209.2548894133, 6217.595856269328, 6221.782237621662, 6105.732692890381, 6149.0, 6212.782552610637, 6236.594621349598, 6248.4688801452385, 6282.246130464282, 6284.497240220771, 6290.874182516415, 6290.968545157337, 6304.873902521924, 6298.0, 6316.905246421307, 6320.62076275425, 6328.71520781566, 6336.20797400325, 4942.481039490874, 4943.728094954812, 4968.056069346812, 4983.252512123138, 4994.700317980933, 4239.364095385717, 4454.977725111354, 4484.0583477469445, 4526.388937493462, 4545.5908968193025, 5673.3475650299915, 5736.799212027559, 5750.913736293959, 5753.568482363839, 5807.245058142415, 5193.948060519347, 6153.753849846045, 6332.4934005279465, 6355.713992870332, 6360.7137678804565, 5696.800611978644, 5714.399988001283, 5718.942810571924, 5777.364489906107, 5810.12848072784, 5460.5085542301385, 5493.725313734298, 5512.035393806068, 5517.862053448615, 5528.723563821793, 6226.283977342664, 6421.197350331208, 6422.0, 6426.550141490051, 6448.29068802422, 6221.253449586078, 6241.375862413723, 6272.780452684134, 6282.120503129474, 6345.777897773556, 6407.10300557913, 6417.8716425672155, 6422.614643121427, 6423.871522569616, 6458.38645328693, 6211.130441738251, 6279.434850863444, 6287.811365659038, 6288.748450061947, 6290.46531044848, 6035.1249068885645, 6036.245469316335, 6238.282597501346, 6244.219472565929, 6270.874582508415, 6217.098520714561, 6219.222597175353, 6224.782132625336, 6231.345708700521, 6241.687915604292, 6021.036634138517, 6147.415995480422, 6209.503239740719, 6210.378962103753, 6217.813465596039, 5875.6474611521735, 5890.410958904075, 5896.528277737685, 5962.37395073511, 5990.101484777144, 6122.571420000735, 6166.352532984151, 6213.285472170577, 6222.81331560054, 6243.188385509855, 5838.766449341979, 6043.00290452088, 6045.0, 6060.4550821828825, 6118.082287656887], "value": 5754.81300662793, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [484.76488902882096, 498.860319110649, 528.9523942845145, 536.691402443595, 555.5971920357739, 29763.0, 38402.0, 39270.0, 39296.41055384168, 39389.212215755666, 41075.38386924199, 41495.17009659805, 41609.37585936213, 41618.58381416185, 41706.58293417065, 8615.827683446327, 9654.082862128087, 9836.458994755285, 9841.90158098417, 9851.802963940718, 8568.0, 11761.176717629769, 11807.468663910102, 11812.881871181307, 12099.213551119194, 11920.7615847683, 12007.519699212022, 12022.819657705142, 12035.097367697414, 12068.758624827498, 11971.162018658697, 12037.0, 12040.518379264819, 12067.939660301643, 12108.455119519598, 11747.060235181165, 11846.289222646583, 11854.05167586591, 11855.881441185564, 11866.050715942703, 39121.80439097815, 39319.21361572752, 39329.213415731814, 39363.0, 39398.40902386467, 3296.225387034045, 3336.4494858348344, 3613.4218525035867, 3910.980445097785, 3990.940135897964, 29912.0, 30643.387132257452, 30681.232969175726, 30700.078997630106, 31287.530687039714, 3188.075458117143, 3201.2317043909525, 3222.661620529834, 3227.3222623249144, 3335.5997280326515, 380377.0, 382966.0, 383393.0, 383456.0, 390813.0, 32405.0, 33115.66884331174, 56845.43154568484, 57049.0, 57146.714266428404, 34246.14384640404, 34465.483017754756, 34493.310133797684, 34501.0, 34630.480542791876, 14403.567892963229, 14512.201828899511, 14525.346359413852, 14532.63668408277, 14541.27293635314, 11141.164412669083, 11348.716282093013, 12389.814152787716, 12515.499380024698, 12522.872941435315, 6482.57863238888, 7463.9626801866725, 7609.619519024027, 7658.387329013665, 7721.691132354756, 2786.651668541432, 3236.7410607151373, 3247.2206670399405, 3283.5074738788894, 3284.917877053045, 918.3938600523622, 939.6570251858159, 940.3370623710268, 990.9207263418909, 1008.6419321141036, 27150.5927411089, 29387.971421000162, 29453.55819662707, 30191.0, 30324.393512129634, 1069.9625513107003, 1100.8073587122383, 1123.528118190362, 1150.3558007515817, 1210.7578484303174, 12709.428075736614, 14245.430182792572, 15166.848331516765, 15225.390984360502, 15502.224888755518, 701014.0, 707887.0, 709293.0, 709724.0, 711335.7631753919, 54876.0, 55473.72263138658, 55552.0, 55657.72171139118, 55659.443405566235, 1232.5439587352557, 1252.630474010169, 1360.9455621775019, 1371.35546293242, 1387.0, 29130.708692912798, 30681.079567613007, 30706.539401908543, 30928.226794330323, 31411.52882706807, 45867.77066114648, 46485.0, 46490.90790914349, 46588.53411465978, 46827.29759053685, 357068.0, 357276.0, 357899.0, 358047.0, 359557.0, 25283.494330113663, 25960.09139680102, 26569.867150664122, 26597.468050639265, 27207.72792272131, 45076.549234507234, 45157.54842451665, 45807.85480363017, 46272.0, 46413.0, 31066.53400199044, 36668.44997324989, 36996.26007479889, 37766.811165944, 37992.620073798906, 35902.820485897406, 36256.281032400264, 36649.08377290589, 36789.81605091957, 37350.06624834292, 35460.0, 35748.0, 36521.63478365289, 36555.81722091479, 36724.0, 31813.5227971576, 33226.833865830515, 33251.33497329991, 33527.497087544194, 33539.83230083834, 23543.76456235484, 24208.75791242065, 24438.633420499064, 24967.500649986534, 25025.749742502343], "value": 18340.34174524576, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.66686647684696, 353.2793102071774, 373.55173791450267, 379.3248018527022, 402.27791114948667, 15273.0, 15446.613834654125, 16321.347146114142, 20727.963601819887, 21083.68374474384, 19607.70588441171, 20483.0, 20519.794802051976, 20703.585928281424, 20833.166673333086, 5312.415634280226, 5936.198613187229, 6221.408966148231, 6229.408206220402, 6229.719662615194, 5881.147233651118, 6228.501719862423, 6252.781152659658, 6253.530985176108, 6254.968725156368, 3938.4683067785913, 4006.699497537682, 4146.419501269825, 4146.771927543984, 4499.572540608653, 4467.910641787179, 6879.346462086119, 6892.793216203472, 6899.344562266577, 6899.965500172517, 5846.941530584682, 5986.161937328778, 6045.909311360333, 6052.788152414644, 6087.147799308101, 12873.163244389085, 13038.0, 13101.672458188526, 17455.0, 24960.375990600198, 2130.659094544865, 2151.0427859602482, 2315.8610483371053, 2491.4145175883655, 2637.8261673555276, 10326.380417174918, 10416.843747343795, 11738.002269807077, 12365.072619553479, 14801.11193328393, 1787.4101546489758, 1844.7325137854937, 1890.9810901890942, 1920.0591710062108, 1951.8581629746616, 112151.87848121495, 121943.0, 123400.9999999991, 126506.36746816208, 131083.0, 9247.306452016126, 9715.951420242853, 9723.319367644197, 9843.803123937625, 11299.039581635489, 12300.384980750927, 12389.946854517286, 12394.938025309995, 12395.318257495745, 12405.503779848885, 7630.351420129351, 7751.3411360035025, 7781.9610901945125, 7842.882356764653, 7852.332551733052, 7915.722949696733, 7929.207079292025, 7980.082290536547, 8087.797805054921, 8101.878471822927, 3182.4908014717535, 3815.1797363566966, 3825.84696612138, 3859.671927886161, 3922.6077392260545, 1624.3746157729122, 1867.2624313396132, 1919.1172060851886, 1927.4506765572025, 1930.9806901930801, 776.7087342246657, 904.7828521154876, 946.2619157057507, 958.4441024205944, 990.6285143071349, 20695.79304206939, 21535.569288614453, 21556.89221553882, 21558.353249402542, 21598.920053997237, 893.6336102198068, 895.6686026170229, 900.7793090692691, 928.5682157796522, 934.0566028311318, 10622.521986510626, 11341.489632966537, 11358.60244891425, 11360.943195283971, 11430.25703329281, 598013.0, 599346.0, 602672.0, 616818.0, 617836.0, 92968.0, 111913.76172476505, 113459.43270283761, 125878.0, 133726.95866934027, 865.6191275838611, 887.729242581019, 949.1647350331664, 972.1687956796978, 973.5910917414703, 19226.134823932676, 19331.806681933, 19617.92101434435, 19638.509037274183, 19683.901580492005, 56547.0, 56914.0, 57074.85850282888, 57522.13716794335, 57832.71083644555, 78599.82100268386, 79920.0, 80741.59629201816, 90046.0, 92044.0, 7108.857822843411, 7297.489175757646, 7332.81667958284, 7434.7769566913075, 7909.288164065031, 8890.444172269956, 12556.87443125557, 12960.0, 12977.545785897448, 13079.215247085205, 9321.953390233006, 10788.892111078789, 10882.347059176158, 11227.157963152633, 11361.091112710797, 9524.619015239312, 10931.01620854127, 11005.779884402427, 11312.925272099137, 11348.602798901999, 11126.05428538551, 11255.908777470142, 11477.598284060017, 11585.768284634429, 11774.587889423829, 6085.543584231112, 7168.8924666129005, 7327.63361831917, 7507.024086868672, 7512.098548174254, 7998.48009879356, 9745.171660408945, 11130.220884538005, 11297.661070167907, 11308.49111789955], "value": 9189.967112425446, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1504.112573581587, 1548.2104126895283, 1739.512936377814, 1881.6142690748397, 1909.780375256845, 7806.180351063142, 8607.13928607139, 9025.593848276827, 9044.5929933153, 9078.773030674236, 7990.520568765878, 8170.264676179143, 8196.180381961802, 8725.43284686495, 8851.070637583058, 2389.2593296078185, 2420.6611074449593, 2422.1401402502124, 2424.4302588891596, 2425.5876500994873, 2397.30478161333, 2402.9759702402926, 2419.6128619420897, 2435.9391015224583, 2440.5240978009297, 1797.9910100449545, 2416.2871952773976, 2429.4047958250253, 2431.0397393029743, 2431.173401043645, 2369.2655276864225, 2379.4051487128218, 2386.2244770449674, 2387.069043073203, 2388.128333158393, 2383.0, 2387.820913431482, 2388.534235824015, 2389.2115601851347, 2389.928302150938, 8921.643134274622, 8989.595468203881, 9037.367384283103, 9042.728718138467, 9048.864267036, 5285.973570132125, 5452.345718513764, 5453.454654534555, 5467.261919640838, 5490.533304669108, 3554.449060395617, 3595.0832537702718, 3604.441311596707, 3691.483192353073, 3751.737378383514, 945.9243260539209, 1056.5863910521277, 1090.487470888681, 1095.3811096730321, 1111.372074777748, 11926.701832454171, 11992.940035299767, 11993.340366279852, 12020.09849261309, 12036.81944770838, 4730.5813770284985, 4741.122892264942, 4797.352357431702, 4871.610271178368, 4880.243562247857, 10084.546195421224, 10088.344257623232, 10094.0, 10097.343672661253, 10141.036601522843, 5462.7268636568015, 5465.808696695596, 5470.398256191816, 5471.863203419866, 5478.123500240023, 4502.527234640381, 4610.7694615269775, 4961.305417241589, 5008.223725322581, 5020.5732512736095, 3417.504461853067, 3907.531096268466, 3916.4516967624554, 4017.397390391406, 4077.898052548651, 1114.849495318138, 1296.5332480307081, 1304.9804252936215, 1307.66654503103, 1317.5915466205506, 813.3818298093491, 877.3069275272591, 891.808261223827, 927.800522887569, 937.4843835890233, 5642.0, 5768.798092066758, 5971.820845374559, 5979.222701048923, 6105.084237364431, 374.43647310797076, 426.86767102198417, 433.38892162051354, 443.8513098112104, 446.87040758180416, 3564.465330200491, 3912.68698504119, 4015.437838702584, 4035.3745169498775, 4174.666026717916, 11009.669709908716, 11024.669259922215, 11149.331040137618, 11190.32858028521, 11238.550457981753, 10765.30025548337, 10948.616798412017, 11114.277571957797, 11173.938475844781, 11184.944075279735, 6905.343992320822, 8422.0, 8430.704925327584, 8440.62017209227, 8467.618957147066, 5956.136360227656, 6019.307779605227, 6079.696015199134, 6159.568830182024, 6180.6291622502795, 9840.261980351359, 10291.433971131659, 10297.3821570706, 10337.37975721459, 10345.482725863827, 10437.373757574569, 10461.738456538647, 10467.371957682564, 10472.21458390639, 10486.16110711126, 7023.543469674455, 7023.578585284899, 7432.479726419098, 7558.13081495635, 7725.49784264021, 8387.83224335522, 8616.612252448529, 8617.784555386166, 8620.353473489638, 8623.784405389664, 7405.074365704287, 7483.476156669199, 7570.0, 7678.040244969379, 7707.499012564165, 7282.708691652275, 7396.593187374746, 7519.097708275042, 7562.0925488941675, 7587.962060189664, 7018.192907815666, 7149.9285007149265, 7152.713891444284, 7166.355028047501, 7171.677274522764, 4933.876653083558, 4993.5755460784785, 5097.08252514551, 5115.87210319745, 5125.897482050413, 4808.519148085164, 4830.106430310404, 5014.0974624567925, 5052.292679025013, 5158.97420512895], "value": 4552.675445681328, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.5599109287081, 306.94168108059466, 312.403309678514, 317.49835260288745, 321.53056537455313, 5315.309009828722, 5368.409474957752, 5421.945780542198, 5455.1544510600825, 5483.890322193553, 4706.882327941803, 4852.078105160017, 5113.309703190068, 5127.205283181103, 5418.593605479585, 3970.6227908348806, 4121.752694838305, 4133.0700592366775, 4139.586041395852, 4186.79066046698, 3410.420228561138, 3628.1473853644375, 4091.24312002279, 4127.215828992496, 4152.626263636272, 3280.6555311692287, 3452.5339079224236, 4024.0282261691855, 4101.610347017044, 4109.075458021951, 3401.9489707654407, 4067.633912947819, 4077.0, 4083.8774836754797, 4110.362893751459, 3138.2938838761097, 3249.821259830708, 3382.374260761767, 3489.91275218119, 3491.2768040927863, 5101.821436249714, 5190.766415511311, 5246.186841039645, 5247.344081989751, 5295.973520132375, 4121.319982202933, 4469.955300446986, 4660.836870709509, 4661.533846615345, 4693.694909830884, 5651.32184137902, 5665.082482521505, 5687.7156142193135, 5688.60179787415, 5714.771409143629, 3837.6737977271964, 3838.4434257032854, 3855.4939289461795, 3858.6334298241622, 3878.70909681775, 5101.923471147936, 5528.723563821793, 5946.375630558816, 5969.701664859061, 5975.312839023481, 3294.331591410965, 3860.613938606117, 3890.2997460456822, 3890.9416358754643, 4001.519817621904, 3683.4843121962945, 3947.822347994347, 3955.4066889966734, 3957.465742124776, 3971.801409929492, 4501.414816073955, 4619.69971951822, 4653.60444362233, 4657.394538710014, 4665.790039448233, 5681.920435117315, 5687.2701103469735, 5688.658680479184, 5726.627769194989, 5737.225474560881, 1155.4973586489905, 1257.3273298785107, 1262.8926541243914, 1318.905308593865, 1349.8245228120281, 697.4629535257823, 845.4631309118631, 850.8173638642293, 882.4881568690273, 912.885889263842, 5185.844424667266, 5189.584833213334, 5197.324347834758, 5214.191800270964, 5231.659942103751, 4837.540433658797, 5692.8861422771315, 5740.712964351849, 5752.539796816244, 5755.165501002331, 4738.8341408050555, 5087.033463641897, 5141.974290128525, 5177.611679124081, 5209.609279304067, 2627.3431642089477, 2789.4700006998605, 2812.479691257124, 2990.8355040472984, 3001.8799248029836, 4879.316895634614, 5031.119554078022, 5666.433356664301, 5937.495312898365, 6000.249968753906, 5106.693598384109, 5189.740512974412, 5557.610597350662, 5585.804496842591, 6006.759729610767, 5226.902350506436, 5370.892582148335, 5509.898020396017, 6095.744574507461, 6255.6872156392, 4868.878278043077, 5352.571794256528, 5490.80782172638, 5907.675077870586, 5933.122435618808, 5810.593258471867, 5821.621594596507, 5831.825045248649, 5835.045259837832, 5873.676947767914, 5769.384572319757, 5805.477507024388, 6086.360932102066, 6113.84715382119, 6166.445019948226, 3848.7113466490687, 3913.1195481016543, 3913.471681323099, 3929.9017524562114, 3931.3906344516645, 4957.380327459067, 5380.515753582196, 5427.3215848019, 5906.911396328973, 5923.378045305353, 5571.229882773661, 5689.487946084872, 5726.341470730753, 5757.625754325955, 5761.913571296518, 4859.684120532154, 5053.8736531587, 5711.600187986801, 5727.828165155052, 5773.16289138081, 5263.736813159403, 5514.007478653881, 5580.525655319343, 5599.720013999202, 5613.635113717595, 5282.339707536558, 5562.3881373048125, 5634.605577609692, 5666.291713535808, 5725.599208055396, 5581.6371935824045, 5593.440655934374, 5613.438656134354, 5615.354234262949, 5639.605227634191], "value": 4116.451767996735, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [687.5702685821361, 720.0818955831318, 739.9075115610547, 772.3435080181843, 777.1839568453123, 14653.780193297109, 16028.038317700946, 16041.358345666189, 16066.598335041615, 16107.275172617232, 12057.397130143501, 14587.635309117251, 14600.634984125401, 14600.780988285183, 14604.977651564395, 659.3735950846715, 679.2155060904663, 827.9917200828005, 840.7519781664394, 862.4092496639822, 650.2684479960045, 819.2831272636444, 829.8174401631632, 831.6423937706784, 836.0803116571783, 897.7800438892481, 897.8428774964387, 899.4468401932829, 902.7111324376202, 903.3089686389884, 708.3341658840674, 728.2862794461457, 892.7768057985536, 894.9955250223773, 895.6372669069004, 655.4756195043993, 666.6100331306192, 668.9431398331147, 673.4444083631024, 674.1741366825648, 14457.927710361486, 14515.782263266059, 14519.346629401702, 14525.78211326831, 14533.491327803582, 13247.470101196037, 13610.727785444335, 13679.179249245077, 13683.931580342034, 13956.651083722887, 11985.0, 12149.574764883275, 12345.5061797528, 12714.682132946751, 12890.162139460905, 4019.2765302245584, 4360.803763830635, 4390.670699697504, 4405.845795397177, 4542.227821270392, 126334.0, 129931.7006829929, 131813.34093329473, 132696.0, 134092.0, 10093.596256149818, 10557.366558006544, 10596.099331556905, 10638.840417393745, 10765.0, 20966.37100886976, 21113.894430528057, 21165.470863228235, 21175.36473905785, 21178.364649060553, 7634.160242373335, 7634.351080158249, 7721.806954826062, 7723.073231212291, 7837.529748215125, 4166.0834616384345, 4210.7684077375425, 4239.639630631369, 4247.915041699149, 4378.759168245777, 3301.8018918864946, 3405.267867408519, 3406.5060566217758, 3406.982965085159, 3411.846466908995, 620.5283984172062, 621.273110460762, 624.8750249950028, 634.2642534659773, 644.7292137302344, 525.8317338451659, 599.484443378694, 612.1368869893429, 620.5532016947791, 637.7672149665339, 8470.15298470148, 8488.915110848937, 8552.61513231906, 8815.0, 9001.639934402681, 526.3447008474398, 532.2973674749371, 611.9326874043852, 616.0728104203146, 625.8028720952885, 4200.894977625584, 4336.414584031116, 4349.804258808361, 4442.669595167934, 4471.731696098244, 99799.0, 145348.81976770356, 168075.1596242011, 169151.154244228, 170691.0, 136766.0, 137837.0, 141338.43174157434, 144185.0, 153549.46450535575, 115479.0, 116564.0, 116727.41636291765, 118467.40766296114, 119526.0, 8336.499430102398, 8356.080831108451, 8380.622871970654, 8517.23344898962, 8661.783455413664, 64627.35372646401, 64697.0, 64870.0, 67027.32972670211, 68541.65729171323, 85944.57027714822, 103054.0, 104164.47917760363, 106801.92183664476, 107439.46280268549, 10777.059661212605, 10788.298760580537, 10882.551425929103, 10885.782284354427, 11319.264247823865, 11273.605423810128, 11649.359285239054, 11904.239838937989, 12195.0, 12369.196002259825, 9167.862482062908, 9534.380265282734, 9620.37467564606, 9681.273904457054, 9797.265205109788, 11201.775964480828, 11222.831657525307, 11288.468650361428, 11311.943440282746, 11566.30602163873, 11021.504032318726, 11188.328700278009, 11288.435578220891, 11424.257423267461, 11625.418729063344, 9425.245980321695, 9532.23742100644, 9602.759931001781, 9834.60661573529, 9891.851622225817, 9403.623855045722, 9578.473183974947, 9588.328816982745, 9596.856047159437, 9607.663731769355], "value": 7489.642453366277, "unit": "score"}], "name": "dromaeo_css", "value": 7172.560667927958, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 113.0, 95.0, 152.0, 167.0, 118.0, 112.0, 112.0, 113.0, 123.0], "value": 121.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 115.0, 132.0, 161.0, 109.0, 132.0, 128.0, 106.0, 162.0, 106.0], "value": 129.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 185.0, 185.0, 184.0, 183.0, 184.0, 181.0, 184.0, 184.0, 184.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 71.0, 74.0, 152.0, 57.0, 57.0, 57.0, 63.0, 139.0, 57.0], "value": 80.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 134.0, 71.0, 71.0, 88.0, 120.0, 68.0, 69.0, 117.0], "value": 90.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 134.0, 85.0, 87.0, 83.0, 88.0, 85.0, 87.0, 84.0], "value": 90.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 150.0, 122.0, 94.0, 92.0, 92.0, 93.0, 93.0, 93.0, 94.0], "value": 102.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 85.0, 84.0, 86.0, 86.0, 86.0, 84.0, 85.0, 86.0], "value": 85.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 56.0, 55.0, 55.0, 54.0, 53.0, 54.0, 53.0, 55.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 75.0, 76.0, 71.0, 71.0, 69.0, 70.0, 70.0, 71.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 86.0, 86.0, 84.0, 85.0, 87.0, 87.0, 85.0], "value": 85.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 141.0, 145.0, 148.0, 151.0, 137.0, 139.0, 222.0, 135.0], "value": 149.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 173.0, 229.0, 203.0, 169.0, 165.0, 169.0, 167.0, 167.0, 167.0], "value": 177.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 69.0, 69.0, 67.0, 67.0, 66.0, 67.0, 65.0, 67.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1489.1, "alertThreshold": 2.0}]} 08:27:27 INFO - Return code: 0 08:27:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:27:27 INFO - # TBPL SUCCESS # 08:27:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:27:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:27:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:27:27 INFO - 2016-09-12 08:27:27.682 screenresolution[1677:11622] starting screenresolution argv=screenresolution get 08:27:27 INFO - 2016-09-12 08:27:27.684 screenresolution[1677:11622] Display 0: 1600x1200x32@60 08:27:27 INFO - 2016-09-12 08:27:27.690 screenresolution[1678:11625] starting screenresolution argv=screenresolution list 08:27:27 INFO - Available Modes on Display 0 08:27:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:27:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:27:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:27:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:27:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:27:27 INFO - Intel Iris: 08:27:27 INFO - Chipset Model: Intel Iris 08:27:27 INFO - Type: GPU 08:27:27 INFO - Bus: Built-In 08:27:27 INFO - VRAM (Dynamic, Max): 1536 MB 08:27:27 INFO - Vendor: Intel (0x8086) 08:27:27 INFO - Device ID: 0x0a2e 08:27:27 INFO - Revision ID: 0x0009 08:27:27 INFO - Displays: 08:27:27 INFO - Display: 08:27:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:27:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:27:27 INFO - Display Serial Number: 1600x1200 60 08:27:27 INFO - Main Display: Yes 08:27:27 INFO - Mirror: Off 08:27:27 INFO - Online: Yes 08:27:27 INFO - Rotation: Supported 08:27:27 INFO - Return code: 0 08:27:27 INFO - Running post-action listener: _resource_record_post_action 08:27:27 INFO - [mozharness: 2016-09-12 15:27:27.925330Z] Finished run-tests step (success) 08:27:27 INFO - Running post-run listener: _resource_record_post_run 08:27:27 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 31943680; Write bytes: 707848704; Read time: 196; Write time: 2003 08:27:27 INFO - TinderboxPrint: CPU usage
53.5% 08:27:27 INFO - TinderboxPrint: I/O read bytes / time
31,943,680 / 196 08:27:27 INFO - TinderboxPrint: I/O write bytes / time
707,848,704 / 2,003 08:27:27 INFO - TinderboxPrint: CPU idle
2,255.4 (46.5%) 08:27:27 INFO - TinderboxPrint: CPU system
966.0 (19.9%) 08:27:27 INFO - TinderboxPrint: CPU user
1,624.4 (33.5%) 08:27:27 INFO - TinderboxPrint: Swap in / out
433,528,832 / 0 08:27:27 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 402858496; Write bytes: 394663936; Read time: 29768; Write time: 847 08:27:28 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 29854720; Write bytes: 296647680; Read time: 175; Write time: 1109 08:27:28 INFO - Running post-run listener: _upload_blobber_files 08:27:28 INFO - Blob upload gear active. 08:27:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:27:28 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 08:27:28 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:27:28 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:27:28 INFO - (blobuploader) - INFO - Open directory for files ... 08:27:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:27:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:27:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:27:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:27:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:27:29 INFO - (blobuploader) - INFO - Done attempting. 08:27:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:27:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:27:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:27:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:27:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:27:30 INFO - (blobuploader) - INFO - Done attempting. 08:27:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:27:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:27:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:27:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:27:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:27:33 INFO - (blobuploader) - INFO - Done attempting. 08:27:33 INFO - (blobuploader) - INFO - Iteration through files over. 08:27:33 INFO - Return code: 0 08:27:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:27:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:27:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4870bb29736cf86e2b3802387291437341813431b46584d7516e880f8374e90e626af72c973a12f0196c69736dffcc25bc5bcc59faaece324c22c9e072ff130f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/809086f9baf9aff00c3548d618ac5c14932a54a1f294e851584a2640293c2d9be7680f76cde35f12ff7f42b1185c9f2ebc56d5e433c1fdd787b96b3036224db5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/030684bab061f8815ce31770c7b1c6741d12e9e72772620cd2f57419a1b24b1f5f473c9605cd07a69d785496186d73658f7b15c17cfd5798f3ab2a900876d35e"} 08:27:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:27:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:27:33 INFO - Contents: 08:27:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4870bb29736cf86e2b3802387291437341813431b46584d7516e880f8374e90e626af72c973a12f0196c69736dffcc25bc5bcc59faaece324c22c9e072ff130f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/809086f9baf9aff00c3548d618ac5c14932a54a1f294e851584a2640293c2d9be7680f76cde35f12ff7f42b1185c9f2ebc56d5e433c1fdd787b96b3036224db5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/030684bab061f8815ce31770c7b1c6741d12e9e72772620cd2f57419a1b24b1f5f473c9605cd07a69d785496186d73658f7b15c17cfd5798f3ab2a900876d35e"} 08:27:33 INFO - Running post-run listener: copy_logs_to_upload_dir 08:27:33 INFO - Copying logs to upload dir... 08:27:33 INFO - mkdir: /builds/slave/test/build/upload/logs 08:27:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.355011 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-12 08:27:33.618455) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 08:27:33.622020) ========= 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.schOEClg87/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.yItXNKBddG/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4870bb29736cf86e2b3802387291437341813431b46584d7516e880f8374e90e626af72c973a12f0196c69736dffcc25bc5bcc59faaece324c22c9e072ff130f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/809086f9baf9aff00c3548d618ac5c14932a54a1f294e851584a2640293c2d9be7680f76cde35f12ff7f42b1185c9f2ebc56d5e433c1fdd787b96b3036224db5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/030684bab061f8815ce31770c7b1c6741d12e9e72772620cd2f57419a1b24b1f5f473c9605cd07a69d785496186d73658f7b15c17cfd5798f3ab2a900876d35e"} build_url:https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010993 build_url: 'https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4870bb29736cf86e2b3802387291437341813431b46584d7516e880f8374e90e626af72c973a12f0196c69736dffcc25bc5bcc59faaece324c22c9e072ff130f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/809086f9baf9aff00c3548d618ac5c14932a54a1f294e851584a2640293c2d9be7680f76cde35f12ff7f42b1185c9f2ebc56d5e433c1fdd787b96b3036224db5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/030684bab061f8815ce31770c7b1c6741d12e9e72772620cd2f57419a1b24b1f5f473c9605cd07a69d785496186d73658f7b15c17cfd5798f3ab2a900876d35e"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 08:27:33.660298) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 08:27:33.661115) ========= 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.schOEClg87/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.yItXNKBddG/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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.005157 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 08:27:33.714609) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 08:27:33.715332) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 08:27:33.716102) ========= ========= Total master_lag: 0.34 =========