[Sun Jul 15 18:16:28 EDT 2018] picard.analysis.CollectInsertSizeMetrics HISTOGRAM_FILE=CollectInsertSizeMetrics/G167_M7_hist.pdf INPUT=G167_M7_primary_unique.bam OUTPUT=CollectInsertSizeMetrics/G167_M7_metrics    DEVIATIONS=10.0 MINIMUM_PCT=0.05 METRIC_ACCUMULATION_LEVEL=[ALL_READS] ASSUME_SORTED=true STOP_AFTER=0 VERBOSITY=INFO QUIET=false VALIDATION_STRINGENCY=STRICT COMPRESSION_LEVEL=5 MAX_RECORDS_IN_RAM=500000 CREATE_INDEX=false CREATE_MD5_FILE=false
[Sun Jul 15 18:16:28 EDT 2018] Executing as kkarri@scc-ka4.scc.bu.edu on Linux 2.6.32-696.28.1.el6.x86_64 amd64; Java HotSpot(TM) 64-Bit Server VM 1.7.0_21-b11; Picard version: 1.123(286a232caea2fdc8fdd88574c09c460b46386fff_1413818736) IntelDeflater
INFO	2018-07-15 18:16:36	SinglePassSamProgram	Processed     1,000,000 records.  Elapsed time: 00:00:08s.  Time for last 1,000,000:    8s.  Last read position: chr10:61,135,154
INFO	2018-07-15 18:16:41	SinglePassSamProgram	Processed     2,000,000 records.  Elapsed time: 00:00:13s.  Time for last 1,000,000:    5s.  Last read position: chr11:69,652,888
INFO	2018-07-15 18:16:47	SinglePassSamProgram	Processed     3,000,000 records.  Elapsed time: 00:00:19s.  Time for last 1,000,000:    5s.  Last read position: chr12:86,470,784
INFO	2018-07-15 18:16:53	SinglePassSamProgram	Processed     4,000,000 records.  Elapsed time: 00:00:25s.  Time for last 1,000,000:    5s.  Last read position: chr14:31,708,630
INFO	2018-07-15 18:17:01	SinglePassSamProgram	Processed     5,000,000 records.  Elapsed time: 00:00:33s.  Time for last 1,000,000:    8s.  Last read position: chr15:79,909,178
INFO	2018-07-15 18:17:09	SinglePassSamProgram	Processed     6,000,000 records.  Elapsed time: 00:00:40s.  Time for last 1,000,000:    7s.  Last read position: chr16:33,057,541
INFO	2018-07-15 18:17:17	SinglePassSamProgram	Processed     7,000,000 records.  Elapsed time: 00:00:48s.  Time for last 1,000,000:    8s.  Last read position: chr17:56,860,481
INFO	2018-07-15 18:17:25	SinglePassSamProgram	Processed     8,000,000 records.  Elapsed time: 00:00:56s.  Time for last 1,000,000:    8s.  Last read position: chr19:47,939,092
INFO	2018-07-15 18:17:32	SinglePassSamProgram	Processed     9,000,000 records.  Elapsed time: 00:01:04s.  Time for last 1,000,000:    7s.  Last read position: chr3:5,860,398
INFO	2018-07-15 18:17:38	SinglePassSamProgram	Processed    10,000,000 records.  Elapsed time: 00:01:10s.  Time for last 1,000,000:    5s.  Last read position: chr4:15,845,554
INFO	2018-07-15 18:17:42	SinglePassSamProgram	Processed    11,000,000 records.  Elapsed time: 00:01:14s.  Time for last 1,000,000:    4s.  Last read position: chr4:63,026,722
INFO	2018-07-15 18:17:47	SinglePassSamProgram	Processed    12,000,000 records.  Elapsed time: 00:01:19s.  Time for last 1,000,000:    4s.  Last read position: chr5:8,958,977
INFO	2018-07-15 18:17:51	SinglePassSamProgram	Processed    13,000,000 records.  Elapsed time: 00:01:23s.  Time for last 1,000,000:    4s.  Last read position: chr5:115,699,497
INFO	2018-07-15 18:17:56	SinglePassSamProgram	Processed    14,000,000 records.  Elapsed time: 00:01:28s.  Time for last 1,000,000:    4s.  Last read position: chr6:71,235,810
INFO	2018-07-15 18:18:00	SinglePassSamProgram	Processed    15,000,000 records.  Elapsed time: 00:01:32s.  Time for last 1,000,000:    4s.  Last read position: chr7:20,282,866
INFO	2018-07-15 18:18:05	SinglePassSamProgram	Processed    16,000,000 records.  Elapsed time: 00:01:37s.  Time for last 1,000,000:    4s.  Last read position: chr7:146,083,563
INFO	2018-07-15 18:18:10	SinglePassSamProgram	Processed    17,000,000 records.  Elapsed time: 00:01:41s.  Time for last 1,000,000:    4s.  Last read position: chr9:21,725,699
INFO	2018-07-15 18:18:14	SinglePassSamProgram	Processed    18,000,000 records.  Elapsed time: 00:01:46s.  Time for last 1,000,000:    4s.  Last read position: chr9:108,564,649
INFO	2018-07-15 18:18:18	SinglePassSamProgram	Processed    19,000,000 records.  Elapsed time: 00:01:50s.  Time for last 1,000,000:    4s.  Last read position: chrX:45,903,480
INFO	2018-07-15 18:19:00	RExecutor	Executing R script via command: Rscript /tmp/kkarri/script8315498633297301378.R /scratch/6944808.1.linga/CollectInsertSizeMetrics/G167_M7_metrics /scratch/6944808.1.linga/CollectInsertSizeMetrics/G167_M7_hist.pdf G167_M7_primary_unique.bam
INFO	2018-07-15 18:19:00	ProcessExecutor	null device 
INFO	2018-07-15 18:19:00	ProcessExecutor	          1 
[Sun Jul 15 18:19:00 EDT 2018] picard.analysis.CollectInsertSizeMetrics done. Elapsed time: 2.55 minutes.
Runtime.totalMemory()=1900675072