PATH=/usr/bin:/bin:/Users/fly2000/var/megalib/bin Start 2015-10-06T10:33:10 ActivePerl-2000 CPAN-2.00 Reading '/Users/fly2000/var/cpan/Metadata' Database was generated on Tue, 06 Oct 2015 04:17:02 GMT Running make for P/PR/PREACTION/Mercury-0.004.tar.gz Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/P/PR/PREACTION/Mercury-0.004.tar.gz Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/P/PR/PREACTION/CHECKSUMS Checksum for /Users/fly2000/var/cpan/sources/authors/id/P/PR/PREACTION/Mercury-0.004.tar.gz ok Mercury-0.004/ Mercury-0.004/bin/ Mercury-0.004/CHANGES Mercury-0.004/CONTRIBUTING.md Mercury-0.004/cpanfile Mercury-0.004/lib/ Mercury-0.004/LICENSE Mercury-0.004/Makefile.PL Mercury-0.004/MANIFEST Mercury-0.004/META.yml Mercury-0.004/README.mkdn Mercury-0.004/t/ Mercury-0.004/t/00-compile.t Mercury-0.004/t/00-report-prereqs.dd Mercury-0.004/t/00-report-prereqs.t Mercury-0.004/t/allow_origin.t Mercury-0.004/t/broker.t Mercury-0.004/t/share/ Mercury-0.004/t/share/allow_origin.conf Mercury-0.004/lib/Mercury/ Mercury-0.004/lib/Mercury.pm Mercury-0.004/lib/Mercury/Command/ Mercury-0.004/lib/Mercury/PushPull.pm Mercury-0.004/lib/Mercury/templates/ Mercury-0.004/lib/Mercury/templates/bus.html.ep Mercury-0.004/lib/Mercury/templates/index.html.ep Mercury-0.004/lib/Mercury/templates/layouts/ Mercury-0.004/lib/Mercury/templates/pubsub.html.ep Mercury-0.004/lib/Mercury/templates/pushpull.html.ep Mercury-0.004/lib/Mercury/templates/layouts/standard.html.ep Mercury-0.004/lib/Mercury/Command/mercury/ Mercury-0.004/lib/Mercury/Command/mercury.pm Mercury-0.004/lib/Mercury/Command/mercury/broker.pm Mercury-0.004/bin/mercury CPAN.pm: Building P/PR/PREACTION/Mercury-0.004.tar.gz >>> /Users/fly2000/ap2000-298465/bin/perl-dynamic Makefile.PL Checking if your kit is complete... Looks good Generating a Unix-style Makefile Writing Makefile for Mercury Writing MYMETA.yml and MYMETA.json >>> make cp lib/Mercury.pm blib/lib/Mercury.pm cp lib/Mercury/templates/layouts/standard.html.ep blib/lib/Mercury/templates/layouts/standard.html.ep cp lib/Mercury/templates/pubsub.html.ep blib/lib/Mercury/templates/pubsub.html.ep cp lib/Mercury/templates/bus.html.ep blib/lib/Mercury/templates/bus.html.ep cp lib/Mercury/Command/mercury.pm blib/lib/Mercury/Command/mercury.pm cp lib/Mercury/Command/mercury/broker.pm blib/lib/Mercury/Command/mercury/broker.pm cp lib/Mercury/templates/pushpull.html.ep blib/lib/Mercury/templates/pushpull.html.ep cp lib/Mercury/PushPull.pm blib/lib/Mercury/PushPull.pm cp lib/Mercury/templates/index.html.ep blib/lib/Mercury/templates/index.html.ep cp bin/mercury blib/script/mercury "/Users/fly2000/ap2000-298465/bin/perl-dynamic" -MExtUtils::MY -e 'MY->fixin(shift)' -- blib/script/mercury Manifying 1 pod document Manifying 4 pod documents PREACTION/Mercury-0.004.tar.gz make -- OK Running make test >>> make test TEST_VERBOSE=1 PERL_DL_NONLAZY=1 "/Users/fly2000/ap2000-298465/bin/perl-dynamic" "-MExtUtils::Command::MM" "-MTest::Harness" "-e" "undef *Test::Harness::Switches; test_harness(1, 'blib/lib', 'blib/arch')" t/*.t t/00-compile.t ......... 1..5 ok 1 - Mercury.pm loaded ok ok 2 - Mercury/Command/mercury.pm loaded ok ok 3 - Mercury/Command/mercury/broker.pm loaded ok ok 4 - Mercury/PushPull.pm loaded ok ok 5 # skip bin/mercury isn't perl ok # # Versions for all modules listed in MYMETA.json (including optional ones): # # === Configure Requires === # # Module Want Have # ------------------- ---- ---- # ExtUtils::MakeMaker any 7.04 # # === Build Requires === # # Module Want Have # ------------------- ---- ---- # ExtUtils::MakeMaker any 7.04 # # === Test Requires === # # Module Want Have # ------------------- -------- -------- # ExtUtils::MakeMaker any 7.04 # File::Spec any 3.47 # IO::Handle any 1.35 # IPC::Open3 any 1.16 # Test::More 1.001005 1.001009 # # === Test Recommends === # # Module Want Have # ---------- -------- -------- # CPAN::Meta 2.120900 2.150005 # # === Runtime Requires === # # Module Want Have # --------------------- ---- ---- # File::Basename any 2.85 # File::Spec::Functions any 3.47 # Mojolicious 6.00 6.22 # Scalar::Util any 1.42 # t/00-report-prereqs.t .. 1..1 ok 1 ok [Tue Oct 6 10:33:26 2015] [debug] Reading configuration file "/Users/fly2000/var/cpan/build/Mercury-0.004-931RK_/t/share/allow_origin.conf" [Tue Oct 6 10:33:26 2015] [debug] Reading configuration file "/Users/fly2000/var/cpan/build/Mercury-0.004-931RK_/t/share/allow_origin.conf" [Tue Oct 6 10:33:26 2015] [debug] Your secret passphrase needs to be changed [Tue Oct 6 10:33:26 2015] [debug] GET "/bus/fry" [Tue Oct 6 10:33:26 2015] [debug] Routing to a callback [Tue Oct 6 10:33:26 2015] [debug] Routing to a callback [Tue Oct 6 10:33:26 2015] [debug] 101 Switching Protocols (0.001086s, 920.810/s) [Tue Oct 6 10:33:26 2015] [debug] GET "/bus/leela" [Tue Oct 6 10:33:26 2015] [debug] Routing to a callback [Tue Oct 6 10:33:26 2015] [debug] 401 Unauthorized (0.000693s, 1443.001/s) [Tue Oct 6 10:33:26 2015] [debug] GET "/bus/bender" [Tue Oct 6 10:33:26 2015] [debug] Routing to a callback [Tue Oct 6 10:33:26 2015] [debug] 401 Unauthorized (0.000634s, 1577.287/s) [Tue Oct 6 10:33:26 2015] [debug] GET "/sub/fry" [Tue Oct 6 10:33:26 2015] [debug] Routing to a callback [Tue Oct 6 10:33:26 2015] [debug] Routing to a callback [Tue Oct 6 10:33:26 2015] [debug] 101 Switching Protocols (0.000671s, 1490.313/s) [Tue Oct 6 10:33:26 2015] [debug] GET "/sub/leela" [Tue Oct 6 10:33:26 2015] [debug] Routing to a callback [Tue Oct 6 10:33:26 2015] [debug] 401 Unauthorized (0.000590s, 1694.915/s) [Tue Oct 6 10:33:26 2015] [debug] GET "/sub/bender" [Tue Oct 6 10:33:26 2015] [debug] Routing to a callback [Tue Oct 6 10:33:26 2015] [debug] 401 Unauthorized (0.000622s, 1607.717/s) [Tue Oct 6 10:33:26 2015] [debug] GET "/pub/fry" [Tue Oct 6 10:33:26 2015] [debug] Routing to a callback [Tue Oct 6 10:33:26 2015] [debug] Routing to a callback [Tue Oct 6 10:33:26 2015] [debug] 101 Switching Protocols (0.000832s, 1201.923/s) [Tue Oct 6 10:33:26 2015] [debug] GET "/pub/leela" [Tue Oct 6 10:33:26 2015] [debug] Routing to a callback [Tue Oct 6 10:33:26 2015] [debug] 401 Unauthorized (0.000657s, 1522.070/s) [Tue Oct 6 10:33:26 2015] [debug] GET "/pub/bender" [Tue Oct 6 10:33:26 2015] [debug] Routing to a callback [Tue Oct 6 10:33:26 2015] [debug] 401 Unauthorized (0.000687s, 1455.604/s) [Tue Oct 6 10:33:26 2015] [debug] GET "/push/fry" [Tue Oct 6 10:33:26 2015] [debug] Routing to a callback [Tue Oct 6 10:33:26 2015] [debug] Routing to a callback [Tue Oct 6 10:33:26 2015] [debug] 101 Switching Protocols (0.000967s, 1034.126/s) [Tue Oct 6 10:33:26 2015] [debug] GET "/push/leela" [Tue Oct 6 10:33:26 2015] [debug] Routing to a callback [Tue Oct 6 10:33:26 2015] [debug] 401 Unauthorized (0.000652s, 1533.742/s) [Tue Oct 6 10:33:26 2015] [debug] GET "/push/bender" [Tue Oct 6 10:33:26 2015] [debug] Routing to a callback [Tue Oct 6 10:33:26 2015] [debug] 401 Unauthorized (0.000804s, 1243.781/s) [Tue Oct 6 10:33:26 2015] [debug] GET "/pull/fry" [Tue Oct 6 10:33:26 2015] [debug] Routing to a callback [Tue Oct 6 10:33:26 2015] [debug] Routing to a callback [Tue Oct 6 10:33:26 2015] [debug] 101 Switching Protocols (0.000810s, 1234.568/s) [Tue Oct 6 10:33:26 2015] [debug] GET "/pull/leela" [Tue Oct 6 10:33:26 2015] [debug] Routing to a callback [Tue Oct 6 10:33:26 2015] [debug] 401 Unauthorized (0.000651s, 1536.098/s) [Tue Oct 6 10:33:26 2015] [debug] GET "/pull/bender" [Tue Oct 6 10:33:26 2015] [debug] Routing to a callback [Tue Oct 6 10:33:26 2015] [debug] 401 Unauthorized (0.000647s, 1545.595/s) t/allow_origin.t ....... # Subtest: /bus # Subtest: example.com - host only # Subtest: correct origin ok 1 - WebSocket handshake with /bus/fry ok 2 - 101 Switching Protocols ok 3 - closed WebSocket 1..3 ok 1 - correct origin # Subtest: bad origin ok 1 - did not succeed ok 2 - unauthorized 1..2 ok 2 - bad origin # Subtest: no origin ok 1 - did not succeed ok 2 - unauthorized 1..2 ok 3 - no origin 1..3 ok 1 - example.com - host only 1..1 ok 1 - /bus # Subtest: /sub # Subtest: example.com - host only # Subtest: correct origin ok 1 - WebSocket handshake with /sub/fry ok 2 - 101 Switching Protocols ok 3 - closed WebSocket 1..3 ok 1 - correct origin # Subtest: bad origin ok 1 - did not succeed ok 2 - unauthorized 1..2 ok 2 - bad origin # Subtest: no origin ok 1 - did not succeed ok 2 - unauthorized 1..2 ok 3 - no origin 1..3 ok 1 - example.com - host only 1..1 ok 2 - /sub # Subtest: /pub # Subtest: example.com - host only # Subtest: correct origin ok 1 - WebSocket handshake with /pub/fry ok 2 - 101 Switching Protocols ok 3 - closed WebSocket 1..3 ok 1 - correct origin # Subtest: bad origin ok 1 - did not succeed ok 2 - unauthorized 1..2 ok 2 - bad origin # Subtest: no origin ok 1 - did not succeed ok 2 - unauthorized 1..2 ok 3 - no origin 1..3 ok 1 - example.com - host only 1..1 ok 3 - /pub # Subtest: /push # Subtest: example.com - host only # Subtest: correct origin ok 1 - WebSocket handshake with /push/fry ok 2 - 101 Switching Protocols ok 3 - closed WebSocket 1..3 ok 1 - correct origin # Subtest: bad origin ok 1 - did not succeed ok 2 - unauthorized 1..2 ok 2 - bad origin # Subtest: no origin ok 1 - did not succeed ok 2 - unauthorized 1..2 ok 3 - no origin 1..3 ok 1 - example.com - host only 1..1 ok 4 - /push # Subtest: /pull # Subtest: example.com - host only # Subtest: correct origin ok 1 - WebSocket handshake with /pull/fry ok 2 - 101 Switching Protocols ok 3 - closed WebSocket 1..3 ok 1 - correct origin # Subtest: bad origin ok 1 - did not succeed ok 2 - unauthorized 1..2 ok 2 - bad origin # Subtest: no origin ok 1 - did not succeed ok 2 - unauthorized 1..2 ok 3 - no origin 1..3 ok 1 - example.com - host only 1..1 ok 5 - /pull 1..5 ok [Tue Oct 6 10:33:28 2015] [debug] Your secret passphrase needs to be changed [Tue Oct 6 10:33:28 2015] [debug] GET "/bus/foo" [Tue Oct 6 10:33:28 2015] [debug] Routing to a callback [Tue Oct 6 10:33:28 2015] [debug] 101 Switching Protocols (0.000886s, 1128.668/s) [Tue Oct 6 10:33:28 2015] [debug] GET "/bus/foo" [Tue Oct 6 10:33:28 2015] [debug] Routing to a callback [Tue Oct 6 10:33:28 2015] [debug] 101 Switching Protocols (0.000519s, 1926.782/s) [Tue Oct 6 10:33:28 2015] [debug] GET "/bus/foo" [Tue Oct 6 10:33:28 2015] [debug] Routing to a callback [Tue Oct 6 10:33:28 2015] [debug] 101 Switching Protocols (0.000467s, 2141.328/s) [Tue Oct 6 10:33:28 2015] [debug] GET "/bus/foo" [Tue Oct 6 10:33:28 2015] [debug] Routing to a callback [Tue Oct 6 10:33:28 2015] [debug] 101 Switching Protocols (0.000478s, 2092.050/s) [Tue Oct 6 10:33:28 2015] [debug] GET "/bus/bar" [Tue Oct 6 10:33:28 2015] [debug] Routing to a callback [Tue Oct 6 10:33:28 2015] [debug] 101 Switching Protocols (0.012377s, 80.795/s) [Tue Oct 6 10:33:28 2015] [debug] GET "/pub/foo" [Tue Oct 6 10:33:28 2015] [debug] Routing to a callback [Tue Oct 6 10:33:28 2015] [debug] 101 Switching Protocols (0.000708s, 1412.429/s) [Tue Oct 6 10:33:28 2015] [debug] GET "/sub/foo" [Tue Oct 6 10:33:28 2015] [debug] Routing to a callback [Tue Oct 6 10:33:28 2015] [debug] 101 Switching Protocols (0.000588s, 1700.680/s) [Tue Oct 6 10:33:28 2015] [debug] GET "/sub/foo" [Tue Oct 6 10:33:28 2015] [debug] Routing to a callback [Tue Oct 6 10:33:28 2015] [debug] 101 Switching Protocols (0.000497s, 2012.072/s) [Tue Oct 6 10:33:28 2015] [debug] GET "/sub/foo" [Tue Oct 6 10:33:28 2015] [debug] Routing to a callback [Tue Oct 6 10:33:28 2015] [debug] 101 Switching Protocols (0.000478s, 2092.050/s) [Tue Oct 6 10:33:28 2015] [debug] GET "/sub/foo/bar" [Tue Oct 6 10:33:28 2015] [debug] Routing to a callback [Tue Oct 6 10:33:28 2015] [debug] 101 Switching Protocols (0.000627s, 1594.896/s) [Tue Oct 6 10:33:28 2015] [debug] GET "/pub/foo/bar" [Tue Oct 6 10:33:28 2015] [debug] Routing to a callback [Tue Oct 6 10:33:28 2015] [debug] 101 Switching Protocols (0.000566s, 1766.784/s) [Tue Oct 6 10:33:28 2015] [debug] GET "/pub/foo" [Tue Oct 6 10:33:28 2015] [debug] Routing to a callback [Tue Oct 6 10:33:28 2015] [debug] 101 Switching Protocols (0.000573s, 1745.201/s) [Tue Oct 6 10:33:28 2015] [debug] GET "/pull/foo" [Tue Oct 6 10:33:28 2015] [debug] Routing to a callback [Tue Oct 6 10:33:28 2015] [debug] 101 Switching Protocols (0.000823s, 1215.067/s) [Tue Oct 6 10:33:28 2015] [debug] GET "/pull/foo" [Tue Oct 6 10:33:28 2015] [debug] Routing to a callback [Tue Oct 6 10:33:28 2015] [debug] 101 Switching Protocols (0.000403s, 2481.390/s) [Tue Oct 6 10:33:28 2015] [debug] GET "/pull/bar" [Tue Oct 6 10:33:28 2015] [debug] Routing to a callback [Tue Oct 6 10:33:28 2015] [debug] 101 Switching Protocols (0.006352s, 157.431/s) [Tue Oct 6 10:33:28 2015] [debug] GET "/push/foo" [Tue Oct 6 10:33:28 2015] [debug] Routing to a callback [Tue Oct 6 10:33:28 2015] [debug] 101 Switching Protocols (0.000677s, 1477.105/s) [Tue Oct 6 10:33:28 2015] [debug] GET "/pull/foo" [Tue Oct 6 10:33:28 2015] [debug] Routing to a callback [Tue Oct 6 10:33:28 2015] [debug] 101 Switching Protocols (0.000345s, 2898.551/s) [Tue Oct 6 10:33:28 2015] [debug] GET "/pull/foo" [Tue Oct 6 10:33:28 2015] [debug] Routing to a callback [Tue Oct 6 10:33:28 2015] [debug] 101 Switching Protocols (0.000393s, 2544.529/s) t/broker.t ............. # Subtest: bus ok 1 - WebSocket handshake with /bus/foo ok 2 - WebSocket handshake with /bus/foo ok 3 - WebSocket handshake with /bus/foo ok 4 - WebSocket handshake with /bus/foo ok 5 - WebSocket handshake with /bus/bar # Subtest: peer 0 ok 1 - peer 0 sends message ok 2 - peer 1 received message ok 3 - exact match for message ok 4 - peer 2 received message ok 5 - exact match for message ok 6 - peer 3 received message ok 7 - exact match for message 1..7 ok 6 - peer 0 # Subtest: peer 2 ok 1 - peer 2 sends message ok 2 - peer 0 received message ok 3 - exact match for message ok 4 - peer 1 received message ok 5 - exact match for message ok 6 - peer 3 received message ok 7 - exact match for message 1..7 ok 7 - peer 2 ok 8 - closed WebSocket ok 9 - closed WebSocket ok 10 - closed WebSocket ok 11 - closed WebSocket ok 12 - closed WebSocket 1..12 ok 1 - bus # Subtest: pubsub # Subtest: exact topic ok 1 - WebSocket handshake with /pub/foo ok 2 - WebSocket handshake with /sub/foo ok 3 - WebSocket handshake with /sub/foo ok 4 - send message ok 5 - sub received message ok 6 - exact match for message ok 7 - sub received message ok 8 - exact match for message ok 9 - closed WebSocket ok 10 - closed WebSocket ok 11 - closed WebSocket 1..11 ok 1 - exact topic # Subtest: topic hierarchy ok 1 - WebSocket handshake with /sub/foo ok 2 - WebSocket handshake with /sub/foo/bar # Subtest: publish on child topic ok 1 - WebSocket handshake with /pub/foo/bar ok 2 - send message ok 3 - sub received message ok 4 - exact match for message ok 5 - sub received message ok 6 - exact match for message ok 7 - closed WebSocket 1..7 ok 3 - publish on child topic # Subtest: publish on parent topic ok 1 - WebSocket handshake with /pub/foo ok 2 - send message ok 3 - sub received message ok 4 - exact match for message ok 5 - closed WebSocket 1..5 ok 4 - publish on parent topic ok 5 - closed WebSocket ok 6 - closed WebSocket 1..6 ok 2 - topic hierarchy 1..2 ok 2 - pubsub # Subtest: push/pull ok 1 - WebSocket handshake with /pull/foo ok 2 - WebSocket handshake with /pull/foo ok 3 - WebSocket handshake with /pull/bar ok 4 - WebSocket handshake with /push/foo # Subtest: first message ok 1 - send message ok 2 - first puller got first message ok 3 - exact match for message ok 4 - other pullers got no message 1..4 ok 5 - first message # Subtest: second message ok 1 - send message ok 2 - second puller got second message ok 3 - exact match for message ok 4 - other pullers got no message 1..4 ok 6 - second message # Subtest: third message ok 1 - send message ok 2 - first puller got third message ok 3 - exact match for message 1..3 ok 7 - third message # Subtest: remove a puller ok 1 - closed WebSocket 1..1 ok 8 - remove a puller # Subtest: fourth message ok 1 - send message ok 2 - first puller got third message ok 3 - exact match for message 1..3 ok 9 - fourth message # Subtest: add a puller ok 1 - WebSocket handshake with /pull/foo 1..1 ok 10 - add a puller # Subtest: fourth message ok 1 - send message ok 2 - first puller got fourth message ok 3 - exact match for message ok 4 - other pullers got no message 1..4 ok 11 - fourth message # Subtest: fifth message ok 1 - send message ok 2 - third puller got fifth message ok 3 - exact match for message ok 4 - other pullers got no message 1..4 ok 12 - fifth message # Subtest: remove all pullers ok 1 - closed WebSocket ok 2 - closed WebSocket 1..2 ok 13 - remove all pullers # Subtest: start again ok 1 - WebSocket handshake with /pull/foo 1..1 ok 14 - start again # Subtest: sixth message ok 1 - send message ok 2 - fourth puller got sixth message ok 3 - exact match for message 1..3 ok 15 - sixth message ok 16 - closed WebSocket ok 17 - closed WebSocket 1..17 ok 3 - push/pull 1..3 ok All tests successful. Files=4, Tests=14, 8 wallclock secs ( 0.07 usr 0.02 sys + 2.79 cusr 0.33 csys = 3.21 CPU) Result: PASS PREACTION/Mercury-0.004.tar.gz make test TEST_VERBOSE=1 -- OK Doug Bell <preaction@cpan.org> A message broker for WebSockets >>> (cd /Users/fly2000/var/cpan/build/Mercury-0.004-931RK_ && tar cvf - Mercury-0.004.ppd blib) | gzip -c >/Users/fly2000/var/REPO/P/PR/PREACTION/Mercury-0.004.tar.gz Mercury-0.004.ppd blib/ blib/lib/ blib/lib/Mercury/ blib/lib/Mercury/Command/ blib/lib/Mercury/Command/mercury/ blib/lib/Mercury/Command/mercury/broker.pm blib/lib/Mercury/Command/mercury.pm blib/lib/Mercury/PushPull.pm blib/lib/Mercury/templates/ blib/lib/Mercury/templates/bus.html.ep blib/lib/Mercury/templates/index.html.ep blib/lib/Mercury/templates/layouts/ blib/lib/Mercury/templates/layouts/standard.html.ep blib/lib/Mercury/templates/pubsub.html.ep blib/lib/Mercury/templates/pushpull.html.ep blib/lib/Mercury.pm blib/man1/ blib/man1/mercury.1 blib/man3/ blib/man3/Mercury.3 blib/man3/Mercury::Command::mercury.3 blib/man3/Mercury::Command::mercury::broker.3 blib/man3/Mercury::PushPull.3 blib/script/ blib/script/mercury >>> mv /Users/fly2000/var/cpan/build/Mercury-0.004-931RK_/Mercury-0.004.ppd /Users/fly2000/var/REPO/P/PR/PREACTION Finished 2015-10-06T10:33:29