Dec 05, 2022 9:40:42 AM edu.stsci.apt.TestProposal setUpOnce
INFO: ========== Beginning run of APT.main. ==========
Dec 05, 2022 2:40:44 PM edu.stsci.roman.apt.io.file.RomanProgrammaticInfoImportAction detectImporterMode
INFO: Running Roman Programmatic Info Importer via the commandline to merge xml into an existing APT file.
Dec 05, 2022 2:40:51 PM nom.tam.fits.Header addDuplicate
WARNING: Multiple occurrences of key:CONTINUE
Dec 05, 2022 2:40:51 PM nom.tam.fits.Header addDuplicate
WARNING: Multiple occurrences of key:CONTINUE
Dec 05, 2022 2:40:51 PM nom.tam.fits.Header addDuplicate
WARNING: Multiple occurrences of key:CONTINUE
Dec 05, 2022 2:40:51 PM edu.stsci.roman.apt.io.file.RomanProgrammaticInfoImportAction doImport
INFO: Program ID for merge is 13532 derived from the APT filename 13532-RomanProgInfoMerge.apt
Dec 05, 2022 2:40:51 PM edu.stsci.interfaces.proper.ProPerClient openStreamWithApiKey
INFO: Opening: 'https://propertest.stsci.edu/proper/rest/getInvestigators?mission=ROMAN&id=13532'
Dec 05, 2022 2:40:51 PM edu.stsci.roman.apt.io.file.RomanProgrammaticInfoImportAction updateInvestigatorsInDataModel
WARNING: No investigators are registered in ProPer for program %d
Dec 05, 2022 2:40:51 PM edu.stsci.apt.APTServers getClientRegistry
INFO: Using local APTServer: [Version:120]
Dec 05, 2022 2:40:51 PM edu.stsci.apt.hst.AddressServerImpl writeLogEntry
INFO: Starting Address Server: null
Dec 05, 2022 2:40:51 PM edu.stsci.apt.hst.OpportunityServerImpl <init>
INFO: Starting Opportunity Server: null
Dec 05, 2022 2:40:51 PM edu.stsci.apt.hst.StatusServerImpl <init>
INFO: Starting HST Status Server: null
Dec 05, 2022 2:40:51 PM edu.stsci.apt.APTServers getAPTServer
INFO: Using embedded server
Dec 05, 2022 2:40:51 PM edu.stsci.apt.hst.AddressServerImpl writeLogEntry
INFO: >>>>> Address requested for: [Myers, Andrew, , Space Telescope Science Institute, , , , amyers@stsci.edu, , ]
Dec 05, 2022 2:40:51 PM edu.stsci.apt.hst.AddressServerImpl writeLogEntry
INFO: >>>>> Addresses requested for: Myers
Dec 05, 2022 2:40:51 PM edu.stsci.interfaces.proper.ProPerClient openStreamWithApiKey
INFO: Opening: 'https://propertest.stsci.edu/proper/rest/findPeople?name=Myers&max=50'
Dec 05, 2022 2:40:52 PM edu.stsci.interfaces.proper.ProPerClient openStreamWithApiKey
INFO: Opening: 'https://propertest.stsci.edu/proper/rest/getInstitutions?ids=3932,221,10702,3425,483,,24524,341,3487,1312,72,2002,1083,3658,450,25051,221,12258,1774,600,12424,1574,38,3378,,221,221,295,250,181,282,509,1312,79,365,1825,233,13221,2210,346,12694,18,1825,2130,10633,1321,1131,20,11755,2077,'
Dec 05, 2022 2:40:52 PM edu.stsci.apt.hst.AddressServerImpl writeLogEntry
INFO: <<<<< Found: 50
Dec 05, 2022 2:40:52 PM edu.stsci.apt.hst.AddressServerImpl writeLogEntry
INFO: <<<<< Found: [Myers, Andrew, , Space Telescope Science Institute, , , , amyers@stsci.edu, N, 12156, USA, MD, 221, , N]
Dec 05, 2022 2:40:52 PM edu.stsci.roman.apt.io.file.RomanProgrammaticInfoImportAction resolveWithDataFromProper
INFO: Unique ID 12156 found in ProPer for investigator Andrew Myers (amyers@stsci.edu)
Dec 05, 2022 2:40:52 PM edu.stsci.apt.hst.AddressServerImpl writeLogEntry
INFO: >>>>> Address requested for: 12156
Dec 05, 2022 2:40:52 PM edu.stsci.interfaces.proper.ProPerClient openStreamWithApiKey
INFO: Opening: 'https://propertest.stsci.edu/proper/rest/getPeople?ids=12156'
Dec 05, 2022 2:40:52 PM edu.stsci.apt.hst.AddressServerImpl writeLogEntry
INFO: >>>>> Address requested for: [Goldberg, Joshua, , null, , , , goldberg@stsci.edu, , ]
Dec 05, 2022 2:40:52 PM edu.stsci.apt.hst.AddressServerImpl writeLogEntry
INFO: >>>>> Addresses requested for: Goldberg
Dec 05, 2022 2:40:52 PM edu.stsci.interfaces.proper.ProPerClient openStreamWithApiKey
INFO: Opening: 'https://propertest.stsci.edu/proper/rest/findPeople?name=Goldberg&max=50'
Dec 05, 2022 2:40:53 PM edu.stsci.interfaces.proper.ProPerClient openStreamWithApiKey
INFO: Opening: 'https://propertest.stsci.edu/proper/rest/getInstitutions?ids=221,'
Dec 05, 2022 2:40:53 PM edu.stsci.apt.hst.AddressServerImpl writeLogEntry
INFO: <<<<< Found: [Myers, Andrew, , Space Telescope Science Institute, , , , amyers@stsci.edu, N, 12156, USA, MD, 221, , N]
Dec 05, 2022 2:40:53 PM edu.stsci.interfaces.proper.ProPerClient openStreamWithApiKey
INFO: Opening: 'https://propertest.stsci.edu/proper/rest/getInstitutions?ids=3256,2178,1320,221,2629,12613,72,2785,524,'
Dec 05, 2022 2:40:53 PM edu.stsci.apt.hst.AddressServerImpl writeLogEntry
INFO: <<<<< Found: 9
Dec 05, 2022 2:40:53 PM edu.stsci.apt.hst.AddressServerImpl writeLogEntry
INFO: <<<<< Found: [Goldberg, Joshua, , Space Telescope Science Institute, Dr., , , goldberg@stsci.edu, N, 16204, USA, MD, 221, , N]
Dec 05, 2022 2:40:53 PM edu.stsci.roman.apt.io.file.RomanProgrammaticInfoImportAction resolveWithDataFromProper
INFO: Unique ID 16204 found in ProPer for investigator Joshua Goldberg (goldberg@stsci.edu)
Dec 05, 2022 2:40:53 PM edu.stsci.apt.hst.AddressServerImpl writeLogEntry
INFO: >>>>> Address requested for: [Ritchie, Christine, , null, , , , ritchie@stsci.edu, , ]
Dec 05, 2022 2:40:53 PM edu.stsci.apt.hst.AddressServerImpl writeLogEntry
INFO: >>>>> Addresses requested for: Ritchie
Dec 05, 2022 2:40:53 PM edu.stsci.interfaces.proper.ProPerClient openStreamWithApiKey
INFO: Opening: 'https://propertest.stsci.edu/proper/rest/findPeople?name=Ritchie&max=50'
Dec 05, 2022 2:40:53 PM edu.stsci.apt.hst.AddressServerImpl writeLogEntry
INFO: >>>>> Address requested for: 16204
Dec 05, 2022 2:40:53 PM edu.stsci.interfaces.proper.ProPerClient openStreamWithApiKey
INFO: Opening: 'https://propertest.stsci.edu/proper/rest/getPeople?ids=16204'
Dec 05, 2022 2:40:53 PM edu.stsci.interfaces.proper.ProPerClient openStreamWithApiKey
INFO: Opening: 'https://propertest.stsci.edu/proper/rest/getInstitutions?ids=221,'
Dec 05, 2022 2:40:53 PM edu.stsci.apt.hst.AddressServerImpl writeLogEntry
INFO: <<<<< Found: [Goldberg, Joshua, , Space Telescope Science Institute, Dr., , , goldberg@stsci.edu, N, 16204, USA, MD, 221, , N]
Dec 05, 2022 2:40:54 PM edu.stsci.interfaces.proper.ProPerClient openStreamWithApiKey
INFO: Opening: 'https://propertest.stsci.edu/proper/rest/getInstitutions?ids=221,11846,10479,2105,2565,10529,2077,111,3329,2894,13532,1840,17,218,3798,41,258,3670,1775,1315,221,2077,12278,229,360,2806,72,8002,287,11812,'
Dec 05, 2022 2:40:54 PM edu.stsci.apt.hst.AddressServerImpl writeLogEntry
INFO: <<<<< Found: 30
Dec 05, 2022 2:40:54 PM edu.stsci.apt.hst.AddressServerImpl writeLogEntry
INFO: <<<<< Found: [Ritchie, Christine, , Space Telescope Science Institute, Ms., , 410-338-4486, ritchie@stsci.edu, N, 3476, USA, MD, 221, , N]
Dec 05, 2022 2:40:54 PM edu.stsci.roman.apt.io.file.RomanProgrammaticInfoImportAction resolveWithDataFromProper
INFO: Unique ID 3476 found in ProPer for investigator Christine Ritchie (ritchie@stsci.edu)
Dec 05, 2022 2:40:54 PM edu.stsci.apt.hst.AddressServerImpl writeLogEntry
INFO: >>>>> Address requested for: [Aarnio, Alicia, , null, , , , aarnio@umich.edu, , ]
Dec 05, 2022 2:40:54 PM edu.stsci.apt.hst.AddressServerImpl writeLogEntry
INFO: >>>>> Address requested for: 3476
Dec 05, 2022 2:40:54 PM edu.stsci.apt.hst.AddressServerImpl writeLogEntry
INFO: >>>>> Addresses requested for: Aarnio
Dec 05, 2022 2:40:54 PM edu.stsci.interfaces.proper.ProPerClient openStreamWithApiKey
INFO: Opening: 'https://propertest.stsci.edu/proper/rest/getPeople?ids=3476'
Dec 05, 2022 2:40:54 PM edu.stsci.interfaces.proper.ProPerClient openStreamWithApiKey
INFO: Opening: 'https://propertest.stsci.edu/proper/rest/findPeople?name=Aarnio&max=50'
Dec 05, 2022 2:40:54 PM edu.stsci.interfaces.proper.ProPerClient openStreamWithApiKey
INFO: Opening: 'https://propertest.stsci.edu/proper/rest/getInstitutions?ids=221,'
Dec 05, 2022 2:40:54 PM edu.stsci.apt.hst.AddressServerImpl writeLogEntry
INFO: <<<<< Found: [Ritchie, Christine, , Space Telescope Science Institute, Ms., , 410-338-4486, ritchie@stsci.edu, N, 3476, USA, MD, 221, , N]
Dec 05, 2022 2:40:54 PM edu.stsci.interfaces.proper.ProPerClient openStreamWithApiKey
INFO: Opening: 'https://propertest.stsci.edu/proper/rest/getInstitutions?ids=,281,221,261,218,'
Dec 05, 2022 2:40:54 PM edu.stsci.apt.hst.AddressServerImpl writeLogEntry
INFO: <<<<< Found: 5
Dec 05, 2022 2:40:54 PM edu.stsci.apt.hst.AddressServerImpl writeLogEntry
INFO: <<<<< Found: [Aarnio, Alicia, , , , , , aarnio@umich.edu, , 22814, , , , , ]
Dec 05, 2022 2:40:54 PM edu.stsci.roman.apt.io.file.RomanProgrammaticInfoImportAction resolveWithDataFromProper
INFO: Unique ID 22814 found in ProPer for investigator Alicia Aarnio (aarnio@umich.edu)
Dec 05, 2022 2:40:54 PM edu.stsci.roman.apt.io.file.RomanProgrammaticInfoImportAction doImport
INFO: Imported SSC Programmatic Information from /Users/Shared/APT/Quick/AptBuild/apt/RegressionTests/output/13532-RomanProgInfoMerge/13532-RomanProgInfoMerge.apt into /Users/Shared/APT/Quick/AptBuild/apt/RegressionTests/output/13532-RomanProgInfoMerge/13532-RomanProgInfoMerge.apt
Dec 05, 2022 2:40:54 PM edu.stsci.apt.hst.AddressServerImpl writeLogEntry
INFO: >>>>> Address requested for: 22814
Dec 05, 2022 2:40:54 PM edu.stsci.interfaces.proper.ProPerClient openStreamWithApiKey
INFO: Opening: 'https://propertest.stsci.edu/proper/rest/getPeople?ids=22814'
Dec 05, 2022 2:40:54 PM edu.stsci.interfaces.proper.ProPerClient openStreamWithApiKey
INFO: Opening: 'https://propertest.stsci.edu/proper/rest/getInstitutions?ids=,'
java.io.IOException: Server returned HTTP response code: 400 for URL: https://propertest.stsci.edu/proper/rest/getInstitutions?ids=,
at java.base/sun.net.www.protocol.http.HttpURLConnection.getInputStream0(HttpURLConnection.java:1924)
at java.base/sun.net.www.protocol.http.HttpURLConnection$9.run(HttpURLConnection.java:1512)
at java.base/sun.net.www.protocol.http.HttpURLConnection$9.run(HttpURLConnection.java:1510)
at java.base/java.security.AccessController.doPrivileged(Native Method)
at java.base/java.security.AccessController.doPrivilegedWithCombiner(AccessController.java:795)
at java.base/sun.net.www.protocol.http.HttpURLConnection.getInputStream(HttpURLConnection.java:1509)
at java.base/sun.net.www.protocol.https.HttpsURLConnectionImpl.getInputStream(HttpsURLConnectionImpl.java:250)
at edu.stsci.interfaces.proper.ProPerClient.openStreamWithApiKey(ProPerClient.java:209)
at edu.stsci.interfaces.proper.ProPerClient.getInstitutions(ProPerClient.java:304)
at edu.stsci.apt.hst.AddressServerImpl.populateCountryStateEsa(AddressServerImpl.java:192)
at edu.stsci.apt.hst.AddressServerImpl.lookupUniqueAddress(AddressServerImpl.java:147)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at edu.stsci.apt.AptServlet.localService(AptServlet.java:89)
at edu.stsci.apt.AptClient.sendRequest(AptClient.java:192)
at edu.stsci.apt.AptClient.sendRequest(AptClient.java:162)
at edu.stsci.apt.hst.AddressClient.lookupUniqueAddress(AddressClient.java:91)
at edu.stsci.apt.model.STScICoInvestigator.refreshAddressLookup(STScICoInvestigator.java:182)
at edu.stsci.apt.model.STScICoInvestigator.cosiRefreshAddress(STScICoInvestigator.java:164)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at edu.stsci.CoSI.AutoConstraint.run(AutoConstraint.java:68)
at edu.stsci.CoSI.Propagator.propagate(Propagator.java:230)
at edu.stsci.CoSI.Propagator._addConstraint(Propagator.java:87)
at edu.stsci.CoSI.Propagator.addConstraint(Propagator.java:82)
at edu.stsci.CoSI.swing.SwingConstraint$1.run(SwingConstraint.java:47)
at java.desktop/java.awt.event.InvocationEvent.dispatch(InvocationEvent.java:313)
at java.desktop/java.awt.EventQueue.dispatchEventImpl(EventQueue.java:770)
at java.desktop/java.awt.EventQueue$4.run(EventQueue.java:721)
at java.desktop/java.awt.EventQueue$4.run(EventQueue.java:715)
at java.base/java.security.AccessController.doPrivileged(Native Method)
at java.base/java.security.ProtectionDomain$JavaSecurityAccessImpl.doIntersectionPrivilege(ProtectionDomain.java:85)
at java.desktop/java.awt.EventQueue.dispatchEvent(EventQueue.java:740)
at java.desktop/java.awt.EventDispatchThread.pumpOneEventForFilters(EventDispatchThread.java:203)
at java.desktop/java.awt.EventDispatchThread.pumpEventsForFilter(EventDispatchThread.java:124)
at java.desktop/java.awt.EventDispatchThread.pumpEventsForHierarchy(EventDispatchThread.java:113)
at java.desktop/java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:109)
at java.desktop/java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:101)
at java.desktop/java.awt.EventDispatchThread.run(EventDispatchThread.java:90)
Dec 05, 2022 2:40:54 PM edu.stsci.apt.hst.AddressServerImpl writeLogEntry
INFO: <<<<< Found: [Aarnio, Alicia, , , , , , aarnio@umich.edu, , 22814, , , , , ]
Dec 05, 2022 2:40:55 PM edu.stsci.pcg.roman.RpcgWsClient getConstraintGenerator
INFO: RPCG WS QName: {http://roman.pcg.stsci.edu/}ConstraintGeneratorService
Dec 05, 2022 2:40:55 PM edu.stsci.pcg.roman.RpcgWsClient getConstraintGenerator
INFO: RPCG WSDL Location: https://rpcg.stsci.edu/Rpcg-Ws/ConstraintGeneratorService?wsdl
Dec 05, 2022 2:40:55 PM edu.stsci.pcg.roman.RpcgWsClient getConstraintGenerator
INFO: RPCG Version: 0.9
Dec 05, 2022 2:41:05 PM edu.stsci.apt.TestProposal setUpOnce
INFO: ========== Finished APT.main. ==========
Comparing files: RegressionTests/expectedOutput/13532-RomanProgInfoMerge.apt RegressionTests/output/13532-RomanProgInfoMerge/13532-RomanProgInfoMerge.apt
End of File: Files are the same
Dec 05, 2022 2:41:05 PM edu.stsci.interfaces.proper.ProPerClient openStreamWithApiKey
INFO: Opening: 'https://propertest.stsci.edu/proper/isUp'