excessive wakeupp / standby cycles

91 views
Skip to first unread message

3p141592654

unread,
Aug 31, 2012, 8:49:54 PM8/31/12
to al...@googlegroups.com
I am seeing hundreds of standby/wakeup cycles on  my primary drive, literally every minute, as shown below with a snip from the system log file.

I've tried Power Sav. "medium" and "high" with no change in behavior. Spindow is greyed out for both drives and shows "60" mins.



SYSTEM LOG
==================================================================================
Aug 31 15:43:54 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 15:44:42 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 15:44:43 jbhnas1 daemon.info sysctrl: left_dev disk (sdb) wakeup Aug 31 15:45:44 jbhnas1 daemon.info sysctrl: left_dev disk (sdb) standby Aug 31 15:45:49 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 15:46:43 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 15:47:51 jbhnas1 daemon.info sysctrl: left_dev disk (sdb) wakeup Aug 31 15:48:31 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 15:48:39 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 15:49:44 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 15:50:18 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 15:51:34 jbhnas1 daemon.info sysctrl: left_dev disk (sdb) standby Aug 31 15:51:44 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 15:52:37 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 15:53:42 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 15:54:38 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 15:55:48 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 15:56:40 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 15:57:45 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 15:58:41 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 15:59:46 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 16:00:42 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 16:01:53 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 16:02:46 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 16:03:51 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 16:04:47 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 16:05:58 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 16:06:48 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 16:07:58 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 16:08:52 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 16:09:57 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 16:10:54 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 16:11:59 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 16:12:55 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 16:14:01 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 16:14:52 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 16:16:03 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 16:16:53 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 16:18:03 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 16:18:25 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 16:18:25 jbhnas1 daemon.info sysctrl: left_dev disk (sdb) wakeup Aug 31 16:19:26 jbhnas1 daemon.info sysctrl: left_dev disk (sdb) standby Aug 31 16:19:51 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 16:20:48 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 16:21:59 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 16:22:50 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 16:23:30 jbhnas1 daemon.info sysctrl: temp=42.9 fan=2908 Aug 31 16:23:55 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 16:24:51 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 16:25:56 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 16:26:52 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 16:27:57 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 16:28:49 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 16:29:54 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 16:30:50 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 16:31:55 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 16:32:51 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 16:33:56 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 16:34:52 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 16:36:03 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 16:36:53 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 16:38:04 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 16:38:58 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 16:40:08 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 16:40:59 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 16:42:04 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 16:43:01 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 16:44:06 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 16:45:02 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 16:46:13 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 16:47:03 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 16:48:13 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 16:49:04 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup Aug 31 16:50:09 jbhnas1 daemon.info sysctrl: right_dev disk (sda) standby Aug 31 16:50:30 jbhnas1 daemon.info sysctrl: right_dev disk (sda) wakeup
==================================================================================

Not sure if its any value/relevance, but the drive Health output is as follows:

==================================================================================
smartctl 5.42 2011-10-20 r3458 [armv5tel-linux-2.6.35.14] (local build) Copyright (C) 2002-11 by Bruce Allen, http://smartmontools.sourceforge.net === START OF INFORMATION SECTION === Device Model: ST3000DM001-9YN166 Serial Number: W1F0S1PX LU WWN Device Id: 5 000c50 0527fc24c Firmware Version: CC4B User Capacity: 3,000,592,982,016 bytes [3.00 TB] Sector Sizes: 512 bytes logical, 4096 bytes physical Device is: Not in smartctl database [for details use: -P showall] ATA Version is: 8 ATA Standard is: ATA-8-ACS revision 4 Local Time is: Fri Aug 31 17:44:01 2012 PDT SMART support is: Available - device has SMART capability. SMART support is: Enabled === START OF READ SMART DATA SECTION === SMART overall-health self-assessment test result: PASSED SMART Attributes Data Structure revision number: 10 Vendor Specific SMART Attributes with Thresholds: ID# ATTRIBUTE_NAME FLAG VALUE WORST THRESH TYPE UPDATED WHEN_FAILED RAW_VALUE 1 Raw_Read_Error_Rate 0x000f 119 099 006 Pre-fail Always - 201768528 3 Spin_Up_Time 0x0003 093 092 000 Pre-fail Always - 0 4 Start_Stop_Count 0x0032 099 099 020 Old_age Always - 1892 5 Reallocated_Sector_Ct 0x0033 100 100 036 Pre-fail Always - 0 7 Seek_Error_Rate 0x000f 061 060 030 Pre-fail Always - 1535362 9 Power_On_Hours 0x0032 100 100 000 Old_age Always - 378 10 Spin_Retry_Count 0x0013 100 100 097 Pre-fail Always - 0 12 Power_Cycle_Count 0x0032 100 100 020 Old_age Always - 9 183 Runtime_Bad_Block 0x0032 100 100 000 Old_age Always - 0 184 End-to-End_Error 0x0032 100 100 099 Old_age Always - 0 187 Reported_Uncorrect 0x0032 100 100 000 Old_age Always - 0 188 Command_Timeout 0x0032 100 100 000 Old_age Always - 0 189 High_Fly_Writes 0x003a 100 100 000 Old_age Always - 0 190 Airflow_Temperature_Cel 0x0022 057 052 045 Old_age Always - 43 (Min/Max 33/48) 191 G-Sense_Error_Rate 0x0032 100 100 000 Old_age Always - 0 192 Power-Off_Retract_Count 0x0032 100 100 000 Old_age Always - 6 193 Load_Cycle_Count 0x0032 100 100 000 Old_age Always - 1993 194 Temperature_Celsius 0x0022 043 048 000 Old_age Always - 43 (0 26 0 0 0) 197 Current_Pending_Sector 0x0012 100 100 000 Old_age Always - 0 198 Offline_Uncorrectable 0x0010 100 100 000 Old_age Offline - 0 199 UDMA_CRC_Error_Count 0x003e 200 200 000 Old_age Always - 0 240 Head_Flying_Hours 0x0000 100 253 000 Old_age Offline - 120894739448052 241 Total_LBAs_Written 0x0000 100 253 000 Old_age Offline - 15501308620378 242 Total_LBAs_Read 0x0000 100 253 000 Old_age Offline - 46870696643 SMART Error Log Version: 1 No Errors Logged SMART Self-test log structure revision number 1 Num Test_Description Status Remaining LifeTime(hours) LBA_of_first_error # 1 Extended offline Completed without error 00% 332 - # 2 Short offline Completed without error 00% 243 - # 3 Extended offline Completed without error 00% 81 -
==================================================================================

Image1.jpg

Joao Cardoso

unread,
Sep 1, 2012, 8:28:28 AM9/1/12
to al...@googlegroups.com


On Saturday, September 1, 2012 1:49:55 AM UTC+1, 3p141592654 wrote:
I am seeing hundreds of standby/wakeup cycles on  my primary drive,

A disk wakes-up when there is system activity. Period.

System activity can be:
-a running service that needs to read or update info from the disk,
-other computers in the network are refreshing theirs views or status
-competition for memory from too many services running or a memory hungry service.

The most frequent cause are the last two.
The 323 has only 64MB of memory, and it is needed for both a programs code and the data it manipulates.

There is *no control* how a disk is awakened-up, only when it spins-down.
If you force a disk to spindown, and the system needs it for any of the above reasons, the disk will immediately be awakened-up. That is what your system's log shows.

 
 literally every minute, as shown below with a snip from the system log file.

I've tried Power Sav. "medium" and "high" with no change in behavior. Spindow is greyed out for both drives and shows "60" mins.

The Spindown entry grays out when any of the Power Sav mode is selected. Set Power Sav to disable.
Power Sav. is an old APM (Advanced Power Management) facility that many new disks don't support, and it interferes with the normal spindown.

The online help is not very clear about this:
  • Power Sav. if the disk supports A.P.M (Advanced Power Management), three levels of power saving can be set. If grayed out, the disk does not supported APM.
    • Low: Low power saving, higher performance, can't spindown
    • Med: Medium power savings and performance, can spindow
    • High: Higher power savings, lower performance, can spindow
    • Disable: disables APM
  • Spindow You can enter the number of minutes of disk inactivity after which it will enter standby mode, stopping spinning. If services are using the disk, it might not be possible to spin it down. This depends on how the service uses the disk. Also, when packages are installed on disk and are running, the disk where the package was installed might not be put in standby; again, it depends on the service that the package offers. You have to press theSubmit button for this to take efect.
Reply all
Reply to author
Forward
0 new messages