COG crashing - more info

Previous Topic Next Topic
 
classic Classic list List threaded Threaded
13 messages Options
Reply | Threaded
Open this post in threaded view
|

COG crashing - more info

Bob Arning-2
 
Found some more details. Seems to be saying it's out of memory. How is this different from getting a LowSpaceDebug log?

Cheers,
Bob

Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: warning, processHasThreadId flag is unset; cannot function as a threaded VM if so.
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: warning: Process doesn't have threadId; VM will not thread
Jan  7 00:07:22: --- last message repeated 9 times ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: out of memory
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: C stack backtrace:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: Smalltalk stack dump:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: 0xbff8e1d0 M Delay class>handleTimerEvent 157474072: a(n) Delay class
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: 0xbff8e1f0 I Delay class>runTimerEventLoop 157474072: a(n) Delay class
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]:  0x9851140 s [] in Delay class>startTimerEventLoop
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]:  0x9851260 s [] in BlockClosure>newProcess
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: Most recent primitives
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: at:put:
Jan  7 00:07:22: --- last message repeated 1 time ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: findSubstring:in:startingAt:matchTable:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: indexOfAscii:inString:startingAt:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: value
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: compare:with:collated:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: at:put:
Jan  7 00:07:22: --- last message repeated 11 times ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: findSubstring:in:startingAt:matchTable:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: indexOfAscii:inString:startingAt:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: value
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: compare:with:collated:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: at:put:
Jan  7 00:07:22: --- last message repeated 11 times ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: findSubstring:in:startingAt:matchTable:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: indexOfAscii:inString:startingAt:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: value
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: compare:with:collated:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: at:put:
Jan  7 00:07:22: --- last message repeated 13 times ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: at:put:
Jan  7 00:07:22: --- last message repeated 1 time ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: yield
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: signal
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: findNextUnwindContextUpTo:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: tempAt:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: tempAt:put:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: tempAt:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: terminateTo:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: findNextUnwindContextUpTo:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: tempAt:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: tempAt:put:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: tempAt:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: terminateTo:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: signal
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: findNextUnwindContextUpTo:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: terminateTo:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: basicNew
Jan  7 00:07:22: --- last message repeated 1 time ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: signal
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: at:put:
Jan  7 00:07:22: --- last message repeated 3 times ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: primSignal:atMilliseconds:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: signal
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: signal
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: at:put:
Jan  7 00:07:22: --- last message repeated 7 times ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: primSignal:atMilliseconds:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: @
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: actualScreenSize
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: species
Jan  7 00:07:22: --- last message repeated 1 time ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22: --- last message repeated 1 time ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: new:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: primGetNextEvent:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: basicNew
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: new:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: at:put:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: new:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: basicNew
Jan  7 00:07:22: --- last message repeated 3 times ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: at:put:
Jan  7 00:07:22: --- last message repeated 1 time ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: basicNew
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: new:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: basicNew
Jan  7 00:07:22: --- last message repeated 1 time ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22: --- last message repeated 2 times ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: signal
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: yield
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: signal
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: findNextUnwindContextUpTo:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: tempAt:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: tempAt:put:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: tempAt:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: terminateTo:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: findNextUnwindContextUpTo:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: tempAt:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: tempAt:put:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: tempAt:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: terminateTo:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: signal
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: findNextUnwindContextUpTo:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: terminateTo:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: basicNew
Jan  7 00:07:22: --- last message repeated 1 time ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: signal
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: at:put:
Jan  7 00:07:22: --- last message repeated 3 times ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: primSignal:atMilliseconds:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: signal
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: signal
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: at:put:
Jan  7 00:07:22: --- last message repeated 7 times ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: primSignal:atMilliseconds:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: @
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: actualScreenSize
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: species
Jan  7 00:07:22: --- last message repeated 1 time ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22: --- last message repeated 1 time ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: new:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: signal
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: at:put:
Jan  7 00:07:22: --- last message repeated 5 times ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: primSignal:atMilliseconds:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:25 robert-arnings-computer ReportCrash[65021]: Formulating crash report for process Croquet[64493]
Jan  7 00:07:26 robert-arnings-computer ReportCrash[65021]: Saved crashreport to /Users/bob/Library/Logs/CrashReporter/Croquet_2012-01-07-000722_robert-arnings-computer.crash using uid: 503 gid: 503, euid: 503 egid: 503
Jan  7 00:07:25 robert-arnings-computer com.apple.launchd[157] ([0x0-0x1ad1ad0].com.squeak.Cog[64493]): Exited abnormally: Abort trap

Reply | Threaded
Open this post in threaded view
|

Re: COG crashing - more info

Eliot Miranda-2
 


On Sat, Jan 7, 2012 at 7:28 AM, Bob Arning <[hidden email]> wrote:
 
Found some more details. Seems to be saying it's out of memory. How is this different from getting a LowSpaceDebug log?

LowSpaceDebug.log is written from the image when a low space condition is detected.  The VM aborting with out of memory means an allocation in the VM (e.g. in a primitive) faled and the VM had not option but to abort.   If when the VM signals the low space semaphore the image doesn't take appropriate action in response (i.e. stop allocating and free up space) the system may progress to the point of an allocation failure.

BTW, the below is not as useful as the crash.log file.


Cheers,
Bob

Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: warning, processHasThreadId flag is unset; cannot function as a threaded VM if so.
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: warning: Process doesn't have threadId; VM will not thread
Jan  7 00:07:22: --- last message repeated 9 times ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: out of memory
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: C stack backtrace:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: Smalltalk stack dump:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: 0xbff8e1d0 M Delay class>handleTimerEvent 157474072: a(n) Delay class
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: 0xbff8e1f0 I Delay class>runTimerEventLoop 157474072: a(n) Delay class
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]:  0x9851140 s [] in Delay class>startTimerEventLoop
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]:  0x9851260 s [] in BlockClosure>newProcess
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: Most recent primitives
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: at:put:
Jan  7 00:07:22: --- last message repeated 1 time ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: findSubstring:in:startingAt:matchTable:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: indexOfAscii:inString:startingAt:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: value
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: compare:with:collated:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: at:put:
Jan  7 00:07:22: --- last message repeated 11 times ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: findSubstring:in:startingAt:matchTable:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: indexOfAscii:inString:startingAt:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: value
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: compare:with:collated:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: at:put:
Jan  7 00:07:22: --- last message repeated 11 times ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: findSubstring:in:startingAt:matchTable:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: indexOfAscii:inString:startingAt:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: value
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: compare:with:collated:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: at:put:
Jan  7 00:07:22: --- last message repeated 13 times ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: at:put:
Jan  7 00:07:22: --- last message repeated 1 time ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: yield
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: signal
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: findNextUnwindContextUpTo:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: tempAt:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: tempAt:put:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: tempAt:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: terminateTo:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: findNextUnwindContextUpTo:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: tempAt:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: tempAt:put:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: tempAt:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: terminateTo:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: signal
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: findNextUnwindContextUpTo:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: terminateTo:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: basicNew
Jan  7 00:07:22: --- last message repeated 1 time ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: signal
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: at:put:
Jan  7 00:07:22: --- last message repeated 3 times ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: primSignal:atMilliseconds:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: signal
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: signal
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: at:put:
Jan  7 00:07:22: --- last message repeated 7 times ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: primSignal:atMilliseconds:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: @
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: actualScreenSize
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: species
Jan  7 00:07:22: --- last message repeated 1 time ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22: --- last message repeated 1 time ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: new:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: primGetNextEvent:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: basicNew
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: new:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: at:put:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: new:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: basicNew
Jan  7 00:07:22: --- last message repeated 3 times ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: shallowCopy
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: perform:with:with:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: at:put:
Jan  7 00:07:22: --- last message repeated 1 time ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: basicNew
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: new:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: basicNew
Jan  7 00:07:22: --- last message repeated 1 time ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22: --- last message repeated 2 times ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: signal
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: yield
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: signal
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: findNextUnwindContextUpTo:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: tempAt:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: tempAt:put:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: tempAt:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: terminateTo:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: findNextUnwindContextUpTo:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: tempAt:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: tempAt:put:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: tempAt:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: terminateTo:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: signal
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: findNextUnwindContextUpTo:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: terminateTo:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: basicNew
Jan  7 00:07:22: --- last message repeated 1 time ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: signal
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: at:put:
Jan  7 00:07:22: --- last message repeated 3 times ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: primSignal:atMilliseconds:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: signal
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: signal
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: at:put:
Jan  7 00:07:22: --- last message repeated 7 times ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: primSignal:atMilliseconds:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: @
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: actualScreenSize
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: species
Jan  7 00:07:22: --- last message repeated 1 time ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22: --- last message repeated 1 time ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: new:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: signal
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: at:put:
Jan  7 00:07:22: --- last message repeated 5 times ---
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: primSignal:atMilliseconds:
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: millisecondClockValue
Jan  7 00:07:22 robert-arnings-computer [0x0-0x1ad1ad0].com.squeak.Cog[64493]: wait
Jan  7 00:07:25 robert-arnings-computer ReportCrash[65021]: Formulating crash report for process Croquet[64493]
Jan  7 00:07:26 robert-arnings-computer ReportCrash[65021]: Saved crashreport to /Users/bob/Library/Logs/CrashReporter/Croquet_2012-01-07-000722_robert-arnings-computer.crash using uid: 503 gid: 503, euid: 503 egid: 503
Jan  7 00:07:25 robert-arnings-computer com.apple.launchd[157] ([0x0-0x1ad1ad0].com.squeak.Cog[64493]): Exited abnormally: Abort trap





--
best,
Eliot

Reply | Threaded
Open this post in threaded view
|

Re: COG crashing - more info

Bob Arning-2
 
Is that the first file I posted, or yet another file? I looked and did not see anything specifically named "crash.log"

Cheers,
Bob

On 1/7/12 6:35 PM, Eliot Miranda wrote:
BTW, the below is not as useful as the crash.log file.

Reply | Threaded
Open this post in threaded view
|

Re: COG crashing - more info

johnmci
In reply to this post by Eliot Miranda-2

On Sat, Jan 7, 2012 at 6:35 PM, Eliot Miranda <[hidden email]> wrote:

>
>
>
> On Sat, Jan 7, 2012 at 7:28 AM, Bob Arning <[hidden email]> wrote:
>>
>>
>> Found some more details. Seems to be saying it's out of memory. How is this different from getting a LowSpaceDebug log?
>
>
> LowSpaceDebug.log is written from the image ]

Like

Back in 05 Tim and I looked at this, you can steal space from the GC
reserved space btw, chewing it down from MB to kilobytes. But what Tim
and I realized was that the condition was usually fatal, so why
bother.
To fix what you have to do is suspend/debug all tasks not associated
with running the image, and let the developer figure out who is the
bad guy. Right now it suspects the running process which usually isn't
the evil one.
Or attempts to ask the developer to fix the issue, but you are
milliseconds from death...

--
===========================================================================
John M. McIntosh <[hidden email]>
Corporate Smalltalk Consulting Ltd.  http://www.smalltalkconsulting.com
===========================================================================
Reply | Threaded
Open this post in threaded view
|

Re: COG crashing - more info

Eliot Miranda-2
In reply to this post by Bob Arning-2
 


On Sat, Jan 7, 2012 at 6:22 PM, Bob Arning <[hidden email]> wrote:
 
Is that the first file I posted, or yet another file? I looked and did not see anything specifically named "crash.log"

Oops.  It is called crash.dmp and is produced in whatever the image file's directory is (a poor choice in certain circumstances, but it has to go somewhere).
  

Cheers,
Bob

On 1/7/12 6:35 PM, Eliot Miranda wrote:
BTW, the below is not as useful as the crash.log file.





--
best,
Eliot

Reply | Threaded
Open this post in threaded view
|

Re: COG crashing - more info

Bob Arning-2
 
Does it take something special to generate one of those? The most recent I have is 18 months old.

Cheers,
Bob

On 1/8/12 6:34 PM, Eliot Miranda wrote:
 




On Sat, Jan 7, 2012 at 6:22 PM, Bob Arning <[hidden email]> wrote:
 
Is that the first file I posted, or yet another file? I looked and did not see anything specifically named "crash.log"

Oops.  It is called crash.dmp and is produced in whatever the image file's directory is (a poor choice in certain circumstances, but it has to go somewhere).
  

Cheers,
Bob

On 1/7/12 6:35 PM, Eliot Miranda wrote:
BTW, the below is not as useful as the crash.log file.





--
best,
Eliot

Reply | Threaded
Open this post in threaded view
|

Re: COG crashing - more info

Bob Arning-2
In reply to this post by Eliot Miranda-2
 
Crashed again overnight. Still no crash.dmp to be found. This time using latest non-MT Cog (2522).

Cheers,
Bob

On 1/8/12 6:34 PM, Eliot Miranda wrote:
 




On Sat, Jan 7, 2012 at 6:22 PM, Bob Arning <[hidden email]> wrote:
 
Is that the first file I posted, or yet another file? I looked and did not see anything specifically named "crash.log"

Oops.  It is called crash.dmp and is produced in whatever the image file's directory is (a poor choice in certain circumstances, but it has to go somewhere).
  

Cheers,
Bob

On 1/7/12 6:35 PM, Eliot Miranda wrote:
BTW, the below is not as useful as the crash.log file.





--
best,
Eliot

Reply | Threaded
Open this post in threaded view
|

Re: COG crashing - more info

Eliot Miranda-2
In reply to this post by Bob Arning-2
 


On Sun, Jan 8, 2012 at 4:20 PM, Bob Arning <[hidden email]> wrote:
 
Does it take something special to generate one of those? The most recent I have is 18 months old.

They're produced either when the VM experiences a fatal error (either an internal call of error or catching a fatal signal etc).  How up-to-date is your VM?  That you're seeing messages on the console and not getting a crash.dmp might indicate your VM is old.


Cheers,
Bob

On 1/8/12 6:34 PM, Eliot Miranda wrote:
 




On Sat, Jan 7, 2012 at 6:22 PM, Bob Arning <[hidden email]> wrote:
 
Is that the first file I posted, or yet another file? I looked and did not see anything specifically named "crash.log"

Oops.  It is called crash.dmp and is produced in whatever the image file's directory is (a poor choice in certain circumstances, but it has to go somewhere).
  

Cheers,
Bob

On 1/7/12 6:35 PM, Eliot Miranda wrote:
BTW, the below is not as useful as the crash.log file.





--
best,
Eliot





--
best,
Eliot

Reply | Threaded
Open this post in threaded view
|

Re: COG crashing - more info

Bob Arning-2
 
The most recent crash was with Cog 2522 which I just downloaded a day or two ago. FWIW, I'm not actually seeing these message on a console - I locate the files manually after the fact. I'm not very console oriented.

Cheers,
Bob

On 1/9/12 4:05 PM, Eliot Miranda wrote:
 




On Sun, Jan 8, 2012 at 4:20 PM, Bob Arning <[hidden email]> wrote:
 
Does it take something special to generate one of those? The most recent I have is 18 months old.

They're produced either when the VM experiences a fatal error (either an internal call of error or catching a fatal signal etc).  How up-to-date is your VM?  That you're seeing messages on the console and not getting a crash.dmp might indicate your VM is old.


Cheers,
Bob

On 1/8/12 6:34 PM, Eliot Miranda wrote:
 




On Sat, Jan 7, 2012 at 6:22 PM, Bob Arning <[hidden email]> wrote:
 
Is that the first file I posted, or yet another file? I looked and did not see anything specifically named "crash.log"

Oops.  It is called crash.dmp and is produced in whatever the image file's directory is (a poor choice in certain circumstances, but it has to go somewhere).
  

Cheers,
Bob

On 1/7/12 6:35 PM, Eliot Miranda wrote:
BTW, the below is not as useful as the crash.log file.





--
best,
Eliot





--
best,
Eliot

Reply | Threaded
Open this post in threaded view
|

Re: COG crashing - more info

Bob Arning-2
In reply to this post by Eliot Miranda-2
 
A little more info on these crashes:

I had 4 crashes in the space of about 5 days, then none for 10 days, then another today. This time I happened to be looking at Squeak and noticed that it was no longer writing simulation results to the screen. It was sluggish to come to the front and would not accept CMD-period. Looking in Activity Monitor, real memory usage had gone from the normal 210M to 510M and it seemed to spend about 10 minutes in gargage collection before crashing completely. Any thoughts on how to debug this?

Cheers,
Bob


===this is what Activity Monitor saw happening (repeated as often as I chose to sample it)===
Sampling process 306 for 3 seconds with 1 millisecond of run time between samples
Sampling completed, processing symbols...
Analysis of sampling Croquet (pid 306) every 1 millisecond
Call graph:
    2506 Thread_2507
      2506 start
        2506 main
          2506 RunApplicationEventLoopWithSqueak
            2506 RunApplicationEventLoop
              2506 ToolboxEventDispatcher
                2506 SendEventToEventTarget
                  2506 SendEventToEventTargetInternal(OpaqueEventRef*, OpaqueEventTargetRef*, HandlerCallRec*)
                    2506 DispatchEventToHandlers(EventTargetRec*, OpaqueEventRef*, HandlerCallRec*)
                      2506 ToolboxEventDispatcherHandler(OpaqueEventHandlerCallRef*, OpaqueEventRef*, void*)
                        2506 SendEventToEventTargetWithOptions
                          2506 SendEventToEventTargetInternal(OpaqueEventRef*, OpaqueEventTargetRef*, HandlerCallRec*)
                            2506 DispatchEventToHandlers(EventTargetRec*, OpaqueEventRef*, HandlerCallRec*)
                              2506 EventLoopEventHandler
                                2506 initStackPagesAndInterpret
                                  2506 0x906dd9c
                                    2506 ceSendFromInLineCacheMiss
                                      2505 executeNewMethod
                                        2505 primitiveNew
                                          2505 sufficientSpaceAfterGC
                                            2497 fullGC
                                              1260 markPhase
                                                688 markAndTraceStackPage
                                                  688 markAndTrace
                                                    688 markAndTrace
                                                566 markAndTraceInterpreterOops
                                                  566 markAndTraceStackPage
                                                    566 markAndTrace
                                                      566 markAndTrace
                                                6 markAndTraceObjectsOrFreeMachineCode
                                                  6 markAndTraceOrFreeCogMethodfirstVisit
                                                    5 markLiteralsAndUnlinkIfUnmarkedSendpcmethod
                                                      3 markAndTraceOrFreeCogMethodfirstVisit
                                                        3 markLiteralsAndUnlinkIfUnmarkedSendpcmethod
                                                          3 markAndTraceOrFreeCogMethodfirstVisit
                                                            2 markLiteralsAndUnlinkIfUnmarkedSendpcmethod
                                                              2 markAndTraceOrFreeCogMethodfirstVisit
                                                                2 markLiteralsAndUnlinkIfUnmarkedSendpcmethod
                                                                  1 markAndTraceOrFreeCogMethodfirstVisit
                                                                    1 markAndTraceOrFreeCogMethodfirstVisit
                                                                  1 markLiteralsAndUnlinkIfUnmarkedSendpcmethod
                                                            1 startOfMemory
                                                              1 startOfMemory
                                                      2 markAndTrace
                                                        2 markAndTrace
                                                    1 markAndTraceOrFreeCogMethodfirstVisit
                                              800 incCompBody
                                                800 mapPointersInObjectsFromto
                                                  797 mapPointersInObjectsFromto
                                                  3 mapObjectReferencesInMachineCode
                                                    2 remap
                                                      2 remap
                                                    1 remapIfObjectRefpchasYoung
                                                      1 remap
                                                        1 remap
                                              432 fullGC
                                              5 finalizeReference
                                                5 finalizeReference
                                            8 incrementalGC
                                              8 markPhase
                                                8 markAndTraceInterpreterOops
                                                  7 markAndTraceStackPage
                                                    6 markAndTrace
                                                      6 markAndTrace
                                                    1 markAndTraceStackPage
                                                  1 markAndTraceInterpreterOops
                                      1 activateCoggedNewMethod
                                        1 checkForEventsMayContextSwitch
                                          1 ioProcessEvents
                                            1 ReceiveNextEvent
                                              1 ReceiveNextEventCommon
                                                1 RunCurrentEventLoopInMode
                                                  1 CFStringCompare
                                                    1 CFStringCompareWithOptions
                                                      1 CFStringCompareWithOptionsAndLocale
                                                        1 CFUniCharGetBitmapPtrForPlane
                                                          1 CFUniCharGetBitmapPtrForPlane
    2506 Thread_2603
      2506 thread_start
        2506 _pthread_start
          2506 beatStateMachine
            2487 __semwait_signal
              2487 __semwait_signal
            11 nanosleep$UNIX2003
              10 clock_get_time
                10 mach_msg
                  10 mach_msg_trap
                    10 mach_msg_trap
              1 nanosleep$UNIX2003
            2 __udivdi3
              2 __udivdi3
            2 beatStateMachine
            2 cerror
              2 __error
                2 __error
            1 __error
              1 __error
            1 forceInterruptCheckFromHeartbeat
              1 forceInterruptCheckFromHeartbeat

Total number in stack (recursive counted multiple, when >=5):
        5       markAndTraceOrFreeCogMethodfirstVisit

Sort by top of stack, same collapsed (when >= 5):
        __semwait_signal        2487
        markAndTrace        1262
        mapPointersInObjectsFromto        797
        fullGC        432
        mach_msg_trap        10
        finalizeReference        5
Sample analysis of process 306 written to file /dev/stdout

===this appeared on the console===
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: out of memory
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: C stack backtrace:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: Smalltalk stack dump:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: 0xbffa8308 M Delay class>handleTimerEvent 157474072: a(n) Delay class
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: 0xbffa8320 M Delay
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]:  class>runTimerEventLoop 157474072: a(n) Delay class
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]:  0x9851140 s [] in Delay class>startTimerEventLoop
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]:  0x9851260 s [] in BlockClosure>newProcess
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: Most recent primitives
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: basicNew
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: primSignal:atMilliseconds:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: basicNew
...repeated items snipped...
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: primSignal:atMilliseconds:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: primSignal:atMilliseconds:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: primSignal:atMilliseconds:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: primSignal:atMilliseconds:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: primSignal:atMilliseconds:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]:     (out of memory)
Jan 19 07:37:19 robert-arnings-computer com.apple.launchd[102] ([0x0-0x25025].com.squeak.Cog[306]): Exited abnormally: Abort trap
 
On 1/9/12 4:05 PM, Eliot Miranda wrote:
 




On Sun, Jan 8, 2012 at 4:20 PM, Bob Arning <[hidden email]> wrote:
 
Does it take something special to generate one of those? The most recent I have is 18 months old.

They're produced either when the VM experiences a fatal error (either an internal call of error or catching a fatal signal etc).  How up-to-date is your VM?  That you're seeing messages on the console and not getting a crash.dmp might indicate your VM is old.


Cheers,
Bob

On 1/8/12 6:34 PM, Eliot Miranda wrote:
 




On Sat, Jan 7, 2012 at 6:22 PM, Bob Arning <[hidden email]> wrote:
 
Is that the first file I posted, or yet another file? I looked and did not see anything specifically named "crash.log"

Oops.  It is called crash.dmp and is produced in whatever the image file's directory is (a poor choice in certain circumstances, but it has to go somewhere).
  

Cheers,
Bob

On 1/7/12 6:35 PM, Eliot Miranda wrote:
BTW, the below is not as useful as the crash.log file.





--
best,
Eliot





--
best,
Eliot

Reply | Threaded
Open this post in threaded view
|

Re: COG crashing - more info

Bob Arning-2
 
A little more info...

Smalltalk installLowSpaceWatcher; useUpMemory == crashes VM

Smalltalk installLowSpaceWatcher; useUpMemoryWithArrays == produces low space debugger

Smalltalk installLowSpaceWatcher; useUpMemoryWithContexts == crashes VM

Smalltalk installLowSpaceWatcher; useUpMemoryWithTinyObjects == produces low space debugger

Cheers,
Bob

On 1/19/12 8:12 AM, Bob Arning wrote:
A little more info on these crashes:

I had 4 crashes in the space of about 5 days, then none for 10 days, then another today. This time I happened to be looking at Squeak and noticed that it was no longer writing simulation results to the screen. It was sluggish to come to the front and would not accept CMD-period. Looking in Activity Monitor, real memory usage had gone from the normal 210M to 510M and it seemed to spend about 10 minutes in gargage collection before crashing completely. Any thoughts on how to debug this?

Cheers,
Bob


===this is what Activity Monitor saw happening (repeated as often as I chose to sample it)===
Sampling process 306 for 3 seconds with 1 millisecond of run time between samples
Sampling completed, processing symbols...
Analysis of sampling Croquet (pid 306) every 1 millisecond
Call graph:
    2506 Thread_2507
      2506 start
        2506 main
          2506 RunApplicationEventLoopWithSqueak
            2506 RunApplicationEventLoop
              2506 ToolboxEventDispatcher
                2506 SendEventToEventTarget
                  2506 SendEventToEventTargetInternal(OpaqueEventRef*, OpaqueEventTargetRef*, HandlerCallRec*)
                    2506 DispatchEventToHandlers(EventTargetRec*, OpaqueEventRef*, HandlerCallRec*)
                      2506 ToolboxEventDispatcherHandler(OpaqueEventHandlerCallRef*, OpaqueEventRef*, void*)
                        2506 SendEventToEventTargetWithOptions
                          2506 SendEventToEventTargetInternal(OpaqueEventRef*, OpaqueEventTargetRef*, HandlerCallRec*)
                            2506 DispatchEventToHandlers(EventTargetRec*, OpaqueEventRef*, HandlerCallRec*)
                              2506 EventLoopEventHandler
                                2506 initStackPagesAndInterpret
                                  2506 0x906dd9c
                                    2506 ceSendFromInLineCacheMiss
                                      2505 executeNewMethod
                                        2505 primitiveNew
                                          2505 sufficientSpaceAfterGC
                                            2497 fullGC
                                              1260 markPhase
                                                688 markAndTraceStackPage
                                                  688 markAndTrace
                                                    688 markAndTrace
                                                566 markAndTraceInterpreterOops
                                                  566 markAndTraceStackPage
                                                    566 markAndTrace
                                                      566 markAndTrace
                                                6 markAndTraceObjectsOrFreeMachineCode
                                                  6 markAndTraceOrFreeCogMethodfirstVisit
                                                    5 markLiteralsAndUnlinkIfUnmarkedSendpcmethod
                                                      3 markAndTraceOrFreeCogMethodfirstVisit
                                                        3 markLiteralsAndUnlinkIfUnmarkedSendpcmethod
                                                          3 markAndTraceOrFreeCogMethodfirstVisit
                                                            2 markLiteralsAndUnlinkIfUnmarkedSendpcmethod
                                                              2 markAndTraceOrFreeCogMethodfirstVisit
                                                                2 markLiteralsAndUnlinkIfUnmarkedSendpcmethod
                                                                  1 markAndTraceOrFreeCogMethodfirstVisit
                                                                    1 markAndTraceOrFreeCogMethodfirstVisit
                                                                  1 markLiteralsAndUnlinkIfUnmarkedSendpcmethod
                                                            1 startOfMemory
                                                              1 startOfMemory
                                                      2 markAndTrace
                                                        2 markAndTrace
                                                    1 markAndTraceOrFreeCogMethodfirstVisit
                                              800 incCompBody
                                                800 mapPointersInObjectsFromto
                                                  797 mapPointersInObjectsFromto
                                                  3 mapObjectReferencesInMachineCode
                                                    2 remap
                                                      2 remap
                                                    1 remapIfObjectRefpchasYoung
                                                      1 remap
                                                        1 remap
                                              432 fullGC
                                              5 finalizeReference
                                                5 finalizeReference
                                            8 incrementalGC
                                              8 markPhase
                                                8 markAndTraceInterpreterOops
                                                  7 markAndTraceStackPage
                                                    6 markAndTrace
                                                      6 markAndTrace
                                                    1 markAndTraceStackPage
                                                  1 markAndTraceInterpreterOops
                                      1 activateCoggedNewMethod
                                        1 checkForEventsMayContextSwitch
                                          1 ioProcessEvents
                                            1 ReceiveNextEvent
                                              1 ReceiveNextEventCommon
                                                1 RunCurrentEventLoopInMode
                                                  1 CFStringCompare
                                                    1 CFStringCompareWithOptions
                                                      1 CFStringCompareWithOptionsAndLocale
                                                        1 CFUniCharGetBitmapPtrForPlane
                                                          1 CFUniCharGetBitmapPtrForPlane
    2506 Thread_2603
      2506 thread_start
        2506 _pthread_start
          2506 beatStateMachine
            2487 __semwait_signal
              2487 __semwait_signal
            11 nanosleep$UNIX2003
              10 clock_get_time
                10 mach_msg
                  10 mach_msg_trap
                    10 mach_msg_trap
              1 nanosleep$UNIX2003
            2 __udivdi3
              2 __udivdi3
            2 beatStateMachine
            2 cerror
              2 __error
                2 __error
            1 __error
              1 __error
            1 forceInterruptCheckFromHeartbeat
              1 forceInterruptCheckFromHeartbeat

Total number in stack (recursive counted multiple, when >=5):
        5       markAndTraceOrFreeCogMethodfirstVisit

Sort by top of stack, same collapsed (when >= 5):
        __semwait_signal        2487
        markAndTrace        1262
        mapPointersInObjectsFromto        797
        fullGC        432
        mach_msg_trap        10
        finalizeReference        5
Sample analysis of process 306 written to file /dev/stdout

===this appeared on the console===
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: out of memory
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: C stack backtrace:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: Smalltalk stack dump:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: 0xbffa8308 M Delay class>handleTimerEvent 157474072: a(n) Delay class
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: 0xbffa8320 M Delay
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]:  class>runTimerEventLoop 157474072: a(n) Delay class
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]:  0x9851140 s [] in Delay class>startTimerEventLoop
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]:  0x9851260 s [] in BlockClosure>newProcess
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: Most recent primitives
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: basicNew
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: primSignal:atMilliseconds:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: basicNew
...repeated items snipped...
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: primSignal:atMilliseconds:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: primSignal:atMilliseconds:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: primSignal:atMilliseconds:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: primSignal:atMilliseconds:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: primSignal:atMilliseconds:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]:     (out of memory)
Jan 19 07:37:19 robert-arnings-computer com.apple.launchd[102] ([0x0-0x25025].com.squeak.Cog[306]): Exited abnormally: Abort trap
 
On 1/9/12 4:05 PM, Eliot Miranda wrote:
 




On Sun, Jan 8, 2012 at 4:20 PM, Bob Arning <[hidden email]> wrote:
 
Does it take something special to generate one of those? The most recent I have is 18 months old.

They're produced either when the VM experiences a fatal error (either an internal call of error or catching a fatal signal etc).  How up-to-date is your VM?  That you're seeing messages on the console and not getting a crash.dmp might indicate your VM is old.


Cheers,
Bob

On 1/8/12 6:34 PM, Eliot Miranda wrote:
 




On Sat, Jan 7, 2012 at 6:22 PM, Bob Arning <[hidden email]> wrote:
 
Is that the first file I posted, or yet another file? I looked and did not see anything specifically named "crash.log"

Oops.  It is called crash.dmp and is produced in whatever the image file's directory is (a poor choice in certain circumstances, but it has to go somewhere).
  

Cheers,
Bob

On 1/7/12 6:35 PM, Eliot Miranda wrote:
BTW, the below is not as useful as the crash.log file.





--
best,
Eliot





--
best,
Eliot

Reply | Threaded
Open this post in threaded view
|

Re: COG crashing - more info

Eliot Miranda-2
In reply to this post by Bob Arning-2
 
Bob,

    the error clearly says

Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: out of memory

So you have an infinite recursion somewhere.  If you have advance warning (e.g. the system starts to get sluggish) you can use kill -USR1 to get a dump of all processes.  Alternatively you can run the VM under gdb, put a halt in error, and do

gdb> call printAllStacks()

when the error is reached.  You can use pushOutputFile to get the stack dump written to a particular file, e.g.

gdb> call pushOutputFile("stacktraces.txt")
gdb> call printAllStacks()
gdb> call popOutputFile()

HTH
Eliot

On Thu, Jan 19, 2012 at 5:12 AM, Bob Arning <[hidden email]> wrote:
 
A little more info on these crashes:

I had 4 crashes in the space of about 5 days, then none for 10 days, then another today. This time I happened to be looking at Squeak and noticed that it was no longer writing simulation results to the screen. It was sluggish to come to the front and would not accept CMD-period. Looking in Activity Monitor, real memory usage had gone from the normal 210M to 510M and it seemed to spend about 10 minutes in gargage collection before crashing completely. Any thoughts on how to debug this?

Cheers,
Bob


===this is what Activity Monitor saw happening (repeated as often as I chose to sample it)===
Sampling process 306 for 3 seconds with 1 millisecond of run time between samples
Sampling completed, processing symbols...
Analysis of sampling Croquet (pid 306) every 1 millisecond
Call graph:
    2506 Thread_2507
      2506 start
        2506 main
          2506 RunApplicationEventLoopWithSqueak
            2506 RunApplicationEventLoop
              2506 ToolboxEventDispatcher
                2506 SendEventToEventTarget
                  2506 SendEventToEventTargetInternal(OpaqueEventRef*, OpaqueEventTargetRef*, HandlerCallRec*)
                    2506 DispatchEventToHandlers(EventTargetRec*, OpaqueEventRef*, HandlerCallRec*)
                      2506 ToolboxEventDispatcherHandler(OpaqueEventHandlerCallRef*, OpaqueEventRef*, void*)
                        2506 SendEventToEventTargetWithOptions
                          2506 SendEventToEventTargetInternal(OpaqueEventRef*, OpaqueEventTargetRef*, HandlerCallRec*)
                            2506 DispatchEventToHandlers(EventTargetRec*, OpaqueEventRef*, HandlerCallRec*)
                              2506 EventLoopEventHandler
                                2506 initStackPagesAndInterpret
                                  2506 0x906dd9c
                                    2506 ceSendFromInLineCacheMiss
                                      2505 executeNewMethod
                                        2505 primitiveNew
                                          2505 sufficientSpaceAfterGC
                                            2497 fullGC
                                              1260 markPhase
                                                688 markAndTraceStackPage
                                                  688 markAndTrace
                                                    688 markAndTrace
                                                566 markAndTraceInterpreterOops
                                                  566 markAndTraceStackPage
                                                    566 markAndTrace
                                                      566 markAndTrace
                                                6 markAndTraceObjectsOrFreeMachineCode
                                                  6 markAndTraceOrFreeCogMethodfirstVisit
                                                    5 markLiteralsAndUnlinkIfUnmarkedSendpcmethod
                                                      3 markAndTraceOrFreeCogMethodfirstVisit
                                                        3 markLiteralsAndUnlinkIfUnmarkedSendpcmethod
                                                          3 markAndTraceOrFreeCogMethodfirstVisit
                                                            2 markLiteralsAndUnlinkIfUnmarkedSendpcmethod
                                                              2 markAndTraceOrFreeCogMethodfirstVisit
                                                                2 markLiteralsAndUnlinkIfUnmarkedSendpcmethod
                                                                  1 markAndTraceOrFreeCogMethodfirstVisit
                                                                    1 markAndTraceOrFreeCogMethodfirstVisit
                                                                  1 markLiteralsAndUnlinkIfUnmarkedSendpcmethod
                                                            1 startOfMemory
                                                              1 startOfMemory
                                                      2 markAndTrace
                                                        2 markAndTrace
                                                    1 markAndTraceOrFreeCogMethodfirstVisit
                                              800 incCompBody
                                                800 mapPointersInObjectsFromto
                                                  797 mapPointersInObjectsFromto
                                                  3 mapObjectReferencesInMachineCode
                                                    2 remap
                                                      2 remap
                                                    1 remapIfObjectRefpchasYoung
                                                      1 remap
                                                        1 remap
                                              432 fullGC
                                              5 finalizeReference
                                                5 finalizeReference
                                            8 incrementalGC
                                              8 markPhase
                                                8 markAndTraceInterpreterOops
                                                  7 markAndTraceStackPage
                                                    6 markAndTrace
                                                      6 markAndTrace
                                                    1 markAndTraceStackPage
                                                  1 markAndTraceInterpreterOops
                                      1 activateCoggedNewMethod
                                        1 checkForEventsMayContextSwitch
                                          1 ioProcessEvents
                                            1 ReceiveNextEvent
                                              1 ReceiveNextEventCommon
                                                1 RunCurrentEventLoopInMode
                                                  1 CFStringCompare
                                                    1 CFStringCompareWithOptions
                                                      1 CFStringCompareWithOptionsAndLocale
                                                        1 CFUniCharGetBitmapPtrForPlane
                                                          1 CFUniCharGetBitmapPtrForPlane
    2506 Thread_2603
      2506 thread_start
        2506 _pthread_start
          2506 beatStateMachine
            2487 __semwait_signal
              2487 __semwait_signal
            11 nanosleep$UNIX2003
              10 clock_get_time
                10 mach_msg
                  10 mach_msg_trap
                    10 mach_msg_trap
              1 nanosleep$UNIX2003
            2 __udivdi3
              2 __udivdi3
            2 beatStateMachine
            2 cerror
              2 __error
                2 __error
            1 __error
              1 __error
            1 forceInterruptCheckFromHeartbeat
              1 forceInterruptCheckFromHeartbeat

Total number in stack (recursive counted multiple, when >=5):
        5       markAndTraceOrFreeCogMethodfirstVisit

Sort by top of stack, same collapsed (when >= 5):
        __semwait_signal        2487
        markAndTrace        1262
        mapPointersInObjectsFromto        797
        fullGC        432
        mach_msg_trap        10
        finalizeReference        5
Sample analysis of process 306 written to file /dev/stdout

===this appeared on the console===
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: out of memory
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: C stack backtrace:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: Smalltalk stack dump:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: 0xbffa8308 M Delay class>handleTimerEvent 157474072: a(n) Delay class
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: 0xbffa8320 M Delay
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]:  class>runTimerEventLoop 157474072: a(n) Delay class
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]:  0x9851140 s [] in Delay class>startTimerEventLoop
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]:  0x9851260 s [] in BlockClosure>newProcess
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: Most recent primitives
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: basicNew
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: primSignal:atMilliseconds:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: basicNew
...repeated items snipped...
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: primSignal:atMilliseconds:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: primSignal:atMilliseconds:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: primSignal:atMilliseconds:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: primSignal:atMilliseconds:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: primSignal:atMilliseconds:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]:     (out of memory)
Jan 19 07:37:19 robert-arnings-computer com.apple.launchd[102] ([0x0-0x25025].com.squeak.Cog[306]): Exited abnormally: Abort trap
 
On 1/9/12 4:05 PM, Eliot Miranda wrote:
 




On Sun, Jan 8, 2012 at 4:20 PM, Bob Arning <[hidden email]> wrote:
 
Does it take something special to generate one of those? The most recent I have is 18 months old.

They're produced either when the VM experiences a fatal error (either an internal call of error or catching a fatal signal etc).  How up-to-date is your VM?  That you're seeing messages on the console and not getting a crash.dmp might indicate your VM is old.


Cheers,
Bob

On 1/8/12 6:34 PM, Eliot Miranda wrote:
 




On Sat, Jan 7, 2012 at 6:22 PM, Bob Arning <[hidden email]> wrote:
 
Is that the first file I posted, or yet another file? I looked and did not see anything specifically named "crash.log"

Oops.  It is called crash.dmp and is produced in whatever the image file's directory is (a poor choice in certain circumstances, but it has to go somewhere).
  

Cheers,
Bob

On 1/7/12 6:35 PM, Eliot Miranda wrote:
BTW, the below is not as useful as the crash.log file.





--
best,
Eliot





--
best,
Eliot





--
best,
Eliot

Reply | Threaded
Open this post in threaded view
|

Re: COG crashing - more info

Bob Arning-2
In reply to this post by Bob Arning-2
 
Still hoping to debug this in Smalltalk, I'm exploring the bounds a bit.

for
Smalltalk installLowSpaceWatcher; useUpMemory

I tried increasing the
lowSpaceThreshold in case that was the reason I was not getting a low space log. The nominal size (200k) and 300k both lead to vm crashes. Larger numbers cause the vm to spend forever in garbage collection (well, 15 minutes at least)

10M hangs at 476M real
1M hangs at 490M real
400K hangs at 491.5M real
300K ==crashes VM at 499M real (approximate)
200K ==crashes VM
--------------------------------------------------------------------------------------
repeating the experiment for
Smalltalk installLowSpaceWatcher; useUpMemoryWithContexts


had similar results although the crash-to-hang threshold was a bit higher
12M hangs at 493M real
1M ==crashesVM around 508M real
600K ==crashesVM around 507M real
400K ==crashesVM around 508M real
200K ==crashes VM


------------------------------------------------------------------------------------
The interesting thing about this test is that it get a low space debugger, but not where the code expects it. The error is signalled when allocating the String, not the Arrays.

Smalltalk installLowSpaceWatcher; useUpMemoryWithArrays == produces low space debugger

------------------------------------------------------------------------------------
So, I made a slightly different version that allocates a slightly smaller, but still huge String and then allocates Arrays of a particular size. It appears that for smaller array sizes, the vm hangs, while for larger sizes, it will signal low space.

Smalltalk installLowSpaceWatcher; useUpMemoryWithArrays: 100 -- hangs at 509M
Smalltalk installLowSpaceWatcher; useUpMemoryWithArrays: 300 -- hangs at 509M
Smalltalk installLowSpaceWatcher; useUpMemoryWithArrays: 500 -- hangs at 515M
Smalltalk installLowSpaceWatcher; useUpMemoryWithArrays: 700 -- low space at 509M
Smalltalk installLowSpaceWatcher; useUpMemoryWithArrays: 1000 -- low space at 509M

============
useUpMemoryWithArrays: arraySize

    | b bytesLeft lsp req count |
   
    req _ (bytesLeft _ self bytesLeft) - (lsp _ self lowSpaceThreshold) - (20*1024*1024).
    count _ 50000000 // arraySize // 4.
    Transcript
        show: bytesLeft asStringWithCommas,' bytesLeft(before)'; cr;
        show: lsp asStringWithCommas,' lowSpaceThreshold'; cr;
        show: req asStringWithCommas,' string size requested'; cr;
        show: count asStringWithCommas,' Arrays to be requested'; cr;
        show: arraySize asStringWithCommas,' Array size requested'; cr;
        yourself.
    self confirm: 'ready to allocate the string'.
    b := String new: req.
    self confirm: 'we got the string ',b size asStringWithCommas.
   
    (1 to: count) collect: [:i | Array new: arraySize].
   
    self confirm: 'oops! no problems detected'.
=============

--------------------------------------------------------------------------------------

On 1/19/12 10:26 AM, Bob Arning wrote:
A little more info...

Smalltalk installLowSpaceWatcher; useUpMemory == crashes VM

Smalltalk installLowSpaceWatcher; useUpMemoryWithArrays == produces low space debugger

Smalltalk installLowSpaceWatcher; useUpMemoryWithContexts == crashes VM

Smalltalk installLowSpaceWatcher; useUpMemoryWithTinyObjects == produces low space debugger

Cheers,
Bob

On 1/19/12 8:12 AM, Bob Arning wrote:
A little more info on these crashes:

I had 4 crashes in the space of about 5 days, then none for 10 days, then another today. This time I happened to be looking at Squeak and noticed that it was no longer writing simulation results to the screen. It was sluggish to come to the front and would not accept CMD-period. Looking in Activity Monitor, real memory usage had gone from the normal 210M to 510M and it seemed to spend about 10 minutes in gargage collection before crashing completely. Any thoughts on how to debug this?

Cheers,
Bob


===this is what Activity Monitor saw happening (repeated as often as I chose to sample it)===
Sampling process 306 for 3 seconds with 1 millisecond of run time between samples
Sampling completed, processing symbols...
Analysis of sampling Croquet (pid 306) every 1 millisecond
Call graph:
    2506 Thread_2507
      2506 start
        2506 main
          2506 RunApplicationEventLoopWithSqueak
            2506 RunApplicationEventLoop
              2506 ToolboxEventDispatcher
                2506 SendEventToEventTarget
                  2506 SendEventToEventTargetInternal(OpaqueEventRef*, OpaqueEventTargetRef*, HandlerCallRec*)
                    2506 DispatchEventToHandlers(EventTargetRec*, OpaqueEventRef*, HandlerCallRec*)
                      2506 ToolboxEventDispatcherHandler(OpaqueEventHandlerCallRef*, OpaqueEventRef*, void*)
                        2506 SendEventToEventTargetWithOptions
                          2506 SendEventToEventTargetInternal(OpaqueEventRef*, OpaqueEventTargetRef*, HandlerCallRec*)
                            2506 DispatchEventToHandlers(EventTargetRec*, OpaqueEventRef*, HandlerCallRec*)
                              2506 EventLoopEventHandler
                                2506 initStackPagesAndInterpret
                                  2506 0x906dd9c
                                    2506 ceSendFromInLineCacheMiss
                                      2505 executeNewMethod
                                        2505 primitiveNew
                                          2505 sufficientSpaceAfterGC
                                            2497 fullGC
                                              1260 markPhase
                                                688 markAndTraceStackPage
                                                  688 markAndTrace
                                                    688 markAndTrace
                                                566 markAndTraceInterpreterOops
                                                  566 markAndTraceStackPage
                                                    566 markAndTrace
                                                      566 markAndTrace
                                                6 markAndTraceObjectsOrFreeMachineCode
                                                  6 markAndTraceOrFreeCogMethodfirstVisit
                                                    5 markLiteralsAndUnlinkIfUnmarkedSendpcmethod
                                                      3 markAndTraceOrFreeCogMethodfirstVisit
                                                        3 markLiteralsAndUnlinkIfUnmarkedSendpcmethod
                                                          3 markAndTraceOrFreeCogMethodfirstVisit
                                                            2 markLiteralsAndUnlinkIfUnmarkedSendpcmethod
                                                              2 markAndTraceOrFreeCogMethodfirstVisit
                                                                2 markLiteralsAndUnlinkIfUnmarkedSendpcmethod
                                                                  1 markAndTraceOrFreeCogMethodfirstVisit
                                                                    1 markAndTraceOrFreeCogMethodfirstVisit
                                                                  1 markLiteralsAndUnlinkIfUnmarkedSendpcmethod
                                                            1 startOfMemory
                                                              1 startOfMemory
                                                      2 markAndTrace
                                                        2 markAndTrace
                                                    1 markAndTraceOrFreeCogMethodfirstVisit
                                              800 incCompBody
                                                800 mapPointersInObjectsFromto
                                                  797 mapPointersInObjectsFromto
                                                  3 mapObjectReferencesInMachineCode
                                                    2 remap
                                                      2 remap
                                                    1 remapIfObjectRefpchasYoung
                                                      1 remap
                                                        1 remap
                                              432 fullGC
                                              5 finalizeReference
                                                5 finalizeReference
                                            8 incrementalGC
                                              8 markPhase
                                                8 markAndTraceInterpreterOops
                                                  7 markAndTraceStackPage
                                                    6 markAndTrace
                                                      6 markAndTrace
                                                    1 markAndTraceStackPage
                                                  1 markAndTraceInterpreterOops
                                      1 activateCoggedNewMethod
                                        1 checkForEventsMayContextSwitch
                                          1 ioProcessEvents
                                            1 ReceiveNextEvent
                                              1 ReceiveNextEventCommon
                                                1 RunCurrentEventLoopInMode
                                                  1 CFStringCompare
                                                    1 CFStringCompareWithOptions
                                                      1 CFStringCompareWithOptionsAndLocale
                                                        1 CFUniCharGetBitmapPtrForPlane
                                                          1 CFUniCharGetBitmapPtrForPlane
    2506 Thread_2603
      2506 thread_start
        2506 _pthread_start
          2506 beatStateMachine
            2487 __semwait_signal
              2487 __semwait_signal
            11 nanosleep$UNIX2003
              10 clock_get_time
                10 mach_msg
                  10 mach_msg_trap
                    10 mach_msg_trap
              1 nanosleep$UNIX2003
            2 __udivdi3
              2 __udivdi3
            2 beatStateMachine
            2 cerror
              2 __error
                2 __error
            1 __error
              1 __error
            1 forceInterruptCheckFromHeartbeat
              1 forceInterruptCheckFromHeartbeat

Total number in stack (recursive counted multiple, when >=5):
        5       markAndTraceOrFreeCogMethodfirstVisit

Sort by top of stack, same collapsed (when >= 5):
        __semwait_signal        2487
        markAndTrace        1262
        mapPointersInObjectsFromto        797
        fullGC        432
        mach_msg_trap        10
        finalizeReference        5
Sample analysis of process 306 written to file /dev/stdout

===this appeared on the console===
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: out of memory
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: C stack backtrace:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: Smalltalk stack dump:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: 0xbffa8308 M Delay class>handleTimerEvent 157474072: a(n) Delay class
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: 0xbffa8320 M Delay
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]:  class>runTimerEventLoop 157474072: a(n) Delay class
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]:  0x9851140 s [] in Delay class>startTimerEventLoop
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]:  0x9851260 s [] in BlockClosure>newProcess
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: Most recent primitives
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: basicNew
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: primSignal:atMilliseconds:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: basicNew
...repeated items snipped...
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: primSignal:atMilliseconds:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: primSignal:atMilliseconds:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: primSignal:atMilliseconds:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: primSignal:atMilliseconds:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: primSignal:atMilliseconds:
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: millisecondClockValue
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]: wait
Jan 19 07:37:15 robert-arnings-computer [0x0-0x25025].com.squeak.Cog[306]:     (out of memory)
Jan 19 07:37:19 robert-arnings-computer com.apple.launchd[102] ([0x0-0x25025].com.squeak.Cog[306]): Exited abnormally: Abort trap
 
On 1/9/12 4:05 PM, Eliot Miranda wrote:
 




On Sun, Jan 8, 2012 at 4:20 PM, Bob Arning <[hidden email]> wrote:
 
Does it take something special to generate one of those? The most recent I have is 18 months old.

They're produced either when the VM experiences a fatal error (either an internal call of error or catching a fatal signal etc).  How up-to-date is your VM?  That you're seeing messages on the console and not getting a crash.dmp might indicate your VM is old.


Cheers,
Bob

On 1/8/12 6:34 PM, Eliot Miranda wrote:
 




On Sat, Jan 7, 2012 at 6:22 PM, Bob Arning <[hidden email]> wrote:
 
Is that the first file I posted, or yet another file? I looked and did not see anything specifically named "crash.log"

Oops.  It is called crash.dmp and is produced in whatever the image file's directory is (a poor choice in certain circumstances, but it has to go somewhere).
  

Cheers,
Bob

On 1/7/12 6:35 PM, Eliot Miranda wrote:
BTW, the below is not as useful as the crash.log file.





--
best,
Eliot





--
best,
Eliot