Follow

Help Deciphering SCORM Cloud /Debug Logs

Avatar

I'm an LMS n00b and have been trying to troubleshoot LMS reporting and tracking issues.  I've followed the '9 Ways to Troubleshoot Atriculate & LMS Issues' guidance and haven't yet had luck.

One of the things I've tried is running a course in debug mode through SCORM Cloud.  I get the following debug log output and could really use some help deciphering it. 

My first question is whether this is working correctly in SCORM Cloud and the rest of my questions are unknown as I don't yet know what I don't yet know.  :) 

Any help would be much appreciated.

[url]http://cloud.scorm.com/sc/guest/ViewDebugLog?logId=eabe3ade-9084-44dd-b559-eebeee83e973&courseTitle=How+to+Navigate+Cigital+eLearning+-+Debug[/url]

0:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - AICCComm - Trying to create XMLHttpRequest in JavaScript1.5
1:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - AICCComm - Entered browser conditional
2:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - AICCComm - Creating object
3:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - AICCComm - intReCheckLoadedInterval=250
4:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - AICCComm - intReCheckAttemptsBeforeTimeout=240
5:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - AICCComm - IFrameLoaded
6:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - AICCComm - In GetAICCURL
7:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - GetQueryStringValue Element 'AICC_URL' Not Found, Returning: empty string
8:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - AICCComm - Querystring value =
9:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - AICCComm - GetAICCURL returning:
10:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - AICCComm - In DetectPreferredCommMethod, checking XMLHTTP
11:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - AICCComm - Checking IFrame
12:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - AICCComm - blnCanUseXMLHTTP=true
13:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - AICCComm - blnCanUseIFrame=true
14:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - ----------------------------------------
15:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - ----------------------------------------
16:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - In Start - Version: 3.3 Last Modified=12/02/2010 17:11:48
17:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - Browser Info (Netscape 5.0 (Macintosh; en-US))
18:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - URL: http://cloud.scorm.com/courses/0H4DTFPX3J/SCORM1.2-v1.0-Debug53567c6b-0fb2-4ec2-a33a-025c16acfd63/0/SCORM1.2-v1.0%20-%20Debug/index_lms.html
19:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - ----------------------------------------
20:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - ----------------------------------------
21:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - In ClearErrorInfo
22:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - GetQueryStringValue Element 'StandAlone' Not Found, Returning: empty string
23:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - GetQueryStringValue Element 'ShowDebug' Not Found, Returning: empty string
24:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - strStandAlone= strShowInteractiveDebug=
25:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - Standard From Configuration File - SCORM
26:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - Using Standard From Configuration File - SCORM
27:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - In LMSStandardAPI strStandard=SCORM
28:Thu Dec 02 2010 17:14:41 GMT-0500 (EST) - Showing Interactive Debug Windows

182:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - In Unload
183:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - In ClearErrorInfo
184:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - In ExecFinish, ExiType=SUSPEND
185:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - In ClearErrorInfo
186:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - Haven't called finish before, finishing
187:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - Did not override time
188:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - In AccumulateTime dtmStart=Thu Dec 02 2010 17:14:41 GMT-0500 (EST) dtmEnd=Thu Dec 02 2010 17:18:45 GMT-0500 (EST) intAccumulatedMS=255
189:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - Accumulating Time
190:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - intAccumulatedMS=244459
191:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - In SCORM_SaveTime intMilliSeconds=244459
192:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - In SCORM_ClearErrorInfo
193:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - In ConvertMilliSecondsIntoSCORMTime, intTotalMilliseconds = 244459, blnIncludeFraction = true
194:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - Separated Parts, intHours=0, intMinutes=4, intSeconds=4, intMilliseconds=459
195:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - In ZeroPad intNum=0 intNumDigits=4
196:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - Returning - 0000
197:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - In ZeroPad intNum=4 intNumDigits=2
198:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - Returning - 04
199:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - In ZeroPad intNum=4 intNumDigits=2
200:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - Returning - 04
201:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - strCMITimeSpan=0000:04:04.45
202:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - returning 0000:04:04.45
203:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - strCMITime=0000:04:04.45
204:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - SCORM_CallLMSSetValue strElement=cmi.core.session_time, strValue=0000:04:04.45
205:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - In SCORM_GrabAPI
206:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - SCORM_GrabAPI, returning
207:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - Calling LMSSetValue
208:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - strResult=true
209:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - Returning true
210:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - Calling LMS Finish
211:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - In SCORM_Finish strExitType=SUSPEND, blnStatusWasSet=false
212:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - In SCORM_ClearErrorInfo
213:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - Setting Exit
214:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - In SCORM_TranslatgeExitTypeToSCORM strExitType-SUSPEND
215:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - Returning suspend
216:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - SCORM_CallLMSSetValue strElement=cmi.core.exit, strValue=suspend
217:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - In SCORM_GrabAPI
218:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - SCORM_GrabAPI, returning
219:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - Calling LMSSetValue
220:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - strResult=true
221:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - Returning true
222:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - Calling Commit
223:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - In SCORM_CallLMSCommit
224:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - In SCORM_GrabAPI
225:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - SCORM_GrabAPI, returning
226:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - Calling LMSCommit
227:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - strResult=true
228:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - Returning true
229:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - Calling Finish
230:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - In SCORM_CallLMSFinish
231:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - In SCORM_GrabAPI
232:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - SCORM_GrabAPI, returning
233:Thu Dec 02 2010 17:18:45 GMT-0500 (EST) - Calling LMS Finish
234:Thu Dec 02 2010 17:18:48 GMT-0500 (EST) - strResult=true
235:Thu Dec 02 2010 17:18:48 GMT-0500 (EST) - Returning True
236:Thu Dec 02 2010 17:18:48 GMT-0500 (EST) - Returning true
237:Thu Dec 02 2010 17:18:48 GMT-0500 (EST) - In Unload
238:Thu Dec 02 2010 17:18:48 GMT-0500 (EST) - In ClearErrorInfo
239:Thu Dec 02 2010 17:18:48 GMT-0500 (EST) - In ExecFinish, ExiType=SUSPEND
240:Thu Dec 02 2010 17:18:48 GMT-0500 (EST) - In ClearErrorInfo
241:Thu Dec 02 2010 17:18:48 GMT-0500 (EST) - In Unload
242:Thu Dec 02 2010 17:18:48 GMT-0500 (EST) - In ClearErrorInfo
243:Thu Dec 02 2010 17:18:48 GMT-0500 (EST) - In ExecFinish, ExiType=SUSPEND
244:Thu Dec 02 2010 17:18:48 GMT-0500 (EST) - In ClearErrorInfo
245:Thu Dec 02 2010 17:18:48 GMT-0500 (EST) - In Unload
246:Thu Dec 02 2010 17:18:48 GMT-0500 (EST) - In ClearErrorInfo
247:Thu Dec 02 2010 17:18:48 GMT-0500 (EST) - In ExecFinish, ExiType=SUSPEND
248:Thu Dec 02 2010 17:18:48 GMT-0500 (EST) - In ClearErrorInfo

Was this article helpful?
0 out of 0 found this helpful
Have more questions? Submit a request

Comments

  • Avatar
    John Mensel

    Ben.

    I'm certainly one who could help you read this... It looks, though, like the logs may have been truncated.  Lines 29 - 181 are missing.  

    (Also of note, this is the content side debug log from Articulate... the SCORM Cloud side is actually easier to read...)

    There's nothing in this much of the log that says anything is going wrong.  Did you have a specific concern you were seeking out?

  • Avatar
    Ben

    Hi Tim,

    Thanks for the help here.  I've closed down the debug window, and lost lines 29-181.  However, are you saying that the SCORM Cloud Test Track results don't indicate an issue?

    The issue I'm trying to troubleshoot is that this module is not properly reporting completion status in a customer's LMS.  I want to see if there's a problem in the publishing settings of the module itself, or determine if the problem resides on their side.  Can you help?

  • Avatar
    Ben

    Also, I tested the module in Test Track and the module is not showing as having been completed in the Course Reportage section.  Can anyone help?

  • Avatar
    John Mensel

    SCORM Cloud has probably persisted a debug log for you.  Log back in and look down and to the left from the launch button for that course.  You'll see a link to a debug log.  Once you open it, you can find a url that you could post here.  

    If things aren't working with an Articulate course in SCORM Cloud, you probably did something wrong on export.  Is that something you could pursue with Articulate support or forums?

  • Avatar
    Ben

    Thanks, Tim.  Here are a couple of Test Track debug logs for your review:

    This one I generated a few days ago:

    http://cloud.scorm.com/sc/guest/ViewDebugLog?logId=41b7271d-5f0a-4605-933c-92af02c437a8&courseTitle=How+to+Navigate+Cigital+eLearning+-+NonResume

    This one I generated today after manipulating some of the Articulate settings:

    http://cloud.scorm.com/sc/guest/ViewDebugLog?logId=913ccc8b-4cd2-4ab5-a695-d6530e22e1eb&courseTitle=PPT

  • Avatar
    John Mensel

    In both cases it looks like you have a communicative SCO.  It is talking to the cloud effectively.  The second also sends a status of complete to the LMS.

    What gives you the impression that it's not working properly?

  • Avatar
    Ben

    Two things that are concerning me are:

    1. A customer is having problems with the modules reporting as 'complete' in their LMS; and

    2. The first course (the first link below that I posted) is reporting in Test Track as incomplete (under SCORM Test Track Sandbox, the Completion status is 'incomplete' and the Success status is 'unknown'.

    Thoughts?

    Thanks again for your help.

  • Avatar
    John Mensel

    In the first course, you didn't do whatever the course required of you to send the completion status, so Cloud is right to believe you haven't completed... It's probably something with your settings.

    As for the destination LMS, we don't have an agreement with Articulate whereby we do the support as the content is deployed to LMSs.  You'll have to start with Articulate to work through that... (Or potentially the LMS vendor since you have a conformant course.  Sorry I can't be more helpful.)

    Tim

     

Powered by Zendesk