cyrusimap / cyrus-imapd

Cyrus IMAP is an email, contacts and calendar server
http://cyrusimap.org
Other
537 stars 147 forks source link

Three new cassandane failures with imaptest-20170719 #2087

Open jasontibbitts opened 7 years ago

jasontibbitts commented 7 years ago

Fedora very recently updated imaptest from version 20151228 to 20170719. This has resulted in three new cassandane failures: Cyrus::ImapTest.append-binary, Cyrus::ImapTest.fetch-binary-mime and Cyrus::ImapTest.urlauth-binary. This is not related to any changes in cyrus 3.0.3 as it also happens with 3.0.2. In the interests of getting the 3.0.3 fixes out I will exclude these tests but it would be good to know what's going on under the hood.

Note that this is with a cassandane snapshot taken on June 22. If update to a current cassandane snapshot, those three tests still fail but as expected there are a pile of other failures as well.

Time: 93 wallclock secs ( 0.53 usr  0.07 sys + 140.53 cusr 125.29 csys = 266.42 CPU)

!!!FAILURES!!!
Test Results:
Run: 660, Failures: 3, Errors: 0

There were 3 failures:
1) test_append-binary(Cassandane::Cyrus::ImapTest)
 Boolean assertion failed at /usr/share/perl5/vendor_perl/Test/Unit/Exception.pm line 13.
        Test::Unit::Exception::throw_new(Test::Unit::Failure=HASH(0x56365c9fb678), "-package", "Cassandane::Cyrus::ImapTest", "-file", "Cassandane/Cyrus/ImapTest.pm", "-line", 186, "-object", ...) called at /usr/share/perl5/vendor_perl/Test/Unit/Assert.pm line 85
        Test::Unit::Assert::do_assertion(Cassandane::Cyrus::ImapTest=HASH(0x56365ad57488), Test::Unit::Assertion::Boolean=SCALAR(0x56365c9f0718), "Cassandane::Cyrus::ImapTest", "Cassandane/Cyrus/ImapTest.pm", 186) called at /usr/share/perl5/vendor_perl/Test/Unit/Assert.pm line 19
        Test::Unit::Assert::assert(Cassandane::Cyrus::ImapTest=HASH(0x56365ad57488), 0) called at Cassandane/Cyrus/ImapTest.pm line 186
        Cassandane::Cyrus::ImapTest::run_test(Cassandane::Cyrus::ImapTest=HASH(0x56365ad57488)) called at /usr/share/perl5/vendor_perl/Test/Unit/TestCase.pm line 61
        Test::Unit::TestCase::run_bare(Cassandane::Cyrus::ImapTest=HASH(0x56365ad57488)) called at /usr/share/perl5/vendor_perl/Test/Unit/Result.pm line 103
        Test::Unit::Result::__ANON__() called at /usr/share/perl5/vendor_perl/Test/Unit/Result.pm line 119
        Test::Unit::Result::run_protected(Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Cyrus::ImapTest=HASH(0x56365ad57488), CODE(0x56365c9e8ae0)) called at /usr/share/perl5/vendor_perl/Test/Unit/Result.pm line 107
        Test::Unit::Result::run(Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Cyrus::ImapTest=HASH(0x56365ad57488)) called at /usr/share/perl5/vendor_perl/Test/Unit/TestCase.pm line 51
        Test::Unit::TestCase::run(Cassandane::Cyrus::ImapTest=HASH(0x56365ad57488), Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Unit::RunnerPretty=HASH(0x5636594ec730)) called at /usr/share/perl5/vendor_perl/Test/Unit/TestSuite.pm line 278
        Test::Unit::TestSuite::run(Test::Unit::TestSuite=HASH(0x56365ad1a978), Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Unit::RunnerPretty=HASH(0x5636594ec730)) called at Cassandane/Unit/TestPlan.pm line 749
        Cassandane::Unit::TestPlan::_run_workitem(Cassandane::Unit::TestPlan=HASH(0x563656c05c50), HASH(0x56365c6e5760), Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Unit::RunnerPretty=HASH(0x5636594ec730), 0) called at Cassandane/Unit/TestPlan.pm line 865
        Cassandane::Unit::TestPlan::__ANON__(HASH(0x56365c6e5760)) called at Cassandane/Unit/TestPlan.pm line 207
        Cassandane::Unit::Worker::_mainloop(Cassandane::Unit::Worker=HASH(0x56365c0f1c00)) called at Cassandane/Unit/TestPlan.pm line 167
        Cassandane::Unit::Worker::start(Cassandane::Unit::Worker=HASH(0x56365c0f1c00)) called at Cassandane/Unit/TestPlan.pm line 312
        Cassandane::Unit::WorkerPool::start(Cassandane::Unit::WorkerPool=HASH(0x5636594f3fa8)) called at Cassandane/Unit/TestPlan.pm line 869
        Cassandane::Unit::TestPlan::run(Cassandane::Unit::TestPlan=HASH(0x563656c05c50), Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Unit::RunnerPretty=HASH(0x5636594ec730)) called at /usr/share/perl5/vendor_perl/Test/Unit/TestRunner.pm line 54
        Test::Unit::TestRunner::do_run(Cassandane::Unit::RunnerPretty=HASH(0x5636594ec730), Cassandane::Unit::TestPlan=HASH(0x563656c05c50), 0) called at ./testrunner.pl line 126
        main::__ANON__(Cassandane::Unit::TestPlan=HASH(0x563656c05c50), GLOB(0x563656c05c80)) called at ./testrunner.pl line 300

Annotations:
=====> Cyrus::TestCase[567] ---------- BEGIN test_append-binary ----------
=====> Instance[1004] start main instance for test test_append-binary: basedir /builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane/work/160321H18
=====> Instance[455] Found binary cyr_buildinfo in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3-1.fc27.x86_64/usr/sbin
=====> Instance[455] Found binary cyr_buildinfo in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3-1.fc27.x86_64/usr/sbin
=====> Instance[1505] Running: "/builddir/build/BUILDROOT/cyrus-imapd-3.0.3-1.fc27.x86_64/usr/sbin/cyr_buildinfo" "-C" "/builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane/work/160321H18/conf/imapd.conf"
=====> Instance[455] Found binary ctl_cyrusdb in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3-1.fc27.x86_64/usr/sbin
=====> Instance[455] Found binary imapd in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3-1.fc27.x86_64/usr/libexec/cyrus-imapd
=====> Instance[916] started saslauthd for /builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane/work/160321H18 as 16752
=====> Instance[1759] opening socket /builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane/work/160321H18/run/mux
=====> Instance[943] started notifyd for /builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane/work/160321H18 as 16753
=====> Instance[455] Found binary master in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3-1.fc27.x86_64/usr/libexec/cyrus-imapd
=====> Instance[1505] Running: "/builddir/build/BUILDROOT/cyrus-imapd-3.0.3-1.fc27.x86_64/usr/libexec/cyrus-imapd/master" "-C" "/builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane/work/160321H18/conf/imapd.conf" "-l" "255" "-p" "/builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane/work/160321H18/run/master.pid" "-d" "-M" "/builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane/work/160321H18/conf/cyrus.conf"
=====> Instance[868] _start_master: waiting for PID file
=====> Instance[871] _start_master: PID file present and correct
=====> Instance[883] _start_master: PID waiting for services
=====> Daemon[358] is_listening: service imap is listening on 127.0.0.1:9173
=====> Util::Wait[77] Waited 0.034991 sec for 127.0.0.1:9173 to be in LISTEN state
=====> Instance[894] _start_master: all services listening
=====> Instance[970] create user cassandane
=====> Util::Socket[63] create_client_socket INET host=127.0.0.1 port=9173
=====> Instance[1774] authdaemon connection: admin testpw imap 
=====> Instance[1035] started main instance for test test_append-binary: cyrus version 3.0.3-1.fc27 Fedora
=====> Cyrus::TestCase[574] Calling test function
=====> Instance[1505] Running: "/builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane-6d33978ebb0055be41a5cb8e52958e100401bf26/imaptest/src/imaptest" "host=127.0.0.1" "port=9173" "user=cassandane" "pass=testpw" "rawlog" "test=/builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane-6d33978ebb0055be41a5cb8e52958e100401bf26/imaptest/src/tests/append-binary"
=====> Instance[1774] authdaemon connection: cassandane testpw imap 
=====> Cyrus::ImapTest[170] Error: cassandane[1]: BAD reply for command: fetch 1 (binary.size[text] binary.peek[text]): 1.8 BAD Invalid binary section

=====> Cyrus::ImapTest[170] *** Test append-binary command 4/5 (line 35)

=====> Cyrus::ImapTest[170]  - failed: Expected tagged reply 'ok', got 'BAD Invalid binary section'

=====> Cyrus::ImapTest[170]  - Command (tag 1.8): fetch 1 (binary.size[text] binary.peek[text])

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] *** Test append-binary command 4/5 (line 35)

=====> Cyrus::ImapTest[170]  - failed: Missing 1 untagged replies (0 mismatches)

=====> Cyrus::ImapTest[170]  - first unexpanded: 1 fetch ($!unordered=2 binary.size[text] 7 binary[text] {0}

=====> Cyrus::ImapTest[170] )

=====> Cyrus::ImapTest[170]  - first expanded: 1 fetch ( binary.size[text] 7 binary[text] {0}

=====> Cyrus::ImapTest[170] )

=====> Cyrus::ImapTest[170]  - best match: 1 FETCH (BODY[TEXT] "AC3kCg0KCg==

=====> Cyrus::ImapTest[170] ")

=====> Cyrus::ImapTest[170]  - Command: fetch 1 (binary.size[text] binary.peek[text])

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] *** Test append-binary command 5/5 (line 0)

=====> Cyrus::ImapTest[170]  - failed: Unexpected disconnection

=====> Cyrus::ImapTest[170]  - Command: logout

=====> Cyrus::ImapTest[170] 

=====> Cyrus::TestCase[681] Beginning tear_down
=====> Instance[1242] stop
=====> Instance[1204] _stop_pid: sending signal 15 to 16757
=====> Util::Wait[77] Waited 0.010088 sec for unknown condition
=====> Instance[919] killing saslauthd 16752
Warning: unable to close filehandle $oldout properly: Bad file descriptor at Cassandane/Instance.pm line 913.
Warning: unable to close filehandle $olderr properly: Bad file descriptor at Cassandane/Instance.pm line 913.
=====> Instance[946] killing notifyd 16753
Warning: unable to close filehandle $oldout properly: Bad file descriptor at Cassandane/Instance.pm line 940.
Warning: unable to close filehandle $olderr properly: Bad file descriptor at Cassandane/Instance.pm line 940.
=====> Instance[919] killing saslauthd 16752
=====> Instance[1270] Cleaning up basedir /builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane/work/160321H18
=====> Cyrus::TestCase[726] ---------- END test_append-binary ----------
=====> Daemon[387] checking for stray processes on ports: 9173
============> rawlog.1 <=============
1502467414.077941 I: * OK [CAPABILITY IMAP4rev1 LITERAL+ ID ENABLE AUTH=PLAIN AUTH=LOGIN SASL-IR] 127.0.0.1 Cyrus IMAP 3.0.3-1.fc27 Fedora server ready
1502467414.077962 O: 1.1 LOGIN "cassandane" "testpw"
1502467414.078357 I: 1.1 OK [CAPABILITY IMAP4rev1 LITERAL+ ID ENABLE ACL RIGHTS=kxten QUOTA MAILBOX-REFERRALS NAMESPACE UIDPLUS NO_ATOMIC_RENAME UNSELECT CHILDREN MULTIAPPEND BINARY CATENATE CONDSTORE ESEARCH SEARCH=FUZZY SORT SORT=MODSEQ SORT=DISPLAY SORT=UID THREAD=ORDEREDSUBJECT THREAD=REFERENCES THREAD=REFS ANNOTATEMORE ANNOTATE-EXPERIMENT-1 METADATA LIST-EXTENDED LIST-STATUS LIST-MYRIGHTS LIST-METADATA WITHIN QRESYNC SCAN XLIST XMOVE MOVE SPECIAL-USE CREATE-SPECIAL-USE DIGEST=SHA1 X-REPLICATION URLAUTH URLAUTH=BINARY LOGINDISABLED COMPRESS=DEFLATE X-QUOTA=STORAGE X-QUOTA=MESSAGE X-QUOTA=X-ANNOTATION-STORAGE X-QUOTA=X-NUM-FOLDERS IDLE] User logged in SESSIONID=<160321H18-16763-1502467414-2-1533617781188931385>
1502467414.078394 O: 1.2 LIST "" "imaptest*"
1502467414.078397 O: 1.3 LSUB "" "imaptest*"
1502467414.078577 I: 1.2 OK Completed (0.000 secs)
1502467414.118833 I: 1.3 OK Completed (0.000 secs)
1502467414.118869 O: 1.4 CREATE "imaptest"
1502467414.120058 I: 1.4 OK Completed
1502467414.120094 O: 1.5 append imaptest ~{165+}
1502467414.120094 O: From: user@domain.org
1502467414.120094 O: Date: Sat, 24 Mar 2007 23:00:00 +0200
1502467414.120094 O: Mime-Version: 1.0
1502467414.120094 O: Content-Type: application/octet-stream
1502467414.120094 O: Content-Transfer-Encoding: binary
1502467414.120094 O: 
1502467414.120094 O: -�
1502467414.120094 O: 
1502467414.120094 O: 
1502467414.120102 O: 
1502467414.121009 I: 1.5 OK [APPENDUID 1502467415 1] Completed
1502467414.121035 O: 1.6 select imaptest
1502467414.121242 I: * 1 EXISTS
1502467414.121242 I: * 1 RECENT
1502467414.121242 I: * FLAGS (\Answered \Flagged \Draft \Deleted \Seen)
1502467414.121242 I: * OK [PERMANENTFLAGS (\Answered \Flagged \Draft \Deleted \Seen \*)] Ok
1502467414.121242 I: * OK [UNSEEN 1] Ok
1502467414.121242 I: * OK [UIDVALIDITY 1502467415] Ok
1502467414.121242 I: * OK [UIDNEXT 2] Ok
1502467414.121242 I: * OK [HIGHESTMODSEQ 2] Ok
1502467414.121242 I: * OK [URLMECH INTERNAL] Ok
1502467414.121242 I: * OK [ANNOTATIONS 65536] Ok
1502467414.121242 I: 1.6 OK [READ-WRITE] Completed
1502467414.121277 O: 1.7 fetch 1 (body.peek[text])
1502467414.121465 I: * 1 FETCH (BODY[TEXT] {14}
1502467414.121465 I: AC3kCg0KCg==
1502467414.121465 I: )
1502467414.121465 I: 1.7 OK Completed (0.000 sec)
1502467414.121489 O: 1.8 fetch 1 (binary.size[text] binary.peek[text])
1502467414.121569 I: 1.8 BAD Invalid binary section
2) test_fetch-binary-mime(Cassandane::Cyrus::ImapTest)
 Boolean assertion failed at /usr/share/perl5/vendor_perl/Test/Unit/Exception.pm line 13.
        Test::Unit::Exception::throw_new(Test::Unit::Failure=HASH(0x56365c7e5578), "-package", "Cassandane::Cyrus::ImapTest", "-file", "Cassandane/Cyrus/ImapTest.pm", "-line", 186, "-object", ...) called at /usr/share/perl5/vendor_perl/Test/Unit/Assert.pm line 85
        Test::Unit::Assert::do_assertion(Cassandane::Cyrus::ImapTest=HASH(0x56365ad50c08), Test::Unit::Assertion::Boolean=SCALAR(0x56365c7cb0f0), "Cassandane::Cyrus::ImapTest", "Cassandane/Cyrus/ImapTest.pm", 186) called at /usr/share/perl5/vendor_perl/Test/Unit/Assert.pm line 19
        Test::Unit::Assert::assert(Cassandane::Cyrus::ImapTest=HASH(0x56365ad50c08), 0) called at Cassandane/Cyrus/ImapTest.pm line 186
        Cassandane::Cyrus::ImapTest::run_test(Cassandane::Cyrus::ImapTest=HASH(0x56365ad50c08)) called at /usr/share/perl5/vendor_perl/Test/Unit/TestCase.pm line 61
        Test::Unit::TestCase::run_bare(Cassandane::Cyrus::ImapTest=HASH(0x56365ad50c08)) called at /usr/share/perl5/vendor_perl/Test/Unit/Result.pm line 103
        Test::Unit::Result::__ANON__() called at /usr/share/perl5/vendor_perl/Test/Unit/Result.pm line 119
        Test::Unit::Result::run_protected(Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Cyrus::ImapTest=HASH(0x56365ad50c08), CODE(0x56365c6db208)) called at /usr/share/perl5/vendor_perl/Test/Unit/Result.pm line 107
        Test::Unit::Result::run(Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Cyrus::ImapTest=HASH(0x56365ad50c08)) called at /usr/share/perl5/vendor_perl/Test/Unit/TestCase.pm line 51
        Test::Unit::TestCase::run(Cassandane::Cyrus::ImapTest=HASH(0x56365ad50c08), Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Unit::RunnerPretty=HASH(0x5636594ec730)) called at /usr/share/perl5/vendor_perl/Test/Unit/TestSuite.pm line 278
        Test::Unit::TestSuite::run(Test::Unit::TestSuite=HASH(0x56365ad1a978), Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Unit::RunnerPretty=HASH(0x5636594ec730)) called at Cassandane/Unit/TestPlan.pm line 749
        Cassandane::Unit::TestPlan::_run_workitem(Cassandane::Unit::TestPlan=HASH(0x563656c05c50), HASH(0x56365c7ccd10), Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Unit::RunnerPretty=HASH(0x5636594ec730), 0) called at Cassandane/Unit/TestPlan.pm line 865
        Cassandane::Unit::TestPlan::__ANON__(HASH(0x56365c7ccd10)) called at Cassandane/Unit/TestPlan.pm line 207
        Cassandane::Unit::Worker::_mainloop(Cassandane::Unit::Worker=HASH(0x56365a6a6858)) called at Cassandane/Unit/TestPlan.pm line 167
        Cassandane::Unit::Worker::start(Cassandane::Unit::Worker=HASH(0x56365a6a6858)) called at Cassandane/Unit/TestPlan.pm line 312
        Cassandane::Unit::WorkerPool::start(Cassandane::Unit::WorkerPool=HASH(0x5636594f3fa8)) called at Cassandane/Unit/TestPlan.pm line 869
        Cassandane::Unit::TestPlan::run(Cassandane::Unit::TestPlan=HASH(0x563656c05c50), Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Unit::RunnerPretty=HASH(0x5636594ec730)) called at /usr/share/perl5/vendor_perl/Test/Unit/TestRunner.pm line 54
        Test::Unit::TestRunner::do_run(Cassandane::Unit::RunnerPretty=HASH(0x5636594ec730), Cassandane::Unit::TestPlan=HASH(0x563656c05c50), 0) called at ./testrunner.pl line 126
        main::__ANON__(Cassandane::Unit::TestPlan=HASH(0x563656c05c50), GLOB(0x563656c05c80)) called at ./testrunner.pl line 300

Annotations:
=====> Cyrus::TestCase[567] ---------- BEGIN test_fetch-binary-mime ----------
=====> Instance[1004] start main instance for test test_fetch-binary-mime: basedir /builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane/work/160321K18
=====> Instance[455] Found binary cyr_buildinfo in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3-1.fc27.x86_64/usr/sbin
=====> Instance[455] Found binary cyr_buildinfo in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3-1.fc27.x86_64/usr/sbin
=====> Instance[1505] Running: "/builddir/build/BUILDROOT/cyrus-imapd-3.0.3-1.fc27.x86_64/usr/sbin/cyr_buildinfo" "-C" "/builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane/work/160321K18/conf/imapd.conf"
=====> Instance[455] Found binary ctl_cyrusdb in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3-1.fc27.x86_64/usr/sbin
=====> Instance[455] Found binary imapd in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3-1.fc27.x86_64/usr/libexec/cyrus-imapd
=====> Instance[916] started saslauthd for /builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane/work/160321K18 as 16946
=====> Instance[1759] opening socket /builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane/work/160321K18/run/mux
=====> Instance[943] started notifyd for /builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane/work/160321K18 as 16947
=====> Instance[455] Found binary master in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3-1.fc27.x86_64/usr/libexec/cyrus-imapd
=====> Instance[1505] Running: "/builddir/build/BUILDROOT/cyrus-imapd-3.0.3-1.fc27.x86_64/usr/libexec/cyrus-imapd/master" "-C" "/builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane/work/160321K18/conf/imapd.conf" "-l" "255" "-p" "/builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane/work/160321K18/run/master.pid" "-d" "-M" "/builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane/work/160321K18/conf/cyrus.conf"
=====> Instance[868] _start_master: waiting for PID file
=====> Instance[871] _start_master: PID file present and correct
=====> Instance[883] _start_master: PID waiting for services
=====> Daemon[358] is_listening: service imap is listening on 127.0.0.1:9204
=====> Util::Wait[77] Waited 0.03993 sec for 127.0.0.1:9204 to be in LISTEN state
=====> Instance[894] _start_master: all services listening
=====> Instance[970] create user cassandane
=====> Util::Socket[63] create_client_socket INET host=127.0.0.1 port=9204
=====> Instance[1774] authdaemon connection: admin testpw imap 
=====> Instance[1035] started main instance for test test_fetch-binary-mime: cyrus version 3.0.3-1.fc27 Fedora
=====> Cyrus::TestCase[574] Calling test function
=====> Instance[1505] Running: "/builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane-6d33978ebb0055be41a5cb8e52958e100401bf26/imaptest/src/imaptest" "host=127.0.0.1" "port=9204" "user=cassandane" "pass=testpw" "rawlog" "test=/builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane-6d33978ebb0055be41a5cb8e52958e100401bf26/imaptest/src/tests/fetch-binary-mime"
=====> Instance[1774] authdaemon connection: cassandane testpw imap 
=====> Cyrus::ImapTest[170] Error: cassandane[1]: BAD reply for command: fetch 1 (binary.size[text]): 1.9 BAD Invalid binary section

=====> Cyrus::ImapTest[170] *** Test fetch-binary-mime command 3/29 (line 52)

=====> Cyrus::ImapTest[170]  - failed: Expected tagged reply 'ok', got 'BAD Invalid binary section'

=====> Cyrus::ImapTest[170]  - Command (tag 1.9): fetch 1 (binary.size[text])

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] *** Test fetch-binary-mime command 3/29 (line 52)

=====> Cyrus::ImapTest[170]  - failed: Missing 1 untagged replies (0 mismatches)

=====> Cyrus::ImapTest[170]  - first unexpanded: 1 fetch ($!unordered=2 binary.size[text] 466)

=====> Cyrus::ImapTest[170]  - first expanded: 1 fetch ( binary.size[text] 466)

=====> Cyrus::ImapTest[170]  - best match: 

=====> Cyrus::ImapTest[170]  - Command: fetch 1 (binary.size[text])

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] *** Test fetch-binary-mime command 4/29 (line 55)

=====> Cyrus::ImapTest[170]  - failed: Unexpected disconnection

=====> Cyrus::ImapTest[170]  - Command: fetch 1 (binary.peek[text])

=====> Cyrus::ImapTest[170] 

=====> Cyrus::TestCase[681] Beginning tear_down
=====> Instance[1242] stop
=====> Instance[1204] _stop_pid: sending signal 15 to 16959
=====> Util::Wait[77] Waited 0.010085 sec for unknown condition
=====> Instance[919] killing saslauthd 16946
Warning: unable to close filehandle $oldout properly: Bad file descriptor at Cassandane/Instance.pm line 913.
Warning: unable to close filehandle $olderr properly: Bad file descriptor at Cassandane/Instance.pm line 913.
=====> Instance[946] killing notifyd 16947
Warning: unable to close filehandle $oldout properly: Bad file descriptor at Cassandane/Instance.pm line 940.
Warning: unable to close filehandle $olderr properly: Bad file descriptor at Cassandane/Instance.pm line 940.
=====> Instance[919] killing saslauthd 16946
=====> Instance[1270] Cleaning up basedir /builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane/work/160321K18
=====> Cyrus::TestCase[726] ---------- END test_fetch-binary-mime ----------
=====> Daemon[387] checking for stray processes on ports: 9204
============> rawlog.1 <=============
1502467414.998656 I: * OK [CAPABILITY IMAP4rev1 LITERAL+ ID ENABLE AUTH=PLAIN AUTH=LOGIN SASL-IR] 127.0.0.1 Cyrus IMAP 3.0.3-1.fc27 Fedora server ready
1502467414.998682 O: 1.1 LOGIN "cassandane" "testpw"
1502467414.999050 I: 1.1 OK [CAPABILITY IMAP4rev1 LITERAL+ ID ENABLE ACL RIGHTS=kxten QUOTA MAILBOX-REFERRALS NAMESPACE UIDPLUS NO_ATOMIC_RENAME UNSELECT CHILDREN MULTIAPPEND BINARY CATENATE CONDSTORE ESEARCH SEARCH=FUZZY SORT SORT=MODSEQ SORT=DISPLAY SORT=UID THREAD=ORDEREDSUBJECT THREAD=REFERENCES THREAD=REFS ANNOTATEMORE ANNOTATE-EXPERIMENT-1 METADATA LIST-EXTENDED LIST-STATUS LIST-MYRIGHTS LIST-METADATA WITHIN QRESYNC SCAN XLIST XMOVE MOVE SPECIAL-USE CREATE-SPECIAL-USE DIGEST=SHA1 X-REPLICATION URLAUTH URLAUTH=BINARY LOGINDISABLED COMPRESS=DEFLATE X-QUOTA=STORAGE X-QUOTA=MESSAGE X-QUOTA=X-ANNOTATION-STORAGE X-QUOTA=X-NUM-FOLDERS IDLE] User logged in SESSIONID=<160321K18-16974-1502467414-2-4187343474950476330>
1502467414.999103 O: 1.2 LIST "" "imaptest*"
1502467414.999107 O: 1.3 LSUB "" "imaptest*"
1502467414.999258 I: 1.2 OK Completed (0.000 secs)
1502467415.039848 I: 1.3 OK Completed (0.000 secs)
1502467415.039882 O: 1.4 CREATE "imaptest"
1502467415.041155 I: 1.4 OK Completed
1502467415.041249 O: 1.5 APPEND "imaptest" "22-Feb-2008 17:06:23 -0600" {602+}
1502467415.041259 O: From: user@domain.org
1502467415.041259 O: Date: Sat, 24 Mar 2007 23:00:00 +0200
1502467415.041259 O: Mime-Version: 1.0
1502467415.041259 O: Content-Type: multipart/mixed; boundary="foo
1502467415.041259 O:  bar"
1502467415.041259 O: 
1502467415.041259 O: Root MIME prologue
1502467415.041259 O: 
1502467415.041259 O: --foo bar
1502467415.041259 O: Content-Type: text/x-myown; charset=us-ascii
1502467415.041259 O: 
1502467415.041259 O: hello
1502467415.041259 O: 
1502467415.041259 O: --foo bar
1502467415.041259 O: Content-Type: message/rfc822
1502467415.041259 O: 
1502467415.041259 O: From: sub@domain.org
1502467415.041259 O: Date: Sun, 12 Aug 2012 12:34:56 +0300
1502467415.041259 O: Subject: submsg
1502467415.041259 O: Content-Type: multipart/alternative; boundary="sub1"
1502467415.041259 O: 
1502467415.041259 O: Sub MIME prologue
1502467415.041259 O: --sub1
1502467415.041259 O: Content-Type: text/html
1502467415.041259 O: 
1502467415.041259 O: <p>Hello world</p>
1502467415.041259 O: 
1502467415.041259 O: --sub1
1502467415.041259 O: Content-Type: text/plain
1502467415.041259 O: 
1502467415.041259 O: Hello another world
1502467415.041259 O: 
1502467415.041259 O: --sub1--
1502467415.041259 O: Sub MIME epilogue
1502467415.041259 O: 
1502467415.041259 O: --foo bar--
1502467415.041259 O: Root MIME epilogue
1502467415.041272 O: 
1502467415.042198 I: 1.5 OK [APPENDUID 1502467416 1] Completed
1502467415.042216 O: 1.6 SELECT "imaptest"
1502467415.042427 I: * 1 EXISTS
1502467415.042427 I: * 1 RECENT
1502467415.042427 I: * FLAGS (\Answered \Flagged \Draft \Deleted \Seen)
1502467415.042427 I: * OK [PERMANENTFLAGS (\Answered \Flagged \Draft \Deleted \Seen \*)] Ok
1502467415.042427 I: * OK [UNSEEN 1] Ok
1502467415.042427 I: * OK [UIDVALIDITY 1502467416] Ok
1502467415.042427 I: * OK [UIDNEXT 2] Ok
1502467415.042427 I: * OK [HIGHESTMODSEQ 2] Ok
1502467415.042427 I: * OK [URLMECH INTERNAL] Ok
1502467415.042427 I: * OK [ANNOTATIONS 65536] Ok
1502467415.042427 I: 1.6 OK [READ-WRITE] Completed
1502467415.042457 O: 1.7 fetch 1 (binary.peek[])
1502467415.042654 I: * 1 FETCH (BINARY[] {602}
1502467415.042654 I: From: user@domain.org
1502467415.042654 I: Date: Sat, 24 Mar 2007 23:00:00 +0200
1502467415.042654 I: Mime-Version: 1.0
1502467415.042654 I: Content-Type: multipart/mixed; boundary="foo
1502467415.042654 I:  bar"
1502467415.042654 I: 
1502467415.042654 I: Root MIME prologue
1502467415.042654 I: 
1502467415.042654 I: --foo bar
1502467415.042654 I: Content-Type: text/x-myown; charset=us-ascii
1502467415.042654 I: 
1502467415.042654 I: hello
1502467415.042654 I: 
1502467415.042654 I: --foo bar
1502467415.042654 I: Content-Type: message/rfc822
1502467415.042654 I: 
1502467415.042654 I: From: sub@domain.org
1502467415.042654 I: Date: Sun, 12 Aug 2012 12:34:56 +0300
1502467415.042654 I: Subject: submsg
1502467415.042654 I: Content-Type: multipart/alternative; boundary="sub1"
1502467415.042654 I: 
1502467415.042654 I: Sub MIME prologue
1502467415.042654 I: --sub1
1502467415.042654 I: Content-Type: text/html
1502467415.042654 I: 
1502467415.042654 I: <p>Hello world</p>
1502467415.042654 I: 
1502467415.042654 I: --sub1
1502467415.042654 I: Content-Type: text/plain
1502467415.042654 I: 
1502467415.042654 I: Hello another world
1502467415.042654 I: 
1502467415.042654 I: --sub1--
1502467415.042654 I: Sub MIME epilogue
1502467415.042654 I: 
1502467415.042654 I: --foo bar--
1502467415.042654 I: Root MIME epilogue
1502467415.042654 I: )
1502467415.042654 I: 1.7 OK Completed (0.000 sec)
1502467415.042691 O: 1.8 fetch 1 (binary.size[])
1502467415.042876 I: * 1 FETCH (BINARY.SIZE[] 602)
1502467415.042876 I: 1.8 OK Completed (0.000 sec)
1502467415.042887 O: 1.9 fetch 1 (binary.size[text])
1502467415.042968 I: 1.9 BAD Invalid binary section
3) test_urlauth-binary(Cassandane::Cyrus::ImapTest)
 Boolean assertion failed at /usr/share/perl5/vendor_perl/Test/Unit/Exception.pm line 13.
        Test::Unit::Exception::throw_new(Test::Unit::Failure=HASH(0x56365c7ad708), "-package", "Cassandane::Cyrus::ImapTest", "-file", "Cassandane/Cyrus/ImapTest.pm", "-line", 186, "-object", ...) called at /usr/share/perl5/vendor_perl/Test/Unit/Assert.pm line 85
        Test::Unit::Assert::do_assertion(Cassandane::Cyrus::ImapTest=HASH(0x56365ad1e9f0), Test::Unit::Assertion::Boolean=SCALAR(0x56365c7dd1d8), "Cassandane::Cyrus::ImapTest", "Cassandane/Cyrus/ImapTest.pm", 186) called at /usr/share/perl5/vendor_perl/Test/Unit/Assert.pm line 19
        Test::Unit::Assert::assert(Cassandane::Cyrus::ImapTest=HASH(0x56365ad1e9f0), 0) called at Cassandane/Cyrus/ImapTest.pm line 186
        Cassandane::Cyrus::ImapTest::run_test(Cassandane::Cyrus::ImapTest=HASH(0x56365ad1e9f0)) called at /usr/share/perl5/vendor_perl/Test/Unit/TestCase.pm line 61
        Test::Unit::TestCase::run_bare(Cassandane::Cyrus::ImapTest=HASH(0x56365ad1e9f0)) called at /usr/share/perl5/vendor_perl/Test/Unit/Result.pm line 103
        Test::Unit::Result::__ANON__() called at /usr/share/perl5/vendor_perl/Test/Unit/Result.pm line 119
        Test::Unit::Result::run_protected(Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Cyrus::ImapTest=HASH(0x56365ad1e9f0), CODE(0x56365a8ca1f0)) called at /usr/share/perl5/vendor_perl/Test/Unit/Result.pm line 107
        Test::Unit::Result::run(Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Cyrus::ImapTest=HASH(0x56365ad1e9f0)) called at /usr/share/perl5/vendor_perl/Test/Unit/TestCase.pm line 51
        Test::Unit::TestCase::run(Cassandane::Cyrus::ImapTest=HASH(0x56365ad1e9f0), Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Unit::RunnerPretty=HASH(0x5636594ec730)) called at /usr/share/perl5/vendor_perl/Test/Unit/TestSuite.pm line 278
        Test::Unit::TestSuite::run(Test::Unit::TestSuite=HASH(0x56365ad1a978), Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Unit::RunnerPretty=HASH(0x5636594ec730)) called at Cassandane/Unit/TestPlan.pm line 749
        Cassandane::Unit::TestPlan::_run_workitem(Cassandane::Unit::TestPlan=HASH(0x563656c05c50), HASH(0x56365c710478), Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Unit::RunnerPretty=HASH(0x5636594ec730), 0) called at Cassandane/Unit/TestPlan.pm line 865
        Cassandane::Unit::TestPlan::__ANON__(HASH(0x56365c710478)) called at Cassandane/Unit/TestPlan.pm line 207
        Cassandane::Unit::Worker::_mainloop(Cassandane::Unit::Worker=HASH(0x56365c103670)) called at Cassandane/Unit/TestPlan.pm line 167
        Cassandane::Unit::Worker::start(Cassandane::Unit::Worker=HASH(0x56365c103670)) called at Cassandane/Unit/TestPlan.pm line 312
        Cassandane::Unit::WorkerPool::start(Cassandane::Unit::WorkerPool=HASH(0x5636594f3fa8)) called at Cassandane/Unit/TestPlan.pm line 869
        Cassandane::Unit::TestPlan::run(Cassandane::Unit::TestPlan=HASH(0x563656c05c50), Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Unit::RunnerPretty=HASH(0x5636594ec730)) called at /usr/share/perl5/vendor_perl/Test/Unit/TestRunner.pm line 54
        Test::Unit::TestRunner::do_run(Cassandane::Unit::RunnerPretty=HASH(0x5636594ec730), Cassandane::Unit::TestPlan=HASH(0x563656c05c50), 0) called at ./testrunner.pl line 126
        main::__ANON__(Cassandane::Unit::TestPlan=HASH(0x563656c05c50), GLOB(0x563656c05c80)) called at ./testrunner.pl line 300

Annotations:
=====> Cyrus::TestCase[567] ---------- BEGIN test_urlauth-binary ----------
=====> Instance[1004] start main instance for test test_urlauth-binary: basedir /builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane/work/160321I26
=====> Instance[455] Found binary cyr_buildinfo in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3-1.fc27.x86_64/usr/sbin
=====> Instance[455] Found binary cyr_buildinfo in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3-1.fc27.x86_64/usr/sbin
=====> Instance[1505] Running: "/builddir/build/BUILDROOT/cyrus-imapd-3.0.3-1.fc27.x86_64/usr/sbin/cyr_buildinfo" "-C" "/builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane/work/160321I26/conf/imapd.conf"
=====> Instance[455] Found binary ctl_cyrusdb in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3-1.fc27.x86_64/usr/sbin
=====> Instance[455] Found binary imapd in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3-1.fc27.x86_64/usr/libexec/cyrus-imapd
=====> Instance[916] started saslauthd for /builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane/work/160321I26 as 17884
=====> Instance[1759] opening socket /builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane/work/160321I26/run/mux
=====> Instance[943] started notifyd for /builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane/work/160321I26 as 17886
=====> Instance[455] Found binary master in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3-1.fc27.x86_64/usr/libexec/cyrus-imapd
=====> Instance[1505] Running: "/builddir/build/BUILDROOT/cyrus-imapd-3.0.3-1.fc27.x86_64/usr/libexec/cyrus-imapd/master" "-C" "/builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane/work/160321I26/conf/imapd.conf" "-l" "255" "-p" "/builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane/work/160321I26/run/master.pid" "-d" "-M" "/builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane/work/160321I26/conf/cyrus.conf"
=====> Instance[868] _start_master: waiting for PID file
=====> Instance[871] _start_master: PID file present and correct
=====> Instance[883] _start_master: PID waiting for services
=====> Daemon[358] is_listening: service imap is listening on 127.0.0.1:9181
=====> Util::Wait[77] Waited 0.044518 sec for 127.0.0.1:9181 to be in LISTEN state
=====> Instance[894] _start_master: all services listening
=====> Instance[970] create user cassandane
=====> Util::Socket[63] create_client_socket INET host=127.0.0.1 port=9181
=====> Instance[1774] authdaemon connection: admin testpw imap 
=====> Instance[1035] started main instance for test test_urlauth-binary: cyrus version 3.0.3-1.fc27 Fedora
=====> Cyrus::TestCase[574] Calling test function
=====> Instance[1505] Running: "/builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane-6d33978ebb0055be41a5cb8e52958e100401bf26/imaptest/src/imaptest" "host=127.0.0.1" "port=9181" "user=cassandane" "pass=testpw" "rawlog" "test=/builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane-6d33978ebb0055be41a5cb8e52958e100401bf26/imaptest/src/tests/urlauth-binary"
=====> Instance[1774] authdaemon connection: cassandane testpw imap 
=====> Cyrus::ImapTest[170] *** Test urlauth-binary command 9/16 (line 22)

=====> Cyrus::ImapTest[170]  - failed: Missing 1 untagged replies (1 mismatches)

=====> Cyrus::ImapTest[170]  - first unexpanded: URLFETCH $mail_url1 (binary {390}

=====> Cyrus::ImapTest[170] From: sub@domain.org

=====> Cyrus::ImapTest[170] Date: Sun, 12 Aug 2012 12:34:56 +0300

=====> Cyrus::ImapTest[170] Subject: submsg

=====> Cyrus::ImapTest[170] Content-Type: multipart/alternative; boundary="sub1"

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] Sub MIME prologue

=====> Cyrus::ImapTest[170] --sub1

=====> Cyrus::ImapTest[170] Content-Type: text/x-myown; charset=us-ascii

=====> Cyrus::ImapTest[170] Content-Transfer-Encoding: binary

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] hello world

=====> Cyrus::ImapTest[170] --sub1

=====> Cyrus::ImapTest[170] Content-Type: text/x-myown; charset=us-ascii

=====> Cyrus::ImapTest[170] Content-Transfer-Encoding: binary

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] hello world

=====> Cyrus::ImapTest[170] --sub1--

=====> Cyrus::ImapTest[170] Sub MIME epilogue

=====> Cyrus::ImapTest[170] )

=====> Cyrus::ImapTest[170]  - first expanded: URLFETCH imap://cassandane@127.0.0.1/imaptest/;uid=1/;section=1;urlauth=user+cassandane:internal:0097a0ee6d77055f011bb548f01121c1e544a3194c (binary {390}

=====> Cyrus::ImapTest[170] From: sub@domain.org

=====> Cyrus::ImapTest[170] Date: Sun, 12 Aug 2012 12:34:56 +0300

=====> Cyrus::ImapTest[170] Subject: submsg

=====> Cyrus::ImapTest[170] Content-Type: multipart/alternative; boundary="sub1"

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] Sub MIME prologue

=====> Cyrus::ImapTest[170] --sub1

=====> Cyrus::ImapTest[170] Content-Type: text/x-myown; charset=us-ascii

=====> Cyrus::ImapTest[170] Content-Transfer-Encoding: binary

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] hello world

=====> Cyrus::ImapTest[170] --sub1

=====> Cyrus::ImapTest[170] Content-Type: text/x-myown; charset=us-ascii

=====> Cyrus::ImapTest[170] Content-Transfer-Encoding: binary

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] hello world

=====> Cyrus::ImapTest[170] --sub1--

=====> Cyrus::ImapTest[170] Sub MIME epilogue

=====> Cyrus::ImapTest[170] )

=====> Cyrus::ImapTest[170]  - best match: URLFETCH "imap://cassandane@127.0.0.1/imaptest/;uid=1/;section=1;urlauth=user+cassandane:internal:0097a0ee6d77055f011bb548f01121c1e544a3194c" (BINARY "From: sub@domain.org

=====> Cyrus::ImapTest[170] Date: Sun, 12 Aug 2012 12:34:56 +0300

=====> Cyrus::ImapTest[170] Subject: submsg

=====> Cyrus::ImapTest[170] Content-Type: multipart/alternative; boundary=\"sub1\"

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] Sub MIME prologue

=====> Cyrus::ImapTest[170] --sub1

=====> Cyrus::ImapTest[170] Content-Type: text/x-myown; charset=us-ascii

=====> Cyrus::ImapTest[170] Content-Transfer-Encoding: base64

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] aGVs

=====> Cyrus::ImapTest[170] bG8gd29y

=====> Cyrus::ImapTest[170] bGQ=

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] --sub1

=====> Cyrus::ImapTest[170] Content-Type: text/x-myown; charset=us-ascii

=====> Cyrus::ImapTest[170] Content-Transfer-Encoding: base64

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170]   aGVs    

=====> Cyrus::ImapTest[170]  bG8g  

=====> Cyrus::ImapTest[170]    d29y      

=====> Cyrus::ImapTest[170]     bGQ= 

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] --sub1--

=====> Cyrus::ImapTest[170] Sub MIME epilogue

=====> Cyrus::ImapTest[170] ")

=====> Cyrus::ImapTest[170]  - Command: URLFETCH ($mail_url1 binary)

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] *** Test urlauth-binary command 13/16 (line 57)

=====> Cyrus::ImapTest[170]  - failed: Missing 1 untagged replies (1 mismatches)

=====> Cyrus::ImapTest[170]  - first unexpanded: URLFETCH $mail_url2 (BODYPARTSTRUCTURE ("message" "rfc822" NIL NIL NIL "7bit" 390 ("Sun, 12 Aug 2012 12:34:56 +0300" "submsg" ((NIL NIL "sub" "domain.org")) ((NIL NIL "sub" "domain.org")) ((NIL NIL "sub" "domain.org")) NIL NIL NIL NIL NIL) (("text" "x-myown" ("charset" "us-ascii") NIL NIL "base64" 11 0 NIL NIL NIL NIL) ("text" "x-myown" ("charset" "us-ascii") NIL NIL "base64" 11 0 NIL NIL NIL NIL) "alternative" ("boundary" "sub1") NIL NIL NIL) 18 NIL NIL NIL NIL) binary {390}

=====> Cyrus::ImapTest[170] From: sub@domain.org

=====> Cyrus::ImapTest[170] Date: Sun, 12 Aug 2012 12:34:56 +0300

=====> Cyrus::ImapTest[170] Subject: submsg

=====> Cyrus::ImapTest[170] Content-Type: multipart/alternative; boundary="sub1"

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] Sub MIME prologue

=====> Cyrus::ImapTest[170] --sub1

=====> Cyrus::ImapTest[170] Content-Type: text/x-myown; charset=us-ascii

=====> Cyrus::ImapTest[170] Content-Transfer-Encoding: binary

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] hello world

=====> Cyrus::ImapTest[170] --sub1

=====> Cyrus::ImapTest[170] Content-Type: text/x-myown; charset=us-ascii

=====> Cyrus::ImapTest[170] Content-Transfer-Encoding: binary

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] hello world

=====> Cyrus::ImapTest[170] --sub1--

=====> Cyrus::ImapTest[170] Sub MIME epilogue

=====> Cyrus::ImapTest[170] )

=====> Cyrus::ImapTest[170]  - first expanded: URLFETCH imap://cassandane@127.0.0.1/imaptest/;uid=2/;section=1;urlauth=user+cassandane:internal:00a91c0f93d4d016bc9df3b8d06b72c788d9bc3992 (BODYPARTSTRUCTURE ("message" "rfc822" NIL NIL NIL "7bit" 390 ("Sun, 12 Aug 2012 12:34:56 +0300" "submsg" ((NIL NIL "sub" "domain.org")) ((NIL NIL "sub" "domain.org")) ((NIL NIL "sub" "domain.org")) NIL NIL NIL NIL NIL) (("text" "x-myown" ("charset" "us-ascii") NIL NIL "base64" 11 0 NIL NIL NIL NIL) ("text" "x-myown" ("charset" "us-ascii") NIL NIL "base64" 11 0 NIL NIL NIL NIL) "alternative" ("boundary" "sub1") NIL NIL NIL) 18 NIL NIL NIL NIL) binary {390}

=====> Cyrus::ImapTest[170] From: sub@domain.org

=====> Cyrus::ImapTest[170] Date: Sun, 12 Aug 2012 12:34:56 +0300

=====> Cyrus::ImapTest[170] Subject: submsg

=====> Cyrus::ImapTest[170] Content-Type: multipart/alternative; boundary="sub1"

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] Sub MIME prologue

=====> Cyrus::ImapTest[170] --sub1

=====> Cyrus::ImapTest[170] Content-Type: text/x-myown; charset=us-ascii

=====> Cyrus::ImapTest[170] Content-Transfer-Encoding: binary

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] hello world

=====> Cyrus::ImapTest[170] --sub1

=====> Cyrus::ImapTest[170] Content-Type: text/x-myown; charset=us-ascii

=====> Cyrus::ImapTest[170] Content-Transfer-Encoding: binary

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] hello world

=====> Cyrus::ImapTest[170] --sub1--

=====> Cyrus::ImapTest[170] Sub MIME epilogue

=====> Cyrus::ImapTest[170] )

=====> Cyrus::ImapTest[170]  - best match: URLFETCH "imap://cassandane@127.0.0.1/imaptest/;uid=2/;section=1;urlauth=user+cassandane:internal:00a91c0f93d4d016bc9df3b8d06b72c788d9bc3992" (BODYPARTSTRUCTURE ("MESSAGE" "RFC822" NIL NIL NIL "7BIT" 437 ("Sun, 12 Aug 2012 12:34:56 +0300" "submsg" ((NIL NIL "sub" "domain.org")) ((NIL NIL "sub" "domain.org")) ((NIL NIL "sub" "domain.org")) NIL NIL NIL NIL NIL) (("TEXT" "X-MYOWN" ("CHARSET" "us-ascii") NIL NIL "BASE64" 22 3 NIL NIL NIL NIL) ("TEXT" "X-MYOWN" ("CHARSET" "us-ascii") NIL NIL "BASE64" 47 5 NIL NIL NIL NIL) "ALTERNATIVE" ("BOUNDARY" "sub1") NIL NIL NIL) 26 NIL NIL NIL NIL)) (BINARY "From: sub@domain.org

=====> Cyrus::ImapTest[170] Date: Sun, 12 Aug 2012 12:34:56 +0300

=====> Cyrus::ImapTest[170] Subject: submsg

=====> Cyrus::ImapTest[170] Content-Type: multipart/alternative; boundary=\"sub1\"

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] Sub MIME prologue

=====> Cyrus::ImapTest[170] --sub1

=====> Cyrus::ImapTest[170] Content-Type: text/x-myown; charset=us-ascii

=====> Cyrus::ImapTest[170] Content-Transfer-Encoding: base64

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] aGVs

=====> Cyrus::ImapTest[170] bG8gd29y

=====> Cyrus::ImapTest[170] bGQ=

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] --sub1

=====> Cyrus::ImapTest[170] Content-Type: text/x-myown; charset=us-ascii

=====> Cyrus::ImapTest[170] Content-Transfer-Encoding: base64

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170]   aGVs    

=====> Cyrus::ImapTest[170]  bG8g  

=====> Cyrus::ImapTest[170]    d29y      

=====> Cyrus::ImapTest[170]     bGQ= 

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] --sub1--

=====> Cyrus::ImapTest[170] Sub MIME epilogue

=====> Cyrus::ImapTest[170] ")

=====> Cyrus::ImapTest[170]  - Command: URLFETCH ($mail_url2 binary bodypartstructure)

=====> Cyrus::ImapTest[170] 

=====> Cyrus::ImapTest[170] *** Test urlauth-binary command 15/16 (line 85)

=====> Cyrus::ImapTest[170]  - failed: Missing 1 untagged replies (1 mismatches)

=====> Cyrus::ImapTest[170]  - first unexpanded: URLFETCH $mail_url2sub (BODYPARTSTRUCTURE ("text" "x-myown" ("charset" "us-ascii") NIL NIL "base64" 11 0 NIL NIL NIL NIL) BINARY {11}

=====> Cyrus::ImapTest[170] hello world)

=====> Cyrus::ImapTest[170]  - first expanded: URLFETCH imap://cassandane@127.0.0.1/imaptest/;uid=2/;section=1.1;urlauth=user+cassandane:internal:00309636bf5df8a7e1466644a89659c2afd5ba240a (BODYPARTSTRUCTURE ("text" "x-myown" ("charset" "us-ascii") NIL NIL "base64" 11 0 NIL NIL NIL NIL) BINARY {11}

=====> Cyrus::ImapTest[170] hello world)

=====> Cyrus::ImapTest[170]  - best match: URLFETCH "imap://cassandane@127.0.0.1/imaptest/;uid=2/;section=1.1;urlauth=user+cassandane:internal:00309636bf5df8a7e1466644a89659c2afd5ba240a" (BODYPARTSTRUCTURE ("TEXT" "X-MYOWN" ("CHARSET" "us-ascii") NIL NIL "7BIT" 11 0 NIL NIL NIL NIL)) (BINARY "hello world")

=====> Cyrus::ImapTest[170]  - Command: URLFETCH ($mail_url2sub binary bodypartstructure)

=====> Cyrus::ImapTest[170] 

============> rawlog.1 <=============
1502467417.335299 I: * OK [CAPABILITY IMAP4rev1 LITERAL+ ID ENABLE AUTH=PLAIN AUTH=LOGIN SASL-IR] 127.0.0.1 Cyrus IMAP 3.0.3-1.fc27 Fedora server ready
1502467417.335330 O: 1.1 LOGIN "cassandane" "testpw"
1502467417.335848 I: 1.1 OK [CAPABILITY IMAP4rev1 LITERAL+ ID ENABLE ACL RIGHTS=kxten QUOTA MAILBOX-REFERRALS NAMESPACE UIDPLUS NO_ATOMIC_RENAME UNSELECT CHILDREN MULTIAPPEND BINARY CATENATE CONDSTORE ESEARCH SEARCH=FUZZY SORT SORT=MODSEQ SORT=DISPLAY SORT=UID THREAD=ORDEREDSUBJECT THREAD=REFERENCES THREAD=REFS ANNOTATEMORE ANNOTATE-EXPERIMENT-1 METADATA LIST-EXTENDED LIST-STATUS LIST-MYRIGHTS LIST-METADATA WITHIN QRESYNC SCAN XLIST XMOVE MOVE SPECIAL-USE CREATE-SPECIAL-USE DIGEST=SHA1 X-REPLICATION URLAUTH URLAUTH=BINARY LOGINDISABLED COMPRESS=DEFLATE X-QUOTA=STORAGE X-QUOTA=MESSAGE X-QUOTA=X-ANNOTATION-STORAGE X-QUOTA=X-NUM-FOLDERS IDLE] User logged in SESSIONID=<160321I26-17913-1502467417-2-7289169598211189542>
1502467417.335909 O: 1.2 LIST "" "imaptest*"
1502467417.335913 O: 1.3 LSUB "" "imaptest*"
1502467417.336128 I: 1.2 OK Completed (0.000 secs)
1502467417.376851 I: 1.3 OK Completed (0.000 secs)
1502467417.376871 O: 1.4 CREATE "imaptest"
1502467417.378169 I: 1.4 OK Completed
1502467417.378194 O: 1.5 select imaptest
1502467417.378530 I: * 0 EXISTS
1502467417.378530 I: * 0 RECENT
1502467417.378530 I: * FLAGS (\Answered \Flagged \Draft \Deleted \Seen)
1502467417.378530 I: * OK [PERMANENTFLAGS (\Answered \Flagged \Draft \Deleted \Seen \*)] Ok
1502467417.378530 I: * OK [UIDVALIDITY 1502467418] Ok
1502467417.378530 I: * OK [UIDNEXT 1] Ok
1502467417.378530 I: * OK [HIGHESTMODSEQ 1] Ok
1502467417.378530 I: * OK [URLMECH INTERNAL] Ok
1502467417.378530 I: * OK [ANNOTATIONS 65536] Ok
1502467417.378530 I: 1.5 OK [READ-WRITE] Completed
1502467417.378619 O: 1.6 APPEND "imaptest" "22-Feb-2008 17:06:23 -0600" {673+}
1502467417.378627 O: From: user@domain.org
1502467417.378627 O: Date: Sat, 24 Mar 2007 23:00:00 +0200
1502467417.378627 O: Mime-Version: 1.0
1502467417.378627 O: Content-Type: multipart/mixed; boundary="foo
1502467417.378627 O:  bar"
1502467417.378627 O: 
1502467417.378627 O: Root MIME prologue
1502467417.378627 O: 
1502467417.378627 O: --foo bar
1502467417.378627 O: Content-Type: message/rfc822
1502467417.378627 O: 
1502467417.378627 O: From: sub@domain.org
1502467417.378627 O: Date: Sun, 12 Aug 2012 12:34:56 +0300
1502467417.378627 O: Subject: submsg
1502467417.378627 O: Content-Type: multipart/alternative; boundary="sub1"
1502467417.378627 O: 
1502467417.378627 O: Sub MIME prologue
1502467417.378627 O: --sub1
1502467417.378627 O: Content-Type: text/x-myown; charset=us-ascii
1502467417.378627 O: Content-Transfer-Encoding: base64
1502467417.378627 O: 
1502467417.378627 O: aGVs
1502467417.378627 O: bG8gd29y
1502467417.378627 O: bGQ=
1502467417.378627 O: 
1502467417.378627 O: --sub1
1502467417.378627 O: Content-Type: text/x-myown; charset=us-ascii
1502467417.378627 O: Content-Transfer-Encoding: base64
1502467417.378627 O: 
1502467417.378627 O:   aGVs    
1502467417.378627 O:  bG8g  
1502467417.378627 O:    d29y     
1502467417.378627 O:     bGQ= 
1502467417.378627 O: 
1502467417.378627 O: 
1502467417.378627 O: --sub1--
1502467417.378627 O: Sub MIME epilogue
1502467417.378627 O: 
1502467417.378627 O: --foo bar--
1502467417.378627 O: Root MIME epilogue
1502467417.378650 O: 
1502467417.379922 I: * 1 EXISTS
1502467417.379922 I: * 1 RECENT
1502467417.379922 I: 1.6 OK [APPENDUID 1502467418 1] Completed
1502467417.379954 O: 1.7 APPEND "imaptest" "22-Feb-2008 17:06:23 -0600" {673+}
1502467417.379958 O: From: user@domain.org
1502467417.379958 O: Date: Sat, 24 Mar 2007 23:00:00 +0200
1502467417.379958 O: Mime-Version: 1.0
1502467417.379958 O: Content-Type: multipart/mixed; boundary="foo
1502467417.379958 O:  bar"
1502467417.379958 O: 
1502467417.379958 O: Root MIME prologue
1502467417.379958 O: 
1502467417.379958 O: --foo bar
1502467417.379958 O: Content-Type: message/rfc822
1502467417.379958 O: 
1502467417.379958 O: From: sub@domain.org
1502467417.379958 O: Date: Sun, 12 Aug 2012 12:34:56 +0300
1502467417.379958 O: Subject: submsg
1502467417.379958 O: Content-Type: multipart/alternative; boundary="sub1"
1502467417.379958 O: 
1502467417.379958 O: Sub MIME prologue
1502467417.379958 O: --sub1
1502467417.379958 O: Content-Type: text/x-myown; charset=us-ascii
1502467417.379958 O: Content-Transfer-Encoding: base64
1502467417.379958 O: 
1502467417.379958 O: aGVs
1502467417.379958 O: bG8gd29y
1502467417.379958 O: bGQ=
1502467417.379958 O: 
1502467417.379958 O: --sub1
1502467417.379958 O: Content-Type: text/x-myown; charset=us-ascii
1502467417.379958 O: Content-Transfer-Encoding: base64
1502467417.379958 O: 
1502467417.379958 O:   aGVs    
1502467417.379958 O:  bG8g  
1502467417.379958 O:    d29y     
1502467417.379958 O:     bGQ= 
1502467417.379958 O: 
1502467417.379958 O: 
1502467417.379958 O: --sub1--
1502467417.379958 O: Sub MIME epilogue
1502467417.379958 O: 
1502467417.379958 O: --foo bar--
1502467417.379958 O: Root MIME epilogue
1502467417.379974 O: 
1502467417.381035 I: * 2 EXISTS
1502467417.381035 I: * 2 RECENT
1502467417.381035 I: 1.7 OK [APPENDUID 1502467418 2] Completed
1502467417.381056 O: 1.8 fetch 1:2 uid
1502467417.381275 I: * 1 FETCH (UID 1)
1502467417.381275 I: * 2 FETCH (UID 2)
1502467417.381275 I: 1.8 OK Completed (0.000 sec)
1502467417.381301 O: 1.9 GENURLAUTH "imap://cassandane@127.0.0.1/imaptest/;uid=1/;section=1;urlauth=user+cassandane" INTERNAL
1502467417.381524 I: * GENURLAUTH "imap://cassandane@127.0.0.1/imaptest/;uid=1/;section=1;urlauth=user+cassandane:internal:0097a0ee6d77055f011bb548f01121c1e544a3194c"
1502467417.381524 I: 1.9 OK Completed
1502467417.381550 O: 1.10 GENURLAUTH "imap://cassandane@127.0.0.1/imaptest/;uid=1/;section=1.1;urlauth=user+cassandane" INTERNAL
1502467417.381705 I: * GENURLAUTH "imap://cassandane@127.0.0.1/imaptest/;uid=1/;section=1.1;urlauth=user+cassandane:internal:00eddf5fc9f7b76eabcebd22efc5e825b5ac98d7ad"
1502467417.381705 I: 1.10 OK Completed
1502467417.381723 O: 1.11 GENURLAUTH "imap://cassandane@127.0.0.1/imaptest/;uid=2/;section=1;urlauth=user+cassandane" INTERNAL
1502467417.381910 I: * GENURLAUTH "imap://cassandane@127.0.0.1/imaptest/;uid=2/;section=1;urlauth=user+cassandane:internal:00a91c0f93d4d016bc9df3b8d06b72c788d9bc3992"
1502467417.381910 I: 1.11 OK Completed
1502467417.381927 O: 1.12 GENURLAUTH "imap://cassandane@127.0.0.1/imaptest/;uid=2/;section=1.1;urlauth=user+cassandane" INTERNAL
1502467417.382082 I: * GENURLAUTH "imap://cassandane@127.0.0.1/imaptest/;uid=2/;section=1.1;urlauth=user+cassandane:internal:00309636bf5df8a7e1466644a89659c2afd5ba240a"
1502467417.382082 I: 1.12 OK Completed
1502467417.382097 O: 1.13 URLFETCH (imap://cassandane@127.0.0.1/imaptest/;uid=1/;section=1;urlauth=user+cassandane:internal:0097a0ee6d77055f011bb548f01121c1e544a3194c binary)
1502467417.382433 I: * URLFETCH "imap://cassandane@127.0.0.1/imaptest/;uid=1/;section=1;urlauth=user+cassandane:internal:0097a0ee6d77055f011bb548f01121c1e544a3194c" (BINARY {437}
1502467417.382433 I: From: sub@domain.org
1502467417.382433 I: Date: Sun, 12 Aug 2012 12:34:56 +0300
1502467417.382433 I: Subject: submsg
1502467417.382433 I: Content-Type: multipart/alternative; boundary="sub1"
1502467417.382433 I: 
1502467417.382433 I: Sub MIME prologue
1502467417.382433 I: --sub1
1502467417.382433 I: Content-Type: text/x-myown; charset=us-ascii
1502467417.382433 I: Content-Transfer-Encoding: base64
1502467417.382433 I: 
1502467417.382433 I: aGVs
1502467417.382433 I: bG8gd29y
1502467417.382433 I: bGQ=
1502467417.382433 I: 
1502467417.382433 I: --sub1
1502467417.382433 I: Content-Type: text/x-myown; charset=us-ascii
1502467417.382433 I: Content-Transfer-Encoding: base64
1502467417.382433 I: 
1502467417.382433 I:   aGVs    
1502467417.382433 I:  bG8g  
1502467417.382433 I:    d29y     
1502467417.382433 I:     bGQ= 
1502467417.382433 I: 
1502467417.382433 I: 
1502467417.382433 I: --sub1--
1502467417.382433 I: Sub MIME epilogue
1502467417.382433 I: =====> Cyrus::TestCase[681] Beginning tear_down
=====> Instance[1242] stop
=====> Instance[1204] _stop_pid: sending signal 15 to 17901
=====> Util::Wait[77] Waited 0.010088 sec for unknown condition
=====> Instance[919] killing saslauthd 17884
Warning: unable to close filehandle $oldout properly: Bad file descriptor at Cassandane/Instance.pm line 913.
Warning: unable to close filehandle $olderr properly: Bad file descriptor at Cassandane/Instance.pm line 913.
=====> Instance[946] killing notifyd 17886
Warning: unable to close filehandle $oldout properly: Bad file descriptor at Cassandane/Instance.pm line 940.
Warning: unable to close filehandle $olderr properly: Bad file descriptor at Cassandane/Instance.pm line 940.
=====> Instance[919] killing saslauthd 17884
=====> Instance[1270] Cleaning up basedir /builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane/work/160321I26
=====> Cyrus::TestCase[726] ---------- END test_urlauth-binary ----------
=====> Daemon[387] checking for stray processes on ports: 9181
)
1502467417.382433 I: 1.13 OK Completed
1502467417.382521 O: 1.14 URLFETCH (imap://cassandane@127.0.0.1/imaptest/;uid=1/;section=1.1;urlauth=user+cassandane:internal:00eddf5fc9f7b76eabcebd22efc5e825b5ac98d7ad binary)
1502467417.382831 I: * URLFETCH "imap://cassandane@127.0.0.1/imaptest/;uid=1/;section=1.1;urlauth=user+cassandane:internal:00eddf5fc9f7b76eabcebd22efc5e825b5ac98d7ad" (BINARY {11}
1502467417.382831 I: hello world)
1502467417.382831 I: 1.14 OK Completed
1502467417.382854 O: 1.15 URLFETCH (imap://cassandane@127.0.0.1/imaptest/;uid=1/;section=1;urlauth=user+cassandane:internal:0097a0ee6d77055f011bb548f01121c1e544a3194c bodypartstructure)
1502467417.383134 I: * URLFETCH "imap://cassandane@127.0.0.1/imaptest/;uid=1/;section=1;urlauth=user+cassandane:internal:0097a0ee6d77055f011bb548f01121c1e544a3194c" (BODYPARTSTRUCTURE ("MESSAGE" "RFC822" NIL NIL NIL "7BIT" 437 ("Sun, 12 Aug 2012 12:34:56 +0300" "submsg" ((NIL NIL "sub" "domain.org")) ((NIL NIL "sub" "domain.org")) ((NIL NIL "sub" "domain.org")) NIL NIL NIL NIL NIL) (("TEXT" "X-MYOWN" ("CHARSET" "us-ascii") NIL NIL "BASE64" 22 3 NIL NIL NIL NIL)("TEXT" "X-MYOWN" ("CHARSET" "us-ascii") NIL NIL "BASE64" 47 5 NIL NIL NIL NIL) "ALTERNATIVE" ("BOUNDARY" "sub1") NIL NIL NIL) 26 NIL NIL NIL NIL))
1502467417.383134 I: 1.15 OK Completed
1502467417.383185 O: 1.16 URLFETCH (imap://cassandane@127.0.0.1/imaptest/;uid=1/;section=1.1;urlauth=user+cassandane:internal:00eddf5fc9f7b76eabcebd22efc5e825b5ac98d7ad bodypartstructure)
1502467417.383400 I: * URLFETCH "imap://cassandane@127.0.0.1/imaptest/;uid=1/;section=1.1;urlauth=user+cassandane:internal:00eddf5fc9f7b76eabcebd22efc5e825b5ac98d7ad" (BODYPARTSTRUCTURE ("TEXT" "X-MYOWN" ("CHARSET" "us-ascii") NIL NIL "BASE64" 22 3 NIL NIL NIL NIL))
1502467417.383400 I: 1.16 OK Completed
1502467417.383427 O: 1.17 URLFETCH (imap://cassandane@127.0.0.1/imaptest/;uid=2/;section=1;urlauth=user+cassandane:internal:00a91c0f93d4d016bc9df3b8d06b72c788d9bc3992 binary bodypartstructure)
1502467417.383705 I: * URLFETCH "imap://cassandane@127.0.0.1/imaptest/;uid=2/;section=1;urlauth=user+cassandane:internal:00a91c0f93d4d016bc9df3b8d06b72c788d9bc3992" (BODYPARTSTRUCTURE ("MESSAGE" "RFC822" NIL NIL NIL "7BIT" 437 ("Sun, 12 Aug 2012 12:34:56 +0300" "submsg" ((NIL NIL "sub" "domain.org")) ((NIL NIL "sub" "domain.org")) ((NIL NIL "sub" "domain.org")) NIL NIL NIL NIL NIL) (("TEXT" "X-MYOWN" ("CHARSET" "us-ascii") NIL NIL "BASE64" 22 3 NIL NIL NIL NIL)("TEXT" "X-MYOWN" ("CHARSET" "us-ascii") NIL NIL "BASE64" 47 5 NIL NIL NIL NIL) "ALTERNATIVE" ("BOUNDARY" "sub1") NIL NIL NIL) 26 NIL NIL NIL NIL)) (BINARY {437}
1502467417.383705 I: From: sub@domain.org
1502467417.383705 I: Date: Sun, 12 Aug 2012 12:34:56 +0300
1502467417.383705 I: Subject: submsg
1502467417.383705 I: Content-Type: multipart/alternative; boundary="sub1"
1502467417.383705 I:
1502467417.383705 I: Sub MIME prologue
1502467417.383705 I: --sub1
1502467417.383705 I: Content-Type: text/x-myown; charset=us-ascii
1502467417.383705 I: Content-Transfer-Encoding: base64
1502467417.383705 I:
1502467417.383705 I: aGVs
1502467417.383705 I: bG8gd29y
1502467417.383705 I: bGQ=
1502467417.383705 I:
1502467417.383705 I: --sub1
1502467417.383705 I: Content-Type: text/x-myown; charset=us-ascii
1502467417.383705 I: Content-Transfer-Encoding: base64
1502467417.383705 I:
1502467417.383705 I:   aGVs
1502467417.383705 I:  bG8g
1502467417.383705 I:    d29y
1502467417.383705 I:     bGQ=
1502467417.383705 I:
1502467417.383705 I:
1502467417.383705 I: --sub1--
1502467417.383705 I: Sub MIME epilogue
1502467417.383705 I: )
1502467417.383705 I: 1.17 OK Completed
1502467417.383793 O: 1.18 URLFETCH (imap://cassandane@127.0.0.1/imaptest/;uid=1/;section=1.1;urlauth=user+cassandane:internal:00eddf5fc9f7b76eabcebd22efc5e825b5ac98d7ad binary)
1502467417.384019 I: * URLFETCH "imap://cassandane@127.0.0.1/imaptest/;uid=1/;section=1.1;urlauth=user+cassandane:internal:00eddf5fc9f7b76eabcebd22efc5e825b5ac98d7ad" (BINARY {11}
1502467417.384019 I: hello world)
1502467417.384019 I: 1.18 OK Completed
1502467417.384041 O: 1.19 URLFETCH (imap://cassandane@127.0.0.1/imaptest/;uid=2/;section=1.1;urlauth=user+cassandane:internal:00309636bf5df8a7e1466644a89659c2afd5ba240a binary bodypartstructure)
1502467417.384344 I: * URLFETCH "imap://cassandane@127.0.0.1/imaptest/;uid=2/;section=1.1;urlauth=user+cassandane:internal:00309636bf5df8a7e1466644a89659c2afd5ba240a" (BODYPARTSTRUCTURE ("TEXT" "X-MYOWN" ("CHARSET" "us-ascii") NIL NIL "7BIT" 11 0 NIL NIL NIL NIL)) (BINARY {11}
1502467417.384344 I: hello world)
1502467417.384344 I: 1.19 OK Completed
1502467417.384389 O: 1.20 logout
1502467417.384553 I: * BYE LOGOUT received
1502467417.384553 I: 1.20 OK Completed

Test was not successful.
brong commented 7 years ago

As discussed on IRC - upstream ImapTest is testing things incorrectly according to the RFC. I have filed pull requests with them, however they haven't been accepted. You can either suppress the individual tests, or use the version of ImapTest from the cyrusimap github repository on the 'cyrus' branch, which has the patches applied.

On Sat, 12 Aug 2017, at 02:08, Jason Tibbitts wrote:

Fedora very recently updated imaptest from version 20151228 to

  1. This has resulted in three new cassandane failures: Cyrus::ImapTest.append- binary, Cyrus::ImapTest.fetch-binary-mime and Cyrus::ImapTest.urlauth- binary. This is not related to any changes in cyrus 3.0.3 as it also happens with 3.0.2. In the interests of getting the 3.0.3 fixes out I will exclude these tests but it would be good to know what's going on under the hood.> Note that this is with a cassandane snapshot taken on June 22. If update to a current cassandane snapshot, those three tests still fail but as expected there are a pile of other failures as well.

Time: 93 wallclock secs ( 0.53 usr 0.07 sys + 140.53 cusr 125.29 csys = 266.42 CPU) !!!FAILURES!!! Test Results: Run: 660, Failures: 3, Errors: 0 There were 3 failures: 1) test_append- binary(Cassandane::Cyrus::ImapTest) Boolean assertion failed at /usr/share/perl5/vendor_perl/Test/Unit/Exception.pm line 13. Test::Un- it::Exception::throw_new(Test::Unit::Failure=HASH(0x56365c9fb678), "- package", "Cassandane::Cyrus::ImapTest", "-file", "Cassandane/Cyrus/ImapTest.pm", "-line", 186, "-object", ...) called at /usr/share/perl5/vendor_perl/Test/Unit/Assert.pm line 85 Test::Uni- t::Assert::do_assertion(Cassandane::Cyrus::ImapTest=HASH(0x56365ad574- 88), Test::Unit::Assertion::Boolean=SCALAR(0x56365c9f0718), "Cassandane::Cyrus::ImapTest", "Cassandane/Cyrus/ImapTest.pm", 186) called at /usr/share/perl5/vendor_perl/Test/Unit/Assert.pm line 19 Te- st::Unit::Assert::assert(Cassandane::Cyrus::ImapTest=HASH(0x56365ad57- 488), 0) called at Cassandane/Cyrus/ImapTest.pm line 186 Cassandane::- Cyrus::ImapTest::run_test(Cassandane::Cyrus::ImapTest=HASH(0x56365ad5- 7488)) called at /usr/share/perl5/vendor_perl/Test/Unit/TestCase.pm line 61 Test::Unit::TestCase::run_bare(Cassandane::Cyrus::ImapTest=HA- SH(0x56365ad57488)) called at /usr/share/perl5/vendor_perl/Test/Unit/Result.pm line 103 Test::Unit::Result::ANON() called at /usr/share/perl5/vendor_perl/Test/Unit/Result.pm line 119 Test::Unit:- :Result::run_protected(Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Cyrus::ImapTest=HASH(0x56365ad57488), CODE(0x56365c9e8ae0)) called at /usr/share/perl5/vendor_perl/Test/Unit/Result.pm line 107 Test::Unit::Result::run(Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Cyrus::ImapTest=HASH(0x56365ad57488)) called at /usr/share/perl5/vendor_perl/Test/Unit/TestCase.pm line 51 Test::Unit- ::TestCase::run(Cassandane::Cyrus::ImapTest=HASH(0x56365ad57488), Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Unit::RunnerPretty=HASH(0x5636594ec730)) called at /usr/share/perl5/vendor_perl/Test/Unit/TestSuite.pm line 278 Test::Un- it::TestSuite::run(Test::Unit::TestSuite=HASH(0x56365ad1a978), Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Unit::RunnerPretty=HASH(0x5636594ec730)) called at Cassandane/Unit/TestPlan.pm line 749 Cassandane::Unit::TestPlan::_run- _workitem(Cassandane::Unit::TestPlan=HASH(0x563656c05c50), HASH(0x56365c6e5760), Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Unit::RunnerPretty=HASH(0x5636594ec730), 0) called at Cassandane/Unit/TestPlan.pm line 865 Cassandane::Unit::TestPlan::ANON(HASH(0x56365c6e5760)) called at Cassandane/Unit/TestPlan.pm line 207 Cassandane::Unit::Worker::_mainl- oop(Cassandane::Unit::Worker=HASH(0x56365c0f1c00)) called at Cassandane/Unit/TestPlan.pm line 167 Cassandane::Unit::Worker::start(- Cassandane::Unit::Worker=HASH(0x56365c0f1c00)) called at Cassandane/Unit/TestPlan.pm line 312 Cassandane::Unit::WorkerPool::st- art(Cassandane::Unit::WorkerPool=HASH(0x5636594f3fa8)) called at Cassandane/Unit/TestPlan.pm line 869 Cassandane::Unit::TestPlan::run(- Cassandane::Unit::TestPlan=HASH(0x563656c05c50), Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Unit::RunnerPretty=HASH(0x5636594ec730)) called at /usr/share/perl5/vendor_perl/Test/Unit/TestRunner.pm line 54 Test::Un- it::TestRunner::do_run(Cassandane::Unit::RunnerPretty=HASH(0x5636594e- c730), Cassandane::Unit::TestPlan=HASH(0x563656c05c50), 0) called at ./testrunner.pl line 126 main::ANON(Cassandane::Unit::TestPlan=HASH(0x563656c05c50), GLOB(0x563656c05c80)) called at ./testrunner.pl line 300 Annotations: =====> Cyrus::TestCase[567] ---------- BEGIN test_append-binary ---------- =====> Instance[1004] start main instance for test test_append-binary: basedir /builddir/build/BUILD/cyrus-imapd- 3.0.3/cassandane/work/160321H18 =====> Instance[455] Found binary cyr_buildinfo in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3- 1.fc27.x86_64/usr/sbin =====> Instance[455] Found binary cyr_buildinfo in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3-1.fc27.x86_64/usr/sbin =====> Instance[1505] Running: "/builddir/build/BUILDROOT/cyrus-imapd-3.0.3- 1.fc27.x86_64/usr/sbin/cyr_buildinfo" "-C" "/builddir/build/BUILD/cyrus-imapd- 3.0.3/cassandane/work/160321H18/conf/imapd.conf" =====> Instance[455] Found binary ctl_cyrusdb in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3- 1.fc27.x86_64/usr/sbin =====> Instance[455] Found binary imapd in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3- 1.fc27.x86_64/usr/libexec/cyrus-imapd =====> Instance[916] started saslauthd for /builddir/build/BUILD/cyrus-imapd- 3.0.3/cassandane/work/160321H18 as 16752 =====> Instance[1759] opening socket /builddir/build/BUILD/cyrus-imapd- 3.0.3/cassandane/work/160321H18/run/mux =====> Instance[943] started notifyd for /builddir/build/BUILD/cyrus-imapd- 3.0.3/cassandane/work/160321H18 as 16753 =====> Instance[455] Found binary master in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3- 1.fc27.x86_64/usr/libexec/cyrus-imapd =====> Instance[1505] Running: "/builddir/build/BUILDROOT/cyrus-imapd-3.0.3- 1.fc27.x86_64/usr/libexec/cyrus-imapd/master" "-C" "/builddir/build/BUILD/cyrus-imapd- 3.0.3/cassandane/work/160321H18/conf/imapd.conf" "-l" "255" "-p" "/builddir/build/BUILD/cyrus-imapd- 3.0.3/cassandane/work/160321H18/run/master.pid" "-d" "-M" "/builddir/build/BUILD/cyrus-imapd- 3.0.3/cassandane/work/160321H18/conf/cyrus.conf" =====> Instance[868] _start_master: waiting for PID file =====> Instance[871] _start_master: PID file present and correct =====> Instance[883] _start_master: PID waiting for services =====> Daemon[358] is_listening: service imap is listening on 127.0.0.1:9173 =====> Util::Wait[77] Waited 0.034991 sec for 127.0.0.1:9173 to be in LISTEN state =====> Instance[894] _start_master: all services listening =====> Instance[970] create user cassandane =====> Util::Socket[63] create_client_socket INET host=127.0.0.1 port=9173 =====> Instance[1774] authdaemon connection: admin testpw imap =====> Instance[1035] started main instance for test test_append-binary: cyrus version 3.0.3-1.fc27 Fedora =====> Cyrus::TestCase[574] Calling test function =====> Instance[1505] Running: "/builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane- 6d33978ebb0055be41a5cb8e52958e100401bf26/imaptest/src/imaptest" "host=127.0.0.1" "port=9173" "user=cassandane" "pass=testpw" "rawlog" "test=/builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane- 6d33978ebb0055be41a5cb8e52958e100401bf26/imaptest/src/tests/append- binary" =====> Instance[1774] authdaemon connection: cassandane testpw imap =====> Cyrus::ImapTest[170] Error: cassandane[1]: BAD reply for command: fetch 1 (binary.size[text] binary.peek[text]): 1.8 BAD Invalid binary section =====> Cyrus::ImapTest[170] Test append- binary command 4/5 (line 35) =====> Cyrus::ImapTest[170] - failed: Expected tagged reply 'ok', got 'BAD Invalid binary section' =====> Cyrus::ImapTest[170] - Command (tag 1.8): fetch 1 (binary.size[text] binary.peek[text]) =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] Test append-binary command 4/5 (line 35) =====> Cyrus::ImapTest[170] - failed: Missing 1 untagged replies (0 mismatches) =====> Cyrus::ImapTest[170] - first unexpanded: 1 fetch ($!unordered=2 binary.size[text] 7 binary[text] {0} =====> Cyrus::ImapTest[170] ) =====> Cyrus::ImapTest[170] - first expanded: 1 fetch ( binary.size[text] 7 binary[text] {0} =====> Cyrus::ImapTest[170] ) =====> Cyrus::ImapTest[170] - best match: 1 FETCH (BODY[TEXT] "AC3kCg0KCg== =====> Cyrus::ImapTest[170] ") =====> Cyrus::ImapTest[170] - Command: fetch 1 (binary.size[text] binary.peek[text]) =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] Test append-binary command 5/5 (line 0) =====> Cyrus::ImapTest[170] - failed: Unexpected disconnection =====> Cyrus::ImapTest[170] - Command: logout =====> Cyrus::ImapTest[170] =====> Cyrus::TestCase[681] Beginning tear_down =====> Instance[1242] stop =====> Instance[1204] _stop_pid: sending signal 15 to 16757 =====> Util::Wait[77] Waited 0.010088 sec for unknown condition =====> Instance[919] killing saslauthd 16752 Warning: unable to close filehandle $oldout properly: Bad file descriptor at Cassandane/Instance.pm line 913. Warning: unable to close filehandle $olderr properly: Bad file descriptor at Cassandane/Instance.pm line 913. =====> Instance[946] killing notifyd 16753 Warning: unable to close filehandle $oldout properly: Bad file descriptor at Cassandane/Instance.pm line 940. Warning: unable to close filehandle $olderr properly: Bad file descriptor at Cassandane/Instance.pm line 940. =====> Instance[919] killing saslauthd 16752 =====> Instance[1270] Cleaning up basedir /builddir/build/BUILD/cyrus-imapd- 3.0.3/cassandane/work/160321H18 =====> Cyrus::TestCase[726] ---------- END test_append-binary ---------- =====> Daemon[387] checking for stray processes on ports: 9173 ============> rawlog.1 <============= 1502467414.077941 I: OK [CAPABILITY IMAP4rev1 LITERAL+ ID ENABLE AUTH=PLAIN AUTH=LOGIN SASL-IR] 127.0.0.1 Cyrus IMAP 3.0.3-1.fc27 Fedora server ready 1502467414.077962 O: 1.1 LOGIN "cassandane" "testpw" 1502467414.078357 I: 1.1 OK [CAPABILITY IMAP4rev1 LITERAL+ ID ENABLE ACL RIGHTS=kxten QUOTA MAILBOX-REFERRALS NAMESPACE UIDPLUS NO_ATOMIC_RENAME UNSELECT CHILDREN MULTIAPPEND BINARY CATENATE CONDSTORE ESEARCH SEARCH=FUZZY SORT SORT=MODSEQ SORT=DISPLAY SORT=UID THREAD=ORDEREDSUBJECT THREAD=REFERENCES THREAD=REFS ANNOTATEMORE ANNOTATE-EXPERIMENT- 1 METADATA LIST-EXTENDED LIST-STATUS LIST-MYRIGHTS LIST-METADATA WITHIN QRESYNC SCAN XLIST XMOVE MOVE SPECIAL-USE CREATE-SPECIAL-USE DIGEST=SHA1 X-REPLICATION URLAUTH URLAUTH=BINARY LOGINDISABLED COMPRESS=DEFLATE X-QUOTA=STORAGE X-QUOTA=MESSAGE X-QUOTA=X-ANNOTATION- STORAGE X-QUOTA=X-NUM-FOLDERS IDLE] User logged in SESSIONID=<160321H18-16763-1502467414-2- 1533617781188931385> 1502467414.078394 O: 1.2 LIST "" "imaptest" 1502467414.078397 O: 1.3 LSUB "" "imaptest" 1502467414.078577 I: 1.2 OK Completed (0.000 secs) 1502467414.118833 I: 1.3 OK Completed (0.000 secs) 1502467414.118869 O: 1.4 CREATE "imaptest" 1502467414.120058 I: 1.4 OK Completed 1502467414.120094 O: 1.5 append imaptest ~{165+} 1502467414.120094 O: From: user@domain.org 1502467414.120094 O: Date: Sat, 24 Mar 2007 23:00:00 +0200 1502467414.120094 O: Mime-Version: 1.0 1502467414.120094 O: Content-Type: application/octet-stream 1502467414.120094 O: Content-Transfer-Encoding: binary 1502467414.120094 O: 1502467414.120094 O: -� 1502467414.120094 O: 1502467414.120094 O: 1502467414.120102 O: 1502467414.121009 I: 1.5 OK [APPENDUID 1502467415 1] Completed 1502467414.121035 O: 1.6 select imaptest 1502467414.121242 I: 1 EXISTS 1502467414.121242 I: 1 RECENT 1502467414.121242 I: FLAGS (\Answered \Flagged \Draft \Deleted \Seen) 1502467414.121242 I: OK [PERMANENTFLAGS (\Answered \Flagged \Draft \Deleted \Seen *)] Ok 1502467414.121242 I: OK [UNSEEN 1] Ok 1502467414.121242 I: OK [UIDVALIDITY 1502467415] Ok 1502467414.121242 I: OK [UIDNEXT 2] Ok 1502467414.121242 I: OK [HIGHESTMODSEQ 2] Ok 1502467414.121242 I: OK [URLMECH INTERNAL] Ok 1502467414.121242 I: OK [ANNOTATIONS 65536] Ok 1502467414.121242 I: 1.6 OK [READ-WRITE] Completed 1502467414.121277 O: 1.7 fetch 1 (body.peek[text]) 1502467414.121465 I: 1 FETCH (BODY[TEXT] {14} 1502467414.121465 I: AC3kCg0KCg== 1502467414.121465 I: ) 1502467414.121465 I: 1.7 OK Completed (0.000 sec) 1502467414.121489 O: 1.8 fetch 1 (binary.size[text] binary.peek[text]) 1502467414.121569 I: 1.8 BAD Invalid binary section 2) test_fetch-binary- mime(Cassandane::Cyrus::ImapTest) Boolean assertion failed at /usr/share/perl5/vendor_perl/Test/Unit/Exception.pm line 13. Test::Un- it::Exception::throw_new(Test::Unit::Failure=HASH(0x56365c7e5578), "- package", "Cassandane::Cyrus::ImapTest", "-file", "Cassandane/Cyrus/ImapTest.pm", "-line", 186, "-object", ...) called at /usr/share/perl5/vendor_perl/Test/Unit/Assert.pm line 85 Test::Uni- t::Assert::do_assertion(Cassandane::Cyrus::ImapTest=HASH(0x56365ad50c- 08), Test::Unit::Assertion::Boolean=SCALAR(0x56365c7cb0f0), "Cassandane::Cyrus::ImapTest", "Cassandane/Cyrus/ImapTest.pm", 186) called at /usr/share/perl5/vendor_perl/Test/Unit/Assert.pm line 19 Te- st::Unit::Assert::assert(Cassandane::Cyrus::ImapTest=HASH(0x56365ad50- c08), 0) called at Cassandane/Cyrus/ImapTest.pm line 186 Cassandane::- Cyrus::ImapTest::run_test(Cassandane::Cyrus::ImapTest=HASH(0x56365ad5- 0c08)) called at /usr/share/perl5/vendor_perl/Test/Unit/TestCase.pm line 61 Test::Unit::TestCase::run_bare(Cassandane::Cyrus::ImapTest=HA- SH(0x56365ad50c08)) called at /usr/share/perl5/vendor_perl/Test/Unit/Result.pm line 103 Test::Unit::Result::ANON() called at /usr/share/perl5/vendor_perl/Test/Unit/Result.pm line 119 Test::Unit:- :Result::run_protected(Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Cyrus::ImapTest=HASH(0x56365ad50c08), CODE(0x56365c6db208)) called at /usr/share/perl5/vendor_perl/Test/Unit/Result.pm line 107 Test::Unit::Result::run(Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Cyrus::ImapTest=HASH(0x56365ad50c08)) called at /usr/share/perl5/vendor_perl/Test/Unit/TestCase.pm line 51 Test::Unit- ::TestCase::run(Cassandane::Cyrus::ImapTest=HASH(0x56365ad50c08), Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Unit::RunnerPretty=HASH(0x5636594ec730)) called at /usr/share/perl5/vendor_perl/Test/Unit/TestSuite.pm line 278 Test::Un- it::TestSuite::run(Test::Unit::TestSuite=HASH(0x56365ad1a978), Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Unit::RunnerPretty=HASH(0x5636594ec730)) called at Cassandane/Unit/TestPlan.pm line 749 Cassandane::Unit::TestPlan::_run- _workitem(Cassandane::Unit::TestPlan=HASH(0x563656c05c50), HASH(0x56365c7ccd10), Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Unit::RunnerPretty=HASH(0x5636594ec730), 0) called at Cassandane/Unit/TestPlan.pm line 865 Cassandane::Unit::TestPlan::ANON(HASH(0x56365c7ccd10)) called at Cassandane/Unit/TestPlan.pm line 207 Cassandane::Unit::Worker::_mainl- oop(Cassandane::Unit::Worker=HASH(0x56365a6a6858)) called at Cassandane/Unit/TestPlan.pm line 167 Cassandane::Unit::Worker::start(- Cassandane::Unit::Worker=HASH(0x56365a6a6858)) called at Cassandane/Unit/TestPlan.pm line 312 Cassandane::Unit::WorkerPool::st- art(Cassandane::Unit::WorkerPool=HASH(0x5636594f3fa8)) called at Cassandane/Unit/TestPlan.pm line 869 Cassandane::Unit::TestPlan::run(- Cassandane::Unit::TestPlan=HASH(0x563656c05c50), Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Unit::RunnerPretty=HASH(0x5636594ec730)) called at /usr/share/perl5/vendor_perl/Test/Unit/TestRunner.pm line 54 Test::Un- it::TestRunner::do_run(Cassandane::Unit::RunnerPretty=HASH(0x5636594e- c730), Cassandane::Unit::TestPlan=HASH(0x563656c05c50), 0) called at ./testrunner.pl line 126 main::ANON(Cassandane::Unit::TestPlan=HASH(0x563656c05c50), GLOB(0x563656c05c80)) called at ./testrunner.pl line 300 Annotations: =====> Cyrus::TestCase[567] ---------- BEGIN test_fetch-binary-mime ---------- =====> Instance[1004] start main instance for test test_fetch-binary- mime: basedir /builddir/build/BUILD/cyrus-imapd- 3.0.3/cassandane/work/160321K18 =====> Instance[455] Found binary cyr_buildinfo in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3- 1.fc27.x86_64/usr/sbin =====> Instance[455] Found binary cyr_buildinfo in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3-1.fc27.x86_64/usr/sbin =====> Instance[1505] Running: "/builddir/build/BUILDROOT/cyrus-imapd-3.0.3- 1.fc27.x86_64/usr/sbin/cyr_buildinfo" "-C" "/builddir/build/BUILD/cyrus-imapd- 3.0.3/cassandane/work/160321K18/conf/imapd.conf" =====> Instance[455] Found binary ctl_cyrusdb in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3- 1.fc27.x86_64/usr/sbin =====> Instance[455] Found binary imapd in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3- 1.fc27.x86_64/usr/libexec/cyrus-imapd =====> Instance[916] started saslauthd for /builddir/build/BUILD/cyrus-imapd- 3.0.3/cassandane/work/160321K18 as 16946 =====> Instance[1759] opening socket /builddir/build/BUILD/cyrus-imapd- 3.0.3/cassandane/work/160321K18/run/mux =====> Instance[943] started notifyd for /builddir/build/BUILD/cyrus-imapd- 3.0.3/cassandane/work/160321K18 as 16947 =====> Instance[455] Found binary master in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3- 1.fc27.x86_64/usr/libexec/cyrus-imapd =====> Instance[1505] Running: "/builddir/build/BUILDROOT/cyrus-imapd-3.0.3- 1.fc27.x86_64/usr/libexec/cyrus-imapd/master" "-C" "/builddir/build/BUILD/cyrus-imapd- 3.0.3/cassandane/work/160321K18/conf/imapd.conf" "-l" "255" "-p" "/builddir/build/BUILD/cyrus-imapd- 3.0.3/cassandane/work/160321K18/run/master.pid" "-d" "-M" "/builddir/build/BUILD/cyrus-imapd- 3.0.3/cassandane/work/160321K18/conf/cyrus.conf" =====> Instance[868] _start_master: waiting for PID file =====> Instance[871] _start_master: PID file present and correct =====> Instance[883] _start_master: PID waiting for services =====> Daemon[358] is_listening: service imap is listening on 127.0.0.1:9204 =====> Util::Wait[77] Waited 0.03993 sec for 127.0.0.1:9204 to be in LISTEN state =====> Instance[894] _start_master: all services listening =====> Instance[970] create user cassandane =====> Util::Socket[63] create_client_socket INET host=127.0.0.1 port=9204 =====> Instance[1774] authdaemon connection: admin testpw imap =====> Instance[1035] started main instance for test test_fetch-binary-mime: cyrus version 3.0.3-1.fc27 Fedora =====> Cyrus::TestCase[574] Calling test function =====> Instance[1505] Running: "/builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane- 6d33978ebb0055be41a5cb8e52958e100401bf26/imaptest/src/imaptest" "host=127.0.0.1" "port=9204" "user=cassandane" "pass=testpw" "rawlog" "test=/builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane- 6d33978ebb0055be41a5cb8e52958e100401bf26/imaptest/src/tests/fetch- binary-mime" =====> Instance[1774] authdaemon connection: cassandane testpw imap =====> Cyrus::ImapTest[170] Error: cassandane[1]: BAD reply for command: fetch 1 (binary.size[text]): 1.9 BAD Invalid binary section =====> Cyrus::ImapTest[170] Test fetch-binary-mime command 3/29 (line 52) =====> Cyrus::ImapTest[170] - failed: Expected tagged reply 'ok', got 'BAD Invalid binary section' =====> Cyrus::ImapTest[170] - Command (tag 1.9): fetch 1 (binary.size[text]) =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] *** Test fetch-binary- mime command 3/29 (line 52) =====> Cyrus::ImapTest[170] - failed: Missing 1 untagged replies (0 mismatches) =====> Cyrus::ImapTest[170]

  • first unexpanded: 1 fetch ($!unordered=2 binary.size[text] 466) =====> Cyrus::ImapTest[170] - first expanded: 1 fetch ( binary.size[text] 466) =====> Cyrus::ImapTest[170] - best match: =====> Cyrus::ImapTest[170] - Command: fetch 1 (binary.size[text]) =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] *** Test fetch-binary- mime command 4/29 (line 55) =====> Cyrus::ImapTest[170] - failed: Unexpected disconnection =====> Cyrus::ImapTest[170] - Command: fetch 1 (binary.peek[text]) =====> Cyrus::ImapTest[170] =====> Cyrus::TestCase[681] Beginning tear_down =====> Instance[1242] stop =====> Instance[1204] _stop_pid: sending signal 15 to 16959 =====> Util::Wait[77] Waited 0.010085 sec for unknown condition =====> Instance[919] killing saslauthd 16946 Warning: unable to close filehandle $oldout properly: Bad file descriptor at Cassandane/Instance.pm line 913. Warning: unable to close filehandle $olderr properly: Bad file descriptor at Cassandane/Instance.pm line
    1. =====> Instance[946] killing notifyd 16947 Warning: unable to close filehandle $oldout properly: Bad file descriptor at Cassandane/Instance.pm line 940. Warning: unable to close filehandle $olderr properly: Bad file descriptor at Cassandane/Instance.pm line
    2. =====> Instance[919] killing saslauthd 16946 =====> Instance[1270] Cleaning up basedir /builddir/build/BUILD/cyrus-imapd- 3.0.3/cassandane/work/160321K18 =====> Cyrus::TestCase[726] ---------- END test_fetch-binary-mime ---------- =====> Daemon[387] checking for stray processes on ports: 9204 ============> rawlog.1 <============= 1502467414.998656 I: OK [CAPABILITY IMAP4rev1 LITERAL+ ID ENABLE AUTH=PLAIN AUTH=LOGIN SASL-IR] 127.0.0.1 Cyrus IMAP 3.0.3-1.fc27 Fedora server ready 1502467414.998682 O: 1.1 LOGIN "cassandane" "testpw" 1502467414.999050 I: 1.1 OK [CAPABILITY IMAP4rev1 LITERAL+ ID ENABLE ACL RIGHTS=kxten QUOTA MAILBOX-REFERRALS NAMESPACE UIDPLUS NO_ATOMIC_RENAME UNSELECT CHILDREN MULTIAPPEND BINARY CATENATE CONDSTORE ESEARCH SEARCH=FUZZY SORT SORT=MODSEQ SORT=DISPLAY SORT=UID THREAD=ORDEREDSUBJECT THREAD=REFERENCES THREAD=REFS ANNOTATEMORE ANNOTATE-EXPERIMENT- 1 METADATA LIST-EXTENDED LIST-STATUS LIST-MYRIGHTS LIST-METADATA WITHIN QRESYNC SCAN XLIST XMOVE MOVE SPECIAL-USE CREATE-SPECIAL-USE DIGEST=SHA1 X-REPLICATION URLAUTH URLAUTH=BINARY LOGINDISABLED COMPRESS=DEFLATE X-QUOTA=STORAGE X-QUOTA=MESSAGE X-QUOTA=X-ANNOTATION- STORAGE X-QUOTA=X-NUM-FOLDERS IDLE] User logged in SESSIONID=<160321K18-16974-1502467414-2- 4187343474950476330> 1502467414.999103 O: 1.2 LIST "" "imaptest" 1502467414.999107 O: 1.3 LSUB "" "imaptest" 1502467414.999258 I: 1.2 OK Completed (0.000 secs) 1502467415.039848 I: 1.3 OK Completed (0.000 secs) 1502467415.039882 O: 1.4 CREATE "imaptest" 1502467415.041155 I: 1.4 OK Completed 1502467415.041249 O: 1.5 APPEND "imaptest" "22-Feb- 2008 17:06:23 -0600" {602+} 1502467415.041259 O: From: user@domain.org 1502467415.041259 O: Date: Sat, 24 Mar 2007 23:00:00 +0200 1502467415.041259 O: Mime-Version: 1.0 1502467415.041259 O: Content- Type: multipart/mixed; boundary="foo 1502467415.041259 O: bar" 1502467415.041259 O: 1502467415.041259 O: Root MIME prologue 1502467415.041259 O: 1502467415.041259 O: --foo bar 1502467415.041259 O: Content-Type: text/x-myown; charset=us-ascii 1502467415.041259 O: 1502467415.041259 O: hello 1502467415.041259 O: 1502467415.041259 O: --foo bar 1502467415.041259 O: Content-Type: message/rfc822 1502467415.041259 O: 1502467415.041259 O: From: sub@domain.org 1502467415.041259 O: Date: Sun, 12 Aug 2012 12:34:56 +0300 1502467415.041259 O: Subject: submsg 1502467415.041259 O: Content- Type: multipart/alternative; boundary="sub1" 1502467415.041259 O: 1502467415.041259 O: Sub MIME prologue 1502467415.041259 O: --sub1 1502467415.041259 O: Content-Type: text/html 1502467415.041259 O: 1502467415.041259 O:

      Hello world 1502467415.041259 O: 1502467415.041259 O: --sub1 1502467415.041259 O: Content-Type: text/plain 1502467415.041259 O: 1502467415.041259 O: Hello another world 1502467415.041259 O: 1502467415.041259 O: --sub1-- 1502467415.041259 O: Sub MIME epilogue 1502467415.041259 O: 1502467415.041259 O: --foo bar-- 1502467415.041259 O: Root MIME epilogue 1502467415.041272 O: 1502467415.042198 I: 1.5 OK [APPENDUID 1502467416 1] Completed 1502467415.042216 O: 1.6 SELECT "imaptest" 1502467415.042427 I: 1 EXISTS 1502467415.042427 I: 1 RECENT 1502467415.042427 I: FLAGS (\Answered \Flagged \Draft \Deleted \Seen) 1502467415.042427 I: OK [PERMANENTFLAGS (\Answered \Flagged \Draft \Deleted \Seen *)] Ok 1502467415.042427 I: OK [UNSEEN 1] Ok 1502467415.042427 I: OK [UIDVALIDITY 1502467416] Ok 1502467415.042427 I: OK [UIDNEXT 2] Ok 1502467415.042427 I: OK [HIGHESTMODSEQ 2] Ok 1502467415.042427 I: OK [URLMECH INTERNAL] Ok 1502467415.042427 I: OK [ANNOTATIONS 65536] Ok 1502467415.042427 I: 1.6 OK [READ-WRITE] Completed 1502467415.042457 O: 1.7 fetch 1 (binary.peek[]) 1502467415.042654 I: 1 FETCH (BINARY[] {602} 1502467415.042654 I: From: user@domain.org 1502467415.042654 I: Date: Sat, 24 Mar 2007 23:00:00 +0200 1502467415.042654 I: Mime-Version: 1.0 1502467415.042654 I: Content-Type: multipart/mixed; boundary="foo 1502467415.042654 I: bar" 1502467415.042654 I: 1502467415.042654 I: Root MIME prologue 1502467415.042654 I: 1502467415.042654 I: --foo bar 1502467415.042654 I: Content-Type: text/x-myown; charset=us-ascii 1502467415.042654 I: 1502467415.042654 I: hello 1502467415.042654 I: 1502467415.042654 I: --foo bar 1502467415.042654 I: Content-Type: message/rfc822 1502467415.042654 I: 1502467415.042654 I: From: sub@domain.org 1502467415.042654 I: Date: Sun, 12 Aug 2012 12:34:56 +0300 1502467415.042654 I: Subject: submsg 1502467415.042654 I: Content- Type: multipart/alternative; boundary="sub1" 1502467415.042654 I: 1502467415.042654 I: Sub MIME prologue 1502467415.042654 I: --sub1 1502467415.042654 I: Content-Type: text/html 1502467415.042654 I: 1502467415.042654 I:

      Hello world

      1502467415.042654 I: 1502467415.042654 I: --sub1 1502467415.042654 I: Content-Type: text/plain 1502467415.042654 I: 1502467415.042654 I: Hello another world 1502467415.042654 I: 1502467415.042654 I: --sub1-- 1502467415.042654 I: Sub MIME epilogue 1502467415.042654 I: 1502467415.042654 I: --foo bar-- 1502467415.042654 I: Root MIME epilogue 1502467415.042654 I: ) 1502467415.042654 I: 1.7 OK Completed (0.000 sec) 1502467415.042691 O: 1.8 fetch 1 (binary.size[]) 1502467415.042876 I: * 1 FETCH (BINARY.SIZE[] 602) 1502467415.042876 I: 1.8 OK Completed (0.000 sec) 1502467415.042887 O: 1.9 fetch 1 (binary.size[text]) 1502467415.042968 I: 1.9 BAD Invalid binary section 3) test_urlauth-binary(Cassandane::Cyrus::ImapTest) Boolean assertion failed at /usr/share/perl5/vendor_perl/Test/Unit/Exception.pm line 13. Test::Un- it::Exception::throw_new(Test::Unit::Failure=HASH(0x56365c7ad708), "- package", "Cassandane::Cyrus::ImapTest", "-file", "Cassandane/Cyrus/ImapTest.pm", "-line", 186, "-object", ...) called at /usr/share/perl5/vendor_perl/Test/Unit/Assert.pm line 85 Test::Uni- t::Assert::do_assertion(Cassandane::Cyrus::ImapTest=HASH(0x56365ad1e9- f0), Test::Unit::Assertion::Boolean=SCALAR(0x56365c7dd1d8), "Cassandane::Cyrus::ImapTest", "Cassandane/Cyrus/ImapTest.pm", 186) called at /usr/share/perl5/vendor_perl/Test/Unit/Assert.pm line 19 Te- st::Unit::Assert::assert(Cassandane::Cyrus::ImapTest=HASH(0x56365ad1e- 9f0), 0) called at Cassandane/Cyrus/ImapTest.pm line 186 Cassandane::- Cyrus::ImapTest::run_test(Cassandane::Cyrus::ImapTest=HASH(0x56365ad1- e9f0)) called at /usr/share/perl5/vendor_perl/Test/Unit/TestCase.pm line 61 Test::Unit::TestCase::run_bare(Cassandane::Cyrus::ImapTest=HA- SH(0x56365ad1e9f0)) called at /usr/share/perl5/vendor_perl/Test/Unit/Result.pm line 103 Test::Unit::Result::ANON() called at /usr/share/perl5/vendor_perl/Test/Unit/Result.pm line 119 Test::Unit:- :Result::run_protected(Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Cyrus::ImapTest=HASH(0x56365ad1e9f0), CODE(0x56365a8ca1f0)) called at /usr/share/perl5/vendor_perl/Test/Unit/Result.pm line 107 Test::Unit::Result::run(Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Cyrus::ImapTest=HASH(0x56365ad1e9f0)) called at /usr/share/perl5/vendor_perl/Test/Unit/TestCase.pm line 51 Test::Unit- ::TestCase::run(Cassandane::Cyrus::ImapTest=HASH(0x56365ad1e9f0), Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Unit::RunnerPretty=HASH(0x5636594ec730)) called at /usr/share/perl5/vendor_perl/Test/Unit/TestSuite.pm line 278 Test::Un- it::TestSuite::run(Test::Unit::TestSuite=HASH(0x56365ad1a978), Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Unit::RunnerPretty=HASH(0x5636594ec730)) called at Cassandane/Unit/TestPlan.pm line 749 Cassandane::Unit::TestPlan::_run- _workitem(Cassandane::Unit::TestPlan=HASH(0x563656c05c50), HASH(0x56365c710478), Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Unit::RunnerPretty=HASH(0x5636594ec730), 0) called at Cassandane/Unit/TestPlan.pm line 865 Cassandane::Unit::TestPlan::ANON(HASH(0x56365c710478)) called at Cassandane/Unit/TestPlan.pm line 207 Cassandane::Unit::Worker::_mainl- oop(Cassandane::Unit::Worker=HASH(0x56365c103670)) called at Cassandane/Unit/TestPlan.pm line 167 Cassandane::Unit::Worker::start(- Cassandane::Unit::Worker=HASH(0x56365c103670)) called at Cassandane/Unit/TestPlan.pm line 312 Cassandane::Unit::WorkerPool::st- art(Cassandane::Unit::WorkerPool=HASH(0x5636594f3fa8)) called at Cassandane/Unit/TestPlan.pm line 869 Cassandane::Unit::TestPlan::run(- Cassandane::Unit::TestPlan=HASH(0x563656c05c50), Test::Unit::Result=HASH(0x5636594f3c00), Cassandane::Unit::RunnerPretty=HASH(0x5636594ec730)) called at /usr/share/perl5/vendor_perl/Test/Unit/TestRunner.pm line 54 Test::Un- it::TestRunner::do_run(Cassandane::Unit::RunnerPretty=HASH(0x5636594e- c730), Cassandane::Unit::TestPlan=HASH(0x563656c05c50), 0) called at ./testrunner.pl line 126 main::ANON(Cassandane::Unit::TestPlan=HASH(0x563656c05c50), GLOB(0x563656c05c80)) called at ./testrunner.pl line 300 Annotations: =====> Cyrus::TestCase[567] ---------- BEGIN test_urlauth-binary ---------- =====> Instance[1004] start main instance for test test_urlauth- binary: basedir /builddir/build/BUILD/cyrus-imapd- 3.0.3/cassandane/work/160321I26 =====> Instance[455] Found binary cyr_buildinfo in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3- 1.fc27.x86_64/usr/sbin =====> Instance[455] Found binary cyr_buildinfo in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3-1.fc27.x86_64/usr/sbin =====> Instance[1505] Running: "/builddir/build/BUILDROOT/cyrus-imapd-3.0.3- 1.fc27.x86_64/usr/sbin/cyr_buildinfo" "-C" "/builddir/build/BUILD/cyrus-imapd- 3.0.3/cassandane/work/160321I26/conf/imapd.conf" =====> Instance[455] Found binary ctl_cyrusdb in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3- 1.fc27.x86_64/usr/sbin =====> Instance[455] Found binary imapd in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3- 1.fc27.x86_64/usr/libexec/cyrus-imapd =====> Instance[916] started saslauthd for /builddir/build/BUILD/cyrus-imapd- 3.0.3/cassandane/work/160321I26 as 17884 =====> Instance[1759] opening socket /builddir/build/BUILD/cyrus-imapd- 3.0.3/cassandane/work/160321I26/run/mux =====> Instance[943] started notifyd for /builddir/build/BUILD/cyrus-imapd- 3.0.3/cassandane/work/160321I26 as 17886 =====> Instance[455] Found binary master in /builddir/build/BUILDROOT/cyrus-imapd-3.0.3- 1.fc27.x86_64/usr/libexec/cyrus-imapd =====> Instance[1505] Running: "/builddir/build/BUILDROOT/cyrus-imapd-3.0.3- 1.fc27.x86_64/usr/libexec/cyrus-imapd/master" "-C" "/builddir/build/BUILD/cyrus-imapd- 3.0.3/cassandane/work/160321I26/conf/imapd.conf" "-l" "255" "-p" "/builddir/build/BUILD/cyrus-imapd- 3.0.3/cassandane/work/160321I26/run/master.pid" "-d" "-M" "/builddir/build/BUILD/cyrus-imapd- 3.0.3/cassandane/work/160321I26/conf/cyrus.conf" =====> Instance[868] _start_master: waiting for PID file =====> Instance[871] _start_master: PID file present and correct =====> Instance[883] _start_master: PID waiting for services =====> Daemon[358] is_listening: service imap is listening on 127.0.0.1:9181 =====> Util::Wait[77] Waited 0.044518 sec for 127.0.0.1:9181 to be in LISTEN state =====> Instance[894] _start_master: all services listening =====> Instance[970] create user cassandane =====> Util::Socket[63] create_client_socket INET host=127.0.0.1 port=9181 =====> Instance[1774] authdaemon connection: admin testpw imap =====> Instance[1035] started main instance for test test_urlauth-binary: cyrus version 3.0.3-1.fc27 Fedora =====> Cyrus::TestCase[574] Calling test function =====> Instance[1505] Running: "/builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane- 6d33978ebb0055be41a5cb8e52958e100401bf26/imaptest/src/imaptest" "host=127.0.0.1" "port=9181" "user=cassandane" "pass=testpw" "rawlog" "test=/builddir/build/BUILD/cyrus-imapd-3.0.3/cassandane- 6d33978ebb0055be41a5cb8e52958e100401bf26/imaptest/src/tests/urlauth- binary" =====> Instance[1774] authdaemon connection: cassandane testpw imap =====> Cyrus::ImapTest[170] Test urlauth-binary command 9/16 (line 22) =====> Cyrus::ImapTest[170] - failed: Missing 1 untagged replies (1 mismatches) =====> Cyrus::ImapTest[170] - first unexpanded: URLFETCH $mail_url1 (binary {390} =====> Cyrus::ImapTest[170] From: sub@domain.org =====> Cyrus::ImapTest[170] Date: Sun, 12 Aug 2012 12:34:56 +0300 =====> Cyrus::ImapTest[170] Subject: submsg =====> Cyrus::ImapTest[170] Content-Type: multipart/alternative; boundary="sub1" =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] Sub MIME prologue =====> Cyrus::ImapTest[170] --sub1 =====> Cyrus::ImapTest[170] Content-Type: text/x-myown; charset=us-ascii =====> Cyrus::ImapTest[170] Content-Transfer- Encoding: binary =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] hello world =====> Cyrus::ImapTest[170] --sub1 =====> Cyrus::ImapTest[170] Content-Type: text/x-myown; charset=us- ascii =====> Cyrus::ImapTest[170] Content-Transfer-Encoding: binary =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] hello world =====> Cyrus::ImapTest[170] --sub1-- =====> Cyrus::ImapTest[170] Sub MIME epilogue =====> Cyrus::ImapTest[170] ) =====> Cyrus::ImapTest[170] - first expanded: URLFETCH imap://cassandane@12- 7.0.0.1/imaptest/;uid=1/;section=1;urlauth=user+cassandane:internal:0- 097a0ee6d77055f011bb548f01121c1e544a3194c (binary {390} =====> Cyrus::ImapTest[170] From: sub@domain.org =====> Cyrus::ImapTest[170] Date: Sun, 12 Aug 2012 12:34:56 +0300 =====> Cyrus::ImapTest[170] Subject: submsg =====> Cyrus::ImapTest[170] Content-Type: multipart/alternative; boundary="sub1" =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] Sub MIME prologue =====> Cyrus::ImapTest[170] --sub1 =====> Cyrus::ImapTest[170] Content-Type: text/x-myown; charset=us-ascii =====> Cyrus::ImapTest[170] Content-Transfer- Encoding: binary =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] hello world =====> Cyrus::ImapTest[170] --sub1 =====> Cyrus::ImapTest[170] Content-Type: text/x-myown; charset=us- ascii =====> Cyrus::ImapTest[170] Content-Transfer-Encoding: binary =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] hello world =====> Cyrus::ImapTest[170] --sub1-- =====> Cyrus::ImapTest[170] Sub MIME epilogue =====> Cyrus::ImapTest[170] ) =====> Cyrus::ImapTest[170] - best match: URLFETCH "imap://cassandane@127.0- .0.1/imaptest/;uid=1/;section=1;urlauth=user+cassandane:internal:0097- a0ee6d77055f011bb548f01121c1e544a3194c" (BINARY "From: sub@domain.org =====> Cyrus::ImapTest[170] Date: Sun, 12 Aug 2012 12:34:56 +0300 =====> Cyrus::ImapTest[170] Subject: submsg =====> Cyrus::ImapTest[170] Content-Type: multipart/alternative; boundary=\"sub1\" =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] Sub MIME prologue =====> Cyrus::ImapTest[170] --sub1 =====> Cyrus::ImapTest[170] Content-Type: text/x-myown; charset=us- ascii =====> Cyrus::ImapTest[170] Content-Transfer-Encoding: base64 =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] aGVs =====> Cyrus::ImapTest[170] bG8gd29y =====> Cyrus::ImapTest[170] bGQ= =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] --sub1 =====> Cyrus::ImapTest[170] Content-Type: text/x-myown; charset=us- ascii =====> Cyrus::ImapTest[170] Content-Transfer-Encoding: base64 =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] aGVs =====> Cyrus::ImapTest[170] bG8g =====> Cyrus::ImapTest[170] d29y =====> Cyrus::ImapTest[170] bGQ= =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] --sub1-- =====> Cyrus::ImapTest[170] Sub MIME epilogue =====> Cyrus::ImapTest[170] ") =====> Cyrus::ImapTest[170] - Command: URLFETCH ($mail_url1 binary) =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] Test urlauth-binary command 13/16 (line 57) =====> Cyrus::ImapTest[170] - failed: Missing 1 untagged replies (1 mismatches) =====> Cyrus::ImapTest[170] - first unexpanded: URLFETCH $mail_url2 (BODYPARTSTRUCTURE ("message" "rfc822" NIL NIL NIL "7bit" 390 ("Sun, 12 Aug 2012 12:34:56 +0300" "submsg" ((NIL NIL "sub" "domain.org")) ((NIL NIL "sub" "domain.org")) ((NIL NIL "sub" "domain.org")) NIL NIL NIL NIL NIL) (("text" "x-myown" ("charset" "us- ascii") NIL NIL "base64" 11 0 NIL NIL NIL NIL) ("text" "x-myown" ("charset" "us-ascii") NIL NIL "base64" 11 0 NIL NIL NIL NIL) "alternative" ("boundary" "sub1") NIL NIL NIL) 18 NIL NIL NIL NIL) binary {390} =====> Cyrus::ImapTest[170] From: sub@domain.org =====> Cyrus::ImapTest[170] Date: Sun, 12 Aug 2012 12:34:56 +0300 =====> Cyrus::ImapTest[170] Subject: submsg =====> Cyrus::ImapTest[170] Content- Type: multipart/alternative; boundary="sub1" =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] Sub MIME prologue =====> Cyrus::ImapTest[170] --sub1 =====> Cyrus::ImapTest[170] Content- Type: text/x-myown; charset=us-ascii =====> Cyrus::ImapTest[170] Content-Transfer- Encoding: binary =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] hello world =====> Cyrus::ImapTest[170] --sub1 =====> Cyrus::ImapTest[170] Content-Type: text/x-myown; charset=us- ascii =====> Cyrus::ImapTest[170] Content-Transfer-Encoding: binary =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] hello world =====> Cyrus::ImapTest[170] --sub1-- =====> Cyrus::ImapTest[170] Sub MIME epilogue =====> Cyrus::ImapTest[170] ) =====> Cyrus::ImapTest[170] - first expanded: URLFETCH imap://cassandane@12- 7.0.0.1/imaptest/;uid=2/;section=1;urlauth=user+cassandane:internal:0- 0a91c0f93d4d016bc9df3b8d06b72c788d9bc3992 (BODYPARTSTRUCTURE ("message" "rfc822" NIL NIL NIL "7bit" 390 ("Sun, 12 Aug 2012 12:34:56 +0300" "submsg" ((NIL NIL "sub" "domain.org")) ((NIL NIL "sub" "domain.org")) ((NIL NIL "sub" "domain.org")) NIL NIL NIL NIL NIL) (("text" "x-myown" ("charset" "us-ascii") NIL NIL "base64" 11 0 NIL NIL NIL NIL) ("text" "x-myown" ("charset" "us-ascii") NIL NIL "base64" 11 0 NIL NIL NIL NIL) "alternative" ("boundary" "sub1") NIL NIL NIL) 18 NIL NIL NIL NIL) binary {390} =====> Cyrus::ImapTest[170] From: sub@domain.org =====> Cyrus::ImapTest[170] Date: Sun, 12 Aug 2012 12:34:56 +0300 =====> Cyrus::ImapTest[170] Subject: submsg =====> Cyrus::ImapTest[170] Content-Type: multipart/alternative; boundary="sub1" =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] Sub MIME prologue =====> Cyrus::ImapTest[170] --sub1 =====> Cyrus::ImapTest[170] Content-Type: text/x-myown; charset=us- ascii =====> Cyrus::ImapTest[170] Content-Transfer-Encoding: binary =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] hello world =====> Cyrus::ImapTest[170] --sub1 =====> Cyrus::ImapTest[170] Content- Type: text/x-myown; charset=us-ascii =====> Cyrus::ImapTest[170] Content-Transfer- Encoding: binary =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] hello world =====> Cyrus::ImapTest[170] --sub1-- =====> Cyrus::ImapTest[170] Sub MIME epilogue =====> Cyrus::ImapTest[170] ) =====> Cyrus::ImapTest[170] - best match: URLFETCH "imap://cassandane@127.0.0.1/imaptest/;uid=2/;section=1;urla- uth=user+cassandane:internal:00a91c0f93d4d016bc9df3b8d06b72c788d9bc39- 92" (BODYPARTSTRUCTURE ("MESSAGE" "RFC822" NIL NIL NIL "7BIT" 437 ("Sun, 12 Aug 2012 12:34:56 +0300" "submsg" ((NIL NIL "sub" "domain.org")) ((NIL NIL "sub" "domain.org")) ((NIL NIL "sub" "domain.org")) NIL NIL NIL NIL NIL) (("TEXT" "X-MYOWN" ("CHARSET" "us- ascii") NIL NIL "BASE64" 22 3 NIL NIL NIL NIL) ("TEXT" "X-MYOWN" ("CHARSET" "us-ascii") NIL NIL "BASE64" 47 5 NIL NIL NIL NIL) "ALTERNATIVE" ("BOUNDARY" "sub1") NIL NIL NIL) 26 NIL NIL NIL NIL)) (BINARY "From: sub@domain.org =====> Cyrus::ImapTest[170] Date: Sun, 12 Aug 2012 12:34:56 +0300 =====> Cyrus::ImapTest[170] Subject: submsg =====> Cyrus::ImapTest[170] Content-Type: multipart/alternative; boundary=\"sub1\" =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] Sub MIME prologue =====> Cyrus::ImapTest[170] --sub1 =====> Cyrus::ImapTest[170] Content-Type: text/x-myown; charset=us-ascii =====> Cyrus::ImapTest[170] Content-Transfer- Encoding: base64 =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] aGVs =====> Cyrus::ImapTest[170] bG8gd29y =====> Cyrus::ImapTest[170] bGQ= =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] --sub1 =====> Cyrus::ImapTest[170] Content-Type: text/x-myown; charset=us-ascii =====> Cyrus::ImapTest[170] Content-Transfer- Encoding: base64 =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] aGVs =====> Cyrus::ImapTest[170] bG8g =====> Cyrus::ImapTest[170] d29y =====> Cyrus::ImapTest[170] bGQ= =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] --sub1-- =====> Cyrus::ImapTest[170] Sub MIME epilogue =====> Cyrus::ImapTest[170] ") =====> Cyrus::ImapTest[170]

  • Command: URLFETCH ($mail_url2 binary bodypartstructure) =====> Cyrus::ImapTest[170] =====> Cyrus::ImapTest[170] *** Test urlauth- binary command 15/16 (line 85) =====> Cyrus::ImapTest[170] - failed: Missing 1 untagged replies (1 mismatches) =====> Cyrus::ImapTest[170]
  • first unexpanded: URLFETCH $mail_url2sub (BODYPARTSTRUCTURE ("text" "x-myown" ("charset" "us-ascii") NIL NIL "base64" 11 0 NIL NIL NIL NIL) BINARY {11} =====> Cyrus::ImapTest[170] hello world) =====> Cyrus::ImapTest[170] - first expanded: URLFETCH imap://cassandane@12- 7.0.0.1/imaptest/;uid=2/;section=1.1;urlauth=user+cassandane:internal- :00309636bf5df8a7e1466644a89659c2afd5ba240a (BODYPARTSTRUCTURE ("text" "x-myown" ("charset" "us-ascii") NIL NIL "base64" 11 0 NIL NIL NIL NIL) BINARY {11} =====> Cyrus::ImapTest[170] hello world) =====> Cyrus::ImapTest[170] - best match: URLFETCH "imap://cassandane@127.0- .0.1/imaptest/;uid=2/;section=1.1;urlauth=user+cassandane:internal:00- 309636bf5df8a7e1466644a89659c2afd5ba240a" (BODYPARTSTRUCTURE ("TEXT" "X-MYOWN" ("CHARSET" "us-ascii") NIL NIL "7BIT" 11 0 NIL NIL NIL NIL)) (BINARY "hello world") =====> Cyrus::ImapTest[170] - Command: URLFETCH ($mail_url2sub binary bodypartstructure) =====> Cyrus::ImapTest[170] ============> rawlog.1 <============= 1502467417.335299 I: OK [CAPABILITY IMAP4rev1 LITERAL+ ID ENABLE AUTH=PLAIN AUTH=LOGIN SASL-IR] 127.0.0.1 Cyrus IMAP 3.0.3-1.fc27 Fedora server ready 1502467417.335330 O: 1.1 LOGIN "cassandane" "testpw" 1502467417.335848 I: 1.1 OK [CAPABILITY IMAP4rev1 LITERAL+ ID ENABLE ACL RIGHTS=kxten QUOTA MAILBOX-REFERRALS NAMESPACE UIDPLUS NO_ATOMIC_RENAME UNSELECT CHILDREN MULTIAPPEND BINARY CATENATE CONDSTORE ESEARCH SEARCH=FUZZY SORT SORT=MODSEQ SORT=DISPLAY SORT=UID THREAD=ORDEREDSUBJECT THREAD=REFERENCES THREAD=REFS ANNOTATEMORE ANNOTATE-EXPERIMENT- 1 METADATA LIST-EXTENDED LIST-STATUS LIST-MYRIGHTS LIST-METADATA WITHIN QRESYNC SCAN XLIST XMOVE MOVE SPECIAL-USE CREATE-SPECIAL-USE DIGEST=SHA1 X-REPLICATION URLAUTH URLAUTH=BINARY LOGINDISABLED COMPRESS=DEFLATE X-QUOTA=STORAGE X-QUOTA=MESSAGE X-QUOTA=X-ANNOTATION- STORAGE X-QUOTA=X-NUM-FOLDERS IDLE] User logged in SESSIONID=<160321I26-17913-1502467417-2- 7289169598211189542> 1502467417.335909 O: 1.2 LIST "" "imaptest" 1502467417.335913 O: 1.3 LSUB "" "imaptest" 1502467417.336128 I: 1.2 OK Completed (0.000 secs) 1502467417.376851 I: 1.3 OK Completed (0.000 secs) 1502467417.376871 O: 1.4 CREATE "imaptest" 1502467417.378169 I: 1.4 OK Completed 1502467417.378194 O: 1.5 select imaptest 1502467417.378530 I: 0 EXISTS 1502467417.378530 I: 0 RECENT 1502467417.378530 I: FLAGS (\Answered \Flagged \Draft \Deleted \Seen) 1502467417.378530 I: OK [PERMANENTFLAGS (\Answered \Flagged \Draft \Deleted \Seen *)] Ok 1502467417.378530 I: OK [UIDVALIDITY 1502467418] Ok 1502467417.378530 I: OK [UIDNEXT 1] Ok 1502467417.378530 I: OK [HIGHESTMODSEQ 1] Ok 1502467417.378530 I: OK [URLMECH INTERNAL] Ok 1502467417.378530 I: OK [ANNOTATIONS 65536] Ok 1502467417.378530 I: 1.5 OK [READ-WRITE] Completed 1502467417.378619 O: 1.6 APPEND "imaptest" "22-Feb-2008 17:06:23 - 0600" {673+} 1502467417.378627 O: From: user@domain.org 1502467417.378627 O: Date: Sat, 24 Mar 2007 23:00:00 +0200 1502467417.378627 O: Mime-Version: 1.0 1502467417.378627 O: Content- Type: multipart/mixed; boundary="foo 1502467417.378627 O: bar" 1502467417.378627 O: 1502467417.378627 O: Root MIME prologue 1502467417.378627 O: 1502467417.378627 O: --foo bar 1502467417.378627 O: Content-Type: message/rfc822 1502467417.378627 O: 1502467417.378627 O: From: sub@domain.org 1502467417.378627 O: Date: Sun, 12 Aug 2012 12:34:56 +0300 1502467417.378627 O: Subject: submsg 1502467417.378627 O: Content-Type: multipart/alternative; boundary="sub1" 1502467417.378627 O: 1502467417.378627 O: Sub MIME prologue 1502467417.378627 O: --sub1 1502467417.378627 O: Content-Type: text/x- myown; charset=us-ascii 1502467417.378627 O: Content-Transfer- Encoding: base64 1502467417.378627 O: 1502467417.378627 O: aGVs 1502467417.378627 O: bG8gd29y 1502467417.378627 O: bGQ= 1502467417.378627 O: 1502467417.378627 O: --sub1 1502467417.378627 O: Content-Type: text/x-myown; charset=us-ascii 1502467417.378627 O: Content-Transfer- Encoding: base64 1502467417.378627 O: 1502467417.378627 O: aGVs 1502467417.378627 O: bG8g 1502467417.378627 O: d29y 1502467417.378627 O: bGQ= 1502467417.378627 O: 1502467417.378627 O: 1502467417.378627 O: --sub1-- 1502467417.378627 O: Sub MIME epilogue 1502467417.378627 O: 1502467417.378627 O: --foo bar-- 1502467417.378627 O: Root MIME epilogue 1502467417.378650 O: 1502467417.379922 I: 1 EXISTS 1502467417.379922 I: 1 RECENT 1502467417.379922 I: 1.6 OK [APPENDUID 1502467418 1] Completed 1502467417.379954 O: 1.7 APPEND "imaptest" "22-Feb-2008 17:06:23 - 0600" {673+} 1502467417.379958 O: From: user@domain.org 1502467417.379958 O: Date: Sat, 24 Mar 2007 23:00:00 +0200 1502467417.379958 O: Mime-Version: 1.0 1502467417.379958 O: Content- Type: multipart/mixed; boundary="foo 1502467417.379958 O: bar" 1502467417.379958 O: 1502467417.379958 O: Root MIME prologue 1502467417.379958 O: 1502467417.379958 O: --foo bar 1502467417.379958 O: Content-Type: message/rfc822 1502467417.379958 O: 1502467417.379958 O: From: sub@domain.org 1502467417.379958 O: Date: Sun, 12 Aug 2012 12:34:56 +0300 1502467417.379958 O: Subject: submsg 1502467417.379958 O: Content-Type: multipart/alternative; boundary="sub1" 1502467417.379958 O: 1502467417.379958 O: Sub MIME prologue 1502467417.379958 O: --sub1 1502467417.379958 O: Content-Type: text/x- myown; charset=us-ascii 1502467417.379958 O: Content-Transfer- Encoding: base64 1502467417.379958 O: 1502467417.379958 O: aGVs 1502467417.379958 O: bG8gd29y 1502467417.379958 O: bGQ= 1502467417.379958 O: 1502467417.379958 O: --sub1 1502467417.379958 O: Content-Type: text/x-myown; charset=us-ascii 1502467417.379958 O: Content-Transfer- Encoding: base64 1502467417.379958 O: 1502467417.379958 O: aGVs 1502467417.379958 O: bG8g 1502467417.379958 O: d29y 1502467417.379958 O: bGQ= 1502467417.379958 O: 1502467417.379958 O: 1502467417.379958 O: --sub1-- 1502467417.379958 O: Sub MIME epilogue 1502467417.379958 O: 1502467417.379958 O: --foo bar-- 1502467417.379958 O: Root MIME epilogue 1502467417.379974 O: 1502467417.381035 I: 2 EXISTS 1502467417.381035 I: 2 RECENT 1502467417.381035 I: 1.7 OK [APPENDUID 1502467418 2] Completed 1502467417.381056 O: 1.8 fetch 1:2 uid 1502467417.381275 I: 1 FETCH (UID 1) 1502467417.381275 I: 2 FETCH (UID 2) 1502467417.381275 I: 1.8 OK Completed (0.000 sec) 1502467417.381301 O: 1.9 GENURLAUTH "ima- p://cassandane@127.0.0.1/imaptest/;uid=1/;section=1;urlauth=user+cass- andane" INTERNAL 1502467417.381524 I: GENURLAUTH "imap://cassandane- @127.0.0.1/imaptest/;uid=1/;section=1;urlauth=user+cassandane:interna- l:0097a0ee6d77055f011bb548f01121c1e544a3194c" 1502467417.381524 I: 1.9 OK Completed 1502467417.381550 O: 1.10 GENURLAUTH "imap://cassandane@- 127.0.0.1/imaptest/;uid=1/;section=1.1;urlauth=user+cassandane" INTERNAL 1502467417.381705 I: GENURLAUTH "imap://cassandane@127.0.0- .1/imaptest/;uid=1/;section=1.1;urlauth=user+cassandane:internal:00ed- df5fc9f7b76eabcebd22efc5e825b5ac98d7ad" 1502467417.381705 I: 1.10 OK Completed 1502467417.381723 O: 1.11 GENURLAUTH "imap://cassandane@127- .0.0.1/imaptest/;uid=2/;section=1;urlauth=user+cassandane" INTERNAL 1502467417.381910 I: GENURLAUTH "imap://cassandane@127.0.0.1/imapte- st/;uid=2/;section=1;urlauth=user+cassandane:internal:00a91c0f93d4d01- 6bc9df3b8d06b72c788d9bc3992" 1502467417.381910 I: 1.11 OK Completed 1502467417.381927 O: 1.12 GENURLAUTH "imap://cassandane@127.0.0.1/ima- ptest/;uid=2/;section=1.1;urlauth=user+cassandane" INTERNAL 1502467417.382082 I: GENURLAUTH "imap://cassandane@127.0.0.1/imapte- st/;uid=2/;section=1.1;urlauth=user+cassandane:internal:00309636bf5df- 8a7e1466644a89659c2afd5ba240a" 1502467417.382082 I: 1.12 OK Completed 1502467417.382097 O: 1.13 URLFETCH (imap://cassandane@127.0.0.1/imapt- est/;uid=1/;section=1;urlauth=user+cassandane:internal:0097a0ee6d7705- 5f011bb548f01121c1e544a3194c binary) 1502467417.382433 I: * URLFETCH "imap://cassandane@127.0.0.1/imaptest/;uid=1/;section=1;urlauth=user+- cassandane:internal:0097a0ee6d77055f011bb548f01121c1e544a3194c" (BINARY {437} 1502467417.382433 I: From: sub@domain.org 1502467417.382433 I: Date: Sun, 12 Aug 2012 12:34:56 +0300 1502467417.382433 I: Subject: submsg 1502467417.382433 I: Content- Type: multipart/alternative; boundary="sub1" 1502467417.382433 I: 1502467417.382433 I: Sub MIME prologue 1502467417.382433 I: --sub1 1502467417.382433 I: Content-Type: text/x-myown; charset=us-ascii 1502467417.382433 I: Content-Transfer-Encoding: base64 1502467417.382433 I: 1502467417.382433 I: aGVs 1502467417.382433 I: bG8gd29y 1502467417.382433 I: bGQ= 1502467417.382433 I: 1502467417.382433 I: --sub1 1502467417.382433 I: Content-Type: text/x- myown; charset=us-ascii 1502467417.382433 I: Content-Transfer- Encoding: base64 1502467417.382433 I: 1502467417.382433 I: aGVs 1502467417.382433 I: bG8g 1502467417.382433 I: d29y 1502467417.382433 I: bGQ= 1502467417.382433 I: 1502467417.382433 I: 1502467417.382433 I: --sub1-- 1502467417.382433 I: Sub MIME epilogue 1502467417.382433 I: =====> Cyrus::TestCase[681] Beginning tear_down =====> Instance[1242] stop =====> Instance[1204] _stop_pid: sending signal 15 to 17901 =====> Util::Wait[77] Waited 0.010088 sec for unknown condition =====> Instance[919] killing saslauthd 17884 Warning: unable to close filehandle $oldout properly: Bad file descriptor at Cassandane/Instance.pm line 913. Warning: unable to close filehandle $olderr properly: Bad file descriptor at Cassandane/Instance.pm line 913. =====> Instance[946] killing notifyd 17886 Warning: unable to close filehandle $oldout properly: Bad file descriptor at Cassandane/Instance.pm line 940. Warning: unable to close filehandle $olderr properly: Bad file descriptor at Cassandane/Instance.pm line 940. =====> Instance[919] killing saslauthd 17884 =====> Instance[1270] Cleaning up basedir /builddir/build/BUILD/cyrus-imapd- 3.0.3/cassandane/work/160321I26 =====> Cyrus::TestCase[726] ---------- END test_urlauth-binary ---------- =====> Daemon[387] checking for stray processes on ports: 9181 ) 1502467417.382433 I: 1.13 OK Completed 1502467417.382521 O: 1.14 URLFETCH (imap://cassandane@127.0- .0.1/imaptest/;uid=1/;section=1.1;urlauth=user+cassandane:internal:00- eddf5fc9f7b76eabcebd22efc5e825b5ac98d7ad binary) 1502467417.382831 I:
  • URLFETCH "imap://cassandane@127.0.0.1/imaptest/;uid=1/;section=1.1;- urlauth=user+cassandane:internal:00eddf5fc9f7b76eabcebd22efc5e825b5ac- 98d7ad" (BINARY {11} 1502467417.382831 I: hello world) 1502467417.382831 I: 1.14 OK Completed 1502467417.382854 O: 1.15 URLFETCH (imap://cassandane@127.0.0.1/imaptest/;uid=1/;section=1;urla- uth=user+cassandane:internal:0097a0ee6d77055f011bb548f01121c1e544a319- 4c bodypartstructure) 1502467417.383134 I: * URLFETCH "imap://cassand- ane@127.0.0.1/imaptest/;uid=1/;section=1;urlauth=user+cassandane:inte- rnal:0097a0ee6d77055f011bb548f01121c1e544a3194c" (BODYPARTSTRUCTURE ("MESSAGE" "RFC822" NIL NIL NIL "7BIT" 437 ("Sun, 12 Aug 2012 12:34:56 +0300" "submsg" ((NIL NIL "sub" "domain.org")) ((NIL NIL "sub" "domain.org")) ((NIL NIL "sub" "domain.org")) NIL NIL NIL NIL NIL) (("TEXT" "X-MYOWN" ("CHARSET" "us-ascii") NIL NIL "BASE64" 22 3 NIL NIL NIL NIL)("TEXT" "X-MYOWN" ("CHARSET" "us-ascii") NIL NIL "BASE64" 47 5 NIL NIL NIL NIL) "ALTERNATIVE" ("BOUNDARY" "sub1") NIL NIL NIL) 26 NIL NIL NIL NIL)) 1502467417.383134 I: 1.15 OK Completed 1502467417.383185 O: 1.16 URLFETCH (imap://cassandane@127.0.0.1/imapt- est/;uid=1/;section=1.1;urlauth=user+cassandane:internal:00eddf5fc9f7- b76eabcebd22efc5e825b5ac98d7ad bodypartstructure) 1502467417.383400 I:
  • URLFETCH "imap://cassandane@127.0.0.1/imaptest/;uid=1/;section=1.1;- urlauth=user+cassandane:internal:00eddf5fc9f7b76eabcebd22efc5e825b5ac- 98d7ad" (BODYPARTSTRUCTURE ("TEXT" "X-MYOWN" ("CHARSET" "us-ascii") NIL NIL "BASE64" 22 3 NIL NIL NIL NIL)) 1502467417.383400 I: 1.16 OK Completed 1502467417.383427 O: 1.17 URLFETCH (imap://cassandane@127.0- .0.1/imaptest/;uid=2/;section=1;urlauth=user+cassandane:internal:00a9- 1c0f93d4d016bc9df3b8d06b72c788d9bc3992 binary bodypartstructure) 1502467417.383705 I: URLFETCH "imap://cassandane@127.0.0.1/imaptest- /;uid=2/;section=1;urlauth=user+cassandane:internal:00a91c0f93d4d016b- c9df3b8d06b72c788d9bc3992" (BODYPARTSTRUCTURE ("MESSAGE" "RFC822" NIL NIL NIL "7BIT" 437 ("Sun, 12 Aug 2012 12:34:56 +0300" "submsg" ((NIL NIL "sub" "domain.org")) ((NIL NIL "sub" "domain.org")) ((NIL NIL "sub" "domain.org")) NIL NIL NIL NIL NIL) (("TEXT" "X-MYOWN" ("CHARSET" "us-ascii") NIL NIL "BASE64" 22 3 NIL NIL NIL NIL)("TEXT" "X-MYOWN" ("CHARSET" "us-ascii") NIL NIL "BASE64" 47 5 NIL NIL NIL NIL) "ALTERNATIVE" ("BOUNDARY" "sub1") NIL NIL NIL) 26 NIL NIL NIL NIL)) (BINARY {437} 1502467417.383705 I: From: sub@domain.org 1502467417.383705 I: Date: Sun, 12 Aug 2012 12:34:56 +0300 1502467417.383705 I: Subject: submsg 1502467417.383705 I: Content- Type: multipart/alternative; boundary="sub1" 1502467417.383705 I: 1502467417.383705 I: Sub MIME prologue 1502467417.383705 I: --sub1 1502467417.383705 I: Content-Type: text/x-myown; charset=us-ascii 1502467417.383705 I: Content-Transfer-Encoding: base64 1502467417.383705 I: 1502467417.383705 I: aGVs 1502467417.383705 I: bG8gd29y 1502467417.383705 I: bGQ= 1502467417.383705 I: 1502467417.383705 I: --sub1 1502467417.383705 I: Content-Type: text/x- myown; charset=us-ascii 1502467417.383705 I: Content-Transfer- Encoding: base64 1502467417.383705 I: 1502467417.383705 I: aGVs 1502467417.383705 I: bG8g 1502467417.383705 I: d29y 1502467417.383705 I: bGQ= 1502467417.383705 I: 1502467417.383705 I: 1502467417.383705 I: --sub1-- 1502467417.383705 I: Sub MIME epilogue 1502467417.383705 I: ) 1502467417.383705 I: 1.17 OK Completed 1502467417.383793 O: 1.18 URLFETCH (imap://cassandane@127.0.0.1/imapt- est/;uid=1/;section=1.1;urlauth=user+cassandane:internal:00eddf5fc9f7- b76eabcebd22efc5e825b5ac98d7ad binary) 1502467417.384019 I: URLFETCH "imap://cassandane@127.0.0.1/imaptest/;uid=1/;section=1.1;urlauth=use- r+cassandane:internal:00eddf5fc9f7b76eabcebd22efc5e825b5ac98d7ad" (BINARY {11} 1502467417.384019 I: hello world) 1502467417.384019 I: 1.18 OK Completed 1502467417.384041 O: 1.19 URLFETCH (imap://cassanda- ne@127.0.0.1/imaptest/;uid=2/;section=1.1;urlauth=user+cassandane:int- ernal:00309636bf5df8a7e1466644a89659c2afd5ba240a binary bodypartstructure) 1502467417.384344 I: URLFETCH "imap://cassandane- @127.0.0.1/imaptest/;uid=2/;section=1.1;urlauth=user+cassandane:inter- nal:00309636bf5df8a7e1466644a89659c2afd5ba240a" (BODYPARTSTRUCTURE ("TEXT" "X-MYOWN" ("CHARSET" "us-ascii") NIL NIL "7BIT" 11 0 NIL NIL NIL NIL)) (BINARY {11} 1502467417.384344 I: hello world) 1502467417.384344 I: 1.19 OK Completed 1502467417.384389 O: 1.20 logout 1502467417.384553 I: BYE LOGOUT received 1502467417.384553 I: 1.20 OK Completed Test was not successful.

    — You are receiving this because you are subscribed to this thread. Reply to this email directly, view it on GitHub[1], or mute the thread[2].>

-- Bron Gondwana, CEO, FastMail Pty Ltd brong@fastmailteam.com

Links:

  1. https://github.com/cyrusimap/cyrus-imapd/issues/2087
  2. https://github.com/notifications/unsubscribe-auth/AABE7ZeZgd6RtYwE3RoWZbJkwD9vmvJSks5sXHyLgaJpZM4O02Tx