6.0.0-beta1
7/7/25

[#13040] Horde thinks the phone is a browser
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

History
03/12/2014 03:59:04 PM software-horde (at) interfasys (dot) ch Comment #4 Reply to this comment
The ONLY way this error is triggered is if the client issues a GET 
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.
I think you're correct. I was able to find, through the sync noise, 
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.
03/12/2014 03:06:19 PM Michael Rubinsky Comment #3
State ⇒ Not A Bug
Reply to this comment
The ONLY way this error is triggered is if the client issues a GET 
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.
03/12/2014 12:05:11 PM software-horde (at) interfasys (dot) ch Comment #2 Reply to this comment
Looking at more data, it seems to affect one email account on 
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.


03/12/2014 11:49:13 AM software-horde (at) interfasys (dot) ch Comment #1
Priority ⇒ 1. Low
Patch ⇒ No
Milestone ⇒
Queue ⇒ Horde Framework Packages
Summary ⇒ Horde thinks the phone is a browser
Type ⇒ Bug
State ⇒ Unconfirmed
Reply to this comment
I get a lot of :
"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"]




Saved Queries