post lograge findit

gistfile1.txt

[13:41:03.865] IP SESSION EXISTS - ALLOW
[13:41:03.865] In set_session...
[13:41:03.865] client_ip:130.132.80.210
[13:41:03.865] Session guestuser:[email protected]
[13:41:03.865]    (0.4ms)  SELECT COUNT(*) FROM `guest_users` WHERE `guest_users`.`email` = '[email protected]'
[13:41:03.865]   GuestUser Load (0.2ms)  SELECT `guest_users`.* FROM `guest_users` WHERE `guest_users`.`email` = '[email protected]' LIMIT 1
[13:41:03.865] Use exisiting session: [email protected] 1CLRQ9944lYkbh_LpVknoxkW-P4
[13:41:03.865]   SQL (22.5ms)  UPDATE `guest_users` SET updated_at = UTC_TIMESTAMP() WHERE (email = "[email protected]")
[13:41:03.865]    (0.1ms)  BEGIN
[13:41:03.865]    (0.1ms)  COMMIT
[13:41:03.865] SESSIONS:{"session_id"=>"e45ccf8656cefdc9b1a9d4ae71d865dc", "ip_check"=>true, "search"=>{:controller=>"catalog", :action=>"index"}, "history"=>[], "guestuser"=>"[email protected]", "yuluser"=>"[email protected]", "yulsess"=>"1CLRQ9944lYkbh_LpVknoxkW-P4", "_csrf_token"=>"X6uTAoGwEuXWz4fJBqR8KlNwgJRDRvEFCp3kXgbUKM4=", "path"=>"http://libdev.library.yale.edu:3005/"}
[13:41:03.865] PARAMSFULL:{"controller"=>"catalog", "action"=>"index"}
[13:41:03.865] var_sw: 
[13:41:03.865] var_sw_str: 
[13:41:03.865] user_parameters:{"controller"=>"catalog", "action"=>"index"}
[13:41:03.865]   AeonAssets Load (0.3ms)  SELECT `aeon_assets`.* FROM `aeon_assets` WHERE (user_id = '' AND pid = 'all' AND startdate <= '2016-04-18' AND enddate > '2016-04-18') LIMIT 1
[13:41:03.865] searchfield:
[13:41:03.865] uid:
[13:41:03.865] user_id:
[13:41:03.865] globalaccess:false
[13:41:03.865] FQ:"(((projid_isi:35 || projid_isi:39) && (state_ssi:I || state_ssi:A) && (viewopt_ssi:archival || viewopt_ssi:default) && !(access_restrictions_tsim:\"Audience Internal\")) || ((active_fedora_model_ssim:ComplexParent || active_fedora_model_ssim:Simple) && state_ssi:A && !(projid_isi:35 || projid_isi:39 || projid_isi:40)))"
[13:41:03.865] FQ2:"(((projid_isi:35 || projid_isi:39) && (state_ssi:I || state_ssi:A) && (viewopt_ssi:archival || viewopt_ssi:default) && !(access_restrictions_tsim:\"Audience Internal\")) || ((active_fedora_model_ssim:ComplexParent || active_fedora_model_ssim:Simple) && state_ssi:A && !(projid_isi:35 || projid_isi:39 || projid_isi:40)))"
[13:41:03.865] Solr query: {:qt=>"search", :rows=>10, :fq=>["(((projid_isi:35 || projid_isi:39) && (state_ssi:I || state_ssi:A) && (viewopt_ssi:archival || viewopt_ssi:default) && !(access_restrictions_tsim:\"Audience Internal\")) || ((active_fedora_model_ssim:ComplexParent || active_fedora_model_ssim:Simple) && state_ssi:A && !(projid_isi:35 || projid_isi:39 || projid_isi:40)))"], :"hl.fl"=>"fulltext_restricted_tsim, fulltext_open_tsim", :"hl.simple.pre"=>"<span class=\"highlight\">", :"hl.simple.post"=>"</span>", :"hl.snippets"=>"3", :hl=>true, :"facet.field"=>["yale_genre_sim", "language_sim", "creator_sim", "date_sim", "subject_topic_sim", "call_number_sim", "yale_collection_sim", "access_restrictions_sim", "start_year_isim", "digital_collection_ssim"], "spellcheck.q"=>nil, :"f.yale_genre_sim.facet.limit"=>16, :"f.language_sim.facet.limit"=>16, :"f.creator_sim.facet.limit"=>16, :"f.date_sim.facet.limit"=>16, :"f.subject_topic_sim.facet.limit"=>16, :"f.call_number_sim.facet.limit"=>16, :"f.yale_collection_sim.facet.limit"=>16, :"f.access_restrictions_sim.facet.limit"=>16, :"f.digital_collection_ssim.facet.limit"=>26, :sort=>"score desc, date_dtsi desc, title_sort_ssi asc"}
[13:41:03.865] Solr fetch: CatalogController#query_solr (310.5ms)
[13:41:03.865] method=GET path=/ format=html controller=catalog action=index status=200 duration=1129.02 view=680.70 db=26.20
[13:41:05.201] Served asset /font-awesome.css - 304 Not Modified (3ms)
[13:41:05.187] Served asset /blacklight.css - 304 Not Modified (29ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.206] Served asset /cas.css - 304 Not Modified (2ms)
[13:41:05.208] Served asset /jstree/style.min.css - 304 Not Modified (4ms)
[13:41:05.189] Served asset /application.css - 304 Not Modified (129ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.204] Served asset /images_layout.css - 304 Not Modified (2ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.360] Served asset /blacklight_range_limit/blacklight_range_limit.css - 304 Not Modified (11ms)
[13:41:05.350] Served asset /yale/yale-archival.css - 304 Not Modified (2ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.343] Served asset /yale/yale.css - 304 Not Modified (29ms)
[13:41:05.371] Served asset /service.css - 304 Not Modified (3ms)
[13:41:05.374] Served asset /blacklight_range_limit.css - 304 Not Modified (11ms)
[13:41:05.374] Served asset /blacklight_range_limit.css - 304 Not Mod[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.393] Served asset /respond.js - 304 Not Modified (3ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.411] Served asset /jquery.js - 304 Not Modified (7ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.429] Served asset /jquery-ui-1.9.2.custom.js - 304 Not Modified (3ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.441] Served asset /flot/jquery.flot.selection.js - 304 Not Modified (11ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.465] Served asset /bootstrap-tooltip.js - 304 Not Modified (3ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.451] Served asset /blacklight_range_limit/range_limit_distro_facets.js - 304 Not Modified (13ms)
[13:41:05.443] Served asset /flot/jquery.flot.js - 304 Not Modified (21ms)
[13:41:05.480] Served asset /blacklight_range_limit/range_limit_slider.js - 304 Not Modified (4ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.478] Served asset /blacklight_range_limit.js - 304 Not Modified (82ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.568] Served asset /blacklight/core.js - 304 Not Modified (8ms)
[13:41:05.589] Served asset /blacklight/checkbox_submit.js - 304 Not Modified (5ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.502] Served asset /jquery_ujs.js - 304 Not Modified (5ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.568] Served asset /blacklight/core.js - 304 Not Modified (8ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.613] Served asset /blacklight/bookmark_toggle.js - 304 Not Modified (8ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.605] Served asset /blacklight/facet_expand_contract.js - 304 Not Modified (27ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.623] Served asset /blacklight/lightbox_dialog.js - 304 Not Modified (18ms)
[13:41:05.638] Served asset /blacklight/search_context.js - 304 Not Modified (7ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.649] Served asset /blacklight/select_submit.js - 304 Not Modified (9ms)
[13:41:05.663] Served asset /blacklight/css_dropdowns.js - 304 Not Modified (5ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.666] Served asset /blacklight/zebra_stripe.js - 304 Not Modified (5ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.689] Served asset /bootstrap-transition.js - 304 Not Modified (4ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.704] Served asset /bootstrap-collapse.js - 304 Not Modified (15ms)
[13:41:05.712] Served asset /improved-modal/bootstrap-modal.js - 304 Not Modified (5ms)
[13:41:05.716] Served asset /bootstrap-alert.js - 304 Not Modified (2ms)
[13:41:05.714] Served asset /improved-modal/bootstrap-modalmanager.js - 304 Not Modified (5ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.730] Served asset /google_analytics.js - 304 Not Modified (2ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.728] Served asset /blacklight/blacklight.js - 304 Not Modified (25ms)
[13:41:05.834] Served asset /cas.js - 304 Not Modified (2ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.728] Served asset /blacklight/blacklight.js - 304 Not Modified (25ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.846] Served asset /images_layout.js - 304 Not Modified (3ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.855] Served asset /children_objects.js - 304 Not Modified (6ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.864] Served asset /openseadragon.min.js - 304 Not Modified (6ms)
[13:41:05.864] Served asset /openseadragon.min.js - 304 Not Modified[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.864] Served asset /openseadragon.min.js - 304 Not Modified (6ms)
[13:41:05.862] Served asset /jstree.min.js - 304 Not Modified (4ms)
[13:41:05.879] Served asset /service.js - 304 Not Modified (1ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true
[13:41:05.881] Served asset /application.js - 304 Not Modified (38ms)
[2016-04-18 13:41:05] WARN  Could not determine content-length of response body. Set content-length of the response or set Response#chunked = true

(aeon) Request phase initiated.
(aeon) Callback phase initiated.
RSA 1024 bit CA certificates are loaded due to old openssl compatibility
RSA 1024 bit CA certificates are loaded due to old openssl compatibility
[13:45:39.071] IP SESSION EXISTS - ALLOW
[13:45:39.071] PROVIDER: aeon
[13:45:39.071] Aeon, signed in successfully via AEON
[13:45:39.071]    (32.8ms)  SELECT COUNT(*) FROM `services` WHERE `services`.`uid` = '8e9508f1-e40a-4d2e-a046-a8ed1f5ee3f5'
[13:45:39.071]   Service Load (0.3ms)  SELECT `services`.* FROM `services` WHERE `services`.`uid` = '8e9508f1-e40a-4d2e-a046-a8ed1f5ee3f5'
[13:45:39.071] found user_id  161 for 8e9508f1-e40a-4d2e-a046-a8ed1f5ee3f5
[13:45:39.071] EMAIL (aeon,downcased):[email protected]
[13:45:39.071] PREFERRED_USERNAME(aeon)YALE\ermadmix
[13:45:39.071] UID (aeon):8e9508f1-e40a-4d2e-a046-a8ed1f5ee3f5
[13:45:39.071] ALL CLAIMS(aeon):#<OmniAuth::AuthHash credentials=#<OmniAuth::AuthHash token="dbdfe753bb8602e29c23dcf1992ae67b"> extra=#<OmniAuth::AuthHash raw_info=#<OmniAuth::AuthHash email="[email protected]" family_name="James" given_name="Eric" preferred_username="YALE\\ermadmix" sub="8e9508f1-e40a-4d2e-a046-a8ed1f5ee3f5">> info=#<OmniAuth::AuthHash::InfoHash email="[email protected]" first_name="Eric" image=nil last_name="James" name=nil nickname="YALE\\ermadmix" phone=nil urls=#<OmniAuth::AuthHash website=nil>> provider=:aeon uid="8e9508f1-e40a-4d2e-a046-a8ed1f5ee3f5">
[13:45:39.071] USERID:161
[13:45:39.071] AUTHZ parameters from: ----- 2016-04-18 13:45:41 -0400
[13:45:39.071] cache on: true
[13:45:39.071] AUTHZ callback base: https://gw-dev.its.yale.edu/soa-gateway/aeonRestrictions/kissinger?username=
[13:45:39.071] pem cert location: /home/ermadmix/certs/nopw/s_l7_findit.dev.cert.pem
[13:45:39.071] pkey location: /home/ermadmix/certs/nopw/s_l7_findit.dev.key.pem
[13:45:39.071] pkey_pw: -----2016-04-18 13:45:41 -0400
[13:45:39.071] AUTHZ callback full: https://gw-dev.its.yale.edu/soa-gateway/aeonRestrictions/kissinger?username=8e9508f1-e40a-4d2e-a046-a8ed1f5ee3f5
[13:45:39.071] AUTHZ JSON: {"username"=>"8e9508f1-e40a-4d2e-a046-a8ed1f5ee3f5", "authorized"=>true, "allowedItems"=>[{"projid"=>"35", "pid"=>"all", "code"=>"all", "startDate"=>"2015-06-15T00:00:00", "endDate"=>"2016-04-17T00:00:00"}, {"projid"=>"35", "pid"=>"all", "code"=>"all", "startDate"=>"2015-06-15T00:00:00", "endDate"=>"2016-04-17T00:00:00"}, {"projid"=>"35", "pid"=>"all", "code"=>"all", "startDate"=>"2015-06-15T00:00:00", "endDate"=>"2016-04-17T00:00:00"}, {"projid"=>"35", "pid"=>"all", "code"=>"all", "startDate"=>"2015-06-15T00:00:00", "endDate"=>"2016-04-17T00:00:00"}, {"projid"=>"35", "pid"=>"all", "code"=>"all", "startDate"=>"2015-06-15T00:00:00", "endDate"=>"2016-04-17T00:00:00"}, {"projid"=>"35", "pid"=>"all", "code"=>"all", "startDate"=>"2015-06-15T00:00:00", "endDate"=>"2016-04-17T00:00:00"}, {"projid"=>"35", "pid"=>"all", "code"=>"all", "startDate"=>"2015-06-15T00:00:00", "endDate"=>"2016-04-17T00:00:00"}, {"projid"=>"35", "pid"=>"all", "code"=>"all", "startDate"=>"2015-06-15T00:00:00", "endDate"=>"2016-04-17T00:00:00"}, {"projid"=>"35", "pid"=>"all", "code"=>"all", "startDate"=>"2015-06-15T00:00:00", "endDate"=>"2016-04-17T00:00:00"}, {"projid"=>"35", "pid"=>"all", "code"=>"all", "startDate"=>"2015-06-15T00:00:00", "endDate"=>"2019-06-15T00:00:00"}]}
[13:45:39.071] **** In add_solr_auth_doc method. Calling get_all_audience_internal ******
[13:45:39.071]   AeonAssets Load (11.8ms)  SELECT pid FROM `aeon_assets` WHERE (user_id = 161 AND (code = 'metadata' OR code = 'all') AND startdate <= '2016-04-18' AND enddate > '2016-04-18')
[13:45:39.071]   AeonAssets Load (0.4ms)  SELECT pid FROM `aeon_assets` WHERE (user_id = 161 AND (code = 'ocr' OR code = 'all') AND startdate <= '2016-04-18' AND enddate > '2016-04-18')
[13:45:39.071] MDALLOWED:["all"]
[13:45:39.071] OCRALLOWED:["all"]
[13:45:39.071] **** In add_solr_auth_doc method connecting to solr ******-----2016-04-18 13:45:56 -0400
[13:45:39.071] **** In add_solr_auth_doc method committed to solr ******
[13:45:39.071] **** add_solr_auth_doc executed in   0.090000   0.000000   0.090000 (  0.560806)

[13:45:39.071] **** uid is present and qeurying the SERVICES table-----2016-04-18 13:45:57 -0400
[13:45:39.071]   Service Load (0.3ms)  SELECT `services`.* FROM `services` WHERE `services`.`uid` = '8e9508f1-e40a-4d2e-a046-a8ed1f5ee3f5' LIMIT 1
[13:45:39.071] **** !user_signed_in-----2016-04-18 13:45:57 -0400
[13:45:39.071] **** checking if aeon record has changed
[13:45:39.071] exisiting email: [email protected] existing preferred_username YALE\ermadmix
[13:45:39.071] new email [email protected] new preferred_username YALE\ermadmix
[13:45:39.071] **** services is present and getting related user-----2016-04-18 13:45:57 -0400
[13:45:39.071]   User Load (10.2ms)  SELECT `users`.* FROM `users` WHERE `users`.`id` = 161 LIMIT 1
[13:45:39.071] **** sign_in_and_redirect-----2016-04-18 13:45:57 -0400
[13:45:39.071] in persist_authz... aeonid: 8e9508f1-e40a-4d2e-a046-a8ed1f5ee3f5 projid 35 pid: all startdt: 2015-06-15T00:00:00 enddt: 2016-04-17T00:00:00-----2016-04-18 13:45:57 -0400
[13:45:39.071]   AeonAssets Load (0.4ms)  SELECT `aeon_assets`.* FROM `aeon_assets` WHERE (user_id = 161 AND pid = 'all' AND code = 'all' AND startdate = '2015-06-15T00:00:00' AND enddate = '2016-04-17T00:00:00') LIMIT 1
[13:45:39.071]   AeonAssets Load (0.2ms)  SELECT `aeon_assets`.* FROM `aeon_assets` WHERE (user_id = 161 AND pid = 'all' AND code = 'all') LIMIT 1
[13:45:39.071] update existing row-----2016-04-18 13:45:57 -0400
[13:45:39.071]    (0.1ms)  BEGIN
[13:45:39.071]    (0.2ms)  UPDATE `aeon_assets` SET `enddate` = '2016-04-17 00:00:00', `updated_at` = '2016-04-18 17:45:57' WHERE `aeon_assets`.`id` = 47
[13:45:39.071]    (15.4ms)  COMMIT
[13:45:39.071] **** persist_authz executed in 
[13:45:39.071] in persist_authz... aeonid: 8e9508f1-e40a-4d2e-a046-a8ed1f5ee3f5 projid 35 pid: all startdt: 2015-06-15T00:00:00 enddt: 2016-04-17T00:00:00-----2016-04-18 13:45:57 -0400
[13:45:39.071]   AeonAssets Load (0.3ms)  SELECT `aeon_assets`.* FROM `aeon_assets` WHERE (user_id = 161 AND pid = 'all' AND code = 'all' AND startdate = '2015-06-15T00:00:00' AND enddate = '2016-04-17T00:00:00') LIMIT 1
[13:45:39.071] full record already exists, no need to persist anything-----2016-04-18 13:45:57 -0400
[13:45:39.071] **** persist_authz executed in 
[13:45:39.071] in persist_authz... aeonid: 8e9508f1-e40a-4d2e-a046-a8ed1f5ee3f5 projid 35 pid: all startdt: 2015-06-15T00:00:00 enddt: 2016-04-17T00:00:00-----2016-04-18 13:45:57 -0400
[13:45:39.071]   CACHE (0.0ms)  SELECT `aeon_assets`.* FROM `aeon_assets` WHERE (user_id = 161 AND pid = 'all' AND code = 'all' AND startdate = '2015-06-15T00:00:00' AND enddate = '2016-04-17T00:00:00') LIMIT 1
[13:45:39.071] full record already exists, no need to persist anything-----2016-04-18 13:45:57 -0400
[13:45:39.071] **** persist_authz executed in 
[13:45:39.071] in persist_authz... aeonid: 8e9508f1-e40a-4d2e-a046-a8ed1f5ee3f5 projid 35 pid: all startdt: 2015-06-15T00:00:00 enddt: 2016-04-17T00:00:00-----2016-04-18 13:45:57 -0400
[13:45:39.071]   CACHE (0.0ms)  SELECT `aeon_assets`.* FROM `aeon_assets` WHERE (user_id = 161 AND pid = 'all' AND code = 'all' AND startdate = '2015-06-15T00:00:00' AND enddate = '2016-04-17T00:00:00') LIMIT 1
[13:45:39.071] full record already exists, no need to persist anything-----2016-04-18 13:45:57 -0400
[13:45:39.071] **** persist_authz executed in 
[13:45:39.071] in persist_authz... aeonid: 8e9508f1-e40a-4d2e-a046-a8ed1f5ee3f5 projid 35 pid: all startdt: 2015-06-15T00:00:00 enddt: 2016-04-17T00:00:00-----2016-04-18 13:45:57 -0400
[13:45:39.071]   CACHE (0.0ms)  SELECT `aeon_assets`.* FROM `aeon_assets` WHERE (user_id = 161 AND pid = 'all' AND code = 'all' AND startdate = '2015-06-15T00:00:00' AND enddate = '2016-04-17T00:00:00') LIMIT 1
[13:45:39.071] full record already exists, no need to persist anything-----2016-04-18 13:45:57 -0400
[13:45:39.071] **** persist_authz executed in 
[13:45:39.071] in persist_authz... aeonid: 8e9508f1-e40a-4d2e-a046-a8ed1f5ee3f5 projid 35 pid: all startdt: 2015-06-15T00:00:00 enddt: 2016-04-17T00:00:00-----2016-04-18 13:45:57 -0400
[13:45:39.071]   CACHE (0.0ms)  SELECT `aeon_assets`.* FROM `aeon_assets` WHERE (user_id = 161 AND pid = 'all' AND code = 'all' AND startdate = '2015-06-15T00:00:00' AND enddate = '2016-04-17T00:00:00') LIMIT 1
[13:45:39.071] full record already exists, no need to persist anything-----2016-04-18 13:45:57 -0400
[13:45:39.071] **** persist_authz executed in 
[13:45:39.071] in persist_authz... aeonid: 8e9508f1-e40a-4d2e-a046-a8ed1f5ee3f5 projid 35 pid: all startdt: 2015-06-15T00:00:00 enddt: 2016-04-17T00:00:00-----2016-04-18 13:45:57 -0400
[13:45:39.071]   CACHE (0.0ms)  SELECT `aeon_assets`.* FROM `aeon_assets` WHERE (user_id = 161 AND pid = 'all' AND code = 'all' AND startdate = '2015-06-15T00:00:00' AND enddate = '2016-04-17T00:00:00') LIMIT 1
[13:45:39.071] full record already exists, no need to persist anything-----2016-04-18 13:45:57 -0400
[13:45:39.071] **** persist_authz executed in 
[13:45:39.071] in persist_authz... aeonid: 8e9508f1-e40a-4d2e-a046-a8ed1f5ee3f5 projid 35 pid: all startdt: 2015-06-15T00:00:00 enddt: 2016-04-17T00:00:00-----2016-04-18 13:45:57 -0400
[13:45:39.071]   CACHE (0.0ms)  SELECT `aeon_assets`.* FROM `aeon_assets` WHERE (user_id = 161 AND pid = 'all' AND code = 'all' AND startdate = '2015-06-15T00:00:00' AND enddate = '2016-04-17T00:00:00') LIMIT 1
[13:45:39.071] full record already exists, no need to persist anything-----2016-04-18 13:45:57 -0400
[13:45:39.071] **** persist_authz executed in 
[13:45:39.071] in persist_authz... aeonid: 8e9508f1-e40a-4d2e-a046-a8ed1f5ee3f5 projid 35 pid: all startdt: 2015-06-15T00:00:00 enddt: 2016-04-17T00:00:00-----2016-04-18 13:45:57 -0400
[13:45:39.071]   CACHE (0.0ms)  SELECT `aeon_assets`.* FROM `aeon_assets` WHERE (user_id = 161 AND pid = 'all' AND code = 'all' AND startdate = '2015-06-15T00:00:00' AND enddate = '2016-04-17T00:00:00') LIMIT 1
[13:45:39.071] full record already exists, no need to persist anything-----2016-04-18 13:45:57 -0400
[13:45:39.071] **** persist_authz executed in 
[13:45:39.071] in persist_authz... aeonid: 8e9508f1-e40a-4d2e-a046-a8ed1f5ee3f5 projid 35 pid: all startdt: 2015-06-15T00:00:00 enddt: 2019-06-15T00:00:00-----2016-04-18 13:45:57 -0400
[13:45:39.071]   AeonAssets Load (0.2ms)  SELECT `aeon_assets`.* FROM `aeon_assets` WHERE (user_id = 161 AND pid = 'all' AND code = 'all' AND startdate = '2015-06-15T00:00:00' AND enddate = '2019-06-15T00:00:00') LIMIT 1
[13:45:39.071]   AeonAssets Load (0.2ms)  SELECT `aeon_assets`.* FROM `aeon_assets` WHERE (user_id = 161 AND pid = 'all' AND code = 'all') LIMIT 1
[13:45:39.071] update existing row-----2016-04-18 13:45:57 -0400
[13:45:39.071]    (0.1ms)  BEGIN
[13:45:39.071]    (0.2ms)  UPDATE `aeon_assets` SET `enddate` = '2019-06-15 00:00:00', `updated_at` = '2016-04-18 17:45:57' WHERE `aeon_assets`.`id` = 47
[13:45:39.071]    (1.6ms)  COMMIT
[13:45:39.071] **** persist_authz executed in 
[13:45:39.071]    (0.1ms)  BEGIN
[13:45:39.071]    (0.6ms)  UPDATE `users` SET `last_sign_in_at` = '2016-04-08 21:02:42', `current_sign_in_at` = '2016-04-18 17:45:57', `sign_in_count` = 6, `updated_at` = '2016-04-18 17:45:57' WHERE `users`.`id` = 161
[13:45:39.071]    (1.7ms)  COMMIT
[13:45:39.071] method=GET path=/users/auth/aeon/callback format=html controller=services action=login status=302 duration=16495.88 view=0.00 db=79.67 location=http://libdev.library.yale.edu:3005/
[13:45:57.842] IP SESSION EXISTS - ALLOW
[13:45:57.842] In set_session...
[13:45:57.842] client_ip:130.132.80.210
[13:45:57.842]   User Load (0.3ms)  SELECT `users`.* FROM `users` WHERE `users`.`id` = 161 LIMIT 1
[13:45:57.842]   User Load (1.1ms)  SELECT `users`.* FROM `users` WHERE `users`.`email` = '[email protected]' LIMIT 1
[13:45:57.842]   SQL (2.1ms)  UPDATE `users` SET updated_at = UTC_TIMESTAMP() WHERE (id = 161)
[13:45:57.842]    (0.1ms)  BEGIN
[13:45:57.842]    (0.1ms)  COMMIT
[13:45:57.842] SESSIONS:{"session_id"=>"65b1be2eeb0738b460a9e6af4306188b", "ip_check"=>true, "search"=>{:controller=>"catalog", :action=>"index"}, "history"=>[], "guestuser"=>"[email protected]", "yuluser"=>"[email protected]", "yulsess"=>"ebV1LGm8VXMsmh3ai8vZM6KaWtE", "_csrf_token"=>"X6uTAoGwEuXWz4fJBqR8KlNwgJRDRvEFCp3kXgbUKM4=", "path"=>"http://libdev.library.yale.edu:3005/", "nonce"=>"4ae89a29d31488b45686a1bf1757d41e", "provider"=>"aeon", "warden.user.user.key"=>["User", [161], "$2a$10$Ln0WUIQJ8YoehviZtz.al."], "flash"=>#<ActionDispatch::Flash::FlashHash:0x00000007bd2778 @used=#<Set: {:notice}>, @closed=false, @flashes={:notice=>"Signed in successfully via AEON"}, @now=nil>}
[13:45:57.842] PARAMSFULL:{"controller"=>"catalog", "action"=>"index"}
[13:45:57.842] var_sw: 
[13:45:57.842] var_sw_str: 
[13:45:57.842] user_parameters:{"controller"=>"catalog", "action"=>"index"}
[13:45:57.842]   Service Load (0.3ms)  SELECT `services`.* FROM `services` WHERE `services`.`user_id` = 161
[13:45:57.842]   AeonAssets Load (0.3ms)  SELECT `aeon_assets`.* FROM `aeon_assets` WHERE (user_id = 161 AND pid = 'all' AND startdate <= '2016-04-18' AND enddate > '2016-04-18') LIMIT 1
[13:45:57.842] searchfield:
[13:45:57.842] uid:8e9508f1-e40a-4d2e-a046-a8ed1f5ee3f5
[13:45:57.842] user_id:161
[13:45:57.842] globalaccess:true
[13:45:57.842] FQ:"(((projid_isi:35 || projid_isi:39) && (state_ssi:A || state_ssi:I) && (viewopt_ssi:archival || viewopt_ssi:default)) || ((active_fedora_model_ssim:ComplexParent || active_fedora_model_ssim:Simple) && state_ssi:A && !(projid_isi:35 || projid_isi:39)))"
[13:45:57.842] FQ2:"(((projid_isi:35 || projid_isi:39) && (state_ssi:A || state_ssi:I) && (viewopt_ssi:archival || viewopt_ssi:default)) || ((active_fedora_model_ssim:ComplexParent || active_fedora_model_ssim:Simple) && state_ssi:A && !(projid_isi:35 || projid_isi:39)))"
[13:45:57.842] Solr query: {:qt=>"search", :rows=>10, :fq=>["(((projid_isi:35 || projid_isi:39) && (state_ssi:A || state_ssi:I) && (viewopt_ssi:archival || viewopt_ssi:default)) || ((active_fedora_model_ssim:ComplexParent || active_fedora_model_ssim:Simple) && state_ssi:A && !(projid_isi:35 || projid_isi:39)))"], :"hl.fl"=>"fulltext_restricted_tsim, fulltext_open_tsim", :"hl.simple.pre"=>"<span class=\"highlight\">", :"hl.simple.post"=>"</span>", :"hl.snippets"=>"3", :hl=>true, :"facet.field"=>["yale_genre_sim", "language_sim", "creator_sim", "date_sim", "subject_topic_sim", "call_number_sim", "yale_collection_sim", "access_restrictions_sim", "start_year_isim", "digital_collection_ssim"], "spellcheck.q"=>nil, :"f.yale_genre_sim.facet.limit"=>16, :"f.language_sim.facet.limit"=>16, :"f.creator_sim.facet.limit"=>16, :"f.date_sim.facet.limit"=>16, :"f.subject_topic_sim.facet.limit"=>16, :"f.call_number_sim.facet.limit"=>16, :"f.yale_collection_sim.facet.limit"=>16, :"f.access_restrictions_sim.facet.limit"=>16, :"f.digital_collection_ssim.facet.limit"=>26, :sort=>"score desc, date_dtsi desc, title_sort_ssi asc"}
[13:45:57.842] Solr fetch: CatalogController#query_solr (149.9ms)
[13:45:57.842] method=GET path=/ format=html controller=catalog action=index status=200 duration=605.43 view=442.24 db=4.27
[13:45:58.524] Served asset /cas.css - 200 OK (0ms)
[13:45:58.522] Served asset /blacklight.css - 200 OK (3ms)
[13:45:58.520] Served asset /application.css - 200 OK (15ms)
[13:45:58.532] Served asset /jstree/style.min.css - 200 OK (0ms)
[13:45:58.535] Served asset /font-awesome.css - 200 OK (55ms)
[13:45:58.527] Served asset /images_layout.css - 200 OK (0ms)
[13:45:58.635] Served asset /service.css - 200 OK (0ms)
[13:45:58.661] Served asset /yale/yale-archival.css - 200 OK (0ms)
[13:45:58.659] Served asset /yale/yale.css - 200 OK (2ms)
[13:45:58.674] Served asset /blacklight_range_limit/blacklight_range_limit.css - 200 OK (0ms)
[13:45:58.681] Served asset /blacklight_range_limit.css - 200 OK (0ms)
[13:45:58.706] Served asset /jquery.js - 200 OK (0ms)
[13:45:58.702] Served asset /respond.js - 200 OK (4ms)
[13:45:58.730] Served asset /jquery-ui-1.9.2.custom.js - 200 OK (0ms)
[13:45:58.755] Served asset /flot/jquery.flot.js - 200 OK (0ms)
[13:45:58.778] Served asset /flot/jquery.flot.selection.js - 200 OK (0ms)
[13:45:58.804] Served asset /bootstrap-tooltip.js - 200 OK (0ms)
[13:45:58.819] Served asset /blacklight_range_limit/range_limit_distro_facets.js - 200 OK (0ms)
[13:45:58.824] Served asset /blacklight_range_limit.js - 200 OK (0ms)
[13:45:58.831] Served asset /blacklight_range_limit/range_limit_slider.js - 200 OK (0ms)
[13:45:58.841] Served asset /jquery_ujs.js - 200 OK (0ms)
[13:45:58.843] Served asset /blacklight/core.js - 200 OK (0ms)
[13:45:58.861] Served asset /blacklight/bookmark_toggle.js - 200 OK (0ms)
[13:45:58.863] Served asset /blacklight/checkbox_submit.js - 200 OK (0ms)
[13:45:58.878] Served asset /blacklight/facet_expand_contract.js - 200 OK (0ms)
[13:45:58.889] Served asset /blacklight/lightbox_dialog.js - 200 OK (0ms)
[13:45:58.898] Served asset /blacklight/search_context.js - 200 OK (0ms)
[13:45:58.907] Served asset /blacklight/select_submit.js - 200 OK (0ms)
[13:45:58.915] Served asset /blacklight/zebra_stripe.js - 200 OK (0ms)
[13:45:58.907] Served asset /blacklight/select_submit.js - 200 OK (0ms)
[13:45:58.907] Served asset /blacklight/select_submit.js - 200 OK (0ms)[13:45:59.053] Served asset /blacklight/css_dropdowns.js - 200 OK (0ms)
[13:45:59.070] Served asset /bootstrap-transition.js - 200 OK (0ms)
[13:45:59.082] Served asset /bootstrap-collapse.js - 200 OK (0ms)
[13:45:59.090] Served asset /bootstrap-alert.js - 200 OK (0ms)
[13:45:59.099] Served asset /improved-modal/bootstrap-modalmanager.js - 200 OK (0ms)
[13:45:59.113] Served asset /improved-modal/bootstrap-modal.js - 200 OK (0ms)
[13:45:59.124] Served asset /blacklight/blacklight.js - 200 OK (0ms)
[13:45:59.130] Served asset /google_analytics.js - 200 OK (0ms)
[13:45:59.142] Served asset /images_layout.js - 200 OK (0ms)
[13:45:59.137] Served asset /cas.js - 200 OK (0ms)
[13:45:59.153] Served asset /children_objects.js - 200 OK (0ms)
[13:45:59.173] Served asset /jstree.min.js - 200 OK (0ms)
[13:45:59.185] Served asset /openseadragon.min.js - 200 OK (0ms)
[13:45:59.185] Served asset /openseadragon.min.js - 200 OK (0ms)
[13:45:59.197] Served asset /service.js - 200 OK (0ms)
[13:45:59.215] Served asset /application.js - 200 OK (3ms)