{"level":"warn","ts":"2026-02-04T01:47:28.337397Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-04T01:47:28.337597Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.204:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.204:2380","--initial-cluster=instance=https://162.253.55.204:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.204:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.204: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-02-04T01:47:28.337728Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-04T01:47:28.33777Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-02-04T01:47:28.337838Z","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-02-04T01:47:28.338674Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"]} {"level":"info","ts":"2026-02-04T01:47:28.338801Z","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":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.204: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-02-04T01:47:28.382735Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"43.619912ms"} {"level":"info","ts":"2026-02-04T01:47:28.419501Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a11c20306510c1f7","cluster-id":"97da89d0cbebd2b5"} {"level":"info","ts":"2026-02-04T01:47:28.419693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=()"} {"level":"info","ts":"2026-02-04T01:47:28.419808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 0"} {"level":"info","ts":"2026-02-04T01:47:28.419916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a11c20306510c1f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-04T01:47:28.420671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 1"} {"level":"info","ts":"2026-02-04T01:47:28.420741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"warn","ts":"2026-02-04T01:47:28.431063Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-04T01:47:28.432078Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-04T01:47:28.437771Z","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-02-04T01:47:28.4425Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a11c20306510c1f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-04T01:47:28.449535Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a11c20306510c1f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-04T01:47:28.450611Z","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-02-04T01:47:28.450713Z","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-02-04T01:47:28.450769Z","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-02-04T01:47:28.45999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"info","ts":"2026-02-04T01:47:28.460567Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","added-peer-id":"a11c20306510c1f7","added-peer-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-02-04T01:47:28.460275Z","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-02-04T01:47:28.460305Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-02-04T01:47:28.461211Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-02-04T01:47:28.461743Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a11c20306510c1f7","initial-advertise-peer-urls":["https://162.253.55.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-04T01:47:28.461806Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-04T01:47:28.621854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 is starting a new election at term 1"} {"level":"info","ts":"2026-02-04T01:47:28.622033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-04T01:47:28.622091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgPreVoteResp from a11c20306510c1f7 at term 1"} {"level":"info","ts":"2026-02-04T01:47:28.62214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became candidate at term 2"} {"level":"info","ts":"2026-02-04T01:47:28.622176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgVoteResp from a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-02-04T01:47:28.622207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became leader at term 2"} {"level":"info","ts":"2026-02-04T01:47:28.622246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a11c20306510c1f7 elected leader a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-02-04T01:47:28.623848Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a11c20306510c1f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.204:2379]}","request-path":"/0/members/a11c20306510c1f7/attributes","cluster-id":"97da89d0cbebd2b5","publish-timeout":"7s"} {"level":"info","ts":"2026-02-04T01:47:28.623939Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T01:47:28.624169Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T01:47:28.624489Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:47:28.625046Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-04T01:47:28.625068Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-04T01:47:28.626719Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.204:2379"} {"level":"info","ts":"2026-02-04T01:47:28.626987Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:47:28.627057Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:47:28.627078Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:47:28.629676Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-04T01:47:30.671006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.108251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-04T01:47:30.671183Z","caller":"traceutil/trace.go:171","msg":"trace[1987805004] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"108.304193ms","start":"2026-02-04T01:47:30.56286Z","end":"2026-02-04T01:47:30.671164Z","steps":["trace[1987805004] 'agreement among raft nodes before linearized reading' (duration: 108.069321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:30.674893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.526426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.204\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-04T01:47:30.674939Z","caller":"traceutil/trace.go:171","msg":"trace[1498907645] range","detail":"{range_begin:/registry/masterleases/162.253.55.204; range_end:; response_count:0; response_revision:11; }","duration":"117.590947ms","start":"2026-02-04T01:47:30.557337Z","end":"2026-02-04T01:47:30.674928Z","steps":["trace[1498907645] 'agreement among raft nodes before linearized reading' (duration: 113.771702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:30.671064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.350029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-04T01:47:30.67533Z","caller":"traceutil/trace.go:171","msg":"trace[114333239] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"117.648147ms","start":"2026-02-04T01:47:30.557677Z","end":"2026-02-04T01:47:30.675325Z","steps":["trace[114333239] 'agreement among raft nodes before linearized reading' (duration: 113.326278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:30.880232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.566196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-04T01:47:30.880627Z","caller":"traceutil/trace.go:171","msg":"trace[1063352342] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"190.666143ms","start":"2026-02-04T01:47:30.6899Z","end":"2026-02-04T01:47:30.880566Z","steps":["trace[1063352342] 'process raft request' (duration: 79.774877ms)","trace[1063352342] 'compare' (duration: 108.419424ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:30.881912Z","caller":"traceutil/trace.go:171","msg":"trace[2103564897] linearizableReadLoop","detail":"{readStateIndex:24; appliedIndex:23; }","duration":"118.411424ms","start":"2026-02-04T01:47:30.763447Z","end":"2026-02-04T01:47:30.881858Z","steps":["trace[2103564897] 'read index received' (duration: 6.255416ms)","trace[2103564897] 'applied index is now lower than readState.Index' (duration: 111.942436ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:30.883783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.352545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-04T01:47:30.883841Z","caller":"traceutil/trace.go:171","msg":"trace[721441642] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:25; }","duration":"146.430146ms","start":"2026-02-04T01:47:30.737389Z","end":"2026-02-04T01:47:30.883819Z","steps":["trace[721441642] 'agreement among raft nodes before linearized reading' (duration: 145.556208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:30.884446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.489148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3179"} {"level":"info","ts":"2026-02-04T01:47:30.884595Z","caller":"traceutil/trace.go:171","msg":"trace[131705994] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25; }","duration":"194.65531ms","start":"2026-02-04T01:47:30.689925Z","end":"2026-02-04T01:47:30.88458Z","steps":["trace[131705994] 'agreement among raft nodes before linearized reading' (duration: 194.464928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:30.88538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.071867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-04T01:47:30.885512Z","caller":"traceutil/trace.go:171","msg":"trace[1984915545] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:25; }","duration":"112.287909ms","start":"2026-02-04T01:47:30.773188Z","end":"2026-02-04T01:47:30.885476Z","steps":["trace[1984915545] 'agreement among raft nodes before linearized reading' (duration: 112.100337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:31.266195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.396273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-04T01:47:31.26631Z","caller":"traceutil/trace.go:171","msg":"trace[226467028] linearizableReadLoop","detail":"{readStateIndex:44; appliedIndex:43; }","duration":"172.319549ms","start":"2026-02-04T01:47:31.09397Z","end":"2026-02-04T01:47:31.266289Z","steps":["trace[226467028] 'read index received' (duration: 28.718464ms)","trace[226467028] 'applied index is now lower than readState.Index' (duration: 143.599535ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:31.267111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.124256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-04T01:47:31.267272Z","caller":"traceutil/trace.go:171","msg":"trace[822049662] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"243.875715ms","start":"2026-02-04T01:47:31.023376Z","end":"2026-02-04T01:47:31.267252Z","steps":["trace[822049662] 'process raft request' (duration: 99.344422ms)","trace[822049662] 'compare' (duration: 143.221671ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:31.267182Z","caller":"traceutil/trace.go:171","msg":"trace[364864577] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:40; }","duration":"173.197577ms","start":"2026-02-04T01:47:31.093964Z","end":"2026-02-04T01:47:31.267162Z","steps":["trace[364864577] 'agreement among raft nodes before linearized reading' (duration: 172.38364ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:31.471101Z","caller":"traceutil/trace.go:171","msg":"trace[2002900976] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"199.262898ms","start":"2026-02-04T01:47:31.271818Z","end":"2026-02-04T01:47:31.471081Z","steps":["trace[2002900976] 'process raft request' (duration: 127.644833ms)","trace[2002900976] 'compare' (duration: 71.328883ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:31.821653Z","caller":"traceutil/trace.go:171","msg":"trace[2122555461] transaction","detail":"{read_only:false; response_revision:54; number_of_response:1; }","duration":"129.844092ms","start":"2026-02-04T01:47:31.69179Z","end":"2026-02-04T01:47:31.821634Z","steps":["trace[2122555461] 'process raft request' (duration: 88.99257ms)","trace[2122555461] 'compare' (duration: 39.893804ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:31.822027Z","caller":"traceutil/trace.go:171","msg":"trace[1143232371] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"118.417351ms","start":"2026-02-04T01:47:31.703599Z","end":"2026-02-04T01:47:31.822017Z","steps":["trace[1143232371] 'process raft request' (duration: 117.23228ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:31.82217Z","caller":"traceutil/trace.go:171","msg":"trace[362234325] transaction","detail":"{read_only:false; response_revision:55; number_of_response:1; }","duration":"118.590073ms","start":"2026-02-04T01:47:31.70357Z","end":"2026-02-04T01:47:31.82216Z","steps":["trace[362234325] 'process raft request' (duration: 117.220281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:32.123584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.606781ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-04T01:47:32.123943Z","caller":"traceutil/trace.go:171","msg":"trace[765885029] transaction","detail":"{read_only:false; response_revision:61; number_of_response:1; }","duration":"205.168338ms","start":"2026-02-04T01:47:31.918653Z","end":"2026-02-04T01:47:32.123821Z","steps":["trace[765885029] 'process raft request' (duration: 16.252554ms)","trace[765885029] 'compare' (duration: 188.49583ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:32.186137Z","caller":"traceutil/trace.go:171","msg":"trace[1224548119] transaction","detail":"{read_only:false; response_revision:62; number_of_response:1; }","duration":"265.151314ms","start":"2026-02-04T01:47:31.920963Z","end":"2026-02-04T01:47:32.186114Z","steps":["trace[1224548119] 'process raft request' (duration: 264.934222ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:32.186446Z","caller":"traceutil/trace.go:171","msg":"trace[1876333716] transaction","detail":"{read_only:false; response_revision:63; number_of_response:1; }","duration":"265.436167ms","start":"2026-02-04T01:47:31.920993Z","end":"2026-02-04T01:47:32.18643Z","steps":["trace[1876333716] 'process raft request' (duration: 264.982823ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:32.442388Z","caller":"traceutil/trace.go:171","msg":"trace[1518403670] linearizableReadLoop","detail":"{readStateIndex:71; appliedIndex:70; }","duration":"214.970116ms","start":"2026-02-04T01:47:32.227395Z","end":"2026-02-04T01:47:32.442365Z","steps":["trace[1518403670] 'read index received' (duration: 214.804105ms)","trace[1518403670] 'applied index is now lower than readState.Index' (duration: 165.251µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:32.442768Z","caller":"traceutil/trace.go:171","msg":"trace[2133936268] transaction","detail":"{read_only:false; response_revision:67; number_of_response:1; }","duration":"218.428217ms","start":"2026-02-04T01:47:32.224324Z","end":"2026-02-04T01:47:32.442752Z","steps":["trace[2133936268] 'process raft request' (duration: 217.914002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:32.442959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.550541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:public-info-viewer\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-04T01:47:32.443562Z","caller":"traceutil/trace.go:171","msg":"trace[614923665] range","detail":"{range_begin:/registry/clusterroles/system:public-info-viewer; range_end:; response_count:0; response_revision:67; }","duration":"216.216027ms","start":"2026-02-04T01:47:32.227312Z","end":"2026-02-04T01:47:32.443528Z","steps":["trace[614923665] 'agreement among raft nodes before linearized reading' (duration: 215.490961ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:32.509352Z","caller":"traceutil/trace.go:171","msg":"trace[1902339309] transaction","detail":"{read_only:false; response_revision:68; number_of_response:1; }","duration":"281.401389ms","start":"2026-02-04T01:47:32.227929Z","end":"2026-02-04T01:47:32.509331Z","steps":["trace[1902339309] 'process raft request' (duration: 281.252277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:32.91383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.368465ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-04T01:47:32.914111Z","caller":"traceutil/trace.go:171","msg":"trace[710527778] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"468.249637ms","start":"2026-02-04T01:47:32.445845Z","end":"2026-02-04T01:47:32.914095Z","steps":["trace[710527778] 'process raft request' (duration: 468.067346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:32.914318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:32.445835Z","time spent":"468.446189ms","remote":"127.0.0.1:41120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":600,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:47:32.914625Z","caller":"traceutil/trace.go:171","msg":"trace[968500082] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"468.774012ms","start":"2026-02-04T01:47:32.445838Z","end":"2026-02-04T01:47:32.914612Z","steps":["trace[968500082] 'process raft request' (duration: 110.569169ms)","trace[968500082] 'compare' (duration: 357.243854ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:32.914672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:32.445825Z","time spent":"468.825542ms","remote":"127.0.0.1:41220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":565,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:47:32.920218Z","caller":"traceutil/trace.go:171","msg":"trace[2047138796] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"406.155943ms","start":"2026-02-04T01:47:32.514047Z","end":"2026-02-04T01:47:32.920203Z","steps":["trace[2047138796] 'process raft request' (duration: 406.070792ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:32.920543Z","caller":"traceutil/trace.go:171","msg":"trace[1150469489] linearizableReadLoop","detail":"{readStateIndex:75; appliedIndex:75; }","duration":"353.537032ms","start":"2026-02-04T01:47:32.566982Z","end":"2026-02-04T01:47:32.920519Z","steps":["trace[1150469489] 'read index received' (duration: 353.529622ms)","trace[1150469489] 'applied index is now lower than readState.Index' (duration: 5.93µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:32.920828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.827404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-04T01:47:32.920933Z","caller":"traceutil/trace.go:171","msg":"trace[1720470703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"353.973105ms","start":"2026-02-04T01:47:32.56695Z","end":"2026-02-04T01:47:32.920923Z","steps":["trace[1720470703] 'agreement among raft nodes before linearized reading' (duration: 353.760953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:32.921054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:32.566936Z","time spent":"354.108136ms","remote":"127.0.0.1:40818","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-02-04T01:47:32.920577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:32.51403Z","time spent":"406.232724ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1121,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:47:32.939091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.290231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-04T01:47:32.939126Z","caller":"traceutil/trace.go:171","msg":"trace[921102443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:72; }","duration":"108.351271ms","start":"2026-02-04T01:47:32.830766Z","end":"2026-02-04T01:47:32.939117Z","steps":["trace[921102443] 'agreement among raft nodes before linearized reading' (duration: 108.30621ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:33.160449Z","caller":"traceutil/trace.go:171","msg":"trace[192522930] linearizableReadLoop","detail":"{readStateIndex:80; appliedIndex:77; }","duration":"186.48363ms","start":"2026-02-04T01:47:32.973943Z","end":"2026-02-04T01:47:33.160426Z","steps":["trace[192522930] 'read index received' (duration: 150.589649ms)","trace[192522930] 'applied index is now lower than readState.Index' (duration: 35.893101ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:33.160802Z","caller":"traceutil/trace.go:171","msg":"trace[1380955778] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"217.586803ms","start":"2026-02-04T01:47:32.943188Z","end":"2026-02-04T01:47:33.160775Z","steps":["trace[1380955778] 'process raft request' (duration: 181.390889ms)","trace[1380955778] 'compare' (duration: 35.607109ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:33.161029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.892403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/service-accounts\" ","response":"range_response_count:1 size:1100"} {"level":"info","ts":"2026-02-04T01:47:33.161194Z","caller":"traceutil/trace.go:171","msg":"trace[117895696] range","detail":"{range_begin:/registry/flowschemas/service-accounts; range_end:; response_count:1; response_revision:76; }","duration":"187.178176ms","start":"2026-02-04T01:47:32.973924Z","end":"2026-02-04T01:47:33.161102Z","steps":["trace[117895696] 'agreement among raft nodes before linearized reading' (duration: 186.735172ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:33.161372Z","caller":"traceutil/trace.go:171","msg":"trace[1562354496] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"217.010557ms","start":"2026-02-04T01:47:32.944348Z","end":"2026-02-04T01:47:33.161359Z","steps":["trace[1562354496] 'process raft request' (duration: 215.998248ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:33.161742Z","caller":"traceutil/trace.go:171","msg":"trace[128118696] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"215.780366ms","start":"2026-02-04T01:47:32.945945Z","end":"2026-02-04T01:47:33.161725Z","steps":["trace[128118696] 'process raft request' (duration: 214.440265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:33.389573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.719597ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-04T01:47:33.389878Z","caller":"traceutil/trace.go:171","msg":"trace[15328506] linearizableReadLoop","detail":"{readStateIndex:82; appliedIndex:80; }","duration":"219.603725ms","start":"2026-02-04T01:47:33.170255Z","end":"2026-02-04T01:47:33.389859Z","steps":["trace[15328506] 'read index received' (duration: 91.534344ms)","trace[15328506] 'applied index is now lower than readState.Index' (duration: 128.068271ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:33.390133Z","caller":"traceutil/trace.go:171","msg":"trace[15120156] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"221.791203ms","start":"2026-02-04T01:47:33.16832Z","end":"2026-02-04T01:47:33.390111Z","steps":["trace[15120156] 'process raft request' (duration: 93.4579ms)","trace[15120156] 'compare' (duration: 127.578066ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:33.390439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.171419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-04T01:47:33.390483Z","caller":"traceutil/trace.go:171","msg":"trace[138483966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:78; }","duration":"220.22809ms","start":"2026-02-04T01:47:33.170244Z","end":"2026-02-04T01:47:33.390472Z","steps":["trace[138483966] 'agreement among raft nodes before linearized reading' (duration: 219.689245ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:33.390852Z","caller":"traceutil/trace.go:171","msg":"trace[242002119] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"222.310228ms","start":"2026-02-04T01:47:33.16853Z","end":"2026-02-04T01:47:33.390841Z","steps":["trace[242002119] 'process raft request' (duration: 221.132548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:33.758849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.761757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-04T01:47:33.758966Z","caller":"traceutil/trace.go:171","msg":"trace[279905958] linearizableReadLoop","detail":"{readStateIndex:87; appliedIndex:86; }","duration":"191.746562ms","start":"2026-02-04T01:47:33.567197Z","end":"2026-02-04T01:47:33.758944Z","steps":["trace[279905958] 'read index received' (duration: 35.19µs)","trace[279905958] 'applied index is now lower than readState.Index' (duration: 191.709042ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:33.759283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.073325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-04T01:47:33.759324Z","caller":"traceutil/trace.go:171","msg":"trace[1075872520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:83; }","duration":"192.154406ms","start":"2026-02-04T01:47:33.567159Z","end":"2026-02-04T01:47:33.759313Z","steps":["trace[1075872520] 'agreement among raft nodes before linearized reading' (duration: 191.932014ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:33.759415Z","caller":"traceutil/trace.go:171","msg":"trace[591481138] transaction","detail":"{read_only:false; response_revision:83; number_of_response:1; }","duration":"282.133866ms","start":"2026-02-04T01:47:33.477158Z","end":"2026-02-04T01:47:33.759292Z","steps":["trace[591481138] 'process raft request' (duration: 62.748005ms)","trace[591481138] 'compare' (duration: 218.622275ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:33.99817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.090091ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-04T01:47:33.999022Z","caller":"traceutil/trace.go:171","msg":"trace[2102982790] transaction","detail":"{read_only:false; response_revision:85; number_of_response:1; }","duration":"177.832032ms","start":"2026-02-04T01:47:33.821163Z","end":"2026-02-04T01:47:33.998995Z","steps":["trace[2102982790] 'process raft request' (duration: 48.844443ms)","trace[2102982790] 'compare' (duration: 127.97079ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:34.333849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.49161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-04T01:47:34.333942Z","caller":"traceutil/trace.go:171","msg":"trace[605715391] transaction","detail":"{read_only:false; response_revision:86; number_of_response:1; }","duration":"330.888346ms","start":"2026-02-04T01:47:34.003036Z","end":"2026-02-04T01:47:34.333924Z","steps":["trace[605715391] 'process raft request' (duration: 173.256925ms)","trace[605715391] 'compare' (duration: 157.079236ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:34.333994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:34.003024Z","time spent":"330.949887ms","remote":"127.0.0.1:41120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:47:38.081834Z","caller":"traceutil/trace.go:171","msg":"trace[1307790017] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"125.625745ms","start":"2026-02-04T01:47:37.956193Z","end":"2026-02-04T01:47:38.081818Z","steps":["trace[1307790017] 'process raft request' (duration: 125.527084ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:38.082965Z","caller":"traceutil/trace.go:171","msg":"trace[1513282190] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"123.874071ms","start":"2026-02-04T01:47:37.959085Z","end":"2026-02-04T01:47:38.082959Z","steps":["trace[1513282190] 'process raft request' (duration: 123.83872ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:45.305055Z","caller":"traceutil/trace.go:171","msg":"trace[962703977] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"152.808772ms","start":"2026-02-04T01:47:45.152221Z","end":"2026-02-04T01:47:45.30503Z","steps":["trace[962703977] 'process raft request' (duration: 152.59529ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:45.663913Z","caller":"traceutil/trace.go:171","msg":"trace[1936487873] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"120.795407ms","start":"2026-02-04T01:47:45.543093Z","end":"2026-02-04T01:47:45.663888Z","steps":["trace[1936487873] 'process raft request' (duration: 120.665536ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:46.167558Z","caller":"traceutil/trace.go:171","msg":"trace[1469819308] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"186.983923ms","start":"2026-02-04T01:47:45.980552Z","end":"2026-02-04T01:47:46.167536Z","steps":["trace[1469819308] 'process raft request' (duration: 186.887052ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:46.544008Z","caller":"traceutil/trace.go:171","msg":"trace[2040653965] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"173.103206ms","start":"2026-02-04T01:47:46.370876Z","end":"2026-02-04T01:47:46.54398Z","steps":["trace[2040653965] 'process raft request' (duration: 114.371429ms)","trace[2040653965] 'compare' (duration: 58.329624ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:48.360526Z","caller":"traceutil/trace.go:171","msg":"trace[210133780] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:432; }","duration":"101.69537ms","start":"2026-02-04T01:47:48.258805Z","end":"2026-02-04T01:47:48.360501Z","steps":["trace[210133780] 'read index received' (duration: 101.448908ms)","trace[210133780] 'applied index is now lower than readState.Index' (duration: 245.062µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:48.360668Z","caller":"traceutil/trace.go:171","msg":"trace[210836240] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"176.222377ms","start":"2026-02-04T01:47:48.184435Z","end":"2026-02-04T01:47:48.360658Z","steps":["trace[210836240] 'process raft request' (duration: 175.901385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:48.360855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.033682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:47:48.360906Z","caller":"traceutil/trace.go:171","msg":"trace[1915441383] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:424; }","duration":"102.095503ms","start":"2026-02-04T01:47:48.258799Z","end":"2026-02-04T01:47:48.360894Z","steps":["trace[1915441383] 'agreement among raft nodes before linearized reading' (duration: 102.011822ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:49.819224Z","caller":"traceutil/trace.go:171","msg":"trace[1098909338] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"129.939053ms","start":"2026-02-04T01:47:49.689258Z","end":"2026-02-04T01:47:49.819197Z","steps":["trace[1098909338] 'process raft request' (duration: 129.54637ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:50.19251Z","caller":"traceutil/trace.go:171","msg":"trace[108152539] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"262.032961ms","start":"2026-02-04T01:47:49.93046Z","end":"2026-02-04T01:47:50.192493Z","steps":["trace[108152539] 'process raft request' (duration: 166.452918ms)","trace[108152539] 'compare' (duration: 95.367182ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:50.195074Z","caller":"traceutil/trace.go:171","msg":"trace[1711774477] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"254.357753ms","start":"2026-02-04T01:47:49.94071Z","end":"2026-02-04T01:47:50.195068Z","steps":["trace[1711774477] 'process raft request' (duration: 254.334693ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:50.195169Z","caller":"traceutil/trace.go:171","msg":"trace[615132103] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"263.581242ms","start":"2026-02-04T01:47:49.931583Z","end":"2026-02-04T01:47:50.195164Z","steps":["trace[615132103] 'process raft request' (duration: 263.43016ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:50.479911Z","caller":"traceutil/trace.go:171","msg":"trace[434992470] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"273.999354ms","start":"2026-02-04T01:47:50.205891Z","end":"2026-02-04T01:47:50.479891Z","steps":["trace[434992470] 'process raft request' (duration: 244.797299ms)","trace[434992470] 'compare' (duration: 29.008593ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:50.480135Z","caller":"traceutil/trace.go:171","msg":"trace[186884127] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"271.089304ms","start":"2026-02-04T01:47:50.209039Z","end":"2026-02-04T01:47:50.480128Z","steps":["trace[186884127] 'process raft request' (duration: 270.984833ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:50.480192Z","caller":"traceutil/trace.go:171","msg":"trace[1114613820] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"212.077199ms","start":"2026-02-04T01:47:50.268087Z","end":"2026-02-04T01:47:50.480164Z","steps":["trace[1114613820] 'process raft request' (duration: 212.042669ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:50.480309Z","caller":"traceutil/trace.go:171","msg":"trace[1473252080] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"274.342507ms","start":"2026-02-04T01:47:50.205958Z","end":"2026-02-04T01:47:50.4803Z","steps":["trace[1473252080] 'process raft request' (duration: 273.925944ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:50.480464Z","caller":"traceutil/trace.go:171","msg":"trace[243926901] linearizableReadLoop","detail":"{readStateIndex:447; appliedIndex:443; }","duration":"216.756993ms","start":"2026-02-04T01:47:50.2637Z","end":"2026-02-04T01:47:50.480457Z","steps":["trace[243926901] 'read index received' (duration: 186.999195ms)","trace[243926901] 'applied index is now lower than readState.Index' (duration: 29.757088ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:50.480566Z","caller":"traceutil/trace.go:171","msg":"trace[317485296] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"270.785241ms","start":"2026-02-04T01:47:50.209775Z","end":"2026-02-04T01:47:50.48056Z","steps":["trace[317485296] 'process raft request' (duration: 270.291307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:50.480674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.959664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-9t8nt\" ","response":"range_response_count:1 size:3994"} {"level":"info","ts":"2026-02-04T01:47:50.480697Z","caller":"traceutil/trace.go:171","msg":"trace[1451398477] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-9t8nt; range_end:; response_count:1; response_revision:439; }","duration":"217.012326ms","start":"2026-02-04T01:47:50.263678Z","end":"2026-02-04T01:47:50.48069Z","steps":["trace[1451398477] 'agreement among raft nodes before linearized reading' (duration: 216.956055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:50.480774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.724773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:47:50.48079Z","caller":"traceutil/trace.go:171","msg":"trace[1257225806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:439; }","duration":"212.770874ms","start":"2026-02-04T01:47:50.268014Z","end":"2026-02-04T01:47:50.480785Z","steps":["trace[1257225806] 'agreement among raft nodes before linearized reading' (duration: 212.746704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:50.48088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.808294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4173"} {"level":"info","ts":"2026-02-04T01:47:50.4809Z","caller":"traceutil/trace.go:171","msg":"trace[500322029] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:439; }","duration":"212.836004ms","start":"2026-02-04T01:47:50.268058Z","end":"2026-02-04T01:47:50.480894Z","steps":["trace[500322029] 'agreement among raft nodes before linearized reading' (duration: 212.803534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:50.774915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.508799ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-04T01:47:50.775066Z","caller":"traceutil/trace.go:171","msg":"trace[605828134] linearizableReadLoop","detail":"{readStateIndex:449; appliedIndex:448; }","duration":"285.875322ms","start":"2026-02-04T01:47:50.489178Z","end":"2026-02-04T01:47:50.775054Z","steps":["trace[605828134] 'read index received' (duration: 15.678515ms)","trace[605828134] 'applied index is now lower than readState.Index' (duration: 270.196277ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:50.775291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.105384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:47:50.775309Z","caller":"traceutil/trace.go:171","msg":"trace[712447266] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:439; }","duration":"286.161574ms","start":"2026-02-04T01:47:50.48914Z","end":"2026-02-04T01:47:50.775302Z","steps":["trace[712447266] 'agreement among raft nodes before linearized reading' (duration: 286.125154ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:50.775673Z","caller":"traceutil/trace.go:171","msg":"trace[1309438701] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"286.496557ms","start":"2026-02-04T01:47:50.489169Z","end":"2026-02-04T01:47:50.775666Z","steps":["trace[1309438701] 'process raft request' (duration: 285.816622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:50.952146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.424567ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:47:50.952268Z","caller":"traceutil/trace.go:171","msg":"trace[1921335116] transaction","detail":"{read_only:false; number_of_response:1; response_revision:441; }","duration":"175.326889ms","start":"2026-02-04T01:47:50.776932Z","end":"2026-02-04T01:47:50.952259Z","steps":["trace[1921335116] 'process raft request' (duration: 175.309399ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:50.952459Z","caller":"traceutil/trace.go:171","msg":"trace[468582387] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"462.47043ms","start":"2026-02-04T01:47:50.489983Z","end":"2026-02-04T01:47:50.952454Z","steps":["trace[468582387] 'process raft request' (duration: 285.676241ms)","trace[468582387] 'compare' (duration: 176.361326ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:50.952502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:50.489975Z","time spent":"462.510991ms","remote":"127.0.0.1:41262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3773,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:47:50.952565Z","caller":"traceutil/trace.go:171","msg":"trace[1948115749] transaction","detail":"{read_only:false; number_of_response:1; response_revision:441; }","duration":"460.898649ms","start":"2026-02-04T01:47:50.491662Z","end":"2026-02-04T01:47:50.952561Z","steps":["trace[1948115749] 'process raft request' (duration: 460.541597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:50.952587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:50.491653Z","time spent":"460.921569ms","remote":"127.0.0.1:41262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":56,"response count":0,"response size":3823,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:47:50.952644Z","caller":"traceutil/trace.go:171","msg":"trace[1547895435] linearizableReadLoop","detail":"{readStateIndex:452; appliedIndex:449; }","duration":"177.556726ms","start":"2026-02-04T01:47:50.775083Z","end":"2026-02-04T01:47:50.952639Z","steps":["trace[1547895435] 'read index received' (duration: 338.663µs)","trace[1547895435] 'applied index is now lower than readState.Index' (duration: 177.217593ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:50.952674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.149598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-04T01:47:50.952685Z","caller":"traceutil/trace.go:171","msg":"trace[1581526718] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:441; }","duration":"379.194728ms","start":"2026-02-04T01:47:50.573486Z","end":"2026-02-04T01:47:50.952681Z","steps":["trace[1581526718] 'agreement among raft nodes before linearized reading' (duration: 379.169148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:50.9527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:50.573468Z","time spent":"379.228978ms","remote":"127.0.0.1:41052","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":"warn","ts":"2026-02-04T01:47:52.461081Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.455334261s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-04T01:47:52.58987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.81134ms","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-02-04T01:47:52.590007Z","caller":"traceutil/trace.go:171","msg":"trace[1160660145] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:442; }","duration":"100.954531ms","start":"2026-02-04T01:47:52.489019Z","end":"2026-02-04T01:47:52.589974Z","steps":["trace[1160660145] 'range keys from in-memory index tree' (duration: 100.792469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:52.590611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.209095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:47:52.59081Z","caller":"traceutil/trace.go:171","msg":"trace[1696722586] linearizableReadLoop","detail":"{readStateIndex:456; appliedIndex:454; }","duration":"1.626483764s","start":"2026-02-04T01:47:50.964315Z","end":"2026-02-04T01:47:52.590799Z","steps":["trace[1696722586] 'read index received' (duration: 41.493685ms)","trace[1696722586] 'applied index is now lower than readState.Index' (duration: 1.584989079s)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:52.590873Z","caller":"traceutil/trace.go:171","msg":"trace[846691602] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"1.629181894s","start":"2026-02-04T01:47:50.961681Z","end":"2026-02-04T01:47:52.590863Z","steps":["trace[846691602] 'process raft request' (duration: 1.499674287s)","trace[846691602] 'compare' (duration: 129.118984ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:52.590919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:50.961667Z","time spent":"1.629228064s","remote":"127.0.0.1:40878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:47:52.591196Z","caller":"traceutil/trace.go:171","msg":"trace[1409235888] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"1.627634102s","start":"2026-02-04T01:47:50.963553Z","end":"2026-02-04T01:47:52.591187Z","steps":["trace[1409235888] 'process raft request' (duration: 1.627111749s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:52.591253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:50.963546Z","time spent":"1.627673553s","remote":"127.0.0.1:41228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4135,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:47:52.59137Z","caller":"traceutil/trace.go:171","msg":"trace[734894002] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"1.626493194s","start":"2026-02-04T01:47:50.964869Z","end":"2026-02-04T01:47:52.591362Z","steps":["trace[734894002] 'process raft request' (duration: 1.625862869s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:52.591417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:50.964863Z","time spent":"1.626531334s","remote":"127.0.0.1:40878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":721,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:47:52.591602Z","caller":"traceutil/trace.go:171","msg":"trace[374841723] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"1.626410213s","start":"2026-02-04T01:47:50.965181Z","end":"2026-02-04T01:47:52.591591Z","steps":["trace[374841723] 'process raft request' (duration: 1.625580827s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:52.59165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:50.965175Z","time spent":"1.626448774s","remote":"127.0.0.1:41052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:47:52.591777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.627456041s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" ","response":"range_response_count:1 size:3788"} {"level":"info","ts":"2026-02-04T01:47:52.591819Z","caller":"traceutil/trace.go:171","msg":"trace[776332078] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-77cccfdc44; range_end:; response_count:1; response_revision:446; }","duration":"1.627515132s","start":"2026-02-04T01:47:50.964295Z","end":"2026-02-04T01:47:52.591811Z","steps":["trace[776332078] 'agreement among raft nodes before linearized reading' (duration: 1.627447011s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:52.591845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:50.964288Z","time spent":"1.627551652s","remote":"127.0.0.1:41262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3812,"request content":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" "} {"level":"warn","ts":"2026-02-04T01:47:53.321907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.769673ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:47:53.322082Z","caller":"traceutil/trace.go:171","msg":"trace[644749454] linearizableReadLoop","detail":"{readStateIndex:461; appliedIndex:458; }","duration":"731.236976ms","start":"2026-02-04T01:47:52.590833Z","end":"2026-02-04T01:47:53.32207Z","steps":["trace[644749454] 'read index received' (duration: 232.233091ms)","trace[644749454] 'applied index is now lower than readState.Index' (duration: 499.003255ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:53.322146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.290218736s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-04T01:47:53.322167Z","caller":"traceutil/trace.go:171","msg":"trace[1368385631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:449; }","duration":"2.290276826s","start":"2026-02-04T01:47:51.031884Z","end":"2026-02-04T01:47:53.322161Z","steps":["trace[1368385631] 'agreement among raft nodes before linearized reading' (duration: 2.290222855s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:53.322202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:51.031869Z","time spent":"2.290327126s","remote":"127.0.0.1:41052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-04T01:47:53.322384Z","caller":"traceutil/trace.go:171","msg":"trace[409002218] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"2.073609295s","start":"2026-02-04T01:47:51.248766Z","end":"2026-02-04T01:47:53.322376Z","steps":["trace[409002218] 'process raft request' (duration: 2.073273643s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:53.322432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:51.248747Z","time spent":"2.073658535s","remote":"127.0.0.1:41052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:47:53.322532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045818593s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:47:53.322564Z","caller":"traceutil/trace.go:171","msg":"trace[1656516193] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:449; }","duration":"1.045872465s","start":"2026-02-04T01:47:52.276686Z","end":"2026-02-04T01:47:53.322558Z","steps":["trace[1656516193] 'agreement among raft nodes before linearized reading' (duration: 1.045828504s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:53.322585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:52.276663Z","time spent":"1.045918465s","remote":"127.0.0.1:40818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:47:53.322685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.391680209s","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-02-04T01:47:53.322708Z","caller":"traceutil/trace.go:171","msg":"trace[287152411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:449; }","duration":"1.39170252s","start":"2026-02-04T01:47:51.931Z","end":"2026-02-04T01:47:53.322703Z","steps":["trace[287152411] 'agreement among raft nodes before linearized reading' (duration: 1.391657209s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:53.322724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:51.930983Z","time spent":"1.39173765s","remote":"127.0.0.1:41052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T01:47:53.322812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.208741071s","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-02-04T01:47:53.322844Z","caller":"traceutil/trace.go:171","msg":"trace[82504907] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:449; }","duration":"2.208798272s","start":"2026-02-04T01:47:51.114041Z","end":"2026-02-04T01:47:53.322839Z","steps":["trace[82504907] 'agreement among raft nodes before linearized reading' (duration: 2.208750331s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:53.322861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:51.114026Z","time spent":"2.208831242s","remote":"127.0.0.1:41052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-04T01:47:53.323302Z","caller":"traceutil/trace.go:171","msg":"trace[1124716675] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"2.312288807s","start":"2026-02-04T01:47:51.011004Z","end":"2026-02-04T01:47:53.323293Z","steps":["trace[1124716675] 'process raft request' (duration: 1.812076803s)","trace[1124716675] 'compare' (duration: 498.674113ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:53.323342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:51.010986Z","time spent":"2.312339757s","remote":"127.0.0.1:40878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:47:53.323534Z","caller":"traceutil/trace.go:171","msg":"trace[105670961] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"2.311024127s","start":"2026-02-04T01:47:51.012504Z","end":"2026-02-04T01:47:53.323528Z","steps":["trace[105670961] 'process raft request' (duration: 2.309488036s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:53.323588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:51.012494Z","time spent":"2.311070938s","remote":"127.0.0.1:41262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:47:53.345511Z","caller":"traceutil/trace.go:171","msg":"trace[1482193897] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"746.026313ms","start":"2026-02-04T01:47:52.599471Z","end":"2026-02-04T01:47:53.345497Z","steps":["trace[1482193897] 'process raft request' (duration: 745.993542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:53.345615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:52.599466Z","time spent":"746.090493ms","remote":"127.0.0.1:41228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4135,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:47:53.34584Z","caller":"traceutil/trace.go:171","msg":"trace[1626911608] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"746.496896ms","start":"2026-02-04T01:47:52.599335Z","end":"2026-02-04T01:47:53.345832Z","steps":["trace[1626911608] 'process raft request' (duration: 746.042843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:53.345888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:52.599312Z","time spent":"746.556426ms","remote":"127.0.0.1:41262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3818,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:47:53.896612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.293339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:47:53.896815Z","caller":"traceutil/trace.go:171","msg":"trace[2130624992] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"562.833743ms","start":"2026-02-04T01:47:53.333969Z","end":"2026-02-04T01:47:53.896803Z","steps":["trace[2130624992] 'process raft request' (duration: 562.783972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:53.896851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:53.333961Z","time spent":"562.874713ms","remote":"127.0.0.1:41262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:47:53.897006Z","caller":"traceutil/trace.go:171","msg":"trace[1060433105] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"564.322844ms","start":"2026-02-04T01:47:53.332663Z","end":"2026-02-04T01:47:53.896986Z","steps":["trace[1060433105] 'process raft request' (duration: 564.072072ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:53.897108Z","caller":"traceutil/trace.go:171","msg":"trace[1557710000] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"568.116521ms","start":"2026-02-04T01:47:53.328985Z","end":"2026-02-04T01:47:53.897101Z","steps":["trace[1557710000] 'process raft request' (duration: 200.281418ms)","trace[1557710000] 'compare' (duration: 367.212878ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:53.897106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:53.332655Z","time spent":"564.393374ms","remote":"127.0.0.1:41052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:47:53.897142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:53.328962Z","time spent":"568.164472ms","remote":"127.0.0.1:41052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:47:53.897189Z","caller":"traceutil/trace.go:171","msg":"trace[787590425] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"567.395646ms","start":"2026-02-04T01:47:53.32979Z","end":"2026-02-04T01:47:53.897186Z","steps":["trace[787590425] 'process raft request' (duration: 566.895523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:53.897212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:53.329782Z","time spent":"567.417296ms","remote":"127.0.0.1:41052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:47:53.935542Z","caller":"traceutil/trace.go:171","msg":"trace[1065024006] linearizableReadLoop","detail":"{readStateIndex:469; appliedIndex:463; }","duration":"576.601482ms","start":"2026-02-04T01:47:53.358926Z","end":"2026-02-04T01:47:53.935528Z","steps":["trace[1065024006] 'read index received' (duration: 170.351313ms)","trace[1065024006] 'applied index is now lower than readState.Index' (duration: 406.249569ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:53.935556Z","caller":"traceutil/trace.go:171","msg":"trace[1166399233] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"585.401105ms","start":"2026-02-04T01:47:53.350144Z","end":"2026-02-04T01:47:53.935545Z","steps":["trace[1166399233] 'process raft request' (duration: 585.268544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:53.935626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:53.350133Z","time spent":"585.456905ms","remote":"127.0.0.1:40968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3541,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:47:53.935649Z","caller":"traceutil/trace.go:171","msg":"trace[430208354] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"582.454284ms","start":"2026-02-04T01:47:53.353189Z","end":"2026-02-04T01:47:53.935643Z","steps":["trace[430208354] 'process raft request' (duration: 582.300573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:53.935692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:53.353175Z","time spent":"582.489625ms","remote":"127.0.0.1:41228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:47:53.9358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.868054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:47:53.935824Z","caller":"traceutil/trace.go:171","msg":"trace[706634737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:457; }","duration":"576.913084ms","start":"2026-02-04T01:47:53.358903Z","end":"2026-02-04T01:47:53.935816Z","steps":["trace[706634737] 'agreement among raft nodes before linearized reading' (duration: 576.858104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:53.935843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:53.358895Z","time spent":"576.943844ms","remote":"127.0.0.1:40818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T01:47:57.78223Z","caller":"traceutil/trace.go:171","msg":"trace[877323468] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"156.890516ms","start":"2026-02-04T01:47:57.625323Z","end":"2026-02-04T01:47:57.782214Z","steps":["trace[877323468] 'process raft request' (duration: 156.783385ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:00.354408Z","caller":"traceutil/trace.go:171","msg":"trace[588504968] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"279.483675ms","start":"2026-02-04T01:48:00.074906Z","end":"2026-02-04T01:48:00.35439Z","steps":["trace[588504968] 'process raft request' (duration: 267.968226ms)","trace[588504968] 'compare' (duration: 11.318277ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:48:00.400022Z","caller":"traceutil/trace.go:171","msg":"trace[1392040595] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:489; }","duration":"135.182107ms","start":"2026-02-04T01:48:00.264823Z","end":"2026-02-04T01:48:00.400005Z","steps":["trace[1392040595] 'read index received' (duration: 77.981094ms)","trace[1392040595] 'applied index is now lower than readState.Index' (duration: 57.200253ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:00.400137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.303017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:48:00.40016Z","caller":"traceutil/trace.go:171","msg":"trace[1497859538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:478; }","duration":"135.369558ms","start":"2026-02-04T01:48:00.264782Z","end":"2026-02-04T01:48:00.400152Z","steps":["trace[1497859538] 'agreement among raft nodes before linearized reading' (duration: 135.288537ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:00.400262Z","caller":"traceutil/trace.go:171","msg":"trace[711709897] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"324.663014ms","start":"2026-02-04T01:48:00.07558Z","end":"2026-02-04T01:48:00.400243Z","steps":["trace[711709897] 'process raft request' (duration: 324.332232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:00.400359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:00.07556Z","time spent":"324.745126ms","remote":"127.0.0.1:41052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:48:03.267142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000122568s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-04T01:48:03.267449Z","caller":"traceutil/trace.go:171","msg":"trace[1698745291] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00047745s","start":"2026-02-04T01:48:01.266948Z","end":"2026-02-04T01:48:03.267425Z","steps":["trace[1698745291] 'agreement among raft nodes before linearized reading' (duration: 2.000155778s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:03.267695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:01.266925Z","time spent":"2.000752642s","remote":"127.0.0.1:40818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/04 01:48:03 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-04T01:48:03.784346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.220502693s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-02-04T01:48:03.785095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.29577906s","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-02-04T01:48:03.786046Z","caller":"traceutil/trace.go:171","msg":"trace[1254135638] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:478; }","duration":"1.296716556s","start":"2026-02-04T01:48:02.489284Z","end":"2026-02-04T01:48:03.786001Z","steps":["trace[1254135638] 'range keys from in-memory index tree' (duration: 1.29576296s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:03.785298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:03.252243Z","time spent":"533.049787ms","remote":"127.0.0.1:44596","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-04T01:48:03.786893Z","caller":"traceutil/trace.go:171","msg":"trace[510495973] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:491; }","duration":"3.201721884s","start":"2026-02-04T01:48:00.585157Z","end":"2026-02-04T01:48:03.786879Z","steps":["trace[510495973] 'read index received' (duration: 26.3µs)","trace[510495973] 'applied index is now lower than readState.Index' (duration: 3.201693904s)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:03.787132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.201962736s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12665"} {"level":"info","ts":"2026-02-04T01:48:03.787172Z","caller":"traceutil/trace.go:171","msg":"trace[457168046] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:478; }","duration":"3.202032406s","start":"2026-02-04T01:48:00.585129Z","end":"2026-02-04T01:48:03.787161Z","steps":["trace[457168046] 'agreement among raft nodes before linearized reading' (duration: 3.201856015s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:03.787376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:00.585116Z","time spent":"3.202196017s","remote":"127.0.0.1:40894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":12689,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-02-04T01:48:03.884688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.199173666s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-04T01:48:03.884704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.671349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:48:03.884746Z","caller":"traceutil/trace.go:171","msg":"trace[1530859743] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:479; }","duration":"3.199274507s","start":"2026-02-04T01:48:00.685458Z","end":"2026-02-04T01:48:03.884733Z","steps":["trace[1530859743] 'agreement among raft nodes before linearized reading' (duration: 3.199133836s)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:03.884765Z","caller":"traceutil/trace.go:171","msg":"trace[221336867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:479; }","duration":"616.83801ms","start":"2026-02-04T01:48:03.267909Z","end":"2026-02-04T01:48:03.884747Z","steps":["trace[221336867] 'agreement among raft nodes before linearized reading' (duration: 616.727369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:03.884776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:00.685442Z","time spent":"3.199327457s","remote":"127.0.0.1:41052","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":"warn","ts":"2026-02-04T01:48:03.884793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:03.267804Z","time spent":"616.984031ms","remote":"127.0.0.1:40808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T01:48:03.885015Z","caller":"traceutil/trace.go:171","msg":"trace[763374590] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"481.016496ms","start":"2026-02-04T01:48:03.40399Z","end":"2026-02-04T01:48:03.885007Z","steps":["trace[763374590] 'process raft request' (duration: 480.449623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:03.885065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:03.403963Z","time spent":"481.064567ms","remote":"127.0.0.1:41052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:48:03.885119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.480371725s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-04T01:48:03.885144Z","caller":"traceutil/trace.go:171","msg":"trace[1124182041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:479; }","duration":"1.480420555s","start":"2026-02-04T01:48:02.404715Z","end":"2026-02-04T01:48:03.885135Z","steps":["trace[1124182041] 'agreement among raft nodes before linearized reading' (duration: 1.480374785s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:03.885165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:02.404696Z","time spent":"1.480464626s","remote":"127.0.0.1:41052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T01:48:03.885251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.81053491s","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-02-04T01:48:03.885269Z","caller":"traceutil/trace.go:171","msg":"trace[1338259701] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:479; }","duration":"1.81058351s","start":"2026-02-04T01:48:02.074679Z","end":"2026-02-04T01:48:03.885262Z","steps":["trace[1338259701] 'agreement among raft nodes before linearized reading' (duration: 1.81053934s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:03.885282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:02.074613Z","time spent":"1.810666241s","remote":"127.0.0.1:41052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T01:48:03.944036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.585670016s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-04T01:48:03.944117Z","caller":"traceutil/trace.go:171","msg":"trace[790836738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:479; }","duration":"1.585807257s","start":"2026-02-04T01:48:02.35829Z","end":"2026-02-04T01:48:03.944097Z","steps":["trace[790836738] 'agreement among raft nodes before linearized reading' (duration: 1.527065362s)","trace[790836738] 'range keys from in-memory index tree' (duration: 58.586274ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:03.944162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:02.358265Z","time spent":"1.585888008s","remote":"127.0.0.1:41052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-04T01:48:04.011741Z","caller":"traceutil/trace.go:171","msg":"trace[403872530] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:493; }","duration":"118.482217ms","start":"2026-02-04T01:48:03.893237Z","end":"2026-02-04T01:48:04.011719Z","steps":["trace[403872530] 'read index received' (duration: 6.445553ms)","trace[403872530] 'applied index is now lower than readState.Index' (duration: 112.035834ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:48:04.012138Z","caller":"traceutil/trace.go:171","msg":"trace[895131937] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"119.723916ms","start":"2026-02-04T01:48:03.892401Z","end":"2026-02-04T01:48:04.012125Z","steps":["trace[895131937] 'process raft request' (duration: 119.122162ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:04.012256Z","caller":"traceutil/trace.go:171","msg":"trace[1074675004] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"119.681596ms","start":"2026-02-04T01:48:03.892561Z","end":"2026-02-04T01:48:04.012242Z","steps":["trace[1074675004] 'process raft request' (duration: 119.119932ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:04.012284Z","caller":"traceutil/trace.go:171","msg":"trace[1170773972] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"119.775726ms","start":"2026-02-04T01:48:03.892503Z","end":"2026-02-04T01:48:04.012278Z","steps":["trace[1170773972] 'process raft request' (duration: 119.136702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:04.01237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.113241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:48:04.014039Z","caller":"traceutil/trace.go:171","msg":"trace[1060485355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:483; }","duration":"120.662261ms","start":"2026-02-04T01:48:03.893204Z","end":"2026-02-04T01:48:04.013866Z","steps":["trace[1060485355] 'agreement among raft nodes before linearized reading' (duration: 119.121771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:04.030115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.576996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12665"} {"level":"info","ts":"2026-02-04T01:48:04.030167Z","caller":"traceutil/trace.go:171","msg":"trace[98259762] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:484; }","duration":"100.670356ms","start":"2026-02-04T01:48:03.929485Z","end":"2026-02-04T01:48:04.030155Z","steps":["trace[98259762] 'agreement among raft nodes before linearized reading' (duration: 100.542685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:05.126003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.522865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:48:05.126066Z","caller":"traceutil/trace.go:171","msg":"trace[804775411] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"189.494213ms","start":"2026-02-04T01:48:04.936561Z","end":"2026-02-04T01:48:05.126055Z","steps":["trace[804775411] 'process raft request' (duration: 66.874708ms)","trace[804775411] 'compare' (duration: 122.364354ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:48:05.127483Z","caller":"traceutil/trace.go:171","msg":"trace[885133638] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"190.731702ms","start":"2026-02-04T01:48:04.936716Z","end":"2026-02-04T01:48:05.127448Z","steps":["trace[885133638] 'process raft request' (duration: 190.100587ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:05.291323Z","caller":"traceutil/trace.go:171","msg":"trace[200060702] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"102.011569ms","start":"2026-02-04T01:48:05.189287Z","end":"2026-02-04T01:48:05.291298Z","steps":["trace[200060702] 'process raft request' (duration: 75.843985ms)","trace[200060702] 'compare' (duration: 25.874952ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:48:05.291319Z","caller":"traceutil/trace.go:171","msg":"trace[222835622] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:504; }","duration":"100.355068ms","start":"2026-02-04T01:48:05.190944Z","end":"2026-02-04T01:48:05.291299Z","steps":["trace[222835622] 'read index received' (duration: 74.191654ms)","trace[222835622] 'applied index is now lower than readState.Index' (duration: 26.162414ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:05.292583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.620146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-04T01:48:05.292638Z","caller":"traceutil/trace.go:171","msg":"trace[172022011] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:491; }","duration":"101.715087ms","start":"2026-02-04T01:48:05.190911Z","end":"2026-02-04T01:48:05.292626Z","steps":["trace[172022011] 'agreement among raft nodes before linearized reading' (duration: 100.454378ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:05.292998Z","caller":"traceutil/trace.go:171","msg":"trace[1839315713] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"102.652283ms","start":"2026-02-04T01:48:05.190333Z","end":"2026-02-04T01:48:05.292986Z","steps":["trace[1839315713] 'process raft request' (duration: 100.899791ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:05.402996Z","caller":"traceutil/trace.go:171","msg":"trace[1454351779] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"102.523942ms","start":"2026-02-04T01:48:05.300454Z","end":"2026-02-04T01:48:05.402978Z","steps":["trace[1454351779] 'process raft request' (duration: 102.493582ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:05.403279Z","caller":"traceutil/trace.go:171","msg":"trace[2144550263] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"103.157767ms","start":"2026-02-04T01:48:05.300067Z","end":"2026-02-04T01:48:05.403225Z","steps":["trace[2144550263] 'process raft request' (duration: 93.012849ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:05.403523Z","caller":"traceutil/trace.go:171","msg":"trace[1549853650] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:506; }","duration":"103.230777ms","start":"2026-02-04T01:48:05.300278Z","end":"2026-02-04T01:48:05.403509Z","steps":["trace[1549853650] 'read index received' (duration: 92.816007ms)","trace[1549853650] 'applied index is now lower than readState.Index' (duration: 10.41306ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:05.403738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.451778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-04T01:48:05.403782Z","caller":"traceutil/trace.go:171","msg":"trace[787633459] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:493; }","duration":"103.515388ms","start":"2026-02-04T01:48:05.300255Z","end":"2026-02-04T01:48:05.40377Z","steps":["trace[787633459] 'agreement among raft nodes before linearized reading' (duration: 103.304647ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:05.672978Z","caller":"traceutil/trace.go:171","msg":"trace[1988996002] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"133.843901ms","start":"2026-02-04T01:48:05.539117Z","end":"2026-02-04T01:48:05.672961Z","steps":["trace[1988996002] 'process raft request' (duration: 87.395431ms)","trace[1988996002] 'compare' (duration: 46.201368ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:48:05.844432Z","caller":"traceutil/trace.go:171","msg":"trace[909476991] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"155.678486ms","start":"2026-02-04T01:48:05.688728Z","end":"2026-02-04T01:48:05.844406Z","steps":["trace[909476991] 'process raft request' (duration: 135.22701ms)","trace[909476991] 'compare' (duration: 20.335935ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:48:05.938998Z","caller":"traceutil/trace.go:171","msg":"trace[275091533] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"192.136979ms","start":"2026-02-04T01:48:05.746841Z","end":"2026-02-04T01:48:05.938978Z","steps":["trace[275091533] 'process raft request' (duration: 191.964947ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:05.939263Z","caller":"traceutil/trace.go:171","msg":"trace[1586602819] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"248.815825ms","start":"2026-02-04T01:48:05.690396Z","end":"2026-02-04T01:48:05.939212Z","steps":["trace[1586602819] 'process raft request' (duration: 248.227971ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:05.939521Z","caller":"traceutil/trace.go:171","msg":"trace[1078937987] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"245.894746ms","start":"2026-02-04T01:48:05.693611Z","end":"2026-02-04T01:48:05.939506Z","steps":["trace[1078937987] 'process raft request' (duration: 245.067631ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:05.939735Z","caller":"traceutil/trace.go:171","msg":"trace[1134722710] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"250.652218ms","start":"2026-02-04T01:48:05.689069Z","end":"2026-02-04T01:48:05.939721Z","steps":["trace[1134722710] 'process raft request' (duration: 249.382789ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:06.15262Z","caller":"traceutil/trace.go:171","msg":"trace[1153016227] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"300.491344ms","start":"2026-02-04T01:48:05.852107Z","end":"2026-02-04T01:48:06.152598Z","steps":["trace[1153016227] 'process raft request' (duration: 300.408553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:06.152741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:05.852084Z","time spent":"300.600915ms","remote":"127.0.0.1:40968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3554,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:48:06.152874Z","caller":"traceutil/trace.go:171","msg":"trace[1930598949] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"177.533166ms","start":"2026-02-04T01:48:05.975324Z","end":"2026-02-04T01:48:06.152857Z","steps":["trace[1930598949] 'process raft request' (duration: 177.497586ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:06.15292Z","caller":"traceutil/trace.go:171","msg":"trace[715326933] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"301.015458ms","start":"2026-02-04T01:48:05.851893Z","end":"2026-02-04T01:48:06.152908Z","steps":["trace[715326933] 'process raft request' (duration: 252.293775ms)","trace[715326933] 'compare' (duration: 47.932217ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:06.152987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:05.851866Z","time spent":"301.078748ms","remote":"127.0.0.1:41228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4298,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:48:06.153051Z","caller":"traceutil/trace.go:171","msg":"trace[1217598607] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"300.913127ms","start":"2026-02-04T01:48:05.85213Z","end":"2026-02-04T01:48:06.153043Z","steps":["trace[1217598607] 'process raft request' (duration: 300.430763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:06.153112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:05.85212Z","time spent":"300.948867ms","remote":"127.0.0.1:40878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:48:06.1532Z","caller":"traceutil/trace.go:171","msg":"trace[163958415] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:519; }","duration":"199.441352ms","start":"2026-02-04T01:48:05.953752Z","end":"2026-02-04T01:48:06.153193Z","steps":["trace[163958415] 'read index received' (duration: 150.460887ms)","trace[163958415] 'applied index is now lower than readState.Index' (duration: 48.979685ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:48:06.153247Z","caller":"traceutil/trace.go:171","msg":"trace[1886364156] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"199.404212ms","start":"2026-02-04T01:48:05.953837Z","end":"2026-02-04T01:48:06.153241Z","steps":["trace[1886364156] 'process raft request' (duration: 198.930829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:06.153427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.585827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-04T01:48:06.153437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.679463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nxd9p\" ","response":"range_response_count:1 size:14921"} {"level":"info","ts":"2026-02-04T01:48:06.15345Z","caller":"traceutil/trace.go:171","msg":"trace[1692734056] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:509; }","duration":"135.636957ms","start":"2026-02-04T01:48:06.017806Z","end":"2026-02-04T01:48:06.153443Z","steps":["trace[1692734056] 'agreement among raft nodes before linearized reading' (duration: 135.588427ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:06.153475Z","caller":"traceutil/trace.go:171","msg":"trace[557122869] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nxd9p; range_end:; response_count:1; response_revision:509; }","duration":"199.738803ms","start":"2026-02-04T01:48:05.953724Z","end":"2026-02-04T01:48:06.153463Z","steps":["trace[557122869] 'agreement among raft nodes before linearized reading' (duration: 199.608483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:06.153857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.569585ms","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-02-04T01:48:06.153883Z","caller":"traceutil/trace.go:171","msg":"trace[1694810731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:509; }","duration":"118.633815ms","start":"2026-02-04T01:48:06.03524Z","end":"2026-02-04T01:48:06.153874Z","steps":["trace[1694810731] 'agreement among raft nodes before linearized reading' (duration: 118.546725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:06.15423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.797245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329936"} {"level":"warn","ts":"2026-02-04T01:48:06.154257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.97256ms","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-02-04T01:48:06.154269Z","caller":"traceutil/trace.go:171","msg":"trace[1437770280] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:509; }","duration":"133.850006ms","start":"2026-02-04T01:48:06.020406Z","end":"2026-02-04T01:48:06.154256Z","steps":["trace[1437770280] 'agreement among raft nodes before linearized reading' (duration: 133.433703ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:06.154284Z","caller":"traceutil/trace.go:171","msg":"trace[1953702155] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:509; }","duration":"136.00721ms","start":"2026-02-04T01:48:06.018269Z","end":"2026-02-04T01:48:06.154276Z","steps":["trace[1953702155] 'agreement among raft nodes before linearized reading' (duration: 135.958129ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:06.154382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.85183ms","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-02-04T01:48:06.1544Z","caller":"traceutil/trace.go:171","msg":"trace[1611858898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:509; }","duration":"135.8779ms","start":"2026-02-04T01:48:06.018517Z","end":"2026-02-04T01:48:06.154395Z","steps":["trace[1611858898] 'agreement among raft nodes before linearized reading' (duration: 135.844959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:14.806401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.40761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:48:14.806464Z","caller":"traceutil/trace.go:171","msg":"trace[1401405432] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:580; }","duration":"548.334283ms","start":"2026-02-04T01:48:14.258117Z","end":"2026-02-04T01:48:14.806452Z","steps":["trace[1401405432] 'read index received' (duration: 370.817852ms)","trace[1401405432] 'applied index is now lower than readState.Index' (duration: 177.515731ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:48:14.806588Z","caller":"traceutil/trace.go:171","msg":"trace[262202095] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"579.403731ms","start":"2026-02-04T01:48:14.227175Z","end":"2026-02-04T01:48:14.806579Z","steps":["trace[262202095] 'process raft request' (duration: 401.767619ms)","trace[262202095] 'compare' (duration: 177.174789ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:14.806625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:14.227165Z","time spent":"579.440841ms","remote":"127.0.0.1:41052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:48:14.806791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.685485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-04T01:48:14.806819Z","caller":"traceutil/trace.go:171","msg":"trace[129611860] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:565; }","duration":"548.751365ms","start":"2026-02-04T01:48:14.258061Z","end":"2026-02-04T01:48:14.806812Z","steps":["trace[129611860] 'agreement among raft nodes before linearized reading' (duration: 548.667715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:14.806836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:14.258041Z","time spent":"548.791065ms","remote":"127.0.0.1:41052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T01:48:14.806932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.018738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-04T01:48:14.806947Z","caller":"traceutil/trace.go:171","msg":"trace[1663737981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:565; }","duration":"535.055858ms","start":"2026-02-04T01:48:14.271886Z","end":"2026-02-04T01:48:14.806942Z","steps":["trace[1663737981] 'agreement among raft nodes before linearized reading' (duration: 535.012738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:14.806966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:14.271874Z","time spent":"535.088058ms","remote":"127.0.0.1:41052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T01:48:14.807109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.023976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-04T01:48:14.807122Z","caller":"traceutil/trace.go:171","msg":"trace[1773865685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:565; }","duration":"530.051396ms","start":"2026-02-04T01:48:14.277066Z","end":"2026-02-04T01:48:14.807118Z","steps":["trace[1773865685] 'agreement among raft nodes before linearized reading' (duration: 530.013206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:14.807134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:14.277059Z","time spent":"530.072766ms","remote":"127.0.0.1:41052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T01:48:15.52069Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811657020909749,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:48:16.021451Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811657020909749,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:48:16.52206Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811657020909749,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:48:16.637564Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.826291611s","expected-duration":"1s"} {"level":"info","ts":"2026-02-04T01:48:16.637781Z","caller":"traceutil/trace.go:171","msg":"trace[1852182103] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"1.826554343s","start":"2026-02-04T01:48:14.811214Z","end":"2026-02-04T01:48:16.637768Z","steps":["trace[1852182103] 'process raft request' (duration: 1.826462993s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:16.637872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:14.811199Z","time spent":"1.826624434s","remote":"127.0.0.1:41052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:48:16.642702Z","caller":"traceutil/trace.go:171","msg":"trace[1849293073] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:582; }","duration":"1.622487564s","start":"2026-02-04T01:48:15.020198Z","end":"2026-02-04T01:48:16.642686Z","steps":["trace[1849293073] 'read index received' (duration: 1.617883795s)","trace[1849293073] 'applied index is now lower than readState.Index' (duration: 4.602959ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:48:16.642722Z","caller":"traceutil/trace.go:171","msg":"trace[735958267] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"1.831356293s","start":"2026-02-04T01:48:14.811358Z","end":"2026-02-04T01:48:16.642714Z","steps":["trace[735958267] 'process raft request' (duration: 1.831212033s)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:16.642759Z","caller":"traceutil/trace.go:171","msg":"trace[660325865] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"1.830887091s","start":"2026-02-04T01:48:14.811864Z","end":"2026-02-04T01:48:16.642752Z","steps":["trace[660325865] 'process raft request' (duration: 1.83079602s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:16.642767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:14.811338Z","time spent":"1.831404984s","remote":"127.0.0.1:41052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:48:16.642842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:14.811846Z","time spent":"1.830931851s","remote":"127.0.0.1:41052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:48:16.642973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.565590873s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.204\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-04T01:48:16.643Z","caller":"traceutil/trace.go:171","msg":"trace[61895882] range","detail":"{range_begin:/registry/masterleases/162.253.55.204; range_end:; response_count:1; response_revision:568; }","duration":"1.565860585s","start":"2026-02-04T01:48:15.077133Z","end":"2026-02-04T01:48:16.642993Z","steps":["trace[61895882] 'agreement among raft nodes before linearized reading' (duration: 1.565819635s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:16.643013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:15.077117Z","time spent":"1.565893225s","remote":"127.0.0.1:40844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.204\" "} {"level":"warn","ts":"2026-02-04T01:48:16.643121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369735921s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:48:16.643149Z","caller":"traceutil/trace.go:171","msg":"trace[1578076534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"1.369794351s","start":"2026-02-04T01:48:15.273347Z","end":"2026-02-04T01:48:16.643141Z","steps":["trace[1578076534] 'agreement among raft nodes before linearized reading' (duration: 1.369754201s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:16.643167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:15.27331Z","time spent":"1.369854962s","remote":"127.0.0.1:40818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:48:16.643126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.622931807s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nxd9p\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-04T01:48:16.643206Z","caller":"traceutil/trace.go:171","msg":"trace[1277542654] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nxd9p; range_end:; response_count:1; response_revision:568; }","duration":"1.623048728s","start":"2026-02-04T01:48:15.020152Z","end":"2026-02-04T01:48:16.643201Z","steps":["trace[1277542654] 'agreement among raft nodes before linearized reading' (duration: 1.622923217s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:16.643222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:15.020138Z","time spent":"1.623079338s","remote":"127.0.0.1:40968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16680,"request content":"key:\"/registry/pods/kube-system/cilium-nxd9p\" "} {"level":"warn","ts":"2026-02-04T01:48:16.801932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.644138ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-04T01:48:16.802007Z","caller":"traceutil/trace.go:171","msg":"trace[70688009] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:584; }","duration":"151.767359ms","start":"2026-02-04T01:48:16.650227Z","end":"2026-02-04T01:48:16.801994Z","steps":["trace[70688009] 'read index received' (duration: 30.170201ms)","trace[70688009] 'applied index is now lower than readState.Index' (duration: 121.596108ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:16.802053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.817629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:48:16.802067Z","caller":"traceutil/trace.go:171","msg":"trace[902226298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"151.850129ms","start":"2026-02-04T01:48:16.650212Z","end":"2026-02-04T01:48:16.802062Z","steps":["trace[902226298] 'agreement among raft nodes before linearized reading' (duration: 151.812379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:17.310745Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811657020909759,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:48:17.811719Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811657020909759,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:48:18.012346Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.331992179s","expected-duration":"1s"} {"level":"info","ts":"2026-02-04T01:48:18.017552Z","caller":"traceutil/trace.go:171","msg":"trace[690829103] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"1.353198112s","start":"2026-02-04T01:48:16.66434Z","end":"2026-02-04T01:48:18.017538Z","steps":["trace[690829103] 'process raft request' (duration: 1.348537603s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:18.017628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:16.664291Z","time spent":"1.353289223s","remote":"127.0.0.1:40968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:48:18.772639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.829813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:48:18.772708Z","caller":"traceutil/trace.go:171","msg":"trace[2125640374] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:585; }","duration":"1.962954257s","start":"2026-02-04T01:48:16.809743Z","end":"2026-02-04T01:48:18.772698Z","steps":["trace[2125640374] 'read index received' (duration: 1.203144644s)","trace[2125640374] 'applied index is now lower than readState.Index' (duration: 759.808743ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:18.772772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.963025308s","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-02-04T01:48:18.772785Z","caller":"traceutil/trace.go:171","msg":"trace[2143366130] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:570; }","duration":"1.963060398s","start":"2026-02-04T01:48:16.80972Z","end":"2026-02-04T01:48:18.77278Z","steps":["trace[2143366130] 'agreement among raft nodes before linearized reading' (duration: 1.963017997s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:18.772803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:16.809712Z","time spent":"1.963086808s","remote":"127.0.0.1:41052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-04T01:48:18.772769Z","caller":"traceutil/trace.go:171","msg":"trace[1037968401] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"1.96975425s","start":"2026-02-04T01:48:16.802969Z","end":"2026-02-04T01:48:18.772723Z","steps":["trace[1037968401] 'process raft request' (duration: 1.501790926s)","trace[1037968401] 'compare' (duration: 467.748132ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:18.772924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:16.802954Z","time spent":"1.969887962s","remote":"127.0.0.1:40844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:48:18.794271Z","caller":"traceutil/trace.go:171","msg":"trace[2014476875] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"531.641726ms","start":"2026-02-04T01:48:18.262609Z","end":"2026-02-04T01:48:18.79425Z","steps":["trace[2014476875] 'process raft request' (duration: 531.561205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:18.794482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:18.262589Z","time spent":"531.720876ms","remote":"127.0.0.1:41052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:48:18.794921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.000367ms","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-02-04T01:48:18.794946Z","caller":"traceutil/trace.go:171","msg":"trace[292798230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:571; }","duration":"148.044197ms","start":"2026-02-04T01:48:18.646896Z","end":"2026-02-04T01:48:18.79494Z","steps":["trace[292798230] 'agreement among raft nodes before linearized reading' (duration: 147.971086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:18.796165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.8621ms","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-02-04T01:48:18.79624Z","caller":"traceutil/trace.go:171","msg":"trace[531195690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:571; }","duration":"149.959701ms","start":"2026-02-04T01:48:18.646275Z","end":"2026-02-04T01:48:18.796235Z","steps":["trace[531195690] 'agreement among raft nodes before linearized reading' (duration: 149.808029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:18.796423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.728569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-04T01:48:18.796887Z","caller":"traceutil/trace.go:171","msg":"trace[681917116] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:571; }","duration":"155.225856ms","start":"2026-02-04T01:48:18.641655Z","end":"2026-02-04T01:48:18.796881Z","steps":["trace[681917116] 'agreement among raft nodes before linearized reading' (duration: 154.73408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:18.797626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.939322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:48:18.797649Z","caller":"traceutil/trace.go:171","msg":"trace[863100637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"541.984952ms","start":"2026-02-04T01:48:18.255659Z","end":"2026-02-04T01:48:18.797644Z","steps":["trace[863100637] 'agreement among raft nodes before linearized reading' (duration: 541.955022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:18.798514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:18.255646Z","time spent":"542.860503ms","remote":"127.0.0.1:40818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:48:18.797054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.574571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:48:18.802439Z","caller":"traceutil/trace.go:171","msg":"trace[1438609261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:571; }","duration":"539.970528ms","start":"2026-02-04T01:48:18.262462Z","end":"2026-02-04T01:48:18.802433Z","steps":["trace[1438609261] 'agreement among raft nodes before linearized reading' (duration: 534.577462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:18.802531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:18.262453Z","time spent":"540.069939ms","remote":"127.0.0.1:40808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T01:48:20.158177Z","caller":"traceutil/trace.go:171","msg":"trace[155507173] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"116.352346ms","start":"2026-02-04T01:48:20.041807Z","end":"2026-02-04T01:48:20.15816Z","steps":["trace[155507173] 'process raft request' (duration: 49.020334ms)","trace[155507173] 'compare' (duration: 67.261307ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:21.003969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.502103ms","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-02-04T01:48:21.004014Z","caller":"traceutil/trace.go:171","msg":"trace[1088772606] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"203.600619ms","start":"2026-02-04T01:48:20.800403Z","end":"2026-02-04T01:48:21.004003Z","steps":["trace[1088772606] 'range keys from in-memory index tree' (duration: 203.29473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:21.004154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.758519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-04T01:48:21.004172Z","caller":"traceutil/trace.go:171","msg":"trace[2015057620] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"193.798262ms","start":"2026-02-04T01:48:20.810369Z","end":"2026-02-04T01:48:21.004168Z","steps":["trace[2015057620] 'range keys from in-memory index tree' (duration: 193.703756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:21.004277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.183227ms","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-02-04T01:48:21.004299Z","caller":"traceutil/trace.go:171","msg":"trace[273730417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:585; }","duration":"192.216939ms","start":"2026-02-04T01:48:20.812077Z","end":"2026-02-04T01:48:21.004294Z","steps":["trace[273730417] 'range keys from in-memory index tree' (duration: 192.138384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:21.004383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.359645ms","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-02-04T01:48:21.0044Z","caller":"traceutil/trace.go:171","msg":"trace[1758138603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:585; }","duration":"186.388256ms","start":"2026-02-04T01:48:20.818008Z","end":"2026-02-04T01:48:21.004396Z","steps":["trace[1758138603] 'range keys from in-memory index tree' (duration: 186.320972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:23.511381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.521002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:48:23.511431Z","caller":"traceutil/trace.go:171","msg":"trace[1403933935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"244.610127ms","start":"2026-02-04T01:48:23.266809Z","end":"2026-02-04T01:48:23.51142Z","steps":["trace[1403933935] 'range keys from in-memory index tree' (duration: 244.459579ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:23.511613Z","caller":"traceutil/trace.go:171","msg":"trace[610668454] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"168.106844ms","start":"2026-02-04T01:48:23.343501Z","end":"2026-02-04T01:48:23.511608Z","steps":["trace[610668454] 'process raft request' (duration: 165.512687ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:27.379286Z","caller":"traceutil/trace.go:171","msg":"trace[1052707771] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:705; }","duration":"153.313033ms","start":"2026-02-04T01:48:27.225957Z","end":"2026-02-04T01:48:27.37927Z","steps":["trace[1052707771] 'read index received' (duration: 77.757341ms)","trace[1052707771] 'applied index is now lower than readState.Index' (duration: 75.555182ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:27.379422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.453371ms","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-02-04T01:48:27.379443Z","caller":"traceutil/trace.go:171","msg":"trace[620285848] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:686; }","duration":"153.482552ms","start":"2026-02-04T01:48:27.225954Z","end":"2026-02-04T01:48:27.379436Z","steps":["trace[620285848] 'agreement among raft nodes before linearized reading' (duration: 153.372486ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:27.37959Z","caller":"traceutil/trace.go:171","msg":"trace[46410388] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"184.655872ms","start":"2026-02-04T01:48:27.194928Z","end":"2026-02-04T01:48:27.379584Z","steps":["trace[46410388] 'process raft request' (duration: 108.798924ms)","trace[46410388] 'compare' (duration: 75.297148ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:48:28.386393Z","caller":"traceutil/trace.go:171","msg":"trace[1224217469] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"111.038302ms","start":"2026-02-04T01:48:28.275339Z","end":"2026-02-04T01:48:28.386378Z","steps":["trace[1224217469] 'process raft request' (duration: 110.949727ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:28.561505Z","caller":"traceutil/trace.go:171","msg":"trace[1528248987] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"145.58012ms","start":"2026-02-04T01:48:28.415911Z","end":"2026-02-04T01:48:28.561491Z","steps":["trace[1528248987] 'process raft request' (duration: 145.490625ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:28.59678Z","caller":"traceutil/trace.go:171","msg":"trace[330240176] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"172.779077ms","start":"2026-02-04T01:48:28.423988Z","end":"2026-02-04T01:48:28.596768Z","steps":["trace[330240176] 'process raft request' (duration: 172.718374ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:39.529419Z","caller":"traceutil/trace.go:171","msg":"trace[996336094] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:799; }","duration":"276.846871ms","start":"2026-02-04T01:48:39.252528Z","end":"2026-02-04T01:48:39.529375Z","steps":["trace[996336094] 'read index received' (duration: 276.671453ms)","trace[996336094] 'applied index is now lower than readState.Index' (duration: 174.518µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:48:39.529697Z","caller":"traceutil/trace.go:171","msg":"trace[876668145] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"305.917035ms","start":"2026-02-04T01:48:39.223768Z","end":"2026-02-04T01:48:39.529685Z","steps":["trace[876668145] 'process raft request' (duration: 305.484425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:39.529788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:39.22375Z","time spent":"305.976388ms","remote":"127.0.0.1:41052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:48:39.530145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.557564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-04T01:48:39.530193Z","caller":"traceutil/trace.go:171","msg":"trace[1351995300] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:778; }","duration":"277.68823ms","start":"2026-02-04T01:48:39.252489Z","end":"2026-02-04T01:48:39.530178Z","steps":["trace[1351995300] 'agreement among raft nodes before linearized reading' (duration: 277.495401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:39.530429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.862619ms","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-02-04T01:48:39.530462Z","caller":"traceutil/trace.go:171","msg":"trace[1359091987] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:778; }","duration":"271.948453ms","start":"2026-02-04T01:48:39.258505Z","end":"2026-02-04T01:48:39.530453Z","steps":["trace[1359091987] 'agreement among raft nodes before linearized reading' (duration: 271.859689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:39.530993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.271983ms","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-02-04T01:48:39.531036Z","caller":"traceutil/trace.go:171","msg":"trace[799897188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:778; }","duration":"203.363207ms","start":"2026-02-04T01:48:39.327661Z","end":"2026-02-04T01:48:39.531024Z","steps":["trace[799897188] 'agreement among raft nodes before linearized reading' (duration: 203.2281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:39.531244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.81274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:48:39.531284Z","caller":"traceutil/trace.go:171","msg":"trace[987745339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:778; }","duration":"267.901664ms","start":"2026-02-04T01:48:39.26337Z","end":"2026-02-04T01:48:39.531272Z","steps":["trace[987745339] 'agreement among raft nodes before linearized reading' (duration: 267.821871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:41.835204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.604534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1000"} {"level":"info","ts":"2026-02-04T01:48:41.835799Z","caller":"traceutil/trace.go:171","msg":"trace[743162014] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:792; }","duration":"122.203171ms","start":"2026-02-04T01:48:41.713579Z","end":"2026-02-04T01:48:41.835782Z","steps":["trace[743162014] 'range keys from in-memory index tree' (duration: 121.515571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:41.835223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.530226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:48:41.836086Z","caller":"traceutil/trace.go:171","msg":"trace[1752434202] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:792; }","duration":"121.401445ms","start":"2026-02-04T01:48:41.714666Z","end":"2026-02-04T01:48:41.836067Z","steps":["trace[1752434202] 'range keys from in-memory index tree' (duration: 120.281884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:41.835593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.716074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:48:41.837178Z","caller":"traceutil/trace.go:171","msg":"trace[1155814168] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:792; }","duration":"122.313817ms","start":"2026-02-04T01:48:41.714846Z","end":"2026-02-04T01:48:41.83716Z","steps":["trace[1155814168] 'count revisions from in-memory index tree' (duration: 120.63987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:41.835613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.800958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:48:41.839504Z","caller":"traceutil/trace.go:171","msg":"trace[1119872838] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:792; }","duration":"124.740117ms","start":"2026-02-04T01:48:41.714747Z","end":"2026-02-04T01:48:41.839487Z","steps":["trace[1119872838] 'range keys from in-memory index tree' (duration: 120.738895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:10.757389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.195215ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-04T01:49:10.75752Z","caller":"traceutil/trace.go:171","msg":"trace[1209956367] linearizableReadLoop","detail":"{readStateIndex:900; appliedIndex:899; }","duration":"146.895614ms","start":"2026-02-04T01:49:10.610606Z","end":"2026-02-04T01:49:10.757501Z","steps":["trace[1209956367] 'read index received' (duration: 29.191µs)","trace[1209956367] 'applied index is now lower than readState.Index' (duration: 146.864343ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:49:10.757654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.032039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:49:10.757682Z","caller":"traceutil/trace.go:171","msg":"trace[749513092] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:871; }","duration":"147.111121ms","start":"2026-02-04T01:49:10.610558Z","end":"2026-02-04T01:49:10.757669Z","steps":["trace[749513092] 'agreement among raft nodes before linearized reading' (duration: 147.021188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:33.374702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.11977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:49:33.374797Z","caller":"traceutil/trace.go:171","msg":"trace[900258615] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:948; }","duration":"107.266463ms","start":"2026-02-04T01:49:33.267509Z","end":"2026-02-04T01:49:33.374775Z","steps":["trace[900258615] 'range keys from in-memory index tree' (duration: 106.981456ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:36.067922Z","caller":"traceutil/trace.go:171","msg":"trace[1040765752] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"114.093206ms","start":"2026-02-04T01:49:35.953806Z","end":"2026-02-04T01:49:36.067899Z","steps":["trace[1040765752] 'process raft request' (duration: 113.971413ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:36.093117Z","caller":"traceutil/trace.go:171","msg":"trace[719350927] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"135.515869ms","start":"2026-02-04T01:49:35.957575Z","end":"2026-02-04T01:49:36.093091Z","steps":["trace[719350927] 'process raft request' (duration: 135.402346ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:38.243655Z","caller":"traceutil/trace.go:171","msg":"trace[549662790] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"130.826339ms","start":"2026-02-04T01:49:38.112806Z","end":"2026-02-04T01:49:38.243632Z","steps":["trace[549662790] 'process raft request' (duration: 122.702944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:38.495837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.443473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:49:38.496341Z","caller":"traceutil/trace.go:171","msg":"trace[162320088] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"187.362063ms","start":"2026-02-04T01:49:38.308953Z","end":"2026-02-04T01:49:38.496315Z","steps":["trace[162320088] 'process raft request' (duration: 46.361196ms)","trace[162320088] 'compare' (duration: 140.32326ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:49:42.392658Z","caller":"traceutil/trace.go:171","msg":"trace[1108706050] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1027; }","duration":"128.710587ms","start":"2026-02-04T01:49:42.263932Z","end":"2026-02-04T01:49:42.392643Z","steps":["trace[1108706050] 'read index received' (duration: 128.43635ms)","trace[1108706050] 'applied index is now lower than readState.Index' (duration: 273.307µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:49:42.392775Z","caller":"traceutil/trace.go:171","msg":"trace[430945452] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"222.804995ms","start":"2026-02-04T01:49:42.169962Z","end":"2026-02-04T01:49:42.392767Z","steps":["trace[430945452] 'process raft request' (duration: 222.561949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:42.393027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.094897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:49:42.393059Z","caller":"traceutil/trace.go:171","msg":"trace[219674307] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:991; }","duration":"129.178969ms","start":"2026-02-04T01:49:42.263871Z","end":"2026-02-04T01:49:42.393049Z","steps":["trace[219674307] 'agreement among raft nodes before linearized reading' (duration: 129.117567ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:42.605544Z","caller":"traceutil/trace.go:171","msg":"trace[1117357254] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"208.447952ms","start":"2026-02-04T01:49:42.397078Z","end":"2026-02-04T01:49:42.605526Z","steps":["trace[1117357254] 'process raft request' (duration: 208.148295ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:42.609924Z","caller":"traceutil/trace.go:171","msg":"trace[1814644305] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"211.164919ms","start":"2026-02-04T01:49:42.398743Z","end":"2026-02-04T01:49:42.609908Z","steps":["trace[1814644305] 'process raft request' (duration: 210.848602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:48.503419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.142341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:49:48.503493Z","caller":"traceutil/trace.go:171","msg":"trace[1029812317] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1013; }","duration":"181.262004ms","start":"2026-02-04T01:49:48.322214Z","end":"2026-02-04T01:49:48.503476Z","steps":["trace[1029812317] 'range keys from in-memory index tree' (duration: 181.059828ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:48.75029Z","caller":"traceutil/trace.go:171","msg":"trace[159590851] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1051; }","duration":"125.611434ms","start":"2026-02-04T01:49:48.624657Z","end":"2026-02-04T01:49:48.750268Z","steps":["trace[159590851] 'read index received' (duration: 125.42534ms)","trace[159590851] 'applied index is now lower than readState.Index' (duration: 185.164µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:49:48.750651Z","caller":"traceutil/trace.go:171","msg":"trace[966443370] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"235.530581ms","start":"2026-02-04T01:49:48.515107Z","end":"2026-02-04T01:49:48.750638Z","steps":["trace[966443370] 'process raft request' (duration: 235.027469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:48.750789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.116496ms","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-02-04T01:49:48.754612Z","caller":"traceutil/trace.go:171","msg":"trace[1583737674] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1014; }","duration":"129.983215ms","start":"2026-02-04T01:49:48.624615Z","end":"2026-02-04T01:49:48.754598Z","steps":["trace[1583737674] 'agreement among raft nodes before linearized reading' (duration: 126.072244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:49.757525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.952005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:49:49.757712Z","caller":"traceutil/trace.go:171","msg":"trace[1942413479] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1052; }","duration":"491.667891ms","start":"2026-02-04T01:49:49.26603Z","end":"2026-02-04T01:49:49.757698Z","steps":["trace[1942413479] 'read index received' (duration: 243.0428ms)","trace[1942413479] 'applied index is now lower than readState.Index' (duration: 248.624061ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:49:49.757781Z","caller":"traceutil/trace.go:171","msg":"trace[1860826476] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"993.885901ms","start":"2026-02-04T01:49:48.763887Z","end":"2026-02-04T01:49:49.757773Z","steps":["trace[1860826476] 'process raft request' (duration: 58.628189ms)","trace[1860826476] 'compare' (duration: 934.499455ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:49:49.757828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:48.76387Z","time spent":"993.934772ms","remote":"127.0.0.1:41052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:49:49.757911Z","caller":"traceutil/trace.go:171","msg":"trace[1669328478] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"993.242616ms","start":"2026-02-04T01:49:48.76466Z","end":"2026-02-04T01:49:49.757903Z","steps":["trace[1669328478] 'process raft request' (duration: 992.939499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:49.757946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:48.76464Z","time spent":"993.287267ms","remote":"127.0.0.1:41052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:49:49.758008Z","caller":"traceutil/trace.go:171","msg":"trace[643807915] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"993.276747ms","start":"2026-02-04T01:49:48.764726Z","end":"2026-02-04T01:49:49.758002Z","steps":["trace[643807915] 'process raft request' (duration: 992.941819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:49.758039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:48.764722Z","time spent":"993.302137ms","remote":"127.0.0.1:41052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:49:49.758097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.08086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:49:49.758117Z","caller":"traceutil/trace.go:171","msg":"trace[663261945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1017; }","duration":"492.132871ms","start":"2026-02-04T01:49:49.265979Z","end":"2026-02-04T01:49:49.758112Z","steps":["trace[663261945] 'agreement among raft nodes before linearized reading' (duration: 492.10065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:49.758134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:49.265941Z","time spent":"492.189753ms","remote":"127.0.0.1:40818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:49:49.758259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.81752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T01:49:49.758285Z","caller":"traceutil/trace.go:171","msg":"trace[1881979665] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1017; }","duration":"389.842631ms","start":"2026-02-04T01:49:49.368434Z","end":"2026-02-04T01:49:49.758277Z","steps":["trace[1881979665] 'agreement among raft nodes before linearized reading' (duration: 389.80426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:49.758309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:49.368414Z","time spent":"389.890093ms","remote":"127.0.0.1:56140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:49:49.75845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.094298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:49:49.758477Z","caller":"traceutil/trace.go:171","msg":"trace[2104970789] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1017; }","duration":"166.152719ms","start":"2026-02-04T01:49:49.592318Z","end":"2026-02-04T01:49:49.758471Z","steps":["trace[2104970789] 'agreement among raft nodes before linearized reading' (duration: 166.114539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:49.996874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.345575ms","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-02-04T01:49:49.99695Z","caller":"traceutil/trace.go:171","msg":"trace[735713168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1017; }","duration":"119.473317ms","start":"2026-02-04T01:49:49.877461Z","end":"2026-02-04T01:49:49.996934Z","steps":["trace[735713168] 'range keys from in-memory index tree' (duration: 119.054467ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:50.104767Z","caller":"traceutil/trace.go:171","msg":"trace[1836415330] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"103.643113ms","start":"2026-02-04T01:49:50.001104Z","end":"2026-02-04T01:49:50.104747Z","steps":["trace[1836415330] 'process raft request' (duration: 103.50502ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:51.905885Z","caller":"traceutil/trace.go:171","msg":"trace[1493695296] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"135.797357ms","start":"2026-02-04T01:49:51.770072Z","end":"2026-02-04T01:49:51.905869Z","steps":["trace[1493695296] 'process raft request' (duration: 135.724976ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:50:16.966756Z","caller":"traceutil/trace.go:171","msg":"trace[151633241] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"123.254858ms","start":"2026-02-04T01:50:16.843489Z","end":"2026-02-04T01:50:16.966744Z","steps":["trace[151633241] 'process raft request' (duration: 123.035544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:50:17.331547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.459026ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:50:17.332291Z","caller":"traceutil/trace.go:171","msg":"trace[369871037] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"293.818266ms","start":"2026-02-04T01:50:17.038457Z","end":"2026-02-04T01:50:17.332275Z","steps":["trace[369871037] 'process raft request' (duration: 36.520564ms)","trace[369871037] 'compare' (duration: 256.358194ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:50:17.332236Z","caller":"traceutil/trace.go:171","msg":"trace[1504315918] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1141; }","duration":"244.197253ms","start":"2026-02-04T01:50:17.088012Z","end":"2026-02-04T01:50:17.33221Z","steps":["trace[1504315918] 'read index received' (duration: 39.491µs)","trace[1504315918] 'applied index is now lower than readState.Index' (duration: 244.155322ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:50:17.333114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.08562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-04T01:50:17.333181Z","caller":"traceutil/trace.go:171","msg":"trace[781443610] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:1098; }","duration":"245.196092ms","start":"2026-02-04T01:50:17.087969Z","end":"2026-02-04T01:50:17.333165Z","steps":["trace[781443610] 'agreement among raft nodes before linearized reading' (duration: 245.047069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:50:17.333336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.075129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/default\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-04T01:50:17.333428Z","caller":"traceutil/trace.go:171","msg":"trace[963997198] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/default; range_end:; response_count:1; response_revision:1098; }","duration":"236.369874ms","start":"2026-02-04T01:50:17.09704Z","end":"2026-02-04T01:50:17.33341Z","steps":["trace[963997198] 'agreement among raft nodes before linearized reading' (duration: 236.065398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:50:17.603379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.892769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:90 size:66403"} {"level":"info","ts":"2026-02-04T01:50:17.60345Z","caller":"traceutil/trace.go:171","msg":"trace[186462423] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:90; response_revision:1098; }","duration":"171.027461ms","start":"2026-02-04T01:50:17.432405Z","end":"2026-02-04T01:50:17.603432Z","steps":["trace[186462423] 'range keys from in-memory index tree' (duration: 170.320837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:50:17.603754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.207048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/default\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-04T01:50:17.603786Z","caller":"traceutil/trace.go:171","msg":"trace[537221671] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/default; range_end:; response_count:1; response_revision:1098; }","duration":"147.27959ms","start":"2026-02-04T01:50:17.456496Z","end":"2026-02-04T01:50:17.603775Z","steps":["trace[537221671] 'range keys from in-memory index tree' (duration: 147.146207ms)"],"step_count":1}