I've enabled debugging to try to find the spooler-on-linux problem I'm having.
Unfortunately, I don't think it's very enlightening:
[-driver:nssock-] Debug: Spooler: 0: started fd=7: 1687742 bytes
[-conn:amagnatune:0] Debug: ns:interptrace[amagnatune]: create nslog:initinterp
/usr/local/naviserver/logs/access.log
[-conn:amagnatune:0] Debug: ns:interptrace[amagnatune]: allocate ns:tcltrace
ns_init
[-conn:amagnatune:0] Notice: upload.tcl: /upload.tcl:
[-conn:amagnatune:0] Debug: ns:interptrace[amagnatune]: deallocate ns:tcltrace
ns_cleanup
Once, I also saw this (but not on repeated runs) which shows the driver debug
info is being logged:
[-driver:nssock-] Debug(ns:driver): SockRelease: Unable to shutdown socket (9:
Bad file descriptor), sock: -1, peer: 64.62.148.4:3361, request:
I'm using Vlad's upload.tcl example, to keep things simple. I'm attaching the
larger naviserver startup log, in case that helps.
When I set
ns_param maxupload 2048000000
the debug log implies the spooler is working correctly, it's just that the
old-style MIME parser kicks in at the end, using lots of memory:
[-driver:nssock-] Debug: Spooler: 0: started fd=7: 1687742 bytes
[-conn:amagnatune:0] Debug: ns:interptrace[amagnatune]: create nslog:initinterp
/usr/local/naviserver/logs/access.log
[-conn:amagnatune:0] Debug: ns:interptrace[amagnatune]: allocate ns:tcltrace
ns_init
[-conn:amagnatune:0] Notice: upload.tcl: /upload.tcl: 778212 1687742
[-conn:amagnatune:0] Debug: ns:interptrace[amagnatune]: deallocate ns:tcltrace
ns_cleanup
[-conn:amagnatune:0] Debug: ns:interptrace[amagnatune]: allocate ns:tcltrace
ns_init
[-conn:amagnatune:0] Notice: upload.tcl: /upload.tcl: 1074812 1687742
[-conn:amagnatune:0] Debug: ns:interptrace[amagnatune]: deallocate ns:tcltrace
ns_cleanup
[-conn:amagnatune:0] Debug: ns:interptrace[amagnatune]: allocate ns:tcltrace
ns_init
[-conn:amagnatune:0] Notice: upload.tcl: /upload.tcl: 1383236 1687742
[-conn:amagnatune:0] Debug: ns:interptrace[amagnatune]: deallocate ns:tcltrace
ns_cleanup
[-conn:amagnatune:0] Debug: ns:interptrace[amagnatune]: allocate ns:tcltrace
ns_init
[-conn:amagnatune:0] Notice: upload.tcl: /upload.tcl: 1687316 1687742
[-conn:amagnatune:0] Debug: ns:interptrace[amagnatune]: deallocate ns:tcltrace
ns_cleanup
[-spooler0-] Debug: spooling content to file: readahead=1024, filesize=1687743
[-conn:amagnatune:0] Debug: ns:interptrace[amagnatune]: allocate ns:tcltrace
ns_init
[-conn:amagnatune:1] Debug: ns:interptrace[amagnatune]: create nslog:initinterp
/usr/local/naviserver/logs/access.log
[-conn:amagnatune:1] Debug: ns:interptrace[amagnatune]: allocate ns:tcltrace
ns_init
[-conn:amagnatune:0] Debug: ns:interptrace[amagnatune]: deallocate ns:tcltrace
ns_cleanup
[-conn:amagnatune:1] Notice: upload.tcl: /upload.tcl:
[-conn:amagnatune:1] Debug: ns:interptrace[amagnatune]: deallocate ns:tcltrace
ns_cleanup
Other question you guys had:
- CVS -- I had previously used the sourceforge CVS from my memory, there wasn't
a link I followed. I'm now using the bitbucket source.
- nslog - I added "ns_logctl severity Debug(ns:driver) true" to my config file.
-john
[22/Nov/2009:21:30:37][9461.b7b878d0][-main-] Notice: prebind: tcp:
64.62.148.10:80 = 6
[22/Nov/2009:21:30:37][9461.b7b878d0][-main-] Notice: prebind: tcp:
64.62.148.10:81 = 7
[22/Nov/2009:21:30:37][9461.b7b878d0][-main-] Notice: binder: started
[22/Nov/2009:21:30:37][9461.b7b878d0][-main-] Notice: nsmain: enable progess
statistics for uploads >= 1048576 bytes
[22/Nov/2009:21:30:37][9461.b7b878d0][-main-] Debug: encoding: loaded: utf-8
[22/Nov/2009:21:30:37][9461.b7b878d0][-main-] Notice: nsmain: NaviServer/4.99.3
starting
[22/Nov/2009:21:30:37][9461.b7b878d0][-main-] Notice: nsmain: security info:
uid=65534, euid=65534, gid=65534, egid=65534
[22/Nov/2009:21:30:37][9461.b7b878d0][-main-] Notice: nsmain: Tcl version: 8.5.8
[22/Nov/2009:21:30:37][9461.b7b878d0][-main-] Notice: nsmain: max files:
FD_SETSIZE = 1024, rl_cur = 1024, rl_max = 1024
[22/Nov/2009:21:30:38][9461.b7b878d0][-main-] Notice: nsd/init.tcl[amagnatune]:
booting virtual server: tcl system encoding: "utf-8"
[22/Nov/2009:21:30:38][9461.b7b878d0][-main-] Notice: modload: loading
/usr/local/naviserver/bin/nssock.so
[22/Nov/2009:21:30:38][9461.b7b878d0][-main-] Notice: nssock: enable 1 spooler
thread(s) for uploads >= 1024 bytes
[22/Nov/2009:21:30:38][9461.b7b878d0][-main-] Notice: modload: loading
/usr/local/naviserver/bin/nslog.so
[22/Nov/2009:21:30:38][9461.b7b878d0][-main-] Notice: nslog: opened
'/usr/local/naviserver/logs/access.log'
[22/Nov/2009:21:30:38][9461.b7b878d0][-main-] Debug: nsd/init.tcl: loading
/usr/local/naviserver/tcl/init.tcl
[22/Nov/2009:21:30:38][9461.b7b878d0][-main-] Debug: nsd/init.tcl: loading
/usr/local/naviserver/tcl/cache.tcl
[22/Nov/2009:21:30:38][9461.b7b878d0][-main-] Debug: nsd/init.tcl: loading
/usr/local/naviserver/tcl/charsets.tcl
[22/Nov/2009:21:30:38][9461.b7b878d0][-main-] Debug: nsd/init.tcl: loading
/usr/local/naviserver/tcl/compat.tcl
[22/Nov/2009:21:30:38][9461.b7b878d0][-main-] Debug: nsd/init.tcl: loading
/usr/local/naviserver/tcl/config.tcl
[22/Nov/2009:21:30:38][9461.b7b878d0][-main-] Notice: limits: default:
maxrun=100 maxwait=100 maxupload=10240000 timeout=60
[22/Nov/2009:21:30:38][9461.b7b878d0][-main-] Notice: adp[amagnatune]: mapped
{GET HEAD POST} /*.adp
[22/Nov/2009:21:30:38][9461.b7b878d0][-main-] Notice: tcl[amagnatune]: mapped
{GET HEAD POST} *.tcl
[22/Nov/2009:21:30:38][9461.b7b878d0][-main-] Debug: nsd/init.tcl: loading
/usr/local/naviserver/tcl/fastpath.tcl
[22/Nov/2009:21:30:38][9461.b7b878d0][-main-] Debug: nsd/init.tcl: loading
/usr/local/naviserver/tcl/file.tcl
[22/Nov/2009:21:30:38][9461.b7b878d0][-main-] Notice: conf:
[ns/server/amagnatune]enabletclpages = 0
[22/Nov/2009:21:30:38][9461.b7b878d0][-main-] Debug: nsd/init.tcl: loading
/usr/local/naviserver/tcl/form.tcl
[22/Nov/2009:21:30:38][9461.b7b878d0][-main-] Debug: nsd/init.tcl: loading
/usr/local/naviserver/tcl/http.tcl
[22/Nov/2009:21:30:38][9461.b7b878d0][-main-] Debug: nsd/init.tcl: loading
/usr/local/naviserver/tcl/limits.tcl
[22/Nov/2009:21:30:38][9461.b7b878d0][-main-] Notice: limits: default:
maxrun=100 maxwait=100 maxupload=10240000 timeout=60
[22/Nov/2009:21:30:38][9461.b7b878d0][-main-] Debug: nsd/init.tcl: loading
/usr/local/naviserver/tcl/nstrace.tcl
[22/Nov/2009:21:30:38][9461.b7b878d0][-main-] Debug: nsd/init.tcl: loading
/usr/local/naviserver/tcl/sendmail.tcl
[22/Nov/2009:21:30:38][9461.b7b878d0][-main-] Debug: nsd/init.tcl: loading
/usr/local/naviserver/tcl/util.tcl
[22/Nov/2009:21:30:38][9461.b7b878d0][-main-] Debug:
ns:interptrace[amagnatune]: deallocate ns:tcltrace ns_cleanup
[22/Nov/2009:21:30:38][9461.b7b878d0][-main-] Debug: ns:markfordelete: true
[22/Nov/2009:21:30:38][9461.b7b878d0][-main-] Debug: ns:callback: prestartup:
ns:tclcallback _ns_load_global_modules
[22/Nov/2009:21:30:38][9461.b7b878d0][-main-] Debug:
ns:interptrace[amagnatune]: create nslog:initinterp
/usr/local/naviserver/logs/access.log
[22/Nov/2009:21:30:39][9461.b7b878d0][-main-] Debug:
ns:interptrace[amagnatune]: allocate ns:tcltrace ns_init
[22/Nov/2009:21:30:39][9461.b7b878d0][-main-] Debug:
ns:interptrace[amagnatune]: deallocate ns:tcltrace ns_cleanup
[22/Nov/2009:21:30:39][9461.b7b878d0][-main-] Notice: driver: starting: nssock
[22/Nov/2009:21:30:39][9461.b498bb90][-driver:nssock-] Notice: nssock:
listening on 64.62.148.10:80
[22/Nov/2009:21:30:39][9461.b498bb90][-driver:nssock-] Notice: driver:
accepting connections
[22/Nov/2009:21:30:39][9461.b7b878d0][-main-] Notice: nsmain: NaviServer/4.99.3
running
[22/Nov/2009:21:30:39][9461.b7b878d0][-main-] Notice: nsmain: security info:
uid=65534, euid=65534, gid=65534, egid=65534
[22/Nov/2009:21:30:39][9461.b7b878d0][-main-] Warning: prebind: closed unused
TCP socket: 64.62.148.10:81 = 7
[22/Nov/2009:21:30:39][9461.b7b878d0][binder] Notice: binder: stopped
[22/Nov/2009:21:30:39][9461.b418ab90][-spooler0-] Notice: spooler0: accepting
connections
[22/Nov/2009:21:30:39][9461.b7b7bb90][-command-] Debug:
ns:interptrace[amagnatune]: create nslog:initinterp
/usr/local/naviserver/logs/access.log
[22/Nov/2009:21:30:39][9461.b5251b90][-sched-] Notice: sched: starting
[22/Nov/2009:21:30:39][9461.b7b7bb90][-command-] Debug:
ns:interptrace[amagnatune]: allocate ns:tcltrace ns_init
[22/Nov/2009:21:30:42][9461.b498bb90][-driver:nssock-] Debug: Spooler: 0:
started fd=7: 1687742 bytes
[22/Nov/2009:21:30:44][9461.b3989b90][-conn:amagnatune:0] Debug:
ns:interptrace[amagnatune]: create nslog:initinterp
/usr/local/naviserver/logs/access.log
[22/Nov/2009:21:30:44][9461.b3989b90][-conn:amagnatune:0] Debug:
ns:interptrace[amagnatune]: allocate ns:tcltrace ns_init
[22/Nov/2009:21:30:45][9461.b3989b90][-conn:amagnatune:0] Notice: upload.tcl:
/upload.tcl:
[22/Nov/2009:21:30:45][9461.b3989b90][-conn:amagnatune:0] Debug:
ns:interptrace[amagnatune]: deallocate ns:tcltrace ns_cleanup
------------------------------------------------------------------------------
Let Crystal Reports handle the reporting - Free Crystal Reports 2008 30-Day
trial. Simplify your report design, integration and deployment - and focus on
what you do best, core application coding. Discover what's new with
Crystal Reports now. http://p.sf.net/sfu/bobj-july
_______________________________________________
naviserver-devel mailing list
naviserver-devel@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/naviserver-devel
