builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0305 starttime: 1472968486.93 results: success (0) buildid: 20160903215150 builduid: e74cf24ffce54dde89a52d48c0ac7dbe revision: d61bbdd0b155f76170adef0e9433e2166c606483 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-03 22:54:46.934675) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-03 22:54:46.935087) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-03 22:54:46.935368) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-03 22:54:46.976540) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 22:54:46.977220) ========= 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.A6PKIOR31L/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.0bqxJYiwCB/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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-03 22:54:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 58.5M=0s 2016-09-03 22:54:47 (58.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.528626 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 22:54:47.565279) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 22:54:47.565621) ========= 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.A6PKIOR31L/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.0bqxJYiwCB/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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.027109 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 22:54:47.663772) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-09-03 22:54:47.664152) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d61bbdd0b155f76170adef0e9433e2166c606483 --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 d61bbdd0b155f76170adef0e9433e2166c606483 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.A6PKIOR31L/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.0bqxJYiwCB/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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-03 22:54:47,755 truncating revision to first 12 chars 2016-09-03 22:54:47,755 Setting DEBUG logging. 2016-09-03 22:54:47,756 attempt 1/10 2016-09-03 22:54:47,756 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d61bbdd0b155?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-03 22:54:48,431 unpacking tar archive at: mozilla-central-d61bbdd0b155/testing/mozharness/ program finished with exit code 0 elapsedTime=0.934706 ========= master_lag: 3.88 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-09-03 22:54:52.481646) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-03 22:54:52.481948) ========= script_repo_revision: d61bbdd0b155f76170adef0e9433e2166c606483 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-03 22:54:52.482351) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-03 22:54:52.482629) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-03 22:54:52.738001) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-03 22:54:52.738288) ========= /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.A6PKIOR31L/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.0bqxJYiwCB/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 22:54:53 INFO - MultiFileLogger online at 20160903 22:54:53 in /builds/slave/test 22:54:53 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 22:54:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:54:53 INFO - {'append_to_log': False, 22:54:53 INFO - 'base_work_dir': '/builds/slave/test', 22:54:53 INFO - 'blob_upload_branch': 'Firefox', 22:54:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:54:53 INFO - 'branch': 'Firefox', 22:54:53 INFO - 'buildbot_json_path': 'buildprops.json', 22:54:53 INFO - 'config_files': ('talos/mac_config.py',), 22:54:53 INFO - 'default_actions': ('clobber', 22:54:53 INFO - 'read-buildbot-config', 22:54:53 INFO - 'download-and-extract', 22:54:53 INFO - 'populate-webroot', 22:54:53 INFO - 'create-virtualenv', 22:54:53 INFO - 'install', 22:54:53 INFO - 'run-tests'), 22:54:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:54:53 INFO - 'download_minidump_stackwalk': True, 22:54:53 INFO - 'download_symbols': 'ondemand', 22:54:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:54:53 INFO - 'tooltool.py': '/tools/tooltool.py', 22:54:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:54:53 INFO - '/tools/misc-python/virtualenv.py')}, 22:54:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:54:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:54:53 INFO - 'installer_path': 'installer.exe', 22:54:53 INFO - 'log_level': 'info', 22:54:53 INFO - 'log_name': 'talos', 22:54:53 INFO - 'log_to_console': True, 22:54:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:54:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:54:53 INFO - 'opt_config_files': (), 22:54:53 INFO - 'pip_index': False, 22:54:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:54:53 INFO - 'cmd': ('bash', 22:54:53 INFO - '-c', 22:54:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:54:53 INFO - 'enabled': True, 22:54:53 INFO - 'halt_on_failure': False, 22:54:53 INFO - 'name': 'check_screen_resolution'},), 22:54:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:54:53 INFO - 'cmd': ('bash', 22:54:53 INFO - '-c', 22:54:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:54:53 INFO - 'enabled': True, 22:54:53 INFO - 'halt_on_failure': False, 22:54:53 INFO - 'name': 'check_screen_resolution'},), 22:54:53 INFO - 'run_cmd_checks_enabled': True, 22:54:53 INFO - 'sps_profile': False, 22:54:53 INFO - 'sps_profile_interval': 0, 22:54:53 INFO - 'suite': 'dromaeojs-e10s', 22:54:53 INFO - 'system_bits': '32', 22:54:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:54:53 INFO - 'title': 't-yosemite-r7-0305', 22:54:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:54:53 INFO - 'use_talos_json': True, 22:54:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:54:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:54:53 INFO - 'work_dir': 'build'} 22:54:53 INFO - [mozharness: 2016-09-04 05:54:53.511854Z] Running clobber step. 22:54:53 INFO - Running pre-action listener: _resource_record_pre_action 22:54:53 INFO - Running main action method: clobber 22:54:53 INFO - rmtree: /builds/slave/test/build 22:54:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:54:55 INFO - Running post-action listener: _resource_record_post_action 22:54:55 INFO - [mozharness: 2016-09-04 05:54:55.673118Z] Finished clobber step (success) 22:54:55 INFO - [mozharness: 2016-09-04 05:54:55.673237Z] Running read-buildbot-config step. 22:54:55 INFO - Running pre-action listener: _resource_record_pre_action 22:54:55 INFO - Running main action method: read_buildbot_config 22:54:55 INFO - Using buildbot properties: 22:54:55 INFO - { 22:54:55 INFO - "project": "", 22:54:55 INFO - "product": "firefox", 22:54:55 INFO - "script_repo_revision": "production", 22:54:55 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 22:54:55 INFO - "repository": "", 22:54:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 22:54:55 INFO - "buildid": "20160903215150", 22:54:55 INFO - "pgo_build": "False", 22:54:55 INFO - "basedir": "/builds/slave/test", 22:54:55 INFO - "buildnumber": 74, 22:54:55 INFO - "slavename": "t-yosemite-r7-0305", 22:54:55 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 22:54:55 INFO - "platform": "yosemite_r7", 22:54:55 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 22:54:55 INFO - "branch": "mozilla-central", 22:54:55 INFO - "repo_path": "mozilla-central", 22:54:55 INFO - "revision": "d61bbdd0b155f76170adef0e9433e2166c606483", 22:54:55 INFO - "stage_platform": "macosx64", 22:54:55 INFO - "builduid": "e74cf24ffce54dde89a52d48c0ac7dbe", 22:54:55 INFO - "slavebuilddir": "test" 22:54:55 INFO - } 22:54:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/HbjXZtSeTOuvT3fKg3OX_g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 22:54:55 INFO - Running post-action listener: _resource_record_post_action 22:54:55 INFO - [mozharness: 2016-09-04 05:54:55.676065Z] Finished read-buildbot-config step (success) 22:54:55 INFO - [mozharness: 2016-09-04 05:54:55.676169Z] Running download-and-extract step. 22:54:55 INFO - Running pre-action listener: _resource_record_pre_action 22:54:55 INFO - Running main action method: download_and_extract 22:54:55 INFO - mkdir: /builds/slave/test/build/tests 22:54:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:54:55 INFO - trying https://queue.taskcluster.net/v1/task/HbjXZtSeTOuvT3fKg3OX_g/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 22:54:55 INFO - Downloading https://queue.taskcluster.net/v1/task/HbjXZtSeTOuvT3fKg3OX_g/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 22:54:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HbjXZtSeTOuvT3fKg3OX_g/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 22:54:56 INFO - Downloaded 1199 bytes. 22:54:56 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 22:54:56 INFO - Using the following test package requirements: 22:54:56 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 22:54:56 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:54:56 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 22:54:56 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:54:56 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 22:54:56 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:54:56 INFO - u'jsshell-mac.zip'], 22:54:56 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:54:56 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 22:54:56 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 22:54:56 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:54:56 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 22:54:56 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:54:56 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 22:54:56 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:54:56 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 22:54:56 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:54:56 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 22:54:56 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:54:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HbjXZtSeTOuvT3fKg3OX_g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 22:54:57 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 22:55:04 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 22:55:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HbjXZtSeTOuvT3fKg3OX_g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 22:55:05 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 22:55:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HbjXZtSeTOuvT3fKg3OX_g/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 22:55:12 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 22:55:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:55:13 INFO - trying https://queue.taskcluster.net/v1/task/HbjXZtSeTOuvT3fKg3OX_g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 22:55:13 INFO - Downloading https://queue.taskcluster.net/v1/task/HbjXZtSeTOuvT3fKg3OX_g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 22:55:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HbjXZtSeTOuvT3fKg3OX_g/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 22:55:18 INFO - Downloaded 111505581 bytes. 22:55:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HbjXZtSeTOuvT3fKg3OX_g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 22:55:18 INFO - mkdir: /builds/slave/test/properties 22:55:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:55:18 INFO - Writing to file /builds/slave/test/properties/build_url 22:55:18 INFO - Contents: 22:55:18 INFO - build_url:https://queue.taskcluster.net/v1/task/HbjXZtSeTOuvT3fKg3OX_g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 22:55:19 INFO - Running post-action listener: _resource_record_post_action 22:55:19 INFO - Running post-action listener: set_extra_try_arguments 22:55:19 INFO - [mozharness: 2016-09-04 05:55:19.335250Z] Finished download-and-extract step (success) 22:55:19 INFO - [mozharness: 2016-09-04 05:55:19.335391Z] Running populate-webroot step. 22:55:19 INFO - Running pre-action listener: _resource_record_pre_action 22:55:19 INFO - Running main action method: populate_webroot 22:55:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:55:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:55:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:55:19 INFO - '--tppagecycles', 22:55:19 INFO - '7'], 22:55:19 INFO - 'tests': ['tsvgm']}}, 22:55:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:55:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:55:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:55:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:55:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:55:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:55:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:55:19 INFO - 'talos_options': ['--disable-e10s'], 22:55:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:55:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:55:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:55:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:55:19 INFO - 'talos_options': ['--disable-e10s'], 22:55:19 INFO - 'tests': ['damp', 'tps']}, 22:55:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:55:19 INFO - 'tests': ['damp', 'tps']}, 22:55:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:55:19 INFO - 'tests': ['dromaeo_dom']}, 22:55:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:55:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:55:19 INFO - 'tests': ['basic_compositor_video']}, 22:55:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:55:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:55:19 INFO - 'tests': ['a11yr', 22:55:19 INFO - 'ts_paint', 22:55:19 INFO - 'tpaint', 22:55:19 INFO - 'sessionrestore', 22:55:19 INFO - 'sessionrestore_no_auto_restore', 22:55:19 INFO - 'tabpaint']}, 22:55:19 INFO - 'other-e10s': {'tests': ['a11yr', 22:55:19 INFO - 'ts_paint', 22:55:19 INFO - 'tpaint', 22:55:19 INFO - 'sessionrestore', 22:55:19 INFO - 'sessionrestore_no_auto_restore', 22:55:19 INFO - 'tabpaint']}, 22:55:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:55:19 INFO - 'ts_paint', 22:55:19 INFO - 'tpaint', 22:55:19 INFO - 'sessionrestore', 22:55:19 INFO - 'sessionrestore_no_auto_restore', 22:55:19 INFO - 'tabpaint']}, 22:55:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:55:19 INFO - 'ts_paint', 22:55:19 INFO - 'tpaint', 22:55:19 INFO - 'sessionrestore', 22:55:19 INFO - 'sessionrestore_no_auto_restore', 22:55:19 INFO - 'tabpaint']}, 22:55:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:55:19 INFO - 'tests': ['a11yr', 22:55:19 INFO - 'ts_paint', 22:55:19 INFO - 'tpaint', 22:55:19 INFO - 'sessionrestore', 22:55:19 INFO - 'sessionrestore_no_auto_restore', 22:55:19 INFO - 'tabpaint']}, 22:55:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:55:19 INFO - 'tests': ['a11yr', 22:55:19 INFO - 'ts_paint', 22:55:19 INFO - 'tpaint', 22:55:19 INFO - 'sessionrestore', 22:55:19 INFO - 'sessionrestore_no_auto_restore', 22:55:19 INFO - 'tabpaint']}, 22:55:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:55:19 INFO - 'tests': ['tsvgx', 22:55:19 INFO - 'tsvgr_opacity', 22:55:19 INFO - 'tart', 22:55:19 INFO - 'tscrollx', 22:55:19 INFO - 'cart']}, 22:55:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:55:19 INFO - 'tsvgr_opacity', 22:55:19 INFO - 'tart', 22:55:19 INFO - 'tscrollx', 22:55:19 INFO - 'cart']}, 22:55:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:55:19 INFO - 'talos_options': ['--disable-e10s'], 22:55:19 INFO - 'tests': ['tp5o']}, 22:55:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:55:19 INFO - 'tests': ['tp5o']}, 22:55:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:55:19 INFO - 'talos_options': ['--disable-e10s', 22:55:19 INFO - '--xperf_path', 22:55:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:55:19 INFO - 'tests': ['tp5n']}, 22:55:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:55:19 INFO - 'talos_options': ['--xperf_path', 22:55:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:55:19 INFO - 'tests': ['tp5n']}}, 22:55:19 INFO - 'talos.zip': {'path': '', 22:55:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:55:19 INFO - Running post-action listener: _resource_record_post_action 22:55:19 INFO - [mozharness: 2016-09-04 05:55:19.353967Z] Finished populate-webroot step (success) 22:55:19 INFO - [mozharness: 2016-09-04 05:55:19.354100Z] Running create-virtualenv step. 22:55:19 INFO - Running pre-action listener: _resource_record_pre_action 22:55:19 INFO - Running main action method: create_virtualenv 22:55:19 INFO - Creating virtualenv /builds/slave/test/build/venv 22:55:19 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 22:55:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:55:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:55:19 INFO - Using real prefix '/tools/python27' 22:55:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:55:20 INFO - Installing distribute.............................................................................................................................................................................................done. 22:55:23 INFO - Installing pip.................done. 22:55:23 INFO - Return code: 0 22:55:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:55:23 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')]} 22:55:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:55:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:23 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')]} 22:55:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:55:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:23 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 0x102a3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ad1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde51560eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029a0d30>, '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 0x102705db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102998b20>, '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.A6PKIOR31L/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.0bqxJYiwCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 22:55:23 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 22:55:23 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 22:55:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A6PKIOR31L/Render', 22:55:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:55:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:55:23 INFO - 'HOME': '/Users/cltbld', 22:55:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:55:23 INFO - 'LOGNAME': 'cltbld', 22:55:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:55:23 INFO - 'MOZ_NO_REMOTE': '1', 22:55:23 INFO - 'NO_EM_RESTART': '1', 22:55:23 INFO - 'PAGER': '/bin/cat', 22:55:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:55:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:55:23 INFO - 'PWD': '/builds/slave/test', 22:55:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:55:23 INFO - 'SHELL': '/bin/bash', 22:55:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0bqxJYiwCB/Listeners', 22:55:23 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 22:55:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:55:23 INFO - 'USER': 'cltbld', 22:55:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:55:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:55:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:55:23 INFO - 'XPC_FLAGS': '0x0', 22:55:23 INFO - 'XPC_SERVICE_NAME': '0', 22:55:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:55:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:55:23 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 22:55:23 INFO - Cleaning up... 22:55:23 INFO - Return code: 0 22:55:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:55:23 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')]} 22:55:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:55:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:23 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')]} 22:55:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:55:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:23 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 0x102a3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ad1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde51560eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029a0d30>, '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 0x102705db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102998b20>, '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.A6PKIOR31L/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.0bqxJYiwCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 22:55:23 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 22:55:23 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 22:55:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A6PKIOR31L/Render', 22:55:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:55:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:55:23 INFO - 'HOME': '/Users/cltbld', 22:55:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:55:23 INFO - 'LOGNAME': 'cltbld', 22:55:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:55:23 INFO - 'MOZ_NO_REMOTE': '1', 22:55:23 INFO - 'NO_EM_RESTART': '1', 22:55:23 INFO - 'PAGER': '/bin/cat', 22:55:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:55:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:55:23 INFO - 'PWD': '/builds/slave/test', 22:55:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:55:23 INFO - 'SHELL': '/bin/bash', 22:55:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0bqxJYiwCB/Listeners', 22:55:23 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 22:55:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:55:23 INFO - 'USER': 'cltbld', 22:55:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:55:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:55:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:55:23 INFO - 'XPC_FLAGS': '0x0', 22:55:23 INFO - 'XPC_SERVICE_NAME': '0', 22:55:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:55:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:55:23 INFO - Downloading/unpacking psutil>=3.1.1 22:55: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 22:55: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 22:55: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 22:55: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 22:55: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 22:55: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 22:55:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:55:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:55:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:55:27 INFO - Installing collected packages: psutil 22:55:27 INFO - Running setup.py install for psutil 22:55:27 INFO - building 'psutil._psutil_osx' extension 22:55:27 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 22:55:28 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 22:55:28 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 22:55:28 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 22:55:28 INFO - building 'psutil._psutil_posix' extension 22:55:28 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 22:55:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:55:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:55:28 INFO - ^ 22:55:28 INFO - 1 warning generated. 22:55:28 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 22:55:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:55:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:55:28 INFO - Successfully installed psutil 22:55:28 INFO - Cleaning up... 22:55:28 INFO - Return code: 0 22:55:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:55:28 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')]} 22:55:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:55:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:28 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')]} 22:55:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:55:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:28 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 0x102a3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ad1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde51560eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029a0d30>, '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 0x102705db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102998b20>, '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.A6PKIOR31L/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.0bqxJYiwCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 22:55:28 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 22:55:28 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 22:55:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A6PKIOR31L/Render', 22:55:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:55:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:55:28 INFO - 'HOME': '/Users/cltbld', 22:55:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:55:28 INFO - 'LOGNAME': 'cltbld', 22:55:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:55:28 INFO - 'MOZ_NO_REMOTE': '1', 22:55:28 INFO - 'NO_EM_RESTART': '1', 22:55:28 INFO - 'PAGER': '/bin/cat', 22:55:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:55:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:55:28 INFO - 'PWD': '/builds/slave/test', 22:55:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:55:28 INFO - 'SHELL': '/bin/bash', 22:55:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0bqxJYiwCB/Listeners', 22:55:28 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 22:55:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:55:28 INFO - 'USER': 'cltbld', 22:55:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:55:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:55:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:55:28 INFO - 'XPC_FLAGS': '0x0', 22:55:28 INFO - 'XPC_SERVICE_NAME': '0', 22:55:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:55:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:55:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:55:29 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 22:55:29 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 22:55:29 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 22:55:29 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 22:55:29 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 22:55:29 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 22:55:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:55:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:55:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:55:31 INFO - Installing collected packages: mozsystemmonitor 22:55:31 INFO - Running setup.py install for mozsystemmonitor 22:55:31 INFO - Successfully installed mozsystemmonitor 22:55:31 INFO - Cleaning up... 22:55:31 INFO - Return code: 0 22:55:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:55:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:55:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:55:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:55:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:55:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:31 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 0x102a3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ad1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde51560eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029a0d30>, '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 0x102705db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102998b20>, '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.A6PKIOR31L/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.0bqxJYiwCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 22:55:31 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 22:55:31 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 22:55:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A6PKIOR31L/Render', 22:55:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:55:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:55:31 INFO - 'HOME': '/Users/cltbld', 22:55:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:55:31 INFO - 'LOGNAME': 'cltbld', 22:55:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:55:31 INFO - 'MOZ_NO_REMOTE': '1', 22:55:31 INFO - 'NO_EM_RESTART': '1', 22:55:31 INFO - 'PAGER': '/bin/cat', 22:55:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:55:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:55:31 INFO - 'PWD': '/builds/slave/test', 22:55:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:55:31 INFO - 'SHELL': '/bin/bash', 22:55:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0bqxJYiwCB/Listeners', 22:55:31 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 22:55:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:55:31 INFO - 'USER': 'cltbld', 22:55:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:55:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:55:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:55:31 INFO - 'XPC_FLAGS': '0x0', 22:55:31 INFO - 'XPC_SERVICE_NAME': '0', 22:55:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:55:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:55:32 INFO - Downloading/unpacking jsonschema==2.5.1 22:55: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 22:55: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 22:55: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 22:55: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 22:55: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 22:55: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 22:55:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:55:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:55:34 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 22:55:34 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 22:55:34 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 22:55:34 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 22:55:34 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 22:55:34 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 22:55:34 INFO - Downloading functools32-3.2.3-2.tar.gz 22:55:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:55:34 INFO - warning: no files found matching '*.txt' 22:55:34 INFO - no previously-included directories found matching 'build' 22:55:34 INFO - no previously-included directories found matching 'dist' 22:55:34 INFO - no previously-included directories found matching '.git*' 22:55:34 INFO - Installing collected packages: jsonschema, functools32 22:55:35 INFO - Running setup.py install for functools32 22:55:35 INFO - warning: no files found matching '*.txt' 22:55:35 INFO - no previously-included directories found matching 'build' 22:55:35 INFO - no previously-included directories found matching 'dist' 22:55:35 INFO - no previously-included directories found matching '.git*' 22:55:35 INFO - Successfully installed jsonschema functools32 22:55:35 INFO - Cleaning up... 22:55:35 INFO - Return code: 0 22:55:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:55: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')]} 22:55:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:55:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55: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')]} 22:55:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:55:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55: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', '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 0x102a3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ad1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde51560eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029a0d30>, '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 0x102705db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102998b20>, '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.A6PKIOR31L/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.0bqxJYiwCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 22:55: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:55: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 functools32==3.2.3-2 22:55:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A6PKIOR31L/Render', 22:55:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:55:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:55:35 INFO - 'HOME': '/Users/cltbld', 22:55:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:55:35 INFO - 'LOGNAME': 'cltbld', 22:55:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:55:35 INFO - 'MOZ_NO_REMOTE': '1', 22:55:35 INFO - 'NO_EM_RESTART': '1', 22:55:35 INFO - 'PAGER': '/bin/cat', 22:55:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:55:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:55:35 INFO - 'PWD': '/builds/slave/test', 22:55:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:55:35 INFO - 'SHELL': '/bin/bash', 22:55:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0bqxJYiwCB/Listeners', 22:55:35 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 22:55:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:55:35 INFO - 'USER': 'cltbld', 22:55:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:55:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:55:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:55:35 INFO - 'XPC_FLAGS': '0x0', 22:55:35 INFO - 'XPC_SERVICE_NAME': '0', 22:55:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:55:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:55:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:55:35 INFO - Cleaning up... 22:55:35 INFO - Return code: 0 22:55:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:55: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')]} 22:55:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:55:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55: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')]} 22:55:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:55:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55: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', '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 0x102a3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ad1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde51560eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029a0d30>, '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 0x102705db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102998b20>, '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.A6PKIOR31L/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.0bqxJYiwCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 22:55: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:55: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 blobuploader==1.2.4 22:55:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A6PKIOR31L/Render', 22:55:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:55:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:55:35 INFO - 'HOME': '/Users/cltbld', 22:55:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:55:35 INFO - 'LOGNAME': 'cltbld', 22:55:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:55:35 INFO - 'MOZ_NO_REMOTE': '1', 22:55:35 INFO - 'NO_EM_RESTART': '1', 22:55:35 INFO - 'PAGER': '/bin/cat', 22:55:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:55:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:55:35 INFO - 'PWD': '/builds/slave/test', 22:55:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:55:35 INFO - 'SHELL': '/bin/bash', 22:55:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0bqxJYiwCB/Listeners', 22:55:35 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 22:55:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:55:35 INFO - 'USER': 'cltbld', 22:55:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:55:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:55:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:55:35 INFO - 'XPC_FLAGS': '0x0', 22:55:35 INFO - 'XPC_SERVICE_NAME': '0', 22:55:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:55:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:55:35 INFO - Downloading/unpacking blobuploader==1.2.4 22:55:35 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 22:55:35 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 22:55:35 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 22:55:35 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 22:55:35 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 22:55:35 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 22:55:38 INFO - Downloading blobuploader-1.2.4.tar.gz 22:55:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:55:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:55:38 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 22:55:38 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 22:55:38 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 22:55:38 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 22:55:38 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 22:55:38 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 22:55:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:55:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:55:38 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 22:55:38 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 22:55:38 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 22:55:38 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 22:55:38 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 22:55:38 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 22:55:39 INFO - Downloading docopt-0.6.1.tar.gz 22:55:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:55:39 INFO - Installing collected packages: blobuploader, requests, docopt 22:55:39 INFO - Running setup.py install for blobuploader 22:55:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:55:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:55:39 INFO - Running setup.py install for requests 22:55:40 INFO - Running setup.py install for docopt 22:55:40 INFO - Successfully installed blobuploader requests docopt 22:55:40 INFO - Cleaning up... 22:55:40 INFO - Return code: 0 22:55:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:55:40 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')]} 22:55:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:55:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:40 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')]} 22:55:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:55:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:40 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 0x102a3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ad1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde51560eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029a0d30>, '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 0x102705db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102998b20>, '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.A6PKIOR31L/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.0bqxJYiwCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 22:55:40 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 22:55:40 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 22:55:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A6PKIOR31L/Render', 22:55:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:55:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:55:40 INFO - 'HOME': '/Users/cltbld', 22:55:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:55:40 INFO - 'LOGNAME': 'cltbld', 22:55:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:55:40 INFO - 'MOZ_NO_REMOTE': '1', 22:55:40 INFO - 'NO_EM_RESTART': '1', 22:55:40 INFO - 'PAGER': '/bin/cat', 22:55:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:55:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:55:40 INFO - 'PWD': '/builds/slave/test', 22:55:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:55:40 INFO - 'SHELL': '/bin/bash', 22:55:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0bqxJYiwCB/Listeners', 22:55:40 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 22:55:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:55:40 INFO - 'USER': 'cltbld', 22:55:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:55:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:55:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:55:40 INFO - 'XPC_FLAGS': '0x0', 22:55:40 INFO - 'XPC_SERVICE_NAME': '0', 22:55:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:55:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:55:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:55:40 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-LQ4G0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:55:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:55:40 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-xXC8WL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:55:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:55:40 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-l9lvcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:55:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:55:40 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-3dgv9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:55:41 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-kmPlGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:55:41 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-NVwhbU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:55:41 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-Rp4SfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:55:41 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-NCnri4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:55:41 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-OiJMLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:55:41 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-Opgd7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:55:41 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-osCj9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:55:41 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-pjYUL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:55:41 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-22V0q4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:55:41 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-TkzawI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:55:42 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-qxEYQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:55:42 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-fNSSjP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:55:42 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-4qRm3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:55:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:55:42 INFO - Running setup.py install for manifestparser 22:55:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:55:42 INFO - Running setup.py install for mozcrash 22:55:42 INFO - Running setup.py install for mozdebug 22:55:42 INFO - Running setup.py install for mozdevice 22:55:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:55:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:55:42 INFO - Running setup.py install for mozfile 22:55:43 INFO - Running setup.py install for mozhttpd 22:55:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:55:43 INFO - Running setup.py install for mozinfo 22:55:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:55:43 INFO - Running setup.py install for mozInstall 22:55:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:55:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:55:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:55:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:55:43 INFO - Running setup.py install for mozleak 22:55:43 INFO - Running setup.py install for mozlog 22:55:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:55:43 INFO - Running setup.py install for moznetwork 22:55:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:55:43 INFO - Running setup.py install for mozprocess 22:55:44 INFO - Running setup.py install for mozprofile 22:55:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:55:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:55:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:55:44 INFO - Running setup.py install for mozrunner 22:55:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:55:44 INFO - Running setup.py install for mozscreenshot 22:55:44 INFO - Running setup.py install for moztest 22:55:44 INFO - Running setup.py install for mozversion 22:55:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:55:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:55:44 INFO - Cleaning up... 22:55:44 INFO - Return code: 0 22:55:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:55:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:55:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:55:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:55:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:55:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:44 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 0x102a3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ad1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde51560eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029a0d30>, '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 0x102705db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102998b20>, '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.A6PKIOR31L/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.0bqxJYiwCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 22:55:44 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 22:55:44 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 22:55:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A6PKIOR31L/Render', 22:55:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:55:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:55:44 INFO - 'HOME': '/Users/cltbld', 22:55:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:55:44 INFO - 'LOGNAME': 'cltbld', 22:55:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:55:44 INFO - 'MOZ_NO_REMOTE': '1', 22:55:44 INFO - 'NO_EM_RESTART': '1', 22:55:44 INFO - 'PAGER': '/bin/cat', 22:55:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:55:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:55:44 INFO - 'PWD': '/builds/slave/test', 22:55:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:55:44 INFO - 'SHELL': '/bin/bash', 22:55:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0bqxJYiwCB/Listeners', 22:55:44 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 22:55:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:55:44 INFO - 'USER': 'cltbld', 22:55:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:55:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:55:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:55:44 INFO - 'XPC_FLAGS': '0x0', 22:55:44 INFO - 'XPC_SERVICE_NAME': '0', 22:55:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:55:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:55:45 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-c0_b76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:55:45 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)) 22:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:55:45 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-6eDLMp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:55:45 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)) 22:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:55:45 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-MlaTaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:55:45 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)) 22:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:55:45 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-wPRcTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:55:45 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)) 22:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:55:45 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-ZRHYNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:55:45 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)) 22:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:55:45 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-8dTK8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:55:45 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)) 22:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:55:45 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-qAYjtL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:55:45 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)) 22:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:55:45 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-I23ehf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:55:46 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)) 22:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:55:46 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-fbGy_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:55:46 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)) 22:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:55:46 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-_45bSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:55:46 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)) 22:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:55:46 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-8VmvBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:55:46 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)) 22:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:55:46 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-uBLBLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:55:46 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)) 22:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:55:46 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-3ZWx4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:55:46 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)) 22:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:55:46 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-KtzIm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:55:46 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)) 22:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:55:46 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-6ID3Wk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:55:46 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)) 22:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:55:46 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-OyVVVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:55:46 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)) 22:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:55:46 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-EaaeCq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:55:46 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)) 22:55:46 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)) 22:55:46 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)) 22:55:46 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)) 22:55:46 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)) 22:55:46 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)) 22:55:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:55:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:55:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:55:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:55:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:55:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:55:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:55:49 INFO - Downloading blessings-1.6.tar.gz 22:55:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:55:49 INFO - Installing collected packages: blessings 22:55:49 INFO - Running setup.py install for blessings 22:55:49 INFO - Successfully installed blessings 22:55:49 INFO - Cleaning up... 22:55:49 INFO - Return code: 0 22:55:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:55:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:55:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:55:50 INFO - Reading from file tmpfile_stdout 22:55:50 INFO - Current package versions: 22:55:50 INFO - blessings == 1.6 22:55:50 INFO - blobuploader == 1.2.4 22:55:50 INFO - docopt == 0.6.1 22:55:50 INFO - functools32 == 3.2.3-2 22:55:50 INFO - jsonschema == 2.5.1 22:55:50 INFO - manifestparser == 1.1 22:55:50 INFO - mozInstall == 1.12 22:55:50 INFO - mozcrash == 0.17 22:55:50 INFO - mozdebug == 0.1 22:55:50 INFO - mozdevice == 0.48 22:55:50 INFO - mozfile == 1.2 22:55:50 INFO - mozhttpd == 0.7 22:55:50 INFO - mozinfo == 0.9 22:55:50 INFO - mozleak == 0.1 22:55:50 INFO - mozlog == 3.3 22:55:50 INFO - moznetwork == 0.27 22:55:50 INFO - mozprocess == 0.23 22:55:50 INFO - mozprofile == 0.28 22:55:50 INFO - mozrunner == 6.12 22:55:50 INFO - mozscreenshot == 0.1 22:55:50 INFO - mozsystemmonitor == 0.3 22:55:50 INFO - moztest == 0.7 22:55:50 INFO - mozversion == 1.4 22:55:50 INFO - psutil == 3.1.1 22:55:50 INFO - requests == 1.2.3 22:55:50 INFO - wsgiref == 0.1.2 22:55:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:55:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:55:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:55:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:55:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:55:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:50 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 0x102a3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ad1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde51560eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029a0d30>, '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 0x102705db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102998b20>, '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.A6PKIOR31L/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.0bqxJYiwCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 22:55:50 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 22:55:50 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 22:55:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A6PKIOR31L/Render', 22:55:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:55:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:55:50 INFO - 'HOME': '/Users/cltbld', 22:55:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:55:50 INFO - 'LOGNAME': 'cltbld', 22:55:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:55:50 INFO - 'MOZ_NO_REMOTE': '1', 22:55:50 INFO - 'NO_EM_RESTART': '1', 22:55:50 INFO - 'PAGER': '/bin/cat', 22:55:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:55:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:55:50 INFO - 'PWD': '/builds/slave/test', 22:55:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:55:50 INFO - 'SHELL': '/bin/bash', 22:55:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0bqxJYiwCB/Listeners', 22:55:50 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 22:55:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:55:50 INFO - 'USER': 'cltbld', 22:55:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:55:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:55:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:55:50 INFO - 'XPC_FLAGS': '0x0', 22:55:50 INFO - 'XPC_SERVICE_NAME': '0', 22:55:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:55:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:55:50 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)) 22:55:50 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)) 22:55:50 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)) 22:55:50 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)) 22:55:50 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)) 22:55:50 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)) 22:55:50 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)) 22:55:50 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)) 22:55:50 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)) 22:55:50 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)) 22:55:50 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)) 22:55:50 INFO - Cleaning up... 22:55:50 INFO - Return code: 0 22:55:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:55:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:55:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:55:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:55:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:55:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102a3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ad1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde51560eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029a0d30>, '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 0x102705db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102998b20>, '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.A6PKIOR31L/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.0bqxJYiwCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 22:55:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:55:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:55:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A6PKIOR31L/Render', 22:55:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:55:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:55:50 INFO - 'HOME': '/Users/cltbld', 22:55:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:55:50 INFO - 'LOGNAME': 'cltbld', 22:55:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:55:50 INFO - 'MOZ_NO_REMOTE': '1', 22:55:50 INFO - 'NO_EM_RESTART': '1', 22:55:50 INFO - 'PAGER': '/bin/cat', 22:55:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:55:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:55:50 INFO - 'PWD': '/builds/slave/test', 22:55:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:55:50 INFO - 'SHELL': '/bin/bash', 22:55:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0bqxJYiwCB/Listeners', 22:55:50 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 22:55:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:55:50 INFO - 'USER': 'cltbld', 22:55:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:55:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:55:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:55:50 INFO - 'XPC_FLAGS': '0x0', 22:55:50 INFO - 'XPC_SERVICE_NAME': '0', 22:55:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:55:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:55:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:55:50 INFO - Cleaning up... 22:55:50 INFO - Return code: 0 22:55:50 INFO - Running post-action listener: _resource_record_post_action 22:55:50 INFO - Running post-action listener: _start_resource_monitoring 22:55:50 INFO - Starting resource monitoring. 22:55:50 INFO - [mozharness: 2016-09-04 05:55:50.686604Z] Finished create-virtualenv step (success) 22:55:50 INFO - [mozharness: 2016-09-04 05:55:50.687570Z] Running install step. 22:55:50 INFO - Running pre-action listener: _resource_record_pre_action 22:55:50 INFO - Running main action method: install 22:55:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:55:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:55:50 INFO - Reading from file tmpfile_stdout 22:55:50 INFO - Detecting whether we're running mozinstall >=1.0... 22:55:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:55:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:55:51 INFO - Reading from file tmpfile_stdout 22:55:51 INFO - Output received: 22:55:51 INFO - Usage: mozinstall [options] installer 22:55:51 INFO - Options: 22:55:51 INFO - -h, --help show this help message and exit 22:55:51 INFO - -d DEST, --destination=DEST 22:55:51 INFO - Directory to install application into. [default: 22:55:51 INFO - "/builds/slave/test"] 22:55:51 INFO - --app=APP Application being installed. [default: firefox] 22:55:51 INFO - mkdir: /builds/slave/test/build/application 22:55:51 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'] 22:55:51 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 22:56:25 INFO - Reading from file tmpfile_stdout 22:56:25 INFO - Output received: 22:56:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:56:25 INFO - Running post-action listener: _resource_record_post_action 22:56:25 INFO - [mozharness: 2016-09-04 05:56:25.348943Z] Finished install step (success) 22:56:25 INFO - [mozharness: 2016-09-04 05:56:25.349103Z] Running run-tests step. 22:56:25 INFO - Running pre-action listener: _resource_record_pre_action 22:56:25 INFO - Running main action method: run_tests 22:56:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:56:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:56:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:56:25 INFO - 2016-09-03 22:56:25.363 screenresolution[1649:12867] starting screenresolution argv=screenresolution get 22:56:25 INFO - 2016-09-03 22:56:25.366 screenresolution[1649:12867] Display 0: 1600x1200x32@60 22:56:25 INFO - 2016-09-03 22:56:25.372 screenresolution[1650:12869] starting screenresolution argv=screenresolution list 22:56:25 INFO - Available Modes on Display 0 22:56:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:56:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:56:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:56:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:56:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:56:25 INFO - Intel Iris: 22:56:25 INFO - Chipset Model: Intel Iris 22:56:25 INFO - Type: GPU 22:56:25 INFO - Bus: Built-In 22:56:25 INFO - VRAM (Dynamic, Max): 1536 MB 22:56:25 INFO - Vendor: Intel (0x8086) 22:56:25 INFO - Device ID: 0x0a2e 22:56:25 INFO - Revision ID: 0x0009 22:56:25 INFO - Displays: 22:56:25 INFO - Display: 22:56:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:56:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:56:25 INFO - Display Serial Number: 1600x1200 60 22:56:25 INFO - Main Display: Yes 22:56:25 INFO - Mirror: Off 22:56:25 INFO - Online: Yes 22:56:25 INFO - Rotation: Supported 22:56:25 INFO - Return code: 0 22:56:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:56:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:56:25 INFO - Python 2.7.3 22:56:25 INFO - Return code: 0 22:56:25 INFO - grabbing minidump binary from tooltool 22:56: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')]} 22:56:25 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 0x1029a0d30>, '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 0x102705db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102998b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:56:25 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 22:56:25 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 22:56:25 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 22:56:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:56:25 INFO - Return code: 0 22:56:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:56:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:56:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:56:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:56:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:56:25 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/HbjXZtSeTOuvT3fKg3OX_g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0305', '--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 22:56:25 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/HbjXZtSeTOuvT3fKg3OX_g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0305 --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 22:56:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A6PKIOR31L/Render', 22:56:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:56:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:56:25 INFO - 'HOME': '/Users/cltbld', 22:56:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:56:25 INFO - 'LOGNAME': 'cltbld', 22:56:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:56:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:56:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:25 INFO - 'MOZ_NO_REMOTE': '1', 22:56:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:56:25 INFO - 'NO_EM_RESTART': '1', 22:56:25 INFO - 'PAGER': '/bin/cat', 22:56:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:56:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:25 INFO - 'PWD': '/builds/slave/test', 22:56:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:56:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:56:25 INFO - 'SHELL': '/bin/bash', 22:56:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0bqxJYiwCB/Listeners', 22:56:25 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 22:56:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:56:25 INFO - 'USER': 'cltbld', 22:56:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:56:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:56:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:25 INFO - 'XPC_FLAGS': '0x0', 22:56:25 INFO - 'XPC_SERVICE_NAME': '0', 22:56:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:56:25 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/HbjXZtSeTOuvT3fKg3OX_g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0305', '--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 22:56:25 INFO - mozversion application_buildid: 20160903215150 22:56:25 INFO - mozversion application_changeset: d61bbdd0b155f76170adef0e9433e2166c606483 22:56:25 INFO - mozversion application_display_name: Nightly 22:56:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:56:25 INFO - mozversion application_name: Firefox 22:56:25 INFO - mozversion application_remotingname: firefox 22:56:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:56:25 INFO - mozversion application_vendor: Mozilla 22:56:25 INFO - mozversion application_version: 51.0a1 22:56:25 INFO - mozversion platform_buildid: 20160903215150 22:56:25 INFO - mozversion platform_changeset: d61bbdd0b155f76170adef0e9433e2166c606483 22:56:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:56:25 INFO - mozversion platform_version: 51.0a1 22:56:25 INFO - using testdate: 1472968585 22:56:25 INFO - actual date: 1472968585 22:56:25 INFO - starting webserver on 'localhost:49271' 22:56:25 INFO - SUITE-START | Running 2 tests 22:56:25 INFO - TEST-START | dromaeo_css 22:56:25 INFO - operating with platform_type : mac_ 22:56:25 INFO - Initialising browser for dromaeo_css test... 22:56:25 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/tmpeS2gVU/profile http://localhost:49271/getInfo.html) 22:56:29 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 22:56:29 INFO - PROCESS | 1660 | colorDepth:24 22:56:29 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 22:56:29 INFO - PROCESS | 1660 | __metrics 22:56:29 ERROR - PROCESS | 1660 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 22:56:29 INFO - TEST-INFO | 1660: exit 0 22:56:29 INFO - Browser initialized. 22:56:29 INFO - Running cycle 1/1 for dromaeo_css test... 22:56:29 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/tmpeS2gVU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:56:41 INFO - PROCESS | 1664 | RSS: Main: 244965376 22:56:41 INFO - PROCESS | 1664 | 22:57:31 INFO - PROCESS | 1664 | 1472968651313 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] 22:59:42 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 22:59:44 INFO - PROCESS | 1664 | RSS: Main: 265834496 22:59:44 INFO - PROCESS | 1664 | 23:02:46 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 23:02:47 INFO - PROCESS | 1664 | RSS: Main: 273633280 23:02:47 INFO - PROCESS | 1664 | 23:05:48 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 23:05:49 INFO - PROCESS | 1664 | RSS: Main: 276434944 23:05:49 INFO - PROCESS | 1664 | 23:08:51 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 23:08:52 INFO - PROCESS | 1664 | RSS: Main: 271601664 23:08:52 INFO - PROCESS | 1664 | 23:11:54 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 23:11:55 INFO - PROCESS | 1664 | RSS: Main: 278024192 23:11:55 INFO - PROCESS | 1664 | 23:14:58 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 23:14:58 INFO - PROCESS | 1664 | RSS: Main: 280686592 23:14:58 INFO - PROCESS | 1664 | 23:14:58 INFO - PROCESS | 1664 | __start_tp_report 23:14:58 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 23:14:58 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 23:14:58 INFO - PROCESS | 1664 | |i|pagename|runs| 23:14:58 INFO - PROCESS | 1664 | |0;dojo.html;2899.4346102510012;3307.0740192746025;3317.8838740644082;3328.550645662835;3340.215049463377;5038;5626.959012582675;5655.377908430075;5656.886862262752;5683.204351390808;5531.1426728856995;5636.2954630671165;5670.206171136044;5694.2312787773635;5729.799457019005;5016.046951079302;5106.234064890259;5169.638125331228;5246.108161612516;5284.075286824809;4442.8667139985855;4500.369948207253;5224.451432599561;5316.574674026068;5330.786768529254;3949.842006319758;4220.472440944882;5092.185250359925;5221.477852214786;5226.8693282667855;5183.870403239949;5192.71440070796;5203.713795741232;5217.947820521784;5244.501772331622;4181.519125300599;5137.280780690707;5139.306193663845;5163.896722065575;5185.974070129663;5507.366652834935;5511.338639363262;5527.281453411071;5621.297337832771;5626.662400255997;6063.501542749097;6196.690165491707;6209.192804935329;6213;6230.5638605297645;6245.7501699931945;6262.530310226751;6263.96868015657;6274.309825919146;6275.61936373997;5633;5739.540836733051;5795.913061304083;5804.9419505805245;5810.477057064884;6245.000799871998;6253;6253.37466253371;6255.311915689271;6260.499160067184;6156.415140561639;6167.321594624587;6173.629582225081;6179.907301390483;6181.783637572664;6086.056661217518;6109.9816581254145;6129.540284478593;6168.290646575611;6234;5053.787091098184;6096.496448835484;6140.596275277727;6157.507399408037;6220.937790622126;6213.596116252429;6216.658083805343;6218.782342617987;6234.501239900797;6234.625922444668;4923.113839508851;4950.381202349707;4976.576990955809;4984.252362145708;4989.351384320015;4344.261475549164;4373.234683930299;4413.293872980372;4418.977905110454;4472.731636101845;5615.129654903497;5651.095824668033;5698.886022279531;5699.116636921284;5706.201131841545;6225.097360882647;6226.910290699109;6239.469645080219;6243.313235544178;6244.718987645567;4960.305557221992;5496.587755918322;5508.779648814083;5717.8564287142735;5761.567882408836;5413.404525502192;5432.130859062531;5434.375046869582;5446.537044351196;5450.645708028966;6254.874902501924;6265.937340626535;6269.028300613412;6287.056941458818;6300.8424789379715;6110.816675499742;6149.723262453111;6164.229471316085;6210.441060304594;6231.937680623317;6229.626222426669;6246.562740608296;6277.372262773686;6279.340669229665;6282.246130464191;6129.141920131096;6143.477804386677;6190.102435146969;6198;6199.132121502903;6037.547183961133;6084.056971169476;6127.250613123855;6146.200993870768;6217.626942383291;6072.05883088122;6089.33017368098;6100.645212681175;6110.7555697771595;6134.447899689049;5064.80976970419;6036.9698151508965;6075.483583895419;6078.361772014052;6079.240094988127;4401.757903315349;5713.685747283939;5874.324452687824;5916.852078698067;6015.428534289411;6075.605085669417;6107.755689772359;6113.23584551931;6119.082137679384;6134.877302453837;4836.226203807374;4907.803687852446;5565.664240582312;5629.746661400329;5876.41235876409 23:14:58 INFO - PROCESS | 1664 | |1;ext.html;488.72142878559214;507.32019094413477;527.8733104055027;535.213236542283;569.8717788497586;29154.41691166178;38937.4159387609;39022.41466378003;39097.41353879694;39224.80387598058;39475.60524394755;39911.60088399115;39955.80022099893;40015.599844001554;40026.79919602416;9587.233021358292;9782.26633002524;9798.559064842064;9811.558479868423;9838.212942964581;9618.807623847519;9723.756906077333;9726.9513652432;9727.367721098106;9760.365576237557;9723.659671911484;9731.854022189673;9754.902450975505;9765;9778.90221097787;9612.89451713055;9753.804923901553;9756.609735610567;9770.609175632966;9772.31593788435;9628.3260171788;9717.368371055858;9768.609255629766;9796.26528010395;9806.117449429514;37846.81076594627;37955.620443795764;37969;38235;38298.23403531914;3307.834608269577;3389.694927456541;3391.6608339166132;3407.1141503209096;3780.7542509736777;29836.55245171324;30136.8493157535;30690;30877.536836947464;31304;3232.4504834178247;3281.212508997847;3288.112209703391;3386.932261354784;3432.5194472773833;407906.96046519873;475859;482735;483093;484033;32131.678683212867;32778.508322375186;55896.44103558994;56090.158647620316;56167.15749263764;27663.34019958809;30679.38641227163;33782.324353513286;34095.82952085224;34218.31563368718;14100;14228.86169106469;14289.571312860422;14298.070625409315;14308.069975451563;12039.097067719957;12083.879161208451;12167.939160304142;12184;12199.756004879853;6474.12599299098;7560.924390756132;7568.924310756822;7595.430342724208;7639;2607.5958226474927;3119.750419966397;3168.445522033681;3181.538676891884;3193.2655489237604;871.5772850167664;884.7390019944132;933.3653115881131;950.7528042708946;1077.2028698763015;27344.863275683892;29485.557716634266;29660.406791864472;29667.703322966496;30304.54543181854;926.073926073926;959.5921733263335;961.0149596663476;1065.2756125834353;1094.2723089145788;13034.41345139471;13665.701758332942;14800.777988330183;14925;15803.757620506105;528965;548759;560615.1969240127;656391;916638;56606.716966414904;56693.14960275599;57241.427585723606;57241.71379143161;57469;1337.1175024483794;1337.1509091726823;1367.446184295362;1375.9036867419384;1377.6211541825878;24401.877990609933;29687.13616884226;30041.248968775955;30318.84840575783;30511.847440762653;47162.52837471581;47570.762146190435;47653;47882.281765772845;47922.5207747932;583682.6317368229;587564;591326;592671;593112;21910.56178876382;25611;26101.608475873258;26342.604860926716;26424;34079.97691921199;43592;44676.32985505155;44942.77528612336;45170.77414612906;36937.44593831041;36987;37361.4395784058;37823.05442363963;37946.0513487165;36897.44653830141;36936;37665.62334376621;38017.61982380141;38065.61934380621;28845.855770721013;35855.28289434249;36183.27633447263;37256.62743372531;37272.81363593165;28347.858260708563;29463.705362946097;30214.2446438832;31256;33008.50487242645;20136.49658758543;24359.903804328493;24787.380315492257;25057;25201.621975670747 23:14:58 INFO - PROCESS | 1664 | |2;jquery.html;316.77825522134503;353.70896228765;376.1536542778748;387.50980010286975;406.6096547314578;19507.927064011474;20555.486112847168;20799;21420.14319427223;21552.137914483403;20102.59794804103;20499.07754151063;20744.066517006733;20993.47516312093;21024.474388140305;5334.5732341412595;5347.25138480613;5362.865928351784;5980.162777211194;6226.190595222614;5426.538744206747;6131.601445906002;6190.78332258371;6230.470410015154;6259.405356491126;5802.53579713624;6101.877962440749;6136.417040381157;6180.042093475518;6204.100405441231;5940.79207227745;6022.307434645006;6111.54163437744;6142.232220972401;6160.229971253593;5895.616784908967;5905.704714764244;5956.880862382728;5958.553108516835;5964.343922168558;13073.346332683328;13532.729345413036;13555.186688798605;14768.409263629443;20646.380608581767;2115.5557332960097;2271.205078222609;2334.5914464968732;2451.2891261534132;2851.586519954595;10285.948570257176;10631.308964917256;10828.783424331548;10925.235233533656;16027.439039633557;1833.8716289859715;1861.8789778664343;1868.7103498957683;1957.5399781051442;1984.107151781704;105203.47398263036;121413;127348;128096.71903281035;128292;9811.675423817693;11210.327380357205;11313.490892909838;11714.414279286002;11715.589954351557;14577.708445831237;14699.853001470063;14712.55862324132;14720.705585888223;14740.631484212767;8236;8549.786255343666;8604.289648760621;8608.09614990417;8662.2637075848;8424.031236407769;8452.661893524313;8473.533955632376;8555.443896146731;8656.8701469478;4481.1933851906315;4501.909961800746;4568.223402021665;4588.403507543998;4644.2801365788355;1898.7057006164066;1944.562473443492;1946.562023544691;1947.9805201948082;1960;800.4236949396425;844.4511067805853;867.3061550759368;891.5631340643038;954.188939401517;20347.389578312675;21175.894120529607;21178.682319765216;21193.36419907405;21222.151113955268;898.3890954151109;916.2303664921498;929.8140371925642;942.853857652065;971.3928794503436;10021.348612340174;11191.32852028881;11245.269057511236;11276.605318813803;11278.210525263154;544553;551543;556652;567544;567974;115463;127995.36002319929;129155;134571;137959.31020344835;950.5294879034922;976.7655762616921;989.1938070472686;1009.1724785675822;1023.2683631203726;19255;19338.806611934262;19703.40889773309;19709.211631534577;19795;51202.74398627983;53026.469735302155;53352.46647533475;54435.72782136064;55537.722311388185;69948.65025674838;80362.5981870087;80514;81293;82734.17265827506;6011;7324.414046876131;7978.680852766057;8027.317677997202;8382.455140415854;8972.327075469497;10719.98160174782;13075.692430756848;13554;13995.720085598028;11267.154963377943;11389;11433.485493152662;11661.358625275352;11712.004479619327;11110.277831940897;11258.943705281421;11501.022413094646;11757.412129393326;11793.52825886955;11445.771084578428;11625.18623696367;11777.823332649845;11819.349935753617;11964.341961191869;7056.259092795388;7158.570485770871;7554.2823431773895;7594.658240379085;7753.147153812964;10943.28868623537;11314.83027754568;11434.770060607412;11492.310461372344;11598.072154227473 23:14:58 INFO - PROCESS | 1664 | |3;mootools.html;1524.3140586735967;1556.3852278350053;1767.4609244180529;1819.590592116773;1884.4535084825402;7947;8461.111583283751;8605.139486051392;9032.367734258605;9039.548022598869;8673.568861137921;8855.247303979153;8881.555922203881;8891.510966896818;8893.199612034916;2397.736249012607;2408.771166739166;2409.5301416223842;2412.5657381671294;2418.51629674065;2355.5406695694346;2395.161693407302;2399.8080153587757;2401.6997875265592;2403.134871446278;2374.608189648706;2379.524095180962;2387.6657267982496;2391.8923648435775;2398.4243781492405;2350.647402889563;2367.2779802160335;2376.66726658268;2381.0356455923848;2381.8451800633075;2060.6393881070753;2134.8185404240658;2295.3228797504607;2297.885105744706;2298.7816157465013;8765.430247033923;8821.779455513599;8932.106789321017;8932.732018039469;8945.64217431302;5267.3152490176035;5430.5384042356445;5432.320959880015;5445.618806683534;5447.4824891635235;3140.8272545010013;3632.2553876455263;3652.9589067116012;3681.245344704356;3714.7213958953184;934.5233930695326;1092.2736380307085;1099.3458891959272;1102;1103.2885916001585;11446.885531144666;11457.25527840696;11562.53749850005;11578.826317605242;11584.942075289653;4561.156186105581;4628.861134165981;4803.087413391514;4818.759062046884;4831.855044348676;9909.35589186715;9993.300468967247;9999.3000489965;10003;10009.349392289478;5411;5429.918551221735;5433.755481003364;5439.4288599696465;5443.564514838803;4409.14021765759;4767;4922.655414120977;4923.75381230937;4946.604271658331;3864.7101467390057;3933.4886464759397;3996.9600303997167;4053.61490658387;4053.8783836484954;1294.9417276222594;1305.8498272698762;1308.8036794480713;1317.8220940173146;1317.8418589769096;876.7720392697817;915.9084091590788;918.8468472067512;922.5479515037583;933.8225737109927;4735.313379559944;5791.102379131242;5872;5883.588148829626;5919.378465261172;416.5792549524941;418.9036521600049;426.95730426957056;430.0860671073968;440.2713509142375;3948.980255098706;3961;4014.217227640582;4058.0869304406274;4073.327900896378;10253.94873025645;10701.946490267655;10722.946385268024;10727.356358618352;10840;10647.041766241075;10658.733531661615;10692.411917344465;10710.357378557312;10733;8122.268995790408;8198.180181981874;8201.71294004719;8211.05572859117;8224.712135075364;5581.665100094007;5615;5753.1370294456165;5769.94230057694;5831.271091113611;9994.100530951959;10000.24998125129;10048.547815348473;10061.547230374505;10088.54601542947;9916.504174791087;9972.152367048882;10072.345297555636;10198.133158681596;10227.232957528066;6863.519553631198;7247.601381924045;7413;7463.2536746327105;7558.546487210785;8385.161483851565;8438.071812100787;8441.788955276168;8471.788205294917;8474.618642160995;7236.746713864989;7355.117385913724;7364.153122390993;7413.666385012795;7514.323710866049;7043.119610048744;7197.496175267681;7229.457790665826;7247.710091596277;7277.2722727726805;6876.828079298058;6879.071325371011;6941.47938904574;6946.097007389007;6980.860382792417;4746.881327966828;4954.455009948975;5000.974995125147;5001.57486613642;5081.72050537224;3907.5897030811357;4468.709533880287;5037.420696619935;5127.077126117186;5145.562627176582 23:14:58 INFO - PROCESS | 1664 | |4;prototype.html;293.244894396928;298.5327961739877;304.55991092870795;310.8622441862782;313.49370766212576;3871.322518559255;4204.852830150938;5033.5469807717345;5535.806246781363;5548.889022219559;4483.950913378901;4583.954160458395;5448.536874365674;5508.9724551377185;5530.806421775239;4203.096334288128;4235.322348424253;4240.918565765725;4258.041940563379;4259.531451540328;3867.588330259463;4098.73358231714;4195.8741237762915;4271;4288.378185163149;3920.4903362562827;4167.729097608661;4189.2668782962855;4192.622663960243;4202.768847713374;3460.4290292101773;4177.185448837478;4198.8320467181275;4227.682923780729;4229.767362795045;3649.3431182387158;3670.2659468106217;3670.5778835434;3678.6505281998166;3678.9448158277646;5289.44460831611;5341.225522299244;5349.786008559653;5375.838724838222;5395.541378982791;4025.81883815229;4804.894874178924;4826.710397376133;4831.782569784368;4851.248056551205;4841.661083724141;5850.707464626794;5885.529157667419;5906.9704651476895;5915.781581906785;3647.635236476331;3893.785841778701;3895.824687889052;3916.921661566753;3922.823472943696;6005.309389420229;6073.817785466443;6122.51019918401;6122.602030868024;6187;3921.431392448079;3923.1172986078473;3925.2738243424487;3948.0919388540797;3958.6041395860184;3075.3695492424;3951.881443556698;3963.9405408918888;4004.979975100106;4020.5175378954127;4670.813167473331;4678.742669153229;4692.343071969927;4710.670253082251;4728.692634978715;5110.207917772751;5356.678599284055;5649.237352957381;5921.970390148022;5925.614835035709;1148.104478506766;1227.533537255837;1266.8598261564666;1321.9801702974464;1369.7739872921056;661.9640262988407;777.5964384286304;794.4875555266821;823.4236034775681;847.7583888591722;3813.56144043433;3990.3216453203027;4082.795860207037;4197.580241975778;4577.336286238543;4460.130274596488;4501.707389019703;4740.620750339965;4748.243150034509;5133.230015497706;4064.878053658395;4218.957810421918;4599.195140850338;4622.491525932145;4624.670237429045;2655.8672066396603;2675.9563770129666;2859.6568411790718;3158.431482333156;3179.650238473766;5500.3949565547755;6026.487748541423;6264.279607845065;6276.937230627726;6279.340669229665;5350.411454739976;5696.832149409361;5735.541156707453;6027.578069535178;6138.631682099089;5205.947940520622;5389.730513474311;5409.377921538995;6188.504919606421;6221.346758590464;4669.879228985087;5289.841304760864;5657.4059723728415;6076;6107;5119.795208191631;5593.860153496033;5994.130851026664;6011.6635267427;6021;6260.186175797117;6270.457191686683;6308.1427300977075;6322.083297921684;6339;3312.801231926092;3839.7120215984473;3989.7207195496035;3991.8203680835013;4016.779077150785;5476.479734425223;5866.709323948725;6002.969985150046;6021.337652858095;6078.6048906820915;4903.926441103457;5622.437756224344;5663.782286808191;5824.475797178275;5933.970330148321;4620.699654522446;4900.754962251802;4929.9014019720125;5409.61820925052;5872.588918775645;4289.592488713567;4559.905622650606;5454.181872719124;5683.516901063456;5767.942320576741;5441.591880609049;5461.317335333083;5886;5895.29256489224;5941.405859414024;4629.430665980279;5036.798528058983;5043.915558155014;5748.936446757363;5798.420157984167 23:14:58 INFO - PROCESS | 1664 | |5;yui.html;632.5382470796319;695.9234484206736;729.9343059124681;731.8761744634554;764.8546776112538;13338.666533336647;15244.46644367446;15967.840321596766;15973.440929567467;16245.106519141442;13311.600651980456;13798.241096739675;14655.92672036641;14686.853131468682;14797.038192517479;661.576590981772;834.1700008491559;854.1287886355914;868.2706526517724;880.1858281089998;843.7300063979529;879.7448739865431;879.9032106468284;885.9557022148899;886.5833058462513;928.5960607135884;929.4423345992418;936.4849332866896;940.1397721085233;940.1679513630418;922.9492377919212;928.7631653928273;929.2705226397246;930.4789317981952;933.0110083311678;680.4556354916048;685.4790359326897;687.051868421575;690.823839920822;690.9481788865805;15138.243087845674;15174.089554626758;15191.696166076727;15193.164375959317;15208.08751474915;14301.713965720628;14321.928390358085;14372.778313843335;14432.278386080654;14600.34298456572;11813.940930295379;11900.702482437922;12022;12073.87926120745;12105.394730263539;3748.9437658435145;3777.7326672376803;3823.885283441501;3834.4631751554803;3931.115499012728;110468;115740;116044.41977790142;116507.41746291214;118361;11195.944020279847;11197.888021119847;11218.270812397168;11275.32348059119;11476.19666623345;19393.709094363596;23212.3036308911;23226;23260.767392326197;23267.76732232656;7678.884816727754;7821.843563128706;7912.0901096374655;7970.242826931432;7979.640916158787;3907;4242.936355954663;4348.173846969065;4429.693240494062;4540.977295113504;3573.12458447682;3657.4148136298595;3684.613115622875;3687.612800655946;3694.51971243742;648.4067078623101;649.0621052579072;654.5712558274338;671.3722669304119;673.1148539670318;546.4808431989626;562.2746656812748;640.5420124610926;651.8500744828716;658.940695337422;8847.73456796297;8978.281737461119;9128.81742365149;9439.961604223667;9633.951830240943;602.8154676061561;643.469137961184;653.8038588423454;664.1797380235405;676.8849295619757;4456.17560751262;4663.417072865892;4762.404699412574;4803.1582410352175;4825.179719447702;138801;141980;143484;151192.2440387791;156836;104603;104991.4750426243;106255.87488250191;139816;148120;66217.66891165513;84430.73353899573;101465;105864.47067764612;106275;8058.476199047043;8435.704750333709;8644.956775216084;9456.2907781918;9758.9753075926;51374.74312628413;58991.41008589859;60533;61962;62847.371526285984;73995;79633.80549291881;83068.58465707632;84015.15984840073;96536;12900.935495322463;13011.544595939096;13131.540396086091;13284.402201901132;13684.52104176349;12682.746345073232;14069.366878490287;14524.279609126865;14851.777223341442;14918.701625967637;11367.602133925273;11394.840883077783;12224.694382640506;13323.267220302589;13381.63647720175;11351.716207094889;13124.300783122964;13547.93226033903;13738.10702304347;14233.146011239358;13270.800937985745;13310.534131305358;13348.799768003293;13708.383122759698;13823.585292441243;11307.604233852106;11506.30962142274;11571.768564628828;11585.246958947642;11833.52665893389;8145.51126932386;9855.746441063397;10126;10393.324433911772;11228.93325134111 23:14:58 INFO - PROCESS | 1664 | __end_tp_report 23:14:58 INFO - PROCESS | 1664 | __start_cc_report 23:14:58 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,377 23:14:58 INFO - PROCESS | 1664 | __end_cc_report 23:14:58 INFO - PROCESS | 1664 | __startTimestamp1472969698540__endTimestamp 23:14:58 INFO - PROCESS | 1664 | 23:14:58 INFO - PROCESS | 1664 | ------- Summary: start ------- 23:14:58 INFO - PROCESS | 1664 | Number of tests: 6 23:14:58 INFO - PROCESS | 1664 | 23:14:58 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5642.12 Median:5810.48 stddev:703.53 (12.1%) stddev-sans-first:671.98 23:14:58 INFO - PROCESS | 1664 | Values: 2899.4 3307.1 3317.9 3328.6 3340.2 5038.0 5627.0 5655.4 5656.9 5683.2 5531.1 5636.3 5670.2 5694.2 5729.8 5016.0 5106.2 5169.6 5246.1 5284.1 4442.9 4500.4 5224.5 5316.6 5330.8 3949.8 4220.5 5092.2 5221.5 5226.9 5183.9 5192.7 5203.7 5217.9 5244.5 4181.5 5137.3 5139.3 5163.9 5186.0 5507.4 5511.3 5527.3 5621.3 5626.7 6063.5 6196.7 6209.2 6213.0 6230.6 6245.8 6262.5 6264.0 6274.3 6275.6 5633.0 5739.5 5795.9 5804.9 5810.5 6245.0 6253.0 6253.4 6255.3 6260.5 6156.4 6167.3 6173.6 6179.9 6181.8 6086.1 6110.0 6129.5 6168.3 6234.0 5053.8 6096.5 6140.6 6157.5 6220.9 6213.6 6216.7 6218.8 6234.5 6234.6 4923.1 4950.4 4976.6 4984.3 4989.4 4344.3 4373.2 4413.3 4419.0 4472.7 5615.1 5651.1 5698.9 5699.1 5706.2 6225.1 6226.9 6239.5 6243.3 6244.7 4960.3 5496.6 5508.8 5717.9 5761.6 5413.4 5432.1 5434.4 5446.5 5450.6 6254.9 6265.9 6269.0 6287.1 6300.8 6110.8 6149.7 6164.2 6210.4 6231.9 6229.6 6246.6 6277.4 6279.3 6282.2 6129.1 6143.5 6190.1 6198.0 6199.1 6037.5 6084.1 6127.3 6146.2 6217.6 6072.1 6089.3 6100.6 6110.8 6134.4 5064.8 6037.0 6075.5 6078.4 6079.2 4401.8 5713.7 5874.3 5916.9 6015.4 6075.6 6107.8 6113.2 6119.1 6134.9 4836.2 4907.8 5565.7 5629.7 5876.4 23:14:58 INFO - PROCESS | 1664 | 23:14:58 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:67462.36 Median:37950.84 stddev:155075.29 (408.6%) stddev-sans-first:155428.79 23:14:58 INFO - PROCESS | 1664 | Values: 488.7 507.3 527.9 535.2 569.9 29154.4 38937.4 39022.4 39097.4 39224.8 39475.6 39911.6 39955.8 40015.6 40026.8 9587.2 9782.3 9798.6 9811.6 9838.2 9618.8 9723.8 9727.0 9727.4 9760.4 9723.7 9731.9 9754.9 9765.0 9778.9 9612.9 9753.8 9756.6 9770.6 9772.3 9628.3 9717.4 9768.6 9796.3 9806.1 37846.8 37955.6 37969.0 38235.0 38298.2 3307.8 3389.7 3391.7 3407.1 3780.8 29836.6 30136.8 30690.0 30877.5 31304.0 3232.5 3281.2 3288.1 3386.9 3432.5 407907.0 475859.0 482735.0 483093.0 484033.0 32131.7 32778.5 55896.4 56090.2 56167.2 27663.3 30679.4 33782.3 34095.8 34218.3 14100.0 14228.9 14289.6 14298.1 14308.1 12039.1 12083.9 12167.9 12184.0 12199.8 6474.1 7560.9 7568.9 7595.4 7639.0 2607.6 3119.8 3168.4 3181.5 3193.3 871.6 884.7 933.4 950.8 1077.2 27344.9 29485.6 29660.4 29667.7 30304.5 926.1 959.6 961.0 1065.3 1094.3 13034.4 13665.7 14800.8 14925.0 15803.8 528965.0 548759.0 560615.2 656391.0 916638.0 56606.7 56693.1 57241.4 57241.7 57469.0 1337.1 1337.2 1367.4 1375.9 1377.6 24401.9 29687.1 30041.2 30318.8 30511.8 47162.5 47570.8 47653.0 47882.3 47922.5 583682.6 587564.0 591326.0 592671.0 593112.0 21910.6 25611.0 26101.6 26342.6 26424.0 34080.0 43592.0 44676.3 44942.8 45170.8 36937.4 36987.0 37361.4 37823.1 37946.1 36897.4 36936.0 37665.6 38017.6 38065.6 28845.9 35855.3 36183.3 37256.6 37272.8 28347.9 29463.7 30214.2 31256.0 33008.5 20136.5 24359.9 24787.4 25057.0 25201.6 23:14:58 INFO - PROCESS | 1664 | 23:14:58 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:33912.58 Median:11911.67 stddev:93674.89 (786.4%) stddev-sans-first:93903.71 23:14:58 INFO - PROCESS | 1664 | Values: 316.8 353.7 376.2 387.5 406.6 19507.9 20555.5 20799.0 21420.1 21552.1 20102.6 20499.1 20744.1 20993.5 21024.5 5334.6 5347.3 5362.9 5980.2 6226.2 5426.5 6131.6 6190.8 6230.5 6259.4 5802.5 6101.9 6136.4 6180.0 6204.1 5940.8 6022.3 6111.5 6142.2 6160.2 5895.6 5905.7 5956.9 5958.6 5964.3 13073.3 13532.7 13555.2 14768.4 20646.4 2115.6 2271.2 2334.6 2451.3 2851.6 10285.9 10631.3 10828.8 10925.2 16027.4 1833.9 1861.9 1868.7 1957.5 1984.1 105203.5 121413.0 127348.0 128096.7 128292.0 9811.7 11210.3 11313.5 11714.4 11715.6 14577.7 14699.9 14712.6 14720.7 14740.6 8236.0 8549.8 8604.3 8608.1 8662.3 8424.0 8452.7 8473.5 8555.4 8656.9 4481.2 4501.9 4568.2 4588.4 4644.3 1898.7 1944.6 1946.6 1948.0 1960.0 800.4 844.5 867.3 891.6 954.2 20347.4 21175.9 21178.7 21193.4 21222.2 898.4 916.2 929.8 942.9 971.4 10021.3 11191.3 11245.3 11276.6 11278.2 544553.0 551543.0 556652.0 567544.0 567974.0 115463.0 127995.4 129155.0 134571.0 137959.3 950.5 976.8 989.2 1009.2 1023.3 19255.0 19338.8 19703.4 19709.2 19795.0 51202.7 53026.5 53352.5 54435.7 55537.7 69948.7 80362.6 80514.0 81293.0 82734.2 6011.0 7324.4 7978.7 8027.3 8382.5 8972.3 10720.0 13075.7 13554.0 13995.7 11267.2 11389.0 11433.5 11661.4 11712.0 11110.3 11258.9 11501.0 11757.4 11793.5 11445.8 11625.2 11777.8 11819.3 11964.3 7056.3 7158.6 7554.3 7594.7 7753.1 10943.3 11314.8 11434.8 11492.3 11598.1 23:14:58 INFO - PROCESS | 1664 | 23:14:58 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5691.76 Median:4785.04 stddev:3210.71 (67.1%) stddev-sans-first:3204.45 23:14:58 INFO - PROCESS | 1664 | Values: 1524.3 1556.4 1767.5 1819.6 1884.5 7947.0 8461.1 8605.1 9032.4 9039.5 8673.6 8855.2 8881.6 8891.5 8893.2 2397.7 2408.8 2409.5 2412.6 2418.5 2355.5 2395.2 2399.8 2401.7 2403.1 2374.6 2379.5 2387.7 2391.9 2398.4 2350.6 2367.3 2376.7 2381.0 2381.8 2060.6 2134.8 2295.3 2297.9 2298.8 8765.4 8821.8 8932.1 8932.7 8945.6 5267.3 5430.5 5432.3 5445.6 5447.5 3140.8 3632.3 3653.0 3681.2 3714.7 934.5 1092.3 1099.3 1102.0 1103.3 11446.9 11457.3 11562.5 11578.8 11584.9 4561.2 4628.9 4803.1 4818.8 4831.9 9909.4 9993.3 9999.3 10003.0 10009.3 5411.0 5429.9 5433.8 5439.4 5443.6 4409.1 4767.0 4922.7 4923.8 4946.6 3864.7 3933.5 3997.0 4053.6 4053.9 1294.9 1305.8 1308.8 1317.8 1317.8 876.8 915.9 918.8 922.5 933.8 4735.3 5791.1 5872.0 5883.6 5919.4 416.6 418.9 427.0 430.1 440.3 3949.0 3961.0 4014.2 4058.1 4073.3 10253.9 10701.9 10722.9 10727.4 10840.0 10647.0 10658.7 10692.4 10710.4 10733.0 8122.3 8198.2 8201.7 8211.1 8224.7 5581.7 5615.0 5753.1 5769.9 5831.3 9994.1 10000.2 10048.5 10061.5 10088.5 9916.5 9972.2 10072.3 10198.1 10227.2 6863.5 7247.6 7413.0 7463.3 7558.5 8385.2 8438.1 8441.8 8471.8 8474.6 7236.7 7355.1 7364.2 7413.7 7514.3 7043.1 7197.5 7229.5 7247.7 7277.3 6876.8 6879.1 6941.5 6946.1 6980.9 4746.9 4954.5 5001.0 5001.6 5081.7 3907.6 4468.7 5037.4 5127.1 5145.6 23:14:58 INFO - PROCESS | 1664 | 23:14:58 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4559.65 Median:4829.25 stddev:1444.56 (29.9%) stddev-sans-first:1412.67 23:14:58 INFO - PROCESS | 1664 | Values: 293.2 298.5 304.6 310.9 313.5 3871.3 4204.9 5033.5 5535.8 5548.9 4484.0 4584.0 5448.5 5509.0 5530.8 4203.1 4235.3 4240.9 4258.0 4259.5 3867.6 4098.7 4195.9 4271.0 4288.4 3920.5 4167.7 4189.3 4192.6 4202.8 3460.4 4177.2 4198.8 4227.7 4229.8 3649.3 3670.3 3670.6 3678.7 3678.9 5289.4 5341.2 5349.8 5375.8 5395.5 4025.8 4804.9 4826.7 4831.8 4851.2 4841.7 5850.7 5885.5 5907.0 5915.8 3647.6 3893.8 3895.8 3916.9 3922.8 6005.3 6073.8 6122.5 6122.6 6187.0 3921.4 3923.1 3925.3 3948.1 3958.6 3075.4 3951.9 3963.9 4005.0 4020.5 4670.8 4678.7 4692.3 4710.7 4728.7 5110.2 5356.7 5649.2 5922.0 5925.6 1148.1 1227.5 1266.9 1322.0 1369.8 662.0 777.6 794.5 823.4 847.8 3813.6 3990.3 4082.8 4197.6 4577.3 4460.1 4501.7 4740.6 4748.2 5133.2 4064.9 4219.0 4599.2 4622.5 4624.7 2655.9 2676.0 2859.7 3158.4 3179.7 5500.4 6026.5 6264.3 6276.9 6279.3 5350.4 5696.8 5735.5 6027.6 6138.6 5205.9 5389.7 5409.4 6188.5 6221.3 4669.9 5289.8 5657.4 6076.0 6107.0 5119.8 5593.9 5994.1 6011.7 6021.0 6260.2 6270.5 6308.1 6322.1 6339.0 3312.8 3839.7 3989.7 3991.8 4016.8 5476.5 5866.7 6003.0 6021.3 6078.6 4903.9 5622.4 5663.8 5824.5 5934.0 4620.7 4900.8 4929.9 5409.6 5872.6 4289.6 4559.9 5454.2 5683.5 5767.9 5441.6 5461.3 5886.0 5895.3 5941.4 4629.4 5036.8 5043.9 5748.9 5798.4 23:14:58 INFO - PROCESS | 1664 | 23:14:58 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:23896.04 Median:3721.73 stddev:37997.39 (1021.0%) stddev-sans-first:38063.84 23:14:58 INFO - PROCESS | 1664 | Values: 632.5 695.9 729.9 731.9 764.9 13338.7 15244.5 15967.8 15973.4 16245.1 13311.6 13798.2 14655.9 14686.9 14797.0 661.6 834.2 854.1 868.3 880.2 843.7 879.7 879.9 886.0 886.6 928.6 929.4 936.5 940.1 940.2 922.9 928.8 929.3 930.5 933.0 680.5 685.5 687.1 690.8 690.9 15138.2 15174.1 15191.7 15193.2 15208.1 14301.7 14321.9 14372.8 14432.3 14600.3 11813.9 11900.7 12022.0 12073.9 12105.4 3748.9 3777.7 3823.9 3834.5 3931.1 110468.0 115740.0 116044.4 116507.4 118361.0 11195.9 11197.9 11218.3 11275.3 11476.2 19393.7 23212.3 23226.0 23260.8 23267.8 7678.9 7821.8 7912.1 7970.2 7979.6 3907.0 4242.9 4348.2 4429.7 4541.0 3573.1 3657.4 3684.6 3687.6 3694.5 648.4 649.1 654.6 671.4 673.1 546.5 562.3 640.5 651.9 658.9 8847.7 8978.3 9128.8 9440.0 9634.0 602.8 643.5 653.8 664.2 676.9 4456.2 4663.4 4762.4 4803.2 4825.2 138801.0 141980.0 143484.0 151192.2 156836.0 104603.0 104991.5 106255.9 139816.0 148120.0 66217.7 84430.7 101465.0 105864.5 106275.0 8058.5 8435.7 8645.0 9456.3 9759.0 51374.7 58991.4 60533.0 61962.0 62847.4 73995.0 79633.8 83068.6 84015.2 96536.0 12900.9 13011.5 13131.5 13284.4 13684.5 12682.7 14069.4 14524.3 14851.8 14918.7 11367.6 11394.8 12224.7 13323.3 13381.6 11351.7 13124.3 13547.9 13738.1 14233.1 13270.8 13310.5 13348.8 13708.4 13823.6 11307.6 11506.3 11571.8 11585.2 11833.5 8145.5 9855.7 10126.0 10393.3 11228.9 23:14:58 INFO - PROCESS | 1664 | -------- Summary: end -------- 23:14:58 INFO - PROCESS | 1664 | 23:14:59 INFO - TEST-INFO | 1664: exit 0 23:14:59 INFO - TEST-OK | dromaeo_css | took 1113262ms 23:14:59 INFO - TEST-START | kraken 23:14:59 INFO - operating with platform_type : mac_ 23:14:59 INFO - Initialising browser for kraken test... 23:14:59 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/tmpb2qDFR/profile http://localhost:49271/getInfo.html) 23:15:01 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 23:15:01 INFO - PROCESS | 1673 | colorDepth:24 23:15:01 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 23:15:01 INFO - PROCESS | 1673 | __metrics 23:15:02 ERROR - PROCESS | 1673 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 23:15:02 INFO - TEST-INFO | 1673: exit 0 23:15:02 INFO - Browser initialized. 23:15:02 INFO - Running cycle 1/1 for kraken test... 23:15:02 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/tmpb2qDFR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:15:14 INFO - PROCESS | 1675 | RSS: Main: 245157888 23:15:14 INFO - PROCESS | 1675 | 23:15:17 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 23:15:18 INFO - PROCESS | 1675 | RSS: Main: 257945600 23:15:18 INFO - PROCESS | 1675 | 23:15:20 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 23:15:21 INFO - PROCESS | 1675 | RSS: Main: 266645504 23:15:21 INFO - PROCESS | 1675 | 23:15:24 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 23:15:25 INFO - PROCESS | 1675 | RSS: Main: 262131712 23:15:25 INFO - PROCESS | 1675 | 23:15:27 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 23:15:28 INFO - PROCESS | 1675 | RSS: Main: 263700480 23:15:28 INFO - PROCESS | 1675 | 23:15:30 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:15:30 INFO - PROCESS | 1675 | RSS: Main: 263905280 23:15:30 INFO - PROCESS | 1675 | 23:15:33 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 23:15:34 INFO - PROCESS | 1675 | RSS: Main: 265949184 23:15:34 INFO - PROCESS | 1675 | 23:15:37 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 23:15:37 INFO - PROCESS | 1675 | RSS: Main: 266051584 23:15:37 INFO - PROCESS | 1675 | 23:15:41 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 23:15:41 INFO - PROCESS | 1675 | RSS: Main: 266289152 23:15:41 INFO - PROCESS | 1675 | 23:15:43 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:15:43 INFO - PROCESS | 1675 | RSS: Main: 265654272 23:15:43 INFO - PROCESS | 1675 | 23:15:45 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:15:46 INFO - PROCESS | 1675 | RSS: Main: 265707520 23:15:46 INFO - PROCESS | 1675 | 23:15:48 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:15:49 INFO - PROCESS | 1675 | RSS: Main: 265859072 23:15:49 INFO - PROCESS | 1675 | 23:15:52 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:15:52 INFO - PROCESS | 1675 | RSS: Main: 265887744 23:15:52 INFO - PROCESS | 1675 | 23:15:55 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:15:56 INFO - PROCESS | 1675 | RSS: Main: 268288000 23:15:56 INFO - PROCESS | 1675 | 23:15:58 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 23:15:58 INFO - PROCESS | 1675 | RSS: Main: 270098432 23:15:58 INFO - PROCESS | 1675 | 23:15:58 INFO - PROCESS | 1675 | __start_tp_report 23:15:58 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 23:15:58 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 23:15:58 INFO - PROCESS | 1675 | |i|pagename|runs| 23:15:58 INFO - PROCESS | 1675 | |0;ai-astar;119;109;120;116;122;102;119;118;117;117 23:15:58 INFO - PROCESS | 1675 | |1;audio-beat-detection;142;112;134;159;110;131;131;141;200;109 23:15:58 INFO - PROCESS | 1675 | |2;audio-dft;195;198;209;229;275;204;195;197;193;196 23:15:58 INFO - PROCESS | 1675 | |3;audio-fft;72;83;75;147;54;64;58;62;133;61 23:15:58 INFO - PROCESS | 1675 | |4;audio-oscillator;69;71;117;71;70;76;112;67;68;113 23:15:58 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;89;87;89;86;90;85;90;88;89;85 23:15:58 INFO - PROCESS | 1675 | |6;imaging-darkroom;99;101;111;132;99;98;121;171;117;99 23:15:58 INFO - PROCESS | 1675 | |7;imaging-desaturate;149;91;127;135;101;143;143;107;92;91 23:15:58 INFO - PROCESS | 1675 | |8;json-parse-financial;53;56;54;65;52;57;57;56;56;56 23:15:58 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;66;69;68;71;77;77;75;75;69;72 23:15:58 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;99;83;84;84;93;74;89;79;100;100 23:15:58 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;142;148;151;149;157;166;155;144;181;142 23:15:58 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;179;181;176;179;181;175;187;178;180;180 23:15:58 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;64;93;96;69;68;68;70;74;62;69 23:15:58 INFO - PROCESS | 1675 | __end_tp_report 23:15:58 INFO - PROCESS | 1675 | __start_cc_report 23:15:58 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,953 23:15:58 INFO - PROCESS | 1675 | __end_cc_report 23:15:58 INFO - PROCESS | 1675 | __startTimestamp1472969758520__endTimestamp 23:15:58 INFO - PROCESS | 1675 | 23:15:58 INFO - PROCESS | 1675 | ------- Summary: start ------- 23:15:58 INFO - PROCESS | 1675 | Number of tests: 14 23:15:58 INFO - PROCESS | 1675 | 23:15:58 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:115.90 Median:118.50 stddev:5.97 (5.0%) stddev-sans-first:6.23 23:15:58 INFO - PROCESS | 1675 | Values: 119.0 109.0 120.0 116.0 122.0 102.0 119.0 118.0 117.0 117.0 23:15:58 INFO - PROCESS | 1675 | 23:15:58 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:136.90 Median:137.50 stddev:27.31 (19.9%) stddev-sans-first:28.91 23:15:58 INFO - PROCESS | 1675 | Values: 142.0 112.0 134.0 159.0 110.0 131.0 131.0 141.0 200.0 109.0 23:15:58 INFO - PROCESS | 1675 | 23:15:58 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:209.10 Median:201.00 stddev:25.52 (12.7%) stddev-sans-first:26.56 23:15:58 INFO - PROCESS | 1675 | Values: 195.0 198.0 209.0 229.0 275.0 204.0 195.0 197.0 193.0 196.0 23:15:58 INFO - PROCESS | 1675 | 23:15:58 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:80.90 Median:63.00 stddev:32.47 (51.5%) stddev-sans-first:34.28 23:15:58 INFO - PROCESS | 1675 | Values: 72.0 83.0 75.0 147.0 54.0 64.0 58.0 62.0 133.0 61.0 23:15:58 INFO - PROCESS | 1675 | 23:15:58 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:83.40 Median:69.50 stddev:21.29 (30.6%) stddev-sans-first:21.93 23:15:58 INFO - PROCESS | 1675 | Values: 69.0 71.0 117.0 71.0 70.0 76.0 112.0 67.0 68.0 113.0 23:15:58 INFO - PROCESS | 1675 | 23:15:58 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:87.80 Median:89.00 stddev:1.93 (2.2%) stddev-sans-first:2.00 23:15:58 INFO - PROCESS | 1675 | Values: 89.0 87.0 89.0 86.0 90.0 85.0 90.0 88.0 89.0 85.0 23:15:58 INFO - PROCESS | 1675 | 23:15:58 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:114.80 Median:134.50 stddev:22.93 (17.1%) stddev-sans-first:23.60 23:15:58 INFO - PROCESS | 1675 | Values: 99.0 101.0 111.0 132.0 99.0 98.0 121.0 171.0 117.0 99.0 23:15:58 INFO - PROCESS | 1675 | 23:15:58 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:117.90 Median:146.00 stddev:23.86 (16.3%) stddev-sans-first:22.50 23:15:58 INFO - PROCESS | 1675 | Values: 149.0 91.0 127.0 135.0 101.0 143.0 143.0 107.0 92.0 91.0 23:15:58 INFO - PROCESS | 1675 | 23:15:58 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:56.00 stddev:3.52 (6.3%) stddev-sans-first:3.54 23:15:58 INFO - PROCESS | 1675 | Values: 53.0 56.0 54.0 65.0 52.0 57.0 57.0 56.0 56.0 56.0 23:15:58 INFO - PROCESS | 1675 | 23:15:58 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:73.50 stddev:3.93 (5.3%) stddev-sans-first:3.54 23:15:58 INFO - PROCESS | 1675 | Values: 66.0 69.0 68.0 71.0 77.0 77.0 75.0 75.0 69.0 72.0 23:15:58 INFO - PROCESS | 1675 | 23:15:58 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:88.50 Median:84.00 stddev:9.23 (11.0%) stddev-sans-first:8.97 23:15:58 INFO - PROCESS | 1675 | Values: 99.0 83.0 84.0 84.0 93.0 74.0 89.0 79.0 100.0 100.0 23:15:58 INFO - PROCESS | 1675 | 23:15:58 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:153.50 Median:153.00 stddev:12.20 (8.0%) stddev-sans-first:12.20 23:15:58 INFO - PROCESS | 1675 | Values: 142.0 148.0 151.0 149.0 157.0 166.0 155.0 144.0 181.0 142.0 23:15:58 INFO - PROCESS | 1675 | 23:15:58 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.60 Median:180.00 stddev:3.27 (1.8%) stddev-sans-first:3.46 23:15:58 INFO - PROCESS | 1675 | Values: 179.0 181.0 176.0 179.0 181.0 175.0 187.0 178.0 180.0 180.0 23:15:58 INFO - PROCESS | 1675 | 23:15:58 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.30 Median:69.50 stddev:11.65 (16.8%) stddev-sans-first:11.86 23:15:58 INFO - PROCESS | 1675 | Values: 64.0 93.0 96.0 69.0 68.0 68.0 70.0 74.0 62.0 69.0 23:15:58 INFO - PROCESS | 1675 | -------- Summary: end -------- 23:15:58 INFO - PROCESS | 1675 | 23:15:59 INFO - TEST-INFO | 1675: exit 0 23:15:59 INFO - TEST-OK | kraken | took 60063ms 23:15:59 INFO - SUITE-END | took 1173s 23:15:59 INFO - Completed test suite (00:19:33) 23:15:59 INFO - javascript benchmark 23:15:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7173.778672654005, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2899.4346102510012, 3307.0740192746025, 3317.8838740644082, 3328.550645662835, 3340.215049463377, 5038.0, 5626.959012582675, 5655.377908430075, 5656.886862262752, 5683.204351390808, 5531.1426728856995, 5636.2954630671165, 5670.206171136044, 5694.2312787773635, 5729.799457019005, 5016.046951079302, 5106.234064890259, 5169.638125331228, 5246.108161612516, 5284.075286824809, 4442.8667139985855, 4500.369948207253, 5224.451432599561, 5316.574674026068, 5330.786768529254, 3949.842006319758, 4220.472440944882, 5092.185250359925, 5221.477852214786, 5226.8693282667855, 5183.870403239949, 5192.71440070796, 5203.713795741232, 5217.947820521784, 5244.501772331622, 4181.519125300599, 5137.280780690707, 5139.306193663845, 5163.896722065575, 5185.974070129663, 5507.366652834935, 5511.338639363262, 5527.281453411071, 5621.297337832771, 5626.662400255997, 6063.501542749097, 6196.690165491707, 6209.192804935329, 6213.0, 6230.5638605297645, 6245.7501699931945, 6262.530310226751, 6263.96868015657, 6274.309825919146, 6275.61936373997, 5633.0, 5739.540836733051, 5795.913061304083, 5804.9419505805245, 5810.477057064884, 6245.000799871998, 6253.0, 6253.37466253371, 6255.311915689271, 6260.499160067184, 6156.415140561639, 6167.321594624587, 6173.629582225081, 6179.907301390483, 6181.783637572664, 6086.056661217518, 6109.9816581254145, 6129.540284478593, 6168.290646575611, 6234.0, 5053.787091098184, 6096.496448835484, 6140.596275277727, 6157.507399408037, 6220.937790622126, 6213.596116252429, 6216.658083805343, 6218.782342617987, 6234.501239900797, 6234.625922444668, 4923.113839508851, 4950.381202349707, 4976.576990955809, 4984.252362145708, 4989.351384320015, 4344.261475549164, 4373.234683930299, 4413.293872980372, 4418.977905110454, 4472.731636101845, 5615.129654903497, 5651.095824668033, 5698.886022279531, 5699.116636921284, 5706.201131841545, 6225.097360882647, 6226.910290699109, 6239.469645080219, 6243.313235544178, 6244.718987645567, 4960.305557221992, 5496.587755918322, 5508.779648814083, 5717.8564287142735, 5761.567882408836, 5413.404525502192, 5432.130859062531, 5434.375046869582, 5446.537044351196, 5450.645708028966, 6254.874902501924, 6265.937340626535, 6269.028300613412, 6287.056941458818, 6300.8424789379715, 6110.816675499742, 6149.723262453111, 6164.229471316085, 6210.441060304594, 6231.937680623317, 6229.626222426669, 6246.562740608296, 6277.372262773686, 6279.340669229665, 6282.246130464191, 6129.141920131096, 6143.477804386677, 6190.102435146969, 6198.0, 6199.132121502903, 6037.547183961133, 6084.056971169476, 6127.250613123855, 6146.200993870768, 6217.626942383291, 6072.05883088122, 6089.33017368098, 6100.645212681175, 6110.7555697771595, 6134.447899689049, 5064.80976970419, 6036.9698151508965, 6075.483583895419, 6078.361772014052, 6079.240094988127, 4401.757903315349, 5713.685747283939, 5874.324452687824, 5916.852078698067, 6015.428534289411, 6075.605085669417, 6107.755689772359, 6113.23584551931, 6119.082137679384, 6134.877302453837, 4836.226203807374, 4907.803687852446, 5565.664240582312, 5629.746661400329, 5876.41235876409], "value": 5596.092703473052, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.72142878559214, 507.32019094413477, 527.8733104055027, 535.213236542283, 569.8717788497586, 29154.41691166178, 38937.4159387609, 39022.41466378003, 39097.41353879694, 39224.80387598058, 39475.60524394755, 39911.60088399115, 39955.80022099893, 40015.599844001554, 40026.79919602416, 9587.233021358292, 9782.26633002524, 9798.559064842064, 9811.558479868423, 9838.212942964581, 9618.807623847519, 9723.756906077333, 9726.9513652432, 9727.367721098106, 9760.365576237557, 9723.659671911484, 9731.854022189673, 9754.902450975505, 9765.0, 9778.90221097787, 9612.89451713055, 9753.804923901553, 9756.609735610567, 9770.609175632966, 9772.31593788435, 9628.3260171788, 9717.368371055858, 9768.609255629766, 9796.26528010395, 9806.117449429514, 37846.81076594627, 37955.620443795764, 37969.0, 38235.0, 38298.23403531914, 3307.834608269577, 3389.694927456541, 3391.6608339166132, 3407.1141503209096, 3780.7542509736777, 29836.55245171324, 30136.8493157535, 30690.0, 30877.536836947464, 31304.0, 3232.4504834178247, 3281.212508997847, 3288.112209703391, 3386.932261354784, 3432.5194472773833, 407906.96046519873, 475859.0, 482735.0, 483093.0, 484033.0, 32131.678683212867, 32778.508322375186, 55896.44103558994, 56090.158647620316, 56167.15749263764, 27663.34019958809, 30679.38641227163, 33782.324353513286, 34095.82952085224, 34218.31563368718, 14100.0, 14228.86169106469, 14289.571312860422, 14298.070625409315, 14308.069975451563, 12039.097067719957, 12083.879161208451, 12167.939160304142, 12184.0, 12199.756004879853, 6474.12599299098, 7560.924390756132, 7568.924310756822, 7595.430342724208, 7639.0, 2607.5958226474927, 3119.750419966397, 3168.445522033681, 3181.538676891884, 3193.2655489237604, 871.5772850167664, 884.7390019944132, 933.3653115881131, 950.7528042708946, 1077.2028698763015, 27344.863275683892, 29485.557716634266, 29660.406791864472, 29667.703322966496, 30304.54543181854, 926.073926073926, 959.5921733263335, 961.0149596663476, 1065.2756125834353, 1094.2723089145788, 13034.41345139471, 13665.701758332942, 14800.777988330183, 14925.0, 15803.757620506105, 528965.0, 548759.0, 560615.1969240127, 656391.0, 916638.0, 56606.716966414904, 56693.14960275599, 57241.427585723606, 57241.71379143161, 57469.0, 1337.1175024483794, 1337.1509091726823, 1367.446184295362, 1375.9036867419384, 1377.6211541825878, 24401.877990609933, 29687.13616884226, 30041.248968775955, 30318.84840575783, 30511.847440762653, 47162.52837471581, 47570.762146190435, 47653.0, 47882.281765772845, 47922.5207747932, 583682.6317368229, 587564.0, 591326.0, 592671.0, 593112.0, 21910.56178876382, 25611.0, 26101.608475873258, 26342.604860926716, 26424.0, 34079.97691921199, 43592.0, 44676.32985505155, 44942.77528612336, 45170.77414612906, 36937.44593831041, 36987.0, 37361.4395784058, 37823.05442363963, 37946.0513487165, 36897.44653830141, 36936.0, 37665.62334376621, 38017.61982380141, 38065.61934380621, 28845.855770721013, 35855.28289434249, 36183.27633447263, 37256.62743372531, 37272.81363593165, 28347.858260708563, 29463.705362946097, 30214.2446438832, 31256.0, 33008.50487242645, 20136.49658758543, 24359.903804328493, 24787.380315492257, 25057.0, 25201.621975670747], "value": 18001.470542830775, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.77825522134503, 353.70896228765, 376.1536542778748, 387.50980010286975, 406.6096547314578, 19507.927064011474, 20555.486112847168, 20799.0, 21420.14319427223, 21552.137914483403, 20102.59794804103, 20499.07754151063, 20744.066517006733, 20993.47516312093, 21024.474388140305, 5334.5732341412595, 5347.25138480613, 5362.865928351784, 5980.162777211194, 6226.190595222614, 5426.538744206747, 6131.601445906002, 6190.78332258371, 6230.470410015154, 6259.405356491126, 5802.53579713624, 6101.877962440749, 6136.417040381157, 6180.042093475518, 6204.100405441231, 5940.79207227745, 6022.307434645006, 6111.54163437744, 6142.232220972401, 6160.229971253593, 5895.616784908967, 5905.704714764244, 5956.880862382728, 5958.553108516835, 5964.343922168558, 13073.346332683328, 13532.729345413036, 13555.186688798605, 14768.409263629443, 20646.380608581767, 2115.5557332960097, 2271.205078222609, 2334.5914464968732, 2451.2891261534132, 2851.586519954595, 10285.948570257176, 10631.308964917256, 10828.783424331548, 10925.235233533656, 16027.439039633557, 1833.8716289859715, 1861.8789778664343, 1868.7103498957683, 1957.5399781051442, 1984.107151781704, 105203.47398263036, 121413.0, 127348.0, 128096.71903281035, 128292.0, 9811.675423817693, 11210.327380357205, 11313.490892909838, 11714.414279286002, 11715.589954351557, 14577.708445831237, 14699.853001470063, 14712.55862324132, 14720.705585888223, 14740.631484212767, 8236.0, 8549.786255343666, 8604.289648760621, 8608.09614990417, 8662.2637075848, 8424.031236407769, 8452.661893524313, 8473.533955632376, 8555.443896146731, 8656.8701469478, 4481.1933851906315, 4501.909961800746, 4568.223402021665, 4588.403507543998, 4644.2801365788355, 1898.7057006164066, 1944.562473443492, 1946.562023544691, 1947.9805201948082, 1960.0, 800.4236949396425, 844.4511067805853, 867.3061550759368, 891.5631340643038, 954.188939401517, 20347.389578312675, 21175.894120529607, 21178.682319765216, 21193.36419907405, 21222.151113955268, 898.3890954151109, 916.2303664921498, 929.8140371925642, 942.853857652065, 971.3928794503436, 10021.348612340174, 11191.32852028881, 11245.269057511236, 11276.605318813803, 11278.210525263154, 544553.0, 551543.0, 556652.0, 567544.0, 567974.0, 115463.0, 127995.36002319929, 129155.0, 134571.0, 137959.31020344835, 950.5294879034922, 976.7655762616921, 989.1938070472686, 1009.1724785675822, 1023.2683631203726, 19255.0, 19338.806611934262, 19703.40889773309, 19709.211631534577, 19795.0, 51202.74398627983, 53026.469735302155, 53352.46647533475, 54435.72782136064, 55537.722311388185, 69948.65025674838, 80362.5981870087, 80514.0, 81293.0, 82734.17265827506, 6011.0, 7324.414046876131, 7978.680852766057, 8027.317677997202, 8382.455140415854, 8972.327075469497, 10719.98160174782, 13075.692430756848, 13554.0, 13995.720085598028, 11267.154963377943, 11389.0, 11433.485493152662, 11661.358625275352, 11712.004479619327, 11110.277831940897, 11258.943705281421, 11501.022413094646, 11757.412129393326, 11793.52825886955, 11445.771084578428, 11625.18623696367, 11777.823332649845, 11819.349935753617, 11964.341961191869, 7056.259092795388, 7158.570485770871, 7554.2823431773895, 7594.658240379085, 7753.147153812964, 10943.28868623537, 11314.83027754568, 11434.770060607412, 11492.310461372344, 11598.072154227473], "value": 9508.82255759626, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1524.3140586735967, 1556.3852278350053, 1767.4609244180529, 1819.590592116773, 1884.4535084825402, 7947.0, 8461.111583283751, 8605.139486051392, 9032.367734258605, 9039.548022598869, 8673.568861137921, 8855.247303979153, 8881.555922203881, 8891.510966896818, 8893.199612034916, 2397.736249012607, 2408.771166739166, 2409.5301416223842, 2412.5657381671294, 2418.51629674065, 2355.5406695694346, 2395.161693407302, 2399.8080153587757, 2401.6997875265592, 2403.134871446278, 2374.608189648706, 2379.524095180962, 2387.6657267982496, 2391.8923648435775, 2398.4243781492405, 2350.647402889563, 2367.2779802160335, 2376.66726658268, 2381.0356455923848, 2381.8451800633075, 2060.6393881070753, 2134.8185404240658, 2295.3228797504607, 2297.885105744706, 2298.7816157465013, 8765.430247033923, 8821.779455513599, 8932.106789321017, 8932.732018039469, 8945.64217431302, 5267.3152490176035, 5430.5384042356445, 5432.320959880015, 5445.618806683534, 5447.4824891635235, 3140.8272545010013, 3632.2553876455263, 3652.9589067116012, 3681.245344704356, 3714.7213958953184, 934.5233930695326, 1092.2736380307085, 1099.3458891959272, 1102.0, 1103.2885916001585, 11446.885531144666, 11457.25527840696, 11562.53749850005, 11578.826317605242, 11584.942075289653, 4561.156186105581, 4628.861134165981, 4803.087413391514, 4818.759062046884, 4831.855044348676, 9909.35589186715, 9993.300468967247, 9999.3000489965, 10003.0, 10009.349392289478, 5411.0, 5429.918551221735, 5433.755481003364, 5439.4288599696465, 5443.564514838803, 4409.14021765759, 4767.0, 4922.655414120977, 4923.75381230937, 4946.604271658331, 3864.7101467390057, 3933.4886464759397, 3996.9600303997167, 4053.61490658387, 4053.8783836484954, 1294.9417276222594, 1305.8498272698762, 1308.8036794480713, 1317.8220940173146, 1317.8418589769096, 876.7720392697817, 915.9084091590788, 918.8468472067512, 922.5479515037583, 933.8225737109927, 4735.313379559944, 5791.102379131242, 5872.0, 5883.588148829626, 5919.378465261172, 416.5792549524941, 418.9036521600049, 426.95730426957056, 430.0860671073968, 440.2713509142375, 3948.980255098706, 3961.0, 4014.217227640582, 4058.0869304406274, 4073.327900896378, 10253.94873025645, 10701.946490267655, 10722.946385268024, 10727.356358618352, 10840.0, 10647.041766241075, 10658.733531661615, 10692.411917344465, 10710.357378557312, 10733.0, 8122.268995790408, 8198.180181981874, 8201.71294004719, 8211.05572859117, 8224.712135075364, 5581.665100094007, 5615.0, 5753.1370294456165, 5769.94230057694, 5831.271091113611, 9994.100530951959, 10000.24998125129, 10048.547815348473, 10061.547230374505, 10088.54601542947, 9916.504174791087, 9972.152367048882, 10072.345297555636, 10198.133158681596, 10227.232957528066, 6863.519553631198, 7247.601381924045, 7413.0, 7463.2536746327105, 7558.546487210785, 8385.161483851565, 8438.071812100787, 8441.788955276168, 8471.788205294917, 8474.618642160995, 7236.746713864989, 7355.117385913724, 7364.153122390993, 7413.666385012795, 7514.323710866049, 7043.119610048744, 7197.496175267681, 7229.457790665826, 7247.710091596277, 7277.2722727726805, 6876.828079298058, 6879.071325371011, 6941.47938904574, 6946.097007389007, 6980.860382792417, 4746.881327966828, 4954.455009948975, 5000.974995125147, 5001.57486613642, 5081.72050537224, 3907.5897030811357, 4468.709533880287, 5037.420696619935, 5127.077126117186, 5145.562627176582], "value": 4497.82577812556, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.244894396928, 298.5327961739877, 304.55991092870795, 310.8622441862782, 313.49370766212576, 3871.322518559255, 4204.852830150938, 5033.5469807717345, 5535.806246781363, 5548.889022219559, 4483.950913378901, 4583.954160458395, 5448.536874365674, 5508.9724551377185, 5530.806421775239, 4203.096334288128, 4235.322348424253, 4240.918565765725, 4258.041940563379, 4259.531451540328, 3867.588330259463, 4098.73358231714, 4195.8741237762915, 4271.0, 4288.378185163149, 3920.4903362562827, 4167.729097608661, 4189.2668782962855, 4192.622663960243, 4202.768847713374, 3460.4290292101773, 4177.185448837478, 4198.8320467181275, 4227.682923780729, 4229.767362795045, 3649.3431182387158, 3670.2659468106217, 3670.5778835434, 3678.6505281998166, 3678.9448158277646, 5289.44460831611, 5341.225522299244, 5349.786008559653, 5375.838724838222, 5395.541378982791, 4025.81883815229, 4804.894874178924, 4826.710397376133, 4831.782569784368, 4851.248056551205, 4841.661083724141, 5850.707464626794, 5885.529157667419, 5906.9704651476895, 5915.781581906785, 3647.635236476331, 3893.785841778701, 3895.824687889052, 3916.921661566753, 3922.823472943696, 6005.309389420229, 6073.817785466443, 6122.51019918401, 6122.602030868024, 6187.0, 3921.431392448079, 3923.1172986078473, 3925.2738243424487, 3948.0919388540797, 3958.6041395860184, 3075.3695492424, 3951.881443556698, 3963.9405408918888, 4004.979975100106, 4020.5175378954127, 4670.813167473331, 4678.742669153229, 4692.343071969927, 4710.670253082251, 4728.692634978715, 5110.207917772751, 5356.678599284055, 5649.237352957381, 5921.970390148022, 5925.614835035709, 1148.104478506766, 1227.533537255837, 1266.8598261564666, 1321.9801702974464, 1369.7739872921056, 661.9640262988407, 777.5964384286304, 794.4875555266821, 823.4236034775681, 847.7583888591722, 3813.56144043433, 3990.3216453203027, 4082.795860207037, 4197.580241975778, 4577.336286238543, 4460.130274596488, 4501.707389019703, 4740.620750339965, 4748.243150034509, 5133.230015497706, 4064.878053658395, 4218.957810421918, 4599.195140850338, 4622.491525932145, 4624.670237429045, 2655.8672066396603, 2675.9563770129666, 2859.6568411790718, 3158.431482333156, 3179.650238473766, 5500.3949565547755, 6026.487748541423, 6264.279607845065, 6276.937230627726, 6279.340669229665, 5350.411454739976, 5696.832149409361, 5735.541156707453, 6027.578069535178, 6138.631682099089, 5205.947940520622, 5389.730513474311, 5409.377921538995, 6188.504919606421, 6221.346758590464, 4669.879228985087, 5289.841304760864, 5657.4059723728415, 6076.0, 6107.0, 5119.795208191631, 5593.860153496033, 5994.130851026664, 6011.6635267427, 6021.0, 6260.186175797117, 6270.457191686683, 6308.1427300977075, 6322.083297921684, 6339.0, 3312.801231926092, 3839.7120215984473, 3989.7207195496035, 3991.8203680835013, 4016.779077150785, 5476.479734425223, 5866.709323948725, 6002.969985150046, 6021.337652858095, 6078.6048906820915, 4903.926441103457, 5622.437756224344, 5663.782286808191, 5824.475797178275, 5933.970330148321, 4620.699654522446, 4900.754962251802, 4929.9014019720125, 5409.61820925052, 5872.588918775645, 4289.592488713567, 4559.905622650606, 5454.181872719124, 5683.516901063456, 5767.942320576741, 5441.591880609049, 5461.317335333083, 5886.0, 5895.29256489224, 5941.405859414024, 4629.430665980279, 5036.798528058983, 5043.915558155014, 5748.936446757363, 5798.420157984167], "value": 4092.3589368558246, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [632.5382470796319, 695.9234484206736, 729.9343059124681, 731.8761744634554, 764.8546776112538, 13338.666533336647, 15244.46644367446, 15967.840321596766, 15973.440929567467, 16245.106519141442, 13311.600651980456, 13798.241096739675, 14655.92672036641, 14686.853131468682, 14797.038192517479, 661.576590981772, 834.1700008491559, 854.1287886355914, 868.2706526517724, 880.1858281089998, 843.7300063979529, 879.7448739865431, 879.9032106468284, 885.9557022148899, 886.5833058462513, 928.5960607135884, 929.4423345992418, 936.4849332866896, 940.1397721085233, 940.1679513630418, 922.9492377919212, 928.7631653928273, 929.2705226397246, 930.4789317981952, 933.0110083311678, 680.4556354916048, 685.4790359326897, 687.051868421575, 690.823839920822, 690.9481788865805, 15138.243087845674, 15174.089554626758, 15191.696166076727, 15193.164375959317, 15208.08751474915, 14301.713965720628, 14321.928390358085, 14372.778313843335, 14432.278386080654, 14600.34298456572, 11813.940930295379, 11900.702482437922, 12022.0, 12073.87926120745, 12105.394730263539, 3748.9437658435145, 3777.7326672376803, 3823.885283441501, 3834.4631751554803, 3931.115499012728, 110468.0, 115740.0, 116044.41977790142, 116507.41746291214, 118361.0, 11195.944020279847, 11197.888021119847, 11218.270812397168, 11275.32348059119, 11476.19666623345, 19393.709094363596, 23212.3036308911, 23226.0, 23260.767392326197, 23267.76732232656, 7678.884816727754, 7821.843563128706, 7912.0901096374655, 7970.242826931432, 7979.640916158787, 3907.0, 4242.936355954663, 4348.173846969065, 4429.693240494062, 4540.977295113504, 3573.12458447682, 3657.4148136298595, 3684.613115622875, 3687.612800655946, 3694.51971243742, 648.4067078623101, 649.0621052579072, 654.5712558274338, 671.3722669304119, 673.1148539670318, 546.4808431989626, 562.2746656812748, 640.5420124610926, 651.8500744828716, 658.940695337422, 8847.73456796297, 8978.281737461119, 9128.81742365149, 9439.961604223667, 9633.951830240943, 602.8154676061561, 643.469137961184, 653.8038588423454, 664.1797380235405, 676.8849295619757, 4456.17560751262, 4663.417072865892, 4762.404699412574, 4803.1582410352175, 4825.179719447702, 138801.0, 141980.0, 143484.0, 151192.2440387791, 156836.0, 104603.0, 104991.4750426243, 106255.87488250191, 139816.0, 148120.0, 66217.66891165513, 84430.73353899573, 101465.0, 105864.47067764612, 106275.0, 8058.476199047043, 8435.704750333709, 8644.956775216084, 9456.2907781918, 9758.9753075926, 51374.74312628413, 58991.41008589859, 60533.0, 61962.0, 62847.371526285984, 73995.0, 79633.80549291881, 83068.58465707632, 84015.15984840073, 96536.0, 12900.935495322463, 13011.544595939096, 13131.540396086091, 13284.402201901132, 13684.52104176349, 12682.746345073232, 14069.366878490287, 14524.279609126865, 14851.777223341442, 14918.701625967637, 11367.602133925273, 11394.840883077783, 12224.694382640506, 13323.267220302589, 13381.63647720175, 11351.716207094889, 13124.300783122964, 13547.93226033903, 13738.10702304347, 14233.146011239358, 13270.800937985745, 13310.534131305358, 13348.799768003293, 13708.383122759698, 13823.585292441243, 11307.604233852106, 11506.30962142274, 11571.768564628828, 11585.246958947642, 11833.52665893389, 8145.51126932386, 9855.746441063397, 10126.0, 10393.324433911772, 11228.93325134111], "value": 7729.454438827672, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1569.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 109.0, 120.0, 116.0, 122.0, 102.0, 119.0, 118.0, 117.0, 117.0], "value": 115.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 112.0, 134.0, 159.0, 110.0, 131.0, 131.0, 141.0, 200.0, 109.0], "value": 136.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 198.0, 209.0, 229.0, 275.0, 204.0, 195.0, 197.0, 193.0, 196.0], "value": 209.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 83.0, 75.0, 147.0, 54.0, 64.0, 58.0, 62.0, 133.0, 61.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 117.0, 71.0, 70.0, 76.0, 112.0, 67.0, 68.0, 113.0], "value": 83.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 89.0, 86.0, 90.0, 85.0, 90.0, 88.0, 89.0, 85.0], "value": 87.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 101.0, 111.0, 132.0, 99.0, 98.0, 121.0, 171.0, 117.0, 99.0], "value": 114.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 91.0, 127.0, 135.0, 101.0, 143.0, 143.0, 107.0, 92.0, 91.0], "value": 117.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 56.0, 54.0, 65.0, 52.0, 57.0, 57.0, 56.0, 56.0, 56.0], "value": 56.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 68.0, 71.0, 77.0, 77.0, 75.0, 75.0, 69.0, 72.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 83.0, 84.0, 84.0, 93.0, 74.0, 89.0, 79.0, 100.0, 100.0], "value": 88.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 148.0, 151.0, 149.0, 157.0, 166.0, 155.0, 144.0, 181.0, 142.0], "value": 153.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 181.0, 176.0, 179.0, 181.0, 175.0, 187.0, 178.0, 180.0, 180.0], "value": 179.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 93.0, 96.0, 69.0, 68.0, 68.0, 70.0, 74.0, 62.0, 69.0], "value": 73.3, "unit": "score"}]}]} 23:15:59 INFO - Return code: 0 23:15:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:15:59 ERROR - # TBPL SUCCESS # 23:15:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:15:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:15:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:15:59 INFO - 2016-09-03 23:15:59.860 screenresolution[1680:15823] starting screenresolution argv=screenresolution get 23:15:59 INFO - 2016-09-03 23:15:59.863 screenresolution[1680:15823] Display 0: 1600x1200x32@60 23:15:59 INFO - 2016-09-03 23:15:59.868 screenresolution[1681:15826] starting screenresolution argv=screenresolution list 23:15:59 INFO - Available Modes on Display 0 23:15:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:15:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:15:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:15:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:16:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:16:00 INFO - Intel Iris: 23:16:00 INFO - Chipset Model: Intel Iris 23:16:00 INFO - Type: GPU 23:16:00 INFO - Bus: Built-In 23:16:00 INFO - VRAM (Dynamic, Max): 1536 MB 23:16:00 INFO - Vendor: Intel (0x8086) 23:16:00 INFO - Device ID: 0x0a2e 23:16:00 INFO - Revision ID: 0x0009 23:16:00 INFO - Displays: 23:16:00 INFO - Display: 23:16:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:16:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:16:00 INFO - Display Serial Number: 1600x1200 60 23:16:00 INFO - Main Display: Yes 23:16:00 INFO - Mirror: Off 23:16:00 INFO - Online: Yes 23:16:00 INFO - Rotation: Supported 23:16:00 INFO - Return code: 0 23:16:00 INFO - Running post-action listener: _resource_record_post_action 23:16:00 INFO - [mozharness: 2016-09-04 06:16:00.111081Z] Finished run-tests step (success) 23:16:00 INFO - Running post-run listener: _resource_record_post_run 23:16:00 INFO - Total resource usage - Wall time: 1208s; CPU: 55.0%; Read bytes: 32033792; Write bytes: 705710080; Read time: 197; Write time: 1995 23:16:00 INFO - TinderboxPrint: CPU usage
54.5% 23:16:00 INFO - TinderboxPrint: I/O read bytes / time
32,033,792 / 197 23:16:00 INFO - TinderboxPrint: I/O write bytes / time
705,710,080 / 1,995 23:16:00 INFO - TinderboxPrint: CPU idle
2,199.1 (45.5%) 23:16:00 INFO - TinderboxPrint: CPU system
976.3 (20.2%) 23:16:00 INFO - TinderboxPrint: CPU user
1,658.3 (34.3%) 23:16:00 INFO - TinderboxPrint: Swap in / out
433,459,200 / 0 23:16:00 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 394052096; Write bytes: 386975744; Read time: 29480; Write time: 781 23:16:00 INFO - run-tests - Wall time: 1175s; CPU: 55.0%; Read bytes: 30276608; Write bytes: 295837696; Read time: 184; Write time: 1162 23:16:00 INFO - Running post-run listener: _upload_blobber_files 23:16:00 INFO - Blob upload gear active. 23:16:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:16:00 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 23:16:00 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'] 23:16:00 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 23:16:00 INFO - (blobuploader) - INFO - Open directory for files ... 23:16:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:16:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:16:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:16:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:16:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:16:01 INFO - (blobuploader) - INFO - Done attempting. 23:16:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:16:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:16:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:16:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:16:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:16:02 INFO - (blobuploader) - INFO - Done attempting. 23:16:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:16:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:16:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:16:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:16:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:16:05 INFO - (blobuploader) - INFO - Done attempting. 23:16:05 INFO - (blobuploader) - INFO - Iteration through files over. 23:16:05 INFO - Return code: 0 23:16:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:16:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:16:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2f822522f881bab0e3be22b7c68ff6d347e13b2d85b747a6d0e61a3c28bb8333e50be68d74207529d2dfe633cc489bd71fca1b2565c3075daa497ebabff1ce8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa9ace553ca45923796468b52c71aa0347fb54ffe1b1aa3eda3e7d6c22b1c9a8264c3eafc0ab420f25de2fae4beb9431930f77c3d05c1e97c568157a5f1ed68d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/69fb3b445221efc96b5cb560b9d53c2d6e78b7a114189aa8036e63bf17393905b591c1323a1475128b88a7b2b4700497863c1eb06aa2d37269899693e18864d3"} 23:16:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:16:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:16:05 INFO - Contents: 23:16:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2f822522f881bab0e3be22b7c68ff6d347e13b2d85b747a6d0e61a3c28bb8333e50be68d74207529d2dfe633cc489bd71fca1b2565c3075daa497ebabff1ce8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa9ace553ca45923796468b52c71aa0347fb54ffe1b1aa3eda3e7d6c22b1c9a8264c3eafc0ab420f25de2fae4beb9431930f77c3d05c1e97c568157a5f1ed68d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/69fb3b445221efc96b5cb560b9d53c2d6e78b7a114189aa8036e63bf17393905b591c1323a1475128b88a7b2b4700497863c1eb06aa2d37269899693e18864d3"} 23:16:05 INFO - Running post-run listener: copy_logs_to_upload_dir 23:16:05 INFO - Copying logs to upload dir... 23:16:05 INFO - mkdir: /builds/slave/test/build/upload/logs 23:16:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.199009 ========= master_lag: 0.61 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-03 23:16:05.548972) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-03 23:16:05.556390) ========= 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.A6PKIOR31L/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.0bqxJYiwCB/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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/f2f822522f881bab0e3be22b7c68ff6d347e13b2d85b747a6d0e61a3c28bb8333e50be68d74207529d2dfe633cc489bd71fca1b2565c3075daa497ebabff1ce8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa9ace553ca45923796468b52c71aa0347fb54ffe1b1aa3eda3e7d6c22b1c9a8264c3eafc0ab420f25de2fae4beb9431930f77c3d05c1e97c568157a5f1ed68d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/69fb3b445221efc96b5cb560b9d53c2d6e78b7a114189aa8036e63bf17393905b591c1323a1475128b88a7b2b4700497863c1eb06aa2d37269899693e18864d3"} build_url:https://queue.taskcluster.net/v1/task/HbjXZtSeTOuvT3fKg3OX_g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010982 build_url: 'https://queue.taskcluster.net/v1/task/HbjXZtSeTOuvT3fKg3OX_g/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/f2f822522f881bab0e3be22b7c68ff6d347e13b2d85b747a6d0e61a3c28bb8333e50be68d74207529d2dfe633cc489bd71fca1b2565c3075daa497ebabff1ce8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa9ace553ca45923796468b52c71aa0347fb54ffe1b1aa3eda3e7d6c22b1c9a8264c3eafc0ab420f25de2fae4beb9431930f77c3d05c1e97c568157a5f1ed68d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/69fb3b445221efc96b5cb560b9d53c2d6e78b7a114189aa8036e63bf17393905b591c1323a1475128b88a7b2b4700497863c1eb06aa2d37269899693e18864d3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-03 23:16:05.589245) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 23:16:05.589566) ========= 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.A6PKIOR31L/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.0bqxJYiwCB/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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.005134 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 23:16:05.637894) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-03 23:16:05.638215) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-03 23:16:05.638528) ========= ========= Total master_lag: 4.69 =========