builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0113 starttime: 1475816948.42 results: success (0) buildid: 20161006200000 builduid: 895c262294e349148de11aaeead40d0a revision: 4b9944879c9a60a9aba4a744a7401bc38e0f39c4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-06 22:09:08.424319) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-06 22:09:08.424841) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-06 22:09:08.425169) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-06 22:09:08.450506) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 22:09:08.450937) ========= 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.Ut9yHJUrjq/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.wFJmWjRKjh/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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-06 22:09:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 154M=0s 2016-10-06 22:09:08 (154 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.103047 ========= master_lag: 0.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 22:09:08.673881) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 22:09:08.674203) ========= 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.Ut9yHJUrjq/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.wFJmWjRKjh/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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.029690 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 22:09:08.724387) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-06 22:09:08.724820) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4b9944879c9a60a9aba4a744a7401bc38e0f39c4 --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 4b9944879c9a60a9aba4a744a7401bc38e0f39c4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ut9yHJUrjq/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.wFJmWjRKjh/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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-06 22:09:08,801 truncating revision to first 12 chars 2016-10-06 22:09:08,801 Setting DEBUG logging. 2016-10-06 22:09:08,801 attempt 1/10 2016-10-06 22:09:08,801 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4b9944879c9a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-06 22:09:09,644 unpacking tar archive at: mozilla-central-4b9944879c9a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.113007 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-06 22:09:09.863805) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-06 22:09:09.864143) ========= script_repo_revision: 4b9944879c9a60a9aba4a744a7401bc38e0f39c4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-06 22:09:09.864575) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-06 22:09:09.864850) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-06 22:09:09.882912) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-06 22:09:09.883201) ========= /tools/buildbot/bin/python 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', '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.Ut9yHJUrjq/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.wFJmWjRKjh/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 22:09:10 INFO - MultiFileLogger online at 20161006 22:09:10 in /builds/slave/test 22:09:10 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 22:09:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:09:10 INFO - {'append_to_log': False, 22:09:10 INFO - 'base_work_dir': '/builds/slave/test', 22:09:10 INFO - 'blob_upload_branch': 'Firefox', 22:09:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:09:10 INFO - 'branch': 'Firefox', 22:09:10 INFO - 'buildbot_json_path': 'buildprops.json', 22:09:10 INFO - 'config_files': ('talos/mac_config.py',), 22:09:10 INFO - 'default_actions': ('clobber', 22:09:10 INFO - 'read-buildbot-config', 22:09:10 INFO - 'download-and-extract', 22:09:10 INFO - 'populate-webroot', 22:09:10 INFO - 'create-virtualenv', 22:09:10 INFO - 'install', 22:09:10 INFO - 'run-tests'), 22:09:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:09:10 INFO - 'download_minidump_stackwalk': True, 22:09:10 INFO - 'download_symbols': 'ondemand', 22:09:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:09:10 INFO - 'tooltool.py': '/tools/tooltool.py', 22:09:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:09:10 INFO - '/tools/misc-python/virtualenv.py')}, 22:09:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:09:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:09:10 INFO - 'installer_path': 'installer.exe', 22:09:10 INFO - 'log_level': 'info', 22:09:10 INFO - 'log_name': 'talos', 22:09:10 INFO - 'log_to_console': True, 22:09:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:09:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:09:10 INFO - 'opt_config_files': (), 22:09:10 INFO - 'pip_index': False, 22:09:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:09:10 INFO - 'cmd': ('bash', 22:09:10 INFO - '-c', 22:09:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:09:10 INFO - 'enabled': True, 22:09:10 INFO - 'halt_on_failure': False, 22:09:10 INFO - 'name': 'check_screen_resolution'},), 22:09:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:09:10 INFO - 'cmd': ('bash', 22:09:10 INFO - '-c', 22:09:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:09:10 INFO - 'enabled': True, 22:09:10 INFO - 'halt_on_failure': False, 22:09:10 INFO - 'name': 'check_screen_resolution'},), 22:09:10 INFO - 'run_cmd_checks_enabled': True, 22:09:10 INFO - 'sps_profile': False, 22:09:10 INFO - 'sps_profile_interval': 0, 22:09:10 INFO - 'suite': 'dromaeojs-e10s', 22:09:10 INFO - 'system_bits': '32', 22:09:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:09:10 INFO - 'title': 't-yosemite-r7-0113', 22:09:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:09:10 INFO - 'use_talos_json': True, 22:09:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:09:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:09:10 INFO - 'work_dir': 'build'} 22:09:10 INFO - [mozharness: 2016-10-07 05:09:10.085154Z] Running clobber step. 22:09:10 INFO - Running pre-action listener: _resource_record_pre_action 22:09:10 INFO - Running main action method: clobber 22:09:10 INFO - rmtree: /builds/slave/test/build 22:09:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:09:12 INFO - Running post-action listener: _resource_record_post_action 22:09:12 INFO - [mozharness: 2016-10-07 05:09:12.312562Z] Finished clobber step (success) 22:09:12 INFO - [mozharness: 2016-10-07 05:09:12.312755Z] Running read-buildbot-config step. 22:09:12 INFO - Running pre-action listener: _resource_record_pre_action 22:09:12 INFO - Running main action method: read_buildbot_config 22:09:12 INFO - Using buildbot properties: 22:09:12 INFO - { 22:09:12 INFO - "project": "", 22:09:12 INFO - "product": "firefox", 22:09:12 INFO - "script_repo_revision": "production", 22:09:12 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 22:09:12 INFO - "repository": "", 22:09:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 22:09:12 INFO - "buildid": "20161006200000", 22:09:12 INFO - "pgo_build": "False", 22:09:12 INFO - "basedir": "/builds/slave/test", 22:09:12 INFO - "buildnumber": 11, 22:09:12 INFO - "slavename": "t-yosemite-r7-0113", 22:09:12 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 22:09:12 INFO - "platform": "yosemite_r7", 22:09:12 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 22:09:12 INFO - "branch": "mozilla-central", 22:09:12 INFO - "repo_path": "mozilla-central", 22:09:12 INFO - "revision": "4b9944879c9a60a9aba4a744a7401bc38e0f39c4", 22:09:12 INFO - "stage_platform": "macosx64", 22:09:12 INFO - "builduid": "895c262294e349148de11aaeead40d0a", 22:09:12 INFO - "slavebuilddir": "test" 22:09:12 INFO - } 22:09:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/KIjDUPkITZyWK8ev6u8BXw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 22:09:12 INFO - Running post-action listener: _resource_record_post_action 22:09:12 INFO - [mozharness: 2016-10-07 05:09:12.318179Z] Finished read-buildbot-config step (success) 22:09:12 INFO - [mozharness: 2016-10-07 05:09:12.318384Z] Running download-and-extract step. 22:09:12 INFO - Running pre-action listener: _resource_record_pre_action 22:09:12 INFO - Running main action method: download_and_extract 22:09:12 INFO - mkdir: /builds/slave/test/build/tests 22:09: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')]} 22:09:12 INFO - trying https://queue.taskcluster.net/v1/task/KIjDUPkITZyWK8ev6u8BXw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 22:09:12 INFO - Downloading https://queue.taskcluster.net/v1/task/KIjDUPkITZyWK8ev6u8BXw/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 22:09:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KIjDUPkITZyWK8ev6u8BXw/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 22:09:13 INFO - Downloaded 1199 bytes. 22:09:13 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 22:09:13 INFO - Using the following test package requirements: 22:09:13 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 22:09:13 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:09:13 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 22:09:13 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:09:13 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 22:09:13 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:09:13 INFO - u'jsshell-mac.zip'], 22:09:13 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:09:13 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 22:09:13 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 22:09:13 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:09:13 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 22:09:13 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:09:13 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 22:09:13 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:09:13 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 22:09:13 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:09:13 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 22:09:13 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:09:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KIjDUPkITZyWK8ev6u8BXw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 22:09:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KIjDUPkITZyWK8ev6u8BXw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:09:13 INFO - Fetch https://queue.taskcluster.net/v1/task/KIjDUPkITZyWK8ev6u8BXw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 22:09:14 INFO - Http code: 200 22:09:14 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 22:09:14 INFO - x-amz-cf-id: oYKAIKhTJbxraIDjPTaLQN1ztuKRS9BpoIqnhJE02Sznf1Z3hdhniQ== 22:09:14 INFO - x-amz-version-id: Tm3V0_YDR0fEte5KFb2C9mFT9YooHfhd 22:09:14 INFO - Expected file size: 16482409 22:09:14 INFO - Obtained file size: 16482409 22:09:21 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 22:09:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KIjDUPkITZyWK8ev6u8BXw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 22:09:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KIjDUPkITZyWK8ev6u8BXw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:09:21 INFO - Fetch https://queue.taskcluster.net/v1/task/KIjDUPkITZyWK8ev6u8BXw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 22:09:22 INFO - Http code: 200 22:09:22 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 22:09:22 INFO - x-amz-cf-id: 8sM-UaHNYdMaLrnLmRgbXzjuVWQu63othxujRjz1dfun4SLVZryveA== 22:09:22 INFO - x-amz-version-id: Tm3V0_YDR0fEte5KFb2C9mFT9YooHfhd 22:09:22 INFO - Expected file size: 16482409 22:09:22 INFO - Obtained file size: 16482409 22:09:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KIjDUPkITZyWK8ev6u8BXw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 22:09:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KIjDUPkITZyWK8ev6u8BXw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:09:29 INFO - Fetch https://queue.taskcluster.net/v1/task/KIjDUPkITZyWK8ev6u8BXw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 22:09:29 INFO - Http code: 200 22:09:29 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 22:09:29 INFO - x-amz-cf-id: MqRV-GrZLr__9kuiR-M6TZxFA9P0XXg8F-nL9TEMrlDWDnpXorNrUQ== 22:09:29 INFO - x-amz-version-id: pQH5xjVYEbOjJi_g7lUqnmLDOEAPTKO5 22:09:30 INFO - Expected file size: 14409006 22:09:30 INFO - Obtained file size: 14409006 22:09:30 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')]} 22:09:30 INFO - trying https://queue.taskcluster.net/v1/task/KIjDUPkITZyWK8ev6u8BXw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:09:30 INFO - Downloading https://queue.taskcluster.net/v1/task/KIjDUPkITZyWK8ev6u8BXw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 22:09:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KIjDUPkITZyWK8ev6u8BXw/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 22:09:33 INFO - Downloaded 111739803 bytes. 22:09:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KIjDUPkITZyWK8ev6u8BXw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:09:33 INFO - mkdir: /builds/slave/test/properties 22:09:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:09:33 INFO - Writing to file /builds/slave/test/properties/build_url 22:09:33 INFO - Contents: 22:09:33 INFO - build_url:https://queue.taskcluster.net/v1/task/KIjDUPkITZyWK8ev6u8BXw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:09:34 INFO - Running post-action listener: _resource_record_post_action 22:09:34 INFO - Running post-action listener: set_extra_try_arguments 22:09:34 INFO - [mozharness: 2016-10-07 05:09:34.156540Z] Finished download-and-extract step (success) 22:09:34 INFO - [mozharness: 2016-10-07 05:09:34.156688Z] Running populate-webroot step. 22:09:34 INFO - Running pre-action listener: _resource_record_pre_action 22:09:34 INFO - Running main action method: populate_webroot 22:09:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:09:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:09:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:09:34 INFO - '--tppagecycles', 22:09:34 INFO - '7'], 22:09:34 INFO - 'tests': ['tsvgm']}}, 22:09:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:09:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:09:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:09:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:09:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:09:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:09:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:34 INFO - 'talos_options': ['--disable-e10s'], 22:09:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:09:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:09:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:34 INFO - 'talos_options': ['--disable-e10s'], 22:09:34 INFO - 'tests': ['damp', 'tps']}, 22:09:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:34 INFO - 'tests': ['damp', 'tps']}, 22:09:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:09:34 INFO - 'tests': ['dromaeo_dom']}, 22:09:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:09:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:09:34 INFO - 'tests': ['basic_compositor_video']}, 22:09:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:09:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:09:34 INFO - 'tests': ['a11yr', 22:09:34 INFO - 'ts_paint', 22:09:34 INFO - 'tpaint', 22:09:34 INFO - 'sessionrestore', 22:09:34 INFO - 'sessionrestore_no_auto_restore', 22:09:34 INFO - 'tabpaint']}, 22:09:34 INFO - 'other-e10s': {'tests': ['a11yr', 22:09:34 INFO - 'ts_paint', 22:09:34 INFO - 'tpaint', 22:09:34 INFO - 'sessionrestore', 22:09:34 INFO - 'sessionrestore_no_auto_restore', 22:09:34 INFO - 'tabpaint']}, 22:09:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:09:34 INFO - 'ts_paint', 22:09:34 INFO - 'tpaint', 22:09:34 INFO - 'sessionrestore', 22:09:34 INFO - 'sessionrestore_no_auto_restore', 22:09:34 INFO - 'tabpaint']}, 22:09:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:09:34 INFO - 'ts_paint', 22:09:34 INFO - 'tpaint', 22:09:34 INFO - 'sessionrestore', 22:09:34 INFO - 'sessionrestore_no_auto_restore', 22:09:34 INFO - 'tabpaint']}, 22:09:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:09:34 INFO - 'tests': ['a11yr', 22:09:34 INFO - 'ts_paint', 22:09:34 INFO - 'tpaint', 22:09:34 INFO - 'sessionrestore', 22:09:34 INFO - 'sessionrestore_no_auto_restore', 22:09:34 INFO - 'tabpaint']}, 22:09:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:09:34 INFO - 'tests': ['a11yr', 22:09:34 INFO - 'ts_paint', 22:09:34 INFO - 'tpaint', 22:09:34 INFO - 'sessionrestore', 22:09:34 INFO - 'sessionrestore_no_auto_restore', 22:09:34 INFO - 'tabpaint']}, 22:09:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:09:34 INFO - 'tests': ['tsvgx', 22:09:34 INFO - 'tsvgr_opacity', 22:09:34 INFO - 'tart', 22:09:34 INFO - 'tscrollx', 22:09:34 INFO - 'cart']}, 22:09:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:09:34 INFO - 'tsvgr_opacity', 22:09:34 INFO - 'tart', 22:09:34 INFO - 'tscrollx', 22:09:34 INFO - 'cart']}, 22:09:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:34 INFO - 'talos_options': ['--disable-e10s'], 22:09:34 INFO - 'tests': ['tp5o']}, 22:09:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:34 INFO - 'tests': ['tp5o']}, 22:09:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:34 INFO - 'talos_options': ['--disable-e10s', 22:09:34 INFO - '--xperf_path', 22:09:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:09:34 INFO - 'tests': ['tp5n']}, 22:09:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:34 INFO - 'talos_options': ['--xperf_path', 22:09:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:09:34 INFO - 'tests': ['tp5n']}}, 22:09:34 INFO - 'talos.zip': {'path': '', 22:09:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:09:34 INFO - Running post-action listener: _resource_record_post_action 22:09:34 INFO - [mozharness: 2016-10-07 05:09:34.175500Z] Finished populate-webroot step (success) 22:09:34 INFO - [mozharness: 2016-10-07 05:09:34.175624Z] Running create-virtualenv step. 22:09:34 INFO - Running pre-action listener: _resource_record_pre_action 22:09:34 INFO - Running main action method: create_virtualenv 22:09:34 INFO - Creating virtualenv /builds/slave/test/build/venv 22:09:34 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 22:09:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:09:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:09:34 INFO - Using real prefix '/tools/python27' 22:09:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:09:35 INFO - Installing distribute.............................................................................................................................................................................................done. 22:09:37 INFO - Installing pip.................done. 22:09:37 INFO - Return code: 0 22:09:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:09:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:09:38 INFO - Reading from file tmpfile_stdout 22:09:38 INFO - Output received: 22:09:38 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) 22:09:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:09:38 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')]} 22:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:38 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')]} 22:09:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:38 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 0x1049ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9b569bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104813d30>, '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 0x104639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10480bb20>, '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.Ut9yHJUrjq/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.wFJmWjRKjh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 22:09:38 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 22:09:38 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 22:09:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ut9yHJUrjq/Render', 22:09:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:38 INFO - 'HOME': '/Users/cltbld', 22:09:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:38 INFO - 'LOGNAME': 'cltbld', 22:09:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:38 INFO - 'MOZ_NO_REMOTE': '1', 22:09:38 INFO - 'NO_EM_RESTART': '1', 22:09:38 INFO - 'PAGER': '/bin/cat', 22:09:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:38 INFO - 'PWD': '/builds/slave/test', 22:09:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:38 INFO - 'SHELL': '/bin/bash', 22:09:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wFJmWjRKjh/Listeners', 22:09:38 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 22:09:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:38 INFO - 'USER': 'cltbld', 22:09:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:38 INFO - 'XPC_FLAGS': '0x0', 22:09:38 INFO - 'XPC_SERVICE_NAME': '0', 22:09:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:38 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 22:09:38 INFO - Cleaning up... 22:09:38 INFO - Return code: 0 22:09:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:09:38 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')]} 22:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:38 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')]} 22:09:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:38 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 0x1049ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9b569bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104813d30>, '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 0x104639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10480bb20>, '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.Ut9yHJUrjq/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.wFJmWjRKjh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 22:09:38 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 22:09:38 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 22:09:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ut9yHJUrjq/Render', 22:09:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:38 INFO - 'HOME': '/Users/cltbld', 22:09:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:38 INFO - 'LOGNAME': 'cltbld', 22:09:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:38 INFO - 'MOZ_NO_REMOTE': '1', 22:09:38 INFO - 'NO_EM_RESTART': '1', 22:09:38 INFO - 'PAGER': '/bin/cat', 22:09:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:38 INFO - 'PWD': '/builds/slave/test', 22:09:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:38 INFO - 'SHELL': '/bin/bash', 22:09:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wFJmWjRKjh/Listeners', 22:09:38 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 22:09:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:38 INFO - 'USER': 'cltbld', 22:09:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:38 INFO - 'XPC_FLAGS': '0x0', 22:09:38 INFO - 'XPC_SERVICE_NAME': '0', 22:09:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:38 INFO - Downloading/unpacking psutil>=3.1.1 22:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:09:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:09:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:09:42 INFO - Installing collected packages: psutil 22:09:42 INFO - Running setup.py install for psutil 22:09:42 INFO - building 'psutil._psutil_osx' extension 22:09:42 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 22:09:42 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 22:09:42 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 22:09:42 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 22:09:42 INFO - building 'psutil._psutil_posix' extension 22:09:42 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 22:09:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:09:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:09:43 INFO - ^ 22:09:43 INFO - 1 warning generated. 22:09:43 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 22:09:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:09:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:09:43 INFO - Successfully installed psutil 22:09:43 INFO - Cleaning up... 22:09:43 INFO - Return code: 0 22:09:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:09: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')]} 22:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09: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')]} 22:09:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:43 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 0x1049ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9b569bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104813d30>, '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 0x104639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10480bb20>, '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.Ut9yHJUrjq/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.wFJmWjRKjh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 22:09:43 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 22:09:43 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 22:09:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ut9yHJUrjq/Render', 22:09:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:43 INFO - 'HOME': '/Users/cltbld', 22:09:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:43 INFO - 'LOGNAME': 'cltbld', 22:09:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:43 INFO - 'MOZ_NO_REMOTE': '1', 22:09:43 INFO - 'NO_EM_RESTART': '1', 22:09:43 INFO - 'PAGER': '/bin/cat', 22:09:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:43 INFO - 'PWD': '/builds/slave/test', 22:09:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:43 INFO - 'SHELL': '/bin/bash', 22:09:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wFJmWjRKjh/Listeners', 22:09:43 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 22:09:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:43 INFO - 'USER': 'cltbld', 22:09:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:43 INFO - 'XPC_FLAGS': '0x0', 22:09:43 INFO - 'XPC_SERVICE_NAME': '0', 22:09:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:09:43 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 22:09:43 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 22:09:43 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 22:09:43 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 22:09:43 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 22:09:43 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 22:09:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:09:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:09:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:09:46 INFO - Installing collected packages: mozsystemmonitor 22:09:46 INFO - Running setup.py install for mozsystemmonitor 22:09:46 INFO - Successfully installed mozsystemmonitor 22:09:46 INFO - Cleaning up... 22:09:46 INFO - Return code: 0 22:09:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:09:46 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')]} 22:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:46 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')]} 22:09:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:46 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 0x1049ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9b569bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104813d30>, '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 0x104639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10480bb20>, '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.Ut9yHJUrjq/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.wFJmWjRKjh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 22:09:46 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 22:09:46 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 22:09:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ut9yHJUrjq/Render', 22:09:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:46 INFO - 'HOME': '/Users/cltbld', 22:09:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:46 INFO - 'LOGNAME': 'cltbld', 22:09:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:46 INFO - 'MOZ_NO_REMOTE': '1', 22:09:46 INFO - 'NO_EM_RESTART': '1', 22:09:46 INFO - 'PAGER': '/bin/cat', 22:09:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:46 INFO - 'PWD': '/builds/slave/test', 22:09:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:46 INFO - 'SHELL': '/bin/bash', 22:09:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wFJmWjRKjh/Listeners', 22:09:46 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 22:09:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:46 INFO - 'USER': 'cltbld', 22:09:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:46 INFO - 'XPC_FLAGS': '0x0', 22:09:46 INFO - 'XPC_SERVICE_NAME': '0', 22:09:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:46 INFO - Downloading/unpacking jsonschema==2.5.1 22:09:46 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 22:09:46 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 22:09:46 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 22:09:46 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 22:09:46 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 22:09:46 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 22:09:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:09:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:09:49 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 22:09:49 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 22:09:49 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 22:09:49 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 22:09:49 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 22:09:49 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 22:09:49 INFO - Downloading functools32-3.2.3-2.tar.gz 22:09:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:09:49 INFO - warning: no files found matching '*.txt' 22:09:49 INFO - no previously-included directories found matching 'build' 22:09:49 INFO - no previously-included directories found matching 'dist' 22:09:49 INFO - no previously-included directories found matching '.git*' 22:09:49 INFO - Installing collected packages: jsonschema, functools32 22:09:49 INFO - Running setup.py install for functools32 22:09:49 INFO - warning: no files found matching '*.txt' 22:09:49 INFO - no previously-included directories found matching 'build' 22:09:49 INFO - no previously-included directories found matching 'dist' 22:09:49 INFO - no previously-included directories found matching '.git*' 22:09:49 INFO - Successfully installed jsonschema functools32 22:09:49 INFO - Cleaning up... 22:09:49 INFO - Return code: 0 22:09:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:09:49 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')]} 22:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:49 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')]} 22:09:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:49 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 0x1049ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9b569bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104813d30>, '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 0x104639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10480bb20>, '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.Ut9yHJUrjq/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.wFJmWjRKjh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 22:09:49 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 22:09:49 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 22:09:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ut9yHJUrjq/Render', 22:09:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:49 INFO - 'HOME': '/Users/cltbld', 22:09:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:49 INFO - 'LOGNAME': 'cltbld', 22:09:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:49 INFO - 'MOZ_NO_REMOTE': '1', 22:09:49 INFO - 'NO_EM_RESTART': '1', 22:09:49 INFO - 'PAGER': '/bin/cat', 22:09:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:49 INFO - 'PWD': '/builds/slave/test', 22:09:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:49 INFO - 'SHELL': '/bin/bash', 22:09:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wFJmWjRKjh/Listeners', 22:09:49 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 22:09:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:49 INFO - 'USER': 'cltbld', 22:09:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:49 INFO - 'XPC_FLAGS': '0x0', 22:09:49 INFO - 'XPC_SERVICE_NAME': '0', 22:09:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:09:50 INFO - Cleaning up... 22:09:50 INFO - Return code: 0 22:09:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:09:50 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')]} 22:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:50 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')]} 22:09:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:50 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 0x1049ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9b569bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104813d30>, '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 0x104639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10480bb20>, '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.Ut9yHJUrjq/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.wFJmWjRKjh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 22:09:50 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 22:09:50 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 22:09:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ut9yHJUrjq/Render', 22:09:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:50 INFO - 'HOME': '/Users/cltbld', 22:09:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:50 INFO - 'LOGNAME': 'cltbld', 22:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:50 INFO - 'MOZ_NO_REMOTE': '1', 22:09:50 INFO - 'NO_EM_RESTART': '1', 22:09:50 INFO - 'PAGER': '/bin/cat', 22:09:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:50 INFO - 'PWD': '/builds/slave/test', 22:09:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:50 INFO - 'SHELL': '/bin/bash', 22:09:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wFJmWjRKjh/Listeners', 22:09:50 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 22:09:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:50 INFO - 'USER': 'cltbld', 22:09:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:50 INFO - 'XPC_FLAGS': '0x0', 22:09:50 INFO - 'XPC_SERVICE_NAME': '0', 22:09:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:50 INFO - Downloading/unpacking blobuploader==1.2.4 22:09:50 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 22:09:50 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 22:09:50 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 22:09:50 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 22:09:50 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 22:09:50 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 22:09:52 INFO - Downloading blobuploader-1.2.4.tar.gz 22:09:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:09:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:09:52 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 22:09:52 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 22:09:52 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 22:09:52 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 22:09:52 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 22:09:52 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 22:09:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:09:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:09:53 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 22:09:53 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 22:09:53 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 22:09:53 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 22:09:53 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 22:09:53 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 22:09:54 INFO - Downloading docopt-0.6.1.tar.gz 22:09:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:09:54 INFO - Installing collected packages: blobuploader, requests, docopt 22:09:54 INFO - Running setup.py install for blobuploader 22:09:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:09:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:09:54 INFO - Running setup.py install for requests 22:09:54 INFO - Running setup.py install for docopt 22:09:54 INFO - Successfully installed blobuploader requests docopt 22:09:54 INFO - Cleaning up... 22:09:54 INFO - Return code: 0 22:09:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:09: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')]} 22:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09: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')]} 22:09:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:54 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 0x1049ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9b569bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104813d30>, '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 0x104639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10480bb20>, '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.Ut9yHJUrjq/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.wFJmWjRKjh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 22:09:54 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 22:09:54 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 22:09:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ut9yHJUrjq/Render', 22:09:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:54 INFO - 'HOME': '/Users/cltbld', 22:09:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:54 INFO - 'LOGNAME': 'cltbld', 22:09:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:54 INFO - 'MOZ_NO_REMOTE': '1', 22:09:54 INFO - 'NO_EM_RESTART': '1', 22:09:54 INFO - 'PAGER': '/bin/cat', 22:09:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:54 INFO - 'PWD': '/builds/slave/test', 22:09:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:54 INFO - 'SHELL': '/bin/bash', 22:09:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wFJmWjRKjh/Listeners', 22:09:54 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 22:09:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:54 INFO - 'USER': 'cltbld', 22:09:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:54 INFO - 'XPC_FLAGS': '0x0', 22:09:54 INFO - 'XPC_SERVICE_NAME': '0', 22:09:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:09:55 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-m2Rcmi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:09:55 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-AgRLOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:09:55 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-5nc94Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:09:55 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-PtkDsm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:09:55 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-enp4Aq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:09:55 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-qwyTQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:09:55 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-wWHO3q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:09:55 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-Z8mb9G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:09:56 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-LX3PHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:09:56 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-gUt3au-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:09:56 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-jF8amL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:09:56 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-SsmOJB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:09:56 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-GFCM6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:09:56 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-gckX0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:09:56 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-2t5mA2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:09:56 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-DM4spl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:09:56 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-doYZ4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:09:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:09:57 INFO - Running setup.py install for manifestparser 22:09:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:09:57 INFO - Running setup.py install for mozcrash 22:09:57 INFO - Running setup.py install for mozdebug 22:09:57 INFO - Running setup.py install for mozdevice 22:09:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:09:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:09:57 INFO - Running setup.py install for mozfile 22:09:57 INFO - Running setup.py install for mozhttpd 22:09:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:09:57 INFO - Running setup.py install for mozinfo 22:09:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:09:58 INFO - Running setup.py install for mozInstall 22:09:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:09:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:09:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:09:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:09:58 INFO - Running setup.py install for mozleak 22:09:58 INFO - Running setup.py install for mozlog 22:09:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:09:58 INFO - Running setup.py install for moznetwork 22:09:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:09:58 INFO - Running setup.py install for mozprocess 22:09:58 INFO - Running setup.py install for mozprofile 22:09:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:09:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:09:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:09:59 INFO - Running setup.py install for mozrunner 22:09:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:09:59 INFO - Running setup.py install for mozscreenshot 22:09:59 INFO - Running setup.py install for moztest 22:09:59 INFO - Running setup.py install for mozversion 22:09:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:09:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:09:59 INFO - Cleaning up... 22:09:59 INFO - Return code: 0 22:09:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:09: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')]} 22:09:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09: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')]} 22:09:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:59 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 0x1049ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9b569bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104813d30>, '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 0x104639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10480bb20>, '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.Ut9yHJUrjq/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.wFJmWjRKjh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 22:09:59 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 22:09:59 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 22:09:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ut9yHJUrjq/Render', 22:09:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:59 INFO - 'HOME': '/Users/cltbld', 22:09:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:59 INFO - 'LOGNAME': 'cltbld', 22:09:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:59 INFO - 'MOZ_NO_REMOTE': '1', 22:09:59 INFO - 'NO_EM_RESTART': '1', 22:09:59 INFO - 'PAGER': '/bin/cat', 22:09:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:59 INFO - 'PWD': '/builds/slave/test', 22:09:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:59 INFO - 'SHELL': '/bin/bash', 22:09:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wFJmWjRKjh/Listeners', 22:09:59 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 22:09:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:59 INFO - 'USER': 'cltbld', 22:09:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:59 INFO - 'XPC_FLAGS': '0x0', 22:09:59 INFO - 'XPC_SERVICE_NAME': '0', 22:09:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:09:59 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-e9EtK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:10:00 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)) 22:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:10:00 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-2B4rOV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:10:00 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)) 22:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:10:00 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-ZGiqYk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:10:00 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)) 22:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:10:00 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-CvSWeV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:10:00 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)) 22:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:10:00 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-pMGbP9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:10:00 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)) 22:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:10:00 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-_BRVs6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:10:00 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)) 22:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:10:00 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-FIQD8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:10:00 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)) 22:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:10:00 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-EmAoIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:10:00 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)) 22:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:10:00 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-Qt70eX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:10:00 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)) 22:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:10:00 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-OA4CTt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:10:00 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)) 22:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:10:00 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-BJHPvy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:10:01 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)) 22:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:10:01 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-WYfktB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:10:01 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)) 22:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:10:01 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-1Zrvx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:10:01 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)) 22:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:10:01 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-LYOeNe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:10:01 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)) 22:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:10:01 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-_jG3FS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:10:01 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)) 22:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:10:01 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-NahHfK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:10:01 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)) 22:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:10:01 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-27vtyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:10:01 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)) 22:10:01 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)) 22:10:01 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)) 22:10:01 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)) 22:10:01 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)) 22:10:01 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)) 22:10:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:04 INFO - Downloading blessings-1.6.tar.gz 22:10:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:10:04 INFO - Installing collected packages: blessings 22:10:04 INFO - Running setup.py install for blessings 22:10:04 INFO - Successfully installed blessings 22:10:04 INFO - Cleaning up... 22:10:04 INFO - Return code: 0 22:10:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:10:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:10:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:10:04 INFO - Reading from file tmpfile_stdout 22:10:04 INFO - Current package versions: 22:10:04 INFO - blessings == 1.6 22:10:04 INFO - blobuploader == 1.2.4 22:10:04 INFO - docopt == 0.6.1 22:10:04 INFO - functools32 == 3.2.3-2 22:10:04 INFO - jsonschema == 2.5.1 22:10:04 INFO - manifestparser == 1.1 22:10:04 INFO - mozInstall == 1.12 22:10:04 INFO - mozcrash == 0.17 22:10:04 INFO - mozdebug == 0.1 22:10:04 INFO - mozdevice == 0.48 22:10:04 INFO - mozfile == 1.2 22:10:04 INFO - mozhttpd == 0.7 22:10:04 INFO - mozinfo == 0.9 22:10:04 INFO - mozleak == 0.1 22:10:04 INFO - mozlog == 3.4 22:10:04 INFO - moznetwork == 0.27 22:10:04 INFO - mozprocess == 0.23 22:10:04 INFO - mozprofile == 0.28 22:10:04 INFO - mozrunner == 6.12 22:10:04 INFO - mozscreenshot == 0.1 22:10:04 INFO - mozsystemmonitor == 0.3 22:10:04 INFO - moztest == 0.7 22:10:04 INFO - mozversion == 1.4 22:10:04 INFO - psutil == 3.1.1 22:10:04 INFO - requests == 1.2.3 22:10:04 INFO - wsgiref == 0.1.2 22:10:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:10:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:05 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 0x1049ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9b569bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104813d30>, '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 0x104639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10480bb20>, '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.Ut9yHJUrjq/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.wFJmWjRKjh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 22:10:05 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 22:10:05 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 22:10:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ut9yHJUrjq/Render', 22:10:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:05 INFO - 'HOME': '/Users/cltbld', 22:10:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:05 INFO - 'LOGNAME': 'cltbld', 22:10:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:05 INFO - 'MOZ_NO_REMOTE': '1', 22:10:05 INFO - 'NO_EM_RESTART': '1', 22:10:05 INFO - 'PAGER': '/bin/cat', 22:10:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:05 INFO - 'PWD': '/builds/slave/test', 22:10:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:05 INFO - 'SHELL': '/bin/bash', 22:10:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wFJmWjRKjh/Listeners', 22:10:05 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 22:10:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:05 INFO - 'USER': 'cltbld', 22:10:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:05 INFO - 'XPC_FLAGS': '0x0', 22:10:05 INFO - 'XPC_SERVICE_NAME': '0', 22:10:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:05 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)) 22:10:05 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)) 22:10:05 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)) 22:10:05 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)) 22:10:05 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)) 22:10:05 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)) 22:10:05 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)) 22:10:05 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)) 22:10:05 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)) 22:10:05 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)) 22:10:05 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)) 22:10:05 INFO - Cleaning up... 22:10:05 INFO - Return code: 0 22:10:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:06 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 0x1049ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9b569bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104813d30>, '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 0x104639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10480bb20>, '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.Ut9yHJUrjq/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.wFJmWjRKjh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 22:10:06 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 22:10:06 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 22:10:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ut9yHJUrjq/Render', 22:10:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:06 INFO - 'HOME': '/Users/cltbld', 22:10:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:06 INFO - 'LOGNAME': 'cltbld', 22:10:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:06 INFO - 'MOZ_NO_REMOTE': '1', 22:10:06 INFO - 'NO_EM_RESTART': '1', 22:10:06 INFO - 'PAGER': '/bin/cat', 22:10:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:06 INFO - 'PWD': '/builds/slave/test', 22:10:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:06 INFO - 'SHELL': '/bin/bash', 22:10:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wFJmWjRKjh/Listeners', 22:10:06 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 22:10:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:06 INFO - 'USER': 'cltbld', 22:10:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:06 INFO - 'XPC_FLAGS': '0x0', 22:10:06 INFO - 'XPC_SERVICE_NAME': '0', 22:10:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:10:06 INFO - Cleaning up... 22:10:06 INFO - Return code: 0 22:10:06 INFO - Running post-action listener: _resource_record_post_action 22:10:06 INFO - Running post-action listener: _start_resource_monitoring 22:10:06 INFO - Starting resource monitoring. 22:10:06 INFO - [mozharness: 2016-10-07 05:10:06.542118Z] Finished create-virtualenv step (success) 22:10:06 INFO - [mozharness: 2016-10-07 05:10:06.542922Z] Running install step. 22:10:06 INFO - Running pre-action listener: _resource_record_pre_action 22:10:06 INFO - Running main action method: install 22:10:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:10:06 INFO - Reading from file tmpfile_stdout 22:10:06 INFO - Detecting whether we're running mozinstall >=1.0... 22:10:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:10:06 INFO - Reading from file tmpfile_stdout 22:10:06 INFO - Output received: 22:10:06 INFO - Usage: mozinstall [options] installer 22:10:06 INFO - Options: 22:10:06 INFO - -h, --help show this help message and exit 22:10:06 INFO - -d DEST, --destination=DEST 22:10:06 INFO - Directory to install application into. [default: 22:10:06 INFO - "/builds/slave/test"] 22:10:06 INFO - --app=APP Application being installed. [default: firefox] 22:10:06 INFO - mkdir: /builds/slave/test/build/application 22:10:06 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'] 22:10:06 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 22:10:41 INFO - Reading from file tmpfile_stdout 22:10:41 INFO - Output received: 22:10:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:10:41 INFO - Running post-action listener: _resource_record_post_action 22:10:41 INFO - [mozharness: 2016-10-07 05:10:41.642363Z] Finished install step (success) 22:10:41 INFO - [mozharness: 2016-10-07 05:10:41.642495Z] Running run-tests step. 22:10:41 INFO - Running pre-action listener: _resource_record_pre_action 22:10:41 INFO - Running main action method: run_tests 22:10:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:10:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:10:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:10:41 INFO - 2016-10-06 22:10:41.657 screenresolution[1640:7540] starting screenresolution argv=screenresolution get 22:10:41 INFO - 2016-10-06 22:10:41.659 screenresolution[1640:7540] Display 0: 1600x1200x32@60 22:10:41 INFO - 2016-10-06 22:10:41.666 screenresolution[1641:7542] starting screenresolution argv=screenresolution list 22:10:41 INFO - Available Modes on Display 0 22:10:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:10:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:10:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:10:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:10:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:10:41 INFO - Intel Iris: 22:10:41 INFO - Chipset Model: Intel Iris 22:10:41 INFO - Type: GPU 22:10:41 INFO - Bus: Built-In 22:10:41 INFO - VRAM (Dynamic, Max): 1536 MB 22:10:41 INFO - Vendor: Intel (0x8086) 22:10:41 INFO - Device ID: 0x0a2e 22:10:41 INFO - Revision ID: 0x0009 22:10:41 INFO - Displays: 22:10:41 INFO - Display: 22:10:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:10:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:10:41 INFO - Display Serial Number: 1600x1200 60 22:10:41 INFO - Main Display: Yes 22:10:41 INFO - Mirror: Off 22:10:41 INFO - Online: Yes 22:10:41 INFO - Rotation: Supported 22:10:41 INFO - Return code: 0 22:10:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:10:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:10:41 INFO - Python 2.7.3 22:10:41 INFO - Return code: 0 22:10:41 INFO - grabbing minidump binary from tooltool 22:10:41 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')]} 22:10:41 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 0x104813d30>, '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 0x104639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10480bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:10:41 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 22:10:41 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 22:10:41 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 22:10:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:10:42 INFO - Return code: 0 22:10:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:10:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:10:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:10:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:10:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:10:42 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/KIjDUPkITZyWK8ev6u8BXw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0113', '--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 22:10:42 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/KIjDUPkITZyWK8ev6u8BXw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0113 --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 22:10:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ut9yHJUrjq/Render', 22:10:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:42 INFO - 'HOME': '/Users/cltbld', 22:10:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:42 INFO - 'LOGNAME': 'cltbld', 22:10:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:10:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:10:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:42 INFO - 'MOZ_NO_REMOTE': '1', 22:10:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:10:42 INFO - 'NO_EM_RESTART': '1', 22:10:42 INFO - 'PAGER': '/bin/cat', 22:10:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:42 INFO - 'PWD': '/builds/slave/test', 22:10:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:10:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:42 INFO - 'SHELL': '/bin/bash', 22:10:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wFJmWjRKjh/Listeners', 22:10:42 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 22:10:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:42 INFO - 'USER': 'cltbld', 22:10:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:42 INFO - 'XPC_FLAGS': '0x0', 22:10:42 INFO - 'XPC_SERVICE_NAME': '0', 22:10:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:42 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/KIjDUPkITZyWK8ev6u8BXw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0113', '--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 22:10:42 INFO - mozversion application_buildid: 20161006200000 22:10:42 INFO - mozversion application_changeset: 4b9944879c9a60a9aba4a744a7401bc38e0f39c4 22:10:42 INFO - mozversion application_display_name: Nightly 22:10:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:10:42 INFO - mozversion application_name: Firefox 22:10:42 INFO - mozversion application_remotingname: firefox 22:10:42 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:10:42 INFO - mozversion application_vendor: Mozilla 22:10:42 INFO - mozversion application_version: 52.0a1 22:10:42 INFO - mozversion platform_buildid: 20161006200000 22:10:42 INFO - mozversion platform_changeset: 4b9944879c9a60a9aba4a744a7401bc38e0f39c4 22:10:42 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:10:42 INFO - mozversion platform_version: 52.0a1 22:10:42 INFO - using testdate: 1475817042 22:10:42 INFO - actual date: 1475817042 22:10:42 INFO - starting webserver on 'localhost:49267' 22:10:42 INFO - SUITE-START | Running 2 tests 22:10:42 INFO - TEST-START | dromaeo_css 22:10:42 INFO - operating with platform_type : mac_ 22:10:42 INFO - Initialising browser for dromaeo_css test... 22:10:42 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmp3zmVXD/profile http://localhost:49267/getInfo.html) 22:10:45 INFO - PROCESS | 1651 | 2016-10-06 22:10:45.183 plugin-container[1653:7689] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b47, name = 'com.apple.tsm.portname' 22:10:45 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:10:45 INFO - PROCESS | 1651 | 2016-10-06 22:10:45.187 plugin-container[1653:7689] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 22:10:45 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:10:45 INFO - PROCESS | 1651 | 2016-10-06 22:10:45.187 plugin-container[1653:7689] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:10:45 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 22:10:45 INFO - PROCESS | 1651 | colorDepth:24 22:10:45 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 22:10:45 INFO - PROCESS | 1651 | __metrics 22:10:45 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 22:10:46 INFO - TEST-INFO | 1651: exit 0 22:10:46 INFO - Browser initialized. 22:10:46 INFO - Running cycle 1/1 for dromaeo_css test... 22:10:46 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmp3zmVXD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:10:48 INFO - PROCESS | 1654 | 2016-10-06 22:10:48.471 plugin-container[1655:7804] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8913, name = 'com.apple.tsm.portname' 22:10:48 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:10:48 INFO - PROCESS | 1654 | 2016-10-06 22:10:48.473 plugin-container[1655:7804] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0f, name = 'com.apple.CFPasteboardClient' 22:10:48 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:10:48 INFO - PROCESS | 1654 | 2016-10-06 22:10:48.474 plugin-container[1655:7804] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:10:58 INFO - PROCESS | 1654 | RSS: Main: 240062464 22:10:58 INFO - PROCESS | 1654 | 22:11:47 INFO - PROCESS | 1654 | 1475817107833 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] 22:14:01 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 22:14:02 INFO - PROCESS | 1654 | RSS: Main: 251719680 22:14:02 INFO - PROCESS | 1654 | 22:17:05 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 22:17:05 INFO - PROCESS | 1654 | RSS: Main: 265248768 22:17:05 INFO - PROCESS | 1654 | 22:20:07 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 22:20:08 INFO - PROCESS | 1654 | RSS: Main: 269889536 22:20:08 INFO - PROCESS | 1654 | 22:23:10 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 22:23:10 INFO - PROCESS | 1654 | RSS: Main: 272408576 22:23:10 INFO - PROCESS | 1654 | 22:26:13 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 22:26:14 INFO - PROCESS | 1654 | RSS: Main: 277921792 22:26:14 INFO - PROCESS | 1654 | 22:29:17 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 22:29:17 INFO - PROCESS | 1654 | RSS: Main: 280608768 22:29:17 INFO - PROCESS | 1654 | 22:29:17 INFO - PROCESS | 1654 | __start_tp_report 22:29:17 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 22:29:17 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 22:29:17 INFO - PROCESS | 1654 | |i|pagename|runs| 22:29:17 INFO - PROCESS | 1654 | |0;dojo.html;2872.8994485193016;3439.535662685537;3476.8087755173456;3476.8087755173456;3485.024193225896;5260.421353651095;5329.25390445337;5726.9140962885485;5825.825225243244;5884.617499862506;5772.538196944245;5862.355140934505;5900.3214630317525;5940.851478713034;5997.460228579427;5230.1108811501945;5239.711815850127;5326.014687282865;5334.493223143814;5385.703786291752;5323.973380133113;5349.8127565535215;5353.839384818461;5391.514763671269;5436.803903141303;4264;5176.042432150046;5213.973930130325;5231.712255825928;5312.548433383167;5232.953409318152;5253.579713622901;5265.210218467222;5287.41838397776;5303.946960530384;5195.19474481456;5216.191490319006;5228.947710522884;5256.526912577848;5267.420583735786;5675.34733505648;5682.403347648478;5735.1684005818925;5744.339400968858;5767.307923049261;5571.526420254283;6008.218931538916;6239.2824825145235;6245.46913512352;6248.031555108965;5129.281900533928;6261.906071408933;6266.968665156691;6335.04974253861;6340;5827.426594819375;5889.028310328834;5913.408659134052;5984;6004.279486461653;6296.275928268216;6423.74305027803;6456.483481321483;6462;6463.48292136628;4623.214053610895;4749.833755818529;5145.974270128625;6207.068939659087;6212.943799554087;5398.865028374322;5623.634463759842;6146.692665366714;6159.692015399212;6295.874082518324;6165.260168779775;6173.166622505904;6185.690715464209;6190.442860142609;6257.843553911189;5741.598088133791;5788.034802043392;6217.96891015542;6231.968840155861;6265;4312.504062032844;4907.141250281186;4967.82612608557;4998.525140111757;5034.295198672189;4494.617957473586;4514.25514790056;4522.140793249272;4562.201614717411;4572.9314060289125;5969.970150149222;6062.272527296753;6065.181200537959;6074.726637301332;6083.939160608337;6156.230471191101;6201.906971395432;6294.8426289342215;6327.620342779448;6341.746330146742;6016.067509536029;6035.939640603626;6081.665508397081;6084.817455476343;6142.140100385949;5497.697626630574;5532.114861622121;5538.446155384429;5543.667379957215;5548.445155484419;6256.968715156395;6521.543491955613;6523.7064332105165;6525.64108974011;6559.180102487189;6192.411720886508;6204.162438070803;6233.2208473940755;6237.75048998035;6350.015747558951;6489.4808415325715;6510.641914694737;6519.217693876575;6521.217453905372;6529.967350163219;6225.8132256032395;6253.187085679015;6254.312025677263;6257.374262573706;6267.874642507216;6145.095020543646;6202.689865506797;6208.503319734501;6213;6226.875462490634;6202.4417802398;6206.689665516616;6212.192414986023;6212.378762123752;6213.068039794067;4893.584045356185;6153.907691384543;6158.846028849135;6180.536459765445;6210.192674952227;5832.183494310885;6001.7599296027665;6068.817935461943;6074;6080.665563394055;6167.198264225622;6178.320384757763;6187.381261873957;6190.690465476618;6192.195014648067;5882.794102206402;6089.54328425361;6114.174586430775;6131.96934015327;6148.200733904564 22:29:17 INFO - PROCESS | 1654 | |1;ext.html;457.8397560853701;469.4577762684101;529.6398449054641;530.978760849566;532.9600279979003;34001;39322.60677393225;41916.43710284622;41980.95047623811;42135.36796948048;35340.823295883485;43033;43603.3459498107;43604.56395436045;43706.562934370726;9691.079347461997;9828.705138845846;9899.307048506607;9900.25748068894;9912.157466615346;9755.219582433423;9842.753931151707;9852.113309802116;9869.703908882744;9885.357451765612;9829.21366290695;9848.753781155458;9862.408255504657;9870.506474676273;9915.652952146676;7915.406344524184;9707.223422126213;9816.361936474106;9868.309218354718;9868.654597089104;7581.583012934287;7767.37860971121;9689.660861869801;9701.854472182922;9724;40424.787256382355;40775.7961210195;40803;40918.38622420666;40991.7950410249;2804.4531316393313;3329.200991761984;3429.5713035870517;3543.167355671415;3727.1986522897705;30743.38513229745;31328.686713133033;31420.685793142235;31731.52402713961;31804.522932156036;2925.605043319146;3126.7643634800997;3131.530270459449;3246.805191688506;3274.918127046819;303292;372551;381954;384996;388855.99999999715;60007.699961499915;60044.39955600388;60386.39613603896;60417.697911510164;60457.69771151116;62762.686186568775;63032.68483657552;63125.684371577845;63199.36800632027;63420.04869926955;12863.614091577268;15854.603634908988;16158.75761863573;16159.757603635955;16195;12131.636050918487;12151.392430378446;12169.817452738216;12227.75544489123;12255.69360765988;16081.839181608268;16851.820372573802;16918.154092295335;16927.291633338606;16942.57643558921;6941.652917354112;7051.224365319811;7079.964600176966;7104.325089116525;7145.8928116078305;845.902721187059;953.8187744328555;964.9565769540387;989.9010098990187;1054.9630762923261;29763;30187.849060754994;30265.07848734408;30466.390672186433;30922;1034.3586976074882;1039.8544203811473;1065.7016035510069;1072.6781965410346;1137.1357768096307;15684.529464116094;15778.447754328543;15814.288357023961;15844.207789610704;16193.109378984269;509487;588185;588810;591103;646758;57034;58074;58199.41800582025;58613.82772344529;58749.412505874396;1331.2811082015603;1338.3107699534748;1373.1143412498882;1386.861313868605;1401.8808401286005;29272;29412.852935735184;30156.69843301539;30290;30689.846550767998;50553.988920222124;51098.48901510937;51153.488465114875;51183.976320474125;51188.48811511837;562593;586380;589116;589116;590221;25913.740862591134;26681.866590666923;26918.73081269162;40597.79701101476;40807;46486;47268.7636561815;47446;47567.286490701976;47931.5206847941;38709.419358710205;39126.6087339123;39400.605993939695;39792.60207397889;40316.79841600773;38583.03542411462;38770.61229387783;38958;40025;40130;38473.615263847;38867.63963261272;39248.2150356997;39337.40993885037;39434;32231.033069007968;32510.83744581262;34115.97652070442;35179.64820351764;35289;23774.405639859142;24967.501949883066;26031.21906342813;26176.8691156543;26274.737252627994 22:29:17 INFO - PROCESS | 1654 | |2;jquery.html;375.77077982430694;377.5846568774348;382.67855001798495;389.33035179491264;403.05081532989806;17353.7396939046;18849.905750471247;21314.14743410262;21648;21745.347639570817;19759.901200493976;19974.60050798983;20635.69046464304;20721.68917466239;21032.8948355258;5502.642328248671;5574.749136288857;5904.350521442638;6196.75212991482;6244.500439964814;3935.626115519021;4135.400366946791;6156.5382596305235;6243.719032643542;6254.624722516641;5914.9408505915035;6188.226471691039;6233.28317243516;6260.186175797163;6269.655168965705;4995.200767877141;6016.4885984691355;6259.780907668232;6279.434850863444;6291.905621415683;6123.846903827395;6124.35694252106;6129.877402451971;6132.988056970595;6143.078538219258;21561.353159405244;24416.511669766503;24576.631350529755;24598.893049812803;24816.3795905102;2120.68189771534;2179.949602253066;2213.8118259947923;2286.4969706664674;2300.9309720708407;9844.409335439897;9930.45382503962;9933.552990115462;10447.79104417915;10468.42423666698;1646.2674110020926;1856.0255865670497;1868.5795695968434;1915.6551820672273;1927.3832373640441;107076.39385409225;109835.45082274618;111682.88317116805;117284;119841.80158198481;9734.36726612768;11180.776384472265;11363.261388009752;11532.596359127558;11752;14561.199134047518;14592.635184120481;14592.927035364755;14607.92696036513;14693.706125877423;7975.122736498981;8150.796230094176;8193.590320483952;8209.507429554245;8231.794205144799;7889.250521200477;7896.486728362638;8024;8044.959775201087;8055.234752698484;4410.316400957792;4520.61574766142;4525.570070843265;4570.9314360284625;4609.631229501631;1617.563257920355;1690.3069741405964;1913.7990510996424;1944.6305202011572;1967.1344608372272;782.6282515805061;935.242453612576;964.6671898195111;977.6529332087007;981.8379967305458;16378.672426551402;18646.906765466356;20708.068136933874;21189.364319070453;21331.146754129662;924.3638759395832;932.2495391210141;940.6801687426171;947.1664934857281;1007.1338648762058;9740.95129524362;10537.63118290856;10583.25917185805;10802.08182304513;10803.675889723321;504531;542013;585282;607416;623715.051397939;95356;140774;145173;145379.2731036338;145792;890.2343984173606;941.275218082073;994.786120983992;998.9850152247722;1016.11090295991;16223.53988141073;18353.449396518125;18354.541136471697;18874;18951.715724264424;39693.404598930465;46797.830054247555;47405;48162.27756583584;49819.750901245265;72291.63854180695;73857.6307118461;74662.25337746553;74734;74742.87885681828;7784.88322675149;7859.52842829432;8001.8399632008195;8030.156833532628;8133.552654604054;11725.589604364148;11947.522099115939;12056.578019769266;12094.637160885188;12312.569060082855;9966.352187107816;10527.368357898551;10676;10731.624393146514;10907.83638245411;8721.389502734748;9890.208783297176;10428.217883658604;10605.151587872797;11227.494762735532;8482.872756908528;10949.894941808281;11130.833037504282;11216.943915280697;11263.26788758728;6899.551529150589;7065.540739851893;7372.705091796268;7436.293552112541;7888;10442.321249118782;10904.38294809256;10910.563577457131;11113.333200008025;11465.197436179387 22:29:17 INFO - PROCESS | 1654 | |3;mootools.html;1434.8995570310078;1530.5561387197713;1741.3295881085778;1849.066221558113;1886.094674556213;8006.399520035991;8255.174482551756;8465.66137354506;9012.188902998729;9022.684206052798;7053.964730176342;8490.575471226444;8800.867986980184;8837.248833849115;8907.821843563124;2342.7071616047992;2380.357303528055;2381.2380038387723;2388.7133543974746;2395.7063185879606;2357.3752955466816;2371.620540713486;2388.450656349041;2406.6750988616486;2409.180878501309;2003.5692326149874;2379.940501487459;2390.1634427950166;2402;2410.602250628644;2032.3699653107583;2335.4978679583887;2356.375560476475;2389.187676190086;2396.2930935374106;2377.9048838046456;2385.6063749481314;2390.306811024801;2391.4380120671626;2392.7248366437734;8968.372213945026;8983.101689830964;9043.231325337354;9043.638254469814;9051.094890510962;5258.711615654151;5373.059714549938;5415.566754659618;5437.531866396091;5439.918401223985;3508.8771892983623;3544.7341449391397;3564.500969864221;3607.5310209672575;3612.801295928723;1054.8154073037188;1061.4215252687304;1070.3631339353071;1083.9458027098613;1091.2197778588347;11543.365114918675;11615.419229038514;11624.418779061098;11660.06719462441;11686.415679216005;4567.1779079765965;4725.787339569728;4801.231802911518;4847.975760121248;4854.296127061556;8520.531370774543;8939;9902.900970990198;9983.950080249697;10060.798784024279;5304.151335786256;5319.840404787863;5325.467453254643;5329.467053294639;5338.252644629755;4372.868813935587;4391.253486907233;4518.502964673883;4866.270059491105;4869.537393947569;3841;3944.546377166605;3998.9600103998587;4018.417329487208;4083.959160408358;1280.7374488230078;1298.9865708152327;1307.7057662025968;1310.1418570836117;1311.4295281552554;833.5373867503472;884.3544212724747;901.3735453859636;915.9587818548181;920.4201353147608;4791.856244312677;5796.710164491843;5901.085331773582;5953.493953014043;6049.334573196945;386.86846472199585;421.42475520913734;440.7730019040197;447.55915423308016;449.46962584151015;3761.9059523512133;3929.214157168578;3936.1537269486626;4035.858744943913;4103.712740108163;7807.765767026998;10635.361878287327;10704.411257380918;10728.839067413996;10805.621803236849;10690.946545267223;10759.031687148194;10759.30064545802;10772.353658780497;10781.784364312669;6911.75808846688;8054.838903221903;8253.215944485264;8308.792280193044;8348.707795227137;5744.712764361681;5842.123681447817;5880.794172203952;5894.351621321737;5941;9989.850152247576;10047.196224302184;10067.899321006696;10132.392056476636;10137;9467.810643787223;9740.659076932274;9896.703098907044;9995.150412215044;10210.336328138646;6939.132608423947;7149.60677162761;7398.2231865655485;7404.370628496638;7673.386129109769;8328.708495202638;8527.957360213159;8533.104024077631;8554.700585479479;8557.48655080697;7342.339189472973;7464.365528930101;7624.123225829101;7689.269519395649;7732.574708391093;7333.339999400079;7448.255174482725;7478.027856378636;7553.848923021619;7651.4261430391825;6928.064711263914;7069.434445244266;7075.363217310467;7085.291470852873;7098.36114749675;4159.292920203512;4992.525710057539;5004.099262132851;5008.649394542347;5090.134677104827;4903.8528844134735;4914.70511769295;5024.773885175103;5066.746662666926;5084.059449001798 22:29:17 INFO - PROCESS | 1654 | |4;prototype.html;301.20181518974715;306.356651032831;311.72100969632186;313.3857639027506;317.8013741411618;4972.577330926875;5158.226266060093;5420.91868621971;5547.611667183299;5602.60781745278;5203;5204.011237864803;5372.52255629702;5604.467575580324;5612.354579223391;4219.261629214883;4220.493540775111;4224.809883555232;4230.534641189467;4240.703150779446;4062.6546743526837;4167.2082304362075;4200.7479551227025;4226.788660566975;4229.181451975649;4146;4161.625453709165;4166.208420400114;4182.100848317611;4193.266178418779;3674.026383008505;3989.920201595981;4197.181549597799;4199.727017743852;4232.661387089025;3063.6170478690165;3093.489574220251;3352.2960178362573;3364.5121457388786;3645.671889529955;4127.587241275878;4891.877703057452;5384.946150538483;5405.216418432279;5441.374241962186;3961.3661814109605;4756.66703330767;4761.452432970218;4794.489735733233;4794.688345257547;5224.947750522484;5630.324361076697;5692.37383887772;5822.243108395924;5842.386549412336;3946.3093958557424;3949.637375105584;3953.2488827122756;3969.0672691917375;3973.3841254605295;5164.870878228036;5916.615419997686;6112.266528016622;6122.663253521055;6123.8162855114415;3749.347518083092;3911.843526258918;3930.3514920037874;3950.8195902298926;3997.9216003677006;3719.7210209233876;4015.397690346413;4019.698522610816;4030.9442184486124;4054.432379466877;4668.229742092517;4718.41019872516;4742.051589682077;4742.609034540251;4763.189987804682;5737.684427356452;5843.3864444233595;5890.2342695449315;5971.671558064349;6006.189164462741;1171.8066519024435;1215.598852378713;1295.3070107492447;1331.9200847949155;1349.50742978814;728.639323534843;766.7431410477552;902.6750369866843;926.346925417579;929.3262384770987;5435.211894275308;5485.807996720096;5490.835274941758;5522.9447705523235;5551.333839939233;5946.762129514771;5987;6026.306974697878;6070.573415247413;6106.2061931948565;4709.858704238878;5225.686458812484;5394;5464.562834973192;5479.890402191933;2330.603797354454;2507.7366876478072;2564.6279240606186;2641.815072944914;2648.3776312566533;4159.396887451363;4903.387076615423;4971.925421118686;5138.707131654402;5207.270982062439;4545.659075569345;4859.2954021667365;5811.738471768781;6358.904616430757;6385.042243663488;5509.917351239735;6178.505719542426;6378.904316435257;6438.356164383524;6458.128152699325;5975.55183361241;6175.2589689237575;6176.505879529716;6250.406211409909;6277.717502712298;5257;5754.597178197486;5923.940760592339;5950.7917222896995;6007.90988135187;5065.974670126626;5583.776648933997;6346.809595712136;6373.565947661739;6536.215654121345;3778.376567866271;3975.204959008152;4000.419939108871;4008.3386241269864;4085.346344584852;5829.417058294137;5981.782843095521;6104.969475152596;6120.7551697931585;6143.754249829957;5619.466150715675;5782.942170578409;5885;5971.940280597139;6013.939860601513;5493.121100623881;5901.645901245939;5959.970200148971;6001.519878409804;6044.969775151096;4879.365142822836;5143.92284115746;5529.972350138223;5853.560982926212;5857.062869940789;4877.195262781744;4933.827316043921;4984.975075124601;5588.798408342226;5923.644581325134;4642.651801114863;5035.917277785294;5737.368889422244;5819.388964158702;5825.388334225015 22:29:17 INFO - PROCESS | 1654 | |5;yui.html;679.3376457953495;698.4691634357887;701.7824474412932;723.8660847743165;736.1203361982433;15154.242287885603;15244.84755152448;15490.845091549081;15723.449679261243;15942.123183224918;13100.737985240314;14567.417303307882;14597.635059123528;14699.706005879876;14731.337089830957;840.9621567029499;868.3660927522913;868.7437206024207;868.956552172392;872.4547158026234;673.885439475288;687.8968154776774;767.3630886364305;818.5620692929284;874.4359887872318;872.9956350218272;903.0878812399458;909.0954500272238;913.3287034029983;917.4586993673731;892.7009451833643;902.8465160922659;910.7358865928873;910.7723069232692;911.5761171055458;673.1652750589283;678.1489231015045;678.932106789322;682.2290811383106;682.8327059870314;14574.927125364411;14723.926380368137;14893.47872824446;14897.47858824936;14900.0314979526;14070.2964851757;14383.928080359636;14511.274436278143;14746.926265368711;14834.10995340283;11595.76808463826;11621.825672614917;11739.941300293443;11795.992495908642;11874.584389546411;3780.7353485256044;3822.197338558906;3854.843546935936;3861.3821788513983;3994.940075898864;112145;113802;116314.41842790817;116646.83353166445;119566;10144.137748291478;11200.38397888124;11256.493457794419;11269.774604507864;11556.133290003116;22906.31281061571;23075.423114422272;23216.187433439747;23218.419539511648;23244.65133023006;7523.774286771406;8052.355811535063;8062.959685201537;8080.19198080199;8279.54462504556;4372.846950356722;4442.955570444319;4484.730916145042;4511.932321015187;4585.587297143273;3482.425399809003;3575.0704816747834;3578.8031658259047;3599.460080987873;3602.063463499509;573.896698594257;643.723199024424;655.6492276631978;655.9639219842954;661.4080398043724;520.2664243416764;533.9893202135936;534.3080710479937;572.2132068405942;628.7233617208414;9001.144891235323;9157.679481218125;9168.220701240336;9185.311101667268;9201;553.239295968044;560.6075746977051;601.3986013986014;611.7430679114782;617.718937883262;4265.957340426556;4365.432493775852;4475.820967161342;4481.7983190756495;4521.502634710179;101116;101187;124623;139317;176131;122862;144378.2781086109;144709;144760;150974.22564435756;66422.33577664258;102223.4888825566;123868;126114.7388526103;128908;9770.609175632895;9798.701881374496;9816.030295038208;9870.753231169278;9873.654422095193;54075.918481630935;55565.72217138889;61143.69428152831;63563.682181588796;63766.04350934647;68171.65914170397;75605;94818.52590737001;97502.51248743995;99077;11956.58151964677;13217.40521676546;13383;13544.93227533856;13573.660658483617;14545.345459454138;14664.3401046951;14980.625484362978;15009.549713508612;15014.624634384229;11311.264767790068;13382.866171338552;13495;13747.937809328681;13821.79267310971;13611.52359667407;13613.387397566936;13624.93187534056;13625.114367566077;13962.441502339792;9728.416295022322;10650.414227217578;11379.069525550314;13293.20240785556;13952.16287022782;10994.780104398027;11178.105751539695;11395.601153959571;11645.825312620149;11692.298462092302;8834.325273302391;11202.551897924317;11439.428028598702;11500.367479788692;11507.252028618113 22:29:17 INFO - PROCESS | 1654 | __end_tp_report 22:29:17 INFO - PROCESS | 1654 | __start_cc_report 22:29:17 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,354 22:29:17 INFO - PROCESS | 1654 | __end_cc_report 22:29:17 INFO - PROCESS | 1654 | __startTimestamp1475818157366__endTimestamp 22:29:17 INFO - PROCESS | 1654 | 22:29:17 INFO - PROCESS | 1654 | ------- Summary: start ------- 22:29:17 INFO - PROCESS | 1654 | Number of tests: 6 22:29:17 INFO - PROCESS | 1654 | 22:29:17 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5763.51 Median:6062.27 stddev:678.81 (11.2%) stddev-sans-first:641.90 22:29:17 INFO - PROCESS | 1654 | Values: 2872.9 3439.5 3476.8 3476.8 3485.0 5260.4 5329.3 5726.9 5825.8 5884.6 5772.5 5862.4 5900.3 5940.9 5997.5 5230.1 5239.7 5326.0 5334.5 5385.7 5324.0 5349.8 5353.8 5391.5 5436.8 4264.0 5176.0 5214.0 5231.7 5312.5 5233.0 5253.6 5265.2 5287.4 5303.9 5195.2 5216.2 5228.9 5256.5 5267.4 5675.3 5682.4 5735.2 5744.3 5767.3 5571.5 6008.2 6239.3 6245.5 6248.0 5129.3 6261.9 6267.0 6335.0 6340.0 5827.4 5889.0 5913.4 5984.0 6004.3 6296.3 6423.7 6456.5 6462.0 6463.5 4623.2 4749.8 5146.0 6207.1 6212.9 5398.9 5623.6 6146.7 6159.7 6295.9 6165.3 6173.2 6185.7 6190.4 6257.8 5741.6 5788.0 6218.0 6232.0 6265.0 4312.5 4907.1 4967.8 4998.5 5034.3 4494.6 4514.3 4522.1 4562.2 4572.9 5970.0 6062.3 6065.2 6074.7 6083.9 6156.2 6201.9 6294.8 6327.6 6341.7 6016.1 6035.9 6081.7 6084.8 6142.1 5497.7 5532.1 5538.4 5543.7 5548.4 6257.0 6521.5 6523.7 6525.6 6559.2 6192.4 6204.2 6233.2 6237.8 6350.0 6489.5 6510.6 6519.2 6521.2 6530.0 6225.8 6253.2 6254.3 6257.4 6267.9 6145.1 6202.7 6208.5 6213.0 6226.9 6202.4 6206.7 6212.2 6212.4 6213.1 4893.6 6153.9 6158.8 6180.5 6210.2 5832.2 6001.8 6068.8 6074.0 6080.7 6167.2 6178.3 6187.4 6190.7 6192.2 5882.8 6089.5 6114.2 6132.0 6148.2 22:29:17 INFO - PROCESS | 1654 | 22:29:17 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:65658.11 Median:40223.40 stddev:139553.81 (346.9%) stddev-sans-first:139859.43 22:29:17 INFO - PROCESS | 1654 | Values: 457.8 469.5 529.6 531.0 533.0 34001.0 39322.6 41916.4 41981.0 42135.4 35340.8 43033.0 43603.3 43604.6 43706.6 9691.1 9828.7 9899.3 9900.3 9912.2 9755.2 9842.8 9852.1 9869.7 9885.4 9829.2 9848.8 9862.4 9870.5 9915.7 7915.4 9707.2 9816.4 9868.3 9868.7 7581.6 7767.4 9689.7 9701.9 9724.0 40424.8 40775.8 40803.0 40918.4 40991.8 2804.5 3329.2 3429.6 3543.2 3727.2 30743.4 31328.7 31420.7 31731.5 31804.5 2925.6 3126.8 3131.5 3246.8 3274.9 303292.0 372551.0 381954.0 384996.0 388856.0 60007.7 60044.4 60386.4 60417.7 60457.7 62762.7 63032.7 63125.7 63199.4 63420.0 12863.6 15854.6 16158.8 16159.8 16195.0 12131.6 12151.4 12169.8 12227.8 12255.7 16081.8 16851.8 16918.2 16927.3 16942.6 6941.7 7051.2 7080.0 7104.3 7145.9 845.9 953.8 965.0 989.9 1055.0 29763.0 30187.8 30265.1 30466.4 30922.0 1034.4 1039.9 1065.7 1072.7 1137.1 15684.5 15778.4 15814.3 15844.2 16193.1 509487.0 588185.0 588810.0 591103.0 646758.0 57034.0 58074.0 58199.4 58613.8 58749.4 1331.3 1338.3 1373.1 1386.9 1401.9 29272.0 29412.9 30156.7 30290.0 30689.8 50554.0 51098.5 51153.5 51184.0 51188.5 562593.0 586380.0 589116.0 589116.0 590221.0 25913.7 26681.9 26918.7 40597.8 40807.0 46486.0 47268.8 47446.0 47567.3 47931.5 38709.4 39126.6 39400.6 39792.6 40316.8 38583.0 38770.6 38958.0 40025.0 40130.0 38473.6 38867.6 39248.2 39337.4 39434.0 32231.0 32510.8 34116.0 35179.6 35289.0 23774.4 24967.5 26031.2 26176.9 26274.7 22:29:17 INFO - PROCESS | 1654 | 22:29:17 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:33842.46 Median:2162.48 stddev:96186.63 (4448.0%) stddev-sans-first:96423.64 22:29:17 INFO - PROCESS | 1654 | Values: 375.8 377.6 382.7 389.3 403.1 17353.7 18849.9 21314.1 21648.0 21745.3 19759.9 19974.6 20635.7 20721.7 21032.9 5502.6 5574.7 5904.4 6196.8 6244.5 3935.6 4135.4 6156.5 6243.7 6254.6 5914.9 6188.2 6233.3 6260.2 6269.7 4995.2 6016.5 6259.8 6279.4 6291.9 6123.8 6124.4 6129.9 6133.0 6143.1 21561.4 24416.5 24576.6 24598.9 24816.4 2120.7 2179.9 2213.8 2286.5 2300.9 9844.4 9930.5 9933.6 10447.8 10468.4 1646.3 1856.0 1868.6 1915.7 1927.4 107076.4 109835.5 111682.9 117284.0 119841.8 9734.4 11180.8 11363.3 11532.6 11752.0 14561.2 14592.6 14592.9 14607.9 14693.7 7975.1 8150.8 8193.6 8209.5 8231.8 7889.3 7896.5 8024.0 8045.0 8055.2 4410.3 4520.6 4525.6 4570.9 4609.6 1617.6 1690.3 1913.8 1944.6 1967.1 782.6 935.2 964.7 977.7 981.8 16378.7 18646.9 20708.1 21189.4 21331.1 924.4 932.2 940.7 947.2 1007.1 9741.0 10537.6 10583.3 10802.1 10803.7 504531.0 542013.0 585282.0 607416.0 623715.1 95356.0 140774.0 145173.0 145379.3 145792.0 890.2 941.3 994.8 999.0 1016.1 16223.5 18353.4 18354.5 18874.0 18951.7 39693.4 46797.8 47405.0 48162.3 49819.8 72291.6 73857.6 74662.3 74734.0 74742.9 7784.9 7859.5 8001.8 8030.2 8133.6 11725.6 11947.5 12056.6 12094.6 12312.6 9966.4 10527.4 10676.0 10731.6 10907.8 8721.4 9890.2 10428.2 10605.2 11227.5 8482.9 10949.9 11130.8 11216.9 11263.3 6899.6 7065.5 7372.7 7436.3 7888.0 10442.3 10904.4 10910.6 11113.3 11465.2 22:29:17 INFO - PROCESS | 1654 | 22:29:17 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5665.51 Median:4867.90 stddev:3184.24 (65.4%) stddev-sans-first:3177.30 22:29:17 INFO - PROCESS | 1654 | Values: 1434.9 1530.6 1741.3 1849.1 1886.1 8006.4 8255.2 8465.7 9012.2 9022.7 7054.0 8490.6 8800.9 8837.2 8907.8 2342.7 2380.4 2381.2 2388.7 2395.7 2357.4 2371.6 2388.5 2406.7 2409.2 2003.6 2379.9 2390.2 2402.0 2410.6 2032.4 2335.5 2356.4 2389.2 2396.3 2377.9 2385.6 2390.3 2391.4 2392.7 8968.4 8983.1 9043.2 9043.6 9051.1 5258.7 5373.1 5415.6 5437.5 5439.9 3508.9 3544.7 3564.5 3607.5 3612.8 1054.8 1061.4 1070.4 1083.9 1091.2 11543.4 11615.4 11624.4 11660.1 11686.4 4567.2 4725.8 4801.2 4848.0 4854.3 8520.5 8939.0 9902.9 9984.0 10060.8 5304.2 5319.8 5325.5 5329.5 5338.3 4372.9 4391.3 4518.5 4866.3 4869.5 3841.0 3944.5 3999.0 4018.4 4084.0 1280.7 1299.0 1307.7 1310.1 1311.4 833.5 884.4 901.4 916.0 920.4 4791.9 5796.7 5901.1 5953.5 6049.3 386.9 421.4 440.8 447.6 449.5 3761.9 3929.2 3936.2 4035.9 4103.7 7807.8 10635.4 10704.4 10728.8 10805.6 10690.9 10759.0 10759.3 10772.4 10781.8 6911.8 8054.8 8253.2 8308.8 8348.7 5744.7 5842.1 5880.8 5894.4 5941.0 9989.9 10047.2 10067.9 10132.4 10137.0 9467.8 9740.7 9896.7 9995.2 10210.3 6939.1 7149.6 7398.2 7404.4 7673.4 8328.7 8528.0 8533.1 8554.7 8557.5 7342.3 7464.4 7624.1 7689.3 7732.6 7333.3 7448.3 7478.0 7553.8 7651.4 6928.1 7069.4 7075.4 7085.3 7098.4 4159.3 4992.5 5004.1 5008.6 5090.1 4903.9 4914.7 5024.8 5066.7 5084.1 22:29:17 INFO - PROCESS | 1654 | 22:29:17 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4681.48 Median:5203.51 stddev:1495.12 (28.7%) stddev-sans-first:1462.72 22:29:17 INFO - PROCESS | 1654 | Values: 301.2 306.4 311.7 313.4 317.8 4972.6 5158.2 5420.9 5547.6 5602.6 5203.0 5204.0 5372.5 5604.5 5612.4 4219.3 4220.5 4224.8 4230.5 4240.7 4062.7 4167.2 4200.7 4226.8 4229.2 4146.0 4161.6 4166.2 4182.1 4193.3 3674.0 3989.9 4197.2 4199.7 4232.7 3063.6 3093.5 3352.3 3364.5 3645.7 4127.6 4891.9 5384.9 5405.2 5441.4 3961.4 4756.7 4761.5 4794.5 4794.7 5224.9 5630.3 5692.4 5822.2 5842.4 3946.3 3949.6 3953.2 3969.1 3973.4 5164.9 5916.6 6112.3 6122.7 6123.8 3749.3 3911.8 3930.4 3950.8 3997.9 3719.7 4015.4 4019.7 4030.9 4054.4 4668.2 4718.4 4742.1 4742.6 4763.2 5737.7 5843.4 5890.2 5971.7 6006.2 1171.8 1215.6 1295.3 1331.9 1349.5 728.6 766.7 902.7 926.3 929.3 5435.2 5485.8 5490.8 5522.9 5551.3 5946.8 5987.0 6026.3 6070.6 6106.2 4709.9 5225.7 5394.0 5464.6 5479.9 2330.6 2507.7 2564.6 2641.8 2648.4 4159.4 4903.4 4971.9 5138.7 5207.3 4545.7 4859.3 5811.7 6358.9 6385.0 5509.9 6178.5 6378.9 6438.4 6458.1 5975.6 6175.3 6176.5 6250.4 6277.7 5257.0 5754.6 5923.9 5950.8 6007.9 5066.0 5583.8 6346.8 6373.6 6536.2 3778.4 3975.2 4000.4 4008.3 4085.3 5829.4 5981.8 6105.0 6120.8 6143.8 5619.5 5782.9 5885.0 5971.9 6013.9 5493.1 5901.6 5960.0 6001.5 6045.0 4879.4 5143.9 5530.0 5853.6 5857.1 4877.2 4933.8 4985.0 5588.8 5923.6 4642.7 5035.9 5737.4 5819.4 5825.4 22:29:17 INFO - PROCESS | 1654 | 22:29:17 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:24604.95 Median:3600.76 stddev:39548.64 (1098.3%) stddev-sans-first:39618.79 22:29:17 INFO - PROCESS | 1654 | Values: 679.3 698.5 701.8 723.9 736.1 15154.2 15244.8 15490.8 15723.4 15942.1 13100.7 14567.4 14597.6 14699.7 14731.3 841.0 868.4 868.7 869.0 872.5 673.9 687.9 767.4 818.6 874.4 873.0 903.1 909.1 913.3 917.5 892.7 902.8 910.7 910.8 911.6 673.2 678.1 678.9 682.2 682.8 14574.9 14723.9 14893.5 14897.5 14900.0 14070.3 14383.9 14511.3 14746.9 14834.1 11595.8 11621.8 11739.9 11796.0 11874.6 3780.7 3822.2 3854.8 3861.4 3994.9 112145.0 113802.0 116314.4 116646.8 119566.0 10144.1 11200.4 11256.5 11269.8 11556.1 22906.3 23075.4 23216.2 23218.4 23244.7 7523.8 8052.4 8063.0 8080.2 8279.5 4372.8 4443.0 4484.7 4511.9 4585.6 3482.4 3575.1 3578.8 3599.5 3602.1 573.9 643.7 655.6 656.0 661.4 520.3 534.0 534.3 572.2 628.7 9001.1 9157.7 9168.2 9185.3 9201.0 553.2 560.6 601.4 611.7 617.7 4266.0 4365.4 4475.8 4481.8 4521.5 101116.0 101187.0 124623.0 139317.0 176131.0 122862.0 144378.3 144709.0 144760.0 150974.2 66422.3 102223.5 123868.0 126114.7 128908.0 9770.6 9798.7 9816.0 9870.8 9873.7 54075.9 55565.7 61143.7 63563.7 63766.0 68171.7 75605.0 94818.5 97502.5 99077.0 11956.6 13217.4 13383.0 13544.9 13573.7 14545.3 14664.3 14980.6 15009.5 15014.6 11311.3 13382.9 13495.0 13747.9 13821.8 13611.5 13613.4 13624.9 13625.1 13962.4 9728.4 10650.4 11379.1 13293.2 13952.2 10994.8 11178.1 11395.6 11645.8 11692.3 8834.3 11202.6 11439.4 11500.4 11507.3 22:29:17 INFO - PROCESS | 1654 | -------- Summary: end -------- 22:29:17 INFO - PROCESS | 1654 | 22:29:18 INFO - TEST-INFO | 1654: exit 0 22:29:18 INFO - TEST-OK | dromaeo_css | took 1115869ms 22:29:18 INFO - TEST-START | kraken 22:29:18 INFO - operating with platform_type : mac_ 22:29:18 INFO - Initialising browser for kraken test... 22:29:18 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmpylkcfe/profile http://localhost:49267/getInfo.html) 22:29:20 INFO - PROCESS | 1662 | 2016-10-06 22:29:20.926 plugin-container[1663:9964] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d1b, name = 'com.apple.tsm.portname' 22:29:20 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:29:20 INFO - PROCESS | 1662 | 2016-10-06 22:29:20.928 plugin-container[1663:9964] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 22:29:20 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:29:20 INFO - PROCESS | 1662 | 2016-10-06 22:29:20.929 plugin-container[1663:9964] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:29:21 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 22:29:21 INFO - PROCESS | 1662 | colorDepth:24 22:29:21 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 22:29:21 INFO - PROCESS | 1662 | __metrics 22:29:21 ERROR - PROCESS | 1662 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 22:29:21 INFO - TEST-INFO | 1662: exit 0 22:29:21 INFO - Browser initialized. 22:29:21 INFO - Running cycle 1/1 for kraken test... 22:29:21 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmpylkcfe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:29:24 INFO - PROCESS | 1664 | 2016-10-06 22:29:24.444 plugin-container[1665:10078] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a1f, name = 'com.apple.tsm.portname' 22:29:24 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:29:24 INFO - PROCESS | 1664 | 2016-10-06 22:29:24.448 plugin-container[1665:10078] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 22:29:24 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:29:24 INFO - PROCESS | 1664 | 2016-10-06 22:29:24.449 plugin-container[1665:10078] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:29:34 INFO - PROCESS | 1664 | RSS: Main: 239800320 22:29:34 INFO - PROCESS | 1664 | 22:29:37 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 22:29:38 INFO - PROCESS | 1664 | RSS: Main: 253599744 22:29:38 INFO - PROCESS | 1664 | 22:29:40 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 22:29:41 INFO - PROCESS | 1664 | RSS: Main: 261160960 22:29:41 INFO - PROCESS | 1664 | 22:29:44 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 22:29:45 INFO - PROCESS | 1664 | RSS: Main: 262103040 22:29:45 INFO - PROCESS | 1664 | 22:29:47 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 22:29:48 INFO - PROCESS | 1664 | RSS: Main: 264208384 22:29:48 INFO - PROCESS | 1664 | 22:29:50 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:29:50 INFO - PROCESS | 1664 | RSS: Main: 264536064 22:29:50 INFO - PROCESS | 1664 | 22:29:53 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 22:29:54 INFO - PROCESS | 1664 | RSS: Main: 264929280 22:29:54 INFO - PROCESS | 1664 | 22:29:57 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 22:29:58 INFO - PROCESS | 1664 | RSS: Main: 265523200 22:29:58 INFO - PROCESS | 1664 | 22:30:01 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 22:30:01 INFO - PROCESS | 1664 | RSS: Main: 265453568 22:30:01 INFO - PROCESS | 1664 | 22:30:03 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:30:03 INFO - PROCESS | 1664 | RSS: Main: 265367552 22:30:03 INFO - PROCESS | 1664 | 22:30:05 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:30:06 INFO - PROCESS | 1664 | RSS: Main: 265404416 22:30:06 INFO - PROCESS | 1664 | 22:30:08 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:30:09 INFO - PROCESS | 1664 | RSS: Main: 265494528 22:30:09 INFO - PROCESS | 1664 | 22:30:12 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:30:12 INFO - PROCESS | 1664 | RSS: Main: 265789440 22:30:12 INFO - PROCESS | 1664 | 22:30:15 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:30:16 INFO - PROCESS | 1664 | RSS: Main: 265875456 22:30:16 INFO - PROCESS | 1664 | 22:30:18 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 22:30:18 INFO - PROCESS | 1664 | RSS: Main: 267730944 22:30:18 INFO - PROCESS | 1664 | 22:30:18 INFO - PROCESS | 1664 | __start_tp_report 22:30:18 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 22:30:18 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 22:30:18 INFO - PROCESS | 1664 | |i|pagename|runs| 22:30:18 INFO - PROCESS | 1664 | |0;ai-astar;113;117;117;119;117;117;118;114;118;117 22:30:18 INFO - PROCESS | 1664 | |1;audio-beat-detection;164;118;115;150;116;117;116;113;172;147 22:30:18 INFO - PROCESS | 1664 | |2;audio-dft;201;204;246;242;203;278;189;271;189;212 22:30:18 INFO - PROCESS | 1664 | |3;audio-fft;99;73;81;109;54;73;51;61;96;56 22:30:18 INFO - PROCESS | 1664 | |4;audio-oscillator;89;71;89;72;68;70;88;69;71;81 22:30:18 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;97;90;116;92;124;130;133;87;91;87 22:30:18 INFO - PROCESS | 1664 | |6;imaging-darkroom;99;100;100;112;171;118;101;99;124;127 22:30:18 INFO - PROCESS | 1664 | |7;imaging-desaturate;92;110;93;90;152;96;149;115;90;93 22:30:18 INFO - PROCESS | 1664 | |8;json-parse-financial;57;56;55;58;58;56;58;56;66;56 22:30:18 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;68;73;82;79;70;67;70;70;70;70 22:30:18 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;92;91;91;90;87;90;79;79;80;104 22:30:18 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;144;170;163;160;153;142;144;151;182;151 22:30:18 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;215;171;211;179;177;175;177;177;173;181 22:30:18 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;71;69;69;71;72;68;69;70;68;70 22:30:18 INFO - PROCESS | 1664 | __end_tp_report 22:30:18 INFO - PROCESS | 1664 | __start_cc_report 22:30:18 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,939 22:30:18 INFO - PROCESS | 1664 | __end_cc_report 22:30:18 INFO - PROCESS | 1664 | __startTimestamp1475818218476__endTimestamp 22:30:18 INFO - PROCESS | 1664 | 22:30:18 INFO - PROCESS | 1664 | ------- Summary: start ------- 22:30:18 INFO - PROCESS | 1664 | Number of tests: 14 22:30:18 INFO - PROCESS | 1664 | 22:30:18 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:116.70 Median:117.00 stddev:1.83 (1.6%) stddev-sans-first:1.36 22:30:18 INFO - PROCESS | 1664 | Values: 113.0 117.0 117.0 119.0 117.0 117.0 118.0 114.0 118.0 117.0 22:30:18 INFO - PROCESS | 1664 | 22:30:18 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:132.80 Median:132.50 stddev:22.97 (17.3%) stddev-sans-first:21.41 22:30:18 INFO - PROCESS | 1664 | Values: 164.0 118.0 115.0 150.0 116.0 117.0 116.0 113.0 172.0 147.0 22:30:18 INFO - PROCESS | 1664 | 22:30:18 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:223.50 Median:227.00 stddev:33.16 (14.6%) stddev-sans-first:34.15 22:30:18 INFO - PROCESS | 1664 | Values: 201.0 204.0 246.0 242.0 203.0 278.0 189.0 271.0 189.0 212.0 22:30:18 INFO - PROCESS | 1664 | 22:30:18 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:75.30 Median:73.00 stddev:20.52 (28.1%) stddev-sans-first:19.89 22:30:18 INFO - PROCESS | 1664 | Values: 99.0 73.0 81.0 109.0 54.0 73.0 51.0 61.0 96.0 56.0 22:30:18 INFO - PROCESS | 1664 | 22:30:18 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:76.80 Median:76.50 stddev:8.92 (11.7%) stddev-sans-first:8.29 22:30:18 INFO - PROCESS | 1664 | Values: 89.0 71.0 89.0 72.0 68.0 70.0 88.0 69.0 71.0 81.0 22:30:18 INFO - PROCESS | 1664 | 22:30:18 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:104.70 Median:88.50 stddev:18.83 (21.3%) stddev-sans-first:19.77 22:30:18 INFO - PROCESS | 1664 | Values: 97.0 90.0 116.0 92.0 124.0 130.0 133.0 87.0 91.0 87.0 22:30:18 INFO - PROCESS | 1664 | 22:30:18 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:115.10 Median:125.50 stddev:22.45 (17.9%) stddev-sans-first:23.05 22:30:18 INFO - PROCESS | 1664 | Values: 99.0 100.0 100.0 112.0 171.0 118.0 101.0 99.0 124.0 127.0 22:30:18 INFO - PROCESS | 1664 | 22:30:18 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:108.00 Median:91.00 stddev:23.96 (26.3%) stddev-sans-first:24.71 22:30:18 INFO - PROCESS | 1664 | Values: 92.0 110.0 93.0 90.0 152.0 96.0 149.0 115.0 90.0 93.0 22:30:18 INFO - PROCESS | 1664 | 22:30:18 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:57.60 Median:57.50 stddev:3.13 (5.5%) stddev-sans-first:3.32 22:30:18 INFO - PROCESS | 1664 | Values: 57.0 56.0 55.0 58.0 58.0 56.0 58.0 56.0 66.0 56.0 22:30:18 INFO - PROCESS | 1664 | 22:30:18 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:70.00 stddev:4.84 (6.9%) stddev-sans-first:4.92 22:30:18 INFO - PROCESS | 1664 | Values: 68.0 73.0 82.0 79.0 70.0 67.0 70.0 70.0 70.0 70.0 22:30:18 INFO - PROCESS | 1664 | 22:30:18 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:90.00 stddev:7.63 (8.5%) stddev-sans-first:7.98 22:30:18 INFO - PROCESS | 1664 | Values: 92.0 91.0 91.0 90.0 87.0 90.0 79.0 79.0 80.0 104.0 22:30:18 INFO - PROCESS | 1664 | 22:30:18 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:156.00 Median:156.50 stddev:12.82 (8.2%) stddev-sans-first:12.85 22:30:18 INFO - PROCESS | 1664 | Values: 144.0 170.0 163.0 160.0 153.0 142.0 144.0 151.0 182.0 151.0 22:30:18 INFO - PROCESS | 1664 | 22:30:18 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.60 Median:178.00 stddev:15.78 (8.9%) stddev-sans-first:11.96 22:30:18 INFO - PROCESS | 1664 | Values: 215.0 171.0 211.0 179.0 177.0 175.0 177.0 177.0 173.0 181.0 22:30:18 INFO - PROCESS | 1664 | 22:30:18 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.70 Median:70.00 stddev:1.34 (1.9%) stddev-sans-first:1.33 22:30:18 INFO - PROCESS | 1664 | Values: 71.0 69.0 69.0 71.0 72.0 68.0 69.0 70.0 68.0 70.0 22:30:18 INFO - PROCESS | 1664 | -------- Summary: end -------- 22:30:18 INFO - PROCESS | 1664 | 22:30:19 INFO - TEST-INFO | 1664: exit 0 22:30:19 INFO - TEST-OK | kraken | took 61182ms 22:30:19 INFO - SUITE-END | took 1177s 22:30:19 INFO - Completed test suite (00:19:37) 22:30:19 INFO - javascript benchmark 22:30:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7317.8374644019705, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2872.8994485193016, 3439.535662685537, 3476.8087755173456, 3476.8087755173456, 3485.024193225896, 5260.421353651095, 5329.25390445337, 5726.9140962885485, 5825.825225243244, 5884.617499862506, 5772.538196944245, 5862.355140934505, 5900.3214630317525, 5940.851478713034, 5997.460228579427, 5230.1108811501945, 5239.711815850127, 5326.014687282865, 5334.493223143814, 5385.703786291752, 5323.973380133113, 5349.8127565535215, 5353.839384818461, 5391.514763671269, 5436.803903141303, 4264.0, 5176.042432150046, 5213.973930130325, 5231.712255825928, 5312.548433383167, 5232.953409318152, 5253.579713622901, 5265.210218467222, 5287.41838397776, 5303.946960530384, 5195.19474481456, 5216.191490319006, 5228.947710522884, 5256.526912577848, 5267.420583735786, 5675.34733505648, 5682.403347648478, 5735.1684005818925, 5744.339400968858, 5767.307923049261, 5571.526420254283, 6008.218931538916, 6239.2824825145235, 6245.46913512352, 6248.031555108965, 5129.281900533928, 6261.906071408933, 6266.968665156691, 6335.04974253861, 6340.0, 5827.426594819375, 5889.028310328834, 5913.408659134052, 5984.0, 6004.279486461653, 6296.275928268216, 6423.74305027803, 6456.483481321483, 6462.0, 6463.48292136628, 4623.214053610895, 4749.833755818529, 5145.974270128625, 6207.068939659087, 6212.943799554087, 5398.865028374322, 5623.634463759842, 6146.692665366714, 6159.692015399212, 6295.874082518324, 6165.260168779775, 6173.166622505904, 6185.690715464209, 6190.442860142609, 6257.843553911189, 5741.598088133791, 5788.034802043392, 6217.96891015542, 6231.968840155861, 6265.0, 4312.504062032844, 4907.141250281186, 4967.82612608557, 4998.525140111757, 5034.295198672189, 4494.617957473586, 4514.25514790056, 4522.140793249272, 4562.201614717411, 4572.9314060289125, 5969.970150149222, 6062.272527296753, 6065.181200537959, 6074.726637301332, 6083.939160608337, 6156.230471191101, 6201.906971395432, 6294.8426289342215, 6327.620342779448, 6341.746330146742, 6016.067509536029, 6035.939640603626, 6081.665508397081, 6084.817455476343, 6142.140100385949, 5497.697626630574, 5532.114861622121, 5538.446155384429, 5543.667379957215, 5548.445155484419, 6256.968715156395, 6521.543491955613, 6523.7064332105165, 6525.64108974011, 6559.180102487189, 6192.411720886508, 6204.162438070803, 6233.2208473940755, 6237.75048998035, 6350.015747558951, 6489.4808415325715, 6510.641914694737, 6519.217693876575, 6521.217453905372, 6529.967350163219, 6225.8132256032395, 6253.187085679015, 6254.312025677263, 6257.374262573706, 6267.874642507216, 6145.095020543646, 6202.689865506797, 6208.503319734501, 6213.0, 6226.875462490634, 6202.4417802398, 6206.689665516616, 6212.192414986023, 6212.378762123752, 6213.068039794067, 4893.584045356185, 6153.907691384543, 6158.846028849135, 6180.536459765445, 6210.192674952227, 5832.183494310885, 6001.7599296027665, 6068.817935461943, 6074.0, 6080.665563394055, 6167.198264225622, 6178.320384757763, 6187.381261873957, 6190.690465476618, 6192.195014648067, 5882.794102206402, 6089.54328425361, 6114.174586430775, 6131.96934015327, 6148.200733904564], "value": 5721.227569355962, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [457.8397560853701, 469.4577762684101, 529.6398449054641, 530.978760849566, 532.9600279979003, 34001.0, 39322.60677393225, 41916.43710284622, 41980.95047623811, 42135.36796948048, 35340.823295883485, 43033.0, 43603.3459498107, 43604.56395436045, 43706.562934370726, 9691.079347461997, 9828.705138845846, 9899.307048506607, 9900.25748068894, 9912.157466615346, 9755.219582433423, 9842.753931151707, 9852.113309802116, 9869.703908882744, 9885.357451765612, 9829.21366290695, 9848.753781155458, 9862.408255504657, 9870.506474676273, 9915.652952146676, 7915.406344524184, 9707.223422126213, 9816.361936474106, 9868.309218354718, 9868.654597089104, 7581.583012934287, 7767.37860971121, 9689.660861869801, 9701.854472182922, 9724.0, 40424.787256382355, 40775.7961210195, 40803.0, 40918.38622420666, 40991.7950410249, 2804.4531316393313, 3329.200991761984, 3429.5713035870517, 3543.167355671415, 3727.1986522897705, 30743.38513229745, 31328.686713133033, 31420.685793142235, 31731.52402713961, 31804.522932156036, 2925.605043319146, 3126.7643634800997, 3131.530270459449, 3246.805191688506, 3274.918127046819, 303292.0, 372551.0, 381954.0, 384996.0, 388855.99999999715, 60007.699961499915, 60044.39955600388, 60386.39613603896, 60417.697911510164, 60457.69771151116, 62762.686186568775, 63032.68483657552, 63125.684371577845, 63199.36800632027, 63420.04869926955, 12863.614091577268, 15854.603634908988, 16158.75761863573, 16159.757603635955, 16195.0, 12131.636050918487, 12151.392430378446, 12169.817452738216, 12227.75544489123, 12255.69360765988, 16081.839181608268, 16851.820372573802, 16918.154092295335, 16927.291633338606, 16942.57643558921, 6941.652917354112, 7051.224365319811, 7079.964600176966, 7104.325089116525, 7145.8928116078305, 845.902721187059, 953.8187744328555, 964.9565769540387, 989.9010098990187, 1054.9630762923261, 29763.0, 30187.849060754994, 30265.07848734408, 30466.390672186433, 30922.0, 1034.3586976074882, 1039.8544203811473, 1065.7016035510069, 1072.6781965410346, 1137.1357768096307, 15684.529464116094, 15778.447754328543, 15814.288357023961, 15844.207789610704, 16193.109378984269, 509487.0, 588185.0, 588810.0, 591103.0, 646758.0, 57034.0, 58074.0, 58199.41800582025, 58613.82772344529, 58749.412505874396, 1331.2811082015603, 1338.3107699534748, 1373.1143412498882, 1386.861313868605, 1401.8808401286005, 29272.0, 29412.852935735184, 30156.69843301539, 30290.0, 30689.846550767998, 50553.988920222124, 51098.48901510937, 51153.488465114875, 51183.976320474125, 51188.48811511837, 562593.0, 586380.0, 589116.0, 589116.0, 590221.0, 25913.740862591134, 26681.866590666923, 26918.73081269162, 40597.79701101476, 40807.0, 46486.0, 47268.7636561815, 47446.0, 47567.286490701976, 47931.5206847941, 38709.419358710205, 39126.6087339123, 39400.605993939695, 39792.60207397889, 40316.79841600773, 38583.03542411462, 38770.61229387783, 38958.0, 40025.0, 40130.0, 38473.615263847, 38867.63963261272, 39248.2150356997, 39337.40993885037, 39434.0, 32231.033069007968, 32510.83744581262, 34115.97652070442, 35179.64820351764, 35289.0, 23774.405639859142, 24967.501949883066, 26031.21906342813, 26176.8691156543, 26274.737252627994], "value": 19784.426053856023, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.77077982430694, 377.5846568774348, 382.67855001798495, 389.33035179491264, 403.05081532989806, 17353.7396939046, 18849.905750471247, 21314.14743410262, 21648.0, 21745.347639570817, 19759.901200493976, 19974.60050798983, 20635.69046464304, 20721.68917466239, 21032.8948355258, 5502.642328248671, 5574.749136288857, 5904.350521442638, 6196.75212991482, 6244.500439964814, 3935.626115519021, 4135.400366946791, 6156.5382596305235, 6243.719032643542, 6254.624722516641, 5914.9408505915035, 6188.226471691039, 6233.28317243516, 6260.186175797163, 6269.655168965705, 4995.200767877141, 6016.4885984691355, 6259.780907668232, 6279.434850863444, 6291.905621415683, 6123.846903827395, 6124.35694252106, 6129.877402451971, 6132.988056970595, 6143.078538219258, 21561.353159405244, 24416.511669766503, 24576.631350529755, 24598.893049812803, 24816.3795905102, 2120.68189771534, 2179.949602253066, 2213.8118259947923, 2286.4969706664674, 2300.9309720708407, 9844.409335439897, 9930.45382503962, 9933.552990115462, 10447.79104417915, 10468.42423666698, 1646.2674110020926, 1856.0255865670497, 1868.5795695968434, 1915.6551820672273, 1927.3832373640441, 107076.39385409225, 109835.45082274618, 111682.88317116805, 117284.0, 119841.80158198481, 9734.36726612768, 11180.776384472265, 11363.261388009752, 11532.596359127558, 11752.0, 14561.199134047518, 14592.635184120481, 14592.927035364755, 14607.92696036513, 14693.706125877423, 7975.122736498981, 8150.796230094176, 8193.590320483952, 8209.507429554245, 8231.794205144799, 7889.250521200477, 7896.486728362638, 8024.0, 8044.959775201087, 8055.234752698484, 4410.316400957792, 4520.61574766142, 4525.570070843265, 4570.9314360284625, 4609.631229501631, 1617.563257920355, 1690.3069741405964, 1913.7990510996424, 1944.6305202011572, 1967.1344608372272, 782.6282515805061, 935.242453612576, 964.6671898195111, 977.6529332087007, 981.8379967305458, 16378.672426551402, 18646.906765466356, 20708.068136933874, 21189.364319070453, 21331.146754129662, 924.3638759395832, 932.2495391210141, 940.6801687426171, 947.1664934857281, 1007.1338648762058, 9740.95129524362, 10537.63118290856, 10583.25917185805, 10802.08182304513, 10803.675889723321, 504531.0, 542013.0, 585282.0, 607416.0, 623715.051397939, 95356.0, 140774.0, 145173.0, 145379.2731036338, 145792.0, 890.2343984173606, 941.275218082073, 994.786120983992, 998.9850152247722, 1016.11090295991, 16223.53988141073, 18353.449396518125, 18354.541136471697, 18874.0, 18951.715724264424, 39693.404598930465, 46797.830054247555, 47405.0, 48162.27756583584, 49819.750901245265, 72291.63854180695, 73857.6307118461, 74662.25337746553, 74734.0, 74742.87885681828, 7784.88322675149, 7859.52842829432, 8001.8399632008195, 8030.156833532628, 8133.552654604054, 11725.589604364148, 11947.522099115939, 12056.578019769266, 12094.637160885188, 12312.569060082855, 9966.352187107816, 10527.368357898551, 10676.0, 10731.624393146514, 10907.83638245411, 8721.389502734748, 9890.208783297176, 10428.217883658604, 10605.151587872797, 11227.494762735532, 8482.872756908528, 10949.894941808281, 11130.833037504282, 11216.943915280697, 11263.26788758728, 6899.551529150589, 7065.540739851893, 7372.705091796268, 7436.293552112541, 7888.0, 10442.321249118782, 10904.38294809256, 10910.563577457131, 11113.333200008025, 11465.197436179387], "value": 9331.747157803264, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1434.8995570310078, 1530.5561387197713, 1741.3295881085778, 1849.066221558113, 1886.094674556213, 8006.399520035991, 8255.174482551756, 8465.66137354506, 9012.188902998729, 9022.684206052798, 7053.964730176342, 8490.575471226444, 8800.867986980184, 8837.248833849115, 8907.821843563124, 2342.7071616047992, 2380.357303528055, 2381.2380038387723, 2388.7133543974746, 2395.7063185879606, 2357.3752955466816, 2371.620540713486, 2388.450656349041, 2406.6750988616486, 2409.180878501309, 2003.5692326149874, 2379.940501487459, 2390.1634427950166, 2402.0, 2410.602250628644, 2032.3699653107583, 2335.4978679583887, 2356.375560476475, 2389.187676190086, 2396.2930935374106, 2377.9048838046456, 2385.6063749481314, 2390.306811024801, 2391.4380120671626, 2392.7248366437734, 8968.372213945026, 8983.101689830964, 9043.231325337354, 9043.638254469814, 9051.094890510962, 5258.711615654151, 5373.059714549938, 5415.566754659618, 5437.531866396091, 5439.918401223985, 3508.8771892983623, 3544.7341449391397, 3564.500969864221, 3607.5310209672575, 3612.801295928723, 1054.8154073037188, 1061.4215252687304, 1070.3631339353071, 1083.9458027098613, 1091.2197778588347, 11543.365114918675, 11615.419229038514, 11624.418779061098, 11660.06719462441, 11686.415679216005, 4567.1779079765965, 4725.787339569728, 4801.231802911518, 4847.975760121248, 4854.296127061556, 8520.531370774543, 8939.0, 9902.900970990198, 9983.950080249697, 10060.798784024279, 5304.151335786256, 5319.840404787863, 5325.467453254643, 5329.467053294639, 5338.252644629755, 4372.868813935587, 4391.253486907233, 4518.502964673883, 4866.270059491105, 4869.537393947569, 3841.0, 3944.546377166605, 3998.9600103998587, 4018.417329487208, 4083.959160408358, 1280.7374488230078, 1298.9865708152327, 1307.7057662025968, 1310.1418570836117, 1311.4295281552554, 833.5373867503472, 884.3544212724747, 901.3735453859636, 915.9587818548181, 920.4201353147608, 4791.856244312677, 5796.710164491843, 5901.085331773582, 5953.493953014043, 6049.334573196945, 386.86846472199585, 421.42475520913734, 440.7730019040197, 447.55915423308016, 449.46962584151015, 3761.9059523512133, 3929.214157168578, 3936.1537269486626, 4035.858744943913, 4103.712740108163, 7807.765767026998, 10635.361878287327, 10704.411257380918, 10728.839067413996, 10805.621803236849, 10690.946545267223, 10759.031687148194, 10759.30064545802, 10772.353658780497, 10781.784364312669, 6911.75808846688, 8054.838903221903, 8253.215944485264, 8308.792280193044, 8348.707795227137, 5744.712764361681, 5842.123681447817, 5880.794172203952, 5894.351621321737, 5941.0, 9989.850152247576, 10047.196224302184, 10067.899321006696, 10132.392056476636, 10137.0, 9467.810643787223, 9740.659076932274, 9896.703098907044, 9995.150412215044, 10210.336328138646, 6939.132608423947, 7149.60677162761, 7398.2231865655485, 7404.370628496638, 7673.386129109769, 8328.708495202638, 8527.957360213159, 8533.104024077631, 8554.700585479479, 8557.48655080697, 7342.339189472973, 7464.365528930101, 7624.123225829101, 7689.269519395649, 7732.574708391093, 7333.339999400079, 7448.255174482725, 7478.027856378636, 7553.848923021619, 7651.4261430391825, 6928.064711263914, 7069.434445244266, 7075.363217310467, 7085.291470852873, 7098.36114749675, 4159.292920203512, 4992.525710057539, 5004.099262132851, 5008.649394542347, 5090.134677104827, 4903.8528844134735, 4914.70511769295, 5024.773885175103, 5066.746662666926, 5084.059449001798], "value": 4483.344885195822, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.20181518974715, 306.356651032831, 311.72100969632186, 313.3857639027506, 317.8013741411618, 4972.577330926875, 5158.226266060093, 5420.91868621971, 5547.611667183299, 5602.60781745278, 5203.0, 5204.011237864803, 5372.52255629702, 5604.467575580324, 5612.354579223391, 4219.261629214883, 4220.493540775111, 4224.809883555232, 4230.534641189467, 4240.703150779446, 4062.6546743526837, 4167.2082304362075, 4200.7479551227025, 4226.788660566975, 4229.181451975649, 4146.0, 4161.625453709165, 4166.208420400114, 4182.100848317611, 4193.266178418779, 3674.026383008505, 3989.920201595981, 4197.181549597799, 4199.727017743852, 4232.661387089025, 3063.6170478690165, 3093.489574220251, 3352.2960178362573, 3364.5121457388786, 3645.671889529955, 4127.587241275878, 4891.877703057452, 5384.946150538483, 5405.216418432279, 5441.374241962186, 3961.3661814109605, 4756.66703330767, 4761.452432970218, 4794.489735733233, 4794.688345257547, 5224.947750522484, 5630.324361076697, 5692.37383887772, 5822.243108395924, 5842.386549412336, 3946.3093958557424, 3949.637375105584, 3953.2488827122756, 3969.0672691917375, 3973.3841254605295, 5164.870878228036, 5916.615419997686, 6112.266528016622, 6122.663253521055, 6123.8162855114415, 3749.347518083092, 3911.843526258918, 3930.3514920037874, 3950.8195902298926, 3997.9216003677006, 3719.7210209233876, 4015.397690346413, 4019.698522610816, 4030.9442184486124, 4054.432379466877, 4668.229742092517, 4718.41019872516, 4742.051589682077, 4742.609034540251, 4763.189987804682, 5737.684427356452, 5843.3864444233595, 5890.2342695449315, 5971.671558064349, 6006.189164462741, 1171.8066519024435, 1215.598852378713, 1295.3070107492447, 1331.9200847949155, 1349.50742978814, 728.639323534843, 766.7431410477552, 902.6750369866843, 926.346925417579, 929.3262384770987, 5435.211894275308, 5485.807996720096, 5490.835274941758, 5522.9447705523235, 5551.333839939233, 5946.762129514771, 5987.0, 6026.306974697878, 6070.573415247413, 6106.2061931948565, 4709.858704238878, 5225.686458812484, 5394.0, 5464.562834973192, 5479.890402191933, 2330.603797354454, 2507.7366876478072, 2564.6279240606186, 2641.815072944914, 2648.3776312566533, 4159.396887451363, 4903.387076615423, 4971.925421118686, 5138.707131654402, 5207.270982062439, 4545.659075569345, 4859.2954021667365, 5811.738471768781, 6358.904616430757, 6385.042243663488, 5509.917351239735, 6178.505719542426, 6378.904316435257, 6438.356164383524, 6458.128152699325, 5975.55183361241, 6175.2589689237575, 6176.505879529716, 6250.406211409909, 6277.717502712298, 5257.0, 5754.597178197486, 5923.940760592339, 5950.7917222896995, 6007.90988135187, 5065.974670126626, 5583.776648933997, 6346.809595712136, 6373.565947661739, 6536.215654121345, 3778.376567866271, 3975.204959008152, 4000.419939108871, 4008.3386241269864, 4085.346344584852, 5829.417058294137, 5981.782843095521, 6104.969475152596, 6120.7551697931585, 6143.754249829957, 5619.466150715675, 5782.942170578409, 5885.0, 5971.940280597139, 6013.939860601513, 5493.121100623881, 5901.645901245939, 5959.970200148971, 6001.519878409804, 6044.969775151096, 4879.365142822836, 5143.92284115746, 5529.972350138223, 5853.560982926212, 5857.062869940789, 4877.195262781744, 4933.827316043921, 4984.975075124601, 5588.798408342226, 5923.644581325134, 4642.651801114863, 5035.917277785294, 5737.368889422244, 5819.388964158702, 5825.388334225015], "value": 4193.5485639297685, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.3376457953495, 698.4691634357887, 701.7824474412932, 723.8660847743165, 736.1203361982433, 15154.242287885603, 15244.84755152448, 15490.845091549081, 15723.449679261243, 15942.123183224918, 13100.737985240314, 14567.417303307882, 14597.635059123528, 14699.706005879876, 14731.337089830957, 840.9621567029499, 868.3660927522913, 868.7437206024207, 868.956552172392, 872.4547158026234, 673.885439475288, 687.8968154776774, 767.3630886364305, 818.5620692929284, 874.4359887872318, 872.9956350218272, 903.0878812399458, 909.0954500272238, 913.3287034029983, 917.4586993673731, 892.7009451833643, 902.8465160922659, 910.7358865928873, 910.7723069232692, 911.5761171055458, 673.1652750589283, 678.1489231015045, 678.932106789322, 682.2290811383106, 682.8327059870314, 14574.927125364411, 14723.926380368137, 14893.47872824446, 14897.47858824936, 14900.0314979526, 14070.2964851757, 14383.928080359636, 14511.274436278143, 14746.926265368711, 14834.10995340283, 11595.76808463826, 11621.825672614917, 11739.941300293443, 11795.992495908642, 11874.584389546411, 3780.7353485256044, 3822.197338558906, 3854.843546935936, 3861.3821788513983, 3994.940075898864, 112145.0, 113802.0, 116314.41842790817, 116646.83353166445, 119566.0, 10144.137748291478, 11200.38397888124, 11256.493457794419, 11269.774604507864, 11556.133290003116, 22906.31281061571, 23075.423114422272, 23216.187433439747, 23218.419539511648, 23244.65133023006, 7523.774286771406, 8052.355811535063, 8062.959685201537, 8080.19198080199, 8279.54462504556, 4372.846950356722, 4442.955570444319, 4484.730916145042, 4511.932321015187, 4585.587297143273, 3482.425399809003, 3575.0704816747834, 3578.8031658259047, 3599.460080987873, 3602.063463499509, 573.896698594257, 643.723199024424, 655.6492276631978, 655.9639219842954, 661.4080398043724, 520.2664243416764, 533.9893202135936, 534.3080710479937, 572.2132068405942, 628.7233617208414, 9001.144891235323, 9157.679481218125, 9168.220701240336, 9185.311101667268, 9201.0, 553.239295968044, 560.6075746977051, 601.3986013986014, 611.7430679114782, 617.718937883262, 4265.957340426556, 4365.432493775852, 4475.820967161342, 4481.7983190756495, 4521.502634710179, 101116.0, 101187.0, 124623.0, 139317.0, 176131.0, 122862.0, 144378.2781086109, 144709.0, 144760.0, 150974.22564435756, 66422.33577664258, 102223.4888825566, 123868.0, 126114.7388526103, 128908.0, 9770.609175632895, 9798.701881374496, 9816.030295038208, 9870.753231169278, 9873.654422095193, 54075.918481630935, 55565.72217138889, 61143.69428152831, 63563.682181588796, 63766.04350934647, 68171.65914170397, 75605.0, 94818.52590737001, 97502.51248743995, 99077.0, 11956.58151964677, 13217.40521676546, 13383.0, 13544.93227533856, 13573.660658483617, 14545.345459454138, 14664.3401046951, 14980.625484362978, 15009.549713508612, 15014.624634384229, 11311.264767790068, 13382.866171338552, 13495.0, 13747.937809328681, 13821.79267310971, 13611.52359667407, 13613.387397566936, 13624.93187534056, 13625.114367566077, 13962.441502339792, 9728.416295022322, 10650.414227217578, 11379.069525550314, 13293.20240785556, 13952.16287022782, 10994.780104398027, 11178.105751539695, 11395.601153959571, 11645.825312620149, 11692.298462092302, 8834.325273302391, 11202.551897924317, 11439.428028598702, 11500.367479788692, 11507.252028618113], "value": 7732.006045638027, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1580.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 117.0, 117.0, 119.0, 117.0, 117.0, 118.0, 114.0, 118.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 118.0, 115.0, 150.0, 116.0, 117.0, 116.0, 113.0, 172.0, 147.0], "value": 132.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 204.0, 246.0, 242.0, 203.0, 278.0, 189.0, 271.0, 189.0, 212.0], "value": 223.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 73.0, 81.0, 109.0, 54.0, 73.0, 51.0, 61.0, 96.0, 56.0], "value": 75.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 89.0, 72.0, 68.0, 70.0, 88.0, 69.0, 71.0, 81.0], "value": 76.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 90.0, 116.0, 92.0, 124.0, 130.0, 133.0, 87.0, 91.0, 87.0], "value": 104.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 100.0, 100.0, 112.0, 171.0, 118.0, 101.0, 99.0, 124.0, 127.0], "value": 115.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 110.0, 93.0, 90.0, 152.0, 96.0, 149.0, 115.0, 90.0, 93.0], "value": 108.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 55.0, 58.0, 58.0, 56.0, 58.0, 56.0, 66.0, 56.0], "value": 57.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 82.0, 79.0, 70.0, 67.0, 70.0, 70.0, 70.0, 70.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 91.0, 90.0, 87.0, 90.0, 79.0, 79.0, 80.0, 104.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 170.0, 163.0, 160.0, 153.0, 142.0, 144.0, 151.0, 182.0, 151.0], "value": 156.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [215.0, 171.0, 211.0, 179.0, 177.0, 175.0, 177.0, 177.0, 173.0, 181.0], "value": 183.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 69.0, 71.0, 72.0, 68.0, 69.0, 70.0, 68.0, 70.0], "value": 69.7, "unit": "score"}]}]} 22:30:19 INFO - Return code: 0 22:30:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:30:19 ERROR - # TBPL SUCCESS # 22:30:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:30:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:30:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:30:19 INFO - 2016-10-06 22:30:19.783 screenresolution[1670:10470] starting screenresolution argv=screenresolution get 22:30:19 INFO - 2016-10-06 22:30:19.786 screenresolution[1670:10470] Display 0: 1600x1200x32@60 22:30:19 INFO - 2016-10-06 22:30:19.792 screenresolution[1671:10473] starting screenresolution argv=screenresolution list 22:30:19 INFO - Available Modes on Display 0 22:30:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:30:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:30:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:30:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:30:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:30:20 INFO - Intel Iris: 22:30:20 INFO - Chipset Model: Intel Iris 22:30:20 INFO - Type: GPU 22:30:20 INFO - Bus: Built-In 22:30:20 INFO - VRAM (Dynamic, Max): 1536 MB 22:30:20 INFO - Vendor: Intel (0x8086) 22:30:20 INFO - Device ID: 0x0a2e 22:30:20 INFO - Revision ID: 0x0009 22:30:20 INFO - Displays: 22:30:20 INFO - Display: 22:30:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:30:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:30:20 INFO - Display Serial Number: 1600x1200 60 22:30:20 INFO - Main Display: Yes 22:30:20 INFO - Mirror: Off 22:30:20 INFO - Online: Yes 22:30:20 INFO - Rotation: Supported 22:30:20 INFO - Return code: 0 22:30:20 INFO - Running post-action listener: _resource_record_post_action 22:30:20 INFO - [mozharness: 2016-10-07 05:30:20.028126Z] Finished run-tests step (success) 22:30:20 INFO - Running post-run listener: _resource_record_post_run 22:30:20 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 38996480; Write bytes: 711672320; Read time: 384; Write time: 2056 22:30:20 INFO - TinderboxPrint: CPU usage
54.4% 22:30:20 INFO - TinderboxPrint: I/O read bytes / time
38,996,480 / 384 22:30:20 INFO - TinderboxPrint: I/O write bytes / time
711,672,320 / 2,056 22:30:20 INFO - TinderboxPrint: CPU idle
2,213.3 (45.6%) 22:30:20 INFO - TinderboxPrint: CPU system
979.6 (20.2%) 22:30:20 INFO - TinderboxPrint: CPU user
1,660.8 (34.2%) 22:30:20 INFO - TinderboxPrint: Swap in / out
446,537,728 / 0 22:30:20 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 414124544; Write bytes: 410506752; Read time: 31108; Write time: 786 22:30:20 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 29868544; Write bytes: 299842560; Read time: 285; Write time: 1265 22:30:20 INFO - Running post-run listener: _upload_blobber_files 22:30:20 INFO - Blob upload gear active. 22:30:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:30:20 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 22:30:20 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'] 22:30:20 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 22:30:20 INFO - (blobuploader) - INFO - Open directory for files ... 22:30:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:30:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:30:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:30:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:30:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:30:22 INFO - (blobuploader) - INFO - Done attempting. 22:30:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:30:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:30:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:30:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:30:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:30:23 INFO - (blobuploader) - INFO - Done attempting. 22:30:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:30:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:30:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:30:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:30:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:30:25 INFO - (blobuploader) - INFO - Done attempting. 22:30:25 INFO - (blobuploader) - INFO - Iteration through files over. 22:30:25 INFO - Return code: 0 22:30:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:30:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:30:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d754cf1dcab4a6a51033523cc9b5e973b3a871ce1b482b86e32cbdc270d914717d7cead4e9896680d9763eec8062810154a7d6de2d41fe2f33746136677795d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00bc61218fd4d96bc77d58e5731d8e19bb89f61258c7ba2409afc30a7063a7a88d36684ec27b58fb468940ff6dcc506b166d6d2637f553c9d9a78a70f63cf5b9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/561d60d72abcf1b8b10a65d83376ef24533de09b309b36862324928ba37e0c07922b05b26b5d40e2cb2effde3558466c5865a9819c7a4210a9eae8e0cdd71449"} 22:30:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:30:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:30:25 INFO - Contents: 22:30:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d754cf1dcab4a6a51033523cc9b5e973b3a871ce1b482b86e32cbdc270d914717d7cead4e9896680d9763eec8062810154a7d6de2d41fe2f33746136677795d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00bc61218fd4d96bc77d58e5731d8e19bb89f61258c7ba2409afc30a7063a7a88d36684ec27b58fb468940ff6dcc506b166d6d2637f553c9d9a78a70f63cf5b9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/561d60d72abcf1b8b10a65d83376ef24533de09b309b36862324928ba37e0c07922b05b26b5d40e2cb2effde3558466c5865a9819c7a4210a9eae8e0cdd71449"} 22:30:25 INFO - Running post-run listener: copy_logs_to_upload_dir 22:30:25 INFO - Copying logs to upload dir... 22:30:25 INFO - mkdir: /builds/slave/test/build/upload/logs 22:30:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.428331 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-06 22:30:25.353200) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-06 22:30:25.356784) ========= 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.Ut9yHJUrjq/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.wFJmWjRKjh/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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/d754cf1dcab4a6a51033523cc9b5e973b3a871ce1b482b86e32cbdc270d914717d7cead4e9896680d9763eec8062810154a7d6de2d41fe2f33746136677795d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00bc61218fd4d96bc77d58e5731d8e19bb89f61258c7ba2409afc30a7063a7a88d36684ec27b58fb468940ff6dcc506b166d6d2637f553c9d9a78a70f63cf5b9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/561d60d72abcf1b8b10a65d83376ef24533de09b309b36862324928ba37e0c07922b05b26b5d40e2cb2effde3558466c5865a9819c7a4210a9eae8e0cdd71449"} build_url:https://queue.taskcluster.net/v1/task/KIjDUPkITZyWK8ev6u8BXw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011051 build_url: 'https://queue.taskcluster.net/v1/task/KIjDUPkITZyWK8ev6u8BXw/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/d754cf1dcab4a6a51033523cc9b5e973b3a871ce1b482b86e32cbdc270d914717d7cead4e9896680d9763eec8062810154a7d6de2d41fe2f33746136677795d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00bc61218fd4d96bc77d58e5731d8e19bb89f61258c7ba2409afc30a7063a7a88d36684ec27b58fb468940ff6dcc506b166d6d2637f553c9d9a78a70f63cf5b9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/561d60d72abcf1b8b10a65d83376ef24533de09b309b36862324928ba37e0c07922b05b26b5d40e2cb2effde3558466c5865a9819c7a4210a9eae8e0cdd71449"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-06 22:30:25.390377) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 22:30:25.390762) ========= 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.Ut9yHJUrjq/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.wFJmWjRKjh/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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.005269 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 22:30:25.440843) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-06 22:30:25.441209) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-06 22:30:25.441607) ========= ========= Total master_lag: 0.28 =========