| forum.bitel.ru http://forum.bitel.ru/ |
|
| [BGScheduler] Не удалось запустить периодическую задачу http://forum.bitel.ru/viewtopic.php?f=22&t=6120 |
Страница 1 из 1 |
| Автор: | jack7 [ 14 ноя 2011, 16:39 ] |
| Заголовок сообщения: | [BGScheduler] Не удалось запустить периодическую задачу |
Код: Сервер: вер. 5.1 сборка 690 от 11.07.2011 18:42:40 os:Linux; java:Sun Microsystems Inc., v.1.6.0_27 Код: ./scheduler.sh -estatus Sending 'status' on 127.0.0.1:9066 Result: TaskExecuter working Started: 10.11.2011 14:27:53 Uptime: 4 d 02:08:24 Memory total: 278 396 928; max: 3 817 799 680; free: 250 656 160 Memory pools: Non-heap memory[Code Cache]: max: 50 331 648; used: 3 763 392; peek: 3 859 008 Heap memory[PS Eden Space]: max: 1 425 735 680; used: 21 563 440; peek: 147 849 216 Heap memory[PS Survivor Space]: max: 2 883 584; used: 0; peek: 4 717 088 Heap memory[PS Old Gen]: max: 2 863 333 376; used: 6 180 256; peek: 9 319 920 Non-heap memory[PS Perm Gen]: max: 85 983 232; used: 26 247 176; peek: 26 247 176 Thread count: 1018 Trees in cache: 3 Periodic tasks executed: 1710; active: 0 Urgent tasks executed: 1; active: 0 Connections pool to Master status Idle: 11; Active: 0; maxActive: 10000; maxIdle: 5000 Постоянно прилетают письма с таким содержанием Код: ID события: scheduler.periodic.task.run.skip Время регистрации события: 14.11.2011 15:30:03 В рамках отведенного переменной scheduler.periodic.thread.count максимального числа одновременно запущенных потоков планировщик не смог запустить периодическую задачу с кодом 1. Необходимо выяснить причину медленного завершения периодических задач, запущенных ранее код 1 это рассыльщик почты лог scheduler.error.log пуст scheduller.log Код: 11-14/16:30:03 INFO [Thread-8] LoggingPrintStream - Executing status
11-14/16:30:03 INFO [Thread-6] TaskExecuter - Starting periodic task ID: 1 bitel.billing.server.mail.MailSender 11-14/16:30:03 INFO [Thread-6] TaskExecuter - Starting periodic task ID: 3 bitel.billing.server.ipn.IPNTestGates 11-14/16:30:03 INFO [Thread-6] TaskExecuter - Starting periodic task ID: 6 ru.bitel.bgbilling.plugins.helpdesk.server.task.NewMessageChecker 11-14/16:30:03 INFO [Thread-6] TaskExecuter - Starting periodic task ID: 7 ru.bitel.bgbilling.plugins.helpdesk.server.task.TopicAvtoClosedTask 11-14/16:30:03 INFO [Thread-6] TaskExecuter - Starting periodic task ID: 11 bitel.billing.server.ipn.LogCalculator 11-14/16:30:03 INFO [pool-2-thread-708] NewMessageChecker - Task finished time=3 ms. 11-14/16:30:03 INFO [pool-2-thread-710] TopicAvtoClosedTask - Task finished time=3 ms. 11-14/16:30:03 INFO [pool-2-thread-704] MailSender - Task finished time=13 ms. 11-14/16:30:03 INFO [pool-2-thread-709] LogCalculator - Difficult mode INIT 11-14/16:30:03 INFO [pool-2-thread-705] IPNTestGates - Locked clients: 11-14/16:30:03 INFO [pool-2-thread-705] IPNTestGates - 11-14/16:30:03 INFO [pool-2-thread-705] IPNTestGates - Task finished time=128 ms. 11-14/16:30:03 ERROR [pool-2-thread-709] LogCalculator - Tree not found for contract id: 11354 11-14/16:30:03 ERROR [pool-2-thread-709] LogCalculator - Tree not found for contract id: 11354 11-14/16:30:03 ERROR [pool-2-thread-709] LogCalculator - Tree not found for contract id: 11354 11-14/16:30:03 ERROR [pool-2-thread-709] LogCalculator - Tree not found for contract id: 11354 11-14/16:30:03 ERROR [pool-2-thread-709] LogCalculator - Tree not found for contract id: 11354 11-14/16:30:03 ERROR [pool-2-thread-709] LogCalculator - Tree not found for contract id: 11354 11-14/16:30:03 ERROR [pool-2-thread-709] LogCalculator - Tree not found for contract id: 11354 11-14/16:30:03 ERROR [pool-2-thread-709] LogCalculator - Tree not found for contract id: 11354 11-14/16:30:03 ERROR [pool-2-thread-709] LogCalculator - Tree not found for contract id: 11354 11-14/16:30:03 INFO [pool-2-thread-709] LogCalculator - Init time => 221 ms 11-14/16:30:03 INFO [pool-2-thread-709] LogCalculator - Processing.. 11-14/16:30:03 INFO [pool-2-thread-709] LogCalculator - Select records time=6 ms. 11-14/16:30:04 INFO [pool-2-thread-709] LogCalculator - Records processing time=126 ms. 11-14/16:30:04 ERROR [pool-2-thread-709] LogCalculator - Detected errors => 1409 11-14/16:30:04 INFO [pool-2-thread-709] LogCalculator - Setting balances for independ and subs.. 11-14/16:30:04 INFO [pool-2-thread-709] LogCalculator - Task finished time=638 ms. 11-14/16:36:18 INFO [Thread-8] LoggingPrintStream - Executing status |
|
| Автор: | dimOn [ 14 ноя 2011, 18:30 ] |
| Заголовок сообщения: | Re: [BGScheduler] Не удалось запустить периодическую задачу |
Не уверен имеет ли это какое-либо отношение к обсуждаемому, но в одном из Цитата: Время регистрации события: 14.11.2011 15:30:03 Цитата: 11-14/16:30:03 INFO направильно настроены таймзоны |
|
| Автор: | jack7 [ 14 ноя 2011, 19:20 ] |
| Заголовок сообщения: | Re: [BGScheduler] Не удалось запустить периодическую задачу |
Цитата: # date Пнд Ноя 14 19:17:41 YEKT 2011 # ./radius.sh status version 5.1 build 243 from 06.12.2010 12:22:10 14.11.2011 19:17:48 5558 5558 0 0 Request accounts per minute start: 81; stop: 164; update: 5647 Request auths per minute accept: 81; reject: 56 Netfow packets per minute: 65617 Ignore per minute auth: 0; update: 0 Antispam ban count: 1; used per minute: 25 FlowListener: queue_size: 0; threads_active: 0; largest: 100; core: 100; pool_size: 100; recv_socket_buf_size: 131 071; recv_buf_size: 8 388 608; packets: 0 FlowListener: queue_size: 0; threads_active: 0; largest: 100; core: 100; pool_size: 100; recv_socket_buf_size: 131 071; recv_buf_size: 8 388 608; packets: 0 FlowListener: queue_size: 0; threads_active: 0; largest: 100; core: 100; pool_size: 100; recv_socket_buf_size: 131 071; recv_buf_size: 8 388 608; packets: 0 FlowListener: queue_size: 0; threads_active: 0; largest: 100; core: 100; pool_size: 100; recv_socket_buf_size: 131 071; recv_buf_size: 8 388 608; packets: 21850 Started: 31.10.2011 13:42:10 Uptime: 14 d 05:35:37 Memory total: 290 521 088; max: 3 817 799 680; free: 18 474 952 Memory pools: Non-heap memory[Code Cache]: max: 50 331 648; used: 10 828 352; peek: 10 838 976 Heap memory[PS Eden Space]: max: 1 423 245 312; used: 67 669 592; peek: 1 407 909 888 Heap memory[PS Survivor Space]: max: 4 128 768; used: 3 686 448; peek: 52 810 000 Heap memory[PS Old Gen]: max: 2 863 333 376; used: 200 690 096; peek: 395 961 864 Non-heap memory[PS Perm Gen]: max: 85 983 232; used: 26 776 872; peek: 26 866 688 Thread count: 671 Trees in cache: 15 Connections pool to Master status Idle: 194; Active: 0; maxActive: 10000; maxIdle: 5000 # echo $JAVA_HOME /opt/java/jre # JAVA_HOME везде в скриптах используется патч ставил для java еще в октябре где у меня может быть неправильно? |
|
| Автор: | stark [ 14 ноя 2011, 19:43 ] |
| Заголовок сообщения: | Re: [BGScheduler] Не удалось запустить периодическую задачу |
Если такое случиться, вызовите jstack(входит в jdk) для процесса шедулера и результат покажите тут . B еще в клиенте есть вкладка - состояние планировщика , можно посмотреть какие задачи сейчас запущены. |
|
| Автор: | stark [ 14 ноя 2011, 19:45 ] |
| Заголовок сообщения: | Re: [BGScheduler] Не удалось запустить периодическую задачу |
jack7 писал(а): Цитата: # date Пнд Ноя 14 19:17:41 YEKT 2011 # ./radius.sh status version 5.1 build 243 from 06.12.2010 12:22:10 14.11.2011 19:17:48 5558 5558 0 0 Request accounts per minute start: 81; stop: 164; update: 5647 Request auths per minute accept: 81; reject: 56 Netfow packets per minute: 65617 Ignore per minute auth: 0; update: 0 Antispam ban count: 1; used per minute: 25 FlowListener: queue_size: 0; threads_active: 0; largest: 100; core: 100; pool_size: 100; recv_socket_buf_size: 131 071; recv_buf_size: 8 388 608; packets: 0 FlowListener: queue_size: 0; threads_active: 0; largest: 100; core: 100; pool_size: 100; recv_socket_buf_size: 131 071; recv_buf_size: 8 388 608; packets: 0 FlowListener: queue_size: 0; threads_active: 0; largest: 100; core: 100; pool_size: 100; recv_socket_buf_size: 131 071; recv_buf_size: 8 388 608; packets: 0 FlowListener: queue_size: 0; threads_active: 0; largest: 100; core: 100; pool_size: 100; recv_socket_buf_size: 131 071; recv_buf_size: 8 388 608; packets: 21850 Started: 31.10.2011 13:42:10 Uptime: 14 d 05:35:37 Memory total: 290 521 088; max: 3 817 799 680; free: 18 474 952 Memory pools: Non-heap memory[Code Cache]: max: 50 331 648; used: 10 828 352; peek: 10 838 976 Heap memory[PS Eden Space]: max: 1 423 245 312; used: 67 669 592; peek: 1 407 909 888 Heap memory[PS Survivor Space]: max: 4 128 768; used: 3 686 448; peek: 52 810 000 Heap memory[PS Old Gen]: max: 2 863 333 376; used: 200 690 096; peek: 395 961 864 Non-heap memory[PS Perm Gen]: max: 85 983 232; used: 26 776 872; peek: 26 866 688 Thread count: 671 Trees in cache: 15 Connections pool to Master status Idle: 194; Active: 0; maxActive: 10000; maxIdle: 5000 # echo $JAVA_HOME /opt/java/jre # JAVA_HOME везде в скриптах используется патч ставил для java еще в октябре где у меня может быть неправильно? Логи из планировщика , а статус радиуса ..Причем он работает 14 дней . Вы тогда уж статус планировщика покажите и перезагружали ли вы его с 31-го числа ? |
|
| Автор: | jack7 [ 14 ноя 2011, 20:02 ] |
| Заголовок сообщения: | Re: [BGScheduler] Не удалось запустить периодическую задачу |
Цитата: # date
Пнд Ноя 14 20:00:28 YEKT 2011 # ./scheduler.sh -estop Sending 'stop' on 127.0.0.1:9066 Result: ok # ./scheduler.sh -estart # ./scheduler.sh -estatus Sending 'status' on 127.0.0.1:9066 Result: TaskExecuter working Started: 14.11.2011 20:00:59 Uptime: 0 d 00:00:18 Memory total: 252 575 744; max: 3 817 799 680; free: 214 230 152 Memory pools: Non-heap memory[Code Cache]: max: 50 331 648; used: 730 176; peek: 736 000 Heap memory[PS Eden Space]: max: 1 409 744 896; used: 38 345 592; peek: 38 345 592 Heap memory[PS Survivor Space]: max: 10 944 512; used: 0; peek: 0 Heap memory[PS Old Gen]: max: 2 863 333 376; used: 0; peek: 0 Non-heap memory[PS Perm Gen]: max: 85 983 232; used: 14 777 760; peek: 14 777 760 Thread count: 13 Trees in cache: 0 Periodic tasks executed: 0; active: 0 Urgent tasks executed: 0; active: 0 Connections pool to Master status Idle: 2; Active: 0; maxActive: 10000; maxIdle: 5000 |
|
| Автор: | jack7 [ 14 ноя 2011, 20:34 ] |
| Заголовок сообщения: | Re: [BGScheduler] Не удалось запустить периодическую задачу |
Цитата: 2011-11-14 20:31:45
Full thread dump Java HotSpot(TM) 64-Bit Server VM (20.2-b06 mixed mode): "Attach Listener" daemon prio=10 tid=0x0000000041339000 nid=0x4c5c waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE "InactivityMonitor Async Task: java.util.concurrent.ThreadPoolExecutor$Worker@67ee75a5" daemon prio=10 tid=0x00007fcde4159000 nid=0x4c37 waiting on condition [0x00007fcdeb8f7000] java.lang.Thread.State: TIMED_WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x00000007002cf310> (a java.util.concurrent.SynchronousQueue$TransferStack) at java.util.concurrent.locks.LockSupport.parkNanos(Unknown Source) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(Unknown Source) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(Unknown Source) at java.util.concurrent.SynchronousQueue.poll(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.getTask(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) "pool-2-thread-8" prio=10 tid=0x000000004133d000 nid=0x4a4f waiting on condition [0x00007fcdebaf9000] java.lang.Thread.State: WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x0000000700007d00> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.park(Unknown Source) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(Unknown Source) at java.util.concurrent.LinkedBlockingQueue.take(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.getTask(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) "pool-2-thread-7" prio=10 tid=0x000000004127c000 nid=0x4a4e waiting on condition [0x00007fcdebbfa000] java.lang.Thread.State: WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x0000000700007d00> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.park(Unknown Source) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(Unknown Source) at java.util.concurrent.LinkedBlockingQueue.take(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.getTask(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) "pool-2-thread-6" prio=10 tid=0x0000000041832800 nid=0x4a4d waiting on condition [0x00007fcdf04ac000] java.lang.Thread.State: WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x0000000700007d00> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.park(Unknown Source) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(Unknown Source) at java.util.concurrent.LinkedBlockingQueue.take(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.getTask(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) "pool-2-thread-5" prio=10 tid=0x0000000041780800 nid=0x4a4c waiting on condition [0x00007fcdf03ab000] java.lang.Thread.State: WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x0000000700007d00> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.park(Unknown Source) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(Unknown Source) at java.util.concurrent.LinkedBlockingQueue.take(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.getTask(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) "pool-2-thread-4" prio=10 tid=0x0000000041779800 nid=0x4a4b waiting on condition [0x00007fcdebcfb000] java.lang.Thread.State: WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x0000000700007d00> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.park(Unknown Source) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(Unknown Source) at java.util.concurrent.LinkedBlockingQueue.take(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.getTask(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) "ActiveMQ Scheduler" daemon prio=10 tid=0x00007fcde406c800 nid=0x4256 in Object.wait() [0x00007fcdebffe000] java.lang.Thread.State: WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x0000000700000108> (a java.util.TaskQueue) at java.lang.Object.wait(Object.java:485) at java.util.TimerThread.mainLoop(Unknown Source) - locked <0x0000000700000108> (a java.util.TaskQueue) at java.util.TimerThread.run(Unknown Source) "InactivityMonitor WriteCheck" daemon prio=10 tid=0x0000000041281000 nid=0x424f in Object.wait() [0x00007fcdebdfc000] java.lang.Thread.State: TIMED_WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x0000000700007c40> (a java.util.TaskQueue) at java.util.TimerThread.mainLoop(Unknown Source) - locked <0x0000000700007c40> (a java.util.TaskQueue) at java.util.TimerThread.run(Unknown Source) "InactivityMonitor ReadCheck" daemon prio=10 tid=0x000000004123d800 nid=0x424e in Object.wait() [0x00007fcdebefd000] java.lang.Thread.State: TIMED_WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x0000000700000240> (a java.util.TaskQueue) at java.util.TimerThread.mainLoop(Unknown Source) - locked <0x0000000700000240> (a java.util.TaskQueue) at java.util.TimerThread.run(Unknown Source) "Selector Worker: 0" daemon prio=10 tid=0x00000000417a9800 nid=0x4249 runnable [0x00007fcdf01a2000] java.lang.Thread.State: RUNNABLE at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method) at sun.nio.ch.EPollArrayWrapper.poll(Unknown Source) at sun.nio.ch.EPollSelectorImpl.doSelect(Unknown Source) at sun.nio.ch.SelectorImpl.lockAndDoSelect(Unknown Source) - locked <0x0000000700000380> (a sun.nio.ch.Util$2) - locked <0x0000000700000390> (a java.util.Collections$UnmodifiableSet) - locked <0x0000000700000338> (a sun.nio.ch.EPollSelectorImpl) at sun.nio.ch.SelectorImpl.select(Unknown Source) at org.apache.activemq.transport.nio.SelectorWorker.run(SelectorWorker.java:93) at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) "pool-2-thread-3" prio=10 tid=0x00000000417d8000 nid=0x41ba waiting on condition [0x00007fcdf05ad000] java.lang.Thread.State: WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x0000000700007d00> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.park(Unknown Source) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(Unknown Source) at java.util.concurrent.LinkedBlockingQueue.take(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.getTask(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) "pool-2-thread-2" prio=10 tid=0x00000000417f2800 nid=0x41b9 waiting on condition [0x00007fcdf06ae000] java.lang.Thread.State: WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x0000000700007d00> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.park(Unknown Source) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(Unknown Source) at java.util.concurrent.LinkedBlockingQueue.take(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.getTask(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) "pool-2-thread-1" prio=10 tid=0x000000004119d000 nid=0x41b8 waiting on condition [0x00007fcdf07af000] java.lang.Thread.State: WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x0000000700007d00> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.park(Unknown Source) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(Unknown Source) at java.util.concurrent.LinkedBlockingQueue.take(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.getTask(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) "DestroyJavaVM" prio=10 tid=0x00007fcde4069800 nid=0x3a18 waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE "Thread-13" prio=10 tid=0x00007fcde4067800 nid=0x3a32 waiting on condition [0x00007fcdf08b0000] java.lang.Thread.State: TIMED_WAITING (sleeping) at java.lang.Thread.sleep(Native Method) at bitel.billing.server.admin.eventbus.BillingEventBus$ListenerThread.run(BillingEventBus.java:242) "Thread-12" prio=10 tid=0x00007fcde4200800 nid=0x3a31 waiting on condition [0x00007fcdf09b1000] java.lang.Thread.State: TIMED_WAITING (sleeping) at java.lang.Thread.sleep(Native Method) at bitel.billing.server.admin.eventbus.BillingEventBus$PublishThread.run(BillingEventBus.java:101) "Thread-6" prio=10 tid=0x00000000417e5800 nid=0x3a30 waiting on condition [0x00007fcdf0ab2000] java.lang.Thread.State: TIMED_WAITING (sleeping) at java.lang.Thread.sleep(Native Method) at bitel.billing.server.TaskExecuter.run(TaskExecuter.java:202) "Thread-11" prio=10 tid=0x00000000417a1000 nid=0x3a2f waiting on condition [0x00007fcdf0bb3000] java.lang.Thread.State: TIMED_WAITING (sleeping) at java.lang.Thread.sleep(Native Method) at bitel.billing.server.admin.errorlog.AlarmSender.run(AlarmSender.java:222) "Thread-10" prio=10 tid=0x000000004184c000 nid=0x3a2e waiting on condition [0x00007fcdf0dbb000] java.lang.Thread.State: TIMED_WAITING (sleeping) at java.lang.Thread.sleep(Native Method) at bitel.billing.server.task.TaskRunProcessor.run(TaskRunProcessor.java:68) "Thread-8" prio=10 tid=0x00000000417f4800 nid=0x3a2d runnable [0x00007fcdf0ebc000] java.lang.Thread.State: RUNNABLE at java.net.PlainSocketImpl.socketAccept(Native Method) at java.net.PlainSocketImpl.accept(Unknown Source) - locked <0x0000000700047890> (a java.net.SocksSocketImpl) at java.net.ServerSocket.implAccept(Unknown Source) at java.net.ServerSocket.accept(Unknown Source) at bitel.billing.server.util.manager.ProcessManager.run(ProcessManager.java:39) "MySQL Statement Cancellation Timer" daemon prio=10 tid=0x0000000041774000 nid=0x3a2c in Object.wait() [0x00007fcdf0fbd000] java.lang.Thread.State: WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x0000000700047a30> (a java.util.TaskQueue) at java.lang.Object.wait(Object.java:485) at java.util.TimerThread.mainLoop(Unknown Source) - locked <0x0000000700047a30> (a java.util.TaskQueue) at java.util.TimerThread.run(Unknown Source) "Thread-3" daemon prio=10 tid=0x00000000416fc800 nid=0x3a2b waiting on condition [0x00007fcdf14dd000] java.lang.Thread.State: TIMED_WAITING (sleeping) at java.lang.Thread.sleep(Native Method) at org.apache.log4j.helpers.FileWatchdog.run(FileWatchdog.java:104) "Dispatcher-Thread-4" daemon prio=10 tid=0x00000000416ca800 nid=0x3a2a waiting on condition [0x00007fcdf15de000] java.lang.Thread.State: TIMED_WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x0000000700047b60> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.parkNanos(Unknown Source) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(Unknown Source) at java.util.concurrent.LinkedBlockingQueue.poll(Unknown Source) at ru.bitel.common.logging.Dispatcher.run(Log4jAsyncAppender.java:293) "Low Memory Detector" daemon prio=10 tid=0x0000000041229000 nid=0x3a27 runnable [0x0000000000000000] java.lang.Thread.State: RUNNABLE "C2 CompilerThread1" daemon prio=10 tid=0x00007fcdec009800 nid=0x3a26 waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE "C2 CompilerThread0" daemon prio=10 tid=0x00007fcdec006800 nid=0x3a25 waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE "Signal Dispatcher" daemon prio=10 tid=0x0000000041225800 nid=0x3a24 runnable [0x0000000000000000] java.lang.Thread.State: RUNNABLE "Finalizer" daemon prio=10 tid=0x000000004120b800 nid=0x3a23 in Object.wait() [0x00007fcdf1fc5000] java.lang.Thread.State: WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x000000070004a5d0> (a java.lang.ref.ReferenceQueue$Lock) at java.lang.ref.ReferenceQueue.remove(Unknown Source) - locked <0x000000070004a5d0> (a java.lang.ref.ReferenceQueue$Lock) at java.lang.ref.ReferenceQueue.remove(Unknown Source) at java.lang.ref.Finalizer$FinalizerThread.run(Unknown Source) "Reference Handler" daemon prio=10 tid=0x0000000041209000 nid=0x3a22 in Object.wait() [0x00007fcdf20c6000] java.lang.Thread.State: WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x00000007000006c0> (a java.lang.ref.Reference$Lock) at java.lang.Object.wait(Object.java:485) at java.lang.ref.Reference$ReferenceHandler.run(Unknown Source) - locked <0x00000007000006c0> (a java.lang.ref.Reference$Lock) "VM Thread" prio=10 tid=0x0000000041202800 nid=0x3a21 runnable "GC task thread#0 (ParallelGC)" prio=10 tid=0x00000000411b0000 nid=0x3a19 runnable "GC task thread#1 (ParallelGC)" prio=10 tid=0x00000000411b1800 nid=0x3a1a runnable "GC task thread#2 (ParallelGC)" prio=10 tid=0x00000000411b3800 nid=0x3a1b runnable "GC task thread#3 (ParallelGC)" prio=10 tid=0x00000000411b5800 nid=0x3a1c runnable "GC task thread#4 (ParallelGC)" prio=10 tid=0x00000000411b7000 nid=0x3a1d runnable "GC task thread#5 (ParallelGC)" prio=10 tid=0x00000000411b9000 nid=0x3a1e runnable "GC task thread#6 (ParallelGC)" prio=10 tid=0x00000000411bb000 nid=0x3a1f runnable "GC task thread#7 (ParallelGC)" prio=10 tid=0x00000000411bd000 nid=0x3a20 runnable "VM Periodic Task Thread" prio=10 tid=0x0000000041233800 nid=0x3a28 waiting on condition JNI global references: 1224 |
|
| Автор: | jack7 [ 14 ноя 2011, 22:56 ] |
| Заголовок сообщения: | Re: [BGScheduler] Не удалось запустить периодическую задачу |
в письме приходит некорректное время (на час запаздывает) Цитата: ID события: scheduler.periodic.task.run.skip Время регистрации события: 14.11.2011 20:30:04 простой класс для проверки времени Код: $ cat DatePrint.java import java.text.SimpleDateFormat; import java.util.Date; import java.util.*; public class DatePrint { public static void main(String[] args) { Date dtn = new Date(); SimpleDateFormat formatter1 = new SimpleDateFormat( "dd.MM.yyyy HH:mm:ss"); String dt1=formatter1.format(dtn); System.out.println( dt1); } } $ javac DatePrint.java $ echo $JAVA_HOME /opt/java/jre $ /opt/java/jre/bin/java DatePrint 14.11.2011 22:51:38 $ date Пнд Ноя 14 22:51:40 YEKT 2011 $ в биллинге для это используется класс TimeUtils Цитата: /* 213 */ StringBuilder text = new StringBuilder(200); /* 214 */ text.append("ID события: "); /* 215 */ text.append(message.getKey()); /* 216 */ text.append("\n"); /* 217 */ text.append("Время регистрации события: "); /* 218 */ text.append(TimeUtils.format(message.getRegistrationTime(), "dd.MM.yyyy HH:mm:ss")); /* 219 */ text.append("\n\n"); /* 220 */ text.append(message.getText()); при этом в scheduler.log время корректное Код: $ cat log/scheduler.log | grep "21:30"
11-14/21:30:01 INFO [Thread-6] TaskExecuter - Starting periodic task ID: 1 bitel.billing.server.mail.MailSender 11-14/21:30:01 INFO [Thread-6] TaskExecuter - Starting periodic task ID: 3 bitel.billing.server.ipn.IPNTestGates 11-14/21:30:01 INFO [Thread-6] TaskExecuter - Starting periodic task ID: 6 ru.bitel.bgbilling.plugins.helpdesk.server.task.NewMessageChecker 11-14/21:30:01 INFO [Thread-6] TaskExecuter - Starting periodic task ID: 7 ru.bitel.bgbilling.plugins.helpdesk.server.task.TopicAvtoClosedTask 11-14/21:30:01 INFO [Thread-6] TaskExecuter - Starting periodic task ID: 11 bitel.billing.server.ipn.LogCalculator 11-14/21:30:01 INFO [pool-2-thread-23] NewMessageChecker - Task finished time=3 ms. 11-14/21:30:01 INFO [pool-2-thread-24] TopicAvtoClosedTask - Task finished time=5 ms. 11-14/21:30:01 INFO [pool-2-thread-21] MailSender - Task finished time=10 ms. 11-14/21:30:01 INFO [pool-2-thread-22] IPNTestGates - Locked clients: 11-14/21:30:01 INFO [pool-2-thread-22] IPNTestGates - 11-14/21:30:01 INFO [pool-2-thread-25] LogCalculator - Difficult mode INIT 11-14/21:30:01 INFO [pool-2-thread-22] IPNTestGates - Task finished time=83 ms. 11-14/21:30:01 ERROR [pool-2-thread-25] LogCalculator - Tree not found for contract id: 11354 11-14/21:30:01 ERROR [pool-2-thread-25] LogCalculator - Tree not found for contract id: 11354 11-14/21:30:01 ERROR [pool-2-thread-25] LogCalculator - Tree not found for contract id: 11354 11-14/21:30:01 ERROR [pool-2-thread-25] LogCalculator - Tree not found for contract id: 11354 11-14/21:30:01 ERROR [pool-2-thread-25] LogCalculator - Tree not found for contract id: 11354 11-14/21:30:01 ERROR [pool-2-thread-25] LogCalculator - Tree not found for contract id: 11354 11-14/21:30:01 ERROR [pool-2-thread-25] LogCalculator - Tree not found for contract id: 11354 11-14/21:30:01 ERROR [pool-2-thread-25] LogCalculator - Tree not found for contract id: 11354 11-14/21:30:01 ERROR [pool-2-thread-25] LogCalculator - Tree not found for contract id: 11354 11-14/21:30:01 INFO [pool-2-thread-25] LogCalculator - Init time => 179 ms 11-14/21:30:01 INFO [pool-2-thread-25] LogCalculator - Processing.. 11-14/21:30:01 INFO [pool-2-thread-25] LogCalculator - Select records time=4 ms. 11-14/21:30:01 INFO [pool-2-thread-25] LogCalculator - Records processing time=138 ms. 11-14/21:30:01 ERROR [pool-2-thread-25] LogCalculator - Detected errors => 1429 11-14/21:30:02 INFO [pool-2-thread-25] LogCalculator - Setting balances for independ and subs.. 11-14/21:30:02 INFO [pool-2-thread-25] LogCalculator - Task finished time=869 ms. 11-14/21:30:04 INFO [Thread-8] LoggingPrintStream - Executing status |
|
| Автор: | jack7 [ 15 ноя 2011, 01:37 ] |
| Заголовок сообщения: | Re: [BGScheduler] Не удалось запустить периодическую задачу |
можно проверить через action ЛК такой Код: package bitel.billing.server.contract.action.web; import java.io.*; import java.util.*; import java.sql.ResultSet; import bitel.billing.common.TimeUtils; import bitel.billing.server.contract.bean.*; import bitel.billing.server.script.bean.event.Event.*; import bitel.billing.server.contract.action.base.ActionBase; import java.text.SimpleDateFormat; public final class ActionDate extends ActionBase { public void doAction() { Date dtn = new Date(); SimpleDateFormat formatter1 = new SimpleDateFormat( "dd.MM.yyyy HH:mm:ss"); String dt1=formatter1.format(dtn); System.out.println( dt1); }; }; делаю запрос с action=Date и вижу в логах Код: # tail -1 server.log
11-15/01:32:56 INFO [http-8080-43] LoggingPrintStream - 15.11.2011 01:32:56 # date Втр Ноя 15 01:33:03 YEKT 2011 # |
|
| Автор: | dimOn [ 15 ноя 2011, 14:19 ] |
| Заголовок сообщения: | Re: [BGScheduler] Не удалось запустить периодическую задачу |
так почему тогда время то отличается? вывод - при форматировании таймзона берётся некорректная! |
|
| Автор: | jack7 [ 15 ноя 2011, 14:38 ] |
| Заголовок сообщения: | Re: [BGScheduler] Не удалось запустить периодическую задачу |
1) в моем случае сервер синхронизирован по времени с помощью ntpdate руками время не ставил - если зона выставлена неверно, то при каждой синхронизации время слетало бы по поясу - у меня этого нет 2) откуда берет настройку зоны класс TimeUtils, если стандартный класс (в примере выше показан) показывает время идентичное с системным? 3) разница в 1 час всплывает только в письмах от планировщика, в клиенте, логах всех служб биллинга (в том числе и в логе планировщика) время идентично системному понимаю что возможно достал глупыми вопросами (извините в таком случае) - но для меня важно найти решение своей проблемы |
|
| Автор: | dimOn [ 15 ноя 2011, 16:05 ] |
| Заголовок сообщения: | Re: [BGScheduler] Не удалось запустить периодическую задачу |
класс TimeUtils форматирует дату и время точно таким же кодом, как у вас в примерах, через SimpleDateFormat итд. То есть буерутся обычные tz из явы. А под нужной явой точно запускаете приложение и ваши примеры? |
|
| Автор: | jack7 [ 15 ноя 2011, 17:17 ] |
| Заголовок сообщения: | Re: [BGScheduler] Не удалось запустить периодическую задачу |
все примеры и биллинг запускаются через JAVA_HOME (в постах показано) |
|
| Автор: | skyb [ 15 ноя 2011, 17:19 ] |
| Заголовок сообщения: | Re: [BGScheduler] Не удалось запустить периодическую задачу |
А шедулер ребутили после обновлении явы? |
|
| Автор: | jack7 [ 15 ноя 2011, 17:22 ] |
| Заголовок сообщения: | Re: [BGScheduler] Не удалось запустить периодическую задачу |
skyb писал(а): А шедулер ребутили после обновлении явы? это первое, что я попробовал |
|
| Автор: | jack7 [ 15 ноя 2011, 17:34 ] |
| Заголовок сообщения: | Re: [BGScheduler] Не удалось запустить периодическую задачу |
вот к примеру запускаю простой класс с выводом даты Код: $ /opt/java/jre/bin/java -verbose DatePrint | grep Date [Loaded DatePrint from file:/home/j4ck/scripts/] [Loaded java.util.Date from /opt/java/jre1.6.0_27/lib/rt.jar] [Loaded java.text.DateFormat from /opt/java/jre1.6.0_27/lib/rt.jar] [Loaded java.text.SimpleDateFormat from /opt/java/jre1.6.0_27/lib/rt.jar] [Loaded java.text.DateFormat$Field from /opt/java/jre1.6.0_27/lib/rt.jar] [Loaded sun.util.calendar.CalendarDate from /opt/java/jre1.6.0_27/lib/rt.jar] [Loaded sun.util.calendar.BaseCalendar$Date from /opt/java/jre1.6.0_27/lib/rt.jar] [Loaded sun.util.calendar.Gregorian$Date from /opt/java/jre1.6.0_27/lib/rt.jar] [Loaded java.text.DateFormatSymbols from /opt/java/jre1.6.0_27/lib/rt.jar] [Loaded java.text.spi.DateFormatSymbolsProvider from /opt/java/jre1.6.0_27/lib/rt.jar] теперь где лежит ява Код: $ echo $JAVA_HOME /opt/java/jre $ ls -la /opt/java/ итого 16 drwxr-xr-x 3 root root 4096 Сен 16 21:54 . drwxr-xr-x 5 root root 4096 Авг 22 13:12 .. lrwxrwxrwx 1 root root 11 Сен 16 21:54 jre -> jre1.6.0_27 drwxr-xr-x 8 root root 4096 Сен 15 11:14 jre1.6.0_27 шедулер запускается также через JAVA_HOME Код: $ nohup ${JAVA_HOME}/bin/java -Dapp.name=BGScheduler -Xmx4096m -cp ${CLASSPATH} ru.bitel.common.bootstrap.Boot bitel.billing.server.TaskExecuter $1 $2 $3 > ./log/scheduler.out 2>&1 & echo $! > .run/bgscheduler.pid как видно ява используется одна |
|
| Автор: | jack7 [ 16 ноя 2011, 11:38 ] |
| Заголовок сообщения: | Re: [BGScheduler] Не удалось запустить периодическую задачу |
проблема скрывалась в настройках java (может кому-нибудь поможет) специфично для дистрибутива Debian Linux в системе установлено две java: JDK и JRE в JAVA_HOME указан путь до JRE, он же указан во всех скриптах биллинга в системе используется update-alterantives (для выбора нескольких вариантов ПО) если java запускать без указания полного пути запускается java из JDK перенастроил для использования по умолчанию java из JRE, перезапустил планировщик и время в письмах и в логах планировщика стало совпадать. |
|
| Автор: | dimOn [ 16 ноя 2011, 11:59 ] |
| Заголовок сообщения: | Re: [BGScheduler] Не удалось запустить периодическую задачу |
Ну вот и решение всё же, так и оказалось. А alterantives используется не только в debian, а также в федоре и многих других, потому и сказано, что пользоваться не какой-то там "системной без пути", а напрямую всё прописывать до нужной JRE! |
|
| Автор: | jack7 [ 16 ноя 2011, 14:51 ] |
| Заголовок сообщения: | Re: [BGScheduler] Не удалось запустить периодическую задачу |
dimOn, загвоздка в том что во всех скриптах установлена JAVA_HOME как начало запускаемой программы Код: ${JAVA_HOME}/bin/java а JAVA_HOME имеет абсолютный путь /opt/java/jre а относительным путем никогда не пользовался |
|
| Страница 1 из 1 | Часовой пояс: UTC + 5 часов [ Летнее время ] |
| Powered by phpBB® Forum Software © phpBB Group http://www.phpbb.com/ |
|