Re: [naviserver-devel] (no subject)

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

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_init
> [0m
> [1;32m[05/Mar/2024:12:16:13][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: ns:interptrace[dev.daidze.org]: deallocate
> nsdb:releasehandles a:(nil) [0m
> [1;32m[05/Mar/2024:12:16:13][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: ns:interptrace[dev.daidze.org]: deallocate ns:tcltrace
> ns_cleanup [0m
> [1;32m[05/Mar/2024:12:16:13][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: QueueEvent (1) based on lastqueue [0m
> [1;32m[05/Mar/2024:12:16:13][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: sched: compute next run time based on: scheduled
> 1709644583.868083 diff 1 [0m
> [1;32m[05/Mar/2024:12:16:13][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: QueueEvent (id 1 qid 2 1709644583.868083) [0m
> [1;32m[05/Mar/2024:12:16:14][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: DeQueueEvent (id 3 qid 1 1709644574.721254) [0m
> [1;32m[05/Mar/2024:12:16:14][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: ns:interptrace[dummy1.daidze.org]: allocate ns:tcltrace
> ns_init [0m
> [1;32m[05/Mar/2024:12:16:14][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: ns:interptrace[dummy1.daidze.org]: deallocate
> nsdb:releasehandles a:(nil) [0m
> [1;32m[05/Mar/2024:12:16:14][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: ns:interptrace[dummy1.daidze.org]: deallocate ns:tcltrace
> ns_cleanup [0m
> [1;32m[05/Mar/2024:12:16:14][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: QueueEvent (3) based on lastqueue [0m
> [1;32m[05/Mar/2024:12:16:14][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: sched: compute next run time based on: scheduled
> 1709644584.721254 diff 1 [0m
> [1;32m[05/Mar/2024:12:16:14][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: QueueEvent (id 3 qid 2 1709644584.721254) [0m
> [1;32m[05/Mar/2024:12:16:23][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: DeQueueEvent (id 1 qid 1 1709644583.868083) [0m
> [1;32m[05/Mar/2024:12:16:23][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: ns:interptrace[dev.daidze.org]: allocate ns:tcltrace ns_init
> [0m
> [1;32m[05/Mar/2024:12:16:23][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: ns:interptrace[dev.daidze.org]: deallocate
> nsdb:releasehandles a:(nil) [0m
> [1;32m[05/Mar/2024:12:16:23][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: ns:interptrace[dev.daidze.org]: deallocate ns:tcltrace
> ns_cleanup [0m
> [1;32m[05/Mar/2024:12:16:23][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: QueueEvent (1) based on lastqueue [0m
> [1;32m[05/Mar/2024:12:16:23][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: sched: compute next run time based on: scheduled
> 1709644593.868083 diff 1 [0m
> [1;32m[05/Mar/2024:12:16:23][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: QueueEvent (id 1 qid 2 1709644593.868083) [0m
> [1;32m[05/Mar/2024:12:16:24][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: DeQueueEvent (id 3 qid 1 1709644584.721254) [0m
> [1;32m[05/Mar/2024:12:16:24][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: ns:interptrace[dummy1.daidze.org]: allocate ns:tcltrace
> ns_init [0m
> [1;32m[05/Mar/2024:12:16:24][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: ns:interptrace[dummy1.daidze.org]: deallocate
> nsdb:releasehandles a:(nil) [0m
> [1;32m[05/Mar/2024:12:16:24][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: ns:interptrace[dummy1.daidze.org]: deallocate ns:tcltrace
> ns_cleanup [0m
> [1;32m[05/Mar/2024:12:16:24][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: QueueEvent (3) based on lastqueue [0m
> [1;32m[05/Mar/2024:12:16:24][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: sched: compute next run time based on: scheduled
> 1709644594.721254 diff 1 [0m
> [1;32m[05/Mar/2024:12:16:24][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: QueueEvent (id 3 qid 2 1709644594.721254) [0m
> [1;32m[05/Mar/2024:12:16:33][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: DeQueueEvent (id 1 qid 1 1709644593.868083) [0m
> [1;32m[05/Mar/2024:12:16:33][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: ns:interptrace[dev.daidze.org]: allocate ns:tcltrace ns_init
> [0m
> [1;32m[05/Mar/2024:12:16:33][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: ns:interptrace[dev.daidze.org]: deallocate
> nsdb:releasehandles a:(nil) [0m
> [1;32m[05/Mar/2024:12:16:33][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: ns:interptrace[dev.daidze.org]: deallocate ns:tcltrace
> ns_cleanup [0m
> [1;32m[05/Mar/2024:12:16:33][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: QueueEvent (1) based on lastqueue [0m
> [1;32m[05/Mar/2024:12:16:33][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: sched: compute next run time based on: scheduled
> 1709644603.868083 diff 1 [0m
> [1;32m[05/Mar/2024:12:16:33][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: QueueEvent (id 1 qid 2 1709644603.868083) [0m
> [1;32m[05/Mar/2024:12:16:34][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: DeQueueEvent (id 3 qid 1 1709644594.721254) [0m
> [1;32m[05/Mar/2024:12:16:34][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: ns:interptrace[dummy1.daidze.org]: allocate ns:tcltrace
> ns_init [0m
> [1;32m[05/Mar/2024:12:16:34][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: ns:interptrace[dummy1.daidze.org]: deallocate
> nsdb:releasehandles a:(nil) [0m
> [1;32m[05/Mar/2024:12:16:34][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: ns:interptrace[dummy1.daidze.org]: deallocate ns:tcltrace
> ns_cleanup [0m
> [1;32m[05/Mar/2024:12:16:34][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: QueueEvent (3) based on lastqueue [0m
> [1;32m[05/Mar/2024:12:16:34][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: sched: compute next run time based on: scheduled
> 1709644604.721254 diff 1 [0m
> [1;32m[05/Mar/2024:12:16:34][189217.7feadb3ec640][-sched-] [0m
> [0;34mDebug: QueueEvent (id 3 qid 2 1709644604.721254) [0m
> [1;32m[05/Mar/2024:12:16:35][189217.7fead19fd640][-driver:nsssl:0-] [0m
> [0;34mDebug: Ns_SockAccept returns sock 6, err NONE [0m
> [1;32m[05/Mar/2024:12:16:35][189217.7fead19fd640][-driver:nsssl:0-] [0m
> [0;34mDebug: nssock(6): option TCP_NODELAY activated [0m
> [1;32m[05/Mar/2024:12:16:35][189217.7fead19fd640][-driver:nsssl:0-] [0m
> [0;34mDebug: SSL_read(6) ERROR_WANT_READ got:0 [0m
> [1;32m[05/Mar/2024:12:16:35][189217.7fead19fd640][-driver:nsssl:0-] [0m
> [0;34mDebug: ### SSL_read(6) return:0 sockState:80 [0m
> [1;32m[05/Mar/2024:12:16:35][189217.7fead19fd640][-driver:nsssl:0-] [0m
> [0;34mDebug: Ns_SockAccept returns sock -1, err Resource temporarily
> unavailable [0m
> [1;32m[05/Mar/2024:12:16:35][189217.7fead19fd640][-driver:nsssl:0-] [0m
> [0;34mDebug: SSL_read(6) ERROR_WANT_READ got:0 [0m
> [1;32m[05/Mar/2024:12:16:35][189217.7fead19fd640][-driver:nsssl:0-] [0m
> [0;34mDebug: ### SSL_read(6) return:0 sockState:80 [0m
> [1;32m[05/Mar/2024:12:16:35][189217.7fead19fd640][-driver:nsssl:0-] [0m
> [0;34mDebug: SSL_read(6) got:418 [0m
> [1;32m[05/Mar/2024:12:16:35][189217.7fead19fd640][-driver:nsssl:0-] [0m
> [0;34mDebug: ### SSL_read(6) return:418 sockState:01 [0m
> [1;32m[05/Mar/2024:12:16:35][189217.7fead19fd640][-driver:nsssl:0-] [0m
> [0;34mDebug: ### Request SetUrl calls Ns_UrlPathDecode '/' [0m
> [1;32m[05/Mar/2024:12:16:35][189217.7fead19fd640][-driver:nsssl:0-] [0m
> [0;34mDebug:  ### decoded path '/' [0m
> [1;32m[05/Mar/2024:12:16:35][189217.7fead19fd640][-driver:nsssl:0-] [0m
> [0;34mDebug: === length < avail (length 0, avail 0) tfd 0 tfile (nil)
> chunkStartOff 0 [0m
> [1;32m[05/Mar/2024:12:16:35][189217.7fead19fd640][-driver:nsssl:0-] [0m
> [0;34mDebug: driver sets  reqPtr->content (len 0) to '' [0m
> [1;32m[05/Mar/2024:12:16:35][189217.7fead19fd640][-driver:nsssl:0-] [0m
> [0;34mDebug: [0] dequeue thread connPtr 0x55d4804627f0 idle 1 state 4
> create 0 [0m
> [1;32m[05/Mar/2024:12:16:35][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:16:35][189217.7feadab59640][-conn:dev.daidze.org:default:0:0-]
> [0m [0;39mNotice: ns_perm_load: /opt/ns/modules/nsperm/passwd: /
> ns_perm_adduser [0m
> [1;32m[05/Mar/2024:12:16:35][189217.7feadab59640][-conn:dev.daidze.org:default:0:0-]
> [0m [0;34mDebug: --- ns_perm_adduser /opt/ns/modules/nsperm/passwd / {{""}
> {} {} {} {} {} {}} [0m
> [1;32m[05/Mar/2024:12:16:35][189217.7feadab59640][-conn:dev.daidze.org:default:0:0-]
> [0m [0;34mDebug: --- ns_perm_adduser /opt/ns/modules/nsperm/passwd /
> {nsadmin ubphBI5lDzSYc {} {} {} {} {}} [0m
> [1;32m[05/Mar/2024:12:16:35][189217.7feadab59640][-conn:dev.daidze.org:default:0:0-]
> [0m [0;34mDebug: --- ns_perm_adduser /opt/ns/modules/nsperm/passwd /
> {nobody {} {} {} {} {} {}} [0m
> [1;32m[05/Mar/2024:12:16:35][189217.7feadab59640][-conn:dev.daidze.org:default:0:0-]
> [0m [0;34mDebug: --- ns_perm_adduser /opt/ns/modules/nsperm/passwd / {} [0m
> [1;32m[05/Mar/2024:12:16:35][189217.7feadab59640][-conn:dev.daidze.org:default:0:0-]
> [0m [0;34mDebug: ### Request SetUrl calls Ns_UrlPathDecode '/index.adp' [0m
> [1;32m[05/Mar/2024:12:16:35][189217.7feadab59640][-conn:dev.daidze.org:default:0:0-]
> [0m [0;34mDebug:  ### decoded path '/index.adp' [0m
> [1;32m[05/Mar/2024:12:16:35][189217.7feadab59640][-conn:dev.daidze.org:default:0:0-]
> [0m [0;34mDebug: AdpPageRequest for
> '/opt/ns/clients/dev/client/pages/index.adp' access ok [0m
> [1;32m[05/Mar/2024:12:16:35][189217.7feadab59640][-conn:dev.daidze.org:default:0:0-]
> [0m [0;34mDebug: nslog called with driver pattern '(null)' via driver
> 'nsssl' req: GET / HTTP/1.0 [0m
> [1;32m[05/Mar/2024:12:16:35][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:16:...
 
[truncated message content]