正确的SM会话日志

02/27/14 16:15:09,770   INFO    SID[285544368599760921]-inited:0x9811cb8,10.135.252.17:45550
02/27/14 16:15:09,770   DEBUG   SID[285544368599760921]-current state: 2 and will set state: 0
02/27/14 16:15:09,771   INFO    SID[285544368599760921]-Received:
SETUP rtsp://172.16.244.239:554/;purchaseToken=DA8AC40E3EB22D9EA951006EDDE3D2AD;serverId=172.16.244.246:8080; RTSP/1.0
CSeq: 1
Require: com.comcast.ngod.s1
Transport: MP2T/DVBC/QAM;unicast;client=8000302100210197;qam_name=11
ClientSessionId: 800030210021019700039500


02/27/14 16:15:09,772   INFO    start to connect AAA 172.16.244.246:8080
02/27/14 16:15:09,772   INFO    succ to connect AAA 172.16.244.246:8080
02/27/14 16:15:09,772   INFO    SID[285544368599760921]-S2 Proxy Sent:
<?xml version="1.0" encoding="UTF-8"?>
<RequestPlaylist SMName="COSHIP_SM" sessionID="6c0f0c1bc09a4226a268bef3906f3168" deviceID="8000302100210197" usage="Start" PT="DA8AC40E3EB22D9EA951006EDDE3D2AD"/>

02/27/14 16:15:09,781   INFO    SID[285544368599760921]-S2 Proxy receive, http status code: 200:
<?xml version="1.0" encoding="UTF-8" standalone="yes"?>
<PhysicalPlaylist startNPT="0" startIndex="1" bitRate="0" playlistID="150543">
    <ContentRef end_npt="591.0" start_npt="0.0" assetID="ABCD4013101219094321" providerID="gd"/>
    <playType>1</playType>
</PhysicalPlaylist>

02/27/14 16:15:09,792   INFO    SID[285544368599760921]-start to connect 172.16.244.197:556
02/27/14 16:15:09,793   INFO    SID[285544368599760921]-succ to connect 172.16.244.197:556
02/27/14 16:15:09,794   INFO    SID[285544368599760921]-ngod.S6 Proxy Sent:
SETUP rtsp://172.16.244.197:556 RTSP/1.0
Require: sdp.s6
SessionGroup: group1
Transport: MP2T/DVBC/QAM;unicast;client=00AF123456DE;bandwidth=0;qam_name=11;modulation=QAM64
OnDemandSessionId: 6c0f0c1bc09a4226a268bef3906f3168
CSeq: 1


02/27/14 16:15:09,839   INFO    SID[285544368599760921]-Proxy Received:
RTSP/1.0 200 OK
CSeq: 1
Session: 1128624144406044
Transport: MP2T/DVBC/UDP;unicast;destination=172.16.244.124;client_port=11010;edge_input_group=11;client=00AF123456DE,MP2T/DVBC/QAM;unicast;destination=706000000.7;qam_name=BeiJing.HaiDian.11;qam_group=null;channel_width=8000000;modulation=qam64;annex=A;symbolRate=6875;client=00AF123456DE
OnDemandSessionId: 6c0f0c1bc09a4226a268bef3906f3168


02/27/14 16:15:09,839   INFO    succ to get sopgroup: EVMSOP_GP0 by landmark: 11
02/27/14 16:15:09,839   INFO    SID[285544368599760921]-get QAM resource, freq usage id:1, start_freq:706000000, prog_no:7, ip:172.16.244.124, port:11010, STB landmark:11, qam landmark:0, vss landmark:EVMSOP_GP0
02/27/14 16:15:09,839   DEBUG   SID[285544368599760921]-demand: 2 seq: 1, landmark: 11, filename:
02/27/14 16:15:09,840   DEBUG   SID[285544368599760921]-select[3] vsid: 1, servername: ServerRtspNGOD
02/27/14 16:15:09,840   DEBUG   SID[285544368599760921]-path_id: -1, volume: library
02/27/14 16:15:09,840   INFO    SID[285544368599760921]-schedule to[ServerRtspNGOD/seachange][phyID,pid]1,0[IP:PORT][172.16.244.212:554], proxy: 0xe8504b98
02/27/14 16:15:09,840   INFO    SID[285544368599760921]-start to connect 172.16.244.212:554
02/27/14 16:15:09,840   DEBUG   SID[285544368599760921]-to update sessioninfo where sessionStatus =0
02/27/14 16:15:09,840   INFO    SID[285544368599760921]-succ to connect 172.16.244.212:554
02/27/14 16:15:09,841   INFO    SID[285544368599760921]-ngod.R2 Proxy Sent:
SETUP rtsp://172.16.244.212:554 RTSP/1.0
Require: com.comcast.ngod.r2,com.comcast.ngod.r2.decimal_npts
StreamControlProto: rtsp
OnDemandSessionId: 6c0f0c1bc09a4226a268bef3906f3168
Volume: library
Transport: MP2T/DVBC/UDP;unicast;client=8000302100210197;bandwidth=0;destination=172.16.244.124;client_port=11010;sop_name=EVMSOP
SessionGroup: SM1
StartPoint: 1 0
Content-Type: application/sdp
Content-Length: 176
CSeq: 2

v=0
o=-6c0f0c1bc09a4226a268bef3906f3168 3602564109 IN IP4 172.16.244.239
s=
t=0 0
a=X-playlist-item: gd ABCD4013101219094321 0-90498
c=IN IP4 0.0.0.0
m=video 0 udp MP2T

02/27/14 16:15:09,842   INFO    SID[285544368599760921]-Proxy Received:
RTSP/1.0 200 OK
CSeq: 2
Session: 956396680
OnDemandSessionId: 6c0f0c1bc09a4226a268bef3906f3168
Transport: MP2T/DVBC/QAM;unicast;client=8000302100210197;bandwidth=;destination=172.16.244.124;client_port=11010;source=172.16.244.212;server_port=48156;sop_name=EVMSOP;sop_group=EVMSOP_GP1
Content-type: application/sdp
Content-Length: 152

v=0
o=- 956396680  IN IP4 172.16.244.212
s=
t=0 0
a=control:rtsp://172.16.244.212:554/956396680
c=IN IP4 172.16.244.124
m=video 11010 udp MP2T


02/27/14 16:15:09,842   DEBUG   before request sop resource availableBW: 1024000000, sessions: 0, sopname: EVMSOP
02/27/14 16:15:09,842   DEBUG   after request sop resource reference: 1, sopname: EVMSOP, sessions: 1,availableBW: 1024000000
02/27/14 16:15:09,842   INFO    SID[285544368599760921] is Added
02/27/14 16:15:09,842   INFO    SID[285544368599760921]-Sent:
RTSP/1.0 200 OK
CSeq: 1
Session: 285544368599760921;timeout=60
OnDemandSessionId: 6c0f0c1bc09a4226a268bef3906f3168
ClientSessionId: 800030210021019700039500
Transport: MP2T/DVBC/QAM;unicast;destination=706000000.7
Content-Type: application/sdp
Content-Length: 154

v=0
o=- 956396680  IN IP4 172.16.244.212
s=
t=0 0
a=control:rtsp://172.16.244.212:554/956396680
c=IN IP4 172.16.244.124
m=video 11010 udp MP2T



02/27/14 16:15:09,842   INFO    SID[285544368599760921]-responsed:2 reqID:1
02/27/14 16:15:09,843   INFO    start to connect AAA 172.16.244.246:8080
02/27/14 16:15:09,843   INFO    succ to connect AAA 172.16.244.246:8080
02/27/14 16:15:09,843   INFO    SID[285544368599760921]-S2 Proxy Sent:
<?xml version="1.0" encoding="UTF-8"?>
<SessionStart SMName="COSHIP_SM" sessionID="6c0f0c1bc09a4226a268bef3906f3168" deviceID="8000302100210197" playlistID="150543" startDateTime="2014-02-27T08:15:09Z"/>

02/27/14 16:15:09,850   INFO    SID[285544368599760921]-S2 Proxy receive, http status code: 200
02/27/14 16:15:09,899   INFO    SID[285544368599760921]-Received:
PING rtsp://172.16.244.239:554 RTSP/1.0
CSeq: 2
Require: com.comcast.ngod.s1
Session: 285544368599760921
OnDemandSessionId:6c0f0c1bc09a4226a268bef3906f3168
ClientSessionId:800030210021019700039500


02/27/14 16:15:09,899   INFO    SID[285544368599760921]-Sent:
RTSP/1.0 200 OK
CSeq: 2
Session: 285544368599760921
OnDemandSessionId: 6c0f0c1bc09a4226a268bef3906f3168


02/27/14 16:15:10,758   INFO    SID[285544368599760921]-Received:
TEARDOWN rtsp://172.16.244.239:554 RTSP/1.0
CSeq: 3
Require: com.comcast.ngod.s1
Reason: 200 "user pressed stop"
Session: 285544368599760921
OnDemandSessionId:6c0f0c1bc09a4226a268bef3906f3168
ClientSessionId:800030210021019700039500


02/27/14 16:15:10,758   DEBUG   SID[285544368599760921]-demand: 5 seq: 3, landmark: 11, filename:
02/27/14 16:15:10,758   INFO    SID[285544368599760921]-ngod.R2 Proxy Sent:
TEARDOWN rtsp://172.16.244.212:554 RTSP/1.0
Reason: 200 "user pressed stop"
Require: com.comcast.ngod.r2,com.comcast.ngod.r2.decimal_npts
OnDemandSessionId: 6c0f0c1bc09a4226a268bef3906f3168
Session: 956396680
CSeq: 3


02/27/14 16:15:10,840   INFO    SID[285544368599760921]-Proxy Received:
RTSP/1.0 200 OK
CSeq: 3
Session: 956396680
OnDemandSessionId: 6c0f0c1bc09a4226a268bef3906f3168
StopPoint: 1 0.000
FinalNPT: 0.000
Content-Type: text/xml
Content-Length: 338

<ResponseData>
<ODRMSessionHistory>
<ODRMSession
componentName="SGP1_1"
ODSessionID="6c0f0c1bc09a4226a268bef3906f3168"
setupDate="2014-02-27T16:16:37Z"
teardownDate="2014-02-27T16:16:38Z"
sessionGroup="SGP1_1"
ODRMIpAddr="172.16.244.212"
SMIpAddr="172.16.244.212"
resultCode="200">
</ODRMSession>
</ODRMSessionHistory>
</ResponseData>


02/27/14 16:15:10,840   INFO    SID[285544368599760921] is removed from server
02/27/14 16:15:10,840   INFO    SID[285544368599760921]-ngod.S6 Proxy Sent:
TEARDOWN rtsp://172.16.244.197:556 RTSP/1.0
Require: sdp.s6
Session: 1128624144406044
Reason: 200 "user pressed stop"
OnDemandSessionId: 6c0f0c1bc09a4226a268bef3906f3168
CSeq: 2


02/27/14 16:15:10,851   INFO    SID[285544368599760921]-Proxy Received:
RTSP/1.0 200 OK
CSeq: 2
Session: 1128624144406044
OnDemandSessionId: 6c0f0c1bc09a4226a268bef3906f3168


02/27/14 16:15:10,851   INFO    SID[285544368599760921]-Sent:
RTSP/1.0 200 OK
CSeq: 3
Session: 285544368599760921
OnDemandSessionId: 6c0f0c1bc09a4226a268bef3906f3168
ClientSessionId: 800030210021019700039500


02/27/14 16:15:10,851   INFO    SID[285544368599760921]-responsed:5 reqID:3
02/27/14 16:15:10,851   INFO    SID[285544368599760921]-teardown response:1
02/27/14 16:15:10,851   DEBUG   SID[285544368599760921]-current state: 0 and will set state: 1
02/27/14 16:15:10,852   INFO    start to connect AAA 172.16.244.246:8080
02/27/14 16:15:10,852   INFO    succ to connect AAA 172.16.244.246:8080
02/27/14 16:15:10,852   INFO    SID[285544368599760921]-S2 Proxy Sent:
<?xml version="1.0" encoding="UTF-8"?>
<SessionStop SMName="COSHIP_SM" sessionID="6c0f0c1bc09a4226a268bef3906f3168" deviceID="8000302100210197" playlistID="150543" stopNPT="0" stopAssetIndex="1" stopDateTime="2014-02-27T08:15:10Z"/>

02/27/14 16:15:10,860   INFO    SID[285544368599760921]-S2 Proxy receive, http status code: 200
02/27/14 16:15:10,972   INFO    SID[285544368599760921]-force to teardown the cur session
02/27/14 16:15:10,972   INFO    SID[285544368599760921]-force to teardown the cur session
02/27/14 16:15:10,972   INFO    SID[285544368599760921]-free m_proxyNGODS6 in FreeProxyObject