Film-Tech Forum ARCHIVE: Barco DP2K-19B, lamp sometimes does not turn on

This is topic Barco DP2K-19B, lamp sometimes does not turn on in forum Digital Cinema Forum at Film-Tech Forum ARCHIVE.

To visit this topic, use this URL:
https://ft-forum.com/ft/cgi-bin/ubb/ultimatebb.cgi?ubb=get_topic;f=16;t=003395

Posted by Marlon Martins (Member # 8823) on 07-31-2018, 06:59 PM:

hi, a few months ago, one of our projectors (19B with alchemy server) started to not turn on the lamp occasionally. pressing the lamp button, the lamp started fine. happened about 5 times till last month, then i remove the lamp housing, reseated the lamp, cleaned the connectors, reseated all LPS cables, and then it worked fine for a month. today happened again.

on the log show as:

Jul 31 23:08:54 icmp-5626c9 user.warning SMS: Projector- read status failed on: lamp
Jul 31 23:08:59 icmp-5626c9 user.warning SMS: Projector- read status failed on: lamp runtime
Jul 31 23:09:03 icmp-5626c9 user.warning SMS: Projector- read status failed on: last executed macro
Jul 31 23:09:03 icmp-5626c9 user.warning SMS: Projector- read status failed on: dowser

don't know if is a software issue or hardware issue. the lamp is around 800h

any ideas?

here a full log of the show:

quote:
Jul 31 22:56:59 icmp-5626c9 user.info SM: SM- Security log event: Key::KDMDeleted
Jul 31 22:56:59 icmp-5626c9 user.info SM: SM- Security log event: Key::KDMDeleted
Jul 31 22:56:59 icmp-5626c9 user.info SM: SM- Security log event: Key::KDMDeleted
Jul 31 22:56:59 icmp-5626c9 user.notice SMS: SMS- admin@10.230.192.150:58276>clear() accepted
Jul 31 22:57:16 icmp-5626c9 user.notice SMS: SMS- admin@10.230.192.150:58276>select("SPL", "1b340b84-c640-440a-9173-c33e6aa18568") accepted
Jul 31 22:57:16 icmp-5626c9 user.warning SMS: Player- Unsupported cue behavior [volume 3.5]: - default punctual behavior used
Jul 31 22:57:16 icmp-5626c9 user.warning SMS: Player- Unsupported cue behavior [volume 4.0]: - default punctual behavior used
Jul 31 22:57:16 icmp-5626c9 user.warning SMS: Player- Unsupported cue behavior [volume 4.0]: - default punctual behavior used
Jul 31 22:57:17 icmp-5626c9 user.info SM: SM- Security log event: Validation::CPLCheck
Jul 31 22:57:19 icmp-5626c9 user.info SM: SM- Security log event: Key::KDMKeysReceived
Jul 31 22:57:19 icmp-5626c9 user.info SMS: Player- KDM [7668ae1f-ec11-46d6-a695-3e21901a6548] Security validation successful
Jul 31 22:57:21 icmp-5626c9 user.info SM: SM- Security log event: Key::KDMKeysReceived
Jul 31 22:57:22 icmp-5626c9 user.info SMS: Player- KDM [c964e4e5-1914-4d74-af47-d6d00ec01bbc] Security validation successful
Jul 31 22:57:22 icmp-5626c9 user.info SMS: Player- CPL [HotelTransyl3_FTR-2-3D_F_QBP-XX_BR_51-Dbox_2K_SPE_20180627_EKN_IOP-3D_VF] Security validation successful
Jul 31 22:57:22 icmp-5626c9 user.debug SM: SM- DEBUG:: KDM c964e4e5-1914-4d74-af47-d6d00ec01bbc
Jul 31 22:57:22 icmp-5626c9 user.info SMS: Report- |SHOW-SELECT| [14:40, 20:30) HOTEL 3D F DUB 2018.07.26_02:46_LEANDRO]
Jul 31 22:57:22 icmp-5626c9 user.debug SMS: Player- starting streams at frame id: 1500 - streams: AV
Jul 31 22:57:22 icmp-5626c9 user.debug SM: IMB Controller- Sync Payload Updated (frameId: 1500, status: 0)
Jul 31 22:57:22 icmp-5626c9 user.debug SMS: Player- Player subtitles: updating gip timeline: 00:00:00:000
Jul 31 23:01:59 icmp-5626c9 user.notice SMS: SMS- admin@10.230.192.150:58276>play() accepted
Jul 31 23:01:59 icmp-5626c9 user.debug SM: SM- DEBUG:: KDM c964e4e5-1914-4d74-af47-d6d00ec01bbc
Jul 31 23:01:59 icmp-5626c9 user.info SMS: Player- Playback Security validation accepted
Jul 31 23:01:59 icmp-5626c9 user.info SMS: Player- play settings: audio delay: 0 ms, freq: 48000 Hz - output size: AUTO
Jul 31 23:01:59 icmp-5626c9 user.info SMS: Report- |SHOW-START| [14:40, 20:30) HOTEL 3D F DUB 2018.07.26_02:46_LEANDRO]
Jul 31 23:01:59 icmp-5626c9 user.debug SMS: Player- Player subtitles: set gip timeline: PLAY
Jul 31 23:01:59 icmp-5626c9 user.err GIP: Starting play while image is blanked
Jul 31 23:01:59 icmp-5626c9 user.info SMS: Report- |CUE| ["USER_CUE" "SCOPE"] from [SHOW]: 3 action(s)
Jul 31 23:01:59 icmp-5626c9 user.info SMS: Automation- Executing action ["PROJECTOR" "Execute Macro"( "SCOPE" )]
Jul 31 23:01:59 icmp-5626c9 user.info SMS: Report- |CUE| ["USER_CUE" "volume 3.5"] from [SHOW]: 1 action(s)
Jul 31 23:01:59 icmp-5626c9 user.info SMS: Report- |PLAYING| [Black]
Jul 31 23:01:59 icmp-5626c9 user.info SMS: Automation- Executing action ["PROJECTOR" "Open Dowser"( )]
Jul 31 23:01:59 icmp-5626c9 user.info SMS: Automation- Executing action ["PROJECTOR" "Turn Lamp On"( )]
Jul 31 23:02:00 icmp-5626c9 user.info SMS: Automation- Executing action ["CP750" "Send Text"( "cp750.sys.fader 35" )]
Jul 31 23:02:00 icmp-5626c9 user.info SMS: Player- received GIP input selection request: input 0
Jul 31 23:02:00 icmp-5626c9 user.debug SM: IMB Controller- switchToInputSource [Media Player]: switching... (not playing)
Jul 31 23:02:00 icmp-5626c9 user.info SMS: Player- select input source [Media Player] successful
Jul 31 23:02:00 icmp-5626c9 user.info SMS: Player- received GIP input selection request: input 0
Jul 31 23:02:00 icmp-5626c9 user.debug SM: IMB Controller- switchToInputSource [Media Player]: switching...
Jul 31 23:02:00 icmp-5626c9 user.info SMS: Player- select input source [Media Player] successful
Jul 31 23:02:00 icmp-5626c9 user.info GIP: LTC sync- set LTC SMPTE-12M out
Jul 31 23:02:00 icmp-5626c9 user.info GIP: Scaling the image from 2048 x 858 to 2048 x 858
Jul 31 23:02:00 icmp-5626c9 user.info GIP: Horizontal downscale sequence = 1 (x 1.00), Vertical downscale sequence = 1 (x 1.00)
Jul 31 23:02:00 icmp-5626c9 user.info GIP: Loading the framereader with following new timings
Jul 31 23:02:00 icmp-5626c9 user.info GIP: TLPF = 1936, TPPL = 3252, APPL = 2048, ALPF = 858, framerate = 4800 (4800)
Jul 31 23:02:03 icmp-5626c9 user.warning SMS: Projector- read status failed on: lamp runtime
Jul 31 23:02:03 icmp-5626c9 user.info GIP: Loading active file : DCDM_XYZ_239
Jul 31 23:02:03 icmp-5626c9 user.info GIP: Scaling the image from 2048 x 858 to 2048 x 858
Jul 31 23:02:03 icmp-5626c9 user.info GIP: Horizontal downscale sequence = 1 (x 1.00), Vertical downscale sequence = 1 (x 1.00)
Jul 31 23:02:03 icmp-5626c9 user.info GIP: Loading the framereader with following new timings
Jul 31 23:02:03 icmp-5626c9 user.info GIP: TLPF = 1936, TPPL = 3252, APPL = 2048, ALPF = 858, framerate = 4800 (4800)
Jul 31 23:02:03 icmp-5626c9 user.info GIP: Loading active file : SCOPE
Jul 31 23:02:03 icmp-5626c9 user.info GIP: Loading active file : No3D
Jul 31 23:02:03 icmp-5626c9 user.info GIP: loading a new sequence, set 2, element index 0, frametime 97 Hz.
Jul 31 23:02:03 icmp-5626c9 user.info GIP: loading a new sequence, set 1, element index 6, frametime 51 Hz.
Jul 31 23:02:03 icmp-5626c9 user.info GIP: Loading active file : Nominal
Jul 31 23:02:54 icmp-5626c9 user.info SMS: Report- |CUE| ["USER_CUE" "LUZ - INICIO SHOW"] from [SHOW]: 1 action(s)
Jul 31 23:02:54 icmp-5626c9 user.info SMS: Automation- Executing action ["GPIO" "Set Outputs"( "Unset", "Unset", "Unset", "Unset", "Unset", "Down", "Up", "Up" )]
Jul 31 23:02:59 icmp-5626c9 user.debug SM: IMB Controller- Sync Payload Updated (frameId: 1500, status: 2)
Jul 31 23:02:59 icmp-5626c9 user.info SMS: Report- |PLAYING| [ RDMIER_CONTINUE_SONHANDO_SCOPE]
Jul 31 23:02:59 icmp-5626c9 user.debug SM: SM- Audio input mux: 0 1 - - - - - - - - - - - - - -
Jul 31 23:02:59 icmp-5626c9 user.info GIP: LTC sync- set LTC SMPTE-12M out
Jul 31 23:02:59 icmp-5626c9 user.info GIP: Scaling the image from 2048 x 858 to 2048 x 858
Jul 31 23:02:59 icmp-5626c9 user.info GIP: Horizontal downscale sequence = 1 (x 1.00), Vertical downscale sequence = 1 (x 1.00)
Jul 31 23:02:59 icmp-5626c9 user.info GIP: Loading the framereader with following new timings
Jul 31 23:02:59 icmp-5626c9 user.info GIP: TLPF = 3872, TPPL = 3252, APPL = 2048, ALPF = 858, framerate = 2400 (2400)
Jul 31 23:02:59 icmp-5626c9 user.info GIP: loading a new sequence, set 1, element index 0, frametime 25 Hz.
Jul 31 23:02:59 icmp-5626c9 user.info SMS: Player- set gip media player timings: 2048x858, 24 fps, XYZ, 2D, standalone, LTC on
Jul 31 23:03:00 icmp-5626c9 user.debug SM: IMB Controller- CPL offset update: frame id: 1500 - CPL frame offset: 1500
Jul 31 23:03:00 icmp-5626c9 user.debug SM: IMB Event- Monitoring event wd0=5ec wd1=104000 FifoLevelAboveThreshold 16
Jul 31 23:03:00 icmp-5626c9 user.info SMS: SMS- preload: 112 AV frames
Jul 31 23:03:00 icmp-5626c9 user.debug SM: IMB Event- Monitoring event wd0=412245 wd1=17737 J2KtoMemBufferThresholdReached VideoFifo=69 AudioFifo=68 Audio2MemFifo=4
Jul 31 23:03:00 icmp-5626c9 user.info SM: SM- Security log event: Playout::CPLStart
Jul 31 23:03:01 icmp-5626c9 user.info SM: SM- ADSP: SPORT resync counter: 1, state: playing
Jul 31 23:03:01 icmp-5626c9 user.info SM: SM- Auro: no license - decoder disabled
Jul 31 23:03:25 icmp-5626c9 user.debug SM: IMB Controller- CPL offset update: frame id: 2577 - CPL frame offset: 2577
Jul 31 23:03:25 icmp-5626c9 user.debug SM: IMB Controller- Sync Payload Updated (frameId: 2577, status: 2)
Jul 31 23:03:45 icmp-5626c9 user.info SM: SM- Security log event: Playout::CPLEnd
Jul 31 23:03:45 icmp-5626c9 user.info SM: SM- Security log event: Playout::PlayoutComplete
Jul 31 23:03:45 icmp-5626c9 user.debug SM: IMB Event- End of track CRC values: 69396939 - 00796939 - 61792052 - 00522052
Jul 31 23:03:45 icmp-5626c9 user.info SM: SM- Security log event: Playout::CPLStart
Jul 31 23:03:45 icmp-5626c9 user.info SMS: Report- |PLAYING| [LEFFA_MOVEIS_SCOPE_2]
Jul 31 23:04:23 icmp-5626c9 user.debug SMS: Player- starting streams at frame id: 4027 - streams: AV
Jul 31 23:04:24 icmp-5626c9 user.debug SM: IMB Controller- Sync Payload Updated (frameId: 4026, status: 0)
Jul 31 23:04:29 icmp-5626c9 user.notice GIP: B log mark - 0000003732f9 - #015#012
Jul 31 23:04:46 icmp-5626c9 user.info SM: SM- Security log event: Playout::CPLEnd
Jul 31 23:04:46 icmp-5626c9 user.info SM: SM- Security log event: Playout::PlayoutComplete
Jul 31 23:04:46 icmp-5626c9 user.debug SM: IMB Event- End of track CRC values: 25a025a0 - 00b125a0 - 11b12067 - 00672067
Jul 31 23:04:46 icmp-5626c9 user.debug SM: IMB Controller- Notify STOPPED state for frame 4026
Jul 31 23:04:46 icmp-5626c9 user.info SM: SM- ADSP: SPORT resync counter: 1, previous value: 1, state: stopped
Jul 31 23:04:46 icmp-5626c9 user.info SMS: Report- |PLAYING| [MONTE_BELLO_SCOPE]
Jul 31 23:04:46 icmp-5626c9 user.debug SM: IMB Controller- Sync Payload Updated (frameId: 4027, status: 2)
Jul 31 23:04:46 icmp-5626c9 user.debug SM: SM- Audio input mux: 0 1 - - - - - - - - - - - - - -
Jul 31 23:04:46 icmp-5626c9 user.info GIP: LTC sync- set LTC SMPTE-12M out
Jul 31 23:04:46 icmp-5626c9 user.info SMS: Player- set gip media player timings: 2048x858, 30 fps, XYZ, 2D, standalone, LTC on
Jul 31 23:04:46 icmp-5626c9 user.info GIP: Scaling the image from 2048 x 858 to 2048 x 858
Jul 31 23:04:46 icmp-5626c9 user.info GIP: Horizontal downscale sequence = 1 (x 1.00), Vertical downscale sequence = 1 (x 1.00)
Jul 31 23:04:46 icmp-5626c9 user.info GIP: Loading the framereader with following new timings
Jul 31 23:04:46 icmp-5626c9 user.info GIP: TLPF = 3099, TPPL = 3252, APPL = 2048, ALPF = 858, framerate = 3000 (3000)
Jul 31 23:04:46 icmp-5626c9 user.info GIP: loading a new sequence, set 1, element index 2, frametime 31 Hz.
Jul 31 23:04:46 icmp-5626c9 user.debug SM: IMB Controller- CPL offset update: frame id: 4027 - CPL frame offset: 4027
Jul 31 23:04:46 icmp-5626c9 user.debug SM: IMB Event- Monitoring event wd0=fcb wd1=104000 FifoLevelAboveThreshold 16
Jul 31 23:04:47 icmp-5626c9 user.info SMS: SMS- preload: 130 AV frames
Jul 31 23:04:47 icmp-5626c9 user.debug SM: IMB Event- Monitoring event wd0=412b56 wd1=16eef J2KtoMemBufferThresholdReached VideoFifo=86 AudioFifo=86 Audio2MemFifo=4
Jul 31 23:04:47 icmp-5626c9 user.info SM: SM- Security log event: Playout::CPLStart
Jul 31 23:04:47 icmp-5626c9 user.info SM: SM- ADSP: SPORT resync counter: 1, state: playing
Jul 31 23:04:47 icmp-5626c9 user.info SM: SM- Auro: no license - decoder disabled
Jul 31 23:05:16 icmp-5626c9 user.debug SMS: Player- starting streams at frame id: 5497 - streams: AV
Jul 31 23:05:16 icmp-5626c9 user.debug SM: IMB Controller- Sync Payload Updated (frameId: 5496, status: 0)
Jul 31 23:05:36 icmp-5626c9 user.info SM: SM- Security log event: Playout::CPLEnd
Jul 31 23:05:36 icmp-5626c9 user.info SM: SM- Security log event: Playout::PlayoutComplete
Jul 31 23:05:36 icmp-5626c9 user.debug SM: IMB Event- End of track CRC values: 981a981a - 0022981a - 282220eb - 00eb20eb
Jul 31 23:05:36 icmp-5626c9 user.debug SM: IMB Controller- Notify STOPPED state for frame 5496
Jul 31 23:05:36 icmp-5626c9 user.info SMS: Report- |PLAYING| [NOSSA_CASA_SCOPE]
Jul 31 23:05:36 icmp-5626c9 user.debug SM: IMB Controller- Sync Payload Updated (frameId: 5497, status: 2)
Jul 31 23:05:36 icmp-5626c9 user.debug SM: SM- Audio input mux: 0 1 - - - - - - - - - - - - - -
Jul 31 23:05:36 icmp-5626c9 user.info GIP: LTC sync- set LTC SMPTE-12M out
Jul 31 23:05:36 icmp-5626c9 user.info GIP: Scaling the image from 2048 x 858 to 2048 x 858
Jul 31 23:05:36 icmp-5626c9 user.info GIP: Horizontal downscale sequence = 1 (x 1.00), Vertical downscale sequence = 1 (x 1.00)
Jul 31 23:05:36 icmp-5626c9 user.info GIP: Loading the framereader with following new timings
Jul 31 23:05:36 icmp-5626c9 user.info GIP: TLPF = 3872, TPPL = 3252, APPL = 2048, ALPF = 858, framerate = 2400 (2400)
Jul 31 23:05:36 icmp-5626c9 user.info SMS: Player- set gip media player timings: 2048x858, 24 fps, XYZ, 2D, standalone, LTC on
Jul 31 23:05:36 icmp-5626c9 user.info GIP: loading a new sequence, set 1, element index 0, frametime 25 Hz.
Jul 31 23:05:36 icmp-5626c9 user.info SM: SM- ADSP: SPORT resync counter: 1, previous value: 1, state: stopped
Jul 31 23:05:37 icmp-5626c9 user.debug SM: IMB Controller- CPL offset update: frame id: 5497 - CPL frame offset: 5497
Jul 31 23:05:37 icmp-5626c9 user.debug SM: IMB Event- Monitoring event wd0=1589 wd1=104000 FifoLevelAboveThreshold 16
Jul 31 23:05:37 icmp-5626c9 user.info SMS: SMS- preload: 112 AV frames
Jul 31 23:05:37 icmp-5626c9 user.debug SM: IMB Event- Monitoring event wd0=12244 wd1=15e5f J2KtoMemBufferThresholdReached VideoFifo=68 AudioFifo=68 Audio2MemFifo=4
Jul 31 23:05:37 icmp-5626c9 user.info SM: SM- Security log event: Playout::CPLStart
Jul 31 23:05:37 icmp-5626c9 user.info SMS: Report- |CUE| ["USER_CUE" "volume 4.0"] from [SHOW]: 1 action(s)
Jul 31 23:05:37 icmp-5626c9 user.info SMS: Automation- Executing action ["CP750" "Send Text"( "cp750.sys.fader 40" )]
Jul 31 23:05:37 icmp-5626c9 user.info SM: SM- ADSP: SPORT resync counter: 1, state: playing
Jul 31 23:05:38 icmp-5626c9 user.info SM: SM- Auro: no license - decoder disabled
Jul 31 23:05:38 icmp-5626c9 user.debug SM: IMB Controller- Sync Payload Updated (frameId: 5928, status: 0)
Jul 31 23:05:38 icmp-5626c9 user.debug SMS: Player- starting streams at frame id: 5929 - streams: AV
Jul 31 23:05:55 icmp-5626c9 user.info SM: SM- Security log event: Playout::CPLEnd
Jul 31 23:05:55 icmp-5626c9 user.info SM: SM- Security log event: Playout::PlayoutComplete
Jul 31 23:05:55 icmp-5626c9 user.debug SM: IMB Event- End of track CRC values: e909e909 - 00dde909 - e8dd20bc - 00bc20bc
Jul 31 23:05:55 icmp-5626c9 user.debug SM: IMB Controller- Notify STOPPED state for frame 5928
Jul 31 23:05:55 icmp-5626c9 user.debug SM: IMB Controller- Sync Payload Updated (frameId: 5929, status: 2)
Jul 31 23:05:55 icmp-5626c9 user.info SMS: Report- |PLAYING| [WreckItRalph2_TLR-G-2D_S_QBP-XX_BR_51_2K_DI_20180601_DDS_IOP]
Jul 31 23:05:55 icmp-5626c9 user.info SM: SM- ADSP: SPORT resync counter: 1, previous value: 1, state: stopped
Jul 31 23:05:56 icmp-5626c9 user.debug SM: SM- Audio input mux: 0 1 2 3 4 5 - - - - - - - - - -
Jul 31 23:05:56 icmp-5626c9 user.info GIP: LTC sync- set LTC SMPTE-12M out
Jul 31 23:05:56 icmp-5626c9 user.info SMS: Player- set gip media player timings: 2048x858, 24 fps, XYZ, 2D, standalone, LTC on
Jul 31 23:05:56 icmp-5626c9 user.debug SM: IMB Controller- CPL offset update: frame id: 5929 - CPL frame offset: 5929
Jul 31 23:05:56 icmp-5626c9 user.debug SM: IMB Event- Monitoring event wd0=1739 wd1=104000 FifoLevelAboveThreshold 16
Jul 31 23:05:57 icmp-5626c9 user.info SMS: SMS- preload: 112 AV frames
Jul 31 23:05:57 icmp-5626c9 user.debug SM: IMB Event- Monitoring event wd0=12244 wd1=14a4b J2KtoMemBufferThresholdReached VideoFifo=68 AudioFifo=68 Audio2MemFifo=4
Jul 31 23:05:57 icmp-5626c9 user.info SM: SM- Security log event: Playout::CPLStart
Jul 31 23:05:57 icmp-5626c9 user.info SM: SM- ADSP: SPORT resync counter: 1, state: playing
Jul 31 23:05:57 icmp-5626c9 user.info SM: SM- Auro: no license - decoder disabled
Jul 31 23:08:01 icmp-5626c9 user.debug SM: IMB Controller- CPL offset update: frame id: 9433 - CPL frame offset: 9433
Jul 31 23:08:01 icmp-5626c9 user.debug SM: IMB Controller- Sync Payload Updated (frameId: 9433, status: 2)
Jul 31 23:08:23 icmp-5626c9 user.info SM: SM- Security log event: Playout::CPLEnd
Jul 31 23:08:23 icmp-5626c9 user.info SM: SM- Security log event: Playout::PlayoutComplete
Jul 31 23:08:23 icmp-5626c9 user.debug SM: IMB Event- End of track CRC values: abb8abb8 - 0007abb8 - 490720d3 - 00d320d3
Jul 31 23:08:23 icmp-5626c9 user.info SM: SM- Security log event: Playout::CPLStart
Jul 31 23:08:23 icmp-5626c9 user.info SMS: Report- |PLAYING| [ALFA_TLR-A-2D_S_QBP-XX_51_2K_SPE_20170719_AUW_IOP_OV]
Jul 31 23:08:54 icmp-5626c9 user.warning SMS: Projector- read status failed on: lamp
Jul 31 23:08:59 icmp-5626c9 user.warning SMS: Projector- read status failed on: lamp runtime
Jul 31 23:09:03 icmp-5626c9 user.warning SMS: Projector- read status failed on: last executed macro
Jul 31 23:09:03 icmp-5626c9 user.warning SMS: Projector- read status failed on: dowser
Jul 31 23:09:58 icmp-5626c9 user.info GIP: RSC controller- Sequencer (--B) is stopped unexpectedly, restarting all...
Jul 31 23:10:38 icmp-5626c9 user.debug SM: IMB Controller- CPL offset update: frame id: 13022 - CPL frame offset: 13022
Jul 31 23:10:38 icmp-5626c9 user.debug SM: IMB Controller- Sync Payload Updated (frameId: 13022, status: 2)
Jul 31 23:10:53 icmp-5626c9 user.info SM: SM- Security log event: Playout::CPLEnd
Jul 31 23:10:53 icmp-5626c9 user.info SM: SM- Security log event: Playout::PlayoutComplete
Jul 31 23:10:53 icmp-5626c9 user.debug SM: IMB Event- End of track CRC values: 91c691c6 - 002091c6 - 262020aa - 00aa20aa
Jul 31 23:10:53 icmp-5626c9 user.info SM: SM- Security log event: Playout::CPLStart
Jul 31 23:10:53 icmp-5626c9 user.info SMS: Report- |PLAYING| [HwToTrnYrDrgn3_TLR-A-2D_S_QBP-XX_BR_51_2K_UP_20180611_MPS_IOP_OV]
Jul 31 23:12:49 icmp-5626c9 user.debug SMS: Player- starting streams at frame id: 16527 - streams: AV
Jul 31 23:12:51 icmp-5626c9 user.debug SM: IMB Controller- Sync Payload Updated (frameId: 16526, status: 0)
Jul 31 23:13:19 icmp-5626c9 user.info SM: SM- Security log event: Playout::CPLEnd
Jul 31 23:13:19 icmp-5626c9 user.info SM: SM- Security log event: Playout::PlayoutComplete
Jul 31 23:13:19 icmp-5626c9 user.debug SM: IMB Event- End of track CRC values: ea14ea14 - 001cea14 - 0b1c20ec - 00ec20ec
Jul 31 23:13:19 icmp-5626c9 user.debug SM: IMB Controller- Notify STOPPED state for frame 16526
Jul 31 23:13:19 icmp-5626c9 user.info SM: SM- ADSP: SPORT resync counter: 1, previous value: 1, state: stopped
Jul 31 23:13:19 icmp-5626c9 user.debug SM: IMB Controller- Sync Payload Updated (frameId: 16527, status: 2)
Jul 31 23:13:19 icmp-5626c9 user.info SMS: Report- |PLAYING| [TheMeg_TLR-F1-2D_S_QBP-XX_BR_51_2K_WR_20180625_DTB_IOP_OV]
Jul 31 23:13:19 icmp-5626c9 user.debug SM: SM- Audio input mux: 0 1 2 3 4 5 21 20 - - - - - - - -
Jul 31 23:13:19 icmp-5626c9 user.info GIP: LTC sync- set LTC SMPTE-12M out
Jul 31 23:13:19 icmp-5626c9 user.info SMS: Player- set gip media player timings: 2048x858, 24 fps, XYZ, 2D, standalone, LTC on
Jul 31 23:13:19 icmp-5626c9 user.debug SM: IMB Controller- CPL offset update: frame id: 16527 - CPL frame offset: 16527
Jul 31 23:13:19 icmp-5626c9 user.debug SM: IMB Event- Monitoring event wd0=409f wd1=104000 FifoLevelAboveThreshold 16
Jul 31 23:13:20 icmp-5626c9 user.info SMS: SMS- preload: 112 AV frames
Jul 31 23:13:20 icmp-5626c9 user.debug SM: IMB Event- Monitoring event wd0=412244 wd1=163e4 J2KtoMemBufferThresholdReached VideoFifo=68 AudioFifo=68 Audio2MemFifo=4
Jul 31 23:13:20 icmp-5626c9 user.info SM: SM- Security log event: Playout::CPLStart
Jul 31 23:13:20 icmp-5626c9 user.info SM: SM- Auro: no license - decoder disabled
Jul 31 23:13:20 icmp-5626c9 user.info SM: SM- ADSP: SPORT resync counter: 1, state: playing
Jul 31 23:15:23 icmp-5626c9 user.debug SM: IMB Controller- CPL offset update: frame id: 20043 - CPL frame offset: 16527
Jul 31 23:15:23 icmp-5626c9 user.debug SM: IMB Controller- Sync Payload Updated (frameId: 20043, status: 2)
Jul 31 23:15:25 icmp-5626c9 user.debug SMS: Player- starting streams at frame id: 20665 - streams: AV
Jul 31 23:15:25 icmp-5626c9 user.debug SM: IMB Controller- Sync Payload Updated (frameId: 20164, status: 0)
Jul 31 23:15:47 icmp-5626c9 user.debug SM: IMB Event- End of track CRC values: e209e209 - 0084e209 - 84842060 - 00602060
Jul 31 23:15:52 icmp-5626c9 user.info SM: SM- Security log event: Playout::CPLEnd
Jul 31 23:15:52 icmp-5626c9 user.info SM: SM- Security log event: Playout::PlayoutComplete
Jul 31 23:15:52 icmp-5626c9 user.debug SM: IMB Event- End of track CRC values: 47764776 - 00b14776 - bdb1209f - 009f209f
Jul 31 23:15:52 icmp-5626c9 user.debug SM: IMB Controller- Notify STOPPED state for frame 20164
Jul 31 23:15:52 icmp-5626c9 user.info SMS: Report- |PLAYING| [Black]
Jul 31 23:15:52 icmp-5626c9 user.info SMS: Report- |CUE| ["USER_CUE" "FLAT 3D"] from [SHOW]: 3 action(s)
Jul 31 23:15:52 icmp-5626c9 user.info SMS: Automation- Executing action ["PROJECTOR" "Execute Macro"( "3D_FLAT" )]
Jul 31 23:15:52 icmp-5626c9 user.info SMS: Automation- Executing action ["PROJECTOR" "Turn Lamp On"( )]
Jul 31 23:15:52 icmp-5626c9 user.info SM: SM- ADSP: SPORT resync counter: 1, previous value: 1, state: stopped
Jul 31 23:15:52 icmp-5626c9 user.info SMS: Automation- Executing action ["PROJECTOR" "Open Dowser"( )]
Jul 31 23:15:52 icmp-5626c9 user.info SMS: Player- received GIP input selection request: input 0
Jul 31 23:15:52 icmp-5626c9 user.debug SM: IMB Controller- switchToInputSource [Media Player]: switching... (not playing)
Jul 31 23:15:52 icmp-5626c9 user.info SMS: Player- select input source [Media Player] successful
Jul 31 23:15:53 icmp-5626c9 user.info SMS: Player- received GIP input selection request: input 0
Jul 31 23:15:53 icmp-5626c9 user.debug SM: IMB Controller- switchToInputSource [Media Player]: switching...
Jul 31 23:15:53 icmp-5626c9 user.info SMS: Player- select input source [Media Player] successful
Jul 31 23:15:53 icmp-5626c9 user.info GIP: LTC sync- set LTC SMPTE-12M out
Jul 31 23:15:53 icmp-5626c9 user.info GIP: Scaling the image from 2048 x 858 to 2048 x 858
Jul 31 23:15:53 icmp-5626c9 user.info GIP: Horizontal downscale sequence = 1 (x 1.00), Vertical downscale sequence = 1 (x 1.00)
Jul 31 23:15:53 icmp-5626c9 user.info GIP: Loading the framereader with following new timings
Jul 31 23:15:53 icmp-5626c9 user.info GIP: TLPF = 3872, TPPL = 3252, APPL = 2048, ALPF = 858, framerate = 2400 (2400)
Jul 31 23:15:53 icmp-5626c9 user.info GIP: Loading active file : DCDM_XYZ_185
Jul 31 23:15:53 icmp-5626c9 user.info GIP: Scaling the image from 2000 x 1080 to 2000 x 1080
Jul 31 23:15:53 icmp-5626c9 user.info GIP: Horizontal downscale sequence = 1 (x 1.00), Vertical downscale sequence = 1 (x 1.00)
Jul 31 23:15:53 icmp-5626c9 user.info GIP: Loading the framereader with following new timings
Jul 31 23:15:53 icmp-5626c9 user.info GIP: TLPF = 3872, TPPL = 3252, APPL = 1998, ALPF = 1080, framerate = 2400 (2400)
Jul 31 23:15:53 icmp-5626c9 user.info GIP: Loading active file : FLAT
Jul 31 23:15:53 icmp-5626c9 user.info GIP: Loading active file : VOLFONI
Jul 31 23:15:53 icmp-5626c9 user.info GIP: loading a new sequence, set 8, element index 0, frametime 24 Hz.
Jul 31 23:15:54 icmp-5626c9 user.info GIP: Loading active file : MeasuredColors
Jul 31 23:15:57 icmp-5626c9 user.info GIP: EtherAcceptConnection on the port 43680 from client 10.230.192.150
Jul 31 23:15:57 icmp-5626c9 user.info GIP: EtherMain - Started EtherLoop thread
Jul 31 23:16:12 icmp-5626c9 user.debug SM: IMB Controller- Sync Payload Updated (frameId: 20665, status: 2)
Jul 31 23:16:12 icmp-5626c9 user.info SMS: Report- |PLAYING| [OCULOS_3D_FLAT]
Jul 31 23:16:12 icmp-5626c9 user.debug SM: SM- Audio input mux: - - - - - - - - - - - - - - - -
Jul 31 23:16:12 icmp-5626c9 user.info GIP: LTC sync- set LTC SMPTE-12M out
Jul 31 23:16:12 icmp-5626c9 user.info GIP: Scaling the image from 2000 x 1080 to 2000 x 1080
Jul 31 23:16:12 icmp-5626c9 user.info GIP: Horizontal downscale sequence = 1 (x 1.00), Vertical downscale sequence = 1 (x 1.00)
Jul 31 23:16:12 icmp-5626c9 user.info GIP: Loading the framereader with following new timings
Jul 31 23:16:12 icmp-5626c9 user.info GIP: TLPF = 1936, TPPL = 3252, APPL = 1998, ALPF = 1080, framerate = 4800 (4800)
Jul 31 23:16:12 icmp-5626c9 user.info GIP: loading a new sequence, set 9, element index 1, frametime 28 Hz.
Jul 31 23:16:12 icmp-5626c9 user.info SMS: Player- set gip media player timings: 1998x1080, 48 fps, XYZ, 3D, standalone, LTC on
Jul 31 23:16:12 icmp-5626c9 user.debug SM: IMB Controller- CPL offset update: frame id: 20665 - CPL frame offset: 20665
Jul 31 23:16:12 icmp-5626c9 user.debug SM: IMB Event- Monitoring event wd0=50c1 wd1=104000 FifoLevelAboveThreshold 16
Jul 31 23:16:13 icmp-5626c9 user.info SMS: SMS- preload: 112 AV frames
Jul 31 23:16:13 icmp-5626c9 user.debug SM: IMB Event- Monitoring event wd0=40007f wd1=1712e J2KtoMemBufferThresholdReached VideoFifo=127 AudioFifo=0 Audio2MemFifo=0
Jul 31 23:16:13 icmp-5626c9 user.info SM: SM- Security log event: Playout::CPLStart
Jul 31 23:16:13 icmp-5626c9 user.debug SMS: Player- starting streams at frame id: 20810 - streams: AV
Jul 31 23:16:13 icmp-5626c9 user.debug SM: IMB Controller- Sync Payload Updated (frameId: 20809, status: 0)
Jul 31 23:16:13 icmp-5626c9 user.info SM: SM- ADSP: SPORT resync counter: 1, state: playing
Jul 31 23:16:14 icmp-5626c9 user.info GIP: 3D-sync- PulseWidth error detected, sync3D_err = 1, 0
Jul 31 23:16:15 icmp-5626c9 user.info SMS: SMS- Login attempt from 10.230.192.150:54296 (User barco)
Jul 31 23:16:15 icmp-5626c9 user.notice SMS: SMS- >login("barco", "") accepted
Jul 31 23:16:19 icmp-5626c9 user.info SM: SM- Security log event: Playout::CPLEnd
Jul 31 23:16:19 icmp-5626c9 user.info SM: SM- Security log event: Playout::PlayoutComplete
Jul 31 23:16:19 icmp-5626c9 user.debug SM: IMB Event- End of track CRC values: ff32ff32 - 00b1ff32 - bdb1cacd - 00b4cacd
Jul 31 23:16:19 icmp-5626c9 user.debug SM: IMB Controller- Notify STOPPED state for frame 20809
Jul 31 23:16:19 icmp-5626c9 user.debug SM: IMB Controller- Sync Payload Updated (frameId: 20810, status: 2)
Jul 31 23:16:19 icmp-5626c9 user.info SMS: Report- |PLAYING| [SR_VAPOR2_FLAT_3D]
Jul 31 23:16:19 icmp-5626c9 user.debug SM: SM- Audio input mux: 0 1 - - - - - - - - - - - - - -
Jul 31 23:16:19 icmp-5626c9 user.info SM: SM- ADSP: SPORT resync counter: 1, previous value: 1, state: stopped
Jul 31 23:16:19 icmp-5626c9 user.info GIP: LTC sync- set LTC SMPTE-12M out
Jul 31 23:16:19 icmp-5626c9 user.info SMS: Player- set gip media player timings: 1998x1080, 48 fps, XYZ, 3D, standalone, LTC on
Jul 31 23:16:20 icmp-5626c9 user.debug SM: IMB Controller- CPL offset update: frame id: 20810 - CPL frame offset: 20810
Jul 31 23:16:20 icmp-5626c9 user.debug SM: IMB Event- Monitoring event wd0=5152 wd1=104000 FifoLevelAboveThreshold 16
Jul 31 23:16:20 icmp-5626c9 user.info SMS: SMS- preload: 112 AV frames
Jul 31 23:16:20 icmp-5626c9 user.debug SM: IMB Event- Monitoring event wd0=60a37f wd1=15292 J2KtoMemBufferThresholdReached VideoFifo=127 AudioFifo=70 Audio2MemFifo=2
Jul 31 23:16:21 icmp-5626c9 user.info SM: SM- Security log event: Playout::CPLStart
Jul 31 23:16:21 icmp-5626c9 user.info SM: SM- ADSP: SPORT resync counter: 1, state: playing
Jul 31 23:16:21 icmp-5626c9 user.debug SM: IMB Controller- Sync Payload Updated (frameId: 20988, status: 0)
Jul 31 23:16:21 icmp-5626c9 user.debug SMS: Player- starting streams at frame id: 20989 - streams: AV
Jul 31 23:16:21 icmp-5626c9 user.info SM: SM- Auro: no license - decoder disabled

Posted by Dave Macaulay (Member # 813) on 08-01-2018, 12:04 AM:

That's a lot of logs. I see lamp runtime read fails. This is stored in the lamphouse data module - the little PCB on the lamphouse. Maybe it's going bad, maybe there's a bad connection to the lampouse or backplane. Clean the lamphouse connector and the connector that mates with it (mutlicontact blue or white connectors). If you feel competent reseat the other end of the lamphouse cable to the backplane - either behind the air filter or under the top lid, not sure but think it's behind the filter.
Check lamp hours in installation/lamp/lamp information - if the numbers seem reasonable then it's reading the data but apparently sometimes it fails.
Reseating all the boards is a good plan whenever anything weird and intermittent happens.
You need the Dallas key and authorization passcode before opening the inner covers or pulling boards out, no show afterwards without those.

Posted by Steve Guttag (Member # 268) on 08-01-2018, 12:45 AM:

I suspect that the lamphouse isn't in all of the way and you are getting intermittent connections on the lamphouse data connector. Make sure that the right hand screw is fully screwed in and that the lamphouse really seats all of the way in.

Posted by Stephan Shelley (Member # 8587) on 08-01-2018, 01:25 PM:

In addition to the above make sure you are on the latest software for the cinema controller and lamp supplies. It has been several years but there was an update back then that updated the lamp supplies and stabilized lamp operation.

Posted by Marlon Martins (Member # 8823) on 08-02-2018, 07:30 PM:

thanks for the answer guys. i cleaned all contacts, including the blue one, and reseated the boards and connectors, including the on in the lamphouse circuit. everything looks clean, but only time will tell now.

i will ask for the installers to do a remote update on everything, as it is beyond my knowledge and responsibility [Wink]

Posted by Leo Enticknap (Member # 534) on 08-02-2018, 10:22 PM:

Do you have another bulb (or even better, another lamphouse module containing a bulb of the same size) that you could swap in to test?

I encountered something similar on a DP2K-32B a couple of months ago, and to cut a long story short, it turned out to be a bad bulb. Bulb failed to strike (brief flash as the SPG fired, then nothing). A wild goose chase was started by the presence of red lights on two of the three lamp power supply modules. So I naturally suspected them first, and swapped in two from another screen's projector. Same result. Then I suspected the SPG and swapped that out, again. Still no joy. The bulb was literally about the last thing I tried, despite being the easiest part to check. As it had been replaced four days prior and had only done 60 hours, I simply assumed that it couldn't be the culprit. It was.

Posted by Marlon Martins (Member # 8823) on 08-02-2018, 10:54 PM:

but it was giving similar errors as mine or just "could not turn lamp on" kind of error?

we have two screens with the same 19b, swap the entire housing is not a bad idea... [Wink]

Posted by Leo Enticknap (Member # 534) on 08-03-2018, 08:17 AM:

I was just getting "Sorry pal, can't strike." From the errors you describe, I'm leaning towards the info module.

If you have another lamphouse, then if swapping them doesn't get you any further forward, the experiment only cost you 2-3 minutes, and even for that price you get three potential causes ruled out.

However, if the problem swaps with them, you've narrowed it down to one of those three causes:

- A bad bulb
- A bad bulb info module
- As Steve suggests, a bad connection between the lamphouse and the projector chassis (either the cathode or anode, or the info module connector).

Posted by Marlon Martins (Member # 8823) on 08-08-2018, 07:48 PM:

we will exchange the lamphouses between the projectors as suggested, see "which one will break first" [Wink]

thanks for the help so far guys!

Posted by Marlon Martins (Member # 8823) on 08-24-2018, 04:47 PM:

today we got the problem again. changing the lamphouse between projectors did not fix the problem. (still happening in the same projector) the log:

local0.err dp61[2073]: lamp - lamp on failed (no or invalid lim data)
local0.err dp61[2073]: lamp - no communication (#5004)
local0.err dp61[2073]: lamp run time - read failed (#5654)
local0.err dp61[2073]: lamp run time - read limits failed (#5658)

had to power off the projector to make i turn on (resetting the server did not solve)

maybe change the power supplies perhaps?

Posted by Monte L Fullmer (Member # 2797) on 08-24-2018, 05:11 PM:

You would have a "can't detect one of the LPS" error message if power supplies are bad.

It has to be that blue connector, or the on board computer on the module, to the projector that isn't doing its thing.

It also could be the socket on the projector that could have gone bad.

Check the wiring that feeds that outlet by taking off the igniter plate. One connector could have been pushed by the plug on the module in to make this erratic contact issue, or the total connection itself isn't faring too well by the module not being pushed in all the way. I usually gauge the distance between the air outlet square duct to the right being almost a fit tight gap to the air entrance on the module. Any wider gap tells me that the module isn't all the way in where I have to pull the module out to get reseated.

Posted by Marlon Martins (Member # 8823) on 10-02-2018, 12:22 AM:

finally the technicians updated remotely, we re-seated all boards and cleaned all contacts and verified all the plugs (as
Monte suggested). comparing with the other 19b, the placement of connectors and everything else is as should be. let's wait and see if the problem reappear [Wink]

the funny thing is, on the same morning they updated remotely, the other projector we had was also updated. but then it got a tons of errors on the voltage rails and etc (24v led was blinking and fans where stopping). Initially i was sure they updated and did something wrong, the projector is running fine for 2 years, and it show problems ON THE SAME MORNING they updated??? well, reviewing the logs, was really just a coincidence. after a lot of back and forth boards with the other projector, to find the problems, the cinema controller and fan controller boards where the problem. they replaced now and is all good [Wink]

(fingers crossed)

Posted by Marlon Martins (Member # 8823) on 10-29-2018, 12:53 AM:

the 19B since then did not show any problem turning on the lamp.

but a few days ago, the projector status led became "blinking green", and the projector did not do a proper startup cycle, freezing forever on the led blinking green. the control panel buttons did not lit up. no response trying to connect to communicator. (the hardware error led stayed lit)

after hours swapping boards with the other 19b, the problem was discovered to be the control panel. (disconnecting it the projector did a proper power up). after further digging, the cause was the button above the dallas key slot was oxidated, and was permanently pressed. cleaning it and fixing the button the projector is now fully working.

well, not fully, strangely, the left smps fan failed a few weeks ago. then yesterday the same cooler on the other 19b failed too. so weird.

Posted by Carsten Kurz (Member # 5396) on 10-29-2018, 05:35 AM:

Maybe you have very unfortunate environmental conditions there?

- Carsten

Posted by Marlon Martins (Member # 8823) on 10-29-2018, 06:29 AM:

Yes, 2 blocks from the beach. [Frown]

but we have air filters in the air inlets to the projectors rooms.

Posted by Marlon Martins (Member # 8823) on 10-31-2018, 02:56 PM:

today the other projector had the same problem, the button got oxidized and got stuck. 4 days between them. amazing

the technicians said some projectors they do maintenance also had this problem.

so, if you have a 19b or similar with a little dusty ambient, place a tape over the button and around the dallas key holder. (where the air goes in)