builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0216 starttime: 1473752620.38 results: success (0) buildid: 20160912213805 builduid: e51bc8c7d06d40e7a54698267eb94c90 revision: 4850895eb2fbee98b74a885ea00be1cadc352313 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-13 00:43:40.375888) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-13 00:43:40.376313) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-13 00:43:40.376623) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-13 00:43:40.428951) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 00:43:40.429214) ========= 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.eQbOWuzbJP/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.w7AUWSR3Tp/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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-13 00:43:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 163M=0s 2016-09-13 00:43:40 (163 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.446111 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 00:43:40.897222) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 00:43:40.897533) ========= 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.eQbOWuzbJP/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.w7AUWSR3Tp/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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.021008 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 00:43:40.947079) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-13 00:43:40.947398) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 4850895eb2fbee98b74a885ea00be1cadc352313 --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 4850895eb2fbee98b74a885ea00be1cadc352313 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eQbOWuzbJP/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.w7AUWSR3Tp/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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-13 00:43:41,034 truncating revision to first 12 chars 2016-09-13 00:43:41,034 Setting DEBUG logging. 2016-09-13 00:43:41,034 attempt 1/10 2016-09-13 00:43:41,035 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/4850895eb2fb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-13 00:43:42,024 unpacking tar archive at: fx-team-4850895eb2fb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.246982 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-13 00:43:42.216397) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-13 00:43:42.216753) ========= script_repo_revision: 4850895eb2fbee98b74a885ea00be1cadc352313 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-13 00:43:42.217149) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-13 00:43:42.217413) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-13 00:43:42.238573) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-13 00:43:42.239686) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.eQbOWuzbJP/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.w7AUWSR3Tp/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 00:43:42 INFO - MultiFileLogger online at 20160913 00:43:42 in /builds/slave/test 00:43:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 00:43:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 00:43:42 INFO - {'append_to_log': False, 00:43:42 INFO - 'base_work_dir': '/builds/slave/test', 00:43:42 INFO - 'blob_upload_branch': 'Fx-Team', 00:43:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 00:43:42 INFO - 'branch': 'Fx-Team', 00:43:42 INFO - 'buildbot_json_path': 'buildprops.json', 00:43:42 INFO - 'config_files': ('talos/mac_config.py',), 00:43:42 INFO - 'default_actions': ('clobber', 00:43:42 INFO - 'read-buildbot-config', 00:43:42 INFO - 'download-and-extract', 00:43:42 INFO - 'populate-webroot', 00:43:42 INFO - 'create-virtualenv', 00:43:42 INFO - 'install', 00:43:42 INFO - 'run-tests'), 00:43:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:43:42 INFO - 'download_minidump_stackwalk': True, 00:43:42 INFO - 'download_symbols': 'ondemand', 00:43:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:43:42 INFO - 'tooltool.py': '/tools/tooltool.py', 00:43:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:43:42 INFO - '/tools/misc-python/virtualenv.py')}, 00:43:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:43:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:43:42 INFO - 'installer_path': 'installer.exe', 00:43:42 INFO - 'log_level': 'info', 00:43:42 INFO - 'log_name': 'talos', 00:43:42 INFO - 'log_to_console': True, 00:43:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 00:43:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 00:43:42 INFO - 'opt_config_files': (), 00:43:42 INFO - 'pip_index': False, 00:43:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:43:42 INFO - 'cmd': ('bash', 00:43:42 INFO - '-c', 00:43:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:43:42 INFO - 'enabled': True, 00:43:42 INFO - 'halt_on_failure': False, 00:43:42 INFO - 'name': 'check_screen_resolution'},), 00:43:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:43:42 INFO - 'cmd': ('bash', 00:43:42 INFO - '-c', 00:43:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:43:42 INFO - 'enabled': True, 00:43:42 INFO - 'halt_on_failure': False, 00:43:42 INFO - 'name': 'check_screen_resolution'},), 00:43:42 INFO - 'run_cmd_checks_enabled': True, 00:43:42 INFO - 'sps_profile': False, 00:43:42 INFO - 'sps_profile_interval': 0, 00:43:42 INFO - 'suite': 'dromaeojs-e10s', 00:43:42 INFO - 'system_bits': '32', 00:43:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:43:42 INFO - 'title': 't-yosemite-r7-0216', 00:43:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:43:42 INFO - 'use_talos_json': True, 00:43:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 00:43:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:43:42 INFO - 'work_dir': 'build'} 00:43:42 INFO - [mozharness: 2016-09-13 07:43:42.441207Z] Running clobber step. 00:43:42 INFO - Running pre-action listener: _resource_record_pre_action 00:43:42 INFO - Running main action method: clobber 00:43:42 INFO - rmtree: /builds/slave/test/build 00:43:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 00:43:43 INFO - Running post-action listener: _resource_record_post_action 00:43:43 INFO - [mozharness: 2016-09-13 07:43:43.878414Z] Finished clobber step (success) 00:43:43 INFO - [mozharness: 2016-09-13 07:43:43.878697Z] Running read-buildbot-config step. 00:43:43 INFO - Running pre-action listener: _resource_record_pre_action 00:43:43 INFO - Running main action method: read_buildbot_config 00:43:43 INFO - Using buildbot properties: 00:43:43 INFO - { 00:43:43 INFO - "project": "", 00:43:43 INFO - "product": "firefox", 00:43:43 INFO - "script_repo_revision": "production", 00:43:43 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 00:43:43 INFO - "repository": "", 00:43:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 00:43:43 INFO - "buildid": "20160912213805", 00:43:43 INFO - "pgo_build": "False", 00:43:43 INFO - "basedir": "/builds/slave/test", 00:43:43 INFO - "buildnumber": 46, 00:43:43 INFO - "slavename": "t-yosemite-r7-0216", 00:43:43 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 00:43:43 INFO - "platform": "yosemite_r7", 00:43:43 INFO - "scheduler": "tests-fx-team-macosx64-talos-8-3600", 00:43:43 INFO - "branch": "fx-team", 00:43:43 INFO - "repo_path": "integration/fx-team", 00:43:43 INFO - "revision": "4850895eb2fbee98b74a885ea00be1cadc352313", 00:43:43 INFO - "stage_platform": "macosx64", 00:43:43 INFO - "builduid": "e51bc8c7d06d40e7a54698267eb94c90", 00:43:43 INFO - "slavebuilddir": "test" 00:43:43 INFO - } 00:43:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/BAdemLy2Qx6IW5-Kb0HyqA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 00:43:43 INFO - Running post-action listener: _resource_record_post_action 00:43:43 INFO - [mozharness: 2016-09-13 07:43:43.883407Z] Finished read-buildbot-config step (success) 00:43:43 INFO - [mozharness: 2016-09-13 07:43:43.883511Z] Running download-and-extract step. 00:43:43 INFO - Running pre-action listener: _resource_record_pre_action 00:43:43 INFO - Running main action method: download_and_extract 00:43:43 INFO - mkdir: /builds/slave/test/build/tests 00:43:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:43:43 INFO - trying https://queue.taskcluster.net/v1/task/BAdemLy2Qx6IW5-Kb0HyqA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 00:43:43 INFO - Downloading https://queue.taskcluster.net/v1/task/BAdemLy2Qx6IW5-Kb0HyqA/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 00:43:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BAdemLy2Qx6IW5-Kb0HyqA/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 00:43:44 INFO - Downloaded 1199 bytes. 00:43:44 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 00:43:44 INFO - Using the following test package requirements: 00:43:44 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 00:43:44 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 00:43:44 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 00:43:44 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 00:43:44 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 00:43:44 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 00:43:44 INFO - u'jsshell-mac.zip'], 00:43:44 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 00:43:44 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 00:43:44 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 00:43:44 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 00:43:44 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 00:43:44 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 00:43:44 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 00:43:44 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 00:43:44 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 00:43:44 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 00:43:44 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 00:43:44 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 00:43:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BAdemLy2Qx6IW5-Kb0HyqA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 00:43:45 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 00:43:52 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 00:43:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BAdemLy2Qx6IW5-Kb0HyqA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 00:43:52 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 00:43:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BAdemLy2Qx6IW5-Kb0HyqA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 00:43:59 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 00:44: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')]} 00:44:00 INFO - trying https://queue.taskcluster.net/v1/task/BAdemLy2Qx6IW5-Kb0HyqA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 00:44:00 INFO - Downloading https://queue.taskcluster.net/v1/task/BAdemLy2Qx6IW5-Kb0HyqA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 00:44:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BAdemLy2Qx6IW5-Kb0HyqA/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 00:44:03 INFO - Downloaded 111409301 bytes. 00:44:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BAdemLy2Qx6IW5-Kb0HyqA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 00:44:03 INFO - mkdir: /builds/slave/test/properties 00:44:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:44:03 INFO - Writing to file /builds/slave/test/properties/build_url 00:44:03 INFO - Contents: 00:44:03 INFO - build_url:https://queue.taskcluster.net/v1/task/BAdemLy2Qx6IW5-Kb0HyqA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 00:44:04 INFO - Running post-action listener: _resource_record_post_action 00:44:04 INFO - Running post-action listener: set_extra_try_arguments 00:44:04 INFO - [mozharness: 2016-09-13 07:44:04.466287Z] Finished download-and-extract step (success) 00:44:04 INFO - [mozharness: 2016-09-13 07:44:04.466386Z] Running populate-webroot step. 00:44:04 INFO - Running pre-action listener: _resource_record_pre_action 00:44:04 INFO - Running main action method: populate_webroot 00:44:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:44:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:44:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:44:04 INFO - '--tppagecycles', 00:44:04 INFO - '7'], 00:44:04 INFO - 'tests': ['tsvgm']}}, 00:44:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 00:44:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 00:44:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:44:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 00:44:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 00:44:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:44:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:44:04 INFO - 'talos_options': ['--disable-e10s'], 00:44:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:44:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:44:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:44:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:44:04 INFO - 'talos_options': ['--disable-e10s'], 00:44:04 INFO - 'tests': ['damp', 'tps']}, 00:44:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:44:04 INFO - 'tests': ['damp', 'tps']}, 00:44:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 00:44:04 INFO - 'tests': ['dromaeo_dom']}, 00:44:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:44:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 00:44:04 INFO - 'tests': ['basic_compositor_video']}, 00:44:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 00:44:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 00:44:04 INFO - 'tests': ['a11yr', 00:44:04 INFO - 'ts_paint', 00:44:04 INFO - 'tpaint', 00:44:04 INFO - 'sessionrestore', 00:44:04 INFO - 'sessionrestore_no_auto_restore', 00:44:04 INFO - 'tabpaint']}, 00:44:04 INFO - 'other-e10s': {'tests': ['a11yr', 00:44:04 INFO - 'ts_paint', 00:44:04 INFO - 'tpaint', 00:44:04 INFO - 'sessionrestore', 00:44:04 INFO - 'sessionrestore_no_auto_restore', 00:44:04 INFO - 'tabpaint']}, 00:44:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 00:44:04 INFO - 'ts_paint', 00:44:04 INFO - 'tpaint', 00:44:04 INFO - 'sessionrestore', 00:44:04 INFO - 'sessionrestore_no_auto_restore', 00:44:04 INFO - 'tabpaint']}, 00:44:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 00:44:04 INFO - 'ts_paint', 00:44:04 INFO - 'tpaint', 00:44:04 INFO - 'sessionrestore', 00:44:04 INFO - 'sessionrestore_no_auto_restore', 00:44:04 INFO - 'tabpaint']}, 00:44:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 00:44:04 INFO - 'tests': ['a11yr', 00:44:04 INFO - 'ts_paint', 00:44:04 INFO - 'tpaint', 00:44:04 INFO - 'sessionrestore', 00:44:04 INFO - 'sessionrestore_no_auto_restore', 00:44:04 INFO - 'tabpaint']}, 00:44:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 00:44:04 INFO - 'tests': ['a11yr', 00:44:04 INFO - 'ts_paint', 00:44:04 INFO - 'tpaint', 00:44:04 INFO - 'sessionrestore', 00:44:04 INFO - 'sessionrestore_no_auto_restore', 00:44:04 INFO - 'tabpaint']}, 00:44:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 00:44:04 INFO - 'tests': ['tsvgx', 00:44:04 INFO - 'tsvgr_opacity', 00:44:04 INFO - 'tart', 00:44:04 INFO - 'tscrollx', 00:44:04 INFO - 'cart']}, 00:44:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:44:04 INFO - 'tsvgr_opacity', 00:44:04 INFO - 'tart', 00:44:04 INFO - 'tscrollx', 00:44:04 INFO - 'cart']}, 00:44:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:44:04 INFO - 'talos_options': ['--disable-e10s'], 00:44:04 INFO - 'tests': ['tp5o']}, 00:44:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:44:04 INFO - 'tests': ['tp5o']}, 00:44:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:44:04 INFO - 'talos_options': ['--disable-e10s', 00:44:04 INFO - '--xperf_path', 00:44:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:44:04 INFO - 'tests': ['tp5n']}, 00:44:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:44:04 INFO - 'talos_options': ['--xperf_path', 00:44:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:44:04 INFO - 'tests': ['tp5n']}}, 00:44:04 INFO - 'talos.zip': {'path': '', 00:44:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:44:04 INFO - Running post-action listener: _resource_record_post_action 00:44:04 INFO - [mozharness: 2016-09-13 07:44:04.485009Z] Finished populate-webroot step (success) 00:44:04 INFO - [mozharness: 2016-09-13 07:44:04.485150Z] Running create-virtualenv step. 00:44:04 INFO - Running pre-action listener: _resource_record_pre_action 00:44:04 INFO - Running main action method: create_virtualenv 00:44:04 INFO - Creating virtualenv /builds/slave/test/build/venv 00:44:04 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 00:44:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:44:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:44:04 INFO - Using real prefix '/tools/python27' 00:44:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:44:05 INFO - Installing distribute.............................................................................................................................................................................................done. 00:44:08 INFO - Installing pip.................done. 00:44:08 INFO - Return code: 0 00:44:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00:44:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:44:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:44:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:08 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 0x10f7ab490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f721570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4404cb4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5bfd30>, '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 0x10f3e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5b7b20>, '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.eQbOWuzbJP/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.w7AUWSR3Tp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 00:44:08 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 00:44:08 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 00:44:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eQbOWuzbJP/Render', 00:44:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:44:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:44:08 INFO - 'HOME': '/Users/cltbld', 00:44:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:44:08 INFO - 'LOGNAME': 'cltbld', 00:44:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:44:08 INFO - 'MOZ_NO_REMOTE': '1', 00:44:08 INFO - 'NO_EM_RESTART': '1', 00:44:08 INFO - 'PAGER': '/bin/cat', 00:44:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:44:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:44:08 INFO - 'PWD': '/builds/slave/test', 00:44:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:44:08 INFO - 'SHELL': '/bin/bash', 00:44:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w7AUWSR3Tp/Listeners', 00:44:08 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 00:44:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:44:08 INFO - 'USER': 'cltbld', 00:44:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:44:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:44:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:44:08 INFO - 'XPC_FLAGS': '0x0', 00:44:08 INFO - 'XPC_SERVICE_NAME': '0', 00:44:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:44:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:44:08 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 00:44:08 INFO - Cleaning up... 00:44:08 INFO - Return code: 0 00:44:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 00:44:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:44:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:44:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:08 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 0x10f7ab490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f721570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4404cb4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5bfd30>, '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 0x10f3e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5b7b20>, '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.eQbOWuzbJP/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.w7AUWSR3Tp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 00:44:08 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 00:44:08 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 00:44:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eQbOWuzbJP/Render', 00:44:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:44:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:44:08 INFO - 'HOME': '/Users/cltbld', 00:44:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:44:08 INFO - 'LOGNAME': 'cltbld', 00:44:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:44:08 INFO - 'MOZ_NO_REMOTE': '1', 00:44:08 INFO - 'NO_EM_RESTART': '1', 00:44:08 INFO - 'PAGER': '/bin/cat', 00:44:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:44:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:44:08 INFO - 'PWD': '/builds/slave/test', 00:44:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:44:08 INFO - 'SHELL': '/bin/bash', 00:44:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w7AUWSR3Tp/Listeners', 00:44:08 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 00:44:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:44:08 INFO - 'USER': 'cltbld', 00:44:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:44:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:44:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:44:08 INFO - 'XPC_FLAGS': '0x0', 00:44:08 INFO - 'XPC_SERVICE_NAME': '0', 00:44:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:44:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:44:08 INFO - Downloading/unpacking psutil>=3.1.1 00:44:08 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 00:44:08 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 00:44:08 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 00:44:08 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 00:44:08 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 00:44:08 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 00:44:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:44:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:44:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:44:12 INFO - Installing collected packages: psutil 00:44:12 INFO - Running setup.py install for psutil 00:44:12 INFO - building 'psutil._psutil_osx' extension 00:44:12 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 00:44:12 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 00:44:12 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 00:44:12 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 00:44:12 INFO - building 'psutil._psutil_posix' extension 00:44:12 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 00:44:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 00:44:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 00:44:12 INFO - ^ 00:44:12 INFO - 1 warning generated. 00:44:12 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 00:44:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:44:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:44:12 INFO - Successfully installed psutil 00:44:12 INFO - Cleaning up... 00:44:13 INFO - Return code: 0 00:44:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 00:44:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:44:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:44:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:13 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 0x10f7ab490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f721570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4404cb4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5bfd30>, '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 0x10f3e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5b7b20>, '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.eQbOWuzbJP/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.w7AUWSR3Tp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 00:44:13 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 00:44:13 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 00:44:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eQbOWuzbJP/Render', 00:44:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:44:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:44:13 INFO - 'HOME': '/Users/cltbld', 00:44:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:44:13 INFO - 'LOGNAME': 'cltbld', 00:44:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:44:13 INFO - 'MOZ_NO_REMOTE': '1', 00:44:13 INFO - 'NO_EM_RESTART': '1', 00:44:13 INFO - 'PAGER': '/bin/cat', 00:44:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:44:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:44:13 INFO - 'PWD': '/builds/slave/test', 00:44:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:44:13 INFO - 'SHELL': '/bin/bash', 00:44:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w7AUWSR3Tp/Listeners', 00:44:13 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 00:44:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:44:13 INFO - 'USER': 'cltbld', 00:44:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:44:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:44:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:44:13 INFO - 'XPC_FLAGS': '0x0', 00:44:13 INFO - 'XPC_SERVICE_NAME': '0', 00:44:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:44:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:44:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 00:44:13 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 00:44:13 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 00:44:13 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 00:44:13 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 00:44:13 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 00:44:13 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 00:44:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 00:44:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:44:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 00:44:15 INFO - Installing collected packages: mozsystemmonitor 00:44:15 INFO - Running setup.py install for mozsystemmonitor 00:44:15 INFO - Successfully installed mozsystemmonitor 00:44:15 INFO - Cleaning up... 00:44:16 INFO - Return code: 0 00:44:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 00:44:16 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')]} 00:44:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:44:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:16 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')]} 00:44:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:44:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:16 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 0x10f7ab490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f721570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4404cb4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5bfd30>, '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 0x10f3e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5b7b20>, '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.eQbOWuzbJP/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.w7AUWSR3Tp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 00:44:16 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 00:44:16 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 00:44:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eQbOWuzbJP/Render', 00:44:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:44:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:44:16 INFO - 'HOME': '/Users/cltbld', 00:44:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:44:16 INFO - 'LOGNAME': 'cltbld', 00:44:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:44:16 INFO - 'MOZ_NO_REMOTE': '1', 00:44:16 INFO - 'NO_EM_RESTART': '1', 00:44:16 INFO - 'PAGER': '/bin/cat', 00:44:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:44:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:44:16 INFO - 'PWD': '/builds/slave/test', 00:44:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:44:16 INFO - 'SHELL': '/bin/bash', 00:44:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w7AUWSR3Tp/Listeners', 00:44:16 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 00:44:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:44:16 INFO - 'USER': 'cltbld', 00:44:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:44:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:44:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:44:16 INFO - 'XPC_FLAGS': '0x0', 00:44:16 INFO - 'XPC_SERVICE_NAME': '0', 00:44:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:44:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:44:16 INFO - Downloading/unpacking jsonschema==2.5.1 00:44:16 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 00:44:16 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 00:44:16 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 00:44:16 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 00:44:16 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 00:44:16 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 00:44:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:44:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 00:44:18 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 00:44:18 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 00:44:18 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 00:44:18 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 00:44:18 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 00:44:18 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 00:44:19 INFO - Downloading functools32-3.2.3-2.tar.gz 00:44:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:44:19 INFO - warning: no files found matching '*.txt' 00:44:19 INFO - no previously-included directories found matching 'build' 00:44:19 INFO - no previously-included directories found matching 'dist' 00:44:19 INFO - no previously-included directories found matching '.git*' 00:44:19 INFO - Installing collected packages: jsonschema, functools32 00:44:19 INFO - Running setup.py install for functools32 00:44:19 INFO - warning: no files found matching '*.txt' 00:44:19 INFO - no previously-included directories found matching 'build' 00:44:19 INFO - no previously-included directories found matching 'dist' 00:44:19 INFO - no previously-included directories found matching '.git*' 00:44:19 INFO - Successfully installed jsonschema functools32 00:44:19 INFO - Cleaning up... 00:44:19 INFO - Return code: 0 00:44:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 00:44:19 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')]} 00:44:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:44:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:19 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')]} 00:44:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:44:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:19 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 0x10f7ab490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f721570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4404cb4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5bfd30>, '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 0x10f3e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5b7b20>, '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.eQbOWuzbJP/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.w7AUWSR3Tp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 00:44:19 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 00:44:19 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 00:44:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eQbOWuzbJP/Render', 00:44:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:44:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:44:19 INFO - 'HOME': '/Users/cltbld', 00:44:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:44:19 INFO - 'LOGNAME': 'cltbld', 00:44:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:44:19 INFO - 'MOZ_NO_REMOTE': '1', 00:44:19 INFO - 'NO_EM_RESTART': '1', 00:44:19 INFO - 'PAGER': '/bin/cat', 00:44:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:44:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:44:19 INFO - 'PWD': '/builds/slave/test', 00:44:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:44:19 INFO - 'SHELL': '/bin/bash', 00:44:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w7AUWSR3Tp/Listeners', 00:44:19 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 00:44:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:44:19 INFO - 'USER': 'cltbld', 00:44:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:44:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:44:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:44:19 INFO - 'XPC_FLAGS': '0x0', 00:44:19 INFO - 'XPC_SERVICE_NAME': '0', 00:44:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:44:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:44:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 00:44:19 INFO - Cleaning up... 00:44:19 INFO - Return code: 0 00:44:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:44:19 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')]} 00:44:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:44:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:19 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')]} 00:44:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:44:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:19 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 0x10f7ab490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f721570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4404cb4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5bfd30>, '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 0x10f3e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5b7b20>, '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.eQbOWuzbJP/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.w7AUWSR3Tp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 00:44:19 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 00:44:19 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 00:44:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eQbOWuzbJP/Render', 00:44:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:44:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:44:19 INFO - 'HOME': '/Users/cltbld', 00:44:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:44:19 INFO - 'LOGNAME': 'cltbld', 00:44:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:44:19 INFO - 'MOZ_NO_REMOTE': '1', 00:44:19 INFO - 'NO_EM_RESTART': '1', 00:44:19 INFO - 'PAGER': '/bin/cat', 00:44:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:44:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:44:19 INFO - 'PWD': '/builds/slave/test', 00:44:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:44:19 INFO - 'SHELL': '/bin/bash', 00:44:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w7AUWSR3Tp/Listeners', 00:44:19 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 00:44:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:44:19 INFO - 'USER': 'cltbld', 00:44:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:44:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:44:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:44:19 INFO - 'XPC_FLAGS': '0x0', 00:44:19 INFO - 'XPC_SERVICE_NAME': '0', 00:44:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:44:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:44:20 INFO - Downloading/unpacking blobuploader==1.2.4 00:44:20 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 00:44:20 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 00:44:20 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 00:44:20 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 00:44:20 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 00:44:20 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 00:44:22 INFO - Downloading blobuploader-1.2.4.tar.gz 00:44:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:44:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:44:22 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 00:44:22 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 00:44:22 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 00:44:22 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 00:44:22 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 00:44:22 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 00:44:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:44:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:44:23 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 00:44:23 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 00:44:23 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 00:44:23 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 00:44:23 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 00:44:23 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 00:44:23 INFO - Downloading docopt-0.6.1.tar.gz 00:44:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:44:23 INFO - Installing collected packages: blobuploader, requests, docopt 00:44:23 INFO - Running setup.py install for blobuploader 00:44:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:44:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:44:23 INFO - Running setup.py install for requests 00:44:24 INFO - Running setup.py install for docopt 00:44:24 INFO - Successfully installed blobuploader requests docopt 00:44:24 INFO - Cleaning up... 00:44:24 INFO - Return code: 0 00:44:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:44:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:44:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:44:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:24 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 0x10f7ab490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f721570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4404cb4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5bfd30>, '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 0x10f3e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5b7b20>, '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.eQbOWuzbJP/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.w7AUWSR3Tp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 00:44:24 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 00:44:24 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 00:44:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eQbOWuzbJP/Render', 00:44:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:44:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:44:24 INFO - 'HOME': '/Users/cltbld', 00:44:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:44:24 INFO - 'LOGNAME': 'cltbld', 00:44:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:44:24 INFO - 'MOZ_NO_REMOTE': '1', 00:44:24 INFO - 'NO_EM_RESTART': '1', 00:44:24 INFO - 'PAGER': '/bin/cat', 00:44:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:44:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:44:24 INFO - 'PWD': '/builds/slave/test', 00:44:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:44:24 INFO - 'SHELL': '/bin/bash', 00:44:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w7AUWSR3Tp/Listeners', 00:44:24 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 00:44:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:44:24 INFO - 'USER': 'cltbld', 00:44:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:44:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:44:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:44:24 INFO - 'XPC_FLAGS': '0x0', 00:44:24 INFO - 'XPC_SERVICE_NAME': '0', 00:44:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:44:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:44:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:44:24 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-zk9gwe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:44:25 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-cXmA9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:44:25 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-UMDO1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:44:25 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-HGHfQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:44:25 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-slbYHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:44:25 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-5wJal6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:44:25 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-HplolJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:44:25 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-H55qWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:44:25 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-T33ugO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:44:25 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-Zv_oDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:44:26 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-Fm1tGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:44:26 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-uOz2wa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:44:26 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-pgLOAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:44:26 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-UY8GC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:44:26 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-LO65Vd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:44:26 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-JAGuXi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:44:26 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-lWOOxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:44:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:44:26 INFO - Running setup.py install for manifestparser 00:44:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:44:26 INFO - Running setup.py install for mozcrash 00:44:27 INFO - Running setup.py install for mozdebug 00:44:27 INFO - Running setup.py install for mozdevice 00:44:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 00:44:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:44:27 INFO - Running setup.py install for mozfile 00:44:27 INFO - Running setup.py install for mozhttpd 00:44:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:44:27 INFO - Running setup.py install for mozinfo 00:44:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:44:27 INFO - Running setup.py install for mozInstall 00:44:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:44:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:44:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:44:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:44:27 INFO - Running setup.py install for mozleak 00:44:28 INFO - Running setup.py install for mozlog 00:44:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:44:28 INFO - Running setup.py install for moznetwork 00:44:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:44:28 INFO - Running setup.py install for mozprocess 00:44:28 INFO - Running setup.py install for mozprofile 00:44:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:44:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:44:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:44:28 INFO - Running setup.py install for mozrunner 00:44:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:44:28 INFO - Running setup.py install for mozscreenshot 00:44:29 INFO - Running setup.py install for moztest 00:44:29 INFO - Running setup.py install for mozversion 00:44:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:44:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:44:29 INFO - Cleaning up... 00:44:29 INFO - Return code: 0 00:44:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:44:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:44:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:44:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:29 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 0x10f7ab490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f721570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4404cb4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5bfd30>, '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 0x10f3e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5b7b20>, '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.eQbOWuzbJP/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.w7AUWSR3Tp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 00:44:29 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 00:44:29 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 00:44:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eQbOWuzbJP/Render', 00:44:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:44:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:44:29 INFO - 'HOME': '/Users/cltbld', 00:44:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:44:29 INFO - 'LOGNAME': 'cltbld', 00:44:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:44:29 INFO - 'MOZ_NO_REMOTE': '1', 00:44:29 INFO - 'NO_EM_RESTART': '1', 00:44:29 INFO - 'PAGER': '/bin/cat', 00:44:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:44:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:44:29 INFO - 'PWD': '/builds/slave/test', 00:44:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:44:29 INFO - 'SHELL': '/bin/bash', 00:44:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w7AUWSR3Tp/Listeners', 00:44:29 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 00:44:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:44:29 INFO - 'USER': 'cltbld', 00:44:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:44:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:44:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:44:29 INFO - 'XPC_FLAGS': '0x0', 00:44:29 INFO - 'XPC_SERVICE_NAME': '0', 00:44:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:44:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:44:29 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-CUg14x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:44:29 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)) 00:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:44:29 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-hh4ixn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:44:29 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)) 00:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:44:29 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-UX_jlw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:44:29 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)) 00:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:44:29 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-eLEN_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:44:30 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)) 00:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:44:30 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-pj1EP8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:44:30 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)) 00:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:44:30 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-ZDsoYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:44:30 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)) 00:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:44:30 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-v0j4zp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:44:30 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)) 00:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:44:30 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-3t3oWF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:44:30 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)) 00:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:44:30 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-VNoWSC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:44:30 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)) 00:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:44:30 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-08Vjwp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:44:30 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)) 00:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:44:30 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-1eToTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:44:30 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)) 00:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:44:30 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-PTjP_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:44:30 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)) 00:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:44:30 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-N0aDdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:44:31 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)) 00:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:44:31 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-SpLUz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:44:31 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)) 00:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:44:31 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-lKg270-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:44:31 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)) 00:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:44:31 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-0A06aP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:44:31 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)) 00:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:44:31 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-weD7ls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:44:31 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)) 00:44:31 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)) 00:44:31 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)) 00:44:31 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)) 00:44:31 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)) 00:44:31 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)) 00:44:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:44:31 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 00:44:31 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 00:44:31 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 00:44:31 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 00:44:31 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 00:44:31 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 00:44:33 INFO - Downloading blessings-1.6.tar.gz 00:44:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:44:33 INFO - Installing collected packages: blessings 00:44:33 INFO - Running setup.py install for blessings 00:44:34 INFO - Successfully installed blessings 00:44:34 INFO - Cleaning up... 00:44:34 INFO - Return code: 0 00:44:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:44:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:44:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:44:34 INFO - Reading from file tmpfile_stdout 00:44:34 INFO - Current package versions: 00:44:34 INFO - blessings == 1.6 00:44:34 INFO - blobuploader == 1.2.4 00:44:34 INFO - docopt == 0.6.1 00:44:34 INFO - functools32 == 3.2.3-2 00:44:34 INFO - jsonschema == 2.5.1 00:44:34 INFO - manifestparser == 1.1 00:44:34 INFO - mozInstall == 1.12 00:44:34 INFO - mozcrash == 0.17 00:44:34 INFO - mozdebug == 0.1 00:44:34 INFO - mozdevice == 0.48 00:44:34 INFO - mozfile == 1.2 00:44:34 INFO - mozhttpd == 0.7 00:44:34 INFO - mozinfo == 0.9 00:44:34 INFO - mozleak == 0.1 00:44:34 INFO - mozlog == 3.3 00:44:34 INFO - moznetwork == 0.27 00:44:34 INFO - mozprocess == 0.23 00:44:34 INFO - mozprofile == 0.28 00:44:34 INFO - mozrunner == 6.12 00:44:34 INFO - mozscreenshot == 0.1 00:44:34 INFO - mozsystemmonitor == 0.3 00:44:34 INFO - moztest == 0.7 00:44:34 INFO - mozversion == 1.4 00:44:34 INFO - psutil == 3.1.1 00:44:34 INFO - requests == 1.2.3 00:44:34 INFO - wsgiref == 0.1.2 00:44:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:44:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:44:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:44:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:34 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 0x10f7ab490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f721570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4404cb4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5bfd30>, '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 0x10f3e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5b7b20>, '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.eQbOWuzbJP/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.w7AUWSR3Tp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 00:44:34 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 00:44:34 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 00:44:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eQbOWuzbJP/Render', 00:44:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:44:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:44:34 INFO - 'HOME': '/Users/cltbld', 00:44:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:44:34 INFO - 'LOGNAME': 'cltbld', 00:44:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:44:34 INFO - 'MOZ_NO_REMOTE': '1', 00:44:34 INFO - 'NO_EM_RESTART': '1', 00:44:34 INFO - 'PAGER': '/bin/cat', 00:44:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:44:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:44:34 INFO - 'PWD': '/builds/slave/test', 00:44:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:44:34 INFO - 'SHELL': '/bin/bash', 00:44:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w7AUWSR3Tp/Listeners', 00:44:34 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 00:44:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:44:34 INFO - 'USER': 'cltbld', 00:44:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:44:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:44:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:44:34 INFO - 'XPC_FLAGS': '0x0', 00:44:34 INFO - 'XPC_SERVICE_NAME': '0', 00:44:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:44:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:44:34 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)) 00:44:34 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)) 00:44:34 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)) 00:44:34 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)) 00:44:34 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)) 00:44:34 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)) 00:44:34 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)) 00:44:34 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)) 00:44:34 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)) 00:44:34 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)) 00:44:34 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)) 00:44:34 INFO - Cleaning up... 00:44:34 INFO - Return code: 0 00:44:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:44:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:44:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:44:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:44:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:44:34 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 0x10f7ab490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f721570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4404cb4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5bfd30>, '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 0x10f3e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5b7b20>, '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.eQbOWuzbJP/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.w7AUWSR3Tp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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 00:44:34 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 00:44:34 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 00:44:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eQbOWuzbJP/Render', 00:44:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:44:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:44:34 INFO - 'HOME': '/Users/cltbld', 00:44:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:44:34 INFO - 'LOGNAME': 'cltbld', 00:44:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:44:34 INFO - 'MOZ_NO_REMOTE': '1', 00:44:34 INFO - 'NO_EM_RESTART': '1', 00:44:34 INFO - 'PAGER': '/bin/cat', 00:44:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:44:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:44:34 INFO - 'PWD': '/builds/slave/test', 00:44:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:44:34 INFO - 'SHELL': '/bin/bash', 00:44:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w7AUWSR3Tp/Listeners', 00:44:34 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 00:44:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:44:34 INFO - 'USER': 'cltbld', 00:44:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:44:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:44:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:44:34 INFO - 'XPC_FLAGS': '0x0', 00:44:34 INFO - 'XPC_SERVICE_NAME': '0', 00:44:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:44:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:44:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 00:44:34 INFO - Cleaning up... 00:44:34 INFO - Return code: 0 00:44:34 INFO - Running post-action listener: _resource_record_post_action 00:44:34 INFO - Running post-action listener: _start_resource_monitoring 00:44:34 INFO - Starting resource monitoring. 00:44:34 INFO - [mozharness: 2016-09-13 07:44:34.998076Z] Finished create-virtualenv step (success) 00:44:34 INFO - [mozharness: 2016-09-13 07:44:34.999098Z] Running install step. 00:44:34 INFO - Running pre-action listener: _resource_record_pre_action 00:44:34 INFO - Running main action method: install 00:44:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:44:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:44:35 INFO - Reading from file tmpfile_stdout 00:44:35 INFO - Detecting whether we're running mozinstall >=1.0... 00:44:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:44:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:44:35 INFO - Reading from file tmpfile_stdout 00:44:35 INFO - Output received: 00:44:35 INFO - Usage: mozinstall [options] installer 00:44:35 INFO - Options: 00:44:35 INFO - -h, --help show this help message and exit 00:44:35 INFO - -d DEST, --destination=DEST 00:44:35 INFO - Directory to install application into. [default: 00:44:35 INFO - "/builds/slave/test"] 00:44:35 INFO - --app=APP Application being installed. [default: firefox] 00:44:35 INFO - mkdir: /builds/slave/test/build/application 00:44:35 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'] 00:44:35 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 00:45:09 INFO - Reading from file tmpfile_stdout 00:45:09 INFO - Output received: 00:45:09 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 00:45:09 INFO - Running post-action listener: _resource_record_post_action 00:45:09 INFO - [mozharness: 2016-09-13 07:45:09.335429Z] Finished install step (success) 00:45:09 INFO - [mozharness: 2016-09-13 07:45:09.335573Z] Running run-tests step. 00:45:09 INFO - Running pre-action listener: _resource_record_pre_action 00:45:09 INFO - Running main action method: run_tests 00:45:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:45:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:45:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:45:09 INFO - 2016-09-13 00:45:09.349 screenresolution[3670:32074] starting screenresolution argv=screenresolution get 00:45:09 INFO - 2016-09-13 00:45:09.352 screenresolution[3670:32074] Display 0: 1600x1200x32@60 00:45:09 INFO - 2016-09-13 00:45:09.358 screenresolution[3671:32076] starting screenresolution argv=screenresolution list 00:45:09 INFO - Available Modes on Display 0 00:45:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:45:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:45:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:45:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:45:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:45:09 INFO - Intel Iris: 00:45:09 INFO - Chipset Model: Intel Iris 00:45:09 INFO - Type: GPU 00:45:09 INFO - Bus: Built-In 00:45:09 INFO - VRAM (Dynamic, Max): 1536 MB 00:45:09 INFO - Vendor: Intel (0x8086) 00:45:09 INFO - Device ID: 0x0a2e 00:45:09 INFO - Revision ID: 0x0009 00:45:09 INFO - Displays: 00:45:09 INFO - Display: 00:45:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:45:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:45:09 INFO - Display Serial Number: 1600x1200 60 00:45:09 INFO - Main Display: Yes 00:45:09 INFO - Mirror: Off 00:45:09 INFO - Online: Yes 00:45:09 INFO - Rotation: Supported 00:45:09 INFO - Return code: 0 00:45:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:45:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:45:09 INFO - Python 2.7.3 00:45:09 INFO - Return code: 0 00:45:09 INFO - grabbing minidump binary from tooltool 00:45:09 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')]} 00:45:09 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 0x10f5bfd30>, '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 0x10f3e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5b7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:45:09 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 00:45:09 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 00:45:09 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 00:45:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:45:09 INFO - Return code: 0 00:45:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 00:45:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:45:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:45:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 00:45:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:45:09 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-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BAdemLy2Qx6IW5-Kb0HyqA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0216', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 00:45:09 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-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BAdemLy2Qx6IW5-Kb0HyqA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0216 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 00:45:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eQbOWuzbJP/Render', 00:45:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:45:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:45:09 INFO - 'HOME': '/Users/cltbld', 00:45:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:45:09 INFO - 'LOGNAME': 'cltbld', 00:45:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:45:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 00:45:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:45:09 INFO - 'MOZ_NO_REMOTE': '1', 00:45:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:45:09 INFO - 'NO_EM_RESTART': '1', 00:45:09 INFO - 'PAGER': '/bin/cat', 00:45:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:45:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:45:09 INFO - 'PWD': '/builds/slave/test', 00:45:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:45:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:45:09 INFO - 'SHELL': '/bin/bash', 00:45:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w7AUWSR3Tp/Listeners', 00:45:09 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 00:45:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:45:09 INFO - 'USER': 'cltbld', 00:45:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:45:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:45:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:45:09 INFO - 'XPC_FLAGS': '0x0', 00:45:09 INFO - 'XPC_SERVICE_NAME': '0', 00:45:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:45:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BAdemLy2Qx6IW5-Kb0HyqA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0216', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 00:45:09 INFO - mozversion application_buildid: 20160912213805 00:45:09 INFO - mozversion application_changeset: 4850895eb2fbee98b74a885ea00be1cadc352313 00:45:09 INFO - mozversion application_display_name: Nightly 00:45:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:45:09 INFO - mozversion application_name: Firefox 00:45:09 INFO - mozversion application_remotingname: firefox 00:45:09 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 00:45:09 INFO - mozversion application_vendor: Mozilla 00:45:09 INFO - mozversion application_version: 51.0a1 00:45:09 INFO - mozversion platform_buildid: 20160912213805 00:45:09 INFO - mozversion platform_changeset: 4850895eb2fbee98b74a885ea00be1cadc352313 00:45:09 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 00:45:09 INFO - mozversion platform_version: 51.0a1 00:45:09 INFO - using testdate: 1473752709 00:45:09 INFO - actual date: 1473752709 00:45:09 INFO - starting webserver on 'localhost:59686' 00:45:09 INFO - SUITE-START | Running 2 tests 00:45:09 INFO - TEST-START | dromaeo_css 00:45:09 INFO - operating with platform_type : mac_ 00:45:09 INFO - Initialising browser for dromaeo_css test... 00:45:09 INFO - TEST-INFO | started process 3681 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmpUSxvsm/profile http://localhost:59686/getInfo.html) 00:45:12 INFO - PROCESS | 3681 | __metrics Screen width/height:1600/1200 00:45:12 INFO - PROCESS | 3681 | colorDepth:24 00:45:12 INFO - PROCESS | 3681 | Browser inner width/height: 1024/689 00:45:12 INFO - PROCESS | 3681 | __metrics 00:45:12 ERROR - PROCESS | 3681 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 00:45:13 INFO - TEST-INFO | 3681: exit 0 00:45:13 INFO - Browser initialized. 00:45:13 INFO - Running cycle 1/1 for dromaeo_css test... 00:45:13 INFO - TEST-INFO | started process 3685 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmpUSxvsm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:45:25 INFO - PROCESS | 3685 | RSS: Main: 245485568 00:45:25 INFO - PROCESS | 3685 | 00:46:15 INFO - PROCESS | 3685 | 1473752775416 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] 00:48:28 INFO - PROCESS | 3685 | Cycle 1(1): loaded http://localhost:59686/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59686/tests/dromaeo/cssquery-ext.html) 00:48:29 INFO - PROCESS | 3685 | RSS: Main: 264699904 00:48:29 INFO - PROCESS | 3685 | 00:51:32 INFO - PROCESS | 3685 | Cycle 1(1): loaded http://localhost:59686/tests/dromaeo/cssquery-ext.html (next: http://localhost:59686/tests/dromaeo/cssquery-jquery.html) 00:51:32 INFO - PROCESS | 3685 | RSS: Main: 271024128 00:51:32 INFO - PROCESS | 3685 | 00:54:34 INFO - PROCESS | 3685 | Cycle 1(1): loaded http://localhost:59686/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59686/tests/dromaeo/cssquery-mootools.html) 00:54:35 INFO - PROCESS | 3685 | RSS: Main: 275476480 00:54:35 INFO - PROCESS | 3685 | 00:57:37 INFO - PROCESS | 3685 | Cycle 1(1): loaded http://localhost:59686/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59686/tests/dromaeo/cssquery-prototype.html) 00:57:37 INFO - PROCESS | 3685 | RSS: Main: 270856192 00:57:37 INFO - PROCESS | 3685 | 01:00:40 INFO - PROCESS | 3685 | Cycle 1(1): loaded http://localhost:59686/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59686/tests/dromaeo/cssquery-yui.html) 01:00:41 INFO - PROCESS | 3685 | RSS: Main: 277233664 01:00:41 INFO - PROCESS | 3685 | 01:03:43 INFO - PROCESS | 3685 | Cycle 1(1): loaded http://localhost:59686/tests/dromaeo/cssquery-yui.html (next: http://localhost:59686/tests/dromaeo/cssquery-dojo.html) 01:03:44 INFO - PROCESS | 3685 | RSS: Main: 280199168 01:03:44 INFO - PROCESS | 3685 | 01:03:44 INFO - PROCESS | 3685 | __start_tp_report 01:03:44 INFO - PROCESS | 3685 | _x_x_mozilla_page_load 01:03:44 INFO - PROCESS | 3685 | _x_x_mozilla_page_load_details 01:03:44 INFO - PROCESS | 3685 | |i|pagename|runs| 01:03:44 INFO - PROCESS | 3685 | |0;dojo.html;3220.871165153394;3320.4687250039983;3389.8983030509094;3396.966030339697;3400.1159698478386;4738.28925661151;5368.114261146906;5614.410486898878;5669.404712505189;5698.772049118035;5630.296212973379;5723.9141412878735;5731.713414329288;5742.655440673552;5771.884562308751;5187.066328060938;5234.162533994561;5248.160294352904;5299.54953828923;5301.151815709487;5290.629655924087;5322.760475778599;5325.4940780625975;5334.413214546396;5344.118220493614;5320.095583750753;5321.175217841241;5329.333833270841;5334.306540149775;5344.438833922441;5286.180642000496;5294.973525132388;5304.389995150568;5304.655197412195;5323.334583177103;5166.948330516722;5200.739963001834;5269.051570717269;5284.445133260991;5328.6269961102735;4781.832635857728;5792.652440853562;5836;5842.912356314659;5888.911666325009;5183.61122915779;6209.565330426872;6249.0626406039;6292.905606415908;6294.244690637152;6145.569810113294;6146.692665366714;6158.969205153946;6199.411055949678;6302.621842689453;5235.821940063455;5625.634333768292;5928.851778705481;5967.5524335675;6011.969940150271;6353.047042943595;6360.236771587439;6369.840753981095;6375.808725738142;6380.55336126476;6084.117802918552;6092.432807696193;6099.0374119281705;6184.474319682788;6196.628202307786;6095.062334737259;6126.761967422298;6231.158793562811;6309.589876658002;6326.9876819708625;6238.469730072904;6258.624482530971;6260.968695156495;6261.530385221126;6272.027835685476;6056.182415373956;6240.438360547572;6251.187345645037;6255.655938923403;6289.654069026247;4171.0823618803815;4872.098661747588;4919.4834542373255;4940.061388336205;5016.899662006739;3534.929301413957;4410.29435290352;4417.845375411845;4422.567587826682;4456.732596044248;4905.141600219948;5903.173555702205;5905.350411454737;5907.49786268172;5918.911216331759;4819.903601927942;6161.014237721943;6185.876282474415;6313.08460273267;6320.652364119926;5321.707306098125;5843.590948633555;5888.941110588839;5892.204552385459;5893.793717219963;4923.729194894315;5263.105272103751;5363.946360536423;5418.431064738145;5485.396606373296;6416.903746443713;6441.033844923299;6443.710033048524;6460.418562329413;6516.446102081187;6298.905516417349;6301.779437719658;6301.905471418024;6322.905156422749;6330.050492426173;6228.968855155696;6234.189555357774;6271.435570798468;6282.591631543935;6471.06169605414;6140.350877192904;6155.599886007395;6161.168242287234;6171.259448866164;6192.628442293477;5295.702552874571;6139.416755408229;6185.721642526187;6223.471004964629;6229.813105606839;5994.28068631767;6038.7282572283475;6103.6032657877095;6111.1444397785235;6123.84690382744;6052.394760523913;6086.969565152146;6125.601835880653;6195.81412557624;6205.6586887721605;5893.381194974466;6058.4244496772735;6070.1198326241565;6084.178635884099;6087.391260873877;5992.970035149797;6107.267127944675;6161.353057928853;6197.318294987638;6215.347388524321;5952.732127054206;5985.820425387245;6020.969895150496;6042.1540984261355;6065 01:03:44 INFO - PROCESS | 3685 | |1;ext.html;460.10968775419565;471.20836993850315;472.95979841713455;476.3045952908753;509.67890229155626;39270.80364598177;40497.79751101244;40562.39156412656;40864.978375540624;41039.79480102595;41846.79076604613;42178;42350.36474452886;42386;42552.57447425533;9806.313558050939;9836.655717049905;9918.652847150352;9977.30158888878;9994.900050999506;9790.167835733972;9801.41191528507;9863.358881672668;9919.900800992005;9920.652777152802;9848.704538863845;9874.802503949917;9921.801563968716;9928.354656947311;9942.403455792639;9728.854067188999;9747.463889486075;9855.655052073178;9881.555330010167;9883.604655813795;9711.465869377182;9762.170215531689;9779.755506112333;9791.265655075898;9805.558749856273;39674.801625991975;39889.40165897514;39926.0018499537;40060.19879602421;40259.59740402617;2730.5631099024063;3272.181954511372;3402.1154499829977;3502.614712381636;4008.7995600219874;29261.70738292611;30095.849520752476;30113.548296775567;30449.411799997026;31026.069217923497;3187.505936579834;3316.950245746316;3349.76058858224;3350.246194606194;3386.542816719736;495793;496774;497975;500377;502605;59472.70263648654;59694.10458843121;59838.70080649569;59994.40005599975;60173.09740353898;51900.74049629728;52548.73725631347;57376.42623573711;57447.71276143593;57605.71197143987;15858.762118568231;15862.68274634524;15898.76151857723;16274.186290685418;16498.75251871223;12564.81152782709;12781.552645657357;12812.55156069533;12858.935705321413;12863.935680321725;14787.260636968109;17445;17457.91271043662;17891.910540447214;18317.3588924387;7178.928210717931;7453;7768.223177682186;7828.921710782934;7994.720184793504;832.629479881447;935.7988032572894;947.4220725357526;1054.6835949215206;1130.8021096308114;28724.856375717987;29333.12000639984;29491.410171796444;29865.55201671977;30237.848810755804;892.7946572288411;1042.593388578455;1103.3324838472608;1119.322809700136;1141.4692168141853;15042.924785376003;15229.390824367123;15325.540233793005;15418.152001640017;15606.531804045897;679095;679417;687812;688780;688808;37897.10514474265;39358.803205983786;40372.394414083814;40516.18967620631;40811.97970050773;1321.7158310963189;1333.686583652841;1349.811026456297;1352.5333759852836;1355.9864401356058;27294.45411091727;28039.859800700866;29451.41097178087;29497;29582.782800616187;38967.62196127846;41408.792956035024;46697.766511168586;47560.2865957004;48162.759186203846;446583;448047;449889;450195;450658;21808.345749627537;22481.887590561942;24630.630540541548;25097.749022509543;25226.99092036298;36119.09702257464;44496.77751611221;44755.776221118685;44827.775861120484;45347.31979020251;31749.5237571432;37269.0682732923;37473.81263093667;37833.297501613044;38591.42112868365;32475.837620811744;37773.43339849849;37916.62083379131;38134.61865381311;38570.228595428496;29319.853400733715;33175.83412082924;37108.70119545911;37986;38730.61269387383;32332.515012275304;32387.35225295528;34131.82934085314;34275.31449371049;34455.65544344525;24691.382715432257;25132.49735005253;25253;25407.491850163264;25742 01:03:44 INFO - PROCESS | 3685 | |2;jquery.html;342.64536205027804;372.4971288760174;378.22841403536785;384.99037524061913;397.6381492841513;19232;19663.803361966377;20250.594988100267;20390.286339978124;20546.280880169197;11645.825312620318;11783.410829458535;11923.701907452318;12068.818967715491;12072.336021518813;5239.397469291023;5565.1374037024325;5568.276124103861;5570.220169176318;5590.888182236353;3910.3938889472033;4123.546409894909;5463.9180412293845;5888.087346461305;6164.29110652274;6179.2893817210925;6195.163652906867;6215.12988181655;6219.315875253718;6222.688865556726;5955.464008239258;6138.109974053759;6146.262448506185;6204.534659900481;6213.96893015532;5727.140928860663;5922.31893332268;5923.7926672566255;6003.199040287896;6032.909506357408;12973;13070.673233169151;13148.60554183376;13211.735765284737;13251.536196233086;2203.889805509718;2290.80528155107;2303.355060583023;2314.1090680088287;2949.1005243400755;9910.752231194207;10404;10431.16550675944;12336.5065397384;14830.332635031342;1452.7820826875948;1841.4107485604477;1894.9052547372576;1928.257620815996;1931.3723040012053;119415.40292298482;123562;123601;130005;132860.33569832187;9538.95230523843;9646.276529260193;9862.654807081717;9932.652357167464;10276.075153236245;12343;12424.75150496985;12425.316607586488;12431.191972521758;12441.93779031099;7946.602669866599;8207.179282071864;8222.342212622976;8300.70947516834;8324;8286.295664868554;8305.211004954519;8398;8676.826463470696;8831.779205519913;3056.786024978275;3828.5022947017264;3839.347310957144;3878.379459286557;3946.0332218606627;1410.8871290296752;1546.2964351220169;1556.704226197019;1909.6562618728485;1990.8208261256557;856.4689892266709;861.7543999959981;917.8898532176048;941.9717408477757;957.6313119448923;17812;20223.898880505796;20691;21023.369298921058;21072;922.3589605224305;923.6997975657804;926.5876684875301;952.3904700991371;962.2686758063782;10215.846762298572;11054.502547385388;11070.169737269736;11101.333919964827;11118.666440006813;518844;573900;574105;586132;587801;73956.63021684857;121836;123716.76283237053;127063;129351.35324323318;951.9381240219363;957.5116690487762;986.7434467038622;986.8519722041608;988.41189492252;18212.544686382946;18359;18409.803362781375;18430.539236518656;18530.44408667742;47526.52473475221;52947.73526132345;55570;56226;56640.86718265694;78639;80011;80090.39819203467;80117.5994120049;80879;7211.567305961659;7302.1440741906035;7361.30067643573;7398.482106252726;7404.518706284074;10758.300710453796;12553.874461255271;12611.684707882376;12869.871301287241;12996.935015324863;9541.713748587554;10209.336393134423;10712.625058122929;11219.439028048402;11297.71755706114;10336.793264134825;10752.247342686249;10954.835677464685;11363.54545818158;11440.885591143982;8746.30029597618;10822.458877056273;11289.774204516028;11315.642122945299;11497.770044599229;6483.962565989419;7019.543729657556;7217.963910180625;7372.483926125335;7404.740834070782;10771.529815126634;10941.617043403443;10943.01512863846;11038.337699738042;11302.60440884598 01:03:44 INFO - PROCESS | 3685 | |3;mootools.html;1450.1806479339168;1496.8054152960115;1643.9342426302949;1849.4451664500664;1849.5191250274925;8057.234562716532;8347.373946953972;8609.440386374881;9203.539823008849;9219.723408297754;8537.701180458685;8839.292856571476;8845.071267516914;8880.866786998184;8962.86555701665;1984.9876562952854;2346.8826558672085;2376.976230237701;2394.8084153267782;2397.1130681647815;1969.3107412405686;2362.70466191726;2399.4721161344564;2408.6025805742033;2413.6138217885054;2379.1553998330605;2387.3076807725824;2391.7608239176116;2396.880155992202;2401.903923843053;2359.457324815302;2362.3503536527505;2367.029517897662;2387.307680772574;2387.844790088656;1916.32928475034;2362.055177928825;2369.8104151667826;2370.9762902370926;2372.905083796646;9025.142611451902;9028.232600228988;9042.23141033013;9066.501342426163;9121.31590130743;5219.164933610642;5356.758945847446;5360.383555891044;5367.81212657559;5378.381486129067;3478.930421391558;3529.8411571479346;3677.797721125337;3682.226732386175;3690.095926498025;1053.5575058475458;1082.4912291223113;1093.7648405592836;1120.2830188679252;1120.4509790202742;11395.689495707933;11878.821817672742;11919.761604767942;11988.70028249292;12027.458764355624;3865.4781604483596;4211.810468528924;4534.6599005074095;4779.259214821708;4825.252085926687;9746.220302375792;9804;9839.868415132209;9840.458774767456;9843.950780246196;5351.1973204019705;5386.784528618811;5409.513143817076;5417.83746487606;5425.891482170335;4139.234241665302;4393.275109606879;4470.240059189946;4878.658493905464;4959.20652695567;3269.7057264845803;3956.1296514766705;3959.8020098994934;4083.224187404384;4107.219628270619;1300.0834411739702;1309.083641450969;1313.7635225659283;1320.6632308761205;1326.2307861440534;808.3654331349979;817.0358976407776;850.5704619167404;893.647009431271;899.2401420799414;4617.746023968714;5123.538881500683;5778.508826749689;6044.546659000593;6066.150738896558;425.20698896557695;435.48830124603467;436.9235383807821;923.5797712041006;985.1675334342469;3339.46568549031;4002.139539998914;4060.1932140197327;4111.512534916393;4125;10665.986731260518;11084.783043391353;11111.277766945284;11115.444227788577;11120.22158448917;10328.380297182193;10778.353298802096;10838.891611083945;11002.50488727993;11063.336199828036;8146.063202731643;8188.467749596257;8344.83310333802;8372.790680232922;8384.203500667427;4601.401817763669;5878.882422351444;6000.129981152621;6065.787697430569;6139.631622102688;10109.545070471699;10259.127974122282;10263.12763415118;10369.9481502592;10392.220583456423;9930;10251.94874025655;10405.895941040493;10504.159667226757;10564.366138031743;6131.509479241561;7117.217106118219;7304.561726296227;7624.923750762643;7809.609519523888;8383.077861435358;8563.058063613122;8615.353848461265;8616.956915215384;8632.568371581521;7442.92557074444;7559.84045855762;7583.582902940391;7637.236276372319;7723.884141737991;7261.273872612696;7351.117865855917;7373.705051797868;7532.096148462219;7555.206703296295;6935.722571097302;7026.824329391806;7100.0414943981905;7129.429645628441;7130.465215108784;4928.8767780805765;5009.248612708123;5061.240813877947;5083.796648134181;5120.436751957207;5058.215976523645;5082.517160869711;5154.381474223117;5159.993801208729;5170.22446632993 01:03:44 INFO - PROCESS | 3685 | |4;prototype.html;285.7713828936851;304.75314994854153;311.7630600743435;316.40042120182255;317.16427214290337;5415.512603865652;5598.860028499279;5697.6866272355;5700.686462244575;5746.339170995337;5135.380955712595;5292.761825717842;5381.461853814626;5426.875574563331;5596.552275817935;3822.923541529168;3870.3420418528917;4238.87283381499;4248.915021699564;4249.745015299092;3267.738580913521;4130.182719603365;4190.6437952773895;4240.893977650552;4241.321388577828;4198.454200953872;4200.411942328076;4213;4227.91544169118;4258;3722.7952462614544;3815.67566756826;4218.978905105455;4248.362745588156;4276.679249056333;3210.887618933326;3684.2631473705364;3693.279810436966;3724.2179142380132;3735.9626403736156;3988.282109220368;4810.446798618169;4841.7579121044155;5058.696478211319;5391.56867450603;4634.698744581591;4863.294822250789;4874.634402419832;4886.998165376125;4945.109880221558;4804.975975120102;5373.489518495737;5424.345574599745;5831.825045248607;5885.617434866763;3291.325278317939;3319.93360132796;3593.9218234529535;3705.814709264526;3861.498005259298;5355.0896347621;6152.230971128609;6184.9485587450245;6199.45822212895;6208.627482351028;4078.204750073707;4083.816228269676;4111.629953304157;4114.88897997545;4121.8969525762095;4006.238814625212;4115.650169735606;4167.270727622654;4170.812313445902;4179.707420480598;4855.927161092586;4856;4865.367502224759;4878.487758785276;4889.902201955941;4833.685810422311;5846.853828654318;5983.282006159288;5998.880022399528;6033.849153771191;1298.4676282724038;1320.74529197263;1415.801787749716;1420.4673247532176;1433.8136042314432;840.6301227459898;847.8092429203504;860.3633311349556;878.9736307910773;880.8768819754864;3833.5208098987628;4029.3955906613655;4132.648724858421;4209.894752631148;4670.486246512881;4614.077184563101;4782.808687652525;4991.97664478781;5029.7736601853;5086.364204474441;4110.588941105865;4617.399738034102;4636.582707556337;4695.624350051988;4729.6452766042685;2243.596152692531;2249.537800429701;2341.97658023421;2646.2855029142024;2698.9730102699114;4368.694191406635;4551.453825540956;4572.679912406166;4931.630127740434;5169.069567477815;4618.584327410482;4663.083010271388;4896.265560165933;5126.66676666023;5165.948340516622;4451.399061126771;4729.983053643415;5007.073691367109;5134.922976155286;5254.789808407697;4456.086502267092;4469.821207151677;4548.772561371852;4592.15045216636;4853.888558477074;4292.59220374064;4543.841320463261;4856.344393506832;5054.444011158696;5058.570021548062;4276.850310239127;4466.59800617946;4534.637229021736;4640.86077417678;4733;3077.0307353183675;3497.4229252174123;3499.972523766872;3560.6973407260675;3603.3874241378503;4504.2793153095345;4584.656150788771;4841.346418233635;5114.900296436284;5852.882942341044;4761.738104404152;5545.306836645419;5807.535397168132;5878.353381127987;5889.823305300848;5460.91808622863;5852.473277405054;5921.881562368814;5930.52555795544;5933.406659334032;5575.693336866511;5726;5789.507891829073;5837.824865254049;5898.498627616529;5912.556558258234;5946.375630558729;5952.375000624872;5981.461668449686;5985.670788106696;5093.770780314969;5538.559974406764;5804.071348584206;5819;5844.386339434298 01:03:44 INFO - PROCESS | 3685 | |5;yui.html;672.7342699633642;676.9390754832065;689.1213702529275;749.4753672429298;789.2502122983163;14852.96029277951;15390.69218615627;15413.768793468107;15416.997895136808;15443.536693899187;11008.449577521113;14384.784228236558;14422.855771442282;14440.422383104664;14445.78331325031;659.80535741956;823.8517066927966;841.0622156295748;847.6651722569582;849.5327569836596;682.1711620381236;805.8710606302993;836.6862426590029;836.7196989008659;846.6782622603403;892.5001998880648;895.122779675918;896.7802888292377;897.4211633496391;898.5148020069155;880.9252711691726;881.6429346114834;881.6914080071988;885.3890815337461;889.3952112563454;639.5331408072087;639.9680015999181;656.0356276273869;660.8546119853625;667.346000919102;11585.841415858431;12809.295488748116;14780.334884930204;14956.326965286587;15000.174990375524;11604.593839215671;14289.356978935974;14516.419343226364;14701.338439770128;14845.331960061823;9917.553710083064;11850.170488065838;11984.400779960966;12003.699907502294;12076.033917286595;3779.1496913194587;3794.203217324365;3809.5428548574073;3828.942565861514;3841.154945911895;83713.16286837115;88819.55590222008;115475;117365.41317293444;118168.68240929178;10939.227620005739;11112.21974995764;11150.832737508943;11163.051140652973;11233.38216398106;22768;22951.196708115134;23036.193733219257;23040.423989400064;23091.07635694587;8101.797455063671;8130.186981301941;8134.918650813416;8253.09215986241;8280.58597070144;4402.823887044482;4474.798634061475;4493.618042466427;4500.684952053325;4513.954860451354;3626.437902125175;3713.350163721338;3748.269087527906;3750.099976005739;3755.6995440364703;610.5146408605169;662.3740565165872;667.2993356975156;687.6011913090396;688.0951548713426;634.3497914637534;636.3254949753184;651.2413038809733;652.3183273479226;678.995087270842;7729.536227826348;9382.061793820563;9408.341416100808;9419.105185007413;9548.331616786758;582.6242073862335;597.5816928150313;649.6491894377078;660.4518249852707;665.5540787672174;4458.175237581057;4510.932336014962;4587.908241835212;4657.301404789308;4741.596964258008;79901;80200;129883;133858;139754;84402.8899277523;114463.42768286105;128211;132623.33688331497;132889;104145.02332231739;104553.06745859131;108806;111212.88787112187;116154.419227905;9755.170810481188;9836.852447213441;9848.858099802448;9905.504724763588;9979.501024948579;64092;64852.675736621015;65143;65675.67162164158;67185;91355.54322228613;98033;99223;100390.99609004108;100923.99076009146;12340.259584424963;12821.32719296494;13035.348232588522;13082.869171308164;13163.473461061832;14479.493217737328;14505.564833055025;14828.18444985493;15003.549893503212;15328.846711533188;13243;13623.65940851447;13648.385822638204;13664.180149190683;14133;13351.132176408502;13648.590542283748;13714.93142534281;13856.030077894853;14108.717825643223;11498;13140.27728474943;13723.931380343034;13944.860551394357;14010.369533370818;10926.73218061948;11223.943880280873;11382.317060976367;11399.715007124556;11568.190226684388;9732.805343893224;10590.099841513558;11404.803169101262;11450.141239406912;11572.189946703988 01:03:44 INFO - PROCESS | 3685 | __end_tp_report 01:03:44 INFO - PROCESS | 3685 | __start_cc_report 01:03:44 INFO - PROCESS | 3685 | _x_x_mozilla_cycle_collect,381 01:03:44 INFO - PROCESS | 3685 | __end_cc_report 01:03:44 INFO - PROCESS | 3685 | __startTimestamp1473753824103__endTimestamp 01:03:44 INFO - PROCESS | 3685 | 01:03:44 INFO - PROCESS | 3685 | ------- Summary: start ------- 01:03:44 INFO - PROCESS | 3685 | Number of tests: 6 01:03:44 INFO - PROCESS | 3685 | 01:03:44 INFO - PROCESS | 3685 | [#0] dojo.html Cycles:165 Average:5731.94 Median:6011.97 stddev:684.93 (11.4%) stddev-sans-first:658.09 01:03:44 INFO - PROCESS | 3685 | Values: 3220.9 3320.5 3389.9 3397.0 3400.1 4738.3 5368.1 5614.4 5669.4 5698.8 5630.3 5723.9 5731.7 5742.7 5771.9 5187.1 5234.2 5248.2 5299.5 5301.2 5290.6 5322.8 5325.5 5334.4 5344.1 5320.1 5321.2 5329.3 5334.3 5344.4 5286.2 5295.0 5304.4 5304.7 5323.3 5166.9 5200.7 5269.1 5284.4 5328.6 4781.8 5792.7 5836.0 5842.9 5888.9 5183.6 6209.6 6249.1 6292.9 6294.2 6145.6 6146.7 6159.0 6199.4 6302.6 5235.8 5625.6 5928.9 5967.6 6012.0 6353.0 6360.2 6369.8 6375.8 6380.6 6084.1 6092.4 6099.0 6184.5 6196.6 6095.1 6126.8 6231.2 6309.6 6327.0 6238.5 6258.6 6261.0 6261.5 6272.0 6056.2 6240.4 6251.2 6255.7 6289.7 4171.1 4872.1 4919.5 4940.1 5016.9 3534.9 4410.3 4417.8 4422.6 4456.7 4905.1 5903.2 5905.4 5907.5 5918.9 4819.9 6161.0 6185.9 6313.1 6320.7 5321.7 5843.6 5888.9 5892.2 5893.8 4923.7 5263.1 5363.9 5418.4 5485.4 6416.9 6441.0 6443.7 6460.4 6516.4 6298.9 6301.8 6301.9 6322.9 6330.1 6229.0 6234.2 6271.4 6282.6 6471.1 6140.4 6155.6 6161.2 6171.3 6192.6 5295.7 6139.4 6185.7 6223.5 6229.8 5994.3 6038.7 6103.6 6111.1 6123.8 6052.4 6087.0 6125.6 6195.8 6205.7 5893.4 6058.4 6070.1 6084.2 6087.4 5993.0 6107.3 6161.4 6197.3 6215.3 5952.7 5985.8 6021.0 6042.2 6065.0 01:03:44 INFO - PROCESS | 3685 | 01:03:44 INFO - PROCESS | 3685 | [#1] ext.html Cycles:180 Average:66851.52 Median:39782.10 stddev:148174.26 (372.5%) stddev-sans-first:148506.08 01:03:44 INFO - PROCESS | 3685 | Values: 460.1 471.2 473.0 476.3 509.7 39270.8 40497.8 40562.4 40865.0 41039.8 41846.8 42178.0 42350.4 42386.0 42552.6 9806.3 9836.7 9918.7 9977.3 9994.9 9790.2 9801.4 9863.4 9919.9 9920.7 9848.7 9874.8 9921.8 9928.4 9942.4 9728.9 9747.5 9855.7 9881.6 9883.6 9711.5 9762.2 9779.8 9791.3 9805.6 39674.8 39889.4 39926.0 40060.2 40259.6 2730.6 3272.2 3402.1 3502.6 4008.8 29261.7 30095.8 30113.5 30449.4 31026.1 3187.5 3317.0 3349.8 3350.2 3386.5 495793.0 496774.0 497975.0 500377.0 502605.0 59472.7 59694.1 59838.7 59994.4 60173.1 51900.7 52548.7 57376.4 57447.7 57605.7 15858.8 15862.7 15898.8 16274.2 16498.8 12564.8 12781.6 12812.6 12858.9 12863.9 14787.3 17445.0 17457.9 17891.9 18317.4 7178.9 7453.0 7768.2 7828.9 7994.7 832.6 935.8 947.4 1054.7 1130.8 28724.9 29333.1 29491.4 29865.6 30237.8 892.8 1042.6 1103.3 1119.3 1141.5 15042.9 15229.4 15325.5 15418.2 15606.5 679095.0 679417.0 687812.0 688780.0 688808.0 37897.1 39358.8 40372.4 40516.2 40812.0 1321.7 1333.7 1349.8 1352.5 1356.0 27294.5 28039.9 29451.4 29497.0 29582.8 38967.6 41408.8 46697.8 47560.3 48162.8 446583.0 448047.0 449889.0 450195.0 450658.0 21808.3 22481.9 24630.6 25097.7 25227.0 36119.1 44496.8 44755.8 44827.8 45347.3 31749.5 37269.1 37473.8 37833.3 38591.4 32475.8 37773.4 37916.6 38134.6 38570.2 29319.9 33175.8 37108.7 37986.0 38730.6 32332.5 32387.4 34131.8 34275.3 34455.7 24691.4 25132.5 25253.0 25407.5 25742.0 01:03:44 INFO - PROCESS | 3685 | 01:03:44 INFO - PROCESS | 3685 | [#2] jquery.html Cycles:180 Average:33327.68 Median:2103.37 stddev:95332.13 (4532.4%) stddev-sans-first:95567.39 01:03:44 INFO - PROCESS | 3685 | Values: 342.6 372.5 378.2 385.0 397.6 19232.0 19663.8 20250.6 20390.3 20546.3 11645.8 11783.4 11923.7 12068.8 12072.3 5239.4 5565.1 5568.3 5570.2 5590.9 3910.4 4123.5 5463.9 5888.1 6164.3 6179.3 6195.2 6215.1 6219.3 6222.7 5955.5 6138.1 6146.3 6204.5 6214.0 5727.1 5922.3 5923.8 6003.2 6032.9 12973.0 13070.7 13148.6 13211.7 13251.5 2203.9 2290.8 2303.4 2314.1 2949.1 9910.8 10404.0 10431.2 12336.5 14830.3 1452.8 1841.4 1894.9 1928.3 1931.4 119415.4 123562.0 123601.0 130005.0 132860.3 9539.0 9646.3 9862.7 9932.7 10276.1 12343.0 12424.8 12425.3 12431.2 12441.9 7946.6 8207.2 8222.3 8300.7 8324.0 8286.3 8305.2 8398.0 8676.8 8831.8 3056.8 3828.5 3839.3 3878.4 3946.0 1410.9 1546.3 1556.7 1909.7 1990.8 856.5 861.8 917.9 942.0 957.6 17812.0 20223.9 20691.0 21023.4 21072.0 922.4 923.7 926.6 952.4 962.3 10215.8 11054.5 11070.2 11101.3 11118.7 518844.0 573900.0 574105.0 586132.0 587801.0 73956.6 121836.0 123716.8 127063.0 129351.4 951.9 957.5 986.7 986.9 988.4 18212.5 18359.0 18409.8 18430.5 18530.4 47526.5 52947.7 55570.0 56226.0 56640.9 78639.0 80011.0 80090.4 80117.6 80879.0 7211.6 7302.1 7361.3 7398.5 7404.5 10758.3 12553.9 12611.7 12869.9 12996.9 9541.7 10209.3 10712.6 11219.4 11297.7 10336.8 10752.2 10954.8 11363.5 11440.9 8746.3 10822.5 11289.8 11315.6 11497.8 6484.0 7019.5 7218.0 7372.5 7404.7 10771.5 10941.6 10943.0 11038.3 11302.6 01:03:44 INFO - PROCESS | 3685 | 01:03:44 INFO - PROCESS | 3685 | [#3] mootools.html Cycles:180 Average:5737.95 Median:4802.26 stddev:3284.40 (68.4%) stddev-sans-first:3277.81 01:03:44 INFO - PROCESS | 3685 | Values: 1450.2 1496.8 1643.9 1849.4 1849.5 8057.2 8347.4 8609.4 9203.5 9219.7 8537.7 8839.3 8845.1 8880.9 8962.9 1985.0 2346.9 2377.0 2394.8 2397.1 1969.3 2362.7 2399.5 2408.6 2413.6 2379.2 2387.3 2391.8 2396.9 2401.9 2359.5 2362.4 2367.0 2387.3 2387.8 1916.3 2362.1 2369.8 2371.0 2372.9 9025.1 9028.2 9042.2 9066.5 9121.3 5219.2 5356.8 5360.4 5367.8 5378.4 3478.9 3529.8 3677.8 3682.2 3690.1 1053.6 1082.5 1093.8 1120.3 1120.5 11395.7 11878.8 11919.8 11988.7 12027.5 3865.5 4211.8 4534.7 4779.3 4825.3 9746.2 9804.0 9839.9 9840.5 9844.0 5351.2 5386.8 5409.5 5417.8 5425.9 4139.2 4393.3 4470.2 4878.7 4959.2 3269.7 3956.1 3959.8 4083.2 4107.2 1300.1 1309.1 1313.8 1320.7 1326.2 808.4 817.0 850.6 893.6 899.2 4617.7 5123.5 5778.5 6044.5 6066.2 425.2 435.5 436.9 923.6 985.2 3339.5 4002.1 4060.2 4111.5 4125.0 10666.0 11084.8 11111.3 11115.4 11120.2 10328.4 10778.4 10838.9 11002.5 11063.3 8146.1 8188.5 8344.8 8372.8 8384.2 4601.4 5878.9 6000.1 6065.8 6139.6 10109.5 10259.1 10263.1 10369.9 10392.2 9930.0 10251.9 10405.9 10504.2 10564.4 6131.5 7117.2 7304.6 7624.9 7809.6 8383.1 8563.1 8615.4 8617.0 8632.6 7442.9 7559.8 7583.6 7637.2 7723.9 7261.3 7351.1 7373.7 7532.1 7555.2 6935.7 7026.8 7100.0 7129.4 7130.5 4928.9 5009.2 5061.2 5083.8 5120.4 5058.2 5082.5 5154.4 5160.0 5170.2 01:03:44 INFO - PROCESS | 3685 | 01:03:44 INFO - PROCESS | 3685 | [#4] prototype.html Cycles:180 Average:4408.52 Median:4729.81 stddev:1362.80 (28.8%) stddev-sans-first:1331.03 01:03:44 INFO - PROCESS | 3685 | Values: 285.8 304.8 311.8 316.4 317.2 5415.5 5598.9 5697.7 5700.7 5746.3 5135.4 5292.8 5381.5 5426.9 5596.6 3822.9 3870.3 4238.9 4248.9 4249.7 3267.7 4130.2 4190.6 4240.9 4241.3 4198.5 4200.4 4213.0 4227.9 4258.0 3722.8 3815.7 4219.0 4248.4 4276.7 3210.9 3684.3 3693.3 3724.2 3736.0 3988.3 4810.4 4841.8 5058.7 5391.6 4634.7 4863.3 4874.6 4887.0 4945.1 4805.0 5373.5 5424.3 5831.8 5885.6 3291.3 3319.9 3593.9 3705.8 3861.5 5355.1 6152.2 6184.9 6199.5 6208.6 4078.2 4083.8 4111.6 4114.9 4121.9 4006.2 4115.7 4167.3 4170.8 4179.7 4855.9 4856.0 4865.4 4878.5 4889.9 4833.7 5846.9 5983.3 5998.9 6033.8 1298.5 1320.7 1415.8 1420.5 1433.8 840.6 847.8 860.4 879.0 880.9 3833.5 4029.4 4132.6 4209.9 4670.5 4614.1 4782.8 4992.0 5029.8 5086.4 4110.6 4617.4 4636.6 4695.6 4729.6 2243.6 2249.5 2342.0 2646.3 2699.0 4368.7 4551.5 4572.7 4931.6 5169.1 4618.6 4663.1 4896.3 5126.7 5165.9 4451.4 4730.0 5007.1 5134.9 5254.8 4456.1 4469.8 4548.8 4592.2 4853.9 4292.6 4543.8 4856.3 5054.4 5058.6 4276.9 4466.6 4534.6 4640.9 4733.0 3077.0 3497.4 3500.0 3560.7 3603.4 4504.3 4584.7 4841.3 5114.9 5852.9 4761.7 5545.3 5807.5 5878.4 5889.8 5460.9 5852.5 5921.9 5930.5 5933.4 5575.7 5726.0 5789.5 5837.8 5898.5 5912.6 5946.4 5952.4 5981.5 5985.7 5093.8 5538.6 5804.1 5819.0 5844.4 01:03:44 INFO - PROCESS | 3685 | 01:03:44 INFO - PROCESS | 3685 | [#5] yui.html Cycles:180 Average:23663.60 Median:3786.68 stddev:36517.34 (964.4%) stddev-sans-first:36578.98 01:03:44 INFO - PROCESS | 3685 | Values: 672.7 676.9 689.1 749.5 789.3 14853.0 15390.7 15413.8 15417.0 15443.5 11008.4 14384.8 14422.9 14440.4 14445.8 659.8 823.9 841.1 847.7 849.5 682.2 805.9 836.7 836.7 846.7 892.5 895.1 896.8 897.4 898.5 880.9 881.6 881.7 885.4 889.4 639.5 640.0 656.0 660.9 667.3 11585.8 12809.3 14780.3 14956.3 15000.2 11604.6 14289.4 14516.4 14701.3 14845.3 9917.6 11850.2 11984.4 12003.7 12076.0 3779.1 3794.2 3809.5 3828.9 3841.2 83713.2 88819.6 115475.0 117365.4 118168.7 10939.2 11112.2 11150.8 11163.1 11233.4 22768.0 22951.2 23036.2 23040.4 23091.1 8101.8 8130.2 8134.9 8253.1 8280.6 4402.8 4474.8 4493.6 4500.7 4514.0 3626.4 3713.4 3748.3 3750.1 3755.7 610.5 662.4 667.3 687.6 688.1 634.3 636.3 651.2 652.3 679.0 7729.5 9382.1 9408.3 9419.1 9548.3 582.6 597.6 649.6 660.5 665.6 4458.2 4510.9 4587.9 4657.3 4741.6 79901.0 80200.0 129883.0 133858.0 139754.0 84402.9 114463.4 128211.0 132623.3 132889.0 104145.0 104553.1 108806.0 111212.9 116154.4 9755.2 9836.9 9848.9 9905.5 9979.5 64092.0 64852.7 65143.0 65675.7 67185.0 91355.5 98033.0 99223.0 100391.0 100924.0 12340.3 12821.3 13035.3 13082.9 13163.5 14479.5 14505.6 14828.2 15003.5 15328.8 13243.0 13623.7 13648.4 13664.2 14133.0 13351.1 13648.6 13714.9 13856.0 14108.7 11498.0 13140.3 13723.9 13944.9 14010.4 10926.7 11223.9 11382.3 11399.7 11568.2 9732.8 10590.1 11404.8 11450.1 11572.2 01:03:44 INFO - PROCESS | 3685 | -------- Summary: end -------- 01:03:44 INFO - PROCESS | 3685 | 01:03:44 INFO - TEST-INFO | 3685: exit 0 01:03:44 INFO - TEST-OK | dromaeo_css | took 1114791ms 01:03:44 INFO - TEST-START | kraken 01:03:44 INFO - operating with platform_type : mac_ 01:03:44 INFO - Initialising browser for kraken test... 01:03:44 INFO - TEST-INFO | started process 3800 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmpW2lRzw/profile http://localhost:59686/getInfo.html) 01:03:47 INFO - PROCESS | 3800 | __metrics Screen width/height:1600/1200 01:03:47 INFO - PROCESS | 3800 | colorDepth:24 01:03:47 INFO - PROCESS | 3800 | Browser inner width/height: 1024/689 01:03:47 INFO - PROCESS | 3800 | __metrics 01:03:47 ERROR - PROCESS | 3800 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 01:03:48 INFO - TEST-INFO | 3800: exit 0 01:03:48 INFO - Browser initialized. 01:03:48 INFO - Running cycle 1/1 for kraken test... 01:03:48 INFO - TEST-INFO | started process 3802 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmpW2lRzw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:04:00 INFO - PROCESS | 3802 | RSS: Main: 245624832 01:04:00 INFO - PROCESS | 3802 | 01:04:03 INFO - PROCESS | 3802 | Cycle 1(1): loaded http://localhost:59686/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59686/tests/kraken/driver.html?testName=audio-beat-detection) 01:04:04 INFO - PROCESS | 3802 | RSS: Main: 258224128 01:04:04 INFO - PROCESS | 3802 | 01:04:07 INFO - PROCESS | 3802 | Cycle 1(1): loaded http://localhost:59686/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59686/tests/kraken/driver.html?testName=audio-dft) 01:04:08 INFO - PROCESS | 3802 | RSS: Main: 266133504 01:04:08 INFO - PROCESS | 3802 | 01:04:11 INFO - PROCESS | 3802 | Cycle 1(1): loaded http://localhost:59686/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59686/tests/kraken/driver.html?testName=audio-fft) 01:04:12 INFO - PROCESS | 3802 | RSS: Main: 264040448 01:04:12 INFO - PROCESS | 3802 | 01:04:14 INFO - PROCESS | 3802 | Cycle 1(1): loaded http://localhost:59686/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59686/tests/kraken/driver.html?testName=audio-oscillator) 01:04:14 INFO - PROCESS | 3802 | RSS: Main: 265162752 01:04:14 INFO - PROCESS | 3802 | 01:04:17 INFO - PROCESS | 3802 | Cycle 1(1): loaded http://localhost:59686/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59686/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:04:17 INFO - PROCESS | 3802 | RSS: Main: 266924032 01:04:17 INFO - PROCESS | 3802 | 01:04:20 INFO - PROCESS | 3802 | Cycle 1(1): loaded http://localhost:59686/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59686/tests/kraken/driver.html?testName=imaging-darkroom) 01:04:21 INFO - PROCESS | 3802 | RSS: Main: 267460608 01:04:21 INFO - PROCESS | 3802 | 01:04:24 INFO - PROCESS | 3802 | Cycle 1(1): loaded http://localhost:59686/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59686/tests/kraken/driver.html?testName=imaging-desaturate) 01:04:25 INFO - PROCESS | 3802 | RSS: Main: 267886592 01:04:25 INFO - PROCESS | 3802 | 01:04:28 INFO - PROCESS | 3802 | Cycle 1(1): loaded http://localhost:59686/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59686/tests/kraken/driver.html?testName=json-parse-financial) 01:04:28 INFO - PROCESS | 3802 | RSS: Main: 267776000 01:04:28 INFO - PROCESS | 3802 | 01:04:30 INFO - PROCESS | 3802 | Cycle 1(1): loaded http://localhost:59686/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59686/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:04:30 INFO - PROCESS | 3802 | RSS: Main: 267579392 01:04:30 INFO - PROCESS | 3802 | 01:04:32 INFO - PROCESS | 3802 | Cycle 1(1): loaded http://localhost:59686/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59686/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:04:33 INFO - PROCESS | 3802 | RSS: Main: 267669504 01:04:33 INFO - PROCESS | 3802 | 01:04:35 INFO - PROCESS | 3802 | Cycle 1(1): loaded http://localhost:59686/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59686/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:04:36 INFO - PROCESS | 3802 | RSS: Main: 268083200 01:04:36 INFO - PROCESS | 3802 | 01:04:39 INFO - PROCESS | 3802 | Cycle 1(1): loaded http://localhost:59686/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59686/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:04:40 INFO - PROCESS | 3802 | RSS: Main: 268140544 01:04:40 INFO - PROCESS | 3802 | 01:04:42 INFO - PROCESS | 3802 | Cycle 1(1): loaded http://localhost:59686/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59686/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:04:43 INFO - PROCESS | 3802 | RSS: Main: 268120064 01:04:43 INFO - PROCESS | 3802 | 01:04:45 INFO - PROCESS | 3802 | Cycle 1(1): loaded http://localhost:59686/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59686/tests/kraken/driver.html?testName=ai-astar) 01:04:45 INFO - PROCESS | 3802 | RSS: Main: 270036992 01:04:45 INFO - PROCESS | 3802 | 01:04:45 INFO - PROCESS | 3802 | __start_tp_report 01:04:45 INFO - PROCESS | 3802 | _x_x_mozilla_page_load 01:04:45 INFO - PROCESS | 3802 | _x_x_mozilla_page_load_details 01:04:45 INFO - PROCESS | 3802 | |i|pagename|runs| 01:04:45 INFO - PROCESS | 3802 | |0;ai-astar;116;115;120;169;159;119;100;116;120;115 01:04:45 INFO - PROCESS | 3802 | |1;audio-beat-detection;151;110;140;162;99;122;170;96;203;143 01:04:45 INFO - PROCESS | 3802 | |2;audio-dft;206;245;267;202;197;199;218;252;245;197 01:04:45 INFO - PROCESS | 3802 | |3;audio-fft;61;106;108;177;60;59;59;60;132;59 01:04:45 INFO - PROCESS | 3802 | |4;audio-oscillator;67;71;129;75;91;64;116;94;102;147 01:04:45 INFO - PROCESS | 3802 | |5;imaging-gaussian-blur;75;128;134;88;98;86;86;74;135;90 01:04:45 INFO - PROCESS | 3802 | |6;imaging-darkroom;95;96;96;90;98;140;149;117;145;149 01:04:45 INFO - PROCESS | 3802 | |7;imaging-desaturate;94;99;108;121;93;122;150;102;95;95 01:04:45 INFO - PROCESS | 3802 | |8;json-parse-financial;56;58;57;57;54;57;56;58;55;58 01:04:45 INFO - PROCESS | 3802 | |9;json-stringify-tinderbox;65;67;81;71;76;109;103;88;70;71 01:04:45 INFO - PROCESS | 3802 | |10;stanford-crypto-aes;88;86;86;89;109;114;107;79;92;100 01:04:45 INFO - PROCESS | 3802 | |11;stanford-crypto-ccm;138;141;144;153;150;185;158;147;167;158 01:04:45 INFO - PROCESS | 3802 | |12;stanford-crypto-pbkdf2;164;168;171;175;173;171;167;177;150;153 01:04:45 INFO - PROCESS | 3802 | |13;stanford-crypto-sha256-iterative;66;68;69;67;69;67;70;66;67;67 01:04:45 INFO - PROCESS | 3802 | __end_tp_report 01:04:45 INFO - PROCESS | 3802 | __start_cc_report 01:04:45 INFO - PROCESS | 3802 | _x_x_mozilla_cycle_collect,954 01:04:45 INFO - PROCESS | 3802 | __end_cc_report 01:04:45 INFO - PROCESS | 3802 | __startTimestamp1473753885455__endTimestamp 01:04:45 INFO - PROCESS | 3802 | 01:04:45 INFO - PROCESS | 3802 | ------- Summary: start ------- 01:04:45 INFO - PROCESS | 3802 | Number of tests: 14 01:04:45 INFO - PROCESS | 3802 | 01:04:45 INFO - PROCESS | 3802 | [#0] ai-astar Cycles:10 Average:124.90 Median:119.50 stddev:21.51 (18.0%) stddev-sans-first:22.57 01:04:45 INFO - PROCESS | 3802 | Values: 116.0 115.0 120.0 169.0 159.0 119.0 100.0 116.0 120.0 115.0 01:04:45 INFO - PROCESS | 3802 | 01:04:45 INFO - PROCESS | 3802 | [#1] audio-beat-detection Cycles:10 Average:139.60 Median:166.00 stddev:33.87 (20.4%) stddev-sans-first:35.67 01:04:45 INFO - PROCESS | 3802 | Values: 151.0 110.0 140.0 162.0 99.0 122.0 170.0 96.0 203.0 143.0 01:04:45 INFO - PROCESS | 3802 | 01:04:45 INFO - PROCESS | 3802 | [#2] audio-dft Cycles:10 Average:222.80 Median:231.50 stddev:26.72 (11.5%) stddev-sans-first:27.65 01:04:45 INFO - PROCESS | 3802 | Values: 206.0 245.0 267.0 202.0 197.0 199.0 218.0 252.0 245.0 197.0 01:04:45 INFO - PROCESS | 3802 | 01:04:45 INFO - PROCESS | 3802 | [#3] audio-fft Cycles:10 Average:88.10 Median:59.00 stddev:41.37 (70.1%) stddev-sans-first:42.70 01:04:45 INFO - PROCESS | 3802 | Values: 61.0 106.0 108.0 177.0 60.0 59.0 59.0 60.0 132.0 59.0 01:04:45 INFO - PROCESS | 3802 | 01:04:45 INFO - PROCESS | 3802 | [#4] audio-oscillator Cycles:10 Average:95.60 Median:69.00 stddev:28.06 (40.7%) stddev-sans-first:27.78 01:04:45 INFO - PROCESS | 3802 | Values: 67.0 71.0 129.0 75.0 91.0 64.0 116.0 94.0 102.0 147.0 01:04:45 INFO - PROCESS | 3802 | 01:04:45 INFO - PROCESS | 3802 | [#5] imaging-gaussian-blur Cycles:10 Average:99.40 Median:86.00 stddev:23.81 (27.7%) stddev-sans-first:23.56 01:04:45 INFO - PROCESS | 3802 | Values: 75.0 128.0 134.0 88.0 98.0 86.0 86.0 74.0 135.0 90.0 01:04:45 INFO - PROCESS | 3802 | 01:04:45 INFO - PROCESS | 3802 | [#6] imaging-darkroom Cycles:10 Average:117.50 Median:92.50 stddev:25.42 (27.5%) stddev-sans-first:25.62 01:04:45 INFO - PROCESS | 3802 | Values: 95.0 96.0 96.0 90.0 98.0 140.0 149.0 117.0 145.0 149.0 01:04:45 INFO - PROCESS | 3802 | 01:04:45 INFO - PROCESS | 3802 | [#7] imaging-desaturate Cycles:10 Average:107.90 Median:93.50 stddev:18.27 (19.5%) stddev-sans-first:18.68 01:04:45 INFO - PROCESS | 3802 | Values: 94.0 99.0 108.0 121.0 93.0 122.0 150.0 102.0 95.0 95.0 01:04:45 INFO - PROCESS | 3802 | 01:04:45 INFO - PROCESS | 3802 | [#8] json-parse-financial Cycles:10 Average:56.60 Median:57.00 stddev:1.35 (2.4%) stddev-sans-first:1.41 01:04:45 INFO - PROCESS | 3802 | Values: 56.0 58.0 57.0 57.0 54.0 57.0 56.0 58.0 55.0 58.0 01:04:45 INFO - PROCESS | 3802 | 01:04:45 INFO - PROCESS | 3802 | [#9] json-stringify-tinderbox Cycles:10 Average:80.10 Median:71.00 stddev:15.30 (21.5%) stddev-sans-first:15.22 01:04:45 INFO - PROCESS | 3802 | Values: 65.0 67.0 81.0 71.0 76.0 109.0 103.0 88.0 70.0 71.0 01:04:45 INFO - PROCESS | 3802 | 01:04:45 INFO - PROCESS | 3802 | [#10] stanford-crypto-aes Cycles:10 Average:95.00 Median:86.00 stddev:11.73 (13.6%) stddev-sans-first:12.16 01:04:45 INFO - PROCESS | 3802 | Values: 88.0 86.0 86.0 89.0 109.0 114.0 107.0 79.0 92.0 100.0 01:04:45 INFO - PROCESS | 3802 | 01:04:45 INFO - PROCESS | 3802 | [#11] stanford-crypto-ccm Cycles:10 Average:154.10 Median:155.50 stddev:13.96 (9.0%) stddev-sans-first:13.53 01:04:45 INFO - PROCESS | 3802 | Values: 138.0 141.0 144.0 153.0 150.0 185.0 158.0 147.0 167.0 158.0 01:04:45 INFO - PROCESS | 3802 | 01:04:45 INFO - PROCESS | 3802 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.90 Median:171.00 stddev:8.99 (5.3%) stddev-sans-first:9.47 01:04:45 INFO - PROCESS | 3802 | Values: 164.0 168.0 171.0 175.0 173.0 171.0 167.0 177.0 150.0 153.0 01:04:45 INFO - PROCESS | 3802 | 01:04:45 INFO - PROCESS | 3802 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:67.50 stddev:1.35 (2.0%) stddev-sans-first:1.30 01:04:45 INFO - PROCESS | 3802 | Values: 66.0 68.0 69.0 67.0 69.0 67.0 70.0 66.0 67.0 67.0 01:04:45 INFO - PROCESS | 3802 | -------- Summary: end -------- 01:04:45 INFO - PROCESS | 3802 | 01:04:46 INFO - TEST-INFO | 3802: exit 0 01:04:46 INFO - TEST-OK | kraken | took 61541ms 01:04:46 INFO - SUITE-END | took 1176s 01:04:46 INFO - Completed test suite (00:19:36) 01:04:46 INFO - javascript benchmark 01:04:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7190.342442311374, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3220.871165153394, 3320.4687250039983, 3389.8983030509094, 3396.966030339697, 3400.1159698478386, 4738.28925661151, 5368.114261146906, 5614.410486898878, 5669.404712505189, 5698.772049118035, 5630.296212973379, 5723.9141412878735, 5731.713414329288, 5742.655440673552, 5771.884562308751, 5187.066328060938, 5234.162533994561, 5248.160294352904, 5299.54953828923, 5301.151815709487, 5290.629655924087, 5322.760475778599, 5325.4940780625975, 5334.413214546396, 5344.118220493614, 5320.095583750753, 5321.175217841241, 5329.333833270841, 5334.306540149775, 5344.438833922441, 5286.180642000496, 5294.973525132388, 5304.389995150568, 5304.655197412195, 5323.334583177103, 5166.948330516722, 5200.739963001834, 5269.051570717269, 5284.445133260991, 5328.6269961102735, 4781.832635857728, 5792.652440853562, 5836.0, 5842.912356314659, 5888.911666325009, 5183.61122915779, 6209.565330426872, 6249.0626406039, 6292.905606415908, 6294.244690637152, 6145.569810113294, 6146.692665366714, 6158.969205153946, 6199.411055949678, 6302.621842689453, 5235.821940063455, 5625.634333768292, 5928.851778705481, 5967.5524335675, 6011.969940150271, 6353.047042943595, 6360.236771587439, 6369.840753981095, 6375.808725738142, 6380.55336126476, 6084.117802918552, 6092.432807696193, 6099.0374119281705, 6184.474319682788, 6196.628202307786, 6095.062334737259, 6126.761967422298, 6231.158793562811, 6309.589876658002, 6326.9876819708625, 6238.469730072904, 6258.624482530971, 6260.968695156495, 6261.530385221126, 6272.027835685476, 6056.182415373956, 6240.438360547572, 6251.187345645037, 6255.655938923403, 6289.654069026247, 4171.0823618803815, 4872.098661747588, 4919.4834542373255, 4940.061388336205, 5016.899662006739, 3534.929301413957, 4410.29435290352, 4417.845375411845, 4422.567587826682, 4456.732596044248, 4905.141600219948, 5903.173555702205, 5905.350411454737, 5907.49786268172, 5918.911216331759, 4819.903601927942, 6161.014237721943, 6185.876282474415, 6313.08460273267, 6320.652364119926, 5321.707306098125, 5843.590948633555, 5888.941110588839, 5892.204552385459, 5893.793717219963, 4923.729194894315, 5263.105272103751, 5363.946360536423, 5418.431064738145, 5485.396606373296, 6416.903746443713, 6441.033844923299, 6443.710033048524, 6460.418562329413, 6516.446102081187, 6298.905516417349, 6301.779437719658, 6301.905471418024, 6322.905156422749, 6330.050492426173, 6228.968855155696, 6234.189555357774, 6271.435570798468, 6282.591631543935, 6471.06169605414, 6140.350877192904, 6155.599886007395, 6161.168242287234, 6171.259448866164, 6192.628442293477, 5295.702552874571, 6139.416755408229, 6185.721642526187, 6223.471004964629, 6229.813105606839, 5994.28068631767, 6038.7282572283475, 6103.6032657877095, 6111.1444397785235, 6123.84690382744, 6052.394760523913, 6086.969565152146, 6125.601835880653, 6195.81412557624, 6205.6586887721605, 5893.381194974466, 6058.4244496772735, 6070.1198326241565, 6084.178635884099, 6087.391260873877, 5992.970035149797, 6107.267127944675, 6161.353057928853, 6197.318294987638, 6215.347388524321, 5952.732127054206, 5985.820425387245, 6020.969895150496, 6042.1540984261355, 6065.0], "value": 5688.286752318612, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [460.10968775419565, 471.20836993850315, 472.95979841713455, 476.3045952908753, 509.67890229155626, 39270.80364598177, 40497.79751101244, 40562.39156412656, 40864.978375540624, 41039.79480102595, 41846.79076604613, 42178.0, 42350.36474452886, 42386.0, 42552.57447425533, 9806.313558050939, 9836.655717049905, 9918.652847150352, 9977.30158888878, 9994.900050999506, 9790.167835733972, 9801.41191528507, 9863.358881672668, 9919.900800992005, 9920.652777152802, 9848.704538863845, 9874.802503949917, 9921.801563968716, 9928.354656947311, 9942.403455792639, 9728.854067188999, 9747.463889486075, 9855.655052073178, 9881.555330010167, 9883.604655813795, 9711.465869377182, 9762.170215531689, 9779.755506112333, 9791.265655075898, 9805.558749856273, 39674.801625991975, 39889.40165897514, 39926.0018499537, 40060.19879602421, 40259.59740402617, 2730.5631099024063, 3272.181954511372, 3402.1154499829977, 3502.614712381636, 4008.7995600219874, 29261.70738292611, 30095.849520752476, 30113.548296775567, 30449.411799997026, 31026.069217923497, 3187.505936579834, 3316.950245746316, 3349.76058858224, 3350.246194606194, 3386.542816719736, 495793.0, 496774.0, 497975.0, 500377.0, 502605.0, 59472.70263648654, 59694.10458843121, 59838.70080649569, 59994.40005599975, 60173.09740353898, 51900.74049629728, 52548.73725631347, 57376.42623573711, 57447.71276143593, 57605.71197143987, 15858.762118568231, 15862.68274634524, 15898.76151857723, 16274.186290685418, 16498.75251871223, 12564.81152782709, 12781.552645657357, 12812.55156069533, 12858.935705321413, 12863.935680321725, 14787.260636968109, 17445.0, 17457.91271043662, 17891.910540447214, 18317.3588924387, 7178.928210717931, 7453.0, 7768.223177682186, 7828.921710782934, 7994.720184793504, 832.629479881447, 935.7988032572894, 947.4220725357526, 1054.6835949215206, 1130.8021096308114, 28724.856375717987, 29333.12000639984, 29491.410171796444, 29865.55201671977, 30237.848810755804, 892.7946572288411, 1042.593388578455, 1103.3324838472608, 1119.322809700136, 1141.4692168141853, 15042.924785376003, 15229.390824367123, 15325.540233793005, 15418.152001640017, 15606.531804045897, 679095.0, 679417.0, 687812.0, 688780.0, 688808.0, 37897.10514474265, 39358.803205983786, 40372.394414083814, 40516.18967620631, 40811.97970050773, 1321.7158310963189, 1333.686583652841, 1349.811026456297, 1352.5333759852836, 1355.9864401356058, 27294.45411091727, 28039.859800700866, 29451.41097178087, 29497.0, 29582.782800616187, 38967.62196127846, 41408.792956035024, 46697.766511168586, 47560.2865957004, 48162.759186203846, 446583.0, 448047.0, 449889.0, 450195.0, 450658.0, 21808.345749627537, 22481.887590561942, 24630.630540541548, 25097.749022509543, 25226.99092036298, 36119.09702257464, 44496.77751611221, 44755.776221118685, 44827.775861120484, 45347.31979020251, 31749.5237571432, 37269.0682732923, 37473.81263093667, 37833.297501613044, 38591.42112868365, 32475.837620811744, 37773.43339849849, 37916.62083379131, 38134.61865381311, 38570.228595428496, 29319.853400733715, 33175.83412082924, 37108.70119545911, 37986.0, 38730.61269387383, 32332.515012275304, 32387.35225295528, 34131.82934085314, 34275.31449371049, 34455.65544344525, 24691.382715432257, 25132.49735005253, 25253.0, 25407.491850163264, 25742.0], "value": 19295.878273212955, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.64536205027804, 372.4971288760174, 378.22841403536785, 384.99037524061913, 397.6381492841513, 19232.0, 19663.803361966377, 20250.594988100267, 20390.286339978124, 20546.280880169197, 11645.825312620318, 11783.410829458535, 11923.701907452318, 12068.818967715491, 12072.336021518813, 5239.397469291023, 5565.1374037024325, 5568.276124103861, 5570.220169176318, 5590.888182236353, 3910.3938889472033, 4123.546409894909, 5463.9180412293845, 5888.087346461305, 6164.29110652274, 6179.2893817210925, 6195.163652906867, 6215.12988181655, 6219.315875253718, 6222.688865556726, 5955.464008239258, 6138.109974053759, 6146.262448506185, 6204.534659900481, 6213.96893015532, 5727.140928860663, 5922.31893332268, 5923.7926672566255, 6003.199040287896, 6032.909506357408, 12973.0, 13070.673233169151, 13148.60554183376, 13211.735765284737, 13251.536196233086, 2203.889805509718, 2290.80528155107, 2303.355060583023, 2314.1090680088287, 2949.1005243400755, 9910.752231194207, 10404.0, 10431.16550675944, 12336.5065397384, 14830.332635031342, 1452.7820826875948, 1841.4107485604477, 1894.9052547372576, 1928.257620815996, 1931.3723040012053, 119415.40292298482, 123562.0, 123601.0, 130005.0, 132860.33569832187, 9538.95230523843, 9646.276529260193, 9862.654807081717, 9932.652357167464, 10276.075153236245, 12343.0, 12424.75150496985, 12425.316607586488, 12431.191972521758, 12441.93779031099, 7946.602669866599, 8207.179282071864, 8222.342212622976, 8300.70947516834, 8324.0, 8286.295664868554, 8305.211004954519, 8398.0, 8676.826463470696, 8831.779205519913, 3056.786024978275, 3828.5022947017264, 3839.347310957144, 3878.379459286557, 3946.0332218606627, 1410.8871290296752, 1546.2964351220169, 1556.704226197019, 1909.6562618728485, 1990.8208261256557, 856.4689892266709, 861.7543999959981, 917.8898532176048, 941.9717408477757, 957.6313119448923, 17812.0, 20223.898880505796, 20691.0, 21023.369298921058, 21072.0, 922.3589605224305, 923.6997975657804, 926.5876684875301, 952.3904700991371, 962.2686758063782, 10215.846762298572, 11054.502547385388, 11070.169737269736, 11101.333919964827, 11118.666440006813, 518844.0, 573900.0, 574105.0, 586132.0, 587801.0, 73956.63021684857, 121836.0, 123716.76283237053, 127063.0, 129351.35324323318, 951.9381240219363, 957.5116690487762, 986.7434467038622, 986.8519722041608, 988.41189492252, 18212.544686382946, 18359.0, 18409.803362781375, 18430.539236518656, 18530.44408667742, 47526.52473475221, 52947.73526132345, 55570.0, 56226.0, 56640.86718265694, 78639.0, 80011.0, 80090.39819203467, 80117.5994120049, 80879.0, 7211.567305961659, 7302.1440741906035, 7361.30067643573, 7398.482106252726, 7404.518706284074, 10758.300710453796, 12553.874461255271, 12611.684707882376, 12869.871301287241, 12996.935015324863, 9541.713748587554, 10209.336393134423, 10712.625058122929, 11219.439028048402, 11297.71755706114, 10336.793264134825, 10752.247342686249, 10954.835677464685, 11363.54545818158, 11440.885591143982, 8746.30029597618, 10822.458877056273, 11289.774204516028, 11315.642122945299, 11497.770044599229, 6483.962565989419, 7019.543729657556, 7217.963910180625, 7372.483926125335, 7404.740834070782, 10771.529815126634, 10941.617043403443, 10943.01512863846, 11038.337699738042, 11302.60440884598], "value": 8984.041969355683, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1450.1806479339168, 1496.8054152960115, 1643.9342426302949, 1849.4451664500664, 1849.5191250274925, 8057.234562716532, 8347.373946953972, 8609.440386374881, 9203.539823008849, 9219.723408297754, 8537.701180458685, 8839.292856571476, 8845.071267516914, 8880.866786998184, 8962.86555701665, 1984.9876562952854, 2346.8826558672085, 2376.976230237701, 2394.8084153267782, 2397.1130681647815, 1969.3107412405686, 2362.70466191726, 2399.4721161344564, 2408.6025805742033, 2413.6138217885054, 2379.1553998330605, 2387.3076807725824, 2391.7608239176116, 2396.880155992202, 2401.903923843053, 2359.457324815302, 2362.3503536527505, 2367.029517897662, 2387.307680772574, 2387.844790088656, 1916.32928475034, 2362.055177928825, 2369.8104151667826, 2370.9762902370926, 2372.905083796646, 9025.142611451902, 9028.232600228988, 9042.23141033013, 9066.501342426163, 9121.31590130743, 5219.164933610642, 5356.758945847446, 5360.383555891044, 5367.81212657559, 5378.381486129067, 3478.930421391558, 3529.8411571479346, 3677.797721125337, 3682.226732386175, 3690.095926498025, 1053.5575058475458, 1082.4912291223113, 1093.7648405592836, 1120.2830188679252, 1120.4509790202742, 11395.689495707933, 11878.821817672742, 11919.761604767942, 11988.70028249292, 12027.458764355624, 3865.4781604483596, 4211.810468528924, 4534.6599005074095, 4779.259214821708, 4825.252085926687, 9746.220302375792, 9804.0, 9839.868415132209, 9840.458774767456, 9843.950780246196, 5351.1973204019705, 5386.784528618811, 5409.513143817076, 5417.83746487606, 5425.891482170335, 4139.234241665302, 4393.275109606879, 4470.240059189946, 4878.658493905464, 4959.20652695567, 3269.7057264845803, 3956.1296514766705, 3959.8020098994934, 4083.224187404384, 4107.219628270619, 1300.0834411739702, 1309.083641450969, 1313.7635225659283, 1320.6632308761205, 1326.2307861440534, 808.3654331349979, 817.0358976407776, 850.5704619167404, 893.647009431271, 899.2401420799414, 4617.746023968714, 5123.538881500683, 5778.508826749689, 6044.546659000593, 6066.150738896558, 425.20698896557695, 435.48830124603467, 436.9235383807821, 923.5797712041006, 985.1675334342469, 3339.46568549031, 4002.139539998914, 4060.1932140197327, 4111.512534916393, 4125.0, 10665.986731260518, 11084.783043391353, 11111.277766945284, 11115.444227788577, 11120.22158448917, 10328.380297182193, 10778.353298802096, 10838.891611083945, 11002.50488727993, 11063.336199828036, 8146.063202731643, 8188.467749596257, 8344.83310333802, 8372.790680232922, 8384.203500667427, 4601.401817763669, 5878.882422351444, 6000.129981152621, 6065.787697430569, 6139.631622102688, 10109.545070471699, 10259.127974122282, 10263.12763415118, 10369.9481502592, 10392.220583456423, 9930.0, 10251.94874025655, 10405.895941040493, 10504.159667226757, 10564.366138031743, 6131.509479241561, 7117.217106118219, 7304.561726296227, 7624.923750762643, 7809.609519523888, 8383.077861435358, 8563.058063613122, 8615.353848461265, 8616.956915215384, 8632.568371581521, 7442.92557074444, 7559.84045855762, 7583.582902940391, 7637.236276372319, 7723.884141737991, 7261.273872612696, 7351.117865855917, 7373.705051797868, 7532.096148462219, 7555.206703296295, 6935.722571097302, 7026.824329391806, 7100.0414943981905, 7129.429645628441, 7130.465215108784, 4928.8767780805765, 5009.248612708123, 5061.240813877947, 5083.796648134181, 5120.436751957207, 5058.215976523645, 5082.517160869711, 5154.381474223117, 5159.993801208729, 5170.22446632993], "value": 4550.506804541563, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.7713828936851, 304.75314994854153, 311.7630600743435, 316.40042120182255, 317.16427214290337, 5415.512603865652, 5598.860028499279, 5697.6866272355, 5700.686462244575, 5746.339170995337, 5135.380955712595, 5292.761825717842, 5381.461853814626, 5426.875574563331, 5596.552275817935, 3822.923541529168, 3870.3420418528917, 4238.87283381499, 4248.915021699564, 4249.745015299092, 3267.738580913521, 4130.182719603365, 4190.6437952773895, 4240.893977650552, 4241.321388577828, 4198.454200953872, 4200.411942328076, 4213.0, 4227.91544169118, 4258.0, 3722.7952462614544, 3815.67566756826, 4218.978905105455, 4248.362745588156, 4276.679249056333, 3210.887618933326, 3684.2631473705364, 3693.279810436966, 3724.2179142380132, 3735.9626403736156, 3988.282109220368, 4810.446798618169, 4841.7579121044155, 5058.696478211319, 5391.56867450603, 4634.698744581591, 4863.294822250789, 4874.634402419832, 4886.998165376125, 4945.109880221558, 4804.975975120102, 5373.489518495737, 5424.345574599745, 5831.825045248607, 5885.617434866763, 3291.325278317939, 3319.93360132796, 3593.9218234529535, 3705.814709264526, 3861.498005259298, 5355.0896347621, 6152.230971128609, 6184.9485587450245, 6199.45822212895, 6208.627482351028, 4078.204750073707, 4083.816228269676, 4111.629953304157, 4114.88897997545, 4121.8969525762095, 4006.238814625212, 4115.650169735606, 4167.270727622654, 4170.812313445902, 4179.707420480598, 4855.927161092586, 4856.0, 4865.367502224759, 4878.487758785276, 4889.902201955941, 4833.685810422311, 5846.853828654318, 5983.282006159288, 5998.880022399528, 6033.849153771191, 1298.4676282724038, 1320.74529197263, 1415.801787749716, 1420.4673247532176, 1433.8136042314432, 840.6301227459898, 847.8092429203504, 860.3633311349556, 878.9736307910773, 880.8768819754864, 3833.5208098987628, 4029.3955906613655, 4132.648724858421, 4209.894752631148, 4670.486246512881, 4614.077184563101, 4782.808687652525, 4991.97664478781, 5029.7736601853, 5086.364204474441, 4110.588941105865, 4617.399738034102, 4636.582707556337, 4695.624350051988, 4729.6452766042685, 2243.596152692531, 2249.537800429701, 2341.97658023421, 2646.2855029142024, 2698.9730102699114, 4368.694191406635, 4551.453825540956, 4572.679912406166, 4931.630127740434, 5169.069567477815, 4618.584327410482, 4663.083010271388, 4896.265560165933, 5126.66676666023, 5165.948340516622, 4451.399061126771, 4729.983053643415, 5007.073691367109, 5134.922976155286, 5254.789808407697, 4456.086502267092, 4469.821207151677, 4548.772561371852, 4592.15045216636, 4853.888558477074, 4292.59220374064, 4543.841320463261, 4856.344393506832, 5054.444011158696, 5058.570021548062, 4276.850310239127, 4466.59800617946, 4534.637229021736, 4640.86077417678, 4733.0, 3077.0307353183675, 3497.4229252174123, 3499.972523766872, 3560.6973407260675, 3603.3874241378503, 4504.2793153095345, 4584.656150788771, 4841.346418233635, 5114.900296436284, 5852.882942341044, 4761.738104404152, 5545.306836645419, 5807.535397168132, 5878.353381127987, 5889.823305300848, 5460.91808622863, 5852.473277405054, 5921.881562368814, 5930.52555795544, 5933.406659334032, 5575.693336866511, 5726.0, 5789.507891829073, 5837.824865254049, 5898.498627616529, 5912.556558258234, 5946.375630558729, 5952.375000624872, 5981.461668449686, 5985.670788106696, 5093.770780314969, 5538.559974406764, 5804.071348584206, 5819.0, 5844.386339434298], "value": 3980.667783183922, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [672.7342699633642, 676.9390754832065, 689.1213702529275, 749.4753672429298, 789.2502122983163, 14852.96029277951, 15390.69218615627, 15413.768793468107, 15416.997895136808, 15443.536693899187, 11008.449577521113, 14384.784228236558, 14422.855771442282, 14440.422383104664, 14445.78331325031, 659.80535741956, 823.8517066927966, 841.0622156295748, 847.6651722569582, 849.5327569836596, 682.1711620381236, 805.8710606302993, 836.6862426590029, 836.7196989008659, 846.6782622603403, 892.5001998880648, 895.122779675918, 896.7802888292377, 897.4211633496391, 898.5148020069155, 880.9252711691726, 881.6429346114834, 881.6914080071988, 885.3890815337461, 889.3952112563454, 639.5331408072087, 639.9680015999181, 656.0356276273869, 660.8546119853625, 667.346000919102, 11585.841415858431, 12809.295488748116, 14780.334884930204, 14956.326965286587, 15000.174990375524, 11604.593839215671, 14289.356978935974, 14516.419343226364, 14701.338439770128, 14845.331960061823, 9917.553710083064, 11850.170488065838, 11984.400779960966, 12003.699907502294, 12076.033917286595, 3779.1496913194587, 3794.203217324365, 3809.5428548574073, 3828.942565861514, 3841.154945911895, 83713.16286837115, 88819.55590222008, 115475.0, 117365.41317293444, 118168.68240929178, 10939.227620005739, 11112.21974995764, 11150.832737508943, 11163.051140652973, 11233.38216398106, 22768.0, 22951.196708115134, 23036.193733219257, 23040.423989400064, 23091.07635694587, 8101.797455063671, 8130.186981301941, 8134.918650813416, 8253.09215986241, 8280.58597070144, 4402.823887044482, 4474.798634061475, 4493.618042466427, 4500.684952053325, 4513.954860451354, 3626.437902125175, 3713.350163721338, 3748.269087527906, 3750.099976005739, 3755.6995440364703, 610.5146408605169, 662.3740565165872, 667.2993356975156, 687.6011913090396, 688.0951548713426, 634.3497914637534, 636.3254949753184, 651.2413038809733, 652.3183273479226, 678.995087270842, 7729.536227826348, 9382.061793820563, 9408.341416100808, 9419.105185007413, 9548.331616786758, 582.6242073862335, 597.5816928150313, 649.6491894377078, 660.4518249852707, 665.5540787672174, 4458.175237581057, 4510.932336014962, 4587.908241835212, 4657.301404789308, 4741.596964258008, 79901.0, 80200.0, 129883.0, 133858.0, 139754.0, 84402.8899277523, 114463.42768286105, 128211.0, 132623.33688331497, 132889.0, 104145.02332231739, 104553.06745859131, 108806.0, 111212.88787112187, 116154.419227905, 9755.170810481188, 9836.852447213441, 9848.858099802448, 9905.504724763588, 9979.501024948579, 64092.0, 64852.675736621015, 65143.0, 65675.67162164158, 67185.0, 91355.54322228613, 98033.0, 99223.0, 100390.99609004108, 100923.99076009146, 12340.259584424963, 12821.32719296494, 13035.348232588522, 13082.869171308164, 13163.473461061832, 14479.493217737328, 14505.564833055025, 14828.18444985493, 15003.549893503212, 15328.846711533188, 13243.0, 13623.65940851447, 13648.385822638204, 13664.180149190683, 14133.0, 13351.132176408502, 13648.590542283748, 13714.93142534281, 13856.030077894853, 14108.717825643223, 11498.0, 13140.27728474943, 13723.931380343034, 13944.860551394357, 14010.369533370818, 10926.73218061948, 11223.943880280873, 11382.317060976367, 11399.715007124556, 11568.190226684388, 9732.805343893224, 10590.099841513558, 11404.803169101262, 11450.141239406912, 11572.189946703988], "value": 7736.035867361702, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1616.0999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 120.0, 169.0, 159.0, 119.0, 100.0, 116.0, 120.0, 115.0], "value": 124.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 110.0, 140.0, 162.0, 99.0, 122.0, 170.0, 96.0, 203.0, 143.0], "value": 139.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [206.0, 245.0, 267.0, 202.0, 197.0, 199.0, 218.0, 252.0, 245.0, 197.0], "value": 222.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 106.0, 108.0, 177.0, 60.0, 59.0, 59.0, 60.0, 132.0, 59.0], "value": 88.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 71.0, 129.0, 75.0, 91.0, 64.0, 116.0, 94.0, 102.0, 147.0], "value": 95.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 128.0, 134.0, 88.0, 98.0, 86.0, 86.0, 74.0, 135.0, 90.0], "value": 99.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 90.0, 98.0, 140.0, 149.0, 117.0, 145.0, 149.0], "value": 117.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 99.0, 108.0, 121.0, 93.0, 122.0, 150.0, 102.0, 95.0, 95.0], "value": 107.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 57.0, 57.0, 54.0, 57.0, 56.0, 58.0, 55.0, 58.0], "value": 56.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 81.0, 71.0, 76.0, 109.0, 103.0, 88.0, 70.0, 71.0], "value": 80.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 86.0, 89.0, 109.0, 114.0, 107.0, 79.0, 92.0, 100.0], "value": 95.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 141.0, 144.0, 153.0, 150.0, 185.0, 158.0, 147.0, 167.0, 158.0], "value": 154.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 168.0, 171.0, 175.0, 173.0, 171.0, 167.0, 177.0, 150.0, 153.0], "value": 166.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 69.0, 67.0, 69.0, 67.0, 70.0, 66.0, 67.0, 67.0], "value": 67.6, "unit": "score"}]}]} 01:04:46 INFO - Return code: 0 01:04:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:04:46 ERROR - # TBPL SUCCESS # 01:04:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:04:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:04:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:04:46 INFO - 2016-09-13 01:04:46.806 screenresolution[3813:34940] starting screenresolution argv=screenresolution get 01:04:46 INFO - 2016-09-13 01:04:46.809 screenresolution[3813:34940] Display 0: 1600x1200x32@60 01:04:46 INFO - 2016-09-13 01:04:46.815 screenresolution[3814:34943] starting screenresolution argv=screenresolution list 01:04:46 INFO - Available Modes on Display 0 01:04:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:04:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:04:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:04:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:04:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:04:47 INFO - Intel Iris: 01:04:47 INFO - Chipset Model: Intel Iris 01:04:47 INFO - Type: GPU 01:04:47 INFO - Bus: Built-In 01:04:47 INFO - VRAM (Dynamic, Max): 1536 MB 01:04:47 INFO - Vendor: Intel (0x8086) 01:04:47 INFO - Device ID: 0x0a2e 01:04:47 INFO - Revision ID: 0x0009 01:04:47 INFO - Displays: 01:04:47 INFO - Display: 01:04:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:04:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:04:47 INFO - Display Serial Number: 1600x1200 60 01:04:47 INFO - Main Display: Yes 01:04:47 INFO - Mirror: Off 01:04:47 INFO - Online: Yes 01:04:47 INFO - Rotation: Supported 01:04:47 INFO - Return code: 0 01:04:47 INFO - Running post-action listener: _resource_record_post_action 01:04:47 INFO - [mozharness: 2016-09-13 08:04:47.049597Z] Finished run-tests step (success) 01:04:47 INFO - Running post-run listener: _resource_record_post_run 01:04:47 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 36864; Write bytes: 707154432; Read time: 2; Write time: 2067 01:04:47 INFO - TinderboxPrint: CPU usage
54.5% 01:04:47 INFO - TinderboxPrint: I/O read bytes / time
36,864 / 2 01:04:47 INFO - TinderboxPrint: I/O write bytes / time
707,154,432 / 2,067 01:04:47 INFO - TinderboxPrint: CPU idle
2,204.4 (45.5%) 01:04:47 INFO - TinderboxPrint: CPU system
978.3 (20.2%) 01:04:47 INFO - TinderboxPrint: CPU user
1,662.8 (34.3%) 01:04:47 INFO - TinderboxPrint: Swap in / out
404,185,088 / 0 01:04:47 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 399385088; Write bytes: 393983488; Read time: 29507; Write time: 823 01:04:47 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 32768; Write bytes: 298384384; Read time: 1; Write time: 1205 01:04:47 INFO - Running post-run listener: _upload_blobber_files 01:04:47 INFO - Blob upload gear active. 01:04:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:04:47 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 01:04:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:04:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:04:47 INFO - (blobuploader) - INFO - Open directory for files ... 01:04:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 01:04:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:04:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:04:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 01:04:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:04:48 INFO - (blobuploader) - INFO - Done attempting. 01:04:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 01:04:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:04:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:04:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 01:04:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:04:50 INFO - (blobuploader) - INFO - Done attempting. 01:04:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:04:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:04:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:04:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:04:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:04:52 INFO - (blobuploader) - INFO - Done attempting. 01:04:52 INFO - (blobuploader) - INFO - Iteration through files over. 01:04:52 INFO - Return code: 0 01:04:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:04:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:04:52 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6f21811ff5264f9c6c5a501020a1e6fea65d45b5d4d38d46a8d756b139b95424ae1860780603cfa480814ade639a8c3aa36eeb3d31a08e966a740a7e6310c576", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/64b4e1941769a606548da47677ecdc0c7ab6d1c9b57529fbe10d74af5a15ad0e23c37c59721ae3ba0fa31fd7775ecfa8c6001a41883fcda1fd8946b835fc992b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c2d1371c6e6d63c745249f490ca366b82ac3e1d254695b56a43bf88befb9efe200cca24c39b8ce090013b8e99a442596ed8a2e38fbffc153dc6c9f88352ea2ef"} 01:04:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:04:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:04:52 INFO - Contents: 01:04:52 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6f21811ff5264f9c6c5a501020a1e6fea65d45b5d4d38d46a8d756b139b95424ae1860780603cfa480814ade639a8c3aa36eeb3d31a08e966a740a7e6310c576", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/64b4e1941769a606548da47677ecdc0c7ab6d1c9b57529fbe10d74af5a15ad0e23c37c59721ae3ba0fa31fd7775ecfa8c6001a41883fcda1fd8946b835fc992b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c2d1371c6e6d63c745249f490ca366b82ac3e1d254695b56a43bf88befb9efe200cca24c39b8ce090013b8e99a442596ed8a2e38fbffc153dc6c9f88352ea2ef"} 01:04:52 INFO - Running post-run listener: copy_logs_to_upload_dir 01:04:52 INFO - Copying logs to upload dir... 01:04:52 INFO - mkdir: /builds/slave/test/build/upload/logs 01:04:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.238013 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-13 01:04:52.518370) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-13 01:04:52.521955) ========= 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.eQbOWuzbJP/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.w7AUWSR3Tp/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6f21811ff5264f9c6c5a501020a1e6fea65d45b5d4d38d46a8d756b139b95424ae1860780603cfa480814ade639a8c3aa36eeb3d31a08e966a740a7e6310c576", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/64b4e1941769a606548da47677ecdc0c7ab6d1c9b57529fbe10d74af5a15ad0e23c37c59721ae3ba0fa31fd7775ecfa8c6001a41883fcda1fd8946b835fc992b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c2d1371c6e6d63c745249f490ca366b82ac3e1d254695b56a43bf88befb9efe200cca24c39b8ce090013b8e99a442596ed8a2e38fbffc153dc6c9f88352ea2ef"} build_url:https://queue.taskcluster.net/v1/task/BAdemLy2Qx6IW5-Kb0HyqA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010988 build_url: 'https://queue.taskcluster.net/v1/task/BAdemLy2Qx6IW5-Kb0HyqA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6f21811ff5264f9c6c5a501020a1e6fea65d45b5d4d38d46a8d756b139b95424ae1860780603cfa480814ade639a8c3aa36eeb3d31a08e966a740a7e6310c576", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/64b4e1941769a606548da47677ecdc0c7ab6d1c9b57529fbe10d74af5a15ad0e23c37c59721ae3ba0fa31fd7775ecfa8c6001a41883fcda1fd8946b835fc992b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c2d1371c6e6d63c745249f490ca366b82ac3e1d254695b56a43bf88befb9efe200cca24c39b8ce090013b8e99a442596ed8a2e38fbffc153dc6c9f88352ea2ef"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-13 01:04:52.554318) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 01:04:52.554657) ========= 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.eQbOWuzbJP/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.w7AUWSR3Tp/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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.005441 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 01:04:52.603253) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-13 01:04:52.603585) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-13 01:04:52.603927) ========= ========= Total master_lag: 0.18 =========