{"level":"warn","ts":"2026-07-16T06:47:55.008467Z","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-16T06:47:55.008615Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.171: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.19.213.171:2380","--initial-cluster=instance=https://199.19.213.171:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.171:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.171: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-16T06:47:55.008847Z","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-16T06:47:55.008869Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.171:2380"]} {"level":"info","ts":"2026-07-16T06:47:55.009037Z","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-16T06:47:55.010142Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.171:2379"]} {"level":"info","ts":"2026-07-16T06:47:55.010255Z","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.19.213.171:2380"],"listen-peer-urls":["https://199.19.213.171:2380"],"advertise-client-urls":["https://199.19.213.171:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.171:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.171: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-16T06:47:55.014823Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.099512ms"} {"level":"info","ts":"2026-07-16T06:47:55.020834Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"837e89131cc6bd81","cluster-id":"8af92e75ab8f3e69"} {"level":"info","ts":"2026-07-16T06:47:55.020911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 switched to configuration voters=()"} {"level":"info","ts":"2026-07-16T06:47:55.020942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 became follower at term 0"} {"level":"info","ts":"2026-07-16T06:47:55.020998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 837e89131cc6bd81 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-16T06:47:55.021028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 became follower at term 1"} {"level":"info","ts":"2026-07-16T06:47:55.021072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 switched to configuration voters=(9475161381214272897)"} {"level":"warn","ts":"2026-07-16T06:47:55.028325Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-16T06:47:55.029682Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-16T06:47:55.030814Z","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-16T06:47:55.032981Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"837e89131cc6bd81","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-16T06:47:55.033767Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"837e89131cc6bd81","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-16T06:47:55.033811Z","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-16T06:47:55.033908Z","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-16T06:47:55.033921Z","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-16T06:47:55.034324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 switched to configuration voters=(9475161381214272897)"} {"level":"info","ts":"2026-07-16T06:47:55.034456Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8af92e75ab8f3e69","local-member-id":"837e89131cc6bd81","added-peer-id":"837e89131cc6bd81","added-peer-peer-urls":["https://199.19.213.171:2380"]} {"level":"info","ts":"2026-07-16T06:47:55.041913Z","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-16T06:47:55.042108Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.171:2380"} {"level":"info","ts":"2026-07-16T06:47:55.04222Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.171:2380"} {"level":"info","ts":"2026-07-16T06:47:55.042974Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"837e89131cc6bd81","initial-advertise-peer-urls":["https://199.19.213.171:2380"],"listen-peer-urls":["https://199.19.213.171:2380"],"advertise-client-urls":["https://199.19.213.171:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.171:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-16T06:47:55.04302Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-16T06:47:56.022328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 is starting a new election at term 1"} {"level":"info","ts":"2026-07-16T06:47:56.022447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-16T06:47:56.022493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 received MsgPreVoteResp from 837e89131cc6bd81 at term 1"} {"level":"info","ts":"2026-07-16T06:47:56.022513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 became candidate at term 2"} {"level":"info","ts":"2026-07-16T06:47:56.022522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 received MsgVoteResp from 837e89131cc6bd81 at term 2"} {"level":"info","ts":"2026-07-16T06:47:56.022536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 became leader at term 2"} {"level":"info","ts":"2026-07-16T06:47:56.022569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 837e89131cc6bd81 elected leader 837e89131cc6bd81 at term 2"} {"level":"info","ts":"2026-07-16T06:47:56.023719Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T06:47:56.023988Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"837e89131cc6bd81","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.171:2379]}","request-path":"/0/members/837e89131cc6bd81/attributes","cluster-id":"8af92e75ab8f3e69","publish-timeout":"7s"} {"level":"info","ts":"2026-07-16T06:47:56.02419Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T06:47:56.024229Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T06:47:56.027166Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-16T06:47:56.027418Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.171:2379"} {"level":"info","ts":"2026-07-16T06:47:56.030869Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-16T06:47:56.031247Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-16T06:47:56.032318Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8af92e75ab8f3e69","local-member-id":"837e89131cc6bd81","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T06:47:56.032733Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T06:47:56.032882Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T06:48:00.852501Z","caller":"traceutil/trace.go:171","msg":"trace[1144232588] transaction","detail":"{read_only:false; number_of_response:0; response_revision:273; }","duration":"105.056974ms","start":"2026-07-16T06:48:00.747405Z","end":"2026-07-16T06:48:00.852462Z","steps":["trace[1144232588] 'process raft request' (duration: 57.503755ms)","trace[1144232588] 'compare' (duration: 47.472687ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:48:00.860763Z","caller":"traceutil/trace.go:171","msg":"trace[1345452740] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"113.285945ms","start":"2026-07-16T06:48:00.747437Z","end":"2026-07-16T06:48:00.860723Z","steps":["trace[1345452740] 'process raft request' (duration: 113.07199ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:01.262948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.242365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:48:01.263311Z","caller":"traceutil/trace.go:171","msg":"trace[725666628] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"397.941648ms","start":"2026-07-16T06:48:00.865321Z","end":"2026-07-16T06:48:01.263262Z","steps":["trace[725666628] 'process raft request' (duration: 124.519733ms)","trace[725666628] 'compare' (duration: 272.095262ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:48:01.263446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:00.865312Z","time spent":"398.068911ms","remote":"127.0.0.1:51392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:48:01.263516Z","caller":"traceutil/trace.go:171","msg":"trace[159017015] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"388.551285ms","start":"2026-07-16T06:48:00.874937Z","end":"2026-07-16T06:48:01.263488Z","steps":["trace[159017015] 'process raft request' (duration: 388.298809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:01.263692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:00.874907Z","time spent":"388.71139ms","remote":"127.0.0.1:51430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1934,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:48:01.263819Z","caller":"traceutil/trace.go:171","msg":"trace[866034379] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"389.615533ms","start":"2026-07-16T06:48:00.87379Z","end":"2026-07-16T06:48:01.263406Z","steps":["trace[866034379] 'process raft request' (duration: 389.342076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:01.264491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:00.873765Z","time spent":"390.282531ms","remote":"127.0.0.1:51492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4183,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:48:01.528014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.887744ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:48:01.528276Z","caller":"traceutil/trace.go:171","msg":"trace[2001437510] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"257.817156ms","start":"2026-07-16T06:48:01.270439Z","end":"2026-07-16T06:48:01.528256Z","steps":["trace[2001437510] 'process raft request' (duration: 257.771935ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:01.528275Z","caller":"traceutil/trace.go:171","msg":"trace[376038748] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"261.010157ms","start":"2026-07-16T06:48:01.267209Z","end":"2026-07-16T06:48:01.528219Z","steps":["trace[376038748] 'process raft request' (duration: 113.852017ms)","trace[376038748] 'compare' (duration: 146.75735ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:48:01.528402Z","caller":"traceutil/trace.go:171","msg":"trace[1298612015] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"260.248818ms","start":"2026-07-16T06:48:01.268118Z","end":"2026-07-16T06:48:01.528367Z","steps":["trace[1298612015] 'process raft request' (duration: 259.993651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:32.926831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.744174ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:48:32.926933Z","caller":"traceutil/trace.go:171","msg":"trace[1189667489] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"225.776187ms","start":"2026-07-16T06:48:32.701142Z","end":"2026-07-16T06:48:32.926918Z","steps":["trace[1189667489] 'process raft request' (duration: 51.890572ms)","trace[1189667489] 'compare' (duration: 173.531199ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:48:32.933958Z","caller":"traceutil/trace.go:171","msg":"trace[398486919] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"225.220899ms","start":"2026-07-16T06:48:32.708704Z","end":"2026-07-16T06:48:32.933925Z","steps":["trace[398486919] 'process raft request' (duration: 224.781171ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:32.934411Z","caller":"traceutil/trace.go:171","msg":"trace[942523277] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"205.571793ms","start":"2026-07-16T06:48:32.728827Z","end":"2026-07-16T06:48:32.934399Z","steps":["trace[942523277] 'process raft request' (duration: 205.004613ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:39.488449Z","caller":"traceutil/trace.go:171","msg":"trace[452761082] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"248.256195ms","start":"2026-07-16T06:48:39.240167Z","end":"2026-07-16T06:48:39.488424Z","steps":["trace[452761082] 'process raft request' (duration: 247.592223ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:39.492758Z","caller":"traceutil/trace.go:171","msg":"trace[254386352] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"176.79887ms","start":"2026-07-16T06:48:39.315942Z","end":"2026-07-16T06:48:39.492741Z","steps":["trace[254386352] 'process raft request' (duration: 176.714898ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:40.021025Z","caller":"traceutil/trace.go:171","msg":"trace[1064491286] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"226.190588ms","start":"2026-07-16T06:48:39.794808Z","end":"2026-07-16T06:48:40.020998Z","steps":["trace[1064491286] 'read index received' (duration: 218.438805ms)","trace[1064491286] 'applied index is now lower than readState.Index' (duration: 7.750603ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:48:40.021151Z","caller":"traceutil/trace.go:171","msg":"trace[1504731616] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"462.117707ms","start":"2026-07-16T06:48:39.559Z","end":"2026-07-16T06:48:40.021118Z","steps":["trace[1504731616] 'process raft request' (duration: 454.239322ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:40.021289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:39.558959Z","time spent":"462.26451ms","remote":"127.0.0.1:51666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T06:48:40.021499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.571845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-16T06:48:40.021637Z","caller":"traceutil/trace.go:171","msg":"trace[484881740] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"226.860739ms","start":"2026-07-16T06:48:39.794757Z","end":"2026-07-16T06:48:40.021618Z","steps":["trace[484881740] 'agreement among raft nodes before linearized reading' (duration: 226.424582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:40.021839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.143087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-x6gbb\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-16T06:48:40.021978Z","caller":"traceutil/trace.go:171","msg":"trace[540627904] range","detail":"{range_begin:/registry/pods/kube-system/cilium-x6gbb; range_end:; response_count:1; response_revision:632; }","duration":"226.339501ms","start":"2026-07-16T06:48:39.79561Z","end":"2026-07-16T06:48:40.021949Z","steps":["trace[540627904] 'agreement among raft nodes before linearized reading' (duration: 225.662269ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:14.889252Z","caller":"traceutil/trace.go:171","msg":"trace[1790045997] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"269.761742ms","start":"2026-07-16T06:50:14.619441Z","end":"2026-07-16T06:50:14.889202Z","steps":["trace[1790045997] 'process raft request' (duration: 269.455249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:14.88956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.257117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-16T06:50:14.889731Z","caller":"traceutil/trace.go:171","msg":"trace[1982285397] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1887; }","duration":"267.51063ms","start":"2026-07-16T06:50:14.622198Z","end":"2026-07-16T06:50:14.889709Z","steps":["trace[1982285397] 'agreement among raft nodes before linearized reading' (duration: 267.091566ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:14.889159Z","caller":"traceutil/trace.go:171","msg":"trace[578964024] linearizableReadLoop","detail":"{readStateIndex:1955; appliedIndex:1954; }","duration":"266.850783ms","start":"2026-07-16T06:50:14.622272Z","end":"2026-07-16T06:50:14.889123Z","steps":["trace[578964024] 'read index received' (duration: 266.618501ms)","trace[578964024] 'applied index is now lower than readState.Index' (duration: 230.302µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:50:15.0519Z","caller":"traceutil/trace.go:171","msg":"trace[864487359] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"348.000585ms","start":"2026-07-16T06:50:14.703826Z","end":"2026-07-16T06:50:15.051827Z","steps":["trace[864487359] 'process raft request' (duration: 347.691432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:15.052319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:50:14.703808Z","time spent":"348.218337ms","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:50:15.297561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.790836ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-16T06:50:15.298094Z","caller":"traceutil/trace.go:171","msg":"trace[1157710366] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"280.031554ms","start":"2026-07-16T06:50:15.018033Z","end":"2026-07-16T06:50:15.298064Z","steps":["trace[1157710366] 'process raft request' (duration: 279.959304ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:15.298074Z","caller":"traceutil/trace.go:171","msg":"trace[936612221] linearizableReadLoop","detail":"{readStateIndex:1957; appliedIndex:1955; }","duration":"407.712639ms","start":"2026-07-16T06:50:14.890316Z","end":"2026-07-16T06:50:15.298029Z","steps":["trace[936612221] 'read index received' (duration: 161.204396ms)","trace[936612221] 'applied index is now lower than readState.Index' (duration: 246.504393ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:50:15.298442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.76365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-172aa3aa-b3ea-4365-8457-143db3c6a210\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-16T06:50:15.298495Z","caller":"traceutil/trace.go:171","msg":"trace[290794812] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-172aa3aa-b3ea-4365-8457-143db3c6a210; range_end:; response_count:1; response_revision:1890; }","duration":"607.891081ms","start":"2026-07-16T06:50:14.690592Z","end":"2026-07-16T06:50:15.298483Z","steps":["trace[290794812] 'agreement among raft nodes before linearized reading' (duration: 607.579448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:15.298532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:50:14.690565Z","time spent":"607.958492ms","remote":"127.0.0.1:51500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-172aa3aa-b3ea-4365-8457-143db3c6a210\" "} {"level":"info","ts":"2026-07-16T06:50:15.298553Z","caller":"traceutil/trace.go:171","msg":"trace[1511824294] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"404.183022ms","start":"2026-07-16T06:50:14.894322Z","end":"2026-07-16T06:50:15.298505Z","steps":["trace[1511824294] 'process raft request' (duration: 403.381805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:15.298666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.587191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-16T06:50:15.298801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:50:14.894298Z","time spent":"404.349915ms","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:50:15.298895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.239544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:50:15.298813Z","caller":"traceutil/trace.go:171","msg":"trace[774953913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1890; }","duration":"368.790413ms","start":"2026-07-16T06:50:14.929996Z","end":"2026-07-16T06:50:15.298787Z","steps":["trace[774953913] 'agreement among raft nodes before linearized reading' (duration: 368.515611ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:15.29894Z","caller":"traceutil/trace.go:171","msg":"trace[988453343] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1890; }","duration":"223.328776ms","start":"2026-07-16T06:50:15.075601Z","end":"2026-07-16T06:50:15.29893Z","steps":["trace[988453343] 'agreement among raft nodes before linearized reading' (duration: 223.245905ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:15.298968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:50:14.929972Z","time spent":"368.982105ms","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-16T06:50:15.299138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.428842ms","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-16T06:50:15.29921Z","caller":"traceutil/trace.go:171","msg":"trace[326946184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1890; }","duration":"249.549843ms","start":"2026-07-16T06:50:15.049643Z","end":"2026-07-16T06:50:15.299193Z","steps":["trace[326946184] 'agreement among raft nodes before linearized reading' (duration: 249.335361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:15.299871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.806968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T06:50:15.299972Z","caller":"traceutil/trace.go:171","msg":"trace[1138528640] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1890; }","duration":"158.840058ms","start":"2026-07-16T06:50:15.141108Z","end":"2026-07-16T06:50:15.299948Z","steps":["trace[1138528640] 'agreement among raft nodes before linearized reading' (duration: 157.718997ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:49.661804Z","caller":"traceutil/trace.go:171","msg":"trace[1202922972] linearizableReadLoop","detail":"{readStateIndex:2203; appliedIndex:2202; }","duration":"148.124151ms","start":"2026-07-16T06:50:49.513648Z","end":"2026-07-16T06:50:49.661773Z","steps":["trace[1202922972] 'read index received' (duration: 147.702888ms)","trace[1202922972] 'applied index is now lower than readState.Index' (duration: 420.393µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:50:49.661927Z","caller":"traceutil/trace.go:171","msg":"trace[1601547476] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"170.862285ms","start":"2026-07-16T06:50:49.491022Z","end":"2026-07-16T06:50:49.661884Z","steps":["trace[1601547476] 'process raft request' (duration: 170.378661ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:49.662143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.505754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-16T06:50:49.662205Z","caller":"traceutil/trace.go:171","msg":"trace[1709619484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2127; }","duration":"178.571225ms","start":"2026-07-16T06:50:49.483619Z","end":"2026-07-16T06:50:49.66219Z","steps":["trace[1709619484] 'agreement among raft nodes before linearized reading' (duration: 178.286112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:49.662129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.424292ms","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-16T06:50:49.662353Z","caller":"traceutil/trace.go:171","msg":"trace[782058311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2127; }","duration":"179.677235ms","start":"2026-07-16T06:50:49.48262Z","end":"2026-07-16T06:50:49.662297Z","steps":["trace[782058311] 'agreement among raft nodes before linearized reading' (duration: 179.387262ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:49.670221Z","caller":"traceutil/trace.go:171","msg":"trace[337220536] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"151.670653ms","start":"2026-07-16T06:50:49.518528Z","end":"2026-07-16T06:50:49.670199Z","steps":["trace[337220536] 'process raft request' (duration: 151.486241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:49.998821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.668247ms","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-16T06:50:49.99909Z","caller":"traceutil/trace.go:171","msg":"trace[1907556166] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2130; }","duration":"158.04512ms","start":"2026-07-16T06:50:49.841006Z","end":"2026-07-16T06:50:49.999051Z","steps":["trace[1907556166] 'range keys from in-memory index tree' (duration: 157.335334ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:50.144558Z","caller":"traceutil/trace.go:171","msg":"trace[634402233] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"126.772326ms","start":"2026-07-16T06:50:50.017763Z","end":"2026-07-16T06:50:50.144535Z","steps":["trace[634402233] 'process raft request' (duration: 126.386333ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:50.458162Z","caller":"traceutil/trace.go:171","msg":"trace[507901522] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"272.898397ms","start":"2026-07-16T06:50:50.185219Z","end":"2026-07-16T06:50:50.458117Z","steps":["trace[507901522] 'process raft request' (duration: 272.618205ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:54.187037Z","caller":"traceutil/trace.go:171","msg":"trace[1798909216] transaction","detail":"{read_only:false; response_revision:2527; number_of_response:1; }","duration":"161.285109ms","start":"2026-07-16T06:51:54.02571Z","end":"2026-07-16T06:51:54.186995Z","steps":["trace[1798909216] 'process raft request' (duration: 161.075274ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:00.929318Z","caller":"traceutil/trace.go:171","msg":"trace[1580462429] linearizableReadLoop","detail":"{readStateIndex:2702; appliedIndex:2701; }","duration":"117.15234ms","start":"2026-07-16T06:52:00.812141Z","end":"2026-07-16T06:52:00.929294Z","steps":["trace[1580462429] 'read index received' (duration: 116.992197ms)","trace[1580462429] 'applied index is now lower than readState.Index' (duration: 158.993µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:52:00.929429Z","caller":"traceutil/trace.go:171","msg":"trace[873584660] transaction","detail":"{read_only:false; response_revision:2610; number_of_response:1; }","duration":"133.354692ms","start":"2026-07-16T06:52:00.796038Z","end":"2026-07-16T06:52:00.929393Z","steps":["trace[873584660] 'process raft request' (duration: 133.097866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:00.929553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.382685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-16T06:52:00.929596Z","caller":"traceutil/trace.go:171","msg":"trace[1544609843] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2610; }","duration":"117.446257ms","start":"2026-07-16T06:52:00.812136Z","end":"2026-07-16T06:52:00.929583Z","steps":["trace[1544609843] 'agreement among raft nodes before linearized reading' (duration: 117.259433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:28.281331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.803144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-16T06:52:28.281411Z","caller":"traceutil/trace.go:171","msg":"trace[1148606687] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2762; }","duration":"110.932707ms","start":"2026-07-16T06:52:28.170461Z","end":"2026-07-16T06:52:28.281394Z","steps":["trace[1148606687] 'range keys from in-memory index tree' (duration: 110.638742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:34.45493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.263801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:52:34.455059Z","caller":"traceutil/trace.go:171","msg":"trace[2065505679] linearizableReadLoop","detail":"{readStateIndex:2894; appliedIndex:2893; }","duration":"376.802007ms","start":"2026-07-16T06:52:34.078241Z","end":"2026-07-16T06:52:34.455043Z","steps":["trace[2065505679] 'read index received' (duration: 4.292851ms)","trace[2065505679] 'applied index is now lower than readState.Index' (duration: 372.508056ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:52:34.455114Z","caller":"traceutil/trace.go:171","msg":"trace[1152742876] transaction","detail":"{read_only:false; response_revision:2795; number_of_response:1; }","duration":"401.894462ms","start":"2026-07-16T06:52:34.053206Z","end":"2026-07-16T06:52:34.455101Z","steps":["trace[1152742876] 'process raft request' (duration: 29.342225ms)","trace[1152742876] 'compare' (duration: 371.996517ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:52:34.455166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.9222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T06:52:34.455172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:52:34.053176Z","time spent":"401.971964ms","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:52:34.455206Z","caller":"traceutil/trace.go:171","msg":"trace[2028205207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2795; }","duration":"376.999901ms","start":"2026-07-16T06:52:34.078193Z","end":"2026-07-16T06:52:34.455193Z","steps":["trace[2028205207] 'agreement among raft nodes before linearized reading' (duration: 376.931939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:34.455235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:52:34.07817Z","time spent":"377.060582ms","remote":"127.0.0.1:51300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T06:52:34.455238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.7167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c2b28ee0373409\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-07-16T06:52:34.455299Z","caller":"traceutil/trace.go:171","msg":"trace[493617297] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c2b28ee0373409; range_end:; response_count:1; response_revision:2795; }","duration":"344.798911ms","start":"2026-07-16T06:52:34.11049Z","end":"2026-07-16T06:52:34.455289Z","steps":["trace[493617297] 'agreement among raft nodes before linearized reading' (duration: 344.646809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:34.455304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.892377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-16T06:52:34.45534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:52:34.110478Z","time spent":"344.854002ms","remote":"127.0.0.1:51392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":803,"request content":"key:\"/registry/events/auth-system/keycloak-0.18c2b28ee0373409\" "} {"level":"warn","ts":"2026-07-16T06:52:34.455335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.093802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-16T06:52:34.455369Z","caller":"traceutil/trace.go:171","msg":"trace[1698604971] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2795; }","duration":"130.02488ms","start":"2026-07-16T06:52:34.325328Z","end":"2026-07-16T06:52:34.455353Z","steps":["trace[1698604971] 'agreement among raft nodes before linearized reading' (duration: 129.836036ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:34.455396Z","caller":"traceutil/trace.go:171","msg":"trace[1587275328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2795; }","duration":"110.225455ms","start":"2026-07-16T06:52:34.345155Z","end":"2026-07-16T06:52:34.45538Z","steps":["trace[1587275328] 'agreement among raft nodes before linearized reading' (duration: 110.004081ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:34.659134Z","caller":"traceutil/trace.go:171","msg":"trace[690101473] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"199.920592ms","start":"2026-07-16T06:52:34.459192Z","end":"2026-07-16T06:52:34.659113Z","steps":["trace[690101473] 'process raft request' (duration: 199.763879ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:34.659134Z","caller":"traceutil/trace.go:171","msg":"trace[355149744] transaction","detail":"{read_only:false; response_revision:2797; number_of_response:1; }","duration":"199.936872ms","start":"2026-07-16T06:52:34.459159Z","end":"2026-07-16T06:52:34.659095Z","steps":["trace[355149744] 'process raft request' (duration: 187.489356ms)","trace[355149744] 'compare' (duration: 12.218051ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:52:34.662577Z","caller":"traceutil/trace.go:171","msg":"trace[520192110] linearizableReadLoop","detail":"{readStateIndex:2898; appliedIndex:2895; }","duration":"182.731226ms","start":"2026-07-16T06:52:34.479831Z","end":"2026-07-16T06:52:34.662563Z","steps":["trace[520192110] 'read index received' (duration: 72.815188ms)","trace[520192110] 'applied index is now lower than readState.Index' (duration: 109.915398ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:52:34.662825Z","caller":"traceutil/trace.go:171","msg":"trace[727357817] transaction","detail":"{read_only:false; response_revision:2799; number_of_response:1; }","duration":"129.176573ms","start":"2026-07-16T06:52:34.533614Z","end":"2026-07-16T06:52:34.662791Z","steps":["trace[727357817] 'process raft request' (duration: 128.820197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:34.662906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.048642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-16T06:52:34.662969Z","caller":"traceutil/trace.go:171","msg":"trace[1795453408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2799; }","duration":"183.163294ms","start":"2026-07-16T06:52:34.479793Z","end":"2026-07-16T06:52:34.662956Z","steps":["trace[1795453408] 'agreement among raft nodes before linearized reading' (duration: 182.95509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:56:29.235182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.332372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1321"} {"level":"info","ts":"2026-07-16T06:56:29.235287Z","caller":"traceutil/trace.go:171","msg":"trace[324151005] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:4810; }","duration":"257.473243ms","start":"2026-07-16T06:56:28.9778Z","end":"2026-07-16T06:56:29.235273Z","steps":["trace[324151005] 'range keys from in-memory index tree' (duration: 257.150279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:56:29.235292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.598447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:56:29.235415Z","caller":"traceutil/trace.go:171","msg":"trace[428732612] linearizableReadLoop","detail":"{readStateIndex:4988; appliedIndex:4987; }","duration":"234.456633ms","start":"2026-07-16T06:56:29.000941Z","end":"2026-07-16T06:56:29.235398Z","steps":["trace[428732612] 'read index received' (duration: 52.585493ms)","trace[428732612] 'applied index is now lower than readState.Index' (duration: 181.87015ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:56:29.235515Z","caller":"traceutil/trace.go:171","msg":"trace[1700229886] transaction","detail":"{read_only:false; response_revision:4811; number_of_response:1; }","duration":"252.222956ms","start":"2026-07-16T06:56:28.983281Z","end":"2026-07-16T06:56:29.235504Z","steps":["trace[1700229886] 'process raft request' (duration: 70.301584ms)","trace[1700229886] 'compare' (duration: 181.495016ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:56:29.235853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.315676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:56:29.235895Z","caller":"traceutil/trace.go:171","msg":"trace[1588022203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4811; }","duration":"158.368067ms","start":"2026-07-16T06:56:29.077518Z","end":"2026-07-16T06:56:29.235886Z","steps":["trace[1588022203] 'agreement among raft nodes before linearized reading' (duration: 158.303186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:56:29.236164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.219463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-16T06:56:29.236203Z","caller":"traceutil/trace.go:171","msg":"trace[476669635] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4811; }","duration":"235.291574ms","start":"2026-07-16T06:56:29.000903Z","end":"2026-07-16T06:56:29.236195Z","steps":["trace[476669635] 'agreement among raft nodes before linearized reading' (duration: 235.159833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:56:29.236407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.723636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206973"} {"level":"info","ts":"2026-07-16T06:56:29.236466Z","caller":"traceutil/trace.go:171","msg":"trace[50839411] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4811; }","duration":"171.807427ms","start":"2026-07-16T06:56:29.064648Z","end":"2026-07-16T06:56:29.236456Z","steps":["trace[50839411] 'agreement among raft nodes before linearized reading' (duration: 170.985958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:56:29.236753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.432169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-07-16T06:56:29.236847Z","caller":"traceutil/trace.go:171","msg":"trace[908029473] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4811; }","duration":"160.555731ms","start":"2026-07-16T06:56:29.076274Z","end":"2026-07-16T06:56:29.23683Z","steps":["trace[908029473] 'agreement among raft nodes before linearized reading' (duration: 159.995655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:56:30.003804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.88462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-390127ae-5c49-4076-8a88-4a7181a8aeb1\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-16T06:56:30.003987Z","caller":"traceutil/trace.go:171","msg":"trace[1895662681] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-390127ae-5c49-4076-8a88-4a7181a8aeb1; range_end:; response_count:1; response_revision:4832; }","duration":"134.111323ms","start":"2026-07-16T06:56:29.869853Z","end":"2026-07-16T06:56:30.003964Z","steps":["trace[1895662681] 'range keys from in-memory index tree' (duration: 133.716768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:56:30.00417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.751463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206973"} {"level":"info","ts":"2026-07-16T06:56:30.004217Z","caller":"traceutil/trace.go:171","msg":"trace[298767969] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4832; }","duration":"191.822824ms","start":"2026-07-16T06:56:29.812385Z","end":"2026-07-16T06:56:30.004208Z","steps":["trace[298767969] 'range keys from in-memory index tree' (duration: 191.153397ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:56:30.16398Z","caller":"traceutil/trace.go:171","msg":"trace[172880721] linearizableReadLoop","detail":"{readStateIndex:5012; appliedIndex:5010; }","duration":"130.605182ms","start":"2026-07-16T06:56:30.033346Z","end":"2026-07-16T06:56:30.163951Z","steps":["trace[172880721] 'read index received' (duration: 29.682875ms)","trace[172880721] 'applied index is now lower than readState.Index' (duration: 100.921027ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:56:30.164423Z","caller":"traceutil/trace.go:171","msg":"trace[1269958091] transaction","detail":"{read_only:false; response_revision:4834; number_of_response:1; }","duration":"155.28802ms","start":"2026-07-16T06:56:30.009104Z","end":"2026-07-16T06:56:30.164392Z","steps":["trace[1269958091] 'process raft request' (duration: 111.379194ms)","trace[1269958091] 'compare' (duration: 43.3617ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:56:30.165374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.006687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1825"} {"level":"info","ts":"2026-07-16T06:56:30.165517Z","caller":"traceutil/trace.go:171","msg":"trace[1633431065] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4834; }","duration":"132.19034ms","start":"2026-07-16T06:56:30.033306Z","end":"2026-07-16T06:56:30.165497Z","steps":["trace[1633431065] 'agreement among raft nodes before linearized reading' (duration: 131.131138ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:56:42.614916Z","caller":"traceutil/trace.go:171","msg":"trace[1387500923] transaction","detail":"{read_only:false; response_revision:5003; number_of_response:1; }","duration":"119.153537ms","start":"2026-07-16T06:56:42.495736Z","end":"2026-07-16T06:56:42.614889Z","steps":["trace[1387500923] 'process raft request' (duration: 82.056453ms)","trace[1387500923] 'compare' (duration: 36.76112ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:56:42.637518Z","caller":"traceutil/trace.go:171","msg":"trace[638572894] transaction","detail":"{read_only:false; response_revision:5004; number_of_response:1; }","duration":"141.577957ms","start":"2026-07-16T06:56:42.495902Z","end":"2026-07-16T06:56:42.63748Z","steps":["trace[638572894] 'process raft request' (duration: 141.219793ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:56:42.638454Z","caller":"traceutil/trace.go:171","msg":"trace[499793069] linearizableReadLoop","detail":"{readStateIndex:5186; appliedIndex:5184; }","duration":"142.348075ms","start":"2026-07-16T06:56:42.496088Z","end":"2026-07-16T06:56:42.638436Z","steps":["trace[499793069] 'read index received' (duration: 81.71609ms)","trace[499793069] 'applied index is now lower than readState.Index' (duration: 60.629105ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:56:42.638613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.370496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:56:42.638643Z","caller":"traceutil/trace.go:171","msg":"trace[1741379575] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:5007; }","duration":"142.417477ms","start":"2026-07-16T06:56:42.496216Z","end":"2026-07-16T06:56:42.638633Z","steps":["trace[1741379575] 'agreement among raft nodes before linearized reading' (duration: 142.344816ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:56:42.638641Z","caller":"traceutil/trace.go:171","msg":"trace[629163476] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"133.975972ms","start":"2026-07-16T06:56:42.504615Z","end":"2026-07-16T06:56:42.638591Z","steps":["trace[629163476] 'process raft request' (duration: 133.73994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:56:42.6387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.580488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-16T06:56:42.63875Z","caller":"traceutil/trace.go:171","msg":"trace[513808944] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5007; }","duration":"142.653279ms","start":"2026-07-16T06:56:42.496082Z","end":"2026-07-16T06:56:42.638736Z","steps":["trace[513808944] 'agreement among raft nodes before linearized reading' (duration: 142.482277ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:56:42.638752Z","caller":"traceutil/trace.go:171","msg":"trace[1171454595] transaction","detail":"{read_only:false; response_revision:5006; number_of_response:1; }","duration":"141.87751ms","start":"2026-07-16T06:56:42.496836Z","end":"2026-07-16T06:56:42.638713Z","steps":["trace[1171454595] 'process raft request' (duration: 141.448346ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:56:42.639191Z","caller":"traceutil/trace.go:171","msg":"trace[147772568] transaction","detail":"{read_only:false; response_revision:5005; number_of_response:1; }","duration":"142.922352ms","start":"2026-07-16T06:56:42.496254Z","end":"2026-07-16T06:56:42.639176Z","steps":["trace[147772568] 'process raft request' (duration: 141.069883ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:57:22.339485Z","caller":"traceutil/trace.go:171","msg":"trace[102302814] transaction","detail":"{read_only:false; response_revision:5346; number_of_response:1; }","duration":"112.191251ms","start":"2026-07-16T06:57:22.227267Z","end":"2026-07-16T06:57:22.339458Z","steps":["trace[102302814] 'process raft request' (duration: 112.014659ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:57:22.339805Z","caller":"traceutil/trace.go:171","msg":"trace[1149998366] linearizableReadLoop","detail":"{readStateIndex:5537; appliedIndex:5537; }","duration":"101.518165ms","start":"2026-07-16T06:57:22.238268Z","end":"2026-07-16T06:57:22.339786Z","steps":["trace[1149998366] 'read index received' (duration: 101.510885ms)","trace[1149998366] 'applied index is now lower than readState.Index' (duration: 5.68µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:57:22.341038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.757919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206973"} {"level":"info","ts":"2026-07-16T06:57:22.341128Z","caller":"traceutil/trace.go:171","msg":"trace[660867909] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5346; }","duration":"102.82822ms","start":"2026-07-16T06:57:22.238249Z","end":"2026-07-16T06:57:22.341077Z","steps":["trace[660867909] 'agreement among raft nodes before linearized reading' (duration: 101.600746ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:57:56.057235Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2911} {"level":"info","ts":"2026-07-16T06:57:56.188291Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2911,"took":"129.476898ms","hash":612812937,"current-db-size-bytes":54292480,"current-db-size":"54 MB","current-db-size-in-use-bytes":29376512,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-16T06:57:56.188343Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":612812937,"revision":2911,"compact-revision":-1} {"level":"info","ts":"2026-07-16T07:02:56.064504Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5605} {"level":"info","ts":"2026-07-16T07:02:56.159818Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5605,"took":"94.127974ms","hash":1607187701,"current-db-size-bytes":54292480,"current-db-size":"54 MB","current-db-size-in-use-bytes":18366464,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-16T07:02:56.159891Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1607187701,"revision":5605,"compact-revision":2911}