builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0367 starttime: 1473687957.38 results: success (0) buildid: 20160912042615 builduid: 899580433f26434ca5792d66c9fd7b47 revision: 1851b78b5a9673ee422f189b92e5f1e86b82a01c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 06:45:57.384875) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 06:45:57.385369) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 06:45:57.385678) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 06:45:57.417341) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 06:45:57.417739) ========= 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.69mUsMhMl1/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.s25nSsIlHM/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-12 06:45:57-- 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% 16.8M=0.001s 2016-09-12 06:45:57 (16.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124450 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 06:45:57.564917) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 06:45:57.565235) ========= 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.69mUsMhMl1/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.s25nSsIlHM/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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.030486 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 06:45:57.617493) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 06:45:57.617856) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1851b78b5a9673ee422f189b92e5f1e86b82a01c --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 1851b78b5a9673ee422f189b92e5f1e86b82a01c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.69mUsMhMl1/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.s25nSsIlHM/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-12 06:45:57,704 truncating revision to first 12 chars 2016-09-12 06:45:57,704 Setting DEBUG logging. 2016-09-12 06:45:57,704 attempt 1/10 2016-09-12 06:45:57,704 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1851b78b5a96?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-12 06:45:58,384 unpacking tar archive at: fx-team-1851b78b5a96/testing/mozharness/ program finished with exit code 0 elapsedTime=0.960948 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 06:45:58.601227) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 06:45:58.602891) ========= script_repo_revision: 1851b78b5a9673ee422f189b92e5f1e86b82a01c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 06:45:58.603286) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 06:45:58.603572) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 06:45:58.622741) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-09-12 06:45:58.623078) ========= /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.69mUsMhMl1/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.s25nSsIlHM/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 06:45:58 INFO - MultiFileLogger online at 20160912 06:45:58 in /builds/slave/test 06:45:58 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 06:45:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:45:58 INFO - {'append_to_log': False, 06:45:58 INFO - 'base_work_dir': '/builds/slave/test', 06:45:58 INFO - 'blob_upload_branch': 'Fx-Team', 06:45:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:45:58 INFO - 'branch': 'Fx-Team', 06:45:58 INFO - 'buildbot_json_path': 'buildprops.json', 06:45:58 INFO - 'config_files': ('talos/mac_config.py',), 06:45:58 INFO - 'default_actions': ('clobber', 06:45:58 INFO - 'read-buildbot-config', 06:45:58 INFO - 'download-and-extract', 06:45:58 INFO - 'populate-webroot', 06:45:58 INFO - 'create-virtualenv', 06:45:58 INFO - 'install', 06:45:58 INFO - 'run-tests'), 06:45:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:45:58 INFO - 'download_minidump_stackwalk': True, 06:45:58 INFO - 'download_symbols': 'ondemand', 06:45:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:45:58 INFO - 'tooltool.py': '/tools/tooltool.py', 06:45:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:45:58 INFO - '/tools/misc-python/virtualenv.py')}, 06:45:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:45:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:45:58 INFO - 'installer_path': 'installer.exe', 06:45:58 INFO - 'log_level': 'info', 06:45:58 INFO - 'log_name': 'talos', 06:45:58 INFO - 'log_to_console': True, 06:45:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:45:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:45:58 INFO - 'opt_config_files': (), 06:45:58 INFO - 'pip_index': False, 06:45:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:45:58 INFO - 'cmd': ('bash', 06:45:58 INFO - '-c', 06:45:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:45:58 INFO - 'enabled': True, 06:45:58 INFO - 'halt_on_failure': False, 06:45:58 INFO - 'name': 'check_screen_resolution'},), 06:45:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:45:58 INFO - 'cmd': ('bash', 06:45:58 INFO - '-c', 06:45:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:45:58 INFO - 'enabled': True, 06:45:58 INFO - 'halt_on_failure': False, 06:45:58 INFO - 'name': 'check_screen_resolution'},), 06:45:58 INFO - 'run_cmd_checks_enabled': True, 06:45:58 INFO - 'sps_profile': False, 06:45:58 INFO - 'sps_profile_interval': 0, 06:45:58 INFO - 'suite': 'dromaeojs', 06:45:58 INFO - 'system_bits': '32', 06:45:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:45:58 INFO - 'title': 't-yosemite-r7-0367', 06:45:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:45:58 INFO - 'use_talos_json': True, 06:45:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:45:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:45:58 INFO - 'work_dir': 'build'} 06:45:58 INFO - [mozharness: 2016-09-12 13:45:58.820877Z] Running clobber step. 06:45:58 INFO - Running pre-action listener: _resource_record_pre_action 06:45:58 INFO - Running main action method: clobber 06:45:58 INFO - rmtree: /builds/slave/test/build 06:45:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:46:00 INFO - Running post-action listener: _resource_record_post_action 06:46:00 INFO - [mozharness: 2016-09-12 13:46:00.810062Z] Finished clobber step (success) 06:46:00 INFO - [mozharness: 2016-09-12 13:46:00.810194Z] Running read-buildbot-config step. 06:46:00 INFO - Running pre-action listener: _resource_record_pre_action 06:46:00 INFO - Running main action method: read_buildbot_config 06:46:00 INFO - Using buildbot properties: 06:46:00 INFO - { 06:46:00 INFO - "project": "", 06:46:00 INFO - "product": "firefox", 06:46:00 INFO - "script_repo_revision": "production", 06:46:00 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 06:46:00 INFO - "repository": "", 06:46:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 06:46:00 INFO - "buildid": "20160912042615", 06:46:00 INFO - "pgo_build": "False", 06:46:00 INFO - "basedir": "/builds/slave/test", 06:46:00 INFO - "buildnumber": 153, 06:46:00 INFO - "slavename": "t-yosemite-r7-0367", 06:46:00 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 06:46:00 INFO - "platform": "yosemite_r7", 06:46:00 INFO - "scheduler": "tests-fx-team-macosx64-talos", 06:46:00 INFO - "branch": "fx-team", 06:46:00 INFO - "repo_path": "integration/fx-team", 06:46:00 INFO - "revision": "1851b78b5a9673ee422f189b92e5f1e86b82a01c", 06:46:00 INFO - "stage_platform": "macosx64", 06:46:00 INFO - "builduid": "899580433f26434ca5792d66c9fd7b47", 06:46:00 INFO - "slavebuilddir": "test" 06:46:00 INFO - } 06:46:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/BgCr2OswS7aC1CoqyBmYVg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 06:46:00 INFO - Running post-action listener: _resource_record_post_action 06:46:00 INFO - [mozharness: 2016-09-12 13:46:00.813511Z] Finished read-buildbot-config step (success) 06:46:00 INFO - [mozharness: 2016-09-12 13:46:00.813642Z] Running download-and-extract step. 06:46:00 INFO - Running pre-action listener: _resource_record_pre_action 06:46:00 INFO - Running main action method: download_and_extract 06:46:00 INFO - mkdir: /builds/slave/test/build/tests 06:46:00 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')]} 06:46:00 INFO - trying https://queue.taskcluster.net/v1/task/BgCr2OswS7aC1CoqyBmYVg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 06:46:00 INFO - Downloading https://queue.taskcluster.net/v1/task/BgCr2OswS7aC1CoqyBmYVg/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 06:46:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BgCr2OswS7aC1CoqyBmYVg/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 06:46:02 INFO - Downloaded 1199 bytes. 06:46:02 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 06:46:02 INFO - Using the following test package requirements: 06:46:02 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 06:46:02 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 06:46:02 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 06:46:02 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 06:46:02 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 06:46:02 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 06:46:02 INFO - u'jsshell-mac.zip'], 06:46:02 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 06:46:02 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 06:46:02 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 06:46:02 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 06:46:02 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 06:46:02 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 06:46:02 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 06:46:02 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 06:46:02 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 06:46:02 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 06:46:02 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 06:46:02 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:46:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BgCr2OswS7aC1CoqyBmYVg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 06:46:02 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 06:46:09 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 06:46:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BgCr2OswS7aC1CoqyBmYVg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 06:46:10 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 06:46:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BgCr2OswS7aC1CoqyBmYVg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 06:46:18 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 06:46:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:46:18 INFO - trying https://queue.taskcluster.net/v1/task/BgCr2OswS7aC1CoqyBmYVg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 06:46:18 INFO - Downloading https://queue.taskcluster.net/v1/task/BgCr2OswS7aC1CoqyBmYVg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 06:46:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BgCr2OswS7aC1CoqyBmYVg/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 06:46:21 INFO - Downloaded 111630594 bytes. 06:46:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BgCr2OswS7aC1CoqyBmYVg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 06:46:21 INFO - mkdir: /builds/slave/test/properties 06:46:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:46:21 INFO - Writing to file /builds/slave/test/properties/build_url 06:46:21 INFO - Contents: 06:46:21 INFO - build_url:https://queue.taskcluster.net/v1/task/BgCr2OswS7aC1CoqyBmYVg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 06:46:22 INFO - Running post-action listener: _resource_record_post_action 06:46:22 INFO - Running post-action listener: set_extra_try_arguments 06:46:22 INFO - [mozharness: 2016-09-12 13:46:22.213492Z] Finished download-and-extract step (success) 06:46:22 INFO - [mozharness: 2016-09-12 13:46:22.213616Z] Running populate-webroot step. 06:46:22 INFO - Running pre-action listener: _resource_record_pre_action 06:46:22 INFO - Running main action method: populate_webroot 06:46:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:46:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:46:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:46:22 INFO - '--tppagecycles', 06:46:22 INFO - '7'], 06:46:22 INFO - 'tests': ['tsvgm']}}, 06:46:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:46:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:46:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:46:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:46:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:46:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:46:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:46:22 INFO - 'talos_options': ['--disable-e10s'], 06:46:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:46:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:46:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:46:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:46:22 INFO - 'talos_options': ['--disable-e10s'], 06:46:22 INFO - 'tests': ['damp', 'tps']}, 06:46:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:46:22 INFO - 'tests': ['damp', 'tps']}, 06:46:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:46:22 INFO - 'tests': ['dromaeo_dom']}, 06:46:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:46:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:46:22 INFO - 'tests': ['basic_compositor_video']}, 06:46:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:46:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:46:22 INFO - 'tests': ['a11yr', 06:46:22 INFO - 'ts_paint', 06:46:22 INFO - 'tpaint', 06:46:22 INFO - 'sessionrestore', 06:46:22 INFO - 'sessionrestore_no_auto_restore', 06:46:22 INFO - 'tabpaint']}, 06:46:22 INFO - 'other-e10s': {'tests': ['a11yr', 06:46:22 INFO - 'ts_paint', 06:46:22 INFO - 'tpaint', 06:46:22 INFO - 'sessionrestore', 06:46:22 INFO - 'sessionrestore_no_auto_restore', 06:46:22 INFO - 'tabpaint']}, 06:46:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:46:22 INFO - 'ts_paint', 06:46:22 INFO - 'tpaint', 06:46:22 INFO - 'sessionrestore', 06:46:22 INFO - 'sessionrestore_no_auto_restore', 06:46:22 INFO - 'tabpaint']}, 06:46:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:46:22 INFO - 'ts_paint', 06:46:22 INFO - 'tpaint', 06:46:22 INFO - 'sessionrestore', 06:46:22 INFO - 'sessionrestore_no_auto_restore', 06:46:22 INFO - 'tabpaint']}, 06:46:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:46:22 INFO - 'tests': ['a11yr', 06:46:22 INFO - 'ts_paint', 06:46:22 INFO - 'tpaint', 06:46:22 INFO - 'sessionrestore', 06:46:22 INFO - 'sessionrestore_no_auto_restore', 06:46:22 INFO - 'tabpaint']}, 06:46:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:46:22 INFO - 'tests': ['a11yr', 06:46:22 INFO - 'ts_paint', 06:46:22 INFO - 'tpaint', 06:46:22 INFO - 'sessionrestore', 06:46:22 INFO - 'sessionrestore_no_auto_restore', 06:46:22 INFO - 'tabpaint']}, 06:46:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:46:22 INFO - 'tests': ['tsvgx', 06:46:22 INFO - 'tsvgr_opacity', 06:46:22 INFO - 'tart', 06:46:22 INFO - 'tscrollx', 06:46:22 INFO - 'cart']}, 06:46:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:46:22 INFO - 'tsvgr_opacity', 06:46:22 INFO - 'tart', 06:46:22 INFO - 'tscrollx', 06:46:22 INFO - 'cart']}, 06:46:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:46:22 INFO - 'talos_options': ['--disable-e10s'], 06:46:22 INFO - 'tests': ['tp5o']}, 06:46:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:46:22 INFO - 'tests': ['tp5o']}, 06:46:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:46:22 INFO - 'talos_options': ['--disable-e10s', 06:46:22 INFO - '--xperf_path', 06:46:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:46:22 INFO - 'tests': ['tp5n']}, 06:46:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:46:22 INFO - 'talos_options': ['--xperf_path', 06:46:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:46:22 INFO - 'tests': ['tp5n']}}, 06:46:22 INFO - 'talos.zip': {'path': '', 06:46:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:46:22 INFO - Running post-action listener: _resource_record_post_action 06:46:22 INFO - [mozharness: 2016-09-12 13:46:22.232064Z] Finished populate-webroot step (success) 06:46:22 INFO - [mozharness: 2016-09-12 13:46:22.232192Z] Running create-virtualenv step. 06:46:22 INFO - Running pre-action listener: _resource_record_pre_action 06:46:22 INFO - Running main action method: create_virtualenv 06:46:22 INFO - Creating virtualenv /builds/slave/test/build/venv 06:46:22 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 06:46:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:46:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:46:22 INFO - Using real prefix '/tools/python27' 06:46:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:46:23 INFO - Installing distribute.............................................................................................................................................................................................done. 06:46:26 INFO - Installing pip.................done. 06:46:26 INFO - Return code: 0 06:46:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:46:26 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')]} 06:46:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:46:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:26 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')]} 06:46:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:46:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:26 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 0x1104e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11045a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a04a5700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f8d30>, '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 0x11011edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1102f0b20>, '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.69mUsMhMl1/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.s25nSsIlHM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 06:46:26 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 06:46:26 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 06:46:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.69mUsMhMl1/Render', 06:46:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:46:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:46:26 INFO - 'HOME': '/Users/cltbld', 06:46:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:46:26 INFO - 'LOGNAME': 'cltbld', 06:46:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:46:26 INFO - 'MOZ_NO_REMOTE': '1', 06:46:26 INFO - 'NO_EM_RESTART': '1', 06:46:26 INFO - 'PAGER': '/bin/cat', 06:46:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:46:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:46:26 INFO - 'PWD': '/builds/slave/test', 06:46:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:46:26 INFO - 'SHELL': '/bin/bash', 06:46:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s25nSsIlHM/Listeners', 06:46:26 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 06:46:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:46:26 INFO - 'USER': 'cltbld', 06:46:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:46:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:46:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:46:26 INFO - 'XPC_FLAGS': '0x0', 06:46:26 INFO - 'XPC_SERVICE_NAME': '0', 06:46:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:46:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:46:26 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 06:46:26 INFO - Cleaning up... 06:46:26 INFO - Return code: 0 06:46:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:46:26 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')]} 06:46:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:46:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:26 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')]} 06:46:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:46:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:26 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 0x1104e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11045a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a04a5700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f8d30>, '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 0x11011edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1102f0b20>, '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.69mUsMhMl1/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.s25nSsIlHM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 06:46:26 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 06:46:26 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 06:46:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.69mUsMhMl1/Render', 06:46:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:46:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:46:26 INFO - 'HOME': '/Users/cltbld', 06:46:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:46:26 INFO - 'LOGNAME': 'cltbld', 06:46:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:46:26 INFO - 'MOZ_NO_REMOTE': '1', 06:46:26 INFO - 'NO_EM_RESTART': '1', 06:46:26 INFO - 'PAGER': '/bin/cat', 06:46:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:46:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:46:26 INFO - 'PWD': '/builds/slave/test', 06:46:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:46:26 INFO - 'SHELL': '/bin/bash', 06:46:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s25nSsIlHM/Listeners', 06:46:26 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 06:46:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:46:26 INFO - 'USER': 'cltbld', 06:46:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:46:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:46:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:46:26 INFO - 'XPC_FLAGS': '0x0', 06:46:26 INFO - 'XPC_SERVICE_NAME': '0', 06:46:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:46:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:46:26 INFO - Downloading/unpacking psutil>=3.1.1 06:46:26 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 06:46:26 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 06:46:26 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 06:46:26 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 06:46:26 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 06:46:26 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 06:46:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:46:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:46:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:46:29 INFO - Installing collected packages: psutil 06:46:29 INFO - Running setup.py install for psutil 06:46:29 INFO - building 'psutil._psutil_osx' extension 06:46:29 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 06:46:30 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 06:46:30 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 06:46:30 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 06:46:30 INFO - building 'psutil._psutil_posix' extension 06:46:30 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 06:46:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:46:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:46:30 INFO - ^ 06:46:30 INFO - 1 warning generated. 06:46:30 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 06:46:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:46:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:46:30 INFO - Successfully installed psutil 06:46:30 INFO - Cleaning up... 06:46:30 INFO - Return code: 0 06:46:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:46:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:46:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:46:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:46:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:46:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:30 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 0x1104e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11045a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a04a5700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f8d30>, '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 0x11011edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1102f0b20>, '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.69mUsMhMl1/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.s25nSsIlHM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 06:46:30 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 06:46:30 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 06:46:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.69mUsMhMl1/Render', 06:46:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:46:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:46:30 INFO - 'HOME': '/Users/cltbld', 06:46:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:46:30 INFO - 'LOGNAME': 'cltbld', 06:46:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:46:30 INFO - 'MOZ_NO_REMOTE': '1', 06:46:30 INFO - 'NO_EM_RESTART': '1', 06:46:30 INFO - 'PAGER': '/bin/cat', 06:46:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:46:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:46:30 INFO - 'PWD': '/builds/slave/test', 06:46:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:46:30 INFO - 'SHELL': '/bin/bash', 06:46:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s25nSsIlHM/Listeners', 06:46:30 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 06:46:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:46:30 INFO - 'USER': 'cltbld', 06:46:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:46:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:46:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:46:30 INFO - 'XPC_FLAGS': '0x0', 06:46:30 INFO - 'XPC_SERVICE_NAME': '0', 06:46:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:46:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:46:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:46: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 06:46: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 06:46: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 06:46: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 06:46: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 06:46: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 06:46:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:46:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:46:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:46:33 INFO - Installing collected packages: mozsystemmonitor 06:46:33 INFO - Running setup.py install for mozsystemmonitor 06:46:33 INFO - Successfully installed mozsystemmonitor 06:46:33 INFO - Cleaning up... 06:46:33 INFO - Return code: 0 06:46:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:46:33 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')]} 06:46:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:46:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:33 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')]} 06:46:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:46:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:33 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 0x1104e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11045a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a04a5700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f8d30>, '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 0x11011edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1102f0b20>, '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.69mUsMhMl1/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.s25nSsIlHM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 06:46:33 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 06:46:33 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 06:46:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.69mUsMhMl1/Render', 06:46:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:46:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:46:33 INFO - 'HOME': '/Users/cltbld', 06:46:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:46:33 INFO - 'LOGNAME': 'cltbld', 06:46:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:46:33 INFO - 'MOZ_NO_REMOTE': '1', 06:46:33 INFO - 'NO_EM_RESTART': '1', 06:46:33 INFO - 'PAGER': '/bin/cat', 06:46:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:46:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:46:33 INFO - 'PWD': '/builds/slave/test', 06:46:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:46:33 INFO - 'SHELL': '/bin/bash', 06:46:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s25nSsIlHM/Listeners', 06:46:33 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 06:46:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:46:33 INFO - 'USER': 'cltbld', 06:46:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:46:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:46:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:46:33 INFO - 'XPC_FLAGS': '0x0', 06:46:33 INFO - 'XPC_SERVICE_NAME': '0', 06:46:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:46:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:46:34 INFO - Downloading/unpacking jsonschema==2.5.1 06:46:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:46:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:46:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:46:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:46:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:46:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:46:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:46:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:46:36 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 06:46:36 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 06:46:36 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 06:46:36 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 06:46:36 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 06:46:36 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 06:46:36 INFO - Downloading functools32-3.2.3-2.tar.gz 06:46:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:46:37 INFO - warning: no files found matching '*.txt' 06:46:37 INFO - no previously-included directories found matching 'build' 06:46:37 INFO - no previously-included directories found matching 'dist' 06:46:37 INFO - no previously-included directories found matching '.git*' 06:46:37 INFO - Installing collected packages: jsonschema, functools32 06:46:37 INFO - Running setup.py install for functools32 06:46:37 INFO - warning: no files found matching '*.txt' 06:46:37 INFO - no previously-included directories found matching 'build' 06:46:37 INFO - no previously-included directories found matching 'dist' 06:46:37 INFO - no previously-included directories found matching '.git*' 06:46:37 INFO - Successfully installed jsonschema functools32 06:46:37 INFO - Cleaning up... 06:46:37 INFO - Return code: 0 06:46:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:46:37 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')]} 06:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:46:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:37 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')]} 06:46:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:46:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:37 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 0x1104e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11045a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a04a5700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f8d30>, '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 0x11011edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1102f0b20>, '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.69mUsMhMl1/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.s25nSsIlHM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 06:46:37 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 06:46:37 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 06:46:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.69mUsMhMl1/Render', 06:46:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:46:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:46:37 INFO - 'HOME': '/Users/cltbld', 06:46:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:46:37 INFO - 'LOGNAME': 'cltbld', 06:46:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:46:37 INFO - 'MOZ_NO_REMOTE': '1', 06:46:37 INFO - 'NO_EM_RESTART': '1', 06:46:37 INFO - 'PAGER': '/bin/cat', 06:46:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:46:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:46:37 INFO - 'PWD': '/builds/slave/test', 06:46:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:46:37 INFO - 'SHELL': '/bin/bash', 06:46:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s25nSsIlHM/Listeners', 06:46:37 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 06:46:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:46:37 INFO - 'USER': 'cltbld', 06:46:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:46:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:46:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:46:37 INFO - 'XPC_FLAGS': '0x0', 06:46:37 INFO - 'XPC_SERVICE_NAME': '0', 06:46:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:46:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:46:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:46:37 INFO - Cleaning up... 06:46:37 INFO - Return code: 0 06:46:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:46:37 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')]} 06:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:46:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:37 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')]} 06:46:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:46:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:37 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 0x1104e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11045a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a04a5700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f8d30>, '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 0x11011edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1102f0b20>, '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.69mUsMhMl1/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.s25nSsIlHM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 06:46:37 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 06:46:37 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 06:46:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.69mUsMhMl1/Render', 06:46:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:46:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:46:37 INFO - 'HOME': '/Users/cltbld', 06:46:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:46:37 INFO - 'LOGNAME': 'cltbld', 06:46:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:46:37 INFO - 'MOZ_NO_REMOTE': '1', 06:46:37 INFO - 'NO_EM_RESTART': '1', 06:46:37 INFO - 'PAGER': '/bin/cat', 06:46:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:46:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:46:37 INFO - 'PWD': '/builds/slave/test', 06:46:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:46:37 INFO - 'SHELL': '/bin/bash', 06:46:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s25nSsIlHM/Listeners', 06:46:37 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 06:46:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:46:37 INFO - 'USER': 'cltbld', 06:46:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:46:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:46:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:46:37 INFO - 'XPC_FLAGS': '0x0', 06:46:37 INFO - 'XPC_SERVICE_NAME': '0', 06:46:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:46:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:46:37 INFO - Downloading/unpacking blobuploader==1.2.4 06:46: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 06:46: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 06:46: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 06:46: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 06:46: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 06:46: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 06:46:40 INFO - Downloading blobuploader-1.2.4.tar.gz 06:46:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:46:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:46:40 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 06:46:40 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 06:46:40 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 06:46:40 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 06:46:40 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 06:46:40 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 06:46:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:46:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:46:41 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 06:46:41 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 06:46:41 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 06:46:41 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 06:46:41 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 06:46:41 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 06:46:41 INFO - Downloading docopt-0.6.1.tar.gz 06:46:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:46:41 INFO - Installing collected packages: blobuploader, requests, docopt 06:46:41 INFO - Running setup.py install for blobuploader 06:46:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:46:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:46:41 INFO - Running setup.py install for requests 06:46:42 INFO - Running setup.py install for docopt 06:46:42 INFO - Successfully installed blobuploader requests docopt 06:46:42 INFO - Cleaning up... 06:46:42 INFO - Return code: 0 06:46:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:46:42 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')]} 06:46:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:46:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:42 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')]} 06:46:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:46:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:42 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 0x1104e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11045a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a04a5700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f8d30>, '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 0x11011edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1102f0b20>, '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.69mUsMhMl1/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.s25nSsIlHM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 06:46:42 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 06:46:42 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 06:46:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.69mUsMhMl1/Render', 06:46:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:46:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:46:42 INFO - 'HOME': '/Users/cltbld', 06:46:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:46:42 INFO - 'LOGNAME': 'cltbld', 06:46:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:46:42 INFO - 'MOZ_NO_REMOTE': '1', 06:46:42 INFO - 'NO_EM_RESTART': '1', 06:46:42 INFO - 'PAGER': '/bin/cat', 06:46:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:46:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:46:42 INFO - 'PWD': '/builds/slave/test', 06:46:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:46:42 INFO - 'SHELL': '/bin/bash', 06:46:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s25nSsIlHM/Listeners', 06:46:42 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 06:46:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:46:42 INFO - 'USER': 'cltbld', 06:46:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:46:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:46:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:46:42 INFO - 'XPC_FLAGS': '0x0', 06:46:42 INFO - 'XPC_SERVICE_NAME': '0', 06:46:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:46:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:46:42 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-dWTjLR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:46:42 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-jVD0V_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:46:42 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-OmStm_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:46:43 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-WbPXtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:46:43 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-PKiEvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:46:43 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-tKZbl7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:46:43 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-hY2Uyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:46:43 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-otdCb6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:46:43 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-893Loh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:46:43 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-wM7HMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:46:43 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-u27tEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:46:43 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-E1BbFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:46:43 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-GSPzmw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:46:44 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-fxRfCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:46:44 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-wJkPP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:46:44 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-C84Mzy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:46:44 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-Y3q1Kj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:46:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:46:44 INFO - Running setup.py install for manifestparser 06:46:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:46:44 INFO - Running setup.py install for mozcrash 06:46:44 INFO - Running setup.py install for mozdebug 06:46:44 INFO - Running setup.py install for mozdevice 06:46:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:46:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:46:45 INFO - Running setup.py install for mozfile 06:46:45 INFO - Running setup.py install for mozhttpd 06:46:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:46:45 INFO - Running setup.py install for mozinfo 06:46:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:46:45 INFO - Running setup.py install for mozInstall 06:46:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:46:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:46:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:46:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:46:45 INFO - Running setup.py install for mozleak 06:46:45 INFO - Running setup.py install for mozlog 06:46:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:46:45 INFO - Running setup.py install for moznetwork 06:46:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:46:46 INFO - Running setup.py install for mozprocess 06:46:46 INFO - Running setup.py install for mozprofile 06:46:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:46:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:46:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:46:46 INFO - Running setup.py install for mozrunner 06:46:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:46:46 INFO - Running setup.py install for mozscreenshot 06:46:46 INFO - Running setup.py install for moztest 06:46:46 INFO - Running setup.py install for mozversion 06:46:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:46:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:46:47 INFO - Cleaning up... 06:46:47 INFO - Return code: 0 06:46:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:46:47 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')]} 06:46:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:46:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:47 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')]} 06:46:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:46:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:47 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 0x1104e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11045a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a04a5700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f8d30>, '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 0x11011edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1102f0b20>, '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.69mUsMhMl1/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.s25nSsIlHM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 06:46:47 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 06:46:47 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 06:46:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.69mUsMhMl1/Render', 06:46:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:46:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:46:47 INFO - 'HOME': '/Users/cltbld', 06:46:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:46:47 INFO - 'LOGNAME': 'cltbld', 06:46:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:46:47 INFO - 'MOZ_NO_REMOTE': '1', 06:46:47 INFO - 'NO_EM_RESTART': '1', 06:46:47 INFO - 'PAGER': '/bin/cat', 06:46:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:46:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:46:47 INFO - 'PWD': '/builds/slave/test', 06:46:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:46:47 INFO - 'SHELL': '/bin/bash', 06:46:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s25nSsIlHM/Listeners', 06:46:47 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 06:46:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:46:47 INFO - 'USER': 'cltbld', 06:46:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:46:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:46:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:46:47 INFO - 'XPC_FLAGS': '0x0', 06:46:47 INFO - 'XPC_SERVICE_NAME': '0', 06:46:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:46:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:46:47 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-_nufos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:46:47 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)) 06:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:46:47 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-NyThtr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:46:47 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)) 06:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:46:47 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-7l6LFr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:46:47 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)) 06:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:46:47 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-lvMfep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:46:47 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)) 06:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:46:47 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-4RtDlO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:46:47 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)) 06:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:46:47 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-3YZbzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:46:47 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)) 06:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:46:47 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-iiCmCR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:46:48 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)) 06:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:46:48 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-prkH1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:46:48 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)) 06:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:46:48 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-_0DPC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:46:48 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)) 06:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:46:48 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-xOsYnW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:46:48 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)) 06:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:46:48 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-hKiHmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:46:48 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)) 06:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:46:48 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-hdxu2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:46:48 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)) 06:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:46:48 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-fyGHBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:46:48 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)) 06:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:46:48 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-M97xTp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:46:48 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)) 06:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:46:48 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-lqwZs_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:46:48 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)) 06:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:46:48 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-gTbUF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:46:49 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)) 06:46:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:46:49 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-KED25_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:46:49 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)) 06:46:49 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)) 06:46:49 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)) 06:46:49 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)) 06:46:49 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)) 06:46:49 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)) 06:46:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:46:49 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 06:46:49 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 06:46:49 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 06:46:49 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 06:46:49 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 06:46:49 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 06:46:51 INFO - Downloading blessings-1.6.tar.gz 06:46:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:46:51 INFO - Installing collected packages: blessings 06:46:51 INFO - Running setup.py install for blessings 06:46:51 INFO - Successfully installed blessings 06:46:51 INFO - Cleaning up... 06:46:52 INFO - Return code: 0 06:46:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:46:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:46:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:46:52 INFO - Reading from file tmpfile_stdout 06:46:52 INFO - Current package versions: 06:46:52 INFO - blessings == 1.6 06:46:52 INFO - blobuploader == 1.2.4 06:46:52 INFO - docopt == 0.6.1 06:46:52 INFO - functools32 == 3.2.3-2 06:46:52 INFO - jsonschema == 2.5.1 06:46:52 INFO - manifestparser == 1.1 06:46:52 INFO - mozInstall == 1.12 06:46:52 INFO - mozcrash == 0.17 06:46:52 INFO - mozdebug == 0.1 06:46:52 INFO - mozdevice == 0.48 06:46:52 INFO - mozfile == 1.2 06:46:52 INFO - mozhttpd == 0.7 06:46:52 INFO - mozinfo == 0.9 06:46:52 INFO - mozleak == 0.1 06:46:52 INFO - mozlog == 3.3 06:46:52 INFO - moznetwork == 0.27 06:46:52 INFO - mozprocess == 0.23 06:46:52 INFO - mozprofile == 0.28 06:46:52 INFO - mozrunner == 6.12 06:46:52 INFO - mozscreenshot == 0.1 06:46:52 INFO - mozsystemmonitor == 0.3 06:46:52 INFO - moztest == 0.7 06:46:52 INFO - mozversion == 1.4 06:46:52 INFO - psutil == 3.1.1 06:46:52 INFO - requests == 1.2.3 06:46:52 INFO - wsgiref == 0.1.2 06:46:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:46:52 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')]} 06:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:46:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:52 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')]} 06:46:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:46:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:52 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 0x1104e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11045a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a04a5700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f8d30>, '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 0x11011edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1102f0b20>, '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.69mUsMhMl1/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.s25nSsIlHM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 06:46:52 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 06:46:52 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 06:46:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.69mUsMhMl1/Render', 06:46:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:46:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:46:52 INFO - 'HOME': '/Users/cltbld', 06:46:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:46:52 INFO - 'LOGNAME': 'cltbld', 06:46:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:46:52 INFO - 'MOZ_NO_REMOTE': '1', 06:46:52 INFO - 'NO_EM_RESTART': '1', 06:46:52 INFO - 'PAGER': '/bin/cat', 06:46:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:46:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:46:52 INFO - 'PWD': '/builds/slave/test', 06:46:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:46:52 INFO - 'SHELL': '/bin/bash', 06:46:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s25nSsIlHM/Listeners', 06:46:52 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 06:46:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:46:52 INFO - 'USER': 'cltbld', 06:46:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:46:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:46:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:46:52 INFO - 'XPC_FLAGS': '0x0', 06:46:52 INFO - 'XPC_SERVICE_NAME': '0', 06:46:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:46:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:46:52 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)) 06:46:52 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)) 06:46:52 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)) 06:46:52 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)) 06:46:52 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)) 06:46:52 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)) 06:46:52 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)) 06:46:52 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)) 06:46:52 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)) 06:46:52 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)) 06:46:52 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)) 06:46:52 INFO - Cleaning up... 06:46:53 INFO - Return code: 0 06:46:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:46:53 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')]} 06:46:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:46:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:53 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')]} 06:46:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:46:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:53 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 0x1104e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11045a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a04a5700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f8d30>, '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 0x11011edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1102f0b20>, '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.69mUsMhMl1/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.s25nSsIlHM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 06:46:53 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 06:46:53 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 06:46:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.69mUsMhMl1/Render', 06:46:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:46:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:46:53 INFO - 'HOME': '/Users/cltbld', 06:46:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:46:53 INFO - 'LOGNAME': 'cltbld', 06:46:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:46:53 INFO - 'MOZ_NO_REMOTE': '1', 06:46:53 INFO - 'NO_EM_RESTART': '1', 06:46:53 INFO - 'PAGER': '/bin/cat', 06:46:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:46:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:46:53 INFO - 'PWD': '/builds/slave/test', 06:46:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:46:53 INFO - 'SHELL': '/bin/bash', 06:46:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s25nSsIlHM/Listeners', 06:46:53 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 06:46:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:46:53 INFO - 'USER': 'cltbld', 06:46:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:46:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:46:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:46:53 INFO - 'XPC_FLAGS': '0x0', 06:46:53 INFO - 'XPC_SERVICE_NAME': '0', 06:46:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:46:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:46:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:46:53 INFO - Cleaning up... 06:46:53 INFO - Return code: 0 06:46:53 INFO - Running post-action listener: _resource_record_post_action 06:46:53 INFO - Running post-action listener: _start_resource_monitoring 06:46:53 INFO - Starting resource monitoring. 06:46:53 INFO - [mozharness: 2016-09-12 13:46:53.718418Z] Finished create-virtualenv step (success) 06:46:53 INFO - [mozharness: 2016-09-12 13:46:53.719265Z] Running install step. 06:46:53 INFO - Running pre-action listener: _resource_record_pre_action 06:46:53 INFO - Running main action method: install 06:46:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:46:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:46:53 INFO - Reading from file tmpfile_stdout 06:46:53 INFO - Detecting whether we're running mozinstall >=1.0... 06:46:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:46:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:46:54 INFO - Reading from file tmpfile_stdout 06:46:54 INFO - Output received: 06:46:54 INFO - Usage: mozinstall [options] installer 06:46:54 INFO - Options: 06:46:54 INFO - -h, --help show this help message and exit 06:46:54 INFO - -d DEST, --destination=DEST 06:46:54 INFO - Directory to install application into. [default: 06:46:54 INFO - "/builds/slave/test"] 06:46:54 INFO - --app=APP Application being installed. [default: firefox] 06:46:54 INFO - mkdir: /builds/slave/test/build/application 06:46:54 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'] 06:46:54 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 06:47:28 INFO - Reading from file tmpfile_stdout 06:47:28 INFO - Output received: 06:47:28 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:47:28 INFO - Running post-action listener: _resource_record_post_action 06:47:28 INFO - [mozharness: 2016-09-12 13:47:28.695784Z] Finished install step (success) 06:47:28 INFO - [mozharness: 2016-09-12 13:47:28.695916Z] Running run-tests step. 06:47:28 INFO - Running pre-action listener: _resource_record_pre_action 06:47:28 INFO - Running main action method: run_tests 06:47:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:47:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:47:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:47:28 INFO - 2016-09-12 06:47:28.710 screenresolution[1634:7411] starting screenresolution argv=screenresolution get 06:47:28 INFO - 2016-09-12 06:47:28.713 screenresolution[1634:7411] Display 0: 1600x1200x32@60 06:47:28 INFO - 2016-09-12 06:47:28.719 screenresolution[1635:7413] starting screenresolution argv=screenresolution list 06:47:28 INFO - Available Modes on Display 0 06:47:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:47:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:47:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:47:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:47:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:47:28 INFO - Intel Iris: 06:47:28 INFO - Chipset Model: Intel Iris 06:47:28 INFO - Type: GPU 06:47:28 INFO - Bus: Built-In 06:47:28 INFO - VRAM (Dynamic, Max): 1536 MB 06:47:28 INFO - Vendor: Intel (0x8086) 06:47:28 INFO - Device ID: 0x0a2e 06:47:28 INFO - Revision ID: 0x0009 06:47:28 INFO - Displays: 06:47:28 INFO - Display: 06:47:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:47:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:47:28 INFO - Display Serial Number: 1600x1200 60 06:47:28 INFO - Main Display: Yes 06:47:28 INFO - Mirror: Off 06:47:28 INFO - Online: Yes 06:47:28 INFO - Rotation: Supported 06:47:28 INFO - Return code: 0 06:47:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:47:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:47:28 INFO - Python 2.7.3 06:47:28 INFO - Return code: 0 06:47:28 INFO - grabbing minidump binary from tooltool 06:47: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')]} 06:47:28 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 0x1102f8d30>, '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 0x11011edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1102f0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:47:28 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 06:47:28 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 06:47:28 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 06:47:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:47:29 INFO - Return code: 0 06:47:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:47:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:47:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:47:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:47:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:47:29 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/BgCr2OswS7aC1CoqyBmYVg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0367', '--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 06:47:29 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/BgCr2OswS7aC1CoqyBmYVg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0367 --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 06:47:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.69mUsMhMl1/Render', 06:47:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:47:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:47:29 INFO - 'HOME': '/Users/cltbld', 06:47:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:47:29 INFO - 'LOGNAME': 'cltbld', 06:47:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:47:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:47:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:47:29 INFO - 'MOZ_NO_REMOTE': '1', 06:47:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:47:29 INFO - 'NO_EM_RESTART': '1', 06:47:29 INFO - 'PAGER': '/bin/cat', 06:47:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:47:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:47:29 INFO - 'PWD': '/builds/slave/test', 06:47:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:47:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:47:29 INFO - 'SHELL': '/bin/bash', 06:47:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s25nSsIlHM/Listeners', 06:47:29 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 06:47:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:47:29 INFO - 'USER': 'cltbld', 06:47:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:47:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:47:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:47:29 INFO - 'XPC_FLAGS': '0x0', 06:47:29 INFO - 'XPC_SERVICE_NAME': '0', 06:47:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:47:29 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/BgCr2OswS7aC1CoqyBmYVg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0367', '--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 06:47:29 INFO - mozversion application_buildid: 20160912042615 06:47:29 INFO - mozversion application_changeset: 1851b78b5a9673ee422f189b92e5f1e86b82a01c 06:47:29 INFO - mozversion application_display_name: Nightly 06:47:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:47:29 INFO - mozversion application_name: Firefox 06:47:29 INFO - mozversion application_remotingname: firefox 06:47:29 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 06:47:29 INFO - mozversion application_vendor: Mozilla 06:47:29 INFO - mozversion application_version: 51.0a1 06:47:29 INFO - mozversion platform_buildid: 20160912042615 06:47:29 INFO - mozversion platform_changeset: 1851b78b5a9673ee422f189b92e5f1e86b82a01c 06:47:29 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 06:47:29 INFO - mozversion platform_version: 51.0a1 06:47:29 INFO - using testdate: 1473688049 06:47:29 INFO - actual date: 1473688049 06:47:29 INFO - starting webserver on 'localhost:49266' 06:47:29 INFO - SUITE-START | Running 2 tests 06:47:29 INFO - TEST-START | dromaeo_css 06:47:29 INFO - operating with platform_type : mac_ 06:47:29 INFO - Initialising browser for dromaeo_css test... 06:47:29 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmpOcI5DO/profile http://localhost:49266/getInfo.html) 06:47:31 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 06:47:31 INFO - PROCESS | 1645 | colorDepth:24 06:47:31 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 06:47:31 INFO - PROCESS | 1645 | __metrics 06:47:31 INFO - PROCESS | 1645 | 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" 06:47:31 INFO - PROCESS | 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:47:32 INFO - TEST-INFO | 1645: exit 0 06:47:32 INFO - Browser initialized. 06:47:32 INFO - Running cycle 1/1 for dromaeo_css test... 06:47:32 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmpOcI5DO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:47:44 INFO - PROCESS | 1647 | RSS: Main: 232665088 06:47:44 INFO - PROCESS | 1647 | 06:48:35 INFO - PROCESS | 1647 | 1473688115904 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] 06:50:47 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 06:50:49 INFO - PROCESS | 1647 | RSS: Main: 397512704 06:50:49 INFO - PROCESS | 1647 | 06:53:51 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 06:53:52 INFO - PROCESS | 1647 | RSS: Main: 392245248 06:53:52 INFO - PROCESS | 1647 | 06:56:54 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 06:56:55 INFO - PROCESS | 1647 | RSS: Main: 332013568 06:56:55 INFO - PROCESS | 1647 | 06:59:57 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 06:59:58 INFO - PROCESS | 1647 | RSS: Main: 332541952 06:59:58 INFO - PROCESS | 1647 | 07:03:01 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 07:03:02 INFO - PROCESS | 1647 | RSS: Main: 320872448 07:03:02 INFO - PROCESS | 1647 | 07:06:05 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 07:06:06 INFO - PROCESS | 1647 | RSS: Main: 404815872 07:06:06 INFO - PROCESS | 1647 | 07:06:06 INFO - PROCESS | 1647 | __start_tp_report 07:06:06 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 07:06:06 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 07:06:06 INFO - PROCESS | 1647 | |i|pagename|runs| 07:06:06 INFO - PROCESS | 1647 | |0;dojo.html;3086.382723455309;3243.935121297574;3278.409886220479;3322.7840190387637;3324.767266291359;5469.72651367432;5516.72416379181;5546.91679624805;5557.72211389431;5583.637063590869;5509.090999984997;5537.058700020996;5558.944410555893;5590.608657393994;5613.859653508664;5080.517350851663;5108.131617625013;5109;5161.148410512261;5184.637075404723;5142.691438513682;5247.606429517782;5260.447652996438;5262.342207224097;5262.447443018466;5101.183810590325;5186.299849520305;5213.58291336694;5225;5228.215767634847;5127.307813445212;5145.9485405146215;5151.7681704323395;5170.276161337416;5178.585713142939;5099.745012749348;5178.119719647669;5181.948180518184;5185.403678576974;5202.271681964528;5535.916961245584;5541.556675465953;5545;5553.361363443215;5563.610547261694;5011.5489605935645;6024.156122327541;6046.33174001866;6085.937305169714;6140.877182456371;6146.938530614726;6188.504919606466;6232.968835155795;6234.563580549363;6275.18422605063;5600.971995140039;5682.54539636828;5706.4864162225595;5743.138529220609;5762.654240745527;6176.413240742122;6186.752529898844;6221.191245138193;6287.968560157261;6304.338044505261;5994.520438364921;6026.578139530191;6030.698465076729;6055.031195008777;6082.756689732361;6128.63228206309;6164.445199932027;6171.290301615282;6177.2587289525545;6180.165677633552;5930.584859059824;5940.732667029994;5954.612950158313;6038.5772995889865;6052.12244224585;6064.75740970365;6079.604825686405;6101.237345331833;6104.481119104926;6130.47890929276;4907.067657145131;4915.139850526144;4925.064237794808;4939.036887806915;4957.529034741766;4366.628836548929;4382.671299652538;4401.6698747594055;4416.072624748775;4447.799849006802;5449.182622606561;5487.2317875497465;5522.061249587579;5529.198266251451;5585.078462053797;6097.796915383898;6104.428839602322;6118.663473509;6133.294671112879;6144.446999770043;5493.890122197534;5517.889642207214;5533.087040638249;5543.307086614173;5557.833265002057;4468.3074123510905;5310.840674779763;5320.866978325573;5350.839474815762;5412.052890744183;6283.905741413882;6291.937080629226;6295.622262664255;6300.684965751694;6309.1167236587835;6081.6351018939;6112.235970503687;6125.938740612537;6133.3866613338305;6156.507479401547;6205.472534834409;6237.345078766664;6276.309605943434;6282.183316168869;6283.5287353449585;5989.251343582052;5993.250843644544;6010.1285313628405;6021.307549631849;6041.305249896142;6002.489788367863;6020;6021.698915054142;6065.787697430569;6107.725152368243;5795.217645617788;5868.765249389977;5919.6152250103605;5938.228030356199;5973.940260597512;5919.6152250103605;5929.525637948867;5938.970305148447;5945.405459454193;6043.274807023009;5734.3692193857805;5734.512566431733;5737.827865164052;5766.5675074368755;5776.104703770922;5710.771569137354;5725.799597014251;5758.568107391879;5863.413658634103;5884.617499862667;5797.275340582427;5809.50619197373;5820.126980952891;5825.94174058254;5858.736356863866 07:06:06 INFO - PROCESS | 1647 | |1;ext.html;478.7869398117839;484.8448496481123;526.3683579704353;528.920661900715;531.0785786660146;29609.85195074027;40288.1942361153;41394.379084313725;41437.51374917507;41469.96325091874;42467.57532424675;42714.35928461076;42755.7173284802;42783;42980.570194298045;8501.404901656886;9709.660161894335;9909.454979976099;9914.305998580103;9915.305928585001;9702.805943881118;9795.804083918318;9821.607135714563;9822.70531884041;9841.55712992917;9828;9860.753481162956;9867.60529578816;9870.01299870011;9874.950625246864;8406.537640429773;9763;9803.019698030212;9816.26378021644;9845.753856153618;8011.599420029033;8875.955620221923;9758.463284519348;9835.754106147331;9854.162396196332;40463.7976810117;40685.18629627391;40688.796556017325;40779;41015.38476922848;3176.3170918252686;3242.3353212591364;3342.7325813934826;3395.779274347184;3591.586967498745;31398.529022064686;31441.528377074363;31503;31571.36857262842;31621.683783162105;3173.76196785242;3236.8057916525086;3269.96730032699;3373.4771110477914;3377.3245350929665;517294;517902;518767;519995;523418.38290808303;60415.69792151011;60549.183524494336;60644.39355606476;60663;60664.69667651634;58837.70581147067;58842;58847.70576147178;58932.705336473045;58955.410445895846;15923.840761592468;16132.838671613368;16138.677226455406;16140.435084771976;16151;12361.07291953089;12417.130800844036;12457.563985260473;12458.252504849737;12461.563845265373;14063.578092657237;17104.74342884858;17110.657786844193;17179.484615461555;17196.398126065527;6015.54883383748;6938.444924406035;7086.92913070873;7140.357367836919;7158.642067896584;826.6486743134144;899.9775005624781;943.6508491858057;981.9705408837747;992.7964767222702;28368.85815570909;29751.75270078066;29823.850880745456;29887.958661466313;29893.551596726065;1013.640157744005;1069.6042464288264;1075.9300645458177;1095.9835602465969;1140.3898914080926;14793.408263669333;15279.006864553769;15292.235388230767;15399.923000384926;15655.452059178102;658436;662399;663731;664031;664079;56984.71507642518;57061.71469142628;57159;57310.14034789481;57403.851922962145;1216.5377156680404;1239.8698136695516;1240.2000709542474;1358.8980826438058;1375.5460697969845;29562.408751824412;30926.453677315592;31220;32214.35571288608;32325;54986.60107191335;55848;55870.16194757163;55933.72033139808;56246;595596.0220198871;600998;601365;602204;603857;25868;26113;26597.601035984088;39580.80209598933;40051.198976020896;45918.31122533231;46396.7680161597;46749.597512074695;46788.720727734326;47016;38363;38539.06296866591;38728.41907371448;40016;40021.39967900426;37349.066273343386;38044.61955380411;38525.4221186688;39964.800175998935;40289.79855100706;37370.626293736714;38055.809720952326;38150.23699525939;38629.4205586922;39159.6084039156;30424.543631845096;30760;33950.830245849604;34244.65755342415;34423.48364774581;23752.643710344015;24932.62601061022;25187.24438266855;25230.74769252284;25783.484330313662 07:06:06 INFO - PROCESS | 1647 | |2;jquery.html;335.4649334311773;346.08460621655706;348.11404974340303;370.8498058286395;404.89472737088346;14313.56864313568;14989.351171371154;15119.697606047886;19759.5060123497;20193.798062019374;19055.71416428751;20133.697994530055;20394.286199983002;20427.897860510715;21698.674519882214;5587.273654424929;6691.230508491537;6837.427391699912;6853.451723862104;6894.655267236618;6600.93399066008;6806.523543351967;6867.141607299088;6870.622115783655;6871.209810871739;4611.953880461186;4717.551832575917;4863.781129849165;6846;7892.131865494791;6711.6979735912;7219.422446204318;7602.0877494700435;7602.657880395395;8001.599920003977;7615.733449329302;7616.200298968639;7620.50466719667;7621.847563048708;7630.046244219473;27465.588016179772;28949.933761591637;29084.12747617575;29204.269893252625;29455.70544294551;2246.6068438023444;2254.6618007299035;2508.611165269386;2511.535365957304;3102.053873568561;16129.596760080974;17255.22351494186;17567.560810979827;17699.93800371969;17941.01324427156;1893.810618938109;2027.3917824652544;2109.2090466075224;2116.2064225915283;2119.300630791835;82190;123277;132653;133386;136275;20302;20549;20571.38285851427;20633;20827.895860520905;34208.48687269693;34335.484967725504;34364.31271374559;34413;34416.82791586076;10540.209484288709;10585.52365143555;10737.51681174349;10833.945830270799;10956.616518421817;8870.467771933705;8880.289576833839;8924.776880577907;9072.412327842601;9076.591553379982;7853.960730196427;9353.204977576846;9459.952700236454;9618.711438656852;9641.566129524188;3126.374725054998;3590.9640903591153;3718.200586873835;3720.739548231598;3722.925541489155;681.5467713970154;700.8563244534859;870.3602851903846;964.3924327673661;977.7506735782472;17232.396866109626;19602.90198548998;20072.297469588495;20940.790592093883;21033;868.7871471489399;905.8369493491235;935.2004036548653;938.990610093904;945.6312038305067;10368.558187640388;10884.891151088388;11101.5559377624;11133.832992505118;11362.375069371265;594931;608651;609150.588223531;611538;617060;129731;135127.3243633795;137578;138602;142561.28719356336;863.9654413823503;913.4656226107669;917.1882883647886;918.4948278446694;947.7630592351912;18685.626287474446;18791.34230301933;18899.811001889804;18901.905490472458;18913.054347282858;50673.49326506688;54256;54967.45032549783;55539.72230138985;55831;77128.6143569301;77493;79466.85303514331;80516;80600.7909881364;6627.668616569056;7817.491863029112;7938.444308898553;7996.280334769898;8071.23323284287;10070.8489372658;12323.753524929272;12329.938350308192;12667.93666031664;12683.556075537312;10353.327033742782;10688.060783284658;10940.671779846618;10953.561857525929;10965.50655220501;9048.954755226181;10835.945820270848;11096.944515277371;11273.7745245093;11274.279487768852;10287.125594324567;10354.5340459678;10525.421101839165;10715.732106697084;11094.223404361617;7757;7849.215078492105;7906.655868502254;8236.423450358418;8256;9940.552675129491;10347.43089130105;10436.060754532129;10440.269181157246;11135.777284454427 07:06:06 INFO - PROCESS | 1647 | |3;mootools.html;1363.1275983370638;1367.849536550979;1541.6300087978884;1727.2227497626063;1920.222309964464;6879.380855722984;7767.766966990992;8277.66889324427;8477.872831907518;9158.99251082382;8469.66121355145;8693.913060869389;8705.042445331008;8712.651493940251;8713.259372953307;2172.391730315514;2239.4401399650087;2347.1080989224115;2385.3678775124604;2385.3798012516786;2333.673285739998;2370.7629237076326;2380.73811880693;2390.306811024801;2397.2089210560475;2351.3533778211045;2366.2428023032635;2373.8338316317863;2383.0467812874817;2383.8688872112025;2325.5116425550655;2348.6946696929376;2358.8820558972157;2362.5274945010897;2370.3600027992347;2331.0093210385517;2349.635806450003;2350.988245058764;2358.3160883343808;2364.066193853418;8702.434341767828;8712.782180445476;8780.121987801169;8797.428167169177;8808.383413161082;5216.504432078947;5392.433794451605;5400.972995135038;5401.675899446007;5422.40353561108;3472.1840367513614;3629.709623230135;3710.2950439416422;3716.1638631307737;3717.312297225022;1053.2837670384135;1085.3542142425244;1089.6567581211973;1104.4588151805638;1105.8894110588876;11394.430278486125;11447.255928364713;11561;11570.479328430241;11570.76858462826;4580.564846339593;4628.352030715702;4838.516148385203;4842.6368022398465;4862.854114376574;9998.700038998843;10000.24998125129;10008.949955250177;10017.298789084694;10044.497775111215;5363.168708850135;5371.060064488699;5374.086405311106;5377.220303056035;5389.730513474311;4139.151473947833;4423.358613001097;4570.360149579062;4581.885452863638;4898.681585696918;3691.3909204981414;3824.7513911595656;3910.6089391061237;3958.9010274743364;4031.7580945143386;1267.7274386007052;1287.729576788866;1290.348374071088;1298.811672307529;1303.5828534868751;817.3502065857657;872.2062922740275;890.7505898348472;912.2291663544295;926.6756635177769;5709.600327977002;5940.970295148497;5978.192943952511;6082.391760823883;6209.19280493542;942.561708805395;946.3470205558143;960.4237457525429;972.7276362618478;981.342500524636;3805.3911374179997;4007.5557985783516;4111.135532599347;4117.670586353084;4135.834566617302;10583.523741431654;10970.725731856768;10986.835197472044;10995.450227488594;11012.669619911416;10953.945230273797;10956.12351011917;10957.452127393599;10959.178061645249;10973.3964631946;7769.3784497240085;8105.432619716563;8120.999999999882;8311;8335.49987000782;5887.764489420547;5953.851153721192;5994.901784375608;6050.969745151246;6087.147799308012;9640.855387169058;9776.169025632616;10054.54754536033;10091.444970526398;10121.848172277274;9249.953750231205;9896.406215627085;10217.693469195936;10365.481725913825;10366.326188797704;6965.547239429624;7124.3944264738075;7576.393888489018;7615.923840761521;7683.731069412544;8271.296939760188;8272.214139656722;8272.420930535045;8289.917100828914;8357.785186925348;7299.452541059336;7458;7542.434317426105;7565.735199268;7588.27911348421;7200.459965502713;7314.268573142856;7327.0841144856895;7346.530693861142;7515.09818821723;6688.130543029375;6892.517523773287;7006.509544331848;7011.614361209979;7014.508984371045;4847.587955023721;4926.5073492650445;4975.203967365204;5028.874278143076;5171.095058364846;4989.2516122580455;4997.700137991732;5037.496250374933;5046.873828154326;5168.457311982188 07:06:06 INFO - PROCESS | 1647 | |4;prototype.html;295.86538125153044;300.57318607577247;307.15225976305396;311.5949265954259;312.04514186589023;5003.424606170292;5115.9488405115935;5189.818356357524;5238.266642670029;5242.554382877451;4447.777611119447;4964.726940018298;5048.293238946541;5156.74216289185;5187.533122019017;3975.2248311579256;3981.600331633547;3984.2828290907623;3989.760614363133;4005.2189822984456;3229.3541291741626;3848.5766565677754;4006.2388146252265;4018.5177778666457;4023.9537720192666;3473.548438702978;3928.5482169550583;3951.861684841031;3967.36522156455;3994.2810294147043;3950.545687245975;3955.2089582083554;3956.802159891994;3977.1449138435373;4006.9764768791865;3489.342562282921;3492.947605785915;3502.6339727506293;3504.179135795709;3517.8944631661093;3855.383138697823;4460.888477788081;4669.1828929937465;5040.873978150539;5044.621653376012;4525.691897132453;4533.909321813579;4536.251518499444;4540.704854184468;4569.4007097515605;4727.45634252062;5404.567634589223;5424.484673955968;5435.293411856473;5446.046941785211;3444.6555344465605;3527.171114788023;3716.0338312039066;3760.3231418344685;3778.770525390033;4576.336431217454;5718.170185540629;5753.26719808092;5754.136879468071;5791.594588378815;3414.641462646436;3588.7308451866215;3642.5213920705664;3732.011017080476;3792.469054332396;3259.543663887058;3886.1346553654403;3893.7469064510715;3899.1811719539214;3915.6506096342537;4290.549492303326;4463.24124898768;4478.484974228006;4518.915460289507;4522.615577675869;4549.522300158435;4809.509052193831;5242.13504771716;5483.862903427446;5519.558435325244;1210.2375503432781;1235.8887700106854;1265.531753251303;1269.7587458382877;1282.6216266201493;795.7055889320989;861.2593169873903;877.8500164784153;891.5809569502323;900.527223207808;4437.489688685778;5049.469805670352;5060.292743382272;5074.391073071181;5081.466446023114;5372.189609041176;5581.944180558224;5600.719964001865;5617.606767526316;5629.296337957755;4257.701960862772;4965.677230979974;5057.142714366371;5066.746662666926;5067.341245638044;2147.5704859028256;2204.7354317482004;2476.739942306068;2514.911978080758;2517.5342561626157;4299.82916570932;4351.760653164043;4716.787744551503;4866.026794641086;4871.318015477836;4132;4531.377994445647;4821.132196204717;4838.419389673261;4905.601903457497;4392.714473559208;4794.712317260975;4833.009233107205;4868.586170175504;4893.9021219576125;3939.684825213919;4672.34587157805;4688.882777930579;4694.201985662377;4699.389079419653;3928.135810121769;4132.566080561618;4177.770222637784;4659.603933665677;4721;4122.42286079955;4830.2513110467935;4839.467658557626;4845.8061677532505;4849.660523763303;3259.527368531597;3271.721903638122;3301.5377847101904;3309.6690330966712;3312.1057314525424;3791.469194312731;3955.723099383016;4059.086705491241;4668.626509879269;4670.9766451167525;4227.830886764618;4480.305552639346;4506.324051392317;4539.954600453953;4585.931211031904;4214.662826973773;4518.932216016697;4531.417092257381;4571.131485017836;4588.678792484494;4305.160493703697;4630.699004564693;4709.740964246862;5230.417164242506;5277.630565860506;5400.594955378284;5406.297181366397;5421.349438067457;5423.267858839164;5427.592930530305;4562.270036794097;4782.254477115897;5035.899282014412;5328.866778330417;5328.973355133355 07:06:06 INFO - PROCESS | 1647 | |5;yui.html;706.2972342519195;755.240982812274;780.1808101493431;800.2317774936062;847.1570787066869;14586.416543338268;15406.921515493892;16056.75914861278;16075.115868627221;16147.354105835782;14339.78490322646;14685.04547204431;14698.485553005647;14705.411783528645;14748.188849613267;670.5071772247394;697.2365260040248;847.563504795031;863.5768473447997;866.3805379153904;650.8828410886039;667.5427332277405;864.2178828160494;872.0581771686595;874.7288340614398;892.7678803511069;893.6202114101513;894.0076515068268;894.7181637784109;894.9105089491064;588.6909372579387;884.0319849764497;884.8407286688393;887.330065800323;888.7955770172832;483.258198665051;647.0552992630764;668.5487296075179;670.1991120610824;676.0940339944498;14686.971911966166;14745.410183592643;14750.926245368704;14755.926220368936;14766.409343626243;13224.537141200011;13599.524016659469;13756.793648095287;13806.65483362914;14187.929060354736;12075.215111017756;12235.388230588434;12247.387630618434;12264;12730.427130779137;4360.119047619048;4398.076403955173;4440.511543730187;4527.615152712024;4535.274356103041;111826;112118.43940780325;112354.43822780834;114732.42633786777;121251.78748212493;9854.852177217348;10214.182865370753;10459;10505.31715438494;10651.467426628638;19780.30768923081;19867.801321986593;19970.60058798845;20155.899220503805;21882.01530931112;7565.319121279002;7611.467197296136;7704.8459030820195;7797.9220207798335;7860.764177074582;4230.788460576959;4290.914181716348;4305.48334199898;4319.416878721332;4448.644108471314;3419.743519236067;3545.7872527648424;3547.343741407848;3553.34263161316;3563.572371315459;648.3937518420292;658.1016911915297;665.9900101498481;667.7662818013754;668.6155460610169;501.8494451664559;506.25833154428204;524.3864678326368;589.755251570602;628.4375483941847;8233.917660823436;8614.802075640946;8735.301175905912;8820.280045391215;8973.730788076367;580.1327016110941;584.6959581017846;602.3406659597827;605.8515663662342;615.8429600451856;4222.535521092677;4331.306990881444;4352.912941741148;4404.691671583022;4429.601335879786;148978.7653185203;150670;155962.22018889833;156160;160805;134835;136452;137623.93564096547;138733.30633346768;141982;113017.76623169452;117017.41491292659;118586;118935;121172;8186.549739764132;8404.621792019496;8458.28104611115;8575.785605359915;8740.956295218482;56788.86422271614;58125.709371452875;60554;63447.413814655;65863.67068164628;84177.57911210405;89096.772580686;97683.02316976739;100122;100899;13763.518276860263;14018.22899740524;14148.080374775607;14349;14552.636184095481;15093.622659433162;15293.694126117192;15345.462908798585;15683.059016459049;15898.68202635964;13480.639980967124;13803.654908627364;13900.304984750519;13925.582232533041;14171.149731016172;14291.928540357232;14408.927955360155;14500.85499144995;14956.700865982837;15330.38678452894;13355.599332020054;13426.731465370833;13464.730705386033;13519.662008449868;13731.107478013897;10029.899701002896;10936.45317734126;11192.048675862643;11243.775124497628;11870.762584748083;9774.657886973922;10501.138611641465;10879.78240435171;11291.604793832177;11500.654980350602 07:06:06 INFO - PROCESS | 1647 | __end_tp_report 07:06:06 INFO - PROCESS | 1647 | __start_cc_report 07:06:06 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1599 07:06:06 INFO - PROCESS | 1647 | __end_cc_report 07:06:06 INFO - PROCESS | 1647 | __startTimestamp1473689166023__endTimestamp 07:06:06 INFO - PROCESS | 1647 | 07:06:06 INFO - PROCESS | 1647 | ------- Summary: start ------- 07:06:06 INFO - PROCESS | 1647 | Number of tests: 6 07:06:06 INFO - PROCESS | 1647 | 07:06:06 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5626.71 Median:5766.57 stddev:623.61 (10.8%) stddev-sans-first:592.82 07:06:06 INFO - PROCESS | 1647 | Values: 3086.4 3243.9 3278.4 3322.8 3324.8 5469.7 5516.7 5546.9 5557.7 5583.6 5509.1 5537.1 5558.9 5590.6 5613.9 5080.5 5108.1 5109.0 5161.1 5184.6 5142.7 5247.6 5260.4 5262.3 5262.4 5101.2 5186.3 5213.6 5225.0 5228.2 5127.3 5145.9 5151.8 5170.3 5178.6 5099.7 5178.1 5181.9 5185.4 5202.3 5535.9 5541.6 5545.0 5553.4 5563.6 5011.5 6024.2 6046.3 6085.9 6140.9 6146.9 6188.5 6233.0 6234.6 6275.2 5601.0 5682.5 5706.5 5743.1 5762.7 6176.4 6186.8 6221.2 6288.0 6304.3 5994.5 6026.6 6030.7 6055.0 6082.8 6128.6 6164.4 6171.3 6177.3 6180.2 5930.6 5940.7 5954.6 6038.6 6052.1 6064.8 6079.6 6101.2 6104.5 6130.5 4907.1 4915.1 4925.1 4939.0 4957.5 4366.6 4382.7 4401.7 4416.1 4447.8 5449.2 5487.2 5522.1 5529.2 5585.1 6097.8 6104.4 6118.7 6133.3 6144.4 5493.9 5517.9 5533.1 5543.3 5557.8 4468.3 5310.8 5320.9 5350.8 5412.1 6283.9 6291.9 6295.6 6300.7 6309.1 6081.6 6112.2 6125.9 6133.4 6156.5 6205.5 6237.3 6276.3 6282.2 6283.5 5989.3 5993.3 6010.1 6021.3 6041.3 6002.5 6020.0 6021.7 6065.8 6107.7 5795.2 5868.8 5919.6 5938.2 5973.9 5919.6 5929.5 5939.0 5945.4 6043.3 5734.4 5734.5 5737.8 5766.6 5776.1 5710.8 5725.8 5758.6 5863.4 5884.6 5797.3 5809.5 5820.1 5825.9 5858.7 07:06:06 INFO - PROCESS | 1647 | 07:06:06 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:72437.31 Median:40897.19 stddev:159709.17 (390.5%) stddev-sans-first:160065.81 07:06:06 INFO - PROCESS | 1647 | Values: 478.8 484.8 526.4 528.9 531.1 29609.9 40288.2 41394.4 41437.5 41470.0 42467.6 42714.4 42755.7 42783.0 42980.6 8501.4 9709.7 9909.5 9914.3 9915.3 9702.8 9795.8 9821.6 9822.7 9841.6 9828.0 9860.8 9867.6 9870.0 9875.0 8406.5 9763.0 9803.0 9816.3 9845.8 8011.6 8876.0 9758.5 9835.8 9854.2 40463.8 40685.2 40688.8 40779.0 41015.4 3176.3 3242.3 3342.7 3395.8 3591.6 31398.5 31441.5 31503.0 31571.4 31621.7 3173.8 3236.8 3270.0 3373.5 3377.3 517294.0 517902.0 518767.0 519995.0 523418.4 60415.7 60549.2 60644.4 60663.0 60664.7 58837.7 58842.0 58847.7 58932.7 58955.4 15923.8 16132.8 16138.7 16140.4 16151.0 12361.1 12417.1 12457.6 12458.3 12461.6 14063.6 17104.7 17110.7 17179.5 17196.4 6015.5 6938.4 7086.9 7140.4 7158.6 826.6 900.0 943.7 982.0 992.8 28368.9 29751.8 29823.9 29888.0 29893.6 1013.6 1069.6 1075.9 1096.0 1140.4 14793.4 15279.0 15292.2 15399.9 15655.5 658436.0 662399.0 663731.0 664031.0 664079.0 56984.7 57061.7 57159.0 57310.1 57403.9 1216.5 1239.9 1240.2 1358.9 1375.5 29562.4 30926.5 31220.0 32214.4 32325.0 54986.6 55848.0 55870.2 55933.7 56246.0 595596.0 600998.0 601365.0 602204.0 603857.0 25868.0 26113.0 26597.6 39580.8 40051.2 45918.3 46396.8 46749.6 46788.7 47016.0 38363.0 38539.1 38728.4 40016.0 40021.4 37349.1 38044.6 38525.4 39964.8 40289.8 37370.6 38055.8 38150.2 38629.4 39159.6 30424.5 30760.0 33950.8 34244.7 34423.5 23752.6 24932.6 25187.2 25230.7 25783.5 07:06:06 INFO - PROCESS | 1647 | 07:06:06 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:37006.63 Median:34349.90 stddev:101580.43 (295.7%) stddev-sans-first:101828.07 07:06:06 INFO - PROCESS | 1647 | Values: 335.5 346.1 348.1 370.8 404.9 14313.6 14989.4 15119.7 19759.5 20193.8 19055.7 20133.7 20394.3 20427.9 21698.7 5587.3 6691.2 6837.4 6853.5 6894.7 6600.9 6806.5 6867.1 6870.6 6871.2 4612.0 4717.6 4863.8 6846.0 7892.1 6711.7 7219.4 7602.1 7602.7 8001.6 7615.7 7616.2 7620.5 7621.8 7630.0 27465.6 28949.9 29084.1 29204.3 29455.7 2246.6 2254.7 2508.6 2511.5 3102.1 16129.6 17255.2 17567.6 17699.9 17941.0 1893.8 2027.4 2109.2 2116.2 2119.3 82190.0 123277.0 132653.0 133386.0 136275.0 20302.0 20549.0 20571.4 20633.0 20827.9 34208.5 34335.5 34364.3 34413.0 34416.8 10540.2 10585.5 10737.5 10833.9 10956.6 8870.5 8880.3 8924.8 9072.4 9076.6 7854.0 9353.2 9460.0 9618.7 9641.6 3126.4 3591.0 3718.2 3720.7 3722.9 681.5 700.9 870.4 964.4 977.8 17232.4 19602.9 20072.3 20940.8 21033.0 868.8 905.8 935.2 939.0 945.6 10368.6 10884.9 11101.6 11133.8 11362.4 594931.0 608651.0 609150.6 611538.0 617060.0 129731.0 135127.3 137578.0 138602.0 142561.3 864.0 913.5 917.2 918.5 947.8 18685.6 18791.3 18899.8 18901.9 18913.1 50673.5 54256.0 54967.5 55539.7 55831.0 77128.6 77493.0 79466.9 80516.0 80600.8 6627.7 7817.5 7938.4 7996.3 8071.2 10070.8 12323.8 12329.9 12667.9 12683.6 10353.3 10688.1 10940.7 10953.6 10965.5 9049.0 10835.9 11096.9 11273.8 11274.3 10287.1 10354.5 10525.4 10715.7 11094.2 7757.0 7849.2 7906.7 8236.4 8256.0 9940.6 10347.4 10436.1 10440.3 11135.8 07:06:06 INFO - PROCESS | 1647 | 07:06:06 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5711.41 Median:4912.59 stddev:3193.41 (65.0%) stddev-sans-first:3185.65 07:06:06 INFO - PROCESS | 1647 | Values: 1363.1 1367.8 1541.6 1727.2 1920.2 6879.4 7767.8 8277.7 8477.9 9159.0 8469.7 8693.9 8705.0 8712.7 8713.3 2172.4 2239.4 2347.1 2385.4 2385.4 2333.7 2370.8 2380.7 2390.3 2397.2 2351.4 2366.2 2373.8 2383.0 2383.9 2325.5 2348.7 2358.9 2362.5 2370.4 2331.0 2349.6 2351.0 2358.3 2364.1 8702.4 8712.8 8780.1 8797.4 8808.4 5216.5 5392.4 5401.0 5401.7 5422.4 3472.2 3629.7 3710.3 3716.2 3717.3 1053.3 1085.4 1089.7 1104.5 1105.9 11394.4 11447.3 11561.0 11570.5 11570.8 4580.6 4628.4 4838.5 4842.6 4862.9 9998.7 10000.2 10008.9 10017.3 10044.5 5363.2 5371.1 5374.1 5377.2 5389.7 4139.2 4423.4 4570.4 4581.9 4898.7 3691.4 3824.8 3910.6 3958.9 4031.8 1267.7 1287.7 1290.3 1298.8 1303.6 817.4 872.2 890.8 912.2 926.7 5709.6 5941.0 5978.2 6082.4 6209.2 942.6 946.3 960.4 972.7 981.3 3805.4 4007.6 4111.1 4117.7 4135.8 10583.5 10970.7 10986.8 10995.5 11012.7 10953.9 10956.1 10957.5 10959.2 10973.4 7769.4 8105.4 8121.0 8311.0 8335.5 5887.8 5953.9 5994.9 6051.0 6087.1 9640.9 9776.2 10054.5 10091.4 10121.8 9250.0 9896.4 10217.7 10365.5 10366.3 6965.5 7124.4 7576.4 7615.9 7683.7 8271.3 8272.2 8272.4 8289.9 8357.8 7299.5 7458.0 7542.4 7565.7 7588.3 7200.5 7314.3 7327.1 7346.5 7515.1 6688.1 6892.5 7006.5 7011.6 7014.5 4847.6 4926.5 4975.2 5028.9 5171.1 4989.3 4997.7 5037.5 5046.9 5168.5 07:06:06 INFO - PROCESS | 1647 | 07:06:06 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4148.44 Median:4532.66 stddev:1238.78 (27.3%) stddev-sans-first:1208.03 07:06:06 INFO - PROCESS | 1647 | Values: 295.9 300.6 307.2 311.6 312.0 5003.4 5115.9 5189.8 5238.3 5242.6 4447.8 4964.7 5048.3 5156.7 5187.5 3975.2 3981.6 3984.3 3989.8 4005.2 3229.4 3848.6 4006.2 4018.5 4024.0 3473.5 3928.5 3951.9 3967.4 3994.3 3950.5 3955.2 3956.8 3977.1 4007.0 3489.3 3492.9 3502.6 3504.2 3517.9 3855.4 4460.9 4669.2 5040.9 5044.6 4525.7 4533.9 4536.3 4540.7 4569.4 4727.5 5404.6 5424.5 5435.3 5446.0 3444.7 3527.2 3716.0 3760.3 3778.8 4576.3 5718.2 5753.3 5754.1 5791.6 3414.6 3588.7 3642.5 3732.0 3792.5 3259.5 3886.1 3893.7 3899.2 3915.7 4290.5 4463.2 4478.5 4518.9 4522.6 4549.5 4809.5 5242.1 5483.9 5519.6 1210.2 1235.9 1265.5 1269.8 1282.6 795.7 861.3 877.9 891.6 900.5 4437.5 5049.5 5060.3 5074.4 5081.5 5372.2 5581.9 5600.7 5617.6 5629.3 4257.7 4965.7 5057.1 5066.7 5067.3 2147.6 2204.7 2476.7 2514.9 2517.5 4299.8 4351.8 4716.8 4866.0 4871.3 4132.0 4531.4 4821.1 4838.4 4905.6 4392.7 4794.7 4833.0 4868.6 4893.9 3939.7 4672.3 4688.9 4694.2 4699.4 3928.1 4132.6 4177.8 4659.6 4721.0 4122.4 4830.3 4839.5 4845.8 4849.7 3259.5 3271.7 3301.5 3309.7 3312.1 3791.5 3955.7 4059.1 4668.6 4671.0 4227.8 4480.3 4506.3 4540.0 4585.9 4214.7 4518.9 4531.4 4571.1 4588.7 4305.2 4630.7 4709.7 5230.4 5277.6 5400.6 5406.3 5421.3 5423.3 5427.6 4562.3 4782.3 5035.9 5328.9 5329.0 07:06:06 INFO - PROCESS | 1647 | 07:06:06 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:25646.13 Median:3482.77 stddev:41614.76 (1194.9%) stddev-sans-first:41689.37 07:06:06 INFO - PROCESS | 1647 | Values: 706.3 755.2 780.2 800.2 847.2 14586.4 15406.9 16056.8 16075.1 16147.4 14339.8 14685.0 14698.5 14705.4 14748.2 670.5 697.2 847.6 863.6 866.4 650.9 667.5 864.2 872.1 874.7 892.8 893.6 894.0 894.7 894.9 588.7 884.0 884.8 887.3 888.8 483.3 647.1 668.5 670.2 676.1 14687.0 14745.4 14750.9 14755.9 14766.4 13224.5 13599.5 13756.8 13806.7 14187.9 12075.2 12235.4 12247.4 12264.0 12730.4 4360.1 4398.1 4440.5 4527.6 4535.3 111826.0 112118.4 112354.4 114732.4 121251.8 9854.9 10214.2 10459.0 10505.3 10651.5 19780.3 19867.8 19970.6 20155.9 21882.0 7565.3 7611.5 7704.8 7797.9 7860.8 4230.8 4290.9 4305.5 4319.4 4448.6 3419.7 3545.8 3547.3 3553.3 3563.6 648.4 658.1 666.0 667.8 668.6 501.8 506.3 524.4 589.8 628.4 8233.9 8614.8 8735.3 8820.3 8973.7 580.1 584.7 602.3 605.9 615.8 4222.5 4331.3 4352.9 4404.7 4429.6 148978.8 150670.0 155962.2 156160.0 160805.0 134835.0 136452.0 137623.9 138733.3 141982.0 113017.8 117017.4 118586.0 118935.0 121172.0 8186.5 8404.6 8458.3 8575.8 8741.0 56788.9 58125.7 60554.0 63447.4 65863.7 84177.6 89096.8 97683.0 100122.0 100899.0 13763.5 14018.2 14148.1 14349.0 14552.6 15093.6 15293.7 15345.5 15683.1 15898.7 13480.6 13803.7 13900.3 13925.6 14171.1 14291.9 14408.9 14500.9 14956.7 15330.4 13355.6 13426.7 13464.7 13519.7 13731.1 10029.9 10936.5 11192.0 11243.8 11870.8 9774.7 10501.1 10879.8 11291.6 11500.7 07:06:06 INFO - PROCESS | 1647 | -------- Summary: end -------- 07:06:06 INFO - PROCESS | 1647 | 07:06:06 INFO - TEST-INFO | 1647: exit 0 07:06:06 INFO - TEST-OK | dromaeo_css | took 1117451ms 07:06:06 INFO - TEST-START | kraken 07:06:06 INFO - operating with platform_type : mac_ 07:06:06 INFO - Initialising browser for kraken test... 07:06:06 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmpI0jZe6/profile http://localhost:49266/getInfo.html) 07:06:08 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 07:06:08 INFO - PROCESS | 1656 | colorDepth:24 07:06:08 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 07:06:08 INFO - PROCESS | 1656 | __metrics 07:06:09 INFO - PROCESS | 1656 | 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" 07:06:09 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:06:09 INFO - TEST-INFO | 1656: exit 0 07:06:09 INFO - Browser initialized. 07:06:09 INFO - Running cycle 1/1 for kraken test... 07:06:09 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmpI0jZe6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:06:21 INFO - PROCESS | 1657 | RSS: Main: 232656896 07:06:21 INFO - PROCESS | 1657 | 07:06:24 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 07:06:25 INFO - PROCESS | 1657 | RSS: Main: 291602432 07:06:25 INFO - PROCESS | 1657 | 07:06:27 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 07:06:28 INFO - PROCESS | 1657 | RSS: Main: 287936512 07:06:28 INFO - PROCESS | 1657 | 07:06:31 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 07:06:32 INFO - PROCESS | 1657 | RSS: Main: 376430592 07:06:32 INFO - PROCESS | 1657 | 07:06:34 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 07:06:35 INFO - PROCESS | 1657 | RSS: Main: 279965696 07:06:35 INFO - PROCESS | 1657 | 07:06:37 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:06:37 INFO - PROCESS | 1657 | RSS: Main: 283987968 07:06:37 INFO - PROCESS | 1657 | 07:06:40 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 07:06:41 INFO - PROCESS | 1657 | RSS: Main: 320270336 07:06:41 INFO - PROCESS | 1657 | 07:06:44 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 07:06:44 INFO - PROCESS | 1657 | RSS: Main: 322805760 07:06:44 INFO - PROCESS | 1657 | 07:06:47 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 07:06:48 INFO - PROCESS | 1657 | RSS: Main: 320520192 07:06:48 INFO - PROCESS | 1657 | 07:06:49 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:06:50 INFO - PROCESS | 1657 | RSS: Main: 298950656 07:06:50 INFO - PROCESS | 1657 | 07:06:52 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:06:52 INFO - PROCESS | 1657 | RSS: Main: 303759360 07:06:52 INFO - PROCESS | 1657 | 07:06:54 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:06:55 INFO - PROCESS | 1657 | RSS: Main: 305844224 07:06:55 INFO - PROCESS | 1657 | 07:06:58 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:06:59 INFO - PROCESS | 1657 | RSS: Main: 315080704 07:06:59 INFO - PROCESS | 1657 | 07:07:02 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:07:02 INFO - PROCESS | 1657 | RSS: Main: 317177856 07:07:02 INFO - PROCESS | 1657 | 07:07:04 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 07:07:04 INFO - PROCESS | 1657 | RSS: Main: 360689664 07:07:04 INFO - PROCESS | 1657 | 07:07:04 INFO - PROCESS | 1657 | __start_tp_report 07:07:04 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 07:07:04 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 07:07:04 INFO - PROCESS | 1657 | |i|pagename|runs| 07:07:04 INFO - PROCESS | 1657 | |0;ai-astar;112;112;116;112;112;114;112;112;110;124 07:07:04 INFO - PROCESS | 1657 | |1;audio-beat-detection;150;142;162;160;106;130;129;107;158;107 07:07:04 INFO - PROCESS | 1657 | |2;audio-dft;185;185;184;185;185;185;180;184;185;184 07:07:04 INFO - PROCESS | 1657 | |3;audio-fft;73;78;78;155;59;57;58;67;143;58 07:07:04 INFO - PROCESS | 1657 | |4;audio-oscillator;82;84;131;71;76;78;117;67;69;116 07:07:04 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;89;87;90;86;90;86;89;87;89;86 07:07:04 INFO - PROCESS | 1657 | |6;imaging-darkroom;95;97;94;94;94;96;94;94;94;95 07:07:04 INFO - PROCESS | 1657 | |7;imaging-desaturate;86;84;87;85;85;85;86;86;87;84 07:07:04 INFO - PROCESS | 1657 | |8;json-parse-financial;54;55;55;54;53;55;55;53;55;54 07:07:04 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;63;65;69;75;73;71;69;70;70;71 07:07:04 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;89;87;86;88;85;88;88;85;87;84 07:07:04 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;142;149;151;144;156;129;144;150;243;139 07:07:04 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;203;168;169;164;177;169;168;169;170;172 07:07:04 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;68;67;68;67;67;66;67;68;67;67 07:07:04 INFO - PROCESS | 1657 | __end_tp_report 07:07:04 INFO - PROCESS | 1657 | __start_cc_report 07:07:04 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,2004 07:07:04 INFO - PROCESS | 1657 | __end_cc_report 07:07:04 INFO - PROCESS | 1657 | __startTimestamp1473689224974__endTimestamp 07:07:04 INFO - PROCESS | 1657 | 07:07:04 INFO - PROCESS | 1657 | ------- Summary: start ------- 07:07:04 INFO - PROCESS | 1657 | Number of tests: 14 07:07:04 INFO - PROCESS | 1657 | 07:07:04 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:113.60 Median:112.00 stddev:3.98 (3.6%) stddev-sans-first:4.18 07:07:04 INFO - PROCESS | 1657 | Values: 112.0 112.0 116.0 112.0 112.0 114.0 112.0 112.0 110.0 124.0 07:07:04 INFO - PROCESS | 1657 | 07:07:04 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:135.10 Median:146.00 stddev:22.67 (15.5%) stddev-sans-first:23.40 07:07:04 INFO - PROCESS | 1657 | Values: 150.0 142.0 162.0 160.0 106.0 130.0 129.0 107.0 158.0 107.0 07:07:04 INFO - PROCESS | 1657 | 07:07:04 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:184.20 Median:185.00 stddev:1.55 (0.8%) stddev-sans-first:1.62 07:07:04 INFO - PROCESS | 1657 | Values: 185.0 185.0 184.0 185.0 185.0 185.0 180.0 184.0 185.0 184.0 07:07:04 INFO - PROCESS | 1657 | 07:07:04 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:82.60 Median:63.00 stddev:36.04 (57.2%) stddev-sans-first:38.06 07:07:04 INFO - PROCESS | 1657 | Values: 73.0 78.0 78.0 155.0 59.0 57.0 58.0 67.0 143.0 58.0 07:07:04 INFO - PROCESS | 1657 | 07:07:04 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:89.10 Median:73.50 stddev:23.21 (31.6%) stddev-sans-first:24.48 07:07:04 INFO - PROCESS | 1657 | Values: 82.0 84.0 131.0 71.0 76.0 78.0 117.0 67.0 69.0 116.0 07:07:04 INFO - PROCESS | 1657 | 07:07:04 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:87.90 Median:89.00 stddev:1.66 (1.9%) stddev-sans-first:1.72 07:07:04 INFO - PROCESS | 1657 | Values: 89.0 87.0 90.0 86.0 90.0 86.0 89.0 87.0 89.0 86.0 07:07:04 INFO - PROCESS | 1657 | 07:07:04 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:94.70 Median:94.50 stddev:1.06 (1.1%) stddev-sans-first:1.12 07:07:04 INFO - PROCESS | 1657 | Values: 95.0 97.0 94.0 94.0 94.0 96.0 94.0 94.0 94.0 95.0 07:07:04 INFO - PROCESS | 1657 | 07:07:04 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:85.50 Median:86.00 stddev:1.08 (1.3%) stddev-sans-first:1.13 07:07:04 INFO - PROCESS | 1657 | Values: 86.0 84.0 87.0 85.0 85.0 85.0 86.0 86.0 87.0 84.0 07:07:04 INFO - PROCESS | 1657 | 07:07:04 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:55.00 stddev:0.82 (1.5%) stddev-sans-first:0.87 07:07:04 INFO - PROCESS | 1657 | Values: 54.0 55.0 55.0 54.0 53.0 55.0 55.0 53.0 55.0 54.0 07:07:04 INFO - PROCESS | 1657 | 07:07:04 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:70.50 stddev:3.50 (5.0%) stddev-sans-first:2.78 07:07:04 INFO - PROCESS | 1657 | Values: 63.0 65.0 69.0 75.0 73.0 71.0 69.0 70.0 70.0 71.0 07:07:04 INFO - PROCESS | 1657 | 07:07:04 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:86.70 Median:87.50 stddev:1.64 (1.9%) stddev-sans-first:1.51 07:07:04 INFO - PROCESS | 1657 | Values: 89.0 87.0 86.0 88.0 85.0 88.0 88.0 85.0 87.0 84.0 07:07:04 INFO - PROCESS | 1657 | 07:07:04 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:154.70 Median:149.50 stddev:31.91 (21.3%) stddev-sans-first:33.51 07:07:04 INFO - PROCESS | 1657 | Values: 142.0 149.0 151.0 144.0 156.0 129.0 144.0 150.0 243.0 139.0 07:07:04 INFO - PROCESS | 1657 | 07:07:04 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.90 Median:169.50 stddev:11.08 (6.5%) stddev-sans-first:3.50 07:07:04 INFO - PROCESS | 1657 | Values: 203.0 168.0 169.0 164.0 177.0 169.0 168.0 169.0 170.0 172.0 07:07:04 INFO - PROCESS | 1657 | 07:07:04 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.00 stddev:0.63 (0.9%) stddev-sans-first:0.60 07:07:04 INFO - PROCESS | 1657 | Values: 68.0 67.0 68.0 67.0 67.0 66.0 67.0 68.0 67.0 67.0 07:07:04 INFO - PROCESS | 1657 | -------- Summary: end -------- 07:07:04 INFO - PROCESS | 1657 | 07:07:05 INFO - TEST-INFO | 1657: exit 0 07:07:05 INFO - TEST-OK | kraken | took 59059ms 07:07:05 INFO - SUITE-END | took 1176s 07:07:06 INFO - Completed test suite (00:19:36) 07:07:06 INFO - javascript benchmark 07:07:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3086.382723455309, 3243.935121297574, 3278.409886220479, 3322.7840190387637, 3324.767266291359, 5469.72651367432, 5516.72416379181, 5546.91679624805, 5557.72211389431, 5583.637063590869, 5509.090999984997, 5537.058700020996, 5558.944410555893, 5590.608657393994, 5613.859653508664, 5080.517350851663, 5108.131617625013, 5109.0, 5161.148410512261, 5184.637075404723, 5142.691438513682, 5247.606429517782, 5260.447652996438, 5262.342207224097, 5262.447443018466, 5101.183810590325, 5186.299849520305, 5213.58291336694, 5225.0, 5228.215767634847, 5127.307813445212, 5145.9485405146215, 5151.7681704323395, 5170.276161337416, 5178.585713142939, 5099.745012749348, 5178.119719647669, 5181.948180518184, 5185.403678576974, 5202.271681964528, 5535.916961245584, 5541.556675465953, 5545.0, 5553.361363443215, 5563.610547261694, 5011.5489605935645, 6024.156122327541, 6046.33174001866, 6085.937305169714, 6140.877182456371, 6146.938530614726, 6188.504919606466, 6232.968835155795, 6234.563580549363, 6275.18422605063, 5600.971995140039, 5682.54539636828, 5706.4864162225595, 5743.138529220609, 5762.654240745527, 6176.413240742122, 6186.752529898844, 6221.191245138193, 6287.968560157261, 6304.338044505261, 5994.520438364921, 6026.578139530191, 6030.698465076729, 6055.031195008777, 6082.756689732361, 6128.63228206309, 6164.445199932027, 6171.290301615282, 6177.2587289525545, 6180.165677633552, 5930.584859059824, 5940.732667029994, 5954.612950158313, 6038.5772995889865, 6052.12244224585, 6064.75740970365, 6079.604825686405, 6101.237345331833, 6104.481119104926, 6130.47890929276, 4907.067657145131, 4915.139850526144, 4925.064237794808, 4939.036887806915, 4957.529034741766, 4366.628836548929, 4382.671299652538, 4401.6698747594055, 4416.072624748775, 4447.799849006802, 5449.182622606561, 5487.2317875497465, 5522.061249587579, 5529.198266251451, 5585.078462053797, 6097.796915383898, 6104.428839602322, 6118.663473509, 6133.294671112879, 6144.446999770043, 5493.890122197534, 5517.889642207214, 5533.087040638249, 5543.307086614173, 5557.833265002057, 4468.3074123510905, 5310.840674779763, 5320.866978325573, 5350.839474815762, 5412.052890744183, 6283.905741413882, 6291.937080629226, 6295.622262664255, 6300.684965751694, 6309.1167236587835, 6081.6351018939, 6112.235970503687, 6125.938740612537, 6133.3866613338305, 6156.507479401547, 6205.472534834409, 6237.345078766664, 6276.309605943434, 6282.183316168869, 6283.5287353449585, 5989.251343582052, 5993.250843644544, 6010.1285313628405, 6021.307549631849, 6041.305249896142, 6002.489788367863, 6020.0, 6021.698915054142, 6065.787697430569, 6107.725152368243, 5795.217645617788, 5868.765249389977, 5919.6152250103605, 5938.228030356199, 5973.940260597512, 5919.6152250103605, 5929.525637948867, 5938.970305148447, 5945.405459454193, 6043.274807023009, 5734.3692193857805, 5734.512566431733, 5737.827865164052, 5766.5675074368755, 5776.104703770922, 5710.771569137354, 5725.799597014251, 5758.568107391879, 5863.413658634103, 5884.617499862667, 5797.275340582427, 5809.50619197373, 5820.126980952891, 5825.94174058254, 5858.736356863866], "value": 5586.660030555982, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [478.7869398117839, 484.8448496481123, 526.3683579704353, 528.920661900715, 531.0785786660146, 29609.85195074027, 40288.1942361153, 41394.379084313725, 41437.51374917507, 41469.96325091874, 42467.57532424675, 42714.35928461076, 42755.7173284802, 42783.0, 42980.570194298045, 8501.404901656886, 9709.660161894335, 9909.454979976099, 9914.305998580103, 9915.305928585001, 9702.805943881118, 9795.804083918318, 9821.607135714563, 9822.70531884041, 9841.55712992917, 9828.0, 9860.753481162956, 9867.60529578816, 9870.01299870011, 9874.950625246864, 8406.537640429773, 9763.0, 9803.019698030212, 9816.26378021644, 9845.753856153618, 8011.599420029033, 8875.955620221923, 9758.463284519348, 9835.754106147331, 9854.162396196332, 40463.7976810117, 40685.18629627391, 40688.796556017325, 40779.0, 41015.38476922848, 3176.3170918252686, 3242.3353212591364, 3342.7325813934826, 3395.779274347184, 3591.586967498745, 31398.529022064686, 31441.528377074363, 31503.0, 31571.36857262842, 31621.683783162105, 3173.76196785242, 3236.8057916525086, 3269.96730032699, 3373.4771110477914, 3377.3245350929665, 517294.0, 517902.0, 518767.0, 519995.0, 523418.38290808303, 60415.69792151011, 60549.183524494336, 60644.39355606476, 60663.0, 60664.69667651634, 58837.70581147067, 58842.0, 58847.70576147178, 58932.705336473045, 58955.410445895846, 15923.840761592468, 16132.838671613368, 16138.677226455406, 16140.435084771976, 16151.0, 12361.07291953089, 12417.130800844036, 12457.563985260473, 12458.252504849737, 12461.563845265373, 14063.578092657237, 17104.74342884858, 17110.657786844193, 17179.484615461555, 17196.398126065527, 6015.54883383748, 6938.444924406035, 7086.92913070873, 7140.357367836919, 7158.642067896584, 826.6486743134144, 899.9775005624781, 943.6508491858057, 981.9705408837747, 992.7964767222702, 28368.85815570909, 29751.75270078066, 29823.850880745456, 29887.958661466313, 29893.551596726065, 1013.640157744005, 1069.6042464288264, 1075.9300645458177, 1095.9835602465969, 1140.3898914080926, 14793.408263669333, 15279.006864553769, 15292.235388230767, 15399.923000384926, 15655.452059178102, 658436.0, 662399.0, 663731.0, 664031.0, 664079.0, 56984.71507642518, 57061.71469142628, 57159.0, 57310.14034789481, 57403.851922962145, 1216.5377156680404, 1239.8698136695516, 1240.2000709542474, 1358.8980826438058, 1375.5460697969845, 29562.408751824412, 30926.453677315592, 31220.0, 32214.35571288608, 32325.0, 54986.60107191335, 55848.0, 55870.16194757163, 55933.72033139808, 56246.0, 595596.0220198871, 600998.0, 601365.0, 602204.0, 603857.0, 25868.0, 26113.0, 26597.601035984088, 39580.80209598933, 40051.198976020896, 45918.31122533231, 46396.7680161597, 46749.597512074695, 46788.720727734326, 47016.0, 38363.0, 38539.06296866591, 38728.41907371448, 40016.0, 40021.39967900426, 37349.066273343386, 38044.61955380411, 38525.4221186688, 39964.800175998935, 40289.79855100706, 37370.626293736714, 38055.809720952326, 38150.23699525939, 38629.4205586922, 39159.6084039156, 30424.543631845096, 30760.0, 33950.830245849604, 34244.65755342415, 34423.48364774581, 23752.643710344015, 24932.62601061022, 25187.24438266855, 25230.74769252284, 25783.484330313662], "value": 19986.41980433655, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.4649334311773, 346.08460621655706, 348.11404974340303, 370.8498058286395, 404.89472737088346, 14313.56864313568, 14989.351171371154, 15119.697606047886, 19759.5060123497, 20193.798062019374, 19055.71416428751, 20133.697994530055, 20394.286199983002, 20427.897860510715, 21698.674519882214, 5587.273654424929, 6691.230508491537, 6837.427391699912, 6853.451723862104, 6894.655267236618, 6600.93399066008, 6806.523543351967, 6867.141607299088, 6870.622115783655, 6871.209810871739, 4611.953880461186, 4717.551832575917, 4863.781129849165, 6846.0, 7892.131865494791, 6711.6979735912, 7219.422446204318, 7602.0877494700435, 7602.657880395395, 8001.599920003977, 7615.733449329302, 7616.200298968639, 7620.50466719667, 7621.847563048708, 7630.046244219473, 27465.588016179772, 28949.933761591637, 29084.12747617575, 29204.269893252625, 29455.70544294551, 2246.6068438023444, 2254.6618007299035, 2508.611165269386, 2511.535365957304, 3102.053873568561, 16129.596760080974, 17255.22351494186, 17567.560810979827, 17699.93800371969, 17941.01324427156, 1893.810618938109, 2027.3917824652544, 2109.2090466075224, 2116.2064225915283, 2119.300630791835, 82190.0, 123277.0, 132653.0, 133386.0, 136275.0, 20302.0, 20549.0, 20571.38285851427, 20633.0, 20827.895860520905, 34208.48687269693, 34335.484967725504, 34364.31271374559, 34413.0, 34416.82791586076, 10540.209484288709, 10585.52365143555, 10737.51681174349, 10833.945830270799, 10956.616518421817, 8870.467771933705, 8880.289576833839, 8924.776880577907, 9072.412327842601, 9076.591553379982, 7853.960730196427, 9353.204977576846, 9459.952700236454, 9618.711438656852, 9641.566129524188, 3126.374725054998, 3590.9640903591153, 3718.200586873835, 3720.739548231598, 3722.925541489155, 681.5467713970154, 700.8563244534859, 870.3602851903846, 964.3924327673661, 977.7506735782472, 17232.396866109626, 19602.90198548998, 20072.297469588495, 20940.790592093883, 21033.0, 868.7871471489399, 905.8369493491235, 935.2004036548653, 938.990610093904, 945.6312038305067, 10368.558187640388, 10884.891151088388, 11101.5559377624, 11133.832992505118, 11362.375069371265, 594931.0, 608651.0, 609150.588223531, 611538.0, 617060.0, 129731.0, 135127.3243633795, 137578.0, 138602.0, 142561.28719356336, 863.9654413823503, 913.4656226107669, 917.1882883647886, 918.4948278446694, 947.7630592351912, 18685.626287474446, 18791.34230301933, 18899.811001889804, 18901.905490472458, 18913.054347282858, 50673.49326506688, 54256.0, 54967.45032549783, 55539.72230138985, 55831.0, 77128.6143569301, 77493.0, 79466.85303514331, 80516.0, 80600.7909881364, 6627.668616569056, 7817.491863029112, 7938.444308898553, 7996.280334769898, 8071.23323284287, 10070.8489372658, 12323.753524929272, 12329.938350308192, 12667.93666031664, 12683.556075537312, 10353.327033742782, 10688.060783284658, 10940.671779846618, 10953.561857525929, 10965.50655220501, 9048.954755226181, 10835.945820270848, 11096.944515277371, 11273.7745245093, 11274.279487768852, 10287.125594324567, 10354.5340459678, 10525.421101839165, 10715.732106697084, 11094.223404361617, 7757.0, 7849.215078492105, 7906.655868502254, 8236.423450358418, 8256.0, 9940.552675129491, 10347.43089130105, 10436.060754532129, 10440.269181157246, 11135.777284454427], "value": 10725.359730526397, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1363.1275983370638, 1367.849536550979, 1541.6300087978884, 1727.2227497626063, 1920.222309964464, 6879.380855722984, 7767.766966990992, 8277.66889324427, 8477.872831907518, 9158.99251082382, 8469.66121355145, 8693.913060869389, 8705.042445331008, 8712.651493940251, 8713.259372953307, 2172.391730315514, 2239.4401399650087, 2347.1080989224115, 2385.3678775124604, 2385.3798012516786, 2333.673285739998, 2370.7629237076326, 2380.73811880693, 2390.306811024801, 2397.2089210560475, 2351.3533778211045, 2366.2428023032635, 2373.8338316317863, 2383.0467812874817, 2383.8688872112025, 2325.5116425550655, 2348.6946696929376, 2358.8820558972157, 2362.5274945010897, 2370.3600027992347, 2331.0093210385517, 2349.635806450003, 2350.988245058764, 2358.3160883343808, 2364.066193853418, 8702.434341767828, 8712.782180445476, 8780.121987801169, 8797.428167169177, 8808.383413161082, 5216.504432078947, 5392.433794451605, 5400.972995135038, 5401.675899446007, 5422.40353561108, 3472.1840367513614, 3629.709623230135, 3710.2950439416422, 3716.1638631307737, 3717.312297225022, 1053.2837670384135, 1085.3542142425244, 1089.6567581211973, 1104.4588151805638, 1105.8894110588876, 11394.430278486125, 11447.255928364713, 11561.0, 11570.479328430241, 11570.76858462826, 4580.564846339593, 4628.352030715702, 4838.516148385203, 4842.6368022398465, 4862.854114376574, 9998.700038998843, 10000.24998125129, 10008.949955250177, 10017.298789084694, 10044.497775111215, 5363.168708850135, 5371.060064488699, 5374.086405311106, 5377.220303056035, 5389.730513474311, 4139.151473947833, 4423.358613001097, 4570.360149579062, 4581.885452863638, 4898.681585696918, 3691.3909204981414, 3824.7513911595656, 3910.6089391061237, 3958.9010274743364, 4031.7580945143386, 1267.7274386007052, 1287.729576788866, 1290.348374071088, 1298.811672307529, 1303.5828534868751, 817.3502065857657, 872.2062922740275, 890.7505898348472, 912.2291663544295, 926.6756635177769, 5709.600327977002, 5940.970295148497, 5978.192943952511, 6082.391760823883, 6209.19280493542, 942.561708805395, 946.3470205558143, 960.4237457525429, 972.7276362618478, 981.342500524636, 3805.3911374179997, 4007.5557985783516, 4111.135532599347, 4117.670586353084, 4135.834566617302, 10583.523741431654, 10970.725731856768, 10986.835197472044, 10995.450227488594, 11012.669619911416, 10953.945230273797, 10956.12351011917, 10957.452127393599, 10959.178061645249, 10973.3964631946, 7769.3784497240085, 8105.432619716563, 8120.999999999882, 8311.0, 8335.49987000782, 5887.764489420547, 5953.851153721192, 5994.901784375608, 6050.969745151246, 6087.147799308012, 9640.855387169058, 9776.169025632616, 10054.54754536033, 10091.444970526398, 10121.848172277274, 9249.953750231205, 9896.406215627085, 10217.693469195936, 10365.481725913825, 10366.326188797704, 6965.547239429624, 7124.3944264738075, 7576.393888489018, 7615.923840761521, 7683.731069412544, 8271.296939760188, 8272.214139656722, 8272.420930535045, 8289.917100828914, 8357.785186925348, 7299.452541059336, 7458.0, 7542.434317426105, 7565.735199268, 7588.27911348421, 7200.459965502713, 7314.268573142856, 7327.0841144856895, 7346.530693861142, 7515.09818821723, 6688.130543029375, 6892.517523773287, 7006.509544331848, 7011.614361209979, 7014.508984371045, 4847.587955023721, 4926.5073492650445, 4975.203967365204, 5028.874278143076, 5171.095058364846, 4989.2516122580455, 4997.700137991732, 5037.496250374933, 5046.873828154326, 5168.457311982188], "value": 4595.319637574947, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.86538125153044, 300.57318607577247, 307.15225976305396, 311.5949265954259, 312.04514186589023, 5003.424606170292, 5115.9488405115935, 5189.818356357524, 5238.266642670029, 5242.554382877451, 4447.777611119447, 4964.726940018298, 5048.293238946541, 5156.74216289185, 5187.533122019017, 3975.2248311579256, 3981.600331633547, 3984.2828290907623, 3989.760614363133, 4005.2189822984456, 3229.3541291741626, 3848.5766565677754, 4006.2388146252265, 4018.5177778666457, 4023.9537720192666, 3473.548438702978, 3928.5482169550583, 3951.861684841031, 3967.36522156455, 3994.2810294147043, 3950.545687245975, 3955.2089582083554, 3956.802159891994, 3977.1449138435373, 4006.9764768791865, 3489.342562282921, 3492.947605785915, 3502.6339727506293, 3504.179135795709, 3517.8944631661093, 3855.383138697823, 4460.888477788081, 4669.1828929937465, 5040.873978150539, 5044.621653376012, 4525.691897132453, 4533.909321813579, 4536.251518499444, 4540.704854184468, 4569.4007097515605, 4727.45634252062, 5404.567634589223, 5424.484673955968, 5435.293411856473, 5446.046941785211, 3444.6555344465605, 3527.171114788023, 3716.0338312039066, 3760.3231418344685, 3778.770525390033, 4576.336431217454, 5718.170185540629, 5753.26719808092, 5754.136879468071, 5791.594588378815, 3414.641462646436, 3588.7308451866215, 3642.5213920705664, 3732.011017080476, 3792.469054332396, 3259.543663887058, 3886.1346553654403, 3893.7469064510715, 3899.1811719539214, 3915.6506096342537, 4290.549492303326, 4463.24124898768, 4478.484974228006, 4518.915460289507, 4522.615577675869, 4549.522300158435, 4809.509052193831, 5242.13504771716, 5483.862903427446, 5519.558435325244, 1210.2375503432781, 1235.8887700106854, 1265.531753251303, 1269.7587458382877, 1282.6216266201493, 795.7055889320989, 861.2593169873903, 877.8500164784153, 891.5809569502323, 900.527223207808, 4437.489688685778, 5049.469805670352, 5060.292743382272, 5074.391073071181, 5081.466446023114, 5372.189609041176, 5581.944180558224, 5600.719964001865, 5617.606767526316, 5629.296337957755, 4257.701960862772, 4965.677230979974, 5057.142714366371, 5066.746662666926, 5067.341245638044, 2147.5704859028256, 2204.7354317482004, 2476.739942306068, 2514.911978080758, 2517.5342561626157, 4299.82916570932, 4351.760653164043, 4716.787744551503, 4866.026794641086, 4871.318015477836, 4132.0, 4531.377994445647, 4821.132196204717, 4838.419389673261, 4905.601903457497, 4392.714473559208, 4794.712317260975, 4833.009233107205, 4868.586170175504, 4893.9021219576125, 3939.684825213919, 4672.34587157805, 4688.882777930579, 4694.201985662377, 4699.389079419653, 3928.135810121769, 4132.566080561618, 4177.770222637784, 4659.603933665677, 4721.0, 4122.42286079955, 4830.2513110467935, 4839.467658557626, 4845.8061677532505, 4849.660523763303, 3259.527368531597, 3271.721903638122, 3301.5377847101904, 3309.6690330966712, 3312.1057314525424, 3791.469194312731, 3955.723099383016, 4059.086705491241, 4668.626509879269, 4670.9766451167525, 4227.830886764618, 4480.305552639346, 4506.324051392317, 4539.954600453953, 4585.931211031904, 4214.662826973773, 4518.932216016697, 4531.417092257381, 4571.131485017836, 4588.678792484494, 4305.160493703697, 4630.699004564693, 4709.740964246862, 5230.417164242506, 5277.630565860506, 5400.594955378284, 5406.297181366397, 5421.349438067457, 5423.267858839164, 5427.592930530305, 4562.270036794097, 4782.254477115897, 5035.899282014412, 5328.866778330417, 5328.973355133355], "value": 3764.4210934169246, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.2972342519195, 755.240982812274, 780.1808101493431, 800.2317774936062, 847.1570787066869, 14586.416543338268, 15406.921515493892, 16056.75914861278, 16075.115868627221, 16147.354105835782, 14339.78490322646, 14685.04547204431, 14698.485553005647, 14705.411783528645, 14748.188849613267, 670.5071772247394, 697.2365260040248, 847.563504795031, 863.5768473447997, 866.3805379153904, 650.8828410886039, 667.5427332277405, 864.2178828160494, 872.0581771686595, 874.7288340614398, 892.7678803511069, 893.6202114101513, 894.0076515068268, 894.7181637784109, 894.9105089491064, 588.6909372579387, 884.0319849764497, 884.8407286688393, 887.330065800323, 888.7955770172832, 483.258198665051, 647.0552992630764, 668.5487296075179, 670.1991120610824, 676.0940339944498, 14686.971911966166, 14745.410183592643, 14750.926245368704, 14755.926220368936, 14766.409343626243, 13224.537141200011, 13599.524016659469, 13756.793648095287, 13806.65483362914, 14187.929060354736, 12075.215111017756, 12235.388230588434, 12247.387630618434, 12264.0, 12730.427130779137, 4360.119047619048, 4398.076403955173, 4440.511543730187, 4527.615152712024, 4535.274356103041, 111826.0, 112118.43940780325, 112354.43822780834, 114732.42633786777, 121251.78748212493, 9854.852177217348, 10214.182865370753, 10459.0, 10505.31715438494, 10651.467426628638, 19780.30768923081, 19867.801321986593, 19970.60058798845, 20155.899220503805, 21882.01530931112, 7565.319121279002, 7611.467197296136, 7704.8459030820195, 7797.9220207798335, 7860.764177074582, 4230.788460576959, 4290.914181716348, 4305.48334199898, 4319.416878721332, 4448.644108471314, 3419.743519236067, 3545.7872527648424, 3547.343741407848, 3553.34263161316, 3563.572371315459, 648.3937518420292, 658.1016911915297, 665.9900101498481, 667.7662818013754, 668.6155460610169, 501.8494451664559, 506.25833154428204, 524.3864678326368, 589.755251570602, 628.4375483941847, 8233.917660823436, 8614.802075640946, 8735.301175905912, 8820.280045391215, 8973.730788076367, 580.1327016110941, 584.6959581017846, 602.3406659597827, 605.8515663662342, 615.8429600451856, 4222.535521092677, 4331.306990881444, 4352.912941741148, 4404.691671583022, 4429.601335879786, 148978.7653185203, 150670.0, 155962.22018889833, 156160.0, 160805.0, 134835.0, 136452.0, 137623.93564096547, 138733.30633346768, 141982.0, 113017.76623169452, 117017.41491292659, 118586.0, 118935.0, 121172.0, 8186.549739764132, 8404.621792019496, 8458.28104611115, 8575.785605359915, 8740.956295218482, 56788.86422271614, 58125.709371452875, 60554.0, 63447.413814655, 65863.67068164628, 84177.57911210405, 89096.772580686, 97683.02316976739, 100122.0, 100899.0, 13763.518276860263, 14018.22899740524, 14148.080374775607, 14349.0, 14552.636184095481, 15093.622659433162, 15293.694126117192, 15345.462908798585, 15683.059016459049, 15898.68202635964, 13480.639980967124, 13803.654908627364, 13900.304984750519, 13925.582232533041, 14171.149731016172, 14291.928540357232, 14408.927955360155, 14500.85499144995, 14956.700865982837, 15330.38678452894, 13355.599332020054, 13426.731465370833, 13464.730705386033, 13519.662008449868, 13731.107478013897, 10029.899701002896, 10936.45317734126, 11192.048675862643, 11243.775124497628, 11870.762584748083, 9774.657886973922, 10501.138611641465, 10879.78240435171, 11291.604793832177, 11500.654980350602], "value": 7776.862292439321, "unit": "score"}], "name": "dromaeo_css", "value": 7376.691905868818, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 116.0, 112.0, 112.0, 114.0, 112.0, 112.0, 110.0, 124.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 142.0, 162.0, 160.0, 106.0, 130.0, 129.0, 107.0, 158.0, 107.0], "value": 135.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 184.0, 185.0, 185.0, 185.0, 180.0, 184.0, 185.0, 184.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 78.0, 78.0, 155.0, 59.0, 57.0, 58.0, 67.0, 143.0, 58.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 131.0, 71.0, 76.0, 78.0, 117.0, 67.0, 69.0, 116.0], "value": 89.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 90.0, 86.0, 90.0, 86.0, 89.0, 87.0, 89.0, 86.0], "value": 87.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 94.0, 94.0, 94.0, 96.0, 94.0, 94.0, 94.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 85.0, 85.0, 85.0, 86.0, 86.0, 87.0, 84.0], "value": 85.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 55.0, 54.0, 53.0, 55.0, 55.0, 53.0, 55.0, 54.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 69.0, 75.0, 73.0, 71.0, 69.0, 70.0, 70.0, 71.0], "value": 69.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 86.0, 88.0, 85.0, 88.0, 88.0, 85.0, 87.0, 84.0], "value": 86.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 149.0, 151.0, 144.0, 156.0, 129.0, 144.0, 150.0, 243.0, 139.0], "value": 154.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 168.0, 169.0, 164.0, 177.0, 169.0, 168.0, 169.0, 170.0, 172.0], "value": 172.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 67.0, 67.0, 66.0, 67.0, 68.0, 67.0, 67.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1478.1000000000001, "alertThreshold": 2.0}]} 07:07:06 INFO - Return code: 0 07:07:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:07:06 INFO - # TBPL SUCCESS # 07:07:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:07:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:07:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:07:06 INFO - 2016-09-12 07:07:06.292 screenresolution[1661:9751] starting screenresolution argv=screenresolution get 07:07:06 INFO - 2016-09-12 07:07:06.295 screenresolution[1661:9751] Display 0: 1600x1200x32@60 07:07:06 INFO - 2016-09-12 07:07:06.301 screenresolution[1662:9754] starting screenresolution argv=screenresolution list 07:07:06 INFO - Available Modes on Display 0 07:07:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:07:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:07:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:07:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:07:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:07:06 INFO - Intel Iris: 07:07:06 INFO - Chipset Model: Intel Iris 07:07:06 INFO - Type: GPU 07:07:06 INFO - Bus: Built-In 07:07:06 INFO - VRAM (Dynamic, Max): 1536 MB 07:07:06 INFO - Vendor: Intel (0x8086) 07:07:06 INFO - Device ID: 0x0a2e 07:07:06 INFO - Revision ID: 0x0009 07:07:06 INFO - Displays: 07:07:06 INFO - Display: 07:07:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:07:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:07:06 INFO - Display Serial Number: 1600x1200 60 07:07:06 INFO - Main Display: Yes 07:07:06 INFO - Mirror: Off 07:07:06 INFO - Online: Yes 07:07:06 INFO - Rotation: Supported 07:07:06 INFO - Return code: 0 07:07:06 INFO - Running post-action listener: _resource_record_post_action 07:07:06 INFO - [mozharness: 2016-09-12 14:07:06.535918Z] Finished run-tests step (success) 07:07:06 INFO - Running post-run listener: _resource_record_post_run 07:07:06 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37176320; Write bytes: 706006528; Read time: 239; Write time: 1965 07:07:06 INFO - TinderboxPrint: CPU usage
53.8% 07:07:06 INFO - TinderboxPrint: I/O read bytes / time
37,176,320 / 239 07:07:06 INFO - TinderboxPrint: I/O write bytes / time
706,006,528 / 1,965 07:07:06 INFO - TinderboxPrint: CPU idle
2,241.5 (46.2%) 07:07:06 INFO - TinderboxPrint: CPU system
970.0 (20.0%) 07:07:06 INFO - TinderboxPrint: CPU user
1,638.2 (33.8%) 07:07:06 INFO - TinderboxPrint: Swap in / out
443,064,320 / 0 07:07:06 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 386097664; Write bytes: 379090944; Read time: 29036; Write time: 775 07:07:06 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 36221952; Write bytes: 296470016; Read time: 227; Write time: 1122 07:07:07 INFO - Running post-run listener: _upload_blobber_files 07:07:07 INFO - Blob upload gear active. 07:07:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:07:07 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 07:07:07 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'] 07:07:07 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 07:07:07 INFO - (blobuploader) - INFO - Open directory for files ... 07:07:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:07:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:07:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:07:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:07:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:07:08 INFO - (blobuploader) - INFO - Done attempting. 07:07:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:07:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:07:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:07:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:07:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:07:09 INFO - (blobuploader) - INFO - Done attempting. 07:07:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:07:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:07:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:07:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:07:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:07:12 INFO - (blobuploader) - INFO - Done attempting. 07:07:12 INFO - (blobuploader) - INFO - Iteration through files over. 07:07:12 INFO - Return code: 0 07:07:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:07:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:07:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/91892962062a0c2f3963b6db16db3dab52050d833531c73732ba0eec85c35d34c3a3bda0b5366a19c3972f6376d9688122bd97af1d0c7b0299dcfa96b37710bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aa3e181256722ae55785b42cbe9803ec257436c5b61455c76b99b0190b8cd8e50f8b8abffc54ee5b30574e999b922d8b320cf459df156caf6d78d0744e7b77d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4dd3cc463f59f4c4ff17806df30ec843e76e018d749f3ca84e8a0ea58d685da283c86b2e9d4aafcabaa5131b7bac20fb535f655c6f747180709899268dc5ceec"} 07:07:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:07:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:07:12 INFO - Contents: 07:07:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/91892962062a0c2f3963b6db16db3dab52050d833531c73732ba0eec85c35d34c3a3bda0b5366a19c3972f6376d9688122bd97af1d0c7b0299dcfa96b37710bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aa3e181256722ae55785b42cbe9803ec257436c5b61455c76b99b0190b8cd8e50f8b8abffc54ee5b30574e999b922d8b320cf459df156caf6d78d0744e7b77d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4dd3cc463f59f4c4ff17806df30ec843e76e018d749f3ca84e8a0ea58d685da283c86b2e9d4aafcabaa5131b7bac20fb535f655c6f747180709899268dc5ceec"} 07:07:12 INFO - Running post-run listener: copy_logs_to_upload_dir 07:07:12 INFO - Copying logs to upload dir... 07:07:12 INFO - mkdir: /builds/slave/test/build/upload/logs 07:07:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.462107 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-09-12 07:07:12.122191) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 07:07:12.125800) ========= 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.69mUsMhMl1/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.s25nSsIlHM/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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/91892962062a0c2f3963b6db16db3dab52050d833531c73732ba0eec85c35d34c3a3bda0b5366a19c3972f6376d9688122bd97af1d0c7b0299dcfa96b37710bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aa3e181256722ae55785b42cbe9803ec257436c5b61455c76b99b0190b8cd8e50f8b8abffc54ee5b30574e999b922d8b320cf459df156caf6d78d0744e7b77d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4dd3cc463f59f4c4ff17806df30ec843e76e018d749f3ca84e8a0ea58d685da283c86b2e9d4aafcabaa5131b7bac20fb535f655c6f747180709899268dc5ceec"} build_url:https://queue.taskcluster.net/v1/task/BgCr2OswS7aC1CoqyBmYVg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011132 build_url: 'https://queue.taskcluster.net/v1/task/BgCr2OswS7aC1CoqyBmYVg/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/91892962062a0c2f3963b6db16db3dab52050d833531c73732ba0eec85c35d34c3a3bda0b5366a19c3972f6376d9688122bd97af1d0c7b0299dcfa96b37710bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aa3e181256722ae55785b42cbe9803ec257436c5b61455c76b99b0190b8cd8e50f8b8abffc54ee5b30574e999b922d8b320cf459df156caf6d78d0744e7b77d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4dd3cc463f59f4c4ff17806df30ec843e76e018d749f3ca84e8a0ea58d685da283c86b2e9d4aafcabaa5131b7bac20fb535f655c6f747180709899268dc5ceec"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 07:07:12.158582) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 07:07:12.158907) ========= 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.69mUsMhMl1/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.s25nSsIlHM/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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.005208 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 07:07:12.207425) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 07:07:12.207749) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 07:07:12.208113) ========= ========= Total master_lag: 0.17 =========