{"level":"warn","ts":"2026-04-11T17:47:40.896995Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-11T17:47:40.897159Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.5:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.5:2380","--initial-cluster=instance=https://199.19.213.5:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.5:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.5:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-11T17:47:40.897258Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-11T17:47:40.897278Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.5:2380"]} {"level":"info","ts":"2026-04-11T17:47:40.897314Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-11T17:47:40.898467Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.5:2379"]} {"level":"info","ts":"2026-04-11T17:47:40.898591Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.5:2380"],"listen-peer-urls":["https://199.19.213.5:2380"],"advertise-client-urls":["https://199.19.213.5:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.5:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.5:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-11T17:47:40.903799Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.892374ms"} {"level":"info","ts":"2026-04-11T17:47:40.913338Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"dcb0af7a3afe1d6d","cluster-id":"4564295d4e5c9a3e"} {"level":"info","ts":"2026-04-11T17:47:40.913514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcb0af7a3afe1d6d switched to configuration voters=()"} {"level":"info","ts":"2026-04-11T17:47:40.913572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcb0af7a3afe1d6d became follower at term 0"} {"level":"info","ts":"2026-04-11T17:47:40.913601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft dcb0af7a3afe1d6d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-11T17:47:40.913618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcb0af7a3afe1d6d became follower at term 1"} {"level":"info","ts":"2026-04-11T17:47:40.913696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcb0af7a3afe1d6d switched to configuration voters=(15902403223755824493)"} {"level":"warn","ts":"2026-04-11T17:47:40.921412Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-11T17:47:40.923685Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-11T17:47:40.924627Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-11T17:47:40.927408Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"dcb0af7a3afe1d6d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-11T17:47:40.92767Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-11T17:47:40.92778Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-11T17:47:40.927792Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-11T17:47:40.927886Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"dcb0af7a3afe1d6d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-11T17:47:40.929914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcb0af7a3afe1d6d switched to configuration voters=(15902403223755824493)"} {"level":"info","ts":"2026-04-11T17:47:40.930018Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4564295d4e5c9a3e","local-member-id":"dcb0af7a3afe1d6d","added-peer-id":"dcb0af7a3afe1d6d","added-peer-peer-urls":["https://199.19.213.5:2380"]} {"level":"info","ts":"2026-04-11T17:47:40.933973Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-11T17:47:40.934257Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"dcb0af7a3afe1d6d","initial-advertise-peer-urls":["https://199.19.213.5:2380"],"listen-peer-urls":["https://199.19.213.5:2380"],"advertise-client-urls":["https://199.19.213.5:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.5:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-11T17:47:40.934299Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-11T17:47:40.934855Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.5:2380"} {"level":"info","ts":"2026-04-11T17:47:40.93488Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.5:2380"} {"level":"info","ts":"2026-04-11T17:47:41.414553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcb0af7a3afe1d6d is starting a new election at term 1"} {"level":"info","ts":"2026-04-11T17:47:41.414625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcb0af7a3afe1d6d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-11T17:47:41.414697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcb0af7a3afe1d6d received MsgPreVoteResp from dcb0af7a3afe1d6d at term 1"} {"level":"info","ts":"2026-04-11T17:47:41.414718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcb0af7a3afe1d6d became candidate at term 2"} {"level":"info","ts":"2026-04-11T17:47:41.41473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcb0af7a3afe1d6d received MsgVoteResp from dcb0af7a3afe1d6d at term 2"} {"level":"info","ts":"2026-04-11T17:47:41.414746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcb0af7a3afe1d6d became leader at term 2"} {"level":"info","ts":"2026-04-11T17:47:41.414759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: dcb0af7a3afe1d6d elected leader dcb0af7a3afe1d6d at term 2"} {"level":"info","ts":"2026-04-11T17:47:41.415709Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"dcb0af7a3afe1d6d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.5:2379]}","request-path":"/0/members/dcb0af7a3afe1d6d/attributes","cluster-id":"4564295d4e5c9a3e","publish-timeout":"7s"} {"level":"info","ts":"2026-04-11T17:47:41.415805Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:47:41.416167Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T17:47:41.419422Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-11T17:47:41.42021Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4564295d4e5c9a3e","local-member-id":"dcb0af7a3afe1d6d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:47:41.420331Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:47:41.420364Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:47:41.42031Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-11T17:47:41.420021Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T17:47:41.420401Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-11T17:47:41.422984Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.5:2379"} {"level":"info","ts":"2026-04-11T17:47:44.291258Z","caller":"traceutil/trace.go:171","msg":"trace[584585620] transaction","detail":"{read_only:false; response_revision:104; number_of_response:1; }","duration":"121.032388ms","start":"2026-04-11T17:47:44.17018Z","end":"2026-04-11T17:47:44.291213Z","steps":["trace[584585620] 'process raft request' (duration: 53.513607ms)","trace[584585620] 'compare' (duration: 67.283825ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:47:44.574846Z","caller":"traceutil/trace.go:171","msg":"trace[250801100] transaction","detail":"{read_only:false; response_revision:109; number_of_response:1; }","duration":"187.226416ms","start":"2026-04-11T17:47:44.387583Z","end":"2026-04-11T17:47:44.57481Z","steps":["trace[250801100] 'process raft request' (duration: 187.163554ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:44.575104Z","caller":"traceutil/trace.go:171","msg":"trace[369355646] linearizableReadLoop","detail":"{readStateIndex:113; appliedIndex:111; }","duration":"193.9649ms","start":"2026-04-11T17:47:44.380913Z","end":"2026-04-11T17:47:44.574878Z","steps":["trace[369355646] 'read index received' (duration: 2.879744ms)","trace[369355646] 'applied index is now lower than readState.Index' (duration: 191.080946ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:47:44.575556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.892469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T17:47:44.575691Z","caller":"traceutil/trace.go:171","msg":"trace[1937550834] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:109; }","duration":"115.160517ms","start":"2026-04-11T17:47:44.460503Z","end":"2026-04-11T17:47:44.575663Z","steps":["trace[1937550834] 'agreement among raft nodes before linearized reading' (duration: 114.841288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:44.575613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.771866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T17:47:44.575781Z","caller":"traceutil/trace.go:171","msg":"trace[1990647038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:109; }","duration":"188.969111ms","start":"2026-04-11T17:47:44.386797Z","end":"2026-04-11T17:47:44.575767Z","steps":["trace[1990647038] 'agreement among raft nodes before linearized reading' (duration: 188.453947ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:44.574881Z","caller":"traceutil/trace.go:171","msg":"trace[1719576332] transaction","detail":"{read_only:false; response_revision:108; number_of_response:1; }","duration":"194.910876ms","start":"2026-04-11T17:47:44.379925Z","end":"2026-04-11T17:47:44.574836Z","steps":["trace[1719576332] 'process raft request' (duration: 139.460237ms)","trace[1719576332] 'compare' (duration: 55.14685ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:47:44.576053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.498593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T17:47:44.576306Z","caller":"traceutil/trace.go:171","msg":"trace[1944244937] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:109; }","duration":"195.349116ms","start":"2026-04-11T17:47:44.380847Z","end":"2026-04-11T17:47:44.576196Z","steps":["trace[1944244937] 'agreement among raft nodes before linearized reading' (duration: 194.356871ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:45.014589Z","caller":"traceutil/trace.go:171","msg":"trace[1500357503] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"177.940921ms","start":"2026-04-11T17:47:44.836614Z","end":"2026-04-11T17:47:45.014555Z","steps":["trace[1500357503] 'process raft request' (duration: 177.749423ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:45.194959Z","caller":"traceutil/trace.go:171","msg":"trace[1438168344] transaction","detail":"{read_only:false; response_revision:122; number_of_response:1; }","duration":"109.204984ms","start":"2026-04-11T17:47:45.085725Z","end":"2026-04-11T17:47:45.19493Z","steps":["trace[1438168344] 'process raft request' (duration: 50.102197ms)","trace[1438168344] 'compare' (duration: 58.992728ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:47:45.377128Z","caller":"traceutil/trace.go:171","msg":"trace[806841557] transaction","detail":"{read_only:false; response_revision:125; number_of_response:1; }","duration":"108.850743ms","start":"2026-04-11T17:47:45.268236Z","end":"2026-04-11T17:47:45.377087Z","steps":["trace[806841557] 'process raft request' (duration: 52.365926ms)","trace[806841557] 'compare' (duration: 56.326683ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:47:45.609141Z","caller":"traceutil/trace.go:171","msg":"trace[1506248390] linearizableReadLoop","detail":"{readStateIndex:134; appliedIndex:133; }","duration":"148.854102ms","start":"2026-04-11T17:47:45.460252Z","end":"2026-04-11T17:47:45.609106Z","steps":["trace[1506248390] 'read index received' (duration: 80.492829ms)","trace[1506248390] 'applied index is now lower than readState.Index' (duration: 68.359843ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:47:45.609278Z","caller":"traceutil/trace.go:171","msg":"trace[926742867] transaction","detail":"{read_only:false; response_revision:129; number_of_response:1; }","duration":"176.841704ms","start":"2026-04-11T17:47:45.43238Z","end":"2026-04-11T17:47:45.609222Z","steps":["trace[926742867] 'process raft request' (duration: 108.443588ms)","trace[926742867] 'compare' (duration: 68.038554ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:47:45.609567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.28612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:47:45.609751Z","caller":"traceutil/trace.go:171","msg":"trace[93050202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:129; }","duration":"149.491618ms","start":"2026-04-11T17:47:45.46017Z","end":"2026-04-11T17:47:45.609661Z","steps":["trace[93050202] 'agreement among raft nodes before linearized reading' (duration: 149.091363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:45.637747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.312992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:47:45.63781Z","caller":"traceutil/trace.go:171","msg":"trace[1491926565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:130; }","duration":"169.435872ms","start":"2026-04-11T17:47:45.468359Z","end":"2026-04-11T17:47:45.637795Z","steps":["trace[1491926565] 'agreement among raft nodes before linearized reading' (duration: 169.328803ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:45.833609Z","caller":"traceutil/trace.go:171","msg":"trace[1512644362] linearizableReadLoop","detail":"{readStateIndex:137; appliedIndex:136; }","duration":"123.007678ms","start":"2026-04-11T17:47:45.710573Z","end":"2026-04-11T17:47:45.83358Z","steps":["trace[1512644362] 'read index received' (duration: 69.190946ms)","trace[1512644362] 'applied index is now lower than readState.Index' (duration: 53.815392ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:47:45.83377Z","caller":"traceutil/trace.go:171","msg":"trace[457611909] transaction","detail":"{read_only:false; response_revision:132; number_of_response:1; }","duration":"190.55276ms","start":"2026-04-11T17:47:45.643175Z","end":"2026-04-11T17:47:45.833728Z","steps":["trace[457611909] 'process raft request' (duration: 136.612056ms)","trace[457611909] 'compare' (duration: 53.639277ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:47:45.834066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.465748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-aggregator\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:47:45.834147Z","caller":"traceutil/trace.go:171","msg":"trace[2102385627] range","detail":"{range_begin:/registry/clusterroles/system:kube-aggregator; range_end:; response_count:0; response_revision:132; }","duration":"123.616932ms","start":"2026-04-11T17:47:45.710502Z","end":"2026-04-11T17:47:45.834119Z","steps":["trace[2102385627] 'agreement among raft nodes before linearized reading' (duration: 123.193625ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:46.015208Z","caller":"traceutil/trace.go:171","msg":"trace[818869563] linearizableReadLoop","detail":"{readStateIndex:140; appliedIndex:138; }","duration":"109.941469ms","start":"2026-04-11T17:47:45.905233Z","end":"2026-04-11T17:47:46.015174Z","steps":["trace[818869563] 'read index received' (duration: 39.537256ms)","trace[818869563] 'applied index is now lower than readState.Index' (duration: 70.402293ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:47:46.015233Z","caller":"traceutil/trace.go:171","msg":"trace[2023195083] transaction","detail":"{read_only:false; response_revision:134; number_of_response:1; }","duration":"123.376081ms","start":"2026-04-11T17:47:45.89181Z","end":"2026-04-11T17:47:46.015186Z","steps":["trace[2023195083] 'process raft request' (duration: 52.940316ms)","trace[2023195083] 'compare' (duration: 70.030859ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:47:46.015271Z","caller":"traceutil/trace.go:171","msg":"trace[1115564919] transaction","detail":"{read_only:false; response_revision:135; number_of_response:1; }","duration":"121.551421ms","start":"2026-04-11T17:47:45.893656Z","end":"2026-04-11T17:47:46.015207Z","steps":["trace[1115564919] 'process raft request' (duration: 121.283207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:46.01554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.29893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:47:46.01567Z","caller":"traceutil/trace.go:171","msg":"trace[1745539740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:135; }","duration":"110.685025ms","start":"2026-04-11T17:47:45.904962Z","end":"2026-04-11T17:47:46.015647Z","steps":["trace[1745539740] 'agreement among raft nodes before linearized reading' (duration: 110.322134ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:46.727727Z","caller":"traceutil/trace.go:171","msg":"trace[1302327700] transaction","detail":"{read_only:false; response_revision:159; number_of_response:1; }","duration":"236.977763ms","start":"2026-04-11T17:47:46.490703Z","end":"2026-04-11T17:47:46.727681Z","steps":["trace[1302327700] 'process raft request' (duration: 146.875217ms)","trace[1302327700] 'compare' (duration: 89.832019ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:47:49.385616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.370838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:47:49.385777Z","caller":"traceutil/trace.go:171","msg":"trace[1434397893] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"189.999393ms","start":"2026-04-11T17:47:49.195747Z","end":"2026-04-11T17:47:49.385746Z","steps":["trace[1434397893] 'process raft request' (duration: 79.04237ms)","trace[1434397893] 'compare' (duration: 110.151252ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:47:49.385868Z","caller":"traceutil/trace.go:171","msg":"trace[136519287] linearizableReadLoop","detail":"{readStateIndex:295; appliedIndex:294; }","duration":"187.557951ms","start":"2026-04-11T17:47:49.198301Z","end":"2026-04-11T17:47:49.385859Z","steps":["trace[136519287] 'read index received' (duration: 76.501385ms)","trace[136519287] 'applied index is now lower than readState.Index' (duration: 111.055456ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:47:49.385833Z","caller":"traceutil/trace.go:171","msg":"trace[495085032] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"186.873893ms","start":"2026-04-11T17:47:49.198936Z","end":"2026-04-11T17:47:49.38581Z","steps":["trace[495085032] 'process raft request' (duration: 186.824231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:49.385988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.682405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-11T17:47:49.386016Z","caller":"traceutil/trace.go:171","msg":"trace[301983031] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:290; }","duration":"187.769027ms","start":"2026-04-11T17:47:49.198239Z","end":"2026-04-11T17:47:49.386008Z","steps":["trace[301983031] 'agreement among raft nodes before linearized reading' (duration: 187.669273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:49.631148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.316703ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-04-11T17:47:49.631439Z","caller":"traceutil/trace.go:171","msg":"trace[1969581474] transaction","detail":"{read_only:false; number_of_response:0; response_revision:290; }","duration":"335.509838ms","start":"2026-04-11T17:47:49.29587Z","end":"2026-04-11T17:47:49.63138Z","steps":["trace[1969581474] 'process raft request' (duration: 216.853937ms)","trace[1969581474] 'compare' (duration: 118.24735ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:47:49.631615Z","caller":"traceutil/trace.go:171","msg":"trace[1148978233] transaction","detail":"{read_only:false; number_of_response:0; response_revision:290; }","duration":"312.896476ms","start":"2026-04-11T17:47:49.318655Z","end":"2026-04-11T17:47:49.631551Z","steps":["trace[1148978233] 'process raft request' (duration: 312.628019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:49.631661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:47:49.295847Z","time spent":"335.699223ms","remote":"127.0.0.1:48276","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:47:49.631733Z","caller":"traceutil/trace.go:171","msg":"trace[553322074] linearizableReadLoop","detail":"{readStateIndex:302; appliedIndex:296; }","duration":"245.831816ms","start":"2026-04-11T17:47:49.385883Z","end":"2026-04-11T17:47:49.631715Z","steps":["trace[553322074] 'read index received' (duration: 126.855345ms)","trace[553322074] 'applied index is now lower than readState.Index' (duration: 118.975191ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:47:49.631695Z","caller":"traceutil/trace.go:171","msg":"trace[1332565063] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"312.547166ms","start":"2026-04-11T17:47:49.319088Z","end":"2026-04-11T17:47:49.631636Z","steps":["trace[1332565063] 'process raft request' (duration: 312.32696ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:49.631847Z","caller":"traceutil/trace.go:171","msg":"trace[999133302] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"312.098354ms","start":"2026-04-11T17:47:49.319737Z","end":"2026-04-11T17:47:49.631836Z","steps":["trace[999133302] 'process raft request' (duration: 311.771856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:49.631847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:47:49.318628Z","time spent":"313.115881ms","remote":"127.0.0.1:48282","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-11T17:47:49.631905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:47:49.319704Z","time spent":"312.172136ms","remote":"127.0.0.1:48282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5872,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-11T17:47:49.63194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:47:49.319054Z","time spent":"312.773422ms","remote":"127.0.0.1:48282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-11T17:47:49.632073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.283512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-04-11T17:47:49.632137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.050775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:47:49.632144Z","caller":"traceutil/trace.go:171","msg":"trace[1271265608] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:295; }","duration":"390.408685ms","start":"2026-04-11T17:47:49.241715Z","end":"2026-04-11T17:47:49.632123Z","steps":["trace[1271265608] 'agreement among raft nodes before linearized reading' (duration: 390.18844ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:49.632172Z","caller":"traceutil/trace.go:171","msg":"trace[889995121] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:295; }","duration":"433.124547ms","start":"2026-04-11T17:47:49.199036Z","end":"2026-04-11T17:47:49.632161Z","steps":["trace[889995121] 'agreement among raft nodes before linearized reading' (duration: 433.054186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:49.632183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:47:49.241689Z","time spent":"390.485278ms","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-11T17:47:49.632205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:47:49.199013Z","time spent":"433.185909ms","remote":"127.0.0.1:48352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-04-11T17:47:49.632251Z","caller":"traceutil/trace.go:171","msg":"trace[1362761526] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"311.473888ms","start":"2026-04-11T17:47:49.320716Z","end":"2026-04-11T17:47:49.63219Z","steps":["trace[1362761526] 'process raft request' (duration: 310.883063ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:49.632274Z","caller":"traceutil/trace.go:171","msg":"trace[71794495] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"312.195387ms","start":"2026-04-11T17:47:49.320063Z","end":"2026-04-11T17:47:49.632258Z","steps":["trace[71794495] 'process raft request' (duration: 311.501299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:49.632281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.63411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"warn","ts":"2026-04-11T17:47:49.632287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.15886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-04-11T17:47:49.632337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:47:49.320053Z","time spent":"312.256618ms","remote":"127.0.0.1:48282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3953,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:47:49.632345Z","caller":"traceutil/trace.go:171","msg":"trace[611688884] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:295; }","duration":"240.716621ms","start":"2026-04-11T17:47:49.391608Z","end":"2026-04-11T17:47:49.632325Z","steps":["trace[611688884] 'agreement among raft nodes before linearized reading' (duration: 240.566678ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:49.632358Z","caller":"traceutil/trace.go:171","msg":"trace[606986296] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:295; }","duration":"242.273623ms","start":"2026-04-11T17:47:49.390064Z","end":"2026-04-11T17:47:49.632338Z","steps":["trace[606986296] 'agreement among raft nodes before linearized reading' (duration: 242.084628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:49.632364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:47:49.320707Z","time spent":"311.601852ms","remote":"127.0.0.1:48282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:47:49.632368Z","caller":"traceutil/trace.go:171","msg":"trace[662643190] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"241.752989ms","start":"2026-04-11T17:47:49.390602Z","end":"2026-04-11T17:47:49.632355Z","steps":["trace[662643190] 'process raft request' (duration: 241.053331ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:49.82457Z","caller":"traceutil/trace.go:171","msg":"trace[744083316] linearizableReadLoop","detail":"{readStateIndex:307; appliedIndex:306; }","duration":"127.422448ms","start":"2026-04-11T17:47:49.69711Z","end":"2026-04-11T17:47:49.824532Z","steps":["trace[744083316] 'read index received' (duration: 39.303044ms)","trace[744083316] 'applied index is now lower than readState.Index' (duration: 88.116673ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:47:49.824573Z","caller":"traceutil/trace.go:171","msg":"trace[1290257783] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"168.952389ms","start":"2026-04-11T17:47:49.655556Z","end":"2026-04-11T17:47:49.824508Z","steps":["trace[1290257783] 'process raft request' (duration: 80.885567ms)","trace[1290257783] 'compare' (duration: 87.695152ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:47:49.824836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.273478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4015"} {"level":"warn","ts":"2026-04-11T17:47:49.824828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.754833ms","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-04-11T17:47:49.824883Z","caller":"traceutil/trace.go:171","msg":"trace[1651117320] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:300; }","duration":"183.383192ms","start":"2026-04-11T17:47:49.641488Z","end":"2026-04-11T17:47:49.824871Z","steps":["trace[1651117320] 'agreement among raft nodes before linearized reading' (duration: 183.226508ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:49.824914Z","caller":"traceutil/trace.go:171","msg":"trace[184007294] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:300; }","duration":"173.878806ms","start":"2026-04-11T17:47:49.651014Z","end":"2026-04-11T17:47:49.824892Z","steps":["trace[184007294] 'agreement among raft nodes before linearized reading' (duration: 173.659981ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:49.825318Z","caller":"traceutil/trace.go:171","msg":"trace[770168659] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"123.835415ms","start":"2026-04-11T17:47:49.701469Z","end":"2026-04-11T17:47:49.825304Z","steps":["trace[770168659] 'process raft request' (duration: 122.943913ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:49.825399Z","caller":"traceutil/trace.go:171","msg":"trace[359944236] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"123.356342ms","start":"2026-04-11T17:47:49.702031Z","end":"2026-04-11T17:47:49.825387Z","steps":["trace[359944236] 'process raft request' (duration: 123.110776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:49.82634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.033746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:47:49.826431Z","caller":"traceutil/trace.go:171","msg":"trace[579657786] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:301; }","duration":"125.1891ms","start":"2026-04-11T17:47:49.701222Z","end":"2026-04-11T17:47:49.826411Z","steps":["trace[579657786] 'agreement among raft nodes before linearized reading' (duration: 124.118782ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:49.990154Z","caller":"traceutil/trace.go:171","msg":"trace[256422390] linearizableReadLoop","detail":"{readStateIndex:312; appliedIndex:309; }","duration":"155.690337ms","start":"2026-04-11T17:47:49.83444Z","end":"2026-04-11T17:47:49.99013Z","steps":["trace[256422390] 'read index received' (duration: 34.463279ms)","trace[256422390] 'applied index is now lower than readState.Index' (duration: 121.225778ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:47:49.990245Z","caller":"traceutil/trace.go:171","msg":"trace[800811274] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"151.856809ms","start":"2026-04-11T17:47:49.838368Z","end":"2026-04-11T17:47:49.990225Z","steps":["trace[800811274] 'process raft request' (duration: 151.696304ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:49.990185Z","caller":"traceutil/trace.go:171","msg":"trace[1449039125] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"157.692799ms","start":"2026-04-11T17:47:49.832433Z","end":"2026-04-11T17:47:49.990126Z","steps":["trace[1449039125] 'process raft request' (duration: 125.652533ms)","trace[1449039125] 'compare' (duration: 31.79751ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:47:49.990545Z","caller":"traceutil/trace.go:171","msg":"trace[270124800] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"157.131065ms","start":"2026-04-11T17:47:49.833344Z","end":"2026-04-11T17:47:49.990475Z","steps":["trace[270124800] 'process raft request' (duration: 156.658672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:49.991016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.53993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-11T17:47:49.99112Z","caller":"traceutil/trace.go:171","msg":"trace[1332262788] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:305; }","duration":"156.689104ms","start":"2026-04-11T17:47:49.834407Z","end":"2026-04-11T17:47:49.991096Z","steps":["trace[1332262788] 'agreement among raft nodes before linearized reading' (duration: 156.480219ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:50.642389Z","caller":"traceutil/trace.go:171","msg":"trace[1601283551] linearizableReadLoop","detail":"{readStateIndex:336; appliedIndex:335; }","duration":"151.617213ms","start":"2026-04-11T17:47:50.490744Z","end":"2026-04-11T17:47:50.642361Z","steps":["trace[1601283551] 'read index received' (duration: 95.215598ms)","trace[1601283551] 'applied index is now lower than readState.Index' (duration: 56.400445ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:47:50.642562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.795337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:47:50.642601Z","caller":"traceutil/trace.go:171","msg":"trace[60418617] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:0; response_revision:325; }","duration":"151.879619ms","start":"2026-04-11T17:47:50.490707Z","end":"2026-04-11T17:47:50.642587Z","steps":["trace[60418617] 'agreement among raft nodes before linearized reading' (duration: 151.755916ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:50.642659Z","caller":"traceutil/trace.go:171","msg":"trace[904866331] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"162.555435ms","start":"2026-04-11T17:47:50.48006Z","end":"2026-04-11T17:47:50.642615Z","steps":["trace[904866331] 'process raft request' (duration: 105.870443ms)","trace[904866331] 'compare' (duration: 56.283972ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:47:50.900397Z","caller":"traceutil/trace.go:171","msg":"trace[447861376] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"158.337447ms","start":"2026-04-11T17:47:50.742034Z","end":"2026-04-11T17:47:50.900371Z","steps":["trace[447861376] 'process raft request' (duration: 158.286356ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:50.900672Z","caller":"traceutil/trace.go:171","msg":"trace[25417175] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"180.474318ms","start":"2026-04-11T17:47:50.720124Z","end":"2026-04-11T17:47:50.900598Z","steps":["trace[25417175] 'process raft request' (duration: 115.305096ms)","trace[25417175] 'compare' (duration: 64.73952ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:47:52.896327Z","caller":"traceutil/trace.go:171","msg":"trace[2027215230] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"173.115648ms","start":"2026-04-11T17:47:52.723185Z","end":"2026-04-11T17:47:52.896301Z","steps":["trace[2027215230] 'process raft request' (duration: 173.034445ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:52.896655Z","caller":"traceutil/trace.go:171","msg":"trace[1425268113] linearizableReadLoop","detail":"{readStateIndex:363; appliedIndex:361; }","duration":"204.439005ms","start":"2026-04-11T17:47:52.692186Z","end":"2026-04-11T17:47:52.896625Z","steps":["trace[1425268113] 'read index received' (duration: 22.369447ms)","trace[1425268113] 'applied index is now lower than readState.Index' (duration: 182.066078ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:47:52.896715Z","caller":"traceutil/trace.go:171","msg":"trace[1788390475] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"218.727023ms","start":"2026-04-11T17:47:52.67796Z","end":"2026-04-11T17:47:52.896687Z","steps":["trace[1788390475] 'process raft request' (duration: 143.140083ms)","trace[1788390475] 'compare' (duration: 74.866142ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:47:52.89861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.399937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-04-11T17:47:52.898808Z","caller":"traceutil/trace.go:171","msg":"trace[610037894] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:353; }","duration":"206.546ms","start":"2026-04-11T17:47:52.692171Z","end":"2026-04-11T17:47:52.898717Z","steps":["trace[610037894] 'agreement among raft nodes before linearized reading' (duration: 204.957179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:52.899224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.265848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-11T17:47:52.899247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.181177ms","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-04-11T17:47:52.899706Z","caller":"traceutil/trace.go:171","msg":"trace[1694318084] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:353; }","duration":"157.686919ms","start":"2026-04-11T17:47:52.741989Z","end":"2026-04-11T17:47:52.899675Z","steps":["trace[1694318084] 'agreement among raft nodes before linearized reading' (duration: 156.939829ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:52.899293Z","caller":"traceutil/trace.go:171","msg":"trace[974537947] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:353; }","duration":"107.364431ms","start":"2026-04-11T17:47:52.791898Z","end":"2026-04-11T17:47:52.899262Z","steps":["trace[974537947] 'agreement among raft nodes before linearized reading' (duration: 107.114414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:53.316876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.75976ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:47:53.31756Z","caller":"traceutil/trace.go:171","msg":"trace[741262406] linearizableReadLoop","detail":"{readStateIndex:379; appliedIndex:375; }","duration":"325.12601ms","start":"2026-04-11T17:47:52.992412Z","end":"2026-04-11T17:47:53.317538Z","steps":["trace[741262406] 'read index received' (duration: 1.430307ms)","trace[741262406] 'applied index is now lower than readState.Index' (duration: 323.694153ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:47:53.317757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.333646ms","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-04-11T17:47:53.317799Z","caller":"traceutil/trace.go:171","msg":"trace[411202350] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:370; }","duration":"325.414188ms","start":"2026-04-11T17:47:52.992374Z","end":"2026-04-11T17:47:53.317788Z","steps":["trace[411202350] 'agreement among raft nodes before linearized reading' (duration: 325.239003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:53.317837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:47:52.992362Z","time spent":"325.465829ms","remote":"127.0.0.1:48304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-04-11T17:47:53.317865Z","caller":"traceutil/trace.go:171","msg":"trace[1298305942] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"326.424634ms","start":"2026-04-11T17:47:52.991399Z","end":"2026-04-11T17:47:53.317824Z","steps":["trace[1298305942] 'process raft request' (duration: 325.681425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:53.318057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:47:52.991389Z","time spent":"326.574619ms","remote":"127.0.0.1:48282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3378,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:47:53.318043Z","caller":"traceutil/trace.go:171","msg":"trace[323655721] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"326.496085ms","start":"2026-04-11T17:47:52.991463Z","end":"2026-04-11T17:47:53.317959Z","steps":["trace[323655721] 'process raft request' (duration: 325.741816ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:53.318042Z","caller":"traceutil/trace.go:171","msg":"trace[222888545] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"325.183243ms","start":"2026-04-11T17:47:52.992817Z","end":"2026-04-11T17:47:53.318001Z","steps":["trace[222888545] 'process raft request' (duration: 324.635188ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:53.318153Z","caller":"traceutil/trace.go:171","msg":"trace[536378136] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"326.856335ms","start":"2026-04-11T17:47:52.991284Z","end":"2026-04-11T17:47:53.31814Z","steps":["trace[536378136] 'process raft request' (duration: 325.703375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:53.318225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:47:52.991253Z","time spent":"326.938617ms","remote":"127.0.0.1:48158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":654,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:47:53.318198Z","caller":"traceutil/trace.go:171","msg":"trace[690272601] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"326.912766ms","start":"2026-04-11T17:47:52.991229Z","end":"2026-04-11T17:47:53.318141Z","steps":["trace[690272601] 'process raft request' (duration: 162.806051ms)","trace[690272601] 'compare' (duration: 162.131224ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:47:53.318335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:47:52.99121Z","time spent":"327.05724ms","remote":"127.0.0.1:48304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:47:53.318082Z","caller":"traceutil/trace.go:171","msg":"trace[793400413] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"324.985427ms","start":"2026-04-11T17:47:52.993073Z","end":"2026-04-11T17:47:53.318058Z","steps":["trace[793400413] 'process raft request' (duration: 324.425602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:53.318507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:47:52.993062Z","time spent":"325.367127ms","remote":"127.0.0.1:48438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:47:53.318512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:47:52.992799Z","time spent":"325.342136ms","remote":"127.0.0.1:48438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:47:53.318746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:47:52.991439Z","time spent":"326.750222ms","remote":"127.0.0.1:48538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:47:53.637175Z","caller":"traceutil/trace.go:171","msg":"trace[1428678985] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"242.263612ms","start":"2026-04-11T17:47:53.394885Z","end":"2026-04-11T17:47:53.637149Z","steps":["trace[1428678985] 'process raft request' (duration: 242.19676ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:53.637233Z","caller":"traceutil/trace.go:171","msg":"trace[220852448] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"243.583836ms","start":"2026-04-11T17:47:53.393614Z","end":"2026-04-11T17:47:53.637198Z","steps":["trace[220852448] 'process raft request' (duration: 177.971463ms)","trace[220852448] 'compare' (duration: 65.180572ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:47:53.676695Z","caller":"traceutil/trace.go:171","msg":"trace[668535162] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"277.045429ms","start":"2026-04-11T17:47:53.399602Z","end":"2026-04-11T17:47:53.676647Z","steps":["trace[668535162] 'process raft request' (duration: 276.855554ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:53.676856Z","caller":"traceutil/trace.go:171","msg":"trace[1592991368] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"272.771248ms","start":"2026-04-11T17:47:53.404057Z","end":"2026-04-11T17:47:53.676828Z","steps":["trace[1592991368] 'process raft request' (duration: 272.470551ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:53.677287Z","caller":"traceutil/trace.go:171","msg":"trace[908816012] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:388; }","duration":"277.511241ms","start":"2026-04-11T17:47:53.399757Z","end":"2026-04-11T17:47:53.677268Z","steps":["trace[908816012] 'read index received' (duration: 171.851954ms)","trace[908816012] 'applied index is now lower than readState.Index' (duration: 105.657967ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:47:53.677357Z","caller":"traceutil/trace.go:171","msg":"trace[850695216] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"272.688517ms","start":"2026-04-11T17:47:53.404619Z","end":"2026-04-11T17:47:53.677308Z","steps":["trace[850695216] 'process raft request' (duration: 271.976259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:53.677518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.734286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2839"} {"level":"info","ts":"2026-04-11T17:47:53.677798Z","caller":"traceutil/trace.go:171","msg":"trace[769317063] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:382; }","duration":"278.050285ms","start":"2026-04-11T17:47:53.399728Z","end":"2026-04-11T17:47:53.677778Z","steps":["trace[769317063] 'agreement among raft nodes before linearized reading' (duration: 277.644345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:53.730853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.768413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-04-11T17:47:53.730928Z","caller":"traceutil/trace.go:171","msg":"trace[745953656] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:384; }","duration":"202.955868ms","start":"2026-04-11T17:47:53.527954Z","end":"2026-04-11T17:47:53.73091Z","steps":["trace[745953656] 'agreement among raft nodes before linearized reading' (duration: 202.792284ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:53.903938Z","caller":"traceutil/trace.go:171","msg":"trace[1784713166] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"220.660584ms","start":"2026-04-11T17:47:53.683214Z","end":"2026-04-11T17:47:53.903874Z","steps":["trace[1784713166] 'process raft request' (duration: 186.342509ms)","trace[1784713166] 'compare' (duration: 34.037118ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:47:53.904074Z","caller":"traceutil/trace.go:171","msg":"trace[1181814071] linearizableReadLoop","detail":"{readStateIndex:398; appliedIndex:395; }","duration":"171.63394ms","start":"2026-04-11T17:47:53.732408Z","end":"2026-04-11T17:47:53.904041Z","steps":["trace[1181814071] 'read index received' (duration: 137.229212ms)","trace[1181814071] 'applied index is now lower than readState.Index' (duration: 34.403277ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:47:53.904303Z","caller":"traceutil/trace.go:171","msg":"trace[445123329] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"219.368201ms","start":"2026-04-11T17:47:53.684895Z","end":"2026-04-11T17:47:53.904263Z","steps":["trace[445123329] 'process raft request' (duration: 219.057323ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:53.904509Z","caller":"traceutil/trace.go:171","msg":"trace[1682718183] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"219.594496ms","start":"2026-04-11T17:47:53.68467Z","end":"2026-04-11T17:47:53.904264Z","steps":["trace[1682718183] 'process raft request' (duration: 219.191156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:53.905027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.584893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-gzzzk\" ","response":"range_response_count:1 size:4458"} {"level":"info","ts":"2026-04-11T17:47:53.905123Z","caller":"traceutil/trace.go:171","msg":"trace[1628029215] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-gzzzk; range_end:; response_count:1; response_revision:387; }","duration":"172.736428ms","start":"2026-04-11T17:47:53.732363Z","end":"2026-04-11T17:47:53.9051Z","steps":["trace[1628029215] 'agreement among raft nodes before linearized reading' (duration: 171.783883ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:54.000422Z","caller":"traceutil/trace.go:171","msg":"trace[901005653] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"262.513694ms","start":"2026-04-11T17:47:53.73784Z","end":"2026-04-11T17:47:54.000354Z","steps":["trace[901005653] 'process raft request' (duration: 262.265687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:54.000495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.030165ms","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-04-11T17:47:54.000723Z","caller":"traceutil/trace.go:171","msg":"trace[367412645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:388; }","duration":"116.299322ms","start":"2026-04-11T17:47:53.884398Z","end":"2026-04-11T17:47:54.000698Z","steps":["trace[367412645] 'agreement among raft nodes before linearized reading' (duration: 115.970424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:54.231904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.734332ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:47:54.232118Z","caller":"traceutil/trace.go:171","msg":"trace[2096585039] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"245.777494ms","start":"2026-04-11T17:47:53.986273Z","end":"2026-04-11T17:47:54.232051Z","steps":["trace[2096585039] 'process raft request' (duration: 28.789224ms)","trace[2096585039] 'compare' (duration: 216.575219ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:47:54.232165Z","caller":"traceutil/trace.go:171","msg":"trace[73324830] linearizableReadLoop","detail":"{readStateIndex:400; appliedIndex:399; }","duration":"231.827502ms","start":"2026-04-11T17:47:54.00029Z","end":"2026-04-11T17:47:54.232117Z","steps":["trace[73324830] 'read index received' (duration: 14.853503ms)","trace[73324830] 'applied index is now lower than readState.Index' (duration: 216.969629ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:47:54.232511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.838387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-11T17:47:54.232584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.78808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-11T17:47:54.232639Z","caller":"traceutil/trace.go:171","msg":"trace[1268996776] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:390; }","duration":"317.038381ms","start":"2026-04-11T17:47:53.915572Z","end":"2026-04-11T17:47:54.23261Z","steps":["trace[1268996776] 'agreement among raft nodes before linearized reading' (duration: 316.682413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:54.232702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:47:53.915548Z","time spent":"317.144174ms","remote":"127.0.0.1:48352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-11T17:47:54.232724Z","caller":"traceutil/trace.go:171","msg":"trace[1382618107] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"227.09454ms","start":"2026-04-11T17:47:54.005613Z","end":"2026-04-11T17:47:54.232707Z","steps":["trace[1382618107] 'process raft request' (duration: 226.439314ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:54.23264Z","caller":"traceutil/trace.go:171","msg":"trace[1010804550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:391; }","duration":"143.886273ms","start":"2026-04-11T17:47:54.088732Z","end":"2026-04-11T17:47:54.232618Z","steps":["trace[1010804550] 'agreement among raft nodes before linearized reading' (duration: 143.76743ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:54.232694Z","caller":"traceutil/trace.go:171","msg":"trace[1825707062] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"223.003574ms","start":"2026-04-11T17:47:54.009678Z","end":"2026-04-11T17:47:54.232681Z","steps":["trace[1825707062] 'process raft request' (duration: 222.547093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:54.50859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.320869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:47:54.508866Z","caller":"traceutil/trace.go:171","msg":"trace[1973861978] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"270.295975ms","start":"2026-04-11T17:47:54.238545Z","end":"2026-04-11T17:47:54.508841Z","steps":["trace[1973861978] 'process raft request' (duration: 270.252664ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:54.509111Z","caller":"traceutil/trace.go:171","msg":"trace[1408642307] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"270.591333ms","start":"2026-04-11T17:47:54.238506Z","end":"2026-04-11T17:47:54.509098Z","steps":["trace[1408642307] 'process raft request' (duration: 270.235264ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:54.509289Z","caller":"traceutil/trace.go:171","msg":"trace[605894155] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"271.375633ms","start":"2026-04-11T17:47:54.237878Z","end":"2026-04-11T17:47:54.509254Z","steps":["trace[605894155] 'process raft request' (duration: 120.214202ms)","trace[605894155] 'compare' (duration: 150.098154ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:47:58.386611Z","caller":"traceutil/trace.go:171","msg":"trace[1391673880] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:421; }","duration":"106.629103ms","start":"2026-04-11T17:47:58.279957Z","end":"2026-04-11T17:47:58.386586Z","steps":["trace[1391673880] 'read index received' (duration: 75.588901ms)","trace[1391673880] 'applied index is now lower than readState.Index' (duration: 31.038822ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:47:58.386761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.789656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-11T17:47:58.386798Z","caller":"traceutil/trace.go:171","msg":"trace[1725970075] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:409; }","duration":"106.899329ms","start":"2026-04-11T17:47:58.279886Z","end":"2026-04-11T17:47:58.386785Z","steps":["trace[1725970075] 'agreement among raft nodes before linearized reading' (duration: 106.803767ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:58.386797Z","caller":"traceutil/trace.go:171","msg":"trace[1874990793] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"114.33391ms","start":"2026-04-11T17:47:58.272411Z","end":"2026-04-11T17:47:58.386745Z","steps":["trace[1874990793] 'process raft request' (duration: 83.106814ms)","trace[1874990793] 'compare' (duration: 30.94848ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:48:18.221169Z","caller":"traceutil/trace.go:171","msg":"trace[231472955] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"291.77344ms","start":"2026-04-11T17:48:17.929368Z","end":"2026-04-11T17:48:18.221141Z","steps":["trace[231472955] 'process raft request' (duration: 291.622376ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:18.23268Z","caller":"traceutil/trace.go:171","msg":"trace[615962998] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"302.58341ms","start":"2026-04-11T17:48:17.930075Z","end":"2026-04-11T17:48:18.232659Z","steps":["trace[615962998] 'process raft request' (duration: 301.82777ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:18.232869Z","caller":"traceutil/trace.go:171","msg":"trace[392612375] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"288.102365ms","start":"2026-04-11T17:48:17.94473Z","end":"2026-04-11T17:48:18.232832Z","steps":["trace[392612375] 'process raft request' (duration: 287.962062ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:18.232972Z","caller":"traceutil/trace.go:171","msg":"trace[2085230937] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"281.970227ms","start":"2026-04-11T17:48:17.950961Z","end":"2026-04-11T17:48:18.232932Z","steps":["trace[2085230937] 'process raft request' (duration: 281.777102ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:18.232997Z","caller":"traceutil/trace.go:171","msg":"trace[905359524] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"272.476781ms","start":"2026-04-11T17:48:17.960509Z","end":"2026-04-11T17:48:18.232986Z","steps":["trace[905359524] 'process raft request' (duration: 272.330447ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:18.23307Z","caller":"traceutil/trace.go:171","msg":"trace[1075565764] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"272.475592ms","start":"2026-04-11T17:48:17.960581Z","end":"2026-04-11T17:48:18.233056Z","steps":["trace[1075565764] 'process raft request' (duration: 272.343779ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:18.233139Z","caller":"traceutil/trace.go:171","msg":"trace[626979039] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"264.823773ms","start":"2026-04-11T17:48:17.968304Z","end":"2026-04-11T17:48:18.233128Z","steps":["trace[626979039] 'process raft request' (duration: 264.69397ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:18.232794Z","caller":"traceutil/trace.go:171","msg":"trace[96889648] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"288.505915ms","start":"2026-04-11T17:48:17.944277Z","end":"2026-04-11T17:48:18.232783Z","steps":["trace[96889648] 'process raft request' (duration: 288.369412ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:18.233276Z","caller":"traceutil/trace.go:171","msg":"trace[512208374] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"225.797797ms","start":"2026-04-11T17:48:18.007468Z","end":"2026-04-11T17:48:18.233266Z","steps":["trace[512208374] 'process raft request' (duration: 225.619002ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:18.233378Z","caller":"traceutil/trace.go:171","msg":"trace[616110758] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"299.473209ms","start":"2026-04-11T17:48:17.933276Z","end":"2026-04-11T17:48:18.232749Z","steps":["trace[616110758] 'process raft request' (duration: 299.316614ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:18.233512Z","caller":"traceutil/trace.go:171","msg":"trace[663687623] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"198.626496ms","start":"2026-04-11T17:48:18.034829Z","end":"2026-04-11T17:48:18.233455Z","steps":["trace[663687623] 'process raft request' (duration: 198.438361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:18.233555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:48:17.930049Z","time spent":"302.843116ms","remote":"127.0.0.1:48122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:48:18.233693Z","caller":"traceutil/trace.go:171","msg":"trace[1685788915] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"107.973297ms","start":"2026-04-11T17:48:18.125693Z","end":"2026-04-11T17:48:18.233667Z","steps":["trace[1685788915] 'process raft request' (duration: 107.833893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:18.233743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:48:17.933271Z","time spent":"300.221688ms","remote":"127.0.0.1:48122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:48:23.757694Z","caller":"traceutil/trace.go:171","msg":"trace[360131620] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"283.600758ms","start":"2026-04-11T17:48:23.474067Z","end":"2026-04-11T17:48:23.757668Z","steps":["trace[360131620] 'read index received' (duration: 223.291082ms)","trace[360131620] 'applied index is now lower than readState.Index' (duration: 60.308836ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:48:23.757825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.739833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:48:23.757848Z","caller":"traceutil/trace.go:171","msg":"trace[911832280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"283.804855ms","start":"2026-04-11T17:48:23.474034Z","end":"2026-04-11T17:48:23.757839Z","steps":["trace[911832280] 'agreement among raft nodes before linearized reading' (duration: 283.715651ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:23.757821Z","caller":"traceutil/trace.go:171","msg":"trace[2002152560] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"353.849291ms","start":"2026-04-11T17:48:23.403924Z","end":"2026-04-11T17:48:23.757773Z","steps":["trace[2002152560] 'process raft request' (duration: 293.460313ms)","trace[2002152560] 'compare' (duration: 60.143912ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:48:23.757982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:48:23.403892Z","time spent":"354.012836ms","remote":"127.0.0.1:48438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:48:24.873943Z","caller":"traceutil/trace.go:171","msg":"trace[830502362] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"210.549444ms","start":"2026-04-11T17:48:24.663348Z","end":"2026-04-11T17:48:24.873898Z","steps":["trace[830502362] 'read index received' (duration: 205.336869ms)","trace[830502362] 'applied index is now lower than readState.Index' (duration: 5.211745ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:48:24.873983Z","caller":"traceutil/trace.go:171","msg":"trace[1738382726] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"214.6215ms","start":"2026-04-11T17:48:24.659325Z","end":"2026-04-11T17:48:24.873947Z","steps":["trace[1738382726] 'process raft request' (duration: 213.108761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:24.874175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.77561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-11T17:48:24.874232Z","caller":"traceutil/trace.go:171","msg":"trace[765492220] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"210.904622ms","start":"2026-04-11T17:48:24.663314Z","end":"2026-04-11T17:48:24.874219Z","steps":["trace[765492220] 'agreement among raft nodes before linearized reading' (duration: 210.710607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:24.874287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.328047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-11T17:48:24.874298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.515135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-11T17:48:24.874329Z","caller":"traceutil/trace.go:171","msg":"trace[8559485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"196.396388ms","start":"2026-04-11T17:48:24.677922Z","end":"2026-04-11T17:48:24.874319Z","steps":["trace[8559485] 'agreement among raft nodes before linearized reading' (duration: 196.294325ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:24.874338Z","caller":"traceutil/trace.go:171","msg":"trace[1842896090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"188.585567ms","start":"2026-04-11T17:48:24.685742Z","end":"2026-04-11T17:48:24.874327Z","steps":["trace[1842896090] 'agreement among raft nodes before linearized reading' (duration: 188.485824ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:25.873933Z","caller":"traceutil/trace.go:171","msg":"trace[921237160] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"942.837732ms","start":"2026-04-11T17:48:24.931071Z","end":"2026-04-11T17:48:25.873908Z","steps":["trace[921237160] 'process raft request' (duration: 942.619596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:25.874115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:48:24.931049Z","time spent":"942.956634ms","remote":"127.0.0.1:48352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:48:25.8765Z","caller":"traceutil/trace.go:171","msg":"trace[1520440676] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:657; }","duration":"404.13469ms","start":"2026-04-11T17:48:25.472348Z","end":"2026-04-11T17:48:25.876483Z","steps":["trace[1520440676] 'read index received' (duration: 401.267075ms)","trace[1520440676] 'applied index is now lower than readState.Index' (duration: 2.866574ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:48:25.876607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.243842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:48:25.876627Z","caller":"traceutil/trace.go:171","msg":"trace[1949058550] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"404.311905ms","start":"2026-04-11T17:48:25.472308Z","end":"2026-04-11T17:48:25.87662Z","steps":["trace[1949058550] 'agreement among raft nodes before linearized reading' (duration: 404.237733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:25.876647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:48:25.472289Z","time spent":"404.354105ms","remote":"127.0.0.1:48084","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-04-11T17:48:25.876628Z","caller":"traceutil/trace.go:171","msg":"trace[946098554] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"937.667527ms","start":"2026-04-11T17:48:24.938927Z","end":"2026-04-11T17:48:25.876594Z","steps":["trace[946098554] 'process raft request' (duration: 937.468442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:25.876766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:48:24.938888Z","time spent":"937.811971ms","remote":"127.0.0.1:48584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:48:27.69802Z","caller":"traceutil/trace.go:171","msg":"trace[1321895369] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"145.719242ms","start":"2026-04-11T17:48:27.552276Z","end":"2026-04-11T17:48:27.697996Z","steps":["trace[1321895369] 'process raft request' (duration: 145.589448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:41.726227Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2120524162577856425,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T17:48:41.735773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.203855ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:48:41.735884Z","caller":"traceutil/trace.go:171","msg":"trace[640298207] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:792; }","duration":"391.324859ms","start":"2026-04-11T17:48:41.344543Z","end":"2026-04-11T17:48:41.735868Z","steps":["trace[640298207] 'range keys from in-memory index tree' (duration: 391.187775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:41.735882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.581752ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:48:41.736165Z","caller":"traceutil/trace.go:171","msg":"trace[1263833994] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"663.633906ms","start":"2026-04-11T17:48:41.072452Z","end":"2026-04-11T17:48:41.736086Z","steps":["trace[1263833994] 'process raft request' (duration: 15.762497ms)","trace[1263833994] 'compare' (duration: 647.455289ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:48:41.736465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:48:41.07243Z","time spent":"663.954515ms","remote":"127.0.0.1:48352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:48:41.778552Z","caller":"traceutil/trace.go:171","msg":"trace[8021407] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"705.65034ms","start":"2026-04-11T17:48:41.072886Z","end":"2026-04-11T17:48:41.778536Z","steps":["trace[8021407] 'process raft request' (duration: 705.542738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:41.77869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:48:41.072873Z","time spent":"705.729563ms","remote":"127.0.0.1:48352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:48:41.780038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.053828ms","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-04-11T17:48:41.780186Z","caller":"traceutil/trace.go:171","msg":"trace[306315265] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:794; }","duration":"554.264573ms","start":"2026-04-11T17:48:41.225903Z","end":"2026-04-11T17:48:41.780167Z","steps":["trace[306315265] 'agreement among raft nodes before linearized reading' (duration: 553.72923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:41.780359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:48:41.22588Z","time spent":"554.46451ms","remote":"127.0.0.1:48352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-11T17:48:41.779562Z","caller":"traceutil/trace.go:171","msg":"trace[2071767400] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:821; }","duration":"553.467133ms","start":"2026-04-11T17:48:41.225962Z","end":"2026-04-11T17:48:41.779429Z","steps":["trace[2071767400] 'read index received' (duration: 553.247278ms)","trace[2071767400] 'applied index is now lower than readState.Index' (duration: 200.455µs)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:48:41.781017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.113456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T17:48:41.781053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.217049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-j5xkh\" ","response":"range_response_count:1 size:3963"} {"level":"info","ts":"2026-04-11T17:48:41.781099Z","caller":"traceutil/trace.go:171","msg":"trace[501338950] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-j5xkh; range_end:; response_count:1; response_revision:794; }","duration":"331.306431ms","start":"2026-04-11T17:48:41.449779Z","end":"2026-04-11T17:48:41.781085Z","steps":["trace[501338950] 'agreement among raft nodes before linearized reading' (duration: 331.119415ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:41.781098Z","caller":"traceutil/trace.go:171","msg":"trace[1267080992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"309.24255ms","start":"2026-04-11T17:48:41.471834Z","end":"2026-04-11T17:48:41.781077Z","steps":["trace[1267080992] 'agreement among raft nodes before linearized reading' (duration: 309.116126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:41.781148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:48:41.471799Z","time spent":"309.341133ms","remote":"127.0.0.1:48086","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-04-11T17:48:41.781148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:48:41.449764Z","time spent":"331.374833ms","remote":"127.0.0.1:48282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3986,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-j5xkh\" "} {"level":"info","ts":"2026-04-11T17:49:16.088483Z","caller":"traceutil/trace.go:171","msg":"trace[367017746] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"144.090166ms","start":"2026-04-11T17:49:15.944367Z","end":"2026-04-11T17:49:16.088458Z","steps":["trace[367017746] 'process raft request' (duration: 106.407017ms)","trace[367017746] 'compare' (duration: 37.546535ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:49:16.108894Z","caller":"traceutil/trace.go:171","msg":"trace[917032916] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"163.708866ms","start":"2026-04-11T17:49:15.945164Z","end":"2026-04-11T17:49:16.108873Z","steps":["trace[917032916] 'process raft request' (duration: 163.661305ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:16.10893Z","caller":"traceutil/trace.go:171","msg":"trace[866858466] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"164.126578ms","start":"2026-04-11T17:49:15.944786Z","end":"2026-04-11T17:49:16.108913Z","steps":["trace[866858466] 'process raft request' (duration: 163.913821ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:16.841212Z","caller":"traceutil/trace.go:171","msg":"trace[729514849] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"441.059086ms","start":"2026-04-11T17:49:16.400108Z","end":"2026-04-11T17:49:16.841167Z","steps":["trace[729514849] 'process raft request' (duration: 440.827019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:16.841457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:16.400087Z","time spent":"441.22577ms","remote":"127.0.0.1:48352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:49:17.00007Z","caller":"traceutil/trace.go:171","msg":"trace[1832602368] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"131.950711ms","start":"2026-04-11T17:49:16.868087Z","end":"2026-04-11T17:49:17.000037Z","steps":["trace[1832602368] 'process raft request' (duration: 125.066642ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:39.478586Z","caller":"traceutil/trace.go:171","msg":"trace[228514718] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"154.683251ms","start":"2026-04-11T17:49:39.323847Z","end":"2026-04-11T17:49:39.47853Z","steps":["trace[228514718] 'process raft request' (duration: 154.364283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:40.865966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.709983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:49:40.86607Z","caller":"traceutil/trace.go:171","msg":"trace[165233072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1089; }","duration":"391.858326ms","start":"2026-04-11T17:49:40.474188Z","end":"2026-04-11T17:49:40.866047Z","steps":["trace[165233072] 'range keys from in-memory index tree' (duration: 391.623421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:40.866133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:40.474178Z","time spent":"391.931708ms","remote":"127.0.0.1:48086","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-04-11T17:49:40.866152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.298253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-11T17:49:40.866291Z","caller":"traceutil/trace.go:171","msg":"trace[1411582653] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1089; }","duration":"393.546622ms","start":"2026-04-11T17:49:40.472714Z","end":"2026-04-11T17:49:40.866261Z","steps":["trace[1411582653] 'count revisions from in-memory index tree' (duration: 393.080439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:40.866352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:40.472675Z","time spent":"393.665785ms","remote":"127.0.0.1:58548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-04-11T17:49:41.225613Z","caller":"traceutil/trace.go:171","msg":"trace[1856958466] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"125.455251ms","start":"2026-04-11T17:49:41.100125Z","end":"2026-04-11T17:49:41.225581Z","steps":["trace[1856958466] 'process raft request' (duration: 125.258766ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:42.200242Z","caller":"traceutil/trace.go:171","msg":"trace[447453329] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"174.027975ms","start":"2026-04-11T17:49:42.026186Z","end":"2026-04-11T17:49:42.200214Z","steps":["trace[447453329] 'process raft request' (duration: 173.869311ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:10.549319Z","caller":"traceutil/trace.go:171","msg":"trace[1954940307] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1295; }","duration":"129.762596ms","start":"2026-04-11T17:50:10.419536Z","end":"2026-04-11T17:50:10.549298Z","steps":["trace[1954940307] 'read index received' (duration: 98.856554ms)","trace[1954940307] 'applied index is now lower than readState.Index' (duration: 30.905142ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:50:10.549427Z","caller":"traceutil/trace.go:171","msg":"trace[2104471286] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"132.631679ms","start":"2026-04-11T17:50:10.416776Z","end":"2026-04-11T17:50:10.549408Z","steps":["trace[2104471286] 'process raft request' (duration: 132.486836ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:10.549419Z","caller":"traceutil/trace.go:171","msg":"trace[1792205795] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"133.471941ms","start":"2026-04-11T17:50:10.415901Z","end":"2026-04-11T17:50:10.549373Z","steps":["trace[1792205795] 'process raft request' (duration: 102.478387ms)","trace[1792205795] 'compare' (duration: 30.768508ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:50:10.549486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.92822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-11T17:50:10.54952Z","caller":"traceutil/trace.go:171","msg":"trace[1894647417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1248; }","duration":"129.987902ms","start":"2026-04-11T17:50:10.419521Z","end":"2026-04-11T17:50:10.549509Z","steps":["trace[1894647417] 'agreement among raft nodes before linearized reading' (duration: 129.869208ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:12.520905Z","caller":"traceutil/trace.go:171","msg":"trace[745757632] linearizableReadLoop","detail":"{readStateIndex:1312; appliedIndex:1311; }","duration":"112.265301ms","start":"2026-04-11T17:50:12.408623Z","end":"2026-04-11T17:50:12.520889Z","steps":["trace[745757632] 'read index received' (duration: 112.167299ms)","trace[745757632] 'applied index is now lower than readState.Index' (duration: 97.192µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:50:12.520984Z","caller":"traceutil/trace.go:171","msg":"trace[82485308] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"194.268969ms","start":"2026-04-11T17:50:12.326682Z","end":"2026-04-11T17:50:12.520951Z","steps":["trace[82485308] 'process raft request' (duration: 194.095983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:12.52111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.469847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5329"} {"level":"info","ts":"2026-04-11T17:50:12.521139Z","caller":"traceutil/trace.go:171","msg":"trace[1764142387] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1263; }","duration":"112.550289ms","start":"2026-04-11T17:50:12.408581Z","end":"2026-04-11T17:50:12.521131Z","steps":["trace[1764142387] 'agreement among raft nodes before linearized reading' (duration: 112.391715ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:12.537165Z","caller":"traceutil/trace.go:171","msg":"trace[1843955339] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"125.528687ms","start":"2026-04-11T17:50:12.411622Z","end":"2026-04-11T17:50:12.53715Z","steps":["trace[1843955339] 'process raft request' (duration: 125.191908ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:14.851743Z","caller":"traceutil/trace.go:171","msg":"trace[1631610864] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"152.536196ms","start":"2026-04-11T17:50:14.699172Z","end":"2026-04-11T17:50:14.851708Z","steps":["trace[1631610864] 'process raft request' (duration: 152.34025ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:14.854021Z","caller":"traceutil/trace.go:171","msg":"trace[392216256] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"153.262305ms","start":"2026-04-11T17:50:14.700734Z","end":"2026-04-11T17:50:14.853996Z","steps":["trace[392216256] 'process raft request' (duration: 153.218604ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:14.854229Z","caller":"traceutil/trace.go:171","msg":"trace[2953058] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"153.618734ms","start":"2026-04-11T17:50:14.700574Z","end":"2026-04-11T17:50:14.854193Z","steps":["trace[2953058] 'process raft request' (duration: 153.252385ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:16.754058Z","caller":"traceutil/trace.go:171","msg":"trace[1696769961] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"177.510635ms","start":"2026-04-11T17:50:16.576516Z","end":"2026-04-11T17:50:16.754027Z","steps":["trace[1696769961] 'process raft request' (duration: 126.207254ms)","trace[1696769961] 'compare' (duration: 50.981542ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:50:27.008864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.247111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-11T17:50:27.008911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.670241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-11T17:50:27.008968Z","caller":"traceutil/trace.go:171","msg":"trace[939858690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1365; }","duration":"100.784854ms","start":"2026-04-11T17:50:26.90817Z","end":"2026-04-11T17:50:27.008955Z","steps":["trace[939858690] 'range keys from in-memory index tree' (duration: 100.510428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:27.008926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.315258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-11T17:50:27.008942Z","caller":"traceutil/trace.go:171","msg":"trace[1323943639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1365; }","duration":"100.369384ms","start":"2026-04-11T17:50:26.908555Z","end":"2026-04-11T17:50:27.008924Z","steps":["trace[1323943639] 'range keys from in-memory index tree' (duration: 100.078286ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:27.009029Z","caller":"traceutil/trace.go:171","msg":"trace[1274068871] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1365; }","duration":"101.470992ms","start":"2026-04-11T17:50:26.907533Z","end":"2026-04-11T17:50:27.009004Z","steps":["trace[1274068871] 'range keys from in-memory index tree' (duration: 101.129763ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:27.138547Z","caller":"traceutil/trace.go:171","msg":"trace[1936497106] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"124.363186ms","start":"2026-04-11T17:50:27.014165Z","end":"2026-04-11T17:50:27.138528Z","steps":["trace[1936497106] 'process raft request' (duration: 122.942129ms)"],"step_count":1}