Ticket #205: race-condition

File race-condition, 22.0 KB (added by mickey, 3 years ago)
Line 
12009.01.15 00:12:30 ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/4>: got 43 bytes from:
2'\r\n%CPI: 1,0,0,0,1,0,"06968098690",129,,,0\r\n'                                                                                               
32009.01.15 00:12:30 ogsmd.device INFO     org.freesmartphone.GSM.Call.CallStatus: 1 incoming {'status':
4'incoming', 'peer': '06968098690', 'line': 0, 'direction': 'incoming'}                                                                             
52009.01.15 00:12:30 oeventsd INFO     Receive CallStatus, status = incoming                                                   
62009.01.15 00:12:30 oeventsd DEBUG    trigger CallStatus                                                                     
72009.01.15 00:12:30 oeventsd DEBUG    Trigger CallListContains(incoming)                                                     
82009.01.15 00:12:30 oeventsd DEBUG    trigger CallListContains(incoming)                                                     
92009.01.15 00:12:30 oeventsd DEBUG    trigger While CallListContains(incoming) if ~(CallListContains(active))
10then [RingToneAction(), SetBrightness((90,)), unamed action]                                                                                 
112009.01.15 00:12:30 oeventsd INFO     RingToneAction play                                                                     
122009.01.15 00:12:30 oeventsd INFO     call dbus signal /org/freesmartphone/Device/Display/0
13SetBrightness((90,))             
142009.01.15 00:12:30 oeventsd INFO     call dbus signal /org/freesmartphone/Usage RequestResource(('CPU',))                   
152009.01.15 00:12:30 oeventsd INFO     Receive CallStatus, status = incoming                                                   
162009.01.15 00:12:30 oeventsd DEBUG    trigger CallStatus                                                                     
172009.01.15 00:12:31 odeviced.helpers DEBUG    (writing '230' to '/sys/class/backlight/gta02-bl/brightness')                   
182009.01.15 00:12:31 odeviced.kernel26 DEBUG    issuing ioctl( FBIOBLANK, FB_BLANK_UNBLANK )                                   
192009.01.15 00:12:31 ogsmd.channel DEBUG    <MiscChannel via /dev/pts/2>: _readyToRead: watch timeout = None                   
202009.01.15 00:12:31 ogsmd.channel DEBUG    <MiscChannel via /dev/pts/2>: got 17 bytes from: '\r\n+CRING:
21VOICE\r\n'           
222009.01.15 00:12:31 ogsmd.modems.abstract.channel WARNING  UNHANDLED INTERMEDIATE: +CRING: VOICE                             
232009.01.15 00:12:31 ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/4>: _readyToRead: watch
24timeout = None   
252009.01.15 00:12:31 ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/4>: got 136 bytes from:
26'\r\n%CPI: 1,0,0,1,1,0,"06968098690",129,,,0\r\n\r\n+CRING: VOICE\r\n\r\n+CLIP:
27"06968098690",129,,,,0\r\n\r\n%CPI: 1,4,0,1,1,0,"06968098690",129,,,0\r\n'                                                                                                                   
282009.01.15 00:12:31 ogsmd.device INFO     org.freesmartphone.GSM.Call.CallStatus: 1 incoming {'status':
29'incoming', 'direction': 'incoming', 'peer': '06968098690', 'line': 0}                                                                             
302009.01.15 00:12:31 ogsmd    DEBUG    +CRING, but call status already ok: ignoring                                           
312009.01.15 00:12:31 ogsmd.channel DEBUG    <CallChannel via /dev/pts/5>: _readyToRead: watch timeout = None                   
322009.01.15 00:12:31 ogsmd.channel DEBUG    <CallChannel via /dev/pts/5>: got 17 bytes from: '\r\n+CRING:
33VOICE\r\n'           
342009.01.15 00:12:31 ogsmd    WARNING  CALLCHANNEL: UNHANDLED INTERMEDIATE: +CRING: VOICE                                     
352009.01.15 00:12:31 opreferencesd INFO     GetService phone                                                                   
362009.01.15 00:12:32 ogsmd.channel DEBUG    <MiscChannel via /dev/pts/2>: _readyToSend: watch timeout = None                   
372009.01.15 00:12:32 ogsmd.channel DEBUG    <MiscChannel via /dev/pts/2>: sending 10 bytes: 'AT%N0187\r\n'                     
382009.01.15 00:12:32 oeventsd INFO     signal SetBrightness responded : ()                                                     
392009.01.15 00:12:32 ousaged  DEBUG    Enabling CPU                                                                           
402009.01.15 00:12:32 ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/4>: _readyToRead: watch
41timeout = None   
422009.01.15 00:12:32 ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/4>: got 70 bytes from:
43'\r\n%CPI: 1,1,0,1,1,0,"06968098690",129,,16,0\r\n\r\n%CPI: 1,7,0,0,,,,,,,0\r\n'                                                                 
442009.01.15 00:12:32 ogsmd.device INFO     org.freesmartphone.GSM.Call.CallStatus: 1 release {'status':
45'release'}             
462009.01.15 00:12:33 oeventsd INFO     Receive CallStatus, status = incoming                                                   
472009.01.15 00:12:33 oeventsd DEBUG    trigger CallStatus                                                                     
482009.01.15 00:12:33 oeventsd DEBUG    Trigger CallListContains(incoming)                                                     
492009.01.15 00:12:33 oeventsd INFO     Receive CallStatus, status = incoming                                                   
502009.01.15 00:12:33 oeventsd DEBUG    trigger CallStatus                                                                     
512009.01.15 00:12:33 ogsmd.channel DEBUG    <MiscChannel via /dev/pts/2>: _readyToRead: watch timeout = 342                   
522009.01.15 00:12:33 ogsmd.channel DEBUG    <MiscChannel via /dev/pts/2>: got 57 bytes from: '\r\nEXT: I\r\nBoth
53AEC and Noise reduction are active\r\n\r\nOK\r\n'                                                                                           
542009.01.15 00:12:33 ogsmd.channel DEBUG    <MiscChannel via /dev/pts/2>: COMPLETED 'AT%N0187' => ['EXT: I',
55'Both AEC and Noise reduction are active', 'OK']                                                                                               
562009.01.15 00:12:33 frameworkd.resource INFO     setting resource status for CPU from disabled to enabling                   
572009.01.15 00:12:33 odeviced.idlenotifier INFO     odeviced.idlenotifier state change to lock                                 
582009.01.15 00:12:33 odeviced.idlenotifier DEBUG    Timeout for suspend disabled, not falling into this state
59next.           
602009.01.15 00:12:33 frameworkd.resource INFO     setting resource status for CPU from enabling to enabled                     
612009.01.15 00:12:33 ogsmd.channel DEBUG    <MiscChannel via /dev/pts/2>: _readyToSend: watch timeout = None                   
622009.01.15 00:12:33 oeventsd INFO     Receive CallStatus, status = release                                                   
632009.01.15 00:12:33 oeventsd DEBUG    trigger CallStatus                                                                     
642009.01.15 00:12:33 oeventsd DEBUG    Trigger CallListContains(incoming)                                                     
652009.01.15 00:12:33 oeventsd DEBUG    untrigger CallListContains(incoming)                                                   
662009.01.15 00:12:33 oeventsd DEBUG    untrigger While CallListContains(incoming) if ~(CallListContains(active))
67then [RingToneAction(), SetBrightness((90,)), unamed action]                                                                               
682009.01.15 00:12:33 oeventsd INFO     RingToneAction stop                                                                     
692009.01.15 00:12:33 oeventsd INFO     call dbus signal /org/freesmartphone/Usage ReleaseResource(('CPU',))                   
702009.01.15 00:12:33 oeventsd INFO     Receive CallStatus, status = release                                                   
712009.01.15 00:12:33 oeventsd DEBUG    trigger CallStatus                                                                     
722009.01.15 00:12:33 oeventsd DEBUG    trigger DBusTrigger(org.freesmartphone.odeviced None.State)                             
732009.01.15 00:12:33 oeventsd DEBUG    trigger on DBusTrigger(org.freesmartphone.odeviced None.State) if And()
74then Debug("dbus trigger test")                                                                                                               
752009.01.15 00:12:33 oeventsd INFO     DebugAction : dbus trigger test                                                         
762009.01.15 00:12:33 oeventsd INFO     Receive IdleState, status = lock                                                       
772009.01.15 00:12:33 oeventsd DEBUG    trigger IdleState                                                                       
782009.01.15 00:12:33 oeventsd INFO     Receive IdleState, status = lock                                                       
792009.01.15 00:12:33 oeventsd DEBUG    trigger IdleState                                                                       
802009.01.15 00:12:33 oeventsd INFO     Receive IdleState, status = lock                                                       
812009.01.15 00:12:33 oeventsd DEBUG    trigger IdleState                                                                       
822009.01.15 00:12:33 ousaged  INFO     Enabled CPU in 0.8 seconds                                                             
832009.01.15 00:12:33 oeventsd INFO     signal RequestResource responded : ()                                                   
842009.01.15 00:12:33 opreferencesd DEBUG    Service phone : Getting key ring-tone                                             
852009.01.15 00:12:34 ousaged  DEBUG    Disabling CPU                                                                           
862009.01.15 00:12:34 frameworkd.resource INFO     setting resource status for CPU from enabled to disabling                   
872009.01.15 00:12:34 odeviced.idlenotifier DEBUG    setting none timeout to -1                                                 
882009.01.15 00:12:34 odeviced.idlenotifier DEBUG    setting suspend timeout to 0                                               
892009.01.15 00:12:34 odeviced.idlenotifier DEBUG    setting lock timeout to 2                                                 
902009.01.15 00:12:34 odeviced.idlenotifier DEBUG    setting idle_prelock timeout to 12                                         
912009.01.15 00:12:34 odeviced.idlenotifier DEBUG    setting idle timeout to 10                                                 
922009.01.15 00:12:34 odeviced.idlenotifier DEBUG    setting busy timeout to -1                                                 
932009.01.15 00:12:34 odeviced.idlenotifier DEBUG    setting awake timeout to -1                                               
942009.01.15 00:12:34 odeviced.idlenotifier DEBUG    setting idle_dim timeout to 20                                             
952009.01.15 00:12:34 odeviced.idlenotifier INFO     odeviced.idlenotifier state change to lock                                 
962009.01.15 00:12:34 odeviced.idlenotifier DEBUG    Timeout for suspend disabled, not falling into this state
97next.           
982009.01.15 00:12:34 frameworkd.resource INFO     setting resource status for CPU from disabling to disabled                   
992009.01.15 00:12:34 opreferencesd DEBUG    Service phone : Getting key ring-volume                                           
1002009.01.15 00:12:34 oeventsd DEBUG    trigger DBusTrigger(org.freesmartphone.odeviced None.State)                             
1012009.01.15 00:12:34 oeventsd DEBUG    trigger on DBusTrigger(org.freesmartphone.odeviced None.State) if And()
102then Debug("dbus trigger test")                                                                                                               
1032009.01.15 00:12:34 oeventsd INFO     DebugAction : dbus trigger test                                                         
1042009.01.15 00:12:34 oeventsd INFO     Receive IdleState, status = lock                                                       
1052009.01.15 00:12:34 oeventsd DEBUG    trigger IdleState                                                                       
1062009.01.15 00:12:34 oeventsd INFO     Receive IdleState, status = lock                                                       
1072009.01.15 00:12:34 oeventsd DEBUG    trigger IdleState                                                                       
1082009.01.15 00:12:34 oeventsd INFO     Receive IdleState, status = lock                                                       
1092009.01.15 00:12:34 oeventsd DEBUG    trigger IdleState                                                                       
1102009.01.15 00:12:34 ousaged  INFO     Disabled CPU in 0.5 seconds                                                             
1112009.01.15 00:12:34 oeventsd INFO     signal ReleaseResource responded : ()                                                   
1122009.01.15 00:12:34 opreferencesd DEBUG    Service phone : Getting key ring-loop                                             
1132009.01.15 00:12:34 opreferencesd INFO     Service phone : can't find key ring-loop, using default                           
1142009.01.15 00:12:34 opreferencesd DEBUG    Service phone : Getting key ring-length                                           
1152009.01.15 00:12:34 oeventsd INFO     Start ringing : tone=Arkanoid_PSID.sid, volume=10, loop=0, length=60                   
1162009.01.15 00:12:35 oeventsd INFO     call dbus signal /org/freesmartphone/Device/Audio
117PlaySound((u'/local/pkg/fso/share/sounds/Arkanoid_PSID.sid', dbus.Int32(0, variant_level=1), dbus.Int32(60,
118variant_level=1)))                                     
1192009.01.15 00:12:35 oeventsd INFO     call dbus signal /org/freesmartphone/Device/LED/neo1973_vibrator
120SetBlinking((300, 700))
1212009.01.15 00:12:35 mppl.asyncworker DEBUG    no elements in queue: starting idle task.                                       
1222009.01.15 00:12:35 odeviced.helpers DEBUG    (writing 'timer' to '/sys/class/leds/neo1973:vibrator/trigger')                 
1232009.01.15 00:12:35 odeviced.helpers DEBUG    (writing '300' to '/sys/class/leds/neo1973:vibrator/delay_on')                 
1242009.01.15 00:12:35 odeviced.helpers DEBUG    (writing '700' to '/sys/class/leds/neo1973:vibrator/delay_off')                 
1252009.01.15 00:12:35 oeventsd INFO     signal SetBlinking responded : ()                                                       
1262009.01.15 00:12:35 mppl.asyncworker DEBUG    _processElement()                                                               
1272009.01.15 00:12:35 mppl.asyncworker DEBUG    got an element from the queue                                                   
1282009.01.15 00:12:35 odeviced.audio DEBUG    getting task from queue...                                                       
1292009.01.15 00:12:35 odeviced.audio DEBUG    got task: play
130(dbus.String(u'/local/pkg/fso/share/sounds/Arkanoid_PSID.sid'), dbus.Int32(0), dbus.Int32(60))                                                                                                   
1312009.01.15 00:12:36 odeviced.audio DEBUG    GST message received while file status = unknown                                 
1322009.01.15 00:12:36 odeviced.audio DEBUG    G: STATE NOW: (<enum GST_STATE_NULL of type GstState>) -> <enum
133GST_STATE_READY of type GstState> -> (<enum GST_STATE_VOID_PENDING of type GstState>)                                                           
1342009.01.15 00:12:36 odeviced.audio DEBUG    GST message received while file status = unknown                                 
1352009.01.15 00:12:36 odeviced.audio DEBUG    G: STATE NOW: (<enum GST_STATE_NULL of type GstState>) -> <enum
136GST_STATE_READY of type GstState> -> (<enum GST_STATE_VOID_PENDING of type GstState>)                                                           
1372009.01.15 00:12:36 odeviced.audio DEBUG    GST message received while file status = unknown                                 
1382009.01.15 00:12:36 odeviced.audio DEBUG    G: STATE NOW: (<enum GST_STATE_NULL of type GstState>) -> <enum
139GST_STATE_READY of type GstState> -> (<enum GST_STATE_VOID_PENDING of type GstState>)                                                           
1402009.01.15 00:12:36 odeviced.audio DEBUG    GST message received while file status = unknown                                 
1412009.01.15 00:12:36 odeviced.audio DEBUG    G: STATE NOW: (<enum GST_STATE_NULL of type GstState>) -> <enum
142GST_STATE_READY of type GstState> -> (<enum GST_STATE_PLAYING of type GstState>)                                                               
1432009.01.15 00:12:37 odeviced.audio DEBUG    GST message received while file status = unknown                                 
1442009.01.15 00:12:37 odeviced.audio DEBUG    G: STATE NOW: (<enum GST_STATE_READY of type GstState>) -> <enum
145GST_STATE_PAUSED of type GstState> -> (<enum GST_STATE_VOID_PENDING of type GstState>)                                                         
1462009.01.15 00:12:37 odeviced.audio DEBUG    GST message received while file status = unknown                                 
1472009.01.15 00:12:37 odeviced.audio DEBUG    G: STATE NOW: (<enum GST_STATE_READY of type GstState>) -> <enum
148GST_STATE_PAUSED of type GstState> -> (<enum GST_STATE_VOID_PENDING of type GstState>)                                                         
1492009.01.15 00:12:37 odeviced.audio DEBUG    GST message received while file status = unknown                                 
1502009.01.15 00:12:37 odeviced.audio DEBUG    G: UNHANDLED: <flags GST_MESSAGE_TAG of type GstMessageType>                     
1512009.01.15 00:12:37 odeviced.audio DEBUG    GST message received while file status = unknown                                 
1522009.01.15 00:12:37 odeviced.audio DEBUG    G: STATE NOW: (<enum GST_STATE_READY of type GstState>) -> <enum
153GST_STATE_PAUSED of type GstState> -> (<enum GST_STATE_VOID_PENDING of type GstState>)                                                         
1542009.01.15 00:12:37 odeviced.audio DEBUG    GST message received while file status = unknown                                 
1552009.01.15 00:12:37 odeviced.audio DEBUG    G: STATE NOW: (<enum GST_STATE_READY of type GstState>) -> <enum
156GST_STATE_PAUSED of type GstState> -> (<enum GST_STATE_PLAYING of type GstState>)                                                             
1572009.01.15 00:12:37 odeviced.audio INFO     sound status /local/pkg/fso/share/sounds/Arkanoid_PSID.sid playing
158{}             
1592009.01.15 00:12:37 odeviced.audio DEBUG    adding timeout for /local/pkg/fso/share/sounds/Arkanoid_PSID.sid of
16060 seconds   
1612009.01.15 00:12:37 odeviced.audio DEBUG    GST message received while file status = playing                                 
1622009.01.15 00:12:37 odeviced.audio DEBUG    G: UNHANDLED: <flags GST_MESSAGE_ASYNC_DONE of type GstMessageType>               
1632009.01.15 00:12:37 oeventsd INFO     signal PlaySound responded : ()                                                         
1642009.01.15 00:12:37 odeviced.audio DEBUG    GST message received while file status = playing                                 
1652009.01.15 00:12:37 odeviced.audio DEBUG    G: UNHANDLED: <flags GST_MESSAGE_NEW_CLOCK of type GstMessageType>               
1662009.01.15 00:12:37 odeviced.audio DEBUG    GST message received while file status = playing                                 
1672009.01.15 00:12:37 odeviced.audio DEBUG    G: STATE NOW: (<enum GST_STATE_PAUSED of type GstState>) -> <enum
168GST_STATE_PLAYING of type GstState> -> (<enum GST_STATE_VOID_PENDING of type GstState>)                                                       
1692009.01.15 00:12:37 odeviced.audio DEBUG    GST message received while file status = playing                                 
1702009.01.15 00:12:37 odeviced.audio DEBUG    G: STATE NOW: (<enum GST_STATE_PAUSED of type GstState>) -> <enum
171GST_STATE_PLAYING of type GstState> -> (<enum GST_STATE_VOID_PENDING of type GstState>)                                                       
1722009.01.15 00:12:37 odeviced.audio DEBUG    GST message received while file status = playing                                 
1732009.01.15 00:12:37 odeviced.audio DEBUG    G: STATE NOW: (<enum GST_STATE_PAUSED of type GstState>) -> <enum
174GST_STATE_PLAYING of type GstState> -> (<enum GST_STATE_VOID_PENDING of type GstState>)                                                       
1752009.01.15 00:12:37 odeviced.audio DEBUG    GST message received while file status = playing                                 
1762009.01.15 00:12:37 odeviced.audio DEBUG    G: STATE NOW: (<enum GST_STATE_PAUSED of type GstState>) -> <enum
177GST_STATE_PLAYING of type GstState> -> (<enum GST_STATE_VOID_PENDING of type GstState>)                                                       
1782009.01.15 00:12:37 mppl.asyncworker DEBUG    _processElement()                                                               
1792009.01.15 00:12:37 mppl.asyncworker DEBUG    no more elements: stopping idle task.                                           
180
181
182
183
184At this point of time it does not stop although the call has been cancelled.
185
186
187
188
189
190
191
192
193
194
195
196
197
198
199
200
201
202
203
204
205
206
207
208
209
210
211^Cctrl-c: exiting.
2122009.01.15 00:12:56 frameworkd.controller INFO     shutting down...
2132009.01.15 00:12:56 odeviced.helpers DEBUG    (writing 'none' to '/sys/class/leds/gta02-power:orange/trigger')
2142009.01.15 00:12:56 odeviced.helpers DEBUG    (writing '0' to '/sys/class/leds/gta02-power:orange/brightness')
2152009.01.15 00:12:56 odeviced.helpers DEBUG    (writing 'none' to '/sys/class/leds/neo1973:vibrator/trigger')
2162009.01.15 00:12:56 odeviced.helpers DEBUG    (writing '0' to '/sys/class/leds/neo1973:vibrator/brightness')
2172009.01.15 00:12:56 odeviced.helpers DEBUG    (writing 'none' to '/sys/class/leds/gta02-power:blue/trigger')
2182009.01.15 00:12:56 odeviced.helpers DEBUG    (writing '0' to '/sys/class/leds/gta02-power:blue/brightness')
2192009.01.15 00:12:56 odeviced.helpers DEBUG    (writing 'none' to '/sys/class/leds/gta02-aux:red/trigger')
2202009.01.15 00:12:56 odeviced.helpers DEBUG    (writing '0' to '/sys/class/leds/gta02-aux:red/brightness')
2212009.01.15 00:12:56 frameworkd.resource INFO     setting resource status for GSM from enabled to disabling
2222009.01.15 00:12:57 frameworkd.resource INFO     setting resource status for GSM from disabling to disabled
223root@om-gta02:/local/pkg/fso/framework/framework#
224