Периодическая услуга, не списываются средства

[gil]

Добрый день.
Завелся абонент. Подключаю ему тарифный план, в который входит периодическая услуга со стоимостью N рублей (метод снятия - в начале р/п), и ни копейки не снимается за эту услугу.
На других пользователях - все работает.
Версия 008.

В дебаге вот такие строки:

Код: Выделить всё

?Debug &#58; Aug 05 13&#58;47&#58;05 RPCConn&#91;SSL&#93;<init@myip>&#58; URFA ping received, sending reply
?Debug &#58; Aug 05 13&#58;47&#58;05 RPCConn&#91;SSL&#93;<init@myip>&#58; Call&#58; 0x2922 &#40;rpcf_add_periodic_service_link_ex&#41;
?Debug &#58; Aug 05 13&#58;47&#58;05 RPCConn&#91;SSL&#93;<init@myip>&#58; Real Call&#58; 0x2922 &#40;rpcf_add_periodic_service_link_ex&#41;
?Debug &#58; Aug 05 13&#58;47&#58;05 UTM5 DBA&#58; DBAccess instance created
?Debug &#58; Aug 05 13&#58;47&#58;05 DBA&#58;Ctx&#58; Looking for free context &#40;system=0&#41;
?Debug &#58; Aug 05 13&#58;47&#58;05 DBCtx&#58; <683567104> SQL query&#58; BEGIN
?Debug &#58; Aug 05 13&#58;47&#58;05 DBCtx&#58; DB transaction begin
?Debug &#58; Aug 05 13&#58;47&#58;05 DBCtx&#58; <683567104> SQL query&#58; INSERT INTO service_links&#40;user_id,account_id,service_id,tariff_link_id&#41; VALUES&#40;'135', '136', '224', '480'&#41;
?Debug &#58; Aug 05 13&#58;47&#58;05 DBCtx&#58; <683567104> SQL query&#58; INSERT INTO periodic_service_links&#40;id,discount_period_id,discounted_in_curr_period,start_date,is_planned,expire_date,need_del,unprepay_period,unabon_period, is_invoice_set&#41; VALUES&#40;'5007', '88', '0', '1281001622', '0', '2000000000', '0', '0', '0', '0'&#41;
?Debug &#58; Aug 05 13&#58;47&#58;05 UTM5 DBA&#58; psl.unabon=<0> psl.unprepay=<0>
?Debug &#58; Aug 05 13&#58;47&#58;05 UTM5 DBA&#58; Generating advance invoices. slink count <1> discount_period_id <88>
?Debug &#58; Aug 05 13&#58;47&#58;05 UTM5 DBA&#58; Invoice entry has for slink_id <5007> has negative cost, skiped
?Debug &#58; Aug 05 13&#58;47&#58;05 UTM5 DBA&#58; Empty invoice for account <136>, not saved
?Debug &#58; Aug 05 13&#58;47&#58;05 UTM5 DBA&#58; Generating advance invoices finished
?Debug &#58; Aug 05 13&#58;47&#58;05 BusPeriodic&#58; BusPeriodic&#58;&#58;rehash_periodic calling pthread_cond_signal
?Debug &#58; Aug 05 13&#58;47&#58;05 DBCtx&#58; <683567104> SQL query&#58; COMMIT
?Debug &#58; Aug 05 13&#58;47&#58;05 BusPeriodic&#58; Wait result&#58;0
?Debug &#58; Aug 05 13&#58;47&#58;05 BusPeriodic&#58; real_rehash start
?Debug &#58; Aug 05 13&#58;47&#58;05 DBCtx&#58; DB transaction commit
?Debug &#58; Aug 05 13&#58;47&#58;05 DBA&#58;Ctx&#58; Pushing back free context &#40;system=0&#41;
-Stats &#58; Aug 05 13&#58;47&#58;05 UTM5 DBA&#58;      Stats&#58; Uptime&#58; 00&#58;00&#58;00. Events&#58; 0; Errors&#58; 0
?Debug &#58; Aug 05 13&#58;47&#58;05 RPCConn&#91;SSL&#93;<init@myip>&#58; Call 0x2922 &#40;rpcf_add_periodic_service_link_ex&#41; finished...
?Debug &#58; Aug 05 13&#58;47&#58;05 RPCConn&#91;SSL&#93;<init@myip>&#58; Stream cleared

Больше всего напрягает следущее:

Код: Выделить всё

?Debug &#58; Aug 05 13&#58;47&#58;05 UTM5 DBA&#58; Invoice entry has for slink_id <5007> has negative cost, skiped

[/b]
Почему отрицательная стоимость?
Расчетный период верный (с 1 августа по 1 сентября).
Тестовому пользователю все подключает верно.

В какую сторону копнуть?

[gil]

Вот такой же почти кусок дебага, тестовый пользователь:

Код: Выделить всё

?Debug &#58; Aug 05 14&#58;16&#58;09 RPCConn&#91;SSL&#93;<init@myip>&#58; URFA ping received, sending reply
?Debug &#58; Aug 05 14&#58;16&#58;09 RPCConn&#91;SSL&#93;<init@myip>&#58; Call&#58; 0x2922 &#40;rpcf_add_periodic_service_link_ex&#41;
?Debug &#58; Aug 05 14&#58;16&#58;09 RPCConn&#91;SSL&#93;<init@myip>&#58; Real Call&#58; 0x2922 &#40;rpcf_add_periodic_service_link_ex&#41;
?Debug &#58; Aug 05 14&#58;16&#58;09 UTM5 DBA&#58; DBAccess instance created
?Debug &#58; Aug 05 14&#58;16&#58;09 DBA&#58;Ctx&#58; Looking for free context &#40;system=0&#41;
?Debug &#58; Aug 05 14&#58;16&#58;09 DBCtx&#58; <683571200> SQL query&#58; BEGIN
?Debug &#58; Aug 05 14&#58;16&#58;09 DBCtx&#58; DB transaction begin
?Debug &#58; Aug 05 14&#58;16&#58;09 DBCtx&#58; <683571200> SQL query&#58; INSERT INTO service_links&#40;user_id,account_id,service_id,tariff_link_id&#41; VALUES&#40;'4', '4', '224', '481'&#41;
?Debug &#58; Aug 05 14&#58;16&#58;09 DBCtx&#58; <683571200> SQL query&#58; INSERT INTO periodic_service_links&#40;id,discount_period_id,discounted_in_curr_period,start_date,is_planned,expire_date,need_del,unprepay_period,unabon_period, is_invoice_set&#41; VALUES&#40;'5008', '88', '0', '1281003366', '0', '2000000000', '0', '0', '0', '0'&#41;
?Debug &#58; Aug 05 14&#58;16&#58;09 UTM5 DBA&#58; psl.unabon=<0> psl.unprepay=<0>
?Debug &#58; Aug 05 14&#58;16&#58;09 UTM5 DBA&#58; Generating advance invoices. slink count <1> discount_period_id <88>
?Debug &#58; Aug 05 14&#58;16&#58;09 DBCtx&#58; <683571200> SQL query&#58; UPDATE periodic_service_links SET is_invoice_set='1' WHERE id='5008'
?Debug &#58; Aug 05 14&#58;16&#58;09 UTM5 DBA&#58; Normal invoice position&#58; sum_cost <90.799031> tax_amount <16.343826> account_id <4>
?Debug &#58; Aug 05 14&#58;16&#58;09 UTM5 DBA&#58; Saving invoice for account <4> entries size <1>
?Debug &#58; Aug 05 14&#58;16&#58;09 DBCtx&#58; <683571200> SQL query&#58; INSERT INTO invoices&#40;invoice_date,uid,account_id,arrearage,period_start,period_end,balance_on_set,versi
on&#41; VALUES&#40;'1281003369','4','4','-400.0814243617139','1280606400','1283284800','-507.224281504571','1'&#41;
?Debug &#58; Aug 05 14&#58;16&#58;09 DBCtx&#58; <683571200> SQL query&#58; INSERT INTO invoice_entry&#40;invoice_id,name,slink_id,discount_period_id,date,qnt,base_cost,sum_cost,tax_a
mount,service_type,version&#41; VALUES&#40;'4850','Название услуги','5008','88','1281003369','1','90.7990314769976','90.7990314769
976','16.34382566585957','2','0'&#41;
?Debug &#58; Aug 05 14&#58;16&#58;09 UTM5 DBA&#58; Generating advance invoices finished
?Debug &#58; Aug 05 14&#58;16&#58;09 BusPeriodic&#58; BusPeriodic&#58;&#58;rehash_periodic calling pthread_cond_signal
?Debug &#58; Aug 05 14&#58;16&#58;09 DBCtx&#58; <683571200> SQL query&#58; COMMIT
?Debug &#58; Aug 05 14&#58;16&#58;09 BusPeriodic&#58; Wait result&#58;0
?Debug &#58; Aug 05 14&#58;16&#58;09 BusPeriodic&#58; real_rehash start
?Debug &#58; Aug 05 14&#58;16&#58;09 DBCtx&#58; DB transaction commit
?Debug &#58; Aug 05 14&#58;16&#58;09 DBA&#58;Ctx&#58; Pushing back free context &#40;system=0&#41;
-Stats &#58; Aug 05 14&#58;16&#58;09 UTM5 DBA&#58;      Stats&#58; Uptime&#58; 00&#58;00&#58;00. Events&#58; 0; Errors&#58; 0
?Debug &#58; Aug 05 14&#58;16&#58;09 RPCConn&#91;SSL&#93;<init@myip>&#58; Call 0x2922 &#40;rpcf_add_periodic_service_link_ex&#41; finished...
?Debug &#58; Aug 05 14&#58;16&#58;09 RPCConn&#91;SSL&#93;<init@myip>&#58; Stream cleared

В нем нет ничего "отрицательного", и все снимается:

Код: Выделить всё

?Debug &#58; Aug 05 14&#58;16&#58;09 UTM5 DBA&#58; Normal invoice position&#58; sum_cost <90.799031> tax_amount <16.343826> account_id <4>

[gil]

Блокировка стояла неправильная (48) у пользователя с ошибкой.
Можно закрыть.