builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0149 starttime: 1475861314.55 results: success (0) buildid: 20161007072801 builduid: be74475102aa4f1bac16b728d897dc09 revision: fec388445e7cfed221984024c18067f44b29fd3c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-07 10:28:34.549328) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-07 10:28:34.549836) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-07 10:28:34.550154) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-07 10:28:34.605275) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 10:28:34.605706) ========= 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.EQ4lfYSN61/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.8fRsz2aG6y/Listeners TMPDIR=/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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-10-07 10:28:34-- 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% 17.8M=0.001s 2016-10-07 10:28:35 (17.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.549235 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 10:28:35.179154) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 10:28:35.179536) ========= 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.EQ4lfYSN61/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.8fRsz2aG6y/Listeners TMPDIR=/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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.028577 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 10:28:35.228363) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-07 10:28:35.228760) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev fec388445e7cfed221984024c18067f44b29fd3c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev fec388445e7cfed221984024c18067f44b29fd3c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EQ4lfYSN61/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.8fRsz2aG6y/Listeners TMPDIR=/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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-10-07 10:28:35,302 truncating revision to first 12 chars 2016-10-07 10:28:35,302 Setting DEBUG logging. 2016-10-07 10:28:35,302 attempt 1/10 2016-10-07 10:28:35,302 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/fec388445e7c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-07 10:28:37,349 unpacking tar archive at: fx-team-fec388445e7c/testing/mozharness/ program finished with exit code 0 elapsedTime=2.291775 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-07 10:28:37.545843) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-07 10:28:37.546284) ========= script_repo_revision: fec388445e7cfed221984024c18067f44b29fd3c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-07 10:28:37.546790) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-07 10:28:37.547109) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-07 10:28:37.572471) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-07 10:28:37.572951) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EQ4lfYSN61/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.8fRsz2aG6y/Listeners TMPDIR=/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 10:28:37 INFO - MultiFileLogger online at 20161007 10:28:37 in /builds/slave/test 10:28:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 10:28:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:28:37 INFO - {'append_to_log': False, 10:28:37 INFO - 'base_work_dir': '/builds/slave/test', 10:28:37 INFO - 'blob_upload_branch': 'Fx-Team', 10:28:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:28:37 INFO - 'branch': 'Fx-Team', 10:28:37 INFO - 'buildbot_json_path': 'buildprops.json', 10:28:37 INFO - 'config_files': ('talos/mac_config.py',), 10:28:37 INFO - 'default_actions': ('clobber', 10:28:37 INFO - 'read-buildbot-config', 10:28:37 INFO - 'download-and-extract', 10:28:37 INFO - 'populate-webroot', 10:28:37 INFO - 'create-virtualenv', 10:28:37 INFO - 'install', 10:28:37 INFO - 'run-tests'), 10:28:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:28:37 INFO - 'download_minidump_stackwalk': True, 10:28:37 INFO - 'download_symbols': 'ondemand', 10:28:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:28:37 INFO - 'tooltool.py': '/tools/tooltool.py', 10:28:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:28:37 INFO - '/tools/misc-python/virtualenv.py')}, 10:28:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:28:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:28:37 INFO - 'installer_path': 'installer.exe', 10:28:37 INFO - 'log_level': 'info', 10:28:37 INFO - 'log_name': 'talos', 10:28:37 INFO - 'log_to_console': True, 10:28:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:28:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:28:37 INFO - 'opt_config_files': (), 10:28:37 INFO - 'pip_index': False, 10:28:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:28:37 INFO - 'cmd': ('bash', 10:28:37 INFO - '-c', 10:28:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:28:37 INFO - 'enabled': True, 10:28:37 INFO - 'halt_on_failure': False, 10:28:37 INFO - 'name': 'check_screen_resolution'},), 10:28:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:28:37 INFO - 'cmd': ('bash', 10:28:37 INFO - '-c', 10:28:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:28:37 INFO - 'enabled': True, 10:28:37 INFO - 'halt_on_failure': False, 10:28:37 INFO - 'name': 'check_screen_resolution'},), 10:28:37 INFO - 'run_cmd_checks_enabled': True, 10:28:37 INFO - 'sps_profile': False, 10:28:37 INFO - 'sps_profile_interval': 0, 10:28:37 INFO - 'suite': 'dromaeojs', 10:28:37 INFO - 'system_bits': '32', 10:28:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:28:37 INFO - 'title': 't-yosemite-r7-0149', 10:28:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:28:37 INFO - 'use_talos_json': True, 10:28:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:28:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:28:37 INFO - 'work_dir': 'build'} 10:28:37 INFO - [mozharness: 2016-10-07 17:28:37.774305Z] Running clobber step. 10:28:37 INFO - Running pre-action listener: _resource_record_pre_action 10:28:37 INFO - Running main action method: clobber 10:28:37 INFO - rmtree: /builds/slave/test/build 10:28:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:28:39 INFO - Running post-action listener: _resource_record_post_action 10:28:39 INFO - [mozharness: 2016-10-07 17:28:39.968864Z] Finished clobber step (success) 10:28:39 INFO - [mozharness: 2016-10-07 17:28:39.969009Z] Running read-buildbot-config step. 10:28:39 INFO - Running pre-action listener: _resource_record_pre_action 10:28:39 INFO - Running main action method: read_buildbot_config 10:28:39 INFO - Using buildbot properties: 10:28:39 INFO - { 10:28:39 INFO - "project": "", 10:28:39 INFO - "product": "firefox", 10:28:39 INFO - "script_repo_revision": "production", 10:28:39 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 10:28:39 INFO - "repository": "", 10:28:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 10:28:39 INFO - "buildid": "20161007072801", 10:28:39 INFO - "pgo_build": "False", 10:28:39 INFO - "basedir": "/builds/slave/test", 10:28:39 INFO - "buildnumber": 14, 10:28:39 INFO - "slavename": "t-yosemite-r7-0149", 10:28:39 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 10:28:39 INFO - "platform": "yosemite_r7", 10:28:39 INFO - "scheduler": "tests-fx-team-macosx64-talos", 10:28:39 INFO - "branch": "fx-team", 10:28:39 INFO - "repo_path": "integration/fx-team", 10:28:39 INFO - "revision": "fec388445e7cfed221984024c18067f44b29fd3c", 10:28:39 INFO - "stage_platform": "macosx64", 10:28:39 INFO - "builduid": "be74475102aa4f1bac16b728d897dc09", 10:28:39 INFO - "slavebuilddir": "test" 10:28:39 INFO - } 10:28:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/Hv6xpYxVQ36GDDSB25jGJA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 10:28:39 INFO - Running post-action listener: _resource_record_post_action 10:28:39 INFO - [mozharness: 2016-10-07 17:28:39.972306Z] Finished read-buildbot-config step (success) 10:28:39 INFO - [mozharness: 2016-10-07 17:28:39.972412Z] Running download-and-extract step. 10:28:39 INFO - Running pre-action listener: _resource_record_pre_action 10:28:39 INFO - Running main action method: download_and_extract 10:28:39 INFO - mkdir: /builds/slave/test/build/tests 10:28:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:28:39 INFO - trying https://queue.taskcluster.net/v1/task/Hv6xpYxVQ36GDDSB25jGJA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 10:28:39 INFO - Downloading https://queue.taskcluster.net/v1/task/Hv6xpYxVQ36GDDSB25jGJA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 10:28:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hv6xpYxVQ36GDDSB25jGJA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 10:28:40 INFO - Downloaded 1199 bytes. 10:28:40 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 10:28:40 INFO - Using the following test package requirements: 10:28:40 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 10:28:40 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:28:40 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 10:28:40 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:28:40 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 10:28:40 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:28:40 INFO - u'jsshell-mac.zip'], 10:28:40 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:28:40 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 10:28:40 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 10:28:40 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:28:40 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 10:28:40 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:28:40 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 10:28:40 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:28:40 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 10:28:40 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:28:40 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 10:28:40 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:28:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hv6xpYxVQ36GDDSB25jGJA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 10:28:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hv6xpYxVQ36GDDSB25jGJA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:28:40 INFO - Fetch https://queue.taskcluster.net/v1/task/Hv6xpYxVQ36GDDSB25jGJA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 10:28:41 INFO - Http code: 200 10:28:41 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 10:28:41 INFO - x-amz-cf-id: QW4y7fLWVXJ0I-ygGHAezsRFo5sUznYA3VZ47lXvcG81T30bpzSAjA== 10:28:41 INFO - x-amz-version-id: k9OmMbZT8hpDs.i3dlDA7r9iYU.Why6g 10:28:41 INFO - Expected file size: 16483295 10:28:41 INFO - Obtained file size: 16483295 10:28:48 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:28:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hv6xpYxVQ36GDDSB25jGJA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 10:28:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hv6xpYxVQ36GDDSB25jGJA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:28:48 INFO - Fetch https://queue.taskcluster.net/v1/task/Hv6xpYxVQ36GDDSB25jGJA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 10:28:49 INFO - Http code: 200 10:28:49 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 10:28:49 INFO - x-amz-cf-id: 9CfGCHmwWJ5vUU3jDfRGKW1aC7fQlBpDrGqrvHaL3TIpVASHTD-VCA== 10:28:49 INFO - x-amz-version-id: k9OmMbZT8hpDs.i3dlDA7r9iYU.Why6g 10:28:49 INFO - Expected file size: 16483295 10:28:49 INFO - Obtained file size: 16483295 10:28:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hv6xpYxVQ36GDDSB25jGJA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 10:28:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hv6xpYxVQ36GDDSB25jGJA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:28:55 INFO - Fetch https://queue.taskcluster.net/v1/task/Hv6xpYxVQ36GDDSB25jGJA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 10:28:56 INFO - Http code: 200 10:28:56 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 10:28:56 INFO - x-amz-cf-id: hE6r-sBZnxqgCgXm_zwQy2hLhpEFSxRV9xiGjcHji3inZFMCu6PxNA== 10:28:56 INFO - x-amz-version-id: kWt58TCzNWwKviTID1oxrPvk5Vb3bq.x 10:28:57 INFO - Expected file size: 14409006 10:28:57 INFO - Obtained file size: 14409006 10:28: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')]} 10:28:57 INFO - trying https://queue.taskcluster.net/v1/task/Hv6xpYxVQ36GDDSB25jGJA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 10:28:57 INFO - Downloading https://queue.taskcluster.net/v1/task/Hv6xpYxVQ36GDDSB25jGJA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 10:28:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hv6xpYxVQ36GDDSB25jGJA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 10:29:00 INFO - Downloaded 111638692 bytes. 10:29:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Hv6xpYxVQ36GDDSB25jGJA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 10:29:00 INFO - mkdir: /builds/slave/test/properties 10:29:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:29:00 INFO - Writing to file /builds/slave/test/properties/build_url 10:29:00 INFO - Contents: 10:29:00 INFO - build_url:https://queue.taskcluster.net/v1/task/Hv6xpYxVQ36GDDSB25jGJA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 10:29:00 INFO - Running post-action listener: _resource_record_post_action 10:29:00 INFO - Running post-action listener: set_extra_try_arguments 10:29:00 INFO - [mozharness: 2016-10-07 17:29:00.790597Z] Finished download-and-extract step (success) 10:29:00 INFO - [mozharness: 2016-10-07 17:29:00.790751Z] Running populate-webroot step. 10:29:00 INFO - Running pre-action listener: _resource_record_pre_action 10:29:00 INFO - Running main action method: populate_webroot 10:29:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:29:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:29:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:29:00 INFO - '--tppagecycles', 10:29:00 INFO - '7'], 10:29:00 INFO - 'tests': ['tsvgm']}}, 10:29:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:29:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:29:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:29:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:29:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:29:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:29:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:29:00 INFO - 'talos_options': ['--disable-e10s'], 10:29:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:29:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:29:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:29:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:29:00 INFO - 'talos_options': ['--disable-e10s'], 10:29:00 INFO - 'tests': ['damp', 'tps']}, 10:29:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:29:00 INFO - 'tests': ['damp', 'tps']}, 10:29:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:29:00 INFO - 'tests': ['dromaeo_dom']}, 10:29:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:29:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:29:00 INFO - 'tests': ['basic_compositor_video']}, 10:29:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:29:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:29:00 INFO - 'tests': ['a11yr', 10:29:00 INFO - 'ts_paint', 10:29:00 INFO - 'tpaint', 10:29:00 INFO - 'sessionrestore', 10:29:00 INFO - 'sessionrestore_no_auto_restore', 10:29:00 INFO - 'tabpaint']}, 10:29:00 INFO - 'other-e10s': {'tests': ['a11yr', 10:29:00 INFO - 'ts_paint', 10:29:00 INFO - 'tpaint', 10:29:00 INFO - 'sessionrestore', 10:29:00 INFO - 'sessionrestore_no_auto_restore', 10:29:00 INFO - 'tabpaint']}, 10:29:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:29:00 INFO - 'ts_paint', 10:29:00 INFO - 'tpaint', 10:29:00 INFO - 'sessionrestore', 10:29:00 INFO - 'sessionrestore_no_auto_restore', 10:29:00 INFO - 'tabpaint']}, 10:29:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:29:00 INFO - 'ts_paint', 10:29:00 INFO - 'tpaint', 10:29:00 INFO - 'sessionrestore', 10:29:00 INFO - 'sessionrestore_no_auto_restore', 10:29:00 INFO - 'tabpaint']}, 10:29:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:29:00 INFO - 'tests': ['a11yr', 10:29:00 INFO - 'ts_paint', 10:29:00 INFO - 'tpaint', 10:29:00 INFO - 'sessionrestore', 10:29:00 INFO - 'sessionrestore_no_auto_restore', 10:29:00 INFO - 'tabpaint']}, 10:29:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:29:00 INFO - 'tests': ['a11yr', 10:29:00 INFO - 'ts_paint', 10:29:00 INFO - 'tpaint', 10:29:00 INFO - 'sessionrestore', 10:29:00 INFO - 'sessionrestore_no_auto_restore', 10:29:00 INFO - 'tabpaint']}, 10:29:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:29:00 INFO - 'tests': ['tsvgx', 10:29:00 INFO - 'tsvgr_opacity', 10:29:00 INFO - 'tart', 10:29:00 INFO - 'tscrollx', 10:29:00 INFO - 'cart']}, 10:29:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:29:00 INFO - 'tsvgr_opacity', 10:29:00 INFO - 'tart', 10:29:00 INFO - 'tscrollx', 10:29:00 INFO - 'cart']}, 10:29:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:29:00 INFO - 'talos_options': ['--disable-e10s'], 10:29:00 INFO - 'tests': ['tp5o']}, 10:29:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:29:00 INFO - 'tests': ['tp5o']}, 10:29:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:29:00 INFO - 'talos_options': ['--disable-e10s', 10:29:00 INFO - '--xperf_path', 10:29:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:29:00 INFO - 'tests': ['tp5n']}, 10:29:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:29:00 INFO - 'talos_options': ['--xperf_path', 10:29:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:29:00 INFO - 'tests': ['tp5n']}}, 10:29:00 INFO - 'talos.zip': {'path': '', 10:29:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:29:00 INFO - Running post-action listener: _resource_record_post_action 10:29:00 INFO - [mozharness: 2016-10-07 17:29:00.809563Z] Finished populate-webroot step (success) 10:29:00 INFO - [mozharness: 2016-10-07 17:29:00.809651Z] Running create-virtualenv step. 10:29:00 INFO - Running pre-action listener: _resource_record_pre_action 10:29:00 INFO - Running main action method: create_virtualenv 10:29:00 INFO - Creating virtualenv /builds/slave/test/build/venv 10:29:00 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 10:29:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:29:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:29:00 INFO - Using real prefix '/tools/python27' 10:29:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:29:01 INFO - Installing distribute.............................................................................................................................................................................................done. 10:29:04 INFO - Installing pip.................done. 10:29:04 INFO - Return code: 0 10:29:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:29:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:29:04 INFO - Reading from file tmpfile_stdout 10:29:04 INFO - Output received: 10:29:04 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:29:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:29: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')]} 10:29:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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')]} 10:29:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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 0x10b943490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8b9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3d34843c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b757d30>, '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 0x10b57ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b74fb20>, '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.EQ4lfYSN61/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.8fRsz2aG6y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 10:29: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 10:29: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 10:29:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EQ4lfYSN61/Render', 10:29:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:04 INFO - 'HOME': '/Users/cltbld', 10:29:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:04 INFO - 'LOGNAME': 'cltbld', 10:29:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:04 INFO - 'MOZ_NO_REMOTE': '1', 10:29:04 INFO - 'NO_EM_RESTART': '1', 10:29:04 INFO - 'PAGER': '/bin/cat', 10:29:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:04 INFO - 'PWD': '/builds/slave/test', 10:29:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:04 INFO - 'SHELL': '/bin/bash', 10:29:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8fRsz2aG6y/Listeners', 10:29:04 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 10:29:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:04 INFO - 'USER': 'cltbld', 10:29:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:04 INFO - 'XPC_FLAGS': '0x0', 10:29:04 INFO - 'XPC_SERVICE_NAME': '0', 10:29:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29: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 10:29:05 INFO - Cleaning up... 10:29:05 INFO - Return code: 0 10:29:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:29: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')]} 10:29:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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')]} 10:29:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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 0x10b943490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8b9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3d34843c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b757d30>, '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 0x10b57ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b74fb20>, '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.EQ4lfYSN61/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.8fRsz2aG6y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 10:29: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 10:29: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 10:29:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EQ4lfYSN61/Render', 10:29:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:05 INFO - 'HOME': '/Users/cltbld', 10:29:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:05 INFO - 'LOGNAME': 'cltbld', 10:29:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:05 INFO - 'MOZ_NO_REMOTE': '1', 10:29:05 INFO - 'NO_EM_RESTART': '1', 10:29:05 INFO - 'PAGER': '/bin/cat', 10:29:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:05 INFO - 'PWD': '/builds/slave/test', 10:29:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:05 INFO - 'SHELL': '/bin/bash', 10:29:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8fRsz2aG6y/Listeners', 10:29:05 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 10:29:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:05 INFO - 'USER': 'cltbld', 10:29:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:05 INFO - 'XPC_FLAGS': '0x0', 10:29:05 INFO - 'XPC_SERVICE_NAME': '0', 10:29:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:05 INFO - Downloading/unpacking psutil>=3.1.1 10:29: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 10:29: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 10:29: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 10:29: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 10:29: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 10:29: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 10:29:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:29:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:29:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:29:09 INFO - Installing collected packages: psutil 10:29:09 INFO - Running setup.py install for psutil 10:29:09 INFO - building 'psutil._psutil_osx' extension 10:29: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 10:29: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 10:29: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 10:29: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 10:29:10 INFO - building 'psutil._psutil_posix' extension 10:29:10 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 10:29:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:29:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:29:10 INFO - ^ 10:29:10 INFO - 1 warning generated. 10:29:10 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 10:29:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:29:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:29:10 INFO - Successfully installed psutil 10:29:10 INFO - Cleaning up... 10:29:10 INFO - Return code: 0 10:29:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:29: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')]} 10:29:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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')]} 10:29:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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 0x10b943490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8b9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3d34843c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b757d30>, '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 0x10b57ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b74fb20>, '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.EQ4lfYSN61/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.8fRsz2aG6y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 10:29: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 10:29: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 10:29:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EQ4lfYSN61/Render', 10:29:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:10 INFO - 'HOME': '/Users/cltbld', 10:29:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:10 INFO - 'LOGNAME': 'cltbld', 10:29:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:10 INFO - 'MOZ_NO_REMOTE': '1', 10:29:10 INFO - 'NO_EM_RESTART': '1', 10:29:10 INFO - 'PAGER': '/bin/cat', 10:29:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:10 INFO - 'PWD': '/builds/slave/test', 10:29:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:10 INFO - 'SHELL': '/bin/bash', 10:29:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8fRsz2aG6y/Listeners', 10:29:10 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 10:29:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:10 INFO - 'USER': 'cltbld', 10:29:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:10 INFO - 'XPC_FLAGS': '0x0', 10:29:10 INFO - 'XPC_SERVICE_NAME': '0', 10:29:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:29: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 10:29: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 10:29: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 10:29: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 10:29: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 10:29: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 10:29:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:29:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:29:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:29:13 INFO - Installing collected packages: mozsystemmonitor 10:29:13 INFO - Running setup.py install for mozsystemmonitor 10:29:13 INFO - Successfully installed mozsystemmonitor 10:29:13 INFO - Cleaning up... 10:29:13 INFO - Return code: 0 10:29:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:29: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')]} 10:29:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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')]} 10:29:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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 0x10b943490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8b9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3d34843c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b757d30>, '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 0x10b57ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b74fb20>, '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.EQ4lfYSN61/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.8fRsz2aG6y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 10:29: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 10:29: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 10:29:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EQ4lfYSN61/Render', 10:29:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:13 INFO - 'HOME': '/Users/cltbld', 10:29:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:13 INFO - 'LOGNAME': 'cltbld', 10:29:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:13 INFO - 'MOZ_NO_REMOTE': '1', 10:29:13 INFO - 'NO_EM_RESTART': '1', 10:29:13 INFO - 'PAGER': '/bin/cat', 10:29:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:13 INFO - 'PWD': '/builds/slave/test', 10:29:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:13 INFO - 'SHELL': '/bin/bash', 10:29:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8fRsz2aG6y/Listeners', 10:29:13 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 10:29:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:13 INFO - 'USER': 'cltbld', 10:29:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:13 INFO - 'XPC_FLAGS': '0x0', 10:29:13 INFO - 'XPC_SERVICE_NAME': '0', 10:29:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:13 INFO - Downloading/unpacking jsonschema==2.5.1 10:29: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 10:29: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 10:29: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 10:29: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 10:29: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 10:29: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 10:29:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:29:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:29: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 10:29: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 10:29: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 10:29: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 10:29: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 10:29: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 10:29:16 INFO - Downloading functools32-3.2.3-2.tar.gz 10:29:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:29:16 INFO - warning: no files found matching '*.txt' 10:29:16 INFO - no previously-included directories found matching 'build' 10:29:16 INFO - no previously-included directories found matching 'dist' 10:29:16 INFO - no previously-included directories found matching '.git*' 10:29:16 INFO - Installing collected packages: jsonschema, functools32 10:29:16 INFO - Running setup.py install for functools32 10:29:16 INFO - warning: no files found matching '*.txt' 10:29:16 INFO - no previously-included directories found matching 'build' 10:29:16 INFO - no previously-included directories found matching 'dist' 10:29:16 INFO - no previously-included directories found matching '.git*' 10:29:16 INFO - Successfully installed jsonschema functools32 10:29:16 INFO - Cleaning up... 10:29:16 INFO - Return code: 0 10:29:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:29: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')]} 10:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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')]} 10:29:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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 0x10b943490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8b9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3d34843c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b757d30>, '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 0x10b57ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b74fb20>, '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.EQ4lfYSN61/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.8fRsz2aG6y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 10:29: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 10:29: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 10:29:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EQ4lfYSN61/Render', 10:29:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:16 INFO - 'HOME': '/Users/cltbld', 10:29:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:16 INFO - 'LOGNAME': 'cltbld', 10:29:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:16 INFO - 'MOZ_NO_REMOTE': '1', 10:29:16 INFO - 'NO_EM_RESTART': '1', 10:29:16 INFO - 'PAGER': '/bin/cat', 10:29:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:16 INFO - 'PWD': '/builds/slave/test', 10:29:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:16 INFO - 'SHELL': '/bin/bash', 10:29:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8fRsz2aG6y/Listeners', 10:29:16 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 10:29:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:16 INFO - 'USER': 'cltbld', 10:29:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:16 INFO - 'XPC_FLAGS': '0x0', 10:29:16 INFO - 'XPC_SERVICE_NAME': '0', 10:29:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:29:16 INFO - Cleaning up... 10:29:16 INFO - Return code: 0 10:29:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:29: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')]} 10:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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')]} 10:29:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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 0x10b943490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8b9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3d34843c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b757d30>, '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 0x10b57ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b74fb20>, '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.EQ4lfYSN61/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.8fRsz2aG6y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 10:29: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 10:29: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 10:29:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EQ4lfYSN61/Render', 10:29:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:16 INFO - 'HOME': '/Users/cltbld', 10:29:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:16 INFO - 'LOGNAME': 'cltbld', 10:29:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:16 INFO - 'MOZ_NO_REMOTE': '1', 10:29:16 INFO - 'NO_EM_RESTART': '1', 10:29:16 INFO - 'PAGER': '/bin/cat', 10:29:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:16 INFO - 'PWD': '/builds/slave/test', 10:29:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:16 INFO - 'SHELL': '/bin/bash', 10:29:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8fRsz2aG6y/Listeners', 10:29:16 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 10:29:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:16 INFO - 'USER': 'cltbld', 10:29:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:16 INFO - 'XPC_FLAGS': '0x0', 10:29:16 INFO - 'XPC_SERVICE_NAME': '0', 10:29:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:17 INFO - Downloading/unpacking blobuploader==1.2.4 10:29: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 10:29: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 10:29: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 10:29: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 10:29: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 10:29: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 10:29:19 INFO - Downloading blobuploader-1.2.4.tar.gz 10:29:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:29:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:29: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 10:29: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 10:29: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 10:29: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 10:29: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 10:29: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 10:29:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:29:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:29: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 10:29: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 10:29: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 10:29: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 10:29: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 10:29: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 10:29:20 INFO - Downloading docopt-0.6.1.tar.gz 10:29:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:29:20 INFO - Installing collected packages: blobuploader, requests, docopt 10:29:20 INFO - Running setup.py install for blobuploader 10:29:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:29:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:29:21 INFO - Running setup.py install for requests 10:29:21 INFO - Running setup.py install for docopt 10:29:21 INFO - Successfully installed blobuploader requests docopt 10:29:21 INFO - Cleaning up... 10:29:21 INFO - Return code: 0 10:29:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:29: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')]} 10:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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')]} 10:29:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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 0x10b943490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8b9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3d34843c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b757d30>, '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 0x10b57ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b74fb20>, '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.EQ4lfYSN61/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.8fRsz2aG6y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 10:29: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 10:29: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 10:29:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EQ4lfYSN61/Render', 10:29:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:21 INFO - 'HOME': '/Users/cltbld', 10:29:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:21 INFO - 'LOGNAME': 'cltbld', 10:29:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:21 INFO - 'MOZ_NO_REMOTE': '1', 10:29:21 INFO - 'NO_EM_RESTART': '1', 10:29:21 INFO - 'PAGER': '/bin/cat', 10:29:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:21 INFO - 'PWD': '/builds/slave/test', 10:29:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:21 INFO - 'SHELL': '/bin/bash', 10:29:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8fRsz2aG6y/Listeners', 10:29:21 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 10:29:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:21 INFO - 'USER': 'cltbld', 10:29:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:21 INFO - 'XPC_FLAGS': '0x0', 10:29:21 INFO - 'XPC_SERVICE_NAME': '0', 10:29:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:29:22 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-5FodJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:29:22 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-jIyFXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:29:22 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-Wp8h0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:29:22 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-OK0gD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:29:22 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-Cg1Tz_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:29:22 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-yV3Jds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:29:22 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-TI3PTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:29:22 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-Lrft87-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:29:22 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-VwgqRp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:29:22 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-d0AwKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:29:23 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-hmuX9q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:29:23 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-ovImYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:29:23 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-MkwznI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:29:23 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-OgtHOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:29:23 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-1JWhfM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:29:23 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-WPqGqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:29:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:29:23 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-H7zcqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:29:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:29:23 INFO - Running setup.py install for manifestparser 10:29:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:29:23 INFO - Running setup.py install for mozcrash 10:29:24 INFO - Running setup.py install for mozdebug 10:29:24 INFO - Running setup.py install for mozdevice 10:29:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:29:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:29:24 INFO - Running setup.py install for mozfile 10:29:24 INFO - Running setup.py install for mozhttpd 10:29:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:29:24 INFO - Running setup.py install for mozinfo 10:29:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:29:24 INFO - Running setup.py install for mozInstall 10:29:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:29:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:29:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:29:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:29:24 INFO - Running setup.py install for mozleak 10:29:25 INFO - Running setup.py install for mozlog 10:29:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:29:25 INFO - Running setup.py install for moznetwork 10:29:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:29:25 INFO - Running setup.py install for mozprocess 10:29:25 INFO - Running setup.py install for mozprofile 10:29:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:29:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:29:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:29:25 INFO - Running setup.py install for mozrunner 10:29:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:29:25 INFO - Running setup.py install for mozscreenshot 10:29:26 INFO - Running setup.py install for moztest 10:29:26 INFO - Running setup.py install for mozversion 10:29:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:29:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:29:26 INFO - Cleaning up... 10:29:26 INFO - Return code: 0 10:29:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:29: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')]} 10:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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')]} 10:29:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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 0x10b943490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8b9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3d34843c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b757d30>, '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 0x10b57ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b74fb20>, '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.EQ4lfYSN61/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.8fRsz2aG6y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 10:29: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 10:29: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 10:29:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EQ4lfYSN61/Render', 10:29:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:26 INFO - 'HOME': '/Users/cltbld', 10:29:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:26 INFO - 'LOGNAME': 'cltbld', 10:29:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:26 INFO - 'MOZ_NO_REMOTE': '1', 10:29:26 INFO - 'NO_EM_RESTART': '1', 10:29:26 INFO - 'PAGER': '/bin/cat', 10:29:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:26 INFO - 'PWD': '/builds/slave/test', 10:29:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:26 INFO - 'SHELL': '/bin/bash', 10:29:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8fRsz2aG6y/Listeners', 10:29:26 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 10:29:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:26 INFO - 'USER': 'cltbld', 10:29:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:26 INFO - 'XPC_FLAGS': '0x0', 10:29:26 INFO - 'XPC_SERVICE_NAME': '0', 10:29:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:29:26 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-H1X7XE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:29: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)) 10:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:29:26 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-ZxYQPg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:29: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)) 10:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:29:26 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-QAwORB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:29:26 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)) 10:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:29:26 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-aaZoxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:29: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)) 10:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:29:27 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-d868I7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:29: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)) 10:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:29:27 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-V0I2zu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:29: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)) 10:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:29:27 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-drNVLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:29: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)) 10:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:29:27 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-qU7OEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:29: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)) 10:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:29:27 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-Cog5mB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:29: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)) 10:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:29:27 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-r5xQd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:29:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 10:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:29:27 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-bn6rsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:29: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)) 10:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:29:27 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-feMnW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:29: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)) 10:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:29:27 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-X7oPtk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:29: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)) 10:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:29:28 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-x0ScLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:29: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)) 10:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:29:28 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-3Clu0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:29: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)) 10:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:29:28 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-mzivjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:29: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)) 10:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:29:28 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-eLb9Og-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:29: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)) 10:29: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)) 10:29: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)) 10:29: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)) 10:29: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)) 10:29: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)) 10:29:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:29: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 10:29: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 10:29: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 10:29: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 10:29: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 10:29: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 10:29:30 INFO - Downloading blessings-1.6.tar.gz 10:29:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:29:30 INFO - Installing collected packages: blessings 10:29:30 INFO - Running setup.py install for blessings 10:29:31 INFO - Successfully installed blessings 10:29:31 INFO - Cleaning up... 10:29:31 INFO - Return code: 0 10:29:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:29:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:29:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:29:31 INFO - Reading from file tmpfile_stdout 10:29:31 INFO - Current package versions: 10:29:31 INFO - blessings == 1.6 10:29:31 INFO - blobuploader == 1.2.4 10:29:31 INFO - docopt == 0.6.1 10:29:31 INFO - functools32 == 3.2.3-2 10:29:31 INFO - jsonschema == 2.5.1 10:29:31 INFO - manifestparser == 1.1 10:29:31 INFO - mozInstall == 1.12 10:29:31 INFO - mozcrash == 0.17 10:29:31 INFO - mozdebug == 0.1 10:29:31 INFO - mozdevice == 0.48 10:29:31 INFO - mozfile == 1.2 10:29:31 INFO - mozhttpd == 0.7 10:29:31 INFO - mozinfo == 0.9 10:29:31 INFO - mozleak == 0.1 10:29:31 INFO - mozlog == 3.4 10:29:31 INFO - moznetwork == 0.27 10:29:31 INFO - mozprocess == 0.23 10:29:31 INFO - mozprofile == 0.28 10:29:31 INFO - mozrunner == 6.12 10:29:31 INFO - mozscreenshot == 0.1 10:29:31 INFO - mozsystemmonitor == 0.3 10:29:31 INFO - moztest == 0.7 10:29:31 INFO - mozversion == 1.4 10:29:31 INFO - psutil == 3.1.1 10:29:31 INFO - requests == 1.2.3 10:29:31 INFO - wsgiref == 0.1.2 10:29:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:29: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')]} 10:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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')]} 10:29:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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 0x10b943490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8b9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3d34843c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b757d30>, '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 0x10b57ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b74fb20>, '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.EQ4lfYSN61/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.8fRsz2aG6y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 10:29: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 10:29: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 10:29:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EQ4lfYSN61/Render', 10:29:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:31 INFO - 'HOME': '/Users/cltbld', 10:29:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:31 INFO - 'LOGNAME': 'cltbld', 10:29:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:31 INFO - 'MOZ_NO_REMOTE': '1', 10:29:31 INFO - 'NO_EM_RESTART': '1', 10:29:31 INFO - 'PAGER': '/bin/cat', 10:29:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:31 INFO - 'PWD': '/builds/slave/test', 10:29:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:31 INFO - 'SHELL': '/bin/bash', 10:29:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8fRsz2aG6y/Listeners', 10:29:31 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 10:29:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:31 INFO - 'USER': 'cltbld', 10:29:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:31 INFO - 'XPC_FLAGS': '0x0', 10:29:31 INFO - 'XPC_SERVICE_NAME': '0', 10:29:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29: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)) 10:29: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)) 10:29: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)) 10:29: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)) 10:29: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)) 10:29: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)) 10:29: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)) 10:29: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)) 10:29: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)) 10:29: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)) 10:29: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)) 10:29:31 INFO - Cleaning up... 10:29:31 INFO - Return code: 0 10:29:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:29: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')]} 10:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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')]} 10:29:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29: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 0x10b943490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8b9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3d34843c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b757d30>, '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 0x10b57ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b74fb20>, '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.EQ4lfYSN61/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.8fRsz2aG6y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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 10:29: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 10:29: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 10:29:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EQ4lfYSN61/Render', 10:29:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:31 INFO - 'HOME': '/Users/cltbld', 10:29:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:31 INFO - 'LOGNAME': 'cltbld', 10:29:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:31 INFO - 'MOZ_NO_REMOTE': '1', 10:29:31 INFO - 'NO_EM_RESTART': '1', 10:29:31 INFO - 'PAGER': '/bin/cat', 10:29:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:31 INFO - 'PWD': '/builds/slave/test', 10:29:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:31 INFO - 'SHELL': '/bin/bash', 10:29:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8fRsz2aG6y/Listeners', 10:29:31 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 10:29:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:31 INFO - 'USER': 'cltbld', 10:29:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:31 INFO - 'XPC_FLAGS': '0x0', 10:29:31 INFO - 'XPC_SERVICE_NAME': '0', 10:29:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:29:32 INFO - Cleaning up... 10:29:32 INFO - Return code: 0 10:29:32 INFO - Running post-action listener: _resource_record_post_action 10:29:32 INFO - Running post-action listener: _start_resource_monitoring 10:29:32 INFO - Starting resource monitoring. 10:29:32 INFO - [mozharness: 2016-10-07 17:29:32.062101Z] Finished create-virtualenv step (success) 10:29:32 INFO - [mozharness: 2016-10-07 17:29:32.063185Z] Running install step. 10:29:32 INFO - Running pre-action listener: _resource_record_pre_action 10:29:32 INFO - Running main action method: install 10:29:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:29:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:29:32 INFO - Reading from file tmpfile_stdout 10:29:32 INFO - Detecting whether we're running mozinstall >=1.0... 10:29:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:29:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:29:32 INFO - Reading from file tmpfile_stdout 10:29:32 INFO - Output received: 10:29:32 INFO - Usage: mozinstall [options] installer 10:29:32 INFO - Options: 10:29:32 INFO - -h, --help show this help message and exit 10:29:32 INFO - -d DEST, --destination=DEST 10:29:32 INFO - Directory to install application into. [default: 10:29:32 INFO - "/builds/slave/test"] 10:29:32 INFO - --app=APP Application being installed. [default: firefox] 10:29:32 INFO - mkdir: /builds/slave/test/build/application 10:29:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:29:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:30:06 INFO - Reading from file tmpfile_stdout 10:30:06 INFO - Output received: 10:30:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:30:06 INFO - Running post-action listener: _resource_record_post_action 10:30:06 INFO - [mozharness: 2016-10-07 17:30:06.513341Z] Finished install step (success) 10:30:06 INFO - [mozharness: 2016-10-07 17:30:06.513466Z] Running run-tests step. 10:30:06 INFO - Running pre-action listener: _resource_record_pre_action 10:30:06 INFO - Running main action method: run_tests 10:30:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:30:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:30:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:30:06 INFO - 2016-10-07 10:30:06.528 screenresolution[1648:7840] starting screenresolution argv=screenresolution get 10:30:06 INFO - 2016-10-07 10:30:06.530 screenresolution[1648:7840] Display 0: 1600x1200x32@60 10:30:06 INFO - 2016-10-07 10:30:06.536 screenresolution[1649:7842] starting screenresolution argv=screenresolution list 10:30:06 INFO - Available Modes on Display 0 10:30:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:30:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:30:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:30:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:30:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:30:06 INFO - Intel Iris: 10:30:06 INFO - Chipset Model: Intel Iris 10:30:06 INFO - Type: GPU 10:30:06 INFO - Bus: Built-In 10:30:06 INFO - VRAM (Dynamic, Max): 1536 MB 10:30:06 INFO - Vendor: Intel (0x8086) 10:30:06 INFO - Device ID: 0x0a2e 10:30:06 INFO - Revision ID: 0x0009 10:30:06 INFO - Displays: 10:30:06 INFO - Display: 10:30:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:30:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:30:06 INFO - Display Serial Number: 1600x1200 60 10:30:06 INFO - Main Display: Yes 10:30:06 INFO - Mirror: Off 10:30:06 INFO - Online: Yes 10:30:06 INFO - Rotation: Supported 10:30:06 INFO - Return code: 0 10:30:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:30:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:30:06 INFO - Python 2.7.3 10:30:06 INFO - Return code: 0 10:30:06 INFO - grabbing minidump binary from tooltool 10:30: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')]} 10:30: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 0x10b757d30>, '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 0x10b57ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b74fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:30: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 10:30: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 10:30: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 10:30:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:30:06 INFO - Return code: 0 10:30:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:30:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:30:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:30:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:30:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:30:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Hv6xpYxVQ36GDDSB25jGJA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0149', '--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 10:30:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Hv6xpYxVQ36GDDSB25jGJA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0149 --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 10:30:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EQ4lfYSN61/Render', 10:30:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:06 INFO - 'HOME': '/Users/cltbld', 10:30:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:06 INFO - 'LOGNAME': 'cltbld', 10:30:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:30:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:30:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:06 INFO - 'MOZ_NO_REMOTE': '1', 10:30:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:30:06 INFO - 'NO_EM_RESTART': '1', 10:30:06 INFO - 'PAGER': '/bin/cat', 10:30:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:06 INFO - 'PWD': '/builds/slave/test', 10:30:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:30:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:06 INFO - 'SHELL': '/bin/bash', 10:30:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8fRsz2aG6y/Listeners', 10:30:06 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 10:30:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:06 INFO - 'USER': 'cltbld', 10:30:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:06 INFO - 'XPC_FLAGS': '0x0', 10:30:06 INFO - 'XPC_SERVICE_NAME': '0', 10:30:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Hv6xpYxVQ36GDDSB25jGJA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0149', '--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 10:30:07 INFO - mozversion application_buildid: 20161007072801 10:30:07 INFO - mozversion application_changeset: fec388445e7cfed221984024c18067f44b29fd3c 10:30:07 INFO - mozversion application_display_name: Nightly 10:30:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:30:07 INFO - mozversion application_name: Firefox 10:30:07 INFO - mozversion application_remotingname: firefox 10:30:07 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 10:30:07 INFO - mozversion application_vendor: Mozilla 10:30:07 INFO - mozversion application_version: 52.0a1 10:30:07 INFO - mozversion platform_buildid: 20161007072801 10:30:07 INFO - mozversion platform_changeset: fec388445e7cfed221984024c18067f44b29fd3c 10:30:07 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 10:30:07 INFO - mozversion platform_version: 52.0a1 10:30:07 INFO - using testdate: 1475861407 10:30:07 INFO - actual date: 1475861407 10:30:07 INFO - starting webserver on 'localhost:49275' 10:30:07 INFO - SUITE-START | Running 2 tests 10:30:07 INFO - TEST-START | dromaeo_css 10:30:07 INFO - operating with platform_type : mac_ 10:30:07 INFO - Initialising browser for dromaeo_css test... 10:30:07 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/tmpeu_7kY/profile http://localhost:49275/getInfo.html) 10:30:09 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 10:30:09 INFO - PROCESS | 1659 | colorDepth:24 10:30:09 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 10:30:09 INFO - PROCESS | 1659 | __metrics 10:30:09 INFO - PROCESS | 1659 | 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" 10:30:09 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:30:10 INFO - TEST-INFO | 1659: exit 0 10:30:10 INFO - Browser initialized. 10:30:10 INFO - Running cycle 1/1 for dromaeo_css test... 10:30:10 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/tmpeu_7kY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:30:22 INFO - PROCESS | 1662 | RSS: Main: 235257856 10:30:22 INFO - PROCESS | 1662 | 10:31:13 INFO - PROCESS | 1662 | 1475861473692 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] 10:33:27 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 10:33:28 INFO - PROCESS | 1662 | RSS: Main: 361349120 10:33:28 INFO - PROCESS | 1662 | 10:36:31 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 10:36:32 INFO - PROCESS | 1662 | RSS: Main: 376610816 10:36:32 INFO - PROCESS | 1662 | 10:39:34 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 10:39:34 INFO - PROCESS | 1662 | RSS: Main: 338423808 10:39:34 INFO - PROCESS | 1662 | 10:42:37 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 10:42:37 INFO - PROCESS | 1662 | RSS: Main: 340582400 10:42:37 INFO - PROCESS | 1662 | 10:45:41 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 10:45:41 INFO - PROCESS | 1662 | RSS: Main: 325500928 10:45:41 INFO - PROCESS | 1662 | 10:48:45 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 10:48:45 INFO - PROCESS | 1662 | RSS: Main: 405712896 10:48:45 INFO - PROCESS | 1662 | 10:48:45 INFO - PROCESS | 1662 | __start_tp_report 10:48:45 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 10:48:45 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 10:48:45 INFO - PROCESS | 1662 | |i|pagename|runs| 10:48:45 INFO - PROCESS | 1662 | |0;dojo.html;3267.1668724475257;3282.524034015068;3306.4048471275178;3340.665933406658;3356.4294070008095;5570.442955704428;5682.687452190138;5712.628679135853;5771.711414429277;5809.883802323956;5599.496045355917;5661.264035675366;5662.688552129631;5693.402192769751;5713.257276554053;5106.591472682177;5123.436421993578;5128.820491282806;5191.948080519184;5207.244949482323;5294.708791016493;5294.973525132388;5304.283921670563;5310.522053015228;5361.51746342829;5100.387953445573;5263.605229607795;5272.973635131838;5283.154695248761;5344.91982620261;5133.666311689765;5144.099782538041;5213.53078222958;5231.450697676765;5373.516383525502;5125.179971204627;5128.076946149691;5131.871703207413;5150.690958542499;5170.327857378555;5710.200571919893;5755.251817263771;5778.855528611818;5781.508571771405;5783.797567085132;6122.204113465265;6135.477655820245;6138.140660307516;6140.350877192993;6141.631502109843;6124.564025909317;6126.79250549526;6206.565540412173;6212.378762123797;6228.906566401508;5785.681787501686;5801.9129713054335;5804.912926306109;5806.796762113306;5823.5341172706085;6123.466143003804;6174.753009879644;6222.719977601018;6271.435570798559;6276.905846412307;6112.07796728157;6139.925513035201;6146.323904370515;6151.630902145886;6154.5076393888385;6133.754649813957;6189.876202476015;6198.2871969723155;6203.720832562545;6230.127782110508;6024.57827952048;6095.05526643371;6164.722587483574;6190.783322583778;6192.659403732838;6123.09992524296;6125.908714686065;6130.233720784902;6137.292318144079;6144.678894037804;4270.402143699823;4942.357493525818;4998.400191976913;5005.27423523587;5007.098722230034;4348;4415.183191109655;4423.1153769246275;4432.15789000089;4449.466064072268;5685.175649530877;5798.304203495523;5801.071828507418;5802.564807639444;5806.245188125601;5221.843344699665;6123.801220575414;6128.044828652092;6182;6197.938020619827;5763.6541807491685;5767.798127065533;5784.3926387729525;5796.217510636095;5831.737571809279;4699.483056863743;5419.187121931663;5428.240046393508;5430.0768869292315;5443.2107344434835;6209.441150296495;6210.689465526796;6279.748810047547;6344.175257216532;6365.649889256136;6190.41191086846;6256.5620406572925;6288.151099601679;6303.054541818764;6304.936950630435;6326.240851097898;6329.90505142414;6351.650659213603;6351.650659213788;6359.395857393541;5799.8550036249435;5829.76680932758;5830.679312637676;6121.081837724377;6163.229596300463;6142.191841058572;6157.599756015845;6159.322474527708;6170.444659980623;6216.53375996793;6127.6936153193055;6152.046432802923;6152.600080994721;6160.44555989963;6168.691565421801;4942.382202224722;6101.572889897842;6149.323574406902;6152.230971128609;6168.537359698131;5185.066687996046;5826.504747096545;5864.149698293809;5902.6458412495385;6001.189839371629;6107.595253091903;6120.785772497941;6145.8156255312415;6147.754089836357;6150.846228844314;5855.824325270249;5926.911096333644;5928.79249226275;5935.495482884004;5942.762289508371 10:48:45 INFO - PROCESS | 1662 | |1;ext.html;434.93693414454907;494.4264653002518;508.5778803593017;536.7530935769546;555.4695266020949;31717.048488545322;32100.197495062606;34504.827475862585;40600.59399406005;41676.58323416765;41948;42273;42286.577134228646;42345.57654423455;42398;9696.0788725071;9890.406575605486;9894.010598940084;9896.40621562705;9897.15874150698;8732.170443807829;8960.731178064669;11859.52561897523;12104.576339828107;12129.150959432844;12167.878321216807;12176;12197.451114699816;12223.938880305586;12243.326617036104;8719.084496127878;8918.821623567557;12075.93962030193;12155.453004614725;12164.574239901562;8772.868406973901;8843.204111629984;8848.336374771918;11613;11945;39924;40298;40339.394909076385;40433.191336173404;40433.797831010954;3257.5113732940013;3275.7215636670912;3284.474484082535;3294.4728843385183;3593.2633810068874;30577.694223057708;30697.846510767304;30808.229794255323;31013.37973240545;31763;3206.9198270043203;3214.5017522284083;3224.339010502841;3241.837908104609;3301.7028467438045;490350;491748;492311;492374;494829.9999999964;48689;49906.75046624744;56772;58426.4157358421;58480.70759646175;61019.69490152521;61492;61498.69250653808;61563;61571.07643385353;15542.300596473186;15761.29074191664;15782.763258551131;15786.526404207892;15851.365945362057;11100.888991110147;11510.30938143714;12208.206466579644;12338.691532711575;12353.011759059433;14184;15715.449959251371;15862.365505379656;16130.838691613168;16296.674066518603;6351.2060992375955;6364.281643956106;6384.106225128486;6393.7762178324465;6409.134766806422;942.6559305853314;953.8283109040301;1000.9449480278508;1012.6000229909145;1026.9948650256702;28727;29074.127776166748;29597;29741.85129074384;29768.660410281154;930.8696782450462;986.9013098690217;1023.1303392116641;1047.701405099543;1140.948657310423;12620.621381358575;15185.772213416807;15207.695846083237;15321.770173447407;15602.609934751723;194960;195983;196596.03403966062;196642;197148;56801;56907.71546142243;57009;57037.42962570321;57207.427925721044;1199.3703305764543;1232.2975903734784;1350.601572536124;1372.0601388049217;1404.1645221093434;26261.606075908494;29458.558121628623;30440.391192176474;30598.84700576483;31034.0689779307;47525.52474475211;48027.5197248037;48056.75971620259;48091;48137.7965550864;519139;520173;521620;522288;522938.38530808623;37555;38938.22123557569;39059.41410878782;39084.60915390924;39162.41256381213;45923.31115033344;45982.08035839198;46584.767076164404;46859.06281874412;47202.527974719815;38044.23911521809;38209.40531526566;38390.616093838704;39100.80449597734;39520.20959580735;36782.44826327661;37874.86375408742;37986.62013379831;38020.429693555176;38901;37195.6280437203;38285.42571861369;38452.615473844904;38545.807270963465;38546.61453385543;27105.714385695388;29864.85067574735;34376.48435273523;34387.48418773671;34513.30973380468;23373.41566460852;24513.754862451147;24739.752602473745;24912.37719057038;25478.85345159435 10:48:45 INFO - PROCESS | 1662 | |2;jquery.html;316.68648038441944;353.1559572621434;375.91729819439723;377.78655059891145;407.7064513550243;13106.60680179595;14318.642033949143;15130.394784208633;20737.066831992557;21183.470413239676;19796.406107816754;19929.800701993012;20297.0866311016;20626.1749530019;20883.477913052182;5264.8683782905355;5802.680852553108;5949.345571987078;5980.102984552308;6019.909701354483;5013.498650134976;6058.212432383784;6087.2999605045325;6108.81673549792;6125.509959203276;5718.799842005531;6053.667048312341;6061.727222274986;6065.181200537915;6065.878682426349;5848.766049358021;5972.522198224175;5972.820815375502;5973.761049558012;5988.251468566429;4399.274119770234;5013;5199.076341753537;5208.635395522315;5226.477352264781;25996.09013684531;26049.479010419876;26130.73869261302;26250.34374140643;26414.6037809433;2168.5988092202992;2304.4584522637165;2497.21337778601;2559.83361081531;2583.057184127799;15588.29852656622;15660.765088523682;15706;16166.676666466723;16432;1837.283459450802;1853.898035608041;1901.1254822781536;1951.4828570428847;1954.6383918974893;119124.40437797843;123747.38126309401;124221.37889310587;124583.37708311492;126016.36991814982;10149.847752283722;10399.53202105892;10807;10856.620018299322;11005;13597.184168949894;13646.317684115755;13655.453781848813;13678.452861885613;13688.863111368959;8190.754277371689;8447.155284471504;8451.746447606582;8615.13848615146;8634.568271586395;8488.957555212184;8552.315814734806;8559.914400856036;8685.739427817176;8703.95648021756;6512.674366281667;6662.834004049272;6673.733050678015;6682.632455214913;6795.2185498667295;1639.467173168701;1762.3169872771473;1808.484581894154;1809.0683298101487;1809.4571628511394;749.7638243953137;872.6553011560525;1004.2167109654481;1027.465717826726;1029.953652085658;21405.374076923934;22039.93189908679;22057;22086.11655533793;22132.89335533217;825.7027470110755;842.806154584449;925.9444433334022;990.9248465414961;999.9950000250097;11052.447377631086;11561.537538498535;11585.062570877079;11608.825867611993;11640.708982275512;576284;584152;588865;590641;596183;85020;109921;112465;113924.86075139143;116420;961.543266948208;1001.3891526169031;1019.3781793106348;1027.4759872465097;1032.5147180825;15265.542033739006;18276.177572009026;18907.90546047316;19155.425337239347;19274.132664029872;49067.263991040876;49169.75415122901;49439.25841112314;50497;50539;68673.65663171653;68883;69321;69694.30305696878;69951;6309.211348581427;6994.746199484453;7171.49799514029;7687.423443241891;7887.408444366581;12581.622551323475;12743.553975610808;12881.871181288441;12893.419796109385;12935.741285174432;10958.506867190836;11036.792641471899;11189.496472658588;11211.71970700739;11271.267367621078;11119;11156.609518666808;11271.718207044889;11510.59712910044;11768.058555315383;10336.328138670962;11091;11367.261128026652;11634.36011019402;11663.88336116628;7554.4334174936;7654.846903061796;7920.603969801372;8213.753587392388;8260.421770476009;9352.625894964398;10710.357378557312;11100.555977761122;11286.266392684449;11368.147388945697 10:48:45 INFO - PROCESS | 1662 | |3;mootools.html;1524.5045360257916;1544.1893006171758;1655.5033489953012;1736.2100244388805;1898.6297671953967;7713.884291735614;8479.491230526173;8933.106689331064;8939.64241430342;8942.239909607675;7180.102487189101;7849.5682737449415;8342.91657083429;8669.696560620363;8701;2334.8832558372055;2367.5975084235633;2369.869657168855;2370.5258948210426;2371.2412028150998;2345.1205797825814;2366.053578568578;2374.7031621047367;2380.083667787897;2382.5711371953043;1995.49114975681;2008.4075197816678;2344.941376465585;2358.6697862299293;2365.73976862545;2335.0543030072763;2337.94155146121;2342.5197834443898;2342.8008619267384;2342.871142087176;2283.9885800571055;2315.4790172211287;2316.420894776306;2318.744938056829;2327.55776402483;8761.780955476164;8842.204201621886;8872.689455869078;8876;8913.420627659181;5149.588032957355;5317.760700768475;5319.946800531984;5319.9734001330135;5325.360956685223;3413.812240326781;3436.810975396352;3534.9823250883837;3583.1937813991904;3647.1429214134655;1043.8695163104612;1086.1528008153653;1090.8437056719902;1111.360967443723;1111.7109551516583;11644.592439264585;11684.415779211005;11703.946644801923;11708.53165873364;11713.590024349107;4086.346184610448;4518.892871246566;4539.114872599811;4636.698614590041;4746.810127594927;9798.412095274307;9887.258455615858;9900.059494348025;9902.55438505269;9911.504424778732;4555.931661025087;5270.973645131749;5281.023010743025;5293.602979776532;5319.627626066215;4312.978435107867;4361.476622805284;4810.63920205986;4824.372831531879;4856.077345304381;3804.410316400907;3804.9346183068783;3887.650111489923;3925.9018524537114;3943.6056394360335;1244.589285535771;1248.2697621891114;1251.3367915005063;1255.9811602825964;1264.6395777203452;790.909045459768;886.6434355436193;914.8581969794559;920.7283851263893;949.3307218411004;5731.770729170787;5741.598088133874;5875.559333050039;5942.584019118706;6081.787137450168;447.85668586052805;456.40211323166756;459.60473992366536;1005.1305620638095;1005.7284533176148;3184.8726050958167;3545.3263879862743;3893.396523538856;3919.984036715543;3991.16185601027;9096.8635470468;10230.59077636901;10608.992145746142;10681.985211404904;10693.411862347646;10606.628767993083;10609.469526523797;10619.20355973305;10626;10636.521356538975;6528.575642583217;8048.315893149031;8058.879116813252;8067.878981815277;8125.431219814556;5634.943650563606;5760.308762948473;5763.193152958505;5775.624584402;5793.478586927028;9528;9625.133737963617;9847.753806154617;9965.302428829913;10008.84986725185;9410.294227932796;9714.80570388574;9858.654947076819;10207;10213.591456341961;6796.830079248059;7251.891221631574;7424.925750742639;7580.734674286374;7590.468667193243;8277.213664701843;8456.492610443393;8459.661613535636;8460.280876125353;8461.40770146084;7344.779656610311;7422.220666829905;7544.6227688616445;7606.657700403384;7676.961615191888;7256.637168141678;7350.632468376667;7369;7508.2867127622785;7564.659590318339;6023.728932198149;6845.041694162722;7003.01957725929;7007.614581198083;7020.7542736004;4755.762211889461;4992.750362481789;5025.221090730943;5043.798248070183;5121.8463446096675;4170.854020109282;4535.979404633931;4960.181570040902;4962.751862406793;5114.769835357343 10:48:45 INFO - PROCESS | 1662 | |4;prototype.html;266.8318959055795;272.5380480086253;306.725480694778;314.410480349345;323.0566745104295;4833.758312084399;5083.542481176693;5470.64440811347;5493.0724531959595;5516.889662206753;4639.814407423707;4996.600271978242;5233.842984710455;5270.8418747437545;5445.401005889349;3867.7872717000623;4079.857639860844;4113.526944401402;4157.438745769327;4161.334186530155;3746.3256613809504;4118.320477121271;4118.755278373279;4129.648979836717;4141.295979683461;3168.7325069971967;3611.458281257806;4048.0487085534874;4120;4131.6281534661875;3548.822558872046;3960.5049368828895;4130.731502452331;4148.294789885712;4184.246835569596;2962.3706961171483;3530.276293359855;3535.575730912307;3543.192971584485;3550.3076900004507;4115.629593336614;4579.519150489184;5202.4277329493725;5311.49540793624;5322.654027488201;4472.530384309665;4531.818727250906;4713.787879545394;4716.245400735866;4724.031573527419;5719.399463056361;5724.5992780505385;5739.311282646067;5739.3399759027825;5773.257702591278;3635.418333066723;3873.9418908716393;3892.961070389288;3900.6489415952415;3919.608039196086;5314.867128321745;5360.973195133999;5553.916986187695;5605.663660180361;5653;3900.1614652849776;3913.295606790748;3930.587288334741;3947.743396679207;3965.385365268388;3701.629837016331;4029.2143032108456;4043.73715708478;4047.392891066305;4058.7970601470397;3929.351656976624;4047.886831121465;4436.733795972252;4658.720476771405;4659.534046595313;5768.50967667753;5782.855428614318;5782.913256301159;5798.246227990334;5805.303363596396;1296.6369416563377;1362.5980335800962;1375.7936309553647;1378.2764048874221;1385.923774192409;822.9711960081369;845.8181490979346;879.7712594725417;917.9449233045905;919.7148883846029;4365.650747940221;5100.48995100487;5367.021303176774;5371.919421208685;5417.83746487606;5294.841154765363;5400.027994960945;5669.489745922887;5854.326752423526;5945.583809133319;4437;4750.311204875274;5338.519533242027;5414.3502779665905;5430.239766432703;2587.8835452404687;2711.6745990481268;2745.437185376993;2939.0448104365737;2966.9912229841957;5865.442782935615;6113.14415981754;6173.629582225081;6186.133941248228;6198.442140207403;5015.3480047593575;5291.433654677478;5940.8810374553705;6006.279246490449;6018.548608854353;4895.828645997373;5313.229581710631;5739.512141467939;6091.258933479863;6132.448079672761;5280.841574752763;5289.2330612061805;5850.64896106235;5875.73559189846;5919.543389493312;4567.223571992836;5135.075686376488;5280.392754833243;5530.115181570929;5598.916016259678;4613.723176609415;5139.94631100631;6117.541184411276;6138.846528836815;6142.560166306883;3184.9522257166623;3872.186840763471;3893.454916311662;3907.3430067554336;3926.234384295035;4951.935333903228;5163.3545806774155;5718.827640333639;5842.532597392284;5916.911246331395;5384.407715151408;5414.377346605191;5666.46168613981;5742.741576629145;5777.62445441045;4842.4673285939225;5840.445157710011;5843.620164689281;5850.723594786933;5854.707264636836;4670.6263498919325;5448.591355648421;5711.8299343864555;5733.283339582552;5742.138679198154;4543.45478542577;5030.496950305087;5721.227634269487;5774.94225057744;5850.853728656647;4429.557044295545;4520.34455004016;4820.783064762024;5656.434356564475;5741.799037033684 10:48:45 INFO - PROCESS | 1662 | |5;yui.html;643.2474005413666;712.5582139073773;758.8823732321489;777.7122464688066;814.959252037398;14335.856641433582;16004.67990640188;16022.519324420256;16127.677446451064;16142.677146457063;14381.928090359559;14557.563273101823;14569.562913112597;14575.27123643819;14842.851571484282;842.5534466732641;854.9572521373922;860.8407444622765;883.3242569434387;884.4648987362649;864.9654013839439;866.4714524140269;867.1891781184581;867.2411639815161;867.7440155154245;706.6749295324157;730.6018220070075;892.6830975003877;913.4975763330175;918.9402688825238;909.7725568607848;913.5432283858071;914.0037359278418;914.8536234202562;917.5779141594875;673.3132205150719;674.9696263668146;675.0245894682187;675.3550359406755;675.7128220506265;11875.52497900083;12349.320787356692;12361.443735031946;15689.058656480647;15802.051876887424;12349.320787356692;13862.4455021799;14030.228337441436;14201.36093875778;14242.85757142436;9801.460919649417;11907.702307442296;11911.285322880654;12013.219140755822;12091.818622720666;3796.9686217873304;3803.353429916921;3806;3835.2137811748526;3955.960440395588;121242;123006;123465;124047.13929291068;124057.75942240552;10812.675619731262;10898;10997;11140.275882067639;11455.427228638702;21708;21733.67399489009;21752.12991480323;21767.23814666479;21790.564188716136;7812.687492500237;7822.765317040498;7824.921750782533;7895.960520197362;7899.210078992055;4415.823367065345;4437.400950871591;4440.178566965122;4511.120331535319;4602.562756538124;3504.299140171976;3591.263790922907;3615.349237137288;3622.5290712207243;3627.582827974764;641.576559470747;655.0992385469979;657.2015001772869;664.0868805392586;673.9157605299338;597.2743117112642;605.1588292273743;640.8846407646575;665.751715533375;666.1473314157886;8949.59726812282;9338.813223735488;9482.668106616373;9700.805983880424;9702.223822094216;642.194046471675;672.9394354508117;673.0879658063352;673.8551211489553;684.6576711644178;4328.978355108205;4552.88617784558;4592.7933243004145;4647.395838541036;4671.299305104194;93951.53024234972;94142.5292873545;94262;94319.52840235892;158598;140001;141214;141878;143038.974543564;144611;99757.50121249493;120280;122383;122799.38600306942;127967;8939.284857211278;8946.910530894867;8951.820963580822;8964.731058068268;9207.539623018689;56242;64077.67961160164;65094.67452662707;65597;65895.34104658892;98293.50853245688;101295;105365;108121;108248;10953.34279943206;11105.722356940818;11227.3615700312;11321.320720756781;11523.888505410465;12612.369381530703;12886.806697899352;12898.806517902427;12960;13095.738085238052;13861.584152475441;14026.158430494203;14087.225202613954;14324.283785810876;14723.043002204822;13672;13766.518171863936;14384;14523.389122379533;14535.055221410574;13456.730865382833;13635.25006124633;13834.93082534581;13916.930415347859;13982.236740853878;9901.257405694745;11352.301711115933;11668.708282292671;11908.821367679318;12074.39628018613;10803.891961080288;11274.154438417316;11534;11568.652940411801;11601.477933492844 10:48:45 INFO - PROCESS | 1662 | __end_tp_report 10:48:45 INFO - PROCESS | 1662 | __start_cc_report 10:48:45 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1347 10:48:45 INFO - PROCESS | 1662 | __end_cc_report 10:48:45 INFO - PROCESS | 1662 | __startTimestamp1475862525507__endTimestamp 10:48:45 INFO - PROCESS | 1662 | 10:48:45 INFO - PROCESS | 1662 | ------- Summary: start ------- 10:48:45 INFO - PROCESS | 1662 | Number of tests: 6 10:48:45 INFO - PROCESS | 1662 | 10:48:45 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5709.53 Median:5855.82 stddev:643.96 (11.0%) stddev-sans-first:616.77 10:48:45 INFO - PROCESS | 1662 | Values: 3267.2 3282.5 3306.4 3340.7 3356.4 5570.4 5682.7 5712.6 5771.7 5809.9 5599.5 5661.3 5662.7 5693.4 5713.3 5106.6 5123.4 5128.8 5191.9 5207.2 5294.7 5295.0 5304.3 5310.5 5361.5 5100.4 5263.6 5273.0 5283.2 5344.9 5133.7 5144.1 5213.5 5231.5 5373.5 5125.2 5128.1 5131.9 5150.7 5170.3 5710.2 5755.3 5778.9 5781.5 5783.8 6122.2 6135.5 6138.1 6140.4 6141.6 6124.6 6126.8 6206.6 6212.4 6228.9 5785.7 5801.9 5804.9 5806.8 5823.5 6123.5 6174.8 6222.7 6271.4 6276.9 6112.1 6139.9 6146.3 6151.6 6154.5 6133.8 6189.9 6198.3 6203.7 6230.1 6024.6 6095.1 6164.7 6190.8 6192.7 6123.1 6125.9 6130.2 6137.3 6144.7 4270.4 4942.4 4998.4 5005.3 5007.1 4348.0 4415.2 4423.1 4432.2 4449.5 5685.2 5798.3 5801.1 5802.6 5806.2 5221.8 6123.8 6128.0 6182.0 6197.9 5763.7 5767.8 5784.4 5796.2 5831.7 4699.5 5419.2 5428.2 5430.1 5443.2 6209.4 6210.7 6279.7 6344.2 6365.6 6190.4 6256.6 6288.2 6303.1 6304.9 6326.2 6329.9 6351.7 6351.7 6359.4 5799.9 5829.8 5830.7 6121.1 6163.2 6142.2 6157.6 6159.3 6170.4 6216.5 6127.7 6152.0 6152.6 6160.4 6168.7 4942.4 6101.6 6149.3 6152.2 6168.5 5185.1 5826.5 5864.1 5902.6 6001.2 6107.6 6120.8 6145.8 6147.8 6150.8 5855.8 5926.9 5928.8 5935.5 5942.8 10:48:45 INFO - PROCESS | 1662 | 10:48:45 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:56233.76 Median:37930.74 stddev:114542.12 (302.0%) stddev-sans-first:114786.83 10:48:45 INFO - PROCESS | 1662 | Values: 434.9 494.4 508.6 536.8 555.5 31717.0 32100.2 34504.8 40600.6 41676.6 41948.0 42273.0 42286.6 42345.6 42398.0 9696.1 9890.4 9894.0 9896.4 9897.2 8732.2 8960.7 11859.5 12104.6 12129.2 12167.9 12176.0 12197.5 12223.9 12243.3 8719.1 8918.8 12075.9 12155.5 12164.6 8772.9 8843.2 8848.3 11613.0 11945.0 39924.0 40298.0 40339.4 40433.2 40433.8 3257.5 3275.7 3284.5 3294.5 3593.3 30577.7 30697.8 30808.2 31013.4 31763.0 3206.9 3214.5 3224.3 3241.8 3301.7 490350.0 491748.0 492311.0 492374.0 494830.0 48689.0 49906.8 56772.0 58426.4 58480.7 61019.7 61492.0 61498.7 61563.0 61571.1 15542.3 15761.3 15782.8 15786.5 15851.4 11100.9 11510.3 12208.2 12338.7 12353.0 14184.0 15715.4 15862.4 16130.8 16296.7 6351.2 6364.3 6384.1 6393.8 6409.1 942.7 953.8 1000.9 1012.6 1027.0 28727.0 29074.1 29597.0 29741.9 29768.7 930.9 986.9 1023.1 1047.7 1140.9 12620.6 15185.8 15207.7 15321.8 15602.6 194960.0 195983.0 196596.0 196642.0 197148.0 56801.0 56907.7 57009.0 57037.4 57207.4 1199.4 1232.3 1350.6 1372.1 1404.2 26261.6 29458.6 30440.4 30598.8 31034.1 47525.5 48027.5 48056.8 48091.0 48137.8 519139.0 520173.0 521620.0 522288.0 522938.4 37555.0 38938.2 39059.4 39084.6 39162.4 45923.3 45982.1 46584.8 46859.1 47202.5 38044.2 38209.4 38390.6 39100.8 39520.2 36782.4 37874.9 37986.6 38020.4 38901.0 37195.6 38285.4 38452.6 38545.8 38546.6 27105.7 29864.9 34376.5 34387.5 34513.3 23373.4 24513.8 24739.8 24912.4 25478.9 10:48:45 INFO - PROCESS | 1662 | 10:48:45 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:34073.64 Median:12218.68 stddev:97648.94 (799.2%) stddev-sans-first:97889.97 10:48:45 INFO - PROCESS | 1662 | Values: 316.7 353.2 375.9 377.8 407.7 13106.6 14318.6 15130.4 20737.1 21183.5 19796.4 19929.8 20297.1 20626.2 20883.5 5264.9 5802.7 5949.3 5980.1 6019.9 5013.5 6058.2 6087.3 6108.8 6125.5 5718.8 6053.7 6061.7 6065.2 6065.9 5848.8 5972.5 5972.8 5973.8 5988.3 4399.3 5013.0 5199.1 5208.6 5226.5 25996.1 26049.5 26130.7 26250.3 26414.6 2168.6 2304.5 2497.2 2559.8 2583.1 15588.3 15660.8 15706.0 16166.7 16432.0 1837.3 1853.9 1901.1 1951.5 1954.6 119124.4 123747.4 124221.4 124583.4 126016.4 10149.8 10399.5 10807.0 10856.6 11005.0 13597.2 13646.3 13655.5 13678.5 13688.9 8190.8 8447.2 8451.7 8615.1 8634.6 8489.0 8552.3 8559.9 8685.7 8704.0 6512.7 6662.8 6673.7 6682.6 6795.2 1639.5 1762.3 1808.5 1809.1 1809.5 749.8 872.7 1004.2 1027.5 1030.0 21405.4 22039.9 22057.0 22086.1 22132.9 825.7 842.8 925.9 990.9 1000.0 11052.4 11561.5 11585.1 11608.8 11640.7 576284.0 584152.0 588865.0 590641.0 596183.0 85020.0 109921.0 112465.0 113924.9 116420.0 961.5 1001.4 1019.4 1027.5 1032.5 15265.5 18276.2 18907.9 19155.4 19274.1 49067.3 49169.8 49439.3 50497.0 50539.0 68673.7 68883.0 69321.0 69694.3 69951.0 6309.2 6994.7 7171.5 7687.4 7887.4 12581.6 12743.6 12881.9 12893.4 12935.7 10958.5 11036.8 11189.5 11211.7 11271.3 11119.0 11156.6 11271.7 11510.6 11768.1 10336.3 11091.0 11367.3 11634.4 11663.9 7554.4 7654.8 7920.6 8213.8 8260.4 9352.6 10710.4 11100.6 11286.3 11368.1 10:48:45 INFO - PROCESS | 1662 | 10:48:45 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5615.83 Median:4908.13 stddev:3171.40 (64.6%) stddev-sans-first:3165.39 10:48:45 INFO - PROCESS | 1662 | Values: 1524.5 1544.2 1655.5 1736.2 1898.6 7713.9 8479.5 8933.1 8939.6 8942.2 7180.1 7849.6 8342.9 8669.7 8701.0 2334.9 2367.6 2369.9 2370.5 2371.2 2345.1 2366.1 2374.7 2380.1 2382.6 1995.5 2008.4 2344.9 2358.7 2365.7 2335.1 2337.9 2342.5 2342.8 2342.9 2284.0 2315.5 2316.4 2318.7 2327.6 8761.8 8842.2 8872.7 8876.0 8913.4 5149.6 5317.8 5319.9 5320.0 5325.4 3413.8 3436.8 3535.0 3583.2 3647.1 1043.9 1086.2 1090.8 1111.4 1111.7 11644.6 11684.4 11703.9 11708.5 11713.6 4086.3 4518.9 4539.1 4636.7 4746.8 9798.4 9887.3 9900.1 9902.6 9911.5 4555.9 5271.0 5281.0 5293.6 5319.6 4313.0 4361.5 4810.6 4824.4 4856.1 3804.4 3804.9 3887.7 3925.9 3943.6 1244.6 1248.3 1251.3 1256.0 1264.6 790.9 886.6 914.9 920.7 949.3 5731.8 5741.6 5875.6 5942.6 6081.8 447.9 456.4 459.6 1005.1 1005.7 3184.9 3545.3 3893.4 3920.0 3991.2 9096.9 10230.6 10609.0 10682.0 10693.4 10606.6 10609.5 10619.2 10626.0 10636.5 6528.6 8048.3 8058.9 8067.9 8125.4 5634.9 5760.3 5763.2 5775.6 5793.5 9528.0 9625.1 9847.8 9965.3 10008.8 9410.3 9714.8 9858.7 10207.0 10213.6 6796.8 7251.9 7424.9 7580.7 7590.5 8277.2 8456.5 8459.7 8460.3 8461.4 7344.8 7422.2 7544.6 7606.7 7677.0 7256.6 7350.6 7369.0 7508.3 7564.7 6023.7 6845.0 7003.0 7007.6 7020.8 4755.8 4992.8 5025.2 5043.8 5121.8 4170.9 4536.0 4960.2 4962.8 5114.8 10:48:45 INFO - PROCESS | 1662 | 10:48:45 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:4551.83 Median:5092.02 stddev:1418.19 (27.9%) stddev-sans-first:1385.22 10:48:45 INFO - PROCESS | 1662 | Values: 266.8 272.5 306.7 314.4 323.1 4833.8 5083.5 5470.6 5493.1 5516.9 4639.8 4996.6 5233.8 5270.8 5445.4 3867.8 4079.9 4113.5 4157.4 4161.3 3746.3 4118.3 4118.8 4129.6 4141.3 3168.7 3611.5 4048.0 4120.0 4131.6 3548.8 3960.5 4130.7 4148.3 4184.2 2962.4 3530.3 3535.6 3543.2 3550.3 4115.6 4579.5 5202.4 5311.5 5322.7 4472.5 4531.8 4713.8 4716.2 4724.0 5719.4 5724.6 5739.3 5739.3 5773.3 3635.4 3873.9 3893.0 3900.6 3919.6 5314.9 5361.0 5553.9 5605.7 5653.0 3900.2 3913.3 3930.6 3947.7 3965.4 3701.6 4029.2 4043.7 4047.4 4058.8 3929.4 4047.9 4436.7 4658.7 4659.5 5768.5 5782.9 5782.9 5798.2 5805.3 1296.6 1362.6 1375.8 1378.3 1385.9 823.0 845.8 879.8 917.9 919.7 4365.7 5100.5 5367.0 5371.9 5417.8 5294.8 5400.0 5669.5 5854.3 5945.6 4437.0 4750.3 5338.5 5414.4 5430.2 2587.9 2711.7 2745.4 2939.0 2967.0 5865.4 6113.1 6173.6 6186.1 6198.4 5015.3 5291.4 5940.9 6006.3 6018.5 4895.8 5313.2 5739.5 6091.3 6132.4 5280.8 5289.2 5850.6 5875.7 5919.5 4567.2 5135.1 5280.4 5530.1 5598.9 4613.7 5139.9 6117.5 6138.8 6142.6 3185.0 3872.2 3893.5 3907.3 3926.2 4951.9 5163.4 5718.8 5842.5 5916.9 5384.4 5414.4 5666.5 5742.7 5777.6 4842.5 5840.4 5843.6 5850.7 5854.7 4670.6 5448.6 5711.8 5733.3 5742.1 4543.5 5030.5 5721.2 5774.9 5850.9 4429.6 4520.3 4820.8 5656.4 5741.8 10:48:45 INFO - PROCESS | 1662 | 10:48:45 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:24980.24 Median:3625.06 stddev:39952.44 (1102.1%) stddev-sans-first:40022.73 10:48:45 INFO - PROCESS | 1662 | Values: 643.2 712.6 758.9 777.7 815.0 14335.9 16004.7 16022.5 16127.7 16142.7 14381.9 14557.6 14569.6 14575.3 14842.9 842.6 855.0 860.8 883.3 884.5 865.0 866.5 867.2 867.2 867.7 706.7 730.6 892.7 913.5 918.9 909.8 913.5 914.0 914.9 917.6 673.3 675.0 675.0 675.4 675.7 11875.5 12349.3 12361.4 15689.1 15802.1 12349.3 13862.4 14030.2 14201.4 14242.9 9801.5 11907.7 11911.3 12013.2 12091.8 3797.0 3803.4 3806.0 3835.2 3956.0 121242.0 123006.0 123465.0 124047.1 124057.8 10812.7 10898.0 10997.0 11140.3 11455.4 21708.0 21733.7 21752.1 21767.2 21790.6 7812.7 7822.8 7824.9 7896.0 7899.2 4415.8 4437.4 4440.2 4511.1 4602.6 3504.3 3591.3 3615.3 3622.5 3627.6 641.6 655.1 657.2 664.1 673.9 597.3 605.2 640.9 665.8 666.1 8949.6 9338.8 9482.7 9700.8 9702.2 642.2 672.9 673.1 673.9 684.7 4329.0 4552.9 4592.8 4647.4 4671.3 93951.5 94142.5 94262.0 94319.5 158598.0 140001.0 141214.0 141878.0 143039.0 144611.0 99757.5 120280.0 122383.0 122799.4 127967.0 8939.3 8946.9 8951.8 8964.7 9207.5 56242.0 64077.7 65094.7 65597.0 65895.3 98293.5 101295.0 105365.0 108121.0 108248.0 10953.3 11105.7 11227.4 11321.3 11523.9 12612.4 12886.8 12898.8 12960.0 13095.7 13861.6 14026.2 14087.2 14324.3 14723.0 13672.0 13766.5 14384.0 14523.4 14535.1 13456.7 13635.3 13834.9 13916.9 13982.2 9901.3 11352.3 11668.7 11908.8 12074.4 10803.9 11274.2 11534.0 11568.7 11601.5 10:48:45 INFO - PROCESS | 1662 | -------- Summary: end -------- 10:48:45 INFO - PROCESS | 1662 | 10:48:46 INFO - TEST-INFO | 1662: exit 0 10:48:46 INFO - TEST-OK | dromaeo_css | took 1119021ms 10:48:46 INFO - TEST-START | kraken 10:48:46 INFO - operating with platform_type : mac_ 10:48:46 INFO - Initialising browser for kraken test... 10:48:46 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/tmpiTuy7a/profile http://localhost:49275/getInfo.html) 10:48:48 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 10:48:48 INFO - PROCESS | 1667 | colorDepth:24 10:48:48 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 10:48:48 INFO - PROCESS | 1667 | __metrics 10:48:48 INFO - PROCESS | 1667 | 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" 10:48:48 INFO - PROCESS | 1667 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:48:49 INFO - TEST-INFO | 1667: exit 0 10:48:49 INFO - Browser initialized. 10:48:49 INFO - Running cycle 1/1 for kraken test... 10:48:49 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/tmpiTuy7a/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:49:01 INFO - PROCESS | 1668 | RSS: Main: 234827776 10:49:01 INFO - PROCESS | 1668 | 10:49:03 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 10:49:04 INFO - PROCESS | 1668 | RSS: Main: 293138432 10:49:04 INFO - PROCESS | 1668 | 10:49:07 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 10:49:07 INFO - PROCESS | 1668 | RSS: Main: 293359616 10:49:07 INFO - PROCESS | 1668 | 10:49:11 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 10:49:12 INFO - PROCESS | 1668 | RSS: Main: 380801024 10:49:12 INFO - PROCESS | 1668 | 10:49:14 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 10:49:14 INFO - PROCESS | 1668 | RSS: Main: 284893184 10:49:14 INFO - PROCESS | 1668 | 10:49:16 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:49:17 INFO - PROCESS | 1668 | RSS: Main: 288837632 10:49:17 INFO - PROCESS | 1668 | 10:49:20 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 10:49:20 INFO - PROCESS | 1668 | RSS: Main: 327266304 10:49:20 INFO - PROCESS | 1668 | 10:49:23 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 10:49:24 INFO - PROCESS | 1668 | RSS: Main: 327897088 10:49:24 INFO - PROCESS | 1668 | 10:49:26 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 10:49:27 INFO - PROCESS | 1668 | RSS: Main: 325562368 10:49:27 INFO - PROCESS | 1668 | 10:49:29 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:49:29 INFO - PROCESS | 1668 | RSS: Main: 300298240 10:49:29 INFO - PROCESS | 1668 | 10:49:31 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:49:32 INFO - PROCESS | 1668 | RSS: Main: 304521216 10:49:32 INFO - PROCESS | 1668 | 10:49:34 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:49:34 INFO - PROCESS | 1668 | RSS: Main: 310489088 10:49:34 INFO - PROCESS | 1668 | 10:49:37 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:49:38 INFO - PROCESS | 1668 | RSS: Main: 326426624 10:49:38 INFO - PROCESS | 1668 | 10:49:41 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:49:42 INFO - PROCESS | 1668 | RSS: Main: 320004096 10:49:42 INFO - PROCESS | 1668 | 10:49:43 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 10:49:44 INFO - PROCESS | 1668 | RSS: Main: 363724800 10:49:44 INFO - PROCESS | 1668 | 10:49:44 INFO - PROCESS | 1668 | __start_tp_report 10:49:44 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 10:49:44 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 10:49:44 INFO - PROCESS | 1668 | |i|pagename|runs| 10:49:44 INFO - PROCESS | 1668 | |0;ai-astar;114;115;112;113;110;114;113;113;113;113 10:49:44 INFO - PROCESS | 1668 | |1;audio-beat-detection;149;115;114;148;113;114;112;112;143;110 10:49:44 INFO - PROCESS | 1668 | |2;audio-dft;183;182;181;186;185;186;180;184;184;185 10:49:44 INFO - PROCESS | 1668 | |3;audio-fft;79;60;58;87;61;59;60;59;81;63 10:49:44 INFO - PROCESS | 1668 | |4;audio-oscillator;86;73;91;70;68;83;76;88;100;121 10:49:44 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;89;89;88;91;91;86;90;86;90;84 10:49:44 INFO - PROCESS | 1668 | |6;imaging-darkroom;97;97;98;97;97;98;97;97;98;97 10:49:44 INFO - PROCESS | 1668 | |7;imaging-desaturate;87;87;86;86;84;87;85;83;99;86 10:49:44 INFO - PROCESS | 1668 | |8;json-parse-financial;56;53;52;52;52;54;53;52;54;53 10:49:44 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;64;74;64;77;74;69;71;71;71;72 10:49:44 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;86;86;89;86;86;86;88;90;84;86 10:49:44 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;147;162;153;159;154;144;147;149;188;145 10:49:44 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;172;169;171;173;175;172;174;170;173;170 10:49:44 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;68;70;68;68;68;69;67;67;68;69 10:49:44 INFO - PROCESS | 1668 | __end_tp_report 10:49:44 INFO - PROCESS | 1668 | __start_cc_report 10:49:44 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1964 10:49:44 INFO - PROCESS | 1668 | __end_cc_report 10:49:44 INFO - PROCESS | 1668 | __startTimestamp1475862584221__endTimestamp 10:49:44 INFO - PROCESS | 1668 | 10:49:44 INFO - PROCESS | 1668 | ------- Summary: start ------- 10:49:44 INFO - PROCESS | 1668 | Number of tests: 14 10:49:44 INFO - PROCESS | 1668 | 10:49:44 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:113.00 Median:113.00 stddev:1.33 (1.2%) stddev-sans-first:1.36 10:49:44 INFO - PROCESS | 1668 | Values: 114.0 115.0 112.0 113.0 110.0 114.0 113.0 113.0 113.0 113.0 10:49:44 INFO - PROCESS | 1668 | 10:49:44 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:123.00 Median:114.50 stddev:16.46 (14.4%) stddev-sans-first:14.52 10:49:44 INFO - PROCESS | 1668 | Values: 149.0 115.0 114.0 148.0 113.0 114.0 112.0 112.0 143.0 110.0 10:49:44 INFO - PROCESS | 1668 | 10:49:44 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.50 stddev:2.07 (1.1%) stddev-sans-first:2.18 10:49:44 INFO - PROCESS | 1668 | Values: 183.0 182.0 181.0 186.0 185.0 186.0 180.0 184.0 184.0 185.0 10:49:44 INFO - PROCESS | 1668 | 10:49:44 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:66.70 Median:62.00 stddev:11.05 (17.8%) stddev-sans-first:10.78 10:49:44 INFO - PROCESS | 1668 | Values: 79.0 60.0 58.0 87.0 61.0 59.0 60.0 59.0 81.0 63.0 10:49:44 INFO - PROCESS | 1668 | 10:49:44 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:85.60 Median:79.50 stddev:16.01 (20.1%) stddev-sans-first:16.98 10:49:44 INFO - PROCESS | 1668 | Values: 86.0 73.0 91.0 70.0 68.0 83.0 76.0 88.0 100.0 121.0 10:49:44 INFO - PROCESS | 1668 | 10:49:44 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:88.40 Median:89.50 stddev:2.37 (2.6%) stddev-sans-first:2.50 10:49:44 INFO - PROCESS | 1668 | Values: 89.0 89.0 88.0 91.0 91.0 86.0 90.0 86.0 90.0 84.0 10:49:44 INFO - PROCESS | 1668 | 10:49:44 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:97.30 Median:97.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 10:49:44 INFO - PROCESS | 1668 | Values: 97.0 97.0 98.0 97.0 97.0 98.0 97.0 97.0 98.0 97.0 10:49:44 INFO - PROCESS | 1668 | 10:49:44 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:87.00 Median:86.50 stddev:4.42 (5.1%) stddev-sans-first:4.69 10:49:44 INFO - PROCESS | 1668 | Values: 87.0 87.0 86.0 86.0 84.0 87.0 85.0 83.0 99.0 86.0 10:49:44 INFO - PROCESS | 1668 | 10:49:44 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:53.10 Median:53.00 stddev:1.29 (2.4%) stddev-sans-first:0.83 10:49:44 INFO - PROCESS | 1668 | Values: 56.0 53.0 52.0 52.0 52.0 54.0 53.0 52.0 54.0 53.0 10:49:44 INFO - PROCESS | 1668 | 10:49:44 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:71.50 stddev:4.16 (5.8%) stddev-sans-first:3.64 10:49:44 INFO - PROCESS | 1668 | Values: 64.0 74.0 64.0 77.0 74.0 69.0 71.0 71.0 71.0 72.0 10:49:44 INFO - PROCESS | 1668 | 10:49:44 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:86.70 Median:86.00 stddev:1.77 (2.1%) stddev-sans-first:1.86 10:49:44 INFO - PROCESS | 1668 | Values: 86.0 86.0 89.0 86.0 86.0 86.0 88.0 90.0 84.0 86.0 10:49:44 INFO - PROCESS | 1668 | 10:49:44 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:154.80 Median:153.50 stddev:13.10 (8.5%) stddev-sans-first:13.58 10:49:44 INFO - PROCESS | 1668 | Values: 147.0 162.0 153.0 159.0 154.0 144.0 147.0 149.0 188.0 145.0 10:49:44 INFO - PROCESS | 1668 | 10:49:44 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:172.50 stddev:1.91 (1.1%) stddev-sans-first:2.03 10:49:44 INFO - PROCESS | 1668 | Values: 172.0 169.0 171.0 173.0 175.0 172.0 174.0 170.0 173.0 170.0 10:49:44 INFO - PROCESS | 1668 | 10:49:44 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:68.00 stddev:0.92 (1.4%) stddev-sans-first:0.97 10:49:44 INFO - PROCESS | 1668 | Values: 68.0 70.0 68.0 68.0 68.0 69.0 67.0 67.0 68.0 69.0 10:49:44 INFO - PROCESS | 1668 | -------- Summary: end -------- 10:49:44 INFO - PROCESS | 1668 | 10:49:45 INFO - TEST-INFO | 1668: exit 0 10:49:45 INFO - TEST-OK | kraken | took 58957ms 10:49:45 INFO - SUITE-END | took 1177s 10:49:45 INFO - Completed test suite (00:19:38) 10:49:45 INFO - javascript benchmark 10:49:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3267.1668724475257, 3282.524034015068, 3306.4048471275178, 3340.665933406658, 3356.4294070008095, 5570.442955704428, 5682.687452190138, 5712.628679135853, 5771.711414429277, 5809.883802323956, 5599.496045355917, 5661.264035675366, 5662.688552129631, 5693.402192769751, 5713.257276554053, 5106.591472682177, 5123.436421993578, 5128.820491282806, 5191.948080519184, 5207.244949482323, 5294.708791016493, 5294.973525132388, 5304.283921670563, 5310.522053015228, 5361.51746342829, 5100.387953445573, 5263.605229607795, 5272.973635131838, 5283.154695248761, 5344.91982620261, 5133.666311689765, 5144.099782538041, 5213.53078222958, 5231.450697676765, 5373.516383525502, 5125.179971204627, 5128.076946149691, 5131.871703207413, 5150.690958542499, 5170.327857378555, 5710.200571919893, 5755.251817263771, 5778.855528611818, 5781.508571771405, 5783.797567085132, 6122.204113465265, 6135.477655820245, 6138.140660307516, 6140.350877192993, 6141.631502109843, 6124.564025909317, 6126.79250549526, 6206.565540412173, 6212.378762123797, 6228.906566401508, 5785.681787501686, 5801.9129713054335, 5804.912926306109, 5806.796762113306, 5823.5341172706085, 6123.466143003804, 6174.753009879644, 6222.719977601018, 6271.435570798559, 6276.905846412307, 6112.07796728157, 6139.925513035201, 6146.323904370515, 6151.630902145886, 6154.5076393888385, 6133.754649813957, 6189.876202476015, 6198.2871969723155, 6203.720832562545, 6230.127782110508, 6024.57827952048, 6095.05526643371, 6164.722587483574, 6190.783322583778, 6192.659403732838, 6123.09992524296, 6125.908714686065, 6130.233720784902, 6137.292318144079, 6144.678894037804, 4270.402143699823, 4942.357493525818, 4998.400191976913, 5005.27423523587, 5007.098722230034, 4348.0, 4415.183191109655, 4423.1153769246275, 4432.15789000089, 4449.466064072268, 5685.175649530877, 5798.304203495523, 5801.071828507418, 5802.564807639444, 5806.245188125601, 5221.843344699665, 6123.801220575414, 6128.044828652092, 6182.0, 6197.938020619827, 5763.6541807491685, 5767.798127065533, 5784.3926387729525, 5796.217510636095, 5831.737571809279, 4699.483056863743, 5419.187121931663, 5428.240046393508, 5430.0768869292315, 5443.2107344434835, 6209.441150296495, 6210.689465526796, 6279.748810047547, 6344.175257216532, 6365.649889256136, 6190.41191086846, 6256.5620406572925, 6288.151099601679, 6303.054541818764, 6304.936950630435, 6326.240851097898, 6329.90505142414, 6351.650659213603, 6351.650659213788, 6359.395857393541, 5799.8550036249435, 5829.76680932758, 5830.679312637676, 6121.081837724377, 6163.229596300463, 6142.191841058572, 6157.599756015845, 6159.322474527708, 6170.444659980623, 6216.53375996793, 6127.6936153193055, 6152.046432802923, 6152.600080994721, 6160.44555989963, 6168.691565421801, 4942.382202224722, 6101.572889897842, 6149.323574406902, 6152.230971128609, 6168.537359698131, 5185.066687996046, 5826.504747096545, 5864.149698293809, 5902.6458412495385, 6001.189839371629, 6107.595253091903, 6120.785772497941, 6145.8156255312415, 6147.754089836357, 6150.846228844314, 5855.824325270249, 5926.911096333644, 5928.79249226275, 5935.495482884004, 5942.762289508371], "value": 5668.310151862819, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [434.93693414454907, 494.4264653002518, 508.5778803593017, 536.7530935769546, 555.4695266020949, 31717.048488545322, 32100.197495062606, 34504.827475862585, 40600.59399406005, 41676.58323416765, 41948.0, 42273.0, 42286.577134228646, 42345.57654423455, 42398.0, 9696.0788725071, 9890.406575605486, 9894.010598940084, 9896.40621562705, 9897.15874150698, 8732.170443807829, 8960.731178064669, 11859.52561897523, 12104.576339828107, 12129.150959432844, 12167.878321216807, 12176.0, 12197.451114699816, 12223.938880305586, 12243.326617036104, 8719.084496127878, 8918.821623567557, 12075.93962030193, 12155.453004614725, 12164.574239901562, 8772.868406973901, 8843.204111629984, 8848.336374771918, 11613.0, 11945.0, 39924.0, 40298.0, 40339.394909076385, 40433.191336173404, 40433.797831010954, 3257.5113732940013, 3275.7215636670912, 3284.474484082535, 3294.4728843385183, 3593.2633810068874, 30577.694223057708, 30697.846510767304, 30808.229794255323, 31013.37973240545, 31763.0, 3206.9198270043203, 3214.5017522284083, 3224.339010502841, 3241.837908104609, 3301.7028467438045, 490350.0, 491748.0, 492311.0, 492374.0, 494829.9999999964, 48689.0, 49906.75046624744, 56772.0, 58426.4157358421, 58480.70759646175, 61019.69490152521, 61492.0, 61498.69250653808, 61563.0, 61571.07643385353, 15542.300596473186, 15761.29074191664, 15782.763258551131, 15786.526404207892, 15851.365945362057, 11100.888991110147, 11510.30938143714, 12208.206466579644, 12338.691532711575, 12353.011759059433, 14184.0, 15715.449959251371, 15862.365505379656, 16130.838691613168, 16296.674066518603, 6351.2060992375955, 6364.281643956106, 6384.106225128486, 6393.7762178324465, 6409.134766806422, 942.6559305853314, 953.8283109040301, 1000.9449480278508, 1012.6000229909145, 1026.9948650256702, 28727.0, 29074.127776166748, 29597.0, 29741.85129074384, 29768.660410281154, 930.8696782450462, 986.9013098690217, 1023.1303392116641, 1047.701405099543, 1140.948657310423, 12620.621381358575, 15185.772213416807, 15207.695846083237, 15321.770173447407, 15602.609934751723, 194960.0, 195983.0, 196596.03403966062, 196642.0, 197148.0, 56801.0, 56907.71546142243, 57009.0, 57037.42962570321, 57207.427925721044, 1199.3703305764543, 1232.2975903734784, 1350.601572536124, 1372.0601388049217, 1404.1645221093434, 26261.606075908494, 29458.558121628623, 30440.391192176474, 30598.84700576483, 31034.0689779307, 47525.52474475211, 48027.5197248037, 48056.75971620259, 48091.0, 48137.7965550864, 519139.0, 520173.0, 521620.0, 522288.0, 522938.38530808623, 37555.0, 38938.22123557569, 39059.41410878782, 39084.60915390924, 39162.41256381213, 45923.31115033344, 45982.08035839198, 46584.767076164404, 46859.06281874412, 47202.527974719815, 38044.23911521809, 38209.40531526566, 38390.616093838704, 39100.80449597734, 39520.20959580735, 36782.44826327661, 37874.86375408742, 37986.62013379831, 38020.429693555176, 38901.0, 37195.6280437203, 38285.42571861369, 38452.615473844904, 38545.807270963465, 38546.61453385543, 27105.714385695388, 29864.85067574735, 34376.48435273523, 34387.48418773671, 34513.30973380468, 23373.41566460852, 24513.754862451147, 24739.752602473745, 24912.37719057038, 25478.85345159435], "value": 19243.008188341773, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.68648038441944, 353.1559572621434, 375.91729819439723, 377.78655059891145, 407.7064513550243, 13106.60680179595, 14318.642033949143, 15130.394784208633, 20737.066831992557, 21183.470413239676, 19796.406107816754, 19929.800701993012, 20297.0866311016, 20626.1749530019, 20883.477913052182, 5264.8683782905355, 5802.680852553108, 5949.345571987078, 5980.102984552308, 6019.909701354483, 5013.498650134976, 6058.212432383784, 6087.2999605045325, 6108.81673549792, 6125.509959203276, 5718.799842005531, 6053.667048312341, 6061.727222274986, 6065.181200537915, 6065.878682426349, 5848.766049358021, 5972.522198224175, 5972.820815375502, 5973.761049558012, 5988.251468566429, 4399.274119770234, 5013.0, 5199.076341753537, 5208.635395522315, 5226.477352264781, 25996.09013684531, 26049.479010419876, 26130.73869261302, 26250.34374140643, 26414.6037809433, 2168.5988092202992, 2304.4584522637165, 2497.21337778601, 2559.83361081531, 2583.057184127799, 15588.29852656622, 15660.765088523682, 15706.0, 16166.676666466723, 16432.0, 1837.283459450802, 1853.898035608041, 1901.1254822781536, 1951.4828570428847, 1954.6383918974893, 119124.40437797843, 123747.38126309401, 124221.37889310587, 124583.37708311492, 126016.36991814982, 10149.847752283722, 10399.53202105892, 10807.0, 10856.620018299322, 11005.0, 13597.184168949894, 13646.317684115755, 13655.453781848813, 13678.452861885613, 13688.863111368959, 8190.754277371689, 8447.155284471504, 8451.746447606582, 8615.13848615146, 8634.568271586395, 8488.957555212184, 8552.315814734806, 8559.914400856036, 8685.739427817176, 8703.95648021756, 6512.674366281667, 6662.834004049272, 6673.733050678015, 6682.632455214913, 6795.2185498667295, 1639.467173168701, 1762.3169872771473, 1808.484581894154, 1809.0683298101487, 1809.4571628511394, 749.7638243953137, 872.6553011560525, 1004.2167109654481, 1027.465717826726, 1029.953652085658, 21405.374076923934, 22039.93189908679, 22057.0, 22086.11655533793, 22132.89335533217, 825.7027470110755, 842.806154584449, 925.9444433334022, 990.9248465414961, 999.9950000250097, 11052.447377631086, 11561.537538498535, 11585.062570877079, 11608.825867611993, 11640.708982275512, 576284.0, 584152.0, 588865.0, 590641.0, 596183.0, 85020.0, 109921.0, 112465.0, 113924.86075139143, 116420.0, 961.543266948208, 1001.3891526169031, 1019.3781793106348, 1027.4759872465097, 1032.5147180825, 15265.542033739006, 18276.177572009026, 18907.90546047316, 19155.425337239347, 19274.132664029872, 49067.263991040876, 49169.75415122901, 49439.25841112314, 50497.0, 50539.0, 68673.65663171653, 68883.0, 69321.0, 69694.30305696878, 69951.0, 6309.211348581427, 6994.746199484453, 7171.49799514029, 7687.423443241891, 7887.408444366581, 12581.622551323475, 12743.553975610808, 12881.871181288441, 12893.419796109385, 12935.741285174432, 10958.506867190836, 11036.792641471899, 11189.496472658588, 11211.71970700739, 11271.267367621078, 11119.0, 11156.609518666808, 11271.718207044889, 11510.59712910044, 11768.058555315383, 10336.328138670962, 11091.0, 11367.261128026652, 11634.36011019402, 11663.88336116628, 7554.4334174936, 7654.846903061796, 7920.603969801372, 8213.753587392388, 8260.421770476009, 9352.625894964398, 10710.357378557312, 11100.555977761122, 11286.266392684449, 11368.147388945697], "value": 9587.492764929058, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1524.5045360257916, 1544.1893006171758, 1655.5033489953012, 1736.2100244388805, 1898.6297671953967, 7713.884291735614, 8479.491230526173, 8933.106689331064, 8939.64241430342, 8942.239909607675, 7180.102487189101, 7849.5682737449415, 8342.91657083429, 8669.696560620363, 8701.0, 2334.8832558372055, 2367.5975084235633, 2369.869657168855, 2370.5258948210426, 2371.2412028150998, 2345.1205797825814, 2366.053578568578, 2374.7031621047367, 2380.083667787897, 2382.5711371953043, 1995.49114975681, 2008.4075197816678, 2344.941376465585, 2358.6697862299293, 2365.73976862545, 2335.0543030072763, 2337.94155146121, 2342.5197834443898, 2342.8008619267384, 2342.871142087176, 2283.9885800571055, 2315.4790172211287, 2316.420894776306, 2318.744938056829, 2327.55776402483, 8761.780955476164, 8842.204201621886, 8872.689455869078, 8876.0, 8913.420627659181, 5149.588032957355, 5317.760700768475, 5319.946800531984, 5319.9734001330135, 5325.360956685223, 3413.812240326781, 3436.810975396352, 3534.9823250883837, 3583.1937813991904, 3647.1429214134655, 1043.8695163104612, 1086.1528008153653, 1090.8437056719902, 1111.360967443723, 1111.7109551516583, 11644.592439264585, 11684.415779211005, 11703.946644801923, 11708.53165873364, 11713.590024349107, 4086.346184610448, 4518.892871246566, 4539.114872599811, 4636.698614590041, 4746.810127594927, 9798.412095274307, 9887.258455615858, 9900.059494348025, 9902.55438505269, 9911.504424778732, 4555.931661025087, 5270.973645131749, 5281.023010743025, 5293.602979776532, 5319.627626066215, 4312.978435107867, 4361.476622805284, 4810.63920205986, 4824.372831531879, 4856.077345304381, 3804.410316400907, 3804.9346183068783, 3887.650111489923, 3925.9018524537114, 3943.6056394360335, 1244.589285535771, 1248.2697621891114, 1251.3367915005063, 1255.9811602825964, 1264.6395777203452, 790.909045459768, 886.6434355436193, 914.8581969794559, 920.7283851263893, 949.3307218411004, 5731.770729170787, 5741.598088133874, 5875.559333050039, 5942.584019118706, 6081.787137450168, 447.85668586052805, 456.40211323166756, 459.60473992366536, 1005.1305620638095, 1005.7284533176148, 3184.8726050958167, 3545.3263879862743, 3893.396523538856, 3919.984036715543, 3991.16185601027, 9096.8635470468, 10230.59077636901, 10608.992145746142, 10681.985211404904, 10693.411862347646, 10606.628767993083, 10609.469526523797, 10619.20355973305, 10626.0, 10636.521356538975, 6528.575642583217, 8048.315893149031, 8058.879116813252, 8067.878981815277, 8125.431219814556, 5634.943650563606, 5760.308762948473, 5763.193152958505, 5775.624584402, 5793.478586927028, 9528.0, 9625.133737963617, 9847.753806154617, 9965.302428829913, 10008.84986725185, 9410.294227932796, 9714.80570388574, 9858.654947076819, 10207.0, 10213.591456341961, 6796.830079248059, 7251.891221631574, 7424.925750742639, 7580.734674286374, 7590.468667193243, 8277.213664701843, 8456.492610443393, 8459.661613535636, 8460.280876125353, 8461.40770146084, 7344.779656610311, 7422.220666829905, 7544.6227688616445, 7606.657700403384, 7676.961615191888, 7256.637168141678, 7350.632468376667, 7369.0, 7508.2867127622785, 7564.659590318339, 6023.728932198149, 6845.041694162722, 7003.01957725929, 7007.614581198083, 7020.7542736004, 4755.762211889461, 4992.750362481789, 5025.221090730943, 5043.798248070183, 5121.8463446096675, 4170.854020109282, 4535.979404633931, 4960.181570040902, 4962.751862406793, 5114.769835357343], "value": 4485.008353082247, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [266.8318959055795, 272.5380480086253, 306.725480694778, 314.410480349345, 323.0566745104295, 4833.758312084399, 5083.542481176693, 5470.64440811347, 5493.0724531959595, 5516.889662206753, 4639.814407423707, 4996.600271978242, 5233.842984710455, 5270.8418747437545, 5445.401005889349, 3867.7872717000623, 4079.857639860844, 4113.526944401402, 4157.438745769327, 4161.334186530155, 3746.3256613809504, 4118.320477121271, 4118.755278373279, 4129.648979836717, 4141.295979683461, 3168.7325069971967, 3611.458281257806, 4048.0487085534874, 4120.0, 4131.6281534661875, 3548.822558872046, 3960.5049368828895, 4130.731502452331, 4148.294789885712, 4184.246835569596, 2962.3706961171483, 3530.276293359855, 3535.575730912307, 3543.192971584485, 3550.3076900004507, 4115.629593336614, 4579.519150489184, 5202.4277329493725, 5311.49540793624, 5322.654027488201, 4472.530384309665, 4531.818727250906, 4713.787879545394, 4716.245400735866, 4724.031573527419, 5719.399463056361, 5724.5992780505385, 5739.311282646067, 5739.3399759027825, 5773.257702591278, 3635.418333066723, 3873.9418908716393, 3892.961070389288, 3900.6489415952415, 3919.608039196086, 5314.867128321745, 5360.973195133999, 5553.916986187695, 5605.663660180361, 5653.0, 3900.1614652849776, 3913.295606790748, 3930.587288334741, 3947.743396679207, 3965.385365268388, 3701.629837016331, 4029.2143032108456, 4043.73715708478, 4047.392891066305, 4058.7970601470397, 3929.351656976624, 4047.886831121465, 4436.733795972252, 4658.720476771405, 4659.534046595313, 5768.50967667753, 5782.855428614318, 5782.913256301159, 5798.246227990334, 5805.303363596396, 1296.6369416563377, 1362.5980335800962, 1375.7936309553647, 1378.2764048874221, 1385.923774192409, 822.9711960081369, 845.8181490979346, 879.7712594725417, 917.9449233045905, 919.7148883846029, 4365.650747940221, 5100.48995100487, 5367.021303176774, 5371.919421208685, 5417.83746487606, 5294.841154765363, 5400.027994960945, 5669.489745922887, 5854.326752423526, 5945.583809133319, 4437.0, 4750.311204875274, 5338.519533242027, 5414.3502779665905, 5430.239766432703, 2587.8835452404687, 2711.6745990481268, 2745.437185376993, 2939.0448104365737, 2966.9912229841957, 5865.442782935615, 6113.14415981754, 6173.629582225081, 6186.133941248228, 6198.442140207403, 5015.3480047593575, 5291.433654677478, 5940.8810374553705, 6006.279246490449, 6018.548608854353, 4895.828645997373, 5313.229581710631, 5739.512141467939, 6091.258933479863, 6132.448079672761, 5280.841574752763, 5289.2330612061805, 5850.64896106235, 5875.73559189846, 5919.543389493312, 4567.223571992836, 5135.075686376488, 5280.392754833243, 5530.115181570929, 5598.916016259678, 4613.723176609415, 5139.94631100631, 6117.541184411276, 6138.846528836815, 6142.560166306883, 3184.9522257166623, 3872.186840763471, 3893.454916311662, 3907.3430067554336, 3926.234384295035, 4951.935333903228, 5163.3545806774155, 5718.827640333639, 5842.532597392284, 5916.911246331395, 5384.407715151408, 5414.377346605191, 5666.46168613981, 5742.741576629145, 5777.62445441045, 4842.4673285939225, 5840.445157710011, 5843.620164689281, 5850.723594786933, 5854.707264636836, 4670.6263498919325, 5448.591355648421, 5711.8299343864555, 5733.283339582552, 5742.138679198154, 4543.45478542577, 5030.496950305087, 5721.227634269487, 5774.94225057744, 5850.853728656647, 4429.557044295545, 4520.34455004016, 4820.783064762024, 5656.434356564475, 5741.799037033684], "value": 4099.851335225283, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [643.2474005413666, 712.5582139073773, 758.8823732321489, 777.7122464688066, 814.959252037398, 14335.856641433582, 16004.67990640188, 16022.519324420256, 16127.677446451064, 16142.677146457063, 14381.928090359559, 14557.563273101823, 14569.562913112597, 14575.27123643819, 14842.851571484282, 842.5534466732641, 854.9572521373922, 860.8407444622765, 883.3242569434387, 884.4648987362649, 864.9654013839439, 866.4714524140269, 867.1891781184581, 867.2411639815161, 867.7440155154245, 706.6749295324157, 730.6018220070075, 892.6830975003877, 913.4975763330175, 918.9402688825238, 909.7725568607848, 913.5432283858071, 914.0037359278418, 914.8536234202562, 917.5779141594875, 673.3132205150719, 674.9696263668146, 675.0245894682187, 675.3550359406755, 675.7128220506265, 11875.52497900083, 12349.320787356692, 12361.443735031946, 15689.058656480647, 15802.051876887424, 12349.320787356692, 13862.4455021799, 14030.228337441436, 14201.36093875778, 14242.85757142436, 9801.460919649417, 11907.702307442296, 11911.285322880654, 12013.219140755822, 12091.818622720666, 3796.9686217873304, 3803.353429916921, 3806.0, 3835.2137811748526, 3955.960440395588, 121242.0, 123006.0, 123465.0, 124047.13929291068, 124057.75942240552, 10812.675619731262, 10898.0, 10997.0, 11140.275882067639, 11455.427228638702, 21708.0, 21733.67399489009, 21752.12991480323, 21767.23814666479, 21790.564188716136, 7812.687492500237, 7822.765317040498, 7824.921750782533, 7895.960520197362, 7899.210078992055, 4415.823367065345, 4437.400950871591, 4440.178566965122, 4511.120331535319, 4602.562756538124, 3504.299140171976, 3591.263790922907, 3615.349237137288, 3622.5290712207243, 3627.582827974764, 641.576559470747, 655.0992385469979, 657.2015001772869, 664.0868805392586, 673.9157605299338, 597.2743117112642, 605.1588292273743, 640.8846407646575, 665.751715533375, 666.1473314157886, 8949.59726812282, 9338.813223735488, 9482.668106616373, 9700.805983880424, 9702.223822094216, 642.194046471675, 672.9394354508117, 673.0879658063352, 673.8551211489553, 684.6576711644178, 4328.978355108205, 4552.88617784558, 4592.7933243004145, 4647.395838541036, 4671.299305104194, 93951.53024234972, 94142.5292873545, 94262.0, 94319.52840235892, 158598.0, 140001.0, 141214.0, 141878.0, 143038.974543564, 144611.0, 99757.50121249493, 120280.0, 122383.0, 122799.38600306942, 127967.0, 8939.284857211278, 8946.910530894867, 8951.820963580822, 8964.731058068268, 9207.539623018689, 56242.0, 64077.67961160164, 65094.67452662707, 65597.0, 65895.34104658892, 98293.50853245688, 101295.0, 105365.0, 108121.0, 108248.0, 10953.34279943206, 11105.722356940818, 11227.3615700312, 11321.320720756781, 11523.888505410465, 12612.369381530703, 12886.806697899352, 12898.806517902427, 12960.0, 13095.738085238052, 13861.584152475441, 14026.158430494203, 14087.225202613954, 14324.283785810876, 14723.043002204822, 13672.0, 13766.518171863936, 14384.0, 14523.389122379533, 14535.055221410574, 13456.730865382833, 13635.25006124633, 13834.93082534581, 13916.930415347859, 13982.236740853878, 9901.257405694745, 11352.301711115933, 11668.708282292671, 11908.821367679318, 12074.39628018613, 10803.891961080288, 11274.154438417316, 11534.0, 11568.652940411801, 11601.477933492844], "value": 7811.639654605587, "unit": "score"}], "name": "dromaeo_css", "value": 7291.078444511374, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 112.0, 113.0, 110.0, 114.0, 113.0, 113.0, 113.0, 113.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 115.0, 114.0, 148.0, 113.0, 114.0, 112.0, 112.0, 143.0, 110.0], "value": 123.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 182.0, 181.0, 186.0, 185.0, 186.0, 180.0, 184.0, 184.0, 185.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 60.0, 58.0, 87.0, 61.0, 59.0, 60.0, 59.0, 81.0, 63.0], "value": 66.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 73.0, 91.0, 70.0, 68.0, 83.0, 76.0, 88.0, 100.0, 121.0], "value": 85.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 88.0, 91.0, 91.0, 86.0, 90.0, 86.0, 90.0, 84.0], "value": 88.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 98.0, 97.0, 97.0, 98.0, 97.0, 97.0, 98.0, 97.0], "value": 97.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 86.0, 86.0, 84.0, 87.0, 85.0, 83.0, 99.0, 86.0], "value": 87.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 52.0, 52.0, 52.0, 54.0, 53.0, 52.0, 54.0, 53.0], "value": 53.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 74.0, 64.0, 77.0, 74.0, 69.0, 71.0, 71.0, 71.0, 72.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 89.0, 86.0, 86.0, 86.0, 88.0, 90.0, 84.0, 86.0], "value": 86.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 162.0, 153.0, 159.0, 154.0, 144.0, 147.0, 149.0, 188.0, 145.0], "value": 154.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 169.0, 171.0, 173.0, 175.0, 172.0, 174.0, 170.0, 173.0, 170.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 68.0, 68.0, 68.0, 69.0, 67.0, 67.0, 68.0, 69.0], "value": 68.2, "unit": "score"}], "name": "kraken", "value": 1450.0, "alertThreshold": 2.0}]} 10:49:45 INFO - Return code: 0 10:49:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:49:45 INFO - # TBPL SUCCESS # 10:49:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:49:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:49:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:49:45 INFO - 2016-10-07 10:49:45.570 screenresolution[1672:10149] starting screenresolution argv=screenresolution get 10:49:45 INFO - 2016-10-07 10:49:45.573 screenresolution[1672:10149] Display 0: 1600x1200x32@60 10:49:45 INFO - 2016-10-07 10:49:45.579 screenresolution[1673:10152] starting screenresolution argv=screenresolution list 10:49:45 INFO - Available Modes on Display 0 10:49:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:49:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:49:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:49:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:49:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:49:45 INFO - Intel Iris: 10:49:45 INFO - Chipset Model: Intel Iris 10:49:45 INFO - Type: GPU 10:49:45 INFO - Bus: Built-In 10:49:45 INFO - VRAM (Dynamic, Max): 1536 MB 10:49:45 INFO - Vendor: Intel (0x8086) 10:49:45 INFO - Device ID: 0x0a2e 10:49:45 INFO - Revision ID: 0x0009 10:49:45 INFO - Displays: 10:49:45 INFO - Display: 10:49:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:49:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:49:45 INFO - Display Serial Number: 1600x1200 60 10:49:45 INFO - Main Display: Yes 10:49:45 INFO - Mirror: Off 10:49:45 INFO - Online: Yes 10:49:45 INFO - Rotation: Supported 10:49:45 INFO - Return code: 0 10:49:45 INFO - Running post-action listener: _resource_record_post_action 10:49:45 INFO - [mozharness: 2016-10-07 17:49:45.815479Z] Finished run-tests step (success) 10:49:45 INFO - Running post-run listener: _resource_record_post_run 10:49:45 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 33057792; Write bytes: 709132288; Read time: 309; Write time: 1926 10:49:45 INFO - TinderboxPrint: CPU usage
53.4% 10:49:45 INFO - TinderboxPrint: I/O read bytes / time
33,057,792 / 309 10:49:45 INFO - TinderboxPrint: I/O write bytes / time
709,132,288 / 1,926 10:49:45 INFO - TinderboxPrint: CPU idle
2,261.1 (46.6%) 10:49:45 INFO - TinderboxPrint: CPU system
968.2 (19.9%) 10:49:45 INFO - TinderboxPrint: CPU user
1,624.4 (33.5%) 10:49:45 INFO - TinderboxPrint: Swap in / out
436,662,272 / 0 10:49:45 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 400394752; Write bytes: 394795008; Read time: 29624; Write time: 786 10:49:45 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 31050752; Write bytes: 297379840; Read time: 287; Write time: 1097 10:49:46 INFO - Running post-run listener: _upload_blobber_files 10:49:46 INFO - Blob upload gear active. 10:49:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:49:46 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 10:49:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:49:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:49:46 INFO - (blobuploader) - INFO - Open directory for files ... 10:49:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:49:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:49:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:49:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:49:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:49:47 INFO - (blobuploader) - INFO - Done attempting. 10:49:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:49:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:49:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:49:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:49:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:49:48 INFO - (blobuploader) - INFO - Done attempting. 10:49:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:49:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:49:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:49:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:49:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:49:51 INFO - (blobuploader) - INFO - Done attempting. 10:49:51 INFO - (blobuploader) - INFO - Iteration through files over. 10:49:51 INFO - Return code: 0 10:49:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:49:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:49:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b9b39d44a3f0c647ab8cbd0f81ad2c97c5b8e97e12ad9baab11aaa907bc647ff037e329ba7f22fa0c9bbc1eb3a6c45bd05c8144024c35a75c997e1403534fff3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e9f63981660e97d0b75a6e30c2a4f56f507965c8dc385917c5929fda0082291bccacd607436d9fb84f1fcff00b473c3918aad18d57b1a87d39d0840884386d96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aaea12fd62dbef8af514faba23eba04e415e9b73d3b80b4c9d62709605aaa3d7d6cb6dbc68bc9a6c7f787e09c1024fba43c882b11038a3f8b2ccafd58e6b7adc"} 10:49:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:49:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:49:51 INFO - Contents: 10:49:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b9b39d44a3f0c647ab8cbd0f81ad2c97c5b8e97e12ad9baab11aaa907bc647ff037e329ba7f22fa0c9bbc1eb3a6c45bd05c8144024c35a75c997e1403534fff3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e9f63981660e97d0b75a6e30c2a4f56f507965c8dc385917c5929fda0082291bccacd607436d9fb84f1fcff00b473c3918aad18d57b1a87d39d0840884386d96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aaea12fd62dbef8af514faba23eba04e415e9b73d3b80b4c9d62709605aaa3d7d6cb6dbc68bc9a6c7f787e09c1024fba43c882b11038a3f8b2ccafd58e6b7adc"} 10:49:51 INFO - Running post-run listener: copy_logs_to_upload_dir 10:49:51 INFO - Copying logs to upload dir... 10:49:51 INFO - mkdir: /builds/slave/test/build/upload/logs 10:49:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.551798 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-07 10:49:51.162196) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-07 10:49:51.166321) ========= 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.EQ4lfYSN61/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.8fRsz2aG6y/Listeners TMPDIR=/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b9b39d44a3f0c647ab8cbd0f81ad2c97c5b8e97e12ad9baab11aaa907bc647ff037e329ba7f22fa0c9bbc1eb3a6c45bd05c8144024c35a75c997e1403534fff3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e9f63981660e97d0b75a6e30c2a4f56f507965c8dc385917c5929fda0082291bccacd607436d9fb84f1fcff00b473c3918aad18d57b1a87d39d0840884386d96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aaea12fd62dbef8af514faba23eba04e415e9b73d3b80b4c9d62709605aaa3d7d6cb6dbc68bc9a6c7f787e09c1024fba43c882b11038a3f8b2ccafd58e6b7adc"} build_url:https://queue.taskcluster.net/v1/task/Hv6xpYxVQ36GDDSB25jGJA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011246 build_url: 'https://queue.taskcluster.net/v1/task/Hv6xpYxVQ36GDDSB25jGJA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b9b39d44a3f0c647ab8cbd0f81ad2c97c5b8e97e12ad9baab11aaa907bc647ff037e329ba7f22fa0c9bbc1eb3a6c45bd05c8144024c35a75c997e1403534fff3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e9f63981660e97d0b75a6e30c2a4f56f507965c8dc385917c5929fda0082291bccacd607436d9fb84f1fcff00b473c3918aad18d57b1a87d39d0840884386d96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aaea12fd62dbef8af514faba23eba04e415e9b73d3b80b4c9d62709605aaa3d7d6cb6dbc68bc9a6c7f787e09c1024fba43c882b11038a3f8b2ccafd58e6b7adc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-07 10:49:51.201410) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 10:49:51.202027) ========= 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.EQ4lfYSN61/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.8fRsz2aG6y/Listeners TMPDIR=/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/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.005297 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 10:49:51.254720) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-07 10:49:51.255143) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-07 10:49:51.255501) ========= ========= Total master_lag: 0.18 =========