{"level":"warn","ts":"2026-07-25T08:40:38.404772Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-25T08:40:38.404894Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.77:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.77:2380","--initial-cluster=instance=https://199.204.45.77:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.77:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.77:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-25T08:40:38.404983Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-25T08:40:38.404998Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-07-25T08:40:38.405019Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-25T08:40:38.405871Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"]} {"level":"info","ts":"2026-07-25T08:40:38.405996Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.77:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-25T08:40:38.410263Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.957016ms"} {"level":"info","ts":"2026-07-25T08:40:38.416424Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9133559b3198d4ec","cluster-id":"60f1aee33b0786f5"} {"level":"info","ts":"2026-07-25T08:40:38.416697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=()"} {"level":"info","ts":"2026-07-25T08:40:38.416728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 0"} {"level":"info","ts":"2026-07-25T08:40:38.416741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9133559b3198d4ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-25T08:40:38.416753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 1"} {"level":"info","ts":"2026-07-25T08:40:38.416794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"warn","ts":"2026-07-25T08:40:38.422906Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-25T08:40:38.424386Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-25T08:40:38.42569Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-25T08:40:38.427385Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9133559b3198d4ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-25T08:40:38.427488Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9133559b3198d4ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-25T08:40:38.427744Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-25T08:40:38.42792Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-25T08:40:38.427934Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-25T08:40:38.4293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"info","ts":"2026-07-25T08:40:38.429434Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","added-peer-id":"9133559b3198d4ec","added-peer-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-07-25T08:40:38.431515Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-25T08:40:38.431734Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-07-25T08:40:38.431752Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-07-25T08:40:38.432622Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9133559b3198d4ec","initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-25T08:40:38.432655Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-25T08:40:39.118089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec is starting a new election at term 1"} {"level":"info","ts":"2026-07-25T08:40:39.118176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became pre-candidate at term 1"} {"level":"info","ts":"2026-07-25T08:40:39.1182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgPreVoteResp from 9133559b3198d4ec at term 1"} {"level":"info","ts":"2026-07-25T08:40:39.118221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became candidate at term 2"} {"level":"info","ts":"2026-07-25T08:40:39.118232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgVoteResp from 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-07-25T08:40:39.118254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became leader at term 2"} {"level":"info","ts":"2026-07-25T08:40:39.118273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9133559b3198d4ec elected leader 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-07-25T08:40:39.119473Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T08:40:39.120529Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9133559b3198d4ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.77:2379]}","request-path":"/0/members/9133559b3198d4ec/attributes","cluster-id":"60f1aee33b0786f5","publish-timeout":"7s"} {"level":"info","ts":"2026-07-25T08:40:39.120538Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T08:40:39.120577Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T08:40:39.121264Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-25T08:40:39.121363Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-25T08:40:39.121411Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T08:40:39.121518Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T08:40:39.121552Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T08:40:39.123861Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-25T08:40:39.12439Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.77:2379"} {"level":"warn","ts":"2026-07-25T08:40:57.917918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.350013ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-25T08:40:57.918035Z","caller":"traceutil/trace.go:171","msg":"trace[1500486411] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:436; }","duration":"422.689668ms","start":"2026-07-25T08:40:57.495322Z","end":"2026-07-25T08:40:57.918012Z","steps":["trace[1500486411] 'read index received' (duration: 200.054324ms)","trace[1500486411] 'applied index is now lower than readState.Index' (duration: 222.634014ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T08:40:57.918152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.830782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T08:40:57.918184Z","caller":"traceutil/trace.go:171","msg":"trace[528994640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:423; }","duration":"422.909206ms","start":"2026-07-25T08:40:57.495267Z","end":"2026-07-25T08:40:57.918176Z","steps":["trace[528994640] 'agreement among raft nodes before linearized reading' (duration: 422.846943ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:40:57.918218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T08:40:57.495247Z","time spent":"422.963898ms","remote":"127.0.0.1:50820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-25T08:40:58.145964Z","caller":"traceutil/trace.go:171","msg":"trace[138354543] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"225.312479ms","start":"2026-07-25T08:40:57.920621Z","end":"2026-07-25T08:40:58.145933Z","steps":["trace[138354543] 'process raft request' (duration: 225.130772ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:41:14.808368Z","caller":"traceutil/trace.go:171","msg":"trace[1833993999] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"100.477482ms","start":"2026-07-25T08:41:14.707859Z","end":"2026-07-25T08:41:14.808336Z","steps":["trace[1833993999] 'process raft request' (duration: 100.300605ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:41:20.78625Z","caller":"traceutil/trace.go:171","msg":"trace[524730145] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"157.5444ms","start":"2026-07-25T08:41:20.62868Z","end":"2026-07-25T08:41:20.786224Z","steps":["trace[524730145] 'process raft request' (duration: 65.14613ms)","trace[524730145] 'compare' (duration: 91.880964ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T08:41:20.966035Z","caller":"traceutil/trace.go:171","msg":"trace[1618012586] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"141.83362ms","start":"2026-07-25T08:41:20.824181Z","end":"2026-07-25T08:41:20.966015Z","steps":["trace[1618012586] 'process raft request' (duration: 141.731627ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:41:20.970188Z","caller":"traceutil/trace.go:171","msg":"trace[2045069501] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"130.873129ms","start":"2026-07-25T08:41:20.839296Z","end":"2026-07-25T08:41:20.970169Z","steps":["trace[2045069501] 'process raft request' (duration: 130.294811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:41:21.271492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.361492ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T08:41:21.271843Z","caller":"traceutil/trace.go:171","msg":"trace[1329850111] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"297.219164ms","start":"2026-07-25T08:41:20.974601Z","end":"2026-07-25T08:41:21.271821Z","steps":["trace[1329850111] 'process raft request' (duration: 191.541301ms)","trace[1329850111] 'compare' (duration: 103.605709ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T08:41:21.2722Z","caller":"traceutil/trace.go:171","msg":"trace[1237440610] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"297.506292ms","start":"2026-07-25T08:41:20.974631Z","end":"2026-07-25T08:41:21.272137Z","steps":["trace[1237440610] 'process raft request' (duration: 297.052269ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:41:21.272834Z","caller":"traceutil/trace.go:171","msg":"trace[1978466708] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:652; }","duration":"292.691752ms","start":"2026-07-25T08:41:20.980084Z","end":"2026-07-25T08:41:21.272776Z","steps":["trace[1978466708] 'read index received' (duration: 186.069001ms)","trace[1978466708] 'applied index is now lower than readState.Index' (duration: 106.621321ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T08:41:21.272909Z","caller":"traceutil/trace.go:171","msg":"trace[774083587] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"298.131622ms","start":"2026-07-25T08:41:20.974764Z","end":"2026-07-25T08:41:21.272895Z","steps":["trace[774083587] 'process raft request' (duration: 297.016848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:41:21.273071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.94559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T08:41:21.273192Z","caller":"traceutil/trace.go:171","msg":"trace[528190068] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:636; }","duration":"293.088385ms","start":"2026-07-25T08:41:20.980049Z","end":"2026-07-25T08:41:21.273138Z","steps":["trace[528190068] 'agreement among raft nodes before linearized reading' (duration: 292.889928ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:41:22.227583Z","caller":"traceutil/trace.go:171","msg":"trace[1241375452] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"519.372558ms","start":"2026-07-25T08:41:21.708183Z","end":"2026-07-25T08:41:22.227555Z","steps":["trace[1241375452] 'process raft request' (duration: 518.45461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:41:22.227727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T08:41:21.708155Z","time spent":"519.498282ms","remote":"127.0.0.1:50944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T08:41:37.210517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.793285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-5pzmb\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-07-25T08:41:37.210602Z","caller":"traceutil/trace.go:171","msg":"trace[153205902] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-5pzmb; range_end:; response_count:1; response_revision:796; }","duration":"300.933559ms","start":"2026-07-25T08:41:36.909649Z","end":"2026-07-25T08:41:37.210582Z","steps":["trace[153205902] 'range keys from in-memory index tree' (duration: 300.573879ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:41:37.210664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T08:41:36.909619Z","time spent":"301.035572ms","remote":"127.0.0.1:51054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-5pzmb\" "} {"level":"info","ts":"2026-07-25T08:43:24.030951Z","caller":"traceutil/trace.go:171","msg":"trace[2054854175] transaction","detail":"{read_only:false; response_revision:1605; number_of_response:1; }","duration":"132.86659ms","start":"2026-07-25T08:43:23.898045Z","end":"2026-07-25T08:43:24.030912Z","steps":["trace[2054854175] 'process raft request' (duration: 88.064313ms)","trace[2054854175] 'compare' (duration: 44.663275ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T08:43:24.047639Z","caller":"traceutil/trace.go:171","msg":"trace[1715229442] transaction","detail":"{read_only:false; response_revision:1606; number_of_response:1; }","duration":"148.449572ms","start":"2026-07-25T08:43:23.899173Z","end":"2026-07-25T08:43:24.047623Z","steps":["trace[1715229442] 'process raft request' (duration: 148.226649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:43:56.691111Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813507590365894,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-25T08:43:56.790102Z","caller":"traceutil/trace.go:171","msg":"trace[1608650274] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"600.378037ms","start":"2026-07-25T08:43:56.1897Z","end":"2026-07-25T08:43:56.790078Z","steps":["trace[1608650274] 'process raft request' (duration: 600.211875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:43:56.790366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T08:43:56.189679Z","time spent":"600.487878ms","remote":"127.0.0.1:51128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T08:43:56.791019Z","caller":"traceutil/trace.go:171","msg":"trace[1987505955] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"601.251669ms","start":"2026-07-25T08:43:56.189753Z","end":"2026-07-25T08:43:56.791005Z","steps":["trace[1987505955] 'process raft request' (duration: 601.138247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:43:56.791104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T08:43:56.18974Z","time spent":"601.321271ms","remote":"127.0.0.1:51128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T08:43:56.791225Z","caller":"traceutil/trace.go:171","msg":"trace[1792413922] linearizableReadLoop","detail":"{readStateIndex:2309; appliedIndex:2306; }","duration":"600.120014ms","start":"2026-07-25T08:43:56.191059Z","end":"2026-07-25T08:43:56.791179Z","steps":["trace[1792413922] 'read index received' (duration: 598.917947ms)","trace[1792413922] 'applied index is now lower than readState.Index' (duration: 1.199877ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T08:43:56.791233Z","caller":"traceutil/trace.go:171","msg":"trace[806012707] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"339.830392ms","start":"2026-07-25T08:43:56.451371Z","end":"2026-07-25T08:43:56.791201Z","steps":["trace[806012707] 'process raft request' (duration: 339.583199ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:43:56.791507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.370487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-25T08:43:56.791547Z","caller":"traceutil/trace.go:171","msg":"trace[333232239] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2222; }","duration":"600.491039ms","start":"2026-07-25T08:43:56.191047Z","end":"2026-07-25T08:43:56.791538Z","steps":["trace[333232239] 'agreement among raft nodes before linearized reading' (duration: 600.218706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:43:56.791583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T08:43:56.191023Z","time spent":"600.542759ms","remote":"127.0.0.1:51128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-25T08:43:56.791711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T08:43:56.451341Z","time spent":"340.159906ms","remote":"127.0.0.1:51128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T08:43:56.791776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.162664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-25T08:43:56.791845Z","caller":"traceutil/trace.go:171","msg":"trace[2039069658] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2222; }","duration":"140.268297ms","start":"2026-07-25T08:43:56.651567Z","end":"2026-07-25T08:43:56.791836Z","steps":["trace[2039069658] 'agreement among raft nodes before linearized reading' (duration: 140.127285ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:43:56.792132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.839583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-332c39eb-3f4f-4a4f-95c1-b532bb37d581\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-25T08:43:56.792862Z","caller":"traceutil/trace.go:171","msg":"trace[61950777] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-332c39eb-3f4f-4a4f-95c1-b532bb37d581; range_end:; response_count:1; response_revision:2222; }","duration":"343.607573ms","start":"2026-07-25T08:43:56.449231Z","end":"2026-07-25T08:43:56.792839Z","steps":["trace[61950777] 'agreement among raft nodes before linearized reading' (duration: 342.323655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:43:56.793003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T08:43:56.449213Z","time spent":"343.771125ms","remote":"127.0.0.1:51054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-332c39eb-3f4f-4a4f-95c1-b532bb37d581\" "} {"level":"info","ts":"2026-07-25T08:44:09.069354Z","caller":"traceutil/trace.go:171","msg":"trace[1095947164] linearizableReadLoop","detail":"{readStateIndex:2431; appliedIndex:2429; }","duration":"175.882267ms","start":"2026-07-25T08:44:08.893457Z","end":"2026-07-25T08:44:09.069339Z","steps":["trace[1095947164] 'read index received' (duration: 87.981729ms)","trace[1095947164] 'applied index is now lower than readState.Index' (duration: 87.899798ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T08:44:09.069417Z","caller":"traceutil/trace.go:171","msg":"trace[73526018] transaction","detail":"{read_only:false; response_revision:2339; number_of_response:1; }","duration":"194.412098ms","start":"2026-07-25T08:44:08.87495Z","end":"2026-07-25T08:44:09.069362Z","steps":["trace[73526018] 'process raft request' (duration: 106.52622ms)","trace[73526018] 'compare' (duration: 87.714136ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T08:44:09.069458Z","caller":"traceutil/trace.go:171","msg":"trace[1406992547] transaction","detail":"{read_only:false; response_revision:2340; number_of_response:1; }","duration":"194.470909ms","start":"2026-07-25T08:44:08.87495Z","end":"2026-07-25T08:44:09.069421Z","steps":["trace[1406992547] 'process raft request' (duration: 194.349507ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:44:09.069517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.047349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-25T08:44:09.069545Z","caller":"traceutil/trace.go:171","msg":"trace[1925677446] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2340; }","duration":"176.12467ms","start":"2026-07-25T08:44:08.893412Z","end":"2026-07-25T08:44:09.069537Z","steps":["trace[1925677446] 'agreement among raft nodes before linearized reading' (duration: 176.026769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:44:09.069717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.602868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11981"} {"level":"info","ts":"2026-07-25T08:44:09.069766Z","caller":"traceutil/trace.go:171","msg":"trace[291036768] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2340; }","duration":"165.69078ms","start":"2026-07-25T08:44:08.904062Z","end":"2026-07-25T08:44:09.069753Z","steps":["trace[291036768] 'agreement among raft nodes before linearized reading' (duration: 165.495386ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:44:09.38148Z","caller":"traceutil/trace.go:171","msg":"trace[1132080755] transaction","detail":"{read_only:false; response_revision:2342; number_of_response:1; }","duration":"110.002045ms","start":"2026-07-25T08:44:09.271445Z","end":"2026-07-25T08:44:09.381447Z","steps":["trace[1132080755] 'process raft request' (duration: 80.937142ms)","trace[1132080755] 'compare' (duration: 28.871ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T08:44:11.80603Z","caller":"traceutil/trace.go:171","msg":"trace[1218288218] transaction","detail":"{read_only:false; response_revision:2363; number_of_response:1; }","duration":"103.785997ms","start":"2026-07-25T08:44:11.702218Z","end":"2026-07-25T08:44:11.806004Z","steps":["trace[1218288218] 'process raft request' (duration: 103.624105ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:44:15.652568Z","caller":"traceutil/trace.go:171","msg":"trace[1079231760] transaction","detail":"{read_only:false; response_revision:2386; number_of_response:1; }","duration":"105.806666ms","start":"2026-07-25T08:44:15.546738Z","end":"2026-07-25T08:44:15.652544Z","steps":["trace[1079231760] 'process raft request' (duration: 105.618653ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:44:16.015325Z","caller":"traceutil/trace.go:171","msg":"trace[1791519231] transaction","detail":"{read_only:false; response_revision:2387; number_of_response:1; }","duration":"199.093585ms","start":"2026-07-25T08:44:15.816209Z","end":"2026-07-25T08:44:16.015303Z","steps":["trace[1791519231] 'process raft request' (duration: 198.960993ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:44:28.216688Z","caller":"traceutil/trace.go:171","msg":"trace[113381085] transaction","detail":"{read_only:false; response_revision:2465; number_of_response:1; }","duration":"156.316107ms","start":"2026-07-25T08:44:28.060342Z","end":"2026-07-25T08:44:28.216659Z","steps":["trace[113381085] 'process raft request' (duration: 68.787255ms)","trace[113381085] 'compare' (duration: 87.433281ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T08:44:34.292874Z","caller":"traceutil/trace.go:171","msg":"trace[1703407088] transaction","detail":"{read_only:false; response_revision:2513; number_of_response:1; }","duration":"206.455439ms","start":"2026-07-25T08:44:34.0864Z","end":"2026-07-25T08:44:34.292856Z","steps":["trace[1703407088] 'process raft request' (duration: 206.264907ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:44:35.118784Z","caller":"traceutil/trace.go:171","msg":"trace[1598260703] linearizableReadLoop","detail":"{readStateIndex:2613; appliedIndex:2612; }","duration":"121.145272ms","start":"2026-07-25T08:44:34.997614Z","end":"2026-07-25T08:44:35.118759Z","steps":["trace[1598260703] 'read index received' (duration: 120.928379ms)","trace[1598260703] 'applied index is now lower than readState.Index' (duration: 215.773µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T08:44:35.11905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.414616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-25T08:44:35.119088Z","caller":"traceutil/trace.go:171","msg":"trace[1872684618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2516; }","duration":"121.504516ms","start":"2026-07-25T08:44:34.997571Z","end":"2026-07-25T08:44:35.119075Z","steps":["trace[1872684618] 'agreement among raft nodes before linearized reading' (duration: 121.290554ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:44:35.119221Z","caller":"traceutil/trace.go:171","msg":"trace[152124949] transaction","detail":"{read_only:false; response_revision:2516; number_of_response:1; }","duration":"133.778288ms","start":"2026-07-25T08:44:34.985402Z","end":"2026-07-25T08:44:35.11918Z","steps":["trace[152124949] 'process raft request' (duration: 133.191051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:44:35.303667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.25721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T08:44:35.304155Z","caller":"traceutil/trace.go:171","msg":"trace[1251630785] transaction","detail":"{read_only:false; response_revision:2517; number_of_response:1; }","duration":"180.336775ms","start":"2026-07-25T08:44:35.123777Z","end":"2026-07-25T08:44:35.304114Z","steps":["trace[1251630785] 'process raft request' (duration: 53.522738ms)","trace[1251630785] 'compare' (duration: 126.129098ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T08:44:35.46151Z","caller":"traceutil/trace.go:171","msg":"trace[499663583] transaction","detail":"{read_only:false; response_revision:2518; number_of_response:1; }","duration":"152.778294ms","start":"2026-07-25T08:44:35.308703Z","end":"2026-07-25T08:44:35.461482Z","steps":["trace[499663583] 'process raft request' (duration: 152.588102ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:44:35.46922Z","caller":"traceutil/trace.go:171","msg":"trace[5093385] transaction","detail":"{read_only:false; response_revision:2519; number_of_response:1; }","duration":"160.07983ms","start":"2026-07-25T08:44:35.309116Z","end":"2026-07-25T08:44:35.469196Z","steps":["trace[5093385] 'process raft request' (duration: 159.916528ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:44:35.469409Z","caller":"traceutil/trace.go:171","msg":"trace[10175125] linearizableReadLoop","detail":"{readStateIndex:2617; appliedIndex:2614; }","duration":"154.109022ms","start":"2026-07-25T08:44:35.315267Z","end":"2026-07-25T08:44:35.469376Z","steps":["trace[10175125] 'read index received' (duration: 146.113057ms)","trace[10175125] 'applied index is now lower than readState.Index' (duration: 7.993105ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T08:44:35.469548Z","caller":"traceutil/trace.go:171","msg":"trace[509902107] transaction","detail":"{read_only:false; response_revision:2520; number_of_response:1; }","duration":"159.23699ms","start":"2026-07-25T08:44:35.310292Z","end":"2026-07-25T08:44:35.469529Z","steps":["trace[509902107] 'process raft request' (duration: 158.825184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:44:35.46975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.899566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T08:44:35.469815Z","caller":"traceutil/trace.go:171","msg":"trace[932286052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2520; }","duration":"142.998617ms","start":"2026-07-25T08:44:35.326799Z","end":"2026-07-25T08:44:35.469798Z","steps":["trace[932286052] 'agreement among raft nodes before linearized reading' (duration: 142.880155ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:44:35.469907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.546697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-25T08:44:35.46999Z","caller":"traceutil/trace.go:171","msg":"trace[1001863760] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2520; }","duration":"154.71355ms","start":"2026-07-25T08:44:35.315259Z","end":"2026-07-25T08:44:35.469973Z","steps":["trace[1001863760] 'agreement among raft nodes before linearized reading' (duration: 154.352285ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:44:35.746082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.793247ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T08:44:35.746178Z","caller":"traceutil/trace.go:171","msg":"trace[1347974618] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2520; }","duration":"120.889758ms","start":"2026-07-25T08:44:35.625264Z","end":"2026-07-25T08:44:35.746154Z","steps":["trace[1347974618] 'range keys from in-memory index tree' (duration: 120.781107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:45:01.74363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.314755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-25T08:45:01.743711Z","caller":"traceutil/trace.go:171","msg":"trace[1683225289] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2662; }","duration":"162.43406ms","start":"2026-07-25T08:45:01.581259Z","end":"2026-07-25T08:45:01.743693Z","steps":["trace[1683225289] 'range keys from in-memory index tree' (duration: 162.102144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:45:01.743985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.560477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-25T08:45:01.744031Z","caller":"traceutil/trace.go:171","msg":"trace[1322980237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2662; }","duration":"160.62486ms","start":"2026-07-25T08:45:01.583397Z","end":"2026-07-25T08:45:01.744022Z","steps":["trace[1322980237] 'range keys from in-memory index tree' (duration: 160.302456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:45:01.744211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.523934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-25T08:45:01.744286Z","caller":"traceutil/trace.go:171","msg":"trace[687738812] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2662; }","duration":"157.64953ms","start":"2026-07-25T08:45:01.58662Z","end":"2026-07-25T08:45:01.74427Z","steps":["trace[687738812] 'range keys from in-memory index tree' (duration: 157.393559ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:46:03.251324Z","caller":"traceutil/trace.go:171","msg":"trace[1575457243] transaction","detail":"{read_only:false; response_revision:3104; number_of_response:1; }","duration":"109.498141ms","start":"2026-07-25T08:46:03.141785Z","end":"2026-07-25T08:46:03.251283Z","steps":["trace[1575457243] 'process raft request' (duration: 109.325166ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:46:04.291861Z","caller":"traceutil/trace.go:171","msg":"trace[1047942521] transaction","detail":"{read_only:false; response_revision:3108; number_of_response:1; }","duration":"232.245928ms","start":"2026-07-25T08:46:04.059597Z","end":"2026-07-25T08:46:04.291843Z","steps":["trace[1047942521] 'process raft request' (duration: 232.116304ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:46:04.292011Z","caller":"traceutil/trace.go:171","msg":"trace[1689401794] linearizableReadLoop","detail":"{readStateIndex:3224; appliedIndex:3224; }","duration":"230.983996ms","start":"2026-07-25T08:46:04.061018Z","end":"2026-07-25T08:46:04.292002Z","steps":["trace[1689401794] 'read index received' (duration: 230.980016ms)","trace[1689401794] 'applied index is now lower than readState.Index' (duration: 3.56µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T08:46:04.292122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.09364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-25T08:46:04.292137Z","caller":"traceutil/trace.go:171","msg":"trace[817370665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3108; }","duration":"231.174302ms","start":"2026-07-25T08:46:04.060958Z","end":"2026-07-25T08:46:04.292132Z","steps":["trace[817370665] 'agreement among raft nodes before linearized reading' (duration: 231.084549ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:46:04.31339Z","caller":"traceutil/trace.go:171","msg":"trace[1416847358] transaction","detail":"{read_only:false; response_revision:3109; number_of_response:1; }","duration":"251.261904ms","start":"2026-07-25T08:46:04.062108Z","end":"2026-07-25T08:46:04.31337Z","steps":["trace[1416847358] 'process raft request' (duration: 241.14197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:46:04.313575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.229658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-423b3124-1ffe-4685-8540-5d33121fe471\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-07-25T08:46:04.313658Z","caller":"traceutil/trace.go:171","msg":"trace[934585079] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-423b3124-1ffe-4685-8540-5d33121fe471; range_end:; response_count:1; response_revision:3109; }","duration":"164.361313ms","start":"2026-07-25T08:46:04.149278Z","end":"2026-07-25T08:46:04.31364Z","steps":["trace[934585079] 'agreement among raft nodes before linearized reading' (duration: 164.110035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:46:04.313754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.690953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-25T08:46:04.313874Z","caller":"traceutil/trace.go:171","msg":"trace[1226752955] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3109; }","duration":"246.829579ms","start":"2026-07-25T08:46:04.067025Z","end":"2026-07-25T08:46:04.313854Z","steps":["trace[1226752955] 'agreement among raft nodes before linearized reading' (duration: 246.487367ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:46:05.877745Z","caller":"traceutil/trace.go:171","msg":"trace[1341548658] linearizableReadLoop","detail":"{readStateIndex:3235; appliedIndex:3234; }","duration":"351.497928ms","start":"2026-07-25T08:46:05.526219Z","end":"2026-07-25T08:46:05.877717Z","steps":["trace[1341548658] 'read index received' (duration: 351.220289ms)","trace[1341548658] 'applied index is now lower than readState.Index' (duration: 276.419µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T08:46:05.877862Z","caller":"traceutil/trace.go:171","msg":"trace[1646347633] transaction","detail":"{read_only:false; response_revision:3119; number_of_response:1; }","duration":"426.727544ms","start":"2026-07-25T08:46:05.451102Z","end":"2026-07-25T08:46:05.877829Z","steps":["trace[1646347633] 'process raft request' (duration: 426.430855ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:46:05.877972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.728727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-25T08:46:05.878009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.123943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-25T08:46:05.878019Z","caller":"traceutil/trace.go:171","msg":"trace[429546601] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:3119; }","duration":"351.799499ms","start":"2026-07-25T08:46:05.526205Z","end":"2026-07-25T08:46:05.878005Z","steps":["trace[429546601] 'agreement among raft nodes before linearized reading' (duration: 351.701226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:46:05.878013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T08:46:05.451085Z","time spent":"426.85231ms","remote":"127.0.0.1:51128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T08:46:05.878063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T08:46:05.526149Z","time spent":"351.903581ms","remote":"127.0.0.1:51416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":2,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-07-25T08:46:05.878042Z","caller":"traceutil/trace.go:171","msg":"trace[1382625159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3119; }","duration":"139.197376ms","start":"2026-07-25T08:46:05.738836Z","end":"2026-07-25T08:46:05.878034Z","steps":["trace[1382625159] 'agreement among raft nodes before linearized reading' (duration: 139.097633ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:46:05.990068Z","caller":"traceutil/trace.go:171","msg":"trace[289675302] linearizableReadLoop","detail":"{readStateIndex:3236; appliedIndex:3235; }","duration":"104.832178ms","start":"2026-07-25T08:46:05.88521Z","end":"2026-07-25T08:46:05.990042Z","steps":["trace[289675302] 'read index received' (duration: 88.12484ms)","trace[289675302] 'applied index is now lower than readState.Index' (duration: 16.706108ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T08:46:05.990144Z","caller":"traceutil/trace.go:171","msg":"trace[663711699] transaction","detail":"{read_only:false; response_revision:3120; number_of_response:1; }","duration":"107.981981ms","start":"2026-07-25T08:46:05.882124Z","end":"2026-07-25T08:46:05.990106Z","steps":["trace[663711699] 'process raft request' (duration: 91.347785ms)","trace[663711699] 'compare' (duration: 16.377047ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T08:46:05.990273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.053085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-07-25T08:46:05.990302Z","caller":"traceutil/trace.go:171","msg":"trace[387063018] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:3120; }","duration":"105.154939ms","start":"2026-07-25T08:46:05.885138Z","end":"2026-07-25T08:46:05.990293Z","steps":["trace[387063018] 'agreement among raft nodes before linearized reading' (duration: 104.991424ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:46:16.056465Z","caller":"traceutil/trace.go:171","msg":"trace[92678543] transaction","detail":"{read_only:false; response_revision:3203; number_of_response:1; }","duration":"145.428086ms","start":"2026-07-25T08:46:15.910995Z","end":"2026-07-25T08:46:16.056423Z","steps":["trace[92678543] 'process raft request' (duration: 145.091035ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:50:39.145941Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2875} {"level":"info","ts":"2026-07-25T08:50:39.215905Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2875,"took":"68.866422ms","hash":2717902528,"current-db-size-bytes":39002112,"current-db-size":"39 MB","current-db-size-in-use-bytes":14073856,"current-db-size-in-use":"14 MB"} {"level":"info","ts":"2026-07-25T08:50:39.215975Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2717902528,"revision":2875,"compact-revision":-1} {"level":"warn","ts":"2026-07-25T08:53:19.082948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.163203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-42f60380-2276-4278-97d8-8c42eccd32b0\" ","response":"range_response_count:1 size:4066"} {"level":"info","ts":"2026-07-25T08:53:19.083046Z","caller":"traceutil/trace.go:171","msg":"trace[582129223] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-42f60380-2276-4278-97d8-8c42eccd32b0; range_end:; response_count:1; response_revision:6654; }","duration":"123.311025ms","start":"2026-07-25T08:53:18.959713Z","end":"2026-07-25T08:53:19.083024Z","steps":["trace[582129223] 'range keys from in-memory index tree' (duration: 122.943519ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:53:20.699372Z","caller":"traceutil/trace.go:171","msg":"trace[1090214026] transaction","detail":"{read_only:false; response_revision:6668; number_of_response:1; }","duration":"120.697513ms","start":"2026-07-25T08:53:20.578657Z","end":"2026-07-25T08:53:20.699354Z","steps":["trace[1090214026] 'process raft request' (duration: 120.601461ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:53:20.770618Z","caller":"traceutil/trace.go:171","msg":"trace[1071003265] transaction","detail":"{read_only:false; response_revision:6669; number_of_response:1; }","duration":"190.789287ms","start":"2026-07-25T08:53:20.579801Z","end":"2026-07-25T08:53:20.77059Z","steps":["trace[1071003265] 'process raft request' (duration: 178.812171ms)","trace[1071003265] 'compare' (duration: 11.61373ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T08:53:28.643946Z","caller":"traceutil/trace.go:171","msg":"trace[422593774] transaction","detail":"{read_only:false; response_revision:6776; number_of_response:1; }","duration":"107.485823ms","start":"2026-07-25T08:53:28.536441Z","end":"2026-07-25T08:53:28.643926Z","steps":["trace[422593774] 'process raft request' (duration: 107.32582ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:53:29.80076Z","caller":"traceutil/trace.go:171","msg":"trace[20819189] transaction","detail":"{read_only:false; response_revision:6809; number_of_response:1; }","duration":"101.23699ms","start":"2026-07-25T08:53:29.699501Z","end":"2026-07-25T08:53:29.800738Z","steps":["trace[20819189] 'process raft request' (duration: 101.114578ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:53:29.807233Z","caller":"traceutil/trace.go:171","msg":"trace[1396381003] transaction","detail":"{read_only:false; response_revision:6810; number_of_response:1; }","duration":"102.241396ms","start":"2026-07-25T08:53:29.704976Z","end":"2026-07-25T08:53:29.807217Z","steps":["trace[1396381003] 'process raft request' (duration: 102.149015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:55:31.798817Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813507590378445,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T08:55:32.222885Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.063610481s","expected-duration":"1s"} {"level":"info","ts":"2026-07-25T08:55:32.223292Z","caller":"traceutil/trace.go:171","msg":"trace[911796722] transaction","detail":"{read_only:false; response_revision:7477; number_of_response:1; }","duration":"1.064087775s","start":"2026-07-25T08:55:31.159177Z","end":"2026-07-25T08:55:32.223265Z","steps":["trace[911796722] 'process raft request' (duration: 1.06391684s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:55:32.223438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T08:55:31.159157Z","time spent":"1.064196438s","remote":"127.0.0.1:51128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T08:55:32.22668Z","caller":"traceutil/trace.go:171","msg":"trace[1365822274] linearizableReadLoop","detail":"{readStateIndex:7752; appliedIndex:7750; }","duration":"928.17216ms","start":"2026-07-25T08:55:31.298479Z","end":"2026-07-25T08:55:32.226651Z","steps":["trace[1365822274] 'read index received' (duration: 924.65562ms)","trace[1365822274] 'applied index is now lower than readState.Index' (duration: 3.51514ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T08:55:32.226839Z","caller":"traceutil/trace.go:171","msg":"trace[787468603] transaction","detail":"{read_only:false; response_revision:7478; number_of_response:1; }","duration":"714.693458ms","start":"2026-07-25T08:55:31.512134Z","end":"2026-07-25T08:55:32.226827Z","steps":["trace[787468603] 'process raft request' (duration: 714.179343ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:55:32.226899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T08:55:31.512112Z","time spent":"714.756519ms","remote":"127.0.0.1:51128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T08:55:32.227055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.712264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-25T08:55:32.227097Z","caller":"traceutil/trace.go:171","msg":"trace[2139594737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7479; }","duration":"892.802999ms","start":"2026-07-25T08:55:31.334284Z","end":"2026-07-25T08:55:32.227087Z","steps":["trace[2139594737] 'agreement among raft nodes before linearized reading' (duration: 892.692005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:55:32.22713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T08:55:31.334258Z","time spent":"892.86224ms","remote":"127.0.0.1:51128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-25T08:55:32.227263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.719677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-25T08:55:32.227367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.227326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-25T08:55:32.227396Z","caller":"traceutil/trace.go:171","msg":"trace[835816487] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7479; }","duration":"811.283308ms","start":"2026-07-25T08:55:31.416106Z","end":"2026-07-25T08:55:32.227389Z","steps":["trace[835816487] 'agreement among raft nodes before linearized reading' (duration: 811.219686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:55:32.227415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T08:55:31.416093Z","time spent":"811.318448ms","remote":"127.0.0.1:51128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-25T08:55:32.227405Z","caller":"traceutil/trace.go:171","msg":"trace[230597556] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7479; }","duration":"928.907343ms","start":"2026-07-25T08:55:31.298427Z","end":"2026-07-25T08:55:32.227334Z","steps":["trace[230597556] 'agreement among raft nodes before linearized reading' (duration: 928.401527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:55:32.227539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.334738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-25T08:55:32.227572Z","caller":"traceutil/trace.go:171","msg":"trace[456161884] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7479; }","duration":"836.43011ms","start":"2026-07-25T08:55:31.391135Z","end":"2026-07-25T08:55:32.227565Z","steps":["trace[456161884] 'agreement among raft nodes before linearized reading' (duration: 836.338047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:55:32.227591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T08:55:31.391116Z","time spent":"836.469761ms","remote":"127.0.0.1:51054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-25T08:55:32.227585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.173364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-07-25T08:55:32.227687Z","caller":"traceutil/trace.go:171","msg":"trace[235602331] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:7479; }","duration":"484.321229ms","start":"2026-07-25T08:55:31.743353Z","end":"2026-07-25T08:55:32.227674Z","steps":["trace[235602331] 'agreement among raft nodes before linearized reading' (duration: 484.101882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:55:32.227712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T08:55:31.743343Z","time spent":"484.362671ms","remote":"127.0.0.1:51064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2009,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-07-25T08:55:32.228195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T08:55:31.298408Z","time spent":"929.735748ms","remote":"127.0.0.1:51128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-25T08:55:32.22856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.617071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-25T08:55:32.228646Z","caller":"traceutil/trace.go:171","msg":"trace[1516634765] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:7479; }","duration":"606.770275ms","start":"2026-07-25T08:55:31.621857Z","end":"2026-07-25T08:55:32.228627Z","steps":["trace[1516634765] 'agreement among raft nodes before linearized reading' (duration: 605.419093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:55:32.22869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T08:55:31.62184Z","time spent":"606.838667ms","remote":"127.0.0.1:50938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":736,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-07-25T08:55:39.154125Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4897} {"level":"info","ts":"2026-07-25T08:55:39.231952Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4897,"took":"74.773011ms","hash":2214139027,"current-db-size-bytes":39002112,"current-db-size":"39 MB","current-db-size-in-use-bytes":28311552,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-25T08:55:39.232027Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2214139027,"revision":4897,"compact-revision":2875}