PATH=C:\mingw\bin;C:\cygwin\bin;C:\cpanfly-5.20\var\megalib\bin;C:\Perl64-5.20\site\bin;C:\Perl64-5.20\bin;C:\cygwin\bin;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\WINDOWS\system32\WindowsPowerShell\v1.0;C:\PROGRA~2\Perforce;C:\instantclient_11_2;C:\cygwin\bin;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\WINDOWS\system32\WindowsPowerShell\v1.0;C:\PROGRA~2\Perforce;C:\mysql\bin
Start 2015-10-10T07:05:46
ActivePerl-2000 CPAN-2.00
PATH=C:/CPANFL~1.20/var/libs/bin;C:\mingw\bin;C:\cygwin\bin;C:\CPANFL~1.20\var\megalib\bin;C:\Perl64-5.20\site\bin;C:\Perl64-5.20\bin;C:\cygwin\bin;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\WINDOWS\system32\WINDOW~1\v1.0;C:\PROGRA~2\Perforce;C:\INSTAN~1;C:\cygwin\bin;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\WINDOWS\system32\WINDOW~1\v1.0;C:\PROGRA~2\Perforce;C:\mysql\bin
Reading 'C:\cpanfly-5.20\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://cpan.nas1.activestate.com/authors/id/P/PR/PREACTION/Mercury-0.005.tar.gz
Fetching with LWP:
http://cpan.nas1.activestate.com/authors/id/P/PR/PREACTION/CHECKSUMS
Checksum for C:\cpanfly-5.20\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

>>> C:\Perl64-5.20\bin\perl.exe Makefile.PL
Checking if your kit is complete...
Looks good
Generating a dmake-style Makefile
Writing Makefile for Mercury
Writing MYMETA.yml and MYMETA.json
>>> dmake
cp lib/Mercury/templates/pubsub.html.ep blib\lib\Mercury\templates\pubsub.html.ep
cp lib/Mercury/Command/mercury/broker.pm blib\lib\Mercury\Command\mercury\broker.pm
cp lib/Mercury/templates/bus.html.ep blib\lib\Mercury\templates\bus.html.ep
cp lib/Mercury/templates/index.html.ep blib\lib\Mercury\templates\index.html.ep
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/PushPull.pm blib\lib\Mercury\PushPull.pm
cp lib/Mercury.pm blib\lib\Mercury.pm
"C:\Perl64-5.20\bin\perl.exe" -MExtUtils::Command -e cp -- bin/mercury blib\script\mercury
pl2bat.bat blib\script\mercury
  PREACTION/Mercury-0.005.tar.gz
  dmake -- OK
Running make test
>>> dmake test TEST_VERBOSE=1
"C:\Perl64-5.20\bin\perl.exe" "-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.23
#     Scalar::Util           any 1.42
# 
t/00-report-prereqs.t .. 
1..1
ok 1
ok
[Sat Oct 10 07:05:56 2015] [debug] Reading configuration file "C:\cpanfly-5.20\var\cpan\build\Mercury-0.005-Wj5pYz\t\share\allow_origin.conf"
[Sat Oct 10 07:05:56 2015] [debug] Reading configuration file "C:\cpanfly-5.20\var\cpan\build\Mercury-0.005-Wj5pYz\t\share\allow_origin.conf"
[Sat Oct 10 07:05:56 2015] [debug] Your secret passphrase needs to be changed
[Sat Oct 10 07:05:56 2015] [debug] GET "/bus/fry"
[Sat Oct 10 07:05:56 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:56 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:56 2015] [debug] 101 Switching Protocols (0.000977s, 1023.541/s)
[Sat Oct 10 07:05:56 2015] [debug] GET "/bus/leela"
[Sat Oct 10 07:05:56 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:56 2015] [debug] 401 Unauthorized (0.000605s, 1652.893/s)
[Sat Oct 10 07:05:56 2015] [debug] GET "/bus/bender"
[Sat Oct 10 07:05:56 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:56 2015] [debug] 401 Unauthorized (0.000479s, 2087.683/s)
[Sat Oct 10 07:05:56 2015] [debug] GET "/sub/fry"
[Sat Oct 10 07:05:56 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:56 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:56 2015] [debug] 101 Switching Protocols (0.000569s, 1757.469/s)
[Sat Oct 10 07:05:56 2015] [debug] GET "/sub/leela"
[Sat Oct 10 07:05:56 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:56 2015] [debug] 401 Unauthorized (0.000460s, 2173.913/s)
[Sat Oct 10 07:05:56 2015] [debug] GET "/sub/bender"
[Sat Oct 10 07:05:56 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:56 2015] [debug] 401 Unauthorized (0.000478s, 2092.050/s)
[Sat Oct 10 07:05:56 2015] [debug] GET "/pub/fry"
[Sat Oct 10 07:05:56 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:56 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:56 2015] [debug] 101 Switching Protocols (0.000544s, 1838.235/s)
[Sat Oct 10 07:05:56 2015] [debug] GET "/pub/leela"
[Sat Oct 10 07:05:56 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:56 2015] [debug] 401 Unauthorized (0.000478s, 2092.050/s)
[Sat Oct 10 07:05:56 2015] [debug] GET "/pub/bender"
[Sat Oct 10 07:05:56 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:56 2015] [debug] 401 Unauthorized (0.000488s, 2049.180/s)
[Sat Oct 10 07:05:57 2015] [debug] GET "/push/fry"
[Sat Oct 10 07:05:57 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:57 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:57 2015] [debug] 101 Switching Protocols (0.000645s, 1550.388/s)
[Sat Oct 10 07:05:57 2015] [debug] GET "/push/leela"
[Sat Oct 10 07:05:57 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:57 2015] [debug] 401 Unauthorized (0.000551s, 1814.882/s)
[Sat Oct 10 07:05:57 2015] [debug] GET "/push/bender"
[Sat Oct 10 07:05:57 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:57 2015] [debug] 401 Unauthorized (0.000530s, 1886.792/s)
[Sat Oct 10 07:05:57 2015] [debug] GET "/pull/fry"
[Sat Oct 10 07:05:57 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:57 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:57 2015] [debug] 101 Switching Protocols (0.000782s, 1278.772/s)
[Sat Oct 10 07:05:57 2015] [debug] GET "/pull/leela"
[Sat Oct 10 07:05:57 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:57 2015] [debug] 401 Unauthorized (0.000514s, 1945.525/s)
[Sat Oct 10 07:05:57 2015] [debug] GET "/pull/bender"
[Sat Oct 10 07:05:57 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:57 2015] [debug] 401 Unauthorized (0.000592s, 1689.189/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 07:05:58 2015] [debug] Your secret passphrase needs to be changed
[Sat Oct 10 07:05:58 2015] [debug] GET "/bus/foo"
[Sat Oct 10 07:05:58 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:58 2015] [debug] 101 Switching Protocols (0.000768s, 1302.083/s)
[Sat Oct 10 07:05:58 2015] [debug] GET "/bus/foo"
[Sat Oct 10 07:05:58 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:58 2015] [debug] 101 Switching Protocols (0.000321s, 3115.265/s)
[Sat Oct 10 07:05:58 2015] [debug] GET "/bus/foo"
[Sat Oct 10 07:05:58 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:58 2015] [debug] 101 Switching Protocols (0.000319s, 3134.796/s)
[Sat Oct 10 07:05:58 2015] [debug] GET "/bus/foo"
[Sat Oct 10 07:05:58 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:58 2015] [debug] 101 Switching Protocols (0.000404s, 2475.248/s)
[Sat Oct 10 07:05:58 2015] [debug] GET "/bus/bar"
[Sat Oct 10 07:05:58 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:58 2015] [debug] 101 Switching Protocols (0.000552s, 1811.594/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 07:05:59 2015] [debug] Your secret passphrase needs to be changed
[Sat Oct 10 07:05:59 2015] [debug] GET "/pub/foo"
[Sat Oct 10 07:05:59 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:59 2015] [debug] 101 Switching Protocols (0.000789s, 1267.427/s)
[Sat Oct 10 07:05:59 2015] [debug] GET "/sub/foo"
[Sat Oct 10 07:05:59 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:59 2015] [debug] 101 Switching Protocols (0.000529s, 1890.359/s)
[Sat Oct 10 07:05:59 2015] [debug] GET "/sub/foo"
[Sat Oct 10 07:05:59 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:59 2015] [debug] 101 Switching Protocols (0.000137s, 7299.270/s)
[Sat Oct 10 07:05:59 2015] [debug] GET "/sub/foo"
[Sat Oct 10 07:05:59 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:59 2015] [debug] 101 Switching Protocols (0.000371s, 2695.418/s)
[Sat Oct 10 07:05:59 2015] [debug] GET "/sub/foo/bar"
[Sat Oct 10 07:05:59 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:59 2015] [debug] 101 Switching Protocols (0.000583s, 1715.266/s)
[Sat Oct 10 07:05:59 2015] [debug] GET "/pub/foo/bar"
[Sat Oct 10 07:05:59 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:59 2015] [debug] 101 Switching Protocols (0.000432s, 2314.815/s)
[Sat Oct 10 07:05:59 2015] [debug] GET "/pub/foo"
[Sat Oct 10 07:05:59 2015] [debug] Routing to a callback
[Sat Oct 10 07:05:59 2015] [debug] 101 Switching Protocols (0.000312s, 3205.128/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 07:06:00 2015] [debug] Your secret passphrase needs to be changed
[Sat Oct 10 07:06:00 2015] [debug] GET "/pull/foo"
[Sat Oct 10 07:06:00 2015] [debug] Routing to a callback
[Sat Oct 10 07:06:00 2015] [debug] 101 Switching Protocols (0.000805s, 1242.236/s)
[Sat Oct 10 07:06:00 2015] [debug] GET "/pull/foo"
[Sat Oct 10 07:06:00 2015] [debug] Routing to a callback
[Sat Oct 10 07:06:00 2015] [debug] 101 Switching Protocols (0.000327s, 3058.104/s)
[Sat Oct 10 07:06:00 2015] [debug] GET "/pull/bar"
[Sat Oct 10 07:06:00 2015] [debug] Routing to a callback
[Sat Oct 10 07:06:00 2015] [debug] 101 Switching Protocols (0.000488s, 2049.180/s)
[Sat Oct 10 07:06:00 2015] [debug] GET "/push/foo"
[Sat Oct 10 07:06:00 2015] [debug] Routing to a callback
[Sat Oct 10 07:06:00 2015] [debug] 101 Switching Protocols (0.000470s, 2127.660/s)
[Sat Oct 10 07:06:00 2015] [debug] GET "/pull/foo"
[Sat Oct 10 07:06:00 2015] [debug] Routing to a callback
[Sat Oct 10 07:06:00 2015] [debug] 101 Switching Protocols (0.000345s, 2898.551/s)
[Sat Oct 10 07:06:00 2015] [debug] GET "/pull/foo"
[Sat Oct 10 07:06:00 2015] [debug] Routing to a callback
[Sat Oct 10 07:06:00 2015] [debug] 101 Switching Protocols (0.000314s, 3184.713/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, 10 wallclock secs ( 0.11 usr +  0.02 sys =  0.13 CPU)
Result: PASS
  PREACTION/Mercury-0.005.tar.gz
  dmake test TEST_VERBOSE=1 -- OK
<SOFTPKG NAME="Mercury" VERSION="0.005" DATE="2015-10-09">
  <AUTHOR CPAN="PREACTION">Doug Bell &lt;preaction@cpan.org></AUTHOR>
  <ABSTRACT>A message broker for WebSockets</ABSTRACT>
  <ARCHITECTURE NAME="MSWin32-x64-multi-thread-5.20"/>
  <CODEBASE HREF="Mercury-0.005.tar.gz"/>
  <PROVIDE NAME="Mercury::" VERSION="0.005"/>
  <PROVIDE NAME="Mercury::PushPull" VERSION="0.005"/>
  <PROVIDE NAME="Mercury::Command::mercury" VERSION="0.005"/>
  <PROVIDE NAME="Mercury::Command::mercury::broker" VERSION="0.005"/>
  <REQUIRE NAME="File::Basename"/>
  <REQUIRE NAME="File::Spec::Functions"/>
  <REQUIRE NAME="Mojolicious::" VERSION="6.00"/>
  <REQUIRE NAME="Scalar::Util"/>
  <!-- Built 2015-10-10 with ActivePerl-2000 on cpanfly-win64 (Windows 2003 Windows XP Professional x64 Edition Service Pack 2) log Mercury-0.005.d\log-20151010T070545.txt -->
</SOFTPKG>
>>> (cd C:\cpanfly-5.20\var\cpan\build\Mercury-0.005-Wj5pYz && tar cvf - Mercury-0.005.ppd blib) | gzip -c >C:/cpanfly-5.20/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/script/
blib/script/mercury
blib/script/mercury.bat
>>> mv C:\cpanfly-5.20\var\cpan\build\Mercury-0.005-Wj5pYz/Mercury-0.005.ppd C:/cpanfly-5.20/var/REPO/P/PR/PREACTION
Finished 2015-10-10T07:06:01