PATH=/opt/SUNWspro/bin:/usr/local/bin:/opt/csw/bin:/usr/ccs/bin:/usr/bin:/usr/sbin Start 2015-10-06T18:38:56 ActivePerl-1205 CPAN-2.00 Reading '/export/home/fly1232/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://cpan.nas1.activestate.com/authors/id/P/PR/PREACTION/Mercury-0.004.tar.gz Fetching with LWP: http://cpan.nas1.activestate.com/authors/id/P/PR/PREACTION/CHECKSUMS Checksum for /export/home/fly1232/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 >>> /export/home/fly1232/bin/perl 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/templates/layouts/standard.html.ep blib/lib/Mercury/templates/layouts/standard.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 lib/Mercury/templates/pubsub.html.ep blib/lib/Mercury/templates/pubsub.html.ep cp lib/Mercury/Command/mercury.pm blib/lib/Mercury/Command/mercury.pm cp lib/Mercury/templates/bus.html.ep blib/lib/Mercury/templates/bus.html.ep cp lib/Mercury/templates/pushpull.html.ep blib/lib/Mercury/templates/pushpull.html.ep cp lib/Mercury.pm blib/lib/Mercury.pm cp bin/mercury blib/script/mercury "/export/home/fly1232/bin/perl" -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 "/export/home/fly1232/bin/perl" "-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.28 # IPC::Open3 any 1.05 # 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.78 # 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 18:39:17 2015] [debug] Reading configuration file "/data1/fly1232-var/cpan/build/Mercury-0.004-VvQ9_V/t/share/allow_origin.conf" [Tue Oct 6 18:39:17 2015] [debug] Reading configuration file "/data1/fly1232-var/cpan/build/Mercury-0.004-VvQ9_V/t/share/allow_origin.conf" [Tue Oct 6 18:39:17 2015] [debug] Your secret passphrase needs to be changed [Tue Oct 6 18:39:17 2015] [debug] GET "/bus/fry" [Tue Oct 6 18:39:17 2015] [debug] Routing to a callback [Tue Oct 6 18:39:17 2015] [debug] Routing to a callback [Tue Oct 6 18:39:17 2015] [debug] 101 Switching Protocols (0.003254s, 307.314/s) [Tue Oct 6 18:39:17 2015] [debug] GET "/bus/leela" [Tue Oct 6 18:39:17 2015] [debug] Routing to a callback [Tue Oct 6 18:39:17 2015] [debug] 401 Unauthorized (0.002264s, 441.696/s) [Tue Oct 6 18:39:17 2015] [debug] GET "/bus/bender" [Tue Oct 6 18:39:17 2015] [debug] Routing to a callback [Tue Oct 6 18:39:17 2015] [debug] 401 Unauthorized (0.001999s, 500.250/s) [Tue Oct 6 18:39:17 2015] [debug] GET "/sub/fry" [Tue Oct 6 18:39:17 2015] [debug] Routing to a callback [Tue Oct 6 18:39:17 2015] [debug] Routing to a callback [Tue Oct 6 18:39:17 2015] [debug] 101 Switching Protocols (0.002290s, 436.681/s) [Tue Oct 6 18:39:17 2015] [debug] GET "/sub/leela" [Tue Oct 6 18:39:17 2015] [debug] Routing to a callback [Tue Oct 6 18:39:17 2015] [debug] 401 Unauthorized (0.001981s, 504.796/s) [Tue Oct 6 18:39:17 2015] [debug] GET "/sub/bender" [Tue Oct 6 18:39:17 2015] [debug] Routing to a callback [Tue Oct 6 18:39:17 2015] [debug] 401 Unauthorized (0.001918s, 521.376/s) [Tue Oct 6 18:39:17 2015] [debug] GET "/pub/fry" [Tue Oct 6 18:39:17 2015] [debug] Routing to a callback [Tue Oct 6 18:39:17 2015] [debug] Routing to a callback [Tue Oct 6 18:39:17 2015] [debug] 101 Switching Protocols (0.002141s, 467.071/s) [Tue Oct 6 18:39:17 2015] [debug] GET "/pub/leela" [Tue Oct 6 18:39:17 2015] [debug] Routing to a callback [Tue Oct 6 18:39:17 2015] [debug] 401 Unauthorized (0.001983s, 504.286/s) [Tue Oct 6 18:39:17 2015] [debug] GET "/pub/bender" [Tue Oct 6 18:39:17 2015] [debug] Routing to a callback [Tue Oct 6 18:39:17 2015] [debug] 401 Unauthorized (0.001994s, 501.505/s) [Tue Oct 6 18:39:17 2015] [debug] GET "/push/fry" [Tue Oct 6 18:39:17 2015] [debug] Routing to a callback [Tue Oct 6 18:39:17 2015] [debug] Routing to a callback [Tue Oct 6 18:39:17 2015] [debug] 101 Switching Protocols (0.002627s, 380.662/s) [Tue Oct 6 18:39:17 2015] [debug] GET "/push/leela" [Tue Oct 6 18:39:17 2015] [debug] Routing to a callback [Tue Oct 6 18:39:17 2015] [debug] 401 Unauthorized (0.002088s, 478.927/s) [Tue Oct 6 18:39:17 2015] [debug] GET "/push/bender" [Tue Oct 6 18:39:17 2015] [debug] Routing to a callback [Tue Oct 6 18:39:17 2015] [debug] 401 Unauthorized (0.002050s, 487.805/s) [Tue Oct 6 18:39:17 2015] [debug] GET "/pull/fry" [Tue Oct 6 18:39:17 2015] [debug] Routing to a callback [Tue Oct 6 18:39:17 2015] [debug] Routing to a callback [Tue Oct 6 18:39:17 2015] [debug] 101 Switching Protocols (0.002533s, 394.789/s) [Tue Oct 6 18:39:17 2015] [debug] GET "/pull/leela" [Tue Oct 6 18:39:17 2015] [debug] Routing to a callback [Tue Oct 6 18:39:17 2015] [debug] 401 Unauthorized (0.002118s, 472.144/s) [Tue Oct 6 18:39:17 2015] [debug] GET "/pull/bender" [Tue Oct 6 18:39:17 2015] [debug] Routing to a callback [Tue Oct 6 18:39:17 2015] [debug] 401 Unauthorized (0.002101s, 475.964/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 18:39:18 2015] [debug] Your secret passphrase needs to be changed [Tue Oct 6 18:39:18 2015] [debug] GET "/bus/foo" [Tue Oct 6 18:39:18 2015] [debug] Routing to a callback [Tue Oct 6 18:39:18 2015] [debug] 101 Switching Protocols (0.002621s, 381.534/s) [Tue Oct 6 18:39:18 2015] [debug] GET "/bus/foo" [Tue Oct 6 18:39:18 2015] [debug] Routing to a callback [Tue Oct 6 18:39:18 2015] [debug] 101 Switching Protocols (0.001187s, 842.460/s) [Tue Oct 6 18:39:18 2015] [debug] GET "/bus/foo" [Tue Oct 6 18:39:18 2015] [debug] Routing to a callback [Tue Oct 6 18:39:18 2015] [debug] 101 Switching Protocols (0.001180s, 847.458/s) [Tue Oct 6 18:39:18 2015] [debug] GET "/bus/foo" [Tue Oct 6 18:39:18 2015] [debug] Routing to a callback [Tue Oct 6 18:39:18 2015] [debug] 101 Switching Protocols (0.001192s, 838.926/s) [Tue Oct 6 18:39:18 2015] [debug] GET "/bus/bar" [Tue Oct 6 18:39:18 2015] [debug] Routing to a callback [Tue Oct 6 18:39:18 2015] [debug] 101 Switching Protocols (0.001762s, 567.537/s) [Tue Oct 6 18:39:19 2015] [debug] GET "/pub/foo" [Tue Oct 6 18:39:19 2015] [debug] Routing to a callback [Tue Oct 6 18:39:19 2015] [debug] 101 Switching Protocols (0.001667s, 599.880/s) [Tue Oct 6 18:39:19 2015] [debug] GET "/sub/foo" [Tue Oct 6 18:39:19 2015] [debug] Routing to a callback [Tue Oct 6 18:39:19 2015] [debug] 101 Switching Protocols (0.001698s, 588.928/s) [Tue Oct 6 18:39:19 2015] [debug] GET "/sub/foo" [Tue Oct 6 18:39:19 2015] [debug] Routing to a callback [Tue Oct 6 18:39:19 2015] [debug] 101 Switching Protocols (0.001210s, 826.446/s) [Tue Oct 6 18:39:19 2015] [debug] GET "/sub/foo" [Tue Oct 6 18:39:19 2015] [debug] Routing to a callback [Tue Oct 6 18:39:19 2015] [debug] 101 Switching Protocols (0.001217s, 821.693/s) [Tue Oct 6 18:39:19 2015] [debug] GET "/sub/foo/bar" [Tue Oct 6 18:39:19 2015] [debug] Routing to a callback [Tue Oct 6 18:39:19 2015] [debug] 101 Switching Protocols (0.001723s, 580.383/s) [Tue Oct 6 18:39:19 2015] [debug] GET "/pub/foo/bar" [Tue Oct 6 18:39:19 2015] [debug] Routing to a callback [Tue Oct 6 18:39:19 2015] [debug] 101 Switching Protocols (0.001768s, 565.611/s) [Tue Oct 6 18:39:19 2015] [debug] GET "/pub/foo" [Tue Oct 6 18:39:19 2015] [debug] Routing to a callback [Tue Oct 6 18:39:19 2015] [debug] 101 Switching Protocols (0.001146s, 872.600/s) [Tue Oct 6 18:39:19 2015] [debug] GET "/pull/foo" [Tue Oct 6 18:39:19 2015] [debug] Routing to a callback [Tue Oct 6 18:39:19 2015] [debug] 101 Switching Protocols (0.002197s, 455.166/s) [Tue Oct 6 18:39:19 2015] [debug] GET "/pull/foo" [Tue Oct 6 18:39:19 2015] [debug] Routing to a callback [Tue Oct 6 18:39:19 2015] [debug] 101 Switching Protocols (0.001133s, 882.613/s) [Tue Oct 6 18:39:19 2015] [debug] GET "/pull/bar" [Tue Oct 6 18:39:19 2015] [debug] Routing to a callback [Tue Oct 6 18:39:19 2015] [debug] 101 Switching Protocols (0.001786s, 559.910/s) [Tue Oct 6 18:39:19 2015] [debug] GET "/push/foo" [Tue Oct 6 18:39:19 2015] [debug] Routing to a callback [Tue Oct 6 18:39:19 2015] [debug] 101 Switching Protocols (0.001730s, 578.035/s) # Failed test 'first puller got third message' # at t/broker.t line 173. # Failed test 'exact match for message' # at t/broker.t line 173. # got: '' # expected: 'Hello' # Looks like you failed 2 tests of 3. # Failed test 'fourth message' # at t/broker.t line 177. [Tue Oct 6 18:39:19 2015] [debug] GET "/pull/foo" [Tue Oct 6 18:39:19 2015] [debug] Routing to a callback [Tue Oct 6 18:39:19 2015] [debug] 101 Switching Protocols (0.001154s, 866.551/s) # Failed test 'other pullers got no message' # at t/broker.t line 206. # Looks like you failed 1 test of 4. # Failed test 'fifth message' # at t/broker.t line 208. [Tue Oct 6 18:39:19 2015] [debug] GET "/pull/foo" [Tue Oct 6 18:39:19 2015] [debug] Routing to a callback [Tue Oct 6 18:39:19 2015] [debug] 101 Switching Protocols (0.001170s, 854.701/s) # Failed test 'fourth puller got sixth message' # at t/broker.t line 233. # Failed test 'exact match for message' # at t/broker.t line 233. # got: '' # expected: 'Hello' # Looks like you failed 2 tests of 3. # Failed test 'sixth message' # at t/broker.t line 237. # Looks like you failed 3 tests of 17. # Failed test 'push/pull' # at t/broker.t line 241. # Looks like you failed 1 test of 3. 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 not ok 2 - first puller got third message not ok 3 - exact match for message 1..3 not 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 not ok 4 - other pullers got no message 1..4 not 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 not ok 2 - fourth puller got sixth message not ok 3 - exact match for message 1..3 not ok 15 - sixth message ok 16 - closed WebSocket ok 17 - closed WebSocket 1..17 not ok 3 - push/pull 1..3 Dubious, test returned 1 (wstat 256, 0x100) Failed 1/3 subtests Test Summary Report ------------------- t/broker.t (Wstat: 256 Tests: 3 Failed: 1) Failed test: 3 Non-zero exit status: 1 Files=4, Tests=14, 9 wallclock secs ( 0.15 usr 0.06 sys + 8.16 cusr 0.86 csys = 9.23 CPU) Result: FAIL Failed 1/4 test programs. 1/14 subtests failed. make: *** [test_dynamic] Error 1 PREACTION/Mercury-0.004.tar.gz make test TEST_VERBOSE=1 -- NOT OK //hint// to see the cpan-testers results for installing this module, try: reports PREACTION/Mercury-0.004.tar.gz Finished 2015-10-06T18:39:19