#114 [PLUGIN][Xmpp] non-responsive

Open
opened 4 years ago by chaotickjg · 25 comments

I have enabled the Xmpp plugin and I have added the corresponding JID to my contacts list. When running the startdaemons.sh script, I can see the JID appear as online in my contacts list and syslog confirms that both imdaemon and queuedaemon start successfully. However, upon adding my JID to my GNU social account, I do not receive the confirmation email, and I do not get any response back or posts to my GNU social account when trying to send messages through XMPP. My config.php is pasted below with the database info censored.

<?php
if (!defined('GNUSOCIAL')) { exit(1); }
 
$config['site']['name'] = 'Proud To Be A Pastafarian!';
 
$config['site']['server'] = 'social.proudtobeapastafarian.com';
$config['site']['path'] = '';
 
$config['site']['ssl'] = 'always';
 
$config['site']['fancy'] = true;
 
$config['db']['database'] = 'mysqli://<censored>';
 
$config['db']['type'] = 'mysql';
 
$config['site']['theme'] = 'neo-blue';
 
// Uncomment below for better performance. Just remember you must run
// php scripts/checkschema.php whenever your enabled plugins change!
$config['db']['schemacheck'] = 'script';
 
$config['site']['profile'] = 'community';
 
$config['queue']['daemon'] = true;
$config['daemon']['piddir'] = '/run/pastafarian/social/';
$config['daemon']['user'] = 'www-data';
$config['daemon']['group'] = 'www-data';
 
addPlugin('Xmpp', [
                        'user' => 'updates',
                        'server' => 'proudtobeapastafarian.com',
                        'host' => 'xmpp.proudtobeapastafarian.com',
                        'password' => 'angryglasskicksfruit',
                        'resource' => 'gnusocial'
                 ]
        );
I have enabled the Xmpp plugin and I have added the corresponding JID to my contacts list. When running the startdaemons.sh script, I can see the JID appear as online in my contacts list and syslog confirms that both imdaemon and queuedaemon start successfully. However, upon adding my JID to my GNU social account, **I do not receive the confirmation email, and I do not get any response back or posts to my GNU social account when trying to send messages through XMPP**. My config.php is pasted below with the database info censored. ```php <?php if (!defined('GNUSOCIAL')) { exit(1); } $config['site']['name'] = 'Proud To Be A Pastafarian!'; $config['site']['server'] = 'social.proudtobeapastafarian.com'; $config['site']['path'] = ''; $config['site']['ssl'] = 'always'; $config['site']['fancy'] = true; $config['db']['database'] = 'mysqli://<censored>'; $config['db']['type'] = 'mysql'; $config['site']['theme'] = 'neo-blue'; // Uncomment below for better performance. Just remember you must run // php scripts/checkschema.php whenever your enabled plugins change! $config['db']['schemacheck'] = 'script'; $config['site']['profile'] = 'community'; $config['queue']['daemon'] = true; $config['daemon']['piddir'] = '/run/pastafarian/social/'; $config['daemon']['user'] = 'www-data'; $config['daemon']['group'] = 'www-data'; addPlugin('Xmpp', [ 'user' => 'updates', 'server' => 'proudtobeapastafarian.com', 'host' => 'xmpp.proudtobeapastafarian.com', 'password' => 'angryglasskicksfruit', 'resource' => 'gnusocial' ] ); ```
Diogo Cordeiro commented 4 years ago
Owner

Do you receive any other emails, e.g.: Password Recovery email?

Do you receive any other emails, e.g.: Password Recovery email?
chaotickjg commented 4 years ago
Poster

Yes, e-mail works fine on my server, and I am able to request a password reset from my GNU social instance.

It seems odd that the Xmpp plugin would depend on e-mail properly functioning as XMPP and e-mail are two different protocols.

Yes, e-mail works fine on my server, and I am able to request a password reset from my GNU social instance. It seems odd that the Xmpp plugin would depend on e-mail properly functioning as XMPP and e-mail are two different protocols.
Diogo Cordeiro commented 4 years ago
Owner

You said that: "I do not receive the confirmation email", that's why I asked...

Does your XMPP server work for normal XMPP usage? (i.e., can you exchange messages between two users?)

You said that: "I do not receive the confirmation email", that's why I asked... Does your XMPP server work for normal XMPP usage? (i.e., can you exchange messages between two users?)
chaotickjg commented 4 years ago
Poster

I do not recall putting "email" there. Maybe I did by accident, but then I don't recall making that particular section bold (I do not even know how to make stuff bold here)... It was meant to simply say "...I do not receive the confirmation..." in reference to the fact that when adding my JID to the IM tab of my user profile on GNU Social, it says, "Awaiting confirmation on this address. Check your XMPP/Jabber account for a message with further instructions."

My XMPP server is intended to function as a normal XMPP server, and it seems to function properly. I have registered a "human account" for myself. I am able to log in and successfully send messages back and forth between my server and my jabber.org account, as well as send presence updates between the two accounts. This confirms that both client-to-server and server-to-server connections are working. My XMPP server is also on the same box as my GNU Social instance.

The GNU Social XMPP account does come online when I run the startdaemons.sh script, however it does not send any messages to my "human" account on my XMPP server, nor does it send messages to my jabber.org account when I try that one.

I do not recall putting "email" there. Maybe I did by accident, but then I don't recall making that particular section bold (I do not even know how to make stuff bold here)... It was meant to simply say "...I do not receive the confirmation..." in reference to the fact that when adding my JID to the IM tab of my user profile on GNU Social, it says, "Awaiting confirmation on this address. Check your XMPP/Jabber account for a message with further instructions." My XMPP server is intended to function as a normal XMPP server, and it seems to function properly. I have registered a "human account" for myself. I am able to log in and successfully send messages back and forth between my server and my jabber.org account, as well as send presence updates between the two accounts. This confirms that both client-to-server and server-to-server connections are working. My XMPP server is also on the same box as my GNU Social instance. The GNU Social XMPP account does come online when I run the startdaemons.sh script, however it does not send any messages to my "human" account on my XMPP server, nor does it send messages to my jabber.org account when I try that one.
Diogo Cordeiro commented 4 years ago
Owner

The fancy formatting was my doing, the only text change I did was s/GNU Social/GNU social

That's a weird issue, XMPP server + GNU social queues + configuration should have been enough to get it working. Will share this issue in IRC to see if anyone has a suspicion of what could be wrong...

The fancy formatting was my doing, the only text change I did was s/GNU Social/GNU social That's a weird issue, XMPP server + GNU social queues + configuration should have been enough to get it working. Will share this issue in IRC to see if anyone has a suspicion of what could be wrong...
Diogo Cordeiro commented 4 years ago
Owner

So as to ensure this is a GNU social issue; can you try with an external XMPP server, e.g. blabber.im?

I mean, it may end being a GS issue anyway, but I was able to test it with blabber successfuly so, this will make it more evident that your configuration is correct :)

If blabber works, we will need to start inspecting logs ^^

So as to ensure this is a GNU social issue; can you try with an external XMPP server, e.g. [blabber.im](https://blabber.im/en/anmelden/)? I mean, it may end being a GS issue anyway, but I was able to test it with blabber successfuly so, this will make it more evident that your configuration is correct :) If blabber works, we will need to start inspecting logs ^^
chaotickjg commented 4 years ago
Poster

I registered a test account on blabber.im. I added the test account to both my jabber.org contact list and my account on my own XMPP server. One thing I noticed is that presence updates are not being sent from blabber.im to jabber.org despite having authorized my jabber.org account (though presence updates are being sent successfully in the opposite direction), but they are being sent to my XMPP server. I am, however, able to chat one-on-one between blabber.im and jabber.org as well as blabber.im and my server. This once again confirms that S2S connections on my server are functioning correctly, and also that messages are able to be sent between blabber.im and jabber.org even though presence seems to be not entirely functioning. I logged my chat client out of my blabber.im account to ensure there would be no issue with my GNU Social instance when linking it to the blabber.im account.

I edited the config.php of my GNU Social instance to have the user, server, and password for the Xmpp plugin point to my blabber.im account. I removed the host setting since I do not see anything on the blabber.im web site that would suggest that the host and server should be different. After running stopdaemons.sh followed by startdaemons.sh, I did not see my test account appear online from either jabber.org or my server. To be sure, I logged into my GNU Social instance and tried adding my jabber.org account as well as my account on my XMPP server, but I did not receive the confirmation message on either account.

To be absolutely certain that my GNU Social instance wasn't logging into blabber.im, I tried sending a few messages to the blabber.im account from both my jabber.org account and my account on my XMPP server, then I logged my chat client into my blabber.im account, and I immediately saw the test messages come through in my chat client, implying that they were not received by my GNU Social instance.

Interestingly, when I edited config.php to have it log into my jabber.org account (again, omitting the host setting since the jabber.org web site doesn't provide a separate value for that) then restarted the GNU Social daemons, I saw my jabber.org account appear online even after I had logged out from my chat client. However, the confirmation message isn't being sent, even when I plug my blabber.im account or my account on my XMPP server into the IM tab. To test further, I sent a few test messages to my jabber.org account from both blabber.im and my XMPP server, then ran stopdaemons.sh, then logged into jabber.org from my chat client, and I did not see any of my test messages come through, implying that they were received by my GNU Social instance (though they were not published to my feed).

I registered a test account on blabber.im. I added the test account to both my jabber.org contact list and my account on my own XMPP server. One thing I noticed is that presence updates are not being sent from blabber.im to jabber.org despite having authorized my jabber.org account (though presence updates are being sent successfully in the opposite direction), but they are being sent to my XMPP server. I am, however, able to chat one-on-one between blabber.im and jabber.org as well as blabber.im and my server. This once again confirms that S2S connections on my server are functioning correctly, and also that messages are able to be sent between blabber.im and jabber.org even though presence seems to be not entirely functioning. I logged my chat client out of my blabber.im account to ensure there would be no issue with my GNU Social instance when linking it to the blabber.im account. I edited the config.php of my GNU Social instance to have the user, server, and password for the Xmpp plugin point to my blabber.im account. I removed the host setting since I do *not* see anything on the blabber.im web site that would suggest that the host and server should be different. After running stopdaemons.sh followed by startdaemons.sh, I did not see my test account appear online from either jabber.org or my server. To be sure, I logged into my GNU Social instance and tried adding my jabber.org account as well as my account on my XMPP server, but I did not receive the confirmation message on either account. To be absolutely certain that my GNU Social instance wasn't logging into blabber.im, I tried sending a few messages to the blabber.im account from both my jabber.org account and my account on my XMPP server, then I logged my chat client into my blabber.im account, and I immediately saw the test messages come through in my chat client, implying that they were not received by my GNU Social instance. Interestingly, when I edited config.php to have it log into my jabber.org account (again, omitting the host setting since the jabber.org web site doesn't provide a separate value for that) then restarted the GNU Social daemons, I saw my jabber.org account appear online even after I had logged out from my chat client. However, the confirmation message isn't being sent, even when I plug my blabber.im account or my account on my XMPP server into the IM tab. To test further, I sent a few test messages to my jabber.org account from both blabber.im and my XMPP server, then ran stopdaemons.sh, then logged into jabber.org from my chat client, and I did *not* see any of my test messages come through, implying that they were received by my GNU Social instance (though they were not published to my feed).
Diogo Cordeiro commented 4 years ago
Owner

Okay, we need to bring some logs here.

In your config.php, please add the following:

$config['site']['logfile'] = 'errors.txt';
$config['site']['logdebug'] = true;

Share with us the resulting errors.txt file as well as the log files from your nginx and php during the time in which you try to use XMPP.

Okay, we need to bring some logs here. In your `config.php`, please add the following: ````php $config['site']['logfile'] = 'errors.txt'; $config['site']['logdebug'] = true; ```` Share with us the resulting `errors.txt` file as well as the log files from your nginx and php during the time in which you try to use XMPP.
chaotickjg commented 4 years ago
Poster

Here are the log files (I've included my Prosody logs for good measure), but they don't seem to say anything about what the error is.

Here are the log files (I've included my Prosody logs for good measure), but they don't seem to say anything about what the error is.
chaotickjg commented 4 years ago
Poster

It looks my logs didn't upload with my last post. Can you confirm whether or not they are there?

It looks my logs didn't upload with my last post. Can you confirm whether or not they are there?
Diogo Cordeiro commented 4 years ago
Owner

I can't see any logs... :s

I can't see any logs... :s
chaotickjg commented 4 years ago
Poster

Retry...

Retry...
Diogo Cordeiro commented 4 years ago
Owner

nopes

nopes
Diogo Cordeiro commented 4 years ago
Owner
Try using: https://paste.debian.net/
chaotickjg commented 4 years ago
Poster

Since notabug is not accepting the uploads for some reason, I am pasting the logs below. Error logs for prosody and the web server (at least for my GNU social instance) are empty, so I'm not pasting them.

piki@Printers:~$ cat errors.txt
2020-03-30 18:02:16 LOG_WARNING: [social.proudtobeapastafarian.com:22994.6760c4ac GET /settings/profile] Session cookie "baf3oprm1ur4lm2cfej8uslm81"  is set but started value is null
2020-03-30 18:02:16 LOG_DEBUG: [social.proudtobeapastafarian.com:22993.68f33df2 GET /main/login] CRON: Running near-minutely cron job!
2020-03-30 18:02:16 LOG_DEBUG: [social.proudtobeapastafarian.com:22993.68f33df2 GET /main/login] CRON: Running near-hourly cron job!
2020-03-30 18:02:27 LOG_INFO: [social.proudtobeapastafarian.com:28212.09a9f10a POST /main/login] adding rememberme cookie "1:18134e6d432f4d0c296e551a4951fcc8" for calicokris
2020-03-30 18:03:28 LOG_INFO: [social.proudtobeapastafarian.com:queuedaemon.php:352] Launching QueueDaemon background process with 2 threads.
2020-03-30 18:03:28 LOG_INFO: [social.proudtobeapastafarian.com:queuedaemon.php:352] Backgrounding daemon "queuedaemon.generic"
2020-03-30 18:03:28 LOG_INFO: [social.proudtobeapastafarian.com:queuedaemon.php:352] Successfully forked.
2020-03-30 18:03:28 LOG_INFO: [social.proudtobeapastafarian.com:queuedaemon.php:353] Setting group to www-data
2020-03-30 18:03:28 LOG_INFO: [social.proudtobeapastafarian.com:queuedaemon.php:353] Setting user to www-data
2020-03-30 18:03:28 LOG_INFO: [social.proudtobeapastafarian.com:queuedaemon.php:353] QueueDaemon (generic): Spawned thread 1 as pid 354
2020-03-30 18:03:28 LOG_INFO: [social.proudtobeapastafarian.com:queuedaemon.php:354] QueueDaemon (generic.1): checking for queued notices
2020-03-30 18:03:28 LOG_INFO: [social.proudtobeapastafarian.com:imdaemon.php:355] Backgrounding daemon "imdaemon.generic"
2020-03-30 18:03:28 LOG_INFO: [social.proudtobeapastafarian.com:imdaemon.php:355] Successfully forked.
2020-03-30 18:03:28 LOG_INFO: [social.proudtobeapastafarian.com:imdaemon.php:356] Setting group to www-data
2020-03-30 18:03:28 LOG_INFO: [social.proudtobeapastafarian.com:imdaemon.php:356] Setting user to www-data
2020-03-30 18:03:28 LOG_INFO: [social.proudtobeapastafarian.com:imdaemon.php:356] ImDaemon (generic): Spawned thread 1 as pid 357
2020-03-30 18:03:28 LOG_INFO: [social.proudtobeapastafarian.com:imdaemon.php:357] Waiting to listen to IM connections and queues
2020-03-30 18:03:28 LOG_DEBUG: [social.proudtobeapastafarian.com:imdaemon.php:357] Sending ping #0
2020-03-30 18:03:28 LOG_DEBUG: [social.proudtobeapastafarian.com:imdaemon.php:357] Servicing the XMPP queue.
2020-03-30 18:03:28 LOG_DEBUG: [social.proudtobeapastafarian.com:imdaemon.php:357] Servicing the XMPP queue.
2020-03-30 18:03:29 LOG_INFO: [social.proudtobeapastafarian.com:queuedaemon.php:353] QueueDaemon (generic): Spawned thread 2 as pid 358
2020-03-30 18:03:29 LOG_INFO: [social.proudtobeapastafarian.com:queuedaemon.php:358] QueueDaemon (generic.2): checking for queued notices
2020-03-30 18:03:29 LOG_INFO: [social.proudtobeapastafarian.com:imdaemon.php:356] ImDaemon (generic): Waiting for children to complete.
2020-03-30 18:03:30 LOG_INFO: [social.proudtobeapastafarian.com:queuedaemon.php:353] QueueDaemon (generic): Waiting for children to complete.
2020-03-30 18:03:38 LOG_DEBUG: [social.proudtobeapastafarian.com:22993.5191b571 POST /settings/im] CRON: Running near-minutely cron job!
2020-03-30 18:05:34 LOG_DEBUG: [social.proudtobeapastafarian.com:imdaemon.php:357] Sending ping #1
2020-03-30 18:05:34 LOG_DEBUG: [social.proudtobeapastafarian.com:imdaemon.php:357] Servicing the XMPP queue.
2020-03-30 18:07:44 LOG_DEBUG: [social.proudtobeapastafarian.com:imdaemon.php:357] Sending ping #2
2020-03-30 18:07:44 LOG_DEBUG: [social.proudtobeapastafarian.com:imdaemon.php:357] Servicing the XMPP queue.
piki@Printers:~$ cat php7.0-fpm.log
[29-Mar-2020 06:31:00] NOTICE: error log file re-opened

piki@Printers:~$ cat prosody.log
Mar 30 07:47:56 c2s564f8812f840 info    Client connected
Mar 30 07:48:05 c2s564f8812f840 info    Client disconnected: closed
Mar 30 08:29:14 c2s564f8814d250 info    Client connected
Mar 30 08:29:15 c2s564f8814d250 info    Client disconnected: connection closed
Mar 30 10:21:55 xmpp.proudtobeapastafarian.com:mam      info    Deleted 0 expired messages for 0 users
Mar 30 10:21:55 chatrooms.proudtobeapastafarian.com:muc_mam     info    Deleted 0 expired messages for 0 rooms
Mar 30 10:21:55 proudtobeapastafarian.com:mam   info    Deleted 0 expired messages for 1 users
Mar 30 10:46:42 s2sin564f87d5d930       info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384)
Mar 30 10:46:42 proudtobeapastafarian.com:saslauth      info    Accepting SASL EXTERNAL identity from jabber.org
Mar 30 10:46:42 s2sin564f87d5d930       info    Incoming s2s connection jabber.org->proudtobeapastafarian.com complete
Mar 30 10:46:42 s2sout564f8806e6f0      info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384)
Mar 30 10:46:43 s2sout564f8806e6f0      info    Outgoing s2s connection proudtobeapastafarian.com->blabber.im complete
Mar 30 10:46:43 s2sin564f8763e560       info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384)
Mar 30 10:46:43 s2sin564f8762fc70       info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384)
Mar 30 10:46:43 s2sin564f878155f0       info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384)
Mar 30 10:46:43 proudtobeapastafarian.com:saslauth      info    Accepting SASL EXTERNAL identity from blabber.im
Mar 30 10:46:43 s2sin564f8763e560       info    Incoming s2s connection blabber.im->proudtobeapastafarian.com complete
Mar 30 10:46:43 proudtobeapastafarian.com:saslauth      info    Accepting SASL EXTERNAL identity from blabber.im
Mar 30 10:46:43 s2sin564f8762fc70       info    Incoming s2s connection blabber.im->proudtobeapastafarian.com complete
Mar 30 10:46:43 proudtobeapastafarian.com:saslauth      info    Accepting SASL EXTERNAL identity from blabber.im
Mar 30 10:46:43 s2sin564f878155f0       info    Incoming s2s connection blabber.im->proudtobeapastafarian.com complete
Mar 30 10:47:04 s2sout564f88125460      info    Stream encrypted (TLSv1.2 with DHE-RSA-AES256-GCM-SHA384)
Mar 30 10:47:05 s2sout564f88125460      info    Outgoing s2s connection proudtobeapastafarian.com->jabber.org complete
Mar 30 10:51:58 s2sin564f878155f0       info    Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed
Mar 30 10:51:58 s2sin564f8763e560       info    Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed
Mar 30 10:51:58 s2sin564f8762fc70       info    Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed
Mar 30 10:51:58 s2sout564f8806e6f0      info    Outgoing s2s stream proudtobeapastafarian.com->blabber.im closed: stream closed
Mar 30 10:55:43 s2sout564f88125460      info    Outgoing s2s stream proudtobeapastafarian.com->jabber.org closed: stream closed
Mar 30 11:01:09 s2sin564f87903c70       info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384)
Mar 30 11:01:09 s2sin564f87a2add0       info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384)
Mar 30 11:01:09 s2sin564f878fab00       info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384)
Mar 30 11:01:09 proudtobeapastafarian.com:saslauth      info    Accepting SASL EXTERNAL identity from blabber.im
Mar 30 11:01:09 s2sin564f87903c70       info    Incoming s2s connection blabber.im->proudtobeapastafarian.com complete
Mar 30 11:01:09 proudtobeapastafarian.com:saslauth      info    Accepting SASL EXTERNAL identity from blabber.im
Mar 30 11:01:09 s2sin564f87a2add0       info    Incoming s2s connection blabber.im->proudtobeapastafarian.com complete
Mar 30 11:01:09 proudtobeapastafarian.com:saslauth      info    Accepting SASL EXTERNAL identity from blabber.im
Mar 30 11:01:09 s2sin564f878fab00       info    Incoming s2s connection blabber.im->proudtobeapastafarian.com complete
Mar 30 11:01:09 s2sout564f87e24f30      info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384)
Mar 30 11:01:10 s2sout564f87e24f30      info    Outgoing s2s connection proudtobeapastafarian.com->blabber.im complete
Mar 30 11:01:29 s2sout564f87e824b0      info    Stream encrypted (TLSv1.2 with DHE-RSA-AES256-GCM-SHA384)
Mar 30 11:01:29 s2sout564f87e824b0      info    Outgoing s2s connection proudtobeapastafarian.com->jabber.org complete
Mar 30 11:06:58 s2sin564f87903c70       info    Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed
Mar 30 11:06:58 s2sin564f87a2add0       info    Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed
Mar 30 11:06:58 s2sout564f87e824b0      info    Outgoing s2s stream proudtobeapastafarian.com->jabber.org closed: stream closed
Mar 30 11:06:58 s2sout564f87e24f30      info    Outgoing s2s stream proudtobeapastafarian.com->blabber.im closed: stream closed
Mar 30 11:06:58 s2sin564f878fab00       info    Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed
Mar 30 11:11:13 s2sin564f88142200       info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384)
Mar 30 11:11:13 s2sin564f8813c910       info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384)
Mar 30 11:11:13 s2sin564f880851d0       info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384)
Mar 30 11:11:13 proudtobeapastafarian.com:saslauth      info    Accepting SASL EXTERNAL identity from blabber.im
Mar 30 11:11:13 s2sin564f88142200       info    Incoming s2s connection blabber.im->proudtobeapastafarian.com complete
Mar 30 11:11:13 proudtobeapastafarian.com:saslauth      info    Accepting SASL EXTERNAL identity from blabber.im
Mar 30 11:11:13 s2sin564f8813c910       info    Incoming s2s connection blabber.im->proudtobeapastafarian.com complete
Mar 30 11:11:13 proudtobeapastafarian.com:saslauth      info    Accepting SASL EXTERNAL identity from blabber.im
Mar 30 11:11:13 s2sin564f880851d0       info    Incoming s2s connection blabber.im->proudtobeapastafarian.com complete
Mar 30 11:11:13 s2sout564f87b44600      info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384)
Mar 30 11:11:14 s2sout564f87b44600      info    Outgoing s2s connection proudtobeapastafarian.com->blabber.im complete
Mar 30 11:11:33 s2sout564f87a59c10      info    Stream encrypted (TLSv1.2 with DHE-RSA-AES256-GCM-SHA384)
Mar 30 11:11:33 s2sout564f87a59c10      info    Outgoing s2s connection proudtobeapastafarian.com->jabber.org complete
Mar 30 11:18:13 s2sout564f87b44600      info    Outgoing s2s stream proudtobeapastafarian.com->blabber.im closed: stream closed
Mar 30 11:18:13 s2sin564f880851d0       info    Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed
Mar 30 11:18:13 s2sin564f88142200       info    Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed
Mar 30 11:18:13 s2sin564f8813c910       info    Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed
Mar 30 11:23:05 s2sout564f8751c5a0      info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384)
Mar 30 11:23:06 s2sout564f8751c5a0      info    Outgoing s2s connection proudtobeapastafarian.com->blabber.im complete
Mar 30 11:23:06 s2sin564f87c62410       info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384)
Mar 30 11:23:06 s2sin564f87ec8fa0       info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384)
Mar 30 11:23:06 s2sin564f87a306b0       info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384)
Mar 30 11:23:06 proudtobeapastafarian.com:saslauth      info    Accepting SASL EXTERNAL identity from blabber.im
Mar 30 11:23:06 s2sin564f87c62410       info    Incoming s2s connection blabber.im->proudtobeapastafarian.com complete
Mar 30 11:23:06 proudtobeapastafarian.com:saslauth      info    Accepting SASL EXTERNAL identity from blabber.im
Mar 30 11:23:06 s2sin564f87ec8fa0       info    Incoming s2s connection blabber.im->proudtobeapastafarian.com complete
Mar 30 11:23:06 proudtobeapastafarian.com:saslauth      info    Accepting SASL EXTERNAL identity from blabber.im
Mar 30 11:23:06 s2sin564f87a306b0       info    Incoming s2s connection blabber.im->proudtobeapastafarian.com complete
Mar 30 11:23:25 s2sout564f8764ffa0      info    Stream encrypted (TLSv1.2 with DHE-RSA-AES256-GCM-SHA384)
Mar 30 11:23:25 s2sout564f8764ffa0      info    Outgoing s2s connection proudtobeapastafarian.com->jabber.org complete
Mar 30 11:29:28 s2sin564f87a306b0       info    Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed
Mar 30 11:29:28 s2sout564f8751c5a0      info    Outgoing s2s stream proudtobeapastafarian.com->blabber.im closed: stream closed
Mar 30 11:29:28 s2sin564f87c62410       info    Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed
Mar 30 11:29:28 s2sin564f87ec8fa0       info    Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed
Mar 30 11:34:02 s2sin564f87888440       info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384)
Mar 30 11:34:02 s2sin564f87d193f0       info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384)
Mar 30 11:34:02 s2sin564f874e4730       info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384)
Mar 30 11:34:02 s2sout564f87f7fb90      info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384)
Mar 30 11:34:02 proudtobeapastafarian.com:saslauth      info    Accepting SASL EXTERNAL identity from blabber.im
Mar 30 11:34:02 s2sin564f87888440       info    Incoming s2s connection blabber.im->proudtobeapastafarian.com complete
Mar 30 11:34:02 proudtobeapastafarian.com:saslauth      info    Accepting SASL EXTERNAL identity from blabber.im
Mar 30 11:34:02 s2sin564f87d193f0       info    Incoming s2s connection blabber.im->proudtobeapastafarian.com complete
Mar 30 11:34:03 proudtobeapastafarian.com:saslauth      info    Accepting SASL EXTERNAL identity from blabber.im
Mar 30 11:34:03 s2sin564f874e4730       info    Incoming s2s connection blabber.im->proudtobeapastafarian.com complete
Mar 30 11:34:03 s2sout564f87f7fb90      info    Outgoing s2s connection proudtobeapastafarian.com->blabber.im complete
Mar 30 11:34:22 s2sout564f879eaca0      info    Stream encrypted (TLSv1.2 with DHE-RSA-AES256-GCM-SHA384)
Mar 30 11:34:22 s2sout564f879eaca0      info    Outgoing s2s connection proudtobeapastafarian.com->jabber.org complete
Mar 30 11:40:43 s2sin564f874e4730       info    Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed
Mar 30 11:40:43 s2sin564f87888440       info    Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed
Mar 30 11:40:43 s2sin564f87d193f0       info    Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed
Mar 30 11:40:43 s2sout564f87f7fb90      info    Outgoing s2s stream proudtobeapastafarian.com->blabber.im closed: stream closed
Mar 30 11:44:03 s2sin564f87a7d700       info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384)
Mar 30 11:44:03 s2sin564f87e58fc0       info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384)
Mar 30 11:44:03 s2sin564f87c01580       info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384)
Mar 30 11:44:03 proudtobeapastafarian.com:saslauth      info    Accepting SASL EXTERNAL identity from blabber.im
Mar 30 11:44:03 s2sin564f87a7d700       info    Incoming s2s connection blabber.im->proudtobeapastafarian.com complete
Mar 30 11:44:03 proudtobeapastafarian.com:saslauth      info    Accepting SASL EXTERNAL identity from blabber.im
Mar 30 11:44:03 s2sin564f87e58fc0       info    Incoming s2s connection blabber.im->proudtobeapastafarian.com complete
Mar 30 11:44:03 proudtobeapastafarian.com:saslauth      info    Accepting SASL EXTERNAL identity from blabber.im
Mar 30 11:44:03 s2sin564f87c01580       info    Incoming s2s connection blabber.im->proudtobeapastafarian.com complete
Mar 30 11:44:03 s2sout564f87888d90      info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384)
Mar 30 11:44:04 s2sout564f87888d90      info    Outgoing s2s connection proudtobeapastafarian.com->blabber.im complete
Mar 30 11:44:23 s2sout564f8792b130      info    Stream encrypted (TLSv1.2 with DHE-RSA-AES256-GCM-SHA384)
Mar 30 11:44:23 s2sout564f8792b130      info    Outgoing s2s connection proudtobeapastafarian.com->jabber.org complete
Mar 30 11:49:54 c2s564f87b928e0 info    Client disconnected: closed
Mar 30 11:51:58 s2sin564f87e58fc0       info    Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed
Mar 30 11:51:58 s2sin564f87a7d700       info    Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed
Mar 30 11:55:43 s2sout564f8792b130      info    Outgoing s2s stream proudtobeapastafarian.com->jabber.org closed: stream closed
Mar 30 11:55:43 s2sout564f87888d90      info    Outgoing s2s stream proudtobeapastafarian.com->blabber.im closed: stream closed
Mar 30 11:55:43 s2sin564f87c01580       info    Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed
Mar 30 13:02:09 s2sin564f878aff00       info    Incoming s2s stream (unknown host)->45.56.96.149 closed: This host does not serve 45.56.96.149
Mar 30 14:03:11 c2s564f876930e0 info    Client disconnected: connection closed
Mar 30 14:03:12 s2sout564f878936a0      info    Stream encrypted (TLSv1.2 with DHE-RSA-AES256-GCM-SHA384)
Mar 30 14:03:13 s2sin564f87e64770       info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384)
Mar 30 14:03:13 proudtobeapastafarian.com:saslauth      info    Accepting SASL EXTERNAL identity from jabber.org
Mar 30 14:03:13 s2sin564f87e64770       info    Incoming s2s connection jabber.org->proudtobeapastafarian.com complete
Mar 30 14:03:13 s2sout564f878936a0      info    Outgoing s2s connection proudtobeapastafarian.com->jabber.org complete
Mar 30 14:03:28 c2s564f87ed8de0 info    Client connected
Mar 30 14:03:28 c2s564f87ed8de0 info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384)
Mar 30 14:03:28 c2s564f87ed8de0 info    Authenticated as updates@proudtobeapastafarian.com
Mar 30 14:10:44 s2sout564f878936a0      info    Outgoing s2s stream proudtobeapastafarian.com->jabber.org closed: stream closed

piki@Printers:~$ cat social-access.log
71.112.206.70 - - [30/Mar/2020:14:02:16 -0400] "GET /settings/profile HTTP/1.1" 307 283 "https://social.proudtobeapastafarian.com/main/ostatussub" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.149 Safari/537.36"
71.112.206.70 - - [30/Mar/2020:14:02:16 -0400] "GET /main/login HTTP/1.1" 200 2753 "https://social.proudtobeapastafarian.com/main/ostatussub" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.149 Safari/537.36"
71.112.206.70 - - [30/Mar/2020:14:02:27 -0400] "POST /main/login HTTP/1.1" 303 289 "https://social.proudtobeapastafarian.com/main/login" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.149 Safari/537.36"
71.112.206.70 - - [30/Mar/2020:14:02:27 -0400] "GET /settings/profile?PHPSESSID=baf3oprm1ur4lm2cfej8uslm81 HTTP/1.1" 200 9879 "https://social.proudtobeapastafarian.com/main/login" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.149 Safari/537.36"
71.112.206.70 - - [30/Mar/2020:14:02:30 -0400] "GET /settings/im HTTP/1.1" 200 2999 "https://social.proudtobeapastafarian.com/settings/profile?PHPSESSID=baf3oprm1ur4lm2cfej8uslm81" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.149 Safari/537.36"
71.112.206.70 - - [30/Mar/2020:14:02:54 -0400] "POST /settings/im HTTP/1.1" 200 2985 "https://social.proudtobeapastafarian.com/settings/im" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.149 Safari/537.36"
71.112.206.70 - - [30/Mar/2020:14:02:57 -0400] "POST /settings/im HTTP/1.1" 200 3040 "https://social.proudtobeapastafarian.com/settings/im" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.149 Safari/537.36"
71.112.206.70 - - [30/Mar/2020:14:03:38 -0400] "POST /settings/im HTTP/1.1" 200 3040 "https://social.proudtobeapastafarian.com/settings/im" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.149 Safari/537.36"
71.112.206.70 - - [30/Mar/2020:14:03:39 -0400] "GET /favicon.ico HTTP/1.1" 200 1406 "https://social.proudtobeapastafarian.com/settings/im" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.149 Safari/537.36"
71.112.206.70 - - [30/Mar/2020:14:03:42 -0400] "POST /settings/im HTTP/1.1" 200 3021 "https://social.proudtobeapastafarian.com/settings/im" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.149 Safari/537.36"
71.112.206.70 - - [30/Mar/2020:14:03:44 -0400] "POST /settings/im HTTP/1.1" 200 2985 "https://social.proudtobeapastafarian.com/settings/im" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.149 Safari/537.36"
71.112.206.70 - - [30/Mar/2020:14:03:53 -0400] "POST /settings/im HTTP/1.1" 200 3040 "https://social.proudtobeapastafarian.com/settings/im" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.149 Safari/537.36"
Since notabug is not accepting the uploads for some reason, I am pasting the logs below. Error logs for prosody and the web server (at least for my GNU social instance) are empty, so I'm not pasting them. ```` piki@Printers:~$ cat errors.txt 2020-03-30 18:02:16 LOG_WARNING: [social.proudtobeapastafarian.com:22994.6760c4ac GET /settings/profile] Session cookie "baf3oprm1ur4lm2cfej8uslm81" is set but started value is null 2020-03-30 18:02:16 LOG_DEBUG: [social.proudtobeapastafarian.com:22993.68f33df2 GET /main/login] CRON: Running near-minutely cron job! 2020-03-30 18:02:16 LOG_DEBUG: [social.proudtobeapastafarian.com:22993.68f33df2 GET /main/login] CRON: Running near-hourly cron job! 2020-03-30 18:02:27 LOG_INFO: [social.proudtobeapastafarian.com:28212.09a9f10a POST /main/login] adding rememberme cookie "1:18134e6d432f4d0c296e551a4951fcc8" for calicokris 2020-03-30 18:03:28 LOG_INFO: [social.proudtobeapastafarian.com:queuedaemon.php:352] Launching QueueDaemon background process with 2 threads. 2020-03-30 18:03:28 LOG_INFO: [social.proudtobeapastafarian.com:queuedaemon.php:352] Backgrounding daemon "queuedaemon.generic" 2020-03-30 18:03:28 LOG_INFO: [social.proudtobeapastafarian.com:queuedaemon.php:352] Successfully forked. 2020-03-30 18:03:28 LOG_INFO: [social.proudtobeapastafarian.com:queuedaemon.php:353] Setting group to www-data 2020-03-30 18:03:28 LOG_INFO: [social.proudtobeapastafarian.com:queuedaemon.php:353] Setting user to www-data 2020-03-30 18:03:28 LOG_INFO: [social.proudtobeapastafarian.com:queuedaemon.php:353] QueueDaemon (generic): Spawned thread 1 as pid 354 2020-03-30 18:03:28 LOG_INFO: [social.proudtobeapastafarian.com:queuedaemon.php:354] QueueDaemon (generic.1): checking for queued notices 2020-03-30 18:03:28 LOG_INFO: [social.proudtobeapastafarian.com:imdaemon.php:355] Backgrounding daemon "imdaemon.generic" 2020-03-30 18:03:28 LOG_INFO: [social.proudtobeapastafarian.com:imdaemon.php:355] Successfully forked. 2020-03-30 18:03:28 LOG_INFO: [social.proudtobeapastafarian.com:imdaemon.php:356] Setting group to www-data 2020-03-30 18:03:28 LOG_INFO: [social.proudtobeapastafarian.com:imdaemon.php:356] Setting user to www-data 2020-03-30 18:03:28 LOG_INFO: [social.proudtobeapastafarian.com:imdaemon.php:356] ImDaemon (generic): Spawned thread 1 as pid 357 2020-03-30 18:03:28 LOG_INFO: [social.proudtobeapastafarian.com:imdaemon.php:357] Waiting to listen to IM connections and queues 2020-03-30 18:03:28 LOG_DEBUG: [social.proudtobeapastafarian.com:imdaemon.php:357] Sending ping #0 2020-03-30 18:03:28 LOG_DEBUG: [social.proudtobeapastafarian.com:imdaemon.php:357] Servicing the XMPP queue. 2020-03-30 18:03:28 LOG_DEBUG: [social.proudtobeapastafarian.com:imdaemon.php:357] Servicing the XMPP queue. 2020-03-30 18:03:29 LOG_INFO: [social.proudtobeapastafarian.com:queuedaemon.php:353] QueueDaemon (generic): Spawned thread 2 as pid 358 2020-03-30 18:03:29 LOG_INFO: [social.proudtobeapastafarian.com:queuedaemon.php:358] QueueDaemon (generic.2): checking for queued notices 2020-03-30 18:03:29 LOG_INFO: [social.proudtobeapastafarian.com:imdaemon.php:356] ImDaemon (generic): Waiting for children to complete. 2020-03-30 18:03:30 LOG_INFO: [social.proudtobeapastafarian.com:queuedaemon.php:353] QueueDaemon (generic): Waiting for children to complete. 2020-03-30 18:03:38 LOG_DEBUG: [social.proudtobeapastafarian.com:22993.5191b571 POST /settings/im] CRON: Running near-minutely cron job! 2020-03-30 18:05:34 LOG_DEBUG: [social.proudtobeapastafarian.com:imdaemon.php:357] Sending ping #1 2020-03-30 18:05:34 LOG_DEBUG: [social.proudtobeapastafarian.com:imdaemon.php:357] Servicing the XMPP queue. 2020-03-30 18:07:44 LOG_DEBUG: [social.proudtobeapastafarian.com:imdaemon.php:357] Sending ping #2 2020-03-30 18:07:44 LOG_DEBUG: [social.proudtobeapastafarian.com:imdaemon.php:357] Servicing the XMPP queue. piki@Printers:~$ cat php7.0-fpm.log [29-Mar-2020 06:31:00] NOTICE: error log file re-opened piki@Printers:~$ cat prosody.log Mar 30 07:47:56 c2s564f8812f840 info Client connected Mar 30 07:48:05 c2s564f8812f840 info Client disconnected: closed Mar 30 08:29:14 c2s564f8814d250 info Client connected Mar 30 08:29:15 c2s564f8814d250 info Client disconnected: connection closed Mar 30 10:21:55 xmpp.proudtobeapastafarian.com:mam info Deleted 0 expired messages for 0 users Mar 30 10:21:55 chatrooms.proudtobeapastafarian.com:muc_mam info Deleted 0 expired messages for 0 rooms Mar 30 10:21:55 proudtobeapastafarian.com:mam info Deleted 0 expired messages for 1 users Mar 30 10:46:42 s2sin564f87d5d930 info Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384) Mar 30 10:46:42 proudtobeapastafarian.com:saslauth info Accepting SASL EXTERNAL identity from jabber.org Mar 30 10:46:42 s2sin564f87d5d930 info Incoming s2s connection jabber.org->proudtobeapastafarian.com complete Mar 30 10:46:42 s2sout564f8806e6f0 info Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384) Mar 30 10:46:43 s2sout564f8806e6f0 info Outgoing s2s connection proudtobeapastafarian.com->blabber.im complete Mar 30 10:46:43 s2sin564f8763e560 info Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384) Mar 30 10:46:43 s2sin564f8762fc70 info Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384) Mar 30 10:46:43 s2sin564f878155f0 info Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384) Mar 30 10:46:43 proudtobeapastafarian.com:saslauth info Accepting SASL EXTERNAL identity from blabber.im Mar 30 10:46:43 s2sin564f8763e560 info Incoming s2s connection blabber.im->proudtobeapastafarian.com complete Mar 30 10:46:43 proudtobeapastafarian.com:saslauth info Accepting SASL EXTERNAL identity from blabber.im Mar 30 10:46:43 s2sin564f8762fc70 info Incoming s2s connection blabber.im->proudtobeapastafarian.com complete Mar 30 10:46:43 proudtobeapastafarian.com:saslauth info Accepting SASL EXTERNAL identity from blabber.im Mar 30 10:46:43 s2sin564f878155f0 info Incoming s2s connection blabber.im->proudtobeapastafarian.com complete Mar 30 10:47:04 s2sout564f88125460 info Stream encrypted (TLSv1.2 with DHE-RSA-AES256-GCM-SHA384) Mar 30 10:47:05 s2sout564f88125460 info Outgoing s2s connection proudtobeapastafarian.com->jabber.org complete Mar 30 10:51:58 s2sin564f878155f0 info Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed Mar 30 10:51:58 s2sin564f8763e560 info Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed Mar 30 10:51:58 s2sin564f8762fc70 info Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed Mar 30 10:51:58 s2sout564f8806e6f0 info Outgoing s2s stream proudtobeapastafarian.com->blabber.im closed: stream closed Mar 30 10:55:43 s2sout564f88125460 info Outgoing s2s stream proudtobeapastafarian.com->jabber.org closed: stream closed Mar 30 11:01:09 s2sin564f87903c70 info Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384) Mar 30 11:01:09 s2sin564f87a2add0 info Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384) Mar 30 11:01:09 s2sin564f878fab00 info Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384) Mar 30 11:01:09 proudtobeapastafarian.com:saslauth info Accepting SASL EXTERNAL identity from blabber.im Mar 30 11:01:09 s2sin564f87903c70 info Incoming s2s connection blabber.im->proudtobeapastafarian.com complete Mar 30 11:01:09 proudtobeapastafarian.com:saslauth info Accepting SASL EXTERNAL identity from blabber.im Mar 30 11:01:09 s2sin564f87a2add0 info Incoming s2s connection blabber.im->proudtobeapastafarian.com complete Mar 30 11:01:09 proudtobeapastafarian.com:saslauth info Accepting SASL EXTERNAL identity from blabber.im Mar 30 11:01:09 s2sin564f878fab00 info Incoming s2s connection blabber.im->proudtobeapastafarian.com complete Mar 30 11:01:09 s2sout564f87e24f30 info Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384) Mar 30 11:01:10 s2sout564f87e24f30 info Outgoing s2s connection proudtobeapastafarian.com->blabber.im complete Mar 30 11:01:29 s2sout564f87e824b0 info Stream encrypted (TLSv1.2 with DHE-RSA-AES256-GCM-SHA384) Mar 30 11:01:29 s2sout564f87e824b0 info Outgoing s2s connection proudtobeapastafarian.com->jabber.org complete Mar 30 11:06:58 s2sin564f87903c70 info Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed Mar 30 11:06:58 s2sin564f87a2add0 info Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed Mar 30 11:06:58 s2sout564f87e824b0 info Outgoing s2s stream proudtobeapastafarian.com->jabber.org closed: stream closed Mar 30 11:06:58 s2sout564f87e24f30 info Outgoing s2s stream proudtobeapastafarian.com->blabber.im closed: stream closed Mar 30 11:06:58 s2sin564f878fab00 info Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed Mar 30 11:11:13 s2sin564f88142200 info Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384) Mar 30 11:11:13 s2sin564f8813c910 info Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384) Mar 30 11:11:13 s2sin564f880851d0 info Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384) Mar 30 11:11:13 proudtobeapastafarian.com:saslauth info Accepting SASL EXTERNAL identity from blabber.im Mar 30 11:11:13 s2sin564f88142200 info Incoming s2s connection blabber.im->proudtobeapastafarian.com complete Mar 30 11:11:13 proudtobeapastafarian.com:saslauth info Accepting SASL EXTERNAL identity from blabber.im Mar 30 11:11:13 s2sin564f8813c910 info Incoming s2s connection blabber.im->proudtobeapastafarian.com complete Mar 30 11:11:13 proudtobeapastafarian.com:saslauth info Accepting SASL EXTERNAL identity from blabber.im Mar 30 11:11:13 s2sin564f880851d0 info Incoming s2s connection blabber.im->proudtobeapastafarian.com complete Mar 30 11:11:13 s2sout564f87b44600 info Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384) Mar 30 11:11:14 s2sout564f87b44600 info Outgoing s2s connection proudtobeapastafarian.com->blabber.im complete Mar 30 11:11:33 s2sout564f87a59c10 info Stream encrypted (TLSv1.2 with DHE-RSA-AES256-GCM-SHA384) Mar 30 11:11:33 s2sout564f87a59c10 info Outgoing s2s connection proudtobeapastafarian.com->jabber.org complete Mar 30 11:18:13 s2sout564f87b44600 info Outgoing s2s stream proudtobeapastafarian.com->blabber.im closed: stream closed Mar 30 11:18:13 s2sin564f880851d0 info Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed Mar 30 11:18:13 s2sin564f88142200 info Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed Mar 30 11:18:13 s2sin564f8813c910 info Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed Mar 30 11:23:05 s2sout564f8751c5a0 info Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384) Mar 30 11:23:06 s2sout564f8751c5a0 info Outgoing s2s connection proudtobeapastafarian.com->blabber.im complete Mar 30 11:23:06 s2sin564f87c62410 info Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384) Mar 30 11:23:06 s2sin564f87ec8fa0 info Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384) Mar 30 11:23:06 s2sin564f87a306b0 info Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384) Mar 30 11:23:06 proudtobeapastafarian.com:saslauth info Accepting SASL EXTERNAL identity from blabber.im Mar 30 11:23:06 s2sin564f87c62410 info Incoming s2s connection blabber.im->proudtobeapastafarian.com complete Mar 30 11:23:06 proudtobeapastafarian.com:saslauth info Accepting SASL EXTERNAL identity from blabber.im Mar 30 11:23:06 s2sin564f87ec8fa0 info Incoming s2s connection blabber.im->proudtobeapastafarian.com complete Mar 30 11:23:06 proudtobeapastafarian.com:saslauth info Accepting SASL EXTERNAL identity from blabber.im Mar 30 11:23:06 s2sin564f87a306b0 info Incoming s2s connection blabber.im->proudtobeapastafarian.com complete Mar 30 11:23:25 s2sout564f8764ffa0 info Stream encrypted (TLSv1.2 with DHE-RSA-AES256-GCM-SHA384) Mar 30 11:23:25 s2sout564f8764ffa0 info Outgoing s2s connection proudtobeapastafarian.com->jabber.org complete Mar 30 11:29:28 s2sin564f87a306b0 info Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed Mar 30 11:29:28 s2sout564f8751c5a0 info Outgoing s2s stream proudtobeapastafarian.com->blabber.im closed: stream closed Mar 30 11:29:28 s2sin564f87c62410 info Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed Mar 30 11:29:28 s2sin564f87ec8fa0 info Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed Mar 30 11:34:02 s2sin564f87888440 info Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384) Mar 30 11:34:02 s2sin564f87d193f0 info Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384) Mar 30 11:34:02 s2sin564f874e4730 info Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384) Mar 30 11:34:02 s2sout564f87f7fb90 info Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384) Mar 30 11:34:02 proudtobeapastafarian.com:saslauth info Accepting SASL EXTERNAL identity from blabber.im Mar 30 11:34:02 s2sin564f87888440 info Incoming s2s connection blabber.im->proudtobeapastafarian.com complete Mar 30 11:34:02 proudtobeapastafarian.com:saslauth info Accepting SASL EXTERNAL identity from blabber.im Mar 30 11:34:02 s2sin564f87d193f0 info Incoming s2s connection blabber.im->proudtobeapastafarian.com complete Mar 30 11:34:03 proudtobeapastafarian.com:saslauth info Accepting SASL EXTERNAL identity from blabber.im Mar 30 11:34:03 s2sin564f874e4730 info Incoming s2s connection blabber.im->proudtobeapastafarian.com complete Mar 30 11:34:03 s2sout564f87f7fb90 info Outgoing s2s connection proudtobeapastafarian.com->blabber.im complete Mar 30 11:34:22 s2sout564f879eaca0 info Stream encrypted (TLSv1.2 with DHE-RSA-AES256-GCM-SHA384) Mar 30 11:34:22 s2sout564f879eaca0 info Outgoing s2s connection proudtobeapastafarian.com->jabber.org complete Mar 30 11:40:43 s2sin564f874e4730 info Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed Mar 30 11:40:43 s2sin564f87888440 info Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed Mar 30 11:40:43 s2sin564f87d193f0 info Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed Mar 30 11:40:43 s2sout564f87f7fb90 info Outgoing s2s stream proudtobeapastafarian.com->blabber.im closed: stream closed Mar 30 11:44:03 s2sin564f87a7d700 info Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384) Mar 30 11:44:03 s2sin564f87e58fc0 info Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384) Mar 30 11:44:03 s2sin564f87c01580 info Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384) Mar 30 11:44:03 proudtobeapastafarian.com:saslauth info Accepting SASL EXTERNAL identity from blabber.im Mar 30 11:44:03 s2sin564f87a7d700 info Incoming s2s connection blabber.im->proudtobeapastafarian.com complete Mar 30 11:44:03 proudtobeapastafarian.com:saslauth info Accepting SASL EXTERNAL identity from blabber.im Mar 30 11:44:03 s2sin564f87e58fc0 info Incoming s2s connection blabber.im->proudtobeapastafarian.com complete Mar 30 11:44:03 proudtobeapastafarian.com:saslauth info Accepting SASL EXTERNAL identity from blabber.im Mar 30 11:44:03 s2sin564f87c01580 info Incoming s2s connection blabber.im->proudtobeapastafarian.com complete Mar 30 11:44:03 s2sout564f87888d90 info Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384) Mar 30 11:44:04 s2sout564f87888d90 info Outgoing s2s connection proudtobeapastafarian.com->blabber.im complete Mar 30 11:44:23 s2sout564f8792b130 info Stream encrypted (TLSv1.2 with DHE-RSA-AES256-GCM-SHA384) Mar 30 11:44:23 s2sout564f8792b130 info Outgoing s2s connection proudtobeapastafarian.com->jabber.org complete Mar 30 11:49:54 c2s564f87b928e0 info Client disconnected: closed Mar 30 11:51:58 s2sin564f87e58fc0 info Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed Mar 30 11:51:58 s2sin564f87a7d700 info Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed Mar 30 11:55:43 s2sout564f8792b130 info Outgoing s2s stream proudtobeapastafarian.com->jabber.org closed: stream closed Mar 30 11:55:43 s2sout564f87888d90 info Outgoing s2s stream proudtobeapastafarian.com->blabber.im closed: stream closed Mar 30 11:55:43 s2sin564f87c01580 info Incoming s2s stream blabber.im->proudtobeapastafarian.com closed: stream closed Mar 30 13:02:09 s2sin564f878aff00 info Incoming s2s stream (unknown host)->45.56.96.149 closed: This host does not serve 45.56.96.149 Mar 30 14:03:11 c2s564f876930e0 info Client disconnected: connection closed Mar 30 14:03:12 s2sout564f878936a0 info Stream encrypted (TLSv1.2 with DHE-RSA-AES256-GCM-SHA384) Mar 30 14:03:13 s2sin564f87e64770 info Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384) Mar 30 14:03:13 proudtobeapastafarian.com:saslauth info Accepting SASL EXTERNAL identity from jabber.org Mar 30 14:03:13 s2sin564f87e64770 info Incoming s2s connection jabber.org->proudtobeapastafarian.com complete Mar 30 14:03:13 s2sout564f878936a0 info Outgoing s2s connection proudtobeapastafarian.com->jabber.org complete Mar 30 14:03:28 c2s564f87ed8de0 info Client connected Mar 30 14:03:28 c2s564f87ed8de0 info Stream encrypted (TLSv1.2 with ECDHE-RSA-AES256-GCM-SHA384) Mar 30 14:03:28 c2s564f87ed8de0 info Authenticated as updates@proudtobeapastafarian.com Mar 30 14:10:44 s2sout564f878936a0 info Outgoing s2s stream proudtobeapastafarian.com->jabber.org closed: stream closed piki@Printers:~$ cat social-access.log 71.112.206.70 - - [30/Mar/2020:14:02:16 -0400] "GET /settings/profile HTTP/1.1" 307 283 "https://social.proudtobeapastafarian.com/main/ostatussub" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.149 Safari/537.36" 71.112.206.70 - - [30/Mar/2020:14:02:16 -0400] "GET /main/login HTTP/1.1" 200 2753 "https://social.proudtobeapastafarian.com/main/ostatussub" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.149 Safari/537.36" 71.112.206.70 - - [30/Mar/2020:14:02:27 -0400] "POST /main/login HTTP/1.1" 303 289 "https://social.proudtobeapastafarian.com/main/login" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.149 Safari/537.36" 71.112.206.70 - - [30/Mar/2020:14:02:27 -0400] "GET /settings/profile?PHPSESSID=baf3oprm1ur4lm2cfej8uslm81 HTTP/1.1" 200 9879 "https://social.proudtobeapastafarian.com/main/login" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.149 Safari/537.36" 71.112.206.70 - - [30/Mar/2020:14:02:30 -0400] "GET /settings/im HTTP/1.1" 200 2999 "https://social.proudtobeapastafarian.com/settings/profile?PHPSESSID=baf3oprm1ur4lm2cfej8uslm81" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.149 Safari/537.36" 71.112.206.70 - - [30/Mar/2020:14:02:54 -0400] "POST /settings/im HTTP/1.1" 200 2985 "https://social.proudtobeapastafarian.com/settings/im" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.149 Safari/537.36" 71.112.206.70 - - [30/Mar/2020:14:02:57 -0400] "POST /settings/im HTTP/1.1" 200 3040 "https://social.proudtobeapastafarian.com/settings/im" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.149 Safari/537.36" 71.112.206.70 - - [30/Mar/2020:14:03:38 -0400] "POST /settings/im HTTP/1.1" 200 3040 "https://social.proudtobeapastafarian.com/settings/im" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.149 Safari/537.36" 71.112.206.70 - - [30/Mar/2020:14:03:39 -0400] "GET /favicon.ico HTTP/1.1" 200 1406 "https://social.proudtobeapastafarian.com/settings/im" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.149 Safari/537.36" 71.112.206.70 - - [30/Mar/2020:14:03:42 -0400] "POST /settings/im HTTP/1.1" 200 3021 "https://social.proudtobeapastafarian.com/settings/im" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.149 Safari/537.36" 71.112.206.70 - - [30/Mar/2020:14:03:44 -0400] "POST /settings/im HTTP/1.1" 200 2985 "https://social.proudtobeapastafarian.com/settings/im" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.149 Safari/537.36" 71.112.206.70 - - [30/Mar/2020:14:03:53 -0400] "POST /settings/im HTTP/1.1" 200 3040 "https://social.proudtobeapastafarian.com/settings/im" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.149 Safari/537.36" ````
chaotickjg commented 4 years ago
Poster

paste.debian.net won't allow me to "spam" a paste of the logs, so the paste here will have to do.

paste.debian.net won't allow me to "spam" a paste of the logs, so the paste here will have to do.
Diogo Cordeiro commented 4 years ago
Owner

In IRC lnxw37d4 asked: _are there stuck items in the queueditems table? Past experience says newer posts will not go through until stuck items are removed.

Also, please check nginx's error log (/var/log/nginx/error.log in Debian), often it's where PHP errors end...

In IRC lnxw37d4 asked: _are there stuck items in the queued_items table? Past experience says newer posts will not go through until stuck items are removed._ Also, please check nginx's error log (`/var/log/nginx/error.log` in Debian), often it's where PHP errors end...
chaotickjg commented 4 years ago
Poster

I don't have a "queued_items" table, but I do have a "queue_item" table with 76 items in it despite having both queuedaemon and imdaemon running. Here's an odd thing I noticed when checking to confirm they were up:

root@li898-149:/srv/www/pastafarian/social/scripts# ps aux | grep php
www-data 14545  0.5  0.8 593304 35288 pts/4    S    11:54   0:00 php /srv/www/pastafarian/social/scripts/queuedaemon.php
www-data 14546  0.0  0.5 593304 23208 pts/4    S    11:54   0:00 php /srv/www/pastafarian/social/scripts/queuedaemon.php
www-data 14548  0.0  0.8 591256 33044 pts/4    S    11:54   0:00 php /srv/www/pastafarian/social/scripts/imdaemon.php
www-data 14549  0.5  0.6 593448 27940 pts/4    S    11:54   0:00 php /srv/www/pastafarian/social/scripts/imdaemon.php
www-data 14565  0.0  0.5 593304 23208 pts/4    S    11:54   0:00 php /srv/www/pastafarian/social/scripts/queuedaemon.php
root     14603  0.0  0.0  13376   948 pts/4    S+   11:54   0:00 grep php
root     22992  0.0  0.6 693888 27568 ?        Ss   Mar27   0:22 php-fpm: master process (/etc/php/7.0/fpm/php-fpm.conf)
www-data 22993  0.0  1.4 696428 58500 ?        S    Mar27   0:14 php-fpm: pool www
www-data 22994  0.0  1.4 697720 60552 ?        S    Mar27   0:14 php-fpm: pool www
www-data 28212  0.0  1.4 696480 58808 ?        S    Mar30   0:07 php-fpm: pool www

As you can see, queuedaemon has three processes and imdaemon has two. When I run stopdaemons.sh, all five processes disappear. When I run startdaemons.sh, they reappear. Trying to kill just one of the queuedaemon processes kills all three, and trying to kill one imdaemon process kills both.

I don't have a "queued_items" table, but I do have a "queue_item" table with 76 items in it despite having both `queuedaemon` and `imdaemon` running. Here's an odd thing I noticed when checking to confirm they were up: ````sh root@li898-149:/srv/www/pastafarian/social/scripts# ps aux | grep php www-data 14545 0.5 0.8 593304 35288 pts/4 S 11:54 0:00 php /srv/www/pastafarian/social/scripts/queuedaemon.php www-data 14546 0.0 0.5 593304 23208 pts/4 S 11:54 0:00 php /srv/www/pastafarian/social/scripts/queuedaemon.php www-data 14548 0.0 0.8 591256 33044 pts/4 S 11:54 0:00 php /srv/www/pastafarian/social/scripts/imdaemon.php www-data 14549 0.5 0.6 593448 27940 pts/4 S 11:54 0:00 php /srv/www/pastafarian/social/scripts/imdaemon.php www-data 14565 0.0 0.5 593304 23208 pts/4 S 11:54 0:00 php /srv/www/pastafarian/social/scripts/queuedaemon.php root 14603 0.0 0.0 13376 948 pts/4 S+ 11:54 0:00 grep php root 22992 0.0 0.6 693888 27568 ? Ss Mar27 0:22 php-fpm: master process (/etc/php/7.0/fpm/php-fpm.conf) www-data 22993 0.0 1.4 696428 58500 ? S Mar27 0:14 php-fpm: pool www www-data 22994 0.0 1.4 697720 60552 ? S Mar27 0:14 php-fpm: pool www www-data 28212 0.0 1.4 696480 58808 ? S Mar30 0:07 php-fpm: pool www ```` As you can see, `queuedaemon` has three processes and `imdaemon` has two. When I run `stopdaemons.sh`, all five processes disappear. When I run `startdaemons.sh`, they reappear. Trying to kill just one of the `queuedaemon` processes kills all three, and trying to kill one `imdaemon` process kills both.
Diogo Cordeiro commented 4 years ago
Owner

And about nginx's error log?

Also, please check nginx's error log (/var/log/nginx/error.log in Debian), often it's where PHP errors end...

And about nginx's error log? > Also, please check nginx's error log (/var/log/nginx/error.log in Debian), often it's where PHP errors end...
chaotickjg commented 4 years ago
Poster

/var/log/nginx/error.log is empty as seen below, most likely because I am defining a separate error log and access log for each vhost :-)

root@li898-149:/srv/www/pastafarian/social# cat /var/log/nginx/error.log
root@li898-149:/srv/www/pastafarian/social# cat /var/log/nginx/access.log
64.246.165.150 - - [02/Apr/2020:06:55:03 -0400] "GET /robots.txt HTTP/1.0" 301 169 "-" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.10; rv:59.0) Gecko/20100101 Firefox/59.0" "-"
64.246.165.150 - - [02/Apr/2020:06:55:03 -0400] "GET / HTTP/1.1" 301 180 "-" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.10; rv:59.0) Gecko/20100101 Firefox/59.0" "-"
162.242.251.9 - - [02/Apr/2020:10:47:01 -0400] "GET / HTTP/1.1" 301 180 "-" "python-requests/2.23.0" "-"
198.27.81.94 - - [02/Apr/2020:11:57:48 -0400] "GET /wp-login.php HTTP/1.1" 301 180 "-" "Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/46.0.2490.80 Safari/537.36" "-"
198.27.81.94 - - [02/Apr/2020:11:58:52 -0400] "GET /robots.txt HTTP/1.1" 301 180 "-" "Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/46.0.2490.80 Safari/537.36" "-"
root@li898-149:/srv/www/pastafarian/social# cat ../logs/social-error.log
2020/03/31 10:11:01 [error] 19038#19038: *226 FastCGI sent in stderr: "Primary script unknown" while reading response header from upstream, client: 95.85.8.239, server: social.proudtobeapastafarian.com, request: "GET /wp-login.php HTTP/1.1", upstream: "fastcgi://unix:/run/php/php7.0-fpm.sock:", host: "social.proudtobeapastafarian.com", referrer: "http://social.proudtobeapastafarian.com/wp-login.php"
2020/04/02 12:12:58 [error] 9093#9093: *744 FastCGI sent in stderr: "PHP message: PHP Notice:  Undefined index: value in /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php on line 179
PHP message: PHP Stack trace:
PHP message: PHP   1. {main}() /srv/www/pastafarian/social/index.php:0
PHP message: PHP   2. main() /srv/www/pastafarian/social/index.php:320
PHP message: PHP   3. Action::run() /srv/www/pastafarian/social/index.php:317
PHP message: PHP   4. Action->execute() /srv/www/pastafarian/social/lib/action.php:96
PHP message: PHP   5. ManagedAction->handle() /srv/www/pastafarian/social/lib/action.php:194
PHP message: PHP   6. Action->showPage() /srv/www/pastafarian/social/lib/managedaction.php:64
PHP message: PHP   7. Action->showBody() /srv/www/pastafarian/social/lib/action.php:553
PHP message: PHP   8. Action->showCore() /srv/www/pastafarian/social/lib/action.php:845
PHP message: PHP   9. Action->showContentBlock() /srv/www/pastafarian/social/lib/action.php:1007
PHP message: PHP  10. ProfileDetailAction->showContent() /srv/www/pastafarian/social/lib/action.php:1072
PHP message: PHP  11. ExtendedProfileWidget->show() /srv/www/pastafarian/social/plugins/ExtendedProfile/actions/profiledetail.php:58
PHP message: PHP  12. ExtendedProfileWidget->showSections() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:71
PHP message: PHP  13. ExtendedProfileWidget->showExtendedProfileSection() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:99
PHP message: PHP  14. ExtendedProfileWidget->showMultiple() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:122
PHP message: PHP  15. ExtendedProfileWidget->showExtendedProfileField() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:156
PHP message: PHP  16. ExtendedProfileWidget->showFieldValue() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:147
PHP message: PHP  17. ExtendedProfileWidge
2020/04/02 12:12:58 [error] 9093#9093: *744 FastCGI sent in stderr: "eSection() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:99
PHP message: PHP  14. ExtendedProfileWidget->showMultiple() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:122
PHP message: PHP  15. ExtendedProfileWidget->showExtendedProfileField() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:156
PHP message: PHP  16. ExtendedProfileWidget->showFieldValue() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:147
PHP message: PHP  17. ExtendedProfileWidget->showWebsite() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:562" while reading upstream, client: 71.112.206.70, server: social.proudtobeapastafarian.com, request: "GET /calicokris/detail HTTP/1.1", upstream: "fastcgi://unix:/run/php/php7.0-fpm.sock:", host: "social.proudtobeapastafarian.com", referrer: "https://social.proudtobeapastafarian.com/directory/users"
`/var/log/nginx/error.log` is empty as seen below, most likely because I am defining a separate error log and access log for each vhost :-) ````sh root@li898-149:/srv/www/pastafarian/social# cat /var/log/nginx/error.log ```` ````sh root@li898-149:/srv/www/pastafarian/social# cat /var/log/nginx/access.log 64.246.165.150 - - [02/Apr/2020:06:55:03 -0400] "GET /robots.txt HTTP/1.0" 301 169 "-" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.10; rv:59.0) Gecko/20100101 Firefox/59.0" "-" 64.246.165.150 - - [02/Apr/2020:06:55:03 -0400] "GET / HTTP/1.1" 301 180 "-" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.10; rv:59.0) Gecko/20100101 Firefox/59.0" "-" 162.242.251.9 - - [02/Apr/2020:10:47:01 -0400] "GET / HTTP/1.1" 301 180 "-" "python-requests/2.23.0" "-" 198.27.81.94 - - [02/Apr/2020:11:57:48 -0400] "GET /wp-login.php HTTP/1.1" 301 180 "-" "Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/46.0.2490.80 Safari/537.36" "-" 198.27.81.94 - - [02/Apr/2020:11:58:52 -0400] "GET /robots.txt HTTP/1.1" 301 180 "-" "Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/46.0.2490.80 Safari/537.36" "-" ```` ````sh root@li898-149:/srv/www/pastafarian/social# cat ../logs/social-error.log 2020/03/31 10:11:01 [error] 19038#19038: *226 FastCGI sent in stderr: "Primary script unknown" while reading response header from upstream, client: 95.85.8.239, server: social.proudtobeapastafarian.com, request: "GET /wp-login.php HTTP/1.1", upstream: "fastcgi://unix:/run/php/php7.0-fpm.sock:", host: "social.proudtobeapastafarian.com", referrer: "http://social.proudtobeapastafarian.com/wp-login.php" 2020/04/02 12:12:58 [error] 9093#9093: *744 FastCGI sent in stderr: "PHP message: PHP Notice: Undefined index: value in /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php on line 179 PHP message: PHP Stack trace: PHP message: PHP 1. {main}() /srv/www/pastafarian/social/index.php:0 PHP message: PHP 2. main() /srv/www/pastafarian/social/index.php:320 PHP message: PHP 3. Action::run() /srv/www/pastafarian/social/index.php:317 PHP message: PHP 4. Action->execute() /srv/www/pastafarian/social/lib/action.php:96 PHP message: PHP 5. ManagedAction->handle() /srv/www/pastafarian/social/lib/action.php:194 PHP message: PHP 6. Action->showPage() /srv/www/pastafarian/social/lib/managedaction.php:64 PHP message: PHP 7. Action->showBody() /srv/www/pastafarian/social/lib/action.php:553 PHP message: PHP 8. Action->showCore() /srv/www/pastafarian/social/lib/action.php:845 PHP message: PHP 9. Action->showContentBlock() /srv/www/pastafarian/social/lib/action.php:1007 PHP message: PHP 10. ProfileDetailAction->showContent() /srv/www/pastafarian/social/lib/action.php:1072 PHP message: PHP 11. ExtendedProfileWidget->show() /srv/www/pastafarian/social/plugins/ExtendedProfile/actions/profiledetail.php:58 PHP message: PHP 12. ExtendedProfileWidget->showSections() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:71 PHP message: PHP 13. ExtendedProfileWidget->showExtendedProfileSection() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:99 PHP message: PHP 14. ExtendedProfileWidget->showMultiple() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:122 PHP message: PHP 15. ExtendedProfileWidget->showExtendedProfileField() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:156 PHP message: PHP 16. ExtendedProfileWidget->showFieldValue() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:147 PHP message: PHP 17. ExtendedProfileWidge 2020/04/02 12:12:58 [error] 9093#9093: *744 FastCGI sent in stderr: "eSection() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:99 PHP message: PHP 14. ExtendedProfileWidget->showMultiple() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:122 PHP message: PHP 15. ExtendedProfileWidget->showExtendedProfileField() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:156 PHP message: PHP 16. ExtendedProfileWidget->showFieldValue() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:147 PHP message: PHP 17. ExtendedProfileWidget->showWebsite() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:562" while reading upstream, client: 71.112.206.70, server: social.proudtobeapastafarian.com, request: "GET /calicokris/detail HTTP/1.1", upstream: "fastcgi://unix:/run/php/php7.0-fpm.sock:", host: "social.proudtobeapastafarian.com", referrer: "https://social.proudtobeapastafarian.com/directory/users" ````
Diogo Cordeiro commented 4 years ago
Owner

Can you disable the ExtendedProfile plugin?

Can you disable the ExtendedProfile plugin?
chaotickjg commented 4 years ago
Poster

If you look at my config.php file in the initial bug report, you will see that this bug manifested itself prior to enabling any plugins aside from the Xmpp plugin. In other words, having the ExtendedProfile plugin being enabled now could not have caused this bug or affected the Xmpp plugin in any other way at the time of filing this bug report because it was not enabled when I first discovered the bug related to the Xmpp plugin.

If you look at my config.php file in the initial bug report, you will see that this bug manifested itself prior to enabling any plugins aside from the Xmpp plugin. In other words, having the ExtendedProfile plugin being enabled now could not have caused this bug or affected the Xmpp plugin in any other way at the time of filing this bug report because it was not enabled when I first discovered the bug related to the Xmpp plugin.
Diogo Cordeiro commented 4 years ago
Owner

I see, but these errors are with respect to that plugin, please disable so that we can get an error log directly related to XMPP...

I see, but these errors are with respect to that plugin, please disable so that we can get an error log directly related to XMPP...
chaotickjg commented 4 years ago
Poster

I'm not sure I follow your logic that I need to disable one plugin to get an error pertaining to another. If that is the case, that is a bug on it's own. At any rate, I disabled all plugins except XmppPlugin, ran the checkschema.php script, then tried re-adding my JID to my account. Here are the logs from after doing that.

root@li898-149:/srv/www/pastafarian/social# cat /var/log/php7.0-fpm.log
[29-Mar-2020 06:31:00] NOTICE: error log file re-opened
root@li898-149:/srv/www/pastafarian/social# cat /var/log/nginx/error.log
root@li898-149:/srv/www/pastafarian/social# cat ../logs/social-error.log
2020/03/31 10:11:01 [error] 19038#19038: *226 FastCGI sent in stderr: "Primary script unknown" while reading response header from upstream, client: 95.85.8.239, server: social.proudtobeapastafarian.com, request: "GET /wp-login.php HTTP/1.1", upstream: "fastcgi://unix:/run/php/php7.0-fpm.sock:", host: "social.proudtobeapastafarian.com", referrer: "http://social.proudtobeapastafarian.com/wp-login.php"
2020/04/02 12:12:58 [error] 9093#9093: *744 FastCGI sent in stderr: "PHP message: PHP Notice:  Undefined index: value in /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php on line 179
PHP message: PHP Stack trace:
PHP message: PHP   1. {main}() /srv/www/pastafarian/social/index.php:0
PHP message: PHP   2. main() /srv/www/pastafarian/social/index.php:320
PHP message: PHP   3. Action::run() /srv/www/pastafarian/social/index.php:317
PHP message: PHP   4. Action->execute() /srv/www/pastafarian/social/lib/action.php:96
PHP message: PHP   5. ManagedAction->handle() /srv/www/pastafarian/social/lib/action.php:194
PHP message: PHP   6. Action->showPage() /srv/www/pastafarian/social/lib/managedaction.php:64
PHP message: PHP   7. Action->showBody() /srv/www/pastafarian/social/lib/action.php:553
PHP message: PHP   8. Action->showCore() /srv/www/pastafarian/social/lib/action.php:845
PHP message: PHP   9. Action->showContentBlock() /srv/www/pastafarian/social/lib/action.php:1007
PHP message: PHP  10. ProfileDetailAction->showContent() /srv/www/pastafarian/social/lib/action.php:1072
PHP message: PHP  11. ExtendedProfileWidget->show() /srv/www/pastafarian/social/plugins/ExtendedProfile/actions/profiledetail.php:58
PHP message: PHP  12. ExtendedProfileWidget->showSections() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:71
PHP message: PHP  13. ExtendedProfileWidget->showExtendedProfileSection() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:99
PHP message: PHP  14. ExtendedProfileWidget->showMultiple() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:122
PHP message: PHP  15. ExtendedProfileWidget->showExtendedProfileField() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:156
PHP message: PHP  16. ExtendedProfileWidget->showFieldValue() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:147
PHP message: PHP  17. ExtendedProfileWidge
2020/04/02 12:12:58 [error] 9093#9093: *744 FastCGI sent in stderr: "eSection() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:99
PHP message: PHP  14. ExtendedProfileWidget->showMultiple() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:122
PHP message: PHP  15. ExtendedProfileWidget->showExtendedProfileField() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:156
PHP message: PHP  16. ExtendedProfileWidget->showFieldValue() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:147
PHP message: PHP  17. ExtendedProfileWidget->showWebsite() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:562" while reading upstream, client: 71.112.206.70, server: social.proudtobeapastafarian.com, request: "GET /calicokris/detail HTTP/1.1", upstream: "fastcgi://unix:/run/php/php7.0-fpm.sock:", host: "social.proudtobeapastafarian.com", referrer: "https://social.proudtobeapastafarian.com/directory/users"
I'm not sure I follow your logic that I need to disable one plugin to get an error pertaining to another. If that is the case, that is a bug on it's own. At any rate, I disabled all plugins except XmppPlugin, ran the checkschema.php script, then tried re-adding my JID to my account. Here are the logs from after doing that. ````sh root@li898-149:/srv/www/pastafarian/social# cat /var/log/php7.0-fpm.log [29-Mar-2020 06:31:00] NOTICE: error log file re-opened ```` ````sh root@li898-149:/srv/www/pastafarian/social# cat /var/log/nginx/error.log ```` ````sh root@li898-149:/srv/www/pastafarian/social# cat ../logs/social-error.log 2020/03/31 10:11:01 [error] 19038#19038: *226 FastCGI sent in stderr: "Primary script unknown" while reading response header from upstream, client: 95.85.8.239, server: social.proudtobeapastafarian.com, request: "GET /wp-login.php HTTP/1.1", upstream: "fastcgi://unix:/run/php/php7.0-fpm.sock:", host: "social.proudtobeapastafarian.com", referrer: "http://social.proudtobeapastafarian.com/wp-login.php" 2020/04/02 12:12:58 [error] 9093#9093: *744 FastCGI sent in stderr: "PHP message: PHP Notice: Undefined index: value in /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php on line 179 PHP message: PHP Stack trace: PHP message: PHP 1. {main}() /srv/www/pastafarian/social/index.php:0 PHP message: PHP 2. main() /srv/www/pastafarian/social/index.php:320 PHP message: PHP 3. Action::run() /srv/www/pastafarian/social/index.php:317 PHP message: PHP 4. Action->execute() /srv/www/pastafarian/social/lib/action.php:96 PHP message: PHP 5. ManagedAction->handle() /srv/www/pastafarian/social/lib/action.php:194 PHP message: PHP 6. Action->showPage() /srv/www/pastafarian/social/lib/managedaction.php:64 PHP message: PHP 7. Action->showBody() /srv/www/pastafarian/social/lib/action.php:553 PHP message: PHP 8. Action->showCore() /srv/www/pastafarian/social/lib/action.php:845 PHP message: PHP 9. Action->showContentBlock() /srv/www/pastafarian/social/lib/action.php:1007 PHP message: PHP 10. ProfileDetailAction->showContent() /srv/www/pastafarian/social/lib/action.php:1072 PHP message: PHP 11. ExtendedProfileWidget->show() /srv/www/pastafarian/social/plugins/ExtendedProfile/actions/profiledetail.php:58 PHP message: PHP 12. ExtendedProfileWidget->showSections() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:71 PHP message: PHP 13. ExtendedProfileWidget->showExtendedProfileSection() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:99 PHP message: PHP 14. ExtendedProfileWidget->showMultiple() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:122 PHP message: PHP 15. ExtendedProfileWidget->showExtendedProfileField() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:156 PHP message: PHP 16. ExtendedProfileWidget->showFieldValue() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:147 PHP message: PHP 17. ExtendedProfileWidge 2020/04/02 12:12:58 [error] 9093#9093: *744 FastCGI sent in stderr: "eSection() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:99 PHP message: PHP 14. ExtendedProfileWidget->showMultiple() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:122 PHP message: PHP 15. ExtendedProfileWidget->showExtendedProfileField() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:156 PHP message: PHP 16. ExtendedProfileWidget->showFieldValue() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:147 PHP message: PHP 17. ExtendedProfileWidget->showWebsite() /srv/www/pastafarian/social/plugins/ExtendedProfile/lib/extendedprofilewidget.php:562" while reading upstream, client: 71.112.206.70, server: social.proudtobeapastafarian.com, request: "GET /calicokris/detail HTTP/1.1", upstream: "fastcgi://unix:/run/php/php7.0-fpm.sock:", host: "social.proudtobeapastafarian.com", referrer: "https://social.proudtobeapastafarian.com/directory/users" ````
chaotickjg commented 4 years ago
Poster

Here is a link to the errors.txt file created earlier (neither Firefox nor Brave would let me paste it's contents directly, not even in pastebin). You'll notice it says it's service the XMPP queue even though it's not doing so.

https://social.proudtobeapastafarian.com/errors.txt

Here is a link to the errors.txt file created earlier (neither Firefox nor Brave would let me paste it's contents directly, not even in pastebin). You'll notice it says it's service the XMPP queue even though it's not doing so. https://social.proudtobeapastafarian.com/errors.txt
Sign in to join this conversation.
No Milestone
No assignee
2 Participants
Loading...
Cancel
Save
There is no content yet.