builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0309 starttime: 1473113916.29 results: success (0) buildid: 20160905130616 builduid: cd7a9bd9d4e24775baeb140ba9a34ac4 revision: 3076fad24896ca0b9d026f67c7f27731191fb4f0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-05 15:18:36.286157) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-05 15:18:36.286699) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-05 15:18:36.287073) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-05 15:18:36.401389) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 15:18:36.401689) ========= 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.x8nscvPQC8/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.W2meH6lu9B/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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-05 15:18:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.8M=0.001s 2016-09-05 15:18:36 (19.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.128435 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 15:18:36.587104) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 15:18:36.587442) ========= 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.x8nscvPQC8/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.W2meH6lu9B/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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.026858 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 15:18:36.686532) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-05 15:18:36.686924) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3076fad24896ca0b9d026f67c7f27731191fb4f0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 3076fad24896ca0b9d026f67c7f27731191fb4f0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.x8nscvPQC8/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.W2meH6lu9B/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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-05 15:18:36,776 truncating revision to first 12 chars 2016-09-05 15:18:36,776 Setting DEBUG logging. 2016-09-05 15:18:36,776 attempt 1/10 2016-09-05 15:18:36,776 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3076fad24896?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-05 15:18:38,006 unpacking tar archive at: mozilla-central-3076fad24896/testing/mozharness/ program finished with exit code 0 elapsedTime=1.495141 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-05 15:18:38.206119) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-05 15:18:38.206470) ========= script_repo_revision: 3076fad24896ca0b9d026f67c7f27731191fb4f0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-05 15:18:38.206892) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-05 15:18:38.207213) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-05 15:18:38.227572) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-05 15:18:38.228703) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.x8nscvPQC8/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.W2meH6lu9B/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 15:18:38 INFO - MultiFileLogger online at 20160905 15:18:38 in /builds/slave/test 15:18:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:18:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:18:38 INFO - {'append_to_log': False, 15:18:38 INFO - 'base_work_dir': '/builds/slave/test', 15:18:38 INFO - 'blob_upload_branch': 'Firefox', 15:18:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:18:38 INFO - 'branch': 'Firefox', 15:18:38 INFO - 'buildbot_json_path': 'buildprops.json', 15:18:38 INFO - 'config_files': ('talos/mac_config.py',), 15:18:38 INFO - 'default_actions': ('clobber', 15:18:38 INFO - 'read-buildbot-config', 15:18:38 INFO - 'download-and-extract', 15:18:38 INFO - 'populate-webroot', 15:18:38 INFO - 'create-virtualenv', 15:18:38 INFO - 'install', 15:18:38 INFO - 'run-tests'), 15:18:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:18:38 INFO - 'download_minidump_stackwalk': True, 15:18:38 INFO - 'download_symbols': 'ondemand', 15:18:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:18:38 INFO - 'tooltool.py': '/tools/tooltool.py', 15:18:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:18:38 INFO - '/tools/misc-python/virtualenv.py')}, 15:18:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:18:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:18:38 INFO - 'installer_path': 'installer.exe', 15:18:38 INFO - 'log_level': 'info', 15:18:38 INFO - 'log_name': 'talos', 15:18:38 INFO - 'log_to_console': True, 15:18:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:18:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:18:38 INFO - 'opt_config_files': (), 15:18:38 INFO - 'pip_index': False, 15:18:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:18:38 INFO - 'cmd': ('bash', 15:18:38 INFO - '-c', 15:18:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:18:38 INFO - 'enabled': True, 15:18:38 INFO - 'halt_on_failure': False, 15:18:38 INFO - 'name': 'check_screen_resolution'},), 15:18:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:18:38 INFO - 'cmd': ('bash', 15:18:38 INFO - '-c', 15:18:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:18:38 INFO - 'enabled': True, 15:18:38 INFO - 'halt_on_failure': False, 15:18:38 INFO - 'name': 'check_screen_resolution'},), 15:18:38 INFO - 'run_cmd_checks_enabled': True, 15:18:38 INFO - 'sps_profile': False, 15:18:38 INFO - 'sps_profile_interval': 0, 15:18:38 INFO - 'suite': 'dromaeojs', 15:18:38 INFO - 'system_bits': '32', 15:18:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:18:38 INFO - 'title': 't-yosemite-r7-0309', 15:18:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:18:38 INFO - 'use_talos_json': True, 15:18:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:18:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:18:38 INFO - 'work_dir': 'build'} 15:18:38 INFO - [mozharness: 2016-09-05 22:18:38.432367Z] Running clobber step. 15:18:38 INFO - Running pre-action listener: _resource_record_pre_action 15:18:38 INFO - Running main action method: clobber 15:18:38 INFO - rmtree: /builds/slave/test/build 15:18:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:18:40 INFO - Running post-action listener: _resource_record_post_action 15:18:40 INFO - [mozharness: 2016-09-05 22:18:40.280660Z] Finished clobber step (success) 15:18:40 INFO - [mozharness: 2016-09-05 22:18:40.280921Z] Running read-buildbot-config step. 15:18:40 INFO - Running pre-action listener: _resource_record_pre_action 15:18:40 INFO - Running main action method: read_buildbot_config 15:18:40 INFO - Using buildbot properties: 15:18:40 INFO - { 15:18:40 INFO - "project": "", 15:18:40 INFO - "product": "firefox", 15:18:40 INFO - "script_repo_revision": "production", 15:18:40 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 15:18:40 INFO - "repository": "", 15:18:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 15:18:40 INFO - "buildid": "20160905130616", 15:18:40 INFO - "pgo_build": "False", 15:18:40 INFO - "basedir": "/builds/slave/test", 15:18:40 INFO - "buildnumber": 54, 15:18:40 INFO - "slavename": "t-yosemite-r7-0309", 15:18:40 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 15:18:40 INFO - "platform": "yosemite_r7", 15:18:40 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 15:18:40 INFO - "branch": "mozilla-central", 15:18:40 INFO - "repo_path": "mozilla-central", 15:18:40 INFO - "revision": "3076fad24896ca0b9d026f67c7f27731191fb4f0", 15:18:40 INFO - "stage_platform": "macosx64", 15:18:40 INFO - "builduid": "cd7a9bd9d4e24775baeb140ba9a34ac4", 15:18:40 INFO - "slavebuilddir": "test" 15:18:40 INFO - } 15:18:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/FEXo1g-FR5m1XJvhKO1Nkw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 15:18:40 INFO - Running post-action listener: _resource_record_post_action 15:18:40 INFO - [mozharness: 2016-09-05 22:18:40.286762Z] Finished read-buildbot-config step (success) 15:18:40 INFO - [mozharness: 2016-09-05 22:18:40.286963Z] Running download-and-extract step. 15:18:40 INFO - Running pre-action listener: _resource_record_pre_action 15:18:40 INFO - Running main action method: download_and_extract 15:18:40 INFO - mkdir: /builds/slave/test/build/tests 15:18:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:18:40 INFO - trying https://queue.taskcluster.net/v1/task/FEXo1g-FR5m1XJvhKO1Nkw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 15:18:40 INFO - Downloading https://queue.taskcluster.net/v1/task/FEXo1g-FR5m1XJvhKO1Nkw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 15:18:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FEXo1g-FR5m1XJvhKO1Nkw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 15:18:41 INFO - Downloaded 1199 bytes. 15:18:41 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 15:18:41 INFO - Using the following test package requirements: 15:18:41 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:18:41 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:18:41 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 15:18:41 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:18:41 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 15:18:41 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:18:41 INFO - u'jsshell-mac.zip'], 15:18:41 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:18:41 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 15:18:41 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:18:41 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:18:41 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 15:18:41 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:18:41 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 15:18:41 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:18:41 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 15:18:41 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:18:41 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 15:18:41 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:18:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FEXo1g-FR5m1XJvhKO1Nkw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:18:42 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 15:18:48 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:18:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FEXo1g-FR5m1XJvhKO1Nkw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:18:49 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 15:18:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FEXo1g-FR5m1XJvhKO1Nkw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 15:18:56 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 15:18:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:18:57 INFO - trying https://queue.taskcluster.net/v1/task/FEXo1g-FR5m1XJvhKO1Nkw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:18:57 INFO - Downloading https://queue.taskcluster.net/v1/task/FEXo1g-FR5m1XJvhKO1Nkw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 15:18:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FEXo1g-FR5m1XJvhKO1Nkw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 15:19:00 INFO - Downloaded 111518193 bytes. 15:19:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FEXo1g-FR5m1XJvhKO1Nkw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:19:00 INFO - mkdir: /builds/slave/test/properties 15:19:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:19:00 INFO - Writing to file /builds/slave/test/properties/build_url 15:19:00 INFO - Contents: 15:19:00 INFO - build_url:https://queue.taskcluster.net/v1/task/FEXo1g-FR5m1XJvhKO1Nkw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:19:01 INFO - Running post-action listener: _resource_record_post_action 15:19:01 INFO - Running post-action listener: set_extra_try_arguments 15:19:01 INFO - [mozharness: 2016-09-05 22:19:01.039404Z] Finished download-and-extract step (success) 15:19:01 INFO - [mozharness: 2016-09-05 22:19:01.039548Z] Running populate-webroot step. 15:19:01 INFO - Running pre-action listener: _resource_record_pre_action 15:19:01 INFO - Running main action method: populate_webroot 15:19:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:19:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:19:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:19:01 INFO - '--tppagecycles', 15:19:01 INFO - '7'], 15:19:01 INFO - 'tests': ['tsvgm']}}, 15:19:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:19:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:19:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:19:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:19:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:19:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:19:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:01 INFO - 'talos_options': ['--disable-e10s'], 15:19:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:19:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:19:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:01 INFO - 'talos_options': ['--disable-e10s'], 15:19:01 INFO - 'tests': ['damp', 'tps']}, 15:19:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:01 INFO - 'tests': ['damp', 'tps']}, 15:19:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:19:01 INFO - 'tests': ['dromaeo_dom']}, 15:19:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:19:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:19:01 INFO - 'tests': ['basic_compositor_video']}, 15:19:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:19:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:19:01 INFO - 'tests': ['a11yr', 15:19:01 INFO - 'ts_paint', 15:19:01 INFO - 'tpaint', 15:19:01 INFO - 'sessionrestore', 15:19:01 INFO - 'sessionrestore_no_auto_restore', 15:19:01 INFO - 'tabpaint']}, 15:19:01 INFO - 'other-e10s': {'tests': ['a11yr', 15:19:01 INFO - 'ts_paint', 15:19:01 INFO - 'tpaint', 15:19:01 INFO - 'sessionrestore', 15:19:01 INFO - 'sessionrestore_no_auto_restore', 15:19:01 INFO - 'tabpaint']}, 15:19:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:19:01 INFO - 'ts_paint', 15:19:01 INFO - 'tpaint', 15:19:01 INFO - 'sessionrestore', 15:19:01 INFO - 'sessionrestore_no_auto_restore', 15:19:01 INFO - 'tabpaint']}, 15:19:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:19:01 INFO - 'ts_paint', 15:19:01 INFO - 'tpaint', 15:19:01 INFO - 'sessionrestore', 15:19:01 INFO - 'sessionrestore_no_auto_restore', 15:19:01 INFO - 'tabpaint']}, 15:19:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:19:01 INFO - 'tests': ['a11yr', 15:19:01 INFO - 'ts_paint', 15:19:01 INFO - 'tpaint', 15:19:01 INFO - 'sessionrestore', 15:19:01 INFO - 'sessionrestore_no_auto_restore', 15:19:01 INFO - 'tabpaint']}, 15:19:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:19:01 INFO - 'tests': ['a11yr', 15:19:01 INFO - 'ts_paint', 15:19:01 INFO - 'tpaint', 15:19:01 INFO - 'sessionrestore', 15:19:01 INFO - 'sessionrestore_no_auto_restore', 15:19:01 INFO - 'tabpaint']}, 15:19:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:19:01 INFO - 'tests': ['tsvgx', 15:19:01 INFO - 'tsvgr_opacity', 15:19:01 INFO - 'tart', 15:19:01 INFO - 'tscrollx', 15:19:01 INFO - 'cart']}, 15:19:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:19:01 INFO - 'tsvgr_opacity', 15:19:01 INFO - 'tart', 15:19:01 INFO - 'tscrollx', 15:19:01 INFO - 'cart']}, 15:19:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:01 INFO - 'talos_options': ['--disable-e10s'], 15:19:01 INFO - 'tests': ['tp5o']}, 15:19:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:01 INFO - 'tests': ['tp5o']}, 15:19:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:01 INFO - 'talos_options': ['--disable-e10s', 15:19:01 INFO - '--xperf_path', 15:19:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:19:01 INFO - 'tests': ['tp5n']}, 15:19:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:01 INFO - 'talos_options': ['--xperf_path', 15:19:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:19:01 INFO - 'tests': ['tp5n']}}, 15:19:01 INFO - 'talos.zip': {'path': '', 15:19:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:19:01 INFO - Running post-action listener: _resource_record_post_action 15:19:01 INFO - [mozharness: 2016-09-05 22:19:01.058641Z] Finished populate-webroot step (success) 15:19:01 INFO - [mozharness: 2016-09-05 22:19:01.058762Z] Running create-virtualenv step. 15:19:01 INFO - Running pre-action listener: _resource_record_pre_action 15:19:01 INFO - Running main action method: create_virtualenv 15:19:01 INFO - Creating virtualenv /builds/slave/test/build/venv 15:19:01 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:19:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:19:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:19:01 INFO - Using real prefix '/tools/python27' 15:19:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:19:01 INFO - Installing distribute.............................................................................................................................................................................................done. 15:19:04 INFO - Installing pip.................done. 15:19:04 INFO - Return code: 0 15:19:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:19:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:04 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 0x10b91fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9b4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a99d5ad20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b883d30>, '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 0x10b5e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b87bb20>, '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.x8nscvPQC8/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.W2meH6lu9B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:19:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:19:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:19:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8nscvPQC8/Render', 15:19:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:04 INFO - 'HOME': '/Users/cltbld', 15:19:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:04 INFO - 'LOGNAME': 'cltbld', 15:19:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:04 INFO - 'MOZ_NO_REMOTE': '1', 15:19:04 INFO - 'NO_EM_RESTART': '1', 15:19:04 INFO - 'PAGER': '/bin/cat', 15:19:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:04 INFO - 'PWD': '/builds/slave/test', 15:19:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:04 INFO - 'SHELL': '/bin/bash', 15:19:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W2meH6lu9B/Listeners', 15:19:04 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 15:19:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:04 INFO - 'USER': 'cltbld', 15:19:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:04 INFO - 'XPC_FLAGS': '0x0', 15:19:04 INFO - 'XPC_SERVICE_NAME': '0', 15:19:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:05 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:19:05 INFO - Cleaning up... 15:19:05 INFO - Return code: 0 15:19:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:05 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 0x10b91fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9b4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a99d5ad20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b883d30>, '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 0x10b5e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b87bb20>, '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.x8nscvPQC8/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.W2meH6lu9B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:19:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:19:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:19:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8nscvPQC8/Render', 15:19:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:05 INFO - 'HOME': '/Users/cltbld', 15:19:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:05 INFO - 'LOGNAME': 'cltbld', 15:19:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:05 INFO - 'MOZ_NO_REMOTE': '1', 15:19:05 INFO - 'NO_EM_RESTART': '1', 15:19:05 INFO - 'PAGER': '/bin/cat', 15:19:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:05 INFO - 'PWD': '/builds/slave/test', 15:19:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:05 INFO - 'SHELL': '/bin/bash', 15:19:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W2meH6lu9B/Listeners', 15:19:05 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 15:19:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:05 INFO - 'USER': 'cltbld', 15:19:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:05 INFO - 'XPC_FLAGS': '0x0', 15:19:05 INFO - 'XPC_SERVICE_NAME': '0', 15:19:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:05 INFO - Downloading/unpacking psutil>=3.1.1 15:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:19:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:19:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:19:08 INFO - Installing collected packages: psutil 15:19:08 INFO - Running setup.py install for psutil 15:19:09 INFO - building 'psutil._psutil_osx' extension 15:19:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 15:19:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 15:19:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 15:19:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 15:19:09 INFO - building 'psutil._psutil_posix' extension 15:19:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 15:19:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:19:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:19:09 INFO - ^ 15:19:09 INFO - 1 warning generated. 15:19:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 15:19:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:19:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:19:10 INFO - Successfully installed psutil 15:19:10 INFO - Cleaning up... 15:19:10 INFO - Return code: 0 15:19:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:19:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:10 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 0x10b91fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9b4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a99d5ad20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b883d30>, '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 0x10b5e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b87bb20>, '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.x8nscvPQC8/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.W2meH6lu9B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:19:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:19:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:19:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8nscvPQC8/Render', 15:19:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:10 INFO - 'HOME': '/Users/cltbld', 15:19:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:10 INFO - 'LOGNAME': 'cltbld', 15:19:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:10 INFO - 'MOZ_NO_REMOTE': '1', 15:19:10 INFO - 'NO_EM_RESTART': '1', 15:19:10 INFO - 'PAGER': '/bin/cat', 15:19:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:10 INFO - 'PWD': '/builds/slave/test', 15:19:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:10 INFO - 'SHELL': '/bin/bash', 15:19:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W2meH6lu9B/Listeners', 15:19:10 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 15:19:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:10 INFO - 'USER': 'cltbld', 15:19:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:10 INFO - 'XPC_FLAGS': '0x0', 15:19:10 INFO - 'XPC_SERVICE_NAME': '0', 15:19:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:19:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:19:12 INFO - Installing collected packages: mozsystemmonitor 15:19:12 INFO - Running setup.py install for mozsystemmonitor 15:19:13 INFO - Successfully installed mozsystemmonitor 15:19:13 INFO - Cleaning up... 15:19:13 INFO - Return code: 0 15:19:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:19:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:13 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 0x10b91fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9b4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a99d5ad20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b883d30>, '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 0x10b5e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b87bb20>, '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.x8nscvPQC8/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.W2meH6lu9B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:19:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:19:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:19:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8nscvPQC8/Render', 15:19:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:13 INFO - 'HOME': '/Users/cltbld', 15:19:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:13 INFO - 'LOGNAME': 'cltbld', 15:19:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:13 INFO - 'MOZ_NO_REMOTE': '1', 15:19:13 INFO - 'NO_EM_RESTART': '1', 15:19:13 INFO - 'PAGER': '/bin/cat', 15:19:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:13 INFO - 'PWD': '/builds/slave/test', 15:19:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:13 INFO - 'SHELL': '/bin/bash', 15:19:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W2meH6lu9B/Listeners', 15:19:13 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 15:19:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:13 INFO - 'USER': 'cltbld', 15:19:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:13 INFO - 'XPC_FLAGS': '0x0', 15:19:13 INFO - 'XPC_SERVICE_NAME': '0', 15:19:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:13 INFO - Downloading/unpacking jsonschema==2.5.1 15:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:19:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:16 INFO - Downloading functools32-3.2.3-2.tar.gz 15:19:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:19:16 INFO - warning: no files found matching '*.txt' 15:19:16 INFO - no previously-included directories found matching 'build' 15:19:16 INFO - no previously-included directories found matching 'dist' 15:19:16 INFO - no previously-included directories found matching '.git*' 15:19:16 INFO - Installing collected packages: jsonschema, functools32 15:19:16 INFO - Running setup.py install for functools32 15:19:16 INFO - warning: no files found matching '*.txt' 15:19:16 INFO - no previously-included directories found matching 'build' 15:19:16 INFO - no previously-included directories found matching 'dist' 15:19:16 INFO - no previously-included directories found matching '.git*' 15:19:16 INFO - Successfully installed jsonschema functools32 15:19:16 INFO - Cleaning up... 15:19:16 INFO - Return code: 0 15:19:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:16 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 0x10b91fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9b4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a99d5ad20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b883d30>, '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 0x10b5e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b87bb20>, '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.x8nscvPQC8/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.W2meH6lu9B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:19:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:19:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:19:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8nscvPQC8/Render', 15:19:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:16 INFO - 'HOME': '/Users/cltbld', 15:19:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:16 INFO - 'LOGNAME': 'cltbld', 15:19:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:16 INFO - 'MOZ_NO_REMOTE': '1', 15:19:16 INFO - 'NO_EM_RESTART': '1', 15:19:16 INFO - 'PAGER': '/bin/cat', 15:19:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:16 INFO - 'PWD': '/builds/slave/test', 15:19:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:16 INFO - 'SHELL': '/bin/bash', 15:19:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W2meH6lu9B/Listeners', 15:19:16 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 15:19:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:16 INFO - 'USER': 'cltbld', 15:19:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:16 INFO - 'XPC_FLAGS': '0x0', 15:19:16 INFO - 'XPC_SERVICE_NAME': '0', 15:19:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:19:16 INFO - Cleaning up... 15:19:16 INFO - Return code: 0 15:19:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:16 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 0x10b91fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9b4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a99d5ad20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b883d30>, '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 0x10b5e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b87bb20>, '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.x8nscvPQC8/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.W2meH6lu9B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:19:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:19:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:19:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8nscvPQC8/Render', 15:19:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:16 INFO - 'HOME': '/Users/cltbld', 15:19:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:16 INFO - 'LOGNAME': 'cltbld', 15:19:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:16 INFO - 'MOZ_NO_REMOTE': '1', 15:19:16 INFO - 'NO_EM_RESTART': '1', 15:19:16 INFO - 'PAGER': '/bin/cat', 15:19:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:16 INFO - 'PWD': '/builds/slave/test', 15:19:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:16 INFO - 'SHELL': '/bin/bash', 15:19:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W2meH6lu9B/Listeners', 15:19:16 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 15:19:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:16 INFO - 'USER': 'cltbld', 15:19:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:16 INFO - 'XPC_FLAGS': '0x0', 15:19:16 INFO - 'XPC_SERVICE_NAME': '0', 15:19:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:17 INFO - Downloading/unpacking blobuploader==1.2.4 15:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:19 INFO - Downloading blobuploader-1.2.4.tar.gz 15:19:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:19:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:19:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:19:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:20 INFO - Downloading docopt-0.6.1.tar.gz 15:19:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:19:20 INFO - Installing collected packages: blobuploader, requests, docopt 15:19:20 INFO - Running setup.py install for blobuploader 15:19:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:19:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:19:21 INFO - Running setup.py install for requests 15:19:21 INFO - Running setup.py install for docopt 15:19:21 INFO - Successfully installed blobuploader requests docopt 15:19:21 INFO - Cleaning up... 15:19:21 INFO - Return code: 0 15:19:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:19:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:21 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 0x10b91fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9b4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a99d5ad20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b883d30>, '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 0x10b5e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b87bb20>, '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.x8nscvPQC8/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.W2meH6lu9B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:19:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:19:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:19:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8nscvPQC8/Render', 15:19:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:21 INFO - 'HOME': '/Users/cltbld', 15:19:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:21 INFO - 'LOGNAME': 'cltbld', 15:19:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:21 INFO - 'MOZ_NO_REMOTE': '1', 15:19:21 INFO - 'NO_EM_RESTART': '1', 15:19:21 INFO - 'PAGER': '/bin/cat', 15:19:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:21 INFO - 'PWD': '/builds/slave/test', 15:19:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:21 INFO - 'SHELL': '/bin/bash', 15:19:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W2meH6lu9B/Listeners', 15:19:21 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 15:19:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:21 INFO - 'USER': 'cltbld', 15:19:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:21 INFO - 'XPC_FLAGS': '0x0', 15:19:21 INFO - 'XPC_SERVICE_NAME': '0', 15:19:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:19:22 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-KOoi2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:19:22 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-WStasm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:19:22 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-avtukn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:19:22 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-7E1xfe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:19:22 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-6I7ewF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:19:22 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-2eUf4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:19:22 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-3ANQ86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:19:22 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-TrxNF3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:19:22 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-AfAOxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:19:22 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-5b6MlR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:19:23 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-V1uzby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:19:23 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-YQZngm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:19:23 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-EjuBjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:19:23 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-9SdGWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:19:23 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-5KDn30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:19:23 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-PpOSo5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:19:23 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-zpgYtU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:19:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:19:23 INFO - Running setup.py install for manifestparser 15:19:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:19:23 INFO - Running setup.py install for mozcrash 15:19:24 INFO - Running setup.py install for mozdebug 15:19:24 INFO - Running setup.py install for mozdevice 15:19:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:19:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:19:24 INFO - Running setup.py install for mozfile 15:19:24 INFO - Running setup.py install for mozhttpd 15:19:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:19:24 INFO - Running setup.py install for mozinfo 15:19:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:19:24 INFO - Running setup.py install for mozInstall 15:19:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:19:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:19:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:19:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:19:24 INFO - Running setup.py install for mozleak 15:19:25 INFO - Running setup.py install for mozlog 15:19:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:19:25 INFO - Running setup.py install for moznetwork 15:19:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:19:25 INFO - Running setup.py install for mozprocess 15:19:25 INFO - Running setup.py install for mozprofile 15:19:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:19:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:19:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:19:25 INFO - Running setup.py install for mozrunner 15:19:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:19:25 INFO - Running setup.py install for mozscreenshot 15:19:26 INFO - Running setup.py install for moztest 15:19:26 INFO - Running setup.py install for mozversion 15:19:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:19:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:19:26 INFO - Cleaning up... 15:19:26 INFO - Return code: 0 15:19:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:19: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')]} 15:19:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19: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')]} 15:19:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:26 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 0x10b91fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9b4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a99d5ad20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b883d30>, '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 0x10b5e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b87bb20>, '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.x8nscvPQC8/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.W2meH6lu9B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:19:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:19:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:19:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8nscvPQC8/Render', 15:19:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:26 INFO - 'HOME': '/Users/cltbld', 15:19:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:26 INFO - 'LOGNAME': 'cltbld', 15:19:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:26 INFO - 'MOZ_NO_REMOTE': '1', 15:19:26 INFO - 'NO_EM_RESTART': '1', 15:19:26 INFO - 'PAGER': '/bin/cat', 15:19:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:26 INFO - 'PWD': '/builds/slave/test', 15:19:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:26 INFO - 'SHELL': '/bin/bash', 15:19:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W2meH6lu9B/Listeners', 15:19:26 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 15:19:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:26 INFO - 'USER': 'cltbld', 15:19:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:26 INFO - 'XPC_FLAGS': '0x0', 15:19:26 INFO - 'XPC_SERVICE_NAME': '0', 15:19:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:19:26 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-zzvu5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:19:26 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-5EjfWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:19:26 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-qTZMfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:19:27 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-SNW16e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:19:27 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-mNLe0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:19:27 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-cK6o57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:19:27 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-_VRlyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:19:27 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-3D6S8E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:19:27 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-WdTCfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:19:27 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-Fx_wuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:19:27 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-zBSYj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:19:27 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-ve7DpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:19:27 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-ppIO_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:19:28 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-1RITbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:19:28 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-QnXCUj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:19:28 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-kd9gJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:19:28 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-4RHi9_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:19:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:30 INFO - Downloading blessings-1.6.tar.gz 15:19:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:19:31 INFO - Installing collected packages: blessings 15:19:31 INFO - Running setup.py install for blessings 15:19:31 INFO - Successfully installed blessings 15:19:31 INFO - Cleaning up... 15:19:31 INFO - Return code: 0 15:19:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:19:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:19:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:19:31 INFO - Reading from file tmpfile_stdout 15:19:31 INFO - Current package versions: 15:19:31 INFO - blessings == 1.6 15:19:31 INFO - blobuploader == 1.2.4 15:19:31 INFO - docopt == 0.6.1 15:19:31 INFO - functools32 == 3.2.3-2 15:19:31 INFO - jsonschema == 2.5.1 15:19:31 INFO - manifestparser == 1.1 15:19:31 INFO - mozInstall == 1.12 15:19:31 INFO - mozcrash == 0.17 15:19:31 INFO - mozdebug == 0.1 15:19:31 INFO - mozdevice == 0.48 15:19:31 INFO - mozfile == 1.2 15:19:31 INFO - mozhttpd == 0.7 15:19:31 INFO - mozinfo == 0.9 15:19:31 INFO - mozleak == 0.1 15:19:31 INFO - mozlog == 3.3 15:19:31 INFO - moznetwork == 0.27 15:19:31 INFO - mozprocess == 0.23 15:19:31 INFO - mozprofile == 0.28 15:19:31 INFO - mozrunner == 6.12 15:19:31 INFO - mozscreenshot == 0.1 15:19:31 INFO - mozsystemmonitor == 0.3 15:19:31 INFO - moztest == 0.7 15:19:31 INFO - mozversion == 1.4 15:19:31 INFO - psutil == 3.1.1 15:19:31 INFO - requests == 1.2.3 15:19:31 INFO - wsgiref == 0.1.2 15:19:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:19:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:31 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 0x10b91fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9b4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a99d5ad20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b883d30>, '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 0x10b5e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b87bb20>, '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.x8nscvPQC8/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.W2meH6lu9B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:19:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:19:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:19:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8nscvPQC8/Render', 15:19:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:31 INFO - 'HOME': '/Users/cltbld', 15:19:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:31 INFO - 'LOGNAME': 'cltbld', 15:19:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:31 INFO - 'MOZ_NO_REMOTE': '1', 15:19:31 INFO - 'NO_EM_RESTART': '1', 15:19:31 INFO - 'PAGER': '/bin/cat', 15:19:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:31 INFO - 'PWD': '/builds/slave/test', 15:19:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:31 INFO - 'SHELL': '/bin/bash', 15:19:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W2meH6lu9B/Listeners', 15:19:31 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 15:19:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:31 INFO - 'USER': 'cltbld', 15:19:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:31 INFO - 'XPC_FLAGS': '0x0', 15:19:31 INFO - 'XPC_SERVICE_NAME': '0', 15:19:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:19:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:19:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:19:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:19:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:19:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:19:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:19:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:19:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:19:31 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:19:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:19:31 INFO - Cleaning up... 15:19:31 INFO - Return code: 0 15:19:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:19:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b91fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9b4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a99d5ad20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b883d30>, '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 0x10b5e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b87bb20>, '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.x8nscvPQC8/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.W2meH6lu9B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:19:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:19:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:19:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8nscvPQC8/Render', 15:19:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:31 INFO - 'HOME': '/Users/cltbld', 15:19:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:31 INFO - 'LOGNAME': 'cltbld', 15:19:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:31 INFO - 'MOZ_NO_REMOTE': '1', 15:19:31 INFO - 'NO_EM_RESTART': '1', 15:19:31 INFO - 'PAGER': '/bin/cat', 15:19:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:31 INFO - 'PWD': '/builds/slave/test', 15:19:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:31 INFO - 'SHELL': '/bin/bash', 15:19:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W2meH6lu9B/Listeners', 15:19:31 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 15:19:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:31 INFO - 'USER': 'cltbld', 15:19:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:31 INFO - 'XPC_FLAGS': '0x0', 15:19:31 INFO - 'XPC_SERVICE_NAME': '0', 15:19:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:19:32 INFO - Cleaning up... 15:19:32 INFO - Return code: 0 15:19:32 INFO - Running post-action listener: _resource_record_post_action 15:19:32 INFO - Running post-action listener: _start_resource_monitoring 15:19:32 INFO - Starting resource monitoring. 15:19:32 INFO - [mozharness: 2016-09-05 22:19:32.115482Z] Finished create-virtualenv step (success) 15:19:32 INFO - [mozharness: 2016-09-05 22:19:32.116488Z] Running install step. 15:19:32 INFO - Running pre-action listener: _resource_record_pre_action 15:19:32 INFO - Running main action method: install 15:19:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:19:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:19:32 INFO - Reading from file tmpfile_stdout 15:19:32 INFO - Detecting whether we're running mozinstall >=1.0... 15:19:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:19:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:19:32 INFO - Reading from file tmpfile_stdout 15:19:32 INFO - Output received: 15:19:32 INFO - Usage: mozinstall [options] installer 15:19:32 INFO - Options: 15:19:32 INFO - -h, --help show this help message and exit 15:19:32 INFO - -d DEST, --destination=DEST 15:19:32 INFO - Directory to install application into. [default: 15:19:32 INFO - "/builds/slave/test"] 15:19:32 INFO - --app=APP Application being installed. [default: firefox] 15:19:32 INFO - mkdir: /builds/slave/test/build/application 15:19:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:19:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:20:06 INFO - Reading from file tmpfile_stdout 15:20:06 INFO - Output received: 15:20:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:20:06 INFO - Running post-action listener: _resource_record_post_action 15:20:06 INFO - [mozharness: 2016-09-05 22:20:06.527204Z] Finished install step (success) 15:20:06 INFO - [mozharness: 2016-09-05 22:20:06.527355Z] Running run-tests step. 15:20:06 INFO - Running pre-action listener: _resource_record_pre_action 15:20:06 INFO - Running main action method: run_tests 15:20:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:20:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:20:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:20:06 INFO - 2016-09-05 15:20:06.541 screenresolution[1650:8588] starting screenresolution argv=screenresolution get 15:20:06 INFO - 2016-09-05 15:20:06.544 screenresolution[1650:8588] Display 0: 1600x1200x32@60 15:20:06 INFO - 2016-09-05 15:20:06.550 screenresolution[1651:8590] starting screenresolution argv=screenresolution list 15:20:06 INFO - Available Modes on Display 0 15:20:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:20:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:20:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:20:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:20:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:20:06 INFO - Intel Iris: 15:20:06 INFO - Chipset Model: Intel Iris 15:20:06 INFO - Type: GPU 15:20:06 INFO - Bus: Built-In 15:20:06 INFO - VRAM (Dynamic, Max): 1536 MB 15:20:06 INFO - Vendor: Intel (0x8086) 15:20:06 INFO - Device ID: 0x0a2e 15:20:06 INFO - Revision ID: 0x0009 15:20:06 INFO - Displays: 15:20:06 INFO - Display: 15:20:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:20:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:20:06 INFO - Display Serial Number: 1600x1200 60 15:20:06 INFO - Main Display: Yes 15:20:06 INFO - Mirror: Off 15:20:06 INFO - Online: Yes 15:20:06 INFO - Rotation: Supported 15:20:06 INFO - Return code: 0 15:20:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:20:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:20:06 INFO - Python 2.7.3 15:20:06 INFO - Return code: 0 15:20:06 INFO - grabbing minidump binary from tooltool 15:20:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:06 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b883d30>, '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 0x10b5e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b87bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:20:06 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:20:06 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 15:20:06 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:20:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:20:06 INFO - Return code: 0 15:20:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:20:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:20:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:20:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:20:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:20:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FEXo1g-FR5m1XJvhKO1Nkw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0309', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:20:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FEXo1g-FR5m1XJvhKO1Nkw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0309 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:20:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x8nscvPQC8/Render', 15:20:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:06 INFO - 'HOME': '/Users/cltbld', 15:20:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:06 INFO - 'LOGNAME': 'cltbld', 15:20:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:20:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:20:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:06 INFO - 'MOZ_NO_REMOTE': '1', 15:20:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:20:06 INFO - 'NO_EM_RESTART': '1', 15:20:06 INFO - 'PAGER': '/bin/cat', 15:20:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:06 INFO - 'PWD': '/builds/slave/test', 15:20:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:20:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:06 INFO - 'SHELL': '/bin/bash', 15:20:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W2meH6lu9B/Listeners', 15:20:06 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 15:20:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:06 INFO - 'USER': 'cltbld', 15:20:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:06 INFO - 'XPC_FLAGS': '0x0', 15:20:06 INFO - 'XPC_SERVICE_NAME': '0', 15:20:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FEXo1g-FR5m1XJvhKO1Nkw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0309', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:20:07 INFO - mozversion application_buildid: 20160905130616 15:20:07 INFO - mozversion application_changeset: 3076fad24896ca0b9d026f67c7f27731191fb4f0 15:20:07 INFO - mozversion application_display_name: Nightly 15:20:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:20:07 INFO - mozversion application_name: Firefox 15:20:07 INFO - mozversion application_remotingname: firefox 15:20:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:20:07 INFO - mozversion application_vendor: Mozilla 15:20:07 INFO - mozversion application_version: 51.0a1 15:20:07 INFO - mozversion platform_buildid: 20160905130616 15:20:07 INFO - mozversion platform_changeset: 3076fad24896ca0b9d026f67c7f27731191fb4f0 15:20:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:20:07 INFO - mozversion platform_version: 51.0a1 15:20:07 INFO - using testdate: 1473114007 15:20:07 INFO - actual date: 1473114007 15:20:07 INFO - starting webserver on 'localhost:49277' 15:20:07 INFO - SUITE-START | Running 2 tests 15:20:07 INFO - TEST-START | dromaeo_css 15:20:07 INFO - operating with platform_type : mac_ 15:20:07 INFO - Initialising browser for dromaeo_css test... 15:20:07 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmp5AIGAj/profile http://localhost:49277/getInfo.html) 15:20:09 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 15:20:09 INFO - PROCESS | 1661 | colorDepth:24 15:20:09 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 15:20:09 INFO - PROCESS | 1661 | __metrics 15:20:09 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:20:09 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:20:10 INFO - TEST-INFO | 1661: exit 0 15:20:10 INFO - Browser initialized. 15:20:10 INFO - Running cycle 1/1 for dromaeo_css test... 15:20:10 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmp5AIGAj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:20:22 INFO - PROCESS | 1664 | RSS: Main: 232378368 15:20:22 INFO - PROCESS | 1664 | 15:21:13 INFO - PROCESS | 1664 | 1473114073357 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:23:25 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 15:23:26 INFO - PROCESS | 1664 | RSS: Main: 388636672 15:23:26 INFO - PROCESS | 1664 | 15:26:29 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 15:26:30 INFO - PROCESS | 1664 | RSS: Main: 370962432 15:26:30 INFO - PROCESS | 1664 | 15:29:32 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 15:29:33 INFO - PROCESS | 1664 | RSS: Main: 329523200 15:29:33 INFO - PROCESS | 1664 | 15:32:35 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 15:32:36 INFO - PROCESS | 1664 | RSS: Main: 337661952 15:32:36 INFO - PROCESS | 1664 | 15:35:39 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 15:35:40 INFO - PROCESS | 1664 | RSS: Main: 323772416 15:35:40 INFO - PROCESS | 1664 | 15:38:43 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 15:38:43 INFO - PROCESS | 1664 | RSS: Main: 394276864 15:38:43 INFO - PROCESS | 1664 | 15:38:43 INFO - PROCESS | 1664 | __start_tp_report 15:38:43 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 15:38:43 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 15:38:43 INFO - PROCESS | 1664 | |i|pagename|runs| 15:38:43 INFO - PROCESS | 1664 | |0;dojo.html;3365.4278772608654;3370.898873033809;3376.5441665375165;3404.1149301181686;3445.9655403445972;5459.12653975364;5538.6122971392015;5671.71641417929;5688.886222275552;5729.3411257705375;5552.11166213406;5588.552915766739;5590.077637189859;5590.273264475622;5591.133374326976;5113;5191.5327620514145;5203.8178663746585;5227.08526007949;5296.92054619181;4962.082014827269;5268.473152684739;5278.868028299304;5333;5388.030154572176;5180.533751962323;5297.576193904479;5311.176767601028;5341.599380046493;5381.4349493303225;4486.730796152241;5283.973580132075;5295.576353891718;5308.177232528964;5326.6537675051195;5058.696478211319;5083.059633967728;5148.433672296044;5181.300524429192;5219.6085293602755;4652;5622.184783206453;5627.915581266284;5633.352164501094;5653.349864765563;6113.358097399753;6113.476306051806;6166.938330616681;6178.7219575119225;6253.1245625612455;6233.501319894398;6269.937300626981;6280.560360774748;6298.968505157491;6315.526335524854;5609.467100625434;5681.17622944679;5783.248177736868;5793.246877905887;5811.447912448353;6244.812655620339;6314.62112273265;6321.494280457552;6325.683715814191;6337.4613157882095;6121.081837724288;6137.96931015342;6149.877002459926;6150.969245153746;6152.415520525543;6018.307894592091;6030.90953635687;6266.811995640138;6273.153124328157;6287.993920972623;6051.031834906394;6075.301340345917;6091.817245482643;6107.5910050938;6133.52252855418;5140.691558506427;6163.660998645028;6168.938310616926;6174.44430001302;6175.660338681416;4732.053589282157;4866.732329721899;4877.048975449753;4881.609471242292;4896.4124305082905;4260.914781704349;4321.9135617287475;4352.39066530686;4363.80362883671;4371.060222052274;5642.774289028393;5703.429657034263;5717.2281741965135;5749.195112684228;5780.739866706008;6224.470919971763;6276.686165691788;6291.6854157291955;6295.338989406138;6301.842453938688;5666.17840413146;5692.971535142381;5697.800576979786;5708.514776244066;5730.484256416942;5291.100512912814;5310.468953104659;5381.354237491526;5421.512063914267;5438.075527160392;6262.467690246289;6321.273053598711;6344.555881088279;6349.079383489368;6366.808995730135;6234.9688251558455;6287.559870808999;6303.873922521432;6314.715837787218;6321.494280457644;6252.531060170596;6258.780942666985;6268.874622507616;6288.402601752826;6324;6190.938090619036;6200.875982480416;6228.968855155696;6229.283632382153;6239.06414037898;6310.747570097328;6315.147455093504;6315.55791094619;6315.684215789101;6320.778772742932;5045.344105266438;5825;6015.639061656315;6073.0283154695035;6100.206973093469;6024.909626355696;6059.787907423219;6065.757369705162;6089.086637004485;6106.938930610637;5890.617109887844;5919.467247947706;5944.821655350346;5956.791512297049;5957.076653118774;5897.970510147422;6041.909371359521;6095.116208149705;6108.328083910855;6125.908111378422;5974.9402505974385;5993.130996005468;5994.760209591742;5994.790182343597;5997.850053748692 15:38:43 INFO - PROCESS | 1664 | |1;ext.html;508.7840062251218;520.4587229281548;522.571491377071;527.722945453637;563.1552670993507;31389;40568;41784.164316713686;42091.57908420915;43960;42947.14105717884;43264.91837704059;43333.13333733331;43347.78326108373;43348;9689.224862011022;9867.703968880909;9877.160441362457;9883.159931405839;9888.307818452711;9662.661806836762;9789.95105024474;9791.902080979205;9793.510324483783;9804.754881127958;9776.071273229032;9800.460974646392;9815.312928095;9817.116459518642;9848.162906152986;9686.176674982635;9740.805183896318;9751.219902407862;9770.169535589483;9779.217662587047;9680.273979451498;9742.269329800223;9745.31782775206;9755.951220243853;9765.218782497454;40585.79707101475;40825.387619185734;40844.59155408438;40950;41023;3321.5848018997626;3423.1613254752497;3552.812893175966;3565.625609311037;3937.5865534118793;30826;31139.688603113904;31405.685943140506;31691.84154079238;32034.519482207786;3239.7084262416497;3305.8677652893857;3363.9831800841084;3370.9494357584654;3395.1002984209204;449203;450498;453664.65838353644;454052;455763;59676.104858427156;60020.69989650111;60069;60104.699476503214;60330.09504857431;63561.68219158875;63737;63775.362246377874;63861.680691596244;63898.361016389244;16120.193990300673;16374.754378684329;16381.935174213639;16386.26261818221;16398.344066237216;12620.432080556397;12703.174293670882;12719.93640031794;12740;12749.17130386522;16864.505644652436;18678.439646810617;18693.54169478651;18765.90617046906;18918.31092936864;7585.1656317805;7678.966808110765;7771.805704857424;7773.106092799288;7810.218978102258;730.7442395161651;767.8195624028349;885.7874110213502;1007.3301254665712;1080.1304949515559;29301.120966371043;29915.850420748193;29916.252093697833;30806;31033.844830776154;923.3259720404143;987.8123156600223;1026.656070216486;1088.8965548272902;1093.9835902461468;15253.694926101416;15611.843881561266;15675.764863527056;15739.667439443583;16125.11311877858;568332;603571;611194;611210;611712;55904.440955589926;56421.71789141028;56610.30169094934;56641;56711.14933276004;1241.2862604002735;1282.8139919711775;1333.6599167212298;1381.461230120254;1409.2319685771074;28968;30843.2289192763;31321.21696957503;31488.055358339287;31801.6819831808;48323.516764831904;48802.02395952132;48822.511774881794;48859.26711099407;48877;472563.2743672519;473265;474199;476446;477041;25518.23445296644;26323.73676263213;26403.339916502242;26444.711057788136;26459.94160233569;46199.53800461952;47002;47243.52756472391;47637;48092.759536202095;37062.07344816401;37954.43068353922;39087;39449.01377465586;40304;38661.61338386693;39246.803765980985;39884.401733973435;40219.19561608693;40366.79816600898;37711.434328485644;38184.04539886525;38422.615773841906;38478.80760596179;38607.42088868613;30001;32033.359332813678;34273.31453370969;34501.482477763355;34817.12957176091;24629;24935.87532062328;25163.119290824732;25313.62029569521;25477.61783573285 15:38:43 INFO - PROCESS | 1664 | |2;jquery.html;317.2655302973616;348.54862952476543;360.8183200019934;364.41329459570073;403.4210907347956;15279.923600381997;17770;20568.897155514238;20704.068316925735;20706;21489.57020859582;22102.336929892088;22207.555848883054;22461.21385751499;23049;6736.427403670694;6749.19009718832;6922;7005.29947005298;7103.25415831338;4682.742449165295;5981.401859814027;7051.85896282074;7072.186698529684;7074.18646855613;7683.57740324282;8025.919740802605;8074.152214017532;8084.070331911847;8158.836823263531;7841.568713720743;8061.153578874192;8074.515529068275;8093.271605555469;8119.9188008120345;7638.923610763877;7688.269614386624;7732.45872788905;7807.141214466405;7813.609319534001;28214.71785282162;28936.42127157445;29088.41823163525;29148.562771558445;29540.70459295401;2198.5712786006734;2367.1596583213054;2557.9872100639564;2605.0882191233104;2831.9008834690685;14146.29268536569;16162.676746465122;16934.560562352213;17301.567460813454;17407.47777566675;1950.2004178286977;2015.486051056971;2052.3842847145797;2075.785665385751;2104.5896050270203;92257.538712306;98804.50597747037;111077;112509;148193;15457.381704731684;19035.57233207515;19583.60832783365;20205.797942020687;21667.78332216689;35197.296054078775;35325.64674353275;35343.82328088343;35413.29173416517;35452.11369715778;10525.21060920434;10740.033396994308;10952.34285942846;10980.835287470694;11134.3876086816;7330.486865919441;7514.248575142551;9000.8649870252;9029.142231487998;9208.217301529445;9408.247340212767;9532.52337383128;9654.855177172349;9672.70981870545;9702.417854928726;3114.76639252057;3122.9219269518444;3605.9278814423565;3734.738568300193;3834.2331533692814;840.7687885831446;847.3348421489225;922.1331947969022;951.3863558005048;1005.8994100589882;20710;21358.145674172858;21437.249696260555;21443.57112857734;21452.034658440407;864.7016779211162;899.7030979776658;904.380499357942;925.250547056886;970.8106919150698;10715.892841071645;11204.383758893338;11252.324860508232;11270;11281.323120612626;540760;542327;543483;544080.2795986074;568547;127437;131194;134170.65829341777;137035;137211;968.5593055160026;1007.1288335589662;1040.7918416316616;1041.317936751429;1041.7499800047933;18518.629627407645;18720.625587488445;18943.526411839815;19046.80953190506;19103;54143.14810868583;57338.13992790028;61693.90520090247;61861.69069154625;61876;82247;82568;87288;88305;88844.5557772207;7096.112985876765;7341.412686985135;7447.329740323397;8033;10989.337814326429;8993.32550058735;13214.933925330311;13399.732005360032;13804.37880295369;13982.58052258434;6438.645874476949;7631.275028872248;7852.293293603603;8366.832663346577;8497.235248827636;7840.607969601383;7853.685852565834;8237.917620823955;8319.209675080858;8355.289800366794;7999.680012799423;8261.091279959079;8336.541490217855;8513.872291915503;8582.270507006724;11976.880231197576;12398.5660501882;12629.557965471164;12661.93669031649;12760.234385936874;11609.187356884937;11711.648650540497;11952.892248015405;12012.879871201525;12032.27806331623 15:38:43 INFO - PROCESS | 1664 | |3;mootools.html;1351.310831475947;1513.6745599696062;1545.5440645009724;1737.7480265361523;1906.971395429068;7873.606319684014;8487.660493580257;8808.03111657717;8824.161704638058;8827.249683776887;8555.31557475402;8721.171488708578;8721.825563488726;8847.247983921357;8855.291576673866;2367.502824406872;2411.131992482705;2419.82638420366;2420.6248031555137;2423.042898055267;2209.414505156135;2229.487217939875;2377.560151371993;2402.939926501834;2420.5037967216676;2371.81025517959;2388.773066558683;2403.2069417092316;2414.4326083370393;2421.9394515137087;2366.4675448024227;2388.3073908566494;2393.066703985447;2394.6886904702365;2403.158894386968;2362.078789272185;2375.560521303564;2384.3443053160345;2391.4380120671626;2397.2448678666165;8629.65481380744;8778;8956;8958.148975847349;8960.8655870162;5262.763175657066;5482.616216864821;5488.725563721798;5493.450654934514;5496.4503549645115;3547.840347184357;3569.7679650822874;3616.5840928293055;3625.492431059654;3636.581793093775;1091.0889407344876;1098.1269890437122;1104.2601457023814;1120.686207861792;1130.327455164176;11648.767024659544;11715.472803723853;11850.407479625985;11871.703207419883;11880;4747.905987325111;4774.379330686988;4785.3300537924715;4887.144749668793;4904.6566740327835;8675.175858293453;10153.086222239886;10160.33957792741;10160.796784064278;10170.338927969806;5426.240326354314;5429.267048948421;5430.5384042356845;5442.891142177214;5450.509454149145;4008.8797336079965;4386.978065109654;4537.342085397599;4924.655274130848;4954.8761280968265;3496.9650303497147;4034.072163402434;4038.353863381845;4114.897127571835;4158.209940111369;1271.154682136369;1301.3818436242898;1304.373900527752;1316.5194703932996;1318.947242110324;867.020267098175;873.5675840458887;875.146731936357;875.5578432891476;912.8179008184285;5414.296141501658;5555.527780138653;5695.686737229409;5804.799392962203;5847.8245652630485;437.6783064447628;446.2792589967244;447.1905850410767;453.0305146985388;454.30264543924915;3772.905677358088;3782.6403647938364;3883.4369016493015;3914.784686842251;3944.073142811437;9149.54252287383;10646.840297395545;10875.673729788119;10964.28732132409;10981.231313807957;10921.344719316867;10922.836157457485;10928.344299342065;10952.178586606036;10961.83557246642;8168.469049511644;8213;8288.8810010649;8292.253697167283;8396.412251142361;5724.513416359656;5769.74036168365;5794;5795.797147099831;5823.912641310299;10153.695389138338;10267.229957753349;10277.845832312372;10312.175025998053;10340.896591034294;9387.859182112137;10123.595056197964;10319.94840025795;10429.478526073513;10477.842832357674;7030.859382812418;7335.7432489862595;7491.138519070377;7534.133574638987;7620.123685776207;7007.754728584475;8165.918340816754;8179.509229446252;8226.917730822615;8313;7478.326950574475;7518.699252030076;7542.83086121652;7566.205548417557;7587.58268295249;7163.570185788869;7316.487845850739;7365;7410.777676669709;7545.886811697938;6655.900161497679;6803.965980170067;6929.7228110875;6930.549514281555;6933;4829.975850120727;4908.484609116134;4936.136176169084;4976.2286845539;5115.411727651219;4696.718196908197;4880.1215781159735;4991.226359914219;5012.348394708664;5106.412762532356 15:38:43 INFO - PROCESS | 1664 | |4;prototype.html;301.9381026889488;303.8070825026109;308.3801558866678;314.7419116324615;319.1192309226534;4469.994251293461;5079.5712893796635;5084.3644544431945;5310.522053015228;5376.516113549785;4034.838606455746;4518.774061296938;4735.597474214687;4985.925211121836;5271.313179782471;4041.3533834586474;4054.533728621202;4056.797160141996;4065.6747460203114;4075.1849630073957;3231.951520727191;3910.569837317893;4024.9195016099807;4033.1530378620523;4056.472658554384;3729.3287208302495;3969.6427321541055;4005.5393629732516;4023.4769479967563;4053.29067413203;4012.5184977802705;4016.819243134066;4027.348786997332;4027.375756757707;4038.1318016626565;2941.2352788275202;3509.859605615772;3518.138056176253;3522.6653467920764;3533.5759708835103;4140.420341152241;5185.766640501149;5205.115130427836;5220.80726095041;5246.344206974129;4578.908421831545;4580.885477863047;4582.083583283356;4582.33556134362;4601.493835678073;4161.313383291753;4642.257238841769;4946.777395017197;5570.359408667974;5677.602567820254;2940.955885661717;3534.770239934396;3786.678132358725;3805.238952209569;3814.749297914216;5347.786088556492;5808.273965754281;5844.736986835645;5875.441833025899;5902.4097590240635;3300.174956260935;3363.545921300642;3659.75568306777;3849.2101172954713;3853.7495062820826;3943.842246310173;3944.9013774655864;3948.368261078214;3968.464257325282;3980.2238563480423;4292.519080917079;4318.848840290575;4515.706479078849;4549.908022074678;4599;4857.489963553847;5122.564582010496;5435;5583.804566840222;5605.523530499871;1097.6048622495896;1281.4297637551388;1283.9293838838767;1310.1418570836117;1313.9014573906995;815.8164413007026;900.5362238447204;900.8333458310234;902.8510295801251;907.1654078248027;4715.844618068595;5143.254228136899;5162.805807531784;5239.580833533384;5278.9208161877605;5421.403645598981;5641.830745077655;5723.599348045597;5750.30996280449;5766.855828604234;4387.451568553931;5144.30551875492;5173.068847607392;5175.741212939338;5200.739963001834;2331.1491305673308;2337.520808234308;2447.3514518652573;2513.8813228113313;2537.69547654279;3910.7317755702193;4132.442120313779;4974.3087350301075;5526.392096869341;5995.550333724988;5782.98797710399;5927.221833449948;5962.135490353874;5968.671723055187;5979.431953964367;4469.888252793706;5688.862227554423;5730.9397761414275;6089.604175728564;6123.38766123393;4720.811167553259;4874.683145595525;5091.923621145612;5111.46329635383;5253.789848406173;5521.337439507285;5707.5433965281845;5733.71331433435;5743.798967036134;5752.424757524382;5780.710964451845;5995.980683283765;5999.970000149971;6020.90968635462;6030.216071910623;3556.9644303556634;3769.3968964965475;3791.914447054164;3810.7713537187856;3842.289176502356;5731.283589551306;5760.510356619567;5770.355448697102;5814.273215848019;5963.970180149072;5445.646033008;5558.72206389687;5623.493885550321;5653.643125649896;5671.432856714296;5596.832095037155;5610.579206559606;5612.155673363759;5612.214289999485;5616.66300022;5225.603357817449;5301.5493683037375;5437.34751829783;5510.752016159363;5549.722513874371;5089.007643509628;5606.355269144101;5641.746121424464;5654.943450565606;5674.432556744293;4587.834394267945;5153.330067091254;5530.695811730394;5532.6680399176175;5533.363663178786 15:38:43 INFO - PROCESS | 1664 | |5;yui.html;662.3211208511278;695.8364784275694;714.6141083814737;758.3629751009152;765.8353454007387;15220.847791522081;15504.534863954072;15683.60790980226;15719.685606287896;15883.20583970801;14160.150390976549;14332.498362557286;14386.496472623461;14390.352434140463;14921.7015659687;820.126565208053;843.2326582809644;845.5729856422498;845.9873101903446;850.5747126436781;671.6138220523219;698.898659694344;699.489372757887;700.6286668065933;705.0763499815258;866.4887716247413;900.1043961258524;901.4095767272447;903.8327909336793;906.1255888068013;898.1512470715177;898.734873212404;899.6401439424217;901.2699713232303;901.5402144906079;520.3183829183781;663.3002182697253;666.8899631560787;667.6428110960612;668.1581207678312;14603.634909127251;14836.925815370962;14883.627909302246;14951.626209344744;14963.775543366859;14409.567712968523;14726;14913.552593422106;14939.477118300807;15250;11990.220635658654;12020.45907934145;12057.638270851889;12230.938845305716;12248.142630015902;3820.121372084436;3842.346801043829;3858.5562660293863;3925.8429662813455;3985.242803867256;111185.88814111837;116616.41691791656;118099;119376.40311798385;119955;11328.150388720878;11381.203315767816;11396.886031139748;11494.827577586342;11520.711982200512;21084.47288817792;22524;23631.291061268188;23638.409039774142;23651.7634823653;7772;7796.649150788115;7800.726974555863;7911.881321780178;8127;4293.2057569349145;4344.761038142869;4402.9559704402545;4456.997175635456;4458.71018383804;3578.4274516077303;3699.4820725098507;3700.1674623209565;3700.7594506357;3704.518412606344;529.2220435959134;645.8030300758314;648.0538413915642;648.1638686094885;648.6951132967592;637.4390536328094;659.7333120408827;663.571996062537;682.3176823176823;685.116200101863;8015.47899386538;8838.86741698875;9174.311926605531;9295.209907157967;9315.39449935752;514.3801718928756;558.5866458820443;601.2274227617578;637.6907200007993;653.7254353171679;4208;4568.771561421916;4586.243269860503;4587.220172570671;4700.95299047012;90960;91504.54247728719;141844;143746;149345;119761.2095516187;124829;125432.74567254393;127576.36211818882;129362;95491;98054;98831;109998;112180;8302.501849889026;9554.187894029086;9857.556409961426;9994.050565196585;10024.548895299875;58320.125198122914;59978.40021599728;60305.79388412295;61109.38890611037;65766.68466630737;85639;90833.54583227042;94675.68635097738;94769.57845632458;96480.51759741438;10984.176186785864;12837.743245134858;12869.420876060787;12974.351282435651;13285.66785830362;14605.269736513344;14687.55937321882;14852.183129927958;14858;15046.322915469049;13438.462461501822;13762.24307663088;13953.581392558239;14004;14031.929840350733;11342.943285283522;13059.477620895057;13186.274754888573;13417.262050587311;13620.591382258548;10900;13505.932470337586;13531.052826302064;13878.653033674238;14169.149851008973;10441.477926103817;10795.460226988462;11200.495977680861;11437.542498299976;11885.76228475443;10722.303050301705;11271.49278282463;11415.60045398407;11528;11585.478653460446 15:38:43 INFO - PROCESS | 1664 | __end_tp_report 15:38:43 INFO - PROCESS | 1664 | __start_cc_report 15:38:43 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1410 15:38:43 INFO - PROCESS | 1664 | __end_cc_report 15:38:43 INFO - PROCESS | 1664 | __startTimestamp1473115123944__endTimestamp 15:38:43 INFO - PROCESS | 1664 | 15:38:43 INFO - PROCESS | 1664 | ------- Summary: start ------- 15:38:43 INFO - PROCESS | 1664 | Number of tests: 6 15:38:43 INFO - PROCESS | 1664 | 15:38:43 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5711.21 Median:5956.79 stddev:654.99 (11.0%) stddev-sans-first:630.62 15:38:43 INFO - PROCESS | 1664 | Values: 3365.4 3370.9 3376.5 3404.1 3446.0 5459.1 5538.6 5671.7 5688.9 5729.3 5552.1 5588.6 5590.1 5590.3 5591.1 5113.0 5191.5 5203.8 5227.1 5296.9 4962.1 5268.5 5278.9 5333.0 5388.0 5180.5 5297.6 5311.2 5341.6 5381.4 4486.7 5284.0 5295.6 5308.2 5326.7 5058.7 5083.1 5148.4 5181.3 5219.6 4652.0 5622.2 5627.9 5633.4 5653.3 6113.4 6113.5 6166.9 6178.7 6253.1 6233.5 6269.9 6280.6 6299.0 6315.5 5609.5 5681.2 5783.2 5793.2 5811.4 6244.8 6314.6 6321.5 6325.7 6337.5 6121.1 6138.0 6149.9 6151.0 6152.4 6018.3 6030.9 6266.8 6273.2 6288.0 6051.0 6075.3 6091.8 6107.6 6133.5 5140.7 6163.7 6168.9 6174.4 6175.7 4732.1 4866.7 4877.0 4881.6 4896.4 4260.9 4321.9 4352.4 4363.8 4371.1 5642.8 5703.4 5717.2 5749.2 5780.7 6224.5 6276.7 6291.7 6295.3 6301.8 5666.2 5693.0 5697.8 5708.5 5730.5 5291.1 5310.5 5381.4 5421.5 5438.1 6262.5 6321.3 6344.6 6349.1 6366.8 6235.0 6287.6 6303.9 6314.7 6321.5 6252.5 6258.8 6268.9 6288.4 6324.0 6190.9 6200.9 6229.0 6229.3 6239.1 6310.7 6315.1 6315.6 6315.7 6320.8 5045.3 5825.0 6015.6 6073.0 6100.2 6024.9 6059.8 6065.8 6089.1 6106.9 5890.6 5919.5 5944.8 5956.8 5957.1 5898.0 6041.9 6095.1 6108.3 6125.9 5974.9 5993.1 5994.8 5994.8 5997.9 15:38:43 INFO - PROCESS | 1664 | 15:38:43 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:65358.94 Median:40986.50 stddev:136822.40 (333.8%) stddev-sans-first:137119.59 15:38:43 INFO - PROCESS | 1664 | Values: 508.8 520.5 522.6 527.7 563.2 31389.0 40568.0 41784.2 42091.6 43960.0 42947.1 43264.9 43333.1 43347.8 43348.0 9689.2 9867.7 9877.2 9883.2 9888.3 9662.7 9790.0 9791.9 9793.5 9804.8 9776.1 9800.5 9815.3 9817.1 9848.2 9686.2 9740.8 9751.2 9770.2 9779.2 9680.3 9742.3 9745.3 9756.0 9765.2 40585.8 40825.4 40844.6 40950.0 41023.0 3321.6 3423.2 3552.8 3565.6 3937.6 30826.0 31139.7 31405.7 31691.8 32034.5 3239.7 3305.9 3364.0 3370.9 3395.1 449203.0 450498.0 453664.7 454052.0 455763.0 59676.1 60020.7 60069.0 60104.7 60330.1 63561.7 63737.0 63775.4 63861.7 63898.4 16120.2 16374.8 16381.9 16386.3 16398.3 12620.4 12703.2 12719.9 12740.0 12749.2 16864.5 18678.4 18693.5 18765.9 18918.3 7585.2 7679.0 7771.8 7773.1 7810.2 730.7 767.8 885.8 1007.3 1080.1 29301.1 29915.9 29916.3 30806.0 31033.8 923.3 987.8 1026.7 1088.9 1094.0 15253.7 15611.8 15675.8 15739.7 16125.1 568332.0 603571.0 611194.0 611210.0 611712.0 55904.4 56421.7 56610.3 56641.0 56711.1 1241.3 1282.8 1333.7 1381.5 1409.2 28968.0 30843.2 31321.2 31488.1 31801.7 48323.5 48802.0 48822.5 48859.3 48877.0 472563.3 473265.0 474199.0 476446.0 477041.0 25518.2 26323.7 26403.3 26444.7 26459.9 46199.5 47002.0 47243.5 47637.0 48092.8 37062.1 37954.4 39087.0 39449.0 40304.0 38661.6 39246.8 39884.4 40219.2 40366.8 37711.4 38184.0 38422.6 38478.8 38607.4 30001.0 32033.4 34273.3 34501.5 34817.1 24629.0 24935.9 25163.1 25313.6 25477.6 15:38:43 INFO - PROCESS | 1664 | 15:38:43 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:35503.37 Median:35432.70 stddev:91917.85 (259.4%) stddev-sans-first:92137.74 15:38:43 INFO - PROCESS | 1664 | Values: 317.3 348.5 360.8 364.4 403.4 15279.9 17770.0 20568.9 20704.1 20706.0 21489.6 22102.3 22207.6 22461.2 23049.0 6736.4 6749.2 6922.0 7005.3 7103.3 4682.7 5981.4 7051.9 7072.2 7074.2 7683.6 8025.9 8074.2 8084.1 8158.8 7841.6 8061.2 8074.5 8093.3 8119.9 7638.9 7688.3 7732.5 7807.1 7813.6 28214.7 28936.4 29088.4 29148.6 29540.7 2198.6 2367.2 2558.0 2605.1 2831.9 14146.3 16162.7 16934.6 17301.6 17407.5 1950.2 2015.5 2052.4 2075.8 2104.6 92257.5 98804.5 111077.0 112509.0 148193.0 15457.4 19035.6 19583.6 20205.8 21667.8 35197.3 35325.6 35343.8 35413.3 35452.1 10525.2 10740.0 10952.3 10980.8 11134.4 7330.5 7514.2 9000.9 9029.1 9208.2 9408.2 9532.5 9654.9 9672.7 9702.4 3114.8 3122.9 3605.9 3734.7 3834.2 840.8 847.3 922.1 951.4 1005.9 20710.0 21358.1 21437.2 21443.6 21452.0 864.7 899.7 904.4 925.3 970.8 10715.9 11204.4 11252.3 11270.0 11281.3 540760.0 542327.0 543483.0 544080.3 568547.0 127437.0 131194.0 134170.7 137035.0 137211.0 968.6 1007.1 1040.8 1041.3 1041.7 18518.6 18720.6 18943.5 19046.8 19103.0 54143.1 57338.1 61693.9 61861.7 61876.0 82247.0 82568.0 87288.0 88305.0 88844.6 7096.1 7341.4 7447.3 8033.0 10989.3 8993.3 13214.9 13399.7 13804.4 13982.6 6438.6 7631.3 7852.3 8366.8 8497.2 7840.6 7853.7 8237.9 8319.2 8355.3 7999.7 8261.1 8336.5 8513.9 8582.3 11976.9 12398.6 12629.6 12661.9 12760.2 11609.2 11711.6 11952.9 12012.9 12032.3 15:38:43 INFO - PROCESS | 1664 | 15:38:43 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5705.12 Median:4722.31 stddev:3233.75 (68.5%) stddev-sans-first:3226.27 15:38:43 INFO - PROCESS | 1664 | Values: 1351.3 1513.7 1545.5 1737.7 1907.0 7873.6 8487.7 8808.0 8824.2 8827.2 8555.3 8721.2 8721.8 8847.2 8855.3 2367.5 2411.1 2419.8 2420.6 2423.0 2209.4 2229.5 2377.6 2402.9 2420.5 2371.8 2388.8 2403.2 2414.4 2421.9 2366.5 2388.3 2393.1 2394.7 2403.2 2362.1 2375.6 2384.3 2391.4 2397.2 8629.7 8778.0 8956.0 8958.1 8960.9 5262.8 5482.6 5488.7 5493.5 5496.5 3547.8 3569.8 3616.6 3625.5 3636.6 1091.1 1098.1 1104.3 1120.7 1130.3 11648.8 11715.5 11850.4 11871.7 11880.0 4747.9 4774.4 4785.3 4887.1 4904.7 8675.2 10153.1 10160.3 10160.8 10170.3 5426.2 5429.3 5430.5 5442.9 5450.5 4008.9 4387.0 4537.3 4924.7 4954.9 3497.0 4034.1 4038.4 4114.9 4158.2 1271.2 1301.4 1304.4 1316.5 1318.9 867.0 873.6 875.1 875.6 912.8 5414.3 5555.5 5695.7 5804.8 5847.8 437.7 446.3 447.2 453.0 454.3 3772.9 3782.6 3883.4 3914.8 3944.1 9149.5 10646.8 10875.7 10964.3 10981.2 10921.3 10922.8 10928.3 10952.2 10961.8 8168.5 8213.0 8288.9 8292.3 8396.4 5724.5 5769.7 5794.0 5795.8 5823.9 10153.7 10267.2 10277.8 10312.2 10340.9 9387.9 10123.6 10319.9 10429.5 10477.8 7030.9 7335.7 7491.1 7534.1 7620.1 7007.8 8165.9 8179.5 8226.9 8313.0 7478.3 7518.7 7542.8 7566.2 7587.6 7163.6 7316.5 7365.0 7410.8 7545.9 6655.9 6804.0 6929.7 6930.5 6933.0 4830.0 4908.5 4936.1 4976.2 5115.4 4696.7 4880.1 4991.2 5012.3 5106.4 15:38:43 INFO - PROCESS | 1664 | 15:38:43 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4474.57 Median:5086.69 stddev:1417.48 (27.9%) stddev-sans-first:1386.43 15:38:43 INFO - PROCESS | 1664 | Values: 301.9 303.8 308.4 314.7 319.1 4470.0 5079.6 5084.4 5310.5 5376.5 4034.8 4518.8 4735.6 4985.9 5271.3 4041.4 4054.5 4056.8 4065.7 4075.2 3232.0 3910.6 4024.9 4033.2 4056.5 3729.3 3969.6 4005.5 4023.5 4053.3 4012.5 4016.8 4027.3 4027.4 4038.1 2941.2 3509.9 3518.1 3522.7 3533.6 4140.4 5185.8 5205.1 5220.8 5246.3 4578.9 4580.9 4582.1 4582.3 4601.5 4161.3 4642.3 4946.8 5570.4 5677.6 2941.0 3534.8 3786.7 3805.2 3814.7 5347.8 5808.3 5844.7 5875.4 5902.4 3300.2 3363.5 3659.8 3849.2 3853.7 3943.8 3944.9 3948.4 3968.5 3980.2 4292.5 4318.8 4515.7 4549.9 4599.0 4857.5 5122.6 5435.0 5583.8 5605.5 1097.6 1281.4 1283.9 1310.1 1313.9 815.8 900.5 900.8 902.9 907.2 4715.8 5143.3 5162.8 5239.6 5278.9 5421.4 5641.8 5723.6 5750.3 5766.9 4387.5 5144.3 5173.1 5175.7 5200.7 2331.1 2337.5 2447.4 2513.9 2537.7 3910.7 4132.4 4974.3 5526.4 5995.6 5783.0 5927.2 5962.1 5968.7 5979.4 4469.9 5688.9 5730.9 6089.6 6123.4 4720.8 4874.7 5091.9 5111.5 5253.8 5521.3 5707.5 5733.7 5743.8 5752.4 5780.7 5996.0 6000.0 6020.9 6030.2 3557.0 3769.4 3791.9 3810.8 3842.3 5731.3 5760.5 5770.4 5814.3 5964.0 5445.6 5558.7 5623.5 5653.6 5671.4 5596.8 5610.6 5612.2 5612.2 5616.7 5225.6 5301.5 5437.3 5510.8 5549.7 5089.0 5606.4 5641.7 5654.9 5674.4 4587.8 5153.3 5530.7 5532.7 5533.4 15:38:43 INFO - PROCESS | 1664 | 15:38:43 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:24105.84 Median:3762.32 stddev:37589.08 (999.1%) stddev-sans-first:37653.31 15:38:43 INFO - PROCESS | 1664 | Values: 662.3 695.8 714.6 758.4 765.8 15220.8 15504.5 15683.6 15719.7 15883.2 14160.2 14332.5 14386.5 14390.4 14921.7 820.1 843.2 845.6 846.0 850.6 671.6 698.9 699.5 700.6 705.1 866.5 900.1 901.4 903.8 906.1 898.2 898.7 899.6 901.3 901.5 520.3 663.3 666.9 667.6 668.2 14603.6 14836.9 14883.6 14951.6 14963.8 14409.6 14726.0 14913.6 14939.5 15250.0 11990.2 12020.5 12057.6 12230.9 12248.1 3820.1 3842.3 3858.6 3925.8 3985.2 111185.9 116616.4 118099.0 119376.4 119955.0 11328.2 11381.2 11396.9 11494.8 11520.7 21084.5 22524.0 23631.3 23638.4 23651.8 7772.0 7796.6 7800.7 7911.9 8127.0 4293.2 4344.8 4403.0 4457.0 4458.7 3578.4 3699.5 3700.2 3700.8 3704.5 529.2 645.8 648.1 648.2 648.7 637.4 659.7 663.6 682.3 685.1 8015.5 8838.9 9174.3 9295.2 9315.4 514.4 558.6 601.2 637.7 653.7 4208.0 4568.8 4586.2 4587.2 4701.0 90960.0 91504.5 141844.0 143746.0 149345.0 119761.2 124829.0 125432.7 127576.4 129362.0 95491.0 98054.0 98831.0 109998.0 112180.0 8302.5 9554.2 9857.6 9994.1 10024.5 58320.1 59978.4 60305.8 61109.4 65766.7 85639.0 90833.5 94675.7 94769.6 96480.5 10984.2 12837.7 12869.4 12974.4 13285.7 14605.3 14687.6 14852.2 14858.0 15046.3 13438.5 13762.2 13953.6 14004.0 14031.9 11342.9 13059.5 13186.3 13417.3 13620.6 10900.0 13505.9 13531.1 13878.7 14169.1 10441.5 10795.5 11200.5 11437.5 11885.8 10722.3 11271.5 11415.6 11528.0 11585.5 15:38:43 INFO - PROCESS | 1664 | -------- Summary: end -------- 15:38:43 INFO - PROCESS | 1664 | 15:38:44 INFO - TEST-INFO | 1664: exit 0 15:38:44 INFO - TEST-OK | dromaeo_css | took 1117383ms 15:38:44 INFO - TEST-START | kraken 15:38:44 INFO - operating with platform_type : mac_ 15:38:44 INFO - Initialising browser for kraken test... 15:38:44 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmpmPmhXh/profile http://localhost:49277/getInfo.html) 15:38:46 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 15:38:46 INFO - PROCESS | 1671 | colorDepth:24 15:38:46 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 15:38:46 INFO - PROCESS | 1671 | __metrics 15:38:46 INFO - PROCESS | 1671 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:38:46 INFO - PROCESS | 1671 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:38:47 INFO - TEST-INFO | 1671: exit 0 15:38:47 INFO - Browser initialized. 15:38:47 INFO - Running cycle 1/1 for kraken test... 15:38:47 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmpmPmhXh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:38:59 INFO - PROCESS | 1672 | RSS: Main: 232288256 15:38:59 INFO - PROCESS | 1672 | 15:39:02 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 15:39:02 INFO - PROCESS | 1672 | RSS: Main: 290824192 15:39:02 INFO - PROCESS | 1672 | 15:39:05 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 15:39:06 INFO - PROCESS | 1672 | RSS: Main: 287080448 15:39:06 INFO - PROCESS | 1672 | 15:39:09 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 15:39:10 INFO - PROCESS | 1672 | RSS: Main: 377651200 15:39:10 INFO - PROCESS | 1672 | 15:39:12 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 15:39:13 INFO - PROCESS | 1672 | RSS: Main: 279547904 15:39:13 INFO - PROCESS | 1672 | 15:39:15 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:39:15 INFO - PROCESS | 1672 | RSS: Main: 283521024 15:39:15 INFO - PROCESS | 1672 | 15:39:18 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 15:39:19 INFO - PROCESS | 1672 | RSS: Main: 321273856 15:39:19 INFO - PROCESS | 1672 | 15:39:22 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 15:39:22 INFO - PROCESS | 1672 | RSS: Main: 321040384 15:39:22 INFO - PROCESS | 1672 | 15:39:25 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 15:39:26 INFO - PROCESS | 1672 | RSS: Main: 320970752 15:39:26 INFO - PROCESS | 1672 | 15:39:27 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:39:28 INFO - PROCESS | 1672 | RSS: Main: 297594880 15:39:28 INFO - PROCESS | 1672 | 15:39:30 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:39:30 INFO - PROCESS | 1672 | RSS: Main: 304279552 15:39:30 INFO - PROCESS | 1672 | 15:39:32 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:39:33 INFO - PROCESS | 1672 | RSS: Main: 301060096 15:39:33 INFO - PROCESS | 1672 | 15:39:36 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:39:37 INFO - PROCESS | 1672 | RSS: Main: 320679936 15:39:37 INFO - PROCESS | 1672 | 15:39:40 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:39:40 INFO - PROCESS | 1672 | RSS: Main: 316710912 15:39:40 INFO - PROCESS | 1672 | 15:39:42 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 15:39:42 INFO - PROCESS | 1672 | RSS: Main: 360407040 15:39:42 INFO - PROCESS | 1672 | 15:39:42 INFO - PROCESS | 1672 | __start_tp_report 15:39:42 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 15:39:42 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 15:39:42 INFO - PROCESS | 1672 | |i|pagename|runs| 15:39:42 INFO - PROCESS | 1672 | |0;ai-astar;112;113;117;114;114;112;112;113;113;124 15:39:42 INFO - PROCESS | 1672 | |1;audio-beat-detection;147;114;131;160;110;133;138;138;180;108 15:39:42 INFO - PROCESS | 1672 | |2;audio-dft;184;181;185;192;260;184;182;184;183;184 15:39:42 INFO - PROCESS | 1672 | |3;audio-fft;77;73;77;158;57;57;58;64;141;57 15:39:42 INFO - PROCESS | 1672 | |4;audio-oscillator;81;97;168;101;99;119;119;68;69;117 15:39:42 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;88;85;89;86;88;86;89;85;89;85 15:39:42 INFO - PROCESS | 1672 | |6;imaging-darkroom;93;92;94;94;94;93;93;95;93;93 15:39:42 INFO - PROCESS | 1672 | |7;imaging-desaturate;84;86;81;88;86;86;85;82;86;86 15:39:42 INFO - PROCESS | 1672 | |8;json-parse-financial;53;53;56;55;53;52;54;54;55;55 15:39:42 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;63;66;76;76;71;72;69;71;70;70 15:39:42 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;85;85;88;74;82;75;103;97;94;84 15:39:42 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;134;156;157;141;144;151;149;150;181;137 15:39:42 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;167;168;172;167;167;168;167;172;168;168 15:39:42 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;63;67;67;68;67;68;68;66;66;68 15:39:42 INFO - PROCESS | 1672 | __end_tp_report 15:39:42 INFO - PROCESS | 1672 | __start_cc_report 15:39:42 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,1989 15:39:42 INFO - PROCESS | 1672 | __end_cc_report 15:39:42 INFO - PROCESS | 1672 | __startTimestamp1473115182631__endTimestamp 15:39:42 INFO - PROCESS | 1672 | 15:39:42 INFO - PROCESS | 1672 | ------- Summary: start ------- 15:39:42 INFO - PROCESS | 1672 | Number of tests: 14 15:39:42 INFO - PROCESS | 1672 | 15:39:42 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:114.40 Median:113.50 stddev:3.69 (3.2%) stddev-sans-first:3.81 15:39:42 INFO - PROCESS | 1672 | Values: 112.0 113.0 117.0 114.0 114.0 112.0 112.0 113.0 113.0 124.0 15:39:42 INFO - PROCESS | 1672 | 15:39:42 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:135.90 Median:138.00 stddev:22.65 (16.4%) stddev-sans-first:23.67 15:39:42 INFO - PROCESS | 1672 | Values: 147.0 114.0 131.0 160.0 110.0 133.0 138.0 138.0 180.0 108.0 15:39:42 INFO - PROCESS | 1672 | 15:39:42 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:191.90 Median:184.00 stddev:24.11 (13.1%) stddev-sans-first:25.40 15:39:42 INFO - PROCESS | 1672 | Values: 184.0 181.0 185.0 192.0 260.0 184.0 182.0 184.0 183.0 184.0 15:39:42 INFO - PROCESS | 1672 | 15:39:42 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:81.90 Median:61.00 stddev:36.76 (60.3%) stddev-sans-first:38.95 15:39:42 INFO - PROCESS | 1672 | Values: 77.0 73.0 77.0 158.0 57.0 57.0 58.0 64.0 141.0 57.0 15:39:42 INFO - PROCESS | 1672 | 15:39:42 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:103.80 Median:68.50 stddev:29.53 (43.1%) stddev-sans-first:30.15 15:39:42 INFO - PROCESS | 1672 | Values: 81.0 97.0 168.0 101.0 99.0 119.0 119.0 68.0 69.0 117.0 15:39:42 INFO - PROCESS | 1672 | 15:39:42 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:87.00 Median:88.00 stddev:1.76 (2.0%) stddev-sans-first:1.83 15:39:42 INFO - PROCESS | 1672 | Values: 88.0 85.0 89.0 86.0 88.0 86.0 89.0 85.0 89.0 85.0 15:39:42 INFO - PROCESS | 1672 | 15:39:42 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:93.40 Median:93.50 stddev:0.84 (0.9%) stddev-sans-first:0.88 15:39:42 INFO - PROCESS | 1672 | Values: 93.0 92.0 94.0 94.0 94.0 93.0 93.0 95.0 93.0 93.0 15:39:42 INFO - PROCESS | 1672 | 15:39:42 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:86.00 stddev:2.11 (2.5%) stddev-sans-first:2.20 15:39:42 INFO - PROCESS | 1672 | Values: 84.0 86.0 81.0 88.0 86.0 86.0 85.0 82.0 86.0 86.0 15:39:42 INFO - PROCESS | 1672 | 15:39:42 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.50 stddev:1.25 (2.3%) stddev-sans-first:1.27 15:39:42 INFO - PROCESS | 1672 | Values: 53.0 53.0 56.0 55.0 53.0 52.0 54.0 54.0 55.0 55.0 15:39:42 INFO - PROCESS | 1672 | 15:39:42 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:71.00 stddev:3.98 (5.6%) stddev-sans-first:3.19 15:39:42 INFO - PROCESS | 1672 | Values: 63.0 66.0 76.0 76.0 71.0 72.0 69.0 71.0 70.0 70.0 15:39:42 INFO - PROCESS | 1672 | 15:39:42 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:86.70 Median:85.00 stddev:9.19 (10.8%) stddev-sans-first:9.73 15:39:42 INFO - PROCESS | 1672 | Values: 85.0 85.0 88.0 74.0 82.0 75.0 103.0 97.0 94.0 84.0 15:39:42 INFO - PROCESS | 1672 | 15:39:42 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:150.00 Median:150.50 stddev:13.29 (8.8%) stddev-sans-first:12.77 15:39:42 INFO - PROCESS | 1672 | Values: 134.0 156.0 157.0 141.0 144.0 151.0 149.0 150.0 181.0 137.0 15:39:42 INFO - PROCESS | 1672 | 15:39:42 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.40 Median:168.00 stddev:1.96 (1.2%) stddev-sans-first:2.01 15:39:42 INFO - PROCESS | 1672 | Values: 167.0 168.0 172.0 167.0 167.0 168.0 167.0 172.0 168.0 168.0 15:39:42 INFO - PROCESS | 1672 | 15:39:42 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.50 stddev:1.55 (2.3%) stddev-sans-first:0.83 15:39:42 INFO - PROCESS | 1672 | Values: 63.0 67.0 67.0 68.0 67.0 68.0 68.0 66.0 66.0 68.0 15:39:42 INFO - PROCESS | 1672 | -------- Summary: end -------- 15:39:42 INFO - PROCESS | 1672 | 15:39:43 INFO - TEST-INFO | 1672: exit 0 15:39:43 INFO - TEST-OK | kraken | took 58974ms 15:39:43 INFO - SUITE-END | took 1176s 15:39:43 INFO - Completed test suite (00:19:36) 15:39:43 INFO - javascript benchmark 15:39:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3365.4278772608654, 3370.898873033809, 3376.5441665375165, 3404.1149301181686, 3445.9655403445972, 5459.12653975364, 5538.6122971392015, 5671.71641417929, 5688.886222275552, 5729.3411257705375, 5552.11166213406, 5588.552915766739, 5590.077637189859, 5590.273264475622, 5591.133374326976, 5113.0, 5191.5327620514145, 5203.8178663746585, 5227.08526007949, 5296.92054619181, 4962.082014827269, 5268.473152684739, 5278.868028299304, 5333.0, 5388.030154572176, 5180.533751962323, 5297.576193904479, 5311.176767601028, 5341.599380046493, 5381.4349493303225, 4486.730796152241, 5283.973580132075, 5295.576353891718, 5308.177232528964, 5326.6537675051195, 5058.696478211319, 5083.059633967728, 5148.433672296044, 5181.300524429192, 5219.6085293602755, 4652.0, 5622.184783206453, 5627.915581266284, 5633.352164501094, 5653.349864765563, 6113.358097399753, 6113.476306051806, 6166.938330616681, 6178.7219575119225, 6253.1245625612455, 6233.501319894398, 6269.937300626981, 6280.560360774748, 6298.968505157491, 6315.526335524854, 5609.467100625434, 5681.17622944679, 5783.248177736868, 5793.246877905887, 5811.447912448353, 6244.812655620339, 6314.62112273265, 6321.494280457552, 6325.683715814191, 6337.4613157882095, 6121.081837724288, 6137.96931015342, 6149.877002459926, 6150.969245153746, 6152.415520525543, 6018.307894592091, 6030.90953635687, 6266.811995640138, 6273.153124328157, 6287.993920972623, 6051.031834906394, 6075.301340345917, 6091.817245482643, 6107.5910050938, 6133.52252855418, 5140.691558506427, 6163.660998645028, 6168.938310616926, 6174.44430001302, 6175.660338681416, 4732.053589282157, 4866.732329721899, 4877.048975449753, 4881.609471242292, 4896.4124305082905, 4260.914781704349, 4321.9135617287475, 4352.39066530686, 4363.80362883671, 4371.060222052274, 5642.774289028393, 5703.429657034263, 5717.2281741965135, 5749.195112684228, 5780.739866706008, 6224.470919971763, 6276.686165691788, 6291.6854157291955, 6295.338989406138, 6301.842453938688, 5666.17840413146, 5692.971535142381, 5697.800576979786, 5708.514776244066, 5730.484256416942, 5291.100512912814, 5310.468953104659, 5381.354237491526, 5421.512063914267, 5438.075527160392, 6262.467690246289, 6321.273053598711, 6344.555881088279, 6349.079383489368, 6366.808995730135, 6234.9688251558455, 6287.559870808999, 6303.873922521432, 6314.715837787218, 6321.494280457644, 6252.531060170596, 6258.780942666985, 6268.874622507616, 6288.402601752826, 6324.0, 6190.938090619036, 6200.875982480416, 6228.968855155696, 6229.283632382153, 6239.06414037898, 6310.747570097328, 6315.147455093504, 6315.55791094619, 6315.684215789101, 6320.778772742932, 5045.344105266438, 5825.0, 6015.639061656315, 6073.0283154695035, 6100.206973093469, 6024.909626355696, 6059.787907423219, 6065.757369705162, 6089.086637004485, 6106.938930610637, 5890.617109887844, 5919.467247947706, 5944.821655350346, 5956.791512297049, 5957.076653118774, 5897.970510147422, 6041.909371359521, 6095.116208149705, 6108.328083910855, 6125.908111378422, 5974.9402505974385, 5993.130996005468, 5994.760209591742, 5994.790182343597, 5997.850053748692], "value": 5669.358487090441, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [508.7840062251218, 520.4587229281548, 522.571491377071, 527.722945453637, 563.1552670993507, 31389.0, 40568.0, 41784.164316713686, 42091.57908420915, 43960.0, 42947.14105717884, 43264.91837704059, 43333.13333733331, 43347.78326108373, 43348.0, 9689.224862011022, 9867.703968880909, 9877.160441362457, 9883.159931405839, 9888.307818452711, 9662.661806836762, 9789.95105024474, 9791.902080979205, 9793.510324483783, 9804.754881127958, 9776.071273229032, 9800.460974646392, 9815.312928095, 9817.116459518642, 9848.162906152986, 9686.176674982635, 9740.805183896318, 9751.219902407862, 9770.169535589483, 9779.217662587047, 9680.273979451498, 9742.269329800223, 9745.31782775206, 9755.951220243853, 9765.218782497454, 40585.79707101475, 40825.387619185734, 40844.59155408438, 40950.0, 41023.0, 3321.5848018997626, 3423.1613254752497, 3552.812893175966, 3565.625609311037, 3937.5865534118793, 30826.0, 31139.688603113904, 31405.685943140506, 31691.84154079238, 32034.519482207786, 3239.7084262416497, 3305.8677652893857, 3363.9831800841084, 3370.9494357584654, 3395.1002984209204, 449203.0, 450498.0, 453664.65838353644, 454052.0, 455763.0, 59676.104858427156, 60020.69989650111, 60069.0, 60104.699476503214, 60330.09504857431, 63561.68219158875, 63737.0, 63775.362246377874, 63861.680691596244, 63898.361016389244, 16120.193990300673, 16374.754378684329, 16381.935174213639, 16386.26261818221, 16398.344066237216, 12620.432080556397, 12703.174293670882, 12719.93640031794, 12740.0, 12749.17130386522, 16864.505644652436, 18678.439646810617, 18693.54169478651, 18765.90617046906, 18918.31092936864, 7585.1656317805, 7678.966808110765, 7771.805704857424, 7773.106092799288, 7810.218978102258, 730.7442395161651, 767.8195624028349, 885.7874110213502, 1007.3301254665712, 1080.1304949515559, 29301.120966371043, 29915.850420748193, 29916.252093697833, 30806.0, 31033.844830776154, 923.3259720404143, 987.8123156600223, 1026.656070216486, 1088.8965548272902, 1093.9835902461468, 15253.694926101416, 15611.843881561266, 15675.764863527056, 15739.667439443583, 16125.11311877858, 568332.0, 603571.0, 611194.0, 611210.0, 611712.0, 55904.440955589926, 56421.71789141028, 56610.30169094934, 56641.0, 56711.14933276004, 1241.2862604002735, 1282.8139919711775, 1333.6599167212298, 1381.461230120254, 1409.2319685771074, 28968.0, 30843.2289192763, 31321.21696957503, 31488.055358339287, 31801.6819831808, 48323.516764831904, 48802.02395952132, 48822.511774881794, 48859.26711099407, 48877.0, 472563.2743672519, 473265.0, 474199.0, 476446.0, 477041.0, 25518.23445296644, 26323.73676263213, 26403.339916502242, 26444.711057788136, 26459.94160233569, 46199.53800461952, 47002.0, 47243.52756472391, 47637.0, 48092.759536202095, 37062.07344816401, 37954.43068353922, 39087.0, 39449.01377465586, 40304.0, 38661.61338386693, 39246.803765980985, 39884.401733973435, 40219.19561608693, 40366.79816600898, 37711.434328485644, 38184.04539886525, 38422.615773841906, 38478.80760596179, 38607.42088868613, 30001.0, 32033.359332813678, 34273.31453370969, 34501.482477763355, 34817.12957176091, 24629.0, 24935.87532062328, 25163.119290824732, 25313.62029569521, 25477.61783573285], "value": 19869.04810429082, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.2655302973616, 348.54862952476543, 360.8183200019934, 364.41329459570073, 403.4210907347956, 15279.923600381997, 17770.0, 20568.897155514238, 20704.068316925735, 20706.0, 21489.57020859582, 22102.336929892088, 22207.555848883054, 22461.21385751499, 23049.0, 6736.427403670694, 6749.19009718832, 6922.0, 7005.29947005298, 7103.25415831338, 4682.742449165295, 5981.401859814027, 7051.85896282074, 7072.186698529684, 7074.18646855613, 7683.57740324282, 8025.919740802605, 8074.152214017532, 8084.070331911847, 8158.836823263531, 7841.568713720743, 8061.153578874192, 8074.515529068275, 8093.271605555469, 8119.9188008120345, 7638.923610763877, 7688.269614386624, 7732.45872788905, 7807.141214466405, 7813.609319534001, 28214.71785282162, 28936.42127157445, 29088.41823163525, 29148.562771558445, 29540.70459295401, 2198.5712786006734, 2367.1596583213054, 2557.9872100639564, 2605.0882191233104, 2831.9008834690685, 14146.29268536569, 16162.676746465122, 16934.560562352213, 17301.567460813454, 17407.47777566675, 1950.2004178286977, 2015.486051056971, 2052.3842847145797, 2075.785665385751, 2104.5896050270203, 92257.538712306, 98804.50597747037, 111077.0, 112509.0, 148193.0, 15457.381704731684, 19035.57233207515, 19583.60832783365, 20205.797942020687, 21667.78332216689, 35197.296054078775, 35325.64674353275, 35343.82328088343, 35413.29173416517, 35452.11369715778, 10525.21060920434, 10740.033396994308, 10952.34285942846, 10980.835287470694, 11134.3876086816, 7330.486865919441, 7514.248575142551, 9000.8649870252, 9029.142231487998, 9208.217301529445, 9408.247340212767, 9532.52337383128, 9654.855177172349, 9672.70981870545, 9702.417854928726, 3114.76639252057, 3122.9219269518444, 3605.9278814423565, 3734.738568300193, 3834.2331533692814, 840.7687885831446, 847.3348421489225, 922.1331947969022, 951.3863558005048, 1005.8994100589882, 20710.0, 21358.145674172858, 21437.249696260555, 21443.57112857734, 21452.034658440407, 864.7016779211162, 899.7030979776658, 904.380499357942, 925.250547056886, 970.8106919150698, 10715.892841071645, 11204.383758893338, 11252.324860508232, 11270.0, 11281.323120612626, 540760.0, 542327.0, 543483.0, 544080.2795986074, 568547.0, 127437.0, 131194.0, 134170.65829341777, 137035.0, 137211.0, 968.5593055160026, 1007.1288335589662, 1040.7918416316616, 1041.317936751429, 1041.7499800047933, 18518.629627407645, 18720.625587488445, 18943.526411839815, 19046.80953190506, 19103.0, 54143.14810868583, 57338.13992790028, 61693.90520090247, 61861.69069154625, 61876.0, 82247.0, 82568.0, 87288.0, 88305.0, 88844.5557772207, 7096.112985876765, 7341.412686985135, 7447.329740323397, 8033.0, 10989.337814326429, 8993.32550058735, 13214.933925330311, 13399.732005360032, 13804.37880295369, 13982.58052258434, 6438.645874476949, 7631.275028872248, 7852.293293603603, 8366.832663346577, 8497.235248827636, 7840.607969601383, 7853.685852565834, 8237.917620823955, 8319.209675080858, 8355.289800366794, 7999.680012799423, 8261.091279959079, 8336.541490217855, 8513.872291915503, 8582.270507006724, 11976.880231197576, 12398.5660501882, 12629.557965471164, 12661.93669031649, 12760.234385936874, 11609.187356884937, 11711.648650540497, 11952.892248015405, 12012.879871201525, 12032.27806331623], "value": 10893.129333389656, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1351.310831475947, 1513.6745599696062, 1545.5440645009724, 1737.7480265361523, 1906.971395429068, 7873.606319684014, 8487.660493580257, 8808.03111657717, 8824.161704638058, 8827.249683776887, 8555.31557475402, 8721.171488708578, 8721.825563488726, 8847.247983921357, 8855.291576673866, 2367.502824406872, 2411.131992482705, 2419.82638420366, 2420.6248031555137, 2423.042898055267, 2209.414505156135, 2229.487217939875, 2377.560151371993, 2402.939926501834, 2420.5037967216676, 2371.81025517959, 2388.773066558683, 2403.2069417092316, 2414.4326083370393, 2421.9394515137087, 2366.4675448024227, 2388.3073908566494, 2393.066703985447, 2394.6886904702365, 2403.158894386968, 2362.078789272185, 2375.560521303564, 2384.3443053160345, 2391.4380120671626, 2397.2448678666165, 8629.65481380744, 8778.0, 8956.0, 8958.148975847349, 8960.8655870162, 5262.763175657066, 5482.616216864821, 5488.725563721798, 5493.450654934514, 5496.4503549645115, 3547.840347184357, 3569.7679650822874, 3616.5840928293055, 3625.492431059654, 3636.581793093775, 1091.0889407344876, 1098.1269890437122, 1104.2601457023814, 1120.686207861792, 1130.327455164176, 11648.767024659544, 11715.472803723853, 11850.407479625985, 11871.703207419883, 11880.0, 4747.905987325111, 4774.379330686988, 4785.3300537924715, 4887.144749668793, 4904.6566740327835, 8675.175858293453, 10153.086222239886, 10160.33957792741, 10160.796784064278, 10170.338927969806, 5426.240326354314, 5429.267048948421, 5430.5384042356845, 5442.891142177214, 5450.509454149145, 4008.8797336079965, 4386.978065109654, 4537.342085397599, 4924.655274130848, 4954.8761280968265, 3496.9650303497147, 4034.072163402434, 4038.353863381845, 4114.897127571835, 4158.209940111369, 1271.154682136369, 1301.3818436242898, 1304.373900527752, 1316.5194703932996, 1318.947242110324, 867.020267098175, 873.5675840458887, 875.146731936357, 875.5578432891476, 912.8179008184285, 5414.296141501658, 5555.527780138653, 5695.686737229409, 5804.799392962203, 5847.8245652630485, 437.6783064447628, 446.2792589967244, 447.1905850410767, 453.0305146985388, 454.30264543924915, 3772.905677358088, 3782.6403647938364, 3883.4369016493015, 3914.784686842251, 3944.073142811437, 9149.54252287383, 10646.840297395545, 10875.673729788119, 10964.28732132409, 10981.231313807957, 10921.344719316867, 10922.836157457485, 10928.344299342065, 10952.178586606036, 10961.83557246642, 8168.469049511644, 8213.0, 8288.8810010649, 8292.253697167283, 8396.412251142361, 5724.513416359656, 5769.74036168365, 5794.0, 5795.797147099831, 5823.912641310299, 10153.695389138338, 10267.229957753349, 10277.845832312372, 10312.175025998053, 10340.896591034294, 9387.859182112137, 10123.595056197964, 10319.94840025795, 10429.478526073513, 10477.842832357674, 7030.859382812418, 7335.7432489862595, 7491.138519070377, 7534.133574638987, 7620.123685776207, 7007.754728584475, 8165.918340816754, 8179.509229446252, 8226.917730822615, 8313.0, 7478.326950574475, 7518.699252030076, 7542.83086121652, 7566.205548417557, 7587.58268295249, 7163.570185788869, 7316.487845850739, 7365.0, 7410.777676669709, 7545.886811697938, 6655.900161497679, 6803.965980170067, 6929.7228110875, 6930.549514281555, 6933.0, 4829.975850120727, 4908.484609116134, 4936.136176169084, 4976.2286845539, 5115.411727651219, 4696.718196908197, 4880.1215781159735, 4991.226359914219, 5012.348394708664, 5106.412762532356], "value": 4507.545158914049, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.9381026889488, 303.8070825026109, 308.3801558866678, 314.7419116324615, 319.1192309226534, 4469.994251293461, 5079.5712893796635, 5084.3644544431945, 5310.522053015228, 5376.516113549785, 4034.838606455746, 4518.774061296938, 4735.597474214687, 4985.925211121836, 5271.313179782471, 4041.3533834586474, 4054.533728621202, 4056.797160141996, 4065.6747460203114, 4075.1849630073957, 3231.951520727191, 3910.569837317893, 4024.9195016099807, 4033.1530378620523, 4056.472658554384, 3729.3287208302495, 3969.6427321541055, 4005.5393629732516, 4023.4769479967563, 4053.29067413203, 4012.5184977802705, 4016.819243134066, 4027.348786997332, 4027.375756757707, 4038.1318016626565, 2941.2352788275202, 3509.859605615772, 3518.138056176253, 3522.6653467920764, 3533.5759708835103, 4140.420341152241, 5185.766640501149, 5205.115130427836, 5220.80726095041, 5246.344206974129, 4578.908421831545, 4580.885477863047, 4582.083583283356, 4582.33556134362, 4601.493835678073, 4161.313383291753, 4642.257238841769, 4946.777395017197, 5570.359408667974, 5677.602567820254, 2940.955885661717, 3534.770239934396, 3786.678132358725, 3805.238952209569, 3814.749297914216, 5347.786088556492, 5808.273965754281, 5844.736986835645, 5875.441833025899, 5902.4097590240635, 3300.174956260935, 3363.545921300642, 3659.75568306777, 3849.2101172954713, 3853.7495062820826, 3943.842246310173, 3944.9013774655864, 3948.368261078214, 3968.464257325282, 3980.2238563480423, 4292.519080917079, 4318.848840290575, 4515.706479078849, 4549.908022074678, 4599.0, 4857.489963553847, 5122.564582010496, 5435.0, 5583.804566840222, 5605.523530499871, 1097.6048622495896, 1281.4297637551388, 1283.9293838838767, 1310.1418570836117, 1313.9014573906995, 815.8164413007026, 900.5362238447204, 900.8333458310234, 902.8510295801251, 907.1654078248027, 4715.844618068595, 5143.254228136899, 5162.805807531784, 5239.580833533384, 5278.9208161877605, 5421.403645598981, 5641.830745077655, 5723.599348045597, 5750.30996280449, 5766.855828604234, 4387.451568553931, 5144.30551875492, 5173.068847607392, 5175.741212939338, 5200.739963001834, 2331.1491305673308, 2337.520808234308, 2447.3514518652573, 2513.8813228113313, 2537.69547654279, 3910.7317755702193, 4132.442120313779, 4974.3087350301075, 5526.392096869341, 5995.550333724988, 5782.98797710399, 5927.221833449948, 5962.135490353874, 5968.671723055187, 5979.431953964367, 4469.888252793706, 5688.862227554423, 5730.9397761414275, 6089.604175728564, 6123.38766123393, 4720.811167553259, 4874.683145595525, 5091.923621145612, 5111.46329635383, 5253.789848406173, 5521.337439507285, 5707.5433965281845, 5733.71331433435, 5743.798967036134, 5752.424757524382, 5780.710964451845, 5995.980683283765, 5999.970000149971, 6020.90968635462, 6030.216071910623, 3556.9644303556634, 3769.3968964965475, 3791.914447054164, 3810.7713537187856, 3842.289176502356, 5731.283589551306, 5760.510356619567, 5770.355448697102, 5814.273215848019, 5963.970180149072, 5445.646033008, 5558.72206389687, 5623.493885550321, 5653.643125649896, 5671.432856714296, 5596.832095037155, 5610.579206559606, 5612.155673363759, 5612.214289999485, 5616.66300022, 5225.603357817449, 5301.5493683037375, 5437.34751829783, 5510.752016159363, 5549.722513874371, 5089.007643509628, 5606.355269144101, 5641.746121424464, 5654.943450565606, 5674.432556744293, 4587.834394267945, 5153.330067091254, 5530.695811730394, 5532.6680399176175, 5533.363663178786], "value": 4024.7542538737366, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [662.3211208511278, 695.8364784275694, 714.6141083814737, 758.3629751009152, 765.8353454007387, 15220.847791522081, 15504.534863954072, 15683.60790980226, 15719.685606287896, 15883.20583970801, 14160.150390976549, 14332.498362557286, 14386.496472623461, 14390.352434140463, 14921.7015659687, 820.126565208053, 843.2326582809644, 845.5729856422498, 845.9873101903446, 850.5747126436781, 671.6138220523219, 698.898659694344, 699.489372757887, 700.6286668065933, 705.0763499815258, 866.4887716247413, 900.1043961258524, 901.4095767272447, 903.8327909336793, 906.1255888068013, 898.1512470715177, 898.734873212404, 899.6401439424217, 901.2699713232303, 901.5402144906079, 520.3183829183781, 663.3002182697253, 666.8899631560787, 667.6428110960612, 668.1581207678312, 14603.634909127251, 14836.925815370962, 14883.627909302246, 14951.626209344744, 14963.775543366859, 14409.567712968523, 14726.0, 14913.552593422106, 14939.477118300807, 15250.0, 11990.220635658654, 12020.45907934145, 12057.638270851889, 12230.938845305716, 12248.142630015902, 3820.121372084436, 3842.346801043829, 3858.5562660293863, 3925.8429662813455, 3985.242803867256, 111185.88814111837, 116616.41691791656, 118099.0, 119376.40311798385, 119955.0, 11328.150388720878, 11381.203315767816, 11396.886031139748, 11494.827577586342, 11520.711982200512, 21084.47288817792, 22524.0, 23631.291061268188, 23638.409039774142, 23651.7634823653, 7772.0, 7796.649150788115, 7800.726974555863, 7911.881321780178, 8127.0, 4293.2057569349145, 4344.761038142869, 4402.9559704402545, 4456.997175635456, 4458.71018383804, 3578.4274516077303, 3699.4820725098507, 3700.1674623209565, 3700.7594506357, 3704.518412606344, 529.2220435959134, 645.8030300758314, 648.0538413915642, 648.1638686094885, 648.6951132967592, 637.4390536328094, 659.7333120408827, 663.571996062537, 682.3176823176823, 685.116200101863, 8015.47899386538, 8838.86741698875, 9174.311926605531, 9295.209907157967, 9315.39449935752, 514.3801718928756, 558.5866458820443, 601.2274227617578, 637.6907200007993, 653.7254353171679, 4208.0, 4568.771561421916, 4586.243269860503, 4587.220172570671, 4700.95299047012, 90960.0, 91504.54247728719, 141844.0, 143746.0, 149345.0, 119761.2095516187, 124829.0, 125432.74567254393, 127576.36211818882, 129362.0, 95491.0, 98054.0, 98831.0, 109998.0, 112180.0, 8302.501849889026, 9554.187894029086, 9857.556409961426, 9994.050565196585, 10024.548895299875, 58320.125198122914, 59978.40021599728, 60305.79388412295, 61109.38890611037, 65766.68466630737, 85639.0, 90833.54583227042, 94675.68635097738, 94769.57845632458, 96480.51759741438, 10984.176186785864, 12837.743245134858, 12869.420876060787, 12974.351282435651, 13285.66785830362, 14605.269736513344, 14687.55937321882, 14852.183129927958, 14858.0, 15046.322915469049, 13438.462461501822, 13762.24307663088, 13953.581392558239, 14004.0, 14031.929840350733, 11342.943285283522, 13059.477620895057, 13186.274754888573, 13417.262050587311, 13620.591382258548, 10900.0, 13505.932470337586, 13531.052826302064, 13878.653033674238, 14169.149851008973, 10441.477926103817, 10795.460226988462, 11200.495977680861, 11437.542498299976, 11885.76228475443, 10722.303050301705, 11271.49278282463, 11415.60045398407, 11528.0, 11585.478653460446], "value": 7730.439942646057, "unit": "score"}], "name": "dromaeo_css", "value": 7458.178593514001, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 117.0, 114.0, 114.0, 112.0, 112.0, 113.0, 113.0, 124.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 114.0, 131.0, 160.0, 110.0, 133.0, 138.0, 138.0, 180.0, 108.0], "value": 135.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 181.0, 185.0, 192.0, 260.0, 184.0, 182.0, 184.0, 183.0, 184.0], "value": 191.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 77.0, 158.0, 57.0, 57.0, 58.0, 64.0, 141.0, 57.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 97.0, 168.0, 101.0, 99.0, 119.0, 119.0, 68.0, 69.0, 117.0], "value": 103.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 89.0, 86.0, 88.0, 86.0, 89.0, 85.0, 89.0, 85.0], "value": 87.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 94.0, 94.0, 94.0, 93.0, 93.0, 95.0, 93.0, 93.0], "value": 93.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 81.0, 88.0, 86.0, 86.0, 85.0, 82.0, 86.0, 86.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 56.0, 55.0, 53.0, 52.0, 54.0, 54.0, 55.0, 55.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 76.0, 76.0, 71.0, 72.0, 69.0, 71.0, 70.0, 70.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 88.0, 74.0, 82.0, 75.0, 103.0, 97.0, 94.0, 84.0], "value": 86.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 156.0, 157.0, 141.0, 144.0, 151.0, 149.0, 150.0, 181.0, 137.0], "value": 150.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 168.0, 172.0, 167.0, 167.0, 168.0, 167.0, 172.0, 168.0, 168.0], "value": 168.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 67.0, 67.0, 68.0, 67.0, 68.0, 68.0, 66.0, 66.0, 68.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1489.6, "alertThreshold": 2.0}]} 15:39:43 INFO - Return code: 0 15:39:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:39:43 INFO - # TBPL SUCCESS # 15:39:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:39:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:39:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:39:43 INFO - 2016-09-05 15:39:43.968 screenresolution[1676:10939] starting screenresolution argv=screenresolution get 15:39:43 INFO - 2016-09-05 15:39:43.970 screenresolution[1676:10939] Display 0: 1600x1200x32@60 15:39:43 INFO - 2016-09-05 15:39:43.976 screenresolution[1677:10942] starting screenresolution argv=screenresolution list 15:39:43 INFO - Available Modes on Display 0 15:39:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:39:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:39:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:39:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:39:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:39:44 INFO - Intel Iris: 15:39:44 INFO - Chipset Model: Intel Iris 15:39:44 INFO - Type: GPU 15:39:44 INFO - Bus: Built-In 15:39:44 INFO - VRAM (Dynamic, Max): 1536 MB 15:39:44 INFO - Vendor: Intel (0x8086) 15:39:44 INFO - Device ID: 0x0a2e 15:39:44 INFO - Revision ID: 0x0009 15:39:44 INFO - Displays: 15:39:44 INFO - Display: 15:39:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:39:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:39:44 INFO - Display Serial Number: 1600x1200 60 15:39:44 INFO - Main Display: Yes 15:39:44 INFO - Mirror: Off 15:39:44 INFO - Online: Yes 15:39:44 INFO - Rotation: Supported 15:39:44 INFO - Return code: 0 15:39:44 INFO - Running post-action listener: _resource_record_post_action 15:39:44 INFO - [mozharness: 2016-09-05 22:39:44.212776Z] Finished run-tests step (success) 15:39:44 INFO - Running post-run listener: _resource_record_post_run 15:39:44 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 31943680; Write bytes: 705437184; Read time: 194; Write time: 1989 15:39:44 INFO - TinderboxPrint: CPU usage
53.5% 15:39:44 INFO - TinderboxPrint: I/O read bytes / time
31,943,680 / 194 15:39:44 INFO - TinderboxPrint: I/O write bytes / time
705,437,184 / 1,989 15:39:44 INFO - TinderboxPrint: CPU idle
2,255.2 (46.5%) 15:39:44 INFO - TinderboxPrint: CPU system
967.3 (20.0%) 15:39:44 INFO - TinderboxPrint: CPU user
1,623.1 (33.5%) 15:39:44 INFO - TinderboxPrint: Swap in / out
433,995,776 / 0 15:39:44 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 398305792; Write bytes: 392824832; Read time: 29490; Write time: 841 15:39:44 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 29907968; Write bytes: 295802368; Read time: 177; Write time: 1103 15:39:44 INFO - Running post-run listener: _upload_blobber_files 15:39:44 INFO - Blob upload gear active. 15:39:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:39:44 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:39:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:39:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:39:45 INFO - (blobuploader) - INFO - Open directory for files ... 15:39:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:39:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:39:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:39:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:39:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:39:46 INFO - (blobuploader) - INFO - Done attempting. 15:39:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:39:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:39:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:39:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:39:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:39:47 INFO - (blobuploader) - INFO - Done attempting. 15:39:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:39:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:39:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:39:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:39:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:39:52 INFO - (blobuploader) - INFO - Done attempting. 15:39:52 INFO - (blobuploader) - INFO - Iteration through files over. 15:39:52 INFO - Return code: 0 15:39:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:39:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:39:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/499742c0a179a4776b473a9843ad858323d4344c05472e852d16fa674391d237e0b962ff109330b0e561f1ad77fc4a331477f3de8195a522751e62d6a5b6018a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/361576e2efb833f20c29cb6ee09ce18a2d6bfb5e6018420b79911ac5ddd156aaa60bcc2945d43d1788b6c5f753a16a84edd3200e0288b8df7fb001549f760876", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/751783ade618fde7a2e3a91881720caa9f3fec0c26071a601bb4ab97ffda139e97f134ad8629437cff70778c6cc5960f76225a9a765507ac60ed0bf0257e5bbc"} 15:39:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:39:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:39:52 INFO - Contents: 15:39:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/499742c0a179a4776b473a9843ad858323d4344c05472e852d16fa674391d237e0b962ff109330b0e561f1ad77fc4a331477f3de8195a522751e62d6a5b6018a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/361576e2efb833f20c29cb6ee09ce18a2d6bfb5e6018420b79911ac5ddd156aaa60bcc2945d43d1788b6c5f753a16a84edd3200e0288b8df7fb001549f760876", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/751783ade618fde7a2e3a91881720caa9f3fec0c26071a601bb4ab97ffda139e97f134ad8629437cff70778c6cc5960f76225a9a765507ac60ed0bf0257e5bbc"} 15:39:52 INFO - Running post-run listener: copy_logs_to_upload_dir 15:39:52 INFO - Copying logs to upload dir... 15:39:52 INFO - mkdir: /builds/slave/test/build/upload/logs 15:39:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.172308 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-05 15:39:52.466404) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-05 15:39:52.470851) ========= 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.x8nscvPQC8/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.W2meH6lu9B/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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/Firefox/sha512/499742c0a179a4776b473a9843ad858323d4344c05472e852d16fa674391d237e0b962ff109330b0e561f1ad77fc4a331477f3de8195a522751e62d6a5b6018a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/361576e2efb833f20c29cb6ee09ce18a2d6bfb5e6018420b79911ac5ddd156aaa60bcc2945d43d1788b6c5f753a16a84edd3200e0288b8df7fb001549f760876", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/751783ade618fde7a2e3a91881720caa9f3fec0c26071a601bb4ab97ffda139e97f134ad8629437cff70778c6cc5960f76225a9a765507ac60ed0bf0257e5bbc"} build_url:https://queue.taskcluster.net/v1/task/FEXo1g-FR5m1XJvhKO1Nkw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011253 build_url: 'https://queue.taskcluster.net/v1/task/FEXo1g-FR5m1XJvhKO1Nkw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/499742c0a179a4776b473a9843ad858323d4344c05472e852d16fa674391d237e0b962ff109330b0e561f1ad77fc4a331477f3de8195a522751e62d6a5b6018a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/361576e2efb833f20c29cb6ee09ce18a2d6bfb5e6018420b79911ac5ddd156aaa60bcc2945d43d1788b6c5f753a16a84edd3200e0288b8df7fb001549f760876", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/751783ade618fde7a2e3a91881720caa9f3fec0c26071a601bb4ab97ffda139e97f134ad8629437cff70778c6cc5960f76225a9a765507ac60ed0bf0257e5bbc"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-05 15:39:52.509464) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 15:39:52.509901) ========= 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.x8nscvPQC8/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.W2meH6lu9B/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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.005195 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 15:39:52.560125) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-05 15:39:52.560891) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-05 15:39:52.561228) ========= ========= Total master_lag: 0.29 =========