{"level":"warn","ts":"2026-04-02T21:24:08.480221Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T21:24:08.480353Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.202: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.202:2380","--initial-cluster=instance=https://199.19.213.202:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.202:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.202: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-04-02T21:24:08.480444Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T21:24:08.480455Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.202:2380"]} {"level":"info","ts":"2026-04-02T21:24:08.480481Z","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-04-02T21:24:08.481462Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.202:2379"]} {"level":"info","ts":"2026-04-02T21:24:08.481557Z","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":16,"max-cpu-available":16,"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.202:2380"],"listen-peer-urls":["https://199.19.213.202:2380"],"advertise-client-urls":["https://199.19.213.202:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.202:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.202: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-04-02T21:24:08.48547Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.642667ms"} {"level":"info","ts":"2026-04-02T21:24:08.491822Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c9b4049ccf4190cf","cluster-id":"2bc66bd50b26302c"} {"level":"info","ts":"2026-04-02T21:24:08.491919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T21:24:08.491948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf became follower at term 0"} {"level":"info","ts":"2026-04-02T21:24:08.491967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c9b4049ccf4190cf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T21:24:08.49198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf became follower at term 1"} {"level":"info","ts":"2026-04-02T21:24:08.492028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf switched to configuration voters=(14534246968970023119)"} {"level":"warn","ts":"2026-04-02T21:24:08.498358Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T21:24:08.499855Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T21:24:08.501443Z","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-04-02T21:24:08.503505Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c9b4049ccf4190cf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T21:24:08.503682Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c9b4049ccf4190cf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T21:24:08.503823Z","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-04-02T21:24:08.503937Z","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-04-02T21:24:08.503962Z","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-04-02T21:24:08.506097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf switched to configuration voters=(14534246968970023119)"} {"level":"info","ts":"2026-04-02T21:24:08.506225Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bc66bd50b26302c","local-member-id":"c9b4049ccf4190cf","added-peer-id":"c9b4049ccf4190cf","added-peer-peer-urls":["https://199.19.213.202:2380"]} {"level":"info","ts":"2026-04-02T21:24:08.509725Z","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-04-02T21:24:08.509819Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.202:2380"} {"level":"info","ts":"2026-04-02T21:24:08.509855Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.202:2380"} {"level":"info","ts":"2026-04-02T21:24:08.510312Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c9b4049ccf4190cf","initial-advertise-peer-urls":["https://199.19.213.202:2380"],"listen-peer-urls":["https://199.19.213.202:2380"],"advertise-client-urls":["https://199.19.213.202:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.202:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T21:24:08.510407Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T21:24:08.893257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T21:24:08.893331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T21:24:08.893389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf received MsgPreVoteResp from c9b4049ccf4190cf at term 1"} {"level":"info","ts":"2026-04-02T21:24:08.893411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf became candidate at term 2"} {"level":"info","ts":"2026-04-02T21:24:08.893423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf received MsgVoteResp from c9b4049ccf4190cf at term 2"} {"level":"info","ts":"2026-04-02T21:24:08.89344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf became leader at term 2"} {"level":"info","ts":"2026-04-02T21:24:08.893454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c9b4049ccf4190cf elected leader c9b4049ccf4190cf at term 2"} {"level":"info","ts":"2026-04-02T21:24:08.894452Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T21:24:08.895316Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c9b4049ccf4190cf","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.202:2379]}","request-path":"/0/members/c9b4049ccf4190cf/attributes","cluster-id":"2bc66bd50b26302c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T21:24:08.895351Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T21:24:08.89542Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T21:24:08.8959Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T21:24:08.895951Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T21:24:08.898942Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.202:2379"} {"level":"info","ts":"2026-04-02T21:24:08.898988Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T21:24:08.902053Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bc66bd50b26302c","local-member-id":"c9b4049ccf4190cf","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T21:24:08.902198Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T21:24:08.902294Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T21:24:28.093045Z","caller":"traceutil/trace.go:171","msg":"trace[1934306519] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"212.03121ms","start":"2026-04-02T21:24:27.880984Z","end":"2026-04-02T21:24:28.093016Z","steps":["trace[1934306519] 'read index received' (duration: 211.764045ms)","trace[1934306519] 'applied index is now lower than readState.Index' (duration: 266.265µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:24:28.093217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.199364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:24:28.093281Z","caller":"traceutil/trace.go:171","msg":"trace[2146918593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:412; }","duration":"212.304886ms","start":"2026-04-02T21:24:27.880966Z","end":"2026-04-02T21:24:28.093271Z","steps":["trace[2146918593] 'agreement among raft nodes before linearized reading' (duration: 212.186193ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:28.093305Z","caller":"traceutil/trace.go:171","msg":"trace[1148365526] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"212.591422ms","start":"2026-04-02T21:24:27.880647Z","end":"2026-04-02T21:24:28.093238Z","steps":["trace[1148365526] 'process raft request' (duration: 212.191564ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:28.375451Z","caller":"traceutil/trace.go:171","msg":"trace[732715080] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"267.351215ms","start":"2026-04-02T21:24:28.108068Z","end":"2026-04-02T21:24:28.375419Z","steps":["trace[732715080] 'process raft request' (duration: 266.596808ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:40.692791Z","caller":"traceutil/trace.go:171","msg":"trace[209128228] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"112.302053ms","start":"2026-04-02T21:24:40.580432Z","end":"2026-04-02T21:24:40.692734Z","steps":["trace[209128228] 'process raft request' (duration: 100.977963ms)","trace[209128228] 'compare' (duration: 11.064915ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:24:46.825364Z","caller":"traceutil/trace.go:171","msg":"trace[70777311] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"263.173431ms","start":"2026-04-02T21:24:46.562157Z","end":"2026-04-02T21:24:46.82533Z","steps":["trace[70777311] 'process raft request' (duration: 181.997891ms)","trace[70777311] 'compare' (duration: 80.818021ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:24:46.896425Z","caller":"traceutil/trace.go:171","msg":"trace[97953000] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"148.790531ms","start":"2026-04-02T21:24:46.747608Z","end":"2026-04-02T21:24:46.896399Z","steps":["trace[97953000] 'read index received' (duration: 147.851842ms)","trace[97953000] 'applied index is now lower than readState.Index' (duration: 937.779µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:24:46.896632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.014686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jfwp4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-02T21:24:46.896616Z","caller":"traceutil/trace.go:171","msg":"trace[2048560265] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"181.424228ms","start":"2026-04-02T21:24:46.715146Z","end":"2026-04-02T21:24:46.89657Z","steps":["trace[2048560265] 'process raft request' (duration: 180.584582ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:46.896676Z","caller":"traceutil/trace.go:171","msg":"trace[1346419488] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jfwp4; range_end:; response_count:1; response_revision:586; }","duration":"149.129448ms","start":"2026-04-02T21:24:46.747533Z","end":"2026-04-02T21:24:46.896663Z","steps":["trace[1346419488] 'agreement among raft nodes before linearized reading' (duration: 148.980315ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:47.060837Z","caller":"traceutil/trace.go:171","msg":"trace[916865055] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"145.100876ms","start":"2026-04-02T21:24:46.915686Z","end":"2026-04-02T21:24:47.060787Z","steps":["trace[916865055] 'process raft request' (duration: 144.787649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:47.061183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.726241ms","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-04-02T21:24:47.06117Z","caller":"traceutil/trace.go:171","msg":"trace[978898215] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"103.292232ms","start":"2026-04-02T21:24:46.957417Z","end":"2026-04-02T21:24:47.060709Z","steps":["trace[978898215] 'read index received' (duration: 103.107828ms)","trace[978898215] 'applied index is now lower than readState.Index' (duration: 183.324µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:24:47.061295Z","caller":"traceutil/trace.go:171","msg":"trace[1428944324] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:587; }","duration":"103.906344ms","start":"2026-04-02T21:24:46.957362Z","end":"2026-04-02T21:24:47.061269Z","steps":["trace[1428944324] 'agreement among raft nodes before linearized reading' (duration: 103.557907ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:47.08785Z","caller":"traceutil/trace.go:171","msg":"trace[295701724] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"130.248002ms","start":"2026-04-02T21:24:46.957565Z","end":"2026-04-02T21:24:47.087813Z","steps":["trace[295701724] 'process raft request' (duration: 129.874735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:47.539026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.044411ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-02T21:24:47.539393Z","caller":"traceutil/trace.go:171","msg":"trace[1195009008] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:611; }","duration":"446.623421ms","start":"2026-04-02T21:24:47.092748Z","end":"2026-04-02T21:24:47.539371Z","steps":["trace[1195009008] 'read index received' (duration: 118.689436ms)","trace[1195009008] 'applied index is now lower than readState.Index' (duration: 327.932585ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:24:47.539519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.764193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-02T21:24:47.539491Z","caller":"traceutil/trace.go:171","msg":"trace[1920906441] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"473.855797ms","start":"2026-04-02T21:24:47.065585Z","end":"2026-04-02T21:24:47.539441Z","steps":["trace[1920906441] 'process raft request' (duration: 473.633623ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:47.539554Z","caller":"traceutil/trace.go:171","msg":"trace[1576118293] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:589; }","duration":"446.854916ms","start":"2026-04-02T21:24:47.092689Z","end":"2026-04-02T21:24:47.539544Z","steps":["trace[1576118293] 'agreement among raft nodes before linearized reading' (duration: 446.746613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:47.539706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:47.092664Z","time spent":"447.032069ms","remote":"127.0.0.1:47584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-02T21:24:47.540422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:47.06554Z","time spent":"474.058772ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T21:24:49.0114Z","caller":"traceutil/trace.go:171","msg":"trace[972775195] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"111.772535ms","start":"2026-04-02T21:24:48.899607Z","end":"2026-04-02T21:24:49.011379Z","steps":["trace[972775195] 'process raft request' (duration: 111.581011ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:25:31.506025Z","caller":"traceutil/trace.go:171","msg":"trace[503822089] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"129.303983ms","start":"2026-04-02T21:25:31.376691Z","end":"2026-04-02T21:25:31.505995Z","steps":["trace[503822089] 'process raft request' (duration: 129.1364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:25:51.27004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.047565ms","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-04-02T21:25:51.270161Z","caller":"traceutil/trace.go:171","msg":"trace[740003466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1393; }","duration":"130.217968ms","start":"2026-04-02T21:25:51.139912Z","end":"2026-04-02T21:25:51.27013Z","steps":["trace[740003466] 'range keys from in-memory index tree' (duration: 129.81862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:15.313077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.686049ms","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-04-02T21:26:15.31317Z","caller":"traceutil/trace.go:171","msg":"trace[1194248774] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1788; }","duration":"122.847993ms","start":"2026-04-02T21:26:15.190304Z","end":"2026-04-02T21:26:15.313152Z","steps":["trace[1194248774] 'range keys from in-memory index tree' (duration: 122.547237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:15.313075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.110242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-96ef4dc4-7535-48bb-a217-2032697f7250\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-02T21:26:15.313264Z","caller":"traceutil/trace.go:171","msg":"trace[122874097] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-96ef4dc4-7535-48bb-a217-2032697f7250; range_end:; response_count:1; response_revision:1788; }","duration":"118.345947ms","start":"2026-04-02T21:26:15.194903Z","end":"2026-04-02T21:26:15.313249Z","steps":["trace[122874097] 'range keys from in-memory index tree' (duration: 117.939588ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:26:15.505455Z","caller":"traceutil/trace.go:171","msg":"trace[807458773] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"187.700112ms","start":"2026-04-02T21:26:15.317728Z","end":"2026-04-02T21:26:15.505429Z","steps":["trace[807458773] 'process raft request' (duration: 187.57033ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:26:15.756449Z","caller":"traceutil/trace.go:171","msg":"trace[2015218987] linearizableReadLoop","detail":"{readStateIndex:1847; appliedIndex:1846; }","duration":"429.439773ms","start":"2026-04-02T21:26:15.326993Z","end":"2026-04-02T21:26:15.756433Z","steps":["trace[2015218987] 'read index received' (duration: 178.445926ms)","trace[2015218987] 'applied index is now lower than readState.Index' (duration: 250.993167ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:26:15.756532Z","caller":"traceutil/trace.go:171","msg":"trace[1444274152] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"436.956203ms","start":"2026-04-02T21:26:15.319559Z","end":"2026-04-02T21:26:15.756516Z","steps":["trace[1444274152] 'process raft request' (duration: 436.751258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:15.756618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:15.319536Z","time spent":"437.019124ms","remote":"127.0.0.1:47570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:26:15.756956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.944503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-04-02T21:26:15.757064Z","caller":"traceutil/trace.go:171","msg":"trace[529534855] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1790; }","duration":"430.058075ms","start":"2026-04-02T21:26:15.326972Z","end":"2026-04-02T21:26:15.75703Z","steps":["trace[529534855] 'agreement among raft nodes before linearized reading' (duration: 429.580715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:15.757107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:15.326963Z","time spent":"430.133907ms","remote":"127.0.0.1:47488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":682,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-04-02T21:26:15.811771Z","caller":"traceutil/trace.go:171","msg":"trace[577102034] transaction","detail":"{read_only:false; response_revision:1791; number_of_response:1; }","duration":"148.271193ms","start":"2026-04-02T21:26:15.66346Z","end":"2026-04-02T21:26:15.811732Z","steps":["trace[577102034] 'process raft request' (duration: 147.959077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:15.812018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.701565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-96ef4dc4-7535-48bb-a217-2032697f7250\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-04-02T21:26:15.812085Z","caller":"traceutil/trace.go:171","msg":"trace[252385617] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-96ef4dc4-7535-48bb-a217-2032697f7250; range_end:; response_count:1; response_revision:1792; }","duration":"134.792216ms","start":"2026-04-02T21:26:15.677274Z","end":"2026-04-02T21:26:15.812067Z","steps":["trace[252385617] 'agreement among raft nodes before linearized reading' (duration: 134.647283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:15.812137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.860142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-04-02T21:26:15.812197Z","caller":"traceutil/trace.go:171","msg":"trace[1404548248] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1792; }","duration":"193.964485ms","start":"2026-04-02T21:26:15.618216Z","end":"2026-04-02T21:26:15.81218Z","steps":["trace[1404548248] 'agreement among raft nodes before linearized reading' (duration: 193.713729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:15.812351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.898839ms","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-04-02T21:26:15.812624Z","caller":"traceutil/trace.go:171","msg":"trace[1800462064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1792; }","duration":"402.96544ms","start":"2026-04-02T21:26:15.409416Z","end":"2026-04-02T21:26:15.812381Z","steps":["trace[1800462064] 'agreement among raft nodes before linearized reading' (duration: 402.832077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:15.812684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:15.409382Z","time spent":"403.289917ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-02T21:26:25.191466Z","caller":"traceutil/trace.go:171","msg":"trace[766043276] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"120.636466ms","start":"2026-04-02T21:26:25.070791Z","end":"2026-04-02T21:26:25.191428Z","steps":["trace[766043276] 'process raft request' (duration: 120.116995ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:26:57.503467Z","caller":"traceutil/trace.go:171","msg":"trace[1841993783] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"133.984987ms","start":"2026-04-02T21:26:57.369439Z","end":"2026-04-02T21:26:57.503423Z","steps":["trace[1841993783] 'process raft request' (duration: 116.404343ms)","trace[1841993783] 'compare' (duration: 17.267357ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:27:37.614982Z","caller":"traceutil/trace.go:171","msg":"trace[1946623570] linearizableReadLoop","detail":"{readStateIndex:2542; appliedIndex:2541; }","duration":"109.830284ms","start":"2026-04-02T21:27:37.505127Z","end":"2026-04-02T21:27:37.614957Z","steps":["trace[1946623570] 'read index received' (duration: 109.595259ms)","trace[1946623570] 'applied index is now lower than readState.Index' (duration: 234.185µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:27:37.615095Z","caller":"traceutil/trace.go:171","msg":"trace[1282582462] transaction","detail":"{read_only:false; response_revision:2450; number_of_response:1; }","duration":"143.380707ms","start":"2026-04-02T21:27:37.47168Z","end":"2026-04-02T21:27:37.615061Z","steps":["trace[1282582462] 'process raft request' (duration: 143.123612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:27:37.615286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.15244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-04-02T21:27:37.615332Z","caller":"traceutil/trace.go:171","msg":"trace[319108261] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2450; }","duration":"110.264832ms","start":"2026-04-02T21:27:37.505055Z","end":"2026-04-02T21:27:37.61532Z","steps":["trace[319108261] 'agreement among raft nodes before linearized reading' (duration: 110.061328ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:28:10.407348Z","caller":"traceutil/trace.go:171","msg":"trace[147069117] transaction","detail":"{read_only:false; response_revision:2679; number_of_response:1; }","duration":"161.778784ms","start":"2026-04-02T21:28:10.245545Z","end":"2026-04-02T21:28:10.407324Z","steps":["trace[147069117] 'process raft request' (duration: 161.58537ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:29:12.802151Z","caller":"traceutil/trace.go:171","msg":"trace[1011432762] transaction","detail":"{read_only:false; response_revision:3272; number_of_response:1; }","duration":"163.708309ms","start":"2026-04-02T21:29:12.63842Z","end":"2026-04-02T21:29:12.802129Z","steps":["trace[1011432762] 'process raft request' (duration: 120.496096ms)","trace[1011432762] 'compare' (duration: 42.962298ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:30:25.22701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.643199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d7796e68-8225-403a-a4e0-2049290c8b7c\" ","response":"range_response_count:1 size:3880"} {"level":"info","ts":"2026-04-02T21:30:25.227678Z","caller":"traceutil/trace.go:171","msg":"trace[1462820423] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d7796e68-8225-403a-a4e0-2049290c8b7c; range_end:; response_count:1; response_revision:4291; }","duration":"114.346654ms","start":"2026-04-02T21:30:25.113307Z","end":"2026-04-02T21:30:25.227653Z","steps":["trace[1462820423] 'range keys from in-memory index tree' (duration: 113.535697ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:30:30.108375Z","caller":"traceutil/trace.go:171","msg":"trace[539337476] transaction","detail":"{read_only:false; response_revision:4459; number_of_response:1; }","duration":"129.949537ms","start":"2026-04-02T21:30:29.978381Z","end":"2026-04-02T21:30:30.108331Z","steps":["trace[539337476] 'process raft request' (duration: 129.785824ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:30:34.643057Z","caller":"traceutil/trace.go:171","msg":"trace[1801706522] transaction","detail":"{read_only:false; response_revision:4555; number_of_response:1; }","duration":"161.755273ms","start":"2026-04-02T21:30:34.481276Z","end":"2026-04-02T21:30:34.643031Z","steps":["trace[1801706522] 'process raft request' (duration: 161.602449ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:30:45.028876Z","caller":"traceutil/trace.go:171","msg":"trace[1775239309] transaction","detail":"{read_only:false; response_revision:4731; number_of_response:1; }","duration":"131.867427ms","start":"2026-04-02T21:30:44.896976Z","end":"2026-04-02T21:30:45.028843Z","steps":["trace[1775239309] 'process raft request' (duration: 76.59652ms)","trace[1775239309] 'compare' (duration: 55.003331ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:30:45.607081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.767396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T21:30:45.607388Z","caller":"traceutil/trace.go:171","msg":"trace[1303572586] transaction","detail":"{read_only:false; response_revision:4738; number_of_response:1; }","duration":"463.786982ms","start":"2026-04-02T21:30:45.143575Z","end":"2026-04-02T21:30:45.607362Z","steps":["trace[1303572586] 'process raft request' (duration: 463.66758ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:30:45.607361Z","caller":"traceutil/trace.go:171","msg":"trace[1090735603] transaction","detail":"{read_only:false; response_revision:4737; number_of_response:1; }","duration":"506.569703ms","start":"2026-04-02T21:30:45.100678Z","end":"2026-04-02T21:30:45.607248Z","steps":["trace[1090735603] 'process raft request' (duration: 46.556911ms)","trace[1090735603] 'compare' (duration: 459.185554ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:30:45.607507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:30:45.143549Z","time spent":"463.899434ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:30:45.607643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:30:45.100637Z","time spent":"506.889318ms","remote":"127.0.0.1:47550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":524,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T21:30:45.607876Z","caller":"traceutil/trace.go:171","msg":"trace[226696924] linearizableReadLoop","detail":"{readStateIndex:4939; appliedIndex:4938; }","duration":"496.189542ms","start":"2026-04-02T21:30:45.111324Z","end":"2026-04-02T21:30:45.607514Z","steps":["trace[226696924] 'read index received' (duration: 18.354171ms)","trace[226696924] 'applied index is now lower than readState.Index' (duration: 477.831891ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:30:45.608094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.754485ms","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-04-02T21:30:45.608157Z","caller":"traceutil/trace.go:171","msg":"trace[1116347664] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4738; }","duration":"496.871817ms","start":"2026-04-02T21:30:45.11127Z","end":"2026-04-02T21:30:45.608142Z","steps":["trace[1116347664] 'agreement among raft nodes before linearized reading' (duration: 496.668123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:30:45.608166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.602335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T21:30:45.608195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:30:45.111247Z","time spent":"496.938408ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-02T21:30:45.608247Z","caller":"traceutil/trace.go:171","msg":"trace[165701919] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:4738; }","duration":"444.795608ms","start":"2026-04-02T21:30:45.16343Z","end":"2026-04-02T21:30:45.608225Z","steps":["trace[165701919] 'agreement among raft nodes before linearized reading' (duration: 444.574393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:30:45.608316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:30:45.16341Z","time spent":"444.886611ms","remote":"127.0.0.1:55524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-04-02T21:30:45.608661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.962796ms","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-04-02T21:30:45.608757Z","caller":"traceutil/trace.go:171","msg":"trace[585471407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4738; }","duration":"246.117669ms","start":"2026-04-02T21:30:45.362613Z","end":"2026-04-02T21:30:45.60873Z","steps":["trace[585471407] 'agreement among raft nodes before linearized reading' (duration: 245.771611ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:30:47.720144Z","caller":"traceutil/trace.go:171","msg":"trace[2088311783] linearizableReadLoop","detail":"{readStateIndex:4978; appliedIndex:4977; }","duration":"100.665223ms","start":"2026-04-02T21:30:47.619444Z","end":"2026-04-02T21:30:47.720109Z","steps":["trace[2088311783] 'read index received' (duration: 100.358305ms)","trace[2088311783] 'applied index is now lower than readState.Index' (duration: 305.468µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:30:47.720347Z","caller":"traceutil/trace.go:171","msg":"trace[894649543] transaction","detail":"{read_only:false; response_revision:4775; number_of_response:1; }","duration":"104.198639ms","start":"2026-04-02T21:30:47.616124Z","end":"2026-04-02T21:30:47.720323Z","steps":["trace[894649543] 'process raft request' (duration: 103.722828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:30:47.720775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.296846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/monitoring/loki-headless-l72mh\" ","response":"range_response_count:1 size:1457"} {"level":"info","ts":"2026-04-02T21:30:47.720892Z","caller":"traceutil/trace.go:171","msg":"trace[315489229] range","detail":"{range_begin:/registry/endpointslices/monitoring/loki-headless-l72mh; range_end:; response_count:1; response_revision:4775; }","duration":"101.49106ms","start":"2026-04-02T21:30:47.619374Z","end":"2026-04-02T21:30:47.720865Z","steps":["trace[315489229] 'agreement among raft nodes before linearized reading' (duration: 101.110872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:30:53.475575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.93741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T21:30:53.475694Z","caller":"traceutil/trace.go:171","msg":"trace[980728249] linearizableReadLoop","detail":"{readStateIndex:5125; appliedIndex:5124; }","duration":"319.956781ms","start":"2026-04-02T21:30:53.155718Z","end":"2026-04-02T21:30:53.475675Z","steps":["trace[980728249] 'read index received' (duration: 209.770676ms)","trace[980728249] 'applied index is now lower than readState.Index' (duration: 110.184795ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:30:53.475793Z","caller":"traceutil/trace.go:171","msg":"trace[2116065448] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"322.587317ms","start":"2026-04-02T21:30:53.153193Z","end":"2026-04-02T21:30:53.47578Z","steps":["trace[2116065448] 'process raft request' (duration: 212.355191ms)","trace[2116065448] 'compare' (duration: 109.647144ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:30:53.475857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:30:53.153179Z","time spent":"322.646028ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":551,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:30:53.475959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.764922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d7dcdd57-8185-49fe-82a5-fb318bd750f0\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-04-02T21:30:53.47596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.200986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-04-02T21:30:53.476041Z","caller":"traceutil/trace.go:171","msg":"trace[1151269545] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d7dcdd57-8185-49fe-82a5-fb318bd750f0; range_end:; response_count:1; response_revision:4916; }","duration":"269.921785ms","start":"2026-04-02T21:30:53.206102Z","end":"2026-04-02T21:30:53.476023Z","steps":["trace[1151269545] 'agreement among raft nodes before linearized reading' (duration: 269.70059ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:30:53.476058Z","caller":"traceutil/trace.go:171","msg":"trace[461992844] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4916; }","duration":"320.355529ms","start":"2026-04-02T21:30:53.155679Z","end":"2026-04-02T21:30:53.476035Z","steps":["trace[461992844] 'agreement among raft nodes before linearized reading' (duration: 320.126214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:30:53.476112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:30:53.155664Z","time spent":"320.438851ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":575,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-02T21:30:55.89409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.10916ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T21:30:55.894362Z","caller":"traceutil/trace.go:171","msg":"trace[1500781556] transaction","detail":"{read_only:false; response_revision:4948; number_of_response:1; }","duration":"111.424362ms","start":"2026-04-02T21:30:55.782924Z","end":"2026-04-02T21:30:55.894349Z","steps":["trace[1500781556] 'process raft request' (duration: 111.396601ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:30:55.894479Z","caller":"traceutil/trace.go:171","msg":"trace[1721183983] transaction","detail":"{read_only:false; response_revision:4946; number_of_response:1; }","duration":"112.7281ms","start":"2026-04-02T21:30:55.781724Z","end":"2026-04-02T21:30:55.894452Z","steps":["trace[1721183983] 'process raft request' (duration: 112.476174ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:30:55.894542Z","caller":"traceutil/trace.go:171","msg":"trace[648104628] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"111.694238ms","start":"2026-04-02T21:30:55.782841Z","end":"2026-04-02T21:30:55.894535Z","steps":["trace[648104628] 'process raft request' (duration: 111.448143ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:30:55.894633Z","caller":"traceutil/trace.go:171","msg":"trace[1278509067] transaction","detail":"{read_only:false; response_revision:4945; number_of_response:1; }","duration":"113.757302ms","start":"2026-04-02T21:30:55.780869Z","end":"2026-04-02T21:30:55.894626Z","steps":["trace[1278509067] 'process raft request' (duration: 12.999027ms)","trace[1278509067] 'compare' (duration: 99.914546ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:34:08.927701Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3246} {"level":"info","ts":"2026-04-02T21:34:09.012011Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3246,"took":"83.023493ms","hash":3155820270,"current-db-size-bytes":61140992,"current-db-size":"61 MB","current-db-size-in-use-bytes":33804288,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-02T21:34:09.012075Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3155820270,"revision":3246,"compact-revision":-1} {"level":"info","ts":"2026-04-02T21:36:40.481458Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000020ff.wal"} {"level":"info","ts":"2026-04-02T21:36:44.840041Z","caller":"traceutil/trace.go:171","msg":"trace[1507688480] transaction","detail":"{read_only:false; response_revision:8116; number_of_response:1; }","duration":"172.082713ms","start":"2026-04-02T21:36:44.667845Z","end":"2026-04-02T21:36:44.839928Z","steps":["trace[1507688480] 'process raft request' (duration: 171.858229ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:36:57.352864Z","caller":"traceutil/trace.go:171","msg":"trace[2063142556] transaction","detail":"{read_only:false; response_revision:8199; number_of_response:1; }","duration":"134.962619ms","start":"2026-04-02T21:36:57.217854Z","end":"2026-04-02T21:36:57.352816Z","steps":["trace[2063142556] 'process raft request' (duration: 134.230413ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:36:57.588296Z","caller":"traceutil/trace.go:171","msg":"trace[1540984802] transaction","detail":"{read_only:false; response_revision:8200; number_of_response:1; }","duration":"162.604771ms","start":"2026-04-02T21:36:57.425668Z","end":"2026-04-02T21:36:57.588273Z","steps":["trace[1540984802] 'process raft request' (duration: 162.465628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:36:58.371848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.923133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:36:58.371946Z","caller":"traceutil/trace.go:171","msg":"trace[2076683857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8210; }","duration":"154.063377ms","start":"2026-04-02T21:36:58.217858Z","end":"2026-04-02T21:36:58.371922Z","steps":["trace[2076683857] 'range keys from in-memory index tree' (duration: 153.834242ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:37:25.245609Z","caller":"traceutil/trace.go:171","msg":"trace[138837737] transaction","detail":"{read_only:false; response_revision:8439; number_of_response:1; }","duration":"172.82945ms","start":"2026-04-02T21:37:25.072702Z","end":"2026-04-02T21:37:25.245532Z","steps":["trace[138837737] 'process raft request' (duration: 172.562773ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:37:25.397264Z","caller":"traceutil/trace.go:171","msg":"trace[1295463877] linearizableReadLoop","detail":"{readStateIndex:8819; appliedIndex:8818; }","duration":"117.671449ms","start":"2026-04-02T21:37:25.279566Z","end":"2026-04-02T21:37:25.397237Z","steps":["trace[1295463877] 'read index received' (duration: 66.445522ms)","trace[1295463877] 'applied index is now lower than readState.Index' (duration: 51.225047ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:37:25.397465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.897224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-02T21:37:25.397494Z","caller":"traceutil/trace.go:171","msg":"trace[1317169811] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8440; }","duration":"117.992046ms","start":"2026-04-02T21:37:25.279495Z","end":"2026-04-02T21:37:25.397488Z","steps":["trace[1317169811] 'agreement among raft nodes before linearized reading' (duration: 117.826573ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:37:25.397941Z","caller":"traceutil/trace.go:171","msg":"trace[548100417] transaction","detail":"{read_only:false; response_revision:8440; number_of_response:1; }","duration":"260.617168ms","start":"2026-04-02T21:37:25.13725Z","end":"2026-04-02T21:37:25.397867Z","steps":["trace[548100417] 'process raft request' (duration: 208.303608ms)","trace[548100417] 'compare' (duration: 51.559544ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:37:39.538413Z","caller":"traceutil/trace.go:171","msg":"trace[691193812] transaction","detail":"{read_only:false; response_revision:8575; number_of_response:1; }","duration":"140.677911ms","start":"2026-04-02T21:37:39.397702Z","end":"2026-04-02T21:37:39.53838Z","steps":["trace[691193812] 'process raft request' (duration: 140.433806ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:37:40.452369Z","caller":"traceutil/trace.go:171","msg":"trace[250687593] linearizableReadLoop","detail":"{readStateIndex:8967; appliedIndex:8966; }","duration":"234.110782ms","start":"2026-04-02T21:37:40.218231Z","end":"2026-04-02T21:37:40.452341Z","steps":["trace[250687593] 'read index received' (duration: 234.058641ms)","trace[250687593] 'applied index is now lower than readState.Index' (duration: 50.831µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:37:40.452508Z","caller":"traceutil/trace.go:171","msg":"trace[338608404] transaction","detail":"{read_only:false; response_revision:8580; number_of_response:1; }","duration":"237.720038ms","start":"2026-04-02T21:37:40.214719Z","end":"2026-04-02T21:37:40.452439Z","steps":["trace[338608404] 'process raft request' (duration: 237.433132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:37:40.452883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.623433ms","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-04-02T21:37:40.452982Z","caller":"traceutil/trace.go:171","msg":"trace[1007992437] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8580; }","duration":"234.766515ms","start":"2026-04-02T21:37:40.218194Z","end":"2026-04-02T21:37:40.452961Z","steps":["trace[1007992437] 'agreement among raft nodes before linearized reading' (duration: 234.418808ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:38:49.772866Z","caller":"traceutil/trace.go:171","msg":"trace[238473482] transaction","detail":"{read_only:false; response_revision:9321; number_of_response:1; }","duration":"299.973766ms","start":"2026-04-02T21:38:49.472854Z","end":"2026-04-02T21:38:49.772828Z","steps":["trace[238473482] 'process raft request' (duration: 299.840433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:38:49.773076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:38:49.472837Z","time spent":"300.138389ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T21:38:49.772869Z","caller":"traceutil/trace.go:171","msg":"trace[1253227794] transaction","detail":"{read_only:false; response_revision:9320; number_of_response:1; }","duration":"303.580223ms","start":"2026-04-02T21:38:49.469227Z","end":"2026-04-02T21:38:49.772808Z","steps":["trace[1253227794] 'process raft request' (duration: 223.592478ms)","trace[1253227794] 'compare' (duration: 79.692449ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:38:49.773256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:38:49.469215Z","time spent":"304.010531ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T21:38:50.154059Z","caller":"traceutil/trace.go:171","msg":"trace[840863968] linearizableReadLoop","detail":"{readStateIndex:9740; appliedIndex:9739; }","duration":"205.394679ms","start":"2026-04-02T21:38:49.94863Z","end":"2026-04-02T21:38:50.154025Z","steps":["trace[840863968] 'read index received' (duration: 205.260196ms)","trace[840863968] 'applied index is now lower than readState.Index' (duration: 132.953µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:38:50.154143Z","caller":"traceutil/trace.go:171","msg":"trace[1408295974] transaction","detail":"{read_only:false; response_revision:9322; number_of_response:1; }","duration":"298.431093ms","start":"2026-04-02T21:38:49.855669Z","end":"2026-04-02T21:38:50.1541Z","steps":["trace[1408295974] 'process raft request' (duration: 298.133246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:38:50.154553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.885139ms","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-04-02T21:38:50.154588Z","caller":"traceutil/trace.go:171","msg":"trace[1576597612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9322; }","duration":"206.004022ms","start":"2026-04-02T21:38:49.948574Z","end":"2026-04-02T21:38:50.154578Z","steps":["trace[1576597612] 'agreement among raft nodes before linearized reading' (duration: 205.622694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:38:50.154896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.967716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-02T21:38:50.155132Z","caller":"traceutil/trace.go:171","msg":"trace[343225016] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:9322; }","duration":"141.273583ms","start":"2026-04-02T21:38:50.013816Z","end":"2026-04-02T21:38:50.155089Z","steps":["trace[343225016] 'agreement among raft nodes before linearized reading' (duration: 140.705951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:38:50.345933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.995309ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T21:38:50.346093Z","caller":"traceutil/trace.go:171","msg":"trace[1565921866] transaction","detail":"{read_only:false; response_revision:9323; number_of_response:1; }","duration":"186.310823ms","start":"2026-04-02T21:38:50.159736Z","end":"2026-04-02T21:38:50.346047Z","steps":["trace[1565921866] 'process raft request' (duration: 59.12747ms)","trace[1565921866] 'compare' (duration: 126.598311ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:38:50.346141Z","caller":"traceutil/trace.go:171","msg":"trace[1503666883] transaction","detail":"{read_only:false; response_revision:9324; number_of_response:1; }","duration":"185.185689ms","start":"2026-04-02T21:38:50.160942Z","end":"2026-04-02T21:38:50.346127Z","steps":["trace[1503666883] 'process raft request' (duration: 185.088147ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:38:50.346162Z","caller":"traceutil/trace.go:171","msg":"trace[69294870] linearizableReadLoop","detail":"{readStateIndex:9741; appliedIndex:9740; }","duration":"185.969766ms","start":"2026-04-02T21:38:50.160154Z","end":"2026-04-02T21:38:50.346124Z","steps":["trace[69294870] 'read index received' (duration: 58.731652ms)","trace[69294870] 'applied index is now lower than readState.Index' (duration: 127.235634ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:38:50.3469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.727692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:28 size:241217"} {"level":"info","ts":"2026-04-02T21:38:50.347087Z","caller":"traceutil/trace.go:171","msg":"trace[915147234] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:28; response_revision:9324; }","duration":"186.950456ms","start":"2026-04-02T21:38:50.160117Z","end":"2026-04-02T21:38:50.347067Z","steps":["trace[915147234] 'agreement among raft nodes before linearized reading' (duration: 186.237191ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:39:08.936215Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6612} {"level":"info","ts":"2026-04-02T21:39:09.042449Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6612,"took":"103.876054ms","hash":170772729,"current-db-size-bytes":61140992,"current-db-size":"61 MB","current-db-size-in-use-bytes":26955776,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-02T21:39:09.042549Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":170772729,"revision":6612,"compact-revision":3246} {"level":"info","ts":"2026-04-02T21:39:19.045533Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c9b4049ccf4190cf","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-02T21:39:19.050538Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-02T21:39:19.05064Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-02T21:44:08.94323Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9500} {"level":"info","ts":"2026-04-02T21:44:09.042962Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9500,"took":"96.223745ms","hash":1364318533,"current-db-size-bytes":61140992,"current-db-size":"61 MB","current-db-size-in-use-bytes":25063424,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-02T21:44:09.043054Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1364318533,"revision":9500,"compact-revision":6612}