File Transfer fails when using Messages.app.

386 views
Skip to first unread message

David Harris

unread,
Sep 25, 2013, 10:40:46 AM9/25/13
to xmpp-b...@googlegroups.com
I have a working blather script that receives file transfers.  The script is a slight modification on this one:


When I send a file from Messages.app the transfer fails.  When I send a file from Adium the transfer succeeds.  Looking at the Blather debug, I see the same error with both file transfers.  

D, [2013-09-25T09:32:56.931784 #8336] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/client/client.rb:149:in `write') <iq type="error" id="iChat_2EFE5603" to="messag...@mycompany.com/0127 iMac" from="blath...@mycompany.com/kerio-3">
  <error type="cancel">
    <item-not-found xmlns="urn:ietf:params:xml:ns:xmpp-stanzas"/>
  </error>
</iq>

Messages.app fails, but Adium seems to ignore the error and continue.  Ultimately, I need this to work in Messages.app, because this is a mac-only environment and we already use Messages.app.

Any suggestions would be appreciated.

David

Ben Langfeld

unread,
Sep 25, 2013, 2:49:26 PM9/25/13
to xmpp-b...@googlegroups.com
Full logs, specifically including the iq#set corresponding to this error, would be most useful. That error on its own doesn't really tell anyone anything :)


--
You received this message because you are subscribed to the Google Groups "Blather" group.
To unsubscribe from this group and stop receiving emails from it, send an email to xmpp-blather...@googlegroups.com.
To post to this group, send email to xmpp-b...@googlegroups.com.
Visit this group at http://groups.google.com/group/xmpp-blather.
For more options, visit https://groups.google.com/groups/opt_out.

David Harris

unread,
Sep 25, 2013, 3:32:35 PM9/25/13
to xmpp-b...@googlegroups.com, b...@langfeld.me

Sorry,


Here is the entire log.


Thank you for your help.


D, [2013-09-25T14:25:37.716302 #11296] DEBUG -- : USING JID: blath...@mycompany.com

D, [2013-09-25T14:25:37.716743 #11296] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/stream/client.rb:12:in `start') <stream:stream to='mycompany.com' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0' xml:lang='en'>

D, [2013-09-25T14:25:37.719410 #11296] DEBUG -- : RECEIVING (stream) <stream:stream xmlns:stream="http://etherx.jabber.org/streams" from="mycompany.com" id="1cdc06da-b7f1-4333-beb4-96d05e8040a3" version="1.0" lang="en"/>

D, [2013-09-25T14:25:37.721184 #11296] DEBUG -- : RECEIVING (features) <stream:features xmlns:stream="http://etherx.jabber.org/streams">

  <ver xmlns="urn:xmpp:features:rosterver"/>

  <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

  <mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl">

    <mechanism>PLAIN</mechanism>

  </mechanisms>

</stream:features>

D, [2013-09-25T14:25:37.722524 #11296] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/stream/features/tls.rb:16:in `receive_data') <starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>

D, [2013-09-25T14:25:37.724184 #11296] DEBUG -- : RECEIVING (proceed) <proceed xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

D, [2013-09-25T14:25:37.725060 #11296] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/stream/client.rb:12:in `start') <stream:stream to='mycompany.com' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0' xml:lang='en'>

D, [2013-09-25T14:25:37.741995 #11296] DEBUG -- : Checking SSL cert: -----BEGIN CERTIFICATE-----

MIIDQDCCAiigAwIBAgIEToxtfjANBgkqhkiG9w0BAQUFADBiMRowGAYDVQQDExFt

YWlsLmtub2NraW5jLmNvbTEUMBIGA1UEChMLS05PQ0ssIGluYy4xFDASBgNVBAcT

C01pbm5lYXBvbGlzMQswCQYDVQQIEwJNTjELMAkGA1UEBhMCVVMwHhcNMTExMDA1

MTQ0NTE4WhcNMTQxMDA0MTQ0NTE4WjBiMRowGAYDVQQDExFtYWlsLmtub2NraW5j

LmNvbTEUMBIGA1UEChMLS05PQ0ssIGluYy4xFDASBgNVBAcTC01pbm5lYXBvbGlz

MQswCQYDVQQIEwJNTjELMAkGA1UEBhMCVVMwggEiMA0GCSqGSIb3DQEBAQUAA4IB

DwAwggEKAoIBAQC5zJz4A4LmDsYWJtnO7umGSXjKhMwdgN+UoIstOOAIzfMn2AP2

tVw198WsqO46P5rk7YU4DOJn+JzHlWXgaCExGdStISrmbdPPtC0CJ/4t833D07qY

B7F+gbe3hSP7s+gMQjfDPOHB3RGIE5MxnPsVdwrdztvvN656DbkRvjSxJ+qLaRz3

e3g6lKx0BZ9AtGP73vMVSAEmmymTPCY//R46GJwpDkMJetrGAXKQwkt0FzTKvWms

3yuy2WP+TDARP2KJqzqWgJXpHPPlJrd791e5oYs7ziZqiSsfHi7H9DIPDyVA9vqO

zK6PXEsNkFMusnJzHDbo1Hfe9FrHooOywfglAgMBAAEwDQYJKoZIhvcNAQEFBQAD

ggEBAHDkdK8n4FQlfvidaXgnfJ5uF1HJvdRI8ne/yCk6uetmOhWaUhg2ug702PYz

XEkq5FypZwRW5PBskXa52DaR6WAbmXqvFQs7utDBJW2zcyA/byFDr2qJySReYHmw

RPXfLEH7zOEpYvIZfw1ZP5Qy++Sc0YuwuV/eJlME9RtlhrW/4E/ZtC2ApIJTUVPl

YD2wz+DQbji+TX6gJENNgzflQltUufSomD8JiclVBdgzRuL6w0pokwdX+Ecvb/Jo

dZetJZg2hWYKNyi6gNAa8nx0u2abjIHTDnpcR9R0IaeLa9XZCUUJPM5a9p/RFjGy

7kNEAoln/3VtUzMC6F4SpHk2nXo=

-----END CERTIFICATE-----


D, [2013-09-25T14:25:37.742875 #11296] DEBUG -- : Checking SSL cert: -----BEGIN CERTIFICATE-----

MIIDQDCCAiigAwIBAgIEToxtfjANBgkqhkiG9w0BAQUFADBiMRowGAYDVQQDExFt

YWlsLmtub2NraW5jLmNvbTEUMBIGA1UEChMLS05PQ0ssIGluYy4xFDASBgNVBAcT

C01pbm5lYXBvbGlzMQswCQYDVQQIEwJNTjELMAkGA1UEBhMCVVMwHhcNMTExMDA1

MTQ0NTE4WhcNMTQxMDA0MTQ0NTE4WjBiMRowGAYDVQQDExFtYWlsLmtub2NraW5j

LmNvbTEUMBIGA1UEChMLS05PQ0ssIGluYy4xFDASBgNVBAcTC01pbm5lYXBvbGlz

MQswCQYDVQQIEwJNTjELMAkGA1UEBhMCVVMwggEiMA0GCSqGSIb3DQEBAQUAA4IB

DwAwggEKAoIBAQC5zJz4A4LmDsYWJtnO7umGSXjKhMwdgN+UoIstOOAIzfMn2AP2

tVw198WsqO46P5rk7YU4DOJn+JzHlWXgaCExGdStISrmbdPPtC0CJ/4t833D07qY

B7F+gbe3hSP7s+gMQjfDPOHB3RGIE5MxnPsVdwrdztvvN656DbkRvjSxJ+qLaRz3

e3g6lKx0BZ9AtGP73vMVSAEmmymTPCY//R46GJwpDkMJetrGAXKQwkt0FzTKvWms

3yuy2WP+TDARP2KJqzqWgJXpHPPlJrd791e5oYs7ziZqiSsfHi7H9DIPDyVA9vqO

zK6PXEsNkFMusnJzHDbo1Hfe9FrHooOywfglAgMBAAEwDQYJKoZIhvcNAQEFBQAD

ggEBAHDkdK8n4FQlfvidaXgnfJ5uF1HJvdRI8ne/yCk6uetmOhWaUhg2ug702PYz

XEkq5FypZwRW5PBskXa52DaR6WAbmXqvFQs7utDBJW2zcyA/byFDr2qJySReYHmw

RPXfLEH7zOEpYvIZfw1ZP5Qy++Sc0YuwuV/eJlME9RtlhrW/4E/ZtC2ApIJTUVPl

YD2wz+DQbji+TX6gJENNgzflQltUufSomD8JiclVBdgzRuL6w0pokwdX+Ecvb/Jo

dZetJZg2hWYKNyi6gNAa8nx0u2abjIHTDnpcR9R0IaeLa9XZCUUJPM5a9p/RFjGy

7kNEAoln/3VtUzMC6F4SpHk2nXo=

-----END CERTIFICATE-----


D, [2013-09-25T14:25:37.789744 #11296] DEBUG -- : RECEIVING (stream) <stream:stream xmlns:stream="http://etherx.jabber.org/streams" from="mycompany.com" id="1cdc06da-b7f1-4333-beb4-96d05e8040a3" version="1.0" lang="en"/>

D, [2013-09-25T14:25:37.828645 #11296] DEBUG -- : RECEIVING (features) <stream:features xmlns:stream="http://etherx.jabber.org/streams">

  <ver xmlns="urn:xmpp:features:rosterver"/>

  <mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl">

    <mechanism>PLAIN</mechanism>

  </mechanisms>

</stream:features>

D, [2013-09-25T14:25:37.830217 #11296] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/stream/features/sasl.rb:177:in `authenticate') <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="PLAIN">c3VwcG9ydEBrbm9ja2luYy5jb20Ac3VwcG9ydABmdDI2NDJoZW5u</auth>

D, [2013-09-25T14:25:38.757077 #11296] DEBUG -- : RECEIVING (success) <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/>

D, [2013-09-25T14:25:38.757510 #11296] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/stream/client.rb:12:in `start') <stream:stream to='mycompany.com' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0' xml:lang='en'>

D, [2013-09-25T14:25:38.759598 #11296] DEBUG -- : RECEIVING (stream) <stream:stream xmlns:stream="http://etherx.jabber.org/streams" from="mycompany.com" id="1cdc06da-b7f1-4333-beb4-96d05e8040a3" version="1.0" lang="en"/>

D, [2013-09-25T14:25:38.800004 #11296] DEBUG -- : RECEIVING (features) <stream:features xmlns:stream="http://etherx.jabber.org/streams">

  <ver xmlns="urn:xmpp:features:rosterver"/>

  <session xmlns="urn:ietf:params:xml:ns:xmpp-session"/>

  <bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/>

</stream:features>

D, [2013-09-25T14:25:38.801390 #11296] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/stream/features/resource.rb:39:in `bind') <iq type="set" id="blather0002">

  <bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/>

</iq>

D, [2013-09-25T14:25:38.805011 #11296] DEBUG -- : RECEIVING (iq) <iq to="blath...@mycompany.com/kerio-55" type="result" id="blather0002">

  <bind xmlns="urn:ietf:params:xml:ns:xmpp-bind">

    <jid>blath...@mycompany.com/kerio-55</jid>

  </bind>

</iq>

D, [2013-09-25T14:25:38.805911 #11296] DEBUG -- : USING JID: blath...@mycompany.com/kerio-55

D, [2013-09-25T14:25:38.806434 #11296] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/stream/features/session.rb:40:in `session') <iq type="set" id="blather0004" to="mycompany.com">

  <session xmlns="urn:ietf:params:xml:ns:xmpp-session"/>

</iq>

D, [2013-09-25T14:25:38.808873 #11296] DEBUG -- : RECEIVING (iq) <iq type="result" id="blather0004" to="blath...@mycompany.com/kerio-55" from="mycompany.com"/>

D, [2013-09-25T14:25:38.809504 #11296] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/client/client.rb:149:in `write') <iq type="get" id="blather0006">

  <query xmlns="jabber:iq:roster"/>

</iq>

D, [2013-09-25T14:25:38.827926 #11296] DEBUG -- : RECEIVING (iq) <iq to="blath...@mycompany.com/kerio-55" type="result" id="blather0006">

  <query xmlns="jabber:iq:roster">

    <item jid="xxxxx...@mycompany.com" subscription="both" name="xxxxx.xxxxx"/>

    <item jid="xxxxx...@mycompany.com" subscription="both" name="xxxxx.xxxxx"/>

    <item jid="xxxxx...@mycompany.com" subscription="both" name="xxxxx.xxxxx"/>

    <item jid="messag...@mycompany.com" subscription="both" name="messages.user">

      <group>Buddies</group>

    </item>

    <item jid="xxxxx...@mycompany.com" subscription="both" name="xxxxx.xxxxx"/>

  </query>

</iq>

D, [2013-09-25T14:25:38.838780 #11296] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/client/client.rb:149:in `write') <presence/>

Connected to blath...@mycompany.com/kerio-55. Sent capabilities:

D, [2013-09-25T14:25:38.843306 #11296] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/client/client.rb:149:in `write') <presence>

  <c xmlns="http://jabber.org/protocol/caps" hash="sha-1" node="http://www.apple.com/ichat/caps" ver="CJjxSk493LAtmP0kZv/hwWw/5IE="/>

</presence>

<iq type="result" id="blather0001">

  <query xmlns="http://jabber.org/protocol/disco#info" node="http://www.apple.com/ichat/caps#CJjxSk493LAtmP0kZv/hwWw/5IE=">

    <identity name="Tech Support" type="bot" category="client"/>

    <feature var="http://jabber.org/protocol/ibb"/>

    <feature var="http://jabber.org/protocol/bytestreams"/>

    <feature var="http://jabber.org/protocol/si"/>

    <feature var="http://jabber.org/protocol/si/profile/file-transfer"/>

  </query>

</iq>


D, [2013-09-25T14:25:38.845141 #11296] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/client/client.rb:149:in `write') <presence>

  <status>Chat with me . . . I am Chat Bot</status>

</presence>

D, [2013-09-25T14:25:38.845991 #11296] DEBUG -- : RECEIVING (presence) <presence to="blath...@mycompany.com" from="blath...@mycompany.com/kerio-55"/>

D, [2013-09-25T14:25:38.847745 #11296] DEBUG -- : RECEIVING (presence) <presence to="blath...@mycompany.com" from="blath...@mycompany.com/kerio-55">

  <c xmlns="http://jabber.org/protocol/caps" ver="CJjxSk493LAtmP0kZv/hwWw/5IE=" node="http://www.apple.com/ichat/caps" hash="sha-1"/>

</presence>

D, [2013-09-25T14:25:38.854000 #11296] DEBUG -- : RECEIVING (presence) <presence to="blath...@mycompany.com" from="messag...@mycompany.com/0127 iMac"><priority>0</priority>

<c xmlns="http://jabber.org/protocol/caps" ver="900" node="http://www.apple.com/ichat/caps" ext="ice recauth rdserver maudio audio rdclient mvideo auxvideo rdmuxing avcap avavail video"/><x xmlns="http://jabber.org/protocol/tune"/><x xmlns="vcard-temp:x:update"/></presence>

D, [2013-09-25T14:25:38.855051 #11296] DEBUG -- : RECEIVING (presence) <presence to="blath...@mycompany.com" from="xxxxx...@mycompany.com/0056 Mac Pro"><priority>0</priority>

<c xmlns="http://jabber.org/protocol/caps" ver="900" node="http://www.apple.com/ichat/caps" ext="ice"/><x xmlns="http://jabber.org/protocol/tune"/><x xmlns="vcard-temp:x:update"><photo>93B16AAD130CE68DAFD7D4E150A9657F1C95F4FB</photo>

</x>

</presence>

D, [2013-09-25T14:25:38.856170 #11296] DEBUG -- : RECEIVING (presence) <presence to="blath...@mycompany.com" from="xxxxx...@mycompany.com/0114 MacBook Pro"><priority>0</priority>

<c xmlns="http://jabber.org/protocol/caps" ver="900" node="http://www.apple.com/ichat/caps" ext="ice recauth rdserver maudio audio rdclient mvideo auxvideo rdmuxing avcap avavail video"/><x xmlns="http://jabber.org/protocol/tune"/><x xmlns="vcard-temp:x:update"/></presence>

D, [2013-09-25T14:25:38.857342 #11296] DEBUG -- : RECEIVING (presence) <presence to="blath...@mycompany.com" from="blath...@mycompany.com/kerio-55">

  <status>Chat with me . . . I am Chat Bot</status>

</presence>

D, [2013-09-25T14:25:38.858275 #11296] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_17BAD4A0" to="blath...@mycompany.com/kerio-55" from="messag...@mycompany.com/0127 iMac">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-25T14:25:38.859522 #11296] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_17BAD4A0" to="blath...@mycompany.com/kerio-55" from="messag...@mycompany.com/0127 iMac">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-25T14:25:38.860686 #11296] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_73A4AC3C" to="blath...@mycompany.com/kerio-55" from="xxxxx...@mycompany.com/0056 Mac Pro">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-25T14:25:38.861812 #11296] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_73A4AC3C" to="blath...@mycompany.com/kerio-55" from="xxxxx...@mycompany.com/0056 Mac Pro">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-25T14:25:38.862950 #11296] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_73A4AC3C" to="blath...@mycompany.com/kerio-55" from="xxxxx...@mycompany.com/0056 Mac Pro">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-25T14:25:38.864176 #11296] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_73A4AC3C" to="blath...@mycompany.com/kerio-55" from="xxxxx...@mycompany.com/0056 Mac Pro">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-25T14:25:38.865325 #11296] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_17BAD4A0" to="blath...@mycompany.com/kerio-55" from="messag...@mycompany.com/0127 iMac">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-25T14:25:38.866433 #11296] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_17BAD4A0" to="blath...@mycompany.com/kerio-55" from="messag...@mycompany.com/0127 iMac">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-25T14:25:38.867749 #11296] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_39EDE86A" to="blath...@mycompany.com/kerio-55" from="xxxxx...@mycompany.com/0114 MacBook Pro">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-25T14:25:38.868982 #11296] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_39EDE86A" to="blath...@mycompany.com/kerio-55" from="xxxxx...@mycompany.com/0114 MacBook Pro">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-25T14:25:38.870146 #11296] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_39EDE86A" to="blath...@mycompany.com/kerio-55" from="xxxxx...@mycompany.com/0114 MacBook Pro">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-25T14:25:38.871252 #11296] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_39EDE86A" to="blath...@mycompany.com/kerio-55" from="xxxxx...@mycompany.com/0114 MacBook Pro">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-25T14:26:33.064521 #11296] DEBUG -- : RECEIVING (message) <message type="chat" id="iChat_15D1212F" to="blath...@mycompany.com" from="messag...@mycompany.com/0127 iMac">

  <composing xmlns="http://jabber.org/protocol/chatstates"/>

</message>

D, [2013-09-25T14:26:34.280143 #11296] DEBUG -- : RECEIVING (message) <message type="chat" id="C4EB3872-F72D-4804-9D32-A3D10171BB3C" to="blath...@mycompany.com" from="messag...@mycompany.com/0127 iMac"><body/>

<html xmlns="http://jabber.org/protocol/xhtml-im"><body xmlns="http://www.w3.org/1999/xhtml" style="background-color:#e9e9e9;color:#000000;"/></html><active xmlns="http://jabber.org/protocol/chatstates"/></message>

D, [2013-09-25T14:26:34.282316 #11296] DEBUG -- : RECEIVING (iq) <iq type="set" id="iChat_42547B64" to="blath...@mycompany.com/kerio-55" from="messag...@mycompany.com/0127 iMac"><si xmlns="http://jabber.org/protocol/si" profile="http://jabber.org/protocol/si/profile/file-transfer" id="sid_3B30C99C" mime-type="binary/octet-stream"><file xmlns:ichat="apple:profile:transfer-extensions" xmlns="http://jabber.org/protocol/si/profile/file-transfer" size="65658" posixflags="000001B4" name="test.png"/><feature xmlns="http://jabber.org/protocol/feature-neg"><x xmlns="jabber:x:data" type="form"><field type="list-single" var="stream-method"><option><value>http://jabber.org/protocol/bytestreams</value>

</option>

</field>

</x>

</feature>

</si>

</iq>

we got a file

D, [2013-09-25T14:26:34.289800 #11296] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/client/client.rb:149:in `write') <iq type="result" id="iChat_42547B64" to="messag...@mycompany.com/0127 iMac" from="blath...@mycompany.com/kerio-55">

  <si xmlns="http://jabber.org/protocol/si">

    <feature xmlns="http://jabber.org/protocol/feature-neg">

      <x xmlns="jabber:x:data" type="submit">

        <field var="stream-method">

          <value>http://jabber.org/protocol/bytestreams</value>

        </field>

      </x>

    </feature>

  </si>

</iq>

Receiving file from messag...@mycompany.com/0127 iMac

D, [2013-09-25T14:26:34.292885 #11296] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/client/client.rb:149:in `write') <message to="messag...@mycompany.com/0127 iMac" type="chat">

  <body>Your image has been forwarded to the world</body>

  <active xmlns="http://jabber.org/protocol/chatstates"/>

</message>

D, [2013-09-25T14:26:34.298045 #11296] DEBUG -- : RECEIVING (iq) <iq type="set" id="iChat_3F411EA5" to="blath...@mycompany.com/kerio-55" from="messag...@mycompany.com/0127 iMac"><query xmlns="http://jabber.org/protocol/bytestreams" sid="sid_3B30C99C"><streamhost jid="messag...@mycompany.com/0127 iMac" host="192.168.122.89" port="49255"/></query>

</iq>

D, [2013-09-25T14:26:34.300351 #11296] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/client/client.rb:149:in `write') <iq type="error" id="iChat_3F411EA5" to="messag...@mycompany.com/0127 iMac" from="blath...@mycompany.com/kerio-55">

Ben Langfeld

unread,
Sep 26, 2013, 9:24:03 PM9/26/13
to David Harris, xmpp-b...@googlegroups.com
I *think* this is probably your issue: https://github.com/adhearsion/blather/blob/develop/lib/blather/file_transfer/s5b.rb#L10

Try setting that true before accepting the transfer and see what happens. If that doesn't help, I'll have to fiddle with a reproduction here.

Ben

Ben Langfeld

unread,
Sep 26, 2013, 9:24:48 PM9/26/13
to David Harris, xmpp-b...@googlegroups.com
If that is indeed the issue, I'd like to know about it, and I will add some noisy logging or change the default.

Ben Langfeld

unread,
Sep 26, 2013, 9:38:40 PM9/26/13
to David Harris, xmpp-b...@googlegroups.com

David Harris

unread,
Sep 26, 2013, 11:34:09 PM9/26/13
to xmpp-b...@googlegroups.com, David Harris, b...@langfeld.me
Thank you for your help.  This is much closer.  I can now transfer files, but it is a little buggy.  When I send the image, the transfer log says "Waiting."  It will continue to wait until I begin typing the next messages (or even just put my cursor in the message box).  In most cases, that is all it takes to send the image.  On occasion, it will fail if I don't finesse it just right.

Can you look at my handler and let me know if there is something I could be doing better?

Thank you.

client.register_handler :file_transfer do |iq| 
    transfer = Blather::FileTransfer.new(client, iq)
    transfer.allow_private_ips = true
    transfer.accept(
      Blather::FileTransfer::SimpleFileReceiver,
      "/path/to/files/#{iq.si.file["name"]}",  # destination path
      iq.si.file["size"].to_i,
    ) 
    puts "Receiving file from #{iq.from}"
    puts iq.si.file.to_xml
    puts
end

Ben

D, [2013-09-25T14:25:38.845991 #11296] DEBUG -- : RECEIVING (presence) <presence to="blath...@mycompany.com" from="blatherbot@mycompany.com/kerio-55"/>

D, [2013-09-25T14:25:38.847745 #11296] DEBUG -- : RECEIVING (presence) <presence to="blath...@mycompany.com" from="blatherbot@mycompany.com/kerio-55">

  <c xmlns="http://jabber.org/protocol/caps" ver="CJjxSk493LAtmP0kZv/hwWw/5IE=" node="http://www.apple.com/ichat/caps" hash="sha-1"/>

</presence>

D, [2013-09-25T14:25:38.854000 #11296] DEBUG -- : RECEIVING (presence) <presence to="blath...@mycompany.com" from="messages.user@mycompany.com/0127 iMac"><priority>0</priority>

<c xmlns="http://jabber.org/protocol/caps" ver="900" node="http://www.apple.com/ichat/caps" ext="ice recauth rdserver maudio audio rdclient mvideo auxvideo rdmuxing avcap avavail video"/><x xmlns="http://jabber.org/protocol/tune"/><x xmlns="vcard-temp:x:update"/></presence>

D, [2013-09-25T14:25:38.855051 #11296] DEBUG -- : RECEIVING (presence) <presence to="blath...@mycompany.com" from="xxxxx.xxxxx@mycompany.com/0056 Mac Pro"><priority>0</priority>

<c xmlns="http://jabber.org/protocol/caps" ver="900" node="http://www.apple.com/ichat/caps" ext="ice"/><x xmlns="http://jabber.org/protocol/tune"/><x xmlns="vcard-temp:x:update"><photo>93B16AAD130CE68DAFD7D4E150A9657F1C95F4FB</photo>

</x>

</presence>

D, [2013-09-25T14:25:38.856170 #11296] DEBUG -- : RECEIVING (presence) <presence to="blath...@mycompany.com" from="xxxxx.xxxxx@mycompany.com/0114 MacBook Pro"><priority>0</priority>

<c xmlns="http://jabber.org/protocol/caps" ver="900" node="http://www.apple.com/ichat/caps" ext="ice recauth rdserver maudio audio rdclient mvideo auxvideo rdmuxing avcap avavail video"/><x xmlns="http://jabber.org/protocol/tune"/><x xmlns="vcard-temp:x:update"/></presence>

D, [2013-09-25T14:25:38.857342 #11296] DEBUG -- : RECEIVING (presence) <presence to="blath...@mycompany.com" from="blatherbot@mycompany.com/kerio-55">

  <status>Chat with me . . . I am Chat Bot</status>

</presence>

D, [2013-09-25T14:25:38.858275 #11296] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_17BAD4A0" to="blath...@mycompany.com/kerio-55" from="messages.user@mycompany.com/0127 iMac">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-25T14:25:38.859522 #11296] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_17BAD4A0" to="blath...@mycompany.com/kerio-55" from="messages.user@mycompany.com/0127 iMac">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-25T14:25:38.860686 #11296] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_73A4AC3C" to="blath...@mycompany.com/kerio-55" from="xxxxx.xxxxx@mycompany.com/0056 Mac Pro">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-25T14:25:38.861812 #11296] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_73A4AC3C" to="blath...@mycompany.com/kerio-55" from="xxxxx.xxxxx@mycompany.com/0056 Mac Pro">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-25T14:25:38.862950 #11296] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_73A4AC3C" to="blath...@mycompany.com/kerio-55" from="xxxxx.xxxxx@mycompany.com/0056 Mac Pro">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-25T14:25:38.864176 #11296] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_73A4AC3C" to="blath...@mycompany.com/kerio-55" from="xxxxx.xxxxx@mycompany.com/0056 Mac Pro">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-25T14:25:38.865325 #11296] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_17BAD4A0" to="blath...@mycompany.com/kerio-55" from="messages.user@mycompany.com/0127 iMac">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-25T14:25:38.866433 #11296] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_17BAD4A0" to="blath...@mycompany.com/kerio-55" from="messages.user@mycompany.com/0127 iMac">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-25T14:25:38.867749 #11296] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_39EDE86A" to="blath...@mycompany.com/kerio-55" from="xxxxx.xxxxx@mycompany.com/0114 MacBook Pro">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-25T14:25:38.868982 #11296] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_39EDE86A" to="blath...@mycompany.com/kerio-55" from="xxxxx.xxxxx@mycompany.com/0114 MacBook Pro">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-25T14:25:38.870146 #11296] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_39EDE86A" to="blath...@mycompany.com/kerio-55" from="xxxxx.xxxxx@mycompany.com/0114 MacBook Pro">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-25T14:25:38.871252 #11296] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_39EDE86A" to="blath...@mycompany.com/kerio-55" from="xxxxx.xxxxx@mycompany.com/0114 MacBook Pro">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-25T14:26:33.064521 #11296] DEBUG -- : RECEIVING (message) <message type="chat" id="iChat_15D1212F" to="blath...@mycompany.com" from="messages.user@mycompany.com/0127 iMac">

  <composing xmlns="http://jabber.org/protocol/chatstates"/>

</message>

D, [2013-09-25T14:26:34.280143 #11296] DEBUG -- : RECEIVING (message) <message type="chat" id="C4EB3872-F72D-4804-9D32-A3D10171BB3C" to="blath...@mycompany.com" from="messages.user@mycompany.com/0127 iMac"><body/>

<html xmlns="http://jabber.org/protocol/xhtml-im"><body xmlns="http://www.w3.org/1999/xhtml" style="background-color:#e9e9e9;color:#000000;"/></html><active xmlns="http://jabber.org/protocol/chatstates"/></message>

D, [2013-09-25T14:26:34.282316 #11296] DEBUG -- : RECEIVING (iq) <iq type="set" id="iChat_42547B64" to="blath...@mycompany.com/kerio-55" from="messages.user@mycompany.com/0127 iMac"><si xmlns="http://jabber.org/protocol/si" profile="http://jabber.org/protocol/si/profile/file-transfer" id="sid_3B30C99C" mime-type="binary/octet-stream"><file xmlns:ichat="apple:profile:transfer-extensions" xmlns="http://jabber.org/protocol/si/profile/file-transfer" size="65658" posixflags="000001B4" name="test.png"/><feature xmlns="http://jabber.org/protocol/feature-neg"><x xmlns="jabber:x:data" type="form"><field type="list-single" var="stream-method"><option><value>http://jabber.org/protocol/bytestreams</value>

</option>

</field>

</x>

</feature>

</si>

</iq>

we got a file

D, [2013-09-25T14:26:34.289800 #11296] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/client/client.rb:149:in `write') <iq type="result" id="iChat_42547B64" to="messages.user@mycompany.com/0127 iMac" from="blatherbot@mycompany.com/kerio-55">

  <si xmlns="http://jabber.org/protocol/si">

    <feature xmlns="http://jabber.org/protocol/feature-neg">

      <x xmlns="jabber:x:data" type="submit">

        <field var="stream-method">

          <value>http://jabber.org/protocol/bytestreams</value>

        </field>

      </x>

    </feature>

  </si>

</iq>

Receiving file from messag...@mycompany.com/0127 iMac

D, [2013-09-25T14:26:34.292885 #11296] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/client/client.rb:149:in `write') <message to="messages.user@mycompany.com/0127 iMac" type="chat">

  <body>Your image has been forwarded to the world</body>

  <active xmlns="http://jabber.org/protocol/chatstates"/>

</message>

D, [2013-09-25T14:26:34.298045 #11296] DEBUG -- : RECEIVING (iq) <iq type="set" id="iChat_3F411EA5" to="blath...@mycompany.com/kerio-55" from="messages.user@mycompany.com/0127 iMac"><query xmlns="http://jabber.org/protocol/bytestreams" sid="sid_3B30C99C"><streamhost jid="messages.user@mycompany.com/0127 iMac" host="192.168.122.89" port="49255"/></query>

</iq>

D, [2013-09-25T14:26:34.300351 #11296] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/client/client.rb:149:in `write') <iq type="error" id="iChat_3F411EA5" to="messages.user@mycompany.com/0127 iMac" from="blatherbot@mycompany.com/kerio-55">

Ben Langfeld

unread,
Sep 27, 2013, 8:52:08 AM9/27/13
to David Harris, xmpp-b...@googlegroups.com
Can you please provide a new log?

Ben Langfeld

unread,
Sep 27, 2013, 9:27:35 AM9/27/13
to David Harris, xmpp-b...@googlegroups.com
So, I'm asking for a new log to check all the correct stanzas are sent/received, but I suspect they now are. My biggest suspicion right now is something related to EM 1.x requiring a call to #next_tick quite often, like here: https://github.com/adhearsion/blather/commit/1d5af784c27921c336228674dfcfef0427430514 or here: https://github.com/adhearsion/blather/commit/facf772e4db1d10b477b778112178050710f807a.

If you could get a wire capture covering both 5222 and the offered transfer port, that'd help.

David Harris

unread,
Sep 27, 2013, 10:35:05 AM9/27/13
to xmpp-b...@googlegroups.com, David Harris, b...@langfeld.me
Here is the new log:

D, [2013-09-27T08:58:43.999551 #28550] DEBUG -- : USING JID: blath...@mycompany.com

D, [2013-09-27T08:58:44.000649 #28550] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/stream/client.rb:12:in `start') <stream:stream to='mycompany.com' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0' xml:lang='en'>

D, [2013-09-27T08:58:44.003443 #28550] DEBUG -- : RECEIVING (stream) <stream:stream xmlns:stream="http://etherx.jabber.org/streams" from="mycompany.com" id="975cecc0-69d5-4fed-bcd2-8c2a80a9bc3a" version="1.0" lang="en"/>

D, [2013-09-27T08:58:44.006047 #28550] DEBUG -- : RECEIVING (features) <stream:features xmlns:stream="http://etherx.jabber.org/streams">

  <ver xmlns="urn:xmpp:features:rosterver"/>

  <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

  <mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl">

    <mechanism>PLAIN</mechanism>

  </mechanisms>

</stream:features>

D, [2013-09-27T08:58:44.006820 #28550] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/stream/features/tls.rb:16:in `receive_data') <starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>

D, [2013-09-27T08:58:44.010321 #28550] DEBUG -- : RECEIVING (proceed) <proceed xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

D, [2013-09-27T08:58:44.011172 #28550] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/stream/client.rb:12:in `start') <stream:stream to='mycompany.com' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0' xml:lang='en'>

D, [2013-09-27T08:58:44.042673 #28550] DEBUG -- : Checking SSL cert: -----BEGIN CERTIFICATE-----

MIIDQDCCAiigAwIBAgIEToxtfjANBgkqhkiG9w0BAQUFADBiMRowGAYDVQQDExFt

YWlsLmtub2NraW5jLmNvbTEUMBIGA1UEChMLS05PQ0ssIGluYy4xFDASBgNVBAcT

C01pbm5lYXBvbGlzMQswCQYDVQQIEwJNTjELMAkGA1UEBhMCVVMwHhcNMTExMDA1

MTQ0NTE4WhcNMTQxMDA0MTQ0NTE4WjBiMRowGAYDVQQDExFtYWlsLmtub2NraW5j

LmNvbTEUMBIGA1UEChMLS05PQ0ssIGluYy4xFDASBgNVBAcTC01pbm5lYXBvbGlz

MQswCQYDVQQIEwJNTjELMAkGA1UEBhMCVVMwggEiMA0GCSqGSIb3DQEBAQUAA4IB

DwAwggEKAoIBAQC5zJz4A4LmDsYWJtnO7umGSXjKhMwdgN+UoIstOOAIzfMn2AP2

tVw198WsqO46P5rk7YU4DOJn+JzHlWXgaCExGdStISrmbdPPtC0CJ/4t833D07qY

B7F+gbe3hSP7s+gMQjfDPOHB3RGIE5MxnPsVdwrdztvvN656DbkRvjSxJ+qLaRz3

e3g6lKx0BZ9AtGP73vMVSAEmmymTPCY//R46GJwpDkMJetrGAXKQwkt0FzTKvWms

3yuy2WP+TDARP2KJqzqWgJXpHPPlJrd791e5oYs7ziZqiSsfHi7H9DIPDyVA9vqO

zK6PXEsNkFMusnJzHDbo1Hfe9FrHooOywfglAgMBAAEwDQYJKoZIhvcNAQEFBQAD

ggEBAHDkdK8n4FQlfvidaXgnfJ5uF1HJvdRI8ne/yCk6uetmOhWaUhg2ug702PYz

XEkq5FypZwRW5PBskXa52DaR6WAbmXqvFQs7utDBJW2zcyA/byFDr2qJySReYHmw

RPXfLEH7zOEpYvIZfw1ZP5Qy++Sc0YuwuV/eJlME9RtlhrW/4E/ZtC2ApIJTUVPl

YD2wz+DQbji+TX6gJENNgzflQltUufSomD8JiclVBdgzRuL6w0pokwdX+Ecvb/Jo

dZetJZg2hWYKNyi6gNAa8nx0u2abjIHTDnpcR9R0IaeLa9XZCUUJPM5a9p/RFjGy

7kNEAoln/3VtUzMC6F4SpHk2nXo=

-----END CERTIFICATE-----


D, [2013-09-27T08:58:44.043469 #28550] DEBUG -- : Checking SSL cert: -----BEGIN CERTIFICATE-----

MIIDQDCCAiigAwIBAgIEToxtfjANBgkqhkiG9w0BAQUFADBiMRowGAYDVQQDExFt

YWlsLmtub2NraW5jLmNvbTEUMBIGA1UEChMLS05PQ0ssIGluYy4xFDASBgNVBAcT

C01pbm5lYXBvbGlzMQswCQYDVQQIEwJNTjELMAkGA1UEBhMCVVMwHhcNMTExMDA1

MTQ0NTE4WhcNMTQxMDA0MTQ0NTE4WjBiMRowGAYDVQQDExFtYWlsLmtub2NraW5j

LmNvbTEUMBIGA1UEChMLS05PQ0ssIGluYy4xFDASBgNVBAcTC01pbm5lYXBvbGlz

MQswCQYDVQQIEwJNTjELMAkGA1UEBhMCVVMwggEiMA0GCSqGSIb3DQEBAQUAA4IB

DwAwggEKAoIBAQC5zJz4A4LmDsYWJtnO7umGSXjKhMwdgN+UoIstOOAIzfMn2AP2

tVw198WsqO46P5rk7YU4DOJn+JzHlWXgaCExGdStISrmbdPPtC0CJ/4t833D07qY

B7F+gbe3hSP7s+gMQjfDPOHB3RGIE5MxnPsVdwrdztvvN656DbkRvjSxJ+qLaRz3

e3g6lKx0BZ9AtGP73vMVSAEmmymTPCY//R46GJwpDkMJetrGAXKQwkt0FzTKvWms

3yuy2WP+TDARP2KJqzqWgJXpHPPlJrd791e5oYs7ziZqiSsfHi7H9DIPDyVA9vqO

zK6PXEsNkFMusnJzHDbo1Hfe9FrHooOywfglAgMBAAEwDQYJKoZIhvcNAQEFBQAD

ggEBAHDkdK8n4FQlfvidaXgnfJ5uF1HJvdRI8ne/yCk6uetmOhWaUhg2ug702PYz

XEkq5FypZwRW5PBskXa52DaR6WAbmXqvFQs7utDBJW2zcyA/byFDr2qJySReYHmw

RPXfLEH7zOEpYvIZfw1ZP5Qy++Sc0YuwuV/eJlME9RtlhrW/4E/ZtC2ApIJTUVPl

YD2wz+DQbji+TX6gJENNgzflQltUufSomD8JiclVBdgzRuL6w0pokwdX+Ecvb/Jo

dZetJZg2hWYKNyi6gNAa8nx0u2abjIHTDnpcR9R0IaeLa9XZCUUJPM5a9p/RFjGy

7kNEAoln/3VtUzMC6F4SpHk2nXo=

-----END CERTIFICATE-----


D, [2013-09-27T08:58:44.089361 #28550] DEBUG -- : RECEIVING (stream) <stream:stream xmlns:stream="http://etherx.jabber.org/streams" from="mycompany.com" id="975cecc0-69d5-4fed-bcd2-8c2a80a9bc3a" version="1.0" lang="en"/>

D, [2013-09-27T08:58:44.127987 #28550] DEBUG -- : RECEIVING (features) <stream:features xmlns:stream="http://etherx.jabber.org/streams">

  <ver xmlns="urn:xmpp:features:rosterver"/>

  <mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl">

    <mechanism>PLAIN</mechanism>

  </mechanisms>

</stream:features>

D, [2013-09-27T08:58:44.128891 #28550] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/stream/features/sasl.rb:177:in `authenticate') <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="PLAIN">c3VwcG9ydEBrbm9ja2luYy5jb20Ac3VwcG9ydABmdDI2NDJoZW5u</auth>

D, [2013-09-27T08:58:44.224835 #28550] DEBUG -- : RECEIVING (success) <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/>

D, [2013-09-27T08:58:44.225216 #28550] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/stream/client.rb:12:in `start') <stream:stream to='mycompany.com' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0' xml:lang='en'>

D, [2013-09-27T08:58:44.227229 #28550] DEBUG -- : RECEIVING (stream) <stream:stream xmlns:stream="http://etherx.jabber.org/streams" from="mycompany.com" id="975cecc0-69d5-4fed-bcd2-8c2a80a9bc3a" version="1.0" lang="en"/>

D, [2013-09-27T08:58:44.263851 #28550] DEBUG -- : RECEIVING (features) <stream:features xmlns:stream="http://etherx.jabber.org/streams">

  <ver xmlns="urn:xmpp:features:rosterver"/>

  <session xmlns="urn:ietf:params:xml:ns:xmpp-session"/>

  <bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/>

</stream:features>

D, [2013-09-27T08:58:44.264930 #28550] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/stream/features/resource.rb:39:in `bind') <iq type="set" id="blather0002">

  <bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/>

</iq>

D, [2013-09-27T08:58:44.267220 #28550] DEBUG -- : RECEIVING (iq) <iq to="blath...@mycompany.com/kerio-88" type="result" id="blather0002">

  <bind xmlns="urn:ietf:params:xml:ns:xmpp-bind">

    <jid>blath...@mycompany.com/kerio-88</jid>

  </bind>

</iq>

D, [2013-09-27T08:58:44.267757 #28550] DEBUG -- : USING JID: blath...@mycompany.com/kerio-88

D, [2013-09-27T08:58:44.268154 #28550] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/stream/features/session.rb:40:in `session') <iq type="set" id="blather0004" to="mycompany.com">

  <session xmlns="urn:ietf:params:xml:ns:xmpp-session"/>

</iq>

D, [2013-09-27T08:58:44.271491 #28550] DEBUG -- : RECEIVING (iq) <iq type="result" id="blather0004" to="blath...@mycompany.com/kerio-88" from="mycompany.com"/>

D, [2013-09-27T08:58:44.272015 #28550] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/client/client.rb:149:in `write') <iq type="get" id="blather0006">

  <query xmlns="jabber:iq:roster"/>

</iq>

D, [2013-09-27T08:58:44.296418 #28550] DEBUG -- : RECEIVING (iq) <iq to="blath...@mycompany.com/kerio-88" type="result" id="blather0006">

  <query xmlns="jabber:iq:roster">

    <item jid="user...@mycompany.com" subscription="both" name="user.name"/>

    <item jid="user...@mycompany.com" subscription="both" name="user.name"/>

    <item jid="user...@mycompany.com" subscription="both" name="user.name"/>

    <item jid="messag...@mycompany.com" subscription="both" name="david.harris">

      <group>Buddies</group>

    </item>

    <item jid="user...@mycompany.com" subscription="both" name="user.name"/>

  </query>

</iq>

D, [2013-09-27T08:58:44.307158 #28550] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/client/client.rb:149:in `write') <presence/>

Connected to blath...@mycompany.com/kerio-88. Sent capabilities:

D, [2013-09-27T08:58:44.311862 #28550] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/client/client.rb:149:in `write') <presence>

  <c xmlns="http://jabber.org/protocol/caps" hash="sha-1" node="http://www.apple.com/ichat/caps" ver="CJjxSk493LAtmP0kZv/hwWw/5IE="/>

</presence>

<iq type="result" id="blather0001">

  <query xmlns="http://jabber.org/protocol/disco#info" node="http://www.apple.com/ichat/caps#CJjxSk493LAtmP0kZv/hwWw/5IE=">

    <identity name="Tech Support" type="bot" category="client"/>

    <feature var="http://jabber.org/protocol/ibb"/>

    <feature var="http://jabber.org/protocol/bytestreams"/>

    <feature var="http://jabber.org/protocol/si"/>

    <feature var="http://jabber.org/protocol/si/profile/file-transfer"/>

  </query>

</iq>


D, [2013-09-27T08:58:44.313128 #28550] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/client/client.rb:149:in `write') <presence>

  <status>Chat with me . . . I am KNOCKBot</status>

</presence>

D, [2013-09-27T08:58:44.315321 #28550] DEBUG -- : RECEIVING (presence) <presence to="blath...@mycompany.com" from="blath...@mycompany.com/kerio-88"/>

D, [2013-09-27T08:58:44.319924 #28550] DEBUG -- : RECEIVING (presence) <presence to="blath...@mycompany.com" from="blath...@mycompany.com/kerio-88">

  <c xmlns="http://jabber.org/protocol/caps" ver="CJjxSk493LAtmP0kZv/hwWw/5IE=" node="http://www.apple.com/ichat/caps" hash="sha-1"/>

</presence>

D, [2013-09-27T08:58:44.320626 #28550] DEBUG -- : RECEIVING (presence) <presence to="blath...@mycompany.com" from="blath...@mycompany.com/kerio-88">

  <status>Chat with me . . . I am KNOCKBot</status>

</presence>

D, [2013-09-27T08:58:44.321541 #28550] DEBUG -- : RECEIVING (presence) <presence to="blath...@mycompany.com" from="messag...@mycompany.com/Magnolia Server"><priority>0</priority>

<c xmlns="http://jabber.org/protocol/caps" ver="900" node="http://www.apple.com/ichat/caps" ext="mvideo maudio avcap recauth rdserver avavail auxvideo ice rdclient rdmuxing"/><x xmlns="http://jabber.org/protocol/tune"/><x xmlns="vcard-temp:x:update"><photo>1BED3F493C8DF58A9DB29EBFE55A396446608C4E</photo>

</x>

</presence>

D, [2013-09-27T08:58:44.322750 #28550] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_44D84FF5" to="blath...@mycompany.com/kerio-88" from="messag...@mycompany.com/Magnolia Server">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-27T08:58:44.324205 #28550] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_44D84FF5" to="blath...@mycompany.com/kerio-88" from="messag...@mycompany.com/Magnolia Server">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-27T08:58:44.359731 #28550] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_44D84FF5" to="blath...@mycompany.com/kerio-88" from="messag...@mycompany.com/Magnolia Server">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-27T08:58:44.360636 #28550] DEBUG -- : RECEIVING (iq) <iq type="get" id="iChat_44D84FF5" to="blath...@mycompany.com/kerio-88" from="messag...@mycompany.com/Magnolia Server">

  <query xmlns="http://jabber.org/protocol/disco#info"/>

</iq>

D, [2013-09-27T08:58:52.591834 #28550] DEBUG -- : RECEIVING (message) <message type="chat" id="A429401A-5BE0-441D-B513-00B94FB3BF79" to="blath...@mycompany.com" from="messag...@mycompany.com/Magnolia Server"><body/>

<html xmlns="http://jabber.org/protocol/xhtml-im"><body xmlns="http://www.w3.org/1999/xhtml" style="background-color:#e9e9e9;color:#000000;"/></html><x xmlns="jabber:x:event"><composing/></x>

<active xmlns="http://jabber.org/protocol/chatstates"/></message>

D, [2013-09-27T08:58:52.763881 #28550] DEBUG -- : RECEIVING (iq) <iq type="set" id="iChat_6044F1F4" to="blath...@mycompany.com/kerio-88" from="messag...@mycompany.com/Magnolia Server"><si xmlns="http://jabber.org/protocol/si" profile="http://jabber.org/protocol/si/profile/file-transfer" id="sid_ 862A239" mime-type="binary/octet-stream"><file xmlns:ichat="apple:profile:transfer-extensions" xmlns="http://jabber.org/protocol/si/profile/file-transfer" size="4138" hfsflags="00000010" posixflags="000001A4" name="image.png"/><feature xmlns="http://jabber.org/protocol/feature-neg"><x xmlns="jabber:x:data" type="form"><field type="list-single" var="stream-method"><option><value>http://jabber.org/protocol/bytestreams</value>

</option>

</field>

</x>

</feature>

</si>

</iq>

we got a file

D, [2013-09-27T08:58:52.769537 #28550] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/client/client.rb:149:in `write') <iq type="result" id="iChat_6044F1F4" to="messag...@mycompany.com/Magnolia Server" from="blath...@mycompany.com/kerio-88">

  <si xmlns="http://jabber.org/protocol/si">

    <feature xmlns="http://jabber.org/protocol/feature-neg">

      <x xmlns="jabber:x:data" type="submit">

        <field var="stream-method">

          <value>http://jabber.org/protocol/bytestreams</value>

        </field>

      </x>

    </feature>

  </si>

</iq>

Receiving file from messag...@mycompany.com/Magnolia Server

D, [2013-09-27T08:58:52.770730 #28550] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/client/client.rb:149:in `write') <message to="messag...@mycompany.com/Magnolia Server" type="chat">

  <body>Your image has been forwarded to the world</body>

  <active xmlns="http://jabber.org/protocol/chatstates"/>

</message>

D, [2013-09-27T08:58:52.776746 #28550] DEBUG -- : RECEIVING (iq) <iq type="set" id="iChat_5E060665" to="blath...@mycompany.com/kerio-88" from="messag...@mycompany.com/Magnolia Server"><query xmlns="http://jabber.org/protocol/bytestreams" sid="sid_ 862A239"><streamhost jid="messag...@mycompany.com/Magnolia Server" host="192.168.123.63" port="49870"/></query>

</iq>

D, [2013-09-27T08:59:01.008705 #28550] DEBUG -- : RECEIVING (message) <message type="chat" id="iChat_655A948F" to="blath...@mycompany.com/kerio-88" from="messag...@mycompany.com/Magnolia Server">

  <composing xmlns="http://jabber.org/protocol/chatstates"/>

</message>

D, [2013-09-27T08:59:01.011624 #28550] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/client/client.rb:149:in `write') <iq type="result" id="iChat_5E060665" to="messag...@mycompany.com/Magnolia Server" from="blath...@mycompany.com/kerio-88">

  <query xmlns="http://jabber.org/protocol/bytestreams">

    <streamhost-used jid="messag...@mycompany.com/Magnolia Server"/>

  </query>

</iq>

David Harris

unread,
Sep 27, 2013, 11:16:20 AM9/27/13
to xmpp-b...@googlegroups.com
Thank you so much for your time.  

I'm working on a wire capture . . . I might not get to it until next week as I'm not exactly sure how to do it and I'm out of the office at the moment.

I noticed this morning that the transfers were working without issue.  I think my troubles are related to the script starting up.  Sometimes when I start the script and try to send it a file, Messages.app says the the user cannot accept file transfers.  If I restart the script, I won't get that error, but I will get the waiting issues.   Then if I restart the script a few more times, at some point it will accept file transfers and I won't get the waiting issue.  It seems pretty random.

Thanks again for your time.

David


On Wednesday, September 25, 2013 9:40:46 AM UTC-5, David Harris wrote:
I have a working blather script that receives file transfers.  The script is a slight modification on this one:


When I send a file from Messages.app the transfer fails.  When I send a file from Adium the transfer succeeds.  Looking at the Blather debug, I see the same error with both file transfers.  

D, [2013-09-25T09:32:56.931784 #8336] DEBUG -- : SENDING: (/var/lib/gems/1.9.1/gems/blather-0.8.7/lib/blather/client/client.rb:149:in `write') <iq type="error" id="iChat_2EFE5603" to="messages.user@mycompany.com/0127 iMac" from="blatherbot@mycompany.com/kerio-3">
  <error type="cancel">
    <item-not-found xmlns="urn:ietf:params:xml:ns:xmpp-stanzas"/>
  </error>
</iq>

Ben Langfeld

unread,
Sep 27, 2013, 12:10:23 PM9/27/13
to xmpp-b...@googlegroups.com
So you'll see that last stanza is not going out until after you receive a composing message from iMessage. This indicates a problem with #next_tick, and so I don't need a wire capture. I'll repro locally and get a fix in later today.


--

Andre LaBranche

unread,
Sep 27, 2013, 2:28:29 PM9/27/13
to xmpp-b...@googlegroups.com, b...@langfeld.me
Hi! I'm in the same boat (I wrote the short example that dharris referenced). This next_tick issue is the only remaining problem I'm seeing. Thanks for your time :)

-dre

Ben Langfeld

unread,
Sep 30, 2013, 5:20:51 PM9/30/13
to Andre LaBranche, xmpp-b...@googlegroups.com
I've released Blather 0.8.8 with a fix for this issue. Let me know how you guys get on.

David Harris

unread,
Oct 1, 2013, 1:04:34 PM10/1/13
to xmpp-b...@googlegroups.com, Andre LaBranche, b...@langfeld.me
I updated this morning and everything is working great!   Thank you for your time.

David
Reply all
Reply to author
Forward
0 new messages