Issue with invitation Calendar?

fbartels

@killmasta93 said in Issue with invitation Calendar?:

i check the logs and i don’t see anything odd

uploading the logs here would allow others to maybe spot something in your logs. Check https://wiki.z-hub.io/display/ZP/Debugging for instructions to create useful logs.

Manfred

Hi killmasta93,

does your email server process the incoming invitations/meeting requests and creates a tentative appointment in your calendar backend? Did you configure email sync on your mobile device? And as Felix already said, without logs it’s not possible to tell anything.

Manfred

killmasta93

Thanks for the replies, im attaching the log i put the log in LOGLEVEL_DEBUG

https://pastebin.com/bwShvper

Thank you

killmasta93

I think i found the error was looking back to the logs found this

              LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 28/10/2018 14:50:02 with pid '26711' terminated unexpectedly or is still running.

Manfred

Hi killmasta93,

the log you’ve posted is not very helpful. I don’t see if there was a meeting invitation, if an appointment for it was created.

Mobile devices issue ‘MeetingResponse’ request when the user responds (accepts, declines or tentatively accepts) to a meeting request which would mean that meeting invitations do work. But it’s not possible to tell more without WBXML logs.

Manfred

killmasta93

@Manfred Thank you manfred for the reply, im attaching you the error log not sure if it has to do anything?

01/11/2018 00:18:41 [  316] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:42 [ 5744] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:42 [ 5744] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:43 [ 5744] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:43 [ 5744] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:43 [ 5744] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:44 [ 5744] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:44 [ 5744] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:41 with pid '316' terminated unexpectedly or is still running.
01/11/2018 00:18:44 [ 5744] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:44 [ 5744] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:45 [ 5744] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:45 [ 5744] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:41 with pid '5744' terminated unexpectedly or is still running.
01/11/2018 00:18:45 [ 5744] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:45 [ 5744] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:45 [ 5744] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:45 [ 5744] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:42 with pid '5744' terminated unexpectedly or is still running.
01/11/2018 00:18:45 [ 5744] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:46 [ 5744] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:46 [ 5744] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:42 with pid '5744' terminated unexpectedly or is still running.
01/11/2018 00:18:46 [ 5744] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:46 [ 5744] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:46 [ 5744] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:43 with pid '5744' terminated unexpectedly or is still running.
01/11/2018 00:18:46 [ 5744] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:46 [ 5744] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:46 [ 5744] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:43 with pid '5744' terminated unexpectedly or is still running.
01/11/2018 00:18:46 [ 5744] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:47 [ 5744] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:47 [ 5744] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:43 with pid '5744' terminated unexpectedly or is still running.
01/11/2018 00:18:47 [ 5744] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:47 [ 5744] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:47 [ 5744] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:44 with pid '5744' terminated unexpectedly or is still running.
01/11/2018 00:18:47 [ 5744] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:48 [ 5744] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:48 [ 5744] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:44 with pid '5744' terminated unexpectedly or is still running.
01/11/2018 00:18:48 [ 5744] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:48 [ 5744] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:48 [ 9779] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:45 with pid '5744' terminated unexpectedly or is still running.
01/11/2018 00:18:48 [ 9779] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:48 [ 9779] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:48 [ 5744] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:45 with pid '5744' terminated unexpectedly or is still running.
01/11/2018 00:18:48 [ 5744] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:49 [ 5744] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:49 [ 9779] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:45 with pid '5744' terminated unexpectedly or is still running.
01/11/2018 00:18:49 [ 9779] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:49 [ 9779] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:49 [ 5744] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:46 with pid '5744' terminated unexpectedly or is still running.
01/11/2018 00:18:49 [ 5744] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:49 [ 5744] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:49 [ 9779] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:46 with pid '5744' terminated unexpectedly or is still running.
01/11/2018 00:18:49 [ 9779] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:50 [ 9779] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:50 [ 5744] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:46 with pid '5744' terminated unexpectedly or is still running.
01/11/2018 00:18:50 [ 5744] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:50 [ 5744] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:50 [ 9779] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:47 with pid '5744' terminated unexpectedly or is still running.
01/11/2018 00:18:50 [ 9779] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:51 [ 9779] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:51 [ 5744] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:47 with pid '5744' terminated unexpectedly or is still running.
01/11/2018 00:18:51 [ 5744] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:51 [ 5744] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:51 [ 9779] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:48 with pid '5744' terminated unexpectedly or is still running.
01/11/2018 00:18:51 [ 9779] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:52 [ 9779] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:52 [ 5744] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:48 with pid '9779' terminated unexpectedly or is still running.
01/11/2018 00:18:52 [ 5744] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:52 [ 5744] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:52 [ 9779] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:48 with pid '5744' terminated unexpectedly or is still running.
01/11/2018 00:18:52 [ 9779] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:52 [ 9779] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:52 [ 5744] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:49 with pid '9779' terminated unexpectedly or is still running.
01/11/2018 00:18:52 [ 5744] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:53 [ 5744] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:53 [ 9779] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:49 with pid '5744' terminated unexpectedly or is still running.
01/11/2018 00:18:53 [ 9779] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:53 [ 9779] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:53 [ 5744] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:49 with pid '9779' terminated unexpectedly or is still running.
01/11/2018 00:18:53 [ 5744] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:54 [ 5744] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:54 [ 9779] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:50 with pid '5744' terminated unexpectedly or is still running.
01/11/2018 00:18:54 [ 9779] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:54 [ 9779] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:54 [ 5744] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:50 with pid '9779' terminated unexpectedly or is still running.
01/11/2018 00:18:54 [ 5744] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:55 [ 5744] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:55 [ 9779] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:51 with pid '5744' terminated unexpectedly or is still running.
01/11/2018 00:18:55 [ 9779] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:55 [ 9779] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:55 [ 5744] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:51 with pid '9779' terminated unexpectedly or is still running.
01/11/2018 00:18:55 [ 5744] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:56 [ 5744] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:56 [ 9779] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:52 with pid '5744' terminated unexpectedly or is still running.
01/11/2018 00:18:56 [ 9779] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:56 [ 9779] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)
01/11/2018 00:18:56 [ 5744] [ERROR] [gerencia] LoopDetection->ProcessLoopDetectionPreviousConnectionFailed(): Command 'MeetingResponse' at 01/11/2018 00:18:52 with pid '9779' terminated unexpectedly or is still running.
01/11/2018 00:18:56 [ 5744] [ERROR] [gerencia] Please check your logs for this PID and errors like PHP-Fatals or Apache segmentation faults and report your results to the Z-Push dev team.
01/11/2018 00:18:57 [ 5744] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)

killmasta93

also wanted to add whats odd when i accept a meeting on the error log i see this

          01/11/2018 00:32:39 [  464] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)

whats odd is that it replicates on my mobile device and on outlook but not on nextcloud Calendar APP, only if someone sends me an invitation and not the other way around

also this error

   ```

  01/11/2018 00:44:21 [  415] [WARN] [gerencia] /var/www/zpush/src/backend/imap/imap.php:1677 imap_fetchheader(): Bad message number (2)
  01/11/2018 00:44:21 [  415] [WARN] [gerencia] /var/www/zpush/src/backend/imap/imap.php:1677 imap_body(): Bad message number (2)
  01/11/2018 00:47:31 [ 3695] [FATAL] [gerencia] Fatal error: /var/www/zpush/src/backend/imap/imap.php:1704 - Call to undefined function GetUserDetails() (1)

the 1709 line in the imap php seems to imply this

   if (is_calendar($part)) {
                    ZLog::Write(LOGLEVEL_DEBUG, "BackendIMAP->MeetingResponse - text/calendar part found, trying to reply");
                    $body_part = reply_meeting_calendar($part, $response, GetUserDetails($this->username)['emailaddress']);

killmasta93

UPDATE: so there was something odd with the imap.php i changed to this and after that no error log spamming. But whats odd is that if someone sends me an invitation calendar and i accept it, it syncs with outlook on the computer but not on the app on the mobile and does not show on the calendar on nextcloud. checked the logs but nothing appears out of the ordinary.

              $body_part = reply_meeting_calendar($part, $response, $this->GetUserDetails($this->username)['emailaddress']);

Manfred

Hi killmasta93,

where do you accept the invitation? In Outlook? Is it then being synced to the mobile?

Manfred

killmasta93

hi manfred thank you for the reply, that’s correct i accept the invitation on outlook. It adds it to the calendar on the outlook but not to nextcloud nor on the mobile.
ex:
Client sends calendar invite--------to me--------open on outlook-------saves on the calendar on outlook but not on mobile nor nextcloud

I send calendar invite -------to client--------it automatic saves on outlook -------and saves also on mobile and nextcloud

Thank you

Manfred

Hi killmasta93,

so you have to check if after accepting the meeting request outlook sends this calendar item to the server as a new appointment.

Manfred

killmasta93

Hi Manfred,
Thank you for the reply, I was checking though the logs but could not find any other errors besides

             StatusException: SyncCollections->CheckForChanges(): Timeout forced after 28s from 470s due to other process - code: 3 - file: /var/www/zpush/src/lib/core/synccollections.php:579

          [WARN] [gerencia] SyncObject->Check(): object from type SyncMail: parameter 'from' contains an invalid email address 'gerencia'. Address is removed.

This is another log


07/11/2018 23:53:09 [24194] [ INFO] [gerencia] cmd='Sync' memory='2.74 MiB/3.00 MiB' time='0.45s' devType='SamsungDevice' devId='sec1f9c9384c7e3f' getUser='gerencia' from='192.168.3.254' idle='0s' version='GIT' method='POST' httpcode='200'
07/11/2018 23:53:09 [24194] [ INFO] [gerencia] ExportChangesDiff->InitializeExporter(): Found '0' changes for 'Tpersonal'
07/11/2018 23:53:09 [24194] [ INFO] [gerencia] cmd='Sync' memory='2.69 MiB/3.00 MiB' time='0.49s' devType='SamsungDevice' devId='sec1f9c9384c7e3f' getUser='gerencia' from='192.168.3.254' idle='0s' version='GIT' method='POST' httpcode='200'
07/11/2018 23:53:10 [24194] [ INFO] [gerencia] ExportChangesDiff->InitializeExporter(): Found '0' changes for 'Cpersonal'
07/11/2018 23:53:10 [24194] [ INFO] [gerencia] cmd='Sync' memory='2.88 MiB/3.25 MiB' time='0.43s' devType='SamsungDevice' devId='sec1f9c9384c7e3f' getUser='gerencia' from='192.168.3.254' idle='0s' version='GIT' method='POST' httpcode='200'
07/11/2018 23:53:10 [24194] [ INFO] [gerencia] ExportChangesDiff->InitializeExporter(): Found '0' changes for '0ba49d4c'
07/11/2018 23:53:10 [24194] [ INFO] [gerencia] cmd='Sync' memory='2.73 MiB/3.00 MiB' time='0.36s' devType='SamsungDevice' devId='sec1f9c9384c7e3f' getUser='gerencia' from='192.168.3.254' idle='0s' version='GIT' method='POST' httpcode='200'
07/11/2018 23:53:22 [24194] [ INFO] [gerencia] Processed '1' incoming changes
07/11/2018 23:53:22 [24194] [ INFO] [gerencia] ExportChangesDiff->InitializeExporter(): Found '0' changes for '0ba49d4c'
07/11/2018 23:53:22 [24194] [ INFO] [gerencia] cmd='Sync' memory='2.80 MiB/3.25 MiB' time='0.39s' devType='SamsungDevice' devId='sec1f9c9384c7e3f' getUser='gerencia' from='192.168.3.254' idle='0s' version='GIT' method='POST' httpcode='200'
07/11/2018 23:53:28 [24194] [ INFO] [gerencia] cmd='MeetingResponse' memory='2.58 MiB/3.00 MiB' time='0.36s' devType='SamsungDevice' devId='sec1f9c9384c7e3f' getUser='gerencia' from='192.168.3.254' idle='0s' version='GIT' method='POST' httpcode='200'
07/11/2018 23:53:30 [24194] [ INFO] [gerencia] cmd='SendMail' memory='2.59 MiB/3.00 MiB' time='0.38s' devType='SamsungDevice' devId='sec1f9c9384c7e3f' getUser='gerencia' from='192.168.3.254' idle='0s' version='GIT' method='POST' httpcode='200'
07/11/2018 23:53:32 [24194] [ INFO] [gerencia] ExportChangesDiff->InitializeExporter(): Found '1' changes for '9d7d5407'
07/11/2018 23:53:32 [24194] [WARN] [gerencia] SyncObject->Check(): object from type SyncMail: parameter 'from' contains an invalid email address 'gerencia'. Address is removed.
07/11/2018 23:53:32 [24194] [ INFO] [gerencia] cmd='Sync' memory='3.33 MiB/3.75 MiB' time='0.36s' devType='SamsungDevice' devId='sec1f9c9384c7e3f' getUser='gerencia' from='192.168.3.254' idle='0s' version='GIT' method='POST' httpcode='200'
07/11/2018 23:53:33 [ 8573] [ INFO] [gerencia] cmd='Ping' memory='3.12 MiB/3.50 MiB' time='181.18s' devType='SamsungDevice' devId='sec1f9c9384c7e3f' getUser='gerencia' from='192.168.3.254' idle='180s' version='GIT' method='POST' httpcode='200'
07/11/2018 23:53:34 [24194] [ INFO] [gerencia] SyncCollections->CheckForChanges(): Waiting for store changes... (lifetime 660 seconds)
07/11/2018 23:53:34 [24194] [ INFO] [gerencia] ExportChangesDiff->InitializeExporter(): Found '6' changes for 'hierarchy'
07/11/2018 23:53:35 [24194] [ INFO] [gerencia] ExportChangesDiff->InitializeExporter(): Found '0' changes for 'hierarchy'
07/11/2018 23:53:35 [24194] [ INFO] [gerencia] ExportChangesDiff->InitializeExporter(): Found '0' changes for 'hierarchy'
07/11/2018 23:53:36 [31900] [ INFO] [gerencia] ExportChangesDiff->InitializeExporter(): Found '0' changes for '69ba70f8'
07/11/2018 23:53:46 [  539] [ INFO] [gerencia] ExportChangesDiff->InitializeExporter(): Found '0' changes for '1543cbea'
07/11/2018 23:53:46 [  539] [ INFO] [gerencia] StatusException: SyncCollections->CheckForChanges(): Timeout forced after 47s from 660s due to other process - code: 3 - file: /var/www/zpush/src/lib/core/synccollections.php:579
07/11/2018 23:53:46 [  539] [ INFO] [gerencia] cmd='Ping' memory='3.25 MiB/3.50 MiB' time='46.98s' devType='Android' devId='4e696e65353731394334383632313636' getUser='gerencia' from='192.168.3.254' idle='47s' version='GIT' method='POST' httpcode='200'
07/11/2018 23:53:48 [ 8573] [ INFO] [gerencia] ExportChangesDiff->InitializeExporter(): Found '0' changes for 'Cpersonal'
07/11/2018 23:53:48 [ 8573] [ INFO] [gerencia] cmd='Sync' memory='2.88 MiB/3.25 MiB' time='0.53s' devType='SamsungDevice' devId='sec1f9c9384c7e3f' getUser='gerencia' from='192.168.3.254' idle='0s' version='GIT' method='POST' httpcode='200'
07/11/2018 23:53:48 [  539] [ INFO] [gerencia] ExportChangesDiff->InitializeExporter(): Found '0' changes for 'Tpersonal'
07/11/2018 23:53:48 [  539] [ INFO] [gerencia] cmd='Sync' memory='2.69 MiB/3.00 MiB' time='0.62s' devType='SamsungDevice' devId='sec1f9c9384c7e3f' getUser='gerencia' from='192.168.3.254' idle='0s' version='GIT' method='POST' httpcode='200'
^C

as you can see it shows the meeting
im not sure what else to look for

Thank you

Manfred

Hi killmasta93,

@killmasta93 said in Issue with invitation Calendar?:

Hi Manfred,
Thank you for the reply, I was checking though the logs but could not find any other errors besides

             StatusException: SyncCollections->CheckForChanges(): Timeout forced after 28s from 470s due to other process - code: 3 - file: /var/www/zpush/src/lib/core/synccollections.php:579

          [WARN] [gerencia] SyncObject->Check(): object from type SyncMail: parameter 'from' contains an invalid email address 'gerencia'. Address is removed.

You don’t have to look for errors only, you have to check if outlook sends the calendar item to the server as a new appointment. To see the data sent between outlook/mobile devices and server you have to set the loglevel to WBXML.

@killmasta93 said in Issue with invitation Calendar?:

as you can see it shows the meeting
im not sure what else to look for

Thank you

It shows the MeetingResponse which is not the same. The INFO loglevel is not helpful when debugging. Felix posted the link in his first response how to enabled WBXML logs and I also already responded in this thread that in order to tell more, WBXML level logs are required.

Manfred

killmasta93

Hi manfred,
Thank you for the reply, sorry for not abiliting the WBXML logs this time i enabled it and added the part when i send the invitation from a hotmail account to my caldav account

https://pastebin.com/K2bSZtLp

Thank you

Manfred

Hi killmasta93,

to you it’s obvious what you’re sending and receiving, but it would help me a lot if you’d at least provide the subject of the meeting request.

Scrolling through more than 1000 lines of the log you’ve posted, there’s one meeting response “Canceled: testest” which is actually a meeting cancellation. Is it really the right one?

Manfred

killmasta93

Hi Manfred, Thank you again for the reply and your time. Sorry for not posting the subject i did another test.
The subject is

       thisisatest

https://pastebin.com/tbBkjBmL

Manfred

Hi killmasta93,

the log you’ve posted begins when you send the MeetingResponse. It’s also important to see what happens when you receive the meeting request.

Manfred

killmasta93

Thanks for the reply, so i did another test the subject is

     this is a test 3

i send invitation from client@hotmail.com(on outlook 2013) to gerencia@mydomain,com(on mobile using nine android app)
after the invitation got send, on my android i hit accept. the issue is that i dont see the receive part on the log, but i receive it though the email with no issue.

               [DEBUG] [gerencia] cmd='MeetingResponse' devType='Android' devId='4e696e65353731394334383632313636' getUser='gerencia' from='192.168.3.254' version='GIT' method='POST'

Not sure if its a bug

LOG:
https://pastebin.com/2apmKfGW
Thank you

Manfred

Hi killmasta93,

did you hit accept on the invitation email or did you open the calendar and hit accept on the appointment? I’m not familiar with the functionality of the Nine app, so the more information you provide the better. Does the app use the built-in email and calendar or is it all inside the app?

There’s no calendar item “this is a test 3” being synced to the server, so I guess that the app creates the appointment in the calendar only locally and doesn’t sync it back to the server. Maybe there’s some checkbox somewhere in the settings like “sync appointments from meeting requests to the server”.

Manfred

killmasta93

Hi Manfred,
Thank you again for your help really appreciate it. This time i did the test with the outlook mail app
0_1542255999756_Clipboarder.2018.11.14-006.png
the subject is testing 4
the only part i found in the log that shows receive
would be this which i dont think its sending the request to the server

          14/11/2018 23:21:51 [29775] [WBXML] [gerencia] O        <POOMMAIL:DateReceived>
          14/11/2018 23:21:51 [29775] [WBXML] [gerencia] O        2018-11-15T04:21:53.000Z
          14/11/2018 23:21:51 [29775] [WBXML] [gerencia] O        </POOMMAIL:DateReceived>

This is the whole log

https://pastebin.com/5LmWY1Eu

Thank you again