mod_nibblebill & freeSWITCH

phunk000
Joined: Sat 20 of Feb, 2010

mod_nibblebill & freeSWITCH

Posted:Sat 20 of Feb, 2010 (19:38 UTC)
/hello... I have installed the fusionpbx version of freeswitch and am trying to get the mod_nibblebill module working. I have installed odbc and the spidermonkey mod. When I make calls the call log shows the following:

2010-02-20 14:06:00.059770 [DEBUG] sofia.c:3727 Channel sofia/internal/sip:3008@192.168.15.176:20012 entering state [completed][200]
2010-02-20 14:06:00.174846 [DEBUG] sofia.c:3727 Channel sofia/internal/sip:3008@192.168.15.176:20012 entering state [ready][200]
2010-02-20 14:06:10.895507 [DEBUG] mod_nibblebill.c:529 Received request via SESSION_HEARTBEAT!
2010-02-20 14:06:10.895507 [DEBUG] mod_nibblebill.c:397 Attempting to bill at $1.0 per minute to account 3007
2010-02-20 14:06:10.895507 [DEBUG] mod_nibblebill.c:449 Last successful billing time was 
2010-02-20 14:06:10.895507 [DEBUG] mod_nibblebill.c:455 30 seconds passed since last bill time of 2010-02-20 14:05:40
2010-02-20 14:06:10.895507 [DEBUG] mod_nibblebill.c:461 Billing $0.501665 to 3007 (Call: 58f2db96-ab4f-4a14-886d-3d6b909f865a / 12.528334 so far)
2010-02-20 14:06:40.915401 [DEBUG] mod_nibblebill.c:529 Received request via SESSION_HEARTBEAT!
2010-02-20 14:06:40.915401 [DEBUG] mod_nibblebill.c:397 Attempting to bill at $1.0 per minute to account 3007
2010-02-20 14:06:40.915401 [DEBUG] mod_nibblebill.c:449 Last successful billing time was 
2010-02-20 14:06:40.915401 [DEBUG] mod_nibblebill.c:455 30 seconds passed since last bill time of 2010-02-20 14:06:10
2010-02-20 14:06:40.915401 [DEBUG] mod_nibblebill.c:461 Billing $0.500332 to 3007 (Call: 58f2db96-ab4f-4a14-886d-3d6b909f865a / 13.029999 so far)
2010-02-20 14:07:10.935281 [DEBUG] mod_nibblebill.c:529 Received request via SESSION_HEARTBEAT!
2010-02-20 14:07:10.935281 [DEBUG] mod_nibblebill.c:397 Attempting to bill at $1.0 per minute to account 3007
2010-02-20 14:07:10.935281 [DEBUG] mod_nibblebill.c:449 Last successful billing time was 
2010-02-20 14:07:10.935281 [DEBUG] mod_nibblebill.c:455 30 seconds passed since last bill time of 2010-02-20 14:06:40
2010-02-20 14:07:10.935281 [DEBUG] mod_nibblebill.c:461 Billing $0.500331 to 3007 (Call: 58f2db96-ab4f-4a14-886d-3d6b909f865a / 13.530331 so far)
2010-02-20 14:07:40.955156 [DEBUG] mod_nibblebill.c:529 Received request via SESSION_HEARTBEAT!
2010-02-20 14:07:40.955156 [DEBUG] mod_nibblebill.c:397 Attempting to bill at $1.0 per minute to account 3007
2010-02-20 14:07:40.955156 [DEBUG] mod_nibblebill.c:449 Last successful billing time was 
2010-02-20 14:07:40.955156 [DEBUG] mod_nibblebill.c:455 30 seconds passed since last bill time of 2010-02-20 14:07:10
2010-02-20 14:07:40.955156 [DEBUG] mod_nibblebill.c:461 Billing $0.500331 to 3007 (Call: 58f2db96-ab4f-4a14-886d-3d6b909f865a / 14.030662 so far)
2010-02-20 14:07:44.015337 [INFO] switch_rtp.c:1987 Auto Changing port from 0.0.0.0:41480 to 192.168.15.176:41480
2010-02-20 14:08:10.975026 [DEBUG] mod_nibblebill.c:529 Received request via SESSION_HEARTBEAT!
2010-02-20 14:08:10.975026 [DEBUG] mod_nibblebill.c:397 Attempting to bill at $1.0 per minute to account 3007
2010-02-20 14:08:10.975026 [DEBUG] mod_nibblebill.c:449 Last successful billing time was 
2010-02-20 14:08:10.975026 [DEBUG] mod_nibblebill.c:455 30 seconds passed since last bill time of 2010-02-20 14:07:40
2010-02-20 14:08:10.975026 [DEBUG] mod_nibblebill.c:461 Billing $0.500331 to 3007 (Call: 58f2db96-ab4f-4a14-886d-3d6b909f865a / 14.530993 so far)
2010-02-20 14:08:40.995932 [DEBUG] mod_nibblebill.c:529 Received request via SESSION_HEARTBEAT!
2010-02-20 14:08:40.995932 [DEBUG] mod_nibblebill.c:397 Attempting to bill at $1.0 per minute to account 3007
2010-02-20 14:08:40.995932 [DEBUG] mod_nibblebill.c:449 Last successful billing time was 
2010-02-20 14:08:40.995932 [DEBUG] mod_nibblebill.c:455 30 seconds passed since last bill time of 2010-02-20 14:08:10
2010-02-20 14:08:40.995932 [DEBUG] mod_nibblebill.c:461 Billing $0.500348 to 3007 (Call: 58f2db96-ab4f-4a14-886d-3d6b909f865a / 15.031323 so far)
2010-02-20 14:09:11.015785 [DEBUG] mod_nibblebill.c:529 Received request via SESSION_HEARTBEAT!
2010-02-20 14:09:11.015785 [DEBUG] mod_nibblebill.c:397 Attempting to bill at $1.0 per minute to account 3007
2010-02-20 14:09:11.015785 [DEBUG] mod_nibblebill.c:449 Last successful billing time was 
2010-02-20 14:09:11.015785 [DEBUG] mod_nibblebill.c:455 30 seconds passed since last bill time of 2010-02-20 14:08:40
2010-02-20 14:09:11.015785 [DEBUG] mod_nibblebill.c:461 Billing $0.500331 to 3007 (Call: 58f2db96-ab4f-4a14-886d-3d6b909f865a / 15.531672 so far)
2010-02-20 14:09:41.035665 [DEBUG] mod_nibblebill.c:529 Received request via SESSION_HEARTBEAT!
2010-02-20 14:09:41.035665 [DEBUG] mod_nibblebill.c:397 Attempting to bill at $1.0 per minute to account 3007
2010-02-20 14:09:41.035665 [DEBUG] mod_nibblebill.c:449 Last successful billing time was 
2010-02-20 14:09:41.035665 [DEBUG] mod_nibblebill.c:455 30 seconds passed since last bill time of 2010-02-20 14:09:11
2010-02-20 14:09:41.035665 [DEBUG] mod_nibblebill.c:461 Billing $0.500331 to 3007 (Call: 58f2db96-ab4f-4a14-886d-3d6b909f865a / 16.032001 so far)
2010-02-20 14:10:11.055545 [DEBUG] mod_nibblebill.c:529 Received request via SESSION_HEARTBEAT!
2010-02-20 14:10:11.055545 [DEBUG] mod_nibblebill.c:397 Attempting to bill at $1.0 per minute to account 3007
2010-02-20 14:10:11.055545 [DEBUG] mod_nibblebill.c:449 Last successful billing time was 
2010-02-20 14:10:11.055545 [DEBUG] mod_nibblebill.c:455 30 seconds passed since last bill time of 2010-02-20 14:09:41
2010-02-20 14:10:11.055545 [DEBUG] mod_nibblebill.c:461 Billing $0.500331 to 3007 (Call: 58f2db96-ab4f-4a14-886d-3d6b909f865a / 16.532333 so far)
2010-02-20 14:10:41.075435 [DEBUG] mod_nibblebill.c:529 Received request via SESSION_HEARTBEAT!
2010-02-20 14:10:41.075435 [DEBUG] mod_nibblebill.c:397 Attempting to bill at $1.0 per minute to account 3007
2010-02-20 14:10:41.075435 [DEBUG] mod_nibblebill.c:449 Last successful billing time was 
2010-02-20 14:10:41.075435 [DEBUG] mod_nibblebill.c:455 30 seconds passed since last bill time of 2010-02-20 14:10:11
2010-02-20 14:10:41.075435 [DEBUG] mod_nibblebill.c:461 Billing $0.500331 to 3007 (Call: 58f2db96-ab4f-4a14-886d-3d6b909f865a / 17.032665 so far)
2010-02-20 14:11:11.095305 [DEBUG] mod_nibblebill.c:529 Received request via SESSION_HEARTBEAT!
2010-02-20 14:11:11.095305 [DEBUG] mod_nibblebill.c:397 Attempting to bill at $1.0 per minute to account 3007
2010-02-20 14:11:11.095305 [DEBUG] mod_nibblebill.c:449 Last successful billing time was 
2010-02-20 14:11:11.095305 [DEBUG] mod_nibblebill.c:455 30 seconds passed since last bill time of 2010-02-20 14:10:41
2010-02-20 14:11:11.095305 [DEBUG] mod_nibblebill.c:461 Billing $0.500331 to 3007 (Call: 58f2db96-ab4f-4a14-886d-3d6b909f865a / 17.532997 so far)
2010-02-20 14:11:41.115184 [DEBUG] mod_nibblebill.c:529 Received request via SESSION_HEARTBEAT!
2010-02-20 14:11:41.115184 [DEBUG] mod_nibblebill.c:397 Attempting to bill at $1.0 per minute to account 3007
2010-02-20 14:11:41.115184 [DEBUG] mod_nibblebill.c:449 Last successful billing time was 
2010-02-20 14:11:41.115184 [DEBUG] mod_nibblebill.c:455 30 seconds passed since last bill time of 2010-02-20 14:11:11
2010-02-20 14:11:41.115184 [DEBUG] mod_nibblebill.c:461 Billing $0.500331 to 3007 (Call: 58f2db96-ab4f-4a14-886d-3d6b909f865a / 18.033329 so far)
2010-02-20 14:12:11.135064 [DEBUG] mod_nibblebill.c:529 Received request via SESSION_HEARTBEAT!
2010-02-20 14:12:11.135064 [DEBUG] mod_nibblebill.c:397 Attempting to bill at $1.0 per minute to account 3007
2010-02-20 14:12:11.135064 [DEBUG] mod_nibblebill.c:449 Last successful billing time was 
2010-02-20 14:12:11.135064 [DEBUG] mod_nibblebill.c:455 30 seconds passed since last bill time of 2010-02-20 14:11:41
2010-02-20 14:12:11.135064 [DEBUG] mod_nibblebill.c:461 Billing $0.500331 to 3007 (Call: 58f2db96-ab4f-4a14-886d-3d6b909f865a / 18.533661 so far)
2010-02-20 14:12:39.699853 [NOTICE] sofia.c:329 Hangup sofia/internal/3007@192.168.15.177 [CS_EXECUTE] [NORMAL_CLEARING]
2010-02-20 14:12:39.699853 [DEBUG] switch_channel.c:1912 Send signal sofia/internal/3007@192.168.15.177 [KILL]
2010-02-20 14:12:39.699853 [DEBUG] switch_core_session.c:999 Send signal sofia/internal/3007@192.168.15.177 [BREAK]
2010-02-20 14:12:39.699853 [DEBUG] switch_core_state_machine.c:459 sofia/internal/3007@192.168.15.177 thread mismatch skipping state handler.
2010-02-20 14:12:39.715861 [DEBUG] switch_ivr_play_say.c:1446 done playing file
2010-02-20 14:12:39.715861 [DEBUG] switch_ivr_bridge.c:320 Send signal sofia/internal/sip:3008@192.168.15.176:20012 [BREAK]
2010-02-20 14:12:39.715861 [DEBUG] switch_ivr_bridge.c:470 sofia/internal/3007@192.168.15.177 ending bridge by request from read function
2010-02-20 14:12:39.715861 [DEBUG] switch_ivr_bridge.c:563 BRIDGE THREAD DONE [sofia/internal/3007@192.168.15.177]
2010-02-20 14:12:39.715861 [DEBUG] switch_ivr_bridge.c:565 Send signal sofia/internal/sip:3008@192.168.15.176:20012 [BREAK]
2010-02-20 14:12:39.715861 [DEBUG] switch_ivr_bridge.c:520 sofia/internal/sip:3008@192.168.15.176:20012 receive message [UNBRIDGE]
2010-02-20 14:12:39.715861 [DEBUG] switch_core_session.c:645 Send signal sofia/internal/sip:3008@192.168.15.176:20012 [BREAK]
2010-02-20 14:12:39.715861 [DEBUG] switch_ivr_bridge.c:563 BRIDGE THREAD DONE [sofia/internal/sip:3008@192.168.15.176:20012]
2010-02-20 14:12:39.715861 [DEBUG] switch_ivr_bridge.c:565 Send signal sofia/internal/3007@192.168.15.177 [BREAK]
2010-02-20 14:12:39.715861 [NOTICE] switch_ivr_bridge.c:617 Hangup sofia/internal/sip:3008@192.168.15.176:20012 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
2010-02-20 14:12:39.715861 [DEBUG] switch_channel.c:1912 Send signal sofia/internal/sip:3008@192.168.15.176:20012 [KILL]
2010-02-20 14:12:39.715861 [DEBUG] switch_core_session.c:999 Send signal sofia/internal/sip:3008@192.168.15.176:20012 [BREAK]
2010-02-20 14:12:39.715861 [DEBUG] switch_core_state_machine.c:488 (sofia/internal/sip:3008@192.168.15.176:20012) State HANGUP
2010-02-20 14:12:39.715861 [DEBUG] mod_sofia.c:358 Channel sofia/internal/sip:3008@192.168.15.176:20012 hanging up, cause: NORMAL_CLEARING
2010-02-20 14:12:39.715861 [DEBUG] mod_sofia.c:400 Sending BYE to sofia/internal/sip:3008@192.168.15.176:20012
2010-02-20 14:12:39.715861 [DEBUG] mod_nibblebill.c:397 Attempting to bill at $2.30 per minute to account 3008
2010-02-20 14:12:39.715861 [INFO] mod_nibblebill.c:447 Beginning new billing on 63fe245a-2d68-4165-b831-322b27be6652
2010-02-20 14:12:39.715861 [DEBUG] mod_nibblebill.c:455 1170 seconds passed since last bill time of 2010-02-20 13:53:09
2010-02-20 14:12:39.715861 [DEBUG] mod_nibblebill.c:461 Billing $44.873932 to 3008 (Call: 63fe245a-2d68-4165-b831-322b27be6652 / 0.000000 so far)
2010-02-20 14:12:39.715861 [DEBUG] switch_core_state_machine.c:46 sofia/internal/sip:3008@192.168.15.176:20012 Standard HANGUP, cause: NORMAL_CLEARING
2010-02-20 14:12:39.715861 [DEBUG] switch_core_state_machine.c:488 (sofia/internal/sip:3008@192.168.15.176:20012) State HANGUP going to sleep
2010-02-20 14:12:39.715861 [INFO] switch_core_session.c:1108 sofia/internal/sip:3008@192.168.15.176:20012 setting session heartbeat to 30 second(s).
2010-02-20 14:12:39.715861 [DEBUG] switch_core_state_machine.c:351 (sofia/internal/sip:3008@192.168.15.176:20012) State EXCHANGE_MEDIA going to sleep
2010-02-20 14:12:39.715861 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/sip:3008@192.168.15.176:20012) Running State Change CS_HANGUP
2010-02-20 14:12:39.715861 [DEBUG] switch_core_state_machine.c:465 sofia/internal/sip:3008@192.168.15.176:20012 handler already called, skipping state handler.
2010-02-20 14:12:39.715861 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/sip:3008@192.168.15.176:20012) State Change CS_HANGUP -> CS_REPORTING
2010-02-20 14:12:39.715861 [DEBUG] switch_core_session.c:999 Send signal sofia/internal/sip:3008@192.168.15.176:20012 [BREAK]
2010-02-20 14:12:39.715861 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/sip:3008@192.168.15.176:20012) Running State Change CS_REPORTING
2010-02-20 14:12:39.715861 [DEBUG] switch_core_state_machine.c:579 (sofia/internal/sip:3008@192.168.15.176:20012) State REPORTING
2010-02-20 14:12:39.715861 [DEBUG] switch_core_state_machine.c:53 sofia/internal/sip:3008@192.168.15.176:20012 Standard REPORTING, cause: NORMAL_CLEARING
2010-02-20 14:12:39.715861 [DEBUG] switch_core_state_machine.c:579 (sofia/internal/sip:3008@192.168.15.176:20012) State REPORTING going to sleep
2010-02-20 14:12:39.715861 [DEBUG] switch_core_state_machine.c:327 (sofia/internal/sip:3008@192.168.15.176:20012) State Change CS_REPORTING -> CS_DESTROY
2010-02-20 14:12:39.715861 [DEBUG] switch_core_session.c:999 Send signal sofia/internal/sip:3008@192.168.15.176:20012 [BREAK]
2010-02-20 14:12:39.715861 [DEBUG] switch_core_session.c:1136 Session 2 (sofia/internal/sip:3008@192.168.15.176:20012) Locked, Waiting on external entities
2010-02-20 14:12:39.719424 [DEBUG] switch_core_state_machine.c:348 (sofia/internal/3007@192.168.15.177) State EXECUTE going to sleep
2010-02-20 14:12:39.719424 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/3007@192.168.15.177) Running State Change CS_HANGUP
2010-02-20 14:12:39.719424 [DEBUG] switch_core_state_machine.c:488 (sofia/internal/3007@192.168.15.177) State HANGUP
2010-02-20 14:12:39.719424 [DEBUG] mod_sofia.c:358 Channel sofia/internal/3007@192.168.15.177 hanging up, cause: NORMAL_CLEARING
2010-02-20 14:12:39.719424 [DEBUG] mod_nibblebill.c:397 Attempting to bill at $1.0 per minute to account 3007
2010-02-20 14:12:39.719424 [DEBUG] mod_nibblebill.c:449 Last successful billing time was 
2010-02-20 14:12:39.719424 [DEBUG] mod_nibblebill.c:455 28 seconds passed since last bill time of 2010-02-20 14:12:11
2010-02-20 14:12:39.719424 [DEBUG] mod_nibblebill.c:461 Billing $0.476406 to 3007 (Call: 58f2db96-ab4f-4a14-886d-3d6b909f865a / 19.033993 so far)
2010-02-20 14:12:39.719424 [DEBUG] switch_core_state_machine.c:46 sofia/internal/3007@192.168.15.177 Standard HANGUP, cause: NORMAL_CLEARING
2010-02-20 14:12:39.719424 [DEBUG] switch_core_state_machine.c:488 (sofia/internal/3007@192.168.15.177) State HANGUP going to sleep


but the accounts table I created in the fusionpbx database, which I have a dsn setup for and all odbc.ini .odbc.ini and odbcinst.ini configured for remains the same. no deductions are made from the cash column. any help would be great, thanks.