Harmonic Confidential

Harmonic Confidential

Asia Pacific Support Centre, Singapore

− Tel +65 6671 1450 (Singapore)

− Fax +65 6671 1454
− Email APACSupport@harmonicinc.com

Omneon 24/7 Technical Assistance Centre, Singapore

− Tel +65 6542 0050

Omneon 24/7 Technical Assistance Centre, USA

− Tel +1 408 585 5200

 Harmonic Confidential

Log messages are a diagnostic tool

They represent was is happening or shouldn’t be happening in the
system
Error (and warning) Messages are usually low level and cryptic,
subsystem X encountered problem Y. And, unfortunately sometimes
only meaningful to the programmer.
Generally need to gather many short simple error messages together to
see the higher level problem.
Debug messages are usually disabled. They can be selectively enabled
to diagnose specific problems.
Debug messages are usually less cryptic and more meaningful on their
own.
Log files are almost always needed to diagnose problems. Usually also
need some debugging enabled.
Harmonic Confidential
Harmonic Confidential
 Harmonic Confidential

Processors generate messages

All processors send messages to all other processors
Hosts accept messages from MediaPorts
Processors hold last 1024 messages
Hosts send messages to monitor clients via network
Maximum 6 clients per host
Host sends all currently held messages on initial connect (or reconnect)
 Harmonic Confidential

Fss writes log file in /fs/omD9xxxx/logs

Maximum of twenty files
Creates a new file on reboot when less than 20 files
Appends to latest file if have 20 files
Deletes oldest file to maintain 20 file.
Maximum of 10 MB per file
File name consists of Director serial number, date and time of file
creation
Harmonic Confidential
 Harmonic Confidential

Message consists of: date, time, level, source, message

Date and time are processor time when the message is generated (or host time
when received from a mediaPort)
Level is one of:
D – debug
I - informational
W – warning
E – error
F – fatal
Note that level is subjective and determined by the programmer.
Source is the originator of the message and may be any of Host0, Host1, Fss, or a
mediaPort identifier.

Monitor now date stamps with month and day just like the disk log.
 Harmonic Confidential

Which time zone?

Internally, time is kept in GMT.
Monitor uses the PC time zone.
The FSS log file uses the Director time zone setting and is stated in an
initial log message
−Log started 3/16/2006 12:06:22 JST
Sample message:
03/16 04:04:35 D Host0 player PRV-DEC-1A: attach /vol1/clip.dir/ZHDTEST6582.mov
0(0)/146(146)/146/0 0x0 SA 0 1/2724830648 0x0 64
 Harmonic Confidential

Some messages use a clamping mechanism to throttle message

generation.
The style of clamping Is chosen by the programmer
One style limits messages to one per X amount of time
One style limits messages to when a condition begins and when it ends
Another clamping mechanism prefixes a short note on messages
indicating operation:
−“[drop…]” indicates that further similar messages may be dropped
−“[5 delayed 00:00:12]” indicates 5 messages were dropped and this
message is 12 seconds old
 Harmonic Confidential

Disk log file may be incomplete when having disk problems.

Monitor can write log files on PC. See logging menu item in monitor.
Monitor log files are written in a subfolder of the folder where the
monitor executable resides.
Log file names start with pcLog_ and contain the director serial number
and file creation time.
 Harmonic Confidential

Note that the log is save in the Directory where monitor.exe is running.
 Harmonic Confidential

History of the Bad blocks in the disks is saved in BadblockhistoryXXXX.log For a

new system with no bad blocks, this file will not appear.
 Harmonic Confidential

Playertest command required to be handle with care, not for user unless told
and by trained personal.

D:
Cd Omneon\bin
D:\omneon\bin\playertest 192.168.111.1 ( playertest <Director IP address>)
> debug ( to see debug parameter set)
>Debug playerdebug 1 ( this turn on the player debug which monitor the
activities of the player)
>debug louthlogging (see louthlogging document)
Harmonic Confidential
 Harmonic Confidential

Playertest is in the Main release version of the server software

under d:\Upgrades\Omneon.release-…. (see below)
Common practice is to copy this into d:\Omneon\BIN.
Harmonic Confidential
Harmonic Confidential
Harmonic Confidential
 Harmonic Confidential

list command list clips

list –allinfo lists details about clips (example list test1 –allinfo)
test1
=======
c:06/02/06 21:34:49 (GMT) Qt referenced
/fs0/clip.dir/test1.mov
t0: mpeg3.0L br:3000000 bpu:100100 29.97Hz 720x480 420 4x3 gop:15/3
/fs0/clip.dir/media.dir/test1.m2v
t1: aud.8 br:9216000 bpu:24 sr:48000.00 ch:8 f:1 be ss:3
/fs0/clip.dir/media.dir/test1.aiff
start timecode: 14:34:10,00
Userdata command lists userdata in a clip
user data for clip:test1 track:0
key:ovn_creation data(169):D2_01221H0(Branch 4.6 Dir2 built by rickst on
2006-06-02
13:30:56);tap1p1727;local_10.1.1.82:3981;Tap1P1727;ltc;/fs0/clip.dir/test1.
mov;in:0 out:107892;6/2/2006 21:34:49
 Harmonic Confidential

Clip below is a FCP exported IMX 30 clip

 Harmonic Confidential

Use debug command to get a list of variables

Most are only useful to programmers
Many can be dangerous to use because they can change the behavior of the system!!
afpDebug = 0x3000 fsvPrintXmitFsi = 0 oftpDataRetrMax = 0xa fileioDebug = 0
afdDebug = 0 fwDebug = 0 oftpLoginMax = 0x14 fileioLatencyMin = 0x7a120
asyncDebug = 0 gDebugPll = 0 raidDebug = 0 fileWatchDelay = 0x7d0
avcDebug = 0 gGenlockDebug = 0 raidVerifyIncr = 0 memPoolDbg = 0
avcDebugCmdTime = 0 gIsrCheckIrqTime = 0 rateDebug = 0 memPoolDbgTarget = 0
avcDebugSendTime = 0 gIsrCheckPciTime = 0 raidXorTqDelay = 0xc8 showContainedPerf = 0
avcDebugTimeInterim = 0 gIsrCheckTime = 0 rebuildDebug = 0x13c msgClampDbg = 0
avcTargetDebug = 0 gPlayerLoadPlayers = 0x1 playerBlackLimit = 0 klvDebug = 0
bvwDebug = 0 gTimerCheckDiff = 0x61a80 playerDebugSel = 0xffffffff mxfContainerSize = 0x3c
bvwLogging = 0 idc1Chatter = 0 playerDebugSnap = 0x64 mxfIndexSize = 0x708
clDebug = 0 idtmChatter = 0 playerDebugTime = 0 mxfDebug = 0
cmpDebug = 0 isoReserveDebug = 0 plrRpcDebugSel = 0xffffffff mxfHeaderPad = 0x2000
dcfgTokenDebugMask = 0x7 logToDisk = 0xffffffff sambaDebug = 0 mediaCopierDbg = 0
deviceRpcDebug = 0 louthLogging = 0x1 SCRDebugFlags = 0x100 defaultMpegWidth = 0x2d0
dirConfRpcDebug = 0 louthLogging1 = 0 spDispDebug = 0 quicktimeDbg = 0
efsChatter = 0 louthLogTime = 0x2710 tdpShipExecTime = 0x4e20 qtMinResizeSeconds = 0
fcDebug = 0x60 louthStatus = 0 vitcdebug = 0 qtVideoChunkSize = 0
fsDebug = 0 louthStatusCmd = 0 xspmumodes = 0x207 qtLookupDbg = 0
fsPadFillPatternChar = 0x7a louthStatusCmd1 = 0 louthDebug = 0xf mediaObjDbg = 0
fsPadUsePattern = 0 mallocDebug = 0x28 louthFilter = 0x40 mediaZombieTime = 0x1
fsPadUsePatternSmallBlock = 0 mpegEsDebug = 0 clipFileDebug = 0 FsvDebug = 0
fsPadUsePatternObuf = 0 ntpDebug = 0 bioDataDebug = 0 tdpDebug = 0
fsRpcDebug = 0 oftpDebug = 0x2 bufferIoFilter = 0 tdTraceRecorder = 0
fsrwHush = 0x1 oftpTransferWhileRecord = 0x1 bufferIoDebug = 0 tdListFilter = 0
fsvPrintDvInfo = 0 oftpTcpEnvironment = 0 bufferIoPerf = 0 tdDebug = 0
fsvPrintLgh = 0 oftpStorSockRecvbuf = 0x20000 bufferIoLookAhead = 0x1 pmuLog = 0
fsvPrintMpegInfo = 0 oftpRetrSockSendbuf = 0x20000 bufferIoResvAbsorb = 0 plrRpcDebug = 0
fsvPrintRecvFsi = 0 oftpDebugStoreFileBytes = 0 virtualArrayDbg = 0 playerDebug = 0x1
oftpDataStoreMax = 0x8 userDataChanges = 0
 Harmonic Confidential

playerdebug
− trace player level commands
− useful for API debugging or lower level VDCP debuggingrace player
API commands
louthlogging (and louthlogging1 and 2)
− trace VDCP commands
louthfilter
− filter some VDCP commands
Cldebug and fsdebug
− Trace file delete, rename or create

Note that Debug variables are set to default value after reboot. Use
debugvars in the host configuration is need to turn on during boot up.
 Harmonic Confidential

debugvar playerdebug bits

− 0x00000001 commands - all player commands (most useful)
− 0x00000002 queries - all normal player queries
− 0x00000004 configQueries - player configuration queries
− 0x00000008 frameInfo1
− 0x00000010 frameInfo2
− 0x00000020 send
− 0x00000040 recv1
− 0x00000080 recv2
− 0x00000100 clipLoad - useful for track tagging debugging
− 0x00000200 recordLock
− 0x00000400 recordLock1
− 0x00000800 vitcLock
− 0x00001000 showVitc
− 0x00002000 errors
− 0x00004000 unitInfo
− 0x00008000 recTcgInsert
 Harmonic Confidential

debugvar plrrpcdebug bits

− 0x00000001 commands useful for non-player commands
− 0x00000002 plrCommands player commands, mostly duplicates playerdebug
− 0x00000004 clipCommands clip commands like delete, copy, userdata
− 0x00000008 queries
− 0x00000010 plrQueries
− 0x00000020 clipQueries
− 0x00000040 showControl
− 0x00000080 checkControl
− 0x00000100 errors
− 0x00000200 getTime
− 0x00000400 plrStatus
− 0x00000800 callbacks
− 0x00001000 clipList
− 0x00002000 clipListChange
− 0x00004000 debugVar
− 0x00008000 dispatch
− 0x00010000 dispatch1
− 0x00020000 forceCreate
− 0x00040000 userData
 Harmonic Confidential

Playerdebug can be use to troubleshoot problem during playout or

ingesting.
Example when playout timing problem (1 second delay or freeze). This
can be use to check if the problem is from the server or the automation
control.
 Harmonic Confidential

28 09:57:08 D Host0 player Om-Ch5a: stop at 1499197 st:1597773.4d1667ef (dl:1)

28 09:57:08 D Host0 player Om-Ch5a: detach /fs0/clip.dir/390ID58U.mov S_auto 0xa243ff28 1499197
28 09:57:08 D Host0 player Om-Ch5a: loop 0 0
28 09:57:08 D Host0 player Om-Ch5a: setMinPos 1490556->0 @1499197
28 09:57:08 D Host0 player Om-Ch5a: setMaxPos 1499198->0 @1499197
28 09:57:12 D Host0 player Om-Ch5a: attach /fs0/clip.dir/390ABK9M.mov 0 250 250 0x0 SA 1
0xa243fc28 0 0
28 09:57:12 D Host0 player Om-Ch5a: setMaxPos 0->250 @0
28 09:57:15 D Host0 player Om-Ch5a: cuePlay 1.000 0 0
28 09:57:15 D Host0 player Om-Ch5a: waitForTdCued time 65 mS
28 09:57:15 D Host0 player Om-Ch5a: waitForTdCued fully time 270 mS
28 09:57:15 D Host0 player Om-Ch5a: play (rate = 1.000) (pos = 0)
Harmonic Confidential
Harmonic Confidential
Harmonic Confidential
 Harmonic Confidential

Set which VDCP port number for the logging.

Can be use during installation to check if the automation is sending the correct
VDCP command to the correct port.
Example. Automation port is 6 but connected to port 1 of Omenon. Thus the
open port command will failed.
It enable to see what command does the Automation sent to Omneon.
 Harmonic Confidential

09/27 16:18:50 D Host0 4158283,0 p00,30f:1> Ack

09/27 16:18:51 D Host0 4162204,0 p00,30f:1> Port_Status 0f 00 00 04 04 00 00 00
09/27 16:18:51 D Host0 4164079,0 p00,30f:1> Port_Status 0f 00 00 04 00 00 00 00
09/27 16:18:51 D Host0 4166791,07% p00,30f:1< Cue_With_Data 390JBBTU 00:00:00:00 00:10:00:00
09/27 16:18:51 D Host0 4166791,0 p00,30f:1> Ack
09/27 16:18:51 D Host0 4168767,0 p00,30f:1> Port_Status 0f 00 00 04 04 00 00 00
09/27 16:18:51 D Host0 4170642,0 p00,30f:1> Port_Status 0f 00 00 04 00 00 00 00
09/27 16:19:49 D Host0 4738131,30% p00,30f:2< Close_Port 06
09/27 16:19:49 E Host0 Louth closePort failed, port 0x6 not open
09/27 16:19:49 D Host0 4738132,1 p00,30f:2> Ack
09/27 16:19:49 D Host0 4739364,38% p00,30f:2< Open_Port 06 00
09/27 16:19:49 D Host0 4739365,1 p00,30f:2> Open_Port 01
09/27 16:19:50 D Host0 4744343,83% p00,30f:2< Select_Port 06
09/27 16:19:50 D Host0 4744344,1 p06,30f:2> Ack
09/27 16:19:50 D Host0 4744449,0 p06,30f:2> Port_Status 0f 01 06 00 00 00 00 10
09/27 16:19:50 D Host0 4744605,48% p06,30f:2< Disk_Preroll 00 00
09/27 16:19:50 D Host0 4744606,1 p06,30f:2> Ack
09/27 16:19:56 D Host0 4810512,24% p00,30e:3< Close_Port 05
 Harmonic Confidential

In the Media Director, there are two 32-bit system variables, namely,
louthlogging, louthlogging1 and louthlogging2.
These 3 variables need to be set in order to enable VDCP command
logging in the Media Director.
For logging VDCP ports 1-32, please set the variable louthlogging.
For logging VDCP ports 33-64, please set the variable louthlogging1.
For logging VDCP ports 65-96, please set the variable louthlogging2.
 Harmonic Confidential

Highest VDCP port number that can be monitored and logged by

playertest.exe is 96
However VDCP signal port numbers can be reused on different hosts.
To enable VDCP logging, set the values of the variables, louthlogging
and louthlogging to non-zero.
To disable VDCP logging, set the values of the variables, louthlogging
and louthlogging to zero.
 Harmonic Confidential

Additional loggings can be enabled by setting the variable, playerdebug

and the louthlogging together.
Note the different :-
• Set the value of playerdebug to 1 to enable
logging.
• Set the value of playerdebug to 0 to disable
logging.
• Playerdebug affects all ports on a host
• Louthlogging only affect specific ports on a host
 Harmonic Confidential

In the System Manager PC, open a DOS command window and type the
following commands:
d:
cd \omneon\bin
playertest <IP address of host>
debugvar louthlogging <value in hex>
debugvar louthlogging1 <value in hex>
debugvar playerdebug <value in hex>
 Harmonic Confidential

To turn on logging for VDCP ports 5 and 3, the associated bits for each
port need to be enabled (i.e. cells with RED numbers)
In this case the binary bits are
0000 0000 0000 0000 0000 0000 0001 0100
which is equivalent to hex value 14.
So the playertest command would be:
debugvar louthlogging 0x14
(the prefix 0x indicates that the following value is in hexadecimal)
 Harmonic Confidential

To turn on logging for VDCP ports 32, 16 and 10, the associated bits for
each port need to be enabled (i.e. cells with RED numbers)
In this case the binary bits are:
1000 0000 0000 0000 1000 0010 0000 0000
which is equivalent to hex value 80008200
So the playertest command would be:
debugvar louthlogging 0x80008200
(the prefix 0x indicates that the following value is in hexadecimal)
 Harmonic Confidential

To turn on logging for VDCP port 35, the associated bit for the port
needs to be enabled (i.e. cell with RED number)
In this case the binary bits are:
0000 0000 0000 0000 0000 0000 0000 0100
which is equivalent to hex value 4
So the playertest command would be:
debugvar louthlogging1 0x4
(the prefix 0x indicates that the following value is in hexadecimal)
 Harmonic Confidential

Launch Windows Calculator and set it to “Scientific” mode to assist you

in Binary to Hexadecimal conversion
 Harmonic Confidential

Command message format:

Timestamp in tenths of milliseconds, time relative to Top-Of-Frame, VDCP port

number, partial mediaPort GUID, mediaPort unit number, “<”, command name,
arguments

11 09:29:40 D Host0 11118214,30% p01,5ac:0< Play_Cue test4

Response message format:

Timestamp in tenths of milliseconds, elapsed time, VDCP port number, partial

mediaPort GUID, mediaPort unit number, “>”, response

11 09:29:40 D Host0 11118220,6 p01,5ac:0> Ack

 Harmonic Confidential

Messages may be filtered.

Some messages are always filtered. The port status, system status and position
request commands are filtered as follows:
− Only the response is shown, not the command
− Response is only shown when it is different from the last response

The following commands may be filtered out completely:

− IDs added list
− IDs deleted list
− ID request
− ID size request
− Active ID request
− Port status
− System status
− Position status
− All other status
 Harmonic Confidential

Hex Louth Filters Description

0x1 idsAdded IDs added list
0x2 idsDeleted IDs deleted list
0x4 idRequest ID request
0x8 idSize ID size request
0x10 activeId Active ID request
0x20 systemStatus System status
0x40 position Position status
0x80 portStatus Port status
0x100 allOther All other status
 Harmonic Confidential

05/18 13:12:24 D Host0 1861197451,21% p05,b9a:0< Stop

05/18 13:12:24 D Host0 1861197452,1 p05,b9a:0> Ack
05/18 13:12:24 D Host0 player CONFI2SVR2: stop at 374 st:47598610.f8c4b5e2 (dl:1)
05/18 13:12:25 D Host0 1861197839,1 p05,b9a:0> Port_Status 05 01 05 00 00 00
05/18 13:12:25 D Host0 player CONFI1SVR2: detach all clips
05/18 13:12:25 D Host0 player CONFI1SVR2: setPos 0 0x00000000
05/18 13:12:25 D Host0 1861198254,22% p05,b9a:0< VarId Play_Cue 823100
05/18 13:12:25 D Host0 1861198260,6 p05,b9a:0> Ack
05/18 13:12:25 D Host0 player CONFI1SVR2: attach /fs0/clip.dir/907060.mov 51151 52651 1500 0x0 SA 1 0xa241ba18 0 0
05/18 13:12:25 D Host0 player CONFI1SVR2: setMaxPos 0->1500 @0
05/18 13:12:25 D Host0 player CONFI1SVR2: setPos 0 0x00000000
05/18 13:12:25 D Host0 player CONFI1SVR2: cuePlay 1.000 0 0
05/18 13:12:25 D Host0 1861198685,0 p05,b9a:0> Port_Status 05 02 05 00 00 00
05/18 13:12:25 D Host0 player CONFI2SVR2: loop 0 0
05/18 13:12:25 D Host0 player CONFI2SVR2: detach all clips
05/18 13:12:25 D Host0 player CONFI2SVR2: detach /fs0/clip.dir/822752.mov SB 0xa26b9338 374
05/18 13:12:25 D Host0 player CONFI2SVR2: setMaxPos 375->0 @374
05/18 13:12:25 D Host0 player CONFI2SVR2: setPos 0 0x00000000
05/18 13:12:25 D Host0 player CONFI2SVR2: stop at 0 st:47598618.eee97400 (dl:1)
05/18 13:12:25 D Host0 player CONFI2SVR2: detach all clips
05/18 13:12:25 D Host0 player CONFI2SVR2: setPos 0 0x00000000
05/18 13:12:25 D Host0 player CONFI2SVR2: attach /fs0/clip.dir/823100.mov 127001 127376 375 0x0 SA 1 0xa26b9338 0 0
05/18 13:12:25 D Host0 player CONFI2SVR2: setMaxPos 0->375 @0
05/18 13:12:25 D Host0 player CONFI2SVR2: setPos 0 0x00000000
05/18 13:12:25 D Host0 player CONFI2SVR2: cuePlay 1.000 0 0
05/18 13:12:25 D Host0 player CONFI1SVR2: waitForTdCued time 370 mS
05/18 13:12:25 D Host0 player CONFI2SVR2: waitForTdCued time 350 mS
05/18 13:12:26 D Host0 1861207873,8 p82,bb8:0> Syst_Status_Req 01 00 51 07 99 00 51 07 99
05/18 13:12:26 D Host0 player CONFI1SVR2: waitForTdCued fully time 260 mS
05/18 13:12:26 D Host0 player CONFI1SVR2: play (rate = 0.000) (pos = 0)
05/18 13:12:26 D Host0 player CONFI2SVR2: waitForTdCued fully time 260 mS
05/18 13:12:26 D Host0 1861217838,1 p05,b9a:0> Port_Status 05 80 05 00 00 00
05/18 13:12:27 D Host0 1861218230,15% p05,b9a:0< Still
05/18 13:12:27 D Host0 1861218231,1 p05,b9a:0> Ack
05/18 13:12:27 D Host0 player CONFI2SVR2: play (rate = 0.000) (pos = 0)
05/18 13:12:27 D Host0 1861218637,1 p05,b9a:0> Port_Status 05 08 05 00 00 00
 Harmonic Confidential

29 09:27:58 D Host0 player ING1SVR1: attach /fs0/clip.dir/rec001ACR5124S00TEST12.mov 2041500(0)/2041900(0)/400/0 0x0 SA 0

1/2722266936 0x0 64
29 09:27:58 D Host0 player ING1SVR1: setMaxPos 0->400 @0
29 09:27:58 D Host0 player ING1SVR1: setPos 0 0x00000000
29 09:27:58 D Host0 player ING1SVR1: cueRecord 0
29 09:27:59 D Host0 469368261,10 p81,de8:0> Syst_Status_Req 02 52 48
29 09:27:59 D Host0 469377853,98% p81,de8:0< Record (0, 600069)
29 09:27:59 D Host0 469377854,1 p81,de8:0> Ack
29 09:28:00 D Host0 469378253,0 p81,de8:0> Port_Status 05 06 81 00 00 00
29 09:28:00 D Host0 469378652,1 p81,de8:0> Position_Request (1)SOM-based 22:41:00:00
29 09:28:01 D Host0 player ING1SVR1: recordDelay del:0 st:97803247.c014692f hf:97803237.505eddf7
29 09:28:01 D Host0 469390255,0 p81,de8:0> Port_Status 05 84 81 00 00 00
29 09:28:01 D Host0 469395056,1 p81,de8:0> Position_Request (1)SOM-based 22:41:00:11
...................Position Request messages (not part of log shown) ..................................
29 09:28:15 D Host0 469531845,0 p81,de8:0> Position_Request (1)SOM-based 22:41:14:03
29 09:28:15 D Host0 469537040,1 p81,de8:0> Position_Request (1)SOM-based 22:41:14:16
29 09:28:15 D Host0 469537840,92% p81,de8:0< Stop
29 09:28:15 D Host0 469537840,0 p81,de8:0> Ack
29 09:28:15 D Host0 player ING1SVR1: stop at 358 st:97803617.af9f6abf (dl:11)
29 09:28:16 D Host0 469538245,0 p81,de8:0> Port_Status 05 01 81 00 00 00
29 09:28:17 D Host0 player ING1SVR1: loop 0 0
29 09:28:17 D Host0 player ING1SVR1: detach all clips
29 09:28:17 D Host0 player ING1SVR1: detach /fs0/clip.dir/rec001ACR5124S00TEST12.mov SB 0xa2427f38 0/1 0 369
 Harmonic Confidential

The debugvar for getting log messages on clip deleted, rename or

created are:-
Debug fsdebug 0xb0000
Debug cldebug 1
 Harmonic Confidential

Debug setting Playertest will be reset when power cycle.

In order to permanently turn on debug variable, put the debugVars file
in the Host folder as shown:-
 Harmonic Confidential

Using the Putty to Telnet into the Host of the MediaDirector.

 Harmonic Confidential

Make sure the Terminal session is set with “ Impicit CR every LF”
 Harmonic Confidential

Remember that some telnet command can be dangerous. It can destroy

the file system. Use commands that are instructed by Omneon only.
Harmonic Confidential
Harmonic Confidential