{"level":"warn","ts":"2026-10-02T15:07:33.922766Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-02T15:07:33.922854Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.72: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.72:2380","--initial-cluster=instance=https://162.253.55.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.72:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-02T15:07:33.923028Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-02T15:07:33.923044Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.72:2380"]} {"level":"info","ts":"2026-10-02T15:07:33.923063Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-02T15:07:33.9237Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.72:2379"]} {"level":"info","ts":"2026-10-02T15:07:33.923775Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.72:2380"],"listen-peer-urls":["https://162.253.55.72:2380"],"advertise-client-urls":["https://162.253.55.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.72:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-02T15:07:33.928654Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.682757ms"} {"level":"info","ts":"2026-10-02T15:07:33.94142Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"638d1b11488f2a2e","cluster-id":"9ca31ccb1db898d"} {"level":"info","ts":"2026-10-02T15:07:33.941484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"638d1b11488f2a2e switched to configuration voters=()"} {"level":"info","ts":"2026-10-02T15:07:33.941511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"638d1b11488f2a2e became follower at term 0"} {"level":"info","ts":"2026-10-02T15:07:33.941519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 638d1b11488f2a2e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-02T15:07:33.941531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"638d1b11488f2a2e became follower at term 1"} {"level":"info","ts":"2026-10-02T15:07:33.941561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"638d1b11488f2a2e switched to configuration voters=(7173419542516804142)"} {"level":"warn","ts":"2026-10-02T15:07:33.948217Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-02T15:07:33.949491Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-02T15:07:33.950931Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-02T15:07:33.952715Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"638d1b11488f2a2e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-02T15:07:33.953329Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-02T15:07:33.953448Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-02T15:07:33.953408Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"638d1b11488f2a2e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-02T15:07:33.953478Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-02T15:07:33.955062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"638d1b11488f2a2e switched to configuration voters=(7173419542516804142)"} {"level":"info","ts":"2026-10-02T15:07:33.955203Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ca31ccb1db898d","local-member-id":"638d1b11488f2a2e","added-peer-id":"638d1b11488f2a2e","added-peer-peer-urls":["https://162.253.55.72:2380"]} {"level":"info","ts":"2026-10-02T15:07:33.957048Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-02T15:07:33.957255Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"638d1b11488f2a2e","initial-advertise-peer-urls":["https://162.253.55.72:2380"],"listen-peer-urls":["https://162.253.55.72:2380"],"advertise-client-urls":["https://162.253.55.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-02T15:07:33.957277Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-02T15:07:33.957676Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.72:2380"} {"level":"info","ts":"2026-10-02T15:07:33.957734Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.72:2380"} {"level":"info","ts":"2026-10-02T15:07:34.442272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"638d1b11488f2a2e is starting a new election at term 1"} {"level":"info","ts":"2026-10-02T15:07:34.44233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"638d1b11488f2a2e became pre-candidate at term 1"} {"level":"info","ts":"2026-10-02T15:07:34.442365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"638d1b11488f2a2e received MsgPreVoteResp from 638d1b11488f2a2e at term 1"} {"level":"info","ts":"2026-10-02T15:07:34.442385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"638d1b11488f2a2e became candidate at term 2"} {"level":"info","ts":"2026-10-02T15:07:34.4424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"638d1b11488f2a2e received MsgVoteResp from 638d1b11488f2a2e at term 2"} {"level":"info","ts":"2026-10-02T15:07:34.442452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"638d1b11488f2a2e became leader at term 2"} {"level":"info","ts":"2026-10-02T15:07:34.442526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 638d1b11488f2a2e elected leader 638d1b11488f2a2e at term 2"} {"level":"info","ts":"2026-10-02T15:07:34.443594Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"638d1b11488f2a2e","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.72:2379]}","request-path":"/0/members/638d1b11488f2a2e/attributes","cluster-id":"9ca31ccb1db898d","publish-timeout":"7s"} {"level":"info","ts":"2026-10-02T15:07:34.443688Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T15:07:34.443624Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T15:07:34.443781Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T15:07:34.444091Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-02T15:07:34.444146Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-02T15:07:34.445421Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-02T15:07:34.446976Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.72:2379"} {"level":"info","ts":"2026-10-02T15:07:34.447031Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ca31ccb1db898d","local-member-id":"638d1b11488f2a2e","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T15:07:34.447161Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T15:07:34.447212Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T15:08:11.684946Z","caller":"traceutil/trace.go:171","msg":"trace[1855266094] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"115.004182ms","start":"2026-10-02T15:08:11.569924Z","end":"2026-10-02T15:08:11.684928Z","steps":["trace[1855266094] 'process raft request' (duration: 114.90924ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:08:18.052836Z","caller":"traceutil/trace.go:171","msg":"trace[937486913] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"331.32662ms","start":"2026-10-02T15:08:17.721464Z","end":"2026-10-02T15:08:18.05279Z","steps":["trace[937486913] 'read index received' (duration: 330.136181ms)","trace[937486913] 'applied index is now lower than readState.Index' (duration: 1.188749ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:08:18.053013Z","caller":"traceutil/trace.go:171","msg":"trace[915309235] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"338.375005ms","start":"2026-10-02T15:08:17.714623Z","end":"2026-10-02T15:08:18.052998Z","steps":["trace[915309235] 'process raft request' (duration: 337.479134ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:08:18.053574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.080378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-02T15:08:18.053729Z","caller":"traceutil/trace.go:171","msg":"trace[1140357389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:630; }","duration":"332.292093ms","start":"2026-10-02T15:08:17.721414Z","end":"2026-10-02T15:08:18.053706Z","steps":["trace[1140357389] 'agreement among raft nodes before linearized reading' (duration: 331.862603ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:08:18.053847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:17.721388Z","time spent":"332.386725ms","remote":"127.0.0.1:40478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-02T15:08:18.054021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.835451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dj474\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-10-02T15:08:18.054329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.774777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-10-02T15:08:18.054399Z","caller":"traceutil/trace.go:171","msg":"trace[1498737997] range","detail":"{range_begin:/registry/masterleases/162.253.55.72; range_end:; response_count:1; response_revision:630; }","duration":"210.89553ms","start":"2026-10-02T15:08:17.843486Z","end":"2026-10-02T15:08:18.054382Z","steps":["trace[1498737997] 'agreement among raft nodes before linearized reading' (duration: 210.756657ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:08:18.054338Z","caller":"traceutil/trace.go:171","msg":"trace[483181081] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dj474; range_end:; response_count:1; response_revision:630; }","duration":"293.256781ms","start":"2026-10-02T15:08:17.761052Z","end":"2026-10-02T15:08:18.054309Z","steps":["trace[483181081] 'agreement among raft nodes before linearized reading' (duration: 292.812452ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:08:18.055453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:17.714585Z","time spent":"338.744724ms","remote":"127.0.0.1:50442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T15:08:18.166371Z","caller":"traceutil/trace.go:171","msg":"trace[459139181] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"107.214857ms","start":"2026-10-02T15:08:18.059124Z","end":"2026-10-02T15:08:18.166339Z","steps":["trace[459139181] 'process raft request' (duration: 106.881559ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:08:18.842054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.575947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T15:08:18.842269Z","caller":"traceutil/trace.go:171","msg":"trace[1259465583] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:655; }","duration":"336.346161ms","start":"2026-10-02T15:08:18.505906Z","end":"2026-10-02T15:08:18.842252Z","steps":["trace[1259465583] 'read index received' (duration: 116.303093ms)","trace[1259465583] 'applied index is now lower than readState.Index' (duration: 220.042438ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:08:18.843586Z","caller":"traceutil/trace.go:171","msg":"trace[1374479455] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"671.085845ms","start":"2026-10-02T15:08:18.172461Z","end":"2026-10-02T15:08:18.843547Z","steps":["trace[1374479455] 'process raft request' (duration: 669.751844ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:08:18.843714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.547298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-02T15:08:18.843828Z","caller":"traceutil/trace.go:171","msg":"trace[2036656299] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:635; }","duration":"337.943018ms","start":"2026-10-02T15:08:18.505852Z","end":"2026-10-02T15:08:18.843795Z","steps":["trace[2036656299] 'agreement among raft nodes before linearized reading' (duration: 336.445373ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:08:18.843849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:18.172432Z","time spent":"671.297699ms","remote":"127.0.0.1:40478","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-10-02T15:08:18.843871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:18.505822Z","time spent":"338.03873ms","remote":"127.0.0.1:40478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-02T15:08:18.84413Z","caller":"traceutil/trace.go:171","msg":"trace[1864795474] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"702.362452ms","start":"2026-10-02T15:08:18.141527Z","end":"2026-10-02T15:08:18.843889Z","steps":["trace[1864795474] 'process raft request' (duration: 700.630412ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:08:18.844369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:18.141494Z","time spent":"702.70569ms","remote":"127.0.0.1:40732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-02T15:08:18.844487Z","caller":"traceutil/trace.go:171","msg":"trace[444652726] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"758.005763ms","start":"2026-10-02T15:08:18.086444Z","end":"2026-10-02T15:08:18.84445Z","steps":["trace[444652726] 'process raft request' (duration: 535.806984ms)","trace[444652726] 'compare' (duration: 219.414614ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:08:18.844617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:18.086415Z","time spent":"758.129867ms","remote":"127.0.0.1:50374","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-10-02T15:08:34.443151Z","caller":"traceutil/trace.go:171","msg":"trace[422687337] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"197.284663ms","start":"2026-10-02T15:08:34.245841Z","end":"2026-10-02T15:08:34.443126Z","steps":["trace[422687337] 'process raft request' (duration: 197.148619ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:09:55.269145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.556777ms","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-10-02T15:09:55.269241Z","caller":"traceutil/trace.go:171","msg":"trace[1651375769] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1885; }","duration":"134.727672ms","start":"2026-10-02T15:09:55.134492Z","end":"2026-10-02T15:09:55.26922Z","steps":["trace[1651375769] 'range keys from in-memory index tree' (duration: 134.343572ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:09:55.269251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.755976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:09:55.269338Z","caller":"traceutil/trace.go:171","msg":"trace[985421011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1885; }","duration":"251.875808ms","start":"2026-10-02T15:09:55.01743Z","end":"2026-10-02T15:09:55.269306Z","steps":["trace[985421011] 'range keys from in-memory index tree' (duration: 251.638103ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:09:55.50664Z","caller":"traceutil/trace.go:171","msg":"trace[469338225] linearizableReadLoop","detail":"{readStateIndex:1957; appliedIndex:1956; }","duration":"116.707916ms","start":"2026-10-02T15:09:55.389889Z","end":"2026-10-02T15:09:55.506596Z","steps":["trace[469338225] 'read index received' (duration: 116.478ms)","trace[469338225] 'applied index is now lower than readState.Index' (duration: 229.146µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:09:55.506758Z","caller":"traceutil/trace.go:171","msg":"trace[1593207879] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"229.707063ms","start":"2026-10-02T15:09:55.277037Z","end":"2026-10-02T15:09:55.506744Z","steps":["trace[1593207879] 'process raft request' (duration: 229.387506ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:09:55.507023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.118127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-02T15:09:55.507148Z","caller":"traceutil/trace.go:171","msg":"trace[982175840] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1887; }","duration":"117.251499ms","start":"2026-10-02T15:09:55.389847Z","end":"2026-10-02T15:09:55.507098Z","steps":["trace[982175840] 'agreement among raft nodes before linearized reading' (duration: 117.034324ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:09:55.95978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.945627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:09:55.959865Z","caller":"traceutil/trace.go:171","msg":"trace[726258026] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1889; }","duration":"169.13443ms","start":"2026-10-02T15:09:55.790713Z","end":"2026-10-02T15:09:55.959847Z","steps":["trace[726258026] 'count revisions from in-memory index tree' (duration: 168.739182ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:09:55.960353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.084435ms","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-10-02T15:09:55.96048Z","caller":"traceutil/trace.go:171","msg":"trace[1150242289] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1889; }","duration":"105.265879ms","start":"2026-10-02T15:09:55.855188Z","end":"2026-10-02T15:09:55.960454Z","steps":["trace[1150242289] 'range keys from in-memory index tree' (duration: 104.823338ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:09:55.96601Z","caller":"traceutil/trace.go:171","msg":"trace[967241161] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"104.358368ms","start":"2026-10-02T15:09:55.861635Z","end":"2026-10-02T15:09:55.965993Z","steps":["trace[967241161] 'process raft request' (duration: 104.240475ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:10:08.211956Z","caller":"traceutil/trace.go:171","msg":"trace[288721868] linearizableReadLoop","detail":"{readStateIndex:2064; appliedIndex:2063; }","duration":"193.698531ms","start":"2026-10-02T15:10:08.018226Z","end":"2026-10-02T15:10:08.211924Z","steps":["trace[288721868] 'read index received' (duration: 193.536067ms)","trace[288721868] 'applied index is now lower than readState.Index' (duration: 161.453µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:10:08.212065Z","caller":"traceutil/trace.go:171","msg":"trace[118320979] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"211.149642ms","start":"2026-10-02T15:10:08.000906Z","end":"2026-10-02T15:10:08.212055Z","steps":["trace[118320979] 'process raft request' (duration: 210.835824ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:10:08.212243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.008338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:10:08.212294Z","caller":"traceutil/trace.go:171","msg":"trace[1688202871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1991; }","duration":"194.12869ms","start":"2026-10-02T15:10:08.018156Z","end":"2026-10-02T15:10:08.212285Z","steps":["trace[1688202871] 'agreement among raft nodes before linearized reading' (duration: 194.044028ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:10:08.241049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.080285ms","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-10-02T15:10:08.241123Z","caller":"traceutil/trace.go:171","msg":"trace[428480201] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1992; }","duration":"205.192158ms","start":"2026-10-02T15:10:08.035912Z","end":"2026-10-02T15:10:08.241104Z","steps":["trace[428480201] 'agreement among raft nodes before linearized reading' (duration: 204.954502ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:10:08.471852Z","caller":"traceutil/trace.go:171","msg":"trace[990123117] linearizableReadLoop","detail":"{readStateIndex:2066; appliedIndex:2065; }","duration":"205.971596ms","start":"2026-10-02T15:10:08.265845Z","end":"2026-10-02T15:10:08.471816Z","steps":["trace[990123117] 'read index received' (duration: 204.139622ms)","trace[990123117] 'applied index is now lower than readState.Index' (duration: 1.830784ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:10:08.471954Z","caller":"traceutil/trace.go:171","msg":"trace[77425140] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"226.711207ms","start":"2026-10-02T15:10:08.245233Z","end":"2026-10-02T15:10:08.471945Z","steps":["trace[77425140] 'process raft request' (duration: 224.74404ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:10:08.472185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.313284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11984"} {"level":"info","ts":"2026-10-02T15:10:08.472215Z","caller":"traceutil/trace.go:171","msg":"trace[586021957] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1993; }","duration":"206.414867ms","start":"2026-10-02T15:10:08.265791Z","end":"2026-10-02T15:10:08.472206Z","steps":["trace[586021957] 'agreement among raft nodes before linearized reading' (duration: 206.260463ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:10:08.47268Z","caller":"traceutil/trace.go:171","msg":"trace[1189889887] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"138.998238ms","start":"2026-10-02T15:10:08.333663Z","end":"2026-10-02T15:10:08.472661Z","steps":["trace[1189889887] 'process raft request' (duration: 138.904576ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:10:24.502639Z","caller":"traceutil/trace.go:171","msg":"trace[1172787411] linearizableReadLoop","detail":"{readStateIndex:2176; appliedIndex:2175; }","duration":"148.243875ms","start":"2026-10-02T15:10:24.354378Z","end":"2026-10-02T15:10:24.502621Z","steps":["trace[1172787411] 'read index received' (duration: 148.107572ms)","trace[1172787411] 'applied index is now lower than readState.Index' (duration: 135.363µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:10:24.502728Z","caller":"traceutil/trace.go:171","msg":"trace[1855824986] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"170.559085ms","start":"2026-10-02T15:10:24.332131Z","end":"2026-10-02T15:10:24.50269Z","steps":["trace[1855824986] 'process raft request' (duration: 170.35932ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:10:24.503087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.694056ms","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-10-02T15:10:24.503158Z","caller":"traceutil/trace.go:171","msg":"trace[938613233] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2100; }","duration":"148.817329ms","start":"2026-10-02T15:10:24.354323Z","end":"2026-10-02T15:10:24.503141Z","steps":["trace[938613233] 'agreement among raft nodes before linearized reading' (duration: 148.397969ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:10:30.622014Z","caller":"traceutil/trace.go:171","msg":"trace[1726056831] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"103.589846ms","start":"2026-10-02T15:10:30.518398Z","end":"2026-10-02T15:10:30.621987Z","steps":["trace[1726056831] 'process raft request' (duration: 103.431334ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:10:30.65607Z","caller":"traceutil/trace.go:171","msg":"trace[1950089555] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"111.985725ms","start":"2026-10-02T15:10:30.544053Z","end":"2026-10-02T15:10:30.656039Z","steps":["trace[1950089555] 'process raft request' (duration: 91.557196ms)","trace[1950089555] 'compare' (duration: 20.119935ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:10:31.004848Z","caller":"traceutil/trace.go:171","msg":"trace[1842706118] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"203.926016ms","start":"2026-10-02T15:10:30.800871Z","end":"2026-10-02T15:10:31.004797Z","steps":["trace[1842706118] 'process raft request' (duration: 137.154984ms)","trace[1842706118] 'compare' (duration: 66.60116ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:10:31.342076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.375219ms","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-10-02T15:10:31.342261Z","caller":"traceutil/trace.go:171","msg":"trace[170648626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2136; }","duration":"229.590721ms","start":"2026-10-02T15:10:31.112629Z","end":"2026-10-02T15:10:31.34222Z","steps":["trace[170648626] 'range keys from in-memory index tree' (duration: 229.192507ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:10:31.569755Z","caller":"traceutil/trace.go:171","msg":"trace[1172874417] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"223.079232ms","start":"2026-10-02T15:10:31.346653Z","end":"2026-10-02T15:10:31.569732Z","steps":["trace[1172874417] 'process raft request' (duration: 222.91612ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:11:29.094414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.558201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T15:11:29.094541Z","caller":"traceutil/trace.go:171","msg":"trace[1384940346] transaction","detail":"{read_only:false; response_revision:2494; number_of_response:1; }","duration":"137.49848ms","start":"2026-10-02T15:11:28.957011Z","end":"2026-10-02T15:11:29.09451Z","steps":["trace[1384940346] 'process raft request' (duration: 11.770047ms)","trace[1384940346] 'compare' (duration: 125.415648ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:11:38.082Z","caller":"traceutil/trace.go:171","msg":"trace[506143726] transaction","detail":"{read_only:false; response_revision:2596; number_of_response:1; }","duration":"130.971588ms","start":"2026-10-02T15:11:37.950992Z","end":"2026-10-02T15:11:38.081964Z","steps":["trace[506143726] 'process raft request' (duration: 118.196038ms)","trace[506143726] 'compare' (duration: 12.544016ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:11:38.673669Z","caller":"traceutil/trace.go:171","msg":"trace[1232093688] transaction","detail":"{read_only:false; response_revision:2599; number_of_response:1; }","duration":"109.099159ms","start":"2026-10-02T15:11:38.564535Z","end":"2026-10-02T15:11:38.673634Z","steps":["trace[1232093688] 'process raft request' (duration: 108.948286ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:12:10.54829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.963237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-02T15:12:10.548387Z","caller":"traceutil/trace.go:171","msg":"trace[1623242621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2772; }","duration":"153.098549ms","start":"2026-10-02T15:12:10.395257Z","end":"2026-10-02T15:12:10.548356Z","steps":["trace[1623242621] 'range keys from in-memory index tree' (duration: 152.730012ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:12:10.548654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.908855ms","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-10-02T15:12:10.548939Z","caller":"traceutil/trace.go:171","msg":"trace[1567288595] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2772; }","duration":"153.086319ms","start":"2026-10-02T15:12:10.395679Z","end":"2026-10-02T15:12:10.548766Z","steps":["trace[1567288595] 'range keys from in-memory index tree' (duration: 152.529319ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:12:16.195348Z","caller":"traceutil/trace.go:171","msg":"trace[790957463] linearizableReadLoop","detail":"{readStateIndex:2905; appliedIndex:2904; }","duration":"114.772447ms","start":"2026-10-02T15:12:16.080549Z","end":"2026-10-02T15:12:16.195321Z","steps":["trace[790957463] 'read index received' (duration: 114.568924ms)","trace[790957463] 'applied index is now lower than readState.Index' (duration: 202.483µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:12:16.195426Z","caller":"traceutil/trace.go:171","msg":"trace[1676981097] transaction","detail":"{read_only:false; response_revision:2804; number_of_response:1; }","duration":"129.588337ms","start":"2026-10-02T15:12:16.065818Z","end":"2026-10-02T15:12:16.195406Z","steps":["trace[1676981097] 'process raft request' (duration: 129.365893ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:12:16.195543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.984911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-02T15:12:16.195581Z","caller":"traceutil/trace.go:171","msg":"trace[1480418120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2804; }","duration":"115.036192ms","start":"2026-10-02T15:12:16.080533Z","end":"2026-10-02T15:12:16.195569Z","steps":["trace[1480418120] 'agreement among raft nodes before linearized reading' (duration: 114.883339ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:15:29.620123Z","caller":"traceutil/trace.go:171","msg":"trace[214657342] transaction","detail":"{read_only:false; response_revision:3903; number_of_response:1; }","duration":"130.902446ms","start":"2026-10-02T15:15:29.489201Z","end":"2026-10-02T15:15:29.620104Z","steps":["trace[214657342] 'process raft request' (duration: 130.466687ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:16:35.660879Z","caller":"traceutil/trace.go:171","msg":"trace[1597593768] transaction","detail":"{read_only:false; response_revision:4424; number_of_response:1; }","duration":"115.390596ms","start":"2026-10-02T15:16:35.545455Z","end":"2026-10-02T15:16:35.660845Z","steps":["trace[1597593768] 'process raft request' (duration: 76.13946ms)","trace[1597593768] 'compare' (duration: 39.047451ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:16:35.661078Z","caller":"traceutil/trace.go:171","msg":"trace[511208499] transaction","detail":"{read_only:false; response_revision:4426; number_of_response:1; }","duration":"114.634089ms","start":"2026-10-02T15:16:35.546409Z","end":"2026-10-02T15:16:35.661043Z","steps":["trace[511208499] 'process raft request' (duration: 114.410385ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:16:35.661118Z","caller":"traceutil/trace.go:171","msg":"trace[812056623] transaction","detail":"{read_only:false; response_revision:4430; number_of_response:1; }","duration":"112.720777ms","start":"2026-10-02T15:16:35.548382Z","end":"2026-10-02T15:16:35.661103Z","steps":["trace[812056623] 'process raft request' (duration: 112.584544ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:16:35.661166Z","caller":"traceutil/trace.go:171","msg":"trace[1137153122] transaction","detail":"{read_only:false; response_revision:4431; number_of_response:1; }","duration":"112.316497ms","start":"2026-10-02T15:16:35.54884Z","end":"2026-10-02T15:16:35.661156Z","steps":["trace[1137153122] 'process raft request' (duration: 112.189184ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:16:35.661119Z","caller":"traceutil/trace.go:171","msg":"trace[1429841481] transaction","detail":"{read_only:false; response_revision:4428; number_of_response:1; }","duration":"113.77235ms","start":"2026-10-02T15:16:35.547329Z","end":"2026-10-02T15:16:35.661101Z","steps":["trace[1429841481] 'process raft request' (duration: 113.565155ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:16:35.661144Z","caller":"traceutil/trace.go:171","msg":"trace[737001764] transaction","detail":"{read_only:false; response_revision:4427; number_of_response:1; }","duration":"114.293501ms","start":"2026-10-02T15:16:35.546694Z","end":"2026-10-02T15:16:35.660987Z","steps":["trace[737001764] 'process raft request' (duration: 114.156819ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:16:35.661274Z","caller":"traceutil/trace.go:171","msg":"trace[1465605685] transaction","detail":"{read_only:false; response_revision:4429; number_of_response:1; }","duration":"113.213577ms","start":"2026-10-02T15:16:35.548032Z","end":"2026-10-02T15:16:35.661246Z","steps":["trace[1465605685] 'process raft request' (duration: 112.89964ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:16:35.660948Z","caller":"traceutil/trace.go:171","msg":"trace[874329344] transaction","detail":"{read_only:false; response_revision:4425; number_of_response:1; }","duration":"115.209201ms","start":"2026-10-02T15:16:35.545704Z","end":"2026-10-02T15:16:35.660913Z","steps":["trace[874329344] 'process raft request' (duration: 115.074309ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:16:39.963434Z","caller":"traceutil/trace.go:171","msg":"trace[375510294] linearizableReadLoop","detail":"{readStateIndex:4894; appliedIndex:4893; }","duration":"105.325517ms","start":"2026-10-02T15:16:39.858083Z","end":"2026-10-02T15:16:39.963409Z","steps":["trace[375510294] 'read index received' (duration: 105.208004ms)","trace[375510294] 'applied index is now lower than readState.Index' (duration: 116.343µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:16:39.963549Z","caller":"traceutil/trace.go:171","msg":"trace[702747977] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"126.903063ms","start":"2026-10-02T15:16:39.836621Z","end":"2026-10-02T15:16:39.963524Z","steps":["trace[702747977] 'process raft request' (duration: 126.655258ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:16:39.963584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.47467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:16:39.963625Z","caller":"traceutil/trace.go:171","msg":"trace[1164359370] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:4715; }","duration":"105.534491ms","start":"2026-10-02T15:16:39.858076Z","end":"2026-10-02T15:16:39.96361Z","steps":["trace[1164359370] 'agreement among raft nodes before linearized reading' (duration: 105.427439ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:16:44.622239Z","caller":"traceutil/trace.go:171","msg":"trace[829503988] transaction","detail":"{read_only:false; response_revision:4835; number_of_response:1; }","duration":"100.457812ms","start":"2026-10-02T15:16:44.521762Z","end":"2026-10-02T15:16:44.62222Z","steps":["trace[829503988] 'process raft request' (duration: 100.354269ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:16:52.893264Z","caller":"traceutil/trace.go:171","msg":"trace[1993016988] transaction","detail":"{read_only:false; response_revision:5020; number_of_response:1; }","duration":"182.066342ms","start":"2026-10-02T15:16:52.711165Z","end":"2026-10-02T15:16:52.893231Z","steps":["trace[1993016988] 'process raft request' (duration: 181.833957ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:16:53.162614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.337358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:16:53.162704Z","caller":"traceutil/trace.go:171","msg":"trace[1903866842] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5020; }","duration":"144.485681ms","start":"2026-10-02T15:16:53.018199Z","end":"2026-10-02T15:16:53.162685Z","steps":["trace[1903866842] 'range keys from in-memory index tree' (duration: 144.197434ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:16:53.689338Z","caller":"traceutil/trace.go:171","msg":"trace[324628438] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"319.724363ms","start":"2026-10-02T15:16:53.369583Z","end":"2026-10-02T15:16:53.689307Z","steps":["trace[324628438] 'process raft request' (duration: 306.103782ms)","trace[324628438] 'compare' (duration: 13.071529ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:16:53.689512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:16:53.369566Z","time spent":"319.847336ms","remote":"127.0.0.1:40490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1868,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T15:16:53.700629Z","caller":"traceutil/trace.go:171","msg":"trace[1101041436] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"330.696846ms","start":"2026-10-02T15:16:53.369883Z","end":"2026-10-02T15:16:53.70058Z","steps":["trace[1101041436] 'process raft request' (duration: 330.468451ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:16:53.700837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:16:53.369873Z","time spent":"330.853319ms","remote":"127.0.0.1:40490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1454,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T15:16:53.700915Z","caller":"traceutil/trace.go:171","msg":"trace[1472116488] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"331.020333ms","start":"2026-10-02T15:16:53.369871Z","end":"2026-10-02T15:16:53.700891Z","steps":["trace[1472116488] 'process raft request' (duration: 330.636754ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:16:53.701126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:16:53.369833Z","time spent":"331.171516ms","remote":"127.0.0.1:40404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T15:16:53.701445Z","caller":"traceutil/trace.go:171","msg":"trace[2114061567] linearizableReadLoop","detail":"{readStateIndex:5216; appliedIndex:5211; }","duration":"283.793736ms","start":"2026-10-02T15:16:53.417628Z","end":"2026-10-02T15:16:53.701422Z","steps":["trace[2114061567] 'read index received' (duration: 258.076957ms)","trace[2114061567] 'applied index is now lower than readState.Index' (duration: 25.715879ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:16:53.701704Z","caller":"traceutil/trace.go:171","msg":"trace[1339292935] transaction","detail":"{read_only:false; response_revision:5027; number_of_response:1; }","duration":"331.526033ms","start":"2026-10-02T15:16:53.37014Z","end":"2026-10-02T15:16:53.701666Z","steps":["trace[1339292935] 'process raft request' (duration: 330.587253ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:16:53.701715Z","caller":"traceutil/trace.go:171","msg":"trace[911560220] transaction","detail":"{read_only:false; response_revision:5028; number_of_response:1; }","duration":"328.67855ms","start":"2026-10-02T15:16:53.373015Z","end":"2026-10-02T15:16:53.701693Z","steps":["trace[911560220] 'process raft request' (duration: 328.290791ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:16:53.701849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:16:53.370107Z","time spent":"331.672727ms","remote":"127.0.0.1:40404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1282,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T15:16:53.701857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:16:53.372989Z","time spent":"328.774533ms","remote":"127.0.0.1:40412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3750,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T15:16:53.704722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.930069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:16:53.704816Z","caller":"traceutil/trace.go:171","msg":"trace[1536057731] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:5028; }","duration":"287.229923ms","start":"2026-10-02T15:16:53.417564Z","end":"2026-10-02T15:16:53.704794Z","steps":["trace[1536057731] 'agreement among raft nodes before linearized reading' (duration: 283.94868ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:17:07.231735Z","caller":"traceutil/trace.go:171","msg":"trace[1142399076] linearizableReadLoop","detail":"{readStateIndex:5403; appliedIndex:5402; }","duration":"259.181337ms","start":"2026-10-02T15:17:06.972507Z","end":"2026-10-02T15:17:07.231688Z","steps":["trace[1142399076] 'read index received' (duration: 218.414562ms)","trace[1142399076] 'applied index is now lower than readState.Index' (duration: 40.765875ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:17:07.231786Z","caller":"traceutil/trace.go:171","msg":"trace[1868105158] transaction","detail":"{read_only:false; response_revision:5210; number_of_response:1; }","duration":"269.260571ms","start":"2026-10-02T15:17:06.962489Z","end":"2026-10-02T15:17:07.23175Z","steps":["trace[1868105158] 'process raft request' (duration: 228.448775ms)","trace[1868105158] 'compare' (duration: 40.650042ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:17:07.231912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.524805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-02T15:17:07.231894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.354051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:17:07.231955Z","caller":"traceutil/trace.go:171","msg":"trace[173333486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5210; }","duration":"214.619407ms","start":"2026-10-02T15:17:07.017326Z","end":"2026-10-02T15:17:07.231945Z","steps":["trace[173333486] 'agreement among raft nodes before linearized reading' (duration: 214.528855ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:17:07.231976Z","caller":"traceutil/trace.go:171","msg":"trace[1627632536] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:5210; }","duration":"259.485523ms","start":"2026-10-02T15:17:06.972471Z","end":"2026-10-02T15:17:07.231957Z","steps":["trace[1627632536] 'agreement among raft nodes before linearized reading' (duration: 259.34725ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:17:07.562958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.355277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13286"} {"level":"info","ts":"2026-10-02T15:17:07.563057Z","caller":"traceutil/trace.go:171","msg":"trace[151761041] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5210; }","duration":"126.589833ms","start":"2026-10-02T15:17:07.436452Z","end":"2026-10-02T15:17:07.563042Z","steps":["trace[151761041] 'range keys from in-memory index tree' (duration: 126.154043ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:17:34.504518Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2902} {"level":"info","ts":"2026-10-02T15:17:34.636511Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2902,"took":"130.183555ms","hash":2680075064,"current-db-size-bytes":54026240,"current-db-size":"54 MB","current-db-size-in-use-bytes":28540928,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-02T15:17:34.636592Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2680075064,"revision":2902,"compact-revision":-1} {"level":"warn","ts":"2026-10-02T15:17:38.137986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.217804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:17:38.138084Z","caller":"traceutil/trace.go:171","msg":"trace[677486758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5495; }","duration":"120.376017ms","start":"2026-10-02T15:17:38.017684Z","end":"2026-10-02T15:17:38.13806Z","steps":["trace[677486758] 'range keys from in-memory index tree' (duration: 120.080471ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:21:05.391422Z","caller":"traceutil/trace.go:171","msg":"trace[1629216644] transaction","detail":"{read_only:false; response_revision:6950; number_of_response:1; }","duration":"146.950322ms","start":"2026-10-02T15:21:05.24446Z","end":"2026-10-02T15:21:05.39141Z","steps":["trace[1629216644] 'process raft request' (duration: 94.267401ms)","trace[1629216644] 'compare' (duration: 52.250973ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:22:34.511188Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5482} {"level":"info","ts":"2026-10-02T15:22:34.648553Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5482,"took":"136.318458ms","hash":555190146,"current-db-size-bytes":54026240,"current-db-size":"54 MB","current-db-size-in-use-bytes":18882560,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-02T15:22:34.648622Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":555190146,"revision":5482,"compact-revision":2902}