builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0131 starttime: 1473420267.7 results: success (0) buildid: 20160909030022 builduid: a9041215aee14f62a2913d9ba65712ef revision: d4060d4f4f6771608e0c4ee6fc62e430b6f244c0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-09 04:24:27.696594) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-09 04:24:27.697140) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-09 04:24:27.697442) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-09 04:24:27.767946) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 04:24:27.768354) ========= 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.OODW1ls8Dw/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.16aznVqw0d/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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-09 04:24:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 70.6M=0s 2016-09-09 04:24:27 (70.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.153109 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 04:24:27.944294) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 04:24:27.944587) ========= 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.OODW1ls8Dw/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.16aznVqw0d/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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.020460 ========= master_lag: 0.20 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 04:24:28.160991) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-09 04:24:28.161382) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d4060d4f4f6771608e0c4ee6fc62e430b6f244c0 --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 d4060d4f4f6771608e0c4ee6fc62e430b6f244c0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OODW1ls8Dw/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.16aznVqw0d/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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-09 04:24:28,267 truncating revision to first 12 chars 2016-09-09 04:24:28,268 Setting DEBUG logging. 2016-09-09 04:24:28,268 attempt 1/10 2016-09-09 04:24:28,268 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d4060d4f4f67?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-09 04:24:29,383 unpacking tar archive at: mozilla-central-d4060d4f4f67/testing/mozharness/ program finished with exit code 0 elapsedTime=1.387324 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-09 04:24:29.584590) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-09 04:24:29.584990) ========= script_repo_revision: d4060d4f4f6771608e0c4ee6fc62e430b6f244c0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-09 04:24:29.585482) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-09 04:24:29.585763) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-09 04:24:29.608605) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-09 04:24:29.608961) ========= /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.OODW1ls8Dw/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.16aznVqw0d/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 04:24:29 INFO - MultiFileLogger online at 20160909 04:24:29 in /builds/slave/test 04:24:29 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 04:24:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:24:29 INFO - {'append_to_log': False, 04:24:29 INFO - 'base_work_dir': '/builds/slave/test', 04:24:29 INFO - 'blob_upload_branch': 'Firefox', 04:24:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:24:29 INFO - 'branch': 'Firefox', 04:24:29 INFO - 'buildbot_json_path': 'buildprops.json', 04:24:29 INFO - 'config_files': ('talos/mac_config.py',), 04:24:29 INFO - 'default_actions': ('clobber', 04:24:29 INFO - 'read-buildbot-config', 04:24:29 INFO - 'download-and-extract', 04:24:29 INFO - 'populate-webroot', 04:24:29 INFO - 'create-virtualenv', 04:24:29 INFO - 'install', 04:24:29 INFO - 'run-tests'), 04:24:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:24:29 INFO - 'download_minidump_stackwalk': True, 04:24:29 INFO - 'download_symbols': 'ondemand', 04:24:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:24:29 INFO - 'tooltool.py': '/tools/tooltool.py', 04:24:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:24:29 INFO - '/tools/misc-python/virtualenv.py')}, 04:24:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:24:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:24:29 INFO - 'installer_path': 'installer.exe', 04:24:29 INFO - 'log_level': 'info', 04:24:29 INFO - 'log_name': 'talos', 04:24:29 INFO - 'log_to_console': True, 04:24:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:24:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:24:29 INFO - 'opt_config_files': (), 04:24:29 INFO - 'pip_index': False, 04:24:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:24:29 INFO - 'cmd': ('bash', 04:24:29 INFO - '-c', 04:24:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:24:29 INFO - 'enabled': True, 04:24:29 INFO - 'halt_on_failure': False, 04:24:29 INFO - 'name': 'check_screen_resolution'},), 04:24:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:24:29 INFO - 'cmd': ('bash', 04:24:29 INFO - '-c', 04:24:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:24:29 INFO - 'enabled': True, 04:24:29 INFO - 'halt_on_failure': False, 04:24:29 INFO - 'name': 'check_screen_resolution'},), 04:24:29 INFO - 'run_cmd_checks_enabled': True, 04:24:29 INFO - 'sps_profile': False, 04:24:29 INFO - 'sps_profile_interval': 0, 04:24:29 INFO - 'suite': 'dromaeojs', 04:24:29 INFO - 'system_bits': '32', 04:24:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:24:29 INFO - 'title': 't-yosemite-r7-0131', 04:24:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:24:29 INFO - 'use_talos_json': True, 04:24:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:24:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:24:29 INFO - 'work_dir': 'build'} 04:24:29 INFO - [mozharness: 2016-09-09 11:24:29.825218Z] Running clobber step. 04:24:29 INFO - Running pre-action listener: _resource_record_pre_action 04:24:29 INFO - Running main action method: clobber 04:24:29 INFO - rmtree: /builds/slave/test/build 04:24:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:24:31 INFO - Running post-action listener: _resource_record_post_action 04:24:31 INFO - [mozharness: 2016-09-09 11:24:31.502707Z] Finished clobber step (success) 04:24:31 INFO - [mozharness: 2016-09-09 11:24:31.502957Z] Running read-buildbot-config step. 04:24:31 INFO - Running pre-action listener: _resource_record_pre_action 04:24:31 INFO - Running main action method: read_buildbot_config 04:24:31 INFO - Using buildbot properties: 04:24:31 INFO - { 04:24:31 INFO - "project": "", 04:24:31 INFO - "product": "firefox", 04:24:31 INFO - "script_repo_revision": "production", 04:24:31 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:24:31 INFO - "repository": "", 04:24:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:24:31 INFO - "buildid": "20160909030022", 04:24:31 INFO - "pgo_build": "False", 04:24:31 INFO - "basedir": "/builds/slave/test", 04:24:31 INFO - "buildnumber": 48, 04:24:31 INFO - "slavename": "t-yosemite-r7-0131", 04:24:31 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 04:24:31 INFO - "platform": "yosemite_r7", 04:24:31 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:24:31 INFO - "branch": "mozilla-central", 04:24:31 INFO - "repo_path": "mozilla-central", 04:24:31 INFO - "revision": "d4060d4f4f6771608e0c4ee6fc62e430b6f244c0", 04:24:31 INFO - "stage_platform": "macosx64", 04:24:31 INFO - "builduid": "a9041215aee14f62a2913d9ba65712ef", 04:24:31 INFO - "slavebuilddir": "test" 04:24:31 INFO - } 04:24:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/a-iNxkFGReS2qTY2phTuEw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 04:24:31 INFO - Running post-action listener: _resource_record_post_action 04:24:31 INFO - [mozharness: 2016-09-09 11:24:31.508741Z] Finished read-buildbot-config step (success) 04:24:31 INFO - [mozharness: 2016-09-09 11:24:31.508934Z] Running download-and-extract step. 04:24:31 INFO - Running pre-action listener: _resource_record_pre_action 04:24:31 INFO - Running main action method: download_and_extract 04:24:31 INFO - mkdir: /builds/slave/test/build/tests 04:24:31 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')]} 04:24:31 INFO - trying https://queue.taskcluster.net/v1/task/a-iNxkFGReS2qTY2phTuEw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 04:24:31 INFO - Downloading https://queue.taskcluster.net/v1/task/a-iNxkFGReS2qTY2phTuEw/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 04:24:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a-iNxkFGReS2qTY2phTuEw/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 04:24:33 INFO - Downloaded 1199 bytes. 04:24:33 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 04:24:33 INFO - Using the following test package requirements: 04:24:33 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 04:24:33 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:24:33 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 04:24:33 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:24:33 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 04:24:33 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:24:33 INFO - u'jsshell-mac.zip'], 04:24:33 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:24:33 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 04:24:33 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 04:24:33 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:24:33 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 04:24:33 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:24:33 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 04:24:33 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:24:33 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 04:24:33 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:24:33 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 04:24:33 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:24:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a-iNxkFGReS2qTY2phTuEw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 04:24:33 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 04:24:40 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 04:24:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a-iNxkFGReS2qTY2phTuEw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 04:24:41 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 04:24:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a-iNxkFGReS2qTY2phTuEw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 04:24:48 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 04:24: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')]} 04:24:49 INFO - trying https://queue.taskcluster.net/v1/task/a-iNxkFGReS2qTY2phTuEw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 04:24:49 INFO - Downloading https://queue.taskcluster.net/v1/task/a-iNxkFGReS2qTY2phTuEw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 04:24:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a-iNxkFGReS2qTY2phTuEw/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 04:24:53 INFO - Downloaded 111527018 bytes. 04:24:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/a-iNxkFGReS2qTY2phTuEw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 04:24:53 INFO - mkdir: /builds/slave/test/properties 04:24:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:24:53 INFO - Writing to file /builds/slave/test/properties/build_url 04:24:53 INFO - Contents: 04:24:53 INFO - build_url:https://queue.taskcluster.net/v1/task/a-iNxkFGReS2qTY2phTuEw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 04:24:54 INFO - Running post-action listener: _resource_record_post_action 04:24:54 INFO - Running post-action listener: set_extra_try_arguments 04:24:54 INFO - [mozharness: 2016-09-09 11:24:54.551568Z] Finished download-and-extract step (success) 04:24:54 INFO - [mozharness: 2016-09-09 11:24:54.551717Z] Running populate-webroot step. 04:24:54 INFO - Running pre-action listener: _resource_record_pre_action 04:24:54 INFO - Running main action method: populate_webroot 04:24:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:24:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:24:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:24:54 INFO - '--tppagecycles', 04:24:54 INFO - '7'], 04:24:54 INFO - 'tests': ['tsvgm']}}, 04:24:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:24:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:24:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:24:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:24:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:24:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:24:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:24:54 INFO - 'talos_options': ['--disable-e10s'], 04:24:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:24:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:24:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:24:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:24:54 INFO - 'talos_options': ['--disable-e10s'], 04:24:54 INFO - 'tests': ['damp', 'tps']}, 04:24:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:24:54 INFO - 'tests': ['damp', 'tps']}, 04:24:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:24:54 INFO - 'tests': ['dromaeo_dom']}, 04:24:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:24:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:24:54 INFO - 'tests': ['basic_compositor_video']}, 04:24:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:24:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:24:54 INFO - 'tests': ['a11yr', 04:24:54 INFO - 'ts_paint', 04:24:54 INFO - 'tpaint', 04:24:54 INFO - 'sessionrestore', 04:24:54 INFO - 'sessionrestore_no_auto_restore', 04:24:54 INFO - 'tabpaint']}, 04:24:54 INFO - 'other-e10s': {'tests': ['a11yr', 04:24:54 INFO - 'ts_paint', 04:24:54 INFO - 'tpaint', 04:24:54 INFO - 'sessionrestore', 04:24:54 INFO - 'sessionrestore_no_auto_restore', 04:24:54 INFO - 'tabpaint']}, 04:24:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:24:54 INFO - 'ts_paint', 04:24:54 INFO - 'tpaint', 04:24:54 INFO - 'sessionrestore', 04:24:54 INFO - 'sessionrestore_no_auto_restore', 04:24:54 INFO - 'tabpaint']}, 04:24:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:24:54 INFO - 'ts_paint', 04:24:54 INFO - 'tpaint', 04:24:54 INFO - 'sessionrestore', 04:24:54 INFO - 'sessionrestore_no_auto_restore', 04:24:54 INFO - 'tabpaint']}, 04:24:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:24:54 INFO - 'tests': ['a11yr', 04:24:54 INFO - 'ts_paint', 04:24:54 INFO - 'tpaint', 04:24:54 INFO - 'sessionrestore', 04:24:54 INFO - 'sessionrestore_no_auto_restore', 04:24:54 INFO - 'tabpaint']}, 04:24:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:24:54 INFO - 'tests': ['a11yr', 04:24:54 INFO - 'ts_paint', 04:24:54 INFO - 'tpaint', 04:24:54 INFO - 'sessionrestore', 04:24:54 INFO - 'sessionrestore_no_auto_restore', 04:24:54 INFO - 'tabpaint']}, 04:24:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:24:54 INFO - 'tests': ['tsvgx', 04:24:54 INFO - 'tsvgr_opacity', 04:24:54 INFO - 'tart', 04:24:54 INFO - 'tscrollx', 04:24:54 INFO - 'cart']}, 04:24:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:24:54 INFO - 'tsvgr_opacity', 04:24:54 INFO - 'tart', 04:24:54 INFO - 'tscrollx', 04:24:54 INFO - 'cart']}, 04:24:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:24:54 INFO - 'talos_options': ['--disable-e10s'], 04:24:54 INFO - 'tests': ['tp5o']}, 04:24:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:24:54 INFO - 'tests': ['tp5o']}, 04:24:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:24:54 INFO - 'talos_options': ['--disable-e10s', 04:24:54 INFO - '--xperf_path', 04:24:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:24:54 INFO - 'tests': ['tp5n']}, 04:24:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:24:54 INFO - 'talos_options': ['--xperf_path', 04:24:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:24:54 INFO - 'tests': ['tp5n']}}, 04:24:54 INFO - 'talos.zip': {'path': '', 04:24:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:24:54 INFO - Running post-action listener: _resource_record_post_action 04:24:54 INFO - [mozharness: 2016-09-09 11:24:54.570732Z] Finished populate-webroot step (success) 04:24:54 INFO - [mozharness: 2016-09-09 11:24:54.570837Z] Running create-virtualenv step. 04:24:54 INFO - Running pre-action listener: _resource_record_pre_action 04:24:54 INFO - Running main action method: create_virtualenv 04:24:54 INFO - Creating virtualenv /builds/slave/test/build/venv 04:24:54 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 04:24:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:24:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:24:54 INFO - Using real prefix '/tools/python27' 04:24:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:24:55 INFO - Installing distribute.............................................................................................................................................................................................done. 04:24:58 INFO - Installing pip.................done. 04:24:58 INFO - Return code: 0 04:24:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:24:58 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')]} 04:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:24:58 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')]} 04:24:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:24:58 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 0x110659a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106ee618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89b157ee60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105bdd30>, '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 0x110322db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1105b5b20>, '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.OODW1ls8Dw/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.16aznVqw0d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 04:24:58 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 04:24:58 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 04:24:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OODW1ls8Dw/Render', 04:24:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:58 INFO - 'HOME': '/Users/cltbld', 04:24:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:58 INFO - 'LOGNAME': 'cltbld', 04:24:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:58 INFO - 'MOZ_NO_REMOTE': '1', 04:24:58 INFO - 'NO_EM_RESTART': '1', 04:24:58 INFO - 'PAGER': '/bin/cat', 04:24:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:58 INFO - 'PWD': '/builds/slave/test', 04:24:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:58 INFO - 'SHELL': '/bin/bash', 04:24:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.16aznVqw0d/Listeners', 04:24:58 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 04:24:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:58 INFO - 'USER': 'cltbld', 04:24:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:58 INFO - 'XPC_FLAGS': '0x0', 04:24:58 INFO - 'XPC_SERVICE_NAME': '0', 04:24:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:58 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 04:24:58 INFO - Cleaning up... 04:24:58 INFO - Return code: 0 04:24:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:24:58 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')]} 04:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:24:58 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')]} 04:24:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:24:58 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 0x110659a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106ee618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89b157ee60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105bdd30>, '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 0x110322db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1105b5b20>, '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.OODW1ls8Dw/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.16aznVqw0d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 04:24:58 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 04:24:58 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 04:24:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OODW1ls8Dw/Render', 04:24:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:58 INFO - 'HOME': '/Users/cltbld', 04:24:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:58 INFO - 'LOGNAME': 'cltbld', 04:24:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:58 INFO - 'MOZ_NO_REMOTE': '1', 04:24:58 INFO - 'NO_EM_RESTART': '1', 04:24:58 INFO - 'PAGER': '/bin/cat', 04:24:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:58 INFO - 'PWD': '/builds/slave/test', 04:24:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:58 INFO - 'SHELL': '/bin/bash', 04:24:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.16aznVqw0d/Listeners', 04:24:58 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 04:24:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:58 INFO - 'USER': 'cltbld', 04:24:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:58 INFO - 'XPC_FLAGS': '0x0', 04:24:58 INFO - 'XPC_SERVICE_NAME': '0', 04:24:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:58 INFO - Downloading/unpacking psutil>=3.1.1 04:24: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 04:24: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 04:24: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 04:24: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 04:24: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 04:24: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 04:25:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:25:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:25:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:25:01 INFO - Installing collected packages: psutil 04:25:01 INFO - Running setup.py install for psutil 04:25:01 INFO - building 'psutil._psutil_osx' extension 04:25:01 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 04:25:02 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 04:25:02 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 04:25:02 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 04:25:02 INFO - building 'psutil._psutil_posix' extension 04:25:02 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 04:25:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:25:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:25:02 INFO - ^ 04:25:02 INFO - 1 warning generated. 04:25:02 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 04:25:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:25:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:25:02 INFO - Successfully installed psutil 04:25:02 INFO - Cleaning up... 04:25:02 INFO - Return code: 0 04:25:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:25:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:02 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 0x110659a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106ee618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89b157ee60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105bdd30>, '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 0x110322db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1105b5b20>, '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.OODW1ls8Dw/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.16aznVqw0d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 04:25:02 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 04:25:02 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 04:25:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OODW1ls8Dw/Render', 04:25:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:02 INFO - 'HOME': '/Users/cltbld', 04:25:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:02 INFO - 'LOGNAME': 'cltbld', 04:25:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:02 INFO - 'MOZ_NO_REMOTE': '1', 04:25:02 INFO - 'NO_EM_RESTART': '1', 04:25:02 INFO - 'PAGER': '/bin/cat', 04:25:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:02 INFO - 'PWD': '/builds/slave/test', 04:25:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:02 INFO - 'SHELL': '/bin/bash', 04:25:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.16aznVqw0d/Listeners', 04:25:02 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 04:25:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:02 INFO - 'USER': 'cltbld', 04:25:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:02 INFO - 'XPC_FLAGS': '0x0', 04:25:02 INFO - 'XPC_SERVICE_NAME': '0', 04:25:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:25:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:25:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:25:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:25:05 INFO - Installing collected packages: mozsystemmonitor 04:25:05 INFO - Running setup.py install for mozsystemmonitor 04:25:05 INFO - Successfully installed mozsystemmonitor 04:25:05 INFO - Cleaning up... 04:25:05 INFO - Return code: 0 04:25:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:25: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')]} 04:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25: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')]} 04:25:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:05 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 0x110659a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106ee618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89b157ee60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105bdd30>, '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 0x110322db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1105b5b20>, '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.OODW1ls8Dw/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.16aznVqw0d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 04:25:05 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 04:25:05 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 04:25:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OODW1ls8Dw/Render', 04:25:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:05 INFO - 'HOME': '/Users/cltbld', 04:25:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:05 INFO - 'LOGNAME': 'cltbld', 04:25:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:05 INFO - 'MOZ_NO_REMOTE': '1', 04:25:05 INFO - 'NO_EM_RESTART': '1', 04:25:05 INFO - 'PAGER': '/bin/cat', 04:25:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:05 INFO - 'PWD': '/builds/slave/test', 04:25:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:05 INFO - 'SHELL': '/bin/bash', 04:25:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.16aznVqw0d/Listeners', 04:25:05 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 04:25:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:05 INFO - 'USER': 'cltbld', 04:25:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:05 INFO - 'XPC_FLAGS': '0x0', 04:25:05 INFO - 'XPC_SERVICE_NAME': '0', 04:25:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:05 INFO - Downloading/unpacking jsonschema==2.5.1 04:25:05 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 04:25:05 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 04:25:05 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 04:25:05 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 04:25:05 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 04:25:05 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 04:25:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:25:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:08 INFO - Downloading functools32-3.2.3-2.tar.gz 04:25:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:25:08 INFO - warning: no files found matching '*.txt' 04:25:08 INFO - no previously-included directories found matching 'build' 04:25:08 INFO - no previously-included directories found matching 'dist' 04:25:08 INFO - no previously-included directories found matching '.git*' 04:25:08 INFO - Installing collected packages: jsonschema, functools32 04:25:08 INFO - Running setup.py install for functools32 04:25:09 INFO - warning: no files found matching '*.txt' 04:25:09 INFO - no previously-included directories found matching 'build' 04:25:09 INFO - no previously-included directories found matching 'dist' 04:25:09 INFO - no previously-included directories found matching '.git*' 04:25:09 INFO - Successfully installed jsonschema functools32 04:25:09 INFO - Cleaning up... 04:25:09 INFO - Return code: 0 04:25:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:25:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:09 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 0x110659a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106ee618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89b157ee60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105bdd30>, '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 0x110322db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1105b5b20>, '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.OODW1ls8Dw/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.16aznVqw0d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 04:25:09 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 04:25:09 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 04:25:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OODW1ls8Dw/Render', 04:25:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:09 INFO - 'HOME': '/Users/cltbld', 04:25:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:09 INFO - 'LOGNAME': 'cltbld', 04:25:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:09 INFO - 'MOZ_NO_REMOTE': '1', 04:25:09 INFO - 'NO_EM_RESTART': '1', 04:25:09 INFO - 'PAGER': '/bin/cat', 04:25:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:09 INFO - 'PWD': '/builds/slave/test', 04:25:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:09 INFO - 'SHELL': '/bin/bash', 04:25:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.16aznVqw0d/Listeners', 04:25:09 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 04:25:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:09 INFO - 'USER': 'cltbld', 04:25:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:09 INFO - 'XPC_FLAGS': '0x0', 04:25:09 INFO - 'XPC_SERVICE_NAME': '0', 04:25:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:25:09 INFO - Cleaning up... 04:25:09 INFO - Return code: 0 04:25:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:25:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:09 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 0x110659a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106ee618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89b157ee60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105bdd30>, '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 0x110322db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1105b5b20>, '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.OODW1ls8Dw/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.16aznVqw0d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 04:25:09 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 04:25:09 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 04:25:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OODW1ls8Dw/Render', 04:25:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:09 INFO - 'HOME': '/Users/cltbld', 04:25:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:09 INFO - 'LOGNAME': 'cltbld', 04:25:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:09 INFO - 'MOZ_NO_REMOTE': '1', 04:25:09 INFO - 'NO_EM_RESTART': '1', 04:25:09 INFO - 'PAGER': '/bin/cat', 04:25:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:09 INFO - 'PWD': '/builds/slave/test', 04:25:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:09 INFO - 'SHELL': '/bin/bash', 04:25:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.16aznVqw0d/Listeners', 04:25:09 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 04:25:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:09 INFO - 'USER': 'cltbld', 04:25:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:09 INFO - 'XPC_FLAGS': '0x0', 04:25:09 INFO - 'XPC_SERVICE_NAME': '0', 04:25:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:09 INFO - Downloading/unpacking blobuploader==1.2.4 04:25:09 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 04:25:09 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 04:25:09 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 04:25:09 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 04:25:09 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 04:25:09 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 04:25:12 INFO - Downloading blobuploader-1.2.4.tar.gz 04:25:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:25:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:25:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:25:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:25:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:13 INFO - Downloading docopt-0.6.1.tar.gz 04:25:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:25:13 INFO - Installing collected packages: blobuploader, requests, docopt 04:25:13 INFO - Running setup.py install for blobuploader 04:25:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:25:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:25:13 INFO - Running setup.py install for requests 04:25:14 INFO - Running setup.py install for docopt 04:25:14 INFO - Successfully installed blobuploader requests docopt 04:25:14 INFO - Cleaning up... 04:25:14 INFO - Return code: 0 04:25:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:25:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:14 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 0x110659a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106ee618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89b157ee60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105bdd30>, '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 0x110322db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1105b5b20>, '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.OODW1ls8Dw/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.16aznVqw0d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 04:25:14 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 04:25:14 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 04:25:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OODW1ls8Dw/Render', 04:25:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:14 INFO - 'HOME': '/Users/cltbld', 04:25:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:14 INFO - 'LOGNAME': 'cltbld', 04:25:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:14 INFO - 'MOZ_NO_REMOTE': '1', 04:25:14 INFO - 'NO_EM_RESTART': '1', 04:25:14 INFO - 'PAGER': '/bin/cat', 04:25:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:14 INFO - 'PWD': '/builds/slave/test', 04:25:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:14 INFO - 'SHELL': '/bin/bash', 04:25:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.16aznVqw0d/Listeners', 04:25:14 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 04:25:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:14 INFO - 'USER': 'cltbld', 04:25:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:14 INFO - 'XPC_FLAGS': '0x0', 04:25:14 INFO - 'XPC_SERVICE_NAME': '0', 04:25:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:25:14 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-Sfg3Ff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:25:14 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-wVP3Sg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:25:14 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-WbUO4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:25:14 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-4os_36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:25:15 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-M9XQ8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:25:15 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-4CX0z6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:25:15 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-8rUPKN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:25:15 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-W8hZ5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:25:15 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-UMNQLZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:25:15 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-ez3vtm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:25:15 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-32Az5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:25:15 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-TtGQgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:25:15 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-Jx31vl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:25:15 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-waaoEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:16 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-36nzyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:25:16 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-9VRVk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:25:16 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-4dHRBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:25:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:25:16 INFO - Running setup.py install for manifestparser 04:25:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:25:16 INFO - Running setup.py install for mozcrash 04:25:16 INFO - Running setup.py install for mozdebug 04:25:16 INFO - Running setup.py install for mozdevice 04:25:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:25:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:25:16 INFO - Running setup.py install for mozfile 04:25:17 INFO - Running setup.py install for mozhttpd 04:25:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:25:17 INFO - Running setup.py install for mozinfo 04:25:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:25:17 INFO - Running setup.py install for mozInstall 04:25:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:25:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:25:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:25:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:25:17 INFO - Running setup.py install for mozleak 04:25:17 INFO - Running setup.py install for mozlog 04:25:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:25:17 INFO - Running setup.py install for moznetwork 04:25:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:25:17 INFO - Running setup.py install for mozprocess 04:25:18 INFO - Running setup.py install for mozprofile 04:25:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:25:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:25:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:25:18 INFO - Running setup.py install for mozrunner 04:25:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:25:18 INFO - Running setup.py install for mozscreenshot 04:25:18 INFO - Running setup.py install for moztest 04:25:18 INFO - Running setup.py install for mozversion 04:25:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:25:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:25:18 INFO - Cleaning up... 04:25:18 INFO - Return code: 0 04:25:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:25:18 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')]} 04:25:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:18 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')]} 04:25:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:18 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 0x110659a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106ee618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89b157ee60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105bdd30>, '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 0x110322db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1105b5b20>, '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.OODW1ls8Dw/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.16aznVqw0d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 04:25:18 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 04:25:18 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 04:25:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OODW1ls8Dw/Render', 04:25:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:18 INFO - 'HOME': '/Users/cltbld', 04:25:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:18 INFO - 'LOGNAME': 'cltbld', 04:25:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:18 INFO - 'MOZ_NO_REMOTE': '1', 04:25:18 INFO - 'NO_EM_RESTART': '1', 04:25:18 INFO - 'PAGER': '/bin/cat', 04:25:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:18 INFO - 'PWD': '/builds/slave/test', 04:25:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:18 INFO - 'SHELL': '/bin/bash', 04:25:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.16aznVqw0d/Listeners', 04:25:18 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 04:25:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:18 INFO - 'USER': 'cltbld', 04:25:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:18 INFO - 'XPC_FLAGS': '0x0', 04:25:18 INFO - 'XPC_SERVICE_NAME': '0', 04:25:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:25:19 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-mRirqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:25:19 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)) 04:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:25:19 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-nJzoj0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:25:19 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)) 04:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:25:19 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-MVGqEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:25:19 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)) 04:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:25:19 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-LTriQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:25:19 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)) 04:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:25:19 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-TiSITT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:25:19 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)) 04:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:25:19 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-LMj5wR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:25:19 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)) 04:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:25:19 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-Ujrg0r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:25:19 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)) 04:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:25:19 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-kZHFWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:25:20 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)) 04:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:25:20 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-jcEjrn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:25:20 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)) 04:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:25:20 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-KtJtgX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:25:20 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)) 04:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:25:20 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-ARxOcu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:25:20 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)) 04:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:25:20 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-elxZzx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:25:20 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)) 04:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:25:20 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-MwgvLv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:25:20 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)) 04:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:25:20 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-szV54p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:25:20 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)) 04:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:20 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-6YvgYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:20 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)) 04:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:25:20 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-Xvznpz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:25:20 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)) 04:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:25:20 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-SKEt_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:25:20 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)) 04:25:20 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)) 04:25:20 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)) 04:25:20 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)) 04:25:20 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)) 04:25:20 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)) 04:25:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:25:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:23 INFO - Downloading blessings-1.6.tar.gz 04:25:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:25:23 INFO - Installing collected packages: blessings 04:25:23 INFO - Running setup.py install for blessings 04:25:23 INFO - Successfully installed blessings 04:25:23 INFO - Cleaning up... 04:25:23 INFO - Return code: 0 04:25:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:25:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:25:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:25:24 INFO - Reading from file tmpfile_stdout 04:25:24 INFO - Current package versions: 04:25:24 INFO - blessings == 1.6 04:25:24 INFO - blobuploader == 1.2.4 04:25:24 INFO - docopt == 0.6.1 04:25:24 INFO - functools32 == 3.2.3-2 04:25:24 INFO - jsonschema == 2.5.1 04:25:24 INFO - manifestparser == 1.1 04:25:24 INFO - mozInstall == 1.12 04:25:24 INFO - mozcrash == 0.17 04:25:24 INFO - mozdebug == 0.1 04:25:24 INFO - mozdevice == 0.48 04:25:24 INFO - mozfile == 1.2 04:25:24 INFO - mozhttpd == 0.7 04:25:24 INFO - mozinfo == 0.9 04:25:24 INFO - mozleak == 0.1 04:25:24 INFO - mozlog == 3.3 04:25:24 INFO - moznetwork == 0.27 04:25:24 INFO - mozprocess == 0.23 04:25:24 INFO - mozprofile == 0.28 04:25:24 INFO - mozrunner == 6.12 04:25:24 INFO - mozscreenshot == 0.1 04:25:24 INFO - mozsystemmonitor == 0.3 04:25:24 INFO - moztest == 0.7 04:25:24 INFO - mozversion == 1.4 04:25:24 INFO - psutil == 3.1.1 04:25:24 INFO - requests == 1.2.3 04:25:24 INFO - wsgiref == 0.1.2 04:25:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:25:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:24 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 0x110659a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106ee618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89b157ee60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105bdd30>, '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 0x110322db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1105b5b20>, '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.OODW1ls8Dw/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.16aznVqw0d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 04:25:24 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 04:25:24 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 04:25:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OODW1ls8Dw/Render', 04:25:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:24 INFO - 'HOME': '/Users/cltbld', 04:25:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:24 INFO - 'LOGNAME': 'cltbld', 04:25:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:24 INFO - 'MOZ_NO_REMOTE': '1', 04:25:24 INFO - 'NO_EM_RESTART': '1', 04:25:24 INFO - 'PAGER': '/bin/cat', 04:25:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:24 INFO - 'PWD': '/builds/slave/test', 04:25:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:24 INFO - 'SHELL': '/bin/bash', 04:25:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.16aznVqw0d/Listeners', 04:25:24 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 04:25:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:24 INFO - 'USER': 'cltbld', 04:25:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:24 INFO - 'XPC_FLAGS': '0x0', 04:25:24 INFO - 'XPC_SERVICE_NAME': '0', 04:25:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:24 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)) 04:25:24 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)) 04:25:24 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)) 04:25:24 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)) 04:25:24 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)) 04:25:24 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)) 04:25:24 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)) 04:25:24 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)) 04:25:24 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)) 04:25:24 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)) 04:25:24 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)) 04:25:24 INFO - Cleaning up... 04:25:24 INFO - Return code: 0 04:25:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:25:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:24 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 0x110659a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106ee618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89b157ee60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105bdd30>, '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 0x110322db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1105b5b20>, '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.OODW1ls8Dw/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.16aznVqw0d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 04:25:24 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 04:25:24 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 04:25:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OODW1ls8Dw/Render', 04:25:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:24 INFO - 'HOME': '/Users/cltbld', 04:25:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:24 INFO - 'LOGNAME': 'cltbld', 04:25:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:24 INFO - 'MOZ_NO_REMOTE': '1', 04:25:24 INFO - 'NO_EM_RESTART': '1', 04:25:24 INFO - 'PAGER': '/bin/cat', 04:25:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:24 INFO - 'PWD': '/builds/slave/test', 04:25:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:24 INFO - 'SHELL': '/bin/bash', 04:25:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.16aznVqw0d/Listeners', 04:25:24 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 04:25:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:24 INFO - 'USER': 'cltbld', 04:25:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:24 INFO - 'XPC_FLAGS': '0x0', 04:25:24 INFO - 'XPC_SERVICE_NAME': '0', 04:25:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:25:24 INFO - Cleaning up... 04:25:24 INFO - Return code: 0 04:25:24 INFO - Running post-action listener: _resource_record_post_action 04:25:24 INFO - Running post-action listener: _start_resource_monitoring 04:25:24 INFO - Starting resource monitoring. 04:25:24 INFO - [mozharness: 2016-09-09 11:25:24.665437Z] Finished create-virtualenv step (success) 04:25:24 INFO - [mozharness: 2016-09-09 11:25:24.666490Z] Running install step. 04:25:24 INFO - Running pre-action listener: _resource_record_pre_action 04:25:24 INFO - Running main action method: install 04:25:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:25:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:25:24 INFO - Reading from file tmpfile_stdout 04:25:24 INFO - Detecting whether we're running mozinstall >=1.0... 04:25:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:25:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:25:25 INFO - Reading from file tmpfile_stdout 04:25:25 INFO - Output received: 04:25:25 INFO - Usage: mozinstall [options] installer 04:25:25 INFO - Options: 04:25:25 INFO - -h, --help show this help message and exit 04:25:25 INFO - -d DEST, --destination=DEST 04:25:25 INFO - Directory to install application into. [default: 04:25:25 INFO - "/builds/slave/test"] 04:25:25 INFO - --app=APP Application being installed. [default: firefox] 04:25:25 INFO - mkdir: /builds/slave/test/build/application 04:25:25 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'] 04:25:25 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 04:25:59 INFO - Reading from file tmpfile_stdout 04:25:59 INFO - Output received: 04:25:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:25:59 INFO - Running post-action listener: _resource_record_post_action 04:25:59 INFO - [mozharness: 2016-09-09 11:25:59.363859Z] Finished install step (success) 04:25:59 INFO - [mozharness: 2016-09-09 11:25:59.363997Z] Running run-tests step. 04:25:59 INFO - Running pre-action listener: _resource_record_pre_action 04:25:59 INFO - Running main action method: run_tests 04:25:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:25:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:25:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:25:59 INFO - 2016-09-09 04:25:59.378 screenresolution[1929:16872] starting screenresolution argv=screenresolution get 04:25:59 INFO - 2016-09-09 04:25:59.381 screenresolution[1929:16872] Display 0: 1600x1200x32@60 04:25:59 INFO - 2016-09-09 04:25:59.386 screenresolution[1930:16874] starting screenresolution argv=screenresolution list 04:25:59 INFO - Available Modes on Display 0 04:25:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:25:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:25:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:25:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:25:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:25:59 INFO - Intel Iris: 04:25:59 INFO - Chipset Model: Intel Iris 04:25:59 INFO - Type: GPU 04:25:59 INFO - Bus: Built-In 04:25:59 INFO - VRAM (Dynamic, Max): 1536 MB 04:25:59 INFO - Vendor: Intel (0x8086) 04:25:59 INFO - Device ID: 0x0a2e 04:25:59 INFO - Revision ID: 0x0009 04:25:59 INFO - Displays: 04:25:59 INFO - Display: 04:25:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:25:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:25:59 INFO - Display Serial Number: 1600x1200 60 04:25:59 INFO - Main Display: Yes 04:25:59 INFO - Mirror: Off 04:25:59 INFO - Online: Yes 04:25:59 INFO - Rotation: Supported 04:25:59 INFO - Return code: 0 04:25:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:25:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:25:59 INFO - Python 2.7.3 04:25:59 INFO - Return code: 0 04:25:59 INFO - grabbing minidump binary from tooltool 04:25:59 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')]} 04:25:59 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 0x1105bdd30>, '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 0x110322db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1105b5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:25:59 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 04:25:59 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 04:25:59 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 04:25:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:25:59 INFO - Return code: 0 04:25:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:25:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:25:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:25:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:25:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:25:59 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a-iNxkFGReS2qTY2phTuEw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0131', '--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 04:25:59 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/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/a-iNxkFGReS2qTY2phTuEw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0131 --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 04:25:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OODW1ls8Dw/Render', 04:25:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:59 INFO - 'HOME': '/Users/cltbld', 04:25:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:59 INFO - 'LOGNAME': 'cltbld', 04:25:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:25:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:25:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:59 INFO - 'MOZ_NO_REMOTE': '1', 04:25:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:25:59 INFO - 'NO_EM_RESTART': '1', 04:25:59 INFO - 'PAGER': '/bin/cat', 04:25:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:59 INFO - 'PWD': '/builds/slave/test', 04:25:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:25:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:59 INFO - 'SHELL': '/bin/bash', 04:25:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.16aznVqw0d/Listeners', 04:25:59 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 04:25:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:59 INFO - 'USER': 'cltbld', 04:25:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:59 INFO - 'XPC_FLAGS': '0x0', 04:25:59 INFO - 'XPC_SERVICE_NAME': '0', 04:25:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:59 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a-iNxkFGReS2qTY2phTuEw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0131', '--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 04:25:59 INFO - mozversion application_buildid: 20160909030022 04:25:59 INFO - mozversion application_changeset: d4060d4f4f6771608e0c4ee6fc62e430b6f244c0 04:25:59 INFO - mozversion application_display_name: Nightly 04:25:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:25:59 INFO - mozversion application_name: Firefox 04:25:59 INFO - mozversion application_remotingname: firefox 04:25:59 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:25:59 INFO - mozversion application_vendor: Mozilla 04:25:59 INFO - mozversion application_version: 51.0a1 04:25:59 INFO - mozversion platform_buildid: 20160909030022 04:25:59 INFO - mozversion platform_changeset: d4060d4f4f6771608e0c4ee6fc62e430b6f244c0 04:25:59 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:25:59 INFO - mozversion platform_version: 51.0a1 04:25:59 INFO - using testdate: 1473420359 04:25:59 INFO - actual date: 1473420359 04:25:59 INFO - starting webserver on 'localhost:54527' 04:25:59 INFO - SUITE-START | Running 2 tests 04:25:59 INFO - TEST-START | dromaeo_css 04:25:59 INFO - operating with platform_type : mac_ 04:25:59 INFO - Initialising browser for dromaeo_css test... 04:25:59 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpJWZ1Qg/profile http://localhost:54527/getInfo.html) 04:26:02 INFO - PROCESS | 1940 | __metrics Screen width/height:1600/1200 04:26:02 INFO - PROCESS | 1940 | colorDepth:24 04:26:02 INFO - PROCESS | 1940 | Browser inner width/height: 1024/689 04:26:02 INFO - PROCESS | 1940 | __metrics 04:26:02 INFO - PROCESS | 1940 | 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" 04:26:02 INFO - PROCESS | 1940 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:26:03 INFO - TEST-INFO | 1940: exit 0 04:26:03 INFO - Browser initialized. 04:26:03 INFO - Running cycle 1/1 for dromaeo_css test... 04:26:03 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpJWZ1Qg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:26:15 INFO - PROCESS | 1942 | RSS: Main: 232349696 04:26:15 INFO - PROCESS | 1942 | 04:27:06 INFO - PROCESS | 1942 | 1473420426711 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] 04:29:18 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:54527/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54527/tests/dromaeo/cssquery-ext.html) 04:29:19 INFO - PROCESS | 1942 | RSS: Main: 367136768 04:29:19 INFO - PROCESS | 1942 | 04:32:23 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:54527/tests/dromaeo/cssquery-ext.html (next: http://localhost:54527/tests/dromaeo/cssquery-jquery.html) 04:32:23 INFO - PROCESS | 1942 | RSS: Main: 368750592 04:32:23 INFO - PROCESS | 1942 | 04:35:25 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:54527/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54527/tests/dromaeo/cssquery-mootools.html) 04:35:26 INFO - PROCESS | 1942 | RSS: Main: 361410560 04:35:26 INFO - PROCESS | 1942 | 04:38:28 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:54527/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54527/tests/dromaeo/cssquery-prototype.html) 04:38:29 INFO - PROCESS | 1942 | RSS: Main: 343609344 04:38:29 INFO - PROCESS | 1942 | 04:41:32 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:54527/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54527/tests/dromaeo/cssquery-yui.html) 04:41:33 INFO - PROCESS | 1942 | RSS: Main: 332152832 04:41:33 INFO - PROCESS | 1942 | 04:44:36 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:54527/tests/dromaeo/cssquery-yui.html (next: http://localhost:54527/tests/dromaeo/cssquery-dojo.html) 04:44:37 INFO - PROCESS | 1942 | RSS: Main: 455446528 04:44:37 INFO - PROCESS | 1942 | 04:44:37 INFO - PROCESS | 1942 | __start_tp_report 04:44:37 INFO - PROCESS | 1942 | _x_x_mozilla_page_load 04:44:37 INFO - PROCESS | 1942 | _x_x_mozilla_page_load_details 04:44:37 INFO - PROCESS | 1942 | |i|pagename|runs| 04:44:37 INFO - PROCESS | 1942 | |0;dojo.html;2690.5426077566813;3315.8507867145977;3363.243270264191;3394.9321013579715;3428.417169081256;5594;5663.4336566343345;5667.8583035424135;5740.684262365568;5744.9138262926035;5676.687782171979;5726.3700992890745;5748.396418376063;5754.597178197539;5789.652620842752;5137.3064636274;5148.459411761767;5212.6611770235;5233;5240.921386179191;5301.920471192916;5306.8407947761625;5308.336457942757;5316.680999140064;5339.305890234265;5158.148905430599;5195.1687729963405;5197.58419326453;5241.213817927303;5268.736563171845;5221.556167725748;5238.1618940969265;5249.737513124367;5251.816186433494;5266.947330526684;5153.304303918961;5198.506141916512;5220.086484865134;5222.738863056832;5228.08508511009;5655.123455864347;5691.51622112121;5728.112142617901;5831.329397119343;5855.531557475391;6222.097795819581;6278.591891527082;6291.182146320949;6309.3690630937;6334.049892516114;6156.7994241123;6240.188775459206;6282.780102696383;6348.650824204667;6350.682465876687;5778.335491418498;5848.619839710405;5863.560232982543;5871.031279838779;5881.3530511643685;6265.760524909729;6309.432151106423;6318.241810982711;6338.8415289617205;6355.586886852339;5908.527317814564;5933.792317268875;6199.659018754011;6204.13142160098;6211.81364559064;6159.168512250879;6207.782727604512;6230.221222347207;6308.684565771785;6314.842128946813;6223.502119830403;6233.407826256498;6242.469390101892;6242.968785156045;6252.155958945484;6116.244470650255;6175.62946223228;6212.596181248295;6264.874702505925;6279.215098112736;4156;4896.5837903777865;4904.803807847647;4923.507649235119;4945.554900059012;4445.977770111193;4449.332600109945;4452.5992660660695;4482.193205223026;4526.547345265513;5745.827625171251;5748.109043098327;5751.913721294184;5765.481106700333;5781.624194427349;6101.671357587133;6144;6270.968645156836;6298.716557754912;6310.337414571496;5702.372738998707;5749.051406517961;5770.07678771403;5788.710564471759;5798.391168927286;5426.728663566806;5436.972815135899;5445.754941027584;5446.700431476307;5449.12813949774;6234.906476402948;6264.906026409516;6306.716197771019;6352.36476352361;6356.713947872357;6300.117983482408;6320.146780184617;6345;6347.365263473615;6349.936500634934;6328.398802113792;6339.524535659752;6349.682515874096;6351;6352.428281454691;5972.58191926561;6126.700892189639;6147.862645410613;6228.4705800007505;6236.407541283571;6281.780137695341;6309;6339.492840572835;6349.111124442674;6356.523260755369;6012.518998480198;6182.845428864134;6196.442320191024;6211.503079753518;6219.937800622117;5910.468057874812;6031.065184896348;6037.849053773691;6174.320824709189;6177.81466556004;5986.491148252448;5987;5996.640201587918;6024.03615421548;6048.092786082123;6108.694565271808;6141.9078713818435;6142.662153581596;6178.721957511832;6179.196704428576;5816.127580862904;5850.882982340415;5925.229720136527;5975.55183361241;5987.7604895805425 04:44:37 INFO - PROCESS | 1942 | |1;ext.html;503.44872364760585;521.9138842091055;521.9843404697859;523.596830440561;524.582956549543;30787;34584.481232781494;40743;41978.160436791244;42048.57951420485;43554.346684799755;43727.56272437274;44036;44059.55940440595;44121.55878441215;8619.870701939475;9695.224382049437;9902.455364954925;9904.801903961952;9942.950285248564;8609.225169734722;8776.297896168291;8780.604872780741;8785.165409286139;11758.941205294004;11820.408979551032;12098.637040888743;12098.758024839499;12121.575744848888;12160.331181784999;12082.758344833099;12162.391880406032;12165.817512737316;12183.329916854525;12191.695207619792;11929.28424294545;12002.819957700642;12004.279743215435;12006.69983250417;12014.69963250917;41016;41409.96475088117;41418.792906035276;41519.58480415188;41653;3279.3277378137423;3334.583177102862;3461.238527523941;3818.732688711791;4181.954511372157;24943.50112997748;32078.198045048826;32153.03540893777;32229.677703223137;32319.353612927844;3283.3925723740945;3299.5875515560556;3310.619278782947;3389.694927456516;3394.915127121817;456685;457154;459346;460360;461468;59046.81906361935;59378.51553711192;59475.10787338107;59566.70216648889;59663;63222;63295;63371;63480.682596586725;63566.36433635697;16171;16396.590085247964;16411.671766564603;16450.75323870143;16480;12803.359832008362;12863.099583029098;12865.228086314852;12899.355032248537;12899.677508062186;13980.72038559223;14634.853651463562;15264.313105910262;16826.83173168277;17140.742888856676;4163.577592598082;5442.564594832404;6070.8768877756875;6301.4328710416285;6309.305976342599;850.81311570359;898.9910100899039;937.4422218779815;948.8339540580371;1038.5118994072773;28160.071987402123;29749.256268593457;29859;30344.54483182754;30595;994.9104580587782;1008.969730908074;1054.1566746602841;1153.7058050197145;1199.598134624893;14606;15503.457378991681;15550.455734049254;15685.137317447421;15991.60020999461;595753;596376;597594;599749;601539;46574.76712616483;49126.26310605344;49382.765430864514;56467.71766141225;58397.12404313939;1105.6019832860163;1314.3691028306466;1364.8498665146826;1366.2417358365806;1373.9656508587366;29541.704582954757;31334.686653133176;31381.372372552876;31425.057248281675;31981.040568782973;43044.35433468438;51265.23102153396;51910.74044629904;51920;52046.739766300925;515281;523983;527357.3632131815;529488.2307061589;532236;24625;26591.33521661974;26635.46729065369;33946;40087;48088.519114808405;48761;49114;49228.753856231924;49279.76800579871;38355.424668629435;39224.823255302384;39236.2152756949;40241.78586390535;40893;39182.80408597939;39481.2103757929;39728.80813575597;40263.39604905988;40910.59089409068;37687.43468848024;38274;38447.423288650134;38775;39004;32450.83774581112;34548.13629659178;34962.65037349594;35021.64978350184;35349;25756.840942157272;25800.354991125372;25816.354591135372;25881.96472141093;26076.34809129787 04:44:37 INFO - PROCESS | 1942 | |2;jquery.html;302.7638442015229;364.66450865204007;367.08229426433917;378.62894363523736;398.97805620690866;14985.850141498595;15411.768823467643;20384.286549970755;20527.384178474633;21134.89432552839;19976.00119993998;20587;21130.260440884573;21277.468063298424;21357.038933248;4735.952640473585;5930.199423077873;6203.2245969253845;6204.4105809948205;6209.441150296472;6738.831529211784;6883.724651013953;6918.584884906922;6920.75777347793;6941.479389045841;6258.1551490548645;6536.803895883131;6814.4889133314955;6819.1135152430115;6846.965765171192;5691.971540142294;6485.156929599169;6764.661766911635;6782.830429239284;6795.218549866754;5850.590458667895;5893.0276504376725;5903.557233207526;5914.112883067532;5914.4676979071655;22706;25182.748172518222;25774;25889.870550647316;25909.093181738735;2223.8220942324574;2235.5752407042773;2324.93025209244;2379.0721618568773;2877.208767588909;15185.240737963057;15928.283227254802;15953.202339882959;16167.514974550782;16388.6722265554;1789.2127463915836;1809.3576780242956;1838.6322735452964;1935.4290484307023;1935.4387227703949;115343;117956.82043179544;118578;118888;119272.40363798212;9609;10647.414392208502;10799.190060745319;10929.398883061507;11008.229423940247;13947.721045579234;13953.953453490825;13957;13974.021818472609;13979.231142287068;8430.426425012754;8706.564671766386;8724.738257852274;8860.3797734158;8883.511406872554;8719;8719.215270625547;8746.344024198082;8911.331650126267;8913.24237439812;6625.469962402996;6751.223609284897;6768.120144381199;6778.864422711518;6887.001384799176;1808.6834803909264;1838.3810354396373;1849.426677729881;1856.5358660334916;1902.914368853405;852.9104444033411;880.3573391424168;900.9773906616037;902.4495058014605;955.9102623009775;21186.15255389767;22677.43306417353;22727.204547841076;22767.203147890075;22789.202377916692;845.6828689241535;948.3645957208687;1014.2393205096178;1015.4262841494532;1019.887812340642;9833.852492212622;11743.804120633058;11985.400729963467;12166;12190.207636503776;410423;496254;525623;551364;552447;108384;114824.42587787175;116591.41704291594;117754.99999999828;119696;830.8006078541227;975.3806332978468;1031.5770534080991;1033.452270296744;1033.850091736694;18957;19060.714089288394;19346.4196074118;19350.90324548368;19431.4170574883;41269.793651031556;49585;50279.49720502748;50962.490375095775;51909;57195;57608.71195643995;73425.63287183744;74496;74969;7118.928810712034;7286.077659870418;7432.145303289974;7433.219511951167;7674.270944260286;12580.370981450707;13238.404271807984;13279.933600331937;13695.863041369857;13716.862831371958;10728;10962;11133.443327833738;11345.716357090809;11664.008559272557;11323.377214253294;11381.260218085798;11474.77050459003;11613.419329033346;11777.352245626573;11171.832422513831;11552.59565915189;11893;12105.213161144497;12138.393080346124;7339.633018349168;7432.516886402366;7529.4729368943645;7542.358899493385;7661.314510807613;10439.269251152346;11223.326600404002;11354.886451135382;11813.40932953366;11828.822567661664 04:44:37 INFO - PROCESS | 1942 | |3;mootools.html;1345.2937207965822;1501.527018989018;1533.7162624914392;1731.0911771320054;1899.7340372347874;7817.3355264802485;8575.828483430336;8905.821883562325;8913.108689131084;9050.140236677522;7484.887726684091;8877.289816814655;8884.64461421544;8884.73345799627;8891.466512009283;2395.1497218487402;2421.4309637235237;2421.903123875043;2425.9029638814427;2435.1355268879556;2001.2595339724323;2360.9881950590225;2412.95174096518;2415.7221919479307;2420.515896820634;2366.857988520694;2396.2212281008656;2398.5442765874513;2417.746136655652;2419.9395015124674;2358.068562917638;2388.295452841416;2388.605880029793;2389.940251493709;2399.3281881073153;2345.7302410222874;2365.053978408633;2404.0864471500718;2407.5546023985444;2408.8313818032743;8754.781130471725;8829.028806831244;8938.597763100675;8943.955280223558;8949.015608283084;5301.840944771663;5526.198701188305;5531.3362396512675;5535.944640553583;5539.750711217965;3025.5764193012997;3690.926181476382;3710.9443358349645;3758.755680880734;3773.698074164409;1059.390850261103;1067.7223921780394;1070.138538476526;1073.6725298783867;1086.277625379122;11722.35527046012;11780.057595392347;11856.644300670994;11858.40707964607;11895.643130706092;4113;4231.534531201565;4551.931721024187;4636.582707556337;4647.651426143053;9206.125418085272;9213.81572368549;9214;9218.953905230564;9231.446113233227;5287.365516138088;5299.2316114164005;5300.92048619271;5301;5301.1783173608155;4451.132029254329;4608.976955115203;4805.519448055166;4932.062908047459;4990.051890140861;3721.181340105227;3756.7745935243975;3833.4824798652385;3849.6920246380223;3877.495925529663;1157.6989982604584;1166.9591564295208;1167.3229526874393;1183.6567395455393;1185.1940680337282;828.0891019878206;871.7079778274223;878.2710350409145;883.4655033704645;912.885889263842;5734.283214598176;5801.593888427769;5906.5570082244;6108.350745298744;6200.038993955944;780.3601047141403;856.1096459681986;914.5381582300895;933.4772527384687;935.9859602105973;3173.984130079381;3787.772733635991;3928.5678575356687;3931.5478719947;4063.3701776224734;10644.254902156774;11109.666709998713;11121.054710349548;11123.944380278046;11143.888561114447;11055.778884422267;11062.004419602274;11091.33451992883;11102.944485277521;11134;8317;8423.663053477792;8430.747077587683;8437.240648341556;8443.873341899876;5625.353084395347;5808.854778630568;5942.465178133989;5995.46040856325;6017.187679663364;9945.254105941938;10234.795304093728;10244.948775256076;10278.948605256926;10297.279190456597;10437;10445.216608754221;10508.316959397614;10572.259941804308;10587.523561439599;6537.0521274413995;6712.060311556484;6722.361375669304;6984.965075174591;7140;7926;7945.48354356965;8295.917040829514;8442.873356899774;8592.828143437222;7513.248675132443;7597.3162415382885;7757.806054848674;7764.805879852823;7765.650545725569;7569.6593653284635;7603.65783539731;7709.2290770922455;7773.37812974972;7806.414518911218;6974.302569743188;7106.5736055836815;7109.253528379652;7117.395021423237;7118.85762284741;4305.48334199898;4454.376387305844;5028.446870844277;5234.842954711365;5374.978754036721;4922.72924989129;5035.798568057383;5119.078565858182;5236.2407450918645;5248.580113590827 04:44:37 INFO - PROCESS | 1942 | |4;prototype.html;289.74068208952986;296.1840130440637;305.4029372576613;309.12825831156147;309.17604583784214;4828.299896515004;5140.408852981908;5216.478352164781;5264.39459462162;5293.920591191135;4054.797260136996;4771.308160316752;4925.704457732539;5084.771185296661;5283.365996080466;3800.6959443244473;3832.1186127190763;3944.9032509480135;3949.7037722170808;3978.3037968355566;3277.8688852445875;3974;3985.900352491182;4024.2555127301544;4030.858919937803;3231.6122065352192;3689.723270754691;3950.980245098785;3959.742616729903;3960.2871483133026;3651.6713495785243;3940.6650434713083;3963.5136823691114;3965.781881996489;4026.6913253192683;3229.7093261606333;3372.730181585467;3426.1605906552804;3426.3489936911906;3447.637998010198;4208.326667733148;5052.216906379517;5065.265536497187;5065.96042374576;5086.593072554187;4330.610245077958;4403.895253846031;4466.464024317071;4479.126570318789;4560.817567297304;5419.991904493005;5529.336479622471;5533.612647114704;5559.193916882029;5571.303587051619;3149.6062992125985;3744.2698673758355;3744.4383342498572;3753.380692185786;3759.8120093995462;5033.84898453047;5630.408807075239;5643.463870932255;5707.77168913243;5726.284214473191;3373.4602463605706;3792.7913964732206;3793.8103094845146;3801.9809900950872;3809.790461524643;3832.5784163741964;3851.5378154621626;3860.903477413031;3874.5350557932666;3885.7279990400402;4131.232074510085;4171.770552619574;4513.864584062483;4585.174668559691;4613.19269127908;5554.250176226238;5568.693721845338;5569.192467092249;5569.248151499577;5609.859753506195;1297.8702129786946;1311.1674086954852;1316.453671726242;1317.8748018938186;1323.9536616218386;733.7101844771286;821.2157389692829;839.878761852921;847.7329641163137;885.1281487734576;5111.361079865017;5130.897382052338;5146.330976973044;5149.716765577929;5184.6299905062015;5583.664980101125;5593.510887654955;5670.858228544319;5685.005124103265;5687.459691329317;4473.351364052194;4783.043391321749;5067.746612669352;5088.185890257541;5107.591392688576;2471.43157073874;2937.3390987028174;2945.8527073646233;2955.1725516855313;2965.339409930446;5150.660828184624;5534.395025442576;5782.855428614234;5790.40994583361;5796.666766643695;4721.858344249678;5289.3917199521775;5311.362636483646;5697.4587414195585;5714.05718056516;4838.516148385203;5034.14419548685;5095.974520127375;5692.402297758676;5813.494541730193;4246.915061698687;4969.4782047884455;5249.737513124405;5590.832275031756;5599.556272892223;5472.2065300531995;5489.423610520837;5507.540501767115;5528.226048353154;5548.278723765885;4770.640367495247;5580.720963951868;5670.971645141748;5742.483176514133;5742.7702891883855;3426.5374174486124;3549.7515173937577;3712.9628703712615;3719.8698045568276;3737.6921424538937;4608.88477788058;5533.5849811263515;5550;5571.183739138703;5589.720513974366;5164.561012313995;5385.488378604026;5400.324959380077;5411.510705075551;5457.672539647634;4254.149444991315;4549.45406551216;5124.85148613706;5342.732863356894;5412.242286079873;4484.529124442017;5187.896242075062;5276.973615131899;5299.5760339172;5313.574914006947;4626.606738427271;4965.602751779921;5324.840254792362;5487.341519017744;5517.641353312022;4983.476734942778;5383.5962302826665;5385.057614917452;5403.919490996073;5411.2965314508865 04:44:37 INFO - PROCESS | 1942 | |5;yui.html;613.7667686279216;643.7103303513419;780.0756104016741;801.6946714093654;813;14872.553823385302;16059.437919672813;16095.195240237985;16098.114603696822;16116.83883161168;14592.708145837078;14750.778738318906;14759.77860332096;14769.335379907903;14772.6306842329;654.9672516374162;820.950742955423;825.4428260923852;839.6557411461303;841.9938173882214;831.7463173732009;832.0015980823007;833.2875391540207;833.6165363932598;834.015861283685;880.106691707917;882.6160620130232;882.7351794461636;883.4390162246988;887.8490656588395;690.4614400767384;867.1328671328671;869.678219058946;878.0253918150847;882.2412725056446;668.5119862500353;668.548729607513;669.7086767256259;670.1589505171028;670.3263220463451;14773.926130369387;14820.036697614729;14823.407063717439;14830.62923426923;14836.480723174636;11778.764424711459;13934.442622295095;14046.367913443817;14076.718465630733;14212.502562410265;10669.573217071307;11341.489632966453;12004.519819207222;12355.938220308932;12662.366881655973;4608.216603177468;4774.021325628272;4806;4808.951910480921;4824.445188803263;87416;100759;108896;109294.3605845913;110066.44966775195;8763.868541971748;9182.35723499362;10621.681349559525;10667.519961601747;11129.777404451866;20370.388888333375;20643.27748528794;20686.79313206879;20746.27388041411;20966.685499717518;7474.215207403145;7515.699372025167;7522.435817313723;7585.279398457138;7717.2668596483245;4099.85650502237;4119.423280740639;4137.91724165521;4149.336106223051;4158.355454904495;3331.833408329574;3479.2171761353998;3491.5984661763714;3492.9126771830906;3495.2485215678753;592.1413949772847;638.4030931079456;643.4080645805869;651.081974416071;661.0018871503968;571.3401021819805;615.3385111005687;639.4085470939362;675.5811396933834;709.5565271705184;9008.864867027;9163.266938644892;9628.951855240679;9638.180754635934;9719.17387022111;543.2882923370397;571.2174321179926;629.3769168523221;655.9836004099935;678.35217367414;3744.513213282256;4460.264056430718;4579.771011449414;4637.628989680885;4668.439787225555;137675;142501.28749356396;148322.51677483303;149044.25477872757;150744.2462787701;91711.08288917025;130874;141695;144245.27877360547;145507;69319.65340173368;90979;98896;101013.98986010192;108829.45585272023;9340.81318373615;9963.900360996297;10005.799884002425;10152.137068349273;10169.491525423551;59146.40853591581;60171.69914150401;60177.69911150416;60610.060346077364;61119;88168.55915720637;92711.53644231736;93745.5312723432;93771.53114234385;94230;13085;13126.540571079966;13147.802782958455;13605.455781769015;13788.793168102286;14519.346629401913;14685.55943321702;14807.555773326818;14812.407503699731;15096.24518774035;11375.886241137814;13811.654708631962;13824.032317737661;13971.720565588834;14320.856791431952;9853.95073024659;13302.334883255991;13467.704536274645;13540.729185416434;13722.588322350346;13319.933400332937;13673.726525469236;13799.03406761517;13894.513692020731;14181.361838717488;10819.18856085781;11224.045956093823;11239.775204495701;11408.38510855441;11743.355930169611;10067.446290454096;10340.482975851328;11269.887301126884;11400.429978501208;11522.76954460923 04:44:37 INFO - PROCESS | 1942 | __end_tp_report 04:44:37 INFO - PROCESS | 1942 | __start_cc_report 04:44:37 INFO - PROCESS | 1942 | _x_x_mozilla_cycle_collect,1207 04:44:37 INFO - PROCESS | 1942 | __end_cc_report 04:44:37 INFO - PROCESS | 1942 | __startTimestamp1473421477063__endTimestamp 04:44:37 INFO - PROCESS | 1942 | 04:44:37 INFO - PROCESS | 1942 | ------- Summary: start ------- 04:44:37 INFO - PROCESS | 1942 | Number of tests: 6 04:44:37 INFO - PROCESS | 1942 | 04:44:37 INFO - PROCESS | 1942 | [#0] dojo.html Cycles:165 Average:5765.77 Median:5975.55 stddev:666.08 (11.1%) stddev-sans-first:622.90 04:44:37 INFO - PROCESS | 1942 | Values: 2690.5 3315.9 3363.2 3394.9 3428.4 5594.0 5663.4 5667.9 5740.7 5744.9 5676.7 5726.4 5748.4 5754.6 5789.7 5137.3 5148.5 5212.7 5233.0 5240.9 5301.9 5306.8 5308.3 5316.7 5339.3 5158.1 5195.2 5197.6 5241.2 5268.7 5221.6 5238.2 5249.7 5251.8 5266.9 5153.3 5198.5 5220.1 5222.7 5228.1 5655.1 5691.5 5728.1 5831.3 5855.5 6222.1 6278.6 6291.2 6309.4 6334.0 6156.8 6240.2 6282.8 6348.7 6350.7 5778.3 5848.6 5863.6 5871.0 5881.4 6265.8 6309.4 6318.2 6338.8 6355.6 5908.5 5933.8 6199.7 6204.1 6211.8 6159.2 6207.8 6230.2 6308.7 6314.8 6223.5 6233.4 6242.5 6243.0 6252.2 6116.2 6175.6 6212.6 6264.9 6279.2 4156.0 4896.6 4904.8 4923.5 4945.6 4446.0 4449.3 4452.6 4482.2 4526.5 5745.8 5748.1 5751.9 5765.5 5781.6 6101.7 6144.0 6271.0 6298.7 6310.3 5702.4 5749.1 5770.1 5788.7 5798.4 5426.7 5437.0 5445.8 5446.7 5449.1 6234.9 6264.9 6306.7 6352.4 6356.7 6300.1 6320.1 6345.0 6347.4 6349.9 6328.4 6339.5 6349.7 6351.0 6352.4 5972.6 6126.7 6147.9 6228.5 6236.4 6281.8 6309.0 6339.5 6349.1 6356.5 6012.5 6182.8 6196.4 6211.5 6219.9 5910.5 6031.1 6037.8 6174.3 6177.8 5986.5 5987.0 5996.6 6024.0 6048.1 6108.7 6141.9 6142.7 6178.7 6179.2 5816.1 5850.9 5925.2 5975.6 5987.8 04:44:37 INFO - PROCESS | 1942 | 04:44:37 INFO - PROCESS | 1942 | [#1] ext.html Cycles:180 Average:67003.01 Median:38889.50 stddev:141364.97 (363.5%) stddev-sans-first:141673.37 04:44:37 INFO - PROCESS | 1942 | Values: 503.4 521.9 522.0 523.6 524.6 30787.0 34584.5 40743.0 41978.2 42048.6 43554.3 43727.6 44036.0 44059.6 44121.6 8619.9 9695.2 9902.5 9904.8 9943.0 8609.2 8776.3 8780.6 8785.2 11758.9 11820.4 12098.6 12098.8 12121.6 12160.3 12082.8 12162.4 12165.8 12183.3 12191.7 11929.3 12002.8 12004.3 12006.7 12014.7 41016.0 41410.0 41418.8 41519.6 41653.0 3279.3 3334.6 3461.2 3818.7 4182.0 24943.5 32078.2 32153.0 32229.7 32319.4 3283.4 3299.6 3310.6 3389.7 3394.9 456685.0 457154.0 459346.0 460360.0 461468.0 59046.8 59378.5 59475.1 59566.7 59663.0 63222.0 63295.0 63371.0 63480.7 63566.4 16171.0 16396.6 16411.7 16450.8 16480.0 12803.4 12863.1 12865.2 12899.4 12899.7 13980.7 14634.9 15264.3 16826.8 17140.7 4163.6 5442.6 6070.9 6301.4 6309.3 850.8 899.0 937.4 948.8 1038.5 28160.1 29749.3 29859.0 30344.5 30595.0 994.9 1009.0 1054.2 1153.7 1199.6 14606.0 15503.5 15550.5 15685.1 15991.6 595753.0 596376.0 597594.0 599749.0 601539.0 46574.8 49126.3 49382.8 56467.7 58397.1 1105.6 1314.4 1364.8 1366.2 1374.0 29541.7 31334.7 31381.4 31425.1 31981.0 43044.4 51265.2 51910.7 51920.0 52046.7 515281.0 523983.0 527357.4 529488.2 532236.0 24625.0 26591.3 26635.5 33946.0 40087.0 48088.5 48761.0 49114.0 49228.8 49279.8 38355.4 39224.8 39236.2 40241.8 40893.0 39182.8 39481.2 39728.8 40263.4 40910.6 37687.4 38274.0 38447.4 38775.0 39004.0 32450.8 34548.1 34962.7 35021.6 35349.0 25756.8 25800.4 25816.4 25882.0 26076.3 04:44:37 INFO - PROCESS | 1942 | 04:44:37 INFO - PROCESS | 1942 | [#2] jquery.html Cycles:180 Average:32071.67 Median:22778.20 stddev:85470.17 (375.2%) stddev-sans-first:85676.65 04:44:37 INFO - PROCESS | 1942 | Values: 302.8 364.7 367.1 378.6 399.0 14985.9 15411.8 20384.3 20527.4 21134.9 19976.0 20587.0 21130.3 21277.5 21357.0 4736.0 5930.2 6203.2 6204.4 6209.4 6738.8 6883.7 6918.6 6920.8 6941.5 6258.2 6536.8 6814.5 6819.1 6847.0 5692.0 6485.2 6764.7 6782.8 6795.2 5850.6 5893.0 5903.6 5914.1 5914.5 22706.0 25182.7 25774.0 25889.9 25909.1 2223.8 2235.6 2324.9 2379.1 2877.2 15185.2 15928.3 15953.2 16167.5 16388.7 1789.2 1809.4 1838.6 1935.4 1935.4 115343.0 117956.8 118578.0 118888.0 119272.4 9609.0 10647.4 10799.2 10929.4 11008.2 13947.7 13954.0 13957.0 13974.0 13979.2 8430.4 8706.6 8724.7 8860.4 8883.5 8719.0 8719.2 8746.3 8911.3 8913.2 6625.5 6751.2 6768.1 6778.9 6887.0 1808.7 1838.4 1849.4 1856.5 1902.9 852.9 880.4 901.0 902.4 955.9 21186.2 22677.4 22727.2 22767.2 22789.2 845.7 948.4 1014.2 1015.4 1019.9 9833.9 11743.8 11985.4 12166.0 12190.2 410423.0 496254.0 525623.0 551364.0 552447.0 108384.0 114824.4 116591.4 117755.0 119696.0 830.8 975.4 1031.6 1033.5 1033.9 18957.0 19060.7 19346.4 19350.9 19431.4 41269.8 49585.0 50279.5 50962.5 51909.0 57195.0 57608.7 73425.6 74496.0 74969.0 7118.9 7286.1 7432.1 7433.2 7674.3 12580.4 13238.4 13279.9 13695.9 13716.9 10728.0 10962.0 11133.4 11345.7 11664.0 11323.4 11381.3 11474.8 11613.4 11777.4 11171.8 11552.6 11893.0 12105.2 12138.4 7339.6 7432.5 7529.5 7542.4 7661.3 10439.3 11223.3 11354.9 11813.4 11828.8 04:44:37 INFO - PROCESS | 1942 | 04:44:37 INFO - PROCESS | 1942 | [#3] mootools.html Cycles:180 Average:5734.14 Median:4961.06 stddev:3257.38 (65.7%) stddev-sans-first:3249.82 04:44:37 INFO - PROCESS | 1942 | Values: 1345.3 1501.5 1533.7 1731.1 1899.7 7817.3 8575.8 8905.8 8913.1 9050.1 7484.9 8877.3 8884.6 8884.7 8891.5 2395.1 2421.4 2421.9 2425.9 2435.1 2001.3 2361.0 2413.0 2415.7 2420.5 2366.9 2396.2 2398.5 2417.7 2419.9 2358.1 2388.3 2388.6 2389.9 2399.3 2345.7 2365.1 2404.1 2407.6 2408.8 8754.8 8829.0 8938.6 8944.0 8949.0 5301.8 5526.2 5531.3 5535.9 5539.8 3025.6 3690.9 3710.9 3758.8 3773.7 1059.4 1067.7 1070.1 1073.7 1086.3 11722.4 11780.1 11856.6 11858.4 11895.6 4113.0 4231.5 4551.9 4636.6 4647.7 9206.1 9213.8 9214.0 9219.0 9231.4 5287.4 5299.2 5300.9 5301.0 5301.2 4451.1 4609.0 4805.5 4932.1 4990.1 3721.2 3756.8 3833.5 3849.7 3877.5 1157.7 1167.0 1167.3 1183.7 1185.2 828.1 871.7 878.3 883.5 912.9 5734.3 5801.6 5906.6 6108.4 6200.0 780.4 856.1 914.5 933.5 936.0 3174.0 3787.8 3928.6 3931.5 4063.4 10644.3 11109.7 11121.1 11123.9 11143.9 11055.8 11062.0 11091.3 11102.9 11134.0 8317.0 8423.7 8430.7 8437.2 8443.9 5625.4 5808.9 5942.5 5995.5 6017.2 9945.3 10234.8 10244.9 10278.9 10297.3 10437.0 10445.2 10508.3 10572.3 10587.5 6537.1 6712.1 6722.4 6985.0 7140.0 7926.0 7945.5 8295.9 8442.9 8592.8 7513.2 7597.3 7757.8 7764.8 7765.7 7569.7 7603.7 7709.2 7773.4 7806.4 6974.3 7106.6 7109.3 7117.4 7118.9 4305.5 4454.4 5028.4 5234.8 5375.0 4922.7 5035.8 5119.1 5236.2 5248.6 04:44:37 INFO - PROCESS | 1942 | 04:44:37 INFO - PROCESS | 1942 | [#4] prototype.html Cycles:180 Average:4407.59 Median:5043.18 stddev:1351.65 (26.8%) stddev-sans-first:1319.63 04:44:37 INFO - PROCESS | 1942 | Values: 289.7 296.2 305.4 309.1 309.2 4828.3 5140.4 5216.5 5264.4 5293.9 4054.8 4771.3 4925.7 5084.8 5283.4 3800.7 3832.1 3944.9 3949.7 3978.3 3277.9 3974.0 3985.9 4024.3 4030.9 3231.6 3689.7 3951.0 3959.7 3960.3 3651.7 3940.7 3963.5 3965.8 4026.7 3229.7 3372.7 3426.2 3426.3 3447.6 4208.3 5052.2 5065.3 5066.0 5086.6 4330.6 4403.9 4466.5 4479.1 4560.8 5420.0 5529.3 5533.6 5559.2 5571.3 3149.6 3744.3 3744.4 3753.4 3759.8 5033.8 5630.4 5643.5 5707.8 5726.3 3373.5 3792.8 3793.8 3802.0 3809.8 3832.6 3851.5 3860.9 3874.5 3885.7 4131.2 4171.8 4513.9 4585.2 4613.2 5554.3 5568.7 5569.2 5569.2 5609.9 1297.9 1311.2 1316.5 1317.9 1324.0 733.7 821.2 839.9 847.7 885.1 5111.4 5130.9 5146.3 5149.7 5184.6 5583.7 5593.5 5670.9 5685.0 5687.5 4473.4 4783.0 5067.7 5088.2 5107.6 2471.4 2937.3 2945.9 2955.2 2965.3 5150.7 5534.4 5782.9 5790.4 5796.7 4721.9 5289.4 5311.4 5697.5 5714.1 4838.5 5034.1 5096.0 5692.4 5813.5 4246.9 4969.5 5249.7 5590.8 5599.6 5472.2 5489.4 5507.5 5528.2 5548.3 4770.6 5580.7 5671.0 5742.5 5742.8 3426.5 3549.8 3713.0 3719.9 3737.7 4608.9 5533.6 5550.0 5571.2 5589.7 5164.6 5385.5 5400.3 5411.5 5457.7 4254.1 4549.5 5124.9 5342.7 5412.2 4484.5 5187.9 5277.0 5299.6 5313.6 4626.6 4965.6 5324.8 5487.3 5517.6 4983.5 5383.6 5385.1 5403.9 5411.3 04:44:37 INFO - PROCESS | 1942 | 04:44:37 INFO - PROCESS | 1942 | [#5] yui.html Cycles:180 Average:24127.48 Median:3922.18 stddev:38392.69 (978.9%) stddev-sans-first:38459.79 04:44:37 INFO - PROCESS | 1942 | Values: 613.8 643.7 780.1 801.7 813.0 14872.6 16059.4 16095.2 16098.1 16116.8 14592.7 14750.8 14759.8 14769.3 14772.6 655.0 821.0 825.4 839.7 842.0 831.7 832.0 833.3 833.6 834.0 880.1 882.6 882.7 883.4 887.8 690.5 867.1 869.7 878.0 882.2 668.5 668.5 669.7 670.2 670.3 14773.9 14820.0 14823.4 14830.6 14836.5 11778.8 13934.4 14046.4 14076.7 14212.5 10669.6 11341.5 12004.5 12355.9 12662.4 4608.2 4774.0 4806.0 4809.0 4824.4 87416.0 100759.0 108896.0 109294.4 110066.4 8763.9 9182.4 10621.7 10667.5 11129.8 20370.4 20643.3 20686.8 20746.3 20966.7 7474.2 7515.7 7522.4 7585.3 7717.3 4099.9 4119.4 4137.9 4149.3 4158.4 3331.8 3479.2 3491.6 3492.9 3495.2 592.1 638.4 643.4 651.1 661.0 571.3 615.3 639.4 675.6 709.6 9008.9 9163.3 9629.0 9638.2 9719.2 543.3 571.2 629.4 656.0 678.4 3744.5 4460.3 4579.8 4637.6 4668.4 137675.0 142501.3 148322.5 149044.3 150744.2 91711.1 130874.0 141695.0 144245.3 145507.0 69319.7 90979.0 98896.0 101014.0 108829.5 9340.8 9963.9 10005.8 10152.1 10169.5 59146.4 60171.7 60177.7 60610.1 61119.0 88168.6 92711.5 93745.5 93771.5 94230.0 13085.0 13126.5 13147.8 13605.5 13788.8 14519.3 14685.6 14807.6 14812.4 15096.2 11375.9 13811.7 13824.0 13971.7 14320.9 9854.0 13302.3 13467.7 13540.7 13722.6 13319.9 13673.7 13799.0 13894.5 14181.4 10819.2 11224.0 11239.8 11408.4 11743.4 10067.4 10340.5 11269.9 11400.4 11522.8 04:44:37 INFO - PROCESS | 1942 | -------- Summary: end -------- 04:44:37 INFO - PROCESS | 1942 | 04:44:37 INFO - TEST-INFO | 1942: exit 0 04:44:37 INFO - TEST-OK | dromaeo_css | took 1117710ms 04:44:37 INFO - TEST-START | kraken 04:44:37 INFO - operating with platform_type : mac_ 04:44:37 INFO - Initialising browser for kraken test... 04:44:37 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpYfSZKq/profile http://localhost:54527/getInfo.html) 04:44:40 INFO - PROCESS | 1946 | __metrics Screen width/height:1600/1200 04:44:40 INFO - PROCESS | 1946 | colorDepth:24 04:44:40 INFO - PROCESS | 1946 | Browser inner width/height: 1024/689 04:44:40 INFO - PROCESS | 1946 | __metrics 04:44:40 INFO - PROCESS | 1946 | 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" 04:44:40 INFO - PROCESS | 1946 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:44:40 INFO - TEST-INFO | 1946: exit 0 04:44:40 INFO - Browser initialized. 04:44:40 INFO - Running cycle 1/1 for kraken test... 04:44:40 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpYfSZKq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:44:52 INFO - PROCESS | 1947 | RSS: Main: 232427520 04:44:52 INFO - PROCESS | 1947 | 04:44:55 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:54527/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54527/tests/kraken/driver.html?testName=audio-beat-detection) 04:44:56 INFO - PROCESS | 1947 | RSS: Main: 291061760 04:44:56 INFO - PROCESS | 1947 | 04:44:58 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:54527/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54527/tests/kraken/driver.html?testName=audio-dft) 04:44:59 INFO - PROCESS | 1947 | RSS: Main: 287637504 04:44:59 INFO - PROCESS | 1947 | 04:45:02 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:54527/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54527/tests/kraken/driver.html?testName=audio-fft) 04:45:03 INFO - PROCESS | 1947 | RSS: Main: 377700352 04:45:03 INFO - PROCESS | 1947 | 04:45:05 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:54527/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54527/tests/kraken/driver.html?testName=audio-oscillator) 04:45:06 INFO - PROCESS | 1947 | RSS: Main: 280956928 04:45:06 INFO - PROCESS | 1947 | 04:45:08 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:54527/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54527/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:45:09 INFO - PROCESS | 1947 | RSS: Main: 284946432 04:45:09 INFO - PROCESS | 1947 | 04:45:11 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:54527/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54527/tests/kraken/driver.html?testName=imaging-darkroom) 04:45:12 INFO - PROCESS | 1947 | RSS: Main: 322551808 04:45:12 INFO - PROCESS | 1947 | 04:45:15 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:54527/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54527/tests/kraken/driver.html?testName=imaging-desaturate) 04:45:15 INFO - PROCESS | 1947 | RSS: Main: 323481600 04:45:15 INFO - PROCESS | 1947 | 04:45:18 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:54527/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54527/tests/kraken/driver.html?testName=json-parse-financial) 04:45:19 INFO - PROCESS | 1947 | RSS: Main: 323117056 04:45:19 INFO - PROCESS | 1947 | 04:45:20 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:54527/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54527/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:45:21 INFO - PROCESS | 1947 | RSS: Main: 298942464 04:45:21 INFO - PROCESS | 1947 | 04:45:23 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:54527/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54527/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:45:24 INFO - PROCESS | 1947 | RSS: Main: 305225728 04:45:24 INFO - PROCESS | 1947 | 04:45:26 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:54527/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54527/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:45:26 INFO - PROCESS | 1947 | RSS: Main: 307400704 04:45:26 INFO - PROCESS | 1947 | 04:45:29 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:54527/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54527/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:45:30 INFO - PROCESS | 1947 | RSS: Main: 320049152 04:45:30 INFO - PROCESS | 1947 | 04:45:33 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:54527/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54527/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:45:34 INFO - PROCESS | 1947 | RSS: Main: 315887616 04:45:34 INFO - PROCESS | 1947 | 04:45:35 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:54527/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54527/tests/kraken/driver.html?testName=ai-astar) 04:45:36 INFO - PROCESS | 1947 | RSS: Main: 359309312 04:45:36 INFO - PROCESS | 1947 | 04:45:36 INFO - PROCESS | 1947 | __start_tp_report 04:45:36 INFO - PROCESS | 1947 | _x_x_mozilla_page_load 04:45:36 INFO - PROCESS | 1947 | _x_x_mozilla_page_load_details 04:45:36 INFO - PROCESS | 1947 | |i|pagename|runs| 04:45:36 INFO - PROCESS | 1947 | |0;ai-astar;112;113;116;112;113;111;110;111;110;123 04:45:36 INFO - PROCESS | 1947 | |1;audio-beat-detection;148;113;131;161;107;131;129;106;159;107 04:45:36 INFO - PROCESS | 1947 | |2;audio-dft;185;184;183;181;185;184;180;186;243;181 04:45:36 INFO - PROCESS | 1947 | |3;audio-fft;76;73;82;155;57;58;58;69;141;57 04:45:36 INFO - PROCESS | 1947 | |4;audio-oscillator;81;83;136;69;72;80;116;89;100;151 04:45:36 INFO - PROCESS | 1947 | |5;imaging-gaussian-blur;88;86;87;85;86;84;88;84;87;84 04:45:36 INFO - PROCESS | 1947 | |6;imaging-darkroom;93;93;93;94;93;93;92;93;93;93 04:45:36 INFO - PROCESS | 1947 | |7;imaging-desaturate;85;84;84;86;99;132;139;86;85;85 04:45:36 INFO - PROCESS | 1947 | |8;json-parse-financial;55;54;56;56;56;54;55;54;54;53 04:45:36 INFO - PROCESS | 1947 | |9;json-stringify-tinderbox;66;65;64;79;74;71;68;70;69;71 04:45:36 INFO - PROCESS | 1947 | |10;stanford-crypto-aes;87;84;88;83;86;85;83;77;98;97 04:45:36 INFO - PROCESS | 1947 | |11;stanford-crypto-ccm;136;133;138;139;137;135;135;136;178;140 04:45:36 INFO - PROCESS | 1947 | |12;stanford-crypto-pbkdf2;163;193;233;218;169;169;175;219;169;167 04:45:36 INFO - PROCESS | 1947 | |13;stanford-crypto-sha256-iterative;68;68;69;67;66;69;65;59;57;81 04:45:36 INFO - PROCESS | 1947 | __end_tp_report 04:45:36 INFO - PROCESS | 1947 | __start_cc_report 04:45:36 INFO - PROCESS | 1947 | _x_x_mozilla_cycle_collect,2066 04:45:36 INFO - PROCESS | 1947 | __end_cc_report 04:45:36 INFO - PROCESS | 1947 | __startTimestamp1473421536089__endTimestamp 04:45:36 INFO - PROCESS | 1947 | 04:45:36 INFO - PROCESS | 1947 | ------- Summary: start ------- 04:45:36 INFO - PROCESS | 1947 | Number of tests: 14 04:45:36 INFO - PROCESS | 1947 | 04:45:36 INFO - PROCESS | 1947 | [#0] ai-astar Cycles:10 Average:113.10 Median:112.50 stddev:3.90 (3.5%) stddev-sans-first:4.12 04:45:36 INFO - PROCESS | 1947 | Values: 112.0 113.0 116.0 112.0 113.0 111.0 110.0 111.0 110.0 123.0 04:45:36 INFO - PROCESS | 1947 | 04:45:36 INFO - PROCESS | 1947 | [#1] audio-beat-detection Cycles:10 Average:129.20 Median:131.00 stddev:21.20 (16.2%) stddev-sans-first:21.37 04:45:36 INFO - PROCESS | 1947 | Values: 148.0 113.0 131.0 161.0 107.0 131.0 129.0 106.0 159.0 107.0 04:45:36 INFO - PROCESS | 1947 | 04:45:36 INFO - PROCESS | 1947 | [#2] audio-dft Cycles:10 Average:189.20 Median:184.50 stddev:19.01 (10.3%) stddev-sans-first:20.10 04:45:36 INFO - PROCESS | 1947 | Values: 185.0 184.0 183.0 181.0 185.0 184.0 180.0 186.0 243.0 181.0 04:45:36 INFO - PROCESS | 1947 | 04:45:36 INFO - PROCESS | 1947 | [#3] audio-fft Cycles:10 Average:82.60 Median:63.50 stddev:35.74 (56.3%) stddev-sans-first:37.83 04:45:36 INFO - PROCESS | 1947 | Values: 76.0 73.0 82.0 155.0 57.0 58.0 58.0 69.0 141.0 57.0 04:45:36 INFO - PROCESS | 1947 | 04:45:36 INFO - PROCESS | 1947 | [#4] audio-oscillator Cycles:10 Average:97.70 Median:76.00 stddev:27.92 (36.7%) stddev-sans-first:28.95 04:45:36 INFO - PROCESS | 1947 | Values: 81.0 83.0 136.0 69.0 72.0 80.0 116.0 89.0 100.0 151.0 04:45:36 INFO - PROCESS | 1947 | 04:45:36 INFO - PROCESS | 1947 | [#5] imaging-gaussian-blur Cycles:10 Average:85.90 Median:86.50 stddev:1.60 (1.8%) stddev-sans-first:1.50 04:45:36 INFO - PROCESS | 1947 | Values: 88.0 86.0 87.0 85.0 86.0 84.0 88.0 84.0 87.0 84.0 04:45:36 INFO - PROCESS | 1947 | 04:45:36 INFO - PROCESS | 1947 | [#6] imaging-darkroom Cycles:10 Average:93.00 Median:93.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 04:45:36 INFO - PROCESS | 1947 | Values: 93.0 93.0 93.0 94.0 93.0 93.0 92.0 93.0 93.0 93.0 04:45:36 INFO - PROCESS | 1947 | 04:45:36 INFO - PROCESS | 1947 | [#7] imaging-desaturate Cycles:10 Average:96.50 Median:85.00 stddev:21.09 (24.8%) stddev-sans-first:21.95 04:45:36 INFO - PROCESS | 1947 | Values: 85.0 84.0 84.0 86.0 99.0 132.0 139.0 86.0 85.0 85.0 04:45:36 INFO - PROCESS | 1947 | 04:45:36 INFO - PROCESS | 1947 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.00 stddev:1.06 (1.9%) stddev-sans-first:1.12 04:45:36 INFO - PROCESS | 1947 | Values: 55.0 54.0 56.0 56.0 56.0 54.0 55.0 54.0 54.0 53.0 04:45:36 INFO - PROCESS | 1947 | 04:45:36 INFO - PROCESS | 1947 | [#9] json-stringify-tinderbox Cycles:10 Average:69.70 Median:70.50 stddev:4.47 (6.3%) stddev-sans-first:4.54 04:45:36 INFO - PROCESS | 1947 | Values: 66.0 65.0 64.0 79.0 74.0 71.0 68.0 70.0 69.0 71.0 04:45:36 INFO - PROCESS | 1947 | 04:45:36 INFO - PROCESS | 1947 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:86.50 stddev:6.39 (7.4%) stddev-sans-first:6.78 04:45:36 INFO - PROCESS | 1947 | Values: 87.0 84.0 88.0 83.0 86.0 85.0 83.0 77.0 98.0 97.0 04:45:36 INFO - PROCESS | 1947 | 04:45:36 INFO - PROCESS | 1947 | [#11] stanford-crypto-ccm Cycles:10 Average:140.70 Median:137.50 stddev:13.27 (9.6%) stddev-sans-first:13.96 04:45:36 INFO - PROCESS | 1947 | Values: 136.0 133.0 138.0 139.0 137.0 135.0 135.0 136.0 178.0 140.0 04:45:36 INFO - PROCESS | 1947 | 04:45:36 INFO - PROCESS | 1947 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.50 Median:184.00 stddev:26.30 (14.3%) stddev-sans-first:26.36 04:45:36 INFO - PROCESS | 1947 | Values: 163.0 193.0 233.0 218.0 169.0 169.0 175.0 219.0 169.0 167.0 04:45:36 INFO - PROCESS | 1947 | 04:45:36 INFO - PROCESS | 1947 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:68.00 stddev:6.45 (9.5%) stddev-sans-first:6.83 04:45:36 INFO - PROCESS | 1947 | Values: 68.0 68.0 69.0 67.0 66.0 69.0 65.0 59.0 57.0 81.0 04:45:36 INFO - PROCESS | 1947 | -------- Summary: end -------- 04:45:36 INFO - PROCESS | 1947 | 04:45:36 INFO - TEST-INFO | 1947: exit 0 04:45:37 INFO - TEST-OK | kraken | took 59353ms 04:45:37 INFO - SUITE-END | took 1177s 04:45:37 INFO - Completed test suite (00:19:37) 04:45:37 INFO - javascript benchmark 04:45:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2690.5426077566813, 3315.8507867145977, 3363.243270264191, 3394.9321013579715, 3428.417169081256, 5594.0, 5663.4336566343345, 5667.8583035424135, 5740.684262365568, 5744.9138262926035, 5676.687782171979, 5726.3700992890745, 5748.396418376063, 5754.597178197539, 5789.652620842752, 5137.3064636274, 5148.459411761767, 5212.6611770235, 5233.0, 5240.921386179191, 5301.920471192916, 5306.8407947761625, 5308.336457942757, 5316.680999140064, 5339.305890234265, 5158.148905430599, 5195.1687729963405, 5197.58419326453, 5241.213817927303, 5268.736563171845, 5221.556167725748, 5238.1618940969265, 5249.737513124367, 5251.816186433494, 5266.947330526684, 5153.304303918961, 5198.506141916512, 5220.086484865134, 5222.738863056832, 5228.08508511009, 5655.123455864347, 5691.51622112121, 5728.112142617901, 5831.329397119343, 5855.531557475391, 6222.097795819581, 6278.591891527082, 6291.182146320949, 6309.3690630937, 6334.049892516114, 6156.7994241123, 6240.188775459206, 6282.780102696383, 6348.650824204667, 6350.682465876687, 5778.335491418498, 5848.619839710405, 5863.560232982543, 5871.031279838779, 5881.3530511643685, 6265.760524909729, 6309.432151106423, 6318.241810982711, 6338.8415289617205, 6355.586886852339, 5908.527317814564, 5933.792317268875, 6199.659018754011, 6204.13142160098, 6211.81364559064, 6159.168512250879, 6207.782727604512, 6230.221222347207, 6308.684565771785, 6314.842128946813, 6223.502119830403, 6233.407826256498, 6242.469390101892, 6242.968785156045, 6252.155958945484, 6116.244470650255, 6175.62946223228, 6212.596181248295, 6264.874702505925, 6279.215098112736, 4156.0, 4896.5837903777865, 4904.803807847647, 4923.507649235119, 4945.554900059012, 4445.977770111193, 4449.332600109945, 4452.5992660660695, 4482.193205223026, 4526.547345265513, 5745.827625171251, 5748.109043098327, 5751.913721294184, 5765.481106700333, 5781.624194427349, 6101.671357587133, 6144.0, 6270.968645156836, 6298.716557754912, 6310.337414571496, 5702.372738998707, 5749.051406517961, 5770.07678771403, 5788.710564471759, 5798.391168927286, 5426.728663566806, 5436.972815135899, 5445.754941027584, 5446.700431476307, 5449.12813949774, 6234.906476402948, 6264.906026409516, 6306.716197771019, 6352.36476352361, 6356.713947872357, 6300.117983482408, 6320.146780184617, 6345.0, 6347.365263473615, 6349.936500634934, 6328.398802113792, 6339.524535659752, 6349.682515874096, 6351.0, 6352.428281454691, 5972.58191926561, 6126.700892189639, 6147.862645410613, 6228.4705800007505, 6236.407541283571, 6281.780137695341, 6309.0, 6339.492840572835, 6349.111124442674, 6356.523260755369, 6012.518998480198, 6182.845428864134, 6196.442320191024, 6211.503079753518, 6219.937800622117, 5910.468057874812, 6031.065184896348, 6037.849053773691, 6174.320824709189, 6177.81466556004, 5986.491148252448, 5987.0, 5996.640201587918, 6024.03615421548, 6048.092786082123, 6108.694565271808, 6141.9078713818435, 6142.662153581596, 6178.721957511832, 6179.196704428576, 5816.127580862904, 5850.882982340415, 5925.229720136527, 5975.55183361241, 5987.7604895805425], "value": 5720.108997025701, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [503.44872364760585, 521.9138842091055, 521.9843404697859, 523.596830440561, 524.582956549543, 30787.0, 34584.481232781494, 40743.0, 41978.160436791244, 42048.57951420485, 43554.346684799755, 43727.56272437274, 44036.0, 44059.55940440595, 44121.55878441215, 8619.870701939475, 9695.224382049437, 9902.455364954925, 9904.801903961952, 9942.950285248564, 8609.225169734722, 8776.297896168291, 8780.604872780741, 8785.165409286139, 11758.941205294004, 11820.408979551032, 12098.637040888743, 12098.758024839499, 12121.575744848888, 12160.331181784999, 12082.758344833099, 12162.391880406032, 12165.817512737316, 12183.329916854525, 12191.695207619792, 11929.28424294545, 12002.819957700642, 12004.279743215435, 12006.69983250417, 12014.69963250917, 41016.0, 41409.96475088117, 41418.792906035276, 41519.58480415188, 41653.0, 3279.3277378137423, 3334.583177102862, 3461.238527523941, 3818.732688711791, 4181.954511372157, 24943.50112997748, 32078.198045048826, 32153.03540893777, 32229.677703223137, 32319.353612927844, 3283.3925723740945, 3299.5875515560556, 3310.619278782947, 3389.694927456516, 3394.915127121817, 456685.0, 457154.0, 459346.0, 460360.0, 461468.0, 59046.81906361935, 59378.51553711192, 59475.10787338107, 59566.70216648889, 59663.0, 63222.0, 63295.0, 63371.0, 63480.682596586725, 63566.36433635697, 16171.0, 16396.590085247964, 16411.671766564603, 16450.75323870143, 16480.0, 12803.359832008362, 12863.099583029098, 12865.228086314852, 12899.355032248537, 12899.677508062186, 13980.72038559223, 14634.853651463562, 15264.313105910262, 16826.83173168277, 17140.742888856676, 4163.577592598082, 5442.564594832404, 6070.8768877756875, 6301.4328710416285, 6309.305976342599, 850.81311570359, 898.9910100899039, 937.4422218779815, 948.8339540580371, 1038.5118994072773, 28160.071987402123, 29749.256268593457, 29859.0, 30344.54483182754, 30595.0, 994.9104580587782, 1008.969730908074, 1054.1566746602841, 1153.7058050197145, 1199.598134624893, 14606.0, 15503.457378991681, 15550.455734049254, 15685.137317447421, 15991.60020999461, 595753.0, 596376.0, 597594.0, 599749.0, 601539.0, 46574.76712616483, 49126.26310605344, 49382.765430864514, 56467.71766141225, 58397.12404313939, 1105.6019832860163, 1314.3691028306466, 1364.8498665146826, 1366.2417358365806, 1373.9656508587366, 29541.704582954757, 31334.686653133176, 31381.372372552876, 31425.057248281675, 31981.040568782973, 43044.35433468438, 51265.23102153396, 51910.74044629904, 51920.0, 52046.739766300925, 515281.0, 523983.0, 527357.3632131815, 529488.2307061589, 532236.0, 24625.0, 26591.33521661974, 26635.46729065369, 33946.0, 40087.0, 48088.519114808405, 48761.0, 49114.0, 49228.753856231924, 49279.76800579871, 38355.424668629435, 39224.823255302384, 39236.2152756949, 40241.78586390535, 40893.0, 39182.80408597939, 39481.2103757929, 39728.80813575597, 40263.39604905988, 40910.59089409068, 37687.43468848024, 38274.0, 38447.423288650134, 38775.0, 39004.0, 32450.83774581112, 34548.13629659178, 34962.65037349594, 35021.64978350184, 35349.0, 25756.840942157272, 25800.354991125372, 25816.354591135372, 25881.96472141093, 26076.34809129787], "value": 20113.80300753643, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.7638442015229, 364.66450865204007, 367.08229426433917, 378.62894363523736, 398.97805620690866, 14985.850141498595, 15411.768823467643, 20384.286549970755, 20527.384178474633, 21134.89432552839, 19976.00119993998, 20587.0, 21130.260440884573, 21277.468063298424, 21357.038933248, 4735.952640473585, 5930.199423077873, 6203.2245969253845, 6204.4105809948205, 6209.441150296472, 6738.831529211784, 6883.724651013953, 6918.584884906922, 6920.75777347793, 6941.479389045841, 6258.1551490548645, 6536.803895883131, 6814.4889133314955, 6819.1135152430115, 6846.965765171192, 5691.971540142294, 6485.156929599169, 6764.661766911635, 6782.830429239284, 6795.218549866754, 5850.590458667895, 5893.0276504376725, 5903.557233207526, 5914.112883067532, 5914.4676979071655, 22706.0, 25182.748172518222, 25774.0, 25889.870550647316, 25909.093181738735, 2223.8220942324574, 2235.5752407042773, 2324.93025209244, 2379.0721618568773, 2877.208767588909, 15185.240737963057, 15928.283227254802, 15953.202339882959, 16167.514974550782, 16388.6722265554, 1789.2127463915836, 1809.3576780242956, 1838.6322735452964, 1935.4290484307023, 1935.4387227703949, 115343.0, 117956.82043179544, 118578.0, 118888.0, 119272.40363798212, 9609.0, 10647.414392208502, 10799.190060745319, 10929.398883061507, 11008.229423940247, 13947.721045579234, 13953.953453490825, 13957.0, 13974.021818472609, 13979.231142287068, 8430.426425012754, 8706.564671766386, 8724.738257852274, 8860.3797734158, 8883.511406872554, 8719.0, 8719.215270625547, 8746.344024198082, 8911.331650126267, 8913.24237439812, 6625.469962402996, 6751.223609284897, 6768.120144381199, 6778.864422711518, 6887.001384799176, 1808.6834803909264, 1838.3810354396373, 1849.426677729881, 1856.5358660334916, 1902.914368853405, 852.9104444033411, 880.3573391424168, 900.9773906616037, 902.4495058014605, 955.9102623009775, 21186.15255389767, 22677.43306417353, 22727.204547841076, 22767.203147890075, 22789.202377916692, 845.6828689241535, 948.3645957208687, 1014.2393205096178, 1015.4262841494532, 1019.887812340642, 9833.852492212622, 11743.804120633058, 11985.400729963467, 12166.0, 12190.207636503776, 410423.0, 496254.0, 525623.0, 551364.0, 552447.0, 108384.0, 114824.42587787175, 116591.41704291594, 117754.99999999828, 119696.0, 830.8006078541227, 975.3806332978468, 1031.5770534080991, 1033.452270296744, 1033.850091736694, 18957.0, 19060.714089288394, 19346.4196074118, 19350.90324548368, 19431.4170574883, 41269.793651031556, 49585.0, 50279.49720502748, 50962.490375095775, 51909.0, 57195.0, 57608.71195643995, 73425.63287183744, 74496.0, 74969.0, 7118.928810712034, 7286.077659870418, 7432.145303289974, 7433.219511951167, 7674.270944260286, 12580.370981450707, 13238.404271807984, 13279.933600331937, 13695.863041369857, 13716.862831371958, 10728.0, 10962.0, 11133.443327833738, 11345.716357090809, 11664.008559272557, 11323.377214253294, 11381.260218085798, 11474.77050459003, 11613.419329033346, 11777.352245626573, 11171.832422513831, 11552.59565915189, 11893.0, 12105.213161144497, 12138.393080346124, 7339.633018349168, 7432.516886402366, 7529.4729368943645, 7542.358899493385, 7661.314510807613, 10439.269251152346, 11223.326600404002, 11354.886451135382, 11813.40932953366, 11828.822567661664], "value": 9782.308024666796, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1345.2937207965822, 1501.527018989018, 1533.7162624914392, 1731.0911771320054, 1899.7340372347874, 7817.3355264802485, 8575.828483430336, 8905.821883562325, 8913.108689131084, 9050.140236677522, 7484.887726684091, 8877.289816814655, 8884.64461421544, 8884.73345799627, 8891.466512009283, 2395.1497218487402, 2421.4309637235237, 2421.903123875043, 2425.9029638814427, 2435.1355268879556, 2001.2595339724323, 2360.9881950590225, 2412.95174096518, 2415.7221919479307, 2420.515896820634, 2366.857988520694, 2396.2212281008656, 2398.5442765874513, 2417.746136655652, 2419.9395015124674, 2358.068562917638, 2388.295452841416, 2388.605880029793, 2389.940251493709, 2399.3281881073153, 2345.7302410222874, 2365.053978408633, 2404.0864471500718, 2407.5546023985444, 2408.8313818032743, 8754.781130471725, 8829.028806831244, 8938.597763100675, 8943.955280223558, 8949.015608283084, 5301.840944771663, 5526.198701188305, 5531.3362396512675, 5535.944640553583, 5539.750711217965, 3025.5764193012997, 3690.926181476382, 3710.9443358349645, 3758.755680880734, 3773.698074164409, 1059.390850261103, 1067.7223921780394, 1070.138538476526, 1073.6725298783867, 1086.277625379122, 11722.35527046012, 11780.057595392347, 11856.644300670994, 11858.40707964607, 11895.643130706092, 4113.0, 4231.534531201565, 4551.931721024187, 4636.582707556337, 4647.651426143053, 9206.125418085272, 9213.81572368549, 9214.0, 9218.953905230564, 9231.446113233227, 5287.365516138088, 5299.2316114164005, 5300.92048619271, 5301.0, 5301.1783173608155, 4451.132029254329, 4608.976955115203, 4805.519448055166, 4932.062908047459, 4990.051890140861, 3721.181340105227, 3756.7745935243975, 3833.4824798652385, 3849.6920246380223, 3877.495925529663, 1157.6989982604584, 1166.9591564295208, 1167.3229526874393, 1183.6567395455393, 1185.1940680337282, 828.0891019878206, 871.7079778274223, 878.2710350409145, 883.4655033704645, 912.885889263842, 5734.283214598176, 5801.593888427769, 5906.5570082244, 6108.350745298744, 6200.038993955944, 780.3601047141403, 856.1096459681986, 914.5381582300895, 933.4772527384687, 935.9859602105973, 3173.984130079381, 3787.772733635991, 3928.5678575356687, 3931.5478719947, 4063.3701776224734, 10644.254902156774, 11109.666709998713, 11121.054710349548, 11123.944380278046, 11143.888561114447, 11055.778884422267, 11062.004419602274, 11091.33451992883, 11102.944485277521, 11134.0, 8317.0, 8423.663053477792, 8430.747077587683, 8437.240648341556, 8443.873341899876, 5625.353084395347, 5808.854778630568, 5942.465178133989, 5995.46040856325, 6017.187679663364, 9945.254105941938, 10234.795304093728, 10244.948775256076, 10278.948605256926, 10297.279190456597, 10437.0, 10445.216608754221, 10508.316959397614, 10572.259941804308, 10587.523561439599, 6537.0521274413995, 6712.060311556484, 6722.361375669304, 6984.965075174591, 7140.0, 7926.0, 7945.48354356965, 8295.917040829514, 8442.873356899774, 8592.828143437222, 7513.248675132443, 7597.3162415382885, 7757.806054848674, 7764.805879852823, 7765.650545725569, 7569.6593653284635, 7603.65783539731, 7709.2290770922455, 7773.37812974972, 7806.414518911218, 6974.302569743188, 7106.5736055836815, 7109.253528379652, 7117.395021423237, 7118.85762284741, 4305.48334199898, 4454.376387305844, 5028.446870844277, 5234.842954711365, 5374.978754036721, 4922.72924989129, 5035.798568057383, 5119.078565858182, 5236.2407450918645, 5248.580113590827], "value": 4580.223132052497, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.74068208952986, 296.1840130440637, 305.4029372576613, 309.12825831156147, 309.17604583784214, 4828.299896515004, 5140.408852981908, 5216.478352164781, 5264.39459462162, 5293.920591191135, 4054.797260136996, 4771.308160316752, 4925.704457732539, 5084.771185296661, 5283.365996080466, 3800.6959443244473, 3832.1186127190763, 3944.9032509480135, 3949.7037722170808, 3978.3037968355566, 3277.8688852445875, 3974.0, 3985.900352491182, 4024.2555127301544, 4030.858919937803, 3231.6122065352192, 3689.723270754691, 3950.980245098785, 3959.742616729903, 3960.2871483133026, 3651.6713495785243, 3940.6650434713083, 3963.5136823691114, 3965.781881996489, 4026.6913253192683, 3229.7093261606333, 3372.730181585467, 3426.1605906552804, 3426.3489936911906, 3447.637998010198, 4208.326667733148, 5052.216906379517, 5065.265536497187, 5065.96042374576, 5086.593072554187, 4330.610245077958, 4403.895253846031, 4466.464024317071, 4479.126570318789, 4560.817567297304, 5419.991904493005, 5529.336479622471, 5533.612647114704, 5559.193916882029, 5571.303587051619, 3149.6062992125985, 3744.2698673758355, 3744.4383342498572, 3753.380692185786, 3759.8120093995462, 5033.84898453047, 5630.408807075239, 5643.463870932255, 5707.77168913243, 5726.284214473191, 3373.4602463605706, 3792.7913964732206, 3793.8103094845146, 3801.9809900950872, 3809.790461524643, 3832.5784163741964, 3851.5378154621626, 3860.903477413031, 3874.5350557932666, 3885.7279990400402, 4131.232074510085, 4171.770552619574, 4513.864584062483, 4585.174668559691, 4613.19269127908, 5554.250176226238, 5568.693721845338, 5569.192467092249, 5569.248151499577, 5609.859753506195, 1297.8702129786946, 1311.1674086954852, 1316.453671726242, 1317.8748018938186, 1323.9536616218386, 733.7101844771286, 821.2157389692829, 839.878761852921, 847.7329641163137, 885.1281487734576, 5111.361079865017, 5130.897382052338, 5146.330976973044, 5149.716765577929, 5184.6299905062015, 5583.664980101125, 5593.510887654955, 5670.858228544319, 5685.005124103265, 5687.459691329317, 4473.351364052194, 4783.043391321749, 5067.746612669352, 5088.185890257541, 5107.591392688576, 2471.43157073874, 2937.3390987028174, 2945.8527073646233, 2955.1725516855313, 2965.339409930446, 5150.660828184624, 5534.395025442576, 5782.855428614234, 5790.40994583361, 5796.666766643695, 4721.858344249678, 5289.3917199521775, 5311.362636483646, 5697.4587414195585, 5714.05718056516, 4838.516148385203, 5034.14419548685, 5095.974520127375, 5692.402297758676, 5813.494541730193, 4246.915061698687, 4969.4782047884455, 5249.737513124405, 5590.832275031756, 5599.556272892223, 5472.2065300531995, 5489.423610520837, 5507.540501767115, 5528.226048353154, 5548.278723765885, 4770.640367495247, 5580.720963951868, 5670.971645141748, 5742.483176514133, 5742.7702891883855, 3426.5374174486124, 3549.7515173937577, 3712.9628703712615, 3719.8698045568276, 3737.6921424538937, 4608.88477788058, 5533.5849811263515, 5550.0, 5571.183739138703, 5589.720513974366, 5164.561012313995, 5385.488378604026, 5400.324959380077, 5411.510705075551, 5457.672539647634, 4254.149444991315, 4549.45406551216, 5124.85148613706, 5342.732863356894, 5412.242286079873, 4484.529124442017, 5187.896242075062, 5276.973615131899, 5299.5760339172, 5313.574914006947, 4626.606738427271, 4965.602751779921, 5324.840254792362, 5487.341519017744, 5517.641353312022, 4983.476734942778, 5383.5962302826665, 5385.057614917452, 5403.919490996073, 5411.2965314508865], "value": 3975.8707326908243, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [613.7667686279216, 643.7103303513419, 780.0756104016741, 801.6946714093654, 813.0, 14872.553823385302, 16059.437919672813, 16095.195240237985, 16098.114603696822, 16116.83883161168, 14592.708145837078, 14750.778738318906, 14759.77860332096, 14769.335379907903, 14772.6306842329, 654.9672516374162, 820.950742955423, 825.4428260923852, 839.6557411461303, 841.9938173882214, 831.7463173732009, 832.0015980823007, 833.2875391540207, 833.6165363932598, 834.015861283685, 880.106691707917, 882.6160620130232, 882.7351794461636, 883.4390162246988, 887.8490656588395, 690.4614400767384, 867.1328671328671, 869.678219058946, 878.0253918150847, 882.2412725056446, 668.5119862500353, 668.548729607513, 669.7086767256259, 670.1589505171028, 670.3263220463451, 14773.926130369387, 14820.036697614729, 14823.407063717439, 14830.62923426923, 14836.480723174636, 11778.764424711459, 13934.442622295095, 14046.367913443817, 14076.718465630733, 14212.502562410265, 10669.573217071307, 11341.489632966453, 12004.519819207222, 12355.938220308932, 12662.366881655973, 4608.216603177468, 4774.021325628272, 4806.0, 4808.951910480921, 4824.445188803263, 87416.0, 100759.0, 108896.0, 109294.3605845913, 110066.44966775195, 8763.868541971748, 9182.35723499362, 10621.681349559525, 10667.519961601747, 11129.777404451866, 20370.388888333375, 20643.27748528794, 20686.79313206879, 20746.27388041411, 20966.685499717518, 7474.215207403145, 7515.699372025167, 7522.435817313723, 7585.279398457138, 7717.2668596483245, 4099.85650502237, 4119.423280740639, 4137.91724165521, 4149.336106223051, 4158.355454904495, 3331.833408329574, 3479.2171761353998, 3491.5984661763714, 3492.9126771830906, 3495.2485215678753, 592.1413949772847, 638.4030931079456, 643.4080645805869, 651.081974416071, 661.0018871503968, 571.3401021819805, 615.3385111005687, 639.4085470939362, 675.5811396933834, 709.5565271705184, 9008.864867027, 9163.266938644892, 9628.951855240679, 9638.180754635934, 9719.17387022111, 543.2882923370397, 571.2174321179926, 629.3769168523221, 655.9836004099935, 678.35217367414, 3744.513213282256, 4460.264056430718, 4579.771011449414, 4637.628989680885, 4668.439787225555, 137675.0, 142501.28749356396, 148322.51677483303, 149044.25477872757, 150744.2462787701, 91711.08288917025, 130874.0, 141695.0, 144245.27877360547, 145507.0, 69319.65340173368, 90979.0, 98896.0, 101013.98986010192, 108829.45585272023, 9340.81318373615, 9963.900360996297, 10005.799884002425, 10152.137068349273, 10169.491525423551, 59146.40853591581, 60171.69914150401, 60177.69911150416, 60610.060346077364, 61119.0, 88168.55915720637, 92711.53644231736, 93745.5312723432, 93771.53114234385, 94230.0, 13085.0, 13126.540571079966, 13147.802782958455, 13605.455781769015, 13788.793168102286, 14519.346629401913, 14685.55943321702, 14807.555773326818, 14812.407503699731, 15096.24518774035, 11375.886241137814, 13811.654708631962, 13824.032317737661, 13971.720565588834, 14320.856791431952, 9853.95073024659, 13302.334883255991, 13467.704536274645, 13540.729185416434, 13722.588322350346, 13319.933400332937, 13673.726525469236, 13799.03406761517, 13894.513692020731, 14181.361838717488, 10819.18856085781, 11224.045956093823, 11239.775204495701, 11408.38510855441, 11743.355930169611, 10067.446290454096, 10340.482975851328, 11269.887301126884, 11400.429978501208, 11522.76954460923], "value": 7718.503433031024, "unit": "score"}], "name": "dromaeo_css", "value": 7354.276491014656, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 116.0, 112.0, 113.0, 111.0, 110.0, 111.0, 110.0, 123.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 113.0, 131.0, 161.0, 107.0, 131.0, 129.0, 106.0, 159.0, 107.0], "value": 129.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 183.0, 181.0, 185.0, 184.0, 180.0, 186.0, 243.0, 181.0], "value": 189.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 82.0, 155.0, 57.0, 58.0, 58.0, 69.0, 141.0, 57.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 136.0, 69.0, 72.0, 80.0, 116.0, 89.0, 100.0, 151.0], "value": 97.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 87.0, 85.0, 86.0, 84.0, 88.0, 84.0, 87.0, 84.0], "value": 85.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 94.0, 93.0, 93.0, 92.0, 93.0, 93.0, 93.0], "value": 93.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 86.0, 99.0, 132.0, 139.0, 86.0, 85.0, 85.0], "value": 96.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 56.0, 56.0, 56.0, 54.0, 55.0, 54.0, 54.0, 53.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 64.0, 79.0, 74.0, 71.0, 68.0, 70.0, 69.0, 71.0], "value": 69.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 88.0, 83.0, 86.0, 85.0, 83.0, 77.0, 98.0, 97.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 133.0, 138.0, 139.0, 137.0, 135.0, 135.0, 136.0, 178.0, 140.0], "value": 140.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 193.0, 233.0, 218.0, 169.0, 169.0, 175.0, 219.0, 169.0, 167.0], "value": 187.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 67.0, 66.0, 69.0, 65.0, 59.0, 57.0, 81.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1493.5000000000002, "alertThreshold": 2.0}]} 04:45:37 INFO - Return code: 0 04:45:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:45:37 INFO - # TBPL SUCCESS # 04:45:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:45:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:45:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:45:37 INFO - 2016-09-09 04:45:37.350 screenresolution[1951:18815] starting screenresolution argv=screenresolution get 04:45:37 INFO - 2016-09-09 04:45:37.354 screenresolution[1951:18815] Display 0: 1600x1200x32@60 04:45:37 INFO - 2016-09-09 04:45:37.360 screenresolution[1952:18818] starting screenresolution argv=screenresolution list 04:45:37 INFO - Available Modes on Display 0 04:45:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:45:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:45:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:45:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:45:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:45:37 INFO - Intel Iris: 04:45:37 INFO - Chipset Model: Intel Iris 04:45:37 INFO - Type: GPU 04:45:37 INFO - Bus: Built-In 04:45:37 INFO - VRAM (Dynamic, Max): 1536 MB 04:45:37 INFO - Vendor: Intel (0x8086) 04:45:37 INFO - Device ID: 0x0a2e 04:45:37 INFO - Revision ID: 0x0009 04:45:37 INFO - Displays: 04:45:37 INFO - Display: 04:45:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:45:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:45:37 INFO - Display Serial Number: 1600x1200 60 04:45:37 INFO - Main Display: Yes 04:45:37 INFO - Mirror: Off 04:45:37 INFO - Online: Yes 04:45:37 INFO - Rotation: Supported 04:45:37 INFO - Return code: 0 04:45:37 INFO - Running post-action listener: _resource_record_post_action 04:45:37 INFO - [mozharness: 2016-09-09 11:45:37.601496Z] Finished run-tests step (success) 04:45:37 INFO - Running post-run listener: _resource_record_post_run 04:45:37 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 368640; Write bytes: 705896960; Read time: 5; Write time: 1889 04:45:37 INFO - TinderboxPrint: CPU usage
53.5% 04:45:37 INFO - TinderboxPrint: I/O read bytes / time
368,640 / 5 04:45:37 INFO - TinderboxPrint: I/O write bytes / time
705,896,960 / 1,889 04:45:37 INFO - TinderboxPrint: CPU idle
2,256.5 (46.5%) 04:45:37 INFO - TinderboxPrint: CPU system
967.9 (20.0%) 04:45:37 INFO - TinderboxPrint: CPU user
1,625.3 (33.5%) 04:45:37 INFO - TinderboxPrint: Swap in / out
405,590,016 / 0 04:45:37 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 391967232; Write bytes: 386135552; Read time: 29592; Write time: 712 04:45:37 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 368640; Write bytes: 297720832; Read time: 5; Write time: 1131 04:45:38 INFO - Running post-run listener: _upload_blobber_files 04:45:38 INFO - Blob upload gear active. 04:45:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:45:38 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 04:45:38 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'] 04:45:38 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 04:45:38 INFO - (blobuploader) - INFO - Open directory for files ... 04:45:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:45:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:45:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:45:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:45:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:45:39 INFO - (blobuploader) - INFO - Done attempting. 04:45:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:45:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:45:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:45:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:45:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:45:40 INFO - (blobuploader) - INFO - Done attempting. 04:45:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:45:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:45:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:45:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:45:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:45:42 INFO - (blobuploader) - INFO - Done attempting. 04:45:42 INFO - (blobuploader) - INFO - Iteration through files over. 04:45:42 INFO - Return code: 0 04:45:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:45:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:45:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c3622e26e4f757404499db5c82ea8ea11544768d0912e4f640775717972f77584e9347c4994e9fc577631637bcb653a437d2825d480ccdfbed355f5db05b1e43", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cbff8e0f428cd79c31a6cc914962caf0658261fea20fe2bb89dfe9b2116e0bc207c92654127369c45e2add2696f3979a09657307100598512147b9095fb09589", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef9a656d5f1ab018f192b8183c7e70886129a3f281539a51b92e10aa23c0dfb430c3c107c331c8e2fd5824db6d70d64ded5920a3ed2437741be28798021a5b29"} 04:45:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:45:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:45:42 INFO - Contents: 04:45:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c3622e26e4f757404499db5c82ea8ea11544768d0912e4f640775717972f77584e9347c4994e9fc577631637bcb653a437d2825d480ccdfbed355f5db05b1e43", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cbff8e0f428cd79c31a6cc914962caf0658261fea20fe2bb89dfe9b2116e0bc207c92654127369c45e2add2696f3979a09657307100598512147b9095fb09589", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef9a656d5f1ab018f192b8183c7e70886129a3f281539a51b92e10aa23c0dfb430c3c107c331c8e2fd5824db6d70d64ded5920a3ed2437741be28798021a5b29"} 04:45:42 INFO - Running post-run listener: copy_logs_to_upload_dir 04:45:42 INFO - Copying logs to upload dir... 04:45:42 INFO - mkdir: /builds/slave/test/build/upload/logs 04:45:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.922980 ========= master_lag: 1.10 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-09 04:45:43.634901) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-09 04:45:43.642738) ========= 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.OODW1ls8Dw/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.16aznVqw0d/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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/c3622e26e4f757404499db5c82ea8ea11544768d0912e4f640775717972f77584e9347c4994e9fc577631637bcb653a437d2825d480ccdfbed355f5db05b1e43", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cbff8e0f428cd79c31a6cc914962caf0658261fea20fe2bb89dfe9b2116e0bc207c92654127369c45e2add2696f3979a09657307100598512147b9095fb09589", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef9a656d5f1ab018f192b8183c7e70886129a3f281539a51b92e10aa23c0dfb430c3c107c331c8e2fd5824db6d70d64ded5920a3ed2437741be28798021a5b29"} build_url:https://queue.taskcluster.net/v1/task/a-iNxkFGReS2qTY2phTuEw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010912 build_url: 'https://queue.taskcluster.net/v1/task/a-iNxkFGReS2qTY2phTuEw/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/c3622e26e4f757404499db5c82ea8ea11544768d0912e4f640775717972f77584e9347c4994e9fc577631637bcb653a437d2825d480ccdfbed355f5db05b1e43", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cbff8e0f428cd79c31a6cc914962caf0658261fea20fe2bb89dfe9b2116e0bc207c92654127369c45e2add2696f3979a09657307100598512147b9095fb09589", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef9a656d5f1ab018f192b8183c7e70886129a3f281539a51b92e10aa23c0dfb430c3c107c331c8e2fd5824db6d70d64ded5920a3ed2437741be28798021a5b29"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-09 04:45:43.683727) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 04:45:43.684051) ========= 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.OODW1ls8Dw/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.16aznVqw0d/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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.005203 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 04:45:43.732008) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-09 04:45:43.735341) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-09 04:45:43.735698) ========= ========= Total master_lag: 1.43 =========