servedat produces a log of individual transactions, errors, and state changes. This information is vital to diagnosing problems, and can also be used to generate usage statistics and inform other processes of the state of transactions.
Logging output normally consists of UTF-8 encoded character lines in the format described below. Enabling Debugging may cause non-UTF-8 data to be written to the logs. See the Unicode section for more information about ExpeDat character encoding.
When servedat is run from a terminal or command shell, logs are sent to stderr by default.
Windows: If servedat is run as a Windows Service, it will default to writing diagnostics to
"C:\ProgramData\DEI\servedat.log" on Windows 7, or later.
You can use the enclosed "Shortcut to servedat.log" link to open the log file, if the log exists.
All Others: If servedat is run as a root daemon in unix it will default to writing diagnostic output to the file /var/log/servedat.log or /var/adm/servedat.log, depending on the platform.
If you wish to redirect logs to a file or pipe, use the -l command line option or the LogFile configuration variable. The argument can be a pathname, or the number "1" to specify stdout, or the number "2" to specify stderr. For example, the following commands both redirect output to the file "/tmp/servedat.log".
# servedat -l 1 >> /tmp/servedat.log C 20190816 14:14:57.807 servedat 1.18.3 "July 2019" DEI  C 20190816 14:14:57.807 Switching logs to <stdout> # servedat -l /tmp/servedat.log C 20190816 14:17:03.201 servedat 1.18.3 "July 2019" DEI  C 20190816 14:17:03.202 Switching logs to /tmp/servedat.log
The -l and LogFile options are processed as soon as they are found, so multiple declarations may cause the logfile to change multiple times during startup. Since command line options are processed first and have priority over configuration files, the best practice is to use "-l" to send logging output to your intended destination as soon as possible.
Each line of diagnostic output takes the following form:
[Type] [Date] [Time] [Message]
|Type||A single character denoting the type of message:|
|C||Comment or Configuration note.|
|E||General server error.|
|F||The final disposition of an action. There will be exactly one of these per action.|
|I||Initiation of an action which has not yet completed. There will be at most one of these per action.|
|M||Message from one of the MTP libraries.|
|P||A progress update for an ongoing transaction. These occur at intervals of LogProgress seconds.|
|R||A session report from a client.|
|S||A status update showing server load and transaction counts. These occur at intervals of LogStatus seconds while the server is active. See the Monitoring section for details.|
|T||Message from one of the application toolkit libraries.|
|V||Version and process id, displayed once at each startup.|
|W||Warning or detailed error regarding an action in progress.|
|Date||Eight characters giving the year, month, and day as YYYYMMDD|
|Time||Twelve characters giving the hour, minute, seconds, and milliseconds in localtime as HH:MM:SS.mmm|
|Message||The message format will vary for C, D, E, M, and W. I, P, F, and S type messages conform to the format below.|
Particular attention should be paid to all E, M, T, and W messages as these may indicate improper operation.
Initiation, Progress, Final, and Report log messages (I, P F, and R) share the same initial format:
DocID SEND|GET Client User Encrypt "Path" "Action"
|DocID||An 8 character hexadecimal transaction identifier. No two transactions can have the same DocID at the same time, and IDs will not be reused for a long time.|
|SEND|GET||Incoming documents are be labeled "SEND". Outgoing documents and most actions are labeled "GET".|
|Client||The IP address and UDP port number of the client application, separated by a colon.|
|User||The username used for authentication, or "-" if none was provided.|
|Encrypt||The content encryption method, or "-" if none was used.|
|Path||The pathname of the local file target. This may be absolute or relative to the applicable home directory.|
|Action||For actions or object handler requests, the specifier used to process the document will appear here.|
|P lines occur at intervals of LogProgress seconds during each transaction and have additional fields in the form: Progress Size "Duration (Speed)" Load|
|Progress||The amount of network data transferred up to this point.|
|Size||The full size of the data, if this is known. Transactions involving compression, streaming, object handlers, or actions may set this field to 0.|
|Duration||The time elapsed so far.|
|Speed||The average network speed since the last update.|
|Load||The current server load.|
|F lines occur at the end of each transaction and have additional fields in the form: Transfer (Class,Error) "Comment"|
|Transfer||The amount of network data transferred up to the point of the log. If an error has occurred, this value may only be approximate or it may be given as "-" if a value is not known. Note that if a transfer is compressed or resumed, the amount here may be much less than the size of the file on disk.|
|Class||Zero for successes, otherwise an MTP/IP error class, indicating the source of the error. Most errors will be class "-4", indicating that servedat itself is reporting the error.|
|Error||Zero for successes, otherwise an MTP/IP error code, indicating the type of error. See DEI Tech Note 0013 for information about error classes and codes.|
|Comment||A human readable string describing the result. On success it will show the time elapsed and overall transfer rate. On failure, it will show an error message.|
|R lines are created by client software to report events such as the beginning and end of a session involving multiple transactions. The "Path" portion of an R record contains the client's report which should be in the following format: Client-Version Operation UUID Message|
|Client||The client type, such as "movedat" or "syncdat".|
|Version||The version number of the client executable.|
|Operation||The type of operation being reported.|
|UUID||A unique string matching this R record to others from the same operation.|
|Message||The remainder of the report depends on the client. See its documentation for details.|
Status lines, S, occur at intervals of LogStatus seconds while the server is active and consist of the following comma separated fields:
|Load Current:||The current number of active transactions.|
|MaxConfig:||The peak load since the last configuration reload.|
|MaxStart:||The peak load since the last restart.|
|TotalStart:||The total number of transactions attempted since startup.|
|Errors:||The number of server errors since startup.|
If this number is non-zero, look for 'E' logs and correct any problems. Approximately equals the number of 'E' log entries.
|Failures:||The number of transactions which started, but then failed before completion.|
|Warnings:||The number of server or transaction warnings issued since startup. Approximately equals the number of 'W' log entries.|
|Denials:||The number of transactions which were rejected due to authentication or other configuration issues.|
|The number of transactions which were rejected because the Load had already reached the server's capacity. These events are also included in the Denials count.|
|The total bytes of data transferred in and out since the last configuration change.|
|The bandwidth consumed incoming and outgoing averaged over the previous minute, in bits per second.|
|Bad Checksums||The number of datagrams dropped due to bad checksums.|
|Bad Headers||The number of datagrams dropped due to improperly formatted headers.|
Following is a sample of servedat log output illustrating the format:
V 20190816 14:22:36.019 servedat 1.18.3 "July 2019" DEI  (8080) C 20190816 14:22:36.020 (C)2019 Data Expedition, Inc.(R) US Pat. 7158479,7313627,7404003,7630315,8014289 C 20190816 14:22:36.020 servedat - 1.18.3 July 2019 - DEI, DOC-2.3.2 MTP-osx-4.2.9 3077 C 20190816 14:22:36.020 00000001: ExpeDat-1.18D Data Expedition Inc., DataExpedition.com C 20190816 14:22:36.022 Default Home: /Library/WebServer/Documents/downloads/ S 20190816 14:22:36.022 Load Current: 0, MaxConfig: 0, MaxStart: 0, TotalStart: 0, Errors: 0, Failures: 0, Warnings: 0, Denials: 0, Capacity Exceeded: 0, Bytes In: 0, Out: 0, Bandwidth In: 0, Out: 0 , Bad Checksums: 0, Bad Headers: 0 I 20190816 14:46:35.130 F5FBCE5C GET 220.127.116.11:49928 - - "tst/" "" F 20190816 14:46:35.131 F5FBCE5C GET 18.104.22.168:49928 - - "tst/" "" 256 (0,0) "0.00 seconds (2.05 megabits/sec)" I 20190816 14:46:38.883 F5FBCE5D GET 22.214.171.124:61412 - - "tst/test5mb.dat" "" F 20190816 14:46:38.952 F5FBCE5D GET 126.96.36.199:61412 - - "tst/test5mb.dat" "" 5242880 (0,0) "0.07 seconds (608 megabits/sec)"
By default, servedat logs the successful initiation and any completion for all transactions. You may reduce the amount of logging by setting the LogFilter level. This may be desirable if logs are being written to slow storage or parsed by another process, and high volume is impeding performance. Filtering applies first to "bulk" transactions such as SyncDat synchronizations and one-at-a-time directory transfers. At higher levels, it will be applied to regular transactions as well. The default filtering level is 0 (no filtering).
|0||No filtering: at least one line of output will be logged for every transaction.|
|1||Initiation of bulk transactions will not be logged.|
|2||Success reports for bulk transactions will also be suppressed. Bulk errors will still be reported.|
|3||All initiation lines and bulk successes will be suppressed.|
|4||Only errors will be logged.|
|5||Only server-wide and non-bulk errors will be logged.|
|6||Only server-wide errors will be logged|
Setting a debug level of 1 or higher will automatically reset LogFilter to level 0.
During startup, servedat will check the size of the specified log file and rotate it if it has grown larger than LogRotate megabytes. Rotation consists of renaming the existing log file with the local date and time, then starting a new log file.
On unix systems, sending the HUP signal to a running servedat process will cause it to restart and reload all of its configuration settings. This includes closing and reopening the log file. If the existing log file has grown beyond the LogRotate limit, it will be rotated. You can manually rotate the log file by renaming it prior to sending HUP. Note that any active transactions will be interrupted by a HUP restart.
If you experience difficulties configuring or communicating with the server, the servedat logs will provide important information about what happened. Each time servedat is started, it will display a V line indicating its version, process id, and network port. Whenever a log file is specified or diagnostic output is enabled, servedat will also display a list of all configuration settings and their origins. For example:
D 20151218 13:24:50.601 Configuration settings: D 20151218 13:24:50.601 [-A] AuthFile 13 System /etc/svpasswd D 20151218 13:24:50.601 AuthReload 5 Default D 20151218 13:24:50.601 [-b] StreamSize 16384 Default D 20151218 13:24:50.601 [-c] Capacity 15 License D 20151218 13:24:50.601 [-d] Debug 0 System D 20151218 13:24:50.601 [-F] LogFilter 0 Default D 20151218 13:24:50.601 [-I] LogProgress 300 Default D 20151218 13:24:50.601 [-J] LogStatus 600 Default D 20151218 13:24:50.601 [-l] LogFile 3 Default /var/log/servedat.log D 20151218 13:24:50.601 LogRotate 25 Default D 20151218 13:24:50.601 [-M] FileMode 420 Default D 20151218 13:24:50.601 [-p] Port 8080 Default D 20151218 13:24:50.601 [-V] ServiceName 8 Default servedat D 20151218 13:24:50.601 [-S] SysAuth 1 System D 20151218 13:24:50.601 AllowEncrypt 1 Default D 20151218 13:24:50.601 HomeDir 39 System /Library/WebServer/Documents/downloads/
Please send a copy of all relevant log lines with any support request. Be sure to include the startup lines include the V line and the configuration settings. When in doubt, compress and email the entire file!