Skip to content

Instantly share code, notes, and snippets.

@Morganjackson
Created July 21, 2022 03:18
Show Gist options
  • Select an option

  • Save Morganjackson/74b4f6313d620268b53909ba84b6db3c to your computer and use it in GitHub Desktop.

Select an option

Save Morganjackson/74b4f6313d620268b53909ba84b6db3c to your computer and use it in GitHub Desktop.
af-backend-sql-server
2022-07-21T03:13:09.908148173Z Azure SQL Edge will run as non-root by default.
This container is running as user mssql.
Your master database file is owned by mssql.
To learn more visit https://go.microsoft.com/fwlink/?linkid=2140520.
2022/07/21 03:13:09 [launchpadd] INFO: Extensibility Log Header: <timestamp> <process> <sandboxId> <sessionId> <message>
2022/07/21 03:13:09 [launchpadd] WARNING: Failed to load /var/opt/mssql/mssql.conf ini file with error open /var/opt/mssql/mssql.conf: no such file or directory
2022/07/21 03:13:09 [launchpadd] INFO: DataDirectories =  /bin:/etc:/lib:/lib32:/lib64:/sbin:/usr/bin:/usr/include:/usr/lib:/usr/lib32:/usr/lib64:/usr/libexec/gcc:/usr/sbin:/usr/share:/var/lib:/opt/microsoft:/opt/mssql-extensibility:/opt/mssql/mlservices:/opt/mssql/lib/zulu-jre-11:/opt/mssql-tools
2022/07/21 03:13:09 Drop permitted effective capabilities.
2022/07/21 03:13:10 [launchpadd] INFO: Polybase remote hadoop bridge disabled
2022/07/21 03:13:10 [launchpadd] INFO: Launchpadd is connecting to mssql on localhost:1431
2022/07/21 03:13:10 [launchpadd] WARNING: Failed to connect to SQL because: dial tcp 127.0.0.1:1431: connect: connection refused, will reattempt connection.
2022/07/21 03:13:11 [launchpadd] WARNING: Failed to connect to SQL because: dial tcp 127.0.0.1:1431: connect: connection refused, will reattempt connection.
2022/07/21 03:13:12 [launchpadd] WARNING: Failed to connect to SQL because: dial tcp 127.0.0.1:1431: connect: connection refused, will reattempt connection.
2022-07-21 03:13:12.05 Server      Microsoft Azure SQL Edge Developer (RTM) - 15.0.2000.1565 (ARM64) 
Jun 14 2022 00:37:12 
Copyright (C) 2019 Microsoft Corporation
Linux (Ubuntu 18.04.6 LTS aarch64) <ARM64>
2022-07-21 03:13:12.05 Server      UTC adjustment: 0:00
2022-07-21 03:13:12.05 Server      (c) Microsoft Corporation.
2022-07-21 03:13:12.05 Server      All rights reserved.
2022-07-21 03:13:12.06 Server      Server process ID is 32.
2022-07-21 03:13:12.06 Server      Logging SQL Server messages in file '/var/opt/mssql/log/errorlog'.
2022-07-21 03:13:12.06 Server      Registry startup parameters: 
 -d /var/opt/mssql/data/master.mdf
 -l /var/opt/mssql/data/mastlog.ldf
 -e /var/opt/mssql/log/errorlog
2022-07-21 03:13:12.06 Server      SQL Server detected 1 sockets with 5 cores per socket and 5 logical processors per socket, 5 total logical processors; using 4 logical processors based on SQL Server licensing. This is an informational message; no user action is required.
2022-07-21 03:13:12.07 Server      SQL Server is starting at normal priority base (=7). This is an informational message only. No user action is required.
2022-07-21 03:13:12.07 Server      Detected 3147 MB of RAM. This is an informational message; no user action is required.
2022-07-21 03:13:12.07 Server      Using conventional memory in the memory manager.
2022-07-21 03:13:12.08 Server      Page exclusion bitmap is enabled.
2022-07-21 03:13:12.10 Server      Buffer Pool: Allocating 524288 bytes for 445540 hashPages.
2022-07-21 03:13:12.52 Server      Buffer pool extension is already disabled. No action is necessary.
2022/07/21 03:13:13 [launchpadd] WARNING: Failed to connect to SQL because: dial tcp 127.0.0.1:1431: connect: connection refused, will reattempt connection.
2022-07-21 03:13:13.99 Server      Successfully initialized the TLS configuration. Allowed TLS protocol versions are ['1.0 1.1 1.2']. Allowed TLS ciphers are ['ECDHE-ECDSA-AES128-GCM-SHA256:ECDHE-ECDSA-AES256-GCM-SHA384:ECDHE-RSA-AES128-GCM-SHA256:ECDHE-RSA-AES256-GCM-SHA384:ECDHE-ECDSA-AES128-SHA256:ECDHE-ECDSA-AES256-SHA384:ECDHE-ECDSA-AES256-SHA:ECDHE-ECDSA-AES128-SHA:AES256-GCM-SHA384:AES128-GCM-SHA256:AES256-SHA256:AES128-SHA256:AES256-SHA:AES128-SHA:!DHE-RSA-AES256-GCM-SHA384:!DHE-RSA-AES128-GCM-SHA256:!DHE-RSA-AES256-SHA:!DHE-RSA-AES128-SHA'].
2022/07/21 03:13:14 [launchpadd] WARNING: Failed to connect to SQL because: dial tcp 127.0.0.1:1431: connect: connection refused, will reattempt connection.
2022-07-21 03:13:14.02 Server      Query Store settings initialized with enabled = 1, 
2022-07-21 03:13:14.04 Server      Node configuration: node 0: CPU mask: 0x000000000000001f:0 Active CPU mask: 0x000000000000000f:0. This message provides a description of the NUMA configuration for this computer. This is an informational message only. No user action is required.
2022-07-21 03:13:14.05 Server      Using dynamic lock allocation.  Initial allocation of 500 Lock blocks and 1000 Lock Owner blocks per node.  This is an informational message only.  No user action is required.
2022-07-21 03:13:14.06 Server      Database Instant File Initialization: enabled. For security and performance considerations see the topic 'Database Instant File Initialization' in SQL Server Books Online. This is an informational message only. No user action is required.
ForceFlush is enabled for this instance. 
2022-07-21 03:13:14.07 Server      Total Log Writer threads: 1. This is an informational message; no user action is required.
2022-07-21 03:13:14.08 Server      Software Usage Metrics is disabled.
2022-07-21 03:13:14.10 spid7s      [1]. Feature Status: PVS: 0. CTR: 0. ConcurrentPFSUpdate: 1. ConcurrentGAMUpdate: 0. CleanupUnderUserTransaction: 0.
2022-07-21 03:13:14.10 spid7s      Starting up database 'master'.
2022-07-21 03:13:14.16 spid7s      Refresh DEK for DBID: [1] File Id: [1] - Zeroring encryption state
2022-07-21 03:13:14.16 spid7s      The DEK is already set for DBID = [1] file id 1.
2022-07-21 03:13:14.21 spid7s      Refresh DEK for DBID: [1] File Id: [2] - Zeroring encryption state
2022-07-21 03:13:14.21 spid7s      The DEK is already set for DBID = [1] file id 2.
ForceFlush feature is enabled for log durability.
2022-07-21 03:13:14.24 spid7s      27 transactions rolled forward in database 'master' (1:0). This is an informational message only. No user action is required.
2022-07-21 03:13:14.25 spid7s      0 transactions rolled back in database 'master' (1:0). This is an informational message only. No user action is required.
2022-07-21 03:13:14.25 spid7s      Recovery is writing a checkpoint in database 'master' (1). This is an informational message only. No user action is required.
2022-07-21 03:13:14.45 spid7s      SQL Server Audit is starting the audits. This is an informational message. No user action is required.
2022-07-21 03:13:14.45 spid7s      SQL Server Audit has started the audits. This is an informational message. No user action is required.
2022-07-21 03:13:14.49 spid7s      SQL Trace ID 1 was started by login "sa".
2022-07-21 03:13:14.50 spid7s      Server name is 'f97e1e5b517d'. This is an informational message only. No user action is required.
2022-07-21 03:13:14.51 spid7s      [4]. Feature Status: PVS: 0. CTR: 0. ConcurrentPFSUpdate: 1. ConcurrentGAMUpdate: 0. CleanupUnderUserTransaction: 0.
2022-07-21 03:13:14.51 spid11s     [32767]. Feature Status: PVS: 0. CTR: 0. ConcurrentPFSUpdate: 1. ConcurrentGAMUpdate: 0. CleanupUnderUserTransaction: 0.
2022-07-21 03:13:14.52 spid7s      Starting up database 'msdb'.
2022-07-21 03:13:14.52 spid11s     Starting up database 'mssqlsystemresource'.
2022-07-21 03:13:14.53 spid11s     Refresh DEK for DBID: [32767] File Id: [1] - Copying encryption state
2022-07-21 03:13:14.54 spid11s     DBID: [32767] File Header for File Id: [1] was read with state = 0.
2022-07-21 03:13:14.54 spid11s     DBID: [32767] No DEK update for File Id: [1] while existing DEK is null.
2022-07-21 03:13:14.54 spid11s     The resource database build version is 15.00.2000. This is an informational message only. No user action is required.
2022-07-21 03:13:14.55 spid11s     Refresh DEK for DBID: [32767] File Id: [2] - Copying encryption state
2022-07-21 03:13:14.55 spid11s     DBID: [32767] File Header for File Id: [2] was read with state = 0.
2022-07-21 03:13:14.55 spid11s     DBID: [32767] No DEK update for File Id: [2] while existing DEK is null.
2022-07-21 03:13:14.57 spid22s     A self-generated certificate was successfully loaded for encryption.
2022-07-21 03:13:14.57 spid11s     [3]. Feature Status: PVS: 0. CTR: 0. ConcurrentPFSUpdate: 1. ConcurrentGAMUpdate: 0. CleanupUnderUserTransaction: 0.
2022-07-21 03:13:14.58 spid22s     Server is listening on [ 'any' <ipv6> 1433] accept sockets 1.
2022-07-21 03:13:14.58 spid11s     Starting up database 'model'.
2022-07-21 03:13:14.58 spid22s     Server is listening on [ 'any' <ipv4> 1433] accept sockets 1.
2022-07-21 03:13:14.59 spid22s     Dedicated administrator connection support was not started because it is disabled on this edition of SQL Server. If you want to use a dedicated administrator connection, restart SQL Server using the trace flag 7806. This is an informational message only. No user action is required.
2022-07-21 03:13:14.60 spid22s     Server is listening on [ ::1 <ipv6> 1431] accept sockets 1.
2022-07-21 03:13:14.61 spid22s     Server is listening on [ 127.0.0.1 <ipv4> 1431] accept sockets 1.
2022-07-21 03:13:14.61 spid7s      Refresh DEK for DBID: [4] File Id: [1] - Copying encryption state
2022-07-21 03:13:14.61 spid7s      DBID: [4] File Header for File Id: [1] was read with state = 0.
2022-07-21 03:13:14.61 spid7s      DBID: [4] No DEK update for File Id: [1] while existing DEK is null.
2022-07-21 03:13:14.62 spid22s     SQL Server is now ready for client connections. This is an informational message; no user action is required.
2022-07-21 03:13:14.66 spid11s     Refresh DEK for DBID: [3] File Id: [1] - Zeroring encryption state
2022-07-21 03:13:14.66 spid11s     The DEK is already set for DBID = [3] file id 1.
2022-07-21 03:13:14.69 spid7s      Refresh DEK for DBID: [4] File Id: [2] - Copying encryption state
2022-07-21 03:13:14.69 spid7s      DBID: [4] File Header for File Id: [2] was read with state = 0.
2022-07-21 03:13:14.69 spid7s      DBID: [4] No DEK update for File Id: [2] while existing DEK is null.
2022-07-21 03:13:14.70 spid7s      4 transactions rolled forward in database 'msdb' (4:0). This is an informational message only. No user action is required.
2022-07-21 03:13:14.71 spid7s      0 transactions rolled back in database 'msdb' (4:0). This is an informational message only. No user action is required.
2022-07-21 03:13:14.72 spid7s      Recovery is writing a checkpoint in database 'msdb' (4). This is an informational message only. No user action is required.
2022-07-21 03:13:14.74 spid11s     Refresh DEK for DBID: [3] File Id: [2] - Zeroring encryption state
2022-07-21 03:13:14.74 spid11s     The DEK is already set for DBID = [3] file id 2.
2022-07-21 03:13:14.75 spid11s     11 transactions rolled forward in database 'model' (3:0). This is an informational message only. No user action is required.
2022-07-21 03:13:14.76 spid11s     0 transactions rolled back in database 'model' (3:0). This is an informational message only. No user action is required.
2022-07-21 03:13:14.76 spid11s     Recovery is writing a checkpoint in database 'model' (3). This is an informational message only. No user action is required.
2022-07-21 03:13:14.78 spid11s     Clearing tempdb database.
2022-07-21 03:13:15.00 spid11s     Refresh DEK for DBID: [2] File Id: [1] - Copying encryption state
2022-07-21 03:13:15.00 spid11s     DBID: [2] File Header for File Id: [1] was read with state = 0.
2022-07-21 03:13:15.01 spid11s     DBID: [2] No DEK update for File Id: [1] while existing DEK is null.
2022/07/21 03:13:15 [launchpadd] INFO: Connection opened from 127.0.0.1:41112 to 127.0.0.1:1431
2022/07/21 03:13:15 [launchpadd] INFO: Sending message of type 20 [MessageTypePackageLauncherConnect], SessionID:00000000-0000-0000-0000-000000000000
2022/07/21 03:13:15 [launchpadd] INFO: Sent message of type 20 [MessageTypePackageLauncherConnect], SessionID:00000000-0000-0000-0000-000000000000
2022-07-21 03:13:15.02 Server      Server is listening on [ 127.0.0.1 <ipv4> 14300] accept sockets 1.
2022-07-21 03:13:15.02 Server      Server is listening on [ 127.0.0.1 <ipv4> 37257] accept sockets 1.
2022/07/21 03:13:15 [launchpadd] INFO: Received packet of type 17 [MessageTypeXdbDataPackageStart]
2022/07/21 03:13:15 [launchpadd] INFO: Packet header: &{Version:1 TypeAndEndOfMessageBit:32785 PacketSizeInBytes:310 SessionID:3558dd16-6757-4ac8-a32e-862656ab2302}
2022/07/21 03:13:15 [launchpadd] INFO: DataPackageStart message is received with package GUID=b22b365c-0809-4c1d-ad54-255e424c0dcf, SandboxFileMappingEntryCount=0, Configuration=6.
2022-07-21 03:13:15.04 spid11s     Refresh DEK for DBID: [2] File Id: [2] - Copying encryption state
2022-07-21 03:13:15.04 spid11s     DBID: [2] File Header for File Id: [2] was read with state = 0.
2022-07-21 03:13:15.04 spid11s     DBID: [2] No DEK update for File Id: [2] while existing DEK is null.
2022-07-21 03:13:15.06 spid11s     [2]. Feature Status: PVS: 0. CTR: 0. ConcurrentPFSUpdate: 1. ConcurrentGAMUpdate: 0. CleanupUnderUserTransaction: 0.
2022-07-21 03:13:15.06 spid11s     Starting up database 'tempdb'.
2022-07-21 03:13:15.12 spid11s     Refresh DEK for DBID: [2] File Id: [1] - Copying encryption state
2022-07-21 03:13:15.12 spid11s     DBID: [2] File Header for File Id: [1] was read with state = 0.
2022-07-21 03:13:15.12 spid11s     DBID: [2] No DEK update for File Id: [1] while existing DEK is null.
2022-07-21 03:13:15.18 spid11s     Refresh DEK for DBID: [2] File Id: [2] - Copying encryption state
2022-07-21 03:13:15.18 spid11s     DBID: [2] File Header for File Id: [2] was read with state = 0.
2022-07-21 03:13:15.18 spid11s     DBID: [2] No DEK update for File Id: [2] while existing DEK is null.
2022/07/21 03:13:15 [launchpadd] [b22b365c] INFO: Sending message of type 19 [MessageTypeXdbPackageResume], SessionID:3558dd16-6757-4ac8-a32e-862656ab2302
2022/07/21 03:13:15 [launchpadd] [b22b365c] INFO: Sent message of type 19 [MessageTypeXdbPackageResume], SessionID:3558dd16-6757-4ac8-a32e-862656ab2302
2022/07/21 03:13:15 [launchpadd] [b22b365c] INFO: Received packet of type 12 [MessageTypeAck]
2022/07/21 03:13:15 [launchpadd] [b22b365c] INFO: Packet header: &{Version:1 TypeAndEndOfMessageBit:32780 PacketSizeInBytes:28 SessionID:b22b365c-0809-4c1d-ad54-255e424c0dcf}
2022/07/21 03:13:15 [launchpadd] [b22b365c] INFO: Starting launchpad for b22b365c-0809-4c1d-ad54-255e424c0dcf
2022/07/21 03:13:15 [launchpadd] [b22b365c] INFO: Create sandbox directory /var/opt/mssql-extensibility/data/b22b365c-0809-4c1d-ad54-255e424c0dcf  with uid= 10001 gid= 0 perms= -rwxrwxr-x
2022/07/21 03:13:15 [launchpadd] [b22b365c] INFO: Change owner and permissions for sandbox directory /var/opt/mssql-extensibility/data/b22b365c-0809-4c1d-ad54-255e424c0dcf uid= 10001 gid= 0 perms= -rwxrwxr-x
2022/07/21 03:13:15 [launchpadd] [b22b365c] INFO: mountExtMgmtPaths
2022/07/21 03:13:15 [launchpadd] [b22b365c] INFO: env var EXT_MGR_PATH_COUNT not set: strconv.Atoi: parsing "": invalid syntax 
2022/07/21 03:13:15 [launchpadd] [b22b365c] INFO: Mount external language or library paths, EXT_MGR_PATH_COUNT=0 
2022/07/21 03:13:15 [launchpadd] [b22b365c] INFO: In no isolation mode, running /opt/mssql-extensibility/bin/launchpad with args [-hostname 127.0.0.1 -sandboxWorkingDir /var/opt/mssql-extensibility/data/b22b365c-0809-4c1d-ad54-255e424c0dcf -reparentOrphanedDescendants=true -useDefaultLaunchers=false]
2022/07/21 03:13:15 [launchpadd] [b22b365c] INFO: Started launchpad with pid:149
2022-07-21 03:13:15.24 spid11s     The tempdb database has 1 data file(s).
2022/07/21 03:13:15 [launchpad] [b22b365c] INFO: Launchpad is connecting to mssql on 127.0.0.1:14300
2022/07/21 03:13:15 [launchpad] [b22b365c] INFO: Setting PR_SET_CHILD_SUBREAPER
2022/07/21 03:13:15 [launchpad] [b22b365c] INFO: Received packet of type 8 [MessageTypeServiceStart]
2022/07/21 03:13:15 [launchpad] [b22b365c] INFO: Packet header: &{Version:1 TypeAndEndOfMessageBit:32776 PacketSizeInBytes:426 SessionID:00000001-0000-0000-0000-000000000000}
2022/07/21 03:13:15 [launchpad] [b22b365c] [00000001] INFO: Start processPacketLoop for SessionID: 00000001-0000-0000-0000-000000000000
2022/07/21 03:13:15 [launchpad] [b22b365c] [00000001] INFO: Launching satellite for SessionID: 00000001-0000-0000-0000-000000000000
2022/07/21 03:13:15 [launchpad] [b22b365c] [00000001] INFO: Cleaning up prior external service lifetime for groups map[]
2022/07/21 03:13:15 [launchpad] [b22b365c] [00000001] INFO: Cleanup of prior external service lifetime complete
2022/07/21 03:13:15 [launchpad] [b22b365c] [00000001] INFO: Starting script dotnet /app/ASDE.Dotnet.dll 00000001-0000-0000-0000-000000000000
2022/07/21 03:13:15 [launchpad] [b22b365c] [00000001] INFO: Sending message of type 3 [MessageTypeResumeWithLogin], SessionID:00000001-0000-0000-0000-000000000000
2022/07/21 03:13:15 [launchpad] [b22b365c] [00000001] INFO: Sent message of type 3 [MessageTypeResumeWithLogin], SessionID:00000001-0000-0000-0000-000000000000
2022/07/21 03:13:15 [launchpad] [b22b365c] [00000001] INFO: Done processing the packet of type 8 [MessageTypeServiceStart]
2022-07-21T03:13:15.298Z [dotnet /app/ASDE.Dotnet.dll] [158] ASDE.Dotnet starting up
2022022-07-21T03:13:15.300Z [dotnet /app/ASDE.Dotnet.dll] [158] MSSQL_LCID: 
2-07-21 03:13:15.29 spid7s      Recovery is complete. This is an informational message only. No user action is required.
2022-07-21 03:13:15.30 spid25s     The Service Broker endpoint is in disabled or stopped state.
2022-07-21 03:13:15.30 spid25s     The Database Mirroring endpoint is in disabled or stopped state.
2022-07-21T03:13:15.326Z [dotnet /app/ASDE.Dotnet.dll] [158] Creating ASA Job Manager with: OfflineEdgeHubClientWrapper
2022-07-21 03:13:15.33 spid25s     Service Broker manager has started.
2022-07-21T03:13:15.379Z [dotnet /app/ASDE.Dotnet.dll] [158] Initializing ASA Job Manager
2022-07-21T03:13:15.388Z [dotnet /app/ASDE.Dotnet.dll] [158] ASDE.Dotnet entering main loop.
2022-07-21T03:13:15.388Z [dotnet /app/ASDE.Dotnet.dll] [158] Satellite listener exists. Starting ASASatelliteService.
2022-07-21T03:13:15.390Z [dotnet /app/ASDE.Dotnet.dll] [158] Waiting for next message
2022-07-21 03:13:15.51 Server      Configuring Always Encrypted enclave in asynchronous mode.
2022-07-21T03:13:15.596Z [dotnet /app/ASDE.Dotnet.dll] [158] Waiting for next message
2022-07-21T03:13:15.655Z [dotnet /app/ASDE.Dotnet.dll] [158] Operation: NotifyJobsRecovered
2022-07-21T03:13:15.657Z [dotnet /app/ASDE.Dotnet.dll] [158] Job recovery notification received.
2022-07-21T03:13:15.657Z [dotnet /app/ASDE.Dotnet.dll] [158] Operation: NotifyJobsRecovered success: True. message: 
2022-07-21T03:13:15.671Z [dotnet /app/ASDE.Dotnet.dll] [158] Sending response message for 4a85c5f3-03e0-4110-bb73-2a4843c77353 with length: 162
2022/07/21 03:13:15 [launchpad] [b22b365c] [00000001] INFO: Received packet of type 8 [MessageTypeServiceStart]
2022/07/21 03:13:15 [launchpad] [b22b365c] [00000001] INFO: Packet header: &{Version:1 TypeAndEndOfMessageBit:32776 PacketSizeInBytes:452 SessionID:0b69cc1c-cf9c-4725-b52a-fccf1f3a038e}
2022/07/21 03:13:15 [launchpad] [b22b365c] [0b69cc1c] INFO: Start processPacketLoop for SessionID: 0b69cc1c-cf9c-4725-b52a-fccf1f3a038e
2022/07/21 03:13:15 [launchpad] [b22b365c] [0b69cc1c] INFO: Launching satellite for SessionID: 0b69cc1c-cf9c-4725-b52a-fccf1f3a038e
2022/07/21 03:13:15 [launchpad] [b22b365c] [0b69cc1c] INFO: Cleaning up prior external service lifetime for groups map[]
2022/07/21 03:13:15 [launchpad] [b22b365c] [0b69cc1c] INFO: Cleanup of prior external service lifetime complete
2022/07/21 03:13:15 [launchpad] [b22b365c] [0b69cc1c] INFO: Starting script /opt/mssql-extensibility/bin/predicthost 0b69cc1c-cf9c-4725-b52a-fccf1f3a038e
2022/07/21 03:13:15 [launchpad] [b22b365c] [0b69cc1c] INFO: Sending message of type 3 [MessageTypeResumeWithLogin], SessionID:0b69cc1c-cf9c-4725-b52a-fccf1f3a038e
2022/07/21 03:13:15 [launchpad] [b22b365c] [0b69cc1c] INFO: Sent message of type 3 [MessageTypeResumeWithLogin], SessionID:0b69cc1c-cf9c-4725-b52a-fccf1f3a038e
2022/07/21 03:13:15 [launchpad] [b22b365c] [0b69cc1c] INFO: Done processing the packet of type 8 [MessageTypeServiceStart]
2022/07/21 03:13:15 [launchpadd] [b22b365c] INFO: Received packet of type 17 [MessageTypeXdbDataPackageStart]
2022/07/21 03:13:15 [launchpadd] [b22b365c] INFO: Packet header: &{Version:1 TypeAndEndOfMessageBit:32785 PacketSizeInBytes:310 SessionID:bfff05d8-41ec-40ea-af57-14d23c612f50}
2022/07/21 03:13:15 [launchpadd] [b22b365c] INFO: DataPackageStart message is received with package GUID=ae79c10b-7d20-42f4-9b91-82dd40c38e2a, SandboxFileMappingEntryCount=0, Configuration=6.
2022/07/21 03:13:15 [launchpadd] [ae79c10b] INFO: Sending message of type 19 [MessageTypeXdbPackageResume], SessionID:bfff05d8-41ec-40ea-af57-14d23c612f50
2022/07/21 03:13:15 [launchpadd] [ae79c10b] INFO: Sent message of type 19 [MessageTypeXdbPackageResume], SessionID:bfff05d8-41ec-40ea-af57-14d23c612f50
2022/07/21 03:13:15 [launchpadd] [ae79c10b] INFO: Received packet of type 12 [MessageTypeAck]
2022/07/21 03:13:15 [launchpadd] [ae79c10b] INFO: Packet header: &{Version:1 TypeAndEndOfMessageBit:32780 PacketSizeInBytes:28 SessionID:ae79c10b-7d20-42f4-9b91-82dd40c38e2a}
2022/07/21 03:13:15 [launchpadd] [ae79c10b] INFO: Starting launchpad for ae79c10b-7d20-42f4-9b91-82dd40c38e2a
2022/07/21 03:13:15 [launchpadd] [ae79c10b] INFO: Create sandbox directory /var/opt/mssql-extensibility/data/ae79c10b-7d20-42f4-9b91-82dd40c38e2a  with uid= 10001 gid= 0 perms= -rwxrwxr-x
2022/07/21 03:13:15 [launchpadd] [ae79c10b] INFO: Change owner and permissions for sandbox directory /var/opt/mssql-extensibility/data/ae79c10b-7d20-42f4-9b91-82dd40c38e2a uid= 10001 gid= 0 perms= -rwxrwxr-x
2022/07/21 03:13:15 [launchpadd] [ae79c10b] INFO: mountExtMgmtPaths
2022/07/21 03:13:15 [launchpadd] [ae79c10b] INFO: env var EXT_MGR_PATH_COUNT not set: strconv.Atoi: parsing "": invalid syntax 
2022/07/21 03:13:15 [launchpadd] [ae79c10b] INFO: Mount external language or library paths, EXT_MGR_PATH_COUNT=0 
2022/07/21 03:13:15 [launchpadd] [ae79c10b] INFO: In no isolation mode, running /opt/mssql-extensibility/bin/launchpad with args [-hostname 127.0.0.1 -sandboxWorkingDir /var/opt/mssql-extensibility/data/ae79c10b-7d20-42f4-9b91-82dd40c38e2a -reparentOrphanedDescendants=true -useDefaultLaunchers=false]
2022/07/21 03:13:15 [launchpadd] [ae79c10b] INFO: Started launchpad with pid:188
2022/07/21 03:13:15 [launchpad] [ae79c10b] INFO: Launchpad is connecting to mssql on 127.0.0.1:14300
2022/07/21 03:13:15 [launchpad] [ae79c10b] INFO: Setting PR_SET_CHILD_SUBREAPER
2022/07/21 03:13:15 [launchpad] [ae79c10b] INFO: Received packet of type 8 [MessageTypeServiceStart]
2022/07/21 03:13:15 [launchpad] [ae79c10b] INFO: Packet header: &{Version:1 TypeAndEndOfMessageBit:32776 PacketSizeInBytes:446 SessionID:00000001-0000-002a-0000-080000000100}
2022/07/21 03:13:15 [launchpad] [ae79c10b] [00000001] INFO: Start processPacketLoop for SessionID: 00000001-0000-002a-0000-080000000100
2022/07/21 03:13:15 [launchpad] [ae79c10b] [00000001] INFO: Launching satellite for SessionID: 00000001-0000-002a-0000-080000000100
2022/07/21 03:13:15 [launchpad] [ae79c10b] [00000001] INFO: Cleaning up prior external service lifetime for groups map[]
2022/07/21 03:13:15 [launchpad] [ae79c10b] [00000001] INFO: Cleanup of prior external service lifetime complete
2022/07/21 03:13:15 [launchpad] [ae79c10b] [00000001] INFO: Starting script dotnet /app/sqlceip/EdgeTelemetry.dll 00000001-0000-002a-0000-080000000100
2022/07/21 03:13:15 [launchpad] [ae79c10b] [00000001] INFO: Sending message of type 3 [MessageTypeResumeWithLogin], SessionID:00000001-0000-002a-0000-080000000100
2022/07/21 03:13:15 [launchpad] [ae79c10b] [00000001] INFO: Sent message of type 3 [MessageTypeResumeWithLogin], SessionID:00000001-0000-002a-0000-080000000100
2022/07/21 03:13:15 [launchpad] [ae79c10b] [00000001] INFO: Done processing the packet of type 8 [MessageTypeServiceStart]
2022-07-21T03:13:15.974Z [dotnet /app/sqlceip/EdgeTelemetry.dll] [197] EdgeTelemetry starting up
2022-07-21 03:13:48.04 spid51      Attempting to load library 'xplog70.dll' into memory. This is an informational message only. No user action is required.
2022-07-21 03:13:48.09 spid51      Using 'xplog70.dll' version '2019.150.2000' to execute extended stored procedure 'xp_msver'. This is an informational message only; no user action is required.
2022/07/21 03:13:58 [launchpad] [ae79c10b] [00000001] INFO: Logging stopped for 00000001-0000-002a-0000-080000000100
2022/07/21 03:13:58 [launchpad] [ae79c10b] [00000001] INFO: Pid 197 exited with status 0 for SessionID:00000001-0000-002a-0000-080000000100
2022/07/21 03:13:58 [launchpad] [ae79c10b] [00000001] INFO: All current child processes have exited. SessionID:00000001-0000-002a-0000-080000000100
2022/07/21 03:13:58 [launchpad] [ae79c10b] [00000001] WARNING: Error waiting for external service process wait: no child processes
The login info is removed from the store and destroyed.
2022/07/21 03:13:58 [launchpadd] [ae79c10b] INFO: Received packet of type 18 [MessageTypeXdbDataPackageStop]
2022/07/21 03:13:58 [launchpadd] [ae79c10b] INFO: Packet header: &{Version:1 TypeAndEndOfMessageBit:32786 PacketSizeInBytes:40 SessionID:7570a7dc-71b7-470e-bd59-7bbde26fef3c}
2022/07/21 03:13:58 [launchpadd] [ae79c10b] INFO: DataPackageStop message is received with package GUID ae79c10b-7d20-42f4-9b91-82dd40c38e2a.
2022/07/21 03:13:58 [launchpadd] [ae79c10b] INFO: Launchpad of package GUID ae79c10b-7d20-42f4-9b91-82dd40c38e2a found in the store.
2022/07/21 03:13:58 [launchpadd] [ae79c10b] INFO: Launchpad of package GUID ae79c10b-7d20-42f4-9b91-82dd40c38e2a is terminated.
2022/07/21 03:13:58 [launchpadd] [ae79c10b] INFO: resume after receiving stop message. 
2022/07/21 03:13:58 [launchpadd] [ae79c10b] INFO: Sending message of type 19 [MessageTypeXdbPackageResume], SessionID:7570a7dc-71b7-470e-bd59-7bbde26fef3c
2022/07/21 03:13:58 [launchpadd] [ae79c10b] INFO: Sent message of type 19 [MessageTypeXdbPackageResume], SessionID:7570a7dc-71b7-470e-bd59-7bbde26fef3c
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment