builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0026 starttime: 1473769875.5 results: success (0) buildid: 20160913030425 builduid: d6e69507404b4feeb25f80b26b9e1a7f revision: f5d043ce6d36a3c461cbd829d4a4a38394b7c436 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-13 05:31:15.495590) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-13 05:31:15.496141) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-13 05:31:15.496527) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-13 05:31:15.573821) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 05:31:15.574141) ========= 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.awzObfRxc1/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.jbl5vMtby4/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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:15-- 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.7M=0s 2016-09-13 05:31:16 (28.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.635051 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 05:31:16.233089) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 05:31:16.233418) ========= 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.awzObfRxc1/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.jbl5vMtby4/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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.028430 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 05:31:16.282188) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-13 05:31:16.282550) ========= 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.awzObfRxc1/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.jbl5vMtby4/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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:16,361 truncating revision to first 12 chars 2016-09-13 05:31:16,361 Setting DEBUG logging. 2016-09-13 05:31:16,361 attempt 1/10 2016-09-13 05:31:16,361 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:17,130 unpacking tar archive at: mozilla-central-f5d043ce6d36/testing/mozharness/ program finished with exit code 0 elapsedTime=1.045696 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-13 05:31:17.355837) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-13 05:31:17.356738) ========= script_repo_revision: f5d043ce6d36a3c461cbd829d4a4a38394b7c436 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-13 05:31:17.357840) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-13 05:31:17.358637) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-13 05:31:17.379703) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-13 05:31:17.380462) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.awzObfRxc1/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.jbl5vMtby4/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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:17 INFO - MultiFileLogger online at 20160913 05:31:17 in /builds/slave/test 05:31:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:31:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:31:17 INFO - {'append_to_log': False, 05:31:17 INFO - 'base_work_dir': '/builds/slave/test', 05:31:17 INFO - 'blob_upload_branch': 'Firefox', 05:31:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:31:17 INFO - 'branch': 'Firefox', 05:31:17 INFO - 'buildbot_json_path': 'buildprops.json', 05:31:17 INFO - 'config_files': ('talos/mac_config.py',), 05:31:17 INFO - 'default_actions': ('clobber', 05:31:17 INFO - 'read-buildbot-config', 05:31:17 INFO - 'download-and-extract', 05:31:17 INFO - 'populate-webroot', 05:31:17 INFO - 'create-virtualenv', 05:31:17 INFO - 'install', 05:31:17 INFO - 'run-tests'), 05:31:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:31:17 INFO - 'download_minidump_stackwalk': True, 05:31:17 INFO - 'download_symbols': 'ondemand', 05:31:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:31:17 INFO - 'tooltool.py': '/tools/tooltool.py', 05:31:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:31:17 INFO - '/tools/misc-python/virtualenv.py')}, 05:31:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:31:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:31:17 INFO - 'installer_path': 'installer.exe', 05:31:17 INFO - 'log_level': 'info', 05:31:17 INFO - 'log_name': 'talos', 05:31:17 INFO - 'log_to_console': True, 05:31:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:31:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:31:17 INFO - 'opt_config_files': (), 05:31:17 INFO - 'pip_index': False, 05:31:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:31:17 INFO - 'cmd': ('bash', 05:31:17 INFO - '-c', 05:31:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:31:17 INFO - 'enabled': True, 05:31:17 INFO - 'halt_on_failure': False, 05:31:17 INFO - 'name': 'check_screen_resolution'},), 05:31:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:31:17 INFO - 'cmd': ('bash', 05:31:17 INFO - '-c', 05:31:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:31:17 INFO - 'enabled': True, 05:31:17 INFO - 'halt_on_failure': False, 05:31:17 INFO - 'name': 'check_screen_resolution'},), 05:31:17 INFO - 'run_cmd_checks_enabled': True, 05:31:17 INFO - 'sps_profile': False, 05:31:17 INFO - 'sps_profile_interval': 0, 05:31:17 INFO - 'suite': 'dromaeojs', 05:31:17 INFO - 'system_bits': '32', 05:31:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:31:17 INFO - 'title': 't-yosemite-r7-0026', 05:31:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:31:17 INFO - 'use_talos_json': True, 05:31:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:31:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:31:17 INFO - 'work_dir': 'build'} 05:31:17 INFO - [mozharness: 2016-09-13 12:31:17.589423Z] Running clobber step. 05:31:17 INFO - Running pre-action listener: _resource_record_pre_action 05:31:17 INFO - Running main action method: clobber 05:31:17 INFO - rmtree: /builds/slave/test/build 05:31:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:31:19 INFO - Running post-action listener: _resource_record_post_action 05:31:19 INFO - [mozharness: 2016-09-13 12:31:19.830387Z] Finished clobber step (success) 05:31:19 INFO - [mozharness: 2016-09-13 12:31:19.830514Z] Running read-buildbot-config step. 05:31:19 INFO - Running pre-action listener: _resource_record_pre_action 05:31:19 INFO - Running main action method: read_buildbot_config 05:31:19 INFO - Using buildbot properties: 05:31:19 INFO - { 05:31:19 INFO - "project": "", 05:31:19 INFO - "product": "firefox", 05:31:19 INFO - "script_repo_revision": "production", 05:31:19 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:31:19 INFO - "repository": "", 05:31:19 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:31:19 INFO - "buildid": "20160913030425", 05:31:19 INFO - "stage_platform": "macosx64", 05:31:19 INFO - "basedir": "/builds/slave/test", 05:31:19 INFO - "nightly_build": "True", 05:31:19 INFO - "buildnumber": 41, 05:31:19 INFO - "slavename": "t-yosemite-r7-0026", 05:31:19 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 05:31:19 INFO - "platform": "yosemite_r7", 05:31:19 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:31:19 INFO - "branch": "mozilla-central", 05:31:19 INFO - "repo_path": "mozilla-central", 05:31:19 INFO - "revision": "f5d043ce6d36a3c461cbd829d4a4a38394b7c436", 05:31:19 INFO - "pgo_build": "False", 05:31:19 INFO - "builduid": "d6e69507404b4feeb25f80b26b9e1a7f", 05:31:19 INFO - "slavebuilddir": "test" 05:31:19 INFO - } 05:31:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/AkOssNPhREWrglmx2BtkFA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 05:31:19 INFO - Running post-action listener: _resource_record_post_action 05:31:19 INFO - [mozharness: 2016-09-13 12:31:19.833928Z] Finished read-buildbot-config step (success) 05:31:19 INFO - [mozharness: 2016-09-13 12:31:19.834038Z] Running download-and-extract step. 05:31:19 INFO - Running pre-action listener: _resource_record_pre_action 05:31:19 INFO - Running main action method: download_and_extract 05:31:19 INFO - mkdir: /builds/slave/test/build/tests 05:31:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:19 INFO - trying https://queue.taskcluster.net/v1/task/AkOssNPhREWrglmx2BtkFA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 05:31:19 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:19 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:20 INFO - Downloaded 1199 bytes. 05:31:20 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:31:20 INFO - Using the following test package requirements: 05:31:20 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:31:20 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:31:20 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 05:31:20 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:31:20 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 05:31:20 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:31:20 INFO - u'jsshell-mac.zip'], 05:31:20 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:31:20 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 05:31:20 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:31:20 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:31:20 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 05:31:20 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:31:20 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 05:31:20 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:31:20 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 05:31:20 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:31:20 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 05:31:20 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:31:21 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:21 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 05:31:28 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:28 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:28 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 05:31:35 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:35 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 05:31:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:36 INFO - trying https://queue.taskcluster.net/v1/task/AkOssNPhREWrglmx2BtkFA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:31:36 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:36 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:39 INFO - Downloaded 111538977 bytes. 05:31:39 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:39 INFO - mkdir: /builds/slave/test/properties 05:31:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:31:39 INFO - Writing to file /builds/slave/test/properties/build_url 05:31:39 INFO - Contents: 05:31:39 INFO - build_url:https://queue.taskcluster.net/v1/task/AkOssNPhREWrglmx2BtkFA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:31:40 INFO - Running post-action listener: _resource_record_post_action 05:31:40 INFO - Running post-action listener: set_extra_try_arguments 05:31:40 INFO - [mozharness: 2016-09-13 12:31:40.060661Z] Finished download-and-extract step (success) 05:31:40 INFO - [mozharness: 2016-09-13 12:31:40.060767Z] Running populate-webroot step. 05:31:40 INFO - Running pre-action listener: _resource_record_pre_action 05:31:40 INFO - Running main action method: populate_webroot 05:31:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:31:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:31:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:31:40 INFO - '--tppagecycles', 05:31:40 INFO - '7'], 05:31:40 INFO - 'tests': ['tsvgm']}}, 05:31:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:31:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:31:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:31:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:31:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:31:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:31:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:40 INFO - 'talos_options': ['--disable-e10s'], 05:31:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:31:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:31:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:40 INFO - 'talos_options': ['--disable-e10s'], 05:31:40 INFO - 'tests': ['damp', 'tps']}, 05:31:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:40 INFO - 'tests': ['damp', 'tps']}, 05:31:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:31:40 INFO - 'tests': ['dromaeo_dom']}, 05:31:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:31:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:31:40 INFO - 'tests': ['basic_compositor_video']}, 05:31:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:31:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:31:40 INFO - 'tests': ['a11yr', 05:31:40 INFO - 'ts_paint', 05:31:40 INFO - 'tpaint', 05:31:40 INFO - 'sessionrestore', 05:31:40 INFO - 'sessionrestore_no_auto_restore', 05:31:40 INFO - 'tabpaint']}, 05:31:40 INFO - 'other-e10s': {'tests': ['a11yr', 05:31:40 INFO - 'ts_paint', 05:31:40 INFO - 'tpaint', 05:31:40 INFO - 'sessionrestore', 05:31:40 INFO - 'sessionrestore_no_auto_restore', 05:31:40 INFO - 'tabpaint']}, 05:31:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:31:40 INFO - 'ts_paint', 05:31:40 INFO - 'tpaint', 05:31:40 INFO - 'sessionrestore', 05:31:40 INFO - 'sessionrestore_no_auto_restore', 05:31:40 INFO - 'tabpaint']}, 05:31:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:31:40 INFO - 'ts_paint', 05:31:40 INFO - 'tpaint', 05:31:40 INFO - 'sessionrestore', 05:31:40 INFO - 'sessionrestore_no_auto_restore', 05:31:40 INFO - 'tabpaint']}, 05:31:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:31:40 INFO - 'tests': ['a11yr', 05:31:40 INFO - 'ts_paint', 05:31:40 INFO - 'tpaint', 05:31:40 INFO - 'sessionrestore', 05:31:40 INFO - 'sessionrestore_no_auto_restore', 05:31:40 INFO - 'tabpaint']}, 05:31:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:31:40 INFO - 'tests': ['a11yr', 05:31:40 INFO - 'ts_paint', 05:31:40 INFO - 'tpaint', 05:31:40 INFO - 'sessionrestore', 05:31:40 INFO - 'sessionrestore_no_auto_restore', 05:31:40 INFO - 'tabpaint']}, 05:31:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:31:40 INFO - 'tests': ['tsvgx', 05:31:40 INFO - 'tsvgr_opacity', 05:31:40 INFO - 'tart', 05:31:40 INFO - 'tscrollx', 05:31:40 INFO - 'cart']}, 05:31:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:31:40 INFO - 'tsvgr_opacity', 05:31:40 INFO - 'tart', 05:31:40 INFO - 'tscrollx', 05:31:40 INFO - 'cart']}, 05:31:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:40 INFO - 'talos_options': ['--disable-e10s'], 05:31:40 INFO - 'tests': ['tp5o']}, 05:31:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:40 INFO - 'tests': ['tp5o']}, 05:31:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:40 INFO - 'talos_options': ['--disable-e10s', 05:31:40 INFO - '--xperf_path', 05:31:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:31:40 INFO - 'tests': ['tp5n']}, 05:31:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:40 INFO - 'talos_options': ['--xperf_path', 05:31:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:31:40 INFO - 'tests': ['tp5n']}}, 05:31:40 INFO - 'talos.zip': {'path': '', 05:31:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:31:40 INFO - Running post-action listener: _resource_record_post_action 05:31:40 INFO - [mozharness: 2016-09-13 12:31:40.080149Z] Finished populate-webroot step (success) 05:31:40 INFO - [mozharness: 2016-09-13 12:31:40.080287Z] Running create-virtualenv step. 05:31:40 INFO - Running pre-action listener: _resource_record_pre_action 05:31:40 INFO - Running main action method: create_virtualenv 05:31:40 INFO - Creating virtualenv /builds/slave/test/build/venv 05:31:40 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:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:31:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:31:40 INFO - Using real prefix '/tools/python27' 05:31:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:31:40 INFO - Installing distribute.............................................................................................................................................................................................done. 05:31:43 INFO - Installing pip.................done. 05:31:43 INFO - Return code: 0 05:31:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:31:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:43 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 0x10ba44490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328588f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b858d30>, '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 0x10b67edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b850b20>, '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.awzObfRxc1/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.jbl5vMtby4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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:43 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:43 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:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.awzObfRxc1/Render', 05:31:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:43 INFO - 'HOME': '/Users/cltbld', 05:31:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:43 INFO - 'LOGNAME': 'cltbld', 05:31:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:43 INFO - 'MOZ_NO_REMOTE': '1', 05:31:43 INFO - 'NO_EM_RESTART': '1', 05:31:43 INFO - 'PAGER': '/bin/cat', 05:31:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:43 INFO - 'PWD': '/builds/slave/test', 05:31:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:43 INFO - 'SHELL': '/bin/bash', 05:31:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jbl5vMtby4/Listeners', 05:31:43 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 05:31:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:43 INFO - 'USER': 'cltbld', 05:31:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:43 INFO - 'XPC_FLAGS': '0x0', 05:31:43 INFO - 'XPC_SERVICE_NAME': '0', 05:31:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:44 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:44 INFO - Cleaning up... 05:31:44 INFO - Return code: 0 05:31:44 INFO - Installing psutil>=3.1.1 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', '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 0x10ba44490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328588f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b858d30>, '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 0x10b67edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b850b20>, '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.awzObfRxc1/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.jbl5vMtby4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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', 'psutil>=3.1.1'] 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 psutil>=3.1.1 05:31:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.awzObfRxc1/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.jbl5vMtby4/Listeners', 05:31:44 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 psutil>=3.1.1 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 - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:31:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:31:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:31:47 INFO - Installing collected packages: psutil 05:31:47 INFO - Running setup.py install for psutil 05:31:47 INFO - building 'psutil._psutil_osx' extension 05:31:47 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:48 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:48 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:48 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:48 INFO - building 'psutil._psutil_posix' extension 05:31:48 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:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:31:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:31:48 INFO - ^ 05:31:48 INFO - 1 warning generated. 05:31:48 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:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:31:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:31:48 INFO - Successfully installed psutil 05:31:48 INFO - Cleaning up... 05:31:49 INFO - Return code: 0 05:31:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ba44490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328588f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b858d30>, '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 0x10b67edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b850b20>, '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.awzObfRxc1/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.jbl5vMtby4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:31:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:31:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.awzObfRxc1/Render', 05:31:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:49 INFO - 'HOME': '/Users/cltbld', 05:31:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:49 INFO - 'LOGNAME': 'cltbld', 05:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:49 INFO - 'MOZ_NO_REMOTE': '1', 05:31:49 INFO - 'NO_EM_RESTART': '1', 05:31:49 INFO - 'PAGER': '/bin/cat', 05:31:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:49 INFO - 'PWD': '/builds/slave/test', 05:31:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:49 INFO - 'SHELL': '/bin/bash', 05:31:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jbl5vMtby4/Listeners', 05:31:49 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 05:31:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:49 INFO - 'USER': 'cltbld', 05:31:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:49 INFO - 'XPC_FLAGS': '0x0', 05:31:49 INFO - 'XPC_SERVICE_NAME': '0', 05:31:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:31:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:31:51 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:51 INFO - Installing collected packages: mozsystemmonitor 05:31:51 INFO - Running setup.py install for mozsystemmonitor 05:31:52 INFO - Successfully installed mozsystemmonitor 05:31:52 INFO - Cleaning up... 05:31:52 INFO - Return code: 0 05:31:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:31:52 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:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:52 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:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:52 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 0x10ba44490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328588f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b858d30>, '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 0x10b67edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b850b20>, '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.awzObfRxc1/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.jbl5vMtby4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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:52 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:52 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:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.awzObfRxc1/Render', 05:31:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:52 INFO - 'HOME': '/Users/cltbld', 05:31:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:52 INFO - 'LOGNAME': 'cltbld', 05:31:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:52 INFO - 'MOZ_NO_REMOTE': '1', 05:31:52 INFO - 'NO_EM_RESTART': '1', 05:31:52 INFO - 'PAGER': '/bin/cat', 05:31:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:52 INFO - 'PWD': '/builds/slave/test', 05:31:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:52 INFO - 'SHELL': '/bin/bash', 05:31:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jbl5vMtby4/Listeners', 05:31:52 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 05:31:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:52 INFO - 'USER': 'cltbld', 05:31:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:52 INFO - 'XPC_FLAGS': '0x0', 05:31:52 INFO - 'XPC_SERVICE_NAME': '0', 05:31:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:52 INFO - Downloading/unpacking jsonschema==2.5.1 05:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:31:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 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 functools32-3.2.3-2.tar.gz 05:31:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:31:55 INFO - warning: no files found matching '*.txt' 05:31:55 INFO - no previously-included directories found matching 'build' 05:31:55 INFO - no previously-included directories found matching 'dist' 05:31:55 INFO - no previously-included directories found matching '.git*' 05:31:55 INFO - Installing collected packages: jsonschema, functools32 05:31:55 INFO - Running setup.py install for functools32 05:31:55 INFO - warning: no files found matching '*.txt' 05:31:55 INFO - no previously-included directories found matching 'build' 05:31:55 INFO - no previously-included directories found matching 'dist' 05:31:55 INFO - no previously-included directories found matching '.git*' 05:31:55 INFO - Successfully installed jsonschema functools32 05:31:55 INFO - Cleaning up... 05:31:55 INFO - Return code: 0 05:31:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:31:55 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:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:55 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:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:55 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 0x10ba44490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328588f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b858d30>, '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 0x10b67edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b850b20>, '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.awzObfRxc1/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.jbl5vMtby4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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:55 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:55 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:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.awzObfRxc1/Render', 05:31:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:55 INFO - 'HOME': '/Users/cltbld', 05:31:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:55 INFO - 'LOGNAME': 'cltbld', 05:31:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:55 INFO - 'MOZ_NO_REMOTE': '1', 05:31:55 INFO - 'NO_EM_RESTART': '1', 05:31:55 INFO - 'PAGER': '/bin/cat', 05:31:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:55 INFO - 'PWD': '/builds/slave/test', 05:31:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:55 INFO - 'SHELL': '/bin/bash', 05:31:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jbl5vMtby4/Listeners', 05:31:55 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 05:31:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:55 INFO - 'USER': 'cltbld', 05:31:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:55 INFO - 'XPC_FLAGS': '0x0', 05:31:55 INFO - 'XPC_SERVICE_NAME': '0', 05:31:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:31:55 INFO - Cleaning up... 05:31:55 INFO - Return code: 0 05:31:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:31:55 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:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:55 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:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:55 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 0x10ba44490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328588f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b858d30>, '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 0x10b67edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b850b20>, '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.awzObfRxc1/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.jbl5vMtby4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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:55 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:55 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:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.awzObfRxc1/Render', 05:31:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:55 INFO - 'HOME': '/Users/cltbld', 05:31:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:55 INFO - 'LOGNAME': 'cltbld', 05:31:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:55 INFO - 'MOZ_NO_REMOTE': '1', 05:31:55 INFO - 'NO_EM_RESTART': '1', 05:31:55 INFO - 'PAGER': '/bin/cat', 05:31:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:55 INFO - 'PWD': '/builds/slave/test', 05:31:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:55 INFO - 'SHELL': '/bin/bash', 05:31:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jbl5vMtby4/Listeners', 05:31:55 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 05:31:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:55 INFO - 'USER': 'cltbld', 05:31:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:55 INFO - 'XPC_FLAGS': '0x0', 05:31:55 INFO - 'XPC_SERVICE_NAME': '0', 05:31:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:56 INFO - Downloading/unpacking blobuploader==1.2.4 05:31:56 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:56 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:56 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:56 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:56 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:56 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:58 INFO - Downloading blobuploader-1.2.4.tar.gz 05:31:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:31:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:31:58 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:58 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:58 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:58 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:58 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:58 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:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:31:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:59 INFO - Downloading docopt-0.6.1.tar.gz 05:31:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:31:59 INFO - Installing collected packages: blobuploader, requests, docopt 05:31:59 INFO - Running setup.py install for blobuploader 05:31:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:31:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:31:59 INFO - Running setup.py install for requests 05:32:00 INFO - Running setup.py install for docopt 05:32:00 INFO - Successfully installed blobuploader requests docopt 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', '--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 0x10ba44490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328588f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b858d30>, '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 0x10b67edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b850b20>, '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.awzObfRxc1/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.jbl5vMtby4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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', '--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:32:00 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:32:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.awzObfRxc1/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.jbl5vMtby4/Listeners', 05:32:00 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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:01 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-mmQCI3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:32:01 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-kBLsfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:32:01 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-kC_1NE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:32:01 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-xQo8u6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:32:01 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-XICTcF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:32:01 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-rhdB1O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:32:01 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-24MPhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:32:01 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-w93LAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:32:01 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-E4Fokt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:32:01 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-pYP74l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:32:02 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-aJHPto-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:32:02 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-Vsuk0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:32:02 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-ReQ7jK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:32:02 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-gDAMt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:32:02 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-dUjFl8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:32:02 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-XWNMkw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:32:02 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-3hna4x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:32:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:32:02 INFO - Running setup.py install for manifestparser 05:32:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:32:02 INFO - Running setup.py install for mozcrash 05:32:03 INFO - Running setup.py install for mozdebug 05:32:03 INFO - Running setup.py install for mozdevice 05:32:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:32:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:32:03 INFO - Running setup.py install for mozfile 05:32:03 INFO - Running setup.py install for mozhttpd 05:32:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:32:03 INFO - Running setup.py install for mozinfo 05:32:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:32:03 INFO - Running setup.py install for mozInstall 05:32:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:32:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:32:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:32:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:32:03 INFO - Running setup.py install for mozleak 05:32:04 INFO - Running setup.py install for mozlog 05:32:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:32:04 INFO - Running setup.py install for moznetwork 05:32:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:32:04 INFO - Running setup.py install for mozprocess 05:32:04 INFO - Running setup.py install for mozprofile 05:32:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:32:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:32:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:32:04 INFO - Running setup.py install for mozrunner 05:32:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:32:04 INFO - Running setup.py install for mozscreenshot 05:32:05 INFO - Running setup.py install for moztest 05:32:05 INFO - Running setup.py install for mozversion 05:32:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:32:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:32:05 INFO - Cleaning up... 05:32:05 INFO - Return code: 0 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/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 0x10ba44490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328588f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b858d30>, '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 0x10b67edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b850b20>, '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.awzObfRxc1/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.jbl5vMtby4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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/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:05 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:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.awzObfRxc1/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.jbl5vMtby4/Listeners', 05:32:05 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:32:05 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-dbEUWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:32:05 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:32:05 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-BmmnL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:32:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:32:05 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-nhGmFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:32:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:32:05 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-oV_K57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:32:06 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:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:32:06 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-BiZ13Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:32:06 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:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:32:06 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-FVI6F2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:32:06 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:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:32:06 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-zz74D0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:32:06 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:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:32:06 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-un2M22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:32:06 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:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:32:06 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-oXJFNz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:32:06 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:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:32:06 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-pXcyqD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:32:06 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-29yIIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:32:06 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-0vT022-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:32:06 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-y4Htx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:32:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:32:07 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-AruGPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:32:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:32:07 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-9cUOnk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:32:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:32:07 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-Jwc8cz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:32:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:32:07 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-A3KwiX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:32:07 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:07 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:07 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:07 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:07 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:07 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:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:32:07 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:07 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:07 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:07 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:07 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:07 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:09 INFO - Downloading blessings-1.6.tar.gz 05:32:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:32:10 INFO - Installing collected packages: blessings 05:32:10 INFO - Running setup.py install for blessings 05:32:10 INFO - Successfully installed blessings 05:32:10 INFO - Cleaning up... 05:32:10 INFO - Return code: 0 05:32:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:32:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:32:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:32:10 INFO - Reading from file tmpfile_stdout 05:32:10 INFO - Current package versions: 05:32:10 INFO - blessings == 1.6 05:32:10 INFO - blobuploader == 1.2.4 05:32:10 INFO - docopt == 0.6.1 05:32:10 INFO - functools32 == 3.2.3-2 05:32:10 INFO - jsonschema == 2.5.1 05:32:10 INFO - manifestparser == 1.1 05:32:10 INFO - mozInstall == 1.12 05:32:10 INFO - mozcrash == 0.17 05:32:10 INFO - mozdebug == 0.1 05:32:10 INFO - mozdevice == 0.48 05:32:10 INFO - mozfile == 1.2 05:32:10 INFO - mozhttpd == 0.7 05:32:10 INFO - mozinfo == 0.9 05:32:10 INFO - mozleak == 0.1 05:32:10 INFO - mozlog == 3.3 05:32:10 INFO - moznetwork == 0.27 05:32:10 INFO - mozprocess == 0.23 05:32:10 INFO - mozprofile == 0.28 05:32:10 INFO - mozrunner == 6.12 05:32:10 INFO - mozscreenshot == 0.1 05:32:10 INFO - mozsystemmonitor == 0.3 05:32:10 INFO - moztest == 0.7 05:32:10 INFO - mozversion == 1.4 05:32:10 INFO - psutil == 3.1.1 05:32:10 INFO - requests == 1.2.3 05:32:10 INFO - wsgiref == 0.1.2 05:32:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:32:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ba44490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328588f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b858d30>, '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 0x10b67edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b850b20>, '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.awzObfRxc1/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.jbl5vMtby4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:32:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:32:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.awzObfRxc1/Render', 05:32:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:10 INFO - 'HOME': '/Users/cltbld', 05:32:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:10 INFO - 'LOGNAME': 'cltbld', 05:32:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:10 INFO - 'MOZ_NO_REMOTE': '1', 05:32:10 INFO - 'NO_EM_RESTART': '1', 05:32:10 INFO - 'PAGER': '/bin/cat', 05:32:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:10 INFO - 'PWD': '/builds/slave/test', 05:32:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:10 INFO - 'SHELL': '/bin/bash', 05:32:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jbl5vMtby4/Listeners', 05:32:10 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 05:32:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:10 INFO - 'USER': 'cltbld', 05:32:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:10 INFO - 'XPC_FLAGS': '0x0', 05:32:10 INFO - 'XPC_SERVICE_NAME': '0', 05:32:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:32:10 INFO - Cleaning up... 05:32:10 INFO - Return code: 0 05:32:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:32:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ba44490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ba570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328588f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b858d30>, '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 0x10b67edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b850b20>, '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.awzObfRxc1/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.jbl5vMtby4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:32:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:32:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.awzObfRxc1/Render', 05:32:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:10 INFO - 'HOME': '/Users/cltbld', 05:32:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:10 INFO - 'LOGNAME': 'cltbld', 05:32:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:10 INFO - 'MOZ_NO_REMOTE': '1', 05:32:10 INFO - 'NO_EM_RESTART': '1', 05:32:10 INFO - 'PAGER': '/bin/cat', 05:32:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:10 INFO - 'PWD': '/builds/slave/test', 05:32:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:10 INFO - 'SHELL': '/bin/bash', 05:32:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jbl5vMtby4/Listeners', 05:32:10 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 05:32:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:10 INFO - 'USER': 'cltbld', 05:32:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:10 INFO - 'XPC_FLAGS': '0x0', 05:32:10 INFO - 'XPC_SERVICE_NAME': '0', 05:32:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:32:11 INFO - Cleaning up... 05:32:11 INFO - Return code: 0 05:32:11 INFO - Running post-action listener: _resource_record_post_action 05:32:11 INFO - Running post-action listener: _start_resource_monitoring 05:32:11 INFO - Starting resource monitoring. 05:32:11 INFO - [mozharness: 2016-09-13 12:32:11.112779Z] Finished create-virtualenv step (success) 05:32:11 INFO - [mozharness: 2016-09-13 12:32:11.113783Z] Running install step. 05:32:11 INFO - Running pre-action listener: _resource_record_pre_action 05:32:11 INFO - Running main action method: install 05:32:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:32:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:32:11 INFO - Reading from file tmpfile_stdout 05:32:11 INFO - Detecting whether we're running mozinstall >=1.0... 05:32:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:32:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:32:11 INFO - Reading from file tmpfile_stdout 05:32:11 INFO - Output received: 05:32:11 INFO - Usage: mozinstall [options] installer 05:32:11 INFO - Options: 05:32:11 INFO - -h, --help show this help message and exit 05:32:11 INFO - -d DEST, --destination=DEST 05:32:11 INFO - Directory to install application into. [default: 05:32:11 INFO - "/builds/slave/test"] 05:32:11 INFO - --app=APP Application being installed. [default: firefox] 05:32:11 INFO - mkdir: /builds/slave/test/build/application 05:32:11 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:11 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:45 INFO - Reading from file tmpfile_stdout 05:32:45 INFO - Output received: 05:32:45 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:32:45 INFO - Running post-action listener: _resource_record_post_action 05:32:45 INFO - [mozharness: 2016-09-13 12:32:45.628982Z] Finished install step (success) 05:32:45 INFO - [mozharness: 2016-09-13 12:32:45.629139Z] Running run-tests step. 05:32:45 INFO - Running pre-action listener: _resource_record_pre_action 05:32:45 INFO - Running main action method: run_tests 05:32:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:32:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:32:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:32:45 INFO - 2016-09-13 05:32:45.643 screenresolution[1646:8240] starting screenresolution argv=screenresolution get 05:32:45 INFO - 2016-09-13 05:32:45.646 screenresolution[1646:8240] Display 0: 1600x1200x32@60 05:32:45 INFO - 2016-09-13 05:32:45.652 screenresolution[1647:8242] starting screenresolution argv=screenresolution list 05:32:45 INFO - Available Modes on Display 0 05:32:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:32:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:32:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:32:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:32:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:32:45 INFO - Intel Iris: 05:32:45 INFO - Chipset Model: Intel Iris 05:32:45 INFO - Type: GPU 05:32:45 INFO - Bus: Built-In 05:32:45 INFO - VRAM (Dynamic, Max): 1536 MB 05:32:45 INFO - Vendor: Intel (0x8086) 05:32:45 INFO - Device ID: 0x0a2e 05:32:45 INFO - Revision ID: 0x0009 05:32:45 INFO - Displays: 05:32:45 INFO - Display: 05:32:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:32:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:32:45 INFO - Display Serial Number: 1600x1200 60 05:32:45 INFO - Main Display: Yes 05:32:45 INFO - Mirror: Off 05:32:45 INFO - Online: Yes 05:32:45 INFO - Rotation: Supported 05:32:45 INFO - Return code: 0 05:32:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:32:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:32:45 INFO - Python 2.7.3 05:32:45 INFO - Return code: 0 05:32:45 INFO - grabbing minidump binary from tooltool 05:32:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:45 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 0x10b858d30>, '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 0x10b67edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b850b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:32:45 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:45 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:45 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:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:32:46 INFO - Return code: 0 05:32:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:32:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:32:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:32:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:32:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:32:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--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-0026', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:32:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --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-0026 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:32:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.awzObfRxc1/Render', 05:32:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:46 INFO - 'HOME': '/Users/cltbld', 05:32:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:46 INFO - 'LOGNAME': 'cltbld', 05:32:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:32:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:32:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:46 INFO - 'MOZ_NO_REMOTE': '1', 05:32:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:32:46 INFO - 'NO_EM_RESTART': '1', 05:32:46 INFO - 'PAGER': '/bin/cat', 05:32:46 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:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:46 INFO - 'PWD': '/builds/slave/test', 05:32:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:32:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:46 INFO - 'SHELL': '/bin/bash', 05:32:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jbl5vMtby4/Listeners', 05:32:46 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 05:32:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:46 INFO - 'USER': 'cltbld', 05:32:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:46 INFO - 'XPC_FLAGS': '0x0', 05:32:46 INFO - 'XPC_SERVICE_NAME': '0', 05:32:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--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-0026', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:32:46 INFO - mozversion application_buildid: 20160913030425 05:32:46 INFO - mozversion application_changeset: f5d043ce6d36a3c461cbd829d4a4a38394b7c436 05:32:46 INFO - mozversion application_display_name: Nightly 05:32:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:32:46 INFO - mozversion application_name: Firefox 05:32:46 INFO - mozversion application_remotingname: firefox 05:32:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:32:46 INFO - mozversion application_vendor: Mozilla 05:32:46 INFO - mozversion application_version: 51.0a1 05:32:46 INFO - mozversion platform_buildid: 20160913030425 05:32:46 INFO - mozversion platform_changeset: f5d043ce6d36a3c461cbd829d4a4a38394b7c436 05:32:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:32:46 INFO - mozversion platform_version: 51.0a1 05:32:46 INFO - using testdate: 1473769966 05:32:46 INFO - actual date: 1473769966 05:32:46 INFO - starting webserver on 'localhost:49281' 05:32:46 INFO - SUITE-START | Running 2 tests 05:32:46 INFO - TEST-START | dromaeo_css 05:32:46 INFO - operating with platform_type : mac_ 05:32:46 INFO - Initialising browser for dromaeo_css test... 05:32:46 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmpRl8Y5i/profile http://localhost:49281/getInfo.html) 05:32:48 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 05:32:48 INFO - PROCESS | 1657 | colorDepth:24 05:32:48 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 05:32:48 INFO - PROCESS | 1657 | __metrics 05:32:48 INFO - PROCESS | 1657 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:32:48 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:32:49 INFO - TEST-INFO | 1657: exit 0 05:32:49 INFO - Browser initialized. 05:32:49 INFO - Running cycle 1/1 for dromaeo_css test... 05:32:49 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmpRl8Y5i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:33:01 INFO - PROCESS | 1661 | RSS: Main: 229236736 05:33:01 INFO - PROCESS | 1661 | 05:33:52 INFO - PROCESS | 1661 | 1473770032455 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:04 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49281/tests/dromaeo/cssquery-ext.html) 05:36:06 INFO - PROCESS | 1661 | RSS: Main: 437960704 05:36:06 INFO - PROCESS | 1661 | 05:39:09 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-ext.html (next: http://localhost:49281/tests/dromaeo/cssquery-jquery.html) 05:39:09 INFO - PROCESS | 1661 | RSS: Main: 369512448 05:39:09 INFO - PROCESS | 1661 | 05:42:12 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49281/tests/dromaeo/cssquery-mootools.html) 05:42:12 INFO - PROCESS | 1661 | RSS: Main: 333631488 05:42:12 INFO - PROCESS | 1661 | 05:45:15 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49281/tests/dromaeo/cssquery-prototype.html) 05:45:15 INFO - PROCESS | 1661 | RSS: Main: 348209152 05:45:15 INFO - PROCESS | 1661 | 05:48:19 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49281/tests/dromaeo/cssquery-yui.html) 05:48:19 INFO - PROCESS | 1661 | RSS: Main: 329166848 05:48:19 INFO - PROCESS | 1661 | 05:51:23 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-yui.html (next: http://localhost:49281/tests/dromaeo/cssquery-dojo.html) 05:51:23 INFO - PROCESS | 1661 | RSS: Main: 447356928 05:51:23 INFO - PROCESS | 1661 | 05:51:23 INFO - PROCESS | 1661 | __start_tp_report 05:51:23 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 05:51:23 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 05:51:23 INFO - PROCESS | 1661 | |i|pagename|runs| 05:51:23 INFO - PROCESS | 1661 | |0;dojo.html;2789.8744556494958;2972.2272209225607;3326.068700763787;3352.742721479446;3378.375000624883;5505.779768809243;5609.467100625439;5649.293838270216;5706.144078388239;5803.709814509279;5635.887282254353;5660;5704.11586204138;5713.657180569169;5769.509591684701;5166.793328266865;5183.2743415921805;5224.085784987631;5255.343082114736;5263.815766448156;5298.443663415324;5300.92048619271;5300.966311569246;5301.867453313659;5303.761330740107;5136.666116702421;5289.576833853302;5317;5351.678899266057;5357.1696387060065;5118.976204759026;5154.407243167008;5231.764570594332;5259.526642602146;5291.391489978663;5063.87340316495;5102.413222479426;5160.92258616121;5175.637705360588;5246.501582349671;5628.662280263156;5634.0703783875615;5638.4925356718095;5643.351014633328;5714.7428365723645;6252.6561039142825;6259.593126446812;6267.9686601567155;6291.842703932392;6294;4353.717008394476;6181.3509581493745;6190.380961903818;6301.4013668701855;6304.779332723333;5754.79858204965;5782.843431313754;5817.680027598522;5853.268341457318;5856.648601083949;6250.499960003189;6263.404976527223;6273.8745225095245;6276.560640755103;6289.119523266746;5995.730192141277;6053.031514957674;6190.938090619036;6198.876022479615;6225.159603453566;6123.740835073669;6161.7227224775;6216.813495595139;6216.844578885564;6297.27581328144;6236.563440559117;6260.029695397221;6260.373962603703;6261.060840873905;6262.968685156545;6196.783112591038;6220.595661282002;6284.905726414107;6292.937070629235;6294.65379404128;4816.590589799906;4869.780859861315;4879.829205977774;4931.260310953386;4941.061198372297;4450.933236001462;4471.731696098244;4495.64034877209;4496.797644106023;4498.797554110073;5717.628354156966;5735.1684005818925;5735.8566035849435;5738.77044918207;5742.971285143548;6173.475254603409;6196.845078873064;6262.436380725665;6262.46769024638;6270.498360131179;5769.336526299446;5779.624324418899;5792.333881603585;5798.217240672455;5815.854603634858;5435.347758268954;5438.347398312228;5450.29146210998;5458.563314934796;5460.6996615186545;6186.690665466798;6204.441600255998;6275.246970363585;6304.842378940563;6322;6208.25500939872;6254.155688981929;6284.371562843679;6295.716692748746;6324.4940404766585;6284.654344010941;6305.96847015762;6318.526110541636;6320.494360451061;6323.7786677466065;6099.054646529795;6139.8948189324565;6232.688365581794;6237.656928868956;6248.750049997949;6166.599171053867;6201.844953876189;6217.658028808459;6300.810975670737;6318.05229215603;5419.078756611464;5712.857178570569;6169.413905678954;6179.0422484514975;6190.133381326528;5995.760169593167;6140.478059365004;6177.536684748572;6191.9690401547705;6211.130441738251;5883.705814709162;5948.970255148696;5995.070764031582;6000.249968753906;6002.429769171922;5376.005438993799;5823.505002074871;6084.513238940963;6097.548858883019;6115.331611965906;6013;6014.849628759316;6035.517158627212;6045.365236650088;6093.6039157454625 05:51:23 INFO - PROCESS | 1661 | |1;ext.html;461.7737308718729;483.3136945537337;501.76667849449996;503.3255437713463;528.1127705454837;29903.252418689517;30315.242118947037;31450.842745786238;31462.842685786538;31490.212744681365;31791.205219869516;41162.38256426156;41815.79092104543;41944.58055419445;44018.89952751183;8250.711225107123;9840.114389704926;9847.950760246189;9861.11249987501;9866.260030497706;9712.902870971306;9824.312298139133;9840.753981150458;9842.507874606277;9843.015698430136;9802.313838031341;9808.754781130458;9852.162566181883;9870.618113464085;9911.108000279974;9754.853677194847;9807.185670650952;9819.705408837746;9828.557714902847;9856;9857.457839818808;9883.505824708736;9894.208463322917;9908.851367229498;9921.702348929544;39024.60975390238;39390.87918570596;39414.802925985474;39465.81602551928;39468.60531394707;3343.8996830095134;3418.179636887154;3572.481990111445;3897.7661340319387;4188.350805625102;29492.410151796845;30565.847170764227;30659.233519161975;30722.078337649906;30755.846220768977;3169.889053883103;3202.6797320267788;3236.304194598149;3269.9509507357407;3273.9836300818583;490663;492373;493285;494116.5294173542;494600;53677.7316113417;53730.194047089324;53853.38439846811;53927;54035;59859.10211346833;60018;60035.69982150061;60040.39959600348;60224.69887650534;16077.276522556513;16329.101899395648;16340.428085016967;16342.509724708278;16361.590960226089;12678.175918565265;12727.363631818373;12765.425555849823;12768.297743624003;12787.680307992188;11263.718407039725;14838.851611483962;15815.23523384107;15835.68328633421;16062.518124456285;5087.771050302746;5115.974420127875;6084.421979911901;6119.602225855305;6144.907826382608;858.4891989266376;920.4983284110176;941.5621735892843;963.2582911158369;1035.502958579886;27653.861730691217;28987.56518652222;29024.56463153012;29780;30064.69935300663;829.8298848735994;1006.1849901579744;1026.1893104447552;1033.617561502234;1038.08129805123;13017;14887.47893823711;14939.925300373647;15333.156676382907;15365.154916479478;636444;636584;637409;642237;642463;56432;56566;56660.43339566634;56669;56825.715871420376;1241.6771639373828;1271.8918891894293;1348.6965432777547;1373.9656508587366;1408.0917808013774;28948.81563028023;30242.54636180503;30401.23996900095;30882.53676194814;30906.845465773426;44525.33212001758;44904.77547612241;44958;44968;45002.09995800131;532286;533290;533619;534217;537448;25440.929451287706;25870.87064564665;26151.823167957868;26377.60433593536;26690.733092668826;45925.54074459212;46085;46477;46797;46956.7652161737;34796;38178.70927744344;38388.80805595954;38762.80618597002;38954.4156837642;34545.654543455246;37516.43725344067;37735.433968489946;37936.24127517489;39222.803885980386;30317;36572.63427365692;37295.25409491741;37462;37536.624633753316;32692.67307326896;33696.05928796524;34076.82961585275;34224.82887585546;34423.31153376868;24990;25235.495290094463;25329.240122796346;25405;25421.491570168124 05:51:23 INFO - PROCESS | 1661 | |2;jquery.html;317.2544520377114;342.5936530542126;359.8848368522074;364.8631763088842;397.6778809164577;14651.12093274403;15133.394664213432;16115.355385784569;17951.461456156336;21026.474338141554;19449.41651750446;20297.492562685904;20413;20654.380368588965;20716.89641551794;6750.459963202944;6820.829479263033;7057.505974581782;7120.786376408716;7129.7861064168155;6892.862142757167;6923.965380173117;6991;7003.3346832050875;7007.299270073003;4484.944532488104;5686.573506986972;6974.895376569356;6991.755288564901;7996.680132794681;7702.229777022309;7945.880811787828;7988.320992715626;8008.998875140636;8019.879701804478;7721.729739459092;7752.224777522202;7755.302022817917;7756.185600511922;7756.340711039568;28288.776469176748;29411.852940735374;29506.85246573775;29529.40941181186;29532.26169345762;2272.2750606868644;2354.3878591566145;2404.146527982576;2594.8572828494425;3305.1406634275263;16219.91890040554;16656.50030499087;16903.915480422518;17172.39896603613;18018.90990545039;1966.1447270437407;1987.2945104487844;2073.782252863458;2074.1495986645555;2108.852380333362;106508;137939;144114;145131;147759.99999999892;15811.525654230392;17019.40432084896;19682.523810714254;20796.896015519826;20998.950052497315;34487;34606;34630.82684586561;34659.653403466145;34684.30631387358;10590.947045264878;10659.467026648792;10751.354918704747;10762.461876906124;11039;8910.420822646507;8912.598933048028;8926.241269492166;9081.59132839024;9105.590248438835;9152.81694366109;9410.294227932933;9518.381305215138;9576;9617.951910240545;2976.925576860596;3615.94576081359;3725.839013372238;3726.757760745597;3749.756265842766;827.1728271728272;889.62470204554;893.718478679214;904.4980036080038;1001.7495626093477;20420.285290014777;20902.58194836125;20922.16311347529;20961.68557471639;20973.26593569248;839.2069494327924;917.9724608261763;926.420986883198;926.6432423516994;966.5360626899128;8947.552622368985;10773.892261077446;10894.346339219514;10894.891051089546;10910.399928004035;490341;560269;575733;596535;616733;122972;130990;131583.34208328897;132367;140748.5925140756;959.6928982725461;974.7319487141093;998.9650362237287;1021.4433133942019;1022.5143057047843;18543.35098271554;19328.130234139215;19429.222831086598;19429.319973800848;19540.706889396388;48860.75569622129;54098.72950635222;56587.15119273132;57291.14063289137;58724.706376467846;85605;87040.1295987032;87333;87415.12584874325;87943;5872.001759700946;7496.887546686695;7756.922430775846;7995.84008319842;8248.340132789242;8710.782230444289;10222.217779421662;12504.374781260718;12626.494940202288;12689.048321375749;7402.259774022555;7721.729739459092;7750.961245193738;7772.611369431619;8205.507669539607;7464.701411943462;7598.354139898171;7672.654730537028;7840.215978402342;7933.206679332021;7808.219178082373;7897.24976127267;7941;8043.477173983672;8309.459885107448;11292.378919159524;12188.806014917045;12195.878041219474;12232.816507752214;12284.324362160167;11230.775384492428;11338.716532086764;11575.76848463043;11662.183647144619;11774 05:51:23 INFO - PROCESS | 1661 | |3;mootools.html;1501.6996600679868;1533.8159420869495;1721.8794684372099;1825.379371013855;1882.651709433754;8441.91558084419;8984.91015089849;8996.95501522493;9001.684941027066;9037.909620903798;7792.025996750406;7981.28168464838;7996.640151193195;8124.553149576771;8733.388662793606;2148.9570208595856;2185.191479152708;2322.198841399714;2405.530921470314;2405.989484416549;2388.4387169015267;2389.426537630965;2389.5937690592555;2391.4977854650456;2394.988025059872;2332.3819187915215;2384.523095380922;2388.8924998375114;2391.8445301055463;2394.281715485356;2341.836071474989;2352.7882490575844;2370.4784947311564;2371.9288421347387;2375;2330.7669233076726;2341.8243631727687;2352.741198468167;2357.7760112789256;2360.6223004319227;8820.8676869847;8858.86711699325;8948.8657670135;8950.239229665487;8952.149545793205;5237.659552129137;5400.540954018913;5401.648892821954;5410.864728381783;5421.186821976696;3454.5854497460214;3610.801405922673;3652;3652.7077833773233;3671.485991961101;989.4063561862897;993.5578667492969;1012.4735137728413;1078.3529882070773;1080.864891888514;11790.056795456343;11804.350760708157;11827.999999999915;11870;11878.287302761863;4448.466184057934;4733.124371991193;4812.807487700523;4844.975775121102;4855.562999330077;9864.013598640078;9926.602935882484;9934.45360505179;9938.403695778277;9950.701478955643;5398.595105367034;5398.676079435247;5399.352077750695;5403.162509810985;5413.702246376487;4776.450708168605;4916.287138364917;4971.701697898137;4977.452480227172;4983.775730092155;3526.612072672004;3641.726870484671;3748.981255093762;3867.8259478323544;3874.9806250969127;1053.6039747334812;1123.0790751583622;1163.203205804027;1172.5427083437296;1181.6809461445362;856.4133568505594;861.6725644255143;869.5130726792889;929.6235024814825;929.8469897327401;5217.843464696065;5740.655560666373;5792.333881603585;5792.681402522901;6080.14877917092;788.8777239527882;888.1784349476708;927.1238679447857;928.9767755806159;933.7758937855002;3277.885274015398;3652.634736526326;3804.0489877530617;3847.442120892455;3975.7216994810665;10258.12805911491;10336.5348559315;10539.420331881667;10975.670729878117;10985.450727463754;10765.730856728644;10774.569017239222;10777.407242601575;10782.1913356497;10782.406967616856;7910.881336779953;7979.640916158671;8114.715984940617;8229.95885020583;8233.053198882086;5755.97122014404;5814.970925145516;5842.912356314744;5860.736266867916;5965.164876917322;8697.21725044749;9738.659146929824;9959.800803983735;10173;10252;9885.357451765612;10310.948445257725;10428.322159059637;10436.686899392727;10445.373277603368;7548.962255188689;7697.461177717506;7698.0762308523335;7698.538087714755;7710.92289077102;7719.729809456642;7937.920620793718;7940.126586075643;8033.076196237507;8045.477043992122;6992.895106573507;7313.451491138079;7532.13380461232;7574.3183113520045;7581.6209189541405;7354.96322518384;7402.33378995893;7418.666160022704;7476.663550140148;7510.173880872991;6864.073350097872;6956.686949087202;6966.651667416711;6975.162980442379;6975.8604827902145;4463.821447142078;4844.491328410466;4868.367112275523;4940.407151141886;4991.9500804991485;4887.560119589254;4957.256411538298;5058.848234552969;5081.8729531762;5193.37679478465 05:51:23 INFO - PROCESS | 1661 | |4;prototype.html;277.4852648337334;298.546209761163;304.82624903804833;309.31023816888336;313.32634835104534;5202.713850738212;5203.063448579254;5254.92117618236;5300.867478313039;5335.599830012745;3934.626210509997;4494.752788596626;4893.33939918111;4937.790241390855;5081.771320290586;3398.8130652814084;3758.455024021529;3865.7487263327935;3904.941425878614;3975.483187185662;3936.8937618374175;3937;3937.370020796673;3976.727447216892;3983.2830090583684;3171.587693599829;3201.391735570246;3669.6697297243245;3905.6875449964077;3937.5743103499995;3935.1308128638952;3946.309395855714;3953.4069889516513;3960.841566337343;3962.9405558916637;3405.6594340565994;3406.9488957665653;3408.113890388492;3418.7265018798435;3419.675130862564;4445.488768791598;4777.06847164803;4801.447833499157;4990.3013578098735;5124.641275110744;4192.517860446057;4454.198244316021;4460.955390446086;4471.23988921884;4492.001059528511;4503.459584849839;5430.795830460048;5459.45135661819;5459.945400546023;5479.780808767645;3748.925021499582;3760.2479504099288;3761.849526018956;3776.0553315042775;3784.8993808436308;5084.542391184774;5157.381114266274;5343.438938911398;5700.800471983503;5718.199452076713;3778.471014058034;3781.9221521866134;3789.810509474515;3804.9429258561145;3821.5032045833864;3191.5850939377733;3821.6942644588366;3838.347480928249;3854.6338097880657;3855.575886652466;4364.301711726109;4493.415855938707;4499.4150760400935;4509.399163296982;4520.434945631796;4158.586080732505;4525.411696479437;5133.178691409356;5413.783448662089;5476.698781567052;1165.318288801044;1292.8707129286995;1319.808627748971;1322.1802482460744;1326.9137506062075;828.4449418889238;859.334016137486;887.3893882603055;888.626776753765;893.4282059481936;4269.743815371087;4279.850205242864;5045.091883461012;5046.4953504649975;5078.695278283241;4608.916904527433;5094.388673359221;5546.722663866791;5614.382417934024;5617.410171931889;3905.7070719696135;5116.744162791845;5135.435102138762;5170.948290517047;5189.818356357585;2371.573116838951;2773.8072628769823;2789.693133755285;2926.897558585439;2954.084233887502;5350.357957045179;5493.862653433696;5540.889182216414;5644.407337229532;5837.854053648608;5611.971940140274;5620.859478512988;5700.916825803167;5767.36558978512;5798.362180160179;5056.84829455117;5135.175653390695;5377.5160235578205;5837.503812176013;5839.562032847553;5180.4458662401885;5336.813211537577;5575.553955683617;5656.472752356923;5669.22208118011;5522.530584900329;5530;5532.36795145425;5537.4495141360485;5543.390227074938;4424.579664931826;5076.441591425014;5212.791488340424;5796.1305804129715;5813.447722466359;3146.4179126861604;3219.8712051517678;3378.8141652209365;3750.4936833527127;3794.317022935898;5178.352705911761;5342.8931421372135;5603.159526071122;5603.31041100266;5665.20687103814;5495.725213739217;5496.5983674162835;5506.862328441821;5520.889582208253;5548.861278468071;4316.611504964569;4541.931871021871;5302.761375738024;5318.468153184651;5474.616776825584;5119.257707632447;5295.311609490742;5332.493413125747;5346.78612855497;5356.705381204071;5284.9735751320995;5380.461953804745;5444.891102178013;5454.290942177491;5461.787975869779;5299.4700529946695;5305.832716802297;5309.601779866448;5329.600279979095;5336.759845807026 05:51:23 INFO - PROCESS | 1661 | |5;yui.html;701.7017767448834;717.4475653746615;756.6103456719791;766.4935660390523;816.6284340625009;15277;15942.282597283122;15944.36222551098;15950.12274324913;15967.201639918016;14560.199189044624;14771.482998095069;14780.556583302518;14803.555893323191;14867.702645947074;677.2448719677549;698.3609996852899;699.8425354295268;816.5631387207845;829.5105887526358;684.6063513479769;835.9665613375457;852.2202185000717;853.2320911179957;853.6201390381283;858.6994551906845;907.2333877873186;910.39003867409;911.8632205169245;913.2648218184358;709.3934685843581;735.3859527294716;903.9502827344494;908.6637943960744;909.5543183839937;642.5470043619284;644.1079105439001;645.9289478157399;659.475716805141;661.262692098307;14116.294185290695;14134.363953622009;14149.221792801392;14168.079074860101;14177.220252886087;13295.20228786276;13600;13635.318234088356;13652.180869147884;14075.577732668036;11882.643520694393;12245.265284082983;12316.322602256872;12330.075244356709;12763.298018608974;3788.4317352397084;4508.917859713645;4582.404287442644;4609;4692.36653051837;111818;113159;121818;121934;123172.38413807964;10499.895001050045;10772.353658780497;10797.406142662072;10819.404932728776;11211.047060999907;20885.164593416397;21119.366419007456;21187.36437906865;21236.36290911275;21529.78470215309;6561.639109849004;7742.447542040798;7847.097583777939;7902.525848449111;8064.233897779702;4297.742135471882;4390.253656878338;4399.912001759947;4520.208963431386;4549.7497637629585;3325.185329594265;3429.3141371725756;3430.27964127536;3430.9828450857585;3460.048486666137;562.7383266780967;577.2639884147745;603.9456448919531;627.1157667688537;629.7512482569359;536.9597280204;557.9665220086807;566.8129517259378;596.6330706615487;601.2664549249909;8584.01283852365;8647.610857511427;8713.651453941899;8954.86567701485;8959.910400895908;585.5315747402061;598.2671227746064;606.2724730323541;608.3034925010837;638.3138126514016;4159.604837540429;4273.059926816158;4346.6522678185665;4437.29003359461;4514.300283456069;99919.50040249752;119347;151682;162380;165997;87024.5648771752;92391;108113;134382;136846;115348;117015.93794428158;118430;119575.8042419582;122982.38508807578;8250.38122140854;8427.831443370975;8446.788830279293;8594.183552562497;8750.081241469554;37528.81235593805;50948.74525627497;54073.918521630134;55277;57907.13139302998;88143;93427;95268;96164;100055.499167514;9469.763255918382;10133.240006999651;10914.617988370368;10984.505697243803;11347.659570212907;11946.446961894899;12024.737402572604;12026.414320921043;12073.098256735271;12508.812367814307;11748.530058797553;11786.882131178922;11831.11266654987;12274;12344.13591048618;11762.470688818852;11807.586734464252;11827.172097953406;11979.700507487383;12253.448594813077;11622.951146163177;11768.293902365886;11771.058315334581;11781.234219775688;12138.817917731418;7761.728339508316;9280.72157835266;9445.552453131342;9682.806343872942;9880.752981175528;8882.511461869659;9596.95201523988;9638.469884156439;9654.469004204555;9734.805303893741 05:51:23 INFO - PROCESS | 1661 | __end_tp_report 05:51:23 INFO - PROCESS | 1661 | __start_cc_report 05:51:23 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1931 05:51:23 INFO - PROCESS | 1661 | __end_cc_report 05:51:23 INFO - PROCESS | 1661 | __startTimestamp1473771083263__endTimestamp 05:51:23 INFO - PROCESS | 1661 | 05:51:23 INFO - PROCESS | 1661 | ------- Summary: start ------- 05:51:23 INFO - PROCESS | 1661 | Number of tests: 6 05:51:23 INFO - PROCESS | 1661 | 05:51:23 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5734.80 Median:5995.07 stddev:668.48 (11.2%) stddev-sans-first:629.35 05:51:23 INFO - PROCESS | 1661 | Values: 2789.9 2972.2 3326.1 3352.7 3378.4 5505.8 5609.5 5649.3 5706.1 5803.7 5635.9 5660.0 5704.1 5713.7 5769.5 5166.8 5183.3 5224.1 5255.3 5263.8 5298.4 5300.9 5301.0 5301.9 5303.8 5136.7 5289.6 5317.0 5351.7 5357.2 5119.0 5154.4 5231.8 5259.5 5291.4 5063.9 5102.4 5160.9 5175.6 5246.5 5628.7 5634.1 5638.5 5643.4 5714.7 6252.7 6259.6 6268.0 6291.8 6294.0 4353.7 6181.4 6190.4 6301.4 6304.8 5754.8 5782.8 5817.7 5853.3 5856.6 6250.5 6263.4 6273.9 6276.6 6289.1 5995.7 6053.0 6190.9 6198.9 6225.2 6123.7 6161.7 6216.8 6216.8 6297.3 6236.6 6260.0 6260.4 6261.1 6263.0 6196.8 6220.6 6284.9 6292.9 6294.7 4816.6 4869.8 4879.8 4931.3 4941.1 4450.9 4471.7 4495.6 4496.8 4498.8 5717.6 5735.2 5735.9 5738.8 5743.0 6173.5 6196.8 6262.4 6262.5 6270.5 5769.3 5779.6 5792.3 5798.2 5815.9 5435.3 5438.3 5450.3 5458.6 5460.7 6186.7 6204.4 6275.2 6304.8 6322.0 6208.3 6254.2 6284.4 6295.7 6324.5 6284.7 6306.0 6318.5 6320.5 6323.8 6099.1 6139.9 6232.7 6237.7 6248.8 6166.6 6201.8 6217.7 6300.8 6318.1 5419.1 5712.9 6169.4 6179.0 6190.1 5995.8 6140.5 6177.5 6192.0 6211.1 5883.7 5949.0 5995.1 6000.2 6002.4 5376.0 5823.5 6084.5 6097.5 6115.3 6013.0 6014.8 6035.5 6045.4 6093.6 05:51:23 INFO - PROCESS | 1661 | 05:51:23 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:68008.83 Median:39306.84 stddev:149390.00 (380.1%) stddev-sans-first:149722.99 05:51:23 INFO - PROCESS | 1661 | Values: 461.8 483.3 501.8 503.3 528.1 29903.3 30315.2 31450.8 31462.8 31490.2 31791.2 41162.4 41815.8 41944.6 44018.9 8250.7 9840.1 9848.0 9861.1 9866.3 9712.9 9824.3 9840.8 9842.5 9843.0 9802.3 9808.8 9852.2 9870.6 9911.1 9754.9 9807.2 9819.7 9828.6 9856.0 9857.5 9883.5 9894.2 9908.9 9921.7 39024.6 39390.9 39414.8 39465.8 39468.6 3343.9 3418.2 3572.5 3897.8 4188.4 29492.4 30565.8 30659.2 30722.1 30755.8 3169.9 3202.7 3236.3 3270.0 3274.0 490663.0 492373.0 493285.0 494116.5 494600.0 53677.7 53730.2 53853.4 53927.0 54035.0 59859.1 60018.0 60035.7 60040.4 60224.7 16077.3 16329.1 16340.4 16342.5 16361.6 12678.2 12727.4 12765.4 12768.3 12787.7 11263.7 14838.9 15815.2 15835.7 16062.5 5087.8 5116.0 6084.4 6119.6 6144.9 858.5 920.5 941.6 963.3 1035.5 27653.9 28987.6 29024.6 29780.0 30064.7 829.8 1006.2 1026.2 1033.6 1038.1 13017.0 14887.5 14939.9 15333.2 15365.2 636444.0 636584.0 637409.0 642237.0 642463.0 56432.0 56566.0 56660.4 56669.0 56825.7 1241.7 1271.9 1348.7 1374.0 1408.1 28948.8 30242.5 30401.2 30882.5 30906.8 44525.3 44904.8 44958.0 44968.0 45002.1 532286.0 533290.0 533619.0 534217.0 537448.0 25440.9 25870.9 26151.8 26377.6 26690.7 45925.5 46085.0 46477.0 46797.0 46956.8 34796.0 38178.7 38388.8 38762.8 38954.4 34545.7 37516.4 37735.4 37936.2 39222.8 30317.0 36572.6 37295.3 37462.0 37536.6 32692.7 33696.1 34076.8 34224.8 34423.3 24990.0 25235.5 25329.2 25405.0 25421.5 05:51:23 INFO - PROCESS | 1661 | 05:51:23 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:36337.92 Median:1990.40 stddev:95899.27 (4818.1%) stddev-sans-first:96130.15 05:51:23 INFO - PROCESS | 1661 | Values: 317.3 342.6 359.9 364.9 397.7 14651.1 15133.4 16115.4 17951.5 21026.5 19449.4 20297.5 20413.0 20654.4 20716.9 6750.5 6820.8 7057.5 7120.8 7129.8 6892.9 6924.0 6991.0 7003.3 7007.3 4484.9 5686.6 6974.9 6991.8 7996.7 7702.2 7945.9 7988.3 8009.0 8019.9 7721.7 7752.2 7755.3 7756.2 7756.3 28288.8 29411.9 29506.9 29529.4 29532.3 2272.3 2354.4 2404.1 2594.9 3305.1 16219.9 16656.5 16903.9 17172.4 18018.9 1966.1 1987.3 2073.8 2074.1 2108.9 106508.0 137939.0 144114.0 145131.0 147760.0 15811.5 17019.4 19682.5 20796.9 20999.0 34487.0 34606.0 34630.8 34659.7 34684.3 10590.9 10659.5 10751.4 10762.5 11039.0 8910.4 8912.6 8926.2 9081.6 9105.6 9152.8 9410.3 9518.4 9576.0 9618.0 2976.9 3615.9 3725.8 3726.8 3749.8 827.2 889.6 893.7 904.5 1001.7 20420.3 20902.6 20922.2 20961.7 20973.3 839.2 918.0 926.4 926.6 966.5 8947.6 10773.9 10894.3 10894.9 10910.4 490341.0 560269.0 575733.0 596535.0 616733.0 122972.0 130990.0 131583.3 132367.0 140748.6 959.7 974.7 999.0 1021.4 1022.5 18543.4 19328.1 19429.2 19429.3 19540.7 48860.8 54098.7 56587.2 57291.1 58724.7 85605.0 87040.1 87333.0 87415.1 87943.0 5872.0 7496.9 7756.9 7995.8 8248.3 8710.8 10222.2 12504.4 12626.5 12689.0 7402.3 7721.7 7751.0 7772.6 8205.5 7464.7 7598.4 7672.7 7840.2 7933.2 7808.2 7897.2 7941.0 8043.5 8309.5 11292.4 12188.8 12195.9 12232.8 12284.3 11230.8 11338.7 11575.8 11662.2 11774.0 05:51:23 INFO - PROCESS | 1661 | 05:51:23 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5693.45 Median:5025.40 stddev:3208.50 (63.8%) stddev-sans-first:3202.03 05:51:23 INFO - PROCESS | 1661 | Values: 1501.7 1533.8 1721.9 1825.4 1882.7 8441.9 8984.9 8997.0 9001.7 9037.9 7792.0 7981.3 7996.6 8124.6 8733.4 2149.0 2185.2 2322.2 2405.5 2406.0 2388.4 2389.4 2389.6 2391.5 2395.0 2332.4 2384.5 2388.9 2391.8 2394.3 2341.8 2352.8 2370.5 2371.9 2375.0 2330.8 2341.8 2352.7 2357.8 2360.6 8820.9 8858.9 8948.9 8950.2 8952.1 5237.7 5400.5 5401.6 5410.9 5421.2 3454.6 3610.8 3652.0 3652.7 3671.5 989.4 993.6 1012.5 1078.4 1080.9 11790.1 11804.4 11828.0 11870.0 11878.3 4448.5 4733.1 4812.8 4845.0 4855.6 9864.0 9926.6 9934.5 9938.4 9950.7 5398.6 5398.7 5399.4 5403.2 5413.7 4776.5 4916.3 4971.7 4977.5 4983.8 3526.6 3641.7 3749.0 3867.8 3875.0 1053.6 1123.1 1163.2 1172.5 1181.7 856.4 861.7 869.5 929.6 929.8 5217.8 5740.7 5792.3 5792.7 6080.1 788.9 888.2 927.1 929.0 933.8 3277.9 3652.6 3804.0 3847.4 3975.7 10258.1 10336.5 10539.4 10975.7 10985.5 10765.7 10774.6 10777.4 10782.2 10782.4 7910.9 7979.6 8114.7 8230.0 8233.1 5756.0 5815.0 5842.9 5860.7 5965.2 8697.2 9738.7 9959.8 10173.0 10252.0 9885.4 10310.9 10428.3 10436.7 10445.4 7549.0 7697.5 7698.1 7698.5 7710.9 7719.7 7937.9 7940.1 8033.1 8045.5 6992.9 7313.5 7532.1 7574.3 7581.6 7355.0 7402.3 7418.7 7476.7 7510.2 6864.1 6956.7 6966.7 6975.2 6975.9 4463.8 4844.5 4868.4 4940.4 4992.0 4887.6 4957.3 5058.8 5081.9 5193.4 05:51:23 INFO - PROCESS | 1661 | 05:51:23 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:4384.11 Median:5066.64 stddev:1352.78 (26.7%) stddev-sans-first:1320.99 05:51:23 INFO - PROCESS | 1661 | Values: 277.5 298.5 304.8 309.3 313.3 5202.7 5203.1 5254.9 5300.9 5335.6 3934.6 4494.8 4893.3 4937.8 5081.8 3398.8 3758.5 3865.7 3904.9 3975.5 3936.9 3937.0 3937.4 3976.7 3983.3 3171.6 3201.4 3669.7 3905.7 3937.6 3935.1 3946.3 3953.4 3960.8 3962.9 3405.7 3406.9 3408.1 3418.7 3419.7 4445.5 4777.1 4801.4 4990.3 5124.6 4192.5 4454.2 4461.0 4471.2 4492.0 4503.5 5430.8 5459.5 5459.9 5479.8 3748.9 3760.2 3761.8 3776.1 3784.9 5084.5 5157.4 5343.4 5700.8 5718.2 3778.5 3781.9 3789.8 3804.9 3821.5 3191.6 3821.7 3838.3 3854.6 3855.6 4364.3 4493.4 4499.4 4509.4 4520.4 4158.6 4525.4 5133.2 5413.8 5476.7 1165.3 1292.9 1319.8 1322.2 1326.9 828.4 859.3 887.4 888.6 893.4 4269.7 4279.9 5045.1 5046.5 5078.7 4608.9 5094.4 5546.7 5614.4 5617.4 3905.7 5116.7 5135.4 5170.9 5189.8 2371.6 2773.8 2789.7 2926.9 2954.1 5350.4 5493.9 5540.9 5644.4 5837.9 5612.0 5620.9 5700.9 5767.4 5798.4 5056.8 5135.2 5377.5 5837.5 5839.6 5180.4 5336.8 5575.6 5656.5 5669.2 5522.5 5530.0 5532.4 5537.4 5543.4 4424.6 5076.4 5212.8 5796.1 5813.4 3146.4 3219.9 3378.8 3750.5 3794.3 5178.4 5342.9 5603.2 5603.3 5665.2 5495.7 5496.6 5506.9 5520.9 5548.9 4316.6 4541.9 5302.8 5318.5 5474.6 5119.3 5295.3 5332.5 5346.8 5356.7 5285.0 5380.5 5444.9 5454.3 5461.8 5299.5 5305.8 5309.6 5329.6 5336.8 05:51:23 INFO - PROCESS | 1661 | 05:51:23 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:23951.39 Median:4473.10 stddev:39185.89 (876.0%) stddev-sans-first:39256.94 05:51:23 INFO - PROCESS | 1661 | Values: 701.7 717.4 756.6 766.5 816.6 15277.0 15942.3 15944.4 15950.1 15967.2 14560.2 14771.5 14780.6 14803.6 14867.7 677.2 698.4 699.8 816.6 829.5 684.6 836.0 852.2 853.2 853.6 858.7 907.2 910.4 911.9 913.3 709.4 735.4 904.0 908.7 909.6 642.5 644.1 645.9 659.5 661.3 14116.3 14134.4 14149.2 14168.1 14177.2 13295.2 13600.0 13635.3 13652.2 14075.6 11882.6 12245.3 12316.3 12330.1 12763.3 3788.4 4508.9 4582.4 4609.0 4692.4 111818.0 113159.0 121818.0 121934.0 123172.4 10499.9 10772.4 10797.4 10819.4 11211.0 20885.2 21119.4 21187.4 21236.4 21529.8 6561.6 7742.4 7847.1 7902.5 8064.2 4297.7 4390.3 4399.9 4520.2 4549.7 3325.2 3429.3 3430.3 3431.0 3460.0 562.7 577.3 603.9 627.1 629.8 537.0 558.0 566.8 596.6 601.3 8584.0 8647.6 8713.7 8954.9 8959.9 585.5 598.3 606.3 608.3 638.3 4159.6 4273.1 4346.7 4437.3 4514.3 99919.5 119347.0 151682.0 162380.0 165997.0 87024.6 92391.0 108113.0 134382.0 136846.0 115348.0 117015.9 118430.0 119575.8 122982.4 8250.4 8427.8 8446.8 8594.2 8750.1 37528.8 50948.7 54073.9 55277.0 57907.1 88143.0 93427.0 95268.0 96164.0 100055.5 9469.8 10133.2 10914.6 10984.5 11347.7 11946.4 12024.7 12026.4 12073.1 12508.8 11748.5 11786.9 11831.1 12274.0 12344.1 11762.5 11807.6 11827.2 11979.7 12253.4 11623.0 11768.3 11771.1 11781.2 12138.8 7761.7 9280.7 9445.6 9682.8 9880.8 8882.5 9597.0 9638.5 9654.5 9734.8 05:51:23 INFO - PROCESS | 1661 | -------- Summary: end -------- 05:51:23 INFO - PROCESS | 1661 | 05:51:23 INFO - TEST-INFO | 1661: exit 0 05:51:23 INFO - TEST-OK | dromaeo_css | took 1117620ms 05:51:23 INFO - TEST-START | kraken 05:51:23 INFO - operating with platform_type : mac_ 05:51:23 INFO - Initialising browser for kraken test... 05:51:23 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmp6hvr6b/profile http://localhost:49281/getInfo.html) 05:51:26 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 05:51:26 INFO - PROCESS | 1666 | colorDepth:24 05:51:26 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 05:51:26 INFO - PROCESS | 1666 | __metrics 05:51:26 INFO - PROCESS | 1666 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:51:26 INFO - PROCESS | 1666 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:51:26 INFO - TEST-INFO | 1666: exit 0 05:51:26 INFO - Browser initialized. 05:51:26 INFO - Running cycle 1/1 for kraken test... 05:51:26 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmp6hvr6b/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:51:38 INFO - PROCESS | 1668 | RSS: Main: 229498880 05:51:38 INFO - PROCESS | 1668 | 05:51:41 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection) 05:51:42 INFO - PROCESS | 1668 | RSS: Main: 292945920 05:51:42 INFO - PROCESS | 1668 | 05:51:45 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-dft) 05:51:45 INFO - PROCESS | 1668 | RSS: Main: 293748736 05:51:45 INFO - PROCESS | 1668 | 05:51:49 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-fft) 05:51:49 INFO - PROCESS | 1668 | RSS: Main: 382087168 05:51:49 INFO - PROCESS | 1668 | 05:51:51 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator) 05:51:52 INFO - PROCESS | 1668 | RSS: Main: 284999680 05:51:52 INFO - PROCESS | 1668 | 05:51:54 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:51:55 INFO - PROCESS | 1668 | RSS: Main: 287657984 05:51:55 INFO - PROCESS | 1668 | 05:51:57 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom) 05:51:58 INFO - PROCESS | 1668 | RSS: Main: 325681152 05:51:58 INFO - PROCESS | 1668 | 05:52:01 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate) 05:52:01 INFO - PROCESS | 1668 | RSS: Main: 326270976 05:52:01 INFO - PROCESS | 1668 | 05:52:04 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial) 05:52:05 INFO - PROCESS | 1668 | RSS: Main: 325464064 05:52:05 INFO - PROCESS | 1668 | 05:52:06 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:52:07 INFO - PROCESS | 1668 | RSS: Main: 299126784 05:52:07 INFO - PROCESS | 1668 | 05:52:09 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:52:09 INFO - PROCESS | 1668 | RSS: Main: 302948352 05:52:09 INFO - PROCESS | 1668 | 05:52:12 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:52:12 INFO - PROCESS | 1668 | RSS: Main: 306503680 05:52:12 INFO - PROCESS | 1668 | 05:52:15 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:52:16 INFO - PROCESS | 1668 | RSS: Main: 320163840 05:52:16 INFO - PROCESS | 1668 | 05:52:19 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:52:20 INFO - PROCESS | 1668 | RSS: Main: 316411904 05:52:20 INFO - PROCESS | 1668 | 05:52:21 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49281/tests/kraken/driver.html?testName=ai-astar) 05:52:22 INFO - PROCESS | 1668 | RSS: Main: 360296448 05:52:22 INFO - PROCESS | 1668 | 05:52:22 INFO - PROCESS | 1668 | __start_tp_report 05:52:22 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 05:52:22 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 05:52:22 INFO - PROCESS | 1668 | |i|pagename|runs| 05:52:22 INFO - PROCESS | 1668 | |0;ai-astar;107;163;91;157;169;168;114;116;113;114 05:52:22 INFO - PROCESS | 1668 | |1;audio-beat-detection;144;108;106;148;105;97;126;139;151;108 05:52:22 INFO - PROCESS | 1668 | |2;audio-dft;186;184;185;184;186;182;182;179;181;182 05:52:22 INFO - PROCESS | 1668 | |3;audio-fft;78;58;61;103;90;83;75;57;83;59 05:52:22 INFO - PROCESS | 1668 | |4;audio-oscillator;88;70;95;73;68;68;92;66;68;91 05:52:22 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;87;86;87;85;87;85;88;86;89;84 05:52:22 INFO - PROCESS | 1668 | |6;imaging-darkroom;93;95;93;94;93;94;91;93;94;91 05:52:22 INFO - PROCESS | 1668 | |7;imaging-desaturate;85;87;87;86;85;85;84;95;86;86 05:52:22 INFO - PROCESS | 1668 | |8;json-parse-financial;55;54;53;53;54;55;54;54;53;54 05:52:22 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;65;73;72;75;72;69;69;71;72;73 05:52:22 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;88;88;87;84;85;87;83;96;106;85 05:52:22 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;140;142;132;124;151;151;150;138;179;140 05:52:22 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;169;170;169;169;169;171;170;167;168;188 05:52:22 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;69;69;70;69;69;68;70;69;71;67 05:52:22 INFO - PROCESS | 1668 | __end_tp_report 05:52:22 INFO - PROCESS | 1668 | __start_cc_report 05:52:22 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,2016 05:52:22 INFO - PROCESS | 1668 | __end_cc_report 05:52:22 INFO - PROCESS | 1668 | __startTimestamp1473771142010__endTimestamp 05:52:22 INFO - PROCESS | 1668 | 05:52:22 INFO - PROCESS | 1668 | ------- Summary: start ------- 05:52:22 INFO - PROCESS | 1668 | Number of tests: 14 05:52:22 INFO - PROCESS | 1668 | 05:52:22 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:131.20 Median:160.00 stddev:29.47 (18.4%) stddev-sans-first:29.93 05:52:22 INFO - PROCESS | 1668 | Values: 107.0 163.0 91.0 157.0 169.0 168.0 114.0 116.0 113.0 114.0 05:52:22 INFO - PROCESS | 1668 | 05:52:22 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:123.20 Median:141.50 stddev:20.69 (14.6%) stddev-sans-first:20.53 05:52:22 INFO - PROCESS | 1668 | Values: 144.0 108.0 106.0 148.0 105.0 97.0 126.0 139.0 151.0 108.0 05:52:22 INFO - PROCESS | 1668 | 05:52:22 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:183.10 Median:184.00 stddev:2.28 (1.2%) stddev-sans-first:2.17 05:52:22 INFO - PROCESS | 1668 | Values: 186.0 184.0 185.0 184.0 186.0 182.0 182.0 179.0 181.0 182.0 05:52:22 INFO - PROCESS | 1668 | 05:52:22 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:74.70 Median:76.50 stddev:15.67 (20.5%) stddev-sans-first:16.58 05:52:22 INFO - PROCESS | 1668 | Values: 78.0 58.0 61.0 103.0 90.0 83.0 75.0 57.0 83.0 59.0 05:52:22 INFO - PROCESS | 1668 | 05:52:22 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:80.50 stddev:11.96 (14.9%) stddev-sans-first:12.11 05:52:22 INFO - PROCESS | 1668 | Values: 88.0 70.0 95.0 73.0 68.0 68.0 92.0 66.0 68.0 91.0 05:52:22 INFO - PROCESS | 1668 | 05:52:22 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:86.40 Median:87.00 stddev:1.51 (1.7%) stddev-sans-first:1.58 05:52:22 INFO - PROCESS | 1668 | Values: 87.0 86.0 87.0 85.0 87.0 85.0 88.0 86.0 89.0 84.0 05:52:22 INFO - PROCESS | 1668 | 05:52:22 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:93.10 Median:93.50 stddev:1.29 (1.4%) stddev-sans-first:1.36 05:52:22 INFO - PROCESS | 1668 | Values: 93.0 95.0 93.0 94.0 93.0 94.0 91.0 93.0 94.0 91.0 05:52:22 INFO - PROCESS | 1668 | 05:52:22 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:86.60 Median:86.00 stddev:3.10 (3.6%) stddev-sans-first:3.23 05:52:22 INFO - PROCESS | 1668 | Values: 85.0 87.0 87.0 86.0 85.0 85.0 84.0 95.0 86.0 86.0 05:52:22 INFO - PROCESS | 1668 | 05:52:22 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.00 stddev:0.74 (1.4%) stddev-sans-first:0.67 05:52:22 INFO - PROCESS | 1668 | Values: 55.0 54.0 53.0 53.0 54.0 55.0 54.0 54.0 53.0 54.0 05:52:22 INFO - PROCESS | 1668 | 05:52:22 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:72.00 stddev:2.81 (3.9%) stddev-sans-first:1.92 05:52:22 INFO - PROCESS | 1668 | Values: 65.0 73.0 72.0 75.0 72.0 69.0 69.0 71.0 72.0 73.0 05:52:22 INFO - PROCESS | 1668 | 05:52:22 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:87.00 stddev:7.00 (8.0%) stddev-sans-first:7.42 05:52:22 INFO - PROCESS | 1668 | Values: 88.0 88.0 87.0 84.0 85.0 87.0 83.0 96.0 106.0 85.0 05:52:22 INFO - PROCESS | 1668 | 05:52:22 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:144.70 Median:146.00 stddev:14.80 (10.1%) stddev-sans-first:15.59 05:52:22 INFO - PROCESS | 1668 | Values: 140.0 142.0 132.0 124.0 151.0 151.0 150.0 138.0 179.0 140.0 05:52:22 INFO - PROCESS | 1668 | 05:52:22 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.00 Median:169.50 stddev:6.07 (3.6%) stddev-sans-first:6.40 05:52:22 INFO - PROCESS | 1668 | Values: 169.0 170.0 169.0 169.0 169.0 171.0 170.0 167.0 168.0 188.0 05:52:22 INFO - PROCESS | 1668 | 05:52:22 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:69.00 stddev:1.10 (1.6%) stddev-sans-first:1.17 05:52:22 INFO - PROCESS | 1668 | Values: 69.0 69.0 70.0 69.0 69.0 68.0 70.0 69.0 71.0 67.0 05:52:22 INFO - PROCESS | 1668 | -------- Summary: end -------- 05:52:22 INFO - PROCESS | 1668 | 05:52:22 INFO - TEST-INFO | 1668: exit 0 05:52:22 INFO - TEST-OK | kraken | took 59073ms 05:52:22 INFO - SUITE-END | took 1176s 05:52:23 INFO - Completed test suite (00:19:36) 05:52:23 INFO - javascript benchmark 05:52:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2789.8744556494958, 2972.2272209225607, 3326.068700763787, 3352.742721479446, 3378.375000624883, 5505.779768809243, 5609.467100625439, 5649.293838270216, 5706.144078388239, 5803.709814509279, 5635.887282254353, 5660.0, 5704.11586204138, 5713.657180569169, 5769.509591684701, 5166.793328266865, 5183.2743415921805, 5224.085784987631, 5255.343082114736, 5263.815766448156, 5298.443663415324, 5300.92048619271, 5300.966311569246, 5301.867453313659, 5303.761330740107, 5136.666116702421, 5289.576833853302, 5317.0, 5351.678899266057, 5357.1696387060065, 5118.976204759026, 5154.407243167008, 5231.764570594332, 5259.526642602146, 5291.391489978663, 5063.87340316495, 5102.413222479426, 5160.92258616121, 5175.637705360588, 5246.501582349671, 5628.662280263156, 5634.0703783875615, 5638.4925356718095, 5643.351014633328, 5714.7428365723645, 6252.6561039142825, 6259.593126446812, 6267.9686601567155, 6291.842703932392, 6294.0, 4353.717008394476, 6181.3509581493745, 6190.380961903818, 6301.4013668701855, 6304.779332723333, 5754.79858204965, 5782.843431313754, 5817.680027598522, 5853.268341457318, 5856.648601083949, 6250.499960003189, 6263.404976527223, 6273.8745225095245, 6276.560640755103, 6289.119523266746, 5995.730192141277, 6053.031514957674, 6190.938090619036, 6198.876022479615, 6225.159603453566, 6123.740835073669, 6161.7227224775, 6216.813495595139, 6216.844578885564, 6297.27581328144, 6236.563440559117, 6260.029695397221, 6260.373962603703, 6261.060840873905, 6262.968685156545, 6196.783112591038, 6220.595661282002, 6284.905726414107, 6292.937070629235, 6294.65379404128, 4816.590589799906, 4869.780859861315, 4879.829205977774, 4931.260310953386, 4941.061198372297, 4450.933236001462, 4471.731696098244, 4495.64034877209, 4496.797644106023, 4498.797554110073, 5717.628354156966, 5735.1684005818925, 5735.8566035849435, 5738.77044918207, 5742.971285143548, 6173.475254603409, 6196.845078873064, 6262.436380725665, 6262.46769024638, 6270.498360131179, 5769.336526299446, 5779.624324418899, 5792.333881603585, 5798.217240672455, 5815.854603634858, 5435.347758268954, 5438.347398312228, 5450.29146210998, 5458.563314934796, 5460.6996615186545, 6186.690665466798, 6204.441600255998, 6275.246970363585, 6304.842378940563, 6322.0, 6208.25500939872, 6254.155688981929, 6284.371562843679, 6295.716692748746, 6324.4940404766585, 6284.654344010941, 6305.96847015762, 6318.526110541636, 6320.494360451061, 6323.7786677466065, 6099.054646529795, 6139.8948189324565, 6232.688365581794, 6237.656928868956, 6248.750049997949, 6166.599171053867, 6201.844953876189, 6217.658028808459, 6300.810975670737, 6318.05229215603, 5419.078756611464, 5712.857178570569, 6169.413905678954, 6179.0422484514975, 6190.133381326528, 5995.760169593167, 6140.478059365004, 6177.536684748572, 6191.9690401547705, 6211.130441738251, 5883.705814709162, 5948.970255148696, 5995.070764031582, 6000.249968753906, 6002.429769171922, 5376.005438993799, 5823.505002074871, 6084.513238940963, 6097.548858883019, 6115.331611965906, 6013.0, 6014.849628759316, 6035.517158627212, 6045.365236650088, 6093.6039157454625], "value": 5689.937137340915, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [461.7737308718729, 483.3136945537337, 501.76667849449996, 503.3255437713463, 528.1127705454837, 29903.252418689517, 30315.242118947037, 31450.842745786238, 31462.842685786538, 31490.212744681365, 31791.205219869516, 41162.38256426156, 41815.79092104543, 41944.58055419445, 44018.89952751183, 8250.711225107123, 9840.114389704926, 9847.950760246189, 9861.11249987501, 9866.260030497706, 9712.902870971306, 9824.312298139133, 9840.753981150458, 9842.507874606277, 9843.015698430136, 9802.313838031341, 9808.754781130458, 9852.162566181883, 9870.618113464085, 9911.108000279974, 9754.853677194847, 9807.185670650952, 9819.705408837746, 9828.557714902847, 9856.0, 9857.457839818808, 9883.505824708736, 9894.208463322917, 9908.851367229498, 9921.702348929544, 39024.60975390238, 39390.87918570596, 39414.802925985474, 39465.81602551928, 39468.60531394707, 3343.8996830095134, 3418.179636887154, 3572.481990111445, 3897.7661340319387, 4188.350805625102, 29492.410151796845, 30565.847170764227, 30659.233519161975, 30722.078337649906, 30755.846220768977, 3169.889053883103, 3202.6797320267788, 3236.304194598149, 3269.9509507357407, 3273.9836300818583, 490663.0, 492373.0, 493285.0, 494116.5294173542, 494600.0, 53677.7316113417, 53730.194047089324, 53853.38439846811, 53927.0, 54035.0, 59859.10211346833, 60018.0, 60035.69982150061, 60040.39959600348, 60224.69887650534, 16077.276522556513, 16329.101899395648, 16340.428085016967, 16342.509724708278, 16361.590960226089, 12678.175918565265, 12727.363631818373, 12765.425555849823, 12768.297743624003, 12787.680307992188, 11263.718407039725, 14838.851611483962, 15815.23523384107, 15835.68328633421, 16062.518124456285, 5087.771050302746, 5115.974420127875, 6084.421979911901, 6119.602225855305, 6144.907826382608, 858.4891989266376, 920.4983284110176, 941.5621735892843, 963.2582911158369, 1035.502958579886, 27653.861730691217, 28987.56518652222, 29024.56463153012, 29780.0, 30064.69935300663, 829.8298848735994, 1006.1849901579744, 1026.1893104447552, 1033.617561502234, 1038.08129805123, 13017.0, 14887.47893823711, 14939.925300373647, 15333.156676382907, 15365.154916479478, 636444.0, 636584.0, 637409.0, 642237.0, 642463.0, 56432.0, 56566.0, 56660.43339566634, 56669.0, 56825.715871420376, 1241.6771639373828, 1271.8918891894293, 1348.6965432777547, 1373.9656508587366, 1408.0917808013774, 28948.81563028023, 30242.54636180503, 30401.23996900095, 30882.53676194814, 30906.845465773426, 44525.33212001758, 44904.77547612241, 44958.0, 44968.0, 45002.09995800131, 532286.0, 533290.0, 533619.0, 534217.0, 537448.0, 25440.929451287706, 25870.87064564665, 26151.823167957868, 26377.60433593536, 26690.733092668826, 45925.54074459212, 46085.0, 46477.0, 46797.0, 46956.7652161737, 34796.0, 38178.70927744344, 38388.80805595954, 38762.80618597002, 38954.4156837642, 34545.654543455246, 37516.43725344067, 37735.433968489946, 37936.24127517489, 39222.803885980386, 30317.0, 36572.63427365692, 37295.25409491741, 37462.0, 37536.624633753316, 32692.67307326896, 33696.05928796524, 34076.82961585275, 34224.82887585546, 34423.31153376868, 24990.0, 25235.495290094463, 25329.240122796346, 25405.0, 25421.491570168124], "value": 19209.127535168805, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.2544520377114, 342.5936530542126, 359.8848368522074, 364.8631763088842, 397.6778809164577, 14651.12093274403, 15133.394664213432, 16115.355385784569, 17951.461456156336, 21026.474338141554, 19449.41651750446, 20297.492562685904, 20413.0, 20654.380368588965, 20716.89641551794, 6750.459963202944, 6820.829479263033, 7057.505974581782, 7120.786376408716, 7129.7861064168155, 6892.862142757167, 6923.965380173117, 6991.0, 7003.3346832050875, 7007.299270073003, 4484.944532488104, 5686.573506986972, 6974.895376569356, 6991.755288564901, 7996.680132794681, 7702.229777022309, 7945.880811787828, 7988.320992715626, 8008.998875140636, 8019.879701804478, 7721.729739459092, 7752.224777522202, 7755.302022817917, 7756.185600511922, 7756.340711039568, 28288.776469176748, 29411.852940735374, 29506.85246573775, 29529.40941181186, 29532.26169345762, 2272.2750606868644, 2354.3878591566145, 2404.146527982576, 2594.8572828494425, 3305.1406634275263, 16219.91890040554, 16656.50030499087, 16903.915480422518, 17172.39896603613, 18018.90990545039, 1966.1447270437407, 1987.2945104487844, 2073.782252863458, 2074.1495986645555, 2108.852380333362, 106508.0, 137939.0, 144114.0, 145131.0, 147759.99999999892, 15811.525654230392, 17019.40432084896, 19682.523810714254, 20796.896015519826, 20998.950052497315, 34487.0, 34606.0, 34630.82684586561, 34659.653403466145, 34684.30631387358, 10590.947045264878, 10659.467026648792, 10751.354918704747, 10762.461876906124, 11039.0, 8910.420822646507, 8912.598933048028, 8926.241269492166, 9081.59132839024, 9105.590248438835, 9152.81694366109, 9410.294227932933, 9518.381305215138, 9576.0, 9617.951910240545, 2976.925576860596, 3615.94576081359, 3725.839013372238, 3726.757760745597, 3749.756265842766, 827.1728271728272, 889.62470204554, 893.718478679214, 904.4980036080038, 1001.7495626093477, 20420.285290014777, 20902.58194836125, 20922.16311347529, 20961.68557471639, 20973.26593569248, 839.2069494327924, 917.9724608261763, 926.420986883198, 926.6432423516994, 966.5360626899128, 8947.552622368985, 10773.892261077446, 10894.346339219514, 10894.891051089546, 10910.399928004035, 490341.0, 560269.0, 575733.0, 596535.0, 616733.0, 122972.0, 130990.0, 131583.34208328897, 132367.0, 140748.5925140756, 959.6928982725461, 974.7319487141093, 998.9650362237287, 1021.4433133942019, 1022.5143057047843, 18543.35098271554, 19328.130234139215, 19429.222831086598, 19429.319973800848, 19540.706889396388, 48860.75569622129, 54098.72950635222, 56587.15119273132, 57291.14063289137, 58724.706376467846, 85605.0, 87040.1295987032, 87333.0, 87415.12584874325, 87943.0, 5872.001759700946, 7496.887546686695, 7756.922430775846, 7995.84008319842, 8248.340132789242, 8710.782230444289, 10222.217779421662, 12504.374781260718, 12626.494940202288, 12689.048321375749, 7402.259774022555, 7721.729739459092, 7750.961245193738, 7772.611369431619, 8205.507669539607, 7464.701411943462, 7598.354139898171, 7672.654730537028, 7840.215978402342, 7933.206679332021, 7808.219178082373, 7897.24976127267, 7941.0, 8043.477173983672, 8309.459885107448, 11292.378919159524, 12188.806014917045, 12195.878041219474, 12232.816507752214, 12284.324362160167, 11230.775384492428, 11338.716532086764, 11575.76848463043, 11662.183647144619, 11774.0], "value": 10729.742143184589, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1501.6996600679868, 1533.8159420869495, 1721.8794684372099, 1825.379371013855, 1882.651709433754, 8441.91558084419, 8984.91015089849, 8996.95501522493, 9001.684941027066, 9037.909620903798, 7792.025996750406, 7981.28168464838, 7996.640151193195, 8124.553149576771, 8733.388662793606, 2148.9570208595856, 2185.191479152708, 2322.198841399714, 2405.530921470314, 2405.989484416549, 2388.4387169015267, 2389.426537630965, 2389.5937690592555, 2391.4977854650456, 2394.988025059872, 2332.3819187915215, 2384.523095380922, 2388.8924998375114, 2391.8445301055463, 2394.281715485356, 2341.836071474989, 2352.7882490575844, 2370.4784947311564, 2371.9288421347387, 2375.0, 2330.7669233076726, 2341.8243631727687, 2352.741198468167, 2357.7760112789256, 2360.6223004319227, 8820.8676869847, 8858.86711699325, 8948.8657670135, 8950.239229665487, 8952.149545793205, 5237.659552129137, 5400.540954018913, 5401.648892821954, 5410.864728381783, 5421.186821976696, 3454.5854497460214, 3610.801405922673, 3652.0, 3652.7077833773233, 3671.485991961101, 989.4063561862897, 993.5578667492969, 1012.4735137728413, 1078.3529882070773, 1080.864891888514, 11790.056795456343, 11804.350760708157, 11827.999999999915, 11870.0, 11878.287302761863, 4448.466184057934, 4733.124371991193, 4812.807487700523, 4844.975775121102, 4855.562999330077, 9864.013598640078, 9926.602935882484, 9934.45360505179, 9938.403695778277, 9950.701478955643, 5398.595105367034, 5398.676079435247, 5399.352077750695, 5403.162509810985, 5413.702246376487, 4776.450708168605, 4916.287138364917, 4971.701697898137, 4977.452480227172, 4983.775730092155, 3526.612072672004, 3641.726870484671, 3748.981255093762, 3867.8259478323544, 3874.9806250969127, 1053.6039747334812, 1123.0790751583622, 1163.203205804027, 1172.5427083437296, 1181.6809461445362, 856.4133568505594, 861.6725644255143, 869.5130726792889, 929.6235024814825, 929.8469897327401, 5217.843464696065, 5740.655560666373, 5792.333881603585, 5792.681402522901, 6080.14877917092, 788.8777239527882, 888.1784349476708, 927.1238679447857, 928.9767755806159, 933.7758937855002, 3277.885274015398, 3652.634736526326, 3804.0489877530617, 3847.442120892455, 3975.7216994810665, 10258.12805911491, 10336.5348559315, 10539.420331881667, 10975.670729878117, 10985.450727463754, 10765.730856728644, 10774.569017239222, 10777.407242601575, 10782.1913356497, 10782.406967616856, 7910.881336779953, 7979.640916158671, 8114.715984940617, 8229.95885020583, 8233.053198882086, 5755.97122014404, 5814.970925145516, 5842.912356314744, 5860.736266867916, 5965.164876917322, 8697.21725044749, 9738.659146929824, 9959.800803983735, 10173.0, 10252.0, 9885.357451765612, 10310.948445257725, 10428.322159059637, 10436.686899392727, 10445.373277603368, 7548.962255188689, 7697.461177717506, 7698.0762308523335, 7698.538087714755, 7710.92289077102, 7719.729809456642, 7937.920620793718, 7940.126586075643, 8033.076196237507, 8045.477043992122, 6992.895106573507, 7313.451491138079, 7532.13380461232, 7574.3183113520045, 7581.6209189541405, 7354.96322518384, 7402.33378995893, 7418.666160022704, 7476.663550140148, 7510.173880872991, 6864.073350097872, 6956.686949087202, 6966.651667416711, 6975.162980442379, 6975.8604827902145, 4463.821447142078, 4844.491328410466, 4868.367112275523, 4940.407151141886, 4991.9500804991485, 4887.560119589254, 4957.256411538298, 5058.848234552969, 5081.8729531762, 5193.37679478465], "value": 4557.260161995345, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [277.4852648337334, 298.546209761163, 304.82624903804833, 309.31023816888336, 313.32634835104534, 5202.713850738212, 5203.063448579254, 5254.92117618236, 5300.867478313039, 5335.599830012745, 3934.626210509997, 4494.752788596626, 4893.33939918111, 4937.790241390855, 5081.771320290586, 3398.8130652814084, 3758.455024021529, 3865.7487263327935, 3904.941425878614, 3975.483187185662, 3936.8937618374175, 3937.0, 3937.370020796673, 3976.727447216892, 3983.2830090583684, 3171.587693599829, 3201.391735570246, 3669.6697297243245, 3905.6875449964077, 3937.5743103499995, 3935.1308128638952, 3946.309395855714, 3953.4069889516513, 3960.841566337343, 3962.9405558916637, 3405.6594340565994, 3406.9488957665653, 3408.113890388492, 3418.7265018798435, 3419.675130862564, 4445.488768791598, 4777.06847164803, 4801.447833499157, 4990.3013578098735, 5124.641275110744, 4192.517860446057, 4454.198244316021, 4460.955390446086, 4471.23988921884, 4492.001059528511, 4503.459584849839, 5430.795830460048, 5459.45135661819, 5459.945400546023, 5479.780808767645, 3748.925021499582, 3760.2479504099288, 3761.849526018956, 3776.0553315042775, 3784.8993808436308, 5084.542391184774, 5157.381114266274, 5343.438938911398, 5700.800471983503, 5718.199452076713, 3778.471014058034, 3781.9221521866134, 3789.810509474515, 3804.9429258561145, 3821.5032045833864, 3191.5850939377733, 3821.6942644588366, 3838.347480928249, 3854.6338097880657, 3855.575886652466, 4364.301711726109, 4493.415855938707, 4499.4150760400935, 4509.399163296982, 4520.434945631796, 4158.586080732505, 4525.411696479437, 5133.178691409356, 5413.783448662089, 5476.698781567052, 1165.318288801044, 1292.8707129286995, 1319.808627748971, 1322.1802482460744, 1326.9137506062075, 828.4449418889238, 859.334016137486, 887.3893882603055, 888.626776753765, 893.4282059481936, 4269.743815371087, 4279.850205242864, 5045.091883461012, 5046.4953504649975, 5078.695278283241, 4608.916904527433, 5094.388673359221, 5546.722663866791, 5614.382417934024, 5617.410171931889, 3905.7070719696135, 5116.744162791845, 5135.435102138762, 5170.948290517047, 5189.818356357585, 2371.573116838951, 2773.8072628769823, 2789.693133755285, 2926.897558585439, 2954.084233887502, 5350.357957045179, 5493.862653433696, 5540.889182216414, 5644.407337229532, 5837.854053648608, 5611.971940140274, 5620.859478512988, 5700.916825803167, 5767.36558978512, 5798.362180160179, 5056.84829455117, 5135.175653390695, 5377.5160235578205, 5837.503812176013, 5839.562032847553, 5180.4458662401885, 5336.813211537577, 5575.553955683617, 5656.472752356923, 5669.22208118011, 5522.530584900329, 5530.0, 5532.36795145425, 5537.4495141360485, 5543.390227074938, 4424.579664931826, 5076.441591425014, 5212.791488340424, 5796.1305804129715, 5813.447722466359, 3146.4179126861604, 3219.8712051517678, 3378.8141652209365, 3750.4936833527127, 3794.317022935898, 5178.352705911761, 5342.8931421372135, 5603.159526071122, 5603.31041100266, 5665.20687103814, 5495.725213739217, 5496.5983674162835, 5506.862328441821, 5520.889582208253, 5548.861278468071, 4316.611504964569, 4541.931871021871, 5302.761375738024, 5318.468153184651, 5474.616776825584, 5119.257707632447, 5295.311609490742, 5332.493413125747, 5346.78612855497, 5356.705381204071, 5284.9735751320995, 5380.461953804745, 5444.891102178013, 5454.290942177491, 5461.787975869779, 5299.4700529946695, 5305.832716802297, 5309.601779866448, 5329.600279979095, 5336.759845807026], "value": 3956.2181467082396, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.7017767448834, 717.4475653746615, 756.6103456719791, 766.4935660390523, 816.6284340625009, 15277.0, 15942.282597283122, 15944.36222551098, 15950.12274324913, 15967.201639918016, 14560.199189044624, 14771.482998095069, 14780.556583302518, 14803.555893323191, 14867.702645947074, 677.2448719677549, 698.3609996852899, 699.8425354295268, 816.5631387207845, 829.5105887526358, 684.6063513479769, 835.9665613375457, 852.2202185000717, 853.2320911179957, 853.6201390381283, 858.6994551906845, 907.2333877873186, 910.39003867409, 911.8632205169245, 913.2648218184358, 709.3934685843581, 735.3859527294716, 903.9502827344494, 908.6637943960744, 909.5543183839937, 642.5470043619284, 644.1079105439001, 645.9289478157399, 659.475716805141, 661.262692098307, 14116.294185290695, 14134.363953622009, 14149.221792801392, 14168.079074860101, 14177.220252886087, 13295.20228786276, 13600.0, 13635.318234088356, 13652.180869147884, 14075.577732668036, 11882.643520694393, 12245.265284082983, 12316.322602256872, 12330.075244356709, 12763.298018608974, 3788.4317352397084, 4508.917859713645, 4582.404287442644, 4609.0, 4692.36653051837, 111818.0, 113159.0, 121818.0, 121934.0, 123172.38413807964, 10499.895001050045, 10772.353658780497, 10797.406142662072, 10819.404932728776, 11211.047060999907, 20885.164593416397, 21119.366419007456, 21187.36437906865, 21236.36290911275, 21529.78470215309, 6561.639109849004, 7742.447542040798, 7847.097583777939, 7902.525848449111, 8064.233897779702, 4297.742135471882, 4390.253656878338, 4399.912001759947, 4520.208963431386, 4549.7497637629585, 3325.185329594265, 3429.3141371725756, 3430.27964127536, 3430.9828450857585, 3460.048486666137, 562.7383266780967, 577.2639884147745, 603.9456448919531, 627.1157667688537, 629.7512482569359, 536.9597280204, 557.9665220086807, 566.8129517259378, 596.6330706615487, 601.2664549249909, 8584.01283852365, 8647.610857511427, 8713.651453941899, 8954.86567701485, 8959.910400895908, 585.5315747402061, 598.2671227746064, 606.2724730323541, 608.3034925010837, 638.3138126514016, 4159.604837540429, 4273.059926816158, 4346.6522678185665, 4437.29003359461, 4514.300283456069, 99919.50040249752, 119347.0, 151682.0, 162380.0, 165997.0, 87024.5648771752, 92391.0, 108113.0, 134382.0, 136846.0, 115348.0, 117015.93794428158, 118430.0, 119575.8042419582, 122982.38508807578, 8250.38122140854, 8427.831443370975, 8446.788830279293, 8594.183552562497, 8750.081241469554, 37528.81235593805, 50948.74525627497, 54073.918521630134, 55277.0, 57907.13139302998, 88143.0, 93427.0, 95268.0, 96164.0, 100055.499167514, 9469.763255918382, 10133.240006999651, 10914.617988370368, 10984.505697243803, 11347.659570212907, 11946.446961894899, 12024.737402572604, 12026.414320921043, 12073.098256735271, 12508.812367814307, 11748.530058797553, 11786.882131178922, 11831.11266654987, 12274.0, 12344.13591048618, 11762.470688818852, 11807.586734464252, 11827.172097953406, 11979.700507487383, 12253.448594813077, 11622.951146163177, 11768.293902365886, 11771.058315334581, 11781.234219775688, 12138.817917731418, 7761.728339508316, 9280.72157835266, 9445.552453131342, 9682.806343872942, 9880.752981175528, 8882.511461869659, 9596.95201523988, 9638.469884156439, 9654.469004204555, 9734.805303893741], "value": 7403.429688554742, "unit": "score"}], "name": "dromaeo_css", "value": 7341.389483331075, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 163.0, 91.0, 157.0, 169.0, 168.0, 114.0, 116.0, 113.0, 114.0], "value": 131.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 108.0, 106.0, 148.0, 105.0, 97.0, 126.0, 139.0, 151.0, 108.0], "value": 123.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 185.0, 184.0, 186.0, 182.0, 182.0, 179.0, 181.0, 182.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 61.0, 103.0, 90.0, 83.0, 75.0, 57.0, 83.0, 59.0], "value": 74.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 95.0, 73.0, 68.0, 68.0, 92.0, 66.0, 68.0, 91.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 87.0, 85.0, 87.0, 85.0, 88.0, 86.0, 89.0, 84.0], "value": 86.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 93.0, 94.0, 93.0, 94.0, 91.0, 93.0, 94.0, 91.0], "value": 93.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 87.0, 86.0, 85.0, 85.0, 84.0, 95.0, 86.0, 86.0], "value": 86.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 53.0, 53.0, 54.0, 55.0, 54.0, 54.0, 53.0, 54.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 73.0, 72.0, 75.0, 72.0, 69.0, 69.0, 71.0, 72.0, 73.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 87.0, 84.0, 85.0, 87.0, 83.0, 96.0, 106.0, 85.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 142.0, 132.0, 124.0, 151.0, 151.0, 150.0, 138.0, 179.0, 140.0], "value": 144.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 170.0, 169.0, 169.0, 169.0, 171.0, 170.0, 167.0, 168.0, 188.0], "value": 171.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 70.0, 69.0, 69.0, 68.0, 70.0, 69.0, 71.0, 67.0], "value": 69.1, "unit": "score"}], "name": "kraken", "value": 1454.9, "alertThreshold": 2.0}]} 05:52:23 INFO - Return code: 0 05:52:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:52:23 INFO - # TBPL SUCCESS # 05:52:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:52:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:52:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:52:23 INFO - 2016-09-13 05:52:23.313 screenresolution[1672:10519] starting screenresolution argv=screenresolution get 05:52:23 INFO - 2016-09-13 05:52:23.316 screenresolution[1672:10519] Display 0: 1600x1200x32@60 05:52:23 INFO - 2016-09-13 05:52:23.323 screenresolution[1673:10522] starting screenresolution argv=screenresolution list 05:52:23 INFO - Available Modes on Display 0 05:52:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:52:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:52:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:52:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:52:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:52:23 INFO - Intel Iris: 05:52:23 INFO - Chipset Model: Intel Iris 05:52:23 INFO - Type: GPU 05:52:23 INFO - Bus: Built-In 05:52:23 INFO - VRAM (Dynamic, Max): 1536 MB 05:52:23 INFO - Vendor: Intel (0x8086) 05:52:23 INFO - Device ID: 0x0a2e 05:52:23 INFO - Revision ID: 0x0009 05:52:23 INFO - Displays: 05:52:23 INFO - Display: 05:52:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:52:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:52:23 INFO - Display Serial Number: 1600x1200 60 05:52:23 INFO - Main Display: Yes 05:52:23 INFO - Mirror: Off 05:52:23 INFO - Online: Yes 05:52:23 INFO - Rotation: Supported 05:52:23 INFO - Return code: 0 05:52:23 INFO - Running post-action listener: _resource_record_post_action 05:52:23 INFO - [mozharness: 2016-09-13 12:52:23.563556Z] Finished run-tests step (success) 05:52:23 INFO - Running post-run listener: _resource_record_post_run 05:52:23 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 33045504; Write bytes: 702246912; Read time: 316; Write time: 1922 05:52:23 INFO - TinderboxPrint: CPU usage
53.4% 05:52:23 INFO - TinderboxPrint: I/O read bytes / time
33,045,504 / 316 05:52:23 INFO - TinderboxPrint: I/O write bytes / time
702,246,912 / 1,922 05:52:23 INFO - TinderboxPrint: CPU idle
2,256.4 (46.6%) 05:52:23 INFO - TinderboxPrint: CPU system
969.4 (20.0%) 05:52:23 INFO - TinderboxPrint: CPU user
1,619.9 (33.4%) 05:52:23 INFO - TinderboxPrint: Swap in / out
433,639,424 / 0 05:52:23 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 395510272; Write bytes: 389527040; Read time: 29547; Write time: 693 05:52:23 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 31063040; Write bytes: 293976576; Read time: 294; Write time: 1187 05:52:24 INFO - Running post-run listener: _upload_blobber_files 05:52:24 INFO - Blob upload gear active. 05:52:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:52:24 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:24 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:24 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:24 INFO - (blobuploader) - INFO - Open directory for files ... 05:52:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:52:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:52:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:52:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:52:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:52:25 INFO - (blobuploader) - INFO - Done attempting. 05:52:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:52:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:52:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:52:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:52:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:52:26 INFO - (blobuploader) - INFO - Done attempting. 05:52:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:52:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:52:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:52:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:52:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:52:28 INFO - (blobuploader) - INFO - Done attempting. 05:52:28 INFO - (blobuploader) - INFO - Iteration through files over. 05:52:28 INFO - Return code: 0 05:52:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:52:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:52:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bac3e89a458e0bd7cc6582fa15396169b70881bad8430aa132eed1a2a655a3b2e20d2e5ec866ab58da24271ea337c210aa965227721f3e386253ed13ef2956c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d524add9fb5e186dabecb3e5d4e19fc094f02f79b5d58315c0fa242ab107e4ce7ba58aa0144f7aed87356943ba1cfd837b01fe650e0bf684fa44e96ecfefc56", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56d468c785c5ba6ca1bf5290c698c7c4a02e0492824bc3154c256e0b99fa37c86fa50e4ec76c37a856142539d44bfb43428f8e90314ca9f247a9b90f2746fb1d"} 05:52:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:52:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:52:28 INFO - Contents: 05:52:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bac3e89a458e0bd7cc6582fa15396169b70881bad8430aa132eed1a2a655a3b2e20d2e5ec866ab58da24271ea337c210aa965227721f3e386253ed13ef2956c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d524add9fb5e186dabecb3e5d4e19fc094f02f79b5d58315c0fa242ab107e4ce7ba58aa0144f7aed87356943ba1cfd837b01fe650e0bf684fa44e96ecfefc56", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56d468c785c5ba6ca1bf5290c698c7c4a02e0492824bc3154c256e0b99fa37c86fa50e4ec76c37a856142539d44bfb43428f8e90314ca9f247a9b90f2746fb1d"} 05:52:28 INFO - Running post-run listener: copy_logs_to_upload_dir 05:52:28 INFO - Copying logs to upload dir... 05:52:28 INFO - mkdir: /builds/slave/test/build/upload/logs 05:52:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.591023 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-13 05:52:29.020309) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-13 05:52:29.024072) ========= 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.awzObfRxc1/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.jbl5vMtby4/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bac3e89a458e0bd7cc6582fa15396169b70881bad8430aa132eed1a2a655a3b2e20d2e5ec866ab58da24271ea337c210aa965227721f3e386253ed13ef2956c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d524add9fb5e186dabecb3e5d4e19fc094f02f79b5d58315c0fa242ab107e4ce7ba58aa0144f7aed87356943ba1cfd837b01fe650e0bf684fa44e96ecfefc56", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56d468c785c5ba6ca1bf5290c698c7c4a02e0492824bc3154c256e0b99fa37c86fa50e4ec76c37a856142539d44bfb43428f8e90314ca9f247a9b90f2746fb1d"} 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.010920 build_url: 'https://queue.taskcluster.net/v1/task/AkOssNPhREWrglmx2BtkFA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bac3e89a458e0bd7cc6582fa15396169b70881bad8430aa132eed1a2a655a3b2e20d2e5ec866ab58da24271ea337c210aa965227721f3e386253ed13ef2956c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d524add9fb5e186dabecb3e5d4e19fc094f02f79b5d58315c0fa242ab107e4ce7ba58aa0144f7aed87356943ba1cfd837b01fe650e0bf684fa44e96ecfefc56", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56d468c785c5ba6ca1bf5290c698c7c4a02e0492824bc3154c256e0b99fa37c86fa50e4ec76c37a856142539d44bfb43428f8e90314ca9f247a9b90f2746fb1d"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-13 05:52:29.062338) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 05:52:29.063141) ========= 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.awzObfRxc1/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.jbl5vMtby4/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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.005219 ========= master_lag: 0.13 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 05:52:29.199755) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-13 05:52:29.200109) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-13 05:52:29.200508) ========= ========= Total master_lag: 0.28 =========