cron2container.log

2015.10.18 19.18.39_849 INF :  4536 SpreadServeProcess ctor: InputTopic(/ss/engine/4428/input) OutputTopic(/ss/engine/4428/output) ServiceName(SpreadServeEngine4428)
 
2015.10.18 19.18.39_849 INF :  4536 host(WIN-E2ABUUD2V1V) user(NT AUTHORITY\SYSTEM) pid(4428)
2015.10.18 19.18.39_849 INF :  4536 cmd line args: c:\SpreadServe\ss0.3.1\bin\sseng.exe -PIPE_LOG SX  -env SIT -name SpreadServeEngine
2015.10.18 19.18.39_849 INF :  4536 ######################################################
2015.10.18 19.18.39_849 INF :  4536 SpreadServeProcess ctor: InputTopic(/ss/engine/4428/input) OutputTopic(/ss/engine/4428/output) ServiceName(SpreadServeEngine4428)
2015.10.18 19.18.39_850 INF :  4536 CCmdLineOpt::ParseFromString: opt(PIPE_LOG) val(SX)
2015.10.18 19.18.39_850 INF :  4536 CCmdLineOpt::ParseFromString: opt(ENV) val(SIT)
2015.10.18 19.18.39_850 INF :  4536 CCmdLineOpt::ParseFromString: opt(NAME) val(SpreadServeEngine)
2015.10.18 19.18.39_850 INF :  4536 CProcFwk::GenSessionId : sessionid : win-e2abuud2v1v_4428_a18191839046
2015.10.18 19.18.39_850 INF :  4536 CDuct::Initialise: Created Duct endpoint \\.\Pipe\Duct_SIT_4428_4536
2015.10.18 19.18.39_850 INF :  4536 CProcFwk::RegisterMessage:  Registering interface class Leto::CDuct [0x013cdb94] to message id "49230".
2015.10.18 19.18.39_850 INF :  4536 CProcFwk::RegisterMessage:  Registering interface class Leto::CDuct [0x013cdb94] to message id "49231".
2015.10.18 19.18.39_851 INF :  4536 CDuct::Initialise: Created Duct endpoint \\.\Pipe\Duct_SIT_Logger_4428_4536
2015.10.18 19.18.39_852 INF :  4536 will warn if return interval to DispatchEvents not within 5000ms
2015.10.18 19.18.39_852 INF :  4536 ProcFwk::CreateAide: SpreadServeEngine4428, True
2015.10.18 19.18.39_854 INF :  4536 CProcFwk::RegisterMessage:  Registering interface class SpreadServeProcess [0x006efa34] to message id "49233".
2015.10.18 19.18.39_854 INF :  4536 CProcFwk::RegisterMessage:  Registering interface class SpreadServeProcess [0x006efa34] to message id "49234".
2015.10.18 19.18.39_854 INF :  4536 CProcFwk::RegisterMessage:  Registering interface class SpreadServeProcess [0x006efa34] to message id "49235".
2015.10.18 19.18.39_854 INF :  4536 CProcFwk::RedirectStdoutToLog: logPipeName(\\.\Pipe\Duct_SIT_Logger_4428_4536), handle(0x00000394) osfhandle(3) dup2(0)
2015.10.18 19.18.39_855 INF :  4536 OnAideStatus: AideMgr True
2015.10.18 19.18.39_856 INF :  4536 OnAideStatus: AppMgr True
2015.10.18 19.18.39_856 INF :  4536 CProcFwk::LocalSetAideStatus: Aide EventBus SetStatus( OnlineButLocallyOffline) 
2015.10.18 19.18.39_856 INF :  4536 CProcFwk::RegisterMessage:  Registering interface class Leto::CPanClient [0x022e2f78] to message id "49229".
2015.10.18 19.18.39_856 INF :  4536 CPanChannel::CreateClientSidePipes:  created inPanPipe(\\.\pipe\ClientPipe_4428_4536_IN) and outPanPipe(\\.\pipe\ClientPipe_4428_4536_OUT)
2015.10.18 19.18.39_858 INF :  4536 CProcFwk::ProcessAideMgrMessage: Overriding received status (Online) of my own Service SpreadServeEngine4428
2015.10.18 19.18.39_858 INF :  4536 OnAideStatus: SpreadServeEngine4428 True
2015.10.18 19.18.39_858 INF :  4536 CPanClient::OnAideResp: calling LocalSendAideStatus
2015.10.18 19.18.39_858 INF :  4536 CProcFwk::LocalSendAideStatus: Aide EventBus SetStatus( Online) 
2015.10.18 19.18.39_858 INF :  4536 OnAideStatus: EventBus True
2015.10.18 19.18.39_858 INF :  4536 CPanClient::CPanSubService::RegisterConsumer:  registered consumer of type class SpreadServeProcess at 0x006efa30
2015.10.18 19.18.39_858 INF :  4536 CPanClient::Subscribe: topic(/ss/engine/4428/input) opts(66)
2015.10.18 19.18.39_929 INF :  1628 EngineThreadFunc: launching EngineThread. CoInitialize hr(The operation completed successfully.)
2015.10.18 19.18.39_929 INF :  1628 SSUnknown::SSUnknown: SSConnectionPointContainer
2015.10.18 19.18.39_932 INF :  1628 SSApplication::SSApplication: LoadTypeLib( ) hr1(The operation completed successfully.) hr2(The operation completed successfully.)
2015.10.18 19.18.39_932 INF :  1628 SSWindows::WindowProc: hwnd(0x00010030) msg(36)
2015.10.18 19.18.39_932 INF :  1628 SSWindows::WindowProc: hwnd(0x00010030) msg(129)
2015.10.18 19.18.39_932 INF :  1628 SSWindows::WindowProc: hwnd(0x00010030) msg(131)
2015.10.18 19.18.39_932 INF :  1628 SSWindows::WindowProc: hwnd(0x00010030) msg(1)
2015.10.18 19.18.39_932 INF :  1628 SSWindows::WindowProc: hwnd(0x00010032) msg(129)
2015.10.18 19.18.39_932 INF :  1628 SSWindows::WindowProc: hwnd(0x00010032) msg(131)
2015.10.18 19.18.39_932 INF :  1628 SSWindows::WindowProc: hwnd(0x00010032) msg(1)
2015.10.18 19.18.39_933 INF :  1628 SSWindows::WindowProc: hwnd(0x00010032) msg(5)
2015.10.18 19.18.39_933 INF :  1628 SSWindows::WindowProc: hwnd(0x00010032) msg(3)
2015.10.18 19.18.39_933 INF :  1628 SSWindows::WindowProc: hwnd(0x00010030) msg(528)
2015.10.18 19.18.39_933 INF :  1628 SSWindows::Initialise: XLMAIN(0x00010030) EXCEL7(0x00010032)
2015.10.18 19.18.44_625 INF :  1628 SpreadServeEngine::Init: RTD_FULL_CALC(False)
2015.10.18 19.18.44_625 INF :  1628 SpreadServeEngine::Init: XLS_CFG_DIR(file:///c:/SpreadServe/ss0.3.1/cfg/xcfg)
2015.10.18 19.18.44_725 INF :  1628 EngineThreadFunc: msg(32769) for RTDEvent
2015.10.18 19.18.44_725 INF :  1628 SSWindows::DispatchRTDMessage: msg( eTLSCreated), RefreshData(0x70066be3)
2015.10.18 19.18.45_750 INF :  4536 OnAideStatus: RealTimeWebServer True
2015.10.18 19.18.47_737 INF :  4536 SpreadServeProcess::OnMessage: /ss/engine/4428/input {"action": "publish", "req_uid": "WORKGROUP\\WIN-E2ABUUD2V1V$", "src_uid": "WORKGROUP\\WIN-E2ABUUD2V1V$", "src_pid": 300, "format": "html"}
2015.10.18 19.18.47_737 INF :  1628 SpreadServeEngine::Input: {"action": "publish", "req_uid": "WORKGROUP\\WIN-E2ABUUD2V1V$", "src_uid": "WORKGROUP\\WIN-E2ABUUD2V1V$", "src_pid": 300, "format": "html"}
2015.10.18 19.18.47_738 ERR :  1628 SpreadServeEngine::GetDoc: no doc loaded!
2015.10.18 19.18.47_738 INF :  4536 SpreadServeProcess::OnMessageEvent: /ss/engine/4428/output {
    "action": "publish",
    "req_uid": "WORKGROUP\\WIN-E2ABUUD2V1V$",
    "src_uid": "WORKGROUP\\WIN-E2ABUUD2V1V$",
    "src_pid": "300",
    "format": "html",
    "tables": "",
    "sheet": "",
    "pid": "4428",
    "fingerprint": "",
    "result": "failed",
    "error": "no doc loaded!"
}
2015.10.18 19.19.38_985 INF :  4536 CDuctPipeInstance::DeleteConnection: disconnection pipe=0x00000294, activeIO=0, this=0x022e4480
2015.10.18 19.19.38_985 INF :  4536 CDuctPipeInstance::~CDuctPipeInstance 0x022e4480, pipe=0x00000294, refCount=6
2015.10.18 19.19.39_117 INF :  4536 CDuctPipeInstance::DeleteConnection: disconnection pipe=0x00000298, activeIO=0, this=0x022e4dd8
2015.10.18 19.19.39_117 INF :  4536 CDuctPipeInstance::~CDuctPipeInstance 0x022e4dd8, pipe=0x00000298, refCount=5
2015.10.18 19.19.39_853 INF :  4536 CDuct::OnTimerEvent: Pipe \\.\Pipe\Duct_SIT inactive for 30 seconds - closing
2015.10.18 19.19.39_853 INF :  4536 CDuct::OnTimerEvent: Pipe \\.\Pipe\Duct_SIT_2212_4044 inactive for 30 seconds - closing
2015.10.18 19.19.51_005 INF :  4536 SpreadServeProcess::OnMessage: /ss/engine/4428/input {"sheet": "file:///c:/SpreadServe/ss0.3.1/py/http/repo/cron2.xls", "format": "xls", "src_pid": 300, "req_uid": "autotest", "action": "load", "src_uid": "WORKGROUP\\WIN-E2ABUUD2V1V$"}
2015.10.18 19.19.51_006 INF :  1628 SpreadServeEngine::Input: {"sheet": "file:///c:/SpreadServe/ss0.3.1/py/http/repo/cron2.xls", "format": "xls", "src_pid": 300, "req_uid": "autotest", "action": "load", "src_uid": "WORKGROUP\\WIN-E2ABUUD2V1V$"}
2015.10.18 19.19.51_656 INF :  1628 SSApplication::AddRef: RefCount(2)
2015.10.18 19.19.51_659 INF :  1628 SSApplication::AddRef: RefCount(3)
2015.10.18 19.19.51_659 INF :  1628 SSApplication::AddRef: RefCount(4)
2015.10.18 19.19.51_735 INF :  1628 SpreadServeEngine::Load: loaded file:///c:/SpreadServe/ss0.3.1/py/http/repo/cron2.xls as xls
2015.10.18 19.19.52_149 INF :  1628 SSApplication::AddRef: RefCount(5)
2015.10.18 19.19.52_149 INF :  1628 SSApplication::AddRef: RefCount(6)
2015.10.18 19.19.52_149 INF :  1628 SSApplication::AddRef: RefCount(7)
2015.10.18 19.19.52_149 INF :  1628 SpreadServeEngine::Load: 2 tables. subs s2cfg 
2015.10.18 19.19.52_150 INF :  1628 SpreadServeEngine::BuildSSVID: (10_1_1)=21;15;0
2015.10.18 19.19.52_150 INF :  1628 SpreadServeEngine::BuildSSVID: (1_0_0)=s2about
2015.10.18 19.19.52_150 INF :  1628 SpreadServeEngine::BuildSSVID: (1_1_0)=s2cronA2
2015.10.18 19.19.52_150 INF :  1628 SpreadServeEngine::BuildSSVID: (1_2_0)=s2sub$s2cfg.$A$1;$A$2;A3
2015.10.18 19.19.52_150 INF :  1628 SpreadServeEngine::BuildSSVID: (1_3_0)=s2sub$s2cfg.$A$1;$A$2;A4
2015.10.18 19.19.52_150 INF :  1628 SpreadServeEngine::BuildSSVID: (1_4_0)=s2sub$s2cfg.$A$1;$A$2;A5
2015.10.18 19.19.52_150 INF :  1628 SpreadServeEngine::BuildSSVID: fingerprint(cab351d335418560)
2015.10.18 19.19.52_153 INF :  4536 SpreadServeProcess::OnMessageEvent: /ss/engine/4428/output {
    "sheet": "file:///c:/SpreadServe/ss0.3.1/py/http/repo/cron2.xls",
    "format": "xls",
    "src_pid": "300",
    "req_uid": "autotest",
    "action": "load",
    "src_uid": "WORKGROUP\\WIN-E2ABUUD2V1V$",
    "tables":
    [
        "subs",
        "s2cfg"
    ],
    "pid": "4428",
    "fingerprint": "cab351d335418560",
    "result": "ok"
}
2015.10.18 19.19.52_267 INF :  4536 SpreadServeProcess::OnMessage: /ss/engine/4428/input {"action": "publish", "req_uid": "WORKGROUP\\WIN-E2ABUUD2V1V$", "src_uid": "WORKGROUP\\WIN-E2ABUUD2V1V$", "src_pid": 300, "format": "html"}
2015.10.18 19.19.52_267 INF :  1628 SpreadServeEngine::Input: {"action": "publish", "req_uid": "WORKGROUP\\WIN-E2ABUUD2V1V$", "src_uid": "WORKGROUP\\WIN-E2ABUUD2V1V$", "src_pid": 300, "format": "html"}
2015.10.18 19.19.52_268 INF :  4536 SpreadServeProcess::OnMessageEvent: /ss/engine/4428/output {
    "action": "publish",
    "req_uid": "WORKGROUP\\WIN-E2ABUUD2V1V$",
    "src_uid": "WORKGROUP\\WIN-E2ABUUD2V1V$",
    "src_pid": "300",
    "format": "html",
    "result": "ok",
    "html":
    [
        "<HEAD>\n\n\n<STYLE>\n<!-- \nBODY,DIV,TABLE,THEAD,TBODY,TFOOT,TR,TH,TD,P { font-family:\"Calibri\"; font-size:x-small }\n -->\n</STYLE>\n\n</HEAD>\n",
        "<TABLE FRAME=VOID CELLSPACING=0 COLS=2 RULES=NONE BORDER=0 ID=sstable>\n<COLGROUP><COL WIDTH=133><COL WIDTH=150></COLGROUP>\n<TBODY>\n<TR id=ssrow0>\n<TD WIDTH=133 HEIGHT=19 ALIGN=LEFT id=m0_0_0 class=ssedit><FONT COLOR=\"#000000\">about</FONT></TD>\n<TD WIDTH=150 ALIGN=LEFT id=m1_0_0><FONT COLOR=\"#000000\">SSAddin 0.1 Excel 11</FONT></TD>\n</TR>\n<TR id=ssrow1>\n<TD HEIGHT=19 ALIGN=LEFT id=m0_1_0 class=ssedit><FONT COLOR=\"#000000\">every5secs</FONT></TD>\n<TD ALIGN=LEFT id=m1_1_0><FONT COLOR=\"#000000\">OK</FONT></TD>\n</TR>\n<TR id=ssrow2>\n<TD HEIGHT=19 ALIGN=LEFT id=m0_2_0 class=ssedit><FONT COLOR=\"#000000\">count</FONT></TD>\n<TD ALIGN=RIGHT id=m1_2_0 SDVAL=\"0\" SDNUM=\"1033;\"><FONT COLOR=\"#000000\"><BR></FONT></TD>\n</TR>\n<TR id=ssrow3>\n<TD HEIGHT=19 ALIGN=LEFT id=m0_3_0 class=ssedit><FONT COLOR=\"#000000\">next</FONT></TD>\n<TD ALIGN=RIGHT id=m1_3_0 SDVAL=\"0\" SDNUM=\"1033;\"><FONT COLOR=\"#000000\"><BR></FONT></TD>\n</TR>\n<TR id=ssrow4>\n<TD HEIGHT=19 ALIGN=LEFT id=m0_4_0 class=ssedit><FONT COLOR=\"#000000\">last</FONT></TD>\n<TD ALIGN=RIGHT id=m1_4_0 SDVAL=\"0\" SDNUM=\"1033;\"><FONT COLOR=\"#000000\"><BR></FONT></TD>\n</TR>\n</TBODY>\n</TABLE>\n",
        "<TABLE FRAME=VOID CELLSPACING=0 COLS=11 RULES=NONE BORDER=0 ID=sstable>\n<COLGROUP><COL WIDTH=66><COL WIDTH=89><COL WIDTH=101><COL WIDTH=72><COL WIDTH=79><COL WIDTH=84><COL WIDTH=96><COL WIDTH=96><COL WIDTH=97><COL WIDTH=66><COL WIDTH=66></COLGROUP>\n<TBODY>\n<TR id=ssrow0>\n<TD WIDTH=66 HEIGHT=19 ALIGN=LEFT id=m0_0_1 class=ssedit><FONT COLOR=\"#000000\">cron</FONT></TD>\n<TD WIDTH=89 ALIGN=LEFT id=m1_0_1 class=ssedit><FONT COLOR=\"#000000\">comment</FONT></TD>\n<TD WIDTH=101 ALIGN=LEFT id=m2_0_1 class=ssedit><FONT COLOR=\"#000000\">headers</FONT></TD>\n<TD WIDTH=72 ALIGN=LEFT id=m3_0_1 class=ssedit><FONT COLOR=\"#000000\">sec:0-59</FONT></TD>\n<TD WIDTH=79 ALIGN=LEFT id=m4_0_1 class=ssedit><FONT COLOR=\"#000000\">min:0-59</FONT></TD>\n<TD WIDTH=84 ALIGN=LEFT id=m5_0_1 class=ssedit><FONT COLOR=\"#000000\">hr:0-23</FONT></TD>\n<TD WIDTH=96 ALIGN=LEFT id=m6_0_1 class=ssedit><FONT COLOR=\"#000000\">day:1-31</FONT></TD>\n<TD WIDTH=96 ALIGN=LEFT id=m7_0_1 class=ssedit><FONT COLOR=\"#000000\">month:1-12</FONT></TD>\n<TD WIDTH=97 ALIGN=LEFT id=m8_0_1 class=ssedit><FONT COLOR=\"#000000\">weekday:0-7</FONT></TD>\n<TD WIDTH=66 ALIGN=LEFT id=m9_0_1 class=ssedit><FONT COLOR=\"#000000\">start</FONT></TD>\n<TD WIDTH=66 ALIGN=LEFT id=m10_0_1 class=ssedit><FONT COLOR=\"#000000\">end</FONT></TD>\n</TR>\n<TR id=ssrow1>\n<TD HEIGHT=19 ALIGN=LEFT id=m0_1_1 class=ssedit><FONT COLOR=\"#000000\">cron</FONT></TD>\n<TD ALIGN=LEFT id=m1_1_1 class=ssedit><FONT COLOR=\"#000000\">tab</FONT></TD>\n<TD ALIGN=LEFT id=m2_1_1 class=ssedit><FONT COLOR=\"#000000\">every5secs</FONT></TD>\n<TD ALIGN=LEFT id=m3_1_1 class=ssedit><FONT COLOR=\"#000000\">0-59/5</FONT></TD>\n<TD ALIGN=LEFT id=m4_1_1 class=ssedit><FONT COLOR=\"#000000\">*</FONT></TD>\n<TD ALIGN=LEFT id=m5_1_1 class=ssedit><FONT COLOR=\"#000000\">*</FONT></TD>\n<TD ALIGN=LEFT id=m6_1_1 class=ssedit><FONT COLOR=\"#000000\">*</FONT></TD>\n<TD ALIGN=LEFT id=m7_1_1 class=ssedit><FONT COLOR=\"#000000\">*</FONT></TD>\n<TD ALIGN=LEFT id=m8_1_1 class=ssedit><FONT COLOR=\"#000000\">*</FONT></TD>\n<TD ALIGN=RIGHT id=m9_1_1 class=ssedit SDVAL=\"0.329861111111111\" SDNUM=\"1033;1033;H:MM AM/PM\"><FONT COLOR=\"#000000\">7:55 AM</FONT></TD>\n<TD ALIGN=RIGHT id=m10_1_1 SDVAL=\"0.885416666666667\" SDNUM=\"1033;1033;H:MM AM/PM\"><FONT COLOR=\"#000000\">9:15 PM</FONT></TD>\n</TR>\n</TBODY>\n</TABLE>\n"
    ],
    "tables":
    [
        "subs",
        "s2cfg"
    ],
    "sheet": "file:///c:/SpreadServe/ss0.3.1/py/http/repo/cron2.xls",
    "pid": "4428",
    "fingerprint": "cab351d335418560"
}

Hypothesis: EngineThreadFunc: msg(799) for XLMAIN|EXCEL7 hwnd(0x000403fe) is missing from this log because GetApplicationFromWindow in Excel-DNA's Excel.cs (https://github.com/Excel-DNA/ExcelDna/blob/master/Source/ExcelDna.Integration/Excel.cs) fails. And it fails because AccessibleObjectFromWindow(
hWndChild, OBJID_NATIVEOM, IID_IDispatchBytes, ref pUnk) fails. This call passes in an HWND, and expects to get a COM object in return. In this case the COM obj is the Excel Application object. Excel-DNA uses that for invoking the IRTDUpdateEvent.UpdateNotify( ) method to tell Excel that there are RTD events waiting to be processed.

Simple check: compare Process Explorer DLLs for for this scenario in container and out, and see if oleacc.dll is loaded. NB the MS doc for AccessibleObjectFromWindow: https://msdn.microsoft.com/en-us/library/windows/desktop/dd317978%28v=vs.85%29.aspx. Note the platform qualifications on desktop apps only.

Windows Container does have all 4 ole dlls.