PATH=C:\cpanfly-5.24-64\var\megalib\bin;C:\Perl-5.24-64\site\bin;C:\Perl-5.24-64\bin;C:\MinGW-64\bin;C:\cygwin\bin;C:\instantclient_11_2-64;C:\cpanfly-5.24-64\var\megalib\bin;C:\Perl-5.24-64\site\bin;C:\Perl-5.24-64\bin;C:\MinGW-64\bin;C:\cygwin\bin;C:\instantclient_11_2-64;C:\cpanfly-5.24-64\var\megalib\bin;C:\Perl-5.22-64\site\bin;C:\Perl-5.22-64\bin;C:\MinGW-64\bin;C:\cygwin\bin;C:\instantclient_11_2-64;C:\p4;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;C:\Program Files\Git\cmd;C:\Program Files (x86)\CMake\bin
Start 2016-09-02T10:00:45
ActivePerl-2400 CPAN-2.10
PATH=C:/CPANFL~1.24-/var/libs/bin;C:\CPANFL~1.24-\var\megalib\bin;C:\PERL-5~1.24-\site\bin;C:\PERL-5~1.24-\bin;C:\MinGW-64\bin;C:\cygwin\bin;C:\INSTAN~1;C:\CPANFL~1.24-\var\megalib\bin;C:\PERL-5~1.24-\site\bin;C:\PERL-5~1.24-\bin;C:\MinGW-64\bin;C:\cygwin\bin;C:\INSTAN~1;C:\CPANFL~1.24-\var\megalib\bin;C:\PERL-5~2.22-\site\bin;C:\PERL-5~2.22-\bin;C:\MinGW-64\bin;C:\cygwin\bin;C:\INSTAN~1;C:\p4;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WINDOW~1\v1.0\;C:\PROGRA~1\Git\cmd;C:\PROGRA~2\CMake\bin
Reading 'C:\cpanfly-5.24-64\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.24-64\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.24-64\bin\perl.exe Makefile.PL
Checking if your kit is complete...
Looks good
Have \cpanfl~1.24-\var\megalib
Want \perl-5~1.24-\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-x64-multi-thread]
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.24-64\bin\perl.exe Makefile.PL -- OK
Running make for P/PR/PREACTION/Mercury-0.009.tar.gz
>>> dmake
cp lib/Mercury/templates/index.html.ep blib\lib\Mercury\templates\index.html.ep
cp lib/Mercury/Controller/PubSub/Cascade.pm blib\lib\Mercury\Controller\PubSub\Cascade.pm
cp lib/Mercury/templates/pushpull.html.ep blib\lib\Mercury\templates\pushpull.html.ep
cp lib/Mercury/Controller/PubSub.pm blib\lib\Mercury\Controller\PubSub.pm
cp lib/Mercury/Pattern/PushPull.pm blib\lib\Mercury\Pattern\PushPull.pm
cp lib/Mercury.pm blib\lib\Mercury.pm
cp lib/Mercury/Command/mercury.pm blib\lib\Mercury\Command\mercury.pm
cp lib/Mercury/public/example.js blib\lib\Mercury\public\example.js
cp lib/Mercury/templates/bus.html.ep blib\lib\Mercury\templates\bus.html.ep
cp lib/Mercury/Pattern/PubSub.pm blib\lib\Mercury\Pattern\PubSub.pm
cp lib/Mercury/Controller/Bus.pm blib\lib\Mercury\Controller\Bus.pm
cp lib/Mercury/Command/mercury/broker.pm blib\lib\Mercury\Command\mercury\broker.pm
cp lib/Mojolicious/Plugin/Mercury.pm blib\lib\Mojolicious\Plugin\Mercury.pm
cp lib/Mercury/templates/layouts/standard.html.ep blib\lib\Mercury\templates\layouts\standard.html.ep
cp lib/Mercury/Controller/PushPull.pm blib\lib\Mercury\Controller\PushPull.pm
cp lib/Mercury/Pattern/Bus.pm blib\lib\Mercury\Pattern\Bus.pm
cp lib/Mercury/templates/pubsub.html.ep blib\lib\Mercury\templates\pubsub.html.ep
"C:\Perl-5.24-64\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.24-64\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.16
# 
# === Build Requires ===
# 
#     Module              Want Have
#     ------------------- ---- ----
#     ExtUtils::MakeMaker  any 7.16
# 
# === Test Requires ===
# 
#     Module                  Want     Have
#     ------------------- -------- --------
#     ExtUtils::MakeMaker      any     7.16
#     File::Spec               any     3.62
#     IO::Handle               any     1.36
#     IPC::Open3               any     1.20
#     Test::More          1.001005 1.302052
# 
# === 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
[Fri Sep  2 10:01:23 2016] [debug] GET "/bus/fry"
[Fri Sep  2 10:01:23 2016] [debug] Routing to a callback
[Fri Sep  2 10:01:23 2016] [debug] Routing to controller "Mercury::Controller::Bus" and action "connect"
[Fri Sep  2 10:01:23 2016] [debug] 101 Switching Protocols (0.004001s, 249.938/s)
[Fri Sep  2 10:01:23 2016] [debug] GET "/bus/leela"
[Fri Sep  2 10:01:23 2016] [debug] Routing to a callback
[Fri Sep  2 10:01:23 2016] [debug] 401 Unauthorized (0.000621s, 1610.306/s)
[Fri Sep  2 10:01:23 2016] [debug] GET "/bus/bender"
[Fri Sep  2 10:01:23 2016] [debug] Routing to a callback
[Fri Sep  2 10:01:23 2016] [debug] 401 Unauthorized (0.000678s, 1474.926/s)
[Fri Sep  2 10:01:23 2016] [debug] GET "/sub/fry"
[Fri Sep  2 10:01:23 2016] [debug] Routing to a callback
[Fri Sep  2 10:01:23 2016] [debug] Routing to controller "Mercury::Controller::PubSub::Cascade" and action "subscribe"
[Fri Sep  2 10:01:23 2016] [debug] 101 Switching Protocols (0.004761s, 210.040/s)
[Fri Sep  2 10:01:23 2016] [debug] GET "/sub/leela"
[Fri Sep  2 10:01:23 2016] [debug] Routing to a callback
[Fri Sep  2 10:01:23 2016] [debug] 401 Unauthorized (0.000495s, 2020.202/s)
[Fri Sep  2 10:01:23 2016] [debug] GET "/sub/bender"
[Fri Sep  2 10:01:23 2016] [debug] Routing to a callback
[Fri Sep  2 10:01:23 2016] [debug] 401 Unauthorized (0.000452s, 2212.389/s)
[Fri Sep  2 10:01:23 2016] [debug] GET "/pub/fry"
[Fri Sep  2 10:01:23 2016] [debug] Routing to a callback
[Fri Sep  2 10:01:23 2016] [debug] Routing to controller "Mercury::Controller::PubSub::Cascade" and action "publish"
[Fri Sep  2 10:01:23 2016] [debug] 101 Switching Protocols (0.000566s, 1766.784/s)
[Fri Sep  2 10:01:23 2016] [debug] GET "/pub/leela"
[Fri Sep  2 10:01:23 2016] [debug] Routing to a callback
[Fri Sep  2 10:01:23 2016] [debug] 401 Unauthorized (0.000465s, 2150.538/s)
[Fri Sep  2 10:01:23 2016] [debug] GET "/pub/bender"
[Fri Sep  2 10:01:23 2016] [debug] Routing to a callback
[Fri Sep  2 10:01:23 2016] [debug] 401 Unauthorized (0.000436s, 2293.578/s)
[Fri Sep  2 10:01:23 2016] [debug] GET "/push/fry"
[Fri Sep  2 10:01:23 2016] [debug] Routing to a callback
[Fri Sep  2 10:01:23 2016] [debug] Routing to controller "Mercury::Controller::PushPull" and action "push"
[Fri Sep  2 10:01:23 2016] [debug] 101 Switching Protocols (0.004115s, 243.013/s)
[Fri Sep  2 10:01:23 2016] [debug] GET "/push/leela"
[Fri Sep  2 10:01:23 2016] [debug] Routing to a callback
[Fri Sep  2 10:01:23 2016] [debug] 401 Unauthorized (0.000449s, 2227.171/s)
[Fri Sep  2 10:01:23 2016] [debug] GET "/push/bender"
[Fri Sep  2 10:01:23 2016] [debug] Routing to a callback
[Fri Sep  2 10:01:23 2016] [debug] 401 Unauthorized (0.000411s, 2433.090/s)
[Fri Sep  2 10:01:23 2016] [debug] GET "/pull/fry"
[Fri Sep  2 10:01:23 2016] [debug] Routing to a callback
[Fri Sep  2 10:01:23 2016] [debug] Routing to controller "Mercury::Controller::PushPull" and action "pull"
[Fri Sep  2 10:01:23 2016] [debug] 101 Switching Protocols (0.000556s, 1798.561/s)
[Fri Sep  2 10:01:23 2016] [debug] GET "/pull/leela"
[Fri Sep  2 10:01:23 2016] [debug] Routing to a callback
[Fri Sep  2 10:01:23 2016] [debug] 401 Unauthorized (0.000449s, 2227.171/s)
[Fri Sep  2 10:01:23 2016] [debug] GET "/pull/bender"
[Fri Sep  2 10:01:23 2016] [debug] Routing to a callback
[Fri Sep  2 10:01:23 2016] [debug] 401 Unauthorized (0.000508s, 1968.504/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
[Fri Sep  2 10:01:25 2016] [debug] GET "/bus/foo"
[Fri Sep  2 10:01:25 2016] [debug] Routing to controller "Mercury::Controller::Bus" and action "connect"
[Fri Sep  2 10:01:25 2016] [debug] 101 Switching Protocols (0.00386s, 259.067/s)
[Fri Sep  2 10:01:25 2016] [debug] GET "/bus/foo"
[Fri Sep  2 10:01:25 2016] [debug] Routing to controller "Mercury::Controller::Bus" and action "connect"
[Fri Sep  2 10:01:25 2016] [debug] 101 Switching Protocols (0.000503s, 1988.072/s)
[Fri Sep  2 10:01:25 2016] [debug] GET "/bus/foo"
[Fri Sep  2 10:01:25 2016] [debug] Routing to controller "Mercury::Controller::Bus" and action "connect"
[Fri Sep  2 10:01:25 2016] [debug] 101 Switching Protocols (0.000469s, 2132.196/s)
[Fri Sep  2 10:01:25 2016] [debug] GET "/bus/foo"
[Fri Sep  2 10:01:25 2016] [debug] Routing to controller "Mercury::Controller::Bus" and action "connect"
[Fri Sep  2 10:01:25 2016] [debug] 101 Switching Protocols (0.000609s, 1642.036/s)
[Fri Sep  2 10:01:25 2016] [debug] GET "/bus/bar"
[Fri Sep  2 10:01:25 2016] [debug] Routing to controller "Mercury::Controller::Bus" and action "connect"
[Fri Sep  2 10:01:25 2016] [debug] 101 Switching Protocols (0.000899s, 1112.347/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
[Fri Sep  2 10:01:27 2016] [debug] GET "/pub/foo"
[Fri Sep  2 10:01:27 2016] [debug] Routing to controller "Mercury::Controller::PubSub" and action "publish"
[Fri Sep  2 10:01:27 2016] [debug] 101 Switching Protocols (0.003495s, 286.123/s)
[Fri Sep  2 10:01:27 2016] [debug] GET "/sub/foo"
[Fri Sep  2 10:01:27 2016] [debug] Routing to controller "Mercury::Controller::PubSub" and action "subscribe"
[Fri Sep  2 10:01:27 2016] [debug] 101 Switching Protocols (0.002636s, 379.363/s)
[Fri Sep  2 10:01:27 2016] [debug] GET "/sub/foo"
[Fri Sep  2 10:01:27 2016] [debug] Routing to controller "Mercury::Controller::PubSub" and action "subscribe"
[Fri Sep  2 10:01:27 2016] [debug] 101 Switching Protocols (0.002795s, 357.782/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
[Fri Sep  2 10:01:29 2016] [debug] GET "/pub/foo"
[Fri Sep  2 10:01:29 2016] [debug] Routing to controller "Mercury::Controller::PubSub::Cascade" and action "publish"
[Fri Sep  2 10:01:29 2016] [debug] 101 Switching Protocols (0.005059s, 197.668/s)
[Fri Sep  2 10:01:29 2016] [debug] GET "/sub/foo"
[Fri Sep  2 10:01:29 2016] [debug] Routing to controller "Mercury::Controller::PubSub::Cascade" and action "subscribe"
[Fri Sep  2 10:01:29 2016] [debug] 101 Switching Protocols (0.000558s, 1792.115/s)
[Fri Sep  2 10:01:29 2016] [debug] GET "/sub/foo"
[Fri Sep  2 10:01:29 2016] [debug] Routing to controller "Mercury::Controller::PubSub::Cascade" and action "subscribe"
[Fri Sep  2 10:01:29 2016] [debug] 101 Switching Protocols (0.000322s, 3105.590/s)
[Fri Sep  2 10:01:29 2016] [debug] GET "/sub/foo"
[Fri Sep  2 10:01:29 2016] [debug] Routing to controller "Mercury::Controller::PubSub::Cascade" and action "subscribe"
[Fri Sep  2 10:01:29 2016] [debug] 101 Switching Protocols (0.000338s, 2958.580/s)
[Fri Sep  2 10:01:29 2016] [debug] GET "/sub/foo/bar"
[Fri Sep  2 10:01:29 2016] [debug] Routing to controller "Mercury::Controller::PubSub::Cascade" and action "subscribe"
[Fri Sep  2 10:01:29 2016] [debug] 101 Switching Protocols (0.000533s, 1876.173/s)
[Fri Sep  2 10:01:29 2016] [debug] GET "/pub/foo/bar"
[Fri Sep  2 10:01:29 2016] [debug] Routing to controller "Mercury::Controller::PubSub::Cascade" and action "publish"
[Fri Sep  2 10:01:29 2016] [debug] 101 Switching Protocols (0.000651s, 1536.098/s)
[Fri Sep  2 10:01:29 2016] [debug] GET "/pub/foo"
[Fri Sep  2 10:01:29 2016] [debug] Routing to controller "Mercury::Controller::PubSub::Cascade" and action "publish"
[Fri Sep  2 10:01:29 2016] [debug] 101 Switching Protocols (0.00035s, 2857.143/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
[Fri Sep  2 10:01:31 2016] [debug] GET "/pull/foo"
[Fri Sep  2 10:01:31 2016] [debug] Routing to controller "Mercury::Controller::PushPull" and action "pull"
[Fri Sep  2 10:01:31 2016] [debug] 101 Switching Protocols (0.004796s, 208.507/s)
[Fri Sep  2 10:01:31 2016] [debug] GET "/pull/foo"
[Fri Sep  2 10:01:31 2016] [debug] Routing to controller "Mercury::Controller::PushPull" and action "pull"
[Fri Sep  2 10:01:31 2016] [debug] 101 Switching Protocols (0.000414s, 2415.459/s)
[Fri Sep  2 10:01:31 2016] [debug] GET "/pull/bar"
[Fri Sep  2 10:01:31 2016] [debug] Routing to controller "Mercury::Controller::PushPull" and action "pull"
[Fri Sep  2 10:01:31 2016] [debug] 101 Switching Protocols (0.000514s, 1945.525/s)
[Fri Sep  2 10:01:31 2016] [debug] GET "/push/foo"
[Fri Sep  2 10:01:31 2016] [debug] Routing to controller "Mercury::Controller::PushPull" and action "push"
[Fri Sep  2 10:01:31 2016] [debug] 101 Switching Protocols (0.000452s, 2212.389/s)
[Fri Sep  2 10:01:31 2016] [debug] GET "/pull/foo"
[Fri Sep  2 10:01:31 2016] [debug] Routing to controller "Mercury::Controller::PushPull" and action "pull"
[Fri Sep  2 10:01:31 2016] [debug] 101 Switching Protocols (0.000443s, 2257.336/s)
[Fri Sep  2 10:01:31 2016] [debug] GET "/pull/foo"
[Fri Sep  2 10:01:31 2016] [debug] Routing to controller "Mercury::Controller::PushPull" and action "pull"
[Fri Sep  2 10:01:31 2016] [debug] 101 Switching Protocols (0.000415s, 2409.639/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, 34 wallclock secs ( 0.19 usr +  0.02 sys =  0.20 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-x64-multi-thread-5.24"/>
  <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-02 with ActivePerl-2400 on cpan-win7 (Windows 7 Professional (64-bit) Service Pack 1) log Mercury-0.009.d\log-20160902T100044.txt -->
</SOFTPKG>
>>> (cd C:\cpanfly-5.24-64\var\tmp\cpan_build\Mercury-0.009-jEEvuj && tar cvf - Mercury-0.009.ppd blib) | gzip -c >C:/cpanfly-5.24-64/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-02T10:01:32