Project

General

Profile

Bug #415 » Incomplete_data_30sec_delay_before_killing_relayd.txt

Jesus Garcia, 12/19/2012 12:24 AM

 
SC-1:/storage/no-backup/coremw/var/log/saflog # /cluster/temp/eusgarc/test_once
Wed Dec 19 00:09:17 EST 2012
root 21870 1 0 Dec18 ? 00:00:04 /opt/trace_ea/bin/TraceEa
root 21877 1 0 Dec18 ? 00:00:00 lttng-sessiond -d
root 21878 21870 0 Dec18 ? 00:00:00 /opt/trace_ea/bin/TraceEa
root 21948 21877 0 Dec18 ? 00:00:00 lttng-consumerd --quiet -u --consumerd-cmd-sock /var/run/lttng/ustconsumerd64/command --consumerd-err-sock /var/run/lttng/ustconsumerd64/error
root 24049 1 0 00:05 ? 00:00:00 /opt/trace/sbin/trace_c
root 24053 1 0 00:05 ? 00:00:00 /opt/trace/sbin/trace_p
Wed Dec 19 00:09:17 EST 2012
Creating Profile
####################
Wed Dec 19 00:09:17 EST 2012
Dec 19 00:09:17 SC-1 osafimmnd[6451]: Ccb 437 COMMITTED (TraceCLI)
Dec 19 00:09:17 PL-3 osafimmnd[4905]: Ccb 437 COMMITTED (TraceCLI)
Dec 19 00:09:17 PL-4 osafimmnd[4935]: Ccb 437 COMMITTED (TraceCLI)
Dec 19 00:09:17 SC-2 osafimmnd[5121]: Ccb 437 COMMITTED (TraceCLI)
Wed Dec 19 00:09:17 EST 2012
Wait 1 sec...
Dec 19 00:09:17 SC-1 osafimmnd[6451]: Ccb 438 COMMITTED (TraceC)
Dec 19 00:09:17 PL-3 osafimmnd[4905]: Ccb 438 COMMITTED (TraceC)
Dec 19 00:09:17 PL-4 osafimmnd[4935]: Ccb 438 COMMITTED (TraceC)
Dec 19 00:09:17 SC-2 osafimmnd[5121]: Ccb 438 COMMITTED (TraceC)
5184 00:09:17 12/19/2012 IN TraceC "DBG: ProfileAdmin::profileIdValidator was called
5185 00:09:17 12/19/2012 IN TraceC "DBG: newvalue size=23
5186 00:09:17 12/19/2012 IN TraceC "DBG: newvalue=traceProfileId=TestProf

5187 00:09:17 12/19/2012 IN TraceC "DBG: traceExpressionsValidator was called for traceExpression with size = 1.
5188 00:09:17 12/19/2012 IN TraceC "DBG: conditionValidator: About to create a temporary session _TraceCValidator_etBUJm in path /tmp/_TraceCValidator_etBUJm - Invoke lttng_create_session
5189 00:09:17 12/19/2012 IN TraceC "DBG: conditionValidator: Invoke lttng_create_handle for temporary session _TraceCValidator_etBUJm.
5190 00:09:17 12/19/2012 IN TraceC "DBG: conditionValidator: Invoke lttng_set_default_handle_attr for temporary session _TraceCValidator_etBUJm
5191 00:09:17 12/19/2012 IN TraceC "DBG: conditionValidator: Invoke lttng_destroy_session for temporary session _TraceCValidator_etBUJm
5192 00:09:17 12/19/2012 IN TraceC "DBG: Try to remove directory "/tmp/_TraceCValidator_etBUJm"
5193 00:09:17 12/19/2012 IN TraceC "DBG: Remove Directory "/tmp/_TraceCValidator_etBUJm" Deleted!
5194 00:09:17 12/19/2012 IN TraceC "DBG: conditionValidator: Reply recieved from lttng_destroy_session for temporary session _TraceCValidator_etBUJm.
5195 00:09:17 12/19/2012 IN TraceC "DBG: Create: class: AsyncActionProgress, parentName: traceProfileId=TestProf,traceMId=1
Wed Dec 19 00:09:18 EST 2012
root 21870 1 0 Dec18 ? 00:00:04 /opt/trace_ea/bin/TraceEa
root 21877 1 0 Dec18 ? 00:00:00 lttng-sessiond -d
root 21878 21870 0 Dec18 ? 00:00:00 /opt/trace_ea/bin/TraceEa
root 21948 21877 0 Dec18 ? 00:00:00 lttng-consumerd --quiet -u --consumerd-cmd-sock /var/run/lttng/ustconsumerd64/command --consumerd-err-sock /var/run/lttng/ustconsumerd64/error
root 24049 1 0 00:05 ? 00:00:00 /opt/trace/sbin/trace_c
root 24053 1 0 00:05 ? 00:00:00 /opt/trace/sbin/trace_p
Wed Dec 19 00:09:18 EST 2012
Activating Profile
####################
Wed Dec 19 00:09:18 EST 2012
Dec 19 00:09:18 SC-1 osafimmnd[6451]: Ccb 440 COMMITTED (TraceC)
Dec 19 00:09:18 PL-4 osafimmnd[4935]: Ccb 440 COMMITTED (TraceC)
Dec 19 00:09:18 PL-3 osafimmnd[4905]: Ccb 440 COMMITTED (TraceC)
Dec 19 00:09:18 SC-2 osafimmnd[5121]: Ccb 440 COMMITTED (TraceC)
Dec 19 00:09:18 SC-1 osafimmnd[6451]: Ccb 441 COMMITTED (TraceC)
Dec 19 00:09:18 PL-4 osafimmnd[4935]: Ccb 441 COMMITTED (TraceC)
Dec 19 00:09:18 PL-3 osafimmnd[4905]: Ccb 441 COMMITTED (TraceC)
Dec 19 00:09:18 SC-2 osafimmnd[5121]: Ccb 441 COMMITTED (TraceC)
Dec 19 00:09:19 SC-1 osafimmnd[6451]: Ccb 442 COMMITTED (TraceC)
Dec 19 00:09:19 PL-3 osafimmnd[4905]: Ccb 442 COMMITTED (TraceC)
Dec 19 00:09:19 PL-4 osafimmnd[4935]: Ccb 442 COMMITTED (TraceC)
Dec 19 00:09:19 SC-2 osafimmnd[5121]: Ccb 442 COMMITTED (TraceC)
5196 00:09:18 12/19/2012 IN TraceC "DBG: Activating
5197 00:09:18 12/19/2012 IN TraceC "DBG: creating session traceSessionRecordingId=TestProf_1,traceMId=1 as a runtime object
5198 00:09:18 12/19/2012 NO TraceC "Activating Session TestProf_1.
5199 00:09:18 12/19/2012 IN TraceC "DBG: Directory /storage/no-backup/tracecc-apr9010500//TestProf_1 was created successfully!
5200 00:09:18 12/19/2012 IN TraceC "DBG: Return for invocation 1 value: 1, return code: 1 , SA_AIS_OK
5201 00:09:18 12/19/2012 IN TraceC "DBG: performActivate: Starting RelayD for session TestProf_1.
5202 00:09:18 12/19/2012 IN TraceC "DBG: relayD ip address is found, IP address: 192.168.0.1 for host: SC-1
5203 00:09:18 12/19/2012 IN TraceC "DBG: RelayDManager::start() Control port selected: 59383
5204 00:09:18 12/19/2012 IN TraceC "DBG: RelayDManager::start() Data port selected: 50886
5205 00:09:18 12/19/2012 IN TraceC "DBG: RelayDManager::start() Pipe opened
5206 00:09:18 12/19/2012 IN TraceC "DBG: relayD ip address is found, IP address: 192.168.0.1 for host: SC-1
5207 00:09:18 12/19/2012 IN TraceC "DBG: RelayDManager::start() RelayD for "TestProf_1" pid: 24406 bound on 192.168.0.1:59383:50886
5208 00:09:18 12/19/2012 IN TraceC "DBG: performActivate: Writing session into file
5209 00:09:18 12/19/2012 IN TraceC "DBG: Done Activating
5210 00:09:18 12/19/2012 IN TraceC "DBG: processMostQueuedEvents : directory TestProf_1 is being added to parent directory /storage/no-backup/tracecc-apr9010500
5211 00:09:18 12/19/2012 IN TraceC "DBG: RelayD has been launch for TestProf_1 session after 1 tries to connect to free ports.
5212 00:09:18 12/19/2012 IN TraceC "DBG: relayD ip address is found, IP address: 192.168.0.1 for host: SC-1
5213 00:09:18 12/19/2012 IN TraceC "DBG: performActivateAfterRelayD: Sending commands to the trace_ps.
5214 00:09:18 12/19/2012 IN TraceC "DBG: Creating admin op: dn tracePId=PL-3,traceMId=1, op: 2, addr: 0x7F76CC000CC0
5215 00:09:18 12/19/2012 IN TraceC "DBG: Creating admin op: dn tracePId=PL-4,traceMId=1, op: 2, addr: 0x7F76CC0024A0
5216 00:09:18 12/19/2012 IN TraceC "DBG: Creating admin op: dn tracePId=SC-1,traceMId=1, op: 2, addr: 0x7F76CC002590
5217 00:09:18 12/19/2012 IN TraceC "DBG: Creating admin op: dn tracePId=SC-2,traceMId=1, op: 2, addr: 0x7F76CC0029E0
5218 00:09:18 12/19/2012 ER TraceC "Update AsyncActionProgress "id=1,traceProfileId=TestProf,traceMId=1". Saf ErrorCode: SA_AIS_OK
5219 00:09:18 12/19/2012 IN TraceC "DBG: Invoking admin op 0x7F76CC000CC0
5220 00:09:18 12/19/2012 ER TraceC "Update AsyncActionProgress "id=1,traceProfileId=TestProf,traceMId=1". Saf ErrorCode: SA_AIS_OK
5221 00:09:18 12/19/2012 IN TraceC "DBG: Invoking admin op 0x7F76CC0024A0
5222 00:09:18 12/19/2012 IN TraceP_PL-3 "Activating
5223 00:09:18 12/19/2012 IN TraceC "DBG: Invoking admin op 0x7F76CC002590
5224 00:09:18 12/19/2012 IN TraceP_PL-4 "Activating
5225 00:09:18 12/19/2012 IN TraceC "DBG: Invoking admin op 0x7F76CC0029E0
5226 00:09:18 12/19/2012 IN TraceP_SC-1 "Activating
5227 00:09:18 12/19/2012 IN TraceP_SC-2 "Activating
5228 00:09:18 12/19/2012 IN TraceP_PL-3 "DBG: activateSession: invoke lttng_create_session for session TestProf_1 with url net://192.168.0.1:59383:50886/./.
5229 00:09:18 12/19/2012 IN TraceP_PL-3 "DBG: activateSession: Invoking lttng_create_handle for session TestProf_1
5230 00:09:18 12/19/2012 IN TraceP_PL-3 "DBG: activateSession: Invoking lttng_set_default_attr for session TestProf_1.
5231 00:09:18 12/19/2012 IN TraceP_PL-3 "DBG: activateSession: Invoking lttng_enable_channel for session TestProf_1
5232 00:09:18 12/19/2012 IN TraceP_PL-3 "DBG: activateSession: Invoking lttng_enable_event_with_filter for session TestProf_1, event com_ericsson_cba_trace_testapp_lowtraf:TenPerSecA, with cond ''
5233 00:09:18 12/19/2012 IN TraceP_PL-3 "DBG: activateSession: Invoking lttng_start_tracing, session name TestProf_1
5234 00:09:18 12/19/2012 IN TraceP_PL-4 "DBG: activateSession: invoke lttng_create_session for session TestProf_1 with url net://192.168.0.1:59383:50886/./.
5235 00:09:18 12/19/2012 IN TraceP_PL-4 "DBG: activateSession: Invoking lttng_create_handle for session TestProf_1
5236 00:09:18 12/19/2012 IN TraceP_PL-4 "DBG: activateSession: Invoking lttng_set_default_attr for session TestProf_1.
5237 00:09:18 12/19/2012 IN TraceP_PL-4 "DBG: activateSession: Invoking lttng_enable_channel for session TestProf_1
5238 00:09:18 12/19/2012 IN TraceP_SC-1 "DBG: activateSession: invoke lttng_create_session for session TestProf_1 with url net://192.168.0.1:59383:50886/./.
5239 00:09:18 12/19/2012 IN TraceP_SC-1 "DBG: activateSession: Invoking lttng_create_handle for session TestProf_1
5240 00:09:18 12/19/2012 IN TraceP_SC-1 "DBG: activateSession: Invoking lttng_set_default_attr for session TestProf_1.
5241 00:09:18 12/19/2012 IN TraceP_SC-1 "DBG: activateSession: Invoking lttng_enable_channel for session TestProf_1
5242 00:09:19 12/19/2012 IN TraceP_SC-1 "DBG: activateSession: Invoking lttng_enable_event_with_filter for session TestProf_1, event com_ericsson_cba_trace_testapp_lowtraf:TenPerSecA, with cond ''
5243 00:09:19 12/19/2012 IN TraceP_SC-1 "DBG: activateSession: Invoking lttng_start_tracing, session name TestProf_1
5244 00:09:18 12/19/2012 IN TraceP_PL-4 "DBG: activateSession: Invoking lttng_enable_event_with_filter for session TestProf_1, event com_ericsson_cba_trace_testapp_lowtraf:TenPerSecA, with cond ''
5245 00:09:18 12/19/2012 IN TraceP_PL-4 "DBG: activateSession: Invoking lttng_start_tracing, session name TestProf_1
5246 00:09:18 12/19/2012 IN TraceP_SC-2 "DBG: activateSession: invoke lttng_create_session for session TestProf_1 with url net://192.168.0.1:59383:50886/./.
5247 00:09:18 12/19/2012 IN TraceP_SC-2 "DBG: activateSession: Invoking lttng_create_handle for session TestProf_1
5248 00:09:18 12/19/2012 IN TraceP_SC-2 "DBG: activateSession: Invoking lttng_set_default_attr for session TestProf_1.
5249 00:09:18 12/19/2012 IN TraceP_SC-2 "DBG: activateSession: Invoking lttng_enable_channel for session TestProf_1
5250 00:09:19 12/19/2012 IN TraceP_SC-2 "DBG: activateSession: Invoking lttng_enable_event_with_filter for session TestProf_1, event com_ericsson_cba_trace_testapp_lowtraf:TenPerSecA, with cond ''
5251 00:09:19 12/19/2012 IN TraceP_SC-2 "DBG: activateSession: Invoking lttng_start_tracing, session name TestProf_1
5252 00:09:19 12/19/2012 IN TraceC "DBG: processMostQueuedEvents : directory PL-3 is being added to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1
5253 00:09:19 12/19/2012 IN TraceC "DBG: synchronizeDirectory : directory ust is being added by sync to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-3
5254 00:09:19 12/19/2012 IN TraceC "DBG: synchronizeDirectory : directory TestApp_type1-6865-20121219-000918 is being added by sync to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-3/ust
5255 00:09:19 12/19/2012 IN TraceC "DBG: processMostQueuedEvents : directory ust has been deleted, moved, replaced or a delayed creation notification has been received in /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-3
5256 00:09:19 12/19/2012 IN TraceC "DBG: TraceCHouseKeeping:directoryDeleteHandler is deleting ust
5257 00:09:19 12/19/2012 IN TraceC "DBG: directoryDeleteHandler : directory ust is being deleted
5258 00:09:19 12/19/2012 IN TraceC "DBG: processMostQueuedEvents : directory ust is being added to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-3
5259 00:09:19 12/19/2012 IN TraceC "DBG: synchronizeDirectory : directory TestApp_type1-6865-20121219-000918 is being added by sync to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-3/ust
5260 00:09:19 12/19/2012 ER TraceC "Update AsyncActionProgress "id=1,traceProfileId=TestProf,traceMId=1". Saf ErrorCode: SA_AIS_OK
5261 00:09:19 12/19/2012 IN TraceC "DBG: processMostQueuedEvents : directory SC-1 is being added to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1
5262 00:09:19 12/19/2012 IN TraceC "DBG: synchronizeDirectory : directory ust is being added by sync to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-1
5263 00:09:19 12/19/2012 IN TraceC "DBG: synchronizeDirectory : directory TestApp_type1-21999-20121219-000918 is being added by sync to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-1/ust
5264 00:09:19 12/19/2012 IN TraceC "DBG: processMostQueuedEvents : directory ust has been deleted, moved, replaced or a delayed creation notification has been received in /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-1
5265 00:09:19 12/19/2012 IN TraceC "DBG: TraceCHouseKeeping:directoryDeleteHandler is deleting ust
5266 00:09:19 12/19/2012 IN TraceC "DBG: directoryDeleteHandler : directory ust is being deleted
5267 00:09:19 12/19/2012 IN TraceC "DBG: processMostQueuedEvents : directory ust is being added to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-1
5268 00:09:19 12/19/2012 IN TraceC "DBG: synchronizeDirectory : directory TestApp_type1-21999-20121219-000918 is being added by sync to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-1/ust
5269 00:09:19 12/19/2012 IN TraceC "DBG: processMostQueuedEvents : directory PL-4 is being added to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1
5270 00:09:19 12/19/2012 IN TraceC "DBG: synchronizeDirectory : directory ust is being added by sync to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-4
5271 00:09:19 12/19/2012 IN TraceC "DBG: synchronizeDirectory : directory TestApp_type1-6899-20121219-000918 is being added by sync to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-4/ust
5272 00:09:19 12/19/2012 IN TraceC "DBG: processMostQueuedEvents : directory ust has been deleted, moved, replaced or a delayed creation notification has been received in /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-4
5273 00:09:19 12/19/2012 IN TraceC "DBG: TraceCHouseKeeping:directoryDeleteHandler is deleting ust
5274 00:09:19 12/19/2012 IN TraceC "DBG: directoryDeleteHandler : directory ust is being deleted
5275 00:09:19 12/19/2012 IN TraceC "DBG: processMostQueuedEvents : directory ust is being added to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-4
5276 00:09:19 12/19/2012 IN TraceC "DBG: synchronizeDirectory : directory TestApp_type1-6899-20121219-000918 is being added by sync to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-4/ust
5277 00:09:19 12/19/2012 IN TraceC "DBG: processMostQueuedEvents : directory TestApp_Fork1-22008-20121219-000918 is being added to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-1/ust
5278 00:09:19 12/19/2012 IN TraceC "DBG: processMostQueuedEvents : directory SC-2 is being added to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1
5279 00:09:19 12/19/2012 IN TraceC "DBG: synchronizeDirectory : directory ust is being added by sync to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-2
5280 00:09:19 12/19/2012 IN TraceC "DBG: synchronizeDirectory : directory TestApp_Fork1-9078-20121219-000918 is being added by sync to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-2/ust
5281 00:09:19 12/19/2012 IN TraceC "DBG: processMostQueuedEvents : directory ust has been deleted, moved, replaced or a delayed creation notification has been received in /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-2
5282 00:09:19 12/19/2012 IN TraceC "DBG: TraceCHouseKeeping:directoryDeleteHandler is deleting ust
5283 00:09:19 12/19/2012 IN TraceC "DBG: directoryDeleteHandler : directory ust is being deleted
5284 00:09:19 12/19/2012 IN TraceC "DBG: processMostQueuedEvents : directory ust is being added to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-2
5285 00:09:19 12/19/2012 IN TraceC "DBG: synchronizeDirectory : directory TestApp_Fork1-9078-20121219-000918 is being added by sync to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-2/ust
5286 00:09:19 12/19/2012 IN TraceC "DBG: processMostQueuedEvents : directory TestApp_Thread1-6867-20121219-000918 is being added to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-3/ust
5287 00:09:19 12/19/2012 IN TraceC "DBG: processMostQueuedEvents : directory TestApp_Thread1-6901-20121219-000918 is being added to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-4/ust
5288 00:09:19 12/19/2012 IN TraceC "DBG: processMostQueuedEvents : directory TestApp_Thread1-9073-20121219-000918 is being added to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-2/ust
5289 00:09:19 12/19/2012 IN TraceC "DBG: processMostQueuedEvents : directory TestApp_Fork1-22000-20121219-000918 is being added to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-1/ust
5290 00:09:19 12/19/2012 IN TraceC "DBG: processMostQueuedEvents : directory TestApp_Fork1-6866-20121219-000918 is being added to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-3/ust
Dec 19 00:09:19 SC-1 osafimmnd[6451]: Ccb 443 COMMITTED (TraceC)
Dec 19 00:09:19 PL-3 osafimmnd[4905]: Ccb 443 COMMITTED (TraceC)
Dec 19 00:09:19 PL-4 osafimmnd[4935]: Ccb 443 COMMITTED (TraceC)
Dec 19 00:09:19 SC-2 osafimmnd[5121]: Ccb 443 COMMITTED (TraceC)
Wed Dec 19 00:09:19 EST 2012
root 21870 1 0 Dec18 ? 00:00:04 /opt/trace_ea/bin/TraceEa
root 21877 1 0 Dec18 ? 00:00:00 lttng-sessiond -d
root 21878 21870 0 Dec18 ? 00:00:00 /opt/trace_ea/bin/TraceEa
root 21948 21877 0 Dec18 ? 00:00:00 lttng-consumerd --quiet -u --consumerd-cmd-sock /var/run/lttng/ustconsumerd64/command --consumerd-err-sock /var/run/lttng/ustconsumerd64/error
root 24049 1 0 00:05 ? 00:00:00 /opt/trace/sbin/trace_c
root 24053 1 0 00:05 ? 00:00:00 /opt/trace/sbin/trace_p
root 24406 24049 1 00:09 ? 00:00:00 lttng-relayd -C tcp://192.168.0.1:59383 -D tcp://192.168.0.1:50886 -o /storage/no-backup/tracecc-apr9010500/TestProf_1
Wed Dec 19 00:09:19 EST 2012
Allow session to run for 10 sec...
5291 00:09:19 12/19/2012 IN TraceC "DBG: processMostQueuedEvents : directory TestApp_Fork1-6908-20121219-000918 is being added to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-4/ust
5292 00:09:19 12/19/2012 IN TraceC "DBG: processMostQueuedEvents : directory TestApp_Fork1-9072-20121219-000918 is being added to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-2/ust
5293 00:09:19 12/19/2012 IN TraceC "DBG: processMostQueuedEvents : directory TestApp_Thread1-22001-20121219-000918 is being added to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-1/ust
5294 00:09:19 12/19/2012 IN TraceC "DBG: processMostQueuedEvents : directory TestApp_Fork1-6874-20121219-000918 is being added to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-3/ust
5295 00:09:19 12/19/2012 IN TraceC "DBG: processMostQueuedEvents : directory TestApp_Fork1-6900-20121219-000918 is being added to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-4/ust
5296 00:09:19 12/19/2012 IN TraceP_SC-1 "DBG: activateSession: Reply recieved for lttng_start_tracing for session TestProf_1.
5297 00:09:19 12/19/2012 IN TraceP_SC-1 "DBG: Return for invocation -3 value: 1, return code: 1 , SA_AIS_OK
5298 00:09:19 12/19/2012 IN TraceP_SC-1 "Activation completed successfully for session traceSessionRecordingId=TestProf_1,traceMId=1
5299 00:09:19 12/19/2012 IN TraceC "DBG: adminOperationInvokeAsyncCallback() operationReturnValue: 1 error: 1
5300 00:09:19 12/19/2012 IN TraceC "DBG: saImmOmDispatch Succeeded
5301 00:09:19 12/19/2012 IN TraceC "receiveTracePActivateResult result: 1 op_result 1
5302 00:09:19 12/19/2012 NO TraceC "Session TestProf_1 activated.
5303 00:09:19 12/19/2012 IN TraceC "DBG: processMostQueuedEvents : directory TestApp_type1-9071-20121219-000918 is being added to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-2/ust
5304 00:09:19 12/19/2012 IN TraceP_PL-3 "DBG: activateSession: Reply recieved for lttng_start_tracing for session TestProf_1.
5305 00:09:19 12/19/2012 IN TraceP_PL-3 "DBG: Return for invocation -1 value: 1, return code: 1 , SA_AIS_OK
5306 00:09:19 12/19/2012 IN TraceP_PL-3 "Activation completed successfully for session traceSessionRecordingId=TestProf_1,traceMId=1
5307 00:09:19 12/19/2012 IN TraceP_PL-4 "DBG: activateSession: Reply recieved for lttng_start_tracing for session TestProf_1.
5308 00:09:19 12/19/2012 IN TraceP_PL-4 "DBG: Return for invocation -2 value: 1, return code: 1 , SA_AIS_OK
5309 00:09:19 12/19/2012 IN TraceP_PL-4 "Activation completed successfully for session traceSessionRecordingId=TestProf_1,traceMId=1
5310 00:09:19 12/19/2012 IN TraceC "DBG: adminOperationInvokeAsyncCallback() operationReturnValue: 1 error: 1
5311 00:09:19 12/19/2012 ER TraceC "Update AsyncActionProgress "id=1,traceProfileId=TestProf,traceMId=1". Saf ErrorCode: SA_AIS_OK
5312 00:09:19 12/19/2012 IN TraceC "DBG: adminOperationInvokeAsyncCallback() operationReturnValue: 1 error: 1
5313 00:09:19 12/19/2012 IN TraceC "receiveTracePActivateResult result: 1 op_result 1
5314 00:09:19 12/19/2012 IN TraceC "DBG: saImmOmDispatch Succeeded
5315 00:09:19 12/19/2012 IN TraceC "receiveTracePActivateResult result: 1 op_result 1
5316 00:09:19 12/19/2012 IN TraceP_SC-2 "DBG: activateSession: Reply recieved for lttng_start_tracing for session TestProf_1.
5317 00:09:19 12/19/2012 IN TraceP_SC-2 "DBG: Return for invocation -4 value: 1, return code: 1 , SA_AIS_OK
5318 00:09:19 12/19/2012 IN TraceP_SC-2 "Activation completed successfully for session traceSessionRecordingId=TestProf_1,traceMId=1
5319 00:09:19 12/19/2012 IN TraceC "DBG: adminOperationInvokeAsyncCallback() operationReturnValue: 1 error: 1
5320 00:09:19 12/19/2012 IN TraceC "DBG: saImmOmDispatch Succeeded
5321 00:09:19 12/19/2012 IN TraceC "receiveTracePActivateResult result: 1 op_result 1
Wed Dec 19 00:09:29 EST 2012
root 21870 1 0 Dec18 ? 00:00:04 /opt/trace_ea/bin/TraceEa
root 21877 1 0 Dec18 ? 00:00:00 lttng-sessiond -d
root 21878 21870 0 Dec18 ? 00:00:00 /opt/trace_ea/bin/TraceEa
root 21948 21877 0 Dec18 ? 00:00:00 lttng-consumerd --quiet -u --consumerd-cmd-sock /var/run/lttng/ustconsumerd64/command --consumerd-err-sock /var/run/lttng/ustconsumerd64/error
root 24049 1 0 00:05 ? 00:00:00 /opt/trace/sbin/trace_c
root 24053 1 0 00:05 ? 00:00:00 /opt/trace/sbin/trace_p
root 24406 24049 0 00:09 ? 00:00:00 lttng-relayd -C tcp://192.168.0.1:59383 -D tcp://192.168.0.1:50886 -o /storage/no-backup/tracecc-apr9010500/TestProf_1
Wed Dec 19 00:09:29 EST 2012
Printing Session
####################
Wed Dec 19 00:09:29 EST 2012
errors : NoError;
logLevelDefault : TRACE_DEBUG_SYSTEM(7)
startTime : 12/19/12T00:09:18-0500
state : ACTIVE
stopTime : ""
traceExpressions : com_ericsson_cba_trace_testapp_lowtraf:TenPerSecA,TRACE_DEBUG_MODULE(10)
traceProfileId : TestProf
--------------------------------
progress : ""
4.0K /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-1/ust/TestApp_Fork1-22008-20121219-000918
8.0K /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-1/ust/TestApp_type1-21999-20121219-000918
4.0K /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-1/ust/TestApp_Fork1-22000-20121219-000918
4.0K /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-1/ust/TestApp_Thread1-22001-20121219-000918
24K /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-1/ust
28K /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-1
8.0K /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-4/ust/TestApp_type1-6899-20121219-000918
4.0K /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-4/ust/TestApp_Thread1-6901-20121219-000918
4.0K /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-4/ust/TestApp_Fork1-6900-20121219-000918
4.0K /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-4/ust/TestApp_Fork1-6908-20121219-000918
24K /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-4/ust
28K /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-4
8.0K /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-3/ust/TestApp_type1-6865-20121219-000918
4.0K /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-3/ust/TestApp_Fork1-6866-20121219-000918
4.0K /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-3/ust/TestApp_Fork1-6874-20121219-000918
4.0K /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-3/ust/TestApp_Thread1-6867-20121219-000918
24K /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-3/ust
28K /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-3
4.0K /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-2/ust/TestApp_Fork1-9078-20121219-000918
4.0K /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-2/ust/TestApp_type1-9071-20121219-000918
4.0K /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-2/ust/TestApp_Fork1-9072-20121219-000918
4.0K /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-2/ust/TestApp_Thread1-9073-20121219-000918
20K /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-2/ust
24K /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-2
116K /storage/no-backup/tracecc-apr9010500/TestProf_1
Wed Dec 19 00:09:29 EST 2012
Printing Ports while Session is active
####################
tcp 0 0 192.168.0.1:50886 0.0.0.0:* LISTEN 0 344796 24406/lttng-relayd
tcp 0 0 192.168.0.1:59383 0.0.0.0:* LISTEN 0 344795 24406/lttng-relayd
tcp 0 0 192.168.0.1:50886 192.168.0.1:59967 ESTABLISHED 0 349203 24406/lttng-relayd
tcp 0 0 192.168.0.1:59383 192.168.0.4:60649 ESTABLISHED 0 349202 24406/lttng-relayd
tcp 0 0 192.168.0.1:50886 192.168.0.3:38844 ESTABLISHED 0 349204 24406/lttng-relayd
tcp 0 0 192.168.0.1:50886 192.168.0.4:45792 ESTABLISHED 0 349211 24406/lttng-relayd
tcp 0 0 192.168.0.1:59383 192.168.0.3:33840 ESTABLISHED 0 349197 24406/lttng-relayd
tcp 0 0 192.168.0.1:59967 192.168.0.1:50886 ESTABLISHED 0 349201 21948/lttng-consume
tcp 0 0 192.168.0.1:59383 192.168.0.2:47014 ESTABLISHED 0 349210 24406/lttng-relayd
tcp 0 0 192.168.0.1:55638 192.168.0.1:59383 ESTABLISHED 0 348234 21948/lttng-consume
tcp 0 0 192.168.0.1:50886 192.168.0.2:46562 ESTABLISHED 0 349214 24406/lttng-relayd
tcp 0 0 192.168.0.1:59383 192.168.0.1:55638 ESTABLISHED 0 349199 24406/lttng-relayd
Wed Dec 19 00:09:29 EST 2012
Deactivating Profile
####################
Wed Dec 19 00:09:29 EST 2012
Dec 19 00:09:29 SC-1 osafimmnd[6451]: Ccb 446 COMMITTED (TraceC)
Dec 19 00:09:29 PL-3 osafimmnd[4905]: Ccb 446 COMMITTED (TraceC)
Dec 19 00:09:29 PL-4 osafimmnd[4935]: Ccb 446 COMMITTED (TraceC)
Dec 19 00:09:29 SC-2 osafimmnd[5121]: Ccb 446 COMMITTED (TraceC)
Dec 19 00:09:30 SC-1 osafimmnd[6451]: Ccb 447 COMMITTED (TraceC)
Dec 19 00:09:30 PL-3 osafimmnd[4905]: Ccb 447 COMMITTED (TraceC)
Dec 19 00:09:30 PL-4 osafimmnd[4935]: Ccb 447 COMMITTED (TraceC)
Dec 19 00:09:30 SC-2 osafimmnd[5121]: Ccb 447 COMMITTED (TraceC)
5322 00:09:29 12/19/2012 IN TraceC "DBG: Deactivating
5323 00:09:29 12/19/2012 IN TraceC "DBG: Getting Session "traceSessionRecordingId=TestProf_1,traceMId=1" from Registries

5324 00:09:29 12/19/2012 IN TraceC "DBG: Checking if the current state of Session "traceSessionRecordingId=TestProf_1,traceMId=1" is valid

5325 00:09:29 12/19/2012 NO TraceC "Deactivating session TestProf_1.
5326 00:09:29 12/19/2012 IN TraceC "DBG: update Async for deactivate - first time
5327 00:09:29 12/19/2012 IN TraceC "DBG: sending deactivate requests to all traceP's

5328 00:09:29 12/19/2012 IN TraceC "DBG: Creating admin op: dn tracePId=PL-3,traceMId=1, op: 3, addr: 0x7F76CC003E00
5329 00:09:29 12/19/2012 IN TraceC "DBG: Creating admin op: dn tracePId=PL-4,traceMId=1, op: 3, addr: 0x7F76CC003F50
5330 00:09:29 12/19/2012 IN TraceC "DBG: Creating admin op: dn tracePId=SC-1,traceMId=1, op: 3, addr: 0x7F76CC0040A0
5331 00:09:29 12/19/2012 IN TraceC "DBG: Creating admin op: dn tracePId=SC-2,traceMId=1, op: 3, addr: 0x7F76CC0041F0
5332 00:09:29 12/19/2012 IN TraceC "DBG: Return for invocation 1 value: 1, return code: 1 , SA_AIS_OK
5333 00:09:30 12/19/2012 ER TraceC "Update AsyncActionProgress "id=1,traceProfileId=TestProf,traceMId=1". Saf ErrorCode: SA_AIS_OK
5334 00:09:30 12/19/2012 IN TraceC "DBG: Invoking admin op 0x7F76CC003E00
5335 00:09:30 12/19/2012 ER TraceC "Update AsyncActionProgress "id=1,traceProfileId=TestProf,traceMId=1". Saf ErrorCode: SA_AIS_OK
5336 00:09:30 12/19/2012 IN TraceC "DBG: Invoking admin op 0x7F76CC003F50
5337 00:09:30 12/19/2012 IN TraceP_PL-3 "Deactivating
5338 00:09:30 12/19/2012 IN TraceP_PL-3 "DBG: hasSession: Invoke lttng_list_session.
5339 00:09:30 12/19/2012 IN TraceP_PL-3 "DBG: hasSession: Reply recieved from lttng_list_session with count 1
5340 00:09:30 12/19/2012 IN TraceP_PL-3 "DBG: deactivateSession: Invoke lttng_stop_tracing for session TestProf_1
5341 00:09:30 12/19/2012 IN TraceC "DBG: Invoking admin op 0x7F76CC0040A0
5342 00:09:30 12/19/2012 IN TraceP_PL-4 "Deactivating
5343 00:09:30 12/19/2012 IN TraceP_PL-4 "DBG: hasSession: Invoke lttng_list_session.
5344 00:09:30 12/19/2012 IN TraceP_PL-4 "DBG: hasSession: Reply recieved from lttng_list_session with count 1
5345 00:09:30 12/19/2012 IN TraceP_PL-4 "DBG: deactivateSession: Invoke lttng_stop_tracing for session TestProf_1
5346 00:09:30 12/19/2012 IN TraceC "DBG: Invoking admin op 0x7F76CC0041F0
5347 00:09:30 12/19/2012 IN TraceP_SC-1 "Deactivating
5348 00:09:30 12/19/2012 IN TraceP_SC-1 "DBG: hasSession: Invoke lttng_list_session.
5349 00:09:30 12/19/2012 IN TraceP_SC-1 "DBG: hasSession: Reply recieved from lttng_list_session with count 1
5350 00:09:30 12/19/2012 IN TraceP_SC-1 "DBG: deactivateSession: Invoke lttng_stop_tracing for session TestProf_1
5351 00:09:30 12/19/2012 IN TraceP_SC-2 "Deactivating
5352 00:09:30 12/19/2012 IN TraceP_SC-2 "DBG: hasSession: Invoke lttng_list_session.
5353 00:09:30 12/19/2012 IN TraceP_SC-2 "DBG: hasSession: Reply recieved from lttng_list_session with count 1
5354 00:09:30 12/19/2012 IN TraceP_SC-2 "DBG: deactivateSession: Invoke lttng_stop_tracing for session TestProf_1
5355 00:09:30 12/19/2012 IN TraceP_PL-3 "DBG: deactivateSession: Invoke lttng_destroy_session TestProf_1.
5356 00:09:30 12/19/2012 IN TraceP_SC-1 "DBG: deactivateSession: Invoke lttng_destroy_session TestProf_1.
5357 00:09:30 12/19/2012 IN TraceP_PL-3 "DBG: deactivateSession: Reply recieved for lttng_destroy_session for session TestProf_1
5358 00:09:30 12/19/2012 IN TraceP_PL-3 "DBG: Return for invocation -5 value: 1, return code: 1 , SA_AIS_OK
5359 00:09:30 12/19/2012 IN TraceC "DBG: adminOperationInvokeAsyncCallback() operationReturnValue: 1 error: 1
5360 00:09:30 12/19/2012 IN TraceP_SC-1 "DBG: deactivateSession: Reply recieved for lttng_destroy_session for session TestProf_1
5361 00:09:30 12/19/2012 IN TraceP_SC-1 "DBG: Return for invocation -7 value: 1, return code: 1 , SA_AIS_OK
5362 00:09:30 12/19/2012 IN TraceC "DBG: adminOperationInvokeAsyncCallback() operationReturnValue: 1 error: 1
5363 00:09:30 12/19/2012 IN TraceC "receiveTracePDeactivateResult result: 1 op_result 1
5364 00:09:30 12/19/2012 IN TraceC "DBG: saImmOmDispatch Succeeded
5365 00:09:30 12/19/2012 IN TraceC "receiveTracePDeactivateResult result: 1 op_result 1
5366 00:09:30 12/19/2012 IN TraceP_PL-3 "Deactivation was completed successfully for session traceSessionRecordingId=TestProf_1,traceMId=1.
5367 00:09:30 12/19/2012 IN TraceP_SC-1 "Deactivation was completed successfully for session traceSessionRecordingId=TestProf_1,traceMId=1.
5368 00:09:30 12/19/2012 IN TraceP_PL-4 "DBG: deactivateSession: Invoke lttng_destroy_session TestProf_1.
5369 00:09:30 12/19/2012 IN TraceP_PL-4 "DBG: deactivateSession: Reply recieved for lttng_destroy_session for session TestProf_1
5370 00:09:30 12/19/2012 IN TraceP_PL-4 "DBG: Return for invocation -6 value: 1, return code: 1 , SA_AIS_OK
5371 00:09:30 12/19/2012 IN TraceC "DBG: adminOperationInvokeAsyncCallback() operationReturnValue: 1 error: 1
5372 00:09:30 12/19/2012 IN TraceC "DBG: saImmOmDispatch Succeeded
5373 00:09:30 12/19/2012 IN TraceC "receiveTracePDeactivateResult result: 1 op_result 1
5374 00:09:30 12/19/2012 IN TraceP_PL-4 "Deactivation was completed successfully for session traceSessionRecordingId=TestProf_1,traceMId=1.
5375 00:09:30 12/19/2012 IN TraceP_SC-2 "DBG: deactivateSession: Invoke lttng_destroy_session TestProf_1.
5376 00:09:30 12/19/2012 IN TraceP_SC-2 "DBG: deactivateSession: Reply recieved for lttng_destroy_session for session TestProf_1
5377 00:09:30 12/19/2012 IN TraceP_SC-2 "DBG: Return for invocation -8 value: 1, return code: 1 , SA_AIS_OK
5378 00:09:30 12/19/2012 IN TraceC "DBG: adminOperationInvokeAsyncCallback() operationReturnValue: 1 error: 1
5379 00:09:30 12/19/2012 IN TraceC "DBG: saImmOmDispatch Succeeded
5380 00:09:30 12/19/2012 IN TraceC "receiveTracePDeactivateResult result: 1 op_result 1
5381 00:09:30 12/19/2012 IN TraceC "deactivateSessionOnTracePsComplete
5382 00:09:30 12/19/2012 IN TraceC "DBG: Waiting 30 sec before killing relayd
5383 00:09:30 12/19/2012 IN TraceP_SC-2 "Deactivation was completed successfully for session traceSessionRecordingId=TestProf_1,traceMId=1.
5384 00:10:00 12/19/2012 IN TraceC "DBG: Terminating relayD for session TestProf_1
5385 00:10:00 12/19/2012 NO TraceC "Deactivate complete for session "TestProf_1"
5386 00:10:00 12/19/2012 IN TraceC "DBG: deactivateSessionOnTracePsComplete calling TraceCHousekeeping::handleDelayedSessionWipes() for session traceSessionRecordingId=TestProf_1,traceMId=1
5387 00:10:00 12/19/2012 IN TraceC "DBG: !!! CcMasterQueueRunner took 30058157us to operate !!!
5388 00:10:00 12/19/2012 IN TraceC "DBG: !!! Jobs took excessively long to complete: 30058186us !!!
5389 00:10:00 12/19/2012 IN TraceC "DBG: SigChildHandler: second half handling. death count: 1
5390 00:10:00 12/19/2012 IN TraceC "DBG: Received death of pid 24406 with ret 9.
5391 00:10:00 12/19/2012 IN TraceC "DBG: No handler for pid 24406.
Dec 19 00:10:00 SC-1 osafimmnd[6451]: Ccb 448 COMMITTED (TraceC)
Dec 19 00:10:00 PL-3 osafimmnd[4905]: Ccb 448 COMMITTED (TraceC)
Dec 19 00:10:00 PL-4 osafimmnd[4935]: Ccb 448 COMMITTED (TraceC)
Dec 19 00:10:00 SC-2 osafimmnd[5121]: Ccb 448 COMMITTED (TraceC)
Wed Dec 19 00:10:00 EST 2012
Printing Profile
####################
Wed Dec 19 00:10:00 EST 2012
consistentLogs : NOT_ENFORCED
description : ""
latestSessionId : 1
logLevelDefault : TRACE_DEBUG_SYSTEM(7)
state : INACTIVE
traceExpressions : com_ericsson_cba_trace_testapp_lowtraf:TenPerSecA,TRACE_DEBUG_MODULE(10)
--------------------------------
progress.actionName : deactivateSession
progress.additionalInfo : ""
progress.info : Trace deactivation is complete
progress.percentage : 100
progress.result : SUCCESS
progress.resultInfo : Trace deactivateSession was completed successfully
progress.state : FINISHED
progress.timeActionCompleted : 12/19/12T00:10:00-0500
progress.timeActionStarted : 12/19/12T00:09:29-0500
progress.timeOfLastStatusUpdate : 12/19/12T00:10:00-0500
Wed Dec 19 00:10:00 EST 2012
Printing Session
####################
Wed Dec 19 00:10:00 EST 2012
errors : NoError;
logLevelDefault : TRACE_DEBUG_SYSTEM(7)
startTime : 12/19/12T00:09:18-0500
state : COMPLETE
stopTime : 12/19/12T00:10:00-0500
traceExpressions : com_ericsson_cba_trace_testapp_lowtraf:TenPerSecA,TRACE_DEBUG_MODULE(10)
traceProfileId : TestProf
--------------------------------
progress : ""
8.0K /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-1/ust/TestApp_Fork1-22008-20121219-000918
28K /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-1/ust/TestApp_type1-21999-20121219-000918
12K /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-1/ust/TestApp_Fork1-22000-20121219-000918
12K /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-1/ust/TestApp_Thread1-22001-20121219-000918
64K /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-1/ust
68K /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-1
28K /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-4/ust/TestApp_type1-6899-20121219-000918
12K /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-4/ust/TestApp_Thread1-6901-20121219-000918
16K /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-4/ust/TestApp_Fork1-6900-20121219-000918
12K /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-4/ust/TestApp_Fork1-6908-20121219-000918
72K /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-4/ust
76K /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-4
28K /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-3/ust/TestApp_type1-6865-20121219-000918
12K /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-3/ust/TestApp_Fork1-6866-20121219-000918
12K /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-3/ust/TestApp_Fork1-6874-20121219-000918
8.0K /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-3/ust/TestApp_Thread1-6867-20121219-000918
64K /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-3/ust
68K /storage/no-backup/tracecc-apr9010500/TestProf_1/PL-3
24K /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-2/ust/TestApp_Fork1-9078-20121219-000918
28K /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-2/ust/TestApp_type1-9071-20121219-000918
24K /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-2/ust/TestApp_Fork1-9072-20121219-000918
24K /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-2/ust/TestApp_Thread1-9073-20121219-000918
104K /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-2/ust
108K /storage/no-backup/tracecc-apr9010500/TestProf_1/SC-2
328K /storage/no-backup/tracecc-apr9010500/TestProf_1
Wed Dec 19 00:10:00 EST 2012
Printing Ports after Session has been deactivated
####################
Wed Dec 19 00:10:01 EST 2012
Converting Session
####################
Wed Dec 19 00:10:01 EST 2012
5392 00:10:00 12/19/2012 ER TraceC "Update AsyncActionProgress "id=1,traceProfileId=TestProf,traceMId=1". Saf ErrorCode: SA_AIS_OK
5393 00:10:01 12/19/2012 IN TraceC "DBG: Converting output operation
5394 00:10:01 12/19/2012 IN TraceC "DBG: Try to async-remove directory "/storage/no-backup/tracecc-apr9010500//TestProf_1/Converted_logs"
5395 00:10:01 12/19/2012 IN TraceC "DBG: Directory /storage/no-backup/tracecc-apr9010500//TestProf_1/Converted_logs was created successfully!
5396 00:10:01 12/19/2012 NO TraceC "Converting session "TestProf_1"
5397 00:10:01 12/19/2012 IN TraceC "DBG: resetOrCreate traceSessionRecordingId=TestProf_1,traceMId=1
5398 00:10:01 12/19/2012 IN TraceC "DBG: ConvertAsyncProgress: Create request for child object of traceSessionRecordingId=TestProf_1,traceMId=1
5399 00:10:01 12/19/2012 IN TraceC "DBG: ConvertAsyncProgressImplementer: id=1,traceSessionRecordingId=TestProf_1,traceMId=1 was successfully created
5400 00:10:01 12/19/2012 IN TraceC "DBG: Return for invocation 1 value: 1, return code: 1 , SA_AIS_OK
5401 00:10:01 12/19/2012 IN TraceC "DBG: -== start convert output from /storage/no-backup/tracecc-apr9010500//TestProf_1/PL-3 to /storage/no-backup/tracecc-apr9010500//TestProf_1/Converted_logs/Node_PL-3.log ==-
5402 00:10:01 12/19/2012 IN TraceC "DBG: === ConvertOutputTask::babelTraceConvert, bfile=/storage/no-backup/tracecc-apr9010500//TestProf_1/PL-3,desFile=/storage/no-backup/tracecc-apr9010500//TestProf_1/Converted_logs/Node_PL-3.log ===
5403 00:10:01 12/19/2012 IN TraceC "DBG: processMostQueuedEvents : directory Converted_logs is being added to parent directory /storage/no-backup/tracecc-apr9010500/TestProf_1
5404 00:10:04 12/19/2012 IN TraceC "DBG: Dispatch: epoll_wait returned an error: 4
5405 00:10:04 12/19/2012 IN TraceC "DBG: SigChildHandler: second half handling. death count: 1
5406 00:10:04 12/19/2012 IN TraceC "DBG: Received death of pid 24527 with ret 256.
5407 00:10:04 12/19/2012 IN TraceC "DBG: ConvertOutputTask::handleSigChild line=61 return: 1
5408 00:10:04 12/19/2012 IN TraceC "DBG: receiveConvertOutputResult() result: 1
5409 00:10:04 12/19/2012 ER TraceC "Convert Session: TestProf_1 session log conversion failed
5410 00:10:04 12/19/2012 IN TraceC "DBG: -== start convert output from /storage/no-backup/tracecc-apr9010500//TestProf_1/PL-4 to /storage/no-backup/tracecc-apr9010500//TestProf_1/Converted_logs/Node_PL-4.log ==-
5411 00:10:04 12/19/2012 IN TraceC "DBG: === ConvertOutputTask::babelTraceConvert, bfile=/storage/no-backup/tracecc-apr9010500//TestProf_1/PL-4,desFile=/storage/no-backup/tracecc-apr9010500//TestProf_1/Converted_logs/Node_PL-4.log ===
5412 00:10:07 12/19/2012 IN TraceC "DBG: Dispatch: epoll_wait returned an error: 4
5413 00:10:07 12/19/2012 IN TraceC "DBG: SigChildHandler: second half handling. death count: 1
5414 00:10:07 12/19/2012 IN TraceC "DBG: Received death of pid 24531 with ret 256.
5415 00:10:07 12/19/2012 IN TraceC "DBG: ConvertOutputTask::handleSigChild line=61 return: 1
5416 00:10:07 12/19/2012 IN TraceC "DBG: receiveConvertOutputResult() result: 1
5417 00:10:07 12/19/2012 ER TraceC "Convert Session: TestProf_1 session log conversion failed
5418 00:10:07 12/19/2012 IN TraceC "DBG: -== start convert output from /storage/no-backup/tracecc-apr9010500//TestProf_1/SC-1 to /storage/no-backup/tracecc-apr9010500//TestProf_1/Converted_logs/Node_SC-1.log ==-
5419 00:10:07 12/19/2012 IN TraceC "DBG: === ConvertOutputTask::babelTraceConvert, bfile=/storage/no-backup/tracecc-apr9010500//TestProf_1/SC-1,desFile=/storage/no-backup/tracecc-apr9010500//TestProf_1/Converted_logs/Node_SC-1.log ===
5420 00:10:10 12/19/2012 IN TraceC "DBG: Dispatch: epoll_wait returned an error: 4
5421 00:10:10 12/19/2012 IN TraceC "DBG: SigChildHandler: second half handling. death count: 1
5422 00:10:10 12/19/2012 IN TraceC "DBG: Received death of pid 24539 with ret 256.
5423 00:10:10 12/19/2012 IN TraceC "DBG: ConvertOutputTask::handleSigChild line=61 return: 1
5424 00:10:10 12/19/2012 IN TraceC "DBG: receiveConvertOutputResult() result: 1
5425 00:10:10 12/19/2012 ER TraceC "Convert Session: TestProf_1 session log conversion failed
5426 00:10:10 12/19/2012 IN TraceC "DBG: -== start convert output from /storage/no-backup/tracecc-apr9010500//TestProf_1/SC-2 to /storage/no-backup/tracecc-apr9010500//TestProf_1/Converted_logs/Node_SC-2.log ==-
5427 00:10:10 12/19/2012 IN TraceC "DBG: === ConvertOutputTask::babelTraceConvert, bfile=/storage/no-backup/tracecc-apr9010500//TestProf_1/SC-2,desFile=/storage/no-backup/tracecc-apr9010500//TestProf_1/Converted_logs/Node_SC-2.log ===
5428 00:10:10 12/19/2012 IN TraceC "DBG: Dispatch: epoll_wait returned an error: 4
5429 00:10:10 12/19/2012 IN TraceC "DBG: SigChildHandler: second half handling. death count: 1
5430 00:10:10 12/19/2012 IN TraceC "DBG: Received death of pid 24543 with ret 0.
5431 00:10:10 12/19/2012 IN TraceC "DBG: ConvertOutputTask::handleSigChild line=61 return: 0
5432 00:10:10 12/19/2012 IN TraceC "DBG: receiveConvertOutputResult() result: 0
5433 00:10:10 12/19/2012 NO TraceC "Completed conversion of session "TestProf_1".
Wed Dec 19 00:10:11 EST 2012
Listing Session Directory Structure
####################
/storage/no-backup/tracecc-apr9010500/TestProf_1:
total 32
drwxr-xr-x 7 root root 4096 Dec 19 00:10 .
drwxr-xr-x 5 root root 4096 Dec 19 00:09 ..
drwxr-xr-x 2 root root 4096 Dec 19 00:10 Converted_logs
drwxr-x--- 3 root root 4096 Dec 19 00:09 PL-3
drwxr-x--- 3 root root 4096 Dec 19 00:09 PL-4
drwxr-x--- 3 root root 4096 Dec 19 00:09 SC-1
drwxr-x--- 3 root root 4096 Dec 19 00:09 SC-2
-rw-r--r-- 1 root root 230 Dec 19 00:10 session_metadata

/storage/no-backup/tracecc-apr9010500/TestProf_1/Converted_logs:
total 128
drwxr-xr-x 2 root root 4096 Dec 19 00:10 .
drwxr-xr-x 7 root root 4096 Dec 19 00:10 ..
-rwxr-xr-x 1 root root 26814 Dec 19 00:10 Node_PL-3.log
-rwxr-xr-x 1 root root 27306 Dec 19 00:10 Node_PL-4.log
-rwxr-xr-x 1 root root 26676 Dec 19 00:10 Node_SC-1.log
-rwxr-xr-x 1 root root 25830 Dec 19 00:10 Node_SC-2.log
-rw-r--r-- 1 root root 4673 Dec 19 00:10 conversionResult.log

/storage/no-backup/tracecc-apr9010500/TestProf_1/PL-3:
total 12
drwxr-x--- 3 root root 4096 Dec 19 00:09 .
drwxr-xr-x 7 root root 4096 Dec 19 00:10 ..
drwxr-x--- 6 root root 4096 Dec 19 00:09 ust

/storage/no-backup/tracecc-apr9010500/TestProf_1/PL-3/ust:
total 24
drwxr-x--- 6 root root 4096 Dec 19 00:09 .
drwxr-x--- 3 root root 4096 Dec 19 00:09 ..
drwxr-x--- 2 root root 4096 Dec 19 00:09 TestApp_Fork1-6866-20121219-000918
drwxr-x--- 2 root root 4096 Dec 19 00:09 TestApp_Fork1-6874-20121219-000918
drwxr-x--- 2 root root 4096 Dec 19 00:09 TestApp_Thread1-6867-20121219-000918
drwxr-x--- 2 root root 4096 Dec 19 00:09 TestApp_type1-6865-20121219-000918

/storage/no-backup/tracecc-apr9010500/TestProf_1/PL-3/ust/TestApp_Fork1-6866-20121219-000918:
total 16
drwxr-x--- 2 root root 4096 Dec 19 00:09 .
drwxr-x--- 6 root root 4096 Dec 19 00:09 ..
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_0
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_1
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_2
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_3
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 metadata

/storage/no-backup/tracecc-apr9010500/TestProf_1/PL-3/ust/TestApp_Fork1-6874-20121219-000918:
total 16
drwxr-x--- 2 root root 4096 Dec 19 00:09 .
drwxr-x--- 6 root root 4096 Dec 19 00:09 ..
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_0
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_1
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_2
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_3
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 metadata

/storage/no-backup/tracecc-apr9010500/TestProf_1/PL-3/ust/TestApp_Thread1-6867-20121219-000918:
total 12
drwxr-x--- 2 root root 4096 Dec 19 00:09 .
drwxr-x--- 6 root root 4096 Dec 19 00:09 ..
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_0
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_1
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_2
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_3
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 metadata

/storage/no-backup/tracecc-apr9010500/TestProf_1/PL-3/ust/TestApp_type1-6865-20121219-000918:
total 32
drwxr-x--- 2 root root 4096 Dec 19 00:09 .
drwxr-x--- 6 root root 4096 Dec 19 00:09 ..
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_0
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_1
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_2
-rwxr-xr-x 1 root root 8192 Dec 19 00:09 channel0_3
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 metadata

/storage/no-backup/tracecc-apr9010500/TestProf_1/PL-4:
total 12
drwxr-x--- 3 root root 4096 Dec 19 00:09 .
drwxr-xr-x 7 root root 4096 Dec 19 00:10 ..
drwxr-x--- 6 root root 4096 Dec 19 00:09 ust

/storage/no-backup/tracecc-apr9010500/TestProf_1/PL-4/ust:
total 24
drwxr-x--- 6 root root 4096 Dec 19 00:09 .
drwxr-x--- 3 root root 4096 Dec 19 00:09 ..
drwxr-x--- 2 root root 4096 Dec 19 00:09 TestApp_Fork1-6900-20121219-000918
drwxr-x--- 2 root root 4096 Dec 19 00:09 TestApp_Fork1-6908-20121219-000918
drwxr-x--- 2 root root 4096 Dec 19 00:09 TestApp_Thread1-6901-20121219-000918
drwxr-x--- 2 root root 4096 Dec 19 00:09 TestApp_type1-6899-20121219-000918

/storage/no-backup/tracecc-apr9010500/TestProf_1/PL-4/ust/TestApp_Fork1-6900-20121219-000918:
total 20
drwxr-x--- 2 root root 4096 Dec 19 00:09 .
drwxr-x--- 6 root root 4096 Dec 19 00:09 ..
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_0
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_1
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_2
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_3
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 metadata

/storage/no-backup/tracecc-apr9010500/TestProf_1/PL-4/ust/TestApp_Fork1-6908-20121219-000918:
total 16
drwxr-x--- 2 root root 4096 Dec 19 00:09 .
drwxr-x--- 6 root root 4096 Dec 19 00:09 ..
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_0
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_1
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_2
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_3
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 metadata

/storage/no-backup/tracecc-apr9010500/TestProf_1/PL-4/ust/TestApp_Thread1-6901-20121219-000918:
total 16
drwxr-x--- 2 root root 4096 Dec 19 00:09 .
drwxr-x--- 6 root root 4096 Dec 19 00:09 ..
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_0
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_1
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_2
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_3
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 metadata

/storage/no-backup/tracecc-apr9010500/TestProf_1/PL-4/ust/TestApp_type1-6899-20121219-000918:
total 32
drwxr-x--- 2 root root 4096 Dec 19 00:09 .
drwxr-x--- 6 root root 4096 Dec 19 00:09 ..
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_0
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_1
-rwxr-xr-x 1 root root 8192 Dec 19 00:09 channel0_2
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_3
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 metadata

/storage/no-backup/tracecc-apr9010500/TestProf_1/SC-1:
total 12
drwxr-x--- 3 root root 4096 Dec 19 00:09 .
drwxr-xr-x 7 root root 4096 Dec 19 00:10 ..
drwxr-x--- 6 root root 4096 Dec 19 00:09 ust

/storage/no-backup/tracecc-apr9010500/TestProf_1/SC-1/ust:
total 24
drwxr-x--- 6 root root 4096 Dec 19 00:09 .
drwxr-x--- 3 root root 4096 Dec 19 00:09 ..
drwxr-x--- 2 root root 4096 Dec 19 00:09 TestApp_Fork1-22000-20121219-000918
drwxr-x--- 2 root root 4096 Dec 19 00:09 TestApp_Fork1-22008-20121219-000918
drwxr-x--- 2 root root 4096 Dec 19 00:09 TestApp_Thread1-22001-20121219-000918
drwxr-x--- 2 root root 4096 Dec 19 00:09 TestApp_type1-21999-20121219-000918

/storage/no-backup/tracecc-apr9010500/TestProf_1/SC-1/ust/TestApp_Fork1-22000-20121219-000918:
total 16
drwxr-x--- 2 root root 4096 Dec 19 00:09 .
drwxr-x--- 6 root root 4096 Dec 19 00:09 ..
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_0
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_1
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_2
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_3
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 metadata

/storage/no-backup/tracecc-apr9010500/TestProf_1/SC-1/ust/TestApp_Fork1-22008-20121219-000918:
total 12
drwxr-x--- 2 root root 4096 Dec 19 00:09 .
drwxr-x--- 6 root root 4096 Dec 19 00:09 ..
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_0
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_1
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_2
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_3
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 metadata

/storage/no-backup/tracecc-apr9010500/TestProf_1/SC-1/ust/TestApp_Thread1-22001-20121219-000918:
total 16
drwxr-x--- 2 root root 4096 Dec 19 00:09 .
drwxr-x--- 6 root root 4096 Dec 19 00:09 ..
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_0
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_1
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_2
-rwxr-xr-x 1 root root 0 Dec 19 00:09 channel0_3
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 metadata

/storage/no-backup/tracecc-apr9010500/TestProf_1/SC-1/ust/TestApp_type1-21999-20121219-000918:
total 32
drwxr-x--- 2 root root 4096 Dec 19 00:09 .
drwxr-x--- 6 root root 4096 Dec 19 00:09 ..
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_0
-rwxr-xr-x 1 root root 8192 Dec 19 00:09 channel0_1
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_2
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_3
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 metadata

/storage/no-backup/tracecc-apr9010500/TestProf_1/SC-2:
total 12
drwxr-x--- 3 root root 4096 Dec 19 00:09 .
drwxr-xr-x 7 root root 4096 Dec 19 00:10 ..
drwxr-x--- 6 root root 4096 Dec 19 00:09 ust

/storage/no-backup/tracecc-apr9010500/TestProf_1/SC-2/ust:
total 24
drwxr-x--- 6 root root 4096 Dec 19 00:09 .
drwxr-x--- 3 root root 4096 Dec 19 00:09 ..
drwxr-x--- 2 root root 4096 Dec 19 00:09 TestApp_Fork1-9072-20121219-000918
drwxr-x--- 2 root root 4096 Dec 19 00:09 TestApp_Fork1-9078-20121219-000918
drwxr-x--- 2 root root 4096 Dec 19 00:09 TestApp_Thread1-9073-20121219-000918
drwxr-x--- 2 root root 4096 Dec 19 00:09 TestApp_type1-9071-20121219-000918

/storage/no-backup/tracecc-apr9010500/TestProf_1/SC-2/ust/TestApp_Fork1-9072-20121219-000918:
total 28
drwxr-x--- 2 root root 4096 Dec 19 00:09 .
drwxr-x--- 6 root root 4096 Dec 19 00:09 ..
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_0
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_1
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_2
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_3
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 metadata

/storage/no-backup/tracecc-apr9010500/TestProf_1/SC-2/ust/TestApp_Fork1-9078-20121219-000918:
total 28
drwxr-x--- 2 root root 4096 Dec 19 00:09 .
drwxr-x--- 6 root root 4096 Dec 19 00:09 ..
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_0
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_1
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_2
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_3
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 metadata

/storage/no-backup/tracecc-apr9010500/TestProf_1/SC-2/ust/TestApp_Thread1-9073-20121219-000918:
total 28
drwxr-x--- 2 root root 4096 Dec 19 00:09 .
drwxr-x--- 6 root root 4096 Dec 19 00:09 ..
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_0
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_1
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_2
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_3
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 metadata

/storage/no-backup/tracecc-apr9010500/TestProf_1/SC-2/ust/TestApp_type1-9071-20121219-000918:
total 32
drwxr-x--- 2 root root 4096 Dec 19 00:09 .
drwxr-x--- 6 root root 4096 Dec 19 00:09 ..
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_0
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_1
-rwxr-xr-x 1 root root 8192 Dec 19 00:09 channel0_2
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 channel0_3
-rwxr-xr-x 1 root root 4096 Dec 19 00:09 metadata
Wed Dec 19 00:10:11 EST 2012
Printing Session
####################
Wed Dec 19 00:10:11 EST 2012
errors : NoError;
logLevelDefault : TRACE_DEBUG_SYSTEM(7)
startTime : 12/19/12T00:09:18-0500
state : COMPLETE
stopTime : 12/19/12T00:10:00-0500
traceExpressions : com_ericsson_cba_trace_testapp_lowtraf:TenPerSecA,TRACE_DEBUG_MODULE(10)
traceProfileId : TestProf
--------------------------------
progress.actionName : convertOutput
progress.additionalInfo : nodeName=All processId=All
progress.info : Convert is terminated with failure!
progress.percentage : 100
progress.result : FAILURE
progress.resultInfo : Failed to convert output
progress.state : FINISHED
progress.timeActionCompleted : 12/19/12T00:10:10-0500
progress.timeActionStarted : 12/19/12T00:10:01-0500
progress.timeOfLastStatusUpdate : ""
Wed Dec 19 00:10:11 EST 2012
Deleting Session
####################
Wed Dec 19 00:10:11 EST 2012
Wed Dec 19 00:10:11 EST 2012
Deleting Profile
####################
Wed Dec 19 00:10:11 EST 2012
Dec 19 00:10:11 PL-3 osafimmnd[4905]: Ccb 454 COMMITTED (TraceCLI)
Dec 19 00:10:11 SC-1 osafimmnd[6451]: Ccb 454 COMMITTED (TraceCLI)
###########################################################################
###########################################################################
###########################################################################
SC-1:/storage/no-backup/coremw/var/log/saflog # Dec 19 00:10:11 PL-4 osafimmnd[4935]: Ccb 454 COMMITTED (TraceCLI)
Dec 19 00:10:11 SC-2 osafimmnd[5121]: Ccb 454 COMMITTED (TraceCLI)
5434 00:10:11 12/19/2012 IN TraceC "DBG: Delete Session operation was requested for traceSessionRecordingId=TestProf_1,traceMId=1
5435 00:10:11 12/19/2012 IN TraceC "deleteSession
5436 00:10:11 12/19/2012 IN TraceC "DBG: deleteSession Removing session Directory traceSessionRecordingId=TestProf_1,traceMId=1
5437 00:10:11 12/19/2012 IN TraceC "DBG: Try to async-remove directory "/storage/no-backup/tracecc-apr9010500//TestProf_1"
5438 00:10:11 12/19/2012 IN TraceC "DBG: remove called to delete file /storage/no-backup/tracecc-apr9010500//.tmp/TestProf_1
5439 00:10:11 12/19/2012 IN TraceC "DBG: deleteSession deleting ConvertAsyncActionProgress if it exists traceSessionRecordingId=TestProf_1,traceMId=1
5440 00:10:11 12/19/2012 IN TraceC "DBG: Try to remove directory "/storage/no-backup/tracecc-apr9010500//.tmp/TestProf_1"
5441 00:10:11 12/19/2012 IN TraceC "DBG: deleteSession deleting Session MO and removing it from Registry traceSessionRecordingId=TestProf_1,traceMId=1
5442 00:10:11 12/19/2012 IN TraceC "DBG: deleteSession deleting Session Object traceSessionRecordingId=TestProf_1,traceMId=1
5443 00:10:11 12/19/2012 IN TraceC "DBG: Return for invocation 1 value: 1, return code: 1 , SA_AIS_OK
5444 00:10:11 12/19/2012 IN TraceC "DBG: processMostQueuedEvents : directory TestProf_1 has been deleted, moved, replaced or a delayed creation notification has been received in /storage/no-backup/tracecc-apr9010500
5445 00:10:11 12/19/2012 IN TraceC "DBG: TraceCHouseKeeping:directoryDeleteHandler is deleting TestProf_1
5446 00:10:11 12/19/2012 IN TraceC "DBG: TraceCHouseKeeping: directoryDeleteHandler is deleting TestProf_1
5447 00:10:11 12/19/2012 IN TraceC "DBG: directoryDeleteHandler : directory TestProf_1 is being deleted
5448 00:10:11 12/19/2012 IN TraceC "DBG: Remove Directory "/storage/no-backup/tracecc-apr9010500//.tmp/TestProf_1" Deleted!
5449 00:10:11 12/19/2012 IN TraceC "DBG: Completed deletion of "/storage/no-backup/tracecc-apr9010500//.tmp/TestProf_1"
(1-1/5)