b1gMail 7.4.0 Beta 3

bcmAlex · Dec 4, 2017

Der code von Heute......

Code:

04/12/2017 22:35:19 [18676] [ INFO] [unknown] AuthenticationRequiredException: Access denied. Please send authorisation information - code: 0 - file: /var/www/vhosts/bigcitymail.de/zpush/index.php:90
04/12/2017 22:35:19 [18676] [ INFO] [unknown] User-agent: 'Mozilla/5.0 (Windows NT 10.0; rv:57.0) Gecko/20100101 Firefox/57.0'
04/12/2017 22:35:19 [18676] [FATAL] [unknown] Exception: (AuthenticationRequiredException) - Access denied. Please send authorisation information
04/12/2017 22:35:19 [18676] [ INFO] [unknown] cmd='' memory='2.30 MiB/2.75 MiB' time='0.02s' devType='' devId='' getUser='unknown' from='200373600494961061' version='2.3.0' method='GET' httpcode='401'
04/12/2017 22:35:45 [18677] [ INFO] [unknown] AuthenticationRequiredException: Access denied. Please send authorisation information - code: 0 - file: /var/www/vhosts/bigcitymail.de/zpush/index.php:90
04/12/2017 22:35:45 [18677] [ INFO] [unknown] User-agent: 'Mozilla/5.0 (Windows NT 10.0; rv:57.0) Gecko/20100101 Firefox/57.0'
04/12/2017 22:35:45 [18677] [FATAL] [unknown] Exception: (AuthenticationRequiredException) - Access denied. Please send authorisation information
04/12/2017 22:35:45 [18677] [ INFO] [unknown] cmd='' memory='1.72 MiB/2.00 MiB' time='0.02s' devType='' devId='' getUser='unknown' from='200373600494961061' version='2.3.0' method='GET' httpcode='401'
04/12/2017 23:03:44 [23187] [ INFO] [unknown] AuthenticationRequiredException: Access denied. Please send authorisation information - code: 0 - file: /var/www/vhosts/bigcitymail.de/zpush/index.php:90
04/12/2017 23:03:44 [23187] [ INFO] [unknown] User-agent: 'Mozilla/5.0 (Windows NT 10.0; rv:57.0) Gecko/20100101 Firefox/57.0'
04/12/2017 23:03:44 [23187] [FATAL] [unknown] Exception: (AuthenticationRequiredException) - Access denied. Please send authorisation information
04/12/2017 23:03:44 [23187] [ INFO] [unknown] cmd='' memory='2.13 MiB/2.50 MiB' time='0.02s' devType='' devId='' getUser='unknown' from='200373600494961061' version='2.3.0' method='GET' httpcode='401'
04/12/2017 23:04:10 [23197] [ INFO] [unknown] AuthenticationRequiredException: Access denied. Please send authorisation information - code: 0 - file: /var/www/vhosts/bigcitymail.de/zpush/index.php:90
04/12/2017 23:04:10 [23197] [ INFO] [unknown] User-agent: 'Mozilla/5.0 (Windows NT 10.0; rv:57.0) Gecko/20100101 Firefox/57.0'
04/12/2017 23:04:10 [23197] [FATAL] [unknown] Exception: (AuthenticationRequiredException) - Access denied. Please send authorisation information
04/12/2017 23:04:10 [23197] [ INFO] [unknown] cmd='' memory='1.72 MiB/2.00 MiB' time='0.01s' devType='' devId='' getUser='unknown' from='200373600494961061' version='2.3.0' method='GET' httpcode='401'
04/12/2017 23:09:40 [24150] [ INFO] [unknown] AuthenticationRequiredException: Access denied. Please send authorisation information - code: 0 - file: /var/www/vhosts/bigcitymail.de/zpush/index.php:90
04/12/2017 23:09:40 [24150] [ INFO] [unknown] User-agent: 'Mozilla/5.0 (Windows NT 10.0; rv:57.0) Gecko/20100101 Firefox/57.0'
04/12/2017 23:09:40 [24150] [FATAL] [unknown] Exception: (AuthenticationRequiredException) - Access denied. Please send authorisation information
04/12/2017 23:09:40 [24150] [ INFO] [unknown] cmd='' memory='1.72 MiB/2.00 MiB' time='0.01s' devType='' devId='' getUser='unknown' from='200373600494961061' version='2.3.0' method='GET' httpcode='401'
04/12/2017 23:09:55 [24151] [ INFO] [unknown] AuthenticationRequiredException: Access denied. Please send authorisation information - code: 0 - file: /var/www/vhosts/bigcitymail.de/zpush/index.php:90
04/12/2017 23:09:55 [24151] [ INFO] [unknown] User-agent: 'Mozilla/5.0 (Windows NT 10.0; rv:57.0) Gecko/20100101 Firefox/57.0'
04/12/2017 23:09:55 [24151] [FATAL] [unknown] Exception: (AuthenticationRequiredException) - Access denied. Please send authorisation information
04/12/2017 23:09:55 [24151] [ INFO] [unknown] cmd='' memory='1.72 MiB/2.00 MiB' time='0.01s' devType='' devId='' getUser='unknown' from='200373600494961061' version='2.3.0' method='GET' httpcode='401'
04/12/2017 23:10:09 [24995] [ INFO] [unknown] AuthenticationRequiredException: Access denied. Please send authorisation information - code: 0 - file: /var/www/vhosts/bigcitymail.de/zpush/index.php:90
04/12/2017 23:10:09 [24995] [ INFO] [unknown] User-agent: 'Mozilla/5.0 (Windows NT 10.0; rv:57.0) Gecko/20100101 Firefox/57.0'
04/12/2017 23:10:09 [24995] [FATAL] [unknown] Exception: (AuthenticationRequiredException) - Access denied. Please send authorisation information
04/12/2017 23:10:09 [24995] [ INFO] [unknown] cmd='' memory='1.72 MiB/2.00 MiB' time='0.01s' devType='' devId='' getUser='unknown' from='200373600494961061' version='2.3.0' method='GET' httpcode='401'

patrick · Dec 4, 2017

Da hast du das Loglevel aber nicht auf maximale Ausführlichkeit gestellt?

bcmAlex · Dec 4, 2017

Sry vergessen zu speichern

Ist der Log so besser ?

Code:

04/12/2017 23:32:23 [28774] [DEBUG] [unknown] -------- Start
04/12/2017 23:32:23 [28774] [DEBUG] [unknown] cmd='' devType='' devId='' getUser='unknown' from='200373600494961061' version='2.3.0' method='GET'
04/12/2017 23:32:23 [28774] [DEBUG] [unknown] Used timezone 'Europe/Berlin'
04/12/2017 23:32:23 [28774] [DEBUG] [unknown] ZPush::GetBackend(): autoload register ZPush::IncludeBackend
04/12/2017 23:32:23 [28774] [DEBUG] [unknown] Including backend file: '/var/www/vhosts/bigcitymail.de/zpush/backend/b1gmail/b1gmail.php'
04/12/2017 23:32:23 [28774] [DEBUG] [unknown] ZPush::GetBackend(): autoload unregister ZPush::IncludeBackend
04/12/2017 23:32:23 [28774] [DEBUG] [unknown] Request::ProcessHeaders() ASVersion: 14.0
04/12/2017 23:32:23 [28774] [ INFO] [unknown] AuthenticationRequiredException: Access denied. Please send authorisation information - code: 0 - file: /var/www/vhosts/bigcitymail.de/zpush/index.php:90
04/12/2017 23:32:23 [28774] [ INFO] [unknown] User-agent: 'Mozilla/5.0 (Windows NT 10.0; rv:57.0) Gecko/20100101 Firefox/57.0'
04/12/2017 23:32:23 [28774] [FATAL] [unknown] Exception: (AuthenticationRequiredException) - Access denied. Please send authorisation information
04/12/2017 23:32:23 [28774] [DEBUG] [unknown] ZPush::PrintZPushLegal()
04/12/2017 23:32:23 [28774] [DEBUG] [unknown] TopCollector(): Initialized mutexid Resource id #46 and memid Resource id #47.
04/12/2017 23:32:23 [28774] [DEBUG] [unknown] TopCollector initialised with IPC provider 'IpcSharedMemoryProvider' with type '20'
04/12/2017 23:32:23 [28774] [ INFO] [unknown] cmd='' memory='1.72 MiB/2.00 MiB' time='0.00s' devType='' devId='' getUser='unknown' from='200373600494961061' version='2.3.0' method='GET' httpcode='401'
04/12/2017 23:32:23 [28774] [DEBUG] [unknown] -------- End

bcmAlex · Dec 5, 2017

Hey,

habe durch Zufall einen Fehler gefunden.

Wenn eine signierte Mail reinkommt und man auf das Zertifikat klickt erscheint in der Zertifikatsinformation ein Fehler:

Code:

Warning: strlen() expects parameter 1 to be string, array given in /var/www/vhosts/bigcitymail.de/mailcenter/serverlib/common.inc.php on line 709

oyoony · Dec 5, 2017

Hallo Patrick,

hier nochmal das Detailierte Loglfile

05/12/2017 16:37:11 [ 3630] [DEBUG] [unknown] -------- Start
05/12/2017 16:37:11 [ 3630] [DEBUG] [unknown] cmd='' devType='' devId='' getUser='unknown' from='78.48.218.217' version='2.3.8' method='GET'
05/12/2017 16:37:11 [ 3630] [ INFO] [unknown] AuthenticationRequiredException: Access denied. Please send authorisation information - code: 0 - file: /var/www/vhosts/domain.de/httpdocs/Microsoft-Server-ActiveSync/index.php:56
05/12/2017 16:37:11 [ 3630] [ INFO] [unknown] User-agent: 'unknown'
05/12/2017 16:37:11 [ 3630] [FATAL] [unknown] Exception: (AuthenticationRequiredException) - Access denied. Please send authorisation information
05/12/2017 16:37:11 [ 3630] [DEBUG] [unknown] ZPush:rintZPushLegal()
05/12/2017 16:37:11 [ 3630] [DEBUG] [unknown] TopCollector(): Initialized mutexid Resource id #14 and memid Resource id #15.
05/12/2017 16:37:11 [ 3630] [DEBUG] [unknown] TopCollector initialised with IPC provider 'IpcSharedMemoryProvider' with type '20'
05/12/2017 16:37:11 [ 3630] [ INFO] [unknown] cmd='' memory='1.50 MiB/2.00 MiB' time='0.01s' devType='' devId='' getUser='unknown' from='78.48.218.217' idle='0s' version='2.3.8' method='GET' httpcode='401'
05/12/2017 16:37:11 [ 3630] [DEBUG] [unknown] -------- End

Hier die ERROR.LOG

05/12/2017 16:37:11 [ 3630] [FATAL] [unknown] Exception: (AuthenticationRequiredException) - Access denied. Please send authorisation information

Das Merkwürdeige ist, dass er nicht mal den UserAgent identifiziert.
Username und Passwort wurden eingegeben wie gwohnt, wenn man sich über den Browser einloggt...

Gruss OYOONY

patrick · Dec 5, 2017

Sieht für mich fast so aus, als wenn der Authorization-Header nicht an PHP durchgereicht wird vom Webserver. Am unproblematischsten ist hier, wenn man PHP als Modul in Apache einbindet. Wenn man andere Webserver oder Einbindungsmethoden (FastCGI/FPM, ...) verwendet, ist ggf. eine entsprechende Konfigurationsänderung im Webserver nötig. Siehe auch hier bzw hier.

@bcmAlex: Danke für den Hinweis.

bcmAlex · Dec 5, 2017

Nicht dafür Patrick.

Bei versendeten Mails mit Zertifikat erscheint der Fehler nicht und alle Angaben sind korrekt.

Bezüglich zpush sehe ich mir nachher die Thematik genauer an. Im Moment versuche ich die S MIME Zertifikatsangelegenheit einzubinden

oyoony · Dec 5, 2017

Hallo Patrick,

also ich habe auf dem selbern Server mehrere Webanwendungen ausser B1GMail laufen. Da funktioniert das durchreichen des Auth einwandfrei und ohne Probleme.
Daher verstehe ich nicht, dass hier bei z-push das Auth nicht funzt...

Verwendet wird FPM für PHP 7.0 von Apache.

oyoony · Dec 6, 2017

Also ich denke ich habe den Fehler gefunden...
Soweit ich das verstanden habe, liegt das am FPM für nginx ...
Da ich den nginx als Proxy vor den Apache geschaltet habe....
Die Features dafür kommen erst ab der z-push Version 2.4, was natürlich etwas doof ist...

Heute Abend mal sehen wie ich das Problem lösen kann...?(

Gruß OYO

oyoony · Dec 7, 2017

Soo...
Also ich habs soweit geschafft, dass nun nginx den Authorization-Header an PHP durchreicht. Dies geschieht allerdings bisher nur bei PHP5. Mit PHP7 bin ich noch nicht soweit.

Jetzt erhalte ich folgende Log

Code:

07/12/2017 19:20:12 [26949] [DEBUG] [user@domain.de] -------- Start
07/12/2017 19:20:12 [26949] [DEBUG] [user@domain.de] cmd='Ping' devType='Android' devId='androidc339228027' getUser='user@domain.de' from='85.181.107.9' version='2.3.8' method='GET'
07/12/2017 19:20:12 [26949] [DEBUG] [user@domain.de] Used timezone 'Europe/Amsterdam'
07/12/2017 19:20:12 [26949] [DEBUG] [user@domain.de] Including backend file: '/var/www/vhosts/domain.de/httpdocs/Microsoft-Server-ActiveSync/backend/b1gmail/b1gmail.php'
07/12/2017 19:20:12 [26949] [WARN] [user@domain.de] /var/www/vhosts/domain.de/httpdocs/Microsoft-Server-ActiveSync/backend/b1gmail/b1gmail.php:62 mysql_connect(): Connection refused (2)
07/12/2017 19:20:12 [26949] [FATAL] [user@domain.de] FatalException: Failed to connect to b1gMail MySQL server - code: 0 - file: /var/www/vhosts/domain.de/httpdocs/Microsoft-Server-ActiveSync/backend/b1gmail/b1gmail.php:64
07/12/2017 19:20:12 [26949] [ INFO] [user@domain.de] User-agent: 'unknown'
07/12/2017 19:20:12 [26949] [FATAL] [user@domain.de] Exception: (FatalException) - Failed to connect to b1gMail MySQL server
07/12/2017 19:20:12 [26949] [DEBUG] [user@domain.de] ZPush::PrintZPushLegal()
07/12/2017 19:20:12 [26949] [DEBUG] [user@domain.de] TopCollector(): Initialized mutexid Resource id #41 and memid Resource id #42.
07/12/2017 19:20:12 [26949] [DEBUG] [user@domain.de] TopCollector initialised with IPC provider 'IpcSharedMemoryProvider' with type '20'
07/12/2017 19:20:12 [26949] [ INFO] [user@domain.de] cmd='Ping' memory='1.66 MiB/2.00 MiB' time='0.01s' devType='Android' devId='androidc339228027' getUser='user@domain.de' from='85.181.107.9' idle='0s' version='2.3.8' method='GET' httpcode='500'
07/12/2017 19:20:12 [26949] [DEBUG] [user@domain.de] -------- End

Nun stellt sich mir die Frage, warum er die Verbindung trotz richtiger Nuterdaten, herstellen kann..
*grüberl*

Gruß OYO

patrick · Dec 7, 2017

MySQL-Zugangsdaten wohl falsch...?

oyoony · Dec 7, 2017

patrick said:
MySQL-Zugangsdaten wohl falsch...?

Hallo Patrick,
Das habe ich auch erst gedacht..
Weil es das naheliegenste wäre...
Auch das er vielleicht localhost wegen der beiden IP‘s nicht richtig auflösen kann..

Gesendet von iPhone mit Tapatalk

bcmAlex · Dec 7, 2017

Knnst du bitte mitteilen wie du so weit gekommen bist ? Würde mich echt interessieren weil ich hier kein Stück weiter komme.

oyoony · Dec 9, 2017

@bcmAlex

Also in meinem Fall musste der Authorization-Header über den NGINX weitergereicht werden. Demnach musste FastCGI/FPM mitgeteilt werden, welcher Webserver für die Weiterleitung des AuthHeader zuständig ist, da ich den NGINX als Proxy vor den Apache geschaltet habe. Dies muss man dann in der ServerConfig entsprechend anpassen.

Das funzt nun super...

Was nun noch Probleme macht ist das let´s encrypt Zertifikat....
Das will irgendwie nicht mit IOS 11 zusammenarbeiten.
Unter Android funzt let´s encrypt super...

IOS 11 lehnt aus Sicherheitsgründen let´s encrypt Zertifikate ab, da Apple die Root CA aus dem CA Stamm entfernt hat.
Ich denke das Problem dürfte dann auch ab Januar behoben sein, wenn let´s encrypt auch Subdomains unterstützt. Dann dürfte let´s encrypt zwei neue Sicherheitsinstanzen eingefügt haben. Die alten sollen wollen Mist gebaut haben.

Gruß OYO

bcmAlex · Dec 10, 2017

Guten Morgen,

danke für den Hinweis. Werde es mal probieren

Euch allen einen schönen 2. Advent !

oyoony · Dec 12, 2017

Hallo Pratick,

ich hab da wohl doch noch nen Problem am Strart:

Offensichtlich klappt der Login, aber z-push kann so wie es ausschaut keine Daten zurück liefern.

Logfile:

Code:

12/12/2017 08:16:03 [26950] [DEBUG] [user@domain.de] -------- Start
12/12/2017 08:16:03 [26950] [DEBUG] [user@domain.de] cmd='Ping' devType='Android' devId='androidc1744440925' getUser='user@domain.de' from='85.182.87.97' version='2.3.8' method='GET'
12/12/2017 08:16:03 [26950] [DEBUG] [user@domain.de] Used timezone 'Europe/Amsterdam'
12/12/2017 08:16:03 [26950] [DEBUG] [user@domain.de] Including backend file: '/var/www/vhosts/domain.de/httpdocs/Microsoft-Server-ActiveSync/backend/b1gmail/b1gmail.php'
12/12/2017 08:16:03 [26950] [DEBUG] [user@domain.de] Request::ProcessHeaders() ASVersion: 14.0
12/12/2017 08:16:03 [26950] [DEBUG] [user@domain.de] ZPush::CommandNeedsAuthentication(18): true
12/12/2017 08:16:03 [26950] [DEBUG] [user@domain.de] NoPostRequestException: This is the Z-Push location and can only be accessed by Microsoft ActiveSync-capable devices - code: 2 - file: /var/www/vhosts/domain.de/httpdocs/Microsoft-Server-ActiveSync/index.php:90
12/12/2017 08:16:03 [26950] [ INFO] [user@domain.de] User-agent: 'Android-Mail/7.11.5.177402951.release'
12/12/2017 08:16:03 [26950] [DEBUG] [user@domain.de] ZPush::PrintZPushLegal()
12/12/2017 08:16:03 [26950] [ INFO] [user@domain.de] cmd='Ping' memory='1.83 MiB/2.25 MiB' time='0.01s' devType='Android' devId='androidc1744440925' getUser='user@domain.de' from='85.182.87.97' idle='0s' version='2.3.8' method='GET' httpcode='200'
12/12/2017 08:16:03 [26950] [DEBUG] [user@domain.de] -------- End

Z-push error_log bleibt leer

und auf dem Tablet, erschein folgende Meldung:

Sie haben eine falsche Serveradresse eingegeben oder der Server erfordert eine Protokollversion, die zur Zeit nicht unterstützt wird

Serveradresse ist aber soweit ich sehen kann okay.

Gruß OYO

b1gMail 7.4.0 Beta 3

Attachments