builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0274 starttime: 1473185495.62 results: success (0) buildid: 20160906073854 builduid: ad376272eeb3486192bcb9f87449fb8a revision: 37c9349b4e8167a61b08b7e119c21ea177b98942 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 11:11:35.616933) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 11:11:35.617457) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 11:11:35.617749) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 11:11:35.643679) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 11:11:35.644082) ========= 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.PGOAjlZl3G/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.PtKYAOlBzh/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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-06 11:11:35-- 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% 136M=0s 2016-09-06 11:11:35 (136 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126358 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 11:11:35.793745) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 11:11:35.794098) ========= 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.PGOAjlZl3G/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.PtKYAOlBzh/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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.026609 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 11:11:35.843658) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 11:11:35.844078) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 37c9349b4e8167a61b08b7e119c21ea177b98942 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 37c9349b4e8167a61b08b7e119c21ea177b98942 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PGOAjlZl3G/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.PtKYAOlBzh/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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-06 11:11:35,927 truncating revision to first 12 chars 2016-09-06 11:11:35,927 Setting DEBUG logging. 2016-09-06 11:11:35,927 attempt 1/10 2016-09-06 11:11:35,927 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/37c9349b4e81?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-06 11:11:36,986 unpacking tar archive at: fx-team-37c9349b4e81/testing/mozharness/ program finished with exit code 0 elapsedTime=1.330350 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 11:11:37.197402) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 11:11:37.197758) ========= script_repo_revision: 37c9349b4e8167a61b08b7e119c21ea177b98942 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 11:11:37.198167) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 11:11:37.198499) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 11:11:37.218694) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-06 11:11:37.219225) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PGOAjlZl3G/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.PtKYAOlBzh/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 11:11:37 INFO - MultiFileLogger online at 20160906 11:11:37 in /builds/slave/test 11:11:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 11:11:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:11:37 INFO - {'append_to_log': False, 11:11:37 INFO - 'base_work_dir': '/builds/slave/test', 11:11:37 INFO - 'blob_upload_branch': 'Fx-Team', 11:11:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:11:37 INFO - 'branch': 'Fx-Team', 11:11:37 INFO - 'buildbot_json_path': 'buildprops.json', 11:11:37 INFO - 'config_files': ('talos/mac_config.py',), 11:11:37 INFO - 'default_actions': ('clobber', 11:11:37 INFO - 'read-buildbot-config', 11:11:37 INFO - 'download-and-extract', 11:11:37 INFO - 'populate-webroot', 11:11:37 INFO - 'create-virtualenv', 11:11:37 INFO - 'install', 11:11:37 INFO - 'run-tests'), 11:11:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:11:37 INFO - 'download_minidump_stackwalk': True, 11:11:37 INFO - 'download_symbols': 'ondemand', 11:11:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:11:37 INFO - 'tooltool.py': '/tools/tooltool.py', 11:11:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:11:37 INFO - '/tools/misc-python/virtualenv.py')}, 11:11:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:11:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:11:37 INFO - 'installer_path': 'installer.exe', 11:11:37 INFO - 'log_level': 'info', 11:11:37 INFO - 'log_name': 'talos', 11:11:37 INFO - 'log_to_console': True, 11:11:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:11:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:11:37 INFO - 'opt_config_files': (), 11:11:37 INFO - 'pip_index': False, 11:11:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:11:37 INFO - 'cmd': ('bash', 11:11:37 INFO - '-c', 11:11:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:11:37 INFO - 'enabled': True, 11:11:37 INFO - 'halt_on_failure': False, 11:11:37 INFO - 'name': 'check_screen_resolution'},), 11:11:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:11:37 INFO - 'cmd': ('bash', 11:11:37 INFO - '-c', 11:11:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:11:37 INFO - 'enabled': True, 11:11:37 INFO - 'halt_on_failure': False, 11:11:37 INFO - 'name': 'check_screen_resolution'},), 11:11:37 INFO - 'run_cmd_checks_enabled': True, 11:11:37 INFO - 'sps_profile': False, 11:11:37 INFO - 'sps_profile_interval': 0, 11:11:37 INFO - 'suite': 'dromaeojs-e10s', 11:11:37 INFO - 'system_bits': '32', 11:11:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:11:37 INFO - 'title': 't-yosemite-r7-0274', 11:11:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:11:37 INFO - 'use_talos_json': True, 11:11:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:11:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:11:37 INFO - 'work_dir': 'build'} 11:11:37 INFO - [mozharness: 2016-09-06 18:11:37.429940Z] Running clobber step. 11:11:37 INFO - Running pre-action listener: _resource_record_pre_action 11:11:37 INFO - Running main action method: clobber 11:11:37 INFO - rmtree: /builds/slave/test/build 11:11:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:11:39 INFO - Running post-action listener: _resource_record_post_action 11:11:39 INFO - [mozharness: 2016-09-06 18:11:39.424423Z] Finished clobber step (success) 11:11:39 INFO - [mozharness: 2016-09-06 18:11:39.424555Z] Running read-buildbot-config step. 11:11:39 INFO - Running pre-action listener: _resource_record_pre_action 11:11:39 INFO - Running main action method: read_buildbot_config 11:11:39 INFO - Using buildbot properties: 11:11:39 INFO - { 11:11:39 INFO - "project": "", 11:11:39 INFO - "product": "firefox", 11:11:39 INFO - "script_repo_revision": "production", 11:11:39 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 11:11:39 INFO - "repository": "", 11:11:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 11:11:39 INFO - "buildid": "20160906073854", 11:11:39 INFO - "pgo_build": "False", 11:11:39 INFO - "basedir": "/builds/slave/test", 11:11:39 INFO - "buildnumber": 109, 11:11:39 INFO - "slavename": "t-yosemite-r7-0274", 11:11:39 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 11:11:39 INFO - "platform": "yosemite_r7", 11:11:39 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 11:11:39 INFO - "branch": "fx-team", 11:11:39 INFO - "repo_path": "integration/fx-team", 11:11:39 INFO - "revision": "37c9349b4e8167a61b08b7e119c21ea177b98942", 11:11:39 INFO - "stage_platform": "macosx64", 11:11:39 INFO - "builduid": "ad376272eeb3486192bcb9f87449fb8a", 11:11:39 INFO - "slavebuilddir": "test" 11:11:39 INFO - } 11:11:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/Lv7yCflATWaf6nS5Qd2uwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 11:11:39 INFO - Running post-action listener: _resource_record_post_action 11:11:39 INFO - [mozharness: 2016-09-06 18:11:39.427920Z] Finished read-buildbot-config step (success) 11:11:39 INFO - [mozharness: 2016-09-06 18:11:39.428009Z] Running download-and-extract step. 11:11:39 INFO - Running pre-action listener: _resource_record_pre_action 11:11:39 INFO - Running main action method: download_and_extract 11:11:39 INFO - mkdir: /builds/slave/test/build/tests 11:11:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:11:39 INFO - trying https://queue.taskcluster.net/v1/task/Lv7yCflATWaf6nS5Qd2uwA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 11:11:39 INFO - Downloading https://queue.taskcluster.net/v1/task/Lv7yCflATWaf6nS5Qd2uwA/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 11:11:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Lv7yCflATWaf6nS5Qd2uwA/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 11:11:40 INFO - Downloaded 1199 bytes. 11:11:40 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 11:11:40 INFO - Using the following test package requirements: 11:11:40 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:11:40 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:11:40 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 11:11:40 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:11:40 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 11:11:40 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:11:40 INFO - u'jsshell-mac.zip'], 11:11:40 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:11:40 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 11:11:40 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:11:40 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:11:40 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 11:11:40 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:11:40 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 11:11:40 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:11:40 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 11:11:40 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:11:40 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 11:11:40 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:11:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Lv7yCflATWaf6nS5Qd2uwA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:11:41 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 11:11:48 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 11:11:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Lv7yCflATWaf6nS5Qd2uwA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:11:48 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 11:11:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Lv7yCflATWaf6nS5Qd2uwA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 11:11:55 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 11:11:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:11:56 INFO - trying https://queue.taskcluster.net/v1/task/Lv7yCflATWaf6nS5Qd2uwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:11:56 INFO - Downloading https://queue.taskcluster.net/v1/task/Lv7yCflATWaf6nS5Qd2uwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 11:11:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Lv7yCflATWaf6nS5Qd2uwA/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 11:11:59 INFO - Downloaded 111492158 bytes. 11:11:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Lv7yCflATWaf6nS5Qd2uwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:11:59 INFO - mkdir: /builds/slave/test/properties 11:11:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:11:59 INFO - Writing to file /builds/slave/test/properties/build_url 11:11:59 INFO - Contents: 11:11:59 INFO - build_url:https://queue.taskcluster.net/v1/task/Lv7yCflATWaf6nS5Qd2uwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:11:59 INFO - Running post-action listener: _resource_record_post_action 11:11:59 INFO - Running post-action listener: set_extra_try_arguments 11:11:59 INFO - [mozharness: 2016-09-06 18:11:59.879257Z] Finished download-and-extract step (success) 11:11:59 INFO - [mozharness: 2016-09-06 18:11:59.879377Z] Running populate-webroot step. 11:11:59 INFO - Running pre-action listener: _resource_record_pre_action 11:11:59 INFO - Running main action method: populate_webroot 11:11:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:11:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:11:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:11:59 INFO - '--tppagecycles', 11:11:59 INFO - '7'], 11:11:59 INFO - 'tests': ['tsvgm']}}, 11:11:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:11:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:11:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:11:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:11:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:11:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:11:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:11:59 INFO - 'talos_options': ['--disable-e10s'], 11:11:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:11:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:11:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:11:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:11:59 INFO - 'talos_options': ['--disable-e10s'], 11:11:59 INFO - 'tests': ['damp', 'tps']}, 11:11:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:11:59 INFO - 'tests': ['damp', 'tps']}, 11:11:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:11:59 INFO - 'tests': ['dromaeo_dom']}, 11:11:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:11:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:11:59 INFO - 'tests': ['basic_compositor_video']}, 11:11:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:11:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:11:59 INFO - 'tests': ['a11yr', 11:11:59 INFO - 'ts_paint', 11:11:59 INFO - 'tpaint', 11:11:59 INFO - 'sessionrestore', 11:11:59 INFO - 'sessionrestore_no_auto_restore', 11:11:59 INFO - 'tabpaint']}, 11:11:59 INFO - 'other-e10s': {'tests': ['a11yr', 11:11:59 INFO - 'ts_paint', 11:11:59 INFO - 'tpaint', 11:11:59 INFO - 'sessionrestore', 11:11:59 INFO - 'sessionrestore_no_auto_restore', 11:11:59 INFO - 'tabpaint']}, 11:11:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:11:59 INFO - 'ts_paint', 11:11:59 INFO - 'tpaint', 11:11:59 INFO - 'sessionrestore', 11:11:59 INFO - 'sessionrestore_no_auto_restore', 11:11:59 INFO - 'tabpaint']}, 11:11:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:11:59 INFO - 'ts_paint', 11:11:59 INFO - 'tpaint', 11:11:59 INFO - 'sessionrestore', 11:11:59 INFO - 'sessionrestore_no_auto_restore', 11:11:59 INFO - 'tabpaint']}, 11:11:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:11:59 INFO - 'tests': ['a11yr', 11:11:59 INFO - 'ts_paint', 11:11:59 INFO - 'tpaint', 11:11:59 INFO - 'sessionrestore', 11:11:59 INFO - 'sessionrestore_no_auto_restore', 11:11:59 INFO - 'tabpaint']}, 11:11:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:11:59 INFO - 'tests': ['a11yr', 11:11:59 INFO - 'ts_paint', 11:11:59 INFO - 'tpaint', 11:11:59 INFO - 'sessionrestore', 11:11:59 INFO - 'sessionrestore_no_auto_restore', 11:11:59 INFO - 'tabpaint']}, 11:11:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:11:59 INFO - 'tests': ['tsvgx', 11:11:59 INFO - 'tsvgr_opacity', 11:11:59 INFO - 'tart', 11:11:59 INFO - 'tscrollx', 11:11:59 INFO - 'cart']}, 11:11:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:11:59 INFO - 'tsvgr_opacity', 11:11:59 INFO - 'tart', 11:11:59 INFO - 'tscrollx', 11:11:59 INFO - 'cart']}, 11:11:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:11:59 INFO - 'talos_options': ['--disable-e10s'], 11:11:59 INFO - 'tests': ['tp5o']}, 11:11:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:11:59 INFO - 'tests': ['tp5o']}, 11:11:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:11:59 INFO - 'talos_options': ['--disable-e10s', 11:11:59 INFO - '--xperf_path', 11:11:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:11:59 INFO - 'tests': ['tp5n']}, 11:11:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:11:59 INFO - 'talos_options': ['--xperf_path', 11:11:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:11:59 INFO - 'tests': ['tp5n']}}, 11:11:59 INFO - 'talos.zip': {'path': '', 11:11:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:11:59 INFO - Running post-action listener: _resource_record_post_action 11:11:59 INFO - [mozharness: 2016-09-06 18:11:59.897999Z] Finished populate-webroot step (success) 11:11:59 INFO - [mozharness: 2016-09-06 18:11:59.898140Z] Running create-virtualenv step. 11:11:59 INFO - Running pre-action listener: _resource_record_pre_action 11:11:59 INFO - Running main action method: create_virtualenv 11:11:59 INFO - Creating virtualenv /builds/slave/test/build/venv 11:11:59 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 11:11:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:12:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:12:00 INFO - Using real prefix '/tools/python27' 11:12:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:12:00 INFO - Installing distribute.............................................................................................................................................................................................done. 11:12:03 INFO - Installing pip.................done. 11:12:03 INFO - Return code: 0 11:12:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:12:03 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')]} 11:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12:03 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')]} 11:12:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12:03 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 0x109427a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88c2498810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10938bd30>, '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 0x1090f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109383b20>, '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.PGOAjlZl3G/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.PtKYAOlBzh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 11:12:03 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 11:12:03 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 11:12:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PGOAjlZl3G/Render', 11:12:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:03 INFO - 'HOME': '/Users/cltbld', 11:12:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:03 INFO - 'LOGNAME': 'cltbld', 11:12:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:03 INFO - 'MOZ_NO_REMOTE': '1', 11:12:03 INFO - 'NO_EM_RESTART': '1', 11:12:03 INFO - 'PAGER': '/bin/cat', 11:12:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:03 INFO - 'PWD': '/builds/slave/test', 11:12:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:03 INFO - 'SHELL': '/bin/bash', 11:12:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtKYAOlBzh/Listeners', 11:12:03 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 11:12:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:03 INFO - 'USER': 'cltbld', 11:12:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:03 INFO - 'XPC_FLAGS': '0x0', 11:12:03 INFO - 'XPC_SERVICE_NAME': '0', 11:12:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:03 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 11:12:03 INFO - Cleaning up... 11:12:03 INFO - Return code: 0 11:12:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:12:03 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')]} 11:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12:03 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')]} 11:12:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12:03 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 0x109427a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88c2498810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10938bd30>, '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 0x1090f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109383b20>, '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.PGOAjlZl3G/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.PtKYAOlBzh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 11:12:03 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 11:12:03 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 11:12:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PGOAjlZl3G/Render', 11:12:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:03 INFO - 'HOME': '/Users/cltbld', 11:12:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:03 INFO - 'LOGNAME': 'cltbld', 11:12:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:03 INFO - 'MOZ_NO_REMOTE': '1', 11:12:03 INFO - 'NO_EM_RESTART': '1', 11:12:03 INFO - 'PAGER': '/bin/cat', 11:12:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:03 INFO - 'PWD': '/builds/slave/test', 11:12:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:03 INFO - 'SHELL': '/bin/bash', 11:12:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtKYAOlBzh/Listeners', 11:12:03 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 11:12:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:03 INFO - 'USER': 'cltbld', 11:12:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:03 INFO - 'XPC_FLAGS': '0x0', 11:12:03 INFO - 'XPC_SERVICE_NAME': '0', 11:12:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:04 INFO - Downloading/unpacking psutil>=3.1.1 11:12:04 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 11:12:04 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 11:12:04 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 11:12:04 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 11:12:04 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 11:12:04 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 11:12:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:12:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:12:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:12:07 INFO - Installing collected packages: psutil 11:12:07 INFO - Running setup.py install for psutil 11:12:07 INFO - building 'psutil._psutil_osx' extension 11:12:07 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 11:12:08 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 11:12:08 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 11:12:08 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 11:12:08 INFO - building 'psutil._psutil_posix' extension 11:12:08 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 11:12:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:12:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:12:08 INFO - ^ 11:12:08 INFO - 1 warning generated. 11:12:08 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 11:12:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:12:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:12:08 INFO - Successfully installed psutil 11:12:08 INFO - Cleaning up... 11:12:08 INFO - Return code: 0 11:12:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:12:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109427a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88c2498810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10938bd30>, '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 0x1090f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109383b20>, '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.PGOAjlZl3G/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.PtKYAOlBzh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 11:12:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:12:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:12:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PGOAjlZl3G/Render', 11:12:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:08 INFO - 'HOME': '/Users/cltbld', 11:12:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:08 INFO - 'LOGNAME': 'cltbld', 11:12:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:08 INFO - 'MOZ_NO_REMOTE': '1', 11:12:08 INFO - 'NO_EM_RESTART': '1', 11:12:08 INFO - 'PAGER': '/bin/cat', 11:12:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:08 INFO - 'PWD': '/builds/slave/test', 11:12:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:08 INFO - 'SHELL': '/bin/bash', 11:12:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtKYAOlBzh/Listeners', 11:12:08 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 11:12:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:08 INFO - 'USER': 'cltbld', 11:12:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:08 INFO - 'XPC_FLAGS': '0x0', 11:12:08 INFO - 'XPC_SERVICE_NAME': '0', 11:12:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:12:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:12:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:12:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:12:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:12:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:12:11 INFO - Installing collected packages: mozsystemmonitor 11:12:11 INFO - Running setup.py install for mozsystemmonitor 11:12:11 INFO - Successfully installed mozsystemmonitor 11:12:11 INFO - Cleaning up... 11:12:11 INFO - Return code: 0 11:12:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:12:11 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')]} 11:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12:11 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')]} 11:12:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12:11 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 0x109427a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88c2498810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10938bd30>, '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 0x1090f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109383b20>, '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.PGOAjlZl3G/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.PtKYAOlBzh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 11:12:11 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 11:12:11 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 11:12:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PGOAjlZl3G/Render', 11:12:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:11 INFO - 'HOME': '/Users/cltbld', 11:12:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:11 INFO - 'LOGNAME': 'cltbld', 11:12:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:11 INFO - 'MOZ_NO_REMOTE': '1', 11:12:11 INFO - 'NO_EM_RESTART': '1', 11:12:11 INFO - 'PAGER': '/bin/cat', 11:12:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:11 INFO - 'PWD': '/builds/slave/test', 11:12:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:11 INFO - 'SHELL': '/bin/bash', 11:12:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtKYAOlBzh/Listeners', 11:12:11 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 11:12:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:11 INFO - 'USER': 'cltbld', 11:12:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:11 INFO - 'XPC_FLAGS': '0x0', 11:12:11 INFO - 'XPC_SERVICE_NAME': '0', 11:12:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:12 INFO - Downloading/unpacking jsonschema==2.5.1 11:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:12:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:12:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:12:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:12:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:12:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:14 INFO - Downloading functools32-3.2.3-2.tar.gz 11:12:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:12:14 INFO - warning: no files found matching '*.txt' 11:12:14 INFO - no previously-included directories found matching 'build' 11:12:14 INFO - no previously-included directories found matching 'dist' 11:12:14 INFO - no previously-included directories found matching '.git*' 11:12:14 INFO - Installing collected packages: jsonschema, functools32 11:12:14 INFO - Running setup.py install for functools32 11:12:15 INFO - warning: no files found matching '*.txt' 11:12:15 INFO - no previously-included directories found matching 'build' 11:12:15 INFO - no previously-included directories found matching 'dist' 11:12:15 INFO - no previously-included directories found matching '.git*' 11:12:15 INFO - Successfully installed jsonschema functools32 11:12:15 INFO - Cleaning up... 11:12:15 INFO - Return code: 0 11:12:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:12:15 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')]} 11:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12:15 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')]} 11:12:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12:15 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 0x109427a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88c2498810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10938bd30>, '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 0x1090f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109383b20>, '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.PGOAjlZl3G/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.PtKYAOlBzh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 11:12:15 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 11:12:15 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 11:12:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PGOAjlZl3G/Render', 11:12:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:15 INFO - 'HOME': '/Users/cltbld', 11:12:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:15 INFO - 'LOGNAME': 'cltbld', 11:12:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:15 INFO - 'MOZ_NO_REMOTE': '1', 11:12:15 INFO - 'NO_EM_RESTART': '1', 11:12:15 INFO - 'PAGER': '/bin/cat', 11:12:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:15 INFO - 'PWD': '/builds/slave/test', 11:12:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:15 INFO - 'SHELL': '/bin/bash', 11:12:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtKYAOlBzh/Listeners', 11:12:15 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 11:12:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:15 INFO - 'USER': 'cltbld', 11:12:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:15 INFO - 'XPC_FLAGS': '0x0', 11:12:15 INFO - 'XPC_SERVICE_NAME': '0', 11:12:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:12:15 INFO - Cleaning up... 11:12:15 INFO - Return code: 0 11:12:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:12:15 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')]} 11:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12:15 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')]} 11:12:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12:15 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 0x109427a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88c2498810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10938bd30>, '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 0x1090f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109383b20>, '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.PGOAjlZl3G/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.PtKYAOlBzh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 11:12:15 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 11:12:15 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 11:12:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PGOAjlZl3G/Render', 11:12:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:15 INFO - 'HOME': '/Users/cltbld', 11:12:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:15 INFO - 'LOGNAME': 'cltbld', 11:12:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:15 INFO - 'MOZ_NO_REMOTE': '1', 11:12:15 INFO - 'NO_EM_RESTART': '1', 11:12:15 INFO - 'PAGER': '/bin/cat', 11:12:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:15 INFO - 'PWD': '/builds/slave/test', 11:12:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:15 INFO - 'SHELL': '/bin/bash', 11:12:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtKYAOlBzh/Listeners', 11:12:15 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 11:12:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:15 INFO - 'USER': 'cltbld', 11:12:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:15 INFO - 'XPC_FLAGS': '0x0', 11:12:15 INFO - 'XPC_SERVICE_NAME': '0', 11:12:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:15 INFO - Downloading/unpacking blobuploader==1.2.4 11:12:15 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 11:12:15 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 11:12:15 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 11:12:15 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 11:12:15 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 11:12:15 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 11:12:18 INFO - Downloading blobuploader-1.2.4.tar.gz 11:12:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:12:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:12:18 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 11:12:18 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 11:12:18 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 11:12:18 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 11:12:18 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 11:12:18 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 11:12:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:12:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:12:18 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 11:12:18 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 11:12:18 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 11:12:18 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 11:12:18 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 11:12:18 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 11:12:19 INFO - Downloading docopt-0.6.1.tar.gz 11:12:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:12:19 INFO - Installing collected packages: blobuploader, requests, docopt 11:12:19 INFO - Running setup.py install for blobuploader 11:12:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:12:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:12:19 INFO - Running setup.py install for requests 11:12:20 INFO - Running setup.py install for docopt 11:12:20 INFO - Successfully installed blobuploader requests docopt 11:12:20 INFO - Cleaning up... 11:12:20 INFO - Return code: 0 11:12:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:12:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12:20 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 0x109427a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88c2498810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10938bd30>, '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 0x1090f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109383b20>, '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.PGOAjlZl3G/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.PtKYAOlBzh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 11:12:20 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 11:12:20 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 11:12:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PGOAjlZl3G/Render', 11:12:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:20 INFO - 'HOME': '/Users/cltbld', 11:12:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:20 INFO - 'LOGNAME': 'cltbld', 11:12:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:20 INFO - 'MOZ_NO_REMOTE': '1', 11:12:20 INFO - 'NO_EM_RESTART': '1', 11:12:20 INFO - 'PAGER': '/bin/cat', 11:12:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:20 INFO - 'PWD': '/builds/slave/test', 11:12:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:20 INFO - 'SHELL': '/bin/bash', 11:12:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtKYAOlBzh/Listeners', 11:12:20 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 11:12:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:20 INFO - 'USER': 'cltbld', 11:12:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:20 INFO - 'XPC_FLAGS': '0x0', 11:12:20 INFO - 'XPC_SERVICE_NAME': '0', 11:12:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:12:20 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-yflTQj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:12:20 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-xQYx5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:12:20 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-FfhL8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:12:20 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-c8DJF3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:12:21 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-SgjMYH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:12:21 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-0m9kVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:12:21 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-DFW8ZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:12:21 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-XILWwF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:12:21 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-_ftccA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:12:21 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-wUpd_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:12:21 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-q_UK7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:12:21 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-PxrUFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:12:21 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-3FSvWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:12:22 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-7Rd32D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:12:22 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-9nCMMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:12:22 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-QrBwTc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:12:22 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-sGSQNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:12:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:12:22 INFO - Running setup.py install for manifestparser 11:12:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:12:22 INFO - Running setup.py install for mozcrash 11:12:22 INFO - Running setup.py install for mozdebug 11:12:22 INFO - Running setup.py install for mozdevice 11:12:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:12:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:12:23 INFO - Running setup.py install for mozfile 11:12:23 INFO - Running setup.py install for mozhttpd 11:12:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:12:23 INFO - Running setup.py install for mozinfo 11:12:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:12:23 INFO - Running setup.py install for mozInstall 11:12:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:12:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:12:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:12:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:12:23 INFO - Running setup.py install for mozleak 11:12:23 INFO - Running setup.py install for mozlog 11:12:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:12:23 INFO - Running setup.py install for moznetwork 11:12:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:12:24 INFO - Running setup.py install for mozprocess 11:12:24 INFO - Running setup.py install for mozprofile 11:12:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:12:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:12:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:12:24 INFO - Running setup.py install for mozrunner 11:12:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:12:24 INFO - Running setup.py install for mozscreenshot 11:12:24 INFO - Running setup.py install for moztest 11:12:24 INFO - Running setup.py install for mozversion 11:12:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:12:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:12:25 INFO - Cleaning up... 11:12:25 INFO - Return code: 0 11:12:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:12: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')]} 11:12:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12: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')]} 11:12:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12:25 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 0x109427a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88c2498810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10938bd30>, '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 0x1090f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109383b20>, '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.PGOAjlZl3G/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.PtKYAOlBzh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 11:12:25 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 11:12:25 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 11:12:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PGOAjlZl3G/Render', 11:12:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:25 INFO - 'HOME': '/Users/cltbld', 11:12:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:25 INFO - 'LOGNAME': 'cltbld', 11:12:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:25 INFO - 'MOZ_NO_REMOTE': '1', 11:12:25 INFO - 'NO_EM_RESTART': '1', 11:12:25 INFO - 'PAGER': '/bin/cat', 11:12:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:25 INFO - 'PWD': '/builds/slave/test', 11:12:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:25 INFO - 'SHELL': '/bin/bash', 11:12:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtKYAOlBzh/Listeners', 11:12:25 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 11:12:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:25 INFO - 'USER': 'cltbld', 11:12:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:25 INFO - 'XPC_FLAGS': '0x0', 11:12:25 INFO - 'XPC_SERVICE_NAME': '0', 11:12:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:12:25 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-QqGltR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:12:25 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)) 11:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:12:25 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-YpAIAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:12:25 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)) 11:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:12:25 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-YNV6DI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:12:25 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)) 11:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:12:25 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-2fKuBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:12:25 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)) 11:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:12:25 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-JZQo6G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:12:25 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)) 11:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:12:25 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-a1ZZYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:12:25 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)) 11:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:12:25 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-ORsLD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:12:26 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)) 11:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:12:26 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-x2J0yL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:12:26 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)) 11:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:12:26 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-WD6v4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:12:26 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)) 11:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:12:26 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-LxLoXi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:12:26 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)) 11:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:12:26 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-BE7MZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:12:26 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)) 11:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:12:26 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-FwEbnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:12:26 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)) 11:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:12:26 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-RULQd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:12:26 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)) 11:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:12:26 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-Gdqo2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:12:26 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)) 11:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:12:26 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-DN2a6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:12:26 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)) 11:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:12:26 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-gLGrIN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:12:26 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)) 11:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:12:26 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-66jgVk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:12:27 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)) 11:12:27 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)) 11:12:27 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)) 11:12:27 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)) 11:12:27 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)) 11:12:27 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)) 11:12:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:12:27 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 11:12:27 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 11:12:27 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 11:12:27 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 11:12:27 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 11:12:27 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 11:12:29 INFO - Downloading blessings-1.6.tar.gz 11:12:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:12:29 INFO - Installing collected packages: blessings 11:12:29 INFO - Running setup.py install for blessings 11:12:29 INFO - Successfully installed blessings 11:12:29 INFO - Cleaning up... 11:12:29 INFO - Return code: 0 11:12:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:12:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:12:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:12:30 INFO - Reading from file tmpfile_stdout 11:12:30 INFO - Current package versions: 11:12:30 INFO - blessings == 1.6 11:12:30 INFO - blobuploader == 1.2.4 11:12:30 INFO - docopt == 0.6.1 11:12:30 INFO - functools32 == 3.2.3-2 11:12:30 INFO - jsonschema == 2.5.1 11:12:30 INFO - manifestparser == 1.1 11:12:30 INFO - mozInstall == 1.12 11:12:30 INFO - mozcrash == 0.17 11:12:30 INFO - mozdebug == 0.1 11:12:30 INFO - mozdevice == 0.48 11:12:30 INFO - mozfile == 1.2 11:12:30 INFO - mozhttpd == 0.7 11:12:30 INFO - mozinfo == 0.9 11:12:30 INFO - mozleak == 0.1 11:12:30 INFO - mozlog == 3.3 11:12:30 INFO - moznetwork == 0.27 11:12:30 INFO - mozprocess == 0.23 11:12:30 INFO - mozprofile == 0.28 11:12:30 INFO - mozrunner == 6.12 11:12:30 INFO - mozscreenshot == 0.1 11:12:30 INFO - mozsystemmonitor == 0.3 11:12:30 INFO - moztest == 0.7 11:12:30 INFO - mozversion == 1.4 11:12:30 INFO - psutil == 3.1.1 11:12:30 INFO - requests == 1.2.3 11:12:30 INFO - wsgiref == 0.1.2 11:12:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:12:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12:30 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 0x109427a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88c2498810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10938bd30>, '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 0x1090f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109383b20>, '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.PGOAjlZl3G/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.PtKYAOlBzh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 11:12:30 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 11:12:30 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 11:12:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PGOAjlZl3G/Render', 11:12:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:30 INFO - 'HOME': '/Users/cltbld', 11:12:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:30 INFO - 'LOGNAME': 'cltbld', 11:12:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:30 INFO - 'MOZ_NO_REMOTE': '1', 11:12:30 INFO - 'NO_EM_RESTART': '1', 11:12:30 INFO - 'PAGER': '/bin/cat', 11:12:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:30 INFO - 'PWD': '/builds/slave/test', 11:12:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:30 INFO - 'SHELL': '/bin/bash', 11:12:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtKYAOlBzh/Listeners', 11:12:30 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 11:12:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:30 INFO - 'USER': 'cltbld', 11:12:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:30 INFO - 'XPC_FLAGS': '0x0', 11:12:30 INFO - 'XPC_SERVICE_NAME': '0', 11:12:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:31 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)) 11:12:31 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)) 11:12:31 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)) 11:12:31 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)) 11:12:31 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)) 11:12:31 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)) 11:12:31 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)) 11:12:31 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)) 11:12:31 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)) 11:12:31 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)) 11:12:31 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)) 11:12:31 INFO - Cleaning up... 11:12:31 INFO - Return code: 0 11:12:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:12: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')]} 11:12:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12: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')]} 11:12:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109427a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094bc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88c2498810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10938bd30>, '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 0x1090f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109383b20>, '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.PGOAjlZl3G/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.PtKYAOlBzh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 11:12: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'] in /builds/slave/test/build 11:12: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 11:12:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PGOAjlZl3G/Render', 11:12:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:31 INFO - 'HOME': '/Users/cltbld', 11:12:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:31 INFO - 'LOGNAME': 'cltbld', 11:12:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:31 INFO - 'MOZ_NO_REMOTE': '1', 11:12:31 INFO - 'NO_EM_RESTART': '1', 11:12:31 INFO - 'PAGER': '/bin/cat', 11:12:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:31 INFO - 'PWD': '/builds/slave/test', 11:12:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:31 INFO - 'SHELL': '/bin/bash', 11:12:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtKYAOlBzh/Listeners', 11:12:31 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 11:12:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:31 INFO - 'USER': 'cltbld', 11:12:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:31 INFO - 'XPC_FLAGS': '0x0', 11:12:31 INFO - 'XPC_SERVICE_NAME': '0', 11:12:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:12:31 INFO - Cleaning up... 11:12:31 INFO - Return code: 0 11:12:31 INFO - Running post-action listener: _resource_record_post_action 11:12:31 INFO - Running post-action listener: _start_resource_monitoring 11:12:31 INFO - Starting resource monitoring. 11:12:31 INFO - [mozharness: 2016-09-06 18:12:31.492813Z] Finished create-virtualenv step (success) 11:12:31 INFO - [mozharness: 2016-09-06 18:12:31.493842Z] Running install step. 11:12:31 INFO - Running pre-action listener: _resource_record_pre_action 11:12:31 INFO - Running main action method: install 11:12:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:12:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:12:31 INFO - Reading from file tmpfile_stdout 11:12:31 INFO - Detecting whether we're running mozinstall >=1.0... 11:12:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:12:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:12:31 INFO - Reading from file tmpfile_stdout 11:12:31 INFO - Output received: 11:12:31 INFO - Usage: mozinstall [options] installer 11:12:31 INFO - Options: 11:12:31 INFO - -h, --help show this help message and exit 11:12:31 INFO - -d DEST, --destination=DEST 11:12:31 INFO - Directory to install application into. [default: 11:12:31 INFO - "/builds/slave/test"] 11:12:31 INFO - --app=APP Application being installed. [default: firefox] 11:12:31 INFO - mkdir: /builds/slave/test/build/application 11:12:31 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'] 11:12:31 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 11:13:05 INFO - Reading from file tmpfile_stdout 11:13:05 INFO - Output received: 11:13:05 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:13:05 INFO - Running post-action listener: _resource_record_post_action 11:13:05 INFO - [mozharness: 2016-09-06 18:13:05.834840Z] Finished install step (success) 11:13:05 INFO - [mozharness: 2016-09-06 18:13:05.834997Z] Running run-tests step. 11:13:05 INFO - Running pre-action listener: _resource_record_pre_action 11:13:05 INFO - Running main action method: run_tests 11:13:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:13:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:13:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:13:05 INFO - 2016-09-06 11:13:05.849 screenresolution[1641:7397] starting screenresolution argv=screenresolution get 11:13:05 INFO - 2016-09-06 11:13:05.852 screenresolution[1641:7397] Display 0: 1600x1200x32@60 11:13:05 INFO - 2016-09-06 11:13:05.857 screenresolution[1642:7399] starting screenresolution argv=screenresolution list 11:13:05 INFO - Available Modes on Display 0 11:13:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:13:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:13:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:13:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:13:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:13:06 INFO - Intel Iris: 11:13:06 INFO - Chipset Model: Intel Iris 11:13:06 INFO - Type: GPU 11:13:06 INFO - Bus: Built-In 11:13:06 INFO - VRAM (Dynamic, Max): 1536 MB 11:13:06 INFO - Vendor: Intel (0x8086) 11:13:06 INFO - Device ID: 0x0a2e 11:13:06 INFO - Revision ID: 0x0009 11:13:06 INFO - Displays: 11:13:06 INFO - Display: 11:13:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:13:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:13:06 INFO - Display Serial Number: 1600x1200 60 11:13:06 INFO - Main Display: Yes 11:13:06 INFO - Mirror: Off 11:13:06 INFO - Online: Yes 11:13:06 INFO - Rotation: Supported 11:13:06 INFO - Return code: 0 11:13:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:13:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:13:06 INFO - Python 2.7.3 11:13:06 INFO - Return code: 0 11:13:06 INFO - grabbing minidump binary from tooltool 11:13:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:13:06 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 0x10938bd30>, '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 0x1090f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109383b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:13:06 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 11:13:06 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 11:13:06 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 11:13:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:13:06 INFO - Return code: 0 11:13:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:13:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:13:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:13:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:13:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:13:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Lv7yCflATWaf6nS5Qd2uwA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0274', '--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 11:13:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Lv7yCflATWaf6nS5Qd2uwA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0274 --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 11:13:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PGOAjlZl3G/Render', 11:13:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:13:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:13:06 INFO - 'HOME': '/Users/cltbld', 11:13:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:13:06 INFO - 'LOGNAME': 'cltbld', 11:13:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:13:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:13:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:13:06 INFO - 'MOZ_NO_REMOTE': '1', 11:13:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:13:06 INFO - 'NO_EM_RESTART': '1', 11:13:06 INFO - 'PAGER': '/bin/cat', 11:13:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:13:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:13:06 INFO - 'PWD': '/builds/slave/test', 11:13:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:13:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:13:06 INFO - 'SHELL': '/bin/bash', 11:13:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtKYAOlBzh/Listeners', 11:13:06 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 11:13:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:13:06 INFO - 'USER': 'cltbld', 11:13:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:13:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:13:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:13:06 INFO - 'XPC_FLAGS': '0x0', 11:13:06 INFO - 'XPC_SERVICE_NAME': '0', 11:13:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:13:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Lv7yCflATWaf6nS5Qd2uwA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0274', '--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 11:13:06 INFO - mozversion application_buildid: 20160906073854 11:13:06 INFO - mozversion application_changeset: 37c9349b4e8167a61b08b7e119c21ea177b98942 11:13:06 INFO - mozversion application_display_name: Nightly 11:13:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:13:06 INFO - mozversion application_name: Firefox 11:13:06 INFO - mozversion application_remotingname: firefox 11:13:06 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:13:06 INFO - mozversion application_vendor: Mozilla 11:13:06 INFO - mozversion application_version: 51.0a1 11:13:06 INFO - mozversion platform_buildid: 20160906073854 11:13:06 INFO - mozversion platform_changeset: 37c9349b4e8167a61b08b7e119c21ea177b98942 11:13:06 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:13:06 INFO - mozversion platform_version: 51.0a1 11:13:06 INFO - using testdate: 1473185586 11:13:06 INFO - actual date: 1473185586 11:13:06 INFO - starting webserver on 'localhost:49283' 11:13:06 INFO - SUITE-START | Running 2 tests 11:13:06 INFO - TEST-START | dromaeo_css 11:13:06 INFO - operating with platform_type : mac_ 11:13:06 INFO - Initialising browser for dromaeo_css test... 11:13:06 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpAQXMmF/profile http://localhost:49283/getInfo.html) 11:13:09 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 11:13:09 INFO - PROCESS | 1652 | colorDepth:24 11:13:09 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 11:13:09 INFO - PROCESS | 1652 | __metrics 11:13:09 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 11:13:10 INFO - TEST-INFO | 1652: exit 0 11:13:10 INFO - Browser initialized. 11:13:10 INFO - Running cycle 1/1 for dromaeo_css test... 11:13:10 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpAQXMmF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:13:22 INFO - PROCESS | 1655 | RSS: Main: 244092928 11:13:22 INFO - PROCESS | 1655 | 11:14:11 INFO - PROCESS | 1655 | 1473185651785 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] 11:16:24 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49283/tests/dromaeo/cssquery-ext.html) 11:16:25 INFO - PROCESS | 1655 | RSS: Main: 264261632 11:16:25 INFO - PROCESS | 1655 | 11:19:27 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-ext.html (next: http://localhost:49283/tests/dromaeo/cssquery-jquery.html) 11:19:28 INFO - PROCESS | 1655 | RSS: Main: 269250560 11:19:28 INFO - PROCESS | 1655 | 11:22:30 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49283/tests/dromaeo/cssquery-mootools.html) 11:22:31 INFO - PROCESS | 1655 | RSS: Main: 273190912 11:22:31 INFO - PROCESS | 1655 | 11:25:33 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49283/tests/dromaeo/cssquery-prototype.html) 11:25:33 INFO - PROCESS | 1655 | RSS: Main: 270532608 11:25:33 INFO - PROCESS | 1655 | 11:28:36 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49283/tests/dromaeo/cssquery-yui.html) 11:28:37 INFO - PROCESS | 1655 | RSS: Main: 276893696 11:28:37 INFO - PROCESS | 1655 | 11:31:40 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-yui.html (next: http://localhost:49283/tests/dromaeo/cssquery-dojo.html) 11:31:40 INFO - PROCESS | 1655 | RSS: Main: 280551424 11:31:40 INFO - PROCESS | 1655 | 11:31:40 INFO - PROCESS | 1655 | __start_tp_report 11:31:40 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 11:31:40 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 11:31:40 INFO - PROCESS | 1655 | |i|pagename|runs| 11:31:40 INFO - PROCESS | 1655 | |0;dojo.html;2724.2644485988794;2814.226087825846;3413.6927676509113;3429.2112814052766;3469.4622333538305;4181.498220213579;4650.162970665279;5433.510984011438;5698.572607054467;5734.397888221734;5855;5870.853228669274;5873.794417195399;5881.117832325153;5898.115282707596;5247.081760691883;5331.946680533203;5350.117230656937;5404.351477822667;5406.02691515529;5357.1696387060065;5368.0605893968395;5396.703181325026;5460.590455715817;5476.644018138828;4236.894077648052;5186.0665080285535;5204.011237864813;5294.285271488338;5338.97330513345;5279.815206467755;5282.841514754564;5298.523132918056;5327;5346.893062138735;5300.966311569207;5311.867203319948;5324.627276090675;5334.03987282289;5359.624826262163;4778.904421911542;5289.259503669489;5850.76596936122;5872.530197584183;5898.469137777621;6175.567710260284;6306.747730090791;6327.651979141146;6332.968335158294;6349.587276827037;6216.036514340284;6233.937660623381;6261.092141639482;6310.9684451577905;6318.33657465964;5880.353161152269;5978.85052873673;5989.071693887455;6006.459418652343;6016.2479690038745;6411.647359395278;6441.903371449432;6459.515536334699;6466.191726034246;6495.935040649533;6081.57428979976;6167.444929956326;6292.779752708633;6297.118403423525;6312.43188113072;5338.492843179892;6219.346968568235;6241.594296370721;6284.24589049317;6329.68351582419;6176.795524872697;6309.495240380758;6312.147860038836;6319.431251187415;6336.904946425807;6290.905636415458;6328.493720502349;6333.429991300805;6355.205599300088;6361.872762544724;4915.311856340115;5013.573846223039;5037.345145131109;5056.342675452242;5116.155834287301;4448.488423831236;4519.977400112978;4530.116627257719;4549.27211646135;4549.7042692224895;5819.476247137778;5876.853078673067;5879.676617785977;5896.3219229788265;5911.497522710618;6129.970128114947;6217.658028808369;6234.220722409699;6239.282482514478;6292.716827742763;4372.825086996548;5206.661566998133;5542.8337149885565;5895.233619629506;5909.8227053188475;5530.364008139116;5568.554515638739;5571;5572.637778544382;5631.2960879890015;6257;6391.552591318656;6434.935650643433;6475.611463312216;6568.671566421755;6310.148130002391;6321.494280457644;6332;6332.556721029668;6340.239171299474;6512.5766825156215;6545.509086818603;6548.214214294315;6553.967230163818;6561.80314590563;6108.524204008529;6116.518381401602;6127.372786728296;6180.381961803783;6254.343293954069;5599.32808063035;5997.13041608973;6263.27972283175;6265.749370025148;6276.529260305404;4841.733704646278;4921.154567037361;5559.499645031967;6150.446459818638;6284.874302514016;5077.593792496666;5525.867197224639;6120.447133218882;6166.475849552839;6195.1636529068;4388.5830846069575;6115.235595550556;6125.540584456273;6138.877222455615;6152.477039451518;4568.040711450632;6066.696665166813;6230.875382492234;6302.842428939313;6309.842253943688;5682.460166284196;6076.939230607637;6099.420555047263;6108.847278818065;6146.678464130234 11:31:40 INFO - PROCESS | 1655 | |1;ext.html;481.96385271104674;513.6789506558401;524.6379997801516;531.7341329335334;572.4475880775057;38915.805420972894;41792;41846;41848.58151418485;41856.79071604642;41918.76015498063;42537.7873110634;42972.57027429725;43021;43033.569664303344;9574.281928855346;9735.561899714494;9764.707058788248;9796.265280103986;9796.922338542754;9658.420494770302;9755.853662195073;9762.560684769167;9764.414135151914;9790.216782657406;9761.902380976171;9766.169875560587;9788.168005719523;9813.65652202173;9830.9508452458;9688.41869487833;9734.41593504392;9736.221102311798;9783.608655653836;9785.951070244675;9689.854652180224;9773.41359518424;9779.853302200401;9786.412815231039;9807.31348805584;40832.591674083174;40881.977950551474;41267.58732412697;41287.174256515005;41378.1724365514;3255.283837555734;3335.4778540828174;3368.191634007845;3426.5031570422148;3656.725745569093;31258.531122033186;31369.36124232354;31848.522272165937;32130.357392852246;32643.020709378758;3189;3191.934487636737;3213.6625654306195;3223.935521289561;3330.7668463207583;493542;496098;497144;499536;499605.9999999964;31593.894213702868;61232;61797;61824;61903.38096619066;33272.83363583167;34077.31845363079;34230.48654270188;34388.48417273743;34581.654183457846;14954.252287385587;14957.102573845605;15152.090874547563;15166;15181.544553663407;12708.936455317664;12799.808002879965;12840.935795320964;12901.870981290254;12910.160839545588;6606.900896486556;7741.806454838562;7787.80530486731;7788.4548081634875;7828.530288182727;3217.694319039734;3229.273413481995;3271.198556353661;3271.214908422009;3293.077938177314;783.7687882074802;982.6708052802392;1016.6543375252302;1061.124572227915;1077.5205033760058;25680.871595642275;30456.390872182874;30464.390712186076;30831.69168308288;31049.223769405944;993.4486360070086;1072.1637123044038;1072.201210098466;1104.9447527623586;1137.5222406589253;15655;15796.447124350823;15836;15856.841431585768;16074.43739469113;505896;537723;583806.7286972096;656222;657241;48431.515684843405;58670.41329586735;59164;59236.70381648064;59255.70372148111;1293.624848793858;1308.9476420943247;1328.4154971812566;1350.73660636175;1351.0272603725498;24329.98555238366;29938.952136675984;30031.399372012;30287.242818929702;31064.689353107085;50714.49285507245;51163.232551512505;51501;51709;51848.22227666664;566709;568164;570434;570602;572928;27004.72995270022;27121;27262.454750904475;27330.1800945964;27350.179494615193;47295;47582.52417475781;47966.4988284555;48961.75519122381;49391.25913111378;39735;39919;40371;41167.588324116376;41199.28504926431;38020.23959520849;39859.60140398559;40778.59221407748;41223;41307;37961.24077518379;38527;38638.840834775;38668.6133138665;39020;34143.82928085344;34729.82635086808;35298.941031769085;35472;35665.46501802527;21102.6957894525;25747;25886.74113258843;25947.35131621649;26006.86996565005 11:31:40 INFO - PROCESS | 1655 | |2;jquery.html;336.9898903032909;379.16394350457244;379.1658351626423;386.73315412365446;404.5347849972531;16015.519534413957;20789.168433262672;21031.47421314468;21691;21905.342839714795;18862.90568547159;20492.385228443134;20660.276890308844;20675.37973860783;21223.575528489422;5102.719350435725;5434.592405569589;5928.466438020578;6208.56540042195;6226.066090086478;6051.27384713835;6143.631382117087;6151.630902145886;6152.077188421727;6172.567920245585;5807.622504537213;6033.758649654009;6097.908531372033;6102.847428814271;6108.877822443548;5963.135345374918;6059.2728872535135;6063.545234107438;6069.059295809113;6083.543734219951;5105.183170692671;5136.460671629462;5177.922331165036;5179.482051794828;5188.974055129738;25854.741452585422;25922.092726754472;26038.86980565085;26043.479130417476;26222.475550489075;2176.3894717908634;2270.9318720438414;2300.5858945389823;2418.6251131074537;2456.115798312606;10635;12876.613701588967;15500.689986200325;15567.532974010797;16051.839481605151;1778.8399044085966;1841.373932862833;1870.6258748250405;1895.5640202753443;1897.2600685732575;86135;121755.56488870262;122947;123923.38038309751;123932.1410178848;9509.66716164931;9576.904230957602;10925.617603383844;10989.230753847314;11207.887921120848;13339.132956357806;13448.260345680887;13578.524751633646;13608.65978350549;13609.31953402326;8330.70842520521;8570.828583428296;8649.91350086491;8737.082606326372;8780.165884240987;7396.593187374746;8639.611217495229;8669.523176225242;8746.457684637731;8863.335249856287;5639.872025594897;6471.352864713491;6510.511711621647;6644.269130395653;6692.564983276072;1823.3344829137534;1866.4587269691908;1869.7569315989106;1870.1116969439408;1871.9812801872079;718.9287960938269;848.1518481518482;903.9231665308522;942.3968660057569;955.3217215776738;15298.770518442232;21850.016749246322;21983.560328793654;22011.559768804535;22135.557288854456;997.0159382828119;1044.4412239451856;1050.3592808386877;1052.6684094510358;1063.5533076108054;11002.665451194429;11449.484773185226;11768.70578235551;11774.646760597196;11803.586874459354;501059;506183;508851.45574271894;580999;605920;131109;140482.89275660872;140919;148423.25788371204;149134;931.6366617019368;963.5375019990444;975.6682727872558;981.2983716642635;982.9459379734184;16388.918055409646;19427.01746142066;19465;19471.123799429344;19477.123529440927;56554;56601.43398565962;57026.714866425405;58242.7087864558;59064;84902.57548712465;85818;86880.5655971716;86889;88032.55983720296;7924.920750792418;8007.55958422269;8173.141820108803;8331.916680833114;8332.50004999702;9365.625374984924;10912.672619821418;12603.495860165858;12913.289769062416;13100.934495327463;10449.373037617766;10695.839562406725;10878.347299162075;11112.944435277772;11339.979401853872;8728.519931403835;10620.734481638021;10630.096441802225;10838.349699018085;11116.49975751077;10529.526171322463;10762.246642735246;10912.672619821418;11050.33697978124;11335.943320283346;7853.0133365312295;8018.594421673358;8075.313598344206;8136.349092072502;8334.6666133354;5390.622656414013;7910.9604451977375;8175.67297308101;8309.626066827128;10668.253222274367 11:31:40 INFO - PROCESS | 1655 | |3;mootools.html;1528.625486755745;1552.9611759706008;1780.2256018631897;1872.5505878589142;1904.295410698042;6610.768623098193;6756.527043106985;8468.788280292996;8907.73276801697;8992.68525601604;7347;7652.693892244318;8786.956065219681;8791.780205494866;8793.120687931203;2048.139781291861;2341.7424083350816;2352.3766201956496;2355.9293221203393;2360.8583484990954;2320.454693147109;2333.9649905251435;2374.2995816234165;2398.4723360860585;2400.8199385046105;2350.118705485443;2375.798057165143;2387.606045002581;2393.461471168982;2398.196604137616;2350.0717216699477;2361.9763802362013;2368.2421625079983;2376.5128148729555;2380.0479808076734;2340.4382948092507;2357.5167090746354;2367.9526409471887;2371.1226846067066;2371.9288421347214;8777.078406767261;8893.688720894737;8893.955530222307;8911.910880891173;8913.420627659181;5256.632035757499;5439.673619582798;5439.945600543983;5441.891162176774;5443.346798384179;3586.246888153491;3602.7838329700303;3643.653852883972;3649.8540058397634;3661.560612726463;1073.0825144501496;1073.8335557988523;1076.612419528969;1082.767205050918;1086.1202426034938;11458.484368203452;11508.136889733303;11534.365609891369;11541.422928853524;11576.826347604709;4552.271636538138;4741.857744267678;4804.135255654016;4839.322494850724;4843.903121937612;9883.505824708736;9951.203903687687;9966.40201587907;9969.700908972742;9977.852546957192;5365.463453654604;5370.973145134328;5384.6499977501335;5391.622586418913;5397.217403476474;4419.292913133883;4785.832495862698;4927.581155601814;4929.802807887644;4937.185364414903;3882;3985.5416627087675;4001.8799436016966;4045.292073887058;4095.3037983542868;1197.1978774221293;1279.4242590834162;1299.5971248912867;1300.1613958996395;1310.7509573181064;859.2438653984453;881.0836729801064;881.3345923827469;899.8470260055675;913.2146951796992;5590.608657393944;5711.600187986801;5783.537317014629;5889.4993925515955;6019.729112189962;912;923.5290002098978;940.7977284883783;944.5359692476634;1013.1489548779061;3122.361604444241;3170.5878235829196;3345.849436775351;3976.622220889011;4038.919221615551;10324.587016519405;10793.67618971432;10806.621768238074;10807.189460790472;10827.404492752974;9058.36591438593;9148.725538233864;10485.737856553647;10784.460776961121;10837.83243351333;6816.931830681729;8025.31784798287;8115.675372985014;8209.179082091863;8237.546934918517;5662.66024038559;5711;5746.885062298814;5860.150278209721;5886.646801191942;9662.951685241529;9775.657851975147;9840.655577055055;9882.752931176778;10006.14947729422;9967.40195588267;10056.094951454405;10142.49287535635;10239.180865530889;10356.896431035593;6570.145881035435;6929.792106236821;7044.823879403056;7068.434525238072;7141.500094993301;8479.745607631781;8566.229039386486;8568;8585;8588.699395521377;6920.688569014305;6989.266127056586;7104.644767761488;7458.925410745823;7699.384049275933;7404.518706284074;7432.442566807403;7451.105867295959;7471;7541.886871697038;6799.558028728117;6804.911214205703;6872.862542749217;6883.449324054164;6893.482988775761;4637.25803871379;4812.903741925212;4879.780409881635;4922.458529561817;4943.703377797344;4501.3022981437925;4509.2559727644575;4581.656375771764;4892.730899800546;4966.180580204225 11:31:40 INFO - PROCESS | 1655 | |4;prototype.html;299.351903129724;304.4960590223181;309.13134093198124;311.8222613110525;312.89518011466157;4974.8507544773665;5451.236826844235;5617.354004289508;5712.228849105375;5750.884982300351;4918.508149185089;5515.422199574354;5592.909382670371;5616;5696.430356964302;3649.525561676978;4299.570042995691;4347.347897815322;4375.6499480041675;4389.046874141009;3436.8453419596176;4138;4299.957000430002;4345.891352716176;4415.20526305265;3657.963420365802;4273.786310684469;4307.956920430803;4315.568443155691;4325.156594464081;3461.446168613035;3661.5972243053243;4177.916441671165;4300.731284271147;4367.235733746582;3083.8127320981357;3122.67211942745;3525.8765943191866;3671.265746850614;3739.364308067635;4761.285807128924;5050.60096454185;5514.558835293207;5582.553395728372;5591.552675785927;4105.835766569334;4516.1419330327135;4798.856034318976;4977.776000080005;5021.824236151717;5216.478352164791;5662.055731019887;5694.5159661428825;5975.671338076404;6056.636601803906;3372.730181585467;3429.6227414984332;4074.124063326369;4087.2234275487563;4101.2412703649625;4954.752262386903;5425.701586412746;6373.9681301593655;6403.711832967481;6465.579737317059;3482.3905816482015;3547.592026916886;3869.7616762635685;4150.584941505826;4165.3963224158915;3860.208657225262;4171.270027745132;4215.852445164404;4224.78876056196;4224.894377640584;4815.614750819926;4930.2851086592245;4937.333459982929;4947.628927830355;4966.701997880139;5489.231507588941;5615;5662.68855212959;5926.762929482772;6122.571420000557;1043.1341986151476;1416.9231384147915;1422.7906279662204;1426.9357878895476;1431.7494438473225;737.2590546500733;847.3508168112875;863.5552690364467;882.3029806452959;885.4023534114447;4054.108096218827;4316.223079845595;4378.292465938287;4455.596487106551;4701.459332176843;4871.3667223261455;4940.813175501781;5148.4257871064465;5149.35220729363;5182.222666600041;3915.3164139419755;4231.365295205744;4315.223259813264;4704.3064496781135;4769.092363054806;2402.4354276744953;2496.988719568578;2529.78496827768;2627.5664515355134;2722.5780004099397;4457.8439754608435;5001.949590586018;5152.6650767699975;5263.447338029452;5375.919361209585;4335.241332766753;4677.924077462135;4761.500042495557;5078.339815823993;5325.8934821303355;4071.3383176263605;4238.85164019264;4923.77842997066;5190.195519694454;5318.73406329682;4491.921938734746;4714.57568818808;4812.903741925072;4820.023790245982;5166.6125040621355;4265.076129960506;4372.693911426297;4388.645852060481;4521.186186486332;4646.907061858811;4641.303804429363;4761.0001899601475;4778.402699662543;5331.653442526223;5362.624616276979;3232.0303908827727;3602.7117830574016;3606.819659016986;3650.2881938021833;3659.1949771050326;4389.890252743707;5060.873478163076;5184.988927159318;5220.295260139833;5256.684598924076;5310.017646735367;5705.400932902152;5745.511631511201;5955.40445955401;6055.878882422415;5030.591434398374;5319.335083114611;5548.250986116823;5783.421657834183;5825.854353641193;5679.971600141973;5794.82615521535;5867.442592953663;5875.353711091862;5983.820485385445;4924.482929292516;5888.466919848057;6004.238050496646;6075.149479072845;6076.210092688099;5101.795928162832;5484.012877682056;5881.7647294107755;5890.6760128191645;5959.553033522417 11:31:40 INFO - PROCESS | 1655 | |5;yui.html;669.4577392312227;705.1150805738797;792.4056957282038;798.461038798811;813.2111851504043;14787.112773233614;15715.057096574214;15989.440369587066;16023.35906563736;16039.51881443557;14379.78430323546;14559.27203639819;14562.854371456282;14564.781528277084;14602.926985365084;691.5090285897019;810.8662070758318;835.2983493865167;859.834923678413;862.2929198057595;676.5839009009449;836.1471299274766;851.5571902610639;854.6495936665968;861.0356400831078;885.5395194498857;895.8297923394534;896.6951236579562;898.784291769977;899.4243684042218;786.8583654942137;893.1068931068932;893.8033632600817;893.8122994171232;895.6999405199266;664.8603793203433;665.940065394112;668.6723505482279;668.9197296324472;670.2861452553016;13819.585412437644;14228.217448040354;14258.714825703428;14264.643383915381;14268.714625707427;13948.721025579533;14097.013209075369;14212.360443780055;14214.289285535786;14339.354729037113;10230.925752795998;11619.186656934018;11675.824862627067;11691.240069395462;11812.645620631394;3737.813109344549;3746.194568167857;3765.5104836371097;3802.524684414448;3830.5211848518934;116088;117817;118838.40580797041;119004.40497797543;119370.80629193684;10724.73188170302;10742.785144297226;10816.72958176036;11020.50407731654;11311.208215424842;22122.668159977613;22128.778712212992;22134.778652213594;22147.889260553915;22581.209657661904;7895.486793358414;7996.440249182618;8036.156203598655;8138.633761480747;8160;4192.80536886044;4379.868603941887;4399.296112621965;4425.402570652985;4507.4365704286965;3556.6798988091195;3643.9817800911355;3660.908477288089;3661.6521430464595;3670.7797532148156;645.6803882078401;649.3084864619166;655.5312951239887;658.5324419662094;670.9295523969913;575.3010092737344;612.3631423319698;622.9408206220402;671.3487916720773;692.7817737412698;7437.479376443597;8075.878861817077;8661.956690216635;9263.212626926786;9340.673076442292;508.9312942752681;622.3154530016946;645.6739346629922;657.4313219065474;693.6809067828855;3927.430522574211;4034.870236333831;4442.42248507692;4542.341360502709;4616.792244349012;118549.81450185388;133486;133574;139590;149669;121523;126107.33091534005;126561.3671931653;127137.36431317784;130200.74498137622;94762.0523794767;107118.46440767903;109991;113593.43203284097;114704;7463.626818658937;9403.106704863027;9558.522073896416;9783;10021.79738431393;60930;62010;62797;63542.3280555535;64562.35437645563;83177.16822831694;93847;98575.50712246393;99615;99921;10756.946215268874;12900.741985160057;13131.343432828511;13297.601071967856;13686.110402823784;14609.780853286997;14683.185416645834;14769.261536923326;14770.409183632535;15352.692946141238;11821;13100.672483187996;13218;13479.730405392034;13602.523911663044;11554.133439991867;12792.936035319764;13483.528076517276;13638.863611363759;14067.929660351632;13205.801912971121;13410.463581456632;13411.731765364833;13417.865821341662;14035.438582456587;10590.311629744043;10813.621523246964;10838.837417438586;10871.891281087088;11455.713607159887;10234.897651023395;10989.560417583207;11048.779024419628;11271.154663400443;11301.15241356885 11:31:40 INFO - PROCESS | 1655 | __end_tp_report 11:31:40 INFO - PROCESS | 1655 | __start_cc_report 11:31:40 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,383 11:31:40 INFO - PROCESS | 1655 | __end_cc_report 11:31:40 INFO - PROCESS | 1655 | __startTimestamp1473186700728__endTimestamp 11:31:40 INFO - PROCESS | 1655 | 11:31:40 INFO - PROCESS | 1655 | ------- Summary: start ------- 11:31:40 INFO - PROCESS | 1655 | Number of tests: 6 11:31:40 INFO - PROCESS | 1655 | 11:31:40 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5736.84 Median:5989.07 stddev:731.50 (12.2%) stddev-sans-first:694.52 11:31:40 INFO - PROCESS | 1655 | Values: 2724.3 2814.2 3413.7 3429.2 3469.5 4181.5 4650.2 5433.5 5698.6 5734.4 5855.0 5870.9 5873.8 5881.1 5898.1 5247.1 5331.9 5350.1 5404.4 5406.0 5357.2 5368.1 5396.7 5460.6 5476.6 4236.9 5186.1 5204.0 5294.3 5339.0 5279.8 5282.8 5298.5 5327.0 5346.9 5301.0 5311.9 5324.6 5334.0 5359.6 4778.9 5289.3 5850.8 5872.5 5898.5 6175.6 6306.7 6327.7 6333.0 6349.6 6216.0 6233.9 6261.1 6311.0 6318.3 5880.4 5978.9 5989.1 6006.5 6016.2 6411.6 6441.9 6459.5 6466.2 6495.9 6081.6 6167.4 6292.8 6297.1 6312.4 5338.5 6219.3 6241.6 6284.2 6329.7 6176.8 6309.5 6312.1 6319.4 6336.9 6290.9 6328.5 6333.4 6355.2 6361.9 4915.3 5013.6 5037.3 5056.3 5116.2 4448.5 4520.0 4530.1 4549.3 4549.7 5819.5 5876.9 5879.7 5896.3 5911.5 6130.0 6217.7 6234.2 6239.3 6292.7 4372.8 5206.7 5542.8 5895.2 5909.8 5530.4 5568.6 5571.0 5572.6 5631.3 6257.0 6391.6 6434.9 6475.6 6568.7 6310.1 6321.5 6332.0 6332.6 6340.2 6512.6 6545.5 6548.2 6554.0 6561.8 6108.5 6116.5 6127.4 6180.4 6254.3 5599.3 5997.1 6263.3 6265.7 6276.5 4841.7 4921.2 5559.5 6150.4 6284.9 5077.6 5525.9 6120.4 6166.5 6195.2 4388.6 6115.2 6125.5 6138.9 6152.5 4568.0 6066.7 6230.9 6302.8 6309.8 5682.5 6076.9 6099.4 6108.8 6146.7 11:31:40 INFO - PROCESS | 1655 | 11:31:40 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:67712.16 Median:40857.28 stddev:148139.96 (362.6%) stddev-sans-first:148469.54 11:31:40 INFO - PROCESS | 1655 | Values: 482.0 513.7 524.6 531.7 572.4 38915.8 41792.0 41846.0 41848.6 41856.8 41918.8 42537.8 42972.6 43021.0 43033.6 9574.3 9735.6 9764.7 9796.3 9796.9 9658.4 9755.9 9762.6 9764.4 9790.2 9761.9 9766.2 9788.2 9813.7 9831.0 9688.4 9734.4 9736.2 9783.6 9786.0 9689.9 9773.4 9779.9 9786.4 9807.3 40832.6 40882.0 41267.6 41287.2 41378.2 3255.3 3335.5 3368.2 3426.5 3656.7 31258.5 31369.4 31848.5 32130.4 32643.0 3189.0 3191.9 3213.7 3223.9 3330.8 493542.0 496098.0 497144.0 499536.0 499606.0 31593.9 61232.0 61797.0 61824.0 61903.4 33272.8 34077.3 34230.5 34388.5 34581.7 14954.3 14957.1 15152.1 15166.0 15181.5 12708.9 12799.8 12840.9 12901.9 12910.2 6606.9 7741.8 7787.8 7788.5 7828.5 3217.7 3229.3 3271.2 3271.2 3293.1 783.8 982.7 1016.7 1061.1 1077.5 25680.9 30456.4 30464.4 30831.7 31049.2 993.4 1072.2 1072.2 1104.9 1137.5 15655.0 15796.4 15836.0 15856.8 16074.4 505896.0 537723.0 583806.7 656222.0 657241.0 48431.5 58670.4 59164.0 59236.7 59255.7 1293.6 1308.9 1328.4 1350.7 1351.0 24330.0 29939.0 30031.4 30287.2 31064.7 50714.5 51163.2 51501.0 51709.0 51848.2 566709.0 568164.0 570434.0 570602.0 572928.0 27004.7 27121.0 27262.5 27330.2 27350.2 47295.0 47582.5 47966.5 48961.8 49391.3 39735.0 39919.0 40371.0 41167.6 41199.3 38020.2 39859.6 40778.6 41223.0 41307.0 37961.2 38527.0 38638.8 38668.6 39020.0 34143.8 34729.8 35298.9 35472.0 35665.5 21102.7 25747.0 25886.7 25947.4 26006.9 11:31:40 INFO - PROCESS | 1655 | 11:31:40 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:34122.70 Median:358.08 stddev:91674.76 (25602.0%) stddev-sans-first:91896.84 11:31:40 INFO - PROCESS | 1655 | Values: 337.0 379.2 379.2 386.7 404.5 16015.5 20789.2 21031.5 21691.0 21905.3 18862.9 20492.4 20660.3 20675.4 21223.6 5102.7 5434.6 5928.5 6208.6 6226.1 6051.3 6143.6 6151.6 6152.1 6172.6 5807.6 6033.8 6097.9 6102.8 6108.9 5963.1 6059.3 6063.5 6069.1 6083.5 5105.2 5136.5 5177.9 5179.5 5189.0 25854.7 25922.1 26038.9 26043.5 26222.5 2176.4 2270.9 2300.6 2418.6 2456.1 10635.0 12876.6 15500.7 15567.5 16051.8 1778.8 1841.4 1870.6 1895.6 1897.3 86135.0 121755.6 122947.0 123923.4 123932.1 9509.7 9576.9 10925.6 10989.2 11207.9 13339.1 13448.3 13578.5 13608.7 13609.3 8330.7 8570.8 8649.9 8737.1 8780.2 7396.6 8639.6 8669.5 8746.5 8863.3 5639.9 6471.4 6510.5 6644.3 6692.6 1823.3 1866.5 1869.8 1870.1 1872.0 718.9 848.2 903.9 942.4 955.3 15298.8 21850.0 21983.6 22011.6 22135.6 997.0 1044.4 1050.4 1052.7 1063.6 11002.7 11449.5 11768.7 11774.6 11803.6 501059.0 506183.0 508851.5 580999.0 605920.0 131109.0 140482.9 140919.0 148423.3 149134.0 931.6 963.5 975.7 981.3 982.9 16388.9 19427.0 19465.0 19471.1 19477.1 56554.0 56601.4 57026.7 58242.7 59064.0 84902.6 85818.0 86880.6 86889.0 88032.6 7924.9 8007.6 8173.1 8331.9 8332.5 9365.6 10912.7 12603.5 12913.3 13100.9 10449.4 10695.8 10878.3 11112.9 11340.0 8728.5 10620.7 10630.1 10838.3 11116.5 10529.5 10762.2 10912.7 11050.3 11335.9 7853.0 8018.6 8075.3 8136.3 8334.7 5390.6 7911.0 8175.7 8309.6 10668.3 11:31:40 INFO - PROCESS | 1655 | 11:31:40 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5626.68 Median:4933.49 stddev:3139.53 (63.6%) stddev-sans-first:3133.24 11:31:40 INFO - PROCESS | 1655 | Values: 1528.6 1553.0 1780.2 1872.6 1904.3 6610.8 6756.5 8468.8 8907.7 8992.7 7347.0 7652.7 8787.0 8791.8 8793.1 2048.1 2341.7 2352.4 2355.9 2360.9 2320.5 2334.0 2374.3 2398.5 2400.8 2350.1 2375.8 2387.6 2393.5 2398.2 2350.1 2362.0 2368.2 2376.5 2380.0 2340.4 2357.5 2368.0 2371.1 2371.9 8777.1 8893.7 8894.0 8911.9 8913.4 5256.6 5439.7 5439.9 5441.9 5443.3 3586.2 3602.8 3643.7 3649.9 3661.6 1073.1 1073.8 1076.6 1082.8 1086.1 11458.5 11508.1 11534.4 11541.4 11576.8 4552.3 4741.9 4804.1 4839.3 4843.9 9883.5 9951.2 9966.4 9969.7 9977.9 5365.5 5371.0 5384.6 5391.6 5397.2 4419.3 4785.8 4927.6 4929.8 4937.2 3882.0 3985.5 4001.9 4045.3 4095.3 1197.2 1279.4 1299.6 1300.2 1310.8 859.2 881.1 881.3 899.8 913.2 5590.6 5711.6 5783.5 5889.5 6019.7 912.0 923.5 940.8 944.5 1013.1 3122.4 3170.6 3345.8 3976.6 4038.9 10324.6 10793.7 10806.6 10807.2 10827.4 9058.4 9148.7 10485.7 10784.5 10837.8 6816.9 8025.3 8115.7 8209.2 8237.5 5662.7 5711.0 5746.9 5860.2 5886.6 9663.0 9775.7 9840.7 9882.8 10006.1 9967.4 10056.1 10142.5 10239.2 10356.9 6570.1 6929.8 7044.8 7068.4 7141.5 8479.7 8566.2 8568.0 8585.0 8588.7 6920.7 6989.3 7104.6 7458.9 7699.4 7404.5 7432.4 7451.1 7471.0 7541.9 6799.6 6804.9 6872.9 6883.4 6893.5 4637.3 4812.9 4879.8 4922.5 4943.7 4501.3 4509.3 4581.7 4892.7 4966.2 11:31:40 INFO - PROCESS | 1655 | 11:31:40 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4440.92 Median:4805.88 stddev:1377.76 (28.7%) stddev-sans-first:1346.10 11:31:40 INFO - PROCESS | 1655 | Values: 299.4 304.5 309.1 311.8 312.9 4974.9 5451.2 5617.4 5712.2 5750.9 4918.5 5515.4 5592.9 5616.0 5696.4 3649.5 4299.6 4347.3 4375.6 4389.0 3436.8 4138.0 4300.0 4345.9 4415.2 3658.0 4273.8 4308.0 4315.6 4325.2 3461.4 3661.6 4177.9 4300.7 4367.2 3083.8 3122.7 3525.9 3671.3 3739.4 4761.3 5050.6 5514.6 5582.6 5591.6 4105.8 4516.1 4798.9 4977.8 5021.8 5216.5 5662.1 5694.5 5975.7 6056.6 3372.7 3429.6 4074.1 4087.2 4101.2 4954.8 5425.7 6374.0 6403.7 6465.6 3482.4 3547.6 3869.8 4150.6 4165.4 3860.2 4171.3 4215.9 4224.8 4224.9 4815.6 4930.3 4937.3 4947.6 4966.7 5489.2 5615.0 5662.7 5926.8 6122.6 1043.1 1416.9 1422.8 1426.9 1431.7 737.3 847.4 863.6 882.3 885.4 4054.1 4316.2 4378.3 4455.6 4701.5 4871.4 4940.8 5148.4 5149.4 5182.2 3915.3 4231.4 4315.2 4704.3 4769.1 2402.4 2497.0 2529.8 2627.6 2722.6 4457.8 5001.9 5152.7 5263.4 5375.9 4335.2 4677.9 4761.5 5078.3 5325.9 4071.3 4238.9 4923.8 5190.2 5318.7 4491.9 4714.6 4812.9 4820.0 5166.6 4265.1 4372.7 4388.6 4521.2 4646.9 4641.3 4761.0 4778.4 5331.7 5362.6 3232.0 3602.7 3606.8 3650.3 3659.2 4389.9 5060.9 5185.0 5220.3 5256.7 5310.0 5705.4 5745.5 5955.4 6055.9 5030.6 5319.3 5548.3 5783.4 5825.9 5680.0 5794.8 5867.4 5875.4 5983.8 4924.5 5888.5 6004.2 6075.1 6076.2 5101.8 5484.0 5881.8 5890.7 5959.6 11:31:40 INFO - PROCESS | 1655 | 11:31:40 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:24618.75 Median:3600.33 stddev:38917.64 (1080.9%) stddev-sans-first:38985.27 11:31:40 INFO - PROCESS | 1655 | Values: 669.5 705.1 792.4 798.5 813.2 14787.1 15715.1 15989.4 16023.4 16039.5 14379.8 14559.3 14562.9 14564.8 14602.9 691.5 810.9 835.3 859.8 862.3 676.6 836.1 851.6 854.6 861.0 885.5 895.8 896.7 898.8 899.4 786.9 893.1 893.8 893.8 895.7 664.9 665.9 668.7 668.9 670.3 13819.6 14228.2 14258.7 14264.6 14268.7 13948.7 14097.0 14212.4 14214.3 14339.4 10230.9 11619.2 11675.8 11691.2 11812.6 3737.8 3746.2 3765.5 3802.5 3830.5 116088.0 117817.0 118838.4 119004.4 119370.8 10724.7 10742.8 10816.7 11020.5 11311.2 22122.7 22128.8 22134.8 22147.9 22581.2 7895.5 7996.4 8036.2 8138.6 8160.0 4192.8 4379.9 4399.3 4425.4 4507.4 3556.7 3644.0 3660.9 3661.7 3670.8 645.7 649.3 655.5 658.5 670.9 575.3 612.4 622.9 671.3 692.8 7437.5 8075.9 8662.0 9263.2 9340.7 508.9 622.3 645.7 657.4 693.7 3927.4 4034.9 4442.4 4542.3 4616.8 118549.8 133486.0 133574.0 139590.0 149669.0 121523.0 126107.3 126561.4 127137.4 130200.7 94762.1 107118.5 109991.0 113593.4 114704.0 7463.6 9403.1 9558.5 9783.0 10021.8 60930.0 62010.0 62797.0 63542.3 64562.4 83177.2 93847.0 98575.5 99615.0 99921.0 10756.9 12900.7 13131.3 13297.6 13686.1 14609.8 14683.2 14769.3 14770.4 15352.7 11821.0 13100.7 13218.0 13479.7 13602.5 11554.1 12792.9 13483.5 13638.9 14067.9 13205.8 13410.5 13411.7 13417.9 14035.4 10590.3 10813.6 10838.8 10871.9 11455.7 10234.9 10989.6 11048.8 11271.2 11301.2 11:31:40 INFO - PROCESS | 1655 | -------- Summary: end -------- 11:31:40 INFO - PROCESS | 1655 | 11:31:41 INFO - TEST-INFO | 1655: exit 0 11:31:41 INFO - TEST-OK | dromaeo_css | took 1114890ms 11:31:41 INFO - TEST-START | kraken 11:31:41 INFO - operating with platform_type : mac_ 11:31:41 INFO - Initialising browser for kraken test... 11:31:41 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpr9N_Ax/profile http://localhost:49283/getInfo.html) 11:31:44 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 11:31:44 INFO - PROCESS | 1668 | colorDepth:24 11:31:44 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 11:31:44 INFO - PROCESS | 1668 | __metrics 11:31:44 ERROR - PROCESS | 1668 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 11:31:45 INFO - TEST-INFO | 1668: exit 0 11:31:45 INFO - Browser initialized. 11:31:45 INFO - Running cycle 1/1 for kraken test... 11:31:45 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpr9N_Ax/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:31:57 INFO - PROCESS | 1670 | RSS: Main: 243630080 11:31:57 INFO - PROCESS | 1670 | 11:32:00 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection) 11:32:01 INFO - PROCESS | 1670 | RSS: Main: 256757760 11:32:01 INFO - PROCESS | 1670 | 11:32:03 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-dft) 11:32:04 INFO - PROCESS | 1670 | RSS: Main: 265519104 11:32:04 INFO - PROCESS | 1670 | 11:32:07 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-fft) 11:32:08 INFO - PROCESS | 1670 | RSS: Main: 263237632 11:32:08 INFO - PROCESS | 1670 | 11:32:10 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator) 11:32:11 INFO - PROCESS | 1670 | RSS: Main: 265502720 11:32:11 INFO - PROCESS | 1670 | 11:32:13 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:32:14 INFO - PROCESS | 1670 | RSS: Main: 265879552 11:32:14 INFO - PROCESS | 1670 | 11:32:16 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom) 11:32:17 INFO - PROCESS | 1670 | RSS: Main: 266457088 11:32:17 INFO - PROCESS | 1670 | 11:32:20 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate) 11:32:21 INFO - PROCESS | 1670 | RSS: Main: 266801152 11:32:21 INFO - PROCESS | 1670 | 11:32:24 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial) 11:32:24 INFO - PROCESS | 1670 | RSS: Main: 266829824 11:32:24 INFO - PROCESS | 1670 | 11:32:26 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:32:27 INFO - PROCESS | 1670 | RSS: Main: 266739712 11:32:27 INFO - PROCESS | 1670 | 11:32:29 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:32:29 INFO - PROCESS | 1670 | RSS: Main: 266592256 11:32:29 INFO - PROCESS | 1670 | 11:32:32 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:32:32 INFO - PROCESS | 1670 | RSS: Main: 266665984 11:32:32 INFO - PROCESS | 1670 | 11:32:35 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:32:36 INFO - PROCESS | 1670 | RSS: Main: 266805248 11:32:36 INFO - PROCESS | 1670 | 11:32:39 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:32:39 INFO - PROCESS | 1670 | RSS: Main: 266985472 11:32:39 INFO - PROCESS | 1670 | 11:32:41 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49283/tests/kraken/driver.html?testName=ai-astar) 11:32:41 INFO - PROCESS | 1670 | RSS: Main: 269008896 11:32:41 INFO - PROCESS | 1670 | 11:32:41 INFO - PROCESS | 1670 | __start_tp_report 11:32:41 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 11:32:41 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 11:32:41 INFO - PROCESS | 1670 | |i|pagename|runs| 11:32:41 INFO - PROCESS | 1670 | |0;ai-astar;107;125;163;113;125;136;118;117;117;136 11:32:41 INFO - PROCESS | 1670 | |1;audio-beat-detection;140;113;132;160;114;134;129;106;164;100 11:32:41 INFO - PROCESS | 1670 | |2;audio-dft;198;210;281;268;195;201;200;222;274;195 11:32:41 INFO - PROCESS | 1670 | |3;audio-fft;70;107;106;138;84;83;82;68;132;59 11:32:41 INFO - PROCESS | 1670 | |4;audio-oscillator;77;106;141;69;70;92;106;102;108;147 11:32:41 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;103;86;87;86;113;74;79;133;135;85 11:32:41 INFO - PROCESS | 1670 | |6;imaging-darkroom;97;99;99;99;113;98;97;129;169;106 11:32:41 INFO - PROCESS | 1670 | |7;imaging-desaturate;76;153;145;91;90;92;139;92;90;95 11:32:41 INFO - PROCESS | 1670 | |8;json-parse-financial;56;58;58;58;58;58;59;60;70;82 11:32:41 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;80;105;60;96;112;111;92;72;69;71 11:32:41 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;89;78;97;110;85;85;78;95;105;100 11:32:41 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;138;145;154;154;143;138;141;150;190;141 11:32:41 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;172;176;184;239;176;171;172;172;172;176 11:32:41 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;71;69;68;69;68;69;70;67;76;69 11:32:41 INFO - PROCESS | 1670 | __end_tp_report 11:32:41 INFO - PROCESS | 1670 | __start_cc_report 11:32:41 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,972 11:32:41 INFO - PROCESS | 1670 | __end_cc_report 11:32:41 INFO - PROCESS | 1670 | __startTimestamp1473186761953__endTimestamp 11:32:41 INFO - PROCESS | 1670 | 11:32:41 INFO - PROCESS | 1670 | ------- Summary: start ------- 11:32:41 INFO - PROCESS | 1670 | Number of tests: 14 11:32:41 INFO - PROCESS | 1670 | 11:32:41 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:125.70 Median:125.00 stddev:16.08 (12.9%) stddev-sans-first:15.56 11:32:41 INFO - PROCESS | 1670 | Values: 107.0 125.0 163.0 113.0 125.0 136.0 118.0 117.0 117.0 136.0 11:32:41 INFO - PROCESS | 1670 | 11:32:41 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:129.20 Median:133.00 stddev:21.58 (16.2%) stddev-sans-first:22.53 11:32:41 INFO - PROCESS | 1670 | Values: 140.0 113.0 132.0 160.0 114.0 134.0 129.0 106.0 164.0 100.0 11:32:41 INFO - PROCESS | 1670 | 11:32:41 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:224.40 Median:216.00 stddev:35.51 (16.4%) stddev-sans-first:36.35 11:32:41 INFO - PROCESS | 1670 | Values: 198.0 210.0 281.0 268.0 195.0 201.0 200.0 222.0 274.0 195.0 11:32:41 INFO - PROCESS | 1670 | 11:32:41 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:92.90 Median:69.00 stddev:26.92 (39.0%) stddev-sans-first:27.25 11:32:41 INFO - PROCESS | 1670 | Values: 70.0 107.0 106.0 138.0 84.0 83.0 82.0 68.0 132.0 59.0 11:32:41 INFO - PROCESS | 1670 | 11:32:41 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:101.80 Median:108.00 stddev:26.77 (24.8%) stddev-sans-first:26.85 11:32:41 INFO - PROCESS | 1670 | Values: 77.0 106.0 141.0 69.0 70.0 92.0 106.0 102.0 108.0 147.0 11:32:41 INFO - PROCESS | 1670 | 11:32:41 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:98.10 Median:82.00 stddev:22.01 (26.8%) stddev-sans-first:23.27 11:32:41 INFO - PROCESS | 1670 | Values: 103.0 86.0 87.0 86.0 113.0 74.0 79.0 133.0 135.0 85.0 11:32:41 INFO - PROCESS | 1670 | 11:32:41 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:110.60 Median:97.50 stddev:22.87 (23.5%) stddev-sans-first:23.72 11:32:41 INFO - PROCESS | 1670 | Values: 97.0 99.0 99.0 99.0 113.0 98.0 97.0 129.0 169.0 106.0 11:32:41 INFO - PROCESS | 1670 | 11:32:41 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:106.30 Median:90.50 stddev:27.83 (30.7%) stddev-sans-first:27.27 11:32:41 INFO - PROCESS | 1670 | Values: 76.0 153.0 145.0 91.0 90.0 92.0 139.0 92.0 90.0 95.0 11:32:41 INFO - PROCESS | 1670 | 11:32:41 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:61.70 Median:58.50 stddev:8.11 (13.9%) stddev-sans-first:8.34 11:32:41 INFO - PROCESS | 1670 | Values: 56.0 58.0 58.0 58.0 58.0 58.0 59.0 60.0 70.0 82.0 11:32:41 INFO - PROCESS | 1670 | 11:32:41 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:86.80 Median:71.50 stddev:18.90 (26.4%) stddev-sans-first:19.88 11:32:41 INFO - PROCESS | 1670 | Values: 80.0 105.0 60.0 96.0 112.0 111.0 92.0 72.0 69.0 71.0 11:32:41 INFO - PROCESS | 1670 | 11:32:41 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:92.20 Median:85.00 stddev:11.00 (12.9%) stddev-sans-first:11.61 11:32:41 INFO - PROCESS | 1670 | Values: 89.0 78.0 97.0 110.0 85.0 85.0 78.0 95.0 105.0 100.0 11:32:41 INFO - PROCESS | 1670 | 11:32:41 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:149.40 Median:147.50 stddev:15.46 (10.5%) stddev-sans-first:15.84 11:32:41 INFO - PROCESS | 1670 | Values: 138.0 145.0 154.0 154.0 143.0 138.0 141.0 150.0 190.0 141.0 11:32:41 INFO - PROCESS | 1670 | 11:32:41 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.00 Median:176.00 stddev:20.74 (11.8%) stddev-sans-first:21.74 11:32:41 INFO - PROCESS | 1670 | Values: 172.0 176.0 184.0 239.0 176.0 171.0 172.0 172.0 172.0 176.0 11:32:41 INFO - PROCESS | 1670 | 11:32:41 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.60 Median:69.00 stddev:2.50 (3.6%) stddev-sans-first:2.60 11:32:41 INFO - PROCESS | 1670 | Values: 71.0 69.0 68.0 69.0 68.0 69.0 70.0 67.0 76.0 69.0 11:32:41 INFO - PROCESS | 1670 | -------- Summary: end -------- 11:32:41 INFO - PROCESS | 1670 | 11:32:42 INFO - TEST-INFO | 1670: exit 0 11:32:42 INFO - TEST-OK | kraken | took 61444ms 11:32:42 INFO - SUITE-END | took 1176s 11:32:43 INFO - Completed test suite (00:19:36) 11:32:43 INFO - javascript benchmark 11:32:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7251.186757814382, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2724.2644485988794, 2814.226087825846, 3413.6927676509113, 3429.2112814052766, 3469.4622333538305, 4181.498220213579, 4650.162970665279, 5433.510984011438, 5698.572607054467, 5734.397888221734, 5855.0, 5870.853228669274, 5873.794417195399, 5881.117832325153, 5898.115282707596, 5247.081760691883, 5331.946680533203, 5350.117230656937, 5404.351477822667, 5406.02691515529, 5357.1696387060065, 5368.0605893968395, 5396.703181325026, 5460.590455715817, 5476.644018138828, 4236.894077648052, 5186.0665080285535, 5204.011237864813, 5294.285271488338, 5338.97330513345, 5279.815206467755, 5282.841514754564, 5298.523132918056, 5327.0, 5346.893062138735, 5300.966311569207, 5311.867203319948, 5324.627276090675, 5334.03987282289, 5359.624826262163, 4778.904421911542, 5289.259503669489, 5850.76596936122, 5872.530197584183, 5898.469137777621, 6175.567710260284, 6306.747730090791, 6327.651979141146, 6332.968335158294, 6349.587276827037, 6216.036514340284, 6233.937660623381, 6261.092141639482, 6310.9684451577905, 6318.33657465964, 5880.353161152269, 5978.85052873673, 5989.071693887455, 6006.459418652343, 6016.2479690038745, 6411.647359395278, 6441.903371449432, 6459.515536334699, 6466.191726034246, 6495.935040649533, 6081.57428979976, 6167.444929956326, 6292.779752708633, 6297.118403423525, 6312.43188113072, 5338.492843179892, 6219.346968568235, 6241.594296370721, 6284.24589049317, 6329.68351582419, 6176.795524872697, 6309.495240380758, 6312.147860038836, 6319.431251187415, 6336.904946425807, 6290.905636415458, 6328.493720502349, 6333.429991300805, 6355.205599300088, 6361.872762544724, 4915.311856340115, 5013.573846223039, 5037.345145131109, 5056.342675452242, 5116.155834287301, 4448.488423831236, 4519.977400112978, 4530.116627257719, 4549.27211646135, 4549.7042692224895, 5819.476247137778, 5876.853078673067, 5879.676617785977, 5896.3219229788265, 5911.497522710618, 6129.970128114947, 6217.658028808369, 6234.220722409699, 6239.282482514478, 6292.716827742763, 4372.825086996548, 5206.661566998133, 5542.8337149885565, 5895.233619629506, 5909.8227053188475, 5530.364008139116, 5568.554515638739, 5571.0, 5572.637778544382, 5631.2960879890015, 6257.0, 6391.552591318656, 6434.935650643433, 6475.611463312216, 6568.671566421755, 6310.148130002391, 6321.494280457644, 6332.0, 6332.556721029668, 6340.239171299474, 6512.5766825156215, 6545.509086818603, 6548.214214294315, 6553.967230163818, 6561.80314590563, 6108.524204008529, 6116.518381401602, 6127.372786728296, 6180.381961803783, 6254.343293954069, 5599.32808063035, 5997.13041608973, 6263.27972283175, 6265.749370025148, 6276.529260305404, 4841.733704646278, 4921.154567037361, 5559.499645031967, 6150.446459818638, 6284.874302514016, 5077.593792496666, 5525.867197224639, 6120.447133218882, 6166.475849552839, 6195.1636529068, 4388.5830846069575, 6115.235595550556, 6125.540584456273, 6138.877222455615, 6152.477039451518, 4568.040711450632, 6066.696665166813, 6230.875382492234, 6302.842428939313, 6309.842253943688, 5682.460166284196, 6076.939230607637, 6099.420555047263, 6108.847278818065, 6146.678464130234], "value": 5690.2488353297385, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.96385271104674, 513.6789506558401, 524.6379997801516, 531.7341329335334, 572.4475880775057, 38915.805420972894, 41792.0, 41846.0, 41848.58151418485, 41856.79071604642, 41918.76015498063, 42537.7873110634, 42972.57027429725, 43021.0, 43033.569664303344, 9574.281928855346, 9735.561899714494, 9764.707058788248, 9796.265280103986, 9796.922338542754, 9658.420494770302, 9755.853662195073, 9762.560684769167, 9764.414135151914, 9790.216782657406, 9761.902380976171, 9766.169875560587, 9788.168005719523, 9813.65652202173, 9830.9508452458, 9688.41869487833, 9734.41593504392, 9736.221102311798, 9783.608655653836, 9785.951070244675, 9689.854652180224, 9773.41359518424, 9779.853302200401, 9786.412815231039, 9807.31348805584, 40832.591674083174, 40881.977950551474, 41267.58732412697, 41287.174256515005, 41378.1724365514, 3255.283837555734, 3335.4778540828174, 3368.191634007845, 3426.5031570422148, 3656.725745569093, 31258.531122033186, 31369.36124232354, 31848.522272165937, 32130.357392852246, 32643.020709378758, 3189.0, 3191.934487636737, 3213.6625654306195, 3223.935521289561, 3330.7668463207583, 493542.0, 496098.0, 497144.0, 499536.0, 499605.9999999964, 31593.894213702868, 61232.0, 61797.0, 61824.0, 61903.38096619066, 33272.83363583167, 34077.31845363079, 34230.48654270188, 34388.48417273743, 34581.654183457846, 14954.252287385587, 14957.102573845605, 15152.090874547563, 15166.0, 15181.544553663407, 12708.936455317664, 12799.808002879965, 12840.935795320964, 12901.870981290254, 12910.160839545588, 6606.900896486556, 7741.806454838562, 7787.80530486731, 7788.4548081634875, 7828.530288182727, 3217.694319039734, 3229.273413481995, 3271.198556353661, 3271.214908422009, 3293.077938177314, 783.7687882074802, 982.6708052802392, 1016.6543375252302, 1061.124572227915, 1077.5205033760058, 25680.871595642275, 30456.390872182874, 30464.390712186076, 30831.69168308288, 31049.223769405944, 993.4486360070086, 1072.1637123044038, 1072.201210098466, 1104.9447527623586, 1137.5222406589253, 15655.0, 15796.447124350823, 15836.0, 15856.841431585768, 16074.43739469113, 505896.0, 537723.0, 583806.7286972096, 656222.0, 657241.0, 48431.515684843405, 58670.41329586735, 59164.0, 59236.70381648064, 59255.70372148111, 1293.624848793858, 1308.9476420943247, 1328.4154971812566, 1350.73660636175, 1351.0272603725498, 24329.98555238366, 29938.952136675984, 30031.399372012, 30287.242818929702, 31064.689353107085, 50714.49285507245, 51163.232551512505, 51501.0, 51709.0, 51848.22227666664, 566709.0, 568164.0, 570434.0, 570602.0, 572928.0, 27004.72995270022, 27121.0, 27262.454750904475, 27330.1800945964, 27350.179494615193, 47295.0, 47582.52417475781, 47966.4988284555, 48961.75519122381, 49391.25913111378, 39735.0, 39919.0, 40371.0, 41167.588324116376, 41199.28504926431, 38020.23959520849, 39859.60140398559, 40778.59221407748, 41223.0, 41307.0, 37961.24077518379, 38527.0, 38638.840834775, 38668.6133138665, 39020.0, 34143.82928085344, 34729.82635086808, 35298.941031769085, 35472.0, 35665.46501802527, 21102.6957894525, 25747.0, 25886.74113258843, 25947.35131621649, 26006.86996565005], "value": 18788.671540598854, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.9898903032909, 379.16394350457244, 379.1658351626423, 386.73315412365446, 404.5347849972531, 16015.519534413957, 20789.168433262672, 21031.47421314468, 21691.0, 21905.342839714795, 18862.90568547159, 20492.385228443134, 20660.276890308844, 20675.37973860783, 21223.575528489422, 5102.719350435725, 5434.592405569589, 5928.466438020578, 6208.56540042195, 6226.066090086478, 6051.27384713835, 6143.631382117087, 6151.630902145886, 6152.077188421727, 6172.567920245585, 5807.622504537213, 6033.758649654009, 6097.908531372033, 6102.847428814271, 6108.877822443548, 5963.135345374918, 6059.2728872535135, 6063.545234107438, 6069.059295809113, 6083.543734219951, 5105.183170692671, 5136.460671629462, 5177.922331165036, 5179.482051794828, 5188.974055129738, 25854.741452585422, 25922.092726754472, 26038.86980565085, 26043.479130417476, 26222.475550489075, 2176.3894717908634, 2270.9318720438414, 2300.5858945389823, 2418.6251131074537, 2456.115798312606, 10635.0, 12876.613701588967, 15500.689986200325, 15567.532974010797, 16051.839481605151, 1778.8399044085966, 1841.373932862833, 1870.6258748250405, 1895.5640202753443, 1897.2600685732575, 86135.0, 121755.56488870262, 122947.0, 123923.38038309751, 123932.1410178848, 9509.66716164931, 9576.904230957602, 10925.617603383844, 10989.230753847314, 11207.887921120848, 13339.132956357806, 13448.260345680887, 13578.524751633646, 13608.65978350549, 13609.31953402326, 8330.70842520521, 8570.828583428296, 8649.91350086491, 8737.082606326372, 8780.165884240987, 7396.593187374746, 8639.611217495229, 8669.523176225242, 8746.457684637731, 8863.335249856287, 5639.872025594897, 6471.352864713491, 6510.511711621647, 6644.269130395653, 6692.564983276072, 1823.3344829137534, 1866.4587269691908, 1869.7569315989106, 1870.1116969439408, 1871.9812801872079, 718.9287960938269, 848.1518481518482, 903.9231665308522, 942.3968660057569, 955.3217215776738, 15298.770518442232, 21850.016749246322, 21983.560328793654, 22011.559768804535, 22135.557288854456, 997.0159382828119, 1044.4412239451856, 1050.3592808386877, 1052.6684094510358, 1063.5533076108054, 11002.665451194429, 11449.484773185226, 11768.70578235551, 11774.646760597196, 11803.586874459354, 501059.0, 506183.0, 508851.45574271894, 580999.0, 605920.0, 131109.0, 140482.89275660872, 140919.0, 148423.25788371204, 149134.0, 931.6366617019368, 963.5375019990444, 975.6682727872558, 981.2983716642635, 982.9459379734184, 16388.918055409646, 19427.01746142066, 19465.0, 19471.123799429344, 19477.123529440927, 56554.0, 56601.43398565962, 57026.714866425405, 58242.7087864558, 59064.0, 84902.57548712465, 85818.0, 86880.5655971716, 86889.0, 88032.55983720296, 7924.920750792418, 8007.55958422269, 8173.141820108803, 8331.916680833114, 8332.50004999702, 9365.625374984924, 10912.672619821418, 12603.495860165858, 12913.289769062416, 13100.934495327463, 10449.373037617766, 10695.839562406725, 10878.347299162075, 11112.944435277772, 11339.979401853872, 8728.519931403835, 10620.734481638021, 10630.096441802225, 10838.349699018085, 11116.49975751077, 10529.526171322463, 10762.246642735246, 10912.672619821418, 11050.33697978124, 11335.943320283346, 7853.0133365312295, 8018.594421673358, 8075.313598344206, 8136.349092072502, 8334.6666133354, 5390.622656414013, 7910.9604451977375, 8175.67297308101, 8309.626066827128, 10668.253222274367], "value": 9648.735817687962, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1528.625486755745, 1552.9611759706008, 1780.2256018631897, 1872.5505878589142, 1904.295410698042, 6610.768623098193, 6756.527043106985, 8468.788280292996, 8907.73276801697, 8992.68525601604, 7347.0, 7652.693892244318, 8786.956065219681, 8791.780205494866, 8793.120687931203, 2048.139781291861, 2341.7424083350816, 2352.3766201956496, 2355.9293221203393, 2360.8583484990954, 2320.454693147109, 2333.9649905251435, 2374.2995816234165, 2398.4723360860585, 2400.8199385046105, 2350.118705485443, 2375.798057165143, 2387.606045002581, 2393.461471168982, 2398.196604137616, 2350.0717216699477, 2361.9763802362013, 2368.2421625079983, 2376.5128148729555, 2380.0479808076734, 2340.4382948092507, 2357.5167090746354, 2367.9526409471887, 2371.1226846067066, 2371.9288421347214, 8777.078406767261, 8893.688720894737, 8893.955530222307, 8911.910880891173, 8913.420627659181, 5256.632035757499, 5439.673619582798, 5439.945600543983, 5441.891162176774, 5443.346798384179, 3586.246888153491, 3602.7838329700303, 3643.653852883972, 3649.8540058397634, 3661.560612726463, 1073.0825144501496, 1073.8335557988523, 1076.612419528969, 1082.767205050918, 1086.1202426034938, 11458.484368203452, 11508.136889733303, 11534.365609891369, 11541.422928853524, 11576.826347604709, 4552.271636538138, 4741.857744267678, 4804.135255654016, 4839.322494850724, 4843.903121937612, 9883.505824708736, 9951.203903687687, 9966.40201587907, 9969.700908972742, 9977.852546957192, 5365.463453654604, 5370.973145134328, 5384.6499977501335, 5391.622586418913, 5397.217403476474, 4419.292913133883, 4785.832495862698, 4927.581155601814, 4929.802807887644, 4937.185364414903, 3882.0, 3985.5416627087675, 4001.8799436016966, 4045.292073887058, 4095.3037983542868, 1197.1978774221293, 1279.4242590834162, 1299.5971248912867, 1300.1613958996395, 1310.7509573181064, 859.2438653984453, 881.0836729801064, 881.3345923827469, 899.8470260055675, 913.2146951796992, 5590.608657393944, 5711.600187986801, 5783.537317014629, 5889.4993925515955, 6019.729112189962, 912.0, 923.5290002098978, 940.7977284883783, 944.5359692476634, 1013.1489548779061, 3122.361604444241, 3170.5878235829196, 3345.849436775351, 3976.622220889011, 4038.919221615551, 10324.587016519405, 10793.67618971432, 10806.621768238074, 10807.189460790472, 10827.404492752974, 9058.36591438593, 9148.725538233864, 10485.737856553647, 10784.460776961121, 10837.83243351333, 6816.931830681729, 8025.31784798287, 8115.675372985014, 8209.179082091863, 8237.546934918517, 5662.66024038559, 5711.0, 5746.885062298814, 5860.150278209721, 5886.646801191942, 9662.951685241529, 9775.657851975147, 9840.655577055055, 9882.752931176778, 10006.14947729422, 9967.40195588267, 10056.094951454405, 10142.49287535635, 10239.180865530889, 10356.896431035593, 6570.145881035435, 6929.792106236821, 7044.823879403056, 7068.434525238072, 7141.500094993301, 8479.745607631781, 8566.229039386486, 8568.0, 8585.0, 8588.699395521377, 6920.688569014305, 6989.266127056586, 7104.644767761488, 7458.925410745823, 7699.384049275933, 7404.518706284074, 7432.442566807403, 7451.105867295959, 7471.0, 7541.886871697038, 6799.558028728117, 6804.911214205703, 6872.862542749217, 6883.449324054164, 6893.482988775761, 4637.25803871379, 4812.903741925212, 4879.780409881635, 4922.458529561817, 4943.703377797344, 4501.3022981437925, 4509.2559727644575, 4581.656375771764, 4892.730899800546, 4966.180580204225], "value": 4544.553921089879, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.351903129724, 304.4960590223181, 309.13134093198124, 311.8222613110525, 312.89518011466157, 4974.8507544773665, 5451.236826844235, 5617.354004289508, 5712.228849105375, 5750.884982300351, 4918.508149185089, 5515.422199574354, 5592.909382670371, 5616.0, 5696.430356964302, 3649.525561676978, 4299.570042995691, 4347.347897815322, 4375.6499480041675, 4389.046874141009, 3436.8453419596176, 4138.0, 4299.957000430002, 4345.891352716176, 4415.20526305265, 3657.963420365802, 4273.786310684469, 4307.956920430803, 4315.568443155691, 4325.156594464081, 3461.446168613035, 3661.5972243053243, 4177.916441671165, 4300.731284271147, 4367.235733746582, 3083.8127320981357, 3122.67211942745, 3525.8765943191866, 3671.265746850614, 3739.364308067635, 4761.285807128924, 5050.60096454185, 5514.558835293207, 5582.553395728372, 5591.552675785927, 4105.835766569334, 4516.1419330327135, 4798.856034318976, 4977.776000080005, 5021.824236151717, 5216.478352164791, 5662.055731019887, 5694.5159661428825, 5975.671338076404, 6056.636601803906, 3372.730181585467, 3429.6227414984332, 4074.124063326369, 4087.2234275487563, 4101.2412703649625, 4954.752262386903, 5425.701586412746, 6373.9681301593655, 6403.711832967481, 6465.579737317059, 3482.3905816482015, 3547.592026916886, 3869.7616762635685, 4150.584941505826, 4165.3963224158915, 3860.208657225262, 4171.270027745132, 4215.852445164404, 4224.78876056196, 4224.894377640584, 4815.614750819926, 4930.2851086592245, 4937.333459982929, 4947.628927830355, 4966.701997880139, 5489.231507588941, 5615.0, 5662.68855212959, 5926.762929482772, 6122.571420000557, 1043.1341986151476, 1416.9231384147915, 1422.7906279662204, 1426.9357878895476, 1431.7494438473225, 737.2590546500733, 847.3508168112875, 863.5552690364467, 882.3029806452959, 885.4023534114447, 4054.108096218827, 4316.223079845595, 4378.292465938287, 4455.596487106551, 4701.459332176843, 4871.3667223261455, 4940.813175501781, 5148.4257871064465, 5149.35220729363, 5182.222666600041, 3915.3164139419755, 4231.365295205744, 4315.223259813264, 4704.3064496781135, 4769.092363054806, 2402.4354276744953, 2496.988719568578, 2529.78496827768, 2627.5664515355134, 2722.5780004099397, 4457.8439754608435, 5001.949590586018, 5152.6650767699975, 5263.447338029452, 5375.919361209585, 4335.241332766753, 4677.924077462135, 4761.500042495557, 5078.339815823993, 5325.8934821303355, 4071.3383176263605, 4238.85164019264, 4923.77842997066, 5190.195519694454, 5318.73406329682, 4491.921938734746, 4714.57568818808, 4812.903741925072, 4820.023790245982, 5166.6125040621355, 4265.076129960506, 4372.693911426297, 4388.645852060481, 4521.186186486332, 4646.907061858811, 4641.303804429363, 4761.0001899601475, 4778.402699662543, 5331.653442526223, 5362.624616276979, 3232.0303908827727, 3602.7117830574016, 3606.819659016986, 3650.2881938021833, 3659.1949771050326, 4389.890252743707, 5060.873478163076, 5184.988927159318, 5220.295260139833, 5256.684598924076, 5310.017646735367, 5705.400932902152, 5745.511631511201, 5955.40445955401, 6055.878882422415, 5030.591434398374, 5319.335083114611, 5548.250986116823, 5783.421657834183, 5825.854353641193, 5679.971600141973, 5794.82615521535, 5867.442592953663, 5875.353711091862, 5983.820485385445, 4924.482929292516, 5888.466919848057, 6004.238050496646, 6075.149479072845, 6076.210092688099, 5101.795928162832, 5484.012877682056, 5881.7647294107755, 5890.6760128191645, 5959.553033522417], "value": 4006.7567661113567, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [669.4577392312227, 705.1150805738797, 792.4056957282038, 798.461038798811, 813.2111851504043, 14787.112773233614, 15715.057096574214, 15989.440369587066, 16023.35906563736, 16039.51881443557, 14379.78430323546, 14559.27203639819, 14562.854371456282, 14564.781528277084, 14602.926985365084, 691.5090285897019, 810.8662070758318, 835.2983493865167, 859.834923678413, 862.2929198057595, 676.5839009009449, 836.1471299274766, 851.5571902610639, 854.6495936665968, 861.0356400831078, 885.5395194498857, 895.8297923394534, 896.6951236579562, 898.784291769977, 899.4243684042218, 786.8583654942137, 893.1068931068932, 893.8033632600817, 893.8122994171232, 895.6999405199266, 664.8603793203433, 665.940065394112, 668.6723505482279, 668.9197296324472, 670.2861452553016, 13819.585412437644, 14228.217448040354, 14258.714825703428, 14264.643383915381, 14268.714625707427, 13948.721025579533, 14097.013209075369, 14212.360443780055, 14214.289285535786, 14339.354729037113, 10230.925752795998, 11619.186656934018, 11675.824862627067, 11691.240069395462, 11812.645620631394, 3737.813109344549, 3746.194568167857, 3765.5104836371097, 3802.524684414448, 3830.5211848518934, 116088.0, 117817.0, 118838.40580797041, 119004.40497797543, 119370.80629193684, 10724.73188170302, 10742.785144297226, 10816.72958176036, 11020.50407731654, 11311.208215424842, 22122.668159977613, 22128.778712212992, 22134.778652213594, 22147.889260553915, 22581.209657661904, 7895.486793358414, 7996.440249182618, 8036.156203598655, 8138.633761480747, 8160.0, 4192.80536886044, 4379.868603941887, 4399.296112621965, 4425.402570652985, 4507.4365704286965, 3556.6798988091195, 3643.9817800911355, 3660.908477288089, 3661.6521430464595, 3670.7797532148156, 645.6803882078401, 649.3084864619166, 655.5312951239887, 658.5324419662094, 670.9295523969913, 575.3010092737344, 612.3631423319698, 622.9408206220402, 671.3487916720773, 692.7817737412698, 7437.479376443597, 8075.878861817077, 8661.956690216635, 9263.212626926786, 9340.673076442292, 508.9312942752681, 622.3154530016946, 645.6739346629922, 657.4313219065474, 693.6809067828855, 3927.430522574211, 4034.870236333831, 4442.42248507692, 4542.341360502709, 4616.792244349012, 118549.81450185388, 133486.0, 133574.0, 139590.0, 149669.0, 121523.0, 126107.33091534005, 126561.3671931653, 127137.36431317784, 130200.74498137622, 94762.0523794767, 107118.46440767903, 109991.0, 113593.43203284097, 114704.0, 7463.626818658937, 9403.106704863027, 9558.522073896416, 9783.0, 10021.79738431393, 60930.0, 62010.0, 62797.0, 63542.3280555535, 64562.35437645563, 83177.16822831694, 93847.0, 98575.50712246393, 99615.0, 99921.0, 10756.946215268874, 12900.741985160057, 13131.343432828511, 13297.601071967856, 13686.110402823784, 14609.780853286997, 14683.185416645834, 14769.261536923326, 14770.409183632535, 15352.692946141238, 11821.0, 13100.672483187996, 13218.0, 13479.730405392034, 13602.523911663044, 11554.133439991867, 12792.936035319764, 13483.528076517276, 13638.863611363759, 14067.929660351632, 13205.801912971121, 13410.463581456632, 13411.731765364833, 13417.865821341662, 14035.438582456587, 10590.311629744043, 10813.621523246964, 10838.837417438586, 10871.891281087088, 11455.713607159887, 10234.897651023395, 10989.560417583207, 11048.779024419628, 11271.154663400443, 11301.15241356885], "value": 7737.976368576511, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1629.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 125.0, 163.0, 113.0, 125.0, 136.0, 118.0, 117.0, 117.0, 136.0], "value": 125.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 113.0, 132.0, 160.0, 114.0, 134.0, 129.0, 106.0, 164.0, 100.0], "value": 129.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 210.0, 281.0, 268.0, 195.0, 201.0, 200.0, 222.0, 274.0, 195.0], "value": 224.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 107.0, 106.0, 138.0, 84.0, 83.0, 82.0, 68.0, 132.0, 59.0], "value": 92.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 106.0, 141.0, 69.0, 70.0, 92.0, 106.0, 102.0, 108.0, 147.0], "value": 101.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 86.0, 87.0, 86.0, 113.0, 74.0, 79.0, 133.0, 135.0, 85.0], "value": 98.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 99.0, 99.0, 99.0, 113.0, 98.0, 97.0, 129.0, 169.0, 106.0], "value": 110.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 153.0, 145.0, 91.0, 90.0, 92.0, 139.0, 92.0, 90.0, 95.0], "value": 106.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 58.0, 58.0, 58.0, 58.0, 59.0, 60.0, 70.0, 82.0], "value": 61.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 105.0, 60.0, 96.0, 112.0, 111.0, 92.0, 72.0, 69.0, 71.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 78.0, 97.0, 110.0, 85.0, 85.0, 78.0, 95.0, 105.0, 100.0], "value": 92.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 145.0, 154.0, 154.0, 143.0, 138.0, 141.0, 150.0, 190.0, 141.0], "value": 149.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 176.0, 184.0, 239.0, 176.0, 171.0, 172.0, 172.0, 172.0, 176.0], "value": 181.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 68.0, 69.0, 68.0, 69.0, 70.0, 67.0, 76.0, 69.0], "value": 69.6, "unit": "score"}]}]} 11:32:43 INFO - Return code: 0 11:32:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:32:43 ERROR - # TBPL SUCCESS # 11:32:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:32:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:32:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:32:43 INFO - 2016-09-06 11:32:43.296 screenresolution[1675:10375] starting screenresolution argv=screenresolution get 11:32:43 INFO - 2016-09-06 11:32:43.299 screenresolution[1675:10375] Display 0: 1600x1200x32@60 11:32:43 INFO - 2016-09-06 11:32:43.305 screenresolution[1676:10378] starting screenresolution argv=screenresolution list 11:32:43 INFO - Available Modes on Display 0 11:32:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:32:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:32:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:32:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:32:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:32:43 INFO - Intel Iris: 11:32:43 INFO - Chipset Model: Intel Iris 11:32:43 INFO - Type: GPU 11:32:43 INFO - Bus: Built-In 11:32:43 INFO - VRAM (Dynamic, Max): 1536 MB 11:32:43 INFO - Vendor: Intel (0x8086) 11:32:43 INFO - Device ID: 0x0a2e 11:32:43 INFO - Revision ID: 0x0009 11:32:43 INFO - Displays: 11:32:43 INFO - Display: 11:32:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:32:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:32:43 INFO - Display Serial Number: 1600x1200 60 11:32:43 INFO - Main Display: Yes 11:32:43 INFO - Mirror: Off 11:32:43 INFO - Online: Yes 11:32:43 INFO - Rotation: Supported 11:32:43 INFO - Return code: 0 11:32:43 INFO - Running post-action listener: _resource_record_post_action 11:32:43 INFO - [mozharness: 2016-09-06 18:32:43.544874Z] Finished run-tests step (success) 11:32:43 INFO - Running post-run listener: _resource_record_post_run 11:32:43 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 37233664; Write bytes: 707992576; Read time: 242; Write time: 2007 11:32:43 INFO - TinderboxPrint: CPU usage
54.5% 11:32:43 INFO - TinderboxPrint: I/O read bytes / time
37,233,664 / 242 11:32:43 INFO - TinderboxPrint: I/O write bytes / time
707,992,576 / 2,007 11:32:43 INFO - TinderboxPrint: CPU idle
2,205.4 (45.5%) 11:32:43 INFO - TinderboxPrint: CPU system
977.1 (20.2%) 11:32:43 INFO - TinderboxPrint: CPU user
1,663.2 (34.3%) 11:32:43 INFO - TinderboxPrint: Swap in / out
443,109,376 / 0 11:32:43 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 402526720; Write bytes: 394811392; Read time: 29776; Write time: 817 11:32:43 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 35107840; Write bytes: 298374144; Read time: 219; Write time: 1152 11:32:44 INFO - Running post-run listener: _upload_blobber_files 11:32:44 INFO - Blob upload gear active. 11:32:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:32:44 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 11:32:44 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:32:44 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:32:44 INFO - (blobuploader) - INFO - Open directory for files ... 11:32:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:32:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:32:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:32:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:32:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:32:45 INFO - (blobuploader) - INFO - Done attempting. 11:32:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:32:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:32:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:32:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:32:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:32:46 INFO - (blobuploader) - INFO - Done attempting. 11:32:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:32:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:32:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:32:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:32:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:32:49 INFO - (blobuploader) - INFO - Done attempting. 11:32:49 INFO - (blobuploader) - INFO - Iteration through files over. 11:32:49 INFO - Return code: 0 11:32:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:32:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:32:49 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cb5d0596109c831050c54d312f2b44ac09e9399b1143ce72827cf3c5dd7b60ec9c2e2d1ed2e61ee16294b227b8fc8afcc32d7d6990ac22e1db55d9b1d7ba8762", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1321d52b14ae24e0d11e7d8c86a1790ba54a1c73d72874b097e2a3187faf730fdf2fec358eb24fd255cfbc05350a3ac70bcdc9cff41c28e45a24d1512a2aff77", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d419a391963e2ac26cd512c95bbfffbb6f4e9c80bc9693d81accf38f45c572e4129294533383ea4a7aac8e6e8f7fd14ed8c5aeb8e6822e8350a3a45571b4b93e"} 11:32:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:32:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:32:49 INFO - Contents: 11:32:49 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cb5d0596109c831050c54d312f2b44ac09e9399b1143ce72827cf3c5dd7b60ec9c2e2d1ed2e61ee16294b227b8fc8afcc32d7d6990ac22e1db55d9b1d7ba8762", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1321d52b14ae24e0d11e7d8c86a1790ba54a1c73d72874b097e2a3187faf730fdf2fec358eb24fd255cfbc05350a3ac70bcdc9cff41c28e45a24d1512a2aff77", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d419a391963e2ac26cd512c95bbfffbb6f4e9c80bc9693d81accf38f45c572e4129294533383ea4a7aac8e6e8f7fd14ed8c5aeb8e6822e8350a3a45571b4b93e"} 11:32:49 INFO - Running post-run listener: copy_logs_to_upload_dir 11:32:49 INFO - Copying logs to upload dir... 11:32:49 INFO - mkdir: /builds/slave/test/build/upload/logs 11:32:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.383349 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-06 11:32:49.642801) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 11:32:49.648765) ========= 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.PGOAjlZl3G/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.PtKYAOlBzh/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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/Fx-Team/sha512/cb5d0596109c831050c54d312f2b44ac09e9399b1143ce72827cf3c5dd7b60ec9c2e2d1ed2e61ee16294b227b8fc8afcc32d7d6990ac22e1db55d9b1d7ba8762", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1321d52b14ae24e0d11e7d8c86a1790ba54a1c73d72874b097e2a3187faf730fdf2fec358eb24fd255cfbc05350a3ac70bcdc9cff41c28e45a24d1512a2aff77", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d419a391963e2ac26cd512c95bbfffbb6f4e9c80bc9693d81accf38f45c572e4129294533383ea4a7aac8e6e8f7fd14ed8c5aeb8e6822e8350a3a45571b4b93e"} build_url:https://queue.taskcluster.net/v1/task/Lv7yCflATWaf6nS5Qd2uwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011209 build_url: 'https://queue.taskcluster.net/v1/task/Lv7yCflATWaf6nS5Qd2uwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cb5d0596109c831050c54d312f2b44ac09e9399b1143ce72827cf3c5dd7b60ec9c2e2d1ed2e61ee16294b227b8fc8afcc32d7d6990ac22e1db55d9b1d7ba8762", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1321d52b14ae24e0d11e7d8c86a1790ba54a1c73d72874b097e2a3187faf730fdf2fec358eb24fd255cfbc05350a3ac70bcdc9cff41c28e45a24d1512a2aff77", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d419a391963e2ac26cd512c95bbfffbb6f4e9c80bc9693d81accf38f45c572e4129294533383ea4a7aac8e6e8f7fd14ed8c5aeb8e6822e8350a3a45571b4b93e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 11:32:49.682086) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 11:32:49.682446) ========= 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.PGOAjlZl3G/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.PtKYAOlBzh/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005219 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 11:32:49.731600) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 11:32:49.731999) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 11:32:49.732362) ========= ========= Total master_lag: 0.18 =========