builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0162 starttime: 1475153914.83 results: success (0) buildid: 20160929025020 builduid: 54f8ee3756a349de9a4fc6fc8d5bcf99 revision: dc21ccd6a304df32cdec43a71f01bdf80288ee98 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 05:58:34.830771) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 05:58:34.831231) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 05:58:34.831515) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 05:58:34.859860) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 05:58:34.860307) ========= 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.UJeCwHY4LX/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.gudp4MMn5F/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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-09-29 05:58:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 26.6M=0s 2016-09-29 05:58:34 (26.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.118032 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 05:58:35.000706) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 05:58:35.001063) ========= 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.UJeCwHY4LX/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.gudp4MMn5F/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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.037389 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 05:58:35.060792) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-29 05:58:35.061196) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev dc21ccd6a304df32cdec43a71f01bdf80288ee98 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev dc21ccd6a304df32cdec43a71f01bdf80288ee98 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UJeCwHY4LX/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.gudp4MMn5F/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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-09-29 05:58:35,144 truncating revision to first 12 chars 2016-09-29 05:58:35,144 Setting DEBUG logging. 2016-09-29 05:58:35,144 attempt 1/10 2016-09-29 05:58:35,144 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/dc21ccd6a304?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-29 05:58:37,235 unpacking tar archive at: fx-team-dc21ccd6a304/testing/mozharness/ program finished with exit code 0 elapsedTime=2.374140 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-29 05:58:37.459695) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 05:58:37.460060) ========= script_repo_revision: dc21ccd6a304df32cdec43a71f01bdf80288ee98 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 05:58:37.460506) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 05:58:37.460781) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 05:58:37.487857) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-09-29 05:58:37.488281) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UJeCwHY4LX/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.gudp4MMn5F/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 05:58:37 INFO - MultiFileLogger online at 20160929 05:58:37 in /builds/slave/test 05:58:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:58:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:58:37 INFO - {'append_to_log': False, 05:58:37 INFO - 'base_work_dir': '/builds/slave/test', 05:58:37 INFO - 'blob_upload_branch': 'Fx-Team', 05:58:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:58:37 INFO - 'branch': 'Fx-Team', 05:58:37 INFO - 'buildbot_json_path': 'buildprops.json', 05:58:37 INFO - 'config_files': ('talos/mac_config.py',), 05:58:37 INFO - 'default_actions': ('clobber', 05:58:37 INFO - 'read-buildbot-config', 05:58:37 INFO - 'download-and-extract', 05:58:37 INFO - 'populate-webroot', 05:58:37 INFO - 'create-virtualenv', 05:58:37 INFO - 'install', 05:58:37 INFO - 'run-tests'), 05:58:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:58:37 INFO - 'download_minidump_stackwalk': True, 05:58:37 INFO - 'download_symbols': 'ondemand', 05:58:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:58:37 INFO - 'tooltool.py': '/tools/tooltool.py', 05:58:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:58:37 INFO - '/tools/misc-python/virtualenv.py')}, 05:58:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:58:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:58:37 INFO - 'installer_path': 'installer.exe', 05:58:37 INFO - 'log_level': 'info', 05:58:37 INFO - 'log_name': 'talos', 05:58:37 INFO - 'log_to_console': True, 05:58:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:58:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:58:37 INFO - 'opt_config_files': (), 05:58:37 INFO - 'pip_index': False, 05:58:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:58:37 INFO - 'cmd': ('bash', 05:58:37 INFO - '-c', 05:58:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:58:37 INFO - 'enabled': True, 05:58:37 INFO - 'halt_on_failure': False, 05:58:37 INFO - 'name': 'check_screen_resolution'},), 05:58:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:58:37 INFO - 'cmd': ('bash', 05:58:37 INFO - '-c', 05:58:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:58:37 INFO - 'enabled': True, 05:58:37 INFO - 'halt_on_failure': False, 05:58:37 INFO - 'name': 'check_screen_resolution'},), 05:58:37 INFO - 'run_cmd_checks_enabled': True, 05:58:37 INFO - 'sps_profile': False, 05:58:37 INFO - 'sps_profile_interval': 0, 05:58:37 INFO - 'suite': 'dromaeojs-e10s', 05:58:37 INFO - 'system_bits': '32', 05:58:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:58:37 INFO - 'title': 't-yosemite-r7-0162', 05:58:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:58:37 INFO - 'use_talos_json': True, 05:58:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:58:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:58:37 INFO - 'work_dir': 'build'} 05:58:37 INFO - [mozharness: 2016-09-29 12:58:37.694430Z] Running clobber step. 05:58:37 INFO - Running pre-action listener: _resource_record_pre_action 05:58:37 INFO - Running main action method: clobber 05:58:37 INFO - rmtree: /builds/slave/test/build 05:58:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:58:40 INFO - Running post-action listener: _resource_record_post_action 05:58:40 INFO - [mozharness: 2016-09-29 12:58:40.427670Z] Finished clobber step (success) 05:58:40 INFO - [mozharness: 2016-09-29 12:58:40.427847Z] Running read-buildbot-config step. 05:58:40 INFO - Running pre-action listener: _resource_record_pre_action 05:58:40 INFO - Running main action method: read_buildbot_config 05:58:40 INFO - Using buildbot properties: 05:58:40 INFO - { 05:58:40 INFO - "project": "", 05:58:40 INFO - "product": "firefox", 05:58:40 INFO - "script_repo_revision": "production", 05:58:40 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 05:58:40 INFO - "repository": "", 05:58:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 05:58:40 INFO - "buildid": "20160929025020", 05:58:40 INFO - "pgo_build": "False", 05:58:40 INFO - "basedir": "/builds/slave/test", 05:58:40 INFO - "buildnumber": 21, 05:58:40 INFO - "slavename": "t-yosemite-r7-0162", 05:58:40 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 05:58:40 INFO - "platform": "yosemite_r7", 05:58:40 INFO - "scheduler": "tests-fx-team-macosx64-talos-8-3600", 05:58:40 INFO - "branch": "fx-team", 05:58:40 INFO - "repo_path": "integration/fx-team", 05:58:40 INFO - "revision": "dc21ccd6a304df32cdec43a71f01bdf80288ee98", 05:58:40 INFO - "stage_platform": "macosx64", 05:58:40 INFO - "builduid": "54f8ee3756a349de9a4fc6fc8d5bcf99", 05:58:40 INFO - "slavebuilddir": "test" 05:58:40 INFO - } 05:58:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/VFbplssDRn-qdBbbQ8MpFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:58:40 INFO - Running post-action listener: _resource_record_post_action 05:58:40 INFO - [mozharness: 2016-09-29 12:58:40.431278Z] Finished read-buildbot-config step (success) 05:58:40 INFO - [mozharness: 2016-09-29 12:58:40.431416Z] Running download-and-extract step. 05:58:40 INFO - Running pre-action listener: _resource_record_pre_action 05:58:40 INFO - Running main action method: download_and_extract 05:58:40 INFO - mkdir: /builds/slave/test/build/tests 05:58:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:58:40 INFO - trying https://queue.taskcluster.net/v1/task/VFbplssDRn-qdBbbQ8MpFw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:58:40 INFO - Downloading https://queue.taskcluster.net/v1/task/VFbplssDRn-qdBbbQ8MpFw/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 05:58:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VFbplssDRn-qdBbbQ8MpFw/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 05:58:41 INFO - Downloaded 1199 bytes. 05:58:41 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:58:41 INFO - Using the following test package requirements: 05:58:41 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:58:41 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:58:41 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:58:41 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:58:41 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:58:41 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:58:41 INFO - u'jsshell-mac.zip'], 05:58:41 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:58:41 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:58:41 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:58:41 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:58:41 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:58:41 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:58:41 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:58:41 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:58:41 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:58:41 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:58:41 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:58:41 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:58:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VFbplssDRn-qdBbbQ8MpFw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:58:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VFbplssDRn-qdBbbQ8MpFw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:58:41 INFO - Fetch https://queue.taskcluster.net/v1/task/VFbplssDRn-qdBbbQ8MpFw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:58:42 INFO - Http code: 200 05:58:42 INFO - Content-Encoding: None 05:58:42 INFO - Content-Type: application/zip 05:58:42 INFO - via: 1.1 ff09df097f823b2834408d17e9779d62.cloudfront.net (CloudFront) 05:58:42 INFO - x-amz-cf-id: OGgoL1WhOTj_32hraoVCFNRSb2pWO_nxyAMuwin1S1F-L6GnhqbpnQ== 05:58:42 INFO - x-amz-version-id: UE45R8QfflY4bMTVYiGg2CRIr2D3S89S 05:58:42 INFO - x-cache: Hit from cloudfront 05:58:42 INFO - Expected file size: 16473311 05:58:42 INFO - Obtained file size: 16473311 05:58:49 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 05:58:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VFbplssDRn-qdBbbQ8MpFw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:58:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VFbplssDRn-qdBbbQ8MpFw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:58:49 INFO - Fetch https://queue.taskcluster.net/v1/task/VFbplssDRn-qdBbbQ8MpFw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:58:49 INFO - Http code: 200 05:58:49 INFO - Content-Encoding: None 05:58:49 INFO - Content-Type: application/zip 05:58:49 INFO - via: 1.1 6e65abb04cb818a6ec78111935b507f7.cloudfront.net (CloudFront) 05:58:49 INFO - x-amz-cf-id: VN_Bu_KgER8JYrAsA4lfpkgYmbH2CQu8yaJW2rEtS4c4m2h4_3b2xA== 05:58:49 INFO - x-amz-version-id: UE45R8QfflY4bMTVYiGg2CRIr2D3S89S 05:58:49 INFO - x-cache: Hit from cloudfront 05:58:50 INFO - Expected file size: 16473311 05:58:50 INFO - Obtained file size: 16473311 05:58:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VFbplssDRn-qdBbbQ8MpFw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:58:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VFbplssDRn-qdBbbQ8MpFw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:58:56 INFO - Fetch https://queue.taskcluster.net/v1/task/VFbplssDRn-qdBbbQ8MpFw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:58:57 INFO - Http code: 200 05:58:57 INFO - Content-Encoding: None 05:58:57 INFO - Content-Type: application/zip 05:58:57 INFO - via: 1.1 3344ddc09ccf1d185bb41add18940cc4.cloudfront.net (CloudFront) 05:58:57 INFO - x-amz-cf-id: 5BPVOeckeDrxuatNB-RjHtgW2daGFs-pKUx1Kp_a89arbw6e9rsVKQ== 05:58:57 INFO - x-amz-version-id: sdd.tAKZ5x7fUQ.2tZQUzANBTc3.uJF5 05:58:57 INFO - x-cache: Hit from cloudfront 05:58:57 INFO - Expected file size: 14414067 05:58:57 INFO - Obtained file size: 14414067 05:58:57 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')]} 05:58:57 INFO - trying https://queue.taskcluster.net/v1/task/VFbplssDRn-qdBbbQ8MpFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:58:57 INFO - Downloading https://queue.taskcluster.net/v1/task/VFbplssDRn-qdBbbQ8MpFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:58:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VFbplssDRn-qdBbbQ8MpFw/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 05:59:00 INFO - Downloaded 111786539 bytes. 05:59:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VFbplssDRn-qdBbbQ8MpFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:59:00 INFO - mkdir: /builds/slave/test/properties 05:59:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:59:00 INFO - Writing to file /builds/slave/test/properties/build_url 05:59:00 INFO - Contents: 05:59:00 INFO - build_url:https://queue.taskcluster.net/v1/task/VFbplssDRn-qdBbbQ8MpFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:59:01 INFO - Running post-action listener: _resource_record_post_action 05:59:01 INFO - Running post-action listener: set_extra_try_arguments 05:59:01 INFO - [mozharness: 2016-09-29 12:59:01.542764Z] Finished download-and-extract step (success) 05:59:01 INFO - [mozharness: 2016-09-29 12:59:01.542899Z] Running populate-webroot step. 05:59:01 INFO - Running pre-action listener: _resource_record_pre_action 05:59:01 INFO - Running main action method: populate_webroot 05:59:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:59:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:59:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:59:01 INFO - '--tppagecycles', 05:59:01 INFO - '7'], 05:59:01 INFO - 'tests': ['tsvgm']}}, 05:59:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:59:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:59:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:59:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:59:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:59:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:59:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:01 INFO - 'talos_options': ['--disable-e10s'], 05:59:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:59:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:59:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:01 INFO - 'talos_options': ['--disable-e10s'], 05:59:01 INFO - 'tests': ['damp', 'tps']}, 05:59:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:01 INFO - 'tests': ['damp', 'tps']}, 05:59:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:59:01 INFO - 'tests': ['dromaeo_dom']}, 05:59:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:59:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:59:01 INFO - 'tests': ['basic_compositor_video']}, 05:59:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:59:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:59:01 INFO - 'tests': ['a11yr', 05:59:01 INFO - 'ts_paint', 05:59:01 INFO - 'tpaint', 05:59:01 INFO - 'sessionrestore', 05:59:01 INFO - 'sessionrestore_no_auto_restore', 05:59:01 INFO - 'tabpaint']}, 05:59:01 INFO - 'other-e10s': {'tests': ['a11yr', 05:59:01 INFO - 'ts_paint', 05:59:01 INFO - 'tpaint', 05:59:01 INFO - 'sessionrestore', 05:59:01 INFO - 'sessionrestore_no_auto_restore', 05:59:01 INFO - 'tabpaint']}, 05:59:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:59:01 INFO - 'ts_paint', 05:59:01 INFO - 'tpaint', 05:59:01 INFO - 'sessionrestore', 05:59:01 INFO - 'sessionrestore_no_auto_restore', 05:59:01 INFO - 'tabpaint']}, 05:59:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:59:01 INFO - 'ts_paint', 05:59:01 INFO - 'tpaint', 05:59:01 INFO - 'sessionrestore', 05:59:01 INFO - 'sessionrestore_no_auto_restore', 05:59:01 INFO - 'tabpaint']}, 05:59:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:59:01 INFO - 'tests': ['a11yr', 05:59:01 INFO - 'ts_paint', 05:59:01 INFO - 'tpaint', 05:59:01 INFO - 'sessionrestore', 05:59:01 INFO - 'sessionrestore_no_auto_restore', 05:59:01 INFO - 'tabpaint']}, 05:59:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:59:01 INFO - 'tests': ['a11yr', 05:59:01 INFO - 'ts_paint', 05:59:01 INFO - 'tpaint', 05:59:01 INFO - 'sessionrestore', 05:59:01 INFO - 'sessionrestore_no_auto_restore', 05:59:01 INFO - 'tabpaint']}, 05:59:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:59:01 INFO - 'tests': ['tsvgx', 05:59:01 INFO - 'tsvgr_opacity', 05:59:01 INFO - 'tart', 05:59:01 INFO - 'tscrollx', 05:59:01 INFO - 'cart']}, 05:59:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:59:01 INFO - 'tsvgr_opacity', 05:59:01 INFO - 'tart', 05:59:01 INFO - 'tscrollx', 05:59:01 INFO - 'cart']}, 05:59:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:01 INFO - 'talos_options': ['--disable-e10s'], 05:59:01 INFO - 'tests': ['tp5o']}, 05:59:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:01 INFO - 'tests': ['tp5o']}, 05:59:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:01 INFO - 'talos_options': ['--disable-e10s', 05:59:01 INFO - '--xperf_path', 05:59:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:59:01 INFO - 'tests': ['tp5n']}, 05:59:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:01 INFO - 'talos_options': ['--xperf_path', 05:59:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:59:01 INFO - 'tests': ['tp5n']}}, 05:59:01 INFO - 'talos.zip': {'path': '', 05:59:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:59:01 INFO - Running post-action listener: _resource_record_post_action 05:59:01 INFO - [mozharness: 2016-09-29 12:59:01.561852Z] Finished populate-webroot step (success) 05:59:01 INFO - [mozharness: 2016-09-29 12:59:01.561981Z] Running create-virtualenv step. 05:59:01 INFO - Running pre-action listener: _resource_record_pre_action 05:59:01 INFO - Running main action method: create_virtualenv 05:59:01 INFO - Creating virtualenv /builds/slave/test/build/venv 05:59:01 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 05:59:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:59:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:59:01 INFO - Using real prefix '/tools/python27' 05:59:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:59:02 INFO - Installing distribute.............................................................................................................................................................................................done. 05:59:05 INFO - Installing pip.................done. 05:59:05 INFO - Return code: 0 05:59:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:59:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:59:05 INFO - Reading from file tmpfile_stdout 05:59:05 INFO - Output received: 05:59:05 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) 05:59:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:59:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eae7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda12469660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8fbd30>, '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 0x10e721db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8f3b20>, '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.UJeCwHY4LX/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.gudp4MMn5F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 05:59:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:59:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:59:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UJeCwHY4LX/Render', 05:59:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:05 INFO - 'HOME': '/Users/cltbld', 05:59:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:05 INFO - 'LOGNAME': 'cltbld', 05:59:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:05 INFO - 'MOZ_NO_REMOTE': '1', 05:59:05 INFO - 'NO_EM_RESTART': '1', 05:59:05 INFO - 'PAGER': '/bin/cat', 05:59:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:05 INFO - 'PWD': '/builds/slave/test', 05:59:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:05 INFO - 'SHELL': '/bin/bash', 05:59:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gudp4MMn5F/Listeners', 05:59:05 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 05:59:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:05 INFO - 'USER': 'cltbld', 05:59:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:05 INFO - 'XPC_FLAGS': '0x0', 05:59:05 INFO - 'XPC_SERVICE_NAME': '0', 05:59:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:05 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 05:59:05 INFO - Cleaning up... 05:59:05 INFO - Return code: 0 05:59:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:59:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10eae7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda12469660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8fbd30>, '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 0x10e721db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8f3b20>, '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.UJeCwHY4LX/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.gudp4MMn5F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 05:59:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:59:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:59:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UJeCwHY4LX/Render', 05:59:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:05 INFO - 'HOME': '/Users/cltbld', 05:59:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:05 INFO - 'LOGNAME': 'cltbld', 05:59:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:05 INFO - 'MOZ_NO_REMOTE': '1', 05:59:05 INFO - 'NO_EM_RESTART': '1', 05:59:05 INFO - 'PAGER': '/bin/cat', 05:59:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:05 INFO - 'PWD': '/builds/slave/test', 05:59:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:05 INFO - 'SHELL': '/bin/bash', 05:59:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gudp4MMn5F/Listeners', 05:59:05 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 05:59:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:05 INFO - 'USER': 'cltbld', 05:59:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:05 INFO - 'XPC_FLAGS': '0x0', 05:59:05 INFO - 'XPC_SERVICE_NAME': '0', 05:59:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:06 INFO - Downloading/unpacking psutil>=3.1.1 05:59:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:59:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:59:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:59:09 INFO - Installing collected packages: psutil 05:59:09 INFO - Running setup.py install for psutil 05:59:09 INFO - building 'psutil._psutil_osx' extension 05:59:09 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 05:59:10 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 05:59:10 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 05:59:10 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 05:59:10 INFO - building 'psutil._psutil_posix' extension 05:59:10 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 05:59:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:59:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:59:10 INFO - ^ 05:59:10 INFO - 1 warning generated. 05:59:10 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 05:59:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:59:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:59:10 INFO - Successfully installed psutil 05:59:10 INFO - Cleaning up... 05:59:10 INFO - Return code: 0 05:59:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:59:10 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')]} 05:59:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:10 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')]} 05:59:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:10 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 0x10eae7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda12469660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8fbd30>, '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 0x10e721db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8f3b20>, '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.UJeCwHY4LX/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.gudp4MMn5F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 05:59:10 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 05:59:10 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 05:59:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UJeCwHY4LX/Render', 05:59:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:10 INFO - 'HOME': '/Users/cltbld', 05:59:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:10 INFO - 'LOGNAME': 'cltbld', 05:59:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:10 INFO - 'MOZ_NO_REMOTE': '1', 05:59:10 INFO - 'NO_EM_RESTART': '1', 05:59:10 INFO - 'PAGER': '/bin/cat', 05:59:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:10 INFO - 'PWD': '/builds/slave/test', 05:59:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:10 INFO - 'SHELL': '/bin/bash', 05:59:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gudp4MMn5F/Listeners', 05:59:10 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 05:59:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:10 INFO - 'USER': 'cltbld', 05:59:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:10 INFO - 'XPC_FLAGS': '0x0', 05:59:10 INFO - 'XPC_SERVICE_NAME': '0', 05:59:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:59:11 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 05:59:11 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 05:59:11 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 05:59:11 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 05:59:11 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 05:59:11 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 05:59:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:59:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:59:13 INFO - Installing collected packages: mozsystemmonitor 05:59:13 INFO - Running setup.py install for mozsystemmonitor 05:59:13 INFO - Successfully installed mozsystemmonitor 05:59:13 INFO - Cleaning up... 05:59:13 INFO - Return code: 0 05:59:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:59:13 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')]} 05:59:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:13 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')]} 05:59:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:13 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 0x10eae7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda12469660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8fbd30>, '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 0x10e721db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8f3b20>, '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.UJeCwHY4LX/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.gudp4MMn5F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 05:59:13 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 05:59:13 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 05:59:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UJeCwHY4LX/Render', 05:59:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:13 INFO - 'HOME': '/Users/cltbld', 05:59:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:13 INFO - 'LOGNAME': 'cltbld', 05:59:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:13 INFO - 'MOZ_NO_REMOTE': '1', 05:59:13 INFO - 'NO_EM_RESTART': '1', 05:59:13 INFO - 'PAGER': '/bin/cat', 05:59:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:13 INFO - 'PWD': '/builds/slave/test', 05:59:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:13 INFO - 'SHELL': '/bin/bash', 05:59:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gudp4MMn5F/Listeners', 05:59:13 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 05:59:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:13 INFO - 'USER': 'cltbld', 05:59:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:13 INFO - 'XPC_FLAGS': '0x0', 05:59:13 INFO - 'XPC_SERVICE_NAME': '0', 05:59:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:14 INFO - Downloading/unpacking jsonschema==2.5.1 05:59:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:59:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:59:16 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 05:59:16 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 05:59:16 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 05:59:16 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 05:59:16 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 05:59:16 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 05:59:16 INFO - Downloading functools32-3.2.3-2.tar.gz 05:59:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:59:17 INFO - warning: no files found matching '*.txt' 05:59:17 INFO - no previously-included directories found matching 'build' 05:59:17 INFO - no previously-included directories found matching 'dist' 05:59:17 INFO - no previously-included directories found matching '.git*' 05:59:17 INFO - Installing collected packages: jsonschema, functools32 05:59:17 INFO - Running setup.py install for functools32 05:59:17 INFO - warning: no files found matching '*.txt' 05:59:17 INFO - no previously-included directories found matching 'build' 05:59:17 INFO - no previously-included directories found matching 'dist' 05:59:17 INFO - no previously-included directories found matching '.git*' 05:59:17 INFO - Successfully installed jsonschema functools32 05:59:17 INFO - Cleaning up... 05:59:17 INFO - Return code: 0 05:59:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:17 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 0x10eae7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda12469660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8fbd30>, '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 0x10e721db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8f3b20>, '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.UJeCwHY4LX/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.gudp4MMn5F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 05:59:17 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 05:59:17 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 05:59:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UJeCwHY4LX/Render', 05:59:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:17 INFO - 'HOME': '/Users/cltbld', 05:59:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:17 INFO - 'LOGNAME': 'cltbld', 05:59:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:17 INFO - 'MOZ_NO_REMOTE': '1', 05:59:17 INFO - 'NO_EM_RESTART': '1', 05:59:17 INFO - 'PAGER': '/bin/cat', 05:59:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:17 INFO - 'PWD': '/builds/slave/test', 05:59:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:17 INFO - 'SHELL': '/bin/bash', 05:59:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gudp4MMn5F/Listeners', 05:59:17 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 05:59:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:17 INFO - 'USER': 'cltbld', 05:59:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:17 INFO - 'XPC_FLAGS': '0x0', 05:59:17 INFO - 'XPC_SERVICE_NAME': '0', 05:59:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:59:17 INFO - Cleaning up... 05:59:17 INFO - Return code: 0 05:59:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:17 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 0x10eae7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda12469660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8fbd30>, '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 0x10e721db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8f3b20>, '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.UJeCwHY4LX/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.gudp4MMn5F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 05:59:17 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 05:59:17 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 05:59:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UJeCwHY4LX/Render', 05:59:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:17 INFO - 'HOME': '/Users/cltbld', 05:59:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:17 INFO - 'LOGNAME': 'cltbld', 05:59:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:17 INFO - 'MOZ_NO_REMOTE': '1', 05:59:17 INFO - 'NO_EM_RESTART': '1', 05:59:17 INFO - 'PAGER': '/bin/cat', 05:59:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:17 INFO - 'PWD': '/builds/slave/test', 05:59:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:17 INFO - 'SHELL': '/bin/bash', 05:59:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gudp4MMn5F/Listeners', 05:59:17 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 05:59:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:17 INFO - 'USER': 'cltbld', 05:59:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:17 INFO - 'XPC_FLAGS': '0x0', 05:59:17 INFO - 'XPC_SERVICE_NAME': '0', 05:59:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:17 INFO - Downloading/unpacking blobuploader==1.2.4 05:59:17 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 05:59:17 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 05:59:17 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 05:59:17 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 05:59:17 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 05:59:17 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 05:59:20 INFO - Downloading blobuploader-1.2.4.tar.gz 05:59:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:59:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:59: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 05:59: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 05:59: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 05:59: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 05:59: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 05:59: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 05:59:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:59:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:59:21 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 05:59:21 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 05:59:21 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 05:59:21 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 05:59:21 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 05:59:21 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 05:59:21 INFO - Downloading docopt-0.6.1.tar.gz 05:59:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:59:21 INFO - Installing collected packages: blobuploader, requests, docopt 05:59:21 INFO - Running setup.py install for blobuploader 05:59:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:59:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:59:21 INFO - Running setup.py install for requests 05:59:22 INFO - Running setup.py install for docopt 05:59:22 INFO - Successfully installed blobuploader requests docopt 05:59:22 INFO - Cleaning up... 05:59:22 INFO - Return code: 0 05:59:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:59: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')]} 05:59:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59: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')]} 05:59:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:22 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 0x10eae7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda12469660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8fbd30>, '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 0x10e721db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8f3b20>, '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.UJeCwHY4LX/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.gudp4MMn5F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 05:59:22 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 05:59:22 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 05:59:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UJeCwHY4LX/Render', 05:59:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:22 INFO - 'HOME': '/Users/cltbld', 05:59:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:22 INFO - 'LOGNAME': 'cltbld', 05:59:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:22 INFO - 'MOZ_NO_REMOTE': '1', 05:59:22 INFO - 'NO_EM_RESTART': '1', 05:59:22 INFO - 'PAGER': '/bin/cat', 05:59:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:22 INFO - 'PWD': '/builds/slave/test', 05:59:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:22 INFO - 'SHELL': '/bin/bash', 05:59:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gudp4MMn5F/Listeners', 05:59:22 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 05:59:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:22 INFO - 'USER': 'cltbld', 05:59:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:22 INFO - 'XPC_FLAGS': '0x0', 05:59:22 INFO - 'XPC_SERVICE_NAME': '0', 05:59:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:59:22 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-GVG0oz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:59:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:59:22 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-1g3TkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:59:23 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-DSM0tR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:59:23 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-AvKhOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:59:23 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-cFR7ag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:59:23 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-SE8Ba1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:59:23 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-yo6iX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:59:23 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-dw3zuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:59:23 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-1CwLud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:59:23 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-Fwq8RC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:59:23 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-kno5PG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:59:23 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-w4w8Td-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:59:24 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-4WpQsw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:59:24 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-gFaEFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:59:24 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-9L81z4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:59:24 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-u3iIGu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:59:24 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-hiNRlW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:59:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:59:24 INFO - Running setup.py install for manifestparser 05:59:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:59:24 INFO - Running setup.py install for mozcrash 05:59:24 INFO - Running setup.py install for mozdebug 05:59:25 INFO - Running setup.py install for mozdevice 05:59:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:59:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:59:25 INFO - Running setup.py install for mozfile 05:59:25 INFO - Running setup.py install for mozhttpd 05:59:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:59:25 INFO - Running setup.py install for mozinfo 05:59:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:59:25 INFO - Running setup.py install for mozInstall 05:59:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:59:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:59:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:59:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:59:25 INFO - Running setup.py install for mozleak 05:59:25 INFO - Running setup.py install for mozlog 05:59:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:59:26 INFO - Running setup.py install for moznetwork 05:59:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:59:26 INFO - Running setup.py install for mozprocess 05:59:26 INFO - Running setup.py install for mozprofile 05:59:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:59:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:59:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:59:26 INFO - Running setup.py install for mozrunner 05:59:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:59:26 INFO - Running setup.py install for mozscreenshot 05:59:26 INFO - Running setup.py install for moztest 05:59:27 INFO - Running setup.py install for mozversion 05:59:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:59:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:59:27 INFO - Cleaning up... 05:59:27 INFO - Return code: 0 05:59:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:59:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:27 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 0x10eae7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda12469660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8fbd30>, '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 0x10e721db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8f3b20>, '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.UJeCwHY4LX/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.gudp4MMn5F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 05:59:27 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 05:59:27 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 05:59:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UJeCwHY4LX/Render', 05:59:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:27 INFO - 'HOME': '/Users/cltbld', 05:59:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:27 INFO - 'LOGNAME': 'cltbld', 05:59:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:27 INFO - 'MOZ_NO_REMOTE': '1', 05:59:27 INFO - 'NO_EM_RESTART': '1', 05:59:27 INFO - 'PAGER': '/bin/cat', 05:59:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:27 INFO - 'PWD': '/builds/slave/test', 05:59:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:27 INFO - 'SHELL': '/bin/bash', 05:59:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gudp4MMn5F/Listeners', 05:59:27 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 05:59:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:27 INFO - 'USER': 'cltbld', 05:59:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:27 INFO - 'XPC_FLAGS': '0x0', 05:59:27 INFO - 'XPC_SERVICE_NAME': '0', 05:59:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:59:27 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-ooyal0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:59:27 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)) 05:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:59:27 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-oXZzsf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:59:27 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)) 05:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:59:27 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-74vxv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:59:27 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)) 05:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:59:27 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-mjnX5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:59:27 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)) 05:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:59:27 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-tUlT9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:59:27 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)) 05:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:59:27 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-0rmYWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:59:28 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)) 05:59:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:59:28 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-BMmUsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:59:28 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)) 05:59:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:59:28 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-CZq1yx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:59:28 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)) 05:59:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:59:28 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-JJdr6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:59:28 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)) 05:59:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:59:28 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-sYRoPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:59:28 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)) 05:59:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:59:28 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-uwGsBF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:59:28 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)) 05:59:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:59:28 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-fZV3EA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:59:28 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)) 05:59:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:59:28 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-bOuddZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:59:28 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)) 05:59:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:59:28 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-yQeExe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:59:28 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)) 05:59:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:59:28 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-RtaaYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:59:29 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)) 05:59:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:59:29 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-Us9xWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:59:29 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)) 05:59:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:59:29 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-xyVDNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:59:29 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)) 05:59:29 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)) 05:59:29 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)) 05:59:29 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)) 05:59:29 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)) 05:59:29 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)) 05:59:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:59:29 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 05:59:29 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 05:59:29 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 05:59:29 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 05:59:29 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 05:59:29 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 05:59:32 INFO - Downloading blessings-1.6.tar.gz 05:59:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:59:32 INFO - Installing collected packages: blessings 05:59:32 INFO - Running setup.py install for blessings 05:59:32 INFO - Successfully installed blessings 05:59:32 INFO - Cleaning up... 05:59:32 INFO - Return code: 0 05:59:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:59:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:59:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:59:32 INFO - Reading from file tmpfile_stdout 05:59:32 INFO - Current package versions: 05:59:32 INFO - blessings == 1.6 05:59:32 INFO - blobuploader == 1.2.4 05:59:32 INFO - docopt == 0.6.1 05:59:32 INFO - functools32 == 3.2.3-2 05:59:32 INFO - jsonschema == 2.5.1 05:59:32 INFO - manifestparser == 1.1 05:59:32 INFO - mozInstall == 1.12 05:59:32 INFO - mozcrash == 0.17 05:59:32 INFO - mozdebug == 0.1 05:59:32 INFO - mozdevice == 0.48 05:59:32 INFO - mozfile == 1.2 05:59:32 INFO - mozhttpd == 0.7 05:59:32 INFO - mozinfo == 0.9 05:59:32 INFO - mozleak == 0.1 05:59:32 INFO - mozlog == 3.4 05:59:32 INFO - moznetwork == 0.27 05:59:32 INFO - mozprocess == 0.23 05:59:32 INFO - mozprofile == 0.28 05:59:32 INFO - mozrunner == 6.12 05:59:32 INFO - mozscreenshot == 0.1 05:59:32 INFO - mozsystemmonitor == 0.3 05:59:32 INFO - moztest == 0.7 05:59:32 INFO - mozversion == 1.4 05:59:32 INFO - psutil == 3.1.1 05:59:32 INFO - requests == 1.2.3 05:59:32 INFO - wsgiref == 0.1.2 05:59:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:59: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')]} 05:59:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:33 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')]} 05:59:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:33 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 0x10eae7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda12469660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8fbd30>, '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 0x10e721db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8f3b20>, '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.UJeCwHY4LX/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.gudp4MMn5F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 05:59:33 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 05:59:33 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 05:59:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UJeCwHY4LX/Render', 05:59:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:33 INFO - 'HOME': '/Users/cltbld', 05:59:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:33 INFO - 'LOGNAME': 'cltbld', 05:59:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:33 INFO - 'MOZ_NO_REMOTE': '1', 05:59:33 INFO - 'NO_EM_RESTART': '1', 05:59:33 INFO - 'PAGER': '/bin/cat', 05:59:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:33 INFO - 'PWD': '/builds/slave/test', 05:59:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:33 INFO - 'SHELL': '/bin/bash', 05:59:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gudp4MMn5F/Listeners', 05:59:33 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 05:59:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:33 INFO - 'USER': 'cltbld', 05:59:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:33 INFO - 'XPC_FLAGS': '0x0', 05:59:33 INFO - 'XPC_SERVICE_NAME': '0', 05:59:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:33 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)) 05:59:33 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)) 05:59:33 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)) 05:59:33 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)) 05:59:33 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)) 05:59:33 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)) 05:59:33 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)) 05:59:33 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)) 05:59:33 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)) 05:59:33 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)) 05:59:33 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)) 05:59:33 INFO - Cleaning up... 05:59:33 INFO - Return code: 0 05:59:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:59:33 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')]} 05:59:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:33 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')]} 05:59:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:33 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 0x10eae7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda12469660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8fbd30>, '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 0x10e721db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8f3b20>, '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.UJeCwHY4LX/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.gudp4MMn5F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 05:59:33 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 05:59:33 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 05:59:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UJeCwHY4LX/Render', 05:59:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:33 INFO - 'HOME': '/Users/cltbld', 05:59:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:33 INFO - 'LOGNAME': 'cltbld', 05:59:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:33 INFO - 'MOZ_NO_REMOTE': '1', 05:59:33 INFO - 'NO_EM_RESTART': '1', 05:59:33 INFO - 'PAGER': '/bin/cat', 05:59:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:33 INFO - 'PWD': '/builds/slave/test', 05:59:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:33 INFO - 'SHELL': '/bin/bash', 05:59:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gudp4MMn5F/Listeners', 05:59:33 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 05:59:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:33 INFO - 'USER': 'cltbld', 05:59:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:33 INFO - 'XPC_FLAGS': '0x0', 05:59:33 INFO - 'XPC_SERVICE_NAME': '0', 05:59:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:59:33 INFO - Cleaning up... 05:59:33 INFO - Return code: 0 05:59:33 INFO - Running post-action listener: _resource_record_post_action 05:59:33 INFO - Running post-action listener: _start_resource_monitoring 05:59:33 INFO - Starting resource monitoring. 05:59:33 INFO - [mozharness: 2016-09-29 12:59:33.762401Z] Finished create-virtualenv step (success) 05:59:33 INFO - [mozharness: 2016-09-29 12:59:33.763365Z] Running install step. 05:59:33 INFO - Running pre-action listener: _resource_record_pre_action 05:59:33 INFO - Running main action method: install 05:59:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:59:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:59:34 INFO - Reading from file tmpfile_stdout 05:59:34 INFO - Detecting whether we're running mozinstall >=1.0... 05:59:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:59:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:59:34 INFO - Reading from file tmpfile_stdout 05:59:34 INFO - Output received: 05:59:34 INFO - Usage: mozinstall [options] installer 05:59:34 INFO - Options: 05:59:34 INFO - -h, --help show this help message and exit 05:59:34 INFO - -d DEST, --destination=DEST 05:59:34 INFO - Directory to install application into. [default: 05:59:34 INFO - "/builds/slave/test"] 05:59:34 INFO - --app=APP Application being installed. [default: firefox] 05:59:34 INFO - mkdir: /builds/slave/test/build/application 05:59:34 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'] 05:59:34 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 06:00:08 INFO - Reading from file tmpfile_stdout 06:00:08 INFO - Output received: 06:00:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:00:08 INFO - Running post-action listener: _resource_record_post_action 06:00:08 INFO - [mozharness: 2016-09-29 13:00:08.944262Z] Finished install step (success) 06:00:08 INFO - [mozharness: 2016-09-29 13:00:08.944452Z] Running run-tests step. 06:00:08 INFO - Running pre-action listener: _resource_record_pre_action 06:00:08 INFO - Running main action method: run_tests 06:00:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:00:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:00:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:00:08 INFO - 2016-09-29 06:00:08.958 screenresolution[1644:7473] starting screenresolution argv=screenresolution get 06:00:08 INFO - 2016-09-29 06:00:08.961 screenresolution[1644:7473] Display 0: 1600x1200x32@60 06:00:08 INFO - 2016-09-29 06:00:08.967 screenresolution[1645:7475] starting screenresolution argv=screenresolution list 06:00:08 INFO - Available Modes on Display 0 06:00:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:00:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:00:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:00:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:00:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:00:09 INFO - Intel Iris: 06:00:09 INFO - Chipset Model: Intel Iris 06:00:09 INFO - Type: GPU 06:00:09 INFO - Bus: Built-In 06:00:09 INFO - VRAM (Dynamic, Max): 1536 MB 06:00:09 INFO - Vendor: Intel (0x8086) 06:00:09 INFO - Device ID: 0x0a2e 06:00:09 INFO - Revision ID: 0x0009 06:00:09 INFO - Displays: 06:00:09 INFO - Display: 06:00:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:00:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:00:09 INFO - Display Serial Number: 1600x1200 60 06:00:09 INFO - Main Display: Yes 06:00:09 INFO - Mirror: Off 06:00:09 INFO - Online: Yes 06:00:09 INFO - Rotation: Supported 06:00:09 INFO - Return code: 0 06:00:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:00:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:00:09 INFO - Python 2.7.3 06:00:09 INFO - Return code: 0 06:00:09 INFO - grabbing minidump binary from tooltool 06:00:09 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')]} 06:00:09 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 0x10e8fbd30>, '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 0x10e721db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8f3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:00:09 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 06:00:09 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 06:00:09 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 06:00:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:00:09 INFO - Return code: 0 06:00:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:00:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:00:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:00:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:00:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:00:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VFbplssDRn-qdBbbQ8MpFw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0162', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 06:00:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VFbplssDRn-qdBbbQ8MpFw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0162 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 06:00:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UJeCwHY4LX/Render', 06:00:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:00:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:00:09 INFO - 'HOME': '/Users/cltbld', 06:00:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:00:09 INFO - 'LOGNAME': 'cltbld', 06:00:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:00:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:00:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:00:09 INFO - 'MOZ_NO_REMOTE': '1', 06:00:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:00:09 INFO - 'NO_EM_RESTART': '1', 06:00:09 INFO - 'PAGER': '/bin/cat', 06:00:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:00:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:00:09 INFO - 'PWD': '/builds/slave/test', 06:00:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:00:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:00:09 INFO - 'SHELL': '/bin/bash', 06:00:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gudp4MMn5F/Listeners', 06:00:09 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 06:00:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:00:09 INFO - 'USER': 'cltbld', 06:00:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:00:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:00:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:00:09 INFO - 'XPC_FLAGS': '0x0', 06:00:09 INFO - 'XPC_SERVICE_NAME': '0', 06:00:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:00:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VFbplssDRn-qdBbbQ8MpFw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0162', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 06:00:09 INFO - mozversion application_buildid: 20160929025020 06:00:09 INFO - mozversion application_changeset: dc21ccd6a304df32cdec43a71f01bdf80288ee98 06:00:09 INFO - mozversion application_display_name: Nightly 06:00:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:00:09 INFO - mozversion application_name: Firefox 06:00:09 INFO - mozversion application_remotingname: firefox 06:00:09 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 06:00:09 INFO - mozversion application_vendor: Mozilla 06:00:09 INFO - mozversion application_version: 52.0a1 06:00:09 INFO - mozversion platform_buildid: 20160929025020 06:00:09 INFO - mozversion platform_changeset: dc21ccd6a304df32cdec43a71f01bdf80288ee98 06:00:09 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 06:00:09 INFO - mozversion platform_version: 52.0a1 06:00:09 INFO - using testdate: 1475154009 06:00:09 INFO - actual date: 1475154009 06:00:09 INFO - starting webserver on 'localhost:49279' 06:00:09 INFO - SUITE-START | Running 2 tests 06:00:09 INFO - TEST-START | dromaeo_css 06:00:09 INFO - operating with platform_type : mac_ 06:00:09 INFO - Initialising browser for dromaeo_css test... 06:00:09 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/tmpRb36fc/profile http://localhost:49279/getInfo.html) 06:00:12 INFO - PROCESS | 1655 | 2016-09-29 06:00:12.392 plugin-container[1657:7647] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3b, name = 'com.apple.tsm.portname' 06:00:12 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:00:12 INFO - PROCESS | 1655 | 2016-09-29 06:00:12.394 plugin-container[1657:7647] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 06:00:12 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:00:12 INFO - PROCESS | 1655 | 2016-09-29 06:00:12.394 plugin-container[1657:7647] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:00:12 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 06:00:12 INFO - PROCESS | 1655 | colorDepth:24 06:00:12 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 06:00:12 INFO - PROCESS | 1655 | __metrics 06:00:12 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3076: TypeError: this.tabs is undefined 06:00:13 INFO - TEST-INFO | 1655: exit 0 06:00:13 INFO - Browser initialized. 06:00:13 INFO - Running cycle 1/1 for dromaeo_css test... 06:00:13 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/tmpRb36fc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:00:15 INFO - PROCESS | 1658 | 2016-09-29 06:00:15.631 plugin-container[1659:7764] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x881f, name = 'com.apple.tsm.portname' 06:00:15 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:00:15 INFO - PROCESS | 1658 | 2016-09-29 06:00:15.638 plugin-container[1659:7764] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d13, name = 'com.apple.CFPasteboardClient' 06:00:15 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:00:15 INFO - PROCESS | 1658 | 2016-09-29 06:00:15.638 plugin-container[1659:7764] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:00:25 INFO - PROCESS | 1658 | RSS: Main: 240181248 06:00:25 INFO - PROCESS | 1658 | 06:01:14 INFO - PROCESS | 1658 | 1475154074863 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] 06:03:26 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 06:03:28 INFO - PROCESS | 1658 | RSS: Main: 257089536 06:03:28 INFO - PROCESS | 1658 | 06:06:30 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 06:06:31 INFO - PROCESS | 1658 | RSS: Main: 267792384 06:06:31 INFO - PROCESS | 1658 | 06:09:33 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 06:09:33 INFO - PROCESS | 1658 | RSS: Main: 270528512 06:09:33 INFO - PROCESS | 1658 | 06:12:35 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 06:12:36 INFO - PROCESS | 1658 | RSS: Main: 271040512 06:12:36 INFO - PROCESS | 1658 | 06:15:39 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 06:15:39 INFO - PROCESS | 1658 | RSS: Main: 276238336 06:15:39 INFO - PROCESS | 1658 | 06:18:42 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 06:18:42 INFO - PROCESS | 1658 | RSS: Main: 278978560 06:18:42 INFO - PROCESS | 1658 | 06:18:42 INFO - PROCESS | 1658 | __start_tp_report 06:18:42 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 06:18:42 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 06:18:42 INFO - PROCESS | 1658 | |i|pagename|runs| 06:18:42 INFO - PROCESS | 1658 | |0;dojo.html;3098.3493466372065;3345.264041910779;3345.6988871001604;3352.631210566838;3359.445691460909;4560.498345182032;5508;5597.992361374951;5649.943500564993;5657.886842263162;5521.171824226368;5642.294713160855;5698.772049118041;5735.1970724098555;5753.65478071316;5118.206677964921;5216.530512253897;5228.712420816853;5229.790808367661;5241.842744717665;5214.661047031931;5224.973875130619;5269.314989051418;5306.044911915854;5331.840044798643;5090.720010399426;5229.947700522984;5234.7120908350025;5285.365756109253;5288.206768984644;5125.666831655931;5233.162693968985;5243.318368612094;5261.579073674096;5275.736213189363;4812.5668689818085;5183.429822719498;5193.013327467769;5213.4004589472315;5217.087009773312;5554.50009499147;5611.046122159243;5635.323761148688;5664.461876121762;5719.084946408555;6099.2795188853615;6105.358937311607;6181.3509581493745;6251.156093927307;6257.186565746424;6269.561130720851;6271.435570798604;6292.2134733158355;6299.96850015747;6323.367663233686;5696.401877802854;5844.094165404326;5846.035404158309;5849.970750146222;5886.969780288432;6217.285012223561;6217.813465596039;6223.96888015557;6254.124422580842;6263.592866463665;6120.969395152996;6154.969225153845;6162.352952939877;6166;6176.320604733476;6107.747911678095;6256.687165641791;6259.593126446766;6290.119383286344;6294.087357333253;6061.242344706911;6144.969275153596;6181.752729890754;6224.159738435344;6230.408111229426;6180.258368995749;6224;6237.157983672237;6245.094461303176;6245.375462453809;4910.705357678551;4928.926066109011;4932.136876046678;4933.062718083553;4934.333864928189;4345.847895323712;4356.237658409766;4359.651227901761;4375.7374557526655;4397.274449715825;5822;5874.060150375919;5898.4101589840675;5899.823005309848;5940.940590594125;6199.814005579839;6234.657093859882;6253.874922501615;6272.184615999982;6284.622922624657;5682.806610611735;5759.827205183851;5780.653160810365;5844.035734103911;5891.086881533369;5337.466253374631;5370.9731451342495;5377.946220537745;5379.623426360117;5442.863928401822;5196.4283928767045;6269.564395164658;6318.494520438354;6321.399467050623;6346.4605508532295;5051.646384753032;5103.7192954387865;6268.874622507616;6284;6289.559730818982;6153.169322141453;6171.228596425447;6175.722092505938;6196.194494715658;6219.875602487835;6128.19758601264;6135.967692822357;6145.201123854049;6147.538934579835;6162.044883043135;6067.605605635619;6097.939020609737;6148.292946311231;6154.384561543989;6174.135621012972;5825.825225243249;6152.938470615237;6160.7535698571555;6181.81454556364;6195.194624698941;4773.403324584427;5936.970315148569;5977.551683623658;6056.878862422815;6170.9074363885475;5802.4197580241635;5983.37174596661;5987.491063259672;6008.489278411384;6009.699515024144;6117.174181485442;6130.601510901777;6153.138560601609;6158.815235542941;6188;5853.765849365977;5919.4080591940465;5935.169076329404;5991.161237426851;6013.067974463965 06:18:42 INFO - PROCESS | 1658 | |1;ext.html;460.30493954129275;463.0622988448392;473.6329344757812;481.22522738391206;485.8129620096263;38861;40427.39358909615;40465.59534404655;42765.144697106116;42818.143637127316;41466.378004329956;41512.58487415125;41781.37327940083;41815.16369672612;41920.16159676812;9743.269254805882;9928.950355248213;9932.404055756642;9963.850542241873;9967.202623790079;9809.50952452378;9910;9936.105750482455;9945;9951.303408761389;9937.503124843764;9959.55182016811;9989.55047022882;9991.850122248172;10014.899851001506;9906.157976572;9930.9503452483;9940.701778946606;9947.5026248688;9974.301798874081;9718.028197180296;9738.90261097387;9758.414495130315;9774.5601447935;9777.90222097777;39478;39533.209335813124;39791.8010409949;39801;39849.00377490557;3227.2415982244156;3309.5532103166006;3323.80057196569;3330.833458327074;3405.1827561385335;25209.873950630314;29888.70111298881;30815;31207.531887021712;31723.889663861653;3197.600299962505;3213.389456003352;3251.4472539668313;3277.3936821687826;3313.022658315803;334488;405542;412579;414698;415274;56998.87514060742;57328.14007789887;57330.85338293211;57357.42642573521;57554;34501.48247776285;34959.475607865905;35197.64802351995;36365.81817090951;59899;16210.594735131715;16367.345306187857;16376.918115409348;16379;16416;12584.37078146089;12679.809802852964;12688.936555317165;12711.364431778375;12795;16389.42637007699;18738.437846864617;18950.81049189518;19016.334428294944;19085.61828763417;6518.706658200297;7340.486165968331;7747.690092396354;7855.842883142305;7886.684532618746;812.3257696112213;958.9904100958901;989.7871957529022;1045.7659961245652;1092.4876521981867;21034.44337969148;22022.44943876416;24007.758952541182;29114.708852911623;29299.121026369245;942.7171848445439;1005.2762011351815;1018.4214467575376;1023.2837014090167;1180.6103985684704;12824.845763881109;14546.781798273034;15395.153266570445;15653.060816351055;15963.521094367188;672612;675518;676030;679818;681846;45651.543484565394;55004.72497637566;55486.44513554894;55685.44314556884;55902.720486398124;1336.9665758356118;1356.8982326325565;1378.076688618628;1378.3521744779937;1386.5632325817335;28770.85614571914;29684.406311874074;29795.702042979294;30356;30434.543481847348;49515.75242123767;49611.751941240065;49755.004899902524;49894.75052624714;49938.25092623541;482546;569515;570283;570964;571001;26405.01962352793;26479.867600661873;26596.734032659424;26915.730842691322;26940.32649183786;45601.54398455973;46389.07221855612;46879.53120468752;47206.055878882915;47388;38302.61697382991;38388;38427;39684.2063158741;39722.60277397189;37830.62169378271;37968.050798730255;38143;39147;39334.60665393309;30748.846255769473;31411.528827067155;37514.62485375111;37893.43159852659;38244.04389890275;32934.176645583095;35209.01439799631;35296.11759706028;35555.644443556266;35632;25212.487250765014;25470;25621.97512099495;25996.740032600188;26008.08971685982 06:18:42 INFO - PROCESS | 1658 | |2;jquery.html;316.2583741127057;365.16377495535244;372.06611405372513;380.88763814674667;398.2691760619264;17789.021603811787;19326.710099348504;19801.306954256605;21075.156993720233;21322;19569.608607827835;20652.793472065274;20928.95355232236;21048.684269735928;21116;5916.26046744157;5927.555433342516;6095.847603809896;6201.1008403781425;6210.62736235825;4700;6111.816645500642;6119.173911521954;6125.081237814319;6130.478909292716;5818.650880947135;6003.429674180946;6049.1228771828055;6065.60573562717;6083.695815209244;6022.367651396606;6031.728572214261;6038.939610603904;6070.848228794293;6083.847903802396;5139.537441630271;5167.870803229912;5194.792208311663;5269.578433725293;5271.393789714193;26108.347291317677;26227.606585901227;26379.208623741317;26542.46915061688;26591.601125983125;2152.8277737781095;2303.5623231585946;2322.1059891941554;2415.577273977047;2653.668291463567;15558.688826223412;15666.68666626661;15676.529704108782;16246.675066498721;16656.6668666626;1895.4882181810833;1925.5763731978943;1936.448112287991;1937.476881242071;1946.3868881302344;105477.47261263721;116967.41516292449;120139;120306;121197;8683.565821709015;9172.724818255463;11181.161412893904;11340.036096931688;11423.485943132579;14008;14048.508302209579;14097.295135243197;14112.576622701336;14135.85864141366;7398.779201431811;7497.475176737576;8326.83346333082;8400.453970491899;8672.78318042054;7833.764987050397;7860.174681658344;8753.212210901049;8763.474191548528;8899;6666.96666516674;6716.597004179765;6782.20271628019;6785.694643741043;6968.616726080114;1828.7531183290353;1866.8039855814943;1877.8497720182352;1877.9718304225448;1910.3695780392436;777.149021821105;796.8167321516083;822.4571782623559;892.3262936482914;949.5916755794937;18016.549586260186;22082;22331.66502502464;22385.328440146823;22435.663465048037;931.1759093202429;936.8828896387952;938.6292414496372;979.3095867413457;980.3039841712322;9589.760255993517;11007;11384.48769805344;11718.06255499558;11910.642680719593;572597.2740272627;582155.0892245596;586970;593941;600175;119071.40464297622;119490;121654.78345216611;124555;125684;990.1187942730832;1030.217035053365;1068.3429690740138;1068.8396740488988;1070.1118072000222;19412.708809368152;19564.804351956867;19654.508637284183;19822.50443738918;19844.20623175057;50942;51553;52747.736261318445;53530.732346338016;53620.463795361546;74598.25401745913;74643;74899.25100748923;75152.62423687846;76016.61991690006;7931.127575966524;7954.164812694815;8028.237317454832;8078.959605201936;8166.4283500154415;12871;12998.155119917175;13167.078304518593;13276.402561884932;13277.336133193496;9007.369484136048;9115.769371134811;9584.04159584064;10749.247552671239;10895.346279223271;8371.62327695243;8439.831203375776;10437.217208709226;10514.053735163872;10738.73153171177;10210.591576336863;10769.9461502692;10999.505022274177;11164.497597607964;11804.586839460577;6822.283660215804;6995.545289556163;7351.8529629408185;7447.88828167567;8249.546274954935;10521.947390263;10537.262391632512;10986.560537578407;11165.441727913409;11218.943905280421 06:18:42 INFO - PROCESS | 1658 | |3;mootools.html;1324.7946568281916;1352.3508715816408;1481.644405342718;1830.1489807239632;1871.5882505848708;7945.006874140732;7975.521468711881;8518.361122915783;9065.592048357816;9097.272218222543;7209.567425954447;8389.783481395194;8835.779105522364;8912.554372281376;8939.642414303436;2114.3234165067224;2352.623580227164;2373.5134297469062;2382.5711371953043;2388.426777573379;2315.9305220843403;2351.1300818697105;2353.470469144437;2357.9056837726557;2382.118616112041;1999.4401567561033;2371.4308565944134;2371.620540713486;2372.9406764830846;2397.184957114581;2224.6652008794754;2355.0697474497647;2362.633791762271;2375.3824005758543;2377.037299893546;2080.4278823323707;2318.4667526469007;2321.8955147018255;2321.953560928772;2326.232343326715;8832.55837208137;8972;8994.415363001384;8999.325050621164;9034.09659034098;5229.346331708536;5382.407935127172;5395.7032363220005;5407.918881216785;5416.620836541444;3441.6558344165633;3506.5967413747235;3601.4957905893198;3629.4192929131214;3640.8179591020607;850.2772643253247;993.8558908958233;1021.0759262867117;1045.46158728255;1050.154625526451;11665.416729163508;11670.124740644485;11764.117691173196;11766.823497647541;11814.173007889365;4619.145458090264;4655.883602909887;4834.057358814997;4837.927431088537;4850.587700045536;9899.554520046615;9989.150922171551;10019.348742331726;10032.849507257397;10045.447500387549;5332.013577488177;5360.249565060894;5369.6509726867625;5376.78492860289;5378.139497680352;4366.257736184856;4501.909961800811;4717.622590192845;4877.609791216694;4937.407511098691;3520.1727594015388;3595.838187281526;3887.4168874669026;3997.8400863965117;4016.2971479990883;1294.4369199398293;1295.475332490343;1302.5441095616459;1307.8103674967076;1314.185205172799;782.5109306683323;846.6951897316961;866.5442057343597;893.1470445724318;917.8026724254318;5545.944540554543;5831.2127862738835;5887.381824908323;5941.979726892877;6024.728887199999;867.483847110968;921.1019256225127;922.7324076017879;962.841131213356;986.0681655835167;3469.132716820795;3623.5289412376223;3691.963080369162;3898.6686131678557;3912.2371137628456;10581;10740.78518429627;10839.83740243897;10882.891171088346;10886.782264354826;10791.6222932197;10825;10825.83761243582;10851.620193293355;10919.727006824734;7863.9213607863185;8162.510249385056;8268.338532917352;8301.169883011771;8325.916740832636;5056.544910958031;5378.704171270618;5508.980838544882;5538.141588053858;5642.097264437671;8562.186592273723;8849.159329863653;9630.32587718853;9891.739139087242;10021;9830.213582913493;9964.451955142536;10067.597296108073;10164.136048435967;10286.434246116536;6193.752249909953;6971.44228461732;7226.4218862491925;7661.770146895602;7780.1052878919645;8366.916330836613;8466.534340611079;8584.484930904166;8584.828303433771;8585.227329540121;7286.380657643947;7424.331810137113;7479.775606731807;7517.361024312776;7580.431467639839;7296;7325.157606875277;7411.8147046324275;7534.284242996907;7582.090149182133;5566.359868615161;6996.650167491503;7080.008798768074;7091.361777440055;7096.112985876765;4921.360223171108;4976.303317535476;4976.800927962841;5026.145555255688;5029.44676085623;4881.389826271716;4972.5027497249985;5034.0686972910125;5134.075866344094;5142.485751424828 06:18:42 INFO - PROCESS | 1658 | |4;prototype.html;291.2340544368311;301.062191274181;303.1798983748959;305.869811048177;310.81972455975534;4812.542808433198;5403.729813509319;5501.037318469267;5529.944700552994;5565.443455654433;4478.544473046257;5236.476352364762;5424.972875135619;5460;5477.945220547794;3672.429299490354;4201.962048406521;4212.705110642256;4218.557051509593;4221.197972385252;3828.559715632696;4068.328725760261;4155.2728272552185;4191.987845834002;4232.449781528412;3070.07897630711;3995.720299579031;4118.588141185887;4127.649149822269;4200.684948628865;3616.7468277220605;3694.2980833641523;3797.2785170817456;4189.748615083075;4203.558626344251;3034.908952731422;3530.8171762459606;3534.7879127252445;3681.11653203232;3695.63043695631;4166.979165104185;4502.212112880233;4685.789139488731;5289.338832645919;5303.965726683298;4193.811278492475;4349.739015659071;4723.315119307716;4744.793670942993;4778.61420188145;4683.625309975194;5100.719460429675;5476.67139971603;5777.335606405275;5893.675847828367;3088.552159936819;3459.567554055743;3541.6281290464644;3775.452559378902;3901.8634347797692;5397.4062853086125;6090.360512146205;6130.570860039799;6157.476614487819;6167.229096362955;3499.772514786531;3984.183242435352;3992.0219546211365;4010.4184893190322;4017.4777278954134;3910.354791459434;3956.7823769692945;4015.4579131817413;4032.5362583303295;4046.7571945683353;3942.7634341939574;4649.256119020941;4704.623630109583;4713.855195885863;4724.267738500538;4886.900447399378;5322.760475778599;5500;5754.884902302014;5913.733881975321;1201.552129683729;1287.8905293050195;1328.1499840102342;1329.1657512632505;1366.8291463567055;778.5951305321202;892.2549671024767;900.4597241654955;924.6809850601531;927.4759760735163;4606.95393046072;4846.684965477232;5348.893022139536;5423.755930983115;5474.616776825584;5957.970210148921;5960.433758792908;5962.25471816023;5966.791162309299;6000.3999600040515;4603.562661547147;5206.921896171561;5328.106995479428;5373.462653734673;5457.263269458573;2208.6134926387817;2618.7560908568275;2633.7629613334893;2646.3648724306413;2672.412069344741;4326.264535029052;4453.235038508829;4883.853484395474;5201.763241806059;5309.33633295838;4259.850905218303;4353.997161305771;4718.178275596778;4755.267115314462;5142.537171654569;4203.330884888118;4901.9264711028645;5210.47895210476;5235.502627250406;5243.662865969153;4495.280355384945;4526;4702.858914232578;4843.854684359475;5010.423801262901;4336.809910994927;4367.061081867414;5056.115179843586;5347.732613369394;6091.5126789857595;5793.3337666167245;6082.4525792677105;6240.875182496416;6424.614523128627;6449.4840412765925;3711.109333759824;4014.498187726534;4034.4150098236178;4038;4055.3511438169753;4979.701217926936;5234.528892399702;5319.361676598833;5502.669839809624;5747.8563035924435;5696.518905084625;5813.912791308049;5927.688201601465;5954.702264886825;5969.940300596939;5961.374055724086;5967.76128954837;5996.220491336098;5997.640141591519;6004.489618382487;4788.281757736228;5073.771680274471;5402.540784033401;5744.347078865965;5826.446487583673;5890.293164820249;5897.5576831736935;5942.286925568959;5957.434043765836;5969.313528944227;4930.112579735682;5350.732463376893;5719.942800571941;5766.855828604151;5850.53195744348 06:18:42 INFO - PROCESS | 1658 | |5;yui.html;669.9799006029817;739.2792027772919;754.7509321923766;793.9841203175937;845.725139329718;15122.243887805607;15813.367465301402;15826.28781704823;15829.287682054306;15861.444849430272;14401;14506.782398264035;14598;14603.123812571253;14635.853641463582;763.6754379388738;816.9346452283818;845.0788640381983;852.4629483425433;852.7825404521869;867.9218870301671;871.1071152069148;872.9258013068865;873.5894129759004;873.76845136039;897.8069715011271;902.4630344944748;904.1862323908503;904.7466709321401;905.2712566384058;874.5889431966965;895.2569367425054;896.3859756067083;899.995500022499;900.1538553759445;663.3101574362657;663.8904580744171;664.40203816565;665.7869481765837;667.9799606011827;15383.461578844686;15545.844541554552;15576;15619.531414057596;15620.453284135001;13945.187125673598;14019.301575913412;14128.152310861278;14180.575503937356;14356.784648230285;11963.700907477296;11983.28100313984;12039.277643341427;12121.212121212093;12184.81722774159;3052.465818481779;3665.7902892045563;3719.7396182267253;3787.091098136455;3798.8860334189744;87600.56199719025;115410.42294788556;117097;117708.82291177064;118598;8582.914170858212;8901.16177824213;10858.945705271422;11010.284331518425;11192.38441885704;22364.88817555902;22551.32346029622;22620.660690089662;22622.321330360115;22807.885960570093;6900.896486552706;7995.800104997422;8023.117457079717;8185.140560241208;8264.760285957034;3902.980485097613;4391.099824535962;4468.624813228183;4495.64034877209;4524.751138687404;3369.107186595554;3498.5451891253974;3549.3256281306985;3733.7386382953478;3744.700423966076;700.6041586503711;714.6033951157158;722.7036914864985;728.6939485416138;733.5525329548971;588.8704485013291;662.6587307536622;690.9136357955256;693.1093544794915;696.345435290825;9393.248540116774;9609.183219426428;9642.276829237835;9737.513124343754;9793.36343137708;629.6127881353027;640.1901594482982;660.7918505670698;663.6847497438678;687.7730348984823;4398.868033958986;4545.272756359032;4705.505921878222;4764.809407623726;4885.096257192431;162662.18668906816;166808.497872532;168821;173515;175218;92182.53908730413;101550;128009;140861;140918.47703807487;111212;122939;123580.76419235872;125128.99999999818;126810;7682.385409167141;8050.798730031796;8407.033191183093;8824.249938755278;9124.589393477443;34795;49799.75100124476;55580;58713;65308;72297.6385118071;88105.55947220222;90398;90776.0922390794;93501;11307.095432365226;13033.218006919615;13071.542496012595;13284.667883302995;13370.799438008244;14387.640308992359;14876;14936.551903442914;15013.399464021317;15349.07905525672;11151.888481115086;13880.722385552433;13946.30268486592;13979.09135906163;14321.928390357982;11644.708882278012;13349.866501334862;13619.523316683868;13689.931550342184;13852.515161969686;13435.798463023259;13471.797923031358;13562.321883905568;13669.38487768033;13901;9333.579988900652;11274.267172633752;11292.378919159524;11451.342281879208;11940.343281119274;9790.804183916423;9974.152197063331;10845.186611004365;11406.087512999105;11783.234089784137 06:18:42 INFO - PROCESS | 1658 | __end_tp_report 06:18:42 INFO - PROCESS | 1658 | __start_cc_report 06:18:42 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,365 06:18:42 INFO - PROCESS | 1658 | __end_cc_report 06:18:42 INFO - PROCESS | 1658 | __startTimestamp1475155122363__endTimestamp 06:18:42 INFO - PROCESS | 1658 | 06:18:42 INFO - PROCESS | 1658 | ------- Summary: start ------- 06:18:42 INFO - PROCESS | 1658 | Number of tests: 6 06:18:42 INFO - PROCESS | 1658 | 06:18:42 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5705.02 Median:5935.17 stddev:656.56 (11.1%) stddev-sans-first:625.92 06:18:42 INFO - PROCESS | 1658 | Values: 3098.3 3345.3 3345.7 3352.6 3359.4 4560.5 5508.0 5598.0 5649.9 5657.9 5521.2 5642.3 5698.8 5735.2 5753.7 5118.2 5216.5 5228.7 5229.8 5241.8 5214.7 5225.0 5269.3 5306.0 5331.8 5090.7 5229.9 5234.7 5285.4 5288.2 5125.7 5233.2 5243.3 5261.6 5275.7 4812.6 5183.4 5193.0 5213.4 5217.1 5554.5 5611.0 5635.3 5664.5 5719.1 6099.3 6105.4 6181.4 6251.2 6257.2 6269.6 6271.4 6292.2 6300.0 6323.4 5696.4 5844.1 5846.0 5850.0 5887.0 6217.3 6217.8 6224.0 6254.1 6263.6 6121.0 6155.0 6162.4 6166.0 6176.3 6107.7 6256.7 6259.6 6290.1 6294.1 6061.2 6145.0 6181.8 6224.2 6230.4 6180.3 6224.0 6237.2 6245.1 6245.4 4910.7 4928.9 4932.1 4933.1 4934.3 4345.8 4356.2 4359.7 4375.7 4397.3 5822.0 5874.1 5898.4 5899.8 5940.9 6199.8 6234.7 6253.9 6272.2 6284.6 5682.8 5759.8 5780.7 5844.0 5891.1 5337.5 5371.0 5377.9 5379.6 5442.9 5196.4 6269.6 6318.5 6321.4 6346.5 5051.6 5103.7 6268.9 6284.0 6289.6 6153.2 6171.2 6175.7 6196.2 6219.9 6128.2 6136.0 6145.2 6147.5 6162.0 6067.6 6097.9 6148.3 6154.4 6174.1 5825.8 6152.9 6160.8 6181.8 6195.2 4773.4 5937.0 5977.6 6056.9 6170.9 5802.4 5983.4 5987.5 6008.5 6009.7 6117.2 6130.6 6153.1 6158.8 6188.0 5853.8 5919.4 5935.2 5991.2 6013.1 06:18:42 INFO - PROCESS | 1658 | 06:18:42 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:66963.85 Median:39406.30 stddev:148604.59 (377.1%) stddev-sans-first:148937.57 06:18:42 INFO - PROCESS | 1658 | Values: 460.3 463.1 473.6 481.2 485.8 38861.0 40427.4 40465.6 42765.1 42818.1 41466.4 41512.6 41781.4 41815.2 41920.2 9743.3 9929.0 9932.4 9963.9 9967.2 9809.5 9910.0 9936.1 9945.0 9951.3 9937.5 9959.6 9989.6 9991.9 10014.9 9906.2 9931.0 9940.7 9947.5 9974.3 9718.0 9738.9 9758.4 9774.6 9777.9 39478.0 39533.2 39791.8 39801.0 39849.0 3227.2 3309.6 3323.8 3330.8 3405.2 25209.9 29888.7 30815.0 31207.5 31723.9 3197.6 3213.4 3251.4 3277.4 3313.0 334488.0 405542.0 412579.0 414698.0 415274.0 56998.9 57328.1 57330.9 57357.4 57554.0 34501.5 34959.5 35197.6 36365.8 59899.0 16210.6 16367.3 16376.9 16379.0 16416.0 12584.4 12679.8 12688.9 12711.4 12795.0 16389.4 18738.4 18950.8 19016.3 19085.6 6518.7 7340.5 7747.7 7855.8 7886.7 812.3 959.0 989.8 1045.8 1092.5 21034.4 22022.4 24007.8 29114.7 29299.1 942.7 1005.3 1018.4 1023.3 1180.6 12824.8 14546.8 15395.2 15653.1 15963.5 672612.0 675518.0 676030.0 679818.0 681846.0 45651.5 55004.7 55486.4 55685.4 55902.7 1337.0 1356.9 1378.1 1378.4 1386.6 28770.9 29684.4 29795.7 30356.0 30434.5 49515.8 49611.8 49755.0 49894.8 49938.3 482546.0 569515.0 570283.0 570964.0 571001.0 26405.0 26479.9 26596.7 26915.7 26940.3 45601.5 46389.1 46879.5 47206.1 47388.0 38302.6 38388.0 38427.0 39684.2 39722.6 37830.6 37968.1 38143.0 39147.0 39334.6 30748.8 31411.5 37514.6 37893.4 38244.0 32934.2 35209.0 35296.1 35555.6 35632.0 25212.5 25470.0 25622.0 25996.7 26008.1 06:18:42 INFO - PROCESS | 1658 | 06:18:42 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:34581.51 Median:13453.93 stddev:97873.52 (727.5%) stddev-sans-first:98114.26 06:18:42 INFO - PROCESS | 1658 | Values: 316.3 365.2 372.1 380.9 398.3 17789.0 19326.7 19801.3 21075.2 21322.0 19569.6 20652.8 20929.0 21048.7 21116.0 5916.3 5927.6 6095.8 6201.1 6210.6 4700.0 6111.8 6119.2 6125.1 6130.5 5818.7 6003.4 6049.1 6065.6 6083.7 6022.4 6031.7 6038.9 6070.8 6083.8 5139.5 5167.9 5194.8 5269.6 5271.4 26108.3 26227.6 26379.2 26542.5 26591.6 2152.8 2303.6 2322.1 2415.6 2653.7 15558.7 15666.7 15676.5 16246.7 16656.7 1895.5 1925.6 1936.4 1937.5 1946.4 105477.5 116967.4 120139.0 120306.0 121197.0 8683.6 9172.7 11181.2 11340.0 11423.5 14008.0 14048.5 14097.3 14112.6 14135.9 7398.8 7497.5 8326.8 8400.5 8672.8 7833.8 7860.2 8753.2 8763.5 8899.0 6667.0 6716.6 6782.2 6785.7 6968.6 1828.8 1866.8 1877.8 1878.0 1910.4 777.1 796.8 822.5 892.3 949.6 18016.5 22082.0 22331.7 22385.3 22435.7 931.2 936.9 938.6 979.3 980.3 9589.8 11007.0 11384.5 11718.1 11910.6 572597.3 582155.1 586970.0 593941.0 600175.0 119071.4 119490.0 121654.8 124555.0 125684.0 990.1 1030.2 1068.3 1068.8 1070.1 19412.7 19564.8 19654.5 19822.5 19844.2 50942.0 51553.0 52747.7 53530.7 53620.5 74598.3 74643.0 74899.3 75152.6 76016.6 7931.1 7954.2 8028.2 8079.0 8166.4 12871.0 12998.2 13167.1 13276.4 13277.3 9007.4 9115.8 9584.0 10749.2 10895.3 8371.6 8439.8 10437.2 10514.1 10738.7 10210.6 10769.9 10999.5 11164.5 11804.6 6822.3 6995.5 7351.9 7447.9 8249.5 10521.9 10537.3 10986.6 11165.4 11218.9 06:18:42 INFO - PROCESS | 1658 | 06:18:42 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5668.86 Median:5027.80 stddev:3209.85 (63.8%) stddev-sans-first:3202.25 06:18:42 INFO - PROCESS | 1658 | Values: 1324.8 1352.4 1481.6 1830.1 1871.6 7945.0 7975.5 8518.4 9065.6 9097.3 7209.6 8389.8 8835.8 8912.6 8939.6 2114.3 2352.6 2373.5 2382.6 2388.4 2315.9 2351.1 2353.5 2357.9 2382.1 1999.4 2371.4 2371.6 2372.9 2397.2 2224.7 2355.1 2362.6 2375.4 2377.0 2080.4 2318.5 2321.9 2322.0 2326.2 8832.6 8972.0 8994.4 8999.3 9034.1 5229.3 5382.4 5395.7 5407.9 5416.6 3441.7 3506.6 3601.5 3629.4 3640.8 850.3 993.9 1021.1 1045.5 1050.2 11665.4 11670.1 11764.1 11766.8 11814.2 4619.1 4655.9 4834.1 4837.9 4850.6 9899.6 9989.2 10019.3 10032.8 10045.4 5332.0 5360.2 5369.7 5376.8 5378.1 4366.3 4501.9 4717.6 4877.6 4937.4 3520.2 3595.8 3887.4 3997.8 4016.3 1294.4 1295.5 1302.5 1307.8 1314.2 782.5 846.7 866.5 893.1 917.8 5545.9 5831.2 5887.4 5942.0 6024.7 867.5 921.1 922.7 962.8 986.1 3469.1 3623.5 3692.0 3898.7 3912.2 10581.0 10740.8 10839.8 10882.9 10886.8 10791.6 10825.0 10825.8 10851.6 10919.7 7863.9 8162.5 8268.3 8301.2 8325.9 5056.5 5378.7 5509.0 5538.1 5642.1 8562.2 8849.2 9630.3 9891.7 10021.0 9830.2 9964.5 10067.6 10164.1 10286.4 6193.8 6971.4 7226.4 7661.8 7780.1 8366.9 8466.5 8584.5 8584.8 8585.2 7286.4 7424.3 7479.8 7517.4 7580.4 7296.0 7325.2 7411.8 7534.3 7582.1 5566.4 6996.7 7080.0 7091.4 7096.1 4921.4 4976.3 4976.8 5026.1 5029.4 4881.4 4972.5 5034.1 5134.1 5142.5 06:18:42 INFO - PROCESS | 1658 | 06:18:42 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:4500.55 Median:4828.20 stddev:1419.62 (29.4%) stddev-sans-first:1388.00 06:18:42 INFO - PROCESS | 1658 | Values: 291.2 301.1 303.2 305.9 310.8 4812.5 5403.7 5501.0 5529.9 5565.4 4478.5 5236.5 5425.0 5460.0 5477.9 3672.4 4202.0 4212.7 4218.6 4221.2 3828.6 4068.3 4155.3 4192.0 4232.4 3070.1 3995.7 4118.6 4127.6 4200.7 3616.7 3694.3 3797.3 4189.7 4203.6 3034.9 3530.8 3534.8 3681.1 3695.6 4167.0 4502.2 4685.8 5289.3 5304.0 4193.8 4349.7 4723.3 4744.8 4778.6 4683.6 5100.7 5476.7 5777.3 5893.7 3088.6 3459.6 3541.6 3775.5 3901.9 5397.4 6090.4 6130.6 6157.5 6167.2 3499.8 3984.2 3992.0 4010.4 4017.5 3910.4 3956.8 4015.5 4032.5 4046.8 3942.8 4649.3 4704.6 4713.9 4724.3 4886.9 5322.8 5500.0 5754.9 5913.7 1201.6 1287.9 1328.1 1329.2 1366.8 778.6 892.3 900.5 924.7 927.5 4607.0 4846.7 5348.9 5423.8 5474.6 5958.0 5960.4 5962.3 5966.8 6000.4 4603.6 5206.9 5328.1 5373.5 5457.3 2208.6 2618.8 2633.8 2646.4 2672.4 4326.3 4453.2 4883.9 5201.8 5309.3 4259.9 4354.0 4718.2 4755.3 5142.5 4203.3 4901.9 5210.5 5235.5 5243.7 4495.3 4526.0 4702.9 4843.9 5010.4 4336.8 4367.1 5056.1 5347.7 6091.5 5793.3 6082.5 6240.9 6424.6 6449.5 3711.1 4014.5 4034.4 4038.0 4055.4 4979.7 5234.5 5319.4 5502.7 5747.9 5696.5 5813.9 5927.7 5954.7 5969.9 5961.4 5967.8 5996.2 5997.6 6004.5 4788.3 5073.8 5402.5 5744.3 5826.4 5890.3 5897.6 5942.3 5957.4 5969.3 4930.1 5350.7 5719.9 5766.9 5850.5 06:18:42 INFO - PROCESS | 1658 | 06:18:42 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:25188.51 Median:3739.22 stddev:41593.72 (1112.4%) stddev-sans-first:41669.66 06:18:42 INFO - PROCESS | 1658 | Values: 670.0 739.3 754.8 794.0 845.7 15122.2 15813.4 15826.3 15829.3 15861.4 14401.0 14506.8 14598.0 14603.1 14635.9 763.7 816.9 845.1 852.5 852.8 867.9 871.1 872.9 873.6 873.8 897.8 902.5 904.2 904.7 905.3 874.6 895.3 896.4 900.0 900.2 663.3 663.9 664.4 665.8 668.0 15383.5 15545.8 15576.0 15619.5 15620.5 13945.2 14019.3 14128.2 14180.6 14356.8 11963.7 11983.3 12039.3 12121.2 12184.8 3052.5 3665.8 3719.7 3787.1 3798.9 87600.6 115410.4 117097.0 117708.8 118598.0 8582.9 8901.2 10858.9 11010.3 11192.4 22364.9 22551.3 22620.7 22622.3 22807.9 6900.9 7995.8 8023.1 8185.1 8264.8 3903.0 4391.1 4468.6 4495.6 4524.8 3369.1 3498.5 3549.3 3733.7 3744.7 700.6 714.6 722.7 728.7 733.6 588.9 662.7 690.9 693.1 696.3 9393.2 9609.2 9642.3 9737.5 9793.4 629.6 640.2 660.8 663.7 687.8 4398.9 4545.3 4705.5 4764.8 4885.1 162662.2 166808.5 168821.0 173515.0 175218.0 92182.5 101550.0 128009.0 140861.0 140918.5 111212.0 122939.0 123580.8 125129.0 126810.0 7682.4 8050.8 8407.0 8824.2 9124.6 34795.0 49799.8 55580.0 58713.0 65308.0 72297.6 88105.6 90398.0 90776.1 93501.0 11307.1 13033.2 13071.5 13284.7 13370.8 14387.6 14876.0 14936.6 15013.4 15349.1 11151.9 13880.7 13946.3 13979.1 14321.9 11644.7 13349.9 13619.5 13689.9 13852.5 13435.8 13471.8 13562.3 13669.4 13901.0 9333.6 11274.3 11292.4 11451.3 11940.3 9790.8 9974.2 10845.2 11406.1 11783.2 06:18:42 INFO - PROCESS | 1658 | -------- Summary: end -------- 06:18:42 INFO - PROCESS | 1658 | 06:18:42 INFO - TEST-INFO | 1658: exit 0 06:18:42 INFO - TEST-OK | dromaeo_css | took 1113437ms 06:18:42 INFO - TEST-START | kraken 06:18:42 INFO - operating with platform_type : mac_ 06:18:42 INFO - Initialising browser for kraken test... 06:18:42 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/tmpcYbqwR/profile http://localhost:49279/getInfo.html) 06:18:45 INFO - PROCESS | 1670 | 2016-09-29 06:18:45.704 plugin-container[1671:9951] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b1f, name = 'com.apple.tsm.portname' 06:18:45 INFO - PROCESS | 1670 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:18:45 INFO - PROCESS | 1670 | 2016-09-29 06:18:45.707 plugin-container[1671:9951] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 06:18:45 INFO - PROCESS | 1670 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:18:45 INFO - PROCESS | 1670 | 2016-09-29 06:18:45.707 plugin-container[1671:9951] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:18:45 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 06:18:45 INFO - PROCESS | 1670 | colorDepth:24 06:18:45 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 06:18:45 INFO - PROCESS | 1670 | __metrics 06:18:45 ERROR - PROCESS | 1670 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3076: TypeError: this.tabs is undefined 06:18:46 INFO - TEST-INFO | 1670: exit 0 06:18:46 INFO - Browser initialized. 06:18:46 INFO - Running cycle 1/1 for kraken test... 06:18:46 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/tmpcYbqwR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:18:49 INFO - PROCESS | 1672 | 2016-09-29 06:18:49.010 plugin-container[1673:10063] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.tsm.portname' 06:18:49 INFO - PROCESS | 1672 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:18:49 INFO - PROCESS | 1672 | 2016-09-29 06:18:49.013 plugin-container[1673:10063] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b17, name = 'com.apple.CFPasteboardClient' 06:18:49 INFO - PROCESS | 1672 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:18:49 INFO - PROCESS | 1672 | 2016-09-29 06:18:49.013 plugin-container[1673:10063] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:18:58 INFO - PROCESS | 1672 | RSS: Main: 239661056 06:18:58 INFO - PROCESS | 1672 | 06:19:01 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 06:19:02 INFO - PROCESS | 1672 | RSS: Main: 253235200 06:19:02 INFO - PROCESS | 1672 | 06:19:05 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 06:19:05 INFO - PROCESS | 1672 | RSS: Main: 260751360 06:19:05 INFO - PROCESS | 1672 | 06:19:09 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 06:19:09 INFO - PROCESS | 1672 | RSS: Main: 261521408 06:19:09 INFO - PROCESS | 1672 | 06:19:11 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 06:19:12 INFO - PROCESS | 1672 | RSS: Main: 263798784 06:19:12 INFO - PROCESS | 1672 | 06:19:14 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:19:15 INFO - PROCESS | 1672 | RSS: Main: 264134656 06:19:15 INFO - PROCESS | 1672 | 06:19:17 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 06:19:18 INFO - PROCESS | 1672 | RSS: Main: 264622080 06:19:18 INFO - PROCESS | 1672 | 06:19:21 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 06:19:22 INFO - PROCESS | 1672 | RSS: Main: 265199616 06:19:22 INFO - PROCESS | 1672 | 06:19:25 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 06:19:26 INFO - PROCESS | 1672 | RSS: Main: 265261056 06:19:26 INFO - PROCESS | 1672 | 06:19:27 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:19:28 INFO - PROCESS | 1672 | RSS: Main: 265637888 06:19:28 INFO - PROCESS | 1672 | 06:19:30 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:19:31 INFO - PROCESS | 1672 | RSS: Main: 265125888 06:19:31 INFO - PROCESS | 1672 | 06:19:33 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:19:34 INFO - PROCESS | 1672 | RSS: Main: 265195520 06:19:34 INFO - PROCESS | 1672 | 06:19:37 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:19:37 INFO - PROCESS | 1672 | RSS: Main: 265506816 06:19:37 INFO - PROCESS | 1672 | 06:19:40 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:19:41 INFO - PROCESS | 1672 | RSS: Main: 265310208 06:19:41 INFO - PROCESS | 1672 | 06:19:43 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 06:19:43 INFO - PROCESS | 1672 | RSS: Main: 266936320 06:19:43 INFO - PROCESS | 1672 | 06:19:43 INFO - PROCESS | 1672 | __start_tp_report 06:19:43 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 06:19:43 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 06:19:43 INFO - PROCESS | 1672 | |i|pagename|runs| 06:19:43 INFO - PROCESS | 1672 | |0;ai-astar;108;127;161;117;118;117;117;118;116;118 06:19:43 INFO - PROCESS | 1672 | |1;audio-beat-detection;173;113;145;147;130;110;99;130;182;132 06:19:43 INFO - PROCESS | 1672 | |2;audio-dft;197;209;209;210;196;194;200;266;208;192 06:19:43 INFO - PROCESS | 1672 | |3;audio-fft;80;56;60;78;61;79;86;60;79;81 06:19:43 INFO - PROCESS | 1672 | |4;audio-oscillator;89;70;80;59;99;101;123;99;71;86 06:19:43 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;99;74;134;130;103;87;92;103;91;85 06:19:43 INFO - PROCESS | 1672 | |6;imaging-darkroom;151;128;136;152;151;99;152;149;117;97 06:19:43 INFO - PROCESS | 1672 | |7;imaging-desaturate;99;92;92;137;120;91;115;149;85;143 06:19:43 INFO - PROCESS | 1672 | |8;json-parse-financial;57;58;55;59;59;59;57;58;59;59 06:19:43 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;68;76;70;86;102;111;102;101;59;85 06:19:43 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;92;90;88;97;93;108;109;91;87;77 06:19:43 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;160;146;161;140;142;143;149;165;202;143 06:19:43 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;173;196;211;172;173;174;170;172;173;172 06:19:43 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;81;95;90;95;87;79;69;68;69;69 06:19:43 INFO - PROCESS | 1672 | __end_tp_report 06:19:43 INFO - PROCESS | 1672 | __start_cc_report 06:19:43 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,931 06:19:43 INFO - PROCESS | 1672 | __end_cc_report 06:19:43 INFO - PROCESS | 1672 | __startTimestamp1475155183502__endTimestamp 06:19:43 INFO - PROCESS | 1672 | 06:19:43 INFO - PROCESS | 1672 | ------- Summary: start ------- 06:19:43 INFO - PROCESS | 1672 | Number of tests: 14 06:19:43 INFO - PROCESS | 1672 | 06:19:43 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:121.70 Median:118.00 stddev:14.53 (12.3%) stddev-sans-first:14.54 06:19:43 INFO - PROCESS | 1672 | Values: 108.0 127.0 161.0 117.0 118.0 117.0 117.0 118.0 116.0 118.0 06:19:43 INFO - PROCESS | 1672 | 06:19:43 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:136.10 Median:146.00 stddev:26.56 (18.2%) stddev-sans-first:24.59 06:19:43 INFO - PROCESS | 1672 | Values: 173.0 113.0 145.0 147.0 130.0 110.0 99.0 130.0 182.0 132.0 06:19:43 INFO - PROCESS | 1672 | 06:19:43 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:208.10 Median:208.50 stddev:21.48 (10.3%) stddev-sans-first:22.40 06:19:43 INFO - PROCESS | 1672 | Values: 197.0 209.0 209.0 210.0 196.0 194.0 200.0 266.0 208.0 192.0 06:19:43 INFO - PROCESS | 1672 | 06:19:43 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:72.00 Median:79.00 stddev:11.25 (14.2%) stddev-sans-first:11.56 06:19:43 INFO - PROCESS | 1672 | Values: 80.0 56.0 60.0 78.0 61.0 79.0 86.0 60.0 79.0 81.0 06:19:43 INFO - PROCESS | 1672 | 06:19:43 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:87.70 Median:83.00 stddev:18.73 (22.6%) stddev-sans-first:19.86 06:19:43 INFO - PROCESS | 1672 | Values: 89.0 70.0 80.0 59.0 99.0 101.0 123.0 99.0 71.0 86.0 06:19:43 INFO - PROCESS | 1672 | 06:19:43 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:99.80 Median:86.00 stddev:19.12 (22.2%) stddev-sans-first:20.28 06:19:43 INFO - PROCESS | 1672 | Values: 99.0 74.0 134.0 130.0 103.0 87.0 92.0 103.0 91.0 85.0 06:19:43 INFO - PROCESS | 1672 | 06:19:43 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:133.20 Median:151.50 stddev:22.03 (14.5%) stddev-sans-first:22.40 06:19:43 INFO - PROCESS | 1672 | Values: 151.0 128.0 136.0 152.0 151.0 99.0 152.0 149.0 117.0 97.0 06:19:43 INFO - PROCESS | 1672 | 06:19:43 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:112.30 Median:88.00 stddev:23.96 (27.2%) stddev-sans-first:24.92 06:19:43 INFO - PROCESS | 1672 | Values: 99.0 92.0 92.0 137.0 120.0 91.0 115.0 149.0 85.0 143.0 06:19:43 INFO - PROCESS | 1672 | 06:19:43 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:58.00 Median:59.00 stddev:1.33 (2.3%) stddev-sans-first:1.36 06:19:43 INFO - PROCESS | 1672 | Values: 57.0 58.0 55.0 59.0 59.0 59.0 57.0 58.0 59.0 59.0 06:19:43 INFO - PROCESS | 1672 | 06:19:43 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:86.00 Median:69.00 stddev:17.55 (25.4%) stddev-sans-first:17.36 06:19:43 INFO - PROCESS | 1672 | Values: 68.0 76.0 70.0 86.0 102.0 111.0 102.0 101.0 59.0 85.0 06:19:43 INFO - PROCESS | 1672 | 06:19:43 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:93.20 Median:90.50 stddev:9.59 (10.6%) stddev-sans-first:10.16 06:19:43 INFO - PROCESS | 1672 | Values: 92.0 90.0 88.0 97.0 93.0 108.0 109.0 91.0 87.0 77.0 06:19:43 INFO - PROCESS | 1672 | 06:19:43 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:155.10 Median:154.50 stddev:18.76 (12.1%) stddev-sans-first:19.82 06:19:43 INFO - PROCESS | 1672 | Values: 160.0 146.0 161.0 140.0 142.0 143.0 149.0 165.0 202.0 143.0 06:19:43 INFO - PROCESS | 1672 | 06:19:43 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.60 Median:173.00 stddev:13.63 (7.9%) stddev-sans-first:14.31 06:19:43 INFO - PROCESS | 1672 | Values: 173.0 196.0 211.0 172.0 173.0 174.0 170.0 172.0 173.0 172.0 06:19:43 INFO - PROCESS | 1672 | 06:19:43 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.20 Median:84.00 stddev:11.09 (13.2%) stddev-sans-first:11.76 06:19:43 INFO - PROCESS | 1672 | Values: 81.0 95.0 90.0 95.0 87.0 79.0 69.0 68.0 69.0 69.0 06:19:43 INFO - PROCESS | 1672 | -------- Summary: end -------- 06:19:43 INFO - PROCESS | 1672 | 06:19:43 INFO - PROCESS | 1672 | 06:19:43 INFO - PROCESS | 1672 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x10E0011,name=PVRManager::Msg_NotifyVSync) Channel error: cannot send/recv 06:19:43 INFO - PROCESS | 1672 | 06:19:43 INFO - PROCESS | 1672 | 06:19:43 INFO - PROCESS | 1672 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x10E0011,name=PVRManager::Msg_NotifyVSync) Channel error: cannot send/recv 06:19:43 INFO - PROCESS | 1672 | 06:19:44 INFO - TEST-INFO | 1672: exit 0 06:19:44 INFO - TEST-OK | kraken | took 61387ms 06:19:44 INFO - SUITE-END | took 1174s 06:19:44 INFO - Completed test suite (00:19:35) 06:19:44 INFO - javascript benchmark 06:19:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7312.096698127767, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3098.3493466372065, 3345.264041910779, 3345.6988871001604, 3352.631210566838, 3359.445691460909, 4560.498345182032, 5508.0, 5597.992361374951, 5649.943500564993, 5657.886842263162, 5521.171824226368, 5642.294713160855, 5698.772049118041, 5735.1970724098555, 5753.65478071316, 5118.206677964921, 5216.530512253897, 5228.712420816853, 5229.790808367661, 5241.842744717665, 5214.661047031931, 5224.973875130619, 5269.314989051418, 5306.044911915854, 5331.840044798643, 5090.720010399426, 5229.947700522984, 5234.7120908350025, 5285.365756109253, 5288.206768984644, 5125.666831655931, 5233.162693968985, 5243.318368612094, 5261.579073674096, 5275.736213189363, 4812.5668689818085, 5183.429822719498, 5193.013327467769, 5213.4004589472315, 5217.087009773312, 5554.50009499147, 5611.046122159243, 5635.323761148688, 5664.461876121762, 5719.084946408555, 6099.2795188853615, 6105.358937311607, 6181.3509581493745, 6251.156093927307, 6257.186565746424, 6269.561130720851, 6271.435570798604, 6292.2134733158355, 6299.96850015747, 6323.367663233686, 5696.401877802854, 5844.094165404326, 5846.035404158309, 5849.970750146222, 5886.969780288432, 6217.285012223561, 6217.813465596039, 6223.96888015557, 6254.124422580842, 6263.592866463665, 6120.969395152996, 6154.969225153845, 6162.352952939877, 6166.0, 6176.320604733476, 6107.747911678095, 6256.687165641791, 6259.593126446766, 6290.119383286344, 6294.087357333253, 6061.242344706911, 6144.969275153596, 6181.752729890754, 6224.159738435344, 6230.408111229426, 6180.258368995749, 6224.0, 6237.157983672237, 6245.094461303176, 6245.375462453809, 4910.705357678551, 4928.926066109011, 4932.136876046678, 4933.062718083553, 4934.333864928189, 4345.847895323712, 4356.237658409766, 4359.651227901761, 4375.7374557526655, 4397.274449715825, 5822.0, 5874.060150375919, 5898.4101589840675, 5899.823005309848, 5940.940590594125, 6199.814005579839, 6234.657093859882, 6253.874922501615, 6272.184615999982, 6284.622922624657, 5682.806610611735, 5759.827205183851, 5780.653160810365, 5844.035734103911, 5891.086881533369, 5337.466253374631, 5370.9731451342495, 5377.946220537745, 5379.623426360117, 5442.863928401822, 5196.4283928767045, 6269.564395164658, 6318.494520438354, 6321.399467050623, 6346.4605508532295, 5051.646384753032, 5103.7192954387865, 6268.874622507616, 6284.0, 6289.559730818982, 6153.169322141453, 6171.228596425447, 6175.722092505938, 6196.194494715658, 6219.875602487835, 6128.19758601264, 6135.967692822357, 6145.201123854049, 6147.538934579835, 6162.044883043135, 6067.605605635619, 6097.939020609737, 6148.292946311231, 6154.384561543989, 6174.135621012972, 5825.825225243249, 6152.938470615237, 6160.7535698571555, 6181.81454556364, 6195.194624698941, 4773.403324584427, 5936.970315148569, 5977.551683623658, 6056.878862422815, 6170.9074363885475, 5802.4197580241635, 5983.37174596661, 5987.491063259672, 6008.489278411384, 6009.699515024144, 6117.174181485442, 6130.601510901777, 6153.138560601609, 6158.815235542941, 6188.0, 5853.765849365977, 5919.4080591940465, 5935.169076329404, 5991.161237426851, 6013.067974463965], "value": 5663.347053908075, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [460.30493954129275, 463.0622988448392, 473.6329344757812, 481.22522738391206, 485.8129620096263, 38861.0, 40427.39358909615, 40465.59534404655, 42765.144697106116, 42818.143637127316, 41466.378004329956, 41512.58487415125, 41781.37327940083, 41815.16369672612, 41920.16159676812, 9743.269254805882, 9928.950355248213, 9932.404055756642, 9963.850542241873, 9967.202623790079, 9809.50952452378, 9910.0, 9936.105750482455, 9945.0, 9951.303408761389, 9937.503124843764, 9959.55182016811, 9989.55047022882, 9991.850122248172, 10014.899851001506, 9906.157976572, 9930.9503452483, 9940.701778946606, 9947.5026248688, 9974.301798874081, 9718.028197180296, 9738.90261097387, 9758.414495130315, 9774.5601447935, 9777.90222097777, 39478.0, 39533.209335813124, 39791.8010409949, 39801.0, 39849.00377490557, 3227.2415982244156, 3309.5532103166006, 3323.80057196569, 3330.833458327074, 3405.1827561385335, 25209.873950630314, 29888.70111298881, 30815.0, 31207.531887021712, 31723.889663861653, 3197.600299962505, 3213.389456003352, 3251.4472539668313, 3277.3936821687826, 3313.022658315803, 334488.0, 405542.0, 412579.0, 414698.0, 415274.0, 56998.87514060742, 57328.14007789887, 57330.85338293211, 57357.42642573521, 57554.0, 34501.48247776285, 34959.475607865905, 35197.64802351995, 36365.81817090951, 59899.0, 16210.594735131715, 16367.345306187857, 16376.918115409348, 16379.0, 16416.0, 12584.37078146089, 12679.809802852964, 12688.936555317165, 12711.364431778375, 12795.0, 16389.42637007699, 18738.437846864617, 18950.81049189518, 19016.334428294944, 19085.61828763417, 6518.706658200297, 7340.486165968331, 7747.690092396354, 7855.842883142305, 7886.684532618746, 812.3257696112213, 958.9904100958901, 989.7871957529022, 1045.7659961245652, 1092.4876521981867, 21034.44337969148, 22022.44943876416, 24007.758952541182, 29114.708852911623, 29299.121026369245, 942.7171848445439, 1005.2762011351815, 1018.4214467575376, 1023.2837014090167, 1180.6103985684704, 12824.845763881109, 14546.781798273034, 15395.153266570445, 15653.060816351055, 15963.521094367188, 672612.0, 675518.0, 676030.0, 679818.0, 681846.0, 45651.543484565394, 55004.72497637566, 55486.44513554894, 55685.44314556884, 55902.720486398124, 1336.9665758356118, 1356.8982326325565, 1378.076688618628, 1378.3521744779937, 1386.5632325817335, 28770.85614571914, 29684.406311874074, 29795.702042979294, 30356.0, 30434.543481847348, 49515.75242123767, 49611.751941240065, 49755.004899902524, 49894.75052624714, 49938.25092623541, 482546.0, 569515.0, 570283.0, 570964.0, 571001.0, 26405.01962352793, 26479.867600661873, 26596.734032659424, 26915.730842691322, 26940.32649183786, 45601.54398455973, 46389.07221855612, 46879.53120468752, 47206.055878882915, 47388.0, 38302.61697382991, 38388.0, 38427.0, 39684.2063158741, 39722.60277397189, 37830.62169378271, 37968.050798730255, 38143.0, 39147.0, 39334.60665393309, 30748.846255769473, 31411.528827067155, 37514.62485375111, 37893.43159852659, 38244.04389890275, 32934.176645583095, 35209.01439799631, 35296.11759706028, 35555.644443556266, 35632.0, 25212.487250765014, 25470.0, 25621.97512099495, 25996.740032600188, 26008.08971685982], "value": 19406.425863799024, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.2583741127057, 365.16377495535244, 372.06611405372513, 380.88763814674667, 398.2691760619264, 17789.021603811787, 19326.710099348504, 19801.306954256605, 21075.156993720233, 21322.0, 19569.608607827835, 20652.793472065274, 20928.95355232236, 21048.684269735928, 21116.0, 5916.26046744157, 5927.555433342516, 6095.847603809896, 6201.1008403781425, 6210.62736235825, 4700.0, 6111.816645500642, 6119.173911521954, 6125.081237814319, 6130.478909292716, 5818.650880947135, 6003.429674180946, 6049.1228771828055, 6065.60573562717, 6083.695815209244, 6022.367651396606, 6031.728572214261, 6038.939610603904, 6070.848228794293, 6083.847903802396, 5139.537441630271, 5167.870803229912, 5194.792208311663, 5269.578433725293, 5271.393789714193, 26108.347291317677, 26227.606585901227, 26379.208623741317, 26542.46915061688, 26591.601125983125, 2152.8277737781095, 2303.5623231585946, 2322.1059891941554, 2415.577273977047, 2653.668291463567, 15558.688826223412, 15666.68666626661, 15676.529704108782, 16246.675066498721, 16656.6668666626, 1895.4882181810833, 1925.5763731978943, 1936.448112287991, 1937.476881242071, 1946.3868881302344, 105477.47261263721, 116967.41516292449, 120139.0, 120306.0, 121197.0, 8683.565821709015, 9172.724818255463, 11181.161412893904, 11340.036096931688, 11423.485943132579, 14008.0, 14048.508302209579, 14097.295135243197, 14112.576622701336, 14135.85864141366, 7398.779201431811, 7497.475176737576, 8326.83346333082, 8400.453970491899, 8672.78318042054, 7833.764987050397, 7860.174681658344, 8753.212210901049, 8763.474191548528, 8899.0, 6666.96666516674, 6716.597004179765, 6782.20271628019, 6785.694643741043, 6968.616726080114, 1828.7531183290353, 1866.8039855814943, 1877.8497720182352, 1877.9718304225448, 1910.3695780392436, 777.149021821105, 796.8167321516083, 822.4571782623559, 892.3262936482914, 949.5916755794937, 18016.549586260186, 22082.0, 22331.66502502464, 22385.328440146823, 22435.663465048037, 931.1759093202429, 936.8828896387952, 938.6292414496372, 979.3095867413457, 980.3039841712322, 9589.760255993517, 11007.0, 11384.48769805344, 11718.06255499558, 11910.642680719593, 572597.2740272627, 582155.0892245596, 586970.0, 593941.0, 600175.0, 119071.40464297622, 119490.0, 121654.78345216611, 124555.0, 125684.0, 990.1187942730832, 1030.217035053365, 1068.3429690740138, 1068.8396740488988, 1070.1118072000222, 19412.708809368152, 19564.804351956867, 19654.508637284183, 19822.50443738918, 19844.20623175057, 50942.0, 51553.0, 52747.736261318445, 53530.732346338016, 53620.463795361546, 74598.25401745913, 74643.0, 74899.25100748923, 75152.62423687846, 76016.61991690006, 7931.127575966524, 7954.164812694815, 8028.237317454832, 8078.959605201936, 8166.4283500154415, 12871.0, 12998.155119917175, 13167.078304518593, 13276.402561884932, 13277.336133193496, 9007.369484136048, 9115.769371134811, 9584.04159584064, 10749.247552671239, 10895.346279223271, 8371.62327695243, 8439.831203375776, 10437.217208709226, 10514.053735163872, 10738.73153171177, 10210.591576336863, 10769.9461502692, 10999.505022274177, 11164.497597607964, 11804.586839460577, 6822.283660215804, 6995.545289556163, 7351.8529629408185, 7447.88828167567, 8249.546274954935, 10521.947390263, 10537.262391632512, 10986.560537578407, 11165.441727913409, 11218.943905280421], "value": 9657.782909328407, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1324.7946568281916, 1352.3508715816408, 1481.644405342718, 1830.1489807239632, 1871.5882505848708, 7945.006874140732, 7975.521468711881, 8518.361122915783, 9065.592048357816, 9097.272218222543, 7209.567425954447, 8389.783481395194, 8835.779105522364, 8912.554372281376, 8939.642414303436, 2114.3234165067224, 2352.623580227164, 2373.5134297469062, 2382.5711371953043, 2388.426777573379, 2315.9305220843403, 2351.1300818697105, 2353.470469144437, 2357.9056837726557, 2382.118616112041, 1999.4401567561033, 2371.4308565944134, 2371.620540713486, 2372.9406764830846, 2397.184957114581, 2224.6652008794754, 2355.0697474497647, 2362.633791762271, 2375.3824005758543, 2377.037299893546, 2080.4278823323707, 2318.4667526469007, 2321.8955147018255, 2321.953560928772, 2326.232343326715, 8832.55837208137, 8972.0, 8994.415363001384, 8999.325050621164, 9034.09659034098, 5229.346331708536, 5382.407935127172, 5395.7032363220005, 5407.918881216785, 5416.620836541444, 3441.6558344165633, 3506.5967413747235, 3601.4957905893198, 3629.4192929131214, 3640.8179591020607, 850.2772643253247, 993.8558908958233, 1021.0759262867117, 1045.46158728255, 1050.154625526451, 11665.416729163508, 11670.124740644485, 11764.117691173196, 11766.823497647541, 11814.173007889365, 4619.145458090264, 4655.883602909887, 4834.057358814997, 4837.927431088537, 4850.587700045536, 9899.554520046615, 9989.150922171551, 10019.348742331726, 10032.849507257397, 10045.447500387549, 5332.013577488177, 5360.249565060894, 5369.6509726867625, 5376.78492860289, 5378.139497680352, 4366.257736184856, 4501.909961800811, 4717.622590192845, 4877.609791216694, 4937.407511098691, 3520.1727594015388, 3595.838187281526, 3887.4168874669026, 3997.8400863965117, 4016.2971479990883, 1294.4369199398293, 1295.475332490343, 1302.5441095616459, 1307.8103674967076, 1314.185205172799, 782.5109306683323, 846.6951897316961, 866.5442057343597, 893.1470445724318, 917.8026724254318, 5545.944540554543, 5831.2127862738835, 5887.381824908323, 5941.979726892877, 6024.728887199999, 867.483847110968, 921.1019256225127, 922.7324076017879, 962.841131213356, 986.0681655835167, 3469.132716820795, 3623.5289412376223, 3691.963080369162, 3898.6686131678557, 3912.2371137628456, 10581.0, 10740.78518429627, 10839.83740243897, 10882.891171088346, 10886.782264354826, 10791.6222932197, 10825.0, 10825.83761243582, 10851.620193293355, 10919.727006824734, 7863.9213607863185, 8162.510249385056, 8268.338532917352, 8301.169883011771, 8325.916740832636, 5056.544910958031, 5378.704171270618, 5508.980838544882, 5538.141588053858, 5642.097264437671, 8562.186592273723, 8849.159329863653, 9630.32587718853, 9891.739139087242, 10021.0, 9830.213582913493, 9964.451955142536, 10067.597296108073, 10164.136048435967, 10286.434246116536, 6193.752249909953, 6971.44228461732, 7226.4218862491925, 7661.770146895602, 7780.1052878919645, 8366.916330836613, 8466.534340611079, 8584.484930904166, 8584.828303433771, 8585.227329540121, 7286.380657643947, 7424.331810137113, 7479.775606731807, 7517.361024312776, 7580.431467639839, 7296.0, 7325.157606875277, 7411.8147046324275, 7534.284242996907, 7582.090149182133, 5566.359868615161, 6996.650167491503, 7080.008798768074, 7091.361777440055, 7096.112985876765, 4921.360223171108, 4976.303317535476, 4976.800927962841, 5026.145555255688, 5029.44676085623, 4881.389826271716, 4972.5027497249985, 5034.0686972910125, 5134.075866344094, 5142.485751424828], "value": 4537.45957243342, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.2340544368311, 301.062191274181, 303.1798983748959, 305.869811048177, 310.81972455975534, 4812.542808433198, 5403.729813509319, 5501.037318469267, 5529.944700552994, 5565.443455654433, 4478.544473046257, 5236.476352364762, 5424.972875135619, 5460.0, 5477.945220547794, 3672.429299490354, 4201.962048406521, 4212.705110642256, 4218.557051509593, 4221.197972385252, 3828.559715632696, 4068.328725760261, 4155.2728272552185, 4191.987845834002, 4232.449781528412, 3070.07897630711, 3995.720299579031, 4118.588141185887, 4127.649149822269, 4200.684948628865, 3616.7468277220605, 3694.2980833641523, 3797.2785170817456, 4189.748615083075, 4203.558626344251, 3034.908952731422, 3530.8171762459606, 3534.7879127252445, 3681.11653203232, 3695.63043695631, 4166.979165104185, 4502.212112880233, 4685.789139488731, 5289.338832645919, 5303.965726683298, 4193.811278492475, 4349.739015659071, 4723.315119307716, 4744.793670942993, 4778.61420188145, 4683.625309975194, 5100.719460429675, 5476.67139971603, 5777.335606405275, 5893.675847828367, 3088.552159936819, 3459.567554055743, 3541.6281290464644, 3775.452559378902, 3901.8634347797692, 5397.4062853086125, 6090.360512146205, 6130.570860039799, 6157.476614487819, 6167.229096362955, 3499.772514786531, 3984.183242435352, 3992.0219546211365, 4010.4184893190322, 4017.4777278954134, 3910.354791459434, 3956.7823769692945, 4015.4579131817413, 4032.5362583303295, 4046.7571945683353, 3942.7634341939574, 4649.256119020941, 4704.623630109583, 4713.855195885863, 4724.267738500538, 4886.900447399378, 5322.760475778599, 5500.0, 5754.884902302014, 5913.733881975321, 1201.552129683729, 1287.8905293050195, 1328.1499840102342, 1329.1657512632505, 1366.8291463567055, 778.5951305321202, 892.2549671024767, 900.4597241654955, 924.6809850601531, 927.4759760735163, 4606.95393046072, 4846.684965477232, 5348.893022139536, 5423.755930983115, 5474.616776825584, 5957.970210148921, 5960.433758792908, 5962.25471816023, 5966.791162309299, 6000.3999600040515, 4603.562661547147, 5206.921896171561, 5328.106995479428, 5373.462653734673, 5457.263269458573, 2208.6134926387817, 2618.7560908568275, 2633.7629613334893, 2646.3648724306413, 2672.412069344741, 4326.264535029052, 4453.235038508829, 4883.853484395474, 5201.763241806059, 5309.33633295838, 4259.850905218303, 4353.997161305771, 4718.178275596778, 4755.267115314462, 5142.537171654569, 4203.330884888118, 4901.9264711028645, 5210.47895210476, 5235.502627250406, 5243.662865969153, 4495.280355384945, 4526.0, 4702.858914232578, 4843.854684359475, 5010.423801262901, 4336.809910994927, 4367.061081867414, 5056.115179843586, 5347.732613369394, 6091.5126789857595, 5793.3337666167245, 6082.4525792677105, 6240.875182496416, 6424.614523128627, 6449.4840412765925, 3711.109333759824, 4014.498187726534, 4034.4150098236178, 4038.0, 4055.3511438169753, 4979.701217926936, 5234.528892399702, 5319.361676598833, 5502.669839809624, 5747.8563035924435, 5696.518905084625, 5813.912791308049, 5927.688201601465, 5954.702264886825, 5969.940300596939, 5961.374055724086, 5967.76128954837, 5996.220491336098, 5997.640141591519, 6004.489618382487, 4788.281757736228, 5073.771680274471, 5402.540784033401, 5744.347078865965, 5826.446487583673, 5890.293164820249, 5897.5576831736935, 5942.286925568959, 5957.434043765836, 5969.313528944227, 4930.112579735682, 5350.732463376893, 5719.942800571941, 5766.855828604151, 5850.53195744348], "value": 4051.138585555492, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [669.9799006029817, 739.2792027772919, 754.7509321923766, 793.9841203175937, 845.725139329718, 15122.243887805607, 15813.367465301402, 15826.28781704823, 15829.287682054306, 15861.444849430272, 14401.0, 14506.782398264035, 14598.0, 14603.123812571253, 14635.853641463582, 763.6754379388738, 816.9346452283818, 845.0788640381983, 852.4629483425433, 852.7825404521869, 867.9218870301671, 871.1071152069148, 872.9258013068865, 873.5894129759004, 873.76845136039, 897.8069715011271, 902.4630344944748, 904.1862323908503, 904.7466709321401, 905.2712566384058, 874.5889431966965, 895.2569367425054, 896.3859756067083, 899.995500022499, 900.1538553759445, 663.3101574362657, 663.8904580744171, 664.40203816565, 665.7869481765837, 667.9799606011827, 15383.461578844686, 15545.844541554552, 15576.0, 15619.531414057596, 15620.453284135001, 13945.187125673598, 14019.301575913412, 14128.152310861278, 14180.575503937356, 14356.784648230285, 11963.700907477296, 11983.28100313984, 12039.277643341427, 12121.212121212093, 12184.81722774159, 3052.465818481779, 3665.7902892045563, 3719.7396182267253, 3787.091098136455, 3798.8860334189744, 87600.56199719025, 115410.42294788556, 117097.0, 117708.82291177064, 118598.0, 8582.914170858212, 8901.16177824213, 10858.945705271422, 11010.284331518425, 11192.38441885704, 22364.88817555902, 22551.32346029622, 22620.660690089662, 22622.321330360115, 22807.885960570093, 6900.896486552706, 7995.800104997422, 8023.117457079717, 8185.140560241208, 8264.760285957034, 3902.980485097613, 4391.099824535962, 4468.624813228183, 4495.64034877209, 4524.751138687404, 3369.107186595554, 3498.5451891253974, 3549.3256281306985, 3733.7386382953478, 3744.700423966076, 700.6041586503711, 714.6033951157158, 722.7036914864985, 728.6939485416138, 733.5525329548971, 588.8704485013291, 662.6587307536622, 690.9136357955256, 693.1093544794915, 696.345435290825, 9393.248540116774, 9609.183219426428, 9642.276829237835, 9737.513124343754, 9793.36343137708, 629.6127881353027, 640.1901594482982, 660.7918505670698, 663.6847497438678, 687.7730348984823, 4398.868033958986, 4545.272756359032, 4705.505921878222, 4764.809407623726, 4885.096257192431, 162662.18668906816, 166808.497872532, 168821.0, 173515.0, 175218.0, 92182.53908730413, 101550.0, 128009.0, 140861.0, 140918.47703807487, 111212.0, 122939.0, 123580.76419235872, 125128.99999999818, 126810.0, 7682.385409167141, 8050.798730031796, 8407.033191183093, 8824.249938755278, 9124.589393477443, 34795.0, 49799.75100124476, 55580.0, 58713.0, 65308.0, 72297.6385118071, 88105.55947220222, 90398.0, 90776.0922390794, 93501.0, 11307.095432365226, 13033.218006919615, 13071.542496012595, 13284.667883302995, 13370.799438008244, 14387.640308992359, 14876.0, 14936.551903442914, 15013.399464021317, 15349.07905525672, 11151.888481115086, 13880.722385552433, 13946.30268486592, 13979.09135906163, 14321.928390357982, 11644.708882278012, 13349.866501334862, 13619.523316683868, 13689.931550342184, 13852.515161969686, 13435.798463023259, 13471.797923031358, 13562.321883905568, 13669.38487768033, 13901.0, 9333.579988900652, 11274.267172633752, 11292.378919159524, 11451.342281879208, 11940.343281119274, 9790.804183916423, 9974.152197063331, 10845.186611004365, 11406.087512999105, 11783.234089784137], "value": 7832.8443622249615, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1621.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 127.0, 161.0, 117.0, 118.0, 117.0, 117.0, 118.0, 116.0, 118.0], "value": 121.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 113.0, 145.0, 147.0, 130.0, 110.0, 99.0, 130.0, 182.0, 132.0], "value": 136.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 209.0, 209.0, 210.0, 196.0, 194.0, 200.0, 266.0, 208.0, 192.0], "value": 208.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 56.0, 60.0, 78.0, 61.0, 79.0, 86.0, 60.0, 79.0, 81.0], "value": 72.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 80.0, 59.0, 99.0, 101.0, 123.0, 99.0, 71.0, 86.0], "value": 87.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 74.0, 134.0, 130.0, 103.0, 87.0, 92.0, 103.0, 91.0, 85.0], "value": 99.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 128.0, 136.0, 152.0, 151.0, 99.0, 152.0, 149.0, 117.0, 97.0], "value": 133.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 92.0, 92.0, 137.0, 120.0, 91.0, 115.0, 149.0, 85.0, 143.0], "value": 112.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 55.0, 59.0, 59.0, 59.0, 57.0, 58.0, 59.0, 59.0], "value": 58.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 76.0, 70.0, 86.0, 102.0, 111.0, 102.0, 101.0, 59.0, 85.0], "value": 86.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 88.0, 97.0, 93.0, 108.0, 109.0, 91.0, 87.0, 77.0], "value": 93.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 146.0, 161.0, 140.0, 142.0, 143.0, 149.0, 165.0, 202.0, 143.0], "value": 155.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 196.0, 211.0, 172.0, 173.0, 174.0, 170.0, 172.0, 173.0, 172.0], "value": 178.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 95.0, 90.0, 95.0, 87.0, 79.0, 69.0, 68.0, 69.0, 69.0], "value": 80.2, "unit": "score"}]}]} 06:19:44 INFO - Return code: 0 06:19:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:19:44 ERROR - # TBPL SUCCESS # 06:19:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:19:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:19:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:19:44 INFO - 2016-09-29 06:19:44.821 screenresolution[1677:10446] starting screenresolution argv=screenresolution get 06:19:44 INFO - 2016-09-29 06:19:44.824 screenresolution[1677:10446] Display 0: 1600x1200x32@60 06:19:44 INFO - 2016-09-29 06:19:44.830 screenresolution[1678:10450] starting screenresolution argv=screenresolution list 06:19:44 INFO - Available Modes on Display 0 06:19:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:19:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:19:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:19:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:19:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:19:45 INFO - Intel Iris: 06:19:45 INFO - Chipset Model: Intel Iris 06:19:45 INFO - Type: GPU 06:19:45 INFO - Bus: Built-In 06:19:45 INFO - VRAM (Dynamic, Max): 1536 MB 06:19:45 INFO - Vendor: Intel (0x8086) 06:19:45 INFO - Device ID: 0x0a2e 06:19:45 INFO - Revision ID: 0x0009 06:19:45 INFO - Displays: 06:19:45 INFO - Display: 06:19:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:19:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:19:45 INFO - Display Serial Number: 1600x1200 60 06:19:45 INFO - Main Display: Yes 06:19:45 INFO - Mirror: Off 06:19:45 INFO - Online: Yes 06:19:45 INFO - Rotation: Supported 06:19:45 INFO - Return code: 0 06:19:45 INFO - Running post-action listener: _resource_record_post_action 06:19:45 INFO - [mozharness: 2016-09-29 13:19:45.063748Z] Finished run-tests step (success) 06:19:45 INFO - Running post-run listener: _resource_record_post_run 06:19:45 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 38414848; Write bytes: 707880448; Read time: 380; Write time: 1989 06:19:45 INFO - TinderboxPrint: CPU usage
54.4% 06:19:45 INFO - TinderboxPrint: I/O read bytes / time
38,414,848 / 380 06:19:45 INFO - TinderboxPrint: I/O write bytes / time
707,880,448 / 1,989 06:19:45 INFO - TinderboxPrint: CPU idle
2,207.3 (45.6%) 06:19:45 INFO - TinderboxPrint: CPU system
978.0 (20.2%) 06:19:45 INFO - TinderboxPrint: CPU user
1,656.3 (34.2%) 06:19:45 INFO - TinderboxPrint: Swap in / out
446,517,248 / 0 06:19:45 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 406204928; Write bytes: 401143808; Read time: 30471; Write time: 718 06:19:45 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 34908672; Write bytes: 295845376; Read time: 349; Write time: 1239 06:19:45 INFO - Running post-run listener: _upload_blobber_files 06:19:45 INFO - Blob upload gear active. 06:19:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:19:45 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 06:19:45 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:19:45 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:19:45 INFO - (blobuploader) - INFO - Open directory for files ... 06:19:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:19:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:19:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:19:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:19:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:19:47 INFO - (blobuploader) - INFO - Done attempting. 06:19:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:19:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:19:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:19:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:19:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:19:47 INFO - (blobuploader) - INFO - Done attempting. 06:19:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:19:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:19:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:19:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:19:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:19:51 INFO - (blobuploader) - INFO - Done attempting. 06:19:51 INFO - (blobuploader) - INFO - Iteration through files over. 06:19:51 INFO - Return code: 0 06:19:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:19:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:19:51 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f3ee20f43b4d41d4309ba633acb08181e6d6c59bab6f971ca11df62397ce027f23669ebd14edb66fa24cb35130654db8975c50bfb71cbf8e899340a5bc5f38e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fd2e752e53cac029c1c31100b326601754291fb2b39699e4dd4b38b9b99efe9479d3469d35e2cd1ef142257f14c78df5a966c045750f28e12e8a2732063f2407", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2f36f19a17930fb24048f2bec72b6745bda0ba3077b9f6aa194d3020187d0156bc209484224f326ad9dda84a2c96f137a79196bbf6dfa9ab8d44e0ef0b8f27af"} 06:19:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:19:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:19:51 INFO - Contents: 06:19:51 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f3ee20f43b4d41d4309ba633acb08181e6d6c59bab6f971ca11df62397ce027f23669ebd14edb66fa24cb35130654db8975c50bfb71cbf8e899340a5bc5f38e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fd2e752e53cac029c1c31100b326601754291fb2b39699e4dd4b38b9b99efe9479d3469d35e2cd1ef142257f14c78df5a966c045750f28e12e8a2732063f2407", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2f36f19a17930fb24048f2bec72b6745bda0ba3077b9f6aa194d3020187d0156bc209484224f326ad9dda84a2c96f137a79196bbf6dfa9ab8d44e0ef0b8f27af"} 06:19:51 INFO - Running post-run listener: copy_logs_to_upload_dir 06:19:51 INFO - Copying logs to upload dir... 06:19:51 INFO - mkdir: /builds/slave/test/build/upload/logs 06:19:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.728310 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-09-29 06:19:51.300907) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 06:19:51.305353) ========= 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.UJeCwHY4LX/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.gudp4MMn5F/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f3ee20f43b4d41d4309ba633acb08181e6d6c59bab6f971ca11df62397ce027f23669ebd14edb66fa24cb35130654db8975c50bfb71cbf8e899340a5bc5f38e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fd2e752e53cac029c1c31100b326601754291fb2b39699e4dd4b38b9b99efe9479d3469d35e2cd1ef142257f14c78df5a966c045750f28e12e8a2732063f2407", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2f36f19a17930fb24048f2bec72b6745bda0ba3077b9f6aa194d3020187d0156bc209484224f326ad9dda84a2c96f137a79196bbf6dfa9ab8d44e0ef0b8f27af"} build_url:https://queue.taskcluster.net/v1/task/VFbplssDRn-qdBbbQ8MpFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011005 build_url: 'https://queue.taskcluster.net/v1/task/VFbplssDRn-qdBbbQ8MpFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f3ee20f43b4d41d4309ba633acb08181e6d6c59bab6f971ca11df62397ce027f23669ebd14edb66fa24cb35130654db8975c50bfb71cbf8e899340a5bc5f38e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fd2e752e53cac029c1c31100b326601754291fb2b39699e4dd4b38b9b99efe9479d3469d35e2cd1ef142257f14c78df5a966c045750f28e12e8a2732063f2407", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2f36f19a17930fb24048f2bec72b6745bda0ba3077b9f6aa194d3020187d0156bc209484224f326ad9dda84a2c96f137a79196bbf6dfa9ab8d44e0ef0b8f27af"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 06:19:51.347962) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 06:19:51.348280) ========= 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.UJeCwHY4LX/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.gudp4MMn5F/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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.005119 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 06:19:51.402669) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 06:19:51.402997) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 06:19:51.403315) ========= ========= Total master_lag: 0.23 =========