builder: cedar_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0176 starttime: 1480376683.58 results: success (0) buildid: 20161128132821 builduid: 73419967302a446c85e13a99f3425584 revision: cfd73f7bffcbedfe998e96e4c2a420981a57510e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-28 15:44:43.583708) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-28 15:44:43.584158) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-28 15:44:43.584428) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-28 15:44:43.709684) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 15:44:43.710058) ========= 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.FXdP3k35ks/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.FwWj6uiqaM/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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-28 15:44:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.8M=0.001s 2016-11-28 15:44:43 (13.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.251504 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 15:44:43.996677) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 15:44:43.997083) ========= 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.FXdP3k35ks/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.FwWj6uiqaM/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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.028228 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 15:44:44.047759) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-28 15:44:44.048089) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev cfd73f7bffcbedfe998e96e4c2a420981a57510e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/cedar --rev cfd73f7bffcbedfe998e96e4c2a420981a57510e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FXdP3k35ks/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.FwWj6uiqaM/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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-28 15:44:44,139 truncating revision to first 12 chars 2016-11-28 15:44:44,139 Setting DEBUG logging. 2016-11-28 15:44:44,139 attempt 1/10 2016-11-28 15:44:44,139 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/cfd73f7bffcb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-28 15:44:45,116 unpacking tar archive at: cedar-cfd73f7bffcb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.247847 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-28 15:44:45.319961) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-28 15:44:45.320347) ========= script_repo_revision: cfd73f7bffcbedfe998e96e4c2a420981a57510e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-28 15:44:45.320803) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-28 15:44:45.321080) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-28 15:44:45.341249) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2016-11-28 15:44:45.341683) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 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', 'Cedar', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Cedar'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FXdP3k35ks/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.FwWj6uiqaM/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 15:44:45 INFO - MultiFileLogger online at 20161128 15:44:45 in /builds/slave/test 15:44:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 15:44:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:44:45 INFO - {'append_to_log': False, 15:44:45 INFO - 'base_work_dir': '/builds/slave/test', 15:44:45 INFO - 'blob_upload_branch': 'Cedar', 15:44:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:44:45 INFO - 'branch': 'Cedar', 15:44:45 INFO - 'buildbot_json_path': 'buildprops.json', 15:44:45 INFO - 'config_files': ('talos/mac_config.py',), 15:44:45 INFO - 'default_actions': ('clobber', 15:44:45 INFO - 'read-buildbot-config', 15:44:45 INFO - 'download-and-extract', 15:44:45 INFO - 'populate-webroot', 15:44:45 INFO - 'create-virtualenv', 15:44:45 INFO - 'install', 15:44:45 INFO - 'run-tests'), 15:44:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:44:45 INFO - 'download_minidump_stackwalk': True, 15:44:45 INFO - 'download_symbols': 'ondemand', 15:44:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:44:45 INFO - 'tooltool.py': '/tools/tooltool.py', 15:44:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:44:45 INFO - '/tools/misc-python/virtualenv.py')}, 15:44:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:44:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:44:45 INFO - 'installer_path': 'installer.exe', 15:44:45 INFO - 'log_level': 'info', 15:44:45 INFO - 'log_name': 'talos', 15:44:45 INFO - 'log_to_console': True, 15:44:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:44:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:44:45 INFO - 'opt_config_files': (), 15:44:45 INFO - 'pip_index': False, 15:44:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:44:45 INFO - 'cmd': ('bash', 15:44:45 INFO - '-c', 15:44:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:44:45 INFO - 'enabled': True, 15:44:45 INFO - 'halt_on_failure': False, 15:44:45 INFO - 'name': 'check_screen_resolution'},), 15:44:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:44:45 INFO - 'cmd': ('bash', 15:44:45 INFO - '-c', 15:44:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:44:45 INFO - 'enabled': True, 15:44:45 INFO - 'halt_on_failure': False, 15:44:45 INFO - 'name': 'check_screen_resolution'},), 15:44:45 INFO - 'run_cmd_checks_enabled': True, 15:44:45 INFO - 'sps_profile': False, 15:44:45 INFO - 'sps_profile_interval': 0, 15:44:45 INFO - 'suite': 'dromaeojs-e10s', 15:44:45 INFO - 'system_bits': '32', 15:44:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:44:45 INFO - 'title': 't-yosemite-r7-0176', 15:44:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:44:45 INFO - 'use_talos_json': True, 15:44:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:44:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:44:45 INFO - 'work_dir': 'build'} 15:44:45 INFO - [mozharness: 2016-11-28 23:44:45.546119Z] Running clobber step. 15:44:45 INFO - Running pre-action listener: _resource_record_pre_action 15:44:45 INFO - Running main action method: clobber 15:44:45 INFO - rmtree: /builds/slave/test/build 15:44:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:44:47 INFO - Running post-action listener: _resource_record_post_action 15:44:47 INFO - [mozharness: 2016-11-28 23:44:47.940367Z] Finished clobber step (success) 15:44:47 INFO - [mozharness: 2016-11-28 23:44:47.940444Z] Running read-buildbot-config step. 15:44:47 INFO - Running pre-action listener: _resource_record_pre_action 15:44:47 INFO - Running main action method: read_buildbot_config 15:44:47 INFO - Using buildbot properties: 15:44:47 INFO - { 15:44:47 INFO - "project": "", 15:44:47 INFO - "product": "firefox", 15:44:47 INFO - "script_repo_revision": "default", 15:44:47 INFO - "builddir": "cedar_yosemite_r7_test-dromaeojs-e10s", 15:44:47 INFO - "repository": "", 15:44:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 cedar talos dromaeojs-e10s", 15:44:47 INFO - "buildid": "20161128132821", 15:44:47 INFO - "pgo_build": "False", 15:44:47 INFO - "basedir": "/builds/slave/test", 15:44:47 INFO - "buildnumber": 0, 15:44:47 INFO - "slavename": "t-yosemite-r7-0176", 15:44:47 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 15:44:47 INFO - "platform": "yosemite_r7", 15:44:47 INFO - "scheduler": "tests-cedar-macosx64-talos", 15:44:47 INFO - "branch": "cedar", 15:44:47 INFO - "repo_path": "projects/cedar", 15:44:47 INFO - "revision": "cfd73f7bffcbedfe998e96e4c2a420981a57510e", 15:44:47 INFO - "stage_platform": "macosx64", 15:44:47 INFO - "builduid": "73419967302a446c85e13a99f3425584", 15:44:47 INFO - "slavebuilddir": "test" 15:44:47 INFO - } 15:44:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/XT7rWq4TQqKVo4TOgp0tng/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 15:44:47 INFO - Running post-action listener: _resource_record_post_action 15:44:47 INFO - [mozharness: 2016-11-28 23:44:47.946315Z] Finished read-buildbot-config step (success) 15:44:47 INFO - [mozharness: 2016-11-28 23:44:47.946545Z] Running download-and-extract step. 15:44:47 INFO - Running pre-action listener: _resource_record_pre_action 15:44:47 INFO - Running main action method: download_and_extract 15:44:47 INFO - mkdir: /builds/slave/test/build/tests 15:44:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:47 INFO - trying https://queue.taskcluster.net/v1/task/XT7rWq4TQqKVo4TOgp0tng/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 15:44:47 INFO - Downloading https://queue.taskcluster.net/v1/task/XT7rWq4TQqKVo4TOgp0tng/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 15:44:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XT7rWq4TQqKVo4TOgp0tng/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 15:44:48 INFO - Downloaded 1199 bytes. 15:44:48 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 15:44:48 INFO - Using the following test package requirements: 15:44:48 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 15:44:48 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:44:48 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 15:44:48 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:44:48 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 15:44:48 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:44:48 INFO - u'jsshell-mac.zip'], 15:44:48 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:44:48 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 15:44:48 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 15:44:48 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:44:48 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 15:44:48 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:44:48 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 15:44:48 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:44:48 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 15:44:48 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:44:48 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 15:44:48 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:44:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XT7rWq4TQqKVo4TOgp0tng/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 15:44:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XT7rWq4TQqKVo4TOgp0tng/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:44:48 INFO - Fetch https://queue.taskcluster.net/v1/task/XT7rWq4TQqKVo4TOgp0tng/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 15:44:49 INFO - Http code: 200 15:44:49 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 15:44:49 INFO - x-amz-cf-id: 4Ojio5eTvTWs8ceAuu3P02oI4Uf3EuZ5-f5kPWhD2tp7M8RQoo5mtQ== 15:44:49 INFO - x-amz-version-id: R5ym8c2Zr7DTb362GjLItqqUWfEn4kY. 15:44:49 INFO - Expected file size: 16282622 15:44:49 INFO - Obtained file size: 16282622 15:44:56 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 15:44:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XT7rWq4TQqKVo4TOgp0tng/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 15:44:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XT7rWq4TQqKVo4TOgp0tng/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:44:56 INFO - Fetch https://queue.taskcluster.net/v1/task/XT7rWq4TQqKVo4TOgp0tng/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 15:44:56 INFO - Http code: 200 15:44:56 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 15:44:56 INFO - x-amz-cf-id: LllIlDmZrVWBEQVDfSc5PZAsIlK21IU1XNbA0txmc5zud6fqFF9C0Q== 15:44:56 INFO - x-amz-version-id: R5ym8c2Zr7DTb362GjLItqqUWfEn4kY. 15:44:56 INFO - Expected file size: 16282622 15:44:56 INFO - Obtained file size: 16282622 15:45:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XT7rWq4TQqKVo4TOgp0tng/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 15:45:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XT7rWq4TQqKVo4TOgp0tng/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:45:03 INFO - Fetch https://queue.taskcluster.net/v1/task/XT7rWq4TQqKVo4TOgp0tng/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 15:45:04 INFO - Http code: 200 15:45:04 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 15:45:04 INFO - x-amz-cf-id: -YNtWp-Yj1S2mHo2f1LQFFJIZ-lCWgVcOlAK4elpH2803VMcSmxJdQ== 15:45:04 INFO - x-amz-version-id: kC_PBFk8KOPIW4cZ2pMrBEIitHPzE3jI 15:45:04 INFO - Expected file size: 14409702 15:45:04 INFO - Obtained file size: 14409702 15:45:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:04 INFO - trying https://queue.taskcluster.net/v1/task/XT7rWq4TQqKVo4TOgp0tng/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 15:45:04 INFO - Downloading https://queue.taskcluster.net/v1/task/XT7rWq4TQqKVo4TOgp0tng/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 15:45:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XT7rWq4TQqKVo4TOgp0tng/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 15:45:09 INFO - Downloaded 107603874 bytes. 15:45:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XT7rWq4TQqKVo4TOgp0tng/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 15:45:09 INFO - mkdir: /builds/slave/test/properties 15:45:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:45:09 INFO - Writing to file /builds/slave/test/properties/build_url 15:45:09 INFO - Contents: 15:45:09 INFO - build_url:https://queue.taskcluster.net/v1/task/XT7rWq4TQqKVo4TOgp0tng/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 15:45:10 INFO - Running post-action listener: _resource_record_post_action 15:45:10 INFO - Running post-action listener: set_extra_try_arguments 15:45:10 INFO - [mozharness: 2016-11-28 23:45:10.210644Z] Finished download-and-extract step (success) 15:45:10 INFO - [mozharness: 2016-11-28 23:45:10.210797Z] Running populate-webroot step. 15:45:10 INFO - Running pre-action listener: _resource_record_pre_action 15:45:10 INFO - Running main action method: populate_webroot 15:45:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:45:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:45:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:45:10 INFO - '--tppagecycles', 15:45:10 INFO - '7'], 15:45:10 INFO - 'tests': ['tsvgm']}}, 15:45:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:45:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:45:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:45:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:45:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:45:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:45:10 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:45:10 INFO - 'talos_options': ['--disable-e10s'], 15:45:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:45:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:45:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:45:10 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:45:10 INFO - 'talos_options': ['--disable-e10s'], 15:45:10 INFO - 'tests': ['damp', 'tps']}, 15:45:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:45:10 INFO - 'tests': ['damp', 'tps']}, 15:45:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:45:10 INFO - 'tests': ['dromaeo_dom']}, 15:45:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:45:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:45:10 INFO - 'tests': ['basic_compositor_video']}, 15:45:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:45:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:45:10 INFO - 'tests': ['a11yr', 15:45:10 INFO - 'ts_paint', 15:45:10 INFO - 'tpaint', 15:45:10 INFO - 'sessionrestore', 15:45:10 INFO - 'sessionrestore_no_auto_restore', 15:45:10 INFO - 'tabpaint']}, 15:45:10 INFO - 'other-e10s': {'tests': ['a11yr', 15:45:10 INFO - 'ts_paint', 15:45:10 INFO - 'tpaint', 15:45:10 INFO - 'sessionrestore', 15:45:10 INFO - 'sessionrestore_no_auto_restore', 15:45:10 INFO - 'tabpaint']}, 15:45:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:45:10 INFO - 'ts_paint', 15:45:10 INFO - 'tpaint', 15:45:10 INFO - 'sessionrestore', 15:45:10 INFO - 'sessionrestore_no_auto_restore', 15:45:10 INFO - 'tabpaint']}, 15:45:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:45:10 INFO - 'ts_paint', 15:45:10 INFO - 'tpaint', 15:45:10 INFO - 'sessionrestore', 15:45:10 INFO - 'sessionrestore_no_auto_restore', 15:45:10 INFO - 'tabpaint']}, 15:45:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:45:10 INFO - 'tests': ['a11yr', 15:45:10 INFO - 'ts_paint', 15:45:10 INFO - 'tpaint', 15:45:10 INFO - 'sessionrestore', 15:45:10 INFO - 'sessionrestore_no_auto_restore', 15:45:10 INFO - 'tabpaint']}, 15:45:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:45:10 INFO - 'tests': ['a11yr', 15:45:10 INFO - 'ts_paint', 15:45:10 INFO - 'tpaint', 15:45:10 INFO - 'sessionrestore', 15:45:10 INFO - 'sessionrestore_no_auto_restore', 15:45:10 INFO - 'tabpaint']}, 15:45:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:45:10 INFO - 'tests': ['tsvgx', 15:45:10 INFO - 'tsvgr_opacity', 15:45:10 INFO - 'tart', 15:45:10 INFO - 'tscrollx', 15:45:10 INFO - 'cart', 15:45:10 INFO - 'tsvg_static']}, 15:45:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:45:10 INFO - 'tsvgr_opacity', 15:45:10 INFO - 'tart', 15:45:10 INFO - 'tscrollx', 15:45:10 INFO - 'cart', 15:45:10 INFO - 'tsvg_static']}, 15:45:10 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:45:10 INFO - 'talos_options': ['--disable-e10s'], 15:45:10 INFO - 'tests': ['tp5o']}, 15:45:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:45:10 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:45:10 INFO - 'talos_options': ['--disable-e10s', 15:45:10 INFO - '--xperf_path', 15:45:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:45:10 INFO - 'tests': ['tp5n']}, 15:45:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:45:10 INFO - 'talos_options': ['--xperf_path', 15:45:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:45:10 INFO - 'tests': ['tp5n']}}, 15:45:10 INFO - 'talos.zip': {'path': '', 15:45:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:45:10 INFO - Running post-action listener: _resource_record_post_action 15:45:10 INFO - [mozharness: 2016-11-28 23:45:10.229662Z] Finished populate-webroot step (success) 15:45:10 INFO - [mozharness: 2016-11-28 23:45:10.229777Z] Running create-virtualenv step. 15:45:10 INFO - Running pre-action listener: _resource_record_pre_action 15:45:10 INFO - Running main action method: create_virtualenv 15:45:10 INFO - Creating virtualenv /builds/slave/test/build/venv 15:45:10 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:45:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:45:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:45:10 INFO - Using real prefix '/tools/python27' 15:45:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:45:11 INFO - Installing distribute.............................................................................................................................................................................................done. 15:45:13 INFO - Installing pip.................done. 15:45:13 INFO - Return code: 0 15:45:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:45:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:45:14 INFO - Reading from file tmpfile_stdout 15:45:14 INFO - Output received: 15:45:14 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) 15:45:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:45:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:14 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 0x10ec803f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebf6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86b057bff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea96d30>, '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 0x10e8fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea8eb20>, '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.FXdP3k35ks/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.FwWj6uiqaM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:45:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:45:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:45:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FXdP3k35ks/Render', 15:45:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:14 INFO - 'HOME': '/Users/cltbld', 15:45:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:14 INFO - 'LOGNAME': 'cltbld', 15:45:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:14 INFO - 'MOZ_NO_REMOTE': '1', 15:45:14 INFO - 'NO_EM_RESTART': '1', 15:45:14 INFO - 'PAGER': '/bin/cat', 15:45:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:14 INFO - 'PWD': '/builds/slave/test', 15:45:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:14 INFO - 'SHELL': '/bin/bash', 15:45:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FwWj6uiqaM/Listeners', 15:45:14 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 15:45:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:14 INFO - 'USER': 'cltbld', 15:45:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:14 INFO - 'XPC_FLAGS': '0x0', 15:45:14 INFO - 'XPC_SERVICE_NAME': '0', 15:45:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:14 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:45:14 INFO - Cleaning up... 15:45:14 INFO - Return code: 0 15:45:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:45:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:14 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 0x10ec803f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebf6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86b057bff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea96d30>, '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 0x10e8fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea8eb20>, '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.FXdP3k35ks/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.FwWj6uiqaM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:45:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:45:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:45:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FXdP3k35ks/Render', 15:45:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:14 INFO - 'HOME': '/Users/cltbld', 15:45:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:14 INFO - 'LOGNAME': 'cltbld', 15:45:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:14 INFO - 'MOZ_NO_REMOTE': '1', 15:45:14 INFO - 'NO_EM_RESTART': '1', 15:45:14 INFO - 'PAGER': '/bin/cat', 15:45:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:14 INFO - 'PWD': '/builds/slave/test', 15:45:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:14 INFO - 'SHELL': '/bin/bash', 15:45:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FwWj6uiqaM/Listeners', 15:45:14 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 15:45:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:14 INFO - 'USER': 'cltbld', 15:45:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:14 INFO - 'XPC_FLAGS': '0x0', 15:45:14 INFO - 'XPC_SERVICE_NAME': '0', 15:45:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:14 INFO - Downloading/unpacking psutil>=3.1.1 15:45:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:45:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:45:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:45:18 INFO - Installing collected packages: psutil 15:45:18 INFO - Running setup.py install for psutil 15:45:18 INFO - building 'psutil._psutil_osx' extension 15:45:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 15:45:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 15:45:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 15:45:19 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 15:45:19 INFO - building 'psutil._psutil_posix' extension 15:45:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 15:45:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:45:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:45:19 INFO - ^ 15:45:19 INFO - 1 warning generated. 15:45:19 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 15:45:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:45:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:45:19 INFO - Successfully installed psutil 15:45:19 INFO - Cleaning up... 15:45:19 INFO - Return code: 0 15:45:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:19 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 0x10ec803f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebf6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86b057bff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea96d30>, '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 0x10e8fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea8eb20>, '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.FXdP3k35ks/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.FwWj6uiqaM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:45:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:45:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:45:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FXdP3k35ks/Render', 15:45:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:19 INFO - 'HOME': '/Users/cltbld', 15:45:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:19 INFO - 'LOGNAME': 'cltbld', 15:45:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:19 INFO - 'MOZ_NO_REMOTE': '1', 15:45:19 INFO - 'NO_EM_RESTART': '1', 15:45:19 INFO - 'PAGER': '/bin/cat', 15:45:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:19 INFO - 'PWD': '/builds/slave/test', 15:45:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:19 INFO - 'SHELL': '/bin/bash', 15:45:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FwWj6uiqaM/Listeners', 15:45:19 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 15:45:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:19 INFO - 'USER': 'cltbld', 15:45:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:19 INFO - 'XPC_FLAGS': '0x0', 15:45:19 INFO - 'XPC_SERVICE_NAME': '0', 15:45:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:45:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:45:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:45:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:45:22 INFO - Installing collected packages: mozsystemmonitor 15:45:22 INFO - Running setup.py install for mozsystemmonitor 15:45:23 INFO - Successfully installed mozsystemmonitor 15:45:23 INFO - Cleaning up... 15:45:23 INFO - Return code: 0 15:45:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ec803f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebf6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86b057bff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea96d30>, '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 0x10e8fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea8eb20>, '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.FXdP3k35ks/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.FwWj6uiqaM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:45: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==2.5.1'] in /builds/slave/test/build 15:45: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==2.5.1 15:45:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FXdP3k35ks/Render', 15:45:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:23 INFO - 'HOME': '/Users/cltbld', 15:45:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:23 INFO - 'LOGNAME': 'cltbld', 15:45:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:23 INFO - 'MOZ_NO_REMOTE': '1', 15:45:23 INFO - 'NO_EM_RESTART': '1', 15:45:23 INFO - 'PAGER': '/bin/cat', 15:45:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:23 INFO - 'PWD': '/builds/slave/test', 15:45:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:23 INFO - 'SHELL': '/bin/bash', 15:45:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FwWj6uiqaM/Listeners', 15:45:23 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 15:45:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:23 INFO - 'USER': 'cltbld', 15:45:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:23 INFO - 'XPC_FLAGS': '0x0', 15:45:23 INFO - 'XPC_SERVICE_NAME': '0', 15:45:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:23 INFO - Downloading/unpacking jsonschema==2.5.1 15:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:45:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:26 INFO - Downloading functools32-3.2.3-2.tar.gz 15:45:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:45:26 INFO - warning: no files found matching '*.txt' 15:45:26 INFO - no previously-included directories found matching 'build' 15:45:26 INFO - no previously-included directories found matching 'dist' 15:45:26 INFO - no previously-included directories found matching '.git*' 15:45:26 INFO - Installing collected packages: jsonschema, functools32 15:45:26 INFO - Running setup.py install for functools32 15:45:26 INFO - warning: no files found matching '*.txt' 15:45:26 INFO - no previously-included directories found matching 'build' 15:45:26 INFO - no previously-included directories found matching 'dist' 15:45:26 INFO - no previously-included directories found matching '.git*' 15:45:26 INFO - Successfully installed jsonschema functools32 15:45:26 INFO - Cleaning up... 15:45:26 INFO - Return code: 0 15:45:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:45:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ec803f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebf6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86b057bff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea96d30>, '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 0x10e8fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea8eb20>, '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.FXdP3k35ks/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.FwWj6uiqaM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:45:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:45:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:45:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FXdP3k35ks/Render', 15:45:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:26 INFO - 'HOME': '/Users/cltbld', 15:45:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:26 INFO - 'LOGNAME': 'cltbld', 15:45:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:26 INFO - 'MOZ_NO_REMOTE': '1', 15:45:26 INFO - 'NO_EM_RESTART': '1', 15:45:26 INFO - 'PAGER': '/bin/cat', 15:45:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:26 INFO - 'PWD': '/builds/slave/test', 15:45:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:26 INFO - 'SHELL': '/bin/bash', 15:45:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FwWj6uiqaM/Listeners', 15:45:26 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 15:45:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:26 INFO - 'USER': 'cltbld', 15:45:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:26 INFO - 'XPC_FLAGS': '0x0', 15:45:26 INFO - 'XPC_SERVICE_NAME': '0', 15:45:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:45:27 INFO - Cleaning up... 15:45:27 INFO - Return code: 0 15:45:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:45:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:27 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 0x10ec803f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebf6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86b057bff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea96d30>, '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 0x10e8fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea8eb20>, '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.FXdP3k35ks/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.FwWj6uiqaM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:45:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:45:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:45:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FXdP3k35ks/Render', 15:45:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:27 INFO - 'HOME': '/Users/cltbld', 15:45:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:27 INFO - 'LOGNAME': 'cltbld', 15:45:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:27 INFO - 'MOZ_NO_REMOTE': '1', 15:45:27 INFO - 'NO_EM_RESTART': '1', 15:45:27 INFO - 'PAGER': '/bin/cat', 15:45:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:27 INFO - 'PWD': '/builds/slave/test', 15:45:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:27 INFO - 'SHELL': '/bin/bash', 15:45:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FwWj6uiqaM/Listeners', 15:45:27 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 15:45:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:27 INFO - 'USER': 'cltbld', 15:45:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:27 INFO - 'XPC_FLAGS': '0x0', 15:45:27 INFO - 'XPC_SERVICE_NAME': '0', 15:45:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:27 INFO - Downloading/unpacking blobuploader==1.2.4 15:45:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:29 INFO - Downloading blobuploader-1.2.4.tar.gz 15:45:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:45:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:45:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:45:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:45:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:31 INFO - Downloading docopt-0.6.1.tar.gz 15:45:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:45:31 INFO - Installing collected packages: blobuploader, requests, docopt 15:45:31 INFO - Running setup.py install for blobuploader 15:45:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:45:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:45:31 INFO - Running setup.py install for requests 15:45:31 INFO - Running setup.py install for docopt 15:45:32 INFO - Successfully installed blobuploader requests docopt 15:45:32 INFO - Cleaning up... 15:45:32 INFO - Return code: 0 15:45:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:45:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:32 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 0x10ec803f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebf6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86b057bff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea96d30>, '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 0x10e8fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea8eb20>, '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.FXdP3k35ks/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.FwWj6uiqaM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:45:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:45:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:45:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FXdP3k35ks/Render', 15:45:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:32 INFO - 'HOME': '/Users/cltbld', 15:45:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:32 INFO - 'LOGNAME': 'cltbld', 15:45:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:32 INFO - 'MOZ_NO_REMOTE': '1', 15:45:32 INFO - 'NO_EM_RESTART': '1', 15:45:32 INFO - 'PAGER': '/bin/cat', 15:45:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:32 INFO - 'PWD': '/builds/slave/test', 15:45:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:32 INFO - 'SHELL': '/bin/bash', 15:45:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FwWj6uiqaM/Listeners', 15:45:32 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 15:45:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:32 INFO - 'USER': 'cltbld', 15:45:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:32 INFO - 'XPC_FLAGS': '0x0', 15:45:32 INFO - 'XPC_SERVICE_NAME': '0', 15:45:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:45:32 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-GSexvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:45:32 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-EnG741-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:45:32 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-iyJ3eO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:45:32 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-nXijue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:45:32 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-6sWAf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:45:32 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-JIsJDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:45:33 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-xI7GpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:45:33 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-G8DdhG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:45:33 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-sj3xfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:45:33 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-2MJkNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:45:33 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-PWjN_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:45:33 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-4HjKm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:45:33 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-9kxLyW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:45:33 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-N4r7O8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:45:33 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-sDVe3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:45:33 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-PhfFs5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:45:34 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-E5K4CU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:45:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:45:34 INFO - Running setup.py install for manifestparser 15:45:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:45:34 INFO - Running setup.py install for mozcrash 15:45:34 INFO - Running setup.py install for mozdebug 15:45:34 INFO - Running setup.py install for mozdevice 15:45:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:45:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:45:34 INFO - Running setup.py install for mozfile 15:45:34 INFO - Running setup.py install for mozhttpd 15:45:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:45:35 INFO - Running setup.py install for mozinfo 15:45:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:45:35 INFO - Running setup.py install for mozInstall 15:45:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:45:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:45:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:45:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:45:35 INFO - Running setup.py install for mozleak 15:45:35 INFO - Running setup.py install for mozlog 15:45:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:45:35 INFO - Running setup.py install for moznetwork 15:45:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:45:35 INFO - Running setup.py install for mozprocess 15:45:35 INFO - Running setup.py install for mozprofile 15:45:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:45:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:45:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:45:36 INFO - Running setup.py install for mozrunner 15:45:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:45:36 INFO - Running setup.py install for mozscreenshot 15:45:36 INFO - Running setup.py install for moztest 15:45:36 INFO - Running setup.py install for mozversion 15:45:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:45:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:45:36 INFO - Cleaning up... 15:45:36 INFO - Return code: 0 15:45:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:45:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:36 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 0x10ec803f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebf6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86b057bff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea96d30>, '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 0x10e8fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea8eb20>, '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.FXdP3k35ks/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.FwWj6uiqaM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:45:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:45:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:45:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FXdP3k35ks/Render', 15:45:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:36 INFO - 'HOME': '/Users/cltbld', 15:45:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:36 INFO - 'LOGNAME': 'cltbld', 15:45:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:36 INFO - 'MOZ_NO_REMOTE': '1', 15:45:36 INFO - 'NO_EM_RESTART': '1', 15:45:36 INFO - 'PAGER': '/bin/cat', 15:45:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:36 INFO - 'PWD': '/builds/slave/test', 15:45:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:36 INFO - 'SHELL': '/bin/bash', 15:45:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FwWj6uiqaM/Listeners', 15:45:36 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 15:45:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:36 INFO - 'USER': 'cltbld', 15:45:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:36 INFO - 'XPC_FLAGS': '0x0', 15:45:36 INFO - 'XPC_SERVICE_NAME': '0', 15:45:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:45:37 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-WWzZsV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:45:37 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-b6C9jw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:45:37 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)) 15:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:45:37 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-aFtnBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:45:37 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-s_FA9G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:45:37 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)) 15:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:45:37 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-QD1GpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:45:37 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-ZepBPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:45:37 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-WYEWBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:45:37 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-EnmS39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:45:37 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-Noq_cB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:45:37 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-BtX39Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:45:38 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)) 15:45:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:45:38 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-u3qiu3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:45:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:45:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:45:38 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-3a24os-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:45:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:45:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:45:38 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-wTj9Pc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:45:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:45:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:45:38 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-3bjbxc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:45:38 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)) 15:45:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:45:38 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-_p8CrN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:45:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:45:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:45:38 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-8pF4Hl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:45:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:45:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:45:38 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-cDznae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:45:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:45:38 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)) 15:45:38 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)) 15:45:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:45:38 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)) 15:45:38 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)) 15:45:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:45:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:41 INFO - Downloading blessings-1.6.tar.gz 15:45:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:45:41 INFO - Installing collected packages: blessings 15:45:41 INFO - Running setup.py install for blessings 15:45:41 INFO - Successfully installed blessings 15:45:41 INFO - Cleaning up... 15:45:41 INFO - Return code: 0 15:45:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:45:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:45:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:45:42 INFO - Reading from file tmpfile_stdout 15:45:42 INFO - Current package versions: 15:45:42 INFO - blessings == 1.6 15:45:42 INFO - blobuploader == 1.2.4 15:45:42 INFO - docopt == 0.6.1 15:45:42 INFO - functools32 == 3.2.3-2 15:45:42 INFO - jsonschema == 2.5.1 15:45:42 INFO - manifestparser == 1.1 15:45:42 INFO - mozInstall == 1.12 15:45:42 INFO - mozcrash == 1.0 15:45:42 INFO - mozdebug == 0.1 15:45:42 INFO - mozdevice == 0.49 15:45:42 INFO - mozfile == 1.2 15:45:42 INFO - mozhttpd == 0.7 15:45:42 INFO - mozinfo == 0.9 15:45:42 INFO - mozleak == 0.1 15:45:42 INFO - mozlog == 3.4 15:45:42 INFO - moznetwork == 0.27 15:45:42 INFO - mozprocess == 0.23 15:45:42 INFO - mozprofile == 0.28 15:45:42 INFO - mozrunner == 6.13 15:45:42 INFO - mozscreenshot == 0.1 15:45:42 INFO - mozsystemmonitor == 0.3 15:45:42 INFO - moztest == 0.7 15:45:42 INFO - mozversion == 1.4 15:45:42 INFO - psutil == 3.1.1 15:45:42 INFO - requests == 1.2.3 15:45:42 INFO - wsgiref == 0.1.2 15:45:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:45:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:42 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 0x10ec803f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebf6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86b057bff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea96d30>, '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 0x10e8fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea8eb20>, '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.FXdP3k35ks/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.FwWj6uiqaM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:45:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:45:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:45:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FXdP3k35ks/Render', 15:45:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:42 INFO - 'HOME': '/Users/cltbld', 15:45:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:42 INFO - 'LOGNAME': 'cltbld', 15:45:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:42 INFO - 'MOZ_NO_REMOTE': '1', 15:45:42 INFO - 'NO_EM_RESTART': '1', 15:45:42 INFO - 'PAGER': '/bin/cat', 15:45:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:42 INFO - 'PWD': '/builds/slave/test', 15:45:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:42 INFO - 'SHELL': '/bin/bash', 15:45:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FwWj6uiqaM/Listeners', 15:45:42 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 15:45:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:42 INFO - 'USER': 'cltbld', 15:45:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:42 INFO - 'XPC_FLAGS': '0x0', 15:45:42 INFO - 'XPC_SERVICE_NAME': '0', 15:45:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:45:42 INFO - Cleaning up... 15:45:42 INFO - Return code: 0 15:45:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:45:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:42 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 0x10ec803f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebf6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86b057bff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea96d30>, '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 0x10e8fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea8eb20>, '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.FXdP3k35ks/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.FwWj6uiqaM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:45:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:45:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:45:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FXdP3k35ks/Render', 15:45:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:42 INFO - 'HOME': '/Users/cltbld', 15:45:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:42 INFO - 'LOGNAME': 'cltbld', 15:45:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:42 INFO - 'MOZ_NO_REMOTE': '1', 15:45:42 INFO - 'NO_EM_RESTART': '1', 15:45:42 INFO - 'PAGER': '/bin/cat', 15:45:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:42 INFO - 'PWD': '/builds/slave/test', 15:45:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:42 INFO - 'SHELL': '/bin/bash', 15:45:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FwWj6uiqaM/Listeners', 15:45:42 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 15:45:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:42 INFO - 'USER': 'cltbld', 15:45:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:42 INFO - 'XPC_FLAGS': '0x0', 15:45:42 INFO - 'XPC_SERVICE_NAME': '0', 15:45:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:45:42 INFO - Cleaning up... 15:45:42 INFO - Return code: 0 15:45:42 INFO - Running post-action listener: _resource_record_post_action 15:45:42 INFO - Running post-action listener: _start_resource_monitoring 15:45:42 INFO - Starting resource monitoring. 15:45:42 INFO - [mozharness: 2016-11-28 23:45:42.705557Z] Finished create-virtualenv step (success) 15:45:42 INFO - [mozharness: 2016-11-28 23:45:42.706530Z] Running install step. 15:45:42 INFO - Running pre-action listener: _resource_record_pre_action 15:45:42 INFO - Running main action method: install 15:45:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:45:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:45:42 INFO - Reading from file tmpfile_stdout 15:45:42 INFO - Detecting whether we're running mozinstall >=1.0... 15:45:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:45:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:45:43 INFO - Reading from file tmpfile_stdout 15:45:43 INFO - Output received: 15:45:43 INFO - Usage: mozinstall [options] installer 15:45:43 INFO - Options: 15:45:43 INFO - -h, --help show this help message and exit 15:45:43 INFO - -d DEST, --destination=DEST 15:45:43 INFO - Directory to install application into. [default: 15:45:43 INFO - "/builds/slave/test"] 15:45:43 INFO - --app=APP Application being installed. [default: firefox] 15:45:43 INFO - mkdir: /builds/slave/test/build/application 15:45:43 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'] 15:45:43 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 15:46:16 INFO - Reading from file tmpfile_stdout 15:46:16 INFO - Output received: 15:46:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:46:16 INFO - Running post-action listener: _resource_record_post_action 15:46:16 INFO - [mozharness: 2016-11-28 23:46:16.057710Z] Finished install step (success) 15:46:16 INFO - [mozharness: 2016-11-28 23:46:16.057827Z] Running run-tests step. 15:46:16 INFO - Running pre-action listener: _resource_record_pre_action 15:46:16 INFO - Running main action method: run_tests 15:46:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:46:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:46:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:46:16 INFO - 2016-11-28 15:46:16.071 screenresolution[1626:7866] starting screenresolution argv=screenresolution get 15:46:16 INFO - 2016-11-28 15:46:16.074 screenresolution[1626:7866] Display 0: 1600x1200x32@60 15:46:16 INFO - 2016-11-28 15:46:16.079 screenresolution[1627:7869] starting screenresolution argv=screenresolution list 15:46:16 INFO - Available Modes on Display 0 15:46:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:46:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:46:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:46:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:46:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:46:16 INFO - Intel Iris: 15:46:16 INFO - Chipset Model: Intel Iris 15:46:16 INFO - Type: GPU 15:46:16 INFO - Bus: Built-In 15:46:16 INFO - VRAM (Dynamic, Max): 1536 MB 15:46:16 INFO - Vendor: Intel (0x8086) 15:46:16 INFO - Device ID: 0x0a2e 15:46:16 INFO - Revision ID: 0x0009 15:46:16 INFO - Displays: 15:46:16 INFO - Display: 15:46:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:46:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:46:16 INFO - Display Serial Number: 1600x1200 60 15:46:16 INFO - Main Display: Yes 15:46:16 INFO - Mirror: Off 15:46:16 INFO - Online: Yes 15:46:16 INFO - Rotation: Supported 15:46:16 INFO - Return code: 0 15:46:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:46:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:46:16 INFO - Python 2.7.3 15:46:16 INFO - Return code: 0 15:46:16 INFO - grabbing minidump binary from tooltool 15:46:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:16 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 0x10ea96d30>, '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 0x10e8fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea8eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:46:16 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:46:16 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 15:46:16 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:46:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:46:16 INFO - Return code: 0 15:46:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:46:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:46:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:46:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:46:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:46:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XT7rWq4TQqKVo4TOgp0tng/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0176', '--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 15:46:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Cedar --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XT7rWq4TQqKVo4TOgp0tng/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0176 --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 15:46:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FXdP3k35ks/Render', 15:46:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:46:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:46:16 INFO - 'HOME': '/Users/cltbld', 15:46:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:46:16 INFO - 'LOGNAME': 'cltbld', 15:46:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:46:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:46:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:16 INFO - 'MOZ_NO_REMOTE': '1', 15:46:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:46:16 INFO - 'NO_EM_RESTART': '1', 15:46:16 INFO - 'PAGER': '/bin/cat', 15:46:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:46:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:16 INFO - 'PWD': '/builds/slave/test', 15:46:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:46:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:46:16 INFO - 'SHELL': '/bin/bash', 15:46:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FwWj6uiqaM/Listeners', 15:46:16 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 15:46:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:46:16 INFO - 'USER': 'cltbld', 15:46:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:46:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:46:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:16 INFO - 'XPC_FLAGS': '0x0', 15:46:16 INFO - 'XPC_SERVICE_NAME': '0', 15:46:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:46:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XT7rWq4TQqKVo4TOgp0tng/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0176', '--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 15:46:16 INFO - mozversion application_buildid: 20161128132821 15:46:16 INFO - mozversion application_changeset: cfd73f7bffcbedfe998e96e4c2a420981a57510e 15:46:16 INFO - mozversion application_display_name: Nightly 15:46:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:46:16 INFO - mozversion application_name: Firefox 15:46:16 INFO - mozversion application_remotingname: firefox 15:46:16 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 15:46:16 INFO - mozversion application_vendor: Mozilla 15:46:16 INFO - mozversion application_version: 53.0a1 15:46:16 INFO - mozversion platform_buildid: 20161128132821 15:46:16 INFO - mozversion platform_changeset: cfd73f7bffcbedfe998e96e4c2a420981a57510e 15:46:16 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 15:46:16 INFO - mozversion platform_version: 53.0a1 15:46:16 INFO - using testdate: 1480376776 15:46:16 INFO - actual date: 1480376776 15:46:16 INFO - starting webserver on 'localhost:49243' 15:46:16 INFO - SUITE-START | Running 2 tests 15:46:16 INFO - TEST-START | dromaeo_css 15:46:16 INFO - operating with platform_type : mac_ 15:46:16 INFO - Initialising browser for dromaeo_css test... 15:46:16 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmp2Vd0WZ/profile http://localhost:49243/getInfo.html) 15:46:19 INFO - PROCESS | 1637 | 2016-11-28 15:46:19.722 plugin-container[1639:8045] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a43, name = 'com.apple.tsm.portname' 15:46:19 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:46:19 INFO - PROCESS | 1637 | 2016-11-28 15:46:19.725 plugin-container[1639:8045] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c0f, name = 'com.apple.CFPasteboardClient' 15:46:19 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:46:19 INFO - PROCESS | 1637 | 2016-11-28 15:46:19.725 plugin-container[1639:8045] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:46:19 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 15:46:19 INFO - PROCESS | 1637 | colorDepth:24 15:46:19 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 15:46:19 INFO - PROCESS | 1637 | __metrics 15:46:19 ERROR - PROCESS | 1637 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 15:46:20 INFO - TEST-INFO | 1637: exit 0 15:46:20 INFO - Browser initialized. 15:46:20 INFO - Running cycle 1/1 for dromaeo_css test... 15:46:20 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmp2Vd0WZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:46:23 INFO - PROCESS | 1640 | 2016-11-28 15:46:23.301 plugin-container[1641:8165] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 15:46:23 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:46:23 INFO - PROCESS | 1640 | 2016-11-28 15:46:23.302 plugin-container[1641:8165] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 15:46:23 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:46:23 INFO - PROCESS | 1640 | 2016-11-28 15:46:23.302 plugin-container[1641:8165] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:46:32 INFO - PROCESS | 1640 | RSS: Main: 242315264 15:46:32 INFO - PROCESS | 1640 | 15:47:22 INFO - PROCESS | 1640 | 1480376842540 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:49:35 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 15:49:37 INFO - PROCESS | 1640 | RSS: Main: 252784640 15:49:37 INFO - PROCESS | 1640 | 15:52:40 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 15:52:40 INFO - PROCESS | 1640 | RSS: Main: 262025216 15:52:40 INFO - PROCESS | 1640 | 15:55:42 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 15:55:43 INFO - PROCESS | 1640 | RSS: Main: 266522624 15:55:43 INFO - PROCESS | 1640 | 15:58:45 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 15:58:45 INFO - PROCESS | 1640 | RSS: Main: 266100736 15:58:45 INFO - PROCESS | 1640 | 16:01:48 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 16:01:49 INFO - PROCESS | 1640 | RSS: Main: 270712832 16:01:49 INFO - PROCESS | 1640 | 16:04:52 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 16:04:52 INFO - PROCESS | 1640 | RSS: Main: 272998400 16:04:52 INFO - PROCESS | 1640 | 16:04:52 INFO - PROCESS | 1640 | __start_tp_report 16:04:52 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 16:04:52 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 16:04:52 INFO - PROCESS | 1640 | |i|pagename|runs| 16:04:52 INFO - PROCESS | 1640 | |0;dojo.html;2946.882124715011;3320.086976081577;3343.0973637117977;3381.8478168482416;3399.4900764885274;5614.887702245957;5650.5479561635075;5705.343885453174;5734.254546908906;5735.512481439083;5018;5622.32532096149;5654.660720356782;5677.176809362651;5680.176374425706;5153.6392452528335;5177.404598471167;5191.143461328877;5233;5240.082985477546;5216.8174113906025;5251.632385733;5309.123994540915;5313.884084342293;5348.705821179834;109.63954982812986;5274.340707411574;5279.366476022863;5292.894142117156;5303.469653034685;4478.529754375809;5204.42751297357;5233.136532472137;5258.316418865561;5294.602904782157;5156.793728250866;5161.4322424533275;5170.224466330043;5202.635815492917;5213.426523082458;5551;5673.120666296731;5673.801416950429;5715.085586306171;5720.456556627114;4401.449818772653;5230.816921407771;6123.112148738453;6147.47746441553;6207;4802.495737947535;6178.938210617881;6198.411150940653;6278.336240896167;6281.685915704196;5818.21454103699;5825.330087040044;5837.474627283523;5838.065909454509;5874.089516125007;6111.5309653553995;6299.96850015747;6313.116163737081;6313.305536390993;6326.4622507087415;5956.940430595639;5988.371221021817;6064.151018857364;6068.908966365508;6072.392760723892;6129.601575897553;6154.138420621117;6154.876902461926;6159.983602705593;6161.014237721943;5004.874878128003;6069.180660610849;6121.605402471149;6132.969335153296;6140.846478837975;6108.786192483241;6117.614269788186;6140.416660417253;6142.468080853527;6179.0422484514975;4173.9582604173565;4926.802927882844;4950.108980383566;4967;4967.428745694219;4332.241857674894;4361.542038085955;4375.299952007664;4400.9119817604105;4411.250087485071;5623.325200975827;5658.688772117491;5839.9124013139835;5854.648721076749;5885.264341957255;6143.139960405547;6148;6194.0708893666315;6253.249610046732;6307.2431308242385;5876.823695289148;5876.853078672982;5884.293884733859;5918.99377105893;5950.018246989285;4713.9764301178275;5393.919091213635;5399.2980912481125;5427.592930530226;5456.61803673747;6195.132681424604;6232.906506402317;6262.1546091278005;6271.811845644638;6281.497480201481;6266.498680105672;6268.749250029948;6281.434670879643;6287.685615719287;6307.432331090224;6358;6376.298607153302;6384.3934826191435;6390.041493775971;6397.200349956255;6062.666553339609;6098.878022439615;6102.755889764538;6141.723622436912;6144.8771024580155;6169.691515424301;6261.624302541862;6266.373362663697;6283;6288.937110629018;5975.671338076274;6057.818265452043;6091.269047714302;6128.63228206309;6157.630542167484;5924.229850119457;5924.733386997509;5941.435563621622;6042.093685947143;6064.211652485149;5731.541476681939;5788.565857560616;5845.678487683218;5868.833128478657;5904.911426328695;6069.969650151721;6107.786227482017;6109.358517355614;6122.173506576555;6142.662153581596;5783.306003279633;5787.450192231731;5852.7658893643775;5856.648601083949;5933.732982015733 16:04:52 INFO - PROCESS | 1640 | |1;ext.html;423.6229755517589;479.7697105389415;506.2002036781885;527.485701441095;568.3432793407216;30057.84971075147;33903.49144762827;38122;38211.61788382122;38218.80890595543;38332.425013624816;38720.419193712114;38884.805575972154;38933.610663893356;39024.414633780514;8435.95782021089;9130.954345228298;9344.20574251186;9346.672866449642;9363;8478.406511544194;11013.779724405507;11286.040686541653;11288.492017859175;11484.310941343505;11265.211435199539;11312.943435282854;11325.716857078556;11344.48949797261;11366.60216892409;9825.361351512129;10805.621803236927;11190.496427660775;11195.040867848134;11236.157288203418;11040.227184097117;11079.390633515153;11124.55501779936;11125.054370378528;11130.610428634958;38145.42781858274;38379;38419.61580384188;38477.807610962045;38530.614693852986;3258.840790905191;3306.1404034950847;3395.5076513905346;3478.4260597001467;3647.6170002149925;26378.472430551472;28956.42087158266;29529.409411811645;29609.259768505744;29689.554656680168;2754.862256887148;3191.2341038150907;3209.9197520062185;3225.4033003894356;3298.2908674634828;505580;509095.7268632171;512763;514310;516271.9999999962;58436;58728.11907821386;58945.705271473365;59106.11340829891;59195;54963.175552366745;61337;61451;61458.69270653708;61536.69231653813;15209.870618113473;15687;15713.528594142193;15737;15764.921175394278;10573.577056917637;10981.066609338295;11257.493412796444;11862.169648124549;12172.513099476098;14021.369038393297;15331.770023449657;17551.561210969572;17847.375341863233;18132.456026319232;6317.610125772323;7608.505447145918;7623.618819059025;7692.499987500794;7713;830.6137645994642;854.811941372897;957.7749228931195;971.155095067296;1017.2452519404117;27790;28451.430971380458;28655;29447.411051778843;29448;1022.1311884897926;1041.8489319048695;1044.9529771160317;1046.215338496128;1086.2721976275914;13779;14907.776383354258;14963.40146394132;15125.243737813285;15197.784177265792;667371;669441;672059;673082;675235;53907;54166;54232;54399;54544.454555453936;1071.4535586850607;1130.8699499557492;1174.7591743692522;1310.5871650430083;1362.223532586436;24839.751602484466;26126.477470450864;27641;27674.44651107007;28580.85709571439;46076.7696161517;46845.765771170925;46871.53128468672;47017.764911175225;47131.29303060388;578961;580517;581751;581841;582376;22052.669209962183;22514.549709006056;24389.878050609634;25016.499670006862;25411.983520659705;43162.56837431585;43479.56520434755;43543;43850.69508665397;44813.32780008368;36500;36607;36858.70994515286;37689;38000.80999595095;36263.27473450464;36843.26313473769;37453.25093498169;37483.81258093692;37726.43410348792;28738;34097;35996.4600530987;36006.45990310095;36335.454968176025;31736.206594835316;33053;33103;33132;33145.17137071592;22534.184320658365;24019.399515012265;24461.877690611433;24532.6320105195;25122 16:04:52 INFO - PROCESS | 1640 | |2;jquery.html;350.66161154485934;358.80445157389227;362.6609120472358;363.3369101389961;386.73508646577096;17947.641047179048;18436;19502.51243718906;19621.411357659294;19712.60574788507;18919.810801891977;19627.803721962813;19638.509037274074;19674.016299185056;20118.396448106545;4863.929935414221;5147.6911385317;5210.557102646279;5396.271503347057;5425.6202065855405;5321.787128514855;5592.5246354059955;5892;5974.522038236931;6052.031674932012;5889.7644094236175;5938.792142275021;6030.909536356936;6035;6046.637201767908;5825.708714564276;5956.404359564052;5981.551383646244;5985.072313791369;6004.639721616695;5524.779008839641;5760.279965004374;5766.538676905838;5772.769089236425;5795.88408231833;12830.87169128306;12988.545400911018;12993.545225917142;13039.413226404835;18411.815881841143;2144.7426308842882;2182.23621732394;2310.7111611048617;2349.929502114939;2909.214512081747;8626.18051285127;9331.953340233324;9740.123388894963;9831.410115393099;10203.132733717643;1757.393699173783;1778.2531336838558;1784.6787578235912;1785.7767779027622;1826.488583196707;101129;133316;134583.9812402815;134902.3254883729;138108;11259.155563332783;11372.431378431045;11464;11588.826167607493;11711.414429278673;11767.411629418495;14274.286285685674;14397.78403323951;14407.711845763026;14433.639159020899;8264.471072851542;8385.203405676451;8390.916090839013;8407.2433480987;8545.48727076365;7215.9536867153965;8255.463394879434;8271.627776750061;8430.873536896952;8434.325253979667;4406.1187762447;4463.330500424897;4506.977465112654;4516.638668906479;4590.747508886977;1619.8461146191094;1852.5553867072076;1908.7136929460692;1919.6352692988287;1958.3439547751664;819.328150916243;860.7202659135806;909.1770999146669;928.4150984879618;935.7099299217265;19817.900910495642;20559.794402056086;20645.58708825815;20757.688634670492;20808.063637136365;867.6399294292924;870.8214815962713;883.867419887022;919.384012711485;928.03020843219;9147.72556823283;10469.895301047045;10781.177262957823;10792.730181745519;10835.458227088771;565908;570597;581612;584068;585106;112682.43658781654;113303.43348283206;117218;117764.41117794528;118919;947.8388673925449;965.4014511003082;968.41894863083;969.0890562870878;969.7381706939087;14077.225752583707;15475.148866812433;17813.376531821323;18283.360082397052;18375.805572637735;53292.73353633207;53580;55756.72121639366;55849.72075139598;56552.15171772509;75757.6212118936;78339.60830195813;80361.19638803537;81302.59348703455;83371.02501537482;7720.150783413932;7870.921290787019;7892.881606776018;7909.1695371985115;7980.960095199719;10714.51784669676;11605.6518304451;11826.645200643994;11924.495855870018;12216.511339546674;8875.911240887508;8880.801091852518;9544.95227523858;10258.28192026551;10521.736956576147;9201.953990230006;10467.110295624958;10884.346939183357;10900.509477073392;11145.832812507657;11061.944690276498;11073.501692423699;11311.547538098384;11408.030317423112;11424.543018279175;6139.570230083851;7967.482113662593;8086.312663423675;8154.184581542036;8159.551224682462;8959.865602015845;9975.650852220137;10815.134789216687;11092.279001864854;11233.382163980732 16:04:52 INFO - PROCESS | 1640 | |3;mootools.html;1442.9350679219433;1497.9176145312008;1654.9999999999993;1740.4604572582493;1803.6933721267383;7429.182789893121;7639.350655194316;7814;8240.917590824083;8299.294559962404;7801.92198078019;8090.352771778258;8119.797005074876;8226.588670566463;8229.341652667787;2293.7820907013847;2319.8516734216587;2320.814334853216;2325.5232677301115;2327.290176496168;2306.8615883046987;2340.496793189464;2345.5660702769956;2359.1742889988536;2362.29131260622;1850.2968871828687;2301.1255722825304;2305.4236440889777;2322.512272422793;2326.499802542453;2260.8417410781253;2266.7619899910437;2285.1316499730165;2295.070496448941;2296.3925252323443;2294.128231272123;2295.2884605772265;2305.6656784766114;2310.838241323108;2311.3643747969277;8115;8169.387295952827;8189.058258300312;8230.62962166698;8248.381371397169;4955.430125535573;5085.847424577269;5110.003549307887;5113.897722045575;5121.795128194868;3174.2857856981996;3239.919002024945;3498.2303893143467;3508.2281897982402;3519.8768043118366;888.5956889615275;1014.1987829614595;1077.6497638267595;1080.2276372393699;1084.7505073833063;9205.355625106245;10044.94977525115;10244.924282950333;10545.209109316833;10546.789064218749;3792.639699228569;3797.316483033025;4230.49234091911;4328.523862375137;4381.6494680425485;7212.459065570103;8414.915850841535;8518.957405212934;8526.87209691855;8530.317574594017;4794.568488836021;5020.171671674206;5030.37120359955;5031.748412579356;5040.31955685985;4230.280852255124;4364.10535840152;4553;4697.765111744399;4736.171170045228;2894.4645240630553;3570.6072332043454;3655.049687081325;3661.1945372018113;3669.743117981769;958.2621381536178;1129.785340785248;1132.5583022621183;1135.137295655308;1149.310413751759;801.3749275565068;831.1771346367053;856.2678909532385;887.8668199769957;924.2836801478774;5285.365756109291;5487.917681234785;5679.574031947621;5717.171010203498;5972.134040564094;942.9627409849111;943.6980166346704;965.8647789309503;1013.9847902281472;1050.81610718124;3613.439916812898;3657.7805331680183;3740.4389341598435;3780.9243815124096;3806.3909774435956;9546;9770.462624555576;9979.550920208609;9996.500174991366;10009.949950250202;9311.161995420445;9756.707298781048;9781.77727784027;9900.455474948947;9962.352447090916;7073.681684324218;7665.501742386727;7719.305262526287;7732.994710687574;7762;5279.683219006718;5363.812266570807;5384.596155288291;5763.538916886723;5769.884602307847;8827.69103081389;9128.680496182602;9177.862332065159;9272.53637318118;9288.210502107126;8821;9144.862827057732;9281.071892810665;9306.534673266446;9372.343935924693;6375.725193883495;6935.445164386938;7036.824079398056;7091.680874360563;7197.064381630564;7722.652480638497;7889.0138732658415;7893.881591776013;7909.367250620052;7917.564533950688;6934.341237582422;7027.227005029545;7046.436285097282;7086.50394472382;7179.748708795167;5909.8227053188475;6898.379145876896;6921.515493915579;6960.199577048705;7002.509824312249;6451.548391612542;6526.869462610816;6609.20689517261;6614.173228346457;6628.072069910313;4717.315989181481;4759.428868535798;4763.499832517536;4809.807607695653;4854.975725121351;4642.117997580449;4692.929606055844;4792.568668819823;4862.41651001882;4957.281194226889 16:04:52 INFO - PROCESS | 1640 | |4;prototype.html;259.3037693792168;296.64699008180264;302.1871166561948;306.63816696298375;308.12492521239676;4449.466064072311;5188.16989281715;5254.211868219769;5316.537952063185;5490.368607610131;4382;5184.403793563742;5186.740662966846;5325.094733895237;5413.215083812855;3185.936281274379;3410.9658903410896;3441.8316460629894;4165.66674666026;4223.936640950373;3668.7982160981137;3863.7498751373546;4192.413062171298;4243.872683819475;4266.352268810154;3872.845086196549;4099.221147981889;4149.419081328617;4162.354835000565;4188.889944164784;3778.414345776409;3780.8487660493547;3801.3917773156163;4109.630133288018;4132.876013719593;3610.801405922673;3616.963830361689;3624.3476174288617;3635.909102272438;3675.4303083022173;4949.381327334083;5269.578433725293;5328.254044433782;5355.544778693816;5381.408045115034;4569.588737013684;4609;4653.58117769399;4657.441107067174;4707.38803955487;5685.971570142165;5708.257926469574;5720.799772008001;5733.741981610838;5762;3771.962280377216;3852.4028775539837;3875.4380614810975;3875.6318149775725;3888.1640447303685;4915.950840491585;5192.636515443921;5585.832425027215;5935.40645935403;5971.761129554812;3580.838862251205;3882.514685664292;3915.941260881089;3934.5475270343704;3938.350172221609;3522.802247235952;3963.2165525513633;3967.761934283952;3999.8800035998966;4001.7598944063448;4252.298370768788;4607.3319368691355;4626.514216007271;4647.651426143053;4667.229907065364;5546.140348245947;5773.047447171254;5773.884522309614;5834.474897259182;5843.678597677169;1117.2570240789946;1163.5462169753803;1243.7139457924727;1253;1297.2151848131932;773.8701495816059;785.4776573578506;867.6009035843457;880.3133555826465;882.5631312500225;4286.592773686495;4821.590164835958;4922.757263514346;5145.922811157836;5150.175971844487;4711.858644240679;4994.307627778794;5079.5428411443145;5520.723963801794;5644.74598643062;5467.589930755209;5619.522340601095;5619.634723742944;5623.8594035149445;5631.971840140773;2700.9594856077174;2862.0412161925988;2873.204247345409;2975.5536669499315;3027.3339865229614;4551.340055691906;4749;5518.724063796874;5584.52531534816;5864.501517370986;5331.413544510101;5780.739866706008;5815.883682326415;5846.561507886926;5861.677607731615;5036.597072234139;5135.717535535581;5911.07889935764;5977.731002104916;6025.66858822769;4482.932756008598;5302.125149350314;5340.9465905342;5364.570834333166;5659.745311460912;4583.564561366665;4800.783964721666;5279.868003299948;5626.634268772516;5636.2954630671165;5542.916856247079;5629.3112066380745;5902.6458412495385;5943.227380440515;5963.254593175853;3477.175107381961;3659.670629643345;3674.008017835116;3699.7780133192095;3727.9254414912093;5651.361105279423;5673.74468148926;5676.262085928968;5678.943210568007;5692.260006199168;4699.624030077517;5110.821121260738;5116.936116779079;5275.762590683506;5502.926929248758;4873.2739031636975;4920.532549407801;4997.375328083989;5432.755526001419;5505.532029777513;5095.694258344512;5307.681539107666;5318;5494.478024587658;5508.669479831223;4819.807207711652;5705.058665320175;5708.400617935223;5714.79998200061;5732.340780810094;4848.7090774553635;4972.105021096238;5342.786288548415;5568.443155684399;5612.611892583166 16:04:52 INFO - PROCESS | 1640 | |5;yui.html;581.6190395291085;678.9388954994049;697.4002357972145;722.1081963774737;767.5663250263601;11785.233959792607;15077.170755608437;15261.160636165007;15544.68910621787;16390.83609163908;15530.456434024783;16041.278142483587;16085.356585736556;16125;16128.83871161288;797.6889013284823;799.0611032037348;821.5481485183155;826.1284345016006;838.5513750143674;639.1403562208822;660.7224965514496;825.0347093900122;825.2531459029558;827.2843989948681;835.6699103853974;867.6746220167437;868.5700578213794;873.9825203495926;874.1870060843413;863.8574635185187;864.6238886084574;865.1996902864825;865.5845194306705;867.1935100356666;624.1605041219598;626.6302881300055;633.8668879535303;643.4498503779248;644.7711062572813;14498.057626254365;14825.77761333581;14869;14869.776953345709;14908.105513669107;12937.547185848543;13185.86814131856;13243.47026118954;13370.39833207508;13494.662633434145;12582.496700132075;12623.179493332997;12661.556845510362;12879.613611591667;12891.548795792101;4339.60943515082;4371.890702732426;4379.386885835986;4557.680962332638;4566.246569316058;95807;112699;119495;121534;121554;9089.590968406437;9848.852267215998;10209.489525523693;10293.94853025745;10575.788484230272;20294.086766095264;20343.186272548934;20440.59118817645;20478.590428191354;20500.487487812923;7388.44586656003;7490.700371985168;7492.363149132276;7562.659680314399;7566.659500322498;4099.323611604112;4101.569335219759;4141.503019637603;4157.438745769283;4171.3325867860995;2678.2902530829347;3372.2581032172884;3375.4261775498226;3601.549806274216;3772.981135094307;652.2368828470701;655.3774112592282;667.4160109903834;668.301624802083;671.2549070531705;557.565099222607;562.4431812505671;608.7078202462843;654.4110300729286;668.7258224128084;7396.667149978264;8527.616257268437;8668.306535477146;9040.050794666597;9532;507.55335304931424;578.8089930322983;598.1715324275909;639.6258188959417;655.226832337846;3988.6011398859882;4231.767252801125;4596.218642830727;4643.582077613031;4684.625229981593;113778.29332560019;116944.24583631252;118979;119793.76910933126;121785;51876.74061629668;96139;99839;106547;108125.45937270264;82565.17434825575;82992.58503707564;83753.58123209467;84939.57530212433;85872;7864.921350786419;9478.431294122374;9900.702978910644;9929.205663547044;9944.651937182163;37381.439278410304;43348;50218.748906255234;50612;57470.7126464365;60557.39442605517;63987.36012639814;75863.62068189624;79369.60315198387;81487;12214.511419543118;12722.936385318015;12758.744825103631;12822.551210707581;13227.933860330637;14290.42838286457;14440.638984025483;14486.348114334669;14972.775408369102;14975.475858344906;13243.20540767557;13301.733965320445;13309;13360.398782055025;14136.575902722523;13103.383262796948;13146.408411621309;13338.599842004756;13389.732205355644;13462;10630.936906309616;12901.806472902726;13058.738825223632;13242.602721918358;13294;10796.352218866894;10954.452277385939;11182.72043198894;11207.60773372928;11848.70378240551;9508.762280942756;10029.74925626836;11288.717782055513;11332.490037948477;11554.42227888619 16:04:52 INFO - PROCESS | 1640 | __end_tp_report 16:04:52 INFO - PROCESS | 1640 | __start_cc_report 16:04:52 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,379 16:04:52 INFO - PROCESS | 1640 | __end_cc_report 16:04:52 INFO - PROCESS | 1640 | __startTimestamp1480377892473__endTimestamp 16:04:52 INFO - PROCESS | 1640 | 16:04:52 INFO - PROCESS | 1640 | ------- Summary: start ------- 16:04:52 INFO - PROCESS | 1640 | Number of tests: 6 16:04:52 INFO - PROCESS | 1640 | 16:04:52 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:5652.30 Median:5876.85 stddev:795.08 (13.5%) stddev-sans-first:768.67 16:04:52 INFO - PROCESS | 1640 | Values: 2946.9 3320.1 3343.1 3381.8 3399.5 5614.9 5650.5 5705.3 5734.3 5735.5 5018.0 5622.3 5654.7 5677.2 5680.2 5153.6 5177.4 5191.1 5233.0 5240.1 5216.8 5251.6 5309.1 5313.9 5348.7 109.6 5274.3 5279.4 5292.9 5303.5 4478.5 5204.4 5233.1 5258.3 5294.6 5156.8 5161.4 5170.2 5202.6 5213.4 5551.0 5673.1 5673.8 5715.1 5720.5 4401.4 5230.8 6123.1 6147.5 6207.0 4802.5 6178.9 6198.4 6278.3 6281.7 5818.2 5825.3 5837.5 5838.1 5874.1 6111.5 6300.0 6313.1 6313.3 6326.5 5956.9 5988.4 6064.2 6068.9 6072.4 6129.6 6154.1 6154.9 6160.0 6161.0 5004.9 6069.2 6121.6 6133.0 6140.8 6108.8 6117.6 6140.4 6142.5 6179.0 4174.0 4926.8 4950.1 4967.0 4967.4 4332.2 4361.5 4375.3 4400.9 4411.3 5623.3 5658.7 5839.9 5854.6 5885.3 6143.1 6148.0 6194.1 6253.2 6307.2 5876.8 5876.9 5884.3 5919.0 5950.0 4714.0 5393.9 5399.3 5427.6 5456.6 6195.1 6232.9 6262.2 6271.8 6281.5 6266.5 6268.7 6281.4 6287.7 6307.4 6358.0 6376.3 6384.4 6390.0 6397.2 6062.7 6098.9 6102.8 6141.7 6144.9 6169.7 6261.6 6266.4 6283.0 6288.9 5975.7 6057.8 6091.3 6128.6 6157.6 5924.2 5924.7 5941.4 6042.1 6064.2 5731.5 5788.6 5845.7 5868.8 5904.9 6070.0 6107.8 6109.4 6122.2 6142.7 5783.3 5787.5 5852.8 5856.6 5933.7 16:04:52 INFO - PROCESS | 1640 | 16:04:52 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:70663.12 Median:18787.71 stddev:158465.36 (843.5%) stddev-sans-first:158822.14 16:04:52 INFO - PROCESS | 1640 | Values: 423.6 479.8 506.2 527.5 568.3 30057.8 33903.5 38122.0 38211.6 38218.8 38332.4 38720.4 38884.8 38933.6 39024.4 8436.0 9131.0 9344.2 9346.7 9363.0 8478.4 11013.8 11286.0 11288.5 11484.3 11265.2 11312.9 11325.7 11344.5 11366.6 9825.4 10805.6 11190.5 11195.0 11236.2 11040.2 11079.4 11124.6 11125.1 11130.6 38145.4 38379.0 38419.6 38477.8 38530.6 3258.8 3306.1 3395.5 3478.4 3647.6 26378.5 28956.4 29529.4 29609.3 29689.6 2754.9 3191.2 3209.9 3225.4 3298.3 505580.0 509095.7 512763.0 514310.0 516272.0 58436.0 58728.1 58945.7 59106.1 59195.0 54963.2 61337.0 61451.0 61458.7 61536.7 15209.9 15687.0 15713.5 15737.0 15764.9 10573.6 10981.1 11257.5 11862.2 12172.5 14021.4 15331.8 17551.6 17847.4 18132.5 6317.6 7608.5 7623.6 7692.5 7713.0 830.6 854.8 957.8 971.2 1017.2 27790.0 28451.4 28655.0 29447.4 29448.0 1022.1 1041.8 1045.0 1046.2 1086.3 13779.0 14907.8 14963.4 15125.2 15197.8 667371.0 669441.0 672059.0 673082.0 675235.0 53907.0 54166.0 54232.0 54399.0 54544.5 1071.5 1130.9 1174.8 1310.6 1362.2 24839.8 26126.5 27641.0 27674.4 28580.9 46076.8 46845.8 46871.5 47017.8 47131.3 578961.0 580517.0 581751.0 581841.0 582376.0 22052.7 22514.5 24389.9 25016.5 25412.0 43162.6 43479.6 43543.0 43850.7 44813.3 36500.0 36607.0 36858.7 37689.0 38000.8 36263.3 36843.3 37453.3 37483.8 37726.4 28738.0 34097.0 35996.5 36006.5 36335.5 31736.2 33053.0 33103.0 33132.0 33145.2 22534.2 24019.4 24461.9 24532.6 25122.0 16:04:52 INFO - PROCESS | 1640 | 16:04:52 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:33883.51 Median:4485.15 stddev:96726.15 (2156.6%) stddev-sans-first:96964.72 16:04:52 INFO - PROCESS | 1640 | Values: 350.7 358.8 362.7 363.3 386.7 17947.6 18436.0 19502.5 19621.4 19712.6 18919.8 19627.8 19638.5 19674.0 20118.4 4863.9 5147.7 5210.6 5396.3 5425.6 5321.8 5592.5 5892.0 5974.5 6052.0 5889.8 5938.8 6030.9 6035.0 6046.6 5825.7 5956.4 5981.6 5985.1 6004.6 5524.8 5760.3 5766.5 5772.8 5795.9 12830.9 12988.5 12993.5 13039.4 18411.8 2144.7 2182.2 2310.7 2349.9 2909.2 8626.2 9332.0 9740.1 9831.4 10203.1 1757.4 1778.3 1784.7 1785.8 1826.5 101129.0 133316.0 134584.0 134902.3 138108.0 11259.2 11372.4 11464.0 11588.8 11711.4 11767.4 14274.3 14397.8 14407.7 14433.6 8264.5 8385.2 8390.9 8407.2 8545.5 7216.0 8255.5 8271.6 8430.9 8434.3 4406.1 4463.3 4507.0 4516.6 4590.7 1619.8 1852.6 1908.7 1919.6 1958.3 819.3 860.7 909.2 928.4 935.7 19817.9 20559.8 20645.6 20757.7 20808.1 867.6 870.8 883.9 919.4 928.0 9147.7 10469.9 10781.2 10792.7 10835.5 565908.0 570597.0 581612.0 584068.0 585106.0 112682.4 113303.4 117218.0 117764.4 118919.0 947.8 965.4 968.4 969.1 969.7 14077.2 15475.1 17813.4 18283.4 18375.8 53292.7 53580.0 55756.7 55849.7 56552.2 75757.6 78339.6 80361.2 81302.6 83371.0 7720.2 7870.9 7892.9 7909.2 7981.0 10714.5 11605.7 11826.6 11924.5 12216.5 8875.9 8880.8 9545.0 10258.3 10521.7 9202.0 10467.1 10884.3 10900.5 11145.8 11061.9 11073.5 11311.5 11408.0 11424.5 6139.6 7967.5 8086.3 8154.2 8159.6 8959.9 9975.7 10815.1 11092.3 11233.4 16:04:52 INFO - PROCESS | 1640 | 16:04:52 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:5284.94 Median:5282.52 stddev:2861.12 (54.2%) stddev-sans-first:2854.58 16:04:52 INFO - PROCESS | 1640 | Values: 1442.9 1497.9 1655.0 1740.5 1803.7 7429.2 7639.4 7814.0 8240.9 8299.3 7801.9 8090.4 8119.8 8226.6 8229.3 2293.8 2319.9 2320.8 2325.5 2327.3 2306.9 2340.5 2345.6 2359.2 2362.3 1850.3 2301.1 2305.4 2322.5 2326.5 2260.8 2266.8 2285.1 2295.1 2296.4 2294.1 2295.3 2305.7 2310.8 2311.4 8115.0 8169.4 8189.1 8230.6 8248.4 4955.4 5085.8 5110.0 5113.9 5121.8 3174.3 3239.9 3498.2 3508.2 3519.9 888.6 1014.2 1077.6 1080.2 1084.8 9205.4 10044.9 10244.9 10545.2 10546.8 3792.6 3797.3 4230.5 4328.5 4381.6 7212.5 8414.9 8519.0 8526.9 8530.3 4794.6 5020.2 5030.4 5031.7 5040.3 4230.3 4364.1 4553.0 4697.8 4736.2 2894.5 3570.6 3655.0 3661.2 3669.7 958.3 1129.8 1132.6 1135.1 1149.3 801.4 831.2 856.3 887.9 924.3 5285.4 5487.9 5679.6 5717.2 5972.1 943.0 943.7 965.9 1014.0 1050.8 3613.4 3657.8 3740.4 3780.9 3806.4 9546.0 9770.5 9979.6 9996.5 10009.9 9311.2 9756.7 9781.8 9900.5 9962.4 7073.7 7665.5 7719.3 7733.0 7762.0 5279.7 5363.8 5384.6 5763.5 5769.9 8827.7 9128.7 9177.9 9272.5 9288.2 8821.0 9144.9 9281.1 9306.5 9372.3 6375.7 6935.4 7036.8 7091.7 7197.1 7722.7 7889.0 7893.9 7909.4 7917.6 6934.3 7027.2 7046.4 7086.5 7179.7 5909.8 6898.4 6921.5 6960.2 7002.5 6451.5 6526.9 6609.2 6614.2 6628.1 4717.3 4759.4 4763.5 4809.8 4855.0 4642.1 4692.9 4792.6 4862.4 4957.3 16:04:52 INFO - PROCESS | 1640 | 16:04:52 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4503.30 Median:5016.99 stddev:1385.70 (27.6%) stddev-sans-first:1352.48 16:04:52 INFO - PROCESS | 1640 | Values: 259.3 296.6 302.2 306.6 308.1 4449.5 5188.2 5254.2 5316.5 5490.4 4382.0 5184.4 5186.7 5325.1 5413.2 3185.9 3411.0 3441.8 4165.7 4223.9 3668.8 3863.7 4192.4 4243.9 4266.4 3872.8 4099.2 4149.4 4162.4 4188.9 3778.4 3780.8 3801.4 4109.6 4132.9 3610.8 3617.0 3624.3 3635.9 3675.4 4949.4 5269.6 5328.3 5355.5 5381.4 4569.6 4609.0 4653.6 4657.4 4707.4 5686.0 5708.3 5720.8 5733.7 5762.0 3772.0 3852.4 3875.4 3875.6 3888.2 4916.0 5192.6 5585.8 5935.4 5971.8 3580.8 3882.5 3915.9 3934.5 3938.4 3522.8 3963.2 3967.8 3999.9 4001.8 4252.3 4607.3 4626.5 4647.7 4667.2 5546.1 5773.0 5773.9 5834.5 5843.7 1117.3 1163.5 1243.7 1253.0 1297.2 773.9 785.5 867.6 880.3 882.6 4286.6 4821.6 4922.8 5145.9 5150.2 4711.9 4994.3 5079.5 5520.7 5644.7 5467.6 5619.5 5619.6 5623.9 5632.0 2701.0 2862.0 2873.2 2975.6 3027.3 4551.3 4749.0 5518.7 5584.5 5864.5 5331.4 5780.7 5815.9 5846.6 5861.7 5036.6 5135.7 5911.1 5977.7 6025.7 4482.9 5302.1 5340.9 5364.6 5659.7 4583.6 4800.8 5279.9 5626.6 5636.3 5542.9 5629.3 5902.6 5943.2 5963.3 3477.2 3659.7 3674.0 3699.8 3727.9 5651.4 5673.7 5676.3 5678.9 5692.3 4699.6 5110.8 5116.9 5275.8 5502.9 4873.3 4920.5 4997.4 5432.8 5505.5 5095.7 5307.7 5318.0 5494.5 5508.7 4819.8 5705.1 5708.4 5714.8 5732.3 4848.7 4972.1 5342.8 5568.4 5612.6 16:04:52 INFO - PROCESS | 1640 | 16:04:52 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:21337.91 Median:23789.88 stddev:32320.69 (135.9%) stddev-sans-first:32373.78 16:04:52 INFO - PROCESS | 1640 | Values: 581.6 678.9 697.4 722.1 767.6 11785.2 15077.2 15261.2 15544.7 16390.8 15530.5 16041.3 16085.4 16125.0 16128.8 797.7 799.1 821.5 826.1 838.6 639.1 660.7 825.0 825.3 827.3 835.7 867.7 868.6 874.0 874.2 863.9 864.6 865.2 865.6 867.2 624.2 626.6 633.9 643.4 644.8 14498.1 14825.8 14869.0 14869.8 14908.1 12937.5 13185.9 13243.5 13370.4 13494.7 12582.5 12623.2 12661.6 12879.6 12891.5 4339.6 4371.9 4379.4 4557.7 4566.2 95807.0 112699.0 119495.0 121534.0 121554.0 9089.6 9848.9 10209.5 10293.9 10575.8 20294.1 20343.2 20440.6 20478.6 20500.5 7388.4 7490.7 7492.4 7562.7 7566.7 4099.3 4101.6 4141.5 4157.4 4171.3 2678.3 3372.3 3375.4 3601.5 3773.0 652.2 655.4 667.4 668.3 671.3 557.6 562.4 608.7 654.4 668.7 7396.7 8527.6 8668.3 9040.1 9532.0 507.6 578.8 598.2 639.6 655.2 3988.6 4231.8 4596.2 4643.6 4684.6 113778.3 116944.2 118979.0 119793.8 121785.0 51876.7 96139.0 99839.0 106547.0 108125.5 82565.2 82992.6 83753.6 84939.6 85872.0 7864.9 9478.4 9900.7 9929.2 9944.7 37381.4 43348.0 50218.7 50612.0 57470.7 60557.4 63987.4 75863.6 79369.6 81487.0 12214.5 12722.9 12758.7 12822.6 13227.9 14290.4 14440.6 14486.3 14972.8 14975.5 13243.2 13301.7 13309.0 13360.4 14136.6 13103.4 13146.4 13338.6 13389.7 13462.0 10630.9 12901.8 13058.7 13242.6 13294.0 10796.4 10954.5 11182.7 11207.6 11848.7 9508.8 10029.7 11288.7 11332.5 11554.4 16:04:52 INFO - PROCESS | 1640 | -------- Summary: end -------- 16:04:52 INFO - PROCESS | 1640 | 16:04:53 INFO - TEST-INFO | 1640: exit 0 16:04:53 INFO - TEST-OK | dromaeo_css | took 1116461ms 16:04:53 INFO - TEST-START | kraken 16:04:53 INFO - operating with platform_type : mac_ 16:04:53 INFO - Initialising browser for kraken test... 16:04:53 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmpWpisJ7/profile http://localhost:49243/getInfo.html) 16:04:56 INFO - PROCESS | 1648 | 2016-11-28 16:04:56.298 plugin-container[1649:10497] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c4b, name = 'com.apple.tsm.portname' 16:04:56 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:04:56 INFO - PROCESS | 1648 | 2016-11-28 16:04:56.301 plugin-container[1649:10497] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910f, name = 'com.apple.CFPasteboardClient' 16:04:56 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:04:56 INFO - PROCESS | 1648 | 2016-11-28 16:04:56.301 plugin-container[1649:10497] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:04:56 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 16:04:56 INFO - PROCESS | 1648 | colorDepth:24 16:04:56 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 16:04:56 INFO - PROCESS | 1648 | __metrics 16:04:56 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 16:04:57 INFO - TEST-INFO | 1648: exit 0 16:04:57 INFO - Browser initialized. 16:04:57 INFO - Running cycle 1/1 for kraken test... 16:04:57 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmpWpisJ7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:04:59 INFO - PROCESS | 1650 | 2016-11-28 16:04:59.903 plugin-container[1651:10613] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 16:04:59 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:04:59 INFO - PROCESS | 1650 | 2016-11-28 16:04:59.904 plugin-container[1651:10613] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 16:04:59 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:04:59 INFO - PROCESS | 1650 | 2016-11-28 16:04:59.904 plugin-container[1651:10613] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:05:09 INFO - PROCESS | 1650 | RSS: Main: 242536448 16:05:09 INFO - PROCESS | 1650 | 16:05:12 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 16:05:13 INFO - PROCESS | 1650 | RSS: Main: 254857216 16:05:13 INFO - PROCESS | 1650 | 16:05:15 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 16:05:16 INFO - PROCESS | 1650 | RSS: Main: 262606848 16:05:16 INFO - PROCESS | 1650 | 16:05:19 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 16:05:20 INFO - PROCESS | 1650 | RSS: Main: 265371648 16:05:20 INFO - PROCESS | 1650 | 16:05:22 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 16:05:23 INFO - PROCESS | 1650 | RSS: Main: 267907072 16:05:23 INFO - PROCESS | 1650 | 16:05:24 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:05:25 INFO - PROCESS | 1650 | RSS: Main: 268267520 16:05:25 INFO - PROCESS | 1650 | 16:05:28 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 16:05:29 INFO - PROCESS | 1650 | RSS: Main: 274046976 16:05:29 INFO - PROCESS | 1650 | 16:05:32 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 16:05:32 INFO - PROCESS | 1650 | RSS: Main: 274542592 16:05:32 INFO - PROCESS | 1650 | 16:05:35 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 16:05:36 INFO - PROCESS | 1650 | RSS: Main: 273924096 16:05:36 INFO - PROCESS | 1650 | 16:05:38 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:05:38 INFO - PROCESS | 1650 | RSS: Main: 272871424 16:05:38 INFO - PROCESS | 1650 | 16:05:40 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:05:41 INFO - PROCESS | 1650 | RSS: Main: 272953344 16:05:41 INFO - PROCESS | 1650 | 16:05:43 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:05:44 INFO - PROCESS | 1650 | RSS: Main: 272863232 16:05:44 INFO - PROCESS | 1650 | 16:05:47 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:05:48 INFO - PROCESS | 1650 | RSS: Main: 273285120 16:05:48 INFO - PROCESS | 1650 | 16:05:51 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:05:51 INFO - PROCESS | 1650 | RSS: Main: 273211392 16:05:51 INFO - PROCESS | 1650 | 16:05:53 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 16:05:53 INFO - PROCESS | 1650 | RSS: Main: 274804736 16:05:53 INFO - PROCESS | 1650 | 16:05:53 INFO - PROCESS | 1650 | __start_tp_report 16:05:53 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 16:05:53 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 16:05:53 INFO - PROCESS | 1650 | |i|pagename|runs| 16:05:53 INFO - PROCESS | 1650 | |0;ai-astar;107;119;118;117;117;117;118;118;118;147 16:05:53 INFO - PROCESS | 1650 | |1;audio-beat-detection;180;146;119;152;141;148;148;116;164;108 16:05:53 INFO - PROCESS | 1650 | |2;audio-dft;194;206;211;226;194;199;198;197;197;200 16:05:53 INFO - PROCESS | 1650 | |3;audio-fft;84;60;65;107;80;61;57;60;73;59 16:05:53 INFO - PROCESS | 1650 | |4;audio-oscillator;84;71;85;73;71;72;82;69;70;82 16:05:53 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;108;90;94;90;94;89;93;78;135;131 16:05:53 INFO - PROCESS | 1650 | |6;imaging-darkroom;98;100;98;98;98;113;154;118;98;99 16:05:53 INFO - PROCESS | 1650 | |7;imaging-desaturate;94;96;86;97;130;110;147;150;136;92 16:05:53 INFO - PROCESS | 1650 | |8;json-parse-financial;76;61;58;58;57;58;58;60;61;81 16:05:53 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;68;77;78;84;76;73;71;72;73;72 16:05:53 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;81;84;99;108;110;99;87;88;89;88 16:05:53 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;149;167;175;180;159;159;139;149;195;167 16:05:53 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;206;242;191;178;182;177;177;178;202;179 16:05:53 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;72;69;70;72;72;68;70;71;72;68 16:05:53 INFO - PROCESS | 1650 | __end_tp_report 16:05:53 INFO - PROCESS | 1650 | __start_cc_report 16:05:53 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,958 16:05:53 INFO - PROCESS | 1650 | __end_cc_report 16:05:53 INFO - PROCESS | 1650 | __startTimestamp1480377953820__endTimestamp 16:05:53 INFO - PROCESS | 1650 | 16:05:53 INFO - PROCESS | 1650 | ------- Summary: start ------- 16:05:53 INFO - PROCESS | 1650 | Number of tests: 14 16:05:53 INFO - PROCESS | 1650 | 16:05:53 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:119.60 Median:118.00 stddev:10.22 (8.7%) stddev-sans-first:9.77 16:05:53 INFO - PROCESS | 1650 | Values: 107.0 119.0 118.0 117.0 117.0 117.0 118.0 118.0 118.0 147.0 16:05:53 INFO - PROCESS | 1650 | 16:05:53 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:142.20 Median:148.00 stddev:22.30 (15.1%) stddev-sans-first:19.01 16:05:53 INFO - PROCESS | 1650 | Values: 180.0 146.0 119.0 152.0 141.0 148.0 148.0 116.0 164.0 108.0 16:05:53 INFO - PROCESS | 1650 | 16:05:53 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:202.20 Median:199.50 stddev:9.89 (5.0%) stddev-sans-first:10.03 16:05:53 INFO - PROCESS | 1650 | Values: 194.0 206.0 211.0 226.0 194.0 199.0 198.0 197.0 197.0 200.0 16:05:53 INFO - PROCESS | 1650 | 16:05:53 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:70.60 Median:63.00 stddev:15.87 (25.2%) stddev-sans-first:16.07 16:05:53 INFO - PROCESS | 1650 | Values: 84.0 60.0 65.0 107.0 80.0 61.0 57.0 60.0 73.0 59.0 16:05:53 INFO - PROCESS | 1650 | 16:05:53 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:75.90 Median:77.50 stddev:6.47 (8.4%) stddev-sans-first:6.16 16:05:53 INFO - PROCESS | 1650 | Values: 84.0 71.0 85.0 73.0 71.0 72.0 82.0 69.0 70.0 82.0 16:05:53 INFO - PROCESS | 1650 | 16:05:53 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:100.20 Median:90.00 stddev:18.78 (20.9%) stddev-sans-first:19.71 16:05:53 INFO - PROCESS | 1650 | Values: 108.0 90.0 94.0 90.0 94.0 89.0 93.0 78.0 135.0 131.0 16:05:53 INFO - PROCESS | 1650 | 16:05:53 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:107.40 Median:98.00 stddev:17.90 (18.3%) stddev-sans-first:18.66 16:05:53 INFO - PROCESS | 1650 | Values: 98.0 100.0 98.0 98.0 98.0 113.0 154.0 118.0 98.0 99.0 16:05:53 INFO - PROCESS | 1650 | 16:05:53 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:113.80 Median:89.00 stddev:24.54 (27.6%) stddev-sans-first:24.96 16:05:53 INFO - PROCESS | 1650 | Values: 94.0 96.0 86.0 97.0 130.0 110.0 147.0 150.0 136.0 92.0 16:05:53 INFO - PROCESS | 1650 | 16:05:53 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:62.80 Median:60.50 stddev:8.47 (14.0%) stddev-sans-first:7.52 16:05:53 INFO - PROCESS | 1650 | Values: 76.0 61.0 58.0 58.0 57.0 58.0 58.0 60.0 61.0 81.0 16:05:53 INFO - PROCESS | 1650 | 16:05:53 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:74.40 Median:74.50 stddev:4.50 (6.0%) stddev-sans-first:4.14 16:05:53 INFO - PROCESS | 1650 | Values: 68.0 77.0 78.0 84.0 76.0 73.0 71.0 72.0 73.0 72.0 16:05:53 INFO - PROCESS | 1650 | 16:05:53 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:93.30 Median:88.00 stddev:10.07 (11.4%) stddev-sans-first:9.64 16:05:53 INFO - PROCESS | 1650 | Values: 81.0 84.0 99.0 108.0 110.0 99.0 87.0 88.0 89.0 88.0 16:05:53 INFO - PROCESS | 1650 | 16:05:53 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:163.90 Median:167.00 stddev:16.60 (9.9%) stddev-sans-first:16.71 16:05:53 INFO - PROCESS | 1650 | Values: 149.0 167.0 175.0 180.0 159.0 159.0 139.0 149.0 195.0 167.0 16:05:53 INFO - PROCESS | 1650 | 16:05:53 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.20 Median:186.50 stddev:20.82 (11.2%) stddev-sans-first:21.38 16:05:53 INFO - PROCESS | 1650 | Values: 206.0 242.0 191.0 178.0 182.0 177.0 177.0 178.0 202.0 179.0 16:05:53 INFO - PROCESS | 1650 | 16:05:53 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:71.50 stddev:1.65 (2.3%) stddev-sans-first:1.64 16:05:53 INFO - PROCESS | 1650 | Values: 72.0 69.0 70.0 72.0 72.0 68.0 70.0 71.0 72.0 68.0 16:05:53 INFO - PROCESS | 1650 | -------- Summary: end -------- 16:05:53 INFO - PROCESS | 1650 | 16:05:54 INFO - TEST-INFO | 1650: exit 0 16:05:54 INFO - TEST-OK | kraken | took 61750ms 16:05:54 INFO - SUITE-END | took 1178s 16:05:54 INFO - Completed test suite (00:19:38) 16:05:54 INFO - javascript benchmark 16:05:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7096.800285755639, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2946.882124715011, 3320.086976081577, 3343.0973637117977, 3381.8478168482416, 3399.4900764885274, 5614.887702245957, 5650.5479561635075, 5705.343885453174, 5734.254546908906, 5735.512481439083, 5018.0, 5622.32532096149, 5654.660720356782, 5677.176809362651, 5680.176374425706, 5153.6392452528335, 5177.404598471167, 5191.143461328877, 5233.0, 5240.082985477546, 5216.8174113906025, 5251.632385733, 5309.123994540915, 5313.884084342293, 5348.705821179834, 109.63954982812986, 5274.340707411574, 5279.366476022863, 5292.894142117156, 5303.469653034685, 4478.529754375809, 5204.42751297357, 5233.136532472137, 5258.316418865561, 5294.602904782157, 5156.793728250866, 5161.4322424533275, 5170.224466330043, 5202.635815492917, 5213.426523082458, 5551.0, 5673.120666296731, 5673.801416950429, 5715.085586306171, 5720.456556627114, 4401.449818772653, 5230.816921407771, 6123.112148738453, 6147.47746441553, 6207.0, 4802.495737947535, 6178.938210617881, 6198.411150940653, 6278.336240896167, 6281.685915704196, 5818.21454103699, 5825.330087040044, 5837.474627283523, 5838.065909454509, 5874.089516125007, 6111.5309653553995, 6299.96850015747, 6313.116163737081, 6313.305536390993, 6326.4622507087415, 5956.940430595639, 5988.371221021817, 6064.151018857364, 6068.908966365508, 6072.392760723892, 6129.601575897553, 6154.138420621117, 6154.876902461926, 6159.983602705593, 6161.014237721943, 5004.874878128003, 6069.180660610849, 6121.605402471149, 6132.969335153296, 6140.846478837975, 6108.786192483241, 6117.614269788186, 6140.416660417253, 6142.468080853527, 6179.0422484514975, 4173.9582604173565, 4926.802927882844, 4950.108980383566, 4967.0, 4967.428745694219, 4332.241857674894, 4361.542038085955, 4375.299952007664, 4400.9119817604105, 4411.250087485071, 5623.325200975827, 5658.688772117491, 5839.9124013139835, 5854.648721076749, 5885.264341957255, 6143.139960405547, 6148.0, 6194.0708893666315, 6253.249610046732, 6307.2431308242385, 5876.823695289148, 5876.853078672982, 5884.293884733859, 5918.99377105893, 5950.018246989285, 4713.9764301178275, 5393.919091213635, 5399.2980912481125, 5427.592930530226, 5456.61803673747, 6195.132681424604, 6232.906506402317, 6262.1546091278005, 6271.811845644638, 6281.497480201481, 6266.498680105672, 6268.749250029948, 6281.434670879643, 6287.685615719287, 6307.432331090224, 6358.0, 6376.298607153302, 6384.3934826191435, 6390.041493775971, 6397.200349956255, 6062.666553339609, 6098.878022439615, 6102.755889764538, 6141.723622436912, 6144.8771024580155, 6169.691515424301, 6261.624302541862, 6266.373362663697, 6283.0, 6288.937110629018, 5975.671338076274, 6057.818265452043, 6091.269047714302, 6128.63228206309, 6157.630542167484, 5924.229850119457, 5924.733386997509, 5941.435563621622, 6042.093685947143, 6064.211652485149, 5731.541476681939, 5788.565857560616, 5845.678487683218, 5868.833128478657, 5904.911426328695, 6069.969650151721, 6107.786227482017, 6109.358517355614, 6122.173506576555, 6142.662153581596, 5783.306003279633, 5787.450192231731, 5852.7658893643775, 5856.648601083949, 5933.732982015733], "value": 5604.459092925252, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.6229755517589, 479.7697105389415, 506.2002036781885, 527.485701441095, 568.3432793407216, 30057.84971075147, 33903.49144762827, 38122.0, 38211.61788382122, 38218.80890595543, 38332.425013624816, 38720.419193712114, 38884.805575972154, 38933.610663893356, 39024.414633780514, 8435.95782021089, 9130.954345228298, 9344.20574251186, 9346.672866449642, 9363.0, 8478.406511544194, 11013.779724405507, 11286.040686541653, 11288.492017859175, 11484.310941343505, 11265.211435199539, 11312.943435282854, 11325.716857078556, 11344.48949797261, 11366.60216892409, 9825.361351512129, 10805.621803236927, 11190.496427660775, 11195.040867848134, 11236.157288203418, 11040.227184097117, 11079.390633515153, 11124.55501779936, 11125.054370378528, 11130.610428634958, 38145.42781858274, 38379.0, 38419.61580384188, 38477.807610962045, 38530.614693852986, 3258.840790905191, 3306.1404034950847, 3395.5076513905346, 3478.4260597001467, 3647.6170002149925, 26378.472430551472, 28956.42087158266, 29529.409411811645, 29609.259768505744, 29689.554656680168, 2754.862256887148, 3191.2341038150907, 3209.9197520062185, 3225.4033003894356, 3298.2908674634828, 505580.0, 509095.7268632171, 512763.0, 514310.0, 516271.9999999962, 58436.0, 58728.11907821386, 58945.705271473365, 59106.11340829891, 59195.0, 54963.175552366745, 61337.0, 61451.0, 61458.69270653708, 61536.69231653813, 15209.870618113473, 15687.0, 15713.528594142193, 15737.0, 15764.921175394278, 10573.577056917637, 10981.066609338295, 11257.493412796444, 11862.169648124549, 12172.513099476098, 14021.369038393297, 15331.770023449657, 17551.561210969572, 17847.375341863233, 18132.456026319232, 6317.610125772323, 7608.505447145918, 7623.618819059025, 7692.499987500794, 7713.0, 830.6137645994642, 854.811941372897, 957.7749228931195, 971.155095067296, 1017.2452519404117, 27790.0, 28451.430971380458, 28655.0, 29447.411051778843, 29448.0, 1022.1311884897926, 1041.8489319048695, 1044.9529771160317, 1046.215338496128, 1086.2721976275914, 13779.0, 14907.776383354258, 14963.40146394132, 15125.243737813285, 15197.784177265792, 667371.0, 669441.0, 672059.0, 673082.0, 675235.0, 53907.0, 54166.0, 54232.0, 54399.0, 54544.454555453936, 1071.4535586850607, 1130.8699499557492, 1174.7591743692522, 1310.5871650430083, 1362.223532586436, 24839.751602484466, 26126.477470450864, 27641.0, 27674.44651107007, 28580.85709571439, 46076.7696161517, 46845.765771170925, 46871.53128468672, 47017.764911175225, 47131.29303060388, 578961.0, 580517.0, 581751.0, 581841.0, 582376.0, 22052.669209962183, 22514.549709006056, 24389.878050609634, 25016.499670006862, 25411.983520659705, 43162.56837431585, 43479.56520434755, 43543.0, 43850.69508665397, 44813.32780008368, 36500.0, 36607.0, 36858.70994515286, 37689.0, 38000.80999595095, 36263.27473450464, 36843.26313473769, 37453.25093498169, 37483.81258093692, 37726.43410348792, 28738.0, 34097.0, 35996.4600530987, 36006.45990310095, 36335.454968176025, 31736.206594835316, 33053.0, 33103.0, 33132.0, 33145.17137071592, 22534.184320658365, 24019.399515012265, 24461.877690611433, 24532.6320105195, 25122.0], "value": 19393.98533877146, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.66161154485934, 358.80445157389227, 362.6609120472358, 363.3369101389961, 386.73508646577096, 17947.641047179048, 18436.0, 19502.51243718906, 19621.411357659294, 19712.60574788507, 18919.810801891977, 19627.803721962813, 19638.509037274074, 19674.016299185056, 20118.396448106545, 4863.929935414221, 5147.6911385317, 5210.557102646279, 5396.271503347057, 5425.6202065855405, 5321.787128514855, 5592.5246354059955, 5892.0, 5974.522038236931, 6052.031674932012, 5889.7644094236175, 5938.792142275021, 6030.909536356936, 6035.0, 6046.637201767908, 5825.708714564276, 5956.404359564052, 5981.551383646244, 5985.072313791369, 6004.639721616695, 5524.779008839641, 5760.279965004374, 5766.538676905838, 5772.769089236425, 5795.88408231833, 12830.87169128306, 12988.545400911018, 12993.545225917142, 13039.413226404835, 18411.815881841143, 2144.7426308842882, 2182.23621732394, 2310.7111611048617, 2349.929502114939, 2909.214512081747, 8626.18051285127, 9331.953340233324, 9740.123388894963, 9831.410115393099, 10203.132733717643, 1757.393699173783, 1778.2531336838558, 1784.6787578235912, 1785.7767779027622, 1826.488583196707, 101129.0, 133316.0, 134583.9812402815, 134902.3254883729, 138108.0, 11259.155563332783, 11372.431378431045, 11464.0, 11588.826167607493, 11711.414429278673, 11767.411629418495, 14274.286285685674, 14397.78403323951, 14407.711845763026, 14433.639159020899, 8264.471072851542, 8385.203405676451, 8390.916090839013, 8407.2433480987, 8545.48727076365, 7215.9536867153965, 8255.463394879434, 8271.627776750061, 8430.873536896952, 8434.325253979667, 4406.1187762447, 4463.330500424897, 4506.977465112654, 4516.638668906479, 4590.747508886977, 1619.8461146191094, 1852.5553867072076, 1908.7136929460692, 1919.6352692988287, 1958.3439547751664, 819.328150916243, 860.7202659135806, 909.1770999146669, 928.4150984879618, 935.7099299217265, 19817.900910495642, 20559.794402056086, 20645.58708825815, 20757.688634670492, 20808.063637136365, 867.6399294292924, 870.8214815962713, 883.867419887022, 919.384012711485, 928.03020843219, 9147.72556823283, 10469.895301047045, 10781.177262957823, 10792.730181745519, 10835.458227088771, 565908.0, 570597.0, 581612.0, 584068.0, 585106.0, 112682.43658781654, 113303.43348283206, 117218.0, 117764.41117794528, 118919.0, 947.8388673925449, 965.4014511003082, 968.41894863083, 969.0890562870878, 969.7381706939087, 14077.225752583707, 15475.148866812433, 17813.376531821323, 18283.360082397052, 18375.805572637735, 53292.73353633207, 53580.0, 55756.72121639366, 55849.72075139598, 56552.15171772509, 75757.6212118936, 78339.60830195813, 80361.19638803537, 81302.59348703455, 83371.02501537482, 7720.150783413932, 7870.921290787019, 7892.881606776018, 7909.1695371985115, 7980.960095199719, 10714.51784669676, 11605.6518304451, 11826.645200643994, 11924.495855870018, 12216.511339546674, 8875.911240887508, 8880.801091852518, 9544.95227523858, 10258.28192026551, 10521.736956576147, 9201.953990230006, 10467.110295624958, 10884.346939183357, 10900.509477073392, 11145.832812507657, 11061.944690276498, 11073.501692423699, 11311.547538098384, 11408.030317423112, 11424.543018279175, 6139.570230083851, 7967.482113662593, 8086.312663423675, 8154.184581542036, 8159.551224682462, 8959.865602015845, 9975.650852220137, 10815.134789216687, 11092.279001864854, 11233.382163980732], "value": 9129.464109225135, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1442.9350679219433, 1497.9176145312008, 1654.9999999999993, 1740.4604572582493, 1803.6933721267383, 7429.182789893121, 7639.350655194316, 7814.0, 8240.917590824083, 8299.294559962404, 7801.92198078019, 8090.352771778258, 8119.797005074876, 8226.588670566463, 8229.341652667787, 2293.7820907013847, 2319.8516734216587, 2320.814334853216, 2325.5232677301115, 2327.290176496168, 2306.8615883046987, 2340.496793189464, 2345.5660702769956, 2359.1742889988536, 2362.29131260622, 1850.2968871828687, 2301.1255722825304, 2305.4236440889777, 2322.512272422793, 2326.499802542453, 2260.8417410781253, 2266.7619899910437, 2285.1316499730165, 2295.070496448941, 2296.3925252323443, 2294.128231272123, 2295.2884605772265, 2305.6656784766114, 2310.838241323108, 2311.3643747969277, 8115.0, 8169.387295952827, 8189.058258300312, 8230.62962166698, 8248.381371397169, 4955.430125535573, 5085.847424577269, 5110.003549307887, 5113.897722045575, 5121.795128194868, 3174.2857856981996, 3239.919002024945, 3498.2303893143467, 3508.2281897982402, 3519.8768043118366, 888.5956889615275, 1014.1987829614595, 1077.6497638267595, 1080.2276372393699, 1084.7505073833063, 9205.355625106245, 10044.94977525115, 10244.924282950333, 10545.209109316833, 10546.789064218749, 3792.639699228569, 3797.316483033025, 4230.49234091911, 4328.523862375137, 4381.6494680425485, 7212.459065570103, 8414.915850841535, 8518.957405212934, 8526.87209691855, 8530.317574594017, 4794.568488836021, 5020.171671674206, 5030.37120359955, 5031.748412579356, 5040.31955685985, 4230.280852255124, 4364.10535840152, 4553.0, 4697.765111744399, 4736.171170045228, 2894.4645240630553, 3570.6072332043454, 3655.049687081325, 3661.1945372018113, 3669.743117981769, 958.2621381536178, 1129.785340785248, 1132.5583022621183, 1135.137295655308, 1149.310413751759, 801.3749275565068, 831.1771346367053, 856.2678909532385, 887.8668199769957, 924.2836801478774, 5285.365756109291, 5487.917681234785, 5679.574031947621, 5717.171010203498, 5972.134040564094, 942.9627409849111, 943.6980166346704, 965.8647789309503, 1013.9847902281472, 1050.81610718124, 3613.439916812898, 3657.7805331680183, 3740.4389341598435, 3780.9243815124096, 3806.3909774435956, 9546.0, 9770.462624555576, 9979.550920208609, 9996.500174991366, 10009.949950250202, 9311.161995420445, 9756.707298781048, 9781.77727784027, 9900.455474948947, 9962.352447090916, 7073.681684324218, 7665.501742386727, 7719.305262526287, 7732.994710687574, 7762.0, 5279.683219006718, 5363.812266570807, 5384.596155288291, 5763.538916886723, 5769.884602307847, 8827.69103081389, 9128.680496182602, 9177.862332065159, 9272.53637318118, 9288.210502107126, 8821.0, 9144.862827057732, 9281.071892810665, 9306.534673266446, 9372.343935924693, 6375.725193883495, 6935.445164386938, 7036.824079398056, 7091.680874360563, 7197.064381630564, 7722.652480638497, 7889.0138732658415, 7893.881591776013, 7909.367250620052, 7917.564533950688, 6934.341237582422, 7027.227005029545, 7046.436285097282, 7086.50394472382, 7179.748708795167, 5909.8227053188475, 6898.379145876896, 6921.515493915579, 6960.199577048705, 7002.509824312249, 6451.548391612542, 6526.869462610816, 6609.20689517261, 6614.173228346457, 6628.072069910313, 4717.315989181481, 4759.428868535798, 4763.499832517536, 4809.807607695653, 4854.975725121351, 4642.117997580449, 4692.929606055844, 4792.568668819823, 4862.41651001882, 4957.281194226889], "value": 4303.982029600326, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [259.3037693792168, 296.64699008180264, 302.1871166561948, 306.63816696298375, 308.12492521239676, 4449.466064072311, 5188.16989281715, 5254.211868219769, 5316.537952063185, 5490.368607610131, 4382.0, 5184.403793563742, 5186.740662966846, 5325.094733895237, 5413.215083812855, 3185.936281274379, 3410.9658903410896, 3441.8316460629894, 4165.66674666026, 4223.936640950373, 3668.7982160981137, 3863.7498751373546, 4192.413062171298, 4243.872683819475, 4266.352268810154, 3872.845086196549, 4099.221147981889, 4149.419081328617, 4162.354835000565, 4188.889944164784, 3778.414345776409, 3780.8487660493547, 3801.3917773156163, 4109.630133288018, 4132.876013719593, 3610.801405922673, 3616.963830361689, 3624.3476174288617, 3635.909102272438, 3675.4303083022173, 4949.381327334083, 5269.578433725293, 5328.254044433782, 5355.544778693816, 5381.408045115034, 4569.588737013684, 4609.0, 4653.58117769399, 4657.441107067174, 4707.38803955487, 5685.971570142165, 5708.257926469574, 5720.799772008001, 5733.741981610838, 5762.0, 3771.962280377216, 3852.4028775539837, 3875.4380614810975, 3875.6318149775725, 3888.1640447303685, 4915.950840491585, 5192.636515443921, 5585.832425027215, 5935.40645935403, 5971.761129554812, 3580.838862251205, 3882.514685664292, 3915.941260881089, 3934.5475270343704, 3938.350172221609, 3522.802247235952, 3963.2165525513633, 3967.761934283952, 3999.8800035998966, 4001.7598944063448, 4252.298370768788, 4607.3319368691355, 4626.514216007271, 4647.651426143053, 4667.229907065364, 5546.140348245947, 5773.047447171254, 5773.884522309614, 5834.474897259182, 5843.678597677169, 1117.2570240789946, 1163.5462169753803, 1243.7139457924727, 1253.0, 1297.2151848131932, 773.8701495816059, 785.4776573578506, 867.6009035843457, 880.3133555826465, 882.5631312500225, 4286.592773686495, 4821.590164835958, 4922.757263514346, 5145.922811157836, 5150.175971844487, 4711.858644240679, 4994.307627778794, 5079.5428411443145, 5520.723963801794, 5644.74598643062, 5467.589930755209, 5619.522340601095, 5619.634723742944, 5623.8594035149445, 5631.971840140773, 2700.9594856077174, 2862.0412161925988, 2873.204247345409, 2975.5536669499315, 3027.3339865229614, 4551.340055691906, 4749.0, 5518.724063796874, 5584.52531534816, 5864.501517370986, 5331.413544510101, 5780.739866706008, 5815.883682326415, 5846.561507886926, 5861.677607731615, 5036.597072234139, 5135.717535535581, 5911.07889935764, 5977.731002104916, 6025.66858822769, 4482.932756008598, 5302.125149350314, 5340.9465905342, 5364.570834333166, 5659.745311460912, 4583.564561366665, 4800.783964721666, 5279.868003299948, 5626.634268772516, 5636.2954630671165, 5542.916856247079, 5629.3112066380745, 5902.6458412495385, 5943.227380440515, 5963.254593175853, 3477.175107381961, 3659.670629643345, 3674.008017835116, 3699.7780133192095, 3727.9254414912093, 5651.361105279423, 5673.74468148926, 5676.262085928968, 5678.943210568007, 5692.260006199168, 4699.624030077517, 5110.821121260738, 5116.936116779079, 5275.762590683506, 5502.926929248758, 4873.2739031636975, 4920.532549407801, 4997.375328083989, 5432.755526001419, 5505.532029777513, 5095.694258344512, 5307.681539107666, 5318.0, 5494.478024587658, 5508.669479831223, 4819.807207711652, 5705.058665320175, 5708.400617935223, 5714.79998200061, 5732.340780810094, 4848.7090774553635, 4972.105021096238, 5342.786288548415, 5568.443155684399, 5612.611892583166], "value": 4050.308367672732, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [581.6190395291085, 678.9388954994049, 697.4002357972145, 722.1081963774737, 767.5663250263601, 11785.233959792607, 15077.170755608437, 15261.160636165007, 15544.68910621787, 16390.83609163908, 15530.456434024783, 16041.278142483587, 16085.356585736556, 16125.0, 16128.83871161288, 797.6889013284823, 799.0611032037348, 821.5481485183155, 826.1284345016006, 838.5513750143674, 639.1403562208822, 660.7224965514496, 825.0347093900122, 825.2531459029558, 827.2843989948681, 835.6699103853974, 867.6746220167437, 868.5700578213794, 873.9825203495926, 874.1870060843413, 863.8574635185187, 864.6238886084574, 865.1996902864825, 865.5845194306705, 867.1935100356666, 624.1605041219598, 626.6302881300055, 633.8668879535303, 643.4498503779248, 644.7711062572813, 14498.057626254365, 14825.77761333581, 14869.0, 14869.776953345709, 14908.105513669107, 12937.547185848543, 13185.86814131856, 13243.47026118954, 13370.39833207508, 13494.662633434145, 12582.496700132075, 12623.179493332997, 12661.556845510362, 12879.613611591667, 12891.548795792101, 4339.60943515082, 4371.890702732426, 4379.386885835986, 4557.680962332638, 4566.246569316058, 95807.0, 112699.0, 119495.0, 121534.0, 121554.0, 9089.590968406437, 9848.852267215998, 10209.489525523693, 10293.94853025745, 10575.788484230272, 20294.086766095264, 20343.186272548934, 20440.59118817645, 20478.590428191354, 20500.487487812923, 7388.44586656003, 7490.700371985168, 7492.363149132276, 7562.659680314399, 7566.659500322498, 4099.323611604112, 4101.569335219759, 4141.503019637603, 4157.438745769283, 4171.3325867860995, 2678.2902530829347, 3372.2581032172884, 3375.4261775498226, 3601.549806274216, 3772.981135094307, 652.2368828470701, 655.3774112592282, 667.4160109903834, 668.301624802083, 671.2549070531705, 557.565099222607, 562.4431812505671, 608.7078202462843, 654.4110300729286, 668.7258224128084, 7396.667149978264, 8527.616257268437, 8668.306535477146, 9040.050794666597, 9532.0, 507.55335304931424, 578.8089930322983, 598.1715324275909, 639.6258188959417, 655.226832337846, 3988.6011398859882, 4231.767252801125, 4596.218642830727, 4643.582077613031, 4684.625229981593, 113778.29332560019, 116944.24583631252, 118979.0, 119793.76910933126, 121785.0, 51876.74061629668, 96139.0, 99839.0, 106547.0, 108125.45937270264, 82565.17434825575, 82992.58503707564, 83753.58123209467, 84939.57530212433, 85872.0, 7864.921350786419, 9478.431294122374, 9900.702978910644, 9929.205663547044, 9944.651937182163, 37381.439278410304, 43348.0, 50218.748906255234, 50612.0, 57470.7126464365, 60557.39442605517, 63987.36012639814, 75863.62068189624, 79369.60315198387, 81487.0, 12214.511419543118, 12722.936385318015, 12758.744825103631, 12822.551210707581, 13227.933860330637, 14290.42838286457, 14440.638984025483, 14486.348114334669, 14972.775408369102, 14975.475858344906, 13243.20540767557, 13301.733965320445, 13309.0, 13360.398782055025, 14136.575902722523, 13103.383262796948, 13146.408411621309, 13338.599842004756, 13389.732205355644, 13462.0, 10630.936906309616, 12901.806472902726, 13058.738825223632, 13242.602721918358, 13294.0, 10796.352218866894, 10954.452277385939, 11182.72043198894, 11207.60773372928, 11848.70378240551, 9508.762280942756, 10029.74925626836, 11288.717782055513, 11332.490037948477, 11554.42227888619], "value": 7384.544353261222, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1587.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 119.0, 118.0, 117.0, 117.0, 117.0, 118.0, 118.0, 118.0, 147.0], "value": 119.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 146.0, 119.0, 152.0, 141.0, 148.0, 148.0, 116.0, 164.0, 108.0], "value": 142.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 206.0, 211.0, 226.0, 194.0, 199.0, 198.0, 197.0, 197.0, 200.0], "value": 202.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 60.0, 65.0, 107.0, 80.0, 61.0, 57.0, 60.0, 73.0, 59.0], "value": 70.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 71.0, 85.0, 73.0, 71.0, 72.0, 82.0, 69.0, 70.0, 82.0], "value": 75.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 90.0, 94.0, 90.0, 94.0, 89.0, 93.0, 78.0, 135.0, 131.0], "value": 100.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 100.0, 98.0, 98.0, 98.0, 113.0, 154.0, 118.0, 98.0, 99.0], "value": 107.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 86.0, 97.0, 130.0, 110.0, 147.0, 150.0, 136.0, 92.0], "value": 113.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 61.0, 58.0, 58.0, 57.0, 58.0, 58.0, 60.0, 61.0, 81.0], "value": 62.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 77.0, 78.0, 84.0, 76.0, 73.0, 71.0, 72.0, 73.0, 72.0], "value": 74.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 99.0, 108.0, 110.0, 99.0, 87.0, 88.0, 89.0, 88.0], "value": 93.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 167.0, 175.0, 180.0, 159.0, 159.0, 139.0, 149.0, 195.0, 167.0], "value": 163.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [206.0, 242.0, 191.0, 178.0, 182.0, 177.0, 177.0, 178.0, 202.0, 179.0], "value": 191.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 69.0, 70.0, 72.0, 72.0, 68.0, 70.0, 71.0, 72.0, 68.0], "value": 70.4, "unit": "score"}]}]} 16:05:55 INFO - Return code: 0 16:05:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:05:55 ERROR - # TBPL SUCCESS # 16:05:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:05:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:05:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:05:55 INFO - 2016-11-28 16:05:55.155 screenresolution[1655:11004] starting screenresolution argv=screenresolution get 16:05:55 INFO - 2016-11-28 16:05:55.157 screenresolution[1655:11004] Display 0: 1600x1200x32@60 16:05:55 INFO - 2016-11-28 16:05:55.164 screenresolution[1656:11007] starting screenresolution argv=screenresolution list 16:05:55 INFO - Available Modes on Display 0 16:05:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:05:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:05:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:05:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:05:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:05:55 INFO - Intel Iris: 16:05:55 INFO - Chipset Model: Intel Iris 16:05:55 INFO - Type: GPU 16:05:55 INFO - Bus: Built-In 16:05:55 INFO - VRAM (Dynamic, Max): 1536 MB 16:05:55 INFO - Vendor: Intel (0x8086) 16:05:55 INFO - Device ID: 0x0a2e 16:05:55 INFO - Revision ID: 0x0009 16:05:55 INFO - Displays: 16:05:55 INFO - Display: 16:05:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:05:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:05:55 INFO - Display Serial Number: 1600x1200 60 16:05:55 INFO - Main Display: Yes 16:05:55 INFO - Mirror: Off 16:05:55 INFO - Online: Yes 16:05:55 INFO - Rotation: Supported 16:05:55 INFO - Return code: 0 16:05:55 INFO - Running post-action listener: _resource_record_post_action 16:05:55 INFO - [mozharness: 2016-11-29 00:05:55.404355Z] Finished run-tests step (success) 16:05:55 INFO - Running post-run listener: _resource_record_post_run 16:05:55 INFO - Total resource usage - Wall time: 1212s; CPU: 55.0%; Read bytes: 34308608; Write bytes: 693758464; Read time: 320; Write time: 1875 16:05:55 INFO - TinderboxPrint: CPU usage
54.6% 16:05:55 INFO - TinderboxPrint: I/O read bytes / time
34,308,608 / 320 16:05:55 INFO - TinderboxPrint: I/O write bytes / time
693,758,464 / 1,875 16:05:55 INFO - TinderboxPrint: CPU idle
2,201.9 (45.4%) 16:05:55 INFO - TinderboxPrint: CPU system
984.6 (20.3%) 16:05:55 INFO - TinderboxPrint: CPU user
1,663.2 (34.3%) 16:05:55 INFO - TinderboxPrint: Swap in / out
413,818,880 / 0 16:05:55 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 378714624; Write bytes: 372016640; Read time: 28715; Write time: 654 16:05:55 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 32236032; Write bytes: 307287040; Read time: 298; Write time: 1181 16:05:55 INFO - Running post-run listener: _upload_blobber_files 16:05:55 INFO - Blob upload gear active. 16:05:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:05:55 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 16:05:55 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', 'Cedar', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:05:55 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 Cedar -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:06:03 INFO - (blobuploader) - INFO - Open directory for files ... 16:06:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:06:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:06:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:06:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:06:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:06:04 INFO - (blobuploader) - INFO - Done attempting. 16:06:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:06:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:06:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:06:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:06:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:06:05 INFO - (blobuploader) - INFO - Done attempting. 16:06:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:06:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:06:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:06:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:06:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:06:10 INFO - (blobuploader) - INFO - Done attempting. 16:06:10 INFO - (blobuploader) - INFO - Iteration through files over. 16:06:10 INFO - Return code: 0 16:06:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:06:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:06:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/0495060326d21cafa5a149b04b8f92680afbea66385ae397353a7a060cd8ef8196e2a280eb5ae34f68dfc4f0632ad363f0e2d840dc00fcc11d3a0b469e14987c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/84b55836510cd5f3c588a8f5ff83933ad284dcaf4f53de40a2426481173d11494074655cfddef4fb821aef03abce2324cfbcb425da8c588006925fd981e4e1c4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/9c7200c2dcc22f9a5927be20ef9568fd18fff2a6fc91c0e381f596d4e15286f99f6a45a0067eac8d2153dceb0fc59ae19ae585ad759a1092f7af31eaec377288"} 16:06:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:06:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:06:10 INFO - Contents: 16:06:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/0495060326d21cafa5a149b04b8f92680afbea66385ae397353a7a060cd8ef8196e2a280eb5ae34f68dfc4f0632ad363f0e2d840dc00fcc11d3a0b469e14987c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/84b55836510cd5f3c588a8f5ff83933ad284dcaf4f53de40a2426481173d11494074655cfddef4fb821aef03abce2324cfbcb425da8c588006925fd981e4e1c4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/9c7200c2dcc22f9a5927be20ef9568fd18fff2a6fc91c0e381f596d4e15286f99f6a45a0067eac8d2153dceb0fc59ae19ae585ad759a1092f7af31eaec377288"} 16:06:10 INFO - Running post-run listener: copy_logs_to_upload_dir 16:06:10 INFO - Copying logs to upload dir... 16:06:10 INFO - mkdir: /builds/slave/test/build/upload/logs 16:06:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.473129 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2016-11-28 16:06:10.863107) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-28 16:06:10.867089) ========= 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.FXdP3k35ks/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.FwWj6uiqaM/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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/Cedar/sha512/0495060326d21cafa5a149b04b8f92680afbea66385ae397353a7a060cd8ef8196e2a280eb5ae34f68dfc4f0632ad363f0e2d840dc00fcc11d3a0b469e14987c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/84b55836510cd5f3c588a8f5ff83933ad284dcaf4f53de40a2426481173d11494074655cfddef4fb821aef03abce2324cfbcb425da8c588006925fd981e4e1c4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/9c7200c2dcc22f9a5927be20ef9568fd18fff2a6fc91c0e381f596d4e15286f99f6a45a0067eac8d2153dceb0fc59ae19ae585ad759a1092f7af31eaec377288"} build_url:https://queue.taskcluster.net/v1/task/XT7rWq4TQqKVo4TOgp0tng/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010971 build_url: 'https://queue.taskcluster.net/v1/task/XT7rWq4TQqKVo4TOgp0tng/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/0495060326d21cafa5a149b04b8f92680afbea66385ae397353a7a060cd8ef8196e2a280eb5ae34f68dfc4f0632ad363f0e2d840dc00fcc11d3a0b469e14987c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/84b55836510cd5f3c588a8f5ff83933ad284dcaf4f53de40a2426481173d11494074655cfddef4fb821aef03abce2324cfbcb425da8c588006925fd981e4e1c4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/9c7200c2dcc22f9a5927be20ef9568fd18fff2a6fc91c0e381f596d4e15286f99f6a45a0067eac8d2153dceb0fc59ae19ae585ad759a1092f7af31eaec377288"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-28 16:06:10.900760) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 16:06:10.901154) ========= 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.FXdP3k35ks/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.FwWj6uiqaM/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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.005221 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 16:06:10.950885) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-28 16:06:10.951342) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-28 16:06:10.951723) ========= ========= Total master_lag: 0.20 =========