builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0135 starttime: 1475635747.94 results: success (0) buildid: 20161004170101 builduid: dab5411d79224b8281d16f72b567b0d8 revision: 3470e326025c62381dc5f7c06629dbe5dbd7f242 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-04 19:49:07.940071) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-04 19:49:07.940474) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-04 19:49:07.940782) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-04 19:49:07.983765) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 19:49:07.984076) ========= 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.ToFNlNtEtz/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.4KaQJvIbIY/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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-04 19:49:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.95M=0.001s 2016-10-04 19:49:08 (7.95 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.159331 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 19:49:08.165993) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 19:49:08.166349) ========= 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.ToFNlNtEtz/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.4KaQJvIbIY/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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.021098 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 19:49:08.214775) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-04 19:49:08.215173) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3470e326025c62381dc5f7c06629dbe5dbd7f242 --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 3470e326025c62381dc5f7c06629dbe5dbd7f242 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ToFNlNtEtz/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.4KaQJvIbIY/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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-04 19:49:08,283 truncating revision to first 12 chars 2016-10-04 19:49:08,283 Setting DEBUG logging. 2016-10-04 19:49:08,283 attempt 1/10 2016-10-04 19:49:08,283 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3470e326025c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-04 19:49:09,054 unpacking tar archive at: mozilla-central-3470e326025c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.051946 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-04 19:49:09.288470) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-04 19:49:09.288800) ========= script_repo_revision: 3470e326025c62381dc5f7c06629dbe5dbd7f242 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-04 19:49:09.289218) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-04 19:49:09.289523) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-04 19:49:09.307218) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-04 19:49:09.307530) ========= /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.ToFNlNtEtz/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.4KaQJvIbIY/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 19:49:09 INFO - MultiFileLogger online at 20161004 19:49:09 in /builds/slave/test 19:49:09 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 19:49:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:49:09 INFO - {'append_to_log': False, 19:49:09 INFO - 'base_work_dir': '/builds/slave/test', 19:49:09 INFO - 'blob_upload_branch': 'Firefox', 19:49:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:49:09 INFO - 'branch': 'Firefox', 19:49:09 INFO - 'buildbot_json_path': 'buildprops.json', 19:49:09 INFO - 'config_files': ('talos/mac_config.py',), 19:49:09 INFO - 'default_actions': ('clobber', 19:49:09 INFO - 'read-buildbot-config', 19:49:09 INFO - 'download-and-extract', 19:49:09 INFO - 'populate-webroot', 19:49:09 INFO - 'create-virtualenv', 19:49:09 INFO - 'install', 19:49:09 INFO - 'run-tests'), 19:49:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:49:09 INFO - 'download_minidump_stackwalk': True, 19:49:09 INFO - 'download_symbols': 'ondemand', 19:49:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:49:09 INFO - 'tooltool.py': '/tools/tooltool.py', 19:49:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:49:09 INFO - '/tools/misc-python/virtualenv.py')}, 19:49:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:49:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:49:09 INFO - 'installer_path': 'installer.exe', 19:49:09 INFO - 'log_level': 'info', 19:49:09 INFO - 'log_name': 'talos', 19:49:09 INFO - 'log_to_console': True, 19:49:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:49:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:49:09 INFO - 'opt_config_files': (), 19:49:09 INFO - 'pip_index': False, 19:49:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:49:09 INFO - 'cmd': ('bash', 19:49:09 INFO - '-c', 19:49:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:49:09 INFO - 'enabled': True, 19:49:09 INFO - 'halt_on_failure': False, 19:49:09 INFO - 'name': 'check_screen_resolution'},), 19:49:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:49:09 INFO - 'cmd': ('bash', 19:49:09 INFO - '-c', 19:49:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:49:09 INFO - 'enabled': True, 19:49:09 INFO - 'halt_on_failure': False, 19:49:09 INFO - 'name': 'check_screen_resolution'},), 19:49:09 INFO - 'run_cmd_checks_enabled': True, 19:49:09 INFO - 'sps_profile': False, 19:49:09 INFO - 'sps_profile_interval': 0, 19:49:09 INFO - 'suite': 'dromaeojs-e10s', 19:49:09 INFO - 'system_bits': '32', 19:49:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:49:09 INFO - 'title': 't-yosemite-r7-0135', 19:49:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:49:09 INFO - 'use_talos_json': True, 19:49:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:49:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:49:09 INFO - 'work_dir': 'build'} 19:49:09 INFO - [mozharness: 2016-10-05 02:49:09.507172Z] Running clobber step. 19:49:09 INFO - Running pre-action listener: _resource_record_pre_action 19:49:09 INFO - Running main action method: clobber 19:49:09 INFO - rmtree: /builds/slave/test/build 19:49:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:49:11 INFO - Running post-action listener: _resource_record_post_action 19:49:11 INFO - [mozharness: 2016-10-05 02:49:11.145826Z] Finished clobber step (success) 19:49:11 INFO - [mozharness: 2016-10-05 02:49:11.145956Z] Running read-buildbot-config step. 19:49:11 INFO - Running pre-action listener: _resource_record_pre_action 19:49:11 INFO - Running main action method: read_buildbot_config 19:49:11 INFO - Using buildbot properties: 19:49:11 INFO - { 19:49:11 INFO - "project": "", 19:49:11 INFO - "product": "firefox", 19:49:11 INFO - "script_repo_revision": "production", 19:49:11 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 19:49:11 INFO - "repository": "", 19:49:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 19:49:11 INFO - "buildid": "20161004170101", 19:49:11 INFO - "pgo_build": "False", 19:49:11 INFO - "basedir": "/builds/slave/test", 19:49:11 INFO - "buildnumber": 44, 19:49:11 INFO - "slavename": "t-yosemite-r7-0135", 19:49:11 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 19:49:11 INFO - "platform": "yosemite_r7", 19:49:11 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:49:11 INFO - "branch": "mozilla-central", 19:49:11 INFO - "repo_path": "mozilla-central", 19:49:11 INFO - "revision": "3470e326025c62381dc5f7c06629dbe5dbd7f242", 19:49:11 INFO - "stage_platform": "macosx64", 19:49:11 INFO - "builduid": "dab5411d79224b8281d16f72b567b0d8", 19:49:11 INFO - "slavebuilddir": "test" 19:49:11 INFO - } 19:49:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/CmZVkc3BTTmPm8wvR2VN_g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 19:49:11 INFO - Running post-action listener: _resource_record_post_action 19:49:11 INFO - [mozharness: 2016-10-05 02:49:11.149439Z] Finished read-buildbot-config step (success) 19:49:11 INFO - [mozharness: 2016-10-05 02:49:11.149604Z] Running download-and-extract step. 19:49:11 INFO - Running pre-action listener: _resource_record_pre_action 19:49:11 INFO - Running main action method: download_and_extract 19:49:11 INFO - mkdir: /builds/slave/test/build/tests 19:49:11 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')]} 19:49:11 INFO - trying https://queue.taskcluster.net/v1/task/CmZVkc3BTTmPm8wvR2VN_g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 19:49:11 INFO - Downloading https://queue.taskcluster.net/v1/task/CmZVkc3BTTmPm8wvR2VN_g/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 19:49:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CmZVkc3BTTmPm8wvR2VN_g/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 19:49:12 INFO - Downloaded 1057 bytes. 19:49:12 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 19:49:12 INFO - Using the following test package requirements: 19:49:12 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 19:49:12 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:49:12 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 19:49:12 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:49:12 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 19:49:12 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:49:12 INFO - u'jsshell-mac.zip'], 19:49:12 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:49:12 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 19:49:12 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 19:49:12 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:49:12 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 19:49:12 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:49:12 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 19:49:12 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:49:12 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 19:49:12 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:49:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CmZVkc3BTTmPm8wvR2VN_g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 19:49:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CmZVkc3BTTmPm8wvR2VN_g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:49:12 INFO - Fetch https://queue.taskcluster.net/v1/task/CmZVkc3BTTmPm8wvR2VN_g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 19:49:13 INFO - Http code: 200 19:49:13 INFO - via: 1.1 6be093c16fa776bbd432bbe9bd03f6af.cloudfront.net (CloudFront) 19:49:13 INFO - x-amz-cf-id: OesisHZDnVxHxRf5iYlDjXIxU4TgsuXasOMFVJ_xisscjJK2T9UPpA== 19:49:13 INFO - x-amz-version-id: XqoxydmLxb5LYBHLylV3ckeGL5jBs0wL 19:49:13 INFO - Expected file size: 16477186 19:49:13 INFO - Obtained file size: 16477186 19:49:20 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 19:49:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CmZVkc3BTTmPm8wvR2VN_g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 19:49:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CmZVkc3BTTmPm8wvR2VN_g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:49:20 INFO - Fetch https://queue.taskcluster.net/v1/task/CmZVkc3BTTmPm8wvR2VN_g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 19:49:20 INFO - Http code: 200 19:49:20 INFO - via: 1.1 aa89533ad2ec5e0edba466c9920bd000.cloudfront.net (CloudFront) 19:49:20 INFO - x-amz-cf-id: zXjXnXIuSapI7mCJgtT9rGtV98FSdodYh5H7WPabyCAsVZ6pSZf5jA== 19:49:20 INFO - x-amz-version-id: XqoxydmLxb5LYBHLylV3ckeGL5jBs0wL 19:49:20 INFO - Expected file size: 16477186 19:49:20 INFO - Obtained file size: 16477186 19:49:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CmZVkc3BTTmPm8wvR2VN_g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 19:49:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CmZVkc3BTTmPm8wvR2VN_g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:49:27 INFO - Fetch https://queue.taskcluster.net/v1/task/CmZVkc3BTTmPm8wvR2VN_g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 19:49:28 INFO - Http code: 200 19:49:28 INFO - via: 1.1 5574699f9d8b8aad21acf2edbb25dc62.cloudfront.net (CloudFront) 19:49:28 INFO - x-amz-cf-id: HBfQxYnJp1EEtrcCRLSeegPRKobFmazCXTng6Uvt2c-IZHCPeg736w== 19:49:28 INFO - x-amz-version-id: 3GbCfZtYs571zPwFcumyw5ZHOsLSMokz 19:49:28 INFO - Expected file size: 14409006 19:49:28 INFO - Obtained file size: 14409006 19:49:28 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')]} 19:49:28 INFO - trying https://queue.taskcluster.net/v1/task/CmZVkc3BTTmPm8wvR2VN_g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 19:49:28 INFO - Downloading https://queue.taskcluster.net/v1/task/CmZVkc3BTTmPm8wvR2VN_g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 19:49:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CmZVkc3BTTmPm8wvR2VN_g/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 19:49:33 INFO - Downloaded 111669090 bytes. 19:49:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CmZVkc3BTTmPm8wvR2VN_g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 19:49:33 INFO - mkdir: /builds/slave/test/properties 19:49:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:49:33 INFO - Writing to file /builds/slave/test/properties/build_url 19:49:33 INFO - Contents: 19:49:33 INFO - build_url:https://queue.taskcluster.net/v1/task/CmZVkc3BTTmPm8wvR2VN_g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 19:49:34 INFO - Running post-action listener: _resource_record_post_action 19:49:34 INFO - Running post-action listener: set_extra_try_arguments 19:49:34 INFO - [mozharness: 2016-10-05 02:49:34.213728Z] Finished download-and-extract step (success) 19:49:34 INFO - [mozharness: 2016-10-05 02:49:34.213851Z] Running populate-webroot step. 19:49:34 INFO - Running pre-action listener: _resource_record_pre_action 19:49:34 INFO - Running main action method: populate_webroot 19:49:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:49:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:49:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:49:34 INFO - '--tppagecycles', 19:49:34 INFO - '7'], 19:49:34 INFO - 'tests': ['tsvgm']}}, 19:49:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:49:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:49:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:49:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:49:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:49:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:49:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:49:34 INFO - 'talos_options': ['--disable-e10s'], 19:49:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:49:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:49:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:49:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:49:34 INFO - 'talos_options': ['--disable-e10s'], 19:49:34 INFO - 'tests': ['damp', 'tps']}, 19:49:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:49:34 INFO - 'tests': ['damp', 'tps']}, 19:49:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:49:34 INFO - 'tests': ['dromaeo_dom']}, 19:49:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:49:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:49:34 INFO - 'tests': ['basic_compositor_video']}, 19:49:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:49:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:49:34 INFO - 'tests': ['a11yr', 19:49:34 INFO - 'ts_paint', 19:49:34 INFO - 'tpaint', 19:49:34 INFO - 'sessionrestore', 19:49:34 INFO - 'sessionrestore_no_auto_restore', 19:49:34 INFO - 'tabpaint']}, 19:49:34 INFO - 'other-e10s': {'tests': ['a11yr', 19:49:34 INFO - 'ts_paint', 19:49:34 INFO - 'tpaint', 19:49:34 INFO - 'sessionrestore', 19:49:34 INFO - 'sessionrestore_no_auto_restore', 19:49:34 INFO - 'tabpaint']}, 19:49:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:49:34 INFO - 'ts_paint', 19:49:34 INFO - 'tpaint', 19:49:34 INFO - 'sessionrestore', 19:49:34 INFO - 'sessionrestore_no_auto_restore', 19:49:34 INFO - 'tabpaint']}, 19:49:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:49:34 INFO - 'ts_paint', 19:49:34 INFO - 'tpaint', 19:49:34 INFO - 'sessionrestore', 19:49:34 INFO - 'sessionrestore_no_auto_restore', 19:49:34 INFO - 'tabpaint']}, 19:49:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:49:34 INFO - 'tests': ['a11yr', 19:49:34 INFO - 'ts_paint', 19:49:34 INFO - 'tpaint', 19:49:34 INFO - 'sessionrestore', 19:49:34 INFO - 'sessionrestore_no_auto_restore', 19:49:34 INFO - 'tabpaint']}, 19:49:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:49:34 INFO - 'tests': ['a11yr', 19:49:34 INFO - 'ts_paint', 19:49:34 INFO - 'tpaint', 19:49:34 INFO - 'sessionrestore', 19:49:34 INFO - 'sessionrestore_no_auto_restore', 19:49:34 INFO - 'tabpaint']}, 19:49:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:49:34 INFO - 'tests': ['tsvgx', 19:49:34 INFO - 'tsvgr_opacity', 19:49:34 INFO - 'tart', 19:49:34 INFO - 'tscrollx', 19:49:34 INFO - 'cart']}, 19:49:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:49:34 INFO - 'tsvgr_opacity', 19:49:34 INFO - 'tart', 19:49:34 INFO - 'tscrollx', 19:49:34 INFO - 'cart']}, 19:49:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:49:34 INFO - 'talos_options': ['--disable-e10s'], 19:49:34 INFO - 'tests': ['tp5o']}, 19:49:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:49:34 INFO - 'tests': ['tp5o']}, 19:49:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:49:34 INFO - 'talos_options': ['--disable-e10s', 19:49:34 INFO - '--xperf_path', 19:49:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:49:34 INFO - 'tests': ['tp5n']}, 19:49:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:49:34 INFO - 'talos_options': ['--xperf_path', 19:49:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:49:34 INFO - 'tests': ['tp5n']}}, 19:49:34 INFO - 'talos.zip': {'path': '', 19:49:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:49:34 INFO - Running post-action listener: _resource_record_post_action 19:49:34 INFO - [mozharness: 2016-10-05 02:49:34.232964Z] Finished populate-webroot step (success) 19:49:34 INFO - [mozharness: 2016-10-05 02:49:34.233083Z] Running create-virtualenv step. 19:49:34 INFO - Running pre-action listener: _resource_record_pre_action 19:49:34 INFO - Running main action method: create_virtualenv 19:49:34 INFO - Creating virtualenv /builds/slave/test/build/venv 19:49: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 19:49:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:49:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:49:34 INFO - Using real prefix '/tools/python27' 19:49:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:49:35 INFO - Installing distribute.............................................................................................................................................................................................done. 19:49:37 INFO - Installing pip.................done. 19:49:37 INFO - Return code: 0 19:49:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:49:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:49:38 INFO - Reading from file tmpfile_stdout 19:49:38 INFO - Output received: 19:49: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) 19:49:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:49: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')]} 19:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49: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')]} 19:49:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49: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 0x10f9ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9241d603c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f813d30>, '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 0x10f639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f80bb20>, '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.ToFNlNtEtz/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.4KaQJvIbIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 19:49: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 19:49: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 19:49:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ToFNlNtEtz/Render', 19:49:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:49:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:49:38 INFO - 'HOME': '/Users/cltbld', 19:49:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:49:38 INFO - 'LOGNAME': 'cltbld', 19:49:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:38 INFO - 'MOZ_NO_REMOTE': '1', 19:49:38 INFO - 'NO_EM_RESTART': '1', 19:49:38 INFO - 'PAGER': '/bin/cat', 19:49:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:49:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:49:38 INFO - 'PWD': '/builds/slave/test', 19:49:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:49:38 INFO - 'SHELL': '/bin/bash', 19:49:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4KaQJvIbIY/Listeners', 19:49:38 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 19:49:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:49:38 INFO - 'USER': 'cltbld', 19:49:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:49:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:49:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:38 INFO - 'XPC_FLAGS': '0x0', 19:49:38 INFO - 'XPC_SERVICE_NAME': '0', 19:49:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:49:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:49: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 19:49:38 INFO - Cleaning up... 19:49:38 INFO - Return code: 0 19:49:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:49: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')]} 19:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49: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')]} 19:49:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49: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 0x10f9ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9241d603c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f813d30>, '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 0x10f639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f80bb20>, '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.ToFNlNtEtz/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.4KaQJvIbIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 19:49: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 19:49: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 19:49:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ToFNlNtEtz/Render', 19:49:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:49:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:49:38 INFO - 'HOME': '/Users/cltbld', 19:49:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:49:38 INFO - 'LOGNAME': 'cltbld', 19:49:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:38 INFO - 'MOZ_NO_REMOTE': '1', 19:49:38 INFO - 'NO_EM_RESTART': '1', 19:49:38 INFO - 'PAGER': '/bin/cat', 19:49:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:49:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:49:38 INFO - 'PWD': '/builds/slave/test', 19:49:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:49:38 INFO - 'SHELL': '/bin/bash', 19:49:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4KaQJvIbIY/Listeners', 19:49:38 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 19:49:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:49:38 INFO - 'USER': 'cltbld', 19:49:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:49:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:49:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:38 INFO - 'XPC_FLAGS': '0x0', 19:49:38 INFO - 'XPC_SERVICE_NAME': '0', 19:49:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:49:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:49:38 INFO - Downloading/unpacking psutil>=3.1.1 19:49: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 19:49: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 19:49: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 19:49: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 19:49: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 19:49: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 19:49:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:49:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:49:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:49:42 INFO - Installing collected packages: psutil 19:49:42 INFO - Running setup.py install for psutil 19:49:42 INFO - building 'psutil._psutil_osx' extension 19:49: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 19:49:43 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 19:49:43 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 19:49:43 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 19:49:43 INFO - building 'psutil._psutil_posix' extension 19:49:43 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 19:49:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:49:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:49:43 INFO - ^ 19:49:43 INFO - 1 warning generated. 19:49: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 19:49:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:49:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:49:43 INFO - Successfully installed psutil 19:49:43 INFO - Cleaning up... 19:49:43 INFO - Return code: 0 19:49:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:49: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')]} 19:49:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49: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')]} 19:49:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49: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 0x10f9ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9241d603c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f813d30>, '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 0x10f639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f80bb20>, '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.ToFNlNtEtz/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.4KaQJvIbIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 19:49: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 19:49: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 19:49:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ToFNlNtEtz/Render', 19:49:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:49:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:49:43 INFO - 'HOME': '/Users/cltbld', 19:49:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:49:43 INFO - 'LOGNAME': 'cltbld', 19:49:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:43 INFO - 'MOZ_NO_REMOTE': '1', 19:49:43 INFO - 'NO_EM_RESTART': '1', 19:49:43 INFO - 'PAGER': '/bin/cat', 19:49:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:49:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:49:43 INFO - 'PWD': '/builds/slave/test', 19:49:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:49:43 INFO - 'SHELL': '/bin/bash', 19:49:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4KaQJvIbIY/Listeners', 19:49:43 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 19:49:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:49:43 INFO - 'USER': 'cltbld', 19:49:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:49:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:49:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:43 INFO - 'XPC_FLAGS': '0x0', 19:49:43 INFO - 'XPC_SERVICE_NAME': '0', 19:49:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:49:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:49:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:49: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 19:49: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 19:49: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 19:49: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 19:49: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 19:49: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 19:49:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:49:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:49:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:49:46 INFO - Installing collected packages: mozsystemmonitor 19:49:46 INFO - Running setup.py install for mozsystemmonitor 19:49:46 INFO - Successfully installed mozsystemmonitor 19:49:46 INFO - Cleaning up... 19:49:46 INFO - Return code: 0 19:49:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:49: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')]} 19:49:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49: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')]} 19:49:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49: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 0x10f9ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9241d603c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f813d30>, '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 0x10f639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f80bb20>, '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.ToFNlNtEtz/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.4KaQJvIbIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 19:49: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 19:49: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 19:49:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ToFNlNtEtz/Render', 19:49:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:49:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:49:46 INFO - 'HOME': '/Users/cltbld', 19:49:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:49:46 INFO - 'LOGNAME': 'cltbld', 19:49:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:46 INFO - 'MOZ_NO_REMOTE': '1', 19:49:46 INFO - 'NO_EM_RESTART': '1', 19:49:46 INFO - 'PAGER': '/bin/cat', 19:49:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:49:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:49:46 INFO - 'PWD': '/builds/slave/test', 19:49:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:49:46 INFO - 'SHELL': '/bin/bash', 19:49:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4KaQJvIbIY/Listeners', 19:49:46 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 19:49:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:49:46 INFO - 'USER': 'cltbld', 19:49:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:49:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:49:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:46 INFO - 'XPC_FLAGS': '0x0', 19:49:46 INFO - 'XPC_SERVICE_NAME': '0', 19:49:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:49:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:49:46 INFO - Downloading/unpacking jsonschema==2.5.1 19:49: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 19:49: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 19:49: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 19:49: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 19:49: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 19:49: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 19:49:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:49:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:49: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 19:49: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 19:49: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 19:49: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 19:49: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 19:49: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 19:49:49 INFO - Downloading functools32-3.2.3-2.tar.gz 19:49:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:49:49 INFO - warning: no files found matching '*.txt' 19:49:49 INFO - no previously-included directories found matching 'build' 19:49:49 INFO - no previously-included directories found matching 'dist' 19:49:49 INFO - no previously-included directories found matching '.git*' 19:49:49 INFO - Installing collected packages: jsonschema, functools32 19:49:49 INFO - Running setup.py install for functools32 19:49:49 INFO - warning: no files found matching '*.txt' 19:49:49 INFO - no previously-included directories found matching 'build' 19:49:49 INFO - no previously-included directories found matching 'dist' 19:49:49 INFO - no previously-included directories found matching '.git*' 19:49:49 INFO - Successfully installed jsonschema functools32 19:49:49 INFO - Cleaning up... 19:49:50 INFO - Return code: 0 19:49:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:49: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')]} 19:49:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49: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')]} 19:49:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49: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', '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 0x10f9ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9241d603c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f813d30>, '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 0x10f639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f80bb20>, '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.ToFNlNtEtz/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.4KaQJvIbIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 19:49: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:49: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 functools32==3.2.3-2 19:49:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ToFNlNtEtz/Render', 19:49:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:49:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:49:50 INFO - 'HOME': '/Users/cltbld', 19:49:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:49:50 INFO - 'LOGNAME': 'cltbld', 19:49:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:50 INFO - 'MOZ_NO_REMOTE': '1', 19:49:50 INFO - 'NO_EM_RESTART': '1', 19:49:50 INFO - 'PAGER': '/bin/cat', 19:49:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:49:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:49:50 INFO - 'PWD': '/builds/slave/test', 19:49:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:49:50 INFO - 'SHELL': '/bin/bash', 19:49:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4KaQJvIbIY/Listeners', 19:49:50 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 19:49:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:49:50 INFO - 'USER': 'cltbld', 19:49:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:49:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:49:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:50 INFO - 'XPC_FLAGS': '0x0', 19:49:50 INFO - 'XPC_SERVICE_NAME': '0', 19:49:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:49:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:49:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:49:50 INFO - Cleaning up... 19:49:50 INFO - Return code: 0 19:49:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:49: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')]} 19:49:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49: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')]} 19:49:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49: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 0x10f9ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9241d603c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f813d30>, '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 0x10f639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f80bb20>, '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.ToFNlNtEtz/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.4KaQJvIbIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 19:49: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 19:49: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 19:49:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ToFNlNtEtz/Render', 19:49:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:49:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:49:50 INFO - 'HOME': '/Users/cltbld', 19:49:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:49:50 INFO - 'LOGNAME': 'cltbld', 19:49:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:50 INFO - 'MOZ_NO_REMOTE': '1', 19:49:50 INFO - 'NO_EM_RESTART': '1', 19:49:50 INFO - 'PAGER': '/bin/cat', 19:49:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:49:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:49:50 INFO - 'PWD': '/builds/slave/test', 19:49:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:49:50 INFO - 'SHELL': '/bin/bash', 19:49:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4KaQJvIbIY/Listeners', 19:49:50 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 19:49:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:49:50 INFO - 'USER': 'cltbld', 19:49:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:49:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:49:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:50 INFO - 'XPC_FLAGS': '0x0', 19:49:50 INFO - 'XPC_SERVICE_NAME': '0', 19:49:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:49:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:49:50 INFO - Downloading/unpacking blobuploader==1.2.4 19:49: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 19:49: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 19:49: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 19:49: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 19:49: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 19:49: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 19:49:53 INFO - Downloading blobuploader-1.2.4.tar.gz 19:49:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:49:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:49: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 19:49: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 19:49: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 19:49: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 19:49: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 19:49: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 19:49:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:49:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:49: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 19:49: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 19:49: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 19:49: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 19:49: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 19:49: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 19:49:54 INFO - Downloading docopt-0.6.1.tar.gz 19:49:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:49:54 INFO - Installing collected packages: blobuploader, requests, docopt 19:49:54 INFO - Running setup.py install for blobuploader 19:49:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:49:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:49:54 INFO - Running setup.py install for requests 19:49:54 INFO - Running setup.py install for docopt 19:49:55 INFO - Successfully installed blobuploader requests docopt 19:49:55 INFO - Cleaning up... 19:49:55 INFO - Return code: 0 19:49:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:49:55 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')]} 19:49:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:55 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')]} 19:49:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:55 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 0x10f9ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9241d603c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f813d30>, '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 0x10f639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f80bb20>, '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.ToFNlNtEtz/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.4KaQJvIbIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 19:49:55 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 19:49:55 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 19:49:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ToFNlNtEtz/Render', 19:49:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:49:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:49:55 INFO - 'HOME': '/Users/cltbld', 19:49:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:49:55 INFO - 'LOGNAME': 'cltbld', 19:49:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:55 INFO - 'MOZ_NO_REMOTE': '1', 19:49:55 INFO - 'NO_EM_RESTART': '1', 19:49:55 INFO - 'PAGER': '/bin/cat', 19:49:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:49:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:49:55 INFO - 'PWD': '/builds/slave/test', 19:49:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:49:55 INFO - 'SHELL': '/bin/bash', 19:49:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4KaQJvIbIY/Listeners', 19:49:55 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 19:49:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:49:55 INFO - 'USER': 'cltbld', 19:49:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:49:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:49:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:55 INFO - 'XPC_FLAGS': '0x0', 19:49:55 INFO - 'XPC_SERVICE_NAME': '0', 19:49:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:49:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:49:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:49:55 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-X52qNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:49:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:49:55 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-jspq8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:49:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:49:55 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-gRP8m8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:49:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:49:55 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-Evq6pT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:49:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:49:55 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-SkGfxR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:49:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:49:55 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-cHSWnT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:49:56 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-frlyWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:49:56 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-lx1L0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:49:56 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-J8RICm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:49:56 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-HXO7KC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:49:56 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-ER9e3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:49:56 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-doVXWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:49:56 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-AQfV1r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:49:56 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-qmk8kc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:49:56 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-BnRAgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:49:57 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-SqNIEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:49:57 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-Lvz6x8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:49:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:49:57 INFO - Running setup.py install for manifestparser 19:49:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:49:57 INFO - Running setup.py install for mozcrash 19:49:57 INFO - Running setup.py install for mozdebug 19:49:57 INFO - Running setup.py install for mozdevice 19:49:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:49:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:49:57 INFO - Running setup.py install for mozfile 19:49:57 INFO - Running setup.py install for mozhttpd 19:49:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:49:58 INFO - Running setup.py install for mozinfo 19:49:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:49:58 INFO - Running setup.py install for mozInstall 19:49:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:49:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:49:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:49:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:49:58 INFO - Running setup.py install for mozleak 19:49:58 INFO - Running setup.py install for mozlog 19:49:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:49:58 INFO - Running setup.py install for moznetwork 19:49:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:49:58 INFO - Running setup.py install for mozprocess 19:49:59 INFO - Running setup.py install for mozprofile 19:49:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:49:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:49:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:49:59 INFO - Running setup.py install for mozrunner 19:49:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:49:59 INFO - Running setup.py install for mozscreenshot 19:49:59 INFO - Running setup.py install for moztest 19:49:59 INFO - Running setup.py install for mozversion 19:49:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:49:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:49:59 INFO - Cleaning up... 19:49:59 INFO - Return code: 0 19:49:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:49: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')]} 19:49:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49: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')]} 19:49:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49: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 0x10f9ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9241d603c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f813d30>, '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 0x10f639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f80bb20>, '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.ToFNlNtEtz/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.4KaQJvIbIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 19:49: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 19:49: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 19:49:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ToFNlNtEtz/Render', 19:49:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:49:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:49:59 INFO - 'HOME': '/Users/cltbld', 19:49:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:49:59 INFO - 'LOGNAME': 'cltbld', 19:49:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:59 INFO - 'MOZ_NO_REMOTE': '1', 19:49:59 INFO - 'NO_EM_RESTART': '1', 19:49:59 INFO - 'PAGER': '/bin/cat', 19:49:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:49:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:49:59 INFO - 'PWD': '/builds/slave/test', 19:49:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:49:59 INFO - 'SHELL': '/bin/bash', 19:49:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4KaQJvIbIY/Listeners', 19:49:59 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 19:49:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:49:59 INFO - 'USER': 'cltbld', 19:49:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:49:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:49:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:59 INFO - 'XPC_FLAGS': '0x0', 19:49:59 INFO - 'XPC_SERVICE_NAME': '0', 19:49:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:50:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:50:00 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-CfFL45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:50: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)) 19:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:50:00 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-bagpbe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:50: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)) 19:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:50:00 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-C4rNtr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:50: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)) 19:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:50:00 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-_15adV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:50: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)) 19:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:50:00 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-BS5Wqy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:50: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)) 19:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:50:00 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-D45RGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:50: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)) 19:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:50:00 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-L3JLzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:50: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)) 19:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:50:00 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-WERKTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:50: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)) 19:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:50:00 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-ZgDLdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:50:01 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)) 19:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:50:01 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-2CNnic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:50:01 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)) 19:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:50:01 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-X4J6NW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:50: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)) 19:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:50:01 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-BvWnon-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:50: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)) 19:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:50:01 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-L4w4pW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:50: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)) 19:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:50:01 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-KSG9WG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:50: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)) 19:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:50:01 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-fSXfAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:50: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)) 19:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:50:01 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-A2UZvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:50: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)) 19:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:50:01 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-gDYQvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:50: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)) 19:50: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)) 19:50: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)) 19:50: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)) 19:50: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)) 19:50: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)) 19:50:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:50: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 19:50: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 19:50: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 19:50: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 19:50: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 19:50: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 19:50:04 INFO - Downloading blessings-1.6.tar.gz 19:50:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:50:04 INFO - Installing collected packages: blessings 19:50:04 INFO - Running setup.py install for blessings 19:50:04 INFO - Successfully installed blessings 19:50:04 INFO - Cleaning up... 19:50:04 INFO - Return code: 0 19:50:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:50:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:50:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:50:04 INFO - Reading from file tmpfile_stdout 19:50:04 INFO - Current package versions: 19:50:04 INFO - blessings == 1.6 19:50:04 INFO - blobuploader == 1.2.4 19:50:04 INFO - docopt == 0.6.1 19:50:04 INFO - functools32 == 3.2.3-2 19:50:04 INFO - jsonschema == 2.5.1 19:50:04 INFO - manifestparser == 1.1 19:50:04 INFO - mozInstall == 1.12 19:50:04 INFO - mozcrash == 0.17 19:50:04 INFO - mozdebug == 0.1 19:50:04 INFO - mozdevice == 0.48 19:50:04 INFO - mozfile == 1.2 19:50:04 INFO - mozhttpd == 0.7 19:50:04 INFO - mozinfo == 0.9 19:50:04 INFO - mozleak == 0.1 19:50:04 INFO - mozlog == 3.4 19:50:04 INFO - moznetwork == 0.27 19:50:04 INFO - mozprocess == 0.23 19:50:04 INFO - mozprofile == 0.28 19:50:04 INFO - mozrunner == 6.12 19:50:04 INFO - mozscreenshot == 0.1 19:50:04 INFO - mozsystemmonitor == 0.3 19:50:04 INFO - moztest == 0.7 19:50:04 INFO - mozversion == 1.4 19:50:04 INFO - psutil == 3.1.1 19:50:04 INFO - requests == 1.2.3 19:50:04 INFO - wsgiref == 0.1.2 19:50:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:50: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')]} 19:50:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:50:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:50: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')]} 19:50:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:50:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:50:04 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 0x10f9ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9241d603c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f813d30>, '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 0x10f639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f80bb20>, '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.ToFNlNtEtz/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.4KaQJvIbIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 19:50:04 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 19:50:04 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 19:50:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ToFNlNtEtz/Render', 19:50:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:50:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:50:04 INFO - 'HOME': '/Users/cltbld', 19:50:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:50:04 INFO - 'LOGNAME': 'cltbld', 19:50:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:50:04 INFO - 'MOZ_NO_REMOTE': '1', 19:50:04 INFO - 'NO_EM_RESTART': '1', 19:50:04 INFO - 'PAGER': '/bin/cat', 19:50:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:50:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:50:04 INFO - 'PWD': '/builds/slave/test', 19:50:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:50:04 INFO - 'SHELL': '/bin/bash', 19:50:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4KaQJvIbIY/Listeners', 19:50:04 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 19:50:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:50:04 INFO - 'USER': 'cltbld', 19:50:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:50:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:50:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:50:04 INFO - 'XPC_FLAGS': '0x0', 19:50:04 INFO - 'XPC_SERVICE_NAME': '0', 19:50:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:50:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:50: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)) 19:50: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)) 19:50: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)) 19:50: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)) 19:50: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)) 19:50: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)) 19:50: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)) 19:50: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)) 19:50: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)) 19:50: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)) 19:50: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)) 19:50:05 INFO - Cleaning up... 19:50:05 INFO - Return code: 0 19:50:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:50: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')]} 19:50:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:50:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:50: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')]} 19:50:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:50:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:50:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f9ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9241d603c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f813d30>, '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 0x10f639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f80bb20>, '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.ToFNlNtEtz/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.4KaQJvIbIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 19:50:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:50:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:50:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ToFNlNtEtz/Render', 19:50:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:50:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:50:05 INFO - 'HOME': '/Users/cltbld', 19:50:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:50:05 INFO - 'LOGNAME': 'cltbld', 19:50:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:50:05 INFO - 'MOZ_NO_REMOTE': '1', 19:50:05 INFO - 'NO_EM_RESTART': '1', 19:50:05 INFO - 'PAGER': '/bin/cat', 19:50:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:50:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:50:05 INFO - 'PWD': '/builds/slave/test', 19:50:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:50:05 INFO - 'SHELL': '/bin/bash', 19:50:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4KaQJvIbIY/Listeners', 19:50:05 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 19:50:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:50:05 INFO - 'USER': 'cltbld', 19:50:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:50:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:50:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:50:05 INFO - 'XPC_FLAGS': '0x0', 19:50:05 INFO - 'XPC_SERVICE_NAME': '0', 19:50:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:50:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:50:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:50:05 INFO - Cleaning up... 19:50:05 INFO - Return code: 0 19:50:05 INFO - Running post-action listener: _resource_record_post_action 19:50:05 INFO - Running post-action listener: _start_resource_monitoring 19:50:05 INFO - Starting resource monitoring. 19:50:05 INFO - [mozharness: 2016-10-05 02:50:05.523013Z] Finished create-virtualenv step (success) 19:50:05 INFO - [mozharness: 2016-10-05 02:50:05.523844Z] Running install step. 19:50:05 INFO - Running pre-action listener: _resource_record_pre_action 19:50:05 INFO - Running main action method: install 19:50:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:50:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:50:05 INFO - Reading from file tmpfile_stdout 19:50:05 INFO - Detecting whether we're running mozinstall >=1.0... 19:50:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:50:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:50:05 INFO - Reading from file tmpfile_stdout 19:50:05 INFO - Output received: 19:50:05 INFO - Usage: mozinstall [options] installer 19:50:05 INFO - Options: 19:50:05 INFO - -h, --help show this help message and exit 19:50:05 INFO - -d DEST, --destination=DEST 19:50:05 INFO - Directory to install application into. [default: 19:50:05 INFO - "/builds/slave/test"] 19:50:05 INFO - --app=APP Application being installed. [default: firefox] 19:50:05 INFO - mkdir: /builds/slave/test/build/application 19:50:05 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'] 19:50:05 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 19:50:40 INFO - Reading from file tmpfile_stdout 19:50:40 INFO - Output received: 19:50:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:50:40 INFO - Running post-action listener: _resource_record_post_action 19:50:40 INFO - [mozharness: 2016-10-05 02:50:40.339123Z] Finished install step (success) 19:50:40 INFO - [mozharness: 2016-10-05 02:50:40.339257Z] Running run-tests step. 19:50:40 INFO - Running pre-action listener: _resource_record_pre_action 19:50:40 INFO - Running main action method: run_tests 19:50:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:50:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:50:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:50:40 INFO - 2016-10-04 19:50:40.353 screenresolution[1927:17555] starting screenresolution argv=screenresolution get 19:50:40 INFO - 2016-10-04 19:50:40.356 screenresolution[1927:17555] Display 0: 1600x1200x32@60 19:50:40 INFO - 2016-10-04 19:50:40.362 screenresolution[1928:17557] starting screenresolution argv=screenresolution list 19:50:40 INFO - Available Modes on Display 0 19:50:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:50:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:50:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:50:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:50:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:50:40 INFO - Intel Iris: 19:50:40 INFO - Chipset Model: Intel Iris 19:50:40 INFO - Type: GPU 19:50:40 INFO - Bus: Built-In 19:50:40 INFO - VRAM (Dynamic, Max): 1536 MB 19:50:40 INFO - Vendor: Intel (0x8086) 19:50:40 INFO - Device ID: 0x0a2e 19:50:40 INFO - Revision ID: 0x0009 19:50:40 INFO - Displays: 19:50:40 INFO - Display: 19:50:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:50:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:50:40 INFO - Display Serial Number: 1600x1200 60 19:50:40 INFO - Main Display: Yes 19:50:40 INFO - Mirror: Off 19:50:40 INFO - Online: Yes 19:50:40 INFO - Rotation: Supported 19:50:40 INFO - Return code: 0 19:50:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:50:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:50:40 INFO - Python 2.7.3 19:50:40 INFO - Return code: 0 19:50:40 INFO - grabbing minidump binary from tooltool 19:50:40 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')]} 19:50:40 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 0x10f813d30>, '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 0x10f639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f80bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:50:40 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 19:50:40 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 19:50:40 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 19:50:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:50:40 INFO - Return code: 0 19:50:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:50:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:50:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:50:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:50:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:50:40 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/CmZVkc3BTTmPm8wvR2VN_g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0135', '--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 19:50:40 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/CmZVkc3BTTmPm8wvR2VN_g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0135 --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 19:50:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ToFNlNtEtz/Render', 19:50:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:50:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:50:40 INFO - 'HOME': '/Users/cltbld', 19:50:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:50:40 INFO - 'LOGNAME': 'cltbld', 19:50:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:50:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:50:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:50:40 INFO - 'MOZ_NO_REMOTE': '1', 19:50:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:50:40 INFO - 'NO_EM_RESTART': '1', 19:50:40 INFO - 'PAGER': '/bin/cat', 19:50:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:50:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:50:40 INFO - 'PWD': '/builds/slave/test', 19:50:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:50:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:50:40 INFO - 'SHELL': '/bin/bash', 19:50:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4KaQJvIbIY/Listeners', 19:50:40 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 19:50:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:50:40 INFO - 'USER': 'cltbld', 19:50:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:50:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:50:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:50:40 INFO - 'XPC_FLAGS': '0x0', 19:50:40 INFO - 'XPC_SERVICE_NAME': '0', 19:50:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:50:40 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/CmZVkc3BTTmPm8wvR2VN_g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0135', '--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 19:50:40 INFO - mozversion application_buildid: 20161004170101 19:50:40 INFO - mozversion application_changeset: 3470e326025c62381dc5f7c06629dbe5dbd7f242 19:50:40 INFO - mozversion application_display_name: Nightly 19:50:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:50:40 INFO - mozversion application_name: Firefox 19:50:40 INFO - mozversion application_remotingname: firefox 19:50:40 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:50:40 INFO - mozversion application_vendor: Mozilla 19:50:40 INFO - mozversion application_version: 52.0a1 19:50:40 INFO - mozversion platform_buildid: 20161004170101 19:50:40 INFO - mozversion platform_changeset: 3470e326025c62381dc5f7c06629dbe5dbd7f242 19:50:40 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:50:40 INFO - mozversion platform_version: 52.0a1 19:50:40 INFO - using testdate: 1475635840 19:50:40 INFO - actual date: 1475635840 19:50:40 INFO - starting webserver on 'localhost:53420' 19:50:40 INFO - SUITE-START | Running 2 tests 19:50:40 INFO - TEST-START | dromaeo_css 19:50:40 INFO - operating with platform_type : mac_ 19:50:40 INFO - Initialising browser for dromaeo_css test... 19:50:40 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpcktZqA/profile http://localhost:53420/getInfo.html) 19:50:43 INFO - PROCESS | 1938 | 2016-10-04 19:50:43.858 plugin-container[1940:17714] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 19:50:43 INFO - PROCESS | 1938 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:50:43 INFO - PROCESS | 1938 | 2016-10-04 19:50:43.861 plugin-container[1940:17714] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 19:50:43 INFO - PROCESS | 1938 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:50:43 INFO - PROCESS | 1938 | 2016-10-04 19:50:43.861 plugin-container[1940:17714] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:50:43 INFO - PROCESS | 1938 | __metrics Screen width/height:1600/1200 19:50:43 INFO - PROCESS | 1938 | colorDepth:24 19:50:43 INFO - PROCESS | 1938 | Browser inner width/height: 1024/689 19:50:43 INFO - PROCESS | 1938 | __metrics 19:50:44 ERROR - PROCESS | 1938 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 19:50:44 INFO - PROCESS | 1938 | 19:50:44 INFO - PROCESS | 1938 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x10E0011,name=PVRManager::Msg_NotifyVSync) Channel error: cannot send/recv 19:50:44 INFO - PROCESS | 1938 | 19:50:44 INFO - TEST-INFO | 1938: exit 0 19:50:44 INFO - Browser initialized. 19:50:44 INFO - Running cycle 1/1 for dromaeo_css test... 19:50:44 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpcktZqA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:50:47 INFO - PROCESS | 1941 | 2016-10-04 19:50:47.001 plugin-container[1942:17829] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.tsm.portname' 19:50:47 INFO - PROCESS | 1941 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:50:47 INFO - PROCESS | 1941 | 2016-10-04 19:50:47.003 plugin-container[1942:17829] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 19:50:47 INFO - PROCESS | 1941 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:50:47 INFO - PROCESS | 1941 | 2016-10-04 19:50:47.003 plugin-container[1942:17829] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:50:56 INFO - PROCESS | 1941 | RSS: Main: 240545792 19:50:56 INFO - PROCESS | 1941 | 19:51:46 INFO - PROCESS | 1941 | 1475635906370 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] 19:53:59 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53420/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53420/tests/dromaeo/cssquery-ext.html) 19:54:01 INFO - PROCESS | 1941 | RSS: Main: 253071360 19:54:01 INFO - PROCESS | 1941 | 19:57:03 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53420/tests/dromaeo/cssquery-ext.html (next: http://localhost:53420/tests/dromaeo/cssquery-jquery.html) 19:57:03 INFO - PROCESS | 1941 | RSS: Main: 264142848 19:57:03 INFO - PROCESS | 1941 | 20:00:05 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53420/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53420/tests/dromaeo/cssquery-mootools.html) 20:00:06 INFO - PROCESS | 1941 | RSS: Main: 270610432 20:00:06 INFO - PROCESS | 1941 | 20:03:08 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53420/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53420/tests/dromaeo/cssquery-prototype.html) 20:03:08 INFO - PROCESS | 1941 | RSS: Main: 270487552 20:03:08 INFO - PROCESS | 1941 | 20:06:11 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53420/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53420/tests/dromaeo/cssquery-yui.html) 20:06:12 INFO - PROCESS | 1941 | RSS: Main: 275726336 20:06:12 INFO - PROCESS | 1941 | 20:09:15 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53420/tests/dromaeo/cssquery-yui.html (next: http://localhost:53420/tests/dromaeo/cssquery-dojo.html) 20:09:15 INFO - PROCESS | 1941 | RSS: Main: 278683648 20:09:15 INFO - PROCESS | 1941 | 20:09:15 INFO - PROCESS | 1941 | __start_tp_report 20:09:15 INFO - PROCESS | 1941 | _x_x_mozilla_page_load 20:09:15 INFO - PROCESS | 1941 | _x_x_mozilla_page_load_details 20:09:15 INFO - PROCESS | 1941 | |i|pagename|runs| 20:09:15 INFO - PROCESS | 1941 | |0;dojo.html;3227.37066272077;3423.486477028446;3430.4168291390447;3469.427544455165;3471.861125554978;4069;5767.798127065554;5875.8824823503555;5918.46733793959;5968.164456976021;5901.940980590193;5929.822105336836;5965.761369545224;5993.970030149854;6018.458338749511;5299.5760339172775;5313.1764576490705;5322.92015619766;5441.5102640762325;5466.125419932811;5448.91826622601;5473.370562385317;5497.202905578689;5504.981578408007;5515.365732940723;5196.610254230948;5254.684718916839;5413.864653383697;5425.674459532441;5461.0716178249595;5284.048871203182;5320.9467905320835;5330.653507522037;5406.54044406226;5447.046766815792;3818.4463252828464;5314.362276526803;5315.362156541201;5347.839564813062;5357.383900851413;4857.417109946829;5747.166660834156;5948.851278718023;6005.609635373687;6013.977623803964;6134.099846298167;6141.762184093006;6268.937310626881;6269;6342.6511541865175;6389.5527313088105;6413.326600706905;6415.743370265184;6431;6434.227892652865;6016.308124565688;6059.697015149225;6079.726412311456;6085.96538588441;6102.176206212149;6241.095041218998;6372.235331760218;6385.936140638534;6388.2653494847755;6402.455791257702;6149.815505534841;6163.568550201443;6214.471769899519;6218.067289906459;6219.440250377397;6215.533834962396;6412.839679008062;6428.71070801815;6431.196100487439;6444.13004244421;6127.0673618518995;6254.405831446005;6255.468285195719;6258;6262.248530176408;6150.477209437248;6259.342769009279;6293.811185664437;6296.181496405438;6312.179416675803;4129.401236820644;4205.747655140702;4336.457942757155;4990.525900039563;5050.191969284897;4502.414686090788;4521.231390663595;4533.138703646297;4580.358749775034;4609.055143695535;5887.735051922674;6022.307434644984;6055.818325450244;6066.514678825683;6070.817875463743;6208.596441231305;6213.1922850029205;6415.839604009844;6444.065610486453;6444.548881578338;5039.941612261467;5735.311762588516;6067.302260240041;6080.69596520181;6112.266528016667;5513.58648101394;5540.750666220029;5563.165525171175;5570.693611851306;5574.749136288877;6373.362663733775;6517.771877984343;6549.083128362033;6556.672166391757;6589.538732288694;6244.500439964701;6260.968695156495;6266.060090986206;6274.811755647338;6369.044643303541;6553.377429144223;6571.507136964651;6589.934100659124;6593.571417857824;6594.406503414716;6286.214223222098;6305.432511074026;6328.557001010069;6329.873402531832;6335.619862808062;5382.4886635769535;6206.844828879314;6217.533684973555;6239.812805615838;6250.468710159687;6261.279952805486;6270.2475702915945;6270.686465676789;6271.78048768291;6276;6133.570650054453;6204.534659900435;6243.313235544178;6243.37566243372;6268.937310627018;5467.261919640798;5846.912296315473;6142.57002009855;6190.659513726608;6213.440790328892;6223.37766223374;6239.594426362272;6256.530760192913;6276.184096067482;6281.371862813682;6041.305249896318;6126.632402055891;6134.447899688871;6184.041473571604;6190.442860142609 20:09:15 INFO - PROCESS | 1941 | |1;ext.html;479.8896253861613;519.5687579309173;530.8434011966469;532.8268312798339;542.2083757713738;39705.838241028665;41957.58042419575;41984;42098.158036839246;42262.36606450902;42438;42457.36313955293;42925;42962.14075718484;43008.139837203315;9736.610535578568;9864.901350986505;9910.653127140551;9919;9944.254180936423;9768.413895166277;9827.557759900787;9887.950560247225;9920.206383489303;9922.950385248063;9899.307048506607;9925.702228933143;9945.751356216117;9960.302778805486;9966.202703783716;9886.505674716307;9908.20734341251;9927.106560409598;9929.2056635469;9942.55258513365;9712.174465170468;9763.121319081245;9786.853197202048;9822.901770982271;9835.655752048715;40916.590834091876;41019;41113.79443102765;41515.792421038;41611.37582936259;3367.5958884933966;3371.96628033719;3413.641567635387;3434.969509147271;3858.614138586147;25283.333916581993;30801.845990770125;31569;31998.040058798273;34087.63649454019;3198.1365031441383;3215.1479857837694;3222.951655725166;3247.756418268639;3313.6023677158655;472509;477936;484508;485686;486338;57467.42532574704;57715.42284577184;57757.42242577604;57792.39934202971;57917;59490.70254648699;59992.80014399687;60249.397506025256;60340.698296508235;60758;15999.680006399807;16227.918860405622;16251.10618915971;16290.755638665429;16314.510564683078;12578;12612.621621351374;12633.557825476064;12644.304563248925;12686.175398599062;15368.538943831703;15643.296051677702;15857.68284634324;15925.362985480682;15994.360225590846;6989.4408447324095;7061;7091.723489771787;7161.606111663804;7187.5687458752645;898.9820203595891;948.4735971535864;978.5889926231;1035.1563475767225;1058.3544038136731;29660.851695741385;29747.107586772432;29875.701242987725;30600.69399306023;30656.540151897738;959.7216807125856;1017.1049476460668;1027.9331843430152;1079.6005477973201;1159.513004538096;15110.848891510945;15573.377064917502;15589.68820623581;15621.984571002848;15750.527484175493;569837;573208;573895;575061;581489;56535;56670;56934;56937.145942810894;57034;1345.6837643153851;1362.9591112266648;1387.2162228341156;1391.4364682303683;1391.902566820333;24946.875265623556;28491.432971186783;29402.823887045135;29557.704422956354;29855;47340.05319893514;47598;47598.99999999862;47612;47905;432851;506820.46589766815;510430;513001;513950;37548;38739.80630096832;38976.6102338973;39042;39053;46006.309905350776;46169.04358876331;47665;49918.75040624774;50530.45225929684;37897;39466.802665986484;39851.40222896717;39909;40830.77507674775;30931.226719332197;38858.2228355437;39357.237795135305;40024.799876000434;40031.79984100061;37485.81257093806;37781.622183777814;38534.22931541297;38652.647157349355;38741;33114.5032824503;33405;34730.47904281387;34849.8257508721;35010.64989350074;23649.645255321528;25878.353041174123;25941;26268.21195364142;26508.8674556626 20:09:15 INFO - PROCESS | 1941 | |2;jquery.html;302.48577418388743;368.9372806622874;371.0852747976238;381.6450700848211;402.816718393131;17455.738163927534;19353.806461935375;20886.791132088674;21481.140754369826;21764.782352176473;16099.919500402511;19807.405777826687;20234.190632374724;20348.08433620487;20423.18307267711;5211.166213405856;5473.863153421156;5545.085060964936;5665.915011274834;5677.091665333547;5546.750396232179;6842.452603791709;7059.152901651809;7076.433885289191;7094.503384763068;5202.375714914216;6145.4161854623735;6214.50283977283;6673.865442874699;7002.334778196063;5833.449909070931;6167.229096362977;6193.411625895533;6196.876062478747;6251.156093927307;5936.643801371932;6006.639601623873;6050.757969681207;6055.485283750842;6056.727447264883;13169.078164528486;13190.406431710597;13201.60395188146;13355.265460399674;13361.465541378333;2118.8304935605115;2262.9773702262933;2325.186184835294;2331;2353;10400;12331.815022774666;14787.852121478863;15419.691606167926;15709.293081811345;1839.8804077734903;1876.5496280892487;1878.2017642501883;1943.280986035162;1958.6670266054805;71777.92333114955;99195.0080499193;111861;112630;113143;11292.717682058013;11516.30902145874;11770.646880593597;11849.703757406134;11919.3444360561;14499;14755.262236888113;14772.335244914004;14788.482403115839;14799.18604476764;8369.665213391396;8530.829383412298;8553.187447192508;8574.142585741376;8609.48343099416;8315.459495132796;8362.707305244287;8453.830923381498;8536.70121545743;8571.871421928676;4571.839985600488;4586.243269860503;4592.127495775792;4597.540245975376;4659.277811939155;1528.8929774915648;1832.6059897122182;1912.512309361104;1953.1113343428706;1966.3117908731829;893.0756666849779;935.6725146198912;947.5688561704408;1006.9144122749502;1036.8807587127576;19853.801461985197;20274.594508109756;21233.78766212318;21413.250536231157;21726.565468690536;947.313197931508;948.6632245552868;964.4261664309724;965.3725078698902;972.8783902012249;10822.729431764268;11280.210385273116;11283.323000619988;11319.886801131883;11323.377214253294;547615;557830.5904937069;558463;559620;590373;129479;130784.3460782709;132257;132800.33599831938;136999;948.9525523723786;954.8424509955919;973.8393165127816;977.1401166973018;979.857920601523;14971.026883252554;15269.770953435485;18293.993830338928;18369.54076148107;19048.523786904883;56579;56718.716406417705;56947;57520.424795753184;57540.71229643825;73547.52904941764;81482.37035259143;82395;83148.58425707833;83336.58331708303;6524.608523488606;7485.737999170003;7851.60741962911;8137.633806478604;8371.539565323723;9935.552900119368;12548.560800371943;12556.121071524905;12590.307533085768;12661;9953.85530663954;10863.590481615885;10886.945565272123;11121.666350009513;11139.220254582102;9978.700638980843;10481.475926203811;10811.783764324513;10960.12319014461;11016.779664406828;10455.477226138815;10508.369497829848;10818.404987725751;10941.507632156412;11193.776124477628;6506.479481641552;6709.832254193684;7762.689492420466;7982.920170798217;8180.550069746221;9648.228141748783;10410.739731506774;10744.677659670222;10814.513346899566;10853.240273180802 20:09:15 INFO - PROCESS | 1941 | |3;mootools.html;1333.2467156056828;1505.616067902685;1712.3321904457257;1884.3028079610547;1901;8149.755507334775;8440.155984401572;8637.222649961503;9265.53672316384;9269.860952085724;8617.396782225247;8908.599113039912;8930.285577153829;9027.503487308195;9043.186113249807;2354.3290162303706;2376.4058985253687;2386.0217310902535;2386.6300723387903;2398.3284680289457;2313.976860231393;2371.158238825218;2376.1089591403224;2394.6767186429784;2397.880105994693;1886.7264246684222;2351.400392899817;2354.458474550854;2354.799842013431;2368.928932132039;2333.5916214662366;2347.3310106619615;2361.4332560185603;2361.6929799126087;2364.243442098512;2317.304808557426;2363.0902102690416;2364.054378248697;2366.479374537599;2375.168690958168;9192.954035229848;9237.861432078524;9241.722748317561;9250.306227033;9256.768580785467;4413.889652758675;5059.87350316245;5113;5411.18832175173;5421.349438067417;3335.699787019156;3503.3693935091674;3624.2026754114054;3685.7788532688123;3698.6301369863068;1048.2086025051115;1079.4872435593109;1088.5101704233127;1090.1061129873508;1103.8564986551696;12125.636230913087;12241.20432171906;12336.321502317369;12370.690732731664;12373.93813030929;4570.680052396367;4655.836040989752;4853.39332583427;4854.393200849894;4865.294532292798;10129.746756331004;10313;10318.329308594917;10347.327423717434;10362.326448780805;5348.03735327637;5365.7585408656705;5401.675899446046;5405.864853378697;5434.809781657702;4077.8368865245056;4442.777861106932;4611.538846115429;4762.59517940972;4925.014997000614;3386.745994050407;3900.531936167621;3934.8229329680235;4033.3748269018347;4112.527059388209;985.930265661765;1102.5589764094427;1128.90968722502;1278.7953927371575;1284.2679672586723;741.4846681556375;834.9540775257298;913.5660561233361;937.8452555328431;939.9765005874908;5848.502877255396;5889.793857214977;5896.941030589725;6023.759049637965;6087.6956152192215;413.136544621742;422.3622330281236;424.94688163979504;443.45898004434537;446.68285517283095;3692.6521819536215;3738.831752571141;3929.4891664084057;4051.230266249403;4126.711130220856;9452.385594936308;9598.280128990353;9655.275854310954;9679.467629280316;9681.37071090377;9654.179394751385;9664.613415463444;9670.323077384515;9675.322727409013;9694.22446204302;7510.511816731895;7749.380049596019;8101.392395570356;8235.8352832943;8482.321414286966;5654.604177707521;5946.345901950695;6087.786927457518;6106.236720409949;6226.346233645583;10240.846387304346;10442.843357349799;10455.529501172314;10530.262881598215;10541.367517948947;9933.403995760278;10361.119304859172;10425.270231083752;10538.104261137889;10580.100691441006;6240.781572644935;7064.293570642894;7282.053333066668;7471.290227428385;7822.100458447351;7695.268949449794;8455.689368147947;8624.965004199536;8681.782955426164;8757.6934807284;7563.357114645097;7723.266289702469;7770.611469426619;7813.492123011986;7831.412644051833;7388.1503627083575;7547.320741133324;7561.848763024599;7607.201243869536;7696.499727517694;6668.899966500409;7141.928580714334;7176.677049532679;7179.03083083777;7190.280971902768;4938.259261110718;5048.848534543969;5057.409239440039;5084.7966081356335;5193;4724.173269677862;5037.823676171316;5051.848444546669;5073.16292811682;5230.5815534757885 20:09:15 INFO - PROCESS | 1941 | |4;prototype.html;287.35488827582077;294.79953631530566;300.6917909143129;309.6346311352606;311.2079757018388;4420.314851198062;4796.448408433027;4827.758612069396;5170.405403378612;5678.0347340952085;4494.640428765699;5412.5940554458375;5467.398586155519;5487.161800796728;5506.64206826557;3459.809710465923;3656.7567027210744;3848.9230215395673;4225.302825033873;4262.87211383659;4037.798110094498;4191.559886211934;4239.851605193819;4267.594578515036;4289.528151903288;3399.609044959824;3522.8766993155245;4183.991683991686;4190.234445266125;4202.747835129902;3632.0919770057353;4033.576474470197;4186.70693051485;4218.831246750126;4222.690965800612;3372.8313584320686;3377.3583019226267;3602.9279414411567;3671.724620653435;3676.1544844685604;4582.24392975162;4614.330922016289;4873.320642914612;5372.865678358034;5376.462353764631;4329.696921215516;4489.46126464823;4822.734749588772;4845.927311090301;4880.389951256093;4991.475895031006;5723.484886360206;5798.971005144947;5824.650520968756;5834.6790926499025;3549.396602577568;3804.410316400962;3963.286608410485;3999.8200080996135;4054.959450405517;4940.3330550375595;5377.7580008899695;5592.029658942115;6026.367231440723;6034.607750496204;4084.14233011071;4089.427480152781;4097.487814023247;4099.323611604112;4102.2000709861295;3936.7047471439755;3975.522937247491;4041.5756345583877;4133.772642504691;4163.812628431728;4760.619150468024;4783.373652957941;4785.952140478551;4797.368894575861;4819.518048195152;4567.680262381601;5793.855153621109;5809.767609295665;5878.854218210323;5879.911801322984;1332.7201287729492;1335.8740547119787;1337.5118081900036;1396.232072360198;1396.9022168448314;784.6822037074994;828.341468532506;856.738694698123;860.8536548786722;880.903100658927;3890.902727431837;4008.376607473978;4554;4651;4662.906741865144;4166.979165104155;4314.439122914001;4664.180969421928;5023.974880125649;5120.590352771769;3861.1505468796818;3878.592747761501;3956.5252169739815;4327.112941846914;4483.910321793546;2400.099962514057;2515.232853979517;2530.557582178141;2544.4656622109196;2669.79507276041;4263.467066616673;4304.569543045733;5071.236176283934;5210.426853046162;5217.57382816992;4464.196444639996;4511.458624965026;4511.481179664315;5054;5177.275181474596;4388.467283836479;4812.711237325771;5095.566876815438;5173.076827033026;5251.739582500249;4464.441944756905;4654.114449041615;4697.416420968522;5058.822941197188;5064.924026139537;4255.085266592394;4648.37246971668;4719.693219940693;4740.312654665123;4872.853814385574;4720.645951553579;4769.880752981203;4864.951350486592;4920.852374428773;5249.212618107162;3019.033909149139;3061.8622162002316;3333.4999750037687;3351;3435.952034629462;4331.870043898688;4376.592873919395;4752.714837109785;4799.856004319737;4866.878328041827;5436.456354364532;5548.694821784679;5553.9725150847225;5808.448197421239;5838.737256823369;5818.5453636590855;5828;5828.892510423006;5873.853153671193;5876.559258055749;5519.558435325084;5620.522255608155;5633.943660563506;5728.828135155952;5730.5702072345575;4713.764311784328;5705.7147142643535;5807.332156802022;5834.241548598655;5834.504067154339;5011.7494125294315;5225.372955245243;5413.485718856702;5711.457411545896;5794.913076303943 20:09:15 INFO - PROCESS | 1941 | |5;yui.html;716.1656669979474;719.9812265644114;743.2715938380388;777.5451360953841;802.9478083924548;14536.854631453682;15022.399104035852;15080.396784128621;15083.698326033487;15086.924565377172;14033.508827191024;14232.715345693106;14570.635734106652;14598.197099159543;14604;676.3000294694991;702.2240424331117;832.6877420967137;834.9206825351612;861;851.6423102297019;872.6378552900538;873.2141073034288;873.4366333714751;874.6501399440243;925.3476299209075;925.6112432778216;927.7495076329387;931.483026920059;932.7108596335158;920.6869664314132;921.6912334367928;923.1230331185367;923.3121324613139;928.4893308680232;498.8029728257318;626.924769027715;627.3914303125961;683.7060064172404;684.5003147702205;15193;15350.462733804376;15377;15405.691886162325;15413.229338533029;12115.454804533818;14534;14548.272586370638;14568.927155364261;14776.556703298918;11913.10651701126;11954.773819155178;11967.222130561573;12071.396430178456;12117.636470905887;3630.346587454275;3790.1093243087853;3794.772313661189;3827.27281816454;3842.6925845932537;96983;97573;105403.47298263536;107105.39341909878;108277.45861270723;10891.222468883196;10908.618198363178;10919.454027298603;10919.617813376493;11069.335839849635;20332.288369906983;20535.075921583564;20645.793542064388;20700.792992070186;20702.275420360216;6453.451456626145;7541.170471248159;7741.651625676858;7786.883196752054;7907.802304942423;4320.503142138694;4440.311751678495;4501.527339629357;4529.433820772403;4547.727136371828;3562;3663.084228942764;3691.8892433227047;3703.4444833274683;3716.851325946986;667.5894324990096;667.6895243711697;675.8918573028391;681.9556728812711;688.7348370877248;542.1704791668753;607.9088136779518;656.5765081522392;684.1789852177407;723.7286017743346;8794.60424280909;9354.95322523383;9459.19596834263;9506.429614223169;9546.140847323773;633.5944995203074;637.512303088138;660.8612191439755;691.7163962775238;698.9510734248655;4502.797374118172;4635.513271106553;4641.9580768175865;4690.484046754854;4710.317004034396;84590.5770471156;94826.0517394831;138685;144384.56779035222;145300.27349863184;121851;124691;127705;128031;129814.35092824475;67730.98403523951;68761;98511.50744246233;106611;110107;8583.141685831368;8936.642534298815;9035.322350823582;9078;9170.082991700776;62251.688741556005;62610.373896262274;64040.67979660072;64304.67847660732;64740.6762966201;79195;83385.16614833774;84371;85441;87256.1274387248;11618.593349232737;11620.128490363426;11733.941330293294;11873.406329683654;12080.39598020078;12966.675833104247;13011.414486347947;13043.021773366845;13477.932610336886;13715.519956801863;10110.342827716175;11934.641960741192;12164.39178041112;12294.446749896455;12648.747025059263;9715.36850104741;11874.168808183344;11894.583689570823;12107.394630268274;12303.261804291771;11942.701432464259;12154.331511766937;12322.938385308016;12345.259284442962;12544.184627999151;9611.519424028631;9628.988956159503;9724.611015559298;10420.531076101744;10599.89400105989;9017.774555635899;9074.954625226832;10384.628761943733;10605.840912386475;10714.89285107139 20:09:15 INFO - PROCESS | 1941 | __end_tp_report 20:09:15 INFO - PROCESS | 1941 | __start_cc_report 20:09:15 INFO - PROCESS | 1941 | _x_x_mozilla_cycle_collect,364 20:09:15 INFO - PROCESS | 1941 | __end_cc_report 20:09:15 INFO - PROCESS | 1941 | __startTimestamp1475636955382__endTimestamp 20:09:15 INFO - PROCESS | 1941 | 20:09:15 INFO - PROCESS | 1941 | ------- Summary: start ------- 20:09:15 INFO - PROCESS | 1941 | Number of tests: 6 20:09:15 INFO - PROCESS | 1941 | 20:09:15 INFO - PROCESS | 1941 | [#0] dojo.html Cycles:165 Average:5841.55 Median:6134.10 stddev:711.49 (11.6%) stddev-sans-first:683.48 20:09:15 INFO - PROCESS | 1941 | Values: 3227.4 3423.5 3430.4 3469.4 3471.9 4069.0 5767.8 5875.9 5918.5 5968.2 5901.9 5929.8 5965.8 5994.0 6018.5 5299.6 5313.2 5322.9 5441.5 5466.1 5448.9 5473.4 5497.2 5505.0 5515.4 5196.6 5254.7 5413.9 5425.7 5461.1 5284.0 5320.9 5330.7 5406.5 5447.0 3818.4 5314.4 5315.4 5347.8 5357.4 4857.4 5747.2 5948.9 6005.6 6014.0 6134.1 6141.8 6268.9 6269.0 6342.7 6389.6 6413.3 6415.7 6431.0 6434.2 6016.3 6059.7 6079.7 6086.0 6102.2 6241.1 6372.2 6385.9 6388.3 6402.5 6149.8 6163.6 6214.5 6218.1 6219.4 6215.5 6412.8 6428.7 6431.2 6444.1 6127.1 6254.4 6255.5 6258.0 6262.2 6150.5 6259.3 6293.8 6296.2 6312.2 4129.4 4205.7 4336.5 4990.5 5050.2 4502.4 4521.2 4533.1 4580.4 4609.1 5887.7 6022.3 6055.8 6066.5 6070.8 6208.6 6213.2 6415.8 6444.1 6444.5 5039.9 5735.3 6067.3 6080.7 6112.3 5513.6 5540.8 5563.2 5570.7 5574.7 6373.4 6517.8 6549.1 6556.7 6589.5 6244.5 6261.0 6266.1 6274.8 6369.0 6553.4 6571.5 6589.9 6593.6 6594.4 6286.2 6305.4 6328.6 6329.9 6335.6 5382.5 6206.8 6217.5 6239.8 6250.5 6261.3 6270.2 6270.7 6271.8 6276.0 6133.6 6204.5 6243.3 6243.4 6268.9 5467.3 5846.9 6142.6 6190.7 6213.4 6223.4 6239.6 6256.5 6276.2 6281.4 6041.3 6126.6 6134.4 6184.0 6190.4 20:09:15 INFO - PROCESS | 1941 | 20:09:15 INFO - PROCESS | 1941 | [#1] ext.html Cycles:180 Average:66063.37 Median:41563.58 stddev:138157.82 (332.4%) stddev-sans-first:138457.64 20:09:15 INFO - PROCESS | 1941 | Values: 479.9 519.6 530.8 532.8 542.2 39705.8 41957.6 41984.0 42098.2 42262.4 42438.0 42457.4 42925.0 42962.1 43008.1 9736.6 9864.9 9910.7 9919.0 9944.3 9768.4 9827.6 9888.0 9920.2 9923.0 9899.3 9925.7 9945.8 9960.3 9966.2 9886.5 9908.2 9927.1 9929.2 9942.6 9712.2 9763.1 9786.9 9822.9 9835.7 40916.6 41019.0 41113.8 41515.8 41611.4 3367.6 3372.0 3413.6 3435.0 3858.6 25283.3 30801.8 31569.0 31998.0 34087.6 3198.1 3215.1 3223.0 3247.8 3313.6 472509.0 477936.0 484508.0 485686.0 486338.0 57467.4 57715.4 57757.4 57792.4 57917.0 59490.7 59992.8 60249.4 60340.7 60758.0 15999.7 16227.9 16251.1 16290.8 16314.5 12578.0 12612.6 12633.6 12644.3 12686.2 15368.5 15643.3 15857.7 15925.4 15994.4 6989.4 7061.0 7091.7 7161.6 7187.6 899.0 948.5 978.6 1035.2 1058.4 29660.9 29747.1 29875.7 30600.7 30656.5 959.7 1017.1 1027.9 1079.6 1159.5 15110.8 15573.4 15589.7 15622.0 15750.5 569837.0 573208.0 573895.0 575061.0 581489.0 56535.0 56670.0 56934.0 56937.1 57034.0 1345.7 1363.0 1387.2 1391.4 1391.9 24946.9 28491.4 29402.8 29557.7 29855.0 47340.1 47598.0 47599.0 47612.0 47905.0 432851.0 506820.5 510430.0 513001.0 513950.0 37548.0 38739.8 38976.6 39042.0 39053.0 46006.3 46169.0 47665.0 49918.8 50530.5 37897.0 39466.8 39851.4 39909.0 40830.8 30931.2 38858.2 39357.2 40024.8 40031.8 37485.8 37781.6 38534.2 38652.6 38741.0 33114.5 33405.0 34730.5 34849.8 35010.6 23649.6 25878.4 25941.0 26268.2 26508.9 20:09:15 INFO - PROCESS | 1941 | 20:09:15 INFO - PROCESS | 1941 | [#2] jquery.html Cycles:180 Average:33567.26 Median:1327.74 stddev:94214.41 (7095.8%) stddev-sans-first:94445.60 20:09:15 INFO - PROCESS | 1941 | Values: 302.5 368.9 371.1 381.6 402.8 17455.7 19353.8 20886.8 21481.1 21764.8 16099.9 19807.4 20234.2 20348.1 20423.2 5211.2 5473.9 5545.1 5665.9 5677.1 5546.8 6842.5 7059.2 7076.4 7094.5 5202.4 6145.4 6214.5 6673.9 7002.3 5833.4 6167.2 6193.4 6196.9 6251.2 5936.6 6006.6 6050.8 6055.5 6056.7 13169.1 13190.4 13201.6 13355.3 13361.5 2118.8 2263.0 2325.2 2331.0 2353.0 10400.0 12331.8 14787.9 15419.7 15709.3 1839.9 1876.5 1878.2 1943.3 1958.7 71777.9 99195.0 111861.0 112630.0 113143.0 11292.7 11516.3 11770.6 11849.7 11919.3 14499.0 14755.3 14772.3 14788.5 14799.2 8369.7 8530.8 8553.2 8574.1 8609.5 8315.5 8362.7 8453.8 8536.7 8571.9 4571.8 4586.2 4592.1 4597.5 4659.3 1528.9 1832.6 1912.5 1953.1 1966.3 893.1 935.7 947.6 1006.9 1036.9 19853.8 20274.6 21233.8 21413.3 21726.6 947.3 948.7 964.4 965.4 972.9 10822.7 11280.2 11283.3 11319.9 11323.4 547615.0 557830.6 558463.0 559620.0 590373.0 129479.0 130784.3 132257.0 132800.3 136999.0 949.0 954.8 973.8 977.1 979.9 14971.0 15269.8 18294.0 18369.5 19048.5 56579.0 56718.7 56947.0 57520.4 57540.7 73547.5 81482.4 82395.0 83148.6 83336.6 6524.6 7485.7 7851.6 8137.6 8371.5 9935.6 12548.6 12556.1 12590.3 12661.0 9953.9 10863.6 10886.9 11121.7 11139.2 9978.7 10481.5 10811.8 10960.1 11016.8 10455.5 10508.4 10818.4 10941.5 11193.8 6506.5 6709.8 7762.7 7982.9 8180.6 9648.2 10410.7 10744.7 10814.5 10853.2 20:09:15 INFO - PROCESS | 1941 | 20:09:15 INFO - PROCESS | 1941 | [#3] mootools.html Cycles:180 Average:5714.04 Median:4988.04 stddev:3250.91 (65.2%) stddev-sans-first:3243.35 20:09:15 INFO - PROCESS | 1941 | Values: 1333.2 1505.6 1712.3 1884.3 1901.0 8149.8 8440.2 8637.2 9265.5 9269.9 8617.4 8908.6 8930.3 9027.5 9043.2 2354.3 2376.4 2386.0 2386.6 2398.3 2314.0 2371.2 2376.1 2394.7 2397.9 1886.7 2351.4 2354.5 2354.8 2368.9 2333.6 2347.3 2361.4 2361.7 2364.2 2317.3 2363.1 2364.1 2366.5 2375.2 9193.0 9237.9 9241.7 9250.3 9256.8 4413.9 5059.9 5113.0 5411.2 5421.3 3335.7 3503.4 3624.2 3685.8 3698.6 1048.2 1079.5 1088.5 1090.1 1103.9 12125.6 12241.2 12336.3 12370.7 12373.9 4570.7 4655.8 4853.4 4854.4 4865.3 10129.7 10313.0 10318.3 10347.3 10362.3 5348.0 5365.8 5401.7 5405.9 5434.8 4077.8 4442.8 4611.5 4762.6 4925.0 3386.7 3900.5 3934.8 4033.4 4112.5 985.9 1102.6 1128.9 1278.8 1284.3 741.5 835.0 913.6 937.8 940.0 5848.5 5889.8 5896.9 6023.8 6087.7 413.1 422.4 424.9 443.5 446.7 3692.7 3738.8 3929.5 4051.2 4126.7 9452.4 9598.3 9655.3 9679.5 9681.4 9654.2 9664.6 9670.3 9675.3 9694.2 7510.5 7749.4 8101.4 8235.8 8482.3 5654.6 5946.3 6087.8 6106.2 6226.3 10240.8 10442.8 10455.5 10530.3 10541.4 9933.4 10361.1 10425.3 10538.1 10580.1 6240.8 7064.3 7282.1 7471.3 7822.1 7695.3 8455.7 8625.0 8681.8 8757.7 7563.4 7723.3 7770.6 7813.5 7831.4 7388.2 7547.3 7561.8 7607.2 7696.5 6668.9 7141.9 7176.7 7179.0 7190.3 4938.3 5048.8 5057.4 5084.8 5193.0 4724.2 5037.8 5051.8 5073.2 5230.6 20:09:15 INFO - PROCESS | 1941 | 20:09:15 INFO - PROCESS | 1941 | [#4] prototype.html Cycles:180 Average:4336.20 Median:4663.54 stddev:1324.38 (28.4%) stddev-sans-first:1292.76 20:09:15 INFO - PROCESS | 1941 | Values: 287.4 294.8 300.7 309.6 311.2 4420.3 4796.4 4827.8 5170.4 5678.0 4494.6 5412.6 5467.4 5487.2 5506.6 3459.8 3656.8 3848.9 4225.3 4262.9 4037.8 4191.6 4239.9 4267.6 4289.5 3399.6 3522.9 4184.0 4190.2 4202.7 3632.1 4033.6 4186.7 4218.8 4222.7 3372.8 3377.4 3602.9 3671.7 3676.2 4582.2 4614.3 4873.3 5372.9 5376.5 4329.7 4489.5 4822.7 4845.9 4880.4 4991.5 5723.5 5799.0 5824.7 5834.7 3549.4 3804.4 3963.3 3999.8 4055.0 4940.3 5377.8 5592.0 6026.4 6034.6 4084.1 4089.4 4097.5 4099.3 4102.2 3936.7 3975.5 4041.6 4133.8 4163.8 4760.6 4783.4 4786.0 4797.4 4819.5 4567.7 5793.9 5809.8 5878.9 5879.9 1332.7 1335.9 1337.5 1396.2 1396.9 784.7 828.3 856.7 860.9 880.9 3890.9 4008.4 4554.0 4651.0 4662.9 4167.0 4314.4 4664.2 5024.0 5120.6 3861.2 3878.6 3956.5 4327.1 4483.9 2400.1 2515.2 2530.6 2544.5 2669.8 4263.5 4304.6 5071.2 5210.4 5217.6 4464.2 4511.5 4511.5 5054.0 5177.3 4388.5 4812.7 5095.6 5173.1 5251.7 4464.4 4654.1 4697.4 5058.8 5064.9 4255.1 4648.4 4719.7 4740.3 4872.9 4720.6 4769.9 4865.0 4920.9 5249.2 3019.0 3061.9 3333.5 3351.0 3436.0 4331.9 4376.6 4752.7 4799.9 4866.9 5436.5 5548.7 5554.0 5808.4 5838.7 5818.5 5828.0 5828.9 5873.9 5876.6 5519.6 5620.5 5633.9 5728.8 5730.6 4713.8 5705.7 5807.3 5834.2 5834.5 5011.7 5225.4 5413.5 5711.5 5794.9 20:09:15 INFO - PROCESS | 1941 | 20:09:15 INFO - PROCESS | 1941 | [#5] yui.html Cycles:180 Average:22808.63 Median:4516.12 stddev:35754.80 (791.7%) stddev-sans-first:35816.62 20:09:15 INFO - PROCESS | 1941 | Values: 716.2 720.0 743.3 777.5 802.9 14536.9 15022.4 15080.4 15083.7 15086.9 14033.5 14232.7 14570.6 14598.2 14604.0 676.3 702.2 832.7 834.9 861.0 851.6 872.6 873.2 873.4 874.7 925.3 925.6 927.7 931.5 932.7 920.7 921.7 923.1 923.3 928.5 498.8 626.9 627.4 683.7 684.5 15193.0 15350.5 15377.0 15405.7 15413.2 12115.5 14534.0 14548.3 14568.9 14776.6 11913.1 11954.8 11967.2 12071.4 12117.6 3630.3 3790.1 3794.8 3827.3 3842.7 96983.0 97573.0 105403.5 107105.4 108277.5 10891.2 10908.6 10919.5 10919.6 11069.3 20332.3 20535.1 20645.8 20700.8 20702.3 6453.5 7541.2 7741.7 7786.9 7907.8 4320.5 4440.3 4501.5 4529.4 4547.7 3562.0 3663.1 3691.9 3703.4 3716.9 667.6 667.7 675.9 682.0 688.7 542.2 607.9 656.6 684.2 723.7 8794.6 9355.0 9459.2 9506.4 9546.1 633.6 637.5 660.9 691.7 699.0 4502.8 4635.5 4642.0 4690.5 4710.3 84590.6 94826.1 138685.0 144384.6 145300.3 121851.0 124691.0 127705.0 128031.0 129814.4 67731.0 68761.0 98511.5 106611.0 110107.0 8583.1 8936.6 9035.3 9078.0 9170.1 62251.7 62610.4 64040.7 64304.7 64740.7 79195.0 83385.2 84371.0 85441.0 87256.1 11618.6 11620.1 11733.9 11873.4 12080.4 12966.7 13011.4 13043.0 13477.9 13715.5 10110.3 11934.6 12164.4 12294.4 12648.7 9715.4 11874.2 11894.6 12107.4 12303.3 11942.7 12154.3 12322.9 12345.3 12544.2 9611.5 9629.0 9724.6 10420.5 10599.9 9017.8 9075.0 10384.6 10605.8 10714.9 20:09:15 INFO - PROCESS | 1941 | -------- Summary: end -------- 20:09:15 INFO - PROCESS | 1941 | 20:09:16 INFO - TEST-INFO | 1941: exit 0 20:09:16 INFO - TEST-OK | dromaeo_css | took 1115140ms 20:09:16 INFO - TEST-START | kraken 20:09:16 INFO - operating with platform_type : mac_ 20:09:16 INFO - Initialising browser for kraken test... 20:09:16 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpOZLiRk/profile http://localhost:53420/getInfo.html) 20:09:18 INFO - PROCESS | 1947 | 2016-10-04 20:09:18.731 plugin-container[1948:20027] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e1f, name = 'com.apple.tsm.portname' 20:09:18 INFO - PROCESS | 1947 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:09:18 INFO - PROCESS | 1947 | 2016-10-04 20:09:18.734 plugin-container[1948:20027] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9303, name = 'com.apple.CFPasteboardClient' 20:09:18 INFO - PROCESS | 1947 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:09:18 INFO - PROCESS | 1947 | 2016-10-04 20:09:18.735 plugin-container[1948:20027] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:09:18 INFO - PROCESS | 1947 | __metrics Screen width/height:1600/1200 20:09:18 INFO - PROCESS | 1947 | colorDepth:24 20:09:18 INFO - PROCESS | 1947 | Browser inner width/height: 1024/689 20:09:18 INFO - PROCESS | 1947 | __metrics 20:09:18 ERROR - PROCESS | 1947 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 20:09:19 INFO - TEST-INFO | 1947: exit 0 20:09:19 INFO - Browser initialized. 20:09:19 INFO - Running cycle 1/1 for kraken test... 20:09:19 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpOZLiRk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:09:21 INFO - PROCESS | 1949 | 2016-10-04 20:09:21.908 plugin-container[1950:20143] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a1f, name = 'com.apple.tsm.portname' 20:09:21 INFO - PROCESS | 1949 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:09:21 INFO - PROCESS | 1949 | 2016-10-04 20:09:21.912 plugin-container[1950:20143] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 20:09:21 INFO - PROCESS | 1949 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:09:21 INFO - PROCESS | 1949 | 2016-10-04 20:09:21.912 plugin-container[1950:20143] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:09:31 INFO - PROCESS | 1949 | RSS: Main: 240414720 20:09:31 INFO - PROCESS | 1949 | 20:09:34 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53420/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53420/tests/kraken/driver.html?testName=audio-beat-detection) 20:09:35 INFO - PROCESS | 1949 | RSS: Main: 253853696 20:09:35 INFO - PROCESS | 1949 | 20:09:38 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53420/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53420/tests/kraken/driver.html?testName=audio-dft) 20:09:38 INFO - PROCESS | 1949 | RSS: Main: 261300224 20:09:38 INFO - PROCESS | 1949 | 20:09:42 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53420/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53420/tests/kraken/driver.html?testName=audio-fft) 20:09:42 INFO - PROCESS | 1949 | RSS: Main: 262180864 20:09:42 INFO - PROCESS | 1949 | 20:09:44 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53420/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53420/tests/kraken/driver.html?testName=audio-oscillator) 20:09:45 INFO - PROCESS | 1949 | RSS: Main: 264437760 20:09:45 INFO - PROCESS | 1949 | 20:09:47 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53420/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53420/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:09:47 INFO - PROCESS | 1949 | RSS: Main: 264785920 20:09:47 INFO - PROCESS | 1949 | 20:09:50 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53420/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53420/tests/kraken/driver.html?testName=imaging-darkroom) 20:09:51 INFO - PROCESS | 1949 | RSS: Main: 266067968 20:09:51 INFO - PROCESS | 1949 | 20:09:54 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53420/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53420/tests/kraken/driver.html?testName=imaging-desaturate) 20:09:55 INFO - PROCESS | 1949 | RSS: Main: 266108928 20:09:55 INFO - PROCESS | 1949 | 20:09:58 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53420/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53420/tests/kraken/driver.html?testName=json-parse-financial) 20:09:58 INFO - PROCESS | 1949 | RSS: Main: 266530816 20:09:58 INFO - PROCESS | 1949 | 20:10:00 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53420/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53420/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:10:00 INFO - PROCESS | 1949 | RSS: Main: 266076160 20:10:00 INFO - PROCESS | 1949 | 20:10:02 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53420/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53420/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:10:03 INFO - PROCESS | 1949 | RSS: Main: 266104832 20:10:03 INFO - PROCESS | 1949 | 20:10:05 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53420/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53420/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:10:06 INFO - PROCESS | 1949 | RSS: Main: 266125312 20:10:06 INFO - PROCESS | 1949 | 20:10:09 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53420/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53420/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:10:10 INFO - PROCESS | 1949 | RSS: Main: 266510336 20:10:10 INFO - PROCESS | 1949 | 20:10:13 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53420/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53420/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:10:13 INFO - PROCESS | 1949 | RSS: Main: 266571776 20:10:13 INFO - PROCESS | 1949 | 20:10:15 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53420/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53420/tests/kraken/driver.html?testName=ai-astar) 20:10:15 INFO - PROCESS | 1949 | RSS: Main: 268423168 20:10:15 INFO - PROCESS | 1949 | 20:10:15 INFO - PROCESS | 1949 | __start_tp_report 20:10:15 INFO - PROCESS | 1949 | _x_x_mozilla_page_load 20:10:15 INFO - PROCESS | 1949 | _x_x_mozilla_page_load_details 20:10:15 INFO - PROCESS | 1949 | |i|pagename|runs| 20:10:15 INFO - PROCESS | 1949 | |0;ai-astar;106;117;118;117;118;117;117;116;117;116 20:10:15 INFO - PROCESS | 1949 | |1;audio-beat-detection;164;144;108;175;145;146;113;115;148;117 20:10:15 INFO - PROCESS | 1949 | |2;audio-dft;199;231;236;196;194;196;197;194;192;197 20:10:15 INFO - PROCESS | 1949 | |3;audio-fft;82;58;59;84;58;60;58;59;78;61 20:10:15 INFO - PROCESS | 1949 | |4;audio-oscillator;83;73;85;73;69;71;89;69;73;88 20:10:15 INFO - PROCESS | 1949 | |5;imaging-gaussian-blur;90;88;90;88;90;87;90;89;90;87 20:10:15 INFO - PROCESS | 1949 | |6;imaging-darkroom;137;155;97;128;97;97;96;97;152;154 20:10:15 INFO - PROCESS | 1949 | |7;imaging-desaturate;89;92;75;141;145;91;116;134;147;101 20:10:15 INFO - PROCESS | 1949 | |8;json-parse-financial;56;57;57;56;59;58;58;58;58;57 20:10:15 INFO - PROCESS | 1949 | |9;json-stringify-tinderbox;67;76;71;86;76;75;68;70;70;70 20:10:15 INFO - PROCESS | 1949 | |10;stanford-crypto-aes;101;110;85;88;90;89;102;95;89;93 20:10:15 INFO - PROCESS | 1949 | |11;stanford-crypto-ccm;147;152;159;160;166;132;169;151;207;159 20:10:15 INFO - PROCESS | 1949 | |12;stanford-crypto-pbkdf2;175;178;172;175;173;175;175;175;173;173 20:10:15 INFO - PROCESS | 1949 | |13;stanford-crypto-sha256-iterative;73;70;70;73;72;72;80;92;93;96 20:10:15 INFO - PROCESS | 1949 | __end_tp_report 20:10:15 INFO - PROCESS | 1949 | __start_cc_report 20:10:15 INFO - PROCESS | 1949 | _x_x_mozilla_cycle_collect,935 20:10:15 INFO - PROCESS | 1949 | __end_cc_report 20:10:15 INFO - PROCESS | 1949 | __startTimestamp1475637015819__endTimestamp 20:10:15 INFO - PROCESS | 1949 | 20:10:15 INFO - PROCESS | 1949 | ------- Summary: start ------- 20:10:15 INFO - PROCESS | 1949 | Number of tests: 14 20:10:15 INFO - PROCESS | 1949 | 20:10:15 INFO - PROCESS | 1949 | [#0] ai-astar Cycles:10 Average:115.90 Median:117.00 stddev:3.54 (3.0%) stddev-sans-first:0.71 20:10:15 INFO - PROCESS | 1949 | Values: 106.0 117.0 118.0 117.0 118.0 117.0 117.0 116.0 117.0 116.0 20:10:15 INFO - PROCESS | 1949 | 20:10:15 INFO - PROCESS | 1949 | [#1] audio-beat-detection Cycles:10 Average:137.50 Median:145.50 stddev:23.06 (15.8%) stddev-sans-first:22.38 20:10:15 INFO - PROCESS | 1949 | Values: 164.0 144.0 108.0 175.0 145.0 146.0 113.0 115.0 148.0 117.0 20:10:15 INFO - PROCESS | 1949 | 20:10:15 INFO - PROCESS | 1949 | [#2] audio-dft Cycles:10 Average:203.20 Median:197.00 stddev:16.13 (8.2%) stddev-sans-first:17.04 20:10:15 INFO - PROCESS | 1949 | Values: 199.0 231.0 236.0 196.0 194.0 196.0 197.0 194.0 192.0 197.0 20:10:15 INFO - PROCESS | 1949 | 20:10:15 INFO - PROCESS | 1949 | [#3] audio-fft Cycles:10 Average:65.70 Median:60.50 stddev:10.92 (18.1%) stddev-sans-first:9.87 20:10:15 INFO - PROCESS | 1949 | Values: 82.0 58.0 59.0 84.0 58.0 60.0 58.0 59.0 78.0 61.0 20:10:15 INFO - PROCESS | 1949 | 20:10:15 INFO - PROCESS | 1949 | [#4] audio-oscillator Cycles:10 Average:77.30 Median:78.00 stddev:8.00 (10.3%) stddev-sans-first:8.22 20:10:15 INFO - PROCESS | 1949 | Values: 83.0 73.0 85.0 73.0 69.0 71.0 89.0 69.0 73.0 88.0 20:10:15 INFO - PROCESS | 1949 | 20:10:15 INFO - PROCESS | 1949 | [#5] imaging-gaussian-blur Cycles:10 Average:88.90 Median:90.00 stddev:1.29 (1.4%) stddev-sans-first:1.30 20:10:15 INFO - PROCESS | 1949 | Values: 90.0 88.0 90.0 88.0 90.0 87.0 90.0 89.0 90.0 87.0 20:10:15 INFO - PROCESS | 1949 | 20:10:15 INFO - PROCESS | 1949 | [#6] imaging-darkroom Cycles:10 Average:121.00 Median:96.50 stddev:26.75 (27.7%) stddev-sans-first:27.74 20:10:15 INFO - PROCESS | 1949 | Values: 137.0 155.0 97.0 128.0 97.0 97.0 96.0 97.0 152.0 154.0 20:10:15 INFO - PROCESS | 1949 | 20:10:15 INFO - PROCESS | 1949 | [#7] imaging-desaturate Cycles:10 Average:113.10 Median:111.00 stddev:26.88 (24.2%) stddev-sans-first:27.06 20:10:15 INFO - PROCESS | 1949 | Values: 89.0 92.0 75.0 141.0 145.0 91.0 116.0 134.0 147.0 101.0 20:10:15 INFO - PROCESS | 1949 | 20:10:15 INFO - PROCESS | 1949 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:58.00 stddev:0.97 (1.7%) stddev-sans-first:0.88 20:10:15 INFO - PROCESS | 1949 | Values: 56.0 57.0 57.0 56.0 59.0 58.0 58.0 58.0 58.0 57.0 20:10:15 INFO - PROCESS | 1949 | 20:10:15 INFO - PROCESS | 1949 | [#9] json-stringify-tinderbox Cycles:10 Average:72.90 Median:73.00 stddev:5.61 (7.7%) stddev-sans-first:5.53 20:10:15 INFO - PROCESS | 1949 | Values: 67.0 76.0 71.0 86.0 76.0 75.0 68.0 70.0 70.0 70.0 20:10:15 INFO - PROCESS | 1949 | 20:10:15 INFO - PROCESS | 1949 | [#10] stanford-crypto-aes Cycles:10 Average:94.20 Median:89.00 stddev:7.84 (8.8%) stddev-sans-first:7.92 20:10:15 INFO - PROCESS | 1949 | Values: 101.0 110.0 85.0 88.0 90.0 89.0 102.0 95.0 89.0 93.0 20:10:15 INFO - PROCESS | 1949 | 20:10:15 INFO - PROCESS | 1949 | [#11] stanford-crypto-ccm Cycles:10 Average:160.20 Median:159.50 stddev:19.51 (12.2%) stddev-sans-first:20.10 20:10:15 INFO - PROCESS | 1949 | Values: 147.0 152.0 159.0 160.0 166.0 132.0 169.0 151.0 207.0 159.0 20:10:15 INFO - PROCESS | 1949 | 20:10:15 INFO - PROCESS | 1949 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.40 Median:175.00 stddev:1.71 (1.0%) stddev-sans-first:1.80 20:10:15 INFO - PROCESS | 1949 | Values: 175.0 178.0 172.0 175.0 173.0 175.0 175.0 175.0 173.0 173.0 20:10:15 INFO - PROCESS | 1949 | 20:10:15 INFO - PROCESS | 1949 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.10 Median:76.50 stddev:10.47 (13.7%) stddev-sans-first:10.87 20:10:15 INFO - PROCESS | 1949 | Values: 73.0 70.0 70.0 73.0 72.0 72.0 80.0 92.0 93.0 96.0 20:10:15 INFO - PROCESS | 1949 | -------- Summary: end -------- 20:10:15 INFO - PROCESS | 1949 | 20:10:16 INFO - TEST-INFO | 1949: exit 0 20:10:16 INFO - TEST-OK | kraken | took 60609ms 20:10:16 INFO - SUITE-END | took 1175s 20:10:16 INFO - Completed test suite (00:19:36) 20:10:16 INFO - javascript benchmark 20:10:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7242.858169718464, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3227.37066272077, 3423.486477028446, 3430.4168291390447, 3469.427544455165, 3471.861125554978, 4069.0, 5767.798127065554, 5875.8824823503555, 5918.46733793959, 5968.164456976021, 5901.940980590193, 5929.822105336836, 5965.761369545224, 5993.970030149854, 6018.458338749511, 5299.5760339172775, 5313.1764576490705, 5322.92015619766, 5441.5102640762325, 5466.125419932811, 5448.91826622601, 5473.370562385317, 5497.202905578689, 5504.981578408007, 5515.365732940723, 5196.610254230948, 5254.684718916839, 5413.864653383697, 5425.674459532441, 5461.0716178249595, 5284.048871203182, 5320.9467905320835, 5330.653507522037, 5406.54044406226, 5447.046766815792, 3818.4463252828464, 5314.362276526803, 5315.362156541201, 5347.839564813062, 5357.383900851413, 4857.417109946829, 5747.166660834156, 5948.851278718023, 6005.609635373687, 6013.977623803964, 6134.099846298167, 6141.762184093006, 6268.937310626881, 6269.0, 6342.6511541865175, 6389.5527313088105, 6413.326600706905, 6415.743370265184, 6431.0, 6434.227892652865, 6016.308124565688, 6059.697015149225, 6079.726412311456, 6085.96538588441, 6102.176206212149, 6241.095041218998, 6372.235331760218, 6385.936140638534, 6388.2653494847755, 6402.455791257702, 6149.815505534841, 6163.568550201443, 6214.471769899519, 6218.067289906459, 6219.440250377397, 6215.533834962396, 6412.839679008062, 6428.71070801815, 6431.196100487439, 6444.13004244421, 6127.0673618518995, 6254.405831446005, 6255.468285195719, 6258.0, 6262.248530176408, 6150.477209437248, 6259.342769009279, 6293.811185664437, 6296.181496405438, 6312.179416675803, 4129.401236820644, 4205.747655140702, 4336.457942757155, 4990.525900039563, 5050.191969284897, 4502.414686090788, 4521.231390663595, 4533.138703646297, 4580.358749775034, 4609.055143695535, 5887.735051922674, 6022.307434644984, 6055.818325450244, 6066.514678825683, 6070.817875463743, 6208.596441231305, 6213.1922850029205, 6415.839604009844, 6444.065610486453, 6444.548881578338, 5039.941612261467, 5735.311762588516, 6067.302260240041, 6080.69596520181, 6112.266528016667, 5513.58648101394, 5540.750666220029, 5563.165525171175, 5570.693611851306, 5574.749136288877, 6373.362663733775, 6517.771877984343, 6549.083128362033, 6556.672166391757, 6589.538732288694, 6244.500439964701, 6260.968695156495, 6266.060090986206, 6274.811755647338, 6369.044643303541, 6553.377429144223, 6571.507136964651, 6589.934100659124, 6593.571417857824, 6594.406503414716, 6286.214223222098, 6305.432511074026, 6328.557001010069, 6329.873402531832, 6335.619862808062, 5382.4886635769535, 6206.844828879314, 6217.533684973555, 6239.812805615838, 6250.468710159687, 6261.279952805486, 6270.2475702915945, 6270.686465676789, 6271.78048768291, 6276.0, 6133.570650054453, 6204.534659900435, 6243.313235544178, 6243.37566243372, 6268.937310627018, 5467.261919640798, 5846.912296315473, 6142.57002009855, 6190.659513726608, 6213.440790328892, 6223.37766223374, 6239.594426362272, 6256.530760192913, 6276.184096067482, 6281.371862813682, 6041.305249896318, 6126.632402055891, 6134.447899688871, 6184.041473571604, 6190.442860142609], "value": 5795.462297033211, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.8896253861613, 519.5687579309173, 530.8434011966469, 532.8268312798339, 542.2083757713738, 39705.838241028665, 41957.58042419575, 41984.0, 42098.158036839246, 42262.36606450902, 42438.0, 42457.36313955293, 42925.0, 42962.14075718484, 43008.139837203315, 9736.610535578568, 9864.901350986505, 9910.653127140551, 9919.0, 9944.254180936423, 9768.413895166277, 9827.557759900787, 9887.950560247225, 9920.206383489303, 9922.950385248063, 9899.307048506607, 9925.702228933143, 9945.751356216117, 9960.302778805486, 9966.202703783716, 9886.505674716307, 9908.20734341251, 9927.106560409598, 9929.2056635469, 9942.55258513365, 9712.174465170468, 9763.121319081245, 9786.853197202048, 9822.901770982271, 9835.655752048715, 40916.590834091876, 41019.0, 41113.79443102765, 41515.792421038, 41611.37582936259, 3367.5958884933966, 3371.96628033719, 3413.641567635387, 3434.969509147271, 3858.614138586147, 25283.333916581993, 30801.845990770125, 31569.0, 31998.040058798273, 34087.63649454019, 3198.1365031441383, 3215.1479857837694, 3222.951655725166, 3247.756418268639, 3313.6023677158655, 472509.0, 477936.0, 484508.0, 485686.0, 486338.0, 57467.42532574704, 57715.42284577184, 57757.42242577604, 57792.39934202971, 57917.0, 59490.70254648699, 59992.80014399687, 60249.397506025256, 60340.698296508235, 60758.0, 15999.680006399807, 16227.918860405622, 16251.10618915971, 16290.755638665429, 16314.510564683078, 12578.0, 12612.621621351374, 12633.557825476064, 12644.304563248925, 12686.175398599062, 15368.538943831703, 15643.296051677702, 15857.68284634324, 15925.362985480682, 15994.360225590846, 6989.4408447324095, 7061.0, 7091.723489771787, 7161.606111663804, 7187.5687458752645, 898.9820203595891, 948.4735971535864, 978.5889926231, 1035.1563475767225, 1058.3544038136731, 29660.851695741385, 29747.107586772432, 29875.701242987725, 30600.69399306023, 30656.540151897738, 959.7216807125856, 1017.1049476460668, 1027.9331843430152, 1079.6005477973201, 1159.513004538096, 15110.848891510945, 15573.377064917502, 15589.68820623581, 15621.984571002848, 15750.527484175493, 569837.0, 573208.0, 573895.0, 575061.0, 581489.0, 56535.0, 56670.0, 56934.0, 56937.145942810894, 57034.0, 1345.6837643153851, 1362.9591112266648, 1387.2162228341156, 1391.4364682303683, 1391.902566820333, 24946.875265623556, 28491.432971186783, 29402.823887045135, 29557.704422956354, 29855.0, 47340.05319893514, 47598.0, 47598.99999999862, 47612.0, 47905.0, 432851.0, 506820.46589766815, 510430.0, 513001.0, 513950.0, 37548.0, 38739.80630096832, 38976.6102338973, 39042.0, 39053.0, 46006.309905350776, 46169.04358876331, 47665.0, 49918.75040624774, 50530.45225929684, 37897.0, 39466.802665986484, 39851.40222896717, 39909.0, 40830.77507674775, 30931.226719332197, 38858.2228355437, 39357.237795135305, 40024.799876000434, 40031.79984100061, 37485.81257093806, 37781.622183777814, 38534.22931541297, 38652.647157349355, 38741.0, 33114.5032824503, 33405.0, 34730.47904281387, 34849.8257508721, 35010.64989350074, 23649.645255321528, 25878.353041174123, 25941.0, 26268.21195364142, 26508.8674556626], "value": 19971.86801365248, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.48577418388743, 368.9372806622874, 371.0852747976238, 381.6450700848211, 402.816718393131, 17455.738163927534, 19353.806461935375, 20886.791132088674, 21481.140754369826, 21764.782352176473, 16099.919500402511, 19807.405777826687, 20234.190632374724, 20348.08433620487, 20423.18307267711, 5211.166213405856, 5473.863153421156, 5545.085060964936, 5665.915011274834, 5677.091665333547, 5546.750396232179, 6842.452603791709, 7059.152901651809, 7076.433885289191, 7094.503384763068, 5202.375714914216, 6145.4161854623735, 6214.50283977283, 6673.865442874699, 7002.334778196063, 5833.449909070931, 6167.229096362977, 6193.411625895533, 6196.876062478747, 6251.156093927307, 5936.643801371932, 6006.639601623873, 6050.757969681207, 6055.485283750842, 6056.727447264883, 13169.078164528486, 13190.406431710597, 13201.60395188146, 13355.265460399674, 13361.465541378333, 2118.8304935605115, 2262.9773702262933, 2325.186184835294, 2331.0, 2353.0, 10400.0, 12331.815022774666, 14787.852121478863, 15419.691606167926, 15709.293081811345, 1839.8804077734903, 1876.5496280892487, 1878.2017642501883, 1943.280986035162, 1958.6670266054805, 71777.92333114955, 99195.0080499193, 111861.0, 112630.0, 113143.0, 11292.717682058013, 11516.30902145874, 11770.646880593597, 11849.703757406134, 11919.3444360561, 14499.0, 14755.262236888113, 14772.335244914004, 14788.482403115839, 14799.18604476764, 8369.665213391396, 8530.829383412298, 8553.187447192508, 8574.142585741376, 8609.48343099416, 8315.459495132796, 8362.707305244287, 8453.830923381498, 8536.70121545743, 8571.871421928676, 4571.839985600488, 4586.243269860503, 4592.127495775792, 4597.540245975376, 4659.277811939155, 1528.8929774915648, 1832.6059897122182, 1912.512309361104, 1953.1113343428706, 1966.3117908731829, 893.0756666849779, 935.6725146198912, 947.5688561704408, 1006.9144122749502, 1036.8807587127576, 19853.801461985197, 20274.594508109756, 21233.78766212318, 21413.250536231157, 21726.565468690536, 947.313197931508, 948.6632245552868, 964.4261664309724, 965.3725078698902, 972.8783902012249, 10822.729431764268, 11280.210385273116, 11283.323000619988, 11319.886801131883, 11323.377214253294, 547615.0, 557830.5904937069, 558463.0, 559620.0, 590373.0, 129479.0, 130784.3460782709, 132257.0, 132800.33599831938, 136999.0, 948.9525523723786, 954.8424509955919, 973.8393165127816, 977.1401166973018, 979.857920601523, 14971.026883252554, 15269.770953435485, 18293.993830338928, 18369.54076148107, 19048.523786904883, 56579.0, 56718.716406417705, 56947.0, 57520.424795753184, 57540.71229643825, 73547.52904941764, 81482.37035259143, 82395.0, 83148.58425707833, 83336.58331708303, 6524.608523488606, 7485.737999170003, 7851.60741962911, 8137.633806478604, 8371.539565323723, 9935.552900119368, 12548.560800371943, 12556.121071524905, 12590.307533085768, 12661.0, 9953.85530663954, 10863.590481615885, 10886.945565272123, 11121.666350009513, 11139.220254582102, 9978.700638980843, 10481.475926203811, 10811.783764324513, 10960.12319014461, 11016.779664406828, 10455.477226138815, 10508.369497829848, 10818.404987725751, 10941.507632156412, 11193.776124477628, 6506.479481641552, 6709.832254193684, 7762.689492420466, 7982.920170798217, 8180.550069746221, 9648.228141748783, 10410.739731506774, 10744.677659670222, 10814.513346899566, 10853.240273180802], "value": 9415.024082596585, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1333.2467156056828, 1505.616067902685, 1712.3321904457257, 1884.3028079610547, 1901.0, 8149.755507334775, 8440.155984401572, 8637.222649961503, 9265.53672316384, 9269.860952085724, 8617.396782225247, 8908.599113039912, 8930.285577153829, 9027.503487308195, 9043.186113249807, 2354.3290162303706, 2376.4058985253687, 2386.0217310902535, 2386.6300723387903, 2398.3284680289457, 2313.976860231393, 2371.158238825218, 2376.1089591403224, 2394.6767186429784, 2397.880105994693, 1886.7264246684222, 2351.400392899817, 2354.458474550854, 2354.799842013431, 2368.928932132039, 2333.5916214662366, 2347.3310106619615, 2361.4332560185603, 2361.6929799126087, 2364.243442098512, 2317.304808557426, 2363.0902102690416, 2364.054378248697, 2366.479374537599, 2375.168690958168, 9192.954035229848, 9237.861432078524, 9241.722748317561, 9250.306227033, 9256.768580785467, 4413.889652758675, 5059.87350316245, 5113.0, 5411.18832175173, 5421.349438067417, 3335.699787019156, 3503.3693935091674, 3624.2026754114054, 3685.7788532688123, 3698.6301369863068, 1048.2086025051115, 1079.4872435593109, 1088.5101704233127, 1090.1061129873508, 1103.8564986551696, 12125.636230913087, 12241.20432171906, 12336.321502317369, 12370.690732731664, 12373.93813030929, 4570.680052396367, 4655.836040989752, 4853.39332583427, 4854.393200849894, 4865.294532292798, 10129.746756331004, 10313.0, 10318.329308594917, 10347.327423717434, 10362.326448780805, 5348.03735327637, 5365.7585408656705, 5401.675899446046, 5405.864853378697, 5434.809781657702, 4077.8368865245056, 4442.777861106932, 4611.538846115429, 4762.59517940972, 4925.014997000614, 3386.745994050407, 3900.531936167621, 3934.8229329680235, 4033.3748269018347, 4112.527059388209, 985.930265661765, 1102.5589764094427, 1128.90968722502, 1278.7953927371575, 1284.2679672586723, 741.4846681556375, 834.9540775257298, 913.5660561233361, 937.8452555328431, 939.9765005874908, 5848.502877255396, 5889.793857214977, 5896.941030589725, 6023.759049637965, 6087.6956152192215, 413.136544621742, 422.3622330281236, 424.94688163979504, 443.45898004434537, 446.68285517283095, 3692.6521819536215, 3738.831752571141, 3929.4891664084057, 4051.230266249403, 4126.711130220856, 9452.385594936308, 9598.280128990353, 9655.275854310954, 9679.467629280316, 9681.37071090377, 9654.179394751385, 9664.613415463444, 9670.323077384515, 9675.322727409013, 9694.22446204302, 7510.511816731895, 7749.380049596019, 8101.392395570356, 8235.8352832943, 8482.321414286966, 5654.604177707521, 5946.345901950695, 6087.786927457518, 6106.236720409949, 6226.346233645583, 10240.846387304346, 10442.843357349799, 10455.529501172314, 10530.262881598215, 10541.367517948947, 9933.403995760278, 10361.119304859172, 10425.270231083752, 10538.104261137889, 10580.100691441006, 6240.781572644935, 7064.293570642894, 7282.053333066668, 7471.290227428385, 7822.100458447351, 7695.268949449794, 8455.689368147947, 8624.965004199536, 8681.782955426164, 8757.6934807284, 7563.357114645097, 7723.266289702469, 7770.611469426619, 7813.492123011986, 7831.412644051833, 7388.1503627083575, 7547.320741133324, 7561.848763024599, 7607.201243869536, 7696.499727517694, 6668.899966500409, 7141.928580714334, 7176.677049532679, 7179.03083083777, 7190.280971902768, 4938.259261110718, 5048.848534543969, 5057.409239440039, 5084.7966081356335, 5193.0, 4724.173269677862, 5037.823676171316, 5051.848444546669, 5073.16292811682, 5230.5815534757885], "value": 4489.063282218104, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [287.35488827582077, 294.79953631530566, 300.6917909143129, 309.6346311352606, 311.2079757018388, 4420.314851198062, 4796.448408433027, 4827.758612069396, 5170.405403378612, 5678.0347340952085, 4494.640428765699, 5412.5940554458375, 5467.398586155519, 5487.161800796728, 5506.64206826557, 3459.809710465923, 3656.7567027210744, 3848.9230215395673, 4225.302825033873, 4262.87211383659, 4037.798110094498, 4191.559886211934, 4239.851605193819, 4267.594578515036, 4289.528151903288, 3399.609044959824, 3522.8766993155245, 4183.991683991686, 4190.234445266125, 4202.747835129902, 3632.0919770057353, 4033.576474470197, 4186.70693051485, 4218.831246750126, 4222.690965800612, 3372.8313584320686, 3377.3583019226267, 3602.9279414411567, 3671.724620653435, 3676.1544844685604, 4582.24392975162, 4614.330922016289, 4873.320642914612, 5372.865678358034, 5376.462353764631, 4329.696921215516, 4489.46126464823, 4822.734749588772, 4845.927311090301, 4880.389951256093, 4991.475895031006, 5723.484886360206, 5798.971005144947, 5824.650520968756, 5834.6790926499025, 3549.396602577568, 3804.410316400962, 3963.286608410485, 3999.8200080996135, 4054.959450405517, 4940.3330550375595, 5377.7580008899695, 5592.029658942115, 6026.367231440723, 6034.607750496204, 4084.14233011071, 4089.427480152781, 4097.487814023247, 4099.323611604112, 4102.2000709861295, 3936.7047471439755, 3975.522937247491, 4041.5756345583877, 4133.772642504691, 4163.812628431728, 4760.619150468024, 4783.373652957941, 4785.952140478551, 4797.368894575861, 4819.518048195152, 4567.680262381601, 5793.855153621109, 5809.767609295665, 5878.854218210323, 5879.911801322984, 1332.7201287729492, 1335.8740547119787, 1337.5118081900036, 1396.232072360198, 1396.9022168448314, 784.6822037074994, 828.341468532506, 856.738694698123, 860.8536548786722, 880.903100658927, 3890.902727431837, 4008.376607473978, 4554.0, 4651.0, 4662.906741865144, 4166.979165104155, 4314.439122914001, 4664.180969421928, 5023.974880125649, 5120.590352771769, 3861.1505468796818, 3878.592747761501, 3956.5252169739815, 4327.112941846914, 4483.910321793546, 2400.099962514057, 2515.232853979517, 2530.557582178141, 2544.4656622109196, 2669.79507276041, 4263.467066616673, 4304.569543045733, 5071.236176283934, 5210.426853046162, 5217.57382816992, 4464.196444639996, 4511.458624965026, 4511.481179664315, 5054.0, 5177.275181474596, 4388.467283836479, 4812.711237325771, 5095.566876815438, 5173.076827033026, 5251.739582500249, 4464.441944756905, 4654.114449041615, 4697.416420968522, 5058.822941197188, 5064.924026139537, 4255.085266592394, 4648.37246971668, 4719.693219940693, 4740.312654665123, 4872.853814385574, 4720.645951553579, 4769.880752981203, 4864.951350486592, 4920.852374428773, 5249.212618107162, 3019.033909149139, 3061.8622162002316, 3333.4999750037687, 3351.0, 3435.952034629462, 4331.870043898688, 4376.592873919395, 4752.714837109785, 4799.856004319737, 4866.878328041827, 5436.456354364532, 5548.694821784679, 5553.9725150847225, 5808.448197421239, 5838.737256823369, 5818.5453636590855, 5828.0, 5828.892510423006, 5873.853153671193, 5876.559258055749, 5519.558435325084, 5620.522255608155, 5633.943660563506, 5728.828135155952, 5730.5702072345575, 4713.764311784328, 5705.7147142643535, 5807.332156802022, 5834.241548598655, 5834.504067154339, 5011.7494125294315, 5225.372955245243, 5413.485718856702, 5711.457411545896, 5794.913076303943], "value": 3920.5485405742947, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [716.1656669979474, 719.9812265644114, 743.2715938380388, 777.5451360953841, 802.9478083924548, 14536.854631453682, 15022.399104035852, 15080.396784128621, 15083.698326033487, 15086.924565377172, 14033.508827191024, 14232.715345693106, 14570.635734106652, 14598.197099159543, 14604.0, 676.3000294694991, 702.2240424331117, 832.6877420967137, 834.9206825351612, 861.0, 851.6423102297019, 872.6378552900538, 873.2141073034288, 873.4366333714751, 874.6501399440243, 925.3476299209075, 925.6112432778216, 927.7495076329387, 931.483026920059, 932.7108596335158, 920.6869664314132, 921.6912334367928, 923.1230331185367, 923.3121324613139, 928.4893308680232, 498.8029728257318, 626.924769027715, 627.3914303125961, 683.7060064172404, 684.5003147702205, 15193.0, 15350.462733804376, 15377.0, 15405.691886162325, 15413.229338533029, 12115.454804533818, 14534.0, 14548.272586370638, 14568.927155364261, 14776.556703298918, 11913.10651701126, 11954.773819155178, 11967.222130561573, 12071.396430178456, 12117.636470905887, 3630.346587454275, 3790.1093243087853, 3794.772313661189, 3827.27281816454, 3842.6925845932537, 96983.0, 97573.0, 105403.47298263536, 107105.39341909878, 108277.45861270723, 10891.222468883196, 10908.618198363178, 10919.454027298603, 10919.617813376493, 11069.335839849635, 20332.288369906983, 20535.075921583564, 20645.793542064388, 20700.792992070186, 20702.275420360216, 6453.451456626145, 7541.170471248159, 7741.651625676858, 7786.883196752054, 7907.802304942423, 4320.503142138694, 4440.311751678495, 4501.527339629357, 4529.433820772403, 4547.727136371828, 3562.0, 3663.084228942764, 3691.8892433227047, 3703.4444833274683, 3716.851325946986, 667.5894324990096, 667.6895243711697, 675.8918573028391, 681.9556728812711, 688.7348370877248, 542.1704791668753, 607.9088136779518, 656.5765081522392, 684.1789852177407, 723.7286017743346, 8794.60424280909, 9354.95322523383, 9459.19596834263, 9506.429614223169, 9546.140847323773, 633.5944995203074, 637.512303088138, 660.8612191439755, 691.7163962775238, 698.9510734248655, 4502.797374118172, 4635.513271106553, 4641.9580768175865, 4690.484046754854, 4710.317004034396, 84590.5770471156, 94826.0517394831, 138685.0, 144384.56779035222, 145300.27349863184, 121851.0, 124691.0, 127705.0, 128031.0, 129814.35092824475, 67730.98403523951, 68761.0, 98511.50744246233, 106611.0, 110107.0, 8583.141685831368, 8936.642534298815, 9035.322350823582, 9078.0, 9170.082991700776, 62251.688741556005, 62610.373896262274, 64040.67979660072, 64304.67847660732, 64740.6762966201, 79195.0, 83385.16614833774, 84371.0, 85441.0, 87256.1274387248, 11618.593349232737, 11620.128490363426, 11733.941330293294, 11873.406329683654, 12080.39598020078, 12966.675833104247, 13011.414486347947, 13043.021773366845, 13477.932610336886, 13715.519956801863, 10110.342827716175, 11934.641960741192, 12164.39178041112, 12294.446749896455, 12648.747025059263, 9715.36850104741, 11874.168808183344, 11894.583689570823, 12107.394630268274, 12303.261804291771, 11942.701432464259, 12154.331511766937, 12322.938385308016, 12345.259284442962, 12544.184627999151, 9611.519424028631, 9628.988956159503, 9724.611015559298, 10420.531076101744, 10599.89400105989, 9017.774555635899, 9074.954625226832, 10384.628761943733, 10605.840912386475, 10714.89285107139], "value": 7526.288576410256, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1560.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 117.0, 118.0, 117.0, 118.0, 117.0, 117.0, 116.0, 117.0, 116.0], "value": 115.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 144.0, 108.0, 175.0, 145.0, 146.0, 113.0, 115.0, 148.0, 117.0], "value": 137.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 231.0, 236.0, 196.0, 194.0, 196.0, 197.0, 194.0, 192.0, 197.0], "value": 203.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 59.0, 84.0, 58.0, 60.0, 58.0, 59.0, 78.0, 61.0], "value": 65.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 73.0, 85.0, 73.0, 69.0, 71.0, 89.0, 69.0, 73.0, 88.0], "value": 77.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 90.0, 88.0, 90.0, 87.0, 90.0, 89.0, 90.0, 87.0], "value": 88.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 155.0, 97.0, 128.0, 97.0, 97.0, 96.0, 97.0, 152.0, 154.0], "value": 121.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 92.0, 75.0, 141.0, 145.0, 91.0, 116.0, 134.0, 147.0, 101.0], "value": 113.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 57.0, 56.0, 59.0, 58.0, 58.0, 58.0, 58.0, 57.0], "value": 57.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 76.0, 71.0, 86.0, 76.0, 75.0, 68.0, 70.0, 70.0, 70.0], "value": 72.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 85.0, 88.0, 90.0, 89.0, 102.0, 95.0, 89.0, 93.0], "value": 94.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 152.0, 159.0, 160.0, 166.0, 132.0, 169.0, 151.0, 207.0, 159.0], "value": 160.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 178.0, 172.0, 175.0, 173.0, 175.0, 175.0, 175.0, 173.0, 173.0], "value": 174.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 70.0, 70.0, 73.0, 72.0, 72.0, 80.0, 92.0, 93.0, 96.0], "value": 79.1, "unit": "score"}]}]} 20:10:17 INFO - Return code: 0 20:10:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:10:17 ERROR - # TBPL SUCCESS # 20:10:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:10:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:10:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:10:17 INFO - 2016-10-04 20:10:17.109 screenresolution[1954:20519] starting screenresolution argv=screenresolution get 20:10:17 INFO - 2016-10-04 20:10:17.112 screenresolution[1954:20519] Display 0: 1600x1200x32@60 20:10:17 INFO - 2016-10-04 20:10:17.118 screenresolution[1955:20522] starting screenresolution argv=screenresolution list 20:10:17 INFO - Available Modes on Display 0 20:10:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:10:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:10:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:10:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:10:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:10:17 INFO - Intel Iris: 20:10:17 INFO - Chipset Model: Intel Iris 20:10:17 INFO - Type: GPU 20:10:17 INFO - Bus: Built-In 20:10:17 INFO - VRAM (Dynamic, Max): 1536 MB 20:10:17 INFO - Vendor: Intel (0x8086) 20:10:17 INFO - Device ID: 0x0a2e 20:10:17 INFO - Revision ID: 0x0009 20:10:17 INFO - Displays: 20:10:17 INFO - Display: 20:10:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:10:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:10:17 INFO - Display Serial Number: 1600x1200 60 20:10:17 INFO - Main Display: Yes 20:10:17 INFO - Mirror: Off 20:10:17 INFO - Online: Yes 20:10:17 INFO - Rotation: Supported 20:10:17 INFO - Return code: 0 20:10:17 INFO - Running post-action listener: _resource_record_post_action 20:10:17 INFO - [mozharness: 2016-10-05 03:10:17.360360Z] Finished run-tests step (success) 20:10:17 INFO - Running post-run listener: _resource_record_post_run 20:10:17 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 1299968; Write bytes: 712988160; Read time: 14; Write time: 1991 20:10:17 INFO - TinderboxPrint: CPU usage
54.6% 20:10:17 INFO - TinderboxPrint: I/O read bytes / time
1,299,968 / 14 20:10:17 INFO - TinderboxPrint: I/O write bytes / time
712,988,160 / 1,991 20:10:17 INFO - TinderboxPrint: CPU idle
2,198.3 (45.4%) 20:10:17 INFO - TinderboxPrint: CPU system
980.9 (20.2%) 20:10:17 INFO - TinderboxPrint: CPU user
1,666.5 (34.4%) 20:10:17 INFO - TinderboxPrint: Swap in / out
408,530,944 / 0 20:10:17 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 391195136; Write bytes: 384919040; Read time: 29196; Write time: 680 20:10:17 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 1299968; Write bytes: 300902400; Read time: 14; Write time: 1248 20:10:17 INFO - Running post-run listener: _upload_blobber_files 20:10:17 INFO - Blob upload gear active. 20:10:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:10:17 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 20:10:17 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'] 20:10:17 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 20:10:18 INFO - (blobuploader) - INFO - Open directory for files ... 20:10:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:10:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:10:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:10:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:10:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:10:19 INFO - (blobuploader) - INFO - Done attempting. 20:10:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:10:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:10:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:10:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:10:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:10:20 INFO - (blobuploader) - INFO - Done attempting. 20:10:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:10:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:10:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:10:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:10:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:10:23 INFO - (blobuploader) - INFO - Done attempting. 20:10:23 INFO - (blobuploader) - INFO - Iteration through files over. 20:10:23 INFO - Return code: 0 20:10:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:10:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:10:23 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9298e774d8b56865dafc2fc5b8efb379ea9d13a42684eb3a8aca00b95beaa2244530beb072fff6446f057cce071d32ce1644888ed829086856bac790dc0629f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c996940d958305daa61a5481b151881b2b393e875ee52c72a332b01e79f58fc5d8524d57a85cd4c83219bd0d1da9e8b5d8b804d645b746eb3329a99f71bfcdd3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1dda46ed188d2b43f752148657781bb6fcfc97eac5ecb067b8c26f35514795cc0fea5b0b879a9c5aa4de611fab194ae00d15451946099899c6c9dc20be8426d9"} 20:10:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:10:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:10:23 INFO - Contents: 20:10:23 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9298e774d8b56865dafc2fc5b8efb379ea9d13a42684eb3a8aca00b95beaa2244530beb072fff6446f057cce071d32ce1644888ed829086856bac790dc0629f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c996940d958305daa61a5481b151881b2b393e875ee52c72a332b01e79f58fc5d8524d57a85cd4c83219bd0d1da9e8b5d8b804d645b746eb3329a99f71bfcdd3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1dda46ed188d2b43f752148657781bb6fcfc97eac5ecb067b8c26f35514795cc0fea5b0b879a9c5aa4de611fab194ae00d15451946099899c6c9dc20be8426d9"} 20:10:23 INFO - Running post-run listener: copy_logs_to_upload_dir 20:10:23 INFO - Copying logs to upload dir... 20:10:23 INFO - mkdir: /builds/slave/test/build/upload/logs 20:10:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.755009 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-04 20:10:23.098565) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-04 20:10:23.102315) ========= 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.ToFNlNtEtz/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.4KaQJvIbIY/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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/9298e774d8b56865dafc2fc5b8efb379ea9d13a42684eb3a8aca00b95beaa2244530beb072fff6446f057cce071d32ce1644888ed829086856bac790dc0629f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c996940d958305daa61a5481b151881b2b393e875ee52c72a332b01e79f58fc5d8524d57a85cd4c83219bd0d1da9e8b5d8b804d645b746eb3329a99f71bfcdd3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1dda46ed188d2b43f752148657781bb6fcfc97eac5ecb067b8c26f35514795cc0fea5b0b879a9c5aa4de611fab194ae00d15451946099899c6c9dc20be8426d9"} build_url:https://queue.taskcluster.net/v1/task/CmZVkc3BTTmPm8wvR2VN_g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011169 build_url: 'https://queue.taskcluster.net/v1/task/CmZVkc3BTTmPm8wvR2VN_g/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/9298e774d8b56865dafc2fc5b8efb379ea9d13a42684eb3a8aca00b95beaa2244530beb072fff6446f057cce071d32ce1644888ed829086856bac790dc0629f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c996940d958305daa61a5481b151881b2b393e875ee52c72a332b01e79f58fc5d8524d57a85cd4c83219bd0d1da9e8b5d8b804d645b746eb3329a99f71bfcdd3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1dda46ed188d2b43f752148657781bb6fcfc97eac5ecb067b8c26f35514795cc0fea5b0b879a9c5aa4de611fab194ae00d15451946099899c6c9dc20be8426d9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-04 20:10:23.134802) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 20:10:23.135132) ========= 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.ToFNlNtEtz/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.4KaQJvIbIY/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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.005113 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 20:10:23.183859) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-04 20:10:23.184171) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-04 20:10:23.184505) ========= ========= Total master_lag: 0.17 =========