builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0149 starttime: 1479834387.84 results: success (0) buildid: 20161122064750 builduid: b620ef547add4eadae085b811eb67b90 revision: d5d357b7ca3095e311add0e06bf9027cb9c5dce7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 09:06:27.839723) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 09:06:27.840161) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 09:06:27.840431) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 09:06:27.866691) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 09:06:27.867042) ========= 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.kVXKlQ80hP/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.TGM1ANHsYG/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-11-22 09:06:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 16.5M=0.001s 2016-11-22 09:06:28 (16.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127153 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 09:06:28.018275) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 09:06:28.018583) ========= 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.kVXKlQ80hP/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.TGM1ANHsYG/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.028438 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 09:06:28.068972) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 09:06:28.069324) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d5d357b7ca3095e311add0e06bf9027cb9c5dce7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev d5d357b7ca3095e311add0e06bf9027cb9c5dce7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kVXKlQ80hP/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.TGM1ANHsYG/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-11-22 09:06:28,166 truncating revision to first 12 chars 2016-11-22 09:06:28,166 Setting DEBUG logging. 2016-11-22 09:06:28,166 attempt 1/10 2016-11-22 09:06:28,166 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d5d357b7ca30?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-22 09:06:28,859 unpacking tar archive at: mozilla-central-d5d357b7ca30/testing/mozharness/ program finished with exit code 0 elapsedTime=0.957134 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 09:06:29.050954) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 09:06:29.051340) ========= script_repo_revision: d5d357b7ca3095e311add0e06bf9027cb9c5dce7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 09:06:29.051879) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 09:06:29.052308) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 09:06:29.072361) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-22 09:06:29.072774) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kVXKlQ80hP/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.TGM1ANHsYG/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 09:06:29 INFO - MultiFileLogger online at 20161122 09:06:29 in /builds/slave/test 09:06:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:06:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:06:29 INFO - {'append_to_log': False, 09:06:29 INFO - 'base_work_dir': '/builds/slave/test', 09:06:29 INFO - 'blob_upload_branch': 'Firefox', 09:06:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:06:29 INFO - 'branch': 'Firefox', 09:06:29 INFO - 'buildbot_json_path': 'buildprops.json', 09:06:29 INFO - 'config_files': ('talos/mac_config.py',), 09:06:29 INFO - 'default_actions': ('clobber', 09:06:29 INFO - 'read-buildbot-config', 09:06:29 INFO - 'download-and-extract', 09:06:29 INFO - 'populate-webroot', 09:06:29 INFO - 'create-virtualenv', 09:06:29 INFO - 'install', 09:06:29 INFO - 'run-tests'), 09:06:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:06:29 INFO - 'download_minidump_stackwalk': True, 09:06:29 INFO - 'download_symbols': 'ondemand', 09:06:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:06:29 INFO - 'tooltool.py': '/tools/tooltool.py', 09:06:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:06:29 INFO - '/tools/misc-python/virtualenv.py')}, 09:06:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:06:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:06:29 INFO - 'installer_path': 'installer.exe', 09:06:29 INFO - 'log_level': 'info', 09:06:29 INFO - 'log_name': 'talos', 09:06:29 INFO - 'log_to_console': True, 09:06:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:06:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:06:29 INFO - 'opt_config_files': (), 09:06:29 INFO - 'pip_index': False, 09:06:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:06:29 INFO - 'cmd': ('bash', 09:06:29 INFO - '-c', 09:06:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:06:29 INFO - 'enabled': True, 09:06:29 INFO - 'halt_on_failure': False, 09:06:29 INFO - 'name': 'check_screen_resolution'},), 09:06:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:06:29 INFO - 'cmd': ('bash', 09:06:29 INFO - '-c', 09:06:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:06:29 INFO - 'enabled': True, 09:06:29 INFO - 'halt_on_failure': False, 09:06:29 INFO - 'name': 'check_screen_resolution'},), 09:06:29 INFO - 'run_cmd_checks_enabled': True, 09:06:29 INFO - 'sps_profile': False, 09:06:29 INFO - 'sps_profile_interval': 0, 09:06:29 INFO - 'suite': 'dromaeojs-e10s', 09:06:29 INFO - 'system_bits': '32', 09:06:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:06:29 INFO - 'title': 't-yosemite-r7-0149', 09:06:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:06:29 INFO - 'use_talos_json': True, 09:06:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:06:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:06:29 INFO - 'work_dir': 'build'} 09:06:29 INFO - [mozharness: 2016-11-22 17:06:29.280553Z] Running clobber step. 09:06:29 INFO - Running pre-action listener: _resource_record_pre_action 09:06:29 INFO - Running main action method: clobber 09:06:29 INFO - rmtree: /builds/slave/test/build 09:06:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:06:31 INFO - Running post-action listener: _resource_record_post_action 09:06:31 INFO - [mozharness: 2016-11-22 17:06:31.436413Z] Finished clobber step (success) 09:06:31 INFO - [mozharness: 2016-11-22 17:06:31.436581Z] Running read-buildbot-config step. 09:06:31 INFO - Running pre-action listener: _resource_record_pre_action 09:06:31 INFO - Running main action method: read_buildbot_config 09:06:31 INFO - Using buildbot properties: 09:06:31 INFO - { 09:06:31 INFO - "project": "", 09:06:31 INFO - "product": "firefox", 09:06:31 INFO - "script_repo_revision": "production", 09:06:31 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:06:31 INFO - "repository": "", 09:06:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:06:31 INFO - "buildid": "20161122064750", 09:06:31 INFO - "pgo_build": "False", 09:06:31 INFO - "basedir": "/builds/slave/test", 09:06:31 INFO - "buildnumber": 71, 09:06:31 INFO - "slavename": "t-yosemite-r7-0149", 09:06:31 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 09:06:31 INFO - "platform": "yosemite_r7", 09:06:31 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:06:31 INFO - "branch": "mozilla-central", 09:06:31 INFO - "repo_path": "mozilla-central", 09:06:31 INFO - "revision": "d5d357b7ca3095e311add0e06bf9027cb9c5dce7", 09:06:31 INFO - "stage_platform": "macosx64", 09:06:31 INFO - "builduid": "b620ef547add4eadae085b811eb67b90", 09:06:31 INFO - "slavebuilddir": "test" 09:06:31 INFO - } 09:06:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/IupauqylRJehdgeBkvEWDw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 09:06:31 INFO - Running post-action listener: _resource_record_post_action 09:06:31 INFO - [mozharness: 2016-11-22 17:06:31.439480Z] Finished read-buildbot-config step (success) 09:06:31 INFO - [mozharness: 2016-11-22 17:06:31.439592Z] Running download-and-extract step. 09:06:31 INFO - Running pre-action listener: _resource_record_pre_action 09:06:31 INFO - Running main action method: download_and_extract 09:06:31 INFO - mkdir: /builds/slave/test/build/tests 09:06: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')]} 09:06:31 INFO - trying https://queue.taskcluster.net/v1/task/IupauqylRJehdgeBkvEWDw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 09:06:31 INFO - Downloading https://queue.taskcluster.net/v1/task/IupauqylRJehdgeBkvEWDw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 09:06:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IupauqylRJehdgeBkvEWDw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 09:06:32 INFO - Downloaded 1199 bytes. 09:06:32 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 09:06:32 INFO - Using the following test package requirements: 09:06:32 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 09:06:32 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:06:32 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 09:06:32 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:06:32 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 09:06:32 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:06:32 INFO - u'jsshell-mac.zip'], 09:06:32 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:06:32 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 09:06:32 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 09:06:32 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:06:32 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 09:06:32 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:06:32 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 09:06:32 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:06:32 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 09:06:32 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:06:32 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 09:06:32 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:06:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IupauqylRJehdgeBkvEWDw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 09:06:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IupauqylRJehdgeBkvEWDw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:06:32 INFO - Fetch https://queue.taskcluster.net/v1/task/IupauqylRJehdgeBkvEWDw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 09:06:33 INFO - Http code: 200 09:06:33 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 09:06:33 INFO - x-amz-cf-id: 6yfbNSSACKlodO_IfyNnn9zg3vU_gH0azaorNo5dk-nDpcDjDzR_Rg== 09:06:33 INFO - x-amz-version-id: B.zPnID60yY.pqt60_NPxBK3dGb5Hy7E 09:06:33 INFO - Expected file size: 16281806 09:06:33 INFO - Obtained file size: 16281806 09:06:40 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:06:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IupauqylRJehdgeBkvEWDw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 09:06:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IupauqylRJehdgeBkvEWDw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:06:40 INFO - Fetch https://queue.taskcluster.net/v1/task/IupauqylRJehdgeBkvEWDw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 09:06:40 INFO - Http code: 200 09:06:40 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 09:06:40 INFO - x-amz-cf-id: emz5Bhl7eN0afjCMUb6FFBHRdc4XJYfhJlmMqxrdkbkRkrwrYWyQbw== 09:06:40 INFO - x-amz-version-id: B.zPnID60yY.pqt60_NPxBK3dGb5Hy7E 09:06:41 INFO - Expected file size: 16281806 09:06:41 INFO - Obtained file size: 16281806 09:06:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IupauqylRJehdgeBkvEWDw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 09:06:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IupauqylRJehdgeBkvEWDw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:06:47 INFO - Fetch https://queue.taskcluster.net/v1/task/IupauqylRJehdgeBkvEWDw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 09:06:48 INFO - Http code: 200 09:06:48 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 09:06:48 INFO - x-amz-cf-id: 9IbA0YL8zafB9cq3g8nM-MqOCNjBxib36yuWxFL20XzYI8UxvTFvvw== 09:06:48 INFO - x-amz-version-id: 8blgrXhBdGSGZEmviollaZEnEbL7kWaU 09:06:48 INFO - Expected file size: 14409392 09:06:48 INFO - Obtained file size: 14409392 09:06:48 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')]} 09:06:48 INFO - trying https://queue.taskcluster.net/v1/task/IupauqylRJehdgeBkvEWDw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:06:48 INFO - Downloading https://queue.taskcluster.net/v1/task/IupauqylRJehdgeBkvEWDw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 09:06:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IupauqylRJehdgeBkvEWDw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 09:06:51 INFO - Downloaded 107412967 bytes. 09:06:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IupauqylRJehdgeBkvEWDw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:06:51 INFO - mkdir: /builds/slave/test/properties 09:06:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:06:51 INFO - Writing to file /builds/slave/test/properties/build_url 09:06:51 INFO - Contents: 09:06:51 INFO - build_url:https://queue.taskcluster.net/v1/task/IupauqylRJehdgeBkvEWDw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:06:52 INFO - Running post-action listener: _resource_record_post_action 09:06:52 INFO - Running post-action listener: set_extra_try_arguments 09:06:52 INFO - [mozharness: 2016-11-22 17:06:52.116494Z] Finished download-and-extract step (success) 09:06:52 INFO - [mozharness: 2016-11-22 17:06:52.116666Z] Running populate-webroot step. 09:06:52 INFO - Running pre-action listener: _resource_record_pre_action 09:06:52 INFO - Running main action method: populate_webroot 09:06:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:06:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:06:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:06:52 INFO - '--tppagecycles', 09:06:52 INFO - '7'], 09:06:52 INFO - 'tests': ['tsvgm']}}, 09:06:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:06:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:06:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:06:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:06:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:06:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:06:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:06:52 INFO - 'talos_options': ['--disable-e10s'], 09:06:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:06:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:06:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:06:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:06:52 INFO - 'talos_options': ['--disable-e10s'], 09:06:52 INFO - 'tests': ['damp', 'tps']}, 09:06:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:06:52 INFO - 'tests': ['damp', 'tps']}, 09:06:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:06:52 INFO - 'tests': ['dromaeo_dom']}, 09:06:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:06:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:06:52 INFO - 'tests': ['basic_compositor_video']}, 09:06:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:06:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:06:52 INFO - 'tests': ['a11yr', 09:06:52 INFO - 'ts_paint', 09:06:52 INFO - 'tpaint', 09:06:52 INFO - 'sessionrestore', 09:06:52 INFO - 'sessionrestore_no_auto_restore', 09:06:52 INFO - 'tabpaint']}, 09:06:52 INFO - 'other-e10s': {'tests': ['a11yr', 09:06:52 INFO - 'ts_paint', 09:06:52 INFO - 'tpaint', 09:06:52 INFO - 'sessionrestore', 09:06:52 INFO - 'sessionrestore_no_auto_restore', 09:06:52 INFO - 'tabpaint']}, 09:06:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:06:52 INFO - 'ts_paint', 09:06:52 INFO - 'tpaint', 09:06:52 INFO - 'sessionrestore', 09:06:52 INFO - 'sessionrestore_no_auto_restore', 09:06:52 INFO - 'tabpaint']}, 09:06:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:06:52 INFO - 'ts_paint', 09:06:52 INFO - 'tpaint', 09:06:52 INFO - 'sessionrestore', 09:06:52 INFO - 'sessionrestore_no_auto_restore', 09:06:52 INFO - 'tabpaint']}, 09:06:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:06:52 INFO - 'tests': ['a11yr', 09:06:52 INFO - 'ts_paint', 09:06:52 INFO - 'tpaint', 09:06:52 INFO - 'sessionrestore', 09:06:52 INFO - 'sessionrestore_no_auto_restore', 09:06:52 INFO - 'tabpaint']}, 09:06:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:06:52 INFO - 'tests': ['a11yr', 09:06:52 INFO - 'ts_paint', 09:06:52 INFO - 'tpaint', 09:06:52 INFO - 'sessionrestore', 09:06:52 INFO - 'sessionrestore_no_auto_restore', 09:06:52 INFO - 'tabpaint']}, 09:06:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:06:52 INFO - 'tests': ['tsvgx', 09:06:52 INFO - 'tsvgr_opacity', 09:06:52 INFO - 'tart', 09:06:52 INFO - 'tscrollx', 09:06:52 INFO - 'cart']}, 09:06:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:06:52 INFO - 'tsvgr_opacity', 09:06:52 INFO - 'tart', 09:06:52 INFO - 'tscrollx', 09:06:52 INFO - 'cart']}, 09:06:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:06:52 INFO - 'talos_options': ['--disable-e10s'], 09:06:52 INFO - 'tests': ['tp5o']}, 09:06:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:06:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:06:52 INFO - 'talos_options': ['--disable-e10s', 09:06:52 INFO - '--xperf_path', 09:06:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:06:52 INFO - 'tests': ['tp5n']}, 09:06:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:06:52 INFO - 'talos_options': ['--xperf_path', 09:06:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:06:52 INFO - 'tests': ['tp5n']}}, 09:06:52 INFO - 'talos.zip': {'path': '', 09:06:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:06:52 INFO - Running post-action listener: _resource_record_post_action 09:06:52 INFO - [mozharness: 2016-11-22 17:06:52.135340Z] Finished populate-webroot step (success) 09:06:52 INFO - [mozharness: 2016-11-22 17:06:52.135468Z] Running create-virtualenv step. 09:06:52 INFO - Running pre-action listener: _resource_record_pre_action 09:06:52 INFO - Running main action method: create_virtualenv 09:06:52 INFO - Creating virtualenv /builds/slave/test/build/venv 09:06:52 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 09:06:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:06:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:06:52 INFO - Using real prefix '/tools/python27' 09:06:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:06:52 INFO - Installing distribute.............................................................................................................................................................................................done. 09:06:55 INFO - Installing pip.................done. 09:06:55 INFO - Return code: 0 09:06:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:06:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:06:56 INFO - Reading from file tmpfile_stdout 09:06:56 INFO - Output received: 09:06:56 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) 09:06:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:06:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:56 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 0x1095fe3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109574570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd29585b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109414d30>, '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 0x10927bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940cb20>, '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.kVXKlQ80hP/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.TGM1ANHsYG/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 09:06:56 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 09:06:56 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 09:06:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kVXKlQ80hP/Render', 09:06:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:56 INFO - 'HOME': '/Users/cltbld', 09:06:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:56 INFO - 'LOGNAME': 'cltbld', 09:06:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:56 INFO - 'MOZ_NO_REMOTE': '1', 09:06:56 INFO - 'NO_EM_RESTART': '1', 09:06:56 INFO - 'PAGER': '/bin/cat', 09:06:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:56 INFO - 'PWD': '/builds/slave/test', 09:06:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:56 INFO - 'SHELL': '/bin/bash', 09:06:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TGM1ANHsYG/Listeners', 09:06:56 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 09:06:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:56 INFO - 'USER': 'cltbld', 09:06:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:56 INFO - 'XPC_FLAGS': '0x0', 09:06:56 INFO - 'XPC_SERVICE_NAME': '0', 09:06:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:56 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 09:06:56 INFO - Cleaning up... 09:06:56 INFO - Return code: 0 09:06:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:06:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:56 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 0x1095fe3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109574570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd29585b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109414d30>, '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 0x10927bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940cb20>, '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.kVXKlQ80hP/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.TGM1ANHsYG/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 09:06:56 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 09:06:56 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 09:06:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kVXKlQ80hP/Render', 09:06:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:56 INFO - 'HOME': '/Users/cltbld', 09:06:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:56 INFO - 'LOGNAME': 'cltbld', 09:06:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:56 INFO - 'MOZ_NO_REMOTE': '1', 09:06:56 INFO - 'NO_EM_RESTART': '1', 09:06:56 INFO - 'PAGER': '/bin/cat', 09:06:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:56 INFO - 'PWD': '/builds/slave/test', 09:06:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:56 INFO - 'SHELL': '/bin/bash', 09:06:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TGM1ANHsYG/Listeners', 09:06:56 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 09:06:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:56 INFO - 'USER': 'cltbld', 09:06:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:56 INFO - 'XPC_FLAGS': '0x0', 09:06:56 INFO - 'XPC_SERVICE_NAME': '0', 09:06:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:56 INFO - Downloading/unpacking psutil>=3.1.1 09:06:56 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 09:06:56 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 09:06:56 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 09:06:56 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 09:06:56 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 09:06:56 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 09:06:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:06:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:06:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:06:59 INFO - Installing collected packages: psutil 09:06:59 INFO - Running setup.py install for psutil 09:07:00 INFO - building 'psutil._psutil_osx' extension 09:07:00 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 09:07:00 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 09:07:00 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 09:07:00 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 09:07:00 INFO - building 'psutil._psutil_posix' extension 09:07:00 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 09:07:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:07:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:07:00 INFO - ^ 09:07:01 INFO - 1 warning generated. 09:07:01 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 09:07:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:07:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:07:01 INFO - Successfully installed psutil 09:07:01 INFO - Cleaning up... 09:07:01 INFO - Return code: 0 09:07:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:07:01 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')]} 09:07:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:01 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')]} 09:07:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:01 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 0x1095fe3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109574570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd29585b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109414d30>, '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 0x10927bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940cb20>, '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.kVXKlQ80hP/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.TGM1ANHsYG/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 09:07:01 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 09:07:01 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 09:07:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kVXKlQ80hP/Render', 09:07:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:01 INFO - 'HOME': '/Users/cltbld', 09:07:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:01 INFO - 'LOGNAME': 'cltbld', 09:07:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:01 INFO - 'MOZ_NO_REMOTE': '1', 09:07:01 INFO - 'NO_EM_RESTART': '1', 09:07:01 INFO - 'PAGER': '/bin/cat', 09:07:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:01 INFO - 'PWD': '/builds/slave/test', 09:07:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:01 INFO - 'SHELL': '/bin/bash', 09:07:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TGM1ANHsYG/Listeners', 09:07:01 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 09:07:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:01 INFO - 'USER': 'cltbld', 09:07:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:01 INFO - 'XPC_FLAGS': '0x0', 09:07:01 INFO - 'XPC_SERVICE_NAME': '0', 09:07:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:07:01 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 09:07:01 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 09:07:01 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 09:07:01 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 09:07:01 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 09:07:01 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 09:07:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:07:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:07:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:07:04 INFO - Installing collected packages: mozsystemmonitor 09:07:04 INFO - Running setup.py install for mozsystemmonitor 09:07:04 INFO - Successfully installed mozsystemmonitor 09:07:04 INFO - Cleaning up... 09:07:04 INFO - Return code: 0 09:07:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:07: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')]} 09:07:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07: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')]} 09:07:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07: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', '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 0x1095fe3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109574570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd29585b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109414d30>, '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 0x10927bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940cb20>, '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.kVXKlQ80hP/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.TGM1ANHsYG/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 09:07: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:07: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 jsonschema==2.5.1 09:07:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kVXKlQ80hP/Render', 09:07:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:04 INFO - 'HOME': '/Users/cltbld', 09:07:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:04 INFO - 'LOGNAME': 'cltbld', 09:07:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:04 INFO - 'MOZ_NO_REMOTE': '1', 09:07:04 INFO - 'NO_EM_RESTART': '1', 09:07:04 INFO - 'PAGER': '/bin/cat', 09:07:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:04 INFO - 'PWD': '/builds/slave/test', 09:07:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:04 INFO - 'SHELL': '/bin/bash', 09:07:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TGM1ANHsYG/Listeners', 09:07:04 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 09:07:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:04 INFO - 'USER': 'cltbld', 09:07:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:04 INFO - 'XPC_FLAGS': '0x0', 09:07:04 INFO - 'XPC_SERVICE_NAME': '0', 09:07:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:04 INFO - Downloading/unpacking jsonschema==2.5.1 09:07:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:07:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:07:07 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 09:07:07 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 09:07:07 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 09:07:07 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 09:07:07 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 09:07:07 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 09:07:07 INFO - Downloading functools32-3.2.3-2.tar.gz 09:07:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:07:07 INFO - warning: no files found matching '*.txt' 09:07:07 INFO - no previously-included directories found matching 'build' 09:07:07 INFO - no previously-included directories found matching 'dist' 09:07:07 INFO - no previously-included directories found matching '.git*' 09:07:07 INFO - Installing collected packages: jsonschema, functools32 09:07:07 INFO - Running setup.py install for functools32 09:07:07 INFO - warning: no files found matching '*.txt' 09:07:07 INFO - no previously-included directories found matching 'build' 09:07:07 INFO - no previously-included directories found matching 'dist' 09:07:07 INFO - no previously-included directories found matching '.git*' 09:07:07 INFO - Successfully installed jsonschema functools32 09:07:07 INFO - Cleaning up... 09:07:07 INFO - Return code: 0 09:07:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:07:07 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')]} 09:07:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:07 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')]} 09:07:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:07 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 0x1095fe3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109574570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd29585b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109414d30>, '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 0x10927bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940cb20>, '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.kVXKlQ80hP/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.TGM1ANHsYG/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 09:07:07 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 09:07:07 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 09:07:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kVXKlQ80hP/Render', 09:07:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:07 INFO - 'HOME': '/Users/cltbld', 09:07:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:07 INFO - 'LOGNAME': 'cltbld', 09:07:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:07 INFO - 'MOZ_NO_REMOTE': '1', 09:07:07 INFO - 'NO_EM_RESTART': '1', 09:07:07 INFO - 'PAGER': '/bin/cat', 09:07:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:07 INFO - 'PWD': '/builds/slave/test', 09:07:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:07 INFO - 'SHELL': '/bin/bash', 09:07:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TGM1ANHsYG/Listeners', 09:07:07 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 09:07:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:07 INFO - 'USER': 'cltbld', 09:07:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:07 INFO - 'XPC_FLAGS': '0x0', 09:07:07 INFO - 'XPC_SERVICE_NAME': '0', 09:07:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:07:08 INFO - Cleaning up... 09:07:08 INFO - Return code: 0 09:07:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:07:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1095fe3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109574570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd29585b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109414d30>, '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 0x10927bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940cb20>, '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.kVXKlQ80hP/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.TGM1ANHsYG/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 09:07:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:07:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:07:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kVXKlQ80hP/Render', 09:07:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:08 INFO - 'HOME': '/Users/cltbld', 09:07:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:08 INFO - 'LOGNAME': 'cltbld', 09:07:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:08 INFO - 'MOZ_NO_REMOTE': '1', 09:07:08 INFO - 'NO_EM_RESTART': '1', 09:07:08 INFO - 'PAGER': '/bin/cat', 09:07:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:08 INFO - 'PWD': '/builds/slave/test', 09:07:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:08 INFO - 'SHELL': '/bin/bash', 09:07:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TGM1ANHsYG/Listeners', 09:07:08 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 09:07:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:08 INFO - 'USER': 'cltbld', 09:07:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:08 INFO - 'XPC_FLAGS': '0x0', 09:07:08 INFO - 'XPC_SERVICE_NAME': '0', 09:07:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:08 INFO - Downloading/unpacking blobuploader==1.2.4 09:07:08 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 09:07:08 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 09:07:08 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 09:07:08 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 09:07:08 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 09:07:08 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 09:07:10 INFO - Downloading blobuploader-1.2.4.tar.gz 09:07:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:07:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:07:11 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 09:07:11 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 09:07:11 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 09:07:11 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 09:07:11 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 09:07:11 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 09:07:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:07:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:07:11 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 09:07:11 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 09:07:11 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 09:07:11 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 09:07:11 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 09:07:11 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 09:07:12 INFO - Downloading docopt-0.6.1.tar.gz 09:07:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:07:12 INFO - Installing collected packages: blobuploader, requests, docopt 09:07:12 INFO - Running setup.py install for blobuploader 09:07:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:07:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:07:12 INFO - Running setup.py install for requests 09:07:12 INFO - Running setup.py install for docopt 09:07:13 INFO - Successfully installed blobuploader requests docopt 09:07:13 INFO - Cleaning up... 09:07:13 INFO - Return code: 0 09:07:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:07: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')]} 09:07:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07: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')]} 09:07:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:13 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 0x1095fe3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109574570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd29585b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109414d30>, '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 0x10927bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940cb20>, '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.kVXKlQ80hP/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.TGM1ANHsYG/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 09:07:13 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 09:07:13 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 09:07:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kVXKlQ80hP/Render', 09:07:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:13 INFO - 'HOME': '/Users/cltbld', 09:07:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:13 INFO - 'LOGNAME': 'cltbld', 09:07:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:13 INFO - 'MOZ_NO_REMOTE': '1', 09:07:13 INFO - 'NO_EM_RESTART': '1', 09:07:13 INFO - 'PAGER': '/bin/cat', 09:07:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:13 INFO - 'PWD': '/builds/slave/test', 09:07:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:13 INFO - 'SHELL': '/bin/bash', 09:07:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TGM1ANHsYG/Listeners', 09:07:13 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 09:07:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:13 INFO - 'USER': 'cltbld', 09:07:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:13 INFO - 'XPC_FLAGS': '0x0', 09:07:13 INFO - 'XPC_SERVICE_NAME': '0', 09:07:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:07:13 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-mUIaWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:07:13 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-aRojMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:07:13 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-OTandX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:07:13 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-XgQ9KF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:07:13 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-MlF8Cj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:07:13 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-4ys0aS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:07:14 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-etARK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:07:14 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-N1Ebp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:07:14 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-5oU6ZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:07:14 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-VE9FnM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:07:14 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-UjlJtb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:07:14 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-_RYuvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:07:14 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-fEkyR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:07:14 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-oo0iX9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:14 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-V_gcEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:07:14 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-whnH3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:07:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:07:15 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-R0Z7sZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:07:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:07:15 INFO - Running setup.py install for manifestparser 09:07:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:07:15 INFO - Running setup.py install for mozcrash 09:07:15 INFO - Running setup.py install for mozdebug 09:07:15 INFO - Running setup.py install for mozdevice 09:07:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:07:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:07:15 INFO - Running setup.py install for mozfile 09:07:15 INFO - Running setup.py install for mozhttpd 09:07:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:07:16 INFO - Running setup.py install for mozinfo 09:07:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:07:16 INFO - Running setup.py install for mozInstall 09:07:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:07:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:07:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:07:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:07:16 INFO - Running setup.py install for mozleak 09:07:16 INFO - Running setup.py install for mozlog 09:07:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:07:16 INFO - Running setup.py install for moznetwork 09:07:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:07:16 INFO - Running setup.py install for mozprocess 09:07:16 INFO - Running setup.py install for mozprofile 09:07:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:07:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:07:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:07:17 INFO - Running setup.py install for mozrunner 09:07:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:07:17 INFO - Running setup.py install for mozscreenshot 09:07:17 INFO - Running setup.py install for moztest 09:07:17 INFO - Running setup.py install for mozversion 09:07:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:07:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:07:17 INFO - Cleaning up... 09:07:17 INFO - Return code: 0 09:07:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:07:17 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')]} 09:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:17 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')]} 09:07:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:17 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 0x1095fe3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109574570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd29585b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109414d30>, '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 0x10927bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940cb20>, '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.kVXKlQ80hP/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.TGM1ANHsYG/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 09:07:17 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 09:07:17 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 09:07:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kVXKlQ80hP/Render', 09:07:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:17 INFO - 'HOME': '/Users/cltbld', 09:07:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:17 INFO - 'LOGNAME': 'cltbld', 09:07:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:17 INFO - 'MOZ_NO_REMOTE': '1', 09:07:17 INFO - 'NO_EM_RESTART': '1', 09:07:17 INFO - 'PAGER': '/bin/cat', 09:07:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:17 INFO - 'PWD': '/builds/slave/test', 09:07:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:17 INFO - 'SHELL': '/bin/bash', 09:07:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TGM1ANHsYG/Listeners', 09:07:17 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 09:07:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:17 INFO - 'USER': 'cltbld', 09:07:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:17 INFO - 'XPC_FLAGS': '0x0', 09:07:17 INFO - 'XPC_SERVICE_NAME': '0', 09:07:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:07:18 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-2eTotW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:07:18 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)) 09:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:07:18 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-1BFLF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 09:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:07:18 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-7zDWmp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:07:18 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)) 09:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:07:18 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-qnQNW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 09:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:07:18 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-Zxsq8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:07:18 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)) 09:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:07:18 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-QFz16_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:07:18 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)) 09:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:07:18 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-s3Biw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:07:18 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)) 09:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:07:18 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-kNOZrU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:07:18 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)) 09:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:07:18 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-wSyk7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:07:18 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)) 09:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:07:18 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-RFJWHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:07:19 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)) 09:07:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:07:19 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-Sm65LM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:07:19 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)) 09:07:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:07:19 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-YnkH63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:07:19 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)) 09:07:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:07:19 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-TmuF8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:07:19 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)) 09:07:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:07:19 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-pim1es-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:07:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 09:07:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:19 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-OmD7OW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:19 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)) 09:07:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:07:19 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-LFrzGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:07:19 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)) 09:07:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:07:19 INFO - Running setup.py (path:/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/pip-r2cC3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:07:19 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)) 09:07:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:07:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:07:19 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)) 09:07:19 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:07:19 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:07:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:07: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 09:07: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 09:07: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 09:07: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 09:07: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 09:07: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 09:07:22 INFO - Downloading blessings-1.6.tar.gz 09:07:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:07:22 INFO - Installing collected packages: blessings 09:07:22 INFO - Running setup.py install for blessings 09:07:22 INFO - Successfully installed blessings 09:07:22 INFO - Cleaning up... 09:07:22 INFO - Return code: 0 09:07:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:07:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:07:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:07:22 INFO - Reading from file tmpfile_stdout 09:07:22 INFO - Current package versions: 09:07:22 INFO - blessings == 1.6 09:07:22 INFO - blobuploader == 1.2.4 09:07:22 INFO - docopt == 0.6.1 09:07:22 INFO - functools32 == 3.2.3-2 09:07:22 INFO - jsonschema == 2.5.1 09:07:22 INFO - manifestparser == 1.1 09:07:22 INFO - mozInstall == 1.12 09:07:22 INFO - mozcrash == 1.0 09:07:22 INFO - mozdebug == 0.1 09:07:22 INFO - mozdevice == 0.49 09:07:22 INFO - mozfile == 1.2 09:07:22 INFO - mozhttpd == 0.7 09:07:22 INFO - mozinfo == 0.9 09:07:22 INFO - mozleak == 0.1 09:07:22 INFO - mozlog == 3.4 09:07:22 INFO - moznetwork == 0.27 09:07:22 INFO - mozprocess == 0.23 09:07:22 INFO - mozprofile == 0.28 09:07:22 INFO - mozrunner == 6.13 09:07:22 INFO - mozscreenshot == 0.1 09:07:22 INFO - mozsystemmonitor == 0.3 09:07:22 INFO - moztest == 0.7 09:07:22 INFO - mozversion == 1.4 09:07:22 INFO - psutil == 3.1.1 09:07:22 INFO - requests == 1.2.3 09:07:22 INFO - wsgiref == 0.1.2 09:07:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:07:22 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')]} 09:07:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:22 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')]} 09:07:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:22 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 0x1095fe3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109574570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd29585b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109414d30>, '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 0x10927bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940cb20>, '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.kVXKlQ80hP/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.TGM1ANHsYG/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 09:07:22 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 09:07:22 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 09:07:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kVXKlQ80hP/Render', 09:07:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:22 INFO - 'HOME': '/Users/cltbld', 09:07:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:22 INFO - 'LOGNAME': 'cltbld', 09:07:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:22 INFO - 'MOZ_NO_REMOTE': '1', 09:07:22 INFO - 'NO_EM_RESTART': '1', 09:07:22 INFO - 'PAGER': '/bin/cat', 09:07:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:22 INFO - 'PWD': '/builds/slave/test', 09:07:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:22 INFO - 'SHELL': '/bin/bash', 09:07:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TGM1ANHsYG/Listeners', 09:07:22 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 09:07:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:22 INFO - 'USER': 'cltbld', 09:07:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:22 INFO - 'XPC_FLAGS': '0x0', 09:07:22 INFO - 'XPC_SERVICE_NAME': '0', 09:07:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:23 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)) 09:07:23 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)) 09:07:23 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)) 09:07:23 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)) 09:07:23 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)) 09:07:23 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)) 09:07:23 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)) 09:07:23 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)) 09:07:23 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)) 09:07:23 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)) 09:07:23 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)) 09:07:23 INFO - Cleaning up... 09:07:23 INFO - Return code: 0 09:07:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:07:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1095fe3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109574570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd29585b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109414d30>, '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 0x10927bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940cb20>, '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.kVXKlQ80hP/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.TGM1ANHsYG/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 09:07:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:07:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:07:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kVXKlQ80hP/Render', 09:07:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:23 INFO - 'HOME': '/Users/cltbld', 09:07:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:23 INFO - 'LOGNAME': 'cltbld', 09:07:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:23 INFO - 'MOZ_NO_REMOTE': '1', 09:07:23 INFO - 'NO_EM_RESTART': '1', 09:07:23 INFO - 'PAGER': '/bin/cat', 09:07:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:23 INFO - 'PWD': '/builds/slave/test', 09:07:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:23 INFO - 'SHELL': '/bin/bash', 09:07:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TGM1ANHsYG/Listeners', 09:07:23 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 09:07:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:23 INFO - 'USER': 'cltbld', 09:07:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:23 INFO - 'XPC_FLAGS': '0x0', 09:07:23 INFO - 'XPC_SERVICE_NAME': '0', 09:07:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:07:23 INFO - Cleaning up... 09:07:23 INFO - Return code: 0 09:07:23 INFO - Running post-action listener: _resource_record_post_action 09:07:23 INFO - Running post-action listener: _start_resource_monitoring 09:07:23 INFO - Starting resource monitoring. 09:07:23 INFO - [mozharness: 2016-11-22 17:07:23.499186Z] Finished create-virtualenv step (success) 09:07:23 INFO - [mozharness: 2016-11-22 17:07:23.500195Z] Running install step. 09:07:23 INFO - Running pre-action listener: _resource_record_pre_action 09:07:23 INFO - Running main action method: install 09:07:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:07:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:07:23 INFO - Reading from file tmpfile_stdout 09:07:23 INFO - Detecting whether we're running mozinstall >=1.0... 09:07:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:07:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:07:23 INFO - Reading from file tmpfile_stdout 09:07:23 INFO - Output received: 09:07:23 INFO - Usage: mozinstall [options] installer 09:07:23 INFO - Options: 09:07:23 INFO - -h, --help show this help message and exit 09:07:23 INFO - -d DEST, --destination=DEST 09:07:23 INFO - Directory to install application into. [default: 09:07:23 INFO - "/builds/slave/test"] 09:07:23 INFO - --app=APP Application being installed. [default: firefox] 09:07:23 INFO - mkdir: /builds/slave/test/build/application 09:07:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:07:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:07:56 INFO - Reading from file tmpfile_stdout 09:07:56 INFO - Output received: 09:07:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:07:56 INFO - Running post-action listener: _resource_record_post_action 09:07:56 INFO - [mozharness: 2016-11-22 17:07:56.702239Z] Finished install step (success) 09:07:56 INFO - [mozharness: 2016-11-22 17:07:56.702361Z] Running run-tests step. 09:07:56 INFO - Running pre-action listener: _resource_record_pre_action 09:07:56 INFO - Running main action method: run_tests 09:07:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:07:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:07:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:07:56 INFO - 2016-11-22 09:07:56.714 screenresolution[1623:7467] starting screenresolution argv=screenresolution get 09:07:56 INFO - 2016-11-22 09:07:56.717 screenresolution[1623:7467] Display 0: 1600x1200x32@60 09:07:56 INFO - 2016-11-22 09:07:56.722 screenresolution[1624:7469] starting screenresolution argv=screenresolution list 09:07:56 INFO - Available Modes on Display 0 09:07:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:07:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:07:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:07:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:07:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:07:56 INFO - Intel Iris: 09:07:56 INFO - Chipset Model: Intel Iris 09:07:56 INFO - Type: GPU 09:07:56 INFO - Bus: Built-In 09:07:56 INFO - VRAM (Dynamic, Max): 1536 MB 09:07:56 INFO - Vendor: Intel (0x8086) 09:07:56 INFO - Device ID: 0x0a2e 09:07:56 INFO - Revision ID: 0x0009 09:07:56 INFO - Displays: 09:07:56 INFO - Display: 09:07:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:07:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:07:56 INFO - Display Serial Number: 1600x1200 60 09:07:56 INFO - Main Display: Yes 09:07:56 INFO - Mirror: Off 09:07:56 INFO - Online: Yes 09:07:56 INFO - Rotation: Supported 09:07:56 INFO - Return code: 0 09:07:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:07:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:07:56 INFO - Python 2.7.3 09:07:56 INFO - Return code: 0 09:07:56 INFO - grabbing minidump binary from tooltool 09:07:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:56 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 0x109414d30>, '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 0x10927bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:07:56 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 09:07:56 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 09:07:56 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 09:07:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:07:57 INFO - Return code: 0 09:07:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:07:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:07:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:07:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:07:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:07:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IupauqylRJehdgeBkvEWDw/artifacts/public/build/firefox-53.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-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:07:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IupauqylRJehdgeBkvEWDw/artifacts/public/build/firefox-53.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-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:07:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kVXKlQ80hP/Render', 09:07:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:57 INFO - 'HOME': '/Users/cltbld', 09:07:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:57 INFO - 'LOGNAME': 'cltbld', 09:07:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:07:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:07:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:57 INFO - 'MOZ_NO_REMOTE': '1', 09:07:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:07:57 INFO - 'NO_EM_RESTART': '1', 09:07:57 INFO - 'PAGER': '/bin/cat', 09:07:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:57 INFO - 'PWD': '/builds/slave/test', 09:07:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:07:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:57 INFO - 'SHELL': '/bin/bash', 09:07:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TGM1ANHsYG/Listeners', 09:07:57 INFO - 'TMPDIR': '/var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/', 09:07:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:57 INFO - 'USER': 'cltbld', 09:07:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:57 INFO - 'XPC_FLAGS': '0x0', 09:07:57 INFO - 'XPC_SERVICE_NAME': '0', 09:07:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IupauqylRJehdgeBkvEWDw/artifacts/public/build/firefox-53.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-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:07:57 INFO - mozversion application_buildid: 20161122064750 09:07:57 INFO - mozversion application_changeset: d5d357b7ca3095e311add0e06bf9027cb9c5dce7 09:07:57 INFO - mozversion application_display_name: Nightly 09:07:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:07:57 INFO - mozversion application_name: Firefox 09:07:57 INFO - mozversion application_remotingname: firefox 09:07:57 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:07:57 INFO - mozversion application_vendor: Mozilla 09:07:57 INFO - mozversion application_version: 53.0a1 09:07:57 INFO - mozversion platform_buildid: 20161122064750 09:07:57 INFO - mozversion platform_changeset: d5d357b7ca3095e311add0e06bf9027cb9c5dce7 09:07:57 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:07:57 INFO - mozversion platform_version: 53.0a1 09:07:57 INFO - using testdate: 1479834477 09:07:57 INFO - actual date: 1479834477 09:07:57 INFO - starting webserver on 'localhost:49239' 09:07:57 INFO - SUITE-START | Running 2 tests 09:07:57 INFO - TEST-START | dromaeo_css 09:07:57 INFO - operating with platform_type : mac_ 09:07:57 INFO - Initialising browser for dromaeo_css test... 09:07:57 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/tmpja20e5/profile http://localhost:49239/getInfo.html) 09:08:00 INFO - PROCESS | 1634 | 2016-11-22 09:08:00.470 plugin-container[1636:7602] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9047, name = 'com.apple.tsm.portname' 09:08:00 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:08:00 INFO - PROCESS | 1634 | 2016-11-22 09:08:00.473 plugin-container[1636:7602] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9513, name = 'com.apple.CFPasteboardClient' 09:08:00 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:08:00 INFO - PROCESS | 1634 | 2016-11-22 09:08:00.473 plugin-container[1636:7602] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:08:00 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 09:08:00 INFO - PROCESS | 1634 | colorDepth:24 09:08:00 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 09:08:00 INFO - PROCESS | 1634 | __metrics 09:08:00 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 09:08:01 INFO - TEST-INFO | 1634: exit 0 09:08:01 INFO - Browser initialized. 09:08:01 INFO - Running cycle 1/1 for dromaeo_css test... 09:08:01 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/tmpja20e5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:08:04 INFO - PROCESS | 1637 | 2016-11-22 09:08:04.192 plugin-container[1638:7721] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 09:08:04 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:08:04 INFO - PROCESS | 1637 | 2016-11-22 09:08:04.192 plugin-container[1638:7721] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 09:08:04 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:08:04 INFO - PROCESS | 1637 | 2016-11-22 09:08:04.192 plugin-container[1638:7721] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:08:13 INFO - PROCESS | 1637 | RSS: Main: 243036160 09:08:13 INFO - PROCESS | 1637 | 09:09:03 INFO - PROCESS | 1637 | 1479834543512 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] 09:11:16 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49239/tests/dromaeo/cssquery-ext.html) 09:11:18 INFO - PROCESS | 1637 | RSS: Main: 250630144 09:11:18 INFO - PROCESS | 1637 | 09:14:21 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-ext.html (next: http://localhost:49239/tests/dromaeo/cssquery-jquery.html) 09:14:21 INFO - PROCESS | 1637 | RSS: Main: 259985408 09:14:21 INFO - PROCESS | 1637 | 09:17:23 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49239/tests/dromaeo/cssquery-mootools.html) 09:17:24 INFO - PROCESS | 1637 | RSS: Main: 264822784 09:17:24 INFO - PROCESS | 1637 | 09:20:26 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49239/tests/dromaeo/cssquery-prototype.html) 09:20:27 INFO - PROCESS | 1637 | RSS: Main: 262983680 09:20:27 INFO - PROCESS | 1637 | 09:23:30 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49239/tests/dromaeo/cssquery-yui.html) 09:23:30 INFO - PROCESS | 1637 | RSS: Main: 267534336 09:23:30 INFO - PROCESS | 1637 | 09:26:33 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-yui.html (next: http://localhost:49239/tests/dromaeo/cssquery-dojo.html) 09:26:33 INFO - PROCESS | 1637 | RSS: Main: 270127104 09:26:33 INFO - PROCESS | 1637 | 09:26:33 INFO - PROCESS | 1637 | __start_tp_report 09:26:33 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 09:26:33 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 09:26:33 INFO - PROCESS | 1637 | |i|pagename|runs| 09:26:33 INFO - PROCESS | 1637 | |0;dojo.html;2716.6739991201052;3416.6583341665837;3455.464403017533;3459.9793061047003;3474.3225071111137;4580.335851301564;5277.44315426949;5642.097264437681;5679.318481782187;5817.592768506206;5713.942860571393;5722.341930677973;5799.072148456248;5801.100829371454;5890.175375447441;5183.844484665447;5185.014847179043;5186.92219616706;5283.365996080475;5369.355677318727;5340.198970154488;5367.731613419333;5370.3287089113865;5378.704171270579;5383.623146379755;5187.0922588546855;5368;5374.758135883894;5382.085045542267;5456.072467680504;5212.765425555859;5289.576833853283;5296.205569164617;5305.124654432014;5313.415524292324;5202.557782588485;5224.895502089937;5231.2937753403185;5238.79044838206;5278.076336641111;5028.396592408934;5630.0991841305195;5697.829065128011;5716.971415142939;5792.68140252286;6159.38406159385;6166.660833654148;6211.068339749028;6219.067139929001;6274.2784579773;6245.875082498324;6262.405071518198;6300.779472718478;6303.936960630381;6312;5796.362400135981;5813.651180929158;5911.9113213301835;5918.2898052234;5937.228160339128;6325.96837015812;6327.746890124344;6336.873262534816;6339.778107766206;6341.143945567382;6218.098375735493;6218.22272215973;6238;6243.188385509855;6267.592606480564;5108.61685373591;6137.652792202601;6177.7837775677635;6183.196184495987;6281.214848143982;6218.689065546704;6226.719797609118;6233.252009758768;6236.563440559117;6276.121343011982;6249.687515624291;6251.6874156292915;6258;6261.78083767066;6292.653904035322;4114.341705327133;4837.588804951548;4940.110780059624;4987.376577927759;5023.874403139951;4345.587169218982;4453.643708503312;4456.64346852251;4508.233600287959;4521.841735539306;5741.655500669973;5954;5968.432998865101;5972.790952316648;5979.402059793986;5217.399999000164;6253.6873156342;6263.091851681572;6280;6283.434490895841;5681.318241811009;5812.128180772834;5817.185594016756;5875.206847075676;5879.706014699248;5059.822906198266;5432.538234250054;5439.5104440600535;5476.17857321397;5516.062269414209;4880.6095512358925;5256.921146182887;5370.812021579226;5386.653336665833;6364.586301890362;6243.687815609111;6259.687015649109;6266.749330026931;6276;6320.241571011508;6223.564350495421;6250.3749625037135;6300.9684951574945;6329.620222786648;6375.330590288138;6237.656928868956;6238;6239.438450539473;6251.124842521959;6252.155958945484;6088.086786981989;6190.442860142609;6200.348963358782;6243.8126856194385;6257.217847769029;6058.4244496772735;6063.272407311151;6130.938690613037;6161.969190154021;6168.814935551941;4963.553280204799;4989.575886049726;6102.0541816018585;6132.080177995836;6135.938640613536;5055.5955523557295;6069.2716873975405;6096.055111457731;6104.38956104386;6115.908261376172;6137.232845894263;6174.783882564268;6183.96908015475;6201.720922558406;6220.7511699531515;5839.649621022752;5939.07944268639;5961.970190149022;6064.151018857276;6079.483243924316 09:26:33 INFO - PROCESS | 1637 | |1;ext.html;473.80810771637476;490.03952253582975;513.4172713969645;520.7656554550452;525.2279149650018;30668.846655766716;39681;40056.79971600141;42410.36384454236;42413.57586424135;40958.59041409585;41521.16957660845;41693.37459938104;41733.16533669325;41734.37398439026;9167.770805729844;9185.173334399937;9320.673776417827;9375.062493750604;9386.202172815285;9155.404898681565;9275.30435217358;9355.204807591328;9364.906350936471;9385.530723463833;9206.493642849675;9218.585163667618;9306.906930930705;9361.67234146805;9367.484788336638;9170.174684278447;9206.447613143233;9259.583318750605;9272.768180795467;9334.859977100348;9283.58223879927;9336.3464557481;9361.812763744687;9363;9374.437533747998;39172.824815255306;39223.60776392228;39537;39570;39573;3259.7066264036353;3304.322613864152;3340.9498857517156;3661.4690869823726;3925.450436938831;28867.133985980454;29825.70174298251;29928;30165.396692066257;30371;2866.813657112281;3219.114743445548;3271;3304.5043243513423;3372.038968893878;482713;486912;488344;491548;491955.54022230016;60023.39976600266;60301.69849150814;60343;60613.393866060775;61860.76278474405;61992.69003655043;62066;65451.09116816258;65765.67117164383;66210.33789662138;15634.296456659478;15717.292721827316;15922.840771592137;15923.840761592468;15951.20243987796;11917.582884599171;11950.760984780429;12071.517139314505;12151.270923744427;12165.026797856328;16343.918280408521;19631.607367852564;19653.41039768809;19690.60618787616;19705.802941970684;7805.165786040276;7968.681252749825;7994.720184793504;8034.919650803417;8061.2744852963515;812.0417906869832;948.137195152408;990.5641517732175;1037.201354956679;1041.5677493840124;28812.42375152527;28890.855545722137;29419.705802941695;30107.69892301093;30111.397772044438;942.3309450290234;1001.8897921228659;1041.739565108723;1049.2340591368343;1064.5316060933164;12015.939920300343;12571.1200215984;15155.242237888062;15280.006799557994;15543.45597904068;467802;487706;528746;530164.3967120636;532169;54733.45266547363;55172;56262;56400.71799640975;56444.43555564474;1307.5031488034485;1316.4405127820637;1329.335332333833;1329.7539955108336;1333.8666133386778;24571.877140614182;27859.024934127207;28171.154865354074;28960.855195723885;29617.22464119476;47785.52214477811;47892.521074788805;48051.798705032656;48098;48272.5172748268;507435;508071.4596426994;508559;508563;508894.45552772;39896.80051599723;40060;40624.59375406208;40954.59045409627;41188.38217426801;43230.351544726225;44161.155020971804;44253;44682.10635787331;45351.77324113358;37635.62364376321;37778;37833.62166378411;38716;39143.41284880786;37450;37508.74947503158;37680.81159594184;38937.2212555753;39036.609633903296;36576.45135322919;37533.93291905558;37640.62359376371;37862.810685946395;37934.430983534716;32736.83631581827;33335.49996750099;33591.83204083964;34096.65903340935;34161.31677366488;23285;24770.380740481633;24966.37584060413;25144;25409.237722868344 09:26:33 INFO - PROCESS | 1637 | |2;jquery.html;366.19619934244344;369.8317265644132;377.86828448795853;378.36623655377235;395.22338604641874;18865.056747162656;19835.40493785185;20130.09414576344;20629.484262893417;20775.68836467454;19582.90208548959;19794.802051979477;20023.098960546773;20223.49441263969;20348.69476957847;5080.822171224008;5575.330960284761;5847.707614619273;5853.151293062503;6553.5740176888585;6398.8080357589115;6517.67411629419;6521.673916304189;6526.379993900572;6542.999999999976;6208.875822483547;6471.644059576722;6553.344665533432;6573;6634.104395906563;5975.133605627203;6109.053096770008;6576.309487503815;6592.80221593353;6615.6030638161865;5730.512906402919;5738.942610573882;5797.594168408213;5804.100364443518;5823.796167134173;12379.75240495194;12458.81311780324;12469.314187719672;12890.226586404846;12904.612861614167;2197.143114185469;2215.5679642469727;2291.0377641390655;2628.7371262873557;2871.296532349567;8715.259202967756;9268.95365523168;9389.436633801994;10187.185025197967;10205.591776328938;1860.8139186081419;1898.2122419196016;1900;1910.6942889137672;1921.5388306806267;118779;120909.39545302305;123275.38362308132;127381;129627.35186324103;10964.890351096386;11414.771704566028;11478.540858365572;11480.253783504046;11630.88369116315;12109.818352724717;13906.026578139636;14193.929030354782;14233.50182743599;14248.715025699428;7995.40034497415;8256.545889976109;8269.082116028798;8294.921660184138;8412.453190542596;6331.461825744771;8184.6316915738935;8190.631421586043;8291.95854020726;8298.253157215879;4406.27296496081;4420.160169567772;4437.711548749321;4455.821767129343;4568.954310456919;1878.117284876106;1917.5781328107794;1917.990410047941;1917.9904100479687;1945.2705235536673;816.9550674712948;849.2059923971107;893.0399820193318;937.8686983822271;968.2447690801187;14766.852331476548;20714;21114.366569002956;21211.893940530506;21263.255786047415;894.2890402130192;938.9530523473799;946.5503885654397;952.2905435450496;967.0697785709312;7493.812654683677;8610.569471526398;8870.778230544289;10581.100606448537;11056.391898445663;524566;578531;583764.0811795914;593465;594861;71946;73666;120416;126194.36902815427;130157;795.2365728900205;861.7673228228343;925.2648154849916;979.9167070799062;987.3335498538602;17860.821391786434;19134.904325478285;19342.70985935184;19555.51111222231;19683.803161968197;37358.439623406215;52661;54744.08395706131;56019.71990140023;56112;79811.60094199724;83248.7512687298;83956;84224;84263;7653.464257501922;7674.232576742281;7914.485558438683;7989.800254993672;8082.919170808216;10646.467676615983;12585;12662;12806.48774049065;12891.16207446513;9426.717198484057;9544.093311135432;9874.259430542594;10049.799004019733;10994.890051099386;10612.575496980035;10748.462576871281;10956.397398143179;11109.444527773741;11141.665750027512;8403.789905252417;10122.59509619607;10189;10558.894411055791;10615.787684246117;7407.481476296607;7574.810629734301;7627.771166865003;7693.846123077619;7729.922700773145;10404.427756473468;10441.425721585081;10728.195385346286;11128.55485780592;11183.552657893593 09:26:33 INFO - PROCESS | 1637 | |3;mootools.html;1471.2864260833494;1521.8021657184565;1687.5696697342185;1795.0023460820428;1802.7025540785771;7503.399728021759;7773.106092799329;7979.680812767489;8440.409171358;8463.534505602198;7800.843983120349;8174.305184059347;8204.958975205116;8212.425130240885;8259.545724985124;2249.685044093824;2294.3919861236786;2297.425643589103;2298.138198175684;2302.1712183613886;2252.3805953362794;2286.2912497125944;2320.396696858812;2323.1288266899915;2326.325365643961;2244.6408574628063;2261.174671244992;2290.919817806377;2296.689946857178;2298.287530865437;1889.7165425186263;2250.3024062540667;2257.0407576779885;2282.7488976212603;2284.7715228477186;1879.6052828905947;2307.492351682627;2308.2382813671447;2310.353101131686;2312.2716344351643;8176.059753128407;8222.218889205516;8296.83406331876;8359.080501144868;8360.749177524684;4658.767061646904;4800.279958006327;4804.20730579454;4809.1583972804965;4813.518648135194;3182.9681703183137;3255.2187475006067;3508.596511401196;3515.7363197760014;3522.3131489359585;1002.7593377589399;1031.7626945802433;1046.602291129366;1048.9003544663244;1052.831546952484;10425.79148417035;10640.361578305326;10708.41103739294;10708.571657133705;10871.83692244625;4461.732296062247;4529.932051019237;4627.467841198305;4668.649851261169;4683.414573178353;7912;9309.208717258973;9382.10869967352;9405.247580193569;9412.199963003084;5157;5186.247994040843;5189.299444575009;5190.351206099238;5190.766415511311;4225.366195070764;4225.3873188388125;4662.417197850269;4714.740689262055;4748.905021899543;3173.3494633600517;3661.56061272649;3739.7943113128495;3780.716446266541;3844.942325865114;1220.2190598017276;1249.056961993689;1253.5612535612463;1268.7589358021946;1274.6048724895313;707.7841258416232;724.2431658916445;775.2634996753108;879.4283715584921;892.8660700894787;5375.139977603565;5419.268398766195;5489.725513724298;5591.41290164535;5665.858353541112;877.3902138014147;880.5157163560144;913.7076135636532;920.4477313611914;953.5137080089204;3615.313090512847;3708.517892673935;3745.8688945886765;3823.617638236154;3859.421086836997;9549.140577348073;9757.512124393894;9971.700848974542;10035.548400321857;10177.796444071077;9933.15568176713;9963.252756043326;9967.850482242773;9971.351862128939;10009.499525023866;7227.747028853965;7596.278353556403;7648.38812894967;7674.347680447113;7693.653785579662;4115.97942010288;5309.389420216725;5499.202615620793;5617.297837770278;5750.769969201185;6942.375186233263;8894.822103557763;9232.076792320715;9263.351565390358;9300.39547429415;8799.680047992852;9217.815643687223;9408.059194080812;9493.810123797348;9529;5963.463288304073;6463.804196223714;6690.69891854858;7171.247019063132;7249.710011599477;6677.966610166918;7244.963775181091;7710.843373493796;7810.140884502586;7836;6892.724845903524;7027.332403421871;7134.001239826324;7202.135743710788;7254.5647261164495;5690.857728556819;6958.060661810793;6998.790036298919;7015.438764898694;7060.505764596429;6534;6589.374009469093;6663.666816659051;6665.266820649627;6680.198376194888;4014.6550905447;4511.616512596334;4599.4710608280475;4639.884002899955;4791.137595232892;4355.455568053993;4416.712913660602;4422.270325396274;4548.818047278204;4658.534146585314 09:26:33 INFO - PROCESS | 1637 | |4;prototype.html;255.99360015999596;294.31865231987945;299.42510380070263;305.9831709255991;309.5310604434281;3921.9607803921954;4549.11292298002;5327.7602507887095;5332.3601167859915;5346.59900507462;4440.80016399262;5099.388073431184;5246.108161612526;5277.8944421111555;5303.7082960437165;3555.146764776448;3973.7615743055367;4046.6327198588715;4106.240345536071;4144.730592511484;3251.935021261504;3804.828782704771;4082.326416141333;4083.1425400665894;4091.4067460218253;3412.488126780978;3822.9617703822883;3950.407438884176;4001.3197756381487;4027.2348253831824;4004.939925901114;4021.436998820153;4042.8180731867283;4050.8808217672226;4054.331035379159;2926.8975585854605;3482.2513159670793;3498.2828520153307;3502.509648649191;3519.9120021999397;4752.405949256343;4879.756012199411;5184.403793563713;5223.947760522384;5279;3695.6858667013335;3970.4242884781834;4362.520122786491;4536.909261814778;4552.544745525421;5380.784768609251;5582.553395728332;5649.943500565024;5661.688607126606;5757.524264566235;3206.743460523176;3700.1859590889962;3713.795741234231;3827.7129215308964;3873.4964454620717;5074.391073071218;5416.241726158341;5571.1086226203615;5898.7640494380175;5936.287645482526;2914.693957134513;3328.5007248912857;3353.3293341331832;3899.3760998240714;3899.5710471848074;3734.607866174067;3798.183390570985;3805.961940380616;3941.349677303213;3962.32640451124;3696.7800625793425;3991.3613821788376;4195.405745816631;4410.558944105564;4604.907901841944;4416.9779151104685;4907.730074845917;5648.49163575272;5660.858478537987;5734.168545560954;1088.6679562733284;1189.9226550274377;1209.661294837447;1236.1594116001027;1242.6085782978332;770.7880332908383;838.2455789789238;860.8622620380709;866.0992567729617;870.8737233101292;3981.581933896899;4342.609165175086;4856.781444834991;5185.714785686748;5219.96421395656;5193.714345710947;5326.760295786699;5473.31583552056;5492.258545096441;5588.552915766729;4337.180832458864;4622.815087396534;4719.38647975761;4765.833195838129;5063.860631357915;2661.5874539446418;2762.2265765585666;2882.250614840115;2892.7850302872844;2904.782141339408;4365.2774726920825;5198.376194856642;5596.692181929951;5708.143778433186;5775;5179.326687530597;5218.947810521922;5262.947370526323;5404.297441332602;5526.297380754904;5195.766190521435;5280.947190528199;5704.942950570524;5706.9714651426475;5743.597948143673;4111.773852438145;5097.8529830787775;5453;5520.392756796829;5540.307461567304;4872.049950259665;5145.597123020888;5202.167653175474;5243.23973023917;5484.643498172606;4855.781489833037;5249.422563517934;5686.374498805211;5734.684592347461;5766.798162064308;3242.5246512837075;3413.6245013048047;3699.2971335446177;3709.480672705877;3745.2883952049024;4588.495265520857;5241.240020197126;5558.555315574825;5569.693666848362;5770.711464426846;4839.395075615548;5400.7569659364635;5514.61397702157;5701.771929122789;5767.538596912321;5136.4077136087935;5654.915176272277;5739.339975902824;5744.253247077853;5746.626469279484;4464.642828573641;5446.537044351116;5528;5602.663840169603;5625.690587017753;4156.757728971407;5132.30713853625;5606.467385598362;5695.971520142372;5736.397678243891;4389.912201755883;5237.397699264633;5627.155926611041;5629.7748090077575;5638.746256418389 09:26:33 INFO - PROCESS | 1637 | |5;yui.html;651.4332530698293;690.9550879192849;749.1310080306845;765.9617019149041;767.2174382130228;13953.16281023138;14604.561863144096;14653.194074325907;14690.926545367258;14710.485133020346;12531.060170487204;12629.810552841715;12643.83904023994;12669.746605067892;12677.873221267784;631.5263552335748;656.8095252376805;684.9486288528356;729.7193425538173;802.297989259398;805.6898094233733;807.9636416361278;809.295912556077;809.5871105736088;810.9918900811005;840.2815592668272;849.5285116760195;852.8763329317245;853.6500034985658;854.700854700856;829.5437509369823;845.1379592815325;847.9993607543097;848.465466755946;850.4557083466586;613.4540259169343;617.0836308082539;623.2987888625297;634.0932466572799;634.8063840528637;13550.390232439564;13836.654083647989;13845.515406960709;13911.443542258297;14436.566902992821;12433.37833108341;12472.126951113425;12511.249325040526;12588.307643079626;12723.745525089538;10991.175661825395;11058.060064894493;11079.889201108046;11120.666380008613;11320.716982075432;3085.969140308591;3614;3619.819009049537;3629.4192929131214;3776.0748616588835;91737.54131229302;94264.11471770595;94747.52626236893;94878;95450;10374.896251037544;10399.84400233997;10440.530176142092;10514.316569422963;10777.892221077846;20530.897345513178;20729.89635051815;20789.584208315748;20829.58340833175;21128.154873805182;6643.56816806906;7546.66040028189;7551.433642476836;7560.735374261874;7593.430492713069;4123.154753275571;4205.915881682349;4208.906319813359;4244.660427165819;4249.936250956238;2912.6941671124227;3299.884504042347;3357.0096821437733;3411.829408529564;3736.75711078779;677.949153813466;683.3098570443788;686.0943554508002;698.6506746626686;704.8872180451103;440.8897775556111;567.1719289836802;580.2862479150668;611.5994023914343;614.9323574406811;8868.645254189889;9054.86417703735;9085.545722713838;9140.497272650067;9312.068793120634;582.1645938078871;626.5958456795341;643.1703102997081;646.2406672160193;649.870025994803;4295.6778241632;4343.261645520269;4439.334099884978;4495.9325610115875;4540.954590454053;96230.51884740531;97511.04977900402;102913.4562981556;106644.86710265752;111695;61643;68939.31060689429;72853;91633.54183229042;92855.53572232231;67621.66189169022;82089;82874;84305;85768.57115714382;9338.393004454689;9364;9368.156865882103;9371.484568348806;9383.483908385104;29652.258693532833;51589.71025724387;51814.740926295126;51988.74005629947;56416.153757692846;46801.829954251414;47093.82265443391;59667.40332596619;68189.97715034378;76597.23402765901;12035;12100;12384.938075309567;12576.993840492556;12821.358932053174;13858.584242472742;13969.511067112602;14109.29453527299;14171.574852754435;14278.07192532482;12737.808932866199;12864.40863532771;13037.413316400594;13049.608511744664;13082.845396785848;12455.377231138587;12773.872261277269;13008.544700935421;13019.349032548145;13327.600171994856;12708;12860.614181574567;12949.935250323688;13042.413091410719;13070.477180912658;9838.557264923238;10490.475476226311;10490.580376784843;10582.735431614272;10884.238103333008;9535.61857525719;10329.586816527555;10826.89173108259;10884.673459796219;10892.455377230948 09:26:33 INFO - PROCESS | 1637 | __end_tp_report 09:26:33 INFO - PROCESS | 1637 | __start_cc_report 09:26:33 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,371 09:26:33 INFO - PROCESS | 1637 | __end_cc_report 09:26:33 INFO - PROCESS | 1637 | __startTimestamp1479835593665__endTimestamp 09:26:33 INFO - PROCESS | 1637 | 09:26:33 INFO - PROCESS | 1637 | ------- Summary: start ------- 09:26:33 INFO - PROCESS | 1637 | Number of tests: 6 09:26:33 INFO - PROCESS | 1637 | 09:26:33 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5717.29 Median:5954.00 stddev:672.42 (11.3%) stddev-sans-first:631.94 09:26:33 INFO - PROCESS | 1637 | Values: 2716.7 3416.7 3455.5 3460.0 3474.3 4580.3 5277.4 5642.1 5679.3 5817.6 5713.9 5722.3 5799.1 5801.1 5890.2 5183.8 5185.0 5186.9 5283.4 5369.4 5340.2 5367.7 5370.3 5378.7 5383.6 5187.1 5368.0 5374.8 5382.1 5456.1 5212.8 5289.6 5296.2 5305.1 5313.4 5202.6 5224.9 5231.3 5238.8 5278.1 5028.4 5630.1 5697.8 5717.0 5792.7 6159.4 6166.7 6211.1 6219.1 6274.3 6245.9 6262.4 6300.8 6303.9 6312.0 5796.4 5813.7 5911.9 5918.3 5937.2 6326.0 6327.7 6336.9 6339.8 6341.1 6218.1 6218.2 6238.0 6243.2 6267.6 5108.6 6137.7 6177.8 6183.2 6281.2 6218.7 6226.7 6233.3 6236.6 6276.1 6249.7 6251.7 6258.0 6261.8 6292.7 4114.3 4837.6 4940.1 4987.4 5023.9 4345.6 4453.6 4456.6 4508.2 4521.8 5741.7 5954.0 5968.4 5972.8 5979.4 5217.4 6253.7 6263.1 6280.0 6283.4 5681.3 5812.1 5817.2 5875.2 5879.7 5059.8 5432.5 5439.5 5476.2 5516.1 4880.6 5256.9 5370.8 5386.7 6364.6 6243.7 6259.7 6266.7 6276.0 6320.2 6223.6 6250.4 6301.0 6329.6 6375.3 6237.7 6238.0 6239.4 6251.1 6252.2 6088.1 6190.4 6200.3 6243.8 6257.2 6058.4 6063.3 6130.9 6162.0 6168.8 4963.6 4989.6 6102.1 6132.1 6135.9 5055.6 6069.3 6096.1 6104.4 6115.9 6137.2 6174.8 6184.0 6201.7 6220.8 5839.6 5939.1 5962.0 6064.2 6079.5 09:26:33 INFO - PROCESS | 1637 | 09:26:33 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:64577.62 Median:40342.30 stddev:133553.05 (331.0%) stddev-sans-first:133840.97 09:26:33 INFO - PROCESS | 1637 | Values: 473.8 490.0 513.4 520.8 525.2 30668.8 39681.0 40056.8 42410.4 42413.6 40958.6 41521.2 41693.4 41733.2 41734.4 9167.8 9185.2 9320.7 9375.1 9386.2 9155.4 9275.3 9355.2 9364.9 9385.5 9206.5 9218.6 9306.9 9361.7 9367.5 9170.2 9206.4 9259.6 9272.8 9334.9 9283.6 9336.3 9361.8 9363.0 9374.4 39172.8 39223.6 39537.0 39570.0 39573.0 3259.7 3304.3 3340.9 3661.5 3925.5 28867.1 29825.7 29928.0 30165.4 30371.0 2866.8 3219.1 3271.0 3304.5 3372.0 482713.0 486912.0 488344.0 491548.0 491955.5 60023.4 60301.7 60343.0 60613.4 61860.8 61992.7 62066.0 65451.1 65765.7 66210.3 15634.3 15717.3 15922.8 15923.8 15951.2 11917.6 11950.8 12071.5 12151.3 12165.0 16343.9 19631.6 19653.4 19690.6 19705.8 7805.2 7968.7 7994.7 8034.9 8061.3 812.0 948.1 990.6 1037.2 1041.6 28812.4 28890.9 29419.7 30107.7 30111.4 942.3 1001.9 1041.7 1049.2 1064.5 12015.9 12571.1 15155.2 15280.0 15543.5 467802.0 487706.0 528746.0 530164.4 532169.0 54733.5 55172.0 56262.0 56400.7 56444.4 1307.5 1316.4 1329.3 1329.8 1333.9 24571.9 27859.0 28171.2 28960.9 29617.2 47785.5 47892.5 48051.8 48098.0 48272.5 507435.0 508071.5 508559.0 508563.0 508894.5 39896.8 40060.0 40624.6 40954.6 41188.4 43230.4 44161.2 44253.0 44682.1 45351.8 37635.6 37778.0 37833.6 38716.0 39143.4 37450.0 37508.7 37680.8 38937.2 39036.6 36576.5 37533.9 37640.6 37862.8 37934.4 32736.8 33335.5 33591.8 34096.7 34161.3 23285.0 24770.4 24966.4 25144.0 25409.2 09:26:33 INFO - PROCESS | 1637 | 09:26:33 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:33441.23 Median:4428.94 stddev:96149.00 (2170.9%) stddev-sans-first:96386.65 09:26:33 INFO - PROCESS | 1637 | Values: 366.2 369.8 377.9 378.4 395.2 18865.1 19835.4 20130.1 20629.5 20775.7 19582.9 19794.8 20023.1 20223.5 20348.7 5080.8 5575.3 5847.7 5853.2 6553.6 6398.8 6517.7 6521.7 6526.4 6543.0 6208.9 6471.6 6553.3 6573.0 6634.1 5975.1 6109.1 6576.3 6592.8 6615.6 5730.5 5738.9 5797.6 5804.1 5823.8 12379.8 12458.8 12469.3 12890.2 12904.6 2197.1 2215.6 2291.0 2628.7 2871.3 8715.3 9269.0 9389.4 10187.2 10205.6 1860.8 1898.2 1900.0 1910.7 1921.5 118779.0 120909.4 123275.4 127381.0 129627.4 10964.9 11414.8 11478.5 11480.3 11630.9 12109.8 13906.0 14193.9 14233.5 14248.7 7995.4 8256.5 8269.1 8294.9 8412.5 6331.5 8184.6 8190.6 8292.0 8298.3 4406.3 4420.2 4437.7 4455.8 4569.0 1878.1 1917.6 1918.0 1918.0 1945.3 817.0 849.2 893.0 937.9 968.2 14766.9 20714.0 21114.4 21211.9 21263.3 894.3 939.0 946.6 952.3 967.1 7493.8 8610.6 8870.8 10581.1 11056.4 524566.0 578531.0 583764.1 593465.0 594861.0 71946.0 73666.0 120416.0 126194.4 130157.0 795.2 861.8 925.3 979.9 987.3 17860.8 19134.9 19342.7 19555.5 19683.8 37358.4 52661.0 54744.1 56019.7 56112.0 79811.6 83248.8 83956.0 84224.0 84263.0 7653.5 7674.2 7914.5 7989.8 8082.9 10646.5 12585.0 12662.0 12806.5 12891.2 9426.7 9544.1 9874.3 10049.8 10994.9 10612.6 10748.5 10956.4 11109.4 11141.7 8403.8 10122.6 10189.0 10558.9 10615.8 7407.5 7574.8 7627.8 7693.8 7729.9 10404.4 10441.4 10728.2 11128.6 11183.6 09:26:33 INFO - PROCESS | 1637 | 09:26:33 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:5301.13 Median:5187.77 stddev:2919.51 (56.3%) stddev-sans-first:2913.52 09:26:33 INFO - PROCESS | 1637 | Values: 1471.3 1521.8 1687.6 1795.0 1802.7 7503.4 7773.1 7979.7 8440.4 8463.5 7800.8 8174.3 8205.0 8212.4 8259.5 2249.7 2294.4 2297.4 2298.1 2302.2 2252.4 2286.3 2320.4 2323.1 2326.3 2244.6 2261.2 2290.9 2296.7 2298.3 1889.7 2250.3 2257.0 2282.7 2284.8 1879.6 2307.5 2308.2 2310.4 2312.3 8176.1 8222.2 8296.8 8359.1 8360.7 4658.8 4800.3 4804.2 4809.2 4813.5 3183.0 3255.2 3508.6 3515.7 3522.3 1002.8 1031.8 1046.6 1048.9 1052.8 10425.8 10640.4 10708.4 10708.6 10871.8 4461.7 4529.9 4627.5 4668.6 4683.4 7912.0 9309.2 9382.1 9405.2 9412.2 5157.0 5186.2 5189.3 5190.4 5190.8 4225.4 4225.4 4662.4 4714.7 4748.9 3173.3 3661.6 3739.8 3780.7 3844.9 1220.2 1249.1 1253.6 1268.8 1274.6 707.8 724.2 775.3 879.4 892.9 5375.1 5419.3 5489.7 5591.4 5665.9 877.4 880.5 913.7 920.4 953.5 3615.3 3708.5 3745.9 3823.6 3859.4 9549.1 9757.5 9971.7 10035.5 10177.8 9933.2 9963.3 9967.9 9971.4 10009.5 7227.7 7596.3 7648.4 7674.3 7693.7 4116.0 5309.4 5499.2 5617.3 5750.8 6942.4 8894.8 9232.1 9263.4 9300.4 8799.7 9217.8 9408.1 9493.8 9529.0 5963.5 6463.8 6690.7 7171.2 7249.7 6678.0 7245.0 7710.8 7810.1 7836.0 6892.7 7027.3 7134.0 7202.1 7254.6 5690.9 6958.1 6998.8 7015.4 7060.5 6534.0 6589.4 6663.7 6665.3 6680.2 4014.7 4511.6 4599.5 4639.9 4791.1 4355.5 4416.7 4422.3 4548.8 4658.5 09:26:33 INFO - PROCESS | 1637 | 09:26:33 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4383.03 Median:4875.90 stddev:1374.84 (28.2%) stddev-sans-first:1343.34 09:26:33 INFO - PROCESS | 1637 | Values: 256.0 294.3 299.4 306.0 309.5 3922.0 4549.1 5327.8 5332.4 5346.6 4440.8 5099.4 5246.1 5277.9 5303.7 3555.1 3973.8 4046.6 4106.2 4144.7 3251.9 3804.8 4082.3 4083.1 4091.4 3412.5 3823.0 3950.4 4001.3 4027.2 4004.9 4021.4 4042.8 4050.9 4054.3 2926.9 3482.3 3498.3 3502.5 3519.9 4752.4 4879.8 5184.4 5223.9 5279.0 3695.7 3970.4 4362.5 4536.9 4552.5 5380.8 5582.6 5649.9 5661.7 5757.5 3206.7 3700.2 3713.8 3827.7 3873.5 5074.4 5416.2 5571.1 5898.8 5936.3 2914.7 3328.5 3353.3 3899.4 3899.6 3734.6 3798.2 3806.0 3941.3 3962.3 3696.8 3991.4 4195.4 4410.6 4604.9 4417.0 4907.7 5648.5 5660.9 5734.2 1088.7 1189.9 1209.7 1236.2 1242.6 770.8 838.2 860.9 866.1 870.9 3981.6 4342.6 4856.8 5185.7 5220.0 5193.7 5326.8 5473.3 5492.3 5588.6 4337.2 4622.8 4719.4 4765.8 5063.9 2661.6 2762.2 2882.3 2892.8 2904.8 4365.3 5198.4 5596.7 5708.1 5775.0 5179.3 5218.9 5262.9 5404.3 5526.3 5195.8 5280.9 5704.9 5707.0 5743.6 4111.8 5097.9 5453.0 5520.4 5540.3 4872.0 5145.6 5202.2 5243.2 5484.6 4855.8 5249.4 5686.4 5734.7 5766.8 3242.5 3413.6 3699.3 3709.5 3745.3 4588.5 5241.2 5558.6 5569.7 5770.7 4839.4 5400.8 5514.6 5701.8 5767.5 5136.4 5654.9 5739.3 5744.3 5746.6 4464.6 5446.5 5528.0 5602.7 5625.7 4156.8 5132.3 5606.5 5696.0 5736.4 4389.9 5237.4 5627.2 5629.8 5638.7 09:26:33 INFO - PROCESS | 1637 | 09:26:33 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:19259.07 Median:4467.63 stddev:27837.97 (623.1%) stddev-sans-first:27881.00 09:26:33 INFO - PROCESS | 1637 | Values: 651.4 691.0 749.1 766.0 767.2 13953.2 14604.6 14653.2 14690.9 14710.5 12531.1 12629.8 12643.8 12669.7 12677.9 631.5 656.8 684.9 729.7 802.3 805.7 808.0 809.3 809.6 811.0 840.3 849.5 852.9 853.7 854.7 829.5 845.1 848.0 848.5 850.5 613.5 617.1 623.3 634.1 634.8 13550.4 13836.7 13845.5 13911.4 14436.6 12433.4 12472.1 12511.2 12588.3 12723.7 10991.2 11058.1 11079.9 11120.7 11320.7 3086.0 3614.0 3619.8 3629.4 3776.1 91737.5 94264.1 94747.5 94878.0 95450.0 10374.9 10399.8 10440.5 10514.3 10777.9 20530.9 20729.9 20789.6 20829.6 21128.2 6643.6 7546.7 7551.4 7560.7 7593.4 4123.2 4205.9 4208.9 4244.7 4249.9 2912.7 3299.9 3357.0 3411.8 3736.8 677.9 683.3 686.1 698.7 704.9 440.9 567.2 580.3 611.6 614.9 8868.6 9054.9 9085.5 9140.5 9312.1 582.2 626.6 643.2 646.2 649.9 4295.7 4343.3 4439.3 4495.9 4541.0 96230.5 97511.0 102913.5 106644.9 111695.0 61643.0 68939.3 72853.0 91633.5 92855.5 67621.7 82089.0 82874.0 84305.0 85768.6 9338.4 9364.0 9368.2 9371.5 9383.5 29652.3 51589.7 51814.7 51988.7 56416.2 46801.8 47093.8 59667.4 68190.0 76597.2 12035.0 12100.0 12384.9 12577.0 12821.4 13858.6 13969.5 14109.3 14171.6 14278.1 12737.8 12864.4 13037.4 13049.6 13082.8 12455.4 12773.9 13008.5 13019.3 13327.6 12708.0 12860.6 12949.9 13042.4 13070.5 9838.6 10490.5 10490.6 10582.7 10884.2 9535.6 10329.6 10826.9 10884.7 10892.5 09:26:33 INFO - PROCESS | 1637 | -------- Summary: end -------- 09:26:33 INFO - PROCESS | 1637 | 09:26:34 INFO - TEST-INFO | 1637: exit 0 09:26:34 INFO - TEST-OK | dromaeo_css | took 1117010ms 09:26:34 INFO - TEST-START | kraken 09:26:34 INFO - operating with platform_type : mac_ 09:26:34 INFO - Initialising browser for kraken test... 09:26:34 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/tmpzlxtSx/profile http://localhost:49239/getInfo.html) 09:26:37 INFO - PROCESS | 1649 | 2016-11-22 09:26:37.162 plugin-container[1650:10268] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x892f, name = 'com.apple.tsm.portname' 09:26:37 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:26:37 INFO - PROCESS | 1649 | 2016-11-22 09:26:37.166 plugin-container[1650:10268] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 09:26:37 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:26:37 INFO - PROCESS | 1649 | 2016-11-22 09:26:37.166 plugin-container[1650:10268] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:26:37 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 09:26:37 INFO - PROCESS | 1649 | colorDepth:24 09:26:37 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 09:26:37 INFO - PROCESS | 1649 | __metrics 09:26:37 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 09:26:38 INFO - TEST-INFO | 1649: exit 0 09:26:38 INFO - Browser initialized. 09:26:38 INFO - Running cycle 1/1 for kraken test... 09:26:38 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4g/xr8x86t14y5glqkjysh4fh1c00000w/T/tmpzlxtSx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:26:40 INFO - PROCESS | 1651 | 2016-11-22 09:26:40.952 plugin-container[1652:10385] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 09:26:40 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:26:40 INFO - PROCESS | 1651 | 2016-11-22 09:26:40.952 plugin-container[1652:10385] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 09:26:40 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:26:40 INFO - PROCESS | 1651 | 2016-11-22 09:26:40.952 plugin-container[1652:10385] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:26:50 INFO - PROCESS | 1651 | RSS: Main: 243126272 09:26:50 INFO - PROCESS | 1651 | 09:26:53 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection) 09:26:54 INFO - PROCESS | 1651 | RSS: Main: 255090688 09:26:54 INFO - PROCESS | 1651 | 09:26:56 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-dft) 09:26:57 INFO - PROCESS | 1651 | RSS: Main: 262778880 09:26:57 INFO - PROCESS | 1651 | 09:27:00 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-fft) 09:27:01 INFO - PROCESS | 1651 | RSS: Main: 265129984 09:27:01 INFO - PROCESS | 1651 | 09:27:03 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator) 09:27:03 INFO - PROCESS | 1651 | RSS: Main: 267378688 09:27:03 INFO - PROCESS | 1651 | 09:27:05 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:27:06 INFO - PROCESS | 1651 | RSS: Main: 268062720 09:27:06 INFO - PROCESS | 1651 | 09:27:09 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom) 09:27:10 INFO - PROCESS | 1651 | RSS: Main: 273240064 09:27:10 INFO - PROCESS | 1651 | 09:27:13 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate) 09:27:14 INFO - PROCESS | 1651 | RSS: Main: 272375808 09:27:14 INFO - PROCESS | 1651 | 09:27:17 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial) 09:27:18 INFO - PROCESS | 1651 | RSS: Main: 272957440 09:27:18 INFO - PROCESS | 1651 | 09:27:19 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:27:20 INFO - PROCESS | 1651 | RSS: Main: 271925248 09:27:20 INFO - PROCESS | 1651 | 09:27:22 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:27:23 INFO - PROCESS | 1651 | RSS: Main: 271888384 09:27:23 INFO - PROCESS | 1651 | 09:27:25 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:27:26 INFO - PROCESS | 1651 | RSS: Main: 272232448 09:27:26 INFO - PROCESS | 1651 | 09:27:29 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:27:30 INFO - PROCESS | 1651 | RSS: Main: 272461824 09:27:30 INFO - PROCESS | 1651 | 09:27:33 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:27:33 INFO - PROCESS | 1651 | RSS: Main: 272257024 09:27:33 INFO - PROCESS | 1651 | 09:27:35 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49239/tests/kraken/driver.html?testName=ai-astar) 09:27:35 INFO - PROCESS | 1651 | RSS: Main: 275374080 09:27:35 INFO - PROCESS | 1651 | 09:27:35 INFO - PROCESS | 1651 | __start_tp_report 09:27:35 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 09:27:35 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 09:27:35 INFO - PROCESS | 1651 | |i|pagename|runs| 09:27:35 INFO - PROCESS | 1651 | |0;ai-astar;107;117;122;118;118;119;119;118;120;117 09:27:35 INFO - PROCESS | 1651 | |1;audio-beat-detection;149;127;117;155;122;119;118;114;165;149 09:27:35 INFO - PROCESS | 1651 | |2;audio-dft;193;201;207;237;197;200;194;199;214;190 09:27:35 INFO - PROCESS | 1651 | |3;audio-fft;75;60;75;85;61;60;61;59;82;59 09:27:35 INFO - PROCESS | 1651 | |4;audio-oscillator;92;56;72;63;77;100;121;99;102;99 09:27:35 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;132;89;92;90;103;112;134;113;115;77 09:27:35 INFO - PROCESS | 1651 | |6;imaging-darkroom;129;116;91;153;156;98;98;96;154;98 09:27:35 INFO - PROCESS | 1651 | |7;imaging-desaturate;151;151;100;149;104;148;87;148;151;97 09:27:35 INFO - PROCESS | 1651 | |8;json-parse-financial;58;58;57;57;57;58;60;59;58;49 09:27:35 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;78;99;124;117;76;70;71;93;105;107 09:27:35 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;88;89;87;91;91;87;90;94;108;114 09:27:35 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;149;151;152;151;146;151;150;164;225;192 09:27:35 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;179;177;177;179;177;195;240;197;175;224 09:27:35 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;69;91;94;90;94;82;68;67;67;69 09:27:35 INFO - PROCESS | 1651 | __end_tp_report 09:27:35 INFO - PROCESS | 1651 | __start_cc_report 09:27:35 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,982 09:27:35 INFO - PROCESS | 1651 | __end_cc_report 09:27:35 INFO - PROCESS | 1651 | __startTimestamp1479835655949__endTimestamp 09:27:35 INFO - PROCESS | 1651 | 09:27:35 INFO - PROCESS | 1651 | ------- Summary: start ------- 09:27:35 INFO - PROCESS | 1651 | Number of tests: 14 09:27:35 INFO - PROCESS | 1651 | 09:27:35 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:117.50 Median:118.50 stddev:3.98 (3.4%) stddev-sans-first:1.58 09:27:35 INFO - PROCESS | 1651 | Values: 107.0 117.0 122.0 118.0 118.0 119.0 119.0 118.0 120.0 117.0 09:27:35 INFO - PROCESS | 1651 | 09:27:35 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:133.50 Median:138.00 stddev:18.89 (13.7%) stddev-sans-first:19.19 09:27:35 INFO - PROCESS | 1651 | Values: 149.0 127.0 117.0 155.0 122.0 119.0 118.0 114.0 165.0 149.0 09:27:35 INFO - PROCESS | 1651 | 09:27:35 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:203.20 Median:200.50 stddev:13.77 (6.9%) stddev-sans-first:14.11 09:27:35 INFO - PROCESS | 1651 | Values: 193.0 201.0 207.0 237.0 197.0 200.0 194.0 199.0 214.0 190.0 09:27:35 INFO - PROCESS | 1651 | 09:27:35 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:67.70 Median:68.00 stddev:10.38 (15.3%) stddev-sans-first:10.67 09:27:35 INFO - PROCESS | 1651 | Values: 75.0 60.0 75.0 85.0 61.0 60.0 61.0 59.0 82.0 59.0 09:27:35 INFO - PROCESS | 1651 | 09:27:35 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:88.10 Median:74.50 stddev:20.31 (27.3%) stddev-sans-first:21.49 09:27:35 INFO - PROCESS | 1651 | Values: 92.0 56.0 72.0 63.0 77.0 100.0 121.0 99.0 102.0 99.0 09:27:35 INFO - PROCESS | 1651 | 09:27:35 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:105.70 Median:105.50 stddev:18.90 (17.9%) stddev-sans-first:17.49 09:27:35 INFO - PROCESS | 1651 | Values: 132.0 89.0 92.0 90.0 103.0 112.0 134.0 113.0 115.0 77.0 09:27:35 INFO - PROCESS | 1651 | 09:27:35 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:118.90 Median:93.50 stddev:26.86 (28.7%) stddev-sans-first:28.24 09:27:35 INFO - PROCESS | 1651 | Values: 129.0 116.0 91.0 153.0 156.0 98.0 98.0 96.0 154.0 98.0 09:27:35 INFO - PROCESS | 1651 | 09:27:35 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:128.60 Median:151.00 stddev:27.54 (18.2%) stddev-sans-first:27.99 09:27:35 INFO - PROCESS | 1651 | Values: 151.0 151.0 100.0 149.0 104.0 148.0 87.0 148.0 151.0 97.0 09:27:35 INFO - PROCESS | 1651 | 09:27:35 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:57.10 Median:58.00 stddev:3.00 (5.2%) stddev-sans-first:3.16 09:27:35 INFO - PROCESS | 1651 | Values: 58.0 58.0 57.0 57.0 57.0 58.0 60.0 59.0 58.0 49.0 09:27:35 INFO - PROCESS | 1651 | 09:27:35 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:94.00 Median:73.50 stddev:19.52 (26.6%) stddev-sans-first:19.83 09:27:35 INFO - PROCESS | 1651 | Values: 78.0 99.0 124.0 117.0 76.0 70.0 71.0 93.0 105.0 107.0 09:27:35 INFO - PROCESS | 1651 | 09:27:35 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:93.90 Median:89.50 stddev:9.36 (10.5%) stddev-sans-first:9.68 09:27:35 INFO - PROCESS | 1651 | Values: 88.0 89.0 87.0 91.0 91.0 87.0 90.0 94.0 108.0 114.0 09:27:35 INFO - PROCESS | 1651 | 09:27:35 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:163.10 Median:151.50 stddev:25.59 (16.9%) stddev-sans-first:26.63 09:27:35 INFO - PROCESS | 1651 | Values: 149.0 151.0 152.0 151.0 146.0 151.0 150.0 164.0 225.0 192.0 09:27:35 INFO - PROCESS | 1651 | 09:27:35 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.00 Median:187.00 stddev:22.76 (12.2%) stddev-sans-first:23.65 09:27:35 INFO - PROCESS | 1651 | Values: 179.0 177.0 177.0 179.0 177.0 195.0 240.0 197.0 175.0 224.0 09:27:35 INFO - PROCESS | 1651 | 09:27:35 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.10 Median:86.00 stddev:12.17 (14.2%) stddev-sans-first:12.35 09:27:35 INFO - PROCESS | 1651 | Values: 69.0 91.0 94.0 90.0 94.0 82.0 68.0 67.0 67.0 69.0 09:27:35 INFO - PROCESS | 1651 | -------- Summary: end -------- 09:27:35 INFO - PROCESS | 1651 | 09:27:36 INFO - TEST-INFO | 1651: exit 0 09:27:36 INFO - TEST-OK | kraken | took 62680ms 09:27:36 INFO - SUITE-END | took 1179s 09:27:37 INFO - Completed test suite (00:19:39) 09:27:37 INFO - javascript benchmark 09:27:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7049.162617147086, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2716.6739991201052, 3416.6583341665837, 3455.464403017533, 3459.9793061047003, 3474.3225071111137, 4580.335851301564, 5277.44315426949, 5642.097264437681, 5679.318481782187, 5817.592768506206, 5713.942860571393, 5722.341930677973, 5799.072148456248, 5801.100829371454, 5890.175375447441, 5183.844484665447, 5185.014847179043, 5186.92219616706, 5283.365996080475, 5369.355677318727, 5340.198970154488, 5367.731613419333, 5370.3287089113865, 5378.704171270579, 5383.623146379755, 5187.0922588546855, 5368.0, 5374.758135883894, 5382.085045542267, 5456.072467680504, 5212.765425555859, 5289.576833853283, 5296.205569164617, 5305.124654432014, 5313.415524292324, 5202.557782588485, 5224.895502089937, 5231.2937753403185, 5238.79044838206, 5278.076336641111, 5028.396592408934, 5630.0991841305195, 5697.829065128011, 5716.971415142939, 5792.68140252286, 6159.38406159385, 6166.660833654148, 6211.068339749028, 6219.067139929001, 6274.2784579773, 6245.875082498324, 6262.405071518198, 6300.779472718478, 6303.936960630381, 6312.0, 5796.362400135981, 5813.651180929158, 5911.9113213301835, 5918.2898052234, 5937.228160339128, 6325.96837015812, 6327.746890124344, 6336.873262534816, 6339.778107766206, 6341.143945567382, 6218.098375735493, 6218.22272215973, 6238.0, 6243.188385509855, 6267.592606480564, 5108.61685373591, 6137.652792202601, 6177.7837775677635, 6183.196184495987, 6281.214848143982, 6218.689065546704, 6226.719797609118, 6233.252009758768, 6236.563440559117, 6276.121343011982, 6249.687515624291, 6251.6874156292915, 6258.0, 6261.78083767066, 6292.653904035322, 4114.341705327133, 4837.588804951548, 4940.110780059624, 4987.376577927759, 5023.874403139951, 4345.587169218982, 4453.643708503312, 4456.64346852251, 4508.233600287959, 4521.841735539306, 5741.655500669973, 5954.0, 5968.432998865101, 5972.790952316648, 5979.402059793986, 5217.399999000164, 6253.6873156342, 6263.091851681572, 6280.0, 6283.434490895841, 5681.318241811009, 5812.128180772834, 5817.185594016756, 5875.206847075676, 5879.706014699248, 5059.822906198266, 5432.538234250054, 5439.5104440600535, 5476.17857321397, 5516.062269414209, 4880.6095512358925, 5256.921146182887, 5370.812021579226, 5386.653336665833, 6364.586301890362, 6243.687815609111, 6259.687015649109, 6266.749330026931, 6276.0, 6320.241571011508, 6223.564350495421, 6250.3749625037135, 6300.9684951574945, 6329.620222786648, 6375.330590288138, 6237.656928868956, 6238.0, 6239.438450539473, 6251.124842521959, 6252.155958945484, 6088.086786981989, 6190.442860142609, 6200.348963358782, 6243.8126856194385, 6257.217847769029, 6058.4244496772735, 6063.272407311151, 6130.938690613037, 6161.969190154021, 6168.814935551941, 4963.553280204799, 4989.575886049726, 6102.0541816018585, 6132.080177995836, 6135.938640613536, 5055.5955523557295, 6069.2716873975405, 6096.055111457731, 6104.38956104386, 6115.908261376172, 6137.232845894263, 6174.783882564268, 6183.96908015475, 6201.720922558406, 6220.7511699531515, 5839.649621022752, 5939.07944268639, 5961.970190149022, 6064.151018857276, 6079.483243924316], "value": 5675.4807290013005, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [473.80810771637476, 490.03952253582975, 513.4172713969645, 520.7656554550452, 525.2279149650018, 30668.846655766716, 39681.0, 40056.79971600141, 42410.36384454236, 42413.57586424135, 40958.59041409585, 41521.16957660845, 41693.37459938104, 41733.16533669325, 41734.37398439026, 9167.770805729844, 9185.173334399937, 9320.673776417827, 9375.062493750604, 9386.202172815285, 9155.404898681565, 9275.30435217358, 9355.204807591328, 9364.906350936471, 9385.530723463833, 9206.493642849675, 9218.585163667618, 9306.906930930705, 9361.67234146805, 9367.484788336638, 9170.174684278447, 9206.447613143233, 9259.583318750605, 9272.768180795467, 9334.859977100348, 9283.58223879927, 9336.3464557481, 9361.812763744687, 9363.0, 9374.437533747998, 39172.824815255306, 39223.60776392228, 39537.0, 39570.0, 39573.0, 3259.7066264036353, 3304.322613864152, 3340.9498857517156, 3661.4690869823726, 3925.450436938831, 28867.133985980454, 29825.70174298251, 29928.0, 30165.396692066257, 30371.0, 2866.813657112281, 3219.114743445548, 3271.0, 3304.5043243513423, 3372.038968893878, 482713.0, 486912.0, 488344.0, 491548.0, 491955.54022230016, 60023.39976600266, 60301.69849150814, 60343.0, 60613.393866060775, 61860.76278474405, 61992.69003655043, 62066.0, 65451.09116816258, 65765.67117164383, 66210.33789662138, 15634.296456659478, 15717.292721827316, 15922.840771592137, 15923.840761592468, 15951.20243987796, 11917.582884599171, 11950.760984780429, 12071.517139314505, 12151.270923744427, 12165.026797856328, 16343.918280408521, 19631.607367852564, 19653.41039768809, 19690.60618787616, 19705.802941970684, 7805.165786040276, 7968.681252749825, 7994.720184793504, 8034.919650803417, 8061.2744852963515, 812.0417906869832, 948.137195152408, 990.5641517732175, 1037.201354956679, 1041.5677493840124, 28812.42375152527, 28890.855545722137, 29419.705802941695, 30107.69892301093, 30111.397772044438, 942.3309450290234, 1001.8897921228659, 1041.739565108723, 1049.2340591368343, 1064.5316060933164, 12015.939920300343, 12571.1200215984, 15155.242237888062, 15280.006799557994, 15543.45597904068, 467802.0, 487706.0, 528746.0, 530164.3967120636, 532169.0, 54733.45266547363, 55172.0, 56262.0, 56400.71799640975, 56444.43555564474, 1307.5031488034485, 1316.4405127820637, 1329.335332333833, 1329.7539955108336, 1333.8666133386778, 24571.877140614182, 27859.024934127207, 28171.154865354074, 28960.855195723885, 29617.22464119476, 47785.52214477811, 47892.521074788805, 48051.798705032656, 48098.0, 48272.5172748268, 507435.0, 508071.4596426994, 508559.0, 508563.0, 508894.45552772, 39896.80051599723, 40060.0, 40624.59375406208, 40954.59045409627, 41188.38217426801, 43230.351544726225, 44161.155020971804, 44253.0, 44682.10635787331, 45351.77324113358, 37635.62364376321, 37778.0, 37833.62166378411, 38716.0, 39143.41284880786, 37450.0, 37508.74947503158, 37680.81159594184, 38937.2212555753, 39036.609633903296, 36576.45135322919, 37533.93291905558, 37640.62359376371, 37862.810685946395, 37934.430983534716, 32736.83631581827, 33335.49996750099, 33591.83204083964, 34096.65903340935, 34161.31677366488, 23285.0, 24770.380740481633, 24966.37584060413, 25144.0, 25409.237722868344], "value": 19634.442527425766, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.19619934244344, 369.8317265644132, 377.86828448795853, 378.36623655377235, 395.22338604641874, 18865.056747162656, 19835.40493785185, 20130.09414576344, 20629.484262893417, 20775.68836467454, 19582.90208548959, 19794.802051979477, 20023.098960546773, 20223.49441263969, 20348.69476957847, 5080.822171224008, 5575.330960284761, 5847.707614619273, 5853.151293062503, 6553.5740176888585, 6398.8080357589115, 6517.67411629419, 6521.673916304189, 6526.379993900572, 6542.999999999976, 6208.875822483547, 6471.644059576722, 6553.344665533432, 6573.0, 6634.104395906563, 5975.133605627203, 6109.053096770008, 6576.309487503815, 6592.80221593353, 6615.6030638161865, 5730.512906402919, 5738.942610573882, 5797.594168408213, 5804.100364443518, 5823.796167134173, 12379.75240495194, 12458.81311780324, 12469.314187719672, 12890.226586404846, 12904.612861614167, 2197.143114185469, 2215.5679642469727, 2291.0377641390655, 2628.7371262873557, 2871.296532349567, 8715.259202967756, 9268.95365523168, 9389.436633801994, 10187.185025197967, 10205.591776328938, 1860.8139186081419, 1898.2122419196016, 1900.0, 1910.6942889137672, 1921.5388306806267, 118779.0, 120909.39545302305, 123275.38362308132, 127381.0, 129627.35186324103, 10964.890351096386, 11414.771704566028, 11478.540858365572, 11480.253783504046, 11630.88369116315, 12109.818352724717, 13906.026578139636, 14193.929030354782, 14233.50182743599, 14248.715025699428, 7995.40034497415, 8256.545889976109, 8269.082116028798, 8294.921660184138, 8412.453190542596, 6331.461825744771, 8184.6316915738935, 8190.631421586043, 8291.95854020726, 8298.253157215879, 4406.27296496081, 4420.160169567772, 4437.711548749321, 4455.821767129343, 4568.954310456919, 1878.117284876106, 1917.5781328107794, 1917.990410047941, 1917.9904100479687, 1945.2705235536673, 816.9550674712948, 849.2059923971107, 893.0399820193318, 937.8686983822271, 968.2447690801187, 14766.852331476548, 20714.0, 21114.366569002956, 21211.893940530506, 21263.255786047415, 894.2890402130192, 938.9530523473799, 946.5503885654397, 952.2905435450496, 967.0697785709312, 7493.812654683677, 8610.569471526398, 8870.778230544289, 10581.100606448537, 11056.391898445663, 524566.0, 578531.0, 583764.0811795914, 593465.0, 594861.0, 71946.0, 73666.0, 120416.0, 126194.36902815427, 130157.0, 795.2365728900205, 861.7673228228343, 925.2648154849916, 979.9167070799062, 987.3335498538602, 17860.821391786434, 19134.904325478285, 19342.70985935184, 19555.51111222231, 19683.803161968197, 37358.439623406215, 52661.0, 54744.08395706131, 56019.71990140023, 56112.0, 79811.60094199724, 83248.7512687298, 83956.0, 84224.0, 84263.0, 7653.464257501922, 7674.232576742281, 7914.485558438683, 7989.800254993672, 8082.919170808216, 10646.467676615983, 12585.0, 12662.0, 12806.48774049065, 12891.16207446513, 9426.717198484057, 9544.093311135432, 9874.259430542594, 10049.799004019733, 10994.890051099386, 10612.575496980035, 10748.462576871281, 10956.397398143179, 11109.444527773741, 11141.665750027512, 8403.789905252417, 10122.59509619607, 10189.0, 10558.894411055791, 10615.787684246117, 7407.481476296607, 7574.810629734301, 7627.771166865003, 7693.846123077619, 7729.922700773145, 10404.427756473468, 10441.425721585081, 10728.195385346286, 11128.55485780592, 11183.552657893593], "value": 9196.241176680556, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1471.2864260833494, 1521.8021657184565, 1687.5696697342185, 1795.0023460820428, 1802.7025540785771, 7503.399728021759, 7773.106092799329, 7979.680812767489, 8440.409171358, 8463.534505602198, 7800.843983120349, 8174.305184059347, 8204.958975205116, 8212.425130240885, 8259.545724985124, 2249.685044093824, 2294.3919861236786, 2297.425643589103, 2298.138198175684, 2302.1712183613886, 2252.3805953362794, 2286.2912497125944, 2320.396696858812, 2323.1288266899915, 2326.325365643961, 2244.6408574628063, 2261.174671244992, 2290.919817806377, 2296.689946857178, 2298.287530865437, 1889.7165425186263, 2250.3024062540667, 2257.0407576779885, 2282.7488976212603, 2284.7715228477186, 1879.6052828905947, 2307.492351682627, 2308.2382813671447, 2310.353101131686, 2312.2716344351643, 8176.059753128407, 8222.218889205516, 8296.83406331876, 8359.080501144868, 8360.749177524684, 4658.767061646904, 4800.279958006327, 4804.20730579454, 4809.1583972804965, 4813.518648135194, 3182.9681703183137, 3255.2187475006067, 3508.596511401196, 3515.7363197760014, 3522.3131489359585, 1002.7593377589399, 1031.7626945802433, 1046.602291129366, 1048.9003544663244, 1052.831546952484, 10425.79148417035, 10640.361578305326, 10708.41103739294, 10708.571657133705, 10871.83692244625, 4461.732296062247, 4529.932051019237, 4627.467841198305, 4668.649851261169, 4683.414573178353, 7912.0, 9309.208717258973, 9382.10869967352, 9405.247580193569, 9412.199963003084, 5157.0, 5186.247994040843, 5189.299444575009, 5190.351206099238, 5190.766415511311, 4225.366195070764, 4225.3873188388125, 4662.417197850269, 4714.740689262055, 4748.905021899543, 3173.3494633600517, 3661.56061272649, 3739.7943113128495, 3780.716446266541, 3844.942325865114, 1220.2190598017276, 1249.056961993689, 1253.5612535612463, 1268.7589358021946, 1274.6048724895313, 707.7841258416232, 724.2431658916445, 775.2634996753108, 879.4283715584921, 892.8660700894787, 5375.139977603565, 5419.268398766195, 5489.725513724298, 5591.41290164535, 5665.858353541112, 877.3902138014147, 880.5157163560144, 913.7076135636532, 920.4477313611914, 953.5137080089204, 3615.313090512847, 3708.517892673935, 3745.8688945886765, 3823.617638236154, 3859.421086836997, 9549.140577348073, 9757.512124393894, 9971.700848974542, 10035.548400321857, 10177.796444071077, 9933.15568176713, 9963.252756043326, 9967.850482242773, 9971.351862128939, 10009.499525023866, 7227.747028853965, 7596.278353556403, 7648.38812894967, 7674.347680447113, 7693.653785579662, 4115.97942010288, 5309.389420216725, 5499.202615620793, 5617.297837770278, 5750.769969201185, 6942.375186233263, 8894.822103557763, 9232.076792320715, 9263.351565390358, 9300.39547429415, 8799.680047992852, 9217.815643687223, 9408.059194080812, 9493.810123797348, 9529.0, 5963.463288304073, 6463.804196223714, 6690.69891854858, 7171.247019063132, 7249.710011599477, 6677.966610166918, 7244.963775181091, 7710.843373493796, 7810.140884502586, 7836.0, 6892.724845903524, 7027.332403421871, 7134.001239826324, 7202.135743710788, 7254.5647261164495, 5690.857728556819, 6958.060661810793, 6998.790036298919, 7015.438764898694, 7060.505764596429, 6534.0, 6589.374009469093, 6663.666816659051, 6665.266820649627, 6680.198376194888, 4014.6550905447, 4511.616512596334, 4599.4710608280475, 4639.884002899955, 4791.137595232892, 4355.455568053993, 4416.712913660602, 4422.270325396274, 4548.818047278204, 4658.534146585314], "value": 4300.710078394005, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [255.99360015999596, 294.31865231987945, 299.42510380070263, 305.9831709255991, 309.5310604434281, 3921.9607803921954, 4549.11292298002, 5327.7602507887095, 5332.3601167859915, 5346.59900507462, 4440.80016399262, 5099.388073431184, 5246.108161612526, 5277.8944421111555, 5303.7082960437165, 3555.146764776448, 3973.7615743055367, 4046.6327198588715, 4106.240345536071, 4144.730592511484, 3251.935021261504, 3804.828782704771, 4082.326416141333, 4083.1425400665894, 4091.4067460218253, 3412.488126780978, 3822.9617703822883, 3950.407438884176, 4001.3197756381487, 4027.2348253831824, 4004.939925901114, 4021.436998820153, 4042.8180731867283, 4050.8808217672226, 4054.331035379159, 2926.8975585854605, 3482.2513159670793, 3498.2828520153307, 3502.509648649191, 3519.9120021999397, 4752.405949256343, 4879.756012199411, 5184.403793563713, 5223.947760522384, 5279.0, 3695.6858667013335, 3970.4242884781834, 4362.520122786491, 4536.909261814778, 4552.544745525421, 5380.784768609251, 5582.553395728332, 5649.943500565024, 5661.688607126606, 5757.524264566235, 3206.743460523176, 3700.1859590889962, 3713.795741234231, 3827.7129215308964, 3873.4964454620717, 5074.391073071218, 5416.241726158341, 5571.1086226203615, 5898.7640494380175, 5936.287645482526, 2914.693957134513, 3328.5007248912857, 3353.3293341331832, 3899.3760998240714, 3899.5710471848074, 3734.607866174067, 3798.183390570985, 3805.961940380616, 3941.349677303213, 3962.32640451124, 3696.7800625793425, 3991.3613821788376, 4195.405745816631, 4410.558944105564, 4604.907901841944, 4416.9779151104685, 4907.730074845917, 5648.49163575272, 5660.858478537987, 5734.168545560954, 1088.6679562733284, 1189.9226550274377, 1209.661294837447, 1236.1594116001027, 1242.6085782978332, 770.7880332908383, 838.2455789789238, 860.8622620380709, 866.0992567729617, 870.8737233101292, 3981.581933896899, 4342.609165175086, 4856.781444834991, 5185.714785686748, 5219.96421395656, 5193.714345710947, 5326.760295786699, 5473.31583552056, 5492.258545096441, 5588.552915766729, 4337.180832458864, 4622.815087396534, 4719.38647975761, 4765.833195838129, 5063.860631357915, 2661.5874539446418, 2762.2265765585666, 2882.250614840115, 2892.7850302872844, 2904.782141339408, 4365.2774726920825, 5198.376194856642, 5596.692181929951, 5708.143778433186, 5775.0, 5179.326687530597, 5218.947810521922, 5262.947370526323, 5404.297441332602, 5526.297380754904, 5195.766190521435, 5280.947190528199, 5704.942950570524, 5706.9714651426475, 5743.597948143673, 4111.773852438145, 5097.8529830787775, 5453.0, 5520.392756796829, 5540.307461567304, 4872.049950259665, 5145.597123020888, 5202.167653175474, 5243.23973023917, 5484.643498172606, 4855.781489833037, 5249.422563517934, 5686.374498805211, 5734.684592347461, 5766.798162064308, 3242.5246512837075, 3413.6245013048047, 3699.2971335446177, 3709.480672705877, 3745.2883952049024, 4588.495265520857, 5241.240020197126, 5558.555315574825, 5569.693666848362, 5770.711464426846, 4839.395075615548, 5400.7569659364635, 5514.61397702157, 5701.771929122789, 5767.538596912321, 5136.4077136087935, 5654.915176272277, 5739.339975902824, 5744.253247077853, 5746.626469279484, 4464.642828573641, 5446.537044351116, 5528.0, 5602.663840169603, 5625.690587017753, 4156.757728971407, 5132.30713853625, 5606.467385598362, 5695.971520142372, 5736.397678243891, 4389.912201755883, 5237.397699264633, 5627.155926611041, 5629.7748090077575, 5638.746256418389], "value": 3944.5326363024647, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [651.4332530698293, 690.9550879192849, 749.1310080306845, 765.9617019149041, 767.2174382130228, 13953.16281023138, 14604.561863144096, 14653.194074325907, 14690.926545367258, 14710.485133020346, 12531.060170487204, 12629.810552841715, 12643.83904023994, 12669.746605067892, 12677.873221267784, 631.5263552335748, 656.8095252376805, 684.9486288528356, 729.7193425538173, 802.297989259398, 805.6898094233733, 807.9636416361278, 809.295912556077, 809.5871105736088, 810.9918900811005, 840.2815592668272, 849.5285116760195, 852.8763329317245, 853.6500034985658, 854.700854700856, 829.5437509369823, 845.1379592815325, 847.9993607543097, 848.465466755946, 850.4557083466586, 613.4540259169343, 617.0836308082539, 623.2987888625297, 634.0932466572799, 634.8063840528637, 13550.390232439564, 13836.654083647989, 13845.515406960709, 13911.443542258297, 14436.566902992821, 12433.37833108341, 12472.126951113425, 12511.249325040526, 12588.307643079626, 12723.745525089538, 10991.175661825395, 11058.060064894493, 11079.889201108046, 11120.666380008613, 11320.716982075432, 3085.969140308591, 3614.0, 3619.819009049537, 3629.4192929131214, 3776.0748616588835, 91737.54131229302, 94264.11471770595, 94747.52626236893, 94878.0, 95450.0, 10374.896251037544, 10399.84400233997, 10440.530176142092, 10514.316569422963, 10777.892221077846, 20530.897345513178, 20729.89635051815, 20789.584208315748, 20829.58340833175, 21128.154873805182, 6643.56816806906, 7546.66040028189, 7551.433642476836, 7560.735374261874, 7593.430492713069, 4123.154753275571, 4205.915881682349, 4208.906319813359, 4244.660427165819, 4249.936250956238, 2912.6941671124227, 3299.884504042347, 3357.0096821437733, 3411.829408529564, 3736.75711078779, 677.949153813466, 683.3098570443788, 686.0943554508002, 698.6506746626686, 704.8872180451103, 440.8897775556111, 567.1719289836802, 580.2862479150668, 611.5994023914343, 614.9323574406811, 8868.645254189889, 9054.86417703735, 9085.545722713838, 9140.497272650067, 9312.068793120634, 582.1645938078871, 626.5958456795341, 643.1703102997081, 646.2406672160193, 649.870025994803, 4295.6778241632, 4343.261645520269, 4439.334099884978, 4495.9325610115875, 4540.954590454053, 96230.51884740531, 97511.04977900402, 102913.4562981556, 106644.86710265752, 111695.0, 61643.0, 68939.31060689429, 72853.0, 91633.54183229042, 92855.53572232231, 67621.66189169022, 82089.0, 82874.0, 84305.0, 85768.57115714382, 9338.393004454689, 9364.0, 9368.156865882103, 9371.484568348806, 9383.483908385104, 29652.258693532833, 51589.71025724387, 51814.740926295126, 51988.74005629947, 56416.153757692846, 46801.829954251414, 47093.82265443391, 59667.40332596619, 68189.97715034378, 76597.23402765901, 12035.0, 12100.0, 12384.938075309567, 12576.993840492556, 12821.358932053174, 13858.584242472742, 13969.511067112602, 14109.29453527299, 14171.574852754435, 14278.07192532482, 12737.808932866199, 12864.40863532771, 13037.413316400594, 13049.608511744664, 13082.845396785848, 12455.377231138587, 12773.872261277269, 13008.544700935421, 13019.349032548145, 13327.600171994856, 12708.0, 12860.614181574567, 12949.935250323688, 13042.413091410719, 13070.477180912658, 9838.557264923238, 10490.475476226311, 10490.580376784843, 10582.735431614272, 10884.238103333008, 9535.61857525719, 10329.586816527555, 10826.89173108259, 10884.673459796219, 10892.455377230948], "value": 7056.820245377545, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1642.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 117.0, 122.0, 118.0, 118.0, 119.0, 119.0, 118.0, 120.0, 117.0], "value": 117.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 127.0, 117.0, 155.0, 122.0, 119.0, 118.0, 114.0, 165.0, 149.0], "value": 133.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 201.0, 207.0, 237.0, 197.0, 200.0, 194.0, 199.0, 214.0, 190.0], "value": 203.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 60.0, 75.0, 85.0, 61.0, 60.0, 61.0, 59.0, 82.0, 59.0], "value": 67.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 56.0, 72.0, 63.0, 77.0, 100.0, 121.0, 99.0, 102.0, 99.0], "value": 88.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 89.0, 92.0, 90.0, 103.0, 112.0, 134.0, 113.0, 115.0, 77.0], "value": 105.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 116.0, 91.0, 153.0, 156.0, 98.0, 98.0, 96.0, 154.0, 98.0], "value": 118.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 100.0, 149.0, 104.0, 148.0, 87.0, 148.0, 151.0, 97.0], "value": 128.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 57.0, 57.0, 57.0, 58.0, 60.0, 59.0, 58.0, 49.0], "value": 57.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 99.0, 124.0, 117.0, 76.0, 70.0, 71.0, 93.0, 105.0, 107.0], "value": 94.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 87.0, 91.0, 91.0, 87.0, 90.0, 94.0, 108.0, 114.0], "value": 93.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 151.0, 152.0, 151.0, 146.0, 151.0, 150.0, 164.0, 225.0, 192.0], "value": 163.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 177.0, 177.0, 179.0, 177.0, 195.0, 240.0, 197.0, 175.0, 224.0], "value": 192.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 91.0, 94.0, 90.0, 94.0, 82.0, 68.0, 67.0, 67.0, 69.0], "value": 79.1, "unit": "score"}]}]} 09:27:37 INFO - Return code: 0 09:27:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:27:37 ERROR - # TBPL SUCCESS # 09:27:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:27:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:27:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:27:37 INFO - 2016-11-22 09:27:37.275 screenresolution[1656:10774] starting screenresolution argv=screenresolution get 09:27:37 INFO - 2016-11-22 09:27:37.278 screenresolution[1656:10774] Display 0: 1600x1200x32@60 09:27:37 INFO - 2016-11-22 09:27:37.285 screenresolution[1657:10777] starting screenresolution argv=screenresolution list 09:27:37 INFO - Available Modes on Display 0 09:27:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:27:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:27:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:27:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:27:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:27:37 INFO - Intel Iris: 09:27:37 INFO - Chipset Model: Intel Iris 09:27:37 INFO - Type: GPU 09:27:37 INFO - Bus: Built-In 09:27:37 INFO - VRAM (Dynamic, Max): 1536 MB 09:27:37 INFO - Vendor: Intel (0x8086) 09:27:37 INFO - Device ID: 0x0a2e 09:27:37 INFO - Revision ID: 0x0009 09:27:37 INFO - Displays: 09:27:37 INFO - Display: 09:27:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:27:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:27:37 INFO - Display Serial Number: 1600x1200 60 09:27:37 INFO - Main Display: Yes 09:27:37 INFO - Mirror: Off 09:27:37 INFO - Online: Yes 09:27:37 INFO - Rotation: Supported 09:27:37 INFO - Return code: 0 09:27:37 INFO - Running post-action listener: _resource_record_post_action 09:27:37 INFO - [mozharness: 2016-11-22 17:27:37.529373Z] Finished run-tests step (success) 09:27:37 INFO - Running post-run listener: _resource_record_post_run 09:27:37 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 39594496; Write bytes: 697262080; Read time: 384; Write time: 1983 09:27:37 INFO - TinderboxPrint: CPU usage
54.5% 09:27:37 INFO - TinderboxPrint: I/O read bytes / time
39,594,496 / 384 09:27:37 INFO - TinderboxPrint: I/O write bytes / time
697,262,080 / 1,983 09:27:37 INFO - TinderboxPrint: CPU idle
2,208.0 (45.5%) 09:27:37 INFO - TinderboxPrint: CPU system
980.5 (20.2%) 09:27:37 INFO - TinderboxPrint: CPU user
1,665.3 (34.3%) 09:27:37 INFO - TinderboxPrint: Swap in / out
422,625,280 / 0 09:27:37 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 382181888; Write bytes: 376166400; Read time: 28738; Write time: 737 09:27:37 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 35482112; Write bytes: 309456896; Read time: 353; Write time: 1201 09:27:38 INFO - Running post-run listener: _upload_blobber_files 09:27:38 INFO - Blob upload gear active. 09:27:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:27:38 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 09:27:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:27:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:27:38 INFO - (blobuploader) - INFO - Open directory for files ... 09:27:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:27:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:27:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:39 INFO - (blobuploader) - INFO - Done attempting. 09:27:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:27:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:27:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:40 INFO - (blobuploader) - INFO - Done attempting. 09:27:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:27:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:27:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:42 INFO - (blobuploader) - INFO - Done attempting. 09:27:42 INFO - (blobuploader) - INFO - Iteration through files over. 09:27:42 INFO - Return code: 0 09:27:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:27:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:27:42 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/552026dda09e160ce3c45a5cba0e1388071273ad05eb1e8e4de8a2692af2566e11d863421166dda7eaa9dde7030b36733d3a2c6c2544c264444d6ad5ca3a1570", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d241732c4bd5e35a64f4faaddd58f6225db08512999fe9d41010d790b545fe6fca1087bbc494a0de9cea0348725033c569459574b225fd25a614212248711295", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/efab8696f3762c6649052068a33999e9352c0fd85e959883dc2a1745a9b8f22b3f727fcce21a0b38ff8381582790198b9ba50cd93b5e4419ad4d0c7ce9357ed2"} 09:27:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:27:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:27:42 INFO - Contents: 09:27:42 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/552026dda09e160ce3c45a5cba0e1388071273ad05eb1e8e4de8a2692af2566e11d863421166dda7eaa9dde7030b36733d3a2c6c2544c264444d6ad5ca3a1570", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d241732c4bd5e35a64f4faaddd58f6225db08512999fe9d41010d790b545fe6fca1087bbc494a0de9cea0348725033c569459574b225fd25a614212248711295", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/efab8696f3762c6649052068a33999e9352c0fd85e959883dc2a1745a9b8f22b3f727fcce21a0b38ff8381582790198b9ba50cd93b5e4419ad4d0c7ce9357ed2"} 09:27:42 INFO - Running post-run listener: copy_logs_to_upload_dir 09:27:42 INFO - Copying logs to upload dir... 09:27:42 INFO - mkdir: /builds/slave/test/build/upload/logs 09:27:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.406153 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-22 09:27:42.518003) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 09:27:42.521379) ========= 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.kVXKlQ80hP/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.TGM1ANHsYG/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/552026dda09e160ce3c45a5cba0e1388071273ad05eb1e8e4de8a2692af2566e11d863421166dda7eaa9dde7030b36733d3a2c6c2544c264444d6ad5ca3a1570", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d241732c4bd5e35a64f4faaddd58f6225db08512999fe9d41010d790b545fe6fca1087bbc494a0de9cea0348725033c569459574b225fd25a614212248711295", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/efab8696f3762c6649052068a33999e9352c0fd85e959883dc2a1745a9b8f22b3f727fcce21a0b38ff8381582790198b9ba50cd93b5e4419ad4d0c7ce9357ed2"} build_url:https://queue.taskcluster.net/v1/task/IupauqylRJehdgeBkvEWDw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011100 build_url: 'https://queue.taskcluster.net/v1/task/IupauqylRJehdgeBkvEWDw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/552026dda09e160ce3c45a5cba0e1388071273ad05eb1e8e4de8a2692af2566e11d863421166dda7eaa9dde7030b36733d3a2c6c2544c264444d6ad5ca3a1570", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d241732c4bd5e35a64f4faaddd58f6225db08512999fe9d41010d790b545fe6fca1087bbc494a0de9cea0348725033c569459574b225fd25a614212248711295", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/efab8696f3762c6649052068a33999e9352c0fd85e959883dc2a1745a9b8f22b3f727fcce21a0b38ff8381582790198b9ba50cd93b5e4419ad4d0c7ce9357ed2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 09:27:42.556107) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 09:27:42.556447) ========= 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.kVXKlQ80hP/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.TGM1ANHsYG/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.004996 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 09:27:42.604934) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 09:27:42.605243) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 09:27:42.605601) ========= ========= Total master_lag: 0.18 =========