Bilgin, Yes I noticed that too for a while now. The POS module is really at the end of the chain (it depends of lot of other modules). So sometimes problems like this appear when other modules change. It should not be too hard to track and fix if needed (at least no inventory movements seems a bug). Sorry, I have no time for the moment. Of course any help will be appreciated.
Jacques De : "Bilgin" <[EMAIL PROTECTED]> > > 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()][ma xAmount,47.99(java.math.BigDecimal)][overflowFlag,N(java.lang.String)][paymentMethodId,null()][paymentMethodTypeId,CASH(java.lang.St ring)][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)][createdS tamp,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)][invo iceSequenceEnumId,INVSQ_ENF_SEQ(java.lang.String)][lastInvoiceNumber,0(java.lang.Long)][lastInvoiceRestartDate,null()][lastOrderNumb er,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,C ompany(java.lang.String)][quoteIdPrefix,CQ(java.lang.String)][quoteSequenceEnumId,INVSQ_ENF_SEQ(java.lang.String)][refundPaymentMeth odId,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)][createdS tamp,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)][invo iceSequenceEnumId,INVSQ_ENF_SEQ(java.lang.String)][lastInvoiceNumber,0(java.lang.Long)][lastInvoiceRestartDate,null()][lastOrderNumb er,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,C ompany(java.lang.String)][quoteIdPrefix,CQ(java.lang.String)][quoteSequenceEnumId,INVSQ_ENF_SEQ(java.lang.String)][refundPaymentMeth odId,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,nu ll()][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,nu ll()][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. >
