"Auction reported as ended" failure

Subscribe to "Auction reported as ended" failure 8 post(s), 7 voice(s)

 
Avatar merciful 1 post

I’ve had two of these in he last day: the first, I thought was perhaps that 30 seconds didn’t work for once, so I tried a minute for the important auction. (Neither auction ended early, by the way.)

Fri Mar 28 02:15:56 ADT 2008: Preparing snipe. (1)
Fri Mar 28 02:18:43 ADT 2008: Cancelling snipe, auction is reported as ended. (1)
Fri Mar 28 02:18:43 ADT 2008: Cancelling snipe. (1)

I don’t know what’s up: I’d be pleased to hear any suggestions.

OS X.4.11.

Thanks!

 
Avatar maxolla 1 post

The same thing just happened to me. Does any one know why?

 
Avatar Anagoge 1 post

I’ve missed a couple of consecutive snipes today because of this same error. I’m using JBidWatcher 1.0.3pre2 under Windows Vista. Bids yesterday seemed to go through fine. Has there been an eBay change? Or should I try the 2.0 test version? I don’t recall changing any JBidWatcher settings recently, but is there any way I can reset everything in JBidWatcher to the default settings in case that is related?

Also, I noticed a minor bug – if I check for updates with 1.0.3, it reports the “new version is 1.0.2”.

 
Avatar tmetro 3 post(s)

I’ve also seen this a few times in the past few months. I had sniping set to 10 seconds, but because of this I’ve bumped that up to 60 for now.

It would help if JBidwatcher provided additional logging of what it was doing in preparation for a snipe. I’ll try enabling the debugging option. Though neither the UI or the FAQ indicates where debugging information is logged. And how about the regular logging? Where does that go? Or do these log to the per-auction information window? They don’t appear to log to the application directory.

Here are the logs for the two that were missed:

Listing ended at Sat Apr 26 15:15:09 EDT 2008
Sun Apr 27 15:29:58 EDT 2008: Preparing snipe. (1)
Sun Apr 27 15:30:13 EDT 2008: Cancelling snipe, auction is reported as ended. (1)
Sun Apr 27 15:30:13 EDT 2008: Cancelling snipe. (1)

Listing ended at Sun Apr 27 22:24:37 EDT 2008
Sun Apr 27 22:23:13 EDT 2008: Preparing snipe. (1)
Sun Apr 27 22:25:27 EDT 2008: Cancelling snipe, auction is reported as ended. (1)
Sun Apr 27 22:25:27 EDT 2008: Cancelling snipe. (1)

In the first case the delay from the prepare point to the cancel point is 15 seconds, and in the second case 2 minutes 14 seconds.

Is this just a matter of eBay responding slowly? Additional logging would help answer that.

I haven’t shut down JBidwatcher since the last snipe was lost, and I’ll keep it running for a few more days in case any additional information can be extracted.

-Tom
 
Avatar tmetro 3 post(s)

Saw this again…this time with the 2.0 beta, and the error message is different, but essentially the same problem:

Ended: May-10-08 20:18:44 PDT Sat May 10 23:16:45 EDT 2008: Preparing snipe. Sat May 10 23:16:53 EDT 2008: Loading bid request… Sat May 10 23:17:06 EDT 2008: Done loading bid request, got form… Sat May 10 23:17:19 EDT 2008: Preparing snipe. Sat May 10 23:17:34 EDT 2008: Loading bid request… Sat May 10 23:17:44 EDT 2008: Done loading bid request, got form… Sat May 10 23:18:18 EDT 2008: Preparing snipe. Sat May 10 23:18:32 EDT 2008: Loading bid request… Sat May 10 23:18:44 EDT 2008: Done loading bid request, got form… Sat May 10 23:18:45 EDT 2008: Firing actual snipe. Sat May 10 23:18:45 EDT 2008: Submitting bid form. Sat May 10 23:18:54 EDT 2008: Done submitting bid form. Sat May 10 23:18:54 EDT 2008: Loading post-bid data. Sat May 10 23:18:54 EDT 2008: Failed to load post-bid data. ‘Show Last Error’ from context menu to see the failure page from the post-bid page. Sat May 10 23:18:54 EDT 2008: Unknown error sniping on Nintendo DS Lite Bundle – Excellent Condition!!!!

If I look at the error message captured, it is:
Bidding has ended for this item

Though that’s a post-bid error, which isn’t that critical. There may be a bug in 2.0 if it isn’t detecting that it bid after the end of the auction, as 1.x did.

This auction ended at 18:44 and was supposed to be sniped 20 seconds before ending (18:24), but the “Firing actual snipe” didn’t happen until 18:45.

-Tom

 
Avatar pax 1 post

Running into the same problem with 1.0.2 and XP, snipping set to 25 seconds.

I found this documentsandsettings/username/jbidwatcher/errors.log:

‘Sat Jan 12 20:13:40 PST 2008: Couldn’t sign in! (yet I was logged in)
java.net.SocketTimeoutException: connect timed out
at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source)
at java.lang.reflect.Constructor.newInstance(Unknown Source)
at sun.net.www.protocol.http.HttpURLConnection$6.run(Unknown Source)
at java.security.AccessController.doPrivileged(Native Method)
at sun.net.www.protocol.http.HttpURLConnection.getChainedException(Unknown Source)
at sun.net.www.protocol.http.HttpURLConnection.getInputStream(Unknown Source)
at sun.net.www.protocol.https.HttpsURLConnectionImpl.getInputStream(Unknown Source)
at Http.receiveData(Unknown Source)
at Http.receivePage(Unknown Source)
at ebayServer.getSignInCookie(Unknown Source)
at ebayServer.getSignInCookie(Unknown Source)
at ebayServer.getNecessaryCookie(Unknown Source)
at ebayServer.messageAction(Unknown Source)
at PlainMessageQueue.run(Unknown Source)
at java.lang.Thread.run(Unknown Source)
Caused by: java.net.SocketTimeoutException: connect timed out
at java.net.PlainSocketImpl.socketConnect(Native Method)
at java.net.PlainSocketImpl.doConnect(Unknown Source)
at java.net.PlainSocketImpl.connectToAddress(Unknown Source)
at java.net.PlainSocketImpl.connect(Unknown Source)
at java.net.Socket.connect(Unknown Source)
at sun.net.NetworkClient.doConnect(Unknown Source)
at sun.net.www.http.HttpClient.openServer(Unknown Source)
at sun.net.www.http.HttpClient.openServer(Unknown Source)
at sun.net.www.protocol.https.HttpsClient.(Unknown Source)
at sun.net.www.protocol.https.HttpsClient.New(Unknown Source)
at sun.net.www.protocol.https.AbstractDelegateHttpsURLConnection.getNewHttpClient(Unknown Source)
at sun.net.www.protocol.http.HttpURLConnection.plainConnect(Unknown Source)
at sun.net.www.protocol.https.AbstractDelegateHttpsURLConnection.connect(Unknown Source)
at sun.net.www.protocol.http.HttpURLConnection.getInputStream(Unknown Source)
at sun.net.www.protocol.http.HttpURLConnection.getHeaderFieldKey(Unknown Source)
at sun.net.www.protocol.https.HttpsURLConnectionImpl.getHeaderFieldKey(Unknown Source)
at CookieJar.getAllCookiesFromPage(Unknown Source)
at CookieJar.getAllCookiesFromPage(Unknown Source)
… 6 more
Sun Jan 13 02:04:26 PST 2008: Sun Jan 13 02:04:26 PST 2008: 000000000000 (something hard to come by that I really wanted – eBay ) – Cancelling snipe. (1)’

All the other errors.logs are like the one posted above by merciful.

 
Avatar RBEmerson 22 post(s)

I’ve also just missed a snipe in what seems to be a related manner:
Sun May 25 16:44:11 EDT 2008: Sun May 25 16:44:11 EDT 2008: 220236619087 (Raymarine SeaTalk Cable 29’ plugs both ends D287 new ) – Preparing snipe.
Sun May 25 16:44:11 EDT 2008: Getting the sign in cookie.
Sun May 25 16:44:12 EDT 2008: Done getting the sign in cookie.
Sun May 25 16:44:12 EDT 2008: Sun May 25 16:44:12 EDT 2008: 220236619087 (Raymarine SeaTalk Cable 29’ plugs both ends D287 new ) – Loading bid request…
Sun May 25 16:44:13 EDT 2008: Checking sign in as bid key load failed!
Sun May 25 16:44:13 EDT 2008: Sun May 25 16:44:13 EDT 2008: 220236619087 (Raymarine SeaTalk Cable 29’ plugs both ends D287 new ) – Failed to bid. ‘Show Last Error’ from context menu to see the failure page from the bid attempt.

Sun May 25 16:44:13 EDT 2008: File contents logged with message: MfcISAPICommand=MakeBid&co_partnerid=&item=220236619087&fb=2&maxbid=29.99

Sun May 25 16:44:15 EDT 2008: Sun May 25 16:44:15 EDT 2008: 220236619087 (Raymarine SeaTalk Cable 29’ plugs both ends D287 new ) – Preparing snipe.
Sun May 25 16:44:15 EDT 2008: Getting the sign in cookie.
Sun May 25 16:44:16 EDT 2008: Done getting the sign in cookie.
Sun May 25 16:44:16 EDT 2008: Sun May 25 16:44:16 EDT 2008: 220236619087 (Raymarine SeaTalk Cable 29’ plugs both ends D287 new ) – Loading bid request…
Sun May 25 16:44:16 EDT 2008: Checking sign in as bid key load failed!
Sun May 25 16:44:16 EDT 2008: Sun May 25 16:44:16 EDT 2008: 220236619087 (Raymarine SeaTalk Cable 29’ plugs both ends D287 new ) – Failed to bid. ‘Show Last Error’ from context menu to see the failure page from the bid attempt.

Sun May 25 16:44:16 EDT 2008: File contents logged with message: MfcISAPICommand=MakeBid&co_partnerid=&item=220236619087&fb=2&maxbid=29.99

Sun May 25 16:45:17 EDT 2008: Sun May 25 16:45:17 EDT 2008: 220236619087 (Raymarine SeaTalk Cable 29’ plugs both ends D287 new ) – Preparing snipe.
Sun May 25 16:45:17 EDT 2008: Getting the sign in cookie.
Sun May 25 16:45:17 EDT 2008: Unexpected exception during auction reload/update.
at java.util.AbstractList$Itr.checkForComodification(AbstractList.java:372)
at java.util.AbstractList$Itr.next(AbstractList.java:343)
at com.jbidwatcher.auction.event.EventLogger.save(EventLogger.java:176)
at com.jbidwatcher.auction.AuctionEntry.saveDB(AuctionEntry.java:1721)
at com.jbidwatcher.auction.AuctionEntry.setAuctionInfo(AuctionEntry.java:1572)
at com.jbidwatcher.auction.server.AuctionServer.reloadAuction(AuctionServer.java:236)
at com.jbidwatcher.auction.AuctionEntry.update(AuctionEntry.java:1171)
at com.jbidwatcher.auction.Auctions.doUpdate(Auctions.java:181)
at com.jbidwatcher.auction.Auctions.doNextUpdate(Auctions.java:203)
at com.jbidwatcher.auction.Auctions.check(Auctions.java:225)
at com.jbidwatcher.auction.AuctionsManager.checkAuctions(AuctionsManager.java:102)
at com.jbidwatcher.auction.AuctionsManager.check(AuctionsManager.java:129)
at com.jbidwatcher.util.queue.TimerHandler.run(TimerHandler.java:56)
Sun May 25 16:45:18 EDT 2008: Done getting the sign in cookie.
Sun May 25 16:45:18 EDT 2008: Sun May 25 16:45:18 EDT 2008: 220236619087 (Raymarine SeaTalk Cable 29’ plugs both ends D287 new ) – Loading bid request…
Sun May 25 16:45:18 EDT 2008: Checking sign in as bid key load failed!
Sun May 25 16:45:18 EDT 2008: Sun May 25 16:45:18 EDT 2008: 220236619087 (Raymarine SeaTalk Cable 29’ plugs both ends D287 new ) – Failed to bid. ‘Show Last Error’ from context menu to see the failure page from the bid attempt.

Sun May 25 16:45:18 EDT 2008: File contents logged with message: MfcISAPICommand=MakeBid&co_partnerid=&item=220236619087&fb=2&maxbid=29.99

Sun May 25 16:46:19 EDT 2008: Sun May 25 16:46:19 EDT 2008: 220236619087 (Raymarine SeaTalk Cable 29’ plugs both ends D287 new ) – Preparing snipe.
Sun May 25 16:46:19 EDT 2008: Getting the sign in cookie.
Sun May 25 16:46:20 EDT 2008: Done getting the sign in cookie.
Sun May 25 16:46:20 EDT 2008: Sun May 25 16:46:20 EDT 2008: 220236619087 (Raymarine SeaTalk Cable 29’ plugs both ends D287 new ) – Loading bid request…
Sun May 25 16:46:21 EDT 2008: Checking sign in as bid key load failed!
Sun May 25 16:46:21 EDT 2008: Sun May 25 16:46:21 EDT 2008: 220236619087 (Raymarine SeaTalk Cable 29’ plugs both ends D287 new ) – Failed to bid. ‘Show Last Error’ from context menu to see the failure page from the bid attempt.

Sun May 25 16:46:21 EDT 2008: File contents logged with message: MfcISAPICommand=MakeBid&co_partnerid=&item=220236619087&fb=2&maxbid=29.99

Sun May 25 16:46:24 EDT 2008: Sun May 25 16:46:24 EDT 2008: 220236619087 (Raymarine SeaTalk Cable 29’ plugs both ends D287 new ) – Cancelling snipe, auction is reported as ended.
Sun May 25 16:46:24 EDT 2008: Sun May 25 16:46:24 EDT 2008: 220236619087 (Raymarine SeaTalk Cable 29’ plugs both ends D287 new ) – Cancelling snipe.
Sun May 25 19:59:13 EDT 2008: Getting the sign in cookie.
Sun May 25 19:59:14 EDT 2008: Done getting the sign in cookie.
Sun May 25 22:31:01 EDT 2008: Getting the sign in cookie.
Sun May 25 22:31:02 EDT 2008: Done getting the sign in cookie.

It appears I can talk to eBay, collect watched items, etc., but forget sniping. >:-(

 
Avatar hoggyn1 4 post(s)

I have had similar problems on two occasions recently. On both occasions I was running V2. Admitedly the snipe was set at six seconds – but this is the value (sometimes five) I have used for the last couple of years with no problems.

On the last occasion (yesterday) the snipe was set at six seconds but failed as the bidding had ended. To be sure I had the same snipe set on two machines (different snipe times, different networks 25 miles apart) but both snipes failed with the same problem. Out of interest I checked the time delta and it returned 6xxx, then I fired up V1.02 and checked again, it returned 8xxx. I repeated the test, back to back several times and the difference was consistent. I would have expected that on the same machine the time delta should be the same regardless of the JBidwatcher version. Am I wrong?

Listing ended at
Thu May 29 19:21:26 BST 2008

Events
Thu May 29 19:19:20 BST 2008: Preparing snipe.
Thu May 29 19:19:21 BST 2008: Loading bid request…
Thu May 29 19:19:22 BST 2008: Done loading bid request, got form…
Thu May 29 19:20:06 BST 2008: Preparing snipe.
Thu May 29 19:20:08 BST 2008: Loading bid request…
Thu May 29 19:20:09 BST 2008: Done loading bid request, got form…
Thu May 29 19:21:10 BST 2008: Preparing snipe.
Thu May 29 19:21:12 BST 2008: Loading bid request…
Thu May 29 19:21:13 BST 2008: Done loading bid request, got form…
Thu May 29 19:21:19 BST 2008: Submitting bid form.
Thu May 29 19:21:19 BST 2008: Firing actual snipe.
Thu May 29 19:21:28 BST 2008: Unknown error sniping on Best crosstrainer on the market.
Thu May 29 19:21:28 BST 2008: Failed to load post-bid data. ‘Show Last Error’ from context menu to see the failure page from the post-bid page.
Thu May 29 19:21:28 BST 2008: Loading post-bid data.
Thu May 29 19:21:28 BST 2008: Done submitting bid form.