jasig-ssp IRC Logs-2013-05-24

Owned by Apereo Infrastructure
Last updated: May 24, 2013
4 min read

[12:13:17 CDT(-0500)] <JasonElwood> Having some difficulty with the MAP filters and available courses. TAGs and terms show up, but programs and available courses do not. I have courses in external_course, external_program and external_course_program. Anything else I should check before opening a ticket?

[12:21:08 CDT(-0500)] <js70> thinking...

[12:21:54 CDT(-0500)] <js70> what do you mean by available course? You are not seeing any courses with no tags?

[12:22:00 CDT(-0500)] <js70> sorry, no filters?

[12:24:43 CDT(-0500)] <JasonElwood> TAG and term filters show values from the database. program filter does NOT show values. the available courses list from external_course (and filters applied) show NO courses without filters selected

[12:25:04 CDT(-0500)] <JasonElwood> IE when MAP loads, there are no courses to put on the plan

[12:25:42 CDT(-0500)] <js70> But you get courses after application of filters?

[12:26:49 CDT(-0500)] <JasonElwood> no. never any courses

[12:27:20 CDT(-0500)] <js70> do you see any javascript errors or network issues? If you do then, you should definitely open a ticket. Otherwise, its probably a data issue, Tony or Tyler would probably solve the problem faster.

[12:27:53 CDT(-0500)] <js70> My guess is that it is a data issue of sometype (maybe duplications somewhere)

[12:28:27 CDT(-0500)] <JasonElwood> http://ec2-23-20-222-7.compute-1.amazonaws.com/ssp/api/1/reference/course/search?_dc=1369416377831&amp;termCode=Fall%202013&amp;page=1&amp;start=0&amp;limit=25

[12:28:43 CDT(-0500)] <JasonElwood> looks like it times out

[12:30:15 CDT(-0500)] <js70> k. how many courses?

[12:31:34 CDT(-0500)] <JasonElwood> 3112

[12:31:49 CDT(-0500)] <JasonElwood> This is in the linux CI if you want to see what's going o n

[12:32:50 CDT(-0500)] <TonyUnicon> do you have associations between tags and courses?

[12:33:01 CDT(-0500)] <JasonElwood> yes

[12:33:16 CDT(-0500)] <JasonElwood> if there aren't the full list should still show right

[12:33:39 CDT(-0500)] <TonyUnicon> no

[12:34:18 CDT(-0500)] <JasonElwood> so you must have tags, programs and term associations to use MAP?

[12:34:34 CDT(-0500)] <TonyUnicon> when you mean full list

[12:34:37 CDT(-0500)] <TonyUnicon> do you mean courses or tags?

[12:34:55 CDT(-0500)] <JasonElwood> when open MAP, I should see every course in external_course

[12:35:05 CDT(-0500)] <JasonElwood> in the available courses

[12:35:05 CDT(-0500)] <TonyUnicon> so you mean courses

[12:35:18 CDT(-0500)] <TonyUnicon> yes the full course list should show regardless of what associations you have

[12:35:49 CDT(-0500)] <TonyUnicon> it is the filters that do not show full lists if they do not have assocations

[12:36:19 CDT(-0500)] <JasonElwood> right. so I don't get any courses. the call times out

[12:36:40 CDT(-0500)] <TonyUnicon> logs show anything?

[12:40:34 CDT(-0500)] <JasonElwood> that's a whole other story

[12:41:41 CDT(-0500)] <JasonElwood> I can run the call separately and get results

[12:47:36 CDT(-0500)] <TonyUnicon> did you just take the instance down?

[12:48:29 CDT(-0500)] <JasonElwood> yeah. tomcat is out of control again

[12:49:23 CDT(-0500)] <JasonElwood> tried to view the log which is already 13gb

[12:49:53 CDT(-0500)] <js70> do have the logback.xml in you config folder?

[12:50:02 CDT(-0500)] <js70> that should cut down on the size of the log.

[12:50:16 CDT(-0500)] <JasonElwood> yes

[12:51:49 CDT(-0500)] <js70> is it the ssp.log geting to that size or the catalina.out?

[12:52:14 CDT(-0500)] <JasonElwood> ssp.log doesn't exist right now. catalina.out is that large

[12:53:09 CDT(-0500)] <js70> ok. sounds like you are not picking up the logback.xml

[12:56:29 CDT(-0500)] <js70> I've found that you can have some performance issues without setting you logging. Serious hibernate logging is generated.

[12:56:52 CDT(-0500)] <js70> I'd fix that first, delete the log and try again.

[12:57:29 CDT(-0500)] <js70> You can always put the logback file in the classes folder, if you can't get the CONFIG_DIR working.

[12:58:10 CDT(-0500)] <JasonElwood> that's the weird thing. this has worked, as recently as yesterday

[13:01:49 CDT(-0500)] <JasonElwood> JS- joining the SCRUM?

[14:01:10 CDT(-0500)] <JasonElwood> so making progress on the linux env

[14:01:50 CDT(-0500)] <JasonElwood> DEV: 18:59:30.164 [http-8081-1] ERROR o.j.s.w.a.e.ExternalCourseController - Error:

[14:01:50 CDT(-0500)] <JasonElwood> org.springframework.http.converter.HttpMessageNotWritableException: Could not write JSON: null; nested exception is ClientAbortException: java.net.SocketException: Brok

[14:01:50 CDT(-0500)] <JasonElwood> en pipe

[14:01:50 CDT(-0500)] <JasonElwood> at org.springframework.http.converter.json.MappingJacksonHttpMessageConverter.writeInternal(MappingJacksonHttpMessageConverter.java:143) ~[spring-web-3.1.2.RELEA

[14:01:50 CDT(-0500)] <JasonElwood> SE.jar:3.1.2.RELEASE]

[14:01:50 CDT(-0500)] <JasonElwood> at org.springframework.http.converter.AbstractHttpMessageConverter.write(AbstractHttpMessageConverter.java:179) ~[spring-web-3.1.2.RELEASE.jar:3.1.2.RELEASE]

[14:01:51 CDT(-0500)] <JasonElwood> at org.springframework.web.servlet.mvc.method.annotation.AbstractMessageConverterMethodProcessor.writeWithMessageConverters(AbstractMessageConverterMethodProcess

[14:01:51 CDT(-0500)] <JasonElwood> or.java:138) ~[spring-webmvc-3.1.2.RELEASE.jar:3.1.2.RELEASE]

[14:01:52 CDT(-0500)] <JasonElwood> at org.springframework.web.servlet.mvc.method.annotation.AbstractMessageConverterMethodProcessor.writeWithMessageConverters(AbstractMessageConverterMethodProcess

[14:01:52 CDT(-0500)] <JasonElwood> or.java:80) ~[spring-webmvc-3.1.2.RELEASE.jar:3.1.2.RELEASE]

[14:01:53 CDT(-0500)] <JasonElwood> at org.springframework.web.servlet.mvc.method.annotation.RequestResponseBodyMethodProcessor.handleReturnValue(RequestResponseBodyMethodProcessor.java:94) ~[sprin

[14:01:53 CDT(-0500)] <JasonElwood> g-webmvc-3.1.2.RELEASE.jar:3.1.2.RELEASE]

[14:15:02 CDT(-0500)] <JasonElwood> does this look familiar at all

[14:15:02 CDT(-0500)] <JasonElwood> ERROR o.j.s.s.e.i.ExternalPersonServiceImpl - Gender with code x not found

[14:16:59 CDT(-0500)] <TonyUnicon> looks like a reference data issue

[14:17:48 CDT(-0500)] <JasonElwood> you mean 'Gender'?

[14:17:54 CDT(-0500)] <TonyUnicon> yeah

[14:18:09 CDT(-0500)] <JasonElwood> except gender isn't a reference item

[14:18:15 CDT(-0500)] <JasonElwood> it is sync'd

[14:20:30 CDT(-0500)] <TonyUnicon> don't know much about it, but the error does imply that we validate it somewhere

[14:21:35 CDT(-0500)] <JasonElwood> ah ha

[14:22:20 CDT(-0500)] <TonyUnicon> select * from external_person where gender not in ('F','M','f','m')

[14:22:32 CDT(-0500)] <TonyUnicon> does that return anything?

[14:22:57 CDT(-0500)] <JasonElwood> yeah. I fixed it

[14:23:14 CDT(-0500)] <JasonElwood> well, updated the data

[14:23:24 CDT(-0500)] <JasonElwood> any ideas on the exception with externalcourse?

[14:24:30 CDT(-0500)] <TonyUnicon> I didn't see an exception

[14:24:34 CDT(-0500)] <TonyUnicon> just that you told me it times out

[14:24:43 CDT(-0500)] <JasonElwood> I posted it here

[14:24:57 CDT(-0500)] <JasonElwood>  DEV: 18:59:30.164 [http-8081-1] ERROR o.j.s.w.a.e.ExternalCourseController - Error:

[14:24:57 CDT(-0500)] <JasonElwood> org.springframework.http.converter.HttpMessageNotWritableException: Could not write JSON: null; nested exception is ClientAbortException:  java.net.SocketException: Brok

[14:24:57 CDT(-0500)] <JasonElwood> en pipe

[14:24:57 CDT(-0500)] <JasonElwood>     at org.springframework.http.converter.json.MappingJacksonHttpMessageConverter.writeInternal(MappingJacksonHttpMessageConverter.java:143) ~[spring-web-3.1.2.RELEA

[14:24:57 CDT(-0500)] <JasonElwood> SE.jar:3.1.2.RELEASE]

[14:24:57 CDT(-0500)] <JasonElwood>     at org.springframework.http.converter.AbstractHttpMessageConverter.write(AbstractHttpMessageConverter.java:179) ~[spring-web-3.1.2.RELEASE.jar:3.1.2.RELEASE]

[14:24:57 CDT(-0500)] <JasonElwood>     at org.springframework.web.servlet.mvc.method.annotation.AbstractMessageConverterMethodProcessor.writeWithMessageConverters(AbstractMessageConverterMethodProcess

[14:24:58 CDT(-0500)] <JasonElwood> or.java:138) ~[spring-webmvc-3.1.2.RELEASE.jar:3.1.2.RELEASE]

[14:24:58 CDT(-0500)] <JasonElwood>     at org.springframework.web.servlet.mvc.method.annotation.AbstractMessageConverterMethodProcessor.writeWithMessageConverters(AbstractMessageConverterMethodProcess

[14:24:59 CDT(-0500)] <JasonElwood> or.java:80) ~[spring-webmvc-3.1.2.RELEASE.jar:3.1.2.RELEASE]

[14:24:59 CDT(-0500)] <JasonElwood>     at org.springframework.web.servlet.mvc.method.annotation.RequestResponseBodyMethodProcessor.handleReturnValue(RequestResponseBodyMethodProcessor.java:94) ~[sprin

[14:25:00 CDT(-0500)] <JasonElwood> g-webmvc-3.1.2.RELEASE.jar:3.1.2.RELEASE]

[14:25:00 CDT(-0500)] <JasonElwood>     at org.springframework.web.method.support.HandlerMethodReturnValueHandlerComposite.handleReturnValue(HandlerMethodReturnValueHandlerComposite.java:74) ~[spring-w

[14:25:01 CDT(-0500)] <JasonElwood> eb-3.1.2.RELEASE.jar:3.1.2.RELEASE]

[14:25:15 CDT(-0500)] <JasonElwood> irc rejected it

[14:26:51 CDT(-0500)] <js70> gender is an enum I believe and if you are sending an gender that doesn't match the enum values, you will cause an error.

[14:26:53 CDT(-0500)] <js70> Gender with code x not found

[14:27:16 CDT(-0500)] <JasonElwood> yeah. I got it fixed. thanks. that value is in the sample data Tyler created

[14:29:15 CDT(-0500)] <TonyUnicon> select count(star) from external_course

[14:29:23 CDT(-0500)] <JasonElwood> 3112

[14:30:53 CDT(-0500)] <TonyUnicon> can you send me a dump of that table

[14:32:15 CDT(-0500)] <TonyUnicon> or maybe we should webex

[14:32:22 CDT(-0500)] <TonyUnicon> that would probably be faster overall