[Kolab-devel] [issue3966] All day event with "not booked" not correctly processed

Saim Kim issues at kolab.org
Wed Nov 25 16:02:57 CET 2009

Previous message: [Kolab-devel] [issue3965] Always accept: Invitationmails get lost when Calendarfolder ist not writable for the calendar user
Next message: [Kolab-devel] [issue3967] UNTRUSTED vs. UNAUTHENTICATED, distinction considered harmful
Messages sorted by: [ date ] [ thread ] [ subject ] [ author ]

New submission from Saim Kim <s.kim at hia.rwth-aachen.de>:

Hello,

we have recently switched over to kolab 2.2.2 (openpkg) on a 64bit Xeon Quad
core machine. We are experiencing problems with all day events where the user is
not booked aka free, e.g. for birthday events. We have tested both
Outlook/Toltec (2.3.1) and the Horde interface (Dynamic and Traditional) and the
issus is reproducible. 

An allday event with attribute "free/unbooked" is created and an invitation is
send to a ressource (automatic handling is activated). The kolab-filter.log
shows the following error:

Nov 25 14:52:34 Kolab Filter [debug] [horde] Arguments: Horde_Argv_Values Object
(
    [sender] => testuser at test.domain
    [recipient] => Array
        (
            [0] => kalender_medit_wma at test.domain
        )

    [host] => kolab.test.domain
    [client] => 127.0.0.1
    [user] => testuser at test.domain
    [config] => /kolab/etc/kolab/kolabfilter.conf
)
 [on line 244 of "/kolab/lib/php/Horde/Kolab/Filter/Base.php"]
Nov 25 14:52:34 Kolab Filter [info] [horde] Horde_Kolab_Filter_Content
successfully completed (sender=testuser at test.domain,
recipients=kalender_medit_wma at test.domain, client_address=127.0.0.1,
id=<20091125145234.12765lg14rzq2t9e at kolab.test.domain>) [on line 141 of
"/kolab/lib/php/Horde/Kolab/Filter/Base.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Arguments: Horde_Argv_Values Object
(
    [sender] => testuser at test.domain
    [recipient] => Array
        (
            [0] => kalender_medit_wma at test.domain
        )

    [host] => kolab.test.domain
    [client] => 127.0.0.1
    [user] =>
    [config] => /kolab/etc/kolab/kolabfilter.conf
)
 [on line 244 of "/kolab/lib/php/Horde/Kolab/Filter/Base.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Calling
resmgr_filter(kolab.test.domain, testuser at test.domain,
kalender_medit_wma at test.domain, /tmp/IN.Horde_Kolab_Filter_Incoming.OFYHSG) [on
line 144 of "/kolab/lib/php/Horde/Kolab/Filter/Incoming.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Action for testuser at test.domain is
ACT_ALWAYS_ACCEPT [on line 355 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Processing REQUEST method for
kalender_medit_wma at test.domain [on line 387 of
"/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Event has UID
95e59a3d3bfac0d7b686f59865622ecc [on line 392 of
"/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Request made by
testuser at test.domain [on line 397 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Converting to epoch Array
(
    [year] => 2009
    [month] => 11
    [mday] => 27
)
 [on line 1074 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Converted <1259280000> [on line
1085 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Converting to epoch Array
(
    [year] => 2009
    [month] => 11
    [mday] => 28
)
 [on line 1074 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Converted <1259366400> [on line
1085 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Converting to kolab format
1259280000 [on line 1034 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] To <2009-11-27T00:00:00Z> [on line
1059 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Converting to kolab format
1259366400 [on line 1034 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] To <2009-11-28T00:00:00Z> [on line
1059 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Event starts on <1259280000>
2009-11-27T00:00:00Z and ends on <1259366400> 2009-11-28T00:00:00Z. [on line 407
of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Converting to epoch Array
(
    [year] => 2009
    [month] => 11
    [mday] => 27
)
 [on line 1074 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Converted <1259280000> [on line
1085 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Converting to epoch Array
(
    [year] => 2009
    [month] => 11
    [mday] => 28
)
 [on line 1074 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Converted <1259366400> [on line
1085 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Assembled event object: Array
(
    [uid] => 95e59a3d3bfac0d7b686f59865622ecc
    [organizer] => Array
        (
            [display-name] => Saim Kim
            [smtp-address] => testuser at test.domain
        )

    [summary] => Test ganztagstermin
    [location] =>
    [body] =>
    [_is_all_day] => 1
    [start-date] => 1259280000
    [end-date] => 1259366400
    [show-time-as] => Array
        (
            [year] => 2009
            [month] => 11
            [mday] => 28
        )

    [attendee] => Array
        (
            [0] => Array
                (
                    [display-name] => Kalender Medit Wma
                    [smtp-address] => kalender_medit_wma at test.domain
                    [request-response] => 1
                    [role] => REQ-PARTICIPANT
                )

        )

)
 [on line 325 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [info] [horde] Adding event
95e59a3d3bfac0d7b686f59865622ecc [on line 568 of
"/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [error] [horde] PHP Error:
DOMDocument::createTextNode() expects parameter 1 to be string, array given;
Code: 0 [on line 146 of "/kolab/lib/php/Horde/Kolab/Filter/Response.php"]
Nov 25 14:52:35 Kolab Filter [error] [horde] PHP Error: Argument 1 passed to
DOMNode::appendChild() must be an instance of DOMNode, null given, called in
/kolab/lib/php/Horde/Kolab/Format/XML.php on line 798 and defined; Code: 0 [on
line 146 of "/kolab/lib/php/Horde/Kolab/Filter/Response.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Arguments: Horde_Argv_Values Object
(
    [sender] =>
    [recipient] => Array  
       (
            [0] => testuser at test.domain
        )

    [host] => kolab.test.domain
    [client] =>
    [user] =>
    [config] => /kolab/etc/kolab/kolabfilter.conf
)
 [on line 244 of "/kolab/lib/php/Horde/Kolab/Filter/Base.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Calling
resmgr_filter(kolab.test.domain, , testuser at test.domain,
/tmp/IN.Horde_Kolab_Filter_Incoming.0CIeD9) [on line 144 of
"/kolab/lib/php/Horde/Kolab/Filter/Incoming.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Action for  is ACT_MANUAL [on line
355 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [info] [horde] Passing through message to
testuser at test.domain [on line 360 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Filter_Incoming successfully
completed. [on line 174 of "/kolab/lib/php/Horde/Kolab/Filter/Incoming.php"]
Nov 25 14:52:35 Kolab Filter [info] [horde] Horde_Kolab_Filter_Incoming
successfully completed (sender=, recipients=testuser at test.domain,
client_address=, id=<20091125135235.78CF4F69C1 at kolab.test.domain>) [on line 141
of "/kolab/lib/php/Horde/Kolab/Filter/Base.php"]



Also an error email is send to the user:

Reporting-MTA: dns; kolab.test.domain
X-Postfix-Queue-ID: A23B0F69B5
X-Postfix-Sender: rfc822; testuser at test.domain
Arrival-Date: Wed, 25 Nov 2009 15:44:40 +0100 (CET)

Final-Recipient: rfc822; kalender_medit_wma at test.domain
Original-Recipient: rfc822;kalender_medit_wma at test.domain
Action: failed
Status: 5.3.0
Diagnostic-Code: x-unix; unknown mail system error 255



The php error seems to react on the line:
[show-time-as] => Array

Nov 25 14:52:35 Kolab Filter [error] [horde] PHP Error:
DOMDocument::createTextNode() expects parameter 1 to be string, array given;
Code: 0 [on line 146 of "/kolab/lib/php/Horde/Kolab/Filter/Response.php"]
Nov 25 14:52:35 Kolab Filter [error] [horde] PHP Error: Argument 1 passed to
DOMNode::appendChild() must be an instance of DOMNode, null given, called in
/kolab/lib/php/Horde/Kolab/Format/XML.php on line 798 and defined; Code: 0 [on
line 146 of "/kolab/lib/php/Horde/Kolab/Filter/Response.php"]

Is that a known bug?

----------
files: Kolab_whole_day_event_free_bug.txt
messages: 22544
nosy: sojakim
priority: bug
status: unread
title: All day event with "not booked" not correctly processed

______________________________________
Kolab issue tracker <issues at kolab.org>
<https://issues.kolab.org/issue3966>
______________________________________
-------------- next part --------------
Nov 25 14:52:34 Kolab Filter [debug] [horde] Arguments: Horde_Argv_Values Object
(
    [sender] => s.kim at medit.intern
    [recipient] => Array
        (
            [0] => kalender_medit_wma at medit.intern
        )

    [host] => kolab.medit.intern
    [client] => 127.0.0.1
    [user] => s.kim at medit.intern
    [config] => /kolab/etc/kolab/kolabfilter.conf
)
 [on line 244 of "/kolab/lib/php/Horde/Kolab/Filter/Base.php"]
Nov 25 14:52:34 Kolab Filter [info] [horde] Horde_Kolab_Filter_Content successfully completed (sender=s.kim at medit.intern, recipients=kalender_medit_wma at medit.intern, client_address=127.0.0.1, id=<20091125145234.12765lg14rzq2t9e at kolab.medit.intern>) [on line 141 of "/kolab/lib/php/Horde/Kolab/Filter/Base.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Arguments: Horde_Argv_Values Object
(
    [sender] => s.kim at medit.intern
    [recipient] => Array
        (
            [0] => kalender_medit_wma at medit.intern
        )

    [host] => kolab.medit.intern
    [client] => 127.0.0.1
    [user] =>
    [config] => /kolab/etc/kolab/kolabfilter.conf
)
 [on line 244 of "/kolab/lib/php/Horde/Kolab/Filter/Base.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Calling resmgr_filter(kolab.medit.intern, s.kim at medit.intern, kalender_medit_wma at medit.intern, /tmp/IN.Horde_Kolab_Filter_Incoming.OFYHSG) [on line 144 of "/kolab/lib/php/Horde/Kolab/Filter/Incoming.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Action for s.kim at medit.intern is ACT_ALWAYS_ACCEPT [on line 355 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Processing REQUEST method for kalender_medit_wma at medit.intern [on line 387 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Event has UID 95e59a3d3bfac0d7b686f59865622ecc [on line 392 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Request made by s.kim at medit.intern [on line 397 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Converting to epoch Array
(
    [year] => 2009
    [month] => 11
    [mday] => 27
)
 [on line 1074 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Converted <1259280000> [on line 1085 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Converting to epoch Array
(
    [year] => 2009
    [month] => 11
    [mday] => 28
)
 [on line 1074 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Converted <1259366400> [on line 1085 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Converting to kolab format 1259280000 [on line 1034 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] To <2009-11-27T00:00:00Z> [on line 1059 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Converting to kolab format 1259366400 [on line 1034 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] To <2009-11-28T00:00:00Z> [on line 1059 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Event starts on <1259280000> 2009-11-27T00:00:00Z and ends on <1259366400> 2009-11-28T00:00:00Z. [on line 407 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Converting to epoch Array
(
    [year] => 2009
    [month] => 11
    [mday] => 27
)
 [on line 1074 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Converted <1259280000> [on line 1085 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Converting to epoch Array
(
    [year] => 2009
    [month] => 11
    [mday] => 28
)
 [on line 1074 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Converted <1259366400> [on line 1085 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Assembled event object: Array
(
    [uid] => 95e59a3d3bfac0d7b686f59865622ecc
    [organizer] => Array
        (
            [display-name] => Saim Kim
            [smtp-address] => s.kim at medit.intern
        )

    [summary] => Test ganztagstermin
    [location] =>
    [body] =>
    [_is_all_day] => 1
    [start-date] => 1259280000
    [end-date] => 1259366400
    [show-time-as] => Array
        (
            [year] => 2009
            [month] => 11
            [mday] => 28
        )

    [attendee] => Array
        (
            [0] => Array
                (
                    [display-name] => Kalender Medit Wma
                    [smtp-address] => kalender_medit_wma at medit.intern
                    [request-response] => 1
                    [role] => REQ-PARTICIPANT
                )

        )

)
 [on line 325 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [info] [horde] Adding event 95e59a3d3bfac0d7b686f59865622ecc [on line 568 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [error] [horde] PHP Error: DOMDocument::createTextNode() expects parameter 1 to be string, array given; Code: 0 [on line 146 of "/kolab/lib/php/Horde/Kolab/Filter/Response.php"]
Nov 25 14:52:35 Kolab Filter [error] [horde] PHP Error: Argument 1 passed to DOMNode::appendChild() must be an instance of DOMNode, null given, called in /kolab/lib/php/Horde/Kolab/Format/XML.php on line 798 and defined; Code: 0 [on line 146 of "/kolab/lib/php/Horde/Kolab/Filter/Response.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Arguments: Horde_Argv_Values Object
(
    [sender] =>
    [recipient] => Array  
       (
            [0] => s.kim at medit.intern
        )

    [host] => kolab.medit.intern
    [client] =>
    [user] =>
    [config] => /kolab/etc/kolab/kolabfilter.conf
)
 [on line 244 of "/kolab/lib/php/Horde/Kolab/Filter/Base.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Calling resmgr_filter(kolab.medit.intern, , s.kim at medit.intern, /tmp/IN.Horde_Kolab_Filter_Incoming.0CIeD9) [on line 144 of "/kolab/lib/php/Horde/Kolab/Filter/Incoming.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Action for  is ACT_MANUAL [on line 355 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [info] [horde] Passing through message to s.kim at medit.intern [on line 360 of "/kolab/lib/php/Horde/Kolab/Resource.php"]
Nov 25 14:52:35 Kolab Filter [debug] [horde] Filter_Incoming successfully completed. [on line 174 of "/kolab/lib/php/Horde/Kolab/Filter/Incoming.php"]
Nov 25 14:52:35 Kolab Filter [info] [horde] Horde_Kolab_Filter_Incoming successfully completed (sender=, recipients=s.kim at medit.intern, client_address=, id=<20091125135235.78CF4F69C1 at kolab.medit.intern>) [on line 141 of "/kolab/lib/php/Horde/Kolab/Filter/Base.php"]

Previous message: [Kolab-devel] [issue3965] Always accept: Invitationmails get lost when Calendarfolder ist not writable for the calendar user
Next message: [Kolab-devel] [issue3967] UNTRUSTED vs. UNAUTHENTICATED, distinction considered harmful
Messages sorted by: [ date ] [ thread ] [ subject ] [ author ]

More information about the devel mailing list