2010-08-13 12:51:16,076 0522149171 Processing file list 2010-08-13 12:51:16,088 0522149171 Updating jars 2010-08-13 12:51:16,090 0522149171 Downloading backport-util-concurrent.jar 2010-08-13 12:51:16,315 0522149171 Downloading cog-abstraction-common-2.3.jar 2010-08-13 12:51:16,517 0522149171 Downloading cog-jglobus-1.7.0.jar 2010-08-13 12:51:16,793 0522149171 Downloading cog-karajan-0.36-dev.jar 2010-08-13 12:51:17,083 0522149171 Downloading cog-provider-coaster-0.3.jar 2010-08-13 12:51:17,272 0522149171 Downloading cog-provider-gt2-2.4.jar 2010-08-13 12:51:17,482 0522149171 Downloading cog-provider-gt4_0_0-2.5.jar 2010-08-13 12:51:17,702 0522149171 Downloading cog-provider-local-2.2.jar 2010-08-13 12:51:17,891 0522149171 Downloading cog-provider-localscheduler-0.4.jar 2010-08-13 12:51:18,105 0522149171 Downloading cog-provider-ssh-2.4.jar 2010-08-13 12:51:18,316 0522149171 Downloading cog-util-0.92.jar 2010-08-13 12:51:18,521 0522149171 Downloading cryptix.jar 2010-08-13 12:51:18,716 0522149171 Downloading cryptix32.jar 2010-08-13 12:51:18,969 0522149171 Downloading cryptix-asn1.jar 2010-08-13 12:51:19,179 0522149171 Downloading concurrent.jar 2010-08-13 12:51:19,399 0522149171 Downloading j2ssh-common-0.2.2.jar 2010-08-13 12:51:19,647 0522149171 Downloading j2ssh-core-0.2.2-patched.jar 2010-08-13 12:51:19,890 0522149171 Downloading jaxrpc.jar 2010-08-13 12:51:20,090 0522149171 Downloading jce-jdk13-131.jar 2010-08-13 12:51:20,411 0522149171 Downloading jgss.jar 2010-08-13 12:51:20,565 0522149171 Downloading log4j-1.2.8.jar 2010-08-13 12:51:20,814 0522149171 Downloading puretls.jar 2010-08-13 12:51:21,038 0522149171 Downloading addressing-1.0.jar 2010-08-13 12:51:21,255 0522149171 Downloading commonj.jar 2010-08-13 12:51:21,448 0522149171 Downloading axis.jar 2010-08-13 12:51:21,871 0522149171 Downloading axis-url.jar 2010-08-13 12:51:22,009 0522149171 Downloading cog-axis.jar 2010-08-13 12:51:22,224 0522149171 Downloading globus_delegation_service.jar 2010-08-13 12:51:22,428 0522149171 Downloading globus_delegation_stubs.jar 2010-08-13 12:51:22,644 0522149171 Downloading globus_wsrf_rendezvous_stubs.jar 2010-08-13 12:51:22,854 0522149171 Downloading globus_wsrf_mds_aggregator_stubs.jar 2010-08-13 12:51:23,057 0522149171 Downloading globus_wsrf_rft_stubs.jar 2010-08-13 12:51:23,271 0522149171 Downloading globus_wsrf_rendezvous_service.jar 2010-08-13 12:51:23,471 0522149171 Downloading gram-utils.jar 2010-08-13 12:51:23,680 0522149171 Downloading gram-client.jar 2010-08-13 12:51:23,887 0522149171 Downloading gram-stubs.jar 2010-08-13 12:51:24,113 0522149171 Downloading naming-common.jar 2010-08-13 12:51:24,319 0522149171 Downloading naming-resources.jar 2010-08-13 12:51:24,530 0522149171 Downloading naming-java.jar 2010-08-13 12:51:24,728 0522149171 Downloading naming-factory.jar 2010-08-13 12:51:24,937 0522149171 Downloading saaj.jar 2010-08-13 12:51:25,149 0522149171 Downloading wsdl4j.jar 2010-08-13 12:51:25,375 0522149171 Downloading wss4j.jar 2010-08-13 12:51:25,620 0522149171 Downloading commons-collections-3.0.jar 2010-08-13 12:51:25,865 0522149171 Downloading commons-digester.jar 2010-08-13 12:51:26,065 0522149171 Downloading commons-discovery.jar 2010-08-13 12:51:26,270 0522149171 Downloading commons-beanutils.jar 2010-08-13 12:51:26,482 0522149171 Downloading commons-logging-1.1.jar 2010-08-13 12:51:26,683 0522149171 Downloading wsrf_provider_jce.jar 2010-08-13 12:51:26,881 0522149171 Downloading wsrf_tools.jar 2010-08-13 12:51:27,098 0522149171 Downloading wsrf_mds_usefulrp_schema_stubs.jar 2010-08-13 12:51:27,310 0522149171 Downloading wsrf_core_stubs.jar 2010-08-13 12:51:27,570 0522149171 Downloading wsrf_core.jar 2010-08-13 12:51:27,823 0522149171 Downloading wsrf_mds_index_stubs.jar 2010-08-13 12:51:28,020 0522149171 Downloading xalan.jar 2010-08-13 12:51:28,735 0522149171 Downloading xercesImpl.jar 2010-08-13 12:51:28,999 0522149171 Downloading xml-apis.jar 2010-08-13 12:51:29,188 0522149171 Downloading xmlsec.jar 2010-08-13 12:51:29,412 0522149171 Forking service 2010-08-13 12:51:29,413 0522149171 Moved coaster jar to head of classpath 2010-08-13 12:51:29,414 0522149171 Args: [nice, -n, 2, /usr/local/jdk1.5.0_12/bin/java, -Xmx256M, -DX509_USER_PROXY=/u/ac/skenny/.globus/job/abe1196.ncsa.uiuc.edu/14028.1281721872/x509_up, -DGLOBUS_HOSTNAME=grid-abe.ncsa.teragrid.org, -Djava.security.egd=file:///dev/urandom, -cp, /u/ac/skenny/.globus/coasters/cache/cog-provider-coaster-0.3-aa31110dc74c9210ca17eac05131f566.jar:/u/ac/skenny/.globus/coasters/cache/backport-util-concurrent-f9c59530e5d6ca38f3ba6c0b6213e016.jar:/u/ac/skenny/.globus/coasters/cache/cog-abstraction-common-2.3-64a7403302f21522f08ad1dd49b3719b.jar:/u/ac/skenny/.globus/coasters/cache/cog-jglobus-1.7.0-cee6d545519812b15f799ca5130e4f4b.jar:/u/ac/skenny/.globus/coasters/cache/cog-karajan-0.36-dev-b19d6edea84bb96c5689807ecd9a0681.jar:/u/ac/skenny/.globus/coasters/cache/cog-provider-gt2-2.4-da7b2a96423f72ad9068390e8231f5d5.jar:/u/ac/skenny/.globus/coasters/cache/cog-provider-gt4_0_0-2.5-725c4c8e6cf9009bf67fd1260d5cd20f.jar:/u/ac/skenny/.globus/coasters/cache/cog-provider-local-2.2-1965ea2fe368b569a0572375931c1125.jar:/u/ac/skenny/.globus/coasters/cache/cog-provider-localscheduler-0.4-96c45687475f3c9805a95a040a20a151.jar:/u/ac/skenny/.globus/coasters/cache/cog-provider-ssh-2.4-831ae22da7935cafd2db1c767138ce74.jar:/u/ac/skenny/.globus/coasters/cache/cog-util-0.92-2ba1abf6bab4d7a571a9a599ec5e9ee4.jar:/u/ac/skenny/.globus/coasters/cache/cryptix-c3dad86be114c7aaf2ddf32c8e52184a.jar:/u/ac/skenny/.globus/coasters/cache/cryptix32-748b9c4a7b593adcbd5d6fc33eb9e0ad.jar:/u/ac/skenny/.globus/coasters/cache/cryptix-asn1-87c4cf848c81d102bd29e33681b80e8a.jar:/u/ac/skenny/.globus/coasters/cache/concurrent-967678fe1b153be98d982e3867e7271b.jar:/u/ac/skenny/.globus/coasters/cache/j2ssh-common-0.2.2-d65a51ea6f64efc066915c1618c613ca.jar:/u/ac/skenny/.globus/coasters/cache/j2ssh-core-0.2.2-patched-9bf1ffb8ab700234649f70ef4a35f029.jar:/u/ac/skenny/.globus/coasters/cache/jaxrpc-8e7d80b5d77dff6ed2f41352e9147101.jar:/u/ac/skenny/.globus/coasters/cache/jce-jdk13-131-06fc7049669d16c4001a452e100b401f.jar:/u/ac/skenny/.globus/coasters/cache/jgss-9cccfd21259791b509af229a0181f207.jar:/u/ac/skenny/.globus/coasters/cache/log4j-1.2.8-18a4ca847248e5b8606325684342701c.jar:/u/ac/skenny/.globus/coasters/cache/puretls-90b9c31c201243b9f4a24fa11d404702.jar:/u/ac/skenny/.globus/coasters/cache/addressing-1.0-44c19ed929b7d8ab75812b7cd60753c7.jar:/u/ac/skenny/.globus/coasters/cache/commonj-80b93fb3333a17d66fc1afdef5a13563.jar:/u/ac/skenny/.globus/coasters/cache/axis-f01bcaa789cf9735430b289f6b39ea9a.jar:/u/ac/skenny/.globus/coasters/cache/axis-url-fffc9e2378df340c8d3ed0c029867d0d.jar:/u/ac/skenny/.globus/coasters/cache/cog-axis-eafb6cd78da733f3293a5508793c10a4.jar:/u/ac/skenny/.globus/coasters/cache/globus_delegation_service-e49623aeb2d0297615dfb7ad5a834306.jar:/u/ac/skenny/.globus/coasters/cache/globus_delegation_stubs-29ce051b29a9422aeba1f60ac205f1b1.jar:/u/ac/skenny/.globus/coasters/cache/globus_wsrf_rendezvous_stubs-d09ea57f3863104dafca984682ec71ff.jar:/u/ac/skenny/.globus/coasters/cache/globus_wsrf_mds_aggregator_stubs-fbcd9a33c3982fae5a4231ca8f426560.jar:/u/ac/skenny/.globus/coasters/cache/globus_wsrf_rft_stubs-e3be33b222d03afc750b112c7f638f41.jar:/u/ac/skenny/.globus/coasters/cache/globus_wsrf_rendezvous_service-afc177c2e02fd0e773698f3d478a33ef.jar:/u/ac/skenny/.globus/coasters/cache/gram-utils-ab1a282ee889d381b22051a863f086cb.jar:/u/ac/skenny/.globus/coasters/cache/gram-client-197210112784800e635b333acda58ee9.jar:/u/ac/skenny/.globus/coasters/cache/gram-stubs-1bd6f6863c3d4c31bf5aa0dd34adf0be.jar:/u/ac/skenny/.globus/coasters/cache/naming-common-1cfe69c9206c1f13bb328a350e3fb0e4.jar:/u/ac/skenny/.globus/coasters/cache/naming-resources-d7a5b4123aad30d5dc11ca827aa6177a.jar:/u/ac/skenny/.globus/coasters/cache/naming-java-6f6855fb184b81d050d17a1e938cd2a2.jar:/u/ac/skenny/.globus/coasters/cache/naming-factory-ddb1fb5f295162e0389d713822f1112e.jar:/u/ac/skenny/.globus/coasters/cache/saaj-fa0706bd9bcb29f522c1a08db1cbcd94.jar:/u/ac/skenny/.globus/coasters/cache/wsdl4j-a0f571fafcedd5ccd975a3df36e451b2.jar:/u/ac/skenny/.globus/coasters/cache/wss4j-36bb09056ca93ac78d05b3c989d8e13c.jar:/u/ac/skenny/.globus/coasters/cache/commons-collections-3.0-87e27d2ba688c70565f2590946ae7bb6.jar:/u/ac/skenny/.globus/coasters/cache/commons-digester-4bab2d22aa4dc855b13780237831d1f4.jar:/u/ac/skenny/.globus/coasters/cache/commons-discovery-aa23ab2153fa50054bbcb90ee0998f12.jar:/u/ac/skenny/.globus/coasters/cache/commons-beanutils-79f469610dc0f76ee27a74f198858b6b.jar:/u/ac/skenny/.globus/coasters/cache/commons-logging-1.1-6b62417e77b000a87de66ee3935edbf5.jar:/u/ac/skenny/.globus/coasters/cache/wsrf_provider_jce-26b6c4a3ff9dbb20da06c6edf08d81dd.jar:/u/ac/skenny/.globus/coasters/cache/wsrf_tools-bf5eefba04b771ca0f3f86658f41dd16.jar:/u/ac/skenny/.globus/coasters/cache/wsrf_mds_usefulrp_schema_stubs-bb816638335d3b778a80a7c3bf4f1155.jar:/u/ac/skenny/.globus/coasters/cache/wsrf_core_stubs-36753e26d96200d41c4ca1c3b94be263.jar:/u/ac/skenny/.globus/coasters/cache/wsrf_core-f8aa4c6044dd67fa7c4e4fef6736d267.jar:/u/ac/skenny/.globus/coasters/cache/wsrf_mds_index_stubs-3e8023cbe61cb5c7bf43e507130d9c71.jar:/u/ac/skenny/.globus/coasters/cache/xalan-d4b8027f3c933e3ddea6051044245f1d.jar:/u/ac/skenny/.globus/coasters/cache/xercesImpl-53c0a04a4efb20d52249155a312e91d9.jar:/u/ac/skenny/.globus/coasters/cache/xml-apis-2651f9f7c39e3524f3e2c394625ac63a.jar:/u/ac/skenny/.globus/coasters/cache/xmlsec-1ec887e091632e020866d47438312808.jar, org.globus.cog.abstraction.coaster.service.CoasterService, https://128.135.92.82:50000, 0522149171] 2010-08-13 12:51:29,423 0522149171 Starting stdout consumer 2010-08-13 12:51:29,425 0522149171 Starting stderr consumer 2010-08-13 12:51:29,770-0500 INFO CoasterService Started local service: http://141.142.68.180:46822 2010-08-13 12:51:29,771-0500 INFO CoasterService Reserving channel for registration 2010-08-13 12:51:29,774-0500 WARN RemoteConfiguration Invalid line in remote.properties: -- Resource not found -- 2010-08-13 12:51:29,775-0500 INFO BlockQueueProcessor allocsize = 0.0, queuedsize = 0.0, qsz = 0 2010-08-13 12:51:29,775-0500 INFO BlockQueueProcessor Plan time: 1 2010-08-13 12:51:29,781-0500 WARN RemoteConfiguration Find: https://128.135.92.82:50000 2010-08-13 12:51:29,781-0500 WARN RemoteConfiguration Find: KEEPALIVE, RECONNECT(8), HEARTBEAT(300) - https://128.135.92.82:50000 2010-08-13 12:51:30,935-0500 INFO CoasterService Sending registration 2010-08-13 12:51:31,038-0500 INFO CoasterService Registration complete 2010-08-13 12:51:31,039-0500 INFO CoasterService Started coaster service: https://141.142.68.180:46904 2010-08-13 12:51:31,138-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866539-1281721891130-1281721891131) to coaster queue 2010-08-13 12:51:31,144-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866541-1281721891133-1281721891134) to coaster queue 2010-08-13 12:51:31,147-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866544-1281721891136-1281721891137) to coaster queue 2010-08-13 12:51:31,151-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866548-1281721891139-1281721891140) to coaster queue 2010-08-13 12:51:31,155-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866546-1281721891142-1281721891143) to coaster queue 2010-08-13 12:51:31,158-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866552-1281721891145-1281721891146) to coaster queue 2010-08-13 12:51:31,161-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866550-1281721891148-1281721891149) to coaster queue 2010-08-13 12:51:31,164-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866554-1281721891151-1281721891152) to coaster queue 2010-08-13 12:51:31,170-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866558-1281721891154-1281721891155) to coaster queue 2010-08-13 12:51:31,173-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866557-1281721891157-1281721891158) to coaster queue 2010-08-13 12:51:31,177-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866561-1281721891160-1281721891161) to coaster queue 2010-08-13 12:51:31,180-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866564-1281721891163-1281721891164) to coaster queue 2010-08-13 12:51:31,183-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866562-1281721891166-1281721891167) to coaster queue 2010-08-13 12:51:31,187-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866569-1281721891169-1281721891170) to coaster queue 2010-08-13 12:51:31,190-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866567-1281721891172-1281721891173) to coaster queue 2010-08-13 12:51:31,194-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866571-1281721891175-1281721891176) to coaster queue 2010-08-13 12:51:31,197-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866575-1281721891178-1281721891179) to coaster queue 2010-08-13 12:51:31,200-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866573-1281721891181-1281721891182) to coaster queue 2010-08-13 12:51:31,203-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866577-1281721891184-1281721891185) to coaster queue 2010-08-13 12:51:31,206-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866581-1281721891187-1281721891188) to coaster queue 2010-08-13 12:51:31,209-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866579-1281721891190-1281721891191) to coaster queue 2010-08-13 12:51:31,212-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866585-1281721891193-1281721891194) to coaster queue 2010-08-13 12:51:31,218-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866583-1281721891196-1281721891197) to coaster queue 2010-08-13 12:51:31,221-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866587-1281721891199-1281721891200) to coaster queue 2010-08-13 12:51:31,224-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866589-1281721891202-1281721891203) to coaster queue 2010-08-13 12:51:31,226-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866591-1281721891205-1281721891206) to coaster queue 2010-08-13 12:51:31,229-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866593-1281721891208-1281721891209) to coaster queue 2010-08-13 12:51:31,231-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866595-1281721891211-1281721891212) to coaster queue 2010-08-13 12:51:31,233-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866597-1281721891214-1281721891215) to coaster queue 2010-08-13 12:51:31,235-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866599-1281721891217-1281721891218) to coaster queue 2010-08-13 12:51:31,237-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866601-1281721891220-1281721891221) to coaster queue 2010-08-13 12:51:31,240-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866603-1281721891223-1281721891224) to coaster queue 2010-08-13 12:51:31,244-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866605-1281721891226-1281721891227) to coaster queue 2010-08-13 12:51:31,247-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866607-1281721891229-1281721891230) to coaster queue 2010-08-13 12:51:31,249-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866611-1281721891232-1281721891233) to coaster queue 2010-08-13 12:51:31,251-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866540-1281721891235-1281721891236) to coaster queue 2010-08-13 12:51:31,253-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866613-1281721891238-1281721891239) to coaster queue 2010-08-13 12:51:31,255-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866615-1281721891241-1281721891242) to coaster queue 2010-08-13 12:51:31,257-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866617-1281721891244-1281721891245) to coaster queue 2010-08-13 12:51:31,259-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866619-1281721891247-1281721891248) to coaster queue 2010-08-13 12:51:31,263-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866621-1281721891250-1281721891251) to coaster queue 2010-08-13 12:51:31,265-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866623-1281721891253-1281721891254) to coaster queue 2010-08-13 12:51:31,267-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866625-1281721891256-1281721891257) to coaster queue 2010-08-13 12:51:31,269-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866627-1281721891259-1281721891260) to coaster queue 2010-08-13 12:51:31,271-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866629-1281721891262-1281721891263) to coaster queue 2010-08-13 12:51:31,273-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866631-1281721891265-1281721891266) to coaster queue 2010-08-13 12:51:31,275-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866633-1281721891268-1281721891269) to coaster queue 2010-08-13 12:51:31,277-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866635-1281721891271-1281721891272) to coaster queue 2010-08-13 12:51:31,278-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866637-1281721891274-1281721891275) to coaster queue 2010-08-13 12:51:31,280-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866639-1281721891277-1281721891278) to coaster queue 2010-08-13 12:51:31,281-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866641-1281721891280-1281721891281) to coaster queue 2010-08-13 12:51:31,283-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866643-1281721891283-1281721891284) to coaster queue 2010-08-13 12:51:31,285-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866645-1281721891286-1281721891287) to coaster queue 2010-08-13 12:51:31,287-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866647-1281721891289-1281721891290) to coaster queue 2010-08-13 12:51:31,289-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866649-1281721891292-1281721891293) to coaster queue 2010-08-13 12:51:31,290-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866651-1281721891295-1281721891296) to coaster queue 2010-08-13 12:51:31,292-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866653-1281721891298-1281721891299) to coaster queue 2010-08-13 12:51:31,293-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866655-1281721891301-1281721891302) to coaster queue 2010-08-13 12:51:31,294-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866657-1281721891304-1281721891305) to coaster queue 2010-08-13 12:51:31,295-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866659-1281721891307-1281721891308) to coaster queue 2010-08-13 12:51:31,297-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866661-1281721891310-1281721891311) to coaster queue 2010-08-13 12:51:31,298-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866663-1281721891313-1281721891314) to coaster queue 2010-08-13 12:51:31,299-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866665-1281721891316-1281721891317) to coaster queue 2010-08-13 12:51:31,300-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866669-1281721891319-1281721891320) to coaster queue 2010-08-13 12:51:31,301-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866667-1281721891322-1281721891323) to coaster queue 2010-08-13 12:51:31,302-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866673-1281721891325-1281721891326) to coaster queue 2010-08-13 12:51:31,306-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866671-1281721891328-1281721891329) to coaster queue 2010-08-13 12:51:31,307-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866675-1281721891331-1281721891332) to coaster queue 2010-08-13 12:51:31,308-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866679-1281721891334-1281721891335) to coaster queue 2010-08-13 12:51:31,310-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866677-1281721891337-1281721891338) to coaster queue 2010-08-13 12:51:31,311-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866681-1281721891340-1281721891341) to coaster queue 2010-08-13 12:51:31,312-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866685-1281721891343-1281721891344) to coaster queue 2010-08-13 12:51:31,313-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866683-1281721891346-1281721891347) to coaster queue 2010-08-13 12:51:31,315-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866686-1281721891349-1281721891350) to coaster queue 2010-08-13 12:51:31,316-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866691-1281721891352-1281721891353) to coaster queue 2010-08-13 12:51:31,317-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866693-1281721891355-1281721891356) to coaster queue 2010-08-13 12:51:31,318-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866695-1281721891358-1281721891359) to coaster queue 2010-08-13 12:51:31,319-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866697-1281721891361-1281721891362) to coaster queue 2010-08-13 12:51:31,321-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866609-1281721891364-1281721891365) to coaster queue 2010-08-13 12:51:31,322-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866699-1281721891367-1281721891368) to coaster queue 2010-08-13 12:51:31,323-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866701-1281721891370-1281721891371) to coaster queue 2010-08-13 12:51:31,324-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866705-1281721891373-1281721891374) to coaster queue 2010-08-13 12:51:31,325-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866703-1281721891376-1281721891377) to coaster queue 2010-08-13 12:51:31,326-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866706-1281721891379-1281721891380) to coaster queue 2010-08-13 12:51:31,328-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866709-1281721891382-1281721891383) to coaster queue 2010-08-13 12:51:31,330-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866710-1281721891385-1281721891386) to coaster queue 2010-08-13 12:51:31,331-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866713-1281721891388-1281721891389) to coaster queue 2010-08-13 12:51:31,332-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866714-1281721891391-1281721891392) to coaster queue 2010-08-13 12:51:31,334-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866717-1281721891394-1281721891395) to coaster queue 2010-08-13 12:51:31,335-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866718-1281721891397-1281721891398) to coaster queue 2010-08-13 12:51:31,336-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866722-1281721891400-1281721891401) to coaster queue 2010-08-13 12:51:31,337-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866725-1281721891403-1281721891404) to coaster queue 2010-08-13 12:51:31,338-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866726-1281721891406-1281721891407) to coaster queue 2010-08-13 12:51:31,339-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866721-1281721891409-1281721891410) to coaster queue 2010-08-13 12:51:31,341-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866729-1281721891412-1281721891413) to coaster queue 2010-08-13 12:51:31,342-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866730-1281721891415-1281721891416) to coaster queue 2010-08-13 12:51:31,343-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866733-1281721891418-1281721891419) to coaster queue 2010-08-13 12:51:31,356-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866734-1281721891421-1281721891422) to coaster queue 2010-08-13 12:51:31,357-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866737-1281721891424-1281721891425) to coaster queue 2010-08-13 12:51:31,358-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866740-1281721891427-1281721891428) to coaster queue 2010-08-13 12:51:31,360-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866742-1281721891430-1281721891431) to coaster queue 2010-08-13 12:51:31,361-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866744-1281721891433-1281721891434) to coaster queue 2010-08-13 12:51:31,362-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866746-1281721891436-1281721891437) to coaster queue 2010-08-13 12:51:31,363-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866748-1281721891439-1281721891440) to coaster queue 2010-08-13 12:51:31,364-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866750-1281721891442-1281721891443) to coaster queue 2010-08-13 12:51:31,366-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866752-1281721891445-1281721891446) to coaster queue 2010-08-13 12:51:31,367-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866688-1281721891448-1281721891449) to coaster queue 2010-08-13 12:51:31,368-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866758-1281721891451-1281721891452) to coaster queue 2010-08-13 12:51:31,369-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866756-1281721891454-1281721891455) to coaster queue 2010-08-13 12:51:31,370-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866760-1281721891457-1281721891458) to coaster queue 2010-08-13 12:51:31,372-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866762-1281721891460-1281721891461) to coaster queue 2010-08-13 12:51:31,373-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866766-1281721891463-1281721891464) to coaster queue 2010-08-13 12:51:31,374-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866764-1281721891466-1281721891467) to coaster queue 2010-08-13 12:51:31,376-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866768-1281721891469-1281721891470) to coaster queue 2010-08-13 12:51:31,377-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866770-1281721891472-1281721891473) to coaster queue 2010-08-13 12:51:31,378-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866774-1281721891475-1281721891476) to coaster queue 2010-08-13 12:51:31,379-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866772-1281721891478-1281721891479) to coaster queue 2010-08-13 12:51:31,381-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866776-1281721891481-1281721891482) to coaster queue 2010-08-13 12:51:31,383-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866780-1281721891484-1281721891485) to coaster queue 2010-08-13 12:51:31,384-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866778-1281721891487-1281721891488) to coaster queue 2010-08-13 12:51:31,385-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866782-1281721891490-1281721891491) to coaster queue 2010-08-13 12:51:31,386-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866784-1281721891493-1281721891494) to coaster queue 2010-08-13 12:51:31,387-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866788-1281721891496-1281721891497) to coaster queue 2010-08-13 12:51:31,388-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866790-1281721891499-1281721891500) to coaster queue 2010-08-13 12:51:31,390-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866786-1281721891502-1281721891503) to coaster queue 2010-08-13 12:51:31,391-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866792-1281721891505-1281721891506) to coaster queue 2010-08-13 12:51:31,392-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866754-1281721891508-1281721891509) to coaster queue 2010-08-13 12:51:31,394-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866738-1281721891511-1281721891512) to coaster queue 2010-08-13 12:51:31,395-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866542-1281721891514-1281721891515) to coaster queue 2010-08-13 12:51:31,978-0500 INFO BlockQueueProcessor allocsize = 0.0, queuedsize = 139.04085680652847, qsz = 129 2010-08-13 12:51:31,980-0500 INFO BlockQueueProcessor Requeued 129 non-fitting jobs 2010-08-13 12:51:31,993-0500 INFO BlockQueueProcessor Settings { slots = 10 workersPerNode = 1 nodeGranularity = 16 allocationStepSize = 0.1 maxNodes = 32 lowOverallocation = 10.0 highOverallocation = 1.0 overallocationDecayFactor = 0.0010 spread = 0.9 reserve = 60.000s maxtime = 2147483647 project = TG-DBS080004N queue = normal remoteMonitorEnabled = false kernelprofile = null alcfbgpnat = false internalHostname = 141.142.68.180 name = configtester } 2010-08-13 12:51:31,993-0500 INFO BlockQueueProcessor Required size: 129 for 129 jobs 2010-08-13 12:51:31,994-0500 INFO BlockQueueProcessor h: 5400, jj: 1800, x-last: , r: 0, sumsz: 128 2010-08-13 12:51:31,994-0500 INFO BlockQueueProcessor h: 5400, w: 32, size: 129.0, msz: 129, w*h: 172800 2010-08-13 12:51:31,998-0500 INFO BlockQueueProcessor Added: 0 - 33 2010-08-13 12:51:31,998-0500 INFO Block Starting block: workers=32, walltime=5400.000s 2010-08-13 12:51:32,002-0500 INFO BlockTaskSubmitter Queuing block Block 0813-511229-000000 (32x5400.000s) for submission 2010-08-13 12:51:32,002-0500 INFO BlockQueueProcessor Added 34 jobs to new blocks 2010-08-13 12:51:32,002-0500 INFO BlockQueueProcessor Plan time: 24 2010-08-13 12:51:32,002-0500 INFO BlockTaskSubmitter Submitting block Block 0813-511229-000000 (32x5400.000s) 2010-08-13 12:51:32,010-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891516) setting status to Submitting 2010-08-13 12:51:32,011-0500 INFO Block Block task status changed: Submitting 2010-08-13 12:51:32,533-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891516) setting status to Submitted 2010-08-13 12:51:32,533-0500 INFO Block Block task status changed: Submitted 2010-08-13 12:51:32,684-0500 INFO BlockQueueProcessor Updated allocsize: 34.87176392395544 2010-08-13 12:51:32,685-0500 INFO BlockQueueProcessor allocsize = 34.87176392395544, queuedsize = 36.64642737536411, qsz = 34 2010-08-13 12:51:32,685-0500 INFO BlockQueueProcessor Requeued 2 non-fitting jobs 2010-08-13 12:51:32,686-0500 INFO BlockQueueProcessor Required size: 97 for 97 jobs 2010-08-13 12:51:32,686-0500 INFO BlockQueueProcessor h: 5400, jj: 1800, x-last: , r: 0, sumsz: 96 2010-08-13 12:51:32,686-0500 INFO BlockQueueProcessor h: 5400, w: 32, size: 97.0, msz: 97, w*h: 172800 2010-08-13 12:51:32,686-0500 INFO BlockQueueProcessor Added: 0 - 33 2010-08-13 12:51:32,686-0500 INFO Block Starting block: workers=32, walltime=5400.000s 2010-08-13 12:51:32,687-0500 INFO BlockTaskSubmitter Queuing block Block 0813-511229-000001 (32x5400.000s) for submission 2010-08-13 12:51:32,687-0500 INFO BlockQueueProcessor Added 34 jobs to new blocks 2010-08-13 12:51:32,687-0500 INFO BlockQueueProcessor Plan time: 3 2010-08-13 12:51:32,690-0500 INFO BlockTaskSubmitter Submitting block Block 0813-511229-000001 (32x5400.000s) 2010-08-13 12:51:32,690-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891517) setting status to Submitting 2010-08-13 12:51:32,690-0500 INFO Block Block task status changed: Submitting 2010-08-13 12:51:32,951-0500 INFO BlockQueueProcessor Updated allocsize: 69.74352784791088 2010-08-13 12:51:32,951-0500 INFO BlockQueueProcessor allocsize = 69.74352784791088, queuedsize = 71.13718255217742, qsz = 66 2010-08-13 12:51:32,951-0500 INFO BlockQueueProcessor Requeued 2 non-fitting jobs 2010-08-13 12:51:32,952-0500 INFO BlockQueueProcessor Required size: 65 for 65 jobs 2010-08-13 12:51:32,952-0500 INFO BlockQueueProcessor h: 5400, jj: 1800, x-last: , r: 0, sumsz: 64 2010-08-13 12:51:32,952-0500 INFO BlockQueueProcessor h: 5400, w: 32, size: 65.0, msz: 65, w*h: 172800 2010-08-13 12:51:32,952-0500 INFO BlockQueueProcessor Added: 0 - 33 2010-08-13 12:51:32,952-0500 INFO Block Starting block: workers=32, walltime=5400.000s 2010-08-13 12:51:32,953-0500 INFO BlockTaskSubmitter Queuing block Block 0813-511229-000002 (32x5400.000s) for submission 2010-08-13 12:51:32,953-0500 INFO BlockQueueProcessor Added 34 jobs to new blocks 2010-08-13 12:51:32,953-0500 INFO BlockQueueProcessor Plan time: 2 2010-08-13 12:51:33,195-0500 INFO BlockQueueProcessor Updated allocsize: 104.61529177186632 2010-08-13 12:51:33,195-0500 INFO BlockQueueProcessor allocsize = 104.61529177186632, queuedsize = 105.62793772899073, qsz = 98 2010-08-13 12:51:33,196-0500 INFO BlockQueueProcessor Requeued 1 non-fitting jobs 2010-08-13 12:51:33,196-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891517) setting status to Submitted 2010-08-13 12:51:33,196-0500 INFO BlockQueueProcessor Required size: 32 for 32 jobs 2010-08-13 12:51:33,196-0500 INFO Block Block task status changed: Submitted 2010-08-13 12:51:33,196-0500 INFO BlockQueueProcessor h: 5400, jj: 1800, x-last: , r: 31, sumsz: 31 2010-08-13 12:51:33,196-0500 INFO BlockTaskSubmitter Submitting block Block 0813-511229-000002 (32x5400.000s) 2010-08-13 12:51:33,196-0500 INFO BlockQueueProcessor h: 7200, w: 32, size: 32.0, msz: 32, w*h: 230400 2010-08-13 12:51:33,196-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891518) setting status to Submitting 2010-08-13 12:51:33,197-0500 INFO Block Block task status changed: Submitting 2010-08-13 12:51:33,197-0500 INFO BlockQueueProcessor Added: 0 - 31 2010-08-13 12:51:33,197-0500 INFO Block Starting block: workers=32, walltime=7200.000s 2010-08-13 12:51:33,197-0500 INFO BlockTaskSubmitter Queuing block Block 0813-511229-000003 (32x7200.000s) for submission 2010-08-13 12:51:33,197-0500 INFO BlockQueueProcessor Added 32 jobs to new blocks 2010-08-13 12:51:33,197-0500 INFO BlockQueueProcessor Plan time: 2 2010-08-13 12:51:33,656-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891518) setting status to Submitted 2010-08-13 12:51:33,656-0500 INFO Block Block task status changed: Submitted 2010-08-13 12:51:33,656-0500 INFO BlockTaskSubmitter Submitting block Block 0813-511229-000003 (32x7200.000s) 2010-08-13 12:51:33,657-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891519) setting status to Submitting 2010-08-13 12:51:33,657-0500 INFO Block Block task status changed: Submitting 2010-08-13 12:51:34,115-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891519) setting status to Submitted 2010-08-13 12:51:34,116-0500 INFO Block Block task status changed: Submitted 2010-08-13 12:51:35,400-0500 INFO BlockQueueProcessor Updated allocsize: 139.58751994851315 2010-08-13 12:51:35,400-0500 INFO BlockQueueProcessor allocsize = 139.58751994851315, queuedsize = 139.04085680652847, qsz = 129 2010-08-13 12:51:35,400-0500 INFO BlockQueueProcessor Plan time: 0 2010-08-13 12:51:37,603-0500 INFO BlockQueueProcessor allocsize = 139.58751994851315, queuedsize = 139.04085680652847, qsz = 129 2010-08-13 12:51:37,603-0500 INFO BlockQueueProcessor Plan time: 0 2010-08-13 12:51:39,806-0500 INFO BlockQueueProcessor allocsize = 139.58751994851315, queuedsize = 139.04085680652847, qsz = 129 2010-08-13 12:51:39,806-0500 INFO BlockQueueProcessor Plan time: 0 2010-08-13 12:51:41,042-0500 INFO CoasterService Idle time: 0 2010-08-13 12:51:41,140-0500 INFO TaskNotifier Congestion queue size: 0 2010-08-13 12:51:42,008-0500 INFO BlockQueueProcessor allocsize = 139.58751994851315, queuedsize = 139.04085680652847, qsz = 129 2010-08-13 12:51:42,009-0500 INFO BlockQueueProcessor Plan time: 1 2010-08-13 12:51:43,350-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891516) setting status to Active 2010-08-13 12:51:43,350-0500 INFO Block Block task status changed: Active 2010-08-13 12:51:44,211-0500 INFO BlockQueueProcessor Updated allocsize: 139.58745536525223 2010-08-13 12:51:44,212-0500 INFO BlockQueueProcessor allocsize = 139.58745536525223, queuedsize = 139.04085680652847, qsz = 129 2010-08-13 12:51:44,212-0500 INFO BlockQueueProcessor Plan time: 1 2010-08-13 12:51:46,414-0500 INFO BlockQueueProcessor Updated allocsize: 139.58726154439074 2010-08-13 12:51:46,415-0500 INFO BlockQueueProcessor allocsize = 139.58726154439074, queuedsize = 139.04085680652847, qsz = 129 2010-08-13 12:51:46,415-0500 INFO BlockQueueProcessor Plan time: 1 2010-08-13 12:51:48,617-0500 INFO BlockQueueProcessor Updated allocsize: 139.5871322712071 2010-08-13 12:51:48,617-0500 INFO BlockQueueProcessor allocsize = 139.5871322712071, queuedsize = 139.04085680652847, qsz = 129 2010-08-13 12:51:48,617-0500 INFO BlockQueueProcessor Plan time: 0 2010-08-13 12:51:50,819-0500 INFO BlockQueueProcessor Updated allocsize: 139.5870029505619 2010-08-13 12:51:50,820-0500 INFO BlockQueueProcessor allocsize = 139.5870029505619, queuedsize = 139.04085680652847, qsz = 129 2010-08-13 12:51:50,820-0500 INFO BlockQueueProcessor Plan time: 1 2010-08-13 12:51:51,044-0500 INFO CoasterService Idle time: 0 2010-08-13 12:51:51,143-0500 INFO TaskNotifier Congestion queue size: 0 2010-08-13 12:51:53,021-0500 INFO BlockQueueProcessor Updated allocsize: 139.58687358242003 2010-08-13 12:51:53,022-0500 INFO BlockQueueProcessor allocsize = 139.58687358242003, queuedsize = 139.04085680652847, qsz = 129 2010-08-13 12:51:53,022-0500 INFO BlockQueueProcessor Plan time: 1 2010-08-13 12:51:55,224-0500 INFO BlockQueueProcessor Updated allocsize: 139.58674416674643 2010-08-13 12:51:55,224-0500 INFO BlockQueueProcessor allocsize = 139.58674416674643, queuedsize = 139.04085680652847, qsz = 129 2010-08-13 12:51:55,224-0500 INFO BlockQueueProcessor Plan time: 0 2010-08-13 12:51:57,426-0500 INFO BlockQueueProcessor Updated allocsize: 139.58654995403722 2010-08-13 12:51:57,427-0500 INFO BlockQueueProcessor allocsize = 139.58654995403722, queuedsize = 139.04085680652847, qsz = 129 2010-08-13 12:51:57,427-0500 INFO BlockQueueProcessor Plan time: 1 2010-08-13 12:51:59,628-0500 INFO BlockQueueProcessor Updated allocsize: 139.5864204193806 2010-08-13 12:51:59,629-0500 INFO BlockQueueProcessor allocsize = 139.5864204193806, queuedsize = 139.04085680652847, qsz = 129 2010-08-13 12:51:59,629-0500 INFO BlockQueueProcessor Plan time: 1 2010-08-13 12:52:01,048-0500 INFO CoasterService Idle time: 0 2010-08-13 12:52:01,145-0500 INFO TaskNotifier Congestion queue size: 0 2010-08-13 12:52:01,834-0500 INFO BlockQueueProcessor Updated allocsize: 139.58629083706919 2010-08-13 12:52:01,834-0500 INFO BlockQueueProcessor allocsize = 139.58629083706919, queuedsize = 139.04085680652847, qsz = 129 2010-08-13 12:52:01,834-0500 INFO BlockQueueProcessor Plan time: 0 2010-08-13 12:52:04,037-0500 INFO BlockQueueProcessor Updated allocsize: 139.58616120706768 2010-08-13 12:52:04,037-0500 INFO BlockQueueProcessor allocsize = 139.58616120706768, queuedsize = 139.04085680652847, qsz = 129 2010-08-13 12:52:04,037-0500 INFO BlockQueueProcessor Plan time: 0 2010-08-13 12:52:06,241-0500 INFO BlockQueueProcessor Updated allocsize: 139.58603152934086 2010-08-13 12:52:06,241-0500 INFO BlockQueueProcessor allocsize = 139.58603152934086, queuedsize = 139.04085680652847, qsz = 129 2010-08-13 12:52:06,241-0500 INFO BlockQueueProcessor Plan time: 0 2010-08-13 12:52:08,443-0500 INFO BlockQueueProcessor Updated allocsize: 139.58583692318828 2010-08-13 12:52:08,443-0500 INFO BlockQueueProcessor allocsize = 139.58583692318828, queuedsize = 139.04085680652847, qsz = 129 2010-08-13 12:52:08,443-0500 INFO BlockQueueProcessor Plan time: 0 2010-08-13 12:52:10,646-0500 INFO BlockQueueProcessor Updated allocsize: 139.5857071259934 2010-08-13 12:52:10,646-0500 INFO BlockQueueProcessor allocsize = 139.5857071259934, queuedsize = 139.04085680652847, qsz = 129 2010-08-13 12:52:10,646-0500 INFO BlockQueueProcessor Plan time: 0 2010-08-13 12:52:11,051-0500 INFO CoasterService Idle time: 0 2010-08-13 12:52:11,149-0500 INFO TaskNotifier Congestion queue size: 0 2010-08-13 12:52:12,849-0500 INFO BlockQueueProcessor Updated allocsize: 139.58557728094934 2010-08-13 12:52:12,849-0500 INFO BlockQueueProcessor allocsize = 139.58557728094934, queuedsize = 139.04085680652847, qsz = 129 2010-08-13 12:52:12,849-0500 INFO BlockQueueProcessor Plan time: 0 2010-08-13 12:52:14,470-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:14,474-0500 INFO Block Started worker 0813-511229-000000:000000 2010-08-13 12:52:14,474-0500 INFO Cpu 0813-511229-000000:0 pull 2010-08-13 12:52:14,474-0500 INFO Cpu 0813-511229-000000:0 submitting urn:1281721866552-1281721891145-1281721891146 2010-08-13 12:52:14,474-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866552-1281721891145-1281721891146) setting status to Submitting 2010-08-13 12:52:14,585-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:14,586-0500 INFO Block Started worker 0813-511229-000000:000001 2010-08-13 12:52:14,586-0500 INFO Cpu 0813-511229-000000:1 pull 2010-08-13 12:52:14,586-0500 INFO Cpu 0813-511229-000000:1 submitting urn:1281721866550-1281721891148-1281721891149 2010-08-13 12:52:14,586-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866550-1281721891148-1281721891149) setting status to Submitting 2010-08-13 12:52:14,653-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:14,653-0500 INFO Block Started worker 0813-511229-000000:000002 2010-08-13 12:52:14,653-0500 INFO Cpu 0813-511229-000000:2 pull 2010-08-13 12:52:14,653-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:14,653-0500 INFO Cpu 0813-511229-000000:2 submitting urn:1281721866554-1281721891151-1281721891152 2010-08-13 12:52:14,653-0500 INFO Block Started worker 0813-511229-000000:000003 2010-08-13 12:52:14,654-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866554-1281721891151-1281721891152) setting status to Submitting 2010-08-13 12:52:14,654-0500 INFO Cpu 0813-511229-000000:3 pull 2010-08-13 12:52:14,654-0500 INFO Cpu 0813-511229-000000:3 submitting urn:1281721866558-1281721891154-1281721891155 2010-08-13 12:52:14,654-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866558-1281721891154-1281721891155) setting status to Submitting 2010-08-13 12:52:14,698-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:14,699-0500 INFO Block Started worker 0813-511229-000000:000004 2010-08-13 12:52:14,699-0500 INFO Cpu 0813-511229-000000:4 pull 2010-08-13 12:52:14,699-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:14,699-0500 INFO Cpu 0813-511229-000000:4 submitting urn:1281721866557-1281721891157-1281721891158 2010-08-13 12:52:14,699-0500 INFO Block Started worker 0813-511229-000000:000005 2010-08-13 12:52:14,699-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866557-1281721891157-1281721891158) setting status to Submitting 2010-08-13 12:52:14,699-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:14,700-0500 INFO Block Started worker 0813-511229-000000:000006 2010-08-13 12:52:14,700-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:14,700-0500 INFO Block Started worker 0813-511229-000000:000007 2010-08-13 12:52:14,700-0500 INFO Cpu 0813-511229-000000:5 pull 2010-08-13 12:52:14,700-0500 INFO Cpu 0813-511229-000000:5 submitting urn:1281721866561-1281721891160-1281721891161 2010-08-13 12:52:14,700-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866561-1281721891160-1281721891161) setting status to Submitting 2010-08-13 12:52:14,701-0500 INFO Cpu 0813-511229-000000:6 pull 2010-08-13 12:52:14,701-0500 INFO Cpu 0813-511229-000000:6 submitting urn:1281721866564-1281721891163-1281721891164 2010-08-13 12:52:14,701-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866564-1281721891163-1281721891164) setting status to Submitting 2010-08-13 12:52:14,702-0500 INFO Cpu 0813-511229-000000:7 pull 2010-08-13 12:52:14,702-0500 INFO Cpu 0813-511229-000000:7 submitting urn:1281721866562-1281721891166-1281721891167 2010-08-13 12:52:14,702-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866562-1281721891166-1281721891167) setting status to Submitting 2010-08-13 12:52:14,722-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866552-1281721891145-1281721891146) setting status to Submitted 2010-08-13 12:52:14,744-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866552-1281721891145-1281721891146) setting status to Active workerid=000000 2010-08-13 12:52:14,834-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:14,834-0500 INFO Block Started worker 0813-511229-000000:000008 2010-08-13 12:52:14,834-0500 INFO Cpu 0813-511229-000000:8 pull 2010-08-13 12:52:14,835-0500 INFO Cpu 0813-511229-000000:8 submitting urn:1281721866569-1281721891169-1281721891170 2010-08-13 12:52:14,835-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:14,835-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866569-1281721891169-1281721891170) setting status to Submitting 2010-08-13 12:52:14,835-0500 INFO Block Started worker 0813-511229-000000:000009 2010-08-13 12:52:14,835-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:14,835-0500 INFO Block Started worker 0813-511229-000000:000010 2010-08-13 12:52:14,836-0500 INFO Cpu 0813-511229-000000:9 pull 2010-08-13 12:52:14,836-0500 INFO Cpu 0813-511229-000000:9 submitting urn:1281721866567-1281721891172-1281721891173 2010-08-13 12:52:14,836-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866567-1281721891172-1281721891173) setting status to Submitting 2010-08-13 12:52:14,836-0500 INFO Cpu 0813-511229-000000:10 pull 2010-08-13 12:52:14,837-0500 INFO Cpu 0813-511229-000000:10 submitting urn:1281721866571-1281721891175-1281721891176 2010-08-13 12:52:14,837-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866571-1281721891175-1281721891176) setting status to Submitting 2010-08-13 12:52:14,859-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866550-1281721891148-1281721891149) setting status to Submitted 2010-08-13 12:52:14,882-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:14,882-0500 INFO Block Started worker 0813-511229-000000:000011 2010-08-13 12:52:14,882-0500 INFO Cpu 0813-511229-000000:11 pull 2010-08-13 12:52:14,882-0500 INFO Cpu 0813-511229-000000:11 submitting urn:1281721866575-1281721891178-1281721891179 2010-08-13 12:52:14,882-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:14,883-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866575-1281721891178-1281721891179) setting status to Submitting 2010-08-13 12:52:14,883-0500 INFO Block Started worker 0813-511229-000000:000012 2010-08-13 12:52:14,883-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:14,883-0500 INFO Block Started worker 0813-511229-000000:000013 2010-08-13 12:52:14,884-0500 INFO Cpu 0813-511229-000000:12 pull 2010-08-13 12:52:14,884-0500 INFO Cpu 0813-511229-000000:12 submitting urn:1281721866573-1281721891181-1281721891182 2010-08-13 12:52:14,884-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866573-1281721891181-1281721891182) setting status to Submitting 2010-08-13 12:52:14,884-0500 INFO Cpu 0813-511229-000000:13 pull 2010-08-13 12:52:14,885-0500 INFO Cpu 0813-511229-000000:13 submitting urn:1281721866577-1281721891184-1281721891185 2010-08-13 12:52:14,885-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866577-1281721891184-1281721891185) setting status to Submitting 2010-08-13 12:52:14,906-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866554-1281721891151-1281721891152) setting status to Submitted 2010-08-13 12:52:14,906-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866558-1281721891154-1281721891155) setting status to Submitted 2010-08-13 12:52:14,906-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866550-1281721891148-1281721891149) setting status to Active workerid=000001 2010-08-13 12:52:14,928-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866557-1281721891157-1281721891158) setting status to Submitted 2010-08-13 12:52:14,929-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866561-1281721891160-1281721891161) setting status to Submitted 2010-08-13 12:52:14,952-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866564-1281721891163-1281721891164) setting status to Submitted 2010-08-13 12:52:14,953-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:14,953-0500 INFO Block Started worker 0813-511229-000000:000014 2010-08-13 12:52:14,953-0500 INFO Cpu 0813-511229-000000:14 pull 2010-08-13 12:52:14,953-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866562-1281721891166-1281721891167) setting status to Submitted 2010-08-13 12:52:14,954-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866554-1281721891151-1281721891152) setting status to Active workerid=000002 2010-08-13 12:52:14,955-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866558-1281721891154-1281721891155) setting status to Active workerid=000003 2010-08-13 12:52:14,953-0500 INFO Cpu 0813-511229-000000:14 submitting urn:1281721866581-1281721891187-1281721891188 2010-08-13 12:52:14,956-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866581-1281721891187-1281721891188) setting status to Submitting 2010-08-13 12:52:14,978-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:14,979-0500 INFO Block Started worker 0813-511229-000000:000015 2010-08-13 12:52:14,979-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866557-1281721891157-1281721891158) setting status to Active workerid=000004 2010-08-13 12:52:14,979-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866561-1281721891160-1281721891161) setting status to Active workerid=000005 2010-08-13 12:52:14,980-0500 INFO Cpu 0813-511229-000000:15 pull 2010-08-13 12:52:14,982-0500 INFO Cpu 0813-511229-000000:15 submitting urn:1281721866579-1281721891190-1281721891191 2010-08-13 12:52:14,982-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866579-1281721891190-1281721891191) setting status to Submitting 2010-08-13 12:52:15,004-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:15,004-0500 INFO Block Started worker 0813-511229-000000:000016 2010-08-13 12:52:15,004-0500 INFO Cpu 0813-511229-000000:16 pull 2010-08-13 12:52:15,004-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866564-1281721891163-1281721891164) setting status to Active workerid=000006 2010-08-13 12:52:15,004-0500 INFO Cpu 0813-511229-000000:16 submitting urn:1281721866585-1281721891193-1281721891194 2010-08-13 12:52:15,004-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866585-1281721891193-1281721891194) setting status to Submitting 2010-08-13 12:52:15,005-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866562-1281721891166-1281721891167) setting status to Active workerid=000007 2010-08-13 12:52:15,051-0500 INFO BlockQueueProcessor Updated allocsize: 139.58544738802064 2010-08-13 12:52:15,052-0500 INFO BlockQueueProcessor allocsize = 139.58544738802064, queuedsize = 120.71764311884654, qsz = 112 2010-08-13 12:52:15,052-0500 INFO BlockQueueProcessor Plan time: 1 2010-08-13 12:52:15,072-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:15,072-0500 INFO Block Started worker 0813-511229-000000:000017 2010-08-13 12:52:15,073-0500 INFO Cpu 0813-511229-000000:17 pull 2010-08-13 12:52:15,073-0500 INFO Cpu 0813-511229-000000:17 submitting urn:1281721866583-1281721891196-1281721891197 2010-08-13 12:52:15,073-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866583-1281721891196-1281721891197) setting status to Submitting 2010-08-13 12:52:15,073-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:15,074-0500 INFO Block Started worker 0813-511229-000000:000018 2010-08-13 12:52:15,074-0500 INFO Cpu 0813-511229-000000:18 pull 2010-08-13 12:52:15,074-0500 INFO Cpu 0813-511229-000000:18 submitting urn:1281721866587-1281721891199-1281721891200 2010-08-13 12:52:15,074-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866587-1281721891199-1281721891200) setting status to Submitting 2010-08-13 12:52:15,100-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866569-1281721891169-1281721891170) setting status to Submitted 2010-08-13 12:52:15,143-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866571-1281721891175-1281721891176) setting status to Submitted 2010-08-13 12:52:15,143-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:15,143-0500 INFO Block Started worker 0813-511229-000000:000019 2010-08-13 12:52:15,143-0500 INFO Cpu 0813-511229-000000:19 pull 2010-08-13 12:52:15,144-0500 INFO Cpu 0813-511229-000000:19 submitting urn:1281721866589-1281721891202-1281721891203 2010-08-13 12:52:15,144-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866569-1281721891169-1281721891170) setting status to Active workerid=000008 2010-08-13 12:52:15,144-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866589-1281721891202-1281721891203) setting status to Submitting 2010-08-13 12:52:15,189-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:15,190-0500 INFO Block Started worker 0813-511229-000000:000020 2010-08-13 12:52:15,190-0500 INFO Cpu 0813-511229-000000:20 pull 2010-08-13 12:52:15,190-0500 INFO Cpu 0813-511229-000000:20 submitting urn:1281721866591-1281721891205-1281721891206 2010-08-13 12:52:15,190-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866591-1281721891205-1281721891206) setting status to Submitting 2010-08-13 12:52:15,190-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866571-1281721891175-1281721891176) setting status to Active workerid=000010 2010-08-13 12:52:15,212-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866567-1281721891172-1281721891173) setting status to Submitted 2010-08-13 12:52:15,212-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:15,212-0500 INFO Block Started worker 0813-511229-000000:000021 2010-08-13 12:52:15,212-0500 INFO Cpu 0813-511229-000000:21 pull 2010-08-13 12:52:15,212-0500 INFO Cpu 0813-511229-000000:21 submitting urn:1281721866593-1281721891208-1281721891209 2010-08-13 12:52:15,212-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866593-1281721891208-1281721891209) setting status to Submitting 2010-08-13 12:52:15,234-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866575-1281721891178-1281721891179) setting status to Submitted 2010-08-13 12:52:15,256-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866573-1281721891181-1281721891182) setting status to Submitted 2010-08-13 12:52:15,256-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:15,256-0500 INFO Block Started worker 0813-511229-000000:000022 2010-08-13 12:52:15,256-0500 INFO Cpu 0813-511229-000000:22 pull 2010-08-13 12:52:15,257-0500 INFO Cpu 0813-511229-000000:22 submitting urn:1281721866595-1281721891211-1281721891212 2010-08-13 12:52:15,257-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:15,257-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866595-1281721891211-1281721891212) setting status to Submitting 2010-08-13 12:52:15,257-0500 INFO Block Started worker 0813-511229-000000:000023 2010-08-13 12:52:15,257-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866567-1281721891172-1281721891173) setting status to Active workerid=000009 2010-08-13 12:52:15,257-0500 INFO Cpu 0813-511229-000000:23 pull 2010-08-13 12:52:15,257-0500 INFO Cpu 0813-511229-000000:23 submitting urn:1281721866597-1281721891214-1281721891215 2010-08-13 12:52:15,258-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866597-1281721891214-1281721891215) setting status to Submitting 2010-08-13 12:52:15,281-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866577-1281721891184-1281721891185) setting status to Submitted 2010-08-13 12:52:15,281-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:15,281-0500 INFO Block Started worker 0813-511229-000000:000024 2010-08-13 12:52:15,282-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:15,282-0500 INFO Block Started worker 0813-511229-000000:000025 2010-08-13 12:52:15,282-0500 INFO Cpu 0813-511229-000000:24 pull 2010-08-13 12:52:15,282-0500 INFO Cpu 0813-511229-000000:24 submitting urn:1281721866599-1281721891217-1281721891218 2010-08-13 12:52:15,282-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866599-1281721891217-1281721891218) setting status to Submitting 2010-08-13 12:52:15,283-0500 INFO Cpu 0813-511229-000000:25 pull 2010-08-13 12:52:15,282-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866575-1281721891178-1281721891179) setting status to Active workerid=000011 2010-08-13 12:52:15,283-0500 INFO Cpu 0813-511229-000000:25 submitting urn:1281721866601-1281721891220-1281721891221 2010-08-13 12:52:15,283-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866601-1281721891220-1281721891221) setting status to Submitting 2010-08-13 12:52:15,306-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866581-1281721891187-1281721891188) setting status to Submitted 2010-08-13 12:52:15,307-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866587-1281721891199-1281721891200) setting status to Submitted 2010-08-13 12:52:15,307-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:15,307-0500 INFO Block Started worker 0813-511229-000000:000026 2010-08-13 12:52:15,307-0500 INFO Cpu 0813-511229-000000:26 pull 2010-08-13 12:52:15,307-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866573-1281721891181-1281721891182) setting status to Active workerid=000012 2010-08-13 12:52:15,307-0500 INFO Cpu 0813-511229-000000:26 submitting urn:1281721866603-1281721891223-1281721891224 2010-08-13 12:52:15,308-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866603-1281721891223-1281721891224) setting status to Submitting 2010-08-13 12:52:15,331-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:15,331-0500 INFO Block Started worker 0813-511229-000000:000027 2010-08-13 12:52:15,331-0500 INFO Cpu 0813-511229-000000:27 pull 2010-08-13 12:52:15,331-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:15,331-0500 INFO Cpu 0813-511229-000000:27 submitting urn:1281721866605-1281721891226-1281721891227 2010-08-13 12:52:15,331-0500 INFO Block Started worker 0813-511229-000000:000028 2010-08-13 12:52:15,331-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866605-1281721891226-1281721891227) setting status to Submitting 2010-08-13 12:52:15,331-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:15,332-0500 INFO Block Started worker 0813-511229-000000:000029 2010-08-13 12:52:15,332-0500 INFO Cpu 0813-511229-000000:28 pull 2010-08-13 12:52:15,332-0500 INFO Cpu 0813-511229-000000:28 submitting urn:1281721866607-1281721891229-1281721891230 2010-08-13 12:52:15,332-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866577-1281721891184-1281721891185) setting status to Active workerid=000013 2010-08-13 12:52:15,332-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866607-1281721891229-1281721891230) setting status to Submitting 2010-08-13 12:52:15,332-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866581-1281721891187-1281721891188) setting status to Active workerid=000014 2010-08-13 12:52:15,332-0500 INFO Cpu 0813-511229-000000:29 pull 2010-08-13 12:52:15,333-0500 INFO Cpu 0813-511229-000000:29 submitting urn:1281721866611-1281721891232-1281721891233 2010-08-13 12:52:15,333-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866611-1281721891232-1281721891233) setting status to Submitting 2010-08-13 12:52:15,355-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866585-1281721891193-1281721891194) setting status to Submitted 2010-08-13 12:52:15,356-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866579-1281721891190-1281721891191) setting status to Submitted 2010-08-13 12:52:15,356-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:15,356-0500 INFO Block Started worker 0813-511229-000000:000030 2010-08-13 12:52:15,356-0500 INFO Cpu 0813-511229-000000:30 pull 2010-08-13 12:52:15,356-0500 INFO Cpu 0813-511229-000000:30 submitting urn:1281721866540-1281721891235-1281721891236 2010-08-13 12:52:15,356-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866540-1281721891235-1281721891236) setting status to Submitting 2010-08-13 12:52:15,356-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866587-1281721891199-1281721891200) setting status to Active workerid=- 2010-08-13 12:52:15,379-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866583-1281721891196-1281721891197) setting status to Submitted 2010-08-13 12:52:15,402-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866589-1281721891202-1281721891203) setting status to Submitted 2010-08-13 12:52:15,402-0500 INFO LocalTCPService Received registration: blockid = 0813-511229-000000, url = 2010-08-13 12:52:15,403-0500 INFO Block Started worker 0813-511229-000000:000031 2010-08-13 12:52:15,403-0500 INFO Cpu 0813-511229-000000:31 pull 2010-08-13 12:52:15,403-0500 INFO Cpu 0813-511229-000000:31 submitting urn:1281721866613-1281721891238-1281721891239 2010-08-13 12:52:15,403-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866585-1281721891193-1281721891194) setting status to Active workerid=000016 2010-08-13 12:52:15,403-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866613-1281721891238-1281721891239) setting status to Submitting 2010-08-13 12:52:15,405-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866579-1281721891190-1281721891191) setting status to Active workerid=000015 2010-08-13 12:52:15,427-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866591-1281721891205-1281721891206) setting status to Submitted 2010-08-13 12:52:15,428-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866583-1281721891196-1281721891197) setting status to Active workerid=000017 2010-08-13 12:52:15,450-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866589-1281721891202-1281721891203) setting status to Active workerid=000019 2010-08-13 12:52:15,473-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866593-1281721891208-1281721891209) setting status to Submitted 2010-08-13 12:52:15,473-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866591-1281721891205-1281721891206) setting status to Active workerid=000020 2010-08-13 12:52:15,496-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866597-1281721891214-1281721891215) setting status to Submitted 2010-08-13 12:52:15,497-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866593-1281721891208-1281721891209) setting status to Active workerid=000021 2010-08-13 12:52:15,519-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866595-1281721891211-1281721891212) setting status to Submitted 2010-08-13 12:52:15,541-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866599-1281721891217-1281721891218) setting status to Submitted 2010-08-13 12:52:15,542-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866601-1281721891220-1281721891221) setting status to Submitted 2010-08-13 12:52:15,542-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866597-1281721891214-1281721891215) setting status to Active workerid=000023 2010-08-13 12:52:15,564-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866603-1281721891223-1281721891224) setting status to Submitted 2010-08-13 12:52:15,565-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866595-1281721891211-1281721891212) setting status to Active workerid=000022 2010-08-13 12:52:15,565-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866601-1281721891220-1281721891221) setting status to Active workerid=000025 2010-08-13 12:52:15,588-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866605-1281721891226-1281721891227) setting status to Submitted 2010-08-13 12:52:15,588-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866607-1281721891229-1281721891230) setting status to Submitted 2010-08-13 12:52:15,588-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866611-1281721891232-1281721891233) setting status to Submitted 2010-08-13 12:52:15,588-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866599-1281721891217-1281721891218) setting status to Active workerid=000024 2010-08-13 12:52:15,611-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866540-1281721891235-1281721891236) setting status to Submitted 2010-08-13 12:52:15,612-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866607-1281721891229-1281721891230) setting status to Active workerid=000028 2010-08-13 12:52:15,612-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866603-1281721891223-1281721891224) setting status to Active workerid=000026 2010-08-13 12:52:15,634-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866605-1281721891226-1281721891227) setting status to Active workerid=000027 2010-08-13 12:52:15,634-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866611-1281721891232-1281721891233) setting status to Active workerid=000029 2010-08-13 12:52:15,657-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866613-1281721891238-1281721891239) setting status to Submitted 2010-08-13 12:52:15,658-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866540-1281721891235-1281721891236) setting status to Active workerid=000030 2010-08-13 12:52:15,703-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866613-1281721891238-1281721891239) setting status to Active workerid=000031 2010-08-13 12:52:17,254-0500 INFO BlockQueueProcessor Updated allocsize: 139.58531744717175 2010-08-13 12:52:17,254-0500 INFO BlockQueueProcessor allocsize = 139.58531744717175, queuedsize = 104.5501016297153, qsz = 97 2010-08-13 12:52:17,255-0500 INFO BlockQueueProcessor Plan time: 1 2010-08-13 12:52:19,458-0500 INFO BlockQueueProcessor Updated allocsize: 139.58512244597034 2010-08-13 12:52:19,458-0500 INFO BlockQueueProcessor allocsize = 139.58512244597034, queuedsize = 104.5501016297153, qsz = 97 2010-08-13 12:52:19,458-0500 INFO BlockQueueProcessor Plan time: 1 2010-08-13 12:52:21,054-0500 INFO CoasterService Idle time: 0 2010-08-13 12:52:21,152-0500 INFO TaskNotifier Congestion queue size: 0 2010-08-13 12:52:21,660-0500 INFO BlockQueueProcessor Updated allocsize: 139.58499238516544 2010-08-13 12:52:21,661-0500 INFO BlockQueueProcessor allocsize = 139.58499238516544, queuedsize = 104.5501016297153, qsz = 97 2010-08-13 12:52:21,661-0500 INFO BlockQueueProcessor Plan time: 1 2010-08-13 12:52:23,863-0500 INFO BlockQueueProcessor Updated allocsize: 139.5848622763158 2010-08-13 12:52:23,863-0500 INFO BlockQueueProcessor allocsize = 139.5848622763158, queuedsize = 104.5501016297153, qsz = 97 2010-08-13 12:52:23,863-0500 INFO BlockQueueProcessor Plan time: 0 2010-08-13 12:52:24,489-0500 INFO Cpu runTime: 45, sleepTime: 9970 2010-08-13 12:52:25,502-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866552-1281721891145-1281721891146) setting status to Completed 2010-08-13 12:52:25,502-0500 INFO Cpu 0813-511229-000000:0 jobTerminated 2010-08-13 12:52:25,502-0500 INFO Cpu 0813-511229-000000:0 pull 2010-08-13 12:52:25,503-0500 INFO Cpu 0813-511229-000000:0 submitting urn:1281721866615-1281721891241-1281721891242 2010-08-13 12:52:25,503-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866615-1281721891241-1281721891242) setting status to Submitting 2010-08-13 12:52:25,658-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866550-1281721891148-1281721891149) setting status to Completed 2010-08-13 12:52:25,658-0500 INFO Cpu 0813-511229-000000:1 jobTerminated 2010-08-13 12:52:25,658-0500 INFO Cpu 0813-511229-000000:1 pull 2010-08-13 12:52:25,658-0500 INFO Cpu 0813-511229-000000:1 submitting urn:1281721866617-1281721891244-1281721891245 2010-08-13 12:52:25,658-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866617-1281721891244-1281721891245) setting status to Submitting 2010-08-13 12:52:25,702-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866554-1281721891151-1281721891152) setting status to Completed 2010-08-13 12:52:25,703-0500 INFO Cpu 0813-511229-000000:2 jobTerminated 2010-08-13 12:52:25,703-0500 INFO Cpu 0813-511229-000000:2 pull 2010-08-13 12:52:25,703-0500 INFO Cpu 0813-511229-000000:2 submitting urn:1281721866619-1281721891247-1281721891248 2010-08-13 12:52:25,703-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866619-1281721891247-1281721891248) setting status to Submitting 2010-08-13 12:52:25,726-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866615-1281721891241-1281721891242) setting status to Submitted 2010-08-13 12:52:25,727-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866561-1281721891160-1281721891161) setting status to Completed 2010-08-13 12:52:25,727-0500 INFO Cpu 0813-511229-000000:5 jobTerminated 2010-08-13 12:52:25,727-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866557-1281721891157-1281721891158) setting status to Completed 2010-08-13 12:52:25,727-0500 INFO Cpu 0813-511229-000000:5 pull 2010-08-13 12:52:25,727-0500 INFO Cpu 0813-511229-000000:5 submitting urn:1281721866621-1281721891250-1281721891251 2010-08-13 12:52:25,727-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866621-1281721891250-1281721891251) setting status to Submitting 2010-08-13 12:52:25,728-0500 INFO Cpu 0813-511229-000000:4 jobTerminated 2010-08-13 12:52:25,728-0500 INFO Cpu 0813-511229-000000:4 pull 2010-08-13 12:52:25,728-0500 INFO Cpu 0813-511229-000000:4 submitting urn:1281721866623-1281721891253-1281721891254 2010-08-13 12:52:25,728-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866623-1281721891253-1281721891254) setting status to Submitting 2010-08-13 12:52:25,750-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866564-1281721891163-1281721891164) setting status to Completed 2010-08-13 12:52:25,750-0500 INFO Cpu 0813-511229-000000:6 jobTerminated 2010-08-13 12:52:25,751-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866615-1281721891241-1281721891242) setting status to Active workerid=000000 2010-08-13 12:52:25,751-0500 INFO Cpu 0813-511229-000000:6 pull 2010-08-13 12:52:25,751-0500 INFO Cpu 0813-511229-000000:6 submitting urn:1281721866625-1281721891256-1281721891257 2010-08-13 12:52:25,751-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866625-1281721891256-1281721891257) setting status to Submitting 2010-08-13 12:52:25,751-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866562-1281721891166-1281721891167) setting status to Completed 2010-08-13 12:52:25,752-0500 INFO Cpu 0813-511229-000000:7 jobTerminated 2010-08-13 12:52:25,752-0500 INFO Cpu 0813-511229-000000:7 pull 2010-08-13 12:52:25,752-0500 INFO Cpu 0813-511229-000000:7 submitting urn:1281721866627-1281721891259-1281721891260 2010-08-13 12:52:25,752-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866627-1281721891259-1281721891260) setting status to Submitting 2010-08-13 12:52:25,884-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866617-1281721891244-1281721891245) setting status to Submitted 2010-08-13 12:52:25,907-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866619-1281721891247-1281721891248) setting status to Submitted 2010-08-13 12:52:25,908-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866569-1281721891169-1281721891170) setting status to Completed 2010-08-13 12:52:25,908-0500 INFO Cpu 0813-511229-000000:8 jobTerminated 2010-08-13 12:52:25,908-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866617-1281721891244-1281721891245) setting status to Active workerid=000001 2010-08-13 12:52:25,908-0500 INFO Cpu 0813-511229-000000:8 pull 2010-08-13 12:52:25,908-0500 INFO Cpu 0813-511229-000000:8 submitting urn:1281721866629-1281721891262-1281721891263 2010-08-13 12:52:25,908-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866629-1281721891262-1281721891263) setting status to Submitting 2010-08-13 12:52:25,931-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866621-1281721891250-1281721891251) setting status to Submitted 2010-08-13 12:52:25,954-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866625-1281721891256-1281721891257) setting status to Submitted 2010-08-13 12:52:25,981-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866623-1281721891253-1281721891254) setting status to Submitted 2010-08-13 12:52:25,981-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866627-1281721891259-1281721891260) setting status to Submitted 2010-08-13 12:52:25,982-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866571-1281721891175-1281721891176) setting status to Completed 2010-08-13 12:52:25,982-0500 INFO Cpu 0813-511229-000000:10 jobTerminated 2010-08-13 12:52:25,982-0500 INFO Cpu 0813-511229-000000:10 pull 2010-08-13 12:52:25,982-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866558-1281721891154-1281721891155) setting status to Completed 2010-08-13 12:52:25,982-0500 INFO Cpu 0813-511229-000000:10 submitting urn:1281721866631-1281721891265-1281721891266 2010-08-13 12:52:25,982-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866631-1281721891265-1281721891266) setting status to Submitting 2010-08-13 12:52:25,982-0500 INFO Cpu 0813-511229-000000:3 jobTerminated 2010-08-13 12:52:25,983-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866619-1281721891247-1281721891248) setting status to Active workerid=000002 2010-08-13 12:52:25,983-0500 INFO Cpu 0813-511229-000000:3 pull 2010-08-13 12:52:25,983-0500 INFO Cpu 0813-511229-000000:3 submitting urn:1281721866633-1281721891268-1281721891269 2010-08-13 12:52:25,983-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866633-1281721891268-1281721891269) setting status to Submitting 2010-08-13 12:52:25,983-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866621-1281721891250-1281721891251) setting status to Active workerid=000005 2010-08-13 12:52:25,984-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866623-1281721891253-1281721891254) setting status to Active workerid=000004 2010-08-13 12:52:26,007-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866567-1281721891172-1281721891173) setting status to Completed 2010-08-13 12:52:26,008-0500 INFO Cpu 0813-511229-000000:9 jobTerminated 2010-08-13 12:52:26,008-0500 INFO Cpu 0813-511229-000000:9 pull 2010-08-13 12:52:26,008-0500 INFO Cpu 0813-511229-000000:9 submitting urn:1281721866635-1281721891271-1281721891272 2010-08-13 12:52:26,008-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866635-1281721891271-1281721891272) setting status to Submitting 2010-08-13 12:52:26,008-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866625-1281721891256-1281721891257) setting status to Active workerid=000006 2010-08-13 12:52:26,008-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866627-1281721891259-1281721891260) setting status to Active workerid=000007 2010-08-13 12:52:26,031-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866575-1281721891178-1281721891179) setting status to Completed 2010-08-13 12:52:26,031-0500 INFO Cpu 0813-511229-000000:11 jobTerminated 2010-08-13 12:52:26,031-0500 INFO Cpu 0813-511229-000000:11 pull 2010-08-13 12:52:26,031-0500 INFO Cpu 0813-511229-000000:11 submitting urn:1281721866637-1281721891274-1281721891275 2010-08-13 12:52:26,031-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866637-1281721891274-1281721891275) setting status to Submitting 2010-08-13 12:52:26,053-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866573-1281721891181-1281721891182) setting status to Completed 2010-08-13 12:52:26,053-0500 INFO Cpu 0813-511229-000000:12 jobTerminated 2010-08-13 12:52:26,054-0500 INFO Cpu 0813-511229-000000:12 pull 2010-08-13 12:52:26,054-0500 INFO Cpu 0813-511229-000000:12 submitting urn:1281721866639-1281721891277-1281721891278 2010-08-13 12:52:26,054-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866639-1281721891277-1281721891278) setting status to Submitting 2010-08-13 12:52:26,065-0500 INFO BlockQueueProcessor Updated allocsize: 139.58473211938565 2010-08-13 12:52:26,065-0500 INFO BlockQueueProcessor allocsize = 139.58473211938565, queuedsize = 90.5382323391349, qsz = 84 2010-08-13 12:52:26,065-0500 INFO BlockQueueProcessor Plan time: 0 2010-08-13 12:52:26,120-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866629-1281721891262-1281721891263) setting status to Submitted 2010-08-13 12:52:26,165-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866631-1281721891265-1281721891266) setting status to Submitted 2010-08-13 12:52:26,165-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866633-1281721891268-1281721891269) setting status to Submitted 2010-08-13 12:52:26,166-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866629-1281721891262-1281721891263) setting status to Active workerid=000008 2010-08-13 12:52:26,188-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866631-1281721891265-1281721891266) setting status to Active workerid=000010 2010-08-13 12:52:26,188-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866633-1281721891268-1281721891269) setting status to Active workerid=000003 2010-08-13 12:52:26,195-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866883-1281721891521-1281721891522) to coaster queue 2010-08-13 12:52:26,196-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866879-1281721891524-1281721891525) to coaster queue 2010-08-13 12:52:26,211-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866635-1281721891271-1281721891272) setting status to Submitted 2010-08-13 12:52:26,234-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866637-1281721891274-1281721891275) setting status to Submitted 2010-08-13 12:52:26,256-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866639-1281721891277-1281721891278) setting status to Submitted 2010-08-13 12:52:26,256-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866635-1281721891271-1281721891272) setting status to Active workerid=000009 2010-08-13 12:52:26,278-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866637-1281721891274-1281721891275) setting status to Active workerid=000011 2010-08-13 12:52:26,301-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866639-1281721891277-1281721891278) setting status to Active workerid=000012 2010-08-13 12:52:26,346-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866577-1281721891184-1281721891185) setting status to Completed 2010-08-13 12:52:26,347-0500 INFO Cpu 0813-511229-000000:13 jobTerminated 2010-08-13 12:52:26,347-0500 INFO Cpu 0813-511229-000000:13 pull 2010-08-13 12:52:26,347-0500 INFO Cpu 0813-511229-000000:13 submitting urn:1281721866641-1281721891280-1281721891281 2010-08-13 12:52:26,347-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866641-1281721891280-1281721891281) setting status to Submitting 2010-08-13 12:52:26,414-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866910-1281721891527-1281721891528) to coaster queue 2010-08-13 12:52:26,415-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866912-1281721891530-1281721891531) to coaster queue 2010-08-13 12:52:26,416-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866928-1281721891533-1281721891534) to coaster queue 2010-08-13 12:52:26,417-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866930-1281721891536-1281721891537) to coaster queue 2010-08-13 12:52:26,545-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866641-1281721891280-1281721891281) setting status to Submitted 2010-08-13 12:52:26,594-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866641-1281721891280-1281721891281) setting status to Active workerid=000013 2010-08-13 12:52:26,642-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866942-1281721891539-1281721891540) to coaster queue 2010-08-13 12:52:26,643-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866948-1281721891542-1281721891543) to coaster queue 2010-08-13 12:52:26,644-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866960-1281721891545-1281721891546) to coaster queue 2010-08-13 12:52:26,646-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866966-1281721891548-1281721891549) to coaster queue 2010-08-13 12:52:26,803-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866597-1281721891214-1281721891215) setting status to Completed 2010-08-13 12:52:26,803-0500 INFO Cpu 0813-511229-000000:23 jobTerminated 2010-08-13 12:52:26,803-0500 INFO Cpu 0813-511229-000000:23 pull 2010-08-13 12:52:26,803-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866595-1281721891211-1281721891212) setting status to Completed 2010-08-13 12:52:26,804-0500 INFO Cpu 0813-511229-000000:22 jobTerminated 2010-08-13 12:52:26,804-0500 INFO Cpu 0813-511229-000000:23 submitting urn:1281721866643-1281721891283-1281721891284 2010-08-13 12:52:26,804-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866643-1281721891283-1281721891284) setting status to Submitting 2010-08-13 12:52:26,804-0500 INFO Cpu 0813-511229-000000:22 pull 2010-08-13 12:52:26,805-0500 INFO Cpu 0813-511229-000000:22 submitting urn:1281721866645-1281721891286-1281721891287 2010-08-13 12:52:26,805-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866645-1281721891286-1281721891287) setting status to Submitting 2010-08-13 12:52:26,848-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866581-1281721891187-1281721891188) setting status to Completed 2010-08-13 12:52:26,848-0500 INFO Cpu 0813-511229-000000:14 jobTerminated 2010-08-13 12:52:26,848-0500 INFO Cpu 0813-511229-000000:14 pull 2010-08-13 12:52:26,848-0500 INFO Cpu 0813-511229-000000:14 submitting urn:1281721866647-1281721891289-1281721891290 2010-08-13 12:52:26,849-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866647-1281721891289-1281721891290) setting status to Submitting 2010-08-13 12:52:26,851-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721866993-1281721891551-1281721891552) to coaster queue 2010-08-13 12:52:26,893-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866540-1281721891235-1281721891236) setting status to Completed 2010-08-13 12:52:26,893-0500 INFO Cpu 0813-511229-000000:30 jobTerminated 2010-08-13 12:52:26,893-0500 INFO Cpu 0813-511229-000000:30 pull 2010-08-13 12:52:26,893-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866579-1281721891190-1281721891191) setting status to Completed 2010-08-13 12:52:26,893-0500 INFO Cpu 0813-511229-000000:30 submitting urn:1281721866649-1281721891292-1281721891293 2010-08-13 12:52:26,893-0500 INFO Cpu 0813-511229-000000:15 jobTerminated 2010-08-13 12:52:26,894-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866649-1281721891292-1281721891293) setting status to Submitting 2010-08-13 12:52:26,894-0500 INFO Cpu 0813-511229-000000:15 pull 2010-08-13 12:52:26,894-0500 INFO Cpu 0813-511229-000000:15 submitting urn:1281721866651-1281721891295-1281721891296 2010-08-13 12:52:26,894-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866651-1281721891295-1281721891296) setting status to Submitting 2010-08-13 12:52:26,896-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721867007-1281721891554-1281721891555) to coaster queue 2010-08-13 12:52:26,898-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721867009-1281721891557-1281721891558) to coaster queue 2010-08-13 12:52:26,917-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866585-1281721891193-1281721891194) setting status to Completed 2010-08-13 12:52:26,917-0500 INFO Cpu 0813-511229-000000:16 jobTerminated 2010-08-13 12:52:26,917-0500 INFO Cpu 0813-511229-000000:16 pull 2010-08-13 12:52:26,917-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866583-1281721891196-1281721891197) setting status to Completed 2010-08-13 12:52:26,917-0500 INFO Cpu 0813-511229-000000:17 jobTerminated 2010-08-13 12:52:26,917-0500 INFO Cpu 0813-511229-000000:16 submitting urn:1281721866653-1281721891298-1281721891299 2010-08-13 12:52:26,917-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866653-1281721891298-1281721891299) setting status to Submitting 2010-08-13 12:52:26,918-0500 INFO Cpu 0813-511229-000000:17 pull 2010-08-13 12:52:26,918-0500 INFO Cpu 0813-511229-000000:17 submitting urn:1281721866655-1281721891301-1281721891302 2010-08-13 12:52:26,918-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866655-1281721891301-1281721891302) setting status to Submitting 2010-08-13 12:52:26,940-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866587-1281721891199-1281721891200) setting status to Completed 2010-08-13 12:52:26,940-0500 INFO Cpu 0813-511229-000000:18 jobTerminated 2010-08-13 12:52:26,941-0500 INFO Cpu 0813-511229-000000:18 pull 2010-08-13 12:52:26,941-0500 INFO Cpu 0813-511229-000000:18 submitting urn:1281721866657-1281721891304-1281721891305 2010-08-13 12:52:26,941-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866657-1281721891304-1281721891305) setting status to Submitting 2010-08-13 12:52:26,963-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866591-1281721891205-1281721891206) setting status to Completed 2010-08-13 12:52:26,963-0500 INFO Cpu 0813-511229-000000:20 jobTerminated 2010-08-13 12:52:26,963-0500 INFO Cpu 0813-511229-000000:20 pull 2010-08-13 12:52:26,963-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866589-1281721891202-1281721891203) setting status to Completed 2010-08-13 12:52:26,963-0500 INFO Cpu 0813-511229-000000:20 submitting urn:1281721866659-1281721891307-1281721891308 2010-08-13 12:52:26,964-0500 INFO Cpu 0813-511229-000000:19 jobTerminated 2010-08-13 12:52:26,964-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866659-1281721891307-1281721891308) setting status to Submitting 2010-08-13 12:52:26,964-0500 INFO Cpu 0813-511229-000000:19 pull 2010-08-13 12:52:26,964-0500 INFO Cpu 0813-511229-000000:19 submitting urn:1281721866661-1281721891310-1281721891311 2010-08-13 12:52:26,964-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866661-1281721891310-1281721891311) setting status to Submitting 2010-08-13 12:52:26,966-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721867033-1281721891560-1281721891561) to coaster queue 2010-08-13 12:52:27,007-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866643-1281721891283-1281721891284) setting status to Submitted 2010-08-13 12:52:27,008-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866645-1281721891286-1281721891287) setting status to Submitted 2010-08-13 12:52:27,052-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866647-1281721891289-1281721891290) setting status to Submitted 2010-08-13 12:52:27,053-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866643-1281721891283-1281721891284) setting status to Active workerid=000023 2010-08-13 12:52:27,053-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866645-1281721891286-1281721891287) setting status to Active workerid=000022 2010-08-13 12:52:27,075-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866601-1281721891220-1281721891221) setting status to Completed 2010-08-13 12:52:27,075-0500 INFO Cpu 0813-511229-000000:25 jobTerminated 2010-08-13 12:52:27,075-0500 INFO Cpu 0813-511229-000000:25 pull 2010-08-13 12:52:27,075-0500 INFO Cpu 0813-511229-000000:25 submitting urn:1281721866663-1281721891313-1281721891314 2010-08-13 12:52:27,075-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866663-1281721891313-1281721891314) setting status to Submitting 2010-08-13 12:52:27,081-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721867079-1281721891563-1281721891564) to coaster queue 2010-08-13 12:52:27,097-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866651-1281721891295-1281721891296) setting status to Submitted 2010-08-13 12:52:27,097-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866647-1281721891289-1281721891290) setting status to Active workerid=000014 2010-08-13 12:52:27,105-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721867081-1281721891566-1281721891567) to coaster queue 2010-08-13 12:52:27,120-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866649-1281721891292-1281721891293) setting status to Submitted 2010-08-13 12:52:27,120-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866653-1281721891298-1281721891299) setting status to Submitted 2010-08-13 12:52:27,143-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866657-1281721891304-1281721891305) setting status to Submitted 2010-08-13 12:52:27,143-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866655-1281721891301-1281721891302) setting status to Submitted 2010-08-13 12:52:27,143-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866605-1281721891226-1281721891227) setting status to Completed 2010-08-13 12:52:27,144-0500 INFO Cpu 0813-511229-000000:27 jobTerminated 2010-08-13 12:52:27,144-0500 INFO Cpu 0813-511229-000000:27 pull 2010-08-13 12:52:27,144-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866649-1281721891292-1281721891293) setting status to Active workerid=000030 2010-08-13 12:52:27,144-0500 INFO Cpu 0813-511229-000000:27 submitting urn:1281721866665-1281721891316-1281721891317 2010-08-13 12:52:27,144-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866665-1281721891316-1281721891317) setting status to Submitting 2010-08-13 12:52:27,144-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866651-1281721891295-1281721891296) setting status to Active workerid=000015 2010-08-13 12:52:27,151-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721867096-1281721891569-1281721891570) to coaster queue 2010-08-13 12:52:27,167-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866661-1281721891310-1281721891311) setting status to Submitted 2010-08-13 12:52:27,167-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866653-1281721891298-1281721891299) setting status to Active workerid=000016 2010-08-13 12:52:27,168-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866655-1281721891301-1281721891302) setting status to Active workerid=000017 2010-08-13 12:52:27,175-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721867107-1281721891572-1281721891573) to coaster queue 2010-08-13 12:52:27,191-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866659-1281721891307-1281721891308) setting status to Submitted 2010-08-13 12:52:27,192-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866657-1281721891304-1281721891305) setting status to Active workerid=000018 2010-08-13 12:52:27,204-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866613-1281721891238-1281721891239) setting status to Completed 2010-08-13 12:52:27,204-0500 INFO Cpu 0813-511229-000000:31 jobTerminated 2010-08-13 12:52:27,204-0500 INFO Cpu 0813-511229-000000:31 pull 2010-08-13 12:52:27,205-0500 INFO Cpu 0813-511229-000000:31 submitting urn:1281721866669-1281721891319-1281721891320 2010-08-13 12:52:27,205-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866669-1281721891319-1281721891320) setting status to Submitting 2010-08-13 12:52:27,205-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866661-1281721891310-1281721891311) setting status to Active workerid=000019 2010-08-13 12:52:27,227-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866659-1281721891307-1281721891308) setting status to Active workerid=000020 2010-08-13 12:52:27,250-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866593-1281721891208-1281721891209) setting status to Completed 2010-08-13 12:52:27,251-0500 INFO Cpu 0813-511229-000000:21 jobTerminated 2010-08-13 12:52:27,251-0500 INFO Cpu 0813-511229-000000:21 pull 2010-08-13 12:52:27,251-0500 INFO Cpu 0813-511229-000000:21 submitting urn:1281721866667-1281721891322-1281721891323 2010-08-13 12:52:27,251-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866667-1281721891322-1281721891323) setting status to Submitting 2010-08-13 12:52:27,295-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866663-1281721891313-1281721891314) setting status to Submitted 2010-08-13 12:52:27,310-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721867143-1281721891575-1281721891576) to coaster queue 2010-08-13 12:52:27,311-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721867145-1281721891578-1281721891579) to coaster queue 2010-08-13 12:52:27,339-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866599-1281721891217-1281721891218) setting status to Completed 2010-08-13 12:52:27,340-0500 INFO Cpu 0813-511229-000000:24 jobTerminated 2010-08-13 12:52:27,340-0500 INFO Cpu 0813-511229-000000:24 pull 2010-08-13 12:52:27,340-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866663-1281721891313-1281721891314) setting status to Active workerid=000025 2010-08-13 12:52:27,340-0500 INFO Cpu 0813-511229-000000:24 submitting urn:1281721866673-1281721891325-1281721891326 2010-08-13 12:52:27,340-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866673-1281721891325-1281721891326) setting status to Submitting 2010-08-13 12:52:27,362-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866665-1281721891316-1281721891317) setting status to Submitted 2010-08-13 12:52:27,385-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866607-1281721891229-1281721891230) setting status to Completed 2010-08-13 12:52:27,385-0500 INFO Cpu 0813-511229-000000:28 jobTerminated 2010-08-13 12:52:27,385-0500 INFO Cpu 0813-511229-000000:28 pull 2010-08-13 12:52:27,385-0500 INFO Cpu 0813-511229-000000:28 submitting urn:1281721866671-1281721891328-1281721891329 2010-08-13 12:52:27,385-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866611-1281721891232-1281721891233) setting status to Completed 2010-08-13 12:52:27,385-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866671-1281721891328-1281721891329) setting status to Submitting 2010-08-13 12:52:27,386-0500 INFO Cpu 0813-511229-000000:29 jobTerminated 2010-08-13 12:52:27,386-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866665-1281721891316-1281721891317) setting status to Active workerid=000027 2010-08-13 12:52:27,387-0500 INFO Cpu 0813-511229-000000:29 pull 2010-08-13 12:52:27,387-0500 INFO Cpu 0813-511229-000000:29 submitting urn:1281721866675-1281721891331-1281721891332 2010-08-13 12:52:27,387-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866675-1281721891331-1281721891332) setting status to Submitting 2010-08-13 12:52:27,391-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721867158-1281721891581-1281721891582) to coaster queue 2010-08-13 12:52:27,408-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866669-1281721891319-1281721891320) setting status to Submitted 2010-08-13 12:52:27,453-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866669-1281721891319-1281721891320) setting status to Active workerid=000031 2010-08-13 12:52:27,475-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866667-1281721891322-1281721891323) setting status to Submitted 2010-08-13 12:52:27,498-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866667-1281721891322-1281721891323) setting status to Active workerid=000021 2010-08-13 12:52:27,541-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866673-1281721891325-1281721891326) setting status to Submitted 2010-08-13 12:52:27,586-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866671-1281721891328-1281721891329) setting status to Submitted 2010-08-13 12:52:27,586-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866675-1281721891331-1281721891332) setting status to Submitted 2010-08-13 12:52:27,587-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866673-1281721891325-1281721891326) setting status to Active workerid=000024 2010-08-13 12:52:27,609-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866603-1281721891223-1281721891224) setting status to Completed 2010-08-13 12:52:27,609-0500 INFO Cpu 0813-511229-000000:26 jobTerminated 2010-08-13 12:52:27,609-0500 INFO Cpu 0813-511229-000000:26 pull 2010-08-13 12:52:27,609-0500 INFO Cpu 0813-511229-000000:26 submitting urn:1281721866679-1281721891334-1281721891335 2010-08-13 12:52:27,609-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866679-1281721891334-1281721891335) setting status to Submitting 2010-08-13 12:52:27,632-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866671-1281721891328-1281721891329) setting status to Active workerid=000028 2010-08-13 12:52:27,633-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866675-1281721891331-1281721891332) setting status to Active workerid=000029 2010-08-13 12:52:27,831-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866679-1281721891334-1281721891335) setting status to Submitted 2010-08-13 12:52:27,875-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866679-1281721891334-1281721891335) setting status to Active workerid=000026 2010-08-13 12:52:28,268-0500 INFO BlockQueueProcessor Updated allocsize: 139.58460191433937 2010-08-13 12:52:28,268-0500 INFO BlockQueueProcessor allocsize = 139.58460191433937, queuedsize = 92.69390453768573, qsz = 86 2010-08-13 12:52:28,268-0500 INFO BlockQueueProcessor Plan time: 0 2010-08-13 12:52:30,471-0500 INFO BlockQueueProcessor Updated allocsize: 139.58440651647385 2010-08-13 12:52:30,471-0500 INFO BlockQueueProcessor allocsize = 139.58440651647385, queuedsize = 92.69390453768573, qsz = 86 2010-08-13 12:52:30,471-0500 INFO BlockQueueProcessor Plan time: 0 2010-08-13 12:52:31,058-0500 INFO CoasterService Idle time: 0 2010-08-13 12:52:31,156-0500 INFO TaskNotifier Congestion queue size: 0 2010-08-13 12:52:32,676-0500 INFO BlockQueueProcessor Updated allocsize: 139.58427619098052 2010-08-13 12:52:32,676-0500 INFO BlockQueueProcessor allocsize = 139.58427619098052, queuedsize = 92.69390453768573, qsz = 86 2010-08-13 12:52:32,676-0500 INFO BlockQueueProcessor Plan time: 2 2010-08-13 12:52:34,536-0500 INFO Cpu runTime: 22, sleepTime: 10025 2010-08-13 12:52:34,879-0500 INFO BlockQueueProcessor Updated allocsize: 139.58414581724568 2010-08-13 12:52:34,879-0500 INFO BlockQueueProcessor allocsize = 139.58414581724568, queuedsize = 92.69390453768573, qsz = 86 2010-08-13 12:52:34,879-0500 INFO BlockQueueProcessor Plan time: 0 2010-08-13 12:52:36,519-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866615-1281721891241-1281721891242) setting status to Completed 2010-08-13 12:52:36,519-0500 INFO Cpu 0813-511229-000000:0 jobTerminated 2010-08-13 12:52:36,520-0500 INFO Cpu 0813-511229-000000:0 pull 2010-08-13 12:52:36,520-0500 INFO Cpu 0813-511229-000000:0 submitting urn:1281721866677-1281721891337-1281721891338 2010-08-13 12:52:36,520-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866677-1281721891337-1281721891338) setting status to Submitting 2010-08-13 12:52:36,718-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866677-1281721891337-1281721891338) setting status to Submitted 2010-08-13 12:52:36,719-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866619-1281721891247-1281721891248) setting status to Completed 2010-08-13 12:52:36,719-0500 INFO Cpu 0813-511229-000000:2 jobTerminated 2010-08-13 12:52:36,719-0500 INFO Cpu 0813-511229-000000:2 pull 2010-08-13 12:52:36,719-0500 INFO Cpu 0813-511229-000000:2 submitting urn:1281721866681-1281721891340-1281721891341 2010-08-13 12:52:36,719-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866681-1281721891340-1281721891341) setting status to Submitting 2010-08-13 12:52:36,741-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866621-1281721891250-1281721891251) setting status to Completed 2010-08-13 12:52:36,742-0500 INFO Cpu 0813-511229-000000:5 jobTerminated 2010-08-13 12:52:36,742-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866623-1281721891253-1281721891254) setting status to Completed 2010-08-13 12:52:36,742-0500 INFO Cpu 0813-511229-000000:5 pull 2010-08-13 12:52:36,742-0500 INFO Cpu 0813-511229-000000:5 submitting urn:1281721866685-1281721891343-1281721891344 2010-08-13 12:52:36,742-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866685-1281721891343-1281721891344) setting status to Submitting 2010-08-13 12:52:36,743-0500 INFO Cpu 0813-511229-000000:4 jobTerminated 2010-08-13 12:52:36,743-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866625-1281721891256-1281721891257) setting status to Completed 2010-08-13 12:52:36,743-0500 INFO Cpu 0813-511229-000000:4 pull 2010-08-13 12:52:36,743-0500 INFO Cpu 0813-511229-000000:4 submitting urn:1281721866683-1281721891346-1281721891347 2010-08-13 12:52:36,743-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866683-1281721891346-1281721891347) setting status to Submitting 2010-08-13 12:52:36,744-0500 INFO Cpu 0813-511229-000000:6 jobTerminated 2010-08-13 12:52:36,744-0500 INFO Cpu 0813-511229-000000:6 pull 2010-08-13 12:52:36,744-0500 INFO Cpu 0813-511229-000000:6 submitting urn:1281721866686-1281721891349-1281721891350 2010-08-13 12:52:36,744-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866686-1281721891349-1281721891350) setting status to Submitting 2010-08-13 12:52:36,766-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866627-1281721891259-1281721891260) setting status to Completed 2010-08-13 12:52:36,766-0500 INFO Cpu 0813-511229-000000:7 jobTerminated 2010-08-13 12:52:36,766-0500 INFO Cpu 0813-511229-000000:7 pull 2010-08-13 12:52:36,766-0500 INFO Cpu 0813-511229-000000:7 submitting urn:1281721866691-1281721891352-1281721891353 2010-08-13 12:52:36,766-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866691-1281721891352-1281721891353) setting status to Submitting 2010-08-13 12:52:36,767-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866677-1281721891337-1281721891338) setting status to Active workerid=000000 2010-08-13 12:52:36,908-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866629-1281721891262-1281721891263) setting status to Completed 2010-08-13 12:52:36,908-0500 INFO Cpu 0813-511229-000000:8 jobTerminated 2010-08-13 12:52:36,909-0500 INFO Cpu 0813-511229-000000:8 pull 2010-08-13 12:52:36,909-0500 INFO Cpu 0813-511229-000000:8 submitting urn:1281721866693-1281721891355-1281721891356 2010-08-13 12:52:36,909-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866693-1281721891355-1281721891356) setting status to Submitting 2010-08-13 12:52:36,931-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866681-1281721891340-1281721891341) setting status to Submitted 2010-08-13 12:52:36,931-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866617-1281721891244-1281721891245) setting status to Completed 2010-08-13 12:52:36,931-0500 INFO Cpu 0813-511229-000000:1 jobTerminated 2010-08-13 12:52:36,932-0500 INFO Cpu 0813-511229-000000:1 pull 2010-08-13 12:52:36,932-0500 INFO Cpu 0813-511229-000000:1 submitting urn:1281721866695-1281721891358-1281721891359 2010-08-13 12:52:36,932-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866695-1281721891358-1281721891359) setting status to Submitting 2010-08-13 12:52:36,955-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866686-1281721891349-1281721891350) setting status to Submitted 2010-08-13 12:52:36,955-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866685-1281721891343-1281721891344) setting status to Submitted 2010-08-13 12:52:36,956-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866683-1281721891346-1281721891347) setting status to Submitted 2010-08-13 12:52:36,956-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866631-1281721891265-1281721891266) setting status to Completed 2010-08-13 12:52:36,957-0500 INFO Cpu 0813-511229-000000:10 jobTerminated 2010-08-13 12:52:36,957-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866633-1281721891268-1281721891269) setting status to Completed 2010-08-13 12:52:36,957-0500 INFO Cpu 0813-511229-000000:3 jobTerminated 2010-08-13 12:52:36,957-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866681-1281721891340-1281721891341) setting status to Active workerid=000002 2010-08-13 12:52:36,958-0500 INFO Cpu 0813-511229-000000:10 pull 2010-08-13 12:52:36,958-0500 INFO Cpu 0813-511229-000000:10 submitting urn:1281721866697-1281721891361-1281721891362 2010-08-13 12:52:36,958-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866697-1281721891361-1281721891362) setting status to Submitting 2010-08-13 12:52:36,958-0500 INFO Cpu 0813-511229-000000:3 pull 2010-08-13 12:52:36,958-0500 INFO Cpu 0813-511229-000000:3 submitting urn:1281721866609-1281721891364-1281721891365 2010-08-13 12:52:36,958-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866609-1281721891364-1281721891365) setting status to Submitting 2010-08-13 12:52:36,980-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866691-1281721891352-1281721891353) setting status to Submitted 2010-08-13 12:52:36,980-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866685-1281721891343-1281721891344) setting status to Active workerid=000005 2010-08-13 12:52:36,981-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866683-1281721891346-1281721891347) setting status to Active workerid=000004 2010-08-13 12:52:37,003-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866686-1281721891349-1281721891350) setting status to Active workerid=000006 2010-08-13 12:52:37,026-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866637-1281721891274-1281721891275) setting status to Completed 2010-08-13 12:52:37,026-0500 INFO Cpu 0813-511229-000000:11 jobTerminated 2010-08-13 12:52:37,026-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866691-1281721891352-1281721891353) setting status to Active workerid=000007 2010-08-13 12:52:37,027-0500 INFO Cpu 0813-511229-000000:11 pull 2010-08-13 12:52:37,027-0500 INFO Cpu 0813-511229-000000:11 submitting urn:1281721866699-1281721891367-1281721891368 2010-08-13 12:52:37,027-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866699-1281721891367-1281721891368) setting status to Submitting 2010-08-13 12:52:37,071-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866639-1281721891277-1281721891278) setting status to Completed 2010-08-13 12:52:37,071-0500 INFO Cpu 0813-511229-000000:12 jobTerminated 2010-08-13 12:52:37,071-0500 INFO Cpu 0813-511229-000000:12 pull 2010-08-13 12:52:37,072-0500 INFO Cpu 0813-511229-000000:12 submitting urn:1281721866701-1281721891370-1281721891371 2010-08-13 12:52:37,072-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866701-1281721891370-1281721891371) setting status to Submitting 2010-08-13 12:52:37,081-0500 INFO BlockQueueProcessor Updated allocsize: 139.5840153952334 2010-08-13 12:52:37,081-0500 INFO BlockQueueProcessor allocsize = 139.5840153952334, queuedsize = 79.75987134638073, qsz = 74 2010-08-13 12:52:37,082-0500 INFO BlockQueueProcessor Plan time: 0 2010-08-13 12:52:37,116-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866693-1281721891355-1281721891356) setting status to Submitted 2010-08-13 12:52:37,137-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866695-1281721891358-1281721891359) setting status to Submitted 2010-08-13 12:52:37,159-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866697-1281721891361-1281721891362) setting status to Submitted 2010-08-13 12:52:37,160-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866609-1281721891364-1281721891365) setting status to Submitted 2010-08-13 12:52:37,160-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866693-1281721891355-1281721891356) setting status to Active workerid=000008 2010-08-13 12:52:37,182-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866695-1281721891358-1281721891359) setting status to Active workerid=000001 2010-08-13 12:52:37,205-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866697-1281721891361-1281721891362) setting status to Active workerid=000010 2010-08-13 12:52:37,205-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866609-1281721891364-1281721891365) setting status to Active workerid=000003 2010-08-13 12:52:37,250-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866699-1281721891367-1281721891368) setting status to Submitted 2010-08-13 12:52:37,250-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866635-1281721891271-1281721891272) setting status to Completed 2010-08-13 12:52:37,251-0500 INFO Cpu 0813-511229-000000:9 jobTerminated 2010-08-13 12:52:37,251-0500 INFO Cpu 0813-511229-000000:9 pull 2010-08-13 12:52:37,251-0500 INFO Cpu 0813-511229-000000:9 submitting urn:1281721866705-1281721891373-1281721891374 2010-08-13 12:52:37,251-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866705-1281721891373-1281721891374) setting status to Submitting 2010-08-13 12:52:37,273-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866701-1281721891370-1281721891371) setting status to Submitted 2010-08-13 12:52:37,296-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866699-1281721891367-1281721891368) setting status to Active workerid=000011 2010-08-13 12:52:37,318-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866701-1281721891370-1281721891371) setting status to Active workerid=000012 2010-08-13 12:52:37,341-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866641-1281721891280-1281721891281) setting status to Completed 2010-08-13 12:52:37,341-0500 INFO Cpu 0813-511229-000000:13 jobTerminated 2010-08-13 12:52:37,341-0500 INFO Cpu 0813-511229-000000:13 pull 2010-08-13 12:52:37,342-0500 INFO Cpu 0813-511229-000000:13 submitting urn:1281721866703-1281721891376-1281721891377 2010-08-13 12:52:37,342-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866703-1281721891376-1281721891377) setting status to Submitting 2010-08-13 12:52:37,474-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866705-1281721891373-1281721891374) setting status to Submitted 2010-08-13 12:52:37,518-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866705-1281721891373-1281721891374) setting status to Active workerid=000009 2010-08-13 12:52:37,541-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866703-1281721891376-1281721891377) setting status to Submitted 2010-08-13 12:52:37,586-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866703-1281721891376-1281721891377) setting status to Active workerid=000013 2010-08-13 12:52:37,807-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866643-1281721891283-1281721891284) setting status to Completed 2010-08-13 12:52:37,807-0500 INFO Cpu 0813-511229-000000:23 jobTerminated 2010-08-13 12:52:37,807-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866645-1281721891286-1281721891287) setting status to Completed 2010-08-13 12:52:37,807-0500 INFO Cpu 0813-511229-000000:22 jobTerminated 2010-08-13 12:52:37,808-0500 INFO Cpu 0813-511229-000000:23 pull 2010-08-13 12:52:37,808-0500 INFO Cpu 0813-511229-000000:23 submitting urn:1281721866706-1281721891379-1281721891380 2010-08-13 12:52:37,808-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866706-1281721891379-1281721891380) setting status to Submitting 2010-08-13 12:52:37,808-0500 INFO Cpu 0813-511229-000000:22 pull 2010-08-13 12:52:37,808-0500 INFO Cpu 0813-511229-000000:22 submitting urn:1281721866709-1281721891382-1281721891383 2010-08-13 12:52:37,808-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866709-1281721891382-1281721891383) setting status to Submitting 2010-08-13 12:52:37,852-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866647-1281721891289-1281721891290) setting status to Completed 2010-08-13 12:52:37,852-0500 INFO Cpu 0813-511229-000000:14 jobTerminated 2010-08-13 12:52:37,852-0500 INFO Cpu 0813-511229-000000:14 pull 2010-08-13 12:52:37,852-0500 INFO Cpu 0813-511229-000000:14 submitting urn:1281721866710-1281721891385-1281721891386 2010-08-13 12:52:37,852-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866710-1281721891385-1281721891386) setting status to Submitting 2010-08-13 12:52:37,897-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866649-1281721891292-1281721891293) setting status to Completed 2010-08-13 12:52:37,897-0500 INFO Cpu 0813-511229-000000:30 jobTerminated 2010-08-13 12:52:37,897-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866651-1281721891295-1281721891296) setting status to Completed 2010-08-13 12:52:37,898-0500 INFO Cpu 0813-511229-000000:15 jobTerminated 2010-08-13 12:52:37,898-0500 INFO Cpu 0813-511229-000000:30 pull 2010-08-13 12:52:37,898-0500 INFO Cpu 0813-511229-000000:30 submitting urn:1281721866713-1281721891388-1281721891389 2010-08-13 12:52:37,898-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866713-1281721891388-1281721891389) setting status to Submitting 2010-08-13 12:52:37,898-0500 INFO Cpu 0813-511229-000000:15 pull 2010-08-13 12:52:37,898-0500 INFO Cpu 0813-511229-000000:15 submitting urn:1281721866714-1281721891391-1281721891392 2010-08-13 12:52:37,898-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866714-1281721891391-1281721891392) setting status to Submitting 2010-08-13 12:52:37,921-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866653-1281721891298-1281721891299) setting status to Completed 2010-08-13 12:52:37,921-0500 INFO Cpu 0813-511229-000000:16 jobTerminated 2010-08-13 12:52:37,921-0500 INFO Cpu 0813-511229-000000:16 pull 2010-08-13 12:52:37,921-0500 INFO Cpu 0813-511229-000000:16 submitting urn:1281721866717-1281721891394-1281721891395 2010-08-13 12:52:37,921-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866717-1281721891394-1281721891395) setting status to Submitting 2010-08-13 12:52:37,921-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866655-1281721891301-1281721891302) setting status to Completed 2010-08-13 12:52:37,921-0500 INFO Cpu 0813-511229-000000:17 jobTerminated 2010-08-13 12:52:37,922-0500 INFO Cpu 0813-511229-000000:17 pull 2010-08-13 12:52:37,922-0500 INFO Cpu 0813-511229-000000:17 submitting urn:1281721866718-1281721891397-1281721891398 2010-08-13 12:52:37,922-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866718-1281721891397-1281721891398) setting status to Submitting 2010-08-13 12:52:37,945-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866657-1281721891304-1281721891305) setting status to Completed 2010-08-13 12:52:37,945-0500 INFO Cpu 0813-511229-000000:18 jobTerminated 2010-08-13 12:52:37,946-0500 INFO Cpu 0813-511229-000000:18 pull 2010-08-13 12:52:37,946-0500 INFO Cpu 0813-511229-000000:18 submitting urn:1281721866722-1281721891400-1281721891401 2010-08-13 12:52:37,946-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866722-1281721891400-1281721891401) setting status to Submitting 2010-08-13 12:52:37,969-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866659-1281721891307-1281721891308) setting status to Completed 2010-08-13 12:52:37,969-0500 INFO Cpu 0813-511229-000000:20 jobTerminated 2010-08-13 12:52:37,969-0500 INFO Cpu 0813-511229-000000:20 pull 2010-08-13 12:52:37,969-0500 INFO Cpu 0813-511229-000000:20 submitting urn:1281721866725-1281721891403-1281721891404 2010-08-13 12:52:37,969-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866725-1281721891403-1281721891404) setting status to Submitting 2010-08-13 12:52:37,969-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866661-1281721891310-1281721891311) setting status to Completed 2010-08-13 12:52:37,970-0500 INFO Cpu 0813-511229-000000:19 jobTerminated 2010-08-13 12:52:37,970-0500 INFO Cpu 0813-511229-000000:19 pull 2010-08-13 12:52:37,970-0500 INFO Cpu 0813-511229-000000:19 submitting urn:1281721866726-1281721891406-1281721891407 2010-08-13 12:52:37,970-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866726-1281721891406-1281721891407) setting status to Submitting 2010-08-13 12:52:38,016-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866706-1281721891379-1281721891380) setting status to Submitted 2010-08-13 12:52:38,016-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866709-1281721891382-1281721891383) setting status to Submitted 2010-08-13 12:52:38,060-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866710-1281721891385-1281721891386) setting status to Submitted 2010-08-13 12:52:38,060-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866706-1281721891379-1281721891380) setting status to Active workerid=000023 2010-08-13 12:52:38,060-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866709-1281721891382-1281721891383) setting status to Active workerid=000022 2010-08-13 12:52:38,105-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866713-1281721891388-1281721891389) setting status to Submitted 2010-08-13 12:52:38,105-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866714-1281721891391-1281721891392) setting status to Submitted 2010-08-13 12:52:38,105-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866710-1281721891385-1281721891386) setting status to Active workerid=000014 2010-08-13 12:52:38,127-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866717-1281721891394-1281721891395) setting status to Submitted 2010-08-13 12:52:38,150-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866722-1281721891400-1281721891401) setting status to Submitted 2010-08-13 12:52:38,150-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866718-1281721891397-1281721891398) setting status to Submitted 2010-08-13 12:52:38,151-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866665-1281721891316-1281721891317) setting status to Completed 2010-08-13 12:52:38,151-0500 INFO Cpu 0813-511229-000000:27 jobTerminated 2010-08-13 12:52:38,151-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866713-1281721891388-1281721891389) setting status to Active workerid=000030 2010-08-13 12:52:38,151-0500 INFO Cpu 0813-511229-000000:27 pull 2010-08-13 12:52:38,151-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866717-1281721891394-1281721891395) setting status to Active workerid=000016 2010-08-13 12:52:38,151-0500 INFO Cpu 0813-511229-000000:27 submitting urn:1281721866721-1281721891409-1281721891410 2010-08-13 12:52:38,152-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866721-1281721891409-1281721891410) setting status to Submitting 2010-08-13 12:52:38,152-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866714-1281721891391-1281721891392) setting status to Active workerid=000015 2010-08-13 12:52:38,174-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866726-1281721891406-1281721891407) setting status to Submitted 2010-08-13 12:52:38,174-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866718-1281721891397-1281721891398) setting status to Active workerid=000017 2010-08-13 12:52:38,197-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866725-1281721891403-1281721891404) setting status to Submitted 2010-08-13 12:52:38,197-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866722-1281721891400-1281721891401) setting status to Active workerid=000018 2010-08-13 12:52:38,220-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866669-1281721891319-1281721891320) setting status to Completed 2010-08-13 12:52:38,220-0500 INFO Cpu 0813-511229-000000:31 jobTerminated 2010-08-13 12:52:38,220-0500 INFO Cpu 0813-511229-000000:31 pull 2010-08-13 12:52:38,220-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866725-1281721891403-1281721891404) setting status to Active workerid=000020 2010-08-13 12:52:38,220-0500 INFO Cpu 0813-511229-000000:31 submitting urn:1281721866729-1281721891412-1281721891413 2010-08-13 12:52:38,221-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866729-1281721891412-1281721891413) setting status to Submitting 2010-08-13 12:52:38,221-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866726-1281721891406-1281721891407) setting status to Active workerid=000019 2010-08-13 12:52:38,265-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866667-1281721891322-1281721891323) setting status to Completed 2010-08-13 12:52:38,266-0500 INFO Cpu 0813-511229-000000:21 jobTerminated 2010-08-13 12:52:38,266-0500 INFO Cpu 0813-511229-000000:21 pull 2010-08-13 12:52:38,266-0500 INFO Cpu 0813-511229-000000:21 submitting urn:1281721866730-1281721891415-1281721891416 2010-08-13 12:52:38,266-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866730-1281721891415-1281721891416) setting status to Submitting 2010-08-13 12:52:38,331-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866663-1281721891313-1281721891314) setting status to Completed 2010-08-13 12:52:38,332-0500 INFO Cpu 0813-511229-000000:25 jobTerminated 2010-08-13 12:52:38,332-0500 INFO Cpu 0813-511229-000000:25 pull 2010-08-13 12:52:38,332-0500 INFO Cpu 0813-511229-000000:25 submitting urn:1281721866733-1281721891418-1281721891419 2010-08-13 12:52:38,332-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866733-1281721891418-1281721891419) setting status to Submitting 2010-08-13 12:52:38,353-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866721-1281721891409-1281721891410) setting status to Submitted 2010-08-13 12:52:38,353-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866673-1281721891325-1281721891326) setting status to Completed 2010-08-13 12:52:38,354-0500 INFO Cpu 0813-511229-000000:24 jobTerminated 2010-08-13 12:52:38,354-0500 INFO Cpu 0813-511229-000000:24 pull 2010-08-13 12:52:38,354-0500 INFO Cpu 0813-511229-000000:24 submitting urn:1281721866734-1281721891421-1281721891422 2010-08-13 12:52:38,354-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866734-1281721891421-1281721891422) setting status to Submitting 2010-08-13 12:52:38,398-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866721-1281721891409-1281721891410) setting status to Active workerid=000027 2010-08-13 12:52:38,420-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866729-1281721891412-1281721891413) setting status to Submitted 2010-08-13 12:52:38,464-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866729-1281721891412-1281721891413) setting status to Active workerid=000031 2010-08-13 12:52:38,487-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866730-1281721891415-1281721891416) setting status to Submitted 2010-08-13 12:52:38,510-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866730-1281721891415-1281721891416) setting status to Active workerid=000021 2010-08-13 12:52:38,554-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866734-1281721891421-1281721891422) setting status to Submitted 2010-08-13 12:52:38,555-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866733-1281721891418-1281721891419) setting status to Submitted 2010-08-13 12:52:38,576-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866733-1281721891418-1281721891419) setting status to Active workerid=000025 2010-08-13 12:52:38,599-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866734-1281721891421-1281721891422) setting status to Active workerid=000024 2010-08-13 12:52:38,621-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866671-1281721891328-1281721891329) setting status to Completed 2010-08-13 12:52:38,621-0500 INFO Cpu 0813-511229-000000:28 jobTerminated 2010-08-13 12:52:38,622-0500 INFO Cpu 0813-511229-000000:28 pull 2010-08-13 12:52:38,622-0500 INFO Cpu 0813-511229-000000:28 submitting urn:1281721866737-1281721891424-1281721891425 2010-08-13 12:52:38,622-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866737-1281721891424-1281721891425) setting status to Submitting 2010-08-13 12:52:38,643-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866675-1281721891331-1281721891332) setting status to Completed 2010-08-13 12:52:38,643-0500 INFO Cpu 0813-511229-000000:29 jobTerminated 2010-08-13 12:52:38,643-0500 INFO Cpu 0813-511229-000000:29 pull 2010-08-13 12:52:38,644-0500 INFO Cpu 0813-511229-000000:29 submitting urn:1281721866740-1281721891427-1281721891428 2010-08-13 12:52:38,644-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866740-1281721891427-1281721891428) setting status to Submitting 2010-08-13 12:52:38,843-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866737-1281721891424-1281721891425) setting status to Submitted 2010-08-13 12:52:38,843-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866740-1281721891427-1281721891428) setting status to Submitted 2010-08-13 12:52:38,865-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866679-1281721891334-1281721891335) setting status to Completed 2010-08-13 12:52:38,866-0500 INFO Cpu 0813-511229-000000:26 jobTerminated 2010-08-13 12:52:38,866-0500 INFO Cpu 0813-511229-000000:26 pull 2010-08-13 12:52:38,866-0500 INFO Cpu 0813-511229-000000:26 submitting urn:1281721866742-1281721891430-1281721891431 2010-08-13 12:52:38,866-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866742-1281721891430-1281721891431) setting status to Submitting 2010-08-13 12:52:38,888-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866737-1281721891424-1281721891425) setting status to Active workerid=000028 2010-08-13 12:52:38,888-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866740-1281721891427-1281721891428) setting status to Active workerid=000029 2010-08-13 12:52:39,087-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866742-1281721891430-1281721891431) setting status to Submitted 2010-08-13 12:52:39,109-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866742-1281721891430-1281721891431) setting status to Active workerid=000026 2010-08-13 12:52:39,283-0500 INFO BlockQueueProcessor Updated allocsize: 139.5838849249077 2010-08-13 12:52:39,283-0500 INFO BlockQueueProcessor allocsize = 139.5838849249077, queuedsize = 58.203149360872416, qsz = 54 2010-08-13 12:52:39,283-0500 INFO BlockQueueProcessor Plan time: 0 2010-08-13 12:52:41,060-0500 INFO CoasterService Idle time: 0 2010-08-13 12:52:41,158-0500 INFO TaskNotifier Congestion queue size: 0 2010-08-13 12:52:41,486-0500 INFO BlockQueueProcessor Updated allocsize: 139.58368912875278 2010-08-13 12:52:41,486-0500 INFO BlockQueueProcessor allocsize = 139.58368912875278, queuedsize = 58.203149360872416, qsz = 54 2010-08-13 12:52:41,486-0500 INFO BlockQueueProcessor Plan time: 0 2010-08-13 12:52:43,689-0500 INFO BlockQueueProcessor Updated allocsize: 139.58355853748606 2010-08-13 12:52:43,689-0500 INFO BlockQueueProcessor allocsize = 139.58355853748606, queuedsize = 58.203149360872416, qsz = 54 2010-08-13 12:52:43,689-0500 INFO BlockQueueProcessor Plan time: 0 2010-08-13 12:52:44,546-0500 INFO Cpu runTime: 16, sleepTime: 9994 2010-08-13 12:52:45,891-0500 INFO BlockQueueProcessor Updated allocsize: 139.5834278977798 2010-08-13 12:52:45,892-0500 INFO BlockQueueProcessor allocsize = 139.5834278977798, queuedsize = 58.203149360872416, qsz = 54 2010-08-13 12:52:45,892-0500 INFO BlockQueueProcessor Plan time: 1 2010-08-13 12:52:47,519-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866677-1281721891337-1281721891338) setting status to Completed 2010-08-13 12:52:47,520-0500 INFO Cpu 0813-511229-000000:0 jobTerminated 2010-08-13 12:52:47,520-0500 INFO Cpu 0813-511229-000000:0 pull 2010-08-13 12:52:47,520-0500 INFO Cpu 0813-511229-000000:0 submitting urn:1281721866744-1281721891433-1281721891434 2010-08-13 12:52:47,520-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866744-1281721891433-1281721891434) setting status to Submitting 2010-08-13 12:52:47,720-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866744-1281721891433-1281721891434) setting status to Submitted 2010-08-13 12:52:47,720-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866681-1281721891340-1281721891341) setting status to Completed 2010-08-13 12:52:47,720-0500 INFO Cpu 0813-511229-000000:2 jobTerminated 2010-08-13 12:52:47,720-0500 INFO Cpu 0813-511229-000000:2 pull 2010-08-13 12:52:47,720-0500 INFO Cpu 0813-511229-000000:2 submitting urn:1281721866746-1281721891436-1281721891437 2010-08-13 12:52:47,720-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866746-1281721891436-1281721891437) setting status to Submitting 2010-08-13 12:52:47,742-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866685-1281721891343-1281721891344) setting status to Completed 2010-08-13 12:52:47,742-0500 INFO Cpu 0813-511229-000000:5 jobTerminated 2010-08-13 12:52:47,742-0500 INFO Cpu 0813-511229-000000:5 pull 2010-08-13 12:52:47,743-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866683-1281721891346-1281721891347) setting status to Completed 2010-08-13 12:52:47,743-0500 INFO Cpu 0813-511229-000000:5 submitting urn:1281721866748-1281721891439-1281721891440 2010-08-13 12:52:47,743-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866748-1281721891439-1281721891440) setting status to Submitting 2010-08-13 12:52:47,743-0500 INFO Cpu 0813-511229-000000:4 jobTerminated 2010-08-13 12:52:47,743-0500 INFO Cpu 0813-511229-000000:4 pull 2010-08-13 12:52:47,743-0500 INFO Cpu 0813-511229-000000:4 submitting urn:1281721866750-1281721891442-1281721891443 2010-08-13 12:52:47,743-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866750-1281721891442-1281721891443) setting status to Submitting 2010-08-13 12:52:47,766-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866691-1281721891352-1281721891353) setting status to Completed 2010-08-13 12:52:47,766-0500 INFO Cpu 0813-511229-000000:7 jobTerminated 2010-08-13 12:52:47,766-0500 INFO Cpu 0813-511229-000000:7 pull 2010-08-13 12:52:47,766-0500 INFO Cpu 0813-511229-000000:7 submitting urn:1281721866752-1281721891445-1281721891446 2010-08-13 12:52:47,766-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866752-1281721891445-1281721891446) setting status to Submitting 2010-08-13 12:52:47,766-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866744-1281721891433-1281721891434) setting status to Active workerid=000000 2010-08-13 12:52:47,921-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866746-1281721891436-1281721891437) setting status to Submitted 2010-08-13 12:52:47,922-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866693-1281721891355-1281721891356) setting status to Completed 2010-08-13 12:52:47,922-0500 INFO Cpu 0813-511229-000000:8 jobTerminated 2010-08-13 12:52:47,922-0500 INFO Cpu 0813-511229-000000:8 pull 2010-08-13 12:52:47,922-0500 INFO Cpu 0813-511229-000000:8 submitting urn:1281721866688-1281721891448-1281721891449 2010-08-13 12:52:47,922-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866688-1281721891448-1281721891449) setting status to Submitting 2010-08-13 12:52:47,944-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866748-1281721891439-1281721891440) setting status to Submitted 2010-08-13 12:52:47,944-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866609-1281721891364-1281721891365) setting status to Completed 2010-08-13 12:52:47,944-0500 INFO Cpu 0813-511229-000000:3 jobTerminated 2010-08-13 12:52:47,944-0500 INFO Cpu 0813-511229-000000:3 pull 2010-08-13 12:52:47,944-0500 INFO Cpu 0813-511229-000000:3 submitting urn:1281721866758-1281721891451-1281721891452 2010-08-13 12:52:47,944-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866758-1281721891451-1281721891452) setting status to Submitting 2010-08-13 12:52:47,966-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866750-1281721891442-1281721891443) setting status to Submitted 2010-08-13 12:52:47,967-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866697-1281721891361-1281721891362) setting status to Completed 2010-08-13 12:52:47,967-0500 INFO Cpu 0813-511229-000000:10 jobTerminated 2010-08-13 12:52:47,967-0500 INFO Cpu 0813-511229-000000:10 pull 2010-08-13 12:52:47,967-0500 INFO Cpu 0813-511229-000000:10 submitting urn:1281721866756-1281721891454-1281721891455 2010-08-13 12:52:47,967-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866756-1281721891454-1281721891455) setting status to Submitting 2010-08-13 12:52:47,967-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866746-1281721891436-1281721891437) setting status to Active workerid=000002 2010-08-13 12:52:47,989-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866752-1281721891445-1281721891446) setting status to Submitted 2010-08-13 12:52:47,989-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866748-1281721891439-1281721891440) setting status to Active workerid=000005 2010-08-13 12:52:47,989-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866750-1281721891442-1281721891443) setting status to Active workerid=000004 2010-08-13 12:52:48,013-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866686-1281721891349-1281721891350) setting status to Completed 2010-08-13 12:52:48,013-0500 INFO Cpu 0813-511229-000000:6 jobTerminated 2010-08-13 12:52:48,013-0500 INFO Cpu 0813-511229-000000:6 pull 2010-08-13 12:52:48,013-0500 INFO Cpu 0813-511229-000000:6 submitting urn:1281721866760-1281721891457-1281721891458 2010-08-13 12:52:48,013-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866752-1281721891445-1281721891446) setting status to Active workerid=000007 2010-08-13 12:52:48,013-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866760-1281721891457-1281721891458) setting status to Submitting 2010-08-13 12:52:48,035-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866699-1281721891367-1281721891368) setting status to Completed 2010-08-13 12:52:48,035-0500 INFO Cpu 0813-511229-000000:11 jobTerminated 2010-08-13 12:52:48,035-0500 INFO Cpu 0813-511229-000000:11 pull 2010-08-13 12:52:48,036-0500 INFO Cpu 0813-511229-000000:11 submitting urn:1281721866762-1281721891460-1281721891461 2010-08-13 12:52:48,036-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866762-1281721891460-1281721891461) setting status to Submitting 2010-08-13 12:52:48,058-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866701-1281721891370-1281721891371) setting status to Completed 2010-08-13 12:52:48,058-0500 INFO Cpu 0813-511229-000000:12 jobTerminated 2010-08-13 12:52:48,058-0500 INFO Cpu 0813-511229-000000:12 pull 2010-08-13 12:52:48,058-0500 INFO Cpu 0813-511229-000000:12 submitting urn:1281721866766-1281721891463-1281721891464 2010-08-13 12:52:48,058-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866766-1281721891463-1281721891464) setting status to Submitting 2010-08-13 12:52:48,095-0500 INFO BlockQueueProcessor Updated allocsize: 139.58329720959787 2010-08-13 12:52:48,095-0500 INFO BlockQueueProcessor allocsize = 139.58329720959787, queuedsize = 46.34695226884284, qsz = 43 2010-08-13 12:52:48,095-0500 INFO BlockQueueProcessor Plan time: 0 2010-08-13 12:52:48,124-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866688-1281721891448-1281721891449) setting status to Submitted 2010-08-13 12:52:48,168-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866756-1281721891454-1281721891455) setting status to Submitted 2010-08-13 12:52:48,168-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866758-1281721891451-1281721891452) setting status to Submitted 2010-08-13 12:52:48,168-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866695-1281721891358-1281721891359) setting status to Completed 2010-08-13 12:52:48,169-0500 INFO Cpu 0813-511229-000000:1 jobTerminated 2010-08-13 12:52:48,169-0500 INFO Cpu 0813-511229-000000:1 pull 2010-08-13 12:52:48,169-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866688-1281721891448-1281721891449) setting status to Active workerid=000008 2010-08-13 12:52:48,169-0500 INFO Cpu 0813-511229-000000:1 submitting urn:1281721866764-1281721891466-1281721891467 2010-08-13 12:52:48,169-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866764-1281721891466-1281721891467) setting status to Submitting 2010-08-13 12:52:48,192-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866758-1281721891451-1281721891452) setting status to Active workerid=000003 2010-08-13 12:52:48,214-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866760-1281721891457-1281721891458) setting status to Submitted 2010-08-13 12:52:48,214-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866756-1281721891454-1281721891455) setting status to Active workerid=000010 2010-08-13 12:52:48,260-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866762-1281721891460-1281721891461) setting status to Submitted 2010-08-13 12:52:48,260-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866705-1281721891373-1281721891374) setting status to Completed 2010-08-13 12:52:48,261-0500 INFO Cpu 0813-511229-000000:9 jobTerminated 2010-08-13 12:52:48,261-0500 INFO Cpu 0813-511229-000000:9 pull 2010-08-13 12:52:48,261-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866760-1281721891457-1281721891458) setting status to Active workerid=000006 2010-08-13 12:52:48,261-0500 INFO Cpu 0813-511229-000000:9 submitting urn:1281721866768-1281721891469-1281721891470 2010-08-13 12:52:48,261-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866768-1281721891469-1281721891470) setting status to Submitting 2010-08-13 12:52:48,284-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866766-1281721891463-1281721891464) setting status to Submitted 2010-08-13 12:52:48,284-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866762-1281721891460-1281721891461) setting status to Active workerid=000011 2010-08-13 12:52:48,306-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866766-1281721891463-1281721891464) setting status to Active workerid=000012 2010-08-13 12:52:48,351-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866703-1281721891376-1281721891377) setting status to Completed 2010-08-13 12:52:48,352-0500 INFO Cpu 0813-511229-000000:13 jobTerminated 2010-08-13 12:52:48,352-0500 INFO Cpu 0813-511229-000000:13 pull 2010-08-13 12:52:48,352-0500 INFO Cpu 0813-511229-000000:13 submitting urn:1281721866770-1281721891472-1281721891473 2010-08-13 12:52:48,352-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866770-1281721891472-1281721891473) setting status to Submitting 2010-08-13 12:52:48,398-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866764-1281721891466-1281721891467) setting status to Submitted 2010-08-13 12:52:48,420-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866764-1281721891466-1281721891467) setting status to Active workerid=000001 2010-08-13 12:52:48,465-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866768-1281721891469-1281721891470) setting status to Submitted 2010-08-13 12:52:48,510-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866768-1281721891469-1281721891470) setting status to Active workerid=000009 2010-08-13 12:52:48,555-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866770-1281721891472-1281721891473) setting status to Submitted 2010-08-13 12:52:48,601-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866770-1281721891472-1281721891473) setting status to Active workerid=000013 2010-08-13 12:52:48,799-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866706-1281721891379-1281721891380) setting status to Completed 2010-08-13 12:52:48,800-0500 INFO Cpu 0813-511229-000000:23 jobTerminated 2010-08-13 12:52:48,800-0500 INFO Cpu 0813-511229-000000:23 pull 2010-08-13 12:52:48,800-0500 INFO Cpu 0813-511229-000000:23 submitting urn:1281721866774-1281721891475-1281721891476 2010-08-13 12:52:48,800-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866774-1281721891475-1281721891476) setting status to Submitting 2010-08-13 12:52:48,822-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866709-1281721891382-1281721891383) setting status to Completed 2010-08-13 12:52:48,822-0500 INFO Cpu 0813-511229-000000:22 jobTerminated 2010-08-13 12:52:48,822-0500 INFO Cpu 0813-511229-000000:22 pull 2010-08-13 12:52:48,822-0500 INFO Cpu 0813-511229-000000:22 submitting urn:1281721866772-1281721891478-1281721891479 2010-08-13 12:52:48,822-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866772-1281721891478-1281721891479) setting status to Submitting 2010-08-13 12:52:48,844-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866710-1281721891385-1281721891386) setting status to Completed 2010-08-13 12:52:48,845-0500 INFO Cpu 0813-511229-000000:14 jobTerminated 2010-08-13 12:52:48,845-0500 INFO Cpu 0813-511229-000000:14 pull 2010-08-13 12:52:48,845-0500 INFO Cpu 0813-511229-000000:14 submitting urn:1281721866776-1281721891481-1281721891482 2010-08-13 12:52:48,845-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866776-1281721891481-1281721891482) setting status to Submitting 2010-08-13 12:52:48,912-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866713-1281721891388-1281721891389) setting status to Completed 2010-08-13 12:52:48,912-0500 INFO Cpu 0813-511229-000000:30 jobTerminated 2010-08-13 12:52:48,912-0500 INFO Cpu 0813-511229-000000:30 pull 2010-08-13 12:52:48,912-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866717-1281721891394-1281721891395) setting status to Completed 2010-08-13 12:52:48,912-0500 INFO Cpu 0813-511229-000000:30 submitting urn:1281721866780-1281721891484-1281721891485 2010-08-13 12:52:48,912-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866780-1281721891484-1281721891485) setting status to Submitting 2010-08-13 12:52:48,912-0500 INFO Cpu 0813-511229-000000:16 jobTerminated 2010-08-13 12:52:48,913-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866714-1281721891391-1281721891392) setting status to Completed 2010-08-13 12:52:48,913-0500 INFO Cpu 0813-511229-000000:16 pull 2010-08-13 12:52:48,913-0500 INFO Cpu 0813-511229-000000:15 jobTerminated 2010-08-13 12:52:48,913-0500 INFO Cpu 0813-511229-000000:16 submitting urn:1281721866778-1281721891487-1281721891488 2010-08-13 12:52:48,913-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866778-1281721891487-1281721891488) setting status to Submitting 2010-08-13 12:52:48,913-0500 INFO Cpu 0813-511229-000000:15 pull 2010-08-13 12:52:48,914-0500 INFO Cpu 0813-511229-000000:15 submitting urn:1281721866782-1281721891490-1281721891491 2010-08-13 12:52:48,914-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866782-1281721891490-1281721891491) setting status to Submitting 2010-08-13 12:52:48,936-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866722-1281721891400-1281721891401) setting status to Completed 2010-08-13 12:52:48,936-0500 INFO Cpu 0813-511229-000000:18 jobTerminated 2010-08-13 12:52:48,936-0500 INFO Cpu 0813-511229-000000:18 pull 2010-08-13 12:52:48,936-0500 INFO Cpu 0813-511229-000000:18 submitting urn:1281721866784-1281721891493-1281721891494 2010-08-13 12:52:48,936-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866718-1281721891397-1281721891398) setting status to Completed 2010-08-13 12:52:48,936-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866784-1281721891493-1281721891494) setting status to Submitting 2010-08-13 12:52:48,936-0500 INFO Cpu 0813-511229-000000:17 jobTerminated 2010-08-13 12:52:48,937-0500 INFO Cpu 0813-511229-000000:17 pull 2010-08-13 12:52:48,937-0500 INFO Cpu 0813-511229-000000:17 submitting urn:1281721866788-1281721891496-1281721891497 2010-08-13 12:52:48,937-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866788-1281721891496-1281721891497) setting status to Submitting 2010-08-13 12:52:48,959-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866726-1281721891406-1281721891407) setting status to Completed 2010-08-13 12:52:48,960-0500 INFO Cpu 0813-511229-000000:19 jobTerminated 2010-08-13 12:52:48,960-0500 INFO Cpu 0813-511229-000000:19 pull 2010-08-13 12:52:48,960-0500 INFO Cpu 0813-511229-000000:19 submitting urn:1281721866790-1281721891499-1281721891500 2010-08-13 12:52:48,960-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866790-1281721891499-1281721891500) setting status to Submitting 2010-08-13 12:52:48,982-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866725-1281721891403-1281721891404) setting status to Completed 2010-08-13 12:52:48,982-0500 INFO Cpu 0813-511229-000000:20 jobTerminated 2010-08-13 12:52:48,982-0500 INFO Cpu 0813-511229-000000:20 pull 2010-08-13 12:52:48,983-0500 INFO Cpu 0813-511229-000000:20 submitting urn:1281721866786-1281721891502-1281721891503 2010-08-13 12:52:48,983-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866786-1281721891502-1281721891503) setting status to Submitting 2010-08-13 12:52:49,026-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866774-1281721891475-1281721891476) setting status to Submitted 2010-08-13 12:52:49,027-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866772-1281721891478-1281721891479) setting status to Submitted 2010-08-13 12:52:49,049-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866774-1281721891475-1281721891476) setting status to Active workerid=000023 2010-08-13 12:52:49,072-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866776-1281721891481-1281721891482) setting status to Submitted 2010-08-13 12:52:49,072-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866772-1281721891478-1281721891479) setting status to Active workerid=000022 2010-08-13 12:52:49,095-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866776-1281721891481-1281721891482) setting status to Active workerid=000014 2010-08-13 12:52:49,117-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866780-1281721891484-1281721891485) setting status to Submitted 2010-08-13 12:52:49,117-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866778-1281721891487-1281721891488) setting status to Submitted 2010-08-13 12:52:49,117-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866782-1281721891490-1281721891491) setting status to Submitted 2010-08-13 12:52:49,139-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866788-1281721891496-1281721891497) setting status to Submitted 2010-08-13 12:52:49,161-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866784-1281721891493-1281721891494) setting status to Submitted 2010-08-13 12:52:49,162-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866721-1281721891409-1281721891410) setting status to Completed 2010-08-13 12:52:49,162-0500 INFO Cpu 0813-511229-000000:27 jobTerminated 2010-08-13 12:52:49,162-0500 INFO Cpu 0813-511229-000000:27 pull 2010-08-13 12:52:49,162-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866780-1281721891484-1281721891485) setting status to Active workerid=000030 2010-08-13 12:52:49,162-0500 INFO Cpu 0813-511229-000000:27 submitting urn:1281721866792-1281721891505-1281721891506 2010-08-13 12:52:49,162-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866778-1281721891487-1281721891488) setting status to Active workerid=000016 2010-08-13 12:52:49,162-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866792-1281721891505-1281721891506) setting status to Submitting 2010-08-13 12:52:49,163-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866782-1281721891490-1281721891491) setting status to Active workerid=000015 2010-08-13 12:52:49,185-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866786-1281721891502-1281721891503) setting status to Submitted 2010-08-13 12:52:49,185-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866790-1281721891499-1281721891500) setting status to Submitted 2010-08-13 12:52:49,186-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866784-1281721891493-1281721891494) setting status to Active workerid=000018 2010-08-13 12:52:49,186-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866788-1281721891496-1281721891497) setting status to Active workerid=000017 2010-08-13 12:52:49,208-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866729-1281721891412-1281721891413) setting status to Completed 2010-08-13 12:52:49,208-0500 INFO Cpu 0813-511229-000000:31 jobTerminated 2010-08-13 12:52:49,208-0500 INFO Cpu 0813-511229-000000:31 pull 2010-08-13 12:52:49,208-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866790-1281721891499-1281721891500) setting status to Active workerid=000019 2010-08-13 12:52:49,208-0500 INFO Cpu 0813-511229-000000:31 submitting urn:1281721866754-1281721891508-1281721891509 2010-08-13 12:52:49,209-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866754-1281721891508-1281721891509) setting status to Submitting 2010-08-13 12:52:49,232-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866786-1281721891502-1281721891503) setting status to Active workerid=000020 2010-08-13 12:52:49,276-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866730-1281721891415-1281721891416) setting status to Completed 2010-08-13 12:52:49,277-0500 INFO Cpu 0813-511229-000000:21 jobTerminated 2010-08-13 12:52:49,277-0500 INFO Cpu 0813-511229-000000:21 pull 2010-08-13 12:52:49,277-0500 INFO Cpu 0813-511229-000000:21 submitting urn:1281721866738-1281721891511-1281721891512 2010-08-13 12:52:49,277-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866738-1281721891511-1281721891512) setting status to Submitting 2010-08-13 12:52:49,343-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866734-1281721891421-1281721891422) setting status to Completed 2010-08-13 12:52:49,343-0500 INFO Cpu 0813-511229-000000:24 jobTerminated 2010-08-13 12:52:49,343-0500 INFO Cpu 0813-511229-000000:24 pull 2010-08-13 12:52:49,344-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866733-1281721891418-1281721891419) setting status to Completed 2010-08-13 12:52:49,344-0500 INFO Cpu 0813-511229-000000:24 submitting urn:1281721866542-1281721891514-1281721891515 2010-08-13 12:52:49,344-0500 INFO Cpu 0813-511229-000000:25 jobTerminated 2010-08-13 12:52:49,344-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866542-1281721891514-1281721891515) setting status to Submitting 2010-08-13 12:52:49,344-0500 INFO Cpu 0813-511229-000000:25 pull 2010-08-13 12:52:49,344-0500 INFO Cpu 0813-511229-000000:25 submitting urn:1281721866539-1281721891130-1281721891131 2010-08-13 12:52:49,344-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866539-1281721891130-1281721891131) setting status to Submitting 2010-08-13 12:52:49,366-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866792-1281721891505-1281721891506) setting status to Submitted 2010-08-13 12:52:49,411-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866792-1281721891505-1281721891506) setting status to Active workerid=000027 2010-08-13 12:52:49,435-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866754-1281721891508-1281721891509) setting status to Submitted 2010-08-13 12:52:49,458-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866754-1281721891508-1281721891509) setting status to Active workerid=000031 2010-08-13 12:52:49,480-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866738-1281721891511-1281721891512) setting status to Submitted 2010-08-13 12:52:49,525-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866738-1281721891511-1281721891512) setting status to Active workerid=000021 2010-08-13 12:52:49,547-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866539-1281721891130-1281721891131) setting status to Submitted 2010-08-13 12:52:49,570-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866542-1281721891514-1281721891515) setting status to Submitted 2010-08-13 12:52:49,594-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866542-1281721891514-1281721891515) setting status to Active workerid=000024 2010-08-13 12:52:49,594-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866539-1281721891130-1281721891131) setting status to Active workerid=000025 2010-08-13 12:52:49,639-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866737-1281721891424-1281721891425) setting status to Completed 2010-08-13 12:52:49,640-0500 INFO Cpu 0813-511229-000000:28 jobTerminated 2010-08-13 12:52:49,640-0500 INFO Cpu 0813-511229-000000:28 pull 2010-08-13 12:52:49,640-0500 INFO Cpu 0813-511229-000000:28 submitting urn:1281721866541-1281721891133-1281721891134 2010-08-13 12:52:49,640-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866541-1281721891133-1281721891134) setting status to Submitting 2010-08-13 12:52:49,838-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866541-1281721891133-1281721891134) setting status to Submitted 2010-08-13 12:52:49,882-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866541-1281721891133-1281721891134) setting status to Active workerid=000028 2010-08-13 12:52:49,904-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866740-1281721891427-1281721891428) setting status to Completed 2010-08-13 12:52:49,904-0500 INFO Cpu 0813-511229-000000:29 jobTerminated 2010-08-13 12:52:49,904-0500 INFO Cpu 0813-511229-000000:29 pull 2010-08-13 12:52:49,904-0500 INFO Cpu 0813-511229-000000:29 submitting urn:1281721866544-1281721891136-1281721891137 2010-08-13 12:52:49,904-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866544-1281721891136-1281721891137) setting status to Submitting 2010-08-13 12:52:50,104-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866544-1281721891136-1281721891137) setting status to Submitted 2010-08-13 12:52:50,127-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866742-1281721891430-1281721891431) setting status to Completed 2010-08-13 12:52:50,127-0500 INFO Cpu 0813-511229-000000:26 jobTerminated 2010-08-13 12:52:50,127-0500 INFO Cpu 0813-511229-000000:26 pull 2010-08-13 12:52:50,127-0500 INFO Cpu 0813-511229-000000:26 submitting urn:1281721866548-1281721891139-1281721891140 2010-08-13 12:52:50,127-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866548-1281721891139-1281721891140) setting status to Submitting 2010-08-13 12:52:50,149-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866544-1281721891136-1281721891137) setting status to Active workerid=000029 2010-08-13 12:52:50,297-0500 INFO BlockQueueProcessor Updated allocsize: 139.5831664729041 2010-08-13 12:52:50,297-0500 INFO BlockQueueProcessor allocsize = 139.5831664729041, queuedsize = 23.712394184059107, qsz = 22 2010-08-13 12:52:50,297-0500 INFO BlockQueueProcessor Plan time: 0 2010-08-13 12:52:50,327-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866548-1281721891139-1281721891140) setting status to Submitted 2010-08-13 12:52:50,371-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866548-1281721891139-1281721891140) setting status to Active workerid=000026 2010-08-13 12:52:51,063-0500 INFO CoasterService Idle time: 0 2010-08-13 12:52:51,161-0500 INFO TaskNotifier Congestion queue size: 0 2010-08-13 12:52:52,499-0500 INFO BlockQueueProcessor Updated allocsize: 139.58297027682457 2010-08-13 12:52:52,499-0500 INFO BlockQueueProcessor allocsize = 139.58297027682457, queuedsize = 23.712394184059107, qsz = 22 2010-08-13 12:52:52,499-0500 INFO BlockQueueProcessor Plan time: 0 2010-08-13 12:52:54,561-0500 INFO Cpu runTime: 16, sleepTime: 9999 2010-08-13 12:52:54,701-0500 INFO BlockQueueProcessor Updated allocsize: 139.5828394186927 2010-08-13 12:52:54,702-0500 INFO BlockQueueProcessor allocsize = 139.5828394186927, queuedsize = 23.712394184059107, qsz = 22 2010-08-13 12:52:54,702-0500 INFO BlockQueueProcessor Plan time: 1 2010-08-13 12:52:56,904-0500 INFO BlockQueueProcessor Updated allocsize: 139.5827085119221 2010-08-13 12:52:56,904-0500 INFO BlockQueueProcessor allocsize = 139.5827085119221, queuedsize = 23.712394184059107, qsz = 22 2010-08-13 12:52:56,904-0500 INFO BlockQueueProcessor Plan time: 0 2010-08-13 12:52:58,724-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866746-1281721891436-1281721891437) setting status to Completed 2010-08-13 12:52:58,724-0500 INFO Cpu 0813-511229-000000:2 jobTerminated 2010-08-13 12:52:58,724-0500 INFO Cpu 0813-511229-000000:2 pull 2010-08-13 12:52:58,725-0500 INFO Cpu 0813-511229-000000:2 submitting urn:1281721866546-1281721891142-1281721891143 2010-08-13 12:52:58,725-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866546-1281721891142-1281721891143) setting status to Submitting 2010-08-13 12:52:58,747-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866748-1281721891439-1281721891440) setting status to Completed 2010-08-13 12:52:58,747-0500 INFO Cpu 0813-511229-000000:5 jobTerminated 2010-08-13 12:52:58,747-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866750-1281721891442-1281721891443) setting status to Completed 2010-08-13 12:52:58,747-0500 INFO Cpu 0813-511229-000000:5 pull 2010-08-13 12:52:58,747-0500 INFO Cpu 0813-511229-000000:5 submitting urn:1281721866883-1281721891521-1281721891522 2010-08-13 12:52:58,748-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866883-1281721891521-1281721891522) setting status to Submitting 2010-08-13 12:52:58,748-0500 INFO Cpu 0813-511229-000000:4 jobTerminated 2010-08-13 12:52:58,748-0500 INFO Cpu 0813-511229-000000:4 pull 2010-08-13 12:52:58,748-0500 INFO Cpu 0813-511229-000000:4 submitting urn:1281721866879-1281721891524-1281721891525 2010-08-13 12:52:58,748-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866879-1281721891524-1281721891525) setting status to Submitting 2010-08-13 12:52:58,770-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866744-1281721891433-1281721891434) setting status to Completed 2010-08-13 12:52:58,770-0500 INFO Cpu 0813-511229-000000:0 jobTerminated 2010-08-13 12:52:58,771-0500 INFO Cpu 0813-511229-000000:0 pull 2010-08-13 12:52:58,771-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866752-1281721891445-1281721891446) setting status to Completed 2010-08-13 12:52:58,772-0500 INFO Cpu 0813-511229-000000:7 jobTerminated 2010-08-13 12:52:58,772-0500 INFO Cpu 0813-511229-000000:0 submitting urn:1281721866910-1281721891527-1281721891528 2010-08-13 12:52:58,772-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866910-1281721891527-1281721891528) setting status to Submitting 2010-08-13 12:52:58,772-0500 INFO Cpu 0813-511229-000000:7 pull 2010-08-13 12:52:58,773-0500 INFO Cpu 0813-511229-000000:7 submitting urn:1281721866912-1281721891530-1281721891531 2010-08-13 12:52:58,773-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866912-1281721891530-1281721891531) setting status to Submitting 2010-08-13 12:52:58,927-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866546-1281721891142-1281721891143) setting status to Submitted 2010-08-13 12:52:58,928-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866688-1281721891448-1281721891449) setting status to Completed 2010-08-13 12:52:58,928-0500 INFO Cpu 0813-511229-000000:8 jobTerminated 2010-08-13 12:52:58,928-0500 INFO Cpu 0813-511229-000000:8 pull 2010-08-13 12:52:58,928-0500 INFO Cpu 0813-511229-000000:8 submitting urn:1281721866928-1281721891533-1281721891534 2010-08-13 12:52:58,928-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866928-1281721891533-1281721891534) setting status to Submitting 2010-08-13 12:52:58,950-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866883-1281721891521-1281721891522) setting status to Submitted 2010-08-13 12:52:58,950-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866758-1281721891451-1281721891452) setting status to Completed 2010-08-13 12:52:58,951-0500 INFO Cpu 0813-511229-000000:3 jobTerminated 2010-08-13 12:52:58,951-0500 INFO Cpu 0813-511229-000000:3 pull 2010-08-13 12:52:58,951-0500 INFO Cpu 0813-511229-000000:3 submitting urn:1281721866930-1281721891536-1281721891537 2010-08-13 12:52:58,951-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866930-1281721891536-1281721891537) setting status to Submitting 2010-08-13 12:52:58,951-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866756-1281721891454-1281721891455) setting status to Completed 2010-08-13 12:52:58,951-0500 INFO Cpu 0813-511229-000000:10 jobTerminated 2010-08-13 12:52:58,951-0500 INFO Cpu 0813-511229-000000:10 pull 2010-08-13 12:52:58,952-0500 INFO Cpu 0813-511229-000000:10 submitting urn:1281721866942-1281721891539-1281721891540 2010-08-13 12:52:58,952-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866942-1281721891539-1281721891540) setting status to Submitting 2010-08-13 12:52:58,974-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866910-1281721891527-1281721891528) setting status to Submitted 2010-08-13 12:52:58,974-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866879-1281721891524-1281721891525) setting status to Submitted 2010-08-13 12:52:58,975-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866546-1281721891142-1281721891143) setting status to Active workerid=000002 2010-08-13 12:52:58,997-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866912-1281721891530-1281721891531) setting status to Submitted 2010-08-13 12:52:58,997-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866883-1281721891521-1281721891522) setting status to Active workerid=000005 2010-08-13 12:52:58,998-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866879-1281721891524-1281721891525) setting status to Active workerid=000004 2010-08-13 12:52:59,020-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866760-1281721891457-1281721891458) setting status to Completed 2010-08-13 12:52:59,020-0500 INFO Cpu 0813-511229-000000:6 jobTerminated 2010-08-13 12:52:59,020-0500 INFO Cpu 0813-511229-000000:6 pull 2010-08-13 12:52:59,020-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866910-1281721891527-1281721891528) setting status to Active workerid=000000 2010-08-13 12:52:59,020-0500 INFO Cpu 0813-511229-000000:6 submitting urn:1281721866948-1281721891542-1281721891543 2010-08-13 12:52:59,020-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866948-1281721891542-1281721891543) setting status to Submitting 2010-08-13 12:52:59,020-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866912-1281721891530-1281721891531) setting status to Active workerid=000007 2010-08-13 12:52:59,043-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866762-1281721891460-1281721891461) setting status to Completed 2010-08-13 12:52:59,043-0500 INFO Cpu 0813-511229-000000:11 jobTerminated 2010-08-13 12:52:59,043-0500 INFO Cpu 0813-511229-000000:11 pull 2010-08-13 12:52:59,043-0500 INFO Cpu 0813-511229-000000:11 submitting urn:1281721866960-1281721891545-1281721891546 2010-08-13 12:52:59,044-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866960-1281721891545-1281721891546) setting status to Submitting 2010-08-13 12:52:59,066-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866766-1281721891463-1281721891464) setting status to Completed 2010-08-13 12:52:59,066-0500 INFO Cpu 0813-511229-000000:12 jobTerminated 2010-08-13 12:52:59,066-0500 INFO Cpu 0813-511229-000000:12 pull 2010-08-13 12:52:59,066-0500 INFO Cpu 0813-511229-000000:12 submitting urn:1281721866966-1281721891548-1281721891549 2010-08-13 12:52:59,066-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866966-1281721891548-1281721891549) setting status to Submitting 2010-08-13 12:52:59,106-0500 INFO BlockQueueProcessor Updated allocsize: 139.5825775564764 2010-08-13 12:52:59,106-0500 INFO BlockQueueProcessor allocsize = 139.5825775564764, queuedsize = 11.85619709202954, qsz = 11 2010-08-13 12:52:59,106-0500 INFO BlockQueueProcessor Plan time: 1 2010-08-13 12:52:59,132-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866928-1281721891533-1281721891534) setting status to Submitted 2010-08-13 12:52:59,155-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866930-1281721891536-1281721891537) setting status to Submitted 2010-08-13 12:52:59,178-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866942-1281721891539-1281721891540) setting status to Submitted 2010-08-13 12:52:59,178-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866764-1281721891466-1281721891467) setting status to Completed 2010-08-13 12:52:59,178-0500 INFO Cpu 0813-511229-000000:1 jobTerminated 2010-08-13 12:52:59,179-0500 INFO Cpu 0813-511229-000000:1 pull 2010-08-13 12:52:59,179-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866928-1281721891533-1281721891534) setting status to Active workerid=000008 2010-08-13 12:52:59,179-0500 INFO Cpu 0813-511229-000000:1 submitting urn:1281721866993-1281721891551-1281721891552 2010-08-13 12:52:59,179-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866993-1281721891551-1281721891552) setting status to Submitting 2010-08-13 12:52:59,201-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866942-1281721891539-1281721891540) setting status to Active workerid=000010 2010-08-13 12:52:59,202-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866930-1281721891536-1281721891537) setting status to Active workerid=000003 2010-08-13 12:52:59,224-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866948-1281721891542-1281721891543) setting status to Submitted 2010-08-13 12:52:59,245-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866960-1281721891545-1281721891546) setting status to Submitted 2010-08-13 12:52:59,268-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866948-1281721891542-1281721891543) setting status to Active workerid=000006 2010-08-13 12:52:59,291-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866966-1281721891548-1281721891549) setting status to Submitted 2010-08-13 12:52:59,291-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866960-1281721891545-1281721891546) setting status to Active workerid=000011 2010-08-13 12:52:59,314-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866966-1281721891548-1281721891549) setting status to Active workerid=000012 2010-08-13 12:52:59,402-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866993-1281721891551-1281721891552) setting status to Submitted 2010-08-13 12:52:59,425-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866993-1281721891551-1281721891552) setting status to Active workerid=000001 2010-08-13 12:52:59,514-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866768-1281721891469-1281721891470) setting status to Completed 2010-08-13 12:52:59,515-0500 INFO Cpu 0813-511229-000000:9 jobTerminated 2010-08-13 12:52:59,515-0500 INFO Cpu 0813-511229-000000:9 pull 2010-08-13 12:52:59,515-0500 INFO Cpu 0813-511229-000000:9 submitting urn:1281721867007-1281721891554-1281721891555 2010-08-13 12:52:59,515-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867007-1281721891554-1281721891555) setting status to Submitting 2010-08-13 12:52:59,603-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866770-1281721891472-1281721891473) setting status to Completed 2010-08-13 12:52:59,603-0500 INFO Cpu 0813-511229-000000:13 jobTerminated 2010-08-13 12:52:59,603-0500 INFO Cpu 0813-511229-000000:13 pull 2010-08-13 12:52:59,603-0500 INFO Cpu 0813-511229-000000:13 submitting urn:1281721867009-1281721891557-1281721891558 2010-08-13 12:52:59,603-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867009-1281721891557-1281721891558) setting status to Submitting 2010-08-13 12:52:59,736-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867007-1281721891554-1281721891555) setting status to Submitted 2010-08-13 12:52:59,780-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867007-1281721891554-1281721891555) setting status to Active workerid=000009 2010-08-13 12:52:59,801-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867009-1281721891557-1281721891558) setting status to Submitted 2010-08-13 12:52:59,824-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866772-1281721891478-1281721891479) setting status to Completed 2010-08-13 12:52:59,824-0500 INFO Cpu 0813-511229-000000:22 jobTerminated 2010-08-13 12:52:59,825-0500 INFO Cpu 0813-511229-000000:22 pull 2010-08-13 12:52:59,825-0500 INFO Cpu 0813-511229-000000:22 submitting urn:1281721867033-1281721891560-1281721891561 2010-08-13 12:52:59,825-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867033-1281721891560-1281721891561) setting status to Submitting 2010-08-13 12:52:59,847-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867009-1281721891557-1281721891558) setting status to Active workerid=000013 2010-08-13 12:52:59,914-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866780-1281721891484-1281721891485) setting status to Completed 2010-08-13 12:52:59,914-0500 INFO Cpu 0813-511229-000000:30 jobTerminated 2010-08-13 12:52:59,914-0500 INFO Cpu 0813-511229-000000:30 pull 2010-08-13 12:52:59,914-0500 INFO Cpu 0813-511229-000000:30 submitting urn:1281721867079-1281721891563-1281721891564 2010-08-13 12:52:59,914-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867079-1281721891563-1281721891564) setting status to Submitting 2010-08-13 12:52:59,914-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866778-1281721891487-1281721891488) setting status to Completed 2010-08-13 12:52:59,915-0500 INFO Cpu 0813-511229-000000:16 jobTerminated 2010-08-13 12:52:59,915-0500 INFO Cpu 0813-511229-000000:16 pull 2010-08-13 12:52:59,915-0500 INFO Cpu 0813-511229-000000:16 submitting urn:1281721867081-1281721891566-1281721891567 2010-08-13 12:52:59,915-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867081-1281721891566-1281721891567) setting status to Submitting 2010-08-13 12:52:59,915-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866782-1281721891490-1281721891491) setting status to Completed 2010-08-13 12:52:59,915-0500 INFO Cpu 0813-511229-000000:15 jobTerminated 2010-08-13 12:52:59,916-0500 INFO Cpu 0813-511229-000000:15 pull 2010-08-13 12:52:59,916-0500 INFO Cpu 0813-511229-000000:15 submitting urn:1281721867096-1281721891569-1281721891570 2010-08-13 12:52:59,916-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867096-1281721891569-1281721891570) setting status to Submitting 2010-08-13 12:52:59,938-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866788-1281721891496-1281721891497) setting status to Completed 2010-08-13 12:52:59,938-0500 INFO Cpu 0813-511229-000000:17 jobTerminated 2010-08-13 12:52:59,938-0500 INFO Cpu 0813-511229-000000:17 pull 2010-08-13 12:52:59,938-0500 INFO Cpu 0813-511229-000000:17 submitting urn:1281721867107-1281721891572-1281721891573 2010-08-13 12:52:59,938-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867107-1281721891572-1281721891573) setting status to Submitting 2010-08-13 12:52:59,961-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866790-1281721891499-1281721891500) setting status to Completed 2010-08-13 12:52:59,961-0500 INFO Cpu 0813-511229-000000:19 jobTerminated 2010-08-13 12:52:59,962-0500 INFO Cpu 0813-511229-000000:19 pull 2010-08-13 12:52:59,962-0500 INFO Cpu 0813-511229-000000:19 submitting urn:1281721867143-1281721891575-1281721891576 2010-08-13 12:52:59,962-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867143-1281721891575-1281721891576) setting status to Submitting 2010-08-13 12:52:59,984-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866786-1281721891502-1281721891503) setting status to Completed 2010-08-13 12:52:59,984-0500 INFO Cpu 0813-511229-000000:20 jobTerminated 2010-08-13 12:52:59,984-0500 INFO Cpu 0813-511229-000000:20 pull 2010-08-13 12:52:59,984-0500 INFO Cpu 0813-511229-000000:20 submitting urn:1281721867145-1281721891578-1281721891579 2010-08-13 12:52:59,984-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867145-1281721891578-1281721891579) setting status to Submitting 2010-08-13 12:53:00,029-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867033-1281721891560-1281721891561) setting status to Submitted 2010-08-13 12:53:00,073-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866774-1281721891475-1281721891476) setting status to Completed 2010-08-13 12:53:00,073-0500 INFO Cpu 0813-511229-000000:23 jobTerminated 2010-08-13 12:53:00,073-0500 INFO Cpu 0813-511229-000000:23 pull 2010-08-13 12:53:00,073-0500 INFO Cpu 0813-511229-000000:23 submitting urn:1281721867158-1281721891581-1281721891582 2010-08-13 12:53:00,073-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867158-1281721891581-1281721891582) setting status to Submitting 2010-08-13 12:53:00,073-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867033-1281721891560-1281721891561) setting status to Active workerid=000022 2010-08-13 12:53:00,118-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867079-1281721891563-1281721891564) setting status to Submitted 2010-08-13 12:53:00,118-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867081-1281721891566-1281721891567) setting status to Submitted 2010-08-13 12:53:00,118-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867096-1281721891569-1281721891570) setting status to Submitted 2010-08-13 12:53:00,118-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866776-1281721891481-1281721891482) setting status to Completed 2010-08-13 12:53:00,118-0500 INFO Cpu 0813-511229-000000:14 jobTerminated 2010-08-13 12:53:00,119-0500 INFO Cpu 0813-511229-000000:14 pull 2010-08-13 12:53:00,140-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867107-1281721891572-1281721891573) setting status to Submitted 2010-08-13 12:53:00,163-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867079-1281721891563-1281721891564) setting status to Active workerid=000030 2010-08-13 12:53:00,164-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867081-1281721891566-1281721891567) setting status to Active workerid=000016 2010-08-13 12:53:00,164-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867096-1281721891569-1281721891570) setting status to Active workerid=000015 2010-08-13 12:53:00,185-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867145-1281721891578-1281721891579) setting status to Submitted 2010-08-13 12:53:00,186-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867143-1281721891575-1281721891576) setting status to Submitted 2010-08-13 12:53:00,186-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867107-1281721891572-1281721891573) setting status to Active workerid=000017 2010-08-13 12:53:00,208-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866784-1281721891493-1281721891494) setting status to Completed 2010-08-13 12:53:00,208-0500 INFO Cpu 0813-511229-000000:18 jobTerminated 2010-08-13 12:53:00,209-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867143-1281721891575-1281721891576) setting status to Active workerid=000019 2010-08-13 12:53:00,231-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866754-1281721891508-1281721891509) setting status to Completed 2010-08-13 12:53:00,231-0500 INFO Cpu 0813-511229-000000:31 jobTerminated 2010-08-13 12:53:00,231-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867145-1281721891578-1281721891579) setting status to Active workerid=000020 2010-08-13 12:53:00,277-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867158-1281721891581-1281721891582) setting status to Submitted 2010-08-13 12:53:00,277-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866738-1281721891511-1281721891512) setting status to Completed 2010-08-13 12:53:00,277-0500 INFO Cpu 0813-511229-000000:21 jobTerminated 2010-08-13 12:53:00,322-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867158-1281721891581-1281721891582) setting status to Active workerid=000023 2010-08-13 12:53:00,344-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866539-1281721891130-1281721891131) setting status to Completed 2010-08-13 12:53:00,345-0500 INFO Cpu 0813-511229-000000:25 jobTerminated 2010-08-13 12:53:00,367-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866542-1281721891514-1281721891515) setting status to Completed 2010-08-13 12:53:00,367-0500 INFO Cpu 0813-511229-000000:24 jobTerminated 2010-08-13 12:53:00,412-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866792-1281721891505-1281721891506) setting status to Completed 2010-08-13 12:53:00,412-0500 INFO Cpu 0813-511229-000000:27 jobTerminated 2010-08-13 12:53:00,899-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866541-1281721891133-1281721891134) setting status to Completed 2010-08-13 12:53:00,899-0500 INFO Cpu 0813-511229-000000:28 jobTerminated 2010-08-13 12:53:00,900-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866544-1281721891136-1281721891137) setting status to Completed 2010-08-13 12:53:00,900-0500 INFO Cpu 0813-511229-000000:29 jobTerminated 2010-08-13 12:53:01,065-0500 INFO CoasterService Idle time: 0 2010-08-13 12:53:01,121-0500 INFO Cpu 0813-511229-000000:18 pull 2010-08-13 12:53:01,164-0500 INFO TaskNotifier Congestion queue size: 0 2010-08-13 12:53:01,308-0500 INFO BlockQueueProcessor Updated allocsize: 139.5824465523192 2010-08-13 12:53:01,308-0500 INFO BlockQueueProcessor allocsize = 139.5824465523192, queuedsize = 0.0, qsz = 0 2010-08-13 12:53:01,308-0500 INFO BlockQueueProcessor Plan time: 1 2010-08-13 12:53:01,386-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866548-1281721891139-1281721891140) setting status to Completed 2010-08-13 12:53:01,386-0500 INFO Cpu 0813-511229-000000:26 jobTerminated 2010-08-13 12:53:02,123-0500 INFO Cpu 0813-511229-000000:31 pull 2010-08-13 12:53:03,125-0500 INFO Cpu 0813-511229-000000:21 pull 2010-08-13 12:53:03,510-0500 INFO BlockQueueProcessor Updated allocsize: 139.58224995466963 2010-08-13 12:53:03,510-0500 INFO BlockQueueProcessor allocsize = 139.58224995466963, queuedsize = 0.0, qsz = 0 2010-08-13 12:53:03,510-0500 INFO BlockQueueProcessor Plan time: 1 2010-08-13 12:53:04,128-0500 INFO Cpu 0813-511229-000000:25 pull 2010-08-13 12:53:05,129-0500 INFO Cpu 0813-511229-000000:24 pull 2010-08-13 12:53:05,712-0500 INFO BlockQueueProcessor Updated allocsize: 139.58211882857418 2010-08-13 12:53:05,712-0500 INFO BlockQueueProcessor allocsize = 139.58211882857418, queuedsize = 0.0, qsz = 0 2010-08-13 12:53:05,712-0500 INFO BlockQueueProcessor Plan time: 0 2010-08-13 12:53:06,131-0500 INFO Cpu 0813-511229-000000:27 pull 2010-08-13 12:53:07,134-0500 INFO Cpu 0813-511229-000000:28 pull 2010-08-13 12:53:07,915-0500 INFO BlockQueueProcessor Updated allocsize: 139.58198765363954 2010-08-13 12:53:07,915-0500 INFO BlockQueueProcessor allocsize = 139.58198765363954, queuedsize = 0.0, qsz = 0 2010-08-13 12:53:07,915-0500 INFO BlockQueueProcessor Plan time: 0 2010-08-13 12:53:08,136-0500 INFO Cpu 0813-511229-000000:29 pull 2010-08-13 12:53:09,139-0500 INFO Cpu 0813-511229-000000:14 pull 2010-08-13 12:53:09,730-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866546-1281721891142-1281721891143) setting status to Completed 2010-08-13 12:53:09,730-0500 INFO Cpu 0813-511229-000000:2 jobTerminated 2010-08-13 12:53:09,753-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866883-1281721891521-1281721891522) setting status to Completed 2010-08-13 12:53:09,753-0500 INFO Cpu 0813-511229-000000:5 jobTerminated 2010-08-13 12:53:09,753-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866879-1281721891524-1281721891525) setting status to Completed 2010-08-13 12:53:09,753-0500 INFO Cpu 0813-511229-000000:4 jobTerminated 2010-08-13 12:53:09,775-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866910-1281721891527-1281721891528) setting status to Completed 2010-08-13 12:53:09,775-0500 INFO Cpu 0813-511229-000000:0 jobTerminated 2010-08-13 12:53:09,776-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866912-1281721891530-1281721891531) setting status to Completed 2010-08-13 12:53:09,776-0500 INFO Cpu 0813-511229-000000:7 jobTerminated 2010-08-13 12:53:09,931-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866928-1281721891533-1281721891534) setting status to Completed 2010-08-13 12:53:09,931-0500 INFO Cpu 0813-511229-000000:8 jobTerminated 2010-08-13 12:53:09,976-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866942-1281721891539-1281721891540) setting status to Completed 2010-08-13 12:53:09,976-0500 INFO Cpu 0813-511229-000000:10 jobTerminated 2010-08-13 12:53:10,044-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866960-1281721891545-1281721891546) setting status to Completed 2010-08-13 12:53:10,044-0500 INFO Cpu 0813-511229-000000:11 jobTerminated 2010-08-13 12:53:10,066-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866966-1281721891548-1281721891549) setting status to Completed 2010-08-13 12:53:10,066-0500 INFO Cpu 0813-511229-000000:12 jobTerminated 2010-08-13 12:53:10,117-0500 INFO BlockQueueProcessor Updated allocsize: 139.58185642982912 2010-08-13 12:53:10,118-0500 INFO BlockQueueProcessor allocsize = 139.58185642982912, queuedsize = 0.0, qsz = 0 2010-08-13 12:53:10,118-0500 INFO BlockQueueProcessor Plan time: 1 2010-08-13 12:53:10,142-0500 INFO Cpu 0813-511229-000000:26 pull 2010-08-13 12:53:10,198-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866993-1281721891551-1281721891552) setting status to Completed 2010-08-13 12:53:10,198-0500 INFO Cpu 0813-511229-000000:1 jobTerminated 2010-08-13 12:53:10,199-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866930-1281721891536-1281721891537) setting status to Completed 2010-08-13 12:53:10,199-0500 INFO Cpu 0813-511229-000000:3 jobTerminated 2010-08-13 12:53:10,266-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721866948-1281721891542-1281721891543) setting status to Completed 2010-08-13 12:53:10,266-0500 INFO Cpu 0813-511229-000000:6 jobTerminated 2010-08-13 12:53:10,531-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867007-1281721891554-1281721891555) setting status to Completed 2010-08-13 12:53:10,531-0500 INFO Cpu 0813-511229-000000:9 jobTerminated 2010-08-13 12:53:10,598-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867009-1281721891557-1281721891558) setting status to Completed 2010-08-13 12:53:10,598-0500 INFO Cpu 0813-511229-000000:13 jobTerminated 2010-08-13 12:53:10,820-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867033-1281721891560-1281721891561) setting status to Completed 2010-08-13 12:53:10,820-0500 INFO Cpu 0813-511229-000000:22 jobTerminated 2010-08-13 12:53:10,908-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867096-1281721891569-1281721891570) setting status to Completed 2010-08-13 12:53:10,908-0500 INFO Cpu 0813-511229-000000:15 jobTerminated 2010-08-13 12:53:11,064-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867158-1281721891581-1281721891582) setting status to Completed 2010-08-13 12:53:11,065-0500 INFO Cpu 0813-511229-000000:23 jobTerminated 2010-08-13 12:53:11,069-0500 INFO CoasterService Idle time: 0 2010-08-13 12:53:11,145-0500 INFO Cpu 0813-511229-000000:18 pull 2010-08-13 12:53:11,155-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867079-1281721891563-1281721891564) setting status to Completed 2010-08-13 12:53:11,155-0500 INFO Cpu 0813-511229-000000:30 jobTerminated 2010-08-13 12:53:11,167-0500 INFO TaskNotifier Congestion queue size: 0 2010-08-13 12:53:11,178-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867081-1281721891566-1281721891567) setting status to Completed 2010-08-13 12:53:11,178-0500 INFO Cpu 0813-511229-000000:16 jobTerminated 2010-08-13 12:53:11,178-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867107-1281721891572-1281721891573) setting status to Completed 2010-08-13 12:53:11,178-0500 INFO Cpu 0813-511229-000000:17 jobTerminated 2010-08-13 12:53:11,223-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867143-1281721891575-1281721891576) setting status to Completed 2010-08-13 12:53:11,223-0500 INFO Cpu 0813-511229-000000:19 jobTerminated 2010-08-13 12:53:11,246-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721867145-1281721891578-1281721891579) setting status to Completed 2010-08-13 12:53:11,246-0500 INFO Cpu 0813-511229-000000:20 jobTerminated 2010-08-13 12:53:11,669-0500 INFO JobQueue Job batch mode flag set. Routing through local queue. 2010-08-13 12:53:11,670-0500 INFO JobQueue Adding task Task(type=JOB_SUBMISSION, identity=urn:1281721868275-1281721891584-1281721891585) to local queue 2010-08-13 12:53:11,671-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721868275-1281721891584-1281721891585) setting status to Submitting 2010-08-13 12:53:12,090-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721868275-1281721891584-1281721891585) setting status to Submitted 2010-08-13 12:53:12,090-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721868275-1281721891584-1281721891585) setting status to Active 2010-08-13 12:53:12,090-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:1281721868275-1281721891584-1281721891585) setting status to Failed Cannot submit job 2010-08-13 12:53:12,146-0500 INFO Cpu 0813-511229-000000:31 pull 2010-08-13 12:53:12,266-0500 INFO BlockQueueProcessor Shutting down blocks 2010-08-13 12:53:12,266-0500 INFO Block Shutting down block Block 0813-511229-000000 (32x5400.000s) 2010-08-13 12:53:12,268-0500 INFO Block Average utilization: 99.99% 2010-08-13 12:53:12,268-0500 INFO Block Shutting down block Block 0813-511229-000001 (32x5400.000s) 2010-08-13 12:53:12,268-0500 INFO Block Block Block 0813-511229-000001 (32x5400.000s) not running. Cancelling job. 2010-08-13 12:53:12,288-0500 INFO Cpu 12:1281721990s shut down successfully 2010-08-13 12:53:12,289-0500 INFO Cpu 23:1281721991s shut down successfully 2010-08-13 12:53:12,289-0500 INFO Cpu 22:1281721990s shut down successfully 2010-08-13 12:53:12,310-0500 INFO Cpu 13:1281721990s shut down successfully 2010-08-13 12:53:12,310-0500 INFO Cpu 14:1281721980s shut down successfully 2010-08-13 12:53:12,310-0500 INFO Cpu 25:1281721980s shut down successfully 2010-08-13 12:53:12,310-0500 INFO Cpu 24:1281721980s shut down successfully 2010-08-13 12:53:12,333-0500 INFO Cpu 26:1281721981s shut down successfully 2010-08-13 12:53:12,356-0500 INFO Cpu 28:1281721980s shut down successfully 2010-08-13 12:53:12,356-0500 INFO Cpu 29:1281721980s shut down successfully 2010-08-13 12:53:12,378-0500 INFO Cpu 8:1281721989s shut down successfully 2010-08-13 12:53:12,378-0500 INFO Cpu 30:1281721991s shut down successfully 2010-08-13 12:53:12,378-0500 INFO Cpu 27:1281721980s shut down successfully 2010-08-13 12:53:12,378-0500 INFO Cpu 16:1281721991s shut down successfully 2010-08-13 12:53:12,379-0500 INFO Cpu 15:1281721990s shut down successfully 2010-08-13 12:53:12,400-0500 INFO Cpu 18:1281721980s shut down successfully 2010-08-13 12:53:12,401-0500 INFO Cpu 17:1281721991s shut down successfully 2010-08-13 12:53:12,401-0500 INFO Cpu 1:1281721990s shut down successfully 2010-08-13 12:53:12,424-0500 INFO Cpu 19:1281721991s shut down successfully 2010-08-13 12:53:12,424-0500 INFO Cpu 10:1281721989s shut down successfully 2010-08-13 12:53:12,424-0500 INFO Cpu 3:1281721990s shut down successfully 2010-08-13 12:53:12,424-0500 INFO Cpu 31:1281721980s shut down successfully 2010-08-13 12:53:12,424-0500 INFO Cpu 2:1281721989s shut down successfully 2010-08-13 12:53:12,447-0500 INFO Cpu 20:1281721991s shut down successfully 2010-08-13 12:53:12,470-0500 INFO Cpu 6:1281721990s shut down successfully 2010-08-13 12:53:12,470-0500 INFO Cpu 5:1281721989s shut down successfully 2010-08-13 12:53:12,470-0500 INFO Cpu 4:1281721989s shut down successfully 2010-08-13 12:53:12,493-0500 INFO Cpu 9:1281721990s shut down successfully 2010-08-13 12:53:12,493-0500 INFO Cpu 0:1281721989s shut down successfully 2010-08-13 12:53:12,493-0500 INFO Cpu 21:1281721980s shut down successfully 2010-08-13 12:53:12,493-0500 INFO Cpu 7:1281721989s shut down successfully 2010-08-13 12:53:12,512-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891517) setting status to Canceled 2010-08-13 12:53:12,512-0500 INFO Block Block task status changed: Canceled 2010-08-13 12:53:12,512-0500 INFO BlockQueueProcessor Removing block Block 0813-511229-000001 (32x5400.000s) 2010-08-13 12:53:12,512-0500 INFO Block 0813-511229-000001 stdout: 2010-08-13 12:53:12,513-0500 INFO Block 0813-511229-000001 stderr: 2010-08-13 12:53:12,513-0500 INFO BlockQueueProcessor Removing block Block 0813-511229-000001 (32x5400.000s) 2010-08-13 12:53:12,513-0500 INFO Block Shutting down block Block 0813-511229-000002 (32x5400.000s) 2010-08-13 12:53:12,513-0500 INFO Block Block Block 0813-511229-000002 (32x5400.000s) not running. Cancelling job. 2010-08-13 12:53:12,516-0500 INFO Cpu 11:1281721990s shut down successfully 2010-08-13 12:53:12,573-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891517) setting status to Unsubmitted 2010-08-13 12:53:12,573-0500 INFO Block Block task status changed: Unsubmitted 2010-08-13 12:53:12,573-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891517) setting status to Submitting 2010-08-13 12:53:12,573-0500 INFO Block Block task status changed: Submitting 2010-08-13 12:53:12,573-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891517) setting status to Submitted 2010-08-13 12:53:12,574-0500 INFO Block Block task status changed: Submitted 2010-08-13 12:53:12,574-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891517) setting status to Active 2010-08-13 12:53:12,574-0500 INFO Block Block task status changed: Active 2010-08-13 12:53:12,574-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891517) setting status to Failed Job cancelled by user 2010-08-13 12:53:12,574-0500 INFO Block Block task status changed: Failed Job cancelled by user 2010-08-13 12:53:12,574-0500 INFO BlockQueueProcessor Removing block Block 0813-511229-000001 (32x5400.000s) 2010-08-13 12:53:12,627-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891516) setting status to Canceled 2010-08-13 12:53:12,631-0500 INFO Block Block task status changed: Canceled 2010-08-13 12:53:12,632-0500 INFO BlockQueueProcessor Removing block Block 0813-511229-000000 (32x5400.000s) 2010-08-13 12:53:12,682-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891516) setting status to Unsubmitted 2010-08-13 12:53:12,682-0500 INFO Block Block task status changed: Unsubmitted 2010-08-13 12:53:12,682-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891516) setting status to Submitting 2010-08-13 12:53:12,682-0500 INFO Block Block task status changed: Submitting 2010-08-13 12:53:12,682-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891516) setting status to Submitted 2010-08-13 12:53:12,682-0500 INFO Block Block task status changed: Submitted 2010-08-13 12:53:12,682-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891516) setting status to Active 2010-08-13 12:53:12,682-0500 INFO Block Block task status changed: Active 2010-08-13 12:53:12,682-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891516) setting status to Failed Job cancelled by user 2010-08-13 12:53:12,682-0500 INFO Block Block task status changed: Failed Job cancelled by user 2010-08-13 12:53:12,790-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891518) setting status to Canceled 2010-08-13 12:53:12,790-0500 INFO Block Block task status changed: Canceled 2010-08-13 12:53:12,790-0500 INFO BlockQueueProcessor Removing block Block 0813-511229-000002 (32x5400.000s) 2010-08-13 12:53:12,790-0500 INFO Block 0813-511229-000002 stdout: 2010-08-13 12:53:12,790-0500 INFO Block 0813-511229-000002 stderr: 2010-08-13 12:53:12,790-0500 INFO BlockQueueProcessor Removing block Block 0813-511229-000002 (32x5400.000s) 2010-08-13 12:53:12,790-0500 INFO Block Shutting down block Block 0813-511229-000003 (32x7200.000s) 2010-08-13 12:53:12,800-0500 WARN RemoteLogger Failed to send remote log message org.globus.cog.karajan.workflow.service.channels.ChannelException: Failed to start channel GSSCChannel-https://128.135.92.82:50000(3) at org.globus.cog.karajan.workflow.service.channels.GSSChannel.reconnect(GSSChannel.java:104) at org.globus.cog.karajan.workflow.service.channels.GSSChannel.start(GSSChannel.java:63) at org.globus.cog.karajan.workflow.service.ChannelFactory.newChannel(ChannelFactory.java:43) at org.globus.cog.karajan.workflow.service.Client.connect(Client.java:115) at org.globus.cog.karajan.workflow.service.Client.newClient(Client.java:72) at org.globus.cog.karajan.workflow.service.channels.ChannelManager.connect(ChannelManager.java:214) at org.globus.cog.karajan.workflow.service.channels.ChannelManager.reserveChannel(ChannelManager.java:233) at org.globus.cog.karajan.workflow.service.channels.ChannelManager.reserveChannel(ChannelManager.java:204) at org.globus.cog.abstraction.coaster.rlog.RemoteLogger.log(RemoteLogger.java:31) at org.globus.cog.abstraction.coaster.service.job.manager.Block.shutdown(Block.java:220) at org.globus.cog.abstraction.coaster.service.job.manager.BlockQueueProcessor.shutdownBlocks(BlockQueueProcessor.java:487) at org.globus.cog.abstraction.coaster.service.job.manager.BlockQueueProcessor.shutdown(BlockQueueProcessor.java:477) at org.globus.cog.abstraction.coaster.service.job.manager.JobQueue.shutdown(JobQueue.java:70) at org.globus.cog.abstraction.coaster.service.CoasterService.shutdown(CoasterService.java:211) at org.globus.cog.abstraction.coaster.service.ServiceShutdownHandler.requestComplete(ServiceShutdownHandler.java:28) at org.globus.cog.karajan.workflow.service.handlers.RequestHandler.receiveCompleted(RequestHandler.java:84) at org.globus.cog.karajan.workflow.service.channels.AbstractKarajanChannel.handleRequest(AbstractKarajanChannel.java:348) at org.globus.cog.karajan.workflow.service.channels.AbstractStreamKarajanChannel.step(AbstractStreamKarajanChannel.java:156) at org.globus.cog.karajan.workflow.service.channels.AbstractStreamKarajanChannel$Multiplexer.run(AbstractStreamKarajanChannel.java:365) Caused by: java.net.ConnectException: Connection refused at java.net.PlainSocketImpl.socketConnect(Native Method) at java.net.PlainSocketImpl.doConnect(PlainSocketImpl.java:333) at java.net.PlainSocketImpl.connectToAddress(PlainSocketImpl.java:195) at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:182) at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:366) at java.net.Socket.connect(Socket.java:519) at java.net.Socket.connect(Socket.java:469) at java.net.Socket.(Socket.java:366) at java.net.Socket.(Socket.java:266) at org.globus.net.SocketFactory.createSocket(SocketFactory.java:74) at org.globus.net.SocketFactory.createSocket(SocketFactory.java:53) at org.globus.gsi.gssapi.net.GssSocket.(GssSocket.java:56) at org.globus.gsi.gssapi.net.impl.GSIGssSocket.(GSIGssSocket.java:29) at org.globus.gsi.gssapi.net.impl.GSIGssSocketFactory.createSocket(GSIGssSocketFactory.java:38) at org.globus.cog.karajan.workflow.service.channels.GSSChannel.reconnect(GSSChannel.java:90) ... 18 more 2010-08-13 12:53:12,801-0500 INFO Block Block Block 0813-511229-000003 (32x7200.000s) not running. Cancelling job. 2010-08-13 12:53:12,844-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891518) setting status to Unsubmitted 2010-08-13 12:53:12,844-0500 INFO Block Block task status changed: Unsubmitted 2010-08-13 12:53:12,844-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891518) setting status to Submitting 2010-08-13 12:53:12,844-0500 INFO Block Block task status changed: Submitting 2010-08-13 12:53:12,844-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891518) setting status to Submitted 2010-08-13 12:53:12,844-0500 INFO Block Block task status changed: Submitted 2010-08-13 12:53:12,844-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891518) setting status to Active 2010-08-13 12:53:12,844-0500 INFO Block Block task status changed: Active 2010-08-13 12:53:12,852-0500 WARN RemoteLogger Failed to send remote log message org.globus.cog.karajan.workflow.service.channels.ChannelException: Failed to start channel GSSCChannel-https://128.135.92.82:50000(4) at org.globus.cog.karajan.workflow.service.channels.GSSChannel.reconnect(GSSChannel.java:104) at org.globus.cog.karajan.workflow.service.channels.GSSChannel.start(GSSChannel.java:63) at org.globus.cog.karajan.workflow.service.ChannelFactory.newChannel(ChannelFactory.java:43) at org.globus.cog.karajan.workflow.service.Client.connect(Client.java:115) at org.globus.cog.karajan.workflow.service.Client.newClient(Client.java:72) at org.globus.cog.karajan.workflow.service.channels.ChannelManager.connect(ChannelManager.java:214) at org.globus.cog.karajan.workflow.service.channels.ChannelManager.reserveChannel(ChannelManager.java:233) at org.globus.cog.karajan.workflow.service.channels.ChannelManager.reserveChannel(ChannelManager.java:204) at org.globus.cog.abstraction.coaster.rlog.RemoteLogger.log(RemoteLogger.java:31) at org.globus.cog.abstraction.coaster.service.job.manager.Block.statusChanged(Block.java:368) at org.globus.cog.abstraction.impl.common.task.TaskImpl.notifyListeners(TaskImpl.java:236) at org.globus.cog.abstraction.impl.common.task.TaskImpl.setStatus(TaskImpl.java:224) at org.globus.cog.abstraction.impl.common.task.TaskImpl.setStatus(TaskImpl.java:253) at org.globus.cog.abstraction.impl.common.task.TaskImpl.setStatus(TaskImpl.java:214) at org.globus.cog.abstraction.impl.common.AbstractDelegatedTaskHandler.failTask(AbstractDelegatedTaskHandler.java:54) at org.globus.cog.abstraction.impl.execution.gt2.JobSubmissionTaskHandler.statusChanged(JobSubmissionTaskHandler.java:532) at org.globus.gram.GramJob.setStatus(GramJob.java:184) at org.globus.gram.GramCallbackHandler.run(CallbackHandler.java:176) at java.lang.Thread.run(Thread.java:595) Caused by: java.net.ConnectException: Connection refused at java.net.PlainSocketImpl.socketConnect(Native Method) at java.net.PlainSocketImpl.doConnect(PlainSocketImpl.java:333) at java.net.PlainSocketImpl.connectToAddress(PlainSocketImpl.java:195) at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:182) at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:366) at java.net.Socket.connect(Socket.java:519) at java.net.Socket.connect(Socket.java:469) at java.net.Socket.(Socket.java:366) at java.net.Socket.(Socket.java:266) at org.globus.net.SocketFactory.createSocket(SocketFactory.java:74) at org.globus.net.SocketFactory.createSocket(SocketFactory.java:53) at org.globus.gsi.gssapi.net.GssSocket.(GssSocket.java:56) at org.globus.gsi.gssapi.net.impl.GSIGssSocket.(GSIGssSocket.java:29) at org.globus.gsi.gssapi.net.impl.GSIGssSocketFactory.createSocket(GSIGssSocketFactory.java:38) at org.globus.cog.karajan.workflow.service.channels.GSSChannel.reconnect(GSSChannel.java:90) ... 18 more 2010-08-13 12:53:12,853-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891518) setting status to Failed Job cancelled by user 2010-08-13 12:53:12,853-0500 INFO Block Block task status changed: Failed Job cancelled by user 2010-08-13 12:53:12,853-0500 INFO BlockQueueProcessor Removing block Block 0813-511229-000002 (32x5400.000s) 2010-08-13 12:53:13,074-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891519) setting status to Canceled 2010-08-13 12:53:13,074-0500 INFO Block Block task status changed: Canceled 2010-08-13 12:53:13,075-0500 INFO BlockQueueProcessor Removing block Block 0813-511229-000003 (32x7200.000s) 2010-08-13 12:53:13,075-0500 INFO Block 0813-511229-000003 stdout: 2010-08-13 12:53:13,075-0500 INFO Block 0813-511229-000003 stderr: 2010-08-13 12:53:13,075-0500 INFO BlockQueueProcessor Removing block Block 0813-511229-000003 (32x7200.000s) 2010-08-13 12:53:13,075-0500 INFO CoasterService Shutdown sequence completed 2010-08-13 12:53:13,075-0500 INFO Block 0813-511229-000002 stdout: 2010-08-13 12:53:13,076-0500 INFO Block 0813-511229-000002 stderr: 2010-08-13 12:53:13,077-0500 INFO Block 0813-511229-000000 stdout: 2010-08-13 12:53:13,077-0500 INFO BlockQueueProcessor Removing block Block 0813-511229-000000 (32x5400.000s) 2010-08-13 12:53:13,077-0500 INFO Block 0813-511229-000000 stdout: 2010-08-13 12:53:13,077-0500 INFO Block 0813-511229-000000 stderr: 2010-08-13 12:53:13,078-0500 INFO Block 0813-511229-000001 stdout: 2010-08-13 12:53:13,078-0500 INFO Block 0813-511229-000001 stderr: 2010-08-13 12:53:13,078-0500 INFO BlockQueueProcessor Updated allocsize: 0.0 2010-08-13 12:53:13,078-0500 INFO BlockQueueProcessor allocsize = 0.0, queuedsize = 0.0, qsz = 0 2010-08-13 12:53:13,079-0500 INFO BlockQueueProcessor Plan time: 760 2010-08-13 12:53:13,077-0500 INFO Block 0813-511229-000000 stderr: 2010-08-13 12:53:13,086-0500 INFO BlockQueueProcessor Removing block Block 0813-511229-000000 (32x5400.000s) 2010-08-13 12:53:13,139-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891519) setting status to Unsubmitted 2010-08-13 12:53:13,139-0500 INFO Block Block task status changed: Unsubmitted 2010-08-13 12:53:13,139-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891519) setting status to Submitting 2010-08-13 12:53:13,139-0500 INFO Block Block task status changed: Submitting 2010-08-13 12:53:13,139-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891519) setting status to Submitted 2010-08-13 12:53:13,139-0500 INFO Block Block task status changed: Submitted 2010-08-13 12:53:13,139-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891519) setting status to Active 2010-08-13 12:53:13,139-0500 INFO Block Block task status changed: Active 2010-08-13 12:53:13,146-0500 WARN RemoteLogger Failed to send remote log message org.globus.cog.karajan.workflow.service.channels.ChannelException: Failed to start channel GSSCChannel-https://128.135.92.82:50000(5) at org.globus.cog.karajan.workflow.service.channels.GSSChannel.reconnect(GSSChannel.java:104) at org.globus.cog.karajan.workflow.service.channels.GSSChannel.start(GSSChannel.java:63) at org.globus.cog.karajan.workflow.service.ChannelFactory.newChannel(ChannelFactory.java:43) at org.globus.cog.karajan.workflow.service.Client.connect(Client.java:115) at org.globus.cog.karajan.workflow.service.Client.newClient(Client.java:72) at org.globus.cog.karajan.workflow.service.channels.ChannelManager.connect(ChannelManager.java:214) at org.globus.cog.karajan.workflow.service.channels.ChannelManager.reserveChannel(ChannelManager.java:233) at org.globus.cog.karajan.workflow.service.channels.ChannelManager.reserveChannel(ChannelManager.java:204) at org.globus.cog.abstraction.coaster.rlog.RemoteLogger.log(RemoteLogger.java:31) at org.globus.cog.abstraction.coaster.service.job.manager.Block.statusChanged(Block.java:368) at org.globus.cog.abstraction.impl.common.task.TaskImpl.notifyListeners(TaskImpl.java:236) at org.globus.cog.abstraction.impl.common.task.TaskImpl.setStatus(TaskImpl.java:224) at org.globus.cog.abstraction.impl.common.task.TaskImpl.setStatus(TaskImpl.java:253) at org.globus.cog.abstraction.impl.common.task.TaskImpl.setStatus(TaskImpl.java:214) at org.globus.cog.abstraction.impl.common.AbstractDelegatedTaskHandler.failTask(AbstractDelegatedTaskHandler.java:54) at org.globus.cog.abstraction.impl.execution.gt2.JobSubmissionTaskHandler.statusChanged(JobSubmissionTaskHandler.java:532) at org.globus.gram.GramJob.setStatus(GramJob.java:184) at org.globus.gram.GramCallbackHandler.run(CallbackHandler.java:176) at java.lang.Thread.run(Thread.java:595) Caused by: java.net.ConnectException: Connection refused at java.net.PlainSocketImpl.socketConnect(Native Method) at java.net.PlainSocketImpl.doConnect(PlainSocketImpl.java:333) at java.net.PlainSocketImpl.connectToAddress(PlainSocketImpl.java:195) at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:182) at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:366) at java.net.Socket.connect(Socket.java:519) at java.net.Socket.connect(Socket.java:469) at java.net.Socket.(Socket.java:366) at java.net.Socket.(Socket.java:266) at org.globus.net.SocketFactory.createSocket(SocketFactory.java:74) at org.globus.net.SocketFactory.createSocket(SocketFactory.java:53) at org.globus.gsi.gssapi.net.GssSocket.(GssSocket.java:56) at org.globus.gsi.gssapi.net.impl.GSIGssSocket.(GSIGssSocket.java:29) at org.globus.gsi.gssapi.net.impl.GSIGssSocketFactory.createSocket(GSIGssSocketFactory.java:38) at org.globus.cog.karajan.workflow.service.channels.GSSChannel.reconnect(GSSChannel.java:90) ... 18 more 2010-08-13 12:53:13,147-0500 DEBUG TaskImpl Task(type=JOB_SUBMISSION, identity=urn:cog-1281721891519) setting status to Failed Job cancelled by user 2010-08-13 12:53:13,147-0500 INFO Block Block task status changed: Failed Job cancelled by user 2010-08-13 12:53:13,147-0500 INFO BlockQueueProcessor Removing block Block 0813-511229-000003 (32x7200.000s) 2010-08-13 12:53:13,147-0500 INFO Block 0813-511229-000003 stdout: 2010-08-13 12:53:13,147-0500 INFO Block 0813-511229-000003 stderr: 2010-08-13 12:53:13,147-0500 WARN CallbackHandlerManager No registered callback handler for null 2010-08-13 12:53:13,149-0500 INFO Cpu 0813-511229-000000:21 pull 2010-08-13 12:53:13,150-0500 INFO Cpu 0813-511229-000000:25 pull 2010-08-13 12:53:13,150-0500 INFO Cpu 0813-511229-000000:24 pull 2010-08-13 12:53:13,150-0500 INFO Cpu 0813-511229-000000:27 pull 2010-08-13 12:53:13,150-0500 INFO Cpu 0813-511229-000000:28 pull 2010-08-13 12:53:13,150-0500 INFO Cpu 0813-511229-000000:29 pull 2010-08-13 12:53:13,150-0500 INFO Cpu 0813-511229-000000:2 pull 2010-08-13 12:53:13,150-0500 INFO Cpu 0813-511229-000000:5 pull 2010-08-13 12:53:13,150-0500 INFO Cpu 0813-511229-000000:4 pull 2010-08-13 12:53:13,150-0500 INFO Cpu 0813-511229-000000:0 pull 2010-08-13 12:53:13,150-0500 INFO Cpu 0813-511229-000000:7 pull 2010-08-13 12:53:13,150-0500 INFO Cpu 0813-511229-000000:8 pull 2010-08-13 12:53:13,150-0500 INFO Cpu 0813-511229-000000:10 pull 2010-08-13 12:53:13,150-0500 INFO Cpu 0813-511229-000000:11 pull 2010-08-13 12:53:13,150-0500 INFO Cpu 0813-511229-000000:12 pull 2010-08-13 12:53:13,150-0500 INFO Cpu 0813-511229-000000:14 pull 2010-08-13 12:53:13,150-0500 INFO Cpu 0813-511229-000000:1 pull 2010-08-13 12:53:13,150-0500 INFO Cpu 0813-511229-000000:3 pull 2010-08-13 12:53:13,150-0500 INFO Cpu 0813-511229-000000:6 pull 2010-08-13 12:53:13,150-0500 INFO Cpu 0813-511229-000000:9 pull 2010-08-13 12:53:13,150-0500 INFO Cpu 0813-511229-000000:13 pull 2010-08-13 12:53:13,151-0500 INFO Cpu 0813-511229-000000:22 pull 2010-08-13 12:53:13,151-0500 INFO Cpu 0813-511229-000000:15 pull 2010-08-13 12:53:13,151-0500 INFO Cpu 0813-511229-000000:23 pull 2010-08-13 12:53:13,151-0500 INFO Cpu 0813-511229-000000:26 pull 2010-08-13 12:53:13,151-0500 INFO Cpu 0813-511229-000000:30 pull 2010-08-13 12:53:13,151-0500 INFO Cpu 0813-511229-000000:16 pull 2010-08-13 12:53:13,151-0500 INFO Cpu 0813-511229-000000:17 pull 2010-08-13 12:53:13,151-0500 INFO Cpu 0813-511229-000000:19 pull 2010-08-13 12:53:13,151-0500 INFO Cpu 0813-511229-000000:20 pull 2010-08-13 12:53:13,151-0500 INFO Cpu 0813-511229-000000:18 pull 2010-08-13 12:53:13,151-0500 INFO Cpu 0813-511229-000000:31 pull 2010-08-13 12:53:13,206-0500 INFO Cpu runTime: 13047, sleepTime: 5598 2010-08-13 12:53:21,072-0500 INFO CoasterService Idle time: 0 2010-08-13 12:53:21,603 0522149171 Exit code: 0