Hi guys,
I use latest ofbiz trunk and when i do a POS order i get an error in the
console saying "Could not change order status ; status is not a valid
change." Then the order in POS is finished in normal way. But when i go to
order manger i can see that the order is in created status (insted of
completed). Also there is no inventory movements... Is this a bug ?
Here i atache POS error log
[java] 2007-07-31 15:57:59,548 (Thread-162) [ Log4jLoggerWriter.java:67
:INFO ] [POS @ pos-1 TX:10000] - add item(GZ-5005/1.0)
[java] 2007-07-31 15:58:04,095 (Thread-177) [
Log4jLoggerWriter.java:67 :INFO ] [POS @ pos-1 TX:10000] - removing
payment(CASH)
[java] 2007-07-31 15:58:04,114 (Thread-177) [
ShoppingCart.java:1436:INFO ] Returned new PaymentInfo - Pm: null / PmType:
CASH / Amt: null / Ref: null!null
[java] 2007-07-31 15:58:04,115 (Thread-177) [
PaymentEvents.java:334:INFO ] Amount is empty; assumption is full amount :
47.99
[java] 2007-07-31 15:58:04,119 (Thread-177) [
PaymentEvents.java:43 :INFO ] Processing [Cash] Amount : 47.99
[java] 2007-07-31 15:58:04,119 (Thread-177) [
Log4jLoggerWriter.java:67 :INFO ] [POS @ pos-1 TX:10000] - added
payment(CASH/47.99)
[java] 2007-07-31 15:58:04,119 (Thread-177) [
ShoppingCart.java:1436:INFO ] Returned new PaymentInfo - Pm: null / PmType:
CASH / Amt: null / Ref: null!null
[java] 2007-07-31 15:58:04,120 (Thread-177) [
ShoppingCart.java:1436:INFO ] Returned new PaymentInfo - Pm: null / PmType:
CASH / Amt: 47.99 / Ref: null!null
[java] 2007-07-31 15:58:04,973 (Thread-179) [
Log4jLoggerWriter.java:67 :INFO ] [POS @ pos-1 TX:10000] - process sale
[java] 2007-07-31 15:58:04,976 (Thread-179) [
ShoppingCartItem.java:1400:INFO ] Setting orderItemSeqId - 00001
[java] 2007-07-31 15:58:04,978 (Thread-179) [
GenericEntity.java:386:WARN ] In entity field
[OrderPaymentPreference.maxAmount] set the value passed in
[java.math.BigDecimal] is not compatible with the Java type of the field
[Double]
[java] 2007-07-31 15:58:04,979 (Thread-179) [
ShoppingCart.java:4368:INFO ] Creating OrderPaymentPreference -
[GenericEntity:OrderPaymentPreference][billingPostalCode,null()][finAccountId,null()][manualAuthCode,null()][manualRefNum,null()][maxAmount,47.99(java.math.BigDecimal)][overflowFlag,N(java.lang.String)][paymentMethodId,null()][paymentMethodTypeId,CASH(java.lang.String)][presentFlag,N(java.lang.String)][statusId,PAYMENT_RECEIVED(java.lang.String)]
[java] 2007-07-31 15:58:05,404 (Thread-179) [
Log.java:94 :INFO ] In getNextOrderId partyId is [Company],
partyAcctgPreference:
[GenericEntity:PartyAcctgPreference][baseCurrencyUomId,USD(java.lang.String)][cogsMethodId,COGS_AVG_COST(java.lang.String)][createdStamp,2007-07-31
15:56:40.852(java.sql.Timestamp)][createdTxStamp,2007-07-31
15:56:40.602(java.sql.Timestamp)][fiscalYearStartDay,null()][fiscalYearStartMonth,null()][invoiceIdPrefix,CI(java.lang.String)][invoiceSequenceEnumId,INVSQ_ENF_SEQ(java.lang.String)][lastInvoiceNumber,0(java.lang.Long)][lastInvoiceRestartDate,null()][lastOrderNumber,0(java.lang.Long)][lastQuoteNumber,0(java.lang.Long)][lastUpdatedStamp,2007-07-31
15:56:40.852(java.sql.Timestamp)][lastUpdatedTxStamp,2007-07-31
15:56:40.602(java.sql.Timestamp)][orderIdPrefix,CO(java.lang.String)][orderSequenceEnumId,INVSQ_ENF_SEQ(java.lang.String)][partyId,Company(java.lang.String)][quoteIdPrefix,CQ(java.lang.String)][quoteSequenceEnumId,INVSQ_ENF_SEQ(java.lang.String)][refundPaymentMethodId,null()][taxFormId,US_IRS_1120(java.lang.String)][useInvoiceIdForReturns,null()]
[java] 2007-07-31 15:58:05,420 (Thread-179) [
SequenceUtil.java:203:WARN ] [SequenceUtil.SequenceBank.fillBank] first
select failed: will try to add new row, result set was empty for sequence
[OrderHeader]
[java] Used SQL: SELECT SEQ_ID FROM OFBIZ.SEQUENCE_VALUE_ITEM WHERE
SEQ_NAME='OrderHeader'
[java] Thread Name is: Thread-179:Thread[Thread-179,6,main]
[java] 2007-07-31 15:58:05,440 (Thread-179) [
SequenceUtil.java:254:INFO ] Got bank of sequenced IDs for [OrderHeader];
curSeqId=10000, maxSeqId=10010, bankSize=10
[java] 2007-07-31 15:58:05,502 (Thread-179) [
SequenceUtil.java:203:WARN ] [SequenceUtil.SequenceBank.fillBank] first
select failed: will try to add new row, result set was empty for sequence
[OrderStatus]
[java] Used SQL: SELECT SEQ_ID FROM OFBIZ.SEQUENCE_VALUE_ITEM WHERE
SEQ_NAME='OrderStatus'
[java] Thread Name is: Thread-179:Thread[Thread-179,6,main]
[java] 2007-07-31 15:58:05,522 (Thread-179) [
SequenceUtil.java:254:INFO ] Got bank of sequenced IDs for [OrderStatus];
curSeqId=10000, maxSeqId=10010, bankSize=10
[java] 2007-07-31 15:58:05,551 (Thread-179) [
SequenceUtil.java:203:WARN ] [SequenceUtil.SequenceBank.fillBank] first
select failed: will try to add new row, result set was empty for sequence
[OrderPaymentPreference]
[java] Used SQL: SELECT SEQ_ID FROM OFBIZ.SEQUENCE_VALUE_ITEM WHERE
SEQ_NAME='OrderPaymentPreference'
[java] Thread Name is: Thread-179:Thread[Thread-179,6,main]
[java] 2007-07-31 15:58:05,570 (Thread-179) [
SequenceUtil.java:254:INFO ] Got bank of sequenced IDs for
[OrderPaymentPreference]; curSeqId=10000, maxSeqId=10010, bankSize=10
[java] 2007-07-31 15:58:05,725 (Thread-179) [
EntityEcaRule.java:111:INFO ] Running Entity ECA Service:
checkCreateOrderRequirement, triggered by rule on Entity: OrderItem
[java] 2007-07-31 15:58:06,021 (Thread-179) [
ServiceEcaRule.java:137:INFO ] Running Service ECA Service:
updateShoppingListQuantitiesFromOrder, triggered by rule on Service:
storeOrder
[java] 2007-07-31 15:58:06,051 (Thread-179) [
ServiceEcaRule.java:137:INFO ] Running Service ECA Service: resetGrandTotal,
triggered by rule on Service: storeOrder
[java] 2007-07-31 15:58:06,148 (Thread-179) [
GenericEntity.java:386:WARN ] In entity field [OrderHeader.grandTotal] set
the value passed in [java.math.BigDecimal] is not compatible with the Java
type of the field [Double]
[java] 2007-07-31 15:58:06,149 (Thread-179) [
GenericEntity.java:386:WARN ] In entity field
[OrderHeader.remainingSubTotal] set the value passed in
[java.math.BigDecimal] is not compatible with the Java type of the field
[Double]
[java] 2007-07-31 15:58:06,187 (Thread-179) [
ServiceEcaRule.java:137:INFO ] Running Service ECA Service:
addSuggestionsToShoppingList, triggered by rule on Service: storeOrder
[java] 2007-07-31 15:58:06,203 (Thread-179) [
SequenceUtil.java:203:WARN ] [SequenceUtil.SequenceBank.fillBank] first
select failed: will try to add new row, result set was empty for sequence
[RuntimeData]
[java] Used SQL: SELECT SEQ_ID FROM OFBIZ.SEQUENCE_VALUE_ITEM WHERE
SEQ_NAME='RuntimeData'
[java] Thread Name is: Thread-179:Thread[Thread-179,6,main]
[java] 2007-07-31 15:58:06,223 (Thread-179) [
SequenceUtil.java:254:INFO ] Got bank of sequenced IDs for [RuntimeData];
curSeqId=10000, maxSeqId=10010, bankSize=10
[java] 2007-07-31 15:58:06,304 (Thread-179) [
GenericAsyncEngine.java:142:INFO ] Persisted job queued : 1185886686273
[java] 2007-07-31 15:58:06,312 (Thread-179) [
ServiceEcaRule.java:137:INFO ] Running Service ECA Service:
checkCreateDropShipPurchaseOrders, triggered by rule on Service: storeOrder
[java] 2007-07-31 15:58:06,423 (Thread-179) [
CheckOutHelper.java:1038:INFO ] Face-To-Face Sale - RSCO10000
[java] 2007-07-31 15:58:06,629 (Thread-179) [
SequenceUtil.java:203:WARN ] [SequenceUtil.SequenceBank.fillBank] first
select failed: will try to add new row, result set was empty for sequence
[Payment]
[java] Used SQL: SELECT SEQ_ID FROM OFBIZ.SEQUENCE_VALUE_ITEM WHERE
SEQ_NAME='Payment'
[java] Thread Name is: Thread-179:Thread[Thread-179,6,main]
[java] 2007-07-31 15:58:06,647 (Thread-179) [
SequenceUtil.java:254:INFO ] Got bank of sequenced IDs for [Payment];
curSeqId=10000, maxSeqId=10010, bankSize=10
[java] 2007-07-31 15:58:06,909 (Thread-179) [
Log.java:94 :INFO ] In getNextInvoiceId partyId is [Company],
partyAcctgPreference:
[GenericEntity:PartyAcctgPreference][baseCurrencyUomId,USD(java.lang.String)][cogsMethodId,COGS_AVG_COST(java.lang.String)][createdStamp,2007-07-31
15:56:40.852(java.sql.Timestamp)][createdTxStamp,2007-07-31
15:56:40.602(java.sql.Timestamp)][fiscalYearStartDay,null()][fiscalYearStartMonth,null()][invoiceIdPrefix,CI(java.lang.String)][invoiceSequenceEnumId,INVSQ_ENF_SEQ(java.lang.String)][lastInvoiceNumber,0(java.lang.Long)][lastInvoiceRestartDate,null()][lastOrderNumber,0(java.lang.Long)][lastQuoteNumber,0(java.lang.Long)][lastUpdatedStamp,2007-07-31
15:56:40.852(java.sql.Timestamp)][lastUpdatedTxStamp,2007-07-31
15:56:40.602(java.sql.Timestamp)][orderIdPrefix,CO(java.lang.String)][orderSequenceEnumId,INVSQ_ENF_SEQ(java.lang.String)][partyId,Company(java.lang.String)][quoteIdPrefix,CQ(java.lang.String)][quoteSequenceEnumId,INVSQ_ENF_SEQ(java.lang.String)][refundPaymentMethodId,null()][taxFormId,US_IRS_1120(java.lang.String)][useInvoiceIdForReturns,null()]
[java] 2007-07-31 15:58:06,910 (Thread-179) [
Log.java:94 :INFO ] In createInvoice sequence enum INVSQ_ENF_SEQ
[java] 2007-07-31 15:58:07,128 (Thread-179) [
InvoiceServices.java:335:WARN ] No billing locations found for order
[RSCO10000] and none were created for Invoice [CI1]
[java] 2007-07-31 15:58:07,741 (Thread-179) [
SequenceUtil.java:203:WARN ] [SequenceUtil.SequenceBank.fillBank] first
select failed: will try to add new row, result set was empty for sequence
[PaymentApplication]
[java] Used SQL: SELECT SEQ_ID FROM OFBIZ.SEQUENCE_VALUE_ITEM WHERE
SEQ_NAME='PaymentApplication'
[java] Thread Name is: Thread-179:Thread[Thread-179,6,main]
[java] 2007-07-31 15:58:07,757 (Thread-179) [
SequenceUtil.java:254:INFO ] Got bank of sequenced IDs for
[PaymentApplication]; curSeqId=10000, maxSeqId=10010, bankSize=10
[java] 2007-07-31 15:58:07,793 (Thread-179) [
ServiceEcaRule.java:137:INFO ] Running Service ECA Service:
checkInvoicePaymentApplications, triggered by rule on Service:
createPaymentApplication
[java] 2007-07-31 15:58:07,796 (Thread-179) [
ServiceEcaRule.java:137:INFO ] Running Service ECA Service:
createCommissionInvoices, triggered by rule on Service:
createPaymentApplication
[java] 2007-07-31 15:58:08,140 (Thread-179) [
ServiceEcaRule.java:137:INFO ] Running Service ECA Service:
capturePaymentsByInvoice, triggered by rule on Service:
createInvoiceForOrder
[java] 2007-07-31 15:58:08,162 (Thread-179)
[PaymentGatewayServices.java:995:INFO ] (Capture) Invoice [#CI1] total: 0.0
[java] 2007-07-31 15:58:08,206 (Thread-179)
[PaymentGatewayServices.java:1060:INFO ] The Remaining Total for order:
RSCO10000 is: 0.00
[java] 2007-07-31 15:58:08,208 (Thread-179)
[PaymentGatewayServices.java:1063:INFO ] Actual Expected Capture Amount :
0.00
[java] 2007-07-31 15:58:08,231 (Thread-179) [
ServiceDispatcher.java:410:ERROR] Service Error [changeOrderStatus]: ERROR :
Could not change order status ; status is not a valid change.
[java] 2007-07-31 15:58:08,232 (Thread-179) [
TransactionUtil.java:255:ERROR]
[java] ---- exception report
----------------------------------------------------------
[java] [TransactionUtil.rollback]
[java] Exception: java.lang.Exception
[java] Message: Stack Trace
[java] ---- stack trace
---------------------------------------------------------------
[java] java.lang.Exception: Stack Trace
[java]
org.ofbiz.entity.transaction.TransactionUtil.rollback(TransactionUtil.java:254)
[java]
org.ofbiz.entity.transaction.TransactionUtil.rollback(TransactionUtil.java:236)
[java]
org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:414)
[java]
org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:210)
[java]
org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:136)
[java]
org.ofbiz.order.order.OrderChangeHelper.orderStatusChanges(OrderChangeHelper.java:158)
[java]
org.ofbiz.order.order.OrderChangeHelper.completeOrder(OrderChangeHelper.java:121)
[java]
org.ofbiz.order.shoppingcart.CheckOutHelper.processPayment(CheckOutHelper.java:1040)
[java]
org.ofbiz.order.shoppingcart.CheckOutHelper.processPayment(CheckOutHelper.java:845)
[java]
org.ofbiz.pos.PosTransaction.processSale(PosTransaction.java:625)
[java]
org.ofbiz.pos.event.PaymentEvents.processSale(PaymentEvents.java:304)
[java] sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[java]
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[java]
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[java] java.lang.reflect.Method.invoke(Method.java:585)
[java]
org.ofbiz.pos.config.ButtonEventConfig.invoke(ButtonEventConfig.java:208)
[java]
org.ofbiz.pos.config.ButtonEventConfig.invokeButtonEvent(ButtonEventConfig.java:105)
[java]
org.ofbiz.pos.component.PosButton$1.construct(PosButton.java:120)
[java] net.xoetrope.xui.helper.SwingWorker$2.run(Unknown Source)
[java] java.lang.Thread.run(Thread.java:595)
[java]
--------------------------------------------------------------------------------
[java] 2007-07-31 15:58:08,234 (Thread-179) [
TransactionUtil.java:265:INFO ] [TransactionUtil.rollback] transaction
rolled back
[java] 2007-07-31 15:58:08,237 (Thread-179) [
OrderChangeHelper.java:160:ERROR] Problems adjusting order header status for
order #RSCO10000
[java] 2007-07-31 15:58:08,270 (Thread-179) [
ServiceEcaRule.java:112:INFO ] Got false for condition:
[null][][statusId][equals][null][ITEM_CANCELLED][true][String][]
[java] 2007-07-31 15:58:08,271 (Thread-179) [
ServiceEcaRule.java:137:INFO ] Running Service ECA Service:
checkOrderItemStatus, triggered by rule on Service: changeOrderItemStatus
[java] 2007-07-31 15:58:08,305 (Thread-179) [
ServiceEcaRule.java:112:INFO ] Got false for condition:
[null][][statusId][equals][null][ITEM_APPROVED][true][String][]
[java] 2007-07-31 15:58:08,306 (Thread-179) [
ServiceEcaRule.java:112:INFO ] Got false for condition:
[null][][statusId][equals][null][ITEM_APPROVED][true][String][]
[java] 2007-07-31 15:58:08,308 (Thread-179) [
CheckOutHelper.java:1041:INFO ] Complete Order Result - true
[java] 2007-07-31 15:58:08,311 (Thread-215) [
Receipt.java:140:INFO ] Print Receipt Requested : 10000
[java] 2007-07-31 15:58:08,352 (Thread-215) [
PosTransaction.java:283:ERROR] No Authorization transaction found for
payment preference -
[GenericEntity:OrderPaymentPreference][billingPostalCode,null()][createdByUserLogin,1(java.lang.String)][createdDate,2007-07-31
15:58:05.572(java.sql.Timestamp)][createdStamp,2007-07-31
15:58:05.982(java.sql.Timestamp)][createdTxStamp,2007-07-31
15:58:04.98(java.sql.Timestamp)][finAccountId,null()][lastUpdatedStamp,2007-07-31
15:58:05.982(java.sql.Timestamp)][lastUpdatedTxStamp,2007-07-31
15:58:04.98(java.sql.Timestamp)][manualAuthCode,null()][manualRefNum,null()][maxAmount,47.99(java.lang.Double)][needsNsfRetry,null()][orderId,RSCO10000(java.lang.String)][orderItemSeqId,null()][orderPaymentPreferenceId,10000(java.lang.String)][overflowFlag,N(java.lang.String)][paymentMethodId,null()][paymentMethodTypeId,CASH(java.lang.String)][presentFlag,N(java.lang.String)][processAttempt,null()][productPricePurposeId,null()][securityCode,null()][statusId,PAYMENT_RECEIVED(java.lang.String)]
[java] 2007-07-31 15:58:11,361 (Thread-215) [
NullPosPrinter.java:519:INFO ] transactionPrint:
[java] 2007-07-31 15:58:11,368 (Thread-215) [
PosTransaction.java:283:ERROR] No Authorization transaction found for
payment preference -
[GenericEntity:OrderPaymentPreference][billingPostalCode,null()][createdByUserLogin,1(java.lang.String)][createdDate,2007-07-31
15:58:05.572(java.sql.Timestamp)][createdStamp,2007-07-31
15:58:05.982(java.sql.Timestamp)][createdTxStamp,2007-07-31
15:58:04.98(java.sql.Timestamp)][finAccountId,null()][lastUpdatedStamp,2007-07-31
15:58:05.982(java.sql.Timestamp)][lastUpdatedTxStamp,2007-07-31
15:58:04.98(java.sql.Timestamp)][manualAuthCode,null()][manualRefNum,null()][maxAmount,47.99(java.lang.Double)][needsNsfRetry,null()][orderId,RSCO10000(java.lang.String)][orderItemSeqId,null()][orderPaymentPreferenceId,10000(java.lang.String)][overflowFlag,N(java.lang.String)][paymentMethodId,null()][paymentMethodTypeId,CASH(java.lang.String)][presentFlag,N(java.lang.String)][processAttempt,null()][productPricePurposeId,null()][securityCode,null()][statusId,PAYMENT_RECEIVED(java.lang.String)]
[java] 2007-07-31 15:58:11,370 (Thread-215)
[FlexibleStringExpander.java:355:WARN ] Could not find value in environment
for the name [nameOnCard], inserting nothing.
[java] 2007-07-31 15:58:11,370 (Thread-215)
[FlexibleStringExpander.java:355:WARN ] Could not find value in environment
for the name [authInfoString], inserting nothing.
[java] 2007-07-31 15:58:11,372 (Thread-215) [
NullPosPrinter.java:484:INFO ] Normal:
[java] Normal:|cA|4C|bCCompany XYZ
[java] Normal:|cA|bC7 E. 8th St. #308
[java] Normal:|cA|bCNew York, NY 10003
[java] Normal:|cA|bC212.655.3052
[java] Normal:
[java] Normal:|cASale: RSCO10000 Tx: 10000 Dr: 1 Clerk: 1
[java] Normal:|cATue, 31 Jul 2007 15:58:11 EEST
[java] Normal:
[java] Normal:DESC PRICE QTY
[java] Normal:==============================================
[java] Normal:GZ-5005 T 47.99 1
[java] Normal:The stylish gizmo
[java] Normal:
[java] Normal: SALES TAX: 0.00
[java] Normal: TOTAL: 47.99
[java] Normal:
[java] Normal: Cash: 47.99
[java] Normal:
[java] Normal: CHANGE: 0.00
[java] Normal:
[java] Normal:
[java] Barcode:RSCO10000Normal:
[java] Normal:|cA|4C|bCTHANK-YOU
[java] Normal:
[java] Normal:
[java] Normal:
[java] Normal:|100fP
[java] 2007-07-31 15:58:11,373 (Thread-215) [
NullPosPrinter.java:519:INFO ] transactionPrint:
Regards,
Bilgin Ibryam
--
View this message in context:
http://www.nabble.com/POS-order-status-change-error-tf4192871.html#a11923722
Sent from the OFBiz - User mailing list archive at Nabble.com.
