Skip to content

Instantly share code, notes, and snippets.

@Elexy

Elexy/Longer log with several ocurances

Last active August 29, 2015 14:13
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save Elexy/052a6a428fb57147bf4e to your computer and use it in GitHub Desktop.
Save Elexy/052a6a428fb57147bf4e to your computer and use it in GitHub Desktop.
Download ZIP
Ejabberd log with session terminate
Raw
Longer log with several ocurances
2015-01-09 18:16:44.089 [debug] <0.2648.0>@ejabberd_router:do_route:322 route
from {jid,<<>>,<<"localhost">>,<<>>,<<>>,<<"localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>}
packet {xmlel,<<"iq">>,[{<<"id">>,<<"1593051025">>},{<<"type">>,<<"get">>}],[{xmlel,<<"ping">>,[{<<"xmlns">>,<<"urn:xmpp:ping">>}],[]}]}
2015-01-09 18:16:44.090 [debug] <0.2648.0>@ejabberd_local:do_route:296 local route
from {jid,<<>>,<<"localhost">>,<<>>,<<>>,<<"localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>}
packet {xmlel,<<"iq">>,[{<<"id">>,<<"1593"...>>},{<<"type">>,<<...>>}],[{xmlel,<<...>>,...}]}
2015-01-09 18:16:44.091 [debug] <0.2648.0>@ejabberd_sm:do_route:514 session manager
from {jid,<<>>,<<"localhost">>,<<>>,<<>>,<<"localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>}
packet {xmlel,<<"iq">>,[{<<"id">>,<<"1593"...>>},{<<"type">>,<<...>>}],[{xmlel,<<...>>,...}]}
2015-01-09 18:16:44.091 [debug] <0.2648.0>@ejabberd_sm:do_route:612 sending to process <0.4053.0>
2015-01-09 18:16:44.092 [debug] <0.5198.0>@ejabberd_http_bind:prepare_response:914 OutPacket: [{xmlstreamelement,{xmlel,<<"iq">>,[{<<"from">>,<<"localhost">>},{<<"to">>,<<"agent1@localhost/cb_operator_1420818758533">>},{<<"id">>,<<"1593051025">>},{<<"type">>,<<"get">>}],[{xmlel,<<"ping">>,[{<<"xmlns">>,<<"urn:xmpp:ping">>}],[]}]}}]
2015-01-09 18:16:44.092 [debug] <0.5198.0>@ejabberd_http_bind:send_outpacket:1056 --- outgoing data ---
<body xmlns='http://jabber.org/protocol/httpbind'><iq xmlns='jabber:client' from='localhost' to='agent1@localhost/cb_operator_1420818758533' id='1593051025' type='get'><ping xmlns='urn:xmpp:ping'/></iq></body>
--- END ---
2015-01-09 18:16:44.161 [info] <0.2709.0>@ejabberd_listener:accept:313 (#Port<0.41528>) Accepted connection ::FFFF:127.0.0.1:58299 -> ::FFFF:127.0.0.1:5280
2015-01-09 18:16:44.162 [debug] <0.5199.0>@ejabberd_http:init:145 S: [{[<<"presence">>],mod_webpresence},{[<<"captcha">>],ejabberd_captcha},{[<<"admin">>],ejabberd_web_admin},{[<<"http-bind">>],mod_http_bind},{[<<"http-poll">>],ejabberd_http_poll}]
2015-01-09 18:16:44.162 [info] <0.5199.0>@ejabberd_http:init:149 started: {gen_tcp,#Port<0.41528>}
2015-01-09 18:16:44.163 [debug] <0.5199.0>@ejabberd_http:process_header:277 (#Port<0.41528>) http query: 'POST' /http-bind
2015-01-09 18:16:44.163 [debug] <0.5199.0>@ejabberd_http:process_request:438 client data: <<"<body rid='876136' sid='a1e29cded4325f591bdc5b3646a8f3c78553cdd8' xmlns='http://jabber.org/protocol/httpbind' key='bbbd363400fbf957d9a69bc2645a4f14dd29eab9'><iq xmlns=\"jabber:client\" to=\"localhost\" id=\"1593051025\" type=\"result\"><ping xmlns=\"urn:xmpp:ping\"/></iq></body>">>
2015-01-09 18:16:44.164 [debug] <0.5199.0>@ejabberd_http:process:350 [<<"http-bind">>] matches [<<"http-bind">>]
2015-01-09 18:16:44.164 [debug] <0.5199.0>@ejabberd_http:process:354 [{'Accept-Language',<<"en-US,en;q=0.8,es;q=0.6,und;q=0.4,nl;q=0.2,ca;q=0.2">>},{'Accept-Encoding',<<"gzip, deflate">>},{'Referer',<<"http://localhost:9080/">>},{'Accept',<<"*/*">>},{'Content-Type',<<"text/xml; charset=UTF-8">>},{'User-Agent',<<"Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/39.0.2171.95 Safari/537.36">>},{<<"Origin">>,<<"http://localhost:9080">>},{'Content-Length',<<"269">>},{'Connection',<<"close">>},{'Host',<<"vagrant.localhost">>}]
2015-01-09 18:16:44.165 [debug] <0.5199.0>@mod_http_bind:process:68 Incoming data: <body rid='876136' sid='a1e29cded4325f591bdc5b3646a8f3c78553cdd8' xmlns='http://jabber.org/protocol/httpbind' key='bbbd363400fbf957d9a69bc2645a4f14dd29eab9'><iq xmlns="jabber:client" to="localhost" id="1593051025" type="result"><ping xmlns="urn:xmpp:ping"/></iq></body>
2015-01-09 18:16:44.165 [debug] <0.5199.0>@ejabberd_http_bind:parse_request:1128 --- incoming data ---
<body rid='876136' sid='a1e29cded4325f591bdc5b3646a8f3c78553cdd8' xmlns='http://jabber.org/protocol/httpbind' key='bbbd363400fbf957d9a69bc2645a4f14dd29eab9'><iq xmlns="jabber:client" to="localhost" id="1593051025" type="result"><ping xmlns="urn:xmpp:ping"/></iq></body>
--- END ---
2015-01-09 18:16:44.166 [debug] <0.5199.0>@ejabberd_http_bind:http_put:792 Looking for session: <<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>
2015-01-09 18:16:44.166 [debug] <0.4052.0>@ejabberd_http_bind:handle_sync_event:440 New request: {http_put,876136,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"876136">>},{<<"sid">>,<<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>},{<<"key">>,<<"bbbd363400fbf957d9a69bc2645a4f14dd29eab9">>}],[{xmlel,<<"iq">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"to">>,<<"localhost">>},{<<"id">>,<<"1593051025">>},{<<"type">>,<<"result">>}],[{xmlel,<<"ping">>,[{<<"xmlns">>,<<"urn:xmpp:ping">>}],[]}]}],269,1,<<>>,{{0,0,0,0,0,65535,32512,1},58299}}
2015-01-09 18:16:44.167 [debug] <0.4052.0>@ejabberd_http_bind:handle_http_put_event:575 New request: {http_put,876136,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"876136">>},{<<"sid">>,<<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>},{<<"key">>,<<"bbbd363400fbf957d9a69bc2645a4f14dd29eab9">>}],[{xmlel,<<"iq">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"to">>,<<"localhost">>},{<<"id">>,<<"1593051025">>},{<<"type">>,<<"result">>}],[{xmlel,<<"ping">>,[{<<"xmlns">>,<<"urn:xmpp:ping">>}],[]}]}],269,1,<<>>,{{0,0,0,0,0,65535,32512,1},58299}}
2015-01-09 18:16:44.167 [debug] <0.4052.0>@ejabberd_http_bind:rid_allow:866 Previous rid / New rid: 876135/876136
2015-01-09 18:16:44.168 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:608 Actually processing request: {http_put,876136,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"876136">>},{<<"sid">>,<<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>},{<<"key">>,<<"bbbd363400fbf957d9a69bc2645a4f14dd29eab9">>}],[{xmlel,<<"iq">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"to">>,<<"localhost">>},{<<"id">>,<<"1593051025">>},{<<"type">>,<<"result">>}],[{xmlel,<<"ping">>,[{<<"xmlns">>,<<"urn:xmpp:ping">>}],[]}]}],269,1,<<>>,{{0,0,0,0,0,65535,32512,1},58299}}
2015-01-09 18:16:44.168 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:619 Key/OldKey/NextKey: bbbd363400fbf957d9a69bc2645a4f14dd29eab9/a01fe27beaf386c34535782a1eafec4f275fbb60/a01fe27beaf386c34535782a1eafec4f275fbb60
2015-01-09 18:16:44.168 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:659 -- SaveKey: bbbd363400fbf957d9a69bc2645a4f14dd29eab9
2015-01-09 18:16:44.169 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:671 reqlist: [{hbr,876136,<<"a01fe27beaf386c34535782a1eafec4f275fbb60">>,[]},{hbr,876135,<<"a01fe27beaf386c34535782a1eafec4f275fbb60">>,[{xmlstreamelement,{xmlel,<<"iq">>,[{<<"from">>,<<"localhost">>},{<<"to">>,<<"agent1@localhost/cb_operator_1420818758533">>},{<<"id">>,<<"1593051025">>},{<<"type">>,<<"get">>}],[{xmlel,<<"ping">>,[{<<"xmlns">>,<<"urn:xmpp:ping">>}],[]}]}}]}]
2015-01-09 18:16:44.171 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:717 really sending now: [{xmlel,<<"iq">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"to">>,<<"localhost">>},{<<"id">>,<<"1593051025">>},{<<"type">>,<<"result">>}],[{xmlel,<<"ping">>,[{<<"xmlns">>,<<"urn:xmpp:ping">>}],[]}]}]
2015-01-09 18:16:44.171 [debug] <0.4053.0>@ejabberd_router:do_route:322 route
from {jid,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>}
to {jid,<<>>,<<"localhost">>,<<>>,<<>>,<<"localhost">>,<<>>}
packet {xmlel,<<"iq">>,[{<<"to">>,<<"localhost">>},{<<"id">>,<<"1593051025">>},{<<"type">>,<<"result">>}],[{xmlel,<<"ping">>,[{<<"xmlns">>,<<"urn:xmpp:ping">>}],[]}]}
2015-01-09 18:16:44.171 [debug] <0.4053.0>@ejabberd_local:do_route:296 local route
from {jid,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>}
to {jid,<<>>,<<"localhost">>,<<>>,<<>>,<<"localhost">>,<<>>}
packet {xmlel,<<"iq">>,[{<<"to">>,<<"loca"...>>},{<<"id">>,<<...>>},{<<...>>,...}],[{xmlel,<<...>>,...}]}
2015-01-09 18:16:54.839 [info] <0.2709.0>@ejabberd_listener:accept:313 (#Port<0.41530>) Accepted connection ::FFFF:127.0.0.1:58300 -> ::FFFF:127.0.0.1:5280
2015-01-09 18:16:54.839 [debug] <0.5201.0>@ejabberd_http:init:145 S: [{[<<"presence">>],mod_webpresence},{[<<"captcha">>],ejabberd_captcha},{[<<"admin">>],ejabberd_web_admin},{[<<"http-bind">>],mod_http_bind},{[<<"http-poll">>],ejabberd_http_poll}]
2015-01-09 18:16:54.840 [info] <0.5201.0>@ejabberd_http:init:149 started: {gen_tcp,#Port<0.41530>}
2015-01-09 18:16:54.840 [debug] <0.5201.0>@ejabberd_http:process_header:277 (#Port<0.41530>) http query: 'GET' /http-bind
2015-01-09 18:16:54.841 [debug] <0.5201.0>@ejabberd_http:process:350 [<<"http-bind">>] matches [<<"http-bind">>]
2015-01-09 18:16:54.843 [debug] <0.5201.0>@ejabberd_http:process:354 [{'Cookie',<<"_cb_secure=%7B%2254ac093c612c34.56437777%22%3A%7B%22_cbtt%22%3A%2229d27dec634cc86dcc6084a6ae830c753f594798a10b8a24aa18c1ddc8fa39e4%22%7D%7D; _cb_settings=%7B%2254ac093c612c34.56437777%22%3A%7B%22_cbchat%22%3A%7B%22session%22%3A%7B%22jid%22%3A%22anonymous.c6502a950667fc79c3e1534559cc02e0%40localhost%2F7f317c7fcbb9fb1b79c414c2d8afe05c%22%2C%22sid%22%3A%22eed4d847111872817ecc8c49243aafb560bda9f2%22%2C%22rid%22%3A5292088526%2C%22polling%22%3A5%2C%22inactivity%22%3A10%2C%22requests%22%3A2%7D%2C%22groupJid%22%3A%22bc1234%40jaxlecho.localhost%22%2C%22messages%22%3A%5B%5D%7D%7D%7D; _cb_analytics=null; _cb_tracking=%7B%2254ac093c612c34.56437777%22%3A%7B%22trackingOn%22%3A1%2C%22_cbs%22%3A%22b62d40341f950986066a9b28f911ba14326101ab%22%2C%22_cbv%22%3A%2297a71b9487d1b8447b00f274d3923d37d8c006d7%22%7D%7D">>},{'Accept-Language',<<"en-US,en;q=0.8,es;q=0.6,und;q=0.4,nl;q=0.2,ca;q=0.2">>},{'Accept-Encoding',<<"gzip, deflate, sdch">>},{'User-Agent',<<"Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/39.0.2171.95 Safari/537.36">>},{'Accept',<<"*/*">>},{'Cache-Control',<<"no-cache">>},{'Pragma',<<"no-cache">>},{'Connection',<<"close">>},{'Host',<<"vagrant.localhost">>}]
2015-01-09 18:16:54.879 [info] <0.2709.0>@ejabberd_listener:accept:313 (#Port<0.41531>) Accepted connection ::FFFF:127.0.0.1:58302 -> ::FFFF:127.0.0.1:5280
2015-01-09 18:16:54.880 [debug] <0.5202.0>@ejabberd_http:init:145 S: [{[<<"presence">>],mod_webpresence},{[<<"captcha">>],ejabberd_captcha},{[<<"admin">>],ejabberd_web_admin},{[<<"http-bind">>],mod_http_bind},{[<<"http-poll">>],ejabberd_http_poll}]
2015-01-09 18:16:54.880 [info] <0.5202.0>@ejabberd_http:init:149 started: {gen_tcp,#Port<0.41531>}
2015-01-09 18:16:54.880 [debug] <0.5202.0>@ejabberd_http:process_header:277 (#Port<0.41531>) http query: 'POST' /http-bind
2015-01-09 18:16:54.880 [debug] <0.5202.0>@ejabberd_http:process_request:438 client data: <<"<?xml version=\"1.0\" encoding=\"UTF-8\"?>\n<body xmlns=\"http://jabber.org/protocol/httpbind\" content=\"text/xml charset=utf-8\" rid=\"1634610720\" xml:lang=\"en\" hold=\"1\" to=\"localhost\" xmlns:xmpp=\"urn:xmpp:xbosh\" xmpp:version=\"1.0\" wait=\"60\"/>\n">>
2015-01-09 18:16:54.880 [debug] <0.5202.0>@ejabberd_http:process:350 [<<"http-bind">>] matches [<<"http-bind">>]
2015-01-09 18:16:54.881 [debug] <0.5202.0>@ejabberd_http:process:354 [{'Content-Type',<<"text/xml charset=utf-8">>},{'Accept',<<"*/*">>},{'Content-Length',<<"236">>},{'Connection',<<"close">>},{'Host',<<"localhost">>}]
2015-01-09 18:16:54.881 [debug] <0.5202.0>@mod_http_bind:process:68 Incoming data: <?xml version="1.0" encoding="UTF-8"?>
<body xmlns="http://jabber.org/protocol/httpbind" content="text/xml charset=utf-8" rid="1634610720" xml:lang="en" hold="1" to="localhost" xmlns:xmpp="urn:xmpp:xbosh" xmpp:version="1.0" wait="60"/>
2015-01-09 18:16:54.881 [debug] <0.5202.0>@ejabberd_http_bind:parse_request:1128 --- incoming data ---
<?xml version="1.0" encoding="UTF-8"?>
<body xmlns="http://jabber.org/protocol/httpbind" content="text/xml charset=utf-8" rid="1634610720" xml:lang="en" hold="1" to="localhost" xmlns:xmpp="urn:xmpp:xbosh" xmpp:version="1.0" wait="60"/>
--- END ---
2015-01-09 18:16:54.881 [debug] <0.5202.0>@ejabberd_http_bind:start:141 Starting session
2015-01-09 18:16:54.881 [debug] <0.5203.0>@ejabberd_http_bind:init:330 started: {<<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>,<<>>,{{0,0,0,0,0,65535,32512,1},58302}}
2015-01-09 18:16:54.882 [debug] <0.5202.0>@ejabberd_http_bind:handle_session_start:266 got pid: <0.5203.0>
2015-01-09 18:16:54.882 [debug] <0.5202.0>@ejabberd_http_bind:handle_session_start:305 Create session: <<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>
2015-01-09 18:16:54.882 [debug] <0.5202.0>@ejabberd_http_bind:http_put:792 Looking for session: <<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>
2015-01-09 18:16:54.882 [debug] <0.5203.0>@ejabberd_http_bind:handle_sync_event:440 New request: {http_put,1634610720,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"xmlns:xmpp">>,<<"urn:xmpp:xbosh">>},{<<"content">>,<<"text/xml charset=utf-8">>},{<<"rid">>,<<"1634610720">>},{<<"xml:lang">>,<<"en">>},{<<"hold">>,<<"1">>},{<<"to">>,<<"localhost">>},{<<"xmpp:version">>,<<"1.0">>},{<<"wait">>,<<"60">>}],[],236,1,{<<"localhost">>,<<"1.0">>},{{0,0,0,0,0,65535,32512,1},58302}}
2015-01-09 18:16:54.882 [debug] <0.5203.0>@ejabberd_http_bind:handle_http_put_event:575 New request: {http_put,1634610720,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"xmlns:xmpp">>,<<"urn:xmpp:xbosh">>},{<<"content">>,<<"text/xml charset=utf-8">>},{<<"rid">>,<<"1634610720">>},{<<"xml:lang">>,<<"en">>},{<<"hold">>,<<"1">>},{<<"to">>,<<"localhost">>},{<<"xmpp:version">>,<<"1.0">>},{<<"wait">>,<<"60">>}],[],236,1,{<<"localhost">>,<<"1.0">>},{{0,0,0,0,0,65535,32512,1},58302}}
2015-01-09 18:16:54.883 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:608 Actually processing request: {http_put,1634610720,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"xmlns:xmpp">>,<<"urn:xmpp:xbosh">>},{<<"content">>,<<"text/xml charset=utf-8">>},{<<"rid">>,<<"1634610720">>},{<<"xml:lang">>,<<"en">>},{<<"hold">>,<<"1">>},{<<"to">>,<<"localhost">>},{<<"xmpp:version">>,<<"1.0">>},{<<"wait">>,<<"60">>}],[],236,1,{<<"localhost">>,<<"1.0">>},{{0,0,0,0,0,65535,32512,1},58302}}
2015-01-09 18:16:54.883 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:659 -- SaveKey:
2015-01-09 18:16:54.883 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:671 reqlist: [{hbr,1634610720,<<>>,[]}]
2015-01-09 18:16:54.883 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:717 really sending now: []
2015-01-09 18:16:54.989 [debug] <0.5202.0>@ejabberd_http_bind:prepare_response:914 OutPacket: [{xmlstreamstart,<<"stream:stream">>,[{<<"version">>,<<"1.0">>},{<<"xml:lang">>,<<"en">>},{<<"xmlns">>,<<"jabber:client">>},{<<"xmlns:stream">>,<<"http://etherx.jabber.org/streams">>},{<<"id">>,<<"640881963">>},{<<"from">>,<<"localhost">>}]},{xmlstreamelement,{xmlel,<<"stream:features">>,[],[{xmlel,<<"mechanisms">>,[{<<"xmlns">>,<<"urn:ietf:params:xml:ns:xmpp-sasl">>}],[{xmlel,<<"mechanism">>,[],[{xmlcdata,<<"PLAIN">>}]}]},{xmlel,<<"c">>,[{<<"xmlns">>,<<"http://jabber.org/protocol/caps">>},{<<"hash">>,<<"sha-1">>},{<<"node">>,<<"http://www.process-one.net/en/ejabberd/">>},{<<"ver">>,<<"/nWL9StXSXhEsL2wg0+s4xo/UdA=">>}],[]},{xmlel,<<"register">>,[{<<"xmlns">>,<<"http://jabber.org/features/iq-register">>}],[]}]}}]
2015-01-09 18:16:55.000 [info] <0.2709.0>@ejabberd_listener:accept:313 (#Port<0.41533>) Accepted connection ::FFFF:127.0.0.1:58304 -> ::FFFF:127.0.0.1:5280
2015-01-09 18:16:55.001 [debug] <0.5205.0>@ejabberd_http:init:145 S: [{[<<"presence">>],mod_webpresence},{[<<"captcha">>],ejabberd_captcha},{[<<"admin">>],ejabberd_web_admin},{[<<"http-bind">>],mod_http_bind},{[<<"http-poll">>],ejabberd_http_poll}]
2015-01-09 18:16:55.001 [info] <0.5205.0>@ejabberd_http:init:149 started: {gen_tcp,#Port<0.41533>}
2015-01-09 18:16:55.001 [debug] <0.5205.0>@ejabberd_http:process_header:277 (#Port<0.41533>) http query: 'POST' /http-bind
2015-01-09 18:16:55.002 [debug] <0.5205.0>@ejabberd_http:process_request:438 client data: <<"<?xml version=\"1.0\" encoding=\"UTF-8\"?>\n<body xmlns=\"http://jabber.org/protocol/httpbind\" content=\"text/xml charset=utf-8\" rid=\"1634610721\" xml:lang=\"en\" sid=\"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3\"><auth xmlns=\"urn:ietf:params:xml:ns:xmpp-sasl\" mechanism=\"PLAIN\">YW5vbnltb3VzLmM2NTAyYTk1MDY2N2ZjNzljM2UxNTM0NTU5Y2MwMmUwQGxvY2FsaG9zdC83ZjMxN2M3ZmNiYjlmYjFiNzljNDE0YzJkOGFmZTA1YwBhbm9ueW1vdXMuYzY1MDJhOTUwNjY3ZmM3OWMzZTE1MzQ1NTljYzAyZTAANDhlMTNkY2VkYzc1MTY3</auth></body>\n">>
2015-01-09 18:16:55.003 [debug] <0.5205.0>@ejabberd_http:process:350 [<<"http-bind">>] matches [<<"http-bind">>]
2015-01-09 18:16:55.003 [debug] <0.5205.0>@ejabberd_http:process:354 [{'Content-Type',<<"text/xml charset=utf-8">>},{'Accept',<<"*/*">>},{'Content-Length',<<"472">>},{'Connection',<<"close">>},{'Host',<<"localhost">>}]
2015-01-09 18:16:55.003 [debug] <0.5205.0>@mod_http_bind:process:68 Incoming data: <?xml version="1.0" encoding="UTF-8"?>
<body xmlns="http://jabber.org/protocol/httpbind" content="text/xml charset=utf-8" rid="1634610721" xml:lang="en" sid="a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3"><auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="PLAIN">YW5vbnltb3VzLmM2NTAyYTk1MDY2N2ZjNzljM2UxNTM0NTU5Y2MwMmUwQGxvY2FsaG9zdC83ZjMxN2M3ZmNiYjlmYjFiNzljNDE0YzJkOGFmZTA1YwBhbm9ueW1vdXMuYzY1MDJhOTUwNjY3ZmM3OWMzZTE1MzQ1NTljYzAyZTAANDhlMTNkY2VkYzc1MTY3</auth></body>
2015-01-09 18:16:55.003 [debug] <0.5205.0>@ejabberd_http_bind:parse_request:1128 --- incoming data ---
<?xml version="1.0" encoding="UTF-8"?>
<body xmlns="http://jabber.org/protocol/httpbind" content="text/xml charset=utf-8" rid="1634610721" xml:lang="en" sid="a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3"><auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="PLAIN">YW5vbnltb3VzLmM2NTAyYTk1MDY2N2ZjNzljM2UxNTM0NTU5Y2MwMmUwQGxvY2FsaG9zdC83ZjMxN2M3ZmNiYjlmYjFiNzljNDE0YzJkOGFmZTA1YwBhbm9ueW1vdXMuYzY1MDJhOTUwNjY3ZmM3OWMzZTE1MzQ1NTljYzAyZTAANDhlMTNkY2VkYzc1MTY3</auth></body>
--- END ---
2015-01-09 18:16:55.004 [debug] <0.5205.0>@ejabberd_http_bind:http_put:792 Looking for session: <<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>
2015-01-09 18:16:55.005 [debug] <0.5203.0>@ejabberd_http_bind:handle_sync_event:440 New request: {http_put,1634610721,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"content">>,<<"text/xml charset=utf-8">>},{<<"rid">>,<<"1634610721">>},{<<"xml:lang">>,<<"en">>},{<<"sid">>,<<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>}],[{xmlel,<<"auth">>,[{<<"xmlns">>,<<"urn:ietf:params:xml:ns:xmpp-sasl">>},{<<"mechanism">>,<<"PLAIN">>}],[{xmlcdata,<<"YW5vbnltb3VzLmM2NTAyYTk1MDY2N2ZjNzljM2UxNTM0NTU5Y2MwMmUwQGxvY2FsaG9zdC83ZjMxN2M3ZmNiYjlmYjFiNzljNDE0YzJkOGFmZTA1YwBhbm9ueW1vdXMuYzY1MDJhOTUwNjY3ZmM3OWMzZTE1MzQ1NTljYzAyZTAANDhlMTNkY2VkYzc1MTY3">>}]}],472,1,<<>>,{{0,0,0,0,0,65535,32512,1},58304}}
2015-01-09 18:16:55.005 [debug] <0.5203.0>@ejabberd_http_bind:handle_http_put_event:575 New request: {http_put,1634610721,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"content">>,<<"text/xml charset=utf-8">>},{<<"rid">>,<<"1634610721">>},{<<"xml:lang">>,<<"en">>},{<<"sid">>,<<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>}],[{xmlel,<<"auth">>,[{<<"xmlns">>,<<"urn:ietf:params:xml:ns:xmpp-sasl">>},{<<"mechanism">>,<<"PLAIN">>}],[{xmlcdata,<<"YW5vbnltb3VzLmM2NTAyYTk1MDY2N2ZjNzljM2UxNTM0NTU5Y2MwMmUwQGxvY2FsaG9zdC83ZjMxN2M3ZmNiYjlmYjFiNzljNDE0YzJkOGFmZTA1YwBhbm9ueW1vdXMuYzY1MDJhOTUwNjY3ZmM3OWMzZTE1MzQ1NTljYzAyZTAANDhlMTNkY2VkYzc1MTY3">>}]}],472,1,<<>>,{{0,0,0,0,0,65535,32512,1},58304}}
2015-01-09 18:16:55.005 [debug] <0.5203.0>@ejabberd_http_bind:rid_allow:866 Previous rid / New rid: 1634610720/1634610721
2015-01-09 18:16:55.006 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:608 Actually processing request: {http_put,1634610721,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"content">>,<<"text/xml charset=utf-8">>},{<<"rid">>,<<"1634610721">>},{<<"xml:lang">>,<<"en">>},{<<"sid">>,<<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>}],[{xmlel,<<"auth">>,[{<<"xmlns">>,<<"urn:ietf:params:xml:ns:xmpp-sasl">>},{<<"mechanism">>,<<"PLAIN">>}],[{xmlcdata,<<"YW5vbnltb3VzLmM2NTAyYTk1MDY2N2ZjNzljM2UxNTM0NTU5Y2MwMmUwQGxvY2FsaG9zdC83ZjMxN2M3ZmNiYjlmYjFiNzljNDE0YzJkOGFmZTA1YwBhbm9ueW1vdXMuYzY1MDJhOTUwNjY3ZmM3OWMzZTE1MzQ1NTljYzAyZTAANDhlMTNkY2VkYzc1MTY3">>}]}],472,1,<<>>,{{0,0,0,0,0,65535,32512,1},58304}}
2015-01-09 18:16:55.006 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:659 -- SaveKey:
2015-01-09 18:16:55.007 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:671 reqlist: [{hbr,1634610721,<<>>,[]},{hbr,1634610720,<<>>,[{xmlstreamelement,{xmlel,<<"stream:features">>,[],[{xmlel,<<"mechanisms">>,[{<<"xmlns">>,<<"urn:ietf:params:xml:ns:xmpp-sasl">>}],[{xmlel,<<"mechanism">>,[],[{xmlcdata,<<"PLAIN">>}]}]},{xmlel,<<"c">>,[{<<"xmlns">>,<<"http://jabber.org/protocol/caps">>},{<<"hash">>,<<"sha-1">>},{<<"node">>,<<"http://www.process-one.net/en/ejabberd/">>},{<<"ver">>,<<"/nWL9StXSXhEsL2wg0+s4xo/UdA=">>}],[]},{xmlel,<<"register">>,[{<<"xmlns">>,<<"http://jabber.org/features/iq-register">>}],[]}]}},{xmlstreamstart,<<"stream:stream">>,[{<<"version">>,<<"1.0">>},{<<"xml:lang">>,<<"en">>},{<<"xmlns">>,<<"jabber:client">>},{<<"xmlns:stream">>,<<"http://etherx.jabber.org/streams">>},{<<"id">>,<<"640881963">>},{<<"from">>,<<"localhost">>}]}]}]
2015-01-09 18:16:55.007 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:717 really sending now: [{xmlel,<<"auth">>,[{<<"xmlns">>,<<"urn:ietf:params:xml:ns:xmpp-sasl">>},{<<"mechanism">>,<<"PLAIN">>}],[{xmlcdata,<<"YW5vbnltb3VzLmM2NTAyYTk1MDY2N2ZjNzljM2UxNTM0NTU5Y2MwMmUwQGxvY2FsaG9zdC83ZjMxN2M3ZmNiYjlmYjFiNzljNDE0YzJkOGFmZTA1YwBhbm9ueW1vdXMuYzY1MDJhOTUwNjY3ZmM3OWMzZTE1MzQ1NTljYzAyZTAANDhlMTNkY2VkYzc1MTY3">>}]}]
2015-01-09 18:16:55.037 [debug] <0.2601.0>@extauth:loop:120 extauth call '[<<"auth">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"48e13dcedc75167">>]' received data response:
[0,1]
2015-01-09 18:16:55.038 [info] <0.5204.0>@ejabberd_c2s:wait_for_feature_request:733 ({socket_state,ejabberd_http_bind,{http_bind,<0.5203.0>,{{0,0,0,0,0,65535,32512,1},58302}},ejabberd_http_bind}) Accepted authentication for anonymous.c6502a950667fc79c3e1534559cc02e0 by ejabberd_auth_external
2015-01-09 18:16:55.108 [debug] <0.5205.0>@ejabberd_http_bind:prepare_response:914 OutPacket: [{xmlstreamelement,{xmlel,<<"success">>,[{<<"xmlns">>,<<"urn:ietf:params:xml:ns:xmpp-sasl">>}],[]}}]
2015-01-09 18:16:55.109 [debug] <0.5205.0>@ejabberd_http_bind:send_outpacket:1056 --- outgoing data ---
<body xmlns='http://jabber.org/protocol/httpbind'><success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/></body>
--- END ---
2015-01-09 18:16:55.115 [info] <0.2709.0>@ejabberd_listener:accept:313 (#Port<0.41535>) Accepted connection ::FFFF:127.0.0.1:58307 -> ::FFFF:127.0.0.1:5280
2015-01-09 18:16:55.116 [debug] <0.5206.0>@ejabberd_http:init:145 S: [{[<<"presence">>],mod_webpresence},{[<<"captcha">>],ejabberd_captcha},{[<<"admin">>],ejabberd_web_admin},{[<<"http-bind">>],mod_http_bind},{[<<"http-poll">>],ejabberd_http_poll}]
2015-01-09 18:16:55.117 [info] <0.5206.0>@ejabberd_http:init:149 started: {gen_tcp,#Port<0.41535>}
2015-01-09 18:16:55.117 [debug] <0.5206.0>@ejabberd_http:process_header:277 (#Port<0.41535>) http query: 'POST' /http-bind
2015-01-09 18:16:55.117 [debug] <0.5206.0>@ejabberd_http:process_request:438 client data: <<"<?xml version=\"1.0\" encoding=\"UTF-8\"?>\n<body xmlns=\"http://jabber.org/protocol/httpbind\" content=\"text/xml charset=utf-8\" rid=\"1634610722\" xml:lang=\"en\" sid=\"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3\" to=\"localhost\" xmlns:xmpp=\"urn:xmpp:xbosh\" xmpp:restart=\"true\"/>\n">>
2015-01-09 18:16:55.118 [debug] <0.5206.0>@ejabberd_http:process:350 [<<"http-bind">>] matches [<<"http-bind">>]
2015-01-09 18:16:55.118 [debug] <0.5206.0>@ejabberd_http:process:354 [{'Content-Type',<<"text/xml charset=utf-8">>},{'Accept',<<"*/*">>},{'Content-Length',<<"265">>},{'Connection',<<"close">>},{'Host',<<"localhost">>}]
2015-01-09 18:16:55.118 [debug] <0.5206.0>@mod_http_bind:process:68 Incoming data: <?xml version="1.0" encoding="UTF-8"?>
<body xmlns="http://jabber.org/protocol/httpbind" content="text/xml charset=utf-8" rid="1634610722" xml:lang="en" sid="a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3" to="localhost" xmlns:xmpp="urn:xmpp:xbosh" xmpp:restart="true"/>
2015-01-09 18:16:55.119 [debug] <0.5206.0>@ejabberd_http_bind:parse_request:1128 --- incoming data ---
<?xml version="1.0" encoding="UTF-8"?>
<body xmlns="http://jabber.org/protocol/httpbind" content="text/xml charset=utf-8" rid="1634610722" xml:lang="en" sid="a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3" to="localhost" xmlns:xmpp="urn:xmpp:xbosh" xmpp:restart="true"/>
--- END ---
2015-01-09 18:16:55.119 [debug] <0.5206.0>@ejabberd_http_bind:http_put:792 Looking for session: <<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>
2015-01-09 18:16:55.120 [debug] <0.5203.0>@ejabberd_http_bind:handle_sync_event:440 New request: {http_put,1634610722,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"xmlns:xmpp">>,<<"urn:xmpp:xbosh">>},{<<"content">>,<<"text/xml charset=utf-8">>},{<<"rid">>,<<"1634610722">>},{<<"xml:lang">>,<<"en">>},{<<"sid">>,<<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>},{<<"to">>,<<"localhost">>},{<<"xmpp:restart">>,<<"true">>}],[],265,1,{<<"localhost">>,<<"1.0">>},{{0,0,0,0,0,65535,32512,1},58307}}
2015-01-09 18:16:55.121 [debug] <0.5203.0>@ejabberd_http_bind:handle_http_put_event:575 New request: {http_put,1634610722,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"xmlns:xmpp">>,<<"urn:xmpp:xbosh">>},{<<"content">>,<<"text/xml charset=utf-8">>},{<<"rid">>,<<"1634610722">>},{<<"xml:lang">>,<<"en">>},{<<"sid">>,<<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>},{<<"to">>,<<"localhost">>},{<<"xmpp:restart">>,<<"true">>}],[],265,1,{<<"localhost">>,<<"1.0">>},{{0,0,0,0,0,65535,32512,1},58307}}
2015-01-09 18:16:55.121 [debug] <0.5203.0>@ejabberd_http_bind:rid_allow:866 Previous rid / New rid: 1634610721/1634610722
2015-01-09 18:16:55.122 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:608 Actually processing request: {http_put,1634610722,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"xmlns:xmpp">>,<<"urn:xmpp:xbosh">>},{<<"content">>,<<"text/xml charset=utf-8">>},{<<"rid">>,<<"1634610722">>},{<<"xml:lang">>,<<"en">>},{<<"sid">>,<<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>},{<<"to">>,<<"localhost">>},{<<"xmpp:restart">>,<<"true">>}],[],265,1,{<<"localhost">>,<<"1.0">>},{{0,0,0,0,0,65535,32512,1},58307}}
2015-01-09 18:16:55.122 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:659 -- SaveKey:
2015-01-09 18:16:55.122 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:671 reqlist: [{hbr,1634610722,<<>>,[]},{hbr,1634610721,<<>>,[{xmlstreamelement,{xmlel,<<"success">>,[{<<"xmlns">>,<<"urn:ietf:params:xml:ns:xmpp-sasl">>}],[]}}]}]
2015-01-09 18:16:55.122 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:717 really sending now: []
2015-01-09 18:16:55.225 [debug] <0.5206.0>@ejabberd_http_bind:prepare_response:914 OutPacket: [{xmlstreamstart,<<"stream:stream">>,[{<<"version">>,<<"1.0">>},{<<"xml:lang">>,<<"en">>},{<<"xmlns">>,<<"jabber:client">>},{<<"xmlns:stream">>,<<"http://etherx.jabber.org/streams">>},{<<"id">>,<<"1198743806">>},{<<"from">>,<<"localhost">>}]},{xmlstreamelement,{xmlel,<<"stream:features">>,[],[{xmlel,<<"bind">>,[{<<"xmlns">>,<<"urn:ietf:params:xml:ns:xmpp-bind">>}],[]},{xmlel,<<"session">>,[{<<"xmlns">>,<<"urn:ietf:params:xml:ns:xmpp-session">>}],[]},{xmlel,<<"sm">>,[{<<"xmlns">>,<<"urn:xmpp:sm:2">>}],[]},{xmlel,<<"sm">>,[{<<"xmlns">>,<<"urn:xmpp:sm:3">>}],[]},{xmlel,<<"c">>,[{<<"xmlns">>,<<"http://jabber.org/protocol/caps">>},{<<"hash">>,<<"sha-1">>},{<<"node">>,<<"http://www.process-one.net/en/ejabberd/">>},{<<"ver">>,<<"/nWL9StXSXhEsL2wg0+s4xo/UdA=">>}],[]},{xmlel,<<"register">>,[{<<"xmlns">>,<<"http://jabber.org/features/iq-register">>}],[]}]}}]
2015-01-09 18:16:55.233 [info] <0.2709.0>@ejabberd_listener:accept:313 (#Port<0.41537>) Accepted connection ::FFFF:127.0.0.1:58309 -> ::FFFF:127.0.0.1:5280
2015-01-09 18:16:55.234 [debug] <0.5207.0>@ejabberd_http:init:145 S: [{[<<"presence">>],mod_webpresence},{[<<"captcha">>],ejabberd_captcha},{[<<"admin">>],ejabberd_web_admin},{[<<"http-bind">>],mod_http_bind},{[<<"http-poll">>],ejabberd_http_poll}]
2015-01-09 18:16:55.235 [info] <0.5207.0>@ejabberd_http:init:149 started: {gen_tcp,#Port<0.41537>}
2015-01-09 18:16:55.235 [debug] <0.5207.0>@ejabberd_http:process_header:277 (#Port<0.41537>) http query: 'POST' /http-bind
2015-01-09 18:16:55.235 [debug] <0.5207.0>@ejabberd_http:process_request:438 client data: <<"<?xml version=\"1.0\" encoding=\"UTF-8\"?>\n<body xmlns=\"http://jabber.org/protocol/httpbind\" content=\"text/xml charset=utf-8\" rid=\"1634610723\" xml:lang=\"en\" sid=\"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3\"><iq xmlns=\"jabber:client\" type=\"set\" id=\"bind_1591054881\"><bind xmlns=\"urn:ietf:params:xml:ns:xmpp-bind\"><resource>7f317c7fcbb9fb1b79c414c2d8afe05c</resource></bind></iq></body>\n">>
2015-01-09 18:16:55.236 [debug] <0.5207.0>@ejabberd_http:process:350 [<<"http-bind">>] matches [<<"http-bind">>]
2015-01-09 18:16:55.236 [debug] <0.5207.0>@ejabberd_http:process:354 [{'Content-Type',<<"text/xml charset=utf-8">>},{'Accept',<<"*/*">>},{'Content-Length',<<"378">>},{'Connection',<<"close">>},{'Host',<<"localhost">>}]
2015-01-09 18:16:55.236 [debug] <0.5207.0>@mod_http_bind:process:68 Incoming data: <?xml version="1.0" encoding="UTF-8"?>
<body xmlns="http://jabber.org/protocol/httpbind" content="text/xml charset=utf-8" rid="1634610723" xml:lang="en" sid="a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3"><iq xmlns="jabber:client" type="set" id="bind_1591054881"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource>7f317c7fcbb9fb1b79c414c2d8afe05c</resource></bind></iq></body>
2015-01-09 18:16:55.237 [debug] <0.5207.0>@ejabberd_http_bind:parse_request:1128 --- incoming data ---
<?xml version="1.0" encoding="UTF-8"?>
<body xmlns="http://jabber.org/protocol/httpbind" content="text/xml charset=utf-8" rid="1634610723" xml:lang="en" sid="a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3"><iq xmlns="jabber:client" type="set" id="bind_1591054881"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource>7f317c7fcbb9fb1b79c414c2d8afe05c</resource></bind></iq></body>
--- END ---
2015-01-09 18:16:55.237 [debug] <0.5207.0>@ejabberd_http_bind:http_put:792 Looking for session: <<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>
2015-01-09 18:16:55.238 [debug] <0.5203.0>@ejabberd_http_bind:handle_sync_event:440 New request: {http_put,1634610723,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"content">>,<<"text/xml charset=utf-8">>},{<<"rid">>,<<"1634610723">>},{<<"xml:lang">>,<<"en">>},{<<"sid">>,<<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>}],[{xmlel,<<"iq">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"type">>,<<"set">>},{<<"id">>,<<"bind_1591054881">>}],[{xmlel,<<"bind">>,[{<<"xmlns">>,<<"urn:ietf:params:xml:ns:xmpp-bind">>}],[{xmlel,<<"resource">>,[],[{xmlcdata,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}]}]}]}],378,1,<<>>,{{0,0,0,0,0,65535,32512,1},58309}}
2015-01-09 18:16:55.238 [debug] <0.5203.0>@ejabberd_http_bind:handle_http_put_event:575 New request: {http_put,1634610723,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"content">>,<<"text/xml charset=utf-8">>},{<<"rid">>,<<"1634610723">>},{<<"xml:lang">>,<<"en">>},{<<"sid">>,<<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>}],[{xmlel,<<"iq">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"type">>,<<"set">>},{<<"id">>,<<"bind_1591054881">>}],[{xmlel,<<"bind">>,[{<<"xmlns">>,<<"urn:ietf:params:xml:ns:xmpp-bind">>}],[{xmlel,<<"resource">>,[],[{xmlcdata,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}]}]}]}],378,1,<<>>,{{0,0,0,0,0,65535,32512,1},58309}}
2015-01-09 18:16:55.239 [debug] <0.5203.0>@ejabberd_http_bind:rid_allow:866 Previous rid / New rid: 1634610722/1634610723
2015-01-09 18:16:55.239 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:608 Actually processing request: {http_put,1634610723,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"content">>,<<"text/xml charset=utf-8">>},{<<"rid">>,<<"1634610723">>},{<<"xml:lang">>,<<"en">>},{<<"sid">>,<<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>}],[{xmlel,<<"iq">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"type">>,<<"set">>},{<<"id">>,<<"bind_1591054881">>}],[{xmlel,<<"bind">>,[{<<"xmlns">>,<<"urn:ietf:params:xml:ns:xmpp-bind">>}],[{xmlel,<<"resource">>,[],[{xmlcdata,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}]}]}]}],378,1,<<>>,{{0,0,0,0,0,65535,32512,1},58309}}
2015-01-09 18:16:55.239 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:659 -- SaveKey:
2015-01-09 18:16:55.240 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:671 reqlist: [{hbr,1634610723,<<>>,[]},{hbr,1634610722,<<>>,[{xmlstreamelement,{xmlel,<<"stream:features">>,[],[{xmlel,<<"bind">>,[{<<"xmlns">>,<<"urn:ietf:params:xml:ns:xmpp-bind">>}],[]},{xmlel,<<"session">>,[{<<"xmlns">>,<<"urn:ietf:params:xml:ns:xmpp-session">>}],[]},{xmlel,<<"sm">>,[{<<"xmlns">>,<<"urn:xmpp:sm:2">>}],[]},{xmlel,<<"sm">>,[{<<"xmlns">>,<<"urn:xmpp:sm:3">>}],[]},{xmlel,<<"c">>,[{<<"xmlns">>,<<"http://jabber.org/protocol/caps">>},{<<"hash">>,<<"sha-1">>},{<<"node">>,<<"http://www.process-one.net/en/ejabberd/">>},{<<"ver">>,<<"/nWL9StXSXhEsL2wg0+s4xo/UdA=">>}],[]},{xmlel,<<"register">>,[{<<"xmlns">>,<<"http://jabber.org/features/iq-register">>}],[]}]}},{xmlstreamstart,<<"stream:stream">>,[{<<"version">>,<<"1.0">>},{<<"xml:lang">>,<<"en">>},{<<"xmlns">>,<<"jabber:client">>},{<<"xmlns:stream">>,<<"http://etherx.jabber.org/streams">>},{<<"id">>,<<"1198743806">>},{<<"from">>,<<"localhost">>}]}]}]
2015-01-09 18:16:55.241 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:717 really sending now: [{xmlel,<<"iq">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"type">>,<<"set">>},{<<"id">>,<<"bind_1591054881">>}],[{xmlel,<<"bind">>,[{<<"xmlns">>,<<"urn:ietf:params:xml:ns:xmpp-bind">>}],[{xmlel,<<"resource">>,[],[{xmlcdata,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}]}]}]}]
2015-01-09 18:16:55.342 [debug] <0.5207.0>@ejabberd_http_bind:prepare_response:914 OutPacket: [{xmlstreamelement,{xmlel,<<"iq">>,[{<<"id">>,<<"bind_1591054881">>},{<<"type">>,<<"result">>}],[{xmlel,<<"bind">>,[{<<"xmlns">>,<<"urn:ietf:params:xml:ns:xmpp-bind">>}],[{xmlel,<<"jid">>,[],[{xmlcdata,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@localhost/7f317c7fcbb9fb1b79c414c2d8afe05c">>}]}]}]}}]
2015-01-09 18:16:55.342 [debug] <0.5207.0>@ejabberd_http_bind:send_outpacket:1056 --- outgoing data ---
<body xmlns='http://jabber.org/protocol/httpbind'><iq xmlns='jabber:client' id='bind_1591054881' type='result'><bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'><jid>anonymous.c6502a950667fc79c3e1534559cc02e0@localhost/7f317c7fcbb9fb1b79c414c2d8afe05c</jid></bind></iq></body>
--- END ---
2015-01-09 18:16:55.349 [info] <0.2709.0>@ejabberd_listener:accept:313 (#Port<0.41539>) Accepted connection ::FFFF:127.0.0.1:58311 -> ::FFFF:127.0.0.1:5280
2015-01-09 18:16:55.350 [debug] <0.5208.0>@ejabberd_http:init:145 S: [{[<<"presence">>],mod_webpresence},{[<<"captcha">>],ejabberd_captcha},{[<<"admin">>],ejabberd_web_admin},{[<<"http-bind">>],mod_http_bind},{[<<"http-poll">>],ejabberd_http_poll}]
2015-01-09 18:16:55.350 [info] <0.5208.0>@ejabberd_http:init:149 started: {gen_tcp,#Port<0.41539>}
2015-01-09 18:16:55.351 [debug] <0.5208.0>@ejabberd_http:process_header:277 (#Port<0.41539>) http query: 'POST' /http-bind
2015-01-09 18:16:55.351 [debug] <0.5208.0>@ejabberd_http:process_request:438 client data: <<"<?xml version=\"1.0\" encoding=\"UTF-8\"?>\n<body xmlns=\"http://jabber.org/protocol/httpbind\" content=\"text/xml charset=utf-8\" rid=\"1634610724\" xml:lang=\"en\" sid=\"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3\"><iq xmlns=\"jabber:client\" type=\"set\" id=\"session_auth_1164611163\"><session xmlns=\"urn:ietf:params:xml:ns:xmpp-session\"/></iq></body>\n">>
2015-01-09 18:16:55.351 [debug] <0.5208.0>@ejabberd_http:process:350 [<<"http-bind">>] matches [<<"http-bind">>]
2015-01-09 18:16:55.351 [debug] <0.5208.0>@ejabberd_http:process:354 [{'Content-Type',<<"text/xml charset=utf-8">>},{'Accept',<<"*/*">>},{'Content-Length',<<"333">>},{'Connection',<<"close">>},{'Host',<<"localhost">>}]
2015-01-09 18:16:55.351 [debug] <0.5208.0>@mod_http_bind:process:68 Incoming data: <?xml version="1.0" encoding="UTF-8"?>
<body xmlns="http://jabber.org/protocol/httpbind" content="text/xml charset=utf-8" rid="1634610724" xml:lang="en" sid="a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3"><iq xmlns="jabber:client" type="set" id="session_auth_1164611163"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq></body>
2015-01-09 18:16:55.351 [debug] <0.5208.0>@ejabberd_http_bind:parse_request:1128 --- incoming data ---
<?xml version="1.0" encoding="UTF-8"?>
<body xmlns="http://jabber.org/protocol/httpbind" content="text/xml charset=utf-8" rid="1634610724" xml:lang="en" sid="a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3"><iq xmlns="jabber:client" type="set" id="session_auth_1164611163"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq></body>
--- END ---
2015-01-09 18:16:55.351 [debug] <0.5208.0>@ejabberd_http_bind:http_put:792 Looking for session: <<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>
2015-01-09 18:16:55.352 [debug] <0.5203.0>@ejabberd_http_bind:handle_sync_event:440 New request: {http_put,1634610724,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"content">>,<<"text/xml charset=utf-8">>},{<<"rid">>,<<"1634610724">>},{<<"xml:lang">>,<<"en">>},{<<"sid">>,<<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>}],[{xmlel,<<"iq">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"type">>,<<"set">>},{<<"id">>,<<"session_auth_1164611163">>}],[{xmlel,<<"session">>,[{<<"xmlns">>,<<"urn:ietf:params:xml:ns:xmpp-session">>}],[]}]}],333,1,<<>>,{{0,0,0,0,0,65535,32512,1},58311}}
2015-01-09 18:16:55.352 [debug] <0.5203.0>@ejabberd_http_bind:handle_http_put_event:575 New request: {http_put,1634610724,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"content">>,<<"text/xml charset=utf-8">>},{<<"rid">>,<<"1634610724">>},{<<"xml:lang">>,<<"en">>},{<<"sid">>,<<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>}],[{xmlel,<<"iq">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"type">>,<<"set">>},{<<"id">>,<<"session_auth_1164611163">>}],[{xmlel,<<"session">>,[{<<"xmlns">>,<<"urn:ietf:params:xml:ns:xmpp-session">>}],[]}]}],333,1,<<>>,{{0,0,0,0,0,65535,32512,1},58311}}
2015-01-09 18:16:55.352 [debug] <0.5203.0>@ejabberd_http_bind:rid_allow:866 Previous rid / New rid: 1634610723/1634610724
2015-01-09 18:16:55.353 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:608 Actually processing request: {http_put,1634610724,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"content">>,<<"text/xml charset=utf-8">>},{<<"rid">>,<<"1634610724">>},{<<"xml:lang">>,<<"en">>},{<<"sid">>,<<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>}],[{xmlel,<<"iq">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"type">>,<<"set">>},{<<"id">>,<<"session_auth_1164611163">>}],[{xmlel,<<"session">>,[{<<"xmlns">>,<<"urn:ietf:params:xml:ns:xmpp-session">>}],[]}]}],333,1,<<>>,{{0,0,0,0,0,65535,32512,1},58311}}
2015-01-09 18:16:55.353 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:659 -- SaveKey:
2015-01-09 18:16:55.353 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:671 reqlist: [{hbr,1634610724,<<>>,[]},{hbr,1634610723,<<>>,[{xmlstreamelement,{xmlel,<<"iq">>,[{<<"id">>,<<"bind_1591054881">>},{<<"type">>,<<"result">>}],[{xmlel,<<"bind">>,[{<<"xmlns">>,<<"urn:ietf:params:xml:ns:xmpp-bind">>}],[{xmlel,<<"jid">>,[],[{xmlcdata,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@localhost/7f317c7fcbb9fb1b79c414c2d8afe05c">>}]}]}]}}]}]
2015-01-09 18:16:55.353 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:717 really sending now: [{xmlel,<<"iq">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"type">>,<<"set">>},{<<"id">>,<<"session_auth_1164611163">>}],[{xmlel,<<"session">>,[{<<"xmlns">>,<<"urn:ietf:params:xml:ns:xmpp-session">>}],[]}]}]
2015-01-09 18:16:55.353 [info] <0.5204.0>@ejabberd_c2s:wait_for_session:1079 ({socket_state,ejabberd_http_bind,{http_bind,<0.5203.0>,{{0,0,0,0,0,65535,32512,1},58302}},ejabberd_http_bind}) Opened session for anonymous.c6502a950667fc79c3e1534559cc02e0@localhost/7f317c7fcbb9fb1b79c414c2d8afe05c
2015-01-09 18:16:55.454 [debug] <0.5208.0>@ejabberd_http_bind:prepare_response:914 OutPacket: [{xmlstreamelement,{xmlel,<<"iq">>,[{<<"type">>,<<"result">>},{<<"xmlns">>,<<"jabber:client">>},{<<"id">>,<<"session_auth_1164611163">>}],[]}}]
2015-01-09 18:16:55.455 [debug] <0.5208.0>@ejabberd_http_bind:send_outpacket:1056 --- outgoing data ---
<body xmlns='http://jabber.org/protocol/httpbind'><iq type='result' xmlns='jabber:client' id='session_auth_1164611163'/></body>
--- END ---
2015-01-09 18:16:55.874 [info] <0.2709.0>@ejabberd_listener:accept:313 (#Port<0.41541>) Accepted connection ::FFFF:127.0.0.1:58312 -> ::FFFF:127.0.0.1:5280
2015-01-09 18:16:55.875 [debug] <0.5209.0>@ejabberd_http:init:145 S: [{[<<"presence">>],mod_webpresence},{[<<"captcha">>],ejabberd_captcha},{[<<"admin">>],ejabberd_web_admin},{[<<"http-bind">>],mod_http_bind},{[<<"http-poll">>],ejabberd_http_poll}]
2015-01-09 18:16:55.875 [info] <0.5209.0>@ejabberd_http:init:149 started: {gen_tcp,#Port<0.41541>}
2015-01-09 18:16:55.875 [debug] <0.5209.0>@ejabberd_http:process_header:277 (#Port<0.41541>) http query: 'POST' /http-bind
2015-01-09 18:16:55.875 [debug] <0.5209.0>@ejabberd_http:process_request:438 client data: <<"<body rid='1634610725' sid='a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3' xmlns='http://jabber.org/protocol/httpbind'><presence xmlns=\"jabber:client\"/><presence xmlns=\"jabber:client\" to=\"bc1234@jaxlecho.localhost\" type=\"subscribe\"/><message xmlns=\"jabber:client\" to=\"bc1234@jaxlecho.localhost\"><active xmlns=\"http://jabber.org/protocol/chatstates\"/></message></body>">>
2015-01-09 18:16:55.876 [debug] <0.5209.0>@ejabberd_http:process:350 [<<"http-bind">>] matches [<<"http-bind">>]
2015-01-09 18:16:55.877 [debug] <0.5209.0>@ejabberd_http:process:354 [{'Cookie',<<"_cb_secure=%7B%2254ac093c612c34.56437777%22%3A%7B%22_cbtt%22%3A%2254628a0d7e4e9086e015ec69f281ed2195c9ca304ff43288b2578b494584bd76%22%7D%7D; _cb_settings=%7B%2254ac093c612c34.56437777%22%3A%7B%22_cbchat%22%3A%7B%22session%22%3A%7B%22jid%22%3A%22anonymous.c6502a950667fc79c3e1534559cc02e0%40localhost%2F7f317c7fcbb9fb1b79c414c2d8afe05c%22%2C%22sid%22%3A%22a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3%22%2C%22rid%22%3A1634610724%7D%2C%22groupJid%22%3A%22bc1234%40jaxlecho.localhost%22%2C%22messages%22%3A%5B%5D%7D%7D%7D; _cb_analytics=null; _cb_tracking=%7B%2254ac093c612c34.56437777%22%3A%7B%22trackingOn%22%3A1%2C%22_cbs%22%3A%22b62d40341f950986066a9b28f911ba14326101ab%22%2C%22_cbv%22%3A%2297a71b9487d1b8447b00f274d3923d37d8c006d7%22%7D%7D">>},{'Accept-Language',<<"en-US,en;q=0.8,es;q=0.6,und;q=0.4,nl;q=0.2,ca;q=0.2">>},{'Accept-Encoding',<<"gzip, deflate">>},{'Accept',<<"*/*">>},{'Content-Type',<<"text/xml; charset=UTF-8">>},{'User-Agent',<<"Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/39.0.2171.95 Safari/537.36">>},{<<"Origin">>,<<"https://vagrant.localhost">>},{'Cache-Control',<<"no-cache">>},{'Pragma',<<"no-cache">>},{'Content-Length',<<"362">>},{'Connection',<<"close">>},{'Host',<<"vagrant.localhost">>}]
2015-01-09 18:16:55.877 [debug] <0.5209.0>@mod_http_bind:process:68 Incoming data: <body rid='1634610725' sid='a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3' xmlns='http://jabber.org/protocol/httpbind'><presence xmlns="jabber:client"/><presence xmlns="jabber:client" to="bc1234@jaxlecho.localhost" type="subscribe"/><message xmlns="jabber:client" to="bc1234@jaxlecho.localhost"><active xmlns="http://jabber.org/protocol/chatstates"/></message></body>
2015-01-09 18:16:55.877 [debug] <0.5209.0>@ejabberd_http_bind:parse_request:1128 --- incoming data ---
<body rid='1634610725' sid='a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3' xmlns='http://jabber.org/protocol/httpbind'><presence xmlns="jabber:client"/><presence xmlns="jabber:client" to="bc1234@jaxlecho.localhost" type="subscribe"/><message xmlns="jabber:client" to="bc1234@jaxlecho.localhost"><active xmlns="http://jabber.org/protocol/chatstates"/></message></body>
--- END ---
2015-01-09 18:16:55.877 [debug] <0.5209.0>@ejabberd_http_bind:http_put:792 Looking for session: <<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>
2015-01-09 18:16:55.878 [debug] <0.5203.0>@ejabberd_http_bind:handle_sync_event:440 New request: {http_put,1634610725,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"1634610725">>},{<<"sid">>,<<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>}],[{xmlel,<<"presence">>,[{<<"xmlns">>,<<"jabber:client">>}],[]},{xmlel,<<"presence">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"to">>,<<"bc1234@jaxlecho.localhost">>},{<<"type">>,<<"subscribe">>}],[]},{xmlel,<<"message">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"to">>,<<"bc1234@jaxlecho.localhost">>}],[{xmlel,<<"active">>,[{<<"xmlns">>,<<"http://jabber.org/protocol/chatstates">>}],[]}]}],362,1,<<>>,{{0,0,0,0,0,65535,32512,1},58312}}
2015-01-09 18:16:55.878 [debug] <0.5203.0>@ejabberd_http_bind:handle_http_put_event:575 New request: {http_put,1634610725,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"1634610725">>},{<<"sid">>,<<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>}],[{xmlel,<<"presence">>,[{<<"xmlns">>,<<"jabber:client">>}],[]},{xmlel,<<"presence">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"to">>,<<"bc1234@jaxlecho.localhost">>},{<<"type">>,<<"subscribe">>}],[]},{xmlel,<<"message">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"to">>,<<"bc1234@jaxlecho.localhost">>}],[{xmlel,<<"active">>,[{<<"xmlns">>,<<"http://jabber.org/protocol/chatstates">>}],[]}]}],362,1,<<>>,{{0,0,0,0,0,65535,32512,1},58312}}
2015-01-09 18:16:55.879 [debug] <0.5203.0>@ejabberd_http_bind:rid_allow:866 Previous rid / New rid: 1634610724/1634610725
2015-01-09 18:16:55.879 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:608 Actually processing request: {http_put,1634610725,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"1634610725">>},{<<"sid">>,<<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>}],[{xmlel,<<"presence">>,[{<<"xmlns">>,<<"jabber:client">>}],[]},{xmlel,<<"presence">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"to">>,<<"bc1234@jaxlecho.localhost">>},{<<"type">>,<<"subscribe">>}],[]},{xmlel,<<"message">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"to">>,<<"bc1234@jaxlecho.localhost">>}],[{xmlel,<<"active">>,[{<<"xmlns">>,<<"http://jabber.org/protocol/chatstates">>}],[]}]}],362,1,<<>>,{{0,0,0,0,0,65535,32512,1},58312}}
2015-01-09 18:16:55.879 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:659 -- SaveKey:
2015-01-09 18:16:55.879 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:671 reqlist: [{hbr,1634610725,<<>>,[]},{hbr,1634610724,<<>>,[{xmlstreamelement,{xmlel,<<"iq">>,[{<<"type">>,<<"result">>},{<<"xmlns">>,<<"jabber:client">>},{<<"id">>,<<"session_auth_1164611163">>}],[]}}]}]
2015-01-09 18:16:55.880 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:717 really sending now: [{xmlel,<<"presence">>,[{<<"xmlns">>,<<"jabber:client">>}],[]},{xmlel,<<"presence">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"to">>,<<"bc1234@jaxlecho.localhost">>},{<<"type">>,<<"subscribe">>}],[]},{xmlel,<<"message">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"to">>,<<"bc1234@jaxlecho.localhost">>}],[{xmlel,<<"active">>,[{<<"xmlns">>,<<"http://jabber.org/protocol/chatstates">>}],[]}]}]
2015-01-09 18:16:55.880 [debug] <0.5204.0>@ejabberd_c2s:session_established2:1222 presence_update({jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>},
{xmlel,<<"presence">>,[],[]},
{state,{socket_state,ejabberd_http_bind,{http_bind,<0.5203.0>,{{0,0,0,0,0,65535,32512,1},58302}},ejabberd_http_bind},ejabberd_socket,#Ref<0.0.0.143551>,true,<<"1198743806">>,undefined,all,none,false,false,false,false,[verify_none,compression_none],true,{jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>},<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,{{1420,823814,882170},<0.5204.0>},{2,{{<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>},{{<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>},nil,nil},nil}},{2,{{<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>},{{<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>},nil,nil},nil}},{0,nil},{0,nil},undefined,undefined,undefined,false,{userlist,none,[],false},http_bind,ejabberd_auth_external,{{0,0,0,0,0,65535,32512,1},58302},[],inactive,undefined,undefined,500,undefined,300,false,0,0,<<>>})
2015-01-09 18:16:55.881 [debug] <0.5204.0>@ejabberd_c2s:presence_update:2041 from unavail = true
2015-01-09 18:16:55.881 [debug] <0.5204.0>@mod_shared_roster:user_available:1062 user_available for <<"anonymous.c6502a950667fc79c3e1534559cc02e0">> @ <<"localhost">> (1 resources)
2015-01-09 18:16:55.883 [debug] <0.5204.0>@ejabberd_router:do_route:322 route
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}
to {jid,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"type">>,<<"probe">>}],[]}
2015-01-09 18:16:55.884 [debug] <0.5204.0>@ejabberd_router:do_route:322 route
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}
to {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"type">>,<<"probe">>}],[]}
2015-01-09 18:16:55.885 [debug] <0.5204.0>@ejabberd_local:do_route:296 local route
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}
to {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"type">>,<<"prob"...>>}],[]}
2015-01-09 18:16:55.886 [debug] <0.5204.0>@ejabberd_sm:do_route:514 session manager
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}
to {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"type">>,<<"prob"...>>}],[]}
2015-01-09 18:16:55.887 [debug] <0.5204.0>@ejabberd_sm:do_route:514 session manager
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}
to {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}
packet {xmlel,<<"presence">>,[{<<"type">>,<<"prob"...>>}],[]}
2015-01-09 18:16:55.888 [debug] <0.5204.0>@ejabberd_sm:do_route:612 sending to process <0.5204.0>
2015-01-09 18:16:55.888 [debug] <0.5204.0>@ejabberd_router:do_route:322 route
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}
to {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[],[]}
2015-01-09 18:16:55.889 [debug] <0.5204.0>@ejabberd_local:do_route:296 local route
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}
to {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[],[]}
2015-01-09 18:16:55.889 [debug] <0.5204.0>@ejabberd_sm:do_route:514 session manager
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}
to {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[],[]}
2015-01-09 18:16:55.890 [debug] <0.5204.0>@ejabberd_sm:do_route:514 session manager
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}
to {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}
packet {xmlel,<<"presence">>,[],[]}
2015-01-09 18:16:55.890 [debug] <0.5204.0>@ejabberd_sm:do_route:612 sending to process <0.5204.0>
2015-01-09 18:16:55.890 [debug] <0.5204.0>@ejabberd_router:do_route:322 route
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}
to {jid,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>}
packet {xmlel,<<"presence">>,[],[]}
2015-01-09 18:16:55.891 [debug] <0.5204.0>@ejabberd_router:do_route:322 route
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>}
to {jid,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"to">>,<<"bc1234@jaxlecho.localhost">>},{<<"type">>,<<"subscribe">>}],[]}
2015-01-09 18:16:55.892 [debug] <0.5204.0>@ejabberd_router:do_route:322 route
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}
to {jid,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>}
packet {xmlel,<<"message">>,[{<<"to">>,<<"bc1234@jaxlecho.localhost">>}],[{xmlel,<<"active">>,[{<<"xmlns">>,<<"http://jabber.org/protocol/chatstates">>}],[]}]}
2015-01-09 18:16:55.893 [debug] <0.2672.0>@mod_pubsub:node_action:5143 node_action <<"pubsub.localhost">> <<"flat">> get_entity_subscriptions [<<"pubsub.localhost">>,{jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}]
2015-01-09 18:16:55.894 [debug] <0.2672.0>@mod_pubsub:node_call:5124 node_call <<"flat">> get_entity_subscriptions [<<"pubsub.localhost">>,{jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}]
2015-01-09 18:16:55.894 [debug] <0.2672.0>@mod_pubsub:node_action:5143 node_action <<"pubsub.localhost">> <<"hometree">> get_entity_subscriptions [<<"pubsub.localhost">>,{jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}]
2015-01-09 18:16:55.894 [debug] <0.2672.0>@mod_pubsub:node_call:5124 node_call <<"hometree">> get_entity_subscriptions [<<"pubsub.localhost">>,{jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}]
2015-01-09 18:16:55.895 [debug] <0.2672.0>@mod_pubsub:node_action:5143 node_action <<"pubsub.localhost">> <<"pep">> get_entity_subscriptions [<<"pubsub.localhost">>,{jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}]
2015-01-09 18:16:55.895 [debug] <0.2672.0>@mod_pubsub:node_call:5124 node_call <<"pep">> get_entity_subscriptions [<<"pubsub.localhost">>,{jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}]
2015-01-09 18:16:55.895 [debug] <0.5210.0>@mod_pubsub:tree_action:5118 tree_action <<"pubsub.localhost">> get_nodes [{<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>},{jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}]
2015-01-09 18:16:55.895 [debug] <0.5210.0>@mod_pubsub:tree_call:5105 tree_call <<"pubsub.localhost">> get_nodes [{<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>},{jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}]
2015-01-09 18:16:55.907 [debug] <0.2710.0>@ejabberd_receiver:process_data:343 Received XML on stream = <<"<presence to=\"agent1@localhost\" from=\"anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost\" type=\"available\"/><presence from=\"anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost\" type=\"available\"/>">>
2015-01-09 18:16:55.907 [debug] <0.2710.0>@shaper:update:117 State: {maxrate,50000,607.6339548095515,1420823791952617}, Size=216
M=2.173205132291467, I=23954.812
2015-01-09 18:16:55.908 [debug] <0.2711.0>@ejabberd_router:do_route:322 route
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<>>,<<"agent1">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"to">>,<<"agent1@localhost">>},{<<"from">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost">>},{<<"type">>,<<"available">>}],[]}
2015-01-09 18:16:55.908 [debug] <0.2711.0>@ejabberd_local:do_route:296 local route
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<>>,<<"agent1">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"to">>,<<"agen"...>>},{<<"from">>,<<...>>},{<<...>>,...}],[]}
2015-01-09 18:16:55.908 [debug] <0.2711.0>@ejabberd_sm:do_route:514 session manager
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<>>,<<"agent1">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"to">>,<<"agen"...>>},{<<"from">>,<<...>>},{<<...>>,...}],[]}
2015-01-09 18:16:55.908 [debug] <0.2711.0>@ejabberd_sm:do_route:514 session manager
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>}
packet {xmlel,<<"presence">>,[{<<"to">>,<<"agen"...>>},{<<"from">>,<<...>>},{<<...>>,...}],[]}
2015-01-09 18:16:55.908 [debug] <0.2711.0>@ejabberd_sm:do_route:612 sending to process <0.4053.0>
2015-01-09 18:16:55.909 [debug] <0.5199.0>@ejabberd_http_bind:prepare_response:914 OutPacket: [{xmlstreamelement,{xmlel,<<"presence">>,[{<<"from">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost">>},{<<"to">>,<<"agent1@localhost/cb_operator_1420818758533">>},{<<"type">>,<<"available">>}],[]}}]
2015-01-09 18:16:55.909 [debug] <0.5199.0>@ejabberd_http_bind:send_outpacket:1056 --- outgoing data ---
<body xmlns='http://jabber.org/protocol/httpbind'><presence xmlns='jabber:client' from='anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost' to='agent1@localhost/cb_operator_1420818758533' type='available'/></body>
--- END ---
2015-01-09 18:16:55.912 [debug] <0.2710.0>@ejabberd_receiver:process_data:343 Received XML on stream = <<"<message to=\"agent1@localhost\" from=\"anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost\"><active xmlns=\"http://jabber.org/protocol/chatstates\"/></message>">>
2015-01-09 18:16:55.913 [debug] <0.2710.0>@shaper:update:117 State: {maxrate,50000,308.32506171480156,1420823815909578}, Size=165
M=1.6551030976472645, I=3.504
2015-01-09 18:16:55.913 [debug] <0.2711.0>@ejabberd_router:do_route:322 route
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<>>,<<"agent1">>,<<"localhost">>,<<>>}
packet {xmlel,<<"message">>,[{<<"to">>,<<"agent1@localhost">>},{<<"from">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost">>}],[{xmlel,<<"active">>,[{<<"xmlns">>,<<"http://jabber.org/protocol/chatstates">>}],[]}]}
2015-01-09 18:16:55.913 [debug] <0.2711.0>@ejabberd_local:do_route:296 local route
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<>>,<<"agent1">>,<<"localhost">>,<<>>}
packet {xmlel,<<"message">>,[{<<"to">>,<<"agen"...>>},{<<"from">>,<<...>>}],[{xmlel,<<...>>,...}]}
2015-01-09 18:16:55.913 [debug] <0.2711.0>@ejabberd_sm:do_route:514 session manager
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<>>,<<"agent1">>,<<"localhost">>,<<>>}
packet {xmlel,<<"message">>,[{<<"to">>,<<"agen"...>>},{<<"from">>,<<...>>}],[{xmlel,<<...>>,...}]}
2015-01-09 18:16:55.914 [debug] <0.2711.0>@ejabberd_sm:route_message:658 sending to process <0.4053.0>
2015-01-09 18:16:55.948 [debug] <0.2710.0>@ejabberd_receiver:process_data:343 Received XML on stream = <<"<presence to=\"anonymous.c6502a950667fc79c3e1534559cc02e0@localhost\" from=\"bc1234@jaxlecho.localhost\" type=\"subscribed\"/><presence to=\"anonymous.c6502a950667fc79c3e1534559cc02e0@localhost\" from=\"bc1234@jaxlecho.localhost\" type=\"available\"/><presence to=\"anonymous.c6502a950667fc79c3e1534559cc02e0@localhost\" from=\"bc1234@jaxlecho.localhost\" type=\"subscribe\"/>">>
2015-01-09 18:16:55.949 [debug] <0.2710.0>@shaper:update:117 State: {maxrate,50000,23045.39449534131,1420823815913182}, Size=358
M=4.65209323928413, I=36.001
2015-01-09 18:16:55.949 [debug] <0.2711.0>@ejabberd_router:do_route:322 route
from {jid,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"to">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@localhost">>},{<<"from">>,<<"bc1234@jaxlecho.localhost">>},{<<"type">>,<<"subscribed">>}],[]}
2015-01-09 18:16:55.949 [debug] <0.2711.0>@ejabberd_local:do_route:296 local route
from {jid,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"to">>,<<"anon"...>>},{<<"from">>,<<...>>},{<<...>>,...}],[]}
2015-01-09 18:16:55.950 [debug] <0.2711.0>@ejabberd_sm:do_route:514 session manager
from {jid,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"to">>,<<"anon"...>>},{<<"from">>,<<...>>},{<<...>>,...}],[]}
2015-01-09 18:16:55.959 [debug] <0.2601.0>@extauth:loop:120 extauth call '[<<"isuser">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>]' received data response:
[0,1]
2015-01-09 18:16:55.960 [debug] <0.2711.0>@ejabberd_router:do_route:322 route
from {jid,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"to">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@localhost">>},{<<"from">>,<<"bc1234@jaxlecho.localhost">>},{<<"type">>,<<"available">>}],[]}
2015-01-09 18:16:55.960 [debug] <0.2711.0>@ejabberd_local:do_route:296 local route
from {jid,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"to">>,<<"anon"...>>},{<<"from">>,<<...>>},{<<...>>,...}],[]}
2015-01-09 18:16:55.960 [debug] <0.2711.0>@ejabberd_sm:do_route:514 session manager
from {jid,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"to">>,<<"anon"...>>},{<<"from">>,<<...>>},{<<...>>,...}],[]}
2015-01-09 18:16:55.960 [debug] <0.2711.0>@ejabberd_sm:do_route:514 session manager
from {jid,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}
packet {xmlel,<<"presence">>,[{<<"to">>,<<"anon"...>>},{<<"from">>,<<...>>},{<<...>>,...}],[]}
2015-01-09 18:16:55.960 [debug] <0.2711.0>@ejabberd_sm:do_route:612 sending to process <0.5204.0>
2015-01-09 18:16:55.961 [debug] <0.2711.0>@ejabberd_router:do_route:322 route
from {jid,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"to">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@localhost">>},{<<"from">>,<<"bc1234@jaxlecho.localhost">>},{<<"type">>,<<"subscribe">>}],[]}
2015-01-09 18:16:55.961 [debug] <0.2711.0>@ejabberd_local:do_route:296 local route
from {jid,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"to">>,<<"anon"...>>},{<<"from">>,<<...>>},{<<...>>,...}],[]}
2015-01-09 18:16:55.961 [debug] <0.2711.0>@ejabberd_sm:do_route:514 session manager
from {jid,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"to">>,<<"anon"...>>},{<<"from">>,<<...>>},{<<...>>,...}],[]}
2015-01-09 18:16:55.968 [debug] <0.2601.0>@extauth:loop:120 extauth call '[<<"isuser">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>]' received data response:
[0,1]
2015-01-09 18:16:55.969 [debug] <0.2711.0>@ejabberd_router:do_route:322 route
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>}
to {jid,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"type">>,<<"subscribed">>}],[]}
2015-01-09 18:16:55.982 [debug] <0.5209.0>@ejabberd_http_bind:prepare_response:914 OutPacket: [{xmlstreamelement,{xmlel,<<"presence">>,[{<<"from">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@localhost/7f317c7fcbb9fb1b79c414c2d8afe05c">>},{<<"to">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@localhost/7f317c7fcbb9fb1b79c414c2d8afe05c">>}],[]}},{xmlstreamelement,{xmlel,<<"presence">>,[{<<"from">>,<<"bc1234@jaxlecho.localhost">>},{<<"to">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@localhost/7f317c7fcbb9fb1b79c414c2d8afe05c">>},{<<"type">>,<<"available">>}],[]}}]
2015-01-09 18:16:55.982 [debug] <0.5209.0>@ejabberd_http_bind:send_outpacket:1056 --- outgoing data ---
<body xmlns='http://jabber.org/protocol/httpbind'><presence xmlns='jabber:client' from='anonymous.c6502a950667fc79c3e1534559cc02e0@localhost/7f317c7fcbb9fb1b79c414c2d8afe05c' to='anonymous.c6502a950667fc79c3e1534559cc02e0@localhost/7f317c7fcbb9fb1b79c414c2d8afe05c'/><presence xmlns='jabber:client' from='bc1234@jaxlecho.localhost' to='anonymous.c6502a950667fc79c3e1534559cc02e0@localhost/7f317c7fcbb9fb1b79c414c2d8afe05c' type='available'/></body>
--- END ---
2015-01-09 18:16:56.017 [info] <0.2709.0>@ejabberd_listener:accept:313 (#Port<0.41543>) Accepted connection ::FFFF:127.0.0.1:58322 -> ::FFFF:127.0.0.1:5280
2015-01-09 18:16:56.018 [debug] <0.5211.0>@ejabberd_http:init:145 S: [{[<<"presence">>],mod_webpresence},{[<<"captcha">>],ejabberd_captcha},{[<<"admin">>],ejabberd_web_admin},{[<<"http-bind">>],mod_http_bind},{[<<"http-poll">>],ejabberd_http_poll}]
2015-01-09 18:16:56.018 [info] <0.5211.0>@ejabberd_http:init:149 started: {gen_tcp,#Port<0.41543>}
2015-01-09 18:16:56.018 [debug] <0.5211.0>@ejabberd_http:process_header:277 (#Port<0.41543>) http query: 'POST' /http-bind
2015-01-09 18:16:56.018 [debug] <0.5211.0>@ejabberd_http:process_request:438 client data: <<"<body rid='876137' sid='a1e29cded4325f591bdc5b3646a8f3c78553cdd8' xmlns='http://jabber.org/protocol/httpbind' key='802f04414e3e73bd03ab6a9c711a87f4d9ec71dd'/>">>
2015-01-09 18:16:56.018 [debug] <0.5211.0>@ejabberd_http:process:350 [<<"http-bind">>] matches [<<"http-bind">>]
2015-01-09 18:16:56.018 [debug] <0.5211.0>@ejabberd_http:process:354 [{'Accept-Language',<<"en-US,en;q=0.8,es;q=0.6,und;q=0.4,nl;q=0.2,ca;q=0.2">>},{'Accept-Encoding',<<"gzip, deflate">>},{'Referer',<<"http://localhost:9080/">>},{'Accept',<<"*/*">>},{'Content-Type',<<"text/xml; charset=UTF-8">>},{'User-Agent',<<"Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/39.0.2171.95 Safari/537.36">>},{<<"Origin">>,<<"http://localhost:9080">>},{'Content-Length',<<"158">>},{'Connection',<<"close">>},{'Host',<<"vagrant.localhost">>}]
2015-01-09 18:16:56.018 [debug] <0.5211.0>@mod_http_bind:process:68 Incoming data: <body rid='876137' sid='a1e29cded4325f591bdc5b3646a8f3c78553cdd8' xmlns='http://jabber.org/protocol/httpbind' key='802f04414e3e73bd03ab6a9c711a87f4d9ec71dd'/>
2015-01-09 18:16:56.018 [debug] <0.5211.0>@ejabberd_http_bind:parse_request:1128 --- incoming data ---
<body rid='876137' sid='a1e29cded4325f591bdc5b3646a8f3c78553cdd8' xmlns='http://jabber.org/protocol/httpbind' key='802f04414e3e73bd03ab6a9c711a87f4d9ec71dd'/>
--- END ---
2015-01-09 18:16:56.018 [debug] <0.5211.0>@ejabberd_http_bind:http_put:792 Looking for session: <<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>
2015-01-09 18:16:56.019 [debug] <0.4052.0>@ejabberd_http_bind:handle_sync_event:440 New request: {http_put,876137,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"876137">>},{<<"sid">>,<<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>},{<<"key">>,<<"802f04414e3e73bd03ab6a9c711a87f4d9ec71dd">>}],[],158,1,<<>>,{{0,0,0,0,0,65535,32512,1},58322}}
2015-01-09 18:16:56.019 [debug] <0.4052.0>@ejabberd_http_bind:handle_http_put_event:575 New request: {http_put,876137,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"876137">>},{<<"sid">>,<<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>},{<<"key">>,<<"802f04414e3e73bd03ab6a9c711a87f4d9ec71dd">>}],[],158,1,<<>>,{{0,0,0,0,0,65535,32512,1},58322}}
2015-01-09 18:16:56.019 [debug] <0.4052.0>@ejabberd_http_bind:rid_allow:866 Previous rid / New rid: 876136/876137
2015-01-09 18:16:56.019 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:608 Actually processing request: {http_put,876137,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"876137">>},{<<"sid">>,<<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>},{<<"key">>,<<"802f04414e3e73bd03ab6a9c711a87f4d9ec71dd">>}],[],158,1,<<>>,{{0,0,0,0,0,65535,32512,1},58322}}
2015-01-09 18:16:56.019 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:619 Key/OldKey/NextKey: 802f04414e3e73bd03ab6a9c711a87f4d9ec71dd/bbbd363400fbf957d9a69bc2645a4f14dd29eab9/bbbd363400fbf957d9a69bc2645a4f14dd29eab9
2015-01-09 18:16:56.019 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:659 -- SaveKey: 802f04414e3e73bd03ab6a9c711a87f4d9ec71dd
2015-01-09 18:16:56.019 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:671 reqlist: [{hbr,876137,<<"bbbd363400fbf957d9a69bc2645a4f14dd29eab9">>,[]},{hbr,876136,<<"bbbd363400fbf957d9a69bc2645a4f14dd29eab9">>,[{xmlstreamelement,{xmlel,<<"presence">>,[{<<"from">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost">>},{<<"to">>,<<"agent1@localhost/cb_operator_1420818758533">>},{<<"type">>,<<"available">>}],[]}}]}]
2015-01-09 18:16:56.019 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:717 really sending now: []
2015-01-09 18:16:56.121 [debug] <0.5211.0>@ejabberd_http_bind:prepare_response:914 OutPacket: [{xmlstreamelement,{xmlel,<<"message">>,[{<<"from">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost">>},{<<"to">>,<<"agent1@localhost">>}],[{xmlel,<<"active">>,[{<<"xmlns">>,<<"http://jabber.org/protocol/chatstates">>}],[]}]}}]
2015-01-09 18:16:56.121 [debug] <0.5211.0>@ejabberd_http_bind:send_outpacket:1056 --- outgoing data ---
<body xmlns='http://jabber.org/protocol/httpbind'><message xmlns='jabber:client' from='anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost' to='agent1@localhost'><active xmlns='http://jabber.org/protocol/chatstates'/></message></body>
--- END ---
2015-01-09 18:16:56.190 [info] <0.2709.0>@ejabberd_listener:accept:313 (#Port<0.41545>) Accepted connection ::FFFF:127.0.0.1:58324 -> ::FFFF:127.0.0.1:5280
2015-01-09 18:16:56.190 [debug] <0.5212.0>@ejabberd_http:init:145 S: [{[<<"presence">>],mod_webpresence},{[<<"captcha">>],ejabberd_captcha},{[<<"admin">>],ejabberd_web_admin},{[<<"http-bind">>],mod_http_bind},{[<<"http-poll">>],ejabberd_http_poll}]
2015-01-09 18:16:56.190 [info] <0.5212.0>@ejabberd_http:init:149 started: {gen_tcp,#Port<0.41545>}
2015-01-09 18:16:56.190 [debug] <0.5212.0>@ejabberd_http:process_header:277 (#Port<0.41545>) http query: 'POST' /http-bind
2015-01-09 18:16:56.190 [debug] <0.5212.0>@ejabberd_http:process_request:438 client data: <<"<body rid='1634610726' sid='a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3' xmlns='http://jabber.org/protocol/httpbind'/>">>
2015-01-09 18:16:56.190 [debug] <0.5212.0>@ejabberd_http:process:350 [<<"http-bind">>] matches [<<"http-bind">>]
2015-01-09 18:16:56.191 [debug] <0.5212.0>@ejabberd_http:process:354 [{'Cookie',<<"_cb_secure=%7B%2254ac093c612c34.56437777%22%3A%7B%22_cbtt%22%3A%2254628a0d7e4e9086e015ec69f281ed2195c9ca304ff43288b2578b494584bd76%22%7D%7D; _cb_settings=%7B%2254ac093c612c34.56437777%22%3A%7B%22_cbchat%22%3A%7B%22session%22%3A%7B%22jid%22%3A%22anonymous.c6502a950667fc79c3e1534559cc02e0%40localhost%2F7f317c7fcbb9fb1b79c414c2d8afe05c%22%2C%22sid%22%3A%22a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3%22%2C%22rid%22%3A1634610724%7D%2C%22groupJid%22%3A%22bc1234%40jaxlecho.localhost%22%2C%22messages%22%3A%5B%5D%7D%7D%7D; _cb_analytics=null; _cb_tracking=%7B%2254ac093c612c34.56437777%22%3A%7B%22trackingOn%22%3A1%2C%22_cbs%22%3A%22b62d40341f950986066a9b28f911ba14326101ab%22%2C%22_cbv%22%3A%2297a71b9487d1b8447b00f274d3923d37d8c006d7%22%7D%7D">>},{'Accept-Language',<<"en-US,en;q=0.8,es;q=0.6,und;q=0.4,nl;q=0.2,ca;q=0.2">>},{'Accept-Encoding',<<"gzip, deflate">>},{'Accept',<<"*/*">>},{'Content-Type',<<"text/xml; charset=UTF-8">>},{'User-Agent',<<"Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/39.0.2171.95 Safari/537.36">>},{<<"Origin">>,<<"https://vagrant.localhost">>},{'Cache-Control',<<"no-cache">>},{'Pragma',<<"no-cache">>},{'Content-Length',<<"115">>},{'Connection',<<"close">>},{'Host',<<"vagrant.localhost">>}]
2015-01-09 18:16:56.191 [debug] <0.5212.0>@mod_http_bind:process:68 Incoming data: <body rid='1634610726' sid='a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3' xmlns='http://jabber.org/protocol/httpbind'/>
2015-01-09 18:16:56.191 [debug] <0.5212.0>@ejabberd_http_bind:parse_request:1128 --- incoming data ---
<body rid='1634610726' sid='a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3' xmlns='http://jabber.org/protocol/httpbind'/>
--- END ---
2015-01-09 18:16:56.191 [debug] <0.5212.0>@ejabberd_http_bind:http_put:792 Looking for session: <<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>
2015-01-09 18:16:56.191 [debug] <0.5203.0>@ejabberd_http_bind:handle_sync_event:440 New request: {http_put,1634610726,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"1634610726">>},{<<"sid">>,<<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>}],[],115,1,<<>>,{{0,0,0,0,0,65535,32512,1},58324}}
2015-01-09 18:16:56.191 [debug] <0.5203.0>@ejabberd_http_bind:handle_http_put_event:575 New request: {http_put,1634610726,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"1634610726">>},{<<"sid">>,<<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>}],[],115,1,<<>>,{{0,0,0,0,0,65535,32512,1},58324}}
2015-01-09 18:16:56.191 [debug] <0.5203.0>@ejabberd_http_bind:rid_allow:866 Previous rid / New rid: 1634610725/1634610726
2015-01-09 18:16:56.191 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:608 Actually processing request: {http_put,1634610726,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"1634610726">>},{<<"sid">>,<<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>}],[],115,1,<<>>,{{0,0,0,0,0,65535,32512,1},58324}}
2015-01-09 18:16:56.191 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:659 -- SaveKey:
2015-01-09 18:16:56.192 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:671 reqlist: [{hbr,1634610726,<<>>,[]},{hbr,1634610725,<<>>,[{xmlstreamelement,{xmlel,<<"presence">>,[{<<"from">>,<<"bc1234@jaxlecho.localhost">>},{<<"to">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@localhost/7f317c7fcbb9fb1b79c414c2d8afe05c">>},{<<"type">>,<<"available">>}],[]}},{xmlstreamelement,{xmlel,<<"presence">>,[{<<"from">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@localhost/7f317c7fcbb9fb1b79c414c2d8afe05c">>},{<<"to">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@localhost/7f317c7fcbb9fb1b79c414c2d8afe05c">>}],[]}}]}]
2015-01-09 18:16:56.192 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:717 really sending now: []
2015-01-09 18:16:56.233 [info] <0.2709.0>@ejabberd_listener:accept:313 (#Port<0.41547>) Accepted connection ::FFFF:127.0.0.1:58325 -> ::FFFF:127.0.0.1:5280
2015-01-09 18:16:56.233 [debug] <0.5213.0>@ejabberd_http:init:145 S: [{[<<"presence">>],mod_webpresence},{[<<"captcha">>],ejabberd_captcha},{[<<"admin">>],ejabberd_web_admin},{[<<"http-bind">>],mod_http_bind},{[<<"http-poll">>],ejabberd_http_poll}]
2015-01-09 18:16:56.233 [info] <0.5213.0>@ejabberd_http:init:149 started: {gen_tcp,#Port<0.41547>}
2015-01-09 18:16:56.233 [debug] <0.5213.0>@ejabberd_http:process_header:277 (#Port<0.41547>) http query: 'POST' /http-bind
2015-01-09 18:16:56.234 [debug] <0.5213.0>@ejabberd_http:process_request:438 client data: <<"<body rid='876138' sid='a1e29cded4325f591bdc5b3646a8f3c78553cdd8' xmlns='http://jabber.org/protocol/httpbind' key='3fb50cdec5387d370d0fdb36e4965fe15bc2f50d'/>">>
2015-01-09 18:16:56.234 [debug] <0.5213.0>@ejabberd_http:process:350 [<<"http-bind">>] matches [<<"http-bind">>]
2015-01-09 18:16:56.234 [debug] <0.5213.0>@ejabberd_http:process:354 [{'Accept-Language',<<"en-US,en;q=0.8,es;q=0.6,und;q=0.4,nl;q=0.2,ca;q=0.2">>},{'Accept-Encoding',<<"gzip, deflate">>},{'Referer',<<"http://localhost:9080/">>},{'Accept',<<"*/*">>},{'Content-Type',<<"text/xml; charset=UTF-8">>},{'User-Agent',<<"Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/39.0.2171.95 Safari/537.36">>},{<<"Origin">>,<<"http://localhost:9080">>},{'Content-Length',<<"158">>},{'Connection',<<"close">>},{'Host',<<"vagrant.localhost">>}]
2015-01-09 18:16:56.234 [debug] <0.5213.0>@mod_http_bind:process:68 Incoming data: <body rid='876138' sid='a1e29cded4325f591bdc5b3646a8f3c78553cdd8' xmlns='http://jabber.org/protocol/httpbind' key='3fb50cdec5387d370d0fdb36e4965fe15bc2f50d'/>
2015-01-09 18:16:56.234 [debug] <0.5213.0>@ejabberd_http_bind:parse_request:1128 --- incoming data ---
<body rid='876138' sid='a1e29cded4325f591bdc5b3646a8f3c78553cdd8' xmlns='http://jabber.org/protocol/httpbind' key='3fb50cdec5387d370d0fdb36e4965fe15bc2f50d'/>
--- END ---
2015-01-09 18:16:56.234 [debug] <0.5213.0>@ejabberd_http_bind:http_put:792 Looking for session: <<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>
2015-01-09 18:16:56.234 [debug] <0.4052.0>@ejabberd_http_bind:handle_sync_event:440 New request: {http_put,876138,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"876138">>},{<<"sid">>,<<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>},{<<"key">>,<<"3fb50cdec5387d370d0fdb36e4965fe15bc2f50d">>}],[],158,1,<<>>,{{0,0,0,0,0,65535,32512,1},58325}}
2015-01-09 18:16:56.235 [debug] <0.4052.0>@ejabberd_http_bind:handle_http_put_event:575 New request: {http_put,876138,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"876138">>},{<<"sid">>,<<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>},{<<"key">>,<<"3fb50cdec5387d370d0fdb36e4965fe15bc2f50d">>}],[],158,1,<<>>,{{0,0,0,0,0,65535,32512,1},58325}}
2015-01-09 18:16:56.235 [debug] <0.4052.0>@ejabberd_http_bind:rid_allow:866 Previous rid / New rid: 876137/876138
2015-01-09 18:16:56.235 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:608 Actually processing request: {http_put,876138,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"876138">>},{<<"sid">>,<<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>},{<<"key">>,<<"3fb50cdec5387d370d0fdb36e4965fe15bc2f50d">>}],[],158,1,<<>>,{{0,0,0,0,0,65535,32512,1},58325}}
2015-01-09 18:16:56.235 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:619 Key/OldKey/NextKey: 3fb50cdec5387d370d0fdb36e4965fe15bc2f50d/802f04414e3e73bd03ab6a9c711a87f4d9ec71dd/802f04414e3e73bd03ab6a9c711a87f4d9ec71dd
2015-01-09 18:16:56.235 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:659 -- SaveKey: 3fb50cdec5387d370d0fdb36e4965fe15bc2f50d
2015-01-09 18:16:56.235 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:671 reqlist: [{hbr,876138,<<"802f04414e3e73bd03ab6a9c711a87f4d9ec71dd">>,[]},{hbr,876137,<<"802f04414e3e73bd03ab6a9c711a87f4d9ec71dd">>,[{xmlstreamelement,{xmlel,<<"message">>,[{<<"from">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost">>},{<<"to">>,<<"agent1@localhost">>}],[{xmlel,<<"active">>,[{<<"xmlns">>,<<"http://jabber.org/protocol/chatstates">>}],[]}]}}]}]
2015-01-09 18:16:56.235 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:717 really sending now: []
2015-01-09 18:16:56.386 [info] <0.2709.0>@ejabberd_listener:accept:313 (#Port<0.41549>) Accepted connection ::FFFF:127.0.0.1:58326 -> ::FFFF:127.0.0.1:5280
2015-01-09 18:16:56.386 [debug] <0.5214.0>@ejabberd_http:init:145 S: [{[<<"presence">>],mod_webpresence},{[<<"captcha">>],ejabberd_captcha},{[<<"admin">>],ejabberd_web_admin},{[<<"http-bind">>],mod_http_bind},{[<<"http-poll">>],ejabberd_http_poll}]
2015-01-09 18:16:56.386 [info] <0.5214.0>@ejabberd_http:init:149 started: {gen_tcp,#Port<0.41549>}
2015-01-09 18:16:56.386 [debug] <0.5214.0>@ejabberd_http:process_header:277 (#Port<0.41549>) http query: 'POST' /http-bind
2015-01-09 18:16:56.387 [debug] <0.5214.0>@ejabberd_http:process_request:438 client data: <<"<body rid='1634610727' sid='a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3' xmlns='http://jabber.org/protocol/httpbind'><presence xmlns=\"jabber:client\" from=\"anonymous.c6502a950667fc79c3e1534559cc02e0@localhost/7f317c7fcbb9fb1b79c414c2d8afe05c\" to=\"bc1234@jaxlecho.localhost\"/></body>">>
2015-01-09 18:16:56.387 [debug] <0.5214.0>@ejabberd_http:process:350 [<<"http-bind">>] matches [<<"http-bind">>]
2015-01-09 18:16:56.387 [debug] <0.5214.0>@ejabberd_http:process:354 [{'Cookie',<<"_cb_secure=%7B%2254ac093c612c34.56437777%22%3A%7B%22_cbtt%22%3A%2254628a0d7e4e9086e015ec69f281ed2195c9ca304ff43288b2578b494584bd76%22%7D%7D; _cb_settings=%7B%2254ac093c612c34.56437777%22%3A%7B%22_cbchat%22%3A%7B%22session%22%3A%7B%22jid%22%3A%22anonymous.c6502a950667fc79c3e1534559cc02e0%40localhost%2F7f317c7fcbb9fb1b79c414c2d8afe05c%22%2C%22sid%22%3A%22a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3%22%2C%22rid%22%3A1634610724%7D%2C%22groupJid%22%3A%22bc1234%40jaxlecho.localhost%22%2C%22messages%22%3A%5B%5D%7D%7D%7D; _cb_analytics=null; _cb_tracking=%7B%2254ac093c612c34.56437777%22%3A%7B%22trackingOn%22%3A1%2C%22_cbs%22%3A%22b62d40341f950986066a9b28f911ba14326101ab%22%2C%22_cbv%22%3A%2297a71b9487d1b8447b00f274d3923d37d8c006d7%22%7D%7D">>},{'Accept-Language',<<"en-US,en;q=0.8,es;q=0.6,und;q=0.4,nl;q=0.2,ca;q=0.2">>},{'Accept-Encoding',<<"gzip, deflate">>},{'Accept',<<"*/*">>},{'Content-Type',<<"text/xml; charset=UTF-8">>},{'User-Agent',<<"Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/39.0.2171.95 Safari/537.36">>},{<<"Origin">>,<<"https://vagrant.localhost">>},{'Cache-Control',<<"no-cache">>},{'Pragma',<<"no-cache">>},{'Content-Length',<<"278">>},{'Connection',<<"close">>},{'Host',<<"vagrant.localhost">>}]
2015-01-09 18:16:56.388 [debug] <0.5214.0>@mod_http_bind:process:68 Incoming data: <body rid='1634610727' sid='a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3' xmlns='http://jabber.org/protocol/httpbind'><presence xmlns="jabber:client" from="anonymous.c6502a950667fc79c3e1534559cc02e0@localhost/7f317c7fcbb9fb1b79c414c2d8afe05c" to="bc1234@jaxlecho.localhost"/></body>
2015-01-09 18:16:56.388 [debug] <0.5214.0>@ejabberd_http_bind:parse_request:1128 --- incoming data ---
<body rid='1634610727' sid='a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3' xmlns='http://jabber.org/protocol/httpbind'><presence xmlns="jabber:client" from="anonymous.c6502a950667fc79c3e1534559cc02e0@localhost/7f317c7fcbb9fb1b79c414c2d8afe05c" to="bc1234@jaxlecho.localhost"/></body>
--- END ---
2015-01-09 18:16:56.388 [debug] <0.5214.0>@ejabberd_http_bind:http_put:792 Looking for session: <<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>
2015-01-09 18:16:56.389 [debug] <0.5203.0>@ejabberd_http_bind:handle_sync_event:440 New request: {http_put,1634610727,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"1634610727">>},{<<"sid">>,<<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>}],[{xmlel,<<"presence">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"from">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@localhost/7f317c7fcbb9fb1b79c414c2d8afe05c">>},{<<"to">>,<<"bc1234@jaxlecho.localhost">>}],[]}],278,1,<<>>,{{0,0,0,0,0,65535,32512,1},58326}}
2015-01-09 18:16:56.389 [debug] <0.5203.0>@ejabberd_http_bind:handle_http_put_event:575 New request: {http_put,1634610727,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"1634610727">>},{<<"sid">>,<<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>}],[{xmlel,<<"presence">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"from">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@localhost/7f317c7fcbb9fb1b79c414c2d8afe05c">>},{<<"to">>,<<"bc1234@jaxlecho.localhost">>}],[]}],278,1,<<>>,{{0,0,0,0,0,65535,32512,1},58326}}
2015-01-09 18:16:56.390 [debug] <0.5203.0>@ejabberd_http_bind:rid_allow:866 Previous rid / New rid: 1634610726/1634610727
2015-01-09 18:16:56.390 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:608 Actually processing request: {http_put,1634610727,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"1634610727">>},{<<"sid">>,<<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>}],[{xmlel,<<"presence">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"from">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@localhost/7f317c7fcbb9fb1b79c414c2d8afe05c">>},{<<"to">>,<<"bc1234@jaxlecho.localhost">>}],[]}],278,1,<<>>,{{0,0,0,0,0,65535,32512,1},58326}}
2015-01-09 18:16:56.390 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:659 -- SaveKey:
2015-01-09 18:16:56.390 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:671 reqlist: [{hbr,1634610727,<<>>,[]},{hbr,1634610726,<<>>,[]}]
2015-01-09 18:16:56.390 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:717 really sending now: [{xmlel,<<"presence">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"from">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@localhost/7f317c7fcbb9fb1b79c414c2d8afe05c">>},{<<"to">>,<<"bc1234@jaxlecho.localhost">>}],[]}]
2015-01-09 18:16:56.390 [debug] <0.5204.0>@ejabberd_router:do_route:322 route
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}
to {jid,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"from">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@localhost/7f317c7fcbb9fb1b79c414c2d8afe05c">>},{<<"to">>,<<"bc1234@jaxlecho.localhost">>}],[]}
2015-01-09 18:16:56.402 [debug] <0.2710.0>@ejabberd_receiver:process_data:343 Received XML on stream = <<"<presence to=\"agent1@localhost\" from=\"anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost\" type=\"available\"/>">>
2015-01-09 18:16:56.402 [debug] <0.2710.0>@shaper:update:117 State: {maxrate,50000,16456.409107872965,1420823815949463}, Size=119
M=1.4244060942227743, I=453.47
2015-01-09 18:16:56.403 [debug] <0.2711.0>@ejabberd_router:do_route:322 route
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<>>,<<"agent1">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"to">>,<<"agent1@localhost">>},{<<"from">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost">>},{<<"type">>,<<"available">>}],[]}
2015-01-09 18:16:56.403 [debug] <0.2711.0>@ejabberd_local:do_route:296 local route
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<>>,<<"agent1">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"to">>,<<"agen"...>>},{<<"from">>,<<...>>},{<<...>>,...}],[]}
2015-01-09 18:16:56.403 [debug] <0.2711.0>@ejabberd_sm:do_route:514 session manager
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<>>,<<"agent1">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"to">>,<<"agen"...>>},{<<"from">>,<<...>>},{<<...>>,...}],[]}
2015-01-09 18:16:56.403 [debug] <0.2711.0>@ejabberd_sm:do_route:514 session manager
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>}
packet {xmlel,<<"presence">>,[{<<"to">>,<<"agen"...>>},{<<"from">>,<<...>>},{<<...>>,...}],[]}
2015-01-09 18:16:56.403 [debug] <0.2711.0>@ejabberd_sm:do_route:612 sending to process <0.4053.0>
2015-01-09 18:16:56.403 [debug] <0.5213.0>@ejabberd_http_bind:prepare_response:914 OutPacket: [{xmlstreamelement,{xmlel,<<"presence">>,[{<<"from">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost">>},{<<"to">>,<<"agent1@localhost/cb_operator_1420818758533">>},{<<"type">>,<<"available">>}],[]}}]
2015-01-09 18:16:56.404 [debug] <0.5213.0>@ejabberd_http_bind:send_outpacket:1056 --- outgoing data ---
<body xmlns='http://jabber.org/protocol/httpbind'><presence xmlns='jabber:client' from='anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost' to='agent1@localhost/cb_operator_1420818758533' type='available'/></body>
--- END ---
2015-01-09 18:16:56.442 [debug] <0.2710.0>@ejabberd_receiver:process_data:343 Received XML on stream = <<"<presence from=\"anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost\" type=\"available\"/>">>
2015-01-09 18:16:56.442 [debug] <0.2710.0>@shaper:update:117 State: {maxrate,50000,8359.397060749674,1420823816402995}, Size=97
M=1.058482778253898, I=39.581
2015-01-09 18:16:56.511 [info] <0.2709.0>@ejabberd_listener:accept:313 (#Port<0.41551>) Accepted connection ::FFFF:127.0.0.1:58329 -> ::FFFF:127.0.0.1:5280
2015-01-09 18:16:56.511 [debug] <0.5215.0>@ejabberd_http:init:145 S: [{[<<"presence">>],mod_webpresence},{[<<"captcha">>],ejabberd_captcha},{[<<"admin">>],ejabberd_web_admin},{[<<"http-bind">>],mod_http_bind},{[<<"http-poll">>],ejabberd_http_poll}]
2015-01-09 18:16:56.511 [info] <0.5215.0>@ejabberd_http:init:149 started: {gen_tcp,#Port<0.41551>}
2015-01-09 18:16:56.512 [debug] <0.5215.0>@ejabberd_http:process_header:277 (#Port<0.41551>) http query: 'POST' /http-bind
2015-01-09 18:16:56.512 [debug] <0.5215.0>@ejabberd_http:process_request:438 client data: <<"<body rid='876139' sid='a1e29cded4325f591bdc5b3646a8f3c78553cdd8' xmlns='http://jabber.org/protocol/httpbind' key='0d917bc1b80a5eefe573b493926f2b1b2ce8855a'/>">>
2015-01-09 18:16:56.512 [debug] <0.5215.0>@ejabberd_http:process:350 [<<"http-bind">>] matches [<<"http-bind">>]
2015-01-09 18:16:56.512 [debug] <0.5215.0>@ejabberd_http:process:354 [{'Accept-Language',<<"en-US,en;q=0.8,es;q=0.6,und;q=0.4,nl;q=0.2,ca;q=0.2">>},{'Accept-Encoding',<<"gzip, deflate">>},{'Referer',<<"http://localhost:9080/">>},{'Accept',<<"*/*">>},{'Content-Type',<<"text/xml; charset=UTF-8">>},{'User-Agent',<<"Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/39.0.2171.95 Safari/537.36">>},{<<"Origin">>,<<"http://localhost:9080">>},{'Content-Length',<<"158">>},{'Connection',<<"close">>},{'Host',<<"vagrant.localhost">>}]
2015-01-09 18:16:56.512 [debug] <0.5215.0>@mod_http_bind:process:68 Incoming data: <body rid='876139' sid='a1e29cded4325f591bdc5b3646a8f3c78553cdd8' xmlns='http://jabber.org/protocol/httpbind' key='0d917bc1b80a5eefe573b493926f2b1b2ce8855a'/>
2015-01-09 18:16:56.512 [debug] <0.5215.0>@ejabberd_http_bind:parse_request:1128 --- incoming data ---
<body rid='876139' sid='a1e29cded4325f591bdc5b3646a8f3c78553cdd8' xmlns='http://jabber.org/protocol/httpbind' key='0d917bc1b80a5eefe573b493926f2b1b2ce8855a'/>
--- END ---
2015-01-09 18:16:56.512 [debug] <0.5215.0>@ejabberd_http_bind:http_put:792 Looking for session: <<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>
2015-01-09 18:16:56.512 [debug] <0.4052.0>@ejabberd_http_bind:handle_sync_event:440 New request: {http_put,876139,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"876139">>},{<<"sid">>,<<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>},{<<"key">>,<<"0d917bc1b80a5eefe573b493926f2b1b2ce8855a">>}],[],158,1,<<>>,{{0,0,0,0,0,65535,32512,1},58329}}
2015-01-09 18:16:56.513 [debug] <0.4052.0>@ejabberd_http_bind:handle_http_put_event:575 New request: {http_put,876139,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"876139">>},{<<"sid">>,<<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>},{<<"key">>,<<"0d917bc1b80a5eefe573b493926f2b1b2ce8855a">>}],[],158,1,<<>>,{{0,0,0,0,0,65535,32512,1},58329}}
2015-01-09 18:16:56.513 [debug] <0.4052.0>@ejabberd_http_bind:rid_allow:866 Previous rid / New rid: 876138/876139
2015-01-09 18:16:56.513 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:608 Actually processing request: {http_put,876139,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"876139">>},{<<"sid">>,<<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>},{<<"key">>,<<"0d917bc1b80a5eefe573b493926f2b1b2ce8855a">>}],[],158,1,<<>>,{{0,0,0,0,0,65535,32512,1},58329}}
2015-01-09 18:16:56.513 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:619 Key/OldKey/NextKey: 0d917bc1b80a5eefe573b493926f2b1b2ce8855a/3fb50cdec5387d370d0fdb36e4965fe15bc2f50d/3fb50cdec5387d370d0fdb36e4965fe15bc2f50d
2015-01-09 18:16:56.513 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:659 -- SaveKey: 0d917bc1b80a5eefe573b493926f2b1b2ce8855a
2015-01-09 18:16:56.513 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:671 reqlist: [{hbr,876139,<<"3fb50cdec5387d370d0fdb36e4965fe15bc2f50d">>,[]},{hbr,876138,<<"3fb50cdec5387d370d0fdb36e4965fe15bc2f50d">>,[{xmlstreamelement,{xmlel,<<"presence">>,[{<<"from">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost">>},{<<"to">>,<<"agent1@localhost/cb_operator_1420818758533">>},{<<"type">>,<<"available">>}],[]}}]}]
2015-01-09 18:16:56.513 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:717 really sending now: []
2015-01-09 18:16:56.779 [info] <0.2709.0>@ejabberd_listener:accept:313 (#Port<0.41553>) Accepted connection ::FFFF:127.0.0.1:58330 -> ::FFFF:127.0.0.1:5280
2015-01-09 18:16:56.780 [debug] <0.5216.0>@ejabberd_http:init:145 S: [{[<<"presence">>],mod_webpresence},{[<<"captcha">>],ejabberd_captcha},{[<<"admin">>],ejabberd_web_admin},{[<<"http-bind">>],mod_http_bind},{[<<"http-poll">>],ejabberd_http_poll}]
2015-01-09 18:16:56.780 [info] <0.5216.0>@ejabberd_http:init:149 started: {gen_tcp,#Port<0.41553>}
2015-01-09 18:16:56.780 [debug] <0.5216.0>@ejabberd_http:process_header:277 (#Port<0.41553>) http query: 'POST' /http-bind
2015-01-09 18:16:56.780 [debug] <0.5216.0>@ejabberd_http:process_request:438 client data: <<"<body rid='1634610728' sid='a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3' xmlns='http://jabber.org/protocol/httpbind'><presence xmlns=\"jabber:client\" type=\"available\" to=\"bc1234@jaxlecho.localhost\"><status>available</status><show>chat</show></presence></body>">>
2015-01-09 18:16:56.780 [debug] <0.5216.0>@ejabberd_http:process:350 [<<"http-bind">>] matches [<<"http-bind">>]
2015-01-09 18:16:56.781 [debug] <0.5216.0>@ejabberd_http:process:354 [{'Cookie',<<"_cb_secure=%7B%2254ac093c612c34.56437777%22%3A%7B%22_cbtt%22%3A%2254628a0d7e4e9086e015ec69f281ed2195c9ca304ff43288b2578b494584bd76%22%7D%7D; _cb_settings=%7B%2254ac093c612c34.56437777%22%3A%7B%22_cbchat%22%3A%7B%22session%22%3A%7B%22jid%22%3A%22anonymous.c6502a950667fc79c3e1534559cc02e0%40localhost%2F7f317c7fcbb9fb1b79c414c2d8afe05c%22%2C%22sid%22%3A%22a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3%22%2C%22rid%22%3A1634610724%7D%2C%22groupJid%22%3A%22bc1234%40jaxlecho.localhost%22%2C%22messages%22%3A%5B%5D%7D%7D%7D; _cb_analytics=null; _cb_tracking=%7B%2254ac093c612c34.56437777%22%3A%7B%22trackingOn%22%3A1%2C%22_cbs%22%3A%22b62d40341f950986066a9b28f911ba14326101ab%22%2C%22_cbv%22%3A%2297a71b9487d1b8447b00f274d3923d37d8c006d7%22%7D%7D">>},{'Accept-Language',<<"en-US,en;q=0.8,es;q=0.6,und;q=0.4,nl;q=0.2,ca;q=0.2">>},{'Accept-Encoding',<<"gzip, deflate">>},{'Accept',<<"*/*">>},{'Content-Type',<<"text/xml; charset=UTF-8">>},{'User-Agent',<<"Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/39.0.2171.95 Safari/537.36">>},{<<"Origin">>,<<"https://vagrant.localhost">>},{'Cache-Control',<<"no-cache">>},{'Pragma',<<"no-cache">>},{'Content-Length',<<"255">>},{'Connection',<<"close">>},{'Host',<<"vagrant.localhost">>}]
2015-01-09 18:16:56.781 [debug] <0.5216.0>@mod_http_bind:process:68 Incoming data: <body rid='1634610728' sid='a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3' xmlns='http://jabber.org/protocol/httpbind'><presence xmlns="jabber:client" type="available" to="bc1234@jaxlecho.localhost"><status>available</status><show>chat</show></presence></body>
2015-01-09 18:16:56.781 [debug] <0.5216.0>@ejabberd_http_bind:parse_request:1128 --- incoming data ---
<body rid='1634610728' sid='a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3' xmlns='http://jabber.org/protocol/httpbind'><presence xmlns="jabber:client" type="available" to="bc1234@jaxlecho.localhost"><status>available</status><show>chat</show></presence></body>
--- END ---
2015-01-09 18:16:56.781 [debug] <0.5216.0>@ejabberd_http_bind:http_put:792 Looking for session: <<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>
2015-01-09 18:16:56.782 [debug] <0.5203.0>@ejabberd_http_bind:handle_sync_event:440 New request: {http_put,1634610728,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"1634610728">>},{<<"sid">>,<<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>}],[{xmlel,<<"presence">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"type">>,<<"available">>},{<<"to">>,<<"bc1234@jaxlecho.localhost">>}],[{xmlel,<<"status">>,[],[{xmlcdata,<<"available">>}]},{xmlel,<<"show">>,[],[{xmlcdata,<<"chat">>}]}]}],255,1,<<>>,{{0,0,0,0,0,65535,32512,1},58330}}
2015-01-09 18:16:56.782 [debug] <0.5203.0>@ejabberd_http_bind:handle_http_put_event:575 New request: {http_put,1634610728,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"1634610728">>},{<<"sid">>,<<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>}],[{xmlel,<<"presence">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"type">>,<<"available">>},{<<"to">>,<<"bc1234@jaxlecho.localhost">>}],[{xmlel,<<"status">>,[],[{xmlcdata,<<"available">>}]},{xmlel,<<"show">>,[],[{xmlcdata,<<"chat">>}]}]}],255,1,<<>>,{{0,0,0,0,0,65535,32512,1},58330}}
2015-01-09 18:16:56.782 [debug] <0.5203.0>@ejabberd_http_bind:rid_allow:866 Previous rid / New rid: 1634610727/1634610728
2015-01-09 18:16:56.782 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:608 Actually processing request: {http_put,1634610728,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"1634610728">>},{<<"sid">>,<<"a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3">>}],[{xmlel,<<"presence">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"type">>,<<"available">>},{<<"to">>,<<"bc1234@jaxlecho.localhost">>}],[{xmlel,<<"status">>,[],[{xmlcdata,<<"available">>}]},{xmlel,<<"show">>,[],[{xmlcdata,<<"chat">>}]}]}],255,1,<<>>,{{0,0,0,0,0,65535,32512,1},58330}}
2015-01-09 18:16:56.782 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:659 -- SaveKey:
2015-01-09 18:16:56.782 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:671 reqlist: [{hbr,1634610728,<<>>,[]},{hbr,1634610727,<<>>,[]}]
2015-01-09 18:16:56.782 [debug] <0.5203.0>@ejabberd_http_bind:process_http_put:717 really sending now: [{xmlel,<<"presence">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"type">>,<<"available">>},{<<"to">>,<<"bc1234@jaxlecho.localhost">>}],[{xmlel,<<"status">>,[],[{xmlcdata,<<"available">>}]},{xmlel,<<"show">>,[],[{xmlcdata,<<"chat">>}]}]}]
2015-01-09 18:16:56.783 [debug] <0.5204.0>@ejabberd_router:do_route:322 route
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}
to {jid,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"type">>,<<"available">>},{<<"to">>,<<"bc1234@jaxlecho.localhost">>}],[{xmlel,<<"status">>,[],[{xmlcdata,<<"available">>}]},{xmlel,<<"show">>,[],[{xmlcdata,<<"chat">>}]}]}
2015-01-09 18:16:56.796 [debug] <0.2710.0>@ejabberd_receiver:process_data:343 Received XML on stream = <<"<presence to=\"agent1@localhost\" from=\"anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost\" type=\"available\" show=\"chat\"/>">>
2015-01-09 18:16:56.796 [debug] <0.2710.0>@shaper:update:117 State: {maxrate,50000,5392.31979250105,1420823816442991}, Size=131
M=1.3846655970496617, I=353.442
2015-01-09 18:16:56.796 [debug] <0.2711.0>@ejabberd_router:do_route:322 route
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<>>,<<"agent1">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"to">>,<<"agent1@localhost">>},{<<"from">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost">>},{<<"type">>,<<"available">>},{<<"show">>,<<"chat">>}],[]}
2015-01-09 18:16:56.797 [debug] <0.2711.0>@ejabberd_local:do_route:296 local route
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<>>,<<"agent1">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"to">>,<<"agen"...>>},{<<"from">>,<<...>>},{<<...>>,...},{...}],[]}
2015-01-09 18:16:56.797 [debug] <0.2711.0>@ejabberd_sm:do_route:514 session manager
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<>>,<<"agent1">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"to">>,<<"agen"...>>},{<<"from">>,<<...>>},{<<...>>,...},{...}],[]}
2015-01-09 18:16:56.797 [debug] <0.2711.0>@ejabberd_sm:do_route:514 session manager
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>}
packet {xmlel,<<"presence">>,[{<<"to">>,<<"agen"...>>},{<<"from">>,<<...>>},{<<...>>,...},{...}],[]}
2015-01-09 18:16:56.797 [debug] <0.2711.0>@ejabberd_sm:do_route:612 sending to process <0.4053.0>
2015-01-09 18:16:56.798 [debug] <0.5215.0>@ejabberd_http_bind:prepare_response:914 OutPacket: [{xmlstreamelement,{xmlel,<<"presence">>,[{<<"from">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost">>},{<<"to">>,<<"agent1@localhost/cb_operator_1420818758533">>},{<<"type">>,<<"available">>},{<<"show">>,<<"chat">>}],[]}}]
2015-01-09 18:16:56.798 [debug] <0.5215.0>@ejabberd_http_bind:send_outpacket:1056 --- outgoing data ---
<body xmlns='http://jabber.org/protocol/httpbind'><presence xmlns='jabber:client' from='anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost' to='agent1@localhost/cb_operator_1420818758533' type='available' show='chat'/></body>
--- END ---
2015-01-09 18:16:56.833 [debug] <0.2710.0>@ejabberd_receiver:process_data:343 Received XML on stream = <<"<presence from=\"anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost\" type=\"available\"/>">>
2015-01-09 18:16:56.833 [debug] <0.2710.0>@shaper:update:117 State: {maxrate,50000,2881.325711531369,1420823816796728}, Size=97
M=0.9987780487188681, I=36.685
2015-01-09 18:16:56.908 [info] <0.2709.0>@ejabberd_listener:accept:313 (#Port<0.41555>) Accepted connection ::FFFF:127.0.0.1:58334 -> ::FFFF:127.0.0.1:5280
2015-01-09 18:16:56.908 [debug] <0.5217.0>@ejabberd_http:init:145 S: [{[<<"presence">>],mod_webpresence},{[<<"captcha">>],ejabberd_captcha},{[<<"admin">>],ejabberd_web_admin},{[<<"http-bind">>],mod_http_bind},{[<<"http-poll">>],ejabberd_http_poll}]
2015-01-09 18:16:56.908 [info] <0.5217.0>@ejabberd_http:init:149 started: {gen_tcp,#Port<0.41555>}
2015-01-09 18:16:56.909 [debug] <0.5217.0>@ejabberd_http:process_header:277 (#Port<0.41555>) http query: 'POST' /http-bind
2015-01-09 18:16:56.909 [debug] <0.5217.0>@ejabberd_http:process_request:438 client data: <<"<body rid='876140' sid='a1e29cded4325f591bdc5b3646a8f3c78553cdd8' xmlns='http://jabber.org/protocol/httpbind' key='f4bc6e68d915d3d1782221884dda7edc64710743'/>">>
2015-01-09 18:16:56.909 [debug] <0.5217.0>@ejabberd_http:process:350 [<<"http-bind">>] matches [<<"http-bind">>]
2015-01-09 18:16:56.909 [debug] <0.5217.0>@ejabberd_http:process:354 [{'Accept-Language',<<"en-US,en;q=0.8,es;q=0.6,und;q=0.4,nl;q=0.2,ca;q=0.2">>},{'Accept-Encoding',<<"gzip, deflate">>},{'Referer',<<"http://localhost:9080/">>},{'Accept',<<"*/*">>},{'Content-Type',<<"text/xml; charset=UTF-8">>},{'User-Agent',<<"Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/39.0.2171.95 Safari/537.36">>},{<<"Origin">>,<<"http://localhost:9080">>},{'Content-Length',<<"158">>},{'Connection',<<"close">>},{'Host',<<"vagrant.localhost">>}]
2015-01-09 18:16:56.909 [debug] <0.5217.0>@mod_http_bind:process:68 Incoming data: <body rid='876140' sid='a1e29cded4325f591bdc5b3646a8f3c78553cdd8' xmlns='http://jabber.org/protocol/httpbind' key='f4bc6e68d915d3d1782221884dda7edc64710743'/>
2015-01-09 18:16:56.910 [debug] <0.5217.0>@ejabberd_http_bind:parse_request:1128 --- incoming data ---
<body rid='876140' sid='a1e29cded4325f591bdc5b3646a8f3c78553cdd8' xmlns='http://jabber.org/protocol/httpbind' key='f4bc6e68d915d3d1782221884dda7edc64710743'/>
--- END ---
2015-01-09 18:16:56.910 [debug] <0.5217.0>@ejabberd_http_bind:http_put:792 Looking for session: <<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>
2015-01-09 18:16:56.911 [debug] <0.4052.0>@ejabberd_http_bind:handle_sync_event:440 New request: {http_put,876140,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"876140">>},{<<"sid">>,<<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>},{<<"key">>,<<"f4bc6e68d915d3d1782221884dda7edc64710743">>}],[],158,1,<<>>,{{0,0,0,0,0,65535,32512,1},58334}}
2015-01-09 18:16:56.911 [debug] <0.4052.0>@ejabberd_http_bind:handle_http_put_event:575 New request: {http_put,876140,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"876140">>},{<<"sid">>,<<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>},{<<"key">>,<<"f4bc6e68d915d3d1782221884dda7edc64710743">>}],[],158,1,<<>>,{{0,0,0,0,0,65535,32512,1},58334}}
2015-01-09 18:16:56.911 [debug] <0.4052.0>@ejabberd_http_bind:rid_allow:866 Previous rid / New rid: 876139/876140
2015-01-09 18:16:56.911 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:608 Actually processing request: {http_put,876140,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"876140">>},{<<"sid">>,<<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>},{<<"key">>,<<"f4bc6e68d915d3d1782221884dda7edc64710743">>}],[],158,1,<<>>,{{0,0,0,0,0,65535,32512,1},58334}}
2015-01-09 18:16:56.911 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:619 Key/OldKey/NextKey: f4bc6e68d915d3d1782221884dda7edc64710743/0d917bc1b80a5eefe573b493926f2b1b2ce8855a/0d917bc1b80a5eefe573b493926f2b1b2ce8855a
2015-01-09 18:16:56.911 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:659 -- SaveKey: f4bc6e68d915d3d1782221884dda7edc64710743
2015-01-09 18:16:56.911 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:671 reqlist: [{hbr,876140,<<"0d917bc1b80a5eefe573b493926f2b1b2ce8855a">>,[]},{hbr,876139,<<"0d917bc1b80a5eefe573b493926f2b1b2ce8855a">>,[{xmlstreamelement,{xmlel,<<"presence">>,[{<<"from">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost">>},{<<"to">>,<<"agent1@localhost/cb_operator_1420818758533">>},{<<"type">>,<<"available">>},{<<"show">>,<<"chat">>}],[]}}]}]
2015-01-09 18:16:56.911 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:717 really sending now: []
2015-01-09 18:16:59.174 [debug] <0.2648.0>@ejabberd_router:do_route:322 route
from {jid,<<>>,<<"localhost">>,<<>>,<<>>,<<"localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>}
packet {xmlel,<<"iq">>,[{<<"id">>,<<"3798321368">>},{<<"type">>,<<"get">>}],[{xmlel,<<"ping">>,[{<<"xmlns">>,<<"urn:xmpp:ping">>}],[]}]}
2015-01-09 18:16:59.174 [debug] <0.2648.0>@ejabberd_local:do_route:296 local route
from {jid,<<>>,<<"localhost">>,<<>>,<<>>,<<"localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>}
packet {xmlel,<<"iq">>,[{<<"id">>,<<"3798"...>>},{<<"type">>,<<...>>}],[{xmlel,<<...>>,...}]}
2015-01-09 18:16:59.175 [debug] <0.2648.0>@ejabberd_sm:do_route:514 session manager
from {jid,<<>>,<<"localhost">>,<<>>,<<>>,<<"localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>}
packet {xmlel,<<"iq">>,[{<<"id">>,<<"3798"...>>},{<<"type">>,<<...>>}],[{xmlel,<<...>>,...}]}
2015-01-09 18:16:59.175 [debug] <0.2648.0>@ejabberd_sm:do_route:612 sending to process <0.4053.0>
2015-01-09 18:16:59.177 [debug] <0.5217.0>@ejabberd_http_bind:prepare_response:914 OutPacket: [{xmlstreamelement,{xmlel,<<"iq">>,[{<<"from">>,<<"localhost">>},{<<"to">>,<<"agent1@localhost/cb_operator_1420818758533">>},{<<"id">>,<<"3798321368">>},{<<"type">>,<<"get">>}],[{xmlel,<<"ping">>,[{<<"xmlns">>,<<"urn:xmpp:ping">>}],[]}]}}]
2015-01-09 18:16:59.177 [debug] <0.5217.0>@ejabberd_http_bind:send_outpacket:1056 --- outgoing data ---
<body xmlns='http://jabber.org/protocol/httpbind'><iq xmlns='jabber:client' from='localhost' to='agent1@localhost/cb_operator_1420818758533' id='3798321368' type='get'><ping xmlns='urn:xmpp:ping'/></iq></body>
--- END ---
2015-01-09 18:16:59.211 [info] <0.2709.0>@ejabberd_listener:accept:313 (#Port<0.41557>) Accepted connection ::FFFF:127.0.0.1:58335 -> ::FFFF:127.0.0.1:5280
2015-01-09 18:16:59.211 [debug] <0.5218.0>@ejabberd_http:init:145 S: [{[<<"presence">>],mod_webpresence},{[<<"captcha">>],ejabberd_captcha},{[<<"admin">>],ejabberd_web_admin},{[<<"http-bind">>],mod_http_bind},{[<<"http-poll">>],ejabberd_http_poll}]
2015-01-09 18:16:59.211 [info] <0.5218.0>@ejabberd_http:init:149 started: {gen_tcp,#Port<0.41557>}
2015-01-09 18:16:59.211 [debug] <0.5218.0>@ejabberd_http:process_header:277 (#Port<0.41557>) http query: 'POST' /http-bind
2015-01-09 18:16:59.211 [debug] <0.5218.0>@ejabberd_http:process_request:438 client data: <<"<body rid='876141' sid='a1e29cded4325f591bdc5b3646a8f3c78553cdd8' xmlns='http://jabber.org/protocol/httpbind' key='c41220113a917dffdda6bd9bcb59599a13e795c0'><iq xmlns=\"jabber:client\" to=\"localhost\" id=\"3798321368\" type=\"result\"><ping xmlns=\"urn:xmpp:ping\"/></iq></body>">>
2015-01-09 18:16:59.211 [debug] <0.5218.0>@ejabberd_http:process:350 [<<"http-bind">>] matches [<<"http-bind">>]
2015-01-09 18:16:59.211 [debug] <0.5218.0>@ejabberd_http:process:354 [{'Accept-Language',<<"en-US,en;q=0.8,es;q=0.6,und;q=0.4,nl;q=0.2,ca;q=0.2">>},{'Accept-Encoding',<<"gzip, deflate">>},{'Referer',<<"http://localhost:9080/">>},{'Accept',<<"*/*">>},{'Content-Type',<<"text/xml; charset=UTF-8">>},{'User-Agent',<<"Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/39.0.2171.95 Safari/537.36">>},{<<"Origin">>,<<"http://localhost:9080">>},{'Content-Length',<<"269">>},{'Connection',<<"close">>},{'Host',<<"vagrant.localhost">>}]
2015-01-09 18:16:59.211 [debug] <0.5218.0>@mod_http_bind:process:68 Incoming data: <body rid='876141' sid='a1e29cded4325f591bdc5b3646a8f3c78553cdd8' xmlns='http://jabber.org/protocol/httpbind' key='c41220113a917dffdda6bd9bcb59599a13e795c0'><iq xmlns="jabber:client" to="localhost" id="3798321368" type="result"><ping xmlns="urn:xmpp:ping"/></iq></body>
2015-01-09 18:16:59.212 [debug] <0.5218.0>@ejabberd_http_bind:parse_request:1128 --- incoming data ---
<body rid='876141' sid='a1e29cded4325f591bdc5b3646a8f3c78553cdd8' xmlns='http://jabber.org/protocol/httpbind' key='c41220113a917dffdda6bd9bcb59599a13e795c0'><iq xmlns="jabber:client" to="localhost" id="3798321368" type="result"><ping xmlns="urn:xmpp:ping"/></iq></body>
--- END ---
2015-01-09 18:16:59.212 [debug] <0.5218.0>@ejabberd_http_bind:http_put:792 Looking for session: <<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>
2015-01-09 18:16:59.212 [debug] <0.4052.0>@ejabberd_http_bind:handle_sync_event:440 New request: {http_put,876141,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"876141">>},{<<"sid">>,<<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>},{<<"key">>,<<"c41220113a917dffdda6bd9bcb59599a13e795c0">>}],[{xmlel,<<"iq">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"to">>,<<"localhost">>},{<<"id">>,<<"3798321368">>},{<<"type">>,<<"result">>}],[{xmlel,<<"ping">>,[{<<"xmlns">>,<<"urn:xmpp:ping">>}],[]}]}],269,1,<<>>,{{0,0,0,0,0,65535,32512,1},58335}}
2015-01-09 18:16:59.212 [debug] <0.4052.0>@ejabberd_http_bind:handle_http_put_event:575 New request: {http_put,876141,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"876141">>},{<<"sid">>,<<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>},{<<"key">>,<<"c41220113a917dffdda6bd9bcb59599a13e795c0">>}],[{xmlel,<<"iq">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"to">>,<<"localhost">>},{<<"id">>,<<"3798321368">>},{<<"type">>,<<"result">>}],[{xmlel,<<"ping">>,[{<<"xmlns">>,<<"urn:xmpp:ping">>}],[]}]}],269,1,<<>>,{{0,0,0,0,0,65535,32512,1},58335}}
2015-01-09 18:16:59.212 [debug] <0.4052.0>@ejabberd_http_bind:rid_allow:866 Previous rid / New rid: 876140/876141
2015-01-09 18:16:59.212 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:608 Actually processing request: {http_put,876141,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"876141">>},{<<"sid">>,<<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>},{<<"key">>,<<"c41220113a917dffdda6bd9bcb59599a13e795c0">>}],[{xmlel,<<"iq">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"to">>,<<"localhost">>},{<<"id">>,<<"3798321368">>},{<<"type">>,<<"result">>}],[{xmlel,<<"ping">>,[{<<"xmlns">>,<<"urn:xmpp:ping">>}],[]}]}],269,1,<<>>,{{0,0,0,0,0,65535,32512,1},58335}}
2015-01-09 18:16:59.212 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:619 Key/OldKey/NextKey: c41220113a917dffdda6bd9bcb59599a13e795c0/f4bc6e68d915d3d1782221884dda7edc64710743/f4bc6e68d915d3d1782221884dda7edc64710743
2015-01-09 18:16:59.212 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:659 -- SaveKey: c41220113a917dffdda6bd9bcb59599a13e795c0
2015-01-09 18:16:59.212 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:671 reqlist: [{hbr,876141,<<"f4bc6e68d915d3d1782221884dda7edc64710743">>,[]},{hbr,876140,<<"f4bc6e68d915d3d1782221884dda7edc64710743">>,[{xmlstreamelement,{xmlel,<<"iq">>,[{<<"from">>,<<"localhost">>},{<<"to">>,<<"agent1@localhost/cb_operator_1420818758533">>},{<<"id">>,<<"3798321368">>},{<<"type">>,<<"get">>}],[{xmlel,<<"ping">>,[{<<"xmlns">>,<<"urn:xmpp:ping">>}],[]}]}}]}]
2015-01-09 18:16:59.213 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:717 really sending now: [{xmlel,<<"iq">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"to">>,<<"localhost">>},{<<"id">>,<<"3798321368">>},{<<"type">>,<<"result">>}],[{xmlel,<<"ping">>,[{<<"xmlns">>,<<"urn:xmpp:ping">>}],[]}]}]
2015-01-09 18:16:59.213 [debug] <0.4053.0>@ejabberd_router:do_route:322 route
from {jid,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>}
to {jid,<<>>,<<"localhost">>,<<>>,<<>>,<<"localhost">>,<<>>}
packet {xmlel,<<"iq">>,[{<<"to">>,<<"localhost">>},{<<"id">>,<<"3798321368">>},{<<"type">>,<<"result">>}],[{xmlel,<<"ping">>,[{<<"xmlns">>,<<"urn:xmpp:ping">>}],[]}]}
2015-01-09 18:16:59.213 [debug] <0.4053.0>@ejabberd_local:do_route:296 local route
from {jid,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>}
to {jid,<<>>,<<"localhost">>,<<>>,<<>>,<<"localhost">>,<<>>}
packet {xmlel,<<"iq">>,[{<<"to">>,<<"loca"...>>},{<<"id">>,<<...>>},{<<...>>,...}],[{xmlel,<<...>>,...}]}
2015-01-09 18:17:01.937 [info] <0.5204.0>@ejabberd_c2s:terminate:1757 ({socket_state,ejabberd_http_bind,{http_bind,<0.5203.0>,{{0,0,0,0,0,65535,32512,1},58302}},ejabberd_http_bind}) Close session for anonymous.c6502a950667fc79c3e1534559cc02e0@localhost/7f317c7fcbb9fb1b79c414c2d8afe05c
2015-01-09 18:17:01.938 [debug] <0.5204.0>@mod_pubsub:node_action:5143 node_action <<"pubsub.localhost">> <<"flat">> get_entity_affiliations [<<"pubsub.localhost">>,{<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}]
2015-01-09 18:17:01.938 [debug] <0.5204.0>@mod_pubsub:node_call:5124 node_call <<"flat">> get_entity_affiliations [<<"pubsub.localhost">>,{<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}]
2015-01-09 18:17:01.939 [debug] <0.5204.0>@mod_pubsub:node_action:5143 node_action <<"pubsub.localhost">> <<"hometree">> get_entity_affiliations [<<"pubsub.localhost">>,{<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}]
2015-01-09 18:17:01.940 [debug] <0.5204.0>@mod_pubsub:node_call:5124 node_call <<"hometree">> get_entity_affiliations [<<"pubsub.localhost">>,{<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}]
2015-01-09 18:17:01.940 [debug] <0.5204.0>@mod_pubsub:node_action:5143 node_action <<"pubsub.localhost">> <<"pep">> get_entity_affiliations [<<"pubsub.localhost">>,{<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}]
2015-01-09 18:17:01.940 [debug] <0.5204.0>@mod_pubsub:node_call:5124 node_call <<"pep">> get_entity_affiliations [<<"pubsub.localhost">>,{<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}]
2015-01-09 18:17:01.941 [debug] <0.5204.0>@mod_carboncopy:disable:246 disabling for <<"anonymous.c6502a950667fc79c3e1534559cc02e0">>
2015-01-09 18:17:01.942 [debug] <0.5204.0>@mod_shared_roster:unset_presence:1081 unset_presence for <<"anonymous.c6502a950667fc79c3e1534559cc02e0">> @ <<"localhost">> / <<"7f317c7fcbb9fb1b79c414c2d8afe05c">> -> <<>> (0 resources)
2015-01-09 18:17:01.942 [debug] <0.5204.0>@ejabberd_router:do_route:322 route
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}
to {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"type">>,<<"unavailable">>}],[]}
2015-01-09 18:17:01.942 [debug] <0.5204.0>@ejabberd_local:do_route:296 local route
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}
to {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"type">>,<<"unav"...>>}],[]}
2015-01-09 18:17:01.943 [debug] <0.5204.0>@ejabberd_sm:do_route:514 session manager
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}
to {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"type">>,<<"unav"...>>}],[]}
2015-01-09 18:17:01.943 [debug] <0.5204.0>@ejabberd_router:do_route:322 route
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"localhost">>,<<"7f317c7fcbb9fb1b79c414c2d8afe05c">>}
to {jid,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>,<<"bc1234">>,<<"jaxlecho.localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"type">>,<<"unavailable">>}],[]}
2015-01-09 18:17:01.979 [debug] <0.2710.0>@ejabberd_receiver:process_data:343 Received XML on stream = <<"<presence to=\"agent1@localhost\" from=\"anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost\" type=\"unavailable\"/>">>
2015-01-09 18:17:01.980 [debug] <0.2710.0>@shaper:update:117 State: {maxrate,50000,2758.812554086063,1420823816833522}, Size=121
M=1.2443286962872686, I=5146.855
2015-01-09 18:17:01.981 [debug] <0.2711.0>@ejabberd_router:do_route:322 route
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<>>,<<"agent1">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"to">>,<<"agent1@localhost">>},{<<"from">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost">>},{<<"type">>,<<"unavailable">>}],[]}
2015-01-09 18:17:01.981 [debug] <0.2711.0>@ejabberd_local:do_route:296 local route
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<>>,<<"agent1">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"to">>,<<"agen"...>>},{<<"from">>,<<...>>},{<<...>>,...}],[]}
2015-01-09 18:17:01.982 [debug] <0.2711.0>@ejabberd_sm:do_route:514 session manager
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<>>,<<"agent1">>,<<"localhost">>,<<>>}
packet {xmlel,<<"presence">>,[{<<"to">>,<<"agen"...>>},{<<"from">>,<<...>>},{<<...>>,...}],[]}
2015-01-09 18:17:01.982 [debug] <0.2711.0>@ejabberd_sm:do_route:514 session manager
from {jid,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0">>,<<"jaxlecho.localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>}
packet {xmlel,<<"presence">>,[{<<"to">>,<<"agen"...>>},{<<"from">>,<<...>>},{<<...>>,...}],[]}
2015-01-09 18:17:01.982 [debug] <0.2711.0>@ejabberd_sm:do_route:612 sending to process <0.4053.0>
2015-01-09 18:17:01.983 [debug] <0.5218.0>@ejabberd_http_bind:prepare_response:914 OutPacket: [{xmlstreamelement,{xmlel,<<"presence">>,[{<<"from">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost">>},{<<"to">>,<<"agent1@localhost/cb_operator_1420818758533">>},{<<"type">>,<<"unavailable">>}],[]}}]
2015-01-09 18:17:01.983 [debug] <0.5218.0>@ejabberd_http_bind:send_outpacket:1056 --- outgoing data ---
<body xmlns='http://jabber.org/protocol/httpbind'><presence xmlns='jabber:client' from='anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost' to='agent1@localhost/cb_operator_1420818758533' type='unavailable'/></body>
--- END ---
2015-01-09 18:17:02.093 [info] <0.2709.0>@ejabberd_listener:accept:313 (#Port<0.41559>) Accepted connection ::FFFF:127.0.0.1:58337 -> ::FFFF:127.0.0.1:5280
2015-01-09 18:17:02.094 [debug] <0.5220.0>@ejabberd_http:init:145 S: [{[<<"presence">>],mod_webpresence},{[<<"captcha">>],ejabberd_captcha},{[<<"admin">>],ejabberd_web_admin},{[<<"http-bind">>],mod_http_bind},{[<<"http-poll">>],ejabberd_http_poll}]
2015-01-09 18:17:02.094 [info] <0.5220.0>@ejabberd_http:init:149 started: {gen_tcp,#Port<0.41559>}
2015-01-09 18:17:02.094 [debug] <0.5220.0>@ejabberd_http:process_header:277 (#Port<0.41559>) http query: 'POST' /http-bind
2015-01-09 18:17:02.094 [debug] <0.5220.0>@ejabberd_http:process_request:438 client data: <<"<body rid='876142' sid='a1e29cded4325f591bdc5b3646a8f3c78553cdd8' xmlns='http://jabber.org/protocol/httpbind' key='0ea55b9d53ef97a29a35c9afdb145a0243a78a23'/>">>
2015-01-09 18:17:02.095 [debug] <0.5220.0>@ejabberd_http:process:350 [<<"http-bind">>] matches [<<"http-bind">>]
2015-01-09 18:17:02.095 [debug] <0.5220.0>@ejabberd_http:process:354 [{'Accept-Language',<<"en-US,en;q=0.8,es;q=0.6,und;q=0.4,nl;q=0.2,ca;q=0.2">>},{'Accept-Encoding',<<"gzip, deflate">>},{'Referer',<<"http://localhost:9080/">>},{'Accept',<<"*/*">>},{'Content-Type',<<"text/xml; charset=UTF-8">>},{'User-Agent',<<"Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/39.0.2171.95 Safari/537.36">>},{<<"Origin">>,<<"http://localhost:9080">>},{'Content-Length',<<"158">>},{'Connection',<<"close">>},{'Host',<<"vagrant.localhost">>}]
2015-01-09 18:17:02.095 [debug] <0.5220.0>@mod_http_bind:process:68 Incoming data: <body rid='876142' sid='a1e29cded4325f591bdc5b3646a8f3c78553cdd8' xmlns='http://jabber.org/protocol/httpbind' key='0ea55b9d53ef97a29a35c9afdb145a0243a78a23'/>
2015-01-09 18:17:02.096 [debug] <0.5220.0>@ejabberd_http_bind:parse_request:1128 --- incoming data ---
<body rid='876142' sid='a1e29cded4325f591bdc5b3646a8f3c78553cdd8' xmlns='http://jabber.org/protocol/httpbind' key='0ea55b9d53ef97a29a35c9afdb145a0243a78a23'/>
--- END ---
2015-01-09 18:17:02.096 [debug] <0.5220.0>@ejabberd_http_bind:http_put:792 Looking for session: <<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>
2015-01-09 18:17:02.097 [debug] <0.4052.0>@ejabberd_http_bind:handle_sync_event:440 New request: {http_put,876142,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"876142">>},{<<"sid">>,<<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>},{<<"key">>,<<"0ea55b9d53ef97a29a35c9afdb145a0243a78a23">>}],[],158,1,<<>>,{{0,0,0,0,0,65535,32512,1},58337}}
2015-01-09 18:17:02.097 [debug] <0.4052.0>@ejabberd_http_bind:handle_http_put_event:575 New request: {http_put,876142,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"876142">>},{<<"sid">>,<<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>},{<<"key">>,<<"0ea55b9d53ef97a29a35c9afdb145a0243a78a23">>}],[],158,1,<<>>,{{0,0,0,0,0,65535,32512,1},58337}}
2015-01-09 18:17:02.097 [debug] <0.4052.0>@ejabberd_http_bind:rid_allow:866 Previous rid / New rid: 876141/876142
2015-01-09 18:17:02.097 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:608 Actually processing request: {http_put,876142,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"876142">>},{<<"sid">>,<<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>},{<<"key">>,<<"0ea55b9d53ef97a29a35c9afdb145a0243a78a23">>}],[],158,1,<<>>,{{0,0,0,0,0,65535,32512,1},58337}}
2015-01-09 18:17:02.098 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:619 Key/OldKey/NextKey: 0ea55b9d53ef97a29a35c9afdb145a0243a78a23/c41220113a917dffdda6bd9bcb59599a13e795c0/c41220113a917dffdda6bd9bcb59599a13e795c0
2015-01-09 18:17:02.098 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:659 -- SaveKey: 0ea55b9d53ef97a29a35c9afdb145a0243a78a23
2015-01-09 18:17:02.098 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:671 reqlist: [{hbr,876142,<<"c41220113a917dffdda6bd9bcb59599a13e795c0">>,[]},{hbr,876141,<<"c41220113a917dffdda6bd9bcb59599a13e795c0">>,[{xmlstreamelement,{xmlel,<<"presence">>,[{<<"from">>,<<"anonymous.c6502a950667fc79c3e1534559cc02e0@jaxlecho.localhost">>},{<<"to">>,<<"agent1@localhost/cb_operator_1420818758533">>},{<<"type">>,<<"unavailable">>}],[]}}]}]
2015-01-09 18:17:02.098 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:717 really sending now: []
2015-01-09 18:17:14.218 [debug] <0.2648.0>@ejabberd_router:do_route:322 route
from {jid,<<>>,<<"localhost">>,<<>>,<<>>,<<"localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>}
packet {xmlel,<<"iq">>,[{<<"id">>,<<"3698984713">>},{<<"type">>,<<"get">>}],[{xmlel,<<"ping">>,[{<<"xmlns">>,<<"urn:xmpp:ping">>}],[]}]}
2015-01-09 18:17:14.218 [debug] <0.2648.0>@ejabberd_local:do_route:296 local route
from {jid,<<>>,<<"localhost">>,<<>>,<<>>,<<"localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>}
packet {xmlel,<<"iq">>,[{<<"id">>,<<"3698"...>>},{<<"type">>,<<...>>}],[{xmlel,<<...>>,...}]}
2015-01-09 18:17:14.218 [debug] <0.2648.0>@ejabberd_sm:do_route:514 session manager
from {jid,<<>>,<<"localhost">>,<<>>,<<>>,<<"localhost">>,<<>>}
to {jid,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>}
packet {xmlel,<<"iq">>,[{<<"id">>,<<"3698"...>>},{<<"type">>,<<...>>}],[{xmlel,<<...>>,...}]}
2015-01-09 18:17:14.218 [debug] <0.2648.0>@ejabberd_sm:do_route:612 sending to process <0.4053.0>
2015-01-09 18:17:14.219 [debug] <0.5220.0>@ejabberd_http_bind:prepare_response:914 OutPacket: [{xmlstreamelement,{xmlel,<<"iq">>,[{<<"from">>,<<"localhost">>},{<<"to">>,<<"agent1@localhost/cb_operator_1420818758533">>},{<<"id">>,<<"3698984713">>},{<<"type">>,<<"get">>}],[{xmlel,<<"ping">>,[{<<"xmlns">>,<<"urn:xmpp:ping">>}],[]}]}}]
2015-01-09 18:17:14.219 [debug] <0.5220.0>@ejabberd_http_bind:send_outpacket:1056 --- outgoing data ---
<body xmlns='http://jabber.org/protocol/httpbind'><iq xmlns='jabber:client' from='localhost' to='agent1@localhost/cb_operator_1420818758533' id='3698984713' type='get'><ping xmlns='urn:xmpp:ping'/></iq></body>
--- END ---
2015-01-09 18:17:14.248 [info] <0.2709.0>@ejabberd_listener:accept:313 (#Port<0.41561>) Accepted connection ::FFFF:127.0.0.1:58338 -> ::FFFF:127.0.0.1:5280
2015-01-09 18:17:14.249 [debug] <0.5221.0>@ejabberd_http:init:145 S: [{[<<"presence">>],mod_webpresence},{[<<"captcha">>],ejabberd_captcha},{[<<"admin">>],ejabberd_web_admin},{[<<"http-bind">>],mod_http_bind},{[<<"http-poll">>],ejabberd_http_poll}]
2015-01-09 18:17:14.249 [info] <0.5221.0>@ejabberd_http:init:149 started: {gen_tcp,#Port<0.41561>}
2015-01-09 18:17:14.250 [debug] <0.5221.0>@ejabberd_http:process_header:277 (#Port<0.41561>) http query: 'POST' /http-bind
2015-01-09 18:17:14.250 [debug] <0.5221.0>@ejabberd_http:process_request:438 client data: <<"<body rid='876143' sid='a1e29cded4325f591bdc5b3646a8f3c78553cdd8' xmlns='http://jabber.org/protocol/httpbind' key='7ff59a9be00a511c77210b36c01e7324769c4703'><iq xmlns=\"jabber:client\" to=\"localhost\" id=\"3698984713\" type=\"result\"><ping xmlns=\"urn:xmpp:ping\"/></iq></body>">>
2015-01-09 18:17:14.251 [debug] <0.5221.0>@ejabberd_http:process:350 [<<"http-bind">>] matches [<<"http-bind">>]
2015-01-09 18:17:14.251 [debug] <0.5221.0>@ejabberd_http:process:354 [{'Accept-Language',<<"en-US,en;q=0.8,es;q=0.6,und;q=0.4,nl;q=0.2,ca;q=0.2">>},{'Accept-Encoding',<<"gzip, deflate">>},{'Referer',<<"http://localhost:9080/">>},{'Accept',<<"*/*">>},{'Content-Type',<<"text/xml; charset=UTF-8">>},{'User-Agent',<<"Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/39.0.2171.95 Safari/537.36">>},{<<"Origin">>,<<"http://localhost:9080">>},{'Content-Length',<<"269">>},{'Connection',<<"close">>},{'Host',<<"vagrant.localhost">>}]
2015-01-09 18:17:14.252 [debug] <0.5221.0>@mod_http_bind:process:68 Incoming data: <body rid='876143' sid='a1e29cded4325f591bdc5b3646a8f3c78553cdd8' xmlns='http://jabber.org/protocol/httpbind' key='7ff59a9be00a511c77210b36c01e7324769c4703'><iq xmlns="jabber:client" to="localhost" id="3698984713" type="result"><ping xmlns="urn:xmpp:ping"/></iq></body>
2015-01-09 18:17:14.252 [debug] <0.5221.0>@ejabberd_http_bind:parse_request:1128 --- incoming data ---
<body rid='876143' sid='a1e29cded4325f591bdc5b3646a8f3c78553cdd8' xmlns='http://jabber.org/protocol/httpbind' key='7ff59a9be00a511c77210b36c01e7324769c4703'><iq xmlns="jabber:client" to="localhost" id="3698984713" type="result"><ping xmlns="urn:xmpp:ping"/></iq></body>
--- END ---
2015-01-09 18:17:14.253 [debug] <0.5221.0>@ejabberd_http_bind:http_put:792 Looking for session: <<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>
2015-01-09 18:17:14.254 [debug] <0.4052.0>@ejabberd_http_bind:handle_sync_event:440 New request: {http_put,876143,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"876143">>},{<<"sid">>,<<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>},{<<"key">>,<<"7ff59a9be00a511c77210b36c01e7324769c4703">>}],[{xmlel,<<"iq">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"to">>,<<"localhost">>},{<<"id">>,<<"3698984713">>},{<<"type">>,<<"result">>}],[{xmlel,<<"ping">>,[{<<"xmlns">>,<<"urn:xmpp:ping">>}],[]}]}],269,1,<<>>,{{0,0,0,0,0,65535,32512,1},58338}}
2015-01-09 18:17:14.254 [debug] <0.4052.0>@ejabberd_http_bind:handle_http_put_event:575 New request: {http_put,876143,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"876143">>},{<<"sid">>,<<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>},{<<"key">>,<<"7ff59a9be00a511c77210b36c01e7324769c4703">>}],[{xmlel,<<"iq">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"to">>,<<"localhost">>},{<<"id">>,<<"3698984713">>},{<<"type">>,<<"result">>}],[{xmlel,<<"ping">>,[{<<"xmlns">>,<<"urn:xmpp:ping">>}],[]}]}],269,1,<<>>,{{0,0,0,0,0,65535,32512,1},58338}}
2015-01-09 18:17:14.254 [debug] <0.4052.0>@ejabberd_http_bind:rid_allow:866 Previous rid / New rid: 876142/876143
2015-01-09 18:17:14.254 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:608 Actually processing request: {http_put,876143,[{<<"xmlns">>,<<"http://jabber.org/protocol/httpbind">>},{<<"rid">>,<<"876143">>},{<<"sid">>,<<"a1e29cded4325f591bdc5b3646a8f3c78553cdd8">>},{<<"key">>,<<"7ff59a9be00a511c77210b36c01e7324769c4703">>}],[{xmlel,<<"iq">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"to">>,<<"localhost">>},{<<"id">>,<<"3698984713">>},{<<"type">>,<<"result">>}],[{xmlel,<<"ping">>,[{<<"xmlns">>,<<"urn:xmpp:ping">>}],[]}]}],269,1,<<>>,{{0,0,0,0,0,65535,32512,1},58338}}
2015-01-09 18:17:14.254 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:619 Key/OldKey/NextKey: 7ff59a9be00a511c77210b36c01e7324769c4703/0ea55b9d53ef97a29a35c9afdb145a0243a78a23/0ea55b9d53ef97a29a35c9afdb145a0243a78a23
2015-01-09 18:17:14.254 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:659 -- SaveKey: 7ff59a9be00a511c77210b36c01e7324769c4703
2015-01-09 18:17:14.255 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:671 reqlist: [{hbr,876143,<<"0ea55b9d53ef97a29a35c9afdb145a0243a78a23">>,[]},{hbr,876142,<<"0ea55b9d53ef97a29a35c9afdb145a0243a78a23">>,[{xmlstreamelement,{xmlel,<<"iq">>,[{<<"from">>,<<"localhost">>},{<<"to">>,<<"agent1@localhost/cb_operator_1420818758533">>},{<<"id">>,<<"3698984713">>},{<<"type">>,<<"get">>}],[{xmlel,<<"ping">>,[{<<"xmlns">>,<<"urn:xmpp:ping">>}],[]}]}}]}]
2015-01-09 18:17:14.255 [debug] <0.4052.0>@ejabberd_http_bind:process_http_put:717 really sending now: [{xmlel,<<"iq">>,[{<<"xmlns">>,<<"jabber:client">>},{<<"to">>,<<"localhost">>},{<<"id">>,<<"3698984713">>},{<<"type">>,<<"result">>}],[{xmlel,<<"ping">>,[{<<"xmlns">>,<<"urn:xmpp:ping">>}],[]}]}]
2015-01-09 18:17:14.255 [debug] <0.4053.0>@ejabberd_router:do_route:322 route
from {jid,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>}
to {jid,<<>>,<<"localhost">>,<<>>,<<>>,<<"localhost">>,<<>>}
packet {xmlel,<<"iq">>,[{<<"to">>,<<"localhost">>},{<<"id">>,<<"3698984713">>},{<<"type">>,<<"result">>}],[{xmlel,<<"ping">>,[{<<"xmlns">>,<<"urn:xmpp:ping">>}],[]}]}
2015-01-09 18:17:14.255 [debug] <0.4053.0>@ejabberd_local:do_route:296 local route
from {jid,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>,<<"agent1">>,<<"localhost">>,<<"cb_operator_1420818758533">>}
to {jid,<<>>,<<"localhost">>,<<>>,<<>>,<<"localhost">>,<<>>}
packet {xmlel,<<"iq">>,[{<<"to">>,<<"loca"...>>},{<<"id">>,<<...>>},{<<...>>,...}],[{xmlel,<<...>>,...}]}
2015-01-09 18:17:26.988 [info] <0.2709.0>@ejabberd_listener:accept:313 (#Port<0.41563>) Accepted connection ::FFFF:127.0.0.1:58340 -> ::FFFF:127.0.0.1:5280
2015-01-09 18:17:26.989 [debug] <0.5222.0>@ejabberd_http:init:145 S: [{[<<"presence">>],mod_webpresence},{[<<"captcha">>],ejabberd_captcha},{[<<"admin">>],ejabberd_web_admin},{[<<"http-bind">>],mod_http_bind},{[<<"http-poll">>],ejabberd_http_poll}]
2015-01-09 18:17:26.989 [info] <0.5222.0>@ejabberd_http:init:149 started: {gen_tcp,#Port<0.41563>}
2015-01-09 18:17:26.990 [debug] <0.5222.0>@ejabberd_http:process_header:277 (#Port<0.41563>) http query: 'POST' /http-bind
2015-01-09 18:17:26.991 [debug] <0.5222.0>@ejabberd_http:process_request:438 client data: <<"<body rid='1634610728' sid='a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3' xmlns='http://jabber.org/protocol/httpbind'><presence xmlns=\"jabber:client\" type=\"available\" to=\"bc1234@jaxlecho.localhost\"><status>available</status><show>chat</show></presence></body>">>
2015-01-09 18:17:26.991 [debug] <0.5222.0>@ejabberd_http:process:350 [<<"http-bind">>] matches [<<"http-bind">>]
2015-01-09 18:17:26.993 [debug] <0.5222.0>@ejabberd_http:process:354 [{'Cookie',<<"_cb_secure=%7B%2254ac093c612c34.56437777%22%3A%7B%22_cbtt%22%3A%2254628a0d7e4e9086e015ec69f281ed2195c9ca304ff43288b2578b494584bd76%22%7D%7D; _cb_settings=%7B%2254ac093c612c34.56437777%22%3A%7B%22_cbchat%22%3A%7B%22session%22%3A%7B%22jid%22%3A%22anonymous.c6502a950667fc79c3e1534559cc02e0%40localhost%2F7f317c7fcbb9fb1b79c414c2d8afe05c%22%2C%22sid%22%3A%22a6a75af0732dd2f3c1d221d03b2780cd60ff2ec3%22%2C%22rid%22%3A1634610724%7D%2C%22groupJid%22%3A%22bc1234%40jaxlecho.localhost%22%2C%22messages%22%3A%5B%5D%7D%7D%7D; _cb_analytics=null; _cb_tracking=%7B%2254ac093c612c34.56437777%22%3A%7B%22trackingOn%22%3A1%2C%22_cbs%22%3A%22b62d40341f950986066a9b28f911ba14326101ab%22%2C%22_cbv%22%3A%2297a71b9487d1b8447b00f274d3923d37d8c006d7%22%7D%7D">>},{'Accept-Language',<<"en-US,en;q=0.8,es;q=0.6,und;q=0.4,nl;q=0.2,ca;q=0.2">>},{'Accept-Encoding',<<"gzip, deflate">>},{'Accept',<<"*/*">>},{'Content-Type',<<"text/xml; charset=UTF-8">>},{'User-Agent',<<"Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/39.0.2171.95 Safari/537.36">>},{<<"Origin">>,<<"https://vagrant.localhost">>},{'Cache-Control',<<"no-cache">>},{'Pra
Raw
the initial BOSH response of the ejabberd connection manager
<body xmlns="http://jabber.org/protocol/httpbind" xmlns:xmpp="urn:xmpp:xbosh" xmlns:stream="http://etherx.jabber.org/streams" sid="42e11a86ecba387ef5f53f5b86d38a8c7dc9ef5a" wait="300" requests="2" inactivity="480" maxpause="120" polling="2" ver="1.8" from="localhost" secure="true" authid="3627063868" xmpp:version="1.0"><stream:features xmlns:stream="http://etherx.jabber.org/streams"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/><sm xmlns="urn:xmpp:sm:2"/><sm xmlns="urn:xmpp:sm:3"/><c xmlns="http://jabber.org/protocol/caps" hash="sha-1" node="http://www.process-one.net/en/ejabberd/" ver="/nWL9StXSXhEsL2wg0+s4xo/UdA="/><register xmlns="http://jabber.org/features/iq-register"/></stream:features></body>
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment