builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0111 starttime: 1473769869.37 results: success (0) buildid: 20160913030425 builduid: d6e69507404b4feeb25f80b26b9e1a7f revision: f5d043ce6d36a3c461cbd829d4a4a38394b7c436 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-13 05:31:09.366404) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-13 05:31:09.366920) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-13 05:31:09.367330) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-13 05:31:09.410148) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 05:31:09.410509) ========= 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.QnzTprWqIg/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.q1gVyHRY3c/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-13 05:31:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 28.0M=0s 2016-09-13 05:31:09 (28.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.111315 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 05:31:09.544856) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 05:31:09.545200) ========= 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.QnzTprWqIg/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.q1gVyHRY3c/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.028276 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 05:31:09.594333) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-13 05:31:09.594691) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f5d043ce6d36a3c461cbd829d4a4a38394b7c436 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f5d043ce6d36a3c461cbd829d4a4a38394b7c436 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QnzTprWqIg/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.q1gVyHRY3c/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-13 05:31:09,671 truncating revision to first 12 chars 2016-09-13 05:31:09,672 Setting DEBUG logging. 2016-09-13 05:31:09,672 attempt 1/10 2016-09-13 05:31:09,672 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f5d043ce6d36?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-13 05:31:10,465 unpacking tar archive at: mozilla-central-f5d043ce6d36/testing/mozharness/ program finished with exit code 0 elapsedTime=1.045897 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-13 05:31:10.662575) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-13 05:31:10.663028) ========= script_repo_revision: f5d043ce6d36a3c461cbd829d4a4a38394b7c436 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-13 05:31:10.663578) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-13 05:31:10.663888) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-13 05:31:10.683838) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-13 05:31:10.684201) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QnzTprWqIg/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.q1gVyHRY3c/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 05:31:10 INFO - MultiFileLogger online at 20160913 05:31:10 in /builds/slave/test 05:31:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:31:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:31:10 INFO - {'append_to_log': False, 05:31:10 INFO - 'base_work_dir': '/builds/slave/test', 05:31:10 INFO - 'blob_upload_branch': 'Firefox', 05:31:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:31:10 INFO - 'branch': 'Firefox', 05:31:10 INFO - 'buildbot_json_path': 'buildprops.json', 05:31:10 INFO - 'config_files': ('talos/mac_config.py',), 05:31:10 INFO - 'default_actions': ('clobber', 05:31:10 INFO - 'read-buildbot-config', 05:31:10 INFO - 'download-and-extract', 05:31:10 INFO - 'populate-webroot', 05:31:10 INFO - 'create-virtualenv', 05:31:10 INFO - 'install', 05:31:10 INFO - 'run-tests'), 05:31:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:31:10 INFO - 'download_minidump_stackwalk': True, 05:31:10 INFO - 'download_symbols': 'ondemand', 05:31:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:31:10 INFO - 'tooltool.py': '/tools/tooltool.py', 05:31:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:31:10 INFO - '/tools/misc-python/virtualenv.py')}, 05:31:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:31:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:31:10 INFO - 'installer_path': 'installer.exe', 05:31:10 INFO - 'log_level': 'info', 05:31:10 INFO - 'log_name': 'talos', 05:31:10 INFO - 'log_to_console': True, 05:31:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:31:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:31:10 INFO - 'opt_config_files': (), 05:31:10 INFO - 'pip_index': False, 05:31:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:31:10 INFO - 'cmd': ('bash', 05:31:10 INFO - '-c', 05:31:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:31:10 INFO - 'enabled': True, 05:31:10 INFO - 'halt_on_failure': False, 05:31:10 INFO - 'name': 'check_screen_resolution'},), 05:31:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:31:10 INFO - 'cmd': ('bash', 05:31:10 INFO - '-c', 05:31:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:31:10 INFO - 'enabled': True, 05:31:10 INFO - 'halt_on_failure': False, 05:31:10 INFO - 'name': 'check_screen_resolution'},), 05:31:10 INFO - 'run_cmd_checks_enabled': True, 05:31:10 INFO - 'sps_profile': False, 05:31:10 INFO - 'sps_profile_interval': 0, 05:31:10 INFO - 'suite': 'dromaeojs-e10s', 05:31:10 INFO - 'system_bits': '32', 05:31:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:31:10 INFO - 'title': 't-yosemite-r7-0111', 05:31:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:31:10 INFO - 'use_talos_json': True, 05:31:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:31:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:31:10 INFO - 'work_dir': 'build'} 05:31:10 INFO - [mozharness: 2016-09-13 12:31:10.884860Z] Running clobber step. 05:31:10 INFO - Running pre-action listener: _resource_record_pre_action 05:31:10 INFO - Running main action method: clobber 05:31:10 INFO - rmtree: /builds/slave/test/build 05:31:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:31:13 INFO - Running post-action listener: _resource_record_post_action 05:31:13 INFO - [mozharness: 2016-09-13 12:31:13.088384Z] Finished clobber step (success) 05:31:13 INFO - [mozharness: 2016-09-13 12:31:13.088643Z] Running read-buildbot-config step. 05:31:13 INFO - Running pre-action listener: _resource_record_pre_action 05:31:13 INFO - Running main action method: read_buildbot_config 05:31:13 INFO - Using buildbot properties: 05:31:13 INFO - { 05:31:13 INFO - "project": "", 05:31:13 INFO - "product": "firefox", 05:31:13 INFO - "script_repo_revision": "production", 05:31:13 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:31:13 INFO - "repository": "", 05:31:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:31:13 INFO - "buildid": "20160913030425", 05:31:13 INFO - "stage_platform": "macosx64", 05:31:13 INFO - "basedir": "/builds/slave/test", 05:31:13 INFO - "nightly_build": "True", 05:31:13 INFO - "buildnumber": 292, 05:31:13 INFO - "slavename": "t-yosemite-r7-0111", 05:31:13 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:31:13 INFO - "platform": "yosemite_r7", 05:31:13 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:31:13 INFO - "branch": "mozilla-central", 05:31:13 INFO - "repo_path": "mozilla-central", 05:31:13 INFO - "revision": "f5d043ce6d36a3c461cbd829d4a4a38394b7c436", 05:31:13 INFO - "pgo_build": "False", 05:31:13 INFO - "builduid": "d6e69507404b4feeb25f80b26b9e1a7f", 05:31:13 INFO - "slavebuilddir": "test" 05:31:13 INFO - } 05:31:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/AkOssNPhREWrglmx2BtkFA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 05:31:13 INFO - Running post-action listener: _resource_record_post_action 05:31:13 INFO - [mozharness: 2016-09-13 12:31:13.094462Z] Finished read-buildbot-config step (success) 05:31:13 INFO - [mozharness: 2016-09-13 12:31:13.094660Z] Running download-and-extract step. 05:31:13 INFO - Running pre-action listener: _resource_record_pre_action 05:31:13 INFO - Running main action method: download_and_extract 05:31:13 INFO - mkdir: /builds/slave/test/build/tests 05:31: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')]} 05:31:13 INFO - trying https://queue.taskcluster.net/v1/task/AkOssNPhREWrglmx2BtkFA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 05:31:13 INFO - Downloading https://queue.taskcluster.net/v1/task/AkOssNPhREWrglmx2BtkFA/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 05:31:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AkOssNPhREWrglmx2BtkFA/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 05:31:14 INFO - Downloaded 1199 bytes. 05:31:14 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:31:14 INFO - Using the following test package requirements: 05:31:14 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:31:14 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:31:14 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 05:31:14 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:31:14 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 05:31:14 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:31:14 INFO - u'jsshell-mac.zip'], 05:31:14 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:31:14 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 05:31:14 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:31:14 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:31:14 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 05:31:14 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:31:14 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 05:31:14 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:31:14 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 05:31:14 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:31:14 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 05:31:14 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:31:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AkOssNPhREWrglmx2BtkFA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:31:14 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 05:31:21 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 05:31:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AkOssNPhREWrglmx2BtkFA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:31:22 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 05:31:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AkOssNPhREWrglmx2BtkFA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 05:31:29 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 05:31:30 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')]} 05:31:30 INFO - trying https://queue.taskcluster.net/v1/task/AkOssNPhREWrglmx2BtkFA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:31:30 INFO - Downloading https://queue.taskcluster.net/v1/task/AkOssNPhREWrglmx2BtkFA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 05:31:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AkOssNPhREWrglmx2BtkFA/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 05:31:34 INFO - Downloaded 111538977 bytes. 05:31:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AkOssNPhREWrglmx2BtkFA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:31:34 INFO - mkdir: /builds/slave/test/properties 05:31:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:31:34 INFO - Writing to file /builds/slave/test/properties/build_url 05:31:34 INFO - Contents: 05:31:34 INFO - build_url:https://queue.taskcluster.net/v1/task/AkOssNPhREWrglmx2BtkFA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:31:35 INFO - Running post-action listener: _resource_record_post_action 05:31:35 INFO - Running post-action listener: set_extra_try_arguments 05:31:35 INFO - [mozharness: 2016-09-13 12:31:35.377502Z] Finished download-and-extract step (success) 05:31:35 INFO - [mozharness: 2016-09-13 12:31:35.377660Z] Running populate-webroot step. 05:31:35 INFO - Running pre-action listener: _resource_record_pre_action 05:31:35 INFO - Running main action method: populate_webroot 05:31:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:31:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:31:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:31:35 INFO - '--tppagecycles', 05:31:35 INFO - '7'], 05:31:35 INFO - 'tests': ['tsvgm']}}, 05:31:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:31:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:31:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:31:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:31:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:31:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:31:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:35 INFO - 'talos_options': ['--disable-e10s'], 05:31:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:31:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:31:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:35 INFO - 'talos_options': ['--disable-e10s'], 05:31:35 INFO - 'tests': ['damp', 'tps']}, 05:31:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:35 INFO - 'tests': ['damp', 'tps']}, 05:31:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:31:35 INFO - 'tests': ['dromaeo_dom']}, 05:31:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:31:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:31:35 INFO - 'tests': ['basic_compositor_video']}, 05:31:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:31:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:31:35 INFO - 'tests': ['a11yr', 05:31:35 INFO - 'ts_paint', 05:31:35 INFO - 'tpaint', 05:31:35 INFO - 'sessionrestore', 05:31:35 INFO - 'sessionrestore_no_auto_restore', 05:31:35 INFO - 'tabpaint']}, 05:31:35 INFO - 'other-e10s': {'tests': ['a11yr', 05:31:35 INFO - 'ts_paint', 05:31:35 INFO - 'tpaint', 05:31:35 INFO - 'sessionrestore', 05:31:35 INFO - 'sessionrestore_no_auto_restore', 05:31:35 INFO - 'tabpaint']}, 05:31:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:31:35 INFO - 'ts_paint', 05:31:35 INFO - 'tpaint', 05:31:35 INFO - 'sessionrestore', 05:31:35 INFO - 'sessionrestore_no_auto_restore', 05:31:35 INFO - 'tabpaint']}, 05:31:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:31:35 INFO - 'ts_paint', 05:31:35 INFO - 'tpaint', 05:31:35 INFO - 'sessionrestore', 05:31:35 INFO - 'sessionrestore_no_auto_restore', 05:31:35 INFO - 'tabpaint']}, 05:31:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:31:35 INFO - 'tests': ['a11yr', 05:31:35 INFO - 'ts_paint', 05:31:35 INFO - 'tpaint', 05:31:35 INFO - 'sessionrestore', 05:31:35 INFO - 'sessionrestore_no_auto_restore', 05:31:35 INFO - 'tabpaint']}, 05:31:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:31:35 INFO - 'tests': ['a11yr', 05:31:35 INFO - 'ts_paint', 05:31:35 INFO - 'tpaint', 05:31:35 INFO - 'sessionrestore', 05:31:35 INFO - 'sessionrestore_no_auto_restore', 05:31:35 INFO - 'tabpaint']}, 05:31:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:31:35 INFO - 'tests': ['tsvgx', 05:31:35 INFO - 'tsvgr_opacity', 05:31:35 INFO - 'tart', 05:31:35 INFO - 'tscrollx', 05:31:35 INFO - 'cart']}, 05:31:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:31:35 INFO - 'tsvgr_opacity', 05:31:35 INFO - 'tart', 05:31:35 INFO - 'tscrollx', 05:31:35 INFO - 'cart']}, 05:31:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:35 INFO - 'talos_options': ['--disable-e10s'], 05:31:35 INFO - 'tests': ['tp5o']}, 05:31:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:35 INFO - 'tests': ['tp5o']}, 05:31:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:35 INFO - 'talos_options': ['--disable-e10s', 05:31:35 INFO - '--xperf_path', 05:31:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:31:35 INFO - 'tests': ['tp5n']}, 05:31:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:35 INFO - 'talos_options': ['--xperf_path', 05:31:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:31:35 INFO - 'tests': ['tp5n']}}, 05:31:35 INFO - 'talos.zip': {'path': '', 05:31:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:31:35 INFO - Running post-action listener: _resource_record_post_action 05:31:35 INFO - [mozharness: 2016-09-13 12:31:35.396347Z] Finished populate-webroot step (success) 05:31:35 INFO - [mozharness: 2016-09-13 12:31:35.396465Z] Running create-virtualenv step. 05:31:35 INFO - Running pre-action listener: _resource_record_pre_action 05:31:35 INFO - Running main action method: create_virtualenv 05:31:35 INFO - Creating virtualenv /builds/slave/test/build/venv 05:31:35 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 05:31:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:31:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:31:35 INFO - Using real prefix '/tools/python27' 05:31:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:31:36 INFO - Installing distribute.............................................................................................................................................................................................done. 05:31:39 INFO - Installing pip.................done. 05:31:39 INFO - Return code: 0 05:31:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:31: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')]} 05:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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')]} 05:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102d66490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cdc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe08cab8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b7ad30>, '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 0x1029a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b72b20>, '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.QnzTprWqIg/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.q1gVyHRY3c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 05:31: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', 'pip>=1.5'] in /builds/slave/test/build 05:31: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 pip>=1.5 05:31:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QnzTprWqIg/Render', 05:31:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:39 INFO - 'HOME': '/Users/cltbld', 05:31:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:39 INFO - 'LOGNAME': 'cltbld', 05:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:39 INFO - 'MOZ_NO_REMOTE': '1', 05:31:39 INFO - 'NO_EM_RESTART': '1', 05:31:39 INFO - 'PAGER': '/bin/cat', 05:31:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:39 INFO - 'PWD': '/builds/slave/test', 05:31:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:39 INFO - 'SHELL': '/bin/bash', 05:31:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1gVyHRY3c/Listeners', 05:31:39 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 05:31:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:39 INFO - 'USER': 'cltbld', 05:31:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:39 INFO - 'XPC_FLAGS': '0x0', 05:31:39 INFO - 'XPC_SERVICE_NAME': '0', 05:31:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:39 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 05:31:39 INFO - Cleaning up... 05:31:39 INFO - Return code: 0 05:31:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:31: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')]} 05:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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')]} 05:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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', '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 0x102d66490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cdc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe08cab8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b7ad30>, '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 0x1029a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b72b20>, '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.QnzTprWqIg/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.q1gVyHRY3c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 05:31: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:31: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 psutil>=3.1.1 05:31:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QnzTprWqIg/Render', 05:31:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:39 INFO - 'HOME': '/Users/cltbld', 05:31:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:39 INFO - 'LOGNAME': 'cltbld', 05:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:39 INFO - 'MOZ_NO_REMOTE': '1', 05:31:39 INFO - 'NO_EM_RESTART': '1', 05:31:39 INFO - 'PAGER': '/bin/cat', 05:31:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:39 INFO - 'PWD': '/builds/slave/test', 05:31:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:39 INFO - 'SHELL': '/bin/bash', 05:31:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1gVyHRY3c/Listeners', 05:31:39 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 05:31:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:39 INFO - 'USER': 'cltbld', 05:31:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:39 INFO - 'XPC_FLAGS': '0x0', 05:31:39 INFO - 'XPC_SERVICE_NAME': '0', 05:31:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:39 INFO - Downloading/unpacking psutil>=3.1.1 05:31:39 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 05:31:39 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 05:31:39 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 05:31:39 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 05:31:39 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 05:31:39 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 05:31:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:31:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:31:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:31:43 INFO - Installing collected packages: psutil 05:31:43 INFO - Running setup.py install for psutil 05:31:43 INFO - building 'psutil._psutil_osx' extension 05:31:43 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 05:31:43 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 05:31:43 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 05:31:43 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 05:31:44 INFO - building 'psutil._psutil_posix' extension 05:31:44 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 05:31:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:31:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:31:44 INFO - ^ 05:31:44 INFO - 1 warning generated. 05:31:44 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 05:31:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:31:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:31:44 INFO - Successfully installed psutil 05:31:44 INFO - Cleaning up... 05:31:44 INFO - Return code: 0 05:31:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:31:44 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')]} 05:31:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:44 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')]} 05:31:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:44 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 0x102d66490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cdc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe08cab8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b7ad30>, '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 0x1029a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b72b20>, '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.QnzTprWqIg/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.q1gVyHRY3c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 05:31:44 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 05:31:44 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 05:31:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QnzTprWqIg/Render', 05:31:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:44 INFO - 'HOME': '/Users/cltbld', 05:31:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:44 INFO - 'LOGNAME': 'cltbld', 05:31:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:44 INFO - 'MOZ_NO_REMOTE': '1', 05:31:44 INFO - 'NO_EM_RESTART': '1', 05:31:44 INFO - 'PAGER': '/bin/cat', 05:31:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:44 INFO - 'PWD': '/builds/slave/test', 05:31:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:44 INFO - 'SHELL': '/bin/bash', 05:31:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1gVyHRY3c/Listeners', 05:31:44 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 05:31:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:44 INFO - 'USER': 'cltbld', 05:31:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:44 INFO - 'XPC_FLAGS': '0x0', 05:31:44 INFO - 'XPC_SERVICE_NAME': '0', 05:31:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:31:44 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 05:31:44 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 05:31:44 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 05:31:44 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 05:31:44 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 05:31:44 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 05:31:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:31:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:31:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:31:47 INFO - Installing collected packages: mozsystemmonitor 05:31:47 INFO - Running setup.py install for mozsystemmonitor 05:31:47 INFO - Successfully installed mozsystemmonitor 05:31:47 INFO - Cleaning up... 05:31:47 INFO - Return code: 0 05:31:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:31:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:47 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 0x102d66490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cdc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe08cab8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b7ad30>, '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 0x1029a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b72b20>, '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.QnzTprWqIg/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.q1gVyHRY3c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 05:31:47 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 05:31:47 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 05:31:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QnzTprWqIg/Render', 05:31:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:47 INFO - 'HOME': '/Users/cltbld', 05:31:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:47 INFO - 'LOGNAME': 'cltbld', 05:31:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:47 INFO - 'MOZ_NO_REMOTE': '1', 05:31:47 INFO - 'NO_EM_RESTART': '1', 05:31:47 INFO - 'PAGER': '/bin/cat', 05:31:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:47 INFO - 'PWD': '/builds/slave/test', 05:31:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:47 INFO - 'SHELL': '/bin/bash', 05:31:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1gVyHRY3c/Listeners', 05:31:47 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 05:31:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:47 INFO - 'USER': 'cltbld', 05:31:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:47 INFO - 'XPC_FLAGS': '0x0', 05:31:47 INFO - 'XPC_SERVICE_NAME': '0', 05:31:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:47 INFO - Downloading/unpacking jsonschema==2.5.1 05:31:47 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 05:31:47 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 05:31:47 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 05:31:47 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 05:31:47 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 05:31:47 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 05:31:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:31:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:31:50 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 05:31:50 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 05:31:50 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 05:31:50 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 05:31:50 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 05:31:50 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 05:31:50 INFO - Downloading functools32-3.2.3-2.tar.gz 05:31:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:31:50 INFO - warning: no files found matching '*.txt' 05:31:50 INFO - no previously-included directories found matching 'build' 05:31:50 INFO - no previously-included directories found matching 'dist' 05:31:50 INFO - no previously-included directories found matching '.git*' 05:31:50 INFO - Installing collected packages: jsonschema, functools32 05:31:50 INFO - Running setup.py install for functools32 05:31:50 INFO - warning: no files found matching '*.txt' 05:31:50 INFO - no previously-included directories found matching 'build' 05:31:50 INFO - no previously-included directories found matching 'dist' 05:31:50 INFO - no previously-included directories found matching '.git*' 05:31:50 INFO - Successfully installed jsonschema functools32 05:31:50 INFO - Cleaning up... 05:31:50 INFO - Return code: 0 05:31:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:31:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:50 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 0x102d66490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cdc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe08cab8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b7ad30>, '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 0x1029a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b72b20>, '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.QnzTprWqIg/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.q1gVyHRY3c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 05:31:50 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 05:31:50 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 05:31:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QnzTprWqIg/Render', 05:31:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:50 INFO - 'HOME': '/Users/cltbld', 05:31:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:50 INFO - 'LOGNAME': 'cltbld', 05:31:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:50 INFO - 'MOZ_NO_REMOTE': '1', 05:31:50 INFO - 'NO_EM_RESTART': '1', 05:31:50 INFO - 'PAGER': '/bin/cat', 05:31:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:50 INFO - 'PWD': '/builds/slave/test', 05:31:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:50 INFO - 'SHELL': '/bin/bash', 05:31:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1gVyHRY3c/Listeners', 05:31:50 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 05:31:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:50 INFO - 'USER': 'cltbld', 05:31:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:50 INFO - 'XPC_FLAGS': '0x0', 05:31:50 INFO - 'XPC_SERVICE_NAME': '0', 05:31:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:31:51 INFO - Cleaning up... 05:31:51 INFO - Return code: 0 05:31:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:31:51 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')]} 05:31:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:51 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')]} 05:31:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:51 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 0x102d66490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cdc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe08cab8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b7ad30>, '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 0x1029a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b72b20>, '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.QnzTprWqIg/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.q1gVyHRY3c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 05:31:51 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 05:31:51 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 05:31:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QnzTprWqIg/Render', 05:31:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:51 INFO - 'HOME': '/Users/cltbld', 05:31:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:51 INFO - 'LOGNAME': 'cltbld', 05:31:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:51 INFO - 'MOZ_NO_REMOTE': '1', 05:31:51 INFO - 'NO_EM_RESTART': '1', 05:31:51 INFO - 'PAGER': '/bin/cat', 05:31:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:51 INFO - 'PWD': '/builds/slave/test', 05:31:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:51 INFO - 'SHELL': '/bin/bash', 05:31:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1gVyHRY3c/Listeners', 05:31:51 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 05:31:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:51 INFO - 'USER': 'cltbld', 05:31:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:51 INFO - 'XPC_FLAGS': '0x0', 05:31:51 INFO - 'XPC_SERVICE_NAME': '0', 05:31:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:51 INFO - Downloading/unpacking blobuploader==1.2.4 05:31:51 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 05:31:51 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 05:31:51 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 05:31:51 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 05:31:51 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 05:31:51 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 05:31:53 INFO - Downloading blobuploader-1.2.4.tar.gz 05:31:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:31:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:31:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:55 INFO - Downloading docopt-0.6.1.tar.gz 05:31:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:31:55 INFO - Installing collected packages: blobuploader, requests, docopt 05:31:55 INFO - Running setup.py install for blobuploader 05:31:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:31:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:31:55 INFO - Running setup.py install for requests 05:31:55 INFO - Running setup.py install for docopt 05:31:56 INFO - Successfully installed blobuploader requests docopt 05:31:56 INFO - Cleaning up... 05:31:56 INFO - Return code: 0 05:31:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:31:56 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')]} 05:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:56 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')]} 05:31:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:56 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 0x102d66490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cdc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe08cab8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b7ad30>, '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 0x1029a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b72b20>, '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.QnzTprWqIg/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.q1gVyHRY3c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 05:31:56 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 05:31:56 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 05:31:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QnzTprWqIg/Render', 05:31:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:56 INFO - 'HOME': '/Users/cltbld', 05:31:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:56 INFO - 'LOGNAME': 'cltbld', 05:31:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:56 INFO - 'MOZ_NO_REMOTE': '1', 05:31:56 INFO - 'NO_EM_RESTART': '1', 05:31:56 INFO - 'PAGER': '/bin/cat', 05:31:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:56 INFO - 'PWD': '/builds/slave/test', 05:31:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:56 INFO - 'SHELL': '/bin/bash', 05:31:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1gVyHRY3c/Listeners', 05:31:56 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 05:31:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:56 INFO - 'USER': 'cltbld', 05:31:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:56 INFO - 'XPC_FLAGS': '0x0', 05:31:56 INFO - 'XPC_SERVICE_NAME': '0', 05:31:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:31:56 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-EqcPNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:31:56 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-F7THQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:31:56 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Lfnm9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:31:56 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Yqp_Zy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:31:56 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-5GNKt6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:31:56 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-W73zYi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:31:56 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-23lKBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:31:57 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-8Td4X6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:31:57 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-CXyYRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:31:57 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-U9aFYC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:31:57 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-_heoj1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:31:57 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-ImiBjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:31:57 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Bkwdvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:31:57 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-b3IYH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:57 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-jywVIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:31:57 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-p9HKcw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:31:58 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-XPa1pp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:31:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:31:58 INFO - Running setup.py install for manifestparser 05:31:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:31:58 INFO - Running setup.py install for mozcrash 05:31:58 INFO - Running setup.py install for mozdebug 05:31:58 INFO - Running setup.py install for mozdevice 05:31:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:31:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:31:58 INFO - Running setup.py install for mozfile 05:31:58 INFO - Running setup.py install for mozhttpd 05:31:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:31:59 INFO - Running setup.py install for mozinfo 05:31:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:31:59 INFO - Running setup.py install for mozInstall 05:31:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:31:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:31:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:31:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:31:59 INFO - Running setup.py install for mozleak 05:31:59 INFO - Running setup.py install for mozlog 05:31:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:31:59 INFO - Running setup.py install for moznetwork 05:31:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:31:59 INFO - Running setup.py install for mozprocess 05:31:59 INFO - Running setup.py install for mozprofile 05:32:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:32:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:32:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:32:00 INFO - Running setup.py install for mozrunner 05:32:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:32:00 INFO - Running setup.py install for mozscreenshot 05:32:00 INFO - Running setup.py install for moztest 05:32:00 INFO - Running setup.py install for mozversion 05:32:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:32:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:32:00 INFO - Cleaning up... 05:32:00 INFO - Return code: 0 05:32:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:32:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:00 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 0x102d66490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cdc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe08cab8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b7ad30>, '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 0x1029a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b72b20>, '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.QnzTprWqIg/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.q1gVyHRY3c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 05:32:00 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 05:32:00 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 05:32:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QnzTprWqIg/Render', 05:32:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:00 INFO - 'HOME': '/Users/cltbld', 05:32:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:00 INFO - 'LOGNAME': 'cltbld', 05:32:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:00 INFO - 'MOZ_NO_REMOTE': '1', 05:32:00 INFO - 'NO_EM_RESTART': '1', 05:32:00 INFO - 'PAGER': '/bin/cat', 05:32:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:00 INFO - 'PWD': '/builds/slave/test', 05:32:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:00 INFO - 'SHELL': '/bin/bash', 05:32:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1gVyHRY3c/Listeners', 05:32:00 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 05:32:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:00 INFO - 'USER': 'cltbld', 05:32:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:00 INFO - 'XPC_FLAGS': '0x0', 05:32:00 INFO - 'XPC_SERVICE_NAME': '0', 05:32:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:32:00 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Wm_zup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:32:01 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)) 05:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:32:01 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-ua6CGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:32:01 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)) 05:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:32:01 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-yEev5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:32:01 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)) 05:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:32:01 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-pdzG95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:32:01 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)) 05:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:32:01 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-36VP0J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:32:01 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)) 05:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:32:01 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-4lrZmU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:32:01 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)) 05:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:32:01 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-H72wEl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:32:01 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)) 05:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:32:01 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-WCD00J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:32:01 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)) 05:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:32:01 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-tJCLjS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:32:01 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)) 05:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:32:01 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Wh8OAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:32:02 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)) 05:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:32:02 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-ArfHit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:32:02 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)) 05:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:32:02 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-a55TIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:32:02 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)) 05:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:32:02 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-63_TaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:32:02 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)) 05:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:32:02 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-eCAWg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:32:02 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)) 05:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:32:02 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-bOEtNp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:32:02 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)) 05:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:32:02 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-LRL5Nq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:32:02 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)) 05:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:32:02 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-RafsxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:32:02 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)) 05:32:02 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)) 05:32:02 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)) 05:32:02 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)) 05:32:02 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)) 05:32:02 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)) 05:32:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:32:02 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 05:32:02 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 05:32:02 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 05:32:02 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 05:32:02 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 05:32:02 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 05:32:05 INFO - Downloading blessings-1.6.tar.gz 05:32:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:32:05 INFO - Installing collected packages: blessings 05:32:05 INFO - Running setup.py install for blessings 05:32:05 INFO - Successfully installed blessings 05:32:05 INFO - Cleaning up... 05:32:05 INFO - Return code: 0 05:32:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:32:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:32:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:32:05 INFO - Reading from file tmpfile_stdout 05:32:05 INFO - Current package versions: 05:32:05 INFO - blessings == 1.6 05:32:05 INFO - blobuploader == 1.2.4 05:32:05 INFO - docopt == 0.6.1 05:32:05 INFO - functools32 == 3.2.3-2 05:32:05 INFO - jsonschema == 2.5.1 05:32:05 INFO - manifestparser == 1.1 05:32:05 INFO - mozInstall == 1.12 05:32:05 INFO - mozcrash == 0.17 05:32:05 INFO - mozdebug == 0.1 05:32:05 INFO - mozdevice == 0.48 05:32:05 INFO - mozfile == 1.2 05:32:05 INFO - mozhttpd == 0.7 05:32:05 INFO - mozinfo == 0.9 05:32:05 INFO - mozleak == 0.1 05:32:05 INFO - mozlog == 3.3 05:32:05 INFO - moznetwork == 0.27 05:32:05 INFO - mozprocess == 0.23 05:32:05 INFO - mozprofile == 0.28 05:32:05 INFO - mozrunner == 6.12 05:32:05 INFO - mozscreenshot == 0.1 05:32:05 INFO - mozsystemmonitor == 0.3 05:32:05 INFO - moztest == 0.7 05:32:05 INFO - mozversion == 1.4 05:32:05 INFO - psutil == 3.1.1 05:32:05 INFO - requests == 1.2.3 05:32:05 INFO - wsgiref == 0.1.2 05:32:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:32:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:05 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 0x102d66490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cdc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe08cab8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b7ad30>, '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 0x1029a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b72b20>, '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.QnzTprWqIg/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.q1gVyHRY3c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 05:32:05 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 05:32:05 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 05:32:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QnzTprWqIg/Render', 05:32:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:05 INFO - 'HOME': '/Users/cltbld', 05:32:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:05 INFO - 'LOGNAME': 'cltbld', 05:32:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:05 INFO - 'MOZ_NO_REMOTE': '1', 05:32:05 INFO - 'NO_EM_RESTART': '1', 05:32:05 INFO - 'PAGER': '/bin/cat', 05:32:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:05 INFO - 'PWD': '/builds/slave/test', 05:32:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:05 INFO - 'SHELL': '/bin/bash', 05:32:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1gVyHRY3c/Listeners', 05:32:05 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 05:32:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:05 INFO - 'USER': 'cltbld', 05:32:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:05 INFO - 'XPC_FLAGS': '0x0', 05:32:05 INFO - 'XPC_SERVICE_NAME': '0', 05:32:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:06 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)) 05:32:06 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)) 05:32:06 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)) 05:32:06 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)) 05:32:06 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)) 05:32:06 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)) 05:32:06 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)) 05:32:06 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)) 05:32:06 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)) 05:32:06 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)) 05:32:06 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)) 05:32:06 INFO - Cleaning up... 05:32:06 INFO - Return code: 0 05:32:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:32:06 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')]} 05:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:06 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')]} 05:32:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:06 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 0x102d66490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cdc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe08cab8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b7ad30>, '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 0x1029a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b72b20>, '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.QnzTprWqIg/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.q1gVyHRY3c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 05:32:06 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 05:32:06 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 05:32:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QnzTprWqIg/Render', 05:32:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:06 INFO - 'HOME': '/Users/cltbld', 05:32:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:06 INFO - 'LOGNAME': 'cltbld', 05:32:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:06 INFO - 'MOZ_NO_REMOTE': '1', 05:32:06 INFO - 'NO_EM_RESTART': '1', 05:32:06 INFO - 'PAGER': '/bin/cat', 05:32:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:06 INFO - 'PWD': '/builds/slave/test', 05:32:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:06 INFO - 'SHELL': '/bin/bash', 05:32:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1gVyHRY3c/Listeners', 05:32:06 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 05:32:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:06 INFO - 'USER': 'cltbld', 05:32:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:06 INFO - 'XPC_FLAGS': '0x0', 05:32:06 INFO - 'XPC_SERVICE_NAME': '0', 05:32:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:32:06 INFO - Cleaning up... 05:32:06 INFO - Return code: 0 05:32:06 INFO - Running post-action listener: _resource_record_post_action 05:32:06 INFO - Running post-action listener: _start_resource_monitoring 05:32:06 INFO - Starting resource monitoring. 05:32:06 INFO - [mozharness: 2016-09-13 12:32:06.456555Z] Finished create-virtualenv step (success) 05:32:06 INFO - [mozharness: 2016-09-13 12:32:06.457559Z] Running install step. 05:32:06 INFO - Running pre-action listener: _resource_record_pre_action 05:32:06 INFO - Running main action method: install 05:32:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:32:06 INFO - Reading from file tmpfile_stdout 05:32:06 INFO - Detecting whether we're running mozinstall >=1.0... 05:32:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:32:06 INFO - Reading from file tmpfile_stdout 05:32:06 INFO - Output received: 05:32:06 INFO - Usage: mozinstall [options] installer 05:32:06 INFO - Options: 05:32:06 INFO - -h, --help show this help message and exit 05:32:06 INFO - -d DEST, --destination=DEST 05:32:06 INFO - Directory to install application into. [default: 05:32:06 INFO - "/builds/slave/test"] 05:32:06 INFO - --app=APP Application being installed. [default: firefox] 05:32:06 INFO - mkdir: /builds/slave/test/build/application 05:32:06 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'] 05:32:06 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 05:32:41 INFO - Reading from file tmpfile_stdout 05:32:41 INFO - Output received: 05:32:41 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:32:41 INFO - Running post-action listener: _resource_record_post_action 05:32:41 INFO - [mozharness: 2016-09-13 12:32:41.194342Z] Finished install step (success) 05:32:41 INFO - [mozharness: 2016-09-13 12:32:41.194479Z] Running run-tests step. 05:32:41 INFO - Running pre-action listener: _resource_record_pre_action 05:32:41 INFO - Running main action method: run_tests 05:32:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:32:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:32:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:32:41 INFO - 2016-09-13 05:32:41.208 screenresolution[1643:8248] starting screenresolution argv=screenresolution get 05:32:41 INFO - 2016-09-13 05:32:41.211 screenresolution[1643:8248] Display 0: 1600x1200x32@60 05:32:41 INFO - 2016-09-13 05:32:41.217 screenresolution[1644:8250] starting screenresolution argv=screenresolution list 05:32:41 INFO - Available Modes on Display 0 05:32:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:32:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:32:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:32:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:32:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:32:41 INFO - Intel Iris: 05:32:41 INFO - Chipset Model: Intel Iris 05:32:41 INFO - Type: GPU 05:32:41 INFO - Bus: Built-In 05:32:41 INFO - VRAM (Dynamic, Max): 1536 MB 05:32:41 INFO - Vendor: Intel (0x8086) 05:32:41 INFO - Device ID: 0x0a2e 05:32:41 INFO - Revision ID: 0x0009 05:32:41 INFO - Displays: 05:32:41 INFO - Display: 05:32:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:32:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:32:41 INFO - Display Serial Number: 1600x1200 60 05:32:41 INFO - Main Display: Yes 05:32:41 INFO - Mirror: Off 05:32:41 INFO - Online: Yes 05:32:41 INFO - Rotation: Supported 05:32:41 INFO - Return code: 0 05:32:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:32:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:32:41 INFO - Python 2.7.3 05:32:41 INFO - Return code: 0 05:32:41 INFO - grabbing minidump binary from tooltool 05:32:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:41 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 0x102b7ad30>, '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 0x1029a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102b72b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:32:41 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 05:32:41 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 05:32:41 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 05:32:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:32:41 INFO - Return code: 0 05:32:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:32:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:32:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:32:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:32:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:32:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AkOssNPhREWrglmx2BtkFA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:32:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AkOssNPhREWrglmx2BtkFA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0111 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:32:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QnzTprWqIg/Render', 05:32:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:41 INFO - 'HOME': '/Users/cltbld', 05:32:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:41 INFO - 'LOGNAME': 'cltbld', 05:32:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:32:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:32:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:41 INFO - 'MOZ_NO_REMOTE': '1', 05:32:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:32:41 INFO - 'NO_EM_RESTART': '1', 05:32:41 INFO - 'PAGER': '/bin/cat', 05:32:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:41 INFO - 'PWD': '/builds/slave/test', 05:32:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:32:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:41 INFO - 'SHELL': '/bin/bash', 05:32:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1gVyHRY3c/Listeners', 05:32:41 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 05:32:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:41 INFO - 'USER': 'cltbld', 05:32:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:41 INFO - 'XPC_FLAGS': '0x0', 05:32:41 INFO - 'XPC_SERVICE_NAME': '0', 05:32:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AkOssNPhREWrglmx2BtkFA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:32:41 INFO - mozversion application_buildid: 20160913030425 05:32:41 INFO - mozversion application_changeset: f5d043ce6d36a3c461cbd829d4a4a38394b7c436 05:32:41 INFO - mozversion application_display_name: Nightly 05:32:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:32:41 INFO - mozversion application_name: Firefox 05:32:41 INFO - mozversion application_remotingname: firefox 05:32:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:32:41 INFO - mozversion application_vendor: Mozilla 05:32:41 INFO - mozversion application_version: 51.0a1 05:32:41 INFO - mozversion platform_buildid: 20160913030425 05:32:41 INFO - mozversion platform_changeset: f5d043ce6d36a3c461cbd829d4a4a38394b7c436 05:32:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:32:41 INFO - mozversion platform_version: 51.0a1 05:32:41 INFO - using testdate: 1473769961 05:32:41 INFO - actual date: 1473769961 05:32:41 INFO - starting webserver on 'localhost:49272' 05:32:41 INFO - SUITE-START | Running 2 tests 05:32:41 INFO - TEST-START | dromaeo_css 05:32:41 INFO - operating with platform_type : mac_ 05:32:41 INFO - Initialising browser for dromaeo_css test... 05:32:41 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpOn2Em8/profile http://localhost:49272/getInfo.html) 05:32:44 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 05:32:44 INFO - PROCESS | 1654 | colorDepth:24 05:32:44 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 05:32:44 INFO - PROCESS | 1654 | __metrics 05:32:45 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 05:32:45 INFO - TEST-INFO | 1654: exit 0 05:32:45 INFO - Browser initialized. 05:32:45 INFO - Running cycle 1/1 for dromaeo_css test... 05:32:45 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpOn2Em8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:32:57 INFO - PROCESS | 1659 | RSS: Main: 239902720 05:32:57 INFO - PROCESS | 1659 | 05:33:47 INFO - PROCESS | 1659 | 1473770027562 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] 05:36:00 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 05:36:01 INFO - PROCESS | 1659 | RSS: Main: 256888832 05:36:01 INFO - PROCESS | 1659 | 05:39:03 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 05:39:04 INFO - PROCESS | 1659 | RSS: Main: 265232384 05:39:04 INFO - PROCESS | 1659 | 05:42:06 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 05:42:06 INFO - PROCESS | 1659 | RSS: Main: 269115392 05:42:06 INFO - PROCESS | 1659 | 05:45:08 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 05:45:09 INFO - PROCESS | 1659 | RSS: Main: 268697600 05:45:09 INFO - PROCESS | 1659 | 05:48:12 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 05:48:13 INFO - PROCESS | 1659 | RSS: Main: 274190336 05:48:13 INFO - PROCESS | 1659 | 05:51:15 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 05:51:16 INFO - PROCESS | 1659 | RSS: Main: 276697088 05:51:16 INFO - PROCESS | 1659 | 05:51:16 INFO - PROCESS | 1659 | __start_tp_report 05:51:16 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 05:51:16 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 05:51:16 INFO - PROCESS | 1659 | |i|pagename|runs| 05:51:16 INFO - PROCESS | 1659 | |0;dojo.html;3027.5610036544704;3136.8902088426908;3277.88527401541;3430.536877521535;3439.86240550378;5714.51426628736;5877.911831322528;5912.526997840173;5983;6011.917854786137;5599.720013999294;5655.830325090244;5771.278590176228;5907.822765317037;6010.368911264309;4488.910221795562;5253.0544501989625;5274.235235890794;5365;5446.210299506569;4808.797800549863;5407.188921661763;5442.537384322337;5458.317710286215;5515.420880807517;4637.953620463786;5006.724630145377;5281.471852814726;5300.655457395257;5343.572514198855;5283.049051170788;5387.676739395649;5387.703676297802;5395.001924643953;5411.783528658888;5262.526372626482;5309.65487243328;5330.840074797762;5349.465053494659;5436.619436639437;5625.915611265835;5672.971635141839;5760.654360738327;5773.24947756789;5968.940310596882;5856.795012174553;6214.1921550198185;6236.688165591702;6276.121343011982;6286.685665716695;6232.781852635136;6251.531135164881;6269.341719119518;6300.716467758916;6311.990081586924;6003.969980150071;6004.219451471281;6009.669468179205;6011.158437818709;6025.638461692312;6226.59527130735;6262.2172228471445;6269.404406581368;6293.685315734287;6297.559170858088;6242.188515492957;6245.500359971192;6262.1546091278005;6277.811665650038;6291.96854015727;6099.279518885405;6213.161223234805;6292.874142517124;6302.590331628429;6335.746570137235;6214.782482613177;6282;6289.213848268966;6294.181756371642;6327.303996560375;6241.500679945593;6293.811185664437;6306.558540902093;6310.5267104967315;6318.336574659686;4887.755612219375;4930.433000204951;4958.107540642647;4967.379077615298;5030;4441.733495990186;4460.063386688832;4504.144212599661;4547.545245475492;4574.428196475441;5872.148538461985;5902.3507414184405;5928.081147422156;5981.6710080945095;5986.2517185351835;5114.232865070195;6262.780802671885;6293.056041593706;6293.37066293367;6295;5815.244018277597;5852.122181672698;5884.676342801187;5907.911381329283;5974.85062873423;5469.890602187934;5480.780768769284;5483.232347471358;5484.808031718871;5496.038193316233;5353.705546194997;6222.346653601307;6377.23473183221;6382.489400847828;6416.101745755598;6166.845828854135;6230;6242.719077641426;6274.811755647338;6283.120363149254;5226.346706661668;5581.386047534686;5768.278965129359;5793.3337666167245;6394.488440924808;4665.48679645232;4962.255661650781;5188.896222075613;5473.534749546333;6287.2769631492965;6211.689415529296;6239.906401403891;6242.063690446469;6269;6279.686015699288;5405.702686352289;5582.609217354909;6188.9690551546955;6250.812475625738;6272.2159730033745;5161.587073034223;6078.088286757022;6200.720967556561;6247.406496382836;6263.467605253604;6010.639361638316;6022.397760223943;6089.634621922699;6112.351504965139;6159;6165.815025549241;6197.163382943245;6210.068489726577;6233;6250.624962502265;6042.788502402394;6130.6628135452975;6132;6145.13968044483;6149.631022138686 05:51:16 INFO - PROCESS | 1659 | |1;ext.html;481.2732773511997;498.6085922550796;508.8549763317455;513.7662363624552;531.8936611847355;38818;40570.79714601423;40580.79709601452;40704.38943415851;40753.99999999996;41872.58127418725;42214.36678449818;42485;42512.36231456523;42568;9730.859210973575;9842.261830362751;9886.110250077492;9926.751831204205;9932.702018939442;9773.951130244375;9776.120149186572;9806.656767013155;9858.260630452709;9875.456849873253;9852.852207216898;9874.16069634082;9889.802203955916;9895.802083958317;9907.266228410035;8476.78808029801;9801.362911410772;9855.162311203556;9865.704028879145;9886.307958442912;9761.219102471856;9775.608975640966;9850.60597576096;9850.704478865646;9856.556454959544;40417.79791101025;40627.7968610158;40697.98255043648;40711.77864664065;40751.592484075074;3314.370269648783;3327.3678001179696;3379.4423920053164;3440.913977150566;3452.602950660681;30270.57835373165;30534.84732576345;30663.84668076645;30932.845335773178;31000;3112.9065279157835;3237.8057316560844;3242.2866969266724;3243.059512741302;3258.446064169097;463438;463869;465290;468790;469002;58728.41271587229;58897.11654325188;58989;58999;59063.704681476316;28153.296167595974;33870.661293387246;34978.65021349754;61535;61548;16348.918255408884;16550.6689866202;16627.83372166287;16702.58243543897;16767;12784.424700888483;12843.935780321039;12937.676558086123;12951.417186226454;12964;15391.92304038495;18617;18919.243230270637;19204.807951920582;19332.516687082654;6797.898031529531;7807.609619519001;7922.207779222032;7998.520088794691;8015.679372825139;828.9005319361715;853.6116067189414;867.418829384314;1016.070295679445;1060.533365319257;24543.877280613484;28915.855420723183;29909.850450747606;30463.933762318655;30907.072787815952;893.9910600893908;1042.6559235452282;1044.4464433850071;1059.4120263253815;1067.8665166854144;15223.010504317184;15480.612984675474;15727.213639317988;15729.292181851844;15922.442714504938;592471;599766;601090;601395;601933;55996.72001639966;56886;57101.71449142728;57225.14162287486;57238.56903577444;1301.9414126364336;1326.7479178955969;1346.8047133165833;1350.3990724127866;1351.63505853421;28171.295717607223;29295.267618309714;29422.411551768415;29900.85049574738;29948.550771738875;36529.45205821968;49022.26466603075;49378;49538.25692614542;49557;568132;569136;570547.147264261;571520;572069;26336.078237261605;26473.867630662495;26856.462870742867;26878.193654189625;26959.86520067387;46125.538744612124;46666.533334666216;46707;47342.76328618335;47522.762386187846;38853.81204477181;38971;39163.41254881122;40319.1936161281;40474.797626011685;38638.22723545457;38838.02904927287;38912;39892.00269993273;39966.80016599898;36076;38398.616013839506;38802.41796373113;38891.80554097211;40432.79783601063;31149.688503114678;34300;35043;35118.29763404769;35349.93950181499;24603.384915377257;25611.48777024412;25780.742192577833;25907.611385829605;26097.346371508385 05:51:16 INFO - PROCESS | 1659 | |2;jquery.html;361.2504054087767;369.48272418613936;376.04109520722153;382.64414094891777;391.9398028333359;18826.34107806227;18975.52561185971;19970.7004394934;20855.37433876984;21057.47356316093;11743.941280293586;11809.586664466746;12186.573469928531;12188.451519681614;12200.450979705935;5311.389190243133;5621.943780562204;5628;5635.013872572304;5642.407547207546;3940.330143875548;4144.8963775905695;5476.479734425223;5895.852603684899;6132.4480796728285;6211.0993905883615;6217.222847144107;6220.813375598716;6225.968870155642;6226.750929962796;5881.441263080001;6105.084237364386;6113.296970848365;6129.938700612981;6141.416565426277;4832.275158726184;5083.669561478492;5807.448292412214;5906.940930590682;5968.283805943272;13299.667508312274;13314.600561983156;13348.532801351907;13365.732685346238;18343.724844127348;1828.3783513605306;2040.6020825938947;2072.968905466419;2382.4282172278527;2858.3854471288564;8410.201030902055;9753.366031207948;9966.850497242547;10098.192144628485;10401;1556.486359501362;1830.5057634438626;1861.3764388929724;1909.9236030558761;1928.8939108349034;116248;119421.80578194193;119674;120601;121705.39147304295;10553.577856885791;10810.135189185004;11220.270682405617;11381.582853410893;11511.597094101666;14292.356843942047;14294.28528573588;14327.35526901292;14333.354999025069;14350.928245358915;8229.95885020583;8465.703700370457;8528.6780549015;8614.913850861536;8617.61220745068;8185.590720463953;8344.582770861432;8406.369522285853;8442.15578442151;8573.442726222776;3772.5850156483316;4388.780560972003;4424.712393694464;4463.553644635511;4555.180067587799;1555.929983150761;1558.984410155884;1649.1177220187142;1650.636859890822;1866.0033763872798;761.8133557278502;880.1682410122373;941.1200527506803;973.8831340239217;1001.7545701303227;19614.901925490565;20332.881691506813;20399.08204130818;20405.285814996405;20547.486312842;900.2392977933636;902.7878448564582;937.2923442800642;937.704623043739;968.3899143539525;9898.406095634142;10907.454627268606;10936.96098870606;10957.671269861918;10966.725831854108;569938;575498;576199;588366;590219;126238;127020;127100;127770.41721870897;128376.35811821069;953.1516949914582;960.711786464058;966.9081437263449;967.7483854197959;979.8334283171878;17823.821761782216;17862.46412607624;18130.093495325444;18154.364597239033;18428.98640574781;52539.737301313246;52992;53084.469155309496;53686.73156634192;53907;75449.49101018059;75744.62127689326;76237.13425322584;77924.42831288667;78584.60707696425;5018.924716129188;5903.7048147593305;6999.1950925644205;7234.457415693739;7483.690354188104;11447.14146439037;12361.949234315183;12506.624801255977;12680.6829829255;12761.680957976125;11018.889811101786;11052;11074.335539867634;11088.501017454073;11244.325340479598;5042.621803364689;7681.577513236825;9091.818163636557;9561.330706850455;11029.172812038969;11480.770384592095;11501.942490287494;11675.299482031105;11800.881991179978;11878.049756019325;6833.350831670983;8214;11182.099043162845;11240.7751844961;11872.703182420508;9119.270458363448;9219.86170207434;9948.452835093849;10375.429351385746;10474.21443391764 05:51:16 INFO - PROCESS | 1659 | |3;mootools.html;1484.9703005939882;1497.79030935669;1731.4372828830633;1842.7420161177447;1882.7175923611449;8110.918890811105;8418.831623367529;8620.827583448336;9146.039665835084;9199.080091990805;7192.604406757626;8538.18887205716;8843.204111629952;8866.77833054174;9331.813363732721;2402.1112188490238;2409.3976505873534;2420.1892366057305;2421.5641184586766;2425.733169351379;2388.414838364603;2403.375122468153;2425.805935525154;2429.125514814665;2435.3181109289426;2391.390195500154;2399.400149962509;2409.4578719788083;2415.0460568075687;2424.59994100974;2386.140989243871;2394.497155597318;2397.8561286322874;2404.9398765030837;2409.2290467050375;2372.893219805113;2392.1268736911074;2394.6408038794148;2405.7233418156784;2407.6629271901948;7859.52842829432;8997.775055623597;9007.324450666161;9021.278297736166;9090.409123407024;5321.467853214686;5468.7539060742365;5470.425605311425;5475.123980163195;5496.395396506381;3533.3993221152464;3655.268946210742;3696.963030369716;3701.6668499834873;3760.6051364606597;1054.8259537176357;1077.1921059205595;1083.182197440935;1088.8638920134983;1113.287496002559;11864.762704745857;11917.761644767055;11958.701032474171;11962.102842286777;11992;4192.685548583869;4500.887477813016;4689.132510485571;4838.080764654705;4870.220764677634;9989.350692204984;10065.194784417376;10074.74813129663;10088.69733907984;10112.74718132038;5399.109146990702;5422.593305502103;5428.131498960148;5436.429174936654;5437.619366644297;4268.445102136702;4429.955700443019;4595.816167353335;4751.287306903992;4882;3892.3772196449;3933.272344616255;4001.6998725095737;4043.2317859606583;4050.4734384529825;1278.3991523983714;1287.2533930320392;1290.760869565209;1297.6172029251393;1299.4282515693064;782.342832021105;889.2219308105408;918.3892711347013;920.3005715656016;925.4753984409883;4766.165920963817;5601;5654.208410822406;5898.203742494794;6157.415045570664;942.4439580647314;961.3030552849128;966.2946049383849;977.5063592885547;1060.55986765493;3919.1377896862646;3932.311845427039;3937.3897045957924;4016.9397459038137;4042.9797851010558;10491.475426228812;10933.398663073607;10945.01494865466;10955.397453139994;10963.122950163968;10918.235723499438;10923;10923.672289831318;10935.633045869266;10948.069414099891;7269.055022847102;7288.526245794006;8247.134050924686;8458.111898250718;8461.026981897037;5735.7705691773535;5789.2763404574425;5841.737121829443;5911.231539899957;5973.074173503114;10248.846267305848;10251.897481025095;10314.381137131797;10384;10416.11463025621;9767.60929562838;9861.556229969812;10132.442715650415;10292.485375731034;10495.16038716916;6909.896351554831;7122.821929451805;7268.491205615556;7443.627818608939;7642;8301.58492075406;8446.366522510714;8447.493150410995;8460.450070745383;8469.322454203771;6531.869362612816;7472.29013243741;7620.923790762022;7689.42329325314;7753.883691744515;7175.425965922609;7239.384652304613;7360.447966402434;7400.407967362706;7462.216467270859;7017.2631873652535;7033.331833475811;7107.715691372494;7109;7116.857662846611;5014.17266151081;5059.291699162194;5087.3386459759995;5093.71984540839;5142.074426603247;4931.728754918514;5031.949680503149;5076.416212135652;5125.410577783603;5194.454582268807 05:51:16 INFO - PROCESS | 1659 | |4;prototype.html;296.43677013674017;302.6307904356684;307.9399517094167;309.4121169777424;312.613921806569;5319.441458646835;5468.234447177399;5480.643758155722;5549.833504994852;5551.417101204371;4172.457580514537;5236.7119808410525;5356;5434.7826086956475;5442.292501974747;3797.8731910130414;4190.916181676364;4196.412502249687;4202.789860506978;4217.3341530095595;3240.7569432292553;3755.981220093896;3926.2736393767245;4188.560201178878;4195.538490766014;4043.65628921542;4057.3508238681816;4062.248484030449;4102.5897410258885;4104.958950410502;3662.0844788802665;4031.496062992126;4085.937656209392;4107.958920410788;4125.731827431237;2963.73326400625;3233.7898036627544;3526.347625689256;3543.94684079739;3548.1661809474754;4957.429895562021;5178.663386879841;5281.70950597717;5298.390685071227;5425.186222066682;3798.53756303825;3989.3816458448987;4069.1719255117705;4721.905561888743;4791.2573551099285;5423.376311724162;5713.771449142029;5741.540676745851;5773.06602288234;5787.218725472091;3882.165334453106;3946.052947292658;3969;4024.3963405489117;4024.517057953035;5259.237410575483;6067.302260240085;6083.634981901056;6115.1744514490865;6188.9999999999545;4088.7342322748927;4095.5699651536756;4097.496640372438;4110.09577892869;4113.218488487178;3762.3227818992846;3965.6232657897453;4108.712390132722;4119.320312148462;4119.649829764444;4648.814552289145;4727.550882666142;4740.288956656529;4745.857624271277;4792.2332426811545;4413.602775750198;5706.80026199081;5722.54219662426;5790.681512516768;5895.4399332063385;1311.8556958734532;1323.5110500686728;1377.5040985245505;1390.6106290238547;1396.5880065380736;918.8437965545874;940.7083804020775;966.8598053282235;967.4872317671504;968.4479846487575;4459.955400446019;5135.255387968724;5280.6039547034125;5328.3605967284175;5346.224797404355;5038.833301680239;5719.28508936383;5831.4168583141345;5858.502027327725;5870.354261031283;4157.10622216225;5139.098533542613;5270.683758974474;5363.463653634605;5439.102548079522;2703.202555246207;2810.9156725298276;3072.354805490872;3085.6760040195454;3090.8100381353343;5709.68596727184;6092.5430592705725;6114.9694251529345;6126.632402055891;6151.84620384494;5092.567131793765;5300.655457395257;5603.9072380886155;5923.378045305267;6008.939910600925;4988.376452943382;6064.848378790565;6087.239095113111;6148.323684394713;6151.5386346024225;4682.4381074271305;5516.310461192351;5853.64878107315;5862.853428664317;5871.4715675589405;5361.731913404236;5786.9131963019745;5792;5831.804480081489;5858.47273745365;5744.138379243147;5755.539556835527;6061.787837425669;6070.907236697437;6128.325884152829;3371.561696979377;3610.3140403323287;3638.5633723952237;3668.4497325401403;3883.165119499321;4475.25465068617;5512.944870551243;5867.237259156283;5896.587238893237;5902.439268269508;4782.473927868002;5048.873778155576;5383.627175070332;5515.972420137874;5595.356533998643;4751.453582838018;5271.4464981176425;5747.8563035924435;5748.45389687979;5783.190353350618;4329;5411.891762164813;5422.593305502024;5627.409122042291;5653.802116925888;4641.605463535638;4929.556339929423;5015.996800639814;5325.949509438817;5595.740958284354;4641.350210970534;4854.417469903493;5191.870203244798;5597.720113994365;5627.915581266202 05:51:16 INFO - PROCESS | 1659 | |5;yui.html;693.8716337477567;728.4936968806682;770.445279398833;772.3126417488439;792.9770595931247;15481.767773483392;15605.609859753511;15724.213789310517;15747.921260393696;15763.527094187164;14059.718805623881;14135.858641413583;14226.786598201035;14231.501897433593;14255.50105746299;664.7374287156571;684.336193891924;798.2217338095354;805.3637626275236;827.148037521354;820.8645573480399;826.6817275348974;839.2824135364267;839.546644811804;839.911809260028;898.6046139698544;898.6585097662879;900.3967341880956;900.8108297257551;900.8738776571286;874.9081346458657;878.5958459108817;892.6117139044504;893.4282059481936;893.7229458867772;658.045833541367;663.171036204744;663.7743167323085;664.2029564522592;665.9833504162386;15318.157501337422;15403.768943465857;15548.378064877392;15553.688926221525;15577.922110389376;14600.926995365062;14646.926765366212;14672.92663536686;14697.26513674312;14929.776053359208;11626.534938602446;11753.177277590572;12039.759204815942;12215.328156951364;12499.56251531192;3774.8490060397826;3780.621937806225;3961.2869683456966;4014.237294913957;4018.578049304839;119411;121223.39388303002;122043.38978305052;122350.38824805908;123832.9999999991;11155.219134660658;11215.66353009411;11272.492737826817;11338.43307834605;11639.709007274885;23715.169969051;23780;23790.286291411285;23798.64302035471;23815.047398104227;8030.759077227693;8129.593520323961;8214.973128358955;8235.258826705625;8425.452345597516;4435.2016637005;4507.408240261862;4518.774061296922;4542.136993971201;4676.368690226844;3572.3391172633146;3652.0504668786307;3679.208970071394;3696.7966761828357;3710.239400922804;639.7632875835877;695.7078027228577;705.5872314695961;730.3901242462604;730.8781021132536;597.5339235396398;633;635.9713812878432;651.6318280171688;653.4968074582545;7925.722599708983;8568.742937711879;8943.642254309885;9443.198245351787;9632.711018669452;549.2886711708268;645.8600175064705;658.1772784019975;660.6795704083515;690.0580707334552;4474.485434175112;4488.955110448919;4498.955010449919;4516.774161291923;4540.432445944257;165736.08375246284;169157;170318;173065.50148480618;175301.12349438426;117324.41337793256;122604.38697806453;148223.07107715786;149033.25483372732;152833.23583382234;108884;119935.40032299783;123232;123621;126345.99999999817;8786.121387861163;8916.643334266557;8980.46117232968;9027;9106.908930910606;52053.7397313011;55195.34413967587;69433.65283173551;69744.95382569164;71151;97508;101169.49415252877;102203.52384788697;104267;105546;11667.533298667959;11694.70763230926;11703.478547788733;11828.467718952495;12394.62816115518;12936.611901642966;12964.481420743065;13157.210567365988;13288.06983511145;13674.589762307147;11897.524099035942;11937.28376297425;12165.087618428477;12228.510859565518;12624.684382890502;12204.572839950559;12204.816927745913;12217.633470995885;12538.87461125377;12619;10571.312864663772;11788.410579471163;11868.10989175798;12186.451609677762;12615.747685046432;10021.348612340174;10196.898031019891;10482.63310784119;10488.213383996079;10678.145748339959;8831.558422078999;10519.316244444086;10593.735156621146;10599.258051936598;10645.574177033142 05:51:16 INFO - PROCESS | 1659 | __end_tp_report 05:51:16 INFO - PROCESS | 1659 | __start_cc_report 05:51:16 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,376 05:51:16 INFO - PROCESS | 1659 | __end_cc_report 05:51:16 INFO - PROCESS | 1659 | __startTimestamp1473771076052__endTimestamp 05:51:16 INFO - PROCESS | 1659 | 05:51:16 INFO - PROCESS | 1659 | ------- Summary: start ------- 05:51:16 INFO - PROCESS | 1659 | Number of tests: 6 05:51:16 INFO - PROCESS | 1659 | 05:51:16 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5748.74 Median:6003.97 stddev:668.45 (11.1%) stddev-sans-first:635.50 05:51:16 INFO - PROCESS | 1659 | Values: 3027.6 3136.9 3277.9 3430.5 3439.9 5714.5 5877.9 5912.5 5983.0 6011.9 5599.7 5655.8 5771.3 5907.8 6010.4 4488.9 5253.1 5274.2 5365.0 5446.2 4808.8 5407.2 5442.5 5458.3 5515.4 4638.0 5006.7 5281.5 5300.7 5343.6 5283.0 5387.7 5387.7 5395.0 5411.8 5262.5 5309.7 5330.8 5349.5 5436.6 5625.9 5673.0 5760.7 5773.2 5968.9 5856.8 6214.2 6236.7 6276.1 6286.7 6232.8 6251.5 6269.3 6300.7 6312.0 6004.0 6004.2 6009.7 6011.2 6025.6 6226.6 6262.2 6269.4 6293.7 6297.6 6242.2 6245.5 6262.2 6277.8 6292.0 6099.3 6213.2 6292.9 6302.6 6335.7 6214.8 6282.0 6289.2 6294.2 6327.3 6241.5 6293.8 6306.6 6310.5 6318.3 4887.8 4930.4 4958.1 4967.4 5030.0 4441.7 4460.1 4504.1 4547.5 4574.4 5872.1 5902.4 5928.1 5981.7 5986.3 5114.2 6262.8 6293.1 6293.4 6295.0 5815.2 5852.1 5884.7 5907.9 5974.9 5469.9 5480.8 5483.2 5484.8 5496.0 5353.7 6222.3 6377.2 6382.5 6416.1 6166.8 6230.0 6242.7 6274.8 6283.1 5226.3 5581.4 5768.3 5793.3 6394.5 4665.5 4962.3 5188.9 5473.5 6287.3 6211.7 6239.9 6242.1 6269.0 6279.7 5405.7 5582.6 6189.0 6250.8 6272.2 5161.6 6078.1 6200.7 6247.4 6263.5 6010.6 6022.4 6089.6 6112.4 6159.0 6165.8 6197.2 6210.1 6233.0 6250.6 6042.8 6130.7 6132.0 6145.1 6149.6 05:51:16 INFO - PROCESS | 1659 | 05:51:16 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:67659.15 Median:40453.80 stddev:146330.59 (361.7%) stddev-sans-first:146654.16 05:51:16 INFO - PROCESS | 1659 | Values: 481.3 498.6 508.9 513.8 531.9 38818.0 40570.8 40580.8 40704.4 40754.0 41872.6 42214.4 42485.0 42512.4 42568.0 9730.9 9842.3 9886.1 9926.8 9932.7 9774.0 9776.1 9806.7 9858.3 9875.5 9852.9 9874.2 9889.8 9895.8 9907.3 8476.8 9801.4 9855.2 9865.7 9886.3 9761.2 9775.6 9850.6 9850.7 9856.6 40417.8 40627.8 40698.0 40711.8 40751.6 3314.4 3327.4 3379.4 3440.9 3452.6 30270.6 30534.8 30663.8 30932.8 31000.0 3112.9 3237.8 3242.3 3243.1 3258.4 463438.0 463869.0 465290.0 468790.0 469002.0 58728.4 58897.1 58989.0 58999.0 59063.7 28153.3 33870.7 34978.7 61535.0 61548.0 16348.9 16550.7 16627.8 16702.6 16767.0 12784.4 12843.9 12937.7 12951.4 12964.0 15391.9 18617.0 18919.2 19204.8 19332.5 6797.9 7807.6 7922.2 7998.5 8015.7 828.9 853.6 867.4 1016.1 1060.5 24543.9 28915.9 29909.9 30463.9 30907.1 894.0 1042.7 1044.4 1059.4 1067.9 15223.0 15480.6 15727.2 15729.3 15922.4 592471.0 599766.0 601090.0 601395.0 601933.0 55996.7 56886.0 57101.7 57225.1 57238.6 1301.9 1326.7 1346.8 1350.4 1351.6 28171.3 29295.3 29422.4 29900.9 29948.6 36529.5 49022.3 49378.0 49538.3 49557.0 568132.0 569136.0 570547.1 571520.0 572069.0 26336.1 26473.9 26856.5 26878.2 26959.9 46125.5 46666.5 46707.0 47342.8 47522.8 38853.8 38971.0 39163.4 40319.2 40474.8 38638.2 38838.0 38912.0 39892.0 39966.8 36076.0 38398.6 38802.4 38891.8 40432.8 31149.7 34300.0 35043.0 35118.3 35349.9 24603.4 25611.5 25780.7 25907.6 26097.3 05:51:16 INFO - PROCESS | 1659 | 05:51:16 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:33773.13 Median:2074.31 stddev:97134.71 (4682.7%) stddev-sans-first:97374.80 05:51:16 INFO - PROCESS | 1659 | Values: 361.3 369.5 376.0 382.6 391.9 18826.3 18975.5 19970.7 20855.4 21057.5 11743.9 11809.6 12186.6 12188.5 12200.5 5311.4 5621.9 5628.0 5635.0 5642.4 3940.3 4144.9 5476.5 5895.9 6132.4 6211.1 6217.2 6220.8 6226.0 6226.8 5881.4 6105.1 6113.3 6129.9 6141.4 4832.3 5083.7 5807.4 5906.9 5968.3 13299.7 13314.6 13348.5 13365.7 18343.7 1828.4 2040.6 2073.0 2382.4 2858.4 8410.2 9753.4 9966.9 10098.2 10401.0 1556.5 1830.5 1861.4 1909.9 1928.9 116248.0 119421.8 119674.0 120601.0 121705.4 10553.6 10810.1 11220.3 11381.6 11511.6 14292.4 14294.3 14327.4 14333.4 14350.9 8230.0 8465.7 8528.7 8614.9 8617.6 8185.6 8344.6 8406.4 8442.2 8573.4 3772.6 4388.8 4424.7 4463.6 4555.2 1555.9 1559.0 1649.1 1650.6 1866.0 761.8 880.2 941.1 973.9 1001.8 19614.9 20332.9 20399.1 20405.3 20547.5 900.2 902.8 937.3 937.7 968.4 9898.4 10907.5 10937.0 10957.7 10966.7 569938.0 575498.0 576199.0 588366.0 590219.0 126238.0 127020.0 127100.0 127770.4 128376.4 953.2 960.7 966.9 967.7 979.8 17823.8 17862.5 18130.1 18154.4 18429.0 52539.7 52992.0 53084.5 53686.7 53907.0 75449.5 75744.6 76237.1 77924.4 78584.6 5018.9 5903.7 6999.2 7234.5 7483.7 11447.1 12361.9 12506.6 12680.7 12761.7 11018.9 11052.0 11074.3 11088.5 11244.3 5042.6 7681.6 9091.8 9561.3 11029.2 11480.8 11501.9 11675.3 11800.9 11878.0 6833.4 8214.0 11182.1 11240.8 11872.7 9119.3 9219.9 9948.5 10375.4 10474.2 05:51:16 INFO - PROCESS | 1659 | 05:51:16 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5750.54 Median:4802.12 stddev:3231.21 (67.3%) stddev-sans-first:3224.37 05:51:16 INFO - PROCESS | 1659 | Values: 1485.0 1497.8 1731.4 1842.7 1882.7 8110.9 8418.8 8620.8 9146.0 9199.1 7192.6 8538.2 8843.2 8866.8 9331.8 2402.1 2409.4 2420.2 2421.6 2425.7 2388.4 2403.4 2425.8 2429.1 2435.3 2391.4 2399.4 2409.5 2415.0 2424.6 2386.1 2394.5 2397.9 2404.9 2409.2 2372.9 2392.1 2394.6 2405.7 2407.7 7859.5 8997.8 9007.3 9021.3 9090.4 5321.5 5468.8 5470.4 5475.1 5496.4 3533.4 3655.3 3697.0 3701.7 3760.6 1054.8 1077.2 1083.2 1088.9 1113.3 11864.8 11917.8 11958.7 11962.1 11992.0 4192.7 4500.9 4689.1 4838.1 4870.2 9989.4 10065.2 10074.7 10088.7 10112.7 5399.1 5422.6 5428.1 5436.4 5437.6 4268.4 4430.0 4595.8 4751.3 4882.0 3892.4 3933.3 4001.7 4043.2 4050.5 1278.4 1287.3 1290.8 1297.6 1299.4 782.3 889.2 918.4 920.3 925.5 4766.2 5601.0 5654.2 5898.2 6157.4 942.4 961.3 966.3 977.5 1060.6 3919.1 3932.3 3937.4 4016.9 4043.0 10491.5 10933.4 10945.0 10955.4 10963.1 10918.2 10923.0 10923.7 10935.6 10948.1 7269.1 7288.5 8247.1 8458.1 8461.0 5735.8 5789.3 5841.7 5911.2 5973.1 10248.8 10251.9 10314.4 10384.0 10416.1 9767.6 9861.6 10132.4 10292.5 10495.2 6909.9 7122.8 7268.5 7443.6 7642.0 8301.6 8446.4 8447.5 8460.5 8469.3 6531.9 7472.3 7620.9 7689.4 7753.9 7175.4 7239.4 7360.4 7400.4 7462.2 7017.3 7033.3 7107.7 7109.0 7116.9 5014.2 5059.3 5087.3 5093.7 5142.1 4931.7 5031.9 5076.4 5125.4 5194.5 05:51:16 INFO - PROCESS | 1659 | 05:51:16 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4586.44 Median:5158.88 stddev:1417.39 (27.5%) stddev-sans-first:1384.30 05:51:16 INFO - PROCESS | 1659 | Values: 296.4 302.6 307.9 309.4 312.6 5319.4 5468.2 5480.6 5549.8 5551.4 4172.5 5236.7 5356.0 5434.8 5442.3 3797.9 4190.9 4196.4 4202.8 4217.3 3240.8 3756.0 3926.3 4188.6 4195.5 4043.7 4057.4 4062.2 4102.6 4105.0 3662.1 4031.5 4085.9 4108.0 4125.7 2963.7 3233.8 3526.3 3543.9 3548.2 4957.4 5178.7 5281.7 5298.4 5425.2 3798.5 3989.4 4069.2 4721.9 4791.3 5423.4 5713.8 5741.5 5773.1 5787.2 3882.2 3946.1 3969.0 4024.4 4024.5 5259.2 6067.3 6083.6 6115.2 6189.0 4088.7 4095.6 4097.5 4110.1 4113.2 3762.3 3965.6 4108.7 4119.3 4119.6 4648.8 4727.6 4740.3 4745.9 4792.2 4413.6 5706.8 5722.5 5790.7 5895.4 1311.9 1323.5 1377.5 1390.6 1396.6 918.8 940.7 966.9 967.5 968.4 4460.0 5135.3 5280.6 5328.4 5346.2 5038.8 5719.3 5831.4 5858.5 5870.4 4157.1 5139.1 5270.7 5363.5 5439.1 2703.2 2810.9 3072.4 3085.7 3090.8 5709.7 6092.5 6115.0 6126.6 6151.8 5092.6 5300.7 5603.9 5923.4 6008.9 4988.4 6064.8 6087.2 6148.3 6151.5 4682.4 5516.3 5853.6 5862.9 5871.5 5361.7 5786.9 5792.0 5831.8 5858.5 5744.1 5755.5 6061.8 6070.9 6128.3 3371.6 3610.3 3638.6 3668.4 3883.2 4475.3 5512.9 5867.2 5896.6 5902.4 4782.5 5048.9 5383.6 5516.0 5595.4 4751.5 5271.4 5747.9 5748.5 5783.2 4329.0 5411.9 5422.6 5627.4 5653.8 4641.6 4929.6 5016.0 5325.9 5595.7 4641.4 4854.4 5191.9 5597.7 5627.9 05:51:16 INFO - PROCESS | 1659 | 05:51:16 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:26532.65 Median:23794.46 stddev:44217.66 (185.8%) stddev-sans-first:44299.15 05:51:16 INFO - PROCESS | 1659 | Values: 693.9 728.5 770.4 772.3 793.0 15481.8 15605.6 15724.2 15747.9 15763.5 14059.7 14135.9 14226.8 14231.5 14255.5 664.7 684.3 798.2 805.4 827.1 820.9 826.7 839.3 839.5 839.9 898.6 898.7 900.4 900.8 900.9 874.9 878.6 892.6 893.4 893.7 658.0 663.2 663.8 664.2 666.0 15318.2 15403.8 15548.4 15553.7 15577.9 14600.9 14646.9 14672.9 14697.3 14929.8 11626.5 11753.2 12039.8 12215.3 12499.6 3774.8 3780.6 3961.3 4014.2 4018.6 119411.0 121223.4 122043.4 122350.4 123833.0 11155.2 11215.7 11272.5 11338.4 11639.7 23715.2 23780.0 23790.3 23798.6 23815.0 8030.8 8129.6 8215.0 8235.3 8425.5 4435.2 4507.4 4518.8 4542.1 4676.4 3572.3 3652.1 3679.2 3696.8 3710.2 639.8 695.7 705.6 730.4 730.9 597.5 633.0 636.0 651.6 653.5 7925.7 8568.7 8943.6 9443.2 9632.7 549.3 645.9 658.2 660.7 690.1 4474.5 4489.0 4499.0 4516.8 4540.4 165736.1 169157.0 170318.0 173065.5 175301.1 117324.4 122604.4 148223.1 149033.3 152833.2 108884.0 119935.4 123232.0 123621.0 126346.0 8786.1 8916.6 8980.5 9027.0 9106.9 52053.7 55195.3 69433.7 69745.0 71151.0 97508.0 101169.5 102203.5 104267.0 105546.0 11667.5 11694.7 11703.5 11828.5 12394.6 12936.6 12964.5 13157.2 13288.1 13674.6 11897.5 11937.3 12165.1 12228.5 12624.7 12204.6 12204.8 12217.6 12538.9 12619.0 10571.3 11788.4 11868.1 12186.5 12615.7 10021.3 10196.9 10482.6 10488.2 10678.1 8831.6 10519.3 10593.7 10599.3 10645.6 05:51:16 INFO - PROCESS | 1659 | -------- Summary: end -------- 05:51:16 INFO - PROCESS | 1659 | 05:51:16 INFO - TEST-INFO | 1659: exit 0 05:51:16 INFO - TEST-OK | dromaeo_css | took 1114896ms 05:51:16 INFO - TEST-START | kraken 05:51:16 INFO - operating with platform_type : mac_ 05:51:16 INFO - Initialising browser for kraken test... 05:51:16 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpB6JRLy/profile http://localhost:49272/getInfo.html) 05:51:19 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 05:51:19 INFO - PROCESS | 1667 | colorDepth:24 05:51:19 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 05:51:19 INFO - PROCESS | 1667 | __metrics 05:51:19 ERROR - PROCESS | 1667 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 05:51:20 INFO - TEST-INFO | 1667: exit 0 05:51:20 INFO - Browser initialized. 05:51:20 INFO - Running cycle 1/1 for kraken test... 05:51:20 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpB6JRLy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:51:32 INFO - PROCESS | 1670 | RSS: Main: 239927296 05:51:32 INFO - PROCESS | 1670 | 05:51:35 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 05:51:36 INFO - PROCESS | 1670 | RSS: Main: 252665856 05:51:36 INFO - PROCESS | 1670 | 05:51:38 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 05:51:39 INFO - PROCESS | 1670 | RSS: Main: 260587520 05:51:39 INFO - PROCESS | 1670 | 05:51:42 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 05:51:43 INFO - PROCESS | 1670 | RSS: Main: 261378048 05:51:43 INFO - PROCESS | 1670 | 05:51:45 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 05:51:46 INFO - PROCESS | 1670 | RSS: Main: 262799360 05:51:46 INFO - PROCESS | 1670 | 05:51:48 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:51:48 INFO - PROCESS | 1670 | RSS: Main: 264368128 05:51:48 INFO - PROCESS | 1670 | 05:51:51 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 05:51:52 INFO - PROCESS | 1670 | RSS: Main: 264941568 05:51:52 INFO - PROCESS | 1670 | 05:51:55 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 05:51:56 INFO - PROCESS | 1670 | RSS: Main: 265146368 05:51:56 INFO - PROCESS | 1670 | 05:51:59 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 05:51:59 INFO - PROCESS | 1670 | RSS: Main: 265420800 05:51:59 INFO - PROCESS | 1670 | 05:52:01 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:52:02 INFO - PROCESS | 1670 | RSS: Main: 265084928 05:52:02 INFO - PROCESS | 1670 | 05:52:03 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:52:04 INFO - PROCESS | 1670 | RSS: Main: 265183232 05:52:04 INFO - PROCESS | 1670 | 05:52:06 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:52:07 INFO - PROCESS | 1670 | RSS: Main: 265551872 05:52:07 INFO - PROCESS | 1670 | 05:52:10 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:52:11 INFO - PROCESS | 1670 | RSS: Main: 265678848 05:52:11 INFO - PROCESS | 1670 | 05:52:14 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:52:14 INFO - PROCESS | 1670 | RSS: Main: 265699328 05:52:14 INFO - PROCESS | 1670 | 05:52:16 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 05:52:16 INFO - PROCESS | 1670 | RSS: Main: 267673600 05:52:16 INFO - PROCESS | 1670 | 05:52:16 INFO - PROCESS | 1670 | __start_tp_report 05:52:16 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 05:52:16 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 05:52:16 INFO - PROCESS | 1670 | |i|pagename|runs| 05:52:16 INFO - PROCESS | 1670 | |0;ai-astar;105;118;116;117;118;117;150;171;149;116 05:52:16 INFO - PROCESS | 1670 | |1;audio-beat-detection;146;116;97;175;142;113;118;114;160;128 05:52:16 INFO - PROCESS | 1670 | |2;audio-dft;208;238;200;203;200;198;197;198;194;197 05:52:16 INFO - PROCESS | 1670 | |3;audio-fft;84;60;61;87;59;57;65;81;106;84 05:52:16 INFO - PROCESS | 1670 | |4;audio-oscillator;87;71;89;73;68;70;89;72;84;101 05:52:16 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;92;90;92;89;98;87;93;91;93;90 05:52:16 INFO - PROCESS | 1670 | |6;imaging-darkroom;152;120;97;98;98;146;133;134;127;104 05:52:16 INFO - PROCESS | 1670 | |7;imaging-desaturate;87;108;145;146;91;147;147;117;92;90 05:52:16 INFO - PROCESS | 1670 | |8;json-parse-financial;58;58;57;64;57;57;58;59;58;58 05:52:16 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;69;70;74;83;77;74;69;69;70;71 05:52:16 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;89;88;88;88;88;86;88;92;89;86 05:52:16 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;139;137;136;138;149;128;170;136;183;154 05:52:16 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;208;196;169;172;181;236;210;175;172;168 05:52:16 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;69;70;80;86;88;84;66;73;89;89 05:52:16 INFO - PROCESS | 1670 | __end_tp_report 05:52:16 INFO - PROCESS | 1670 | __start_cc_report 05:52:16 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,964 05:52:16 INFO - PROCESS | 1670 | __end_cc_report 05:52:16 INFO - PROCESS | 1670 | __startTimestamp1473771136830__endTimestamp 05:52:16 INFO - PROCESS | 1670 | 05:52:16 INFO - PROCESS | 1670 | ------- Summary: start ------- 05:52:16 INFO - PROCESS | 1670 | Number of tests: 14 05:52:16 INFO - PROCESS | 1670 | 05:52:16 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:127.70 Median:118.00 stddev:21.17 (17.9%) stddev-sans-first:20.80 05:52:16 INFO - PROCESS | 1670 | Values: 105.0 118.0 116.0 117.0 118.0 117.0 150.0 171.0 149.0 116.0 05:52:16 INFO - PROCESS | 1670 | 05:52:16 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:130.90 Median:144.00 stddev:24.26 (16.8%) stddev-sans-first:25.10 05:52:16 INFO - PROCESS | 1670 | Values: 146.0 116.0 97.0 175.0 142.0 113.0 118.0 114.0 160.0 128.0 05:52:16 INFO - PROCESS | 1670 | 05:52:16 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:203.30 Median:200.00 stddev:12.78 (6.4%) stddev-sans-first:13.44 05:52:16 INFO - PROCESS | 1670 | Values: 208.0 238.0 200.0 203.0 200.0 198.0 197.0 198.0 194.0 197.0 05:52:16 INFO - PROCESS | 1670 | 05:52:16 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:74.40 Median:73.00 stddev:16.33 (22.4%) stddev-sans-first:16.95 05:52:16 INFO - PROCESS | 1670 | Values: 84.0 60.0 61.0 87.0 59.0 57.0 65.0 81.0 106.0 84.0 05:52:16 INFO - PROCESS | 1670 | 05:52:16 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:80.40 Median:78.50 stddev:11.08 (14.1%) stddev-sans-first:11.49 05:52:16 INFO - PROCESS | 1670 | Values: 87.0 71.0 89.0 73.0 68.0 70.0 89.0 72.0 84.0 101.0 05:52:16 INFO - PROCESS | 1670 | 05:52:16 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:91.50 Median:92.00 stddev:2.95 (3.2%) stddev-sans-first:3.13 05:52:16 INFO - PROCESS | 1670 | Values: 92.0 90.0 92.0 89.0 98.0 87.0 93.0 91.0 93.0 90.0 05:52:16 INFO - PROCESS | 1670 | 05:52:16 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:120.90 Median:149.00 stddev:20.71 (13.9%) stddev-sans-first:18.66 05:52:16 INFO - PROCESS | 1670 | Values: 152.0 120.0 97.0 98.0 98.0 146.0 133.0 134.0 127.0 104.0 05:52:16 INFO - PROCESS | 1670 | 05:52:16 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:117.00 Median:117.00 stddev:26.74 (22.9%) stddev-sans-first:26.07 05:52:16 INFO - PROCESS | 1670 | Values: 87.0 108.0 145.0 146.0 91.0 147.0 147.0 117.0 92.0 90.0 05:52:16 INFO - PROCESS | 1670 | 05:52:16 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:58.40 Median:58.00 stddev:2.07 (3.6%) stddev-sans-first:2.19 05:52:16 INFO - PROCESS | 1670 | Values: 58.0 58.0 57.0 64.0 57.0 57.0 58.0 59.0 58.0 58.0 05:52:16 INFO - PROCESS | 1670 | 05:52:16 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:72.60 Median:72.50 stddev:4.55 (6.3%) stddev-sans-first:4.64 05:52:16 INFO - PROCESS | 1670 | Values: 69.0 70.0 74.0 83.0 77.0 74.0 69.0 69.0 70.0 71.0 05:52:16 INFO - PROCESS | 1670 | 05:52:16 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:88.20 Median:88.00 stddev:1.69 (1.9%) stddev-sans-first:1.76 05:52:16 INFO - PROCESS | 1670 | Values: 89.0 88.0 88.0 88.0 88.0 86.0 88.0 92.0 89.0 86.0 05:52:16 INFO - PROCESS | 1670 | 05:52:16 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:147.00 Median:144.00 stddev:17.40 (12.1%) stddev-sans-first:18.22 05:52:16 INFO - PROCESS | 1670 | Values: 139.0 137.0 136.0 138.0 149.0 128.0 170.0 136.0 183.0 154.0 05:52:16 INFO - PROCESS | 1670 | 05:52:16 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.70 Median:188.50 stddev:22.94 (12.2%) stddev-sans-first:23.25 05:52:16 INFO - PROCESS | 1670 | Values: 208.0 196.0 169.0 172.0 181.0 236.0 210.0 175.0 172.0 168.0 05:52:16 INFO - PROCESS | 1670 | 05:52:16 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.40 Median:85.00 stddev:9.07 (10.7%) stddev-sans-first:8.80 05:52:16 INFO - PROCESS | 1670 | Values: 69.0 70.0 80.0 86.0 88.0 84.0 66.0 73.0 89.0 89.0 05:52:16 INFO - PROCESS | 1670 | -------- Summary: end -------- 05:52:16 INFO - PROCESS | 1670 | 05:52:17 INFO - TEST-INFO | 1670: exit 0 05:52:17 INFO - TEST-OK | kraken | took 60920ms 05:52:17 INFO - SUITE-END | took 1175s 05:52:17 INFO - Completed test suite (00:19:36) 05:52:17 INFO - javascript benchmark 05:52:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7307.4751812445475, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3027.5610036544704, 3136.8902088426908, 3277.88527401541, 3430.536877521535, 3439.86240550378, 5714.51426628736, 5877.911831322528, 5912.526997840173, 5983.0, 6011.917854786137, 5599.720013999294, 5655.830325090244, 5771.278590176228, 5907.822765317037, 6010.368911264309, 4488.910221795562, 5253.0544501989625, 5274.235235890794, 5365.0, 5446.210299506569, 4808.797800549863, 5407.188921661763, 5442.537384322337, 5458.317710286215, 5515.420880807517, 4637.953620463786, 5006.724630145377, 5281.471852814726, 5300.655457395257, 5343.572514198855, 5283.049051170788, 5387.676739395649, 5387.703676297802, 5395.001924643953, 5411.783528658888, 5262.526372626482, 5309.65487243328, 5330.840074797762, 5349.465053494659, 5436.619436639437, 5625.915611265835, 5672.971635141839, 5760.654360738327, 5773.24947756789, 5968.940310596882, 5856.795012174553, 6214.1921550198185, 6236.688165591702, 6276.121343011982, 6286.685665716695, 6232.781852635136, 6251.531135164881, 6269.341719119518, 6300.716467758916, 6311.990081586924, 6003.969980150071, 6004.219451471281, 6009.669468179205, 6011.158437818709, 6025.638461692312, 6226.59527130735, 6262.2172228471445, 6269.404406581368, 6293.685315734287, 6297.559170858088, 6242.188515492957, 6245.500359971192, 6262.1546091278005, 6277.811665650038, 6291.96854015727, 6099.279518885405, 6213.161223234805, 6292.874142517124, 6302.590331628429, 6335.746570137235, 6214.782482613177, 6282.0, 6289.213848268966, 6294.181756371642, 6327.303996560375, 6241.500679945593, 6293.811185664437, 6306.558540902093, 6310.5267104967315, 6318.336574659686, 4887.755612219375, 4930.433000204951, 4958.107540642647, 4967.379077615298, 5030.0, 4441.733495990186, 4460.063386688832, 4504.144212599661, 4547.545245475492, 4574.428196475441, 5872.148538461985, 5902.3507414184405, 5928.081147422156, 5981.6710080945095, 5986.2517185351835, 5114.232865070195, 6262.780802671885, 6293.056041593706, 6293.37066293367, 6295.0, 5815.244018277597, 5852.122181672698, 5884.676342801187, 5907.911381329283, 5974.85062873423, 5469.890602187934, 5480.780768769284, 5483.232347471358, 5484.808031718871, 5496.038193316233, 5353.705546194997, 6222.346653601307, 6377.23473183221, 6382.489400847828, 6416.101745755598, 6166.845828854135, 6230.0, 6242.719077641426, 6274.811755647338, 6283.120363149254, 5226.346706661668, 5581.386047534686, 5768.278965129359, 5793.3337666167245, 6394.488440924808, 4665.48679645232, 4962.255661650781, 5188.896222075613, 5473.534749546333, 6287.2769631492965, 6211.689415529296, 6239.906401403891, 6242.063690446469, 6269.0, 6279.686015699288, 5405.702686352289, 5582.609217354909, 6188.9690551546955, 6250.812475625738, 6272.2159730033745, 5161.587073034223, 6078.088286757022, 6200.720967556561, 6247.406496382836, 6263.467605253604, 6010.639361638316, 6022.397760223943, 6089.634621922699, 6112.351504965139, 6159.0, 6165.815025549241, 6197.163382943245, 6210.068489726577, 6233.0, 6250.624962502265, 6042.788502402394, 6130.6628135452975, 6132.0, 6145.13968044483, 6149.631022138686], "value": 5706.593136505615, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.2732773511997, 498.6085922550796, 508.8549763317455, 513.7662363624552, 531.8936611847355, 38818.0, 40570.79714601423, 40580.79709601452, 40704.38943415851, 40753.99999999996, 41872.58127418725, 42214.36678449818, 42485.0, 42512.36231456523, 42568.0, 9730.859210973575, 9842.261830362751, 9886.110250077492, 9926.751831204205, 9932.702018939442, 9773.951130244375, 9776.120149186572, 9806.656767013155, 9858.260630452709, 9875.456849873253, 9852.852207216898, 9874.16069634082, 9889.802203955916, 9895.802083958317, 9907.266228410035, 8476.78808029801, 9801.362911410772, 9855.162311203556, 9865.704028879145, 9886.307958442912, 9761.219102471856, 9775.608975640966, 9850.60597576096, 9850.704478865646, 9856.556454959544, 40417.79791101025, 40627.7968610158, 40697.98255043648, 40711.77864664065, 40751.592484075074, 3314.370269648783, 3327.3678001179696, 3379.4423920053164, 3440.913977150566, 3452.602950660681, 30270.57835373165, 30534.84732576345, 30663.84668076645, 30932.845335773178, 31000.0, 3112.9065279157835, 3237.8057316560844, 3242.2866969266724, 3243.059512741302, 3258.446064169097, 463438.0, 463869.0, 465290.0, 468790.0, 469002.0, 58728.41271587229, 58897.11654325188, 58989.0, 58999.0, 59063.704681476316, 28153.296167595974, 33870.661293387246, 34978.65021349754, 61535.0, 61548.0, 16348.918255408884, 16550.6689866202, 16627.83372166287, 16702.58243543897, 16767.0, 12784.424700888483, 12843.935780321039, 12937.676558086123, 12951.417186226454, 12964.0, 15391.92304038495, 18617.0, 18919.243230270637, 19204.807951920582, 19332.516687082654, 6797.898031529531, 7807.609619519001, 7922.207779222032, 7998.520088794691, 8015.679372825139, 828.9005319361715, 853.6116067189414, 867.418829384314, 1016.070295679445, 1060.533365319257, 24543.877280613484, 28915.855420723183, 29909.850450747606, 30463.933762318655, 30907.072787815952, 893.9910600893908, 1042.6559235452282, 1044.4464433850071, 1059.4120263253815, 1067.8665166854144, 15223.010504317184, 15480.612984675474, 15727.213639317988, 15729.292181851844, 15922.442714504938, 592471.0, 599766.0, 601090.0, 601395.0, 601933.0, 55996.72001639966, 56886.0, 57101.71449142728, 57225.14162287486, 57238.56903577444, 1301.9414126364336, 1326.7479178955969, 1346.8047133165833, 1350.3990724127866, 1351.63505853421, 28171.295717607223, 29295.267618309714, 29422.411551768415, 29900.85049574738, 29948.550771738875, 36529.45205821968, 49022.26466603075, 49378.0, 49538.25692614542, 49557.0, 568132.0, 569136.0, 570547.147264261, 571520.0, 572069.0, 26336.078237261605, 26473.867630662495, 26856.462870742867, 26878.193654189625, 26959.86520067387, 46125.538744612124, 46666.533334666216, 46707.0, 47342.76328618335, 47522.762386187846, 38853.81204477181, 38971.0, 39163.41254881122, 40319.1936161281, 40474.797626011685, 38638.22723545457, 38838.02904927287, 38912.0, 39892.00269993273, 39966.80016599898, 36076.0, 38398.616013839506, 38802.41796373113, 38891.80554097211, 40432.79783601063, 31149.688503114678, 34300.0, 35043.0, 35118.29763404769, 35349.93950181499, 24603.384915377257, 25611.48777024412, 25780.742192577833, 25907.611385829605, 26097.346371508385], "value": 19683.514096740997, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.2504054087767, 369.48272418613936, 376.04109520722153, 382.64414094891777, 391.9398028333359, 18826.34107806227, 18975.52561185971, 19970.7004394934, 20855.37433876984, 21057.47356316093, 11743.941280293586, 11809.586664466746, 12186.573469928531, 12188.451519681614, 12200.450979705935, 5311.389190243133, 5621.943780562204, 5628.0, 5635.013872572304, 5642.407547207546, 3940.330143875548, 4144.8963775905695, 5476.479734425223, 5895.852603684899, 6132.4480796728285, 6211.0993905883615, 6217.222847144107, 6220.813375598716, 6225.968870155642, 6226.750929962796, 5881.441263080001, 6105.084237364386, 6113.296970848365, 6129.938700612981, 6141.416565426277, 4832.275158726184, 5083.669561478492, 5807.448292412214, 5906.940930590682, 5968.283805943272, 13299.667508312274, 13314.600561983156, 13348.532801351907, 13365.732685346238, 18343.724844127348, 1828.3783513605306, 2040.6020825938947, 2072.968905466419, 2382.4282172278527, 2858.3854471288564, 8410.201030902055, 9753.366031207948, 9966.850497242547, 10098.192144628485, 10401.0, 1556.486359501362, 1830.5057634438626, 1861.3764388929724, 1909.9236030558761, 1928.8939108349034, 116248.0, 119421.80578194193, 119674.0, 120601.0, 121705.39147304295, 10553.577856885791, 10810.135189185004, 11220.270682405617, 11381.582853410893, 11511.597094101666, 14292.356843942047, 14294.28528573588, 14327.35526901292, 14333.354999025069, 14350.928245358915, 8229.95885020583, 8465.703700370457, 8528.6780549015, 8614.913850861536, 8617.61220745068, 8185.590720463953, 8344.582770861432, 8406.369522285853, 8442.15578442151, 8573.442726222776, 3772.5850156483316, 4388.780560972003, 4424.712393694464, 4463.553644635511, 4555.180067587799, 1555.929983150761, 1558.984410155884, 1649.1177220187142, 1650.636859890822, 1866.0033763872798, 761.8133557278502, 880.1682410122373, 941.1200527506803, 973.8831340239217, 1001.7545701303227, 19614.901925490565, 20332.881691506813, 20399.08204130818, 20405.285814996405, 20547.486312842, 900.2392977933636, 902.7878448564582, 937.2923442800642, 937.704623043739, 968.3899143539525, 9898.406095634142, 10907.454627268606, 10936.96098870606, 10957.671269861918, 10966.725831854108, 569938.0, 575498.0, 576199.0, 588366.0, 590219.0, 126238.0, 127020.0, 127100.0, 127770.41721870897, 128376.35811821069, 953.1516949914582, 960.711786464058, 966.9081437263449, 967.7483854197959, 979.8334283171878, 17823.821761782216, 17862.46412607624, 18130.093495325444, 18154.364597239033, 18428.98640574781, 52539.737301313246, 52992.0, 53084.469155309496, 53686.73156634192, 53907.0, 75449.49101018059, 75744.62127689326, 76237.13425322584, 77924.42831288667, 78584.60707696425, 5018.924716129188, 5903.7048147593305, 6999.1950925644205, 7234.457415693739, 7483.690354188104, 11447.14146439037, 12361.949234315183, 12506.624801255977, 12680.6829829255, 12761.680957976125, 11018.889811101786, 11052.0, 11074.335539867634, 11088.501017454073, 11244.325340479598, 5042.621803364689, 7681.577513236825, 9091.818163636557, 9561.330706850455, 11029.172812038969, 11480.770384592095, 11501.942490287494, 11675.299482031105, 11800.881991179978, 11878.049756019325, 6833.350831670983, 8214.0, 11182.099043162845, 11240.7751844961, 11872.703182420508, 9119.270458363448, 9219.86170207434, 9948.452835093849, 10375.429351385746, 10474.21443391764], "value": 9022.864282971253, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1484.9703005939882, 1497.79030935669, 1731.4372828830633, 1842.7420161177447, 1882.7175923611449, 8110.918890811105, 8418.831623367529, 8620.827583448336, 9146.039665835084, 9199.080091990805, 7192.604406757626, 8538.18887205716, 8843.204111629952, 8866.77833054174, 9331.813363732721, 2402.1112188490238, 2409.3976505873534, 2420.1892366057305, 2421.5641184586766, 2425.733169351379, 2388.414838364603, 2403.375122468153, 2425.805935525154, 2429.125514814665, 2435.3181109289426, 2391.390195500154, 2399.400149962509, 2409.4578719788083, 2415.0460568075687, 2424.59994100974, 2386.140989243871, 2394.497155597318, 2397.8561286322874, 2404.9398765030837, 2409.2290467050375, 2372.893219805113, 2392.1268736911074, 2394.6408038794148, 2405.7233418156784, 2407.6629271901948, 7859.52842829432, 8997.775055623597, 9007.324450666161, 9021.278297736166, 9090.409123407024, 5321.467853214686, 5468.7539060742365, 5470.425605311425, 5475.123980163195, 5496.395396506381, 3533.3993221152464, 3655.268946210742, 3696.963030369716, 3701.6668499834873, 3760.6051364606597, 1054.8259537176357, 1077.1921059205595, 1083.182197440935, 1088.8638920134983, 1113.287496002559, 11864.762704745857, 11917.761644767055, 11958.701032474171, 11962.102842286777, 11992.0, 4192.685548583869, 4500.887477813016, 4689.132510485571, 4838.080764654705, 4870.220764677634, 9989.350692204984, 10065.194784417376, 10074.74813129663, 10088.69733907984, 10112.74718132038, 5399.109146990702, 5422.593305502103, 5428.131498960148, 5436.429174936654, 5437.619366644297, 4268.445102136702, 4429.955700443019, 4595.816167353335, 4751.287306903992, 4882.0, 3892.3772196449, 3933.272344616255, 4001.6998725095737, 4043.2317859606583, 4050.4734384529825, 1278.3991523983714, 1287.2533930320392, 1290.760869565209, 1297.6172029251393, 1299.4282515693064, 782.342832021105, 889.2219308105408, 918.3892711347013, 920.3005715656016, 925.4753984409883, 4766.165920963817, 5601.0, 5654.208410822406, 5898.203742494794, 6157.415045570664, 942.4439580647314, 961.3030552849128, 966.2946049383849, 977.5063592885547, 1060.55986765493, 3919.1377896862646, 3932.311845427039, 3937.3897045957924, 4016.9397459038137, 4042.9797851010558, 10491.475426228812, 10933.398663073607, 10945.01494865466, 10955.397453139994, 10963.122950163968, 10918.235723499438, 10923.0, 10923.672289831318, 10935.633045869266, 10948.069414099891, 7269.055022847102, 7288.526245794006, 8247.134050924686, 8458.111898250718, 8461.026981897037, 5735.7705691773535, 5789.2763404574425, 5841.737121829443, 5911.231539899957, 5973.074173503114, 10248.846267305848, 10251.897481025095, 10314.381137131797, 10384.0, 10416.11463025621, 9767.60929562838, 9861.556229969812, 10132.442715650415, 10292.485375731034, 10495.16038716916, 6909.896351554831, 7122.821929451805, 7268.491205615556, 7443.627818608939, 7642.0, 8301.58492075406, 8446.366522510714, 8447.493150410995, 8460.450070745383, 8469.322454203771, 6531.869362612816, 7472.29013243741, 7620.923790762022, 7689.42329325314, 7753.883691744515, 7175.425965922609, 7239.384652304613, 7360.447966402434, 7400.407967362706, 7462.216467270859, 7017.2631873652535, 7033.331833475811, 7107.715691372494, 7109.0, 7116.857662846611, 5014.17266151081, 5059.291699162194, 5087.3386459759995, 5093.71984540839, 5142.074426603247, 4931.728754918514, 5031.949680503149, 5076.416212135652, 5125.410577783603, 5194.454582268807], "value": 4632.668737388378, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.43677013674017, 302.6307904356684, 307.9399517094167, 309.4121169777424, 312.613921806569, 5319.441458646835, 5468.234447177399, 5480.643758155722, 5549.833504994852, 5551.417101204371, 4172.457580514537, 5236.7119808410525, 5356.0, 5434.7826086956475, 5442.292501974747, 3797.8731910130414, 4190.916181676364, 4196.412502249687, 4202.789860506978, 4217.3341530095595, 3240.7569432292553, 3755.981220093896, 3926.2736393767245, 4188.560201178878, 4195.538490766014, 4043.65628921542, 4057.3508238681816, 4062.248484030449, 4102.5897410258885, 4104.958950410502, 3662.0844788802665, 4031.496062992126, 4085.937656209392, 4107.958920410788, 4125.731827431237, 2963.73326400625, 3233.7898036627544, 3526.347625689256, 3543.94684079739, 3548.1661809474754, 4957.429895562021, 5178.663386879841, 5281.70950597717, 5298.390685071227, 5425.186222066682, 3798.53756303825, 3989.3816458448987, 4069.1719255117705, 4721.905561888743, 4791.2573551099285, 5423.376311724162, 5713.771449142029, 5741.540676745851, 5773.06602288234, 5787.218725472091, 3882.165334453106, 3946.052947292658, 3969.0, 4024.3963405489117, 4024.517057953035, 5259.237410575483, 6067.302260240085, 6083.634981901056, 6115.1744514490865, 6188.9999999999545, 4088.7342322748927, 4095.5699651536756, 4097.496640372438, 4110.09577892869, 4113.218488487178, 3762.3227818992846, 3965.6232657897453, 4108.712390132722, 4119.320312148462, 4119.649829764444, 4648.814552289145, 4727.550882666142, 4740.288956656529, 4745.857624271277, 4792.2332426811545, 4413.602775750198, 5706.80026199081, 5722.54219662426, 5790.681512516768, 5895.4399332063385, 1311.8556958734532, 1323.5110500686728, 1377.5040985245505, 1390.6106290238547, 1396.5880065380736, 918.8437965545874, 940.7083804020775, 966.8598053282235, 967.4872317671504, 968.4479846487575, 4459.955400446019, 5135.255387968724, 5280.6039547034125, 5328.3605967284175, 5346.224797404355, 5038.833301680239, 5719.28508936383, 5831.4168583141345, 5858.502027327725, 5870.354261031283, 4157.10622216225, 5139.098533542613, 5270.683758974474, 5363.463653634605, 5439.102548079522, 2703.202555246207, 2810.9156725298276, 3072.354805490872, 3085.6760040195454, 3090.8100381353343, 5709.68596727184, 6092.5430592705725, 6114.9694251529345, 6126.632402055891, 6151.84620384494, 5092.567131793765, 5300.655457395257, 5603.9072380886155, 5923.378045305267, 6008.939910600925, 4988.376452943382, 6064.848378790565, 6087.239095113111, 6148.323684394713, 6151.5386346024225, 4682.4381074271305, 5516.310461192351, 5853.64878107315, 5862.853428664317, 5871.4715675589405, 5361.731913404236, 5786.9131963019745, 5792.0, 5831.804480081489, 5858.47273745365, 5744.138379243147, 5755.539556835527, 6061.787837425669, 6070.907236697437, 6128.325884152829, 3371.561696979377, 3610.3140403323287, 3638.5633723952237, 3668.4497325401403, 3883.165119499321, 4475.25465068617, 5512.944870551243, 5867.237259156283, 5896.587238893237, 5902.439268269508, 4782.473927868002, 5048.873778155576, 5383.627175070332, 5515.972420137874, 5595.356533998643, 4751.453582838018, 5271.4464981176425, 5747.8563035924435, 5748.45389687979, 5783.190353350618, 4329.0, 5411.891762164813, 5422.593305502024, 5627.409122042291, 5653.802116925888, 4641.605463535638, 4929.556339929423, 5015.996800639814, 5325.949509438817, 5595.740958284354, 4641.350210970534, 4854.417469903493, 5191.870203244798, 5597.720113994365, 5627.915581266202], "value": 4141.503878261604, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [693.8716337477567, 728.4936968806682, 770.445279398833, 772.3126417488439, 792.9770595931247, 15481.767773483392, 15605.609859753511, 15724.213789310517, 15747.921260393696, 15763.527094187164, 14059.718805623881, 14135.858641413583, 14226.786598201035, 14231.501897433593, 14255.50105746299, 664.7374287156571, 684.336193891924, 798.2217338095354, 805.3637626275236, 827.148037521354, 820.8645573480399, 826.6817275348974, 839.2824135364267, 839.546644811804, 839.911809260028, 898.6046139698544, 898.6585097662879, 900.3967341880956, 900.8108297257551, 900.8738776571286, 874.9081346458657, 878.5958459108817, 892.6117139044504, 893.4282059481936, 893.7229458867772, 658.045833541367, 663.171036204744, 663.7743167323085, 664.2029564522592, 665.9833504162386, 15318.157501337422, 15403.768943465857, 15548.378064877392, 15553.688926221525, 15577.922110389376, 14600.926995365062, 14646.926765366212, 14672.92663536686, 14697.26513674312, 14929.776053359208, 11626.534938602446, 11753.177277590572, 12039.759204815942, 12215.328156951364, 12499.56251531192, 3774.8490060397826, 3780.621937806225, 3961.2869683456966, 4014.237294913957, 4018.578049304839, 119411.0, 121223.39388303002, 122043.38978305052, 122350.38824805908, 123832.9999999991, 11155.219134660658, 11215.66353009411, 11272.492737826817, 11338.43307834605, 11639.709007274885, 23715.169969051, 23780.0, 23790.286291411285, 23798.64302035471, 23815.047398104227, 8030.759077227693, 8129.593520323961, 8214.973128358955, 8235.258826705625, 8425.452345597516, 4435.2016637005, 4507.408240261862, 4518.774061296922, 4542.136993971201, 4676.368690226844, 3572.3391172633146, 3652.0504668786307, 3679.208970071394, 3696.7966761828357, 3710.239400922804, 639.7632875835877, 695.7078027228577, 705.5872314695961, 730.3901242462604, 730.8781021132536, 597.5339235396398, 633.0, 635.9713812878432, 651.6318280171688, 653.4968074582545, 7925.722599708983, 8568.742937711879, 8943.642254309885, 9443.198245351787, 9632.711018669452, 549.2886711708268, 645.8600175064705, 658.1772784019975, 660.6795704083515, 690.0580707334552, 4474.485434175112, 4488.955110448919, 4498.955010449919, 4516.774161291923, 4540.432445944257, 165736.08375246284, 169157.0, 170318.0, 173065.50148480618, 175301.12349438426, 117324.41337793256, 122604.38697806453, 148223.07107715786, 149033.25483372732, 152833.23583382234, 108884.0, 119935.40032299783, 123232.0, 123621.0, 126345.99999999817, 8786.121387861163, 8916.643334266557, 8980.46117232968, 9027.0, 9106.908930910606, 52053.7397313011, 55195.34413967587, 69433.65283173551, 69744.95382569164, 71151.0, 97508.0, 101169.49415252877, 102203.52384788697, 104267.0, 105546.0, 11667.533298667959, 11694.70763230926, 11703.478547788733, 11828.467718952495, 12394.62816115518, 12936.611901642966, 12964.481420743065, 13157.210567365988, 13288.06983511145, 13674.589762307147, 11897.524099035942, 11937.28376297425, 12165.087618428477, 12228.510859565518, 12624.684382890502, 12204.572839950559, 12204.816927745913, 12217.633470995885, 12538.87461125377, 12619.0, 10571.312864663772, 11788.410579471163, 11868.10989175798, 12186.451609677762, 12615.747685046432, 10021.348612340174, 10196.898031019891, 10482.63310784119, 10488.213383996079, 10678.145748339959, 8831.558422078999, 10519.316244444086, 10593.735156621146, 10599.258051936598, 10645.574177033142], "value": 7829.734357361244, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1580.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 118.0, 116.0, 117.0, 118.0, 117.0, 150.0, 171.0, 149.0, 116.0], "value": 127.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 116.0, 97.0, 175.0, 142.0, 113.0, 118.0, 114.0, 160.0, 128.0], "value": 130.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 238.0, 200.0, 203.0, 200.0, 198.0, 197.0, 198.0, 194.0, 197.0], "value": 203.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 60.0, 61.0, 87.0, 59.0, 57.0, 65.0, 81.0, 106.0, 84.0], "value": 74.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 89.0, 73.0, 68.0, 70.0, 89.0, 72.0, 84.0, 101.0], "value": 80.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 92.0, 89.0, 98.0, 87.0, 93.0, 91.0, 93.0, 90.0], "value": 91.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 120.0, 97.0, 98.0, 98.0, 146.0, 133.0, 134.0, 127.0, 104.0], "value": 120.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 108.0, 145.0, 146.0, 91.0, 147.0, 147.0, 117.0, 92.0, 90.0], "value": 117.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 57.0, 64.0, 57.0, 57.0, 58.0, 59.0, 58.0, 58.0], "value": 58.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 74.0, 83.0, 77.0, 74.0, 69.0, 69.0, 70.0, 71.0], "value": 72.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 88.0, 88.0, 88.0, 86.0, 88.0, 92.0, 89.0, 86.0], "value": 88.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 137.0, 136.0, 138.0, 149.0, 128.0, 170.0, 136.0, 183.0, 154.0], "value": 147.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 196.0, 169.0, 172.0, 181.0, 236.0, 210.0, 175.0, 172.0, 168.0], "value": 188.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 80.0, 86.0, 88.0, 84.0, 66.0, 73.0, 89.0, 89.0], "value": 79.4, "unit": "score"}]}]} 05:52:18 INFO - Return code: 0 05:52:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:52:18 ERROR - # TBPL SUCCESS # 05:52:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:52:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:52:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:52:18 INFO - 2016-09-13 05:52:18.118 screenresolution[1675:11195] starting screenresolution argv=screenresolution get 05:52:18 INFO - 2016-09-13 05:52:18.121 screenresolution[1675:11195] Display 0: 1600x1200x32@60 05:52:18 INFO - 2016-09-13 05:52:18.127 screenresolution[1676:11198] starting screenresolution argv=screenresolution list 05:52:18 INFO - Available Modes on Display 0 05:52:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:52:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:52:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:52:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:52:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:52:18 INFO - Intel Iris: 05:52:18 INFO - Chipset Model: Intel Iris 05:52:18 INFO - Type: GPU 05:52:18 INFO - Bus: Built-In 05:52:18 INFO - VRAM (Dynamic, Max): 1536 MB 05:52:18 INFO - Vendor: Intel (0x8086) 05:52:18 INFO - Device ID: 0x0a2e 05:52:18 INFO - Revision ID: 0x0009 05:52:18 INFO - Displays: 05:52:18 INFO - Display: 05:52:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:52:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:52:18 INFO - Display Serial Number: 1600x1200 60 05:52:18 INFO - Main Display: Yes 05:52:18 INFO - Mirror: Off 05:52:18 INFO - Online: Yes 05:52:18 INFO - Rotation: Supported 05:52:18 INFO - Return code: 0 05:52:18 INFO - Running post-action listener: _resource_record_post_action 05:52:18 INFO - [mozharness: 2016-09-13 12:52:18.361078Z] Finished run-tests step (success) 05:52:18 INFO - Running post-run listener: _resource_record_post_run 05:52:18 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 33926144; Write bytes: 708506112; Read time: 315; Write time: 2011 05:52:18 INFO - TinderboxPrint: CPU usage
54.3% 05:52:18 INFO - TinderboxPrint: I/O read bytes / time
33,926,144 / 315 05:52:18 INFO - TinderboxPrint: I/O write bytes / time
708,506,112 / 2,011 05:52:18 INFO - TinderboxPrint: CPU idle
2,215.1 (45.7%) 05:52:18 INFO - TinderboxPrint: CPU system
974.5 (20.1%) 05:52:18 INFO - TinderboxPrint: CPU user
1,656.2 (34.2%) 05:52:18 INFO - TinderboxPrint: Swap in / out
434,917,376 / 0 05:52:18 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 391311872; Write bytes: 385910784; Read time: 29259; Write time: 719 05:52:18 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 32992256; Write bytes: 300501504; Read time: 302; Write time: 1246 05:52:18 INFO - Running post-run listener: _upload_blobber_files 05:52:18 INFO - Blob upload gear active. 05:52:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:52:18 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 05:52:18 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:52:18 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:52:19 INFO - (blobuploader) - INFO - Open directory for files ... 05:52:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:52:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:52:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:52:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:52:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:52:20 INFO - (blobuploader) - INFO - Done attempting. 05:52:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:52:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:52:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:52:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:52:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:52:22 INFO - (blobuploader) - INFO - Done attempting. 05:52:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:52:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:52:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:52:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:52:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:52:24 INFO - (blobuploader) - INFO - Done attempting. 05:52:24 INFO - (blobuploader) - INFO - Iteration through files over. 05:52:24 INFO - Return code: 0 05:52:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:52:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:52:24 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0df189ef94a1a8c89495813c9d02dbd1bb4a281c67d4e36493a08b660755cbb560cc92ffbb706c780d80a5b2abda98d37cd998498d3d6776d0ad6e94e571dce1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f803088f2ed255f87878a3278aad93110ad978f9f059849ebc7ed4b77d90190b68bdc26c78d6da6cf785dd88ca57f7313065abca68d90f1de59ed73c67d98d4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7ff5a42e698719aac99b7f0b9b449d0c83b3eab76eaaef473e61e7f79dc1ff0179a39c5a47d5269bf2d3c4aaf2a7ace191bccbc992461015788dd9a16391d2b"} 05:52:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:52:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:52:24 INFO - Contents: 05:52:24 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0df189ef94a1a8c89495813c9d02dbd1bb4a281c67d4e36493a08b660755cbb560cc92ffbb706c780d80a5b2abda98d37cd998498d3d6776d0ad6e94e571dce1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f803088f2ed255f87878a3278aad93110ad978f9f059849ebc7ed4b77d90190b68bdc26c78d6da6cf785dd88ca57f7313065abca68d90f1de59ed73c67d98d4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7ff5a42e698719aac99b7f0b9b449d0c83b3eab76eaaef473e61e7f79dc1ff0179a39c5a47d5269bf2d3c4aaf2a7ace191bccbc992461015788dd9a16391d2b"} 05:52:24 INFO - Running post-run listener: copy_logs_to_upload_dir 05:52:24 INFO - Copying logs to upload dir... 05:52:24 INFO - mkdir: /builds/slave/test/build/upload/logs 05:52:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.178805 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-13 05:52:24.902278) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-13 05:52:24.905573) ========= 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.QnzTprWqIg/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.q1gVyHRY3c/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0df189ef94a1a8c89495813c9d02dbd1bb4a281c67d4e36493a08b660755cbb560cc92ffbb706c780d80a5b2abda98d37cd998498d3d6776d0ad6e94e571dce1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f803088f2ed255f87878a3278aad93110ad978f9f059849ebc7ed4b77d90190b68bdc26c78d6da6cf785dd88ca57f7313065abca68d90f1de59ed73c67d98d4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7ff5a42e698719aac99b7f0b9b449d0c83b3eab76eaaef473e61e7f79dc1ff0179a39c5a47d5269bf2d3c4aaf2a7ace191bccbc992461015788dd9a16391d2b"} build_url:https://queue.taskcluster.net/v1/task/AkOssNPhREWrglmx2BtkFA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011198 build_url: 'https://queue.taskcluster.net/v1/task/AkOssNPhREWrglmx2BtkFA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0df189ef94a1a8c89495813c9d02dbd1bb4a281c67d4e36493a08b660755cbb560cc92ffbb706c780d80a5b2abda98d37cd998498d3d6776d0ad6e94e571dce1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f803088f2ed255f87878a3278aad93110ad978f9f059849ebc7ed4b77d90190b68bdc26c78d6da6cf785dd88ca57f7313065abca68d90f1de59ed73c67d98d4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7ff5a42e698719aac99b7f0b9b449d0c83b3eab76eaaef473e61e7f79dc1ff0179a39c5a47d5269bf2d3c4aaf2a7ace191bccbc992461015788dd9a16391d2b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-13 05:52:24.939458) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 05:52:24.940193) ========= 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.QnzTprWqIg/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.q1gVyHRY3c/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.004676 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 05:52:24.992254) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-13 05:52:24.992601) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-13 05:52:24.992920) ========= ========= Total master_lag: 0.18 =========