Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

[OracleDatabase/23.5.0] Unable to build a new extended image based on oracle/database:23.5.0-free because listener does not work #2870

Open
doberkofler opened this issue Sep 22, 2024 · 4 comments

Comments

@doberkofler
Copy link

doberkofler commented Sep 22, 2024

I'm trying to extend the Oracle Database image generated by buildContainerImage.sh but when building the new image the listener cannot be started with the following error message:

#11 0.371 Configuring Oracle Listener.
#11 1.827 Listener configuration failed. Check log '/opt/oracle/cfgtoollogs/netca/netca_configure_out.log' for more details.
#11 1.830 Netca configuration log
#11 1.830
#11 1.830 Parsing command line arguments:
#11 1.830     Parameter "orahome" = /opt/oracle/product/23ai/dbhomeFree
#11 1.830     Parameter "instype" = typical
#11 1.830     Parameter "inscomp" = client,oraclenet,javavm,server,ano
#11 1.830     Parameter "insprtcl" = tcp
#11 1.830     Parameter "cfg" = local
#11 1.830     Parameter "authadp" = NO_VALUE
#11 1.830     Parameter "responsefile" = /opt/oracle/product/23ai/dbhomeFree/network/install/netca_typ.rsp
#11 1.830     Parameter "silent" = true
#11 1.830     Parameter "orahnam" = OraHomeFree
#11 1.830     Parameter "listenerparameters" = DEFAULT_SERVICE=FREE
#11 1.830     Parameter "lisport" = 1521
#11 1.830 Done parsing command line arguments.
#11 1.830 Oracle Net Services Configuration:
#11 1.830 Profile configuration complete.
#11 1.830 Listener is using the port provided in the command line:1521
#11 1.830 Oracle Net Listener Startup:
#11 1.830 No valid IP Address returned for the host buildkitsandbox.
#11 1.830 Check the trace file for details: /opt/oracle/cfgtoollogs/netca/trace_OraDBHome23aiFree-2409228AM2107.log
#11 1.830 Oracle Net Services configuration failed.  The exit code is 1

This is my configuration using docker desktop 4.34.2 running macOS 15.0 on an Intel MacBook Pro:

  1. I created the base image using the following command:
./buildContainerImage.sh -v 23.5.0 -f -o '--progress=plain'`
  1. I use the following script to build the extended image:
export TARGET_IMAGE=oracle/database_ext:23.5.0-free
export DOCKERFILE=Dockerfile

docker image rm --force ${TARGET_IMAGE}
docker build --file=${DOCKERFILE} --progress=plain --rm --tag=${TARGET_IMAGE} --no-cache --ulimit nofile=1024:65536 --ulimit nproc=2047:16384 --ulimit stack=10485760:33554432 --ulimit memlock=3221225472 --memory="5g" --memory-swap="16g" .
  1. I use the following (reduced) Dockerfile:
FROM oracle/database:23.5.0-free

USER oracle

RUN /opt/oracle/runOracle.sh --nowait || true && \
	cat /opt/oracle/cfgtoollogs/netca/netca_configure_out.log

ENTRYPOINT [ "bash" ]

Interestingly when running the base image the listener and the database start as expected:

docker run --name test -p 1521:1521 -p 5500:5500 -p 2484:2484 --ulimit nofile=1024:65536 --ulimit nproc=2047:16384 --ulimit stack=10485760:33554432 --ulimit memlock=3221225472 -e ORACLE_PWD=manager oracle/database:23.5.0-free

Specify a password to be used for database accounts. Oracle recommends that the password entered should be at least 8 characters in length, contain at least 1 uppercase character, 1 lower case character and 1 digit [0-9]. Note that the same password will be used for SYS, SYSTEM and PDBADMIN accounts:
Confirm the password:
Configuring Oracle Listener.
Listener configuration succeeded.
Configuring Oracle Database FREE.
Enter SYS user password:
********
Enter SYSTEM user password:
********
Enter PDBADMIN User Password:
*******
Prepare for db operation
Copying database files
Creating and starting Oracle instance
Completing Database Creation
Creating Pluggable Databases
Executing Post Configuration Actions
Running Custom Scripts
Database creation complete. For details check the logfiles at:
 /opt/oracle/cfgtoollogs/dbca/FREE.
Database Information:
Global Database Name:FREE
System Identifier(SID):FREE
Look at the log file "/opt/oracle/cfgtoollogs/dbca/FREE/FREE.log" for further details.
Connect to Oracle Database using one of the connect strings:
     Pluggable database: 38678ca8bf07/FREEPDB1
     Multitenant container database: 38678ca8bf07
SQL*Plus: Release 23.0.0.0.0 - for Oracle Cloud and Engineered Systems on Sun Sep 22 09:50:08 2024
Version 23.5.0.24.07
Copyright (c) 1982, 2024, Oracle.  All rights reserved.
Connected to:
Oracle Database 23ai Free Release 23.0.0.0.0 - Develop, Learn, and Run for Free
Version 23.5.0.24.07
SQL>
System altered.
SQL>
Pluggable database altered.
SQL>
PL/SQL procedure successfully completed.
SQL> SQL>
Session altered.
SQL>
User created.
SQL>
Grant succeeded.
SQL>
Grant succeeded.
SQL>
Grant succeeded.
SQL>
User altered.
SQL> SQL> Disconnected from Oracle Database 23ai Free Release 23.0.0.0.0 - Develop, Learn, and Run for Free
Version 23.5.0.24.07
The Oracle base remains unchanged with value /opt/oracle
The Oracle base remains unchanged with value /opt/oracle
#########################
DATABASE IS READY TO USE!
#########################
The following output is now a tail of the alert.log:
FREEPDB1(3):Tablespace created: USERS ts# 6
FREEPDB1(3):Completed: CREATE SMALLFILE TABLESPACE "USERS" LOGGING  DATAFILE  '/opt/oracle/oradata/FREE/FREEPDB1/users01.dbf' SIZE 5M REUSE AUTOEXTEND ON NEXT  1280K MAXSIZE UNLIMITED  EXTENT MANAGEMENT LOCAL  SEGMENT SPACE MANAGEMENT  AUTO
FREEPDB1(3):ALTER DATABASE DEFAULT TABLESPACE "USERS"
FREEPDB1(3):Completed: ALTER DATABASE DEFAULT TABLESPACE "USERS"
ALTER PLUGGABLE DATABASE FREEPDB1 SAVE STATE
Completed: ALTER PLUGGABLE DATABASE FREEPDB1 SAVE STATE
2024-09-22T09:50:08.994379+00:00
ALTER SYSTEM SET local_listener='' SCOPE=BOTH;
ALTER PLUGGABLE DATABASE FREEPDB1 SAVE STATE
Completed: ALTER PLUGGABLE DATABASE FREEPDB1 SAVE STATE
2024-09-22T09:50:51.444090+00:00
TABLE SYS.WRP$_REPORTS: ADDED AUTOLIST FRAGMENT SYS_P362 (2) VALUES (( 1452402374, TO_DATE(' 2024-09-16 00:00:00', 'syyyy-mm-dd hh24:mi:ss', 'nls_calendar=gregorian') ))
TABLE SYS.WRP$_REPORTS_DETAILS: ADDED AUTOLIST FRAGMENT SYS_P363 (2) VALUES (( 1452402374, TO_DATE(' 2024-09-16 00:00:00', 'syyyy-mm-dd hh24:mi:ss', 'nls_calendar=gregorian') ))
TABLE SYS.WRP$_REPORTS_TIME_BANDS: ADDED AUTOLIST FRAGMENT SYS_P366 (2) VALUES (( 1452402374, TO_DATE(' 2024-09-16 00:00:00', 'syyyy-mm-dd hh24:mi:ss', 'nls_calendar=gregorian') ))

Based on a ChatGPT suggestion, I just discovered that when not using the deprecated legacy builder instead of buildkit the listener actually starts during the build but unfortunately when then using the new image it still fails and it is not possible to connect to the database when using the listener.

I used the following command line to start the build:

docker build --file=${DOCKERFILE} --progress=plain --rm --tag=${TARGET_IMAGE} --build-arg BASE_IMAGE=${BASE_IMAGE} --no-cache --ulimit nofile=1024:65536 --ulimit nproc=2047:16384 --ulimit stack=10485760:33554432 --ulimit memlock=3221225472 --memory="5g" --memory-swap="16g" .
DEPRECATED: The legacy builder is deprecated and will be removed in a future release.
            BuildKit is currently disabled; enable it by removing the DOCKER_BUILDKIT=0 environment-variable.

and this allows to to actually perform the build.

Unfortunately when running the image, it is not possible to connect using the listener as it still seems to be misconfigured.
Starting the image using docker run -it --name test -p 1521:1521 -p 5500:5500 -p 2484:2484 --ulimit nofile=1024:65536 --ulimit nproc=2047:16384 --ulimit stack=10485760:33554432 --ulimit memlock=3221225472 -e ORACLE_PWD=manager oracle/database_extended:23.5.0-free now shows:

Oracle Database instance FREE started.

Failed to start Oracle Net Listener using /opt/oracle/product/23ai/dbhomeFree/bin/tnslsnr and Oracle Database using /opt/oracle/product/23ai/dbhomeFree/bin/sqlplus.
+ '[' -n manager ']'
+ '[' -e /opt/oracle/oradata/FREE/.prebuiltdb ']'
+ /opt/oracle/setPassword.sh manager
The Oracle base remains unchanged with value /opt/oracle

SQL*Plus: Release 23.0.0.0.0 - for Oracle Cloud and Engineered Systems on Mon Sep 23 05:33:36 2024
Version 23.5.0.24.07

Copyright (c) 1982, 2024, Oracle.  All rights reserved.


Connected to:
Oracle Database 23ai Free Release 23.0.0.0.0 - Develop, Learn, and Run for Free
Version 23.5.0.24.07

SQL>
User altered.

SQL>
User altered.

SQL>
Session altered.

SQL>
User altered.

SQL> Disconnected from Oracle Database 23ai Free Release 23.0.0.0.0 - Develop, Learn, and Run for Free
Version 23.5.0.24.07
+ for i in 1 2 4 8
+ /opt/oracle/checkDBStatus.sh
The Oracle base remains unchanged with value /opt/oracle
+ status=0
+ '[' 0 -eq 5 ']'
+ break
+ '[' 0 -eq 0 ']'
+ echo '#########################'
#########################
+ echo 'DATABASE IS READY TO USE!'
DATABASE IS READY TO USE!
+ echo '#########################'
#########################

Using sqlplus sys/manager as sysdba in the container works:

sh-4.4$ sqlplus sys/manager as sysdba
SQL*Plus: Release 23.0.0.0.0 - for Oracle Cloud and Engineered Systems on Mon Sep 23 05:36:02 2024
Version 23.5.0.24.07
Copyright (c) 1982, 2024, Oracle.  All rights reserved.
Connected to:
Oracle Database 23ai Free Release 23.0.0.0.0 - Develop, Learn, and Run for Free
Version 23.5.0.24.07
SQL> 

Using sqlplus sys/manager@localhost:1521/FREE as sysdba does not:

sh-4.4$ sqlplus sys/manager@localhost:1521/FREE as sysdba
SQL*Plus: Release 23.0.0.0.0 - for Oracle Cloud and Engineered Systems on Mon Sep 23 05:37:49 2024
Version 23.5.0.24.07
Copyright (c) 1982, 2024, Oracle.  All rights reserved.
ERROR:
ORA-12541: Cannot connect. No listener at host 127.0.0.1 port 1521.
Help: https://docs.oracle.com/error-help/db/ora-12541/
Enter user-name: 
@doberkofler doberkofler changed the title [OracleDatabase/23.5.0] Unable to build a new extended image based on oracle/database:23.5.0-free [OracleDatabase/23.5.0] Unable to build a new extended image based on oracle/database:23.5.0-free because listener does not work Sep 26, 2024
@felipecrs
Copy link
Contributor

felipecrs commented Oct 7, 2024

Some additional logs:

0.348 + /opt/oracle/runOracle.sh --nowait
0.424 Specify a password to be used for database accounts. Oracle recommends that the password entered should be at least 8 characters in length, contain at least 1 uppercase character, 1 lower case character and 1 digit [0-9]. Note that the same password will be used for SYS, SYSTEM and PDBADMIN accounts:
0.427 Confirm the password:
0.429 Configuring Oracle Listener.
1.649 Listener configuration failed. Check log '/opt/oracle/cfgtoollogs/netca/netca_configure_out.log' for more details.
1.651 + cat /opt/oracle/cfgtoollogs/netca/netca_configure_out.log
1.651 Netca configuration log
1.651 
1.651 Parsing command line arguments:
1.651     Parameter "orahome" = /opt/oracle/product/23ai/dbhomeFree
1.651     Parameter "instype" = typical
1.651     Parameter "inscomp" = client,oraclenet,javavm,server,ano
1.651     Parameter "insprtcl" = tcp
1.651     Parameter "cfg" = local
1.651     Parameter "authadp" = NO_VALUE
1.651     Parameter "responsefile" = /opt/oracle/product/23ai/dbhomeFree/network/install/netca_typ.rsp
1.651     Parameter "silent" = true
1.651     Parameter "orahnam" = OraHomeFree
1.651     Parameter "listenerparameters" = DEFAULT_SERVICE=FREE
1.651     Parameter "lisport" = 1521
1.651 Done parsing command line arguments.
1.651 Oracle Net Services Configuration:
1.651 Profile configuration complete.
1.651 Listener is using the port provided in the command line:1521
1.651 Oracle Net Listener Startup:
1.651 No valid IP Address returned for the host buildkitsandbox.
1.651 Check the trace file for details: /opt/oracle/cfgtoollogs/netca/trace_OraDBHome23aiFree-2410072PM2300.log
1.651 Oracle Net Services configuration failed.  The exit code is 1
Click here to show `/opt/oracle/cfgtoollogs/netca/trace_OraDBHome23aiFree-2410072PM2300.log`
1.652 + cat /opt/oracle/cfgtoollogs/netca/trace_OraDBHome23aiFree-2410072PM2300.log
1.652 [main] [ 2024-10-07 14:23:00.334 UTC ] [NetCALogger.initTrace:203]  Oracle Net Configuration Assistant: 2410072PM2300
1.652 [main] [ 2024-10-07 14:23:00.343 UTC ] [NetCALogger.initTrace:204]  Oracle Home: /opt/oracle/product/23ai/dbhomeFree
1.652 [main] [ 2024-10-07 14:23:00.343 UTC ] [NetCALogger.initTrace:205]  Oracle Base: /opt/oracle
1.652 [main] [ 2024-10-07 14:23:00.344 UTC ] [NetCALogger.initTrace:206]  Oracle Home Name: OraDBHome23aiFree
1.652 [main] [ 2024-10-07 14:23:00.401 UTC ] [InventoryUtil.getOUIInvSession:354]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS
1.652 [main] [ 2024-10-07 14:23:00.401 UTC ] [HAUtils.<init>:352]  oui location /opt/oracle/oraInventory/ContentsXML
1.652 [main] [ 2024-10-07 14:23:00.404 UTC ] [InventoryUtil.getOUIInvSession:354]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS
1.652 [main] [ 2024-10-07 14:23:00.404 UTC ] [OracleHome.isClientHome:2149]  Homeinfo /opt/oracle/product/23ai/dbhomeFree,1
1.652 [main] [ 2024-10-07 14:23:00.664 UTC ] [HAUtils.<init>:385]  isClientHome: false
1.652 [main] [ 2024-10-07 14:23:00.672 UTC ] [Version.isPre:826]  version to be checked 23.0.0.0.0 major version to check against 10
1.652 [main] [ 2024-10-07 14:23:00.672 UTC ] [Version.isPre:837]  isPre.java: Returning FALSE
1.652 [main] [ 2024-10-07 14:23:00.674 UTC ] [OCR.loadLibrary:347]  
1.652  Inside constructor of OCR
1.652 [main] [ 2024-10-07 14:23:00.674 UTC ] [OCR.loadLibrary:355]  Going to load the ocr library
1.652 [main] [ 2024-10-07 14:23:00.674 UTC ] [Library.load:209]  Loading: srvmocr, isOracleLib: true
1.652 [main] [ 2024-10-07 14:23:00.675 UTC ] [Library.load:232]  oracleHome=/opt/oracle/product/23ai/dbhomeFree, path=/opt/oracle/product/23ai/dbhomeFree/lib:/opt/oracle/product/23ai/dbhomeFree/network/lib::/usr/java/packages/lib:/usr/lib64:/lib64:/lib:/usr/lib,installerLib=/opt/oracle/product/23ai/dbhomeFree/oui/lib/linux64
1.652 [main] [ 2024-10-07 14:23:00.678 UTC ] [sPlatform.isHybrid:66]  osName=Linux osArch=amd64 JVM=64 rc=false
1.652 [main] [ 2024-10-07 14:23:00.679 UTC ] [Library.load:263]  oracleLibFile /opt/oracle/product/23ai/dbhomeFree/lib/libsrvmocr.so
1.652 [main] [ 2024-10-07 14:23:00.679 UTC ] [Library.load:264]  installLibFile /opt/oracle/product/23ai/dbhomeFree/oui/lib/linux64/libsrvmocr.so
1.652 [main] [ 2024-10-07 14:23:00.679 UTC ] [Library.load:301]  Loading  library srvmocr from path=
1.652 /opt/oracle/product/23ai/dbhomeFree/lib:/opt/oracle/product/23ai/dbhomeFree/network/lib::/usr/java/packages/lib:/usr/lib64:/lib64:/lib:/usr/lib
1.652 [main] [ 2024-10-07 14:23:00.681 UTC ] [Library.load:340]  Loaded library srvmocr from path=/opt/oracle/product/23ai/dbhomeFree/lib:/opt/oracle/product/23ai/dbhomeFree/network/lib::/usr/java/packages/lib:/usr/lib64:/lib64:/lib:/usr/lib
1.652 [main] [ 2024-10-07 14:23:00.681 UTC ] [OCR.loadLibrary:357]  loaded ocr libraries
1.652 [main] [ 2024-10-07 14:23:00.682 UTC ] [SRVMContext.init:224]  Performing SRVM Context init. Init Counter=1
1.652 [main] [ 2024-10-07 14:23:00.682 UTC ] [Library.load:209]  Loading: srvmhas, isOracleLib: true
1.652 [main] [ 2024-10-07 14:23:00.683 UTC ] [Library.load:232]  oracleHome=/opt/oracle/product/23ai/dbhomeFree, path=/opt/oracle/product/23ai/dbhomeFree/lib:/opt/oracle/product/23ai/dbhomeFree/network/lib::/usr/java/packages/lib:/usr/lib64:/lib64:/lib:/usr/lib,installerLib=/opt/oracle/product/23ai/dbhomeFree/oui/lib/linux64
1.652 [main] [ 2024-10-07 14:23:00.683 UTC ] [sPlatform.isHybrid:66]  osName=Linux osArch=amd64 JVM=64 rc=false
1.652 [main] [ 2024-10-07 14:23:00.683 UTC ] [Library.load:263]  oracleLibFile /opt/oracle/product/23ai/dbhomeFree/lib/libsrvmhas.so
1.652 [main] [ 2024-10-07 14:23:00.683 UTC ] [Library.load:264]  installLibFile /opt/oracle/product/23ai/dbhomeFree/oui/lib/linux64/libsrvmhas.so
1.652 [main] [ 2024-10-07 14:23:00.683 UTC ] [Library.load:301]  Loading  library srvmhas from path=
1.652 /opt/oracle/product/23ai/dbhomeFree/lib:/opt/oracle/product/23ai/dbhomeFree/network/lib::/usr/java/packages/lib:/usr/lib64:/lib64:/lib:/usr/lib
1.652 [main] [ 2024-10-07 14:23:00.684 UTC ] [Library.load:340]  Loaded library srvmhas from path=/opt/oracle/product/23ai/dbhomeFree/lib:/opt/oracle/product/23ai/dbhomeFree/network/lib::/usr/java/packages/lib:/usr/lib64:/lib64:/lib:/usr/lib
1.652 [main] [ 2024-10-07 14:23:00.684 UTC ] [Version.isPre:826]  version to be checked 23.0.0.0.0 major version to check against 10
1.652 [main] [ 2024-10-07 14:23:00.684 UTC ] [Version.isPre:837]  isPre.java: Returning FALSE
1.652 [main] [ 2024-10-07 14:23:00.684 UTC ] [OCR.loadLibrary:347]  
1.652  Inside constructor of OCR
1.652 [main] [ 2024-10-07 14:23:00.684 UTC ] [SRVMContext.init:224]  Performing SRVM Context init. Init Counter=2
1.652 [main] [ 2024-10-07 14:23:00.684 UTC ] [OCR.isCluster:1094]  Calling OCRNative for isCluster()
1.652 [main] [ 2024-10-07 14:23:00.686 UTC ] [OCRNative.Native]  JNI: clsugetconf retValue = 0
1.652 [main] [ 2024-10-07 14:23:00.686 UTC ] [OCR.isCluster:1098]  OCR Result status = true
1.652 [main] [ 2024-10-07 14:23:00.687 UTC ] [OCR.isCluster:1110]  Bolean result = false
1.652 [main] [ 2024-10-07 14:23:00.687 UTC ] [SRVMContext.init:235]  No CRS configuration found
1.652 [main] [ 2024-10-07 14:23:00.687 UTC ] [OCR.isCluster:1094]  Calling OCRNative for isCluster()
1.652 [main] [ 2024-10-07 14:23:00.687 UTC ] [OCRNative.Native]  JNI: clsugetconf retValue = 0
1.652 [main] [ 2024-10-07 14:23:00.687 UTC ] [OCR.isCluster:1098]  OCR Result status = true
1.652 [main] [ 2024-10-07 14:23:00.687 UTC ] [OCR.isCluster:1110]  Bolean result = false
1.652 [main] [ 2024-10-07 14:23:00.688 UTC ] [CmdlineArgs.<init>:157]  Initializing variables array...
1.652 [main] [ 2024-10-07 14:23:00.689 UTC ] [CmdlineArgs.<init>:163]  Initializing NetProperties...
1.652 [main] [ 2024-10-07 14:23:00.692 UTC ] [CmdlineArgs.initArgs:180]  Initializing command line arguments...
1.652 [main] [ 2024-10-07 14:23:00.692 UTC ] [CmdlineArgs.parseLogParam:317]  Parsing /log argument...
1.652 [main] [ 2024-10-07 14:23:00.694 UTC ] [CmdlineArgs.parseLogParam:372]  Returning Log File = null
1.652 [main] [ 2024-10-07 14:23:00.695 UTC ] [CmdlineArgs.setOraArgs:263]  Setting Oracle arguments...
1.652 [main] [ 2024-10-07 14:23:00.696 UTC ] [CmdlineArgs.setOraArgs:267]  Passed Oracle Home = /opt/oracle/product/23ai/dbhomeFree
1.652 [main] [ 2024-10-07 14:23:00.696 UTC ] [CmdlineArgs.setOraArgs:270]  Read Oracle Home = /opt/oracle/product/23ai/dbhomeFree
1.652 [main] [ 2024-10-07 14:23:00.696 UTC ] [CmdlineArgs.setOraArgs:273]  Read Oracle Home ID = 
1.652 [main] [ 2024-10-07 14:23:00.696 UTC ] [CmdlineArgs.setOraArgs:276]  Read Oracle Home Name = null
1.652 [main] [ 2024-10-07 14:23:00.696 UTC ] [CmdlineArgs.compareOraHome:242]  Comparing Oracle Homes ...
1.652 [main] [ 2024-10-07 14:23:00.696 UTC ] [CmdlineArgs.compareOraHome:243]  Oracle Home 1 = /opt/oracle/product/23ai/dbhomeFree
1.652 [main] [ 2024-10-07 14:23:00.696 UTC ] [CmdlineArgs.compareOraHome:244]  Oracle Home 2 = /opt/oracle/product/23ai/dbhomeFree
1.652 [main] [ 2024-10-07 14:23:00.697 UTC ] [CmdlineArgs.compareOraHome:251]  Returning true
1.652 [main] [ 2024-10-07 14:23:00.700 UTC ] [HASContext.<init>:130]  moduleInit = 7
1.652 [main] [ 2024-10-07 14:23:00.700 UTC ] [SRVMContext.init:224]  Performing SRVM Context init. Init Counter=1
1.652 [main] [ 2024-10-07 14:23:00.700 UTC ] [SRVMContext.init:235]  No CRS configuration found
1.652 [main] [ 2024-10-07 14:23:00.700 UTC ] [HASContext.<init>:144]  Initialised SRVMContext
1.652 [main] [ 2024-10-07 14:23:00.701 UTC ] [HASContextNative.Native]  prsr_trace: no lsf ctx, line=Native: allocHASContext
1.652 
1.652 [main] [ 2024-10-07 14:23:00.701 UTC ] [HASContextNative.Native]  allocHASContext: module_init = 7
1.652 [main] [ 2024-10-07 14:23:00.701 UTC ] [HASContextNative.Native]  
1.652  allocHASContext: META context [1]
1.652 [main] [ 2024-10-07 14:23:00.701 UTC ] [HASContextNative.Native]  
1.652  allocHASContext: LSF context [1]
1.652 [main] [ 2024-10-07 14:23:00.701 UTC ] [HASContextNative.Native]  prsr_trace: Native: prsr_initSCLS
1.652 
1.652 [main] [ 2024-10-07 14:23:00.701 UTC ] [HASContextNative.Native]  
1.652  allocHASContext: SCLS context [1]
1.652 [main] [ 2024-10-07 14:23:00.701 UTC ] [HASContextNative.Native]  
1.652  allocHASContext: retval [1]
1.652 [main] [ 2024-10-07 14:23:00.702 UTC ] [Util.<init>:108]  Util Instance created.
1.652 [main] [ 2024-10-07 14:23:00.702 UTC ] [UtilNative.Native]  prsr_trace: Native: getOracleUser
1.652 
1.652 [main] [ 2024-10-07 14:23:00.702 UTC ] [UtilNative.Native]  prsr_trace: Native: prsr_getOracleUserID
1.652 
1.652 [main] [ 2024-10-07 14:23:00.702 UTC ] [UtilNative.Native]  prsr_trace:   OH=/opt/oracle/product/23ai/dbhomeFree SID=NULLSID
1.652 
1.652 [main] [ 2024-10-07 14:23:00.703 UTC ] [UtilNative.Native]  prsr_trace: scls returned=0
1.652 
1.652 [main] [ 2024-10-07 14:23:00.703 UTC ] [UtilNative.Native]  prsr_trace: Native: prsr_getUserName
1.652 
1.652 [main] [ 2024-10-07 14:23:00.703 UTC ] [UtilNative.Native]  prsr_trace: Native: prsr_allocUserName
1.652 
1.652 [main] [ 2024-10-07 14:23:00.703 UTC ] [UtilNative.Native]  prsr_trace: get_user_name_by_id ret=0, insz=32, sz=6, name=oracle
1.652 
1.652 [main] [ 2024-10-07 14:23:00.704 UTC ] [UtilNative.Native]  prsr_trace: Native: getCurrentUser
1.652 
1.652 [main] [ 2024-10-07 14:23:00.704 UTC ] [UtilNative.Native]  prsr_trace: Native: prsr_getUserName
1.652 
1.652 [main] [ 2024-10-07 14:23:00.704 UTC ] [UtilNative.Native]  prsr_trace: Native: prsr_allocUserName
1.652 
1.652 [main] [ 2024-10-07 14:23:00.704 UTC ] [UtilNative.Native]  prsr_trace: get_user_name_by_id ret=0, insz=32, sz=6, name=oracle
1.652 
1.652 [main] [ 2024-10-07 14:23:00.704 UTC ] [UtilNative.Native]  prsr_trace: got uname=oracle
1.652 
1.652 [main] [ 2024-10-07 14:23:00.706 UTC ] [InventoryUtil.getOUIInvSession:354]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS
1.652 [main] [ 2024-10-07 14:23:00.707 UTC ] [OracleHome.isClientHome:2149]  Homeinfo /opt/oracle/product/23ai/dbhomeFree,1
1.652 [main] [ 2024-10-07 14:23:00.796 UTC ] [NetCA.<init>:4193]  isClientHome: false
1.652 [main] [ 2024-10-07 14:23:00.799 UTC ] [NetCA.checkIfBigClusterAndHubNode:6865]  Not a cluster environment: exiting BigCluster Check
1.652 [main] [ 2024-10-07 14:23:00.799 UTC ] [NetCA.configureOPS:4914]  Cluster mode is OFF
1.652 [main] [ 2024-10-07 14:23:00.800 UTC ] [OPSUtil.getHomeVersion:446]  Inside getHomeVersion...
1.652 [main] [ 2024-10-07 14:23:00.800 UTC ] [OPSUtil.getHomeVersion:447]  oracleHome = /opt/oracle/product/23ai/dbhomeFree
1.652 [main] [ 2024-10-07 14:23:00.800 UTC ] [OPSUtil.getHomeVersion:451]  oracle.sysman.assistants.util.OracleHome Object = /opt/oracle/product/23ai/dbhomeFree
1.652 [main] [ 2024-10-07 14:23:00.801 UTC ] [OracleHome.getVersion:1264]  OracleHome.getVersion called.  Current Version: null
1.652 [main] [ 2024-10-07 14:23:00.802 UTC ] [InventoryUtil.getOUIInvSession:354]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS
1.652 [main] [ 2024-10-07 14:23:00.802 UTC ] [OracleHome.getVersion:1283]  Homeinfo /opt/oracle/product/23ai/dbhomeFree,1
1.652 [main] [ 2024-10-07 14:23:00.860 UTC ] [OracleHome.getVersion:1311]  OracleHome.server.getVersion Version: 23.0.0.0.0
1.652 [main] [ 2024-10-07 14:23:00.861 UTC ] [OracleHome.getVersion:1332]  Current Version From Inventory: 23.0.0.0.0
1.652 [main] [ 2024-10-07 14:23:00.861 UTC ] [OPSUtil.getHomeVersion:452]  returning oracleHome Version = 23.0.0.0.0
1.652 [main] [ 2024-10-07 14:23:00.861 UTC ] [OracleHome.getVersion:1264]  OracleHome.getVersion called.  Current Version: 23.0.0.0.0
1.652 [main] [ 2024-10-07 14:23:00.861 UTC ] [OracleHome.getVersion:1332]  Current Version From Inventory: 23.0.0.0.0
1.652 [main] [ 2024-10-07 14:23:00.881 UTC ] [NetCA.<init>:4232]  Admin location is: /opt/oracle/product/23ai/dbhomeFree/network/admin/
1.652 [main] [ 2024-10-07 14:23:00.890 UTC ] [ConfigureProfile.setProfileParam:140]  Setting NAMES.DIRECTORY_PATH: (TNSNAMES, EZCONNECT)
1.652 [main] [ 2024-10-07 14:23:00.892 UTC ] [Version.isPre:826]  version to be checked 23.0.0.0.0 major version to check against 10
1.652 [main] [ 2024-10-07 14:23:00.892 UTC ] [Version.isPre:837]  isPre.java: Returning FALSE
1.652 [main] [ 2024-10-07 14:23:00.893 UTC ] [Version.isPre:826]  version to be checked 23.0.0.0.0 major version to check against 10
1.652 [main] [ 2024-10-07 14:23:00.894 UTC ] [Version.isPre:837]  isPre.java: Returning FALSE
1.652 [main] [ 2024-10-07 14:23:00.894 UTC ] [Version.isPre:826]  version to be checked 23.0.0.0.0 major version to check against 11
1.652 [main] [ 2024-10-07 14:23:00.894 UTC ] [Version.isPre:837]  isPre.java: Returning FALSE
1.652 [main] [ 2024-10-07 14:23:00.896 UTC ] [Version.isPre:858]  version to be checked 23.0.0.0.0 major version to check against 11 minor version to check against 2
1.652 [main] [ 2024-10-07 14:23:00.896 UTC ] [Version.isPre:867]  isPre: Returning FALSE for major version check
1.652 [main] [ 2024-10-07 14:23:00.896 UTC ] [UnixSystem.getOLRConfigLocation:3735]  OLR location/etc/oracle/olr.loc
1.652 [main] [ 2024-10-07 14:23:00.896 UTC ] [UnixSystem.isHAConfigured:4018]  olrFileName = /etc/oracle/olr.loc
1.652 [main] [ 2024-10-07 14:23:00.897 UTC ] [NetCA.checkHAConfiguration:5165]  HA Server is NOT configured.
1.652 [main] [ 2024-10-07 14:23:00.898 UTC ] [NetCAResponseFile.<init>:75]  Response file initialized: /opt/oracle/product/23ai/dbhomeFree/network/install/netca_typ.rsp
1.652 [main] [ 2024-10-07 14:23:00.898 UTC ] [NetCAResponseFile.getInstalledComponents:114]  Installed components from response file: server, net8, javavm
1.652 [main] [ 2024-10-07 14:23:00.899 UTC ] [NetCAResponseFile.getVirtualHost:171]  Virtual Host from response file: null
1.652 [main] [ 2024-10-07 14:23:00.899 UTC ] [SilentConfigure.performSilentConfigure:201]  Typical profile configuration.
1.652 [main] [ 2024-10-07 14:23:00.899 UTC ] [ConfigureProfile.setProfileParam:140]  Setting NAMES.DIRECTORY_PATH: (TNSNAMES, EZCONNECT)
1.652 [main] [ 2024-10-07 14:23:00.900 UTC ] [SilentConfigure.performSilentConfigure:209]  Typical listener configuration.
1.652 [main] [ 2024-10-07 14:23:00.901 UTC ] [ClusterUtils.isContainerEnvironment:2038]  Entering method isContainerEnvironment
1.652 [main] [ 2024-10-07 14:23:00.901 UTC ] [OracleHome.isVersion23OrHigher:1518]  isVersion23OrHigher method begin: 
1.652 [main] [ 2024-10-07 14:23:00.901 UTC ] [OracleHome.getVersion:1264]  OracleHome.getVersion called.  Current Version: null
1.652 [main] [ 2024-10-07 14:23:00.903 UTC ] [InventoryUtil.getOUIInvSession:354]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS
1.652 [main] [ 2024-10-07 14:23:00.903 UTC ] [OracleHome.getVersion:1283]  Homeinfo /opt/oracle/product/23ai/dbhomeFree,1
1.652 [main] [ 2024-10-07 14:23:00.955 UTC ] [OracleHome.getVersion:1311]  OracleHome.server.getVersion Version: 23.0.0.0.0
1.652 [main] [ 2024-10-07 14:23:00.955 UTC ] [OracleHome.getVersion:1332]  Current Version From Inventory: 23.0.0.0.0
1.652 [main] [ 2024-10-07 14:23:00.955 UTC ] [OracleHome.isVersion23OrHigher:1528]  isVersion23OrHigher:true
1.652 [main] [ 2024-10-07 14:23:00.957 UTC ] [UnixSystem.getSystemdContainer:6483]  unixcmd = /usr/bin/systemd-detect-virt --container
1.652 [main] [ 2024-10-07 14:23:00.968 UTC ] [RuntimeExec.runCommand:304]  runCommand: Waiting for the process
1.652 [Thread-1] [ 2024-10-07 14:23:00.968 UTC ] [StreamReader.run:62]  In StreamReader.run 
1.652 [Thread-0] [ 2024-10-07 14:23:00.968 UTC ] [StreamReader.run:62]  In StreamReader.run 
1.652 [main] [ 2024-10-07 14:23:00.969 UTC ] [RuntimeExec.runCommand:306]  runCommand: process returns 0
1.652 [Thread-0] [ 2024-10-07 14:23:00.969 UTC ] [StreamReader.run:66]  OUTPUT>container-other
1.652 [main] [ 2024-10-07 14:23:00.969 UTC ] [RuntimeExec.runCommand:333]  RunTimeExec: error>
1.652 [main] [ 2024-10-07 14:23:00.969 UTC ] [RuntimeExec.runCommand:359]  Returning from RunTimeExec.runCommand
1.652 [main] [ 2024-10-07 14:23:00.970 UTC ] [UnixSystem.getSystemdContainer:6487]  After execution: ret = 0, out = container-other
1.652 [main] [ 2024-10-07 14:23:00.971 UTC ] [UnixSystem.getSystemdContainer:6483]  unixcmd = /usr/bin/systemd-detect-virt --container
1.652 [main] [ 2024-10-07 14:23:00.971 UTC ] [RuntimeExec.runCommand:304]  runCommand: Waiting for the process
1.652 [Thread-3] [ 2024-10-07 14:23:00.971 UTC ] [StreamReader.run:62]  In StreamReader.run 
1.652 [Thread-2] [ 2024-10-07 14:23:00.971 UTC ] [StreamReader.run:62]  In StreamReader.run 
1.652 [Thread-2] [ 2024-10-07 14:23:00.977 UTC ] [StreamReader.run:66]  OUTPUT>container-other
1.652 [main] [ 2024-10-07 14:23:00.977 UTC ] [RuntimeExec.runCommand:306]  runCommand: process returns 0
1.652 [main] [ 2024-10-07 14:23:00.977 UTC ] [RuntimeExec.runCommand:333]  RunTimeExec: error>
1.652 [main] [ 2024-10-07 14:23:00.978 UTC ] [RuntimeExec.runCommand:359]  Returning from RunTimeExec.runCommand
1.652 [main] [ 2024-10-07 14:23:00.978 UTC ] [UnixSystem.getSystemdContainer:6487]  After execution: ret = 0, out = container-other
1.652 [main] [ 2024-10-07 14:23:00.978 UTC ] [ClusterwareInfo.isKubernetesContainer:8156]  On unix system, isKubernetesContainer : false
1.652 [main] [ 2024-10-07 14:23:00.978 UTC ] [ClusterUtils.isContainerEnvironment:2046]  isContainerEnvironment:false
1.652 [main] [ 2024-10-07 14:23:00.980 UTC ] [ConfigureListener.isHASConfigured:1770]  Calling SRVM api to check if Oracle Restart is configured ...
1.652 [main] [ 2024-10-07 14:23:00.980 UTC ] [Version.isPre:826]  version to be checked 23.0.0.0.0 major version to check against 10
1.652 [main] [ 2024-10-07 14:23:00.980 UTC ] [Version.isPre:837]  isPre.java: Returning FALSE
1.652 [main] [ 2024-10-07 14:23:00.980 UTC ] [Version.isPre:826]  version to be checked 23.0.0.0.0 major version to check against 10
1.652 [main] [ 2024-10-07 14:23:00.980 UTC ] [Version.isPre:837]  isPre.java: Returning FALSE
1.652 [main] [ 2024-10-07 14:23:00.980 UTC ] [Version.isPre:826]  version to be checked 23.0.0.0.0 major version to check against 11
1.652 [main] [ 2024-10-07 14:23:00.980 UTC ] [Version.isPre:837]  isPre.java: Returning FALSE
1.652 [main] [ 2024-10-07 14:23:00.980 UTC ] [Version.isPre:858]  version to be checked 23.0.0.0.0 major version to check against 11 minor version to check against 2
1.652 [main] [ 2024-10-07 14:23:00.981 UTC ] [Version.isPre:867]  isPre: Returning FALSE for major version check
1.652 [main] [ 2024-10-07 14:23:00.981 UTC ] [UnixSystem.getOLRConfigLocation:3735]  OLR location/etc/oracle/olr.loc
1.652 [main] [ 2024-10-07 14:23:00.981 UTC ] [UnixSystem.isHAConfigured:4018]  olrFileName = /etc/oracle/olr.loc
1.652 [main] [ 2024-10-07 14:23:00.981 UTC ] [ConfigureListener.isHASConfigured:1780]  Is Oracle Restart configured: false
1.652 [main] [ 2024-10-07 14:23:00.981 UTC ] [ConfigureListener.isHASRunning:1809]  Is Oracle Restart running: false
1.652 [main] [ 2024-10-07 14:23:00.981 UTC ] [ConfigureListener.listenerExists:450]  Is listener "LISTENER" already exists: false
1.652 [main] [ 2024-10-07 14:23:00.982 UTC ] [ConfigureListener.validateEndPoint:1190]  Validating end-point: TCP:1521
1.652 [main] [ 2024-10-07 14:23:00.982 UTC ] [ClusterUtils.isContainerEnvironment:2038]  Entering method isContainerEnvironment
1.652 [main] [ 2024-10-07 14:23:00.982 UTC ] [OracleHome.isVersion23OrHigher:1518]  isVersion23OrHigher method begin: 
1.652 [main] [ 2024-10-07 14:23:00.982 UTC ] [OracleHome.getVersion:1264]  OracleHome.getVersion called.  Current Version: null
1.652 [main] [ 2024-10-07 14:23:00.983 UTC ] [InventoryUtil.getOUIInvSession:354]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS
1.652 [main] [ 2024-10-07 14:23:00.983 UTC ] [OracleHome.getVersion:1283]  Homeinfo /opt/oracle/product/23ai/dbhomeFree,1
1.652 [main] [ 2024-10-07 14:23:01.029 UTC ] [OracleHome.getVersion:1311]  OracleHome.server.getVersion Version: 23.0.0.0.0
1.652 [main] [ 2024-10-07 14:23:01.029 UTC ] [OracleHome.getVersion:1332]  Current Version From Inventory: 23.0.0.0.0
1.652 [main] [ 2024-10-07 14:23:01.029 UTC ] [OracleHome.isVersion23OrHigher:1528]  isVersion23OrHigher:true
1.652 [main] [ 2024-10-07 14:23:01.029 UTC ] [UnixSystem.getSystemdContainer:6483]  unixcmd = /usr/bin/systemd-detect-virt --container
1.652 [main] [ 2024-10-07 14:23:01.030 UTC ] [RuntimeExec.runCommand:304]  runCommand: Waiting for the process
1.652 [Thread-5] [ 2024-10-07 14:23:01.030 UTC ] [StreamReader.run:62]  In StreamReader.run 
1.652 [Thread-4] [ 2024-10-07 14:23:01.030 UTC ] [StreamReader.run:62]  In StreamReader.run 
1.652 [Thread-4] [ 2024-10-07 14:23:01.036 UTC ] [StreamReader.run:66]  OUTPUT>container-other
1.652 [main] [ 2024-10-07 14:23:01.036 UTC ] [RuntimeExec.runCommand:306]  runCommand: process returns 0
1.652 [main] [ 2024-10-07 14:23:01.037 UTC ] [RuntimeExec.runCommand:333]  RunTimeExec: error>
1.652 [main] [ 2024-10-07 14:23:01.037 UTC ] [RuntimeExec.runCommand:359]  Returning from RunTimeExec.runCommand
1.652 [main] [ 2024-10-07 14:23:01.037 UTC ] [UnixSystem.getSystemdContainer:6487]  After execution: ret = 0, out = container-other
1.652 [main] [ 2024-10-07 14:23:01.038 UTC ] [ClusterwareInfo.isKubernetesContainer:8144]  isKubernetesContainer : false
1.652 [main] [ 2024-10-07 14:23:01.038 UTC ] [ClusterUtils.isContainerEnvironment:2046]  isContainerEnvironment:false
1.652 [main] [ 2024-10-07 14:23:01.038 UTC ] [ConfigureListener.validateEndPoint:1209]  Address String : (ADDRESS=(PROTOCOL=TCP)(HOST=localhost)(PORT=1521))
1.652 [main] [ 2024-10-07 14:23:01.043 UTC ] [ConfigureListener.validateEndPoint:1213]  AddressBusy return code : 0
1.652 [main] [ 2024-10-07 14:23:01.043 UTC ] [ConfigureListener.isPortFree:1274]  Checking if port 1521 is free on local machine...
1.652 [main] [ 2024-10-07 14:23:01.043 UTC ] [ConfigureListener.isPortFree:1289]  InetAddress.getByName(127.0.0.1): /127.0.0.1
1.652 [main] [ 2024-10-07 14:23:01.044 UTC ] [ConfigureListener.isPortFree:1291]  Local host IP address: buildkitsandbox/127.0.0.1
1.652 [main] [ 2024-10-07 14:23:01.044 UTC ] [ConfigureListener.isPortFree:1293]  Local host name: buildkitsandbox
1.652 [main] [ 2024-10-07 14:23:01.044 UTC ] [ConfigureListener.isPortFree:1304]  Address buildkitsandbox
1.652 [main] [ 2024-10-07 14:23:01.044 UTC ] [ConfigureListener.isPortFree:1366]  No IP address returned for host. buildkitsandbox
1.652 oracle.net.ca.IllegalEndpointException: No valid IP Address returned for the host buildkitsandbox.
1.652   at oracle.net.ca.ConfigureListener.isPortFree(ConfigureListener.java:1367)
1.652   at oracle.net.ca.ConfigureListener.validatePort(ConfigureListener.java:1256)
1.652   at oracle.net.ca.ConfigureListener.validateEndPoint(ConfigureListener.java:1240)
1.652   at oracle.net.ca.ConfigureListener.typicalConfigure(ConfigureListener.java:287)
1.652   at oracle.net.ca.SilentConfigure.performSilentConfigure(SilentConfigure.java:212)
1.652   at oracle.net.ca.InitialSetup.<init>(NetCA.java:4450)
1.652   at oracle.net.ca.NetCA.main(NetCA.java:491)
1.652 [main] [ 2024-10-07 14:23:01.044 UTC ] [ConfigureListener.typicalConfigure:291]  No valid IP Address returned for the host buildkitsandbox.
1.652 [main] [ 2024-10-07 14:23:01.051 UTC ] [CmdlineArgs.getListenerParameters:1317]  Control parameter loaded: DEFAULT_SERVICE
1.652 [main] [ 2024-10-07 14:23:01.051 UTC ] [ConfigureListener.typicalConfigure:366]  DEFAULT_SERVICE FREE
1.652 [main] [ 2024-10-07 14:23:01.053 UTC ] [ConfigureListener.isPortFree:1274]  Checking if port 1521 is free on local machine...
1.652 [main] [ 2024-10-07 14:23:01.053 UTC ] [ConfigureListener.isPortFree:1289]  InetAddress.getByName(127.0.0.1): /127.0.0.1
1.652 [main] [ 2024-10-07 14:23:01.053 UTC ] [ConfigureListener.isPortFree:1291]  Local host IP address: buildkitsandbox/127.0.0.1
1.652 [main] [ 2024-10-07 14:23:01.053 UTC ] [ConfigureListener.isPortFree:1293]  Local host name: buildkitsandbox
1.652 [main] [ 2024-10-07 14:23:01.053 UTC ] [ConfigureListener.isPortFree:1304]  Address buildkitsandbox
1.652 [main] [ 2024-10-07 14:23:01.053 UTC ] [ConfigureListener.isPortFree:1366]  No IP address returned for host. buildkitsandbox

@felipecrs
Copy link
Contributor

felipecrs commented Oct 7, 2024

I was to bypass the issue by providing:

-o '--add-host buildkitsandbox:172.17.0.2'

To ./buildExtensions.sh.

In my machine, I can obtain such IP address by running:

docker run --rm ubuntu hostname -I

@felipecrs
Copy link
Contributor

felipecrs commented Oct 7, 2024

I can reproduce the exact same issue with Oracle 21.3.0 Express Edition.

After some testing, I am confident this issue started happening after some Docker version. I was previously able to build 21.3.0 XE just fine in the past. I tried building it again at the same git revision it previously worked, and the same issue happened.

Funny that it only happens with 21.3.0 XE and 23.5.0 FREE. All other versions and editions are building just fine.

@doberkofler
Copy link
Author

@felipecrs In my understanding it only happens when using the buildkit builder that is now the default in docker desktop and it only happens in 21.3 because it dynamically determines the hostname.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

2 participants