builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0298 starttime: 1473180101.6 results: success (0) buildid: 20160906065554 builduid: 3dbf7cce6d96438093004baa57f113e3 revision: 97633d930c8f10ca5b8c9a7986bc390900f5970a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 09:41:41.598213) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 09:41:41.598682) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 09:41:41.598955) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 09:41:41.624133) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 09:41:41.624450) ========= 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.67V3NFBqTu/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.2whH08jPnY/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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-06 09:41:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.9M=0.001s 2016-09-06 09:41:42 (18.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.582201 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 09:41:42.228788) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 09:41:42.229096) ========= 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.67V3NFBqTu/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.2whH08jPnY/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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.026855 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 09:41:42.285439) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 09:41:42.285777) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 97633d930c8f10ca5b8c9a7986bc390900f5970a --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 97633d930c8f10ca5b8c9a7986bc390900f5970a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.67V3NFBqTu/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.2whH08jPnY/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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-06 09:41:42,373 truncating revision to first 12 chars 2016-09-06 09:41:42,373 Setting DEBUG logging. 2016-09-06 09:41:42,373 attempt 1/10 2016-09-06 09:41:42,373 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/97633d930c8f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-06 09:41:43,512 unpacking tar archive at: fx-team-97633d930c8f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.403809 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 09:41:43.711977) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 09:41:43.712251) ========= script_repo_revision: 97633d930c8f10ca5b8c9a7986bc390900f5970a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 09:41:43.712623) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 09:41:43.712887) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 09:41:43.731168) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 54 secs) (at 2016-09-06 09:41:43.731471) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.67V3NFBqTu/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.2whH08jPnY/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:41:43 INFO - MultiFileLogger online at 20160906 09:41:43 in /builds/slave/test 09:41:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:41:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:41:43 INFO - {'append_to_log': False, 09:41:43 INFO - 'base_work_dir': '/builds/slave/test', 09:41:43 INFO - 'blob_upload_branch': 'Fx-Team', 09:41:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:41:43 INFO - 'branch': 'Fx-Team', 09:41:43 INFO - 'buildbot_json_path': 'buildprops.json', 09:41:43 INFO - 'config_files': ('talos/mac_config.py',), 09:41:43 INFO - 'default_actions': ('clobber', 09:41:43 INFO - 'read-buildbot-config', 09:41:43 INFO - 'download-and-extract', 09:41:43 INFO - 'populate-webroot', 09:41:43 INFO - 'create-virtualenv', 09:41:43 INFO - 'install', 09:41:43 INFO - 'run-tests'), 09:41:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:41:43 INFO - 'download_minidump_stackwalk': True, 09:41:43 INFO - 'download_symbols': 'ondemand', 09:41:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:41:43 INFO - 'tooltool.py': '/tools/tooltool.py', 09:41:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:41:43 INFO - '/tools/misc-python/virtualenv.py')}, 09:41:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:41:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:41:43 INFO - 'installer_path': 'installer.exe', 09:41:43 INFO - 'log_level': 'info', 09:41:43 INFO - 'log_name': 'talos', 09:41:43 INFO - 'log_to_console': True, 09:41:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:41:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:41:43 INFO - 'opt_config_files': (), 09:41:43 INFO - 'pip_index': False, 09:41:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:41:43 INFO - 'cmd': ('bash', 09:41:43 INFO - '-c', 09:41:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:41:43 INFO - 'enabled': True, 09:41:43 INFO - 'halt_on_failure': False, 09:41:43 INFO - 'name': 'check_screen_resolution'},), 09:41:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:41:43 INFO - 'cmd': ('bash', 09:41:43 INFO - '-c', 09:41:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:41:43 INFO - 'enabled': True, 09:41:43 INFO - 'halt_on_failure': False, 09:41:43 INFO - 'name': 'check_screen_resolution'},), 09:41:43 INFO - 'run_cmd_checks_enabled': True, 09:41:43 INFO - 'sps_profile': False, 09:41:43 INFO - 'sps_profile_interval': 0, 09:41:43 INFO - 'suite': 'dromaeojs', 09:41:43 INFO - 'system_bits': '32', 09:41:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:41:43 INFO - 'title': 't-yosemite-r7-0298', 09:41:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:41:43 INFO - 'use_talos_json': True, 09:41:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:41:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:41:43 INFO - 'work_dir': 'build'} 09:41:43 INFO - [mozharness: 2016-09-06 16:41:43.929029Z] Running clobber step. 09:41:43 INFO - Running pre-action listener: _resource_record_pre_action 09:41:43 INFO - Running main action method: clobber 09:41:43 INFO - rmtree: /builds/slave/test/build 09:41:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:41:45 INFO - Running post-action listener: _resource_record_post_action 09:41:45 INFO - [mozharness: 2016-09-06 16:41:45.963142Z] Finished clobber step (success) 09:41:45 INFO - [mozharness: 2016-09-06 16:41:45.963264Z] Running read-buildbot-config step. 09:41:45 INFO - Running pre-action listener: _resource_record_pre_action 09:41:45 INFO - Running main action method: read_buildbot_config 09:41:45 INFO - Using buildbot properties: 09:41:45 INFO - { 09:41:45 INFO - "project": "", 09:41:45 INFO - "product": "firefox", 09:41:45 INFO - "script_repo_revision": "production", 09:41:45 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:41:45 INFO - "repository": "", 09:41:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:41:45 INFO - "buildid": "20160906065554", 09:41:45 INFO - "pgo_build": "False", 09:41:45 INFO - "basedir": "/builds/slave/test", 09:41:45 INFO - "buildnumber": 136, 09:41:45 INFO - "slavename": "t-yosemite-r7-0298", 09:41:45 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 09:41:45 INFO - "platform": "yosemite_r7", 09:41:45 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 09:41:45 INFO - "branch": "fx-team", 09:41:45 INFO - "repo_path": "integration/fx-team", 09:41:45 INFO - "revision": "97633d930c8f10ca5b8c9a7986bc390900f5970a", 09:41:45 INFO - "stage_platform": "macosx64", 09:41:45 INFO - "builduid": "3dbf7cce6d96438093004baa57f113e3", 09:41:45 INFO - "slavebuilddir": "test" 09:41:45 INFO - } 09:41:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 09:41:45 INFO - Running post-action listener: _resource_record_post_action 09:41:45 INFO - [mozharness: 2016-09-06 16:41:45.966220Z] Finished read-buildbot-config step (success) 09:41:45 INFO - [mozharness: 2016-09-06 16:41:45.966324Z] Running download-and-extract step. 09:41:45 INFO - Running pre-action listener: _resource_record_pre_action 09:41:45 INFO - Running main action method: download_and_extract 09:41:45 INFO - mkdir: /builds/slave/test/build/tests 09:41:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:45 INFO - trying https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 09:41:45 INFO - Downloading https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/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 09:41:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/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 09:41:47 INFO - Downloaded 1199 bytes. 09:41:47 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:41:47 INFO - Using the following test package requirements: 09:41:47 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:41:47 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:41:47 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 09:41:47 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:41:47 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 09:41:47 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:41:47 INFO - u'jsshell-mac.zip'], 09:41:47 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:41:47 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 09:41:47 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:41:47 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:41:47 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 09:41:47 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:41:47 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 09:41:47 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:41:47 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 09:41:47 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:41:47 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 09:41:47 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:41:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:41:47 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 09:41:54 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 09:41:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:41:55 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 09:42:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:42:02 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 09:42:02 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')]} 09:42:02 INFO - trying https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:42:02 INFO - Downloading https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 09:42:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/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 09:42:09 INFO - Downloaded 111493226 bytes. 09:42:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:42:09 INFO - mkdir: /builds/slave/test/properties 09:42:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:42:09 INFO - Writing to file /builds/slave/test/properties/build_url 09:42:09 INFO - Contents: 09:42:09 INFO - build_url:https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:42:10 INFO - Running post-action listener: _resource_record_post_action 09:42:10 INFO - Running post-action listener: set_extra_try_arguments 09:42:10 INFO - [mozharness: 2016-09-06 16:42:10.141294Z] Finished download-and-extract step (success) 09:42:10 INFO - [mozharness: 2016-09-06 16:42:10.141425Z] Running populate-webroot step. 09:42:10 INFO - Running pre-action listener: _resource_record_pre_action 09:42:10 INFO - Running main action method: populate_webroot 09:42:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:42:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:42:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:42:10 INFO - '--tppagecycles', 09:42:10 INFO - '7'], 09:42:10 INFO - 'tests': ['tsvgm']}}, 09:42:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:42:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:42:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:42:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:42:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:42:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:42:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:42:10 INFO - 'talos_options': ['--disable-e10s'], 09:42:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:42:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:42:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:42:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:42:10 INFO - 'talos_options': ['--disable-e10s'], 09:42:10 INFO - 'tests': ['damp', 'tps']}, 09:42:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:42:10 INFO - 'tests': ['damp', 'tps']}, 09:42:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:42:10 INFO - 'tests': ['dromaeo_dom']}, 09:42:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:42:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:42:10 INFO - 'tests': ['basic_compositor_video']}, 09:42:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:42:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:42:10 INFO - 'tests': ['a11yr', 09:42:10 INFO - 'ts_paint', 09:42:10 INFO - 'tpaint', 09:42:10 INFO - 'sessionrestore', 09:42:10 INFO - 'sessionrestore_no_auto_restore', 09:42:10 INFO - 'tabpaint']}, 09:42:10 INFO - 'other-e10s': {'tests': ['a11yr', 09:42:10 INFO - 'ts_paint', 09:42:10 INFO - 'tpaint', 09:42:10 INFO - 'sessionrestore', 09:42:10 INFO - 'sessionrestore_no_auto_restore', 09:42:10 INFO - 'tabpaint']}, 09:42:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:42:10 INFO - 'ts_paint', 09:42:10 INFO - 'tpaint', 09:42:10 INFO - 'sessionrestore', 09:42:10 INFO - 'sessionrestore_no_auto_restore', 09:42:10 INFO - 'tabpaint']}, 09:42:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:42:10 INFO - 'ts_paint', 09:42:10 INFO - 'tpaint', 09:42:10 INFO - 'sessionrestore', 09:42:10 INFO - 'sessionrestore_no_auto_restore', 09:42:10 INFO - 'tabpaint']}, 09:42:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:42:10 INFO - 'tests': ['a11yr', 09:42:10 INFO - 'ts_paint', 09:42:10 INFO - 'tpaint', 09:42:10 INFO - 'sessionrestore', 09:42:10 INFO - 'sessionrestore_no_auto_restore', 09:42:10 INFO - 'tabpaint']}, 09:42:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:42:10 INFO - 'tests': ['a11yr', 09:42:10 INFO - 'ts_paint', 09:42:10 INFO - 'tpaint', 09:42:10 INFO - 'sessionrestore', 09:42:10 INFO - 'sessionrestore_no_auto_restore', 09:42:10 INFO - 'tabpaint']}, 09:42:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:42:10 INFO - 'tests': ['tsvgx', 09:42:10 INFO - 'tsvgr_opacity', 09:42:10 INFO - 'tart', 09:42:10 INFO - 'tscrollx', 09:42:10 INFO - 'cart']}, 09:42:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:42:10 INFO - 'tsvgr_opacity', 09:42:10 INFO - 'tart', 09:42:10 INFO - 'tscrollx', 09:42:10 INFO - 'cart']}, 09:42:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:42:10 INFO - 'talos_options': ['--disable-e10s'], 09:42:10 INFO - 'tests': ['tp5o']}, 09:42:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:42:10 INFO - 'tests': ['tp5o']}, 09:42:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:42:10 INFO - 'talos_options': ['--disable-e10s', 09:42:10 INFO - '--xperf_path', 09:42:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:42:10 INFO - 'tests': ['tp5n']}, 09:42:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:42:10 INFO - 'talos_options': ['--xperf_path', 09:42:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:42:10 INFO - 'tests': ['tp5n']}}, 09:42:10 INFO - 'talos.zip': {'path': '', 09:42:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:42:10 INFO - Running post-action listener: _resource_record_post_action 09:42:10 INFO - [mozharness: 2016-09-06 16:42:10.160228Z] Finished populate-webroot step (success) 09:42:10 INFO - [mozharness: 2016-09-06 16:42:10.160371Z] Running create-virtualenv step. 09:42:10 INFO - Running pre-action listener: _resource_record_pre_action 09:42:10 INFO - Running main action method: create_virtualenv 09:42:10 INFO - Creating virtualenv /builds/slave/test/build/venv 09:42:10 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 09:42:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:42:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:42:10 INFO - Using real prefix '/tools/python27' 09:42:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:42:11 INFO - Installing distribute.............................................................................................................................................................................................done. 09:42:13 INFO - Installing pip.................done. 09:42:13 INFO - Return code: 0 09:42:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:42: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')]} 09:42:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42: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')]} 09:42:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106e39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ece618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa524cad50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9dd30>, '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 0x106b02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d95b20>, '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.67V3NFBqTu/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.2whH08jPnY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:42: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', 'pip>=1.5'] in /builds/slave/test/build 09:42: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 pip>=1.5 09:42:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.67V3NFBqTu/Render', 09:42:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:13 INFO - 'HOME': '/Users/cltbld', 09:42:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:13 INFO - 'LOGNAME': 'cltbld', 09:42:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:13 INFO - 'MOZ_NO_REMOTE': '1', 09:42:13 INFO - 'NO_EM_RESTART': '1', 09:42:13 INFO - 'PAGER': '/bin/cat', 09:42:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:13 INFO - 'PWD': '/builds/slave/test', 09:42:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:13 INFO - 'SHELL': '/bin/bash', 09:42:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2whH08jPnY/Listeners', 09:42:13 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:42:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:13 INFO - 'USER': 'cltbld', 09:42:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:13 INFO - 'XPC_FLAGS': '0x0', 09:42:13 INFO - 'XPC_SERVICE_NAME': '0', 09:42:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:14 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 09:42:14 INFO - Cleaning up... 09:42:14 INFO - Return code: 0 09:42:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:42:14 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')]} 09:42:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:14 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')]} 09:42:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:14 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 0x106e39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ece618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa524cad50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9dd30>, '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 0x106b02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d95b20>, '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.67V3NFBqTu/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.2whH08jPnY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:42:14 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 09:42:14 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 09:42:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.67V3NFBqTu/Render', 09:42:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:14 INFO - 'HOME': '/Users/cltbld', 09:42:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:14 INFO - 'LOGNAME': 'cltbld', 09:42:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:14 INFO - 'MOZ_NO_REMOTE': '1', 09:42:14 INFO - 'NO_EM_RESTART': '1', 09:42:14 INFO - 'PAGER': '/bin/cat', 09:42:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:14 INFO - 'PWD': '/builds/slave/test', 09:42:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:14 INFO - 'SHELL': '/bin/bash', 09:42:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2whH08jPnY/Listeners', 09:42:14 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:42:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:14 INFO - 'USER': 'cltbld', 09:42:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:14 INFO - 'XPC_FLAGS': '0x0', 09:42:14 INFO - 'XPC_SERVICE_NAME': '0', 09:42:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:14 INFO - Downloading/unpacking psutil>=3.1.1 09:42:14 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 09:42:14 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 09:42:14 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 09:42:14 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 09:42:14 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 09:42:14 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 09:42:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:42:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:42:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:42:18 INFO - Installing collected packages: psutil 09:42:18 INFO - Running setup.py install for psutil 09:42:18 INFO - building 'psutil._psutil_osx' extension 09:42:18 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 09:42:18 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 09:42:18 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 09:42:18 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 09:42:18 INFO - building 'psutil._psutil_posix' extension 09:42:18 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 09:42:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:42:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:42:19 INFO - ^ 09:42:19 INFO - 1 warning generated. 09:42:19 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 09:42:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:42:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:42:19 INFO - Successfully installed psutil 09:42:19 INFO - Cleaning up... 09:42:19 INFO - Return code: 0 09:42:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:42: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')]} 09:42:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42: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')]} 09:42:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106e39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ece618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa524cad50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9dd30>, '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 0x106b02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d95b20>, '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.67V3NFBqTu/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.2whH08jPnY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:42: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:42: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 mozsystemmonitor==0.3 09:42:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.67V3NFBqTu/Render', 09:42:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:19 INFO - 'HOME': '/Users/cltbld', 09:42:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:19 INFO - 'LOGNAME': 'cltbld', 09:42:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:19 INFO - 'MOZ_NO_REMOTE': '1', 09:42:19 INFO - 'NO_EM_RESTART': '1', 09:42:19 INFO - 'PAGER': '/bin/cat', 09:42:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:19 INFO - 'PWD': '/builds/slave/test', 09:42:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:19 INFO - 'SHELL': '/bin/bash', 09:42:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2whH08jPnY/Listeners', 09:42:19 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:42:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:19 INFO - 'USER': 'cltbld', 09:42:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:19 INFO - 'XPC_FLAGS': '0x0', 09:42:19 INFO - 'XPC_SERVICE_NAME': '0', 09:42:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:42:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:42:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:42:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:42:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:42:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:42:22 INFO - Installing collected packages: mozsystemmonitor 09:42:22 INFO - Running setup.py install for mozsystemmonitor 09:42:22 INFO - Successfully installed mozsystemmonitor 09:42:22 INFO - Cleaning up... 09:42:22 INFO - Return code: 0 09:42:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:42:22 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')]} 09:42:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:22 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')]} 09:42:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:22 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 0x106e39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ece618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa524cad50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9dd30>, '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 0x106b02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d95b20>, '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.67V3NFBqTu/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.2whH08jPnY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:42:22 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 09:42:22 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 09:42:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.67V3NFBqTu/Render', 09:42:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:22 INFO - 'HOME': '/Users/cltbld', 09:42:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:22 INFO - 'LOGNAME': 'cltbld', 09:42:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:22 INFO - 'MOZ_NO_REMOTE': '1', 09:42:22 INFO - 'NO_EM_RESTART': '1', 09:42:22 INFO - 'PAGER': '/bin/cat', 09:42:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:22 INFO - 'PWD': '/builds/slave/test', 09:42:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:22 INFO - 'SHELL': '/bin/bash', 09:42:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2whH08jPnY/Listeners', 09:42:22 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:42:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:22 INFO - 'USER': 'cltbld', 09:42:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:22 INFO - 'XPC_FLAGS': '0x0', 09:42:22 INFO - 'XPC_SERVICE_NAME': '0', 09:42:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:22 INFO - Downloading/unpacking jsonschema==2.5.1 09:42: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 09:42: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 09:42: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 09:42: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 09:42: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 09:42: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 09:42:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:42:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:42:25 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 09:42:25 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 09:42:25 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 09:42:25 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 09:42:25 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 09:42:25 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 09:42:25 INFO - Downloading functools32-3.2.3-2.tar.gz 09:42:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:42:25 INFO - warning: no files found matching '*.txt' 09:42:25 INFO - no previously-included directories found matching 'build' 09:42:25 INFO - no previously-included directories found matching 'dist' 09:42:25 INFO - no previously-included directories found matching '.git*' 09:42:25 INFO - Installing collected packages: jsonschema, functools32 09:42:25 INFO - Running setup.py install for functools32 09:42:25 INFO - warning: no files found matching '*.txt' 09:42:25 INFO - no previously-included directories found matching 'build' 09:42:25 INFO - no previously-included directories found matching 'dist' 09:42:25 INFO - no previously-included directories found matching '.git*' 09:42:25 INFO - Successfully installed jsonschema functools32 09:42:25 INFO - Cleaning up... 09:42:25 INFO - Return code: 0 09:42:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:42:25 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')]} 09:42:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:25 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')]} 09:42:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:25 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 0x106e39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ece618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa524cad50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9dd30>, '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 0x106b02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d95b20>, '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.67V3NFBqTu/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.2whH08jPnY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:42:25 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 09:42:25 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 09:42:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.67V3NFBqTu/Render', 09:42:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:25 INFO - 'HOME': '/Users/cltbld', 09:42:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:25 INFO - 'LOGNAME': 'cltbld', 09:42:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:25 INFO - 'MOZ_NO_REMOTE': '1', 09:42:25 INFO - 'NO_EM_RESTART': '1', 09:42:25 INFO - 'PAGER': '/bin/cat', 09:42:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:25 INFO - 'PWD': '/builds/slave/test', 09:42:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:25 INFO - 'SHELL': '/bin/bash', 09:42:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2whH08jPnY/Listeners', 09:42:25 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:42:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:25 INFO - 'USER': 'cltbld', 09:42:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:25 INFO - 'XPC_FLAGS': '0x0', 09:42:25 INFO - 'XPC_SERVICE_NAME': '0', 09:42:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:42:26 INFO - Cleaning up... 09:42:26 INFO - Return code: 0 09:42:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:42:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:42:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:42:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106e39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ece618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa524cad50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9dd30>, '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 0x106b02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d95b20>, '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.67V3NFBqTu/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.2whH08jPnY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:42:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:42:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:42:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.67V3NFBqTu/Render', 09:42:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:26 INFO - 'HOME': '/Users/cltbld', 09:42:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:26 INFO - 'LOGNAME': 'cltbld', 09:42:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:26 INFO - 'MOZ_NO_REMOTE': '1', 09:42:26 INFO - 'NO_EM_RESTART': '1', 09:42:26 INFO - 'PAGER': '/bin/cat', 09:42:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:26 INFO - 'PWD': '/builds/slave/test', 09:42:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:26 INFO - 'SHELL': '/bin/bash', 09:42:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2whH08jPnY/Listeners', 09:42:26 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:42:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:26 INFO - 'USER': 'cltbld', 09:42:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:26 INFO - 'XPC_FLAGS': '0x0', 09:42:26 INFO - 'XPC_SERVICE_NAME': '0', 09:42:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:26 INFO - Downloading/unpacking blobuploader==1.2.4 09:42:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:42:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:42:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:27 INFO - Downloading blobuploader-1.2.4.tar.gz 09:42:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:42:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:42:27 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 09:42:27 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 09:42:27 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 09:42:27 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 09:42:27 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 09:42:27 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 09:42:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:42:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:42:28 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 09:42:28 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 09:42:28 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 09:42:28 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 09:42:28 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 09:42:28 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 09:42:28 INFO - Downloading docopt-0.6.1.tar.gz 09:42:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:42:28 INFO - Installing collected packages: blobuploader, requests, docopt 09:42:28 INFO - Running setup.py install for blobuploader 09:42:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:42:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:42:28 INFO - Running setup.py install for requests 09:42:29 INFO - Running setup.py install for docopt 09:42:29 INFO - Successfully installed blobuploader requests docopt 09:42:29 INFO - Cleaning up... 09:42:29 INFO - Return code: 0 09:42:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:42: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')]} 09:42:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42: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')]} 09:42:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:29 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 0x106e39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ece618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa524cad50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9dd30>, '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 0x106b02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d95b20>, '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.67V3NFBqTu/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.2whH08jPnY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:42:29 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 09:42:29 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 09:42:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.67V3NFBqTu/Render', 09:42:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:29 INFO - 'HOME': '/Users/cltbld', 09:42:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:29 INFO - 'LOGNAME': 'cltbld', 09:42:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:29 INFO - 'MOZ_NO_REMOTE': '1', 09:42:29 INFO - 'NO_EM_RESTART': '1', 09:42:29 INFO - 'PAGER': '/bin/cat', 09:42:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:29 INFO - 'PWD': '/builds/slave/test', 09:42:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:29 INFO - 'SHELL': '/bin/bash', 09:42:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2whH08jPnY/Listeners', 09:42:29 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:42:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:29 INFO - 'USER': 'cltbld', 09:42:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:29 INFO - 'XPC_FLAGS': '0x0', 09:42:29 INFO - 'XPC_SERVICE_NAME': '0', 09:42:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:42:29 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-bK8jok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:42:29 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-olDqOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:42:29 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-7n3nR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:42:29 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-fyG2N4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:42:30 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-_nX40I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:42:30 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-26BgIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:42:30 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-orVGpz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:42:30 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-4eVEPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:42:30 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-Rfdhro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:42:30 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-0xXdSZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:42:30 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-S_jk__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:42:30 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-774cWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:42:30 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-_7PwIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:42:31 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-0V_k6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:42:31 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-WXPmA5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:42:31 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-QL5qiQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:42:31 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-9C7WGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:42:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:42:31 INFO - Running setup.py install for manifestparser 09:42:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:42:31 INFO - Running setup.py install for mozcrash 09:42:31 INFO - Running setup.py install for mozdebug 09:42:31 INFO - Running setup.py install for mozdevice 09:42:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:42:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:42:32 INFO - Running setup.py install for mozfile 09:42:32 INFO - Running setup.py install for mozhttpd 09:42:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:42:32 INFO - Running setup.py install for mozinfo 09:42:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:42:32 INFO - Running setup.py install for mozInstall 09:42:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:42:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:42:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:42:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:42:32 INFO - Running setup.py install for mozleak 09:42:32 INFO - Running setup.py install for mozlog 09:42:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:42:32 INFO - Running setup.py install for moznetwork 09:42:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:42:33 INFO - Running setup.py install for mozprocess 09:42:33 INFO - Running setup.py install for mozprofile 09:42:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:42:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:42:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:42:33 INFO - Running setup.py install for mozrunner 09:42:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:42:33 INFO - Running setup.py install for mozscreenshot 09:42:33 INFO - Running setup.py install for moztest 09:42:33 INFO - Running setup.py install for mozversion 09:42:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:42:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:42:33 INFO - Cleaning up... 09:42:34 INFO - Return code: 0 09:42:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:42: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')]} 09:42:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42: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')]} 09:42:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:34 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 0x106e39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ece618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa524cad50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9dd30>, '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 0x106b02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d95b20>, '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.67V3NFBqTu/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.2whH08jPnY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:42:34 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 09:42:34 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 09:42:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.67V3NFBqTu/Render', 09:42:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:34 INFO - 'HOME': '/Users/cltbld', 09:42:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:34 INFO - 'LOGNAME': 'cltbld', 09:42:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:34 INFO - 'MOZ_NO_REMOTE': '1', 09:42:34 INFO - 'NO_EM_RESTART': '1', 09:42:34 INFO - 'PAGER': '/bin/cat', 09:42:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:34 INFO - 'PWD': '/builds/slave/test', 09:42:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:34 INFO - 'SHELL': '/bin/bash', 09:42:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2whH08jPnY/Listeners', 09:42:34 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:42:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:34 INFO - 'USER': 'cltbld', 09:42:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:34 INFO - 'XPC_FLAGS': '0x0', 09:42:34 INFO - 'XPC_SERVICE_NAME': '0', 09:42:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:42:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-JBd7eD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:42:34 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)) 09:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:42:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-1XgpFw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:42:34 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)) 09:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:42:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-PELZCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:42:34 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)) 09:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:42:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-47GAOi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:42:34 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)) 09:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:42:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-HPkwax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:42:34 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)) 09:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:42:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-ceqdaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:42:34 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)) 09:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:42:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-UM8fOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:42:35 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)) 09:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:42:35 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-jVMxbX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:42:35 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)) 09:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:42:35 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-zYVWRm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:42:35 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)) 09:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:42:35 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-RFDv5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:42:35 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)) 09:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:42:35 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-tJ_iN_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:42:35 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)) 09:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:42:35 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-GZQDn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:42:35 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)) 09:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:42:35 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-a0EGgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:42:35 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)) 09:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:42:35 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-yyuUCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:42:35 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)) 09:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:42:35 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-efxIjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:42:35 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)) 09:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:42:35 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-toaTOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:42:36 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)) 09:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:42:36 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-1Qx4iI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:42:36 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)) 09:42:36 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)) 09:42:36 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)) 09:42:36 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)) 09:42:36 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)) 09:42:36 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)) 09:42:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:42:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:42:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:42:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:38 INFO - Downloading blessings-1.6.tar.gz 09:42:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:42:38 INFO - Installing collected packages: blessings 09:42:38 INFO - Running setup.py install for blessings 09:42:38 INFO - Successfully installed blessings 09:42:38 INFO - Cleaning up... 09:42:38 INFO - Return code: 0 09:42:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:42:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:42:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:42:39 INFO - Reading from file tmpfile_stdout 09:42:39 INFO - Current package versions: 09:42:39 INFO - blessings == 1.6 09:42:39 INFO - blobuploader == 1.2.4 09:42:39 INFO - docopt == 0.6.1 09:42:39 INFO - functools32 == 3.2.3-2 09:42:39 INFO - jsonschema == 2.5.1 09:42:39 INFO - manifestparser == 1.1 09:42:39 INFO - mozInstall == 1.12 09:42:39 INFO - mozcrash == 0.17 09:42:39 INFO - mozdebug == 0.1 09:42:39 INFO - mozdevice == 0.48 09:42:39 INFO - mozfile == 1.2 09:42:39 INFO - mozhttpd == 0.7 09:42:39 INFO - mozinfo == 0.9 09:42:39 INFO - mozleak == 0.1 09:42:39 INFO - mozlog == 3.3 09:42:39 INFO - moznetwork == 0.27 09:42:39 INFO - mozprocess == 0.23 09:42:39 INFO - mozprofile == 0.28 09:42:39 INFO - mozrunner == 6.12 09:42:39 INFO - mozscreenshot == 0.1 09:42:39 INFO - mozsystemmonitor == 0.3 09:42:39 INFO - moztest == 0.7 09:42:39 INFO - mozversion == 1.4 09:42:39 INFO - psutil == 3.1.1 09:42:39 INFO - requests == 1.2.3 09:42:39 INFO - wsgiref == 0.1.2 09:42:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:42:39 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')]} 09:42:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:39 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')]} 09:42:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:39 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 0x106e39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ece618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa524cad50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9dd30>, '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 0x106b02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d95b20>, '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.67V3NFBqTu/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.2whH08jPnY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:42:39 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 09:42:39 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 09:42:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.67V3NFBqTu/Render', 09:42:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:39 INFO - 'HOME': '/Users/cltbld', 09:42:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:39 INFO - 'LOGNAME': 'cltbld', 09:42:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:39 INFO - 'MOZ_NO_REMOTE': '1', 09:42:39 INFO - 'NO_EM_RESTART': '1', 09:42:39 INFO - 'PAGER': '/bin/cat', 09:42:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:39 INFO - 'PWD': '/builds/slave/test', 09:42:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:39 INFO - 'SHELL': '/bin/bash', 09:42:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2whH08jPnY/Listeners', 09:42:39 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:42:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:39 INFO - 'USER': 'cltbld', 09:42:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:39 INFO - 'XPC_FLAGS': '0x0', 09:42:39 INFO - 'XPC_SERVICE_NAME': '0', 09:42:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:39 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)) 09:42:39 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)) 09:42:39 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)) 09:42:39 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)) 09:42:39 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)) 09:42:39 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)) 09:42:39 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)) 09:42:39 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)) 09:42:39 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)) 09:42:39 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)) 09:42:39 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)) 09:42:39 INFO - Cleaning up... 09:42:39 INFO - Return code: 0 09:42:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:42:39 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')]} 09:42:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:39 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')]} 09:42:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:39 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 0x106e39a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ece618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa524cad50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d9dd30>, '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 0x106b02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d95b20>, '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.67V3NFBqTu/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.2whH08jPnY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:42:39 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 09:42:39 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 09:42:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.67V3NFBqTu/Render', 09:42:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:39 INFO - 'HOME': '/Users/cltbld', 09:42:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:39 INFO - 'LOGNAME': 'cltbld', 09:42:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:39 INFO - 'MOZ_NO_REMOTE': '1', 09:42:39 INFO - 'NO_EM_RESTART': '1', 09:42:39 INFO - 'PAGER': '/bin/cat', 09:42:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:39 INFO - 'PWD': '/builds/slave/test', 09:42:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:39 INFO - 'SHELL': '/bin/bash', 09:42:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2whH08jPnY/Listeners', 09:42:39 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:42:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:39 INFO - 'USER': 'cltbld', 09:42:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:39 INFO - 'XPC_FLAGS': '0x0', 09:42:39 INFO - 'XPC_SERVICE_NAME': '0', 09:42:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:42:39 INFO - Cleaning up... 09:42:39 INFO - Return code: 0 09:42:39 INFO - Running post-action listener: _resource_record_post_action 09:42:39 INFO - Running post-action listener: _start_resource_monitoring 09:42:39 INFO - Starting resource monitoring. 09:42:39 INFO - [mozharness: 2016-09-06 16:42:39.774552Z] Finished create-virtualenv step (success) 09:42:39 INFO - [mozharness: 2016-09-06 16:42:39.775550Z] Running install step. 09:42:39 INFO - Running pre-action listener: _resource_record_pre_action 09:42:39 INFO - Running main action method: install 09:42:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:42:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:42:40 INFO - Reading from file tmpfile_stdout 09:42:40 INFO - Detecting whether we're running mozinstall >=1.0... 09:42:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:42:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:42:40 INFO - Reading from file tmpfile_stdout 09:42:40 INFO - Output received: 09:42:40 INFO - Usage: mozinstall [options] installer 09:42:40 INFO - Options: 09:42:40 INFO - -h, --help show this help message and exit 09:42:40 INFO - -d DEST, --destination=DEST 09:42:40 INFO - Directory to install application into. [default: 09:42:40 INFO - "/builds/slave/test"] 09:42:40 INFO - --app=APP Application being installed. [default: firefox] 09:42:40 INFO - mkdir: /builds/slave/test/build/application 09:42:40 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'] 09:42:40 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 09:43:14 INFO - Reading from file tmpfile_stdout 09:43:14 INFO - Output received: 09:43:14 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:43:14 INFO - Running post-action listener: _resource_record_post_action 09:43:14 INFO - [mozharness: 2016-09-06 16:43:14.203122Z] Finished install step (success) 09:43:14 INFO - [mozharness: 2016-09-06 16:43:14.203269Z] Running run-tests step. 09:43:14 INFO - Running pre-action listener: _resource_record_pre_action 09:43:14 INFO - Running main action method: run_tests 09:43:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:43:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:43:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:43:14 INFO - 2016-09-06 09:43:14.217 screenresolution[1649:8273] starting screenresolution argv=screenresolution get 09:43:14 INFO - 2016-09-06 09:43:14.220 screenresolution[1649:8273] Display 0: 1600x1200x32@60 09:43:14 INFO - 2016-09-06 09:43:14.226 screenresolution[1650:8275] starting screenresolution argv=screenresolution list 09:43:14 INFO - Available Modes on Display 0 09:43:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:43:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:43:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:43:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:43:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:43:14 INFO - Intel Iris: 09:43:14 INFO - Chipset Model: Intel Iris 09:43:14 INFO - Type: GPU 09:43:14 INFO - Bus: Built-In 09:43:14 INFO - VRAM (Dynamic, Max): 1536 MB 09:43:14 INFO - Vendor: Intel (0x8086) 09:43:14 INFO - Device ID: 0x0a2e 09:43:14 INFO - Revision ID: 0x0009 09:43:14 INFO - Displays: 09:43:14 INFO - Display: 09:43:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:43:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:43:14 INFO - Display Serial Number: 1600x1200 60 09:43:14 INFO - Main Display: Yes 09:43:14 INFO - Mirror: Off 09:43:14 INFO - Online: Yes 09:43:14 INFO - Rotation: Supported 09:43:14 INFO - Return code: 0 09:43:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:43:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:43:14 INFO - Python 2.7.3 09:43:14 INFO - Return code: 0 09:43:14 INFO - grabbing minidump binary from tooltool 09:43:14 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')]} 09:43:14 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 0x106d9dd30>, '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 0x106b02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d95b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:43:14 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 09:43:14 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 09:43:14 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 09:43:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:43:14 INFO - Return code: 0 09:43:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:43:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:43:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:43:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:43:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:43:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0298', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:43:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0298 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:43:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.67V3NFBqTu/Render', 09:43:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:14 INFO - 'HOME': '/Users/cltbld', 09:43:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:14 INFO - 'LOGNAME': 'cltbld', 09:43:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:43:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:43:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:14 INFO - 'MOZ_NO_REMOTE': '1', 09:43:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:43:14 INFO - 'NO_EM_RESTART': '1', 09:43:14 INFO - 'PAGER': '/bin/cat', 09:43:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:14 INFO - 'PWD': '/builds/slave/test', 09:43:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:43:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:14 INFO - 'SHELL': '/bin/bash', 09:43:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2whH08jPnY/Listeners', 09:43:14 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:43:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:14 INFO - 'USER': 'cltbld', 09:43:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:14 INFO - 'XPC_FLAGS': '0x0', 09:43:14 INFO - 'XPC_SERVICE_NAME': '0', 09:43:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0298', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:43:14 INFO - mozversion application_buildid: 20160906065554 09:43:14 INFO - mozversion application_changeset: 97633d930c8f10ca5b8c9a7986bc390900f5970a 09:43:14 INFO - mozversion application_display_name: Nightly 09:43:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:43:14 INFO - mozversion application_name: Firefox 09:43:14 INFO - mozversion application_remotingname: firefox 09:43:14 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:43:14 INFO - mozversion application_vendor: Mozilla 09:43:14 INFO - mozversion application_version: 51.0a1 09:43:14 INFO - mozversion platform_buildid: 20160906065554 09:43:14 INFO - mozversion platform_changeset: 97633d930c8f10ca5b8c9a7986bc390900f5970a 09:43:14 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:43:14 INFO - mozversion platform_version: 51.0a1 09:43:14 INFO - using testdate: 1473180194 09:43:14 INFO - actual date: 1473180194 09:43:14 INFO - starting webserver on 'localhost:49276' 09:43:14 INFO - SUITE-START | Running 2 tests 09:43:14 INFO - TEST-START | dromaeo_css 09:43:14 INFO - operating with platform_type : mac_ 09:43:14 INFO - Initialising browser for dromaeo_css test... 09:43:14 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmpfR9Spn/profile http://localhost:49276/getInfo.html) 09:43:17 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 09:43:17 INFO - PROCESS | 1660 | colorDepth:24 09:43:17 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 09:43:17 INFO - PROCESS | 1660 | __metrics 09:43:17 INFO - PROCESS | 1660 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:43:17 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:43:18 INFO - TEST-INFO | 1660: exit 0 09:43:18 INFO - Browser initialized. 09:43:18 INFO - Running cycle 1/1 for dromaeo_css test... 09:43:18 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmpfR9Spn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:43:29 INFO - PROCESS | 1663 | RSS: Main: 232194048 09:43:29 INFO - PROCESS | 1663 | 09:44:21 INFO - PROCESS | 1663 | 1473180261274 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] 09:46:32 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 09:46:34 INFO - PROCESS | 1663 | RSS: Main: 409014272 09:46:34 INFO - PROCESS | 1663 | 09:49:37 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 09:49:38 INFO - PROCESS | 1663 | RSS: Main: 370487296 09:49:38 INFO - PROCESS | 1663 | 09:52:40 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 09:52:41 INFO - PROCESS | 1663 | RSS: Main: 345817088 09:52:41 INFO - PROCESS | 1663 | 09:55:43 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 09:55:44 INFO - PROCESS | 1663 | RSS: Main: 349466624 09:55:44 INFO - PROCESS | 1663 | 09:58:47 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 09:58:48 INFO - PROCESS | 1663 | RSS: Main: 329728000 09:58:48 INFO - PROCESS | 1663 | 10:01:51 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 10:01:51 INFO - PROCESS | 1663 | RSS: Main: 437821440 10:01:51 INFO - PROCESS | 1663 | 10:01:51 INFO - PROCESS | 1663 | __start_tp_report 10:01:51 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 10:01:51 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 10:01:51 INFO - PROCESS | 1663 | |i|pagename|runs| 10:01:51 INFO - PROCESS | 1663 | |0;dojo.html;3313.9005829825105;3316.0217735767937;3337.7496687748408;3360.411927912615;3409.9318013639727;5544.445555444454;5645;5667.4049224831415;5760.798372056979;5779.36426993031;5646.435356464352;5696.202531645563;5709.628874123179;5711;5720.79977200798;5139.434662187156;5167.922481162776;5196.740162991854;5221.973890130544;5237.711925844077;5267.894642107156;5270.998510283053;5273.525382715555;5276.709780962045;5287.629865909388;4558.202314594949;5176.094183517888;5211.400688920785;5232.921506177391;5270.235815806705;5273.4990175933035;5298.841034768963;5304.310439642861;5305.840824775224;5369;5082.6188035897085;5085.338905942241;5096.872578185575;5209.2446595243855;5237.633365664405;5672.744726487318;5687.857803554903;5704.914426283609;5748.42515748426;5780.566457515703;6244.12582238487;6257.906131408033;6276.717547710364;6277.937220627781;6279.591826531261;6115.082737589352;6173.783917562909;6184.907226391562;6230.7819226326865;6259.671889694423;5807.070868660994;5813.447722466359;5813.563982701315;5834.620749651259;5839.328477225088;6292.339304373067;6299.401556852092;6305.4009869062365;6310;6338.524610654219;5966.910496342558;5985.491233245223;6011;6082.36135205797;6110.083487476825;6138.600990935575;6189.164462797555;6197.876042479125;6198.473129783929;6218.782342618078;6076.756929722762;6128.938710612837;6148.385161483905;6150.600210986271;6166.784162554289;6011.549133814981;6060.454559089615;6100.48145907594;6171.753129874754;6172.074188871705;4972.477889821589;4977.054359671651;4977.129002424561;4981.32752078472;4983.626228032912;3888.747231429948;4394.494633117169;4448.332750087513;4484.417025786627;4502.212112880233;5643.125315576092;5681.517071048925;5692.8861422771315;5702.714864256771;5713.885722285614;6215.34738852414;6291.307956124822;6295.18162638854;6299.401556852092;6302.747890084437;5495.670259784426;5582.804601838916;5653.858653533694;5655.123455864347;5660.377358490563;5378.13949768043;5419.512243898068;5437.347518297751;5444.6188766785945;5490.094134467848;6252.062190671345;6300.936990630126;6314.71583778731;6315.052742088724;6319.873602528016;6102.701334035014;6241.83104353125;6294;6302.243730752339;6318.62088274705;6234.314225435108;6251.499880009497;6252.218472690914;6317.147185129948;6317.210348706411;6179.81460556184;6181.1655426516845;6188.721507532082;6195.31851496326;6213.471854892385;6049.546284028804;6089.421504957022;6274.6548939808745;6277.811665650038;6290.433860952537;5908.940910590839;6044.214252147192;6054.243219597551;6062.514998799998;6068.605540639845;4603.447586289667;6060.333363329942;6068.787592434243;6125.142480052886;6132.662703551169;5954.970225148846;5960.493358064613;5961.4038596140035;5971.641701497924;5974.312954010345;6041.24484439445;6090.908636370546;6093.146959425772;6102.877942441215;6117.418845209698;5777.422257774189;5832.037713777179;5854.736536855767;5888.4700376966275;5917.467427931335 10:01:51 INFO - PROCESS | 1663 | |1;ext.html;515.3352175693354;530.8513616187468;540.1654443884198;545.3946119807011;578.0346820809248;30308.242293942636;40317.39523907144;40485.797571012175;40853.59146408539;43037.35443968343;42919.85400729959;43140;43142;43164.352534712;43200.783996079976;8646.048934617187;9560.996095409986;9725.465099419529;9735.70792876215;9743.853842192337;11557.537698492093;11745.588904388349;11776.470058847372;12056.397180140957;12078.094142939315;10283.691489255334;11813.940930295337;11912.702182445422;11914.880851191507;11929.435171438183;11839.94080029603;11901.94049029758;11903.940480297542;11912.7617447651;11976.760464790656;11681.065514758799;11767.941160294144;11772.941135294355;11784.58753943608;11796.46915888787;39621.40567891484;39961.400578991335;39975;39981.60018399808;40864.97837554055;3306.685864842836;3481.216726236602;3536.681698647134;3756.586775454698;3806.257779732953;30415.847920760254;31486.527702084488;31669.20826979321;31686.366272674648;31694;3199.0722690419752;3321.335732853415;3358.0765289545325;3362.243495213582;3395.47370157626;501967;504209.95790041995;505284;505978;507728;59092.11361829576;59462.40537594569;59692.104618430756;59783.70108149431;59835;62746.37253627405;62801.74396512044;62853.68573157105;62966.055509167396;63231.367686323465;16060.75908861368;16274.26765795542;16284.022958622521;16287.837121628632;16314.918425408034;11356.069468190262;11484.483198256099;12484.75030499385;12665.176763510526;12782.488700451877;16231.26959286835;16599.50201493957;16609.086500242604;16618;16884.493465195817;6721.932780672229;6725.831854203684;6761.647670465828;6796.694148763318;6842.965785171043;874.2437791310471;930.330162283155;969.8690676758688;1015.9034891685426;1112.7941330853819;28536.286592835346;29516.557251641243;29858.850705746332;30150;30581.38837223243;1042.3329069395593;1084.2572837606265;1127.115214556569;1144.4220668562323;1206.4812130783848;15014.699706005818;15451.61370965735;15471.922640386727;15568;15786.9210653946;657886;661450;661773;661960;662539;57814.13278800821;58023.419765802646;58053.419465805644;58183.545411365056;58198.41801582015;1212.745323482061;1240.7642547915866;1327.2302064802584;1340.7653660609353;1372.574501904393;29452.410951780417;30991.760329586563;31046.53430198594;31242.531362030044;31924.201894951882;49274.50725492842;50004.749976249885;50093.49906501034;50136.24795628141;50212;538846;542494;544186;544405;544406;31536;33432.1641958953;34867.47698784571;36319.63680363163;36456.453153202194;38223;42856.57143428526;46741.2988805175;46943;47710.80722981953;38675.22649546937;38826.02934926649;38958.4156237651;39640.60359396369;40302.798486008556;38403.42394864024;38406.6159338403;38532.22935541329;39725;39775.60224397719;37751.2449750998;38166;38391.6160838388;38402.80798596101;38501.80749096237;31598.842005789826;32257.67742322547;33915.53221671954;34195.82902085573;34332.656673432946;24866.75133248717;25164.748352516974;25166.24501264965;25595.6160657594;25752.871235643703 10:01:51 INFO - PROCESS | 1663 | |2;jquery.html;328.20410504526524;378.12464145503156;382.85260174832683;397.33446477150767;406.10453949042363;19687.310944116958;20998;21013;21100.0504977276;21180.8940955295;19840.702389464135;20872.26947056853;21399.251026214086;21491.67762483564;21709.914504274762;5274.947250527484;5739.856503587423;6099.939000610004;6180;6248.31268560458;4196.706230563862;4446.3108218226225;6149.538784591151;6251.718397728478;6265.874682506347;5046.54581087702;6881.243063263037;6901.240863505035;6942.097527321466;6948.235694073673;6676.46588272937;6836.726530938757;6913.170419549661;6924.58452492852;6940.167179938415;5952.315483719384;5958.6424814511265;5962.314333851576;5969.253843269591;5982.521398288127;25556.87221563899;25576;25877.35306617331;26154.215373538824;26243.606345904827;2339.5554844579474;2472.3571871313407;2633.7234590367925;2821.7601503871992;2829.1512546236045;15408.22958852053;15598.844011559853;15899.60250993723;15999;16334.101624410652;1619.7732317475563;1680.2103011584536;1867.4397680695872;1879.318560876819;1976.3082920977686;88478;98548.50725746325;103602;107345.46327268392;108150.45924770326;9618.575455290646;9823.410595364301;10681.465926703633;10738.19463540222;11028.393438360967;13400.463981440633;13584.864151358557;13599.59201223965;13620.387082581308;13645.249511276777;8359.916400836035;8604.268637165911;8605.698800541952;8755.430896991675;8769.91230087691;8725.784206418919;8743.431676940978;8767.210951014311;8955.014948355676;8984.55077246135;5606.775728970795;6553.770618028346;6632.204135503771;6651.23510796255;6657.201135863727;1773.5300145461463;1806.2955447375534;1807.7559529463617;1807.9457616271534;1810.6197698483466;652.3183273479226;884.0982198157972;917.6650522559218;992.5235886774389;1003.663772636175;20400.489987750127;20456.69314960277;20943.581128377653;22279.554408911732;22469.43826404353;811.7767613906105;845.1506236232547;889.7375274294099;913.4610579757974;1011.0193112680688;10729;11308.773824523463;11334.773304534028;11349.319040857576;11444;608582;612834;614674;625108;629120;129292;129979;131594;138636;139042;923.4736200365727;973.299224558331;999.2905037423366;1014.1835822163082;1020.6274709730894;18725.719114213025;18821.623567528848;18851.90574047121;18866.151023203714;18944;53554;54725;54727.72636136794;54767;55197;81172;81662.18337816546;82442.1145259937;83186;84279;7732.652030658522;7842.9215707842195;8041.597920103854;8079.676812927417;8106.189381061847;9455.669051583161;11928.568571771442;12288.201266917622;12585.307808070644;12761.298128603015;10446.05985461312;10795.730106747395;10839.837402439127;10902.12782977344;10909.963553462409;10533.4733263335;10688.83966740484;10811.675649730521;11013.724656883642;11080;10656.946715266375;10914.617988370368;10948;11031.22781405294;11163.553457861595;7423.443241756998;7925.167857374892;7983.640736166771;8081.969261526928;8247.711330103417;10844.132469402586;10857.642794650668;10918;10941.617043403443;11023.889761102286 10:01:51 INFO - PROCESS | 1663 | |3;mootools.html;1500.4223374001006;1560;1733.1334332833583;1855.100276365963;1874.5032566369914;7899.802504937379;8126.228008339213;8386.412951093425;8954.641814327428;8987.460752354855;8003.599820008991;8098.987626546682;8464.619092140852;8680.609372578234;8688.087750786155;2358.268936629646;2412.662227288181;2416.8912398942;2417.987910060447;2428.963565546518;2108.926187583435;2361.645753137026;2397.7961873240797;2415.710114786228;2417.576924038295;2159.84881058326;2187.7155969723913;2191.046894600846;2191.901364438604;2310.4339436838;2364.0780095762666;2381.0475809676095;2383.499465112311;2385.439421735891;2385.463270764073;2348.2720356689483;2362.6574146748626;2373.703287089114;2378.048780487801;2380.988095059531;8856.601452934634;8897.021327653953;8904.28765698749;8915.73252802417;8925.821483570357;5228.712420816853;5351.143816989302;5409.486098820606;5409.864753381157;5420.91868621971;3478.5303983962094;3561.4835848801777;3584.372734771405;3614.313280476701;3651.543557055368;1000.5297510170209;1092.6448904106119;1093.7211011192176;1759.5249282693728;1829.8627602929832;11413.372264525447;11439.885601143966;11529.942350288195;11543.480543375568;11543.653690389301;4351.5430879757805;4493.977530112394;4608.216603177468;4616.30755386688;4621.560951709582;9142.771430714285;9149;9150;9156.450612963243;9156.6795162169;5226.189940559219;5228.607854410859;5238.292830467914;5239.187925871496;5243.554297884646;4769.928451073237;4805.9038819224115;4907.460179380265;4914.901701966012;4918.311436398835;3621.8913432597064;3632.3098611263777;3684.5394325709285;3700.3154416433044;3709.7774133552075;1139.4132022008673;1139.5954436175205;1142.4344949250008;1146.083133493202;1149.3391300002531;805.5126648377765;805.7623001214656;809.8137428391385;848.7453763870938;896.6771962093641;5327.280817089643;5713.514351280104;5793.275840519935;6064.727087281083;6094.603850749776;959.9632397011211;974.7465658928588;1032.2928793776287;1056.693558867935;1096.446294621211;3468.8785892493643;3676.7242456815848;3798.328735356434;3845.73079884411;4044.090079732037;9578.521073946275;10561.630342938113;10808.135349172047;10859.891401086046;10899.618513351994;10910.890891091147;10916.890831091587;10918.399488028235;10923;10940;7716.266954639413;8072.515649061075;8182.7545173644885;8308.12764659714;8316.958415208006;5942.524598032233;5997.910031349446;6054.424829641177;6083.422074902877;6142.416470435302;10023.248256380655;10113.898861011296;10142.594296228363;10154.543045563116;10175.185985121025;9482.573284202366;10138.18894488454;10140.188784897338;10362.222833287684;10371.792564148825;7198.20819709842;7451.2921272479025;7511.173770885089;7525.6989720410575;7552.509086909334;8215.671373145007;8228.25945664893;8287.50274983503;8303.086660467465;8309.626066826886;7483.476156669199;7538.396928245617;7569.1295501018085;7605.771826845204;7624.69501219945;7313.707451701873;7380.114386273682;7434.219406962191;7437.144728356308;7512.624368781649;6815.079964204967;6933.895991560232;6951.756688515878;6953.930460695531;6964.442844572523;4358.825646974085;4861.416630004281;4955.900881982412;4989.176785830297;5018.5232402921665;4925.408950925912;4948.430930442901;5063.215201643751;5134.589232861437;5189.6886186828915 10:01:51 INFO - PROCESS | 1663 | |4;prototype.html;285.2412582530468;286.8021065464828;303.2176983382872;311.59492659542605;314.5894607537164;5100.6684565503265;5293;5418.458154184579;5472.452754724525;5492.80775172869;4615.676902616819;4785.784639691214;5136.2809206711;5431.511163995239;5440.211169380447;4187.748735075906;4188.895277618053;4190.182914331691;4205.327147656376;4211.957880421187;3615.692666123383;4014.153489394882;4076.9796151019204;4149.792510374485;4177.895552611194;3856.7878766667823;4113.053997580543;4129.4012368206595;4136.110736191718;4188.392683060954;3520.8239588020497;4176.874693759161;4178.991126388995;4195.811188496525;4198.013466835291;3291.489819078047;3514.7539672222956;3538.7169026478077;3654.470101835219;3671.724620653462;4380.824767009348;4621.884452888671;5124.820631277925;5134.047922444609;5258.579313654898;4676.6960147590635;4710.576048155648;4722.645801564862;4724.314974328703;4737.810487580493;5014.548690617826;5456.292217182042;5574.526165275956;5737.483626473638;5792.291274074116;3854.5374555053295;3862.4206369044587;3870.6903447724117;3879.1465877506903;3892.610738926113;5102.285680004802;5116.769745361467;5698.0911394682835;5950.821475355746;6040.939590604082;3376.74674399421;3826.521684789401;3926.528816541976;3945.6251656092627;3968.1666849961252;3976.045749020272;3994.7416563782895;4013.7391069580385;4026.865761146597;4040.17176478827;3980.980095099506;4545.568171023748;4559.270516717309;4565.931511027337;4581;5161.535461808456;5238.528532432099;5678;5793.971030144907;5851.444112809277;1334.4862228042273;1359.320339830085;1363.9454421823214;1365.3446345753896;1365.6381059019368;757.9204183560757;796.4902462424053;829.179112678444;835.3526017336612;839.1105428246078;3913.099987003005;3994.5419921728358;4188.905547226387;4279.422277992493;4395;4267.76527291002;4978.77595508203;4983.775730092155;5014;5047.637137972727;3819.295283574928;4007.278689835799;4493.438320209973;4525;4546.318052292183;2262.7850354216325;2399.9600339711105;2450.705915290141;2624.842509449439;2646.308399782293;4593.945026557369;4794.256890182028;5025.833724753382;5139.434662187156;5163.535281824578;4491.281394976788;4766.6901651392545;4899.951000490021;5085.466026067284;5099.464556221617;4585.977070114628;5118.846434606968;5119.948800512022;5124.9743751281;5141.71720555373;4204.600562946515;4388.670849686222;4556.726596404226;4809.54117077127;4936.7284799336385;4391.516933137416;4395.351743096339;4442.400275962704;4529.456465224194;4773.307870358848;4694.7417892016265;5098.094086370733;5127.92308115386;5159.484051594961;5175.922361164661;3003.6095307610744;3282.474804031439;3420.6408327126287;3445.2764919367214;3463.8960831175104;4117.547069822377;4388.890277743082;4943.332650092191;5131.281620573048;5295.967286379273;5628.437156284339;5752.28096487939;5761.366249712612;5781.190633311256;5800.448957349214;5224.712640804641;5735.254416925773;5762.415335782525;5771.740271687868;5782.277215348082;4996.325496057985;5415.4313797050745;5508.476694713996;5626.549876009827;5628.099504079328;4750.762461876961;5716.942830571641;5718.285214348207;5766.626523483809;5780.393058728773;5067.01046382636;5244.265802787537;5588.720563971867;5617.017022021211;5690.914636280542 10:01:51 INFO - PROCESS | 1663 | |5;yui.html;707.9044329015582;723.5513981331578;748.1620584944862;777.4169372970272;860.629929130474;15143.772843407343;15647.765283520728;15951.601209969756;16065.196740162974;16104.677906441864;14560.708785824278;14607.926960365183;14785;14810.777838332433;14933.253337333117;708.3200127877244;858.9906859440149;872.7032808844991;874.1433395272637;874.567089290802;718.6801873166443;862.6506264962669;874.4272501511521;884.6992022712277;886.7339798060589;908.0919080919081;910.6721580231111;911.9635214591408;912.8950170730351;914.6067191107821;728.8651599454065;901.6844104563351;902.4946030223097;905.2169873059789;907.0521305236011;566.6883214232156;570.5663695591339;571;673.6093066021696;693.8438851258427;14629.268536573129;14672.266386680623;14674.55976320712;14689.412423503047;14700.705985880222;13540.59378218655;13794.862051379558;13846.93076534621;13858.722825543433;14130.293485325796;12151.210171338835;12315.26108433488;12396.318202498858;12403.124001278378;12894.484220631164;3783.6783873370796;4444.822207111712;4446.488653804821;4472.060867217888;4504.054148628792;119082.8091719089;119680;120362.39818800938;120415;121682.39158804237;10727.712674478957;10807.78384432307;10809.567617295377;11078.944605276922;11179.329240245612;16525.83474165243;20910.163593456396;21202.78797212039;21253.46866328323;21283.148674052863;8000.8799868002025;8007.519548827089;8050.637721302555;8086.353091752646;8177.427580069338;3983.92032159361;4187.1625674865145;4362.956370436318;4467;4616.261398176276;3535.1338921964284;3683.2265224303196;3683.7605555638797;3686.189038411545;3689.7048236141045;655.806537071565;661.8808614449446;671.1342368344781;680.7617333933084;681.386751923273;598.6467983889526;660.3993089074729;688.2085601558148;690.7789507357697;695.5756988237172;8784.956075219583;9409.717708468757;9412.67055653049;9582.616695332248;9651;595.8193507452658;651.6057785040077;664.7174950645956;688.2841844481783;693.5977133262695;4162.250794856955;4574.08518296342;4631.814727410933;4637.304404339308;4649.209634362167;115332;126609;164291;165647;175153;107756.46121769342;111546;122122.34531856234;132671;142092.86860697097;94529.10941781264;98318.50840745894;107096;107810.67987861318;113222.86777132453;8375.246227839523;9347.29895257872;9825.803483930424;9926.461398483247;10004.399736015863;50606.987860243324;54818.725906370215;58974.70512647581;60038.699806500685;65200;69834.65082674554;76788.61605691936;80120.59939700265;80227.59886200531;89082.55458722924;13254.602361929157;13344.432923911201;13428.127171734197;13739.656508587364;13876.514321998682;14973.775393368891;15149.848501514844;15288.617784555476;15385.230738463257;15973.440929567409;13767.931160344135;13973;14262;14548.272586370427;14612.196329201995;13256.668583285495;13730.45078196901;13733.793993089912;14033.508827190999;14058.156510609397;13557.25435101079;13586.456541738615;13589;13813.102148360324;14332.211728355041;10781.221098518656;10929.289596176222;11265.26775759573;11597.789572027925;12128.696782580506;11066.833997489883;11341.603043893425;11377.4880130396;11582.36297003673;11616.5353385867 10:01:51 INFO - PROCESS | 1663 | __end_tp_report 10:01:51 INFO - PROCESS | 1663 | __start_cc_report 10:01:51 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1503 10:01:51 INFO - PROCESS | 1663 | __end_cc_report 10:01:51 INFO - PROCESS | 1663 | __startTimestamp1473181311813__endTimestamp 10:01:51 INFO - PROCESS | 1663 | 10:01:51 INFO - PROCESS | 1663 | ------- Summary: start ------- 10:01:51 INFO - PROCESS | 1663 | Number of tests: 6 10:01:51 INFO - PROCESS | 1663 | 10:01:51 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5720.69 Median:5954.97 stddev:644.17 (10.8%) stddev-sans-first:617.85 10:01:51 INFO - PROCESS | 1663 | Values: 3313.9 3316.0 3337.7 3360.4 3409.9 5544.4 5645.0 5667.4 5760.8 5779.4 5646.4 5696.2 5709.6 5711.0 5720.8 5139.4 5167.9 5196.7 5222.0 5237.7 5267.9 5271.0 5273.5 5276.7 5287.6 4558.2 5176.1 5211.4 5232.9 5270.2 5273.5 5298.8 5304.3 5305.8 5369.0 5082.6 5085.3 5096.9 5209.2 5237.6 5672.7 5687.9 5704.9 5748.4 5780.6 6244.1 6257.9 6276.7 6277.9 6279.6 6115.1 6173.8 6184.9 6230.8 6259.7 5807.1 5813.4 5813.6 5834.6 5839.3 6292.3 6299.4 6305.4 6310.0 6338.5 5966.9 5985.5 6011.0 6082.4 6110.1 6138.6 6189.2 6197.9 6198.5 6218.8 6076.8 6128.9 6148.4 6150.6 6166.8 6011.5 6060.5 6100.5 6171.8 6172.1 4972.5 4977.1 4977.1 4981.3 4983.6 3888.7 4394.5 4448.3 4484.4 4502.2 5643.1 5681.5 5692.9 5702.7 5713.9 6215.3 6291.3 6295.2 6299.4 6302.7 5495.7 5582.8 5653.9 5655.1 5660.4 5378.1 5419.5 5437.3 5444.6 5490.1 6252.1 6300.9 6314.7 6315.1 6319.9 6102.7 6241.8 6294.0 6302.2 6318.6 6234.3 6251.5 6252.2 6317.1 6317.2 6179.8 6181.2 6188.7 6195.3 6213.5 6049.5 6089.4 6274.7 6277.8 6290.4 5908.9 6044.2 6054.2 6062.5 6068.6 4603.4 6060.3 6068.8 6125.1 6132.7 5955.0 5960.5 5961.4 5971.6 5974.3 6041.2 6090.9 6093.1 6102.9 6117.4 5777.4 5832.0 5854.7 5888.5 5917.5 10:01:51 INFO - PROCESS | 1663 | 10:01:51 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:70624.02 Median:20106.52 stddev:153143.01 (761.7%) stddev-sans-first:153482.15 10:01:51 INFO - PROCESS | 1663 | Values: 515.3 530.9 540.2 545.4 578.0 30308.2 40317.4 40485.8 40853.6 43037.4 42919.9 43140.0 43142.0 43164.4 43200.8 8646.0 9561.0 9725.5 9735.7 9743.9 11557.5 11745.6 11776.5 12056.4 12078.1 10283.7 11813.9 11912.7 11914.9 11929.4 11839.9 11901.9 11903.9 11912.8 11976.8 11681.1 11767.9 11772.9 11784.6 11796.5 39621.4 39961.4 39975.0 39981.6 40865.0 3306.7 3481.2 3536.7 3756.6 3806.3 30415.8 31486.5 31669.2 31686.4 31694.0 3199.1 3321.3 3358.1 3362.2 3395.5 501967.0 504210.0 505284.0 505978.0 507728.0 59092.1 59462.4 59692.1 59783.7 59835.0 62746.4 62801.7 62853.7 62966.1 63231.4 16060.8 16274.3 16284.0 16287.8 16314.9 11356.1 11484.5 12484.8 12665.2 12782.5 16231.3 16599.5 16609.1 16618.0 16884.5 6721.9 6725.8 6761.6 6796.7 6843.0 874.2 930.3 969.9 1015.9 1112.8 28536.3 29516.6 29858.9 30150.0 30581.4 1042.3 1084.3 1127.1 1144.4 1206.5 15014.7 15451.6 15471.9 15568.0 15786.9 657886.0 661450.0 661773.0 661960.0 662539.0 57814.1 58023.4 58053.4 58183.5 58198.4 1212.7 1240.8 1327.2 1340.8 1372.6 29452.4 30991.8 31046.5 31242.5 31924.2 49274.5 50004.7 50093.5 50136.2 50212.0 538846.0 542494.0 544186.0 544405.0 544406.0 31536.0 33432.2 34867.5 36319.6 36456.5 38223.0 42856.6 46741.3 46943.0 47710.8 38675.2 38826.0 38958.4 39640.6 40302.8 38403.4 38406.6 38532.2 39725.0 39775.6 37751.2 38166.0 38391.6 38402.8 38501.8 31598.8 32257.7 33915.5 34195.8 34332.7 24866.8 25164.7 25166.2 25595.6 25752.9 10:01:51 INFO - PROCESS | 1663 | 10:01:51 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:35672.45 Median:14438.66 stddev:102811.12 (712.1%) stddev-sans-first:103065.28 10:01:51 INFO - PROCESS | 1663 | Values: 328.2 378.1 382.9 397.3 406.1 19687.3 20998.0 21013.0 21100.1 21180.9 19840.7 20872.3 21399.3 21491.7 21709.9 5274.9 5739.9 6099.9 6180.0 6248.3 4196.7 4446.3 6149.5 6251.7 6265.9 5046.5 6881.2 6901.2 6942.1 6948.2 6676.5 6836.7 6913.2 6924.6 6940.2 5952.3 5958.6 5962.3 5969.3 5982.5 25556.9 25576.0 25877.4 26154.2 26243.6 2339.6 2472.4 2633.7 2821.8 2829.2 15408.2 15598.8 15899.6 15999.0 16334.1 1619.8 1680.2 1867.4 1879.3 1976.3 88478.0 98548.5 103602.0 107345.5 108150.5 9618.6 9823.4 10681.5 10738.2 11028.4 13400.5 13584.9 13599.6 13620.4 13645.2 8359.9 8604.3 8605.7 8755.4 8769.9 8725.8 8743.4 8767.2 8955.0 8984.6 5606.8 6553.8 6632.2 6651.2 6657.2 1773.5 1806.3 1807.8 1807.9 1810.6 652.3 884.1 917.7 992.5 1003.7 20400.5 20456.7 20943.6 22279.6 22469.4 811.8 845.2 889.7 913.5 1011.0 10729.0 11308.8 11334.8 11349.3 11444.0 608582.0 612834.0 614674.0 625108.0 629120.0 129292.0 129979.0 131594.0 138636.0 139042.0 923.5 973.3 999.3 1014.2 1020.6 18725.7 18821.6 18851.9 18866.2 18944.0 53554.0 54725.0 54727.7 54767.0 55197.0 81172.0 81662.2 82442.1 83186.0 84279.0 7732.7 7842.9 8041.6 8079.7 8106.2 9455.7 11928.6 12288.2 12585.3 12761.3 10446.1 10795.7 10839.8 10902.1 10910.0 10533.5 10688.8 10811.7 11013.7 11080.0 10656.9 10914.6 10948.0 11031.2 11163.6 7423.4 7925.2 7983.6 8082.0 8247.7 10844.1 10857.6 10918.0 10941.6 11023.9 10:01:51 INFO - PROCESS | 1663 | 10:01:51 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5676.08 Median:4911.18 stddev:3163.87 (64.4%) stddev-sans-first:3157.18 10:01:51 INFO - PROCESS | 1663 | Values: 1500.4 1560.0 1733.1 1855.1 1874.5 7899.8 8126.2 8386.4 8954.6 8987.5 8003.6 8099.0 8464.6 8680.6 8688.1 2358.3 2412.7 2416.9 2418.0 2429.0 2108.9 2361.6 2397.8 2415.7 2417.6 2159.8 2187.7 2191.0 2191.9 2310.4 2364.1 2381.0 2383.5 2385.4 2385.5 2348.3 2362.7 2373.7 2378.0 2381.0 8856.6 8897.0 8904.3 8915.7 8925.8 5228.7 5351.1 5409.5 5409.9 5420.9 3478.5 3561.5 3584.4 3614.3 3651.5 1000.5 1092.6 1093.7 1759.5 1829.9 11413.4 11439.9 11529.9 11543.5 11543.7 4351.5 4494.0 4608.2 4616.3 4621.6 9142.8 9149.0 9150.0 9156.5 9156.7 5226.2 5228.6 5238.3 5239.2 5243.6 4769.9 4805.9 4907.5 4914.9 4918.3 3621.9 3632.3 3684.5 3700.3 3709.8 1139.4 1139.6 1142.4 1146.1 1149.3 805.5 805.8 809.8 848.7 896.7 5327.3 5713.5 5793.3 6064.7 6094.6 960.0 974.7 1032.3 1056.7 1096.4 3468.9 3676.7 3798.3 3845.7 4044.1 9578.5 10561.6 10808.1 10859.9 10899.6 10910.9 10916.9 10918.4 10923.0 10940.0 7716.3 8072.5 8182.8 8308.1 8317.0 5942.5 5997.9 6054.4 6083.4 6142.4 10023.2 10113.9 10142.6 10154.5 10175.2 9482.6 10138.2 10140.2 10362.2 10371.8 7198.2 7451.3 7511.2 7525.7 7552.5 8215.7 8228.3 8287.5 8303.1 8309.6 7483.5 7538.4 7569.1 7605.8 7624.7 7313.7 7380.1 7434.2 7437.1 7512.6 6815.1 6933.9 6951.8 6953.9 6964.4 4358.8 4861.4 4955.9 4989.2 5018.5 4925.4 4948.4 5063.2 5134.6 5189.7 10:01:51 INFO - PROCESS | 1663 | 10:01:51 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:4328.83 Median:4649.29 stddev:1313.50 (28.3%) stddev-sans-first:1281.64 10:01:51 INFO - PROCESS | 1663 | Values: 285.2 286.8 303.2 311.6 314.6 5100.7 5293.0 5418.5 5472.5 5492.8 4615.7 4785.8 5136.3 5431.5 5440.2 4187.7 4188.9 4190.2 4205.3 4212.0 3615.7 4014.2 4077.0 4149.8 4177.9 3856.8 4113.1 4129.4 4136.1 4188.4 3520.8 4176.9 4179.0 4195.8 4198.0 3291.5 3514.8 3538.7 3654.5 3671.7 4380.8 4621.9 5124.8 5134.0 5258.6 4676.7 4710.6 4722.6 4724.3 4737.8 5014.5 5456.3 5574.5 5737.5 5792.3 3854.5 3862.4 3870.7 3879.1 3892.6 5102.3 5116.8 5698.1 5950.8 6040.9 3376.7 3826.5 3926.5 3945.6 3968.2 3976.0 3994.7 4013.7 4026.9 4040.2 3981.0 4545.6 4559.3 4565.9 4581.0 5161.5 5238.5 5678.0 5794.0 5851.4 1334.5 1359.3 1363.9 1365.3 1365.6 757.9 796.5 829.2 835.4 839.1 3913.1 3994.5 4188.9 4279.4 4395.0 4267.8 4978.8 4983.8 5014.0 5047.6 3819.3 4007.3 4493.4 4525.0 4546.3 2262.8 2400.0 2450.7 2624.8 2646.3 4593.9 4794.3 5025.8 5139.4 5163.5 4491.3 4766.7 4900.0 5085.5 5099.5 4586.0 5118.8 5119.9 5125.0 5141.7 4204.6 4388.7 4556.7 4809.5 4936.7 4391.5 4395.4 4442.4 4529.5 4773.3 4694.7 5098.1 5127.9 5159.5 5175.9 3003.6 3282.5 3420.6 3445.3 3463.9 4117.5 4388.9 4943.3 5131.3 5296.0 5628.4 5752.3 5761.4 5781.2 5800.4 5224.7 5735.3 5762.4 5771.7 5782.3 4996.3 5415.4 5508.5 5626.5 5628.1 4750.8 5716.9 5718.3 5766.6 5780.4 5067.0 5244.3 5588.7 5617.0 5690.9 10:01:51 INFO - PROCESS | 1663 | 10:01:51 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:24467.73 Median:3683.49 stddev:39154.21 (1063.0%) stddev-sans-first:39223.40 10:01:51 INFO - PROCESS | 1663 | Values: 707.9 723.6 748.2 777.4 860.6 15143.8 15647.8 15951.6 16065.2 16104.7 14560.7 14607.9 14785.0 14810.8 14933.3 708.3 859.0 872.7 874.1 874.6 718.7 862.7 874.4 884.7 886.7 908.1 910.7 912.0 912.9 914.6 728.9 901.7 902.5 905.2 907.1 566.7 570.6 571.0 673.6 693.8 14629.3 14672.3 14674.6 14689.4 14700.7 13540.6 13794.9 13846.9 13858.7 14130.3 12151.2 12315.3 12396.3 12403.1 12894.5 3783.7 4444.8 4446.5 4472.1 4504.1 119082.8 119680.0 120362.4 120415.0 121682.4 10727.7 10807.8 10809.6 11078.9 11179.3 16525.8 20910.2 21202.8 21253.5 21283.1 8000.9 8007.5 8050.6 8086.4 8177.4 3983.9 4187.2 4363.0 4467.0 4616.3 3535.1 3683.2 3683.8 3686.2 3689.7 655.8 661.9 671.1 680.8 681.4 598.6 660.4 688.2 690.8 695.6 8785.0 9409.7 9412.7 9582.6 9651.0 595.8 651.6 664.7 688.3 693.6 4162.3 4574.1 4631.8 4637.3 4649.2 115332.0 126609.0 164291.0 165647.0 175153.0 107756.5 111546.0 122122.3 132671.0 142092.9 94529.1 98318.5 107096.0 107810.7 113222.9 8375.2 9347.3 9825.8 9926.5 10004.4 50607.0 54818.7 58974.7 60038.7 65200.0 69834.7 76788.6 80120.6 80227.6 89082.6 13254.6 13344.4 13428.1 13739.7 13876.5 14973.8 15149.8 15288.6 15385.2 15973.4 13767.9 13973.0 14262.0 14548.3 14612.2 13256.7 13730.5 13733.8 14033.5 14058.2 13557.3 13586.5 13589.0 13813.1 14332.2 10781.2 10929.3 11265.3 11597.8 12128.7 11066.8 11341.6 11377.5 11582.4 11616.5 10:01:51 INFO - PROCESS | 1663 | -------- Summary: end -------- 10:01:51 INFO - PROCESS | 1663 | 10:01:52 INFO - TEST-INFO | 1663: exit 0 10:01:52 INFO - TEST-OK | dromaeo_css | took 1117610ms 10:01:52 INFO - TEST-START | kraken 10:01:52 INFO - operating with platform_type : mac_ 10:01:52 INFO - Initialising browser for kraken test... 10:01:52 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmpbNDwzm/profile http://localhost:49276/getInfo.html) 10:01:54 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 10:01:54 INFO - PROCESS | 1669 | colorDepth:24 10:01:54 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 10:01:54 INFO - PROCESS | 1669 | __metrics 10:01:54 INFO - PROCESS | 1669 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:01:54 INFO - PROCESS | 1669 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:01:55 INFO - TEST-INFO | 1669: exit 0 10:01:55 INFO - Browser initialized. 10:01:55 INFO - Running cycle 1/1 for kraken test... 10:01:55 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmpbNDwzm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:02:07 INFO - PROCESS | 1670 | RSS: Main: 232538112 10:02:07 INFO - PROCESS | 1670 | 10:02:09 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 10:02:10 INFO - PROCESS | 1670 | RSS: Main: 291344384 10:02:10 INFO - PROCESS | 1670 | 10:02:13 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 10:02:14 INFO - PROCESS | 1670 | RSS: Main: 287911936 10:02:14 INFO - PROCESS | 1670 | 10:02:17 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 10:02:18 INFO - PROCESS | 1670 | RSS: Main: 377389056 10:02:18 INFO - PROCESS | 1670 | 10:02:20 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 10:02:21 INFO - PROCESS | 1670 | RSS: Main: 280170496 10:02:21 INFO - PROCESS | 1670 | 10:02:23 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:02:23 INFO - PROCESS | 1670 | RSS: Main: 284135424 10:02:23 INFO - PROCESS | 1670 | 10:02:26 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 10:02:27 INFO - PROCESS | 1670 | RSS: Main: 321630208 10:02:27 INFO - PROCESS | 1670 | 10:02:29 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 10:02:30 INFO - PROCESS | 1670 | RSS: Main: 323670016 10:02:30 INFO - PROCESS | 1670 | 10:02:33 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 10:02:34 INFO - PROCESS | 1670 | RSS: Main: 321662976 10:02:34 INFO - PROCESS | 1670 | 10:02:35 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:02:36 INFO - PROCESS | 1670 | RSS: Main: 297783296 10:02:36 INFO - PROCESS | 1670 | 10:02:38 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:02:38 INFO - PROCESS | 1670 | RSS: Main: 303861760 10:02:38 INFO - PROCESS | 1670 | 10:02:40 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:02:41 INFO - PROCESS | 1670 | RSS: Main: 307286016 10:02:41 INFO - PROCESS | 1670 | 10:02:44 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:02:45 INFO - PROCESS | 1670 | RSS: Main: 319995904 10:02:45 INFO - PROCESS | 1670 | 10:02:48 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:02:48 INFO - PROCESS | 1670 | RSS: Main: 316022784 10:02:48 INFO - PROCESS | 1670 | 10:02:50 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 10:02:50 INFO - PROCESS | 1670 | RSS: Main: 359325696 10:02:50 INFO - PROCESS | 1670 | 10:02:50 INFO - PROCESS | 1670 | __start_tp_report 10:02:50 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 10:02:50 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 10:02:50 INFO - PROCESS | 1670 | |i|pagename|runs| 10:02:50 INFO - PROCESS | 1670 | |0;ai-astar;115;114;116;114;115;117;157;159;114;126 10:02:50 INFO - PROCESS | 1670 | |1;audio-beat-detection;163;136;153;164;136;152;152;114;159;108 10:02:50 INFO - PROCESS | 1670 | |2;audio-dft;185;185;183;183;186;183;184;183;183;181 10:02:50 INFO - PROCESS | 1670 | |3;audio-fft;78;73;102;184;75;57;57;57;140;58 10:02:50 INFO - PROCESS | 1670 | |4;audio-oscillator;83;83;133;70;71;76;118;69;70;116 10:02:50 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;87;84;86;95;120;113;87;84;88;84 10:02:50 INFO - PROCESS | 1670 | |6;imaging-darkroom;94;94;93;93;93;93;93;92;93;93 10:02:50 INFO - PROCESS | 1670 | |7;imaging-desaturate;105;134;126;86;84;84;86;84;86;84 10:02:50 INFO - PROCESS | 1670 | |8;json-parse-financial;56;54;55;54;53;54;54;54;54;54 10:02:50 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;67;65;66;83;72;71;70;71;72;72 10:02:50 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;90;86;85;88;87;87;88;81;91;86 10:02:50 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;137;140;132;137;134;136;139;132;173;151 10:02:50 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;168;168;169;168;167;169;168;168;168;169 10:02:50 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;65;68;67;68;74;82;85;83;73;67 10:02:50 INFO - PROCESS | 1670 | __end_tp_report 10:02:50 INFO - PROCESS | 1670 | __start_cc_report 10:02:50 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,2012 10:02:50 INFO - PROCESS | 1670 | __end_cc_report 10:02:50 INFO - PROCESS | 1670 | __startTimestamp1473181370755__endTimestamp 10:02:50 INFO - PROCESS | 1670 | 10:02:50 INFO - PROCESS | 1670 | ------- Summary: start ------- 10:02:50 INFO - PROCESS | 1670 | Number of tests: 14 10:02:50 INFO - PROCESS | 1670 | 10:02:50 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:124.70 Median:116.50 stddev:17.91 (15.4%) stddev-sans-first:18.65 10:02:50 INFO - PROCESS | 1670 | Values: 115.0 114.0 116.0 114.0 115.0 117.0 157.0 159.0 114.0 126.0 10:02:50 INFO - PROCESS | 1670 | 10:02:50 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:143.70 Median:152.50 stddev:19.77 (13.0%) stddev-sans-first:19.70 10:02:50 INFO - PROCESS | 1670 | Values: 163.0 136.0 153.0 164.0 136.0 152.0 152.0 114.0 159.0 108.0 10:02:50 INFO - PROCESS | 1670 | 10:02:50 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:183.60 Median:183.50 stddev:1.43 (0.8%) stddev-sans-first:1.42 10:02:50 INFO - PROCESS | 1670 | Values: 185.0 185.0 183.0 183.0 186.0 183.0 184.0 183.0 183.0 181.0 10:02:50 INFO - PROCESS | 1670 | 10:02:50 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:88.10 Median:57.50 stddev:42.68 (74.2%) stddev-sans-first:45.11 10:02:50 INFO - PROCESS | 1670 | Values: 78.0 73.0 102.0 184.0 75.0 57.0 57.0 57.0 140.0 58.0 10:02:50 INFO - PROCESS | 1670 | 10:02:50 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:88.90 Median:70.50 stddev:24.02 (34.1%) stddev-sans-first:25.38 10:02:50 INFO - PROCESS | 1670 | Values: 83.0 83.0 133.0 70.0 71.0 76.0 118.0 69.0 70.0 116.0 10:02:50 INFO - PROCESS | 1670 | 10:02:50 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:92.80 Median:86.50 stddev:13.00 (15.0%) stddev-sans-first:13.62 10:02:50 INFO - PROCESS | 1670 | Values: 87.0 84.0 86.0 95.0 120.0 113.0 87.0 84.0 88.0 84.0 10:02:50 INFO - PROCESS | 1670 | 10:02:50 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:93.10 Median:93.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 10:02:50 INFO - PROCESS | 1670 | Values: 94.0 94.0 93.0 93.0 93.0 93.0 93.0 92.0 93.0 93.0 10:02:50 INFO - PROCESS | 1670 | 10:02:50 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:84.00 stddev:19.15 (22.8%) stddev-sans-first:20.03 10:02:50 INFO - PROCESS | 1670 | Values: 105.0 134.0 126.0 86.0 84.0 84.0 86.0 84.0 86.0 84.0 10:02:50 INFO - PROCESS | 1670 | 10:02:50 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.00 stddev:0.79 (1.5%) stddev-sans-first:0.50 10:02:50 INFO - PROCESS | 1670 | Values: 56.0 54.0 55.0 54.0 53.0 54.0 54.0 54.0 54.0 54.0 10:02:50 INFO - PROCESS | 1670 | 10:02:50 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:71.50 stddev:5.00 (7.0%) stddev-sans-first:5.10 10:02:50 INFO - PROCESS | 1670 | Values: 67.0 65.0 66.0 83.0 72.0 71.0 70.0 71.0 72.0 72.0 10:02:50 INFO - PROCESS | 1670 | 10:02:50 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:87.50 stddev:2.77 (3.2%) stddev-sans-first:2.70 10:02:50 INFO - PROCESS | 1670 | Values: 90.0 86.0 85.0 88.0 87.0 87.0 88.0 81.0 91.0 86.0 10:02:50 INFO - PROCESS | 1670 | 10:02:50 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:141.10 Median:138.00 stddev:12.46 (9.0%) stddev-sans-first:13.13 10:02:50 INFO - PROCESS | 1670 | Values: 137.0 140.0 132.0 137.0 134.0 136.0 139.0 132.0 173.0 151.0 10:02:50 INFO - PROCESS | 1670 | 10:02:50 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.20 Median:168.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 10:02:50 INFO - PROCESS | 1670 | Values: 168.0 168.0 169.0 168.0 167.0 169.0 168.0 168.0 168.0 169.0 10:02:50 INFO - PROCESS | 1670 | 10:02:50 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.20 Median:73.50 stddev:7.54 (10.3%) stddev-sans-first:7.39 10:02:50 INFO - PROCESS | 1670 | Values: 65.0 68.0 67.0 68.0 74.0 82.0 85.0 83.0 73.0 67.0 10:02:50 INFO - PROCESS | 1670 | -------- Summary: end -------- 10:02:50 INFO - PROCESS | 1670 | 10:02:51 INFO - TEST-INFO | 1670: exit 0 10:02:51 INFO - TEST-OK | kraken | took 59143ms 10:02:51 INFO - SUITE-END | took 1176s 10:02:51 INFO - Completed test suite (00:19:37) 10:02:51 INFO - javascript benchmark 10:02:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3313.9005829825105, 3316.0217735767937, 3337.7496687748408, 3360.411927912615, 3409.9318013639727, 5544.445555444454, 5645.0, 5667.4049224831415, 5760.798372056979, 5779.36426993031, 5646.435356464352, 5696.202531645563, 5709.628874123179, 5711.0, 5720.79977200798, 5139.434662187156, 5167.922481162776, 5196.740162991854, 5221.973890130544, 5237.711925844077, 5267.894642107156, 5270.998510283053, 5273.525382715555, 5276.709780962045, 5287.629865909388, 4558.202314594949, 5176.094183517888, 5211.400688920785, 5232.921506177391, 5270.235815806705, 5273.4990175933035, 5298.841034768963, 5304.310439642861, 5305.840824775224, 5369.0, 5082.6188035897085, 5085.338905942241, 5096.872578185575, 5209.2446595243855, 5237.633365664405, 5672.744726487318, 5687.857803554903, 5704.914426283609, 5748.42515748426, 5780.566457515703, 6244.12582238487, 6257.906131408033, 6276.717547710364, 6277.937220627781, 6279.591826531261, 6115.082737589352, 6173.783917562909, 6184.907226391562, 6230.7819226326865, 6259.671889694423, 5807.070868660994, 5813.447722466359, 5813.563982701315, 5834.620749651259, 5839.328477225088, 6292.339304373067, 6299.401556852092, 6305.4009869062365, 6310.0, 6338.524610654219, 5966.910496342558, 5985.491233245223, 6011.0, 6082.36135205797, 6110.083487476825, 6138.600990935575, 6189.164462797555, 6197.876042479125, 6198.473129783929, 6218.782342618078, 6076.756929722762, 6128.938710612837, 6148.385161483905, 6150.600210986271, 6166.784162554289, 6011.549133814981, 6060.454559089615, 6100.48145907594, 6171.753129874754, 6172.074188871705, 4972.477889821589, 4977.054359671651, 4977.129002424561, 4981.32752078472, 4983.626228032912, 3888.747231429948, 4394.494633117169, 4448.332750087513, 4484.417025786627, 4502.212112880233, 5643.125315576092, 5681.517071048925, 5692.8861422771315, 5702.714864256771, 5713.885722285614, 6215.34738852414, 6291.307956124822, 6295.18162638854, 6299.401556852092, 6302.747890084437, 5495.670259784426, 5582.804601838916, 5653.858653533694, 5655.123455864347, 5660.377358490563, 5378.13949768043, 5419.512243898068, 5437.347518297751, 5444.6188766785945, 5490.094134467848, 6252.062190671345, 6300.936990630126, 6314.71583778731, 6315.052742088724, 6319.873602528016, 6102.701334035014, 6241.83104353125, 6294.0, 6302.243730752339, 6318.62088274705, 6234.314225435108, 6251.499880009497, 6252.218472690914, 6317.147185129948, 6317.210348706411, 6179.81460556184, 6181.1655426516845, 6188.721507532082, 6195.31851496326, 6213.471854892385, 6049.546284028804, 6089.421504957022, 6274.6548939808745, 6277.811665650038, 6290.433860952537, 5908.940910590839, 6044.214252147192, 6054.243219597551, 6062.514998799998, 6068.605540639845, 4603.447586289667, 6060.333363329942, 6068.787592434243, 6125.142480052886, 6132.662703551169, 5954.970225148846, 5960.493358064613, 5961.4038596140035, 5971.641701497924, 5974.312954010345, 6041.24484439445, 6090.908636370546, 6093.146959425772, 6102.877942441215, 6117.418845209698, 5777.422257774189, 5832.037713777179, 5854.736536855767, 5888.4700376966275, 5917.467427931335], "value": 5679.301603658281, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [515.3352175693354, 530.8513616187468, 540.1654443884198, 545.3946119807011, 578.0346820809248, 30308.242293942636, 40317.39523907144, 40485.797571012175, 40853.59146408539, 43037.35443968343, 42919.85400729959, 43140.0, 43142.0, 43164.352534712, 43200.783996079976, 8646.048934617187, 9560.996095409986, 9725.465099419529, 9735.70792876215, 9743.853842192337, 11557.537698492093, 11745.588904388349, 11776.470058847372, 12056.397180140957, 12078.094142939315, 10283.691489255334, 11813.940930295337, 11912.702182445422, 11914.880851191507, 11929.435171438183, 11839.94080029603, 11901.94049029758, 11903.940480297542, 11912.7617447651, 11976.760464790656, 11681.065514758799, 11767.941160294144, 11772.941135294355, 11784.58753943608, 11796.46915888787, 39621.40567891484, 39961.400578991335, 39975.0, 39981.60018399808, 40864.97837554055, 3306.685864842836, 3481.216726236602, 3536.681698647134, 3756.586775454698, 3806.257779732953, 30415.847920760254, 31486.527702084488, 31669.20826979321, 31686.366272674648, 31694.0, 3199.0722690419752, 3321.335732853415, 3358.0765289545325, 3362.243495213582, 3395.47370157626, 501967.0, 504209.95790041995, 505284.0, 505978.0, 507728.0, 59092.11361829576, 59462.40537594569, 59692.104618430756, 59783.70108149431, 59835.0, 62746.37253627405, 62801.74396512044, 62853.68573157105, 62966.055509167396, 63231.367686323465, 16060.75908861368, 16274.26765795542, 16284.022958622521, 16287.837121628632, 16314.918425408034, 11356.069468190262, 11484.483198256099, 12484.75030499385, 12665.176763510526, 12782.488700451877, 16231.26959286835, 16599.50201493957, 16609.086500242604, 16618.0, 16884.493465195817, 6721.932780672229, 6725.831854203684, 6761.647670465828, 6796.694148763318, 6842.965785171043, 874.2437791310471, 930.330162283155, 969.8690676758688, 1015.9034891685426, 1112.7941330853819, 28536.286592835346, 29516.557251641243, 29858.850705746332, 30150.0, 30581.38837223243, 1042.3329069395593, 1084.2572837606265, 1127.115214556569, 1144.4220668562323, 1206.4812130783848, 15014.699706005818, 15451.61370965735, 15471.922640386727, 15568.0, 15786.9210653946, 657886.0, 661450.0, 661773.0, 661960.0, 662539.0, 57814.13278800821, 58023.419765802646, 58053.419465805644, 58183.545411365056, 58198.41801582015, 1212.745323482061, 1240.7642547915866, 1327.2302064802584, 1340.7653660609353, 1372.574501904393, 29452.410951780417, 30991.760329586563, 31046.53430198594, 31242.531362030044, 31924.201894951882, 49274.50725492842, 50004.749976249885, 50093.49906501034, 50136.24795628141, 50212.0, 538846.0, 542494.0, 544186.0, 544405.0, 544406.0, 31536.0, 33432.1641958953, 34867.47698784571, 36319.63680363163, 36456.453153202194, 38223.0, 42856.57143428526, 46741.2988805175, 46943.0, 47710.80722981953, 38675.22649546937, 38826.02934926649, 38958.4156237651, 39640.60359396369, 40302.798486008556, 38403.42394864024, 38406.6159338403, 38532.22935541329, 39725.0, 39775.60224397719, 37751.2449750998, 38166.0, 38391.6160838388, 38402.80798596101, 38501.80749096237, 31598.842005789826, 32257.67742322547, 33915.53221671954, 34195.82902085573, 34332.656673432946, 24866.75133248717, 25164.748352516974, 25166.24501264965, 25595.6160657594, 25752.871235643703], "value": 20500.801296153746, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.20410504526524, 378.12464145503156, 382.85260174832683, 397.33446477150767, 406.10453949042363, 19687.310944116958, 20998.0, 21013.0, 21100.0504977276, 21180.8940955295, 19840.702389464135, 20872.26947056853, 21399.251026214086, 21491.67762483564, 21709.914504274762, 5274.947250527484, 5739.856503587423, 6099.939000610004, 6180.0, 6248.31268560458, 4196.706230563862, 4446.3108218226225, 6149.538784591151, 6251.718397728478, 6265.874682506347, 5046.54581087702, 6881.243063263037, 6901.240863505035, 6942.097527321466, 6948.235694073673, 6676.46588272937, 6836.726530938757, 6913.170419549661, 6924.58452492852, 6940.167179938415, 5952.315483719384, 5958.6424814511265, 5962.314333851576, 5969.253843269591, 5982.521398288127, 25556.87221563899, 25576.0, 25877.35306617331, 26154.215373538824, 26243.606345904827, 2339.5554844579474, 2472.3571871313407, 2633.7234590367925, 2821.7601503871992, 2829.1512546236045, 15408.22958852053, 15598.844011559853, 15899.60250993723, 15999.0, 16334.101624410652, 1619.7732317475563, 1680.2103011584536, 1867.4397680695872, 1879.318560876819, 1976.3082920977686, 88478.0, 98548.50725746325, 103602.0, 107345.46327268392, 108150.45924770326, 9618.575455290646, 9823.410595364301, 10681.465926703633, 10738.19463540222, 11028.393438360967, 13400.463981440633, 13584.864151358557, 13599.59201223965, 13620.387082581308, 13645.249511276777, 8359.916400836035, 8604.268637165911, 8605.698800541952, 8755.430896991675, 8769.91230087691, 8725.784206418919, 8743.431676940978, 8767.210951014311, 8955.014948355676, 8984.55077246135, 5606.775728970795, 6553.770618028346, 6632.204135503771, 6651.23510796255, 6657.201135863727, 1773.5300145461463, 1806.2955447375534, 1807.7559529463617, 1807.9457616271534, 1810.6197698483466, 652.3183273479226, 884.0982198157972, 917.6650522559218, 992.5235886774389, 1003.663772636175, 20400.489987750127, 20456.69314960277, 20943.581128377653, 22279.554408911732, 22469.43826404353, 811.7767613906105, 845.1506236232547, 889.7375274294099, 913.4610579757974, 1011.0193112680688, 10729.0, 11308.773824523463, 11334.773304534028, 11349.319040857576, 11444.0, 608582.0, 612834.0, 614674.0, 625108.0, 629120.0, 129292.0, 129979.0, 131594.0, 138636.0, 139042.0, 923.4736200365727, 973.299224558331, 999.2905037423366, 1014.1835822163082, 1020.6274709730894, 18725.719114213025, 18821.623567528848, 18851.90574047121, 18866.151023203714, 18944.0, 53554.0, 54725.0, 54727.72636136794, 54767.0, 55197.0, 81172.0, 81662.18337816546, 82442.1145259937, 83186.0, 84279.0, 7732.652030658522, 7842.9215707842195, 8041.597920103854, 8079.676812927417, 8106.189381061847, 9455.669051583161, 11928.568571771442, 12288.201266917622, 12585.307808070644, 12761.298128603015, 10446.05985461312, 10795.730106747395, 10839.837402439127, 10902.12782977344, 10909.963553462409, 10533.4733263335, 10688.83966740484, 10811.675649730521, 11013.724656883642, 11080.0, 10656.946715266375, 10914.617988370368, 10948.0, 11031.22781405294, 11163.553457861595, 7423.443241756998, 7925.167857374892, 7983.640736166771, 8081.969261526928, 8247.711330103417, 10844.132469402586, 10857.642794650668, 10918.0, 10941.617043403443, 11023.889761102286], "value": 9798.535209655827, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1500.4223374001006, 1560.0, 1733.1334332833583, 1855.100276365963, 1874.5032566369914, 7899.802504937379, 8126.228008339213, 8386.412951093425, 8954.641814327428, 8987.460752354855, 8003.599820008991, 8098.987626546682, 8464.619092140852, 8680.609372578234, 8688.087750786155, 2358.268936629646, 2412.662227288181, 2416.8912398942, 2417.987910060447, 2428.963565546518, 2108.926187583435, 2361.645753137026, 2397.7961873240797, 2415.710114786228, 2417.576924038295, 2159.84881058326, 2187.7155969723913, 2191.046894600846, 2191.901364438604, 2310.4339436838, 2364.0780095762666, 2381.0475809676095, 2383.499465112311, 2385.439421735891, 2385.463270764073, 2348.2720356689483, 2362.6574146748626, 2373.703287089114, 2378.048780487801, 2380.988095059531, 8856.601452934634, 8897.021327653953, 8904.28765698749, 8915.73252802417, 8925.821483570357, 5228.712420816853, 5351.143816989302, 5409.486098820606, 5409.864753381157, 5420.91868621971, 3478.5303983962094, 3561.4835848801777, 3584.372734771405, 3614.313280476701, 3651.543557055368, 1000.5297510170209, 1092.6448904106119, 1093.7211011192176, 1759.5249282693728, 1829.8627602929832, 11413.372264525447, 11439.885601143966, 11529.942350288195, 11543.480543375568, 11543.653690389301, 4351.5430879757805, 4493.977530112394, 4608.216603177468, 4616.30755386688, 4621.560951709582, 9142.771430714285, 9149.0, 9150.0, 9156.450612963243, 9156.6795162169, 5226.189940559219, 5228.607854410859, 5238.292830467914, 5239.187925871496, 5243.554297884646, 4769.928451073237, 4805.9038819224115, 4907.460179380265, 4914.901701966012, 4918.311436398835, 3621.8913432597064, 3632.3098611263777, 3684.5394325709285, 3700.3154416433044, 3709.7774133552075, 1139.4132022008673, 1139.5954436175205, 1142.4344949250008, 1146.083133493202, 1149.3391300002531, 805.5126648377765, 805.7623001214656, 809.8137428391385, 848.7453763870938, 896.6771962093641, 5327.280817089643, 5713.514351280104, 5793.275840519935, 6064.727087281083, 6094.603850749776, 959.9632397011211, 974.7465658928588, 1032.2928793776287, 1056.693558867935, 1096.446294621211, 3468.8785892493643, 3676.7242456815848, 3798.328735356434, 3845.73079884411, 4044.090079732037, 9578.521073946275, 10561.630342938113, 10808.135349172047, 10859.891401086046, 10899.618513351994, 10910.890891091147, 10916.890831091587, 10918.399488028235, 10923.0, 10940.0, 7716.266954639413, 8072.515649061075, 8182.7545173644885, 8308.12764659714, 8316.958415208006, 5942.524598032233, 5997.910031349446, 6054.424829641177, 6083.422074902877, 6142.416470435302, 10023.248256380655, 10113.898861011296, 10142.594296228363, 10154.543045563116, 10175.185985121025, 9482.573284202366, 10138.18894488454, 10140.188784897338, 10362.222833287684, 10371.792564148825, 7198.20819709842, 7451.2921272479025, 7511.173770885089, 7525.6989720410575, 7552.509086909334, 8215.671373145007, 8228.25945664893, 8287.50274983503, 8303.086660467465, 8309.626066826886, 7483.476156669199, 7538.396928245617, 7569.1295501018085, 7605.771826845204, 7624.69501219945, 7313.707451701873, 7380.114386273682, 7434.219406962191, 7437.144728356308, 7512.624368781649, 6815.079964204967, 6933.895991560232, 6951.756688515878, 6953.930460695531, 6964.442844572523, 4358.825646974085, 4861.416630004281, 4955.900881982412, 4989.176785830297, 5018.5232402921665, 4925.408950925912, 4948.430930442901, 5063.215201643751, 5134.589232861437, 5189.6886186828915], "value": 4582.904203912814, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.2412582530468, 286.8021065464828, 303.2176983382872, 311.59492659542605, 314.5894607537164, 5100.6684565503265, 5293.0, 5418.458154184579, 5472.452754724525, 5492.80775172869, 4615.676902616819, 4785.784639691214, 5136.2809206711, 5431.511163995239, 5440.211169380447, 4187.748735075906, 4188.895277618053, 4190.182914331691, 4205.327147656376, 4211.957880421187, 3615.692666123383, 4014.153489394882, 4076.9796151019204, 4149.792510374485, 4177.895552611194, 3856.7878766667823, 4113.053997580543, 4129.4012368206595, 4136.110736191718, 4188.392683060954, 3520.8239588020497, 4176.874693759161, 4178.991126388995, 4195.811188496525, 4198.013466835291, 3291.489819078047, 3514.7539672222956, 3538.7169026478077, 3654.470101835219, 3671.724620653462, 4380.824767009348, 4621.884452888671, 5124.820631277925, 5134.047922444609, 5258.579313654898, 4676.6960147590635, 4710.576048155648, 4722.645801564862, 4724.314974328703, 4737.810487580493, 5014.548690617826, 5456.292217182042, 5574.526165275956, 5737.483626473638, 5792.291274074116, 3854.5374555053295, 3862.4206369044587, 3870.6903447724117, 3879.1465877506903, 3892.610738926113, 5102.285680004802, 5116.769745361467, 5698.0911394682835, 5950.821475355746, 6040.939590604082, 3376.74674399421, 3826.521684789401, 3926.528816541976, 3945.6251656092627, 3968.1666849961252, 3976.045749020272, 3994.7416563782895, 4013.7391069580385, 4026.865761146597, 4040.17176478827, 3980.980095099506, 4545.568171023748, 4559.270516717309, 4565.931511027337, 4581.0, 5161.535461808456, 5238.528532432099, 5678.0, 5793.971030144907, 5851.444112809277, 1334.4862228042273, 1359.320339830085, 1363.9454421823214, 1365.3446345753896, 1365.6381059019368, 757.9204183560757, 796.4902462424053, 829.179112678444, 835.3526017336612, 839.1105428246078, 3913.099987003005, 3994.5419921728358, 4188.905547226387, 4279.422277992493, 4395.0, 4267.76527291002, 4978.77595508203, 4983.775730092155, 5014.0, 5047.637137972727, 3819.295283574928, 4007.278689835799, 4493.438320209973, 4525.0, 4546.318052292183, 2262.7850354216325, 2399.9600339711105, 2450.705915290141, 2624.842509449439, 2646.308399782293, 4593.945026557369, 4794.256890182028, 5025.833724753382, 5139.434662187156, 5163.535281824578, 4491.281394976788, 4766.6901651392545, 4899.951000490021, 5085.466026067284, 5099.464556221617, 4585.977070114628, 5118.846434606968, 5119.948800512022, 5124.9743751281, 5141.71720555373, 4204.600562946515, 4388.670849686222, 4556.726596404226, 4809.54117077127, 4936.7284799336385, 4391.516933137416, 4395.351743096339, 4442.400275962704, 4529.456465224194, 4773.307870358848, 4694.7417892016265, 5098.094086370733, 5127.92308115386, 5159.484051594961, 5175.922361164661, 3003.6095307610744, 3282.474804031439, 3420.6408327126287, 3445.2764919367214, 3463.8960831175104, 4117.547069822377, 4388.890277743082, 4943.332650092191, 5131.281620573048, 5295.967286379273, 5628.437156284339, 5752.28096487939, 5761.366249712612, 5781.190633311256, 5800.448957349214, 5224.712640804641, 5735.254416925773, 5762.415335782525, 5771.740271687868, 5782.277215348082, 4996.325496057985, 5415.4313797050745, 5508.476694713996, 5626.549876009827, 5628.099504079328, 4750.762461876961, 5716.942830571641, 5718.285214348207, 5766.626523483809, 5780.393058728773, 5067.01046382636, 5244.265802787537, 5588.720563971867, 5617.017022021211, 5690.914636280542], "value": 3911.056230023731, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [707.9044329015582, 723.5513981331578, 748.1620584944862, 777.4169372970272, 860.629929130474, 15143.772843407343, 15647.765283520728, 15951.601209969756, 16065.196740162974, 16104.677906441864, 14560.708785824278, 14607.926960365183, 14785.0, 14810.777838332433, 14933.253337333117, 708.3200127877244, 858.9906859440149, 872.7032808844991, 874.1433395272637, 874.567089290802, 718.6801873166443, 862.6506264962669, 874.4272501511521, 884.6992022712277, 886.7339798060589, 908.0919080919081, 910.6721580231111, 911.9635214591408, 912.8950170730351, 914.6067191107821, 728.8651599454065, 901.6844104563351, 902.4946030223097, 905.2169873059789, 907.0521305236011, 566.6883214232156, 570.5663695591339, 571.0, 673.6093066021696, 693.8438851258427, 14629.268536573129, 14672.266386680623, 14674.55976320712, 14689.412423503047, 14700.705985880222, 13540.59378218655, 13794.862051379558, 13846.93076534621, 13858.722825543433, 14130.293485325796, 12151.210171338835, 12315.26108433488, 12396.318202498858, 12403.124001278378, 12894.484220631164, 3783.6783873370796, 4444.822207111712, 4446.488653804821, 4472.060867217888, 4504.054148628792, 119082.8091719089, 119680.0, 120362.39818800938, 120415.0, 121682.39158804237, 10727.712674478957, 10807.78384432307, 10809.567617295377, 11078.944605276922, 11179.329240245612, 16525.83474165243, 20910.163593456396, 21202.78797212039, 21253.46866328323, 21283.148674052863, 8000.8799868002025, 8007.519548827089, 8050.637721302555, 8086.353091752646, 8177.427580069338, 3983.92032159361, 4187.1625674865145, 4362.956370436318, 4467.0, 4616.261398176276, 3535.1338921964284, 3683.2265224303196, 3683.7605555638797, 3686.189038411545, 3689.7048236141045, 655.806537071565, 661.8808614449446, 671.1342368344781, 680.7617333933084, 681.386751923273, 598.6467983889526, 660.3993089074729, 688.2085601558148, 690.7789507357697, 695.5756988237172, 8784.956075219583, 9409.717708468757, 9412.67055653049, 9582.616695332248, 9651.0, 595.8193507452658, 651.6057785040077, 664.7174950645956, 688.2841844481783, 693.5977133262695, 4162.250794856955, 4574.08518296342, 4631.814727410933, 4637.304404339308, 4649.209634362167, 115332.0, 126609.0, 164291.0, 165647.0, 175153.0, 107756.46121769342, 111546.0, 122122.34531856234, 132671.0, 142092.86860697097, 94529.10941781264, 98318.50840745894, 107096.0, 107810.67987861318, 113222.86777132453, 8375.246227839523, 9347.29895257872, 9825.803483930424, 9926.461398483247, 10004.399736015863, 50606.987860243324, 54818.725906370215, 58974.70512647581, 60038.699806500685, 65200.0, 69834.65082674554, 76788.61605691936, 80120.59939700265, 80227.59886200531, 89082.55458722924, 13254.602361929157, 13344.432923911201, 13428.127171734197, 13739.656508587364, 13876.514321998682, 14973.775393368891, 15149.848501514844, 15288.617784555476, 15385.230738463257, 15973.440929567409, 13767.931160344135, 13973.0, 14262.0, 14548.272586370427, 14612.196329201995, 13256.668583285495, 13730.45078196901, 13733.793993089912, 14033.508827190999, 14058.156510609397, 13557.25435101079, 13586.456541738615, 13589.0, 13813.102148360324, 14332.211728355041, 10781.221098518656, 10929.289596176222, 11265.26775759573, 11597.789572027925, 12128.696782580506, 11066.833997489883, 11341.603043893425, 11377.4880130396, 11582.36297003673, 11616.5353385867], "value": 7861.932231817123, "unit": "score"}], "name": "dromaeo_css", "value": 7374.0619821949995, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 116.0, 114.0, 115.0, 117.0, 157.0, 159.0, 114.0, 126.0], "value": 124.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 136.0, 153.0, 164.0, 136.0, 152.0, 152.0, 114.0, 159.0, 108.0], "value": 143.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 183.0, 183.0, 186.0, 183.0, 184.0, 183.0, 183.0, 181.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 102.0, 184.0, 75.0, 57.0, 57.0, 57.0, 140.0, 58.0], "value": 88.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 133.0, 70.0, 71.0, 76.0, 118.0, 69.0, 70.0, 116.0], "value": 88.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 95.0, 120.0, 113.0, 87.0, 84.0, 88.0, 84.0], "value": 92.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 93.0, 93.0, 93.0, 93.0, 92.0, 93.0, 93.0], "value": 93.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 134.0, 126.0, 86.0, 84.0, 84.0, 86.0, 84.0, 86.0, 84.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 55.0, 54.0, 53.0, 54.0, 54.0, 54.0, 54.0, 54.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 66.0, 83.0, 72.0, 71.0, 70.0, 71.0, 72.0, 72.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 85.0, 88.0, 87.0, 87.0, 88.0, 81.0, 91.0, 86.0], "value": 86.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 140.0, 132.0, 137.0, 134.0, 136.0, 139.0, 132.0, 173.0, 151.0], "value": 141.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 168.0, 169.0, 168.0, 167.0, 169.0, 168.0, 168.0, 168.0, 169.0], "value": 168.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 67.0, 68.0, 74.0, 82.0, 85.0, 83.0, 73.0, 67.0], "value": 73.2, "unit": "score"}], "name": "kraken", "value": 1505.3000000000002, "alertThreshold": 2.0}]} 10:02:52 INFO - Return code: 0 10:02:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:02:52 INFO - # TBPL SUCCESS # 10:02:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:02:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:02:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:02:52 INFO - 2016-09-06 10:02:52.106 screenresolution[1674:10459] starting screenresolution argv=screenresolution get 10:02:52 INFO - 2016-09-06 10:02:52.108 screenresolution[1674:10459] Display 0: 1600x1200x32@60 10:02:52 INFO - 2016-09-06 10:02:52.114 screenresolution[1675:10462] starting screenresolution argv=screenresolution list 10:02:52 INFO - Available Modes on Display 0 10:02:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:02:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:02:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:02:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:02:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:02:52 INFO - Intel Iris: 10:02:52 INFO - Chipset Model: Intel Iris 10:02:52 INFO - Type: GPU 10:02:52 INFO - Bus: Built-In 10:02:52 INFO - VRAM (Dynamic, Max): 1536 MB 10:02:52 INFO - Vendor: Intel (0x8086) 10:02:52 INFO - Device ID: 0x0a2e 10:02:52 INFO - Revision ID: 0x0009 10:02:52 INFO - Displays: 10:02:52 INFO - Display: 10:02:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:02:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:02:52 INFO - Display Serial Number: 1600x1200 60 10:02:52 INFO - Main Display: Yes 10:02:52 INFO - Mirror: Off 10:02:52 INFO - Online: Yes 10:02:52 INFO - Rotation: Supported 10:02:52 INFO - Return code: 0 10:02:52 INFO - Running post-action listener: _resource_record_post_action 10:02:52 INFO - [mozharness: 2016-09-06 17:02:52.351437Z] Finished run-tests step (success) 10:02:52 INFO - Running post-run listener: _resource_record_post_run 10:02:52 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 31968256; Write bytes: 709477888; Read time: 195; Write time: 2021 10:02:52 INFO - TinderboxPrint: CPU usage
54.2% 10:02:52 INFO - TinderboxPrint: I/O read bytes / time
31,968,256 / 195 10:02:52 INFO - TinderboxPrint: I/O write bytes / time
709,477,888 / 2,021 10:02:52 INFO - TinderboxPrint: CPU idle
2,221.2 (45.8%) 10:02:52 INFO - TinderboxPrint: CPU system
972.9 (20.1%) 10:02:52 INFO - TinderboxPrint: CPU user
1,655.6 (34.1%) 10:02:52 INFO - TinderboxPrint: Swap in / out
434,270,208 / 0 10:02:52 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 398283264; Write bytes: 396594688; Read time: 29456; Write time: 866 10:02:52 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 29932544; Write bytes: 296122368; Read time: 178; Write time: 1110 10:02:52 INFO - Running post-run listener: _upload_blobber_files 10:02:52 INFO - Blob upload gear active. 10:02:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:02:52 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 10:02:52 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'] 10:02:52 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 10:02:53 INFO - (blobuploader) - INFO - Open directory for files ... 10:02:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:02:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:02:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:03:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:03:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:03:13 INFO - (blobuploader) - INFO - Done attempting. 10:03:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:03:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:03:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:03:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:03:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:03:33 INFO - (blobuploader) - INFO - Done attempting. 10:03:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:03:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:03:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:03:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:03:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:03:36 INFO - (blobuploader) - INFO - Done attempting. 10:03:36 INFO - (blobuploader) - INFO - Iteration through files over. 10:03:36 INFO - Return code: 0 10:03:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:03:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:03:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/21ab08cfb04eb148c62d431d3382aff664ec3ac6abf9cfecf23c1c6eedaf9fc360e0e75b615e68f356fd9efb6ac1772a5847b9de17721d21a88be366ef923d07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2a5fe9c833c1423b2308b09743d4a545c8acab3589adbe3f0187e546adb31ea4f1ad70619e1caa7ffd46e3ab7100e2f15dfbdcca9b69c3085e59e75a2e976e85", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/20031e0d2d427794092c1a01512f08f00d514816d43632b9846bed6976b8be11dc59ee09658f69f10826d203de2294b4ccfd89d832ec38ea6c0a4b612ed4e401"} 10:03:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:03:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:03:36 INFO - Contents: 10:03:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/21ab08cfb04eb148c62d431d3382aff664ec3ac6abf9cfecf23c1c6eedaf9fc360e0e75b615e68f356fd9efb6ac1772a5847b9de17721d21a88be366ef923d07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2a5fe9c833c1423b2308b09743d4a545c8acab3589adbe3f0187e546adb31ea4f1ad70619e1caa7ffd46e3ab7100e2f15dfbdcca9b69c3085e59e75a2e976e85", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/20031e0d2d427794092c1a01512f08f00d514816d43632b9846bed6976b8be11dc59ee09658f69f10826d203de2294b4ccfd89d832ec38ea6c0a4b612ed4e401"} 10:03:36 INFO - Running post-run listener: copy_logs_to_upload_dir 10:03:36 INFO - Copying logs to upload dir... 10:03:36 INFO - mkdir: /builds/slave/test/build/upload/logs 10:03:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1313.188584 ========= master_lag: 61.47 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 54 secs) (at 2016-09-06 10:04:38.392781) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 10:04:38.396793) ========= 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.67V3NFBqTu/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.2whH08jPnY/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/21ab08cfb04eb148c62d431d3382aff664ec3ac6abf9cfecf23c1c6eedaf9fc360e0e75b615e68f356fd9efb6ac1772a5847b9de17721d21a88be366ef923d07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2a5fe9c833c1423b2308b09743d4a545c8acab3589adbe3f0187e546adb31ea4f1ad70619e1caa7ffd46e3ab7100e2f15dfbdcca9b69c3085e59e75a2e976e85", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/20031e0d2d427794092c1a01512f08f00d514816d43632b9846bed6976b8be11dc59ee09658f69f10826d203de2294b4ccfd89d832ec38ea6c0a4b612ed4e401"} build_url:https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011020 build_url: 'https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/21ab08cfb04eb148c62d431d3382aff664ec3ac6abf9cfecf23c1c6eedaf9fc360e0e75b615e68f356fd9efb6ac1772a5847b9de17721d21a88be366ef923d07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2a5fe9c833c1423b2308b09743d4a545c8acab3589adbe3f0187e546adb31ea4f1ad70619e1caa7ffd46e3ab7100e2f15dfbdcca9b69c3085e59e75a2e976e85", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/20031e0d2d427794092c1a01512f08f00d514816d43632b9846bed6976b8be11dc59ee09658f69f10826d203de2294b4ccfd89d832ec38ea6c0a4b612ed4e401"}' ========= master_lag: 0.65 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 10:04:39.060637) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 10:04:39.061045) ========= 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.67V3NFBqTu/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.2whH08jPnY/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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.005300 ========= master_lag: 0.35 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 10:04:39.417698) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 10:04:39.417985) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 10:04:40.404646) ========= ========= Total master_lag: 62.55 =========