Test the IDF Monitor through sockets

Creates a socket by socat and sends tests to the idf_monitor through it.

.gitignore

@@ -43,6 +43,9 @@ tools/unit-test-app/build
 tools/unit-test-app/builds
 tools/unit-test-app/output
 
+# IDF monitor test
+tools/test_idf_monitor/outputs
+
 # AWS IoT Examples require device-specific certs/keys
 examples/protocols/aws_iot/*/main/certs/*.pem.*
 

.gitlab-ci.yml

@@ -328,6 +328,17 @@ test_build_system:
     - cd test_build_system
     - ${IDF_PATH}/tools/ci/test_build_system.sh
 
+test_idf_monitor:
+  <<: *host_test_template
+  artifacts:
+    when: on_failure
+    paths:
+      - tools/test_idf_monitor/outputs/*
+    expire_in: 1 week
+  script:
+    - cd ${IDF_PATH}/tools/test_idf_monitor
+    - ./run_test_idf_monitor.py
+
 test_esp_err_to_name_on_host:
   <<: *host_test_template
   script:

tools/ci/executable-list.txt

@@ -33,3 +33,4 @@ tools/kconfig/streamline_config.pl
 tools/kconfig/conf
 tools/kconfig/mconf
 tools/windows/eclipse_make.sh
+tools/test_idf_monitor/run_test_idf_monitor.py

tools/idf_monitor.py

@@ -135,10 +135,11 @@ class ConsoleReader(StoppableThread):
     """ Read input keys from the console and push them to the queue,
     until stopped.
     """
-    def __init__(self, console, event_queue):
+    def __init__(self, console, event_queue, test_mode):
         super(ConsoleReader, self).__init__()
         self.console = console
         self.event_queue = event_queue
+        self.test_mode = test_mode
 
     def run(self):
         self.console.setup()
@@ -155,6 +156,13 @@ class ConsoleReader(StoppableThread):
                             time.sleep(0.1)
                         if not self.alive:
                             break
+                    elif self.test_mode:
+                        # In testing mode the stdin is connected to PTY but is not used for input anything. For PTY
+                        # the canceling by fcntl.ioctl isn't working and would hang in self.console.getkey().
+                        # Therefore, we avoid calling it.
+                        while self.alive:
+                            time.sleep(0.1)
+                        break
                     c = self.console.getkey()
                 except KeyboardInterrupt:
                     c = '\x03'
@@ -164,7 +172,7 @@ class ConsoleReader(StoppableThread):
             self.console.cleanup()
 
     def _cancel(self):
-        if os.name == 'posix':
+        if os.name == 'posix' and not self.test_mode:
             # this is the way cancel() is implemented in pyserial 3.3 or newer,
             # older pyserial (3.1+) has cancellation implemented via 'select',
             # which does not work when console sends an escape sequence response
@@ -175,6 +183,8 @@ class ConsoleReader(StoppableThread):
             #
             # note that TIOCSTI is not implemented in WSL / bash-on-Windows.
             # TODO: introduce some workaround to make it work there.
+            #
+            # Note: This would throw exception in testing mode when the stdin is connected to PTY.
             import fcntl, termios
             fcntl.ioctl(self.console.fd, termios.TIOCSTI, b'\0')
 
@@ -265,6 +275,12 @@ class LineMatcher:
         # We need something more than "*.N" for printing.
         return self._dict.get("*", self.LEVEL_N) > self.LEVEL_N
 
+class SerialStopException(Exception):
+    """
+    This exception is used for stopping the IDF monitor in testing mode.
+    """
+    pass
+
 class Monitor(object):
     """
     Monitor application main class.
@@ -293,8 +309,9 @@ class Monitor(object):
 
             self.console.getkey = types.MethodType(getkey_patched, self.console)
 
+        socket_mode = serial_instance.port.startswith("socket://") # testing hook - data from serial can make exit the monitor
         self.serial = serial_instance
-        self.console_reader = ConsoleReader(self.console, self.event_queue)
+        self.console_reader = ConsoleReader(self.console, self.event_queue, socket_mode)
         self.serial_reader = SerialReader(self.serial, self.event_queue)
         self.elf_file = elf_file
         self.make = make
@@ -317,6 +334,7 @@ class Monitor(object):
         self._invoke_processing_last_line_timer = None
         self._force_line_print = False
         self._output_enabled = True
+        self._serial_check_exit = socket_mode
 
     def invoke_processing_last_line(self):
         self.event_queue.put((TAG_SERIAL_FLUSH, b''), False)
@@ -344,6 +362,8 @@ class Monitor(object):
                     self.handle_serial_input(data, finalize_line=True)
                 else:
                     raise RuntimeError("Bad event data %r" % ((event_tag,data),))
+        except SerialStopException:
+            pass
         finally:
             try:
                 self.console_reader.stop()
@@ -384,6 +404,8 @@ class Monitor(object):
             self._last_line_part = sp.pop()
         for line in sp:
             if line != b"":
+                if self._serial_check_exit and line == self.exit_key:
+                    raise SerialStopException()
                 if self._output_enabled and (self._force_line_print or self._line_matcher.match(line)):
                     self.console.write_bytes(line + b'\n')
                     self.handle_possible_pc_address_in_line(line)

tools/test_idf_monitor/README.md

@@ -0,0 +1,13 @@
+# Project for testing the IDF monitor
+
+Use `run_test_idf_monitor.py` in order to run the test.
+
+New tests can be added into `test_list` of `run_test_idf_monitor.py` and placing the corresponding files into the
+`tests` directory.
+
+Note: The `idf_monitor` is tested by a dummy ELF file which was generated by running the following commands::
+
+    dd if=/dev/zero of=tmp.bin bs=1 count=4
+    xtensa-esp32-elf-objcopy -I binary -O elf32-xtensa-le -B xtensa tmp.bin tmp.o
+    xtensa-esp32-elf-ld --defsym _start=0x40000000 tmp.o -o dummy.elf
+    chmod -x dummy.elf

tools/test_idf_monitor/run_test_idf_monitor.py

@@ -0,0 +1,119 @@
+#!/usr/bin/env python
+#
+# Copyright 2018 Espressif Systems (Shanghai) PTE LTD
+#
+# Licensed under the Apache License, Version 2.0 (the "License");
+# you may not use this file except in compliance with the License.
+# You may obtain a copy of the License at
+#
+#     http://www.apache.org/licenses/LICENSE-2.0
+#
+# Unless required by applicable law or agreed to in writing, software
+# distributed under the License is distributed on an "AS IS" BASIS,
+# WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.
+# See the License for the specific language governing permissions and
+# limitations under the License.
+
+import os
+import signal
+import time
+import subprocess
+import pty
+
+test_list = (
+        # Add new tests here. All files should be placed in in_dir. Columns are:
+        # Input file            Filter string                                               File with expected output
+        ('in1.txt',             '',                                                         'in1f1.txt'),
+        ('in1.txt',             '*:V',                                                      'in1f1.txt'),
+        ('in1.txt',             'hello_world',                                              'in1f2.txt'),
+        ('in1.txt',             '*:N',                                                      'in1f3.txt'),
+        ('in2.txt',             'boot mdf_device_handle:I mesh:E vfs:I',                    'in2f1.txt'),
+        ('in2.txt',             'vfs',                                                      'in2f2.txt'),
+        )
+
+in_dir = 'tests/'       # tests are in this directory
+out_dir = 'outputs/'    # test results are written to this directory (kept only for debugging purposes)
+socat_in = './socatfile'# temporary socat file (deleted after run)
+monitor_error_output = out_dir + 'monitor_error_output'
+elf_file = './dummy.elf'  # ELF file used for starting the monitor
+idf_monitor = '{}/tools/idf_monitor.py'.format(os.getenv("IDF_PATH"))
+
+class SocatRunner:
+    """
+    Runs socat in the background for creating a socket.
+    """
+    def __enter__(self):
+        # Wait for a connection on port 2399 and then run "tail" which will send the file content to that port. Tail
+        # is used because it can start even when the file doesn't exists and remains running after the file has been
+        # processed. This way the idf_monitor can end the communication when it received the content. Using regular
+        # "cat" would invoke exception in idf_monitor.
+        # Note: "-c 1GB" option is used to force sending the whole file under the assumption that all testing files
+        # will be much smaller than 1G.
+        # Note: A temporary file socat_in is used in order to be able to start socat only once instead of for each test.
+        socat_cmd = ['socat',
+                '-U', # unidirectional pipe from file to port
+                'TCP4-LISTEN:2399,reuseaddr,fork',
+                'exec:"tail -c 1GB -F ' + socat_in + '"']
+        print ' '.join(socat_cmd)
+        self._socat_process = subprocess.Popen(socat_cmd, preexec_fn=os.setsid) # See __exit__ for os.setsid
+        return self
+
+    def __exit__(self, type, value, traceback):
+        # self._socat_process.terminate() doesn't enough because each connection to the port starts a new socat and a
+        # tail processes
+        os.killpg(os.getpgid(self._socat_process.pid), signal.SIGTERM)
+        # Note: this terminates all the processes but makes the script UNIX-only
+
+def cleanup():
+    try:
+        os.remove(socat_in)
+    except:
+        # ignore if the file doesn't exist
+        pass
+
+def main():
+    start = time.time()
+    cleanup() # avoid sending old content
+    if not os.path.exists(out_dir):
+        os.mkdir(out_dir)
+    try:
+        with SocatRunner():
+            # Sleep is necessary to make sure that socat is already listening. Only one sleep is used per run (this is
+            # another reason while the temporary socat_in file is used instead of directly reading the test files).
+            time.sleep(1)
+            for t in test_list:
+                print 'Running test on {} with filter "{}" and expecting {}'.format(t[0], t[1], t[2])
+                with open(in_dir + t[0], "r") as input_file, open(socat_in, "w") as socat_file:
+                    print 'cat {} > {}'.format(input_file.name, socat_file.name)
+                    for line in input_file:
+                        socat_file.write(line)
+                    idf_exit_sequence = b'\x1d\n'
+                    print 'echo "<exit>" >> {}'.format(socat_file.name)
+                    socat_file.write(idf_exit_sequence)
+                monitor_cmd = [idf_monitor,
+                        '--port', 'socket://localhost:2399',
+                        '--print_filter', t[1],
+                        elf_file]
+                with open(out_dir + t[2], "w") as mon_out_f, open(monitor_error_output, "w") as mon_err_f:
+                    try:
+                        (master_fd, slave_fd) = pty.openpty()
+                        print ' '.join(monitor_cmd),
+                        print ' > {} 2> {} < {}'.format(mon_out_f.name, mon_err_f.name, os.ttyname(slave_fd))
+                        proc = subprocess.Popen(monitor_cmd, stdin=slave_fd, stdout=mon_out_f, stderr=mon_err_f,
+                                close_fds=True)
+                        proc.wait()
+                    finally:
+                        os.close(slave_fd)
+                        os.close(master_fd)
+                diff_cmd = ['diff', in_dir + t[2], out_dir + t[2]]
+                print ' '.join(diff_cmd)
+                subprocess.check_call(diff_cmd)
+                print 'Test has passed'
+    finally:
+        cleanup()
+
+    end = time.time()
+    print 'Execution took {:.2f} seconds'.format(end - start)
+
+if __name__ == "__main__":
+    main()

tools/test_idf_monitor/tests/in1.txt

@@ -0,0 +1,91 @@
+ets Jun  8 2016 00:22:57
+
+rst:0x1 (POWERON_RESET),boot:0x33 (SPI_FAST_FLASH_BOOT)
+configsip: 0, SPIWP:0xee
+clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00
+mode:DIO, clock div:2
+load:0x3fff0018,len:4
+load:0x3fff001c,len:5728
+ho 0 tail 12 room 4
+load:0x40078000,len:0
+load:0x40078000,len:14944
+entry 0x4007862c
+I (30) boot: ESP-IDF v3.1-dev-1320-gec1fb521b-dirty 2nd stage bootloader
+I (30) boot: compile time 09:31:02
+I (40) boot: Enabling RNG early entropy source...
+I (41) boot: SPI Speed      : 40MHz
+I (41) boot: SPI Mode       : DIO
+I (45) boot: SPI Flash Size : 4MB
+I (49) boot: Partition Table:
+I (53) boot: ## Label            Usage          Type ST Offset   Length
+I (60) boot:  0 nvs              WiFi data        01 02 00009000 00006000
+I (68) boot:  1 phy_init         RF data          01 01 0000f000 00001000
+I (75) boot:  2 factory          factory app      00 00 00010000 00100000
+I (83) boot: End of partition table
+I (87) esp_image: segment 0: paddr=0x00010020 vaddr=0x3f400020 size=0x058ac ( 22700) map
+I (104) esp_image: segment 1: paddr=0x000158d4 vaddr=0x3ffb0000 size=0x022a0 (  8864) load
+I (108) esp_image: segment 2: paddr=0x00017b7c vaddr=0x40080000 size=0x00400 (  1024) load
+0x40080000: _iram_start at /home/dragon/esp/esp-idf/components/freertos/xtensa_vectors.S:1685
+
+I (114) esp_image: segment 3: paddr=0x00017f84 vaddr=0x40080400 size=0x0808c ( 32908) load
+I (136) esp_image: segment 4: paddr=0x00020018 vaddr=0x400d0018 size=0x11e88 ( 73352) map
+0x400d0018: _flash_cache_start at ??:?
+
+I (162) esp_image: segment 5: paddr=0x00031ea8 vaddr=0x4008848c size=0x00670 (  1648) load
+0x4008848c: esp_rom_spiflash_program_page_internal at /home/dragon/esp/esp-idf/components/spi_flash/spi_flash_rom_patch.c:412
+
+I (163) esp_image: segment 6: paddr=0x00032520 vaddr=0x400c0000 size=0x00000 (     0) load
+I (174) boot: Loaded app from partition at offset 0x10000
+I (175) boot: Disabling RNG early entropy source...
+I (180) cpu_start: Pro cpu up.
+I (184) cpu_start: Starting app cpu, entry point is 0x40080e54
+0x40080e54: call_start_cpu1 at /home/dragon/esp/esp-idf/components/esp32/cpu_start.c:225
+
+I (0) cpu_start: App cpu up.
+I (195) heap_init: Initializing. RAM available for dynamic allocation:
+D (201) heap_init: New heap initialised at 0x3ffae6e0
+I (206) heap_init: At 3FFAE6E0 len 00001920 (6 KiB): DRAM
+D (212) heap_init: New heap initialised at 0x3ffb32f0
+I (218) heap_init: At 3FFB32F0 len 0002CD10 (179 KiB): DRAM
+I (224) heap_init: At 3FFE0440 len 00003BC0 (14 KiB): D/IRAM
+I (230) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM
+D (237) heap_init: New heap initialised at 0x40088afc
+I (242) heap_init: At 40088AFC len 00017504 (93 KiB): IRAM
+I (248) cpu_start: Pro cpu start user code
+D (260) clk: RTC_SLOW_CLK calibration value: 3181466
+D (269) intr_alloc: Connected src 46 to int 2 (cpu 0)
+D (270) intr_alloc: Connected src 57 to int 3 (cpu 0)
+D (271) intr_alloc: Connected src 24 to int 9 (cpu 0)
+I (276) cpu_start: Starting scheduler on PRO CPU.
+D (0) intr_alloc: Connected src 25 to int 2 (cpu 1)
+I (0) cpu_start: Starting scheduler on APP CPU.
+D (291) heap_init: New heap initialised at 0x3ffe0440
+D (301) heap_init: New heap initialised at 0x3ffe4350
+D (311) intr_alloc: Connected src 16 to int 12 (cpu 0)
+D (311) hello_world: debug1
+
+W (311) hello_world: warning1
+V (321) hello_world: verbose1
+E (321) hello_world: error1
+I (321) hello_world: info1
+
+regular printf
+D (331) another_world: another debug
+
+I (331) example: Periodic timer called, time since boot: 507065 us
+V (341) another_world: another verbose another very long
+W (341) another_world: another warning very long
+V (351) another_world: another verbose
+E (351) another_world: another error
+I (361) another_world: Register 0x40080000
+0x40080000: _iram_start at /home/dragon/esp/esp-idf/components/freertos/xtensa_vectors.S:1685
+
+D (361) hello_world: debug2
+W (371) hello_world: warning2
+
+V (371) hello_world: verbose2
+E (371) hello_world: error2
+I (381) hello_world: info2
+noeol 0x400800000x40080000: _iram_start at /home/dragon/esp/esp-idf/components/freertos/xtensa_vectors.S:1685
+
+

tools/test_idf_monitor/tests/in1f1.txt

@@ -0,0 +1,91 @@
+ets Jun  8 2016 00:22:57
+
+rst:0x1 (POWERON_RESET),boot:0x33 (SPI_FAST_FLASH_BOOT)
+configsip: 0, SPIWP:0xee
+clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00
+mode:DIO, clock div:2
+load:0x3fff0018,len:4
+load:0x3fff001c,len:5728
+ho 0 tail 12 room 4
+load:0x40078000,len:0
+load:0x40078000,len:14944
+entry 0x4007862c
+I (30) boot: ESP-IDF v3.1-dev-1320-gec1fb521b-dirty 2nd stage bootloader
+I (30) boot: compile time 09:31:02
+I (40) boot: Enabling RNG early entropy source...
+I (41) boot: SPI Speed      : 40MHz
+I (41) boot: SPI Mode       : DIO
+I (45) boot: SPI Flash Size : 4MB
+I (49) boot: Partition Table:
+I (53) boot: ## Label            Usage          Type ST Offset   Length
+I (60) boot:  0 nvs              WiFi data        01 02 00009000 00006000
+I (68) boot:  1 phy_init         RF data          01 01 0000f000 00001000
+I (75) boot:  2 factory          factory app      00 00 00010000 00100000
+I (83) boot: End of partition table
+I (87) esp_image: segment 0: paddr=0x00010020 vaddr=0x3f400020 size=0x058ac ( 22700) map
+I (104) esp_image: segment 1: paddr=0x000158d4 vaddr=0x3ffb0000 size=0x022a0 (  8864) load
+I (108) esp_image: segment 2: paddr=0x00017b7c vaddr=0x40080000 size=0x00400 (  1024) load
+0x40080000: _iram_start at /home/dragon/esp/esp-idf/components/freertos/xtensa_vectors.S:1685
+
+I (114) esp_image: segment 3: paddr=0x00017f84 vaddr=0x40080400 size=0x0808c ( 32908) load
+I (136) esp_image: segment 4: paddr=0x00020018 vaddr=0x400d0018 size=0x11e88 ( 73352) map
+0x400d0018: _flash_cache_start at ??:?
+
+I (162) esp_image: segment 5: paddr=0x00031ea8 vaddr=0x4008848c size=0x00670 (  1648) load
+0x4008848c: esp_rom_spiflash_program_page_internal at /home/dragon/esp/esp-idf/components/spi_flash/spi_flash_rom_patch.c:412
+
+I (163) esp_image: segment 6: paddr=0x00032520 vaddr=0x400c0000 size=0x00000 (     0) load
+I (174) boot: Loaded app from partition at offset 0x10000
+I (175) boot: Disabling RNG early entropy source...
+I (180) cpu_start: Pro cpu up.
+I (184) cpu_start: Starting app cpu, entry point is 0x40080e54
+0x40080e54: call_start_cpu1 at /home/dragon/esp/esp-idf/components/esp32/cpu_start.c:225
+
+I (0) cpu_start: App cpu up.
+I (195) heap_init: Initializing. RAM available for dynamic allocation:
+D (201) heap_init: New heap initialised at 0x3ffae6e0
+I (206) heap_init: At 3FFAE6E0 len 00001920 (6 KiB): DRAM
+D (212) heap_init: New heap initialised at 0x3ffb32f0
+I (218) heap_init: At 3FFB32F0 len 0002CD10 (179 KiB): DRAM
+I (224) heap_init: At 3FFE0440 len 00003BC0 (14 KiB): D/IRAM
+I (230) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM
+D (237) heap_init: New heap initialised at 0x40088afc
+I (242) heap_init: At 40088AFC len 00017504 (93 KiB): IRAM
+I (248) cpu_start: Pro cpu start user code
+D (260) clk: RTC_SLOW_CLK calibration value: 3181466
+D (269) intr_alloc: Connected src 46 to int 2 (cpu 0)
+D (270) intr_alloc: Connected src 57 to int 3 (cpu 0)
+D (271) intr_alloc: Connected src 24 to int 9 (cpu 0)
+I (276) cpu_start: Starting scheduler on PRO CPU.
+D (0) intr_alloc: Connected src 25 to int 2 (cpu 1)
+I (0) cpu_start: Starting scheduler on APP CPU.
+D (291) heap_init: New heap initialised at 0x3ffe0440
+D (301) heap_init: New heap initialised at 0x3ffe4350
+D (311) intr_alloc: Connected src 16 to int 12 (cpu 0)
+D (311) hello_world: debug1
+
+W (311) hello_world: warning1
+V (321) hello_world: verbose1
+E (321) hello_world: error1
+I (321) hello_world: info1
+
+regular printf
+D (331) another_world: another debug
+
+I (331) example: Periodic timer called, time since boot: 507065 us
+V (341) another_world: another verbose another very long
+W (341) another_world: another warning very long
+V (351) another_world: another verbose
+E (351) another_world: another error
+I (361) another_world: Register 0x40080000
+0x40080000: _iram_start at /home/dragon/esp/esp-idf/components/freertos/xtensa_vectors.S:1685
+
+D (361) hello_world: debug2
+W (371) hello_world: warning2
+
+V (371) hello_world: verbose2
+E (371) hello_world: error2
+I (381) hello_world: info2
+noeol 0x400800000x40080000: _iram_start at /home/dragon/esp/esp-idf/components/freertos/xtensa_vectors.S:1685
+
+

tools/test_idf_monitor/tests/in1f2.txt

@@ -0,0 +1,10 @@
+D (311) hello_world: debug1
+W (311) hello_world: warning1
+V (321) hello_world: verbose1
+E (321) hello_world: error1
+I (321) hello_world: info1
+D (361) hello_world: debug2
+W (371) hello_world: warning2
+V (371) hello_world: verbose2
+E (371) hello_world: error2
+I (381) hello_world: info2

tools/test_idf_monitor/tests/in2f1.txt

@@ -0,0 +1,54 @@
+E (39) boot: Factory app partition is not bootable
+I (730) mdf_device_handle: [mdf_device_init_handle, 897]:******************* SYSTEM INFO *******************
+I (740) mdf_device_handle: [mdf_device_init_handle, 898]:idf version      : v3.1-dev-1188-g0c43ac8
+I (749) mdf_device_handle: [mdf_device_init_handle, 901]:device version   : light_004384-0.5.25.1-1
+I (759) mdf_device_handle: [mdf_device_init_handle, 902]:compile time     : May 26 2018 00:22:11
+I (769) mdf_device_handle: [mdf_device_init_handle, 903]:free heap        : 120948 B
+I (777) mdf_device_handle: [mdf_device_init_handle, 904]:CPU cores        : 2
+I (785) mdf_device_handle: [mdf_device_init_handle, 907]:function         : WiFi/BT/BLE
+I (793) mdf_device_handle: [mdf_device_init_handle, 908]:silicon revision : 0
+I (801) mdf_device_handle: [mdf_device_init_handle, 910]:flash            : 4 MB external
+I (810) mdf_device_handle: [mdf_device_init_handle, 911]:***************************************************
+E (45488) mesh: [esp_mesh_push_to_nwk_queue,5360] null args
+I (50495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 00:00:00:00:00:00, mac:30:ae:a4:00:43:84, layer: -1, free heap: 87712, compile time: May 26 2018 00:22:11
+I (55495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 88452, compile time: May 26 2018 00:22:11
+I (60495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 71792, compile time: May 26 2018 00:22:11
+I (65495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 71792, compile time: May 26 2018 00:22:11
+I (70495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 71680, compile time: May 26 2018 00:22:11
+I (75495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 71680, compile time: May 26 2018 00:22:11
+I (80495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 71680, compile time: May 26 2018 00:22:11
+I (85495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 71680, compile time: May 26 2018 00:22:11
+I (90495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 71680, compile time: May 26 2018 00:22:11
+I (95498) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 71468, compile time: May 26 2018 00:22:11
+I (100495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 71264, compile time: May 26 2018 00:22:11
+I (105495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 69120, compile time: May 26 2018 00:22:11
+I (110495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 64408, compile time: May 26 2018 00:22:11
+I (115495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 64408, compile time: May 26 2018 00:22:11
+I (120495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 64408, compile time: May 26 2018 00:22:11
+I (125495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 64408, compile time: May 26 2018 00:22:11
+I (130495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 64408, compile time: May 26 2018 00:22:11
+I (135495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 64408, compile time: May 26 2018 00:22:11
+I (140495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 62196, compile time: May 26 2018 00:22:11
+I (145495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 62196, compile time: May 26 2018 00:22:11
+I (150495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 62196, compile time: May 26 2018 00:22:11
+I (155495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 62196, compile time: May 26 2018 00:22:11
+I (160495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 62196, compile time: May 26 2018 00:22:11
+I (165495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 62196, compile time: May 26 2018 00:22:11
+I (170495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 60008, compile time: May 26 2018 00:22:11
+I (175495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 60008, compile time: May 26 2018 00:22:11
+I (180495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 60008, compile time: May 26 2018 00:22:11
+I (185495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 60008, compile time: May 26 2018 00:22:11
+I (190495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 60008, compile time: May 26 2018 00:22:11
+I (195495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 60008, compile time: May 26 2018 00:22:11
+I (200495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 60212, compile time: May 26 2018 00:22:11
+I (205495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 60212, compile time: May 26 2018 00:22:11
+I (210495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 60212, compile time: May 26 2018 00:22:11
+I (215495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 60212, compile time: May 26 2018 00:22:11
+I (220495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 60212, compile time: May 26 2018 00:22:11
+I (225495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 60212, compile time: May 26 2018 00:22:11
+I (230495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 60212, compile time: May 26 2018 00:22:11
+I (235495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 60212, compile time: May 26 2018 00:22:11
+I (240495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 60212, compile time: May 26 2018 00:22:11
+I (245495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 60212, compile time: May 26 2018 00:22:11
+I (250495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 60212, compile time: May 26 2018 00:22:11
+I (255495) mdf_device_handle: [mdf_show_sysinfo_timercb, 73]:parent: 20:a6:80:98:dc:b4, mac:30:ae:a4:00:43:84, layer: 1, free heap: 60212, compile time: May 26 2018 00:22:11

tools/test_idf_monitor/tests/in2f2.txt

@@ -0,0 +1,995 @@
+D (318) vfs: esp_vfs_register_fd_range is successful for range <54; 64) and VFS ID 1
+D (59342) vfs: esp_vfs_select starts with nfds = 55
+D (59343) vfs: FDs in readfds =
+D (59343) vfs: 54
+D (59355) vfs: calling socket_select with the following FDs
+D (59356) vfs: FDs in readfds =
+D (59356) vfs: 54
+D (59857) vfs: socket_select returned 0 and the FDs are the following
+D (59858) vfs: FDs in readfds =
+D (59858) vfs: esp_vfs_select returns 0
+D (59869) vfs: FDs in readfds =
+D (59870) vfs: esp_vfs_select starts with nfds = 55
+D (59871) vfs: FDs in readfds =
+D (59871) vfs: 54
+D (59882) vfs: calling socket_select with the following FDs
+D (59883) vfs: FDs in readfds =
+D (59884) vfs: 54
+D (60384) vfs: socket_select returned 0 and the FDs are the following
+D (60385) vfs: FDs in readfds =
+D (60385) vfs: esp_vfs_select returns 0
+D (60396) vfs: FDs in readfds =
+D (60397) vfs: esp_vfs_select starts with nfds = 55
+D (60397) vfs: FDs in readfds =
+D (60408) vfs: 54
+D (60409) vfs: calling socket_select with the following FDs
+D (60409) vfs: FDs in readfds =
+D (60410) vfs: 54
+D (60921) vfs: socket_select returned 0 and the FDs are the following
+D (60922) vfs: FDs in readfds =
+D (60922) vfs: esp_vfs_select returns 0
+D (60933) vfs: FDs in readfds =
+D (60934) vfs: esp_vfs_select starts with nfds = 55
+D (60935) vfs: FDs in readfds =
+D (60935) vfs: 54
+D (60946) vfs: calling socket_select with the following FDs
+D (60947) vfs: FDs in readfds =
+D (60947) vfs: 54
+D (61458) vfs: socket_select returned 0 and the FDs are the following
+D (61459) vfs: FDs in readfds =
+D (61460) vfs: esp_vfs_select returns 0
+D (61471) vfs: FDs in readfds =
+D (61471) vfs: esp_vfs_select starts with nfds = 55
+D (61472) vfs: FDs in readfds =
+D (61483) vfs: 54
+D (61483) vfs: calling socket_select with the following FDs
+D (61484) vfs: FDs in readfds =
+D (61484) vfs: 54
+D (61995) vfs: socket_select returned 0 and the FDs are the following
+D (61996) vfs: FDs in readfds =
+D (61996) vfs: esp_vfs_select returns 0
+D (62007) vfs: FDs in readfds =
+D (62008) vfs: esp_vfs_select starts with nfds = 55
+D (62008) vfs: FDs in readfds =
+D (62019) vfs: 54
+D (62021) vfs: calling socket_select with the following FDs
+D (62021) vfs: FDs in readfds =
+D (62022) vfs: 54
+D (62523) vfs: socket_select returned 0 and the FDs are the following
+D (62524) vfs: FDs in readfds =
+D (62524) vfs: esp_vfs_select returns 0
+D (62535) vfs: FDs in readfds =
+D (62536) vfs: esp_vfs_select starts with nfds = 55
+D (62536) vfs: FDs in readfds =
+D (62547) vfs: 54
+D (62548) vfs: calling socket_select with the following FDs
+D (62548) vfs: FDs in readfds =
+D (62549) vfs: 54
+D (63060) vfs: socket_select returned 0 and the FDs are the following
+D (63061) vfs: FDs in readfds =
+D (63061) vfs: esp_vfs_select returns 0
+D (63072) vfs: FDs in readfds =
+D (63073) vfs: esp_vfs_select starts with nfds = 55
+D (63073) vfs: FDs in readfds =
+D (63084) vfs: 54
+D (63085) vfs: calling socket_select with the following FDs
+D (63086) vfs: FDs in readfds =
+D (63086) vfs: 54
+D (63597) vfs: socket_select returned 0 and the FDs are the following
+D (63599) vfs: FDs in readfds =
+D (63599) vfs: esp_vfs_select returns 0
+D (63610) vfs: FDs in readfds =
+D (63611) vfs: esp_vfs_select starts with nfds = 55
+D (63611) vfs: FDs in readfds =
+D (63612) vfs: 54
+D (63623) vfs: calling socket_select with the following FDs
+D (63623) vfs: FDs in readfds =
+D (63624) vfs: 54
+D (64134) vfs: socket_select returned 0 and the FDs are the following
+D (64135) vfs: FDs in readfds =
+D (64135) vfs: esp_vfs_select returns 0
+D (64146) vfs: FDs in readfds =
+D (64147) vfs: esp_vfs_select starts with nfds = 55
+D (64147) vfs: FDs in readfds =
+D (64158) vfs: 54
+D (64159) vfs: calling socket_select with the following FDs
+D (64159) vfs: FDs in readfds =
+D (64160) vfs: 54
+D (64671) vfs: socket_select returned 0 and the FDs are the following
+D (64672) vfs: FDs in readfds =
+D (64674) vfs: esp_vfs_select returns 0
+D (64674) vfs: FDs in readfds =
+D (64685) vfs: esp_vfs_select starts with nfds = 55
+D (64686) vfs: FDs in readfds =
+D (64686) vfs: 54
+D (64697) vfs: calling socket_select with the following FDs
+D (64699) vfs: FDs in readfds =
+D (64699) vfs: 54
+D (65200) vfs: socket_select returned 0 and the FDs are the following
+D (65201) vfs: FDs in readfds =
+D (65201) vfs: esp_vfs_select returns 0
+D (65212) vfs: FDs in readfds =
+D (65213) vfs: esp_vfs_select starts with nfds = 55
+D (65213) vfs: FDs in readfds =
+D (65224) vfs: 54
+D (65225) vfs: calling socket_select with the following FDs
+D (65227) vfs: FDs in readfds =
+D (65227) vfs: 54
+D (65728) vfs: socket_select returned 0 and the FDs are the following
+D (65729) vfs: FDs in readfds =
+D (65729) vfs: esp_vfs_select returns 0
+D (65740) vfs: FDs in readfds =
+D (65741) vfs: esp_vfs_select starts with nfds = 55
+D (65741) vfs: FDs in readfds =
+D (65752) vfs: 54
+D (65753) vfs: calling socket_select with the following FDs
+D (65753) vfs: FDs in readfds =
+D (65754) vfs: 54
+D (66265) vfs: socket_select returned 0 and the FDs are the following
+D (66266) vfs: FDs in readfds =
+D (66266) vfs: esp_vfs_select returns 0
+D (66277) vfs: FDs in readfds =
+D (66278) vfs: esp_vfs_select starts with nfds = 55
+D (66278) vfs: FDs in readfds =
+D (66289) vfs: 54
+D (66290) vfs: calling socket_select with the following FDs
+D (66291) vfs: FDs in readfds =
+D (66291) vfs: 54
+D (66802) vfs: socket_select returned 0 and the FDs are the following
+D (66803) vfs: FDs in readfds =
+D (66803) vfs: esp_vfs_select returns 0
+D (66814) vfs: FDs in readfds =
+D (66815) vfs: esp_vfs_select starts with nfds = 55
+D (66816) vfs: FDs in readfds =
+D (66816) vfs: 54
+D (66827) vfs: calling socket_select with the following FDs
+D (66828) vfs: FDs in readfds =
+D (66828) vfs: 54
+D (67339) vfs: socket_select returned 0 and the FDs are the following
+D (67340) vfs: FDs in readfds =
+D (67340) vfs: esp_vfs_select returns 0
+D (67351) vfs: FDs in readfds =
+D (67352) vfs: esp_vfs_select starts with nfds = 55
+D (67353) vfs: FDs in readfds =
+D (67353) vfs: 54
+D (67364) vfs: calling socket_select with the following FDs
+D (67365) vfs: FDs in readfds =
+D (67365) vfs: 54
+D (67877) vfs: socket_select returned 0 and the FDs are the following
+D (67878) vfs: FDs in readfds =
+D (67879) vfs: esp_vfs_select returns 0
+D (67889) vfs: FDs in readfds =
+D (67890) vfs: esp_vfs_select starts with nfds = 55
+D (67891) vfs: FDs in readfds =
+D (67901) vfs: 54
+D (67902) vfs: calling socket_select with the following FDs
+D (67903) vfs: FDs in readfds =
+D (67903) vfs: 54
+D (68414) vfs: socket_select returned 0 and the FDs are the following
+D (68415) vfs: FDs in readfds =
+D (68415) vfs: esp_vfs_select returns 0
+D (68417) vfs: FDs in readfds =
+D (68428) vfs: esp_vfs_select starts with nfds = 55
+D (68428) vfs: FDs in readfds =
+D (68429) vfs: 54
+D (68440) vfs: calling socket_select with the following FDs
+D (68440) vfs: FDs in readfds =
+D (68441) vfs: 54
+D (68941) vfs: socket_select returned 0 and the FDs are the following
+D (68942) vfs: FDs in readfds =
+D (68942) vfs: esp_vfs_select returns 0
+D (68953) vfs: FDs in readfds =
+D (68954) vfs: esp_vfs_select starts with nfds = 55
+D (68954) vfs: FDs in readfds =
+D (68965) vfs: 54
+D (68966) vfs: calling socket_select with the following FDs
+D (68966) vfs: FDs in readfds =
+D (68967) vfs: 54
+D (69478) vfs: socket_select returned 0 and the FDs are the following
+D (69479) vfs: FDs in readfds =
+D (69480) vfs: esp_vfs_select returns 0
+D (69490) vfs: FDs in readfds =
+D (69491) vfs: esp_vfs_select starts with nfds = 55
+D (69492) vfs: FDs in readfds =
+D (69492) vfs: 54
+D (69503) vfs: calling socket_select with the following FDs
+D (69504) vfs: FDs in readfds =
+D (69504) vfs: 54
+D (70015) vfs: socket_select returned 0 and the FDs are the following
+D (70016) vfs: FDs in readfds =
+D (70016) vfs: esp_vfs_select returns 0
+D (70027) vfs: FDs in readfds =
+D (70028) vfs: esp_vfs_select starts with nfds = 55
+D (70028) vfs: FDs in readfds =
+D (70040) vfs: 54
+D (70041) vfs: calling socket_select with the following FDs
+D (70042) vfs: FDs in readfds =
+D (70042) vfs: 54
+D (70553) vfs: socket_select returned 0 and the FDs are the following
+D (70554) vfs: FDs in readfds =
+D (70555) vfs: esp_vfs_select returns 0
+D (70566) vfs: FDs in readfds =
+D (70566) vfs: esp_vfs_select starts with nfds = 55
+D (70567) vfs: FDs in readfds =
+D (70578) vfs: 54
+D (70578) vfs: calling socket_select with the following FDs
+D (70579) vfs: FDs in readfds =
+D (70590) vfs: 54
+D (71091) vfs: socket_select returned 0 and the FDs are the following
+D (71092) vfs: FDs in readfds =
+D (71092) vfs: esp_vfs_select returns 0
+D (71103) vfs: FDs in readfds =
+D (71104) vfs: esp_vfs_select starts with nfds = 55
+D (71104) vfs: FDs in readfds =
+D (71115) vfs: 54
+D (71116) vfs: calling socket_select with the following FDs
+D (71116) vfs: FDs in readfds =
+D (71117) vfs: 54
+D (71628) vfs: socket_select returned 0 and the FDs are the following
+D (71629) vfs: FDs in readfds =
+D (71630) vfs: esp_vfs_select returns 0
+D (71640) vfs: FDs in readfds =
+D (71641) vfs: esp_vfs_select starts with nfds = 55
+D (71642) vfs: FDs in readfds =
+D (71642) vfs: 54
+D (71653) vfs: calling socket_select with the following FDs
+D (71654) vfs: FDs in readfds =
+D (71654) vfs: 54
+D (72165) vfs: socket_select returned 0 and the FDs are the following
+D (72166) vfs: FDs in readfds =
+D (72166) vfs: esp_vfs_select returns 0
+D (72177) vfs: FDs in readfds =
+D (72178) vfs: esp_vfs_select starts with nfds = 55
+D (72178) vfs: FDs in readfds =
+D (72190) vfs: 54
+D (72191) vfs: calling socket_select with the following FDs
+D (72191) vfs: FDs in readfds =
+D (72192) vfs: 54
+D (72692) vfs: socket_select returned 0 and the FDs are the following
+D (72693) vfs: FDs in readfds =
+D (72693) vfs: esp_vfs_select returns 0
+D (72704) vfs: FDs in readfds =
+D (72705) vfs: esp_vfs_select starts with nfds = 55
+D (72705) vfs: FDs in readfds =
+D (72716) vfs: 54
+D (72717) vfs: calling socket_select with the following FDs
+D (72718) vfs: FDs in readfds =
+D (72718) vfs: 54
+D (73229) vfs: socket_select returned 0 and the FDs are the following
+D (73231) vfs: FDs in readfds =
+D (73231) vfs: esp_vfs_select returns 0
+D (73242) vfs: FDs in readfds =
+D (73243) vfs: esp_vfs_select starts with nfds = 55
+D (73243) vfs: FDs in readfds =
+D (73254) vfs: 54
+D (73255) vfs: calling socket_select with the following FDs
+D (73255) vfs: FDs in readfds =
+D (73266) vfs: 54
+D (73767) vfs: socket_select returned 0 and the FDs are the following
+D (73768) vfs: FDs in readfds =
+D (73768) vfs: esp_vfs_select returns 0
+D (73779) vfs: FDs in readfds =
+D (73780) vfs: esp_vfs_select starts with nfds = 55
+D (73780) vfs: FDs in readfds =
+D (73791) vfs: 54
+D (73792) vfs: calling socket_select with the following FDs
+D (73793) vfs: FDs in readfds =
+D (73793) vfs: 54
+D (74304) vfs: socket_select returned 0 and the FDs are the following
+D (74305) vfs: FDs in readfds =
+D (74305) vfs: esp_vfs_select returns 0
+D (74316) vfs: FDs in readfds =
+D (74317) vfs: esp_vfs_select starts with nfds = 55
+D (74317) vfs: FDs in readfds =
+D (74328) vfs: 54
+D (74329) vfs: calling socket_select with the following FDs
+D (74330) vfs: FDs in readfds =
+D (74330) vfs: 54
+D (74841) vfs: socket_select returned 0 and the FDs are the following
+D (74842) vfs: FDs in readfds =
+D (74842) vfs: esp_vfs_select returns 0
+D (74853) vfs: FDs in readfds =
+D (74854) vfs: esp_vfs_select starts with nfds = 55
+D (74854) vfs: FDs in readfds =
+D (74865) vfs: 54
+D (74866) vfs: calling socket_select with the following FDs
+D (74867) vfs: FDs in readfds =
+D (74868) vfs: 54
+D (75368) vfs: socket_select returned 0 and the FDs are the following
+D (75369) vfs: FDs in readfds =
+D (75369) vfs: esp_vfs_select returns 0
+D (75380) vfs: FDs in readfds =
+D (75381) vfs: esp_vfs_select starts with nfds = 55
+D (75381) vfs: FDs in readfds =
+D (75392) vfs: 54
+D (75393) vfs: calling socket_select with the following FDs
+D (75394) vfs: FDs in readfds =
+D (75394) vfs: 54
+D (75905) vfs: socket_select returned 0 and the FDs are the following
+D (75906) vfs: FDs in readfds =
+D (75906) vfs: esp_vfs_select returns 0
+D (75917) vfs: FDs in readfds =
+D (75918) vfs: esp_vfs_select starts with nfds = 55
+D (75918) vfs: FDs in readfds =
+D (75929) vfs: 54
+D (75930) vfs: calling socket_select with the following FDs
+D (75931) vfs: FDs in readfds =
+D (75931) vfs: 54
+D (76442) vfs: socket_select returned 0 and the FDs are the following
+D (76443) vfs: FDs in readfds =
+D (76444) vfs: esp_vfs_select returns 0
+D (76454) vfs: FDs in readfds =
+D (76455) vfs: esp_vfs_select starts with nfds = 55
+D (76456) vfs: FDs in readfds =
+D (76456) vfs: 54
+D (76467) vfs: calling socket_select with the following FDs
+D (76468) vfs: FDs in readfds =
+D (76468) vfs: 54
+D (76979) vfs: socket_select returned 0 and the FDs are the following
+D (76980) vfs: FDs in readfds =
+D (76980) vfs: esp_vfs_select returns 0
+D (76991) vfs: FDs in readfds =
+D (76992) vfs: esp_vfs_select starts with nfds = 55
+D (76992) vfs: FDs in readfds =
+D (77003) vfs: 54
+D (77004) vfs: calling socket_select with the following FDs
+D (77004) vfs: FDs in readfds =
+D (77005) vfs: 54
+D (77516) vfs: socket_select returned 0 and the FDs are the following
+D (77517) vfs: FDs in readfds =
+D (77517) vfs: esp_vfs_select returns 0
+D (77528) vfs: FDs in readfds =
+D (77529) vfs: esp_vfs_select starts with nfds = 55
+D (77529) vfs: FDs in readfds =
+D (77540) vfs: 54
+D (77541) vfs: calling socket_select with the following FDs
+D (77542) vfs: FDs in readfds =
+D (77542) vfs: 54
+D (78053) vfs: socket_select returned 0 and the FDs are the following
+D (78054) vfs: FDs in readfds =
+D (78054) vfs: esp_vfs_select returns 0
+D (78065) vfs: FDs in readfds =
+D (78066) vfs: esp_vfs_select starts with nfds = 55
+D (78066) vfs: FDs in readfds =
+D (78077) vfs: 54
+D (78078) vfs: calling socket_select with the following FDs
+D (78078) vfs: FDs in readfds =
+D (78079) vfs: 54
+D (78590) vfs: socket_select returned 0 and the FDs are the following
+D (78591) vfs: FDs in readfds =
+D (78591) vfs: esp_vfs_select returns 0
+D (78602) vfs: FDs in readfds =
+D (78603) vfs: esp_vfs_select starts with nfds = 55
+D (78604) vfs: FDs in readfds =
+D (78604) vfs: 54
+D (78615) vfs: calling socket_select with the following FDs
+D (78616) vfs: FDs in readfds =
+D (78616) vfs: 54
+D (79127) vfs: socket_select returned 0 and the FDs are the following
+D (79128) vfs: FDs in readfds =
+D (79128) vfs: esp_vfs_select returns 0
+D (79139) vfs: FDs in readfds =
+D (79140) vfs: esp_vfs_select starts with nfds = 55
+D (79140) vfs: FDs in readfds =
+D (79151) vfs: 54
+D (79152) vfs: calling socket_select with the following FDs
+D (79152) vfs: FDs in readfds =
+D (79153) vfs: 54
+D (79664) vfs: socket_select returned 0 and the FDs are the following
+D (79665) vfs: FDs in readfds =
+D (79665) vfs: esp_vfs_select returns 0
+D (79676) vfs: FDs in readfds =
+D (79677) vfs: esp_vfs_select starts with nfds = 55
+D (79677) vfs: FDs in readfds =
+D (79688) vfs: 54
+D (79689) vfs: calling socket_select with the following FDs
+D (79689) vfs: FDs in readfds =
+D (79690) vfs: 54
+D (80201) vfs: socket_select returned 0 and the FDs are the following
+D (80202) vfs: FDs in readfds =
+D (80202) vfs: esp_vfs_select returns 0
+D (80213) vfs: FDs in readfds =
+D (80214) vfs: esp_vfs_select starts with nfds = 55
+D (80214) vfs: FDs in readfds =
+D (80225) vfs: 54
+D (80226) vfs: calling socket_select with the following FDs
+D (80226) vfs: FDs in readfds =
+D (80227) vfs: 54
+D (80738) vfs: socket_select returned 0 and the FDs are the following
+D (80739) vfs: FDs in readfds =
+D (80739) vfs: esp_vfs_select returns 0
+D (80750) vfs: FDs in readfds =
+D (80751) vfs: esp_vfs_select starts with nfds = 55
+D (80751) vfs: FDs in readfds =
+D (80752) vfs: 54
+D (80763) vfs: calling socket_select with the following FDs
+D (80763) vfs: FDs in readfds =
+D (80764) vfs: 54
+D (81275) vfs: socket_select returned 0 and the FDs are the following
+D (81276) vfs: FDs in readfds =
+D (81276) vfs: esp_vfs_select returns 0
+D (81287) vfs: FDs in readfds =
+D (81288) vfs: esp_vfs_select starts with nfds = 55
+D (81288) vfs: FDs in readfds =
+D (81299) vfs: 54
+D (81300) vfs: calling socket_select with the following FDs
+D (81301) vfs: FDs in readfds =
+D (81301) vfs: 54
+D (81812) vfs: socket_select returned 0 and the FDs are the following
+D (81813) vfs: FDs in readfds =
+D (81813) vfs: esp_vfs_select returns 0
+D (81824) vfs: FDs in readfds =
+D (81825) vfs: esp_vfs_select starts with nfds = 55
+D (81825) vfs: FDs in readfds =
+D (81836) vfs: 54
+D (81837) vfs: calling socket_select with the following FDs
+D (81837) vfs: FDs in readfds =
+D (81838) vfs: 54
+D (82349) vfs: socket_select returned 0 and the FDs are the following
+D (82350) vfs: FDs in readfds =
+D (82350) vfs: esp_vfs_select returns 0
+D (82361) vfs: FDs in readfds =
+D (82362) vfs: esp_vfs_select starts with nfds = 55
+D (82362) vfs: FDs in readfds =
+D (82373) vfs: 54
+D (82374) vfs: calling socket_select with the following FDs
+D (82374) vfs: FDs in readfds =
+D (82375) vfs: 54
+D (82886) vfs: socket_select returned 0 and the FDs are the following
+D (82887) vfs: FDs in readfds =
+D (82887) vfs: esp_vfs_select returns 0
+D (82898) vfs: FDs in readfds =
+D (82899) vfs: esp_vfs_select starts with nfds = 55
+D (82899) vfs: FDs in readfds =
+D (82910) vfs: 54
+D (82911) vfs: calling socket_select with the following FDs
+D (82912) vfs: FDs in readfds =
+D (82912) vfs: 54
+D (83423) vfs: socket_select returned 0 and the FDs are the following
+D (83424) vfs: FDs in readfds =
+D (83424) vfs: esp_vfs_select returns 0
+D (83435) vfs: FDs in readfds =
+D (83436) vfs: esp_vfs_select starts with nfds = 55
+D (83436) vfs: FDs in readfds =
+D (83447) vfs: 54
+D (83448) vfs: calling socket_select with the following FDs
+D (83449) vfs: FDs in readfds =
+D (83449) vfs: 54
+D (83960) vfs: socket_select returned 0 and the FDs are the following
+D (83961) vfs: FDs in readfds =
+D (83961) vfs: esp_vfs_select returns 0
+D (83972) vfs: FDs in readfds =
+D (83973) vfs: esp_vfs_select starts with nfds = 55
+D (83973) vfs: FDs in readfds =
+D (83984) vfs: 54
+D (83985) vfs: calling socket_select with the following FDs
+D (83986) vfs: FDs in readfds =
+D (83986) vfs: 54
+D (84497) vfs: socket_select returned 0 and the FDs are the following
+D (84498) vfs: FDs in readfds =
+D (84498) vfs: esp_vfs_select returns 0
+D (84509) vfs: FDs in readfds =
+D (84510) vfs: esp_vfs_select starts with nfds = 55
+D (84511) vfs: FDs in readfds =
+D (84521) vfs: 54
+D (84522) vfs: calling socket_select with the following FDs
+D (84523) vfs: FDs in readfds =
+D (84523) vfs: 54
+D (85035) vfs: socket_select returned 0 and the FDs are the following
+D (85047) vfs: FDs in readfds =
+D (85048) vfs: esp_vfs_select returns 0
+D (85059) vfs: FDs in readfds =
+D (85059) vfs: esp_vfs_select starts with nfds = 55
+D (85060) vfs: FDs in readfds =
+D (85071) vfs: 54
+D (85071) vfs: calling socket_select with the following FDs
+D (85072) vfs: FDs in readfds =
+D (85083) vfs: 54
+D (85583) vfs: socket_select returned 0 and the FDs are the following
+D (85584) vfs: FDs in readfds =
+D (85584) vfs: esp_vfs_select returns 0
+D (85595) vfs: FDs in readfds =
+D (85596) vfs: esp_vfs_select starts with nfds = 55
+D (85596) vfs: FDs in readfds =
+D (85607) vfs: 54
+D (85608) vfs: calling socket_select with the following FDs
+D (85608) vfs: FDs in readfds =
+D (85609) vfs: 54
+D (86120) vfs: socket_select returned 0 and the FDs are the following
+D (86121) vfs: FDs in readfds =
+D (86121) vfs: esp_vfs_select returns 0
+D (86132) vfs: FDs in readfds =
+D (86133) vfs: esp_vfs_select starts with nfds = 55
+D (86133) vfs: FDs in readfds =
+D (86144) vfs: 54
+D (86145) vfs: calling socket_select with the following FDs
+D (86145) vfs: FDs in readfds =
+D (86146) vfs: 54
+D (86657) vfs: socket_select returned 0 and the FDs are the following
+D (86658) vfs: FDs in readfds =
+D (86658) vfs: esp_vfs_select returns 0
+D (86669) vfs: FDs in readfds =
+D (86670) vfs: esp_vfs_select starts with nfds = 55
+D (86670) vfs: FDs in readfds =
+D (86681) vfs: 54
+D (86682) vfs: calling socket_select with the following FDs
+D (86683) vfs: FDs in readfds =
+D (86683) vfs: 54
+D (87194) vfs: socket_select returned 0 and the FDs are the following
+D (87195) vfs: FDs in readfds =
+D (87196) vfs: esp_vfs_select returns 0
+D (87206) vfs: FDs in readfds =
+D (87207) vfs: esp_vfs_select starts with nfds = 55
+D (87208) vfs: FDs in readfds =
+D (87208) vfs: 54
+D (87219) vfs: calling socket_select with the following FDs
+D (87220) vfs: FDs in readfds =
+D (87220) vfs: 54
+D (87731) vfs: socket_select returned 0 and the FDs are the following
+D (87732) vfs: FDs in readfds =
+D (87732) vfs: esp_vfs_select returns 0
+D (87743) vfs: FDs in readfds =
+D (87744) vfs: esp_vfs_select starts with nfds = 55
+D (87744) vfs: FDs in readfds =
+D (87755) vfs: 54
+D (87756) vfs: calling socket_select with the following FDs
+D (87757) vfs: FDs in readfds =
+D (87757) vfs: 54
+D (88268) vfs: socket_select returned 0 and the FDs are the following
+D (88269) vfs: FDs in readfds =
+D (88269) vfs: esp_vfs_select returns 0
+D (88281) vfs: FDs in readfds =
+D (88281) vfs: esp_vfs_select starts with nfds = 55
+D (88282) vfs: FDs in readfds =
+D (88283) vfs: 54
+D (88294) vfs: calling socket_select with the following FDs
+D (88294) vfs: FDs in readfds =
+D (88295) vfs: 54
+D (88795) vfs: socket_select returned 0 and the FDs are the following
+D (88796) vfs: FDs in readfds =
+D (88796) vfs: esp_vfs_select returns 0
+D (88807) vfs: FDs in readfds =
+D (88808) vfs: esp_vfs_select starts with nfds = 55
+D (88809) vfs: FDs in readfds =
+D (88819) vfs: 54
+D (88820) vfs: calling socket_select with the following FDs
+D (88821) vfs: FDs in readfds =
+D (88821) vfs: 54
+D (89332) vfs: socket_select returned 0 and the FDs are the following
+D (89333) vfs: FDs in readfds =
+D (89333) vfs: esp_vfs_select returns 0
+D (89344) vfs: FDs in readfds =
+D (89345) vfs: esp_vfs_select starts with nfds = 55
+D (89346) vfs: FDs in readfds =
+D (89346) vfs: 54
+D (89357) vfs: calling socket_select with the following FDs
+D (89358) vfs: FDs in readfds =
+D (89358) vfs: 54
+D (89869) vfs: socket_select returned 0 and the FDs are the following
+D (89870) vfs: FDs in readfds =
+D (89871) vfs: esp_vfs_select returns 0
+D (89881) vfs: FDs in readfds =
+D (89882) vfs: esp_vfs_select starts with nfds = 55
+D (89883) vfs: FDs in readfds =
+D (89893) vfs: 54
+D (89894) vfs: calling socket_select with the following FDs
+D (89895) vfs: FDs in readfds =
+D (89895) vfs: 54
+D (90406) vfs: socket_select returned 0 and the FDs are the following
+D (90407) vfs: FDs in readfds =
+D (90407) vfs: esp_vfs_select returns 0
+D (90418) vfs: FDs in readfds =
+D (90419) vfs: esp_vfs_select starts with nfds = 55
+D (90419) vfs: FDs in readfds =
+D (90430) vfs: 54
+D (90432) vfs: calling socket_select with the following FDs
+D (90432) vfs: FDs in readfds =
+D (90433) vfs: 54
+D (90933) vfs: socket_select returned 0 and the FDs are the following
+D (90934) vfs: FDs in readfds =
+D (90935) vfs: esp_vfs_select returns 0
+D (90945) vfs: FDs in readfds =
+D (90946) vfs: esp_vfs_select starts with nfds = 55
+D (90947) vfs: FDs in readfds =
+D (90957) vfs: 54
+D (90958) vfs: calling socket_select with the following FDs
+D (90959) vfs: FDs in readfds =
+D (90959) vfs: 54
+D (91470) vfs: socket_select returned 0 and the FDs are the following
+D (91471) vfs: FDs in readfds =
+D (91471) vfs: esp_vfs_select returns 0
+D (91482) vfs: FDs in readfds =
+D (91483) vfs: esp_vfs_select starts with nfds = 55
+D (91483) vfs: FDs in readfds =
+D (91494) vfs: 54
+D (91495) vfs: calling socket_select with the following FDs
+D (91496) vfs: FDs in readfds =
+D (91496) vfs: 54
+D (92007) vfs: socket_select returned 0 and the FDs are the following
+D (92009) vfs: FDs in readfds =
+D (92009) vfs: esp_vfs_select returns 0
+D (92010) vfs: FDs in readfds =
+D (92021) vfs: esp_vfs_select starts with nfds = 55
+D (92022) vfs: FDs in readfds =
+D (92022) vfs: 54
+D (92033) vfs: calling socket_select with the following FDs
+D (92034) vfs: FDs in readfds =
+D (92034) vfs: 54
+D (92535) vfs: socket_select returned 0 and the FDs are the following
+D (92536) vfs: FDs in readfds =
+D (92536) vfs: esp_vfs_select returns 0
+D (92547) vfs: FDs in readfds =
+D (92548) vfs: esp_vfs_select starts with nfds = 55
+D (92548) vfs: FDs in readfds =
+D (92559) vfs: 54
+D (92560) vfs: calling socket_select with the following FDs
+D (92560) vfs: FDs in readfds =
+D (92561) vfs: 54
+D (93072) vfs: socket_select returned 0 and the FDs are the following
+D (93073) vfs: FDs in readfds =
+D (93073) vfs: esp_vfs_select returns 0
+D (93084) vfs: FDs in readfds =
+D (93085) vfs: esp_vfs_select starts with nfds = 55
+D (93085) vfs: FDs in readfds =
+D (93096) vfs: 54
+D (93097) vfs: calling socket_select with the following FDs
+D (93098) vfs: FDs in readfds =
+D (93098) vfs: 54
+D (93609) vfs: socket_select returned 0 and the FDs are the following
+D (93610) vfs: FDs in readfds =
+D (93610) vfs: esp_vfs_select returns 0
+D (93621) vfs: FDs in readfds =
+D (93622) vfs: esp_vfs_select starts with nfds = 55
+D (93622) vfs: FDs in readfds =
+D (93633) vfs: 54
+D (93634) vfs: calling socket_select with the following FDs
+D (93634) vfs: FDs in readfds =
+D (93635) vfs: 54
+D (94146) vfs: socket_select returned 0 and the FDs are the following
+D (94147) vfs: FDs in readfds =
+D (94147) vfs: esp_vfs_select returns 0
+D (94158) vfs: FDs in readfds =
+D (94159) vfs: esp_vfs_select starts with nfds = 55
+D (94160) vfs: FDs in readfds =
+D (94160) vfs: 54
+D (94171) vfs: calling socket_select with the following FDs
+D (94172) vfs: FDs in readfds =
+D (94172) vfs: 54
+D (94683) vfs: socket_select returned 0 and the FDs are the following
+D (94684) vfs: FDs in readfds =
+D (94684) vfs: esp_vfs_select returns 0
+D (94695) vfs: FDs in readfds =
+D (94696) vfs: esp_vfs_select starts with nfds = 55
+D (94696) vfs: FDs in readfds =
+D (94707) vfs: 54
+D (94708) vfs: calling socket_select with the following FDs
+D (94708) vfs: FDs in readfds =
+D (94709) vfs: 54
+D (95220) vfs: socket_select returned 0 and the FDs are the following
+D (95221) vfs: FDs in readfds =
+D (95221) vfs: esp_vfs_select returns 0
+D (95232) vfs: FDs in readfds =
+D (95233) vfs: esp_vfs_select starts with nfds = 55
+D (95234) vfs: FDs in readfds =
+D (95234) vfs: 54
+D (95245) vfs: calling socket_select with the following FDs
+D (95246) vfs: FDs in readfds =
+D (95246) vfs: 54
+D (95757) vfs: socket_select returned 0 and the FDs are the following
+D (95758) vfs: FDs in readfds =
+D (95758) vfs: esp_vfs_select returns 0
+D (95769) vfs: FDs in readfds =
+D (95770) vfs: esp_vfs_select starts with nfds = 55
+D (95770) vfs: FDs in readfds =
+D (95781) vfs: 54
+D (95782) vfs: calling socket_select with the following FDs
+D (95782) vfs: FDs in readfds =
+D (95783) vfs: 54
+D (96049) vfs: socket_select returned 1 and the FDs are the following
+D (96051) vfs: FDs in readfds =
+D (96051) vfs: 54
+D (96051) vfs: esp_vfs_select returns 1
+D (96063) vfs: FDs in readfds =
+D (96063) vfs: 54
+D (96077) vfs: esp_vfs_select starts with nfds = 58
+D (96078) vfs: FDs in readfds =
+D (96088) vfs: 54
+D (96088) vfs: 57
+D (96089) vfs: calling socket_select with the following FDs
+D (96090) vfs: FDs in readfds =
+D (96101) vfs: 54
+D (96102) vfs: 57
+D (96102) vfs: socket_select returned 1 and the FDs are the following
+D (96103) vfs: FDs in readfds =
+D (96113) vfs: 57
+D (96114) vfs: esp_vfs_select returns 1
+D (96114) vfs: FDs in readfds =
+D (96115) vfs: 57
+D (96179) vfs: esp_vfs_select starts with nfds = 58
+D (96180) vfs: FDs in readfds =
+D (96181) vfs: 54
+D (96181) vfs: 57
+D (96192) vfs: calling socket_select with the following FDs
+D (96194) vfs: FDs in readfds =
+D (96194) vfs: 54
+D (96194) vfs: 57
+D (96583) vfs: socket_select returned 1 and the FDs are the following
+D (96584) vfs: FDs in readfds =
+D (96585) vfs: 57
+D (96585) vfs: esp_vfs_select returns 1
+D (96596) vfs: FDs in readfds =
+D (96596) vfs: 57
+D (96624) vfs: esp_vfs_select starts with nfds = 55
+D (96626) vfs: FDs in readfds =
+D (96626) vfs: 54
+D (96627) vfs: calling socket_select with the following FDs
+D (96638) vfs: FDs in readfds =
+D (96638) vfs: 54
+D (96986) vfs: socket_select returned 1 and the FDs are the following
+D (96988) vfs: FDs in readfds =
+D (96988) vfs: 54
+D (96989) vfs: esp_vfs_select returns 1
+D (96999) vfs: FDs in readfds =
+D (97000) vfs: 54
+D (97013) vfs: esp_vfs_select starts with nfds = 59
+D (97014) vfs: FDs in readfds =
+D (97025) vfs: 54
+D (97025) vfs: 58
+D (97026) vfs: calling socket_select with the following FDs
+D (97026) vfs: FDs in readfds =
+D (97037) vfs: 54
+D (97038) vfs: 58
+D (97038) vfs: socket_select returned 1 and the FDs are the following
+D (97049) vfs: FDs in readfds =
+D (97051) vfs: 58
+D (97051) vfs: esp_vfs_select returns 1
+D (97052) vfs: FDs in readfds =
+D (97052) vfs: 58
+D (97207) vfs: esp_vfs_select starts with nfds = 59
+D (97218) vfs: FDs in readfds =
+D (97219) vfs: 54
+D (97219) vfs: 58
+D (97220) vfs: calling socket_select with the following FDs
+D (97231) vfs: FDs in readfds =
+D (97232) vfs: 54
+D (97232) vfs: 58
+D (97356) vfs: socket_select returned 1 and the FDs are the following
+D (97358) vfs: FDs in readfds =
+D (97358) vfs: 58
+D (97358) vfs: esp_vfs_select returns 1
+D (97369) vfs: FDs in readfds =
+D (97370) vfs: 58
+D (97397) vfs: esp_vfs_select starts with nfds = 55
+D (97398) vfs: FDs in readfds =
+D (97398) vfs: 54
+D (97399) vfs: calling socket_select with the following FDs
+D (97410) vfs: FDs in readfds =
+D (97410) vfs: 54
+D (97911) vfs: socket_select returned 0 and the FDs are the following
+D (97912) vfs: FDs in readfds =
+D (97912) vfs: esp_vfs_select returns 0
+D (97923) vfs: FDs in readfds =
+D (97924) vfs: esp_vfs_select starts with nfds = 55
+D (97925) vfs: FDs in readfds =
+D (97925) vfs: 54
+D (97936) vfs: calling socket_select with the following FDs
+D (97937) vfs: FDs in readfds =
+D (97937) vfs: 54
+D (98448) vfs: socket_select returned 0 and the FDs are the following
+D (98449) vfs: FDs in readfds =
+D (98449) vfs: esp_vfs_select returns 0
+D (98460) vfs: FDs in readfds =
+D (98461) vfs: esp_vfs_select starts with nfds = 55
+D (98462) vfs: FDs in readfds =
+D (98472) vfs: 54
+D (98473) vfs: calling socket_select with the following FDs
+D (98474) vfs: FDs in readfds =
+D (98474) vfs: 54
+D (98985) vfs: socket_select returned 0 and the FDs are the following
+D (98986) vfs: FDs in readfds =
+D (98986) vfs: esp_vfs_select returns 0
+D (98997) vfs: FDs in readfds =
+D (98998) vfs: esp_vfs_select starts with nfds = 55
+D (98998) vfs: FDs in readfds =
+D (99009) vfs: 54
+D (99010) vfs: calling socket_select with the following FDs
+D (99011) vfs: FDs in readfds =
+D (99011) vfs: 54
+D (99522) vfs: socket_select returned 0 and the FDs are the following
+D (99523) vfs: FDs in readfds =
+D (99523) vfs: esp_vfs_select returns 0
+D (99534) vfs: FDs in readfds =
+D (99535) vfs: esp_vfs_select starts with nfds = 55
+D (99536) vfs: FDs in readfds =
+D (99536) vfs: 54
+D (99547) vfs: calling socket_select with the following FDs
+D (99548) vfs: FDs in readfds =
+D (99548) vfs: 54
+D (100059) vfs: socket_select returned 0 and the FDs are the following
+D (100060) vfs: FDs in readfds =
+D (100060) vfs: esp_vfs_select returns 0
+D (100071) vfs: FDs in readfds =
+D (100072) vfs: esp_vfs_select starts with nfds = 55
+D (100072) vfs: FDs in readfds =
+D (100083) vfs: 54
+D (100084) vfs: calling socket_select with the following FDs
+D (100085) vfs: FDs in readfds =
+D (100085) vfs: 54
+D (100596) vfs: socket_select returned 0 and the FDs are the following
+D (100597) vfs: FDs in readfds =
+D (100597) vfs: esp_vfs_select returns 0
+D (100608) vfs: FDs in readfds =
+D (100610) vfs: esp_vfs_select starts with nfds = 55
+D (100610) vfs: FDs in readfds =
+D (100611) vfs: 54
+D (100622) vfs: calling socket_select with the following FDs
+D (100623) vfs: FDs in readfds =
+D (100623) vfs: 54
+D (101124) vfs: socket_select returned 0 and the FDs are the following
+D (101125) vfs: FDs in readfds =
+D (101126) vfs: esp_vfs_select returns 0
+D (101137) vfs: FDs in readfds =
+D (101137) vfs: esp_vfs_select starts with nfds = 55
+D (101138) vfs: FDs in readfds =
+D (101149) vfs: 54
+D (101149) vfs: calling socket_select with the following FDs
+D (101150) vfs: FDs in readfds =
+D (101151) vfs: 54
+D (101661) vfs: socket_select returned 0 and the FDs are the following
+D (101662) vfs: FDs in readfds =
+D (101662) vfs: esp_vfs_select returns 0
+D (101673) vfs: FDs in readfds =
+D (101674) vfs: esp_vfs_select starts with nfds = 55
+D (101674) vfs: FDs in readfds =
+D (101685) vfs: 54
+D (101686) vfs: calling socket_select with the following FDs
+D (101687) vfs: FDs in readfds =
+D (101687) vfs: 54
+D (102198) vfs: socket_select returned 0 and the FDs are the following
+D (102199) vfs: FDs in readfds =
+D (102200) vfs: esp_vfs_select returns 0
+D (102210) vfs: FDs in readfds =
+D (102211) vfs: esp_vfs_select starts with nfds = 55
+D (102212) vfs: FDs in readfds =
+D (102222) vfs: 54
+D (102223) vfs: calling socket_select with the following FDs
+D (102224) vfs: FDs in readfds =
+D (102224) vfs: 54
+D (102735) vfs: socket_select returned 0 and the FDs are the following
+D (102736) vfs: FDs in readfds =
+D (102736) vfs: esp_vfs_select returns 0
+D (102747) vfs: FDs in readfds =
+D (102748) vfs: esp_vfs_select starts with nfds = 55
+D (102749) vfs: FDs in readfds =
+D (102750) vfs: 54
+D (102761) vfs: calling socket_select with the following FDs
+D (102761) vfs: FDs in readfds =
+D (102762) vfs: 54
+D (103207) vfs: socket_select returned 1 and the FDs are the following
+D (103208) vfs: FDs in readfds =
+D (103208) vfs: 54
+D (103209) vfs: esp_vfs_select returns 1
+D (103220) vfs: FDs in readfds =
+D (103220) vfs: 54
+D (103234) vfs: esp_vfs_select starts with nfds = 60
+D (103235) vfs: FDs in readfds =
+D (103246) vfs: 54
+D (103246) vfs: 59
+D (103247) vfs: calling socket_select with the following FDs
+D (103248) vfs: FDs in readfds =
+D (103259) vfs: 54
+D (103259) vfs: 59
+D (103259) vfs: socket_select returned 1 and the FDs are the following
+D (103271) vfs: FDs in readfds =
+D (103271) vfs: 59
+D (103272) vfs: esp_vfs_select returns 1
+D (103272) vfs: FDs in readfds =
+D (103283) vfs: 59
+D (103336) vfs: esp_vfs_select starts with nfds = 60
+D (103336) vfs: FDs in readfds =
+D (103337) vfs: 54
+D (103347) vfs: 59
+D (103348) vfs: calling socket_select with the following FDs
+D (103349) vfs: FDs in readfds =
+D (103349) vfs: 54
+D (103360) vfs: 59
+D (103860) vfs: socket_select returned 0 and the FDs are the following
+D (103861) vfs: FDs in readfds =
+D (103861) vfs: esp_vfs_select returns 0
+D (103872) vfs: FDs in readfds =
+D (103873) vfs: esp_vfs_select starts with nfds = 60
+D (103874) vfs: FDs in readfds =
+D (103884) vfs: 54
+D (103885) vfs: 59
+D (103886) vfs: calling socket_select with the following FDs
+D (103887) vfs: FDs in readfds =
+D (103898) vfs: 54
+D (103898) vfs: 59
+D (104399) vfs: socket_select returned 0 and the FDs are the following
+D (104400) vfs: FDs in readfds =
+D (104400) vfs: esp_vfs_select returns 0
+D (104412) vfs: FDs in readfds =
+D (104412) vfs: esp_vfs_select starts with nfds = 60
+D (104413) vfs: FDs in readfds =
+D (104424) vfs: 54
+D (104424) vfs: 59
+D (104425) vfs: calling socket_select with the following FDs
+D (104425) vfs: FDs in readfds =
+D (104436) vfs: 54
+D (104437) vfs: 59
+D (104937) vfs: socket_select returned 0 and the FDs are the following
+D (104938) vfs: FDs in readfds =
+D (104939) vfs: esp_vfs_select returns 0
+D (104949) vfs: FDs in readfds =
+D (104950) vfs: esp_vfs_select starts with nfds = 60
+D (104951) vfs: FDs in readfds =
+D (104961) vfs: 54
+D (104961) vfs: 59
+D (104962) vfs: calling socket_select with the following FDs
+D (104963) vfs: FDs in readfds =
+D (104974) vfs: 54
+D (104974) vfs: 59
+D (105475) vfs: socket_select returned 0 and the FDs are the following
+D (105476) vfs: FDs in readfds =
+D (105476) vfs: esp_vfs_select returns 0
+D (105487) vfs: FDs in readfds =
+D (105488) vfs: esp_vfs_select starts with nfds = 60
+D (105489) vfs: FDs in readfds =
+D (105499) vfs: 54
+D (105500) vfs: 59
+D (105500) vfs: calling socket_select with the following FDs
+D (105503) vfs: FDs in readfds =
+D (105504) vfs: 54
+D (105514) vfs: 59
+D (105560) vfs: socket_select returned 1 and the FDs are the following
+D (105562) vfs: FDs in readfds =
+D (105562) vfs: 59
+D (105563) vfs: esp_vfs_select returns 1
+D (105573) vfs: FDs in readfds =
+D (105574) vfs: 59
+D (105601) vfs: esp_vfs_select starts with nfds = 55
+D (105602) vfs: FDs in readfds =
+D (105603) vfs: 54
+D (105614) vfs: calling socket_select with the following FDs
+D (105614) vfs: FDs in readfds =
+D (105615) vfs: 54
+D (106115) vfs: socket_select returned 0 and the FDs are the following
+D (106116) vfs: FDs in readfds =
+D (106117) vfs: esp_vfs_select returns 0
+D (106127) vfs: FDs in readfds =
+D (106128) vfs: esp_vfs_select starts with nfds = 55
+D (106129) vfs: FDs in readfds =
+D (106140) vfs: 54
+D (106140) vfs: calling socket_select with the following FDs
+D (106141) vfs: FDs in readfds =
+D (106142) vfs: 54
+D (106652) vfs: socket_select returned 0 and the FDs are the following
+D (106653) vfs: FDs in readfds =
+D (106653) vfs: esp_vfs_select returns 0
+D (106664) vfs: FDs in readfds =
+D (106665) vfs: esp_vfs_select starts with nfds = 55
+D (106666) vfs: FDs in readfds =
+D (106677) vfs: 54
+D (106678) vfs: calling socket_select with the following FDs
+D (106679) vfs: FDs in readfds =
+D (106690) vfs: 54
+D (106690) vfs: socket_select returned 1 and the FDs are the following
+D (106691) vfs: FDs in readfds =
+D (106702) vfs: 54
+D (106702) vfs: esp_vfs_select returns 1
+D (106703) vfs: FDs in readfds =
+D (106703) vfs: 54
+D (106717) vfs: esp_vfs_select starts with nfds = 61
+D (106728) vfs: FDs in readfds =
+D (106728) vfs: 54
+D (106728) vfs: 60
+D (106729) vfs: calling socket_select with the following FDs
+D (106740) vfs: FDs in readfds =
+D (106741) vfs: 54
+D (106741) vfs: 60
+D (106741) vfs: socket_select returned 1 and the FDs are the following
+D (106753) vfs: FDs in readfds =
+D (106754) vfs: 60
+D (106754) vfs: esp_vfs_select returns 1
+D (106765) vfs: FDs in readfds =
+D (106766) vfs: 60