builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0112 starttime: 1476159922.83 results: success (0) buildid: 20161010190946 builduid: 6b884abb428d45748c94413fac20b7c9 revision: ece56e142a1e6d6d94ba55d67ae6f24ba6185d3b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-10 21:25:22.834314) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-10 21:25:22.834697) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-10 21:25:22.834965) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-10 21:25:22.858650) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 21:25:22.858905) ========= 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.IjQhlu0EdE/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.YirgoVMGm9/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-10 21:25:22-- 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% 15.4M=0.001s 2016-10-10 21:25:22 (15.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.141204 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 21:25:23.022332) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 21:25:23.022639) ========= 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.IjQhlu0EdE/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.YirgoVMGm9/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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.032605 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 21:25:23.079033) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 21:25:23.079401) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ece56e142a1e6d6d94ba55d67ae6f24ba6185d3b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev ece56e142a1e6d6d94ba55d67ae6f24ba6185d3b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IjQhlu0EdE/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.YirgoVMGm9/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-10 21:25:23,140 truncating revision to first 12 chars 2016-10-10 21:25:23,140 Setting DEBUG logging. 2016-10-10 21:25:23,140 attempt 1/10 2016-10-10 21:25:23,140 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ece56e142a1e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-10 21:25:23,489 unpacking tar archive at: mozilla-central-ece56e142a1e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.609181 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 21:25:23.713302) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-10 21:25:23.714859) ========= script_repo_revision: ece56e142a1e6d6d94ba55d67ae6f24ba6185d3b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-10 21:25:23.715252) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-10 21:25:23.715510) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-10 21:25:23.735045) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-10-10 21:25:23.735326) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IjQhlu0EdE/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.YirgoVMGm9/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 21:25:23 INFO - MultiFileLogger online at 20161010 21:25:23 in /builds/slave/test 21:25:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 21:25:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:25:23 INFO - {'append_to_log': False, 21:25:23 INFO - 'base_work_dir': '/builds/slave/test', 21:25:23 INFO - 'blob_upload_branch': 'Firefox', 21:25:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:25:23 INFO - 'branch': 'Firefox', 21:25:23 INFO - 'buildbot_json_path': 'buildprops.json', 21:25:23 INFO - 'config_files': ('talos/mac_config.py',), 21:25:23 INFO - 'default_actions': ('clobber', 21:25:23 INFO - 'read-buildbot-config', 21:25:23 INFO - 'download-and-extract', 21:25:23 INFO - 'populate-webroot', 21:25:23 INFO - 'create-virtualenv', 21:25:23 INFO - 'install', 21:25:23 INFO - 'run-tests'), 21:25:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:25:23 INFO - 'download_minidump_stackwalk': True, 21:25:23 INFO - 'download_symbols': 'ondemand', 21:25:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:25:23 INFO - 'tooltool.py': '/tools/tooltool.py', 21:25:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:25:23 INFO - '/tools/misc-python/virtualenv.py')}, 21:25:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:25:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:25:23 INFO - 'installer_path': 'installer.exe', 21:25:23 INFO - 'log_level': 'info', 21:25:23 INFO - 'log_name': 'talos', 21:25:23 INFO - 'log_to_console': True, 21:25:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:25:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:25:23 INFO - 'opt_config_files': (), 21:25:23 INFO - 'pip_index': False, 21:25:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:25:23 INFO - 'cmd': ('bash', 21:25:23 INFO - '-c', 21:25:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:25:23 INFO - 'enabled': True, 21:25:23 INFO - 'halt_on_failure': False, 21:25:23 INFO - 'name': 'check_screen_resolution'},), 21:25:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:25:23 INFO - 'cmd': ('bash', 21:25:23 INFO - '-c', 21:25:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:25:23 INFO - 'enabled': True, 21:25:23 INFO - 'halt_on_failure': False, 21:25:23 INFO - 'name': 'check_screen_resolution'},), 21:25:23 INFO - 'run_cmd_checks_enabled': True, 21:25:23 INFO - 'sps_profile': False, 21:25:23 INFO - 'sps_profile_interval': 0, 21:25:23 INFO - 'suite': 'dromaeojs-e10s', 21:25:23 INFO - 'system_bits': '32', 21:25:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:25:23 INFO - 'title': 't-yosemite-r7-0112', 21:25:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:25:23 INFO - 'use_talos_json': True, 21:25:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:25:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:25:23 INFO - 'work_dir': 'build'} 21:25:23 INFO - [mozharness: 2016-10-11 04:25:23.940702Z] Running clobber step. 21:25:23 INFO - Running pre-action listener: _resource_record_pre_action 21:25:23 INFO - Running main action method: clobber 21:25:23 INFO - rmtree: /builds/slave/test/build 21:25:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:25:26 INFO - Running post-action listener: _resource_record_post_action 21:25:26 INFO - [mozharness: 2016-10-11 04:25:26.123247Z] Finished clobber step (success) 21:25:26 INFO - [mozharness: 2016-10-11 04:25:26.123396Z] Running read-buildbot-config step. 21:25:26 INFO - Running pre-action listener: _resource_record_pre_action 21:25:26 INFO - Running main action method: read_buildbot_config 21:25:26 INFO - Using buildbot properties: 21:25:26 INFO - { 21:25:26 INFO - "project": "", 21:25:26 INFO - "product": "firefox", 21:25:26 INFO - "script_repo_revision": "production", 21:25:26 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 21:25:26 INFO - "repository": "", 21:25:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 21:25:26 INFO - "buildid": "20161010190946", 21:25:26 INFO - "pgo_build": "False", 21:25:26 INFO - "basedir": "/builds/slave/test", 21:25:26 INFO - "buildnumber": 132, 21:25:26 INFO - "slavename": "t-yosemite-r7-0112", 21:25:26 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 21:25:26 INFO - "platform": "yosemite_r7", 21:25:26 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 21:25:26 INFO - "branch": "mozilla-central", 21:25:26 INFO - "repo_path": "mozilla-central", 21:25:26 INFO - "revision": "ece56e142a1e6d6d94ba55d67ae6f24ba6185d3b", 21:25:26 INFO - "stage_platform": "macosx64", 21:25:26 INFO - "builduid": "6b884abb428d45748c94413fac20b7c9", 21:25:26 INFO - "slavebuilddir": "test" 21:25:26 INFO - } 21:25:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/IEg2HFWiTwuWpe4SYJAoLA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 21:25:26 INFO - Running post-action listener: _resource_record_post_action 21:25:26 INFO - [mozharness: 2016-10-11 04:25:26.126995Z] Finished read-buildbot-config step (success) 21:25:26 INFO - [mozharness: 2016-10-11 04:25:26.127154Z] Running download-and-extract step. 21:25:26 INFO - Running pre-action listener: _resource_record_pre_action 21:25:26 INFO - Running main action method: download_and_extract 21:25:26 INFO - mkdir: /builds/slave/test/build/tests 21:25: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')]} 21:25:26 INFO - trying https://queue.taskcluster.net/v1/task/IEg2HFWiTwuWpe4SYJAoLA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 21:25:26 INFO - Downloading https://queue.taskcluster.net/v1/task/IEg2HFWiTwuWpe4SYJAoLA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 21:25:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IEg2HFWiTwuWpe4SYJAoLA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 21:25:27 INFO - Downloaded 1199 bytes. 21:25:27 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 21:25:27 INFO - Using the following test package requirements: 21:25:27 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 21:25:27 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:25:27 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 21:25:27 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:25:27 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 21:25:27 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:25:27 INFO - u'jsshell-mac.zip'], 21:25:27 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:25:27 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 21:25:27 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 21:25:27 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:25:27 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 21:25:27 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:25:27 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 21:25:27 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:25:27 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 21:25:27 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 21:25:27 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 21:25:27 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:25:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IEg2HFWiTwuWpe4SYJAoLA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 21:25:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IEg2HFWiTwuWpe4SYJAoLA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:25:27 INFO - Fetch https://queue.taskcluster.net/v1/task/IEg2HFWiTwuWpe4SYJAoLA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 21:25:27 INFO - Http code: 200 21:25:27 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 21:25:27 INFO - x-amz-cf-id: JR-jqgzKyIa5lA5RqGMBYkF7Db1AYvbW-3RtAxdUrAvf0I7IYqzrXw== 21:25:27 INFO - x-amz-version-id: v1Sry89zwOFJSoBTkXNb.Cb5h6npTJrZ 21:25:28 INFO - Expected file size: 16508392 21:25:28 INFO - Obtained file size: 16508392 21:25:35 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 21:25:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IEg2HFWiTwuWpe4SYJAoLA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 21:25:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IEg2HFWiTwuWpe4SYJAoLA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:25:35 INFO - Fetch https://queue.taskcluster.net/v1/task/IEg2HFWiTwuWpe4SYJAoLA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 21:25:35 INFO - Http code: 200 21:25:35 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 21:25:35 INFO - x-amz-cf-id: dsFVwT7sGufjhH9M-Xe6yri0BsexeIZDbcm_zr8QN1OcLnP9lKdPhA== 21:25:35 INFO - x-amz-version-id: v1Sry89zwOFJSoBTkXNb.Cb5h6npTJrZ 21:25:35 INFO - Expected file size: 16508392 21:25:35 INFO - Obtained file size: 16508392 21:25:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IEg2HFWiTwuWpe4SYJAoLA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 21:25:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IEg2HFWiTwuWpe4SYJAoLA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:25:42 INFO - Fetch https://queue.taskcluster.net/v1/task/IEg2HFWiTwuWpe4SYJAoLA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 21:25:43 INFO - Http code: 200 21:25:43 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 21:25:43 INFO - x-amz-cf-id: jGP3xkk0qzNd24VX_XYiiy9abHiLeqilVJVNhUhrMgaMiQd-PORdVA== 21:25:43 INFO - x-amz-version-id: CdSoqrnYny3k_apvNmMK73LjJrz0UpX0 21:25:43 INFO - Expected file size: 14409006 21:25:43 INFO - Obtained file size: 14409006 21:25:43 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')]} 21:25:43 INFO - trying https://queue.taskcluster.net/v1/task/IEg2HFWiTwuWpe4SYJAoLA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 21:25:43 INFO - Downloading https://queue.taskcluster.net/v1/task/IEg2HFWiTwuWpe4SYJAoLA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 21:25:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IEg2HFWiTwuWpe4SYJAoLA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 21:25:46 INFO - Downloaded 111703178 bytes. 21:25:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IEg2HFWiTwuWpe4SYJAoLA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 21:25:46 INFO - mkdir: /builds/slave/test/properties 21:25:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:25:46 INFO - Writing to file /builds/slave/test/properties/build_url 21:25:46 INFO - Contents: 21:25:46 INFO - build_url:https://queue.taskcluster.net/v1/task/IEg2HFWiTwuWpe4SYJAoLA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 21:25:47 INFO - Running post-action listener: _resource_record_post_action 21:25:47 INFO - Running post-action listener: set_extra_try_arguments 21:25:47 INFO - [mozharness: 2016-10-11 04:25:47.135691Z] Finished download-and-extract step (success) 21:25:47 INFO - [mozharness: 2016-10-11 04:25:47.135814Z] Running populate-webroot step. 21:25:47 INFO - Running pre-action listener: _resource_record_pre_action 21:25:47 INFO - Running main action method: populate_webroot 21:25:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:25:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:25:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:25:47 INFO - '--tppagecycles', 21:25:47 INFO - '7'], 21:25:47 INFO - 'tests': ['tsvgm']}}, 21:25:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:25:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:25:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:25:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:25:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:25:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:25:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:25:47 INFO - 'talos_options': ['--disable-e10s'], 21:25:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:25:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:25:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:25:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:25:47 INFO - 'talos_options': ['--disable-e10s'], 21:25:47 INFO - 'tests': ['damp', 'tps']}, 21:25:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:25:47 INFO - 'tests': ['damp', 'tps']}, 21:25:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:25:47 INFO - 'tests': ['dromaeo_dom']}, 21:25:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:25:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:25:47 INFO - 'tests': ['basic_compositor_video']}, 21:25:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:25:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:25:47 INFO - 'tests': ['a11yr', 21:25:47 INFO - 'ts_paint', 21:25:47 INFO - 'tpaint', 21:25:47 INFO - 'sessionrestore', 21:25:47 INFO - 'sessionrestore_no_auto_restore', 21:25:47 INFO - 'tabpaint']}, 21:25:47 INFO - 'other-e10s': {'tests': ['a11yr', 21:25:47 INFO - 'ts_paint', 21:25:47 INFO - 'tpaint', 21:25:47 INFO - 'sessionrestore', 21:25:47 INFO - 'sessionrestore_no_auto_restore', 21:25:47 INFO - 'tabpaint']}, 21:25:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:25:47 INFO - 'ts_paint', 21:25:47 INFO - 'tpaint', 21:25:47 INFO - 'sessionrestore', 21:25:47 INFO - 'sessionrestore_no_auto_restore', 21:25:47 INFO - 'tabpaint']}, 21:25:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:25:47 INFO - 'ts_paint', 21:25:47 INFO - 'tpaint', 21:25:47 INFO - 'sessionrestore', 21:25:47 INFO - 'sessionrestore_no_auto_restore', 21:25:47 INFO - 'tabpaint']}, 21:25:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:25:47 INFO - 'tests': ['a11yr', 21:25:47 INFO - 'ts_paint', 21:25:47 INFO - 'tpaint', 21:25:47 INFO - 'sessionrestore', 21:25:47 INFO - 'sessionrestore_no_auto_restore', 21:25:47 INFO - 'tabpaint']}, 21:25:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:25:47 INFO - 'tests': ['a11yr', 21:25:47 INFO - 'ts_paint', 21:25:47 INFO - 'tpaint', 21:25:47 INFO - 'sessionrestore', 21:25:47 INFO - 'sessionrestore_no_auto_restore', 21:25:47 INFO - 'tabpaint']}, 21:25:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:25:47 INFO - 'tests': ['tsvgx', 21:25:47 INFO - 'tsvgr_opacity', 21:25:47 INFO - 'tart', 21:25:47 INFO - 'tscrollx', 21:25:47 INFO - 'cart']}, 21:25:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:25:47 INFO - 'tsvgr_opacity', 21:25:47 INFO - 'tart', 21:25:47 INFO - 'tscrollx', 21:25:47 INFO - 'cart']}, 21:25:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:25:47 INFO - 'talos_options': ['--disable-e10s'], 21:25:47 INFO - 'tests': ['tp5o']}, 21:25:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:25:47 INFO - 'tests': ['tp5o']}, 21:25:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:25:47 INFO - 'talos_options': ['--disable-e10s', 21:25:47 INFO - '--xperf_path', 21:25:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:25:47 INFO - 'tests': ['tp5n']}, 21:25:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:25:47 INFO - 'talos_options': ['--xperf_path', 21:25:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:25:47 INFO - 'tests': ['tp5n']}}, 21:25:47 INFO - 'talos.zip': {'path': '', 21:25:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:25:47 INFO - Running post-action listener: _resource_record_post_action 21:25:47 INFO - [mozharness: 2016-10-11 04:25:47.154452Z] Finished populate-webroot step (success) 21:25:47 INFO - [mozharness: 2016-10-11 04:25:47.154557Z] Running create-virtualenv step. 21:25:47 INFO - Running pre-action listener: _resource_record_pre_action 21:25:47 INFO - Running main action method: create_virtualenv 21:25:47 INFO - Creating virtualenv /builds/slave/test/build/venv 21:25:47 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 21:25:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:25:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:25:47 INFO - Using real prefix '/tools/python27' 21:25:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:25:48 INFO - Installing distribute.............................................................................................................................................................................................done. 21:25:50 INFO - Installing pip.................done. 21:25:50 INFO - Return code: 0 21:25:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:25:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:25:51 INFO - Reading from file tmpfile_stdout 21:25:51 INFO - Output received: 21:25:51 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) 21:25:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:25:51 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')]} 21:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:25:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:25:51 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')]} 21:25:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:25:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:25:51 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 0x105d24490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9389d95470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b38d30>, '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 0x10595edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b30b20>, '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.IjQhlu0EdE/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.YirgoVMGm9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 21:25:51 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 21:25:51 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 21:25:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IjQhlu0EdE/Render', 21:25:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:25:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:25:51 INFO - 'HOME': '/Users/cltbld', 21:25:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:25:51 INFO - 'LOGNAME': 'cltbld', 21:25:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:25:51 INFO - 'MOZ_NO_REMOTE': '1', 21:25:51 INFO - 'NO_EM_RESTART': '1', 21:25:51 INFO - 'PAGER': '/bin/cat', 21:25:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:25:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:25:51 INFO - 'PWD': '/builds/slave/test', 21:25:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:25:51 INFO - 'SHELL': '/bin/bash', 21:25:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YirgoVMGm9/Listeners', 21:25:51 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 21:25:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:25:51 INFO - 'USER': 'cltbld', 21:25:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:25:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:25:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:25:51 INFO - 'XPC_FLAGS': '0x0', 21:25:51 INFO - 'XPC_SERVICE_NAME': '0', 21:25:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:25:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:25:51 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 21:25:51 INFO - Cleaning up... 21:25:51 INFO - Return code: 0 21:25:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:25:51 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')]} 21:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:25:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:25:51 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')]} 21:25:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:25:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:25:51 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 0x105d24490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9389d95470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b38d30>, '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 0x10595edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b30b20>, '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.IjQhlu0EdE/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.YirgoVMGm9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 21:25:51 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 21:25:51 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 21:25:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IjQhlu0EdE/Render', 21:25:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:25:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:25:51 INFO - 'HOME': '/Users/cltbld', 21:25:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:25:51 INFO - 'LOGNAME': 'cltbld', 21:25:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:25:51 INFO - 'MOZ_NO_REMOTE': '1', 21:25:51 INFO - 'NO_EM_RESTART': '1', 21:25:51 INFO - 'PAGER': '/bin/cat', 21:25:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:25:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:25:51 INFO - 'PWD': '/builds/slave/test', 21:25:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:25:51 INFO - 'SHELL': '/bin/bash', 21:25:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YirgoVMGm9/Listeners', 21:25:51 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 21:25:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:25:51 INFO - 'USER': 'cltbld', 21:25:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:25:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:25:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:25:51 INFO - 'XPC_FLAGS': '0x0', 21:25:51 INFO - 'XPC_SERVICE_NAME': '0', 21:25:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:25:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:25:51 INFO - Downloading/unpacking psutil>=3.1.1 21:25:51 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 21:25:51 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 21:25:51 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 21:25:51 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 21:25:51 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 21:25:51 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 21:25:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:25:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:25:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:25:55 INFO - Installing collected packages: psutil 21:25:55 INFO - Running setup.py install for psutil 21:25:55 INFO - building 'psutil._psutil_osx' extension 21:25:55 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 21:25:55 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 21:25:55 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 21:25:55 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 21:25:55 INFO - building 'psutil._psutil_posix' extension 21:25:55 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 21:25:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:25:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:25:56 INFO - ^ 21:25:56 INFO - 1 warning generated. 21:25:56 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 21:25:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:25:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:25:56 INFO - Successfully installed psutil 21:25:56 INFO - Cleaning up... 21:25:56 INFO - Return code: 0 21:25:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:25:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:25:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:25:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:25:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:25:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:25:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105d24490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9389d95470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b38d30>, '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 0x10595edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b30b20>, '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.IjQhlu0EdE/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.YirgoVMGm9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 21:25:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:25:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:25:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IjQhlu0EdE/Render', 21:25:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:25:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:25:56 INFO - 'HOME': '/Users/cltbld', 21:25:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:25:56 INFO - 'LOGNAME': 'cltbld', 21:25:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:25:56 INFO - 'MOZ_NO_REMOTE': '1', 21:25:56 INFO - 'NO_EM_RESTART': '1', 21:25:56 INFO - 'PAGER': '/bin/cat', 21:25:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:25:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:25:56 INFO - 'PWD': '/builds/slave/test', 21:25:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:25:56 INFO - 'SHELL': '/bin/bash', 21:25:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YirgoVMGm9/Listeners', 21:25:56 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 21:25:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:25:56 INFO - 'USER': 'cltbld', 21:25:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:25:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:25:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:25:56 INFO - 'XPC_FLAGS': '0x0', 21:25:56 INFO - 'XPC_SERVICE_NAME': '0', 21:25:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:25:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:25:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:25:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:25:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:25:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:25:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:25:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:25:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:25:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:25:59 INFO - Installing collected packages: mozsystemmonitor 21:25:59 INFO - Running setup.py install for mozsystemmonitor 21:25:59 INFO - Successfully installed mozsystemmonitor 21:25:59 INFO - Cleaning up... 21:25:59 INFO - Return code: 0 21:25:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:25:59 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')]} 21:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:25:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:25:59 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')]} 21:25:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:25:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:25:59 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 0x105d24490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9389d95470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b38d30>, '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 0x10595edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b30b20>, '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.IjQhlu0EdE/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.YirgoVMGm9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 21:25:59 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 21:25:59 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 21:25:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IjQhlu0EdE/Render', 21:25:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:25:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:25:59 INFO - 'HOME': '/Users/cltbld', 21:25:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:25:59 INFO - 'LOGNAME': 'cltbld', 21:25:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:25:59 INFO - 'MOZ_NO_REMOTE': '1', 21:25:59 INFO - 'NO_EM_RESTART': '1', 21:25:59 INFO - 'PAGER': '/bin/cat', 21:25:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:25:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:25:59 INFO - 'PWD': '/builds/slave/test', 21:25:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:25:59 INFO - 'SHELL': '/bin/bash', 21:25:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YirgoVMGm9/Listeners', 21:25:59 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 21:25:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:25:59 INFO - 'USER': 'cltbld', 21:25:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:25:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:25:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:25:59 INFO - 'XPC_FLAGS': '0x0', 21:25:59 INFO - 'XPC_SERVICE_NAME': '0', 21:25:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:25:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:25:59 INFO - Downloading/unpacking jsonschema==2.5.1 21:25:59 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 21:25:59 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 21:25:59 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 21:25:59 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 21:25:59 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 21:25:59 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 21:26:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:26:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:26:02 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 21:26:02 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 21:26:02 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 21:26:02 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 21:26:02 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 21:26:02 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 21:26:02 INFO - Downloading functools32-3.2.3-2.tar.gz 21:26:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:26:02 INFO - warning: no files found matching '*.txt' 21:26:02 INFO - no previously-included directories found matching 'build' 21:26:02 INFO - no previously-included directories found matching 'dist' 21:26:02 INFO - no previously-included directories found matching '.git*' 21:26:02 INFO - Installing collected packages: jsonschema, functools32 21:26:02 INFO - Running setup.py install for functools32 21:26:02 INFO - warning: no files found matching '*.txt' 21:26:02 INFO - no previously-included directories found matching 'build' 21:26:02 INFO - no previously-included directories found matching 'dist' 21:26:02 INFO - no previously-included directories found matching '.git*' 21:26:02 INFO - Successfully installed jsonschema functools32 21:26:02 INFO - Cleaning up... 21:26:02 INFO - Return code: 0 21:26:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:26:02 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')]} 21:26:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:02 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')]} 21:26:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:02 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 0x105d24490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9389d95470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b38d30>, '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 0x10595edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b30b20>, '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.IjQhlu0EdE/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.YirgoVMGm9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 21:26:02 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 21:26:02 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 21:26:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IjQhlu0EdE/Render', 21:26:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:26:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:26:02 INFO - 'HOME': '/Users/cltbld', 21:26:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:26:02 INFO - 'LOGNAME': 'cltbld', 21:26:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:02 INFO - 'MOZ_NO_REMOTE': '1', 21:26:02 INFO - 'NO_EM_RESTART': '1', 21:26:02 INFO - 'PAGER': '/bin/cat', 21:26:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:26:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:26:02 INFO - 'PWD': '/builds/slave/test', 21:26:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:26:02 INFO - 'SHELL': '/bin/bash', 21:26:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YirgoVMGm9/Listeners', 21:26:02 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 21:26:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:26:02 INFO - 'USER': 'cltbld', 21:26:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:26:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:26:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:26:02 INFO - 'XPC_FLAGS': '0x0', 21:26:02 INFO - 'XPC_SERVICE_NAME': '0', 21:26:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:26:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:26:03 INFO - Cleaning up... 21:26:03 INFO - Return code: 0 21:26:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:26:03 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')]} 21:26:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:03 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')]} 21:26:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:03 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 0x105d24490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9389d95470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b38d30>, '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 0x10595edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b30b20>, '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.IjQhlu0EdE/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.YirgoVMGm9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 21:26:03 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 21:26:03 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 21:26:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IjQhlu0EdE/Render', 21:26:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:26:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:26:03 INFO - 'HOME': '/Users/cltbld', 21:26:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:26:03 INFO - 'LOGNAME': 'cltbld', 21:26:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:03 INFO - 'MOZ_NO_REMOTE': '1', 21:26:03 INFO - 'NO_EM_RESTART': '1', 21:26:03 INFO - 'PAGER': '/bin/cat', 21:26:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:26:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:26:03 INFO - 'PWD': '/builds/slave/test', 21:26:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:26:03 INFO - 'SHELL': '/bin/bash', 21:26:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YirgoVMGm9/Listeners', 21:26:03 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 21:26:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:26:03 INFO - 'USER': 'cltbld', 21:26:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:26:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:26:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:26:03 INFO - 'XPC_FLAGS': '0x0', 21:26:03 INFO - 'XPC_SERVICE_NAME': '0', 21:26:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:26:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:03 INFO - Downloading/unpacking blobuploader==1.2.4 21:26:03 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 21:26:03 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 21:26:03 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 21:26:03 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 21:26:03 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 21:26:03 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 21:26:05 INFO - Downloading blobuploader-1.2.4.tar.gz 21:26:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:26:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:26:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:26:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:26:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:26:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:26:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:26:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:26:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:26:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:26:06 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 21:26:06 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 21:26:06 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 21:26:06 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 21:26:06 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 21:26:06 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 21:26:07 INFO - Downloading docopt-0.6.1.tar.gz 21:26:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:26:07 INFO - Installing collected packages: blobuploader, requests, docopt 21:26:07 INFO - Running setup.py install for blobuploader 21:26:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:26:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:26:07 INFO - Running setup.py install for requests 21:26:07 INFO - Running setup.py install for docopt 21:26:07 INFO - Successfully installed blobuploader requests docopt 21:26:07 INFO - Cleaning up... 21:26:08 INFO - Return code: 0 21:26:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:08 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 0x105d24490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9389d95470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b38d30>, '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 0x10595edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b30b20>, '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.IjQhlu0EdE/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.YirgoVMGm9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 21:26:08 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 21:26:08 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 21:26:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IjQhlu0EdE/Render', 21:26:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:26:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:26:08 INFO - 'HOME': '/Users/cltbld', 21:26:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:26:08 INFO - 'LOGNAME': 'cltbld', 21:26:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:08 INFO - 'MOZ_NO_REMOTE': '1', 21:26:08 INFO - 'NO_EM_RESTART': '1', 21:26:08 INFO - 'PAGER': '/bin/cat', 21:26:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:26:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:26:08 INFO - 'PWD': '/builds/slave/test', 21:26:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:26:08 INFO - 'SHELL': '/bin/bash', 21:26:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YirgoVMGm9/Listeners', 21:26:08 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 21:26:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:26:08 INFO - 'USER': 'cltbld', 21:26:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:26:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:26:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:26:08 INFO - 'XPC_FLAGS': '0x0', 21:26:08 INFO - 'XPC_SERVICE_NAME': '0', 21:26:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:26:08 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-t2HYyB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:26:08 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-m5nBAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:26:08 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-kA2dcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:26:08 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-nks6P4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:26:08 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-ivup1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:26:08 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-1AxSok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:26:08 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-GrXnKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:26:09 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-tErwAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:26:09 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-U67UXw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:26:09 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-ZJioBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:26:09 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-oGWTNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:26:09 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-vnpgd2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:26:09 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-igc2Ih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:26:09 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-blyoeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:26:09 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-zChUIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:26:09 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-0zqxRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:26:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:26:10 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-PzISqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:26:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:26:10 INFO - Running setup.py install for manifestparser 21:26:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:26:10 INFO - Running setup.py install for mozcrash 21:26:10 INFO - Running setup.py install for mozdebug 21:26:10 INFO - Running setup.py install for mozdevice 21:26:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:26:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:26:10 INFO - Running setup.py install for mozfile 21:26:10 INFO - Running setup.py install for mozhttpd 21:26:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:26:10 INFO - Running setup.py install for mozinfo 21:26:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:26:11 INFO - Running setup.py install for mozInstall 21:26:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:26:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:26:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:26:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:26:11 INFO - Running setup.py install for mozleak 21:26:11 INFO - Running setup.py install for mozlog 21:26:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:26:11 INFO - Running setup.py install for moznetwork 21:26:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:26:11 INFO - Running setup.py install for mozprocess 21:26:11 INFO - Running setup.py install for mozprofile 21:26:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:26:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:26:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:26:12 INFO - Running setup.py install for mozrunner 21:26:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:26:12 INFO - Running setup.py install for mozscreenshot 21:26:12 INFO - Running setup.py install for moztest 21:26:12 INFO - Running setup.py install for mozversion 21:26:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:26:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:26:12 INFO - Cleaning up... 21:26:12 INFO - Return code: 0 21:26:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:26:12 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')]} 21:26:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:12 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')]} 21:26:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:12 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 0x105d24490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9389d95470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b38d30>, '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 0x10595edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b30b20>, '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.IjQhlu0EdE/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.YirgoVMGm9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 21:26:12 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 21:26:12 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 21:26:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IjQhlu0EdE/Render', 21:26:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:26:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:26:12 INFO - 'HOME': '/Users/cltbld', 21:26:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:26:12 INFO - 'LOGNAME': 'cltbld', 21:26:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:12 INFO - 'MOZ_NO_REMOTE': '1', 21:26:12 INFO - 'NO_EM_RESTART': '1', 21:26:12 INFO - 'PAGER': '/bin/cat', 21:26:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:26:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:26:12 INFO - 'PWD': '/builds/slave/test', 21:26:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:26:12 INFO - 'SHELL': '/bin/bash', 21:26:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YirgoVMGm9/Listeners', 21:26:12 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 21:26:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:26:12 INFO - 'USER': 'cltbld', 21:26:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:26:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:26:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:26:12 INFO - 'XPC_FLAGS': '0x0', 21:26:12 INFO - 'XPC_SERVICE_NAME': '0', 21:26:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:26:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:26:12 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-yHg7k_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:26:13 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)) 21:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:26:13 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-p7df8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:26:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:26:13 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-FeUmz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:26:13 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)) 21:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:26:13 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-KzOIoc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:26:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:26:13 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-a3pmbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:26:13 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)) 21:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:26:13 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-hUhRTu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:26:13 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)) 21:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:26:13 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-FuEgYH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:26:13 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)) 21:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:26:13 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-_b5hg7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:26:13 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)) 21:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:26:13 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-xoeaic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:26:13 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)) 21:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:26:13 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-7lMgEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:26:14 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)) 21:26:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:26:14 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-qAtYmC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:26:14 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)) 21:26:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:26:14 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-xWrd26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:26:14 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)) 21:26:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:26:14 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-NmrQ8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:26:14 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)) 21:26:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:26:14 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-osV6S4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:26:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 21:26:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:26:14 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-nnVu0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:26:14 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)) 21:26:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:26:14 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-Ei2c1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:26:14 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)) 21:26:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:26:14 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-heIrNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:26:14 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)) 21:26:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:26:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:26:14 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)) 21:26:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:26:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:26:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:26: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 21:26: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 21:26: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 21:26: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 21:26: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 21:26: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 21:26:17 INFO - Downloading blessings-1.6.tar.gz 21:26:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:26:17 INFO - Installing collected packages: blessings 21:26:17 INFO - Running setup.py install for blessings 21:26:17 INFO - Successfully installed blessings 21:26:17 INFO - Cleaning up... 21:26:17 INFO - Return code: 0 21:26:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:26:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:26:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:26:17 INFO - Reading from file tmpfile_stdout 21:26:17 INFO - Current package versions: 21:26:17 INFO - blessings == 1.6 21:26:17 INFO - blobuploader == 1.2.4 21:26:17 INFO - docopt == 0.6.1 21:26:17 INFO - functools32 == 3.2.3-2 21:26:17 INFO - jsonschema == 2.5.1 21:26:17 INFO - manifestparser == 1.1 21:26:17 INFO - mozInstall == 1.12 21:26:17 INFO - mozcrash == 0.17 21:26:17 INFO - mozdebug == 0.1 21:26:17 INFO - mozdevice == 0.48 21:26:17 INFO - mozfile == 1.2 21:26:17 INFO - mozhttpd == 0.7 21:26:17 INFO - mozinfo == 0.9 21:26:17 INFO - mozleak == 0.1 21:26:17 INFO - mozlog == 3.4 21:26:17 INFO - moznetwork == 0.27 21:26:17 INFO - mozprocess == 0.23 21:26:17 INFO - mozprofile == 0.28 21:26:17 INFO - mozrunner == 6.12 21:26:17 INFO - mozscreenshot == 0.1 21:26:17 INFO - mozsystemmonitor == 0.3 21:26:17 INFO - moztest == 0.7 21:26:17 INFO - mozversion == 1.4 21:26:17 INFO - psutil == 3.1.1 21:26:17 INFO - requests == 1.2.3 21:26:17 INFO - wsgiref == 0.1.2 21:26:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:26:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:17 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 0x105d24490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9389d95470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b38d30>, '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 0x10595edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b30b20>, '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.IjQhlu0EdE/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.YirgoVMGm9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 21:26:17 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 21:26:17 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 21:26:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IjQhlu0EdE/Render', 21:26:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:26:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:26:17 INFO - 'HOME': '/Users/cltbld', 21:26:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:26:17 INFO - 'LOGNAME': 'cltbld', 21:26:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:17 INFO - 'MOZ_NO_REMOTE': '1', 21:26:17 INFO - 'NO_EM_RESTART': '1', 21:26:17 INFO - 'PAGER': '/bin/cat', 21:26:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:26:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:26:17 INFO - 'PWD': '/builds/slave/test', 21:26:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:26:17 INFO - 'SHELL': '/bin/bash', 21:26:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YirgoVMGm9/Listeners', 21:26:17 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 21:26:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:26:17 INFO - 'USER': 'cltbld', 21:26:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:26:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:26:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:26:17 INFO - 'XPC_FLAGS': '0x0', 21:26:17 INFO - 'XPC_SERVICE_NAME': '0', 21:26:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:26:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:18 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)) 21:26:18 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)) 21:26:18 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)) 21:26:18 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)) 21:26:18 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)) 21:26:18 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)) 21:26:18 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)) 21:26:18 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)) 21:26:18 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)) 21:26:18 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)) 21:26:18 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)) 21:26:18 INFO - Cleaning up... 21:26:18 INFO - Return code: 0 21:26:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:26:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:18 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 0x105d24490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9389d95470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b38d30>, '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 0x10595edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b30b20>, '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.IjQhlu0EdE/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.YirgoVMGm9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 21:26:18 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 21:26:18 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 21:26:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IjQhlu0EdE/Render', 21:26:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:26:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:26:18 INFO - 'HOME': '/Users/cltbld', 21:26:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:26:18 INFO - 'LOGNAME': 'cltbld', 21:26:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:18 INFO - 'MOZ_NO_REMOTE': '1', 21:26:18 INFO - 'NO_EM_RESTART': '1', 21:26:18 INFO - 'PAGER': '/bin/cat', 21:26:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:26:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:26:18 INFO - 'PWD': '/builds/slave/test', 21:26:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:26:18 INFO - 'SHELL': '/bin/bash', 21:26:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YirgoVMGm9/Listeners', 21:26:18 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 21:26:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:26:18 INFO - 'USER': 'cltbld', 21:26:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:26:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:26:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:26:18 INFO - 'XPC_FLAGS': '0x0', 21:26:18 INFO - 'XPC_SERVICE_NAME': '0', 21:26:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:26:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:26:19 INFO - Cleaning up... 21:26:19 INFO - Return code: 0 21:26:19 INFO - Running post-action listener: _resource_record_post_action 21:26:19 INFO - Running post-action listener: _start_resource_monitoring 21:26:19 INFO - Starting resource monitoring. 21:26:19 INFO - [mozharness: 2016-10-11 04:26:19.223319Z] Finished create-virtualenv step (success) 21:26:19 INFO - [mozharness: 2016-10-11 04:26:19.224291Z] Running install step. 21:26:19 INFO - Running pre-action listener: _resource_record_pre_action 21:26:19 INFO - Running main action method: install 21:26:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:26:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:26:19 INFO - Reading from file tmpfile_stdout 21:26:19 INFO - Detecting whether we're running mozinstall >=1.0... 21:26:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:26:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:26:19 INFO - Reading from file tmpfile_stdout 21:26:19 INFO - Output received: 21:26:19 INFO - Usage: mozinstall [options] installer 21:26:19 INFO - Options: 21:26:19 INFO - -h, --help show this help message and exit 21:26:19 INFO - -d DEST, --destination=DEST 21:26:19 INFO - Directory to install application into. [default: 21:26:19 INFO - "/builds/slave/test"] 21:26:19 INFO - --app=APP Application being installed. [default: firefox] 21:26:19 INFO - mkdir: /builds/slave/test/build/application 21:26:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 21:26:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 21:26:54 INFO - Reading from file tmpfile_stdout 21:26:54 INFO - Output received: 21:26:54 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:26:54 INFO - Running post-action listener: _resource_record_post_action 21:26:54 INFO - [mozharness: 2016-10-11 04:26:54.176938Z] Finished install step (success) 21:26:54 INFO - [mozharness: 2016-10-11 04:26:54.177120Z] Running run-tests step. 21:26:54 INFO - Running pre-action listener: _resource_record_pre_action 21:26:54 INFO - Running main action method: run_tests 21:26:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:26:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:26:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:26:54 INFO - 2016-10-10 21:26:54.191 screenresolution[1643:7535] starting screenresolution argv=screenresolution get 21:26:54 INFO - 2016-10-10 21:26:54.194 screenresolution[1643:7535] Display 0: 1600x1200x32@60 21:26:54 INFO - 2016-10-10 21:26:54.200 screenresolution[1644:7537] starting screenresolution argv=screenresolution list 21:26:54 INFO - Available Modes on Display 0 21:26:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:26:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:26:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:26:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:26:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:26:54 INFO - Intel Iris: 21:26:54 INFO - Chipset Model: Intel Iris 21:26:54 INFO - Type: GPU 21:26:54 INFO - Bus: Built-In 21:26:54 INFO - VRAM (Dynamic, Max): 1536 MB 21:26:54 INFO - Vendor: Intel (0x8086) 21:26:54 INFO - Device ID: 0x0a2e 21:26:54 INFO - Revision ID: 0x0009 21:26:54 INFO - Displays: 21:26:54 INFO - Display: 21:26:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:26:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:26:54 INFO - Display Serial Number: 1600x1200 60 21:26:54 INFO - Main Display: Yes 21:26:54 INFO - Mirror: Off 21:26:54 INFO - Online: Yes 21:26:54 INFO - Rotation: Supported 21:26:54 INFO - Return code: 0 21:26:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:26:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:26:54 INFO - Python 2.7.3 21:26:54 INFO - Return code: 0 21:26:54 INFO - grabbing minidump binary from tooltool 21:26:54 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')]} 21:26:54 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 0x105b38d30>, '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 0x10595edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b30b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:26:54 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 21:26:54 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 21:26:54 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 21:26:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:26:54 INFO - Return code: 0 21:26:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:26:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:26:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:26:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:26:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:26:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IEg2HFWiTwuWpe4SYJAoLA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0112', '--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 21:26:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IEg2HFWiTwuWpe4SYJAoLA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0112 --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 21:26:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IjQhlu0EdE/Render', 21:26:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:26:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:26:54 INFO - 'HOME': '/Users/cltbld', 21:26:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:26:54 INFO - 'LOGNAME': 'cltbld', 21:26:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:26:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:26:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:54 INFO - 'MOZ_NO_REMOTE': '1', 21:26:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:26:54 INFO - 'NO_EM_RESTART': '1', 21:26:54 INFO - 'PAGER': '/bin/cat', 21:26:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:26:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:26:54 INFO - 'PWD': '/builds/slave/test', 21:26:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:26:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:26:54 INFO - 'SHELL': '/bin/bash', 21:26:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YirgoVMGm9/Listeners', 21:26:54 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 21:26:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:26:54 INFO - 'USER': 'cltbld', 21:26:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:26:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:26:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:26:54 INFO - 'XPC_FLAGS': '0x0', 21:26:54 INFO - 'XPC_SERVICE_NAME': '0', 21:26:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:26:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IEg2HFWiTwuWpe4SYJAoLA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0112', '--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 21:26:54 INFO - mozversion application_buildid: 20161010190946 21:26:54 INFO - mozversion application_changeset: ece56e142a1e6d6d94ba55d67ae6f24ba6185d3b 21:26:54 INFO - mozversion application_display_name: Nightly 21:26:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:26:54 INFO - mozversion application_name: Firefox 21:26:54 INFO - mozversion application_remotingname: firefox 21:26:54 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:26:54 INFO - mozversion application_vendor: Mozilla 21:26:54 INFO - mozversion application_version: 52.0a1 21:26:54 INFO - mozversion platform_buildid: 20161010190946 21:26:54 INFO - mozversion platform_changeset: ece56e142a1e6d6d94ba55d67ae6f24ba6185d3b 21:26:54 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:26:54 INFO - mozversion platform_version: 52.0a1 21:26:54 INFO - using testdate: 1476160014 21:26:54 INFO - actual date: 1476160014 21:26:54 INFO - starting webserver on 'localhost:49272' 21:26:54 INFO - SUITE-START | Running 2 tests 21:26:54 INFO - TEST-START | dromaeo_css 21:26:54 INFO - operating with platform_type : mac_ 21:26:54 INFO - Initialising browser for dromaeo_css test... 21:26:54 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpqOpCSV/profile http://localhost:49272/getInfo.html) 21:26:57 INFO - PROCESS | 1654 | 2016-10-10 21:26:57.651 plugin-container[1656:7698] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c43, name = 'com.apple.tsm.portname' 21:26:57 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:26:57 INFO - PROCESS | 1654 | 2016-10-10 21:26:57.655 plugin-container[1656:7698] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x911f, name = 'com.apple.CFPasteboardClient' 21:26:57 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:26:57 INFO - PROCESS | 1654 | 2016-10-10 21:26:57.655 plugin-container[1656:7698] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:26:57 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 21:26:57 INFO - PROCESS | 1654 | colorDepth:24 21:26:57 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 21:26:57 INFO - PROCESS | 1654 | __metrics 21:26:57 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 21:26:58 INFO - TEST-INFO | 1654: exit 0 21:26:58 INFO - Browser initialized. 21:26:58 INFO - Running cycle 1/1 for dromaeo_css test... 21:26:58 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpqOpCSV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:27:00 INFO - PROCESS | 1657 | 2016-10-10 21:27:00.960 plugin-container[1661:7871] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0b, name = 'com.apple.tsm.portname' 21:27:00 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:27:00 INFO - PROCESS | 1657 | 2016-10-10 21:27:00.962 plugin-container[1661:7871] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e1b, name = 'com.apple.CFPasteboardClient' 21:27:00 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:27:00 INFO - PROCESS | 1657 | 2016-10-10 21:27:00.962 plugin-container[1661:7871] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:27:10 INFO - PROCESS | 1657 | RSS: Main: 239157248 21:27:10 INFO - PROCESS | 1657 | 21:28:00 INFO - PROCESS | 1657 | 1476160080324 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] 21:30:13 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 21:30:14 INFO - PROCESS | 1657 | RSS: Main: 252878848 21:30:14 INFO - PROCESS | 1657 | 21:33:16 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 21:33:17 INFO - PROCESS | 1657 | RSS: Main: 264794112 21:33:17 INFO - PROCESS | 1657 | 21:36:19 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 21:36:20 INFO - PROCESS | 1657 | RSS: Main: 269443072 21:36:20 INFO - PROCESS | 1657 | 21:39:21 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 21:39:22 INFO - PROCESS | 1657 | RSS: Main: 269561856 21:39:22 INFO - PROCESS | 1657 | 21:42:25 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 21:42:26 INFO - PROCESS | 1657 | RSS: Main: 274927616 21:42:26 INFO - PROCESS | 1657 | 21:45:28 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 21:45:28 INFO - PROCESS | 1657 | RSS: Main: 277688320 21:45:28 INFO - PROCESS | 1657 | 21:45:28 INFO - PROCESS | 1657 | __start_tp_report 21:45:28 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 21:45:28 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 21:45:28 INFO - PROCESS | 1657 | |i|pagename|runs| 21:45:28 INFO - PROCESS | 1657 | |0;dojo.html;3382.4080785862466;3408.8977330680086;3430.8456119474613;3432.2105915639404;3461.13471632092;5642.661440313584;5654.0670789319765;5664.575156863236;5724.4561766632205;5724.742386592603;5626.521745651625;5695.886082278352;5709.086546152616;5754.884902301962;5875.500582450497;5184.2223666450145;5220.086484865153;5224.817131400402;5286.127788914824;5313.920291195635;5201.063808514466;5307.840764777063;5319.175527793198;5338.332708411449;5375.865603359908;5138.916650010002;5183.688978661293;5261.000409922102;5265.447128051576;5278.340207474066;3798.3922572388287;4178.540360560336;4343.543927887558;4993.976234871842;5295.947040529622;4332.870013899588;5189.143791274435;5205.375354957391;5243.187305967581;5251.658642188284;5560.582956278296;5591.189277554732;5603.299587551556;5656.66060036395;5733.684647344395;4778.593819525309;5755.510781583571;5769;6219.222597175353;6239.844003899893;5967.970160149215;6232.439080482778;6234.669357699193;6261;6295.527835412317;5148.897022059537;5260.500252476008;5847.619904706181;5849.444302791228;5880.911786323209;6173.135760993375;6190.133381326618;6220.191375121205;6238.812835614939;6255.43701066897;6119.326874043852;6141.662208578482;6143.56995010354;6148.385161483816;6172.105044768484;6115.027386288435;6147.815565533041;6249.531285153632;6257.749690012349;6272.843178920563;6065.605735627259;6142.069075791497;6147.985582378947;6158.3841615838055;6222;6173.32093469718;6238.064290356483;6240.063990401476;6250.156228909039;6256;4916.8770780730765;4924.876878078005;4925.482824303468;4927.408710954708;4943.1596628573225;4277.2942464493635;4290.249206388869;4332.220200363965;4344.109457561192;4347.108842687241;5574.637648552831;5625.887482250332;5649.971750141223;5671.744771485293;5705.4009329019855;6125.9693701531205;6131.325554189036;6175.135481032659;6178.227721534809;6179.412955769285;5670.688112153871;5675.744591493392;5734.455226753452;5781.624194427349;5811.215485909433;5396.109641909119;5406;5406.972965135228;5443.836684899459;5468.316460442445;6297.496200304056;6349.07938348946;6355.142055822497;6355.936440635534;6361.745530178741;6160.199174107337;6164.876702466016;6177.6911154443;6228.501719862309;6266.655333956676;6206.100115483139;6290;6360.363963603602;6369.649669268235;6374.872502549831;6203.7828675996125;6222.81331560054;6226.533010024175;6231.158793562992;6236.438720514994;5100.38795344561;5407.351117865881;5646.88706225865;6283.057541368831;6292.339304372975;6067;6102.908456373069;6114.66235542843;6122.204113465399;6133.457080270946;6098.613770999398;6120.142398366865;6120.9652521188555;6122.123717501779;6129.817282374624;4246.532881383107;5754.525554011815;5980.611260268069;5990.34106248321;6006.789762358297;6046.118676075902;6154.846128846814;6155.169052177898;6157.96921015392;6189.102580125947;6008.759649613967;6039.214902062704;6040.456358927718;6041.697915104315;6056.152138700498 21:45:28 INFO - PROCESS | 1657 | |1;ext.html;463.45775442732;493.4744497110577;527.580573444112;531.7371243297167;548.6077454619946;40733.981650458714;42480.57519424805;42498.99999999996;44399.778001109946;44478.11043779123;42733.78633106838;43245.35131973023;43328.35007474883;43405.34891976615;43446.56553434465;9856.802863942718;10029.899701002969;10029.997000299985;10031.849522257173;10033.698989030305;9886.307958442912;9954.352967057155;9960.551775170135;10014.998500149999;10053.195744340435;8680.218780309771;10016.599336026551;10062.345947513388;10070.597176112946;10077.294589378746;9917.256205784595;9995;10030.448325342104;10036.849447258297;10039.498025098752;9910.55402506889;9949.90050099497;9971.700848974542;9982.301238913278;10001.64994225206;41329.586704132875;41350.172996540205;41578.96052598679;41642.58357416417;41657.79171104155;3595.406757884972;3943.369060950263;3969.4442778011103;3972.6424621784176;4100.671946244323;31156.844215778776;31632.841835790903;32073.839630801696;32245;32294.677053229403;3189.521571764231;3231.5960504936884;3347.380734564089;3385.119868834121;3389.3390788796196;449693;452912;456077.4392256101;458254;463292;59369.70315148397;59751;59814.40185598088;60010.79978400495;60103;62202;62558.37441625616;62601.68699156475;63050.36949630445;63079.68460157762;15806.288717007763;15974.920125399298;15993.440229591908;16044.438444654614;16065.678686426207;12442.315672638091;12549.623511294676;12551.497940082294;12558.372081395893;12558.686032849251;16087.436939707053;16498.83501164997;16563.83436165647;16697.749533757004;16840.24218910152;6618.735250590019;6631.469482441393;6828.829279267959;6892.896606550906;6902.309769023058;836.7740710008263;996.8038353975259;1024.7540590258434;1041.7916416716687;1133.9092872570175;29634;30078.097657070324;30705.539416908763;30917.536236956465;31040.379192416025;924.2698268368049;977.53567055648;1035.699647102347;1045.6340280901622;1105.4638500327335;13882.09766365183;15705.450309239122;15719.97820141687;15970.680586388207;16037.759433608506;677710;680180;685242;686773;687710;58339;58875;58900.70549647224;58922.70538647279;59058.40941590615;1312.7177656803833;1356.071091302461;1374.1892283552752;1379.8206233189621;1381.5579014715395;29122.854385727936;29899.55150672698;30206.441533110476;30521.542176866922;30668.386632267677;42717.57282427136;44151.33772993472;50601.49398505968;51208.48791512186;51397.486025140766;624132;626113;627278;628044;629498;22591.20930767415;26708.866455667598;26727;26890.32774180661;27089.72910270951;38868.6113138865;46912.01247893967;47581;48136.5186348146;48550.757246214955;33745.66254337425;39537.93803355033;39601;40391;41119.58880411277;38631.6136838628;39103;39414.605853941095;40220;40460.59539404568;37416.19378546674;37832;38505.037374065876;38526.80736596299;39016;33215.83392083121;34393.31213375769;34867.4769878447;35100.82449587736;35364.11589710175;21436.464088397915;22070.66893996559;25340.619890701284;25506.74493255044;26044.609330860432 21:45:28 INFO - PROCESS | 1657 | |2;jquery.html;339.52466546834427;374.4776037427787;379.6146910885451;383.3463943975522;403.20367274632605;19576.90211548944;20723.481912952182;20829.06269217885;21344.8932755336;21452.785472145275;20348.491287717814;20544;20773.584528309424;21030.369088927317;21077.36767896962;5849.151872978416;5887.735051922674;6177.196964394622;6185.969070154665;6222.284437289724;4180.28935081037;4404.339349097629;6166.506679465632;6217.689115544227;6224.097505861648;5263.394709608406;5748.885022299552;5889.998700220951;6156.907646385308;6244.281907580641;6040.335563088057;6173.444390004899;6184.938150618504;6200.441960223579;6214.384260092364;5997;6059.45464908156;6070.423309785563;6075.635461872302;6078.422549857756;19320.42038738826;21375.893120534452;24457.877710611512;24657.506849863083;24754.752452475605;2100;2213.225371120095;2239.4065572623103;2247.438140464884;2343.7656234376595;10143.340682855665;10509.684709458728;11260.943695281552;14934.178620175886;16096.75854862178;1642.7761317818183;1770.5485101299087;1843.741876137342;1849;1910.923563057476;97566.51216743942;104315.47842260815;104893;110070.9999999992;111672.44163779129;11402;11560.421978901022;11593.304401735923;11663.708407289885;11999.940000299943;14540.05489643191;14692.632684182981;14697.926510367593;14712.55862324132;14730.484433044792;8285.8342833143;8480.982282126184;8540.103289154673;8544.060153383123;8622.956885215533;6864.759733409307;8395.664173432995;8524.957375213084;8529.488230706178;8530.488170709777;4468.754218518013;4559.52125026874;4575.771211439414;4623.491415944243;4630.282306242538;1577.2271586922554;1871.990640046791;1933.9709904351446;1938.951526211856;1942.300771722179;819.6680344460503;835.592027433776;866.1598249697784;1012.4026824173773;1051.0803047333584;17290;19580.21679132819;20542.281020164224;20880.269190578256;21213.363599092052;758.6472290384997;865.8268346330759;889.4040992534888;931.4830269200523;946.2997381937316;9094.272458203328;10984.615538456115;11138.610148644759;11155.442227888572;11165.944170279097;448974;534534;569299;601790;606300;98276.52585211085;115967;133745;136438;138219;964.9710508684751;1004.0862814838608;1020.2093377632394;1023.81059503992;1024.0936770957755;18763.437096887115;18904.43286701346;19309.13108910074;19314.613707725486;19428.222871085;52968.73515632397;53180;54820.0994410171;55120.16900708339;55388.41479570673;59348.40651593602;78872.21127788878;80881;81451;81723.18276817394;7632.580208088609;7792.649330780242;7944.735750311133;8169.2647661710735;8223.54770487629;10441.791164176522;12471.5011399543;12607.558735444216;12820.487180512675;12850.16473929229;10676.412797296223;10877.347359158475;11073.335599864035;11084.889151108708;11114.27757195812;8470.66117355299;10722;10744.409057501913;10883.074938629989;10948.616798412017;10901.56393744273;11045.33727976324;11176.720581985515;11185.272957258077;11207.702421003838;7888.68445262206;8004.439134368975;8011.759647210593;8121.959390203011;8187.181281871765;5362.356517217959;8174.877376839471;8668.176523230282;10404;10618.36289822613 21:45:28 INFO - PROCESS | 1657 | |3;mootools.html;1404.7752359622461;1457.963550911227;1499.0855578097362;1879.605282890593;1897.3169658922786;8091.595420228994;8378.162183781635;8626.525541095238;9172.633094676214;9182.173604375605;8574.271186949101;8943;8962.95518522408;9084.591193396296;9095.0904909509;2350.5416443793424;2391.80865530758;2394.1381102803066;2395.4849707312924;2397.2688330059414;2358.6579945907833;2378.500514891875;2388.22382725614;2405.230326295586;2406.3743426709093;2351.423901144222;2378.9762102378927;2388.34320561846;2398.388410955204;2411.855288682676;2339.157903154863;2339.6256598944087;2359.8348115631825;2361.8346715729904;2364.1016413762745;2317.5364927014666;2325.6395258734924;2339.7894189522854;2343.1447521654645;2345.777151170636;9016.684416045473;9062.637494500212;9073.954630226806;9087.727368178965;9092.318076144316;5283.788648454057;5364.999999999961;5394.568434525228;5443.156310771837;5448.645838020556;3640.5995340512522;3647.1246900743895;3670.1558641512333;3699.72252081095;3734.9626503734885;1064.9627263045757;1085.9945700271448;1086.136521465429;1095.6658219243207;1098.780243951213;11914.344711040976;11963.401829908556;12026.27842329463;12069.0344772418;12105.818412723816;4693.047858207958;4737.313089601988;4748.264019077049;4879.243717223836;4903.289023091632;10222.795544089076;10262.127719143957;10287.948560257151;10305.278630495794;10313.639022634172;4670.738900496847;4997.100521906092;5064.215046667772;5166.974165129225;5442.91835622466;3856.2480316338047;4415.66882483815;4577.908441831145;4789.425268967746;4933.975330123327;3903.8048097595006;3966.147278335114;4014.2172276406404;4044.4337792709043;4091.8977025574004;1284.6724085358173;1293.9158954667917;1299.330844615024;1306.0139594606012;1307.7842156044337;769.1231995525106;771.9845603087907;909.5770466733001;916.9358144929924;956.636478138303;5851.297844258716;5897.32180799205;5997.250343707036;6049.455549000611;6108.205933228652;438.15873522836233;438.2637169555178;445.857325655787;448.5514485514486;451.1878618486711;4001.579834117434;4004.979975100106;4070.9796451017555;4119.608637179464;4158.812853421603;10636.574537018587;10868.347899126078;10987.175961802897;10998.670039898816;11029.44852757359;10944.124470042536;10947.124230061576;10953;10961.725956850983;10983.780324393467;7827.726029588937;8235.547044912468;8342.83314333722;8391.874121888177;8441.240288374076;6003.6397816131175;6030.909536357046;6065.211522502046;6080;6206.441420272197;10108.54511546997;10296.970302969643;10345.431001295;10368.740781480523;10412.739681508023;10235.437050961971;10327.793444131226;10345.120664743281;10373.844392333971;10576.41829699374;7041.366277035091;7283.635818209174;7453.440991925736;7584.734534291494;7802.024746906637;8459.280961118375;8658.350623703373;8669.653213871374;8686.609102590524;8687.174718401742;7487.925120748723;7582.35549978258;7627.656755445907;7736.806579835324;7746.419018573517;6222.782202622887;7498.212687667717;7517.909903063915;7667.0416197975255;7678.232176782501;6949.4440444765305;7090.361867431956;7106.609136497335;7110.075690160453;7125.857482850418;4802.73584952831;4963.975180124076;5056.494350564914;5069.214271787879;5126.436092029949;5062;5148.845534633967;5211.400688920823;5215.608829337739;5227.89544209106 21:45:28 INFO - PROCESS | 1657 | |4;prototype.html;276.8975479072743;306.2298319725889;313.4248653720423;314.4669784714909;317.22596863652683;3998.1603863188766;5228.712420816853;5542.778288868445;5543.528800051995;5624.268845050148;4786.787626888334;5192.55863251624;5314.973425132878;5322.733863306838;5412.56699464043;4134.904250373661;4160.625543701066;4176.143890502455;4181.393697913801;4187.434696316004;3904.2191561687778;4155.252054630166;4172.311568591179;4186.434831297782;4190.3923931029985;3446.6036405813275;4100;4149.83400663973;4155.58444155585;4178.937315940263;3406.8977930662118;3847.61523847613;4131.607497287753;4139.275626765304;4154.750714957097;3149.685031496855;3606.7294952878638;3609.682465899954;3613.7831730096277;3620.8594292797948;4376.606105450493;4809.855704328876;5270.367555893279;5305.3898801637915;5326.9467305327225;4125.608067233608;4157.3140431828715;4452.443444569429;4498.820047198108;4719.126961512097;4561.201789686792;4597.471290801567;4813.566778989873;4820.661978940211;5840.182374467578;3226.596675415573;3770.604086570898;3880.805959702032;3919.510061242345;3954.703397245218;4793.640476964241;6015.879682406327;6068.362821903681;6128.233970753656;6132.908006379907;3217.742580593547;3701.3522633539023;3943.960560394359;4005.8783540608674;4042.4138499917935;3759.830807613664;3999.1403997140887;4042.15114825884;4050.1292222171787;4057.411675307123;4720.598749106364;4723.149833030087;4723.504032076651;4724.574788269072;4742.478327383985;5836.241288632451;5843.620164689281;5898;5918.852028699317;5933.525317974551;1293.230527835936;1327.6813564744584;1330.846952600444;1351.5269655620455;1352.6280272925023;872.9214370706667;899.1727610598265;921.1249313152398;923.6351641101727;928.7213835849218;4521.4574251089425;4575.7483338416705;5424.294841670637;5454.563634909197;5456.863578410492;5173.4826517347965;5597.412271711493;5915.799782154632;5927.9407205928255;5944.316403613553;4484.573965473275;5206.635535512478;5406.486183868523;5420.566354691615;5427.43011983744;2724.986375068112;2887.805267996338;3087.8919237826567;3155.9900831734085;3191.457452233126;5474.7262636868;5605.607607467438;5646.830595082154;5777.740001699934;5925.318588362307;5830.416958304136;5945.910811337833;6092.969535152296;6131.724072416752;6152.2924863640355;5377.731113444391;6219.222597175443;6225.719842607094;6258.624482530971;6271;5409.24199635128;5923.49650279731;5935.970320148372;5962.6609098904755;5995.880082398415;4959.239130434823;5877.529797616266;5939.732712028055;5950.196723442453;5975.910361344497;5733.967885780599;5995.040793473024;6126.264848218242;6270.056282552241;6295.135713250552;3576.4277715565386;3836.060165259529;3941.940870886882;3970.3647416413237;4005.6595189409227;5780.855478613068;5992.43071908168;6038.5772995889865;6062.393760623902;6095.847603809941;5140.43455219918;5448.754806033818;5468.26178465902;5790.884182316414;5812.360640329645;5090.567301779242;5110.642255042111;5810.82567522975;5835.416458354301;5852.385499522489;4450.2186133666455;5565.55475561946;5742.913856292243;5745.9425405745405;5809.605694633173;4908.901821963469;5055.988802239493;5306.363236411501;5889.146073819358;5903.645781253139;5683.328835447333;5709.914351284651;5722.513586345207;5756.683382414008;5767.711614419179 21:45:28 INFO - PROCESS | 1657 | |5;yui.html;701.3932947999981;703.8064532253633;717.5443593318244;737.2701025984277;783.2911215350107;14861.40554377825;15418.845811541882;15899.205039748009;16023.11872846993;16054.919725401385;13671.521496747615;13866.4453421863;13941.651458713537;13948.372323245452;13965.231912244823;849.9490030598153;869.0310304011014;877.3682948277262;877.6489404238324;879.0242830458186;691.9688614012382;707.1266985273184;848.6223630484437;861.1861790607878;871.298604623278;901.4906577783531;914.3279689428265;914.4558987402485;916.3036092569629;916.5142474488532;814.3851392198911;904.4980036079972;906.5875026862761;909.4725059465494;910.0535443139161;562.9859253518654;575.4073304496334;659.66686823154;680.5236334565774;686.9793906182822;14345.928270358685;14487.3480693368;14517.637059073608;14521;14547.78178327326;14318.64203394913;14500;14567.344469498898;14847;14872.405103795834;10054.296199266055;10058.597656093747;11779.352135632536;11847.703807404798;12251.264924104582;3768;3810.2569998850236;3842.9615703842883;3843.6925045996254;3976.4234186042863;124339.51320973621;125032.37483812614;129012.70987290195;129072.35463822715;130028.34985825105;11045.613403530999;11108.16688748347;11178.832317515244;11181.944090279496;11375.829362559569;22524.662130068064;22614.434639134153;22651.88674056619;22656.773432265796;22702.772972270395;7794.493357931717;7849.293563579306;7891;8098.473599216032;8108.1891810819625;3973.8211780469946;4168.478940132483;4283.164782867311;4292.356146577976;4314.827406903752;3553.129483276614;3695.2055308108884;3696.537932758405;3700.5374328208973;3704.9073773155346;658.8649326887977;659.8911179655303;666.3969107957306;700.3906601256939;719.2735337309355;595.2351228671137;638.9712462939179;673.5655502201053;674.1202730436827;688.8725585766649;8894;9494.28792840526;9516.666916658021;9624.485647959009;9764.462954537426;584.5294537896949;650.8340373204802;670.6345041952147;670.9329067093252;699.4928676709446;4417.6826541866485;4616.7765542131365;4672.976635116871;4774.618030557547;4801.03699232643;141622.2918885399;146376;149981.25009374882;158077;162356;127794;134245;136232;137260;139061;96940.0305996931;105557.47221263999;114490;116883;119427;7710.498817576839;7922.0889597694695;8142.59287035634;8684.305255579664;9096.681616143404;54924.45075549193;57233;63932.68033659988;66755.99732008048;67321.66339168273;91015.54492227496;92783.53608231916;94245.5287723557;96170.0382996161;97502.51248743711;11711.1802173847;11731.530738770354;12109.394530273275;12156.939215303866;12207.69480762988;12798;13009.739805204032;13037.087403881636;13067.994361297146;13108.279044652254;11899.46452409661;12069;12149.574764883188;12200.389980501117;12259;11847.52609895629;11997.520099195934;12101.273923564615;12406.255624662188;12573.182743121668;11608.6517404478;11956.282623042645;12276.386180691108;12348.938255308665;12468.688282793002;9147.542622868963;9543.711598934055;9952.7108764983;10090.231385433899;10435.895641043797;9025.962014368431;9320;10267;10436.739081523023;10536.894631053592 21:45:28 INFO - PROCESS | 1657 | __end_tp_report 21:45:28 INFO - PROCESS | 1657 | __start_cc_report 21:45:28 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,367 21:45:28 INFO - PROCESS | 1657 | __end_cc_report 21:45:28 INFO - PROCESS | 1657 | __startTimestamp1476161128896__endTimestamp 21:45:28 INFO - PROCESS | 1657 | 21:45:28 INFO - PROCESS | 1657 | ------- Summary: start ------- 21:45:28 INFO - PROCESS | 1657 | Number of tests: 6 21:45:28 INFO - PROCESS | 1657 | 21:45:28 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5677.31 Median:5880.91 stddev:684.89 (11.6%) stddev-sans-first:662.90 21:45:28 INFO - PROCESS | 1657 | Values: 3382.4 3408.9 3430.8 3432.2 3461.1 5642.7 5654.1 5664.6 5724.5 5724.7 5626.5 5695.9 5709.1 5754.9 5875.5 5184.2 5220.1 5224.8 5286.1 5313.9 5201.1 5307.8 5319.2 5338.3 5375.9 5138.9 5183.7 5261.0 5265.4 5278.3 3798.4 4178.5 4343.5 4994.0 5295.9 4332.9 5189.1 5205.4 5243.2 5251.7 5560.6 5591.2 5603.3 5656.7 5733.7 4778.6 5755.5 5769.0 6219.2 6239.8 5968.0 6232.4 6234.7 6261.0 6295.5 5148.9 5260.5 5847.6 5849.4 5880.9 6173.1 6190.1 6220.2 6238.8 6255.4 6119.3 6141.7 6143.6 6148.4 6172.1 6115.0 6147.8 6249.5 6257.7 6272.8 6065.6 6142.1 6148.0 6158.4 6222.0 6173.3 6238.1 6240.1 6250.2 6256.0 4916.9 4924.9 4925.5 4927.4 4943.2 4277.3 4290.2 4332.2 4344.1 4347.1 5574.6 5625.9 5650.0 5671.7 5705.4 6126.0 6131.3 6175.1 6178.2 6179.4 5670.7 5675.7 5734.5 5781.6 5811.2 5396.1 5406.0 5407.0 5443.8 5468.3 6297.5 6349.1 6355.1 6355.9 6361.7 6160.2 6164.9 6177.7 6228.5 6266.7 6206.1 6290.0 6360.4 6369.6 6374.9 6203.8 6222.8 6226.5 6231.2 6236.4 5100.4 5407.4 5646.9 6283.1 6292.3 6067.0 6102.9 6114.7 6122.2 6133.5 6098.6 6120.1 6121.0 6122.1 6129.8 4246.5 5754.5 5980.6 5990.3 6006.8 6046.1 6154.8 6155.2 6158.0 6189.1 6008.8 6039.2 6040.5 6041.7 6056.2 21:45:28 INFO - PROCESS | 1657 | 21:45:28 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:71987.11 Median:21678.99 stddev:159785.06 (737.1%) stddev-sans-first:160143.06 21:45:28 INFO - PROCESS | 1657 | Values: 463.5 493.5 527.6 531.7 548.6 40734.0 42480.6 42499.0 44399.8 44478.1 42733.8 43245.4 43328.4 43405.3 43446.6 9856.8 10029.9 10030.0 10031.8 10033.7 9886.3 9954.4 9960.6 10015.0 10053.2 8680.2 10016.6 10062.3 10070.6 10077.3 9917.3 9995.0 10030.4 10036.8 10039.5 9910.6 9949.9 9971.7 9982.3 10001.6 41329.6 41350.2 41579.0 41642.6 41657.8 3595.4 3943.4 3969.4 3972.6 4100.7 31156.8 31632.8 32073.8 32245.0 32294.7 3189.5 3231.6 3347.4 3385.1 3389.3 449693.0 452912.0 456077.4 458254.0 463292.0 59369.7 59751.0 59814.4 60010.8 60103.0 62202.0 62558.4 62601.7 63050.4 63079.7 15806.3 15974.9 15993.4 16044.4 16065.7 12442.3 12549.6 12551.5 12558.4 12558.7 16087.4 16498.8 16563.8 16697.7 16840.2 6618.7 6631.5 6828.8 6892.9 6902.3 836.8 996.8 1024.8 1041.8 1133.9 29634.0 30078.1 30705.5 30917.5 31040.4 924.3 977.5 1035.7 1045.6 1105.5 13882.1 15705.5 15720.0 15970.7 16037.8 677710.0 680180.0 685242.0 686773.0 687710.0 58339.0 58875.0 58900.7 58922.7 59058.4 1312.7 1356.1 1374.2 1379.8 1381.6 29122.9 29899.6 30206.4 30521.5 30668.4 42717.6 44151.3 50601.5 51208.5 51397.5 624132.0 626113.0 627278.0 628044.0 629498.0 22591.2 26708.9 26727.0 26890.3 27089.7 38868.6 46912.0 47581.0 48136.5 48550.8 33745.7 39537.9 39601.0 40391.0 41119.6 38631.6 39103.0 39414.6 40220.0 40460.6 37416.2 37832.0 38505.0 38526.8 39016.0 33215.8 34393.3 34867.5 35100.8 35364.1 21436.5 22070.7 25340.6 25506.7 26044.6 21:45:28 INFO - PROCESS | 1657 | 21:45:28 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:33207.23 Median:13400.82 stddev:92783.01 (692.4%) stddev-sans-first:93010.46 21:45:28 INFO - PROCESS | 1657 | Values: 339.5 374.5 379.6 383.3 403.2 19576.9 20723.5 20829.1 21344.9 21452.8 20348.5 20544.0 20773.6 21030.4 21077.4 5849.2 5887.7 6177.2 6186.0 6222.3 4180.3 4404.3 6166.5 6217.7 6224.1 5263.4 5748.9 5890.0 6156.9 6244.3 6040.3 6173.4 6184.9 6200.4 6214.4 5997.0 6059.5 6070.4 6075.6 6078.4 19320.4 21375.9 24457.9 24657.5 24754.8 2100.0 2213.2 2239.4 2247.4 2343.8 10143.3 10509.7 11260.9 14934.2 16096.8 1642.8 1770.5 1843.7 1849.0 1910.9 97566.5 104315.5 104893.0 110071.0 111672.4 11402.0 11560.4 11593.3 11663.7 11999.9 14540.1 14692.6 14697.9 14712.6 14730.5 8285.8 8481.0 8540.1 8544.1 8623.0 6864.8 8395.7 8525.0 8529.5 8530.5 4468.8 4559.5 4575.8 4623.5 4630.3 1577.2 1872.0 1934.0 1939.0 1942.3 819.7 835.6 866.2 1012.4 1051.1 17290.0 19580.2 20542.3 20880.3 21213.4 758.6 865.8 889.4 931.5 946.3 9094.3 10984.6 11138.6 11155.4 11165.9 448974.0 534534.0 569299.0 601790.0 606300.0 98276.5 115967.0 133745.0 136438.0 138219.0 965.0 1004.1 1020.2 1023.8 1024.1 18763.4 18904.4 19309.1 19314.6 19428.2 52968.7 53180.0 54820.1 55120.2 55388.4 59348.4 78872.2 80881.0 81451.0 81723.2 7632.6 7792.6 7944.7 8169.3 8223.5 10441.8 12471.5 12607.6 12820.5 12850.2 10676.4 10877.3 11073.3 11084.9 11114.3 8470.7 10722.0 10744.4 10883.1 10948.6 10901.6 11045.3 11176.7 11185.3 11207.7 7888.7 8004.4 8011.8 8122.0 8187.2 5362.4 8174.9 8668.2 10404.0 10618.4 21:45:28 INFO - PROCESS | 1657 | 21:45:28 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5799.58 Median:449.87 stddev:3314.94 (736.9%) stddev-sans-first:3307.79 21:45:28 INFO - PROCESS | 1657 | Values: 1404.8 1458.0 1499.1 1879.6 1897.3 8091.6 8378.2 8626.5 9172.6 9182.2 8574.3 8943.0 8963.0 9084.6 9095.1 2350.5 2391.8 2394.1 2395.5 2397.3 2358.7 2378.5 2388.2 2405.2 2406.4 2351.4 2379.0 2388.3 2398.4 2411.9 2339.2 2339.6 2359.8 2361.8 2364.1 2317.5 2325.6 2339.8 2343.1 2345.8 9016.7 9062.6 9074.0 9087.7 9092.3 5283.8 5365.0 5394.6 5443.2 5448.6 3640.6 3647.1 3670.2 3699.7 3735.0 1065.0 1086.0 1086.1 1095.7 1098.8 11914.3 11963.4 12026.3 12069.0 12105.8 4693.0 4737.3 4748.3 4879.2 4903.3 10222.8 10262.1 10287.9 10305.3 10313.6 4670.7 4997.1 5064.2 5167.0 5442.9 3856.2 4415.7 4577.9 4789.4 4934.0 3903.8 3966.1 4014.2 4044.4 4091.9 1284.7 1293.9 1299.3 1306.0 1307.8 769.1 772.0 909.6 916.9 956.6 5851.3 5897.3 5997.3 6049.5 6108.2 438.2 438.3 445.9 448.6 451.2 4001.6 4005.0 4071.0 4119.6 4158.8 10636.6 10868.3 10987.2 10998.7 11029.4 10944.1 10947.1 10953.0 10961.7 10983.8 7827.7 8235.5 8342.8 8391.9 8441.2 6003.6 6030.9 6065.2 6080.0 6206.4 10108.5 10297.0 10345.4 10368.7 10412.7 10235.4 10327.8 10345.1 10373.8 10576.4 7041.4 7283.6 7453.4 7584.7 7802.0 8459.3 8658.4 8669.7 8686.6 8687.2 7487.9 7582.4 7627.7 7736.8 7746.4 6222.8 7498.2 7517.9 7667.0 7678.2 6949.4 7090.4 7106.6 7110.1 7125.9 4802.7 4964.0 5056.5 5069.2 5126.4 5062.0 5148.8 5211.4 5215.6 5227.9 21:45:28 INFO - PROCESS | 1657 | 21:45:28 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:4638.11 Median:5199.60 stddev:1454.76 (28.0%) stddev-sans-first:1421.54 21:45:28 INFO - PROCESS | 1657 | Values: 276.9 306.2 313.4 314.5 317.2 3998.2 5228.7 5542.8 5543.5 5624.3 4786.8 5192.6 5315.0 5322.7 5412.6 4134.9 4160.6 4176.1 4181.4 4187.4 3904.2 4155.3 4172.3 4186.4 4190.4 3446.6 4100.0 4149.8 4155.6 4178.9 3406.9 3847.6 4131.6 4139.3 4154.8 3149.7 3606.7 3609.7 3613.8 3620.9 4376.6 4809.9 5270.4 5305.4 5326.9 4125.6 4157.3 4452.4 4498.8 4719.1 4561.2 4597.5 4813.6 4820.7 5840.2 3226.6 3770.6 3880.8 3919.5 3954.7 4793.6 6015.9 6068.4 6128.2 6132.9 3217.7 3701.4 3944.0 4005.9 4042.4 3759.8 3999.1 4042.2 4050.1 4057.4 4720.6 4723.1 4723.5 4724.6 4742.5 5836.2 5843.6 5898.0 5918.9 5933.5 1293.2 1327.7 1330.8 1351.5 1352.6 872.9 899.2 921.1 923.6 928.7 4521.5 4575.7 5424.3 5454.6 5456.9 5173.5 5597.4 5915.8 5927.9 5944.3 4484.6 5206.6 5406.5 5420.6 5427.4 2725.0 2887.8 3087.9 3156.0 3191.5 5474.7 5605.6 5646.8 5777.7 5925.3 5830.4 5945.9 6093.0 6131.7 6152.3 5377.7 6219.2 6225.7 6258.6 6271.0 5409.2 5923.5 5936.0 5962.7 5995.9 4959.2 5877.5 5939.7 5950.2 5975.9 5734.0 5995.0 6126.3 6270.1 6295.1 3576.4 3836.1 3941.9 3970.4 4005.7 5780.9 5992.4 6038.6 6062.4 6095.8 5140.4 5448.8 5468.3 5790.9 5812.4 5090.6 5110.6 5810.8 5835.4 5852.4 4450.2 5565.6 5742.9 5745.9 5809.6 4908.9 5056.0 5306.4 5889.1 5903.6 5683.3 5709.9 5722.5 5756.7 5767.7 21:45:28 INFO - PROCESS | 1657 | 21:45:28 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:25412.99 Median:3826.61 stddev:41702.44 (1089.8%) stddev-sans-first:41778.15 21:45:28 INFO - PROCESS | 1657 | Values: 701.4 703.8 717.5 737.3 783.3 14861.4 15418.8 15899.2 16023.1 16054.9 13671.5 13866.4 13941.7 13948.4 13965.2 849.9 869.0 877.4 877.6 879.0 692.0 707.1 848.6 861.2 871.3 901.5 914.3 914.5 916.3 916.5 814.4 904.5 906.6 909.5 910.1 563.0 575.4 659.7 680.5 687.0 14345.9 14487.3 14517.6 14521.0 14547.8 14318.6 14500.0 14567.3 14847.0 14872.4 10054.3 10058.6 11779.4 11847.7 12251.3 3768.0 3810.3 3843.0 3843.7 3976.4 124339.5 125032.4 129012.7 129072.4 130028.3 11045.6 11108.2 11178.8 11181.9 11375.8 22524.7 22614.4 22651.9 22656.8 22702.8 7794.5 7849.3 7891.0 8098.5 8108.2 3973.8 4168.5 4283.2 4292.4 4314.8 3553.1 3695.2 3696.5 3700.5 3704.9 658.9 659.9 666.4 700.4 719.3 595.2 639.0 673.6 674.1 688.9 8894.0 9494.3 9516.7 9624.5 9764.5 584.5 650.8 670.6 670.9 699.5 4417.7 4616.8 4673.0 4774.6 4801.0 141622.3 146376.0 149981.3 158077.0 162356.0 127794.0 134245.0 136232.0 137260.0 139061.0 96940.0 105557.5 114490.0 116883.0 119427.0 7710.5 7922.1 8142.6 8684.3 9096.7 54924.5 57233.0 63932.7 66756.0 67321.7 91015.5 92783.5 94245.5 96170.0 97502.5 11711.2 11731.5 12109.4 12156.9 12207.7 12798.0 13009.7 13037.1 13068.0 13108.3 11899.5 12069.0 12149.6 12200.4 12259.0 11847.5 11997.5 12101.3 12406.3 12573.2 11608.7 11956.3 12276.4 12348.9 12468.7 9147.5 9543.7 9952.7 10090.2 10435.9 9026.0 9320.0 10267.0 10436.7 10536.9 21:45:28 INFO - PROCESS | 1657 | -------- Summary: end -------- 21:45:28 INFO - PROCESS | 1657 | 21:45:29 INFO - TEST-INFO | 1657: exit 0 21:45:29 INFO - TEST-OK | dromaeo_css | took 1114736ms 21:45:29 INFO - TEST-START | kraken 21:45:29 INFO - operating with platform_type : mac_ 21:45:29 INFO - Initialising browser for kraken test... 21:45:29 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpJEDveu/profile http://localhost:49272/getInfo.html) 21:45:32 INFO - PROCESS | 1669 | 2016-10-10 21:45:32.048 plugin-container[1670:10031] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d23, name = 'com.apple.tsm.portname' 21:45:32 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:45:32 INFO - PROCESS | 1669 | 2016-10-10 21:45:32.052 plugin-container[1670:10031] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f13, name = 'com.apple.CFPasteboardClient' 21:45:32 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:45:32 INFO - PROCESS | 1669 | 2016-10-10 21:45:32.052 plugin-container[1670:10031] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:45:32 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 21:45:32 INFO - PROCESS | 1669 | colorDepth:24 21:45:32 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 21:45:32 INFO - PROCESS | 1669 | __metrics 21:45:32 ERROR - PROCESS | 1669 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 21:45:32 INFO - TEST-INFO | 1669: exit 0 21:45:32 INFO - Browser initialized. 21:45:32 INFO - Running cycle 1/1 for kraken test... 21:45:32 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpJEDveu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:45:35 INFO - PROCESS | 1671 | 2016-10-10 21:45:35.263 plugin-container[1672:10147] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x891f, name = 'com.apple.tsm.portname' 21:45:35 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:45:35 INFO - PROCESS | 1671 | 2016-10-10 21:45:35.268 plugin-container[1672:10147] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f13, name = 'com.apple.CFPasteboardClient' 21:45:35 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:45:35 INFO - PROCESS | 1671 | 2016-10-10 21:45:35.268 plugin-container[1672:10147] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:45:45 INFO - PROCESS | 1671 | RSS: Main: 239140864 21:45:45 INFO - PROCESS | 1671 | 21:45:47 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 21:45:48 INFO - PROCESS | 1671 | RSS: Main: 252805120 21:45:48 INFO - PROCESS | 1671 | 21:45:51 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 21:45:52 INFO - PROCESS | 1671 | RSS: Main: 261132288 21:45:52 INFO - PROCESS | 1671 | 21:45:55 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 21:45:55 INFO - PROCESS | 1671 | RSS: Main: 261517312 21:45:55 INFO - PROCESS | 1671 | 21:45:57 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 21:45:58 INFO - PROCESS | 1671 | RSS: Main: 262594560 21:45:58 INFO - PROCESS | 1671 | 21:46:00 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:46:01 INFO - PROCESS | 1671 | RSS: Main: 263905280 21:46:01 INFO - PROCESS | 1671 | 21:46:04 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 21:46:04 INFO - PROCESS | 1671 | RSS: Main: 264962048 21:46:04 INFO - PROCESS | 1671 | 21:46:07 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 21:46:08 INFO - PROCESS | 1671 | RSS: Main: 264892416 21:46:08 INFO - PROCESS | 1671 | 21:46:11 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 21:46:11 INFO - PROCESS | 1671 | RSS: Main: 265191424 21:46:11 INFO - PROCESS | 1671 | 21:46:13 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:46:14 INFO - PROCESS | 1671 | RSS: Main: 264851456 21:46:14 INFO - PROCESS | 1671 | 21:46:16 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:46:16 INFO - PROCESS | 1671 | RSS: Main: 264953856 21:46:16 INFO - PROCESS | 1671 | 21:46:18 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:46:19 INFO - PROCESS | 1671 | RSS: Main: 264962048 21:46:19 INFO - PROCESS | 1671 | 21:46:22 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:46:23 INFO - PROCESS | 1671 | RSS: Main: 265347072 21:46:23 INFO - PROCESS | 1671 | 21:46:26 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:46:27 INFO - PROCESS | 1671 | RSS: Main: 265359360 21:46:27 INFO - PROCESS | 1671 | 21:46:28 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 21:46:29 INFO - PROCESS | 1671 | RSS: Main: 267116544 21:46:29 INFO - PROCESS | 1671 | 21:46:29 INFO - PROCESS | 1671 | __start_tp_report 21:46:29 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 21:46:29 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 21:46:29 INFO - PROCESS | 1671 | |i|pagename|runs| 21:46:29 INFO - PROCESS | 1671 | |0;ai-astar;106;118;117;117;119;114;117;117;116;119 21:46:29 INFO - PROCESS | 1671 | |1;audio-beat-detection;146;115;117;152;114;117;115;115;142;113 21:46:29 INFO - PROCESS | 1671 | |2;audio-dft;192;212;241;221;201;194;196;196;197;192 21:46:29 INFO - PROCESS | 1671 | |3;audio-fft;86;58;59;87;59;59;58;58;81;59 21:46:29 INFO - PROCESS | 1671 | |4;audio-oscillator;85;74;84;75;99;82;90;70;69;87 21:46:29 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;111;125;79;116;137;127;90;88;90;87 21:46:29 INFO - PROCESS | 1671 | |6;imaging-darkroom;96;100;96;100;154;152;97;97;97;96 21:46:29 INFO - PROCESS | 1671 | |7;imaging-desaturate;101;79;151;99;94;112;108;76;132;78 21:46:29 INFO - PROCESS | 1671 | |8;json-parse-financial;84;84;56;57;57;58;56;59;52;50 21:46:29 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;68;73;79;72;92;96;70;69;70;70 21:46:29 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;88;88;85;85;92;92;77;88;98;105 21:46:29 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;162;143;163;155;168;150;148;161;209;192 21:46:29 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;174;188;176;175;175;182;238;202;175;172 21:46:29 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;69;71;69;70;71;68;70;68;67;68 21:46:29 INFO - PROCESS | 1671 | __end_tp_report 21:46:29 INFO - PROCESS | 1671 | __start_cc_report 21:46:29 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,924 21:46:29 INFO - PROCESS | 1671 | __end_cc_report 21:46:29 INFO - PROCESS | 1671 | __startTimestamp1476161189039__endTimestamp 21:46:29 INFO - PROCESS | 1671 | 21:46:29 INFO - PROCESS | 1671 | ------- Summary: start ------- 21:46:29 INFO - PROCESS | 1671 | Number of tests: 14 21:46:29 INFO - PROCESS | 1671 | 21:46:29 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:116.00 Median:117.00 stddev:3.80 (3.2%) stddev-sans-first:1.54 21:46:29 INFO - PROCESS | 1671 | Values: 106.0 118.0 117.0 117.0 119.0 114.0 117.0 117.0 116.0 119.0 21:46:29 INFO - PROCESS | 1671 | 21:46:29 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:124.60 Median:117.00 stddev:15.46 (13.2%) stddev-sans-first:14.32 21:46:29 INFO - PROCESS | 1671 | Values: 146.0 115.0 117.0 152.0 114.0 117.0 115.0 115.0 142.0 113.0 21:46:29 INFO - PROCESS | 1671 | 21:46:29 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:204.20 Median:199.00 stddev:15.97 (8.0%) stddev-sans-first:16.32 21:46:29 INFO - PROCESS | 1671 | Values: 192.0 212.0 241.0 221.0 201.0 194.0 196.0 196.0 197.0 192.0 21:46:29 INFO - PROCESS | 1671 | 21:46:29 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:66.40 Median:59.00 stddev:12.70 (21.5%) stddev-sans-first:11.32 21:46:29 INFO - PROCESS | 1671 | Values: 86.0 58.0 59.0 87.0 59.0 59.0 58.0 58.0 81.0 59.0 21:46:29 INFO - PROCESS | 1671 | 21:46:29 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:81.50 Median:84.50 stddev:9.51 (11.3%) stddev-sans-first:10.01 21:46:29 INFO - PROCESS | 1671 | Values: 85.0 74.0 84.0 75.0 99.0 82.0 90.0 70.0 69.0 87.0 21:46:29 INFO - PROCESS | 1671 | 21:46:29 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:105.00 Median:83.00 stddev:20.56 (24.8%) stddev-sans-first:21.69 21:46:29 INFO - PROCESS | 1671 | Values: 111.0 125.0 79.0 116.0 137.0 127.0 90.0 88.0 90.0 87.0 21:46:29 INFO - PROCESS | 1671 | 21:46:29 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:108.50 Median:96.00 stddev:23.51 (24.5%) stddev-sans-first:24.49 21:46:29 INFO - PROCESS | 1671 | Values: 96.0 100.0 96.0 100.0 154.0 152.0 97.0 97.0 97.0 96.0 21:46:29 INFO - PROCESS | 1671 | 21:46:29 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:103.00 Median:77.00 stddev:24.23 (31.5%) stddev-sans-first:25.68 21:46:29 INFO - PROCESS | 1671 | Values: 101.0 79.0 151.0 99.0 94.0 112.0 108.0 76.0 132.0 78.0 21:46:29 INFO - PROCESS | 1671 | 21:46:29 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:61.30 Median:57.50 stddev:12.27 (21.3%) stddev-sans-first:9.88 21:46:29 INFO - PROCESS | 1671 | Values: 84.0 84.0 56.0 57.0 57.0 58.0 56.0 59.0 52.0 50.0 21:46:29 INFO - PROCESS | 1671 | 21:46:29 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:75.90 Median:72.50 stddev:10.06 (13.9%) stddev-sans-first:10.26 21:46:29 INFO - PROCESS | 1671 | Values: 68.0 73.0 79.0 72.0 92.0 96.0 70.0 69.0 70.0 70.0 21:46:29 INFO - PROCESS | 1671 | 21:46:29 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:89.80 Median:88.00 stddev:7.66 (8.7%) stddev-sans-first:8.09 21:46:29 INFO - PROCESS | 1671 | Values: 88.0 88.0 85.0 85.0 92.0 92.0 77.0 88.0 98.0 105.0 21:46:29 INFO - PROCESS | 1671 | 21:46:29 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:165.10 Median:162.50 stddev:20.55 (12.6%) stddev-sans-first:21.77 21:46:29 INFO - PROCESS | 1671 | Values: 162.0 143.0 163.0 155.0 168.0 150.0 148.0 161.0 209.0 192.0 21:46:29 INFO - PROCESS | 1671 | 21:46:29 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.70 Median:179.00 stddev:20.50 (11.5%) stddev-sans-first:21.30 21:46:29 INFO - PROCESS | 1671 | Values: 174.0 188.0 176.0 175.0 175.0 182.0 238.0 202.0 175.0 172.0 21:46:29 INFO - PROCESS | 1671 | 21:46:29 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:69.50 stddev:1.37 (2.0%) stddev-sans-first:1.45 21:46:29 INFO - PROCESS | 1671 | Values: 69.0 71.0 69.0 70.0 71.0 68.0 70.0 68.0 67.0 68.0 21:46:29 INFO - PROCESS | 1671 | -------- Summary: end -------- 21:46:29 INFO - PROCESS | 1671 | 21:46:29 INFO - TEST-INFO | 1671: exit 0 21:46:29 INFO - TEST-OK | kraken | took 60343ms 21:46:29 INFO - SUITE-END | took 1175s 21:46:30 INFO - Completed test suite (00:19:35) 21:46:30 INFO - javascript benchmark 21:46:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7339.498087731994, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3382.4080785862466, 3408.8977330680086, 3430.8456119474613, 3432.2105915639404, 3461.13471632092, 5642.661440313584, 5654.0670789319765, 5664.575156863236, 5724.4561766632205, 5724.742386592603, 5626.521745651625, 5695.886082278352, 5709.086546152616, 5754.884902301962, 5875.500582450497, 5184.2223666450145, 5220.086484865153, 5224.817131400402, 5286.127788914824, 5313.920291195635, 5201.063808514466, 5307.840764777063, 5319.175527793198, 5338.332708411449, 5375.865603359908, 5138.916650010002, 5183.688978661293, 5261.000409922102, 5265.447128051576, 5278.340207474066, 3798.3922572388287, 4178.540360560336, 4343.543927887558, 4993.976234871842, 5295.947040529622, 4332.870013899588, 5189.143791274435, 5205.375354957391, 5243.187305967581, 5251.658642188284, 5560.582956278296, 5591.189277554732, 5603.299587551556, 5656.66060036395, 5733.684647344395, 4778.593819525309, 5755.510781583571, 5769.0, 6219.222597175353, 6239.844003899893, 5967.970160149215, 6232.439080482778, 6234.669357699193, 6261.0, 6295.527835412317, 5148.897022059537, 5260.500252476008, 5847.619904706181, 5849.444302791228, 5880.911786323209, 6173.135760993375, 6190.133381326618, 6220.191375121205, 6238.812835614939, 6255.43701066897, 6119.326874043852, 6141.662208578482, 6143.56995010354, 6148.385161483816, 6172.105044768484, 6115.027386288435, 6147.815565533041, 6249.531285153632, 6257.749690012349, 6272.843178920563, 6065.605735627259, 6142.069075791497, 6147.985582378947, 6158.3841615838055, 6222.0, 6173.32093469718, 6238.064290356483, 6240.063990401476, 6250.156228909039, 6256.0, 4916.8770780730765, 4924.876878078005, 4925.482824303468, 4927.408710954708, 4943.1596628573225, 4277.2942464493635, 4290.249206388869, 4332.220200363965, 4344.109457561192, 4347.108842687241, 5574.637648552831, 5625.887482250332, 5649.971750141223, 5671.744771485293, 5705.4009329019855, 6125.9693701531205, 6131.325554189036, 6175.135481032659, 6178.227721534809, 6179.412955769285, 5670.688112153871, 5675.744591493392, 5734.455226753452, 5781.624194427349, 5811.215485909433, 5396.109641909119, 5406.0, 5406.972965135228, 5443.836684899459, 5468.316460442445, 6297.496200304056, 6349.07938348946, 6355.142055822497, 6355.936440635534, 6361.745530178741, 6160.199174107337, 6164.876702466016, 6177.6911154443, 6228.501719862309, 6266.655333956676, 6206.100115483139, 6290.0, 6360.363963603602, 6369.649669268235, 6374.872502549831, 6203.7828675996125, 6222.81331560054, 6226.533010024175, 6231.158793562992, 6236.438720514994, 5100.38795344561, 5407.351117865881, 5646.88706225865, 6283.057541368831, 6292.339304372975, 6067.0, 6102.908456373069, 6114.66235542843, 6122.204113465399, 6133.457080270946, 6098.613770999398, 6120.142398366865, 6120.9652521188555, 6122.123717501779, 6129.817282374624, 4246.532881383107, 5754.525554011815, 5980.611260268069, 5990.34106248321, 6006.789762358297, 6046.118676075902, 6154.846128846814, 6155.169052177898, 6157.96921015392, 6189.102580125947, 6008.759649613967, 6039.214902062704, 6040.456358927718, 6041.697915104315, 6056.152138700498], "value": 5634.192091085875, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.45775442732, 493.4744497110577, 527.580573444112, 531.7371243297167, 548.6077454619946, 40733.981650458714, 42480.57519424805, 42498.99999999996, 44399.778001109946, 44478.11043779123, 42733.78633106838, 43245.35131973023, 43328.35007474883, 43405.34891976615, 43446.56553434465, 9856.802863942718, 10029.899701002969, 10029.997000299985, 10031.849522257173, 10033.698989030305, 9886.307958442912, 9954.352967057155, 9960.551775170135, 10014.998500149999, 10053.195744340435, 8680.218780309771, 10016.599336026551, 10062.345947513388, 10070.597176112946, 10077.294589378746, 9917.256205784595, 9995.0, 10030.448325342104, 10036.849447258297, 10039.498025098752, 9910.55402506889, 9949.90050099497, 9971.700848974542, 9982.301238913278, 10001.64994225206, 41329.586704132875, 41350.172996540205, 41578.96052598679, 41642.58357416417, 41657.79171104155, 3595.406757884972, 3943.369060950263, 3969.4442778011103, 3972.6424621784176, 4100.671946244323, 31156.844215778776, 31632.841835790903, 32073.839630801696, 32245.0, 32294.677053229403, 3189.521571764231, 3231.5960504936884, 3347.380734564089, 3385.119868834121, 3389.3390788796196, 449693.0, 452912.0, 456077.4392256101, 458254.0, 463292.0, 59369.70315148397, 59751.0, 59814.40185598088, 60010.79978400495, 60103.0, 62202.0, 62558.37441625616, 62601.68699156475, 63050.36949630445, 63079.68460157762, 15806.288717007763, 15974.920125399298, 15993.440229591908, 16044.438444654614, 16065.678686426207, 12442.315672638091, 12549.623511294676, 12551.497940082294, 12558.372081395893, 12558.686032849251, 16087.436939707053, 16498.83501164997, 16563.83436165647, 16697.749533757004, 16840.24218910152, 6618.735250590019, 6631.469482441393, 6828.829279267959, 6892.896606550906, 6902.309769023058, 836.7740710008263, 996.8038353975259, 1024.7540590258434, 1041.7916416716687, 1133.9092872570175, 29634.0, 30078.097657070324, 30705.539416908763, 30917.536236956465, 31040.379192416025, 924.2698268368049, 977.53567055648, 1035.699647102347, 1045.6340280901622, 1105.4638500327335, 13882.09766365183, 15705.450309239122, 15719.97820141687, 15970.680586388207, 16037.759433608506, 677710.0, 680180.0, 685242.0, 686773.0, 687710.0, 58339.0, 58875.0, 58900.70549647224, 58922.70538647279, 59058.40941590615, 1312.7177656803833, 1356.071091302461, 1374.1892283552752, 1379.8206233189621, 1381.5579014715395, 29122.854385727936, 29899.55150672698, 30206.441533110476, 30521.542176866922, 30668.386632267677, 42717.57282427136, 44151.33772993472, 50601.49398505968, 51208.48791512186, 51397.486025140766, 624132.0, 626113.0, 627278.0, 628044.0, 629498.0, 22591.20930767415, 26708.866455667598, 26727.0, 26890.32774180661, 27089.72910270951, 38868.6113138865, 46912.01247893967, 47581.0, 48136.5186348146, 48550.757246214955, 33745.66254337425, 39537.93803355033, 39601.0, 40391.0, 41119.58880411277, 38631.6136838628, 39103.0, 39414.605853941095, 40220.0, 40460.59539404568, 37416.19378546674, 37832.0, 38505.037374065876, 38526.80736596299, 39016.0, 33215.83392083121, 34393.31213375769, 34867.4769878447, 35100.82449587736, 35364.11589710175, 21436.464088397915, 22070.66893996559, 25340.619890701284, 25506.74493255044, 26044.609330860432], "value": 20123.78035416042, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.52466546834427, 374.4776037427787, 379.6146910885451, 383.3463943975522, 403.20367274632605, 19576.90211548944, 20723.481912952182, 20829.06269217885, 21344.8932755336, 21452.785472145275, 20348.491287717814, 20544.0, 20773.584528309424, 21030.369088927317, 21077.36767896962, 5849.151872978416, 5887.735051922674, 6177.196964394622, 6185.969070154665, 6222.284437289724, 4180.28935081037, 4404.339349097629, 6166.506679465632, 6217.689115544227, 6224.097505861648, 5263.394709608406, 5748.885022299552, 5889.998700220951, 6156.907646385308, 6244.281907580641, 6040.335563088057, 6173.444390004899, 6184.938150618504, 6200.441960223579, 6214.384260092364, 5997.0, 6059.45464908156, 6070.423309785563, 6075.635461872302, 6078.422549857756, 19320.42038738826, 21375.893120534452, 24457.877710611512, 24657.506849863083, 24754.752452475605, 2100.0, 2213.225371120095, 2239.4065572623103, 2247.438140464884, 2343.7656234376595, 10143.340682855665, 10509.684709458728, 11260.943695281552, 14934.178620175886, 16096.75854862178, 1642.7761317818183, 1770.5485101299087, 1843.741876137342, 1849.0, 1910.923563057476, 97566.51216743942, 104315.47842260815, 104893.0, 110070.9999999992, 111672.44163779129, 11402.0, 11560.421978901022, 11593.304401735923, 11663.708407289885, 11999.940000299943, 14540.05489643191, 14692.632684182981, 14697.926510367593, 14712.55862324132, 14730.484433044792, 8285.8342833143, 8480.982282126184, 8540.103289154673, 8544.060153383123, 8622.956885215533, 6864.759733409307, 8395.664173432995, 8524.957375213084, 8529.488230706178, 8530.488170709777, 4468.754218518013, 4559.52125026874, 4575.771211439414, 4623.491415944243, 4630.282306242538, 1577.2271586922554, 1871.990640046791, 1933.9709904351446, 1938.951526211856, 1942.300771722179, 819.6680344460503, 835.592027433776, 866.1598249697784, 1012.4026824173773, 1051.0803047333584, 17290.0, 19580.21679132819, 20542.281020164224, 20880.269190578256, 21213.363599092052, 758.6472290384997, 865.8268346330759, 889.4040992534888, 931.4830269200523, 946.2997381937316, 9094.272458203328, 10984.615538456115, 11138.610148644759, 11155.442227888572, 11165.944170279097, 448974.0, 534534.0, 569299.0, 601790.0, 606300.0, 98276.52585211085, 115967.0, 133745.0, 136438.0, 138219.0, 964.9710508684751, 1004.0862814838608, 1020.2093377632394, 1023.81059503992, 1024.0936770957755, 18763.437096887115, 18904.43286701346, 19309.13108910074, 19314.613707725486, 19428.222871085, 52968.73515632397, 53180.0, 54820.0994410171, 55120.16900708339, 55388.41479570673, 59348.40651593602, 78872.21127788878, 80881.0, 81451.0, 81723.18276817394, 7632.580208088609, 7792.649330780242, 7944.735750311133, 8169.2647661710735, 8223.54770487629, 10441.791164176522, 12471.5011399543, 12607.558735444216, 12820.487180512675, 12850.16473929229, 10676.412797296223, 10877.347359158475, 11073.335599864035, 11084.889151108708, 11114.27757195812, 8470.66117355299, 10722.0, 10744.409057501913, 10883.074938629989, 10948.616798412017, 10901.56393744273, 11045.33727976324, 11176.720581985515, 11185.272957258077, 11207.702421003838, 7888.68445262206, 8004.439134368975, 8011.759647210593, 8121.959390203011, 8187.181281871765, 5362.356517217959, 8174.877376839471, 8668.176523230282, 10404.0, 10618.36289822613], "value": 9421.072386666492, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1404.7752359622461, 1457.963550911227, 1499.0855578097362, 1879.605282890593, 1897.3169658922786, 8091.595420228994, 8378.162183781635, 8626.525541095238, 9172.633094676214, 9182.173604375605, 8574.271186949101, 8943.0, 8962.95518522408, 9084.591193396296, 9095.0904909509, 2350.5416443793424, 2391.80865530758, 2394.1381102803066, 2395.4849707312924, 2397.2688330059414, 2358.6579945907833, 2378.500514891875, 2388.22382725614, 2405.230326295586, 2406.3743426709093, 2351.423901144222, 2378.9762102378927, 2388.34320561846, 2398.388410955204, 2411.855288682676, 2339.157903154863, 2339.6256598944087, 2359.8348115631825, 2361.8346715729904, 2364.1016413762745, 2317.5364927014666, 2325.6395258734924, 2339.7894189522854, 2343.1447521654645, 2345.777151170636, 9016.684416045473, 9062.637494500212, 9073.954630226806, 9087.727368178965, 9092.318076144316, 5283.788648454057, 5364.999999999961, 5394.568434525228, 5443.156310771837, 5448.645838020556, 3640.5995340512522, 3647.1246900743895, 3670.1558641512333, 3699.72252081095, 3734.9626503734885, 1064.9627263045757, 1085.9945700271448, 1086.136521465429, 1095.6658219243207, 1098.780243951213, 11914.344711040976, 11963.401829908556, 12026.27842329463, 12069.0344772418, 12105.818412723816, 4693.047858207958, 4737.313089601988, 4748.264019077049, 4879.243717223836, 4903.289023091632, 10222.795544089076, 10262.127719143957, 10287.948560257151, 10305.278630495794, 10313.639022634172, 4670.738900496847, 4997.100521906092, 5064.215046667772, 5166.974165129225, 5442.91835622466, 3856.2480316338047, 4415.66882483815, 4577.908441831145, 4789.425268967746, 4933.975330123327, 3903.8048097595006, 3966.147278335114, 4014.2172276406404, 4044.4337792709043, 4091.8977025574004, 1284.6724085358173, 1293.9158954667917, 1299.330844615024, 1306.0139594606012, 1307.7842156044337, 769.1231995525106, 771.9845603087907, 909.5770466733001, 916.9358144929924, 956.636478138303, 5851.297844258716, 5897.32180799205, 5997.250343707036, 6049.455549000611, 6108.205933228652, 438.15873522836233, 438.2637169555178, 445.857325655787, 448.5514485514486, 451.1878618486711, 4001.579834117434, 4004.979975100106, 4070.9796451017555, 4119.608637179464, 4158.812853421603, 10636.574537018587, 10868.347899126078, 10987.175961802897, 10998.670039898816, 11029.44852757359, 10944.124470042536, 10947.124230061576, 10953.0, 10961.725956850983, 10983.780324393467, 7827.726029588937, 8235.547044912468, 8342.83314333722, 8391.874121888177, 8441.240288374076, 6003.6397816131175, 6030.909536357046, 6065.211522502046, 6080.0, 6206.441420272197, 10108.54511546997, 10296.970302969643, 10345.431001295, 10368.740781480523, 10412.739681508023, 10235.437050961971, 10327.793444131226, 10345.120664743281, 10373.844392333971, 10576.41829699374, 7041.366277035091, 7283.635818209174, 7453.440991925736, 7584.734534291494, 7802.024746906637, 8459.280961118375, 8658.350623703373, 8669.653213871374, 8686.609102590524, 8687.174718401742, 7487.925120748723, 7582.35549978258, 7627.656755445907, 7736.806579835324, 7746.419018573517, 6222.782202622887, 7498.212687667717, 7517.909903063915, 7667.0416197975255, 7678.232176782501, 6949.4440444765305, 7090.361867431956, 7106.609136497335, 7110.075690160453, 7125.857482850418, 4802.73584952831, 4963.975180124076, 5056.494350564914, 5069.214271787879, 5126.436092029949, 5062.0, 5148.845534633967, 5211.400688920823, 5215.608829337739, 5227.89544209106], "value": 4556.036415020961, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [276.8975479072743, 306.2298319725889, 313.4248653720423, 314.4669784714909, 317.22596863652683, 3998.1603863188766, 5228.712420816853, 5542.778288868445, 5543.528800051995, 5624.268845050148, 4786.787626888334, 5192.55863251624, 5314.973425132878, 5322.733863306838, 5412.56699464043, 4134.904250373661, 4160.625543701066, 4176.143890502455, 4181.393697913801, 4187.434696316004, 3904.2191561687778, 4155.252054630166, 4172.311568591179, 4186.434831297782, 4190.3923931029985, 3446.6036405813275, 4100.0, 4149.83400663973, 4155.58444155585, 4178.937315940263, 3406.8977930662118, 3847.61523847613, 4131.607497287753, 4139.275626765304, 4154.750714957097, 3149.685031496855, 3606.7294952878638, 3609.682465899954, 3613.7831730096277, 3620.8594292797948, 4376.606105450493, 4809.855704328876, 5270.367555893279, 5305.3898801637915, 5326.9467305327225, 4125.608067233608, 4157.3140431828715, 4452.443444569429, 4498.820047198108, 4719.126961512097, 4561.201789686792, 4597.471290801567, 4813.566778989873, 4820.661978940211, 5840.182374467578, 3226.596675415573, 3770.604086570898, 3880.805959702032, 3919.510061242345, 3954.703397245218, 4793.640476964241, 6015.879682406327, 6068.362821903681, 6128.233970753656, 6132.908006379907, 3217.742580593547, 3701.3522633539023, 3943.960560394359, 4005.8783540608674, 4042.4138499917935, 3759.830807613664, 3999.1403997140887, 4042.15114825884, 4050.1292222171787, 4057.411675307123, 4720.598749106364, 4723.149833030087, 4723.504032076651, 4724.574788269072, 4742.478327383985, 5836.241288632451, 5843.620164689281, 5898.0, 5918.852028699317, 5933.525317974551, 1293.230527835936, 1327.6813564744584, 1330.846952600444, 1351.5269655620455, 1352.6280272925023, 872.9214370706667, 899.1727610598265, 921.1249313152398, 923.6351641101727, 928.7213835849218, 4521.4574251089425, 4575.7483338416705, 5424.294841670637, 5454.563634909197, 5456.863578410492, 5173.4826517347965, 5597.412271711493, 5915.799782154632, 5927.9407205928255, 5944.316403613553, 4484.573965473275, 5206.635535512478, 5406.486183868523, 5420.566354691615, 5427.43011983744, 2724.986375068112, 2887.805267996338, 3087.8919237826567, 3155.9900831734085, 3191.457452233126, 5474.7262636868, 5605.607607467438, 5646.830595082154, 5777.740001699934, 5925.318588362307, 5830.416958304136, 5945.910811337833, 6092.969535152296, 6131.724072416752, 6152.2924863640355, 5377.731113444391, 6219.222597175443, 6225.719842607094, 6258.624482530971, 6271.0, 5409.24199635128, 5923.49650279731, 5935.970320148372, 5962.6609098904755, 5995.880082398415, 4959.239130434823, 5877.529797616266, 5939.732712028055, 5950.196723442453, 5975.910361344497, 5733.967885780599, 5995.040793473024, 6126.264848218242, 6270.056282552241, 6295.135713250552, 3576.4277715565386, 3836.060165259529, 3941.940870886882, 3970.3647416413237, 4005.6595189409227, 5780.855478613068, 5992.43071908168, 6038.5772995889865, 6062.393760623902, 6095.847603809941, 5140.43455219918, 5448.754806033818, 5468.26178465902, 5790.884182316414, 5812.360640329645, 5090.567301779242, 5110.642255042111, 5810.82567522975, 5835.416458354301, 5852.385499522489, 4450.2186133666455, 5565.55475561946, 5742.913856292243, 5745.9425405745405, 5809.605694633173, 4908.901821963469, 5055.988802239493, 5306.363236411501, 5889.146073819358, 5903.645781253139, 5683.328835447333, 5709.914351284651, 5722.513586345207, 5756.683382414008, 5767.711614419179], "value": 4174.876354593172, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.3932947999981, 703.8064532253633, 717.5443593318244, 737.2701025984277, 783.2911215350107, 14861.40554377825, 15418.845811541882, 15899.205039748009, 16023.11872846993, 16054.919725401385, 13671.521496747615, 13866.4453421863, 13941.651458713537, 13948.372323245452, 13965.231912244823, 849.9490030598153, 869.0310304011014, 877.3682948277262, 877.6489404238324, 879.0242830458186, 691.9688614012382, 707.1266985273184, 848.6223630484437, 861.1861790607878, 871.298604623278, 901.4906577783531, 914.3279689428265, 914.4558987402485, 916.3036092569629, 916.5142474488532, 814.3851392198911, 904.4980036079972, 906.5875026862761, 909.4725059465494, 910.0535443139161, 562.9859253518654, 575.4073304496334, 659.66686823154, 680.5236334565774, 686.9793906182822, 14345.928270358685, 14487.3480693368, 14517.637059073608, 14521.0, 14547.78178327326, 14318.64203394913, 14500.0, 14567.344469498898, 14847.0, 14872.405103795834, 10054.296199266055, 10058.597656093747, 11779.352135632536, 11847.703807404798, 12251.264924104582, 3768.0, 3810.2569998850236, 3842.9615703842883, 3843.6925045996254, 3976.4234186042863, 124339.51320973621, 125032.37483812614, 129012.70987290195, 129072.35463822715, 130028.34985825105, 11045.613403530999, 11108.16688748347, 11178.832317515244, 11181.944090279496, 11375.829362559569, 22524.662130068064, 22614.434639134153, 22651.88674056619, 22656.773432265796, 22702.772972270395, 7794.493357931717, 7849.293563579306, 7891.0, 8098.473599216032, 8108.1891810819625, 3973.8211780469946, 4168.478940132483, 4283.164782867311, 4292.356146577976, 4314.827406903752, 3553.129483276614, 3695.2055308108884, 3696.537932758405, 3700.5374328208973, 3704.9073773155346, 658.8649326887977, 659.8911179655303, 666.3969107957306, 700.3906601256939, 719.2735337309355, 595.2351228671137, 638.9712462939179, 673.5655502201053, 674.1202730436827, 688.8725585766649, 8894.0, 9494.28792840526, 9516.666916658021, 9624.485647959009, 9764.462954537426, 584.5294537896949, 650.8340373204802, 670.6345041952147, 670.9329067093252, 699.4928676709446, 4417.6826541866485, 4616.7765542131365, 4672.976635116871, 4774.618030557547, 4801.03699232643, 141622.2918885399, 146376.0, 149981.25009374882, 158077.0, 162356.0, 127794.0, 134245.0, 136232.0, 137260.0, 139061.0, 96940.0305996931, 105557.47221263999, 114490.0, 116883.0, 119427.0, 7710.498817576839, 7922.0889597694695, 8142.59287035634, 8684.305255579664, 9096.681616143404, 54924.45075549193, 57233.0, 63932.68033659988, 66755.99732008048, 67321.66339168273, 91015.54492227496, 92783.53608231916, 94245.5287723557, 96170.0382996161, 97502.51248743711, 11711.1802173847, 11731.530738770354, 12109.394530273275, 12156.939215303866, 12207.69480762988, 12798.0, 13009.739805204032, 13037.087403881636, 13067.994361297146, 13108.279044652254, 11899.46452409661, 12069.0, 12149.574764883188, 12200.389980501117, 12259.0, 11847.52609895629, 11997.520099195934, 12101.273923564615, 12406.255624662188, 12573.182743121668, 11608.6517404478, 11956.282623042645, 12276.386180691108, 12348.938255308665, 12468.688282793002, 9147.542622868963, 9543.711598934055, 9952.7108764983, 10090.231385433899, 10435.895641043797, 9025.962014368431, 9320.0, 10267.0, 10436.739081523023, 10536.894631053592], "value": 7692.7264138905975, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1556.0999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 118.0, 117.0, 117.0, 119.0, 114.0, 117.0, 117.0, 116.0, 119.0], "value": 116.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 115.0, 117.0, 152.0, 114.0, 117.0, 115.0, 115.0, 142.0, 113.0], "value": 124.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 212.0, 241.0, 221.0, 201.0, 194.0, 196.0, 196.0, 197.0, 192.0], "value": 204.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 58.0, 59.0, 87.0, 59.0, 59.0, 58.0, 58.0, 81.0, 59.0], "value": 66.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 74.0, 84.0, 75.0, 99.0, 82.0, 90.0, 70.0, 69.0, 87.0], "value": 81.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 125.0, 79.0, 116.0, 137.0, 127.0, 90.0, 88.0, 90.0, 87.0], "value": 105.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 100.0, 96.0, 100.0, 154.0, 152.0, 97.0, 97.0, 97.0, 96.0], "value": 108.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 79.0, 151.0, 99.0, 94.0, 112.0, 108.0, 76.0, 132.0, 78.0], "value": 103.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 56.0, 57.0, 57.0, 58.0, 56.0, 59.0, 52.0, 50.0], "value": 61.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 79.0, 72.0, 92.0, 96.0, 70.0, 69.0, 70.0, 70.0], "value": 75.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 85.0, 85.0, 92.0, 92.0, 77.0, 88.0, 98.0, 105.0], "value": 89.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 143.0, 163.0, 155.0, 168.0, 150.0, 148.0, 161.0, 209.0, 192.0], "value": 165.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 188.0, 176.0, 175.0, 175.0, 182.0, 238.0, 202.0, 175.0, 172.0], "value": 185.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 69.0, 70.0, 71.0, 68.0, 70.0, 68.0, 67.0, 68.0], "value": 69.1, "unit": "score"}]}]} 21:46:30 INFO - Return code: 0 21:46:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:46:30 ERROR - # TBPL SUCCESS # 21:46:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:46:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:46:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:46:30 INFO - 2016-10-10 21:46:30.380 screenresolution[1676:10530] starting screenresolution argv=screenresolution get 21:46:30 INFO - 2016-10-10 21:46:30.383 screenresolution[1676:10530] Display 0: 1600x1200x32@60 21:46:30 INFO - 2016-10-10 21:46:30.390 screenresolution[1677:10533] starting screenresolution argv=screenresolution list 21:46:30 INFO - Available Modes on Display 0 21:46:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:46:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:46:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:46:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:46:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:46:30 INFO - Intel Iris: 21:46:30 INFO - Chipset Model: Intel Iris 21:46:30 INFO - Type: GPU 21:46:30 INFO - Bus: Built-In 21:46:30 INFO - VRAM (Dynamic, Max): 1536 MB 21:46:30 INFO - Vendor: Intel (0x8086) 21:46:30 INFO - Device ID: 0x0a2e 21:46:30 INFO - Revision ID: 0x0009 21:46:30 INFO - Displays: 21:46:30 INFO - Display: 21:46:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:46:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:46:30 INFO - Display Serial Number: 1600x1200 60 21:46:30 INFO - Main Display: Yes 21:46:30 INFO - Mirror: Off 21:46:30 INFO - Online: Yes 21:46:30 INFO - Rotation: Supported 21:46:30 INFO - Return code: 0 21:46:30 INFO - Running post-action listener: _resource_record_post_action 21:46:30 INFO - [mozharness: 2016-10-11 04:46:30.626067Z] Finished run-tests step (success) 21:46:30 INFO - Running post-run listener: _resource_record_post_run 21:46:30 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 39193088; Write bytes: 709252096; Read time: 384; Write time: 1865 21:46:30 INFO - TinderboxPrint: CPU usage
54.4% 21:46:30 INFO - TinderboxPrint: I/O read bytes / time
39,193,088 / 384 21:46:30 INFO - TinderboxPrint: I/O write bytes / time
709,252,096 / 1,865 21:46:30 INFO - TinderboxPrint: CPU idle
2,208.3 (45.6%) 21:46:30 INFO - TinderboxPrint: CPU system
975.6 (20.2%) 21:46:30 INFO - TinderboxPrint: CPU user
1,657.8 (34.2%) 21:46:30 INFO - TinderboxPrint: Swap in / out
447,160,320 / 0 21:46:30 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 387748352; Write bytes: 381138432; Read time: 29234; Write time: 674 21:46:30 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 38193664; Write bytes: 299519488; Read time: 366; Write time: 1134 21:46:31 INFO - Running post-run listener: _upload_blobber_files 21:46:31 INFO - Blob upload gear active. 21:46:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:46:31 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 21:46:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:46:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:46:31 INFO - (blobuploader) - INFO - Open directory for files ... 21:46:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:46:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:46:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:46:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:46:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:46:32 INFO - (blobuploader) - INFO - Done attempting. 21:46:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:46:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:46:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:46:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:46:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:46:33 INFO - (blobuploader) - INFO - Done attempting. 21:46:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:46:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:46:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:46:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:46:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:46:36 INFO - (blobuploader) - INFO - Done attempting. 21:46:36 INFO - (blobuploader) - INFO - Iteration through files over. 21:46:36 INFO - Return code: 0 21:46:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:46:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:46:36 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8653a544958b0802d2efe309eb454a673c79e3f91b7a8e31a7e314e3ad779f6889cb52ad9196d09144de158aec49af4c1e49b0d514f74979e2573df1a728779", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd2f92309f5113f7982ab6ab843b8ffc3660014c07fb8e5cab9a0bb5e4d52923048c92d4fa162e563d1c338c61c7ffa399f4754601b08d6548e8f3a130b53824", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3320a54f8d4385da724629c8f057f7b4b10739665e3a13ab17bff29e0247906fc52557791f6191603ff8dd2f8b6de5e8444c267befc95abc839a3a083c4e8aa"} 21:46:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:46:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:46:36 INFO - Contents: 21:46:36 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8653a544958b0802d2efe309eb454a673c79e3f91b7a8e31a7e314e3ad779f6889cb52ad9196d09144de158aec49af4c1e49b0d514f74979e2573df1a728779", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd2f92309f5113f7982ab6ab843b8ffc3660014c07fb8e5cab9a0bb5e4d52923048c92d4fa162e563d1c338c61c7ffa399f4754601b08d6548e8f3a130b53824", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3320a54f8d4385da724629c8f057f7b4b10739665e3a13ab17bff29e0247906fc52557791f6191603ff8dd2f8b6de5e8444c267befc95abc839a3a083c4e8aa"} 21:46:36 INFO - Running post-run listener: copy_logs_to_upload_dir 21:46:36 INFO - Copying logs to upload dir... 21:46:36 INFO - mkdir: /builds/slave/test/build/upload/logs 21:46:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.729908 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-10-10 21:46:36.528715) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-10 21:46:36.536405) ========= 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.IjQhlu0EdE/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.YirgoVMGm9/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8653a544958b0802d2efe309eb454a673c79e3f91b7a8e31a7e314e3ad779f6889cb52ad9196d09144de158aec49af4c1e49b0d514f74979e2573df1a728779", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd2f92309f5113f7982ab6ab843b8ffc3660014c07fb8e5cab9a0bb5e4d52923048c92d4fa162e563d1c338c61c7ffa399f4754601b08d6548e8f3a130b53824", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3320a54f8d4385da724629c8f057f7b4b10739665e3a13ab17bff29e0247906fc52557791f6191603ff8dd2f8b6de5e8444c267befc95abc839a3a083c4e8aa"} build_url:https://queue.taskcluster.net/v1/task/IEg2HFWiTwuWpe4SYJAoLA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011099 build_url: 'https://queue.taskcluster.net/v1/task/IEg2HFWiTwuWpe4SYJAoLA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8653a544958b0802d2efe309eb454a673c79e3f91b7a8e31a7e314e3ad779f6889cb52ad9196d09144de158aec49af4c1e49b0d514f74979e2573df1a728779", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd2f92309f5113f7982ab6ab843b8ffc3660014c07fb8e5cab9a0bb5e4d52923048c92d4fa162e563d1c338c61c7ffa399f4754601b08d6548e8f3a130b53824", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3320a54f8d4385da724629c8f057f7b4b10739665e3a13ab17bff29e0247906fc52557791f6191603ff8dd2f8b6de5e8444c267befc95abc839a3a083c4e8aa"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-10 21:46:36.571308) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 21:46:36.571620) ========= 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.IjQhlu0EdE/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.YirgoVMGm9/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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.005265 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 21:46:36.619409) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-10 21:46:36.619679) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-10 21:46:36.619969) ========= ========= Total master_lag: 0.20 =========