builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0359 starttime: 1473372284.36 results: success (0) buildid: 20160908130820 builduid: af584dae69fe4e2aa41bc1b36f5704c1 revision: c518337a8da24d43364eda6ed741575c71a2655b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 15:04:44.363528) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 15:04:44.364668) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2016-09-08 15:04:44.364946) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2016-09-08 15:04:47.770885) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-08 15:04:47.771262) ========= 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.fU5CvUGDJ1/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.Z0icozdHqf/Listeners TMPDIR=/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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-08 15:04:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.13M=0.001s 2016-09-08 15:04:48 (8.13 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.489357 ========= master_lag: 1.38 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-08 15:04:49.637569) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 15:04:49.637920) ========= 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.fU5CvUGDJ1/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.Z0icozdHqf/Listeners TMPDIR=/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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.020538 ========= master_lag: 0.58 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 15:04:50.241577) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-09-08 15:04:50.241973) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c518337a8da24d43364eda6ed741575c71a2655b --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 c518337a8da24d43364eda6ed741575c71a2655b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fU5CvUGDJ1/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.Z0icozdHqf/Listeners TMPDIR=/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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-08 15:04:54,268 truncating revision to first 12 chars 2016-09-08 15:04:54,268 Setting DEBUG logging. 2016-09-08 15:04:54,268 attempt 1/10 2016-09-08 15:04:54,268 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c518337a8da2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-08 15:04:55,949 unpacking tar archive at: fx-team-c518337a8da2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.947324 ========= master_lag: 4.69 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-09-08 15:04:56.876350) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 15:04:56.876658) ========= script_repo_revision: c518337a8da24d43364eda6ed741575c71a2655b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 15:04:56.877059) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 15:04:56.877334) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 15:04:56.896003) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-08 15:04:56.896284) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.fU5CvUGDJ1/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.Z0icozdHqf/Listeners TMPDIR=/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 15:04:57 INFO - MultiFileLogger online at 20160908 15:04:57 in /builds/slave/test 15:04:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 15:04:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:04:57 INFO - {'append_to_log': False, 15:04:57 INFO - 'base_work_dir': '/builds/slave/test', 15:04:57 INFO - 'blob_upload_branch': 'Fx-Team', 15:04:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:04:57 INFO - 'branch': 'Fx-Team', 15:04:57 INFO - 'buildbot_json_path': 'buildprops.json', 15:04:57 INFO - 'config_files': ('talos/mac_config.py',), 15:04:57 INFO - 'default_actions': ('clobber', 15:04:57 INFO - 'read-buildbot-config', 15:04:57 INFO - 'download-and-extract', 15:04:57 INFO - 'populate-webroot', 15:04:57 INFO - 'create-virtualenv', 15:04:57 INFO - 'install', 15:04:57 INFO - 'run-tests'), 15:04:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:04:57 INFO - 'download_minidump_stackwalk': True, 15:04:57 INFO - 'download_symbols': 'ondemand', 15:04:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:04:57 INFO - 'tooltool.py': '/tools/tooltool.py', 15:04:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:04:57 INFO - '/tools/misc-python/virtualenv.py')}, 15:04:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:04:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:04:57 INFO - 'installer_path': 'installer.exe', 15:04:57 INFO - 'log_level': 'info', 15:04:57 INFO - 'log_name': 'talos', 15:04:57 INFO - 'log_to_console': True, 15:04:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:04:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:04:57 INFO - 'opt_config_files': (), 15:04:57 INFO - 'pip_index': False, 15:04:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:04:57 INFO - 'cmd': ('bash', 15:04:57 INFO - '-c', 15:04:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:04:57 INFO - 'enabled': True, 15:04:57 INFO - 'halt_on_failure': False, 15:04:57 INFO - 'name': 'check_screen_resolution'},), 15:04:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:04:57 INFO - 'cmd': ('bash', 15:04:57 INFO - '-c', 15:04:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:04:57 INFO - 'enabled': True, 15:04:57 INFO - 'halt_on_failure': False, 15:04:57 INFO - 'name': 'check_screen_resolution'},), 15:04:57 INFO - 'run_cmd_checks_enabled': True, 15:04:57 INFO - 'sps_profile': False, 15:04:57 INFO - 'sps_profile_interval': 0, 15:04:57 INFO - 'suite': 'dromaeojs', 15:04:57 INFO - 'system_bits': '32', 15:04:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:04:57 INFO - 'title': 't-yosemite-r7-0359', 15:04:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:04:57 INFO - 'use_talos_json': True, 15:04:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:04:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:04:57 INFO - 'work_dir': 'build'} 15:04:57 INFO - [mozharness: 2016-09-08 22:04:57.088738Z] Running clobber step. 15:04:57 INFO - Running pre-action listener: _resource_record_pre_action 15:04:57 INFO - Running main action method: clobber 15:04:57 INFO - rmtree: /builds/slave/test/build 15:04:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:04:58 INFO - Running post-action listener: _resource_record_post_action 15:04:58 INFO - [mozharness: 2016-09-08 22:04:58.929007Z] Finished clobber step (success) 15:04:58 INFO - [mozharness: 2016-09-08 22:04:58.929224Z] Running read-buildbot-config step. 15:04:58 INFO - Running pre-action listener: _resource_record_pre_action 15:04:58 INFO - Running main action method: read_buildbot_config 15:04:58 INFO - Using buildbot properties: 15:04:58 INFO - { 15:04:58 INFO - "project": "", 15:04:58 INFO - "product": "firefox", 15:04:58 INFO - "script_repo_revision": "production", 15:04:58 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 15:04:58 INFO - "repository": "", 15:04:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 15:04:58 INFO - "buildid": "20160908130820", 15:04:58 INFO - "pgo_build": "False", 15:04:58 INFO - "basedir": "/builds/slave/test", 15:04:58 INFO - "buildnumber": 932, 15:04:58 INFO - "slavename": "t-yosemite-r7-0359", 15:04:58 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 15:04:58 INFO - "platform": "yosemite_r7", 15:04:58 INFO - "scheduler": "tests-fx-team-macosx64-talos", 15:04:58 INFO - "branch": "fx-team", 15:04:58 INFO - "repo_path": "integration/fx-team", 15:04:58 INFO - "revision": "c518337a8da24d43364eda6ed741575c71a2655b", 15:04:58 INFO - "stage_platform": "macosx64", 15:04:58 INFO - "builduid": "af584dae69fe4e2aa41bc1b36f5704c1", 15:04:58 INFO - "slavebuilddir": "test" 15:04:58 INFO - } 15:04:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/IrerJJCyRU2d-VNwQChyBA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 15:04:58 INFO - Running post-action listener: _resource_record_post_action 15:04:58 INFO - [mozharness: 2016-09-08 22:04:58.932849Z] Finished read-buildbot-config step (success) 15:04:58 INFO - [mozharness: 2016-09-08 22:04:58.932991Z] Running download-and-extract step. 15:04:58 INFO - Running pre-action listener: _resource_record_pre_action 15:04:58 INFO - Running main action method: download_and_extract 15:04:58 INFO - mkdir: /builds/slave/test/build/tests 15:04:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:04:58 INFO - trying https://queue.taskcluster.net/v1/task/IrerJJCyRU2d-VNwQChyBA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 15:04:58 INFO - Downloading https://queue.taskcluster.net/v1/task/IrerJJCyRU2d-VNwQChyBA/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 15:04:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IrerJJCyRU2d-VNwQChyBA/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 15:04:59 INFO - Downloaded 1199 bytes. 15:04:59 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 15:04:59 INFO - Using the following test package requirements: 15:04:59 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:04:59 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:04:59 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 15:04:59 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:04:59 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 15:04:59 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:04:59 INFO - u'jsshell-mac.zip'], 15:04:59 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:04:59 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 15:04:59 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:04:59 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:04:59 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 15:04:59 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:04:59 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 15:04:59 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:04:59 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 15:04:59 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:04:59 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 15:04:59 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:05:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IrerJJCyRU2d-VNwQChyBA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:05:00 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 15:05:07 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 15:05:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IrerJJCyRU2d-VNwQChyBA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:05:08 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 15:05:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IrerJJCyRU2d-VNwQChyBA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 15:05:15 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 15:05:16 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')]} 15:05:16 INFO - trying https://queue.taskcluster.net/v1/task/IrerJJCyRU2d-VNwQChyBA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:05:16 INFO - Downloading https://queue.taskcluster.net/v1/task/IrerJJCyRU2d-VNwQChyBA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 15:05:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IrerJJCyRU2d-VNwQChyBA/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 15:05:18 INFO - Downloaded 111480864 bytes. 15:05:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IrerJJCyRU2d-VNwQChyBA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:05:18 INFO - mkdir: /builds/slave/test/properties 15:05:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:05:18 INFO - Writing to file /builds/slave/test/properties/build_url 15:05:18 INFO - Contents: 15:05:18 INFO - build_url:https://queue.taskcluster.net/v1/task/IrerJJCyRU2d-VNwQChyBA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:05:19 INFO - Running post-action listener: _resource_record_post_action 15:05:19 INFO - Running post-action listener: set_extra_try_arguments 15:05:19 INFO - [mozharness: 2016-09-08 22:05:19.444631Z] Finished download-and-extract step (success) 15:05:19 INFO - [mozharness: 2016-09-08 22:05:19.444773Z] Running populate-webroot step. 15:05:19 INFO - Running pre-action listener: _resource_record_pre_action 15:05:19 INFO - Running main action method: populate_webroot 15:05:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:05:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:05:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:05:19 INFO - '--tppagecycles', 15:05:19 INFO - '7'], 15:05:19 INFO - 'tests': ['tsvgm']}}, 15:05:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:05:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:05:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:05:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:05:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:05:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:05:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:05:19 INFO - 'talos_options': ['--disable-e10s'], 15:05:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:05:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:05:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:05:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:05:19 INFO - 'talos_options': ['--disable-e10s'], 15:05:19 INFO - 'tests': ['damp', 'tps']}, 15:05:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:05:19 INFO - 'tests': ['damp', 'tps']}, 15:05:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:05:19 INFO - 'tests': ['dromaeo_dom']}, 15:05:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:05:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:05:19 INFO - 'tests': ['basic_compositor_video']}, 15:05:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:05:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:05:19 INFO - 'tests': ['a11yr', 15:05:19 INFO - 'ts_paint', 15:05:19 INFO - 'tpaint', 15:05:19 INFO - 'sessionrestore', 15:05:19 INFO - 'sessionrestore_no_auto_restore', 15:05:19 INFO - 'tabpaint']}, 15:05:19 INFO - 'other-e10s': {'tests': ['a11yr', 15:05:19 INFO - 'ts_paint', 15:05:19 INFO - 'tpaint', 15:05:19 INFO - 'sessionrestore', 15:05:19 INFO - 'sessionrestore_no_auto_restore', 15:05:19 INFO - 'tabpaint']}, 15:05:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:05:19 INFO - 'ts_paint', 15:05:19 INFO - 'tpaint', 15:05:19 INFO - 'sessionrestore', 15:05:19 INFO - 'sessionrestore_no_auto_restore', 15:05:19 INFO - 'tabpaint']}, 15:05:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:05:19 INFO - 'ts_paint', 15:05:19 INFO - 'tpaint', 15:05:19 INFO - 'sessionrestore', 15:05:19 INFO - 'sessionrestore_no_auto_restore', 15:05:19 INFO - 'tabpaint']}, 15:05:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:05:19 INFO - 'tests': ['a11yr', 15:05:19 INFO - 'ts_paint', 15:05:19 INFO - 'tpaint', 15:05:19 INFO - 'sessionrestore', 15:05:19 INFO - 'sessionrestore_no_auto_restore', 15:05:19 INFO - 'tabpaint']}, 15:05:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:05:19 INFO - 'tests': ['a11yr', 15:05:19 INFO - 'ts_paint', 15:05:19 INFO - 'tpaint', 15:05:19 INFO - 'sessionrestore', 15:05:19 INFO - 'sessionrestore_no_auto_restore', 15:05:19 INFO - 'tabpaint']}, 15:05:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:05:19 INFO - 'tests': ['tsvgx', 15:05:19 INFO - 'tsvgr_opacity', 15:05:19 INFO - 'tart', 15:05:19 INFO - 'tscrollx', 15:05:19 INFO - 'cart']}, 15:05:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:05:19 INFO - 'tsvgr_opacity', 15:05:19 INFO - 'tart', 15:05:19 INFO - 'tscrollx', 15:05:19 INFO - 'cart']}, 15:05:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:05:19 INFO - 'talos_options': ['--disable-e10s'], 15:05:19 INFO - 'tests': ['tp5o']}, 15:05:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:05:19 INFO - 'tests': ['tp5o']}, 15:05:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:05:19 INFO - 'talos_options': ['--disable-e10s', 15:05:19 INFO - '--xperf_path', 15:05:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:05:19 INFO - 'tests': ['tp5n']}, 15:05:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:05:19 INFO - 'talos_options': ['--xperf_path', 15:05:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:05:19 INFO - 'tests': ['tp5n']}}, 15:05:19 INFO - 'talos.zip': {'path': '', 15:05:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:05:19 INFO - Running post-action listener: _resource_record_post_action 15:05:19 INFO - [mozharness: 2016-09-08 22:05:19.463846Z] Finished populate-webroot step (success) 15:05:19 INFO - [mozharness: 2016-09-08 22:05:19.463964Z] Running create-virtualenv step. 15:05:19 INFO - Running pre-action listener: _resource_record_pre_action 15:05:19 INFO - Running main action method: create_virtualenv 15:05:19 INFO - Creating virtualenv /builds/slave/test/build/venv 15:05:19 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:05:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:05:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:05:19 INFO - Using real prefix '/tools/python27' 15:05:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:05:20 INFO - Installing distribute.............................................................................................................................................................................................done. 15:05:23 INFO - Installing pip.................done. 15:05:23 INFO - Return code: 0 15:05:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:05:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:05:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:05:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:05:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:05:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109136a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091cb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde52de66a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10909ad30>, '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 0x108dffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109092b20>, '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.fU5CvUGDJ1/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.Z0icozdHqf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 15:05:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:05:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:05:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fU5CvUGDJ1/Render', 15:05:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:05:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:05:23 INFO - 'HOME': '/Users/cltbld', 15:05:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:05:23 INFO - 'LOGNAME': 'cltbld', 15:05:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:05:23 INFO - 'MOZ_NO_REMOTE': '1', 15:05:23 INFO - 'NO_EM_RESTART': '1', 15:05:23 INFO - 'PAGER': '/bin/cat', 15:05:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:05:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:05:23 INFO - 'PWD': '/builds/slave/test', 15:05:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:05:23 INFO - 'SHELL': '/bin/bash', 15:05:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z0icozdHqf/Listeners', 15:05:23 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 15:05:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:05:23 INFO - 'USER': 'cltbld', 15:05:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:05:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:05:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:05:23 INFO - 'XPC_FLAGS': '0x0', 15:05:23 INFO - 'XPC_SERVICE_NAME': '0', 15:05:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:05:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:05:23 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:05:23 INFO - Cleaning up... 15:05:23 INFO - Return code: 0 15:05:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:05:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:05:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:05:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:05:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:05:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109136a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091cb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde52de66a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10909ad30>, '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 0x108dffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109092b20>, '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.fU5CvUGDJ1/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.Z0icozdHqf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 15:05:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:05:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:05:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fU5CvUGDJ1/Render', 15:05:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:05:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:05:23 INFO - 'HOME': '/Users/cltbld', 15:05:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:05:23 INFO - 'LOGNAME': 'cltbld', 15:05:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:05:23 INFO - 'MOZ_NO_REMOTE': '1', 15:05:23 INFO - 'NO_EM_RESTART': '1', 15:05:23 INFO - 'PAGER': '/bin/cat', 15:05:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:05:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:05:23 INFO - 'PWD': '/builds/slave/test', 15:05:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:05:23 INFO - 'SHELL': '/bin/bash', 15:05:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z0icozdHqf/Listeners', 15:05:23 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 15:05:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:05:23 INFO - 'USER': 'cltbld', 15:05:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:05:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:05:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:05:23 INFO - 'XPC_FLAGS': '0x0', 15:05:23 INFO - 'XPC_SERVICE_NAME': '0', 15:05:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:05:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:05:23 INFO - Downloading/unpacking psutil>=3.1.1 15:05:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:05:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:05:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:05:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:05:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:05:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:05:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:05:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:05:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:05:27 INFO - Installing collected packages: psutil 15:05:27 INFO - Running setup.py install for psutil 15:05:27 INFO - building 'psutil._psutil_osx' extension 15:05:27 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 15:05:27 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 15:05:27 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 15:05:27 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 15:05:27 INFO - building 'psutil._psutil_posix' extension 15:05:27 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 15:05:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:05:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:05:27 INFO - ^ 15:05:28 INFO - 1 warning generated. 15:05:28 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 15:05:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:05:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:05:28 INFO - Successfully installed psutil 15:05:28 INFO - Cleaning up... 15:05:28 INFO - Return code: 0 15:05:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:05:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:05:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:05:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:05:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109136a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091cb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde52de66a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10909ad30>, '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 0x108dffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109092b20>, '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.fU5CvUGDJ1/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.Z0icozdHqf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 15:05:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:05:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:05:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fU5CvUGDJ1/Render', 15:05:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:05:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:05:28 INFO - 'HOME': '/Users/cltbld', 15:05:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:05:28 INFO - 'LOGNAME': 'cltbld', 15:05:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:05:28 INFO - 'MOZ_NO_REMOTE': '1', 15:05:28 INFO - 'NO_EM_RESTART': '1', 15:05:28 INFO - 'PAGER': '/bin/cat', 15:05:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:05:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:05:28 INFO - 'PWD': '/builds/slave/test', 15:05:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:05:28 INFO - 'SHELL': '/bin/bash', 15:05:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z0icozdHqf/Listeners', 15:05:28 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 15:05:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:05:28 INFO - 'USER': 'cltbld', 15:05:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:05:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:05:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:05:28 INFO - 'XPC_FLAGS': '0x0', 15:05:28 INFO - 'XPC_SERVICE_NAME': '0', 15:05:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:05:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:05:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:05:28 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 15:05:28 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 15:05:28 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 15:05:28 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 15:05:28 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 15:05:28 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 15:05:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:05:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:05:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:05:30 INFO - Installing collected packages: mozsystemmonitor 15:05:30 INFO - Running setup.py install for mozsystemmonitor 15:05:31 INFO - Successfully installed mozsystemmonitor 15:05:31 INFO - Cleaning up... 15:05:31 INFO - Return code: 0 15:05:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:05: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')]} 15:05:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:05:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05: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')]} 15:05:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:05:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109136a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091cb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde52de66a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10909ad30>, '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 0x108dffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109092b20>, '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.fU5CvUGDJ1/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.Z0icozdHqf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 15:05:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:05:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:05:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fU5CvUGDJ1/Render', 15:05:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:05:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:05:31 INFO - 'HOME': '/Users/cltbld', 15:05:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:05:31 INFO - 'LOGNAME': 'cltbld', 15:05:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:05:31 INFO - 'MOZ_NO_REMOTE': '1', 15:05:31 INFO - 'NO_EM_RESTART': '1', 15:05:31 INFO - 'PAGER': '/bin/cat', 15:05:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:05:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:05:31 INFO - 'PWD': '/builds/slave/test', 15:05:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:05:31 INFO - 'SHELL': '/bin/bash', 15:05:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z0icozdHqf/Listeners', 15:05:31 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 15:05:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:05:31 INFO - 'USER': 'cltbld', 15:05:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:05:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:05:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:05:31 INFO - 'XPC_FLAGS': '0x0', 15:05:31 INFO - 'XPC_SERVICE_NAME': '0', 15:05:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:05:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:05:31 INFO - Downloading/unpacking jsonschema==2.5.1 15:05:31 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 15:05:31 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 15:05:31 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 15:05:31 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 15:05:31 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 15:05:31 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 15:05:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:05:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:05:33 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 15:05:33 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 15:05:33 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 15:05:33 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 15:05:33 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 15:05:33 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 15:05:34 INFO - Downloading functools32-3.2.3-2.tar.gz 15:05:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:05:34 INFO - warning: no files found matching '*.txt' 15:05:34 INFO - no previously-included directories found matching 'build' 15:05:34 INFO - no previously-included directories found matching 'dist' 15:05:34 INFO - no previously-included directories found matching '.git*' 15:05:34 INFO - Installing collected packages: jsonschema, functools32 15:05:34 INFO - Running setup.py install for functools32 15:05:34 INFO - warning: no files found matching '*.txt' 15:05:34 INFO - no previously-included directories found matching 'build' 15:05:34 INFO - no previously-included directories found matching 'dist' 15:05:34 INFO - no previously-included directories found matching '.git*' 15:05:34 INFO - Successfully installed jsonschema functools32 15:05:34 INFO - Cleaning up... 15:05:34 INFO - Return code: 0 15:05:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:05:34 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')]} 15:05:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:05:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:34 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')]} 15:05:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:05:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:34 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 0x109136a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091cb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde52de66a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10909ad30>, '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 0x108dffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109092b20>, '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.fU5CvUGDJ1/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.Z0icozdHqf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 15:05:34 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 15:05:34 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 15:05:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fU5CvUGDJ1/Render', 15:05:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:05:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:05:34 INFO - 'HOME': '/Users/cltbld', 15:05:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:05:34 INFO - 'LOGNAME': 'cltbld', 15:05:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:05:34 INFO - 'MOZ_NO_REMOTE': '1', 15:05:34 INFO - 'NO_EM_RESTART': '1', 15:05:34 INFO - 'PAGER': '/bin/cat', 15:05:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:05:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:05:34 INFO - 'PWD': '/builds/slave/test', 15:05:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:05:34 INFO - 'SHELL': '/bin/bash', 15:05:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z0icozdHqf/Listeners', 15:05:34 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 15:05:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:05:34 INFO - 'USER': 'cltbld', 15:05:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:05:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:05:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:05:34 INFO - 'XPC_FLAGS': '0x0', 15:05:34 INFO - 'XPC_SERVICE_NAME': '0', 15:05:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:05:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:05:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:05:34 INFO - Cleaning up... 15:05:34 INFO - Return code: 0 15:05:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:05:34 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')]} 15:05:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:05:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:34 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')]} 15:05:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:05:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:34 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 0x109136a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091cb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde52de66a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10909ad30>, '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 0x108dffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109092b20>, '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.fU5CvUGDJ1/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.Z0icozdHqf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 15:05:34 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 15:05:34 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 15:05:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fU5CvUGDJ1/Render', 15:05:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:05:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:05:34 INFO - 'HOME': '/Users/cltbld', 15:05:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:05:34 INFO - 'LOGNAME': 'cltbld', 15:05:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:05:34 INFO - 'MOZ_NO_REMOTE': '1', 15:05:34 INFO - 'NO_EM_RESTART': '1', 15:05:34 INFO - 'PAGER': '/bin/cat', 15:05:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:05:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:05:34 INFO - 'PWD': '/builds/slave/test', 15:05:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:05:34 INFO - 'SHELL': '/bin/bash', 15:05:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z0icozdHqf/Listeners', 15:05:34 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 15:05:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:05:34 INFO - 'USER': 'cltbld', 15:05:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:05:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:05:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:05:34 INFO - 'XPC_FLAGS': '0x0', 15:05:34 INFO - 'XPC_SERVICE_NAME': '0', 15:05:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:05:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:05:35 INFO - Downloading/unpacking blobuploader==1.2.4 15:05:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:05:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:05:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:05:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:05:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:05:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:05:37 INFO - Downloading blobuploader-1.2.4.tar.gz 15:05:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:05:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:05:37 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 15:05:37 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 15:05:37 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 15:05:37 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 15:05:37 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 15:05:37 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 15:05:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:05:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:05:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:05:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:05:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:05:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:05:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:05:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:05:38 INFO - Downloading docopt-0.6.1.tar.gz 15:05:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:05:38 INFO - Installing collected packages: blobuploader, requests, docopt 15:05:38 INFO - Running setup.py install for blobuploader 15:05:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:05:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:05:39 INFO - Running setup.py install for requests 15:05:39 INFO - Running setup.py install for docopt 15:05:39 INFO - Successfully installed blobuploader requests docopt 15:05:39 INFO - Cleaning up... 15:05:39 INFO - Return code: 0 15:05:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:05: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')]} 15:05:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:05:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05: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')]} 15:05:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:05:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:39 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 0x109136a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091cb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde52de66a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10909ad30>, '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 0x108dffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109092b20>, '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.fU5CvUGDJ1/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.Z0icozdHqf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 15:05:39 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 15:05:39 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 15:05:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fU5CvUGDJ1/Render', 15:05:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:05:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:05:39 INFO - 'HOME': '/Users/cltbld', 15:05:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:05:39 INFO - 'LOGNAME': 'cltbld', 15:05:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:05:39 INFO - 'MOZ_NO_REMOTE': '1', 15:05:39 INFO - 'NO_EM_RESTART': '1', 15:05:39 INFO - 'PAGER': '/bin/cat', 15:05:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:05:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:05:39 INFO - 'PWD': '/builds/slave/test', 15:05:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:05:39 INFO - 'SHELL': '/bin/bash', 15:05:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z0icozdHqf/Listeners', 15:05:39 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 15:05:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:05:39 INFO - 'USER': 'cltbld', 15:05:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:05:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:05:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:05:39 INFO - 'XPC_FLAGS': '0x0', 15:05:39 INFO - 'XPC_SERVICE_NAME': '0', 15:05:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:05:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:05:40 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-v6D8T4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:05:40 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-vc3eei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:05:40 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-vahWEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:05:40 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-CA0j_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:05:40 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-hU1SBF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:05:40 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-lJyl1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:05:40 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-mJf5ku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:05:40 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-YC6a57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:05:41 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-K8pBXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:05:41 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-DZ_hsQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:05:41 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-WVt10C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:05:41 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-feq_BI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:05:41 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-5ew8jy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:05:41 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-XOJOO_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:05:41 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-QSGKWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:05:41 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-_vmZPx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:05:41 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-sFg3iA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:05:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:05:41 INFO - Running setup.py install for manifestparser 15:05:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:05:42 INFO - Running setup.py install for mozcrash 15:05:42 INFO - Running setup.py install for mozdebug 15:05:42 INFO - Running setup.py install for mozdevice 15:05:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:05:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:05:42 INFO - Running setup.py install for mozfile 15:05:42 INFO - Running setup.py install for mozhttpd 15:05:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:05:42 INFO - Running setup.py install for mozinfo 15:05:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:05:42 INFO - Running setup.py install for mozInstall 15:05:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:05:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:05:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:05:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:05:43 INFO - Running setup.py install for mozleak 15:05:43 INFO - Running setup.py install for mozlog 15:05:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:05:43 INFO - Running setup.py install for moznetwork 15:05:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:05:43 INFO - Running setup.py install for mozprocess 15:05:43 INFO - Running setup.py install for mozprofile 15:05:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:05:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:05:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:05:43 INFO - Running setup.py install for mozrunner 15:05:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:05:44 INFO - Running setup.py install for mozscreenshot 15:05:44 INFO - Running setup.py install for moztest 15:05:44 INFO - Running setup.py install for mozversion 15:05:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:05:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:05:44 INFO - Cleaning up... 15:05:44 INFO - Return code: 0 15:05:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:05:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:05:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:05:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:05:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109136a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091cb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde52de66a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10909ad30>, '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 0x108dffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109092b20>, '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.fU5CvUGDJ1/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.Z0icozdHqf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 15:05:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:05:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:05:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fU5CvUGDJ1/Render', 15:05:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:05:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:05:44 INFO - 'HOME': '/Users/cltbld', 15:05:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:05:44 INFO - 'LOGNAME': 'cltbld', 15:05:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:05:44 INFO - 'MOZ_NO_REMOTE': '1', 15:05:44 INFO - 'NO_EM_RESTART': '1', 15:05:44 INFO - 'PAGER': '/bin/cat', 15:05:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:05:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:05:44 INFO - 'PWD': '/builds/slave/test', 15:05:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:05:44 INFO - 'SHELL': '/bin/bash', 15:05:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z0icozdHqf/Listeners', 15:05:44 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 15:05:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:05:44 INFO - 'USER': 'cltbld', 15:05:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:05:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:05:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:05:44 INFO - 'XPC_FLAGS': '0x0', 15:05:44 INFO - 'XPC_SERVICE_NAME': '0', 15:05:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:05:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:05:44 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-cKnJ8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:05:44 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)) 15:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:05:44 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-lkEE9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:05:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:05:45 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-43MsuT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:05:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:05:45 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-xEMuHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:05:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:05:45 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-GOorEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:05:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:05:45 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-xvsSar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:05:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:05:45 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-b64Tph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:05:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:05:45 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-d2S28r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:05:45 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)) 15:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:05:45 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-hbrm9c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:05:45 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)) 15:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:05:45 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-7aS95a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:05:45 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)) 15:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:05:45 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-AAEWVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:05:46 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-uM8giY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:05:46 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-Pwck9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:05:46 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-0s3bEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:05:46 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-FatBEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:05:46 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-DcWsVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:05:46 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-ZRdnXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:05:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:05:46 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 15:05:46 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 15:05:46 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 15:05:46 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 15:05:46 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 15:05:46 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 15:05:49 INFO - Downloading blessings-1.6.tar.gz 15:05:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:05:49 INFO - Installing collected packages: blessings 15:05:49 INFO - Running setup.py install for blessings 15:05:49 INFO - Successfully installed blessings 15:05:49 INFO - Cleaning up... 15:05:49 INFO - Return code: 0 15:05:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:05:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:05:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:05:49 INFO - Reading from file tmpfile_stdout 15:05:49 INFO - Current package versions: 15:05:49 INFO - blessings == 1.6 15:05:49 INFO - blobuploader == 1.2.4 15:05:49 INFO - docopt == 0.6.1 15:05:49 INFO - functools32 == 3.2.3-2 15:05:49 INFO - jsonschema == 2.5.1 15:05:49 INFO - manifestparser == 1.1 15:05:49 INFO - mozInstall == 1.12 15:05:49 INFO - mozcrash == 0.17 15:05:49 INFO - mozdebug == 0.1 15:05:49 INFO - mozdevice == 0.48 15:05:49 INFO - mozfile == 1.2 15:05:49 INFO - mozhttpd == 0.7 15:05:49 INFO - mozinfo == 0.9 15:05:49 INFO - mozleak == 0.1 15:05:49 INFO - mozlog == 3.3 15:05:49 INFO - moznetwork == 0.27 15:05:49 INFO - mozprocess == 0.23 15:05:49 INFO - mozprofile == 0.28 15:05:49 INFO - mozrunner == 6.12 15:05:49 INFO - mozscreenshot == 0.1 15:05:49 INFO - mozsystemmonitor == 0.3 15:05:49 INFO - moztest == 0.7 15:05:49 INFO - mozversion == 1.4 15:05:49 INFO - psutil == 3.1.1 15:05:49 INFO - requests == 1.2.3 15:05:49 INFO - wsgiref == 0.1.2 15:05:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:05:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:05:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:05:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:49 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 0x109136a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091cb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde52de66a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10909ad30>, '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 0x108dffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109092b20>, '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.fU5CvUGDJ1/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.Z0icozdHqf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 15:05:49 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 15:05:49 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 15:05:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fU5CvUGDJ1/Render', 15:05:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:05:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:05:49 INFO - 'HOME': '/Users/cltbld', 15:05:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:05:49 INFO - 'LOGNAME': 'cltbld', 15:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:05:49 INFO - 'MOZ_NO_REMOTE': '1', 15:05:49 INFO - 'NO_EM_RESTART': '1', 15:05:49 INFO - 'PAGER': '/bin/cat', 15:05:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:05:49 INFO - 'PWD': '/builds/slave/test', 15:05:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:05:49 INFO - 'SHELL': '/bin/bash', 15:05:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z0icozdHqf/Listeners', 15:05:49 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 15:05:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:05:49 INFO - 'USER': 'cltbld', 15:05:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:05:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:05:49 INFO - 'XPC_FLAGS': '0x0', 15:05:49 INFO - 'XPC_SERVICE_NAME': '0', 15:05:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:05:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:05:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:05:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:05:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:05:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:05:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:05:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:05:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:05:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:05:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:05:50 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:05:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:05:50 INFO - Cleaning up... 15:05:50 INFO - Return code: 0 15:05:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:05:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:05:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:05:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:05:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:05:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109136a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091cb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde52de66a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10909ad30>, '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 0x108dffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109092b20>, '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.fU5CvUGDJ1/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.Z0icozdHqf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 15:05:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:05:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:05:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fU5CvUGDJ1/Render', 15:05:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:05:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:05:50 INFO - 'HOME': '/Users/cltbld', 15:05:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:05:50 INFO - 'LOGNAME': 'cltbld', 15:05:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:05:50 INFO - 'MOZ_NO_REMOTE': '1', 15:05:50 INFO - 'NO_EM_RESTART': '1', 15:05:50 INFO - 'PAGER': '/bin/cat', 15:05:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:05:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:05:50 INFO - 'PWD': '/builds/slave/test', 15:05:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:05:50 INFO - 'SHELL': '/bin/bash', 15:05:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z0icozdHqf/Listeners', 15:05:50 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 15:05:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:05:50 INFO - 'USER': 'cltbld', 15:05:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:05:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:05:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:05:50 INFO - 'XPC_FLAGS': '0x0', 15:05:50 INFO - 'XPC_SERVICE_NAME': '0', 15:05:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:05:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:05:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:05:50 INFO - Cleaning up... 15:05:50 INFO - Return code: 0 15:05:50 INFO - Running post-action listener: _resource_record_post_action 15:05:50 INFO - Running post-action listener: _start_resource_monitoring 15:05:50 INFO - Starting resource monitoring. 15:05:50 INFO - [mozharness: 2016-09-08 22:05:50.347891Z] Finished create-virtualenv step (success) 15:05:50 INFO - [mozharness: 2016-09-08 22:05:50.348922Z] Running install step. 15:05:50 INFO - Running pre-action listener: _resource_record_pre_action 15:05:50 INFO - Running main action method: install 15:05:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:05:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:05:50 INFO - Reading from file tmpfile_stdout 15:05:50 INFO - Detecting whether we're running mozinstall >=1.0... 15:05:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:05:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:05:50 INFO - Reading from file tmpfile_stdout 15:05:50 INFO - Output received: 15:05:50 INFO - Usage: mozinstall [options] installer 15:05:50 INFO - Options: 15:05:50 INFO - -h, --help show this help message and exit 15:05:50 INFO - -d DEST, --destination=DEST 15:05:50 INFO - Directory to install application into. [default: 15:05:50 INFO - "/builds/slave/test"] 15:05:50 INFO - --app=APP Application being installed. [default: firefox] 15:05:50 INFO - mkdir: /builds/slave/test/build/application 15:05:50 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'] 15:05:50 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 15:06:25 INFO - Reading from file tmpfile_stdout 15:06:25 INFO - Output received: 15:06:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:06:25 INFO - Running post-action listener: _resource_record_post_action 15:06:25 INFO - [mozharness: 2016-09-08 22:06:25.260542Z] Finished install step (success) 15:06:25 INFO - [mozharness: 2016-09-08 22:06:25.260690Z] Running run-tests step. 15:06:25 INFO - Running pre-action listener: _resource_record_pre_action 15:06:25 INFO - Running main action method: run_tests 15:06:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:06:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:06:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:06:25 INFO - 2016-09-08 15:06:25.275 screenresolution[1993:11670] starting screenresolution argv=screenresolution get 15:06:25 INFO - 2016-09-08 15:06:25.278 screenresolution[1993:11670] Display 0: 1600x1200x32@60 15:06:25 INFO - 2016-09-08 15:06:25.284 screenresolution[1994:11672] starting screenresolution argv=screenresolution list 15:06:25 INFO - Available Modes on Display 0 15:06:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:06:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:06:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:06:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:06:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:06:25 INFO - Intel Iris: 15:06:25 INFO - Chipset Model: Intel Iris 15:06:25 INFO - Type: GPU 15:06:25 INFO - Bus: Built-In 15:06:25 INFO - VRAM (Dynamic, Max): 1536 MB 15:06:25 INFO - Vendor: Intel (0x8086) 15:06:25 INFO - Device ID: 0x0a2e 15:06:25 INFO - Revision ID: 0x0009 15:06:25 INFO - Displays: 15:06:25 INFO - Display: 15:06:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:06:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:06:25 INFO - Display Serial Number: 1600x1200 60 15:06:25 INFO - Main Display: Yes 15:06:25 INFO - Mirror: Off 15:06:25 INFO - Online: Yes 15:06:25 INFO - Rotation: Supported 15:06:25 INFO - Return code: 0 15:06:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:06:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:06:25 INFO - Python 2.7.3 15:06:25 INFO - Return code: 0 15:06:25 INFO - grabbing minidump binary from tooltool 15:06: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')]} 15:06:25 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10909ad30>, '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 0x108dffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109092b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:06:25 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:06:25 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 15:06:25 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:06:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:06:25 INFO - Return code: 0 15:06:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:06:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:06:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:06:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:06:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:06:25 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', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IrerJJCyRU2d-VNwQChyBA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0359', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:06:25 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 --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IrerJJCyRU2d-VNwQChyBA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0359 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:06:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fU5CvUGDJ1/Render', 15:06:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:06:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:06:25 INFO - 'HOME': '/Users/cltbld', 15:06:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:06:25 INFO - 'LOGNAME': 'cltbld', 15:06:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:06:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:06:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:25 INFO - 'MOZ_NO_REMOTE': '1', 15:06:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:06:25 INFO - 'NO_EM_RESTART': '1', 15:06:25 INFO - 'PAGER': '/bin/cat', 15:06:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:06:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:25 INFO - 'PWD': '/builds/slave/test', 15:06:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:06:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:06:25 INFO - 'SHELL': '/bin/bash', 15:06:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z0icozdHqf/Listeners', 15:06:25 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 15:06:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:06:25 INFO - 'USER': 'cltbld', 15:06:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:06:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:06:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:25 INFO - 'XPC_FLAGS': '0x0', 15:06:25 INFO - 'XPC_SERVICE_NAME': '0', 15:06:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:06:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IrerJJCyRU2d-VNwQChyBA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0359', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:06:25 INFO - mozversion application_buildid: 20160908130820 15:06:25 INFO - mozversion application_changeset: c518337a8da24d43364eda6ed741575c71a2655b 15:06:25 INFO - mozversion application_display_name: Nightly 15:06:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:06:25 INFO - mozversion application_name: Firefox 15:06:25 INFO - mozversion application_remotingname: firefox 15:06:25 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 15:06:25 INFO - mozversion application_vendor: Mozilla 15:06:25 INFO - mozversion application_version: 51.0a1 15:06:25 INFO - mozversion platform_buildid: 20160908130820 15:06:25 INFO - mozversion platform_changeset: c518337a8da24d43364eda6ed741575c71a2655b 15:06:25 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 15:06:25 INFO - mozversion platform_version: 51.0a1 15:06:25 INFO - using testdate: 1473372385 15:06:25 INFO - actual date: 1473372385 15:06:25 INFO - starting webserver on 'localhost:49400' 15:06:25 INFO - SUITE-START | Running 2 tests 15:06:25 INFO - TEST-START | dromaeo_css 15:06:25 INFO - operating with platform_type : mac_ 15:06:25 INFO - Initialising browser for dromaeo_css test... 15:06:25 INFO - TEST-INFO | started process 2004 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/tmpCSQHKy/profile http://localhost:49400/getInfo.html) 15:06:28 INFO - PROCESS | 2004 | __metrics Screen width/height:1600/1200 15:06:28 INFO - PROCESS | 2004 | colorDepth:24 15:06:28 INFO - PROCESS | 2004 | Browser inner width/height: 1024/689 15:06:28 INFO - PROCESS | 2004 | __metrics 15:06:28 INFO - PROCESS | 2004 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:06:28 INFO - PROCESS | 2004 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:06:29 INFO - TEST-INFO | 2004: exit 0 15:06:29 INFO - Browser initialized. 15:06:29 INFO - Running cycle 1/1 for dromaeo_css test... 15:06:29 INFO - TEST-INFO | started process 2006 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/tmpCSQHKy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:06:41 INFO - PROCESS | 2006 | RSS: Main: 232165376 15:06:41 INFO - PROCESS | 2006 | 15:07:32 INFO - PROCESS | 2006 | 1473372452628 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] 15:09:43 INFO - PROCESS | 2006 | Cycle 1(1): loaded http://localhost:49400/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49400/tests/dromaeo/cssquery-ext.html) 15:09:45 INFO - PROCESS | 2006 | RSS: Main: 441204736 15:09:45 INFO - PROCESS | 2006 | 15:12:48 INFO - PROCESS | 2006 | Cycle 1(1): loaded http://localhost:49400/tests/dromaeo/cssquery-ext.html (next: http://localhost:49400/tests/dromaeo/cssquery-jquery.html) 15:12:49 INFO - PROCESS | 2006 | RSS: Main: 368467968 15:12:49 INFO - PROCESS | 2006 | 15:15:51 INFO - PROCESS | 2006 | Cycle 1(1): loaded http://localhost:49400/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49400/tests/dromaeo/cssquery-mootools.html) 15:15:52 INFO - PROCESS | 2006 | RSS: Main: 324333568 15:15:52 INFO - PROCESS | 2006 | 15:18:54 INFO - PROCESS | 2006 | Cycle 1(1): loaded http://localhost:49400/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49400/tests/dromaeo/cssquery-prototype.html) 15:18:55 INFO - PROCESS | 2006 | RSS: Main: 335175680 15:18:55 INFO - PROCESS | 2006 | 15:21:58 INFO - PROCESS | 2006 | Cycle 1(1): loaded http://localhost:49400/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49400/tests/dromaeo/cssquery-yui.html) 15:21:59 INFO - PROCESS | 2006 | RSS: Main: 325730304 15:21:59 INFO - PROCESS | 2006 | 15:25:02 INFO - PROCESS | 2006 | Cycle 1(1): loaded http://localhost:49400/tests/dromaeo/cssquery-yui.html (next: http://localhost:49400/tests/dromaeo/cssquery-dojo.html) 15:25:02 INFO - PROCESS | 2006 | RSS: Main: 529162240 15:25:02 INFO - PROCESS | 2006 | 15:25:02 INFO - PROCESS | 2006 | __start_tp_report 15:25:02 INFO - PROCESS | 2006 | _x_x_mozilla_page_load 15:25:02 INFO - PROCESS | 2006 | _x_x_mozilla_page_load_details 15:25:02 INFO - PROCESS | 2006 | |i|pagename|runs| 15:25:02 INFO - PROCESS | 2006 | |0;dojo.html;2955.4236923799863;3247.350529894021;3269.901902942911;3323.185819474229;3335.7831740936836;5456.508914197721;5505.614606977508;5566.526845218151;5570.275864137661;5586.497215250632;4576.519465456129;5517.420670829565;5610.410906854772;5657.745401456934;5662.546996240301;4985.40175178979;5031.396232452111;5069.923951140736;5094.6433749637545;5233.921491177635;5178.611604129686;5192.299039629658;5195.350581177353;5198.558122559587;5206.843794686166;5025.321581586476;5039.496050394968;5059.822906198302;5123.718195499246;5192.091384007803;5107.540321371057;5111.642185047048;5114.05390002847;5122.641415100945;5127.435982041972;4188.183304255671;5053.065182941167;5070.7464626768515;5097.464766199532;5120.641555091145;5438.891222175574;5451.400345961941;5512.889742205173;5550.833474995757;5625.493705566478;6027.5177985761475;6059.757609695607;6090.939090609126;6093.6039157454625;6102.572819902609;5334.3598768147685;5956.16613674086;5999.190109335227;6152.292486364081;6212.4408803207925;5559.861003474945;5577.721113944286;5597.272354593958;5648.124540696158;5653.773849046074;6208.906866397007;6253.155823963798;6255.937440625627;6261.780837670751;6280.340564240689;5739.942600574024;5795.710214489259;5851.063829787214;5858.882822343529;5861.267341582302;5951.613145145551;6019.337872833985;6021.337652858182;6102.786402475892;6132.324915438508;5882.147088672119;5902.2622172228475;6009.759609615654;6011.729472173763;6025;5857.677827719516;5924.970375148183;5977.073553599199;6045.153678485016;6067.54493412987;4770.833020844254;4812.302216178705;4842.612590992712;4846.200376937766;4863.513648635179;4316.352547117957;4319.43847299849;4328.935065974013;4371.75955322454;4375.890602734893;5455.399906010336;5513.8345849624575;5561.249231353715;5567.610267281332;5599.440055994368;6078.848028799227;6088.665123418255;6099.794059995464;6148.585825260216;6196.194494715658;5267.578593712494;5589.357223919302;5595.7481913313995;5597.63615365;5627.915581266284;5005.624578156653;5212.7914883405;5322.414534401213;5324.068288049576;5397.163439666858;6144.15675297409;6222.906656400158;6268.404501572343;6284.811455656338;6422;6168.475679567287;6173.382661733791;6181.505479561714;6194.318624951342;6213.9067913980425;6299.590526615939;6326.746930122744;6345.936540634534;6345.936540634534;6355.491560675227;5808.593398462067;5817.621854579439;5866.266716660418;5919.6152250103605;5939.524838013035;5029;6026.156338112755;6030.758769649165;6031.276246850406;6037.124616930609;5846.532277417881;5848.619839710405;5851.327097383855;5853.94146058534;5854.209681693087;4622.491525932212;4811.807527698993;5768.884622307446;5806.1000544915605;5868.119782032559;4695.718256904596;5776.133579963039;5812.82561523155;5830.970845145747;5857.414258574108;5924.081767326072;5941.168236446814;5941.37615550361;5945.910811337747;5959.940400595939;5622.803201887914;5670;5686.687232202268;5752.165935939349;5752.597318187687 15:25:02 INFO - PROCESS | 2006 | |1;ext.html;463.1454965588489;492.3722254125989;517.5730022731243;524.3969435149578;531.3889027618238;24609.507809843813;37971.81014094929;39304.82085537431;39515.60484395159;41805;40402.595974040254;40723.18553628925;40747;40850.59149408505;40904.59095409045;8543.231109200171;9542.332036757447;9731.367461115005;9736.464494452803;9741.269404794633;8508.574571271443;9656.22750179984;11593.652190434259;11729.648110556696;12056.63830085099;11586.246893951908;11641.18511704181;11646.767064658701;11653.242539234965;11653.592124275652;11887.227330223552;11966.940165299206;11968.640940771791;11974.700632484213;11984;11662.416879156008;11736.765264694744;11749.882501174965;11752.412379380996;11766.058715302841;38477.42283865744;38731.41902871459;38792.61207387918;38834.611653883665;38857.028574285585;3258.4297747894025;3509.508668786372;3662.8901132966053;3944.2703099926607;4074.6332830045144;29527.85236073806;30360.8481957591;30382.240443988856;30388.54417183744;30486;3217.581714377139;3280.6555311692405;3308.4871844864083;3342.615599206098;3342.8161451120177;384590;386187;386229;388896.0555197234;394119;28645.28386790347;28868.278293042422;29035.7096429033;54678;55318;32399.676003240136;32797.18007049843;32839.88021137988;32861.67138328634;32908.67091329056;14382.208978506073;14542.563723088324;14551.272436378138;14552.708945821025;14569.927150364181;11160.720981975353;11375.431228438545;12176.964957978495;12188.268703877795;12225.877741222652;6473.514486413538;7471.775846724607;7638.885416718754;7659.08091029069;7682;2804.7055059218897;3163.225009872596;3190.9469874941215;3233.531137984979;3250;933.6265493802533;1001.1840350114634;1044.1177205261542;1045.8849526552074;1094.6168840905618;29539.762758124398;29573.852130739207;29829.552556711667;30243.395132097234;30289.697103029128;1036.72008557689;1095.5398732532356;1097.8792332843382;1124.600766727816;1141.7374003979132;13643.590692279247;14915.850841491447;15422.460213892684;15640.82985232533;15739.606509837116;571878;573670;580506;583969;854150;54518;55434.16848747272;55439;55464;55863;1212.7028877924965;1225.6997035726126;1345.6837643153851;1361.884239839605;1391.4781956766212;24456.510869782862;29448.263124528938;29881.252968675955;30761.230969225948;30867.69132308648;50216.74891625519;51053.74473127611;51079.744601276754;51158.74420627873;51257.48742512527;530027;536914;539089;544151;546159;25517;25574.23277301684;25859.870700646377;25932;26353.868230658725;43550.56449435592;43834.56165438305;44295;44479.555204448836;45591.31613025874;31946;33811.4928276081;36348.63651363453;36473.635263647026;37438.62561374351;28915.7108428913;35573.577056917435;35943.82028089843;36558.63441365552;36663.816680916425;35437;36044.4593331095;36150.4577431344;36415.453768192965;36831.26337473182;32187.678123219404;32797.18007049843;33331.83334083411;33748.325033499685;33776;24439.02243910224;24635.63046554339;24914.12800551972;25160.993560257386;25322.74677253204 15:25:02 INFO - PROCESS | 2006 | |2;jquery.html;338.55818157304714;344.0469898381482;368.793475653634;372.6143441538008;395.851555666625;14915.701685966274;17018.40435584755;20064;20140.39578812636;20510.89744551275;15243.237838108078;18984.525386865334;19451.41645750626;19613.31353402631;19710;5926.9407305926925;5967.940320596804;6198.81403557894;6210.161628180205;6211.6273023618505;4315.4605674290715;6087;6142.6314421134875;6154.476869466101;6156.815295541141;5809.419058094199;5883.352831188566;6088.939110608903;6093.421124993119;6129.662868542228;5952.58331916766;6017.729202185912;6019.608725432855;6021.277446706379;6031.487323577501;5133.383993920754;5207.531322181022;5214.921776173361;5394.622376433651;5464.672119672832;12477.75044499105;12492;12550.246985180827;12727.981761459061;12744.107912446132;2095.758987716402;2136.4872430616697;2256.8307376946796;2736.220177249494;2775.111964171466;9462.716118516455;9700.660476883346;9826.950865245699;10048.849267260997;13745.03784735069;1742.451127894709;1753.3512600337829;1809.746635471035;1836.6602178597002;1854.7403363529118;104577;108401;112594.87405125926;113430;115235;9688.788901387326;10754.516046777913;10998.010179083922;11050.613228536962;11230.775384492264;12227.75544489123;13633.318334083257;13643.454261829615;13657.726845463234;13678.31608419575;8134.552599607078;8219.260266576037;8242.670293188205;8271.462354946909;8354.791130221793;8260.958695206606;8277.089520152778;8283.792905177299;8454.408191426664;8455.95772021136;4294.677899157513;4295.9570404295555;4374.343848422762;4385.758783266951;4392.209402307615;1594.9920250398675;1816.8597412170034;1868.112646492932;1873.4379686094117;1917.5493758966632;661.4939571228015;794.1700922535964;829.5396055189428;932.16105505046;996.4170959988343;18989.715154272697;19409.51476213106;19933.302334418226;20915.058822353032;21470.463238419165;877.9165979231963;883.4743327719997;909.1999040844015;919.3932004876749;995.9741466289734;8014.79963000918;10175.28772985884;10183.388996660224;10540.209484288709;10682.1988350874;538778.8366349016;542303;546683;558851;562418;79899;113863.43068284605;119229;129441.70558294485;134934;958.4297343080856;980.4754456365953;1009.0867764672983;1009.268280496634;1009.742515658517;15944.681106378039;16189.59526011859;17191.57021074483;18004.18981145878;18340.266389344277;54339.45660543344;55450.72274638601;55458.44541554533;55589.444105558425;55738;70568;70896;73307.63346183448;77508;83188.58405707932;7260.491765576359;7454.515456495311;7622.047244094489;7633.1985141559335;7817.140114587504;7025.859482810418;8281.585920703821;11815.70460738454;12110.636680899586;12229.75540489203;10064.647737329158;10380.221483388625;10449.582016719247;10503.474826258504;10513.842292365774;10004.649837255953;10365.585376584851;10409.739756506147;10713.035826775316;10726.249162558546;10453.320534165256;10498.895011050097;10784.460776960963;10894.455277236266;11013.779724405307;7462.664180112017;7548.358389536951;7569.432292577969;7639.04511936008;7678.6544605494;9287.953560232156;9884.30809843304;10340.27618066728;10391.272610917164;10508.579656813641 15:25:02 INFO - PROCESS | 2006 | |3;mootools.html;1474.6165996840818;1535.1556643845886;1729.8529624981875;1876.7372567840514;1929.1800984581557;8464.957675211623;9014.684486042981;9037.005929347766;9040.14118658728;9062.320325975554;7864.213578642133;8109.270165685087;8504.787380315494;8732.08313127122;8806.55967201639;2368.230325144331;2395.6167013277877;2397.6763136976547;2397.9160729374476;2398.880055997202;2345;2376.310869847742;2381.57131716291;2385.010220758383;2386.940326491843;2080.3862860456125;2162.7080344153574;2318.9072437102495;2387.6776635154292;2393.5930891748358;2354.952900941989;2357.787799098081;2383.368407372048;2386.749391313904;2390.5696974544576;2297.552541898593;2300.804431623297;2307.9423014424774;2308.1921327535397;2318.014843691424;8889.333300002525;8961.283097352196;8971.147740964598;8974.86537701935;8994.415363001384;5185.792568297264;5282.128448805943;5359.38367087786;5372.355317361941;5374.7850085996515;3462.2036931505895;3511.806850623215;3664.0839790052487;3681.061329360996;3681.1717363593243;1050.666002738703;1084.4198353880636;1092.153580974743;1107.1198397274106;1109.7447587055024;9846.458444785534;9859.852102218472;11511.424428778611;11670.35813030283;11684.123690723145;4518.096380723869;4543.750093744809;4752.429708434941;4757.8096876125255;4759.881002974953;9990.300678952404;9996.950015249877;9998.250131240186;10013.29906906524;10016.849747253797;5352.705601191972;5360.597955153379;5360.892782144335;5364.8122315718765;5369.060414427459;4692.389989301437;4861.732604706775;4911.312416261726;4916.164252077156;4925.310456536088;3079.5380692896247;3688.3729765939856;3750.9062273442837;3790.2798468290935;3799.506064211635;1109.0683825586545;1166.4867458318313;1178.9056875449944;1179.3076934598878;1181.3443538835857;746.0078096132131;850.3112478892117;854.8418542569644;875.9737207883646;927.2211342472356;5233.816816411408;5528.281323428009;5801.071828507418;6044.667543285073;6049.879002419927;917.6833992272656;960.2078285414422;971.8250714871391;976.5312649928075;996.4668902137322;2825.7174282571577;3696.672994305147;3868.6518213360932;3990.06233535119;4006.7996600169877;9617.326787124834;10467.743870735409;10521.736956576147;10931.836022459669;10933.45332733376;10881.347119172875;10951;10960.123190144928;10963.122950163968;10967.50646220922;7684.538927664358;8183.181681831769;8350.958245208734;8353;8367.372447066495;5651.491365777099;5947.851303717442;5962.880742385215;5966.956852005422;6004.279486461478;9463.763405914908;9978.151857092229;10075.244356673133;10079.748006299607;10128.240381971234;9920.817420167552;10091.444970526398;10092.495375231356;10110.443925584164;10114.6459873907;6834.726610935507;6841.657917104224;6997.090378250795;7019.087518622547;7034;8139.3488520917;8142.47073940192;8262.793430164294;8263.256306932404;8268.793280168044;7396.778096657109;7465.4027677786735;7522.736704215326;7576.393888489018;7663.042119735033;6773.221079575912;6782.864342713019;7368.521046131984;7379.077615298088;7457.403407727477;6792.558483698544;6857.965710171417;6938.618375989369;6960.025596416607;6963.651817409008;4828.324034635084;4865.732384718733;4929.507049295042;4941.184704523714;5051.090803655377;4851.417829860298;4886.706797592155;4889.63327750413;4941.97529012367;5015.899682006412 15:25:02 INFO - PROCESS | 2006 | |4;prototype.html;293.14986539036806;296.17515220111983;304.87347750683466;307.95996520452337;309.07124092103226;4295.892602684934;5334;5339;5376.542993845518;5387.841614052983;4886.26705994101;4988.925166122502;5016;5185.325907632012;5313.867153321169;3991.9002024949464;4044.0496483326397;4060.117316705143;4062.2687916175078;4077.2660921034203;3341.448660970937;3687.483752274684;3814.817406603948;4054.3918412238104;4078.2047500737367;3741.3452645787015;3749.662530372266;3766.905827354324;3919.451276821247;4037.899052523681;3245.480723084307;3487.3548393547276;3795.430685397185;3992.441058251818;4009.2382447334912;2862.9713702862914;3501.7898926064518;3508.9824550877083;3512.0693016350688;3513.350030244387;4426.734395936254;5082.415522214956;5105.234214867763;5109.029284435983;5113.985521365747;4134.214499245164;4192.266353388145;4202.85290014848;4549.5905368517;4569.1547063793305;4479.350494178326;4740.549647783455;5441.646292990943;5442.972785136049;5486.890262194774;3837.9808100959594;3849.2109117630816;3855.055511399697;3855.768653880776;3866.8259928303296;4777.737224452689;5490.215679904008;5685.488306052475;5709.914351284734;5744.30542989819;3594.9149493293826;3732.309522738248;3823.3309170895536;3863.632954869283;3893.669038131734;3195.872165113416;3970.622790834866;3976.622220889011;4010.7794071325775;4052.979735101306;4320.978395108004;4553.61294289989;4572.474165470947;4612.815487380534;4626.871639046077;5467.781288748405;5483.369412517532;5498.972505137449;5509.504144626922;5510.6969116698965;1210.7033776724759;1348.797680347936;1374.1480282224884;1380.1856904426236;1387.1065994917067;841.9938173882274;871.4901782457207;899.6221586933503;912.6167009855876;923.9168474837162;4606.700564463299;5077.339945807022;5123.616623511631;5128.640995130305;5161.329027226437;5440.809571664972;5558.999380111619;5608.1587761835235;5614.410486898899;5620.662760234399;3960.4455376247347;4578.679492435497;4872.464028956882;5035.471275516092;5310.123829568155;2326.4183954011496;2776.750462291977;2991.2521869532616;3016.5324374721954;3019.5923550320863;5434.375046869661;5943.940560594425;5953.672548009901;5969.2240008799445;5988.880222395528;5371.409144994048;5508.91736623951;5853.970730146407;5855.2973643163095;5857.769868327614;5908.822735317775;5916.2013128228;5952.583319167703;5961.225040744762;5978.312494063239;5221.4778522149;5369.704666243394;5707.286589176353;5730.541556675539;5790.768369265183;4519.638428925744;5417.377001644703;5756.028340445079;5773.393793651774;5787.305523337058;5238.476152384731;5334.413214546475;5750.453706897838;6045.274567051982;6048.969755151196;3344.7156991655984;3558.2423621693397;3797.31648303297;3819.3698039823116;3849.3071247175776;4852.320675105418;5597.822014636364;5690.317161940594;5710.2576665033275;5727.11229759388;4607.884802879821;5581.302337207849;5592.384837667934;5637.774489020558;5656.37779844225;4329.242382582973;4567.967254571684;4789.736564488987;5073.061483625541;5203.086271687867;4984.476629953948;5014.949850501448;5459.645123066988;5467;5493.258410114583;4731;5316.527814566245;5505;5551.8625329247725;5608.579356548194;4801.399825021872;5500.394956554695;5512.118061110203;5539.418361072029;5556.333240011063 15:25:02 INFO - PROCESS | 2006 | |5;yui.html;685.4139710547482;712.1732755482186;731.9890201646976;734.14105496569;813.8901248331473;11898.226615270012;15804.525864224077;16578.50264492064;17343.306267749274;17432.215550300236;15502.767458488132;16193.514194574152;16209.432669856558;16241.350345986177;16241.431549895757;691.5885048396196;839.9916000840005;866.57537806475;867.6399294292861;872.3326655108815;698.6786078403941;845.344857735254;857.4640849469082;872.1888643561485;873.5544872115236;642.9871402571946;781.1446466119619;893.3835653399166;899.7705585075796;901.1934318784683;715.8604072205922;889.2485849457198;890.4924193209873;890.6971629645951;893.6380765789866;656.4518626946506;674.6559254780049;675.1223409567592;675.8749631318221;677.7390704578723;14971.625709357353;15028.924855375762;15035.849641503555;15044.097354158786;15058.924705376512;13464.86535134646;13791.172529648253;13895.444182232699;13966.511172109029;14184.21986790726;12303.261804291771;12481.126321157522;12651;12771.361431928459;12863.421146048357;4551.908961820745;4586.954130458686;4663.417072865892;4671.392718946549;4673.976630116862;105821;106850;107166;107188.46405767999;107394;8354.66581336752;8627.827443451222;11530.538778448936;11590.304581725124;11851.288922664668;22200;22289.21987730422;22466.887665561568;22595.661065084038;22651.301152413303;7029.929700702927;8113.229243222003;8137.104918458965;8265.8346833063;8490.872636910452;4042.8382864685673;4087.3255912774653;4136.193442278727;4160.167966406731;4273.081287523198;3571.624979377128;3719.628037196259;3724.1992971510713;3724.7392682511963;3729.5268368994093;662.364130434788;666.4135560706547;672.1195234243157;672.640137526421;673.6598018000976;579.2296245992903;667.216021175127;679.942204912588;681.3016657925567;682.8770821252123;8895.644174233088;9203.585838637277;9433.339666223435;9494.430334179971;9580.425174489554;562.7467639562213;580.1616663920594;625.0249610607467;625.956183067181;659.1628631637889;4375.671824613103;4584.3496341317095;4634.768261586907;4682.906341873144;4693.601043911306;116156;116483;119498;127687;155137.2243138777;75760.62119689367;76201.85697214546;76315.47369052698;105575;112533;78810.21189788144;92014;97898.510507447;105323.4733826326;108790;9248.635029378414;9658.227341812779;9744.658936937172;9763.316567840184;9902.70763222194;59867.40132598618;60294;61711.691441542505;62736;63992;81069.78395323957;87128;90861;95882.52058739662;96900;13268.867311326763;13395.732085358433;13396.330183490982;13832.515861944783;14035.438582456996;14405.279736013366;14708.852911471176;14819.555413337617;14855.554333370017;15331;13862.72274554483;13889.374978125807;13895.305234738425;14044.859551404355;14206.502772402917;13617.31913404306;13990.300484975915;14028.438862445797;14268.919984722212;14286.42854285817;13138.35096825729;13337.53318633843;13463.975335002802;13592.320383980563;13759.862401375858;11076.667699968692;11235.719107022063;11298.548058077586;11334;11383.242439266238;9163.77090572741;10103.393796372242;11390.373529455628;11558.537658493566;11614.6515604532 15:25:02 INFO - PROCESS | 2006 | __end_tp_report 15:25:02 INFO - PROCESS | 2006 | __start_cc_report 15:25:02 INFO - PROCESS | 2006 | _x_x_mozilla_cycle_collect,2185 15:25:02 INFO - PROCESS | 2006 | __end_cc_report 15:25:02 INFO - PROCESS | 2006 | __startTimestamp1473373502703__endTimestamp 15:25:02 INFO - PROCESS | 2006 | 15:25:02 INFO - PROCESS | 2006 | ------- Summary: start ------- 15:25:02 INFO - PROCESS | 2006 | Number of tests: 6 15:25:02 INFO - PROCESS | 2006 | 15:25:02 INFO - PROCESS | 2006 | [#0] dojo.html Cycles:165 Average:5555.02 Median:5686.69 stddev:647.63 (11.4%) stddev-sans-first:616.67 15:25:02 INFO - PROCESS | 2006 | Values: 2955.4 3247.4 3269.9 3323.2 3335.8 5456.5 5505.6 5566.5 5570.3 5586.5 4576.5 5517.4 5610.4 5657.7 5662.5 4985.4 5031.4 5069.9 5094.6 5233.9 5178.6 5192.3 5195.4 5198.6 5206.8 5025.3 5039.5 5059.8 5123.7 5192.1 5107.5 5111.6 5114.1 5122.6 5127.4 4188.2 5053.1 5070.7 5097.5 5120.6 5438.9 5451.4 5512.9 5550.8 5625.5 6027.5 6059.8 6090.9 6093.6 6102.6 5334.4 5956.2 5999.2 6152.3 6212.4 5559.9 5577.7 5597.3 5648.1 5653.8 6208.9 6253.2 6255.9 6261.8 6280.3 5739.9 5795.7 5851.1 5858.9 5861.3 5951.6 6019.3 6021.3 6102.8 6132.3 5882.1 5902.3 6009.8 6011.7 6025.0 5857.7 5925.0 5977.1 6045.2 6067.5 4770.8 4812.3 4842.6 4846.2 4863.5 4316.4 4319.4 4328.9 4371.8 4375.9 5455.4 5513.8 5561.2 5567.6 5599.4 6078.8 6088.7 6099.8 6148.6 6196.2 5267.6 5589.4 5595.7 5597.6 5627.9 5005.6 5212.8 5322.4 5324.1 5397.2 6144.2 6222.9 6268.4 6284.8 6422.0 6168.5 6173.4 6181.5 6194.3 6213.9 6299.6 6326.7 6345.9 6345.9 6355.5 5808.6 5817.6 5866.3 5919.6 5939.5 5029.0 6026.2 6030.8 6031.3 6037.1 5846.5 5848.6 5851.3 5853.9 5854.2 4622.5 4811.8 5768.9 5806.1 5868.1 4695.7 5776.1 5812.8 5831.0 5857.4 5924.1 5941.2 5941.4 5945.9 5959.9 5622.8 5670.0 5686.7 5752.2 5752.6 15:25:02 INFO - PROCESS | 2006 | 15:25:02 INFO - PROCESS | 2006 | [#1] ext.html Cycles:180 Average:63791.88 Median:32850.78 stddev:143061.60 (435.5%) stddev-sans-first:143383.91 15:25:02 INFO - PROCESS | 2006 | Values: 463.1 492.4 517.6 524.4 531.4 24609.5 37971.8 39304.8 39515.6 41805.0 40402.6 40723.2 40747.0 40850.6 40904.6 8543.2 9542.3 9731.4 9736.5 9741.3 8508.6 9656.2 11593.7 11729.6 12056.6 11586.2 11641.2 11646.8 11653.2 11653.6 11887.2 11966.9 11968.6 11974.7 11984.0 11662.4 11736.8 11749.9 11752.4 11766.1 38477.4 38731.4 38792.6 38834.6 38857.0 3258.4 3509.5 3662.9 3944.3 4074.6 29527.9 30360.8 30382.2 30388.5 30486.0 3217.6 3280.7 3308.5 3342.6 3342.8 384590.0 386187.0 386229.0 388896.1 394119.0 28645.3 28868.3 29035.7 54678.0 55318.0 32399.7 32797.2 32839.9 32861.7 32908.7 14382.2 14542.6 14551.3 14552.7 14569.9 11160.7 11375.4 12177.0 12188.3 12225.9 6473.5 7471.8 7638.9 7659.1 7682.0 2804.7 3163.2 3190.9 3233.5 3250.0 933.6 1001.2 1044.1 1045.9 1094.6 29539.8 29573.9 29829.6 30243.4 30289.7 1036.7 1095.5 1097.9 1124.6 1141.7 13643.6 14915.9 15422.5 15640.8 15739.6 571878.0 573670.0 580506.0 583969.0 854150.0 54518.0 55434.2 55439.0 55464.0 55863.0 1212.7 1225.7 1345.7 1361.9 1391.5 24456.5 29448.3 29881.3 30761.2 30867.7 50216.7 51053.7 51079.7 51158.7 51257.5 530027.0 536914.0 539089.0 544151.0 546159.0 25517.0 25574.2 25859.9 25932.0 26353.9 43550.6 43834.6 44295.0 44479.6 45591.3 31946.0 33811.5 36348.6 36473.6 37438.6 28915.7 35573.6 35943.8 36558.6 36663.8 35437.0 36044.5 36150.5 36415.5 36831.3 32187.7 32797.2 33331.8 33748.3 33776.0 24439.0 24635.6 24914.1 25161.0 25322.7 15:25:02 INFO - PROCESS | 2006 | 15:25:02 INFO - PROCESS | 2006 | [#2] jquery.html Cycles:180 Average:32345.46 Median:370.70 stddev:91923.14 (24796.9%) stddev-sans-first:92149.59 15:25:02 INFO - PROCESS | 2006 | Values: 338.6 344.0 368.8 372.6 395.9 14915.7 17018.4 20064.0 20140.4 20510.9 15243.2 18984.5 19451.4 19613.3 19710.0 5926.9 5967.9 6198.8 6210.2 6211.6 4315.5 6087.0 6142.6 6154.5 6156.8 5809.4 5883.4 6088.9 6093.4 6129.7 5952.6 6017.7 6019.6 6021.3 6031.5 5133.4 5207.5 5214.9 5394.6 5464.7 12477.8 12492.0 12550.2 12728.0 12744.1 2095.8 2136.5 2256.8 2736.2 2775.1 9462.7 9700.7 9827.0 10048.8 13745.0 1742.5 1753.4 1809.7 1836.7 1854.7 104577.0 108401.0 112594.9 113430.0 115235.0 9688.8 10754.5 10998.0 11050.6 11230.8 12227.8 13633.3 13643.5 13657.7 13678.3 8134.6 8219.3 8242.7 8271.5 8354.8 8261.0 8277.1 8283.8 8454.4 8456.0 4294.7 4296.0 4374.3 4385.8 4392.2 1595.0 1816.9 1868.1 1873.4 1917.5 661.5 794.2 829.5 932.2 996.4 18989.7 19409.5 19933.3 20915.1 21470.5 877.9 883.5 909.2 919.4 996.0 8014.8 10175.3 10183.4 10540.2 10682.2 538778.8 542303.0 546683.0 558851.0 562418.0 79899.0 113863.4 119229.0 129441.7 134934.0 958.4 980.5 1009.1 1009.3 1009.7 15944.7 16189.6 17191.6 18004.2 18340.3 54339.5 55450.7 55458.4 55589.4 55738.0 70568.0 70896.0 73307.6 77508.0 83188.6 7260.5 7454.5 7622.0 7633.2 7817.1 7025.9 8281.6 11815.7 12110.6 12229.8 10064.6 10380.2 10449.6 10503.5 10513.8 10004.6 10365.6 10409.7 10713.0 10726.2 10453.3 10498.9 10784.5 10894.5 11013.8 7462.7 7548.4 7569.4 7639.0 7678.7 9288.0 9884.3 10340.3 10391.3 10508.6 15:25:02 INFO - PROCESS | 2006 | 15:25:02 INFO - PROCESS | 2006 | [#3] mootools.html Cycles:180 Average:5657.12 Median:4927.41 stddev:3173.59 (64.4%) stddev-sans-first:3166.93 15:25:02 INFO - PROCESS | 2006 | Values: 1474.6 1535.2 1729.9 1876.7 1929.2 8465.0 9014.7 9037.0 9040.1 9062.3 7864.2 8109.3 8504.8 8732.1 8806.6 2368.2 2395.6 2397.7 2397.9 2398.9 2345.0 2376.3 2381.6 2385.0 2386.9 2080.4 2162.7 2318.9 2387.7 2393.6 2355.0 2357.8 2383.4 2386.7 2390.6 2297.6 2300.8 2307.9 2308.2 2318.0 8889.3 8961.3 8971.1 8974.9 8994.4 5185.8 5282.1 5359.4 5372.4 5374.8 3462.2 3511.8 3664.1 3681.1 3681.2 1050.7 1084.4 1092.2 1107.1 1109.7 9846.5 9859.9 11511.4 11670.4 11684.1 4518.1 4543.8 4752.4 4757.8 4759.9 9990.3 9997.0 9998.3 10013.3 10016.8 5352.7 5360.6 5360.9 5364.8 5369.1 4692.4 4861.7 4911.3 4916.2 4925.3 3079.5 3688.4 3750.9 3790.3 3799.5 1109.1 1166.5 1178.9 1179.3 1181.3 746.0 850.3 854.8 876.0 927.2 5233.8 5528.3 5801.1 6044.7 6049.9 917.7 960.2 971.8 976.5 996.5 2825.7 3696.7 3868.7 3990.1 4006.8 9617.3 10467.7 10521.7 10931.8 10933.5 10881.3 10951.0 10960.1 10963.1 10967.5 7684.5 8183.2 8351.0 8353.0 8367.4 5651.5 5947.9 5962.9 5967.0 6004.3 9463.8 9978.2 10075.2 10079.7 10128.2 9920.8 10091.4 10092.5 10110.4 10114.6 6834.7 6841.7 6997.1 7019.1 7034.0 8139.3 8142.5 8262.8 8263.3 8268.8 7396.8 7465.4 7522.7 7576.4 7663.0 6773.2 6782.9 7368.5 7379.1 7457.4 6792.6 6858.0 6938.6 6960.0 6963.7 4828.3 4865.7 4929.5 4941.2 5051.1 4851.4 4886.7 4889.6 4942.0 5015.9 15:25:02 INFO - PROCESS | 2006 | 15:25:02 INFO - PROCESS | 2006 | [#4] prototype.html Cycles:180 Average:4463.69 Median:5015.47 stddev:1384.20 (27.6%) stddev-sans-first:1352.22 15:25:02 INFO - PROCESS | 2006 | Values: 293.1 296.2 304.9 308.0 309.1 4295.9 5334.0 5339.0 5376.5 5387.8 4886.3 4988.9 5016.0 5185.3 5313.9 3991.9 4044.0 4060.1 4062.3 4077.3 3341.4 3687.5 3814.8 4054.4 4078.2 3741.3 3749.7 3766.9 3919.5 4037.9 3245.5 3487.4 3795.4 3992.4 4009.2 2863.0 3501.8 3509.0 3512.1 3513.4 4426.7 5082.4 5105.2 5109.0 5114.0 4134.2 4192.3 4202.9 4549.6 4569.2 4479.4 4740.5 5441.6 5443.0 5486.9 3838.0 3849.2 3855.1 3855.8 3866.8 4777.7 5490.2 5685.5 5709.9 5744.3 3594.9 3732.3 3823.3 3863.6 3893.7 3195.9 3970.6 3976.6 4010.8 4053.0 4321.0 4553.6 4572.5 4612.8 4626.9 5467.8 5483.4 5499.0 5509.5 5510.7 1210.7 1348.8 1374.1 1380.2 1387.1 842.0 871.5 899.6 912.6 923.9 4606.7 5077.3 5123.6 5128.6 5161.3 5440.8 5559.0 5608.2 5614.4 5620.7 3960.4 4578.7 4872.5 5035.5 5310.1 2326.4 2776.8 2991.3 3016.5 3019.6 5434.4 5943.9 5953.7 5969.2 5988.9 5371.4 5508.9 5854.0 5855.3 5857.8 5908.8 5916.2 5952.6 5961.2 5978.3 5221.5 5369.7 5707.3 5730.5 5790.8 4519.6 5417.4 5756.0 5773.4 5787.3 5238.5 5334.4 5750.5 6045.3 6049.0 3344.7 3558.2 3797.3 3819.4 3849.3 4852.3 5597.8 5690.3 5710.3 5727.1 4607.9 5581.3 5592.4 5637.8 5656.4 4329.2 4568.0 4789.7 5073.1 5203.1 4984.5 5014.9 5459.6 5467.0 5493.3 4731.0 5316.5 5505.0 5551.9 5608.6 4801.4 5500.4 5512.1 5539.4 5556.3 15:25:02 INFO - PROCESS | 2006 | 15:25:02 INFO - PROCESS | 2006 | [#5] yui.html Cycles:180 Average:22796.74 Median:4324.38 stddev:34249.06 (792.0%) stddev-sans-first:34304.90 15:25:02 INFO - PROCESS | 2006 | Values: 685.4 712.2 732.0 734.1 813.9 11898.2 15804.5 16578.5 17343.3 17432.2 15502.8 16193.5 16209.4 16241.4 16241.4 691.6 840.0 866.6 867.6 872.3 698.7 845.3 857.5 872.2 873.6 643.0 781.1 893.4 899.8 901.2 715.9 889.2 890.5 890.7 893.6 656.5 674.7 675.1 675.9 677.7 14971.6 15028.9 15035.8 15044.1 15058.9 13464.9 13791.2 13895.4 13966.5 14184.2 12303.3 12481.1 12651.0 12771.4 12863.4 4551.9 4587.0 4663.4 4671.4 4674.0 105821.0 106850.0 107166.0 107188.5 107394.0 8354.7 8627.8 11530.5 11590.3 11851.3 22200.0 22289.2 22466.9 22595.7 22651.3 7029.9 8113.2 8137.1 8265.8 8490.9 4042.8 4087.3 4136.2 4160.2 4273.1 3571.6 3719.6 3724.2 3724.7 3729.5 662.4 666.4 672.1 672.6 673.7 579.2 667.2 679.9 681.3 682.9 8895.6 9203.6 9433.3 9494.4 9580.4 562.7 580.2 625.0 626.0 659.2 4375.7 4584.3 4634.8 4682.9 4693.6 116156.0 116483.0 119498.0 127687.0 155137.2 75760.6 76201.9 76315.5 105575.0 112533.0 78810.2 92014.0 97898.5 105323.5 108790.0 9248.6 9658.2 9744.7 9763.3 9902.7 59867.4 60294.0 61711.7 62736.0 63992.0 81069.8 87128.0 90861.0 95882.5 96900.0 13268.9 13395.7 13396.3 13832.5 14035.4 14405.3 14708.9 14819.6 14855.6 15331.0 13862.7 13889.4 13895.3 14044.9 14206.5 13617.3 13990.3 14028.4 14268.9 14286.4 13138.4 13337.5 13464.0 13592.3 13759.9 11076.7 11235.7 11298.5 11334.0 11383.2 9163.8 10103.4 11390.4 11558.5 11614.7 15:25:02 INFO - PROCESS | 2006 | -------- Summary: end -------- 15:25:02 INFO - PROCESS | 2006 | 15:25:03 INFO - TEST-INFO | 2006: exit 0 15:25:03 INFO - TEST-OK | dromaeo_css | took 1117553ms 15:25:03 INFO - TEST-START | kraken 15:25:03 INFO - operating with platform_type : mac_ 15:25:03 INFO - Initialising browser for kraken test... 15:25:03 INFO - TEST-INFO | started process 2008 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/tmpSo2zkt/profile http://localhost:49400/getInfo.html) 15:25:05 INFO - PROCESS | 2008 | __metrics Screen width/height:1600/1200 15:25:05 INFO - PROCESS | 2008 | colorDepth:24 15:25:05 INFO - PROCESS | 2008 | Browser inner width/height: 1024/689 15:25:05 INFO - PROCESS | 2008 | __metrics 15:25:05 INFO - PROCESS | 2008 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:25:05 INFO - PROCESS | 2008 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:25:06 INFO - TEST-INFO | 2008: exit 0 15:25:06 INFO - Browser initialized. 15:25:06 INFO - Running cycle 1/1 for kraken test... 15:25:06 INFO - TEST-INFO | started process 2009 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/tmpSo2zkt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:25:18 INFO - PROCESS | 2009 | RSS: Main: 232284160 15:25:18 INFO - PROCESS | 2009 | 15:25:21 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49400/tests/kraken/driver.html?testName=audio-beat-detection) 15:25:22 INFO - PROCESS | 2009 | RSS: Main: 292085760 15:25:22 INFO - PROCESS | 2009 | 15:25:24 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49400/tests/kraken/driver.html?testName=audio-dft) 15:25:25 INFO - PROCESS | 2009 | RSS: Main: 288681984 15:25:25 INFO - PROCESS | 2009 | 15:25:28 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49400/tests/kraken/driver.html?testName=audio-fft) 15:25:29 INFO - PROCESS | 2009 | RSS: Main: 376565760 15:25:29 INFO - PROCESS | 2009 | 15:25:31 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49400/tests/kraken/driver.html?testName=audio-oscillator) 15:25:32 INFO - PROCESS | 2009 | RSS: Main: 278700032 15:25:32 INFO - PROCESS | 2009 | 15:25:34 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49400/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:25:34 INFO - PROCESS | 2009 | RSS: Main: 282804224 15:25:34 INFO - PROCESS | 2009 | 15:25:37 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49400/tests/kraken/driver.html?testName=imaging-darkroom) 15:25:38 INFO - PROCESS | 2009 | RSS: Main: 320884736 15:25:38 INFO - PROCESS | 2009 | 15:25:41 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49400/tests/kraken/driver.html?testName=imaging-desaturate) 15:25:41 INFO - PROCESS | 2009 | RSS: Main: 322404352 15:25:41 INFO - PROCESS | 2009 | 15:25:44 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49400/tests/kraken/driver.html?testName=json-parse-financial) 15:25:45 INFO - PROCESS | 2009 | RSS: Main: 320401408 15:25:45 INFO - PROCESS | 2009 | 15:25:46 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49400/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:25:47 INFO - PROCESS | 2009 | RSS: Main: 297689088 15:25:47 INFO - PROCESS | 2009 | 15:25:49 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49400/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:25:50 INFO - PROCESS | 2009 | RSS: Main: 303042560 15:25:50 INFO - PROCESS | 2009 | 15:25:52 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49400/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:25:53 INFO - PROCESS | 2009 | RSS: Main: 308350976 15:25:53 INFO - PROCESS | 2009 | 15:25:55 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49400/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:25:56 INFO - PROCESS | 2009 | RSS: Main: 318488576 15:25:56 INFO - PROCESS | 2009 | 15:25:59 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49400/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:25:59 INFO - PROCESS | 2009 | RSS: Main: 314593280 15:25:59 INFO - PROCESS | 2009 | 15:26:01 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49400/tests/kraken/driver.html?testName=ai-astar) 15:26:01 INFO - PROCESS | 2009 | RSS: Main: 358436864 15:26:01 INFO - PROCESS | 2009 | 15:26:01 INFO - PROCESS | 2009 | __start_tp_report 15:26:01 INFO - PROCESS | 2009 | _x_x_mozilla_page_load 15:26:01 INFO - PROCESS | 2009 | _x_x_mozilla_page_load_details 15:26:01 INFO - PROCESS | 2009 | |i|pagename|runs| 15:26:01 INFO - PROCESS | 2009 | |0;ai-astar;113;112;116;112;112;112;110;114;112;123 15:26:01 INFO - PROCESS | 2009 | |1;audio-beat-detection;148;108;132;160;109;133;132;108;162;108 15:26:01 INFO - PROCESS | 2009 | |2;audio-dft;186;197;260;187;183;183;183;181;182;181 15:26:01 INFO - PROCESS | 2009 | |3;audio-fft;77;73;76;184;82;83;47;56;162;82 15:26:01 INFO - PROCESS | 2009 | |4;audio-oscillator;82;82;139;69;70;78;120;68;68;118 15:26:01 INFO - PROCESS | 2009 | |5;imaging-gaussian-blur;87;85;88;84;87;84;88;97;122;85 15:26:01 INFO - PROCESS | 2009 | |6;imaging-darkroom;95;92;93;94;93;93;94;92;92;92 15:26:01 INFO - PROCESS | 2009 | |7;imaging-desaturate;86;86;86;85;86;86;83;86;86;87 15:26:01 INFO - PROCESS | 2009 | |8;json-parse-financial;55;56;56;54;51;53;72;84;85;83 15:26:01 INFO - PROCESS | 2009 | |9;json-stringify-tinderbox;64;68;64;74;72;81;71;70;69;71 15:26:01 INFO - PROCESS | 2009 | |10;stanford-crypto-aes;83;87;88;86;87;88;84;85;85;87 15:26:01 INFO - PROCESS | 2009 | |11;stanford-crypto-ccm;137;146;126;158;151;135;138;136;182;137 15:26:01 INFO - PROCESS | 2009 | |12;stanford-crypto-pbkdf2;167;171;170;164;165;167;168;166;168;172 15:26:01 INFO - PROCESS | 2009 | |13;stanford-crypto-sha256-iterative;68;66;66;70;84;89;72;69;67;67 15:26:01 INFO - PROCESS | 2009 | __end_tp_report 15:26:01 INFO - PROCESS | 2009 | __start_cc_report 15:26:01 INFO - PROCESS | 2009 | _x_x_mozilla_cycle_collect,2033 15:26:01 INFO - PROCESS | 2009 | __end_cc_report 15:26:01 INFO - PROCESS | 2009 | __startTimestamp1473373561978__endTimestamp 15:26:01 INFO - PROCESS | 2009 | 15:26:01 INFO - PROCESS | 2009 | ------- Summary: start ------- 15:26:01 INFO - PROCESS | 2009 | Number of tests: 14 15:26:01 INFO - PROCESS | 2009 | 15:26:01 INFO - PROCESS | 2009 | [#0] ai-astar Cycles:10 Average:113.60 Median:112.50 stddev:3.66 (3.3%) stddev-sans-first:3.87 15:26:01 INFO - PROCESS | 2009 | Values: 113.0 112.0 116.0 112.0 112.0 112.0 110.0 114.0 112.0 123.0 15:26:01 INFO - PROCESS | 2009 | 15:26:01 INFO - PROCESS | 2009 | [#1] audio-beat-detection Cycles:10 Average:130.00 Median:132.50 stddev:21.49 (16.2%) stddev-sans-first:21.79 15:26:01 INFO - PROCESS | 2009 | Values: 148.0 108.0 132.0 160.0 109.0 133.0 132.0 108.0 162.0 108.0 15:26:01 INFO - PROCESS | 2009 | 15:26:01 INFO - PROCESS | 2009 | [#2] audio-dft Cycles:10 Average:192.30 Median:184.50 stddev:24.25 (13.1%) stddev-sans-first:25.62 15:26:01 INFO - PROCESS | 2009 | Values: 186.0 197.0 260.0 187.0 183.0 183.0 183.0 181.0 182.0 181.0 15:26:01 INFO - PROCESS | 2009 | 15:26:01 INFO - PROCESS | 2009 | [#3] audio-fft Cycles:10 Average:92.20 Median:76.50 stddev:44.48 (58.1%) stddev-sans-first:46.84 15:26:01 INFO - PROCESS | 2009 | Values: 77.0 73.0 76.0 184.0 82.0 83.0 47.0 56.0 162.0 82.0 15:26:01 INFO - PROCESS | 2009 | 15:26:01 INFO - PROCESS | 2009 | [#4] audio-oscillator Cycles:10 Average:89.40 Median:69.50 stddev:26.17 (37.7%) stddev-sans-first:27.62 15:26:01 INFO - PROCESS | 2009 | Values: 82.0 82.0 139.0 69.0 70.0 78.0 120.0 68.0 68.0 118.0 15:26:01 INFO - PROCESS | 2009 | 15:26:01 INFO - PROCESS | 2009 | [#5] imaging-gaussian-blur Cycles:10 Average:90.70 Median:87.00 stddev:11.62 (13.4%) stddev-sans-first:12.25 15:26:01 INFO - PROCESS | 2009 | Values: 87.0 85.0 88.0 84.0 87.0 84.0 88.0 97.0 122.0 85.0 15:26:01 INFO - PROCESS | 2009 | 15:26:01 INFO - PROCESS | 2009 | [#6] imaging-darkroom Cycles:10 Average:93.00 Median:93.00 stddev:1.05 (1.1%) stddev-sans-first:0.83 15:26:01 INFO - PROCESS | 2009 | Values: 95.0 92.0 93.0 94.0 93.0 93.0 94.0 92.0 92.0 92.0 15:26:01 INFO - PROCESS | 2009 | 15:26:01 INFO - PROCESS | 2009 | [#7] imaging-desaturate Cycles:10 Average:85.70 Median:86.00 stddev:1.06 (1.2%) stddev-sans-first:1.12 15:26:01 INFO - PROCESS | 2009 | Values: 86.0 86.0 86.0 85.0 86.0 86.0 83.0 86.0 86.0 87.0 15:26:01 INFO - PROCESS | 2009 | 15:26:01 INFO - PROCESS | 2009 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:64.00 stddev:14.36 (22.4%) stddev-sans-first:14.78 15:26:01 INFO - PROCESS | 2009 | Values: 55.0 56.0 56.0 54.0 51.0 53.0 72.0 84.0 85.0 83.0 15:26:01 INFO - PROCESS | 2009 | 15:26:01 INFO - PROCESS | 2009 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:71.00 stddev:4.93 (6.9%) stddev-sans-first:4.65 15:26:01 INFO - PROCESS | 2009 | Values: 64.0 68.0 64.0 74.0 72.0 81.0 71.0 70.0 69.0 71.0 15:26:01 INFO - PROCESS | 2009 | 15:26:01 INFO - PROCESS | 2009 | [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:87.00 stddev:1.70 (2.0%) stddev-sans-first:1.41 15:26:01 INFO - PROCESS | 2009 | Values: 83.0 87.0 88.0 86.0 87.0 88.0 84.0 85.0 85.0 87.0 15:26:01 INFO - PROCESS | 2009 | 15:26:01 INFO - PROCESS | 2009 | [#11] stanford-crypto-ccm Cycles:10 Average:144.60 Median:142.00 stddev:15.96 (11.2%) stddev-sans-first:16.69 15:26:01 INFO - PROCESS | 2009 | Values: 137.0 146.0 126.0 158.0 151.0 135.0 138.0 136.0 182.0 137.0 15:26:01 INFO - PROCESS | 2009 | 15:26:01 INFO - PROCESS | 2009 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.80 Median:168.00 stddev:2.57 (1.5%) stddev-sans-first:2.71 15:26:01 INFO - PROCESS | 2009 | Values: 167.0 171.0 170.0 164.0 165.0 167.0 168.0 166.0 168.0 172.0 15:26:01 INFO - PROCESS | 2009 | 15:26:01 INFO - PROCESS | 2009 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.80 Median:69.50 stddev:8.05 (11.6%) stddev-sans-first:8.42 15:26:01 INFO - PROCESS | 2009 | Values: 68.0 66.0 66.0 70.0 84.0 89.0 72.0 69.0 67.0 67.0 15:26:01 INFO - PROCESS | 2009 | -------- Summary: end -------- 15:26:01 INFO - PROCESS | 2009 | 15:26:02 INFO - TEST-INFO | 2009: exit 0 15:26:02 INFO - TEST-OK | kraken | took 59392ms 15:26:02 INFO - SUITE-END | took 1176s 15:26:03 INFO - Completed test suite (00:19:37) 15:26:03 INFO - javascript benchmark 15:26:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2955.4236923799863, 3247.350529894021, 3269.901902942911, 3323.185819474229, 3335.7831740936836, 5456.508914197721, 5505.614606977508, 5566.526845218151, 5570.275864137661, 5586.497215250632, 4576.519465456129, 5517.420670829565, 5610.410906854772, 5657.745401456934, 5662.546996240301, 4985.40175178979, 5031.396232452111, 5069.923951140736, 5094.6433749637545, 5233.921491177635, 5178.611604129686, 5192.299039629658, 5195.350581177353, 5198.558122559587, 5206.843794686166, 5025.321581586476, 5039.496050394968, 5059.822906198302, 5123.718195499246, 5192.091384007803, 5107.540321371057, 5111.642185047048, 5114.05390002847, 5122.641415100945, 5127.435982041972, 4188.183304255671, 5053.065182941167, 5070.7464626768515, 5097.464766199532, 5120.641555091145, 5438.891222175574, 5451.400345961941, 5512.889742205173, 5550.833474995757, 5625.493705566478, 6027.5177985761475, 6059.757609695607, 6090.939090609126, 6093.6039157454625, 6102.572819902609, 5334.3598768147685, 5956.16613674086, 5999.190109335227, 6152.292486364081, 6212.4408803207925, 5559.861003474945, 5577.721113944286, 5597.272354593958, 5648.124540696158, 5653.773849046074, 6208.906866397007, 6253.155823963798, 6255.937440625627, 6261.780837670751, 6280.340564240689, 5739.942600574024, 5795.710214489259, 5851.063829787214, 5858.882822343529, 5861.267341582302, 5951.613145145551, 6019.337872833985, 6021.337652858182, 6102.786402475892, 6132.324915438508, 5882.147088672119, 5902.2622172228475, 6009.759609615654, 6011.729472173763, 6025.0, 5857.677827719516, 5924.970375148183, 5977.073553599199, 6045.153678485016, 6067.54493412987, 4770.833020844254, 4812.302216178705, 4842.612590992712, 4846.200376937766, 4863.513648635179, 4316.352547117957, 4319.43847299849, 4328.935065974013, 4371.75955322454, 4375.890602734893, 5455.399906010336, 5513.8345849624575, 5561.249231353715, 5567.610267281332, 5599.440055994368, 6078.848028799227, 6088.665123418255, 6099.794059995464, 6148.585825260216, 6196.194494715658, 5267.578593712494, 5589.357223919302, 5595.7481913313995, 5597.63615365, 5627.915581266284, 5005.624578156653, 5212.7914883405, 5322.414534401213, 5324.068288049576, 5397.163439666858, 6144.15675297409, 6222.906656400158, 6268.404501572343, 6284.811455656338, 6422.0, 6168.475679567287, 6173.382661733791, 6181.505479561714, 6194.318624951342, 6213.9067913980425, 6299.590526615939, 6326.746930122744, 6345.936540634534, 6345.936540634534, 6355.491560675227, 5808.593398462067, 5817.621854579439, 5866.266716660418, 5919.6152250103605, 5939.524838013035, 5029.0, 6026.156338112755, 6030.758769649165, 6031.276246850406, 6037.124616930609, 5846.532277417881, 5848.619839710405, 5851.327097383855, 5853.94146058534, 5854.209681693087, 4622.491525932212, 4811.807527698993, 5768.884622307446, 5806.1000544915605, 5868.119782032559, 4695.718256904596, 5776.133579963039, 5812.82561523155, 5830.970845145747, 5857.414258574108, 5924.081767326072, 5941.168236446814, 5941.37615550361, 5945.910811337747, 5959.940400595939, 5622.803201887914, 5670.0, 5686.687232202268, 5752.165935939349, 5752.597318187687], "value": 5514.349162124317, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.1454965588489, 492.3722254125989, 517.5730022731243, 524.3969435149578, 531.3889027618238, 24609.507809843813, 37971.81014094929, 39304.82085537431, 39515.60484395159, 41805.0, 40402.595974040254, 40723.18553628925, 40747.0, 40850.59149408505, 40904.59095409045, 8543.231109200171, 9542.332036757447, 9731.367461115005, 9736.464494452803, 9741.269404794633, 8508.574571271443, 9656.22750179984, 11593.652190434259, 11729.648110556696, 12056.63830085099, 11586.246893951908, 11641.18511704181, 11646.767064658701, 11653.242539234965, 11653.592124275652, 11887.227330223552, 11966.940165299206, 11968.640940771791, 11974.700632484213, 11984.0, 11662.416879156008, 11736.765264694744, 11749.882501174965, 11752.412379380996, 11766.058715302841, 38477.42283865744, 38731.41902871459, 38792.61207387918, 38834.611653883665, 38857.028574285585, 3258.4297747894025, 3509.508668786372, 3662.8901132966053, 3944.2703099926607, 4074.6332830045144, 29527.85236073806, 30360.8481957591, 30382.240443988856, 30388.54417183744, 30486.0, 3217.581714377139, 3280.6555311692405, 3308.4871844864083, 3342.615599206098, 3342.8161451120177, 384590.0, 386187.0, 386229.0, 388896.0555197234, 394119.0, 28645.28386790347, 28868.278293042422, 29035.7096429033, 54678.0, 55318.0, 32399.676003240136, 32797.18007049843, 32839.88021137988, 32861.67138328634, 32908.67091329056, 14382.208978506073, 14542.563723088324, 14551.272436378138, 14552.708945821025, 14569.927150364181, 11160.720981975353, 11375.431228438545, 12176.964957978495, 12188.268703877795, 12225.877741222652, 6473.514486413538, 7471.775846724607, 7638.885416718754, 7659.08091029069, 7682.0, 2804.7055059218897, 3163.225009872596, 3190.9469874941215, 3233.531137984979, 3250.0, 933.6265493802533, 1001.1840350114634, 1044.1177205261542, 1045.8849526552074, 1094.6168840905618, 29539.762758124398, 29573.852130739207, 29829.552556711667, 30243.395132097234, 30289.697103029128, 1036.72008557689, 1095.5398732532356, 1097.8792332843382, 1124.600766727816, 1141.7374003979132, 13643.590692279247, 14915.850841491447, 15422.460213892684, 15640.82985232533, 15739.606509837116, 571878.0, 573670.0, 580506.0, 583969.0, 854150.0, 54518.0, 55434.16848747272, 55439.0, 55464.0, 55863.0, 1212.7028877924965, 1225.6997035726126, 1345.6837643153851, 1361.884239839605, 1391.4781956766212, 24456.510869782862, 29448.263124528938, 29881.252968675955, 30761.230969225948, 30867.69132308648, 50216.74891625519, 51053.74473127611, 51079.744601276754, 51158.74420627873, 51257.48742512527, 530027.0, 536914.0, 539089.0, 544151.0, 546159.0, 25517.0, 25574.23277301684, 25859.870700646377, 25932.0, 26353.868230658725, 43550.56449435592, 43834.56165438305, 44295.0, 44479.555204448836, 45591.31613025874, 31946.0, 33811.4928276081, 36348.63651363453, 36473.635263647026, 37438.62561374351, 28915.7108428913, 35573.577056917435, 35943.82028089843, 36558.63441365552, 36663.816680916425, 35437.0, 36044.4593331095, 36150.4577431344, 36415.453768192965, 36831.26337473182, 32187.678123219404, 32797.18007049843, 33331.83334083411, 33748.325033499685, 33776.0, 24439.02243910224, 24635.63046554339, 24914.12800551972, 25160.993560257386, 25322.74677253204], "value": 18290.134663499368, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.55818157304714, 344.0469898381482, 368.793475653634, 372.6143441538008, 395.851555666625, 14915.701685966274, 17018.40435584755, 20064.0, 20140.39578812636, 20510.89744551275, 15243.237838108078, 18984.525386865334, 19451.41645750626, 19613.31353402631, 19710.0, 5926.9407305926925, 5967.940320596804, 6198.81403557894, 6210.161628180205, 6211.6273023618505, 4315.4605674290715, 6087.0, 6142.6314421134875, 6154.476869466101, 6156.815295541141, 5809.419058094199, 5883.352831188566, 6088.939110608903, 6093.421124993119, 6129.662868542228, 5952.58331916766, 6017.729202185912, 6019.608725432855, 6021.277446706379, 6031.487323577501, 5133.383993920754, 5207.531322181022, 5214.921776173361, 5394.622376433651, 5464.672119672832, 12477.75044499105, 12492.0, 12550.246985180827, 12727.981761459061, 12744.107912446132, 2095.758987716402, 2136.4872430616697, 2256.8307376946796, 2736.220177249494, 2775.111964171466, 9462.716118516455, 9700.660476883346, 9826.950865245699, 10048.849267260997, 13745.03784735069, 1742.451127894709, 1753.3512600337829, 1809.746635471035, 1836.6602178597002, 1854.7403363529118, 104577.0, 108401.0, 112594.87405125926, 113430.0, 115235.0, 9688.788901387326, 10754.516046777913, 10998.010179083922, 11050.613228536962, 11230.775384492264, 12227.75544489123, 13633.318334083257, 13643.454261829615, 13657.726845463234, 13678.31608419575, 8134.552599607078, 8219.260266576037, 8242.670293188205, 8271.462354946909, 8354.791130221793, 8260.958695206606, 8277.089520152778, 8283.792905177299, 8454.408191426664, 8455.95772021136, 4294.677899157513, 4295.9570404295555, 4374.343848422762, 4385.758783266951, 4392.209402307615, 1594.9920250398675, 1816.8597412170034, 1868.112646492932, 1873.4379686094117, 1917.5493758966632, 661.4939571228015, 794.1700922535964, 829.5396055189428, 932.16105505046, 996.4170959988343, 18989.715154272697, 19409.51476213106, 19933.302334418226, 20915.058822353032, 21470.463238419165, 877.9165979231963, 883.4743327719997, 909.1999040844015, 919.3932004876749, 995.9741466289734, 8014.79963000918, 10175.28772985884, 10183.388996660224, 10540.209484288709, 10682.1988350874, 538778.8366349016, 542303.0, 546683.0, 558851.0, 562418.0, 79899.0, 113863.43068284605, 119229.0, 129441.70558294485, 134934.0, 958.4297343080856, 980.4754456365953, 1009.0867764672983, 1009.268280496634, 1009.742515658517, 15944.681106378039, 16189.59526011859, 17191.57021074483, 18004.18981145878, 18340.266389344277, 54339.45660543344, 55450.72274638601, 55458.44541554533, 55589.444105558425, 55738.0, 70568.0, 70896.0, 73307.63346183448, 77508.0, 83188.58405707932, 7260.491765576359, 7454.515456495311, 7622.047244094489, 7633.1985141559335, 7817.140114587504, 7025.859482810418, 8281.585920703821, 11815.70460738454, 12110.636680899586, 12229.75540489203, 10064.647737329158, 10380.221483388625, 10449.582016719247, 10503.474826258504, 10513.842292365774, 10004.649837255953, 10365.585376584851, 10409.739756506147, 10713.035826775316, 10726.249162558546, 10453.320534165256, 10498.895011050097, 10784.460776960963, 10894.455277236266, 11013.779724405307, 7462.664180112017, 7548.358389536951, 7569.432292577969, 7639.04511936008, 7678.6544605494, 9287.953560232156, 9884.30809843304, 10340.27618066728, 10391.272610917164, 10508.579656813641], "value": 8972.29520467522, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1474.6165996840818, 1535.1556643845886, 1729.8529624981875, 1876.7372567840514, 1929.1800984581557, 8464.957675211623, 9014.684486042981, 9037.005929347766, 9040.14118658728, 9062.320325975554, 7864.213578642133, 8109.270165685087, 8504.787380315494, 8732.08313127122, 8806.55967201639, 2368.230325144331, 2395.6167013277877, 2397.6763136976547, 2397.9160729374476, 2398.880055997202, 2345.0, 2376.310869847742, 2381.57131716291, 2385.010220758383, 2386.940326491843, 2080.3862860456125, 2162.7080344153574, 2318.9072437102495, 2387.6776635154292, 2393.5930891748358, 2354.952900941989, 2357.787799098081, 2383.368407372048, 2386.749391313904, 2390.5696974544576, 2297.552541898593, 2300.804431623297, 2307.9423014424774, 2308.1921327535397, 2318.014843691424, 8889.333300002525, 8961.283097352196, 8971.147740964598, 8974.86537701935, 8994.415363001384, 5185.792568297264, 5282.128448805943, 5359.38367087786, 5372.355317361941, 5374.7850085996515, 3462.2036931505895, 3511.806850623215, 3664.0839790052487, 3681.061329360996, 3681.1717363593243, 1050.666002738703, 1084.4198353880636, 1092.153580974743, 1107.1198397274106, 1109.7447587055024, 9846.458444785534, 9859.852102218472, 11511.424428778611, 11670.35813030283, 11684.123690723145, 4518.096380723869, 4543.750093744809, 4752.429708434941, 4757.8096876125255, 4759.881002974953, 9990.300678952404, 9996.950015249877, 9998.250131240186, 10013.29906906524, 10016.849747253797, 5352.705601191972, 5360.597955153379, 5360.892782144335, 5364.8122315718765, 5369.060414427459, 4692.389989301437, 4861.732604706775, 4911.312416261726, 4916.164252077156, 4925.310456536088, 3079.5380692896247, 3688.3729765939856, 3750.9062273442837, 3790.2798468290935, 3799.506064211635, 1109.0683825586545, 1166.4867458318313, 1178.9056875449944, 1179.3076934598878, 1181.3443538835857, 746.0078096132131, 850.3112478892117, 854.8418542569644, 875.9737207883646, 927.2211342472356, 5233.816816411408, 5528.281323428009, 5801.071828507418, 6044.667543285073, 6049.879002419927, 917.6833992272656, 960.2078285414422, 971.8250714871391, 976.5312649928075, 996.4668902137322, 2825.7174282571577, 3696.672994305147, 3868.6518213360932, 3990.06233535119, 4006.7996600169877, 9617.326787124834, 10467.743870735409, 10521.736956576147, 10931.836022459669, 10933.45332733376, 10881.347119172875, 10951.0, 10960.123190144928, 10963.122950163968, 10967.50646220922, 7684.538927664358, 8183.181681831769, 8350.958245208734, 8353.0, 8367.372447066495, 5651.491365777099, 5947.851303717442, 5962.880742385215, 5966.956852005422, 6004.279486461478, 9463.763405914908, 9978.151857092229, 10075.244356673133, 10079.748006299607, 10128.240381971234, 9920.817420167552, 10091.444970526398, 10092.495375231356, 10110.443925584164, 10114.6459873907, 6834.726610935507, 6841.657917104224, 6997.090378250795, 7019.087518622547, 7034.0, 8139.3488520917, 8142.47073940192, 8262.793430164294, 8263.256306932404, 8268.793280168044, 7396.778096657109, 7465.4027677786735, 7522.736704215326, 7576.393888489018, 7663.042119735033, 6773.221079575912, 6782.864342713019, 7368.521046131984, 7379.077615298088, 7457.403407727477, 6792.558483698544, 6857.965710171417, 6938.618375989369, 6960.025596416607, 6963.651817409008, 4828.324034635084, 4865.732384718733, 4929.507049295042, 4941.184704523714, 5051.090803655377, 4851.417829860298, 4886.706797592155, 4889.63327750413, 4941.97529012367, 5015.899682006412], "value": 4544.571924016592, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.14986539036806, 296.17515220111983, 304.87347750683466, 307.95996520452337, 309.07124092103226, 4295.892602684934, 5334.0, 5339.0, 5376.542993845518, 5387.841614052983, 4886.26705994101, 4988.925166122502, 5016.0, 5185.325907632012, 5313.867153321169, 3991.9002024949464, 4044.0496483326397, 4060.117316705143, 4062.2687916175078, 4077.2660921034203, 3341.448660970937, 3687.483752274684, 3814.817406603948, 4054.3918412238104, 4078.2047500737367, 3741.3452645787015, 3749.662530372266, 3766.905827354324, 3919.451276821247, 4037.899052523681, 3245.480723084307, 3487.3548393547276, 3795.430685397185, 3992.441058251818, 4009.2382447334912, 2862.9713702862914, 3501.7898926064518, 3508.9824550877083, 3512.0693016350688, 3513.350030244387, 4426.734395936254, 5082.415522214956, 5105.234214867763, 5109.029284435983, 5113.985521365747, 4134.214499245164, 4192.266353388145, 4202.85290014848, 4549.5905368517, 4569.1547063793305, 4479.350494178326, 4740.549647783455, 5441.646292990943, 5442.972785136049, 5486.890262194774, 3837.9808100959594, 3849.2109117630816, 3855.055511399697, 3855.768653880776, 3866.8259928303296, 4777.737224452689, 5490.215679904008, 5685.488306052475, 5709.914351284734, 5744.30542989819, 3594.9149493293826, 3732.309522738248, 3823.3309170895536, 3863.632954869283, 3893.669038131734, 3195.872165113416, 3970.622790834866, 3976.622220889011, 4010.7794071325775, 4052.979735101306, 4320.978395108004, 4553.61294289989, 4572.474165470947, 4612.815487380534, 4626.871639046077, 5467.781288748405, 5483.369412517532, 5498.972505137449, 5509.504144626922, 5510.6969116698965, 1210.7033776724759, 1348.797680347936, 1374.1480282224884, 1380.1856904426236, 1387.1065994917067, 841.9938173882274, 871.4901782457207, 899.6221586933503, 912.6167009855876, 923.9168474837162, 4606.700564463299, 5077.339945807022, 5123.616623511631, 5128.640995130305, 5161.329027226437, 5440.809571664972, 5558.999380111619, 5608.1587761835235, 5614.410486898899, 5620.662760234399, 3960.4455376247347, 4578.679492435497, 4872.464028956882, 5035.471275516092, 5310.123829568155, 2326.4183954011496, 2776.750462291977, 2991.2521869532616, 3016.5324374721954, 3019.5923550320863, 5434.375046869661, 5943.940560594425, 5953.672548009901, 5969.2240008799445, 5988.880222395528, 5371.409144994048, 5508.91736623951, 5853.970730146407, 5855.2973643163095, 5857.769868327614, 5908.822735317775, 5916.2013128228, 5952.583319167703, 5961.225040744762, 5978.312494063239, 5221.4778522149, 5369.704666243394, 5707.286589176353, 5730.541556675539, 5790.768369265183, 4519.638428925744, 5417.377001644703, 5756.028340445079, 5773.393793651774, 5787.305523337058, 5238.476152384731, 5334.413214546475, 5750.453706897838, 6045.274567051982, 6048.969755151196, 3344.7156991655984, 3558.2423621693397, 3797.31648303297, 3819.3698039823116, 3849.3071247175776, 4852.320675105418, 5597.822014636364, 5690.317161940594, 5710.2576665033275, 5727.11229759388, 4607.884802879821, 5581.302337207849, 5592.384837667934, 5637.774489020558, 5656.37779844225, 4329.242382582973, 4567.967254571684, 4789.736564488987, 5073.061483625541, 5203.086271687867, 4984.476629953948, 5014.949850501448, 5459.645123066988, 5467.0, 5493.258410114583, 4731.0, 5316.527814566245, 5505.0, 5551.8625329247725, 5608.579356548194, 4801.399825021872, 5500.394956554695, 5512.118061110203, 5539.418361072029, 5556.333240011063], "value": 4027.160732708723, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.4139710547482, 712.1732755482186, 731.9890201646976, 734.14105496569, 813.8901248331473, 11898.226615270012, 15804.525864224077, 16578.50264492064, 17343.306267749274, 17432.215550300236, 15502.767458488132, 16193.514194574152, 16209.432669856558, 16241.350345986177, 16241.431549895757, 691.5885048396196, 839.9916000840005, 866.57537806475, 867.6399294292861, 872.3326655108815, 698.6786078403941, 845.344857735254, 857.4640849469082, 872.1888643561485, 873.5544872115236, 642.9871402571946, 781.1446466119619, 893.3835653399166, 899.7705585075796, 901.1934318784683, 715.8604072205922, 889.2485849457198, 890.4924193209873, 890.6971629645951, 893.6380765789866, 656.4518626946506, 674.6559254780049, 675.1223409567592, 675.8749631318221, 677.7390704578723, 14971.625709357353, 15028.924855375762, 15035.849641503555, 15044.097354158786, 15058.924705376512, 13464.86535134646, 13791.172529648253, 13895.444182232699, 13966.511172109029, 14184.21986790726, 12303.261804291771, 12481.126321157522, 12651.0, 12771.361431928459, 12863.421146048357, 4551.908961820745, 4586.954130458686, 4663.417072865892, 4671.392718946549, 4673.976630116862, 105821.0, 106850.0, 107166.0, 107188.46405767999, 107394.0, 8354.66581336752, 8627.827443451222, 11530.538778448936, 11590.304581725124, 11851.288922664668, 22200.0, 22289.21987730422, 22466.887665561568, 22595.661065084038, 22651.301152413303, 7029.929700702927, 8113.229243222003, 8137.104918458965, 8265.8346833063, 8490.872636910452, 4042.8382864685673, 4087.3255912774653, 4136.193442278727, 4160.167966406731, 4273.081287523198, 3571.624979377128, 3719.628037196259, 3724.1992971510713, 3724.7392682511963, 3729.5268368994093, 662.364130434788, 666.4135560706547, 672.1195234243157, 672.640137526421, 673.6598018000976, 579.2296245992903, 667.216021175127, 679.942204912588, 681.3016657925567, 682.8770821252123, 8895.644174233088, 9203.585838637277, 9433.339666223435, 9494.430334179971, 9580.425174489554, 562.7467639562213, 580.1616663920594, 625.0249610607467, 625.956183067181, 659.1628631637889, 4375.671824613103, 4584.3496341317095, 4634.768261586907, 4682.906341873144, 4693.601043911306, 116156.0, 116483.0, 119498.0, 127687.0, 155137.2243138777, 75760.62119689367, 76201.85697214546, 76315.47369052698, 105575.0, 112533.0, 78810.21189788144, 92014.0, 97898.510507447, 105323.4733826326, 108790.0, 9248.635029378414, 9658.227341812779, 9744.658936937172, 9763.316567840184, 9902.70763222194, 59867.40132598618, 60294.0, 61711.691441542505, 62736.0, 63992.0, 81069.78395323957, 87128.0, 90861.0, 95882.52058739662, 96900.0, 13268.867311326763, 13395.732085358433, 13396.330183490982, 13832.515861944783, 14035.438582456996, 14405.279736013366, 14708.852911471176, 14819.555413337617, 14855.554333370017, 15331.0, 13862.72274554483, 13889.374978125807, 13895.305234738425, 14044.859551404355, 14206.502772402917, 13617.31913404306, 13990.300484975915, 14028.438862445797, 14268.919984722212, 14286.42854285817, 13138.35096825729, 13337.53318633843, 13463.975335002802, 13592.320383980563, 13759.862401375858, 11076.667699968692, 11235.719107022063, 11298.548058077586, 11334.0, 11383.242439266238, 9163.77090572741, 10103.393796372242, 11390.373529455628, 11558.537658493566, 11614.6515604532], "value": 7742.665631441904, "unit": "score"}], "name": "dromaeo_css", "value": 7101.336671834374, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 116.0, 112.0, 112.0, 112.0, 110.0, 114.0, 112.0, 123.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 108.0, 132.0, 160.0, 109.0, 133.0, 132.0, 108.0, 162.0, 108.0], "value": 130.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 197.0, 260.0, 187.0, 183.0, 183.0, 183.0, 181.0, 182.0, 181.0], "value": 192.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 76.0, 184.0, 82.0, 83.0, 47.0, 56.0, 162.0, 82.0], "value": 92.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 139.0, 69.0, 70.0, 78.0, 120.0, 68.0, 68.0, 118.0], "value": 89.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 84.0, 87.0, 84.0, 88.0, 97.0, 122.0, 85.0], "value": 90.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 92.0, 93.0, 94.0, 93.0, 93.0, 94.0, 92.0, 92.0, 92.0], "value": 93.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 86.0, 85.0, 86.0, 86.0, 83.0, 86.0, 86.0, 87.0], "value": 85.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 56.0, 54.0, 51.0, 53.0, 72.0, 84.0, 85.0, 83.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 68.0, 64.0, 74.0, 72.0, 81.0, 71.0, 70.0, 69.0, 71.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 87.0, 88.0, 86.0, 87.0, 88.0, 84.0, 85.0, 85.0, 87.0], "value": 86.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 146.0, 126.0, 158.0, 151.0, 135.0, 138.0, 136.0, 182.0, 137.0], "value": 144.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 171.0, 170.0, 164.0, 165.0, 167.0, 168.0, 166.0, 168.0, 172.0], "value": 167.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 66.0, 70.0, 84.0, 89.0, 72.0, 69.0, 67.0, 67.0], "value": 71.8, "unit": "score"}], "name": "kraken", "value": 1492.3999999999999, "alertThreshold": 2.0}]} 15:26:03 INFO - Return code: 0 15:26:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:26:03 INFO - # TBPL SUCCESS # 15:26:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:26:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:26:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:26:03 INFO - 2016-09-08 15:26:03.301 screenresolution[2013:13653] starting screenresolution argv=screenresolution get 15:26:03 INFO - 2016-09-08 15:26:03.304 screenresolution[2013:13653] Display 0: 1600x1200x32@60 15:26:03 INFO - 2016-09-08 15:26:03.310 screenresolution[2014:13656] starting screenresolution argv=screenresolution list 15:26:03 INFO - Available Modes on Display 0 15:26:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:26:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:26:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:26:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:26:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:26:03 INFO - Intel Iris: 15:26:03 INFO - Chipset Model: Intel Iris 15:26:03 INFO - Type: GPU 15:26:03 INFO - Bus: Built-In 15:26:03 INFO - VRAM (Dynamic, Max): 1536 MB 15:26:03 INFO - Vendor: Intel (0x8086) 15:26:03 INFO - Device ID: 0x0a2e 15:26:03 INFO - Revision ID: 0x0009 15:26:03 INFO - Displays: 15:26:03 INFO - Display: 15:26:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:26:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:26:03 INFO - Display Serial Number: 1600x1200 60 15:26:03 INFO - Main Display: Yes 15:26:03 INFO - Mirror: Off 15:26:03 INFO - Online: Yes 15:26:03 INFO - Rotation: Supported 15:26:03 INFO - Return code: 0 15:26:03 INFO - Running post-action listener: _resource_record_post_action 15:26:03 INFO - [mozharness: 2016-09-08 22:26:03.545435Z] Finished run-tests step (success) 15:26:03 INFO - Running post-run listener: _resource_record_post_run 15:26:03 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 225280; Write bytes: 705027584; Read time: 2; Write time: 1935 15:26:03 INFO - TinderboxPrint: CPU usage
53.4% 15:26:03 INFO - TinderboxPrint: I/O read bytes / time
225,280 / 2 15:26:03 INFO - TinderboxPrint: I/O write bytes / time
705,027,584 / 1,935 15:26:03 INFO - TinderboxPrint: CPU idle
2,261.8 (46.6%) 15:26:03 INFO - TinderboxPrint: CPU system
969.2 (20.0%) 15:26:03 INFO - TinderboxPrint: CPU user
1,618.7 (33.4%) 15:26:03 INFO - TinderboxPrint: Swap in / out
405,475,328 / 0 15:26:03 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 385597952; Write bytes: 381170688; Read time: 29206; Write time: 769 15:26:03 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 188416; Write bytes: 295537152; Read time: 1; Write time: 1100 15:26:04 INFO - Running post-run listener: _upload_blobber_files 15:26:04 INFO - Blob upload gear active. 15:26:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:26:04 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 15:26:04 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'] 15:26:04 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 15:26:04 INFO - (blobuploader) - INFO - Open directory for files ... 15:26:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:26:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:26:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:26:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:26:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:26:05 INFO - (blobuploader) - INFO - Done attempting. 15:26:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:26:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:26:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:26:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:26:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:26:06 INFO - (blobuploader) - INFO - Done attempting. 15:26:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:26:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:26:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:26:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:26:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:26:08 INFO - (blobuploader) - INFO - Done attempting. 15:26:08 INFO - (blobuploader) - INFO - Iteration through files over. 15:26:08 INFO - Return code: 0 15:26:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:26:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:26:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6bae536672ad78597f9f1a3c1d7831f0c59eff37ac1f1710be3710a38f547c31aa3c6c6c74f946bf0ebd2d5cd557b45b60f4162133b748555108ea5411c5d10e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cf2a365eba504ffbe0d8f2d120af1d4ff200ccf17cc55988170380ec66efcfb0c75aa338aa00aac1fa33d697b1bfd76827c89e9f36959737557c0db136c5dad2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3688269fcdd47263b5e41de3b6f04737ed91b7b7eb46a9b29d68cadf4cbc78be8ce66cb797c3de785eb39929ac0deab0bea582df5f82243225b7ab2fb2057159"} 15:26:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:26:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:26:08 INFO - Contents: 15:26:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6bae536672ad78597f9f1a3c1d7831f0c59eff37ac1f1710be3710a38f547c31aa3c6c6c74f946bf0ebd2d5cd557b45b60f4162133b748555108ea5411c5d10e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cf2a365eba504ffbe0d8f2d120af1d4ff200ccf17cc55988170380ec66efcfb0c75aa338aa00aac1fa33d697b1bfd76827c89e9f36959737557c0db136c5dad2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3688269fcdd47263b5e41de3b6f04737ed91b7b7eb46a9b29d68cadf4cbc78be8ce66cb797c3de785eb39929ac0deab0bea582df5f82243225b7ab2fb2057159"} 15:26:08 INFO - Running post-run listener: copy_logs_to_upload_dir 15:26:08 INFO - Copying logs to upload dir... 15:26:08 INFO - mkdir: /builds/slave/test/build/upload/logs 15:26:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.516077 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-08 15:26:08.450343) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 15:26:08.453661) ========= 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.fU5CvUGDJ1/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.Z0icozdHqf/Listeners TMPDIR=/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6bae536672ad78597f9f1a3c1d7831f0c59eff37ac1f1710be3710a38f547c31aa3c6c6c74f946bf0ebd2d5cd557b45b60f4162133b748555108ea5411c5d10e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cf2a365eba504ffbe0d8f2d120af1d4ff200ccf17cc55988170380ec66efcfb0c75aa338aa00aac1fa33d697b1bfd76827c89e9f36959737557c0db136c5dad2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3688269fcdd47263b5e41de3b6f04737ed91b7b7eb46a9b29d68cadf4cbc78be8ce66cb797c3de785eb39929ac0deab0bea582df5f82243225b7ab2fb2057159"} build_url:https://queue.taskcluster.net/v1/task/IrerJJCyRU2d-VNwQChyBA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011250 build_url: 'https://queue.taskcluster.net/v1/task/IrerJJCyRU2d-VNwQChyBA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6bae536672ad78597f9f1a3c1d7831f0c59eff37ac1f1710be3710a38f547c31aa3c6c6c74f946bf0ebd2d5cd557b45b60f4162133b748555108ea5411c5d10e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cf2a365eba504ffbe0d8f2d120af1d4ff200ccf17cc55988170380ec66efcfb0c75aa338aa00aac1fa33d697b1bfd76827c89e9f36959737557c0db136c5dad2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3688269fcdd47263b5e41de3b6f04737ed91b7b7eb46a9b29d68cadf4cbc78be8ce66cb797c3de785eb39929ac0deab0bea582df5f82243225b7ab2fb2057159"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 15:26:08.487779) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 15:26:08.488117) ========= 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.fU5CvUGDJ1/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.Z0icozdHqf/Listeners TMPDIR=/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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.005180 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 15:26:08.537222) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 15:26:08.537603) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 15:26:08.538004) ========= ========= Total master_lag: 6.75 =========