java.lang.IllegalArgumentException: Invalid character found in method name [0xc30xbd0xc24{"protocol_version": ]. HTTP method names must be tokens

ubuntu@ip-172-31-5-199:~$ sudo docker ps
CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES

cicd는 성공적으로 되었는데 docker 컨테이너가 실행이 되지 않고 있는 상황이다.

 

ubuntu@ip-172-31-5-199:~$ sudo docker logs fabb0419a8b4

  .   ____          _            __ _ _
 /\\ / ___'_ __ _ _(_)_ __  __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _ | \ \ \ \
 \\/  ___)| |_)| | | | | || (_| |  ) ) ) )
  '  |____| .__|_| |_|_| |_\__, | / / / /
 =========|_|==============|___/=/_/_/_/

 :: Spring Boot ::                (v3.3.5)

2024-11-18T18:19:09.286Z  INFO 1 --- [back] [           main] com.munhwahansang.back.BackApplication   : Starting BackApplication v0.0.1-SNAPSHOT using Java 17.0.2 with PID 1 (/app.jar started by root in /)
2024-11-18T18:19:09.291Z  INFO 1 --- [back] [           main] com.munhwahansang.back.BackApplication   : No active profile set, falling back to 1 default profile: "default"
2024-11-18T18:19:09.452Z  INFO 1 --- [back] [           main] .a.c.a.c.p.ParameterStorePropertySources : Loading property from AWS Parameter Store with name: /SpringBoot/prod/, optional: false
2024-11-18T18:19:11.828Z  INFO 1 --- [back] [           main] .s.d.r.c.RepositoryConfigurationDelegate : Bootstrapping Spring Data JPA repositories in DEFAULT mode.
2024-11-18T18:19:12.071Z  INFO 1 --- [back] [           main] .s.d.r.c.RepositoryConfigurationDelegate : Finished Spring Data repository scanning in 217 ms. Found 4 JPA repository interfaces.
2024-11-18T18:19:13.054Z  WARN 1 --- [back] [           main] trationDelegate$BeanPostProcessorChecker : Bean 'org.springframework.ws.config.annotation.DelegatingWsConfiguration' of type [org.springframework.ws.config.annotation.DelegatingWsConfiguration$$SpringCGLIB$$0] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying). The currently created BeanPostProcessor [annotationActionEndpointMapping] is declared through a non-static factory method on that class; consider declaring it as static instead.
2024-11-18T18:19:13.286Z  INFO 1 --- [back] [           main] .w.s.a.s.AnnotationActionEndpointMapping : Supporting [WS-Addressing August 2004, WS-Addressing 1.0]
2024-11-18T18:19:14.225Z  INFO 1 --- [back] [           main] o.s.b.w.embedded.tomcat.TomcatWebServer  : Tomcat initialized with port 8080 (http)
2024-11-18T18:19:14.259Z  INFO 1 --- [back] [           main] o.apache.catalina.core.StandardService   : Starting service [Tomcat]
2024-11-18T18:19:14.259Z  INFO 1 --- [back] [           main] o.apache.catalina.core.StandardEngine    : Starting Servlet engine: [Apache Tomcat/10.1.31]
2024-11-18T18:19:14.558Z  INFO 1 --- [back] [           main] o.a.c.c.C.[Tomcat].[localhost].[/]       : Initializing Spring embedded WebApplicationContext
2024-11-18T18:19:14.561Z  INFO 1 --- [back] [           main] w.s.c.ServletWebServerApplicationContext : Root WebApplicationContext: initialization completed in 5101 ms
2024-11-18T18:19:15.805Z  INFO 1 --- [back] [           main] o.hibernate.jpa.internal.util.LogHelper  : HHH000204: Processing PersistenceUnitInfo [name: default]
2024-11-18T18:19:16.066Z  INFO 1 --- [back] [           main] org.hibernate.Version                    : HHH000412: Hibernate ORM core version 6.5.3.Final
2024-11-18T18:19:16.186Z  INFO 1 --- [back] [           main] o.h.c.internal.RegionFactoryInitiator    : HHH000026: Second-level cache disabled
2024-11-18T18:19:17.014Z  INFO 1 --- [back] [           main] o.s.o.j.p.SpringPersistenceUnitInfo      : No LoadTimeWeaver setup: ignoring JPA class transformer
2024-11-18T18:19:17.078Z  INFO 1 --- [back] [           main] com.zaxxer.hikari.HikariDataSource       : HikariPool-1 - Starting...
2024-11-18T18:19:17.609Z  INFO 1 --- [back] [           main] com.zaxxer.hikari.pool.HikariPool        : HikariPool-1 - Added connection com.mysql.cj.jdbc.ConnectionImpl@1aea759d
2024-11-18T18:19:17.618Z  INFO 1 --- [back] [           main] com.zaxxer.hikari.HikariDataSource       : HikariPool-1 - Start completed.
2024-11-18T18:19:17.773Z  WARN 1 --- [back] [           main] org.hibernate.orm.deprecation            : HHH90000025: MySQL8Dialect does not need to be specified explicitly using 'hibernate.dialect' (remove the property setting and it will be selected by default)
2024-11-18T18:19:17.779Z  WARN 1 --- [back] [           main] org.hibernate.orm.deprecation            : HHH90000026: MySQL8Dialect has been deprecated; use org.hibernate.dialect.MySQLDialect instead
2024-11-18T18:19:20.840Z  INFO 1 --- [back] [           main] o.h.e.t.j.p.i.JtaPlatformInitiator       : HHH000489: No JTA platform available (set 'hibernate.transaction.jta.platform' to enable JTA platform integration)
2024-11-18T18:19:20.850Z  INFO 1 --- [back] [           main] j.LocalContainerEntityManagerFactoryBean : Initialized JPA EntityManagerFactory for persistence unit 'default'
2024-11-18T18:19:22.388Z  WARN 1 --- [back] [           main] JpaBaseConfiguration$JpaWebConfiguration : spring.jpa.open-in-view is enabled by default. Therefore, database queries may be performed during view rendering. Explicitly configure spring.jpa.open-in-view to disable this warning
2024-11-18T18:19:23.854Z  INFO 1 --- [back] [           main] o.s.b.w.embedded.tomcat.TomcatWebServer  : Tomcat started on port 8080 (http) with context path '/'
2024-11-18T18:19:23.929Z  INFO 1 --- [back] [           main] com.munhwahansang.back.BackApplication   : Started BackApplication in 19.804 seconds (process running for 22.042)
2024-11-18T18:19:49.813Z  INFO 1 --- [back] [nio-8080-exec-1] o.a.c.c.C.[Tomcat].[localhost].[/]       : Initializing Spring DispatcherServlet 'dispatcherServlet'
2024-11-18T18:19:49.814Z  INFO 1 --- [back] [nio-8080-exec-1] o.s.web.servlet.DispatcherServlet        : Initializing Servlet 'dispatcherServlet'
2024-11-18T18:19:49.817Z  INFO 1 --- [back] [nio-8080-exec-1] o.s.web.servlet.DispatcherServlet        : Completed initialization in 3 ms
2024-11-18T19:48:24.004Z  INFO 1 --- [back] [nio-8080-exec-2] o.apache.coyote.http11.Http11Processor   : Error parsing HTTP request header
 Note: further occurrences of HTTP request parsing errors will be logged at DEBUG level.

java.lang.IllegalArgumentException: Invalid character found in method name [0xc30xbd0xc24{"protocol_version": ]. HTTP method names must be tokens
        at org.apache.coyote.http11.Http11InputBuffer.parseRequestLine(Http11InputBuffer.java:406) ~[tomcat-embed-core-10.1.31.jar!/:na]
        at org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:257) ~[tomcat-embed-core-10.1.31.jar!/:na]
        at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:63) ~[tomcat-embed-core-10.1.31.jar!/:na]
        at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:905) ~[tomcat-embed-core-10.1.31.jar!/:na]
        at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1741) ~[tomcat-embed-core-10.1.31.jar!/:na]
        at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:52) ~[tomcat-embed-core-10.1.31.jar!/:na]
        at org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1190) ~[tomcat-embed-core-10.1.31.jar!/:na]
        at org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659) ~[tomcat-embed-core-10.1.31.jar!/:na]
        at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:63) ~[tomcat-embed-core-10.1.31.jar!/:na]
        at java.base/java.lang.Thread.run(Thread.java:833) ~[na:na]

ubuntu@ip-172-31-5-199:~$

로그를 보니 해당에러가 발생하고 있었다.

java.lang.IllegalArgumentException: Invalid character found in method name [0xc30xbd0xc24{"protocol_version": ]. HTTP method names must be tokens

 

즉 애플리케이션이 잘못된 HTTP 요청을 처리하려고 해서 발생하는 에러였다.

 

 

해결방법:

갑자기됨....진짜 뭐지...?

아무것도 안건들이고 조금 시간 지나기까 갑자기 잘 접속됨...