{"level":"warn","ts":"2026-09-03T02:31:31.954834Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-03T02:31:31.95492Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.15: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.15:2380","--initial-cluster=instance=https://162.253.55.15:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.15:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.15: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-09-03T02:31:31.954989Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-03T02:31:31.954998Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.15:2380"]} {"level":"info","ts":"2026-09-03T02:31:31.955015Z","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-09-03T02:31:31.955607Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.15:2379"]} {"level":"info","ts":"2026-09-03T02:31:31.955678Z","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://162.253.55.15:2380"],"listen-peer-urls":["https://162.253.55.15:2380"],"advertise-client-urls":["https://162.253.55.15:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.15:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.15: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-09-03T02:31:31.961663Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.792277ms"} {"level":"info","ts":"2026-09-03T02:31:31.970907Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"337baeb286292d09","cluster-id":"3cc18487e1df591c"} {"level":"info","ts":"2026-09-03T02:31:31.971044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 switched to configuration voters=()"} {"level":"info","ts":"2026-09-03T02:31:31.971079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 became follower at term 0"} {"level":"info","ts":"2026-09-03T02:31:31.971098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 337baeb286292d09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-03T02:31:31.971117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 became follower at term 1"} {"level":"info","ts":"2026-09-03T02:31:31.971169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 switched to configuration voters=(3709750799847992585)"} {"level":"warn","ts":"2026-09-03T02:31:31.988654Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-03T02:31:31.990441Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-03T02:31:31.992302Z","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-09-03T02:31:31.995203Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"337baeb286292d09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-03T02:31:31.995482Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"337baeb286292d09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-03T02:31:31.995713Z","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-09-03T02:31:31.995829Z","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-09-03T02:31:31.995845Z","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-09-03T02:31:31.996832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 switched to configuration voters=(3709750799847992585)"} {"level":"info","ts":"2026-09-03T02:31:31.997022Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3cc18487e1df591c","local-member-id":"337baeb286292d09","added-peer-id":"337baeb286292d09","added-peer-peer-urls":["https://162.253.55.15:2380"]} {"level":"info","ts":"2026-09-03T02:31:32.000854Z","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-09-03T02:31:32.001057Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.15:2380"} {"level":"info","ts":"2026-09-03T02:31:32.001158Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.15:2380"} {"level":"info","ts":"2026-09-03T02:31:32.001259Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"337baeb286292d09","initial-advertise-peer-urls":["https://162.253.55.15:2380"],"listen-peer-urls":["https://162.253.55.15:2380"],"advertise-client-urls":["https://162.253.55.15:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.15:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-03T02:31:32.001334Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-03T02:31:32.771664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 is starting a new election at term 1"} {"level":"info","ts":"2026-09-03T02:31:32.771766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-03T02:31:32.771817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 received MsgPreVoteResp from 337baeb286292d09 at term 1"} {"level":"info","ts":"2026-09-03T02:31:32.77184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 became candidate at term 2"} {"level":"info","ts":"2026-09-03T02:31:32.771852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 received MsgVoteResp from 337baeb286292d09 at term 2"} {"level":"info","ts":"2026-09-03T02:31:32.771871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 became leader at term 2"} {"level":"info","ts":"2026-09-03T02:31:32.77189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 337baeb286292d09 elected leader 337baeb286292d09 at term 2"} {"level":"info","ts":"2026-09-03T02:31:32.773478Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"337baeb286292d09","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.15:2379]}","request-path":"/0/members/337baeb286292d09/attributes","cluster-id":"3cc18487e1df591c","publish-timeout":"7s"} {"level":"info","ts":"2026-09-03T02:31:32.773494Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-03T02:31:32.773532Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-03T02:31:32.773514Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-03T02:31:32.773876Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-03T02:31:32.773959Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-03T02:31:32.774615Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3cc18487e1df591c","local-member-id":"337baeb286292d09","cluster-version":"3.5"} {"level":"info","ts":"2026-09-03T02:31:32.774766Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-03T02:31:32.774807Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-03T02:31:32.77543Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-03T02:31:32.779092Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.15:2379"} {"level":"info","ts":"2026-09-03T02:31:38.280696Z","caller":"traceutil/trace.go:171","msg":"trace[1024766003] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"102.48092ms","start":"2026-09-03T02:31:38.178192Z","end":"2026-09-03T02:31:38.280673Z","steps":["trace[1024766003] 'process raft request' (duration: 102.381417ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:31:42.006789Z","caller":"traceutil/trace.go:171","msg":"trace[2007675356] linearizableReadLoop","detail":"{readStateIndex:358; appliedIndex:356; }","duration":"129.901734ms","start":"2026-09-03T02:31:41.876855Z","end":"2026-09-03T02:31:42.006757Z","steps":["trace[2007675356] 'read index received' (duration: 25.076014ms)","trace[2007675356] 'applied index is now lower than readState.Index' (duration: 104.82447ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:31:42.00685Z","caller":"traceutil/trace.go:171","msg":"trace[862590664] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"150.463029ms","start":"2026-09-03T02:31:41.856349Z","end":"2026-09-03T02:31:42.006812Z","steps":["trace[862590664] 'process raft request' (duration: 114.223856ms)","trace[862590664] 'compare' (duration: 36.059978ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T02:31:42.007106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.120201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-09-03T02:31:42.007186Z","caller":"traceutil/trace.go:171","msg":"trace[309164335] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:347; }","duration":"130.341157ms","start":"2026-09-03T02:31:41.876828Z","end":"2026-09-03T02:31:42.00717Z","steps":["trace[309164335] 'agreement among raft nodes before linearized reading' (duration: 130.078839ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:31:42.259724Z","caller":"traceutil/trace.go:171","msg":"trace[588273626] linearizableReadLoop","detail":"{readStateIndex:360; appliedIndex:359; }","duration":"131.882234ms","start":"2026-09-03T02:31:42.127814Z","end":"2026-09-03T02:31:42.259696Z","steps":["trace[588273626] 'read index received' (duration: 40.833763ms)","trace[588273626] 'applied index is now lower than readState.Index' (duration: 91.047141ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T02:31:42.259918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.07819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-09-03T02:31:42.259958Z","caller":"traceutil/trace.go:171","msg":"trace[1526921731] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:349; }","duration":"132.181144ms","start":"2026-09-03T02:31:42.127765Z","end":"2026-09-03T02:31:42.259946Z","steps":["trace[1526921731] 'agreement among raft nodes before linearized reading' (duration: 132.083451ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:31:42.259936Z","caller":"traceutil/trace.go:171","msg":"trace[1587131527] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"150.022447ms","start":"2026-09-03T02:31:42.109871Z","end":"2026-09-03T02:31:42.259893Z","steps":["trace[1587131527] 'process raft request' (duration: 58.757429ms)","trace[1587131527] 'compare' (duration: 90.924358ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:31:42.63932Z","caller":"traceutil/trace.go:171","msg":"trace[223090219] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"176.528344ms","start":"2026-09-03T02:31:42.462764Z","end":"2026-09-03T02:31:42.639293Z","steps":["trace[223090219] 'process raft request' (duration: 105.386288ms)","trace[223090219] 'compare' (duration: 70.973211ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:31:42.639483Z","caller":"traceutil/trace.go:171","msg":"trace[637537757] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"174.992487ms","start":"2026-09-03T02:31:42.464474Z","end":"2026-09-03T02:31:42.639466Z","steps":["trace[637537757] 'process raft request' (duration: 174.75893ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:31:42.63955Z","caller":"traceutil/trace.go:171","msg":"trace[498573201] linearizableReadLoop","detail":"{readStateIndex:365; appliedIndex:361; }","duration":"161.800526ms","start":"2026-09-03T02:31:42.477727Z","end":"2026-09-03T02:31:42.639528Z","steps":["trace[498573201] 'read index received' (duration: 51.440096ms)","trace[498573201] 'applied index is now lower than readState.Index' (duration: 110.35953ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T02:31:42.639761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.013042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-09-03T02:31:42.639774Z","caller":"traceutil/trace.go:171","msg":"trace[468066679] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"172.48387ms","start":"2026-09-03T02:31:42.46728Z","end":"2026-09-03T02:31:42.639764Z","steps":["trace[468066679] 'process raft request' (duration: 172.129479ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:31:42.639798Z","caller":"traceutil/trace.go:171","msg":"trace[365043068] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:354; }","duration":"162.076703ms","start":"2026-09-03T02:31:42.477712Z","end":"2026-09-03T02:31:42.639788Z","steps":["trace[365043068] 'agreement among raft nodes before linearized reading' (duration: 161.993781ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:31:42.639799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.605847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-09-03T02:31:42.639866Z","caller":"traceutil/trace.go:171","msg":"trace[1529455320] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:354; }","duration":"111.711801ms","start":"2026-09-03T02:31:42.528136Z","end":"2026-09-03T02:31:42.639848Z","steps":["trace[1529455320] 'agreement among raft nodes before linearized reading' (duration: 111.605768ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:31:42.843394Z","caller":"traceutil/trace.go:171","msg":"trace[853944529] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"130.575465ms","start":"2026-09-03T02:31:42.712772Z","end":"2026-09-03T02:31:42.843348Z","steps":["trace[853944529] 'process raft request' (duration: 117.867568ms)","trace[853944529] 'compare' (duration: 12.329115ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:31:42.843439Z","caller":"traceutil/trace.go:171","msg":"trace[880300863] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"130.027088ms","start":"2026-09-03T02:31:42.713379Z","end":"2026-09-03T02:31:42.843406Z","steps":["trace[880300863] 'process raft request' (duration: 129.837842ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:31:42.843524Z","caller":"traceutil/trace.go:171","msg":"trace[355336858] linearizableReadLoop","detail":"{readStateIndex:377; appliedIndex:371; }","duration":"116.30167ms","start":"2026-09-03T02:31:42.727172Z","end":"2026-09-03T02:31:42.843474Z","steps":["trace[355336858] 'read index received' (duration: 4.117035ms)","trace[355336858] 'applied index is now lower than readState.Index' (duration: 112.182795ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:31:42.843597Z","caller":"traceutil/trace.go:171","msg":"trace[2031741381] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"128.076209ms","start":"2026-09-03T02:31:42.715494Z","end":"2026-09-03T02:31:42.843571Z","steps":["trace[2031741381] 'process raft request' (duration: 127.882533ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:31:42.843674Z","caller":"traceutil/trace.go:171","msg":"trace[203916814] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"124.424448ms","start":"2026-09-03T02:31:42.719225Z","end":"2026-09-03T02:31:42.84365Z","steps":["trace[203916814] 'process raft request' (duration: 124.199421ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:31:42.843797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.599239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-09-03T02:31:42.843862Z","caller":"traceutil/trace.go:171","msg":"trace[659231206] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:366; }","duration":"116.728163ms","start":"2026-09-03T02:31:42.727111Z","end":"2026-09-03T02:31:42.843839Z","steps":["trace[659231206] 'agreement among raft nodes before linearized reading' (duration: 116.586878ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:31:42.84351Z","caller":"traceutil/trace.go:171","msg":"trace[1459092934] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"129.257105ms","start":"2026-09-03T02:31:42.714214Z","end":"2026-09-03T02:31:42.843471Z","steps":["trace[1459092934] 'process raft request' (duration: 129.0896ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:31:43.143792Z","caller":"traceutil/trace.go:171","msg":"trace[630348653] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"295.178135ms","start":"2026-09-03T02:31:42.848576Z","end":"2026-09-03T02:31:43.143755Z","steps":["trace[630348653] 'process raft request' (duration: 270.106781ms)","trace[630348653] 'compare' (duration: 24.815896ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:31:43.144019Z","caller":"traceutil/trace.go:171","msg":"trace[467016207] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"294.240596ms","start":"2026-09-03T02:31:42.849772Z","end":"2026-09-03T02:31:43.144012Z","steps":["trace[467016207] 'process raft request' (duration: 293.893586ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:31:43.14421Z","caller":"traceutil/trace.go:171","msg":"trace[2075090501] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:378; }","duration":"294.117983ms","start":"2026-09-03T02:31:42.850084Z","end":"2026-09-03T02:31:43.144202Z","steps":["trace[2075090501] 'read index received' (duration: 47.225288ms)","trace[2075090501] 'applied index is now lower than readState.Index' (duration: 246.891955ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T02:31:43.144317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.213957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-09-03T02:31:43.14435Z","caller":"traceutil/trace.go:171","msg":"trace[1876473318] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:371; }","duration":"294.300459ms","start":"2026-09-03T02:31:42.850039Z","end":"2026-09-03T02:31:43.144339Z","steps":["trace[1876473318] 'agreement among raft nodes before linearized reading' (duration: 294.220627ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:31:43.144291Z","caller":"traceutil/trace.go:171","msg":"trace[1407319194] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"294.125723ms","start":"2026-09-03T02:31:42.850128Z","end":"2026-09-03T02:31:43.144254Z","steps":["trace[1407319194] 'process raft request' (duration: 293.922166ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:31:43.144396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.815182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-09-03T02:31:43.144461Z","caller":"traceutil/trace.go:171","msg":"trace[535320866] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:371; }","duration":"266.888284ms","start":"2026-09-03T02:31:42.877555Z","end":"2026-09-03T02:31:43.144443Z","steps":["trace[535320866] 'agreement among raft nodes before linearized reading' (duration: 266.73838ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:31:43.144817Z","caller":"traceutil/trace.go:171","msg":"trace[845458650] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"294.018129ms","start":"2026-09-03T02:31:42.850096Z","end":"2026-09-03T02:31:43.144114Z","steps":["trace[845458650] 'process raft request' (duration: 293.840404ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:31:43.359618Z","caller":"traceutil/trace.go:171","msg":"trace[1572585139] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"206.466245ms","start":"2026-09-03T02:31:43.153129Z","end":"2026-09-03T02:31:43.359596Z","steps":["trace[1572585139] 'process raft request' (duration: 206.330611ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:31:43.359781Z","caller":"traceutil/trace.go:171","msg":"trace[199153332] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"205.513425ms","start":"2026-09-03T02:31:43.154233Z","end":"2026-09-03T02:31:43.359746Z","steps":["trace[199153332] 'process raft request' (duration: 205.289929ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:31:43.359792Z","caller":"traceutil/trace.go:171","msg":"trace[1681122830] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"201.012528ms","start":"2026-09-03T02:31:43.158761Z","end":"2026-09-03T02:31:43.359773Z","steps":["trace[1681122830] 'process raft request' (duration: 200.895214ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:31:43.360149Z","caller":"traceutil/trace.go:171","msg":"trace[693587688] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"210.495408ms","start":"2026-09-03T02:31:43.149588Z","end":"2026-09-03T02:31:43.360083Z","steps":["trace[693587688] 'process raft request' (duration: 152.558584ms)","trace[693587688] 'compare' (duration: 57.082588ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:31:43.55478Z","caller":"traceutil/trace.go:171","msg":"trace[1854562779] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"102.227682ms","start":"2026-09-03T02:31:43.452509Z","end":"2026-09-03T02:31:43.554737Z","steps":["trace[1854562779] 'process raft request' (duration: 77.126038ms)","trace[1854562779] 'compare' (duration: 24.889408ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:31:43.744919Z","caller":"traceutil/trace.go:171","msg":"trace[1226088923] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"290.857683ms","start":"2026-09-03T02:31:43.454037Z","end":"2026-09-03T02:31:43.744894Z","steps":["trace[1226088923] 'process raft request' (duration: 290.77952ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:31:43.745027Z","caller":"traceutil/trace.go:171","msg":"trace[111586085] linearizableReadLoop","detail":"{readStateIndex:398; appliedIndex:394; }","duration":"117.665121ms","start":"2026-09-03T02:31:43.627352Z","end":"2026-09-03T02:31:43.745017Z","steps":["trace[111586085] 'read index received' (duration: 61.115489ms)","trace[111586085] 'applied index is now lower than readState.Index' (duration: 56.548512ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:31:43.744919Z","caller":"traceutil/trace.go:171","msg":"trace[1056529890] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"292.331637ms","start":"2026-09-03T02:31:43.452559Z","end":"2026-09-03T02:31:43.744891Z","steps":["trace[1056529890] 'process raft request' (duration: 236.002003ms)","trace[1056529890] 'compare' (duration: 56.052696ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T02:31:43.745144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.781395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-03T02:31:43.745181Z","caller":"traceutil/trace.go:171","msg":"trace[1365649914] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:387; }","duration":"117.874889ms","start":"2026-09-03T02:31:43.627294Z","end":"2026-09-03T02:31:43.745169Z","steps":["trace[1365649914] 'agreement among raft nodes before linearized reading' (duration: 117.768456ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:31:43.745221Z","caller":"traceutil/trace.go:171","msg":"trace[1764368647] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"288.1099ms","start":"2026-09-03T02:31:43.457037Z","end":"2026-09-03T02:31:43.745147Z","steps":["trace[1764368647] 'process raft request' (duration: 287.80562ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:31:43.745453Z","caller":"traceutil/trace.go:171","msg":"trace[1721244883] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"291.72466ms","start":"2026-09-03T02:31:43.453709Z","end":"2026-09-03T02:31:43.745434Z","steps":["trace[1721244883] 'process raft request' (duration: 291.054049ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:31:43.925292Z","caller":"traceutil/trace.go:171","msg":"trace[36766313] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"172.360346ms","start":"2026-09-03T02:31:43.752889Z","end":"2026-09-03T02:31:43.92525Z","steps":["trace[36766313] 'process raft request' (duration: 98.935742ms)","trace[36766313] 'compare' (duration: 73.237579ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T02:31:44.233621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.337059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3819"} {"level":"info","ts":"2026-09-03T02:31:44.233714Z","caller":"traceutil/trace.go:171","msg":"trace[1916152577] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:390; }","duration":"201.500834ms","start":"2026-09-03T02:31:44.03219Z","end":"2026-09-03T02:31:44.233691Z","steps":["trace[1916152577] 'range keys from in-memory index tree' (duration: 201.224434ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:31:44.796436Z","caller":"traceutil/trace.go:171","msg":"trace[1475757818] linearizableReadLoop","detail":"{readStateIndex:402; appliedIndex:401; }","duration":"153.79116ms","start":"2026-09-03T02:31:44.642607Z","end":"2026-09-03T02:31:44.796398Z","steps":["trace[1475757818] 'read index received' (duration: 153.678787ms)","trace[1475757818] 'applied index is now lower than readState.Index' (duration: 110.983µs)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:31:44.796527Z","caller":"traceutil/trace.go:171","msg":"trace[1817412517] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"259.081376ms","start":"2026-09-03T02:31:44.537408Z","end":"2026-09-03T02:31:44.796489Z","steps":["trace[1817412517] 'process raft request' (duration: 258.742895ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:31:44.796696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.055949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-03T02:31:44.796792Z","caller":"traceutil/trace.go:171","msg":"trace[1178801199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:391; }","duration":"154.225724ms","start":"2026-09-03T02:31:44.642545Z","end":"2026-09-03T02:31:44.796771Z","steps":["trace[1178801199] 'agreement among raft nodes before linearized reading' (duration: 154.010948ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:31:45.250259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.849533ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-03T02:31:45.250474Z","caller":"traceutil/trace.go:171","msg":"trace[1954077588] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"448.737779ms","start":"2026-09-03T02:31:44.80172Z","end":"2026-09-03T02:31:45.250458Z","steps":["trace[1954077588] 'process raft request' (duration: 448.674377ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:31:45.250486Z","caller":"traceutil/trace.go:171","msg":"trace[2033784312] linearizableReadLoop","detail":"{readStateIndex:403; appliedIndex:402; }","duration":"453.823674ms","start":"2026-09-03T02:31:44.796634Z","end":"2026-09-03T02:31:45.250458Z","steps":["trace[2033784312] 'read index received' (duration: 70.177567ms)","trace[2033784312] 'applied index is now lower than readState.Index' (duration: 383.643337ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:31:45.250511Z","caller":"traceutil/trace.go:171","msg":"trace[1299432150] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"552.451967ms","start":"2026-09-03T02:31:44.698014Z","end":"2026-09-03T02:31:45.250466Z","steps":["trace[1299432150] 'process raft request' (duration: 168.785778ms)","trace[1299432150] 'compare' (duration: 382.708789ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T02:31:45.250568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:31:44.801699Z","time spent":"448.819782ms","remote":"127.0.0.1:40316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-03T02:31:45.250657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.573164ms","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-09-03T02:31:45.250692Z","caller":"traceutil/trace.go:171","msg":"trace[1128922338] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:393; }","duration":"515.644156ms","start":"2026-09-03T02:31:44.735038Z","end":"2026-09-03T02:31:45.250682Z","steps":["trace[1128922338] 'agreement among raft nodes before linearized reading' (duration: 515.522702ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:31:45.250723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:31:44.735022Z","time spent":"515.692637ms","remote":"127.0.0.1:40316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-03T02:31:45.250779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:31:44.697953Z","time spent":"552.683704ms","remote":"127.0.0.1:40316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-03T02:31:45.250713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.19657ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:31:45.250893Z","caller":"traceutil/trace.go:171","msg":"trace[159809707] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:393; }","duration":"511.413856ms","start":"2026-09-03T02:31:44.739451Z","end":"2026-09-03T02:31:45.250864Z","steps":["trace[159809707] 'agreement among raft nodes before linearized reading' (duration: 511.124658ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:31:45.451377Z","caller":"traceutil/trace.go:171","msg":"trace[68339720] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"174.972926ms","start":"2026-09-03T02:31:45.276353Z","end":"2026-09-03T02:31:45.451326Z","steps":["trace[68339720] 'process raft request' (duration: 160.361142ms)","trace[68339720] 'compare' (duration: 14.409858ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:31:45.651103Z","caller":"traceutil/trace.go:171","msg":"trace[1964413470] linearizableReadLoop","detail":"{readStateIndex:407; appliedIndex:406; }","duration":"196.272455ms","start":"2026-09-03T02:31:45.454803Z","end":"2026-09-03T02:31:45.651075Z","steps":["trace[1964413470] 'read index received' (duration: 97.026974ms)","trace[1964413470] 'applied index is now lower than readState.Index' (duration: 99.244261ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:31:45.651136Z","caller":"traceutil/trace.go:171","msg":"trace[2122174125] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"297.295319ms","start":"2026-09-03T02:31:45.353816Z","end":"2026-09-03T02:31:45.651111Z","steps":["trace[2122174125] 'process raft request' (duration: 198.073798ms)","trace[2122174125] 'compare' (duration: 99.024365ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T02:31:45.651295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.46668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"info","ts":"2026-09-03T02:31:45.651343Z","caller":"traceutil/trace.go:171","msg":"trace[256037709] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:396; }","duration":"196.569143ms","start":"2026-09-03T02:31:45.45476Z","end":"2026-09-03T02:31:45.65133Z","steps":["trace[256037709] 'agreement among raft nodes before linearized reading' (duration: 196.410118ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:31:45.797042Z","caller":"traceutil/trace.go:171","msg":"trace[289662140] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"137.575377ms","start":"2026-09-03T02:31:45.659452Z","end":"2026-09-03T02:31:45.797027Z","steps":["trace[289662140] 'process raft request' (duration: 104.267133ms)","trace[289662140] 'compare' (duration: 33.198601ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:31:46.61942Z","caller":"traceutil/trace.go:171","msg":"trace[1424906092] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"108.470171ms","start":"2026-09-03T02:31:46.510916Z","end":"2026-09-03T02:31:46.619387Z","steps":["trace[1424906092] 'process raft request' (duration: 108.176312ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:31:47.205838Z","caller":"traceutil/trace.go:171","msg":"trace[932259271] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"115.95487ms","start":"2026-09-03T02:31:47.089848Z","end":"2026-09-03T02:31:47.205803Z","steps":["trace[932259271] 'process raft request' (duration: 74.756276ms)","trace[932259271] 'compare' (duration: 41.05309ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T02:31:52.682687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.629212ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-03T02:31:52.682802Z","caller":"traceutil/trace.go:171","msg":"trace[1836094933] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:435; }","duration":"795.485434ms","start":"2026-09-03T02:31:51.8873Z","end":"2026-09-03T02:31:52.682785Z","steps":["trace[1836094933] 'read index received' (duration: 50.191µs)","trace[1836094933] 'applied index is now lower than readState.Index' (duration: 795.433753ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T02:31:52.682903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.021341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:31:52.682937Z","caller":"traceutil/trace.go:171","msg":"trace[1658871904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:422; }","duration":"206.075043ms","start":"2026-09-03T02:31:52.476849Z","end":"2026-09-03T02:31:52.682924Z","steps":["trace[1658871904] 'agreement among raft nodes before linearized reading' (duration: 206.017931ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:31:52.68293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.626889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-03T02:31:52.683021Z","caller":"traceutil/trace.go:171","msg":"trace[464088324] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:422; }","duration":"795.733432ms","start":"2026-09-03T02:31:51.887247Z","end":"2026-09-03T02:31:52.68298Z","steps":["trace[464088324] 'agreement among raft nodes before linearized reading' (duration: 795.594538ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:31:52.683072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:31:51.887226Z","time spent":"795.832314ms","remote":"127.0.0.1:40316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-03T02:32:19.16526Z","caller":"traceutil/trace.go:171","msg":"trace[541914763] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"104.976701ms","start":"2026-09-03T02:32:19.060244Z","end":"2026-09-03T02:32:19.165221Z","steps":["trace[541914763] 'process raft request' (duration: 104.793776ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:32:26.007743Z","caller":"traceutil/trace.go:171","msg":"trace[1050801921] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:677; }","duration":"419.998447ms","start":"2026-09-03T02:32:25.58771Z","end":"2026-09-03T02:32:26.007708Z","steps":["trace[1050801921] 'read index received' (duration: 419.76188ms)","trace[1050801921] 'applied index is now lower than readState.Index' (duration: 235.217µs)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:32:26.007886Z","caller":"traceutil/trace.go:171","msg":"trace[1502429115] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"433.091425ms","start":"2026-09-03T02:32:25.574735Z","end":"2026-09-03T02:32:26.007827Z","steps":["trace[1502429115] 'process raft request' (duration: 432.787146ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:32:26.008178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:32:25.574708Z","time spent":"433.324602ms","remote":"127.0.0.1:40400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-03T02:32:26.008276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.533963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-03T02:32:26.008391Z","caller":"traceutil/trace.go:171","msg":"trace[9840737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:654; }","duration":"420.730029ms","start":"2026-09-03T02:32:25.587634Z","end":"2026-09-03T02:32:26.008364Z","steps":["trace[9840737] 'agreement among raft nodes before linearized reading' (duration: 420.241434ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:32:26.008452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:32:25.587605Z","time spent":"420.837033ms","remote":"127.0.0.1:40316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-03T02:32:26.008272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.04817ms","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-09-03T02:32:26.008517Z","caller":"traceutil/trace.go:171","msg":"trace[1396440413] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:654; }","duration":"302.364191ms","start":"2026-09-03T02:32:25.706134Z","end":"2026-09-03T02:32:26.008498Z","steps":["trace[1396440413] 'agreement among raft nodes before linearized reading' (duration: 301.932787ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:32:26.008569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:32:25.706114Z","time spent":"302.439623ms","remote":"127.0.0.1:40316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-03T02:32:26.716373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.973716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-03T02:32:26.716519Z","caller":"traceutil/trace.go:171","msg":"trace[348369185] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:681; }","duration":"624.087001ms","start":"2026-09-03T02:32:26.092408Z","end":"2026-09-03T02:32:26.716495Z","steps":["trace[348369185] 'read index received' (duration: 377.63912ms)","trace[348369185] 'applied index is now lower than readState.Index' (duration: 246.445871ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:32:26.716596Z","caller":"traceutil/trace.go:171","msg":"trace[1512482163] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"661.034464ms","start":"2026-09-03T02:32:26.055486Z","end":"2026-09-03T02:32:26.71652Z","steps":["trace[1512482163] 'process raft request' (duration: 415.81125ms)","trace[1512482163] 'compare' (duration: 244.804781ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T02:32:26.716788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.378899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fhmk7\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-09-03T02:32:26.716842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:32:26.05546Z","time spent":"661.25251ms","remote":"127.0.0.1:40062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-03T02:32:26.716853Z","caller":"traceutil/trace.go:171","msg":"trace[2087237337] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fhmk7; range_end:; response_count:1; response_revision:657; }","duration":"624.478732ms","start":"2026-09-03T02:32:26.092358Z","end":"2026-09-03T02:32:26.716837Z","steps":["trace[2087237337] 'agreement among raft nodes before linearized reading' (duration: 624.229585ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:32:26.716907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:32:26.092338Z","time spent":"624.556866ms","remote":"127.0.0.1:40242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-fhmk7\" "} {"level":"info","ts":"2026-09-03T02:32:26.727155Z","caller":"traceutil/trace.go:171","msg":"trace[847701388] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"379.375842ms","start":"2026-09-03T02:32:26.347759Z","end":"2026-09-03T02:32:26.727135Z","steps":["trace[847701388] 'process raft request' (duration: 379.167786ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:32:26.727276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:32:26.34772Z","time spent":"379.491335ms","remote":"127.0.0.1:40316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-03T02:32:26.727966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.233395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:32:26.728066Z","caller":"traceutil/trace.go:171","msg":"trace[2052860243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"250.37116ms","start":"2026-09-03T02:32:26.477675Z","end":"2026-09-03T02:32:26.728046Z","steps":["trace[2052860243] 'agreement among raft nodes before linearized reading' (duration: 250.211095ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:32:26.728364Z","caller":"traceutil/trace.go:171","msg":"trace[1952467935] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"289.859001ms","start":"2026-09-03T02:32:26.438486Z","end":"2026-09-03T02:32:26.728345Z","steps":["trace[1952467935] 'process raft request' (duration: 288.764737ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:32:43.729998Z","caller":"traceutil/trace.go:171","msg":"trace[544934737] linearizableReadLoop","detail":"{readStateIndex:861; appliedIndex:860; }","duration":"401.252556ms","start":"2026-09-03T02:32:43.328711Z","end":"2026-09-03T02:32:43.729963Z","steps":["trace[544934737] 'read index received' (duration: 310.27155ms)","trace[544934737] 'applied index is now lower than readState.Index' (duration: 90.979656ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:32:43.730062Z","caller":"traceutil/trace.go:171","msg":"trace[2140432132] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"429.459034ms","start":"2026-09-03T02:32:43.300568Z","end":"2026-09-03T02:32:43.730027Z","steps":["trace[2140432132] 'process raft request' (duration: 338.294543ms)","trace[2140432132] 'compare' (duration: 90.837101ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T02:32:43.730235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.502215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:32:43.730276Z","caller":"traceutil/trace.go:171","msg":"trace[1848616677] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:829; }","duration":"401.566746ms","start":"2026-09-03T02:32:43.328694Z","end":"2026-09-03T02:32:43.730261Z","steps":["trace[1848616677] 'agreement among raft nodes before linearized reading' (duration: 401.478914ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:32:43.730295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:32:43.328595Z","time spent":"401.69477ms","remote":"127.0.0.1:52098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 "} {"level":"warn","ts":"2026-09-03T02:32:43.730293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:32:43.300547Z","time spent":"429.66805ms","remote":"127.0.0.1:40316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-03T02:32:43.730528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.17513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:32:43.730556Z","caller":"traceutil/trace.go:171","msg":"trace[1727954936] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:829; }","duration":"314.224462ms","start":"2026-09-03T02:32:43.416325Z","end":"2026-09-03T02:32:43.730549Z","steps":["trace[1727954936] 'agreement among raft nodes before linearized reading' (duration: 314.181441ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:32:43.730546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.68228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-03T02:32:43.7306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.127108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:32:43.730616Z","caller":"traceutil/trace.go:171","msg":"trace[1781759813] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:829; }","duration":"238.152449ms","start":"2026-09-03T02:32:43.492458Z","end":"2026-09-03T02:32:43.73061Z","steps":["trace[1781759813] 'agreement among raft nodes before linearized reading' (duration: 238.126108ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:32:43.730652Z","caller":"traceutil/trace.go:171","msg":"trace[92471902] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:829; }","duration":"401.775992ms","start":"2026-09-03T02:32:43.328817Z","end":"2026-09-03T02:32:43.730593Z","steps":["trace[92471902] 'agreement among raft nodes before linearized reading' (duration: 401.671879ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:32:43.730709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:32:43.328807Z","time spent":"401.886936ms","remote":"127.0.0.1:52098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-03T02:32:43.730918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.947553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-03T02:32:43.730965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.163576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:32:43.730967Z","caller":"traceutil/trace.go:171","msg":"trace[10090661] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:829; }","duration":"315.020076ms","start":"2026-09-03T02:32:43.415929Z","end":"2026-09-03T02:32:43.730949Z","steps":["trace[10090661] 'agreement among raft nodes before linearized reading' (duration: 314.945944ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:32:43.730986Z","caller":"traceutil/trace.go:171","msg":"trace[4794897] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:829; }","duration":"256.197778ms","start":"2026-09-03T02:32:43.474779Z","end":"2026-09-03T02:32:43.730977Z","steps":["trace[4794897] 'agreement among raft nodes before linearized reading' (duration: 256.164287ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:32:43.730988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.491462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-03T02:32:43.731032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:32:43.415915Z","time spent":"315.080577ms","remote":"127.0.0.1:52142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-09-03T02:32:43.731033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.143486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-03T02:32:43.730997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.786184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-03T02:32:43.731058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.919381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-03T02:32:43.731106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.681998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:32:43.731101Z","caller":"traceutil/trace.go:171","msg":"trace[1824188834] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:829; }","duration":"256.25954ms","start":"2026-09-03T02:32:43.474818Z","end":"2026-09-03T02:32:43.731077Z","steps":["trace[1824188834] 'agreement among raft nodes before linearized reading' (duration: 256.111735ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:32:43.731093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.733904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:32:43.731127Z","caller":"traceutil/trace.go:171","msg":"trace[1777312464] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:829; }","duration":"297.724979ms","start":"2026-09-03T02:32:43.433396Z","end":"2026-09-03T02:32:43.731121Z","steps":["trace[1777312464] 'agreement among raft nodes before linearized reading' (duration: 297.691838ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:32:43.731125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.74553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:32:43.731124Z","caller":"traceutil/trace.go:171","msg":"trace[657048750] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:829; }","duration":"239.019754ms","start":"2026-09-03T02:32:43.492085Z","end":"2026-09-03T02:32:43.731104Z","steps":["trace[657048750] 'agreement among raft nodes before linearized reading' (duration: 238.87577ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:32:43.73116Z","caller":"traceutil/trace.go:171","msg":"trace[107910030] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:829; }","duration":"281.837317ms","start":"2026-09-03T02:32:43.449305Z","end":"2026-09-03T02:32:43.731142Z","steps":["trace[107910030] 'agreement among raft nodes before linearized reading' (duration: 281.734054ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:32:43.73117Z","caller":"traceutil/trace.go:171","msg":"trace[1295057359] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:829; }","duration":"296.812262ms","start":"2026-09-03T02:32:43.434344Z","end":"2026-09-03T02:32:43.731157Z","steps":["trace[1295057359] 'agreement among raft nodes before linearized reading' (duration: 296.74286ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:32:43.730926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.398534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-03T02:32:43.731182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.108594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:32:43.731239Z","caller":"traceutil/trace.go:171","msg":"trace[2100986799] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:829; }","duration":"281.727963ms","start":"2026-09-03T02:32:43.449495Z","end":"2026-09-03T02:32:43.731223Z","steps":["trace[2100986799] 'agreement among raft nodes before linearized reading' (duration: 281.398683ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:32:43.73106Z","caller":"traceutil/trace.go:171","msg":"trace[819121185] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:829; }","duration":"297.585875ms","start":"2026-09-03T02:32:43.433458Z","end":"2026-09-03T02:32:43.731044Z","steps":["trace[819121185] 'agreement among raft nodes before linearized reading' (duration: 297.478321ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:32:43.731256Z","caller":"traceutil/trace.go:171","msg":"trace[1237054951] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:829; }","duration":"382.240028ms","start":"2026-09-03T02:32:43.348995Z","end":"2026-09-03T02:32:43.731235Z","steps":["trace[1237054951] 'agreement among raft nodes before linearized reading' (duration: 382.159236ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:32:43.731266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.225332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-03T02:32:43.731071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.483046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-03T02:32:43.731293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.760015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:32:43.73131Z","caller":"traceutil/trace.go:171","msg":"trace[1525224476] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:829; }","duration":"281.734803ms","start":"2026-09-03T02:32:43.449569Z","end":"2026-09-03T02:32:43.731303Z","steps":["trace[1525224476] 'agreement among raft nodes before linearized reading' (duration: 281.482756ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:32:43.731299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:32:43.34896Z","time spent":"382.324971ms","remote":"127.0.0.1:52102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-09-03T02:32:43.731329Z","caller":"traceutil/trace.go:171","msg":"trace[396480040] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:829; }","duration":"239.316004ms","start":"2026-09-03T02:32:43.491978Z","end":"2026-09-03T02:32:43.731294Z","steps":["trace[396480040] 'agreement among raft nodes before linearized reading' (duration: 239.253122ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:32:43.731335Z","caller":"traceutil/trace.go:171","msg":"trace[2101769517] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:829; }","duration":"335.820967ms","start":"2026-09-03T02:32:43.395502Z","end":"2026-09-03T02:32:43.731323Z","steps":["trace[2101769517] 'agreement among raft nodes before linearized reading' (duration: 335.765645ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:32:43.731132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.703982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:32:43.731377Z","caller":"traceutil/trace.go:171","msg":"trace[2028049967] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:829; }","duration":"381.97585ms","start":"2026-09-03T02:32:43.349394Z","end":"2026-09-03T02:32:43.73137Z","steps":["trace[2028049967] 'agreement among raft nodes before linearized reading' (duration: 381.704952ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:32:43.731058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.052043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-03T02:32:43.731398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:32:43.349379Z","time spent":"382.010311ms","remote":"127.0.0.1:52102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 "} {"level":"info","ts":"2026-09-03T02:32:43.731127Z","caller":"traceutil/trace.go:171","msg":"trace[882790585] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:829; }","duration":"353.932388ms","start":"2026-09-03T02:32:43.377165Z","end":"2026-09-03T02:32:43.731098Z","steps":["trace[882790585] 'agreement among raft nodes before linearized reading' (duration: 353.720242ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:32:43.731198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.304894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:32:43.731422Z","caller":"traceutil/trace.go:171","msg":"trace[1583022392] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:829; }","duration":"382.483126ms","start":"2026-09-03T02:32:43.348919Z","end":"2026-09-03T02:32:43.731402Z","steps":["trace[1583022392] 'agreement among raft nodes before linearized reading' (duration: 382.013372ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:32:43.731454Z","caller":"traceutil/trace.go:171","msg":"trace[294181230] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:829; }","duration":"315.581593ms","start":"2026-09-03T02:32:43.415864Z","end":"2026-09-03T02:32:43.731446Z","steps":["trace[294181230] 'agreement among raft nodes before linearized reading' (duration: 315.294634ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:32:43.73143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.943591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-03T02:32:43.731476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:32:43.415857Z","time spent":"315.611793ms","remote":"127.0.0.1:52142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-09-03T02:32:43.731474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:32:43.348904Z","time spent":"382.551838ms","remote":"127.0.0.1:52102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-09-03T02:32:43.731247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.894829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:32:43.731496Z","caller":"traceutil/trace.go:171","msg":"trace[1602396311] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:829; }","duration":"336.032414ms","start":"2026-09-03T02:32:43.395451Z","end":"2026-09-03T02:32:43.731483Z","steps":["trace[1602396311] 'agreement among raft nodes before linearized reading' (duration: 335.948301ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:32:43.731487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.005849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:32:43.731523Z","caller":"traceutil/trace.go:171","msg":"trace[611331112] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:829; }","duration":"335.165288ms","start":"2026-09-03T02:32:43.396341Z","end":"2026-09-03T02:32:43.731507Z","steps":["trace[611331112] 'agreement among raft nodes before linearized reading' (duration: 334.878919ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:32:43.731545Z","caller":"traceutil/trace.go:171","msg":"trace[811416751] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:829; }","duration":"402.089803ms","start":"2026-09-03T02:32:43.329438Z","end":"2026-09-03T02:32:43.731528Z","steps":["trace[811416751] 'agreement among raft nodes before linearized reading' (duration: 401.999259ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:32:43.731539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:32:43.395436Z","time spent":"336.089757ms","remote":"127.0.0.1:52126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-09-03T02:32:43.731569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:32:43.396307Z","time spent":"335.246621ms","remote":"127.0.0.1:52126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-09-03T02:32:43.731567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:32:43.329422Z","time spent":"402.137684ms","remote":"127.0.0.1:52098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 "} {"level":"warn","ts":"2026-09-03T02:32:43.730572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:32:43.41631Z","time spent":"314.257883ms","remote":"127.0.0.1:52142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-09-03T02:32:43.731468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:32:43.377116Z","time spent":"354.332001ms","remote":"127.0.0.1:52116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-09-03T02:32:43.731694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.503497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-03T02:32:43.731377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:32:43.395491Z","time spent":"335.873648ms","remote":"127.0.0.1:52126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"info","ts":"2026-09-03T02:32:43.731713Z","caller":"traceutil/trace.go:171","msg":"trace[865750078] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:829; }","duration":"354.550838ms","start":"2026-09-03T02:32:43.377156Z","end":"2026-09-03T02:32:43.731706Z","steps":["trace[865750078] 'agreement among raft nodes before linearized reading' (duration: 354.503317ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:32:43.731731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:32:43.377139Z","time spent":"354.586599ms","remote":"127.0.0.1:52116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"warn","ts":"2026-09-03T02:32:43.731717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.560638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:32:43.731784Z","caller":"traceutil/trace.go:171","msg":"trace[363086221] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:829; }","duration":"353.672411ms","start":"2026-09-03T02:32:43.378088Z","end":"2026-09-03T02:32:43.731761Z","steps":["trace[363086221] 'agreement among raft nodes before linearized reading' (duration: 353.537507ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:32:43.731829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:32:43.37806Z","time spent":"353.754484ms","remote":"127.0.0.1:52116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"info","ts":"2026-09-03T02:33:16.43668Z","caller":"traceutil/trace.go:171","msg":"trace[1389366726] linearizableReadLoop","detail":"{readStateIndex:1286; appliedIndex:1286; }","duration":"100.246768ms","start":"2026-09-03T02:33:16.3364Z","end":"2026-09-03T02:33:16.436647Z","steps":["trace[1389366726] 'read index received' (duration: 100.231347ms)","trace[1389366726] 'applied index is now lower than readState.Index' (duration: 13.821µs)"],"step_count":2} {"level":"warn","ts":"2026-09-03T02:33:16.43684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.432163ms","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-09-03T02:33:16.436907Z","caller":"traceutil/trace.go:171","msg":"trace[1803410513] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1243; }","duration":"100.547166ms","start":"2026-09-03T02:33:16.336347Z","end":"2026-09-03T02:33:16.436894Z","steps":["trace[1803410513] 'agreement among raft nodes before linearized reading' (duration: 100.384611ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:33:16.436689Z","caller":"traceutil/trace.go:171","msg":"trace[1796022685] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"102.117834ms","start":"2026-09-03T02:33:16.334547Z","end":"2026-09-03T02:33:16.436665Z","steps":["trace[1796022685] 'process raft request' (duration: 101.950969ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:33:16.6945Z","caller":"traceutil/trace.go:171","msg":"trace[1998112823] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"357.766806ms","start":"2026-09-03T02:33:16.336682Z","end":"2026-09-03T02:33:16.694449Z","steps":["trace[1998112823] 'process raft request' (duration: 356.91652ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:33:16.694676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:33:16.33667Z","time spent":"357.90587ms","remote":"127.0.0.1:40316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-03T02:33:16.694791Z","caller":"traceutil/trace.go:171","msg":"trace[785297939] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1286; }","duration":"217.829541ms","start":"2026-09-03T02:33:16.476915Z","end":"2026-09-03T02:33:16.694744Z","steps":["trace[785297939] 'read index received' (duration: 216.849841ms)","trace[785297939] 'applied index is now lower than readState.Index' (duration: 977.55µs)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:33:16.695001Z","caller":"traceutil/trace.go:171","msg":"trace[1403860756] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"253.093883ms","start":"2026-09-03T02:33:16.44186Z","end":"2026-09-03T02:33:16.694954Z","steps":["trace[1403860756] 'process raft request' (duration: 252.733583ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:33:16.695102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.15468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:33:16.695163Z","caller":"traceutil/trace.go:171","msg":"trace[535172983] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1245; }","duration":"218.268864ms","start":"2026-09-03T02:33:16.476876Z","end":"2026-09-03T02:33:16.695145Z","steps":["trace[535172983] 'agreement among raft nodes before linearized reading' (duration: 218.119449ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:33:16.695152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.230702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:33:16.69522Z","caller":"traceutil/trace.go:171","msg":"trace[787858513] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1245; }","duration":"217.330276ms","start":"2026-09-03T02:33:16.477875Z","end":"2026-09-03T02:33:16.695205Z","steps":["trace[787858513] 'agreement among raft nodes before linearized reading' (duration: 217.222973ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:33:38.694132Z","caller":"traceutil/trace.go:171","msg":"trace[176226262] transaction","detail":"{read_only:false; response_revision:1496; number_of_response:1; }","duration":"152.769867ms","start":"2026-09-03T02:33:38.541348Z","end":"2026-09-03T02:33:38.694117Z","steps":["trace[176226262] 'process raft request' (duration: 152.670194ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:34:03.660531Z","caller":"traceutil/trace.go:171","msg":"trace[1942047895] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"220.018183ms","start":"2026-09-03T02:34:03.440484Z","end":"2026-09-03T02:34:03.660502Z","steps":["trace[1942047895] 'process raft request' (duration: 219.713303ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:34:03.660494Z","caller":"traceutil/trace.go:171","msg":"trace[731005145] linearizableReadLoop","detail":"{readStateIndex:1990; appliedIndex:1989; }","duration":"205.711767ms","start":"2026-09-03T02:34:03.454743Z","end":"2026-09-03T02:34:03.660455Z","steps":["trace[731005145] 'read index received' (duration: 205.467679ms)","trace[731005145] 'applied index is now lower than readState.Index' (duration: 242.438µs)"],"step_count":2} {"level":"warn","ts":"2026-09-03T02:34:03.660815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.035497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-03T02:34:03.660888Z","caller":"traceutil/trace.go:171","msg":"trace[780185424] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1913; }","duration":"206.186851ms","start":"2026-09-03T02:34:03.454686Z","end":"2026-09-03T02:34:03.660873Z","steps":["trace[780185424] 'agreement among raft nodes before linearized reading' (duration: 205.907573ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:34:03.667914Z","caller":"traceutil/trace.go:171","msg":"trace[1460078402] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"160.215614ms","start":"2026-09-03T02:34:03.507677Z","end":"2026-09-03T02:34:03.667892Z","steps":["trace[1460078402] 'process raft request' (duration: 160.019278ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:34:03.668053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.838503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-03T02:34:03.668151Z","caller":"traceutil/trace.go:171","msg":"trace[358410654] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1914; }","duration":"114.993908ms","start":"2026-09-03T02:34:03.553133Z","end":"2026-09-03T02:34:03.668127Z","steps":["trace[358410654] 'agreement among raft nodes before linearized reading' (duration: 114.707969ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:34:04.167284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.02882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-03T02:34:04.167506Z","caller":"traceutil/trace.go:171","msg":"trace[1269525921] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"490.280384ms","start":"2026-09-03T02:34:03.677185Z","end":"2026-09-03T02:34:04.167466Z","steps":["trace[1269525921] 'process raft request' (duration: 203.956394ms)","trace[1269525921] 'compare' (duration: 285.774082ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T02:34:04.16768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:34:03.677165Z","time spent":"490.435109ms","remote":"127.0.0.1:40242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-03T02:34:04.167795Z","caller":"traceutil/trace.go:171","msg":"trace[2109101390] linearizableReadLoop","detail":"{readStateIndex:1993; appliedIndex:1992; }","duration":"257.415929ms","start":"2026-09-03T02:34:03.910349Z","end":"2026-09-03T02:34:04.167764Z","steps":["trace[2109101390] 'read index received' (duration: 60.181µs)","trace[2109101390] 'applied index is now lower than readState.Index' (duration: 257.354268ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T02:34:04.167981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.010192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:34:04.168064Z","caller":"traceutil/trace.go:171","msg":"trace[121214264] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1917; }","duration":"220.113875ms","start":"2026-09-03T02:34:03.947905Z","end":"2026-09-03T02:34:04.168018Z","steps":["trace[121214264] 'agreement among raft nodes before linearized reading' (duration: 220.013172ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:34:04.16824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.29931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:34:04.168294Z","caller":"traceutil/trace.go:171","msg":"trace[899194714] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1917; }","duration":"145.392973ms","start":"2026-09-03T02:34:04.022884Z","end":"2026-09-03T02:34:04.168277Z","steps":["trace[899194714] 'agreement among raft nodes before linearized reading' (duration: 145.31277ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:34:04.168241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.721219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f972f929-ed0b-4d04-8a43-9be456c9a3f7\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-09-03T02:34:04.168704Z","caller":"traceutil/trace.go:171","msg":"trace[1507024772] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f972f929-ed0b-4d04-8a43-9be456c9a3f7; range_end:; response_count:1; response_revision:1917; }","duration":"258.076889ms","start":"2026-09-03T02:34:03.910284Z","end":"2026-09-03T02:34:04.168361Z","steps":["trace[1507024772] 'agreement among raft nodes before linearized reading' (duration: 257.589455ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:34:17.194553Z","caller":"traceutil/trace.go:171","msg":"trace[901592243] linearizableReadLoop","detail":"{readStateIndex:2103; appliedIndex:2102; }","duration":"143.346681ms","start":"2026-09-03T02:34:17.051183Z","end":"2026-09-03T02:34:17.19453Z","steps":["trace[901592243] 'read index received' (duration: 143.151705ms)","trace[901592243] 'applied index is now lower than readState.Index' (duration: 194.176µs)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:34:17.194611Z","caller":"traceutil/trace.go:171","msg":"trace[1371318096] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"143.797244ms","start":"2026-09-03T02:34:17.050778Z","end":"2026-09-03T02:34:17.194575Z","steps":["trace[1371318096] 'process raft request' (duration: 143.621089ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:34:17.194722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.526536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-09-03T02:34:17.194749Z","caller":"traceutil/trace.go:171","msg":"trace[306167784] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2022; }","duration":"143.606348ms","start":"2026-09-03T02:34:17.051133Z","end":"2026-09-03T02:34:17.19474Z","steps":["trace[306167784] 'agreement among raft nodes before linearized reading' (duration: 143.489525ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:34:48.542495Z","caller":"traceutil/trace.go:171","msg":"trace[415804208] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"118.664839ms","start":"2026-09-03T02:34:48.423801Z","end":"2026-09-03T02:34:48.542466Z","steps":["trace[415804208] 'process raft request' (duration: 118.40409ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:34:48.542528Z","caller":"traceutil/trace.go:171","msg":"trace[1801270682] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"118.69629ms","start":"2026-09-03T02:34:48.423802Z","end":"2026-09-03T02:34:48.542498Z","steps":["trace[1801270682] 'process raft request' (duration: 118.543946ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:34:54.175816Z","caller":"traceutil/trace.go:171","msg":"trace[973328191] linearizableReadLoop","detail":"{readStateIndex:2327; appliedIndex:2326; }","duration":"169.546811ms","start":"2026-09-03T02:34:54.006238Z","end":"2026-09-03T02:34:54.175784Z","steps":["trace[973328191] 'read index received' (duration: 158.119482ms)","trace[973328191] 'applied index is now lower than readState.Index' (duration: 11.425719ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:34:54.175861Z","caller":"traceutil/trace.go:171","msg":"trace[1349210100] transaction","detail":"{read_only:false; response_revision:2238; number_of_response:1; }","duration":"182.780975ms","start":"2026-09-03T02:34:53.993026Z","end":"2026-09-03T02:34:54.175807Z","steps":["trace[1349210100] 'process raft request' (duration: 171.341716ms)","trace[1349210100] 'compare' (duration: 11.1658ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T02:34:54.176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.78571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-03T02:34:54.176152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.912092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-03T02:34:54.17624Z","caller":"traceutil/trace.go:171","msg":"trace[288984935] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2238; }","duration":"170.025376ms","start":"2026-09-03T02:34:54.006195Z","end":"2026-09-03T02:34:54.176221Z","steps":["trace[288984935] 'agreement among raft nodes before linearized reading' (duration: 169.688976ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:34:54.176034Z","caller":"traceutil/trace.go:171","msg":"trace[900607028] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2238; }","duration":"152.854052ms","start":"2026-09-03T02:34:54.023168Z","end":"2026-09-03T02:34:54.176022Z","steps":["trace[900607028] 'agreement among raft nodes before linearized reading' (duration: 152.7826ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:34:54.489167Z","caller":"traceutil/trace.go:171","msg":"trace[1028915361] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"138.972048ms","start":"2026-09-03T02:34:54.350164Z","end":"2026-09-03T02:34:54.489136Z","steps":["trace[1028915361] 'process raft request' (duration: 138.70988ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:34:55.018788Z","caller":"traceutil/trace.go:171","msg":"trace[1307941457] transaction","detail":"{read_only:false; response_revision:2240; number_of_response:1; }","duration":"457.70829ms","start":"2026-09-03T02:34:54.561035Z","end":"2026-09-03T02:34:55.018743Z","steps":["trace[1307941457] 'process raft request' (duration: 457.542385ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:34:55.018966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:34:54.561017Z","time spent":"457.852735ms","remote":"127.0.0.1:40316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-03T02:34:55.03232Z","caller":"traceutil/trace.go:171","msg":"trace[839228289] linearizableReadLoop","detail":"{readStateIndex:2330; appliedIndex:2328; }","duration":"397.270907ms","start":"2026-09-03T02:34:54.635025Z","end":"2026-09-03T02:34:55.032296Z","steps":["trace[839228289] 'read index received' (duration: 383.576109ms)","trace[839228289] 'applied index is now lower than readState.Index' (duration: 13.693508ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:34:55.032392Z","caller":"traceutil/trace.go:171","msg":"trace[321452158] transaction","detail":"{read_only:false; response_revision:2241; number_of_response:1; }","duration":"470.258993ms","start":"2026-09-03T02:34:54.562115Z","end":"2026-09-03T02:34:55.032374Z","steps":["trace[321452158] 'process raft request' (duration: 470.021626ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:34:55.03247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:34:54.562102Z","time spent":"470.328906ms","remote":"127.0.0.1:40316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-03T02:34:55.032483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.538679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:34:55.032518Z","caller":"traceutil/trace.go:171","msg":"trace[2122610274] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:2241; }","duration":"265.630842ms","start":"2026-09-03T02:34:54.766878Z","end":"2026-09-03T02:34:55.032509Z","steps":["trace[2122610274] 'agreement among raft nodes before linearized reading' (duration: 265.55036ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:34:55.032765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.71075ms","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-09-03T02:34:55.032929Z","caller":"traceutil/trace.go:171","msg":"trace[482127592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2241; }","duration":"397.920587ms","start":"2026-09-03T02:34:54.634982Z","end":"2026-09-03T02:34:55.032902Z","steps":["trace[482127592] 'agreement among raft nodes before linearized reading' (duration: 397.485464ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:34:55.033104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:34:54.634965Z","time spent":"398.124082ms","remote":"127.0.0.1:40316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-03T02:35:00.348015Z","caller":"traceutil/trace.go:171","msg":"trace[293994074] transaction","detail":"{read_only:false; response_revision:2270; number_of_response:1; }","duration":"145.677113ms","start":"2026-09-03T02:35:00.202303Z","end":"2026-09-03T02:35:00.34798Z","steps":["trace[293994074] 'process raft request' (duration: 145.467717ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:35:00.348035Z","caller":"traceutil/trace.go:171","msg":"trace[1451831878] linearizableReadLoop","detail":"{readStateIndex:2360; appliedIndex:2359; }","duration":"130.919602ms","start":"2026-09-03T02:35:00.217091Z","end":"2026-09-03T02:35:00.348011Z","steps":["trace[1451831878] 'read index received' (duration: 130.663205ms)","trace[1451831878] 'applied index is now lower than readState.Index' (duration: 254.597µs)"],"step_count":2} {"level":"warn","ts":"2026-09-03T02:35:00.34828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.211772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-03T02:35:00.348343Z","caller":"traceutil/trace.go:171","msg":"trace[1417329885] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2270; }","duration":"131.309516ms","start":"2026-09-03T02:35:00.217015Z","end":"2026-09-03T02:35:00.348325Z","steps":["trace[1417329885] 'agreement among raft nodes before linearized reading' (duration: 131.094408ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:35:50.788128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.165223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-03T02:35:50.788225Z","caller":"traceutil/trace.go:171","msg":"trace[230730419] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2712; }","duration":"218.302007ms","start":"2026-09-03T02:35:50.569906Z","end":"2026-09-03T02:35:50.788208Z","steps":["trace[230730419] 'range keys from in-memory index tree' (duration: 217.996277ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:36:28.202268Z","caller":"traceutil/trace.go:171","msg":"trace[559856343] linearizableReadLoop","detail":"{readStateIndex:3056; appliedIndex:3055; }","duration":"140.759333ms","start":"2026-09-03T02:36:28.061487Z","end":"2026-09-03T02:36:28.202246Z","steps":["trace[559856343] 'read index received' (duration: 140.424772ms)","trace[559856343] 'applied index is now lower than readState.Index' (duration: 331.121µs)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:36:28.20234Z","caller":"traceutil/trace.go:171","msg":"trace[66528428] transaction","detail":"{read_only:false; response_revision:2941; number_of_response:1; }","duration":"185.612661ms","start":"2026-09-03T02:36:28.016692Z","end":"2026-09-03T02:36:28.202304Z","steps":["trace[66528428] 'process raft request' (duration: 185.425425ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:36:28.202431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.915207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-03T02:36:28.202468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.202216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:36:28.202529Z","caller":"traceutil/trace.go:171","msg":"trace[942312575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2941; }","duration":"141.035051ms","start":"2026-09-03T02:36:28.061474Z","end":"2026-09-03T02:36:28.202509Z","steps":["trace[942312575] 'agreement among raft nodes before linearized reading' (duration: 140.867236ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:36:28.202534Z","caller":"traceutil/trace.go:171","msg":"trace[443563494] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:2941; }","duration":"120.312929ms","start":"2026-09-03T02:36:28.082205Z","end":"2026-09-03T02:36:28.202518Z","steps":["trace[443563494] 'agreement among raft nodes before linearized reading' (duration: 120.206016ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:36:28.368237Z","caller":"traceutil/trace.go:171","msg":"trace[2052275726] transaction","detail":"{read_only:false; response_revision:2943; number_of_response:1; }","duration":"118.644898ms","start":"2026-09-03T02:36:28.249569Z","end":"2026-09-03T02:36:28.368214Z","steps":["trace[2052275726] 'process raft request' (duration: 118.582846ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:36:28.368522Z","caller":"traceutil/trace.go:171","msg":"trace[421196034] transaction","detail":"{read_only:false; response_revision:2942; number_of_response:1; }","duration":"145.229468ms","start":"2026-09-03T02:36:28.223255Z","end":"2026-09-03T02:36:28.368484Z","steps":["trace[421196034] 'process raft request' (duration: 97.989338ms)","trace[421196034] 'compare' (duration: 46.778786ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:38:43.941685Z","caller":"traceutil/trace.go:171","msg":"trace[1600665830] linearizableReadLoop","detail":"{readStateIndex:3947; appliedIndex:3946; }","duration":"137.024675ms","start":"2026-09-03T02:38:43.804627Z","end":"2026-09-03T02:38:43.941652Z","steps":["trace[1600665830] 'read index received' (duration: 103.617099ms)","trace[1600665830] 'applied index is now lower than readState.Index' (duration: 33.406096ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T02:38:43.941886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.222231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/secretgen-controller/secretgen-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:38:43.94194Z","caller":"traceutil/trace.go:171","msg":"trace[393999893] range","detail":"{range_begin:/registry/deployments/secretgen-controller/secretgen-controller; range_end:; response_count:0; response_revision:3798; }","duration":"137.322724ms","start":"2026-09-03T02:38:43.804599Z","end":"2026-09-03T02:38:43.941922Z","steps":["trace[393999893] 'agreement among raft nodes before linearized reading' (duration: 137.18429ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:38:43.941943Z","caller":"traceutil/trace.go:171","msg":"trace[825030880] transaction","detail":"{read_only:false; response_revision:3798; number_of_response:1; }","duration":"137.679924ms","start":"2026-09-03T02:38:43.804193Z","end":"2026-09-03T02:38:43.941873Z","steps":["trace[825030880] 'process raft request' (duration: 104.140805ms)","trace[825030880] 'compare' (duration: 33.163358ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:38:43.942698Z","caller":"traceutil/trace.go:171","msg":"trace[1824135699] transaction","detail":"{read_only:false; response_revision:3799; number_of_response:1; }","duration":"137.589063ms","start":"2026-09-03T02:38:43.805085Z","end":"2026-09-03T02:38:43.942674Z","steps":["trace[1824135699] 'process raft request' (duration: 137.453759ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:39:19.875778Z","caller":"traceutil/trace.go:171","msg":"trace[1681594278] transaction","detail":"{read_only:false; response_revision:4048; number_of_response:1; }","duration":"155.900798ms","start":"2026-09-03T02:39:19.719843Z","end":"2026-09-03T02:39:19.875744Z","steps":["trace[1681594278] 'process raft request' (duration: 155.775905ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:40:40.576587Z","caller":"traceutil/trace.go:171","msg":"trace[1790031284] linearizableReadLoop","detail":"{readStateIndex:5204; appliedIndex:5203; }","duration":"116.650325ms","start":"2026-09-03T02:40:40.459912Z","end":"2026-09-03T02:40:40.576562Z","steps":["trace[1790031284] 'read index received' (duration: 116.505361ms)","trace[1790031284] 'applied index is now lower than readState.Index' (duration: 143.884µs)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:40:40.576736Z","caller":"traceutil/trace.go:171","msg":"trace[2061410122] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"128.938679ms","start":"2026-09-03T02:40:40.447784Z","end":"2026-09-03T02:40:40.576722Z","steps":["trace[2061410122] 'process raft request' (duration: 128.601909ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:40:40.576963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.029946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-09-03T02:40:40.577043Z","caller":"traceutil/trace.go:171","msg":"trace[1860145833] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5011; }","duration":"117.174801ms","start":"2026-09-03T02:40:40.459848Z","end":"2026-09-03T02:40:40.577022Z","steps":["trace[1860145833] 'agreement among raft nodes before linearized reading' (duration: 116.938954ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:40:43.004275Z","caller":"traceutil/trace.go:171","msg":"trace[1683189945] transaction","detail":"{read_only:false; response_revision:5071; number_of_response:1; }","duration":"110.351033ms","start":"2026-09-03T02:40:42.893899Z","end":"2026-09-03T02:40:43.00425Z","steps":["trace[1683189945] 'process raft request' (duration: 109.982121ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:40:45.02085Z","caller":"traceutil/trace.go:171","msg":"trace[870725581] linearizableReadLoop","detail":"{readStateIndex:5402; appliedIndex:5401; }","duration":"333.152301ms","start":"2026-09-03T02:40:44.687673Z","end":"2026-09-03T02:40:45.020825Z","steps":["trace[870725581] 'read index received' (duration: 332.937605ms)","trace[870725581] 'applied index is now lower than readState.Index' (duration: 213.816µs)"],"step_count":2} {"level":"warn","ts":"2026-09-03T02:40:45.021382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.910619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-03T02:40:45.021395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.691047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-03T02:40:45.021433Z","caller":"traceutil/trace.go:171","msg":"trace[2142000587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5203; }","duration":"324.005102ms","start":"2026-09-03T02:40:44.697412Z","end":"2026-09-03T02:40:45.021417Z","steps":["trace[2142000587] 'agreement among raft nodes before linearized reading' (duration: 323.798156ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:40:45.021476Z","caller":"traceutil/trace.go:171","msg":"trace[317937845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5203; }","duration":"333.826561ms","start":"2026-09-03T02:40:44.68763Z","end":"2026-09-03T02:40:45.021456Z","steps":["trace[317937845] 'agreement among raft nodes before linearized reading' (duration: 333.567073ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:40:45.021479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:40:44.697396Z","time spent":"324.071604ms","remote":"127.0.0.1:40316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-03T02:40:45.021519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:40:44.687609Z","time spent":"333.901614ms","remote":"127.0.0.1:40316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-03T02:40:45.021599Z","caller":"traceutil/trace.go:171","msg":"trace[1011869788] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"358.759091ms","start":"2026-09-03T02:40:44.662799Z","end":"2026-09-03T02:40:45.021558Z","steps":["trace[1011869788] 'process raft request' (duration: 357.880025ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:40:45.021773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:40:44.662771Z","time spent":"358.917457ms","remote":"127.0.0.1:40242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14481,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-03T02:40:46.983464Z","caller":"traceutil/trace.go:171","msg":"trace[630547210] transaction","detail":"{read_only:false; response_revision:5246; number_of_response:1; }","duration":"160.661256ms","start":"2026-09-03T02:40:46.822768Z","end":"2026-09-03T02:40:46.983429Z","steps":["trace[630547210] 'process raft request' (duration: 160.407837ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:40:59.867944Z","caller":"traceutil/trace.go:171","msg":"trace[99526483] transaction","detail":"{read_only:false; response_revision:5488; number_of_response:1; }","duration":"200.981644ms","start":"2026-09-03T02:40:59.666935Z","end":"2026-09-03T02:40:59.867916Z","steps":["trace[99526483] 'process raft request' (duration: 200.797429ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:40:59.876636Z","caller":"traceutil/trace.go:171","msg":"trace[2127346757] transaction","detail":"{read_only:false; response_revision:5489; number_of_response:1; }","duration":"175.630641ms","start":"2026-09-03T02:40:59.700972Z","end":"2026-09-03T02:40:59.876602Z","steps":["trace[2127346757] 'process raft request' (duration: 175.368594ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:41:08.540876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.753594ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-03T02:41:08.54105Z","caller":"traceutil/trace.go:171","msg":"trace[1819913795] transaction","detail":"{read_only:false; response_revision:5653; number_of_response:1; }","duration":"228.759191ms","start":"2026-09-03T02:41:08.312271Z","end":"2026-09-03T02:41:08.54103Z","steps":["trace[1819913795] 'process raft request' (duration: 35.389838ms)","trace[1819913795] 'compare' (duration: 192.458685ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:41:08.54137Z","caller":"traceutil/trace.go:171","msg":"trace[1059021764] linearizableReadLoop","detail":"{readStateIndex:5864; appliedIndex:5862; }","duration":"168.822963ms","start":"2026-09-03T02:41:08.37244Z","end":"2026-09-03T02:41:08.541263Z","steps":["trace[1059021764] 'read index received' (duration: 107.040381ms)","trace[1059021764] 'applied index is now lower than readState.Index' (duration: 61.781892ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:41:08.541384Z","caller":"traceutil/trace.go:171","msg":"trace[397693330] transaction","detail":"{read_only:false; response_revision:5654; number_of_response:1; }","duration":"211.342729ms","start":"2026-09-03T02:41:08.329989Z","end":"2026-09-03T02:41:08.541331Z","steps":["trace[397693330] 'process raft request' (duration: 210.991178ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:41:08.541535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.083982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-03T02:41:08.541618Z","caller":"traceutil/trace.go:171","msg":"trace[1474073098] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5654; }","duration":"169.182015ms","start":"2026-09-03T02:41:08.372412Z","end":"2026-09-03T02:41:08.541594Z","steps":["trace[1474073098] 'agreement among raft nodes before linearized reading' (duration: 168.98886ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:41:20.247157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.246627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:41:20.247227Z","caller":"traceutil/trace.go:171","msg":"trace[2079591125] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:5817; }","duration":"361.366941ms","start":"2026-09-03T02:41:19.885846Z","end":"2026-09-03T02:41:20.247213Z","steps":["trace[2079591125] 'count revisions from in-memory index tree' (duration: 361.118393ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:41:20.247256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:41:19.885824Z","time spent":"361.424153ms","remote":"127.0.0.1:52126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-09-03T02:41:20.247278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.627613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-03T02:41:20.247381Z","caller":"traceutil/trace.go:171","msg":"trace[585124699] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5817; }","duration":"305.772177ms","start":"2026-09-03T02:41:19.941582Z","end":"2026-09-03T02:41:20.247354Z","steps":["trace[585124699] 'range keys from in-memory index tree' (duration: 305.421587ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:41:20.247396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.755085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-03T02:41:20.247442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:41:19.941563Z","time spent":"305.862569ms","remote":"127.0.0.1:40214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-09-03T02:41:20.247493Z","caller":"traceutil/trace.go:171","msg":"trace[1859063353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5817; }","duration":"769.90707ms","start":"2026-09-03T02:41:19.477561Z","end":"2026-09-03T02:41:20.247468Z","steps":["trace[1859063353] 'range keys from in-memory index tree' (duration: 769.642752ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:41:20.247553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:41:19.477537Z","time spent":"770.002563ms","remote":"127.0.0.1:40006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-03T02:41:20.247705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.569879ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-03T02:41:20.247831Z","caller":"traceutil/trace.go:171","msg":"trace[1521064647] transaction","detail":"{read_only:false; response_revision:5818; number_of_response:1; }","duration":"242.925452ms","start":"2026-09-03T02:41:20.004885Z","end":"2026-09-03T02:41:20.24781Z","steps":["trace[1521064647] 'process raft request' (duration: 27.162837ms)","trace[1521064647] 'compare' (duration: 215.230479ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:41:32.799459Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2964} {"level":"info","ts":"2026-09-03T02:41:32.887565Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2964,"took":"86.763824ms","hash":4263894056,"current-db-size-bytes":57294848,"current-db-size":"57 MB","current-db-size-in-use-bytes":32546816,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-03T02:41:32.887636Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4263894056,"revision":2964,"compact-revision":-1} {"level":"info","ts":"2026-09-03T02:46:32.805431Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5905} {"level":"info","ts":"2026-09-03T02:46:32.891766Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5905,"took":"84.719944ms","hash":3016137302,"current-db-size-bytes":57294848,"current-db-size":"57 MB","current-db-size-in-use-bytes":25862144,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-03T02:46:32.891821Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3016137302,"revision":5905,"compact-revision":2964} {"level":"info","ts":"2026-09-03T02:46:36.461094Z","caller":"traceutil/trace.go:171","msg":"trace[143867687] transaction","detail":"{read_only:false; response_revision:8083; number_of_response:1; }","duration":"127.009094ms","start":"2026-09-03T02:46:36.334055Z","end":"2026-09-03T02:46:36.461064Z","steps":["trace[143867687] 'process raft request' (duration: 99.219916ms)","trace[143867687] 'compare' (duration: 27.597952ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:46:36.461402Z","caller":"traceutil/trace.go:171","msg":"trace[1736139229] transaction","detail":"{read_only:false; response_revision:8084; number_of_response:1; }","duration":"110.912253ms","start":"2026-09-03T02:46:36.350459Z","end":"2026-09-03T02:46:36.461371Z","steps":["trace[1736139229] 'process raft request' (duration: 110.545202ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:47:28.694321Z","caller":"traceutil/trace.go:171","msg":"trace[2051737143] linearizableReadLoop","detail":"{readStateIndex:8865; appliedIndex:8864; }","duration":"217.210086ms","start":"2026-09-03T02:47:28.477087Z","end":"2026-09-03T02:47:28.694297Z","steps":["trace[2051737143] 'read index received' (duration: 217.065891ms)","trace[2051737143] 'applied index is now lower than readState.Index' (duration: 143.305µs)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:47:28.694447Z","caller":"traceutil/trace.go:171","msg":"trace[1435170242] transaction","detail":"{read_only:false; response_revision:8559; number_of_response:1; }","duration":"254.047248ms","start":"2026-09-03T02:47:28.440375Z","end":"2026-09-03T02:47:28.694422Z","steps":["trace[1435170242] 'process raft request' (duration: 253.7724ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:47:28.694496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.393971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:47:28.69454Z","caller":"traceutil/trace.go:171","msg":"trace[1438116400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8559; }","duration":"217.490405ms","start":"2026-09-03T02:47:28.477036Z","end":"2026-09-03T02:47:28.694527Z","steps":["trace[1438116400] 'agreement among raft nodes before linearized reading' (duration: 217.38392ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:47:28.694527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.992099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-03T02:47:28.694599Z","caller":"traceutil/trace.go:171","msg":"trace[1546415060] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:8559; }","duration":"179.115453ms","start":"2026-09-03T02:47:28.515465Z","end":"2026-09-03T02:47:28.69458Z","steps":["trace[1546415060] 'agreement among raft nodes before linearized reading' (duration: 178.982928ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:47:28.88802Z","caller":"traceutil/trace.go:171","msg":"trace[2033431592] transaction","detail":"{read_only:false; response_revision:8560; number_of_response:1; }","duration":"111.924074ms","start":"2026-09-03T02:47:28.776075Z","end":"2026-09-03T02:47:28.887999Z","steps":["trace[2033431592] 'process raft request' (duration: 103.900319ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:47:28.888041Z","caller":"traceutil/trace.go:171","msg":"trace[1229068012] transaction","detail":"{read_only:false; response_revision:8561; number_of_response:1; }","duration":"108.641864ms","start":"2026-09-03T02:47:28.77938Z","end":"2026-09-03T02:47:28.888022Z","steps":["trace[1229068012] 'process raft request' (duration: 108.534971ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:47:33.005041Z","caller":"traceutil/trace.go:171","msg":"trace[1127628359] transaction","detail":"{read_only:false; response_revision:8580; number_of_response:1; }","duration":"103.081555ms","start":"2026-09-03T02:47:32.901933Z","end":"2026-09-03T02:47:33.005014Z","steps":["trace[1127628359] 'process raft request' (duration: 101.785745ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:47:39.512605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.859098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jvtj5\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-03T02:47:39.512689Z","caller":"traceutil/trace.go:171","msg":"trace[1031803140] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jvtj5; range_end:; response_count:1; response_revision:8618; }","duration":"124.976111ms","start":"2026-09-03T02:47:39.387693Z","end":"2026-09-03T02:47:39.51267Z","steps":["trace[1031803140] 'range keys from in-memory index tree' (duration: 124.730564ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:47:41.385774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.058892ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-03T02:47:41.386264Z","caller":"traceutil/trace.go:171","msg":"trace[1242585341] linearizableReadLoop","detail":"{readStateIndex:8934; appliedIndex:8932; }","duration":"124.406004ms","start":"2026-09-03T02:47:41.261828Z","end":"2026-09-03T02:47:41.386234Z","steps":["trace[1242585341] 'read index received' (duration: 122.386373ms)","trace[1242585341] 'applied index is now lower than readState.Index' (duration: 2.017771ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:47:41.386419Z","caller":"traceutil/trace.go:171","msg":"trace[1710520477] transaction","detail":"{read_only:false; response_revision:8625; number_of_response:1; }","duration":"217.05442ms","start":"2026-09-03T02:47:41.169348Z","end":"2026-09-03T02:47:41.386402Z","steps":["trace[1710520477] 'process raft request' (duration: 216.601027ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:47:41.386519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.946858ms","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-09-03T02:47:41.386597Z","caller":"traceutil/trace.go:171","msg":"trace[323022612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8625; }","duration":"121.045972ms","start":"2026-09-03T02:47:41.265534Z","end":"2026-09-03T02:47:41.38658Z","steps":["trace[323022612] 'agreement among raft nodes before linearized reading' (duration: 120.872906ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:47:41.386765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.92172ms","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-09-03T02:47:41.386831Z","caller":"traceutil/trace.go:171","msg":"trace[389208027] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8625; }","duration":"125.031394ms","start":"2026-09-03T02:47:41.261786Z","end":"2026-09-03T02:47:41.386817Z","steps":["trace[389208027] 'agreement among raft nodes before linearized reading' (duration: 124.827598ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:47:41.38678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.916197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-03T02:47:41.386947Z","caller":"traceutil/trace.go:171","msg":"trace[598141001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8625; }","duration":"121.104274ms","start":"2026-09-03T02:47:41.26582Z","end":"2026-09-03T02:47:41.386924Z","steps":["trace[598141001] 'agreement among raft nodes before linearized reading' (duration: 120.846245ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:47:46.450567Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000230d.wal"} {"level":"info","ts":"2026-09-03T02:48:06.188522Z","caller":"traceutil/trace.go:171","msg":"trace[1523961610] transaction","detail":"{read_only:false; response_revision:8812; number_of_response:1; }","duration":"110.46529ms","start":"2026-09-03T02:48:06.078033Z","end":"2026-09-03T02:48:06.188498Z","steps":["trace[1523961610] 'process raft request' (duration: 108.744948ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:48:06.18865Z","caller":"traceutil/trace.go:171","msg":"trace[1082678234] transaction","detail":"{read_only:false; response_revision:8813; number_of_response:1; }","duration":"101.483735ms","start":"2026-09-03T02:48:06.087139Z","end":"2026-09-03T02:48:06.188623Z","steps":["trace[1082678234] 'process raft request' (duration: 101.28578ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:48:11.233706Z","caller":"traceutil/trace.go:171","msg":"trace[648996712] linearizableReadLoop","detail":"{readStateIndex:9158; appliedIndex:9157; }","duration":"109.611903ms","start":"2026-09-03T02:48:11.124058Z","end":"2026-09-03T02:48:11.23367Z","steps":["trace[648996712] 'read index received' (duration: 108.414557ms)","trace[648996712] 'applied index is now lower than readState.Index' (duration: 1.196216ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:48:11.233765Z","caller":"traceutil/trace.go:171","msg":"trace[363180516] transaction","detail":"{read_only:false; response_revision:8841; number_of_response:1; }","duration":"113.304336ms","start":"2026-09-03T02:48:11.12042Z","end":"2026-09-03T02:48:11.233724Z","steps":["trace[363180516] 'process raft request' (duration: 112.050058ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:48:11.233908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.82824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-03T02:48:11.234065Z","caller":"traceutil/trace.go:171","msg":"trace[1149548239] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:8841; }","duration":"110.037226ms","start":"2026-09-03T02:48:11.124005Z","end":"2026-09-03T02:48:11.234042Z","steps":["trace[1149548239] 'agreement among raft nodes before linearized reading' (duration: 109.800008ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:48:12.395129Z","caller":"traceutil/trace.go:171","msg":"trace[98190801] linearizableReadLoop","detail":"{readStateIndex:9166; appliedIndex:9164; }","duration":"165.749625ms","start":"2026-09-03T02:48:12.229356Z","end":"2026-09-03T02:48:12.395106Z","steps":["trace[98190801] 'read index received' (duration: 165.546019ms)","trace[98190801] 'applied index is now lower than readState.Index' (duration: 202.566µs)"],"step_count":2} {"level":"warn","ts":"2026-09-03T02:48:12.395292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.91026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:48:12.395329Z","caller":"traceutil/trace.go:171","msg":"trace[1407479358] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:8848; }","duration":"165.998634ms","start":"2026-09-03T02:48:12.22932Z","end":"2026-09-03T02:48:12.395318Z","steps":["trace[1407479358] 'agreement among raft nodes before linearized reading' (duration: 165.8774ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:48:12.395365Z","caller":"traceutil/trace.go:171","msg":"trace[422997261] transaction","detail":"{read_only:false; response_revision:8847; number_of_response:1; }","duration":"180.101214ms","start":"2026-09-03T02:48:12.215229Z","end":"2026-09-03T02:48:12.39533Z","steps":["trace[422997261] 'process raft request' (duration: 179.666811ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:48:12.395526Z","caller":"traceutil/trace.go:171","msg":"trace[1799724370] transaction","detail":"{read_only:false; response_revision:8848; number_of_response:1; }","duration":"180.225877ms","start":"2026-09-03T02:48:12.21525Z","end":"2026-09-03T02:48:12.395476Z","steps":["trace[1799724370] 'process raft request' (duration: 179.812145ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:48:19.826929Z","caller":"traceutil/trace.go:171","msg":"trace[287154850] linearizableReadLoop","detail":"{readStateIndex:9206; appliedIndex:9205; }","duration":"212.78593ms","start":"2026-09-03T02:48:19.614106Z","end":"2026-09-03T02:48:19.826892Z","steps":["trace[287154850] 'read index received' (duration: 126.214039ms)","trace[287154850] 'applied index is now lower than readState.Index' (duration: 86.570391ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:48:19.826997Z","caller":"traceutil/trace.go:171","msg":"trace[808750684] transaction","detail":"{read_only:false; response_revision:8887; number_of_response:1; }","duration":"222.745665ms","start":"2026-09-03T02:48:19.604193Z","end":"2026-09-03T02:48:19.826939Z","steps":["trace[808750684] 'process raft request' (duration: 136.196524ms)","trace[808750684] 'compare' (duration: 86.232061ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T02:48:19.827175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.062859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-03T02:48:19.827224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.172845ms","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-09-03T02:48:19.82726Z","caller":"traceutil/trace.go:171","msg":"trace[887408499] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8887; }","duration":"200.248588ms","start":"2026-09-03T02:48:19.627Z","end":"2026-09-03T02:48:19.827249Z","steps":["trace[887408499] 'agreement among raft nodes before linearized reading' (duration: 200.076823ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:48:19.827256Z","caller":"traceutil/trace.go:171","msg":"trace[417803172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8887; }","duration":"213.177053ms","start":"2026-09-03T02:48:19.614058Z","end":"2026-09-03T02:48:19.827235Z","steps":["trace[417803172] 'agreement among raft nodes before linearized reading' (duration: 212.967946ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:48:19.966072Z","caller":"traceutil/trace.go:171","msg":"trace[731544811] linearizableReadLoop","detail":"{readStateIndex:9208; appliedIndex:9206; }","duration":"111.531122ms","start":"2026-09-03T02:48:19.854511Z","end":"2026-09-03T02:48:19.966042Z","steps":["trace[731544811] 'read index received' (duration: 83.967551ms)","trace[731544811] 'applied index is now lower than readState.Index' (duration: 27.562251ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:48:19.966165Z","caller":"traceutil/trace.go:171","msg":"trace[451367546] transaction","detail":"{read_only:false; response_revision:8888; number_of_response:1; }","duration":"133.626036ms","start":"2026-09-03T02:48:19.832494Z","end":"2026-09-03T02:48:19.96612Z","steps":["trace[451367546] 'process raft request' (duration: 105.990433ms)","trace[451367546] 'compare' (duration: 27.311103ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:48:19.966241Z","caller":"traceutil/trace.go:171","msg":"trace[145766563] transaction","detail":"{read_only:false; response_revision:8889; number_of_response:1; }","duration":"132.7793ms","start":"2026-09-03T02:48:19.833435Z","end":"2026-09-03T02:48:19.966214Z","steps":["trace[145766563] 'process raft request' (duration: 132.545213ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:48:19.966264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.736608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-03T02:48:19.966318Z","caller":"traceutil/trace.go:171","msg":"trace[1861615346] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:8889; }","duration":"111.825931ms","start":"2026-09-03T02:48:19.854473Z","end":"2026-09-03T02:48:19.966299Z","steps":["trace[1861615346] 'agreement among raft nodes before linearized reading' (duration: 111.677767ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:48:20.789549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.356193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-03T02:48:20.789643Z","caller":"traceutil/trace.go:171","msg":"trace[939955365] linearizableReadLoop","detail":"{readStateIndex:9210; appliedIndex:9208; }","duration":"335.287177ms","start":"2026-09-03T02:48:20.454342Z","end":"2026-09-03T02:48:20.789629Z","steps":["trace[939955365] 'read index received' (duration: 33.850553ms)","trace[939955365] 'applied index is now lower than readState.Index' (duration: 301.435554ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:48:20.78971Z","caller":"traceutil/trace.go:171","msg":"trace[515823892] transaction","detail":"{read_only:false; response_revision:8891; number_of_response:1; }","duration":"360.563977ms","start":"2026-09-03T02:48:20.429137Z","end":"2026-09-03T02:48:20.789701Z","steps":["trace[515823892] 'process raft request' (duration: 114.988617ms)","trace[515823892] 'compare' (duration: 245.267111ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T02:48:20.789753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:48:20.42911Z","time spent":"360.618059ms","remote":"127.0.0.1:40316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-03T02:48:20.789982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.036969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:48:20.790074Z","caller":"traceutil/trace.go:171","msg":"trace[1019802939] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8891; }","duration":"315.152402ms","start":"2026-09-03T02:48:20.474893Z","end":"2026-09-03T02:48:20.790046Z","steps":["trace[1019802939] 'agreement among raft nodes before linearized reading' (duration: 314.995208ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:48:20.790126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:48:20.474829Z","time spent":"315.289057ms","remote":"127.0.0.1:40006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-03T02:48:20.790006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.666259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:48:20.790201Z","caller":"traceutil/trace.go:171","msg":"trace[325887762] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:8891; }","duration":"335.885325ms","start":"2026-09-03T02:48:20.45429Z","end":"2026-09-03T02:48:20.790176Z","steps":["trace[325887762] 'agreement among raft nodes before linearized reading' (duration: 335.673579ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:48:20.790266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:48:20.45427Z","time spent":"335.978768ms","remote":"127.0.0.1:40272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-09-03T02:48:24.514489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.903134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-03T02:48:24.514872Z","caller":"traceutil/trace.go:171","msg":"trace[257453640] transaction","detail":"{read_only:false; response_revision:8923; number_of_response:1; }","duration":"173.028708ms","start":"2026-09-03T02:48:24.341612Z","end":"2026-09-03T02:48:24.514641Z","steps":["trace[257453640] 'process raft request' (duration: 58.871176ms)","trace[257453640] 'compare' (duration: 113.77107ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:48:35.997793Z","caller":"traceutil/trace.go:171","msg":"trace[1617716940] transaction","detail":"{read_only:false; response_revision:9045; number_of_response:1; }","duration":"116.712038ms","start":"2026-09-03T02:48:35.881061Z","end":"2026-09-03T02:48:35.997773Z","steps":["trace[1617716940] 'process raft request' (duration: 116.550393ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:49:34.322793Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"337baeb286292d09","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-03T02:49:34.328109Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-03T02:49:34.328181Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-03T02:49:50.510409Z","caller":"traceutil/trace.go:171","msg":"trace[1196244038] linearizableReadLoop","detail":"{readStateIndex:10095; appliedIndex:10094; }","duration":"110.297273ms","start":"2026-09-03T02:49:50.400072Z","end":"2026-09-03T02:49:50.510369Z","steps":["trace[1196244038] 'read index received' (duration: 109.928901ms)","trace[1196244038] 'applied index is now lower than readState.Index' (duration: 366.782µs)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:49:50.510448Z","caller":"traceutil/trace.go:171","msg":"trace[881262697] transaction","detail":"{read_only:false; response_revision:9751; number_of_response:1; }","duration":"130.589521ms","start":"2026-09-03T02:49:50.379829Z","end":"2026-09-03T02:49:50.510419Z","steps":["trace[881262697] 'process raft request' (duration: 130.322363ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:49:50.510672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.582722ms","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-09-03T02:49:50.5109Z","caller":"traceutil/trace.go:171","msg":"trace[716640030] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9751; }","duration":"110.893371ms","start":"2026-09-03T02:49:50.399993Z","end":"2026-09-03T02:49:50.510886Z","steps":["trace[716640030] 'agreement among raft nodes before linearized reading' (duration: 110.471049ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:49:50.876691Z","caller":"traceutil/trace.go:171","msg":"trace[298324411] transaction","detail":"{read_only:false; response_revision:9752; number_of_response:1; }","duration":"361.197193ms","start":"2026-09-03T02:49:50.515444Z","end":"2026-09-03T02:49:50.876642Z","steps":["trace[298324411] 'process raft request' (duration: 314.002365ms)","trace[298324411] 'compare' (duration: 46.837758ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:49:50.876687Z","caller":"traceutil/trace.go:171","msg":"trace[1908791581] linearizableReadLoop","detail":"{readStateIndex:10096; appliedIndex:10095; }","duration":"282.06976ms","start":"2026-09-03T02:49:50.594536Z","end":"2026-09-03T02:49:50.876606Z","steps":["trace[1908791581] 'read index received' (duration: 234.82142ms)","trace[1908791581] 'applied index is now lower than readState.Index' (duration: 47.24614ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T02:49:50.876916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T02:49:50.515414Z","time spent":"361.381919ms","remote":"127.0.0.1:40316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-03T02:49:50.877252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.71337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-03T02:49:50.877454Z","caller":"traceutil/trace.go:171","msg":"trace[1088296356] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:9752; }","duration":"282.983228ms","start":"2026-09-03T02:49:50.594446Z","end":"2026-09-03T02:49:50.877429Z","steps":["trace[1088296356] 'agreement among raft nodes before linearized reading' (duration: 282.316617ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:49:50.892222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.294339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T02:49:50.892304Z","caller":"traceutil/trace.go:171","msg":"trace[798803919] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:9753; }","duration":"102.466435ms","start":"2026-09-03T02:49:50.789816Z","end":"2026-09-03T02:49:50.892283Z","steps":["trace[798803919] 'agreement among raft nodes before linearized reading' (duration: 102.273368ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:51:32.81598Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8063} {"level":"info","ts":"2026-09-03T02:51:32.88028Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8063,"took":"62.628149ms","hash":1278983322,"current-db-size-bytes":57294848,"current-db-size":"57 MB","current-db-size-in-use-bytes":28278784,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-03T02:51:32.880406Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1278983322,"revision":8063,"compact-revision":5905} {"level":"info","ts":"2026-09-03T02:55:10.540699Z","caller":"traceutil/trace.go:171","msg":"trace[643635467] transaction","detail":"{read_only:false; response_revision:12079; number_of_response:1; }","duration":"114.248129ms","start":"2026-09-03T02:55:10.426426Z","end":"2026-09-03T02:55:10.540674Z","steps":["trace[643635467] 'process raft request' (duration: 114.097855ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:55:28.776689Z","caller":"traceutil/trace.go:171","msg":"trace[830169417] transaction","detail":"{read_only:false; response_revision:12181; number_of_response:1; }","duration":"150.559986ms","start":"2026-09-03T02:55:28.626107Z","end":"2026-09-03T02:55:28.776667Z","steps":["trace[830169417] 'process raft request' (duration: 150.420322ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:55:29.014716Z","caller":"traceutil/trace.go:171","msg":"trace[1780554829] transaction","detail":"{read_only:false; response_revision:12182; number_of_response:1; }","duration":"182.89293ms","start":"2026-09-03T02:55:28.831798Z","end":"2026-09-03T02:55:29.014691Z","steps":["trace[1780554829] 'process raft request' (duration: 97.905021ms)","trace[1780554829] 'compare' (duration: 84.821354ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:55:29.024559Z","caller":"traceutil/trace.go:171","msg":"trace[265746164] linearizableReadLoop","detail":"{readStateIndex:12605; appliedIndex:12603; }","duration":"115.950772ms","start":"2026-09-03T02:55:28.908581Z","end":"2026-09-03T02:55:29.024532Z","steps":["trace[265746164] 'read index received' (duration: 21.139114ms)","trace[265746164] 'applied index is now lower than readState.Index' (duration: 94.810428ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T02:55:29.024728Z","caller":"traceutil/trace.go:171","msg":"trace[1826176424] transaction","detail":"{read_only:false; response_revision:12183; number_of_response:1; }","duration":"191.827203ms","start":"2026-09-03T02:55:28.832836Z","end":"2026-09-03T02:55:29.024663Z","steps":["trace[1826176424] 'process raft request' (duration: 191.552924ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T02:55:29.024836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.24186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jvtj5\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-03T02:55:29.02504Z","caller":"traceutil/trace.go:171","msg":"trace[1361260682] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jvtj5; range_end:; response_count:1; response_revision:12183; }","duration":"116.502707ms","start":"2026-09-03T02:55:28.908522Z","end":"2026-09-03T02:55:29.025024Z","steps":["trace[1361260682] 'agreement among raft nodes before linearized reading' (duration: 116.107596ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T02:56:32.828282Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10454} {"level":"info","ts":"2026-09-03T02:56:32.914354Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10454,"took":"81.075858ms","hash":3383939142,"current-db-size-bytes":57294848,"current-db-size":"57 MB","current-db-size-in-use-bytes":25702400,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-03T02:56:32.91442Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3383939142,"revision":10454,"compact-revision":8063} {"level":"info","ts":"2026-09-03T03:01:32.837831Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12625} {"level":"info","ts":"2026-09-03T03:01:32.921919Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12625,"took":"81.812284ms","hash":3968365723,"current-db-size-bytes":57294848,"current-db-size":"57 MB","current-db-size-in-use-bytes":27942912,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-03T03:01:32.921995Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3968365723,"revision":12625,"compact-revision":10454} {"level":"info","ts":"2026-09-03T03:02:08.979248Z","caller":"traceutil/trace.go:171","msg":"trace[1166042456] transaction","detail":"{read_only:false; response_revision:14838; number_of_response:1; }","duration":"184.640866ms","start":"2026-09-03T03:02:08.794583Z","end":"2026-09-03T03:02:08.979223Z","steps":["trace[1166042456] 'process raft request' (duration: 184.500872ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:02:09.029769Z","caller":"traceutil/trace.go:171","msg":"trace[414451800] transaction","detail":"{read_only:false; response_revision:14839; number_of_response:1; }","duration":"156.106476ms","start":"2026-09-03T03:02:08.873627Z","end":"2026-09-03T03:02:09.029734Z","steps":["trace[414451800] 'process raft request' (duration: 155.007673ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:02:26.565396Z","caller":"traceutil/trace.go:171","msg":"trace[832410110] transaction","detail":"{read_only:false; response_revision:14945; number_of_response:1; }","duration":"114.252081ms","start":"2026-09-03T03:02:26.451107Z","end":"2026-09-03T03:02:26.565359Z","steps":["trace[832410110] 'process raft request' (duration: 114.080506ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:02:27.244914Z","caller":"traceutil/trace.go:171","msg":"trace[1521405472] linearizableReadLoop","detail":"{readStateIndex:15469; appliedIndex:15468; }","duration":"137.964585ms","start":"2026-09-03T03:02:27.106925Z","end":"2026-09-03T03:02:27.24489Z","steps":["trace[1521405472] 'read index received' (duration: 137.659866ms)","trace[1521405472] 'applied index is now lower than readState.Index' (duration: 303.749µs)"],"step_count":2} {"level":"warn","ts":"2026-09-03T03:02:27.245135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.197731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-03T03:02:27.245093Z","caller":"traceutil/trace.go:171","msg":"trace[1952976381] transaction","detail":"{read_only:false; response_revision:14946; number_of_response:1; }","duration":"182.679587ms","start":"2026-09-03T03:02:27.062355Z","end":"2026-09-03T03:02:27.245034Z","steps":["trace[1952976381] 'process raft request' (duration: 182.345967ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:02:27.245178Z","caller":"traceutil/trace.go:171","msg":"trace[781455914] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14946; }","duration":"138.297854ms","start":"2026-09-03T03:02:27.10687Z","end":"2026-09-03T03:02:27.245168Z","steps":["trace[781455914] 'agreement among raft nodes before linearized reading' (duration: 138.116449ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T03:02:27.483652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.557017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-84qk6\" ","response":"range_response_count:1 size:12360"} {"level":"info","ts":"2026-09-03T03:02:27.483747Z","caller":"traceutil/trace.go:171","msg":"trace[441690017] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-84qk6; range_end:; response_count:1; response_revision:14947; }","duration":"171.698691ms","start":"2026-09-03T03:02:27.312024Z","end":"2026-09-03T03:02:27.483722Z","steps":["trace[441690017] 'range keys from in-memory index tree' (duration: 171.391612ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T03:02:38.719696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.859928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-40bfe0d5-a50c-46c6-a418-e00942f58ca0\" ","response":"range_response_count:1 size:3606"} {"level":"info","ts":"2026-09-03T03:02:38.719803Z","caller":"traceutil/trace.go:171","msg":"trace[1847644863] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-40bfe0d5-a50c-46c6-a418-e00942f58ca0; range_end:; response_count:1; response_revision:15122; }","duration":"238.016662ms","start":"2026-09-03T03:02:38.481763Z","end":"2026-09-03T03:02:38.719779Z","steps":["trace[1847644863] 'range keys from in-memory index tree' (duration: 237.647711ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:02:38.72006Z","caller":"traceutil/trace.go:171","msg":"trace[507213592] transaction","detail":"{read_only:false; response_revision:15123; number_of_response:1; }","duration":"152.455135ms","start":"2026-09-03T03:02:38.567582Z","end":"2026-09-03T03:02:38.720037Z","steps":["trace[507213592] 'process raft request' (duration: 135.100537ms)","trace[507213592] 'compare' (duration: 17.225544ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T03:02:39.0528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.174618ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-03T03:02:39.05297Z","caller":"traceutil/trace.go:171","msg":"trace[1074834973] linearizableReadLoop","detail":"{readStateIndex:15650; appliedIndex:15649; }","duration":"330.965085ms","start":"2026-09-03T03:02:38.721983Z","end":"2026-09-03T03:02:39.052948Z","steps":["trace[1074834973] 'read index received' (duration: 154.528839ms)","trace[1074834973] 'applied index is now lower than readState.Index' (duration: 176.434225ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T03:02:39.053114Z","caller":"traceutil/trace.go:171","msg":"trace[1487201586] transaction","detail":"{read_only:false; response_revision:15124; number_of_response:1; }","duration":"448.444175ms","start":"2026-09-03T03:02:38.604577Z","end":"2026-09-03T03:02:39.053021Z","steps":["trace[1487201586] 'process raft request' (duration: 271.918276ms)","trace[1487201586] 'compare' (duration: 175.764256ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T03:02:39.053211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.221652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jvtj5\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-03T03:02:39.053242Z","caller":"traceutil/trace.go:171","msg":"trace[1803842806] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jvtj5; range_end:; response_count:1; response_revision:15124; }","duration":"331.258453ms","start":"2026-09-03T03:02:38.721973Z","end":"2026-09-03T03:02:39.053232Z","steps":["trace[1803842806] 'agreement among raft nodes before linearized reading' (duration: 331.088008ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T03:02:39.053275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T03:02:38.721895Z","time spent":"331.372767ms","remote":"127.0.0.1:40242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6542,"request content":"key:\"/registry/pods/openstack/keepalived-jvtj5\" "} {"level":"warn","ts":"2026-09-03T03:02:39.053344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T03:02:38.604549Z","time spent":"448.679831ms","remote":"127.0.0.1:40316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-03T03:02:39.058319Z","caller":"traceutil/trace.go:171","msg":"trace[2085461146] transaction","detail":"{read_only:false; response_revision:15125; number_of_response:1; }","duration":"330.069258ms","start":"2026-09-03T03:02:38.728232Z","end":"2026-09-03T03:02:39.058301Z","steps":["trace[2085461146] 'process raft request' (duration: 329.948374ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T03:02:39.058421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T03:02:38.728132Z","time spent":"330.238483ms","remote":"127.0.0.1:40242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3711,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-03T03:02:39.062646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.510403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-gbp78.18d1b0782d533c0f\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-09-03T03:02:39.062878Z","caller":"traceutil/trace.go:171","msg":"trace[1360866789] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-gbp78.18d1b0782d533c0f; range_end:; response_count:1; response_revision:15126; }","duration":"292.687118ms","start":"2026-09-03T03:02:38.770061Z","end":"2026-09-03T03:02:39.062748Z","steps":["trace[1360866789] 'agreement among raft nodes before linearized reading' (duration: 292.122152ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:04:17.870101Z","caller":"traceutil/trace.go:171","msg":"trace[80447399] linearizableReadLoop","detail":"{readStateIndex:16772; appliedIndex:16771; }","duration":"104.338418ms","start":"2026-09-03T03:04:17.765721Z","end":"2026-09-03T03:04:17.870059Z","steps":["trace[80447399] 'read index received' (duration: 104.06752ms)","trace[80447399] 'applied index is now lower than readState.Index' (duration: 269.708µs)"],"step_count":2} {"level":"warn","ts":"2026-09-03T03:04:17.87055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.244465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-62gj9\" ","response":"range_response_count:1 size:22089"} {"level":"info","ts":"2026-09-03T03:04:17.870613Z","caller":"traceutil/trace.go:171","msg":"trace[678660996] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-62gj9; range_end:; response_count:1; response_revision:16211; }","duration":"102.388738ms","start":"2026-09-03T03:04:17.768204Z","end":"2026-09-03T03:04:17.870593Z","steps":["trace[678660996] 'agreement among raft nodes before linearized reading' (duration: 102.095339ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T03:04:17.871072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.327958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769703"} {"level":"info","ts":"2026-09-03T03:04:17.871178Z","caller":"traceutil/trace.go:171","msg":"trace[1029994638] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:16211; }","duration":"105.476722ms","start":"2026-09-03T03:04:17.765681Z","end":"2026-09-03T03:04:17.871157Z","steps":["trace[1029994638] 'agreement among raft nodes before linearized reading' (duration: 104.584625ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:04:17.872296Z","caller":"traceutil/trace.go:171","msg":"trace[1859850474] transaction","detail":"{read_only:false; response_revision:16211; number_of_response:1; }","duration":"107.848455ms","start":"2026-09-03T03:04:17.764423Z","end":"2026-09-03T03:04:17.872272Z","steps":["trace[1859850474] 'process raft request' (duration: 105.37054ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:04:18.00713Z","caller":"traceutil/trace.go:171","msg":"trace[529236259] linearizableReadLoop","detail":"{readStateIndex:16773; appliedIndex:16772; }","duration":"126.06761ms","start":"2026-09-03T03:04:17.881007Z","end":"2026-09-03T03:04:18.007075Z","steps":["trace[529236259] 'read index received' (duration: 86.679341ms)","trace[529236259] 'applied index is now lower than readState.Index' (duration: 39.387119ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T03:04:18.007135Z","caller":"traceutil/trace.go:171","msg":"trace[329029886] transaction","detail":"{read_only:false; response_revision:16212; number_of_response:1; }","duration":"130.6637ms","start":"2026-09-03T03:04:17.876427Z","end":"2026-09-03T03:04:18.007091Z","steps":["trace[329029886] 'process raft request' (duration: 91.946641ms)","trace[329029886] 'compare' (duration: 38.573864ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T03:04:18.007936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.827832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769703"} {"level":"info","ts":"2026-09-03T03:04:18.013453Z","caller":"traceutil/trace.go:171","msg":"trace[2081158579] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:16212; }","duration":"131.369872ms","start":"2026-09-03T03:04:17.882048Z","end":"2026-09-03T03:04:18.013418Z","steps":["trace[2081158579] 'agreement among raft nodes before linearized reading' (duration: 125.241344ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T03:04:18.008003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.997339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769703"} {"level":"info","ts":"2026-09-03T03:04:18.013881Z","caller":"traceutil/trace.go:171","msg":"trace[1277848263] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:16212; }","duration":"132.813965ms","start":"2026-09-03T03:04:17.880966Z","end":"2026-09-03T03:04:18.01378Z","steps":["trace[1277848263] 'agreement among raft nodes before linearized reading' (duration: 126.203654ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:06:32.842436Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14613} {"level":"info","ts":"2026-09-03T03:06:32.920281Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14613,"took":"75.878231ms","hash":2229335802,"current-db-size-bytes":57294848,"current-db-size":"57 MB","current-db-size-in-use-bytes":31731712,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-03T03:06:32.920343Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2229335802,"revision":14613,"compact-revision":12625} {"level":"info","ts":"2026-09-03T03:09:59.941316Z","caller":"traceutil/trace.go:171","msg":"trace[282890223] transaction","detail":"{read_only:false; response_revision:18684; number_of_response:1; }","duration":"289.537409ms","start":"2026-09-03T03:09:59.651743Z","end":"2026-09-03T03:09:59.94128Z","steps":["trace[282890223] 'process raft request' (duration: 289.344014ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:10:01.664804Z","caller":"traceutil/trace.go:171","msg":"trace[284918382] linearizableReadLoop","detail":"{readStateIndex:19338; appliedIndex:19337; }","duration":"208.199082ms","start":"2026-09-03T03:10:01.456566Z","end":"2026-09-03T03:10:01.664765Z","steps":["trace[284918382] 'read index received' (duration: 165.507121ms)","trace[284918382] 'applied index is now lower than readState.Index' (duration: 42.690511ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T03:10:01.664859Z","caller":"traceutil/trace.go:171","msg":"trace[29685612] transaction","detail":"{read_only:false; response_revision:18694; number_of_response:1; }","duration":"210.214972ms","start":"2026-09-03T03:10:01.454598Z","end":"2026-09-03T03:10:01.664813Z","steps":["trace[29685612] 'process raft request' (duration: 167.560863ms)","trace[29685612] 'compare' (duration: 42.457014ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T03:10:01.665123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.546752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:1 size:7382"} {"level":"info","ts":"2026-09-03T03:10:01.665194Z","caller":"traceutil/trace.go:171","msg":"trace[280798030] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:1; response_revision:18694; }","duration":"208.653745ms","start":"2026-09-03T03:10:01.456519Z","end":"2026-09-03T03:10:01.665173Z","steps":["trace[280798030] 'agreement among raft nodes before linearized reading' (duration: 208.435599ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:10:01.808537Z","caller":"traceutil/trace.go:171","msg":"trace[749770253] transaction","detail":"{read_only:false; response_revision:18696; number_of_response:1; }","duration":"102.587115ms","start":"2026-09-03T03:10:01.705923Z","end":"2026-09-03T03:10:01.808511Z","steps":["trace[749770253] 'process raft request' (duration: 102.43826ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:10:03.764055Z","caller":"traceutil/trace.go:171","msg":"trace[360405466] transaction","detail":"{read_only:false; response_revision:18709; number_of_response:1; }","duration":"121.287035ms","start":"2026-09-03T03:10:03.642748Z","end":"2026-09-03T03:10:03.764035Z","steps":["trace[360405466] 'process raft request' (duration: 121.141321ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:10:03.936371Z","caller":"traceutil/trace.go:171","msg":"trace[157429634] linearizableReadLoop","detail":"{readStateIndex:19354; appliedIndex:19352; }","duration":"264.569278ms","start":"2026-09-03T03:10:03.67177Z","end":"2026-09-03T03:10:03.93634Z","steps":["trace[157429634] 'read index received' (duration: 92.159777ms)","trace[157429634] 'applied index is now lower than readState.Index' (duration: 172.3894ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T03:10:03.936507Z","caller":"traceutil/trace.go:171","msg":"trace[601779553] transaction","detail":"{read_only:false; response_revision:18710; number_of_response:1; }","duration":"266.075654ms","start":"2026-09-03T03:10:03.670398Z","end":"2026-09-03T03:10:03.936473Z","steps":["trace[601779553] 'process raft request' (duration: 261.900587ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:10:03.936708Z","caller":"traceutil/trace.go:171","msg":"trace[1381282157] transaction","detail":"{read_only:false; response_revision:18711; number_of_response:1; }","duration":"193.801942ms","start":"2026-09-03T03:10:03.742889Z","end":"2026-09-03T03:10:03.936691Z","steps":["trace[1381282157] 'process raft request' (duration: 193.350169ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T03:10:03.937015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.225209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:1 size:7485"} {"level":"info","ts":"2026-09-03T03:10:03.937071Z","caller":"traceutil/trace.go:171","msg":"trace[817758816] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:1; response_revision:18711; }","duration":"265.293061ms","start":"2026-09-03T03:10:03.671761Z","end":"2026-09-03T03:10:03.937055Z","steps":["trace[817758816] 'agreement among raft nodes before linearized reading' (duration: 265.190658ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T03:10:03.937144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.503611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-03T03:10:03.937198Z","caller":"traceutil/trace.go:171","msg":"trace[1458798912] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18711; }","duration":"187.596544ms","start":"2026-09-03T03:10:03.749585Z","end":"2026-09-03T03:10:03.937182Z","steps":["trace[1458798912] 'agreement among raft nodes before linearized reading' (duration: 187.433899ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:11:06.635615Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"337baeb286292d09","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-03T03:11:06.649958Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-03T03:11:06.650118Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-03T03:11:32.851166Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17148} {"level":"info","ts":"2026-09-03T03:11:32.952793Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17148,"took":"98.15023ms","hash":3573530090,"current-db-size-bytes":57294848,"current-db-size":"57 MB","current-db-size-in-use-bytes":34086912,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-03T03:11:32.952901Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3573530090,"revision":17148,"compact-revision":14613} {"level":"info","ts":"2026-09-03T03:16:32.858348Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19682} {"level":"info","ts":"2026-09-03T03:16:32.981176Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19682,"took":"119.198766ms","hash":946826767,"current-db-size-bytes":57294848,"current-db-size":"57 MB","current-db-size-in-use-bytes":30027776,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-03T03:16:32.981245Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":946826767,"revision":19682,"compact-revision":17148} {"level":"info","ts":"2026-09-03T03:16:57.037875Z","caller":"traceutil/trace.go:171","msg":"trace[710720755] transaction","detail":"{read_only:false; response_revision:21829; number_of_response:1; }","duration":"155.265955ms","start":"2026-09-03T03:16:56.882576Z","end":"2026-09-03T03:16:57.037842Z","steps":["trace[710720755] 'process raft request' (duration: 155.09701ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T03:16:57.038166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.422459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-03T03:16:57.038225Z","caller":"traceutil/trace.go:171","msg":"trace[662362437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21829; }","duration":"154.547053ms","start":"2026-09-03T03:16:56.883662Z","end":"2026-09-03T03:16:57.038209Z","steps":["trace[662362437] 'agreement among raft nodes before linearized reading' (duration: 154.307136ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:16:57.03791Z","caller":"traceutil/trace.go:171","msg":"trace[709412638] linearizableReadLoop","detail":"{readStateIndex:22578; appliedIndex:22578; }","duration":"154.162521ms","start":"2026-09-03T03:16:56.883715Z","end":"2026-09-03T03:16:57.037878Z","steps":["trace[709412638] 'read index received' (duration: 154.148141ms)","trace[709412638] 'applied index is now lower than readState.Index' (duration: 12µs)"],"step_count":2} {"level":"info","ts":"2026-09-03T03:16:57.039905Z","caller":"traceutil/trace.go:171","msg":"trace[610622711] transaction","detail":"{read_only:false; response_revision:21830; number_of_response:1; }","duration":"117.757932ms","start":"2026-09-03T03:16:56.922113Z","end":"2026-09-03T03:16:57.039871Z","steps":["trace[610622711] 'process raft request' (duration: 117.138482ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:16:57.462189Z","caller":"traceutil/trace.go:171","msg":"trace[1845489080] linearizableReadLoop","detail":"{readStateIndex:22580; appliedIndex:22579; }","duration":"393.248103ms","start":"2026-09-03T03:16:57.0689Z","end":"2026-09-03T03:16:57.462148Z","steps":["trace[1845489080] 'read index received' (duration: 393.065878ms)","trace[1845489080] 'applied index is now lower than readState.Index' (duration: 180.685µs)"],"step_count":2} {"level":"info","ts":"2026-09-03T03:16:57.462243Z","caller":"traceutil/trace.go:171","msg":"trace[46226185] transaction","detail":"{read_only:false; response_revision:21831; number_of_response:1; }","duration":"415.256163ms","start":"2026-09-03T03:16:57.046951Z","end":"2026-09-03T03:16:57.462207Z","steps":["trace[46226185] 'process raft request' (duration: 415.007746ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T03:16:57.462633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.570062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-03T03:16:57.462657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.947587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-03T03:16:57.462788Z","caller":"traceutil/trace.go:171","msg":"trace[434307364] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:21831; }","duration":"259.188994ms","start":"2026-09-03T03:16:57.203568Z","end":"2026-09-03T03:16:57.462757Z","steps":["trace[434307364] 'agreement among raft nodes before linearized reading' (duration: 258.876934ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:16:57.462705Z","caller":"traceutil/trace.go:171","msg":"trace[209312999] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:21831; }","duration":"393.845042ms","start":"2026-09-03T03:16:57.068837Z","end":"2026-09-03T03:16:57.462682Z","steps":["trace[209312999] 'agreement among raft nodes before linearized reading' (duration: 393.512371ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T03:16:57.462953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T03:16:57.068815Z","time spent":"394.088249ms","remote":"127.0.0.1:51954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-03T03:16:57.46302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T03:16:57.046927Z","time spent":"415.407548ms","remote":"127.0.0.1:40316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-03T03:16:57.685181Z","caller":"traceutil/trace.go:171","msg":"trace[1226916094] transaction","detail":"{read_only:false; response_revision:21832; number_of_response:1; }","duration":"130.332185ms","start":"2026-09-03T03:16:57.554814Z","end":"2026-09-03T03:16:57.685147Z","steps":["trace[1226916094] 'process raft request' (duration: 78.818185ms)","trace[1226916094] 'compare' (duration: 51.312994ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T03:16:58.053677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.795167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-03T03:16:58.053904Z","caller":"traceutil/trace.go:171","msg":"trace[1406801768] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21832; }","duration":"227.061834ms","start":"2026-09-03T03:16:57.826816Z","end":"2026-09-03T03:16:58.053878Z","steps":["trace[1406801768] 'range keys from in-memory index tree' (duration: 226.57721ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T03:16:58.053937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.607679ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-03T03:16:58.054072Z","caller":"traceutil/trace.go:171","msg":"trace[446224879] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:21832; }","duration":"222.768114ms","start":"2026-09-03T03:16:57.831282Z","end":"2026-09-03T03:16:58.05405Z","steps":["trace[446224879] 'range keys from in-memory index tree' (duration: 222.580628ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:16:58.094654Z","caller":"traceutil/trace.go:171","msg":"trace[1581670212] transaction","detail":"{read_only:false; response_revision:21833; number_of_response:1; }","duration":"135.147803ms","start":"2026-09-03T03:16:57.959489Z","end":"2026-09-03T03:16:58.094637Z","steps":["trace[1581670212] 'process raft request' (duration: 134.649948ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:16:58.25759Z","caller":"traceutil/trace.go:171","msg":"trace[1953928835] transaction","detail":"{read_only:false; response_revision:21834; number_of_response:1; }","duration":"128.785958ms","start":"2026-09-03T03:16:58.128771Z","end":"2026-09-03T03:16:58.257556Z","steps":["trace[1953928835] 'process raft request' (duration: 45.724885ms)","trace[1953928835] 'compare' (duration: 82.701452ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T03:16:58.257836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.758643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-03T03:16:58.257975Z","caller":"traceutil/trace.go:171","msg":"trace[790000806] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21834; }","duration":"101.861787ms","start":"2026-09-03T03:16:58.156012Z","end":"2026-09-03T03:16:58.257874Z","steps":["trace[790000806] 'agreement among raft nodes before linearized reading' (duration: 101.763403ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:16:58.257719Z","caller":"traceutil/trace.go:171","msg":"trace[2042125691] linearizableReadLoop","detail":"{readStateIndex:22583; appliedIndex:22582; }","duration":"101.592508ms","start":"2026-09-03T03:16:58.156067Z","end":"2026-09-03T03:16:58.257659Z","steps":["trace[2042125691] 'read index received' (duration: 18.417672ms)","trace[2042125691] 'applied index is now lower than readState.Index' (duration: 83.173226ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T03:16:58.550091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.217852ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-03T03:16:58.550342Z","caller":"traceutil/trace.go:171","msg":"trace[2017753464] transaction","detail":"{read_only:false; response_revision:21835; number_of_response:1; }","duration":"288.067405ms","start":"2026-09-03T03:16:58.262239Z","end":"2026-09-03T03:16:58.550306Z","steps":["trace[2017753464] 'process raft request' (duration: 115.325517ms)","trace[2017753464] 'compare' (duration: 172.032786ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T03:16:58.550416Z","caller":"traceutil/trace.go:171","msg":"trace[41367562] linearizableReadLoop","detail":"{readStateIndex:22585; appliedIndex:22583; }","duration":"285.9575ms","start":"2026-09-03T03:16:58.264411Z","end":"2026-09-03T03:16:58.550369Z","steps":["trace[41367562] 'read index received' (duration: 113.160421ms)","trace[41367562] 'applied index is now lower than readState.Index' (duration: 172.795899ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T03:16:58.55047Z","caller":"traceutil/trace.go:171","msg":"trace[730325534] transaction","detail":"{read_only:false; response_revision:21837; number_of_response:1; }","duration":"260.827445ms","start":"2026-09-03T03:16:58.289616Z","end":"2026-09-03T03:16:58.550443Z","steps":["trace[730325534] 'process raft request' (duration: 260.67863ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T03:16:58.550596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.187609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-03T03:16:58.550662Z","caller":"traceutil/trace.go:171","msg":"trace[821974926] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21837; }","duration":"286.285241ms","start":"2026-09-03T03:16:58.264351Z","end":"2026-09-03T03:16:58.550636Z","steps":["trace[821974926] 'agreement among raft nodes before linearized reading' (duration: 286.109315ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:16:58.550538Z","caller":"traceutil/trace.go:171","msg":"trace[29162225] transaction","detail":"{read_only:false; response_revision:21836; number_of_response:1; }","duration":"288.180928ms","start":"2026-09-03T03:16:58.262294Z","end":"2026-09-03T03:16:58.550475Z","steps":["trace[29162225] 'process raft request' (duration: 287.91728ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:16:58.784236Z","caller":"traceutil/trace.go:171","msg":"trace[269065676] linearizableReadLoop","detail":"{readStateIndex:22587; appliedIndex:22586; }","duration":"233.752439ms","start":"2026-09-03T03:16:58.550463Z","end":"2026-09-03T03:16:58.784216Z","steps":["trace[269065676] 'read index received' (duration: 233.4524ms)","trace[269065676] 'applied index is now lower than readState.Index' (duration: 298.449µs)"],"step_count":2} {"level":"info","ts":"2026-09-03T03:16:58.784342Z","caller":"traceutil/trace.go:171","msg":"trace[1354628642] transaction","detail":"{read_only:false; response_revision:21838; number_of_response:1; }","duration":"354.185511ms","start":"2026-09-03T03:16:58.430134Z","end":"2026-09-03T03:16:58.784319Z","steps":["trace[1354628642] 'process raft request' (duration: 353.786508ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T03:16:58.784481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T03:16:58.43011Z","time spent":"354.296845ms","remote":"127.0.0.1:39608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7680,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-03T03:16:58.784656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.386965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-03T03:16:58.784703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.404791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-03T03:16:58.784748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.78922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19220"} {"level":"info","ts":"2026-09-03T03:16:58.784793Z","caller":"traceutil/trace.go:171","msg":"trace[1050636940] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21838; }","duration":"307.55451ms","start":"2026-09-03T03:16:58.477193Z","end":"2026-09-03T03:16:58.784748Z","steps":["trace[1050636940] 'agreement among raft nodes before linearized reading' (duration: 307.22146ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:16:58.784793Z","caller":"traceutil/trace.go:171","msg":"trace[150337550] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21838; }","duration":"378.720201ms","start":"2026-09-03T03:16:58.406048Z","end":"2026-09-03T03:16:58.784768Z","steps":["trace[150337550] 'agreement among raft nodes before linearized reading' (duration: 378.316159ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T03:16:58.784852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T03:16:58.406024Z","time spent":"378.815174ms","remote":"127.0.0.1:40006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-03T03:16:58.784874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T03:16:58.477172Z","time spent":"307.664053ms","remote":"127.0.0.1:40022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-03T03:16:58.784977Z","caller":"traceutil/trace.go:171","msg":"trace[1519860229] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:21838; }","duration":"232.860671ms","start":"2026-09-03T03:16:58.551929Z","end":"2026-09-03T03:16:58.78479Z","steps":["trace[1519860229] 'agreement among raft nodes before linearized reading' (duration: 232.686746ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:19:42.871859Z","caller":"traceutil/trace.go:171","msg":"trace[851788294] transaction","detail":"{read_only:false; response_revision:23100; number_of_response:1; }","duration":"215.953095ms","start":"2026-09-03T03:19:42.655877Z","end":"2026-09-03T03:19:42.87183Z","steps":["trace[851788294] 'process raft request' (duration: 215.795801ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:19:48.320225Z","caller":"traceutil/trace.go:171","msg":"trace[692230700] transaction","detail":"{read_only:false; response_revision:23143; number_of_response:1; }","duration":"128.007434ms","start":"2026-09-03T03:19:48.192134Z","end":"2026-09-03T03:19:48.320141Z","steps":["trace[692230700] 'process raft request' (duration: 127.501058ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:19:49.443929Z","caller":"traceutil/trace.go:171","msg":"trace[301148720] transaction","detail":"{read_only:false; response_revision:23147; number_of_response:1; }","duration":"124.329152ms","start":"2026-09-03T03:19:49.319567Z","end":"2026-09-03T03:19:49.443896Z","steps":["trace[301148720] 'process raft request' (duration: 124.127306ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:19:49.449004Z","caller":"traceutil/trace.go:171","msg":"trace[743064661] transaction","detail":"{read_only:false; response_revision:23148; number_of_response:1; }","duration":"120.772162ms","start":"2026-09-03T03:19:49.328212Z","end":"2026-09-03T03:19:49.448984Z","steps":["trace[743064661] 'process raft request' (duration: 120.660499ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:21:15.038229Z","caller":"traceutil/trace.go:171","msg":"trace[488487656] transaction","detail":"{read_only:false; response_revision:24412; number_of_response:1; }","duration":"209.076486ms","start":"2026-09-03T03:21:14.829128Z","end":"2026-09-03T03:21:15.038204Z","steps":["trace[488487656] 'process raft request' (duration: 181.65612ms)","trace[488487656] 'compare' (duration: 27.292002ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T03:21:15.042196Z","caller":"traceutil/trace.go:171","msg":"trace[1819457095] transaction","detail":"{read_only:false; response_revision:24413; number_of_response:1; }","duration":"122.894648ms","start":"2026-09-03T03:21:14.919285Z","end":"2026-09-03T03:21:15.04218Z","steps":["trace[1819457095] 'process raft request' (duration: 122.723413ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:21:15.483744Z","caller":"traceutil/trace.go:171","msg":"trace[686087775] transaction","detail":"{read_only:false; response_revision:24415; number_of_response:1; }","duration":"207.192649ms","start":"2026-09-03T03:21:15.276505Z","end":"2026-09-03T03:21:15.483697Z","steps":["trace[686087775] 'process raft request' (duration: 199.600697ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:21:15.483331Z","caller":"traceutil/trace.go:171","msg":"trace[1672572006] linearizableReadLoop","detail":"{readStateIndex:25241; appliedIndex:25240; }","duration":"170.727797ms","start":"2026-09-03T03:21:15.312573Z","end":"2026-09-03T03:21:15.483301Z","steps":["trace[1672572006] 'read index received' (duration: 163.452135ms)","trace[1672572006] 'applied index is now lower than readState.Index' (duration: 7.274622ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T03:21:15.483946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.342866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-03T03:21:15.484013Z","caller":"traceutil/trace.go:171","msg":"trace[132223676] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:24415; }","duration":"171.47484ms","start":"2026-09-03T03:21:15.31252Z","end":"2026-09-03T03:21:15.483995Z","steps":["trace[132223676] 'agreement among raft nodes before linearized reading' (duration: 171.250183ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:21:32.865798Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21709} {"level":"info","ts":"2026-09-03T03:21:32.982762Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21709,"took":"113.821422ms","hash":1865455481,"current-db-size-bytes":57294848,"current-db-size":"57 MB","current-db-size-in-use-bytes":42450944,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-03T03:21:32.982843Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1865455481,"revision":21709,"compact-revision":19682} {"level":"warn","ts":"2026-09-03T03:22:11.645773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.625951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-03T03:22:11.645947Z","caller":"traceutil/trace.go:171","msg":"trace[906550685] transaction","detail":"{read_only:false; response_revision:25110; number_of_response:1; }","duration":"307.616051ms","start":"2026-09-03T03:22:11.338274Z","end":"2026-09-03T03:22:11.64589Z","steps":["trace[906550685] 'process raft request' (duration: 122.757683ms)","trace[906550685] 'compare' (duration: 184.379064ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T03:22:11.646058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T03:22:11.338251Z","time spent":"307.763306ms","remote":"127.0.0.1:40316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-03T03:22:11.646108Z","caller":"traceutil/trace.go:171","msg":"trace[235609703] linearizableReadLoop","detail":"{readStateIndex:25953; appliedIndex:25952; }","duration":"168.840959ms","start":"2026-09-03T03:22:11.477241Z","end":"2026-09-03T03:22:11.646082Z","steps":["trace[235609703] 'read index received' (duration: 40.081µs)","trace[235609703] 'applied index is now lower than readState.Index' (duration: 168.798778ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T03:22:11.646199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.960332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-03T03:22:11.646246Z","caller":"traceutil/trace.go:171","msg":"trace[327907773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25111; }","duration":"169.033284ms","start":"2026-09-03T03:22:11.477197Z","end":"2026-09-03T03:22:11.646231Z","steps":["trace[327907773] 'agreement among raft nodes before linearized reading' (duration: 168.968402ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T03:22:11.646271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.532461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-03T03:22:11.646305Z","caller":"traceutil/trace.go:171","msg":"trace[214569828] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:25111; }","duration":"151.641574ms","start":"2026-09-03T03:22:11.494651Z","end":"2026-09-03T03:22:11.646293Z","steps":["trace[214569828] 'agreement among raft nodes before linearized reading' (duration: 151.548271ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T03:26:32.872187Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24640} {"level":"info","ts":"2026-09-03T03:26:33.023376Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24640,"took":"147.139482ms","hash":1872771585,"current-db-size-bytes":57294848,"current-db-size":"57 MB","current-db-size-in-use-bytes":32964608,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-03T03:26:33.023426Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1872771585,"revision":24640,"compact-revision":21709} {"level":"info","ts":"2026-09-03T03:26:41.176513Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006d8f.wal"} {"level":"info","ts":"2026-09-03T03:30:57.534074Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"337baeb286292d09","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-03T03:30:57.540386Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-03T03:30:57.540608Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}