Skip to content

Instantly share code, notes, and snippets.

@nrh

nrh/gist:751967

Created December 22, 2010 19:30
Show Gist options
  • Save nrh/751967 to your computer and use it in GitHub Desktop.
Save nrh/751967 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
gistfile1.txt
[nrh@plotz projects/pogo/build] git log -1
commit 2cd6a7b5f371618830dd5ad2bf4a1d39d35ecc2a
Merge: b0ae16b 1cf3221
Author: Michael S. Fischer <michael+github@dynamine.net>
Date: Wed Dec 22 00:21:28 2010 -0800
Merge branch 'master' of github.com:nrh/pogo
[nrh@plotz projects/pogo/build] git status
# On branch master
nothing to commit (working directory clean)
[nrh@plotz projects/pogo/build] make test
prove --timer ../t/10_unit_plugin_inline.t ../t/50_smoke_start_zookeeper.t ../t/51_smoke_loadconf.t ../t/52_smoke_authstore.t ../t/53_smoke_dispatcher.t ../t/54_smoke_worker.t ../t/55_smoke_job.t
[14:29:35] ../t/10_unit_plugin_inline.t ..... ok 124 ms
[14:29:36] ../t/50_smoke_start_zookeeper.t .. Can't locate object method "new" via package "PogoTester" at ../t/50_smoke_start_zookeeper.t line 35.
[14:29:36] ../t/50_smoke_start_zookeeper.t .. Dubious, test returned 255 (wstat 65280, 0xff00)
No subtests run
[14:29:36] ../t/51_smoke_loadconf.t ......... [2516] DEBUG PogoTester::start_zookeeper:104 using 'java -Dzookeeper.log.dir="." -Dzookeeper.root.logger="INFO,CONSOLE" -cp "/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../build/classes:/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../build/lib/*.jar:/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../zookeeper-3.3.1.jar:/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../lib/log4j-1.2.15.jar:/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../lib/jline-0.9.94.jar:/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../src/java/lib/*.jar:/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../conf:" -Dcom.sun.management.jmxremote -Dcom.sun.management.jmxremote.local.only=false org.apache.zookeeper.server.quorum.QuorumPeerMain "/Users/nrh/projects/pogo/t/conf/zookeeper.conf"
'
[2516] INFO PogoTester::start_zookeeper:117 spawned zookeeper (pid 2528)
[2516] DEBUG ZooKeeper.new:72 serverlist=localhost:18121
[2516] INFO ZooKeeper.new:78 Connected to 'localhost:18121'
[2516] DEBUG ZooKeeper.new:79 Session timeout is 10.00 seconds.
[2516] DEBUG ZooKeeper.new:97 Everything is OK
[2516] INFO PogoTester::start_dispatcher:88 spawned dispatcher (pid 2529)
[2529] DEBUG ZooKeeper.new:72 serverlist=localhost:18121
[2529] INFO ZooKeeper.new:78 Connected to 'localhost:18121'
[2529] DEBUG ZooKeeper.new:79 Session timeout is 10.00 seconds.
[2529] DEBUG ZooKeeper.new:97 Everything is OK
[2529] INFO Dispatcher.AE:cb:prepare_cb:79 Accepting worker connections on 0.0.0.0:7654
[2529] INFO Dispatcher.AE:cb:prepare_cb:89 Accepting RPC connections on 127.0.0.1:7655
[2529] DEBUG Dispatcher._write_stats:193 creating new stats node
[2516] INFO PogoTester::start_worker:142 spawned worker (pid 2530)
[14:29:36] ../t/51_smoke_loadconf.t ......... 1/? [2516] DEBUG ZooKeeper.new:72 serverlist=localhost:18121
[2516] INFO ZooKeeper.new:78 Connected to 'localhost:18121'
[2516] DEBUG ZooKeeper.new:79 Session timeout is 10.00 seconds.
[2516] DEBUG ZooKeeper.new:97 Everything is OK
[2516] INFO Engine.loadconf:436 loading new config for namespace 'valid1'
[2516] DEBUG Namespace.set_conf:263 processing 'valid1/plugin/targets'
[2516] DEBUG Namespace.set_conf:263 processing 'valid1/plugin/apps'
[2516] DEBUG Namespace.set_conf:263 processing 'valid1/plugin/envs'
[2516] DEBUG Namespace.set_conf:272 processing 'valid1/app/frontend'
[2516] DEBUG Namespace.set_conf:272 processing 'valid1/app/backend'
[2516] DEBUG Namespace.set_conf:282 processing 'valid1/env/coast'
[2516] DEBUG Namespace.set_conf:293 processing 'valid1/constraints/coast'
[2516] DEBUG Namespace._write_conf:385 writing /pogo/ns/valid1
2010/12/22 14:29:41 Connecting to dispatcher at localhost:7654
2010/12/22 14:29:41 Connected to dispatcher at localhost:7654
2010/12/22 14:29:41 certificate: /C=US/ST=California/L=Sunnyvale/O=Yahoo!/OU=Deployment Tools/CN=deptools.corp.yahoo.com/emailAddress=ymail-infra-devel.yahoo-inc.com/C=US/ST=California/L=Sunnyvale/O=Yahoo!/OU=Deployment Tools/CN=deptools.corp.yahoo.com/emailAddress=ymail-infra-devel.yahoo-inc.com
[2529] INFO Server.handle_request:42 Received HTTP request for /v3 from 127.0.0.1:59520
[2529] INFO Engine.loadconf:436 loading new config for namespace 'valid1'
[2529] DEBUG Namespace.set_conf:263 processing 'valid1/plugin/targets'
[2529] DEBUG Namespace.set_conf:263 processing 'valid1/plugin/apps'
[2529] DEBUG Namespace.set_conf:263 processing 'valid1/plugin/envs'
[2529] DEBUG Namespace.set_conf:272 processing 'valid1/app/frontend'
[2529] DEBUG Namespace.set_conf:272 processing 'valid1/app/backend'
[2529] DEBUG Namespace.set_conf:282 processing 'valid1/env/coast'
[2529] DEBUG Namespace.set_conf:293 processing 'valid1/constraints/coast'
[2529] DEBUG Namespace._write_conf:385 writing /pogo/ns/valid1
[2529] DEBUG WorkerConnection.__ANON__:49 certificate: /C=US/ST=New York/L=Brooklyn/O=Internets/OU=Pogo/CN=Pogo User/emailAddress=pogo-devel.example.com/C=US/ST=New York/L=Brooklyn/O=Internets/OU=Pogo/CN=Pogo User/emailAddress=pogo-devel.example.com
[14:29:36] ../t/51_smoke_loadconf.t ......... 5/? [2529] INFO WorkerConnection.__ANON__:57 Received connection from worker at 127.0.0.1:59519
[2529] INFO WorkerConnection.__ANON__:60 SSL/TLS handshake completed with worker at 127.0.0.1:59519
[2529] DEBUG Dispatcher.idle_worker:217 Marked worker 127.0.0.1:59519 idle
2010/12/22 14:29:42 SSL/TLS handshake completed with dispatcher at localhost:7654
[2516] INFO Engine.loadconf:436 loading new config for namespace 'valid2'
[2516] DEBUG Namespace.set_conf:263 processing 'valid2/plugin/targets'
[2516] DEBUG Namespace.set_conf:263 processing 'valid2/plugin/apps'
[2516] DEBUG Namespace.set_conf:263 processing 'valid2/plugin/envs'
[2516] DEBUG Namespace._write_conf:385 writing /pogo/ns/valid2
[2529] INFO Server.handle_request:42 Received HTTP request for /v3 from 127.0.0.1:59521
[2529] INFO Engine.loadconf:436 loading new config for namespace 'valid2'
[2529] DEBUG Namespace.set_conf:263 processing 'valid2/plugin/targets'
[2529] DEBUG Namespace.set_conf:263 processing 'valid2/plugin/apps'
[2529] DEBUG Namespace.set_conf:263 processing 'valid2/plugin/envs'
[2529] DEBUG Namespace._write_conf:385 writing /pogo/ns/valid2
[2516] INFO Engine.loadconf:436 loading new config for namespace 'valid3'
[2516] DEBUG Namespace.set_conf:263 processing 'valid3/plugin/targets'
[2516] DEBUG Namespace.set_conf:263 processing 'valid3/plugin/apps'
[2516] DEBUG Namespace.set_conf:263 processing 'valid3/plugin/envs'
[2516] DEBUG Namespace._write_conf:385 writing /pogo/ns/valid3
[2529] INFO Server.handle_request:42 Received HTTP request for /v3 from 127.0.0.1:59522
[2529] INFO Engine.loadconf:436 loading new config for namespace 'valid3'
[2529] DEBUG Namespace.set_conf:263 processing 'valid3/plugin/targets'
[2529] DEBUG Namespace.set_conf:263 processing 'valid3/plugin/apps'
[2529] DEBUG Namespace.set_conf:263 processing 'valid3/plugin/envs'
[2529] DEBUG Namespace._write_conf:385 writing /pogo/ns/valid3
[2516] DEBUG Namespace.set_conf:263 processing 'example/plugin/targets'
[2516] DEBUG Namespace.set_conf:263 processing 'example/plugin/apps'
[2516] DEBUG Namespace.set_conf:263 processing 'example/plugin/envs'
[2516] DEBUG Namespace.set_conf:272 processing 'example/app/frontend'
[2516] DEBUG Namespace.set_conf:272 processing 'example/app/backend'
[2516] DEBUG Namespace.set_conf:282 processing 'example/env/coast'
[2516] DEBUG Namespace.set_conf:293 processing 'example/constraints/coast'
[2516] DEBUG Namespace._write_conf:385 writing /pogo/ns/example
[2516] INFO PogoTester::stop_worker:149 killing 2530
[2516] INFO PogoTester::stop_dispatcher:95 killing 2529
[2516] INFO PogoTester::stop_zookeeper:124 killing 2528
2010/12/22 14:29:42 Process terminated by SIGTERM
[2529] FATAL main::sighandler:161 Process terminated by SIGTERM
[14:29:36] ../t/51_smoke_loadconf.t ......... ok 6133 ms
[14:29:42] ../t/52_smoke_authstore.t ........ [2531] DEBUG PogoTester::start_zookeeper:104 using 'java -Dzookeeper.log.dir="." -Dzookeeper.root.logger="INFO,CONSOLE" -cp "/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../build/classes:/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../build/lib/*.jar:/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../zookeeper-3.3.1.jar:/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../lib/log4j-1.2.15.jar:/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../lib/jline-0.9.94.jar:/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../src/java/lib/*.jar:/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../conf:" -Dcom.sun.management.jmxremote -Dcom.sun.management.jmxremote.local.only=false org.apache.zookeeper.server.quorum.QuorumPeerMain "/Users/nrh/projects/pogo/t/conf/zookeeper.conf"
'
[2531] INFO PogoTester::start_zookeeper:117 spawned zookeeper (pid 2543)
Can't locate object method "init" via package "Pogo::Engine::Store" (perhaps you forgot to load "Pogo::Engine::Store"?) at /Users/nrh/projects/pogo/t/lib/PogoTester.pm line 156.
[2531] INFO PogoTester::stop_zookeeper:124 killing 2543
[14:29:42] ../t/52_smoke_authstore.t ........ Dubious, test returned 60 (wstat 15360, 0x3c00)
No subtests run
[14:29:45] ../t/53_smoke_dispatcher.t ....... Can't locate PogoTester.pm in @INC (@INC contains: /opt/local/lib/perl5/site_perl/5.10.1/darwin-2level /opt/local/lib/perl5/site_perl/5.10.1 /opt/local/lib/perl5/vendor_perl/5.10.1/darwin-2level /opt/local/lib/perl5/vendor_perl/5.10.1 /opt/local/lib/perl5/vendor_perl /opt/local/lib/perl5/5.10.1/darwin-2level /opt/local/lib/perl5/5.10.1 .) at ../t/53_smoke_dispatcher.t line 29.
BEGIN failed--compilation aborted at ../t/53_smoke_dispatcher.t line 29.
[14:29:45] ../t/53_smoke_dispatcher.t ....... Dubious, test returned 2 (wstat 512, 0x200)
No subtests run
[14:29:45] ../t/54_smoke_worker.t ........... [2545] DEBUG PogoTester::start_zookeeper:104 using 'java -Dzookeeper.log.dir="." -Dzookeeper.root.logger="INFO,CONSOLE" -cp "/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../build/classes:/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../build/lib/*.jar:/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../zookeeper-3.3.1.jar:/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../lib/log4j-1.2.15.jar:/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../lib/jline-0.9.94.jar:/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../src/java/lib/*.jar:/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../conf:" -Dcom.sun.management.jmxremote -Dcom.sun.management.jmxremote.local.only=false org.apache.zookeeper.server.quorum.QuorumPeerMain "/Users/nrh/projects/pogo/t/conf/zookeeper.conf"
'
[2545] INFO PogoTester::start_zookeeper:117 spawned zookeeper (pid 2557)
Can't locate object method "init" via package "Pogo::Engine::Store" (perhaps you forgot to load "Pogo::Engine::Store"?) at /Users/nrh/projects/pogo/t/lib/PogoTester.pm line 156.
[2545] INFO PogoTester::stop_zookeeper:124 killing 2557
[14:29:45] ../t/54_smoke_worker.t ........... Dubious, test returned 60 (wstat 15360, 0x3c00)
No subtests run
[14:29:47] ../t/55_smoke_job.t .............. [2558] DEBUG PogoTester::start_zookeeper:104 using 'java -Dzookeeper.log.dir="." -Dzookeeper.root.logger="INFO,CONSOLE" -cp "/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../build/classes:/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../build/lib/*.jar:/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../zookeeper-3.3.1.jar:/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../lib/log4j-1.2.15.jar:/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../lib/jline-0.9.94.jar:/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../src/java/lib/*.jar:/Users/nrh/projects/pogo/t/../build/zookeeper/bin/../conf:" -Dcom.sun.management.jmxremote -Dcom.sun.management.jmxremote.local.only=false org.apache.zookeeper.server.quorum.QuorumPeerMain "/Users/nrh/projects/pogo/t/conf/zookeeper.conf"
'
[2558] INFO PogoTester::start_zookeeper:117 spawned zookeeper (pid 2570)
[2558] DEBUG ZooKeeper.new:72 serverlist=localhost:18121
[2558] INFO ZooKeeper.new:78 Connected to 'localhost:18121'
[2558] DEBUG ZooKeeper.new:79 Session timeout is 10.00 seconds.
[2558] DEBUG ZooKeeper.new:97 Everything is OK
[2558] INFO PogoTester::start_dispatcher:88 spawned dispatcher (pid 2571)
[2571] DEBUG ZooKeeper.new:72 serverlist=localhost:18121
[2571] INFO ZooKeeper.new:78 Connected to 'localhost:18121'
[2571] DEBUG ZooKeeper.new:79 Session timeout is 10.00 seconds.
[2571] DEBUG ZooKeeper.new:97 Everything is OK
[2571] INFO Dispatcher.AE:cb:prepare_cb:79 Accepting worker connections on 0.0.0.0:7654
[2571] INFO Dispatcher.AE:cb:prepare_cb:89 Accepting RPC connections on 127.0.0.1:7655
[2571] DEBUG Dispatcher._write_stats:193 creating new stats node
[2558] INFO PogoTester::start_worker:142 spawned worker (pid 2572)
[2571] INFO Server.handle_request:42 Received HTTP request for /v3 from 127.0.0.1:59534
[14:29:47] ../t/55_smoke_job.t .............. 1/? [2571] INFO Server.handle_request:42 Received HTTP request for /v3 from 127.0.0.1:59535
[2571] INFO Server.handle_request:42 Received HTTP request for /v3 from 127.0.0.1:59536
[2571] INFO Engine.loadconf:436 loading new config for namespace 'example'
[2571] DEBUG Namespace.set_conf:263 processing 'example/plugin/targets'
[2571] DEBUG Namespace.set_conf:263 processing 'example/plugin/apps'
[2571] DEBUG Namespace.set_conf:263 processing 'example/plugin/envs'
[2571] DEBUG Namespace.set_conf:272 processing 'example/app/frontend'
[2571] DEBUG Namespace.set_conf:272 processing 'example/app/backend'
[2571] DEBUG Namespace.set_conf:282 processing 'example/env/coast'
[2571] DEBUG Namespace.set_conf:293 processing 'example/constraints/coast'
[2571] DEBUG Namespace._write_conf:385 writing /pogo/ns/example
2010/12/22 14:29:53 Connecting to dispatcher at localhost:7654
2010/12/22 14:29:53 Connected to dispatcher at localhost:7654
2010/12/22 14:29:53 certificate: /C=US/ST=California/L=Sunnyvale/O=Yahoo!/OU=Deployment Tools/CN=deptools.corp.yahoo.com/emailAddress=ymail-infra-devel.yahoo-inc.com/C=US/ST=California/L=Sunnyvale/O=Yahoo!/OU=Deployment Tools/CN=deptools.corp.yahoo.com/emailAddress=ymail-infra-devel.yahoo-inc.com
[2571] DEBUG WorkerConnection.__ANON__:49 certificate: /C=US/ST=New York/L=Brooklyn/O=Internets/OU=Pogo/CN=Pogo User/emailAddress=pogo-devel.example.com/C=US/ST=New York/L=Brooklyn/O=Internets/OU=Pogo/CN=Pogo User/emailAddress=pogo-devel.example.com
[2571] INFO WorkerConnection.__ANON__:57 Received connection from worker at 127.0.0.1:59537
[2571] INFO WorkerConnection.__ANON__:60 SSL/TLS handshake completed with worker at 127.0.0.1:59537
[2571] DEBUG Dispatcher.idle_worker:217 Marked worker 127.0.0.1:59537 idle
2010/12/22 14:29:53 SSL/TLS handshake completed with dispatcher at localhost:7654
[2571] DEBUG RPCConnection.AE:cb:on_read:push_read:101 rpc 'storesecrets' from localhost:59538
[2571] DEBUG RPCConnection.AE:cb:on_read:push_read:114 got secrets for job p0000000004 from local RPC
[2571] INFO AuthStore._store_local:259 stored secrets for job p0000000004
[2558] INFO Job.__ANON__:105 passwords for p0000000004 stored to local dispatcher
[2571] ERROR RPCConnection.AE:cb:on_eof:82 EOF received from RPC client at 127.0.0.1:59538
[2558] DEBUG Engine.add_task:551 adding task: ["startjob","p0000000004"]
[2571] INFO Job.start:438 starting job p0000000004
[2571] DEBUG Job.start:468 we are concurrent!
[2571] DEBUG Job.host:227 adding host foo1.example.com
[2571] DEBUG Job.host:227 adding host foo10.example.com
[2571] DEBUG Job.host:227 adding host foo2.example.com
[2571] DEBUG Job.host:227 adding host foo3.example.com
[2571] DEBUG Job.host:227 adding host foo4.example.com
[2571] DEBUG Job.host:227 adding host foo5.example.com
[2571] DEBUG Job.host:227 adding host foo6.example.com
[2571] DEBUG Job.host:227 adding host foo7.example.com
[2571] DEBUG Job.host:227 adding host foo8.example.com
[2571] DEBUG Job.host:227 adding host foo9.example.com
[2571] DEBUG Job.start_job_timeout:363 starting p0000000004 timeout timer for 3 sec
[2571] DEBUG Job.continue:533 continuing job p0000000004
[2571] DEBUG Job.host:227 adding host foo1.example.com
[2571] DEBUG Namespace.fetch_runnable_hosts:fetch_all_slots:cont:134 reserving /pogo/ns/example/env/locks_p0000000004_concurrent for foo1.example.com
[2571] DEBUG Job.host:227 adding host foo10.example.com
[2571] DEBUG Job.host:227 adding host foo2.example.com
[2571] DEBUG Job.host:227 adding host foo3.example.com
[2571] DEBUG Job.host:227 adding host foo4.example.com
[2571] DEBUG Job.host:227 adding host foo5.example.com
[2571] DEBUG Job.host:227 adding host foo6.example.com
[2571] DEBUG Job.host:227 adding host foo7.example.com
[2571] DEBUG Job.host:227 adding host foo8.example.com
[2571] DEBUG Job.host:227 adding host foo9.example.com
[2571] DEBUG Job.continue:fetch_runnable_hosts:cont:547 enqueueing foo1.example.com
[2571] DEBUG Job.host:227 adding host foo1.example.com
[2571] DEBUG Engine.add_task:551 adding task: ["runhost","p0000000004","foo1.example.com"]
[2571] DEBUG Job.continue:fetch_runnable_hosts:cont:555 not runnable yet: foo10.example.com - p0000000004 concurrent locks
[2571] DEBUG Job.host:227 adding host foo10.example.com
[2571] DEBUG Job.continue:fetch_runnable_hosts:cont:555 not runnable yet: foo2.example.com - p0000000004 concurrent locks
[2571] DEBUG Job.host:227 adding host foo2.example.com
[2571] DEBUG Job.continue:fetch_runnable_hosts:cont:555 not runnable yet: foo9.example.com - p0000000004 concurrent locks
[2571] DEBUG Job.host:227 adding host foo9.example.com
[2571] DEBUG Job.continue:fetch_runnable_hosts:cont:555 not runnable yet: foo7.example.com - p0000000004 concurrent locks
[2571] DEBUG Job.host:227 adding host foo7.example.com
[2571] DEBUG Job.continue:fetch_runnable_hosts:cont:555 not runnable yet: foo3.example.com - p0000000004 concurrent locks
[2571] DEBUG Job.host:227 adding host foo3.example.com
[2571] DEBUG Job.continue:fetch_runnable_hosts:cont:555 not runnable yet: foo5.example.com - p0000000004 concurrent locks
[2571] DEBUG Job.host:227 adding host foo5.example.com
[2571] DEBUG Job.continue:fetch_runnable_hosts:cont:555 not runnable yet: foo4.example.com - p0000000004 concurrent locks
[2571] DEBUG Job.host:227 adding host foo4.example.com
[2571] DEBUG Job.continue:fetch_runnable_hosts:cont:555 not runnable yet: foo6.example.com - p0000000004 concurrent locks
[2571] DEBUG Job.host:227 adding host foo6.example.com
[2571] DEBUG Job.continue:fetch_runnable_hosts:cont:555 not runnable yet: foo8.example.com - p0000000004 concurrent locks
[2571] DEBUG Job.host:227 adding host foo8.example.com
[2571] DEBUG Job.host:227 adding host foo9.example.com
[2571] DEBUG Job.host:227 adding host foo6.example.com
[2571] DEBUG Job.host:227 adding host foo4.example.com
[2571] DEBUG Job.host:227 adding host foo8.example.com
[2571] DEBUG Job.host:227 adding host foo3.example.com
[2571] DEBUG Job.host:227 adding host foo5.example.com
[2571] DEBUG Job.host:227 adding host foo7.example.com
[2571] DEBUG Job.host:227 adding host foo1.example.com
[2571] DEBUG Job.host:227 adding host foo10.example.com
[2571] DEBUG Job.host:227 adding host foo2.example.com
[2571] DEBUG WorkerConnection.start_task:125 p0000000004: foo1.example.com assigned to worker 127.0.0.1:59537
2010/12/22 14:29:55 [p0000000004/foo1.example.com] Received command 'execute'
2010/12/22 14:29:55 Current task count: 1
2010/12/22 14:29:55 [p0000000004/foo1.example.com] Executing job for test
[2571] DEBUG Dispatcher.idle_worker:217 Marked worker 127.0.0.1:59537 idle
2010/12/22 14:29:55 [p0000000004/foo1.example.com] Launched ../bin/pogo-rexecpid 2573
2010/12/22 14:29:55 Writing to output file .tmp/pogo_output/p0000000004/foo1.example.com.0.txt
[2571] FATAL main::__ANON__:146 Process terminated: Can't locate object method "start_host" via package "Pogo::Engine::Job" at /Users/nrh/projects/pogo/t/../lib/Pogo/Dispatcher/WorkerConnection.pm line 98.
[2571] FATAL main::__ANON__:146 at /Users/nrh/projects/pogo/t/../bin/pogo-dispatcher line 146
[2571] FATAL main::__ANON__:146 main::__ANON__('Can\'t locate object method "start_host" via package "Pogo::E...') called at /Users/nrh/projects/pogo/t/../lib/Pogo/Dispatcher/WorkerConnection.pm line 98
[2571] FATAL main::__ANON__:146 Pogo::Dispatcher::WorkerConnection::__ANON__('AnyEvent::Handle=HASH(0x102800288)', 'ARRAY(0x1020084d0)') called at /opt/local/lib/perl5/site_perl/5.10.1/AnyEvent/Handle.pm line 1621
[2571] FATAL main::__ANON__:146 AnyEvent::Handle::__ANON__('AnyEvent::Handle=HASH(0x102800288)') called at /opt/local/lib/perl5/site_perl/5.10.1/AnyEvent/Handle.pm line 1198
[2571] FATAL main::__ANON__:146 AnyEvent::Handle::_drain_rbuf('AnyEvent::Handle=HASH(0x102800288)') called at /opt/local/lib/perl5/site_perl/5.10.1/AnyEvent/Handle.pm line 1831
[2571] FATAL main::__ANON__:146 AnyEvent::Handle::_dotls('AnyEvent::Handle=HASH(0x102800288)') called at /opt/local/lib/perl5/site_perl/5.10.1/AnyEvent/Handle.pm line 1753
[2571] FATAL main::__ANON__:146 AnyEvent::Handle::__ANON__() called at /opt/local/lib/perl5/site_perl/5.10.1/AnyEvent/Impl/Perl.pm line 238
[2571] FATAL main::__ANON__:146 AnyEvent::Impl::Perl::one_event('AnyEvent') called at /opt/local/lib/perl5/site_perl/5.10.1/AnyEvent.pm line 1768
[2571] FATAL main::__ANON__:146 AnyEvent::CondVar::Base::_wait('AnyEvent::CondVar=HASH(0x100d27848)') called at /opt/local/lib/perl5/site_perl/5.10.1/AnyEvent.pm line 1772
[2571] FATAL main::__ANON__:146 AnyEvent::CondVar::Base::recv('AnyEvent::CondVar=HASH(0x100d27848)') called at /Users/nrh/projects/pogo/t/../lib/Pogo/Dispatcher.pm line 106
[2571] FATAL main::__ANON__:146 Pogo::Dispatcher::run('Pogo::Dispatcher', 'peers', 'ARRAY(0x100d14078)', 'store', 'zookeeper', 'worker_cert', 'conf/worker.cert', 'authstore_port', 7656, ...) called at /Users/nrh/projects/pogo/t/../bin/pogo-dispatcher line 176
[2571] FATAL main::__ANON__:146 main::main() called at /Users/nrh/projects/pogo/t/../bin/pogo-dispatcher line 182
Process terminated: Can't locate object method "start_host" via package "Pogo::Engine::Job" at /Users/nrh/projects/pogo/t/../lib/Pogo/Dispatcher/WorkerConnection.pm line 98.
at /Users/nrh/projects/pogo/t/../bin/pogo-dispatcher line 146
main::__ANON__('Can\'t locate object method "start_host" via package "Pogo::E...') called at /Users/nrh/projects/pogo/t/../lib/Pogo/Dispatcher/WorkerConnection.pm line 98
Pogo::Dispatcher::WorkerConnection::__ANON__('AnyEvent::Handle=HASH(0x102800288)', 'ARRAY(0x1020084d0)') called at /opt/local/lib/perl5/site_perl/5.10.1/AnyEvent/Handle.pm line 1621
AnyEvent::Handle::__ANON__('AnyEvent::Handle=HASH(0x102800288)') called at /opt/local/lib/perl5/site_perl/5.10.1/AnyEvent/Handle.pm line 1198
AnyEvent::Handle::_drain_rbuf('AnyEvent::Handle=HASH(0x102800288)') called at /opt/local/lib/perl5/site_perl/5.10.1/AnyEvent/Handle.pm line 1831
AnyEvent::Handle::_dotls('AnyEvent::Handle=HASH(0x102800288)') called at /opt/local/lib/perl5/site_perl/5.10.1/AnyEvent/Handle.pm line 1753
AnyEvent::Handle::__ANON__() called at /opt/local/lib/perl5/site_perl/5.10.1/AnyEvent/Impl/Perl.pm line 238
AnyEvent::Impl::Perl::one_event('AnyEvent') called at /opt/local/lib/perl5/site_perl/5.10.1/AnyEvent.pm line 1768
AnyEvent::CondVar::Base::_wait('AnyEvent::CondVar=HASH(0x100d27848)') called at /opt/local/lib/perl5/site_perl/5.10.1/AnyEvent.pm line 1772
AnyEvent::CondVar::Base::recv('AnyEvent::CondVar=HASH(0x100d27848)') called at /Users/nrh/projects/pogo/t/../lib/Pogo/Dispatcher.pm line 106
Pogo::Dispatcher::run('Pogo::Dispatcher', 'peers', 'ARRAY(0x100d14078)', 'store', 'zookeeper', 'worker_cert', 'conf/worker.cert', 'authstore_port', 7656, ...) called at /Users/nrh/projects/pogo/t/../bin/pogo-dispatcher line 176
main::main() called at /Users/nrh/projects/pogo/t/../bin/pogo-dispatcher line 182
2010/12/22 14:29:55 I/O error occurred while communicating with localhost:7654: Unexpected end-of-file
2010/12/22 14:29:55 Reattempting connection to localhost:7654 in 14.86 seconds.
2010/12/22 14:29:55 [p0000000004/foo1.example.com] Received EOF
2010/12/22 14:29:55 [p0000000004/foo1.example.com] Process returned code 2 (waitpid returned 2573)
2010/12/22 14:29:55 queuing response: ["finish","p0000000004","foo1.example.com","2",null]
[14:29:47] ../t/55_smoke_job.t .............. 10/?
# Failed test 'job timeout'
# at ../t/55_smoke_job.t line 87.
# got: 'gathering'
# expected: 'halted'
[2558] INFO PogoTester::stop_worker:149 killing 2572
[2558] INFO PogoTester::stop_dispatcher:95 killing 2571
[2558] INFO PogoTester::stop_zookeeper:124 killing 2570
2010/12/22 14:29:57 Process terminated by SIGTERM
# Looks like you failed 1 test of 10.
[14:29:47] ../t/55_smoke_job.t .............. Dubious, test returned 1 (wstat 256, 0x100)
Failed 1/10 subtests
[14:29:57]
Test Summary Report
-------------------
../t/50_smoke_start_zookeeper.t (Wstat: 65280 Tests: 0 Failed: 0)
Non-zero exit status: 255
Parse errors: No plan found in TAP output
../t/52_smoke_authstore.t (Wstat: 15360 Tests: 0 Failed: 0)
Non-zero exit status: 60
Parse errors: No plan found in TAP output
../t/53_smoke_dispatcher.t (Wstat: 512 Tests: 0 Failed: 0)
Non-zero exit status: 2
Parse errors: No plan found in TAP output
../t/54_smoke_worker.t (Wstat: 15360 Tests: 0 Failed: 0)
Non-zero exit status: 60
Parse errors: No plan found in TAP output
../t/55_smoke_job.t (Wstat: 256 Tests: 10 Failed: 1)
Failed test: 10
Non-zero exit status: 1
Files=7, Tests=48, 22 wallclock secs ( 0.04 usr 0.03 sys + 2.39 cusr 0.51 csys = 2.97 CPU)
Result: FAIL
make: *** [test] Error 1
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment