DatabaseError 'relation "call_event" does not exist

[javakami]

Hi Guys,

checking in the "celery-newfies-node1.log" I notice a DB error looking something like: "DatabaseError('relation "call_event" does not exist\nLINE 1: ..."
I don't yet no the impact of this error on the system but it seems not to be a good thing.
Has somebody else experienced the same thing? What is the impact of this error? How can I solve it?

PS: OS= CentOS 6.4, VERSION= NEWFIES-DIALER V2.6.2


[]# tail -n 50 celery-newfies-node1.log
[2013-07-29 12:44:03,739: INFO/MainProcess] Task dialer_campaign.tasks.CheckPendingcall[fdc88452-1e22-46fe-9c66-a0ec4e5000c8] succeeded in 0.0146720409393s: False
[2013-07-29 12:44:09,677: INFO/Beat] Scheduler: Sending due task dialer_campaign.tasks.campaign_running (dialer_campaign.tasks.campaign_running)
[2013-07-29 12:44:09,681: INFO/MainProcess] Got task from broker: dialer_campaign.tasks.campaign_running[8c931ae8-aecb-47d9-bfd0-53c4cde07aa6]
[2013-07-29 12:44:09,700: INFO/MainProcess] => Campaign name Hello World Campaign (id:1)
[2013-07-29 12:44:09,703: INFO/MainProcess] Got task from broker: dialer_campaign.tasks.CheckPendingcall[6b63bb0e-760f-44b2-847f-c29db4302b93]
[2013-07-29 12:44:09,705: INFO/MainProcess] Task dialer_campaign.tasks.campaign_running[8c931ae8-aecb-47d9-bfd0-53c4cde07aa6] succeeded in 0.0145699977875s: True
[2013-07-29 12:44:09,710: INFO/PoolWorker-2] TASK :: CheckPendingcall = 1
[2013-07-29 12:44:09,725: INFO/PoolWorker-2] campaign_id=1 #Subscriber: 0
[2013-07-29 12:44:09,727: INFO/MainProcess] Task dialer_campaign.tasks.CheckPendingcall[6b63bb0e-760f-44b2-847f-c29db4302b93] succeeded in 0.0173180103302s: False
[2013-07-29 12:44:11,594: INFO/Beat] Scheduler: Sending due task dialer_cdr.tasks.task_pending_callevent (dialer_cdr.tasks.task_pending_callevent)
[2013-07-29 12:44:11,598: INFO/MainProcess] Got task from broker: dialer_cdr.tasks.task_pending_callevent[d9dfa172-fe15-4ab7-80a3-1b68c024ca88]
[2013-07-29 12:44:11,626: INFO/MainProcess] ASK :: task_pending_callevent
[2013-07-29 12:44:11,633: ERROR/MainProcess] Task dialer_cdr.tasks.task_pending_callevent[d9dfa172-fe15-4ab7-80a3-1b68c024ca88] raised exception: DatabaseError('relation "call_event" does not exist\nLINE 1: ..._date, status, created_date, amd_status, leg FROM call_event...\n ^\n',)
Traceback (most recent call last):
File "/usr/share/virtualenvs/newfies-dialer/lib/python2.6/site-packages/celery/task/trace.py", line 233, in trace_task
R = retval = fun(*args, **kwargs)
File "/usr/share/virtualenvs/newfies-dialer/lib/python2.6/site-packages/celery/task/trace.py", line 420, in __protected_call__
return self.run(*args, **kwargs)
File "/usr/share/virtualenvs/newfies-dialer/lib/python2.6/site-packages/common/only_one_task.py", line 25, in _caller
ret_value = run_func(*args, **kwargs)
File "/usr/share/newfies/dialer_cdr/tasks.py", line 521, in run
check_callevent()
File "/usr/share/newfies/dialer_cdr/tasks.py", line 480, in check_callevent
cursor.execute(sql_statement)
File "/usr/share/virtualenvs/newfies-dialer/lib/python2.6/site-packages/django/db/backends/util.py", line 41, in execute
return self.cursor.execute(sql, params)
File "/usr/share/virtualenvs/newfies-dialer/lib/python2.6/site-packages/django/db/backends/postgresql_psycopg2/base.py", line 58, in execute
six.reraise(utils.DatabaseError, utils.DatabaseError(*tuple(e.args)), sys.exc_info()[2])
File "/usr/share/virtualenvs/newfies-dialer/lib/python2.6/site-packages/django/db/backends/postgresql_psycopg2/base.py", line 54, in execute
return self.cursor.execute(query, args)
None: relation "call_event" does not exist
LINE 1: ..._date, status, created_date, amd_status, leg FROM call_event...
^

[2013-07-29 12:44:15,685: INFO/Beat] Scheduler: Sending due task dialer_campaign.tasks.campaign_running (dialer_campaign.tasks.campaign_running)
[2013-07-29 12:44:15,688: INFO/MainProcess] Got task from broker: dialer_campaign.tasks.campaign_running[cc0ffcc7-b0c7-48b3-b9b6-1340eed446e9]
[2013-07-29 12:44:15,699: INFO/MainProcess] => Campaign name Hello World Campaign (id:1)
[2013-07-29 12:44:15,702: INFO/MainProcess] Got task from broker: dialer_campaign.tasks.CheckPendingcall[20538942-036d-4792-820b-936d47bb934e]
[2013-07-29 12:44:15,703: INFO/MainProcess] Task dialer_campaign.tasks.campaign_running[cc0ffcc7-b0c7-48b3-b9b6-1340eed446e9] succeeded in 0.0122919082642s: True
[2013-07-29 12:44:15,709: INFO/PoolWorker-2] TASK :: CheckPendingcall = 1
[2013-07-29 12:44:15,724: INFO/PoolWorker-2] campaign_id=1 #Subscriber: 0
[2013-07-29 12:44:15,725: INFO/MainProcess] Task dialer_campaign.tasks.CheckPendingcall[20538942-036d-4792-820b-936d47bb934e] succeeded in 0.0163841247559s: False
[2013-07-29 12:44:21,693: INFO/Beat] Scheduler: Sending due task dialer_campaign.tasks.campaign_running (dialer_campaign.tasks.campaign_running)
[2013-07-29 12:44:21,697: INFO/MainProcess] Got task from broker: dialer_campaign.tasks.campaign_running[b2c2fc0d-6f49-461f-9e38-75125d42c007]
[2013-07-29 12:44:21,719: INFO/MainProcess] => Campaign name Hello World Campaign (id:1)
[2013-07-29 12:44:21,722: INFO/MainProcess] Got task from broker: dialer_campaign.tasks.CheckPendingcall[bebc0e08-99f6-4c96-853f-dcf444f5103d]
[2013-07-29 12:44:21,724: INFO/MainProcess] Task dialer_campaign.tasks.campaign_running[b2c2fc0d-6f49-461f-9e38-75125d42c007] succeeded in 0.0145449638367s: True
[2013-07-29 12:44:21,727: INFO/PoolWorker-2] TASK :: CheckPendingcall = 1
[2013-07-29 12:44:21,738: INFO/PoolWorker-2] campaign_id=1 #Subscriber: 0
[2013-07-29 12:44:21,740: INFO/MainProcess] Task dialer_campaign.tasks.CheckPendingcall[bebc0e08-99f6-4c96-853f-dcf444f5103d] succeeded in 0.0128498077393s: False



Thanks,
Kami

[areski]

If you see this error only once, it's absolutely fine. It might be because celery started before FreeSWITCH, once FS will start it will create the call_event table.

[javakami]

If you see this error only once, it's absolutely fine. It might be because celery started before FreeSWITCH, once FS will start it will create the call_event table.

Hi areski,

unfortunately, the error did not appeared just once but regularly and I can confirm that the FS si already started and I could even execute a first Hello World Campaign (eventhough I couldn't heard the "Hello World" before hang up - but that's another problem I looked after soon).

Thanks,
Kami


[2013-07-29 13:31:31,236: INFO/MainProcess] Task dialer_campaign.tasks.CheckPendingcall[cdfc718b-0401-4f04-98d9-4ee2a8351f32] succeeded in 0.0164198875427s: False
[2013-07-29 13:31:37,180: INFO/Beat] Scheduler: Sending due task dialer_campaign.tasks.campaign_running (dialer_campaign.tasks.campaign_running)
[2013-07-29 13:31:37,183: INFO/MainProcess] Got task from broker: dialer_campaign.tasks.campaign_running[f7a5035c-35a2-488c-8238-a09c1976aa6e]
[2013-07-29 13:31:37,195: INFO/MainProcess] => Campaign name Hello World Campaign (id:1)
[2013-07-29 13:31:37,199: INFO/MainProcess] Got task from broker: dialer_campaign.tasks.CheckPendingcall[217d8620-806e-49a4-9837-b5259582d0db]
[2013-07-29 13:31:37,200: INFO/MainProcess] Task dialer_campaign.tasks.campaign_running[f7a5035c-35a2-488c-8238-a09c1976aa6e] succeeded in 0.0143439769745s: True
[2013-07-29 13:31:37,205: INFO/PoolWorker-3] TASK :: CheckPendingcall = 1
[2013-07-29 13:31:37,221: INFO/PoolWorker-3] campaign_id=1 #Subscriber: 0
[2013-07-29 13:31:37,222: INFO/MainProcess] Task dialer_campaign.tasks.CheckPendingcall[217d8620-806e-49a4-9837-b5259582d0db] succeeded in 0.017156124115s: False
[2013-07-29 13:31:42,331: INFO/Beat] Scheduler: Sending due task dialer_cdr.tasks.task_pending_callevent (dialer_cdr.tasks.task_pending_callevent)
[2013-07-29 13:31:42,335: INFO/MainProcess] Got task from broker: dialer_cdr.tasks.task_pending_callevent[3419cc37-6b9e-4b6b-b649-de600d1e38c2]
[2013-07-29 13:31:42,370: INFO/MainProcess] ASK :: task_pending_callevent
[2013-07-29 13:31:42,378: ERROR/MainProcess] Task dialer_cdr.tasks.task_pending_callevent[3419cc37-6b9e-4b6b-b649-de600d1e38c2] raised exception: DatabaseError('relation "call_event" does not exist\nLINE 1: ..._date, status, created_date, amd_status, leg FROM call_event...\n ^\n',)
Traceback (most recent call last):
File "/usr/share/virtualenvs/newfies-dialer/lib/python2.6/site-packages/celery/task/trace.py", line 233, in trace_task
R = retval = fun(*args, **kwargs)
File "/usr/share/virtualenvs/newfies-dialer/lib/python2.6/site-packages/celery/task/trace.py", line 420, in __protected_call__
return self.run(*args, **kwargs)
File "/usr/share/virtualenvs/newfies-dialer/lib/python2.6/site-packages/common/only_one_task.py", line 25, in _caller
ret_value = run_func(*args, **kwargs)
File "/usr/share/newfies/dialer_cdr/tasks.py", line 521, in run
check_callevent()
File "/usr/share/newfies/dialer_cdr/tasks.py", line 480, in check_callevent
cursor.execute(sql_statement)
File "/usr/share/virtualenvs/newfies-dialer/lib/python2.6/site-packages/django/db/backends/util.py", line 41, in execute
return self.cursor.execute(sql, params)
File "/usr/share/virtualenvs/newfies-dialer/lib/python2.6/site-packages/django/db/backends/postgresql_psycopg2/base.py", line 58, in execute
six.reraise(utils.DatabaseError, utils.DatabaseError(*tuple(e.args)), sys.exc_info()[2])
File "/usr/share/virtualenvs/newfies-dialer/lib/python2.6/site-packages/django/db/backends/postgresql_psycopg2/base.py", line 54, in execute
return self.cursor.execute(query, args)
None: relation "call_event" does not exist
LINE 1: ..._date, status, created_date, amd_status, leg FROM call_event...
^

[2013-07-29 13:31:43,185: INFO/Beat] Scheduler: Sending due task dialer_campaign.tasks.campaign_running (dialer_campaign.tasks.campaign_running)
[2013-07-29 13:31:43,188: INFO/MainProcess] Got task from broker: dialer_campaign.tasks.campaign_running[10716606-8728-4262-ae73-3a40f93c4c7a]
[2013-07-29 13:31:43,246: INFO/MainProcess] => Campaign name Hello World Campaign (id:1)
[2013-07-29 13:31:43,249: INFO/MainProcess] Got task from broker: dialer_campaign.tasks.CheckPendingcall[ef5f3b63-d175-4b0c-9219-5a1aefe2bd05]
[2013-07-29 13:31:43,251: INFO/MainProcess] Task dialer_campaign.tasks.campaign_running[10716606-8728-4262-ae73-3a40f93c4c7a] succeeded in 0.0147149562836s: True
[2013-07-29 13:31:43,256: INFO/PoolWorker-2] TASK :: CheckPendingcall = 1
[2013-07-29 13:31:43,271: INFO/PoolWorker-2] campaign_id=1 #Subscriber: 0
[2013-07-29 13:31:43,272: INFO/MainProcess] Task dialer_campaign.tasks.CheckPendingcall[ef5f3b63-d175-4b0c-9219-5a1aefe2bd05] succeeded in 0.0169689655304s: False
[2013-07-29 13:31:49,194: INFO/Beat] Scheduler: Sending due task dialer_campaign.tasks.campaign_running (dialer_campaign.tasks.campaign_running)
[2013-07-29 13:31:49,198: INFO/MainProcess] Got task from broker: dialer_campaign.tasks.campaign_running[76ff6ea5-1eee-4827-9e3f-6aaa2a3230fc]
[2013-07-29 13:31:49,231: INFO/MainProcess] => Campaign name Hello World Campaign (id:1)
[2013-07-29 13:31:49,233: INFO/MainProcess] Got task from broker: dialer_campaign.tasks.CheckPendingcall[15ad9bf3-2791-4dbb-b47c-09ba61ae7b3b]
[2013-07-29 13:31:49,234: INFO/MainProcess] Task dialer_campaign.tasks.campaign_running[76ff6ea5-1eee-4827-9e3f-6aaa2a3230fc] succeeded in 0.0123300552368s: True
[2013-07-29 13:31:49,242: INFO/PoolWorker-2] TASK :: CheckPendingcall = 1
[2013-07-29 13:31:49,255: INFO/PoolWorker-2] campaign_id=1 #Subscriber: 0
[2013-07-29 13:31:49,256: INFO/MainProcess] Task dialer_campaign.tasks.CheckPendingcall[15ad9bf3-2791-4dbb-b47c-09ba61ae7b3b] succeeded in 0.0151000022888s: False

[areski]

Then it might be an issue that will need to be investigated on your install.
You will see that FS have startup script for lua in the configuration, this is what created the call_event table.