Re: CayenneRuntimeException: Can't find id

From: Andrus Adamchik (andru..bjectstyle.org)
Date: Tue Jan 15 2008 - 15:23:52 EST

  • Next message: Andrus Adamchik: "Re: CayenneRuntimeException: Can't find id"

    Hi Dan,

    Your diagnostics is correct... I just tested your case on MySQL with
    PK defined as BIGINT, and got the exact same error. As a temporary fix
    I suggest mapping your PK column as INTEGER in the Modeler without
    changing the DB (I *think* that should work... unless you expect
    values there higher than Integer.MAX_VALUE of course). And I'll think
    of a more permanent fix.

    Thanks,
    Andrus

    On Jan 15, 2008, at 1:01 PM, Daniel Kvasnička jr. wrote:

    > Hi Andrus,
    > I probably didn't write it clear enough.
    >
    > The scenario is: show a page, which shows first five articles obtained
    > by paginated SelectQuery (via AJAX, using Adobe Spry -- but it occus
    > even if I load the page directly) -> click a button for a new page,
    > fill in the form and click save -> save the article to the database
    > and return to the previous page with the list (e.g. a new select
    > query) -> paginate through the pages to the last page, where the new
    > article is -> Exception (the last page is not loaded at all). And as
    > I've said before, if I change ordering so that the new record is on
    > the first page, I can browse through the pages with no exception.
    >
    > The code:
    > SelectQuery select = new SelectQuery(Article.class);
    > select.addOrdering(realOrdering, isAscending);
    > select.setPageSize(pageSize);
    > List<Article> articles = (List<Article>)
    > this.dataCtx.performQuery(select);
    >
    >
    > int start = (page - 1) * pageSize;
    > int end = page * pageSize;
    >
    > System.out.println("END --------------------- service method call to
    > retrieve the list");
    > return articles.subList(start, (end > articles.size() ?
    > articles.size() : end)); // from here the exception comes
    >
    >
    > SQL log & stacktrace (what troubles me is that cayenne retrieves
    > EVERYTHING even though I've set setPageSize(5)):
    >
    > INFO QueryLogger: --- will run 1 query.
    > INFO QueryLogger: --- transaction started.
    > INFO QueryLogger: SELECT COUNT(*) AS count FROM Article // called
    > with every page load to ensure the user sees an up to date articles
    > count
    > INFO QueryLogger: === returned 1 row. - took 0 ms.
    > INFO QueryLogger: +++ transaction committed.
    > BEGIN --------------------- service method call to retrieve the list
    > // show first page of articles -- why does cayenne retireve ALL rows
    > when I have pageSize = 5?
    > INFO QueryLogger: --- will run 1 query.
    > INFO QueryLogger: --- transaction started.
    > INFO QueryLogger: SELECT t0.handle, t0.last_edited, t0.perex,
    > t0.published, t0.read_count, t0.text, t0.title, t0.user_id, t0.id FROM
    > Article t0 ORDER BY t0.id - prepared in 16 ms.
    > INFO QueryLogger: === returned 13 rows. - took 25 ms.
    > INFO QueryLogger: +++ transaction committed.
    > END --------------------- service method call to retrieve the list
    > INFO QueryLogger: --- will run 1 query.
    > INFO QueryLogger: --- transaction started.
    > INFO QueryLogger: SELECT t0.handle, t0.name, t0.id FROM Tag t0 //
    > show the "add" page and retrieve list of tags to display them as
    > checkboxes
    > INFO QueryLogger: === returned 8 rows. - took 1 ms.
    > INFO QueryLogger: +++ transaction committed.
    > BEGIN --------------------- create new article
    > INFO QueryLogger: --- will run 1 query.
    > INFO QueryLogger: --- transaction started.
    > INFO QueryLogger: SELECT t0.handle, t0.name, t0.id FROM Tag t0 WHERE
    > t0.id = ? [bind: '5']
    > INFO QueryLogger: === returned 1 row. - took 1 ms.
    > INFO QueryLogger: +++ transaction committed.
    > INFO QueryLogger: --- will run 1 query.
    > INFO QueryLogger: --- transaction started.
    > INFO QueryLogger: SELECT t0.handle, t0.name, t0.id FROM Tag t0 WHERE
    > t0.id = ? [bind: '6']
    > INFO QueryLogger: === returned 1 row. - took 0 ms.
    > INFO QueryLogger: +++ transaction committed.
    > INFO QueryLogger: --- will run 1 query.
    > INFO QueryLogger: --- transaction started.
    > INFO QueryLogger: SELECT t0.handle, t0.last_edited, t0.perex,
    > t0.published, t0.read_count, t0.text, t0.title, t0.user_id, t0.id FROM
    > Article t0 WHERE t0.handle = ? [bind: 'assdfasdf']
    > INFO QueryLogger: === returned 0 rows. - took 2 ms.
    > INFO QueryLogger: +++ transaction committed.
    > INFO QueryLogger: --- transaction started.
    > INFO QueryLogger: LOCK TABLES AUTO_PK_SUPPORT WRITE
    > INFO QueryLogger: SELECT NEXT_ID FROM AUTO_PK_SUPPORT WHERE
    > TABLE_NAME = 'Article_Tag'
    > INFO QueryLogger: UPDATE AUTO_PK_SUPPORT SET NEXT_ID = NEXT_ID + 20
    > WHERE TABLE_NAME = 'Article_Tag' AND NEXT_ID = 660
    > INFO QueryLogger: UNLOCK TABLES
    > INFO QueryLogger: --- will run 2 queries.
    > INFO QueryLogger: INSERT INTO Article (handle, last_edited, perex,
    > published, read_count, text, title, user_id) VALUES (?, ?, ?, ?, ?, ?,
    > ?, ?)
    > INFO QueryLogger: [bind: 'assdfasdf', '2008-01-15 11:49:36.992', '
    > asdfasdfasdfasdf', '2008-01-15 11:49:36.992', NULL, '<p>asdfsdfasdfasd
    > asd asdf asd</p> ', 'assdfasdf', 3]
    > INFO QueryLogger: === updated 1 row.
    > INFO QueryLogger: INSERT INTO Article_Tag (article_id, tag_id)
    > VALUES (?, ?)
    > INFO QueryLogger: [bind: 38, 5]
    > INFO QueryLogger: === updated 1 row.
    > INFO QueryLogger: [bind: 38, 6]
    > INFO QueryLogger: === updated 1 row.
    > INFO QueryLogger: +++ transaction committed.
    > END --------------------- create new article
    > INFO QueryLogger: --- will run 1 query.
    > INFO QueryLogger: --- transaction started.
    > INFO QueryLogger: SELECT COUNT(*) AS count FROM Article
    > INFO QueryLogger: === returned 1 row. - took 1 ms.
    > INFO QueryLogger: +++ transaction committed.
    > BEGIN --------------------- service method call to retrieve the list
    > // we have returned to the "list" page and see the first page again
    > INFO QueryLogger: --- will run 1 query.
    > INFO QueryLogger: --- transaction started.
    > INFO QueryLogger: SELECT t0.handle, t0.last_edited, t0.perex,
    > t0.published, t0.read_count, t0.text, t0.title, t0.user_id, t0.id FROM
    > Article t0 ORDER BY t0.id
    > INFO QueryLogger: === returned 14 rows. - took 3 ms.
    > INFO QueryLogger: +++ transaction committed.
    > END --------------------- service method call to retrieve the list
    > INFO QueryLogger: --- will run 1 query.
    > INFO QueryLogger: --- transaction started.
    > INFO QueryLogger: SELECT COUNT(*) AS count FROM Article
    > INFO QueryLogger: === returned 1 row. - took 1 ms.
    > INFO QueryLogger: +++ transaction committed.
    > BEGIN --------------------- service method call to retrieve the list
    > // we go to the second page
    > INFO QueryLogger: --- will run 1 query.
    > INFO QueryLogger: --- transaction started.
    > INFO QueryLogger: SELECT t0.handle, t0.last_edited, t0.perex,
    > t0.published, t0.read_count, t0.text, t0.title, t0.user_id, t0.id FROM
    > Article t0 ORDER BY t0.id
    > INFO QueryLogger: === returned 14 rows. - took 3 ms.
    > INFO QueryLogger: +++ transaction committed.
    > END --------------------- service method call to retrieve the list //
    > this is probably called when I call .subList(start, end) on the list ?
    > INFO QueryLogger: --- will run 1 query.
    > INFO QueryLogger: --- transaction started.
    > INFO QueryLogger: SELECT t0.handle, t0.last_edited, t0.perex,
    > t0.published, t0.read_count, t0.text, t0.title, t0.user_id, t0.id FROM
    > Article t0 WHERE (t0.id = ?) OR (t0.id = ?) OR (t0.id = ?) OR (t0.id =
    > ?) OR (t0.id = ?) [bind: 15, 19, 21, 22, 34]
    > INFO QueryLogger: === returned 5 rows. - took 2 ms.
    > INFO QueryLogger: +++ transaction committed.
    > INFO QueryLogger: --- will run 1 query.
    > INFO QueryLogger: --- transaction started.
    > INFO QueryLogger: SELECT COUNT(*) AS count FROM Article
    > INFO QueryLogger: === returned 1 row. - took 0 ms.
    > INFO QueryLogger: +++ transaction committed.
    > BEGIN --------------------- service method call to retrieve the list
    > // we want to go to the third page where the new article is
    > INFO QueryLogger: --- will run 1 query.
    > INFO QueryLogger: --- transaction started.
    > INFO QueryLogger: SELECT t0.handle, t0.last_edited, t0.perex,
    > t0.published, t0.read_count, t0.text, t0.title, t0.user_id, t0.id FROM
    > Article t0 ORDER BY t0.id
    > INFO QueryLogger: === returned 14 rows. - took 4 ms.
    > INFO QueryLogger: +++ transaction committed.
    > END --------------------- service method call to retrieve the list
    > INFO QueryLogger: --- will run 1 query.
    > INFO QueryLogger: --- transaction started.
    > INFO QueryLogger: SELECT t0.handle, t0.last_edited, t0.perex,
    > t0.published, t0.read_count, t0.text, t0.title, t0.user_id, t0.id FROM
    > Article t0 WHERE (t0.id = ?) OR (t0.id = ?) OR (t0.id = ?) OR (t0.id =
    > ?) [bind: 35, 36, 37, 38]
    > INFO QueryLogger: === returned 4 rows. - took 2 ms.
    > INFO QueryLogger: +++ transaction committed.
    > WARN DefaultExceptionHandler: Unhandled exception caught by the
    > Stripes default exception handler.
    > org.apache.cayenne.CayenneRuntimeException: [v.2.0.4 October 8 2007]
    > Can't find id for {<ObjectId:Article, id=38>; committed; [tags=>(..);
    > author=>?; title=>assdfasdf; text=><p>asdfsdfasdfasd asd asdf asd</p>
    > ; handle=>assdfasdf; perex=> asdfasdfasdfasdf; readCount=>null;
    > lastEdited=>Tue Jan 15 11:49:36 GMT+01:00 2008; published=>Tue Jan 15
    > 11:49:36 GMT+01:00 2008]}
    > at org.apache.cayenne.access.IncrementalFaultList
    > $
    > IncrementalListHelper
    > .updateWithResolvedObjectInRange(IncrementalFaultList.java:760)
    > at
    > org
    > .apache
    > .cayenne
    > .access
    > .IncrementalFaultList.resolveInterval(IncrementalFaultList.java:408)
    > at
    > org
    > .apache
    > .cayenne
    > .access.IncrementalFaultList.subList(IncrementalFaultList.java:685)
    > at
    > net
    > .danielkvasnicka
    > .mutara.service.ArticleService.getArticles(ArticleService.java:183)
    > at
    > net
    > .danielkvasnicka
    > .mutara.service.ArticleService.getArticlesAsXml(ArticleService.java:
    > 198)
    > at
    > net
    > .danielkvasnicka
    > .mutara
    > .actionbean
    > .articles.ajax.ArticleAjaxActionBean.list(ArticleAjaxActionBean.java:
    > 40)
    > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    > at
    > sun
    > .reflect
    > .NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
    > at
    > sun
    > .reflect
    > .DelegatingMethodAccessorImpl
    > .invoke(DelegatingMethodAccessorImpl.java:25)
    > at java.lang.reflect.Method.invoke(Method.java:597)
    > at net.sourceforge.stripes.controller.DispatcherHelper
    > $6.intercept(DispatcherHelper.java:445)
    > at
    > net
    > .sourceforge
    > .stripes.controller.ExecutionContext.proceed(ExecutionContext.java:
    > 157)
    > at
    > net
    > .sourceforge
    > .stripes
    > .controller
    > .BeforeAfterMethodInterceptor
    > .intercept(BeforeAfterMethodInterceptor.java:107)
    > at
    > net
    > .sourceforge
    > .stripes.controller.ExecutionContext.proceed(ExecutionContext.java:
    > 154)
    > at
    > net
    > .sourceforge
    > .stripes.controller.ExecutionContext.wrap(ExecutionContext.java:73)
    > at
    > net
    > .sourceforge
    > .stripes
    > .controller
    > .DispatcherHelper.invokeEventHandler(DispatcherHelper.java:443)
    > at
    > net
    > .sourceforge
    > .stripes
    > .controller
    > .DispatcherServlet.invokeEventHandler(DispatcherServlet.java:241)
    > at
    > net
    > .sourceforge
    > .stripes.controller.DispatcherServlet.doPost(DispatcherServlet.java:
    > 154)
    > at
    > net
    > .sourceforge
    > .stripes.controller.DispatcherServlet.doGet(DispatcherServlet.java:61)
    > at javax.servlet.http.HttpServlet.service(HttpServlet.java:690)
    > at javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
    > at
    > org
    > .apache
    > .catalina
    > .core
    > .ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:
    > 290)
    > at
    > org
    > .apache
    > .catalina
    > .core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
    > at
    > net
    > .sourceforge
    > .stripes.controller.StripesFilter.doFilter(StripesFilter.java:180)
    > at
    > org
    > .apache
    > .catalina
    > .core
    > .ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:
    > 235)
    > at
    > org
    > .apache
    > .catalina
    > .core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
    > at
    > org
    > .apache
    > .cayenne
    > .conf
    > .WebApplicationContextFilter
    > .doFilter(WebApplicationContextFilter.java:91)
    > at
    > org
    > .apache
    > .catalina
    > .core
    > .ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:
    > 235)
    > at
    > org
    > .apache
    > .catalina
    > .core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
    > at
    > org
    > .tuckey
    > .web.filters.urlrewrite.RuleChain.handleRewrite(RuleChain.java:164)
    > at
    > org.tuckey.web.filters.urlrewrite.RuleChain.doRules(RuleChain.java:
    > 141)
    > at
    > org
    > .tuckey
    > .web.filters.urlrewrite.UrlRewriter.processRequest(UrlRewriter.java:
    > 90)
    > at
    > org
    > .tuckey
    > .web
    > .filters.urlrewrite.UrlRewriteFilter.doFilter(UrlRewriteFilter.java:
    > 406)
    > at
    > org
    > .apache
    > .catalina
    > .core
    > .ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:
    > 235)
    > at
    > org
    > .apache
    > .catalina
    > .core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
    > at
    > org
    > .apache
    > .catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:
    > 233)
    > at
    > org
    > .apache
    > .catalina.core.StandardContextValve.invoke(StandardContextValve.java:
    > 175)
    > at
    > org
    > .apache
    > .catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
    > at
    > org
    > .apache
    > .catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
    > at
    > org
    > .apache
    > .catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:
    > 109)
    > at
    > org
    > .apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:
    > 263)
    > at
    > org
    > .apache.coyote.http11.Http11Processor.process(Http11Processor.java:
    > 844)
    > at org.apache.coyote.http11.Http11Protocol
    > $Http11ConnectionHandler.process(Http11Protocol.java:584)
    > at org.apache.tomcat.util.net.JIoEndpoint
    > $Worker.run(JIoEndpoint.java:447)
    > at java.lang.Thread.run(Thread.java:619)
    >
    >
    > On Jan 15, 2008 11:38 AM, Andrus Adamchik <andru..bjectstyle.org>
    > wrote:
    >> Hi Dan,
    >>
    >> Not even sure at this point that this is an issue of int vs.
    >> long... I
    >> wonder how the *new* object even appears in the paginated list that
    >> was created *before* the new object? Or did I misunderstand something
    >> about your scenario?
    >>
    >> Maybe it would help if you could post the exception and the SQL log
    >> preceding it.
    >>
    >> Thanks
    >> Andrus
    >>
    >>
    >>
    >>
    >> On Jan 14, 2008, at 10:07 PM, Daniel Kvasnička jr. wrote:
    >>
    >>> Hi people,
    >>> I've bumped into an error with cayenne 2.0.4 and the only similar
    >>> things I found were this message
    >>> (http://cayenne.markmail.org/message/elln5rjlgrsdinxm?q=paging+cache+type:users
    >>> )
    >>> and the bug CAY-643 (and those linked to it).
    >>>
    >>> What I do is that I load a page with paginated query (list of
    >>> articles, first 10), then I hit "new article" on the page, create
    >>> and
    >>> save the article and return back to the list.
    >>> Then I paginate to find the newly added article and when I enter the
    >>> page it is on and the page is the last page at the same time, I get
    >>> the CayenneRuntimeException with "Can't find id for [the newly added
    >>> article]" (i.e. when I set sorting to, let's say, by ID DESC --
    >>> which
    >>> puts the new article to the first page -- everythong is OK on all
    >>> pages)
    >>>
    >>> I understand there is the problem with long vs. int and that it will
    >>> be fixed in 3.0, so how can I as a user prevent this error in 2.0.4?
    >>> This seems to me like a very common scenario in listing records and
    >>> doing CRUD operations, but it seems almost nobody encounters this.
    >>> Do
    >>> you guys adandon pagination and use raw sql?
    >>>
    >>> I wanted to try 3.0M2, but when using it Stripes couldn't find any
    >>> ActionBeans url mappings -- strange.
    >>>
    >>> Thanks for all hints in advance,
    >>> Dan
    >>>
    >>> --
    >>> http://www.danielkvasnicka.net -- webdesign & corporate design,
    >>> programování internetových a intranetových aplikací
    >>>
    >>
    >>
    >
    >
    >
    > --
    > http://www.danielkvasnicka.net -- webdesign & corporate design,
    > programování internetových a intranetových aplikací



    This archive was generated by hypermail 2.0.0 : Tue Jan 15 2008 - 15:24:34 EST