Re: [naviserver-devel] (no subject)

[Maksym Z. <siq...@gm...>]

When I do: curl -v https://dummy1.daidze.org
I can see that is dev is responding instead of dummy1:

[05/Mar/2024:12:22:03][189217.7feadb3ec640][-sched-] Debug: DeQueueEvent
> (id 1 qid 1 1709644923.868083)
> [05/Mar/2024:12:22:03][189217.7feadb3ec640][-sched-] Debug: ns:interptrace[
> dev.daidze.org]: allocate ns:tcltrace ns_init
> [05/Mar/2024:12:22:03][189217.7feadb3ec640][-sched-] Debug: ns:interptrace[
> dev.daidze.org]: deallocate nsdb:releasehandles a:(nil)
> [05/Mar/2024:12:22:03][189217.7feadb3ec640][-sched-] Debug: ns:interptrace[
> dev.daidze.org]: deallocate ns:tcltrace ns_cleanup
> [05/Mar/2024:12:22:03][189217.7feadb3ec640][-sched-] Debug: QueueEvent (1)
> based on lastqueue
> [05/Mar/2024:12:22:03][189217.7feadb3ec640][-sched-] Debug: sched: compute
> next run time based on: scheduled 1709644933.868083 diff 1
> [05/Mar/2024:12:22:03][189217.7feadb3ec640][-sched-] Debug: QueueEvent (id
> 1 qid 2 1709644933.868083)
> [05/Mar/2024:12:22:04][189217.7feadb3ec640][-sched-] Debug: DeQueueEvent
> (id 3 qid 1 1709644924.721254)
> [05/Mar/2024:12:22:04][189217.7feadb3ec640][-sched-] Debug: ns:interptrace[
> dummy1.daidze.org]: allocate ns:tcltrace ns_init
> [05/Mar/2024:12:22:04][189217.7feadb3ec640][-sched-] Debug: ns:interptrace[
> dummy1.daidze.org]: deallocate nsdb:releasehandles a:(nil)
> [05/Mar/2024:12:22:04][189217.7feadb3ec640][-sched-] Debug: ns:interptrace[
> dummy1.daidze.org]: deallocate ns:tcltrace ns_cleanup
> [05/Mar/2024:12:22:04][189217.7feadb3ec640][-sched-] Debug: QueueEvent (3)
> based on lastqueue
> [05/Mar/2024:12:22:04][189217.7feadb3ec640][-sched-] Debug: sched: compute
> next run time based on: scheduled 1709644934.721254 diff 1
> [05/Mar/2024:12:22:04][189217.7feadb3ec640][-sched-] Debug: QueueEvent (id
> 3 qid 2 1709644934.721254)
> [05/Mar/2024:12:22:07][189217.7fead19fd640][-driver:nsssl:0-] Debug:
> Ns_SockAccept returns sock 6, err NONE
> [05/Mar/2024:12:22:07][189217.7fead19fd640][-driver:nsssl:0-] Debug:
> nssock(6): option TCP_NODELAY activated
> [05/Mar/2024:12:22:07][189217.7fead19fd640][-driver:nsssl:0-] Debug:
> SSL_read(6) got:418
> [05/Mar/2024:12:22:07][189217.7fead19fd640][-driver:nsssl:0-] Debug: ###
> SSL_read(6) return:418 sockState:01
> [05/Mar/2024:12:22:07][189217.7fead19fd640][-driver:nsssl:0-] Debug: ###
> Request SetUrl calls Ns_UrlPathDecode '/'
> [05/Mar/2024:12:22:07][189217.7fead19fd640][-driver:nsssl:0-] Debug:  ###
> decoded path '/'
> [05/Mar/2024:12:22:07][189217.7fead19fd640][-driver:nsssl:0-] Debug: ===
> length < avail (length 0, avail 0) tfd 0 tfile (nil) chunkStartOff 0
> [05/Mar/2024:12:22:07][189217.7fead19fd640][-driver:nsssl:0-] Debug:
> driver sets  reqPtr->content (len 0) to ''
> [05/Mar/2024:12:22:07][189217.7fead19fd640][-driver:nsssl:0-] Debug: [0]
> dequeue thread connPtr 0x55d4804627f0 idle 1 state 4 create 0
> [05/Mar/2024:12:22:07][189217.7feadab59640][-conn:dev.daidze.org:default:0:3-]
> Debug: ns:interptrace[dev.daidze.org]: allocate ns:tcltrace ns_init
> [05/Mar/2024:12:22:07][189217.7feadab59640][-conn:dev.daidze.org:default:0:3-]
> Debug: ### Request SetUrl calls Ns_UrlPathDecode '/index.adp'
> [05/Mar/2024:12:22:07][189217.7feadab59640][-conn:dev.daidze.org:default:0:3-]
> Debug:  ### decoded path '/index.adp'
> [05/Mar/2024:12:22:07][189217.7feadab59640][-conn:dev.daidze.org:default:0:3-]
> Debug: AdpPageRequest for '/opt/ns/clients/dev/client/pages/index.adp'
> access ok
> [05/Mar/2024:12:22:07][189217.7feadab59640][-conn:dev.daidze.org:default:0:3-]
> Debug: nslog called with driver pattern '(null)' via driver 'nsssl' req:
> GET / HTTP/1.0
> [05/Mar/2024:12:22:07][189217.7feadab59640][-conn:dev.daidze.org:default:0:3-]
> Debug: ns:interptrace[dev.daidze.org]: deallocate nsdb:releasehandles
> a:(nil)
> [05/Mar/2024:12:22:07][189217.7feadab59640][-conn:dev.daidze.org:default:0:3-]
> Debug: ns:interptrace[dev.daidze.org]: deallocate ns:tcltrace ns_cleanup
> [05/Mar/2024:12:22:07][189217.7feadab59640][-conn:dev.daidze.org:default:0:3-]
> Debug: Ns_ConnClose 0x55d4804627f0 stream 000000 chunk 000000 via writer
> 000000 sockPtr 0x7feac400f180
> [05/Mar/2024:12:22:07][189217.7feadab59640][-conn:dev.daidze.org:default:0:3-]
> Debug: ### SSL close(6) shutdown returned 1 err 0
> [05/Mar/2024:12:22:07][189217.7feadab59640][-conn:dev.daidze.org:default:0:3-]
> Debug: ### SSL close(6) socket
> [05/Mar/2024:12:22:07][189217.7feadab59640][-conn:dev.daidze.org:default:0:3-]
> Debug: [0] end of job, waiting 0 current 1 idle 0 ncons 9996 fromQueue 0
> start 1709644927.307449 1709644927.307449 accept 0.000000 queue 0.016980
> filter 0.001830 run 0.005568 netrun 0.003738 total 0.022548
> [05/Mar/2024:12:22:07][189217.7fead19fd640][-driver:nsssl:0-] Debug:
> Ns_SockAccept returns sock -1, err Resource temporarily unavailable
> [05/Mar/2024:12:22:11][189217.7fead19fd640][-driver:nsssl:0-] Debug:
> Ns_SockAccept returns sock 6, err Resource temporarily unavailable
> [05/Mar/2024:12:22:11][189217.7fead19fd640][-driver:nsssl:0-] Debug:
> nssock(6): option TCP_NODELAY activated
> [05/Mar/2024:12:22:11][189217.7fead19fd640][-driver:nsssl:0-] Debug:
> SSL_read(6) ERROR_WANT_READ got:0
> [05/Mar/2024:12:22:11][189217.7fead19fd640][-driver:nsssl:0-] Debug: ###
> SSL_read(6) return:0 sockState:80
> [05/Mar/2024:12:22:11][189217.7fead19fd640][-driver:nsssl:0-] Debug:
> Ns_SockAccept returns sock -1, err Resource temporarily unavailable
> [05/Mar/2024:12:22:11][189217.7fead19fd640][-driver:nsssl:0-] Debug:
> SSL_read(6) ERROR_WANT_READ got:0
> [05/Mar/2024:12:22:11][189217.7fead19fd640][-driver:nsssl:0-] Debug: ###
> SSL_read(6) return:0 sockState:80
> [05/Mar/2024:12:22:11][189217.7fead19fd640][-driver:nsssl:0-] Debug:
> SSL_read(6) ERROR_WANT_READ got:0
> [05/Mar/2024:12:22:11][189217.7fead19fd640][-driver:nsssl:0-] Debug: ###
> SSL_read(6) return:0 sockState:80
> [05/Mar/2024:12:22:11][189217.7fead19fd640][-driver:nsssl:0-] Debug:
> SSL_read(6) ERROR_WANT_READ got:0
> [05/Mar/2024:12:22:11][189217.7fead19fd640][-driver:nsssl:0-] Debug: ###
> SSL_read(6) return:0 sockState:80
> [05/Mar/2024:12:22:11][189217.7fead19fd640][-driver:nsssl:0-] Debug:
> SSL_read(6) got:743
> [05/Mar/2024:12:22:11][189217.7fead19fd640][-driver:nsssl:0-] Debug: ###
> SSL_read(6) return:743 sockState:01
> [05/Mar/2024:12:22:11][189217.7fead19fd640][-driver:nsssl:0-] Debug: ###
> Request SetUrl calls Ns_UrlPathDecode '/'
> [05/Mar/2024:12:22:11][189217.7fead19fd640][-driver:nsssl:0-] Debug:  ###
> decoded path '/'
> [05/Mar/2024:12:22:11][189217.7fead19fd640][-driver:nsssl:0-] Debug: ===
> length < avail (length 0, avail 0) tfd 0 tfile (nil) chunkStartOff 0
> [05/Mar/2024:12:22:11][189217.7fead19fd640][-driver:nsssl:0-] Debug:
> driver sets  reqPtr->content (len 0) to ''
> [05/Mar/2024:12:22:11][189217.7fead19fd640][-driver:nsssl:0-] Debug: [0]
> dequeue thread connPtr 0x55d4804627f0 idle 1 state 4 create 0
> [05/Mar/2024:12:22:11][189217.7feadab59640][-conn:dev.daidze.org:default:0:4-]
> Debug: ns:interptrace[dev.daidze.org]: allocate ns:tcltrace ns_init
> [05/Mar/2024:12:22:11][189217.7feadab59640][-conn:dev.daidze.org:default:0:4-]
> Debug: ### Request SetUrl calls Ns_UrlPathDecode '/index.adp'
> [05/Mar/2024:12:22:11][189217.7feadab59640][-conn:dev.daidze.org:default:0:4-]
> Debug:  ### decoded path '/index.adp'
> [05/Mar/2024:12:22:11][189217.7feadab59640][-conn:dev.daidze.org:default:0:4-]
> Debug: AdpPageRequest for '/opt/ns/clients/dev/client/pages/index.adp'
> access ok
> [05/Mar/2024:12:22:11][189217.7feadab59640][-conn:dev.daidze.org:default:0:4-]
> Debug: nslog called with driver pattern '(null)' via driver 'nsssl' req:
> GET / HTTP/1.0
> [05/Mar/2024:12:22:11][189217.7feadab59640][-conn:dev.daidze.org:default:0:4-]
> Debug: ns:interptrace[dev.daidze.org]: deallocate nsdb:releasehandles
> a:(nil)
> [05/Mar/2024:12:22:11][189217.7feadab59640][-conn:dev.daidze.org:default:0:4-]
> Debug: ns:interptrace[dev.daidze.org]: deallocate ns:tcltrace ns_cleanup
> [05/Mar/2024:12:22:11][189217.7feadab59640][-conn:dev.daidze.org:default:0:4-]
> Debug: Ns_ConnClose 0x55d4804627f0 stream 000000 chunk 000000 via writer
> 000000 sockPtr 0x7feac400f180
> [05/Mar/2024:12:22:11][189217.7feadab59640][-conn:dev.daidze.org:default:0:4-]
> Debug: ### SSL close(6) shutdown returned 1 err 0
> [05/Mar/2024:12:22:11][189217.7feadab59640][-conn:dev.daidze.org:default:0:4-]
> Debug: ### SSL close(6) socket
> [05/Mar/2024:12:22:11][189217.7feadab59640][-conn:dev.daidze.org:default:0:4-]
> Debug: [0] end of job, waiting 0 current 1 idle 0 ncons 9995 fromQueue 0
> start 1709644931.861799 1709644931.864911 accept 0.003112 queue 0.000169
> filter 0.000287 run 0.000911 netrun 0.000624 total 0.001080
>

On Tue, Mar 5, 2024 at 12:19 PM Maksym Zinchenko <siq...@gm...> wrote:

> Ive added debug, here is my log:
>
> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:conf-] [0m
>> [0;39mNotice: nsmain: NaviServer/5.0.0a (tar-5.0.0a) starting [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:conf-] [0m
>> [0;39mNotice: nsmain: security info: uid=1000, euid=1000, gid=1000,
>> egid=1000 [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:conf-] [0m
>> [0;39mNotice: nsmain: Tcl version: 8.6.12 [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:conf-] [0m
>> [0;39mNotice: nsmain: max files: soft limit 524288, hard limit 524288 [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:conf-] [0m
>> [1;39mWarning: nsmain: current limit of maximum number of files >
>> FD_SETSIZE (1024), select() calls should not be used [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: pool default: queueLength 90 low water 9 high water 72 [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: nsd/init.tcl[dev.daidze.org]: booting virtual server:
>>  Tcl system encoding: "utf-8" [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: nsd/init.tcl[dev.daidze.org]: loaded Tcl 8.6 (8.6.12)
>> [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: modload: loading module nsdb from file
>> /opt/ns/bin/nsdb.so [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: modload: loading module nslog from file
>> /opt/ns/bin/nslog.so [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: SCHED_DAILY: scheduled 1709644553.395622 next
>> 1709600400.000000 diff -1 secdiff -44153 [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: SCHED_DAILY: final next 1709686800.000000 [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: QueueEvent (id 0 qid 1 1709686800.000000) [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: nslog: opened '/opt/ns/logs/dev.daidze.org.log' [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: modload: loading module nsfortune from file
>> /opt/ns/bin/nsfortune.so [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: nsfortune: /usr/share/games/fortunes: loaded 20886
>> epigrams from 47 files [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: modload: loading module nsperm from file
>> /opt/ns/bin/nsperm.so [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: random: generating 1 seed [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [1;39mWarning: privatelib /opt/ns/clients/dev/client/tcl is not a
>> readable directory (ignored) [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/init.tcl [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/aolserver-openacs.tcl [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: nx::serializer version 2.4.0 [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: XOTcl 2.4.0 loaded featuring: memcount 0 profile 0
>> memtrace 0 assertions 1 dtrace 0 development 0 [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: Using ns_cache based on NX 2.4.0 [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/cache.tcl [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/charsets.tcl [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/compat.tcl [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/config.tcl [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: adp[dev.daidze.org]: mapped {GET HEAD POST} /*.adp [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: tcl[dev.daidze.org]: mapped {GET HEAD POST} *.tcl [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/fastpath.tcl [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/file.tcl [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/form.tcl [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/http.tcl [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/limits.tcl [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/ns_crypto.tcl [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/nstrace.tcl [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/sendmail.tcl [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/tcloo.tcl [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/util.tcl [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: privatelib /opt/ns/clients/dev/client/tcl/nsdb is not a
>> readable directory (ignored) [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/nsdb/util.tcl [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: sharedlib /opt/ns/tcl/nslog is not a readable directory
>> (ignored) [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: privatelib /opt/ns/clients/dev/client/tcl/nslog is not a
>> readable directory (ignored) [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: sharedlib /opt/ns/tcl/nsfortune is not a readable
>> directory (ignored) [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: privatelib /opt/ns/clients/dev/client/tcl/nsfortune is
>> not a readable directory (ignored) [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: privatelib /opt/ns/clients/dev/client/tcl/nsperm is not a
>> readable directory (ignored) [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/nsperm/init.tcl [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: PASSWD call <ns_perm adduser  {} {} {}> [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: PASSWD call <ns_perm adduser  nsadmin ubphBI5lDzSYc {}>
>> [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: PASSWD call <ns_perm adduser  nobody {} {}> [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/nsperm/compat.tcl [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/nsperm/htaccess.tcl [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: created unnamed syncobj syncobj:ns:mutex:tcl:0 [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: nsperm: enabling .htaccess support [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: privatelib /opt/ns/clients/dev/client/tcl/nsshell is not
>> a readable directory (ignored) [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/nsshell/init.tcl [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: nsshell: XHR shell registered under url /nsshell [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: WebSocket: no nsshell configured
>> (/opt/ns/tcl/nsshell/init.tcl) [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/nsshell/shell.tcl [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: mutex mid0 created [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: sched: compute next run time based on: scheduled
>> 1709644563.868083 diff 1 [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: QueueEvent (id 1 qid 1 1709644563.868083) [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/nsshell/snapshot.tcl [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: privatelib /opt/ns/clients/dev/client/tcl/oodz is not a
>> readable directory (ignored) [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/oodz/init.tcl [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/oodz/tPackageClass.tcl
>> [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: privatelib /opt/ns/clients/dev/client/tcl/dz is not a
>> readable directory (ignored) [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/init.tcl [0m
>> [1;32m[05/Mar/2024:12:15:53][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: ----------------------------- Loading DZ
>> ----------------------------------- [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_api.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_chilkat.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: Chilkat Unlocked using purchased unlock code. [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_daidze_procs.tcl
>> [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_date_time.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_db.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_dict.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_dummy.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_export.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_filestorage.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_html_wrapper.tcl
>> [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_http.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_img_procs.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_json.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_list_procs.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_login.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_mail.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_math_procs.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_namespace.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_nasa.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_ns_set.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_oauth.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_os_file_procs.tcl
>> [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_password.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_qrcode.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_sanitize.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_startup.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_template.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_test.tcl [0m
>> === aliasedCmd ::ns_cache_flush has regObj <> x '::ns_cache' where
>> 'object'
>> aliases: ::nx::serializer::ObjectSystemSerializer needs
>> ::nx::serializer::Serializer
>> set alias-deps for ::nx::serializer::ObjectSystemSerializer -
>> :alias_dependency(::nx::serializer::ObjectSystemSerializer,class) -
>> ::nx::serializer::Serializer
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: ns:interptrace[dev.daidze.org]: deallocate
>> nsdb:releasehandles a:(nil) [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: ns:interptrace[dev.daidze.org]: deallocate ns:tcltrace
>> ns_cleanup [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: start update interpreter dev.daidze.org to epoch 1,
>> concurrent 1 [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;39mNotice: update interpreter dev.daidze.org to epoch 1 done,
>> trace deallocate, time 0.046980 secs concurrent 1 [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [1;31mError: can't read "selfns": no such variable
>> :    can't read "selfns": no such variable
>> :        while executing
>> :    "list upvar 1 ${selfns}::$varname $varname"
>> :        (procedure "::snit::RT.variable" line 5)
>> :        invoked from within
>> :    "variable Snit_typemethodInfo"
>> :        (in namespace eval "::snit::window" script line 2)
>> :        invoked from within
>> :    "namespace eval ::snit::window {
>> :     variable Snit_typemethodInfo
>> :    array set Snit_typemethodInfo {info {0 {::snit::RT.typemethod.info
>> %t} {}} create {0 {::..."
>> :        invoked from within
>> :    "ns_ictl update"
>> :        (procedure "ns_cleanup" line 8)
>> :        invoked from within
>> :    "ns_cleanup"
>> :        while executing callback
>> :    ns:tcltrace ns_cleanup
>> :    (context: trace proc) line 1 [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [1;31mError: can't read "selfns": no such variable
>> :    can't read "selfns": no such variable
>> :        while executing
>> :    "list upvar 1 ${selfns}::$varname $varname"
>> :        (procedure "::snit::RT.variable" line 5)
>> :        invoked from within
>> :    "variable Snit_typemethodInfo"
>> :        (in namespace eval "::snit::window" script line 2)
>> :        invoked from within
>> :    "namespace eval ::snit::window {
>> :     variable Snit_typemethodInfo
>> :    array set Snit_typemethodInfo {info {0 {::snit::RT.typemethod.info
>> %t} {}} create {0 {::..."
>> :        invoked from within
>> :    "ns_ictl update"
>> :        (procedure "ns_cleanup" line 8)
>> :        invoked from within
>> :    "ns_cleanup"
>> :        while executing callback
>> :    ns:tcltrace ns_cleanup
>> :    (context: trace proc)
>> :    (context: trace deallocate) line 1 [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: ns_markfordelete: true [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dev.daidze.org-]
>> [0m [0;34mDebug: ns:interptrace[dev.daidze.org]: delete ns:tcltrace {if
>> {[info commands ::nsf::finalize] ne ""} {::nsf::finalize}} [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: pool default: queueLength 90 low water 9 high water 72 [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: nsd/init.tcl[dummy1.daidze.org]: booting virtual
>> server:  Tcl system encoding: "utf-8" [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: nsd/init.tcl[dummy1.daidze.org]: loaded Tcl 8.6
>> (8.6.12) [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: modload: loading module nsdb from file
>> /opt/ns/bin/nsdb.so [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: modload: loading module nslog from file
>> /opt/ns/bin/nslog.so [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: SCHED_DAILY: scheduled 1709644554.618167 next
>> 1709600400.000000 diff -1 secdiff -44154 [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: SCHED_DAILY: final next 1709686800.000000 [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: QueueEvent (id 2 qid 3 1709686800.000000) [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: nslog: opened '/opt/ns/logs/dummy1.daidze.org.log' [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: modload: loading module nsfortune from file
>> /opt/ns/bin/nsfortune.so [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: nsfortune: /usr/share/games/fortunes: loaded 20886
>> epigrams from 47 files [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: modload: loading module nsperm from file
>> /opt/ns/bin/nsperm.so [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [1;39mWarning: privatelib /opt/ns/clients/dummy1/client/tcl is not a
>> readable directory (ignored) [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/init.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/aolserver-openacs.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: nx::serializer version 2.4.0 [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: XOTcl 2.4.0 loaded featuring: memcount 0 profile 0
>> memtrace 0 assertions 1 dtrace 0 development 0 [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: Using ns_cache based on NX 2.4.0 [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/cache.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/charsets.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/compat.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/config.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: adp[dummy1.daidze.org]: mapped {GET HEAD POST} /*.adp
>> [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: tcl[dummy1.daidze.org]: mapped {GET HEAD POST} *.tcl
>> [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/fastpath.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/file.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/form.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/http.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/limits.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/ns_crypto.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/nstrace.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/sendmail.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/tcloo.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/util.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: privatelib /opt/ns/clients/dummy1/client/tcl/nsdb is not
>> a readable directory (ignored) [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/nsdb/util.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: sharedlib /opt/ns/tcl/nslog is not a readable directory
>> (ignored) [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: privatelib /opt/ns/clients/dummy1/client/tcl/nslog is not
>> a readable directory (ignored) [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: sharedlib /opt/ns/tcl/nsfortune is not a readable
>> directory (ignored) [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: privatelib /opt/ns/clients/dummy1/client/tcl/nsfortune is
>> not a readable directory (ignored) [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: privatelib /opt/ns/clients/dummy1/client/tcl/nsperm is
>> not a readable directory (ignored) [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/nsperm/init.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: PASSWD call <ns_perm adduser  {} {} {}> [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: PASSWD call <ns_perm adduser  nsadmin ubphBI5lDzSYc {}>
>> [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: PASSWD call <ns_perm adduser  nobody {} {}> [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/nsperm/compat.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/nsperm/htaccess.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: created unnamed syncobj syncobj:ns:mutex:tcl:0 [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: nsperm: enabling .htaccess support [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: privatelib /opt/ns/clients/dummy1/client/tcl/nsshell is
>> not a readable directory (ignored) [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/nsshell/init.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: nsshell: XHR shell registered under url /nsshell [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: WebSocket: no nsshell configured
>> (/opt/ns/tcl/nsshell/init.tcl) [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/nsshell/shell.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: mutex mid1 created [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: sched: compute next run time based on: scheduled
>> 1709644564.721254 diff 1 [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: QueueEvent (id 3 qid 2 1709644564.721254) [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/nsshell/snapshot.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: privatelib /opt/ns/clients/dummy1/client/tcl/oodz is not
>> a readable directory (ignored) [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/oodz/init.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/oodz/tPackageClass.tcl
>> [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: privatelib /opt/ns/clients/dummy1/client/tcl/dz is not a
>> readable directory (ignored) [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/init.tcl [0m
>> [1;32m[05/Mar/2024:12:15:54][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: ----------------------------- Loading DZ
>> ----------------------------------- [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_api.tcl [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_chilkat.tcl [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: Chilkat Unlocked using purchased unlock code. [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_daidze_procs.tcl
>> [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_date_time.tcl [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_db.tcl [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_dict.tcl [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_dummy.tcl [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_export.tcl [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_filestorage.tcl [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_html_wrapper.tcl
>> [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_http.tcl [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_img_procs.tcl [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_json.tcl [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_list_procs.tcl [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_login.tcl [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_mail.tcl [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_math_procs.tcl [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_namespace.tcl [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_nasa.tcl [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_ns_set.tcl [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_oauth.tcl [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_os_file_procs.tcl
>> [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_password.tcl [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_qrcode.tcl [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_sanitize.tcl [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_startup.tcl [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_template.tcl [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsd/init.tcl: loading /opt/ns/tcl/dz/dz_test.tcl [0m
>> === aliasedCmd ::ns_cache_flush has regObj <> x '::ns_cache' where
>> 'object'
>> aliases: ::nx::serializer::ObjectSystemSerializer needs
>> ::nx::serializer::Serializer
>> set alias-deps for ::nx::serializer::ObjectSystemSerializer -
>> :alias_dependency(::nx::serializer::ObjectSystemSerializer,class) -
>> ::nx::serializer::Serializer
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: ns:interptrace[dummy1.daidze.org]: deallocate
>> nsdb:releasehandles a:(nil) [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: ns:interptrace[dummy1.daidze.org]: deallocate
>> ns:tcltrace ns_cleanup [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: start update interpreter dummy1.daidze.org to epoch 1,
>> concurrent 1 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: update interpreter dummy1.daidze.org to epoch 1 done,
>> trace deallocate, time 0.049009 secs concurrent 1 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [1;31mError: can't read "selfns": no such variable
>> :    can't read "selfns": no such variable
>> :        while executing
>> :    "list upvar 1 ${selfns}::$varname $varname"
>> :        (procedure "::snit::RT.variable" line 5)
>> :        invoked from within
>> :    "variable Snit_typemethodInfo"
>> :        (in namespace eval "::snit::window" script line 2)
>> :        invoked from within
>> :    "namespace eval ::snit::window {
>> :     variable Snit_typemethodInfo
>> :    array set Snit_typemethodInfo {info {0 {::snit::RT.typemethod.info
>> %t} {}} create {0 {::..."
>> :        invoked from within
>> :    "ns_ictl update"
>> :        (procedure "ns_cleanup" line 8)
>> :        invoked from within
>> :    "ns_cleanup"
>> :        while executing callback
>> :    ns:tcltrace ns_cleanup
>> :    (context: trace proc) line 1 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [1;31mError: can't read "selfns": no such variable
>> :    can't read "selfns": no such variable
>> :        while executing
>> :    "list upvar 1 ${selfns}::$varname $varname"
>> :        (procedure "::snit::RT.variable" line 5)
>> :        invoked from within
>> :    "variable Snit_typemethodInfo"
>> :        (in namespace eval "::snit::window" script line 2)
>> :        invoked from within
>> :    "namespace eval ::snit::window {
>> :     variable Snit_typemethodInfo
>> :    array set Snit_typemethodInfo {info {0 {::snit::RT.typemethod.info
>> %t} {}} create {0 {::..."
>> :        invoked from within
>> :    "ns_ictl update"
>> :        (procedure "ns_cleanup" line 8)
>> :        invoked from within
>> :    "ns_cleanup"
>> :        while executing callback
>> :    ns:tcltrace ns_cleanup
>> :    (context: trace proc)
>> :    (context: trace deallocate) line 1 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: ns_markfordelete: true [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: ns:interptrace[dummy1.daidze.org]: delete ns:tcltrace
>> {if {[info commands ::nsf::finalize] ne ""} {::nsf::finalize}} [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: ns:callback: prestartup: ns:tclcallback
>> _ns_load_global_modules 1 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: ns:interptrace[dev.daidze.org]: create nsdb:initinterp
>> a:0x55d480451508 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: ns:interptrace[dev.daidze.org]: create nslog:initinterp
>> /opt/ns/logs/dev.daidze.org.log [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: ns:interptrace[dev.daidze.org]: create
>>  p:0x7feadabe85d0 a:0x55d48049fe70 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: ns:interptrace[dev.daidze.org]: create
>>  p:0x7feadabe0ab0 a:0x55d4804cbf40 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: start update interpreter dev.daidze.org to epoch 1,
>> concurrent 1 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: update interpreter dev.daidze.org to epoch 1 done,
>> trace none, time 0.128774 secs concurrent 1 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: ns:interptrace[dev.daidze.org]: allocate ns:tcltrace
>> ns_init [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: modload: loading module nsssl from file
>> /opt/ns/bin/nsssl.so [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: nsssl:0: enable 0 spooler thread(s) [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: nsssl:0: enable 1 writer thread(s) for downloads >= 4096
>> bytes, bufsize=8192 bytes, HTML streaming 1 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: OpenSSL OpenSSL 1.1.1f  31 Mar 2020 initialized [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: load certificate '/opt/ns/modules/nsssl/daidze.pem'
>> specified in section ns/module/nsssl [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: Ns_TLS_CtxServerInit calls Ns_TLS_CtxServerCreate with app
>> data 0x55d482cb6e70 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: nsssl: disabling SSLv2 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: nsssl: disabling SSLv3 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: CertTableAdd: sslCtx 0x55d480c0e8a0 cert
>> '/opt/ns/modules/nsssl/daidze.pem' [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: Ns_TLS_CtxServerInit ctx 0x55d480c0e8a0 ctx app (nil) [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: nsssl SSL_CTX_build_cert_chain failed [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsssl:SSL_CTX_get_cert_store 0x55d4804d7fd0 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: nsssl:X509_STORE_load_locations 1 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: nsssl: activate OCSP stapling for ns/module/nsssl -> 0 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: nsssl: version 2.3 loaded, based on OpenSSL 1.1.1f  31
>> Mar 2020 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: modload: loading module nssock from file
>> /opt/ns/bin/nssock.so [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: nssock:0: enable 0 spooler thread(s) [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: nssock:0: enable 0 writer thread(s) [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: ns:interptrace[dev.daidze.org]: deallocate
>> nsdb:releasehandles a:(nil) [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;34mDebug: ns:interptrace[dev.daidze.org]: deallocate ns:tcltrace
>> ns_cleanup [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: nssock:0: adding virtual host entry for host <
>> dev.daidze.org:8080> location: http://dev.daidze.org:8080 mapped to
>> server: dev.daidze.org ctx (nil) [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: nssock:0: adding virtual host entry for host <
>> dummy1.daidze.org:8080> location: http://dummy1.daidze.org:8080 mapped
>> to server: dummy1.daidze.org ctx (nil) [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: nsssl:0: adding virtual host entry for host <
>> dev.daidze.org:8443> location: https://dev.daidze.org:8443 mapped to
>> server: dev.daidze.org ctx (nil) [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: nsssl:0: adding virtual host entry for host <
>> dummy1.daidze.org:8443> location: https://dummy1.daidze.org:8443 mapped
>> to server: dummy1.daidze.org ctx (nil) [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7fead21fe640][-driver:nssock:0-] [0m
>> [0;39mNotice: starting [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7fead21fe640][-driver:nssock:0-] [0m
>> [0;39mNotice: nssock:0: listening on [172.17.0.1]:8080 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7fead21fe640][-driver:nssock:0-] [0m
>> [0;39mNotice: driver: accepting connections [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadab59640][-conn:dev.daidze.org:default:0:0-]
>> [0m [0;34mDebug: ns:interptrace[dev.daidze.org]: create nsdb:initinterp
>> a:0x55d480451508 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadab59640][-conn:dev.daidze.org:default:0:0-]
>> [0m [0;34mDebug: ns:interptrace[dev.daidze.org]: create nslog:initinterp
>> /opt/ns/logs/dev.daidze.org.log [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadab59640][-conn:dev.daidze.org:default:0:0-]
>> [0m [0;34mDebug: ns:interptrace[dev.daidze.org]: create
>>  p:0x7feadabe85d0 a:0x55d48049fe70 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadab59640][-conn:dev.daidze.org:default:0:0-]
>> [0m [0;34mDebug: ns:interptrace[dev.daidze.org]: create
>>  p:0x7feadabe0ab0 a:0x55d4804cbf40 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7fead19fd640][-driver:nsssl:0-] [0m
>> [0;39mNotice: starting [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7fead19fd640][-driver:nsssl:0-] [0m
>> [0;39mNotice: nsssl:0: listening on [172.17.0.1]:8443 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7fead19fd640][-driver:nsssl:0-] [0m
>> [0;39mNotice: driver: accepting connections [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: nsmain: NaviServer/5.0.0a (tar-5.0.0a) running [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb73f840][-main:dummy1.daidze.org-]
>> [0m [0;39mNotice: nsmain: security info: uid=1000, euid=1000, gid=1000,
>> egid=1000 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadb3ec640][-sched-] [0m
>> [0;39mNotice: sched: starting [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7fead29ff640][-conn:dummy1.daidze.org:default:0:0-]
>> [0m [0;34mDebug: ns:interptrace[dummy1.daidze.org]: create
>> nsdb:initinterp a:0x55d480451526 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7fead29ff640][-conn:dummy1.daidze.org:default:0:0-]
>> [0m [0;34mDebug: ns:interptrace[dummy1.daidze.org]: create
>> nslog:initinterp /opt/ns/logs/dummy1.daidze.org.log [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7fead29ff640][-conn:dummy1.daidze.org:default:0:0-]
>> [0m [0;34mDebug: ns:interptrace[dummy1.daidze.org]: create
>>  p:0x7feadabe85d0 a:0x55d480c7f6f0 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7fead29ff640][-conn:dummy1.daidze.org:default:0:0-]
>> [0m [0;34mDebug: ns:interptrace[dummy1.daidze.org]: create
>>  p:0x7feadabe0ab0 a:0x55d480c803a0 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7fead29ff640][-conn:dummy1.daidze.org:default:0:0-]
>> [0m [0;39mNotice: start update interpreter dummy1.daidze.org to epoch 1,
>> concurrent 2 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7fead11fc640][-writer0-] [0m
>> [0;39mNotice: writer0: accepting connections [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadab59640][-conn:dev.daidze.org:default:0:0-]
>> [0m [0;39mNotice: start update interpreter dev.daidze.org to epoch 1,
>> concurrent 2 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadab59640][-conn:dev.daidze.org:default:0:0-]
>> [0m [0;39mNotice: update interpreter dev.daidze.org to epoch 1 done,
>> trace none, time 0.249683 secs concurrent 2 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadab59640][-conn:dev.daidze.org:default:0:0-]
>> [0m [0;34mDebug: ns:interptrace[dev.daidze.org]: allocate ns:tcltrace
>> ns_init [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadab59640][-conn:dev.daidze.org:default:0:0-]
>> [0m [0;39mNotice: thread initialized (0.279877 secs) [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadab59640][-conn:dev.daidze.org:default:0:0-]
>> [0m [0;34mDebug: ns:interptrace[dev.daidze.org]: deallocate
>> nsdb:releasehandles a:(nil) [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7feadab59640][-conn:dev.daidze.org:default:0:0-]
>> [0m [0;34mDebug: ns:interptrace[dev.daidze.org]: deallocate ns:tcltrace
>> ns_cleanup [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7fead29ff640][-conn:dummy1.daidze.org:default:0:0-]
>> [0m [0;39mNotice: update interpreter dummy1.daidze.org to epoch 1 done,
>> trace none, time 0.301423 secs concurrent 1 [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7fead29ff640][-conn:dummy1.daidze.org:default:0:0-]
>> [0m [0;34mDebug: ns:interptrace[dummy1.daidze.org]: allocate ns:tcltrace
>> ns_init [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7fead29ff640][-conn:dummy1.daidze.org:default:0:0-]
>> [0m [0;39mNotice: thread initialized (0.320145 secs) [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7fead29ff640][-conn:dummy1.daidze.org:default:0:0-]
>> [0m [0;34mDebug: ns:interptrace[dummy1.daidze.org]: deallocate
>> nsdb:releasehandles a:(nil) [0m
>> [1;32m[05/Mar/2024:12:15:55][189217.7fead29ff640][-conn:dummy1.daidze.org:default:0:0-]
>> [0m [0;34mDebug: ns:interptrace[dummy1.daidze.org]: deallocate
>> ns:tcltrace ns_cleanup [0m
>> [1;32m[05/Mar/2024:12:16:03][189217.7feadb3ec640][-sched-] [0m
>> [0;34mDebug: DeQueueEvent (id 1 qid 1 1709644563.868083) [0m
>> [1;32m[05/Mar/2024:12:16:03][189217.7feadb3ec640][-sched-] [0m
>> [0;34mDebug: ns:interptrace[dev.daidze.org]: create nsdb:initinterp
>> a:0x55d480451508 [0m
>> [1;32m[05/Mar/2024:12:16:03][189217.7feadb3ec640][-sched-] [0m
>> [0;34mDebug: ns:interptrace[dev.daidze.org]: create nslog:initinterp
>> /opt/ns/logs/dev.daidze.org.log [0m
>> [1;32m[05/Mar/2024:12:16:03][189217.7feadb3ec640][-sched-] [0m
>> [0;34mDebug: ns:interptrace[dev.daidze.org]: create  p:0x7feadabe85d0
>> a:0x55d48049fe70 [0m
>> [1;32m[05/Mar/2024:12:16:03][189217.7feadb3ec640][-sched-] [0m
>> [0;34mDebug: ns:interptrace[dev.daidze.org]: create  p:0x7feadabe0ab0
>> a:0x55d4804cbf40 [0m
>> [1;32m[05/Mar/2024:12:16:03][189217.7feadb3ec640][-sched-] [0m
>> [0;39mNotice: start update interpreter dev.daidze.org to epoch 1,
>> concurrent 1 [0m
>> [1;32m[05/Mar/2024:12:16:04][189217.7feadb3ec640][-sched-] [0m
>> [0;39mNotice: update interpreter dev.daidze.org to epoch 1 done, trace
>> none, time 0.177554 secs concurrent 1 [0m
>> [1;32m[05/Mar/2024:12:16:04][189217.7feadb3ec640][-sched-] [0m
>> [0;34mDebug: ns:interptrace[dev.daidze.org]: allocate ns:tcltrace
>> ns_init [0m
>> [1;32m[05/Mar/2024:12:16:04][189217.7feadb3ec640][-sched-] [0m
>> [0;34mDebug: ns:interptrace[dev.daidze.org]: deallocate
>> nsdb:releasehandles a:(nil) [0m
>> [1;32m[05/Mar/2024:12:16:04][189217.7feadb3ec640][-sched-] [0m
>> [0;34mDebug: ns:interptrace[dev.daidze.org]: deallocate ns:tcltrace
>> ns_cleanup [0m
>> [1;32m[05/Mar/2024:12:16:04][189217.7feadb3ec640][-sched-] [0m
>> [0;34mDebug: QueueEvent (1) based on lastqueue [0m
>> [1;32m[05/Mar/2024:12:16:04][189217.7feadb3ec640][-sched-] [0m
>> [0;34mDebug: sched: compute next run time based on: scheduled
>> 1709644573.868083 diff 1 [0m
>> [1;32m[05/Mar/2024:12:16:04][189217.7feadb3ec640][-sched-] [0m
>> [0;34mDebug: QueueEvent (id 1 qid 2 1709644573.868083) [0m
>> [1;32m[05/Mar/2024:12:16:04][189217.7feadb3ec640][-sched-] [0m
>> [0;34mDebug: DeQueueEvent (id 3 qid 1 1709644564.721254) [0m
>> [1;32m[05/Mar/2024:12:16:04][189217.7feadb3ec640][-sched-] [0m
>> [0;34mDebug: ns:interptrace[dummy1.daidze.org]: create nsdb:initinterp
>> a:0x55d480451526 [0m
>> [1;32m[05/Mar/2024:12:16:04][189217.7feadb3ec640][-sched-] [0m
>> [0;34mDebug: ns:interptrace[dummy1.daidze.org]: create nslog:initinterp
>> /opt/ns/logs/dummy1.daidze.org.log [0m
>> [1;32m[05/Mar/2024:12:16:04][189217.7feadb3ec640][-sched-] [0m
>> [0;34mDebug: ns:interptrace[dummy1.daidze.org]: create  p:0x7feadabe85d0
>> a:0x55d480c7f6f0 [0m
>> [1;32m[05/Mar/2024:12:16:04][189217.7feadb3ec640][-sched-] [0m
>> [0;34mDebug: ns:interptrace[dummy1.daidze.org]: create  p:0x7feadabe0ab0
>> a:0x55d480c803a0 [0m
>> [1;32m[05/Mar/2024:12:16:04][189217.7feadb3ec640][-sched-] [0m
>> [0;39mNotice: start update interpreter dummy1.daidze.org to epoch 1,
>> concurrent 1 [0m
>> [1;32m[05/Mar/2024:12:16:04][189217.7feadb3ec640][-sched-] [0m
>> [0;39mNotice: update interpreter dummy1.daidze.org to epoch 1 done,
>> trace none, time 0.195646 secs concurrent 1 [0m
>> [1;32m[05/Mar/2024:12:16:04][189217.7feadb3ec640][-sched-] [0m
>> [0;34mDebug: ns:interptrace[dummy1.daidze.org]: allocate ns:tcltrace
>> ns_init [0m
>> [1;32m[05/Mar/2024:12:16:04][189217.7feadb3ec640][-sched-] [0m
>> [0;34mDebug: ns:interptrace[dummy1.daidze.org]: deallocate
>> nsdb:releasehandles a:(nil) [0m
>> [1;32m[05/Mar/2024:12:16:04][189217.7feadb3ec640][-sched-] [0m
>> [0;34mDebug: ns:interptrace[dummy1.daidze.org]: deallocate ns:tcltrace
>> ns_cleanup [0m
>> [1;32m[05/Mar/2024:12:16:04][189217.7feadb3ec640][-sched-] [0m
>> [0;34mDebug: QueueEvent (3) based on lastqueue [0m
>> [1;32m[05/Mar/2024:12:16:04][189217.7feadb3ec640][-sched-] [0m
>> [0;34mDebug: sched: compute next run time based on: scheduled
>> 1709644574.721254 diff 1 [0m
>> [1;32m[05/Mar/2024:12:16:04][189217.7feadb3ec640][-sched-] [0m
>> [0;34mDebug: QueueEvent (id 3 qid 2 1709644574.721254) [0m
>> [1;32m[05/Mar/2024:12:16:13][189217.7feadb3ec640][-sched-] [0m
>> [0;34mDebug: DeQueueEvent (id 1 qid 1 1709644573.868083) [0m
>> [1;32m[05/Mar/2024:12:16:13][189217.7feadb3ec640][-sched-] [0m
>> [0;34mDebug: ns:interptrace[dev.daidze.org]: allocate ns:tcltrace
>> ns_...
 
[truncated message content]