XMPP failure/queue question

smscottensmscotten
in Installation
I have been trying to figure out why XMPP isn't working on my system. I've found that my queue_item table has about 500 items in it, all with "transport" xmppout and "claimed" NULL.

My first question is: once delivered, will the queue items disappear or just have something put into "claimed."

Second, is it more likely that the queue is stuck or that all these items are queued up waiting for XMPP to connect? I've been investigating why XMPP won't connect but should I be investigating the queue instead?

My error log has these entries:

2010-04-26 18:35:00 LOG_INFO: [status.smscotten.com:xmppdaemon.php:28347] Backgrounding daemon "xmppdaemon.generic"
2010-04-26 18:35:00 LOG_INFO: [status.smscotten.com:xmppdaemon.php:28347] Successfully forked.
2010-04-26 18:35:00 LOG_INFO: [status.smscotten.com:xmppdaemon.php:28362] XMPPDaemon (generic): Spawned thread 1 as pid 28383
2010-04-26 18:35:00 LOG_INFO: [status.smscotten.com:xmppdaemon.php:28383] Waiting to listen to XMPP and queues
2010-04-26 18:35:00 LOG_INFO: [status.smscotten.com:xmppdaemon.php:28383] INITIALIZE
2010-04-26 18:35:00 LOG_INFO: [status.smscotten.com:xmppdaemon.php:28383] jabber_connect: connecting update/smscottenupdatedaemon
2010-04-26 18:35:01 LOG_INFO: [status.smscotten.com:xmppdaemon.php:28362] XMPPDaemon (generic): Waiting for children to complete.

Is "waiting for children to complete" my xmpp daemon getting hung up on something, or does that indicate that it's going nicely?

from config.php:

# xmpp
$config['xmpp']['enabled'] = true;
$config['xmpp']['server'] = 'status.smscotten.com'; # XMPP server name
$config['xmpp']['host'] = NULL; # Only set if different from server
$config['xmpp']['port'] = 5222;
$config['xmpp']['user'] = 'update'; # set to what ever user name is registered on XMPP server
$config['xmpp']['encryption'] = false;
$config['xmpp']['resource'] = 'smscottenupdate';
$config['xmpp']['password'] = 'xxxxxx';
$config['xmpp']['debug'] = true;

Comments

  • aricaric
    @smscotten which jabber server are you running, check its debug logs, it sounds as tho it is mis-configured.
  • smscottensmscotten
    @aric

    [ps9666]$ /usr/sbin/jabberd -v
    Jabberd Version 1.4.3

    In record.log I don't show any attempts to log in from update@status.smscotten.com, failed or otherwise.

    In error.log I grepped for 'smscotten' and 'update' (separately, of course) and came up empty. However, I did run across thousands of these which may be my problem:

    20100427T08:54:58: [notice] (MIO_XML_READ): socket from [my ip address] is out of karma: traffic exceeds karma limits defined in jabberd configuration.
  • aricaric
    @smscotten traffic exceeds karma limits defined in jabberd configuration. ???
    im not familiar with jabberd, i cant confirm or deny its compatiablilty, tho in theory and jabber server should work. I suppose you create an update user in your jabber db.
  • smscottensmscotten
    @aric Yeah, apparently there was too much traffic coming from SN in too short a period of time. Although that doesn't explain why my update user never connected in the first place.
  • smscottensmscotten
    OK, I changed my karma limits and restarted jabberd. I'm not yet seeing any of the above karma messages but I haven't seen my update user come online. I have these messages in my error.log since restarting:

    20100427T19:42:44: [warn] (jit/wp_client.cpp:659): Unknown SNAC packet received - Family: 0x1 Subtype: 0x15
    20100427T19:42:44: [warn] (jit/wp_client.cpp:659): Buffer pointer not at end after parsing FLAP was: 0x97 should be: 0x114 on channel 0x2
    20100427T19:42:44: [warn] (jit/wp_client.cpp:659): Unknown SNAC packet received - Family: 0x3 Subtype: 0x1
    20100427T19:42:44: [warn] (jit/wp_client.cpp:659): Unknown SNAC packet received - Family: 0x9 Subtype: 0x1
    20100427T19:42:44: [alert] (-internal): Error processing packet! LSG 10 Coworkers 0

    Do those look like signs of any known issue?
  • smscottensmscotten
    Back to the questions I actually asked:

    Once delivered, will the items in my queue table disappear or just change the value of "claimed?"
  • aricaric
    @smscotten that does not look promising, are you dedicated to jabberd? prosody.im is awsome!
  • smscottensmscotten
    I'm trying to keep the number of variances from stock Dreamhost to a minimum, but if that's what I have to do, that's what I have to do. I've been hoping to smooth the path for other DH users, since easy implementation on a popular host is a good thing for SN in its stated goal to be the Wordpress of microblogging.

    But since getting a full-featured SN install on DH requires getting a private server *and* recompiling PHP, I've already pretty much failed in that quest. What's installing and maintaining a Jabber server on top of that?
  • smscottensmscotten
    So regarding the inability to log in to jabberd, I have a feature request: if the XMPP connection fails, there should be a message written to the log. Even if there's no more information than that available, it at least would save people like me all the hassle of trying to figure out if the problem was on the IM client's side.

    I mean, that is the point of having a log, right?
  • smscottensmscotten
    BTW, with $config['xmpp']['debuglog'] = true; should log messages go to the same log as other statusnet log messages? I'm trying to rule out not seeing log messages because they go somewhere else.
  • aricaric
    @smscotten config xmpp debug true and they go to stdout when you run the daemon.
  • smscottensmscotten
    OK, I have

    $config['xmpp']['debug'] = true;
    $config['xmpp']['debuglog'] = true;

    and all I get when I run the daemon is:

    2010-06-01 17:43:53 LOG_INFO: [status.smscotten.com:xmppdaemon.php:23135] Backgrounding daemon "xmppdaemon.generic"
    2010-06-01 17:43:53 LOG_INFO: [status.smscotten.com:xmppdaemon.php:23135] Successfully forked.
    2010-06-01 17:43:53 LOG_INFO: [status.smscotten.com:xmppdaemon.php:23141] XMPPDaemon (generic): Spawned thread 1 as pid 23142
    2010-06-01 17:43:53 LOG_INFO: [status.smscotten.com:xmppdaemon.php:23142] Waiting to listen to XMPP and queues
    2010-06-01 17:43:53 LOG_INFO: [status.smscotten.com:xmppdaemon.php:23142] INITIALIZE
    2010-06-01 17:43:53 LOG_INFO: [status.smscotten.com:xmppdaemon.php:23142] jabber_connect: connecting update/smscottenupdatedaemon
    2010-06-01 17:43:54 LOG_INFO: [status.smscotten.com:xmppdaemon.php:23141] XMPPDaemon (generic): Waiting for children to complete.

    Does anything there suggest a failure for the daemon to connect to the update account? Or are there other settings I need in config.php?
  • aricaric
    @smscotten as we are both online perhaps irc would be a better place to debug this?
  • aricaric
    $config['xmpp']['debuglog'] = true; <--just remove this, its not used.
  • smscottensmscotten
    Sorry I wasn't able to get on IRC then.

    So here is the set of messages I get when starting the xmppdaemon. I think I posted these in another thread but they are relevant here.

    Starting /[path]/scripts/xmppdaemon.php...1276195970 [INFO]: Connecting to tcp://status.smscotten.com:5222
    1276195970 [VERBOSE]: Socket is ready; send it.
    1276195970 [VERBOSE]: SENT:
    1276195970 [VERBOSE]: Successfully sent 125 bytes.
    1276195970 [VERBOSE]: RECV: <?xml version='1.0'?>http://etherx.jabber.org/streams' id='4C113482' xmlns='jabber:client' from='status.smscotten.com'>

    It looks like a very nice send and receive, doesn't it?

    When I try to use a gtalk account to test, I get a legitimate [ERROR]: Could not connect. While my config may be wrong to use gtalk as my statusnet site's xmpp account, it shows that the xmppdaemon is really trying to connect, and seems to be at least sort of connecting to jabberd.

    So.... I set up a jabber.org account. Everything seemed to go smoothly until I tried to post via XMPP. Then this came up:

    1276198732 [VERBOSE]: SENT: [status.smscotten.com] Unknown user; go to http://status.smscotten.com/settings/im to add your address to your account
    1276198732 [VERBOSE]: Successfully sent 218 bytes.
    1276198732 [VERBOSE]: RECV:

    Of course, at http://status.smscotten.com/settings/im it shows the address as "current/confirmed". I removed the address and added it again, same result.

    Just more information; seems to all go nowhere. No idea what it all means.
  • smscottensmscotten
    Sorry I wasn't able to get on IRC then.

    So here is the set of messages I get when starting the xmppdaemon. I think I posted these in another thread but they are relevant here.

    Starting /[path]/scripts/xmppdaemon.php...1276195970 [INFO]: Connecting to tcp://status.smscotten.com:5222
    1276195970 [VERBOSE]: Socket is ready; send it.
    1276195970 [VERBOSE]: SENT:
    1276195970 [VERBOSE]: Successfully sent 125 bytes.
    1276195970 [VERBOSE]: RECV: <?xml version='1.0'?>http://etherx.jabber.org/streams' id='4C113482' xmlns='jabber:client' from='status.smscotten.com'>

    It looks like a very nice send and receive, doesn't it?

    When I try to use a gtalk account to test, I get a legitimate [ERROR]: Could not connect. While my config may be wrong to use gtalk as my statusnet site's xmpp account, it shows that the xmppdaemon is really trying to connect, and seems to be at least sort of connecting to jabberd.

    So.... I set up a jabber.org account. Everything seemed to go smoothly until I tried to post via XMPP. Then this came up:

    1276198732 [VERBOSE]: SENT: [status.smscotten.com] Unknown user; go to http://status.smscotten.com/settings/im to add your address to your account
    1276198732 [VERBOSE]: Successfully sent 218 bytes.
    1276198732 [VERBOSE]: RECV:

    Of course, at http://status.smscotten.com/settings/im it shows the address as "current/confirmed". I removed the address and added it again, same result.

    Just more information; seems to all go nowhere. No idea what it all means.
  • smscottensmscotten
    Sorry I wasn't able to get on IRC then.

    So here is the set of messages I get when starting the xmppdaemon. I think I posted these in another thread but they are relevant here.

    Starting /[path]/scripts/xmppdaemon.php...1276195970 [INFO]: Connecting to tcp://status.smscotten.com:5222
    1276195970 [VERBOSE]: Socket is ready; send it.
    1276195970 [VERBOSE]: SENT:
    1276195970 [VERBOSE]: Successfully sent 125 bytes.
    1276195970 [VERBOSE]: RECV: <?xml version='1.0'?>http://etherx.jabber.org/streams' id='4C113482' xmlns='jabber:client' from='status.smscotten.com'>

    It looks like a very nice send and receive, doesn't it?

    When I try to use a gtalk account to test, I get a legitimate [ERROR]: Could not connect. While my config may be wrong to use gtalk as my statusnet site's xmpp account, it shows that the xmppdaemon is really trying to connect, and seems to be at least sort of connecting to jabberd.

    So.... I set up a jabber.org account. Everything seemed to go smoothly until I tried to post via XMPP. Then this came up:

    1276198732 [VERBOSE]: SENT: [status.smscotten.com] Unknown user; go to http://status.smscotten.com/settings/im to add your address to your account
    1276198732 [VERBOSE]: Successfully sent 218 bytes.
    1276198732 [VERBOSE]: RECV:

    Of course, at http://status.smscotten.com/settings/im it shows the address as "current/confirmed". I removed the address and added it again, same result.

    Just more information; seems to all go nowhere. No idea what it all means.
  • smscottensmscotten
    Now I can't post more than a line at a time here. Great.
  • smscottensmscotten
    Sorry I wasn't able to get on IRC then.

    So here is the set of messages I get when starting the xmppdaemon. I think I posted these in another thread but they are relevant here.

    Starting /[path]/scripts/xmppdaemon.php...1276195970 [INFO]: Connecting to tcp://status.smscotten.com:5222
    1276195970 [VERBOSE]: Socket is ready; send it.
    1276195970 [VERBOSE]: SENT:
    1276195970 [VERBOSE]: Successfully sent 125 bytes.
    1276195970 [VERBOSE]: RECV: <?xml version='1.0'?>http://etherx.jabber.org/streams' id='4C113482' xmlns='jabber:client' from='status.smscotten.com'>

    It looks like a very nice send and receive, doesn't it?

    When I try to use a gtalk account to test, I get a legitimate [ERROR]: Could not connect. While my config may be wrong to use gtalk as my statusnet site's xmpp account, it shows that the xmppdaemon is really trying to connect, and seems to be at least sort of connecting to jabberd.

    So.... I set up a jabber.org account. Everything seemed to go smoothly until I tried to post via XMPP. Then this came up:

    1276198732 [VERBOSE]: SENT: [status.smscotten.com] Unknown user; go to http://status.smscotten.com/settings/im to add your address to your account
    1276198732 [VERBOSE]: Successfully sent 218 bytes.
    1276198732 [VERBOSE]: RECV:

    Of course, at http://status.smscotten.com/settings/im it shows the address as "current/confirmed". I removed the address and added it again, same result.

    Just more information; seems to all go nowhere. No idea what it all means.
  • smscottensmscotten
    Sorry I wasn't able to get on IRC then.

    So here is the set of messages I get when starting the xmppdaemon. I think I posted these in another thread but they are relevant here.

    Starting /[path]/scripts/xmppdaemon.php...1276195970 [INFO]: Connecting to tcp://status.smscotten.com:5222
    1276195970 [VERBOSE]: Socket is ready; send it.
    1276195970 [VERBOSE]: SENT:
    1276195970 [VERBOSE]: Successfully sent 125 bytes.
    1276195970 [VERBOSE]: RECV: <?xml version='1.0'?>http://etherx.jabber.org/streams' id='4C113482' xmlns='jabber:client' from='status.smscotten.com'>

    It looks like a very nice send and receive, doesn't it?

    When I try to use a gtalk account to test, I get a legitimate [ERROR]: Could not connect. While my config may be wrong to use gtalk as my statusnet site's xmpp account, it shows that the xmppdaemon is really trying to connect, and seems to be at least sort of connecting to jabberd.

    So.... I set up a jabber.org account. Everything seemed to go smoothly until I tried to post via XMPP. Then this came up:

    1276198732 [VERBOSE]: SENT: [status.smscotten.com] Unknown user; go to http://status.smscotten.com/settings/im to add your address to your account
    1276198732 [VERBOSE]: Successfully sent 218 bytes.
    1276198732 [VERBOSE]: RECV:

    Of course, at http://status.smscotten.com/settings/im it shows the address as "current/confirmed". I removed the address and added it again, same result.

    Just more information; seems to all go nowhere. No idea what it all means.
  • smscottensmscotten
    Sorry I wasn't able to get on IRC then.

    So here is the set of messages I get when starting the xmppdaemon. I think I posted these in another thread but they are relevant here.

    Starting /[path]/scripts/xmppdaemon.php...1276195970 [INFO]: Connecting to tcp://status.smscotten.com:5222
    1276195970 [VERBOSE]: Socket is ready; send it.
    1276195970 [VERBOSE]: SENT:
    1276195970 [VERBOSE]: Successfully sent 125 bytes.
    1276195970 [VERBOSE]: RECV: <?xml version='1.0'?>http://etherx.jabber.org/streams' id='4C113482' xmlns='jabber:client' from='status.smscotten.com'>

    It looks like a very nice send and receive, doesn't it?

    When I try to use a gtalk account to test, I get a legitimate [ERROR]: Could not connect. While my config may be wrong to use gtalk as my statusnet site's xmpp account, it shows that the xmppdaemon is really trying to connect, and seems to be at least sort of connecting to jabberd.

    So.... I set up a jabber.org account. Everything seemed to go smoothly until I tried to post via XMPP. Then this came up:

    1276198732 [VERBOSE]: SENT: [status.smscotten.com] Unknown user; go to http://status.smscotten.com/settings/im to add your address to your account
    1276198732 [VERBOSE]: Successfully sent 218 bytes.
    1276198732 [VERBOSE]: RECV:

    Of course, at http://status.smscotten.com/settings/im it shows the address as "current/confirmed". I removed the address and added it again, same result.

    Just more information; seems to all go nowhere. No idea what it all means.
  • smscottensmscotten
    (OK, it was the body tags in the below that screwed up all my attempts to post. Maddening!)

    Sorry I wasn't able to get on IRC then.

    So here is the set of messages I get when starting the xmppdaemon. I think I posted these in another thread but they are relevant here.

    Starting /[path]/scripts/xmppdaemon.php...1276195970 [INFO]: Connecting to tcp://status.smscotten.com:5222
    1276195970 [VERBOSE]: Socket is ready; send it.
    1276195970 [VERBOSE]: SENT:
    1276195970 [VERBOSE]: Successfully sent 125 bytes.
    1276195970 [VERBOSE]: RECV: <?xml version='1.0'?>http://etherx.jabber.org/streams' id='4C113482' xmlns='jabber:client' from='status.smscotten.com'>

    It looks like a very nice send and receive, doesn't it?

    When I try to use a gtalk account to test, I get a legitimate [ERROR]: Could not connect. While my config may be wrong to use gtalk as my statusnet site's xmpp account, it shows that the xmppdaemon is really trying to connect, and seems to be at least sort of connecting to jabberd.

    So.... I set up a jabber.org account. Everything seemed to go smoothly until I tried to post via XMPP. Then this came up:

    1276198732 [VERBOSE]: SENT: <body>[status.smscotten.com] Unknown user; go to http://status.smscotten.com/settings/im to add your address to your account</body>
    1276198732 [VERBOSE]: Successfully sent 218 bytes.
    1276198732 [VERBOSE]: RECV:

    Of course, at http://status.smscotten.com/settings/im it shows the address as "current/confirmed". I removed the address and added it again, same result.

    Just more information; seems to all go nowhere. No idea what it all means.
  • aricaric
    @smsscotten can you look in your jabber hosts database and see his friends list?

    so if you user is called update@yoursite, see if it has the friend smsscotten@jabber.org in his roster.
  • smscottensmscotten
    Actually the smscotten@jabber.org account is the statusnet account. If I log into that account with a jabber client, my personal address appears in the friend list. When I'm logged in to my personal account, smscotten@jabber.org appears in the friend list.

    To the best of my knowledge, I don't have access to jabber.org's database. Is looking at the list through my jabber client sufficient?

    I have confirmed that notices on my site get posted via XMPP to my personal jabber account and the messages come from smscotten@jabber.org. It's just the other direction that isn't working.

    I'm not sure I want to spend a lot of time on it unless it may reveal an underlying problem. Ultimately using a third-party jabber server is not a satisfactory solution. I did this only to make sure that the issues I've encountered were a result of my hosting company choosing software other than your favorite prosody.im.
Sign In or Register to comment.

Howdy, Stranger!

It looks like you're new here. If you want to get involved, click one of these buttons!

Sign In with Google Sign In with OpenID
Sign In Register

Categories