PATH=C:\mingw\bin;C:\cpanfly-5.20\var\megalib\bin;C:\Perl-5.20\site\bin;C:\Perl-5.20\bin;C:\cygwin\bin;C:\mingw\bin;C:\cpanfly-5.20\var\megalib\bin;C:\Perl-5.20\site\bin;C:\Perl-5.20\bin;C:\cygwin\bin;C:\Program Files\Perforce;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\WINDOWS\system32\WindowsPowerShell\v1.0;C:\Program Files\Git\cmd;C:\Program Files\CMake\bin;C:\instantclient_11_2;C:\Program Files\Perforce;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\WINDOWS\system32\WindowsPowerShell\v1.0;C:\Program Files\Git\cmd;C:\Program Files\CMake\bin
Start 2016-09-03T00:01:25
ActivePerl-2003 CPAN-2.10
PATH=C:/CPANFL~1.20/var/libs/bin;C:\mingw\bin;C:\CPANFL~1.20\var\megalib\bin;C:\Perl-5.20\site\bin;C:\Perl-5.20\bin;C:\cygwin\bin;C:\mingw\bin;C:\CPANFL~1.20\var\megalib\bin;C:\Perl-5.20\site\bin;C:\Perl-5.20\bin;C:\cygwin\bin;C:\PROGRA~1\Perforce;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\WINDOWS\system32\WINDOW~2\v1.0;C:\PROGRA~1\Git\cmd;C:\PROGRA~1\CMake\bin;C:\INSTAN~1;C:\PROGRA~1\Perforce;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\WINDOWS\system32\WINDOW~2\v1.0;C:\PROGRA~1\Git\cmd;C:\PROGRA~1\CMake\bin
Reading 'C:\cpanfly-5.20\var\cpan\Metadata'
  Database was generated on Fri, 02 Sep 2016 05:29:02 GMT
Fetching with LWP:
http://ppm.activestate.com/CPAN/authors/id/P/PR/PREACTION/Mercury-0.009.tar.gz
Fetching with LWP:
http://ppm.activestate.com/CPAN/authors/id/P/PR/PREACTION/CHECKSUMS
Checksum for C:\cpanfly-5.20\var\cpan\sources\authors\id\P\PR\PREACTION\Mercury-0.009.tar.gz ok
Mercury-0.009
Mercury-0.009/README
Mercury-0.009/LICENSE
Mercury-0.009/t
Mercury-0.009/t/bus.t
Mercury-0.009/CHANGES
Mercury-0.009/META.yml
Mercury-0.009/MANIFEST
Mercury-0.009/cpanfile
Mercury-0.009/site.yml
Mercury-0.009/META.json
Mercury-0.009/t/pubsub.t
Mercury-0.009/Makefile.PL
Mercury-0.009/bin
Mercury-0.009/bin/mercury
Mercury-0.009/eg
Mercury-0.009/eg/chat.html
Mercury-0.009/eg/daemon.pl
Mercury-0.009/t/pushpull.t
Mercury-0.009/t/00-compile.t
Mercury-0.009/lib
Mercury-0.009/lib/Mercury.pm
Mercury-0.009/CONTRIBUTING.md
Mercury-0.009/t/allow_origin.t
Mercury-0.009/site
Mercury-0.009/site/index.markdown
Mercury-0.009/site/release-blog.pl
Mercury-0.009/t/00-report-prereqs.t
Mercury-0.009/t/controller
Mercury-0.009/t/controller/pubsub.t
Mercury-0.009/t/00-report-prereqs.dd
Mercury-0.009/t/share
Mercury-0.009/t/share/allow_origin.conf
Mercury-0.009/lib/Mercury/Pattern
Mercury-0.009/lib/Mercury/Pattern/Bus.pm
Mercury-0.009/site/theme/site
Mercury-0.009/site/theme/site/style.html
Mercury-0.009/site/theme/css
Mercury-0.009/site/theme/css/skeleton.css
Mercury-0.009/site/theme/blog
Mercury-0.009/site/theme/blog/index.rss.ep
Mercury-0.009/site/theme/blog/post.html.ep
Mercury-0.009/site/theme/css/normalize.css
Mercury-0.009/lib/Mercury/Controller
Mercury-0.009/lib/Mercury/Controller/Bus.pm
Mercury-0.009/lib/Mercury/Pattern/PubSub.pm
Mercury-0.009/lib/Mercury/public
Mercury-0.009/lib/Mercury/public/example.js
Mercury-0.009/site/theme/blog/index.atom.ep
Mercury-0.009/site/theme/blog/index.html.ep
Mercury-0.009/site/theme/site/robots.txt.ep
Mercury-0.009/lib/Mercury/Command
Mercury-0.009/lib/Mercury/Command/mercury.pm
Mercury-0.009/site/theme/perldoc
Mercury-0.009/site/theme/perldoc/pod.html.ep
Mercury-0.009/site/theme/site/footer.html.ep
Mercury-0.009/site/theme/site/layout.html.ep
Mercury-0.009/site/theme/site/sitemap.xml.ep
Mercury-0.009/lib/Mercury/Pattern/PushPull.pm
Mercury-0.009/lib/Mercury/Controller/PubSub.pm
Mercury-0.009/lib/Mercury/templates
Mercury-0.009/lib/Mercury/templates/bus.html.ep
Mercury-0.009/lib/Mojolicious/Plugin
Mercury-0.009/lib/Mojolicious/Plugin/Mercury.pm
Mercury-0.009/site/theme/perldoc/source.html.ep
Mercury-0.009/lib/Mercury/Controller/PushPull.pm
Mercury-0.009/site/theme/site/head_after.html.ep
Mercury-0.009/lib/Mercury/templates/index.html.ep
Mercury-0.009/lib/Mercury/templates/pubsub.html.ep
Mercury-0.009/site/theme/css/statocles-default.css
Mercury-0.009/site/theme/site/header_after.html.ep
Mercury-0.009/site/theme/site/navbar_extra.html.ep
Mercury-0.009/lib/Mercury/Command/mercury
Mercury-0.009/lib/Mercury/Command/mercury/broker.pm
Mercury-0.009/lib/Mercury/templates/pushpull.html.ep
Mercury-0.009/site/theme/site/sidebar_before.html.ep
Mercury-0.009/lib/Mercury/Controller/PubSub
Mercury-0.009/lib/Mercury/Controller/PubSub/Cascade.pm
Mercury-0.009/lib/Mercury/templates/layouts
Mercury-0.009/lib/Mercury/templates/layouts/standard.html.ep
Mercury-0.009/site/blog/2015/12/13/release-v0-007
Mercury-0.009/site/blog/2015/12/13/release-v0-007/index.markdown
Mercury-0.009/site/blog/2015/12/29/release-v0-008
Mercury-0.009/site/blog/2015/12/29/release-v0-008/index.markdown
Configuring P/PR/PREACTION/Mercury-0.009.tar.gz with Makefile.PL
>>> C:\Perl-5.20\bin\perl.exe Makefile.PL
Checking if your kit is complete...
Looks good
Have \cpanfl~1.20\var\megalib
Want \perl-5.20\lib
Your perl and your Config.pm seem to have different ideas about the
architecture they are running on.
Perl thinks: [megalib]
Config says: [MSWin32-x86-multi-thread-64int]
This may or may not cause problems. Please check your installation of perl
if you have problems building this extension.
Generating a dmake-style Makefile
Writing Makefile for Mercury
Writing MYMETA.yml and MYMETA.json
  PREACTION/Mercury-0.009.tar.gz
  C:\Perl-5.20\bin\perl.exe Makefile.PL -- OK
Running make for P/PR/PREACTION/Mercury-0.009.tar.gz
>>> dmake
cp lib/Mercury/Controller/PushPull.pm blib\lib\Mercury\Controller\PushPull.pm
cp lib/Mercury/templates/pushpull.html.ep blib\lib\Mercury\templates\pushpull.html.ep
cp lib/Mercury/Pattern/PushPull.pm blib\lib\Mercury\Pattern\PushPull.pm
cp lib/Mercury/Controller/Bus.pm blib\lib\Mercury\Controller\Bus.pm
cp lib/Mercury/Command/mercury.pm blib\lib\Mercury\Command\mercury.pm
cp lib/Mercury/Controller/PubSub/Cascade.pm blib\lib\Mercury\Controller\PubSub\Cascade.pm
cp lib/Mojolicious/Plugin/Mercury.pm blib\lib\Mojolicious\Plugin\Mercury.pm
cp lib/Mercury/Controller/PubSub.pm blib\lib\Mercury\Controller\PubSub.pm
cp lib/Mercury.pm blib\lib\Mercury.pm
cp lib/Mercury/templates/bus.html.ep blib\lib\Mercury\templates\bus.html.ep
cp lib/Mercury/public/example.js blib\lib\Mercury\public\example.js
cp lib/Mercury/Pattern/Bus.pm blib\lib\Mercury\Pattern\Bus.pm
cp lib/Mercury/Command/mercury/broker.pm blib\lib\Mercury\Command\mercury\broker.pm
cp lib/Mercury/templates/index.html.ep blib\lib\Mercury\templates\index.html.ep
cp lib/Mercury/Pattern/PubSub.pm blib\lib\Mercury\Pattern\PubSub.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
"C:\Perl-5.20\bin\perl.exe" -MExtUtils::Command -e cp -- bin/mercury blib\script\mercury
pl2bat.bat blib\script\mercury
  PREACTION/Mercury-0.009.tar.gz
  dmake -- OK
Running make test
>>> dmake test TEST_VERBOSE=1
"C:\Perl-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/controller/*.t
t/00-compile.t ......... 
1..12
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/Controller/Bus.pm loaded ok
ok 5 - Mercury/Controller/PubSub.pm loaded ok
ok 6 - Mercury/Controller/PubSub/Cascade.pm loaded ok
ok 7 - Mercury/Controller/PushPull.pm loaded ok
ok 8 - Mercury/Pattern/Bus.pm loaded ok
ok 9 - Mercury/Pattern/PubSub.pm loaded ok
ok 10 - Mercury/Pattern/PushPull.pm loaded ok
ok 11 - Mojolicious/Plugin/Mercury.pm loaded ok
ok 12 # 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.62
#     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.62
#     Mojolicious           6.00 7.05
#     Scalar::Util           any 1.45
# 
t/00-report-prereqs.t .. 
1..1
ok 1
ok
[Sat Sep  3 00:02:01 2016] [debug] GET "/bus/fry"
[Sat Sep  3 00:02:01 2016] [debug] Routing to a callback
[Sat Sep  3 00:02:01 2016] [debug] Routing to controller "Mercury::Controller::Bus" and action "connect"
[Sat Sep  3 00:02:01 2016] [debug] 101 Switching Protocols (0.005184s, 192.901/s)
[Sat Sep  3 00:02:01 2016] [debug] GET "/bus/leela"
[Sat Sep  3 00:02:01 2016] [debug] Routing to a callback
[Sat Sep  3 00:02:01 2016] [debug] 401 Unauthorized (0.000844s, 1184.834/s)
[Sat Sep  3 00:02:01 2016] [debug] GET "/bus/bender"
[Sat Sep  3 00:02:01 2016] [debug] Routing to a callback
[Sat Sep  3 00:02:01 2016] [debug] 401 Unauthorized (0.000671s, 1490.313/s)
[Sat Sep  3 00:02:01 2016] [debug] GET "/sub/fry"
[Sat Sep  3 00:02:01 2016] [debug] Routing to a callback
[Sat Sep  3 00:02:01 2016] [debug] Routing to controller "Mercury::Controller::PubSub::Cascade" and action "subscribe"
[Sat Sep  3 00:02:01 2016] [debug] 101 Switching Protocols (0.005598s, 178.635/s)
[Sat Sep  3 00:02:01 2016] [debug] GET "/sub/leela"
[Sat Sep  3 00:02:01 2016] [debug] Routing to a callback
[Sat Sep  3 00:02:01 2016] [debug] 401 Unauthorized (0.000677s, 1477.105/s)
[Sat Sep  3 00:02:01 2016] [debug] GET "/sub/bender"
[Sat Sep  3 00:02:01 2016] [debug] Routing to a callback
[Sat Sep  3 00:02:01 2016] [debug] 401 Unauthorized (0.000542s, 1845.018/s)
[Sat Sep  3 00:02:01 2016] [debug] GET "/pub/fry"
[Sat Sep  3 00:02:01 2016] [debug] Routing to a callback
[Sat Sep  3 00:02:01 2016] [debug] Routing to controller "Mercury::Controller::PubSub::Cascade" and action "publish"
[Sat Sep  3 00:02:01 2016] [debug] 101 Switching Protocols (0.000828s, 1207.729/s)
[Sat Sep  3 00:02:01 2016] [debug] GET "/pub/leela"
[Sat Sep  3 00:02:01 2016] [debug] Routing to a callback
[Sat Sep  3 00:02:01 2016] [debug] 401 Unauthorized (0.000621s, 1610.306/s)
[Sat Sep  3 00:02:01 2016] [debug] GET "/pub/bender"
[Sat Sep  3 00:02:01 2016] [debug] Routing to a callback
[Sat Sep  3 00:02:01 2016] [debug] 401 Unauthorized (0.000629s, 1589.825/s)
[Sat Sep  3 00:02:01 2016] [debug] GET "/push/fry"
[Sat Sep  3 00:02:01 2016] [debug] Routing to a callback
[Sat Sep  3 00:02:01 2016] [debug] Routing to controller "Mercury::Controller::PushPull" and action "push"
[Sat Sep  3 00:02:01 2016] [debug] 101 Switching Protocols (0.005295s, 188.857/s)
[Sat Sep  3 00:02:01 2016] [debug] GET "/push/leela"
[Sat Sep  3 00:02:01 2016] [debug] Routing to a callback
[Sat Sep  3 00:02:01 2016] [debug] 401 Unauthorized (0.000662s, 1510.574/s)
[Sat Sep  3 00:02:01 2016] [debug] GET "/push/bender"
[Sat Sep  3 00:02:01 2016] [debug] Routing to a callback
[Sat Sep  3 00:02:01 2016] [debug] 401 Unauthorized (0.000533s, 1876.173/s)
[Sat Sep  3 00:02:01 2016] [debug] GET "/pull/fry"
[Sat Sep  3 00:02:01 2016] [debug] Routing to a callback
[Sat Sep  3 00:02:01 2016] [debug] Routing to controller "Mercury::Controller::PushPull" and action "pull"
[Sat Sep  3 00:02:01 2016] [debug] 101 Switching Protocols (0.000773s, 1293.661/s)
[Sat Sep  3 00:02:01 2016] [debug] GET "/pull/leela"
[Sat Sep  3 00:02:01 2016] [debug] Routing to a callback
[Sat Sep  3 00:02:01 2016] [debug] 401 Unauthorized (0.000638s, 1567.398/s)
[Sat Sep  3 00:02:01 2016] [debug] GET "/pull/bender"
[Sat Sep  3 00:02:01 2016] [debug] Routing to a callback
[Sat Sep  3 00:02:01 2016] [debug] 401 Unauthorized (0.000581s, 1721.170/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 Sep  3 00:02:03 2016] [debug] GET "/bus/foo"
[Sat Sep  3 00:02:03 2016] [debug] Routing to controller "Mercury::Controller::Bus" and action "connect"
[Sat Sep  3 00:02:03 2016] [debug] 101 Switching Protocols (0.005159s, 193.836/s)
[Sat Sep  3 00:02:03 2016] [debug] GET "/bus/foo"
[Sat Sep  3 00:02:03 2016] [debug] Routing to controller "Mercury::Controller::Bus" and action "connect"
[Sat Sep  3 00:02:03 2016] [debug] 101 Switching Protocols (0.000666s, 1501.502/s)
[Sat Sep  3 00:02:03 2016] [debug] GET "/bus/foo"
[Sat Sep  3 00:02:03 2016] [debug] Routing to controller "Mercury::Controller::Bus" and action "connect"
[Sat Sep  3 00:02:03 2016] [debug] 101 Switching Protocols (0.000696s, 1436.782/s)
[Sat Sep  3 00:02:03 2016] [debug] GET "/bus/foo"
[Sat Sep  3 00:02:03 2016] [debug] Routing to controller "Mercury::Controller::Bus" and action "connect"
[Sat Sep  3 00:02:03 2016] [debug] 101 Switching Protocols (0.000812s, 1231.527/s)
[Sat Sep  3 00:02:03 2016] [debug] GET "/bus/bar"
[Sat Sep  3 00:02:03 2016] [debug] Routing to controller "Mercury::Controller::Bus" and action "connect"
[Sat Sep  3 00:02:03 2016] [debug] 101 Switching Protocols (0.001s, 1000.000/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 Sep  3 00:02:05 2016] [debug] GET "/pub/foo"
[Sat Sep  3 00:02:05 2016] [debug] Routing to controller "Mercury::Controller::PubSub" and action "publish"
[Sat Sep  3 00:02:05 2016] [debug] 101 Switching Protocols (0.005049s, 198.059/s)
[Sat Sep  3 00:02:05 2016] [debug] GET "/sub/foo"
[Sat Sep  3 00:02:05 2016] [debug] Routing to controller "Mercury::Controller::PubSub" and action "subscribe"
[Sat Sep  3 00:02:05 2016] [debug] 101 Switching Protocols (0.004418s, 226.347/s)
[Sat Sep  3 00:02:05 2016] [debug] GET "/sub/foo"
[Sat Sep  3 00:02:05 2016] [debug] Routing to controller "Mercury::Controller::PubSub" and action "subscribe"
[Sat Sep  3 00:02:05 2016] [debug] 101 Switching Protocols (0.003828s, 261.233/s)
t/controller/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
1..1
ok
[Sat Sep  3 00:02:06 2016] [debug] GET "/pub/foo"
[Sat Sep  3 00:02:06 2016] [debug] Routing to controller "Mercury::Controller::PubSub::Cascade" and action "publish"
[Sat Sep  3 00:02:06 2016] [debug] 101 Switching Protocols (0.00622s, 160.772/s)
[Sat Sep  3 00:02:06 2016] [debug] GET "/sub/foo"
[Sat Sep  3 00:02:06 2016] [debug] Routing to controller "Mercury::Controller::PubSub::Cascade" and action "subscribe"
[Sat Sep  3 00:02:06 2016] [debug] 101 Switching Protocols (0.000826s, 1210.654/s)
[Sat Sep  3 00:02:06 2016] [debug] GET "/sub/foo"
[Sat Sep  3 00:02:06 2016] [debug] Routing to controller "Mercury::Controller::PubSub::Cascade" and action "subscribe"
[Sat Sep  3 00:02:06 2016] [debug] 101 Switching Protocols (0.00043s, 2325.581/s)
[Sat Sep  3 00:02:06 2016] [debug] GET "/sub/foo"
[Sat Sep  3 00:02:06 2016] [debug] Routing to controller "Mercury::Controller::PubSub::Cascade" and action "subscribe"
[Sat Sep  3 00:02:06 2016] [debug] 101 Switching Protocols (0.000397s, 2518.892/s)
[Sat Sep  3 00:02:06 2016] [debug] GET "/sub/foo/bar"
[Sat Sep  3 00:02:06 2016] [debug] Routing to controller "Mercury::Controller::PubSub::Cascade" and action "subscribe"
[Sat Sep  3 00:02:06 2016] [debug] 101 Switching Protocols (0.000716s, 1396.648/s)
[Sat Sep  3 00:02:06 2016] [debug] GET "/pub/foo/bar"
[Sat Sep  3 00:02:06 2016] [debug] Routing to controller "Mercury::Controller::PubSub::Cascade" and action "publish"
[Sat Sep  3 00:02:06 2016] [debug] 101 Switching Protocols (0.000748s, 1336.898/s)
[Sat Sep  3 00:02:06 2016] [debug] GET "/pub/foo"
[Sat Sep  3 00:02:06 2016] [debug] Routing to controller "Mercury::Controller::PubSub::Cascade" and action "publish"
[Sat Sep  3 00:02:06 2016] [debug] 101 Switching Protocols (0.000496s, 2016.129/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 Sep  3 00:02:08 2016] [debug] GET "/pull/foo"
[Sat Sep  3 00:02:08 2016] [debug] Routing to controller "Mercury::Controller::PushPull" and action "pull"
[Sat Sep  3 00:02:08 2016] [debug] 101 Switching Protocols (0.006147s, 162.681/s)
[Sat Sep  3 00:02:08 2016] [debug] GET "/pull/foo"
[Sat Sep  3 00:02:08 2016] [debug] Routing to controller "Mercury::Controller::PushPull" and action "pull"
[Sat Sep  3 00:02:08 2016] [debug] 101 Switching Protocols (0.000436s, 2293.578/s)
[Sat Sep  3 00:02:08 2016] [debug] GET "/pull/bar"
[Sat Sep  3 00:02:08 2016] [debug] Routing to controller "Mercury::Controller::PushPull" and action "pull"
[Sat Sep  3 00:02:08 2016] [debug] 101 Switching Protocols (0.000654s, 1529.052/s)
[Sat Sep  3 00:02:08 2016] [debug] GET "/push/foo"
[Sat Sep  3 00:02:08 2016] [debug] Routing to controller "Mercury::Controller::PushPull" and action "push"
[Sat Sep  3 00:02:08 2016] [debug] 101 Switching Protocols (0.000575s, 1739.130/s)
[Sat Sep  3 00:02:08 2016] [debug] GET "/pull/foo"
[Sat Sep  3 00:02:08 2016] [debug] Routing to controller "Mercury::Controller::PushPull" and action "pull"
[Sat Sep  3 00:02:08 2016] [debug] 101 Switching Protocols (0.000462s, 2164.502/s)
[Sat Sep  3 00:02:08 2016] [debug] GET "/pull/foo"
[Sat Sep  3 00:02:08 2016] [debug] Routing to controller "Mercury::Controller::PushPull" and action "pull"
[Sat Sep  3 00:02:08 2016] [debug] 101 Switching Protocols (0.000431s, 2320.186/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=7, Tests=51, 26 wallclock secs ( 0.08 usr +  0.00 sys =  0.08 CPU)
Result: PASS
  PREACTION/Mercury-0.009.tar.gz
  dmake test TEST_VERBOSE=1 -- OK
<SOFTPKG NAME="Mercury" VERSION="0.009" DATE="2016-09-02">
  <AUTHOR CPAN="PREACTION">Doug Bell &lt;preaction@cpan.org></AUTHOR>
  <ABSTRACT>A message broker for WebSockets</ABSTRACT>
  <ARCHITECTURE NAME="MSWin32-x86-multi-thread-64int-5.20"/>
  <CODEBASE HREF="Mercury-0.009.tar.gz"/>
  <PROVIDE NAME="Mercury::" VERSION="0.009"/>
  <PROVIDE NAME="Mercury::Command::mercury" VERSION="0.009"/>
  <PROVIDE NAME="Mercury::Command::mercury::broker" VERSION="0.009"/>
  <PROVIDE NAME="Mercury::Controller::Bus" VERSION="0.009"/>
  <PROVIDE NAME="Mercury::Controller::PubSub" VERSION="0.009"/>
  <PROVIDE NAME="Mercury::Controller::PushPull" VERSION="0.009"/>
  <PROVIDE NAME="Mercury::Controller::PubSub::Cascade" VERSION="0.009"/>
  <PROVIDE NAME="Mercury::Pattern::Bus" VERSION="0.009"/>
  <PROVIDE NAME="Mercury::Pattern::PubSub" VERSION="0.009"/>
  <PROVIDE NAME="Mercury::Pattern::PushPull" VERSION="0.009"/>
  <PROVIDE NAME="Mojolicious::Plugin::Mercury" VERSION="0.009"/>
  <REQUIRE NAME="File::Basename"/>
  <REQUIRE NAME="File::Spec::Functions"/>
  <REQUIRE NAME="Mojolicious::" VERSION="6.00"/>
  <REQUIRE NAME="Scalar::Util"/>
  <!-- Built 2016-09-03 with ActivePerl-2003 on cpanfly-win2003 (Windows 2003 R2 Standard Edition Service Pack 2) log Mercury-0.009.d\log-20160903T000124.txt -->
</SOFTPKG>
>>> (cd C:\cpanfly-5.20\var\tmp\cpan_build\Mercury-0.009-9DC7YM && tar cvf - Mercury-0.009.ppd blib) | gzip -c >C:/cpanfly-5.20/var/REPO/P/PR/PREACTION/Mercury-0.009.tar.gz
Mercury-0.009.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/Controller/
blib/lib/Mercury/Controller/Bus.pm
blib/lib/Mercury/Controller/PubSub/
blib/lib/Mercury/Controller/PubSub/Cascade.pm
blib/lib/Mercury/Controller/PubSub.pm
blib/lib/Mercury/Controller/PushPull.pm
blib/lib/Mercury/Pattern/
blib/lib/Mercury/Pattern/Bus.pm
blib/lib/Mercury/Pattern/PubSub.pm
blib/lib/Mercury/Pattern/PushPull.pm
blib/lib/Mercury/public/
blib/lib/Mercury/public/example.js
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/lib/Mojolicious/
blib/lib/Mojolicious/Plugin/
blib/lib/Mojolicious/Plugin/Mercury.pm
blib/script/
blib/script/mercury
blib/script/mercury.bat
Finished 2016-09-03T00:02:09