PATH=/usr/bin:/bin:/Users/fly1800/var/megalib/bin Start 2015-11-21T07:03:02 ActivePerl-1800 CPAN-2.00 Reading '/Users/fly1800/var/cpan/Metadata' Database was generated on Sat, 21 Nov 2015 06:17:02 GMT Running make for P/PR/PREACTION/Mercury-0.006.tar.gz Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/P/PR/PREACTION/Mercury-0.006.tar.gz Fetching with LWP: http://ppm.activestate.com/CPAN/authors/id/P/PR/PREACTION/CHECKSUMS Checksum for /Users/fly1800/var/cpan/sources/authors/id/P/PR/PREACTION/Mercury-0.006.tar.gz ok Mercury-0.006/ Mercury-0.006/bin/ Mercury-0.006/CHANGES Mercury-0.006/CONTRIBUTING.md Mercury-0.006/cpanfile Mercury-0.006/eg/ Mercury-0.006/lib/ Mercury-0.006/LICENSE Mercury-0.006/Makefile.PL Mercury-0.006/MANIFEST Mercury-0.006/META.yml Mercury-0.006/README.mkdn Mercury-0.006/t/ Mercury-0.006/t/00-compile.t Mercury-0.006/t/00-report-prereqs.dd Mercury-0.006/t/00-report-prereqs.t Mercury-0.006/t/allow_origin.t Mercury-0.006/t/bus.t Mercury-0.006/t/pubsub.t Mercury-0.006/t/pushpull.t Mercury-0.006/t/share/ Mercury-0.006/t/share/allow_origin.conf Mercury-0.006/lib/Mercury/ Mercury-0.006/lib/Mercury.pm Mercury-0.006/lib/Mercury/Command/ Mercury-0.006/lib/Mercury/PushPull.pm Mercury-0.006/lib/Mercury/templates/ Mercury-0.006/lib/Mercury/templates/bus.html.ep Mercury-0.006/lib/Mercury/templates/index.html.ep Mercury-0.006/lib/Mercury/templates/layouts/ Mercury-0.006/lib/Mercury/templates/pubsub.html.ep Mercury-0.006/lib/Mercury/templates/pushpull.html.ep Mercury-0.006/lib/Mercury/templates/layouts/standard.html.ep Mercury-0.006/lib/Mercury/Command/mercury/ Mercury-0.006/lib/Mercury/Command/mercury.pm Mercury-0.006/lib/Mercury/Command/mercury/broker.pm Mercury-0.006/eg/chat.html Mercury-0.006/bin/mercury CPAN.pm: Building P/PR/PREACTION/Mercury-0.006.tar.gz >>> /Users/fly1800/ap1800-297214/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/Command/mercury/broker.pm blib/lib/Mercury/Command/mercury/broker.pm 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/index.html.ep blib/lib/Mercury/templates/index.html.ep cp lib/Mercury/Command/mercury.pm blib/lib/Mercury/Command/mercury.pm cp lib/Mercury/templates/pubsub.html.ep blib/lib/Mercury/templates/pubsub.html.ep cp lib/Mercury/templates/pushpull.html.ep blib/lib/Mercury/templates/pushpull.html.ep cp lib/Mercury/templates/bus.html.ep blib/lib/Mercury/templates/bus.html.ep cp lib/Mercury/PushPull.pm blib/lib/Mercury/PushPull.pm cp bin/mercury blib/script/mercury "/Users/fly1800/ap1800-297214/bin/perl-dynamic" -MExtUtils::MY -e 'MY->fixin(shift)' -- blib/script/mercury Manifying 1 pod document Manifying 4 pod documents PREACTION/Mercury-0.006.tar.gz make -- OK Running make test >>> make test TEST_VERBOSE=1 PERL_DL_NONLAZY=1 "/Users/fly1800/ap1800-297214/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.60 # IO::Handle any 1.34 # IPC::Open3 any 1.13 # 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.84 # File::Spec::Functions any 3.60 # Mojolicious 6.00 6.32 # Scalar::Util any 1.42 # t/00-report-prereqs.t .. 1..1 ok 1 ok [Sat Nov 21 07:03:11 2015] [debug] Reading configuration file "/Users/fly1800/var/cpan/build/Mercury-0.006-mUe1Zi/t/share/allow_origin.conf" [Sat Nov 21 07:03:11 2015] [debug] Reading configuration file "/Users/fly1800/var/cpan/build/Mercury-0.006-mUe1Zi/t/share/allow_origin.conf" [Sat Nov 21 07:03:11 2015] [debug] GET "/bus/fry" [Sat Nov 21 07:03:11 2015] [debug] Routing to a callback [Sat Nov 21 07:03:11 2015] [debug] Routing to a callback [Sat Nov 21 07:03:11 2015] [debug] 101 Switching Protocols (0.001219s, 820.345/s) [Sat Nov 21 07:03:11 2015] [debug] GET "/bus/leela" [Sat Nov 21 07:03:11 2015] [debug] Routing to a callback [Sat Nov 21 07:03:11 2015] [debug] 401 Unauthorized (0.000706s, 1416.431/s) [Sat Nov 21 07:03:11 2015] [debug] GET "/bus/bender" [Sat Nov 21 07:03:11 2015] [debug] Routing to a callback [Sat Nov 21 07:03:11 2015] [debug] 401 Unauthorized (0.000600s, 1666.667/s) [Sat Nov 21 07:03:11 2015] [debug] GET "/sub/fry" [Sat Nov 21 07:03:11 2015] [debug] Routing to a callback [Sat Nov 21 07:03:11 2015] [debug] Routing to a callback [Sat Nov 21 07:03:11 2015] [debug] 101 Switching Protocols (0.000670s, 1492.537/s) [Sat Nov 21 07:03:11 2015] [debug] GET "/sub/leela" [Sat Nov 21 07:03:11 2015] [debug] Routing to a callback [Sat Nov 21 07:03:11 2015] [debug] 401 Unauthorized (0.000579s, 1727.116/s) [Sat Nov 21 07:03:11 2015] [debug] GET "/sub/bender" [Sat Nov 21 07:03:11 2015] [debug] Routing to a callback [Sat Nov 21 07:03:11 2015] [debug] 401 Unauthorized (0.000570s, 1754.386/s) [Sat Nov 21 07:03:11 2015] [debug] GET "/pub/fry" [Sat Nov 21 07:03:11 2015] [debug] Routing to a callback [Sat Nov 21 07:03:11 2015] [debug] Routing to a callback [Sat Nov 21 07:03:11 2015] [debug] 101 Switching Protocols (0.000634s, 1577.287/s) [Sat Nov 21 07:03:11 2015] [debug] GET "/pub/leela" [Sat Nov 21 07:03:11 2015] [debug] Routing to a callback [Sat Nov 21 07:03:11 2015] [debug] 401 Unauthorized (0.000592s, 1689.189/s) [Sat Nov 21 07:03:11 2015] [debug] GET "/pub/bender" [Sat Nov 21 07:03:11 2015] [debug] Routing to a callback [Sat Nov 21 07:03:11 2015] [debug] 401 Unauthorized (0.000584s, 1712.329/s) [Sat Nov 21 07:03:11 2015] [debug] GET "/push/fry" [Sat Nov 21 07:03:11 2015] [debug] Routing to a callback [Sat Nov 21 07:03:11 2015] [debug] Routing to a callback [Sat Nov 21 07:03:11 2015] [debug] 101 Switching Protocols (0.000767s, 1303.781/s) [Sat Nov 21 07:03:11 2015] [debug] GET "/push/leela" [Sat Nov 21 07:03:11 2015] [debug] Routing to a callback [Sat Nov 21 07:03:11 2015] [debug] 401 Unauthorized (0.000629s, 1589.825/s) [Sat Nov 21 07:03:11 2015] [debug] GET "/push/bender" [Sat Nov 21 07:03:11 2015] [debug] Routing to a callback [Sat Nov 21 07:03:11 2015] [debug] 401 Unauthorized (0.000627s, 1594.896/s) [Sat Nov 21 07:03:11 2015] [debug] GET "/pull/fry" [Sat Nov 21 07:03:11 2015] [debug] Routing to a callback [Sat Nov 21 07:03:11 2015] [debug] Routing to a callback [Sat Nov 21 07:03:11 2015] [debug] 101 Switching Protocols (0.000776s, 1288.660/s) [Sat Nov 21 07:03:11 2015] [debug] GET "/pull/leela" [Sat Nov 21 07:03:11 2015] [debug] Routing to a callback [Sat Nov 21 07:03:11 2015] [debug] 401 Unauthorized (0.000638s, 1567.398/s) [Sat Nov 21 07:03:11 2015] [debug] GET "/pull/bender" [Sat Nov 21 07:03:11 2015] [debug] Routing to a callback [Sat Nov 21 07:03:11 2015] [debug] 401 Unauthorized (0.000633s, 1579.779/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 Nov 21 07:03:12 2015] [debug] GET "/bus/foo" [Sat Nov 21 07:03:12 2015] [debug] Routing to a callback [Sat Nov 21 07:03:12 2015] [debug] 101 Switching Protocols (0.000977s, 1023.541/s) [Sat Nov 21 07:03:12 2015] [debug] GET "/bus/foo" [Sat Nov 21 07:03:12 2015] [debug] Routing to a callback [Sat Nov 21 07:03:12 2015] [debug] 101 Switching Protocols (0.000465s, 2150.538/s) [Sat Nov 21 07:03:12 2015] [debug] GET "/bus/foo" [Sat Nov 21 07:03:12 2015] [debug] Routing to a callback [Sat Nov 21 07:03:12 2015] [debug] 101 Switching Protocols (0.000520s, 1923.077/s) [Sat Nov 21 07:03:12 2015] [debug] GET "/bus/foo" [Sat Nov 21 07:03:12 2015] [debug] Routing to a callback [Sat Nov 21 07:03:12 2015] [debug] 101 Switching Protocols (0.000624s, 1602.564/s) [Sat Nov 21 07:03:12 2015] [debug] GET "/bus/bar" [Sat Nov 21 07:03:12 2015] [debug] Routing to a callback [Sat Nov 21 07:03:12 2015] [debug] 101 Switching Protocols (0.000727s, 1375.516/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?echo=1 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 # Subtest: peer 3 ok 1 - peer 3 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 2 received message ok 7 - exact match for message ok 8 - peer 3 received message ok 9 - exact match for message 1..9 ok 8 - peer 3 ok 9 - closed WebSocket ok 10 - closed WebSocket ok 11 - closed WebSocket ok 12 - closed WebSocket ok 13 - closed WebSocket 1..13 ok [Sat Nov 21 07:03:12 2015] [debug] GET "/pub/foo" [Sat Nov 21 07:03:12 2015] [debug] Routing to a callback [Sat Nov 21 07:03:12 2015] [debug] 101 Switching Protocols (0.000736s, 1358.696/s) [Sat Nov 21 07:03:12 2015] [debug] GET "/sub/foo" [Sat Nov 21 07:03:12 2015] [debug] Routing to a callback [Sat Nov 21 07:03:12 2015] [debug] 101 Switching Protocols (0.000554s, 1805.054/s) [Sat Nov 21 07:03:12 2015] [debug] GET "/sub/foo" [Sat Nov 21 07:03:12 2015] [debug] Routing to a callback [Sat Nov 21 07:03:12 2015] [debug] 101 Switching Protocols (0.000387s, 2583.979/s) [Sat Nov 21 07:03:12 2015] [debug] GET "/sub/foo" [Sat Nov 21 07:03:12 2015] [debug] Routing to a callback [Sat Nov 21 07:03:12 2015] [debug] 101 Switching Protocols (0.000374s, 2673.797/s) [Sat Nov 21 07:03:12 2015] [debug] GET "/sub/foo/bar" [Sat Nov 21 07:03:12 2015] [debug] Routing to a callback [Sat Nov 21 07:03:12 2015] [debug] 101 Switching Protocols (0.000532s, 1879.699/s) [Sat Nov 21 07:03:12 2015] [debug] GET "/pub/foo/bar" [Sat Nov 21 07:03:12 2015] [debug] Routing to a callback [Sat Nov 21 07:03:12 2015] [debug] 101 Switching Protocols (0.000521s, 1919.386/s) [Sat Nov 21 07:03:12 2015] [debug] GET "/pub/foo" [Sat Nov 21 07:03:12 2015] [debug] Routing to a callback [Sat Nov 21 07:03:12 2015] [debug] 101 Switching Protocols (0.000345s, 2898.551/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 Nov 21 07:03:13 2015] [debug] GET "/pull/foo" [Sat Nov 21 07:03:13 2015] [debug] Routing to a callback [Sat Nov 21 07:03:13 2015] [debug] 101 Switching Protocols (0.000937s, 1067.236/s) [Sat Nov 21 07:03:13 2015] [debug] GET "/pull/foo" [Sat Nov 21 07:03:13 2015] [debug] Routing to a callback [Sat Nov 21 07:03:13 2015] [debug] 101 Switching Protocols (0.000352s, 2840.909/s) [Sat Nov 21 07:03:13 2015] [debug] GET "/pull/bar" [Sat Nov 21 07:03:13 2015] [debug] Routing to a callback [Sat Nov 21 07:03:13 2015] [debug] 101 Switching Protocols (0.000544s, 1838.235/s) [Sat Nov 21 07:03:13 2015] [debug] GET "/push/foo" [Sat Nov 21 07:03:13 2015] [debug] Routing to a callback [Sat Nov 21 07:03:13 2015] [debug] 101 Switching Protocols (0.000540s, 1851.852/s) [Sat Nov 21 07:03:13 2015] [debug] GET "/pull/foo" [Sat Nov 21 07:03:13 2015] [debug] Routing to a callback [Sat Nov 21 07:03:13 2015] [debug] 101 Switching Protocols (0.000536s, 1865.672/s) [Sat Nov 21 07:03:13 2015] [debug] GET "/pull/foo" [Sat Nov 21 07:03:13 2015] [debug] Routing to a callback [Sat Nov 21 07:03:13 2015] [debug] 101 Switching Protocols (0.000338s, 2958.580/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=43, 6 wallclock secs ( 0.07 usr 0.02 sys + 3.45 cusr 0.41 csys = 3.95 CPU) Result: PASS PREACTION/Mercury-0.006.tar.gz make test TEST_VERBOSE=1 -- OK Doug Bell <preaction@cpan.org> A message broker for WebSockets >>> (cd /Users/fly1800/var/cpan/build/Mercury-0.006-mUe1Zi && tar cvf - Mercury-0.006.ppd blib) | gzip -c >/Users/fly1800/var/REPO/P/PR/PREACTION/Mercury-0.006.tar.gz Mercury-0.006.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/fly1800/var/cpan/build/Mercury-0.006-mUe1Zi/Mercury-0.006.ppd /Users/fly1800/var/REPO/P/PR/PREACTION Finished 2015-11-21T07:03:13