builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0154 starttime: 1494023974.83 results: success (0) buildid: 20170505140954 builduid: b80f32ec100b42a6839d64efd7f6ff0c revision: ff83fde8be946eabcf27ea97d4676f601c122194 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-05 15:39:34.832173) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-05 15:39:34.832546) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2017-05-05 15:39:34.832851) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2017-05-05 15:39:37.904002) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 15:39:37.905468) ========= 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.qeD5qGfeuu/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.RciORhtEah/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 --2017-05-05 15:39:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 27.6M=0s 2017-05-05 15:39:38 (27.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.308461 ========= master_lag: 0.26 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 15:39:38.471616) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2017-05-05 15:39:38.471945) ========= 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.qeD5qGfeuu/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.RciORhtEah/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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.051915 ========= master_lag: 2.89 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2017-05-05 15:39:41.417418) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-05-05 15:39:41.417796) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ff83fde8be946eabcf27ea97d4676f601c122194 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev ff83fde8be946eabcf27ea97d4676f601c122194 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qeD5qGfeuu/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.RciORhtEah/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 2017-05-05 15:39:41,517 truncating revision to first 12 chars 2017-05-05 15:39:41,517 Setting DEBUG logging. 2017-05-05 15:39:41,517 attempt 1/10 2017-05-05 15:39:41,518 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ff83fde8be94?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-05 15:39:42,304 unpacking tar archive at: mozilla-central-ff83fde8be94/testing/mozharness/ program finished with exit code 0 elapsedTime=1.052761 ========= master_lag: 3.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-05-05 15:39:45.598263) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-05 15:39:45.598587) ========= script_repo_revision: ff83fde8be946eabcf27ea97d4676f601c122194 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-05 15:39:45.598983) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-05 15:39:45.599241) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-05 15:39:45.627395) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-05-05 15:39:45.627795) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qeD5qGfeuu/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.RciORhtEah/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 15:39:45 INFO - MultiFileLogger online at 20170505 15:39:45 in /builds/slave/test 15:39:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:39:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:39:45 INFO - {'append_to_log': False, 15:39:45 INFO - 'base_work_dir': '/builds/slave/test', 15:39:45 INFO - 'blob_upload_branch': 'Firefox', 15:39:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:39:45 INFO - 'branch': 'Firefox', 15:39:45 INFO - 'buildbot_json_path': 'buildprops.json', 15:39:45 INFO - 'config_files': ('talos/mac_config.py',), 15:39:45 INFO - 'default_actions': ('clobber', 15:39:45 INFO - 'read-buildbot-config', 15:39:45 INFO - 'download-and-extract', 15:39:45 INFO - 'populate-webroot', 15:39:45 INFO - 'create-virtualenv', 15:39:45 INFO - 'install', 15:39:45 INFO - 'run-tests'), 15:39:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:39:45 INFO - 'download_minidump_stackwalk': True, 15:39:45 INFO - 'download_symbols': 'ondemand', 15:39:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:39:45 INFO - 'tooltool.py': '/tools/tooltool.py', 15:39:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:39:45 INFO - '/tools/misc-python/virtualenv.py')}, 15:39:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:39:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:39:45 INFO - 'gecko_profile': False, 15:39:45 INFO - 'gecko_profile_interval': 0, 15:39:45 INFO - 'installer_path': 'installer.exe', 15:39:45 INFO - 'log_level': 'info', 15:39:45 INFO - 'log_name': 'talos', 15:39:45 INFO - 'log_to_console': True, 15:39:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:39:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:39:45 INFO - 'opt_config_files': (), 15:39:45 INFO - 'pip_index': False, 15:39:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:39:45 INFO - 'cmd': ('bash', 15:39:45 INFO - '-c', 15:39:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:39:45 INFO - 'enabled': True, 15:39:45 INFO - 'halt_on_failure': False, 15:39:45 INFO - 'name': 'check_screen_resolution'},), 15:39:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:39:45 INFO - 'cmd': ('bash', 15:39:45 INFO - '-c', 15:39:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:39:45 INFO - 'enabled': True, 15:39:45 INFO - 'halt_on_failure': False, 15:39:45 INFO - 'name': 'check_screen_resolution'},), 15:39:45 INFO - 'run_cmd_checks_enabled': True, 15:39:45 INFO - 'suite': 'dromaeojs-e10s', 15:39:45 INFO - 'system_bits': '32', 15:39:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:39:45 INFO - 'title': 't-yosemite-r7-0154', 15:39:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:39:45 INFO - 'use_talos_json': True, 15:39:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:39:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:39:45 INFO - 'work_dir': 'build'} 15:39:45 INFO - [mozharness: 2017-05-05 22:39:45.836647Z] Running clobber step. 15:39:45 INFO - Running pre-action listener: _resource_record_pre_action 15:39:45 INFO - Running main action method: clobber 15:39:45 INFO - rmtree: /builds/slave/test/build 15:39:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:39:50 INFO - Running post-action listener: _resource_record_post_action 15:39:50 INFO - [mozharness: 2017-05-05 22:39:50.499901Z] Finished clobber step (success) 15:39:50 INFO - [mozharness: 2017-05-05 22:39:50.500032Z] Running read-buildbot-config step. 15:39:50 INFO - Running pre-action listener: _resource_record_pre_action 15:39:50 INFO - Running main action method: read_buildbot_config 15:39:50 INFO - Using buildbot properties: 15:39:50 INFO - { 15:39:50 INFO - "project": "", 15:39:50 INFO - "product": "firefox", 15:39:50 INFO - "script_repo_revision": "production", 15:39:50 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 15:39:50 INFO - "repository": "", 15:39:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 15:39:50 INFO - "buildid": "20170505140954", 15:39:50 INFO - "pgo_build": "False", 15:39:50 INFO - "basedir": "/builds/slave/test", 15:39:50 INFO - "buildnumber": 61, 15:39:50 INFO - "slavename": "t-yosemite-r7-0154", 15:39:50 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 15:39:50 INFO - "platform": "yosemite_r7", 15:39:50 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 15:39:50 INFO - "branch": "mozilla-central", 15:39:50 INFO - "repo_path": "mozilla-central", 15:39:50 INFO - "revision": "ff83fde8be946eabcf27ea97d4676f601c122194", 15:39:50 INFO - "stage_platform": "macosx64", 15:39:50 INFO - "builduid": "b80f32ec100b42a6839d64efd7f6ff0c", 15:39:50 INFO - "slavebuilddir": "test" 15:39:50 INFO - } 15:39:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 15:39:50 INFO - Running post-action listener: _resource_record_post_action 15:39:50 INFO - [mozharness: 2017-05-05 22:39:50.503223Z] Finished read-buildbot-config step (success) 15:39:50 INFO - [mozharness: 2017-05-05 22:39:50.503356Z] Running download-and-extract step. 15:39:50 INFO - Running pre-action listener: _resource_record_pre_action 15:39:50 INFO - Running main action method: download_and_extract 15:39:50 INFO - mkdir: /builds/slave/test/build/tests 15:39:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:50 INFO - trying https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 15:39:50 INFO - Downloading https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 15:39:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 15:39:51 INFO - Downloaded 1325 bytes. 15:39:51 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 15:39:51 INFO - Using the following test package requirements: 15:39:51 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:39:51 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 15:39:51 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:39:51 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:39:51 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 15:39:51 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:39:51 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 15:39:51 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:39:51 INFO - u'jsshell-mac.zip'], 15:39:51 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:39:51 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 15:39:51 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:39:51 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:39:51 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 15:39:51 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:39:51 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 15:39:51 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:39:51 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 15:39:51 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:39:51 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 15:39:51 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:39:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:39:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:39:51 INFO - Fetch https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:39:53 INFO - Content-Length response header: 52703592 15:39:53 INFO - Bytes received: 52703592 15:40:09 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:40:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:40:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:40:09 INFO - Fetch https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:40:11 INFO - Content-Length response header: 52703592 15:40:11 INFO - Bytes received: 52703592 15:40:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 15:40:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:40:26 INFO - Fetch https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 15:40:27 INFO - Content-Length response header: 14408697 15:40:27 INFO - Bytes received: 14408697 15:40: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')]} 15:40:27 INFO - trying https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:40:27 INFO - Downloading https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 15:40:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 15:40:30 INFO - Downloaded 70882031 bytes. 15:40:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:40:30 INFO - mkdir: /builds/slave/test/properties 15:40:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:40:30 INFO - Writing to file /builds/slave/test/properties/build_url 15:40:30 INFO - Contents: 15:40:30 INFO - build_url:https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:40:30 INFO - Running post-action listener: _resource_record_post_action 15:40:30 INFO - Running post-action listener: set_extra_try_arguments 15:40:30 INFO - [mozharness: 2017-05-05 22:40:30.991163Z] Finished download-and-extract step (success) 15:40:30 INFO - [mozharness: 2017-05-05 22:40:30.991293Z] Running populate-webroot step. 15:40:30 INFO - Running pre-action listener: _resource_record_pre_action 15:40:30 INFO - Running main action method: populate_webroot 15:40:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:40:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:40:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:40:31 INFO - '--tppagecycles', 15:40:31 INFO - '7'], 15:40:31 INFO - 'tests': ['tsvgm']}}, 15:40:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:40:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:40:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:40:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:40:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:40:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:40:31 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:40:31 INFO - 'talos_options': ['--disable-e10s'], 15:40:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:40:31 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:40:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:40:31 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:40:31 INFO - 'talos_options': ['--disable-e10s'], 15:40:31 INFO - 'tests': ['damp', 'tps']}, 15:40:31 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:40:31 INFO - 'tests': ['damp', 'tps']}, 15:40:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:40:31 INFO - 'tests': ['dromaeo_dom']}, 15:40:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:40:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:40:31 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 15:40:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:40:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:40:31 INFO - 'tests': ['a11yr', 15:40:31 INFO - 'ts_paint', 15:40:31 INFO - 'tpaint', 15:40:31 INFO - 'sessionrestore', 15:40:31 INFO - 'sessionrestore_no_auto_restore', 15:40:31 INFO - 'tabpaint']}, 15:40:31 INFO - 'other-e10s': {'tests': ['a11yr', 15:40:31 INFO - 'ts_paint', 15:40:31 INFO - 'tpaint', 15:40:31 INFO - 'sessionrestore', 15:40:31 INFO - 'sessionrestore_no_auto_restore', 15:40:31 INFO - 'tabpaint']}, 15:40:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:40:31 INFO - 'ts_paint', 15:40:31 INFO - 'tpaint', 15:40:31 INFO - 'sessionrestore', 15:40:31 INFO - 'sessionrestore_no_auto_restore', 15:40:31 INFO - 'tabpaint']}, 15:40:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:40:31 INFO - 'ts_paint', 15:40:31 INFO - 'tpaint', 15:40:31 INFO - 'sessionrestore', 15:40:31 INFO - 'sessionrestore_no_auto_restore', 15:40:31 INFO - 'tabpaint']}, 15:40:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:40:31 INFO - 'tests': ['a11yr', 15:40:31 INFO - 'ts_paint', 15:40:31 INFO - 'tpaint', 15:40:31 INFO - 'sessionrestore', 15:40:31 INFO - 'sessionrestore_no_auto_restore', 15:40:31 INFO - 'tabpaint']}, 15:40:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:40:31 INFO - 'tests': ['a11yr', 15:40:31 INFO - 'ts_paint', 15:40:31 INFO - 'tpaint', 15:40:31 INFO - 'sessionrestore', 15:40:31 INFO - 'sessionrestore_no_auto_restore', 15:40:31 INFO - 'tabpaint']}, 15:40:31 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 15:40:31 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 15:40:31 INFO - 'bloom_basic_ref']}, 15:40:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:40:31 INFO - 'tests': ['tsvgx', 15:40:31 INFO - 'tsvgr_opacity', 15:40:31 INFO - 'tart', 15:40:31 INFO - 'tscrollx', 15:40:31 INFO - 'cart', 15:40:31 INFO - 'tsvg_static']}, 15:40:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:40:31 INFO - 'tsvgr_opacity', 15:40:31 INFO - 'tart', 15:40:31 INFO - 'tscrollx', 15:40:31 INFO - 'cart', 15:40:31 INFO - 'tsvg_static']}, 15:40:31 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:40:31 INFO - 'talos_options': ['--disable-e10s'], 15:40:31 INFO - 'tests': ['tp5o']}, 15:40:31 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:40:31 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:40:31 INFO - 'talos_options': ['--disable-e10s', 15:40:31 INFO - '--xperf_path', 15:40:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:40:31 INFO - 'tests': ['tp5n']}, 15:40:31 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:40:31 INFO - 'talos_options': ['--xperf_path', 15:40:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:40:31 INFO - 'tests': ['tp5n']}}, 15:40:31 INFO - 'talos.zip': {'path': '', 15:40:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:40:31 INFO - Running post-action listener: _resource_record_post_action 15:40:31 INFO - [mozharness: 2017-05-05 22:40:31.010614Z] Finished populate-webroot step (success) 15:40:31 INFO - [mozharness: 2017-05-05 22:40:31.010725Z] Running create-virtualenv step. 15:40:31 INFO - Running pre-action listener: _resource_record_pre_action 15:40:31 INFO - Running main action method: create_virtualenv 15:40:31 INFO - Creating virtualenv /builds/slave/test/build/venv 15:40:31 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 15:40:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:40:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:40:31 INFO - Using real prefix '/tools/python27' 15:40:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:40:31 INFO - Installing distribute.............................................................................................................................................................................................done. 15:40:34 INFO - Installing pip.................done. 15:40:34 INFO - Return code: 0 15:40:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:40:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:40:35 INFO - Reading from file tmpfile_stdout 15:40:35 INFO - Output received: 15:40:35 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) 15:40:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:40:35 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')]} 15:40:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:35 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')]} 15:40:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:35 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 0x10d2b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2ba768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf6b55b6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d095d30>, '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 0x10cfbbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d08abe8>, '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.qeD5qGfeuu/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.RciORhtEah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 15:40:35 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 15:40:35 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 15:40:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qeD5qGfeuu/Render', 15:40:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:35 INFO - 'HOME': '/Users/cltbld', 15:40:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:35 INFO - 'LOGNAME': 'cltbld', 15:40:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:35 INFO - 'MOZ_NO_REMOTE': '1', 15:40:35 INFO - 'NO_EM_RESTART': '1', 15:40:35 INFO - 'PAGER': '/bin/cat', 15:40:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:35 INFO - 'PWD': '/builds/slave/test', 15:40:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:35 INFO - 'SHELL': '/bin/bash', 15:40:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RciORhtEah/Listeners', 15:40:35 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 15:40:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:35 INFO - 'USER': 'cltbld', 15:40:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:35 INFO - 'XPC_FLAGS': '0x0', 15:40:35 INFO - 'XPC_SERVICE_NAME': '0', 15:40:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:36 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 15:40:36 INFO - Cleaning up... 15:40:36 INFO - Return code: 0 15:40:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:40:36 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')]} 15:40:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:36 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')]} 15:40:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:36 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 0x10d2b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2ba768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf6b55b6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d095d30>, '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 0x10cfbbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d08abe8>, '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.qeD5qGfeuu/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.RciORhtEah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 15:40:36 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 15:40:36 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 15:40:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qeD5qGfeuu/Render', 15:40:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:36 INFO - 'HOME': '/Users/cltbld', 15:40:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:36 INFO - 'LOGNAME': 'cltbld', 15:40:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:36 INFO - 'MOZ_NO_REMOTE': '1', 15:40:36 INFO - 'NO_EM_RESTART': '1', 15:40:36 INFO - 'PAGER': '/bin/cat', 15:40:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:36 INFO - 'PWD': '/builds/slave/test', 15:40:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:36 INFO - 'SHELL': '/bin/bash', 15:40:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RciORhtEah/Listeners', 15:40:36 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 15:40:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:36 INFO - 'USER': 'cltbld', 15:40:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:36 INFO - 'XPC_FLAGS': '0x0', 15:40:36 INFO - 'XPC_SERVICE_NAME': '0', 15:40:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:36 INFO - Downloading/unpacking psutil>=3.1.1 15:40:36 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 15:40:36 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 15:40:36 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 15:40:36 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 15:40:36 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 15:40:36 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 15:40:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:40:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:40:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:40:39 INFO - Installing collected packages: psutil 15:40:39 INFO - Running setup.py install for psutil 15:40:39 INFO - building 'psutil._psutil_osx' extension 15:40:39 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 15:40:39 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 15:40:40 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 15:40:40 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 15:40:40 INFO - building 'psutil._psutil_posix' extension 15:40:40 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 15:40:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:40:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:40:40 INFO - ^ 15:40:40 INFO - 1 warning generated. 15:40:40 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 15:40:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:40:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:40:40 INFO - Successfully installed psutil 15:40:40 INFO - Cleaning up... 15:40:40 INFO - Return code: 0 15:40:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:40: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')]} 15:40:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40: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')]} 15:40:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:40 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 0x10d2b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2ba768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf6b55b6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d095d30>, '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 0x10cfbbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d08abe8>, '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.qeD5qGfeuu/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.RciORhtEah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 15:40:40 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 15:40:40 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 15:40:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qeD5qGfeuu/Render', 15:40:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:40 INFO - 'HOME': '/Users/cltbld', 15:40:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:40 INFO - 'LOGNAME': 'cltbld', 15:40:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:40 INFO - 'MOZ_NO_REMOTE': '1', 15:40:40 INFO - 'NO_EM_RESTART': '1', 15:40:40 INFO - 'PAGER': '/bin/cat', 15:40:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:40 INFO - 'PWD': '/builds/slave/test', 15:40:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:40 INFO - 'SHELL': '/bin/bash', 15:40:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RciORhtEah/Listeners', 15:40:40 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 15:40:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:40 INFO - 'USER': 'cltbld', 15:40:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:40 INFO - 'XPC_FLAGS': '0x0', 15:40:40 INFO - 'XPC_SERVICE_NAME': '0', 15:40:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:40:40 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 15:40:40 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 15:40:40 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 15:40:40 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 15:40:40 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 15:40:40 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 15:40:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:40:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:40:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:40:42 INFO - Installing collected packages: mozsystemmonitor 15:40:42 INFO - Running setup.py install for mozsystemmonitor 15:40:42 INFO - Successfully installed mozsystemmonitor 15:40:42 INFO - Cleaning up... 15:40:42 INFO - Return code: 0 15:40:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:40:42 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')]} 15:40:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:42 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')]} 15:40:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:42 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 0x10d2b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2ba768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf6b55b6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d095d30>, '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 0x10cfbbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d08abe8>, '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.qeD5qGfeuu/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.RciORhtEah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 15:40:42 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 15:40:42 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 15:40:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qeD5qGfeuu/Render', 15:40:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:42 INFO - 'HOME': '/Users/cltbld', 15:40:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:42 INFO - 'LOGNAME': 'cltbld', 15:40:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:42 INFO - 'MOZ_NO_REMOTE': '1', 15:40:42 INFO - 'NO_EM_RESTART': '1', 15:40:42 INFO - 'PAGER': '/bin/cat', 15:40:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:42 INFO - 'PWD': '/builds/slave/test', 15:40:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:42 INFO - 'SHELL': '/bin/bash', 15:40:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RciORhtEah/Listeners', 15:40:42 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 15:40:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:42 INFO - 'USER': 'cltbld', 15:40:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:42 INFO - 'XPC_FLAGS': '0x0', 15:40:42 INFO - 'XPC_SERVICE_NAME': '0', 15:40:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:42 INFO - Downloading/unpacking jsonschema==2.5.1 15:40:42 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 15:40:42 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 15:40:42 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 15:40:42 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 15:40:42 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 15:40:42 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 15:40:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:40:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:40:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:40:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:44 INFO - Downloading functools32-3.2.3-2.tar.gz 15:40:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:40:44 INFO - warning: no files found matching '*.txt' 15:40:44 INFO - no previously-included directories found matching 'build' 15:40:44 INFO - no previously-included directories found matching 'dist' 15:40:44 INFO - no previously-included directories found matching '.git*' 15:40:44 INFO - Installing collected packages: jsonschema, functools32 15:40:44 INFO - Running setup.py install for functools32 15:40:44 INFO - warning: no files found matching '*.txt' 15:40:44 INFO - no previously-included directories found matching 'build' 15:40:44 INFO - no previously-included directories found matching 'dist' 15:40:44 INFO - no previously-included directories found matching '.git*' 15:40:44 INFO - Successfully installed jsonschema functools32 15:40:44 INFO - Cleaning up... 15:40:44 INFO - Return code: 0 15:40:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:40:44 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')]} 15:40:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:44 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')]} 15:40:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:44 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 0x10d2b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2ba768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf6b55b6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d095d30>, '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 0x10cfbbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d08abe8>, '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.qeD5qGfeuu/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.RciORhtEah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 15:40:44 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 15:40:44 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 15:40:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qeD5qGfeuu/Render', 15:40:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:44 INFO - 'HOME': '/Users/cltbld', 15:40:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:44 INFO - 'LOGNAME': 'cltbld', 15:40:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:44 INFO - 'MOZ_NO_REMOTE': '1', 15:40:44 INFO - 'NO_EM_RESTART': '1', 15:40:44 INFO - 'PAGER': '/bin/cat', 15:40:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:44 INFO - 'PWD': '/builds/slave/test', 15:40:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:44 INFO - 'SHELL': '/bin/bash', 15:40:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RciORhtEah/Listeners', 15:40:44 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 15:40:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:44 INFO - 'USER': 'cltbld', 15:40:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:44 INFO - 'XPC_FLAGS': '0x0', 15:40:44 INFO - 'XPC_SERVICE_NAME': '0', 15:40:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:40:44 INFO - Cleaning up... 15:40:44 INFO - Return code: 0 15:40:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:40:44 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')]} 15:40:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:44 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')]} 15:40:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:44 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 0x10d2b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2ba768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf6b55b6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d095d30>, '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 0x10cfbbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d08abe8>, '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.qeD5qGfeuu/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.RciORhtEah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 15:40:44 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 15:40:44 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 15:40:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qeD5qGfeuu/Render', 15:40:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:44 INFO - 'HOME': '/Users/cltbld', 15:40:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:44 INFO - 'LOGNAME': 'cltbld', 15:40:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:44 INFO - 'MOZ_NO_REMOTE': '1', 15:40:44 INFO - 'NO_EM_RESTART': '1', 15:40:44 INFO - 'PAGER': '/bin/cat', 15:40:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:44 INFO - 'PWD': '/builds/slave/test', 15:40:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:44 INFO - 'SHELL': '/bin/bash', 15:40:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RciORhtEah/Listeners', 15:40:44 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 15:40:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:44 INFO - 'USER': 'cltbld', 15:40:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:44 INFO - 'XPC_FLAGS': '0x0', 15:40:44 INFO - 'XPC_SERVICE_NAME': '0', 15:40:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:45 INFO - Downloading/unpacking blobuploader==1.2.4 15:40:45 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 15:40:45 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 15:40:45 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 15:40:45 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 15:40:45 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 15:40:45 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 15:40:47 INFO - Downloading blobuploader-1.2.4.tar.gz 15:40:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:40:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:40:47 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 15:40:47 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 15:40:47 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 15:40:47 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 15:40:47 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 15:40:47 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 15:40:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:40:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:40:47 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 15:40:47 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 15:40:47 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 15:40:47 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 15:40:47 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 15:40:47 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 15:40:48 INFO - Downloading docopt-0.6.1.tar.gz 15:40:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:40:48 INFO - Installing collected packages: blobuploader, requests, docopt 15:40:48 INFO - Running setup.py install for blobuploader 15:40:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:40:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:40:48 INFO - Running setup.py install for requests 15:40:49 INFO - Running setup.py install for docopt 15:40:49 INFO - Successfully installed blobuploader requests docopt 15:40:49 INFO - Cleaning up... 15:40:49 INFO - Return code: 0 15:40:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:49 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 0x10d2b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2ba768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf6b55b6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d095d30>, '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 0x10cfbbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d08abe8>, '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.qeD5qGfeuu/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.RciORhtEah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 15:40:49 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 15:40:49 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 15:40:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qeD5qGfeuu/Render', 15:40:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:49 INFO - 'HOME': '/Users/cltbld', 15:40:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:49 INFO - 'LOGNAME': 'cltbld', 15:40:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:49 INFO - 'MOZ_NO_REMOTE': '1', 15:40:49 INFO - 'NO_EM_RESTART': '1', 15:40:49 INFO - 'PAGER': '/bin/cat', 15:40:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:49 INFO - 'PWD': '/builds/slave/test', 15:40:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:49 INFO - 'SHELL': '/bin/bash', 15:40:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RciORhtEah/Listeners', 15:40:49 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 15:40:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:49 INFO - 'USER': 'cltbld', 15:40:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:49 INFO - 'XPC_FLAGS': '0x0', 15:40:49 INFO - 'XPC_SERVICE_NAME': '0', 15:40:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:40:49 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-zxKR8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:40:49 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-V3wgxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:40:49 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-RY8WU5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:40:49 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-5lsEti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:40:49 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-P4SX4t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:40:49 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-dZVtF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:40:50 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-z7heHi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:40:50 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-exBA4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:40:50 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-gTAmaI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:40:50 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-WyVxCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:40:50 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-GcB9G5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:40:50 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-8aJ2xG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:40:50 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-zbUW9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:40:50 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-YfFXmY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:50 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-0xUNdT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:40:51 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-vcsRCq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:40:51 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-bBTjTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:40:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:40:51 INFO - Running setup.py install for manifestparser 15:40:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:40:51 INFO - Running setup.py install for mozcrash 15:40:51 INFO - Running setup.py install for mozdebug 15:40:51 INFO - Running setup.py install for mozdevice 15:40:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:40:51 INFO - Running setup.py install for mozfile 15:40:51 INFO - Running setup.py install for mozhttpd 15:40:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:40:52 INFO - Running setup.py install for mozinfo 15:40:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:40:52 INFO - Running setup.py install for mozInstall 15:40:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:40:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:40:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:40:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:40:52 INFO - Running setup.py install for mozleak 15:40:52 INFO - Running setup.py install for mozlog 15:40:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:40:52 INFO - Running setup.py install for moznetwork 15:40:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:40:52 INFO - Running setup.py install for mozprocess 15:40:52 INFO - Running setup.py install for mozprofile 15:40:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:40:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:40:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:40:53 INFO - Running setup.py install for mozrunner 15:40:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:40:53 INFO - Running setup.py install for mozscreenshot 15:40:53 INFO - Running setup.py install for moztest 15:40:53 INFO - Running setup.py install for mozversion 15:40:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:40:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:40:53 INFO - Cleaning up... 15:40:53 INFO - Return code: 0 15:40:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40:53 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')]} 15:40:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:53 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')]} 15:40:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:53 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 0x10d2b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2ba768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf6b55b6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d095d30>, '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 0x10cfbbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d08abe8>, '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.qeD5qGfeuu/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.RciORhtEah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 15:40:53 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 15:40:53 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 15:40:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qeD5qGfeuu/Render', 15:40:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:53 INFO - 'HOME': '/Users/cltbld', 15:40:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:53 INFO - 'LOGNAME': 'cltbld', 15:40:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:53 INFO - 'MOZ_NO_REMOTE': '1', 15:40:53 INFO - 'NO_EM_RESTART': '1', 15:40:53 INFO - 'PAGER': '/bin/cat', 15:40:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:53 INFO - 'PWD': '/builds/slave/test', 15:40:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:53 INFO - 'SHELL': '/bin/bash', 15:40:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RciORhtEah/Listeners', 15:40:53 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 15:40:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:53 INFO - 'USER': 'cltbld', 15:40:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:53 INFO - 'XPC_FLAGS': '0x0', 15:40:53 INFO - 'XPC_SERVICE_NAME': '0', 15:40:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:40:54 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-vmSpY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:40:54 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)) 15:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:40:54 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-j5WxWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:40:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 15:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:40:54 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-mfzpKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:40:54 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)) 15:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:40:54 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-vMUGZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:40:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 15:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:40:54 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Su1Zha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:40:54 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)) 15:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:40:54 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-aE9qTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:40:54 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)) 15:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:40:54 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-MVjwTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:40:54 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)) 15:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:40:54 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-rx_9J6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:40:54 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)) 15:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:40:54 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-PHDEMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:40:54 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)) 15:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:40:54 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-25pwV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:40:55 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)) 15:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:40:55 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-h21HXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:40:55 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)) 15:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:40:55 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-T6F0D6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 15:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:40:55 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-cLqhT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:40:55 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)) 15:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:40:55 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-0iI8vx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 15:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:55 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-t0ZAkc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:55 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)) 15:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:40:55 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-W9ZWKh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 15:40:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:40:55 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-ZWAzaP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:40:55 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)) 15:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:40:55 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)) 15:40:55 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:40:55 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:40:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:40:55 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 15:40:55 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 15:40:55 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 15:40:55 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 15:40:55 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 15:40:55 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 15:40:57 INFO - Downloading blessings-1.6.tar.gz 15:40:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:40:57 INFO - Installing collected packages: blessings 15:40:57 INFO - Running setup.py install for blessings 15:40:57 INFO - Successfully installed blessings 15:40:57 INFO - Cleaning up... 15:40:57 INFO - Return code: 0 15:40:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:40:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:40:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:40:57 INFO - Reading from file tmpfile_stdout 15:40:57 INFO - Current package versions: 15:40:57 INFO - blessings == 1.6 15:40:57 INFO - blobuploader == 1.2.4 15:40:57 INFO - docopt == 0.6.1 15:40:57 INFO - functools32 == 3.2.3-2 15:40:57 INFO - jsonschema == 2.5.1 15:40:57 INFO - manifestparser == 1.1 15:40:57 INFO - mozInstall == 1.12 15:40:57 INFO - mozcrash == 1.0 15:40:57 INFO - mozdebug == 0.1 15:40:57 INFO - mozdevice == 0.50 15:40:57 INFO - mozfile == 1.2 15:40:57 INFO - mozhttpd == 0.7 15:40:57 INFO - mozinfo == 0.9 15:40:57 INFO - mozleak == 0.1 15:40:57 INFO - mozlog == 3.4 15:40:57 INFO - moznetwork == 0.27 15:40:57 INFO - mozprocess == 0.25 15:40:57 INFO - mozprofile == 0.28 15:40:57 INFO - mozrunner == 6.13 15:40:57 INFO - mozscreenshot == 0.1 15:40:57 INFO - mozsystemmonitor == 0.3 15:40:57 INFO - moztest == 0.8 15:40:57 INFO - mozversion == 1.4 15:40:57 INFO - psutil == 3.1.1 15:40:57 INFO - requests == 1.2.3 15:40:57 INFO - wsgiref == 0.1.2 15:40:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40: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')]} 15:40:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40: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')]} 15:40:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:57 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 0x10d2b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2ba768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf6b55b6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d095d30>, '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 0x10cfbbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d08abe8>, '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.qeD5qGfeuu/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.RciORhtEah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 15:40:57 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 15:40:57 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 15:40:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qeD5qGfeuu/Render', 15:40:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:57 INFO - 'HOME': '/Users/cltbld', 15:40:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:57 INFO - 'LOGNAME': 'cltbld', 15:40:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:57 INFO - 'MOZ_NO_REMOTE': '1', 15:40:57 INFO - 'NO_EM_RESTART': '1', 15:40:57 INFO - 'PAGER': '/bin/cat', 15:40:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:57 INFO - 'PWD': '/builds/slave/test', 15:40:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:57 INFO - 'SHELL': '/bin/bash', 15:40:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RciORhtEah/Listeners', 15:40:57 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 15:40:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:57 INFO - 'USER': 'cltbld', 15:40:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:57 INFO - 'XPC_FLAGS': '0x0', 15:40:57 INFO - 'XPC_SERVICE_NAME': '0', 15:40:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:58 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)) 15:40:58 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)) 15:40:58 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)) 15:40:58 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)) 15:40:58 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)) 15:40:58 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)) 15:40:58 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)) 15:40:58 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)) 15:40:58 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)) 15:40:58 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)) 15:40:58 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)) 15:40:58 INFO - Cleaning up... 15:40:58 INFO - Return code: 0 15:40:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:40:58 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')]} 15:40:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:58 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')]} 15:40:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:58 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 0x10d2b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2ba768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf6b55b6e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d095d30>, '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 0x10cfbbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d08abe8>, '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.qeD5qGfeuu/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.RciORhtEah/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 15:40:58 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 15:40:58 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 15:40:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qeD5qGfeuu/Render', 15:40:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:58 INFO - 'HOME': '/Users/cltbld', 15:40:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:58 INFO - 'LOGNAME': 'cltbld', 15:40:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:58 INFO - 'MOZ_NO_REMOTE': '1', 15:40:58 INFO - 'NO_EM_RESTART': '1', 15:40:58 INFO - 'PAGER': '/bin/cat', 15:40:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:58 INFO - 'PWD': '/builds/slave/test', 15:40:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:58 INFO - 'SHELL': '/bin/bash', 15:40:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RciORhtEah/Listeners', 15:40:58 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 15:40:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:58 INFO - 'USER': 'cltbld', 15:40:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:58 INFO - 'XPC_FLAGS': '0x0', 15:40:58 INFO - 'XPC_SERVICE_NAME': '0', 15:40:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:40:58 INFO - Cleaning up... 15:40:58 INFO - Return code: 0 15:40:58 INFO - Running post-action listener: _resource_record_post_action 15:40:58 INFO - Running post-action listener: _start_resource_monitoring 15:40:58 INFO - Starting resource monitoring. 15:40:58 INFO - [mozharness: 2017-05-05 22:40:58.472439Z] Finished create-virtualenv step (success) 15:40:58 INFO - [mozharness: 2017-05-05 22:40:58.473378Z] Running install step. 15:40:58 INFO - Running pre-action listener: _resource_record_pre_action 15:40:58 INFO - Running main action method: install 15:40:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:40:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:40:58 INFO - Reading from file tmpfile_stdout 15:40:58 INFO - Detecting whether we're running mozinstall >=1.0... 15:40:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:40:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:40:58 INFO - Reading from file tmpfile_stdout 15:40:58 INFO - Output received: 15:40:58 INFO - Usage: mozinstall [options] installer 15:40:58 INFO - Options: 15:40:58 INFO - -h, --help show this help message and exit 15:40:58 INFO - -d DEST, --destination=DEST 15:40:58 INFO - Directory to install application into. [default: 15:40:58 INFO - "/builds/slave/test"] 15:40:58 INFO - --app=APP Application being installed. [default: firefox] 15:40:58 INFO - mkdir: /builds/slave/test/build/application 15:40:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:40:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:41:18 INFO - Reading from file tmpfile_stdout 15:41:18 INFO - Output received: 15:41:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:41:18 INFO - Running post-action listener: _resource_record_post_action 15:41:18 INFO - [mozharness: 2017-05-05 22:41:18.469540Z] Finished install step (success) 15:41:18 INFO - [mozharness: 2017-05-05 22:41:18.469690Z] Running run-tests step. 15:41:18 INFO - Running pre-action listener: _resource_record_pre_action 15:41:18 INFO - Running main action method: run_tests 15:41:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:41:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:41:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:41:18 INFO - 2017-05-05 15:41:18.483 screenresolution[1625:7480] starting screenresolution argv=screenresolution get 15:41:18 INFO - 2017-05-05 15:41:18.486 screenresolution[1625:7480] Display 0: 1600x1200x32@60 15:41:18 INFO - 2017-05-05 15:41:18.491 screenresolution[1626:7482] starting screenresolution argv=screenresolution list 15:41:18 INFO - Available Modes on Display 0 15:41:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:41:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:41:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:41:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:41:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:41:18 INFO - Intel Iris: 15:41:18 INFO - Chipset Model: Intel Iris 15:41:18 INFO - Type: GPU 15:41:18 INFO - Bus: Built-In 15:41:18 INFO - VRAM (Dynamic, Max): 1536 MB 15:41:18 INFO - Vendor: Intel (0x8086) 15:41:18 INFO - Device ID: 0x0a2e 15:41:18 INFO - Revision ID: 0x0009 15:41:18 INFO - Displays: 15:41:18 INFO - Display: 15:41:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:41:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:41:18 INFO - Display Serial Number: 1600x1200 60 15:41:18 INFO - Main Display: Yes 15:41:18 INFO - Mirror: Off 15:41:18 INFO - Online: Yes 15:41:18 INFO - Rotation: Supported 15:41:18 INFO - Return code: 0 15:41:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:41:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:41:18 INFO - Python 2.7.3 15:41:18 INFO - Return code: 0 15:41:18 INFO - grabbing minidump binary from tooltool 15:41:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:41:18 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 0x10d095d30>, '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 0x10cfbbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d08abe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:41:18 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 15:41:18 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 15:41:18 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 15:41:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:41:18 INFO - Return code: 0 15:41:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:41:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:41:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:41:18 INFO - ENV: RUST_BACKTRACE is now 1 15:41:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:41:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:41:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0154', '--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 15:41:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0154 --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 15:41:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qeD5qGfeuu/Render', 15:41:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:18 INFO - 'HOME': '/Users/cltbld', 15:41:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:18 INFO - 'LOGNAME': 'cltbld', 15:41:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:41:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:41:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:18 INFO - 'MOZ_NO_REMOTE': '1', 15:41:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:41:18 INFO - 'NO_EM_RESTART': '1', 15:41:18 INFO - 'PAGER': '/bin/cat', 15:41:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:18 INFO - 'PWD': '/builds/slave/test', 15:41:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:41:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:18 INFO - 'RUST_BACKTRACE': '1', 15:41:18 INFO - 'SHELL': '/bin/bash', 15:41:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RciORhtEah/Listeners', 15:41:18 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 15:41:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:18 INFO - 'USER': 'cltbld', 15:41:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:18 INFO - 'XPC_FLAGS': '0x0', 15:41:18 INFO - 'XPC_SERVICE_NAME': '0', 15:41:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0154', '--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 15:41:19 INFO - mozversion application_buildid: 20170505140954 15:41:19 INFO - mozversion application_changeset: ff83fde8be946eabcf27ea97d4676f601c122194 15:41:19 INFO - mozversion application_display_name: Nightly 15:41:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:41:19 INFO - mozversion application_name: Firefox 15:41:19 INFO - mozversion application_remotingname: firefox 15:41:19 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:41:19 INFO - mozversion application_vendor: Mozilla 15:41:19 INFO - mozversion application_version: 55.0a1 15:41:19 INFO - mozversion platform_buildid: 20170505140954 15:41:19 INFO - mozversion platform_changeset: ff83fde8be946eabcf27ea97d4676f601c122194 15:41:19 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:41:19 INFO - mozversion platform_version: 55.0a1 15:41:19 INFO - using testdate: 1494024079 15:41:19 INFO - actual date: 1494024079 15:41:19 INFO - starting webserver on 'localhost:49243' 15:41:19 INFO - SUITE-START | Running 2 tests 15:41:19 INFO - TEST-START | dromaeo_css 15:41:19 INFO - operating with platform_type : mac_ 15:41:19 INFO - Initialising browser for dromaeo_css test... 15:41:19 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpbWWwVU/profile http://localhost:49243/getInfo.html) 15:41:22 INFO - PID 1636 | 2017-05-05 15:41:22.338 plugin-container[1638:7672] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983b, name = 'com.apple.tsm.portname' 15:41:22 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:41:22 INFO - PID 1636 | 2017-05-05 15:41:22.341 plugin-container[1638:7672] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c07, name = 'com.apple.CFPasteboardClient' 15:41:22 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:41:22 INFO - PID 1636 | 2017-05-05 15:41:22.341 plugin-container[1638:7672] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:41:22 INFO - PID 1636 | __metrics Screen width/height:1600/1200 15:41:22 INFO - PID 1636 | colorDepth:24 15:41:22 INFO - PID 1636 | Browser inner width/height: 1024/691 15:41:22 INFO - PID 1636 | __metrics 15:41:22 INFO - PID 1636 | 2017-05-05 15:41:22.964 plugin-container[1639:7723] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 15:41:22 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:41:22 INFO - PID 1636 | 2017-05-05 15:41:22.965 plugin-container[1639:7723] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4717, name = 'com.apple.CFPasteboardClient' 15:41:22 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:41:22 INFO - PID 1636 | 2017-05-05 15:41:22.965 plugin-container[1639:7723] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:41:23 INFO - TEST-INFO | 1636: exit 0 15:41:23 INFO - Browser initialized. 15:41:23 INFO - Running cycle 1/1 for dromaeo_css test... 15:41:23 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpbWWwVU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:41:26 INFO - PID 1640 | 2017-05-05 15:41:26.485 plugin-container[1641:7839] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 15:41:26 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:41:26 INFO - PID 1640 | 2017-05-05 15:41:26.486 plugin-container[1641:7839] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 15:41:26 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:41:26 INFO - PID 1640 | 2017-05-05 15:41:26.487 plugin-container[1641:7839] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:41:26 INFO - PID 1640 | 2017-05-05 15:41:26.839 plugin-container[1642:7879] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903b, name = 'com.apple.tsm.portname' 15:41:26 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:41:26 INFO - PID 1640 | 2017-05-05 15:41:26.839 plugin-container[1642:7879] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 15:41:26 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:41:26 INFO - PID 1640 | 2017-05-05 15:41:26.839 plugin-container[1642:7879] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:41:36 INFO - PID 1640 | RSS: Main: 251490304 15:41:36 INFO - PID 1640 | 15:44:39 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 15:44:41 INFO - PID 1640 | RSS: Main: 264691712 15:44:41 INFO - PID 1640 | 15:47:43 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 15:47:43 INFO - PID 1640 | RSS: Main: 268890112 15:47:43 INFO - PID 1640 | 15:50:45 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 15:50:46 INFO - PID 1640 | RSS: Main: 278872064 15:50:46 INFO - PID 1640 | 15:53:48 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 15:53:48 INFO - PID 1640 | RSS: Main: 271224832 15:53:48 INFO - PID 1640 | 15:56:51 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 15:56:52 INFO - PID 1640 | RSS: Main: 276279296 15:56:52 INFO - PID 1640 | 15:59:53 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 15:59:54 INFO - PID 1640 | RSS: Main: 274378752 15:59:54 INFO - PID 1640 | 15:59:54 INFO - PID 1640 | __start_tp_report 15:59:54 INFO - PID 1640 | _x_x_mozilla_page_load 15:59:54 INFO - PID 1640 | _x_x_mozilla_page_load_details 15:59:54 INFO - PID 1640 | |i|pagename|runs| 15:59:54 INFO - PID 1640 | |0;dojo.html;3078.661347251802;3499.42259527178;3605.1888325126847;3645.744797864149;3657.817109144544;5103.9026609279;5852.736626851792;6109.5723299369065;6192.845178870519;6275.811725648238;6174.567780255384;6175.505959523239;6197;6203.844903877394;6225.035119556464;5388.003219404403;5439.864003399927;5452.345718513784;5466.781328746845;5597.188407680884;4768.260919557457;4996.525330093636;5317.644000779815;5508.283923089993;5573.303337082864;5431.809886653968;5489.011977843987;5527.944720552783;5529.529989950859;5534.972325138368;4502.2571275739465;5425.104857698475;5433.510984011418;5443.074677304868;5443.482869127427;5376.892462150774;5379.950909572635;5392.676439413609;5394.514493695547;5419.485148910847;5960.284765828085;6073.453389194994;6104.0347610180015;6182.196314479089;6187.752489900398;5329.680219186861;6496.382843629847;6557.409833115043;6671.532992690513;6672.466202703772;5709.600327977043;6721.966390168018;6753.527253092286;6758.391744742997;6759.628220447873;6144.972159187547;6268.310485846553;6274.341194174637;6284.780032698834;6285.43431091204;6787.592744435349;6842.144731908512;6893.896591551131;6925.930740692528;6981.650917454107;6200.472959798468;6537.1501704778075;6555.344465553502;6605.372489613479;6613.636250006296;6546.214454265519;6555.082288479617;6657.300983396771;6729.3270672933295;6766.18805743314;6358.237011558643;6615.569987950864;6617.139771829631;6629.1382120325015;6668.633225172565;6402.455791257702;6499.447546958561;6641.66791660415;6649.468042556584;6710.429613482811;4445.444319460067;5053.267276244924;5297.867553311198;5335.413104558495;5358.356997160366;4706.482286948433;4716.985848042687;4727.7163370197895;4747.430308363018;4758.547937945889;5282.894342113136;5512.255845460892;6094.024956007018;6242.5942313749465;6305.4009869062365;6199.101130336076;6760.966195169091;6780.966095169492;6789.694463749143;6817.897731534031;6344.49244060474;6387.680615969183;6402.743890244431;6406.679666016774;6423.710933008026;5811.157382179645;5894.381089985575;5904.586678932519;5951.732172052268;5976.880462390728;6823.692933817891;6903.1371078615175;6968.581885086911;6979.965100174467;7009.334113259232;6679.398854103154;6700.564463309869;6709.36261055199;6723.7646682365885;6727.461803055646;6848.863022739418;6868.519203655696;6925.93074069273;6935.237123916264;6946.479014073863;5287.9471205287455;6401.199850018747;6457.4511166551365;6588.637624930675;6593.538452308292;6640.83397915056;6802.965985170043;6812.557183783038;6828.863422731617;6829;6389.552731308764;6426.228852537725;6544.9672751635935;6570.5400621957915;6622.37087476689;6450;6535.411812936859;6572.76995305162;6600.174978127734;6603.405693487609;5271.393789714232;6078.878422431439;6136.662483563447;6443.484521238196;6502.739890404331;6602.240742314695;6617.239017512855;6621.973594092923;6622;6645.1361323027695;6164.876702466016;6299.685015749103;6302.1492098566105;6341.365863413622;6388.297287298301 15:59:54 INFO - PID 1640 | |1;ext.html;701.4879138229091;765.2385876053328;837.7151768398745;1236.6722818453106;1301.7071158989233;37629.43555846665;38002.719836809745;38084;38156.42765358522;38264;37959.430608540824;38022.859314220615;38225.808870955676;38240.80879595598;38364.616353836456;6818.1136452261135;7071.186813516447;8325.750227493185;8441.662333506652;8521.020082690507;8409.159084091603;8493.36299777516;8496.78758031048;8512.78718032048;8526.104759000278;8444.408891377607;8475.110113438093;8488.448250863674;8531.530765807878;8546.145385461467;8467.02629197644;8485.61814718336;8505.829883402328;8530.616122274512;8536.615852286663;8352.87470687934;8382.832343353099;8390.202930721573;8396.412251142423;8423.831523369498;33224.6677533224;37437.25125497502;37597;37728.05679857998;37728.24543509114;3483.6342184070563;3526.8941931741833;3529.5940966788635;3531.1348719563616;3535.5403797506415;27928.58107128395;28436;30656.540151897738;30763.38473230545;30770.846145769352;3133;3161.8893338733264;3167.667394923523;3177.729892959101;3181.427343078245;195703;245059;256616.99999999814;290308;291858;48294;48492.75753621209;48527.75736121297;48570.75714621404;48576;49862;50298.497015030116;50304.49695502998;50469.747651262245;50570.2414463776;14144.64638384028;14201;14270.785938210935;14281.78577321341;14285.357158927873;11039.558417663204;11624;11654.88345116538;11663.300201987739;11665.183437159318;13537.052406331659;13557.45770169202;13573.321333933263;13598.048136630341;13629.386677599532;6094;6098.054801505774;6113.23584551931;6124.418180272867;6138.416850399205;979.4759803505091;1021.315718468613;1052.5947859607509;1056.5615269663003;1083.701981954953;20741.896290518453;24288.87855560711;24373.63439548408;24571.508569828504;24612.384690382525;1047.7171163785736;1137.135776809614;1178.5698220149584;1184.798584240681;1188.7563049574817;11982.760344793056;12479.376031198404;12525.498980040878;12540.81188782169;12778.297193654438;312431.4378428093;328186;329479.35260324023;330543;330722;53431.46568534343;53515;53530;53581.464185358425;53689.657758555564;1515.7802118692948;1559.4152192927424;1568.4040064775313;1577.936882524686;1581.2726145972981;23835.52328953446;23885;23946.161884333964;23949.521009579363;24062.27813165608;37828.05429864275;37828.81085594554;37908;37939.43090853584;37984.240315194096;263985.3601463961;265659;266783;266948;266956.33043669316;32713.836430817693;33627.49558756669;33902.491462627586;34034.808781692525;34171.65828341685;43213.567864320954;43860.561394386925;45140;45436;45460.8634784133;24434.26697199087;25831.870840645675;26048.34879128037;29587.964421245153;30853.8457307712;28467.145985620464;30714.078577641812;30877.8456107718;31037;31097.68902310948;32388.83805580957;33040.50439243461;36307.63692363042;37195.62804371922;37398.439023415216;30147.547786782776;30612.693873060984;30936.845315773277;31012;31131.533027005065;22986;24225.273241802774;24484.14305499299;24732.876335618206;24738.25785226446 15:59:54 INFO - PID 1640 | |2;jquery.html;418.3452896217419;426.7524835595353;433.37377489527626;436.7706953849231;459.30644726463044;19553.315633952796;21238.46903827403;21787.6731849022;22013.889930550366;22039;22122.22572209973;22128.557428851414;22397.888010559924;22435.887820560914;23568.58588484692;6067.423594758491;6210.347913469089;6256.781012664557;6264.780732674358;6276.309605943343;5848.385919478457;6062.120992456091;6077.939220607804;6116.418940200696;6126.571140020201;5985.461308482236;6024.3373228945;6089.78685745999;6123.234595675541;6143.201383820097;5981.072933695263;6063.514918806507;6098.878022439526;6123.510119190499;6128.38716128388;5213.869653258661;5366;5384.59615528833;5429.968306021843;5447;22058.558828823334;22580;27241.591376129174;27402.5889611656;27668.58497122545;2002.4393169912448;2141.646628306328;2225;2230.386643672987;2233.1960494222067;12422.875771242263;14297.571072867831;15883;17424.564385890455;17886.821131788645;1829.4877434318412;2043.5402034542258;2171.2400659769114;2186.212963333199;2188.365374041526;153482;158430;161161.19419402946;162852.18573907172;163092;12276.508939642494;12356.567520136752;12608.306543140032;12633.242005479517;12832.422540985677;16354.918225408797;16549.420770273224;16562.83437165637;16621.252043658063;16641.916790416213;7723.922760772432;8848.601812918434;9167.587458564381;9252.028537003653;9351.532423378803;8766.274333408275;8773.03450379273;8780.253678437277;8971.730848074569;9036.45781253127;7862.135165131831;7983.2415920487465;8028.759137225892;8059.798505037304;8066.798330041678;1915.4867654552747;2188.540406514618;2193.0679461228915;2194.890255487251;2339.0878356446333;865.2688478235881;951.70497145885;985.1183191043975;991.0188912976101;1018.6842078955548;20779.272725454535;21288.89355553243;21411.571768564543;21492.14031438756;26379.076732314275;882.8675698645254;1002.1331548210745;1029.7888932768915;1051.2956319265957;1054.6256079091966;11028.779424411467;11286.830697539543;11296.152788540892;11308.943455282671;11317.264377815416;537675;557159;563956;575538;577886;133442.33278833545;133928;134274;135308.9703654446;135910;1220.0235618298236;1243.6206956878239;1268.492602958824;1270.2568997136593;1271.580378475101;14802.6299342513;16412.671746564763;19311.03444827725;19412.32056878059;19494.22023119116;58513;58632;59859.40140598538;60419.79160416679;60815.78368432518;49894.75052624714;61220.16339509822;66264;66363;67349.66325168342;7587.962060189884;8566.914330856862;8930.86603700932;8970.775730606787;9136.725898223063;12356.196847204903;13169.670758231121;13963.441462341392;14157.504487342894;14435.639109022359;10307.330023548446;11786.528538858349;11895.643130706092;12160.452779625115;12189.75620487603;10727.463626818471;11153.051990580892;11342.376169310766;11357.432128393713;11694.4737486815;11863.34751588671;12015.639530814089;12125.575604853788;12259.203151795105;12278.631641050782;7447.739329123455;8931.910680893108;9073.410228335137;9081.68214112503;9248.722538323862;9779.022097790164;11433.656990290305;11606.361650109326;11781.587644432404;11785.351805650771 15:59:54 INFO - PID 1640 | |3;mootools.html;1249.9875001249986;1460.2041887171497;1480.2672677024875;1483.940642374305;1485.3984136424745;11406.942965285172;11686.766264674701;11872.584459543918;11880.7623847523;11887.821682674756;11725.530978760838;11857.11071669624;11906.821397679041;11925.463354149062;11964.282143071421;2533.480636469524;2562.064846331085;2569.7301783312764;2579.7549232822853;2583.354161459635;2477.4549599088173;2541.9618705719336;2543.580309248972;2545.0583284184786;2547.783438407738;2499.5000999800022;2520.3699075231193;2552.974470255292;2553.668023156987;2556.961645575318;2489.8132640051977;2503.123906632682;2527.178666933245;2535.2394281715597;2540.6697129373247;2446.7430919753524;2501.949961000788;2515.069424313016;2543.491301739641;2548.7960963122905;11733.354665493394;11748.823767643493;11780.11649126319;11810.940945295304;11843.881561184364;6600.2409722882;6792.117024786796;6803.727850885959;6808.7276508939585;6841.110655614738;4048.1903619276263;4384.167008268419;4458.398116254297;4486.5962063414445;4486.798094085774;1232.8582213045436;1251.7496500699897;1257.7610254051792;1260.1053252190864;1334.866513348667;15018.023828451225;15513.224338783128;16384.344626214937;16476;16500.917495412566;5380.596455265871;5510.366307874646;5704.372519022904;5715.685637289989;5761.85595360111;10782.4069676167;10961.287516311415;11656.533738650529;11736.471858766376;11753.412329383496;6037.184980027728;6127.938720612826;6393.6483493408305;6498.512611554058;6511.446527045254;5536.280283563111;5788.855278618068;5957.672328022;6080.452759251689;6255.96872015637;4342.261815491374;4405.405270288534;4441.467023957146;4477.440319960005;4479.55204479556;1116.6600079904147;1275.559931823538;1299.811527328532;1309.9803502947273;1320.5642138094597;746.272384425192;778.1634742651672;794.4041968523608;796.4783067091065;798.3812545277457;6548.869022619521;6921.134858142732;6923.238443771181;7160.427165826722;7179.641017949081;945.7730144765344;977.5112443778111;985.3004366899581;985.9654912078042;987.2644879564626;3455.3434847378917;4100.753954762724;4134.73124246923;4208.326667733209;4322.22200003996;12853.48586056566;14521.92739036298;15120.243987800566;15310.081395116329;15327.846721532866;14656.047356921767;15333;15395.769063464057;15418.922905385625;15442.305096270695;8674.78313042179;8747.606357713918;8831;8831.69089081879;8833.60248788806;6023.759049637965;6766.2218844831505;6919.584824910521;6922.7923162305215;6958.826029349307;12582.874171258536;13403.195808251536;13527.932360338134;13531.932340338235;13779.379927903468;13706.38321275525;13793.586192414243;13823.79264311016;13916.58250252494;14077.225752583707;8239.340852731886;8278.83442331162;8335.708250211213;8347.916520834713;8391.32869370461;10286.485675716034;10515.684529464128;10525.789484210427;10530.420826854597;10534.36793792375;8802.383833131875;8909.777255568662;8967.192952634294;9158.633654653739;9174.082591740773;8777.42946708462;8778.166074222938;8868.822623547621;8901.643934242817;8905.821883562163;7635.465517413728;8036.4776289541;8115.188481152074;8125.187481251827;8125.512469251864;5984.790532331347;5993.250843644544;6043.425874541911;6092.847678808065;6183.690815459119;5869.413058694096;5894.557908156819;5960.986632272437;5999.070144127667;6089.634621922699 15:59:54 INFO - PID 1640 | |4;prototype.html;399.6363309388455;402.8328243778832;404.16741512484293;404.70658772390004;407.5211626339051;5251.553617942474;6215.617989317531;6303.842403939892;6304.369563043693;6354.269259035206;4405.537418571052;5273.5781137509;5393.541548968333;5802.883942321151;5898.587098903078;3720.2931443025736;3724.3296206682785;4093.2222877653226;4443.22243607369;4463.73217606943;4454.398656181423;4467.821287148527;4471.329300604919;4477;4481.126180394825;3796.6013568575318;4388.275934470808;4418.779061046951;4422.137683151786;4442.822287108512;4400.339949007643;4413.44831896013;4418.80115394808;4420.756858372788;4445;3782.205736795276;3796.088938654703;3797.582265950743;3799.25914446683;3802.5627052888995;4884.658073934826;5027.572656324217;5069.619778516626;5493.752781124859;6029.366916473795;4506.684532082755;5219.399769026572;5233.528982391603;5234.136367499358;5249.580033597303;5454.59090568209;6512.479001679902;6554.836129096763;6573.539852210347;6574.76988305407;3796.4305354196817;4192;4358.455193100863;4366.235908715962;4372.972351497396;6844.452443804484;7077.964610176916;7101.964490177567;7120.323569260912;7148.785536433916;4116.91766164675;4387.780610969438;4428.33574963758;4441.511433742286;4444.466664000341;4496.392986946786;4509.751963642031;4511.796969136397;4517.412736344254;4529.411176547028;5181.844544663666;5296.152615581488;5339.599530035185;5348.251244825728;5371.140617501181;5564.970480461127;6092.268927728701;6617.8014659560295;6663.834678527757;6675.732970681215;1426.5149849051165;1521.6153300496494;1525.1840265457927;1528.8624023837908;1537.9461718839786;849.5964416901923;995.5320999130541;1000.3247807729899;1000.494750151169;1014.4674046125696;5679.4320567943705;5705.144228365695;6373.458256048195;6398.360163983564;6421.389967953037;5812.38969908162;5880.382559831241;6684.598924064474;6884.793456196322;6900.6204658744255;5348.866278343073;5550.750216240279;5601.722992049697;5749.655020698771;6171.783987560414;3010.6838781928022;3576.7496275261;3580.4629305604367;3617.168051348152;3637;6180.938190618036;6312.463440607508;6449.516286278548;7051.506394552434;7194.46041546886;6006.879862402728;6539.117219175446;6546.443552298013;7091.326323999315;7152.749653762094;6552.868942621216;6996.125484314461;7101.112360954984;7104;7165;5998.86021655884;6107.84730381744;6161.84595385119;6639.568428052161;6956.40870526011;6544.6727663617585;6744.605664988859;6744.966275168593;6770.627615480998;6788.537845698828;6369.681515924092;6786.151731033621;6999.335063169195;7030.191527974348;7084.25615310385;3970.2059588083075;4414.82340706381;4415.98785378604;4425.203463376623;4426.82292708301;6402.263739669997;6588.374104460068;6882.449404047764;6911.89632155508;7048.964755176191;6658.500612454182;6669.866602667822;6673.432758215606;6689.5986240825705;6759.121314229119;6438.8390290243115;6602.372774586406;6630.5027122965;6643.302453242533;6682.766103186365;5174.197999310113;6494.27240558957;6528.281888992112;6543.640099794557;6568.9014664781025;6541.313162117909;6647.966760166169;6669.499787515859;6670.266270710315;6684.364985326386;6410.935890641033;6496.902546461902;6511.544191906521;6517.315681853337;6546.541742078009 15:59:54 INFO - PID 1640 | |5;yui.html;819.0989911097789;820.3847114664005;827.2348078027823;847.7834307768353;854.3848429131024;15434.691306173883;15823.20883955802;15918.602034949132;17913.283468661237;18591.721124183146;16583.751243731323;16711.498655040337;16862.241199146036;16910.323587056504;17017.659646807057;995.761017355833;1001.3191030099548;1018.5976539266985;1022.7903279827655;1026.1431704526728;1017.7862648843752;1022.9079382855541;1024.4570377699831;1027.5889644142328;1027.7533391985908;849.2399302624148;1022.3712416863613;1048.192891473565;1075.3440401355206;1112.5994641928899;1055.5250137438145;1068.6633710381243;1068.839674048891;1070.4808168038496;1076.4725284610524;593.6734795862259;794.2732399854126;796.5141263829059;797.6051854332085;800.5316889619579;13696.589102326947;15780.842191578167;16091;16093.678126437406;16115.43595974147;13726.245056521888;15593.220338983005;16448.930819496694;16450.75323870143;16500.75248871268;12859.678508037281;13190.472381104744;13307.268100254481;13326.333683315892;13333;3376.983115084409;3455.291665208626;3470.5314782504292;3484.337975784593;3503.754737168399;100516.49225261627;116766;123869;125496;125627;12577.434015469325;12899.93550032244;12945.54690585825;12985.415656295489;13012.47950082005;23533.29400117999;23879.16422925189;24020.39949001289;24047.038118475415;24531;7468.85062298751;7573.242675732493;7588.165301816795;7605.429592780563;7625.428092893055;3262.8368581570826;3856.768593884376;3864.53625564934;3883.650471457582;3894.7078969076865;3945.743526670757;3980.0447892506168;4163.854265100767;4178.331466965332;4273.829046838092;915.1169121797498;940.7554035950702;948.1419315519466;950.843110886696;952.8237276103943;635.3582881289931;668.4418510543653;668.7057694614355;669.1702289161502;679.7077256779534;10886.292390994719;10888.40113793749;11351.318920864775;11637.825432618518;11720.296782193096;643.1863692429081;657.5002997721766;661.9702113404908;688.8209065642969;693.7259782385929;5070.645054846125;5158.871028224249;5415.214793854869;5420.945790542123;5553.916691249635;80862.78705819417;100774.4961275189;102541.48729256306;104816;106361;95377;95383.52308238414;95600;97422;98237;83288.33423331499;83453.58273208595;83734.58132709419;83870;85223;9117.407368521024;10745.409002504624;10988.945055274673;11268.492917818554;12533.93733031329;33362.16594585155;37188.628113718514;38836.223275534896;40459.59540404676;40577;70426.94359584713;70566.64716676384;71041.6447917757;71671.56656866938;72139.39303034723;15322.616934576725;15781.447649332678;15883;15958.122303272967;16347.836521635107;17558.297668093644;18316.90841545837;18527.980961047273;18584.44246672548;18651.06744662734;15774.763378549102;15884;16542.338306467605;16579.585510362336;16670.66658666844;15799.289031993818;15859.920700396424;16621.750673740145;16868.746968795724;17149.399771007957;15332;15427.382904683687;16096.758548622014;16188.676226475169;16375.263113160174;11455.713607159887;11595.536178552764;11736.941315293368;12074.698132546757;12226.938865305616;11408.714782130513;12502.374881255719;12805.231686098863;13168.802467962796;13321.467141314239 15:59:54 INFO - PID 1640 | __end_tp_report 15:59:54 INFO - PID 1640 | __start_cc_report 15:59:54 INFO - PID 1640 | _x_x_mozilla_cycle_collect,456 15:59:54 INFO - PID 1640 | __end_cc_report 15:59:54 INFO - PID 1640 | __startTimestamp1494025194105__endTimestamp 15:59:54 INFO - PID 1640 | 15:59:54 INFO - PID 1640 | ------- Summary: start ------- 15:59:54 INFO - PID 1640 | Number of tests: 6 15:59:54 INFO - PID 1640 | 15:59:54 INFO - PID 1640 | [#0] dojo.html Cycles:165 Average:6098.18 Median:6344.49 stddev:769.62 (12.1%) stddev-sans-first:734.62 15:59:54 INFO - PID 1640 | Values: 3078.7 3499.4 3605.2 3645.7 3657.8 5103.9 5852.7 6109.6 6192.8 6275.8 6174.6 6175.5 6197.0 6203.8 6225.0 5388.0 5439.9 5452.3 5466.8 5597.2 4768.3 4996.5 5317.6 5508.3 5573.3 5431.8 5489.0 5527.9 5529.5 5535.0 4502.3 5425.1 5433.5 5443.1 5443.5 5376.9 5380.0 5392.7 5394.5 5419.5 5960.3 6073.5 6104.0 6182.2 6187.8 5329.7 6496.4 6557.4 6671.5 6672.5 5709.6 6722.0 6753.5 6758.4 6759.6 6145.0 6268.3 6274.3 6284.8 6285.4 6787.6 6842.1 6893.9 6925.9 6981.7 6200.5 6537.2 6555.3 6605.4 6613.6 6546.2 6555.1 6657.3 6729.3 6766.2 6358.2 6615.6 6617.1 6629.1 6668.6 6402.5 6499.4 6641.7 6649.5 6710.4 4445.4 5053.3 5297.9 5335.4 5358.4 4706.5 4717.0 4727.7 4747.4 4758.5 5282.9 5512.3 6094.0 6242.6 6305.4 6199.1 6761.0 6781.0 6789.7 6817.9 6344.5 6387.7 6402.7 6406.7 6423.7 5811.2 5894.4 5904.6 5951.7 5976.9 6823.7 6903.1 6968.6 6980.0 7009.3 6679.4 6700.6 6709.4 6723.8 6727.5 6848.9 6868.5 6925.9 6935.2 6946.5 5287.9 6401.2 6457.5 6588.6 6593.5 6640.8 6803.0 6812.6 6828.9 6829.0 6389.6 6426.2 6545.0 6570.5 6622.4 6450.0 6535.4 6572.8 6600.2 6603.4 5271.4 6078.9 6136.7 6443.5 6502.7 6602.2 6617.2 6622.0 6622.0 6645.1 6164.9 6299.7 6302.1 6341.4 6388.3 15:59:54 INFO - PID 1640 | 15:59:54 INFO - PID 1640 | [#1] ext.html Cycles:180 Average:43505.32 Median:181259.93 stddev:74750.09 (41.2%) stddev-sans-first:74890.69 15:59:54 INFO - PID 1640 | Values: 701.5 765.2 837.7 1236.7 1301.7 37629.4 38002.7 38084.0 38156.4 38264.0 37959.4 38022.9 38225.8 38240.8 38364.6 6818.1 7071.2 8325.8 8441.7 8521.0 8409.2 8493.4 8496.8 8512.8 8526.1 8444.4 8475.1 8488.4 8531.5 8546.1 8467.0 8485.6 8505.8 8530.6 8536.6 8352.9 8382.8 8390.2 8396.4 8423.8 33224.7 37437.3 37597.0 37728.1 37728.2 3483.6 3526.9 3529.6 3531.1 3535.5 27928.6 28436.0 30656.5 30763.4 30770.8 3133.0 3161.9 3167.7 3177.7 3181.4 195703.0 245059.0 256617.0 290308.0 291858.0 48294.0 48492.8 48527.8 48570.8 48576.0 49862.0 50298.5 50304.5 50469.7 50570.2 14144.6 14201.0 14270.8 14281.8 14285.4 11039.6 11624.0 11654.9 11663.3 11665.2 13537.1 13557.5 13573.3 13598.0 13629.4 6094.0 6098.1 6113.2 6124.4 6138.4 979.5 1021.3 1052.6 1056.6 1083.7 20741.9 24288.9 24373.6 24571.5 24612.4 1047.7 1137.1 1178.6 1184.8 1188.8 11982.8 12479.4 12525.5 12540.8 12778.3 312431.4 328186.0 329479.4 330543.0 330722.0 53431.5 53515.0 53530.0 53581.5 53689.7 1515.8 1559.4 1568.4 1577.9 1581.3 23835.5 23885.0 23946.2 23949.5 24062.3 37828.1 37828.8 37908.0 37939.4 37984.2 263985.4 265659.0 266783.0 266948.0 266956.3 32713.8 33627.5 33902.5 34034.8 34171.7 43213.6 43860.6 45140.0 45436.0 45460.9 24434.3 25831.9 26048.3 29588.0 30853.8 28467.1 30714.1 30877.8 31037.0 31097.7 32388.8 33040.5 36307.6 37195.6 37398.4 30147.5 30612.7 30936.8 31012.0 31131.5 22986.0 24225.3 24484.1 24732.9 24738.3 15:59:54 INFO - PID 1640 | 15:59:54 INFO - PID 1640 | [#2] jquery.html Cycles:180 Average:35747.69 Median:22125.39 stddev:95457.90 (431.4%) stddev-sans-first:95688.82 15:59:54 INFO - PID 1640 | Values: 418.3 426.8 433.4 436.8 459.3 19553.3 21238.5 21787.7 22013.9 22039.0 22122.2 22128.6 22397.9 22435.9 23568.6 6067.4 6210.3 6256.8 6264.8 6276.3 5848.4 6062.1 6077.9 6116.4 6126.6 5985.5 6024.3 6089.8 6123.2 6143.2 5981.1 6063.5 6098.9 6123.5 6128.4 5213.9 5366.0 5384.6 5430.0 5447.0 22058.6 22580.0 27241.6 27402.6 27668.6 2002.4 2141.6 2225.0 2230.4 2233.2 12422.9 14297.6 15883.0 17424.6 17886.8 1829.5 2043.5 2171.2 2186.2 2188.4 153482.0 158430.0 161161.2 162852.2 163092.0 12276.5 12356.6 12608.3 12633.2 12832.4 16354.9 16549.4 16562.8 16621.3 16641.9 7723.9 8848.6 9167.6 9252.0 9351.5 8766.3 8773.0 8780.3 8971.7 9036.5 7862.1 7983.2 8028.8 8059.8 8066.8 1915.5 2188.5 2193.1 2194.9 2339.1 865.3 951.7 985.1 991.0 1018.7 20779.3 21288.9 21411.6 21492.1 26379.1 882.9 1002.1 1029.8 1051.3 1054.6 11028.8 11286.8 11296.2 11308.9 11317.3 537675.0 557159.0 563956.0 575538.0 577886.0 133442.3 133928.0 134274.0 135309.0 135910.0 1220.0 1243.6 1268.5 1270.3 1271.6 14802.6 16412.7 19311.0 19412.3 19494.2 58513.0 58632.0 59859.4 60419.8 60815.8 49894.8 61220.2 66264.0 66363.0 67349.7 7588.0 8566.9 8930.9 8970.8 9136.7 12356.2 13169.7 13963.4 14157.5 14435.6 10307.3 11786.5 11895.6 12160.5 12189.8 10727.5 11153.1 11342.4 11357.4 11694.5 11863.3 12015.6 12125.6 12259.2 12278.6 7447.7 8931.9 9073.4 9081.7 9248.7 9779.0 11433.7 11606.4 11781.6 11785.4 15:59:54 INFO - PID 1640 | 15:59:54 INFO - PID 1640 | [#3] mootools.html Cycles:180 Average:7079.07 Median:3751.77 stddev:4452.33 (118.7%) stddev-sans-first:4443.27 15:59:54 INFO - PID 1640 | Values: 1250.0 1460.2 1480.3 1483.9 1485.4 11406.9 11686.8 11872.6 11880.8 11887.8 11725.5 11857.1 11906.8 11925.5 11964.3 2533.5 2562.1 2569.7 2579.8 2583.4 2477.5 2542.0 2543.6 2545.1 2547.8 2499.5 2520.4 2553.0 2553.7 2557.0 2489.8 2503.1 2527.2 2535.2 2540.7 2446.7 2501.9 2515.1 2543.5 2548.8 11733.4 11748.8 11780.1 11810.9 11843.9 6600.2 6792.1 6803.7 6808.7 6841.1 4048.2 4384.2 4458.4 4486.6 4486.8 1232.9 1251.7 1257.8 1260.1 1334.9 15018.0 15513.2 16384.3 16476.0 16500.9 5380.6 5510.4 5704.4 5715.7 5761.9 10782.4 10961.3 11656.5 11736.5 11753.4 6037.2 6127.9 6393.6 6498.5 6511.4 5536.3 5788.9 5957.7 6080.5 6256.0 4342.3 4405.4 4441.5 4477.4 4479.6 1116.7 1275.6 1299.8 1310.0 1320.6 746.3 778.2 794.4 796.5 798.4 6548.9 6921.1 6923.2 7160.4 7179.6 945.8 977.5 985.3 986.0 987.3 3455.3 4100.8 4134.7 4208.3 4322.2 12853.5 14521.9 15120.2 15310.1 15327.8 14656.0 15333.0 15395.8 15418.9 15442.3 8674.8 8747.6 8831.0 8831.7 8833.6 6023.8 6766.2 6919.6 6922.8 6958.8 12582.9 13403.2 13527.9 13531.9 13779.4 13706.4 13793.6 13823.8 13916.6 14077.2 8239.3 8278.8 8335.7 8347.9 8391.3 10286.5 10515.7 10525.8 10530.4 10534.4 8802.4 8909.8 8967.2 9158.6 9174.1 8777.4 8778.2 8868.8 8901.6 8905.8 7635.5 8036.5 8115.2 8125.2 8125.5 5984.8 5993.3 6043.4 6092.8 6183.7 5869.4 5894.6 5961.0 5999.1 6089.6 15:59:54 INFO - PID 1640 | 15:59:54 INFO - PID 1640 | [#4] prototype.html Cycles:180 Average:5267.76 Median:5807.64 stddev:1696.00 (29.2%) stddev-sans-first:1660.93 15:59:54 INFO - PID 1640 | Values: 399.6 402.8 404.2 404.7 407.5 5251.6 6215.6 6303.8 6304.4 6354.3 4405.5 5273.6 5393.5 5802.9 5898.6 3720.3 3724.3 4093.2 4443.2 4463.7 4454.4 4467.8 4471.3 4477.0 4481.1 3796.6 4388.3 4418.8 4422.1 4442.8 4400.3 4413.4 4418.8 4420.8 4445.0 3782.2 3796.1 3797.6 3799.3 3802.6 4884.7 5027.6 5069.6 5493.8 6029.4 4506.7 5219.4 5233.5 5234.1 5249.6 5454.6 6512.5 6554.8 6573.5 6574.8 3796.4 4192.0 4358.5 4366.2 4373.0 6844.5 7078.0 7102.0 7120.3 7148.8 4116.9 4387.8 4428.3 4441.5 4444.5 4496.4 4509.8 4511.8 4517.4 4529.4 5181.8 5296.2 5339.6 5348.3 5371.1 5565.0 6092.3 6617.8 6663.8 6675.7 1426.5 1521.6 1525.2 1528.9 1537.9 849.6 995.5 1000.3 1000.5 1014.5 5679.4 5705.1 6373.5 6398.4 6421.4 5812.4 5880.4 6684.6 6884.8 6900.6 5348.9 5550.8 5601.7 5749.7 6171.8 3010.7 3576.7 3580.5 3617.2 3637.0 6180.9 6312.5 6449.5 7051.5 7194.5 6006.9 6539.1 6546.4 7091.3 7152.7 6552.9 6996.1 7101.1 7104.0 7165.0 5998.9 6107.8 6161.8 6639.6 6956.4 6544.7 6744.6 6745.0 6770.6 6788.5 6369.7 6786.2 6999.3 7030.2 7084.3 3970.2 4414.8 4416.0 4425.2 4426.8 6402.3 6588.4 6882.4 6911.9 7049.0 6658.5 6669.9 6673.4 6689.6 6759.1 6438.8 6602.4 6630.5 6643.3 6682.8 5174.2 6494.3 6528.3 6543.6 6568.9 6541.3 6648.0 6669.5 6670.3 6684.4 6410.9 6496.9 6511.5 6517.3 6546.5 15:59:54 INFO - PID 1640 | 15:59:54 INFO - PID 1640 | [#5] yui.html Cycles:180 Average:21770.96 Median:16889.54 stddev:30752.04 (182.1%) stddev-sans-first:30798.07 15:59:54 INFO - PID 1640 | Values: 819.1 820.4 827.2 847.8 854.4 15434.7 15823.2 15918.6 17913.3 18591.7 16583.8 16711.5 16862.2 16910.3 17017.7 995.8 1001.3 1018.6 1022.8 1026.1 1017.8 1022.9 1024.5 1027.6 1027.8 849.2 1022.4 1048.2 1075.3 1112.6 1055.5 1068.7 1068.8 1070.5 1076.5 593.7 794.3 796.5 797.6 800.5 13696.6 15780.8 16091.0 16093.7 16115.4 13726.2 15593.2 16448.9 16450.8 16500.8 12859.7 13190.5 13307.3 13326.3 13333.0 3377.0 3455.3 3470.5 3484.3 3503.8 100516.5 116766.0 123869.0 125496.0 125627.0 12577.4 12899.9 12945.5 12985.4 13012.5 23533.3 23879.2 24020.4 24047.0 24531.0 7468.9 7573.2 7588.2 7605.4 7625.4 3262.8 3856.8 3864.5 3883.7 3894.7 3945.7 3980.0 4163.9 4178.3 4273.8 915.1 940.8 948.1 950.8 952.8 635.4 668.4 668.7 669.2 679.7 10886.3 10888.4 11351.3 11637.8 11720.3 643.2 657.5 662.0 688.8 693.7 5070.6 5158.9 5415.2 5420.9 5553.9 80862.8 100774.5 102541.5 104816.0 106361.0 95377.0 95383.5 95600.0 97422.0 98237.0 83288.3 83453.6 83734.6 83870.0 85223.0 9117.4 10745.4 10988.9 11268.5 12533.9 33362.2 37188.6 38836.2 40459.6 40577.0 70426.9 70566.6 71041.6 71671.6 72139.4 15322.6 15781.4 15883.0 15958.1 16347.8 17558.3 18316.9 18528.0 18584.4 18651.1 15774.8 15884.0 16542.3 16579.6 16670.7 15799.3 15859.9 16621.8 16868.7 17149.4 15332.0 15427.4 16096.8 16188.7 16375.3 11455.7 11595.5 11736.9 12074.7 12226.9 11408.7 12502.4 12805.2 13168.8 13321.5 15:59:54 INFO - PID 1640 | -------- Summary: end -------- 15:59:54 INFO - PID 1640 | 15:59:54 INFO - TEST-INFO | 1640: exit 0 15:59:54 INFO - TEST-OK | dromaeo_css | took 1115711ms 15:59:54 INFO - TEST-START | kraken 15:59:54 INFO - operating with platform_type : mac_ 15:59:54 INFO - Initialising browser for kraken test... 15:59:54 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpDKbODT/profile http://localhost:49243/getInfo.html) 15:59:57 INFO - PID 1656 | 2017-05-05 15:59:57.981 plugin-container[1657:10288] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9343, name = 'com.apple.tsm.portname' 15:59:57 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:59:57 INFO - PID 1656 | 2017-05-05 15:59:57.984 plugin-container[1657:10288] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 15:59:57 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:59:57 INFO - PID 1656 | 2017-05-05 15:59:57.984 plugin-container[1657:10288] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:59:58 INFO - PID 1656 | __metrics Screen width/height:1600/1200 15:59:58 INFO - PID 1656 | colorDepth:24 15:59:58 INFO - PID 1656 | Browser inner width/height: 1024/691 15:59:58 INFO - PID 1656 | __metrics 15:59:58 INFO - PID 1656 | 2017-05-05 15:59:58.546 plugin-container[1658:10344] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.tsm.portname' 15:59:58 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:59:58 INFO - PID 1656 | 2017-05-05 15:59:58.547 plugin-container[1658:10344] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 15:59:58 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:59:58 INFO - PID 1656 | 2017-05-05 15:59:58.547 plugin-container[1658:10344] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:59:59 INFO - TEST-INFO | 1656: exit 0 15:59:59 INFO - Browser initialized. 15:59:59 INFO - Running cycle 1/1 for kraken test... 15:59:59 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpDKbODT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:00:02 INFO - PID 1659 | 2017-05-05 16:00:02.195 plugin-container[1660:10460] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 16:00:02 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:00:02 INFO - PID 1659 | 2017-05-05 16:00:02.196 plugin-container[1660:10460] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980f, name = 'com.apple.CFPasteboardClient' 16:00:02 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:00:02 INFO - PID 1659 | 2017-05-05 16:00:02.196 plugin-container[1660:10460] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:00:02 INFO - PID 1659 | 2017-05-05 16:00:02.690 plugin-container[1661:10502] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x964f, name = 'com.apple.tsm.portname' 16:00:02 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:00:02 INFO - PID 1659 | 2017-05-05 16:00:02.690 plugin-container[1661:10502] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 16:00:02 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:00:02 INFO - PID 1659 | 2017-05-05 16:00:02.690 plugin-container[1661:10502] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:00:12 INFO - PID 1659 | RSS: Main: 251826176 16:00:12 INFO - PID 1659 | 16:00:14 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 16:00:15 INFO - PID 1659 | RSS: Main: 263012352 16:00:15 INFO - PID 1659 | 16:00:18 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 16:00:19 INFO - PID 1659 | RSS: Main: 271654912 16:00:19 INFO - PID 1659 | 16:00:22 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 16:00:23 INFO - PID 1659 | RSS: Main: 270381056 16:00:23 INFO - PID 1659 | 16:00:25 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 16:00:25 INFO - PID 1659 | RSS: Main: 273063936 16:00:25 INFO - PID 1659 | 16:00:27 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:00:28 INFO - PID 1659 | RSS: Main: 273686528 16:00:28 INFO - PID 1659 | 16:00:31 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 16:00:32 INFO - PID 1659 | RSS: Main: 278704128 16:00:32 INFO - PID 1659 | 16:00:35 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 16:00:35 INFO - PID 1659 | RSS: Main: 281063424 16:00:35 INFO - PID 1659 | 16:00:38 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 16:00:39 INFO - PID 1659 | RSS: Main: 281161728 16:00:39 INFO - PID 1659 | 16:00:40 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:00:41 INFO - PID 1659 | RSS: Main: 280928256 16:00:41 INFO - PID 1659 | 16:00:43 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:00:44 INFO - PID 1659 | RSS: Main: 280973312 16:00:44 INFO - PID 1659 | 16:00:46 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:00:46 INFO - PID 1659 | RSS: Main: 280514560 16:00:46 INFO - PID 1659 | 16:00:49 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:00:50 INFO - PID 1659 | RSS: Main: 280764416 16:00:50 INFO - PID 1659 | 16:00:53 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:00:54 INFO - PID 1659 | RSS: Main: 281128960 16:00:54 INFO - PID 1659 | 16:00:56 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 16:00:56 INFO - PID 1659 | RSS: Main: 283381760 16:00:56 INFO - PID 1659 | 16:00:56 INFO - PID 1659 | __start_tp_report 16:00:56 INFO - PID 1659 | _x_x_mozilla_page_load 16:00:56 INFO - PID 1659 | _x_x_mozilla_page_load_details 16:00:56 INFO - PID 1659 | |i|pagename|runs| 16:00:56 INFO - PID 1659 | |0;ai-astar;102;115;138;114;117;115;114;115;144;167 16:00:56 INFO - PID 1659 | |1;audio-beat-detection;149;109;117;177;134;112;114;113;143;111 16:00:56 INFO - PID 1659 | |2;audio-dft;212;222;207;268;201;201;200;198;201;201 16:00:56 INFO - PID 1659 | |3;audio-fft;72;57;53;112;88;86;83;81;84;57 16:00:56 INFO - PID 1659 | |4;audio-oscillator;92;69;71;77;95;101;105;55;74;112 16:00:56 INFO - PID 1659 | |5;imaging-gaussian-blur;91;89;101;90;96;90;91;89;89;95 16:00:56 INFO - PID 1659 | |6;imaging-darkroom;95;98;97;97;97;96;104;97;97;102 16:00:56 INFO - PID 1659 | |7;imaging-desaturate;91;93;93;92;93;143;95;91;91;95 16:00:56 INFO - PID 1659 | |8;json-parse-financial;51;50;50;52;61;48;51;49;51;52 16:00:56 INFO - PID 1659 | |9;json-stringify-tinderbox;55;59;64;63;64;62;56;55;49;50 16:00:56 INFO - PID 1659 | |10;stanford-crypto-aes;82;82;94;98;82;82;83;82;87;79 16:00:56 INFO - PID 1659 | |11;stanford-crypto-ccm;142;142;139;151;141;153;153;161;190;165 16:00:56 INFO - PID 1659 | |12;stanford-crypto-pbkdf2;181;185;187;164;163;159;231;229;217;187 16:00:56 INFO - PID 1659 | |13;stanford-crypto-sha256-iterative;71;68;71;67;70;71;67;71;73;73 16:00:56 INFO - PID 1659 | __end_tp_report 16:00:56 INFO - PID 1659 | __start_cc_report 16:00:56 INFO - PID 1659 | _x_x_mozilla_cycle_collect,1136 16:00:56 INFO - PID 1659 | __end_cc_report 16:00:56 INFO - PID 1659 | __startTimestamp1494025256388__endTimestamp 16:00:56 INFO - PID 1659 | 16:00:56 INFO - PID 1659 | ------- Summary: start ------- 16:00:56 INFO - PID 1659 | Number of tests: 14 16:00:56 INFO - PID 1659 | 16:00:56 INFO - PID 1659 | [#0] ai-astar Cycles:10 Average:124.10 Median:116.00 stddev:19.50 (16.8%) stddev-sans-first:18.97 16:00:56 INFO - PID 1659 | Values: 102.0 115.0 138.0 114.0 117.0 115.0 114.0 115.0 144.0 167.0 16:00:56 INFO - PID 1659 | 16:00:56 INFO - PID 1659 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:125.50 stddev:22.49 (17.9%) stddev-sans-first:22.52 16:00:56 INFO - PID 1659 | Values: 149.0 109.0 117.0 177.0 134.0 112.0 114.0 113.0 143.0 111.0 16:00:56 INFO - PID 1659 | 16:00:56 INFO - PID 1659 | [#2] audio-dft Cycles:10 Average:211.10 Median:204.00 stddev:21.28 (10.4%) stddev-sans-first:22.57 16:00:56 INFO - PID 1659 | Values: 212.0 222.0 207.0 268.0 201.0 201.0 200.0 198.0 201.0 201.0 16:00:56 INFO - PID 1659 | 16:00:56 INFO - PID 1659 | [#3] audio-fft Cycles:10 Average:77.30 Median:82.00 stddev:18.04 (22.0%) stddev-sans-first:19.03 16:00:56 INFO - PID 1659 | Values: 72.0 57.0 53.0 112.0 88.0 86.0 83.0 81.0 84.0 57.0 16:00:56 INFO - PID 1659 | 16:00:56 INFO - PID 1659 | [#4] audio-oscillator Cycles:10 Average:85.10 Median:72.50 stddev:18.47 (25.5%) stddev-sans-first:19.42 16:00:56 INFO - PID 1659 | Values: 92.0 69.0 71.0 77.0 95.0 101.0 105.0 55.0 74.0 112.0 16:00:56 INFO - PID 1659 | 16:00:56 INFO - PID 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:92.10 Median:90.50 stddev:3.98 (4.4%) stddev-sans-first:4.21 16:00:56 INFO - PID 1659 | Values: 91.0 89.0 101.0 90.0 96.0 90.0 91.0 89.0 89.0 95.0 16:00:56 INFO - PID 1659 | 16:00:56 INFO - PID 1659 | [#6] imaging-darkroom Cycles:10 Average:98.00 Median:97.00 stddev:2.79 (2.9%) stddev-sans-first:2.74 16:00:56 INFO - PID 1659 | Values: 95.0 98.0 97.0 97.0 97.0 96.0 104.0 97.0 97.0 102.0 16:00:56 INFO - PID 1659 | 16:00:56 INFO - PID 1659 | [#7] imaging-desaturate Cycles:10 Average:97.70 Median:93.00 stddev:15.99 (17.2%) stddev-sans-first:16.77 16:00:56 INFO - PID 1659 | Values: 91.0 93.0 93.0 92.0 93.0 143.0 95.0 91.0 91.0 95.0 16:00:56 INFO - PID 1659 | 16:00:56 INFO - PID 1659 | [#8] json-parse-financial Cycles:10 Average:51.50 Median:51.00 stddev:3.57 (7.0%) stddev-sans-first:3.78 16:00:56 INFO - PID 1659 | Values: 51.0 50.0 50.0 52.0 61.0 48.0 51.0 49.0 51.0 52.0 16:00:56 INFO - PID 1659 | 16:00:56 INFO - PID 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:57.70 Median:60.50 stddev:5.58 (9.2%) stddev-sans-first:5.83 16:00:56 INFO - PID 1659 | Values: 55.0 59.0 64.0 63.0 64.0 62.0 56.0 55.0 49.0 50.0 16:00:56 INFO - PID 1659 | 16:00:56 INFO - PID 1659 | [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:82.50 stddev:6.14 (7.4%) stddev-sans-first:6.41 16:00:56 INFO - PID 1659 | Values: 82.0 82.0 94.0 98.0 82.0 82.0 83.0 82.0 87.0 79.0 16:00:56 INFO - PID 1659 | 16:00:56 INFO - PID 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:153.70 Median:153.00 stddev:15.49 (10.1%) stddev-sans-first:15.84 16:00:56 INFO - PID 1659 | Values: 142.0 142.0 139.0 151.0 141.0 153.0 153.0 161.0 190.0 165.0 16:00:56 INFO - PID 1659 | 16:00:56 INFO - PID 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.30 Median:187.00 stddev:26.71 (14.3%) stddev-sans-first:28.12 16:00:56 INFO - PID 1659 | Values: 181.0 185.0 187.0 164.0 163.0 159.0 231.0 229.0 217.0 187.0 16:00:56 INFO - PID 1659 | 16:00:56 INFO - PID 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.20 Median:71.00 stddev:2.20 (3.1%) stddev-sans-first:2.32 16:00:56 INFO - PID 1659 | Values: 71.0 68.0 71.0 67.0 70.0 71.0 67.0 71.0 73.0 73.0 16:00:56 INFO - PID 1659 | -------- Summary: end -------- 16:00:56 INFO - PID 1659 | 16:00:57 INFO - TEST-INFO | 1659: exit 0 16:00:57 INFO - TEST-OK | kraken | took 62753ms 16:00:57 INFO - SUITE-END | took 1178s 16:00:57 INFO - Completed test suite (00:19:38) 16:00:57 INFO - javascript benchmark 16:00:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7826.557820567958, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3078.661347251802, 3499.42259527178, 3605.1888325126847, 3645.744797864149, 3657.817109144544, 5103.9026609279, 5852.736626851792, 6109.5723299369065, 6192.845178870519, 6275.811725648238, 6174.567780255384, 6175.505959523239, 6197.0, 6203.844903877394, 6225.035119556464, 5388.003219404403, 5439.864003399927, 5452.345718513784, 5466.781328746845, 5597.188407680884, 4768.260919557457, 4996.525330093636, 5317.644000779815, 5508.283923089993, 5573.303337082864, 5431.809886653968, 5489.011977843987, 5527.944720552783, 5529.529989950859, 5534.972325138368, 4502.2571275739465, 5425.104857698475, 5433.510984011418, 5443.074677304868, 5443.482869127427, 5376.892462150774, 5379.950909572635, 5392.676439413609, 5394.514493695547, 5419.485148910847, 5960.284765828085, 6073.453389194994, 6104.0347610180015, 6182.196314479089, 6187.752489900398, 5329.680219186861, 6496.382843629847, 6557.409833115043, 6671.532992690513, 6672.466202703772, 5709.600327977043, 6721.966390168018, 6753.527253092286, 6758.391744742997, 6759.628220447873, 6144.972159187547, 6268.310485846553, 6274.341194174637, 6284.780032698834, 6285.43431091204, 6787.592744435349, 6842.144731908512, 6893.896591551131, 6925.930740692528, 6981.650917454107, 6200.472959798468, 6537.1501704778075, 6555.344465553502, 6605.372489613479, 6613.636250006296, 6546.214454265519, 6555.082288479617, 6657.300983396771, 6729.3270672933295, 6766.18805743314, 6358.237011558643, 6615.569987950864, 6617.139771829631, 6629.1382120325015, 6668.633225172565, 6402.455791257702, 6499.447546958561, 6641.66791660415, 6649.468042556584, 6710.429613482811, 4445.444319460067, 5053.267276244924, 5297.867553311198, 5335.413104558495, 5358.356997160366, 4706.482286948433, 4716.985848042687, 4727.7163370197895, 4747.430308363018, 4758.547937945889, 5282.894342113136, 5512.255845460892, 6094.024956007018, 6242.5942313749465, 6305.4009869062365, 6199.101130336076, 6760.966195169091, 6780.966095169492, 6789.694463749143, 6817.897731534031, 6344.49244060474, 6387.680615969183, 6402.743890244431, 6406.679666016774, 6423.710933008026, 5811.157382179645, 5894.381089985575, 5904.586678932519, 5951.732172052268, 5976.880462390728, 6823.692933817891, 6903.1371078615175, 6968.581885086911, 6979.965100174467, 7009.334113259232, 6679.398854103154, 6700.564463309869, 6709.36261055199, 6723.7646682365885, 6727.461803055646, 6848.863022739418, 6868.519203655696, 6925.93074069273, 6935.237123916264, 6946.479014073863, 5287.9471205287455, 6401.199850018747, 6457.4511166551365, 6588.637624930675, 6593.538452308292, 6640.83397915056, 6802.965985170043, 6812.557183783038, 6828.863422731617, 6829.0, 6389.552731308764, 6426.228852537725, 6544.9672751635935, 6570.5400621957915, 6622.37087476689, 6450.0, 6535.411812936859, 6572.76995305162, 6600.174978127734, 6603.405693487609, 5271.393789714232, 6078.878422431439, 6136.662483563447, 6443.484521238196, 6502.739890404331, 6602.240742314695, 6617.239017512855, 6621.973594092923, 6622.0, 6645.1361323027695, 6164.876702466016, 6299.685015749103, 6302.1492098566105, 6341.365863413622, 6388.297287298301], "value": 6046.506596284262, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.4879138229091, 765.2385876053328, 837.7151768398745, 1236.6722818453106, 1301.7071158989233, 37629.43555846665, 38002.719836809745, 38084.0, 38156.42765358522, 38264.0, 37959.430608540824, 38022.859314220615, 38225.808870955676, 38240.80879595598, 38364.616353836456, 6818.1136452261135, 7071.186813516447, 8325.750227493185, 8441.662333506652, 8521.020082690507, 8409.159084091603, 8493.36299777516, 8496.78758031048, 8512.78718032048, 8526.104759000278, 8444.408891377607, 8475.110113438093, 8488.448250863674, 8531.530765807878, 8546.145385461467, 8467.02629197644, 8485.61814718336, 8505.829883402328, 8530.616122274512, 8536.615852286663, 8352.87470687934, 8382.832343353099, 8390.202930721573, 8396.412251142423, 8423.831523369498, 33224.6677533224, 37437.25125497502, 37597.0, 37728.05679857998, 37728.24543509114, 3483.6342184070563, 3526.8941931741833, 3529.5940966788635, 3531.1348719563616, 3535.5403797506415, 27928.58107128395, 28436.0, 30656.540151897738, 30763.38473230545, 30770.846145769352, 3133.0, 3161.8893338733264, 3167.667394923523, 3177.729892959101, 3181.427343078245, 195703.0, 245059.0, 256616.99999999814, 290308.0, 291858.0, 48294.0, 48492.75753621209, 48527.75736121297, 48570.75714621404, 48576.0, 49862.0, 50298.497015030116, 50304.49695502998, 50469.747651262245, 50570.2414463776, 14144.64638384028, 14201.0, 14270.785938210935, 14281.78577321341, 14285.357158927873, 11039.558417663204, 11624.0, 11654.88345116538, 11663.300201987739, 11665.183437159318, 13537.052406331659, 13557.45770169202, 13573.321333933263, 13598.048136630341, 13629.386677599532, 6094.0, 6098.054801505774, 6113.23584551931, 6124.418180272867, 6138.416850399205, 979.4759803505091, 1021.315718468613, 1052.5947859607509, 1056.5615269663003, 1083.701981954953, 20741.896290518453, 24288.87855560711, 24373.63439548408, 24571.508569828504, 24612.384690382525, 1047.7171163785736, 1137.135776809614, 1178.5698220149584, 1184.798584240681, 1188.7563049574817, 11982.760344793056, 12479.376031198404, 12525.498980040878, 12540.81188782169, 12778.297193654438, 312431.4378428093, 328186.0, 329479.35260324023, 330543.0, 330722.0, 53431.46568534343, 53515.0, 53530.0, 53581.464185358425, 53689.657758555564, 1515.7802118692948, 1559.4152192927424, 1568.4040064775313, 1577.936882524686, 1581.2726145972981, 23835.52328953446, 23885.0, 23946.161884333964, 23949.521009579363, 24062.27813165608, 37828.05429864275, 37828.81085594554, 37908.0, 37939.43090853584, 37984.240315194096, 263985.3601463961, 265659.0, 266783.0, 266948.0, 266956.33043669316, 32713.836430817693, 33627.49558756669, 33902.491462627586, 34034.808781692525, 34171.65828341685, 43213.567864320954, 43860.561394386925, 45140.0, 45436.0, 45460.8634784133, 24434.26697199087, 25831.870840645675, 26048.34879128037, 29587.964421245153, 30853.8457307712, 28467.145985620464, 30714.078577641812, 30877.8456107718, 31037.0, 31097.68902310948, 32388.83805580957, 33040.50439243461, 36307.63692363042, 37195.62804371922, 37398.439023415216, 30147.547786782776, 30612.693873060984, 30936.845315773277, 31012.0, 31131.533027005065, 22986.0, 24225.273241802774, 24484.14305499299, 24732.876335618206, 24738.25785226446], "value": 17391.601542540397, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [418.3452896217419, 426.7524835595353, 433.37377489527626, 436.7706953849231, 459.30644726463044, 19553.315633952796, 21238.46903827403, 21787.6731849022, 22013.889930550366, 22039.0, 22122.22572209973, 22128.557428851414, 22397.888010559924, 22435.887820560914, 23568.58588484692, 6067.423594758491, 6210.347913469089, 6256.781012664557, 6264.780732674358, 6276.309605943343, 5848.385919478457, 6062.120992456091, 6077.939220607804, 6116.418940200696, 6126.571140020201, 5985.461308482236, 6024.3373228945, 6089.78685745999, 6123.234595675541, 6143.201383820097, 5981.072933695263, 6063.514918806507, 6098.878022439526, 6123.510119190499, 6128.38716128388, 5213.869653258661, 5366.0, 5384.59615528833, 5429.968306021843, 5447.0, 22058.558828823334, 22580.0, 27241.591376129174, 27402.5889611656, 27668.58497122545, 2002.4393169912448, 2141.646628306328, 2225.0, 2230.386643672987, 2233.1960494222067, 12422.875771242263, 14297.571072867831, 15883.0, 17424.564385890455, 17886.821131788645, 1829.4877434318412, 2043.5402034542258, 2171.2400659769114, 2186.212963333199, 2188.365374041526, 153482.0, 158430.0, 161161.19419402946, 162852.18573907172, 163092.0, 12276.508939642494, 12356.567520136752, 12608.306543140032, 12633.242005479517, 12832.422540985677, 16354.918225408797, 16549.420770273224, 16562.83437165637, 16621.252043658063, 16641.916790416213, 7723.922760772432, 8848.601812918434, 9167.587458564381, 9252.028537003653, 9351.532423378803, 8766.274333408275, 8773.03450379273, 8780.253678437277, 8971.730848074569, 9036.45781253127, 7862.135165131831, 7983.2415920487465, 8028.759137225892, 8059.798505037304, 8066.798330041678, 1915.4867654552747, 2188.540406514618, 2193.0679461228915, 2194.890255487251, 2339.0878356446333, 865.2688478235881, 951.70497145885, 985.1183191043975, 991.0188912976101, 1018.6842078955548, 20779.272725454535, 21288.89355553243, 21411.571768564543, 21492.14031438756, 26379.076732314275, 882.8675698645254, 1002.1331548210745, 1029.7888932768915, 1051.2956319265957, 1054.6256079091966, 11028.779424411467, 11286.830697539543, 11296.152788540892, 11308.943455282671, 11317.264377815416, 537675.0, 557159.0, 563956.0, 575538.0, 577886.0, 133442.33278833545, 133928.0, 134274.0, 135308.9703654446, 135910.0, 1220.0235618298236, 1243.6206956878239, 1268.492602958824, 1270.2568997136593, 1271.580378475101, 14802.6299342513, 16412.671746564763, 19311.03444827725, 19412.32056878059, 19494.22023119116, 58513.0, 58632.0, 59859.40140598538, 60419.79160416679, 60815.78368432518, 49894.75052624714, 61220.16339509822, 66264.0, 66363.0, 67349.66325168342, 7587.962060189884, 8566.914330856862, 8930.86603700932, 8970.775730606787, 9136.725898223063, 12356.196847204903, 13169.670758231121, 13963.441462341392, 14157.504487342894, 14435.639109022359, 10307.330023548446, 11786.528538858349, 11895.643130706092, 12160.452779625115, 12189.75620487603, 10727.463626818471, 11153.051990580892, 11342.376169310766, 11357.432128393713, 11694.4737486815, 11863.34751588671, 12015.639530814089, 12125.575604853788, 12259.203151795105, 12278.631641050782, 7447.739329123455, 8931.910680893108, 9073.410228335137, 9081.68214112503, 9248.722538323862, 9779.022097790164, 11433.656990290305, 11606.361650109326, 11781.587644432404, 11785.351805650771], "value": 10359.017488337618, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1249.9875001249986, 1460.2041887171497, 1480.2672677024875, 1483.940642374305, 1485.3984136424745, 11406.942965285172, 11686.766264674701, 11872.584459543918, 11880.7623847523, 11887.821682674756, 11725.530978760838, 11857.11071669624, 11906.821397679041, 11925.463354149062, 11964.282143071421, 2533.480636469524, 2562.064846331085, 2569.7301783312764, 2579.7549232822853, 2583.354161459635, 2477.4549599088173, 2541.9618705719336, 2543.580309248972, 2545.0583284184786, 2547.783438407738, 2499.5000999800022, 2520.3699075231193, 2552.974470255292, 2553.668023156987, 2556.961645575318, 2489.8132640051977, 2503.123906632682, 2527.178666933245, 2535.2394281715597, 2540.6697129373247, 2446.7430919753524, 2501.949961000788, 2515.069424313016, 2543.491301739641, 2548.7960963122905, 11733.354665493394, 11748.823767643493, 11780.11649126319, 11810.940945295304, 11843.881561184364, 6600.2409722882, 6792.117024786796, 6803.727850885959, 6808.7276508939585, 6841.110655614738, 4048.1903619276263, 4384.167008268419, 4458.398116254297, 4486.5962063414445, 4486.798094085774, 1232.8582213045436, 1251.7496500699897, 1257.7610254051792, 1260.1053252190864, 1334.866513348667, 15018.023828451225, 15513.224338783128, 16384.344626214937, 16476.0, 16500.917495412566, 5380.596455265871, 5510.366307874646, 5704.372519022904, 5715.685637289989, 5761.85595360111, 10782.4069676167, 10961.287516311415, 11656.533738650529, 11736.471858766376, 11753.412329383496, 6037.184980027728, 6127.938720612826, 6393.6483493408305, 6498.512611554058, 6511.446527045254, 5536.280283563111, 5788.855278618068, 5957.672328022, 6080.452759251689, 6255.96872015637, 4342.261815491374, 4405.405270288534, 4441.467023957146, 4477.440319960005, 4479.55204479556, 1116.6600079904147, 1275.559931823538, 1299.811527328532, 1309.9803502947273, 1320.5642138094597, 746.272384425192, 778.1634742651672, 794.4041968523608, 796.4783067091065, 798.3812545277457, 6548.869022619521, 6921.134858142732, 6923.238443771181, 7160.427165826722, 7179.641017949081, 945.7730144765344, 977.5112443778111, 985.3004366899581, 985.9654912078042, 987.2644879564626, 3455.3434847378917, 4100.753954762724, 4134.73124246923, 4208.326667733209, 4322.22200003996, 12853.48586056566, 14521.92739036298, 15120.243987800566, 15310.081395116329, 15327.846721532866, 14656.047356921767, 15333.0, 15395.769063464057, 15418.922905385625, 15442.305096270695, 8674.78313042179, 8747.606357713918, 8831.0, 8831.69089081879, 8833.60248788806, 6023.759049637965, 6766.2218844831505, 6919.584824910521, 6922.7923162305215, 6958.826029349307, 12582.874171258536, 13403.195808251536, 13527.932360338134, 13531.932340338235, 13779.379927903468, 13706.38321275525, 13793.586192414243, 13823.79264311016, 13916.58250252494, 14077.225752583707, 8239.340852731886, 8278.83442331162, 8335.708250211213, 8347.916520834713, 8391.32869370461, 10286.485675716034, 10515.684529464128, 10525.789484210427, 10530.420826854597, 10534.36793792375, 8802.383833131875, 8909.777255568662, 8967.192952634294, 9158.633654653739, 9174.082591740773, 8777.42946708462, 8778.166074222938, 8868.822623547621, 8901.643934242817, 8905.821883562163, 7635.465517413728, 8036.4776289541, 8115.188481152074, 8125.187481251827, 8125.512469251864, 5984.790532331347, 5993.250843644544, 6043.425874541911, 6092.847678808065, 6183.690815459119, 5869.413058694096, 5894.557908156819, 5960.986632272437, 5999.070144127667, 6089.634621922699], "value": 5383.148259451198, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.6363309388455, 402.8328243778832, 404.16741512484293, 404.70658772390004, 407.5211626339051, 5251.553617942474, 6215.617989317531, 6303.842403939892, 6304.369563043693, 6354.269259035206, 4405.537418571052, 5273.5781137509, 5393.541548968333, 5802.883942321151, 5898.587098903078, 3720.2931443025736, 3724.3296206682785, 4093.2222877653226, 4443.22243607369, 4463.73217606943, 4454.398656181423, 4467.821287148527, 4471.329300604919, 4477.0, 4481.126180394825, 3796.6013568575318, 4388.275934470808, 4418.779061046951, 4422.137683151786, 4442.822287108512, 4400.339949007643, 4413.44831896013, 4418.80115394808, 4420.756858372788, 4445.0, 3782.205736795276, 3796.088938654703, 3797.582265950743, 3799.25914446683, 3802.5627052888995, 4884.658073934826, 5027.572656324217, 5069.619778516626, 5493.752781124859, 6029.366916473795, 4506.684532082755, 5219.399769026572, 5233.528982391603, 5234.136367499358, 5249.580033597303, 5454.59090568209, 6512.479001679902, 6554.836129096763, 6573.539852210347, 6574.76988305407, 3796.4305354196817, 4192.0, 4358.455193100863, 4366.235908715962, 4372.972351497396, 6844.452443804484, 7077.964610176916, 7101.964490177567, 7120.323569260912, 7148.785536433916, 4116.91766164675, 4387.780610969438, 4428.33574963758, 4441.511433742286, 4444.466664000341, 4496.392986946786, 4509.751963642031, 4511.796969136397, 4517.412736344254, 4529.411176547028, 5181.844544663666, 5296.152615581488, 5339.599530035185, 5348.251244825728, 5371.140617501181, 5564.970480461127, 6092.268927728701, 6617.8014659560295, 6663.834678527757, 6675.732970681215, 1426.5149849051165, 1521.6153300496494, 1525.1840265457927, 1528.8624023837908, 1537.9461718839786, 849.5964416901923, 995.5320999130541, 1000.3247807729899, 1000.494750151169, 1014.4674046125696, 5679.4320567943705, 5705.144228365695, 6373.458256048195, 6398.360163983564, 6421.389967953037, 5812.38969908162, 5880.382559831241, 6684.598924064474, 6884.793456196322, 6900.6204658744255, 5348.866278343073, 5550.750216240279, 5601.722992049697, 5749.655020698771, 6171.783987560414, 3010.6838781928022, 3576.7496275261, 3580.4629305604367, 3617.168051348152, 3637.0, 6180.938190618036, 6312.463440607508, 6449.516286278548, 7051.506394552434, 7194.46041546886, 6006.879862402728, 6539.117219175446, 6546.443552298013, 7091.326323999315, 7152.749653762094, 6552.868942621216, 6996.125484314461, 7101.112360954984, 7104.0, 7165.0, 5998.86021655884, 6107.84730381744, 6161.84595385119, 6639.568428052161, 6956.40870526011, 6544.6727663617585, 6744.605664988859, 6744.966275168593, 6770.627615480998, 6788.537845698828, 6369.681515924092, 6786.151731033621, 6999.335063169195, 7030.191527974348, 7084.25615310385, 3970.2059588083075, 4414.82340706381, 4415.98785378604, 4425.203463376623, 4426.82292708301, 6402.263739669997, 6588.374104460068, 6882.449404047764, 6911.89632155508, 7048.964755176191, 6658.500612454182, 6669.866602667822, 6673.432758215606, 6689.5986240825705, 6759.121314229119, 6438.8390290243115, 6602.372774586406, 6630.5027122965, 6643.302453242533, 6682.766103186365, 5174.197999310113, 6494.27240558957, 6528.281888992112, 6543.640099794557, 6568.9014664781025, 6541.313162117909, 6647.966760166169, 6669.499787515859, 6670.266270710315, 6684.364985326386, 6410.935890641033, 6496.902546461902, 6511.544191906521, 6517.315681853337, 6546.541742078009], "value": 4739.184193063844, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [819.0989911097789, 820.3847114664005, 827.2348078027823, 847.7834307768353, 854.3848429131024, 15434.691306173883, 15823.20883955802, 15918.602034949132, 17913.283468661237, 18591.721124183146, 16583.751243731323, 16711.498655040337, 16862.241199146036, 16910.323587056504, 17017.659646807057, 995.761017355833, 1001.3191030099548, 1018.5976539266985, 1022.7903279827655, 1026.1431704526728, 1017.7862648843752, 1022.9079382855541, 1024.4570377699831, 1027.5889644142328, 1027.7533391985908, 849.2399302624148, 1022.3712416863613, 1048.192891473565, 1075.3440401355206, 1112.5994641928899, 1055.5250137438145, 1068.6633710381243, 1068.839674048891, 1070.4808168038496, 1076.4725284610524, 593.6734795862259, 794.2732399854126, 796.5141263829059, 797.6051854332085, 800.5316889619579, 13696.589102326947, 15780.842191578167, 16091.0, 16093.678126437406, 16115.43595974147, 13726.245056521888, 15593.220338983005, 16448.930819496694, 16450.75323870143, 16500.75248871268, 12859.678508037281, 13190.472381104744, 13307.268100254481, 13326.333683315892, 13333.0, 3376.983115084409, 3455.291665208626, 3470.5314782504292, 3484.337975784593, 3503.754737168399, 100516.49225261627, 116766.0, 123869.0, 125496.0, 125627.0, 12577.434015469325, 12899.93550032244, 12945.54690585825, 12985.415656295489, 13012.47950082005, 23533.29400117999, 23879.16422925189, 24020.39949001289, 24047.038118475415, 24531.0, 7468.85062298751, 7573.242675732493, 7588.165301816795, 7605.429592780563, 7625.428092893055, 3262.8368581570826, 3856.768593884376, 3864.53625564934, 3883.650471457582, 3894.7078969076865, 3945.743526670757, 3980.0447892506168, 4163.854265100767, 4178.331466965332, 4273.829046838092, 915.1169121797498, 940.7554035950702, 948.1419315519466, 950.843110886696, 952.8237276103943, 635.3582881289931, 668.4418510543653, 668.7057694614355, 669.1702289161502, 679.7077256779534, 10886.292390994719, 10888.40113793749, 11351.318920864775, 11637.825432618518, 11720.296782193096, 643.1863692429081, 657.5002997721766, 661.9702113404908, 688.8209065642969, 693.7259782385929, 5070.645054846125, 5158.871028224249, 5415.214793854869, 5420.945790542123, 5553.916691249635, 80862.78705819417, 100774.4961275189, 102541.48729256306, 104816.0, 106361.0, 95377.0, 95383.52308238414, 95600.0, 97422.0, 98237.0, 83288.33423331499, 83453.58273208595, 83734.58132709419, 83870.0, 85223.0, 9117.407368521024, 10745.409002504624, 10988.945055274673, 11268.492917818554, 12533.93733031329, 33362.16594585155, 37188.628113718514, 38836.223275534896, 40459.59540404676, 40577.0, 70426.94359584713, 70566.64716676384, 71041.6447917757, 71671.56656866938, 72139.39303034723, 15322.616934576725, 15781.447649332678, 15883.0, 15958.122303272967, 16347.836521635107, 17558.297668093644, 18316.90841545837, 18527.980961047273, 18584.44246672548, 18651.06744662734, 15774.763378549102, 15884.0, 16542.338306467605, 16579.585510362336, 16670.66658666844, 15799.289031993818, 15859.920700396424, 16621.750673740145, 16868.746968795724, 17149.399771007957, 15332.0, 15427.382904683687, 16096.758548622014, 16188.676226475169, 16375.263113160174, 11455.713607159887, 11595.536178552764, 11736.941315293368, 12074.698132546757, 12226.938865305616, 11408.714782130513, 12502.374881255719, 12805.231686098863, 13168.802467962796, 13321.467141314239], "value": 8269.734720583694, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1521.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 115.0, 138.0, 114.0, 117.0, 115.0, 114.0, 115.0, 144.0, 167.0], "value": 124.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 109.0, 117.0, 177.0, 134.0, 112.0, 114.0, 113.0, 143.0, 111.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [212.0, 222.0, 207.0, 268.0, 201.0, 201.0, 200.0, 198.0, 201.0, 201.0], "value": 211.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 57.0, 53.0, 112.0, 88.0, 86.0, 83.0, 81.0, 84.0, 57.0], "value": 77.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 69.0, 71.0, 77.0, 95.0, 101.0, 105.0, 55.0, 74.0, 112.0], "value": 85.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 101.0, 90.0, 96.0, 90.0, 91.0, 89.0, 89.0, 95.0], "value": 92.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 98.0, 97.0, 97.0, 97.0, 96.0, 104.0, 97.0, 97.0, 102.0], "value": 98.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 93.0, 92.0, 93.0, 143.0, 95.0, 91.0, 91.0, 95.0], "value": 97.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 50.0, 52.0, 61.0, 48.0, 51.0, 49.0, 51.0, 52.0], "value": 51.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 59.0, 64.0, 63.0, 64.0, 62.0, 56.0, 55.0, 49.0, 50.0], "value": 57.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 94.0, 98.0, 82.0, 82.0, 83.0, 82.0, 87.0, 79.0], "value": 85.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 142.0, 139.0, 151.0, 141.0, 153.0, 153.0, 161.0, 190.0, 165.0], "value": 153.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 185.0, 187.0, 164.0, 163.0, 159.0, 231.0, 229.0, 217.0, 187.0], "value": 190.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 68.0, 71.0, 67.0, 70.0, 71.0, 67.0, 71.0, 73.0, 73.0], "value": 70.2, "unit": "score"}]}]} 16:00:57 INFO - Return code: 0 16:00:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:00:57 INFO - # TBPL SUCCESS # 16:00:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:00:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:00:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:00:57 INFO - 2017-05-05 16:00:57.721 screenresolution[1666:10884] starting screenresolution argv=screenresolution get 16:00:57 INFO - 2017-05-05 16:00:57.723 screenresolution[1666:10884] Display 0: 1600x1200x32@60 16:00:57 INFO - 2017-05-05 16:00:57.730 screenresolution[1667:10887] starting screenresolution argv=screenresolution list 16:00:57 INFO - Available Modes on Display 0 16:00:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:00:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:00:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:00:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:00:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:00:57 INFO - Intel Iris: 16:00:57 INFO - Chipset Model: Intel Iris 16:00:57 INFO - Type: GPU 16:00:57 INFO - Bus: Built-In 16:00:57 INFO - VRAM (Dynamic, Max): 1536 MB 16:00:57 INFO - Vendor: Intel (0x8086) 16:00:57 INFO - Device ID: 0x0a2e 16:00:57 INFO - Revision ID: 0x0009 16:00:57 INFO - Displays: 16:00:57 INFO - Display: 16:00:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:00:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:00:57 INFO - Display Serial Number: 1600x1200 60 16:00:57 INFO - Main Display: Yes 16:00:57 INFO - Mirror: Off 16:00:57 INFO - Online: Yes 16:00:57 INFO - Rotation: Supported 16:00:57 INFO - Return code: 0 16:00:57 INFO - Running post-action listener: _resource_record_post_action 16:00:57 INFO - [mozharness: 2017-05-05 23:00:57.983678Z] Finished run-tests step (success) 16:00:57 INFO - Running post-run listener: _resource_record_post_run 16:00:58 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 45440000; Write bytes: 515812352; Read time: 478; Write time: 1948 16:00:58 INFO - TinderboxPrint: CPU usage
54.3% 16:00:58 INFO - TinderboxPrint: I/O read bytes / time
45,440,000 / 478 16:00:58 INFO - TinderboxPrint: I/O write bytes / time
515,812,352 / 1,948 16:00:58 INFO - TinderboxPrint: CPU idle
2,191.2 (45.7%) 16:00:58 INFO - TinderboxPrint: CPU system
968.1 (20.2%) 16:00:58 INFO - TinderboxPrint: CPU user
1,638.3 (34.1%) 16:00:58 INFO - TinderboxPrint: Swap in / out
249,040,896 / 0 16:00:58 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 193907200; Write bytes: 195172352; Read time: 15030; Write time: 483 16:00:58 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 44407808; Write bytes: 303793152; Read time: 460; Write time: 1415 16:00:58 INFO - Running post-run listener: _upload_blobber_files 16:00:58 INFO - Blob upload gear active. 16:00:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:00:58 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 16:00:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:00:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:00:58 INFO - (blobuploader) - INFO - Open directory for files ... 16:00:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:00:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:00:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:59 INFO - (blobuploader) - INFO - Done attempting. 16:00:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:00:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:01:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:01:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:01:00 INFO - (blobuploader) - INFO - Done attempting. 16:01:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:01:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:01:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:01:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:01:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:01:01 INFO - (blobuploader) - INFO - Done attempting. 16:01:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:01:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:01:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:01:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:01:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:01:03 INFO - (blobuploader) - INFO - Done attempting. 16:01:03 INFO - (blobuploader) - INFO - Iteration through files over. 16:01:03 INFO - Return code: 0 16:01:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:01:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:01:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b4d42d971ea80d0966acd48397de4d63a2b6ea1d6c6e8bd7354ca6e98c97bcca9e2165a2697325e1410b754535c083e3383dcdc2981171b90b10aff2e6ac506", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42441a947faacd894cb70fc926795bff7f9f0567f3210bddcee7779bc2126e60cdc58a7dd25c9bb19f7a1fd0d33b20aa75d0f657b458387b762fafc2efcfaa95", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6ae951579ff6af9e770c97fe260dcab4cf10a19983f5dbc9d87bee5bf9fe49444c0a8f750dd90e4b5a284ba51abefce8224c3b188f4a889649c5519831c881d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e83ce8d37258b549c310cf0bf4c28449f4f627066381695b5c27b96a8ef115242c6c1c8761b8ccdffd618ebc773f9d121712163b0a231a6d44a3bc9a77f1a29"} 16:01:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:01:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:01:03 INFO - Contents: 16:01:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b4d42d971ea80d0966acd48397de4d63a2b6ea1d6c6e8bd7354ca6e98c97bcca9e2165a2697325e1410b754535c083e3383dcdc2981171b90b10aff2e6ac506", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42441a947faacd894cb70fc926795bff7f9f0567f3210bddcee7779bc2126e60cdc58a7dd25c9bb19f7a1fd0d33b20aa75d0f657b458387b762fafc2efcfaa95", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6ae951579ff6af9e770c97fe260dcab4cf10a19983f5dbc9d87bee5bf9fe49444c0a8f750dd90e4b5a284ba51abefce8224c3b188f4a889649c5519831c881d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e83ce8d37258b549c310cf0bf4c28449f4f627066381695b5c27b96a8ef115242c6c1c8761b8ccdffd618ebc773f9d121712163b0a231a6d44a3bc9a77f1a29"} 16:01:03 INFO - Running post-run listener: copy_logs_to_upload_dir 16:01:03 INFO - Copying logs to upload dir... 16:01:03 INFO - mkdir: /builds/slave/test/build/upload/logs 16:01:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.275026 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-05-05 16:01:03.945113) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-05 16:01:03.949224) ========= 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.qeD5qGfeuu/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.RciORhtEah/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b4d42d971ea80d0966acd48397de4d63a2b6ea1d6c6e8bd7354ca6e98c97bcca9e2165a2697325e1410b754535c083e3383dcdc2981171b90b10aff2e6ac506", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42441a947faacd894cb70fc926795bff7f9f0567f3210bddcee7779bc2126e60cdc58a7dd25c9bb19f7a1fd0d33b20aa75d0f657b458387b762fafc2efcfaa95", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6ae951579ff6af9e770c97fe260dcab4cf10a19983f5dbc9d87bee5bf9fe49444c0a8f750dd90e4b5a284ba51abefce8224c3b188f4a889649c5519831c881d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e83ce8d37258b549c310cf0bf4c28449f4f627066381695b5c27b96a8ef115242c6c1c8761b8ccdffd618ebc773f9d121712163b0a231a6d44a3bc9a77f1a29"} build_url:https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011351 build_url: 'https://queue.taskcluster.net/v1/task/DZ33HXj4R8CKkKcrpy-zMA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b4d42d971ea80d0966acd48397de4d63a2b6ea1d6c6e8bd7354ca6e98c97bcca9e2165a2697325e1410b754535c083e3383dcdc2981171b90b10aff2e6ac506", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42441a947faacd894cb70fc926795bff7f9f0567f3210bddcee7779bc2126e60cdc58a7dd25c9bb19f7a1fd0d33b20aa75d0f657b458387b762fafc2efcfaa95", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6ae951579ff6af9e770c97fe260dcab4cf10a19983f5dbc9d87bee5bf9fe49444c0a8f750dd90e4b5a284ba51abefce8224c3b188f4a889649c5519831c881d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e83ce8d37258b549c310cf0bf4c28449f4f627066381695b5c27b96a8ef115242c6c1c8761b8ccdffd618ebc773f9d121712163b0a231a6d44a3bc9a77f1a29"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-05 16:01:03.984667) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 16:01:03.985023) ========= 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.qeD5qGfeuu/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.RciORhtEah/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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.005259 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 16:01:04.033649) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-05 16:01:04.033982) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-05 16:01:04.034355) ========= ========= Total master_lag: 6.39 =========