From owner-freebsd-questions@FreeBSD.ORG Wed Jul 14 13:09:33 2010 Return-Path: Delivered-To: freebsd-questions@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:4f8:fff6::34]) by hub.freebsd.org (Postfix) with ESMTP id 66E091065678 for ; Wed, 14 Jul 2010 13:09:33 +0000 (UTC) (envelope-from onemda@gmail.com) Received: from mail-wy0-f182.google.com (mail-wy0-f182.google.com [74.125.82.182]) by mx1.freebsd.org (Postfix) with ESMTP id D8D9E8FC16 for ; Wed, 14 Jul 2010 13:09:32 +0000 (UTC) Received: by wyf22 with SMTP id 22so1491604wyf.13 for ; Wed, 14 Jul 2010 06:09:25 -0700 (PDT) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=gmail.com; s=gamma; h=domainkey-signature:received:mime-version:received:in-reply-to :references:from:date:message-id:subject:to:cc:content-type :content-transfer-encoding; bh=pHdFVjvY0kspog9TB/zBqkY6Q3tfZXWhu8eYO37OdQc=; b=kn9tPIkZt40GOG0YyzQSoMmngwlWKcXv3ho2srQNFJvfXlQl0EReZlXzMRvJ/j0R6o DWYruZsdK1TFX/uNUkU/xfUbj3nzWdCzmHZ0yWOpQDtVwpdZdXmvyEepRV8uU3QNt8NI lrX/ICjhVhKm0yutSKBXZOiso71eqBXtSfO0Y= DomainKey-Signature: a=rsa-sha1; c=nofws; d=gmail.com; s=gamma; h=mime-version:in-reply-to:references:from:date:message-id:subject:to :cc:content-type:content-transfer-encoding; b=JSNC+He3yzz/nSS11vx6rL8IMfRe6FS0qpUSlFxqxOxO78DdjM1lZ0LPrVfTdPDsKg Zo3DRGw6G1/3TYvjk7jQcWNPfFgclGeLnDflbiH4h4+EKZv9Zh/ZkF4yaU+kI4FoIGPE DB80n96999JUfziRb847Xga8Zh26yqAhjAY48= Received: by 10.227.156.209 with SMTP id y17mr16096359wbw.18.1279112965357; Wed, 14 Jul 2010 06:09:25 -0700 (PDT) MIME-Version: 1.0 Received: by 10.216.235.200 with HTTP; Wed, 14 Jul 2010 06:09:05 -0700 (PDT) In-Reply-To: References: From: Paul B Mahol Date: Wed, 14 Jul 2010 13:09:05 +0000 Message-ID: To: Alexander Best Content-Type: text/plain; charset=ISO-8859-1 Content-Transfer-Encoding: quoted-printable Cc: freebsd-questions@freebsd.org Subject: Re: buffer starvation when recording DVDs X-BeenThere: freebsd-questions@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: User questions List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Wed, 14 Jul 2010 13:09:33 -0000 On Wed, Jul 14, 2010 at 12:46 PM, Alexander Best wrote: > 2010/7/14 Alexander Best : >> hi there, >> >> my dvdr drive supports recording DVDs (both DVD-Rs and DVD+Rs) at a >> maximum speed of 16x. however at speeds of more than ~12x the burner's >> buffer get's filled only ~20%. probably that's why the burner's speed >> get's reduced by `growisofs` (to prevent buffer starvation?). how can >> this happen? the iso i am using lies on my HDD which is quite fast >> (connected via SATA 2.0). >> >> this is the output of `camcontrol devlist -v`: >> >> scbus0 on ahcich0 bus 0: >> <> =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 at sc= bus0 target -1 lun -1 () >> scbus1 on ahcich1 bus 0: >> <> =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 at sc= bus1 target -1 lun -1 () >> scbus2 on ata2 bus 0: >> =A0 =A0at scbus2 target 0 lun 0 (cd0,pas= s0) >> <> =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 at sc= bus2 target -1 lun -1 () >> scbus3 on ahcich2 bus 0: >> =A0 =A0 =A0 =A0 at scbus3 target 0 lun 0 (ada= 0,pass1) >> <> =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 at sc= bus3 target -1 lun -1 () >> scbus4 on ahcich3 bus 0: >> <> =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 at sc= bus4 target -1 lun -1 () >> scbus5 on ahcich4 bus 0: >> <> =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 at sc= bus5 target -1 lun -1 () >> scbus6 on ahcich5 bus 0: >> <> =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 at sc= bus6 target -1 lun -1 () >> scbus7 on ahcich6 bus 0: >> <> =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 at sc= bus7 target -1 lun -1 () >> scbus8 on ahcich7 bus 0: >> <> =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 at sc= bus8 target -1 lun -1 () >> scbus-1 on xpt0 bus 0: >> <> =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 =A0 at sc= bus-1 target -1 lun -1 (xpt0) >> >> as you can see cd0 and ada0 don't share the same cable (cd0 is PATA, >> ada0 is SATA). the PATA and SATA controllers are: >> >> atapci0: port >> 0xd000-0xd007,0xd100-0xd103,0xd200-0xd207,0xd300-0xd303,0xd400-0xd40f >> irq 16 at device 0.1 on pci3 >> >> ahci0: mem 0xf8000000-0xf8001fff >> irq 19 at device 0.0 on pci3 > > sorry for the wrong information. just realized my HDD is connected to > the intel controller! > >> >> this is the output of `growisofs`: >> >> Executing 'builtin_dd if=3Dnew.iso of=3D/dev/pass0 obs=3D32k seek=3D0' >> /dev/pass0: "Current Write Speed" is 16.4x1352KBps. >> =A0 =A0 =A0 =A0 =A00/4695924736 ( 0.0%) @0x, remaining ??:?? RBU 100.0% = UBU =A0 0.0% >> =A0 =A0 =A0 =A0 =A00/4695924736 ( 0.0%) @0x, remaining ??:?? RBU 100.0% = UBU =A0 0.0% >> =A0 =A0 =A0 =A0 =A00/4695924736 ( 0.0%) @0x, remaining ??:?? RBU 100.0% = UBU =A0 0.0% >> =A0 =A0 =A0 =A0 =A00/4695924736 ( 0.0%) @0x, remaining ??:?? RBU 100.0% = UBU =A0 0.0% >> =A0 =A0 =A0 =A0 =A00/4695924736 ( 0.0%) @0x, remaining ??:?? RBU 100.0% = UBU =A0 0.0% >> =A0 =A0 =A0 =A0 =A00/4695924736 ( 0.0%) @0x, remaining ??:?? RBU 100.0% = UBU =A0 0.0% >> =A0 =A0 =A0 =A0 =A00/4695924736 ( 0.0%) @0x, remaining ??:?? RBU 100.0% = UBU =A0 0.0% >> =A0 =A01114112/4695924736 ( 0.0%) @0.2x, remaining 2036:44 RBU 100.0% UB= U =A0 2.9% >> =A0 =A01114112/4695924736 ( 0.0%) @0.0x, remaining 2247:26 RBU 100.0% UB= U 100.0% >> =A0 19365888/4695924736 ( 0.4%) @4.0x, remaining 144:53 RBU 100.0% UBU 1= 00.0% >> =A0 52789248/4695924736 ( 1.1%) @7.2x, remaining 57:10 RBU =A099.9% UBU = 100.0% >> =A0 86736896/4695924736 ( 1.8%) @7.4x, remaining 37:11 RBU 100.0% UBU 10= 0.0% >> =A0121241600/4695924736 ( 2.6%) @7.5x, remaining 28:55 RBU 100.0% UBU = =A097.1% >> =A0156336128/4695924736 ( 3.3%) @7.6x, remaining 23:42 RBU =A099.9% UBU = =A097.1% >> =A0191954944/4695924736 ( 4.1%) @7.7x, remaining 20:20 RBU 100.0% UBU 10= 0.0% >> =A0228130816/4695924736 ( 4.9%) @7.8x, remaining 18:16 RBU 100.0% UBU 10= 0.0% >> =A0264863744/4695924736 ( 5.6%) @8.0x, remaining 16:27 RBU 100.0% UBU 10= 0.0% >> =A0298352640/4695924736 ( 6.4%) @7.3x, remaining 15:13 RBU 100.0% UBU 10= 0.0% >> =A0336134144/4695924736 ( 7.2%) @8.2x, remaining 14:16 RBU 100.0% UBU 10= 0.0% >> =A0374505472/4695924736 ( 8.0%) @8.3x, remaining 13:16 RBU 100.0% UBU 10= 0.0% >> =A0413401088/4695924736 ( 8.8%) @8.4x, remaining 12:25 RBU =A099.9% UBU = 100.0% >> =A0452886528/4695924736 ( 9.6%) @8.6x, remaining 11:52 RBU 100.0% UBU 10= 0.0% >> =A0492896256/4695924736 (10.5%) @8.7x, remaining 11:13 RBU 100.0% UBU = =A097.1% >> =A0533495808/4695924736 (11.4%) @8.8x, remaining 10:39 RBU 100.0% UBU = =A097.1% >> =A0574619648/4695924736 (12.2%) @8.9x, remaining 10:16 RBU =A099.7% UBU = 100.0% >> =A0616333312/4695924736 (13.1%) @9.0x, remaining 9:49 RBU =A099.9% UBU = =A097.1% >> =A0658538496/4695924736 (14.0%) @9.1x, remaining 9:24 RBU 100.0% UBU =A0= 94.1% >> =A0701366272/4695924736 (14.9%) @9.3x, remaining 9:06 RBU 100.0% UBU =A0= 97.1% >> =A0744751104/4695924736 (15.9%) @9.4x, remaining 8:45 RBU =A099.9% UBU = =A097.1% >> =A0788660224/4695924736 (16.8%) @9.5x, remaining 8:25 RBU 100.0% UBU =A0= 97.1% >> =A0833159168/4695924736 (17.7%) @9.6x, remaining 8:11 RBU 100.0% UBU =A0= 97.1% >> =A0878182400/4695924736 (18.7%) @9.8x, remaining 7:53 RBU 100.0% UBU =A0= 97.1% >> =A0923762688/4695924736 (19.7%) @9.9x, remaining 7:37 RBU 100.0% UBU =A0= 97.1% >> =A0969932800/4695924736 (20.7%) @10.0x, remaining 7:25 RBU 100.0% UBU = =A097.1% >> =A01011908608/4695924736 (21.5%) @9.1x, remaining 7:13 RBU 100.0% UBU = =A097.1% >> =A01059094528/4695924736 (22.6%) @10.2x, remaining 6:58 RBU 100.0% UBU = =A097.1% >> =A01106903040/4695924736 (23.6%) @10.4x, remaining 6:48 RBU 100.0% UBU = =A097.1% >> =A01155203072/4695924736 (24.6%) @10.5x, remaining 6:35 RBU 100.0% UBU = =A097.1% >> =A01204092928/4695924736 (25.6%) @10.6x, remaining 6:22 RBU =A099.9% UBU= =A097.1% >> =A01253507072/4695924736 (26.7%) @10.7x, remaining 6:13 RBU 100.0% UBU = =A097.1% >> =A01303511040/4695924736 (27.8%) @10.8x, remaining 6:01 RBU 100.0% UBU = =A097.1% >> =A01354039296/4695924736 (28.8%) @10.9x, remaining 5:50 RBU 100.0% UBU = =A097.1% >> =A01405124608/4695924736 (29.9%) @11.1x, remaining 5:41 RBU 100.0% UBU = =A091.2% >> =A01456832512/4695924736 (31.0%) @11.2x, remaining 5:31 RBU =A099.9% UBU= =A097.1% >> =A01509031936/4695924736 (32.1%) @11.3x, remaining 5:21 RBU 100.0% UBU = =A097.1% >> =A01561821184/4695924736 (33.3%) @11.4x, remaining 5:13 RBU =A099.9% UBU= =A097.1% >> =A01615134720/4695924736 (34.4%) @11.6x, remaining 5:03 RBU 100.0% UBU = =A091.2% >> =A01669005312/4695924736 (35.5%) @11.7x, remaining 4:53 RBU 100.0% UBU = =A097.1% >> =A01723465728/4695924736 (36.7%) @11.8x, remaining 4:46 RBU 100.0% UBU = =A091.2% >> =A01778417664/4695924736 (37.9%) @11.9x, remaining 4:37 RBU 100.0% UBU = =A097.1% >> =A01834024960/4695924736 (39.1%) @12.0x, remaining 4:28 RBU =A099.5% UBU= =A097.1% >> =A01884389376/4695924736 (40.1%) @10.9x, remaining 4:22 RBU 100.0% UBU = =A047.1% >> =A01941012480/4695924736 (41.3%) @12.3x, remaining 4:14 RBU =A099.9% UBU= =A097.1% >> =A01998159872/4695924736 (42.6%) @12.4x, remaining 4:05 RBU 100.0% UBU = =A097.1% >> =A02055897088/4695924736 (43.8%) @12.5x, remaining 3:58 RBU =A096.6% UBU= =A094.1% >> =A02113961984/4695924736 (45.0%) @12.6x, remaining 3:50 RBU 100.0% UBU = =A082.4% >> =A02172977152/4695924736 (46.3%) @12.8x, remaining 3:42 RBU 100.0% UBU = =A079.4% >> =A02232188928/4695924736 (47.5%) @12.8x, remaining 3:36 RBU 100.0% UBU = =A079.4% >> =A02292154368/4695924736 (48.8%) @13.0x, remaining 3:28 RBU 100.0% UBU = =A073.5% >> =A02352218112/4695924736 (50.1%) @13.0x, remaining 3:21 RBU =A099.9% UBU= =A041.2% >> =A02406875136/4695924736 (51.3%) @11.8x, remaining 3:15 RBU 100.0% UBU = =A023.5% >> =A02462351360/4695924736 (52.4%) @12.0x, remaining 3:09 RBU 100.0% UBU = =A020.6% >> =A02512650240/4695924736 (53.5%) @10.9x, remaining 3:04 RBU 100.0% UBU = =A020.6% >> =A02568781824/4695924736 (54.7%) @12.2x, remaining 2:58 RBU =A099.8% UBU= =A020.6% >> =A02625634304/4695924736 (55.9%) @12.3x, remaining 2:52 RBU =A099.9% UBU= =A020.6% >> =A02682814464/4695924736 (57.1%) @12.4x, remaining 2:46 RBU 100.0% UBU = =A020.6% >> =A02740486144/4695924736 (58.4%) @12.5x, remaining 2:41 RBU =A099.9% UBU= =A020.6% >> =A02785411072/4695924736 (59.3%) @9.7x, remaining 2:37 RBU =A099.5% UBU = =A020.6% >> =A02840920064/4695924736 (60.5%) @12.0x, remaining 2:31 RBU 100.0% UBU = =A097.1% >> =A02896461824/4695924736 (61.7%) @12.0x, remaining 2:26 RBU 100.0% UBU = =A094.1% >> =A02951970816/4695924736 (62.9%) @12.0x, remaining 2:21 RBU 100.0% UBU = =A097.1% >> =A03007479808/4695924736 (64.0%) @12.0x, remaining 2:15 RBU =A099.9% UBU= =A097.1% >> =A03063021568/4695924736 (65.2%) @12.0x, remaining 2:11 RBU =A099.8% UBU= =A088.2% >> =A03118497792/4695924736 (66.4%) @12.0x, remaining 2:05 RBU 100.0% UBU = =A097.1% >> =A03174039552/4695924736 (67.6%) @12.0x, remaining 2:00 RBU 100.0% UBU = =A097.1% >> =A03229581312/4695924736 (68.8%) @12.0x, remaining 1:56 RBU =A099.9% UBU= =A097.1% >> =A03285090304/4695924736 (70.0%) @12.0x, remaining 1:51 RBU 100.0% UBU = =A097.1% >> =A03340599296/4695924736 (71.1%) @12.0x, remaining 1:46 RBU 100.0% UBU = =A097.1% >> =A03396141056/4695924736 (72.3%) @12.0x, remaining 1:41 RBU =A099.9% UBU= =A097.1% >> =A03451682816/4695924736 (73.5%) @12.0x, remaining 1:36 RBU =A099.9% UBU= =A097.1% >> =A03507191808/4695924736 (74.7%) @12.0x, remaining 1:32 RBU 100.0% UBU = =A094.1% >> =A03562733568/4695924736 (75.9%) @12.0x, remaining 1:27 RBU 100.0% UBU = =A091.2% >> =A03618242560/4695924736 (77.1%) @12.0x, remaining 1:23 RBU 100.0% UBU = =A091.2% >> =A03673784320/4695924736 (78.2%) @12.0x, remaining 1:18 RBU 100.0% UBU = =A094.1% >> =A03729293312/4695924736 (79.4%) @12.0x, remaining 1:14 RBU 100.0% UBU = =A094.1% >> =A03784835072/4695924736 (80.6%) @12.0x, remaining 1:09 RBU 100.0% UBU = =A094.1% >> =A03840344064/4695924736 (81.8%) @12.0x, remaining 1:05 RBU 100.0% UBU = =A097.1% >> =A03895885824/4695924736 (83.0%) @12.0x, remaining 1:00 RBU 100.0% UBU = =A070.6% >> =A03951427584/4695924736 (84.1%) @12.0x, remaining 0:56 RBU 100.0% UBU = =A097.1% >> =A04006936576/4695924736 (85.3%) @12.0x, remaining 0:51 RBU 100.0% UBU = =A097.1% >> =A04062478336/4695924736 (86.5%) @12.0x, remaining 0:47 RBU 100.0% UBU = =A097.1% >> =A04118020096/4695924736 (87.7%) @12.0x, remaining 0:43 RBU 100.0% UBU = =A097.1% >> =A04173529088/4695924736 (88.9%) @12.0x, remaining 0:39 RBU 100.0% UBU = =A097.1% >> =A04229103616/4695924736 (90.1%) @12.0x, remaining 0:34 RBU 100.0% UBU = =A097.1% >> =A04284579840/4695924736 (91.2%) @12.0x, remaining 0:30 RBU 100.0% UBU = =A097.1% >> =A04340154368/4695924736 (92.4%) @12.0x, remaining 0:26 RBU 100.0% UBU = =A097.1% >> =A04395663360/4695924736 (93.6%) @12.0x, remaining 0:22 RBU 100.0% UBU = =A097.1% >> =A04451237888/4695924736 (94.8%) @12.0x, remaining 0:18 RBU =A099.9% UBU= =A097.1% >> =A04506779648/4695924736 (96.0%) @12.0x, remaining 0:13 RBU =A099.9% UBU= =A097.1% >> =A04562321408/4695924736 (97.2%) @12.0x, remaining 0:09 RBU =A099.9% UBU= =A097.1% >> =A04617830400/4695924736 (98.3%) @12.0x, remaining 0:05 RBU 100.0% UBU = =A097.1% >> =A04673372160/4695924736 (99.5%) @12.0x, remaining 0:01 RBU =A067.3% UBU= =A097.1% >> builtin_dd: 2292944*2KB out @ average 9.9x1352KBps >> /dev/pass0: flushing cache >> /dev/pass0: closing track >> /dev/pass0: closing disc >> >> i also noticed this output (console): >> >> (cd0:ata2:0:0:0): READ TOC/PMA/ATIP. CDB: 43 0 0 0 0 0 0 0 4 0 >> (cd0:ata2:0:0:0): CAM status: SCSI Status Error >> (cd0:ata2:0:0:0): SCSI status: Check Condition >> (cd0:ata2:0:0:0): SCSI sense: ILLEGAL REQUEST csi:43,0,0,e asc:24,0 >> (Invalid field in CDB): Command byte 6 is invalid >> (cd0:ata2:0:0:0): READ TOC/PMA/ATIP. CDB: 43 0 0 0 0 0 0 0 4 0 >> (cd0:ata2:0:0:0): CAM status: SCSI Status Error >> (cd0:ata2:0:0:0): SCSI status: Check Condition >> (cd0:ata2:0:0:0): SCSI sense: ILLEGAL REQUEST csi:43,0,0,e asc:24,0 >> (Invalid field in CDB): Command byte 6 is invalid >> >> my motherboard features a second SATA controller: >> >> ahci1: port >> 0xe600-0xe607,0xe700-0xe703,0xe800-0xe807,0xe900-0xe903,0xea00-0xea1f >> mem 0xf8206000-0xf82067ff irq 19 at device 31.2 on pci0 > > this one! > >> >> should i consider moving my hdd to this controller? i heard the >> jmicron controllers don't work very well under freebsd. >> >> i'm running >> >> FreeBSD otaku 9.0-CURRENT FreeBSD 9.0-CURRENT #4 r209884: Sat Jul 10 >> 19:25:16 CEST 2010 =A0 =A0 root@otaku:/usr/obj/usr/src/sys/ARUNDEL =A0am= d64 >> >> with >> >> options =A0 =A0 =A0 =A0 ATA_CAM >> >> in my kernel config. >> >> cheers. >> >> ps: during the `growisofs` session i made sure that no other >> application was causing heavy HDD activity!!! How much is your HDD really fast(when reading that iso file)? Usually when I use growisofs, files I burn are located on tmpfs or are completly cached in VM (yes, that is hardcore)