I'm pasting in some timelogs for different instances of the same searches that showed delays below. The time lag doesn't always happen at the same place in the retrieval. To address Mike's questions, we're not currently recording slow queries, but can enable that on our training server, for starters. We're not seeing any query errors that touch asset.copy or biblio.peer_bib_copy_map. And none of the "children available" entries in the error logs around the times of the searches. Timelogs: https://egtraining.noblenet.org/eg/opac/record/1811637?query=glamour;qtype=title;fi%3Asearch_format=serial;locg=1;detail_record_view=0 At 0.0000: New page At 0.1073: Initial load At 0.1375: load_record() began At 0.1393: past added content stage 1 At 0.1398: past staff saved searches At 0.1399: Loading results At 0.1399: Getting search parameters At 0.1403: Tag circed items? At 0.1403: Got search parameters At 0.1411: Firing off the multiclass query At 0.1496: Returned from the multiclass query At 0.1499: past related search info At 0.1972: get_records_and_facets(): about to call multisession At 0.1979: get_records_and_facets(): about to call unapi.bre via json_query (rec_ids has 1 At 1.1367: get_records_and_facets(): got response content At 1.1378: get_records_and_facets(): parsed xml At 1.1389: get_records_and_facets(): end of success handler At 1.1390: get_records_and_facets():past session wait At 1.1390: past get_records_and_facets() At 1.1477: past copy note retrieval call At 1.1778: past peer bib id retrieval At 1.1855: past copy note retrieval call At 1.2134: past peer bib id retrieval At 1.2206: past copy note retrieval call At 1.2586: past peer bib id retrieval At 1.2722: past copy note retrieval call At 1.3154: past peer bib id retrieval At 1.3290: past copy note retrieval call At 1.3722: past peer bib id retrieval At 1.3857: past copy note retrieval call At 1.4282: past peer bib id retrieval At 1.4417: past copy note retrieval call At 1.4985: past peer bib id retrieval At 1.5121: past copy note retrieval call At 1.5554: past peer bib id retrieval At 1.5690: past copy note retrieval call At 1.6125: past peer bib id retrieval At 1.6261: past copy note retrieval call At 71.6979: past peer bib id retrieval At 71.6980: past store copy retrieval call At 71.9477: past get_hold_copy_summary() At 72.2270: past serials holding stuff At 72.2270: past expandies At 72.2290: past added content stage 2 At 72.2353: get_records_and_facets(): about to call multisession At 72.2366: get_records_and_facets(): about to call unapi.mmr via json_query (rec_ids has 1 At 72.4033: get_records_and_facets(): got response content At 72.4042: get_records_and_facets(): parsed xml At 72.4060: get_records_and_facets(): end of success handler At 72.4061: get_records_and_facets():past session wait https://egtraining.noblenet.org/eg/opac/record/1818019?query=entrepreneur;qtype=title;fi%3Asearch_format=serial;locg=1;detail_record_view=0;sort= At 0.0000: New page At 0.2229: Initial load At 0.4900: load_record() began At 0.4937: past added content stage 1 At 0.6601: past staff saved searches At 0.6601: Loading results At 0.6602: Getting search parameters At 0.6610: Tag circed items? At 0.6610: Got search parameters At 0.6620: Firing off the multiclass query At 0.6791: Returned from the multiclass query At 0.6798: past related search info At 0.9441: get_records_and_facets(): about to call multisession At 0.9456: get_records_and_facets(): about to call unapi.bre via json_query (rec_ids has 1 At 1.2277: get_records_and_facets(): got response content At 1.2288: get_records_and_facets(): parsed xml At 1.2305: get_records_and_facets(): end of success handler At 1.2306: get_records_and_facets():past session wait At 1.2306: past get_records_and_facets() At 1.2458: past copy note retrieval call At 1.5060: past peer bib id retrieval At 1.5203: past copy note retrieval call At 1.5684: past peer bib id retrieval At 1.5823: past copy note retrieval call At 71.6541: past peer bib id retrieval At 71.6703: past copy note retrieval call At 71.7182: past peer bib id retrieval At 71.7323: past copy note retrieval call At 71.7797: past peer bib id retrieval At 71.7939: past copy note retrieval call At 71.8406: past peer bib id retrieval At 71.8544: past copy note retrieval call At 71.9006: past peer bib id retrieval At 71.9141: past copy note retrieval call At 71.9610: past peer bib id retrieval At 71.9746: past copy note retrieval call At 72.0213: past peer bib id retrieval At 72.0351: past copy note retrieval call At 72.0813: past peer bib id retrieval At 72.0813: past store copy retrieval call At 72.2725: past get_hold_copy_summary() At 72.3980: past serials holding stuff At 72.3980: past expandies At 72.4001: past added content stage 2 At 72.4060: get_records_and_facets(): about to call multisession At 72.4073: get_records_and_facets(): about to call unapi.mmr via json_query (rec_ids has 1 At 72.5280: get_records_and_facets(): got response content At 72.5289: get_records_and_facets(): parsed xml At 72.5308: get_records_and_facets(): end of success handler At 72.5309: get_records_and_facets():past session wait http://egtraining.noblenet.org/eg/opac/record/1811637?query=glamour;qtype=title;fi%3Asearch_format=serial;locg=1;detail_record_view=0;sort= At 0.0000: New page At 0.2316: Initial load At 0.2877: load_record() began At 0.2912: past added content stage 1 At 0.2921: past staff saved searches At 0.2922: Loading results At 0.2923: Getting search parameters At 0.2931: Tag circed items? At 0.2931: Got search parameters At 0.2943: Firing off the multiclass query At 0.3139: Returned from the multiclass query At 0.3145: past related search info At 0.3705: get_records_and_facets(): about to call multisession At 0.3719: get_records_and_facets(): about to call unapi.bre via json_query (rec_ids has 1 At 1.3727: get_records_and_facets(): got response content At 1.3746: get_records_and_facets(): parsed xml At 1.3761: get_records_and_facets(): end of success handler At 1.3762: get_records_and_facets():past session wait At 1.3763: past get_records_and_facets() At 1.3853: past copy note retrieval call At 1.4259: past peer bib id retrieval At 1.4405: past copy note retrieval call At 1.4833: past peer bib id retrieval At 1.4973: past copy note retrieval call At 1.5421: past peer bib id retrieval At 1.5559: past copy note retrieval call At 1.6000: past peer bib id retrieval At 1.6137: past copy note retrieval call At 1.6574: past peer bib id retrieval At 1.6714: past copy note retrieval call At 1.7151: past peer bib id retrieval At 1.7284: past copy note retrieval call At 71.8001: past peer bib id retrieval At 71.8158: past copy note retrieval call At 71.8601: past peer bib id retrieval At 71.8740: past copy note retrieval call At 71.9168: past peer bib id retrieval At 71.9304: past copy note retrieval call At 71.9738: past peer bib id retrieval At 71.9738: past store copy retrieval call At 72.2960: past get_hold_copy_summary() At 72.5606: past serials holding stuff At 72.5606: past expandies At 72.5626: past added content stage 2 At 72.5686: get_records_and_facets(): about to call multisession At 72.5699: get_records_and_facets(): about to call unapi.mmr via json_query (rec_ids has 1 At 72.7415: get_records_and_facets(): got response content At 72.7423: get_records_and_facets(): parsed xml At 72.7442: get_records_and_facets(): end of success handler At 72.7443: get_records_and_facets():past session wait https://egtraining.noblenet.org/eg/opac/record/1811637?query=glamour;qtype=title;fi%3Asearch_format=serial;locg=1;detail_record_view=0 NOBLE Web  |  NOBLE OverDrive  |  NOBLE Digital Heritage  |  Commonwealth Catalog  |  List of Libraries  |  Help NY Times Fiction Bestsellers  |  NY Times Nonfiction Bestsellers At 0.0000: New page At 0.1113: Initial load At 0.1414: load_record() began At 0.1431: past added content stage 1 At 0.1436: past staff saved searches At 0.1437: Loading results At 0.1437: Getting search parameters At 0.1441: Tag circed items? At 0.1441: Got search parameters At 0.1447: Firing off the multiclass query At 0.1602: Returned from the multiclass query At 0.1608: past related search info At 0.4310: get_records_and_facets(): about to call multisession At 0.4325: get_records_and_facets(): about to call unapi.bre via json_query (rec_ids has 1 At 1.5916: get_records_and_facets(): got response content At 1.5927: get_records_and_facets(): parsed xml At 1.5937: get_records_and_facets(): end of success handler At 1.5938: get_records_and_facets():past session wait At 1.5938: past get_records_and_facets() At 1.6021: past copy note retrieval call At 71.6697: past peer bib id retrieval At 71.6792: past copy note retrieval call At 71.7103: past peer bib id retrieval At 71.7184: past copy note retrieval call At 71.7487: past peer bib id retrieval At 71.7567: past copy note retrieval call At 71.7857: past peer bib id retrieval At 71.7939: past copy note retrieval call At 71.8242: past peer bib id retrieval At 71.8321: past copy note retrieval call At 71.8621: past peer bib id retrieval At 71.8702: past copy note retrieval call At 71.9001: past peer bib id retrieval At 71.9079: past copy note retrieval call At 71.9380: past peer bib id retrieval At 71.9461: past copy note retrieval call At 71.9759: past peer bib id retrieval At 71.9837: past copy note retrieval call At 72.0139: past peer bib id retrieval At 72.0140: past store copy retrieval call At 72.1638: past get_hold_copy_summary() At 72.3147: past serials holding stuff At 72.3147: past expandies At 72.3157: past added content stage 2 At 72.3190: get_records_and_facets(): about to call multisession At 72.3196: get_records_and_facets(): about to call unapi.mmr via json_query (rec_ids has 1 At 72.4519: get_records_and_facets(): got response content At 72.4527: get_records_and_facets(): parsed xml At 72.4545: get_records_and_facets(): end of success handler At 72.4546: get_records_and_facets():past session wait http://egtraining.noblenet.org/eg/opac/record/1818019?query=entrepreneur;qtype=title;fi%3Asearch_format=serial;locg=1;detail_record_view=0 At 0.0000: New page At 0.2285: Initial load At 0.2845: load_record() began At 0.2885: past added content stage 1 At 0.2900: past staff saved searches At 0.2901: Loading results At 0.2902: Getting search parameters At 0.2911: Tag circed items? At 0.2911: Got search parameters At 0.2922: Firing off the multiclass query At 0.3086: Returned from the multiclass query At 0.3091: past related search info At 0.3620: get_records_and_facets(): about to call multisession At 0.3634: get_records_and_facets(): about to call unapi.bre via json_query (rec_ids has 1 At 0.6451: get_records_and_facets(): got response content At 0.6465: get_records_and_facets(): parsed xml At 0.6481: get_records_and_facets(): end of success handler At 0.6482: get_records_and_facets():past session wait At 0.6483: past get_records_and_facets() At 0.6642: past copy note retrieval call At 0.7118: past peer bib id retrieval At 0.7254: past copy note retrieval call At 0.7727: past peer bib id retrieval At 0.7861: past copy note retrieval call At 0.8324: past peer bib id retrieval At 0.8460: past copy note retrieval call At 0.8922: past peer bib id retrieval At 0.9053: past copy note retrieval call At 0.9517: past peer bib id retrieval At 0.9651: past copy note retrieval call At 1.0117: past peer bib id retrieval At 1.0250: past copy note retrieval call At 1.0702: past peer bib id retrieval At 1.0836: past copy note retrieval call At 1.1293: past peer bib id retrieval At 1.1424: past copy note retrieval call At 1.1886: past peer bib id retrieval At 1.2019: past copy note retrieval call At 1.2473: past peer bib id retrieval At 1.2474: past store copy retrieval call At 1.4683: past get_hold_copy_summary() At 71.5543: past serials holding stuff At 71.5544: past expandies At 71.5565: past added content stage 2 At 71.5627: get_records_and_facets(): about to call multisession At 71.5641: get_records_and_facets(): about to call unapi.mmr via json_query (rec_ids has 1 At 71.6889: get_records_and_facets(): got response content At 71.6897: get_records_and_facets(): parsed xml At 71.6915: get_records_and_facets(): end of success handler At 71.6917: get_records_and_facets():past session wait http://egtraining.noblenet.org/eg/opac/record/1818019?query=entrepreneur;qtype=title;fi%3Asearch_format=serial;locg=1;detail_record_view=0 At 0.0000: New page At 0.2263: Initial load At 0.2829: load_record() began At 0.2866: past added content stage 1 At 0.2876: past staff saved searches At 0.2876: Loading results At 0.2877: Getting search parameters At 0.2885: Tag circed items? At 0.2885: Got search parameters At 0.2898: Firing off the multiclass query At 0.3076: Returned from the multiclass query At 0.3081: past related search info At 0.3606: get_records_and_facets(): about to call multisession At 0.3620: get_records_and_facets(): about to call unapi.bre via json_query (rec_ids has 1 At 0.6431: get_records_and_facets(): got response content At 0.6442: get_records_and_facets(): parsed xml At 0.6459: get_records_and_facets(): end of success handler At 0.6461: get_records_and_facets():past session wait At 0.6461: past get_records_and_facets() At 0.6614: past copy note retrieval call At 0.7100: past peer bib id retrieval At 0.7237: past copy note retrieval call At 0.7709: past peer bib id retrieval At 0.7846: past copy note retrieval call At 0.8322: past peer bib id retrieval At 0.8458: past copy note retrieval call At 0.8925: past peer bib id retrieval At 0.9061: past copy note retrieval call At 0.9531: past peer bib id retrieval At 0.9665: past copy note retrieval call At 1.0142: past peer bib id retrieval At 1.0279: past copy note retrieval call At 1.0759: past peer bib id retrieval At 1.0892: past copy note retrieval call At 1.1365: past peer bib id retrieval At 1.1502: past copy note retrieval call At 1.1973: past peer bib id retrieval At 1.2109: past copy note retrieval call At 1.2579: past peer bib id retrieval At 1.2579: past store copy retrieval call At 1.4666: past get_hold_copy_summary() At 71.5526: past serials holding stuff At 71.5527: past expandies At 71.5548: past added content stage 2 At 71.5611: get_records_and_facets(): about to call multisession At 71.5625: get_records_and_facets(): about to call unapi.mmr via json_query (rec_ids has 1 At 71.6927: get_records_and_facets(): got response content At 71.6936: get_records_and_facets(): parsed xml At 71.6956: get_records_and_facets(): end of success handler At 71.6958: get_records_and_facets():past session wait