EEM Run Twice an hour

Answered Question
Jul 2nd, 2010
User Badges:

I configured the EEM applet to run every hour, but it turns out that it run 2 times per hour. Right after the applet finish, it starts again for one more time. I suspect the cron is not configured properly. I checked the crontab documentation and I can't find any wrong with it.


ACMGJC_Router1#sh event manager policy registered
No.  Class     Type    Event Type          Trap  Time Registered           Name
2    applet    user    timer cron          Off   Thu Jul 1 16:48:22 2010   HourlyLog
name {EveryHour} cron entry {0 0-23 * * *}
maxrun 60.000
action 1.0 cli command "enable"
action 2.0 cli command "show process cpu history | redirect flash:/dailylog"
action 2.1 cli command "show interface fastethernet 0/0 | append flash:/dailylog"
action 2.2 cli command "show interface fastethernet 0/1 | append flash:/dailylog"
action 2.3 cli command "show ip nat statistics | append flash:/dailylog"
action 2.4 cli command "show ip nat translations | append flash:/dailylog"
action 2.5 cli command "copy flash:/dailylog scp://username:[email protected] IP/ACMGJC_Router1-Log" pattern "SCP IP"
action 2.6 cli command "SCP IP" pattern "username"
action 2.7 cli command "username" pattern "ACMGJC_Router1-Log"
action 2.8 cli command "ACMGJC_Router1-Log-$_event_pub_sec"
action 3.5 cli command "delete flash:/dailylog" pattern "dailylog"
action 3.6 cli command "dailylog" pattern "confirm"
action 3.7 cli command "y"



ACMGJC_Router1#
ACMGJC_Router1#    
Jul  2 16:00:00.012: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : CTL : cli_open called.
Jul  2 16:00:00.036: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : ACMGJC_Router1>
Jul  2 16:00:00.036: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : IN  : ACMGJC_Router1>enable
Jul  2 16:00:00.048: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : ACMGJC_Router1#
Jul  2 16:00:00.052: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : IN  : ACMGJC_Router1#show process cpu history | redirect flash:/dailylog
Jul  2 16:00:00.612: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT :
Jul  2 16:00:00.612: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : ACMGJC_Router1#
Jul  2 16:00:00.612: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : IN  : ACMGJC_Router1#show interface fastethernet 0/0 | append flash:/dailylog
Jul  2 16:00:01.028: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT :
Jul  2 16:00:01.028: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : ACMGJC_Router1#
Jul  2 16:00:01.028: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : IN  : ACMGJC_Router1#show interface fastethernet 0/1 | append flash:/dailylog
Jul  2 16:00:01.456: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT :
Jul  2 16:00:01.456: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : ACMGJC_Router1#
Jul  2 16:00:01.456: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : IN  : ACMGJC_Router1#show ip nat statistics | append flash:/dailylog
Jul  2 16:00:01.796: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT :
Jul  2 16:00:01.796: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : ACMGJC_Router1#
Jul  2 16:00:01.796: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : IN  : ACMGJC_Router1#show ip nat translations | append flash:/dailylog
Jul  2 16:00:05.823: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : ACMGJC_Router1#
Jul  2 16:00:05.823: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : IN  : ACMGJC_Router1#copy flash:/dailylog scp://username:[email protected] IP/ACMGJC_Router1-Log
Jul  2 16:00:05.935: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : Address or name of remote host [SCP IP]?
Jul  2 16:00:05.935: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : IN  : SCP IP
Jul  2 16:00:05.947: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : Destination username [username]?
Jul  2 16:00:05.947: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : IN  : username
Jul  2 16:00:05.959: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : Destination filename [ACMGJC_Router1-Log]?
Jul  2 16:00:05.959: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : IN  : ACMGJC_Router1-Log-1278086400
Jul  2 16:00:17.026: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : Writing ACMGJC_Router1-Log-1278086400 !!
Jul  2 16:00:17.026: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : 270152 bytes copied in 11.016 secs (24524 bytes/sec)
Jul  2 16:00:17.026: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : ACMGJC_Router1#
Jul  2 16:00:17.030: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : IN  : ACMGJC_Router1#delete flash:/dailylog
Jul  2 16:00:17.042: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : delete flash:/dailylog
Jul  2 16:00:17.042: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : Delete filename [dailylog]?
Jul  2 16:00:17.042: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : IN  : dailylog
Jul  2 16:00:17.054: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : dailylog
Jul  2 16:00:17.054: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : Delete flash:dailylog? [confirm]
Jul  2 16:00:17.054: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : IN  : y
Jul  2 16:00:17.270: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : y
Jul  2 16:00:17.270: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : ACMGJC_Router1#
Jul  2 16:00:17.270: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : ACMGJC_Router1#
Jul  2 16:00:17.270: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : CTL : cli_close called.
Jul  2 16:01:00.006: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : CTL : cli_open called.

Jul  2 16:01:00.034: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : ACMGJC_Router1>
Jul  2 16:01:00.034: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : IN  : ACMGJC_Router1>enable
Jul  2 16:01:00.050: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : ACMGJC_Router1#
Jul  2 16:01:00.050: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : IN  : ACMGJC_Router1#show process cpu history | redirect flash:/dailylog
Jul  2 16:01:00.602: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT :
Jul  2 16:01:00.602: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : ACMGJC_Router1#
Jul  2 16:01:00.606: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : IN  : ACMGJC_Router1#show interface fastethernet 0/0 | append flash:/dailylog
Jul  2 16:01:01.022: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT :
Jul  2 16:01:01.022: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : ACMGJC_Router1#
Jul  2 16:01:01.022: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : IN  : ACMGJC_Router1#show interface fastethernet 0/1 | append flash:/dailylog
Jul  2 16:01:01.298: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT :
Jul  2 16:01:01.298: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : ACMGJC_Router1#
Jul  2 16:01:01.298: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : IN  : ACMGJC_Router1#show ip nat statistics | append flash:/dailylog
Jul  2 16:01:01.562: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT :
Jul  2 16:01:01.562: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : ACMGJC_Router1#
Jul  2 16:01:01.562: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : IN  : ACMGJC_Router1#show ip nat translations | append flash:/dailylog
Jul  2 16:01:05.654: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : ACMGJC_Router1#
Jul  2 16:01:05.654: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : IN  : ACMGJC_Router1#copy flash:/dailylog scp://username:[email protected] IP/ACMGJC_Router1-Log
Jul  2 16:01:05.766: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : Address or name of remote host [SCP IP]?
Jul  2 16:01:05.766: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : IN  : SCP IP
Jul  2 16:01:05.778: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : Destination username [username]?
Jul  2 16:01:05.778: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : IN  : username
Jul  2 16:01:05.790: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : Destination filename [ACMGJC_Router1-Log]?
Jul  2 16:01:05.790: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : IN  : ACMGJC_Router1-Log-1278086459
Jul  2 16:01:16.645: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : Writing ACMGJC_Router1-Log-1278086459 !!
Jul  2 16:01:16.645: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : 266007 bytes copied in 10.816 secs (24594 bytes/sec)
Jul  2 16:01:16.645: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : ACMGJC_Router1#
Jul  2 16:01:16.649: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : IN  : ACMGJC_Router1#delete flash:/dailylog
Jul  2 16:01:16.661: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : delete flash:/dailylog
Jul  2 16:01:16.661: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : Delete filename [dailylog]?
Jul  2 16:01:16.661: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : IN  : dailylog
Jul  2 16:01:16.673: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : dailylog
Jul  2 16:01:16.673: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : Delete flash:dailylog? [confirm]
Jul  2 16:01:16.673: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : IN  : y
Jul  2 16:01:16.885: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : y
Jul  2 16:01:16.889: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : ACMGJC_Router1#
Jul  2 16:01:16.889: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : OUT : ACMGJC_Router1#
Jul  2 16:01:16.889: %HA_EM-6-LOG: HourlyLog : DEBUG(cli_lib) : : CTL : cli_close called.       
ACMGJC_Router1#

Correct Answer by Joe Clarke about 6 years 10 months ago

This sounds like CSCsz12460 in which cron events can run twice in the same minute (if NTP is being used).

  • 1
  • 2
  • 3
  • 4
  • 5
Overall Rating: 5 (1 ratings)
Loading.
Correct Answer
Joe Clarke Fri, 07/02/2010 - 22:46
User Badges:
  • Cisco Employee,
  • Hall of Fame,

    Founding Member

This sounds like CSCsz12460 in which cron events can run twice in the same minute (if NTP is being used).

Actions

This Discussion