[horde] Active Sync Error

Lang, Ralf ralf.lang at gmail.com
Fri May 16 08:26:09 UTC 2025


Hello Andreas,

On 16/05/2025 09:10, Andreas Mauser wrote:
> Am 16. Mai 2025 um 08:49 schrieb "Ralf Lang"<ralf.lang at gmail.com 
> mailto:ralf.lang at gmail.com?to=%22Ralf%20Lang%22%20%3Cralf.lang%40gmail.com%3E 
> >:
>
>
>> hi andreas,
>> depending in your configuration the error handler writes to stdout, to a horde log file, to apache/webserver log, to a php-fpm log or to the syslog/systemd journal. We would need the trace to help you hunt this down.
> It is set to log to syslog. Below some of the lines, when start a sync:
>
> 2025-05-16T09:09:29.867574+02:00 wm HORDE: #15 /var/www/html/wm/vendor/horde/horde/rpc.php(165): Horde_Rpc_ActiveSync->getResponse()
> 2025-05-16T09:09:29.867787+02:00 wm HORDE: #16 {main} [pid 565 on line 76 of "/var/www/html/wm/vendor/horde/core/lib/Horde/ErrorHandler.php"]
> 2025-05-16T09:09:34.657124+02:00 wm HORDE: [imp] Login success for andreas (10.0.25.8) (forwarded for [80.152.143.17]) to {imap://10.0.25.2/} [pid 564 on line 145 of "/var/www/html/wm/vendor/horde/imp/lib/Auth.php"]
> 2025-05-16T09:09:34.733152+02:00 wm*HORDE: ValueError: mb_substr(): Argument #4 ($encoding) must be a 
> valid encoding, "windows-1258" given in 
> /var/www/html/wm/vendor/horde/util/lib/Horde/String.php:329*

*HORDE: ValueError: mb_substr(): Argument #4 ($encoding) must be a valid 
encoding, "windows-1258" given in 
/var/www/html/wm/vendor/horde/util/lib/Horde/String.php:329*

Bingo!

That's something I think we can address.

Tickets :: [#15191] ActiveSync: mb_substr croaks on windows native 
encoding <https://bugs.horde.org/ticket/15191>

> 2025-05-16T09:09:34.733442+02:00 wm HORDE: Stack trace:
> 2025-05-16T09:09:34.734529+02:00 wm HORDE: #0 /var/www/html/wm/vendor/horde/util/lib/Horde/String.php(329): mb_substr()
> 2025-05-16T09:09:34.735295+02:00 wm HORDE: #1 /var/www/html/wm/vendor/horde/activesync/lib/Horde/ActiveSync/Imap/MessageBodyData.php(453): Horde_String::substr()
> 2025-05-16T09:09:34.735997+02:00 wm HORDE: #2 /var/www/html/wm/vendor/horde/activesync/lib/Horde/ActiveSync/Imap/MessageBodyData.php(302): Horde_ActiveSync_Imap_MessageBodyData->_getPlainPart()
> 2025-05-16T09:09:34.736653+02:00 wm HORDE: #3 /var/www/html/wm/vendor/horde/activesync/lib/Horde/ActiveSync/Imap/MessageBodyData.php(145): Horde_ActiveSync_Imap_MessageBodyData->_getParts()
> 2025-05-16T09:09:34.737142+02:00 wm HORDE: #4 /var/www/html/wm/vendor/horde/activesync/lib/Horde/ActiveSync/Imap/Message.php(303): Horde_ActiveSync_Imap_MessageBodyData->__construct()
> 2025-05-16T09:09:34.737430+02:00 wm HORDE: #5 /var/www/html/wm/vendor/horde/activesync/lib/Horde/ActiveSync/Imap/EasMessageBuilder.php(514): Horde_ActiveSync_Imap_Message->getMessageBodyDataObject()
> 2025-05-16T09:09:34.737747+02:00 wm HORDE: #6 /var/www/html/wm/vendor/horde/activesync/lib/Horde/ActiveSync/Imap/Adapter.php(811): Horde_ActiveSync_Imap_EasMessageBuilder::create()
> 2025-05-16T09:09:34.738027+02:00 wm HORDE: #7 /var/www/html/wm/vendor/horde/activesync/lib/Horde/ActiveSync/Imap/Adapter.php(423): Horde_ActiveSync_Imap_Adapter->_buildMailMessage()
> 2025-05-16T09:09:34.738305+02:00 wm HORDE: #8 /var/www/html/wm/vendor/horde/core/lib/Horde/Core/ActiveSync/Driver.php(1515): Horde_ActiveSync_Imap_Adapter->getMessages()
> 2025-05-16T09:09:34.738588+02:00 wm HORDE: #9 /var/www/html/wm/vendor/horde/activesync/lib/Horde/ActiveSync/Connector/Exporter/Sync.php(480): Horde_Core_ActiveSync_Driver->getMessage()
> 2025-05-16T09:09:34.738922+02:00 wm HORDE: #10 /var/www/html/wm/vendor/horde/activesync/lib/Horde/ActiveSync/Connector/Exporter/Sync.php(62): Horde_ActiveSync_Connector_Exporter_Sync->_sendNextChange()
> 2025-05-16T09:09:34.739201+02:00 wm HORDE: #11 /var/www/html/wm/vendor/horde/activesync/lib/Horde/ActiveSync/Request/Sync.php(461): Horde_ActiveSync_Connector_Exporter_Sync->sendNextChange()
> 2025-05-16T09:09:34.739479+02:00 wm HORDE: #12 /var/www/html/wm/vendor/horde/activesync/lib/Horde/ActiveSync/Request/Base.php(250): Horde_ActiveSync_Request_Sync->_handle()
> 2025-05-16T09:09:34.739801+02:00 wm HORDE: #13 /var/www/html/wm/vendor/horde/activesync/lib/Horde/ActiveSync.php(833): Horde_ActiveSync_Request_Base->handle()
> 2025-05-16T09:09:34.740083+02:00 wm HORDE: #14 /var/www/html/wm/vendor/horde/rpc/lib/Horde/Rpc/ActiveSync.php(144): Horde_ActiveSync->handleRequest()
> 2025-05-16T09:09:34.740360+02:00 wm HORDE: #15 /var/www/html/wm/vendor/horde/horde/rpc.php(165): Horde_Rpc_ActiveSync->getResponse()
> 2025-05-16T09:09:34.740639+02:00 wm HORDE: #16 {main} [pid 564 on line 76 of "/var/www/html/wm/vendor/horde/core/lib/Horde/ErrorHandler.php"]
> 2025-05-16T09:09:35.532481+02:00 wm HORDE: [imp] Login success for andreas (10.0.25.8) (forwarded for [80.152.143.17]) to {imap://10.0.25.2/} [pid 566 on line 145 of "/var/www/html/wm/vendor/horde/imp/lib/Auth.php"]
> 2025-05-16T09:09:35.565086+02:00 wm HORDE: ValueError: mb_substr(): Argument #4 ($encoding) must be a valid encoding, "windows-1256" given in /var/www/html/wm/vendor/horde/util/lib/Horde/String.php:329
> 2025-05-16T09:09:35.565890+02:00 wm HORDE: Stack trace:
> 2025-05-16T09:09:35.566352+02:00 wm HORDE: #0 /var/www/html/wm/vendor/horde/util/lib/Horde/String.php(329): mb_substr()
> 2025-05-16T09:09:35.566956+02:00 wm HORDE: #1 /var/www/html/wm/vendor/horde/activesync/lib/Horde/ActiveSync/Imap/MessageBodyData.php(453): Horde_String::substr()
> 2025-05-16T09:09:35.567631+02:00 wm HORDE: #2 /var/www/html/wm/vendor/horde/activesync/lib/Horde/ActiveSync/Imap/MessageBodyData.php(302): Horde_ActiveSync_Imap_MessageBodyData->_getPlainPart()
> 2025-05-16T09:09:35.567988+02:00 wm HORDE: #3 /var/www/html/wm/vendor/horde/activesync/lib/Horde/ActiveSync/Imap/MessageBodyData.php(145): Horde_ActiveSync_Imap_MessageBodyData->_getParts()
> 2025-05-16T09:09:35.568248+02:00 wm HORDE: #4 /var/www/html/wm/vendor/horde/activesync/lib/Horde/ActiveSync/Imap/Message.php(303): Horde_ActiveSync_Imap_MessageBodyData->__construct()
> 2025-05-16T09:09:35.568524+02:00 wm HORDE: #5 /var/www/html/wm/vendor/horde/activesync/lib/Horde/ActiveSync/Imap/EasMessageBuilder.php(514): Horde_ActiveSync_Imap_Message->getMessageBodyDataObject()
> 2025-05-16T09:09:35.568827+02:00 wm HORDE: #6 /var/www/html/wm/vendor/horde/activesync/lib/Horde/ActiveSync/Imap/Adapter.php(811): Horde_ActiveSync_Imap_EasMessageBuilder::create()
> 2025-05-16T09:09:35.569089+02:00 wm HORDE: #7 /var/www/html/wm/vendor/horde/activesync/lib/Horde/ActiveSync/Imap/Adapter.php(423): Horde_ActiveSync_Imap_Adapter->_buildMailMessage()
> 2025-05-16T09:09:35.569304+02:00 wm HORDE: #8 /var/www/html/wm/vendor/horde/core/lib/Horde/Core/ActiveSync/Driver.php(1515): Horde_ActiveSync_Imap_Adapter->getMessages()
> 2025-05-16T09:09:35.569555+02:00 wm HORDE: #9 /var/www/html/wm/vendor/horde/activesync/lib/Horde/ActiveSync/Connector/Exporter/Sync.php(480): Horde_Core_ActiveSync_Driver->getMessage()
> 2025-05-16T09:09:35.569760+02:00 wm HORDE: #10 /var/www/html/wm/vendor/horde/activesync/lib/Horde/ActiveSync/Connector/Exporter/Sync.php(62): Horde_ActiveSync_Connector_Exporter_Sync->_sendNextChange()
> 2025-05-16T09:09:35.570013+02:00 wm HORDE: #11 /var/www/html/wm/vendor/horde/activesync/lib/Horde/ActiveSync/Request/Sync.php(461): Horde_ActiveSync_Connector_Exporter_Sync->sendNextChange()
> 2025-05-16T09:09:35.570264+02:00 wm HORDE: #12 /var/www/html/wm/vendor/horde/activesync/lib/Horde/ActiveSync/Request/Base.php(250): Horde_ActiveSync_Request_Sync->_handle()
> 2025-05-16T09:09:35.570472+02:00 wm HORDE: #13 /var/www/html/wm/vendor/horde/activesync/lib/Horde/ActiveSync.php(833): Horde_ActiveSync_Request_Base->handle()
> 2025-05-16T09:09:35.570705+02:00 wm HORDE: #14 /var/www/html/wm/vendor/horde/rpc/lib/Horde/Rpc/ActiveSync.php(144): Horde_ActiveSync->handleRequest()
> 2025-05-16T09:09:35.570964+02:00 wm HORDE: #15 /var/www/html/wm/vendor/horde/horde/rpc.php(165): Horde_Rpc_ActiveSync->getResponse()
> 2025-05-16T09:09:35.571214+02:00 wm HORDE: #16 {main} [pid 566 on line 76 of "/var/www/html/wm/vendor/horde/core/lib/Horde/ErrorHandler.php"]
>
>
>
>
>> also helpful: composer.json and composer.log file, horde test page content
> Here is what I have in the webmail root:
>
> /var/www/html/wm# ls
> composer.json  composer.lock  presets  README.md  var  vendor  web
>
> Here is the composer.json:
>
> /var/www/html/wm# cat composer.json
> {
>      "name": "horde/bundle",
>      "description": "A base project for a horde installation",
>      "type": "project",
>      "require": {
>          "composer-plugin-api": "^2.0",
>          "horde/horde-installer-plugin": "^2.5.1 || dev-FRAMEWORK_6_0 || dev-master",
>          "horde/horde": "^6 || dev-FRAMEWORK_6_0",
>          "horde/routes": "^3 || dev-FRAMEWORK_6_0",
>          "horde/hordectl": "^1 || dev-FRAMEWORK_6_0 || dev-master",
>          "pear/console_color2": "^0.1.2",
>          "pear/console_table": "^1.3",
>          "horde/kronolith": "^5",
>          "horde/nag": "^5",
>          "horde/content": "^3",
>          "horde/timeobjects": "^3",
>          "horde/mnemo": "^5",
>          "horde/turba": "^5",
>          "horde/passwd": "^6.0 at alpha",
>          "horde/imp": "^7",
>          "horde/ingo": "^4",
>          "horde/activesync": "^3",
>          "horde/memcache": "^3.0 at alpha"
>      },
>      "require-dev": {
>          "horde/test": "^3 || dev-FRAMEWORK_6_0 || dev-master"
>      },
>      "license": "GPL-3.0-only",
>      "authors": [
>          {
>              "name": "Ralf Lang",
>              "email":"ralf.lang at ralf-lang.de"
>          }
>      ],
>      "minimum-stability": "dev",
>      "prefer-stable": true,
>      "extra": {
>          "installer-types": [
>              "horde-library",
>              "horde-application"
>          ]
>      },
>      "config": {
>          "allow-plugins": {
>              "horde/horde-installer-plugin": true
>          }
>      }
> }

Much appreciated.

I should probably build a helper for fetching this kind of report from 
the users.

Regards


Ralf


More information about the horde mailing list