builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0335 starttime: 1477841030.79 results: success (0) buildid: 20161030060922 builduid: ea1784631f55438c9296ea283f27e9ee revision: f4f6435ed714df693381b80184f26f297ec1a461 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-30 08:23:50.790199) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-30 08:23:50.790598) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-30 08:23:50.790864) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-30 08:23:50.919885) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-30 08:23:50.920270) ========= 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.EqoJychxy5/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.01Q6HRRXpg/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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-30 08:23:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.13M=0.001s 2016-10-30 08:23:51 (8.13 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.566474 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-30 08:23:51.509618) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-30 08:23:51.509932) ========= 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.EqoJychxy5/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.01Q6HRRXpg/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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.025086 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-30 08:23:51.558220) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-30 08:23:51.558582) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f4f6435ed714df693381b80184f26f297ec1a461 --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 f4f6435ed714df693381b80184f26f297ec1a461 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EqoJychxy5/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.01Q6HRRXpg/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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-30 08:23:51,642 truncating revision to first 12 chars 2016-10-30 08:23:51,642 Setting DEBUG logging. 2016-10-30 08:23:51,642 attempt 1/10 2016-10-30 08:23:51,642 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f4f6435ed714?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-30 08:23:52,277 unpacking tar archive at: mozilla-central-f4f6435ed714/testing/mozharness/ program finished with exit code 0 elapsedTime=0.897546 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-30 08:23:52.478530) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-30 08:23:52.478855) ========= script_repo_revision: f4f6435ed714df693381b80184f26f297ec1a461 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-30 08:23:52.479248) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-30 08:23:52.479531) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-30 08:23:52.498590) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-10-30 08:23:52.499022) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--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.EqoJychxy5/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.01Q6HRRXpg/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 08:23:52 INFO - MultiFileLogger online at 20161030 08:23:52 in /builds/slave/test 08:23:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:23:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:23:52 INFO - {'append_to_log': False, 08:23:52 INFO - 'base_work_dir': '/builds/slave/test', 08:23:52 INFO - 'blob_upload_branch': 'Firefox', 08:23:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:23:52 INFO - 'branch': 'Firefox', 08:23:52 INFO - 'buildbot_json_path': 'buildprops.json', 08:23:52 INFO - 'config_files': ('talos/mac_config.py',), 08:23:52 INFO - 'default_actions': ('clobber', 08:23:52 INFO - 'read-buildbot-config', 08:23:52 INFO - 'download-and-extract', 08:23:52 INFO - 'populate-webroot', 08:23:52 INFO - 'create-virtualenv', 08:23:52 INFO - 'install', 08:23:52 INFO - 'run-tests'), 08:23:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:23:52 INFO - 'download_minidump_stackwalk': True, 08:23:52 INFO - 'download_symbols': 'ondemand', 08:23:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:23:52 INFO - 'tooltool.py': '/tools/tooltool.py', 08:23:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:23:52 INFO - '/tools/misc-python/virtualenv.py')}, 08:23:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:23:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:23:52 INFO - 'installer_path': 'installer.exe', 08:23:52 INFO - 'log_level': 'info', 08:23:52 INFO - 'log_name': 'talos', 08:23:52 INFO - 'log_to_console': True, 08:23:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:23:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:23:52 INFO - 'opt_config_files': (), 08:23:52 INFO - 'pip_index': False, 08:23:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:23:52 INFO - 'cmd': ('bash', 08:23:52 INFO - '-c', 08:23:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:23:52 INFO - 'enabled': True, 08:23:52 INFO - 'halt_on_failure': False, 08:23:52 INFO - 'name': 'check_screen_resolution'},), 08:23:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:23:52 INFO - 'cmd': ('bash', 08:23:52 INFO - '-c', 08:23:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:23:52 INFO - 'enabled': True, 08:23:52 INFO - 'halt_on_failure': False, 08:23:52 INFO - 'name': 'check_screen_resolution'},), 08:23:52 INFO - 'run_cmd_checks_enabled': True, 08:23:52 INFO - 'sps_profile': False, 08:23:52 INFO - 'sps_profile_interval': 0, 08:23:52 INFO - 'suite': 'dromaeojs', 08:23:52 INFO - 'system_bits': '32', 08:23:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:23:52 INFO - 'title': 't-yosemite-r7-0335', 08:23:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:23:52 INFO - 'use_talos_json': True, 08:23:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:23:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:23:52 INFO - 'work_dir': 'build'} 08:23:52 INFO - [mozharness: 2016-10-30 15:23:52.708565Z] Running clobber step. 08:23:52 INFO - Running pre-action listener: _resource_record_pre_action 08:23:52 INFO - Running main action method: clobber 08:23:52 INFO - rmtree: /builds/slave/test/build 08:23:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:23:54 INFO - Running post-action listener: _resource_record_post_action 08:23:54 INFO - [mozharness: 2016-10-30 15:23:54.601680Z] Finished clobber step (success) 08:23:54 INFO - [mozharness: 2016-10-30 15:23:54.602011Z] Running read-buildbot-config step. 08:23:54 INFO - Running pre-action listener: _resource_record_pre_action 08:23:54 INFO - Running main action method: read_buildbot_config 08:23:54 INFO - Using buildbot properties: 08:23:54 INFO - { 08:23:54 INFO - "project": "", 08:23:54 INFO - "product": "firefox", 08:23:54 INFO - "script_repo_revision": "production", 08:23:54 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 08:23:54 INFO - "repository": "", 08:23:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 08:23:54 INFO - "buildid": "20161030060922", 08:23:54 INFO - "pgo_build": "False", 08:23:54 INFO - "basedir": "/builds/slave/test", 08:23:54 INFO - "buildnumber": 129, 08:23:54 INFO - "slavename": "t-yosemite-r7-0335", 08:23:54 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 08:23:54 INFO - "platform": "yosemite_r7", 08:23:54 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:23:54 INFO - "branch": "mozilla-central", 08:23:54 INFO - "repo_path": "mozilla-central", 08:23:54 INFO - "revision": "f4f6435ed714df693381b80184f26f297ec1a461", 08:23:54 INFO - "stage_platform": "macosx64", 08:23:54 INFO - "builduid": "ea1784631f55438c9296ea283f27e9ee", 08:23:54 INFO - "slavebuilddir": "test" 08:23:54 INFO - } 08:23:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/C3oggZwnSBy2uISkkxPHiw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 08:23:54 INFO - Running post-action listener: _resource_record_post_action 08:23:54 INFO - [mozharness: 2016-10-30 15:23:54.606050Z] Finished read-buildbot-config step (success) 08:23:54 INFO - [mozharness: 2016-10-30 15:23:54.606183Z] Running download-and-extract step. 08:23:54 INFO - Running pre-action listener: _resource_record_pre_action 08:23:54 INFO - Running main action method: download_and_extract 08:23:54 INFO - mkdir: /builds/slave/test/build/tests 08:23:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:54 INFO - trying https://queue.taskcluster.net/v1/task/C3oggZwnSBy2uISkkxPHiw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 08:23:54 INFO - Downloading https://queue.taskcluster.net/v1/task/C3oggZwnSBy2uISkkxPHiw/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 08:23:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C3oggZwnSBy2uISkkxPHiw/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 08:23:55 INFO - Downloaded 1199 bytes. 08:23:55 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:23:55 INFO - Using the following test package requirements: 08:23:55 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:23:55 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:23:55 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 08:23:55 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:23:55 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 08:23:55 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:23:55 INFO - u'jsshell-mac.zip'], 08:23:55 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:23:55 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 08:23:55 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:23:55 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:23:55 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 08:23:55 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:23:55 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 08:23:55 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:23:55 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 08:23:55 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:23:55 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 08:23:55 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:23:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C3oggZwnSBy2uISkkxPHiw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:23:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C3oggZwnSBy2uISkkxPHiw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:23:55 INFO - Fetch https://queue.taskcluster.net/v1/task/C3oggZwnSBy2uISkkxPHiw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:23:56 INFO - Http code: 200 08:23:56 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 08:23:56 INFO - x-amz-cf-id: Wf861NyrYx4SeuGxVqEM_yF75FNbZ4XVmYxqhD6sz3AqBYvOo62mGQ== 08:23:56 INFO - x-amz-version-id: hFYPFgybEV5hBKUQEs_aBIQsuNsOFsW3 08:23:56 INFO - Expected file size: 16391695 08:23:56 INFO - Obtained file size: 16391695 08:24:03 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 08:24:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C3oggZwnSBy2uISkkxPHiw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:24:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C3oggZwnSBy2uISkkxPHiw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:24:03 INFO - Fetch https://queue.taskcluster.net/v1/task/C3oggZwnSBy2uISkkxPHiw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:24:03 INFO - Http code: 200 08:24:03 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 08:24:03 INFO - x-amz-cf-id: -xhku-8LQSrEXyrL4KCpw-BR_IoPltMHH8s8ig6GybGvEku6F6TRBw== 08:24:03 INFO - x-amz-version-id: hFYPFgybEV5hBKUQEs_aBIQsuNsOFsW3 08:24:04 INFO - Expected file size: 16391695 08:24:04 INFO - Obtained file size: 16391695 08:24:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C3oggZwnSBy2uISkkxPHiw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 08:24:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C3oggZwnSBy2uISkkxPHiw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:24:10 INFO - Fetch https://queue.taskcluster.net/v1/task/C3oggZwnSBy2uISkkxPHiw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 08:24:10 INFO - Http code: 200 08:24:10 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 08:24:10 INFO - x-amz-cf-id: j8fljVf3IP2Ks9mlTLm5IgXlAWZqVg5QZN8-9Y4xduHKOLBJaTGh7g== 08:24:10 INFO - x-amz-version-id: W3stwn1WMHPUzxqByNTzIn3SYgb6FHe5 08:24:11 INFO - Expected file size: 14409038 08:24:11 INFO - Obtained file size: 14409038 08:24:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:12 INFO - trying https://queue.taskcluster.net/v1/task/C3oggZwnSBy2uISkkxPHiw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:24:12 INFO - Downloading https://queue.taskcluster.net/v1/task/C3oggZwnSBy2uISkkxPHiw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 08:24:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C3oggZwnSBy2uISkkxPHiw/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 08:24:15 INFO - Downloaded 112037631 bytes. 08:24:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/C3oggZwnSBy2uISkkxPHiw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:24:15 INFO - mkdir: /builds/slave/test/properties 08:24:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:24:15 INFO - Writing to file /builds/slave/test/properties/build_url 08:24:15 INFO - Contents: 08:24:15 INFO - build_url:https://queue.taskcluster.net/v1/task/C3oggZwnSBy2uISkkxPHiw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:24:15 INFO - Running post-action listener: _resource_record_post_action 08:24:15 INFO - Running post-action listener: set_extra_try_arguments 08:24:15 INFO - [mozharness: 2016-10-30 15:24:15.815079Z] Finished download-and-extract step (success) 08:24:15 INFO - [mozharness: 2016-10-30 15:24:15.815250Z] Running populate-webroot step. 08:24:15 INFO - Running pre-action listener: _resource_record_pre_action 08:24:15 INFO - Running main action method: populate_webroot 08:24:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:24:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:24:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:24:15 INFO - '--tppagecycles', 08:24:15 INFO - '7'], 08:24:15 INFO - 'tests': ['tsvgm']}}, 08:24:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:24:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:24:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:24:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:24:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:24:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:24:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:24:15 INFO - 'talos_options': ['--disable-e10s'], 08:24:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:24:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:24:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:24:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:24:15 INFO - 'talos_options': ['--disable-e10s'], 08:24:15 INFO - 'tests': ['damp', 'tps']}, 08:24:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:24:15 INFO - 'tests': ['damp', 'tps']}, 08:24:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:24:15 INFO - 'tests': ['dromaeo_dom']}, 08:24:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:24:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:24:15 INFO - 'tests': ['basic_compositor_video']}, 08:24:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:24:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:24:15 INFO - 'tests': ['a11yr', 08:24:15 INFO - 'ts_paint', 08:24:15 INFO - 'tpaint', 08:24:15 INFO - 'sessionrestore', 08:24:15 INFO - 'sessionrestore_no_auto_restore', 08:24:15 INFO - 'tabpaint']}, 08:24:15 INFO - 'other-e10s': {'tests': ['a11yr', 08:24:15 INFO - 'ts_paint', 08:24:15 INFO - 'tpaint', 08:24:15 INFO - 'sessionrestore', 08:24:15 INFO - 'sessionrestore_no_auto_restore', 08:24:15 INFO - 'tabpaint']}, 08:24:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:24:15 INFO - 'ts_paint', 08:24:15 INFO - 'tpaint', 08:24:15 INFO - 'sessionrestore', 08:24:15 INFO - 'sessionrestore_no_auto_restore', 08:24:15 INFO - 'tabpaint']}, 08:24:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:24:15 INFO - 'ts_paint', 08:24:15 INFO - 'tpaint', 08:24:15 INFO - 'sessionrestore', 08:24:15 INFO - 'sessionrestore_no_auto_restore', 08:24:15 INFO - 'tabpaint']}, 08:24:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:24:15 INFO - 'tests': ['a11yr', 08:24:15 INFO - 'ts_paint', 08:24:15 INFO - 'tpaint', 08:24:15 INFO - 'sessionrestore', 08:24:15 INFO - 'sessionrestore_no_auto_restore', 08:24:15 INFO - 'tabpaint']}, 08:24:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:24:15 INFO - 'tests': ['a11yr', 08:24:15 INFO - 'ts_paint', 08:24:15 INFO - 'tpaint', 08:24:15 INFO - 'sessionrestore', 08:24:15 INFO - 'sessionrestore_no_auto_restore', 08:24:15 INFO - 'tabpaint']}, 08:24:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:24:15 INFO - 'tests': ['tsvgx', 08:24:15 INFO - 'tsvgr_opacity', 08:24:15 INFO - 'tart', 08:24:15 INFO - 'tscrollx', 08:24:15 INFO - 'cart']}, 08:24:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:24:15 INFO - 'tsvgr_opacity', 08:24:15 INFO - 'tart', 08:24:15 INFO - 'tscrollx', 08:24:15 INFO - 'cart']}, 08:24:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:24:15 INFO - 'talos_options': ['--disable-e10s'], 08:24:15 INFO - 'tests': ['tp5o']}, 08:24:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:24:15 INFO - 'tests': ['tp5o']}, 08:24:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:24:15 INFO - 'talos_options': ['--disable-e10s', 08:24:15 INFO - '--xperf_path', 08:24:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:24:15 INFO - 'tests': ['tp5n']}, 08:24:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:24:15 INFO - 'talos_options': ['--xperf_path', 08:24:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:24:15 INFO - 'tests': ['tp5n']}}, 08:24:15 INFO - 'talos.zip': {'path': '', 08:24:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:24:15 INFO - Running post-action listener: _resource_record_post_action 08:24:15 INFO - [mozharness: 2016-10-30 15:24:15.834707Z] Finished populate-webroot step (success) 08:24:15 INFO - [mozharness: 2016-10-30 15:24:15.834843Z] Running create-virtualenv step. 08:24:15 INFO - Running pre-action listener: _resource_record_pre_action 08:24:15 INFO - Running main action method: create_virtualenv 08:24:15 INFO - Creating virtualenv /builds/slave/test/build/venv 08:24:15 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 08:24:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:24:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:24:15 INFO - Using real prefix '/tools/python27' 08:24:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:24:16 INFO - Installing distribute.............................................................................................................................................................................................done. 08:24:19 INFO - Installing pip.................done. 08:24:19 INFO - Return code: 0 08:24:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:24:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:24:20 INFO - Reading from file tmpfile_stdout 08:24:20 INFO - Output received: 08:24:20 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) 08:24:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:24:20 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')]} 08:24:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:20 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')]} 08:24:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:20 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 0x10a30d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a283570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f913adbd2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a121d30>, '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 0x109f47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a119b20>, '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.EqoJychxy5/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.01Q6HRRXpg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 08:24:20 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 08:24:20 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 08:24:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EqoJychxy5/Render', 08:24:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:20 INFO - 'HOME': '/Users/cltbld', 08:24:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:20 INFO - 'LOGNAME': 'cltbld', 08:24:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:20 INFO - 'MOZ_NO_REMOTE': '1', 08:24:20 INFO - 'NO_EM_RESTART': '1', 08:24:20 INFO - 'PAGER': '/bin/cat', 08:24:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:20 INFO - 'PWD': '/builds/slave/test', 08:24:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:20 INFO - 'SHELL': '/bin/bash', 08:24:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.01Q6HRRXpg/Listeners', 08:24:20 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 08:24:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:20 INFO - 'USER': 'cltbld', 08:24:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:20 INFO - 'XPC_FLAGS': '0x0', 08:24:20 INFO - 'XPC_SERVICE_NAME': '0', 08:24:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:20 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 08:24:20 INFO - Cleaning up... 08:24:20 INFO - Return code: 0 08:24:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:24:20 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')]} 08:24:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:20 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')]} 08:24:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:20 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 0x10a30d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a283570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f913adbd2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a121d30>, '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 0x109f47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a119b20>, '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.EqoJychxy5/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.01Q6HRRXpg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 08:24:20 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 08:24:20 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 08:24:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EqoJychxy5/Render', 08:24:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:20 INFO - 'HOME': '/Users/cltbld', 08:24:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:20 INFO - 'LOGNAME': 'cltbld', 08:24:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:20 INFO - 'MOZ_NO_REMOTE': '1', 08:24:20 INFO - 'NO_EM_RESTART': '1', 08:24:20 INFO - 'PAGER': '/bin/cat', 08:24:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:20 INFO - 'PWD': '/builds/slave/test', 08:24:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:20 INFO - 'SHELL': '/bin/bash', 08:24:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.01Q6HRRXpg/Listeners', 08:24:20 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 08:24:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:20 INFO - 'USER': 'cltbld', 08:24:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:20 INFO - 'XPC_FLAGS': '0x0', 08:24:20 INFO - 'XPC_SERVICE_NAME': '0', 08:24:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:20 INFO - Downloading/unpacking psutil>=3.1.1 08:24:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:24:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:24:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:24:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:24:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:24:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:24:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:24:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:24:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:24:24 INFO - Installing collected packages: psutil 08:24:24 INFO - Running setup.py install for psutil 08:24:24 INFO - building 'psutil._psutil_osx' extension 08:24:24 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 08:24:24 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 08:24:24 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 08:24:25 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 08:24:25 INFO - building 'psutil._psutil_posix' extension 08:24:25 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 08:24:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:24:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:24:25 INFO - ^ 08:24:25 INFO - 1 warning generated. 08:24:25 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 08:24:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:24:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:24:25 INFO - Successfully installed psutil 08:24:25 INFO - Cleaning up... 08:24:25 INFO - Return code: 0 08:24:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:24:25 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')]} 08:24:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:25 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')]} 08:24:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:25 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 0x10a30d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a283570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f913adbd2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a121d30>, '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 0x109f47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a119b20>, '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.EqoJychxy5/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.01Q6HRRXpg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 08:24:25 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 08:24:25 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 08:24:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EqoJychxy5/Render', 08:24:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:25 INFO - 'HOME': '/Users/cltbld', 08:24:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:25 INFO - 'LOGNAME': 'cltbld', 08:24:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:25 INFO - 'MOZ_NO_REMOTE': '1', 08:24:25 INFO - 'NO_EM_RESTART': '1', 08:24:25 INFO - 'PAGER': '/bin/cat', 08:24:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:25 INFO - 'PWD': '/builds/slave/test', 08:24:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:25 INFO - 'SHELL': '/bin/bash', 08:24:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.01Q6HRRXpg/Listeners', 08:24:25 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 08:24:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:25 INFO - 'USER': 'cltbld', 08:24:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:25 INFO - 'XPC_FLAGS': '0x0', 08:24:25 INFO - 'XPC_SERVICE_NAME': '0', 08:24:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:24:25 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 08:24:25 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 08:24:25 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 08:24:25 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 08:24:25 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 08:24:25 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 08:24:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:24:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:24:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:24:28 INFO - Installing collected packages: mozsystemmonitor 08:24:28 INFO - Running setup.py install for mozsystemmonitor 08:24:28 INFO - Successfully installed mozsystemmonitor 08:24:28 INFO - Cleaning up... 08:24:28 INFO - Return code: 0 08:24:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:24: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')]} 08:24:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24: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')]} 08:24:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:28 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 0x10a30d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a283570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f913adbd2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a121d30>, '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 0x109f47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a119b20>, '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.EqoJychxy5/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.01Q6HRRXpg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 08:24:28 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 08:24:28 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 08:24:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EqoJychxy5/Render', 08:24:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:28 INFO - 'HOME': '/Users/cltbld', 08:24:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:28 INFO - 'LOGNAME': 'cltbld', 08:24:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:28 INFO - 'MOZ_NO_REMOTE': '1', 08:24:28 INFO - 'NO_EM_RESTART': '1', 08:24:28 INFO - 'PAGER': '/bin/cat', 08:24:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:28 INFO - 'PWD': '/builds/slave/test', 08:24:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:28 INFO - 'SHELL': '/bin/bash', 08:24:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.01Q6HRRXpg/Listeners', 08:24:28 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 08:24:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:28 INFO - 'USER': 'cltbld', 08:24:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:28 INFO - 'XPC_FLAGS': '0x0', 08:24:28 INFO - 'XPC_SERVICE_NAME': '0', 08:24:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:28 INFO - Downloading/unpacking jsonschema==2.5.1 08:24:28 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 08:24:28 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 08:24:28 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 08:24:28 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 08:24:28 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 08:24:28 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 08:24:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:24:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:24:31 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 08:24:31 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 08:24:31 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 08:24:31 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 08:24:31 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 08:24:31 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 08:24:31 INFO - Downloading functools32-3.2.3-2.tar.gz 08:24:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:24:31 INFO - warning: no files found matching '*.txt' 08:24:31 INFO - no previously-included directories found matching 'build' 08:24:31 INFO - no previously-included directories found matching 'dist' 08:24:31 INFO - no previously-included directories found matching '.git*' 08:24:31 INFO - Installing collected packages: jsonschema, functools32 08:24:31 INFO - Running setup.py install for functools32 08:24:31 INFO - warning: no files found matching '*.txt' 08:24:31 INFO - no previously-included directories found matching 'build' 08:24:31 INFO - no previously-included directories found matching 'dist' 08:24:31 INFO - no previously-included directories found matching '.git*' 08:24:31 INFO - Successfully installed jsonschema functools32 08:24:31 INFO - Cleaning up... 08:24:31 INFO - Return code: 0 08:24:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:24:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:31 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 0x10a30d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a283570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f913adbd2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a121d30>, '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 0x109f47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a119b20>, '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.EqoJychxy5/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.01Q6HRRXpg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 08:24:31 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 08:24:31 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 08:24:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EqoJychxy5/Render', 08:24:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:31 INFO - 'HOME': '/Users/cltbld', 08:24:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:31 INFO - 'LOGNAME': 'cltbld', 08:24:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:31 INFO - 'MOZ_NO_REMOTE': '1', 08:24:31 INFO - 'NO_EM_RESTART': '1', 08:24:31 INFO - 'PAGER': '/bin/cat', 08:24:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:31 INFO - 'PWD': '/builds/slave/test', 08:24:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:31 INFO - 'SHELL': '/bin/bash', 08:24:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.01Q6HRRXpg/Listeners', 08:24:31 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 08:24:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:31 INFO - 'USER': 'cltbld', 08:24:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:31 INFO - 'XPC_FLAGS': '0x0', 08:24:31 INFO - 'XPC_SERVICE_NAME': '0', 08:24:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:24:32 INFO - Cleaning up... 08:24:32 INFO - Return code: 0 08:24:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:24:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:32 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 0x10a30d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a283570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f913adbd2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a121d30>, '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 0x109f47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a119b20>, '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.EqoJychxy5/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.01Q6HRRXpg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 08:24:32 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 08:24:32 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 08:24:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EqoJychxy5/Render', 08:24:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:32 INFO - 'HOME': '/Users/cltbld', 08:24:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:32 INFO - 'LOGNAME': 'cltbld', 08:24:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:32 INFO - 'MOZ_NO_REMOTE': '1', 08:24:32 INFO - 'NO_EM_RESTART': '1', 08:24:32 INFO - 'PAGER': '/bin/cat', 08:24:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:32 INFO - 'PWD': '/builds/slave/test', 08:24:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:32 INFO - 'SHELL': '/bin/bash', 08:24:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.01Q6HRRXpg/Listeners', 08:24:32 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 08:24:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:32 INFO - 'USER': 'cltbld', 08:24:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:32 INFO - 'XPC_FLAGS': '0x0', 08:24:32 INFO - 'XPC_SERVICE_NAME': '0', 08:24:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:32 INFO - Downloading/unpacking blobuploader==1.2.4 08:24:32 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 08:24:32 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 08:24:32 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 08:24:32 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 08:24:32 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 08:24:32 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 08:24:34 INFO - Downloading blobuploader-1.2.4.tar.gz 08:24:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:24:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:24:35 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 08:24:35 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 08:24:35 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 08:24:35 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 08:24:35 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 08:24:35 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 08:24:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:24:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:24:35 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 08:24:35 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 08:24:35 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 08:24:35 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 08:24:35 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 08:24:35 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 08:24:36 INFO - Downloading docopt-0.6.1.tar.gz 08:24:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:24:36 INFO - Installing collected packages: blobuploader, requests, docopt 08:24:36 INFO - Running setup.py install for blobuploader 08:24:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:24:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:24:36 INFO - Running setup.py install for requests 08:24:36 INFO - Running setup.py install for docopt 08:24:37 INFO - Successfully installed blobuploader requests docopt 08:24:37 INFO - Cleaning up... 08:24:37 INFO - Return code: 0 08:24:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:24:37 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')]} 08:24:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:37 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')]} 08:24:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:37 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 0x10a30d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a283570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f913adbd2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a121d30>, '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 0x109f47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a119b20>, '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.EqoJychxy5/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.01Q6HRRXpg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 08:24:37 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 08:24:37 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 08:24:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EqoJychxy5/Render', 08:24:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:37 INFO - 'HOME': '/Users/cltbld', 08:24:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:37 INFO - 'LOGNAME': 'cltbld', 08:24:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:37 INFO - 'MOZ_NO_REMOTE': '1', 08:24:37 INFO - 'NO_EM_RESTART': '1', 08:24:37 INFO - 'PAGER': '/bin/cat', 08:24:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:37 INFO - 'PWD': '/builds/slave/test', 08:24:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:37 INFO - 'SHELL': '/bin/bash', 08:24:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.01Q6HRRXpg/Listeners', 08:24:37 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 08:24:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:37 INFO - 'USER': 'cltbld', 08:24:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:37 INFO - 'XPC_FLAGS': '0x0', 08:24:37 INFO - 'XPC_SERVICE_NAME': '0', 08:24:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:24:37 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-9k2q_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:24:37 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-35ekCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:24:37 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-Nyr5pF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:24:37 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-6Cldqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:24:37 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-lKxJLx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:24:37 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-CRRYlf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:24:38 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-JlZlat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:24:38 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-SFHlTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:24:38 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-823dtW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:24:38 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-YD8Thf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:24:38 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-kVkYAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:24:38 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-t2hb8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:24:38 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-PTsHEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:24:38 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-xwutLm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:24:38 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-3yJcjg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:24:39 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-eb98y2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:24:39 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-MFZOhv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:24:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:24:39 INFO - Running setup.py install for manifestparser 08:24:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:24:39 INFO - Running setup.py install for mozcrash 08:24:39 INFO - Running setup.py install for mozdebug 08:24:39 INFO - Running setup.py install for mozdevice 08:24:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:24:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:24:39 INFO - Running setup.py install for mozfile 08:24:39 INFO - Running setup.py install for mozhttpd 08:24:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:24:40 INFO - Running setup.py install for mozinfo 08:24:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:24:40 INFO - Running setup.py install for mozInstall 08:24:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:24:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:24:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:24:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:24:40 INFO - Running setup.py install for mozleak 08:24:40 INFO - Running setup.py install for mozlog 08:24:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:24:40 INFO - Running setup.py install for moznetwork 08:24:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:24:40 INFO - Running setup.py install for mozprocess 08:24:41 INFO - Running setup.py install for mozprofile 08:24:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:24:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:24:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:24:41 INFO - Running setup.py install for mozrunner 08:24:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:24:41 INFO - Running setup.py install for mozscreenshot 08:24:41 INFO - Running setup.py install for moztest 08:24:41 INFO - Running setup.py install for mozversion 08:24:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:24:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:24:41 INFO - Cleaning up... 08:24:41 INFO - Return code: 0 08:24:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:24:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:41 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 0x10a30d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a283570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f913adbd2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a121d30>, '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 0x109f47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a119b20>, '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.EqoJychxy5/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.01Q6HRRXpg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 08:24:41 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 08:24:41 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 08:24:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EqoJychxy5/Render', 08:24:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:41 INFO - 'HOME': '/Users/cltbld', 08:24:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:41 INFO - 'LOGNAME': 'cltbld', 08:24:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:41 INFO - 'MOZ_NO_REMOTE': '1', 08:24:41 INFO - 'NO_EM_RESTART': '1', 08:24:41 INFO - 'PAGER': '/bin/cat', 08:24:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:41 INFO - 'PWD': '/builds/slave/test', 08:24:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:41 INFO - 'SHELL': '/bin/bash', 08:24:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.01Q6HRRXpg/Listeners', 08:24:41 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 08:24:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:41 INFO - 'USER': 'cltbld', 08:24:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:41 INFO - 'XPC_FLAGS': '0x0', 08:24:41 INFO - 'XPC_SERVICE_NAME': '0', 08:24:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:24:42 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-i3LcOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:24:42 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)) 08:24:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:24:42 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-zp1DrM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:24:42 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)) 08:24:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:24:42 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-kCFlhz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:24:42 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)) 08:24:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:24:42 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-CMwift-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:24:42 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)) 08:24:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:24:42 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-ZlxKaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:24:42 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)) 08:24:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:24:42 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-iTkrPB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:24:42 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)) 08:24:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:24:42 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-lIK9xj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:24:42 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)) 08:24:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:24:42 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-J8qOd1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:24:42 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)) 08:24:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:24:42 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-azV2k4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:24:43 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)) 08:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:24:43 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-Wyg7bG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:24:43 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)) 08:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:24:43 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-GhhEF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:24:43 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)) 08:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:24:43 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-QvJUDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:24:43 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)) 08:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:24:43 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-VVrFHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:24:43 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)) 08:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:24:43 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-_8ZhFj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:24:43 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)) 08:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:24:43 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-Hf48UA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:24:43 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)) 08:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:24:43 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-odJRhf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:24:43 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)) 08:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:24:43 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-HtOLRt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:24:43 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)) 08:24:43 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)) 08:24:43 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)) 08:24:43 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)) 08:24:43 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)) 08:24:43 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)) 08:24:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:24: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 08:24: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 08:24: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 08:24: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 08:24: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 08:24: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 08:24:46 INFO - Downloading blessings-1.6.tar.gz 08:24:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:24:46 INFO - Installing collected packages: blessings 08:24:46 INFO - Running setup.py install for blessings 08:24:46 INFO - Successfully installed blessings 08:24:46 INFO - Cleaning up... 08:24:46 INFO - Return code: 0 08:24:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:24:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:24:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:24:47 INFO - Reading from file tmpfile_stdout 08:24:47 INFO - Current package versions: 08:24:47 INFO - blessings == 1.6 08:24:47 INFO - blobuploader == 1.2.4 08:24:47 INFO - docopt == 0.6.1 08:24:47 INFO - functools32 == 3.2.3-2 08:24:47 INFO - jsonschema == 2.5.1 08:24:47 INFO - manifestparser == 1.1 08:24:47 INFO - mozInstall == 1.12 08:24:47 INFO - mozcrash == 0.17 08:24:47 INFO - mozdebug == 0.1 08:24:47 INFO - mozdevice == 0.48 08:24:47 INFO - mozfile == 1.2 08:24:47 INFO - mozhttpd == 0.7 08:24:47 INFO - mozinfo == 0.9 08:24:47 INFO - mozleak == 0.1 08:24:47 INFO - mozlog == 3.4 08:24:47 INFO - moznetwork == 0.27 08:24:47 INFO - mozprocess == 0.23 08:24:47 INFO - mozprofile == 0.28 08:24:47 INFO - mozrunner == 6.12 08:24:47 INFO - mozscreenshot == 0.1 08:24:47 INFO - mozsystemmonitor == 0.3 08:24:47 INFO - moztest == 0.7 08:24:47 INFO - mozversion == 1.4 08:24:47 INFO - psutil == 3.1.1 08:24:47 INFO - requests == 1.2.3 08:24:47 INFO - wsgiref == 0.1.2 08:24:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:24:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:47 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 0x10a30d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a283570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f913adbd2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a121d30>, '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 0x109f47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a119b20>, '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.EqoJychxy5/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.01Q6HRRXpg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 08:24:47 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 08:24:47 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 08:24:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EqoJychxy5/Render', 08:24:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:47 INFO - 'HOME': '/Users/cltbld', 08:24:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:47 INFO - 'LOGNAME': 'cltbld', 08:24:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:47 INFO - 'MOZ_NO_REMOTE': '1', 08:24:47 INFO - 'NO_EM_RESTART': '1', 08:24:47 INFO - 'PAGER': '/bin/cat', 08:24:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:47 INFO - 'PWD': '/builds/slave/test', 08:24:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:47 INFO - 'SHELL': '/bin/bash', 08:24:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.01Q6HRRXpg/Listeners', 08:24:47 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 08:24:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:47 INFO - 'USER': 'cltbld', 08:24:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:47 INFO - 'XPC_FLAGS': '0x0', 08:24:47 INFO - 'XPC_SERVICE_NAME': '0', 08:24:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:47 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)) 08:24:47 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)) 08:24:47 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)) 08:24:47 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)) 08:24:47 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)) 08:24:47 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)) 08:24:47 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)) 08:24:47 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)) 08:24:47 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)) 08:24:47 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)) 08:24:47 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)) 08:24:47 INFO - Cleaning up... 08:24:47 INFO - Return code: 0 08:24:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:24:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:47 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 0x10a30d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a283570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f913adbd2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a121d30>, '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 0x109f47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a119b20>, '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.EqoJychxy5/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.01Q6HRRXpg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 08:24:47 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 08:24:47 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 08:24:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EqoJychxy5/Render', 08:24:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:47 INFO - 'HOME': '/Users/cltbld', 08:24:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:47 INFO - 'LOGNAME': 'cltbld', 08:24:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:47 INFO - 'MOZ_NO_REMOTE': '1', 08:24:47 INFO - 'NO_EM_RESTART': '1', 08:24:47 INFO - 'PAGER': '/bin/cat', 08:24:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:47 INFO - 'PWD': '/builds/slave/test', 08:24:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:47 INFO - 'SHELL': '/bin/bash', 08:24:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.01Q6HRRXpg/Listeners', 08:24:47 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 08:24:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:47 INFO - 'USER': 'cltbld', 08:24:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:47 INFO - 'XPC_FLAGS': '0x0', 08:24:47 INFO - 'XPC_SERVICE_NAME': '0', 08:24:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:24:47 INFO - Cleaning up... 08:24:47 INFO - Return code: 0 08:24:47 INFO - Running post-action listener: _resource_record_post_action 08:24:47 INFO - Running post-action listener: _start_resource_monitoring 08:24:47 INFO - Starting resource monitoring. 08:24:47 INFO - [mozharness: 2016-10-30 15:24:47.645172Z] Finished create-virtualenv step (success) 08:24:47 INFO - [mozharness: 2016-10-30 15:24:47.646303Z] Running install step. 08:24:47 INFO - Running pre-action listener: _resource_record_pre_action 08:24:47 INFO - Running main action method: install 08:24:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:24:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:24:47 INFO - Reading from file tmpfile_stdout 08:24:47 INFO - Detecting whether we're running mozinstall >=1.0... 08:24:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:24:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:24:48 INFO - Reading from file tmpfile_stdout 08:24:48 INFO - Output received: 08:24:48 INFO - Usage: mozinstall [options] installer 08:24:48 INFO - Options: 08:24:48 INFO - -h, --help show this help message and exit 08:24:48 INFO - -d DEST, --destination=DEST 08:24:48 INFO - Directory to install application into. [default: 08:24:48 INFO - "/builds/slave/test"] 08:24:48 INFO - --app=APP Application being installed. [default: firefox] 08:24:48 INFO - mkdir: /builds/slave/test/build/application 08:24:48 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'] 08:24:48 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 08:25:22 INFO - Reading from file tmpfile_stdout 08:25:22 INFO - Output received: 08:25:22 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:25:22 INFO - Running post-action listener: _resource_record_post_action 08:25:22 INFO - [mozharness: 2016-10-30 15:25:22.671549Z] Finished install step (success) 08:25:22 INFO - [mozharness: 2016-10-30 15:25:22.671661Z] Running run-tests step. 08:25:22 INFO - Running pre-action listener: _resource_record_pre_action 08:25:22 INFO - Running main action method: run_tests 08:25:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:25:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:25:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:25:22 INFO - 2016-10-30 08:25:22.686 screenresolution[6718:128304] starting screenresolution argv=screenresolution get 08:25:22 INFO - 2016-10-30 08:25:22.689 screenresolution[6718:128304] Display 0: 1600x1200x32@60 08:25:22 INFO - 2016-10-30 08:25:22.695 screenresolution[6719:128306] starting screenresolution argv=screenresolution list 08:25:22 INFO - Available Modes on Display 0 08:25:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:25:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:25:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:25:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:25:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:25:22 INFO - Intel Iris: 08:25:22 INFO - Chipset Model: Intel Iris 08:25:22 INFO - Type: GPU 08:25:22 INFO - Bus: Built-In 08:25:22 INFO - VRAM (Dynamic, Max): 1536 MB 08:25:22 INFO - Vendor: Intel (0x8086) 08:25:22 INFO - Device ID: 0x0a2e 08:25:22 INFO - Revision ID: 0x0009 08:25:22 INFO - Displays: 08:25:22 INFO - Display: 08:25:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:25:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:25:22 INFO - Display Serial Number: 1600x1200 60 08:25:22 INFO - Main Display: Yes 08:25:22 INFO - Mirror: Off 08:25:22 INFO - Online: Yes 08:25:22 INFO - Rotation: Supported 08:25:22 INFO - Return code: 0 08:25:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:25:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:25:22 INFO - Python 2.7.3 08:25:22 INFO - Return code: 0 08:25:22 INFO - grabbing minidump binary from tooltool 08:25:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:22 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 0x10a121d30>, '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 0x109f47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a119b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:25:22 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 08:25:22 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 08:25:22 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 08:25:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:25:23 INFO - Return code: 0 08:25:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:25:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:25:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:25:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:25:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:25:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C3oggZwnSBy2uISkkxPHiw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0335', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:25:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/C3oggZwnSBy2uISkkxPHiw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0335 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:25:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EqoJychxy5/Render', 08:25:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:25:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:25:23 INFO - 'HOME': '/Users/cltbld', 08:25:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:25:23 INFO - 'LOGNAME': 'cltbld', 08:25:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:25:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:25:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:23 INFO - 'MOZ_NO_REMOTE': '1', 08:25:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:25:23 INFO - 'NO_EM_RESTART': '1', 08:25:23 INFO - 'PAGER': '/bin/cat', 08:25:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:25:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:23 INFO - 'PWD': '/builds/slave/test', 08:25:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:25:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:25:23 INFO - 'SHELL': '/bin/bash', 08:25:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.01Q6HRRXpg/Listeners', 08:25:23 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 08:25:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:25:23 INFO - 'USER': 'cltbld', 08:25:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:25:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:25:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:23 INFO - 'XPC_FLAGS': '0x0', 08:25:23 INFO - 'XPC_SERVICE_NAME': '0', 08:25:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:25:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C3oggZwnSBy2uISkkxPHiw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0335', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:25:23 INFO - mozversion application_buildid: 20161030060922 08:25:23 INFO - mozversion application_changeset: f4f6435ed714df693381b80184f26f297ec1a461 08:25:23 INFO - mozversion application_display_name: Nightly 08:25:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:25:23 INFO - mozversion application_name: Firefox 08:25:23 INFO - mozversion application_remotingname: firefox 08:25:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:25:23 INFO - mozversion application_vendor: Mozilla 08:25:23 INFO - mozversion application_version: 52.0a1 08:25:23 INFO - mozversion platform_buildid: 20161030060922 08:25:23 INFO - mozversion platform_changeset: f4f6435ed714df693381b80184f26f297ec1a461 08:25:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:25:23 INFO - mozversion platform_version: 52.0a1 08:25:23 INFO - using testdate: 1477841123 08:25:23 INFO - actual date: 1477841123 08:25:23 INFO - starting webserver on 'localhost:59877' 08:25:23 INFO - SUITE-START | Running 2 tests 08:25:23 INFO - TEST-START | dromaeo_css 08:25:23 INFO - operating with platform_type : mac_ 08:25:23 INFO - Initialising browser for dromaeo_css test... 08:25:23 INFO - TEST-INFO | started process 6729 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/tmpfSUeFq/profile http://localhost:59877/getInfo.html) 08:25:25 INFO - PROCESS | 6729 | __metrics Screen width/height:1600/1200 08:25:25 INFO - PROCESS | 6729 | colorDepth:24 08:25:25 INFO - PROCESS | 6729 | Browser inner width/height: 1024/689 08:25:25 INFO - PROCESS | 6729 | __metrics 08:25:26 INFO - PROCESS | 6729 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:25:26 INFO - PROCESS | 6729 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 205: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:25:26 INFO - TEST-INFO | 6729: exit 0 08:25:26 INFO - Browser initialized. 08:25:26 INFO - Running cycle 1/1 for dromaeo_css test... 08:25:26 INFO - TEST-INFO | started process 6731 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/tmpfSUeFq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:25:39 INFO - PROCESS | 6731 | RSS: Main: 233005056 08:25:39 INFO - PROCESS | 6731 | 08:26:30 INFO - PROCESS | 6731 | 1477841190579 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] 08:28:44 INFO - PROCESS | 6731 | Cycle 1(1): loaded http://localhost:59877/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59877/tests/dromaeo/cssquery-ext.html) 08:28:46 INFO - PROCESS | 6731 | RSS: Main: 355250176 08:28:46 INFO - PROCESS | 6731 | 08:31:49 INFO - PROCESS | 6731 | Cycle 1(1): loaded http://localhost:59877/tests/dromaeo/cssquery-ext.html (next: http://localhost:59877/tests/dromaeo/cssquery-jquery.html) 08:31:49 INFO - PROCESS | 6731 | RSS: Main: 371249152 08:31:49 INFO - PROCESS | 6731 | 08:34:52 INFO - PROCESS | 6731 | Cycle 1(1): loaded http://localhost:59877/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59877/tests/dromaeo/cssquery-mootools.html) 08:34:52 INFO - PROCESS | 6731 | RSS: Main: 336564224 08:34:52 INFO - PROCESS | 6731 | 08:37:55 INFO - PROCESS | 6731 | Cycle 1(1): loaded http://localhost:59877/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59877/tests/dromaeo/cssquery-prototype.html) 08:37:55 INFO - PROCESS | 6731 | RSS: Main: 347123712 08:37:55 INFO - PROCESS | 6731 | 08:40:59 INFO - PROCESS | 6731 | Cycle 1(1): loaded http://localhost:59877/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59877/tests/dromaeo/cssquery-yui.html) 08:40:59 INFO - PROCESS | 6731 | RSS: Main: 338030592 08:40:59 INFO - PROCESS | 6731 | 08:44:03 INFO - PROCESS | 6731 | Cycle 1(1): loaded http://localhost:59877/tests/dromaeo/cssquery-yui.html (next: http://localhost:59877/tests/dromaeo/cssquery-dojo.html) 08:44:03 INFO - PROCESS | 6731 | RSS: Main: 375107584 08:44:03 INFO - PROCESS | 6731 | 08:44:03 INFO - PROCESS | 6731 | __start_tp_report 08:44:03 INFO - PROCESS | 6731 | _x_x_mozilla_page_load 08:44:03 INFO - PROCESS | 6731 | _x_x_mozilla_page_load_details 08:44:03 INFO - PROCESS | 6731 | |i|pagename|runs| 08:44:03 INFO - PROCESS | 6731 | |0;dojo.html;3253.7234335081516;3258.16916686245;3297.3240485700426;3297.9835100824494;3307.1070810881065;5566.192902029214;5636.943630563693;5678.772849086036;5691.060974939141;5714.857128571788;5631.57763167762;5660.264165658469;5670.291213598301;5701.144828275761;5721.628094173876;5027.472115427882;5103.974480127594;5111.361079865017;5150.587952963754;5172.560332371753;5261.947380526203;5271.525562699357;5281.735913204344;5303.23103150043;5338.119210330291;5095.719735414587;5227.372715274153;5242.292290540767;5265.578753699714;5338.546223571001;5144.639875208737;5187.636865419422;5195.922061169085;5206.583473322125;5209.973950130263;5129.9743501282255;5153.510416510426;5172.069027575035;5185.611079169077;5201.713905735183;5607.214989901417;5637.689927054011;5643.153526970947;5643.971780141073;5654.773809047633;6096.272074548579;6103.519504250422;6127.20346354971;6141.692915354214;6143.508519318444;6091.25479055427;6146.815595532141;6182.536309776785;6188.566800323979;6197.752089916398;4786.688865223784;5631.267935168402;5701.600887937846;5721.370649228582;5729.799457018984;6152.046432802923;6231.563790534619;6246.75012999484;6260.342664020303;6261.467775239064;6068.544859135494;6085.726142323518;6101.60339577926;6128.233970753656;6128.693565321805;6101.084837274356;6111.43980788641;6119.868162944292;6130.245269657028;6208.379162083846;6112.047575716847;6138.143176733788;6148.077788331786;6149.7847575334645;6168.814935551941;6157;6169.259688837278;6172.506199504029;6180.443760061616;6186.350433204539;4809.422869255712;4852.781624826891;4856.053069651384;4869.172240719086;4900.240462728283;4315.892102697395;4337.7180483268485;4343.19650864591;4343.218220720238;4351.129774045204;5579.107342825128;5591.440855914458;5591.552675785927;5598.384177740445;5624.240727501817;6181.258249010148;6183.948728716129;6192.504599632019;6201.968990155111;6214.4407003369915;5497.367802702661;5552.861178470571;5560.193771903051;5570.442955704478;5622.184783206412;5312.150055991023;5333.200019997032;5346.518813306821;5351.919721204185;5363.168708850135;6253.937460625336;6278.309385967631;6278.340774218641;6281.874362512633;6290.0879372491645;6101.084837274444;6101.217066730222;6123.724432400463;6202.317745047951;6251.843703907439;6194.132821404917;6224.564280500322;6229.065640154013;6257.217847769029;6268.843278918063;6101.542384321105;6121.8980583495395;6129.387061293835;6140.263168419819;6144.2319710036245;5025.698458092526;6105.389461053859;6199.16311297969;6236.719347629277;6245.250569931455;5967.9701601491715;6022.789202378071;6037.154798328325;6080.331163572092;6118.345080237391;6050.727717252822;6092.603980741238;6114.082887566723;6118.541109416722;6141.877162456636;5707.4577915098;5943.435373639497;5955.493783028491;5976.820695379146;5980.790672326448;5958.404159584007;5990.6705131218205;6001.549883758648;6050.788222412194;6125.111858780541;5872.882542349043;5918.260217472816;5921.792737254175;5933.110033495011;5933.762649494144 08:44:03 INFO - PROCESS | 6731 | |1;ext.html;463.696278937296;497.7212760853921;503.3908970146125;507.7182163899036;520.591335801396;40799.184016319654;42120.57879421209;42209.944751381234;42389.72830815076;42854.35718464226;43132.78433607835;43338.783306083504;43378.56621433785;43396;43688.12623747531;9452.432854028762;9638.036196380375;9645.03549645037;9645.758856028586;9645.903540964606;9439.339246252764;9526.571304291288;9540.761480962961;9542.427454352726;9548.999999999965;8209.507429554216;9509.80980380392;9567.138957493824;9570.37792543486;9585.712428627152;9488.146066853982;9552.952235238814;9553.761155971157;9571.664991725256;9572;9384.390014649027;9406.717798465988;9448.291378146667;9460.57427415768;9482.762930926781;40719.796401017804;41095.17809643791;41119.7944010278;41208.79395603033;41287.76136715903;3281.8030918144987;3349.949750753741;3443.2941247044296;3520.524729161556;3591.676749092594;29614.92695511315;30481;31691.84154079238;31878.840605796824;32120.839395803105;2747.5968468244546;3147.244661281276;3255.755818313612;3297.142742886843;3360.3615313090436;475810;478426.6078669584;481026;482551.9999999965;495075;59718.40281597128;60318.69840650768;60524.697376512835;60624.696876515336;60649.69675151596;62374.0643890342;62419.375806241354;62448.687756560925;62493.06260406098;62532;15595.532134035997;15724.606884827743;15829.445969391016;15832.28754706041;15909.602259943362;11762.117841161948;12284.385780711107;12310.692232694255;12325.075619328407;12334.136610437363;15652.843471565366;16352.83647163537;16381.59046023835;16381.836181638031;16460.917695411445;5877.2074517271785;7014.1943222711325;7121.82195445118;7170.533915295489;7190.568565886064;749.4716225061319;892.848215803302;934.4533447933036;989.2975987049132;1085.6417382263835;30083.247918801768;30213;30270.54594181089;30488.08535743931;30864;994.2642444591095;1083.864516935383;1183.621241202807;1216.7384012437192;1230.501646833034;13712.89982704812;16463.094529800976;16910.915445422695;16981.83018169827;17765.555861103578;628358;630184;630848;632183;632185;60103.39896600978;60137.79724405488;60222.698886506165;60346.698266508385;60525.69737151286;1239.4918083585687;1275.9298238596966;1366.1120271823395;1391.200059965524;1409.1404243411512;26570.601440978015;30403.37537555559;30900.53649195219;31090.22274443157;31727;49606.751966239935;50372.49627503678;50427;50442;50493.242601360274;517871.41064295947;521992;522578;523377;523924;23417.531649366578;26140.34649133787;26848.731512684622;27283.317917051438;27572;47497.762511187226;47850.62614042459;48081.79795505141;48619.27071094007;48710.26934595913;39285.41071883867;40005.59994400019;40651.390229145996;40736;41006.79496602498;38743.612563874;38915.80542097271;38927.80536097301;40521.98695032648;40724.59275407327;38171.42742858804;39035.02412439712;39151.804240978614;39495.605043949196;39566.2086758269;31081.68918310788;33577.83211083929;33968.490472643425;34182;34626;25154;25407;25559.23322300334;25877.223683289532;26047.609285861105 08:44:03 INFO - PROCESS | 6731 | |2;jquery.html;308.2847793119962;349.43391705437165;361.53001098571866;369.1472698067464;400.80560927949955;14811.481598144053;14992.025518341314;15606.843931560681;15859.127747973856;18934.10658934106;19986;20492;20912.581748365024;21127.154913803428;21177.57644847106;5731.111677689954;5941.405859414046;6012.909806352908;6109.908351374733;6115;3311.8840840570583;5846.269216347957;6562.179727534059;6673.733050677967;6732.259451460335;6563.507736919726;6652.201735791712;6652.70062847173;6666.600003999775;6678.131842860422;5688.544916406699;6024.2770867495965;6066.787662431793;6111.388861113853;6116.388361163892;5147.562457191143;5413.485718856702;5819.010768169422;5858.12128180772;5865.4134586541;25165.496690066095;25206.991720331163;25223.36941576457;25482.980680772747;25719.228423147335;2063.432556047084;2130.211821625993;2151.655735082379;2243.4054975431523;2256.9617975731185;9967.75080622983;10231.386116833013;10524.94750524949;10542.314749541332;11326.433678316134;1702.0978881192987;1790.7851057873008;1798.6672465593776;1798.7858195717838;1804.7645385194253;108550.3717444239;119463;120839;122208;125029;11307.660770176908;11311.547538098384;11383.544658213745;11524.711882203012;11716.882831171579;14410.639734006523;14424.278786060655;14447.422103115969;14465.710685786436;14470.987030907945;7992.760217193493;8242.216989386;8297.211764882326;8479.19447652472;8499.660013599387;8185.222403871741;8190.83618327642;8210.466319689202;8364.247217750431;8368.916310836814;4395.956040439619;4454.398656181439;4477.753723545236;4538.2738761797955;4602.861914142581;1846.18767742193;1892.9366989980988;1898.6392585409005;1901.4580844459263;1907.2275728330048;820.8727647214691;846.9709303196646;884.9911500885038;943.7263193673758;987.5508738328984;19781;20565.69151462729;21235.256766013117;21298.893505532375;21514.439822462817;890.3100097424418;943.9622415103319;945.8061097475002;948.5913418573413;950.3775027357092;8454.82797557774;9959.751006224902;10942.617008404668;11053.226274160732;11180.552777888794;499581;531511.3424432853;579011;583952;590806;126695.36652316863;126784;127336;127779.05362902085;132647;827.8137419080658;958.113744786084;990.6285143071349;991.9305648604673;994.945278009702;14200.78698819498;18521.722174167127;18745.90627046856;18886.622267554845;19190.232390704776;45650.77174614106;50724.74637626788;52829.207561885836;53456;54694.45305546893;75190;76088.85866712114;77959;85651.57174214089;88612;7043.823904402431;7339.522931009467;7702.653380597999;7881.802954926173;8285.585720714062;10518.421486818299;12304.81542776877;12704.793044660742;13295.534656286984;13454.663633409244;9427.717168484956;10016;11605;11858.703532411759;11940.16418850707;11074.778504430027;11402.657920262405;11422.200445968703;11508.712282193012;11703.180777345504;9491.525423728925;11656.825147622962;11942.761144776881;12002.339871306813;12139.75720485603;6073.544484163617;7222.313880181375;7311.67097480604;7464.664090115849;7525.73659921922;9522;11387.032102271069;11520.308781473139;11731.88268117308;11781.23421977603 08:44:03 INFO - PROCESS | 6731 | |3;mootools.html;1341.738361019601;1357.6062941746893;1374.5738820965507;1689.3833750680997;1923.6056608395272;7482.962585187073;7894.644740986655;8167.428280020394;8470.745877623665;8472.91527084729;8315.542645154514;8344;8459.365547583926;8460.91539084609;8471.322294216463;2273.329367836492;2292.9541409171807;2307.3424074138875;2307.7346105197867;2309.4688221709016;2279.794818466338;2320.8491448055906;2336.7312759032757;2340;2340.6372012338115;2269;2281.6691579721014;2308.549832782608;2315.6873822033976;2316.9420764480856;1839.5677015901254;1876.408931186672;2265.139247086105;2282.8173746100274;2284.4288927768057;2269.273832373641;2290.4617414907484;2290.576243394977;2295.4490922178725;2297.8506397084134;8326.167383261685;8366.83266334676;8423.157684231528;8426.410151289412;8427;4941.45643979162;5033.169527028036;5084.855907420838;5105.412877519058;5109;3547;3619.3304238715923;3635.1093981974323;3639.6542328478754;3646.9452958205384;996.9152622027136;1015.1624909449733;1026.3226270661328;1041.6614600254948;1043.0664555223034;10428.800687920835;10740.140788736882;10806.837897431546;10882.836757448644;10896.346219226873;4287.935680964788;4379.627731642782;4488.438945131858;4648.790804413809;4692.296155576691;8671.739847804576;8710.82578348442;8731.563421828883;8738.650453981896;8749.65001399937;5108.310378098983;5111.182210846247;5112.3865136183895;5114.923276150861;5115.718635475085;3764.5858955514873;4254.361845723104;4735.337052812609;4794.089123066676;4807.990322032412;3463.41122009259;3541.29174165168;3570.892873213808;3607.152319204985;3616.6383361663625;1022.4018948914968;1078.1859695929522;1093.484984535565;1101.9504122314515;1102.1458369763336;857.3012994409536;858.52780970466;904.2856143646445;910.5902343945252;937.6202637931649;5608.411116832755;5723.227364305849;5904.881902361929;5937.910931336033;5948.762049518057;862.6376921692904;892.901780804111;901.8241442918699;936.6159874451442;953.5041778275258;3819.52255968004;3873.360895452275;3874.670652994471;3981.0843505993785;4046.2109888572036;9365.578548965314;9442.347589171868;9881.308308418342;10033.046860548237;10141.94929025365;10022.298439109192;10100.242481813893;10112;10117.190624750003;10124.443155626512;7476.364509016795;7823.061232652004;7828.412869034732;7835.451518393772;7946.880796788053;4364.519902810752;5608.943910561005;5790.392008839011;5805.158252053344;5876.471117599436;9075.455472671661;9127.178553930178;9214;9328.720138395858;9435.481048542397;9324.254059675073;9389.718308450758;9474;9548;9583.808323833624;7243.855122897618;7473.476856619985;7493.625318734151;7803.765887023398;7959.482633628795;6500.674966251763;7900.209979002284;8020.0375954885785;8087.191280871866;8128.512289262663;7083.645817709197;7257.310555497219;7285.672144753605;7302.087239095114;7314.049173607397;5951.55363347742;6129;7124.287571242834;7126.714931402686;7178.425725941807;6485;6600.042993765781;6697.330266973264;6710.094137291403;6718.0594716738715;4847.127517046824;4889.584385327287;4916.237983112624;4958.925616115834;5025.221090730943;4854.466008739107;4860.1251774680895;4950;5012.924806127838;5026.095302845377 08:44:03 INFO - PROCESS | 6731 | |4;prototype.html;245.9139301244564;296.9228000719813;304.24091890732626;310.26467272564025;314.28656948726376;5090.770915308815;5100.285959965603;5113.437521872591;5127.564157046646;5149.922751158736;4101.569335219804;4459.353393757911;4679.555442232982;4824;5051.5453609175165;3865.439511270864;3904.707146963975;3929.8231579578987;3943.3099207638693;3958.5447673517483;3418.5897692276835;3887;3923.5880232575455;3930.272899513585;3955.9208815823667;3186.3431015871734;3450.7584469087174;3616.367135751246;3889.4165875118815;3905.668018218455;3182.411253918033;3533.6113027567076;3555.537780088585;3790.54513458384;3869.9032524186837;3405.6083550391772;3407.3185362927265;3408.437607794711;3418.1112910643164;3421.8802341917913;4069.3285607874664;4947.526236881559;4968.354113965162;4969.751512424365;4970.776315065831;3851.84592616295;3872.4965754452023;4256.489221293434;4411.382406463098;4428.656364444856;4422.270325396305;4865.929495510982;4987.875303117415;5225.921611175798;5254.65844720092;3149.401613693391;3727.4967879336214;3741;3749.6812770914503;3769.340365436065;5275.841724748264;5561.499465048165;5592.217089607459;5597.3003374578175;5604.551635869121;3157.3053928135773;3755.887323380303;3756;3774.8490060397826;3789.3368660484307;3829.5978922213326;3830.5020347354666;3838;3838.251540949488;3839.30892439358;3900.1419687668667;4162.063535704442;4374.978125109354;4422.14270006598;4427.623651989553;4991.700497970133;5293.761780719877;5315.681059136464;5324.201369794562;5340.732963351817;1292.7589514066412;1310.5544115000946;1314.3625341709264;1320.3134370127673;1321.45159758701;799.3245707377257;801.382935139939;826.7974346285199;868.5743985447085;874.5190145419994;4177.892858392528;4767.547083027107;4835.347228124158;4862.854114376574;4973.179425394769;4817.927731084036;5272.710000949908;5301.9396120776;5417.539509141767;5451.48210919962;4127.6491498222385;4684.367610372624;4920.606351491872;4950;4981.750912454363;2528.759767822054;2563.9359016024378;2743.670759508872;2846.672632647272;2886.7834912381654;4776.307435421844;5330.947585179733;5432.293801805819;5673.574481913873;5706.457886500776;4907.950920490821;5189.818356357509;5402.972985135049;5549.334079910356;5606.439356064442;4824.758762061883;5767.653940763567;5811.82564523065;5816.47651711348;5830.737616807254;4615.23848564983;5323.334583177103;5352.866178345573;5500.367457742411;5527.778888844401;5166.2508936203285;5394.628357276702;5428.978195169374;5436.782528698966;5467.644603100785;5703.7433315500075;5733.455321744427;5746.195532625519;5789.363170051375;5823.883522329615;3330.400527905;3424.845881935269;3709.27669104522;3734.439834024918;3737.458068580095;5036.773345199402;5091.974540127424;5406.675599464044;5436.945630543644;5482.506574408322;4900.534449227318;5369.543588794996;5377.623566350318;5411.972940135274;5415.945840541702;4466.486354069194;4939.407271127344;4944.456109827988;4948.678335908155;5464.207689885024;5213.452587478412;5267.2889159962915;5276.551493123127;5283.30168881792;5296.219484770526;4246.511651160033;5042.571381432619;5206.817761378334;5231.895362092813;5342.599305052215;4341.327094300388;4645.187092258909;4687.554682305175;5238.345206849144;5274.762635681327 08:44:03 INFO - PROCESS | 6731 | |5;yui.html;743.7619961612286;778.8364443466871;782.7146373604514;798.3812545277409;821.2075347289866;14360.569182924502;15136.091834489924;16154.676906461878;16785.83214167861;16950.491485255432;15044.247787610602;15284.923575382107;15423.997440166382;15447.45933892314;15473.226338683076;862.1249431826701;869.2654706772767;877.754228815931;896.0367604824839;898.6989358564889;584.7982446056104;720.0279622509622;865.9350548708841;891.0198781340538;894.9239314658262;903.9728808135767;942.7548837302282;952.2477242978039;955.2358113509165;955.5174636808403;909.2998391238742;909.5179554835947;943.3113826906397;944.7449188719077;947.327397547742;534.7647035304453;554.8917960997607;701.1340993872564;703.5567592416745;704.968276427562;14534.781978270334;15023.323950422257;15106.320215590325;15408.922955385264;15432.845671543366;12058.457369418396;13097.47610095595;13290.401931913088;13613.455461781517;13805.585832425044;9687.515624218831;11595.884041159648;11653.533858645644;11739.7065073373;11847.992920601759;3449.189440481485;3660.798656073915;3772.603876592973;3884.261990221849;3954.7231693781446;87158.69261961013;89748.55125724395;90476.09523904743;90512.54743726306;91432;10499;10515.26393152472;10532.473376331152;10762.40806755636;10890.782184356427;20358.898205508878;20583.691244631344;20868.79131208699;20878.686819697716;21233.469163271042;7657.46397752152;7738.458307918391;7762.340201082971;7776.494527855784;7942.046954365397;4245.12974840157;4392.253316936129;4400.207962566705;4428.22506061438;4583.931241031387;3422.1273575238156;3524.753267271318;3546.7517273790586;3547.6629720176543;3559.288142371536;637.1430426076919;642.6754488983033;650.3204151661525;667.9298673639295;669.0499490723088;574.1875246526118;579.9362070172277;647.3655817299074;664.7772996046282;693.4036728413561;9097.863532047024;9175.22010629104;9193.356465047382;9299.72100836976;9571.138597526256;625.5183508698277;670.4401824476519;675.2066322071546;684.852756657321;687.4156966578369;4252.766097864585;4502.527234640315;4556.65825063115;4579.702319349231;4603.631709463235;68082.65958670175;68978;73388;90900.09099909049;96459;56767.14849277264;57619.423805761406;58902.410975889696;60198.39801601928;78771.60614196892;60475.69762151161;69860.30139698538;79592.6020369906;83364.16635833564;86081;9466.05339466048;9663.361586438408;9698.9030109698;9711.84419713837;9781.804363912825;52587.47412525979;52601.21098183607;54223.18665220104;54708.4934015407;55517.72241138769;62500.06249906164;76023.8596421043;78757;81210.59394702989;81953.59023204846;10813.485617174607;11960.699707876314;12141;12323.568675096329;13130.474781008652;14055.367508462348;14240.430382784572;14390.928045359706;14670.559883203521;14767.483138090116;10588.364698118137;13229.669258268621;13365.933170334474;13433.26117063571;13620.65948351299;13035.283059431822;13368;13377.66555836112;13577.117487363685;13844.515441959484;12883.291418972047;13066.608001759962;13078.28069456189;13233.80149297742;13516.797248041485;9322.440653560809;10864.77259720422;11056.336619802838;11703.648890533297;11745.823812642988;10118.898811011795;10932.508037138507;11038.889611104107;11409;11666.358350290815 08:44:03 INFO - PROCESS | 6731 | __end_tp_report 08:44:03 INFO - PROCESS | 6731 | __start_cc_report 08:44:03 INFO - PROCESS | 6731 | _x_x_mozilla_cycle_collect,1770 08:44:03 INFO - PROCESS | 6731 | __end_cc_report 08:44:03 INFO - PROCESS | 6731 | __startTimestamp1477842243562__endTimestamp 08:44:03 INFO - PROCESS | 6731 | 08:44:03 INFO - PROCESS | 6731 | ------- Summary: start ------- 08:44:03 INFO - PROCESS | 6731 | Number of tests: 6 08:44:03 INFO - PROCESS | 6731 | 08:44:03 INFO - PROCESS | 6731 | [#0] dojo.html Cycles:165 Average:5681.27 Median:5943.44 stddev:641.92 (10.8%) stddev-sans-first:615.00 08:44:03 INFO - PROCESS | 6731 | Values: 3253.7 3258.2 3297.3 3298.0 3307.1 5566.2 5636.9 5678.8 5691.1 5714.9 5631.6 5660.3 5670.3 5701.1 5721.6 5027.5 5104.0 5111.4 5150.6 5172.6 5261.9 5271.5 5281.7 5303.2 5338.1 5095.7 5227.4 5242.3 5265.6 5338.5 5144.6 5187.6 5195.9 5206.6 5210.0 5130.0 5153.5 5172.1 5185.6 5201.7 5607.2 5637.7 5643.2 5644.0 5654.8 6096.3 6103.5 6127.2 6141.7 6143.5 6091.3 6146.8 6182.5 6188.6 6197.8 4786.7 5631.3 5701.6 5721.4 5729.8 6152.0 6231.6 6246.8 6260.3 6261.5 6068.5 6085.7 6101.6 6128.2 6128.7 6101.1 6111.4 6119.9 6130.2 6208.4 6112.0 6138.1 6148.1 6149.8 6168.8 6157.0 6169.3 6172.5 6180.4 6186.4 4809.4 4852.8 4856.1 4869.2 4900.2 4315.9 4337.7 4343.2 4343.2 4351.1 5579.1 5591.4 5591.6 5598.4 5624.2 6181.3 6183.9 6192.5 6202.0 6214.4 5497.4 5552.9 5560.2 5570.4 5622.2 5312.2 5333.2 5346.5 5351.9 5363.2 6253.9 6278.3 6278.3 6281.9 6290.1 6101.1 6101.2 6123.7 6202.3 6251.8 6194.1 6224.6 6229.1 6257.2 6268.8 6101.5 6121.9 6129.4 6140.3 6144.2 5025.7 6105.4 6199.2 6236.7 6245.3 5968.0 6022.8 6037.2 6080.3 6118.3 6050.7 6092.6 6114.1 6118.5 6141.9 5707.5 5943.4 5955.5 5976.8 5980.8 5958.4 5990.7 6001.5 6050.8 6125.1 5872.9 5918.3 5921.8 5933.1 5933.8 08:44:03 INFO - PROCESS | 6731 | 08:44:03 INFO - PROCESS | 6731 | [#1] ext.html Cycles:180 Average:68464.54 Median:41704.17 stddev:146398.26 (351.0%) stddev-sans-first:146719.91 08:44:03 INFO - PROCESS | 6731 | Values: 463.7 497.7 503.4 507.7 520.6 40799.2 42120.6 42209.9 42389.7 42854.4 43132.8 43338.8 43378.6 43396.0 43688.1 9452.4 9638.0 9645.0 9645.8 9645.9 9439.3 9526.6 9540.8 9542.4 9549.0 8209.5 9509.8 9567.1 9570.4 9585.7 9488.1 9553.0 9553.8 9571.7 9572.0 9384.4 9406.7 9448.3 9460.6 9482.8 40719.8 41095.2 41119.8 41208.8 41287.8 3281.8 3349.9 3443.3 3520.5 3591.7 29614.9 30481.0 31691.8 31878.8 32120.8 2747.6 3147.2 3255.8 3297.1 3360.4 475810.0 478426.6 481026.0 482552.0 495075.0 59718.4 60318.7 60524.7 60624.7 60649.7 62374.1 62419.4 62448.7 62493.1 62532.0 15595.5 15724.6 15829.4 15832.3 15909.6 11762.1 12284.4 12310.7 12325.1 12334.1 15652.8 16352.8 16381.6 16381.8 16460.9 5877.2 7014.2 7121.8 7170.5 7190.6 749.5 892.8 934.5 989.3 1085.6 30083.2 30213.0 30270.5 30488.1 30864.0 994.3 1083.9 1183.6 1216.7 1230.5 13712.9 16463.1 16910.9 16981.8 17765.6 628358.0 630184.0 630848.0 632183.0 632185.0 60103.4 60137.8 60222.7 60346.7 60525.7 1239.5 1275.9 1366.1 1391.2 1409.1 26570.6 30403.4 30900.5 31090.2 31727.0 49606.8 50372.5 50427.0 50442.0 50493.2 517871.4 521992.0 522578.0 523377.0 523924.0 23417.5 26140.3 26848.7 27283.3 27572.0 47497.8 47850.6 48081.8 48619.3 48710.3 39285.4 40005.6 40651.4 40736.0 41006.8 38743.6 38915.8 38927.8 40522.0 40724.6 38171.4 39035.0 39151.8 39495.6 39566.2 31081.7 33577.8 33968.5 34182.0 34626.0 25154.0 25407.0 25559.2 25877.2 26047.6 08:44:03 INFO - PROCESS | 6731 | 08:44:03 INFO - PROCESS | 6731 | [#2] jquery.html Cycles:180 Average:33795.34 Median:1830.66 stddev:93659.69 (5116.2%) stddev-sans-first:93888.68 08:44:03 INFO - PROCESS | 6731 | Values: 308.3 349.4 361.5 369.1 400.8 14811.5 14992.0 15606.8 15859.1 18934.1 19986.0 20492.0 20912.6 21127.2 21177.6 5731.1 5941.4 6012.9 6109.9 6115.0 3311.9 5846.3 6562.2 6673.7 6732.3 6563.5 6652.2 6652.7 6666.6 6678.1 5688.5 6024.3 6066.8 6111.4 6116.4 5147.6 5413.5 5819.0 5858.1 5865.4 25165.5 25207.0 25223.4 25483.0 25719.2 2063.4 2130.2 2151.7 2243.4 2257.0 9967.8 10231.4 10524.9 10542.3 11326.4 1702.1 1790.8 1798.7 1798.8 1804.8 108550.4 119463.0 120839.0 122208.0 125029.0 11307.7 11311.5 11383.5 11524.7 11716.9 14410.6 14424.3 14447.4 14465.7 14471.0 7992.8 8242.2 8297.2 8479.2 8499.7 8185.2 8190.8 8210.5 8364.2 8368.9 4396.0 4454.4 4477.8 4538.3 4602.9 1846.2 1892.9 1898.6 1901.5 1907.2 820.9 847.0 885.0 943.7 987.6 19781.0 20565.7 21235.3 21298.9 21514.4 890.3 944.0 945.8 948.6 950.4 8454.8 9959.8 10942.6 11053.2 11180.6 499581.0 531511.3 579011.0 583952.0 590806.0 126695.4 126784.0 127336.0 127779.1 132647.0 827.8 958.1 990.6 991.9 994.9 14200.8 18521.7 18745.9 18886.6 19190.2 45650.8 50724.7 52829.2 53456.0 54694.5 75190.0 76088.9 77959.0 85651.6 88612.0 7043.8 7339.5 7702.7 7881.8 8285.6 10518.4 12304.8 12704.8 13295.5 13454.7 9427.7 10016.0 11605.0 11858.7 11940.2 11074.8 11402.7 11422.2 11508.7 11703.2 9491.5 11656.8 11942.8 12002.3 12139.8 6073.5 7222.3 7311.7 7464.7 7525.7 9522.0 11387.0 11520.3 11731.9 11781.2 08:44:03 INFO - PROCESS | 6731 | 08:44:03 INFO - PROCESS | 6731 | [#3] mootools.html Cycles:180 Average:5411.61 Median:5106.86 stddev:2967.84 (58.1%) stddev-sans-first:2960.41 08:44:03 INFO - PROCESS | 6731 | Values: 1341.7 1357.6 1374.6 1689.4 1923.6 7483.0 7894.6 8167.4 8470.7 8472.9 8315.5 8344.0 8459.4 8460.9 8471.3 2273.3 2293.0 2307.3 2307.7 2309.5 2279.8 2320.8 2336.7 2340.0 2340.6 2269.0 2281.7 2308.5 2315.7 2316.9 1839.6 1876.4 2265.1 2282.8 2284.4 2269.3 2290.5 2290.6 2295.4 2297.9 8326.2 8366.8 8423.2 8426.4 8427.0 4941.5 5033.2 5084.9 5105.4 5109.0 3547.0 3619.3 3635.1 3639.7 3646.9 996.9 1015.2 1026.3 1041.7 1043.1 10428.8 10740.1 10806.8 10882.8 10896.3 4287.9 4379.6 4488.4 4648.8 4692.3 8671.7 8710.8 8731.6 8738.7 8749.7 5108.3 5111.2 5112.4 5114.9 5115.7 3764.6 4254.4 4735.3 4794.1 4808.0 3463.4 3541.3 3570.9 3607.2 3616.6 1022.4 1078.2 1093.5 1102.0 1102.1 857.3 858.5 904.3 910.6 937.6 5608.4 5723.2 5904.9 5937.9 5948.8 862.6 892.9 901.8 936.6 953.5 3819.5 3873.4 3874.7 3981.1 4046.2 9365.6 9442.3 9881.3 10033.0 10141.9 10022.3 10100.2 10112.0 10117.2 10124.4 7476.4 7823.1 7828.4 7835.5 7946.9 4364.5 5608.9 5790.4 5805.2 5876.5 9075.5 9127.2 9214.0 9328.7 9435.5 9324.3 9389.7 9474.0 9548.0 9583.8 7243.9 7473.5 7493.6 7803.8 7959.5 6500.7 7900.2 8020.0 8087.2 8128.5 7083.6 7257.3 7285.7 7302.1 7314.0 5951.6 6129.0 7124.3 7126.7 7178.4 6485.0 6600.0 6697.3 6710.1 6718.1 4847.1 4889.6 4916.2 4958.9 5025.2 4854.5 4860.1 4950.0 5012.9 5026.1 08:44:03 INFO - PROCESS | 6731 | 08:44:03 INFO - PROCESS | 6731 | [#4] prototype.html Cycles:180 Average:4304.82 Median:4864.39 stddev:1322.98 (27.2%) stddev-sans-first:1291.14 08:44:03 INFO - PROCESS | 6731 | Values: 245.9 296.9 304.2 310.3 314.3 5090.8 5100.3 5113.4 5127.6 5149.9 4101.6 4459.4 4679.6 4824.0 5051.5 3865.4 3904.7 3929.8 3943.3 3958.5 3418.6 3887.0 3923.6 3930.3 3955.9 3186.3 3450.8 3616.4 3889.4 3905.7 3182.4 3533.6 3555.5 3790.5 3869.9 3405.6 3407.3 3408.4 3418.1 3421.9 4069.3 4947.5 4968.4 4969.8 4970.8 3851.8 3872.5 4256.5 4411.4 4428.7 4422.3 4865.9 4987.9 5225.9 5254.7 3149.4 3727.5 3741.0 3749.7 3769.3 5275.8 5561.5 5592.2 5597.3 5604.6 3157.3 3755.9 3756.0 3774.8 3789.3 3829.6 3830.5 3838.0 3838.3 3839.3 3900.1 4162.1 4375.0 4422.1 4427.6 4991.7 5293.8 5315.7 5324.2 5340.7 1292.8 1310.6 1314.4 1320.3 1321.5 799.3 801.4 826.8 868.6 874.5 4177.9 4767.5 4835.3 4862.9 4973.2 4817.9 5272.7 5301.9 5417.5 5451.5 4127.6 4684.4 4920.6 4950.0 4981.8 2528.8 2563.9 2743.7 2846.7 2886.8 4776.3 5330.9 5432.3 5673.6 5706.5 4908.0 5189.8 5403.0 5549.3 5606.4 4824.8 5767.7 5811.8 5816.5 5830.7 4615.2 5323.3 5352.9 5500.4 5527.8 5166.3 5394.6 5429.0 5436.8 5467.6 5703.7 5733.5 5746.2 5789.4 5823.9 3330.4 3424.8 3709.3 3734.4 3737.5 5036.8 5092.0 5406.7 5436.9 5482.5 4900.5 5369.5 5377.6 5412.0 5415.9 4466.5 4939.4 4944.5 4948.7 5464.2 5213.5 5267.3 5276.6 5283.3 5296.2 4246.5 5042.6 5206.8 5231.9 5342.6 4341.3 4645.2 4687.6 5238.3 5274.8 08:44:03 INFO - PROCESS | 6731 | 08:44:03 INFO - PROCESS | 6731 | [#5] yui.html Cycles:180 Average:18825.15 Median:27378.98 stddev:25536.75 (93.3%) stddev-sans-first:25572.29 08:44:03 INFO - PROCESS | 6731 | Values: 743.8 778.8 782.7 798.4 821.2 14360.6 15136.1 16154.7 16785.8 16950.5 15044.2 15284.9 15424.0 15447.5 15473.2 862.1 869.3 877.8 896.0 898.7 584.8 720.0 865.9 891.0 894.9 904.0 942.8 952.2 955.2 955.5 909.3 909.5 943.3 944.7 947.3 534.8 554.9 701.1 703.6 705.0 14534.8 15023.3 15106.3 15408.9 15432.8 12058.5 13097.5 13290.4 13613.5 13805.6 9687.5 11595.9 11653.5 11739.7 11848.0 3449.2 3660.8 3772.6 3884.3 3954.7 87158.7 89748.6 90476.1 90512.5 91432.0 10499.0 10515.3 10532.5 10762.4 10890.8 20358.9 20583.7 20868.8 20878.7 21233.5 7657.5 7738.5 7762.3 7776.5 7942.0 4245.1 4392.3 4400.2 4428.2 4583.9 3422.1 3524.8 3546.8 3547.7 3559.3 637.1 642.7 650.3 667.9 669.0 574.2 579.9 647.4 664.8 693.4 9097.9 9175.2 9193.4 9299.7 9571.1 625.5 670.4 675.2 684.9 687.4 4252.8 4502.5 4556.7 4579.7 4603.6 68082.7 68978.0 73388.0 90900.1 96459.0 56767.1 57619.4 58902.4 60198.4 78771.6 60475.7 69860.3 79592.6 83364.2 86081.0 9466.1 9663.4 9698.9 9711.8 9781.8 52587.5 52601.2 54223.2 54708.5 55517.7 62500.1 76023.9 78757.0 81210.6 81953.6 10813.5 11960.7 12141.0 12323.6 13130.5 14055.4 14240.4 14390.9 14670.6 14767.5 10588.4 13229.7 13365.9 13433.3 13620.7 13035.3 13368.0 13377.7 13577.1 13844.5 12883.3 13066.6 13078.3 13233.8 13516.8 9322.4 10864.8 11056.3 11703.6 11745.8 10118.9 10932.5 11038.9 11409.0 11666.4 08:44:03 INFO - PROCESS | 6731 | -------- Summary: end -------- 08:44:03 INFO - PROCESS | 6731 | 08:44:04 INFO - TEST-INFO | 6731: exit 0 08:44:04 INFO - TEST-OK | dromaeo_css | took 1120962ms 08:44:04 INFO - TEST-START | kraken 08:44:04 INFO - operating with platform_type : mac_ 08:44:04 INFO - Initialising browser for kraken test... 08:44:04 INFO - TEST-INFO | started process 6734 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/tmpJluLmw/profile http://localhost:59877/getInfo.html) 08:44:06 INFO - PROCESS | 6734 | __metrics Screen width/height:1600/1200 08:44:06 INFO - PROCESS | 6734 | colorDepth:24 08:44:06 INFO - PROCESS | 6734 | Browser inner width/height: 1024/689 08:44:06 INFO - PROCESS | 6734 | __metrics 08:44:06 INFO - PROCESS | 6734 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:44:06 INFO - PROCESS | 6734 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 205: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:44:07 INFO - TEST-INFO | 6734: exit 0 08:44:07 INFO - Browser initialized. 08:44:07 INFO - Running cycle 1/1 for kraken test... 08:44:07 INFO - TEST-INFO | started process 6735 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/tmpJluLmw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:44:19 INFO - PROCESS | 6735 | RSS: Main: 233041920 08:44:19 INFO - PROCESS | 6735 | 08:44:22 INFO - PROCESS | 6735 | Cycle 1(1): loaded http://localhost:59877/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59877/tests/kraken/driver.html?testName=audio-beat-detection) 08:44:22 INFO - PROCESS | 6735 | RSS: Main: 293060608 08:44:22 INFO - PROCESS | 6735 | 08:44:25 INFO - PROCESS | 6735 | Cycle 1(1): loaded http://localhost:59877/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59877/tests/kraken/driver.html?testName=audio-dft) 08:44:26 INFO - PROCESS | 6735 | RSS: Main: 294653952 08:44:26 INFO - PROCESS | 6735 | 08:44:29 INFO - PROCESS | 6735 | Cycle 1(1): loaded http://localhost:59877/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59877/tests/kraken/driver.html?testName=audio-fft) 08:44:30 INFO - PROCESS | 6735 | RSS: Main: 385765376 08:44:30 INFO - PROCESS | 6735 | 08:44:32 INFO - PROCESS | 6735 | Cycle 1(1): loaded http://localhost:59877/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59877/tests/kraken/driver.html?testName=audio-oscillator) 08:44:33 INFO - PROCESS | 6735 | RSS: Main: 289890304 08:44:33 INFO - PROCESS | 6735 | 08:44:35 INFO - PROCESS | 6735 | Cycle 1(1): loaded http://localhost:59877/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59877/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:44:36 INFO - PROCESS | 6735 | RSS: Main: 292753408 08:44:36 INFO - PROCESS | 6735 | 08:44:38 INFO - PROCESS | 6735 | Cycle 1(1): loaded http://localhost:59877/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59877/tests/kraken/driver.html?testName=imaging-darkroom) 08:44:39 INFO - PROCESS | 6735 | RSS: Main: 331022336 08:44:39 INFO - PROCESS | 6735 | 08:44:42 INFO - PROCESS | 6735 | Cycle 1(1): loaded http://localhost:59877/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59877/tests/kraken/driver.html?testName=imaging-desaturate) 08:44:42 INFO - PROCESS | 6735 | RSS: Main: 331390976 08:44:42 INFO - PROCESS | 6735 | 08:44:45 INFO - PROCESS | 6735 | Cycle 1(1): loaded http://localhost:59877/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59877/tests/kraken/driver.html?testName=json-parse-financial) 08:44:46 INFO - PROCESS | 6735 | RSS: Main: 330166272 08:44:46 INFO - PROCESS | 6735 | 08:44:47 INFO - PROCESS | 6735 | Cycle 1(1): loaded http://localhost:59877/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59877/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:44:48 INFO - PROCESS | 6735 | RSS: Main: 303067136 08:44:48 INFO - PROCESS | 6735 | 08:44:50 INFO - PROCESS | 6735 | Cycle 1(1): loaded http://localhost:59877/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59877/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:44:51 INFO - PROCESS | 6735 | RSS: Main: 305917952 08:44:51 INFO - PROCESS | 6735 | 08:44:53 INFO - PROCESS | 6735 | Cycle 1(1): loaded http://localhost:59877/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59877/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:44:54 INFO - PROCESS | 6735 | RSS: Main: 307625984 08:44:54 INFO - PROCESS | 6735 | 08:44:57 INFO - PROCESS | 6735 | Cycle 1(1): loaded http://localhost:59877/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59877/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:44:58 INFO - PROCESS | 6735 | RSS: Main: 320851968 08:44:58 INFO - PROCESS | 6735 | 08:45:01 INFO - PROCESS | 6735 | Cycle 1(1): loaded http://localhost:59877/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59877/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:45:01 INFO - PROCESS | 6735 | RSS: Main: 320516096 08:45:01 INFO - PROCESS | 6735 | 08:45:03 INFO - PROCESS | 6735 | Cycle 1(1): loaded http://localhost:59877/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59877/tests/kraken/driver.html?testName=ai-astar) 08:45:03 INFO - PROCESS | 6735 | RSS: Main: 364052480 08:45:03 INFO - PROCESS | 6735 | 08:45:03 INFO - PROCESS | 6735 | __start_tp_report 08:45:03 INFO - PROCESS | 6735 | _x_x_mozilla_page_load 08:45:03 INFO - PROCESS | 6735 | _x_x_mozilla_page_load_details 08:45:03 INFO - PROCESS | 6735 | |i|pagename|runs| 08:45:03 INFO - PROCESS | 6735 | |0;ai-astar;111;114;114;114;114;113;113;113;111;113 08:45:03 INFO - PROCESS | 6735 | |1;audio-beat-detection;154;115;113;153;129;116;101;137;181;145 08:45:03 INFO - PROCESS | 6735 | |2;audio-dft;183;184;186;187;183;182;187;184;183;186 08:45:03 INFO - PROCESS | 6735 | |3;audio-fft;83;59;59;93;62;58;59;61;104;83 08:45:03 INFO - PROCESS | 6735 | |4;audio-oscillator;90;93;109;59;90;98;130;99;82;91 08:45:03 INFO - PROCESS | 6735 | |5;imaging-gaussian-blur;89;87;89;86;89;87;90;88;89;87 08:45:03 INFO - PROCESS | 6735 | |6;imaging-darkroom;93;94;95;95;93;94;95;93;94;94 08:45:03 INFO - PROCESS | 6735 | |7;imaging-desaturate;83;83;79;84;83;84;89;85;81;96 08:45:03 INFO - PROCESS | 6735 | |8;json-parse-financial;59;56;57;57;56;57;56;55;55;55 08:45:03 INFO - PROCESS | 6735 | |9;json-stringify-tinderbox;65;76;68;79;99;105;103;73;72;73 08:45:03 INFO - PROCESS | 6735 | |10;stanford-crypto-aes;90;88;95;103;89;88;88;88;89;89 08:45:03 INFO - PROCESS | 6735 | |11;stanford-crypto-ccm;156;159;144;155;144;163;165;148;195;165 08:45:03 INFO - PROCESS | 6735 | |12;stanford-crypto-pbkdf2;174;174;175;172;233;182;174;175;175;175 08:45:03 INFO - PROCESS | 6735 | |13;stanford-crypto-sha256-iterative;67;69;68;66;66;66;66;67;67;67 08:45:03 INFO - PROCESS | 6735 | __end_tp_report 08:45:03 INFO - PROCESS | 6735 | __start_cc_report 08:45:03 INFO - PROCESS | 6735 | _x_x_mozilla_cycle_collect,2051 08:45:03 INFO - PROCESS | 6735 | __end_cc_report 08:45:03 INFO - PROCESS | 6735 | __startTimestamp1477842303630__endTimestamp 08:45:03 INFO - PROCESS | 6735 | 08:45:03 INFO - PROCESS | 6735 | ------- Summary: start ------- 08:45:03 INFO - PROCESS | 6735 | Number of tests: 14 08:45:03 INFO - PROCESS | 6735 | 08:45:03 INFO - PROCESS | 6735 | [#0] ai-astar Cycles:10 Average:113.00 Median:113.50 stddev:1.15 (1.0%) stddev-sans-first:0.97 08:45:03 INFO - PROCESS | 6735 | Values: 111.0 114.0 114.0 114.0 114.0 113.0 113.0 113.0 111.0 113.0 08:45:03 INFO - PROCESS | 6735 | 08:45:03 INFO - PROCESS | 6735 | [#1] audio-beat-detection Cycles:10 Average:134.40 Median:141.00 stddev:24.35 (17.3%) stddev-sans-first:24.78 08:45:03 INFO - PROCESS | 6735 | Values: 154.0 115.0 113.0 153.0 129.0 116.0 101.0 137.0 181.0 145.0 08:45:03 INFO - PROCESS | 6735 | 08:45:03 INFO - PROCESS | 6735 | [#2] audio-dft Cycles:10 Average:184.50 Median:185.00 stddev:1.84 (1.0%) stddev-sans-first:1.87 08:45:03 INFO - PROCESS | 6735 | Values: 183.0 184.0 186.0 187.0 183.0 182.0 187.0 184.0 183.0 186.0 08:45:03 INFO - PROCESS | 6735 | 08:45:03 INFO - PROCESS | 6735 | [#3] audio-fft Cycles:10 Average:72.10 Median:61.50 stddev:17.10 (27.8%) stddev-sans-first:17.67 08:45:03 INFO - PROCESS | 6735 | Values: 83.0 59.0 59.0 93.0 62.0 58.0 59.0 61.0 104.0 83.0 08:45:03 INFO - PROCESS | 6735 | 08:45:03 INFO - PROCESS | 6735 | [#4] audio-oscillator Cycles:10 Average:94.10 Median:90.50 stddev:18.17 (20.1%) stddev-sans-first:19.22 08:45:03 INFO - PROCESS | 6735 | Values: 90.0 93.0 109.0 59.0 90.0 98.0 130.0 99.0 82.0 91.0 08:45:03 INFO - PROCESS | 6735 | 08:45:03 INFO - PROCESS | 6735 | [#5] imaging-gaussian-blur Cycles:10 Average:88.10 Median:89.00 stddev:1.29 (1.4%) stddev-sans-first:1.32 08:45:03 INFO - PROCESS | 6735 | Values: 89.0 87.0 89.0 86.0 89.0 87.0 90.0 88.0 89.0 87.0 08:45:03 INFO - PROCESS | 6735 | 08:45:03 INFO - PROCESS | 6735 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:94.00 stddev:0.82 (0.9%) stddev-sans-first:0.78 08:45:03 INFO - PROCESS | 6735 | Values: 93.0 94.0 95.0 95.0 93.0 94.0 95.0 93.0 94.0 94.0 08:45:03 INFO - PROCESS | 6735 | 08:45:03 INFO - PROCESS | 6735 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:84.00 stddev:4.74 (5.6%) stddev-sans-first:4.99 08:45:03 INFO - PROCESS | 6735 | Values: 83.0 83.0 79.0 84.0 83.0 84.0 89.0 85.0 81.0 96.0 08:45:03 INFO - PROCESS | 6735 | 08:45:03 INFO - PROCESS | 6735 | [#8] json-parse-financial Cycles:10 Average:56.30 Median:56.50 stddev:1.25 (2.2%) stddev-sans-first:0.87 08:45:03 INFO - PROCESS | 6735 | Values: 59.0 56.0 57.0 57.0 56.0 57.0 56.0 55.0 55.0 55.0 08:45:03 INFO - PROCESS | 6735 | 08:45:03 INFO - PROCESS | 6735 | [#9] json-stringify-tinderbox Cycles:10 Average:81.30 Median:73.00 stddev:15.08 (20.7%) stddev-sans-first:14.79 08:45:03 INFO - PROCESS | 6735 | Values: 65.0 76.0 68.0 79.0 99.0 105.0 103.0 73.0 72.0 73.0 08:45:03 INFO - PROCESS | 6735 | 08:45:03 INFO - PROCESS | 6735 | [#10] stanford-crypto-aes Cycles:10 Average:90.70 Median:89.00 stddev:4.81 (5.4%) stddev-sans-first:5.09 08:45:03 INFO - PROCESS | 6735 | Values: 90.0 88.0 95.0 103.0 89.0 88.0 88.0 88.0 89.0 89.0 08:45:03 INFO - PROCESS | 6735 | 08:45:03 INFO - PROCESS | 6735 | [#11] stanford-crypto-ccm Cycles:10 Average:159.40 Median:161.00 stddev:14.83 (9.2%) stddev-sans-first:15.67 08:45:03 INFO - PROCESS | 6735 | Values: 156.0 159.0 144.0 155.0 144.0 163.0 165.0 148.0 195.0 165.0 08:45:03 INFO - PROCESS | 6735 | 08:45:03 INFO - PROCESS | 6735 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.90 Median:175.00 stddev:18.49 (10.6%) stddev-sans-first:19.44 08:45:03 INFO - PROCESS | 6735 | Values: 174.0 174.0 175.0 172.0 233.0 182.0 174.0 175.0 175.0 175.0 08:45:03 INFO - PROCESS | 6735 | 08:45:03 INFO - PROCESS | 6735 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:0.99 (1.5%) stddev-sans-first:1.05 08:45:03 INFO - PROCESS | 6735 | Values: 67.0 69.0 68.0 66.0 66.0 66.0 66.0 67.0 67.0 67.0 08:45:03 INFO - PROCESS | 6735 | -------- Summary: end -------- 08:45:03 INFO - PROCESS | 6735 | 08:45:04 INFO - TEST-INFO | 6735: exit 0 08:45:04 INFO - TEST-OK | kraken | took 60284ms 08:45:04 INFO - SUITE-END | took 1181s 08:45:04 INFO - Completed test suite (00:19:41) 08:45:04 INFO - javascript benchmark 08:45:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3253.7234335081516, 3258.16916686245, 3297.3240485700426, 3297.9835100824494, 3307.1070810881065, 5566.192902029214, 5636.943630563693, 5678.772849086036, 5691.060974939141, 5714.857128571788, 5631.57763167762, 5660.264165658469, 5670.291213598301, 5701.144828275761, 5721.628094173876, 5027.472115427882, 5103.974480127594, 5111.361079865017, 5150.587952963754, 5172.560332371753, 5261.947380526203, 5271.525562699357, 5281.735913204344, 5303.23103150043, 5338.119210330291, 5095.719735414587, 5227.372715274153, 5242.292290540767, 5265.578753699714, 5338.546223571001, 5144.639875208737, 5187.636865419422, 5195.922061169085, 5206.583473322125, 5209.973950130263, 5129.9743501282255, 5153.510416510426, 5172.069027575035, 5185.611079169077, 5201.713905735183, 5607.214989901417, 5637.689927054011, 5643.153526970947, 5643.971780141073, 5654.773809047633, 6096.272074548579, 6103.519504250422, 6127.20346354971, 6141.692915354214, 6143.508519318444, 6091.25479055427, 6146.815595532141, 6182.536309776785, 6188.566800323979, 6197.752089916398, 4786.688865223784, 5631.267935168402, 5701.600887937846, 5721.370649228582, 5729.799457018984, 6152.046432802923, 6231.563790534619, 6246.75012999484, 6260.342664020303, 6261.467775239064, 6068.544859135494, 6085.726142323518, 6101.60339577926, 6128.233970753656, 6128.693565321805, 6101.084837274356, 6111.43980788641, 6119.868162944292, 6130.245269657028, 6208.379162083846, 6112.047575716847, 6138.143176733788, 6148.077788331786, 6149.7847575334645, 6168.814935551941, 6157.0, 6169.259688837278, 6172.506199504029, 6180.443760061616, 6186.350433204539, 4809.422869255712, 4852.781624826891, 4856.053069651384, 4869.172240719086, 4900.240462728283, 4315.892102697395, 4337.7180483268485, 4343.19650864591, 4343.218220720238, 4351.129774045204, 5579.107342825128, 5591.440855914458, 5591.552675785927, 5598.384177740445, 5624.240727501817, 6181.258249010148, 6183.948728716129, 6192.504599632019, 6201.968990155111, 6214.4407003369915, 5497.367802702661, 5552.861178470571, 5560.193771903051, 5570.442955704478, 5622.184783206412, 5312.150055991023, 5333.200019997032, 5346.518813306821, 5351.919721204185, 5363.168708850135, 6253.937460625336, 6278.309385967631, 6278.340774218641, 6281.874362512633, 6290.0879372491645, 6101.084837274444, 6101.217066730222, 6123.724432400463, 6202.317745047951, 6251.843703907439, 6194.132821404917, 6224.564280500322, 6229.065640154013, 6257.217847769029, 6268.843278918063, 6101.542384321105, 6121.8980583495395, 6129.387061293835, 6140.263168419819, 6144.2319710036245, 5025.698458092526, 6105.389461053859, 6199.16311297969, 6236.719347629277, 6245.250569931455, 5967.9701601491715, 6022.789202378071, 6037.154798328325, 6080.331163572092, 6118.345080237391, 6050.727717252822, 6092.603980741238, 6114.082887566723, 6118.541109416722, 6141.877162456636, 5707.4577915098, 5943.435373639497, 5955.493783028491, 5976.820695379146, 5980.790672326448, 5958.404159584007, 5990.6705131218205, 6001.549883758648, 6050.788222412194, 6125.111858780541, 5872.882542349043, 5918.260217472816, 5921.792737254175, 5933.110033495011, 5933.762649494144], "value": 5639.225225748414, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.696278937296, 497.7212760853921, 503.3908970146125, 507.7182163899036, 520.591335801396, 40799.184016319654, 42120.57879421209, 42209.944751381234, 42389.72830815076, 42854.35718464226, 43132.78433607835, 43338.783306083504, 43378.56621433785, 43396.0, 43688.12623747531, 9452.432854028762, 9638.036196380375, 9645.03549645037, 9645.758856028586, 9645.903540964606, 9439.339246252764, 9526.571304291288, 9540.761480962961, 9542.427454352726, 9548.999999999965, 8209.507429554216, 9509.80980380392, 9567.138957493824, 9570.37792543486, 9585.712428627152, 9488.146066853982, 9552.952235238814, 9553.761155971157, 9571.664991725256, 9572.0, 9384.390014649027, 9406.717798465988, 9448.291378146667, 9460.57427415768, 9482.762930926781, 40719.796401017804, 41095.17809643791, 41119.7944010278, 41208.79395603033, 41287.76136715903, 3281.8030918144987, 3349.949750753741, 3443.2941247044296, 3520.524729161556, 3591.676749092594, 29614.92695511315, 30481.0, 31691.84154079238, 31878.840605796824, 32120.839395803105, 2747.5968468244546, 3147.244661281276, 3255.755818313612, 3297.142742886843, 3360.3615313090436, 475810.0, 478426.6078669584, 481026.0, 482551.9999999965, 495075.0, 59718.40281597128, 60318.69840650768, 60524.697376512835, 60624.696876515336, 60649.69675151596, 62374.0643890342, 62419.375806241354, 62448.687756560925, 62493.06260406098, 62532.0, 15595.532134035997, 15724.606884827743, 15829.445969391016, 15832.28754706041, 15909.602259943362, 11762.117841161948, 12284.385780711107, 12310.692232694255, 12325.075619328407, 12334.136610437363, 15652.843471565366, 16352.83647163537, 16381.59046023835, 16381.836181638031, 16460.917695411445, 5877.2074517271785, 7014.1943222711325, 7121.82195445118, 7170.533915295489, 7190.568565886064, 749.4716225061319, 892.848215803302, 934.4533447933036, 989.2975987049132, 1085.6417382263835, 30083.247918801768, 30213.0, 30270.54594181089, 30488.08535743931, 30864.0, 994.2642444591095, 1083.864516935383, 1183.621241202807, 1216.7384012437192, 1230.501646833034, 13712.89982704812, 16463.094529800976, 16910.915445422695, 16981.83018169827, 17765.555861103578, 628358.0, 630184.0, 630848.0, 632183.0, 632185.0, 60103.39896600978, 60137.79724405488, 60222.698886506165, 60346.698266508385, 60525.69737151286, 1239.4918083585687, 1275.9298238596966, 1366.1120271823395, 1391.200059965524, 1409.1404243411512, 26570.601440978015, 30403.37537555559, 30900.53649195219, 31090.22274443157, 31727.0, 49606.751966239935, 50372.49627503678, 50427.0, 50442.0, 50493.242601360274, 517871.41064295947, 521992.0, 522578.0, 523377.0, 523924.0, 23417.531649366578, 26140.34649133787, 26848.731512684622, 27283.317917051438, 27572.0, 47497.762511187226, 47850.62614042459, 48081.79795505141, 48619.27071094007, 48710.26934595913, 39285.41071883867, 40005.59994400019, 40651.390229145996, 40736.0, 41006.79496602498, 38743.612563874, 38915.80542097271, 38927.80536097301, 40521.98695032648, 40724.59275407327, 38171.42742858804, 39035.02412439712, 39151.804240978614, 39495.605043949196, 39566.2086758269, 31081.68918310788, 33577.83211083929, 33968.490472643425, 34182.0, 34626.0, 25154.0, 25407.0, 25559.23322300334, 25877.223683289532, 26047.609285861105], "value": 19873.38094546498, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.2847793119962, 349.43391705437165, 361.53001098571866, 369.1472698067464, 400.80560927949955, 14811.481598144053, 14992.025518341314, 15606.843931560681, 15859.127747973856, 18934.10658934106, 19986.0, 20492.0, 20912.581748365024, 21127.154913803428, 21177.57644847106, 5731.111677689954, 5941.405859414046, 6012.909806352908, 6109.908351374733, 6115.0, 3311.8840840570583, 5846.269216347957, 6562.179727534059, 6673.733050677967, 6732.259451460335, 6563.507736919726, 6652.201735791712, 6652.70062847173, 6666.600003999775, 6678.131842860422, 5688.544916406699, 6024.2770867495965, 6066.787662431793, 6111.388861113853, 6116.388361163892, 5147.562457191143, 5413.485718856702, 5819.010768169422, 5858.12128180772, 5865.4134586541, 25165.496690066095, 25206.991720331163, 25223.36941576457, 25482.980680772747, 25719.228423147335, 2063.432556047084, 2130.211821625993, 2151.655735082379, 2243.4054975431523, 2256.9617975731185, 9967.75080622983, 10231.386116833013, 10524.94750524949, 10542.314749541332, 11326.433678316134, 1702.0978881192987, 1790.7851057873008, 1798.6672465593776, 1798.7858195717838, 1804.7645385194253, 108550.3717444239, 119463.0, 120839.0, 122208.0, 125029.0, 11307.660770176908, 11311.547538098384, 11383.544658213745, 11524.711882203012, 11716.882831171579, 14410.639734006523, 14424.278786060655, 14447.422103115969, 14465.710685786436, 14470.987030907945, 7992.760217193493, 8242.216989386, 8297.211764882326, 8479.19447652472, 8499.660013599387, 8185.222403871741, 8190.83618327642, 8210.466319689202, 8364.247217750431, 8368.916310836814, 4395.956040439619, 4454.398656181439, 4477.753723545236, 4538.2738761797955, 4602.861914142581, 1846.18767742193, 1892.9366989980988, 1898.6392585409005, 1901.4580844459263, 1907.2275728330048, 820.8727647214691, 846.9709303196646, 884.9911500885038, 943.7263193673758, 987.5508738328984, 19781.0, 20565.69151462729, 21235.256766013117, 21298.893505532375, 21514.439822462817, 890.3100097424418, 943.9622415103319, 945.8061097475002, 948.5913418573413, 950.3775027357092, 8454.82797557774, 9959.751006224902, 10942.617008404668, 11053.226274160732, 11180.552777888794, 499581.0, 531511.3424432853, 579011.0, 583952.0, 590806.0, 126695.36652316863, 126784.0, 127336.0, 127779.05362902085, 132647.0, 827.8137419080658, 958.113744786084, 990.6285143071349, 991.9305648604673, 994.945278009702, 14200.78698819498, 18521.722174167127, 18745.90627046856, 18886.622267554845, 19190.232390704776, 45650.77174614106, 50724.74637626788, 52829.207561885836, 53456.0, 54694.45305546893, 75190.0, 76088.85866712114, 77959.0, 85651.57174214089, 88612.0, 7043.823904402431, 7339.522931009467, 7702.653380597999, 7881.802954926173, 8285.585720714062, 10518.421486818299, 12304.81542776877, 12704.793044660742, 13295.534656286984, 13454.663633409244, 9427.717168484956, 10016.0, 11605.0, 11858.703532411759, 11940.16418850707, 11074.778504430027, 11402.657920262405, 11422.200445968703, 11508.712282193012, 11703.180777345504, 9491.525423728925, 11656.825147622962, 11942.761144776881, 12002.339871306813, 12139.75720485603, 6073.544484163617, 7222.313880181375, 7311.67097480604, 7464.664090115849, 7525.73659921922, 9522.0, 11387.032102271069, 11520.308781473139, 11731.88268117308, 11781.23421977603], "value": 9405.745453599498, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.738361019601, 1357.6062941746893, 1374.5738820965507, 1689.3833750680997, 1923.6056608395272, 7482.962585187073, 7894.644740986655, 8167.428280020394, 8470.745877623665, 8472.91527084729, 8315.542645154514, 8344.0, 8459.365547583926, 8460.91539084609, 8471.322294216463, 2273.329367836492, 2292.9541409171807, 2307.3424074138875, 2307.7346105197867, 2309.4688221709016, 2279.794818466338, 2320.8491448055906, 2336.7312759032757, 2340.0, 2340.6372012338115, 2269.0, 2281.6691579721014, 2308.549832782608, 2315.6873822033976, 2316.9420764480856, 1839.5677015901254, 1876.408931186672, 2265.139247086105, 2282.8173746100274, 2284.4288927768057, 2269.273832373641, 2290.4617414907484, 2290.576243394977, 2295.4490922178725, 2297.8506397084134, 8326.167383261685, 8366.83266334676, 8423.157684231528, 8426.410151289412, 8427.0, 4941.45643979162, 5033.169527028036, 5084.855907420838, 5105.412877519058, 5109.0, 3547.0, 3619.3304238715923, 3635.1093981974323, 3639.6542328478754, 3646.9452958205384, 996.9152622027136, 1015.1624909449733, 1026.3226270661328, 1041.6614600254948, 1043.0664555223034, 10428.800687920835, 10740.140788736882, 10806.837897431546, 10882.836757448644, 10896.346219226873, 4287.935680964788, 4379.627731642782, 4488.438945131858, 4648.790804413809, 4692.296155576691, 8671.739847804576, 8710.82578348442, 8731.563421828883, 8738.650453981896, 8749.65001399937, 5108.310378098983, 5111.182210846247, 5112.3865136183895, 5114.923276150861, 5115.718635475085, 3764.5858955514873, 4254.361845723104, 4735.337052812609, 4794.089123066676, 4807.990322032412, 3463.41122009259, 3541.29174165168, 3570.892873213808, 3607.152319204985, 3616.6383361663625, 1022.4018948914968, 1078.1859695929522, 1093.484984535565, 1101.9504122314515, 1102.1458369763336, 857.3012994409536, 858.52780970466, 904.2856143646445, 910.5902343945252, 937.6202637931649, 5608.411116832755, 5723.227364305849, 5904.881902361929, 5937.910931336033, 5948.762049518057, 862.6376921692904, 892.901780804111, 901.8241442918699, 936.6159874451442, 953.5041778275258, 3819.52255968004, 3873.360895452275, 3874.670652994471, 3981.0843505993785, 4046.2109888572036, 9365.578548965314, 9442.347589171868, 9881.308308418342, 10033.046860548237, 10141.94929025365, 10022.298439109192, 10100.242481813893, 10112.0, 10117.190624750003, 10124.443155626512, 7476.364509016795, 7823.061232652004, 7828.412869034732, 7835.451518393772, 7946.880796788053, 4364.519902810752, 5608.943910561005, 5790.392008839011, 5805.158252053344, 5876.471117599436, 9075.455472671661, 9127.178553930178, 9214.0, 9328.720138395858, 9435.481048542397, 9324.254059675073, 9389.718308450758, 9474.0, 9548.0, 9583.808323833624, 7243.855122897618, 7473.476856619985, 7493.625318734151, 7803.765887023398, 7959.482633628795, 6500.674966251763, 7900.209979002284, 8020.0375954885785, 8087.191280871866, 8128.512289262663, 7083.645817709197, 7257.310555497219, 7285.672144753605, 7302.087239095114, 7314.049173607397, 5951.55363347742, 6129.0, 7124.287571242834, 7126.714931402686, 7178.425725941807, 6485.0, 6600.042993765781, 6697.330266973264, 6710.094137291403, 6718.0594716738715, 4847.127517046824, 4889.584385327287, 4916.237983112624, 4958.925616115834, 5025.221090730943, 4854.466008739107, 4860.1251774680895, 4950.0, 5012.924806127838, 5026.095302845377], "value": 4370.1923156455105, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [245.9139301244564, 296.9228000719813, 304.24091890732626, 310.26467272564025, 314.28656948726376, 5090.770915308815, 5100.285959965603, 5113.437521872591, 5127.564157046646, 5149.922751158736, 4101.569335219804, 4459.353393757911, 4679.555442232982, 4824.0, 5051.5453609175165, 3865.439511270864, 3904.707146963975, 3929.8231579578987, 3943.3099207638693, 3958.5447673517483, 3418.5897692276835, 3887.0, 3923.5880232575455, 3930.272899513585, 3955.9208815823667, 3186.3431015871734, 3450.7584469087174, 3616.367135751246, 3889.4165875118815, 3905.668018218455, 3182.411253918033, 3533.6113027567076, 3555.537780088585, 3790.54513458384, 3869.9032524186837, 3405.6083550391772, 3407.3185362927265, 3408.437607794711, 3418.1112910643164, 3421.8802341917913, 4069.3285607874664, 4947.526236881559, 4968.354113965162, 4969.751512424365, 4970.776315065831, 3851.84592616295, 3872.4965754452023, 4256.489221293434, 4411.382406463098, 4428.656364444856, 4422.270325396305, 4865.929495510982, 4987.875303117415, 5225.921611175798, 5254.65844720092, 3149.401613693391, 3727.4967879336214, 3741.0, 3749.6812770914503, 3769.340365436065, 5275.841724748264, 5561.499465048165, 5592.217089607459, 5597.3003374578175, 5604.551635869121, 3157.3053928135773, 3755.887323380303, 3756.0, 3774.8490060397826, 3789.3368660484307, 3829.5978922213326, 3830.5020347354666, 3838.0, 3838.251540949488, 3839.30892439358, 3900.1419687668667, 4162.063535704442, 4374.978125109354, 4422.14270006598, 4427.623651989553, 4991.700497970133, 5293.761780719877, 5315.681059136464, 5324.201369794562, 5340.732963351817, 1292.7589514066412, 1310.5544115000946, 1314.3625341709264, 1320.3134370127673, 1321.45159758701, 799.3245707377257, 801.382935139939, 826.7974346285199, 868.5743985447085, 874.5190145419994, 4177.892858392528, 4767.547083027107, 4835.347228124158, 4862.854114376574, 4973.179425394769, 4817.927731084036, 5272.710000949908, 5301.9396120776, 5417.539509141767, 5451.48210919962, 4127.6491498222385, 4684.367610372624, 4920.606351491872, 4950.0, 4981.750912454363, 2528.759767822054, 2563.9359016024378, 2743.670759508872, 2846.672632647272, 2886.7834912381654, 4776.307435421844, 5330.947585179733, 5432.293801805819, 5673.574481913873, 5706.457886500776, 4907.950920490821, 5189.818356357509, 5402.972985135049, 5549.334079910356, 5606.439356064442, 4824.758762061883, 5767.653940763567, 5811.82564523065, 5816.47651711348, 5830.737616807254, 4615.23848564983, 5323.334583177103, 5352.866178345573, 5500.367457742411, 5527.778888844401, 5166.2508936203285, 5394.628357276702, 5428.978195169374, 5436.782528698966, 5467.644603100785, 5703.7433315500075, 5733.455321744427, 5746.195532625519, 5789.363170051375, 5823.883522329615, 3330.400527905, 3424.845881935269, 3709.27669104522, 3734.439834024918, 3737.458068580095, 5036.773345199402, 5091.974540127424, 5406.675599464044, 5436.945630543644, 5482.506574408322, 4900.534449227318, 5369.543588794996, 5377.623566350318, 5411.972940135274, 5415.945840541702, 4466.486354069194, 4939.407271127344, 4944.456109827988, 4948.678335908155, 5464.207689885024, 5213.452587478412, 5267.2889159962915, 5276.551493123127, 5283.30168881792, 5296.219484770526, 4246.511651160033, 5042.571381432619, 5206.817761378334, 5231.895362092813, 5342.599305052215, 4341.327094300388, 4645.187092258909, 4687.554682305175, 5238.345206849144, 5274.762635681327], "value": 3885.9092040798187, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [743.7619961612286, 778.8364443466871, 782.7146373604514, 798.3812545277409, 821.2075347289866, 14360.569182924502, 15136.091834489924, 16154.676906461878, 16785.83214167861, 16950.491485255432, 15044.247787610602, 15284.923575382107, 15423.997440166382, 15447.45933892314, 15473.226338683076, 862.1249431826701, 869.2654706772767, 877.754228815931, 896.0367604824839, 898.6989358564889, 584.7982446056104, 720.0279622509622, 865.9350548708841, 891.0198781340538, 894.9239314658262, 903.9728808135767, 942.7548837302282, 952.2477242978039, 955.2358113509165, 955.5174636808403, 909.2998391238742, 909.5179554835947, 943.3113826906397, 944.7449188719077, 947.327397547742, 534.7647035304453, 554.8917960997607, 701.1340993872564, 703.5567592416745, 704.968276427562, 14534.781978270334, 15023.323950422257, 15106.320215590325, 15408.922955385264, 15432.845671543366, 12058.457369418396, 13097.47610095595, 13290.401931913088, 13613.455461781517, 13805.585832425044, 9687.515624218831, 11595.884041159648, 11653.533858645644, 11739.7065073373, 11847.992920601759, 3449.189440481485, 3660.798656073915, 3772.603876592973, 3884.261990221849, 3954.7231693781446, 87158.69261961013, 89748.55125724395, 90476.09523904743, 90512.54743726306, 91432.0, 10499.0, 10515.26393152472, 10532.473376331152, 10762.40806755636, 10890.782184356427, 20358.898205508878, 20583.691244631344, 20868.79131208699, 20878.686819697716, 21233.469163271042, 7657.46397752152, 7738.458307918391, 7762.340201082971, 7776.494527855784, 7942.046954365397, 4245.12974840157, 4392.253316936129, 4400.207962566705, 4428.22506061438, 4583.931241031387, 3422.1273575238156, 3524.753267271318, 3546.7517273790586, 3547.6629720176543, 3559.288142371536, 637.1430426076919, 642.6754488983033, 650.3204151661525, 667.9298673639295, 669.0499490723088, 574.1875246526118, 579.9362070172277, 647.3655817299074, 664.7772996046282, 693.4036728413561, 9097.863532047024, 9175.22010629104, 9193.356465047382, 9299.72100836976, 9571.138597526256, 625.5183508698277, 670.4401824476519, 675.2066322071546, 684.852756657321, 687.4156966578369, 4252.766097864585, 4502.527234640315, 4556.65825063115, 4579.702319349231, 4603.631709463235, 68082.65958670175, 68978.0, 73388.0, 90900.09099909049, 96459.0, 56767.14849277264, 57619.423805761406, 58902.410975889696, 60198.39801601928, 78771.60614196892, 60475.69762151161, 69860.30139698538, 79592.6020369906, 83364.16635833564, 86081.0, 9466.05339466048, 9663.361586438408, 9698.9030109698, 9711.84419713837, 9781.804363912825, 52587.47412525979, 52601.21098183607, 54223.18665220104, 54708.4934015407, 55517.72241138769, 62500.06249906164, 76023.8596421043, 78757.0, 81210.59394702989, 81953.59023204846, 10813.485617174607, 11960.699707876314, 12141.0, 12323.568675096329, 13130.474781008652, 14055.367508462348, 14240.430382784572, 14390.928045359706, 14670.559883203521, 14767.483138090116, 10588.364698118137, 13229.669258268621, 13365.933170334474, 13433.26117063571, 13620.65948351299, 13035.283059431822, 13368.0, 13377.66555836112, 13577.117487363685, 13844.515441959484, 12883.291418972047, 13066.608001759962, 13078.28069456189, 13233.80149297742, 13516.797248041485, 9322.440653560809, 10864.77259720422, 11056.336619802838, 11703.648890533297, 11745.823812642988, 10118.898811011795, 10932.508037138507, 11038.889611104107, 11409.0, 11666.358350290815], "value": 7308.494490672435, "unit": "score"}], "name": "dromaeo_css", "value": 7125.12632759376, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 114.0, 114.0, 114.0, 114.0, 113.0, 113.0, 113.0, 111.0, 113.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 115.0, 113.0, 153.0, 129.0, 116.0, 101.0, 137.0, 181.0, 145.0], "value": 134.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 186.0, 187.0, 183.0, 182.0, 187.0, 184.0, 183.0, 186.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 59.0, 93.0, 62.0, 58.0, 59.0, 61.0, 104.0, 83.0], "value": 72.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 93.0, 109.0, 59.0, 90.0, 98.0, 130.0, 99.0, 82.0, 91.0], "value": 94.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 89.0, 86.0, 89.0, 87.0, 90.0, 88.0, 89.0, 87.0], "value": 88.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 95.0, 93.0, 94.0, 95.0, 93.0, 94.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 79.0, 84.0, 83.0, 84.0, 89.0, 85.0, 81.0, 96.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 56.0, 57.0, 57.0, 56.0, 57.0, 56.0, 55.0, 55.0, 55.0], "value": 56.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 76.0, 68.0, 79.0, 99.0, 105.0, 103.0, 73.0, 72.0, 73.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 95.0, 103.0, 89.0, 88.0, 88.0, 88.0, 89.0, 89.0], "value": 90.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 159.0, 144.0, 155.0, 144.0, 163.0, 165.0, 148.0, 195.0, 165.0], "value": 159.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 174.0, 175.0, 172.0, 233.0, 182.0, 174.0, 175.0, 175.0, 175.0], "value": 180.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 68.0, 66.0, 66.0, 66.0, 66.0, 67.0, 67.0, 67.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1500.4000000000003, "alertThreshold": 2.0}]} 08:45:04 INFO - Return code: 0 08:45:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:45:04 INFO - # TBPL SUCCESS # 08:45:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:45:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:45:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:45:04 INFO - 2016-10-30 08:45:04.924 screenresolution[6739:130425] starting screenresolution argv=screenresolution get 08:45:04 INFO - 2016-10-30 08:45:04.927 screenresolution[6739:130425] Display 0: 1600x1200x32@60 08:45:04 INFO - 2016-10-30 08:45:04.933 screenresolution[6740:130428] starting screenresolution argv=screenresolution list 08:45:04 INFO - Available Modes on Display 0 08:45:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:45:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:45:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:45:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:45:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:45:05 INFO - Intel Iris: 08:45:05 INFO - Chipset Model: Intel Iris 08:45:05 INFO - Type: GPU 08:45:05 INFO - Bus: Built-In 08:45:05 INFO - VRAM (Dynamic, Max): 1536 MB 08:45:05 INFO - Vendor: Intel (0x8086) 08:45:05 INFO - Device ID: 0x0a2e 08:45:05 INFO - Revision ID: 0x0009 08:45:05 INFO - Displays: 08:45:05 INFO - Display: 08:45:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:45:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:45:05 INFO - Display Serial Number: 1600x1200 60 08:45:05 INFO - Main Display: Yes 08:45:05 INFO - Mirror: Off 08:45:05 INFO - Online: Yes 08:45:05 INFO - Rotation: Supported 08:45:05 INFO - Return code: 0 08:45:05 INFO - Running post-action listener: _resource_record_post_action 08:45:05 INFO - [mozharness: 2016-10-30 15:45:05.174678Z] Finished run-tests step (success) 08:45:05 INFO - Running post-run listener: _resource_record_post_run 08:45:05 INFO - Total resource usage - Wall time: 1217s; CPU: 54.0%; Read bytes: 647680; Write bytes: 709297664; Read time: 9; Write time: 2000 08:45:05 INFO - TinderboxPrint: CPU usage
53.6% 08:45:05 INFO - TinderboxPrint: I/O read bytes / time
647,680 / 9 08:45:05 INFO - TinderboxPrint: I/O write bytes / time
709,297,664 / 2,000 08:45:05 INFO - TinderboxPrint: CPU idle
2,261.9 (46.4%) 08:45:05 INFO - TinderboxPrint: CPU system
979.6 (20.1%) 08:45:05 INFO - TinderboxPrint: CPU user
1,628.1 (33.4%) 08:45:05 INFO - TinderboxPrint: Swap in / out
406,671,360 / 0 08:45:05 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 512; Write bytes: 409282560; Read time: 1; Write time: 877 08:45:05 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 536576; Write bytes: 298499584; Read time: 5; Write time: 1114 08:45:05 INFO - Running post-run listener: _upload_blobber_files 08:45:05 INFO - Blob upload gear active. 08:45:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:45:05 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 08:45:05 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'] 08:45:05 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 08:45:06 INFO - (blobuploader) - INFO - Open directory for files ... 08:45:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:45:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:45:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:45:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:45:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:45:07 INFO - (blobuploader) - INFO - Done attempting. 08:45:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:45:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:45:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:45:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:45:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:45:08 INFO - (blobuploader) - INFO - Done attempting. 08:45:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:45:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:45:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:45:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:45:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:45:10 INFO - (blobuploader) - INFO - Done attempting. 08:45:10 INFO - (blobuploader) - INFO - Iteration through files over. 08:45:10 INFO - Return code: 0 08:45:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:45:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:45:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d06867275099168b6dc36b2215cc96da8ed485654fb40957447f1708d665c1bd342d8e200c448b37bd0d36c8d92fef08afd338404e2f731aae1b5467177f1e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5618a9039600117eb0e8ec99de4dec14990d458579c19796fbc01cd18b4992f81198515c11f8f4cd965dedf896f419e07a1442ec69756c81d52c61991c1104d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e464174f4cd77c518a6ce9f8693dd717d251417d7e90774b2d54a0aa63c4ac586c97dd64de14d217f338090b79171f70223a029999c7958c9ee47f52eef6af2"} 08:45:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:45:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:45:10 INFO - Contents: 08:45:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d06867275099168b6dc36b2215cc96da8ed485654fb40957447f1708d665c1bd342d8e200c448b37bd0d36c8d92fef08afd338404e2f731aae1b5467177f1e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5618a9039600117eb0e8ec99de4dec14990d458579c19796fbc01cd18b4992f81198515c11f8f4cd965dedf896f419e07a1442ec69756c81d52c61991c1104d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e464174f4cd77c518a6ce9f8693dd717d251417d7e90774b2d54a0aa63c4ac586c97dd64de14d217f338090b79171f70223a029999c7958c9ee47f52eef6af2"} 08:45:10 INFO - Running post-run listener: copy_logs_to_upload_dir 08:45:10 INFO - Copying logs to upload dir... 08:45:10 INFO - mkdir: /builds/slave/test/build/upload/logs 08:45:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.125921 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-10-30 08:45:10.665143) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-30 08:45:10.668949) ========= 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.EqoJychxy5/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.01Q6HRRXpg/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d06867275099168b6dc36b2215cc96da8ed485654fb40957447f1708d665c1bd342d8e200c448b37bd0d36c8d92fef08afd338404e2f731aae1b5467177f1e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5618a9039600117eb0e8ec99de4dec14990d458579c19796fbc01cd18b4992f81198515c11f8f4cd965dedf896f419e07a1442ec69756c81d52c61991c1104d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e464174f4cd77c518a6ce9f8693dd717d251417d7e90774b2d54a0aa63c4ac586c97dd64de14d217f338090b79171f70223a029999c7958c9ee47f52eef6af2"} build_url:https://queue.taskcluster.net/v1/task/C3oggZwnSBy2uISkkxPHiw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011333 build_url: 'https://queue.taskcluster.net/v1/task/C3oggZwnSBy2uISkkxPHiw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d06867275099168b6dc36b2215cc96da8ed485654fb40957447f1708d665c1bd342d8e200c448b37bd0d36c8d92fef08afd338404e2f731aae1b5467177f1e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5618a9039600117eb0e8ec99de4dec14990d458579c19796fbc01cd18b4992f81198515c11f8f4cd965dedf896f419e07a1442ec69756c81d52c61991c1104d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e464174f4cd77c518a6ce9f8693dd717d251417d7e90774b2d54a0aa63c4ac586c97dd64de14d217f338090b79171f70223a029999c7958c9ee47f52eef6af2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-30 08:45:10.703634) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-30 08:45:10.703980) ========= 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.EqoJychxy5/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.01Q6HRRXpg/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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.005369 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-30 08:45:10.753468) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-30 08:45:10.753790) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-30 08:45:10.754172) ========= ========= Total master_lag: 0.18 =========