PATH=/usr/bin:/bin:/Users/fly2200/var/megalib/bin Start 2015-10-10T06:41:57 ActivePerl-2200 CPAN-2.00 Reading '/Users/fly2200/var/cpan/Metadata' Database was generated on Sat, 10 Oct 2015 04:53:32 GMT Running make for P/PR/PREACTION/Mercury-0.005.tar.gz Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/P/PR/PREACTION/Mercury-0.005.tar.gz Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/P/PR/PREACTION/CHECKSUMS Checksum for /Users/fly2200/var/cpan/sources/authors/id/P/PR/PREACTION/Mercury-0.005.tar.gz ok Mercury-0.005/ Mercury-0.005/bin/ Mercury-0.005/CHANGES Mercury-0.005/CONTRIBUTING.md Mercury-0.005/cpanfile Mercury-0.005/lib/ Mercury-0.005/LICENSE Mercury-0.005/Makefile.PL Mercury-0.005/MANIFEST Mercury-0.005/META.yml Mercury-0.005/README.mkdn Mercury-0.005/t/ Mercury-0.005/t/00-compile.t Mercury-0.005/t/00-report-prereqs.dd Mercury-0.005/t/00-report-prereqs.t Mercury-0.005/t/allow_origin.t Mercury-0.005/t/bus.t Mercury-0.005/t/pubsub.t Mercury-0.005/t/pushpull.t Mercury-0.005/t/share/ Mercury-0.005/t/share/allow_origin.conf Mercury-0.005/lib/Mercury/ Mercury-0.005/lib/Mercury.pm Mercury-0.005/lib/Mercury/Command/ Mercury-0.005/lib/Mercury/PushPull.pm Mercury-0.005/lib/Mercury/templates/ Mercury-0.005/lib/Mercury/templates/bus.html.ep Mercury-0.005/lib/Mercury/templates/index.html.ep Mercury-0.005/lib/Mercury/templates/layouts/ Mercury-0.005/lib/Mercury/templates/pubsub.html.ep Mercury-0.005/lib/Mercury/templates/pushpull.html.ep Mercury-0.005/lib/Mercury/templates/layouts/standard.html.ep Mercury-0.005/lib/Mercury/Command/mercury/ Mercury-0.005/lib/Mercury/Command/mercury.pm Mercury-0.005/lib/Mercury/Command/mercury/broker.pm Mercury-0.005/bin/mercury CPAN.pm: Building P/PR/PREACTION/Mercury-0.005.tar.gz >>> /Users/fly2200/ap2200-299195/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/templates/bus.html.ep blib/lib/Mercury/templates/bus.html.ep cp lib/Mercury.pm blib/lib/Mercury.pm cp lib/Mercury/PushPull.pm blib/lib/Mercury/PushPull.pm cp lib/Mercury/templates/pushpull.html.ep blib/lib/Mercury/templates/pushpull.html.ep cp lib/Mercury/Command/mercury.pm blib/lib/Mercury/Command/mercury.pm cp lib/Mercury/templates/layouts/standard.html.ep blib/lib/Mercury/templates/layouts/standard.html.ep cp lib/Mercury/templates/index.html.ep blib/lib/Mercury/templates/index.html.ep cp lib/Mercury/Command/mercury/broker.pm blib/lib/Mercury/Command/mercury/broker.pm cp lib/Mercury/templates/pubsub.html.ep blib/lib/Mercury/templates/pubsub.html.ep cp bin/mercury blib/script/mercury "/Users/fly2200/ap2200-299195/bin/perl-dynamic" -MExtUtils::MY -e 'MY->fixin(shift)' -- blib/script/mercury Manifying 1 pod document Manifying 4 pod documents PREACTION/Mercury-0.005.tar.gz make -- OK Running make test >>> make test TEST_VERBOSE=1 PERL_DL_NONLAZY=1 "/Users/fly2200/ap2200-299195/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.18 # Test::More 1.001005 1.001014 # # === 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.23 # Scalar::Util any 1.42 # t/00-report-prereqs.t .. 1..1 ok 1 ok [Sat Oct 10 06:42:08 2015] [debug] Reading configuration file "/Users/fly2200/var/cpan/build/Mercury-0.005-OMjcRG/t/share/allow_origin.conf" [Sat Oct 10 06:42:08 2015] [debug] Reading configuration file "/Users/fly2200/var/cpan/build/Mercury-0.005-OMjcRG/t/share/allow_origin.conf" [Sat Oct 10 06:42:08 2015] [debug] Your secret passphrase needs to be changed [Sat Oct 10 06:42:08 2015] [debug] GET "/bus/fry" [Sat Oct 10 06:42:08 2015] [debug] Routing to a callback [Sat Oct 10 06:42:08 2015] [debug] Routing to a callback [Sat Oct 10 06:42:08 2015] [debug] 101 Switching Protocols (0.000721s, 1386.963/s) [Sat Oct 10 06:42:08 2015] [debug] GET "/bus/leela" [Sat Oct 10 06:42:08 2015] [debug] Routing to a callback [Sat Oct 10 06:42:08 2015] [debug] 401 Unauthorized (0.000654s, 1529.052/s) [Sat Oct 10 06:42:08 2015] [debug] GET "/bus/bender" [Sat Oct 10 06:42:08 2015] [debug] Routing to a callback [Sat Oct 10 06:42:08 2015] [debug] 401 Unauthorized (0.000444s, 2252.252/s) [Sat Oct 10 06:42:08 2015] [debug] GET "/sub/fry" [Sat Oct 10 06:42:08 2015] [debug] Routing to a callback [Sat Oct 10 06:42:08 2015] [debug] Routing to a callback [Sat Oct 10 06:42:08 2015] [debug] 101 Switching Protocols (0.000471s, 2123.142/s) [Sat Oct 10 06:42:08 2015] [debug] GET "/sub/leela" [Sat Oct 10 06:42:08 2015] [debug] Routing to a callback [Sat Oct 10 06:42:08 2015] [debug] 401 Unauthorized (0.000411s, 2433.090/s) [Sat Oct 10 06:42:08 2015] [debug] GET "/sub/bender" [Sat Oct 10 06:42:08 2015] [debug] Routing to a callback [Sat Oct 10 06:42:08 2015] [debug] 401 Unauthorized (0.000341s, 2932.551/s) [Sat Oct 10 06:42:08 2015] [debug] GET "/pub/fry" [Sat Oct 10 06:42:08 2015] [debug] Routing to a callback [Sat Oct 10 06:42:08 2015] [debug] Routing to a callback [Sat Oct 10 06:42:08 2015] [debug] 101 Switching Protocols (0.000452s, 2212.389/s) [Sat Oct 10 06:42:08 2015] [debug] GET "/pub/leela" [Sat Oct 10 06:42:08 2015] [debug] Routing to a callback [Sat Oct 10 06:42:08 2015] [debug] 401 Unauthorized (0.000412s, 2427.184/s) [Sat Oct 10 06:42:08 2015] [debug] GET "/pub/bender" [Sat Oct 10 06:42:08 2015] [debug] Routing to a callback [Sat Oct 10 06:42:08 2015] [debug] 401 Unauthorized (0.000390s, 2564.103/s) [Sat Oct 10 06:42:08 2015] [debug] GET "/push/fry" [Sat Oct 10 06:42:08 2015] [debug] Routing to a callback [Sat Oct 10 06:42:08 2015] [debug] Routing to a callback [Sat Oct 10 06:42:08 2015] [debug] 101 Switching Protocols (0.000553s, 1808.318/s) [Sat Oct 10 06:42:08 2015] [debug] GET "/push/leela" [Sat Oct 10 06:42:08 2015] [debug] Routing to a callback [Sat Oct 10 06:42:08 2015] [debug] 401 Unauthorized (0.000428s, 2336.449/s) [Sat Oct 10 06:42:08 2015] [debug] GET "/push/bender" [Sat Oct 10 06:42:08 2015] [debug] Routing to a callback [Sat Oct 10 06:42:08 2015] [debug] 401 Unauthorized (0.000411s, 2433.090/s) [Sat Oct 10 06:42:08 2015] [debug] GET "/pull/fry" [Sat Oct 10 06:42:08 2015] [debug] Routing to a callback [Sat Oct 10 06:42:08 2015] [debug] Routing to a callback [Sat Oct 10 06:42:08 2015] [debug] 101 Switching Protocols (0.000525s, 1904.762/s) [Sat Oct 10 06:42:08 2015] [debug] GET "/pull/leela" [Sat Oct 10 06:42:08 2015] [debug] Routing to a callback [Sat Oct 10 06:42:08 2015] [debug] 401 Unauthorized (0.000403s, 2481.390/s) [Sat Oct 10 06:42:08 2015] [debug] GET "/pull/bender" [Sat Oct 10 06:42:08 2015] [debug] Routing to a callback [Sat Oct 10 06:42:08 2015] [debug] 401 Unauthorized (0.000448s, 2232.143/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 [Sat Oct 10 06:42:09 2015] [debug] Your secret passphrase needs to be changed [Sat Oct 10 06:42:09 2015] [debug] GET "/bus/foo" [Sat Oct 10 06:42:09 2015] [debug] Routing to a callback [Sat Oct 10 06:42:09 2015] [debug] 101 Switching Protocols (0.000645s, 1550.388/s) [Sat Oct 10 06:42:09 2015] [debug] GET "/bus/foo" [Sat Oct 10 06:42:09 2015] [debug] Routing to a callback [Sat Oct 10 06:42:09 2015] [debug] 101 Switching Protocols (0.000285s, 3508.772/s) [Sat Oct 10 06:42:09 2015] [debug] GET "/bus/foo" [Sat Oct 10 06:42:09 2015] [debug] Routing to a callback [Sat Oct 10 06:42:09 2015] [debug] 101 Switching Protocols (0.000285s, 3508.772/s) [Sat Oct 10 06:42:09 2015] [debug] GET "/bus/foo" [Sat Oct 10 06:42:09 2015] [debug] Routing to a callback [Sat Oct 10 06:42:09 2015] [debug] 101 Switching Protocols (0.000287s, 3484.321/s) [Sat Oct 10 06:42:09 2015] [debug] GET "/bus/bar" [Sat Oct 10 06:42:09 2015] [debug] Routing to a callback [Sat Oct 10 06:42:09 2015] [debug] 101 Switching Protocols (0.000538s, 1858.736/s) t/bus.t ................ 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 [Sat Oct 10 06:42:10 2015] [debug] Your secret passphrase needs to be changed [Sat Oct 10 06:42:10 2015] [debug] GET "/pub/foo" [Sat Oct 10 06:42:10 2015] [debug] Routing to a callback [Sat Oct 10 06:42:10 2015] [debug] 101 Switching Protocols (0.000581s, 1721.170/s) [Sat Oct 10 06:42:10 2015] [debug] GET "/sub/foo" [Sat Oct 10 06:42:10 2015] [debug] Routing to a callback [Sat Oct 10 06:42:10 2015] [debug] 101 Switching Protocols (0.000361s, 2770.083/s) [Sat Oct 10 06:42:10 2015] [debug] GET "/sub/foo" [Sat Oct 10 06:42:10 2015] [debug] Routing to a callback [Sat Oct 10 06:42:10 2015] [debug] 101 Switching Protocols (0.000286s, 3496.503/s) [Sat Oct 10 06:42:10 2015] [debug] GET "/sub/foo" [Sat Oct 10 06:42:10 2015] [debug] Routing to a callback [Sat Oct 10 06:42:10 2015] [debug] 101 Switching Protocols (0.000316s, 3164.557/s) [Sat Oct 10 06:42:10 2015] [debug] GET "/sub/foo/bar" [Sat Oct 10 06:42:10 2015] [debug] Routing to a callback [Sat Oct 10 06:42:10 2015] [debug] 101 Switching Protocols (0.000408s, 2450.980/s) [Sat Oct 10 06:42:10 2015] [debug] GET "/pub/foo/bar" [Sat Oct 10 06:42:10 2015] [debug] Routing to a callback [Sat Oct 10 06:42:10 2015] [debug] 101 Switching Protocols (0.000406s, 2463.054/s) [Sat Oct 10 06:42:10 2015] [debug] GET "/pub/foo" [Sat Oct 10 06:42:10 2015] [debug] Routing to a callback [Sat Oct 10 06:42:10 2015] [debug] 101 Switching Protocols (0.000240s, 4166.667/s) t/pubsub.t ............. # 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 [Sat Oct 10 06:42:11 2015] [debug] Your secret passphrase needs to be changed [Sat Oct 10 06:42:11 2015] [debug] GET "/pull/foo" [Sat Oct 10 06:42:11 2015] [debug] Routing to a callback [Sat Oct 10 06:42:11 2015] [debug] 101 Switching Protocols (0.020726s, 48.249/s) [Sat Oct 10 06:42:11 2015] [debug] GET "/pull/foo" [Sat Oct 10 06:42:11 2015] [debug] Routing to a callback [Sat Oct 10 06:42:11 2015] [debug] 101 Switching Protocols (0.000282s, 3546.099/s) [Sat Oct 10 06:42:11 2015] [debug] GET "/pull/bar" [Sat Oct 10 06:42:11 2015] [debug] Routing to a callback [Sat Oct 10 06:42:11 2015] [debug] 101 Switching Protocols (0.000457s, 2188.184/s) [Sat Oct 10 06:42:11 2015] [debug] GET "/push/foo" [Sat Oct 10 06:42:11 2015] [debug] Routing to a callback [Sat Oct 10 06:42:11 2015] [debug] 101 Switching Protocols (0.000407s, 2457.002/s) [Sat Oct 10 06:42:11 2015] [debug] GET "/pull/foo" [Sat Oct 10 06:42:11 2015] [debug] Routing to a callback [Sat Oct 10 06:42:11 2015] [debug] 101 Switching Protocols (0.000299s, 3344.482/s) [Sat Oct 10 06:42:11 2015] [debug] GET "/pull/foo" [Sat Oct 10 06:42:11 2015] [debug] Routing to a callback [Sat Oct 10 06:42:11 2015] [debug] 101 Switching Protocols (0.000294s, 3401.361/s) t/pushpull.t ........... 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 All tests successful. Files=6, Tests=42, 7 wallclock secs ( 0.05 usr 0.02 sys + 2.42 cusr 0.32 csys = 2.81 CPU) Result: PASS PREACTION/Mercury-0.005.tar.gz make test TEST_VERBOSE=1 -- OK Doug Bell <preaction@cpan.org> A message broker for WebSockets >>> (cd /Users/fly2200/var/cpan/build/Mercury-0.005-OMjcRG && tar cvf - Mercury-0.005.ppd blib) | gzip -c >/Users/fly2200/var/REPO/P/PR/PREACTION/Mercury-0.005.tar.gz Mercury-0.005.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/fly2200/var/cpan/build/Mercury-0.005-OMjcRG/Mercury-0.005.ppd /Users/fly2200/var/REPO/P/PR/PREACTION Finished 2015-10-10T06:42:12