bosh task 16 --debug
Director task 16
# Logfile created on 2013-04-11 06:37:20 +0000 by logger.rb/31641
I, [2013-04-11T06:37:20.079325 #2593] INFO -- : Director Version : 1.5.0.pre.3
I, [2013-04-11T06:37:20.079392 #2593] INFO -- : Enqueuing task: 16
I, [2013-04-11T06:37:23.527610 #11176] [0xf2006c] INFO -- : Starting task: 16
I, [2013-04-11T06:37:23.527710 #11176] [task:16] INFO -- : Creating job
D, [2013-04-11T06:37:23.655374 #11176] [task:16] DEBUG -- : Acquired connection: 17076560
D, [2013-04-11T06:37:23.656190 #11176] [task:16] DEBUG -- : (0.000594s) SELECT * FROM "tasks" WHERE "id" = 16
D, [2013-04-11T06:37:23.656579 #11176] [task:16] DEBUG -- : Released connection: 17076560
I, [2013-04-11T06:37:23.656869 #11176] [task:16] INFO -- : Performing task: 16
D, [2013-04-11T06:37:23.657739 #11176] [task:16] DEBUG -- : Acquired connection: 17076560
D, [2013-04-11T06:37:23.658128 #11176] [task:16] DEBUG -- : (0.000159s) BEGIN
D, [2013-04-11T06:37:23.660300 #11176] [task:16] DEBUG -- : (0.001000s) UPDATE "tasks" SET "state" = 'processing', "timestamp" = '2013-04-11 06:37:23.657402+0000', "description" = 'ssh: setup:{"job"=>"director", "indexes"=>[0]}', "result" = NULL, "output" = '/var/vcap/store/director/tasks/16', "user_id" = 1, "checkpoint_time" = '2013-04-11 06:37:23.657489+0000', "type" = 'ssh' WHERE ("id" = 16)
D, [2013-04-11T06:37:23.661789 #11176] [task:16] DEBUG -- : (0.001246s) COMMIT
D, [2013-04-11T06:37:23.662091 #11176] [task:16] DEBUG -- : Released connection: 17076560
D, [2013-04-11T06:37:23.662744 #11176] [task:16] DEBUG -- : Acquired connection: 17076560
D, [2013-04-11T06:37:23.662867 #11176] [task:16] DEBUG -- : Released connection: 17076560
D, [2013-04-11T06:37:23.663077 #11176] [task:16] DEBUG -- : Acquired connection: 17076560
D, [2013-04-11T06:37:23.664267 #11176] [task:16] DEBUG -- : (0.001071s) SELECT * FROM "instances" WHERE (("deployment_id" = 1) AND ("index" IN (0)) AND ("job" = 'director'))
D, [2013-04-11T06:37:23.664468 #11176] [task:16] DEBUG -- : Released connection: 17076560
D, [2013-04-11T06:37:23.665295 #11176] [task:16] DEBUG -- : Acquired connection: 17076560
D, [2013-04-11T06:37:23.666242 #11176] [task:16] DEBUG -- : (0.000787s) SELECT * FROM "vms" WHERE ("vms"."id" = 5) LIMIT 1
D, [2013-04-11T06:37:23.666408 #11176] [task:16] DEBUG -- : Released connection: 17076560
D, [2013-04-11T06:37:23.666999 #11176] [task:16] DEBUG -- : Acquired connection: 17076560
D, [2013-04-11T06:37:23.667110 #11176] [task:16] DEBUG -- : Released connection: 17076560
D, [2013-04-11T06:37:23.667246 #11176] [task:16] DEBUG -- : Acquired connection: 17076560
D, [2013-04-11T06:37:23.667882 #11176] [task:16] DEBUG -- : (0.000528s) SELECT * FROM "vms" WHERE ("agent_id" = '7d011ea6-db18-486c-b367-157afedf70fa') LIMIT 1
D, [2013-04-11T06:37:23.668067 #11176] [task:16] DEBUG -- : Released connection: 17076560
I, [2013-04-11T06:37:23.669526 #11176] [task:16] INFO -- : ssh setup `director/0'
D, [2013-04-11T06:37:23.669991 #11176] [task:16] DEBUG -- : SENT: agent.7d011ea6-db18-486c-b367-157afedf70fa {"method":"ssh","arguments":["setup",{"user":"{user_name}","public_key":"ssh-rsa {key} vcap@inception-vm\n","password":"{pw}"}],"reply_to":"director.80c40800-5e2a-499f-ba7f-ed06c32e332c.a41f037a-aa9c-4687-9379-fb03c81abbe1"}
D, [2013-04-11T06:37:23.787343 #11176] [0x17c28dc] DEBUG -- : RECEIVED: director.80c40800-5e2a-499f-ba7f-ed06c32e332c.a41f037a-aa9c-4687-9379-fb03c81abbe1 {"value":{"command":"setup","status":"success","ip":"10.200.7.10"}}
I, [2013-04-11T06:37:23.788292 #11176] [task:16] INFO -- : Done
D, [2013-04-11T06:37:23.788882 #11176] [task:16] DEBUG -- : Acquired connection: 17076560
D, [2013-04-11T06:37:23.789350 #11176] [task:16] DEBUG -- : (0.000262s) BEGIN
D, [2013-04-11T06:37:23.791272 #11176] [task:16] DEBUG -- : (0.000808s) UPDATE "tasks" SET "state" = 'done', "timestamp" = '2013-04-11 06:37:23.788577+0000', "description" = 'ssh: setup:{"job"=>"director", "indexes"=>[0]}', "result" = '', "output" = '/var/vcap/store/director/tasks/16', "user_id" = 1, "checkpoint_time" = '2013-04-11 06:37:23.657489+0000', "type" = 'ssh' WHERE ("id" = 16)
D, [2013-04-11T06:37:23.792613 #11176] [task:16] DEBUG -- : (0.001050s) COMMIT
D, [2013-04-11T06:37:23.792770 #11176] [task:16] DEBUG -- : Released connection: 17076560
I, [2013-04-11T06:37:23.792934 #11176] [0xf2006c] INFO -- : Task took 0.265165193 seconds to process.