builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0314 starttime: 1494523734.09 results: success (0) revision: 3b96f277325747fe668ca8cd896d2f581238e4ee ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 10:28:54.093182) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 10:28:54.093954) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 10:28:54.094250) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 10:28:54.123513) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 10:28:54.123977) ========= 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.EC0IstH5Ce/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.ci5g6J4IJI/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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-11 10:28:54-- 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% 173M=0s 2017-05-11 10:28:54 (173 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.095111 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 10:28:54.243066) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 10:28:54.243484) ========= 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.EC0IstH5Ce/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.ci5g6J4IJI/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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.024120 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 10:28:54.292889) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-11 10:28:54.293377) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3b96f277325747fe668ca8cd896d2f581238e4ee --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 3b96f277325747fe668ca8cd896d2f581238e4ee --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EC0IstH5Ce/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.ci5g6J4IJI/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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-11 10:28:54,398 truncating revision to first 12 chars 2017-05-11 10:28:54,399 Setting DEBUG logging. 2017-05-11 10:28:54,399 attempt 1/10 2017-05-11 10:28:54,399 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3b96f2773257?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-11 10:28:55,239 unpacking tar archive at: mozilla-central-3b96f2773257/testing/mozharness/ program finished with exit code 0 elapsedTime=1.120155 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-11 10:28:55.437377) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 10:28:55.438023) ========= script_repo_revision: 3b96f277325747fe668ca8cd896d2f581238e4ee ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 10:28:55.438529) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 10:28:55.438901) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 10:28:55.460521) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 41 mins, 25 secs) (at 2017-05-11 10:28:55.461209) ========= /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.EC0IstH5Ce/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.ci5g6J4IJI/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 10:28:55 INFO - MultiFileLogger online at 20170511 10:28:55 in /builds/slave/test 10:28:55 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 10:28:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:28:55 INFO - {'append_to_log': False, 10:28:55 INFO - 'base_work_dir': '/builds/slave/test', 10:28:55 INFO - 'blob_upload_branch': 'Firefox', 10:28:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:28:55 INFO - 'branch': 'Firefox', 10:28:55 INFO - 'buildbot_json_path': 'buildprops.json', 10:28:55 INFO - 'config_files': ('talos/mac_config.py',), 10:28:55 INFO - 'default_actions': ('clobber', 10:28:55 INFO - 'read-buildbot-config', 10:28:55 INFO - 'download-and-extract', 10:28:55 INFO - 'populate-webroot', 10:28:55 INFO - 'create-virtualenv', 10:28:55 INFO - 'install', 10:28:55 INFO - 'run-tests'), 10:28:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:28:55 INFO - 'download_minidump_stackwalk': True, 10:28:55 INFO - 'download_symbols': 'ondemand', 10:28:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:28:55 INFO - 'tooltool.py': '/tools/tooltool.py', 10:28:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:28:55 INFO - '/tools/misc-python/virtualenv.py')}, 10:28:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:28:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:28:55 INFO - 'gecko_profile': False, 10:28:55 INFO - 'gecko_profile_interval': 0, 10:28:55 INFO - 'installer_path': 'installer.exe', 10:28:55 INFO - 'log_level': 'info', 10:28:55 INFO - 'log_name': 'talos', 10:28:55 INFO - 'log_to_console': True, 10:28:55 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:28:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:28:55 INFO - 'opt_config_files': (), 10:28:55 INFO - 'pip_index': False, 10:28:55 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:28:55 INFO - 'cmd': ('bash', 10:28:55 INFO - '-c', 10:28:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:28:55 INFO - 'enabled': True, 10:28:55 INFO - 'halt_on_failure': False, 10:28:55 INFO - 'name': 'check_screen_resolution'},), 10:28:55 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:28:55 INFO - 'cmd': ('bash', 10:28:55 INFO - '-c', 10:28:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:28:55 INFO - 'enabled': True, 10:28:55 INFO - 'halt_on_failure': False, 10:28:55 INFO - 'name': 'check_screen_resolution'},), 10:28:55 INFO - 'run_cmd_checks_enabled': True, 10:28:55 INFO - 'suite': 'dromaeojs-e10s', 10:28:55 INFO - 'system_bits': '32', 10:28:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:28:55 INFO - 'title': 't-yosemite-r7-0314', 10:28:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:28:55 INFO - 'use_talos_json': True, 10:28:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:28:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:28:55 INFO - 'work_dir': 'build'} 10:28:55 INFO - [mozharness: 2017-05-11 17:28:55.676429Z] Running clobber step. 10:28:55 INFO - Running pre-action listener: _resource_record_pre_action 10:28:55 INFO - Running main action method: clobber 10:28:55 INFO - rmtree: /builds/slave/test/build 10:28:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:28:58 INFO - Running post-action listener: _resource_record_post_action 10:28:58 INFO - [mozharness: 2017-05-11 17:28:58.088797Z] Finished clobber step (success) 10:28:58 INFO - [mozharness: 2017-05-11 17:28:58.088921Z] Running read-buildbot-config step. 10:28:58 INFO - Running pre-action listener: _resource_record_pre_action 10:28:58 INFO - Running main action method: read_buildbot_config 10:28:58 INFO - Using buildbot properties: 10:28:58 INFO - { 10:28:58 INFO - "project": "", 10:28:58 INFO - "product": "firefox", 10:28:58 INFO - "who": "ffxbld@noreply.mozilla.org", 10:28:58 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 10:28:58 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 10:28:58 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 10:28:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 10:28:58 INFO - "stage_platform": "macosx64", 10:28:58 INFO - "basedir": "/builds/slave/test", 10:28:58 INFO - "buildnumber": 59, 10:28:58 INFO - "platform": "yosemite_r7", 10:28:58 INFO - "parent_task_id": "AcrmHGpHQDmj8gAyLuoYOg", 10:28:58 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 10:28:58 INFO - "slavebuilddir": "test", 10:28:58 INFO - "taskId": "Mpo3t0JaSteqGeX3FOSwnw", 10:28:58 INFO - "branch": "mozilla-central", 10:28:58 INFO - "script_repo_revision": "production", 10:28:58 INFO - "revision": "3b96f277325747fe668ca8cd896d2f581238e4ee", 10:28:58 INFO - "slavename": "t-yosemite-r7-0314", 10:28:58 INFO - "repo_path": "mozilla-central" 10:28:58 INFO - } 10:28:58 INFO - Finding installer, test and symbols from parent task. 10:28:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mpo3t0JaSteqGeX3FOSwnw'}, attempt #1 10:28:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mpo3t0JaSteqGeX3FOSwnw'}, attempt #1 10:28:59 INFO - Task dependencies: cdC9z_BcS0W3tqQTWCg5Ww 10:28:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AcrmHGpHQDmj8gAyLuoYOg'}, attempt #1 10:28:59 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/AcrmHGpHQDmj8gAyLuoYOg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:28:59 INFO - Set test_url: https://queue.taskcluster.net/v1/task/AcrmHGpHQDmj8gAyLuoYOg/artifacts/public/build/test_packages.json 10:28:59 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/AcrmHGpHQDmj8gAyLuoYOg/artifacts/public/build/target.crashreporter-symbols.zip 10:28:59 INFO - Running post-action listener: _resource_record_post_action 10:28:59 INFO - [mozharness: 2017-05-11 17:28:59.790724Z] Finished read-buildbot-config step (success) 10:28:59 INFO - [mozharness: 2017-05-11 17:28:59.790864Z] Running download-and-extract step. 10:28:59 INFO - Running pre-action listener: _resource_record_pre_action 10:28:59 INFO - Running main action method: download_and_extract 10:28:59 INFO - mkdir: /builds/slave/test/build/tests 10:28:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:28:59 INFO - trying https://queue.taskcluster.net/v1/task/AcrmHGpHQDmj8gAyLuoYOg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 10:28:59 INFO - Downloading https://queue.taskcluster.net/v1/task/AcrmHGpHQDmj8gAyLuoYOg/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 10:28:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AcrmHGpHQDmj8gAyLuoYOg/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 10:29:00 INFO - Downloaded 1325 bytes. 10:29:00 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:29:00 INFO - Using the following test package requirements: 10:29:00 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:29:00 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 10:29:00 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:29:00 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:29:00 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 10:29:00 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:29:00 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 10:29:00 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:29:00 INFO - u'jsshell-mac.zip'], 10:29:00 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:29:00 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 10:29:00 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:29:00 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:29:00 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 10:29:00 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:29:00 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 10:29:00 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:29:00 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 10:29:00 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:29:00 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 10:29:00 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:29:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AcrmHGpHQDmj8gAyLuoYOg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:29:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AcrmHGpHQDmj8gAyLuoYOg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:29:00 INFO - Fetch https://queue.taskcluster.net/v1/task/AcrmHGpHQDmj8gAyLuoYOg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:29:02 INFO - Content-Length response header: 52708090 10:29:02 INFO - Bytes received: 52708090 10:29:18 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 10:29:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AcrmHGpHQDmj8gAyLuoYOg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:29:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AcrmHGpHQDmj8gAyLuoYOg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:29:18 INFO - Fetch https://queue.taskcluster.net/v1/task/AcrmHGpHQDmj8gAyLuoYOg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:29:20 INFO - Content-Length response header: 52708090 10:29:20 INFO - Bytes received: 52708090 10:29:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AcrmHGpHQDmj8gAyLuoYOg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 10:29:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AcrmHGpHQDmj8gAyLuoYOg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:29:35 INFO - Fetch https://queue.taskcluster.net/v1/task/AcrmHGpHQDmj8gAyLuoYOg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 10:29:36 INFO - Content-Length response header: 14409411 10:29:36 INFO - Bytes received: 14409411 10:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:37 INFO - trying https://queue.taskcluster.net/v1/task/AcrmHGpHQDmj8gAyLuoYOg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:29:37 INFO - Downloading https://queue.taskcluster.net/v1/task/AcrmHGpHQDmj8gAyLuoYOg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 10:29:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AcrmHGpHQDmj8gAyLuoYOg/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 10:29:39 INFO - Downloaded 75543569 bytes. 10:29:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AcrmHGpHQDmj8gAyLuoYOg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:29:39 INFO - mkdir: /builds/slave/test/properties 10:29:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:29:39 INFO - Writing to file /builds/slave/test/properties/build_url 10:29:39 INFO - Contents: 10:29:39 INFO - build_url:https://queue.taskcluster.net/v1/task/AcrmHGpHQDmj8gAyLuoYOg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:29:39 INFO - Running post-action listener: _resource_record_post_action 10:29:39 INFO - Running post-action listener: set_extra_try_arguments 10:29:39 INFO - [mozharness: 2017-05-11 17:29:39.351104Z] Finished download-and-extract step (success) 10:29:39 INFO - [mozharness: 2017-05-11 17:29:39.351258Z] Running populate-webroot step. 10:29:39 INFO - Running pre-action listener: _resource_record_pre_action 10:29:39 INFO - Running main action method: populate_webroot 10:29:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:29:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:29:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:29:39 INFO - '--tppagecycles', 10:29:39 INFO - '7'], 10:29:39 INFO - 'tests': ['tsvgm']}}, 10:29:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:29:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:29:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:29:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:29:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:29:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:29:39 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:29:39 INFO - 'talos_options': ['--disable-e10s'], 10:29:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:29:39 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:29:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:29:39 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:29:39 INFO - 'talos_options': ['--disable-e10s'], 10:29:39 INFO - 'tests': ['damp', 'tps']}, 10:29:39 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:29:39 INFO - 'tests': ['damp', 'tps']}, 10:29:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:29:39 INFO - 'tests': ['dromaeo_dom']}, 10:29:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:29:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:29:39 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 10:29:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:29:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:29:39 INFO - 'tests': ['a11yr', 10:29:39 INFO - 'ts_paint', 10:29:39 INFO - 'tpaint', 10:29:39 INFO - 'sessionrestore', 10:29:39 INFO - 'sessionrestore_no_auto_restore', 10:29:39 INFO - 'tabpaint']}, 10:29:39 INFO - 'other-e10s': {'tests': ['a11yr', 10:29:39 INFO - 'ts_paint', 10:29:39 INFO - 'tpaint', 10:29:39 INFO - 'sessionrestore', 10:29:39 INFO - 'sessionrestore_no_auto_restore', 10:29:39 INFO - 'tabpaint']}, 10:29:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:29:39 INFO - 'ts_paint', 10:29:39 INFO - 'tpaint', 10:29:39 INFO - 'sessionrestore', 10:29:39 INFO - 'sessionrestore_no_auto_restore', 10:29:39 INFO - 'tabpaint']}, 10:29:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:29:39 INFO - 'ts_paint', 10:29:39 INFO - 'tpaint', 10:29:39 INFO - 'sessionrestore', 10:29:39 INFO - 'sessionrestore_no_auto_restore', 10:29:39 INFO - 'tabpaint']}, 10:29:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:29:39 INFO - 'tests': ['a11yr', 10:29:39 INFO - 'ts_paint', 10:29:39 INFO - 'tpaint', 10:29:39 INFO - 'sessionrestore', 10:29:39 INFO - 'sessionrestore_no_auto_restore', 10:29:39 INFO - 'tabpaint']}, 10:29:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:29:39 INFO - 'tests': ['a11yr', 10:29:39 INFO - 'ts_paint', 10:29:39 INFO - 'tpaint', 10:29:39 INFO - 'sessionrestore', 10:29:39 INFO - 'sessionrestore_no_auto_restore', 10:29:39 INFO - 'tabpaint']}, 10:29:39 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 10:29:39 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 10:29:39 INFO - 'bloom_basic_ref']}, 10:29:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:29:39 INFO - 'tests': ['tsvgx', 10:29:39 INFO - 'tsvgr_opacity', 10:29:39 INFO - 'tart', 10:29:39 INFO - 'tscrollx', 10:29:39 INFO - 'cart', 10:29:39 INFO - 'tsvg_static']}, 10:29:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:29:39 INFO - 'tsvgr_opacity', 10:29:39 INFO - 'tart', 10:29:39 INFO - 'tscrollx', 10:29:39 INFO - 'cart', 10:29:39 INFO - 'tsvg_static']}, 10:29:39 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:29:39 INFO - 'talos_options': ['--disable-e10s'], 10:29:39 INFO - 'tests': ['tp5o']}, 10:29:39 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:29:39 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:29:39 INFO - 'talos_options': ['--disable-e10s', 10:29:39 INFO - '--xperf_path', 10:29:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:29:39 INFO - 'tests': ['tp5n']}, 10:29:39 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:29:39 INFO - 'talos_options': ['--xperf_path', 10:29:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:29:39 INFO - 'tests': ['tp5n']}}, 10:29:39 INFO - 'talos.zip': {'path': '', 10:29:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:29:39 INFO - Running post-action listener: _resource_record_post_action 10:29:39 INFO - [mozharness: 2017-05-11 17:29:39.372418Z] Finished populate-webroot step (success) 10:29:39 INFO - [mozharness: 2017-05-11 17:29:39.372555Z] Running create-virtualenv step. 10:29:39 INFO - Running pre-action listener: _resource_record_pre_action 10:29:39 INFO - Running main action method: create_virtualenv 10:29:39 INFO - Creating virtualenv /builds/slave/test/build/venv 10:29:39 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 10:29:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:29:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:29:39 INFO - Using real prefix '/tools/python27' 10:29:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:29:40 INFO - Installing distribute.............................................................................................................................................................................................done. 10:29:43 INFO - Installing pip.................done. 10:29:43 INFO - Return code: 0 10:29:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:29:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:29:43 INFO - Reading from file tmpfile_stdout 10:29:43 INFO - Output received: 10:29:43 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) 10:29:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:29:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1064bcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064bf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc43246ef60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061cdd30>, '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 0x106180db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061c1be8>, '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.EC0IstH5Ce/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.ci5g6J4IJI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 10:29:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:29:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:29:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EC0IstH5Ce/Render', 10:29:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:43 INFO - 'HOME': '/Users/cltbld', 10:29:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:43 INFO - 'LOGNAME': 'cltbld', 10:29:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:43 INFO - 'MOZ_NO_REMOTE': '1', 10:29:43 INFO - 'NO_EM_RESTART': '1', 10:29:43 INFO - 'PAGER': '/bin/cat', 10:29:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:43 INFO - 'PWD': '/builds/slave/test', 10:29:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:43 INFO - 'SHELL': '/bin/bash', 10:29:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ci5g6J4IJI/Listeners', 10:29:43 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 10:29:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:43 INFO - 'USER': 'cltbld', 10:29:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:43 INFO - 'XPC_FLAGS': '0x0', 10:29:43 INFO - 'XPC_SERVICE_NAME': '0', 10:29:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:43 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 10:29:43 INFO - Cleaning up... 10:29:43 INFO - Return code: 0 10:29:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:29:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1064bcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064bf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc43246ef60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061cdd30>, '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 0x106180db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061c1be8>, '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.EC0IstH5Ce/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.ci5g6J4IJI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 10:29:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:29:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:29:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EC0IstH5Ce/Render', 10:29:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:43 INFO - 'HOME': '/Users/cltbld', 10:29:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:43 INFO - 'LOGNAME': 'cltbld', 10:29:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:43 INFO - 'MOZ_NO_REMOTE': '1', 10:29:43 INFO - 'NO_EM_RESTART': '1', 10:29:43 INFO - 'PAGER': '/bin/cat', 10:29:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:43 INFO - 'PWD': '/builds/slave/test', 10:29:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:43 INFO - 'SHELL': '/bin/bash', 10:29:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ci5g6J4IJI/Listeners', 10:29:43 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 10:29:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:43 INFO - 'USER': 'cltbld', 10:29:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:43 INFO - 'XPC_FLAGS': '0x0', 10:29:43 INFO - 'XPC_SERVICE_NAME': '0', 10:29:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:43 INFO - Downloading/unpacking psutil>=3.1.1 10:29: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 10:29: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 10:29: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 10:29: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 10:29: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 10:29: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 10:32:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:32:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:32:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:32:16 INFO - Installing collected packages: psutil 10:32:16 INFO - Running setup.py install for psutil 10:32:16 INFO - building 'psutil._psutil_osx' extension 10:32:16 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 10:32:16 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 10:32:16 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 10:32:16 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 10:32:16 INFO - building 'psutil._psutil_posix' extension 10:32:16 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 10:32:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:32:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:32:17 INFO - ^ 10:32:17 INFO - 1 warning generated. 10:32:17 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 10:32:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:32:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:32:17 INFO - Successfully installed psutil 10:32:17 INFO - Cleaning up... 10:32:17 INFO - Return code: 0 10:32:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:32:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:32:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:32:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:32:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:32:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:32:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1064bcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064bf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc43246ef60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061cdd30>, '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 0x106180db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061c1be8>, '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.EC0IstH5Ce/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.ci5g6J4IJI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 10:32:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:32:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:32:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EC0IstH5Ce/Render', 10:32:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:32:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:32:17 INFO - 'HOME': '/Users/cltbld', 10:32:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:32:17 INFO - 'LOGNAME': 'cltbld', 10:32:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:32:17 INFO - 'MOZ_NO_REMOTE': '1', 10:32:17 INFO - 'NO_EM_RESTART': '1', 10:32:17 INFO - 'PAGER': '/bin/cat', 10:32:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:32:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:32:17 INFO - 'PWD': '/builds/slave/test', 10:32:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:32:17 INFO - 'SHELL': '/bin/bash', 10:32:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ci5g6J4IJI/Listeners', 10:32:17 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 10:32:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:32:17 INFO - 'USER': 'cltbld', 10:32:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:32:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:32:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:32:17 INFO - 'XPC_FLAGS': '0x0', 10:32:17 INFO - 'XPC_SERVICE_NAME': '0', 10:32:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:32:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:32:18 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 10:32:18 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 10:32:18 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 10:32:18 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 10:32:18 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 10:32:18 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 10:34:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:34:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:34:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:34:50 INFO - Installing collected packages: mozsystemmonitor 10:34:50 INFO - Running setup.py install for mozsystemmonitor 10:34:50 INFO - Successfully installed mozsystemmonitor 10:34:50 INFO - Cleaning up... 10:34:50 INFO - Return code: 0 10:34:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:34: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')]} 10:34:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:34:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34: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')]} 10:34:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:34:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:52 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 0x1064bcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064bf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc43246ef60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061cdd30>, '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 0x106180db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061c1be8>, '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.EC0IstH5Ce/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.ci5g6J4IJI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 10:34:52 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 10:34:52 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 10:34:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EC0IstH5Ce/Render', 10:34:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:34:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:34:52 INFO - 'HOME': '/Users/cltbld', 10:34:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:34:52 INFO - 'LOGNAME': 'cltbld', 10:34:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:34:52 INFO - 'MOZ_NO_REMOTE': '1', 10:34:52 INFO - 'NO_EM_RESTART': '1', 10:34:52 INFO - 'PAGER': '/bin/cat', 10:34:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:34:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:34:52 INFO - 'PWD': '/builds/slave/test', 10:34:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:34:52 INFO - 'SHELL': '/bin/bash', 10:34:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ci5g6J4IJI/Listeners', 10:34:52 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 10:34:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:34:52 INFO - 'USER': 'cltbld', 10:34:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:34:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:34:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:34:52 INFO - 'XPC_FLAGS': '0x0', 10:34:52 INFO - 'XPC_SERVICE_NAME': '0', 10:34:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:34:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:34:52 INFO - Downloading/unpacking jsonschema==2.5.1 10:34:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:34:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:34:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:34:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:34:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:34:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:37:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:37:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:37:24 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 10:37:24 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 10:37:24 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 10:37:24 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 10:37:24 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 10:37:24 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 10:39:55 INFO - Downloading functools32-3.2.3-2.tar.gz 10:39:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:39:55 INFO - warning: no files found matching '*.txt' 10:39:55 INFO - no previously-included directories found matching 'build' 10:39:55 INFO - no previously-included directories found matching 'dist' 10:39:55 INFO - no previously-included directories found matching '.git*' 10:39:55 INFO - Installing collected packages: jsonschema, functools32 10:39:55 INFO - Running setup.py install for functools32 10:39:55 INFO - warning: no files found matching '*.txt' 10:39:55 INFO - no previously-included directories found matching 'build' 10:39:55 INFO - no previously-included directories found matching 'dist' 10:39:55 INFO - no previously-included directories found matching '.git*' 10:39:55 INFO - Successfully installed jsonschema functools32 10:39:55 INFO - Cleaning up... 10:39:55 INFO - Return code: 0 10:39:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:39:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:39:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:39:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:57 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 0x1064bcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064bf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc43246ef60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061cdd30>, '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 0x106180db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061c1be8>, '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.EC0IstH5Ce/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.ci5g6J4IJI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 10:39:57 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 10:39:57 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 10:39:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EC0IstH5Ce/Render', 10:39:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:39:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:39:57 INFO - 'HOME': '/Users/cltbld', 10:39:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:39:57 INFO - 'LOGNAME': 'cltbld', 10:39:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:57 INFO - 'MOZ_NO_REMOTE': '1', 10:39:57 INFO - 'NO_EM_RESTART': '1', 10:39:57 INFO - 'PAGER': '/bin/cat', 10:39:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:39:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:57 INFO - 'PWD': '/builds/slave/test', 10:39:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:39:57 INFO - 'SHELL': '/bin/bash', 10:39:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ci5g6J4IJI/Listeners', 10:39:57 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 10:39:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:39:57 INFO - 'USER': 'cltbld', 10:39:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:39:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:39:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:57 INFO - 'XPC_FLAGS': '0x0', 10:39:57 INFO - 'XPC_SERVICE_NAME': '0', 10:39:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:39:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:39:57 INFO - Cleaning up... 10:39:57 INFO - Return code: 0 10:39:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:39: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')]} 10:39:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39: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')]} 10:39:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:57 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 0x1064bcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064bf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc43246ef60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061cdd30>, '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 0x106180db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061c1be8>, '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.EC0IstH5Ce/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.ci5g6J4IJI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 10:39:57 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 10:39:57 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 10:39:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EC0IstH5Ce/Render', 10:39:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:39:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:39:57 INFO - 'HOME': '/Users/cltbld', 10:39:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:39:57 INFO - 'LOGNAME': 'cltbld', 10:39:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:57 INFO - 'MOZ_NO_REMOTE': '1', 10:39:57 INFO - 'NO_EM_RESTART': '1', 10:39:57 INFO - 'PAGER': '/bin/cat', 10:39:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:39:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:57 INFO - 'PWD': '/builds/slave/test', 10:39:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:39:57 INFO - 'SHELL': '/bin/bash', 10:39:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ci5g6J4IJI/Listeners', 10:39:57 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 10:39:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:39:57 INFO - 'USER': 'cltbld', 10:39:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:39:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:39:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:57 INFO - 'XPC_FLAGS': '0x0', 10:39:57 INFO - 'XPC_SERVICE_NAME': '0', 10:39:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:39:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:57 INFO - Downloading/unpacking blobuploader==1.2.4 10:39:57 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 10:39:57 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 10:39:57 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 10:39:57 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 10:39:57 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 10:39:57 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 10:42:30 INFO - Downloading blobuploader-1.2.4.tar.gz 10:42:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:42:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:42:30 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 10:42:30 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 10:42:30 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 10:42:30 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 10:42:30 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 10:42:30 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 10:45:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:45:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:45:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:45:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:45:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:47:31 INFO - Downloading docopt-0.6.1.tar.gz 10:47:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:47:31 INFO - Installing collected packages: blobuploader, requests, docopt 10:47:31 INFO - Running setup.py install for blobuploader 10:47:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:47:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:47:31 INFO - Running setup.py install for requests 10:47:32 INFO - Running setup.py install for docopt 10:47:32 INFO - Successfully installed blobuploader requests docopt 10:47:32 INFO - Cleaning up... 10:47:32 INFO - Return code: 0 10:47:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:47:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:47:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:47:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:47:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:47:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:33 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 0x1064bcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064bf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc43246ef60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061cdd30>, '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 0x106180db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061c1be8>, '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.EC0IstH5Ce/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.ci5g6J4IJI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 10:47:33 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 10:47:33 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 10:47:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EC0IstH5Ce/Render', 10:47:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:47:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:47:33 INFO - 'HOME': '/Users/cltbld', 10:47:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:47:33 INFO - 'LOGNAME': 'cltbld', 10:47:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:33 INFO - 'MOZ_NO_REMOTE': '1', 10:47:33 INFO - 'NO_EM_RESTART': '1', 10:47:33 INFO - 'PAGER': '/bin/cat', 10:47:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:47:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:33 INFO - 'PWD': '/builds/slave/test', 10:47:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:47:33 INFO - 'SHELL': '/bin/bash', 10:47:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ci5g6J4IJI/Listeners', 10:47:33 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 10:47:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:47:33 INFO - 'USER': 'cltbld', 10:47:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:47:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:47:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:33 INFO - 'XPC_FLAGS': '0x0', 10:47:33 INFO - 'XPC_SERVICE_NAME': '0', 10:47:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:47:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:47:33 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-chEZe3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:47:33 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-yUYcy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:47:33 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-ZHzVjr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:47:33 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-AshQqM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:47:34 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-uwpPOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:47:34 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-xHwYUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:47:34 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-WCHQSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:47:34 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-9mKL17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:47:34 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-RGpv1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:47:34 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-kwGM5E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:47:34 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-jum2o8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:47:34 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-2WmRmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:47:34 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-c47lqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:47:34 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-l_pASH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:47:35 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-K99DAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:47:35 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-T_mGak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:47:35 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-8ONjsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:47:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:47:35 INFO - Running setup.py install for manifestparser 10:47:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:47:35 INFO - Running setup.py install for mozcrash 10:47:35 INFO - Running setup.py install for mozdebug 10:47:35 INFO - Running setup.py install for mozdevice 10:47:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:47:35 INFO - Running setup.py install for mozfile 10:47:36 INFO - Running setup.py install for mozhttpd 10:47:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:47:36 INFO - Running setup.py install for mozinfo 10:47:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:47:36 INFO - Running setup.py install for mozInstall 10:47:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:47:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:47:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:47:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:47:36 INFO - Running setup.py install for mozleak 10:47:36 INFO - Running setup.py install for mozlog 10:47:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:47:36 INFO - Running setup.py install for moznetwork 10:47:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:47:37 INFO - Running setup.py install for mozprocess 10:47:37 INFO - Running setup.py install for mozprofile 10:47:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:47:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:47:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:47:37 INFO - Running setup.py install for mozrunner 10:47:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:47:37 INFO - Running setup.py install for mozscreenshot 10:47:37 INFO - Running setup.py install for moztest 10:47:37 INFO - Running setup.py install for mozversion 10:47:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:47:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:47:37 INFO - Cleaning up... 10:47:37 INFO - Return code: 0 10:47:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:47:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:47:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:47:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:47:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:47:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:37 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 0x1064bcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064bf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc43246ef60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061cdd30>, '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 0x106180db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061c1be8>, '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.EC0IstH5Ce/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.ci5g6J4IJI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 10:47:37 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 10:47:37 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 10:47:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EC0IstH5Ce/Render', 10:47:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:47:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:47:37 INFO - 'HOME': '/Users/cltbld', 10:47:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:47:37 INFO - 'LOGNAME': 'cltbld', 10:47:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:37 INFO - 'MOZ_NO_REMOTE': '1', 10:47:37 INFO - 'NO_EM_RESTART': '1', 10:47:37 INFO - 'PAGER': '/bin/cat', 10:47:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:47:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:37 INFO - 'PWD': '/builds/slave/test', 10:47:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:47:37 INFO - 'SHELL': '/bin/bash', 10:47:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ci5g6J4IJI/Listeners', 10:47:37 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 10:47:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:47:37 INFO - 'USER': 'cltbld', 10:47:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:47:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:47:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:37 INFO - 'XPC_FLAGS': '0x0', 10:47:37 INFO - 'XPC_SERVICE_NAME': '0', 10:47:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:47:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:47:38 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-NrXd_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:47:38 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)) 10:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:47:38 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-67dlwX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:47:38 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)) 10:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:47:38 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-SgNTkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:47:38 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)) 10:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:47:38 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-c5_WBh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:47:38 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)) 10:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:47:38 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-gSnhaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:47:38 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)) 10:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:47:38 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-djqnTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:47:38 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)) 10:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:47:38 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-wP962v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:47:38 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)) 10:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:47:38 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-5y_rXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:47:39 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)) 10:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:47:39 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-7ugTd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:47:39 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)) 10:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:47:39 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-6zNbPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:47:39 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)) 10:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:47:39 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-T91tll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:47:39 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)) 10:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:47:39 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-tqbVNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:47:39 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)) 10:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:47:39 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-2yBfjz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:47:39 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)) 10:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:47:39 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-DzbLlu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:47:39 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)) 10:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:47:39 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-ttNKh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:47:39 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)) 10:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:47:39 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-bAWyux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:47:39 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)) 10:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:47:39 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-wRq7Ls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:47:40 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)) 10:47:40 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)) 10:47:40 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)) 10:47:40 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)) 10:47:40 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)) 10:47:40 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)) 10:47:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:47: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 10:47: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 10:47: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 10:47: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 10:47: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 10:47: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 10:50:12 INFO - Downloading blessings-1.6.tar.gz 10:50:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:50:12 INFO - Installing collected packages: blessings 10:50:12 INFO - Running setup.py install for blessings 10:50:12 INFO - Successfully installed blessings 10:50:12 INFO - Cleaning up... 10:50:12 INFO - Return code: 0 10:50:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:50:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:50:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:50:13 INFO - Reading from file tmpfile_stdout 10:50:13 INFO - Current package versions: 10:50:13 INFO - blessings == 1.6 10:50:13 INFO - blobuploader == 1.2.4 10:50:13 INFO - docopt == 0.6.1 10:50:13 INFO - functools32 == 3.2.3-2 10:50:13 INFO - jsonschema == 2.5.1 10:50:13 INFO - manifestparser == 1.1 10:50:13 INFO - mozInstall == 1.12 10:50:13 INFO - mozcrash == 1.0 10:50:13 INFO - mozdebug == 0.1 10:50:13 INFO - mozdevice == 0.50 10:50:13 INFO - mozfile == 1.2 10:50:13 INFO - mozhttpd == 0.7 10:50:13 INFO - mozinfo == 0.9 10:50:13 INFO - mozleak == 0.1 10:50:13 INFO - mozlog == 3.4 10:50:13 INFO - moznetwork == 0.27 10:50:13 INFO - mozprocess == 0.25 10:50:13 INFO - mozprofile == 0.28 10:50:13 INFO - mozrunner == 6.13 10:50:13 INFO - mozscreenshot == 0.1 10:50:13 INFO - mozsystemmonitor == 0.3 10:50:13 INFO - moztest == 0.8 10:50:13 INFO - mozversion == 1.4 10:50:13 INFO - psutil == 3.1.1 10:50:13 INFO - requests == 1.2.3 10:50:13 INFO - wsgiref == 0.1.2 10:50:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:50:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:50:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:50:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:14 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 0x1064bcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064bf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc43246ef60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061cdd30>, '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 0x106180db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061c1be8>, '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.EC0IstH5Ce/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.ci5g6J4IJI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 10:50:14 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 10:50:14 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 10:50:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EC0IstH5Ce/Render', 10:50:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:14 INFO - 'HOME': '/Users/cltbld', 10:50:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:14 INFO - 'LOGNAME': 'cltbld', 10:50:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:14 INFO - 'MOZ_NO_REMOTE': '1', 10:50:14 INFO - 'NO_EM_RESTART': '1', 10:50:14 INFO - 'PAGER': '/bin/cat', 10:50:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:14 INFO - 'PWD': '/builds/slave/test', 10:50:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:14 INFO - 'SHELL': '/bin/bash', 10:50:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ci5g6J4IJI/Listeners', 10:50:14 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 10:50:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:14 INFO - 'USER': 'cltbld', 10:50:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:14 INFO - 'XPC_FLAGS': '0x0', 10:50:14 INFO - 'XPC_SERVICE_NAME': '0', 10:50:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:14 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)) 10:50:14 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)) 10:50:14 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)) 10:50:14 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)) 10:50:14 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)) 10:50:14 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)) 10:50:14 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)) 10:50:14 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)) 10:50:14 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)) 10:50:14 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)) 10:50:14 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)) 10:50:14 INFO - Cleaning up... 10:50:14 INFO - Return code: 0 10:50:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:50:14 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')]} 10:50:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:14 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')]} 10:50:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:14 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 0x1064bcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064bf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc43246ef60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061cdd30>, '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 0x106180db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061c1be8>, '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.EC0IstH5Ce/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.ci5g6J4IJI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 10:50:14 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 10:50:14 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 10:50:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EC0IstH5Ce/Render', 10:50:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:14 INFO - 'HOME': '/Users/cltbld', 10:50:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:14 INFO - 'LOGNAME': 'cltbld', 10:50:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:14 INFO - 'MOZ_NO_REMOTE': '1', 10:50:14 INFO - 'NO_EM_RESTART': '1', 10:50:14 INFO - 'PAGER': '/bin/cat', 10:50:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:14 INFO - 'PWD': '/builds/slave/test', 10:50:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:14 INFO - 'SHELL': '/bin/bash', 10:50:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ci5g6J4IJI/Listeners', 10:50:14 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 10:50:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:14 INFO - 'USER': 'cltbld', 10:50:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:14 INFO - 'XPC_FLAGS': '0x0', 10:50:14 INFO - 'XPC_SERVICE_NAME': '0', 10:50:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:50:14 INFO - Cleaning up... 10:50:14 INFO - Return code: 0 10:50:14 INFO - Running post-action listener: _resource_record_post_action 10:50:14 INFO - Running post-action listener: _start_resource_monitoring 10:50:14 INFO - Starting resource monitoring. 10:50:14 INFO - [mozharness: 2017-05-11 17:50:14.676924Z] Finished create-virtualenv step (success) 10:50:14 INFO - [mozharness: 2017-05-11 17:50:14.677879Z] Running install step. 10:50:14 INFO - Running pre-action listener: _resource_record_pre_action 10:50:14 INFO - Running main action method: install 10:50:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:50:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:50:14 INFO - Reading from file tmpfile_stdout 10:50:14 INFO - Detecting whether we're running mozinstall >=1.0... 10:50:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:50:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:50:15 INFO - Reading from file tmpfile_stdout 10:50:15 INFO - Output received: 10:50:15 INFO - Usage: mozinstall [options] installer 10:50:15 INFO - Options: 10:50:15 INFO - -h, --help show this help message and exit 10:50:15 INFO - -d DEST, --destination=DEST 10:50:15 INFO - Directory to install application into. [default: 10:50:15 INFO - "/builds/slave/test"] 10:50:15 INFO - --app=APP Application being installed. [default: firefox] 10:50:15 INFO - mkdir: /builds/slave/test/build/application 10:50:15 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'] 10:50:15 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 10:50:35 INFO - Reading from file tmpfile_stdout 10:50:35 INFO - Output received: 10:50:35 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:50:35 INFO - Running post-action listener: _resource_record_post_action 10:50:35 INFO - [mozharness: 2017-05-11 17:50:35.755243Z] Finished install step (success) 10:50:35 INFO - [mozharness: 2017-05-11 17:50:35.755381Z] Running run-tests step. 10:50:35 INFO - Running pre-action listener: _resource_record_pre_action 10:50:35 INFO - Running main action method: run_tests 10:50:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:50:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:50:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:50:35 INFO - 2017-05-11 10:50:35.769 screenresolution[1771:8883] starting screenresolution argv=screenresolution get 10:50:35 INFO - 2017-05-11 10:50:35.772 screenresolution[1771:8883] Display 0: 1600x1200x32@60 10:50:35 INFO - 2017-05-11 10:50:35.778 screenresolution[1772:8885] starting screenresolution argv=screenresolution list 10:50:35 INFO - Available Modes on Display 0 10:50:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:50:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:50:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:50:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:50:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:50:36 INFO - Intel Iris: 10:50:36 INFO - Chipset Model: Intel Iris 10:50:36 INFO - Type: GPU 10:50:36 INFO - Bus: Built-In 10:50:36 INFO - VRAM (Dynamic, Max): 1536 MB 10:50:36 INFO - Vendor: Intel (0x8086) 10:50:36 INFO - Device ID: 0x0a2e 10:50:36 INFO - Revision ID: 0x0009 10:50:36 INFO - Displays: 10:50:36 INFO - Display: 10:50:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:50:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:50:36 INFO - Display Serial Number: 1600x1200 60 10:50:36 INFO - Main Display: Yes 10:50:36 INFO - Mirror: Off 10:50:36 INFO - Online: Yes 10:50:36 INFO - Rotation: Supported 10:50:36 INFO - Return code: 0 10:50:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:50:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:50:36 INFO - Python 2.7.3 10:50:36 INFO - Return code: 0 10:50:36 INFO - grabbing minidump binary from tooltool 10:50: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')]} 10:50:36 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 0x1061cdd30>, '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 0x106180db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061c1be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:50:36 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 10:50:36 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 10:50:36 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 10:50:36 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:50:36 INFO - Return code: 0 10:50:36 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:50:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:50:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:50:36 INFO - ENV: RUST_BACKTRACE is now 1 10:50:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:50:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:50:36 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/AcrmHGpHQDmj8gAyLuoYOg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0314', '--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 10:50:36 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/AcrmHGpHQDmj8gAyLuoYOg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0314 --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 10:50:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EC0IstH5Ce/Render', 10:50:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:36 INFO - 'HOME': '/Users/cltbld', 10:50:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:36 INFO - 'LOGNAME': 'cltbld', 10:50:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:50:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:50:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:36 INFO - 'MOZ_NO_REMOTE': '1', 10:50:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:50:36 INFO - 'NO_EM_RESTART': '1', 10:50:36 INFO - 'PAGER': '/bin/cat', 10:50:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:36 INFO - 'PWD': '/builds/slave/test', 10:50:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:50:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:36 INFO - 'RUST_BACKTRACE': '1', 10:50:36 INFO - 'SHELL': '/bin/bash', 10:50:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ci5g6J4IJI/Listeners', 10:50:36 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 10:50:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:36 INFO - 'USER': 'cltbld', 10:50:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:36 INFO - 'XPC_FLAGS': '0x0', 10:50:36 INFO - 'XPC_SERVICE_NAME': '0', 10:50:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:36 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/AcrmHGpHQDmj8gAyLuoYOg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0314', '--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 10:50:36 INFO - mozversion application_buildid: 20170511081456 10:50:36 INFO - mozversion application_changeset: 3b96f277325747fe668ca8cd896d2f581238e4ee 10:50:36 INFO - mozversion application_display_name: Nightly 10:50:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:50:36 INFO - mozversion application_name: Firefox 10:50:36 INFO - mozversion application_remotingname: firefox 10:50:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:50:36 INFO - mozversion application_vendor: Mozilla 10:50:36 INFO - mozversion application_version: 55.0a1 10:50:36 INFO - mozversion platform_buildid: 20170511081456 10:50:36 INFO - mozversion platform_changeset: 3b96f277325747fe668ca8cd896d2f581238e4ee 10:50:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:50:36 INFO - mozversion platform_version: 55.0a1 10:50:36 INFO - using testdate: 1494525036 10:50:36 INFO - actual date: 1494525036 10:50:36 INFO - starting webserver on 'localhost:49252' 10:50:36 INFO - SUITE-START | Running 2 tests 10:50:36 INFO - TEST-START | dromaeo_css 10:50:36 INFO - operating with platform_type : mac_ 10:50:36 INFO - Initialising browser for dromaeo_css test... 10:50:36 INFO - TEST-INFO | started process 1782 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpFWKVvj/profile http://localhost:49252/getInfo.html) 10:50:39 INFO - PID 1782 | 2017-05-11 10:50:39.250 plugin-container[1785:9079] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a3f, name = 'com.apple.tsm.portname' 10:50:39 INFO - PID 1782 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:50:39 INFO - PID 1782 | 2017-05-11 10:50:39.253 plugin-container[1785:9079] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a17, name = 'com.apple.CFPasteboardClient' 10:50:39 INFO - PID 1782 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:50:39 INFO - PID 1782 | 2017-05-11 10:50:39.253 plugin-container[1785:9079] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:50:39 INFO - PID 1782 | __metrics Screen width/height:1600/1200 10:50:39 INFO - PID 1782 | colorDepth:24 10:50:39 INFO - PID 1782 | Browser inner width/height: 1024/691 10:50:39 INFO - PID 1782 | __metrics 10:50:39 INFO - PID 1782 | 2017-05-11 10:50:39.786 plugin-container[1786:9129] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.tsm.portname' 10:50:39 INFO - PID 1782 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:50:39 INFO - PID 1782 | 2017-05-11 10:50:39.786 plugin-container[1786:9129] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4723, name = 'com.apple.CFPasteboardClient' 10:50:39 INFO - PID 1782 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:50:39 INFO - PID 1782 | 2017-05-11 10:50:39.786 plugin-container[1786:9129] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:50:40 INFO - TEST-INFO | 1782: exit 0 10:50:40 INFO - Browser initialized. 10:50:40 INFO - Running cycle 1/1 for dromaeo_css test... 10:50:40 INFO - TEST-INFO | started process 1787 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpFWKVvj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:50:42 INFO - PID 1787 | 2017-05-11 10:50:42.603 plugin-container[1788:9237] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x954b, name = 'com.apple.tsm.portname' 10:50:42 INFO - PID 1787 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:50:42 INFO - PID 1787 | 2017-05-11 10:50:42.603 plugin-container[1788:9237] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441b, name = 'com.apple.CFPasteboardClient' 10:50:42 INFO - PID 1787 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:50:42 INFO - PID 1787 | 2017-05-11 10:50:42.603 plugin-container[1788:9237] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:50:43 INFO - PID 1787 | 2017-05-11 10:50:43.101 plugin-container[1789:9279] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 10:50:43 INFO - PID 1787 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:50:43 INFO - PID 1787 | 2017-05-11 10:50:43.101 plugin-container[1789:9279] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4817, name = 'com.apple.CFPasteboardClient' 10:50:43 INFO - PID 1787 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:50:43 INFO - PID 1787 | 2017-05-11 10:50:43.101 plugin-container[1789:9279] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:50:52 INFO - PID 1787 | RSS: Main: 227586048 10:50:52 INFO - PID 1787 | 10:53:55 INFO - PID 1787 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49252/tests/dromaeo/cssquery-ext.html) 10:53:57 INFO - PID 1787 | RSS: Main: 251842560 10:53:57 INFO - PID 1787 | 10:56:59 INFO - PID 1787 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-ext.html (next: http://localhost:49252/tests/dromaeo/cssquery-jquery.html) 10:57:00 INFO - PID 1787 | RSS: Main: 258486272 10:57:00 INFO - PID 1787 | 11:00:01 INFO - PID 1787 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49252/tests/dromaeo/cssquery-mootools.html) 11:00:02 INFO - PID 1787 | RSS: Main: 268603392 11:00:02 INFO - PID 1787 | 11:03:04 INFO - PID 1787 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49252/tests/dromaeo/cssquery-prototype.html) 11:03:04 INFO - PID 1787 | RSS: Main: 262144000 11:03:04 INFO - PID 1787 | 11:06:07 INFO - PID 1787 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49252/tests/dromaeo/cssquery-yui.html) 11:06:08 INFO - PID 1787 | RSS: Main: 266633216 11:06:08 INFO - PID 1787 | 11:09:09 INFO - PID 1787 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-yui.html (next: http://localhost:49252/tests/dromaeo/cssquery-dojo.html) 11:09:10 INFO - PID 1787 | RSS: Main: 265474048 11:09:10 INFO - PID 1787 | 11:09:10 INFO - PID 1787 | __start_tp_report 11:09:10 INFO - PID 1787 | _x_x_mozilla_page_load 11:09:10 INFO - PID 1787 | _x_x_mozilla_page_load_details 11:09:10 INFO - PID 1787 | |i|pagename|runs| 11:09:10 INFO - PID 1787 | |0;dojo.html;3588.38997370447;3610.656987586179;3629.8203083997705;3650.0874781304674;3662.212624285778;4968.701877887329;5460.180972854074;6150.169727086842;6213.782517611885;6246.250449946007;6206.441420272175;6227.626342419458;6241.282252540959;6270.5924114932495;6316.778912738055;4590.586847183753;4782.33047373368;5440.863978400542;5495.203195536645;5532.197831314477;5542.91685624716;5605.159226116095;5633.408492108331;5652.152177173436;5664.518515926151;5406.59450541207;5494.148406996922;5567.069889794526;5609.074502707049;5625.212470254168;5457.045017122027;5468.781248750085;5488.4785945335125;5493.478119578634;5494.340679118532;5459.962607104638;5475.288212532385;5532.474414930575;5580.358258800249;5608.523275521545;4855.684380515255;6069.15031895535;6216.720247588869;6275.968620156916;6288.402601752826;6710.731570737214;6767.323267673242;6778.762743303961;6815;6819.693113809891;6616.437602803768;6733.595984240961;6756.493263005245;6867.553609015448;6887.380135787753;6317.336679648617;6364.745410183634;6365.522585806083;6377.553571250014;6381.3937675920715;7019.649017549102;7024.086868707138;7029.507934444642;7031.40233080194;7037.788866333915;5238.345206849144;6366.554341196072;6538.280789113193;6622.403983641495;6652.600843949379;6734.528582999143;6830;6843.486738494633;6850.38346548813;6853.931460685429;6664.600123992576;6692.196936367667;6705.195376554844;6732.764353247613;6777.186737591521;6464.41820236181;6620.205575330991;6701.664916754143;6727.730890764413;6776.15298087739;5381.354237491526;5387.75755091014;5393.083175860113;5414.0254754144635;5442.183672449164;4742.4309082909585;4817.253325734517;4824.662273640812;4830.082284365959;4841.661083724176;5339.252504649352;5644.322681278273;6293.622382656964;6331;6360.236771587439;6774.254831968479;6778.288279730656;6817.045613614098;6849.212340580797;6868.7252509900045;5401.189821526724;6288.591241569284;6334.144890439824;6361.427471527585;6365.904511432333;6024.548158888101;6052.455279024821;6062.9090563641585;6068.9696551517845;6074.483668888106;5767.625104368202;6709.7651582194385;6867.553609015597;6983.127109111361;6991.9300806993315;5583.74873130702;6824.112865327475;6877.277885821916;6913;6926.757563485254;5724.398938111605;6720.495962802712;6833.93166068333;7020.087388639444;7028.226895041439;6187.504999600112;6643.036759669917;6788.049673045876;6788.59268443895;6792.388685018372;6553.4101930824545;6681.933180668131;6803.727850886107;6894.620795856276;6951.582905025714;6513.576617519846;6546.901796473152;6563.967180164069;6652.600843949379;6681.83295417599;6650.3682150195655;6651.933480665131;6659.700313485808;6678.599284042973;6689.197296324278;6205.100260462117;6322.146510221245;6451.741930322923;6510.609363438209;6578.342165783383;6127.234095738033;6648;6651.833704157435;6659.833504162435;6688.7324507019175;6399.456046235936;6477.255115661571;6494.805155845333;6502.739890404331;6513.185851768529 11:09:10 INFO - PID 1787 | |1;ext.html;694.4930200953304;799.0251892690915;1086.3373342261225;1187.7208855918857;1293.3468598357829;29694.554581681266;36684.266314673725;37304.69433569826;37315.44026839596;37357.06607334818;36777;37150;37150.628493715056;37151.071223219435;37459.438108428396;7805.5706936118495;8199.42604017719;8404.41169118162;8424.915750842474;8431.578421078952;8377.162283771635;8481.194286542768;8482.36382271329;8488.575571221445;8514.872276915852;8433.493990360568;8473.983122025335;8496.320294376466;8503.914960850405;8506.659733610648;8447.57762111895;8481.830363392759;8485.915140848636;8488.066312705598;8511.233988941025;8181.427300088937;8244.258016778518;8252.504849709037;8255.917440825575;8257.21556452136;36177.27645447076;36250.456243156375;36378.45432318517;36498.27003459916;36618;3090.4128215638957;3540.327337805809;3555.466679998021;3575.5173051637958;3582.408902531079;30036;30105.849470752724;30197.0940871772;30272.09183724492;30304;2662.6804783426114;3121.843907804601;3205.3108581655056;3208.8235147066903;3214.7267482264037;296841;298463;298833;298847;299123;45176;47785.5221447788;47880.52119478761;47963.76018119887;48175.5182448178;40880.79559602183;48884;49485.50514494809;49705;52397.7380113097;14159.938004649692;14229.217393043482;14283;14295.428182872776;14345.856541434661;11385.259958102697;11446.027087697472;11451;11469.31184128955;11487.655370338904;13585.456581736818;13614.523491677744;13625;13636.386362613706;13695.246761428218;5985.491233245136;5997.520198384119;6000.519958403317;6004.219451471281;6010.939890601038;981.16600889245;1001.484235618657;1008.1430783833829;1036.4713995862164;1052.2897044494935;24259.878700606383;24306.513869722505;24479.87760061224;24496.510069798504;24513.50972980566;1104.2877344113;1107.883672214422;1141.9371934543515;1146.507001989136;1146.541383446628;10911.79970203277;11855.58505452305;11951.16341856079;12471.812922806164;12521.93739031299;283405;341004;341314;341409;341508;50512.24231636455;53247;53448;53487.46512534825;53597.73201133969;1439.805626240444;1485.5394827603477;1513.8410466901037;1523.131814865516;1544.382247101146;19161.712574311117;24194.879025604758;24259.757402426454;24321.270361889172;24400;37744.24511509809;48810.755946220044;49900.25149622831;50590.24114638357;50753;230873;281248;282729;283262;283446;35981;36192.457113142795;36341;36406.08984775402;36438.27123457463;43436.348454772575;44019.33970990502;45300.77349613231;45474.772626136655;45586.316205256284;30177.547336790405;30871.536926945664;31351.05946821599;31505.52741708922;31759.206019849687;30645.712502662936;31259;31374;31631.20921976969;31640.36719265648;37471.62528374681;37473.6252637481;37546.2490750178;37678.434823478216;37709.434358485196;30012.69987300099;31027.844860775553;31238.90663826846;31829;32196.35607287794;24283.392915176555;24471.021159153435;24525;24677.136300229406;24780.876095619406 11:09:10 INFO - PID 1787 | |2;jquery.html;389.7564022485946;397.78320815155735;399.79210810378623;416.9854055108071;429.6047636174719;16891.915540422295;17171.313147474102;21787.23744668939;21992.670109948325;22526.54946901061;20893.791062089374;22008.899555022224;22910.312690619267;23366.299011029652;23501.177458788945;5596.468335508121;5902.085176797582;5915.792947246847;6186.164867742842;6190.102435146901;5868.9413105869035;5904.55715821313;6038.939610603904;6091.055886337603;6134.96932515339;6091.177691011701;6096.329403765582;6098.908516372258;6103.115048317991;6103.481204097635;5783.132530120494;6008.909866352008;6056.152138700585;6063.484603808682;6079.483243924272;5391.703456309901;5411.431799661058;5417.810376636839;5418.241446197536;5419.376771671229;21855.890720546297;26738.331541711417;26749.930002800058;27548.035818746448;27746.306342341402;2133.797289257518;2166.0144634191406;2166.42589713726;2168.4253672776726;2169.9240526581493;17253.482395528154;17879.821201788076;18117.1847266872;18158.90920545402;18401.723974140397;1940.0299850074962;2038.4801875521796;2071.2854065347433;2133.2746866065613;2136.102836808544;176874;177199;179118;182128;184901.99999999866;12115.697107572205;12703.30131842739;12721.55474558386;12745.872541274653;12752.93623531895;16395.344186232418;16455.930364526266;16490.422835200712;16522.17389130563;16545.090020049014;8726.86909696355;8862.601182946783;9122.680706175252;9158.313126515539;9193.954030229806;7413.147488038944;7571.886421703679;8669;8684.218420342073;8816.735497935073;7811.218878112143;7885.014373203349;7931.920680793118;7957.6021198939825;7978.6409611567615;2153.876779138026;2180.5529866377356;2185.7158569386197;2188.9890550547466;2381.404648837791;946.4888959961681;952.7808604021114;954.174638937328;971.0774763974252;994.4331730913455;18236;19901.402957911287;20412.183512659623;20428.59142817165;20447.977601119885;949.586929685589;988.5057471264367;996.0736515041082;1005.8591797148405;1025.4718819807806;8732.126787321216;9627.759306017406;10560.947195264129;10597.470126493645;10627.9903409176;529000;529670.351648247;573874.1306293525;588136;591029;118166.4091679536;120770;121642;130016.69983300046;130669;1138.2715062360096;1162.3839365136491;1177.12892459581;1178.88800563948;1187.5071845184152;15297.082175069532;15875.206239688201;17778;17880;18138.818611813713;54811.72594137004;55060;55327.61680958008;56940.71529642325;57406.71296643657;76715.46569068475;78124.21875781397;78129.96830285301;79357.60321198357;79815;6801.795946121624;7038.964805175941;8463.111373305714;8474.576271186294;8635.222829945335;12715.682107947376;12879.806802897776;13157.736845263233;13189.670258243621;13257.66855828612;9613.807723845344;11228.438578071227;11413.258138220988;11473.82789258145;11541.826872597087;10664.89335106639;10960.232783705063;10964.232503724981;11244.887551124712;11287.943560282147;11693.590724324607;11794.05647548177;11957;11975.203719442154;11984.161108722306;8493.32053435736;8665.783355416164;8758.824823503366;8857.689980850639;8942.418742781958;9169.449833009774;11993;12067.155299129328;12073.215241009657;12163.331016794162 11:09:10 INFO - PID 1787 | |3;mootools.html;1399.524161784993;1430.5994321589951;1449.688032330741;1452.520668179501;1455.5124033448794;8746.431481953676;11262.662120136409;11368.658940231797;11402.54389824407;11434.313941163537;11140.164487663416;11258.605948791794;11331.773364532724;11355.545778168864;11378.601748938789;2474.8515089094667;2518.622206668996;2521.155412936659;2527.5829488134436;2529.1527338341584;2449.620308852122;2503.3366157968153;2506.6338845329246;2512.309114993373;2517.0309430869156;2465.4822487277693;2475.0471068638612;2505.473850491399;2516.924492265226;2516.924492265235;1984.560102467495;2140.9616336076992;2288.336382449088;2435.6224785158242;2494.289127598626;2019.838412926977;2408.1330720940446;2479.0207867892263;2506.4611108611552;2515.0819950718037;9440.858387124199;11249.43752812356;11387;11397.658070257905;11408.315501069963;6471.0940468334475;6662.800695874741;6695.46436285096;6703.497237707192;6714.899276510856;3962.6037396260144;4282.978585107086;4328.7835608219775;4362.323839804803;4432.800523976429;1050.817829941316;1112.9387883666395;1188.1979663727036;1258.2764910176593;1261.426051146726;15269.236538173047;15860.841391586051;15887.205639717968;15898.966567173096;16133.515994520183;4754.881127971828;5425.186222066643;5592.133219351007;5616.073347897551;5642.504736244899;11254;11357.54569817198;11360.318380897173;11386.658400248005;11506.654800356002;6446.419822216023;6478.2873883872735;6479.416852483299;6483.805485835433;6484.448821850196;5971.492423144082;6024.849378765566;6091.817245482643;6241.126242326078;6241.282252540925;3898.7718868556312;4061.634452899253;4168.124693814272;4193.322670931651;4199.727017743898;1134.2910680824484;1242.4967888005372;1254.9811752823714;1259.0619988108917;1263.7851565233932;783.9608019598998;793.8885560215745;810.2586133687728;816.9950960318841;839.0686338164633;6128.203333566607;6179.227596550431;6324;6372.330905254974;6404.743810247538;583.1107560969556;598.655772930558;604.2899592978233;605.449041372349;619.2445216835454;3916.725829191986;4099.61053699898;4292.313229883203;4355.259605867011;4480.462344518679;14194.07738497015;14337.06809057408;14608.926955365156;14658;14679.266036698336;14237.857621423653;14312.785308220386;14777.113373197642;14786.556403307917;14794.630134246514;7426.183119856812;8495.447795893248;8606.096359882122;8616.224539791448;8621.956890215508;6186.845328866634;6310.706305207514;6561.212654481493;7036.190838053484;7065.646717664199;12010.039196864054;12080.818787718015;12176.269423834598;12261.816072759095;12299.815502767644;12319.63041108768;12470.875291246972;13039.804402933774;13060.346982650639;13299.20204787716;7993.920060799318;8111.229433203836;8112.310453611273;8114.148014458633;8127;10112.119328775638;10368.844467333147;10440.216983726099;10472.633457828939;10563.841542377024;8980.967188773375;9000.954995224982;9040.734297198529;9136.908630913871;9167.44995300284;8798.868016979879;8828.337874659555;8853.291736660924;8903.887014123235;9052.3210759192;8092.797680058045;8229.094799572162;8259.917400825914;8267.255946964802;8267.751967440745;4962.354893863919;5953.28560572734;5968.552358573212;6117.051856962178;6152.661603611844;5759.971200143972;5998.550108741774;6072.757089716361;6093.878122437615;6122.418370254819 11:09:10 INFO - PID 1787 | |4;prototype.html;323.6553070979407;337.59488613663603;339.66882289767466;378.7878787878788;412.4967539601687;6307.526935479835;6404.967975160123;6497.6101433914;6537.346265373454;6556.442702370298;5933.732982015809;6211.254649442062;6281.717322720478;6320.431161195492;6365.681715914197;4598.494165641785;4620.376249206364;4628.259478483444;4643.767811609423;4661.743604101773;3883.922321553567;3987.501562304712;4012.896453475303;4441.489228738688;4635.698679585833;4118.546959834416;4548.272276435771;4550.20266994537;4559.974005848674;4572.748498832563;3832.118612719062;4003.459532963071;4044.4944381952255;4198.475190601175;4529.252673308916;3853.5761066282685;3869.671077958377;3920.137569734654;3926.469926559935;3948.861789837342;6145.664647433947;6150.415710507495;6155.569110162291;6155.969220153916;6185.56701030928;5416.864578385533;5425.294711687456;5427.267318911936;5432.75552600134;5433.8369848904595;5509.421510741395;6301.590396624205;6499.610023398611;6740.966295168493;6855.280195579442;3516.894493165209;3859.208862183246;4432.84485043022;4463.509014008457;4463.776811159429;6995.230524642286;6999.930000699978;7016.193137789169;7156.4632652550745;7171.211166771651;3809.6190380961684;4493.393391892118;4499.212637788374;4502.054568540643;4527.12124468342;3806.923861522754;4569.451665800125;4573.519780423074;4585.105904348687;4586.990862010352;5277.630565860353;5387.703676297763;5466.824632703988;5479.315085614298;5509.421510741395;6703.66481675924;6782.728690852409;6789.456843452512;6799.014142949245;6802.891103757851;1357.1314358810369;1454.8763355114722;1540.8228053773735;1567.2242240091227;1569.8351673074392;998.7103739915489;1015.786684796201;1016.2682868334787;1018.7198520406947;1020.2908978260186;5964.433378829004;6369.172007638977;6382.234131904202;6409.519286053564;6460.773872914426;5370.4361042090795;6729.226138994079;7068.964655176794;7070.823229419306;7176.425885929113;6335.3981371769605;6365.968170159213;6377.246257279286;6381.298057213703;6396.776112836028;3592.892213233607;3605.6574625410544;3650.470681751184;3686.967649058268;3696.685781708531;6803.863922721518;7099.609521476265;7211.423086153095;7222.736021196375;7258.657148427558;6741.426978706865;6808.9659551701925;7192.130046188029;7199.8560028800175;7213.134423869169;7143.92856071443;7222.280525529462;7225;7234.852839015045;7292.161401438902;5835.3740816631835;6415;6705.362990515893;7030.191527974348;7074.292570742885;6751.628660423527;6871.553349032297;6940.583564986117;6979.232284448808;6986.170600906641;6619.3049729779605;6903.861922761617;6925.515213934977;6934.514583979073;7306.575217832472;4456.108778244299;4509.391232183744;4511.3458548510935;4523.886902827456;4533.954660453353;6230.43926046658;7096.609686467294;7122.501424900207;7136.217804563589;7136.286371362822;5562.694051827189;5709.571782116441;6683.080902285713;6730.32696730323;6778.661066946732;6790;6793.932060679527;6815.591064536144;6831.931680683129;6898.378086682035;6545.603468081038;6575.80272591823;6584.242812076673;6665.400113989765;6686.744881803103;5618.612089861874;5716.5426765859465;6755.831104222434;6836.829079273058;6882.311768823278;6515.641639709672;6561.1470508833545;6613.470922326106;6618.2719900811835;6631 11:09:10 INFO - PID 1787 | |5;yui.html;653.9705713242904;659.416416471423;728.482777228168;853.6969375871565;884.5179377239403;15797.921010394946;16895.915520422397;16918.154092295383;18520.907395463037;19105;17769.7334539982;18126.09369531525;18256.81743182568;18261.26954921805;18306.901585904856;970.403202030753;992.5087081894436;996.5714742660653;998.8102260570505;1000.9940010289685;980.4019548075668;984.6849008317341;990.0495524296684;990.3414229537349;992.5781542844298;1035.3529044347283;1052.794705032519;1053.489057806963;1055.7677310991608;1056.1656291529682;1025.5385076715434;1042.8279333909895;1043.0404028293988;1051.4059556350715;1052.7052425320921;707.9185893622197;767.1178145133085;767.3400875247282;770.568481650272;773.9922600773976;16848.663026739396;17166.570835729082;17177.65644687112;17373.21820518067;17400.91299543507;16985.150742462953;17261;17782.199801008857;17817.198226079858;17832.93002419859;14005.509807156803;14048.817400221988;14173.362198701083;14233.430662773477;14249.643758906006;3421.0763093964492;3491.930161396783;3492.738044646662;3494.405950988338;3496.335696217736;132235;132465;132550;135066;135519.32240338833;12632;13507.729845403037;13570.117942333716;13650.385732642055;13702.314884255748;22988.080476781077;24726.13458528943;25447.236582902544;26266;26280.73719262783;7678.5392876427595;7919.208079192034;7919.881201781978;7925;7934.444588878723;3804.201117765245;3862.4978752761963;3862.806859657006;3870.1098747288283;3880.010597297671;4513.142502924499;4672.345871577982;4708.576228139415;4727.929081063787;4740.905181896412;919.2416256588341;938.3009657804843;939.6852054561662;952.7903861150536;956.2827879090682;633.395107672172;653.5033374635311;663.4427081251675;664.5481072870494;683.7948615415355;11435.428228588704;12009.699757505958;12164.2701437914;12182.269063856196;12363.876361236273;552.5331095224528;605.9606125601822;632.3960617610171;636.3509220594967;659.4394764450179;5362.436944120889;5450.400455949843;5621.128725047624;5632.3804381518;5715.485606295453;84604.57697711595;91086;91662.54168729247;96604.55093173608;97175;89392;94184.52907735555;95418;95530;95688;64016.67991660012;82364;87420.56289718511;87880;89498.55250723705;10946.069584085124;11058.612948546763;11424.314541127558;11557.479913404086;11572.710682233012;31525;34390.48414273838;43170.568294316654;43623;43935;38686.419703703905;46158.30762538632;47696.046079078915;52790.47209527856;53020.46979530156;14086.22525761093;14150.575482735534;14452.710945781237;14528.636784080483;14681;15589.844101559293;15815.446459373867;15854.68290634204;15982.60043498922;16165.4342098026;14751.631209219426;14834.332455039334;14870.553883383516;14890.478833240784;15012.7748083781;13892.791608126088;14146.717065658835;14291.42834286617;14408.351624177147;14458.915581331232;14317.498887538886;14540;14626.488072917396;14782.48261310849;14898.255087245378;10650;10737.570497180337;10775.191860610328;10896.782064358827;11035.834462482908;11265.605703800327;11593.82609260845;11652.53389864396;11656.358900260568;11661.008814250883 11:09:10 INFO - PID 1787 | __end_tp_report 11:09:10 INFO - PID 1787 | __start_cc_report 11:09:10 INFO - PID 1787 | _x_x_mozilla_cycle_collect,410 11:09:10 INFO - PID 1787 | __end_cc_report 11:09:10 INFO - PID 1787 | __startTimestamp1494526150012__endTimestamp 11:09:10 INFO - PID 1787 | 11:09:10 INFO - PID 1787 | ------- Summary: start ------- 11:09:10 INFO - PID 1787 | Number of tests: 6 11:09:10 INFO - PID 1787 | 11:09:10 INFO - PID 1787 | [#0] dojo.html Cycles:165 Average:6172.64 Median:6377.55 stddev:758.20 (11.9%) stddev-sans-first:732.92 11:09:10 INFO - PID 1787 | Values: 3588.4 3610.7 3629.8 3650.1 3662.2 4968.7 5460.2 6150.2 6213.8 6246.3 6206.4 6227.6 6241.3 6270.6 6316.8 4590.6 4782.3 5440.9 5495.2 5532.2 5542.9 5605.2 5633.4 5652.2 5664.5 5406.6 5494.1 5567.1 5609.1 5625.2 5457.0 5468.8 5488.5 5493.5 5494.3 5460.0 5475.3 5532.5 5580.4 5608.5 4855.7 6069.2 6216.7 6276.0 6288.4 6710.7 6767.3 6778.8 6815.0 6819.7 6616.4 6733.6 6756.5 6867.6 6887.4 6317.3 6364.7 6365.5 6377.6 6381.4 7019.6 7024.1 7029.5 7031.4 7037.8 5238.3 6366.6 6538.3 6622.4 6652.6 6734.5 6830.0 6843.5 6850.4 6853.9 6664.6 6692.2 6705.2 6732.8 6777.2 6464.4 6620.2 6701.7 6727.7 6776.2 5381.4 5387.8 5393.1 5414.0 5442.2 4742.4 4817.3 4824.7 4830.1 4841.7 5339.3 5644.3 6293.6 6331.0 6360.2 6774.3 6778.3 6817.0 6849.2 6868.7 5401.2 6288.6 6334.1 6361.4 6365.9 6024.5 6052.5 6062.9 6069.0 6074.5 5767.6 6709.8 6867.6 6983.1 6991.9 5583.7 6824.1 6877.3 6913.0 6926.8 5724.4 6720.5 6833.9 7020.1 7028.2 6187.5 6643.0 6788.0 6788.6 6792.4 6553.4 6681.9 6803.7 6894.6 6951.6 6513.6 6546.9 6564.0 6652.6 6681.8 6650.4 6651.9 6659.7 6678.6 6689.2 6205.1 6322.1 6451.7 6510.6 6578.3 6127.2 6648.0 6651.8 6659.8 6688.7 6399.5 6477.3 6494.8 6502.7 6513.2 11:09:10 INFO - PID 1787 | 11:09:10 INFO - PID 1787 | [#1] ext.html Cycles:180 Average:45302.34 Median:341159.00 stddev:79112.79 (23.2%) stddev-sans-first:79263.82 11:09:10 INFO - PID 1787 | Values: 694.5 799.0 1086.3 1187.7 1293.3 29694.6 36684.3 37304.7 37315.4 37357.1 36777.0 37150.0 37150.6 37151.1 37459.4 7805.6 8199.4 8404.4 8424.9 8431.6 8377.2 8481.2 8482.4 8488.6 8514.9 8433.5 8474.0 8496.3 8503.9 8506.7 8447.6 8481.8 8485.9 8488.1 8511.2 8181.4 8244.3 8252.5 8255.9 8257.2 36177.3 36250.5 36378.5 36498.3 36618.0 3090.4 3540.3 3555.5 3575.5 3582.4 30036.0 30105.8 30197.1 30272.1 30304.0 2662.7 3121.8 3205.3 3208.8 3214.7 296841.0 298463.0 298833.0 298847.0 299123.0 45176.0 47785.5 47880.5 47963.8 48175.5 40880.8 48884.0 49485.5 49705.0 52397.7 14159.9 14229.2 14283.0 14295.4 14345.9 11385.3 11446.0 11451.0 11469.3 11487.7 13585.5 13614.5 13625.0 13636.4 13695.2 5985.5 5997.5 6000.5 6004.2 6010.9 981.2 1001.5 1008.1 1036.5 1052.3 24259.9 24306.5 24479.9 24496.5 24513.5 1104.3 1107.9 1141.9 1146.5 1146.5 10911.8 11855.6 11951.2 12471.8 12521.9 283405.0 341004.0 341314.0 341409.0 341508.0 50512.2 53247.0 53448.0 53487.5 53597.7 1439.8 1485.5 1513.8 1523.1 1544.4 19161.7 24194.9 24259.8 24321.3 24400.0 37744.2 48810.8 49900.3 50590.2 50753.0 230873.0 281248.0 282729.0 283262.0 283446.0 35981.0 36192.5 36341.0 36406.1 36438.3 43436.3 44019.3 45300.8 45474.8 45586.3 30177.5 30871.5 31351.1 31505.5 31759.2 30645.7 31259.0 31374.0 31631.2 31640.4 37471.6 37473.6 37546.2 37678.4 37709.4 30012.7 31027.8 31238.9 31829.0 32196.4 24283.4 24471.0 24525.0 24677.1 24780.9 11:09:10 INFO - PID 1787 | 11:09:10 INFO - PID 1787 | [#2] jquery.html Cycles:180 Average:36205.56 Median:23138.31 stddev:96205.30 (415.8%) stddev-sans-first:96437.60 11:09:10 INFO - PID 1787 | Values: 389.8 397.8 399.8 417.0 429.6 16891.9 17171.3 21787.2 21992.7 22526.5 20893.8 22008.9 22910.3 23366.3 23501.2 5596.5 5902.1 5915.8 6186.2 6190.1 5868.9 5904.6 6038.9 6091.1 6135.0 6091.2 6096.3 6098.9 6103.1 6103.5 5783.1 6008.9 6056.2 6063.5 6079.5 5391.7 5411.4 5417.8 5418.2 5419.4 21855.9 26738.3 26749.9 27548.0 27746.3 2133.8 2166.0 2166.4 2168.4 2169.9 17253.5 17879.8 18117.2 18158.9 18401.7 1940.0 2038.5 2071.3 2133.3 2136.1 176874.0 177199.0 179118.0 182128.0 184902.0 12115.7 12703.3 12721.6 12745.9 12752.9 16395.3 16455.9 16490.4 16522.2 16545.1 8726.9 8862.6 9122.7 9158.3 9194.0 7413.1 7571.9 8669.0 8684.2 8816.7 7811.2 7885.0 7931.9 7957.6 7978.6 2153.9 2180.6 2185.7 2189.0 2381.4 946.5 952.8 954.2 971.1 994.4 18236.0 19901.4 20412.2 20428.6 20448.0 949.6 988.5 996.1 1005.9 1025.5 8732.1 9627.8 10560.9 10597.5 10628.0 529000.0 529670.4 573874.1 588136.0 591029.0 118166.4 120770.0 121642.0 130016.7 130669.0 1138.3 1162.4 1177.1 1178.9 1187.5 15297.1 15875.2 17778.0 17880.0 18138.8 54811.7 55060.0 55327.6 56940.7 57406.7 76715.5 78124.2 78130.0 79357.6 79815.0 6801.8 7039.0 8463.1 8474.6 8635.2 12715.7 12879.8 13157.7 13189.7 13257.7 9613.8 11228.4 11413.3 11473.8 11541.8 10664.9 10960.2 10964.2 11244.9 11287.9 11693.6 11794.1 11957.0 11975.2 11984.2 8493.3 8665.8 8758.8 8857.7 8942.4 9169.4 11993.0 12067.2 12073.2 12163.3 11:09:10 INFO - PID 1787 | 11:09:10 INFO - PID 1787 | [#3] mootools.html Cycles:180 Average:6855.86 Median:4012.12 stddev:4287.95 (106.9%) stddev-sans-first:4280.38 11:09:10 INFO - PID 1787 | Values: 1399.5 1430.6 1449.7 1452.5 1455.5 8746.4 11262.7 11368.7 11402.5 11434.3 11140.2 11258.6 11331.8 11355.5 11378.6 2474.9 2518.6 2521.2 2527.6 2529.2 2449.6 2503.3 2506.6 2512.3 2517.0 2465.5 2475.0 2505.5 2516.9 2516.9 1984.6 2141.0 2288.3 2435.6 2494.3 2019.8 2408.1 2479.0 2506.5 2515.1 9440.9 11249.4 11387.0 11397.7 11408.3 6471.1 6662.8 6695.5 6703.5 6714.9 3962.6 4283.0 4328.8 4362.3 4432.8 1050.8 1112.9 1188.2 1258.3 1261.4 15269.2 15860.8 15887.2 15899.0 16133.5 4754.9 5425.2 5592.1 5616.1 5642.5 11254.0 11357.5 11360.3 11386.7 11506.7 6446.4 6478.3 6479.4 6483.8 6484.4 5971.5 6024.8 6091.8 6241.1 6241.3 3898.8 4061.6 4168.1 4193.3 4199.7 1134.3 1242.5 1255.0 1259.1 1263.8 784.0 793.9 810.3 817.0 839.1 6128.2 6179.2 6324.0 6372.3 6404.7 583.1 598.7 604.3 605.4 619.2 3916.7 4099.6 4292.3 4355.3 4480.5 14194.1 14337.1 14608.9 14658.0 14679.3 14237.9 14312.8 14777.1 14786.6 14794.6 7426.2 8495.4 8606.1 8616.2 8622.0 6186.8 6310.7 6561.2 7036.2 7065.6 12010.0 12080.8 12176.3 12261.8 12299.8 12319.6 12470.9 13039.8 13060.3 13299.2 7993.9 8111.2 8112.3 8114.1 8127.0 10112.1 10368.8 10440.2 10472.6 10563.8 8981.0 9001.0 9040.7 9136.9 9167.4 8798.9 8828.3 8853.3 8903.9 9052.3 8092.8 8229.1 8259.9 8267.3 8267.8 4962.4 5953.3 5968.6 6117.1 6152.7 5760.0 5998.6 6072.8 6093.9 6122.4 11:09:10 INFO - PID 1787 | 11:09:10 INFO - PID 1787 | [#4] prototype.html Cycles:180 Average:5445.12 Median:6291.65 stddev:1768.15 (28.1%) stddev-sans-first:1730.82 11:09:10 INFO - PID 1787 | Values: 323.7 337.6 339.7 378.8 412.5 6307.5 6405.0 6497.6 6537.3 6556.4 5933.7 6211.3 6281.7 6320.4 6365.7 4598.5 4620.4 4628.3 4643.8 4661.7 3883.9 3987.5 4012.9 4441.5 4635.7 4118.5 4548.3 4550.2 4560.0 4572.7 3832.1 4003.5 4044.5 4198.5 4529.3 3853.6 3869.7 3920.1 3926.5 3948.9 6145.7 6150.4 6155.6 6156.0 6185.6 5416.9 5425.3 5427.3 5432.8 5433.8 5509.4 6301.6 6499.6 6741.0 6855.3 3516.9 3859.2 4432.8 4463.5 4463.8 6995.2 6999.9 7016.2 7156.5 7171.2 3809.6 4493.4 4499.2 4502.1 4527.1 3806.9 4569.5 4573.5 4585.1 4587.0 5277.6 5387.7 5466.8 5479.3 5509.4 6703.7 6782.7 6789.5 6799.0 6802.9 1357.1 1454.9 1540.8 1567.2 1569.8 998.7 1015.8 1016.3 1018.7 1020.3 5964.4 6369.2 6382.2 6409.5 6460.8 5370.4 6729.2 7069.0 7070.8 7176.4 6335.4 6366.0 6377.2 6381.3 6396.8 3592.9 3605.7 3650.5 3687.0 3696.7 6803.9 7099.6 7211.4 7222.7 7258.7 6741.4 6809.0 7192.1 7199.9 7213.1 7143.9 7222.3 7225.0 7234.9 7292.2 5835.4 6415.0 6705.4 7030.2 7074.3 6751.6 6871.6 6940.6 6979.2 6986.2 6619.3 6903.9 6925.5 6934.5 7306.6 4456.1 4509.4 4511.3 4523.9 4534.0 6230.4 7096.6 7122.5 7136.2 7136.3 5562.7 5709.6 6683.1 6730.3 6778.7 6790.0 6793.9 6815.6 6831.9 6898.4 6545.6 6575.8 6584.2 6665.4 6686.7 5618.6 5716.5 6755.8 6836.8 6882.3 6515.6 6561.1 6613.5 6618.3 6631.0 11:09:10 INFO - PID 1787 | 11:09:10 INFO - PID 1787 | [#5] yui.html Cycles:180 Average:21251.86 Median:21046.54 stddev:30684.52 (145.8%) stddev-sans-first:30731.62 11:09:10 INFO - PID 1787 | Values: 654.0 659.4 728.5 853.7 884.5 15797.9 16895.9 16918.2 18520.9 19105.0 17769.7 18126.1 18256.8 18261.3 18306.9 970.4 992.5 996.6 998.8 1001.0 980.4 984.7 990.0 990.3 992.6 1035.4 1052.8 1053.5 1055.8 1056.2 1025.5 1042.8 1043.0 1051.4 1052.7 707.9 767.1 767.3 770.6 774.0 16848.7 17166.6 17177.7 17373.2 17400.9 16985.2 17261.0 17782.2 17817.2 17832.9 14005.5 14048.8 14173.4 14233.4 14249.6 3421.1 3491.9 3492.7 3494.4 3496.3 132235.0 132465.0 132550.0 135066.0 135519.3 12632.0 13507.7 13570.1 13650.4 13702.3 22988.1 24726.1 25447.2 26266.0 26280.7 7678.5 7919.2 7919.9 7925.0 7934.4 3804.2 3862.5 3862.8 3870.1 3880.0 4513.1 4672.3 4708.6 4727.9 4740.9 919.2 938.3 939.7 952.8 956.3 633.4 653.5 663.4 664.5 683.8 11435.4 12009.7 12164.3 12182.3 12363.9 552.5 606.0 632.4 636.4 659.4 5362.4 5450.4 5621.1 5632.4 5715.5 84604.6 91086.0 91662.5 96604.6 97175.0 89392.0 94184.5 95418.0 95530.0 95688.0 64016.7 82364.0 87420.6 87880.0 89498.6 10946.1 11058.6 11424.3 11557.5 11572.7 31525.0 34390.5 43170.6 43623.0 43935.0 38686.4 46158.3 47696.0 52790.5 53020.5 14086.2 14150.6 14452.7 14528.6 14681.0 15589.8 15815.4 15854.7 15982.6 16165.4 14751.6 14834.3 14870.6 14890.5 15012.8 13892.8 14146.7 14291.4 14408.4 14458.9 14317.5 14540.0 14626.5 14782.5 14898.3 10650.0 10737.6 10775.2 10896.8 11035.8 11265.6 11593.8 11652.5 11656.4 11661.0 11:09:10 INFO - PID 1787 | -------- Summary: end -------- 11:09:10 INFO - PID 1787 | 11:09:10 INFO - TEST-INFO | 1787: exit 0 11:09:10 INFO - TEST-OK | dromaeo_css | took 1114284ms 11:09:10 INFO - TEST-START | kraken 11:09:10 INFO - operating with platform_type : mac_ 11:09:10 INFO - Initialising browser for kraken test... 11:09:10 INFO - TEST-INFO | started process 1909 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpinD2zy/profile http://localhost:49252/getInfo.html) 11:09:13 INFO - PID 1909 | 2017-05-11 11:09:13.000 plugin-container[1910:11645] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 11:09:13 INFO - PID 1909 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:09:13 INFO - PID 1909 | 2017-05-11 11:09:13.003 plugin-container[1910:11645] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4423, name = 'com.apple.CFPasteboardClient' 11:09:13 INFO - PID 1909 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:09:13 INFO - PID 1909 | 2017-05-11 11:09:13.003 plugin-container[1910:11645] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:09:13 INFO - PID 1909 | __metrics Screen width/height:1600/1200 11:09:13 INFO - PID 1909 | colorDepth:24 11:09:13 INFO - PID 1909 | Browser inner width/height: 1024/691 11:09:13 INFO - PID 1909 | __metrics 11:09:13 INFO - PID 1909 | 11:09:13 INFO - PID 1909 | ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 11:09:13 INFO - PID 1909 | 11:09:14 INFO - TEST-INFO | 1909: exit 0 11:09:14 INFO - Browser initialized. 11:09:14 INFO - Running cycle 1/1 for kraken test... 11:09:14 INFO - TEST-INFO | started process 1912 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpinD2zy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:09:16 INFO - PID 1912 | 2017-05-11 11:09:16.631 plugin-container[1914:11805] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x913b, name = 'com.apple.tsm.portname' 11:09:16 INFO - PID 1912 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:09:16 INFO - PID 1912 | 2017-05-11 11:09:16.632 plugin-container[1914:11805] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980f, name = 'com.apple.CFPasteboardClient' 11:09:16 INFO - PID 1912 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:09:16 INFO - PID 1912 | 2017-05-11 11:09:16.632 plugin-container[1914:11805] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:09:17 INFO - PID 1912 | 2017-05-11 11:09:17.071 plugin-container[1915:11847] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x473b, name = 'com.apple.tsm.portname' 11:09:17 INFO - PID 1912 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:09:17 INFO - PID 1912 | 2017-05-11 11:09:17.072 plugin-container[1915:11847] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x440f, name = 'com.apple.CFPasteboardClient' 11:09:17 INFO - PID 1912 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:09:17 INFO - PID 1912 | 2017-05-11 11:09:17.072 plugin-container[1915:11847] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:09:26 INFO - PID 1912 | RSS: Main: 228913152 11:09:26 INFO - PID 1912 | 11:09:29 INFO - PID 1912 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-beat-detection) 11:09:29 INFO - PID 1912 | RSS: Main: 242380800 11:09:29 INFO - PID 1912 | 11:09:32 INFO - PID 1912 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-dft) 11:09:33 INFO - PID 1912 | RSS: Main: 250589184 11:09:33 INFO - PID 1912 | 11:09:36 INFO - PID 1912 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-fft) 11:09:37 INFO - PID 1912 | RSS: Main: 252911616 11:09:37 INFO - PID 1912 | 11:09:39 INFO - PID 1912 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-oscillator) 11:09:39 INFO - PID 1912 | RSS: Main: 255209472 11:09:39 INFO - PID 1912 | 11:09:41 INFO - PID 1912 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49252/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:09:42 INFO - PID 1912 | RSS: Main: 255819776 11:09:42 INFO - PID 1912 | 11:09:45 INFO - PID 1912 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49252/tests/kraken/driver.html?testName=imaging-darkroom) 11:09:46 INFO - PID 1912 | RSS: Main: 261791744 11:09:46 INFO - PID 1912 | 11:09:49 INFO - PID 1912 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49252/tests/kraken/driver.html?testName=imaging-desaturate) 11:09:49 INFO - PID 1912 | RSS: Main: 263729152 11:09:49 INFO - PID 1912 | 11:09:52 INFO - PID 1912 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49252/tests/kraken/driver.html?testName=json-parse-financial) 11:09:53 INFO - PID 1912 | RSS: Main: 263069696 11:09:53 INFO - PID 1912 | 11:09:54 INFO - PID 1912 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49252/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:09:55 INFO - PID 1912 | RSS: Main: 262000640 11:09:55 INFO - PID 1912 | 11:09:57 INFO - PID 1912 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:09:57 INFO - PID 1912 | RSS: Main: 262127616 11:09:57 INFO - PID 1912 | 11:09:59 INFO - PID 1912 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:10:00 INFO - PID 1912 | RSS: Main: 261668864 11:10:00 INFO - PID 1912 | 11:10:03 INFO - PID 1912 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:10:04 INFO - PID 1912 | RSS: Main: 262041600 11:10:04 INFO - PID 1912 | 11:10:07 INFO - PID 1912 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:10:07 INFO - PID 1912 | RSS: Main: 262180864 11:10:07 INFO - PID 1912 | 11:10:09 INFO - PID 1912 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49252/tests/kraken/driver.html?testName=ai-astar) 11:10:09 INFO - PID 1912 | RSS: Main: 264540160 11:10:09 INFO - PID 1912 | 11:10:09 INFO - PID 1912 | __start_tp_report 11:10:09 INFO - PID 1912 | _x_x_mozilla_page_load 11:10:09 INFO - PID 1912 | _x_x_mozilla_page_load_details 11:10:09 INFO - PID 1912 | |i|pagename|runs| 11:10:09 INFO - PID 1912 | |0;ai-astar;102;116;115;115;115;115;115;114;113;114 11:10:09 INFO - PID 1912 | |1;audio-beat-detection;133;111;111;151;109;111;109;109;137;132 11:10:09 INFO - PID 1912 | |2;audio-dft;212;279;257;260;212;228;229;167;186;234 11:10:09 INFO - PID 1912 | |3;audio-fft;85;86;74;79;58;61;58;61;79;68 11:10:09 INFO - PID 1912 | |4;audio-oscillator;91;69;83;66;87;95;73;72;102;116 11:10:09 INFO - PID 1912 | |5;imaging-gaussian-blur;90;90;90;88;89;95;79;119;86;130 11:10:09 INFO - PID 1912 | |6;imaging-darkroom;97;96;96;96;96;96;96;95;118;156 11:10:09 INFO - PID 1912 | |7;imaging-desaturate;92;94;89;90;92;103;138;105;143;140 11:10:09 INFO - PID 1912 | |8;json-parse-financial;51;49;49;50;50;53;50;51;49;51 11:10:09 INFO - PID 1912 | |9;json-stringify-tinderbox;55;62;57;68;60;53;54;58;58;57 11:10:09 INFO - PID 1912 | |10;stanford-crypto-aes;93;82;84;83;81;85;82;85;82;85 11:10:09 INFO - PID 1912 | |11;stanford-crypto-ccm;144;139;141;147;154;142;140;151;197;166 11:10:09 INFO - PID 1912 | |12;stanford-crypto-pbkdf2;178;185;181;210;239;181;188;184;178;188 11:10:09 INFO - PID 1912 | |13;stanford-crypto-sha256-iterative;69;65;68;69;70;67;69;66;69;76 11:10:09 INFO - PID 1912 | __end_tp_report 11:10:09 INFO - PID 1912 | __start_cc_report 11:10:09 INFO - PID 1912 | _x_x_mozilla_cycle_collect,988 11:10:09 INFO - PID 1912 | __end_cc_report 11:10:09 INFO - PID 1912 | __startTimestamp1494526209887__endTimestamp 11:10:09 INFO - PID 1912 | 11:10:09 INFO - PID 1912 | ------- Summary: start ------- 11:10:09 INFO - PID 1912 | Number of tests: 14 11:10:09 INFO - PID 1912 | 11:10:09 INFO - PID 1912 | [#0] ai-astar Cycles:10 Average:113.40 Median:115.00 stddev:4.09 (3.6%) stddev-sans-first:0.87 11:10:09 INFO - PID 1912 | Values: 102.0 116.0 115.0 115.0 115.0 115.0 115.0 114.0 113.0 114.0 11:10:09 INFO - PID 1912 | 11:10:09 INFO - PID 1912 | [#1] audio-beat-detection Cycles:10 Average:121.30 Median:121.50 stddev:15.46 (12.7%) stddev-sans-first:15.81 11:10:09 INFO - PID 1912 | Values: 133.0 111.0 111.0 151.0 109.0 111.0 109.0 109.0 137.0 132.0 11:10:09 INFO - PID 1912 | 11:10:09 INFO - PID 1912 | [#2] audio-dft Cycles:10 Average:226.40 Median:231.50 stddev:34.11 (14.7%) stddev-sans-first:35.78 11:10:09 INFO - PID 1912 | Values: 212.0 279.0 257.0 260.0 212.0 228.0 229.0 167.0 186.0 234.0 11:10:09 INFO - PID 1912 | 11:10:09 INFO - PID 1912 | [#3] audio-fft Cycles:10 Average:70.90 Median:76.50 stddev:11.08 (14.5%) stddev-sans-first:10.51 11:10:09 INFO - PID 1912 | Values: 85.0 86.0 74.0 79.0 58.0 61.0 58.0 61.0 79.0 68.0 11:10:09 INFO - PID 1912 | 11:10:09 INFO - PID 1912 | [#4] audio-oscillator Cycles:10 Average:85.40 Median:78.00 stddev:16.06 (20.6%) stddev-sans-first:16.91 11:10:09 INFO - PID 1912 | Values: 91.0 69.0 83.0 66.0 87.0 95.0 73.0 72.0 102.0 116.0 11:10:09 INFO - PID 1912 | 11:10:09 INFO - PID 1912 | [#5] imaging-gaussian-blur Cycles:10 Average:95.60 Median:89.50 stddev:15.97 (17.8%) stddev-sans-first:16.81 11:10:09 INFO - PID 1912 | Values: 90.0 90.0 90.0 88.0 89.0 95.0 79.0 119.0 86.0 130.0 11:10:09 INFO - PID 1912 | 11:10:09 INFO - PID 1912 | [#6] imaging-darkroom Cycles:10 Average:104.20 Median:96.00 stddev:19.48 (20.3%) stddev-sans-first:20.48 11:10:09 INFO - PID 1912 | Values: 97.0 96.0 96.0 96.0 96.0 96.0 96.0 95.0 118.0 156.0 11:10:09 INFO - PID 1912 | 11:10:09 INFO - PID 1912 | [#7] imaging-desaturate Cycles:10 Average:108.60 Median:89.50 stddev:22.54 (25.2%) stddev-sans-first:23.09 11:10:09 INFO - PID 1912 | Values: 92.0 94.0 89.0 90.0 92.0 103.0 138.0 105.0 143.0 140.0 11:10:09 INFO - PID 1912 | 11:10:09 INFO - PID 1912 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:50.50 stddev:1.25 (2.5%) stddev-sans-first:1.30 11:10:09 INFO - PID 1912 | Values: 51.0 49.0 49.0 50.0 50.0 53.0 50.0 51.0 49.0 51.0 11:10:09 INFO - PID 1912 | 11:10:09 INFO - PID 1912 | [#9] json-stringify-tinderbox Cycles:10 Average:58.20 Median:58.00 stddev:4.37 (7.5%) stddev-sans-first:4.48 11:10:09 INFO - PID 1912 | Values: 55.0 62.0 57.0 68.0 60.0 53.0 54.0 58.0 58.0 57.0 11:10:09 INFO - PID 1912 | 11:10:09 INFO - PID 1912 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:84.50 stddev:3.43 (4.1%) stddev-sans-first:1.56 11:10:09 INFO - PID 1912 | Values: 93.0 82.0 84.0 83.0 81.0 85.0 82.0 85.0 82.0 85.0 11:10:09 INFO - PID 1912 | 11:10:09 INFO - PID 1912 | [#11] stanford-crypto-ccm Cycles:10 Average:152.10 Median:149.00 stddev:17.79 (11.9%) stddev-sans-first:18.63 11:10:09 INFO - PID 1912 | Values: 144.0 139.0 141.0 147.0 154.0 142.0 140.0 151.0 197.0 166.0 11:10:09 INFO - PID 1912 | 11:10:09 INFO - PID 1912 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.20 Median:186.50 stddev:19.16 (10.3%) stddev-sans-first:19.72 11:10:09 INFO - PID 1912 | Values: 178.0 185.0 181.0 210.0 239.0 181.0 188.0 184.0 178.0 188.0 11:10:09 INFO - PID 1912 | 11:10:09 INFO - PID 1912 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:69.00 stddev:2.97 (4.3%) stddev-sans-first:3.15 11:10:09 INFO - PID 1912 | Values: 69.0 65.0 68.0 69.0 70.0 67.0 69.0 66.0 69.0 76.0 11:10:09 INFO - PID 1912 | -------- Summary: end -------- 11:10:09 INFO - PID 1912 | 11:10:10 INFO - TEST-INFO | 1912: exit 0 11:10:10 INFO - TEST-OK | kraken | took 60270ms 11:10:10 INFO - SUITE-END | took 1174s 11:10:11 INFO - Completed test suite (00:19:34) 11:10:11 INFO - javascript benchmark 11:10:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7810.013029435666, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3588.38997370447, 3610.656987586179, 3629.8203083997705, 3650.0874781304674, 3662.212624285778, 4968.701877887329, 5460.180972854074, 6150.169727086842, 6213.782517611885, 6246.250449946007, 6206.441420272175, 6227.626342419458, 6241.282252540959, 6270.5924114932495, 6316.778912738055, 4590.586847183753, 4782.33047373368, 5440.863978400542, 5495.203195536645, 5532.197831314477, 5542.91685624716, 5605.159226116095, 5633.408492108331, 5652.152177173436, 5664.518515926151, 5406.59450541207, 5494.148406996922, 5567.069889794526, 5609.074502707049, 5625.212470254168, 5457.045017122027, 5468.781248750085, 5488.4785945335125, 5493.478119578634, 5494.340679118532, 5459.962607104638, 5475.288212532385, 5532.474414930575, 5580.358258800249, 5608.523275521545, 4855.684380515255, 6069.15031895535, 6216.720247588869, 6275.968620156916, 6288.402601752826, 6710.731570737214, 6767.323267673242, 6778.762743303961, 6815.0, 6819.693113809891, 6616.437602803768, 6733.595984240961, 6756.493263005245, 6867.553609015448, 6887.380135787753, 6317.336679648617, 6364.745410183634, 6365.522585806083, 6377.553571250014, 6381.3937675920715, 7019.649017549102, 7024.086868707138, 7029.507934444642, 7031.40233080194, 7037.788866333915, 5238.345206849144, 6366.554341196072, 6538.280789113193, 6622.403983641495, 6652.600843949379, 6734.528582999143, 6830.0, 6843.486738494633, 6850.38346548813, 6853.931460685429, 6664.600123992576, 6692.196936367667, 6705.195376554844, 6732.764353247613, 6777.186737591521, 6464.41820236181, 6620.205575330991, 6701.664916754143, 6727.730890764413, 6776.15298087739, 5381.354237491526, 5387.75755091014, 5393.083175860113, 5414.0254754144635, 5442.183672449164, 4742.4309082909585, 4817.253325734517, 4824.662273640812, 4830.082284365959, 4841.661083724176, 5339.252504649352, 5644.322681278273, 6293.622382656964, 6331.0, 6360.236771587439, 6774.254831968479, 6778.288279730656, 6817.045613614098, 6849.212340580797, 6868.7252509900045, 5401.189821526724, 6288.591241569284, 6334.144890439824, 6361.427471527585, 6365.904511432333, 6024.548158888101, 6052.455279024821, 6062.9090563641585, 6068.9696551517845, 6074.483668888106, 5767.625104368202, 6709.7651582194385, 6867.553609015597, 6983.127109111361, 6991.9300806993315, 5583.74873130702, 6824.112865327475, 6877.277885821916, 6913.0, 6926.757563485254, 5724.398938111605, 6720.495962802712, 6833.93166068333, 7020.087388639444, 7028.226895041439, 6187.504999600112, 6643.036759669917, 6788.049673045876, 6788.59268443895, 6792.388685018372, 6553.4101930824545, 6681.933180668131, 6803.727850886107, 6894.620795856276, 6951.582905025714, 6513.576617519846, 6546.901796473152, 6563.967180164069, 6652.600843949379, 6681.83295417599, 6650.3682150195655, 6651.933480665131, 6659.700313485808, 6678.599284042973, 6689.197296324278, 6205.100260462117, 6322.146510221245, 6451.741930322923, 6510.609363438209, 6578.342165783383, 6127.234095738033, 6648.0, 6651.833704157435, 6659.833504162435, 6688.7324507019175, 6399.456046235936, 6477.255115661571, 6494.805155845333, 6502.739890404331, 6513.185851768529], "value": 6124.7697562768135, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.4930200953304, 799.0251892690915, 1086.3373342261225, 1187.7208855918857, 1293.3468598357829, 29694.554581681266, 36684.266314673725, 37304.69433569826, 37315.44026839596, 37357.06607334818, 36777.0, 37150.0, 37150.628493715056, 37151.071223219435, 37459.438108428396, 7805.5706936118495, 8199.42604017719, 8404.41169118162, 8424.915750842474, 8431.578421078952, 8377.162283771635, 8481.194286542768, 8482.36382271329, 8488.575571221445, 8514.872276915852, 8433.493990360568, 8473.983122025335, 8496.320294376466, 8503.914960850405, 8506.659733610648, 8447.57762111895, 8481.830363392759, 8485.915140848636, 8488.066312705598, 8511.233988941025, 8181.427300088937, 8244.258016778518, 8252.504849709037, 8255.917440825575, 8257.21556452136, 36177.27645447076, 36250.456243156375, 36378.45432318517, 36498.27003459916, 36618.0, 3090.4128215638957, 3540.327337805809, 3555.466679998021, 3575.5173051637958, 3582.408902531079, 30036.0, 30105.849470752724, 30197.0940871772, 30272.09183724492, 30304.0, 2662.6804783426114, 3121.843907804601, 3205.3108581655056, 3208.8235147066903, 3214.7267482264037, 296841.0, 298463.0, 298833.0, 298847.0, 299123.0, 45176.0, 47785.5221447788, 47880.52119478761, 47963.76018119887, 48175.5182448178, 40880.79559602183, 48884.0, 49485.50514494809, 49705.0, 52397.7380113097, 14159.938004649692, 14229.217393043482, 14283.0, 14295.428182872776, 14345.856541434661, 11385.259958102697, 11446.027087697472, 11451.0, 11469.31184128955, 11487.655370338904, 13585.456581736818, 13614.523491677744, 13625.0, 13636.386362613706, 13695.246761428218, 5985.491233245136, 5997.520198384119, 6000.519958403317, 6004.219451471281, 6010.939890601038, 981.16600889245, 1001.484235618657, 1008.1430783833829, 1036.4713995862164, 1052.2897044494935, 24259.878700606383, 24306.513869722505, 24479.87760061224, 24496.510069798504, 24513.50972980566, 1104.2877344113, 1107.883672214422, 1141.9371934543515, 1146.507001989136, 1146.541383446628, 10911.79970203277, 11855.58505452305, 11951.16341856079, 12471.812922806164, 12521.93739031299, 283405.0, 341004.0, 341314.0, 341409.0, 341508.0, 50512.24231636455, 53247.0, 53448.0, 53487.46512534825, 53597.73201133969, 1439.805626240444, 1485.5394827603477, 1513.8410466901037, 1523.131814865516, 1544.382247101146, 19161.712574311117, 24194.879025604758, 24259.757402426454, 24321.270361889172, 24400.0, 37744.24511509809, 48810.755946220044, 49900.25149622831, 50590.24114638357, 50753.0, 230873.0, 281248.0, 282729.0, 283262.0, 283446.0, 35981.0, 36192.457113142795, 36341.0, 36406.08984775402, 36438.27123457463, 43436.348454772575, 44019.33970990502, 45300.77349613231, 45474.772626136655, 45586.316205256284, 30177.547336790405, 30871.536926945664, 31351.05946821599, 31505.52741708922, 31759.206019849687, 30645.712502662936, 31259.0, 31374.0, 31631.20921976969, 31640.36719265648, 37471.62528374681, 37473.6252637481, 37546.2490750178, 37678.434823478216, 37709.434358485196, 30012.69987300099, 31027.844860775553, 31238.90663826846, 31829.0, 32196.35607287794, 24283.392915176555, 24471.021159153435, 24525.0, 24677.136300229406, 24780.876095619406], "value": 17620.345028747342, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.7564022485946, 397.78320815155735, 399.79210810378623, 416.9854055108071, 429.6047636174719, 16891.915540422295, 17171.313147474102, 21787.23744668939, 21992.670109948325, 22526.54946901061, 20893.791062089374, 22008.899555022224, 22910.312690619267, 23366.299011029652, 23501.177458788945, 5596.468335508121, 5902.085176797582, 5915.792947246847, 6186.164867742842, 6190.102435146901, 5868.9413105869035, 5904.55715821313, 6038.939610603904, 6091.055886337603, 6134.96932515339, 6091.177691011701, 6096.329403765582, 6098.908516372258, 6103.115048317991, 6103.481204097635, 5783.132530120494, 6008.909866352008, 6056.152138700585, 6063.484603808682, 6079.483243924272, 5391.703456309901, 5411.431799661058, 5417.810376636839, 5418.241446197536, 5419.376771671229, 21855.890720546297, 26738.331541711417, 26749.930002800058, 27548.035818746448, 27746.306342341402, 2133.797289257518, 2166.0144634191406, 2166.42589713726, 2168.4253672776726, 2169.9240526581493, 17253.482395528154, 17879.821201788076, 18117.1847266872, 18158.90920545402, 18401.723974140397, 1940.0299850074962, 2038.4801875521796, 2071.2854065347433, 2133.2746866065613, 2136.102836808544, 176874.0, 177199.0, 179118.0, 182128.0, 184901.99999999866, 12115.697107572205, 12703.30131842739, 12721.55474558386, 12745.872541274653, 12752.93623531895, 16395.344186232418, 16455.930364526266, 16490.422835200712, 16522.17389130563, 16545.090020049014, 8726.86909696355, 8862.601182946783, 9122.680706175252, 9158.313126515539, 9193.954030229806, 7413.147488038944, 7571.886421703679, 8669.0, 8684.218420342073, 8816.735497935073, 7811.218878112143, 7885.014373203349, 7931.920680793118, 7957.6021198939825, 7978.6409611567615, 2153.876779138026, 2180.5529866377356, 2185.7158569386197, 2188.9890550547466, 2381.404648837791, 946.4888959961681, 952.7808604021114, 954.174638937328, 971.0774763974252, 994.4331730913455, 18236.0, 19901.402957911287, 20412.183512659623, 20428.59142817165, 20447.977601119885, 949.586929685589, 988.5057471264367, 996.0736515041082, 1005.8591797148405, 1025.4718819807806, 8732.126787321216, 9627.759306017406, 10560.947195264129, 10597.470126493645, 10627.9903409176, 529000.0, 529670.351648247, 573874.1306293525, 588136.0, 591029.0, 118166.4091679536, 120770.0, 121642.0, 130016.69983300046, 130669.0, 1138.2715062360096, 1162.3839365136491, 1177.12892459581, 1178.88800563948, 1187.5071845184152, 15297.082175069532, 15875.206239688201, 17778.0, 17880.0, 18138.818611813713, 54811.72594137004, 55060.0, 55327.61680958008, 56940.71529642325, 57406.71296643657, 76715.46569068475, 78124.21875781397, 78129.96830285301, 79357.60321198357, 79815.0, 6801.795946121624, 7038.964805175941, 8463.111373305714, 8474.576271186294, 8635.222829945335, 12715.682107947376, 12879.806802897776, 13157.736845263233, 13189.670258243621, 13257.66855828612, 9613.807723845344, 11228.438578071227, 11413.258138220988, 11473.82789258145, 11541.826872597087, 10664.89335106639, 10960.232783705063, 10964.232503724981, 11244.887551124712, 11287.943560282147, 11693.590724324607, 11794.05647548177, 11957.0, 11975.203719442154, 11984.161108722306, 8493.32053435736, 8665.783355416164, 8758.824823503366, 8857.689980850639, 8942.418742781958, 9169.449833009774, 11993.0, 12067.155299129328, 12073.215241009657, 12163.331016794162], "value": 10236.919670329902, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1399.524161784993, 1430.5994321589951, 1449.688032330741, 1452.520668179501, 1455.5124033448794, 8746.431481953676, 11262.662120136409, 11368.658940231797, 11402.54389824407, 11434.313941163537, 11140.164487663416, 11258.605948791794, 11331.773364532724, 11355.545778168864, 11378.601748938789, 2474.8515089094667, 2518.622206668996, 2521.155412936659, 2527.5829488134436, 2529.1527338341584, 2449.620308852122, 2503.3366157968153, 2506.6338845329246, 2512.309114993373, 2517.0309430869156, 2465.4822487277693, 2475.0471068638612, 2505.473850491399, 2516.924492265226, 2516.924492265235, 1984.560102467495, 2140.9616336076992, 2288.336382449088, 2435.6224785158242, 2494.289127598626, 2019.838412926977, 2408.1330720940446, 2479.0207867892263, 2506.4611108611552, 2515.0819950718037, 9440.858387124199, 11249.43752812356, 11387.0, 11397.658070257905, 11408.315501069963, 6471.0940468334475, 6662.800695874741, 6695.46436285096, 6703.497237707192, 6714.899276510856, 3962.6037396260144, 4282.978585107086, 4328.7835608219775, 4362.323839804803, 4432.800523976429, 1050.817829941316, 1112.9387883666395, 1188.1979663727036, 1258.2764910176593, 1261.426051146726, 15269.236538173047, 15860.841391586051, 15887.205639717968, 15898.966567173096, 16133.515994520183, 4754.881127971828, 5425.186222066643, 5592.133219351007, 5616.073347897551, 5642.504736244899, 11254.0, 11357.54569817198, 11360.318380897173, 11386.658400248005, 11506.654800356002, 6446.419822216023, 6478.2873883872735, 6479.416852483299, 6483.805485835433, 6484.448821850196, 5971.492423144082, 6024.849378765566, 6091.817245482643, 6241.126242326078, 6241.282252540925, 3898.7718868556312, 4061.634452899253, 4168.124693814272, 4193.322670931651, 4199.727017743898, 1134.2910680824484, 1242.4967888005372, 1254.9811752823714, 1259.0619988108917, 1263.7851565233932, 783.9608019598998, 793.8885560215745, 810.2586133687728, 816.9950960318841, 839.0686338164633, 6128.203333566607, 6179.227596550431, 6324.0, 6372.330905254974, 6404.743810247538, 583.1107560969556, 598.655772930558, 604.2899592978233, 605.449041372349, 619.2445216835454, 3916.725829191986, 4099.61053699898, 4292.313229883203, 4355.259605867011, 4480.462344518679, 14194.07738497015, 14337.06809057408, 14608.926955365156, 14658.0, 14679.266036698336, 14237.857621423653, 14312.785308220386, 14777.113373197642, 14786.556403307917, 14794.630134246514, 7426.183119856812, 8495.447795893248, 8606.096359882122, 8616.224539791448, 8621.956890215508, 6186.845328866634, 6310.706305207514, 6561.212654481493, 7036.190838053484, 7065.646717664199, 12010.039196864054, 12080.818787718015, 12176.269423834598, 12261.816072759095, 12299.815502767644, 12319.63041108768, 12470.875291246972, 13039.804402933774, 13060.346982650639, 13299.20204787716, 7993.920060799318, 8111.229433203836, 8112.310453611273, 8114.148014458633, 8127.0, 10112.119328775638, 10368.844467333147, 10440.216983726099, 10472.633457828939, 10563.841542377024, 8980.967188773375, 9000.954995224982, 9040.734297198529, 9136.908630913871, 9167.44995300284, 8798.868016979879, 8828.337874659555, 8853.291736660924, 8903.887014123235, 9052.3210759192, 8092.797680058045, 8229.094799572162, 8259.917400825914, 8267.255946964802, 8267.751967440745, 4962.354893863919, 5953.28560572734, 5968.552358573212, 6117.051856962178, 6152.661603611844, 5759.971200143972, 5998.550108741774, 6072.757089716361, 6093.878122437615, 6122.418370254819], "value": 5172.414181291009, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.6553070979407, 337.59488613663603, 339.66882289767466, 378.7878787878788, 412.4967539601687, 6307.526935479835, 6404.967975160123, 6497.6101433914, 6537.346265373454, 6556.442702370298, 5933.732982015809, 6211.254649442062, 6281.717322720478, 6320.431161195492, 6365.681715914197, 4598.494165641785, 4620.376249206364, 4628.259478483444, 4643.767811609423, 4661.743604101773, 3883.922321553567, 3987.501562304712, 4012.896453475303, 4441.489228738688, 4635.698679585833, 4118.546959834416, 4548.272276435771, 4550.20266994537, 4559.974005848674, 4572.748498832563, 3832.118612719062, 4003.459532963071, 4044.4944381952255, 4198.475190601175, 4529.252673308916, 3853.5761066282685, 3869.671077958377, 3920.137569734654, 3926.469926559935, 3948.861789837342, 6145.664647433947, 6150.415710507495, 6155.569110162291, 6155.969220153916, 6185.56701030928, 5416.864578385533, 5425.294711687456, 5427.267318911936, 5432.75552600134, 5433.8369848904595, 5509.421510741395, 6301.590396624205, 6499.610023398611, 6740.966295168493, 6855.280195579442, 3516.894493165209, 3859.208862183246, 4432.84485043022, 4463.509014008457, 4463.776811159429, 6995.230524642286, 6999.930000699978, 7016.193137789169, 7156.4632652550745, 7171.211166771651, 3809.6190380961684, 4493.393391892118, 4499.212637788374, 4502.054568540643, 4527.12124468342, 3806.923861522754, 4569.451665800125, 4573.519780423074, 4585.105904348687, 4586.990862010352, 5277.630565860353, 5387.703676297763, 5466.824632703988, 5479.315085614298, 5509.421510741395, 6703.66481675924, 6782.728690852409, 6789.456843452512, 6799.014142949245, 6802.891103757851, 1357.1314358810369, 1454.8763355114722, 1540.8228053773735, 1567.2242240091227, 1569.8351673074392, 998.7103739915489, 1015.786684796201, 1016.2682868334787, 1018.7198520406947, 1020.2908978260186, 5964.433378829004, 6369.172007638977, 6382.234131904202, 6409.519286053564, 6460.773872914426, 5370.4361042090795, 6729.226138994079, 7068.964655176794, 7070.823229419306, 7176.425885929113, 6335.3981371769605, 6365.968170159213, 6377.246257279286, 6381.298057213703, 6396.776112836028, 3592.892213233607, 3605.6574625410544, 3650.470681751184, 3686.967649058268, 3696.685781708531, 6803.863922721518, 7099.609521476265, 7211.423086153095, 7222.736021196375, 7258.657148427558, 6741.426978706865, 6808.9659551701925, 7192.130046188029, 7199.8560028800175, 7213.134423869169, 7143.92856071443, 7222.280525529462, 7225.0, 7234.852839015045, 7292.161401438902, 5835.3740816631835, 6415.0, 6705.362990515893, 7030.191527974348, 7074.292570742885, 6751.628660423527, 6871.553349032297, 6940.583564986117, 6979.232284448808, 6986.170600906641, 6619.3049729779605, 6903.861922761617, 6925.515213934977, 6934.514583979073, 7306.575217832472, 4456.108778244299, 4509.391232183744, 4511.3458548510935, 4523.886902827456, 4533.954660453353, 6230.43926046658, 7096.609686467294, 7122.501424900207, 7136.217804563589, 7136.286371362822, 5562.694051827189, 5709.571782116441, 6683.080902285713, 6730.32696730323, 6778.661066946732, 6790.0, 6793.932060679527, 6815.591064536144, 6831.931680683129, 6898.378086682035, 6545.603468081038, 6575.80272591823, 6584.242812076673, 6665.400113989765, 6686.744881803103, 5618.612089861874, 5716.5426765859465, 6755.831104222434, 6836.829079273058, 6882.311768823278, 6515.641639709672, 6561.1470508833545, 6613.470922326106, 6618.2719900811835, 6631.0], "value": 4871.376864954785, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [653.9705713242904, 659.416416471423, 728.482777228168, 853.6969375871565, 884.5179377239403, 15797.921010394946, 16895.915520422397, 16918.154092295383, 18520.907395463037, 19105.0, 17769.7334539982, 18126.09369531525, 18256.81743182568, 18261.26954921805, 18306.901585904856, 970.403202030753, 992.5087081894436, 996.5714742660653, 998.8102260570505, 1000.9940010289685, 980.4019548075668, 984.6849008317341, 990.0495524296684, 990.3414229537349, 992.5781542844298, 1035.3529044347283, 1052.794705032519, 1053.489057806963, 1055.7677310991608, 1056.1656291529682, 1025.5385076715434, 1042.8279333909895, 1043.0404028293988, 1051.4059556350715, 1052.7052425320921, 707.9185893622197, 767.1178145133085, 767.3400875247282, 770.568481650272, 773.9922600773976, 16848.663026739396, 17166.570835729082, 17177.65644687112, 17373.21820518067, 17400.91299543507, 16985.150742462953, 17261.0, 17782.199801008857, 17817.198226079858, 17832.93002419859, 14005.509807156803, 14048.817400221988, 14173.362198701083, 14233.430662773477, 14249.643758906006, 3421.0763093964492, 3491.930161396783, 3492.738044646662, 3494.405950988338, 3496.335696217736, 132235.0, 132465.0, 132550.0, 135066.0, 135519.32240338833, 12632.0, 13507.729845403037, 13570.117942333716, 13650.385732642055, 13702.314884255748, 22988.080476781077, 24726.13458528943, 25447.236582902544, 26266.0, 26280.73719262783, 7678.5392876427595, 7919.208079192034, 7919.881201781978, 7925.0, 7934.444588878723, 3804.201117765245, 3862.4978752761963, 3862.806859657006, 3870.1098747288283, 3880.010597297671, 4513.142502924499, 4672.345871577982, 4708.576228139415, 4727.929081063787, 4740.905181896412, 919.2416256588341, 938.3009657804843, 939.6852054561662, 952.7903861150536, 956.2827879090682, 633.395107672172, 653.5033374635311, 663.4427081251675, 664.5481072870494, 683.7948615415355, 11435.428228588704, 12009.699757505958, 12164.2701437914, 12182.269063856196, 12363.876361236273, 552.5331095224528, 605.9606125601822, 632.3960617610171, 636.3509220594967, 659.4394764450179, 5362.436944120889, 5450.400455949843, 5621.128725047624, 5632.3804381518, 5715.485606295453, 84604.57697711595, 91086.0, 91662.54168729247, 96604.55093173608, 97175.0, 89392.0, 94184.52907735555, 95418.0, 95530.0, 95688.0, 64016.67991660012, 82364.0, 87420.56289718511, 87880.0, 89498.55250723705, 10946.069584085124, 11058.612948546763, 11424.314541127558, 11557.479913404086, 11572.710682233012, 31525.0, 34390.48414273838, 43170.568294316654, 43623.0, 43935.0, 38686.419703703905, 46158.30762538632, 47696.046079078915, 52790.47209527856, 53020.46979530156, 14086.22525761093, 14150.575482735534, 14452.710945781237, 14528.636784080483, 14681.0, 15589.844101559293, 15815.446459373867, 15854.68290634204, 15982.60043498922, 16165.4342098026, 14751.631209219426, 14834.332455039334, 14870.553883383516, 14890.478833240784, 15012.7748083781, 13892.791608126088, 14146.717065658835, 14291.42834286617, 14408.351624177147, 14458.915581331232, 14317.498887538886, 14540.0, 14626.488072917396, 14782.48261310849, 14898.255087245378, 10650.0, 10737.570497180337, 10775.191860610328, 10896.782064358827, 11035.834462482908, 11265.605703800327, 11593.82609260845, 11652.53389864396, 11656.358900260568, 11661.008814250883], "value": 8151.946829751035, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1530.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 116.0, 115.0, 115.0, 115.0, 115.0, 115.0, 114.0, 113.0, 114.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 111.0, 111.0, 151.0, 109.0, 111.0, 109.0, 109.0, 137.0, 132.0], "value": 121.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [212.0, 279.0, 257.0, 260.0, 212.0, 228.0, 229.0, 167.0, 186.0, 234.0], "value": 226.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 74.0, 79.0, 58.0, 61.0, 58.0, 61.0, 79.0, 68.0], "value": 70.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 69.0, 83.0, 66.0, 87.0, 95.0, 73.0, 72.0, 102.0, 116.0], "value": 85.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 90.0, 88.0, 89.0, 95.0, 79.0, 119.0, 86.0, 130.0], "value": 95.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 118.0, 156.0], "value": 104.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 89.0, 90.0, 92.0, 103.0, 138.0, 105.0, 143.0, 140.0], "value": 108.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 49.0, 50.0, 50.0, 53.0, 50.0, 51.0, 49.0, 51.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 62.0, 57.0, 68.0, 60.0, 53.0, 54.0, 58.0, 58.0, 57.0], "value": 58.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 82.0, 84.0, 83.0, 81.0, 85.0, 82.0, 85.0, 82.0, 85.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 139.0, 141.0, 147.0, 154.0, 142.0, 140.0, 151.0, 197.0, 166.0], "value": 152.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 185.0, 181.0, 210.0, 239.0, 181.0, 188.0, 184.0, 178.0, 188.0], "value": 191.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 65.0, 68.0, 69.0, 70.0, 67.0, 69.0, 66.0, 69.0, 76.0], "value": 68.8, "unit": "score"}]}]} 11:10:11 INFO - Return code: 0 11:10:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:10:11 INFO - # TBPL SUCCESS # 11:10:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:10:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:10:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:10:11 INFO - 2017-05-11 11:10:11.220 screenresolution[1925:12236] starting screenresolution argv=screenresolution get 11:10:11 INFO - 2017-05-11 11:10:11.223 screenresolution[1925:12236] Display 0: 1600x1200x32@60 11:10:11 INFO - 2017-05-11 11:10:11.230 screenresolution[1926:12239] starting screenresolution argv=screenresolution list 11:10:11 INFO - Available Modes on Display 0 11:10:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:10:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:10:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:10:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:10:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:10:11 INFO - Intel Iris: 11:10:11 INFO - Chipset Model: Intel Iris 11:10:11 INFO - Type: GPU 11:10:11 INFO - Bus: Built-In 11:10:11 INFO - VRAM (Dynamic, Max): 1536 MB 11:10:11 INFO - Vendor: Intel (0x8086) 11:10:11 INFO - Device ID: 0x0a2e 11:10:11 INFO - Revision ID: 0x0009 11:10:11 INFO - Displays: 11:10:11 INFO - Display: 11:10:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:10:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:10:11 INFO - Display Serial Number: 1600x1200 60 11:10:11 INFO - Main Display: Yes 11:10:11 INFO - Mirror: Off 11:10:11 INFO - Online: Yes 11:10:11 INFO - Rotation: Supported 11:10:11 INFO - Return code: 0 11:10:11 INFO - Running post-action listener: _resource_record_post_action 11:10:11 INFO - [mozharness: 2017-05-11 18:10:11.477249Z] Finished run-tests step (success) 11:10:11 INFO - Running post-run listener: _resource_record_post_run 11:10:11 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 34620416; Write bytes: 534695936; Read time: 212; Write time: 1736 11:10:11 INFO - TinderboxPrint: CPU usage
54.2% 11:10:11 INFO - TinderboxPrint: I/O read bytes / time
34,620,416 / 212 11:10:11 INFO - TinderboxPrint: I/O write bytes / time
534,695,936 / 1,736 11:10:11 INFO - TinderboxPrint: CPU idle
2,191.8 (45.8%) 11:10:11 INFO - TinderboxPrint: CPU system
962.7 (20.1%) 11:10:11 INFO - TinderboxPrint: CPU user
1,630.9 (34.1%) 11:10:11 INFO - TinderboxPrint: Swap in / out
244,150,272 / 0 11:10:11 INFO - install - Wall time: 21s; CPU: 55.0%; Read bytes: 214522368; Write bytes: 216508416; Read time: 16457; Write time: 494 11:10:11 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 30487552; Write bytes: 316852224; Read time: 186; Write time: 1237 11:10:11 INFO - Running post-run listener: _upload_blobber_files 11:10:11 INFO - Blob upload gear active. 11:10:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:10:11 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 11:10:11 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'] 11:10:11 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 11:10:12 INFO - (blobuploader) - INFO - Open directory for files ... 11:10:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:10:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:10:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:10:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:10:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:10:13 INFO - (blobuploader) - INFO - Done attempting. 11:10:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:10:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:10:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:10:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:10:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:10:13 INFO - (blobuploader) - INFO - Done attempting. 11:10:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:10:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:10:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:10:15 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:10:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:10:15 INFO - (blobuploader) - INFO - Done attempting. 11:10:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:10:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:10:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:10:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:10:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:10:17 INFO - (blobuploader) - INFO - Done attempting. 11:10:17 INFO - (blobuploader) - INFO - Iteration through files over. 11:10:17 INFO - Return code: 0 11:10:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:10:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:10:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f37b680ae64c906ea36c0726875cd8970203e16c0fd6fe9e1bbbecf6b242560ddf2ef2c3237fea19ee67f9dc5d28e82c616432b3b387c4caac9a943e0d37d2bc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b079ec1a95f9184706c22ad730920a8084b605a220de9118149b7dedd3a951ff802e80b81d3cebd8aed58460ed51eddb3ebed2a9a685fde89f4f5f56d8904abe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18e1500d7dd2f8a10c2be6c97a33c0af4a9800e21569e301c03384e01471ac966f84752ae871684950e078fafde0e1525d3bbcb6da2d6102c2404006a84a7559", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0558a7d582d4551cee0ed5a6ea279d167bcd40402254b1c48ed509092ac092122c6191e56b1bc7fc2107a87563cf2469b6ae381f9cc786760dd9ee304cd18cc0"} 11:10:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:10:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:10:17 INFO - Contents: 11:10:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f37b680ae64c906ea36c0726875cd8970203e16c0fd6fe9e1bbbecf6b242560ddf2ef2c3237fea19ee67f9dc5d28e82c616432b3b387c4caac9a943e0d37d2bc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b079ec1a95f9184706c22ad730920a8084b605a220de9118149b7dedd3a951ff802e80b81d3cebd8aed58460ed51eddb3ebed2a9a685fde89f4f5f56d8904abe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18e1500d7dd2f8a10c2be6c97a33c0af4a9800e21569e301c03384e01471ac966f84752ae871684950e078fafde0e1525d3bbcb6da2d6102c2404006a84a7559", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0558a7d582d4551cee0ed5a6ea279d167bcd40402254b1c48ed509092ac092122c6191e56b1bc7fc2107a87563cf2469b6ae381f9cc786760dd9ee304cd18cc0"} 11:10:17 INFO - Running post-run listener: copy_logs_to_upload_dir 11:10:17 INFO - Copying logs to upload dir... 11:10:17 INFO - mkdir: /builds/slave/test/build/upload/logs 11:10:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=2481.639556 ========= master_lag: 4.17 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 41 mins, 25 secs) (at 2017-05-11 11:10:21.272395) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 11:10:21.276569) ========= 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.EC0IstH5Ce/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.ci5g6J4IJI/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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/f37b680ae64c906ea36c0726875cd8970203e16c0fd6fe9e1bbbecf6b242560ddf2ef2c3237fea19ee67f9dc5d28e82c616432b3b387c4caac9a943e0d37d2bc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b079ec1a95f9184706c22ad730920a8084b605a220de9118149b7dedd3a951ff802e80b81d3cebd8aed58460ed51eddb3ebed2a9a685fde89f4f5f56d8904abe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18e1500d7dd2f8a10c2be6c97a33c0af4a9800e21569e301c03384e01471ac966f84752ae871684950e078fafde0e1525d3bbcb6da2d6102c2404006a84a7559", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0558a7d582d4551cee0ed5a6ea279d167bcd40402254b1c48ed509092ac092122c6191e56b1bc7fc2107a87563cf2469b6ae381f9cc786760dd9ee304cd18cc0"} build_url:https://queue.taskcluster.net/v1/task/AcrmHGpHQDmj8gAyLuoYOg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011077 build_url: 'https://queue.taskcluster.net/v1/task/AcrmHGpHQDmj8gAyLuoYOg/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/f37b680ae64c906ea36c0726875cd8970203e16c0fd6fe9e1bbbecf6b242560ddf2ef2c3237fea19ee67f9dc5d28e82c616432b3b387c4caac9a943e0d37d2bc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b079ec1a95f9184706c22ad730920a8084b605a220de9118149b7dedd3a951ff802e80b81d3cebd8aed58460ed51eddb3ebed2a9a685fde89f4f5f56d8904abe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18e1500d7dd2f8a10c2be6c97a33c0af4a9800e21569e301c03384e01471ac966f84752ae871684950e078fafde0e1525d3bbcb6da2d6102c2404006a84a7559", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0558a7d582d4551cee0ed5a6ea279d167bcd40402254b1c48ed509092ac092122c6191e56b1bc7fc2107a87563cf2469b6ae381f9cc786760dd9ee304cd18cc0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 11:10:21.311480) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 11:10:21.311882) ========= 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.EC0IstH5Ce/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.ci5g6J4IJI/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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.005316 ========= master_lag: 0.23 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 11:10:21.545962) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 11:10:21.546376) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 11:10:21.546816) ========= ========= Total master_lag: 4.50 =========