builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0291 starttime: 1473733539.66 results: success (0) buildid: 20160912172009 builduid: 24dc1eb70c114775a3e43666a4a660be revision: b1156b0eb96fcb49966b20e5fcf6a01f634ea2ee ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 19:25:39.659860) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 19:25:39.660472) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 19:25:39.660871) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 19:25:39.686512) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 19:25:39.686929) ========= 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.C7I3rxWpCK/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.09C0aBEwsf/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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-12 19:25:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.3M=0.001s 2016-09-12 19:25:39 (15.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117598 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 19:25:39.827089) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 19:25:39.827418) ========= 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.C7I3rxWpCK/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.09C0aBEwsf/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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.026083 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 19:25:39.877652) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-12 19:25:39.878002) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b1156b0eb96fcb49966b20e5fcf6a01f634ea2ee --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 b1156b0eb96fcb49966b20e5fcf6a01f634ea2ee --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.C7I3rxWpCK/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.09C0aBEwsf/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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-12 19:25:39,961 truncating revision to first 12 chars 2016-09-12 19:25:39,961 Setting DEBUG logging. 2016-09-12 19:25:39,961 attempt 1/10 2016-09-12 19:25:39,962 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b1156b0eb96f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-12 19:25:41,400 unpacking tar archive at: mozilla-central-b1156b0eb96f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.694492 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-12 19:25:41.596216) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 19:25:41.596937) ========= script_repo_revision: b1156b0eb96fcb49966b20e5fcf6a01f634ea2ee ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 19:25:41.597340) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 19:25:41.597676) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 19:25:41.655137) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-09-12 19:25:41.655534) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.C7I3rxWpCK/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.09C0aBEwsf/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 19:25:41 INFO - MultiFileLogger online at 20160912 19:25:41 in /builds/slave/test 19:25:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:25:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:25:41 INFO - {'append_to_log': False, 19:25:41 INFO - 'base_work_dir': '/builds/slave/test', 19:25:41 INFO - 'blob_upload_branch': 'Firefox', 19:25:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:25:41 INFO - 'branch': 'Firefox', 19:25:41 INFO - 'buildbot_json_path': 'buildprops.json', 19:25:41 INFO - 'config_files': ('talos/mac_config.py',), 19:25:41 INFO - 'default_actions': ('clobber', 19:25:41 INFO - 'read-buildbot-config', 19:25:41 INFO - 'download-and-extract', 19:25:41 INFO - 'populate-webroot', 19:25:41 INFO - 'create-virtualenv', 19:25:41 INFO - 'install', 19:25:41 INFO - 'run-tests'), 19:25:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:25:41 INFO - 'download_minidump_stackwalk': True, 19:25:41 INFO - 'download_symbols': 'ondemand', 19:25:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:25:41 INFO - 'tooltool.py': '/tools/tooltool.py', 19:25:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:25:41 INFO - '/tools/misc-python/virtualenv.py')}, 19:25:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:25:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:25:41 INFO - 'installer_path': 'installer.exe', 19:25:41 INFO - 'log_level': 'info', 19:25:41 INFO - 'log_name': 'talos', 19:25:41 INFO - 'log_to_console': True, 19:25:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:25:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:25:41 INFO - 'opt_config_files': (), 19:25:41 INFO - 'pip_index': False, 19:25:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:25:41 INFO - 'cmd': ('bash', 19:25:41 INFO - '-c', 19:25:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:25:41 INFO - 'enabled': True, 19:25:41 INFO - 'halt_on_failure': False, 19:25:41 INFO - 'name': 'check_screen_resolution'},), 19:25:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:25:41 INFO - 'cmd': ('bash', 19:25:41 INFO - '-c', 19:25:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:25:41 INFO - 'enabled': True, 19:25:41 INFO - 'halt_on_failure': False, 19:25:41 INFO - 'name': 'check_screen_resolution'},), 19:25:41 INFO - 'run_cmd_checks_enabled': True, 19:25:41 INFO - 'sps_profile': False, 19:25:41 INFO - 'sps_profile_interval': 0, 19:25:41 INFO - 'suite': 'dromaeojs-e10s', 19:25:41 INFO - 'system_bits': '32', 19:25:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:25:41 INFO - 'title': 't-yosemite-r7-0291', 19:25:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:25:41 INFO - 'use_talos_json': True, 19:25:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:25:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:25:41 INFO - 'work_dir': 'build'} 19:25:41 INFO - [mozharness: 2016-09-13 02:25:41.854892Z] Running clobber step. 19:25:41 INFO - Running pre-action listener: _resource_record_pre_action 19:25:41 INFO - Running main action method: clobber 19:25:41 INFO - rmtree: /builds/slave/test/build 19:25:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:25:43 INFO - Running post-action listener: _resource_record_post_action 19:25:43 INFO - [mozharness: 2016-09-13 02:25:43.960487Z] Finished clobber step (success) 19:25:43 INFO - [mozharness: 2016-09-13 02:25:43.960627Z] Running read-buildbot-config step. 19:25:43 INFO - Running pre-action listener: _resource_record_pre_action 19:25:43 INFO - Running main action method: read_buildbot_config 19:25:43 INFO - Using buildbot properties: 19:25:43 INFO - { 19:25:43 INFO - "project": "", 19:25:43 INFO - "product": "firefox", 19:25:43 INFO - "script_repo_revision": "production", 19:25:43 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 19:25:43 INFO - "repository": "", 19:25:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 19:25:43 INFO - "buildid": "20160912172009", 19:25:43 INFO - "pgo_build": "False", 19:25:43 INFO - "basedir": "/builds/slave/test", 19:25:43 INFO - "buildnumber": 84, 19:25:43 INFO - "slavename": "t-yosemite-r7-0291", 19:25:43 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 19:25:43 INFO - "platform": "yosemite_r7", 19:25:43 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:25:43 INFO - "branch": "mozilla-central", 19:25:43 INFO - "repo_path": "mozilla-central", 19:25:43 INFO - "revision": "b1156b0eb96fcb49966b20e5fcf6a01f634ea2ee", 19:25:43 INFO - "stage_platform": "macosx64", 19:25:43 INFO - "builduid": "24dc1eb70c114775a3e43666a4a660be", 19:25:43 INFO - "slavebuilddir": "test" 19:25:43 INFO - } 19:25:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/c-xUpIT7Qg2_IrnIHzFOqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 19:25:43 INFO - Running post-action listener: _resource_record_post_action 19:25:43 INFO - [mozharness: 2016-09-13 02:25:43.963980Z] Finished read-buildbot-config step (success) 19:25:43 INFO - [mozharness: 2016-09-13 02:25:43.964106Z] Running download-and-extract step. 19:25:43 INFO - Running pre-action listener: _resource_record_pre_action 19:25:43 INFO - Running main action method: download_and_extract 19:25:43 INFO - mkdir: /builds/slave/test/build/tests 19:25:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:25:43 INFO - trying https://queue.taskcluster.net/v1/task/c-xUpIT7Qg2_IrnIHzFOqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 19:25:43 INFO - Downloading https://queue.taskcluster.net/v1/task/c-xUpIT7Qg2_IrnIHzFOqQ/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 19:25:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c-xUpIT7Qg2_IrnIHzFOqQ/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 19:25:45 INFO - Downloaded 1199 bytes. 19:25:45 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 19:25:45 INFO - Using the following test package requirements: 19:25:45 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:25:45 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:25:45 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 19:25:45 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:25:45 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 19:25:45 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:25:45 INFO - u'jsshell-mac.zip'], 19:25:45 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:25:45 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 19:25:45 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:25:45 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:25:45 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 19:25:45 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:25:45 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 19:25:45 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:25:45 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 19:25:45 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:25:45 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 19:25:45 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:25:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c-xUpIT7Qg2_IrnIHzFOqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:25:45 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 19:25:52 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:25:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c-xUpIT7Qg2_IrnIHzFOqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:25:53 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 19:26:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c-xUpIT7Qg2_IrnIHzFOqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 19:26:01 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 19:26:01 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')]} 19:26:01 INFO - trying https://queue.taskcluster.net/v1/task/c-xUpIT7Qg2_IrnIHzFOqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:26:01 INFO - Downloading https://queue.taskcluster.net/v1/task/c-xUpIT7Qg2_IrnIHzFOqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 19:26:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c-xUpIT7Qg2_IrnIHzFOqQ/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 19:26:04 INFO - Downloaded 111425108 bytes. 19:26:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/c-xUpIT7Qg2_IrnIHzFOqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:26:04 INFO - mkdir: /builds/slave/test/properties 19:26:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:26:04 INFO - Writing to file /builds/slave/test/properties/build_url 19:26:04 INFO - Contents: 19:26:04 INFO - build_url:https://queue.taskcluster.net/v1/task/c-xUpIT7Qg2_IrnIHzFOqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:26:05 INFO - Running post-action listener: _resource_record_post_action 19:26:05 INFO - Running post-action listener: set_extra_try_arguments 19:26:05 INFO - [mozharness: 2016-09-13 02:26:05.226119Z] Finished download-and-extract step (success) 19:26:05 INFO - [mozharness: 2016-09-13 02:26:05.226238Z] Running populate-webroot step. 19:26:05 INFO - Running pre-action listener: _resource_record_pre_action 19:26:05 INFO - Running main action method: populate_webroot 19:26:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:26:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:26:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:26:05 INFO - '--tppagecycles', 19:26:05 INFO - '7'], 19:26:05 INFO - 'tests': ['tsvgm']}}, 19:26:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:26:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:26:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:26:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:26:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:26:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:26:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:05 INFO - 'talos_options': ['--disable-e10s'], 19:26:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:26:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:26:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:05 INFO - 'talos_options': ['--disable-e10s'], 19:26:05 INFO - 'tests': ['damp', 'tps']}, 19:26:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:05 INFO - 'tests': ['damp', 'tps']}, 19:26:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:26:05 INFO - 'tests': ['dromaeo_dom']}, 19:26:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:26:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:26:05 INFO - 'tests': ['basic_compositor_video']}, 19:26:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:26:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:26:05 INFO - 'tests': ['a11yr', 19:26:05 INFO - 'ts_paint', 19:26:05 INFO - 'tpaint', 19:26:05 INFO - 'sessionrestore', 19:26:05 INFO - 'sessionrestore_no_auto_restore', 19:26:05 INFO - 'tabpaint']}, 19:26:05 INFO - 'other-e10s': {'tests': ['a11yr', 19:26:05 INFO - 'ts_paint', 19:26:05 INFO - 'tpaint', 19:26:05 INFO - 'sessionrestore', 19:26:05 INFO - 'sessionrestore_no_auto_restore', 19:26:05 INFO - 'tabpaint']}, 19:26:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:26:05 INFO - 'ts_paint', 19:26:05 INFO - 'tpaint', 19:26:05 INFO - 'sessionrestore', 19:26:05 INFO - 'sessionrestore_no_auto_restore', 19:26:05 INFO - 'tabpaint']}, 19:26:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:26:05 INFO - 'ts_paint', 19:26:05 INFO - 'tpaint', 19:26:05 INFO - 'sessionrestore', 19:26:05 INFO - 'sessionrestore_no_auto_restore', 19:26:05 INFO - 'tabpaint']}, 19:26:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:26:05 INFO - 'tests': ['a11yr', 19:26:05 INFO - 'ts_paint', 19:26:05 INFO - 'tpaint', 19:26:05 INFO - 'sessionrestore', 19:26:05 INFO - 'sessionrestore_no_auto_restore', 19:26:05 INFO - 'tabpaint']}, 19:26:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:26:05 INFO - 'tests': ['a11yr', 19:26:05 INFO - 'ts_paint', 19:26:05 INFO - 'tpaint', 19:26:05 INFO - 'sessionrestore', 19:26:05 INFO - 'sessionrestore_no_auto_restore', 19:26:05 INFO - 'tabpaint']}, 19:26:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:26:05 INFO - 'tests': ['tsvgx', 19:26:05 INFO - 'tsvgr_opacity', 19:26:05 INFO - 'tart', 19:26:05 INFO - 'tscrollx', 19:26:05 INFO - 'cart']}, 19:26:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:26:05 INFO - 'tsvgr_opacity', 19:26:05 INFO - 'tart', 19:26:05 INFO - 'tscrollx', 19:26:05 INFO - 'cart']}, 19:26:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:05 INFO - 'talos_options': ['--disable-e10s'], 19:26:05 INFO - 'tests': ['tp5o']}, 19:26:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:05 INFO - 'tests': ['tp5o']}, 19:26:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:05 INFO - 'talos_options': ['--disable-e10s', 19:26:05 INFO - '--xperf_path', 19:26:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:26:05 INFO - 'tests': ['tp5n']}, 19:26:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:05 INFO - 'talos_options': ['--xperf_path', 19:26:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:26:05 INFO - 'tests': ['tp5n']}}, 19:26:05 INFO - 'talos.zip': {'path': '', 19:26:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:26:05 INFO - Running post-action listener: _resource_record_post_action 19:26:05 INFO - [mozharness: 2016-09-13 02:26:05.244598Z] Finished populate-webroot step (success) 19:26:05 INFO - [mozharness: 2016-09-13 02:26:05.244727Z] Running create-virtualenv step. 19:26:05 INFO - Running pre-action listener: _resource_record_pre_action 19:26:05 INFO - Running main action method: create_virtualenv 19:26:05 INFO - Creating virtualenv /builds/slave/test/build/venv 19:26:05 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 19:26:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:26:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:26:05 INFO - Using real prefix '/tools/python27' 19:26:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:26:06 INFO - Installing distribute.............................................................................................................................................................................................done. 19:26:08 INFO - Installing pip.................done. 19:26:08 INFO - Return code: 0 19:26:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107a58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceab46bfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10786cd30>, '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 0x107692db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107864b20>, '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.C7I3rxWpCK/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.09C0aBEwsf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 19:26:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:26:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C7I3rxWpCK/Render', 19:26:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:26:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:26:08 INFO - 'HOME': '/Users/cltbld', 19:26:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:26:08 INFO - 'LOGNAME': 'cltbld', 19:26:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:08 INFO - 'MOZ_NO_REMOTE': '1', 19:26:08 INFO - 'NO_EM_RESTART': '1', 19:26:08 INFO - 'PAGER': '/bin/cat', 19:26:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:26:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:08 INFO - 'PWD': '/builds/slave/test', 19:26:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:26:08 INFO - 'SHELL': '/bin/bash', 19:26:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.09C0aBEwsf/Listeners', 19:26:08 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 19:26:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:26:08 INFO - 'USER': 'cltbld', 19:26:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:26:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:26:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:08 INFO - 'XPC_FLAGS': '0x0', 19:26:08 INFO - 'XPC_SERVICE_NAME': '0', 19:26:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:26:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:09 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 19:26:09 INFO - Cleaning up... 19:26:09 INFO - Return code: 0 19:26:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:26: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')]} 19:26:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26: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')]} 19:26:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26: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', '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 0x107a58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceab46bfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10786cd30>, '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 0x107692db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107864b20>, '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.C7I3rxWpCK/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.09C0aBEwsf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 19:26: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', 'psutil>=3.1.1'] in /builds/slave/test/build 19:26: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 psutil>=3.1.1 19:26:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C7I3rxWpCK/Render', 19:26:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:26:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:26:09 INFO - 'HOME': '/Users/cltbld', 19:26:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:26:09 INFO - 'LOGNAME': 'cltbld', 19:26:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:09 INFO - 'MOZ_NO_REMOTE': '1', 19:26:09 INFO - 'NO_EM_RESTART': '1', 19:26:09 INFO - 'PAGER': '/bin/cat', 19:26:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:26:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:09 INFO - 'PWD': '/builds/slave/test', 19:26:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:26:09 INFO - 'SHELL': '/bin/bash', 19:26:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.09C0aBEwsf/Listeners', 19:26:09 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 19:26:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:26:09 INFO - 'USER': 'cltbld', 19:26:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:26:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:26:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:09 INFO - 'XPC_FLAGS': '0x0', 19:26:09 INFO - 'XPC_SERVICE_NAME': '0', 19:26:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:26:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:09 INFO - Downloading/unpacking psutil>=3.1.1 19:26: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 19:26: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 19:26: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 19:26: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 19:26: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 19:26: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 19:26:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:26:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:26:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:26:12 INFO - Installing collected packages: psutil 19:26:12 INFO - Running setup.py install for psutil 19:26:12 INFO - building 'psutil._psutil_osx' extension 19:26:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 19:26:13 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 19:26:13 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 19:26:13 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 19:26:13 INFO - building 'psutil._psutil_posix' extension 19:26:13 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 19:26:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:26:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:26:13 INFO - ^ 19:26:13 INFO - 1 warning generated. 19:26:13 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 19:26:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:26:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:26:13 INFO - Successfully installed psutil 19:26:13 INFO - Cleaning up... 19:26:14 INFO - Return code: 0 19:26:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:26: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')]} 19:26:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26: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')]} 19:26:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107a58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceab46bfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10786cd30>, '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 0x107692db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107864b20>, '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.C7I3rxWpCK/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.09C0aBEwsf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 19:26:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:26:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:26:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C7I3rxWpCK/Render', 19:26:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:26:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:26:14 INFO - 'HOME': '/Users/cltbld', 19:26:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:26:14 INFO - 'LOGNAME': 'cltbld', 19:26:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:14 INFO - 'MOZ_NO_REMOTE': '1', 19:26:14 INFO - 'NO_EM_RESTART': '1', 19:26:14 INFO - 'PAGER': '/bin/cat', 19:26:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:26:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:14 INFO - 'PWD': '/builds/slave/test', 19:26:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:26:14 INFO - 'SHELL': '/bin/bash', 19:26:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.09C0aBEwsf/Listeners', 19:26:14 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 19:26:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:26:14 INFO - 'USER': 'cltbld', 19:26:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:26:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:26:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:14 INFO - 'XPC_FLAGS': '0x0', 19:26:14 INFO - 'XPC_SERVICE_NAME': '0', 19:26:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:26:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:26:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:26:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:26:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:26:16 INFO - Installing collected packages: mozsystemmonitor 19:26:16 INFO - Running setup.py install for mozsystemmonitor 19:26:16 INFO - Successfully installed mozsystemmonitor 19:26:16 INFO - Cleaning up... 19:26:17 INFO - Return code: 0 19:26:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:26:17 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')]} 19:26:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:17 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')]} 19:26:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:17 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 0x107a58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceab46bfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10786cd30>, '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 0x107692db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107864b20>, '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.C7I3rxWpCK/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.09C0aBEwsf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 19:26:17 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 19:26:17 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 19:26:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C7I3rxWpCK/Render', 19:26:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:26:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:26:17 INFO - 'HOME': '/Users/cltbld', 19:26:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:26:17 INFO - 'LOGNAME': 'cltbld', 19:26:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:17 INFO - 'MOZ_NO_REMOTE': '1', 19:26:17 INFO - 'NO_EM_RESTART': '1', 19:26:17 INFO - 'PAGER': '/bin/cat', 19:26:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:26:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:17 INFO - 'PWD': '/builds/slave/test', 19:26:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:26:17 INFO - 'SHELL': '/bin/bash', 19:26:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.09C0aBEwsf/Listeners', 19:26:17 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 19:26:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:26:17 INFO - 'USER': 'cltbld', 19:26:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:26:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:26:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:17 INFO - 'XPC_FLAGS': '0x0', 19:26:17 INFO - 'XPC_SERVICE_NAME': '0', 19:26:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:26:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:17 INFO - Downloading/unpacking jsonschema==2.5.1 19:26:17 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 19:26:17 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 19:26:17 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 19:26:17 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 19:26:17 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 19:26:17 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 19:26:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:26:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:26:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:20 INFO - Downloading functools32-3.2.3-2.tar.gz 19:26:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:26:20 INFO - warning: no files found matching '*.txt' 19:26:20 INFO - no previously-included directories found matching 'build' 19:26:20 INFO - no previously-included directories found matching 'dist' 19:26:20 INFO - no previously-included directories found matching '.git*' 19:26:20 INFO - Installing collected packages: jsonschema, functools32 19:26:20 INFO - Running setup.py install for functools32 19:26:20 INFO - warning: no files found matching '*.txt' 19:26:20 INFO - no previously-included directories found matching 'build' 19:26:20 INFO - no previously-included directories found matching 'dist' 19:26:20 INFO - no previously-included directories found matching '.git*' 19:26:20 INFO - Successfully installed jsonschema functools32 19:26:20 INFO - Cleaning up... 19:26:20 INFO - Return code: 0 19:26:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:26:20 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')]} 19:26:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:20 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')]} 19:26:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:20 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 0x107a58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceab46bfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10786cd30>, '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 0x107692db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107864b20>, '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.C7I3rxWpCK/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.09C0aBEwsf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 19:26:20 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 19:26:20 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 19:26:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C7I3rxWpCK/Render', 19:26:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:26:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:26:20 INFO - 'HOME': '/Users/cltbld', 19:26:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:26:20 INFO - 'LOGNAME': 'cltbld', 19:26:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:20 INFO - 'MOZ_NO_REMOTE': '1', 19:26:20 INFO - 'NO_EM_RESTART': '1', 19:26:20 INFO - 'PAGER': '/bin/cat', 19:26:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:26:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:20 INFO - 'PWD': '/builds/slave/test', 19:26:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:26:20 INFO - 'SHELL': '/bin/bash', 19:26:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.09C0aBEwsf/Listeners', 19:26:20 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 19:26:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:26:20 INFO - 'USER': 'cltbld', 19:26:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:26:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:26:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:20 INFO - 'XPC_FLAGS': '0x0', 19:26:20 INFO - 'XPC_SERVICE_NAME': '0', 19:26:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:26:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:26:20 INFO - Cleaning up... 19:26:20 INFO - Return code: 0 19:26:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:26:20 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')]} 19:26:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:20 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')]} 19:26:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:20 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 0x107a58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceab46bfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10786cd30>, '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 0x107692db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107864b20>, '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.C7I3rxWpCK/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.09C0aBEwsf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 19:26:20 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 19:26:20 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 19:26:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C7I3rxWpCK/Render', 19:26:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:26:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:26:20 INFO - 'HOME': '/Users/cltbld', 19:26:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:26:20 INFO - 'LOGNAME': 'cltbld', 19:26:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:20 INFO - 'MOZ_NO_REMOTE': '1', 19:26:20 INFO - 'NO_EM_RESTART': '1', 19:26:20 INFO - 'PAGER': '/bin/cat', 19:26:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:26:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:20 INFO - 'PWD': '/builds/slave/test', 19:26:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:26:20 INFO - 'SHELL': '/bin/bash', 19:26:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.09C0aBEwsf/Listeners', 19:26:20 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 19:26:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:26:20 INFO - 'USER': 'cltbld', 19:26:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:26:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:26:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:20 INFO - 'XPC_FLAGS': '0x0', 19:26:20 INFO - 'XPC_SERVICE_NAME': '0', 19:26:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:26:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:21 INFO - Downloading/unpacking blobuploader==1.2.4 19:26:21 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 19:26:21 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 19:26:21 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 19:26:21 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 19:26:21 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 19:26:21 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 19:26:23 INFO - Downloading blobuploader-1.2.4.tar.gz 19:26:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:26:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:26:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:26:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:26:24 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 19:26:24 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 19:26:24 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 19:26:24 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 19:26:24 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 19:26:24 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 19:26:24 INFO - Downloading docopt-0.6.1.tar.gz 19:26:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:26:24 INFO - Installing collected packages: blobuploader, requests, docopt 19:26:24 INFO - Running setup.py install for blobuploader 19:26:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:26:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:26:24 INFO - Running setup.py install for requests 19:26:25 INFO - Running setup.py install for docopt 19:26:25 INFO - Successfully installed blobuploader requests docopt 19:26:25 INFO - Cleaning up... 19:26:25 INFO - Return code: 0 19:26:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:26:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:25 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 0x107a58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceab46bfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10786cd30>, '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 0x107692db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107864b20>, '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.C7I3rxWpCK/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.09C0aBEwsf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 19:26:25 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 19:26:25 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 19:26:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C7I3rxWpCK/Render', 19:26:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:26:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:26:25 INFO - 'HOME': '/Users/cltbld', 19:26:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:26:25 INFO - 'LOGNAME': 'cltbld', 19:26:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:25 INFO - 'MOZ_NO_REMOTE': '1', 19:26:25 INFO - 'NO_EM_RESTART': '1', 19:26:25 INFO - 'PAGER': '/bin/cat', 19:26:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:26:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:25 INFO - 'PWD': '/builds/slave/test', 19:26:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:26:25 INFO - 'SHELL': '/bin/bash', 19:26:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.09C0aBEwsf/Listeners', 19:26:25 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 19:26:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:26:25 INFO - 'USER': 'cltbld', 19:26:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:26:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:26:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:25 INFO - 'XPC_FLAGS': '0x0', 19:26:25 INFO - 'XPC_SERVICE_NAME': '0', 19:26:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:26:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:26:25 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-CSAtMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:26:26 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-QjvpRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:26:26 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-vBjVmi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:26:26 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-x3WsdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:26:26 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-ksOmbT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:26:26 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-iw_aJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:26:26 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-dH4U0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:26:26 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-mkx53M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:26:26 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-9zws1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:26:26 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-P3QkFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:26:26 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-YZ2hXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:26:27 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-Y5phnU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:26:27 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-cYxnXw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:26:27 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-xq6igs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:26:27 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-MKb4Dq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:26:27 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-t_Qe8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:26:27 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-AFvt9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:26:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:26:27 INFO - Running setup.py install for manifestparser 19:26:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:26:27 INFO - Running setup.py install for mozcrash 19:26:27 INFO - Running setup.py install for mozdebug 19:26:28 INFO - Running setup.py install for mozdevice 19:26:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:26:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:26:28 INFO - Running setup.py install for mozfile 19:26:28 INFO - Running setup.py install for mozhttpd 19:26:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:26:28 INFO - Running setup.py install for mozinfo 19:26:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:26:28 INFO - Running setup.py install for mozInstall 19:26:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:26:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:26:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:26:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:26:28 INFO - Running setup.py install for mozleak 19:26:29 INFO - Running setup.py install for mozlog 19:26:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:26:29 INFO - Running setup.py install for moznetwork 19:26:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:26:29 INFO - Running setup.py install for mozprocess 19:26:29 INFO - Running setup.py install for mozprofile 19:26:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:26:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:26:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:26:29 INFO - Running setup.py install for mozrunner 19:26:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:26:29 INFO - Running setup.py install for mozscreenshot 19:26:29 INFO - Running setup.py install for moztest 19:26:30 INFO - Running setup.py install for mozversion 19:26:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:26:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:26:30 INFO - Cleaning up... 19:26:30 INFO - Return code: 0 19:26:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:30 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 0x107a58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceab46bfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10786cd30>, '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 0x107692db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107864b20>, '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.C7I3rxWpCK/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.09C0aBEwsf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 19:26:30 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 19:26:30 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 19:26:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C7I3rxWpCK/Render', 19:26:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:26:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:26:30 INFO - 'HOME': '/Users/cltbld', 19:26:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:26:30 INFO - 'LOGNAME': 'cltbld', 19:26:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:30 INFO - 'MOZ_NO_REMOTE': '1', 19:26:30 INFO - 'NO_EM_RESTART': '1', 19:26:30 INFO - 'PAGER': '/bin/cat', 19:26:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:26:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:30 INFO - 'PWD': '/builds/slave/test', 19:26:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:26:30 INFO - 'SHELL': '/bin/bash', 19:26:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.09C0aBEwsf/Listeners', 19:26:30 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 19:26:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:26:30 INFO - 'USER': 'cltbld', 19:26:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:26:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:26:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:30 INFO - 'XPC_FLAGS': '0x0', 19:26:30 INFO - 'XPC_SERVICE_NAME': '0', 19:26:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:26:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:26:30 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-CAOyRO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:26:30 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)) 19:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:26:30 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-MoP4Iy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:26:30 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)) 19:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:26:30 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-W_lHAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:26:30 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)) 19:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:26:30 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-OAkoYi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:26:31 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-9ir1vn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:26:31 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)) 19:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:26:31 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-pB5tj3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:26:31 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)) 19:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:26:31 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-GQPGzx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:26:31 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)) 19:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:26:31 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-IBwTWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:26:31 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)) 19:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:26:31 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-Wvdq3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:26:31 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)) 19:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:26:31 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-IzJ49j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:26:31 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)) 19:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:26:31 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-IfLALk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:26:31 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)) 19:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:26:31 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-RXR8Qx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:26:31 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)) 19:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:26:31 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-fRXGpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:26:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:26:31 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-zhMVOR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:26:32 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)) 19:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:26:32 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-aF0dTT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:26:32 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)) 19:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:26:32 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-nVg8CI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:26:32 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)) 19:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:26:32 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-tPusjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:26:32 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)) 19:26:32 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)) 19:26:32 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)) 19:26:32 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)) 19:26:32 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)) 19:26:32 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)) 19:26:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:26:32 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 19:26:32 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 19:26:32 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 19:26:32 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 19:26:32 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 19:26:32 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 19:26:34 INFO - Downloading blessings-1.6.tar.gz 19:26:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:26:34 INFO - Installing collected packages: blessings 19:26:34 INFO - Running setup.py install for blessings 19:26:35 INFO - Successfully installed blessings 19:26:35 INFO - Cleaning up... 19:26:35 INFO - Return code: 0 19:26:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:26:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:26:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:26:35 INFO - Reading from file tmpfile_stdout 19:26:35 INFO - Current package versions: 19:26:35 INFO - blessings == 1.6 19:26:35 INFO - blobuploader == 1.2.4 19:26:35 INFO - docopt == 0.6.1 19:26:35 INFO - functools32 == 3.2.3-2 19:26:35 INFO - jsonschema == 2.5.1 19:26:35 INFO - manifestparser == 1.1 19:26:35 INFO - mozInstall == 1.12 19:26:35 INFO - mozcrash == 0.17 19:26:35 INFO - mozdebug == 0.1 19:26:35 INFO - mozdevice == 0.48 19:26:35 INFO - mozfile == 1.2 19:26:35 INFO - mozhttpd == 0.7 19:26:35 INFO - mozinfo == 0.9 19:26:35 INFO - mozleak == 0.1 19:26:35 INFO - mozlog == 3.3 19:26:35 INFO - moznetwork == 0.27 19:26:35 INFO - mozprocess == 0.23 19:26:35 INFO - mozprofile == 0.28 19:26:35 INFO - mozrunner == 6.12 19:26:35 INFO - mozscreenshot == 0.1 19:26:35 INFO - mozsystemmonitor == 0.3 19:26:35 INFO - moztest == 0.7 19:26:35 INFO - mozversion == 1.4 19:26:35 INFO - psutil == 3.1.1 19:26:35 INFO - requests == 1.2.3 19:26:35 INFO - wsgiref == 0.1.2 19:26:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:26:35 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')]} 19:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:35 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')]} 19:26:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:35 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 0x107a58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceab46bfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10786cd30>, '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 0x107692db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107864b20>, '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.C7I3rxWpCK/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.09C0aBEwsf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 19:26:35 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 19:26:35 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 19:26:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C7I3rxWpCK/Render', 19:26:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:26:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:26:35 INFO - 'HOME': '/Users/cltbld', 19:26:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:26:35 INFO - 'LOGNAME': 'cltbld', 19:26:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:35 INFO - 'MOZ_NO_REMOTE': '1', 19:26:35 INFO - 'NO_EM_RESTART': '1', 19:26:35 INFO - 'PAGER': '/bin/cat', 19:26:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:26:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:35 INFO - 'PWD': '/builds/slave/test', 19:26:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:26:35 INFO - 'SHELL': '/bin/bash', 19:26:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.09C0aBEwsf/Listeners', 19:26:35 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 19:26:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:26:35 INFO - 'USER': 'cltbld', 19:26:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:26:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:26:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:35 INFO - 'XPC_FLAGS': '0x0', 19:26:35 INFO - 'XPC_SERVICE_NAME': '0', 19:26:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:26:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:35 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)) 19:26:35 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)) 19:26:35 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)) 19:26:35 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)) 19:26:35 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)) 19:26:35 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)) 19:26:35 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)) 19:26:35 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)) 19:26:35 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)) 19:26:35 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)) 19:26:35 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)) 19:26:35 INFO - Cleaning up... 19:26:35 INFO - Return code: 0 19:26:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:26:35 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')]} 19:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:35 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')]} 19:26:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:35 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 0x107a58490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceab46bfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10786cd30>, '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 0x107692db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107864b20>, '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.C7I3rxWpCK/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.09C0aBEwsf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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 19:26:35 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 19:26:35 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 19:26:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C7I3rxWpCK/Render', 19:26:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:26:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:26:35 INFO - 'HOME': '/Users/cltbld', 19:26:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:26:35 INFO - 'LOGNAME': 'cltbld', 19:26:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:35 INFO - 'MOZ_NO_REMOTE': '1', 19:26:35 INFO - 'NO_EM_RESTART': '1', 19:26:35 INFO - 'PAGER': '/bin/cat', 19:26:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:26:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:35 INFO - 'PWD': '/builds/slave/test', 19:26:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:26:35 INFO - 'SHELL': '/bin/bash', 19:26:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.09C0aBEwsf/Listeners', 19:26:35 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 19:26:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:26:35 INFO - 'USER': 'cltbld', 19:26:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:26:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:26:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:35 INFO - 'XPC_FLAGS': '0x0', 19:26:35 INFO - 'XPC_SERVICE_NAME': '0', 19:26:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:26:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:26:35 INFO - Cleaning up... 19:26:35 INFO - Return code: 0 19:26:35 INFO - Running post-action listener: _resource_record_post_action 19:26:35 INFO - Running post-action listener: _start_resource_monitoring 19:26:35 INFO - Starting resource monitoring. 19:26:35 INFO - [mozharness: 2016-09-13 02:26:35.976826Z] Finished create-virtualenv step (success) 19:26:35 INFO - [mozharness: 2016-09-13 02:26:35.977896Z] Running install step. 19:26:35 INFO - Running pre-action listener: _resource_record_pre_action 19:26:35 INFO - Running main action method: install 19:26:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:26:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:26:36 INFO - Reading from file tmpfile_stdout 19:26:36 INFO - Detecting whether we're running mozinstall >=1.0... 19:26:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:26:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:26:36 INFO - Reading from file tmpfile_stdout 19:26:36 INFO - Output received: 19:26:36 INFO - Usage: mozinstall [options] installer 19:26:36 INFO - Options: 19:26:36 INFO - -h, --help show this help message and exit 19:26:36 INFO - -d DEST, --destination=DEST 19:26:36 INFO - Directory to install application into. [default: 19:26:36 INFO - "/builds/slave/test"] 19:26:36 INFO - --app=APP Application being installed. [default: firefox] 19:26:36 INFO - mkdir: /builds/slave/test/build/application 19:26:36 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'] 19:26:36 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 19:27:10 INFO - Reading from file tmpfile_stdout 19:27:10 INFO - Output received: 19:27:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:27:10 INFO - Running post-action listener: _resource_record_post_action 19:27:10 INFO - [mozharness: 2016-09-13 02:27:10.521353Z] Finished install step (success) 19:27:10 INFO - [mozharness: 2016-09-13 02:27:10.521518Z] Running run-tests step. 19:27:10 INFO - Running pre-action listener: _resource_record_pre_action 19:27:10 INFO - Running main action method: run_tests 19:27:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:27:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:27:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:27:10 INFO - 2016-09-12 19:27:10.536 screenresolution[1646:7610] starting screenresolution argv=screenresolution get 19:27:10 INFO - 2016-09-12 19:27:10.538 screenresolution[1646:7610] Display 0: 1600x1200x32@60 19:27:10 INFO - 2016-09-12 19:27:10.544 screenresolution[1647:7612] starting screenresolution argv=screenresolution list 19:27:10 INFO - Available Modes on Display 0 19:27:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:27:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:27:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:27:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:27:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:27:10 INFO - Intel Iris: 19:27:10 INFO - Chipset Model: Intel Iris 19:27:10 INFO - Type: GPU 19:27:10 INFO - Bus: Built-In 19:27:10 INFO - VRAM (Dynamic, Max): 1536 MB 19:27:10 INFO - Vendor: Intel (0x8086) 19:27:10 INFO - Device ID: 0x0a2e 19:27:10 INFO - Revision ID: 0x0009 19:27:10 INFO - Displays: 19:27:10 INFO - Display: 19:27:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:27:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:27:10 INFO - Display Serial Number: 1600x1200 60 19:27:10 INFO - Main Display: Yes 19:27:10 INFO - Mirror: Off 19:27:10 INFO - Online: Yes 19:27:10 INFO - Rotation: Supported 19:27:10 INFO - Return code: 0 19:27:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:27:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:27:10 INFO - Python 2.7.3 19:27:10 INFO - Return code: 0 19:27:10 INFO - grabbing minidump binary from tooltool 19:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:27:10 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 0x10786cd30>, '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 0x107692db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107864b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:27:10 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 19:27:10 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 19:27:10 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 19:27:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:27:10 INFO - Return code: 0 19:27:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:27:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:27:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:27:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:27:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:27:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c-xUpIT7Qg2_IrnIHzFOqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0291', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 19:27:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/c-xUpIT7Qg2_IrnIHzFOqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0291 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 19:27:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C7I3rxWpCK/Render', 19:27:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:27:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:27:10 INFO - 'HOME': '/Users/cltbld', 19:27:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:27:10 INFO - 'LOGNAME': 'cltbld', 19:27:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:27:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:27:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:10 INFO - 'MOZ_NO_REMOTE': '1', 19:27:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:27:10 INFO - 'NO_EM_RESTART': '1', 19:27:10 INFO - 'PAGER': '/bin/cat', 19:27:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:27:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:10 INFO - 'PWD': '/builds/slave/test', 19:27:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:27:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:27:10 INFO - 'SHELL': '/bin/bash', 19:27:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.09C0aBEwsf/Listeners', 19:27:10 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 19:27:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:27:10 INFO - 'USER': 'cltbld', 19:27:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:27:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:27:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:10 INFO - 'XPC_FLAGS': '0x0', 19:27:10 INFO - 'XPC_SERVICE_NAME': '0', 19:27:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:27:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c-xUpIT7Qg2_IrnIHzFOqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0291', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 19:27:11 INFO - mozversion application_buildid: 20160912172009 19:27:11 INFO - mozversion application_changeset: b1156b0eb96fcb49966b20e5fcf6a01f634ea2ee 19:27:11 INFO - mozversion application_display_name: Nightly 19:27:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:27:11 INFO - mozversion application_name: Firefox 19:27:11 INFO - mozversion application_remotingname: firefox 19:27:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:27:11 INFO - mozversion application_vendor: Mozilla 19:27:11 INFO - mozversion application_version: 51.0a1 19:27:11 INFO - mozversion platform_buildid: 20160912172009 19:27:11 INFO - mozversion platform_changeset: b1156b0eb96fcb49966b20e5fcf6a01f634ea2ee 19:27:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:27:11 INFO - mozversion platform_version: 51.0a1 19:27:11 INFO - using testdate: 1473733631 19:27:11 INFO - actual date: 1473733631 19:27:11 INFO - starting webserver on 'localhost:49273' 19:27:11 INFO - SUITE-START | Running 2 tests 19:27:11 INFO - TEST-START | dromaeo_css 19:27:11 INFO - operating with platform_type : mac_ 19:27:11 INFO - Initialising browser for dromaeo_css test... 19:27:11 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmptC43mN/profile http://localhost:49273/getInfo.html) 19:27:14 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 19:27:14 INFO - PROCESS | 1657 | colorDepth:24 19:27:14 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 19:27:14 INFO - PROCESS | 1657 | __metrics 19:27:14 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 19:27:14 INFO - TEST-INFO | 1657: exit 0 19:27:14 INFO - Browser initialized. 19:27:14 INFO - Running cycle 1/1 for dromaeo_css test... 19:27:14 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmptC43mN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:27:26 INFO - PROCESS | 1660 | RSS: Main: 245239808 19:27:26 INFO - PROCESS | 1660 | 19:28:16 INFO - PROCESS | 1660 | 1473733696468 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] 19:30:28 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 19:30:29 INFO - PROCESS | 1660 | RSS: Main: 266850304 19:30:29 INFO - PROCESS | 1660 | 19:33:31 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 19:33:32 INFO - PROCESS | 1660 | RSS: Main: 273973248 19:33:32 INFO - PROCESS | 1660 | 19:36:34 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 19:36:35 INFO - PROCESS | 1660 | RSS: Main: 278380544 19:36:35 INFO - PROCESS | 1660 | 19:39:36 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 19:39:37 INFO - PROCESS | 1660 | RSS: Main: 273674240 19:39:37 INFO - PROCESS | 1660 | 19:42:40 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 19:42:41 INFO - PROCESS | 1660 | RSS: Main: 279142400 19:42:41 INFO - PROCESS | 1660 | 19:45:43 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 19:45:44 INFO - PROCESS | 1660 | RSS: Main: 281833472 19:45:44 INFO - PROCESS | 1660 | 19:45:44 INFO - PROCESS | 1660 | __start_tp_report 19:45:44 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 19:45:44 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 19:45:44 INFO - PROCESS | 1660 | |i|pagename|runs| 19:45:44 INFO - PROCESS | 1660 | |0;dojo.html;2940.691227421121;3371.1403592084043;3391.9660803391976;3392.7625066245364;3410.1474631342167;5781.595288329813;5857.3849745776715;5898.498627616653;5905.527557795377;5909.084091965752;5650.152477128422;5803.506701930331;5817.272840894888;5837.445442682949;5887.8528036799;5189.792408303663;5215.191645294985;5282.154855223165;5306.416294207634;5373.596980226479;5254.737263136847;5339.305890234284;5345.919811202815;5373.892522149555;5430.5384042356445;5147.536721695065;5239.056969745444;5275.709835959021;5280.313559237275;5361.303030606015;5240.842774716764;5265.183896496049;5282.735863206863;5288.338957630296;5302.787888484456;4791.592714619262;5261.473852614746;5271.789128434896;5280.9471905281225;5308.150695888678;5644.745986430579;5671.886562268773;5740.339860915965;5754.964106460835;5784.1034639630925;5186;6139.154842350791;6189.969050154766;6198.597091189058;6208.9689551551955;5157.922631160535;6233.594816336924;6234.407731265568;6244.875102497925;6270.749170033193;5163.483651634807;5739.799107031317;5786.623869448473;5834.474897259267;5944.227250457456;6189.907151392733;6226.439620434092;6250.6874656267;6321.620702757849;6331.081993110973;6092.72582733778;6093;6126.479249263863;6131.294901086432;6132.294786099566;6095.2685677718955;6104.542159338067;6240.31356550779;6249.6875156242;6253;6057.515398768088;6082.604630699079;6116.548817444082;6120.873812997581;6224.253089629183;6179.3820617938745;6182.6599537025895;6183.84540386485;6189.442950134509;6194.349593292639;4958;5046.1673823818655;5050.3182070420025;5050.873728156826;5053.1157047516535;4319.0066284754685;4360.215161270939;4419.0719948810465;4475.48531918827;4506.887327816831;4320.1143765527995;5372.91940620891;5647.802326918538;5654.74553645087;5698.7435565399655;6117.143599896018;6123.693815309217;6138.693065346715;6140.9078863816185;6152;5618.943810561924;5724.542036637059;5823.30120385548;5825.271841019872;5872.765089396376;4445.599896009375;4993.975030124826;5531.8893622128135;5556.388797232301;5575.860603484945;5077.339945807095;6351.618902865843;6402.583832050995;6403.295637479873;6418.807435776934;5354.598405119709;6109.2974307955155;6149.016157414792;6163.568550201622;6274.215723034621;5694.9179655865255;6045.183900173421;6343.238811342669;6385.68071596409;6392.712327945161;6091.299500557493;6177.320494745484;6190.133381326528;6195.1636529068;6196.876062478816;5165.9483405165465;6107.725152368065;6111.174991376108;6201.410865967726;6287.151234583455;6172.105044768573;6182.505399568114;6183.258009038944;6187.597806142587;6189.535784816247;6160.784372547118;6178.62928224308;6183.96908015457;6189.319174890668;6189.783357582463;5020.799168033238;5855.765769369348;5957.880842383215;5962.373950735284;6028.367021462684;6095.190505933791;6142.529470849135;6155.35368786271;6158.630482171084;6173.722182501709;6087.634741915499;6094.512439004958;6109.022556390956;6111.205543279345;6120.204373431604 19:45:44 INFO - PROCESS | 1660 | |1;ext.html;471.16603611607434;488.3431784250183;493.6618416384774;497.5174081141293;528.3606835728768;38438;40693.389599156035;40705.38941915874;43150.56849431505;43213.783931080376;42055.36916946248;42064.57935420645;42212.57787422125;42515.36226956598;42535.361969570484;9718.805623887518;9878.802423951553;9916.405015699082;9933.304668673196;9944.403335799876;9747.415155090683;9877.851832222523;9888.505574721272;9901.603935842557;9913;9884.308098433077;9892.752681182956;9913.702588922308;9915.95042024789;9936.90063099367;8360.205780450848;9867.40795552262;9877.851832222486;9881.160101391319;9903.8514422283;9759.316847820655;9822.508874556315;9833.606655733762;9859.211263098934;9873.703788886345;40023;40153.19693606141;40417.59582404197;40451.78644640665;40492.797536012135;3392.0841372829445;3537.00963730156;3585.515955346021;3795.3168429682646;3973.3841254605295;30212.395752084834;30237.697623023927;30918.0724578263;31014.689853101405;31329.216769580944;3173.143251322153;3241.9351612967607;3243.3189030303665;3348.179695974502;3391.2030672791875;498592;499920;502331.48834255594;503351;503892;32637.51043734346;33633;47480.28779568309;58744.70627646834;59323.40676593179;27831.30421739473;31650.525242121388;32808;35002.29995400078;35242;14235.85764142366;14496.710065798625;14615.853841461661;14668.926655366655;14692.191929443983;11946.98450631706;12356.135070545155;12472.937635311766;12491.188072775241;12539.373031348396;6573.441257493071;7651.885221721679;7661.15727270011;7692.730754423568;7721.884171737428;3255.9023229303157;3259.1363288728508;3263.951040734391;3277.5083737439577;3300.950485742716;937.6577549194632;967.4195482710318;991.4695637833725;1007.9496025198857;1099.1261946752352;29740.2564935874;30065.849670751508;30224.69775302219;30430.391392172034;30883.382332353227;942.1198193358468;1040.2302296300834;1069.2889228662852;1077.7844431113808;1111.649830303468;15346.769798453031;15406.152661603495;15442.382304707911;15499.070055796688;16240.269187886573;654977;664415;667313;667870;668822.6558867272;54732.72633636806;55244.17133742997;55293;55351;55437;1124.651358078998;1148.2249481599886;1341.7517759214577;1383.557261676254;1386.9791953120914;29084;29184.27039324034;29496.557551636313;29878.551821723126;30100.397992040474;47861.803454913905;48706.75646621744;49196.55624106278;49197.75401123115;49350.50649493459;487297;487592;489123.108768922;490873.0039695559;491363;25250;26215.95136194531;26244.95020199247;26297.553634550284;26384;45171.30845507818;45224;46331.53668463272;46417.53582464132;46985.76507117443;38104.618953811216;38110;38438.6156138435;39209.82370528889;39614.40578391269;30636;33479.83260083684;38284.291760019914;38741.80629096949;38789.806050969564;37378.252434950606;37863.62136378601;37896.05259868525;38020.80989595034;39188.41217381799;28345.574816378183;30531;32070.83964580162;33825.830870846476;34163.48754768631;24147.879260603586;24291.149809756575;24567.52594844315;25561.360965975255;25890.611640825027 19:45:44 INFO - PROCESS | 1660 | |2;jquery.html;317.4365501235307;372.8583138407405;382.262233888595;386.4030073536386;405.5073086200268;17087.743683844754;20180.4954876128;20905.581888362223;20923.267685630988;21042.789572104273;19756.11097500616;19864.40406787795;20722.378328650128;20756.68864967027;20772.584548309023;5847.707614619263;5864.941350586503;6076.696165191723;6120.4185602367925;6133.601315914473;3832.9616703832885;4356.934645980297;5939.227900372945;6045.939540604603;6070.0591408331775;6479.22249330081;6845.657717114124;6874.27820078892;6893.17281926167;6943.965280173617;6066.029435290377;6571.671416429183;6810.353016463453;6935.063766391523;6952.478564107661;5981.58128930975;6008.429199226067;6024.48791852693;6048.304444988795;7325.597092159929;26442.86778566114;27870.860645696845;28404;29154.97957571496;29671.851640741876;2528.448792174098;2591.20968104728;2601.2846467221475;2783.498970185366;2794.7345002224756;14121.293935303194;16207.675846483122;17042.659146817117;17046.595626695424;17768.37810676633;2026.6421497596498;2092.7593326767465;2141.4860433496005;2173.7608863025052;2274.7042884425086;145529;147780;147784;149033.99999999892;150688;17059.48821535356;21469;21701.67447488289;21811.345659630235;21855.158829352084;35179.29641407158;35392.93821185369;35465.822670887;35474.29051418957;35611.28777424437;10697.679069627768;10850.29473084247;11079.50142243601;11084.556617735201;11193.440327983568;8441.915580844114;8974.910250897537;9061.68284110053;9075.2286055686;9220.124088211609;9714.465704386184;9815.871174814845;9823.508824558887;9838.852417213748;9987.250956178315;1601.6476375197437;1680.5378520906604;3802.543694756592;3900.2004589059175;4022.6781857451333;814.450246083891;854.3634991930927;861.642418396371;942.5805516545208;961.5240455974198;19996;20591.4852128695;20882.895585521976;21331.93340332977;21461.57076858454;827.6523859978823;867.280157469299;929.3076657889916;937.2361525356812;1021.7343490692474;8317.376196785144;11199.832002519968;12011.579594714143;12042.698932526757;12323.938380308042;593349;594543;598369;614776;615327;93405.59891601632;134572.32713836367;138993;142763.8650714701;146409.53590464173;767.48578452437;927.9907200928039;965.1940629574384;997.3915911294105;1026.8665073540542;19365.031748412806;19554.51113722168;19627;19652.901735491232;19661.91859447687;59163.40836591751;59292.40707592869;59365.70317148387;59913.10130348135;60543;84814;85222;86087.5695621518;86954.69567956359;87279.56360218412;6151.200343955257;6636;6981.650917454209;8132.390070744599;8139.877901831359;12126.333051682243;12510.562130325396;12687.873121269038;12732.172408793398;12958.870411295766;10790.352578845295;10984.01143897053;11124.555017799197;11282.21024528308;11369.602063927723;6005.6396616203165;8899.866502002606;10888.836667449836;11103.888961110286;11351.659450216506;10900.618478353219;11092.833607496055;11282.153838461983;11308.49111789955;11343.943280283545;8039.316658084128;8183.918160818315;8263.95868020656;8269.29710974574;8287.958560207402;9403.623855045995;9429.765502658103;11223.943880280873;11280.09759219244;11306.717332066764 19:45:44 INFO - PROCESS | 1660 | |3;mootools.html;1452.9636759081022;1495.4765831958812;1699.8300169983004;1852.203552472438;1864.6736821056304;7935.127136015049;8212.219839115274;8415.91584084159;8974.865377019341;9004.054574269705;8346.41575089744;8658.26404755595;8663.480191188517;8685.305175585954;8791.868121978176;2325.651152327152;2385.844920080198;2396.113438027923;2398.328468028955;2411.5538625354366;2359.587072262356;2366.5148644527917;2404.879756012201;2408.3738228060743;2415.9879200603973;2395.5448464791634;2402.327348342467;2410.144398738449;2427.2718184544656;2428.7449817769143;2382.118616112032;2383.380321116505;2385.8926348314367;2391.0913852735935;2411.5779738545684;2372.2764556810166;2383.2611890314056;2386.3795413192693;2389.3668177932864;2399.7120345558646;8710.912890871074;8762.780930476789;8788.20906118446;8795.47227166372;8812.295016398673;5280.287161233223;5430.348358197002;5433.673979561199;5435.130379139318;5505.421930697259;3531.399662057456;3661.3226553087766;3676.5588129424373;3687.8340474678703;3787.621237876218;917.3945196170562;1084.3006260961715;1097.0894157848966;1106.3804269609045;1115.341948250536;11347.886521134847;11393.430328483626;11400;11485.138614603939;11528;4590.908181836411;4673.439187297478;4849.515048495193;4875.293082503017;4891.535304146171;8303.4602750821;9877.11106000449;9922.057404546556;9946.303758736964;9959.850602240827;5300.01949639318;5377.892442151135;5382.919256211082;5387.46125387466;5389.75746091419;4267.935980960288;4410.29435290352;4712.929306060412;4881.707097574157;4901.264810278487;3209.149575362531;3810.599887011879;3902.453656488094;3973.264945984944;3995.881153277087;1117.312852595664;1264.3804535777404;1317.841858976929;1321.1082519299434;1330.0689517337903;784.3842583571983;864.7449002544264;868.9963092628025;871.7036207689289;957.6265256549951;5615.859603509945;5782.277215348082;5913.704314784243;5975.67133807636;6071.089336599457;445.8238995596721;451.63643267169584;453.4581175495251;460.7212636355024;464.92561190209403;3683.9132455925564;3932.646061854447;3979.46277252573;4047.4738284022887;4051.8784436466954;9140.63437462494;10540.683779486628;10651.893481065244;10658.467076646137;10672.626458074086;10328.122109620615;10544.261901666963;10569.207309451667;10610.94694526538;10632.362058276529;7966.1237263902085;8171.713990010321;8199.385046121564;8219.54792486419;8313.833723325499;5682.318121825408;5853.356130825691;5894.322152952293;5896.528277737856;5933.139694744152;9840.507974601385;9924.603015879284;9955.800883982425;9999.500024998575;10005.299629025898;8135.227153420416;10109.34289271195;10199.337043092175;10204.846927296245;10211.336263142872;6888.104546409135;7125.964370178116;7287.089113860768;7418.443616728659;7477.775666730006;8124.431289809657;8438.746837594881;8446.408751387344;8470.9834819822;8538.530380828866;7371.152317483344;7514.248575142661;7517.774466766005;7555.73554925597;7621.12357078943;7223.2415596361625;7276.96361518189;7333.926660733325;7379.594122323109;7431.3683336916965;6903.0680858083515;6925.653717314215;6967.825804354931;6980.720771169096;6996.335348141918;4918.409790825124;4973.129702302011;4979.3277907482025;5102;5116.283720279238;4947.777350019186;5017.297578338962;5138.075146473521;5155.484451554815;5224.373075230997 19:45:44 INFO - PROCESS | 1660 | |4;prototype.html;300.7563873262656;308.2647884794764;314.25364758698095;315.2654315445013;318.3505648477107;4253.829846806132;5622.746976386063;5660.2075709400615;5728.914066289009;5853.414658534144;5107.28498010278;5109.591232701384;5565.721713914309;5605.27131472909;5673.347565030022;3588.5155504006884;3991.7405368650943;4242.321228603417;4266.975925777807;4274.764887931163;3796.791176485292;4117.135401565675;4200;4265.210935976854;4307.353896915472;3487.250241198142;3848.786263469992;4008.819603117867;4185.623293903548;4188.267053265667;3647.4528820676846;4183.686223533232;4192.951762059485;4207.137536804948;4209.97895010526;3143.135637699651;3452.188735647121;3465.9653403465895;3574.088607405121;3585.2790660483115;4251.489821221442;5401.379586124147;5475.288212532345;5478.753456094445;5482.533984611313;4348.630366418858;4444.088961762831;4529.501754806968;4831;4885.56029957302;4721.811127554894;5608.803691870806;5733.219388596527;5947.881042379171;5984.820455386345;3525.012996361023;3981.900452488711;3986.54154772202;3986.960130398688;3991.640752332275;5231.398389185254;6284.946209694813;6292.716827742763;6351.333110023427;6352.841178970517;3145.6382516010954;3256.2836176041233;3372.4823829276434;3751.643593858579;4043.939340909889;4084.9795751021056;4095.385692146202;4099.385092236129;4133.338665813455;4141.7100802943505;4192.8972560305765;4252.596003379674;4682.4381074271305;4733.2900064989835;4853.174960256765;5163.580015495693;5658.547316214693;5721.885562288731;5989.610675306091;6075.605085669417;1110.76704857608;1239.6405042537745;1250.8561515425843;1264.6016504800957;1360.857110003455;893.4773157702816;907.614263937837;917.8852643419573;919.4069824962861;935.7320830275015;5112.750389828464;5336.706481143497;5472.98749731301;5484.91772623411;5519.917201241985;5178.482151784791;5816.185733997244;5960.8807823844145;6034.978282275399;6049.697515124314;5227.320448341768;5392.299001129828;5489.58828087895;5495.313085864267;5508.504234618904;2153.7415510138885;2351.470919043202;2612.7648511634043;2669.7196794336705;2718.605802158676;4745.644076694192;5226.5818734501145;5239.659422137549;5253.768932106803;5279.947200528022;4392.648588112943;4417.730879149406;4801.879953001203;5070.783012077149;5222.843314700565;4393.298121655159;4616.653750968623;4961.404631444249;5254.185601231815;5341;4277.422547956111;4313.654907607447;4628.467726211529;4926.896055485022;5186.559142472932;3711.828580280458;4131.483564554431;4959.512146356006;5219.451957544403;6120.816375508742;5858.974679430998;5986.880262394815;6178.66017369049;6401.743930242738;6447.937799186482;4006.7796271204197;4067.308557545165;4069.023434375788;4075.65356944663;4089.938650920179;5853.161216809759;5904.468597826216;5978.701064946822;6055.727492262947;6124.234470691164;5872.588918775645;5893.9115913260475;5991.861546306188;6028.57799953999;6039.728212230372;4990.825321113889;5995.520358371407;6032.285471537738;6051.213342265477;6093.9085913712215;5067.391912970467;5550.556855217753;5892.616979896293;5895.174675545512;5904.557158213237;4864.440589332272;5529.502344788988;5806.76772929095;6029.457348838626;6066.211392518944;4686.765661716832;5513.145462453326;5789.2763404574425;5863.736131874161;5910.822675319747 19:45:44 INFO - PROCESS | 1660 | |5;yui.html;601.9138281521147;715.5241764226788;726.8546290741849;748.2442732839834;818.1327792539912;14842.851571484282;15474.767878481804;15497;15505.534833954971;15529.456469023586;14094.859051409483;14139.93950453715;14210.289485525735;14225.573232803032;14237.288135593231;833.8749187621845;855.2943821347383;861.5476874640832;862.8231212601402;866.6576702202658;856.5588721808274;859.5530324231396;859.6475445067524;862.0991064337782;863.2791618998144;876.071364353787;894.6421431427449;896.3635818568791;896.6727144592243;899.8920129584459;864.4985908173245;881.2069137775989;892.1168043636818;894.3292530602048;894.4946105450465;655.5542231282726;661.6327937994412;664.120040945744;665.5141746525064;665.5740326191242;13802.723945521133;13817.447302107903;13850.30748462583;13873.4450621975;13886.583402497941;10119.645812396531;11682.941585292105;11907.523699052028;12198.268103913793;12397.442115104843;10726.570937162503;10777.886594847441;10895.509702063348;11084.667459976214;11341.603043893507;3666.816659167058;3707.9629203707887;3724.832382542792;3867.187890542961;3869.8452061917487;101860;102773;103287;103405.96594034114;104068;8974.326925480615;9039.864402033974;9355.906440935503;10086.344387614781;10489.737756555995;20446.182152714024;20754;20786.58426831455;20819;20882.58234835295;7634;7777.72777952769;7784.260495252942;7928.127905930343;7937.523748575104;4319.416878721395;4347.239233134189;4368.803403846835;4404.986853023823;4514.7291162530355;3439.5872495300723;3508.2281897982402;3543.734219933464;3572.303400836864;3573.6069032406413;655.446148004935;659.8713250916026;661.765073398946;670.4167374384219;674.6727836999052;583.4049269744836;584.7281014328273;604.7611193578512;606.6117684681807;610.578700696518;7718.035245594301;7720.806979825437;8342.082370939192;8607.61265743043;8800.339974501938;499.28103530915365;567.1266249974992;576.4466112532016;580.5268706004592;603.6136872401667;3591.3535563598803;4204.768737719393;4286.314189729628;4293.291606884892;4391.956080439155;158936;161403;166302;167796;168387.1580642089;142687;145786;146766;146923;151453.24273378562;79499.20500795034;90005;105877.41183882402;110961;119241.8075819248;7031.613261270679;7627.89395537655;8002.359811215205;8133.959330203311;8280.668773249003;37567.81216093902;42368.88155592146;47929.76035119802;53432.73283633557;56541.71729141328;68148;70544.12382266589;77496.61251693705;88283.11716883005;88440.5577972106;10729.517171727448;10931.726706832393;10952.890471095187;10965.61620343284;11414.714632134263;11764.352960586908;11842.052635788943;11873.050155987328;12319.199252048587;12328.691782705146;10436.321639093434;10733.76561695415;11257.15571332137;11358.488868000795;11737.35444550558;11015.394153321644;11099.889001109885;11257.49341279628;11301.344184116562;11386.886131138583;10806.45967701596;10857.294275872044;10892.945535272273;11009.284396514522;11306.547738090714;9156.359054866096;9466.337356384987;9500.755527278665;9501.524923753923;9723.124918757347;7632.580208088609;8259.297959673495;9226.35415520907;9358;9367.812643747224 19:45:44 INFO - PROCESS | 1660 | __end_tp_report 19:45:44 INFO - PROCESS | 1660 | __start_cc_report 19:45:44 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,376 19:45:44 INFO - PROCESS | 1660 | __end_cc_report 19:45:44 INFO - PROCESS | 1660 | __startTimestamp1473734744064__endTimestamp 19:45:44 INFO - PROCESS | 1660 | 19:45:44 INFO - PROCESS | 1660 | ------- Summary: start ------- 19:45:44 INFO - PROCESS | 1660 | Number of tests: 6 19:45:44 INFO - PROCESS | 1660 | 19:45:44 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5707.99 Median:5944.23 stddev:656.53 (11.0%) stddev-sans-first:621.62 19:45:44 INFO - PROCESS | 1660 | Values: 2940.7 3371.1 3392.0 3392.8 3410.1 5781.6 5857.4 5898.5 5905.5 5909.1 5650.2 5803.5 5817.3 5837.4 5887.9 5189.8 5215.2 5282.2 5306.4 5373.6 5254.7 5339.3 5345.9 5373.9 5430.5 5147.5 5239.1 5275.7 5280.3 5361.3 5240.8 5265.2 5282.7 5288.3 5302.8 4791.6 5261.5 5271.8 5280.9 5308.2 5644.7 5671.9 5740.3 5755.0 5784.1 5186.0 6139.2 6190.0 6198.6 6209.0 5157.9 6233.6 6234.4 6244.9 6270.7 5163.5 5739.8 5786.6 5834.5 5944.2 6189.9 6226.4 6250.7 6321.6 6331.1 6092.7 6093.0 6126.5 6131.3 6132.3 6095.3 6104.5 6240.3 6249.7 6253.0 6057.5 6082.6 6116.5 6120.9 6224.3 6179.4 6182.7 6183.8 6189.4 6194.3 4958.0 5046.2 5050.3 5050.9 5053.1 4319.0 4360.2 4419.1 4475.5 4506.9 4320.1 5372.9 5647.8 5654.7 5698.7 6117.1 6123.7 6138.7 6140.9 6152.0 5618.9 5724.5 5823.3 5825.3 5872.8 4445.6 4994.0 5531.9 5556.4 5575.9 5077.3 6351.6 6402.6 6403.3 6418.8 5354.6 6109.3 6149.0 6163.6 6274.2 5694.9 6045.2 6343.2 6385.7 6392.7 6091.3 6177.3 6190.1 6195.2 6196.9 5165.9 6107.7 6111.2 6201.4 6287.2 6172.1 6182.5 6183.3 6187.6 6189.5 6160.8 6178.6 6184.0 6189.3 6189.8 5020.8 5855.8 5957.9 5962.4 6028.4 6095.2 6142.5 6155.4 6158.6 6173.7 6087.6 6094.5 6109.0 6111.2 6120.2 19:45:44 INFO - PROCESS | 1660 | 19:45:44 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:66873.64 Median:39199.12 stddev:149330.79 (381.0%) stddev-sans-first:149666.48 19:45:44 INFO - PROCESS | 1660 | Values: 471.2 488.3 493.7 497.5 528.4 38438.0 40693.4 40705.4 43150.6 43213.8 42055.4 42064.6 42212.6 42515.4 42535.4 9718.8 9878.8 9916.4 9933.3 9944.4 9747.4 9877.9 9888.5 9901.6 9913.0 9884.3 9892.8 9913.7 9916.0 9936.9 8360.2 9867.4 9877.9 9881.2 9903.9 9759.3 9822.5 9833.6 9859.2 9873.7 40023.0 40153.2 40417.6 40451.8 40492.8 3392.1 3537.0 3585.5 3795.3 3973.4 30212.4 30237.7 30918.1 31014.7 31329.2 3173.1 3241.9 3243.3 3348.2 3391.2 498592.0 499920.0 502331.5 503351.0 503892.0 32637.5 33633.0 47480.3 58744.7 59323.4 27831.3 31650.5 32808.0 35002.3 35242.0 14235.9 14496.7 14615.9 14668.9 14692.2 11947.0 12356.1 12472.9 12491.2 12539.4 6573.4 7651.9 7661.2 7692.7 7721.9 3255.9 3259.1 3264.0 3277.5 3301.0 937.7 967.4 991.5 1007.9 1099.1 29740.3 30065.8 30224.7 30430.4 30883.4 942.1 1040.2 1069.3 1077.8 1111.6 15346.8 15406.2 15442.4 15499.1 16240.3 654977.0 664415.0 667313.0 667870.0 668822.7 54732.7 55244.2 55293.0 55351.0 55437.0 1124.7 1148.2 1341.8 1383.6 1387.0 29084.0 29184.3 29496.6 29878.6 30100.4 47861.8 48706.8 49196.6 49197.8 49350.5 487297.0 487592.0 489123.1 490873.0 491363.0 25250.0 26216.0 26245.0 26297.6 26384.0 45171.3 45224.0 46331.5 46417.5 46985.8 38104.6 38110.0 38438.6 39209.8 39614.4 30636.0 33479.8 38284.3 38741.8 38789.8 37378.3 37863.6 37896.1 38020.8 39188.4 28345.6 30531.0 32070.8 33825.8 34163.5 24147.9 24291.1 24567.5 25561.4 25890.6 19:45:44 INFO - PROCESS | 1660 | 19:45:44 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:37876.16 Median:35429.38 stddev:101481.72 (286.4%) stddev-sans-first:101727.21 19:45:44 INFO - PROCESS | 1660 | Values: 317.4 372.9 382.3 386.4 405.5 17087.7 20180.5 20905.6 20923.3 21042.8 19756.1 19864.4 20722.4 20756.7 20772.6 5847.7 5864.9 6076.7 6120.4 6133.6 3833.0 4356.9 5939.2 6045.9 6070.1 6479.2 6845.7 6874.3 6893.2 6944.0 6066.0 6571.7 6810.4 6935.1 6952.5 5981.6 6008.4 6024.5 6048.3 7325.6 26442.9 27870.9 28404.0 29155.0 29671.9 2528.4 2591.2 2601.3 2783.5 2794.7 14121.3 16207.7 17042.7 17046.6 17768.4 2026.6 2092.8 2141.5 2173.8 2274.7 145529.0 147780.0 147784.0 149034.0 150688.0 17059.5 21469.0 21701.7 21811.3 21855.2 35179.3 35392.9 35465.8 35474.3 35611.3 10697.7 10850.3 11079.5 11084.6 11193.4 8441.9 8974.9 9061.7 9075.2 9220.1 9714.5 9815.9 9823.5 9838.9 9987.3 1601.6 1680.5 3802.5 3900.2 4022.7 814.5 854.4 861.6 942.6 961.5 19996.0 20591.5 20882.9 21331.9 21461.6 827.7 867.3 929.3 937.2 1021.7 8317.4 11199.8 12011.6 12042.7 12323.9 593349.0 594543.0 598369.0 614776.0 615327.0 93405.6 134572.3 138993.0 142763.9 146409.5 767.5 928.0 965.2 997.4 1026.9 19365.0 19554.5 19627.0 19652.9 19661.9 59163.4 59292.4 59365.7 59913.1 60543.0 84814.0 85222.0 86087.6 86954.7 87279.6 6151.2 6636.0 6981.7 8132.4 8139.9 12126.3 12510.6 12687.9 12732.2 12958.9 10790.4 10984.0 11124.6 11282.2 11369.6 6005.6 8899.9 10888.8 11103.9 11351.7 10900.6 11092.8 11282.2 11308.5 11343.9 8039.3 8183.9 8264.0 8269.3 8288.0 9403.6 9429.8 11223.9 11280.1 11306.7 19:45:44 INFO - PROCESS | 1660 | 19:45:44 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5662.91 Median:4976.23 stddev:3151.74 (63.3%) stddev-sans-first:3144.70 19:45:44 INFO - PROCESS | 1660 | Values: 1453.0 1495.5 1699.8 1852.2 1864.7 7935.1 8212.2 8415.9 8974.9 9004.1 8346.4 8658.3 8663.5 8685.3 8791.9 2325.7 2385.8 2396.1 2398.3 2411.6 2359.6 2366.5 2404.9 2408.4 2416.0 2395.5 2402.3 2410.1 2427.3 2428.7 2382.1 2383.4 2385.9 2391.1 2411.6 2372.3 2383.3 2386.4 2389.4 2399.7 8710.9 8762.8 8788.2 8795.5 8812.3 5280.3 5430.3 5433.7 5435.1 5505.4 3531.4 3661.3 3676.6 3687.8 3787.6 917.4 1084.3 1097.1 1106.4 1115.3 11347.9 11393.4 11400.0 11485.1 11528.0 4590.9 4673.4 4849.5 4875.3 4891.5 8303.5 9877.1 9922.1 9946.3 9959.9 5300.0 5377.9 5382.9 5387.5 5389.8 4267.9 4410.3 4712.9 4881.7 4901.3 3209.1 3810.6 3902.5 3973.3 3995.9 1117.3 1264.4 1317.8 1321.1 1330.1 784.4 864.7 869.0 871.7 957.6 5615.9 5782.3 5913.7 5975.7 6071.1 445.8 451.6 453.5 460.7 464.9 3683.9 3932.6 3979.5 4047.5 4051.9 9140.6 10540.7 10651.9 10658.5 10672.6 10328.1 10544.3 10569.2 10610.9 10632.4 7966.1 8171.7 8199.4 8219.5 8313.8 5682.3 5853.4 5894.3 5896.5 5933.1 9840.5 9924.6 9955.8 9999.5 10005.3 8135.2 10109.3 10199.3 10204.8 10211.3 6888.1 7126.0 7287.1 7418.4 7477.8 8124.4 8438.7 8446.4 8471.0 8538.5 7371.2 7514.2 7517.8 7555.7 7621.1 7223.2 7277.0 7333.9 7379.6 7431.4 6903.1 6925.7 6967.8 6980.7 6996.3 4918.4 4973.1 4979.3 5102.0 5116.3 4947.8 5017.3 5138.1 5155.5 5224.4 19:45:44 INFO - PROCESS | 1660 | 19:45:44 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:4573.72 Median:5069.09 stddev:1461.58 (28.8%) stddev-sans-first:1430.06 19:45:44 INFO - PROCESS | 1660 | Values: 300.8 308.3 314.3 315.3 318.4 4253.8 5622.7 5660.2 5728.9 5853.4 5107.3 5109.6 5565.7 5605.3 5673.3 3588.5 3991.7 4242.3 4267.0 4274.8 3796.8 4117.1 4200.0 4265.2 4307.4 3487.3 3848.8 4008.8 4185.6 4188.3 3647.5 4183.7 4193.0 4207.1 4210.0 3143.1 3452.2 3466.0 3574.1 3585.3 4251.5 5401.4 5475.3 5478.8 5482.5 4348.6 4444.1 4529.5 4831.0 4885.6 4721.8 5608.8 5733.2 5947.9 5984.8 3525.0 3981.9 3986.5 3987.0 3991.6 5231.4 6284.9 6292.7 6351.3 6352.8 3145.6 3256.3 3372.5 3751.6 4043.9 4085.0 4095.4 4099.4 4133.3 4141.7 4192.9 4252.6 4682.4 4733.3 4853.2 5163.6 5658.5 5721.9 5989.6 6075.6 1110.8 1239.6 1250.9 1264.6 1360.9 893.5 907.6 917.9 919.4 935.7 5112.8 5336.7 5473.0 5484.9 5519.9 5178.5 5816.2 5960.9 6035.0 6049.7 5227.3 5392.3 5489.6 5495.3 5508.5 2153.7 2351.5 2612.8 2669.7 2718.6 4745.6 5226.6 5239.7 5253.8 5279.9 4392.6 4417.7 4801.9 5070.8 5222.8 4393.3 4616.7 4961.4 5254.2 5341.0 4277.4 4313.7 4628.5 4926.9 5186.6 3711.8 4131.5 4959.5 5219.5 6120.8 5859.0 5986.9 6178.7 6401.7 6447.9 4006.8 4067.3 4069.0 4075.7 4089.9 5853.2 5904.5 5978.7 6055.7 6124.2 5872.6 5893.9 5991.9 6028.6 6039.7 4990.8 5995.5 6032.3 6051.2 6093.9 5067.4 5550.6 5892.6 5895.2 5904.6 4864.4 5529.5 5806.8 6029.5 6066.2 4686.8 5513.1 5789.3 5863.7 5910.8 19:45:44 INFO - PROCESS | 1660 | 19:45:44 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:23859.75 Median:4306.35 stddev:41015.14 (952.4%) stddev-sans-first:41093.02 19:45:44 INFO - PROCESS | 1660 | Values: 601.9 715.5 726.9 748.2 818.1 14842.9 15474.8 15497.0 15505.5 15529.5 14094.9 14139.9 14210.3 14225.6 14237.3 833.9 855.3 861.5 862.8 866.7 856.6 859.6 859.6 862.1 863.3 876.1 894.6 896.4 896.7 899.9 864.5 881.2 892.1 894.3 894.5 655.6 661.6 664.1 665.5 665.6 13802.7 13817.4 13850.3 13873.4 13886.6 10119.6 11682.9 11907.5 12198.3 12397.4 10726.6 10777.9 10895.5 11084.7 11341.6 3666.8 3708.0 3724.8 3867.2 3869.8 101860.0 102773.0 103287.0 103406.0 104068.0 8974.3 9039.9 9355.9 10086.3 10489.7 20446.2 20754.0 20786.6 20819.0 20882.6 7634.0 7777.7 7784.3 7928.1 7937.5 4319.4 4347.2 4368.8 4405.0 4514.7 3439.6 3508.2 3543.7 3572.3 3573.6 655.4 659.9 661.8 670.4 674.7 583.4 584.7 604.8 606.6 610.6 7718.0 7720.8 8342.1 8607.6 8800.3 499.3 567.1 576.4 580.5 603.6 3591.4 4204.8 4286.3 4293.3 4392.0 158936.0 161403.0 166302.0 167796.0 168387.2 142687.0 145786.0 146766.0 146923.0 151453.2 79499.2 90005.0 105877.4 110961.0 119241.8 7031.6 7627.9 8002.4 8134.0 8280.7 37567.8 42368.9 47929.8 53432.7 56541.7 68148.0 70544.1 77496.6 88283.1 88440.6 10729.5 10931.7 10952.9 10965.6 11414.7 11764.4 11842.1 11873.1 12319.2 12328.7 10436.3 10733.8 11257.2 11358.5 11737.4 11015.4 11099.9 11257.5 11301.3 11386.9 10806.5 10857.3 10892.9 11009.3 11306.5 9156.4 9466.3 9500.8 9501.5 9723.1 7632.6 8259.3 9226.4 9358.0 9367.8 19:45:44 INFO - PROCESS | 1660 | -------- Summary: end -------- 19:45:44 INFO - PROCESS | 1660 | 19:45:44 INFO - TEST-INFO | 1660: exit 0 19:45:44 INFO - TEST-OK | dromaeo_css | took 1113634ms 19:45:44 INFO - TEST-START | kraken 19:45:44 INFO - operating with platform_type : mac_ 19:45:44 INFO - Initialising browser for kraken test... 19:45:44 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmpGZbphg/profile http://localhost:49273/getInfo.html) 19:45:47 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 19:45:47 INFO - PROCESS | 1672 | colorDepth:24 19:45:47 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 19:45:47 INFO - PROCESS | 1672 | __metrics 19:45:47 ERROR - PROCESS | 1672 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 19:45:48 INFO - TEST-INFO | 1672: exit 0 19:45:48 INFO - Browser initialized. 19:45:48 INFO - Running cycle 1/1 for kraken test... 19:45:48 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmpGZbphg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:46:00 INFO - PROCESS | 1674 | RSS: Main: 245161984 19:46:00 INFO - PROCESS | 1674 | 19:46:03 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 19:46:04 INFO - PROCESS | 1674 | RSS: Main: 257728512 19:46:04 INFO - PROCESS | 1674 | 19:46:07 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 19:46:07 INFO - PROCESS | 1674 | RSS: Main: 265580544 19:46:07 INFO - PROCESS | 1674 | 19:46:10 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 19:46:11 INFO - PROCESS | 1674 | RSS: Main: 262270976 19:46:11 INFO - PROCESS | 1674 | 19:46:13 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 19:46:14 INFO - PROCESS | 1674 | RSS: Main: 263606272 19:46:14 INFO - PROCESS | 1674 | 19:46:16 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:46:16 INFO - PROCESS | 1674 | RSS: Main: 264028160 19:46:16 INFO - PROCESS | 1674 | 19:46:19 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 19:46:20 INFO - PROCESS | 1674 | RSS: Main: 264368128 19:46:20 INFO - PROCESS | 1674 | 19:46:23 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 19:46:24 INFO - PROCESS | 1674 | RSS: Main: 265728000 19:46:24 INFO - PROCESS | 1674 | 19:46:27 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 19:46:28 INFO - PROCESS | 1674 | RSS: Main: 267268096 19:46:28 INFO - PROCESS | 1674 | 19:46:29 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:46:30 INFO - PROCESS | 1674 | RSS: Main: 266911744 19:46:30 INFO - PROCESS | 1674 | 19:46:32 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:46:32 INFO - PROCESS | 1674 | RSS: Main: 267046912 19:46:32 INFO - PROCESS | 1674 | 19:46:35 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:46:35 INFO - PROCESS | 1674 | RSS: Main: 267456512 19:46:35 INFO - PROCESS | 1674 | 19:46:38 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:46:39 INFO - PROCESS | 1674 | RSS: Main: 267390976 19:46:39 INFO - PROCESS | 1674 | 19:46:42 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:46:42 INFO - PROCESS | 1674 | RSS: Main: 267407360 19:46:42 INFO - PROCESS | 1674 | 19:46:44 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 19:46:44 INFO - PROCESS | 1674 | RSS: Main: 269176832 19:46:44 INFO - PROCESS | 1674 | 19:46:44 INFO - PROCESS | 1674 | __start_tp_report 19:46:44 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 19:46:44 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 19:46:44 INFO - PROCESS | 1674 | |i|pagename|runs| 19:46:44 INFO - PROCESS | 1674 | |0;ai-astar;117;115;108;119;116;116;101;113;114;116 19:46:44 INFO - PROCESS | 1674 | |1;audio-beat-detection;183;122;136;160;112;132;132;112;165;116 19:46:44 INFO - PROCESS | 1674 | |2;audio-dft;200;207;230;199;194;195;201;202;195;194 19:46:44 INFO - PROCESS | 1674 | |3;audio-fft;70;85;78;149;53;81;60;54;136;51 19:46:44 INFO - PROCESS | 1674 | |4;audio-oscillator;86;102;141;86;71;93;110;70;73;113 19:46:44 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;88;88;89;85;88;85;109;85;135;131 19:46:44 INFO - PROCESS | 1674 | |6;imaging-darkroom;106;150;111;153;151;103;92;133;151;95 19:46:44 INFO - PROCESS | 1674 | |7;imaging-desaturate;100;82;153;144;95;98;103;102;115;148 19:46:44 INFO - PROCESS | 1674 | |8;json-parse-financial;56;59;58;57;57;59;58;56;58;56 19:46:44 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;66;68;66;74;77;74;67;71;70;69 19:46:44 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;79;114;119;107;109;84;89;87;81;86 19:46:44 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;138;139;150;146;153;137;162;156;182;146 19:46:44 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;172;176;171;170;168;174;178;163;237;234 19:46:44 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;70;69;68;70;69;67;65;68;69;67 19:46:44 INFO - PROCESS | 1674 | __end_tp_report 19:46:44 INFO - PROCESS | 1674 | __start_cc_report 19:46:44 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,962 19:46:44 INFO - PROCESS | 1674 | __end_cc_report 19:46:44 INFO - PROCESS | 1674 | __startTimestamp1473734804923__endTimestamp 19:46:44 INFO - PROCESS | 1674 | 19:46:44 INFO - PROCESS | 1674 | ------- Summary: start ------- 19:46:44 INFO - PROCESS | 1674 | Number of tests: 14 19:46:44 INFO - PROCESS | 1674 | 19:46:44 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:113.50 Median:116.00 stddev:5.28 (4.5%) stddev-sans-first:5.44 19:46:44 INFO - PROCESS | 1674 | Values: 117.0 115.0 108.0 119.0 116.0 116.0 101.0 113.0 114.0 116.0 19:46:44 INFO - PROCESS | 1674 | 19:46:44 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:137.00 Median:134.00 stddev:24.49 (18.3%) stddev-sans-first:19.51 19:46:44 INFO - PROCESS | 1674 | Values: 183.0 122.0 136.0 160.0 112.0 132.0 132.0 112.0 165.0 116.0 19:46:44 INFO - PROCESS | 1674 | 19:46:44 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:201.70 Median:200.50 stddev:10.79 (5.4%) stddev-sans-first:11.43 19:46:44 INFO - PROCESS | 1674 | Values: 200.0 207.0 230.0 199.0 194.0 195.0 201.0 202.0 195.0 194.0 19:46:44 INFO - PROCESS | 1674 | 19:46:44 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:81.70 Median:65.00 stddev:34.39 (52.9%) stddev-sans-first:36.21 19:46:44 INFO - PROCESS | 1674 | Values: 70.0 85.0 78.0 149.0 53.0 81.0 60.0 54.0 136.0 51.0 19:46:44 INFO - PROCESS | 1674 | 19:46:44 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:94.50 Median:72.00 stddev:22.52 (31.3%) stddev-sans-first:23.67 19:46:44 INFO - PROCESS | 1674 | Values: 86.0 102.0 141.0 86.0 71.0 93.0 110.0 70.0 73.0 113.0 19:46:44 INFO - PROCESS | 1674 | 19:46:44 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:98.30 Median:86.50 stddev:19.62 (22.7%) stddev-sans-first:20.46 19:46:44 INFO - PROCESS | 1674 | Values: 88.0 88.0 89.0 85.0 88.0 85.0 109.0 85.0 135.0 131.0 19:46:44 INFO - PROCESS | 1674 | 19:46:44 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:124.50 Median:151.00 stddev:25.50 (16.9%) stddev-sans-first:26.15 19:46:44 INFO - PROCESS | 1674 | Values: 106.0 150.0 111.0 153.0 151.0 103.0 92.0 133.0 151.0 95.0 19:46:44 INFO - PROCESS | 1674 | 19:46:44 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:114.00 Median:150.50 stddev:25.12 (16.7%) stddev-sans-first:26.13 19:46:44 INFO - PROCESS | 1674 | Values: 100.0 82.0 153.0 144.0 95.0 98.0 103.0 102.0 115.0 148.0 19:46:44 INFO - PROCESS | 1674 | 19:46:44 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:58.00 stddev:1.17 (2.0%) stddev-sans-first:1.13 19:46:44 INFO - PROCESS | 1674 | Values: 56.0 59.0 58.0 57.0 57.0 59.0 58.0 56.0 58.0 56.0 19:46:44 INFO - PROCESS | 1674 | 19:46:44 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:70.50 stddev:3.77 (5.3%) stddev-sans-first:3.67 19:46:44 INFO - PROCESS | 1674 | Values: 66.0 68.0 66.0 74.0 77.0 74.0 67.0 71.0 70.0 69.0 19:46:44 INFO - PROCESS | 1674 | 19:46:44 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:95.50 Median:82.50 stddev:15.01 (18.2%) stddev-sans-first:14.69 19:46:44 INFO - PROCESS | 1674 | Values: 79.0 114.0 119.0 107.0 109.0 84.0 89.0 87.0 81.0 86.0 19:46:44 INFO - PROCESS | 1674 | 19:46:44 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:150.90 Median:151.50 stddev:13.63 (9.0%) stddev-sans-first:13.63 19:46:44 INFO - PROCESS | 1674 | Values: 138.0 139.0 150.0 146.0 153.0 137.0 162.0 156.0 182.0 146.0 19:46:44 INFO - PROCESS | 1674 | 19:46:44 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.30 Median:175.00 stddev:27.31 (15.6%) stddev-sans-first:28.61 19:46:44 INFO - PROCESS | 1674 | Values: 172.0 176.0 171.0 170.0 168.0 174.0 178.0 163.0 237.0 234.0 19:46:44 INFO - PROCESS | 1674 | 19:46:44 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:69.00 stddev:1.55 (2.2%) stddev-sans-first:1.50 19:46:44 INFO - PROCESS | 1674 | Values: 70.0 69.0 68.0 70.0 69.0 67.0 65.0 68.0 69.0 67.0 19:46:44 INFO - PROCESS | 1674 | -------- Summary: end -------- 19:46:44 INFO - PROCESS | 1674 | 19:46:45 INFO - TEST-INFO | 1674: exit 0 19:46:45 INFO - TEST-OK | kraken | took 60958ms 19:46:45 INFO - SUITE-END | took 1174s 19:46:46 INFO - Completed test suite (00:19:34) 19:46:46 INFO - javascript benchmark 19:46:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7288.754264627157, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2940.691227421121, 3371.1403592084043, 3391.9660803391976, 3392.7625066245364, 3410.1474631342167, 5781.595288329813, 5857.3849745776715, 5898.498627616653, 5905.527557795377, 5909.084091965752, 5650.152477128422, 5803.506701930331, 5817.272840894888, 5837.445442682949, 5887.8528036799, 5189.792408303663, 5215.191645294985, 5282.154855223165, 5306.416294207634, 5373.596980226479, 5254.737263136847, 5339.305890234284, 5345.919811202815, 5373.892522149555, 5430.5384042356445, 5147.536721695065, 5239.056969745444, 5275.709835959021, 5280.313559237275, 5361.303030606015, 5240.842774716764, 5265.183896496049, 5282.735863206863, 5288.338957630296, 5302.787888484456, 4791.592714619262, 5261.473852614746, 5271.789128434896, 5280.9471905281225, 5308.150695888678, 5644.745986430579, 5671.886562268773, 5740.339860915965, 5754.964106460835, 5784.1034639630925, 5186.0, 6139.154842350791, 6189.969050154766, 6198.597091189058, 6208.9689551551955, 5157.922631160535, 6233.594816336924, 6234.407731265568, 6244.875102497925, 6270.749170033193, 5163.483651634807, 5739.799107031317, 5786.623869448473, 5834.474897259267, 5944.227250457456, 6189.907151392733, 6226.439620434092, 6250.6874656267, 6321.620702757849, 6331.081993110973, 6092.72582733778, 6093.0, 6126.479249263863, 6131.294901086432, 6132.294786099566, 6095.2685677718955, 6104.542159338067, 6240.31356550779, 6249.6875156242, 6253.0, 6057.515398768088, 6082.604630699079, 6116.548817444082, 6120.873812997581, 6224.253089629183, 6179.3820617938745, 6182.6599537025895, 6183.84540386485, 6189.442950134509, 6194.349593292639, 4958.0, 5046.1673823818655, 5050.3182070420025, 5050.873728156826, 5053.1157047516535, 4319.0066284754685, 4360.215161270939, 4419.0719948810465, 4475.48531918827, 4506.887327816831, 4320.1143765527995, 5372.91940620891, 5647.802326918538, 5654.74553645087, 5698.7435565399655, 6117.143599896018, 6123.693815309217, 6138.693065346715, 6140.9078863816185, 6152.0, 5618.943810561924, 5724.542036637059, 5823.30120385548, 5825.271841019872, 5872.765089396376, 4445.599896009375, 4993.975030124826, 5531.8893622128135, 5556.388797232301, 5575.860603484945, 5077.339945807095, 6351.618902865843, 6402.583832050995, 6403.295637479873, 6418.807435776934, 5354.598405119709, 6109.2974307955155, 6149.016157414792, 6163.568550201622, 6274.215723034621, 5694.9179655865255, 6045.183900173421, 6343.238811342669, 6385.68071596409, 6392.712327945161, 6091.299500557493, 6177.320494745484, 6190.133381326528, 6195.1636529068, 6196.876062478816, 5165.9483405165465, 6107.725152368065, 6111.174991376108, 6201.410865967726, 6287.151234583455, 6172.105044768573, 6182.505399568114, 6183.258009038944, 6187.597806142587, 6189.535784816247, 6160.784372547118, 6178.62928224308, 6183.96908015457, 6189.319174890668, 6189.783357582463, 5020.799168033238, 5855.765769369348, 5957.880842383215, 5962.373950735284, 6028.367021462684, 6095.190505933791, 6142.529470849135, 6155.35368786271, 6158.630482171084, 6173.722182501709, 6087.634741915499, 6094.512439004958, 6109.022556390956, 6111.205543279345, 6120.204373431604], "value": 5668.239167003661, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.16603611607434, 488.3431784250183, 493.6618416384774, 497.5174081141293, 528.3606835728768, 38438.0, 40693.389599156035, 40705.38941915874, 43150.56849431505, 43213.783931080376, 42055.36916946248, 42064.57935420645, 42212.57787422125, 42515.36226956598, 42535.361969570484, 9718.805623887518, 9878.802423951553, 9916.405015699082, 9933.304668673196, 9944.403335799876, 9747.415155090683, 9877.851832222523, 9888.505574721272, 9901.603935842557, 9913.0, 9884.308098433077, 9892.752681182956, 9913.702588922308, 9915.95042024789, 9936.90063099367, 8360.205780450848, 9867.40795552262, 9877.851832222486, 9881.160101391319, 9903.8514422283, 9759.316847820655, 9822.508874556315, 9833.606655733762, 9859.211263098934, 9873.703788886345, 40023.0, 40153.19693606141, 40417.59582404197, 40451.78644640665, 40492.797536012135, 3392.0841372829445, 3537.00963730156, 3585.515955346021, 3795.3168429682646, 3973.3841254605295, 30212.395752084834, 30237.697623023927, 30918.0724578263, 31014.689853101405, 31329.216769580944, 3173.143251322153, 3241.9351612967607, 3243.3189030303665, 3348.179695974502, 3391.2030672791875, 498592.0, 499920.0, 502331.48834255594, 503351.0, 503892.0, 32637.51043734346, 33633.0, 47480.28779568309, 58744.70627646834, 59323.40676593179, 27831.30421739473, 31650.525242121388, 32808.0, 35002.29995400078, 35242.0, 14235.85764142366, 14496.710065798625, 14615.853841461661, 14668.926655366655, 14692.191929443983, 11946.98450631706, 12356.135070545155, 12472.937635311766, 12491.188072775241, 12539.373031348396, 6573.441257493071, 7651.885221721679, 7661.15727270011, 7692.730754423568, 7721.884171737428, 3255.9023229303157, 3259.1363288728508, 3263.951040734391, 3277.5083737439577, 3300.950485742716, 937.6577549194632, 967.4195482710318, 991.4695637833725, 1007.9496025198857, 1099.1261946752352, 29740.2564935874, 30065.849670751508, 30224.69775302219, 30430.391392172034, 30883.382332353227, 942.1198193358468, 1040.2302296300834, 1069.2889228662852, 1077.7844431113808, 1111.649830303468, 15346.769798453031, 15406.152661603495, 15442.382304707911, 15499.070055796688, 16240.269187886573, 654977.0, 664415.0, 667313.0, 667870.0, 668822.6558867272, 54732.72633636806, 55244.17133742997, 55293.0, 55351.0, 55437.0, 1124.651358078998, 1148.2249481599886, 1341.7517759214577, 1383.557261676254, 1386.9791953120914, 29084.0, 29184.27039324034, 29496.557551636313, 29878.551821723126, 30100.397992040474, 47861.803454913905, 48706.75646621744, 49196.55624106278, 49197.75401123115, 49350.50649493459, 487297.0, 487592.0, 489123.108768922, 490873.0039695559, 491363.0, 25250.0, 26215.95136194531, 26244.95020199247, 26297.553634550284, 26384.0, 45171.30845507818, 45224.0, 46331.53668463272, 46417.53582464132, 46985.76507117443, 38104.618953811216, 38110.0, 38438.6156138435, 39209.82370528889, 39614.40578391269, 30636.0, 33479.83260083684, 38284.291760019914, 38741.80629096949, 38789.806050969564, 37378.252434950606, 37863.62136378601, 37896.05259868525, 38020.80989595034, 39188.41217381799, 28345.574816378183, 30531.0, 32070.83964580162, 33825.830870846476, 34163.48754768631, 24147.879260603586, 24291.149809756575, 24567.52594844315, 25561.360965975255, 25890.611640825027], "value": 18368.529713304753, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.4365501235307, 372.8583138407405, 382.262233888595, 386.4030073536386, 405.5073086200268, 17087.743683844754, 20180.4954876128, 20905.581888362223, 20923.267685630988, 21042.789572104273, 19756.11097500616, 19864.40406787795, 20722.378328650128, 20756.68864967027, 20772.584548309023, 5847.707614619263, 5864.941350586503, 6076.696165191723, 6120.4185602367925, 6133.601315914473, 3832.9616703832885, 4356.934645980297, 5939.227900372945, 6045.939540604603, 6070.0591408331775, 6479.22249330081, 6845.657717114124, 6874.27820078892, 6893.17281926167, 6943.965280173617, 6066.029435290377, 6571.671416429183, 6810.353016463453, 6935.063766391523, 6952.478564107661, 5981.58128930975, 6008.429199226067, 6024.48791852693, 6048.304444988795, 7325.597092159929, 26442.86778566114, 27870.860645696845, 28404.0, 29154.97957571496, 29671.851640741876, 2528.448792174098, 2591.20968104728, 2601.2846467221475, 2783.498970185366, 2794.7345002224756, 14121.293935303194, 16207.675846483122, 17042.659146817117, 17046.595626695424, 17768.37810676633, 2026.6421497596498, 2092.7593326767465, 2141.4860433496005, 2173.7608863025052, 2274.7042884425086, 145529.0, 147780.0, 147784.0, 149033.99999999892, 150688.0, 17059.48821535356, 21469.0, 21701.67447488289, 21811.345659630235, 21855.158829352084, 35179.29641407158, 35392.93821185369, 35465.822670887, 35474.29051418957, 35611.28777424437, 10697.679069627768, 10850.29473084247, 11079.50142243601, 11084.556617735201, 11193.440327983568, 8441.915580844114, 8974.910250897537, 9061.68284110053, 9075.2286055686, 9220.124088211609, 9714.465704386184, 9815.871174814845, 9823.508824558887, 9838.852417213748, 9987.250956178315, 1601.6476375197437, 1680.5378520906604, 3802.543694756592, 3900.2004589059175, 4022.6781857451333, 814.450246083891, 854.3634991930927, 861.642418396371, 942.5805516545208, 961.5240455974198, 19996.0, 20591.4852128695, 20882.895585521976, 21331.93340332977, 21461.57076858454, 827.6523859978823, 867.280157469299, 929.3076657889916, 937.2361525356812, 1021.7343490692474, 8317.376196785144, 11199.832002519968, 12011.579594714143, 12042.698932526757, 12323.938380308042, 593349.0, 594543.0, 598369.0, 614776.0, 615327.0, 93405.59891601632, 134572.32713836367, 138993.0, 142763.8650714701, 146409.53590464173, 767.48578452437, 927.9907200928039, 965.1940629574384, 997.3915911294105, 1026.8665073540542, 19365.031748412806, 19554.51113722168, 19627.0, 19652.901735491232, 19661.91859447687, 59163.40836591751, 59292.40707592869, 59365.70317148387, 59913.10130348135, 60543.0, 84814.0, 85222.0, 86087.5695621518, 86954.69567956359, 87279.56360218412, 6151.200343955257, 6636.0, 6981.650917454209, 8132.390070744599, 8139.877901831359, 12126.333051682243, 12510.562130325396, 12687.873121269038, 12732.172408793398, 12958.870411295766, 10790.352578845295, 10984.01143897053, 11124.555017799197, 11282.21024528308, 11369.602063927723, 6005.6396616203165, 8899.866502002606, 10888.836667449836, 11103.888961110286, 11351.659450216506, 10900.618478353219, 11092.833607496055, 11282.153838461983, 11308.49111789955, 11343.943280283545, 8039.316658084128, 8183.918160818315, 8263.95868020656, 8269.29710974574, 8287.958560207402, 9403.623855045995, 9429.765502658103, 11223.943880280873, 11280.09759219244, 11306.717332066764], "value": 10788.388826559798, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1452.9636759081022, 1495.4765831958812, 1699.8300169983004, 1852.203552472438, 1864.6736821056304, 7935.127136015049, 8212.219839115274, 8415.91584084159, 8974.865377019341, 9004.054574269705, 8346.41575089744, 8658.26404755595, 8663.480191188517, 8685.305175585954, 8791.868121978176, 2325.651152327152, 2385.844920080198, 2396.113438027923, 2398.328468028955, 2411.5538625354366, 2359.587072262356, 2366.5148644527917, 2404.879756012201, 2408.3738228060743, 2415.9879200603973, 2395.5448464791634, 2402.327348342467, 2410.144398738449, 2427.2718184544656, 2428.7449817769143, 2382.118616112032, 2383.380321116505, 2385.8926348314367, 2391.0913852735935, 2411.5779738545684, 2372.2764556810166, 2383.2611890314056, 2386.3795413192693, 2389.3668177932864, 2399.7120345558646, 8710.912890871074, 8762.780930476789, 8788.20906118446, 8795.47227166372, 8812.295016398673, 5280.287161233223, 5430.348358197002, 5433.673979561199, 5435.130379139318, 5505.421930697259, 3531.399662057456, 3661.3226553087766, 3676.5588129424373, 3687.8340474678703, 3787.621237876218, 917.3945196170562, 1084.3006260961715, 1097.0894157848966, 1106.3804269609045, 1115.341948250536, 11347.886521134847, 11393.430328483626, 11400.0, 11485.138614603939, 11528.0, 4590.908181836411, 4673.439187297478, 4849.515048495193, 4875.293082503017, 4891.535304146171, 8303.4602750821, 9877.11106000449, 9922.057404546556, 9946.303758736964, 9959.850602240827, 5300.01949639318, 5377.892442151135, 5382.919256211082, 5387.46125387466, 5389.75746091419, 4267.935980960288, 4410.29435290352, 4712.929306060412, 4881.707097574157, 4901.264810278487, 3209.149575362531, 3810.599887011879, 3902.453656488094, 3973.264945984944, 3995.881153277087, 1117.312852595664, 1264.3804535777404, 1317.841858976929, 1321.1082519299434, 1330.0689517337903, 784.3842583571983, 864.7449002544264, 868.9963092628025, 871.7036207689289, 957.6265256549951, 5615.859603509945, 5782.277215348082, 5913.704314784243, 5975.67133807636, 6071.089336599457, 445.8238995596721, 451.63643267169584, 453.4581175495251, 460.7212636355024, 464.92561190209403, 3683.9132455925564, 3932.646061854447, 3979.46277252573, 4047.4738284022887, 4051.8784436466954, 9140.63437462494, 10540.683779486628, 10651.893481065244, 10658.467076646137, 10672.626458074086, 10328.122109620615, 10544.261901666963, 10569.207309451667, 10610.94694526538, 10632.362058276529, 7966.1237263902085, 8171.713990010321, 8199.385046121564, 8219.54792486419, 8313.833723325499, 5682.318121825408, 5853.356130825691, 5894.322152952293, 5896.528277737856, 5933.139694744152, 9840.507974601385, 9924.603015879284, 9955.800883982425, 9999.500024998575, 10005.299629025898, 8135.227153420416, 10109.34289271195, 10199.337043092175, 10204.846927296245, 10211.336263142872, 6888.104546409135, 7125.964370178116, 7287.089113860768, 7418.443616728659, 7477.775666730006, 8124.431289809657, 8438.746837594881, 8446.408751387344, 8470.9834819822, 8538.530380828866, 7371.152317483344, 7514.248575142661, 7517.774466766005, 7555.73554925597, 7621.12357078943, 7223.2415596361625, 7276.96361518189, 7333.926660733325, 7379.594122323109, 7431.3683336916965, 6903.0680858083515, 6925.653717314215, 6967.825804354931, 6980.720771169096, 6996.335348141918, 4918.409790825124, 4973.129702302011, 4979.3277907482025, 5102.0, 5116.283720279238, 4947.777350019186, 5017.297578338962, 5138.075146473521, 5155.484451554815, 5224.373075230997], "value": 4493.137709202718, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.7563873262656, 308.2647884794764, 314.25364758698095, 315.2654315445013, 318.3505648477107, 4253.829846806132, 5622.746976386063, 5660.2075709400615, 5728.914066289009, 5853.414658534144, 5107.28498010278, 5109.591232701384, 5565.721713914309, 5605.27131472909, 5673.347565030022, 3588.5155504006884, 3991.7405368650943, 4242.321228603417, 4266.975925777807, 4274.764887931163, 3796.791176485292, 4117.135401565675, 4200.0, 4265.210935976854, 4307.353896915472, 3487.250241198142, 3848.786263469992, 4008.819603117867, 4185.623293903548, 4188.267053265667, 3647.4528820676846, 4183.686223533232, 4192.951762059485, 4207.137536804948, 4209.97895010526, 3143.135637699651, 3452.188735647121, 3465.9653403465895, 3574.088607405121, 3585.2790660483115, 4251.489821221442, 5401.379586124147, 5475.288212532345, 5478.753456094445, 5482.533984611313, 4348.630366418858, 4444.088961762831, 4529.501754806968, 4831.0, 4885.56029957302, 4721.811127554894, 5608.803691870806, 5733.219388596527, 5947.881042379171, 5984.820455386345, 3525.012996361023, 3981.900452488711, 3986.54154772202, 3986.960130398688, 3991.640752332275, 5231.398389185254, 6284.946209694813, 6292.716827742763, 6351.333110023427, 6352.841178970517, 3145.6382516010954, 3256.2836176041233, 3372.4823829276434, 3751.643593858579, 4043.939340909889, 4084.9795751021056, 4095.385692146202, 4099.385092236129, 4133.338665813455, 4141.7100802943505, 4192.8972560305765, 4252.596003379674, 4682.4381074271305, 4733.2900064989835, 4853.174960256765, 5163.580015495693, 5658.547316214693, 5721.885562288731, 5989.610675306091, 6075.605085669417, 1110.76704857608, 1239.6405042537745, 1250.8561515425843, 1264.6016504800957, 1360.857110003455, 893.4773157702816, 907.614263937837, 917.8852643419573, 919.4069824962861, 935.7320830275015, 5112.750389828464, 5336.706481143497, 5472.98749731301, 5484.91772623411, 5519.917201241985, 5178.482151784791, 5816.185733997244, 5960.8807823844145, 6034.978282275399, 6049.697515124314, 5227.320448341768, 5392.299001129828, 5489.58828087895, 5495.313085864267, 5508.504234618904, 2153.7415510138885, 2351.470919043202, 2612.7648511634043, 2669.7196794336705, 2718.605802158676, 4745.644076694192, 5226.5818734501145, 5239.659422137549, 5253.768932106803, 5279.947200528022, 4392.648588112943, 4417.730879149406, 4801.879953001203, 5070.783012077149, 5222.843314700565, 4393.298121655159, 4616.653750968623, 4961.404631444249, 5254.185601231815, 5341.0, 4277.422547956111, 4313.654907607447, 4628.467726211529, 4926.896055485022, 5186.559142472932, 3711.828580280458, 4131.483564554431, 4959.512146356006, 5219.451957544403, 6120.816375508742, 5858.974679430998, 5986.880262394815, 6178.66017369049, 6401.743930242738, 6447.937799186482, 4006.7796271204197, 4067.308557545165, 4069.023434375788, 4075.65356944663, 4089.938650920179, 5853.161216809759, 5904.468597826216, 5978.701064946822, 6055.727492262947, 6124.234470691164, 5872.588918775645, 5893.9115913260475, 5991.861546306188, 6028.57799953999, 6039.728212230372, 4990.825321113889, 5995.520358371407, 6032.285471537738, 6051.213342265477, 6093.9085913712215, 5067.391912970467, 5550.556855217753, 5892.616979896293, 5895.174675545512, 5904.557158213237, 4864.440589332272, 5529.502344788988, 5806.76772929095, 6029.457348838626, 6066.211392518944, 4686.765661716832, 5513.145462453326, 5789.2763404574425, 5863.736131874161, 5910.822675319747], "value": 4110.706276165279, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [601.9138281521147, 715.5241764226788, 726.8546290741849, 748.2442732839834, 818.1327792539912, 14842.851571484282, 15474.767878481804, 15497.0, 15505.534833954971, 15529.456469023586, 14094.859051409483, 14139.93950453715, 14210.289485525735, 14225.573232803032, 14237.288135593231, 833.8749187621845, 855.2943821347383, 861.5476874640832, 862.8231212601402, 866.6576702202658, 856.5588721808274, 859.5530324231396, 859.6475445067524, 862.0991064337782, 863.2791618998144, 876.071364353787, 894.6421431427449, 896.3635818568791, 896.6727144592243, 899.8920129584459, 864.4985908173245, 881.2069137775989, 892.1168043636818, 894.3292530602048, 894.4946105450465, 655.5542231282726, 661.6327937994412, 664.120040945744, 665.5141746525064, 665.5740326191242, 13802.723945521133, 13817.447302107903, 13850.30748462583, 13873.4450621975, 13886.583402497941, 10119.645812396531, 11682.941585292105, 11907.523699052028, 12198.268103913793, 12397.442115104843, 10726.570937162503, 10777.886594847441, 10895.509702063348, 11084.667459976214, 11341.603043893507, 3666.816659167058, 3707.9629203707887, 3724.832382542792, 3867.187890542961, 3869.8452061917487, 101860.0, 102773.0, 103287.0, 103405.96594034114, 104068.0, 8974.326925480615, 9039.864402033974, 9355.906440935503, 10086.344387614781, 10489.737756555995, 20446.182152714024, 20754.0, 20786.58426831455, 20819.0, 20882.58234835295, 7634.0, 7777.72777952769, 7784.260495252942, 7928.127905930343, 7937.523748575104, 4319.416878721395, 4347.239233134189, 4368.803403846835, 4404.986853023823, 4514.7291162530355, 3439.5872495300723, 3508.2281897982402, 3543.734219933464, 3572.303400836864, 3573.6069032406413, 655.446148004935, 659.8713250916026, 661.765073398946, 670.4167374384219, 674.6727836999052, 583.4049269744836, 584.7281014328273, 604.7611193578512, 606.6117684681807, 610.578700696518, 7718.035245594301, 7720.806979825437, 8342.082370939192, 8607.61265743043, 8800.339974501938, 499.28103530915365, 567.1266249974992, 576.4466112532016, 580.5268706004592, 603.6136872401667, 3591.3535563598803, 4204.768737719393, 4286.314189729628, 4293.291606884892, 4391.956080439155, 158936.0, 161403.0, 166302.0, 167796.0, 168387.1580642089, 142687.0, 145786.0, 146766.0, 146923.0, 151453.24273378562, 79499.20500795034, 90005.0, 105877.41183882402, 110961.0, 119241.8075819248, 7031.613261270679, 7627.89395537655, 8002.359811215205, 8133.959330203311, 8280.668773249003, 37567.81216093902, 42368.88155592146, 47929.76035119802, 53432.73283633557, 56541.71729141328, 68148.0, 70544.12382266589, 77496.61251693705, 88283.11716883005, 88440.5577972106, 10729.517171727448, 10931.726706832393, 10952.890471095187, 10965.61620343284, 11414.714632134263, 11764.352960586908, 11842.052635788943, 11873.050155987328, 12319.199252048587, 12328.691782705146, 10436.321639093434, 10733.76561695415, 11257.15571332137, 11358.488868000795, 11737.35444550558, 11015.394153321644, 11099.889001109885, 11257.49341279628, 11301.344184116562, 11386.886131138583, 10806.45967701596, 10857.294275872044, 10892.945535272273, 11009.284396514522, 11306.547738090714, 9156.359054866096, 9466.337356384987, 9500.755527278665, 9501.524923753923, 9723.124918757347, 7632.580208088609, 8259.297959673495, 9226.35415520907, 9358.0, 9367.812643747224], "value": 7226.5184977937515, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1591.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 115.0, 108.0, 119.0, 116.0, 116.0, 101.0, 113.0, 114.0, 116.0], "value": 113.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 122.0, 136.0, 160.0, 112.0, 132.0, 132.0, 112.0, 165.0, 116.0], "value": 137.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 207.0, 230.0, 199.0, 194.0, 195.0, 201.0, 202.0, 195.0, 194.0], "value": 201.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 85.0, 78.0, 149.0, 53.0, 81.0, 60.0, 54.0, 136.0, 51.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 102.0, 141.0, 86.0, 71.0, 93.0, 110.0, 70.0, 73.0, 113.0], "value": 94.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 89.0, 85.0, 88.0, 85.0, 109.0, 85.0, 135.0, 131.0], "value": 98.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 150.0, 111.0, 153.0, 151.0, 103.0, 92.0, 133.0, 151.0, 95.0], "value": 124.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 82.0, 153.0, 144.0, 95.0, 98.0, 103.0, 102.0, 115.0, 148.0], "value": 114.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 59.0, 58.0, 57.0, 57.0, 59.0, 58.0, 56.0, 58.0, 56.0], "value": 57.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 66.0, 74.0, 77.0, 74.0, 67.0, 71.0, 70.0, 69.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 114.0, 119.0, 107.0, 109.0, 84.0, 89.0, 87.0, 81.0, 86.0], "value": 95.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 139.0, 150.0, 146.0, 153.0, 137.0, 162.0, 156.0, 182.0, 146.0], "value": 150.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 176.0, 171.0, 170.0, 168.0, 174.0, 178.0, 163.0, 237.0, 234.0], "value": 184.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 68.0, 70.0, 69.0, 67.0, 65.0, 68.0, 69.0, 67.0], "value": 68.2, "unit": "score"}]}]} 19:46:46 INFO - Return code: 0 19:46:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:46:46 ERROR - # TBPL SUCCESS # 19:46:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:46:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:46:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:46:46 INFO - 2016-09-12 19:46:46.272 screenresolution[1679:10621] starting screenresolution argv=screenresolution get 19:46:46 INFO - 2016-09-12 19:46:46.274 screenresolution[1679:10621] Display 0: 1600x1200x32@60 19:46:46 INFO - 2016-09-12 19:46:46.280 screenresolution[1680:10624] starting screenresolution argv=screenresolution list 19:46:46 INFO - Available Modes on Display 0 19:46:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:46:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:46:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:46:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:46:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:46:46 INFO - Intel Iris: 19:46:46 INFO - Chipset Model: Intel Iris 19:46:46 INFO - Type: GPU 19:46:46 INFO - Bus: Built-In 19:46:46 INFO - VRAM (Dynamic, Max): 1536 MB 19:46:46 INFO - Vendor: Intel (0x8086) 19:46:46 INFO - Device ID: 0x0a2e 19:46:46 INFO - Revision ID: 0x0009 19:46:46 INFO - Displays: 19:46:46 INFO - Display: 19:46:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:46:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:46:46 INFO - Display Serial Number: 1600x1200 60 19:46:46 INFO - Main Display: Yes 19:46:46 INFO - Mirror: Off 19:46:46 INFO - Online: Yes 19:46:46 INFO - Rotation: Supported 19:46:46 INFO - Return code: 0 19:46:46 INFO - Running post-action listener: _resource_record_post_action 19:46:46 INFO - [mozharness: 2016-09-13 02:46:46.515062Z] Finished run-tests step (success) 19:46:46 INFO - Running post-run listener: _resource_record_post_run 19:46:46 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 37274624; Write bytes: 706651136; Read time: 240; Write time: 1991 19:46:46 INFO - TinderboxPrint: CPU usage
54.3% 19:46:46 INFO - TinderboxPrint: I/O read bytes / time
37,274,624 / 240 19:46:46 INFO - TinderboxPrint: I/O write bytes / time
706,651,136 / 1,991 19:46:46 INFO - TinderboxPrint: CPU idle
2,211.4 (45.7%) 19:46:46 INFO - TinderboxPrint: CPU system
976.9 (20.2%) 19:46:46 INFO - TinderboxPrint: CPU user
1,653.4 (34.1%) 19:46:46 INFO - TinderboxPrint: Swap in / out
441,942,016 / 0 19:46:46 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 395467264; Write bytes: 390284800; Read time: 30170; Write time: 800 19:46:46 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 35296256; Write bytes: 297668096; Read time: 221; Write time: 1148 19:46:46 INFO - Running post-run listener: _upload_blobber_files 19:46:46 INFO - Blob upload gear active. 19:46:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:46:46 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 19:46:46 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'] 19:46:46 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 19:46:47 INFO - (blobuploader) - INFO - Open directory for files ... 19:46:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:46:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:46:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:46:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:46:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:46:48 INFO - (blobuploader) - INFO - Done attempting. 19:46:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:46:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:46:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:46:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:46:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:46:51 INFO - (blobuploader) - INFO - Done attempting. 19:46:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:46:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:46:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:46:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:46:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:46:53 INFO - (blobuploader) - INFO - Done attempting. 19:46:53 INFO - (blobuploader) - INFO - Iteration through files over. 19:46:53 INFO - Return code: 0 19:46:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:46:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:46:53 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/acfa5224c528304b7c06b350626f9d34c7d9e11509194b27cbe201e6c7cbf1975ff47db971434d622c7658255badd1bc7cc7f9c66355d06d2b560a70d107b8e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e5bff77a8d1cf12b7fdff0e4daf546d5bba38064be4f7c1fe3be94adc988faf76876c2e9542b7938654b7c5c23a27558c05d84df00b67bbf1bb7d045836aa70", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be82e4748f14c90ab05c9efa88604344fed5d13e8dd7a5c75448b36e756c13dc526ad588f9edfe98d9c3d630472714f1efa2d595406c5ea79fa70d0fcb928f2f"} 19:46:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:46:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:46:53 INFO - Contents: 19:46:53 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/acfa5224c528304b7c06b350626f9d34c7d9e11509194b27cbe201e6c7cbf1975ff47db971434d622c7658255badd1bc7cc7f9c66355d06d2b560a70d107b8e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e5bff77a8d1cf12b7fdff0e4daf546d5bba38064be4f7c1fe3be94adc988faf76876c2e9542b7938654b7c5c23a27558c05d84df00b67bbf1bb7d045836aa70", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be82e4748f14c90ab05c9efa88604344fed5d13e8dd7a5c75448b36e756c13dc526ad588f9edfe98d9c3d630472714f1efa2d595406c5ea79fa70d0fcb928f2f"} 19:46:53 INFO - Running post-run listener: copy_logs_to_upload_dir 19:46:53 INFO - Copying logs to upload dir... 19:46:53 INFO - mkdir: /builds/slave/test/build/upload/logs 19:46:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.122645 ========= master_lag: 1.11 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-09-12 19:46:54.885363) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 19:46:54.888900) ========= 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.C7I3rxWpCK/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.09C0aBEwsf/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/acfa5224c528304b7c06b350626f9d34c7d9e11509194b27cbe201e6c7cbf1975ff47db971434d622c7658255badd1bc7cc7f9c66355d06d2b560a70d107b8e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e5bff77a8d1cf12b7fdff0e4daf546d5bba38064be4f7c1fe3be94adc988faf76876c2e9542b7938654b7c5c23a27558c05d84df00b67bbf1bb7d045836aa70", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be82e4748f14c90ab05c9efa88604344fed5d13e8dd7a5c75448b36e756c13dc526ad588f9edfe98d9c3d630472714f1efa2d595406c5ea79fa70d0fcb928f2f"} build_url:https://queue.taskcluster.net/v1/task/c-xUpIT7Qg2_IrnIHzFOqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011368 build_url: 'https://queue.taskcluster.net/v1/task/c-xUpIT7Qg2_IrnIHzFOqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/acfa5224c528304b7c06b350626f9d34c7d9e11509194b27cbe201e6c7cbf1975ff47db971434d622c7658255badd1bc7cc7f9c66355d06d2b560a70d107b8e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e5bff77a8d1cf12b7fdff0e4daf546d5bba38064be4f7c1fe3be94adc988faf76876c2e9542b7938654b7c5c23a27558c05d84df00b67bbf1bb7d045836aa70", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be82e4748f14c90ab05c9efa88604344fed5d13e8dd7a5c75448b36e756c13dc526ad588f9edfe98d9c3d630472714f1efa2d595406c5ea79fa70d0fcb928f2f"}' ========= master_lag: 0.09 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 19:46:54.985385) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 19:46:54.985735) ========= 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.C7I3rxWpCK/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.09C0aBEwsf/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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.005545 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 19:46:55.011764) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 19:46:55.012048) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 19:46:55.012318) ========= ========= Total master_lag: 1.28 =========