Summary | Horde thinks the phone is a browser |
Queue | Horde Framework Packages |
Queue Version | Git master |
Type | Bug |
State | Not A Bug |
Priority | 1. Low |
Owners | |
Requester | software-horde (at) interfasys (dot) ch |
Created | 03/12/2014 (4135 days ago) |
Due | |
Updated | 03/12/2014 (4135 days ago) |
Assigned | |
Resolved | 03/12/2014 (4135 days ago) |
Github Issue Link | |
Github Pull Request | |
Milestone | |
Patch | No |
request that is not an OPTION or AUTODISCOVER request...which are
the ONLY two requests allowed via GET in ActiveSync.
In other words, if this is the actual EAS client causing this error,
the client in broken.
the client which triggers the error.
It's an Android device sending a ping via a GET request.
[12/Mar/2014:16:41:18 +0100] "GET
/Microsoft-Server-ActiveSync?Cmd=Ping&User=user%40domain.tld&DeviceId=androidc1234&DeviceType=Android HTTP/1.1" 500 738 "-"
"Android/4.1.2-EAS-1.3"
The same request is then sent a few seconds later using POST.
State ⇒ Not A Bug
request that is not an OPTION or AUTODISCOVER request...which are the
ONLY two requests allowed via GET in ActiveSync.
In other words, if this is the actual EAS client causing this error,
the client in broken.
BlackBerry 10 which tries to connect over and over until it succeeds.
I don't know how to get more valuable information from the logs. The
URL where the data is posted is exactly the same as the one used by
other accounts.
Priority ⇒ 1. Low
Patch ⇒ No
Milestone ⇒
Queue ⇒ Horde Framework Packages
Summary ⇒ Horde thinks the phone is a browser
Type ⇒ Bug
State ⇒ Unconfirmed
"Trying to access the ActiveSync endpoint from a browser. Not Supported"
And I've checked, those are not bots scanning the domain.
Looking at the debug log, I don't see any difference between a
successful sync and one which fails. Both handshakes seem to end the
same with :Horde_Rpc::__construct complete
but then sometimes I get the error message, sometimes it goes to the
next step: Horde_Rpc_ActiveSync::getResponse() starting for Sync
That's assuming I understand what's going on from reading the logs of course.
Here is the debug log:
2014-03-12T11:03:54+00:00 DEBUG: HORDE 1.
Horde_Rpc_ActiveSync->getResponse() /var/www/html/horde/rpc.php:156
2014-03-12T11:03:54+00:00 DEBUG: HORDE [turba] Load config file
(attributes.php; app: turba) [pid 53447 on line 402 of
"/usr/local/php/lib/php/Horde.php"]
2014-03-12T11:03:54+00:00 DEBUG: HORDE [turba] Load config file
(backends.php; app: turba) [pid 53447 on line 402 of
"/usr/local/php/lib/php/Horde.php"]
2014-03-12T11:03:54+00:00 DEBUG: HORDE [turba] SQL (0.0000s)
2014-03-12T11:03:54+00:00 DEBUG: HORDE [turba] SQL (0.0000s)
2014-03-12T11:03:54+00:00 DEBUG: HORDE [turba] SQL (0.0000s)
2014-03-12T11:03:54+00:00 DEBUG: HORDE [imp] Hook prefs_init in
application imp called. [pid 53447 on line 58 of
"/usr/local/php/lib/php/Horde/Core/Hooks.php"]
2014-03-12T11:03:54+00:00 DEBUG: HORDE [imp] Hook prefs_init in
application imp called. [pid 53447 on line 58 of
"/usr/local/php/lib/php/Horde/Core/Hooks.php"]
2014-03-12T11:03:54+00:00 DEBUG: HORDE [turba] Load config file
(backends.php; app: turba) [pid 53447 on line 402 of
"/usr/local/php/lib/php/Horde.php"]
2014-03-12T11:03:54+00:00 DEBUG: HORDE [turba] SQL (0.0000s)
2014-03-12T11:03:54+00:00 DEBUG: HORDE [turba] SQL (0.0000s)
2014-03-12T11:03:54+00:00 DEBUG: HORDE [turba] SQL (0.0000s)
2014-03-12T11:03:54+00:00 NOTICE: HORDE [imp] Login success for
user@domain.tld (Horde user user@domain.tld) [1.2.3.4] to
{localhost:143 [imap]} [pid 53447 on line 157 of
"/var/www/html/horde/imp/lib/Auth.php"]
2014-03-12T11:03:54+00:00 DEBUG: HORDE [imp] SQL (0.0002s)
2014-03-12T11:03:54+00:00 DEBUG: HORDE [horde] SQL (0.0006s)
2014-03-12T11:03:54+00:00 DEBUG: HORDE [horde] SQL (0.0001s)
2014-03-12T11:03:54+00:00 DEBUG: HORDE [horde] SQL (0.0001s)
2014-03-12T11:03:54+00:00 DEBUG: HORDE [horde] SQL (0.0002s)
2014-03-12T11:03:54+00:00 DEBUG: HORDE [horde] Max memory usage:
9961472 bytes [pid 53447 on line 598 of
"/usr/local/php/lib/php/Horde/Registry.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE Load config file (conf.php;
app: horde) [pid 53447 on line 402 of
"/usr/local/php/lib/php/Horde.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE Setting sam inactive because
the fileroot does not exist or the application is not configured yet.
[pid 53447 on line 738 of "/usr/local/php/lib/php/Horde/Registry.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE Setting ansel inactive because
the fileroot does not exist or the application is not configured yet.
[pid 53447 on line 738 of "/usr/local/php/lib/php/Horde/Registry.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE Setting wicked inactive because
the fileroot does not exist or the application is not configured yet.
[pid 53447 on line 738 of "/usr/local/php/lib/php/Horde/Registry.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE Setting chora inactive because
the fileroot does not exist or the application is not configured yet.
[pid 53447 on line 738 of "/usr/local/php/lib/php/Horde/Registry.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE Setting whups inactive because
the fileroot does not exist or the application is not configured yet.
[pid 53447 on line 738 of "/usr/local/php/lib/php/Horde/Registry.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE Setting luxor inactive because
the fileroot does not exist or the application is not configured yet.
[pid 53447 on line 738 of "/usr/local/php/lib/php/Horde/Registry.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE Setting klutz inactive because
the fileroot does not exist or the application is not configured yet.
[pid 53447 on line 738 of "/usr/local/php/lib/php/Horde/Registry.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE Setting jonah inactive because
the fileroot does not exist or the application is not configured yet.
[pid 53447 on line 738 of "/usr/local/php/lib/php/Horde/Registry.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE Setting hermes inactive because
the fileroot does not exist or the application is not configured yet.
[pid 53447 on line 738 of "/usr/local/php/lib/php/Horde/Registry.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE Setting sesha inactive because
the fileroot does not exist or the application is not configured yet.
[pid 53447 on line 738 of "/usr/local/php/lib/php/Horde/Registry.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE Setting kolab inactive because
the fileroot does not exist or the application is not configured yet.
[pid 53447 on line 738 of "/usr/local/php/lib/php/Horde/Registry.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE Setting agora inactive because
the fileroot does not exist or the application is not configured yet.
[pid 53447 on line 738 of "/usr/local/php/lib/php/Horde/Registry.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE Setting ulaform inactive
because the fileroot does not exist or the application is not
configured yet. [pid 53447 on line 738 of
"/usr/local/php/lib/php/Horde/Registry.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE Setting vilma inactive because
the fileroot does not exist or the application is not configured yet.
[pid 53447 on line 738 of "/usr/local/php/lib/php/Horde/Registry.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE Load config file (nls.php; app:
horde) [pid 53447 on line 402 of "/usr/local/php/lib/php/Horde.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE Load config file (hooks.php;
app: imp) [pid 53447 on line 402 of "/usr/local/php/lib/php/Horde.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE [imp] Load config file
(conf.php; app: imp) [pid 53447 on line 402 of
"/usr/local/php/lib/php/Horde.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE [imp] Load config file
(prefs.php; app: horde) [pid 53447 on line 402 of
"/usr/local/php/lib/php/Horde.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE [imp] Load config file
(prefs.php; app: imp) [pid 53447 on line 402 of
"/usr/local/php/lib/php/Horde.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE [imp] Hook prefs_init in
application imp called. [pid 53447 on line 58 of
"/usr/local/php/lib/php/Horde/Core/Hooks.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE [imp] Hook prefs_init in
application imp called. [pid 53447 on line 58 of
"/usr/local/php/lib/php/Horde/Core/Hooks.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE [imp] Hook prefs_init in
application imp called. [pid 53447 on line 58 of
"/usr/local/php/lib/php/Horde/Core/Hooks.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE [imp] Load config file
(backends.php; app: imp) [pid 53447 on line 402 of
"/usr/local/php/lib/php/Horde.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE Load config file (hooks.php;
app: ingo) [pid 53447 on line 402 of "/usr/local/php/lib/php/Horde.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE [gollem] Load config file
(conf.php; app: gollem) [pid 53447 on line 402 of
"/usr/local/php/lib/php/Horde.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE [gollem] Load config file
(prefs.php; app: gollem) [pid 53447 on line 402 of
"/usr/local/php/lib/php/Horde.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE [gollem] Load config file
(backends.php; app: gollem) [pid 53447 on line 402 of
"/usr/local/php/lib/php/Horde.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE [horde] Horde_Rpc::__construct
complete [pid 53447 on line 47 of
"/usr/local/php/lib/php/Horde/Rpc/ActiveSync.php"]
2014-03-12T11:03:55+00:00 DEBUG: HORDE [horde] Accessing ActiveSync
endpoing from browser or missing required data. [pid 53447 on line 156
of "/var/www/html/horde/rpc.php"]
2014-03-12T11:03:55+00:00 ERR: HORDE [horde] Trying to access the
ActiveSync endpoint from a browser. Not Supported. [pid 53447 on line
101 of "/usr/local/php/lib/php/Horde/Rpc/ActiveSync.php"]