{"level":"warn","ts":"2026-10-17T10:40:00.200378Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-17T10:40:00.200461Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.32: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://162.253.55.32:2380","--initial-cluster=instance=https://162.253.55.32:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.32:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.32: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-10-17T10:40:00.200529Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-17T10:40:00.200547Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.32:2380"]} {"level":"info","ts":"2026-10-17T10:40:00.200566Z","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-10-17T10:40:00.201161Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.32:2379"]} {"level":"info","ts":"2026-10-17T10:40:00.201237Z","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://162.253.55.32:2380"],"listen-peer-urls":["https://162.253.55.32:2380"],"advertise-client-urls":["https://162.253.55.32:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.32:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.32: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-10-17T10:40:00.208496Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.035408ms"} {"level":"info","ts":"2026-10-17T10:40:00.220362Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c665390cd9eacc14","cluster-id":"d19b610fbe64baae"} {"level":"info","ts":"2026-10-17T10:40:00.220417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 switched to configuration voters=()"} {"level":"info","ts":"2026-10-17T10:40:00.220437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 became follower at term 0"} {"level":"info","ts":"2026-10-17T10:40:00.220449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c665390cd9eacc14 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-17T10:40:00.22046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 became follower at term 1"} {"level":"info","ts":"2026-10-17T10:40:00.22049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 switched to configuration voters=(14295895319515941908)"} {"level":"warn","ts":"2026-10-17T10:40:00.237526Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-17T10:40:00.239019Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-17T10:40:00.24067Z","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-10-17T10:40:00.244442Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c665390cd9eacc14","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-17T10:40:00.244588Z","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-10-17T10:40:00.244624Z","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-10-17T10:40:00.244633Z","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-10-17T10:40:00.244901Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c665390cd9eacc14","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-17T10:40:00.245667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 switched to configuration voters=(14295895319515941908)"} {"level":"info","ts":"2026-10-17T10:40:00.24579Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d19b610fbe64baae","local-member-id":"c665390cd9eacc14","added-peer-id":"c665390cd9eacc14","added-peer-peer-urls":["https://162.253.55.32:2380"]} {"level":"info","ts":"2026-10-17T10:40:00.247467Z","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-10-17T10:40:00.247587Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.32:2380"} {"level":"info","ts":"2026-10-17T10:40:00.247658Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.32:2380"} {"level":"info","ts":"2026-10-17T10:40:00.248244Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c665390cd9eacc14","initial-advertise-peer-urls":["https://162.253.55.32:2380"],"listen-peer-urls":["https://162.253.55.32:2380"],"advertise-client-urls":["https://162.253.55.32:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.32:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-17T10:40:00.248278Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-17T10:40:00.420624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 is starting a new election at term 1"} {"level":"info","ts":"2026-10-17T10:40:00.420679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-17T10:40:00.420714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 received MsgPreVoteResp from c665390cd9eacc14 at term 1"} {"level":"info","ts":"2026-10-17T10:40:00.420745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 became candidate at term 2"} {"level":"info","ts":"2026-10-17T10:40:00.420759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 received MsgVoteResp from c665390cd9eacc14 at term 2"} {"level":"info","ts":"2026-10-17T10:40:00.420774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 became leader at term 2"} {"level":"info","ts":"2026-10-17T10:40:00.420787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c665390cd9eacc14 elected leader c665390cd9eacc14 at term 2"} {"level":"info","ts":"2026-10-17T10:40:00.421704Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:40:00.42309Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-17T10:40:00.423125Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-17T10:40:00.423114Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-17T10:40:00.423296Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-17T10:40:00.422906Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c665390cd9eacc14","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.32:2379]}","request-path":"/0/members/c665390cd9eacc14/attributes","cluster-id":"d19b610fbe64baae","publish-timeout":"7s"} {"level":"info","ts":"2026-10-17T10:40:00.425914Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d19b610fbe64baae","local-member-id":"c665390cd9eacc14","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:40:00.427355Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:40:00.427551Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:40:00.429255Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.32:2379"} {"level":"info","ts":"2026-10-17T10:40:00.429682Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-17T10:40:04.65846Z","caller":"traceutil/trace.go:171","msg":"trace[790554899] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"307.070802ms","start":"2026-10-17T10:40:04.351362Z","end":"2026-10-17T10:40:04.658433Z","steps":["trace[790554899] 'process raft request' (duration: 306.920857ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:40:04.659081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:40:04.351342Z","time spent":"307.216988ms","remote":"127.0.0.1:34578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3694,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T10:40:04.810872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.726039ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-17T10:40:04.810964Z","caller":"traceutil/trace.go:171","msg":"trace[1904556555] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"144.635733ms","start":"2026-10-17T10:40:04.666314Z","end":"2026-10-17T10:40:04.81095Z","steps":["trace[1904556555] 'process raft request' (duration: 43.679376ms)","trace[1904556555] 'compare' (duration: 100.577955ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:40:41.349317Z","caller":"traceutil/trace.go:171","msg":"trace[235446813] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"143.530889ms","start":"2026-10-17T10:40:41.205756Z","end":"2026-10-17T10:40:41.349287Z","steps":["trace[235446813] 'process raft request' (duration: 140.233732ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:40:41.349363Z","caller":"traceutil/trace.go:171","msg":"trace[703846593] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"136.419332ms","start":"2026-10-17T10:40:41.212912Z","end":"2026-10-17T10:40:41.349331Z","steps":["trace[703846593] 'process raft request' (duration: 136.261015ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:40:41.349386Z","caller":"traceutil/trace.go:171","msg":"trace[629783631] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"127.390005ms","start":"2026-10-17T10:40:41.221972Z","end":"2026-10-17T10:40:41.349362Z","steps":["trace[629783631] 'process raft request' (duration: 127.305871ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:40:41.349476Z","caller":"traceutil/trace.go:171","msg":"trace[2066799854] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"125.80032ms","start":"2026-10-17T10:40:41.223649Z","end":"2026-10-17T10:40:41.349449Z","steps":["trace[2066799854] 'process raft request' (duration: 125.668003ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:40:41.349532Z","caller":"traceutil/trace.go:171","msg":"trace[1847900097] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"124.207234ms","start":"2026-10-17T10:40:41.225273Z","end":"2026-10-17T10:40:41.34948Z","steps":["trace[1847900097] 'process raft request' (duration: 124.080368ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:40:41.349582Z","caller":"traceutil/trace.go:171","msg":"trace[1275806375] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"124.036856ms","start":"2026-10-17T10:40:41.225538Z","end":"2026-10-17T10:40:41.349575Z","steps":["trace[1275806375] 'process raft request' (duration: 123.849187ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:40:41.349596Z","caller":"traceutil/trace.go:171","msg":"trace[1649793397] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"123.395895ms","start":"2026-10-17T10:40:41.226187Z","end":"2026-10-17T10:40:41.349583Z","steps":["trace[1649793397] 'process raft request' (duration: 123.277919ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:40:41.34965Z","caller":"traceutil/trace.go:171","msg":"trace[645034676] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"116.913348ms","start":"2026-10-17T10:40:41.232695Z","end":"2026-10-17T10:40:41.349609Z","steps":["trace[645034676] 'process raft request' (duration: 116.812044ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:40:41.349678Z","caller":"traceutil/trace.go:171","msg":"trace[795790939] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"104.442448ms","start":"2026-10-17T10:40:41.245226Z","end":"2026-10-17T10:40:41.349668Z","steps":["trace[795790939] 'process raft request' (duration: 104.331833ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:40:41.349677Z","caller":"traceutil/trace.go:171","msg":"trace[1745395563] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:508; }","duration":"114.439351ms","start":"2026-10-17T10:40:41.235219Z","end":"2026-10-17T10:40:41.349658Z","steps":["trace[1745395563] 'read index received' (duration: 39.89275ms)","trace[1745395563] 'applied index is now lower than readState.Index' (duration: 74.545791ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:40:41.349879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.651091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-10-17T10:40:41.350752Z","caller":"traceutil/trace.go:171","msg":"trace[1777676996] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:499; }","duration":"115.581346ms","start":"2026-10-17T10:40:41.235155Z","end":"2026-10-17T10:40:41.350736Z","steps":["trace[1777676996] 'agreement among raft nodes before linearized reading' (duration: 114.566838ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:40:47.783242Z","caller":"traceutil/trace.go:171","msg":"trace[1514551068] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"315.513878ms","start":"2026-10-17T10:40:47.467692Z","end":"2026-10-17T10:40:47.783206Z","steps":["trace[1514551068] 'process raft request' (duration: 315.318228ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:40:47.783562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:40:47.467669Z","time spent":"315.698868ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T10:40:47.933719Z","caller":"traceutil/trace.go:171","msg":"trace[33176926] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:619; }","duration":"178.662152ms","start":"2026-10-17T10:40:47.755028Z","end":"2026-10-17T10:40:47.933691Z","steps":["trace[33176926] 'read index received' (duration: 28.065877ms)","trace[33176926] 'applied index is now lower than readState.Index' (duration: 150.594925ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:40:47.93384Z","caller":"traceutil/trace.go:171","msg":"trace[133815991] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"461.185964ms","start":"2026-10-17T10:40:47.472613Z","end":"2026-10-17T10:40:47.933799Z","steps":["trace[133815991] 'process raft request' (duration: 460.011617ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:40:47.934003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.955857ms","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-10-17T10:40:47.934061Z","caller":"traceutil/trace.go:171","msg":"trace[86394626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:597; }","duration":"179.087013ms","start":"2026-10-17T10:40:47.754959Z","end":"2026-10-17T10:40:47.934046Z","steps":["trace[86394626] 'agreement among raft nodes before linearized reading' (duration: 178.855542ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:40:47.93406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:40:47.472593Z","time spent":"461.346452ms","remote":"127.0.0.1:34474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T10:40:48.591016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.731151ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T10:40:48.591245Z","caller":"traceutil/trace.go:171","msg":"trace[159851311] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:621; }","duration":"636.5864ms","start":"2026-10-17T10:40:47.954641Z","end":"2026-10-17T10:40:48.591227Z","steps":["trace[159851311] 'read index received' (duration: 57.524093ms)","trace[159851311] 'applied index is now lower than readState.Index' (duration: 579.060877ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:40:48.591439Z","caller":"traceutil/trace.go:171","msg":"trace[187331352] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"553.608372ms","start":"2026-10-17T10:40:48.037816Z","end":"2026-10-17T10:40:48.591424Z","steps":["trace[187331352] 'process raft request' (duration: 553.35852ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:40:48.591455Z","caller":"traceutil/trace.go:171","msg":"trace[2128987648] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"637.501566ms","start":"2026-10-17T10:40:47.95394Z","end":"2026-10-17T10:40:48.591441Z","steps":["trace[2128987648] 'process raft request' (duration: 502.221039ms)","trace[2128987648] 'compare' (duration: 134.51125ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:40:48.591529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:40:48.037795Z","time spent":"553.692956ms","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-17T10:40:48.591587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.937238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-75256\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-10-17T10:40:48.591556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:40:47.953917Z","time spent":"637.601241ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T10:40:48.591636Z","caller":"traceutil/trace.go:171","msg":"trace[2038899448] range","detail":"{range_begin:/registry/pods/kube-system/cilium-75256; range_end:; response_count:1; response_revision:600; }","duration":"637.021502ms","start":"2026-10-17T10:40:47.954602Z","end":"2026-10-17T10:40:48.591623Z","steps":["trace[2038899448] 'agreement among raft nodes before linearized reading' (duration: 636.839123ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:40:48.591669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:40:47.954592Z","time spent":"637.069845ms","remote":"127.0.0.1:34588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-75256\" "} {"level":"warn","ts":"2026-10-17T10:40:48.591584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.642704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:40:48.591824Z","caller":"traceutil/trace.go:171","msg":"trace[1117692042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"220.912287ms","start":"2026-10-17T10:40:48.370877Z","end":"2026-10-17T10:40:48.591789Z","steps":["trace[1117692042] 'agreement among raft nodes before linearized reading' (duration: 220.57397ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:41:03.776942Z","caller":"traceutil/trace.go:171","msg":"trace[1210341589] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"118.714158ms","start":"2026-10-17T10:41:03.658186Z","end":"2026-10-17T10:41:03.7769Z","steps":["trace[1210341589] 'read index received' (duration: 118.483316ms)","trace[1210341589] 'applied index is now lower than readState.Index' (duration: 227.772µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:41:03.776996Z","caller":"traceutil/trace.go:171","msg":"trace[1113745510] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"162.832832ms","start":"2026-10-17T10:41:03.614131Z","end":"2026-10-17T10:41:03.776964Z","steps":["trace[1113745510] 'process raft request' (duration: 162.591379ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:41:03.777315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.884437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:41:03.777365Z","caller":"traceutil/trace.go:171","msg":"trace[415645234] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:761; }","duration":"119.193184ms","start":"2026-10-17T10:41:03.65816Z","end":"2026-10-17T10:41:03.777353Z","steps":["trace[415645234] 'agreement among raft nodes before linearized reading' (duration: 118.855816ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:41:34.694932Z","caller":"traceutil/trace.go:171","msg":"trace[914152127] linearizableReadLoop","detail":"{readStateIndex:1204; appliedIndex:1203; }","duration":"325.496173ms","start":"2026-10-17T10:41:34.369383Z","end":"2026-10-17T10:41:34.69488Z","steps":["trace[914152127] 'read index received' (duration: 325.277501ms)","trace[914152127] 'applied index is now lower than readState.Index' (duration: 217.772µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:41:34.694965Z","caller":"traceutil/trace.go:171","msg":"trace[1887687169] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"346.081432ms","start":"2026-10-17T10:41:34.348855Z","end":"2026-10-17T10:41:34.694936Z","steps":["trace[1887687169] 'process raft request' (duration: 345.87041ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:41:34.695107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.666813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:41:34.695167Z","caller":"traceutil/trace.go:171","msg":"trace[980862239] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1168; }","duration":"325.829783ms","start":"2026-10-17T10:41:34.369327Z","end":"2026-10-17T10:41:34.695157Z","steps":["trace[980862239] 'agreement among raft nodes before linearized reading' (duration: 325.683955ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:41:34.695208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:41:34.369311Z","time spent":"325.885735ms","remote":"127.0.0.1:34404","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-10-17T10:41:34.696061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:41:34.348827Z","time spent":"346.310575ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T10:41:58.533757Z","caller":"traceutil/trace.go:171","msg":"trace[1910874552] linearizableReadLoop","detail":"{readStateIndex:1481; appliedIndex:1480; }","duration":"161.281753ms","start":"2026-10-17T10:41:58.372449Z","end":"2026-10-17T10:41:58.533731Z","steps":["trace[1910874552] 'read index received' (duration: 161.158506ms)","trace[1910874552] 'applied index is now lower than readState.Index' (duration: 121.857µs)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:41:58.533957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.495194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:41:58.534012Z","caller":"traceutil/trace.go:171","msg":"trace[1670887410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1436; }","duration":"161.58537ms","start":"2026-10-17T10:41:58.372411Z","end":"2026-10-17T10:41:58.533996Z","steps":["trace[1670887410] 'agreement among raft nodes before linearized reading' (duration: 161.455423ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:41:58.534357Z","caller":"traceutil/trace.go:171","msg":"trace[1375847278] transaction","detail":"{read_only:false; response_revision:1436; number_of_response:1; }","duration":"162.277769ms","start":"2026-10-17T10:41:58.372063Z","end":"2026-10-17T10:41:58.534341Z","steps":["trace[1375847278] 'process raft request' (duration: 161.519456ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:42:25.95601Z","caller":"traceutil/trace.go:171","msg":"trace[1186508452] linearizableReadLoop","detail":"{readStateIndex:1925; appliedIndex:1924; }","duration":"216.577449ms","start":"2026-10-17T10:42:25.73941Z","end":"2026-10-17T10:42:25.955987Z","steps":["trace[1186508452] 'read index received' (duration: 216.472813ms)","trace[1186508452] 'applied index is now lower than readState.Index' (duration: 103.586µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:42:25.956156Z","caller":"traceutil/trace.go:171","msg":"trace[584782145] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"379.433031ms","start":"2026-10-17T10:42:25.576691Z","end":"2026-10-17T10:42:25.956124Z","steps":["trace[584782145] 'process raft request' (duration: 379.126522ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:42:25.95621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.789192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-17T10:42:25.956247Z","caller":"traceutil/trace.go:171","msg":"trace[608056053] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1851; }","duration":"216.843975ms","start":"2026-10-17T10:42:25.73939Z","end":"2026-10-17T10:42:25.956234Z","steps":["trace[608056053] 'agreement among raft nodes before linearized reading' (duration: 216.735189ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:42:25.956308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:42:25.576669Z","time spent":"379.558399ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T10:42:25.973081Z","caller":"traceutil/trace.go:171","msg":"trace[1827341794] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"232.377257ms","start":"2026-10-17T10:42:25.740671Z","end":"2026-10-17T10:42:25.973048Z","steps":["trace[1827341794] 'process raft request' (duration: 232.126032ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:42:25.973171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.507829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-17T10:42:25.973266Z","caller":"traceutil/trace.go:171","msg":"trace[805457758] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1852; }","duration":"106.645737ms","start":"2026-10-17T10:42:25.866597Z","end":"2026-10-17T10:42:25.973242Z","steps":["trace[805457758] 'agreement among raft nodes before linearized reading' (duration: 106.390092ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:42:26.39205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.136353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-10-17T10:42:26.392161Z","caller":"traceutil/trace.go:171","msg":"trace[891038329] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1854; }","duration":"150.299513ms","start":"2026-10-17T10:42:26.241839Z","end":"2026-10-17T10:42:26.392138Z","steps":["trace[891038329] 'range keys from in-memory index tree' (duration: 149.950622ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:42:41.045707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.038891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-17T10:42:41.045782Z","caller":"traceutil/trace.go:171","msg":"trace[1673318547] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1982; }","duration":"201.158219ms","start":"2026-10-17T10:42:40.844609Z","end":"2026-10-17T10:42:41.045767Z","steps":["trace[1673318547] 'count revisions from in-memory index tree' (duration: 200.948276ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:42:59.49212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.78671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-10-17T10:42:59.492265Z","caller":"traceutil/trace.go:171","msg":"trace[1963137657] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2092; }","duration":"112.992993ms","start":"2026-10-17T10:42:59.379247Z","end":"2026-10-17T10:42:59.49224Z","steps":["trace[1963137657] 'range keys from in-memory index tree' (duration: 112.358833ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:43:00.522852Z","caller":"traceutil/trace.go:171","msg":"trace[1175694860] linearizableReadLoop","detail":"{readStateIndex:2178; appliedIndex:2177; }","duration":"153.980616ms","start":"2026-10-17T10:43:00.368848Z","end":"2026-10-17T10:43:00.522828Z","steps":["trace[1175694860] 'read index received' (duration: 153.836322ms)","trace[1175694860] 'applied index is now lower than readState.Index' (duration: 143.094µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:43:00.522989Z","caller":"traceutil/trace.go:171","msg":"trace[22597770] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"179.486261ms","start":"2026-10-17T10:43:00.343491Z","end":"2026-10-17T10:43:00.522977Z","steps":["trace[22597770] 'process raft request' (duration: 179.199421ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:43:00.523122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.270777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:43:00.523272Z","caller":"traceutil/trace.go:171","msg":"trace[809568831] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2096; }","duration":"154.458371ms","start":"2026-10-17T10:43:00.368801Z","end":"2026-10-17T10:43:00.52326Z","steps":["trace[809568831] 'agreement among raft nodes before linearized reading' (duration: 154.280287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:43:00.860571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.976859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-17T10:43:00.86066Z","caller":"traceutil/trace.go:171","msg":"trace[578857592] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2096; }","duration":"245.122234ms","start":"2026-10-17T10:43:00.615519Z","end":"2026-10-17T10:43:00.860641Z","steps":["trace[578857592] 'count revisions from in-memory index tree' (duration: 244.835783ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:43:00.8609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.052176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:43:00.861001Z","caller":"traceutil/trace.go:171","msg":"trace[645214513] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:2096; }","duration":"254.179642ms","start":"2026-10-17T10:43:00.606798Z","end":"2026-10-17T10:43:00.860978Z","steps":["trace[645214513] 'count revisions from in-memory index tree' (duration: 253.995757ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:43:00.861466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.709249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-10-17T10:43:00.861508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.060322ms","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-10-17T10:43:00.861607Z","caller":"traceutil/trace.go:171","msg":"trace[512274077] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2096; }","duration":"268.074168ms","start":"2026-10-17T10:43:00.593496Z","end":"2026-10-17T10:43:00.861571Z","steps":["trace[512274077] 'range keys from in-memory index tree' (duration: 267.009522ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:43:00.861621Z","caller":"traceutil/trace.go:171","msg":"trace[191306013] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2096; }","duration":"282.797659ms","start":"2026-10-17T10:43:00.578796Z","end":"2026-10-17T10:43:00.861594Z","steps":["trace[191306013] 'range keys from in-memory index tree' (duration: 281.79191ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:43:57.625384Z","caller":"traceutil/trace.go:171","msg":"trace[195745156] linearizableReadLoop","detail":"{readStateIndex:2547; appliedIndex:2546; }","duration":"102.070428ms","start":"2026-10-17T10:43:57.523288Z","end":"2026-10-17T10:43:57.625358Z","steps":["trace[195745156] 'read index received' (duration: 47.676304ms)","trace[195745156] 'applied index is now lower than readState.Index' (duration: 54.392694ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:43:57.625467Z","caller":"traceutil/trace.go:171","msg":"trace[384627174] transaction","detail":"{read_only:false; response_revision:2452; number_of_response:1; }","duration":"105.180994ms","start":"2026-10-17T10:43:57.520214Z","end":"2026-10-17T10:43:57.625395Z","steps":["trace[384627174] 'process raft request' (duration: 50.80376ms)","trace[384627174] 'compare' (duration: 54.197024ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:43:57.62564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.343158ms","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-10-17T10:43:57.625694Z","caller":"traceutil/trace.go:171","msg":"trace[1259575863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2452; }","duration":"102.413608ms","start":"2026-10-17T10:43:57.523257Z","end":"2026-10-17T10:43:57.625671Z","steps":["trace[1259575863] 'agreement among raft nodes before linearized reading' (duration: 102.269738ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:44:08.201918Z","caller":"traceutil/trace.go:171","msg":"trace[1908202189] transaction","detail":"{read_only:false; response_revision:2562; number_of_response:1; }","duration":"241.305519ms","start":"2026-10-17T10:44:07.960588Z","end":"2026-10-17T10:44:08.201894Z","steps":["trace[1908202189] 'process raft request' (duration: 241.166878ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:44:08.204433Z","caller":"traceutil/trace.go:171","msg":"trace[1611572207] transaction","detail":"{read_only:false; response_revision:2563; number_of_response:1; }","duration":"227.660778ms","start":"2026-10-17T10:44:07.976749Z","end":"2026-10-17T10:44:08.204409Z","steps":["trace[1611572207] 'process raft request' (duration: 227.545487ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:44:39.138408Z","caller":"traceutil/trace.go:171","msg":"trace[172327548] transaction","detail":"{read_only:false; response_revision:2732; number_of_response:1; }","duration":"248.592636ms","start":"2026-10-17T10:44:38.889773Z","end":"2026-10-17T10:44:39.138366Z","steps":["trace[172327548] 'process raft request' (duration: 248.365813ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:44:44.659891Z","caller":"traceutil/trace.go:171","msg":"trace[1225328040] transaction","detail":"{read_only:false; response_revision:2765; number_of_response:1; }","duration":"109.818245ms","start":"2026-10-17T10:44:44.55004Z","end":"2026-10-17T10:44:44.659859Z","steps":["trace[1225328040] 'process raft request' (duration: 109.670392ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:49:09.808526Z","caller":"traceutil/trace.go:171","msg":"trace[371087222] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"105.221952ms","start":"2026-10-17T10:49:09.70328Z","end":"2026-10-17T10:49:09.808502Z","steps":["trace[371087222] 'process raft request' (duration: 33.048143ms)","trace[371087222] 'compare' (duration: 71.809612ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:49:10.598358Z","caller":"traceutil/trace.go:171","msg":"trace[545052042] linearizableReadLoop","detail":"{readStateIndex:4964; appliedIndex:4963; }","duration":"232.152817ms","start":"2026-10-17T10:49:10.366192Z","end":"2026-10-17T10:49:10.598345Z","steps":["trace[545052042] 'read index received' (duration: 165.103804ms)","trace[545052042] 'applied index is now lower than readState.Index' (duration: 67.047993ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:49:10.598418Z","caller":"traceutil/trace.go:171","msg":"trace[1175491537] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"262.506996ms","start":"2026-10-17T10:49:10.335904Z","end":"2026-10-17T10:49:10.598411Z","steps":["trace[1175491537] 'process raft request' (duration: 195.37933ms)","trace[1175491537] 'compare' (duration: 66.823873ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:49:10.598502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.291114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:49:10.598576Z","caller":"traceutil/trace.go:171","msg":"trace[1625936804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4779; }","duration":"232.392759ms","start":"2026-10-17T10:49:10.366166Z","end":"2026-10-17T10:49:10.598559Z","steps":["trace[1625936804] 'agreement among raft nodes before linearized reading' (duration: 232.257083ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:49:10.606413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.917222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3feb6d10-87f9-4de9-ad57-de29cee97051\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-10-17T10:49:10.606484Z","caller":"traceutil/trace.go:171","msg":"trace[1324385942] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3feb6d10-87f9-4de9-ad57-de29cee97051; range_end:; response_count:1; response_revision:4780; }","duration":"211.035708ms","start":"2026-10-17T10:49:10.395434Z","end":"2026-10-17T10:49:10.606469Z","steps":["trace[1324385942] 'agreement among raft nodes before linearized reading' (duration: 210.835459ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:49:10.606493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.725884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3feb6d10-87f9-4de9-ad57-de29cee97051\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-10-17T10:49:10.606525Z","caller":"traceutil/trace.go:171","msg":"trace[1831465263] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3feb6d10-87f9-4de9-ad57-de29cee97051; range_end:; response_count:1; response_revision:4780; }","duration":"162.793178ms","start":"2026-10-17T10:49:10.443723Z","end":"2026-10-17T10:49:10.606516Z","steps":["trace[1831465263] 'agreement among raft nodes before linearized reading' (duration: 162.660722ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:49:10.608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.615486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207411"} {"level":"info","ts":"2026-10-17T10:49:10.60809Z","caller":"traceutil/trace.go:171","msg":"trace[274777569] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4780; }","duration":"177.718121ms","start":"2026-10-17T10:49:10.430338Z","end":"2026-10-17T10:49:10.608056Z","steps":["trace[274777569] 'agreement among raft nodes before linearized reading' (duration: 176.205001ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:49:19.366033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.520682ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-17T10:49:19.366308Z","caller":"traceutil/trace.go:171","msg":"trace[1740332248] linearizableReadLoop","detail":"{readStateIndex:5107; appliedIndex:5106; }","duration":"413.743294ms","start":"2026-10-17T10:49:18.952539Z","end":"2026-10-17T10:49:19.366282Z","steps":["trace[1740332248] 'read index received' (duration: 185.949269ms)","trace[1740332248] 'applied index is now lower than readState.Index' (duration: 227.791195ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:49:19.366622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.06526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-17T10:49:19.366701Z","caller":"traceutil/trace.go:171","msg":"trace[257791354] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4916; }","duration":"414.205935ms","start":"2026-10-17T10:49:18.952478Z","end":"2026-10-17T10:49:19.366684Z","steps":["trace[257791354] 'agreement among raft nodes before linearized reading' (duration: 413.953594ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:49:19.366746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:49:18.952454Z","time spent":"414.283769ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-17T10:49:19.367504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.231025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12430"} {"level":"info","ts":"2026-10-17T10:49:19.367608Z","caller":"traceutil/trace.go:171","msg":"trace[1208770917] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4916; }","duration":"231.382932ms","start":"2026-10-17T10:49:19.136207Z","end":"2026-10-17T10:49:19.36759Z","steps":["trace[1208770917] 'agreement among raft nodes before linearized reading' (duration: 230.933661ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:49:19.71328Z","caller":"traceutil/trace.go:171","msg":"trace[318703209] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"181.265452ms","start":"2026-10-17T10:49:19.531992Z","end":"2026-10-17T10:49:19.713257Z","steps":["trace[318703209] 'process raft request' (duration: 181.129135ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:49:19.713563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.43596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13120"} {"level":"info","ts":"2026-10-17T10:49:19.713633Z","caller":"traceutil/trace.go:171","msg":"trace[1067701130] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4923; }","duration":"181.548665ms","start":"2026-10-17T10:49:19.532069Z","end":"2026-10-17T10:49:19.713618Z","steps":["trace[1067701130] 'agreement among raft nodes before linearized reading' (duration: 181.276832ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:49:19.713297Z","caller":"traceutil/trace.go:171","msg":"trace[1411030578] linearizableReadLoop","detail":"{readStateIndex:5115; appliedIndex:5115; }","duration":"181.163886ms","start":"2026-10-17T10:49:19.532105Z","end":"2026-10-17T10:49:19.713269Z","steps":["trace[1411030578] 'read index received' (duration: 181.153386ms)","trace[1411030578] 'applied index is now lower than readState.Index' (duration: 8.14µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:49:19.761054Z","caller":"traceutil/trace.go:171","msg":"trace[1698457912] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"228.790553ms","start":"2026-10-17T10:49:19.532238Z","end":"2026-10-17T10:49:19.761029Z","steps":["trace[1698457912] 'process raft request' (duration: 228.614814ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:49:19.761362Z","caller":"traceutil/trace.go:171","msg":"trace[66241906] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"228.697908ms","start":"2026-10-17T10:49:19.532641Z","end":"2026-10-17T10:49:19.761339Z","steps":["trace[66241906] 'process raft request' (duration: 228.339201ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:49:19.761649Z","caller":"traceutil/trace.go:171","msg":"trace[1778626510] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"228.926378ms","start":"2026-10-17T10:49:19.532709Z","end":"2026-10-17T10:49:19.761635Z","steps":["trace[1778626510] 'process raft request' (duration: 228.531201ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:49:19.761701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.518456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12430"} {"level":"info","ts":"2026-10-17T10:49:19.761747Z","caller":"traceutil/trace.go:171","msg":"trace[1222459362] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4927; }","duration":"229.59848ms","start":"2026-10-17T10:49:19.532136Z","end":"2026-10-17T10:49:19.761734Z","steps":["trace[1222459362] 'agreement among raft nodes before linearized reading' (duration: 229.413181ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:49:19.762041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.990606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-10-17T10:49:19.762108Z","caller":"traceutil/trace.go:171","msg":"trace[715825140] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4927; }","duration":"154.092351ms","start":"2026-10-17T10:49:19.607999Z","end":"2026-10-17T10:49:19.762091Z","steps":["trace[715825140] 'agreement among raft nodes before linearized reading' (duration: 153.800507ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:49:19.978497Z","caller":"traceutil/trace.go:171","msg":"trace[1686159869] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"145.29675ms","start":"2026-10-17T10:49:19.833177Z","end":"2026-10-17T10:49:19.978473Z","steps":["trace[1686159869] 'process raft request' (duration: 145.055828ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:49:19.978889Z","caller":"traceutil/trace.go:171","msg":"trace[1890299183] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"145.612825ms","start":"2026-10-17T10:49:19.833252Z","end":"2026-10-17T10:49:19.978865Z","steps":["trace[1890299183] 'process raft request' (duration: 145.166284ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:49:19.979074Z","caller":"traceutil/trace.go:171","msg":"trace[75976233] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"145.431396ms","start":"2026-10-17T10:49:19.833633Z","end":"2026-10-17T10:49:19.979065Z","steps":["trace[75976233] 'process raft request' (duration: 145.018217ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:49:26.672252Z","caller":"traceutil/trace.go:171","msg":"trace[1885869840] transaction","detail":"{read_only:false; response_revision:5055; number_of_response:1; }","duration":"331.684385ms","start":"2026-10-17T10:49:26.340543Z","end":"2026-10-17T10:49:26.672227Z","steps":["trace[1885869840] 'process raft request' (duration: 331.547059ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:49:26.672401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:49:26.340524Z","time spent":"331.804211ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T10:49:26.680127Z","caller":"traceutil/trace.go:171","msg":"trace[1070775563] linearizableReadLoop","detail":"{readStateIndex:5250; appliedIndex:5248; }","duration":"311.177541ms","start":"2026-10-17T10:49:26.368922Z","end":"2026-10-17T10:49:26.680099Z","steps":["trace[1070775563] 'read index received' (duration: 303.24937ms)","trace[1070775563] 'applied index is now lower than readState.Index' (duration: 7.926991ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:49:26.680216Z","caller":"traceutil/trace.go:171","msg":"trace[1824057047] transaction","detail":"{read_only:false; response_revision:5056; number_of_response:1; }","duration":"320.338101ms","start":"2026-10-17T10:49:26.359859Z","end":"2026-10-17T10:49:26.680197Z","steps":["trace[1824057047] 'process raft request' (duration: 320.0983ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:49:26.680485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:49:26.359834Z","time spent":"320.558421ms","remote":"127.0.0.1:34474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2585,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-17T10:49:26.681316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.355688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:49:26.681401Z","caller":"traceutil/trace.go:171","msg":"trace[1455768085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5056; }","duration":"312.482254ms","start":"2026-10-17T10:49:26.368897Z","end":"2026-10-17T10:49:26.681379Z","steps":["trace[1455768085] 'agreement among raft nodes before linearized reading' (duration: 311.328639ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:49:26.681474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:49:26.368886Z","time spent":"312.578328ms","remote":"127.0.0.1:34404","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-10-17T10:49:26.696656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.713606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1bb043f3-6e0b-495e-b3b5-6f756b44c95b\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-17T10:49:26.696732Z","caller":"traceutil/trace.go:171","msg":"trace[1690620804] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1bb043f3-6e0b-495e-b3b5-6f756b44c95b; range_end:; response_count:1; response_revision:5057; }","duration":"147.831061ms","start":"2026-10-17T10:49:26.548877Z","end":"2026-10-17T10:49:26.696708Z","steps":["trace[1690620804] 'agreement among raft nodes before linearized reading' (duration: 147.623041ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:49:37.589584Z","caller":"traceutil/trace.go:171","msg":"trace[1354039307] transaction","detail":"{read_only:false; response_revision:5187; number_of_response:1; }","duration":"110.860973ms","start":"2026-10-17T10:49:37.478331Z","end":"2026-10-17T10:49:37.589192Z","steps":["trace[1354039307] 'process raft request' (duration: 69.189091ms)","trace[1354039307] 'compare' (duration: 41.518944ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:49:37.608807Z","caller":"traceutil/trace.go:171","msg":"trace[1529690984] transaction","detail":"{read_only:false; response_revision:5188; number_of_response:1; }","duration":"129.969506ms","start":"2026-10-17T10:49:37.478803Z","end":"2026-10-17T10:49:37.608773Z","steps":["trace[1529690984] 'process raft request' (duration: 129.83818ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:50:00.623Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2848} {"level":"info","ts":"2026-10-17T10:50:00.70824Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2848,"took":"83.773661ms","hash":1036776259,"current-db-size-bytes":52039680,"current-db-size":"52 MB","current-db-size-in-use-bytes":27385856,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-17T10:50:00.708345Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1036776259,"revision":2848,"compact-revision":-1} {"level":"info","ts":"2026-10-17T10:50:15.005383Z","caller":"traceutil/trace.go:171","msg":"trace[261535192] transaction","detail":"{read_only:false; response_revision:5507; number_of_response:1; }","duration":"116.013663ms","start":"2026-10-17T10:50:14.889336Z","end":"2026-10-17T10:50:15.00535Z","steps":["trace[261535192] 'process raft request' (duration: 115.869796ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:50:15.11428Z","caller":"traceutil/trace.go:171","msg":"trace[1511887006] linearizableReadLoop","detail":"{readStateIndex:5717; appliedIndex:5715; }","duration":"124.739346ms","start":"2026-10-17T10:50:14.989508Z","end":"2026-10-17T10:50:15.114248Z","steps":["trace[1511887006] 'read index received' (duration: 15.764021ms)","trace[1511887006] 'applied index is now lower than readState.Index' (duration: 108.974295ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:50:15.114751Z","caller":"traceutil/trace.go:171","msg":"trace[629785254] transaction","detail":"{read_only:false; response_revision:5508; number_of_response:1; }","duration":"214.358566ms","start":"2026-10-17T10:50:14.90035Z","end":"2026-10-17T10:50:15.114708Z","steps":["trace[629785254] 'process raft request' (duration: 180.793437ms)","trace[629785254] 'compare' (duration: 32.895946ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:50:15.114893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.365556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-17T10:50:15.11518Z","caller":"traceutil/trace.go:171","msg":"trace[2131610591] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5508; }","duration":"125.65302ms","start":"2026-10-17T10:50:14.989475Z","end":"2026-10-17T10:50:15.115128Z","steps":["trace[2131610591] 'agreement among raft nodes before linearized reading' (duration: 124.860841ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:55:00.628758Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5329} {"level":"info","ts":"2026-10-17T10:55:00.713765Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5329,"took":"83.55515ms","hash":4269132810,"current-db-size-bytes":52039680,"current-db-size":"52 MB","current-db-size-in-use-bytes":18452480,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-10-17T10:55:00.713807Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4269132810,"revision":5329,"compact-revision":2848}