DEBUG set to false, but DEBUG tasks are still filling the log

[LoryB]

Reporting bugs/issues

• Please provide the following information:

  • OS/distribution version:

    QNAP QTS 
    Version: 5.1.2.2533
    Container Station
    Version: 3.0.5.623 (2023/08/30)

  • Unifi/Container version:

    Unifi Network Server: 8.0.7
    Image: goofball222/unifi:latest

  • Docker version:

    Docker version 20.10.22 (comes with Container Station update)

  • Labels from container: IE:

    Just added this Env "TZ=Europe/Rome", the rest is standard config, networks adapters have ipvlan cfg.
    Env:[
     "DEBIAN_FRONTEND=noninteractive"
     "PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin"
     "PGID=999"
     "PUID=999"
     "RUNAS_UID0=false"
     "BIND_PRIV=false"
     "DEBUG=false"
     "JVM_EXTRA_OPTS="
     "JVM_INIT_HEAP_SIZE="
     "JVM_MAX_HEAP_SIZE=1024M"
     "RUN_CHOWN=true"
     "TZ=Europe/Rome"
    ]

  • Details on how to reproduce the trouble, if available: DEBUG variable is set to false, but DEBUG tasks are still filling the log. Is it normal? IE:

    [2023-12-06 21:31:39,521] <device-connect-incidents> DEBUG tasks  - Started task[device-connect-incidents]
    [2023-12-06 21:31:39,522] <device-connect-incidents> DEBUG tasks  - Finished task[device-connect-incidents] in 1 ms
    [2023-12-06 21:31:39,541] <duplicate-ip-service> DEBUG tasks  - Started task[duplicate-ip-service]
    [2023-12-06 21:31:39,622] <duplicate-ip-service> DEBUG tasks  - Finished task[duplicate-ip-service] in 81 ms
    [2023-12-06 21:31:42,062] <tracer> DEBUG tasks  - Started task[tracer]
    [2023-12-06 21:31:42,062] <tracer> DEBUG tasks  - Finished task[tracer] in 0 ms
    [2023-12-06 21:31:43,167] <ble_state_check> DEBUG tasks  - Started task[ble_state_check]
    [2023-12-06 21:31:43,167] <ble_state_check> DEBUG tasks  - Finished task[ble_state_check] in 0 ms
    [2023-12-06 21:31:47,063] <tracer> DEBUG tasks  - Started task[tracer]
    [2023-12-06 21:31:47,063] <tracer> DEBUG tasks  - Finished task[tracer] in 0 ms
    [2023-12-06 21:31:47,639] <autolink-registry-flush> DEBUG tasks  - Started task[autolink-registry-flush]
    [2023-12-06 21:31:47,639] <autolink-registry-flush> DEBUG tasks  - Finished task[autolink-registry-flush] in 0 ms
    [2023-12-06 21:31:47,667] <live-performance-analytics> DEBUG tasks  - Started task[live-performance-analytics]
    [2023-12-06 21:31:47,667] <trigger-throttling-cache-flush> DEBUG tasks  - Started task[trigger-throttling-cache-flush]
    [2023-12-06 21:31:47,668] <live-performance-analytics> DEBUG tasks  - Finished task[live-performance-analytics] in 1 ms
    [2023-12-06 21:31:47,668] <trigger-throttling-cache-flush> DEBUG tasks  - Finished task[trigger-throttling-cache-flush] in 1 ms
    [2023-12-06 21:31:47,675] <cache-cleanup> DEBUG tasks  - Started task[cache-cleanup]
    [2023-12-06 21:31:47,677] <cache-cleanup> DEBUG tasks  - Finished task[cache-cleanup] in 2 ms
    [2023-12-06 21:31:47,858] <ble_bridge_discovery> DEBUG tasks  - Started task[ble_bridge_discovery]
    [2023-12-06 21:31:47,858] <ble_bridge_discovery> DEBUG tasks  - Finished task[ble_bridge_discovery] in 5 ms
    [2023-12-06 21:31:47,927] <db-stat-prune> DEBUG tasks  - Started task[db-stat-prune]
    [2023-12-06 21:31:47,931] <cache-prune> DEBUG tasks  - Started task[cache-prune]
    [2023-12-06 21:31:47,931] <db-config-prune> DEBUG tasks  - Started task[db-config-prune]
    [2023-12-06 21:31:47,934] <cache-prune> DEBUG tasks  - Finished task[cache-prune] in 3 ms
    [2023-12-06 21:31:47,943] <check-iot-certificate> DEBUG tasks  - Started task[check-iot-certificate]
    [2023-12-06 21:31:48,035] <db-config-prune> DEBUG tasks  - Finished task[db-config-prune] in 104 ms
    [2023-12-06 21:31:48,037] <remotelogger-clean> DEBUG tasks  - Started task[remotelogger-clean]
    [2023-12-06 21:31:48,065] <remotelogger-clean> DEBUG tasks  - Finished task[remotelogger-clean] in 28 ms
    [2023-12-06 21:31:48,069] <check-network-interfaces> DEBUG tasks  - Started task[check-network-interfaces]
    [2023-12-06 21:31:48,073] <check-network-interfaces> DEBUG tasks  - Finished task[check-network-interfaces] in 4 ms
    [2023-12-06 21:31:48,118] <db-stat-prune> DEBUG tasks  - Finished task[db-stat-prune] in 191 ms
    [2023-12-06 21:31:48,167] <ble_state_check> DEBUG tasks  - Started task[ble_state_check]
    [2023-12-06 21:31:48,168] <ble_state_check> DEBUG tasks  - Finished task[ble_state_check] in 1 ms
    [2023-12-06 21:31:48,886] <device-state-check> DEBUG tasks  - Started task[device-state-check]
    [2023-12-06 21:31:48,887] <device-state-check> DEBUG tasks  - Finished task[device-state-check] in 1 ms

[goofball222]

Unlike previously this is now something internal to the UniFi application itself, not anything controllable in the container build. It appears that the UI devs changed the application to dump all output, including debug even if it's set to off to the console if the main process is attached to anything. Guessing this happened around the same time they updated to Java 17.

If you need a "filtered" version of the log consider using server.log in the logs volume / /usr/lib/unifi/logs in the running container.

[goofball222]

I changed the functionality in the latest 8.1/beta container to tail /usr/lib/unifi/logs/server.log to the container STDOUT by default now as well.