{"level":"warn","ts":"2026-07-15T14:27:22.577432Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T14:27:22.577515Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.100:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.100:2380","--initial-cluster=instance=https://199.19.213.100:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.100:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.100: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-07-15T14:27:22.577579Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T14:27:22.577587Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.100:2380"]} {"level":"info","ts":"2026-07-15T14:27:22.577606Z","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-07-15T14:27:22.578413Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.100:2379"]} {"level":"info","ts":"2026-07-15T14:27:22.578553Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.100:2380"],"listen-peer-urls":["https://199.19.213.100:2380"],"advertise-client-urls":["https://199.19.213.100:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.100:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.100: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-07-15T14:27:22.588477Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.857615ms"} {"level":"info","ts":"2026-07-15T14:27:22.59814Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"79e65b4197a2a1a","cluster-id":"bba89cbb2a6fc22a"} {"level":"info","ts":"2026-07-15T14:27:22.598229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a switched to configuration voters=()"} {"level":"info","ts":"2026-07-15T14:27:22.598258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became follower at term 0"} {"level":"info","ts":"2026-07-15T14:27:22.59828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 79e65b4197a2a1a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-15T14:27:22.598292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became follower at term 1"} {"level":"info","ts":"2026-07-15T14:27:22.598345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a switched to configuration voters=(548988028781734426)"} {"level":"warn","ts":"2026-07-15T14:27:22.607767Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-15T14:27:22.609083Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-15T14:27:22.610145Z","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-07-15T14:27:22.612349Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"79e65b4197a2a1a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-15T14:27:22.61343Z","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-07-15T14:27:22.614768Z","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-07-15T14:27:22.614852Z","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-07-15T14:27:22.613439Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"79e65b4197a2a1a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-15T14:27:22.617689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a switched to configuration voters=(548988028781734426)"} {"level":"info","ts":"2026-07-15T14:27:22.617775Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bba89cbb2a6fc22a","local-member-id":"79e65b4197a2a1a","added-peer-id":"79e65b4197a2a1a","added-peer-peer-urls":["https://199.19.213.100:2380"]} {"level":"info","ts":"2026-07-15T14:27:22.619218Z","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-07-15T14:27:22.619452Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.100:2380"} {"level":"info","ts":"2026-07-15T14:27:22.619481Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.100:2380"} {"level":"info","ts":"2026-07-15T14:27:22.619531Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"79e65b4197a2a1a","initial-advertise-peer-urls":["https://199.19.213.100:2380"],"listen-peer-urls":["https://199.19.213.100:2380"],"advertise-client-urls":["https://199.19.213.100:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.100:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-15T14:27:22.619571Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-15T14:27:23.598544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a is starting a new election at term 1"} {"level":"info","ts":"2026-07-15T14:27:23.598634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became pre-candidate at term 1"} {"level":"info","ts":"2026-07-15T14:27:23.59868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a received MsgPreVoteResp from 79e65b4197a2a1a at term 1"} {"level":"info","ts":"2026-07-15T14:27:23.598701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became candidate at term 2"} {"level":"info","ts":"2026-07-15T14:27:23.598731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a received MsgVoteResp from 79e65b4197a2a1a at term 2"} {"level":"info","ts":"2026-07-15T14:27:23.598754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became leader at term 2"} {"level":"info","ts":"2026-07-15T14:27:23.598773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 79e65b4197a2a1a elected leader 79e65b4197a2a1a at term 2"} {"level":"info","ts":"2026-07-15T14:27:23.600917Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T14:27:23.602065Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"79e65b4197a2a1a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.100:2379]}","request-path":"/0/members/79e65b4197a2a1a/attributes","cluster-id":"bba89cbb2a6fc22a","publish-timeout":"7s"} {"level":"info","ts":"2026-07-15T14:27:23.602215Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T14:27:23.602261Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T14:27:23.60239Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-15T14:27:23.602447Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-15T14:27:23.60312Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bba89cbb2a6fc22a","local-member-id":"79e65b4197a2a1a","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T14:27:23.603264Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T14:27:23.603308Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T14:27:23.60458Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-15T14:27:23.609873Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.100:2379"} {"level":"warn","ts":"2026-07-15T14:27:25.134261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.773795ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-15T14:27:25.13452Z","caller":"traceutil/trace.go:171","msg":"trace[1524181528] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"118.383326ms","start":"2026-07-15T14:27:25.016124Z","end":"2026-07-15T14:27:25.134508Z","steps":["trace[1524181528] 'process raft request' (duration: 118.354705ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:27:25.134582Z","caller":"traceutil/trace.go:171","msg":"trace[1953506649] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"146.337734ms","start":"2026-07-15T14:27:24.988178Z","end":"2026-07-15T14:27:25.134516Z","steps":["trace[1953506649] 'process raft request' (duration: 39.919718ms)","trace[1953506649] 'compare' (duration: 105.657212ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:27:25.134626Z","caller":"traceutil/trace.go:171","msg":"trace[1985884086] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"133.452264ms","start":"2026-07-15T14:27:25.001153Z","end":"2026-07-15T14:27:25.134605Z","steps":["trace[1985884086] 'process raft request' (duration: 133.293059ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:27:25.134577Z","caller":"traceutil/trace.go:171","msg":"trace[1165971130] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"146.342165ms","start":"2026-07-15T14:27:24.988173Z","end":"2026-07-15T14:27:25.134515Z","steps":["trace[1165971130] 'process raft request' (duration: 146.18772ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:27:25.134699Z","caller":"traceutil/trace.go:171","msg":"trace[1981914248] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"146.491089ms","start":"2026-07-15T14:27:24.988188Z","end":"2026-07-15T14:27:25.134679Z","steps":["trace[1981914248] 'process raft request' (duration: 146.20286ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:27:25.134747Z","caller":"traceutil/trace.go:171","msg":"trace[1372463957] linearizableReadLoop","detail":"{readStateIndex:27; appliedIndex:23; }","duration":"146.395526ms","start":"2026-07-15T14:27:24.988342Z","end":"2026-07-15T14:27:25.134737Z","steps":["trace[1372463957] 'read index received' (duration: 8.615174ms)","trace[1372463957] 'applied index is now lower than readState.Index' (duration: 137.778902ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:27:25.134811Z","caller":"traceutil/trace.go:171","msg":"trace[2001078489] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"145.492177ms","start":"2026-07-15T14:27:24.989308Z","end":"2026-07-15T14:27:25.1348Z","steps":["trace[2001078489] 'process raft request' (duration: 145.108165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:25.13491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.555391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-07-15T14:27:25.134955Z","caller":"traceutil/trace.go:171","msg":"trace[425327322] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:27; }","duration":"146.604012ms","start":"2026-07-15T14:27:24.988338Z","end":"2026-07-15T14:27:25.134942Z","steps":["trace[425327322] 'agreement among raft nodes before linearized reading' (duration: 146.477978ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:25.134967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.429291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-15T14:27:25.134963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.874399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-15T14:27:25.13501Z","caller":"traceutil/trace.go:171","msg":"trace[1965731274] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:27; }","duration":"132.484003ms","start":"2026-07-15T14:27:25.002513Z","end":"2026-07-15T14:27:25.134997Z","steps":["trace[1965731274] 'agreement among raft nodes before linearized reading' (duration: 132.39044ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:27:25.135039Z","caller":"traceutil/trace.go:171","msg":"trace[1908939859] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:27; }","duration":"145.979253ms","start":"2026-07-15T14:27:24.989042Z","end":"2026-07-15T14:27:25.135021Z","steps":["trace[1908939859] 'agreement among raft nodes before linearized reading' (duration: 145.863469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:25.135069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.072073ms","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-07-15T14:27:25.135114Z","caller":"traceutil/trace.go:171","msg":"trace[1539602646] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:27; }","duration":"134.126795ms","start":"2026-07-15T14:27:25.000973Z","end":"2026-07-15T14:27:25.1351Z","steps":["trace[1539602646] 'agreement among raft nodes before linearized reading' (duration: 134.066473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:25.135226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.682719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-15T14:27:25.135257Z","caller":"traceutil/trace.go:171","msg":"trace[206732534] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:27; }","duration":"132.734981ms","start":"2026-07-15T14:27:25.002513Z","end":"2026-07-15T14:27:25.135248Z","steps":["trace[206732534] 'agreement among raft nodes before linearized reading' (duration: 132.650308ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:25.135286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.13873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-15T14:27:25.135348Z","caller":"traceutil/trace.go:171","msg":"trace[1331897065] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:27; }","duration":"136.344466ms","start":"2026-07-15T14:27:24.998987Z","end":"2026-07-15T14:27:25.135331Z","steps":["trace[1331897065] 'agreement among raft nodes before linearized reading' (duration: 136.1516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:25.135295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.845026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.100\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-15T14:27:25.135432Z","caller":"traceutil/trace.go:171","msg":"trace[209693063] range","detail":"{range_begin:/registry/masterleases/199.19.213.100; range_end:; response_count:0; response_revision:27; }","duration":"133.994461ms","start":"2026-07-15T14:27:25.001427Z","end":"2026-07-15T14:27:25.135421Z","steps":["trace[209693063] 'agreement among raft nodes before linearized reading' (duration: 133.840316ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:27:47.048792Z","caller":"traceutil/trace.go:171","msg":"trace[1478744854] linearizableReadLoop","detail":"{readStateIndex:399; appliedIndex:398; }","duration":"108.757017ms","start":"2026-07-15T14:27:46.94001Z","end":"2026-07-15T14:27:47.048767Z","steps":["trace[1478744854] 'read index received' (duration: 108.583152ms)","trace[1478744854] 'applied index is now lower than readState.Index' (duration: 172.945µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:27:47.048839Z","caller":"traceutil/trace.go:171","msg":"trace[383012469] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"236.103572ms","start":"2026-07-15T14:27:46.812693Z","end":"2026-07-15T14:27:47.048796Z","steps":["trace[383012469] 'process raft request' (duration: 235.928277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:47.049066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.044566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-07-15T14:27:47.049111Z","caller":"traceutil/trace.go:171","msg":"trace[1345287948] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:387; }","duration":"109.151339ms","start":"2026-07-15T14:27:46.939947Z","end":"2026-07-15T14:27:47.049098Z","steps":["trace[1345287948] 'agreement among raft nodes before linearized reading' (duration: 108.992464ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:27:48.903872Z","caller":"traceutil/trace.go:171","msg":"trace[1359851811] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"102.548108ms","start":"2026-07-15T14:27:48.801306Z","end":"2026-07-15T14:27:48.903854Z","steps":["trace[1359851811] 'process raft request' (duration: 102.513647ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:27:48.903965Z","caller":"traceutil/trace.go:171","msg":"trace[579165018] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"102.977441ms","start":"2026-07-15T14:27:48.800918Z","end":"2026-07-15T14:27:48.903895Z","steps":["trace[579165018] 'process raft request' (duration: 20.319158ms)","trace[579165018] 'compare' (duration: 82.481247ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:27:49.105202Z","caller":"traceutil/trace.go:171","msg":"trace[400444585] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"125.4338ms","start":"2026-07-15T14:27:48.979736Z","end":"2026-07-15T14:27:49.105169Z","steps":["trace[400444585] 'process raft request' (duration: 51.752285ms)","trace[400444585] 'compare' (duration: 73.476519ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:27:49.105224Z","caller":"traceutil/trace.go:171","msg":"trace[164191416] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:425; }","duration":"125.238894ms","start":"2026-07-15T14:27:48.979964Z","end":"2026-07-15T14:27:49.105203Z","steps":["trace[164191416] 'read index received' (duration: 10.496493ms)","trace[164191416] 'applied index is now lower than readState.Index' (duration: 114.741611ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:27:49.105283Z","caller":"traceutil/trace.go:171","msg":"trace[1492694187] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"122.407583ms","start":"2026-07-15T14:27:48.982856Z","end":"2026-07-15T14:27:49.105264Z","steps":["trace[1492694187] 'process raft request' (duration: 122.30236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:49.105351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.374618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:27:49.105385Z","caller":"traceutil/trace.go:171","msg":"trace[420920275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:414; }","duration":"125.42369ms","start":"2026-07-15T14:27:48.979953Z","end":"2026-07-15T14:27:49.105377Z","steps":["trace[420920275] 'agreement among raft nodes before linearized reading' (duration: 125.358368ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:27:49.105512Z","caller":"traceutil/trace.go:171","msg":"trace[1464094172] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"124.658397ms","start":"2026-07-15T14:27:48.980842Z","end":"2026-07-15T14:27:49.105501Z","steps":["trace[1464094172] 'process raft request' (duration: 124.271416ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:27:49.110641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.001239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3819"} {"level":"info","ts":"2026-07-15T14:27:49.110707Z","caller":"traceutil/trace.go:171","msg":"trace[1417601603] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:414; }","duration":"114.110561ms","start":"2026-07-15T14:27:48.996584Z","end":"2026-07-15T14:27:49.110695Z","steps":["trace[1417601603] 'agreement among raft nodes before linearized reading' (duration: 109.148248ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:01.571497Z","caller":"traceutil/trace.go:171","msg":"trace[211446772] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"109.723117ms","start":"2026-07-15T14:28:01.461755Z","end":"2026-07-15T14:28:01.571479Z","steps":["trace[211446772] 'process raft request' (duration: 109.625924ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:02.251657Z","caller":"traceutil/trace.go:171","msg":"trace[964126491] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"165.315454ms","start":"2026-07-15T14:28:02.086322Z","end":"2026-07-15T14:28:02.251637Z","steps":["trace[964126491] 'process raft request' (duration: 165.274713ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:02.251652Z","caller":"traceutil/trace.go:171","msg":"trace[1173688851] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"193.239311ms","start":"2026-07-15T14:28:02.058371Z","end":"2026-07-15T14:28:02.25161Z","steps":["trace[1173688851] 'process raft request' (duration: 193.052306ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:02.251684Z","caller":"traceutil/trace.go:171","msg":"trace[1128246302] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"190.478295ms","start":"2026-07-15T14:28:02.061173Z","end":"2026-07-15T14:28:02.251651Z","steps":["trace[1128246302] 'process raft request' (duration: 190.314381ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:02.251867Z","caller":"traceutil/trace.go:171","msg":"trace[1512107022] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"193.322964ms","start":"2026-07-15T14:28:02.058534Z","end":"2026-07-15T14:28:02.251856Z","steps":["trace[1512107022] 'process raft request' (duration: 192.920973ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:02.25191Z","caller":"traceutil/trace.go:171","msg":"trace[707787448] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"204.772349ms","start":"2026-07-15T14:28:02.047128Z","end":"2026-07-15T14:28:02.251901Z","steps":["trace[707787448] 'process raft request' (duration: 197.304154ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:02.251954Z","caller":"traceutil/trace.go:171","msg":"trace[1429986772] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"183.063602ms","start":"2026-07-15T14:28:02.068862Z","end":"2026-07-15T14:28:02.251925Z","steps":["trace[1429986772] 'process raft request' (duration: 182.64947ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:02.251995Z","caller":"traceutil/trace.go:171","msg":"trace[1750230430] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"200.510612ms","start":"2026-07-15T14:28:02.05145Z","end":"2026-07-15T14:28:02.251961Z","steps":["trace[1750230430] 'process raft request' (duration: 199.863424ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:02.252248Z","caller":"traceutil/trace.go:171","msg":"trace[692473330] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"177.848279ms","start":"2026-07-15T14:28:02.074303Z","end":"2026-07-15T14:28:02.252152Z","steps":["trace[692473330] 'process raft request' (duration: 177.237102ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:02.348938Z","caller":"traceutil/trace.go:171","msg":"trace[1072781099] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"252.002096ms","start":"2026-07-15T14:28:02.096899Z","end":"2026-07-15T14:28:02.348901Z","steps":["trace[1072781099] 'process raft request' (duration: 251.799061ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:02.34919Z","caller":"traceutil/trace.go:171","msg":"trace[1730738534] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"247.642217ms","start":"2026-07-15T14:28:02.10152Z","end":"2026-07-15T14:28:02.349162Z","steps":["trace[1730738534] 'process raft request' (duration: 247.280207ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:02.349225Z","caller":"traceutil/trace.go:171","msg":"trace[492885707] transaction","detail":"{read_only:false; number_of_response:1; response_revision:500; }","duration":"247.41823ms","start":"2026-07-15T14:28:02.101781Z","end":"2026-07-15T14:28:02.3492Z","steps":["trace[492885707] 'process raft request' (duration: 247.115602ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:02.349222Z","caller":"traceutil/trace.go:171","msg":"trace[1730197357] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"247.681127ms","start":"2026-07-15T14:28:02.101521Z","end":"2026-07-15T14:28:02.349202Z","steps":["trace[1730197357] 'process raft request' (duration: 247.318057ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:02.349731Z","caller":"traceutil/trace.go:171","msg":"trace[1917396654] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"233.132208ms","start":"2026-07-15T14:28:02.116574Z","end":"2026-07-15T14:28:02.349706Z","steps":["trace[1917396654] 'process raft request' (duration: 232.364957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:02.689723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.715285ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-15T14:28:02.690604Z","caller":"traceutil/trace.go:171","msg":"trace[197719025] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"471.002666ms","start":"2026-07-15T14:28:02.219582Z","end":"2026-07-15T14:28:02.690584Z","steps":["trace[197719025] 'process raft request' (duration: 470.294516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:02.690708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:28:02.219557Z","time spent":"471.101909ms","remote":"127.0.0.1:49800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-15T14:28:02.690797Z","caller":"traceutil/trace.go:171","msg":"trace[1980678597] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"433.712672ms","start":"2026-07-15T14:28:02.257052Z","end":"2026-07-15T14:28:02.690765Z","steps":["trace[1980678597] 'process raft request' (duration: 433.434774ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:02.690861Z","caller":"traceutil/trace.go:171","msg":"trace[1271233556] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"431.359467ms","start":"2026-07-15T14:28:02.259479Z","end":"2026-07-15T14:28:02.690838Z","steps":["trace[1271233556] 'process raft request' (duration: 431.218313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:02.690962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:28:02.259458Z","time spent":"431.453839ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T14:28:02.69099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:28:02.257037Z","time spent":"433.840725ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-15T14:28:02.691016Z","caller":"traceutil/trace.go:171","msg":"trace[2052332792] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"426.741259ms","start":"2026-07-15T14:28:02.264263Z","end":"2026-07-15T14:28:02.691004Z","steps":["trace[2052332792] 'process raft request' (duration: 426.519403ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:02.691128Z","caller":"traceutil/trace.go:171","msg":"trace[204856782] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"426.02732ms","start":"2026-07-15T14:28:02.265086Z","end":"2026-07-15T14:28:02.691113Z","steps":["trace[204856782] 'process raft request' (duration: 425.742722ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:02.691239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:28:02.265062Z","time spent":"426.108332ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-15T14:28:02.691255Z","caller":"traceutil/trace.go:171","msg":"trace[368034673] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"423.523101ms","start":"2026-07-15T14:28:02.267714Z","end":"2026-07-15T14:28:02.691237Z","steps":["trace[368034673] 'process raft request' (duration: 423.182191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:02.691265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:28:02.264244Z","time spent":"426.799061ms","remote":"127.0.0.1:49800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T14:28:02.691335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:28:02.267701Z","time spent":"423.596743ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-15T14:28:02.691969Z","caller":"traceutil/trace.go:171","msg":"trace[1858868122] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"416.782895ms","start":"2026-07-15T14:28:02.275174Z","end":"2026-07-15T14:28:02.691957Z","steps":["trace[1858868122] 'process raft request' (duration: 416.708453ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:02.692052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:28:02.275151Z","time spent":"416.857558ms","remote":"127.0.0.1:49800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T14:28:02.69213Z","caller":"traceutil/trace.go:171","msg":"trace[1614762076] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"423.940633ms","start":"2026-07-15T14:28:02.268182Z","end":"2026-07-15T14:28:02.692122Z","steps":["trace[1614762076] 'process raft request' (duration: 423.439609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:02.692174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:28:02.268176Z","time spent":"423.972314ms","remote":"127.0.0.1:49800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T14:28:02.697304Z","caller":"traceutil/trace.go:171","msg":"trace[801263413] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"423.045657ms","start":"2026-07-15T14:28:02.269203Z","end":"2026-07-15T14:28:02.692249Z","steps":["trace[801263413] 'process raft request' (duration: 422.577945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:02.697441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:28:02.26919Z","time spent":"428.188099ms","remote":"127.0.0.1:49800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T14:28:02.879775Z","caller":"traceutil/trace.go:171","msg":"trace[567960904] transaction","detail":"{read_only:false; number_of_response:0; response_revision:510; }","duration":"527.283333ms","start":"2026-07-15T14:28:02.352455Z","end":"2026-07-15T14:28:02.879738Z","steps":["trace[567960904] 'process raft request' (duration: 477.259718ms)","trace[567960904] 'compare' (duration: 49.874341ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:28:02.87985Z","caller":"traceutil/trace.go:171","msg":"trace[1716208391] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"522.962344ms","start":"2026-07-15T14:28:02.356869Z","end":"2026-07-15T14:28:02.879832Z","steps":["trace[1716208391] 'process raft request' (duration: 522.779349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:02.879928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:28:02.352433Z","time spent":"527.430397ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T14:28:02.879971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:28:02.356854Z","time spent":"523.044156ms","remote":"127.0.0.1:49800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T14:28:02.880133Z","caller":"traceutil/trace.go:171","msg":"trace[2119098783] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"520.510497ms","start":"2026-07-15T14:28:02.359584Z","end":"2026-07-15T14:28:02.880095Z","steps":["trace[2119098783] 'process raft request' (duration: 520.149927ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:02.880187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.959068ms","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-07-15T14:28:02.880222Z","caller":"traceutil/trace.go:171","msg":"trace[1784179224] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:512; }","duration":"434.03539ms","start":"2026-07-15T14:28:02.446177Z","end":"2026-07-15T14:28:02.880213Z","steps":["trace[1784179224] 'agreement among raft nodes before linearized reading' (duration: 433.975538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:02.880246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:28:02.446148Z","time spent":"434.092642ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-15T14:28:02.880341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:28:02.359565Z","time spent":"520.663091ms","remote":"127.0.0.1:49800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T14:28:02.880674Z","caller":"traceutil/trace.go:171","msg":"trace[1124464396] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:518; }","duration":"433.803054ms","start":"2026-07-15T14:28:02.446235Z","end":"2026-07-15T14:28:02.880038Z","steps":["trace[1124464396] 'read index received' (duration: 215.657118ms)","trace[1124464396] 'applied index is now lower than readState.Index' (duration: 218.143346ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:28:02.882781Z","caller":"traceutil/trace.go:171","msg":"trace[1560802229] transaction","detail":"{read_only:false; number_of_response:0; response_revision:512; }","duration":"172.771959ms","start":"2026-07-15T14:28:02.709992Z","end":"2026-07-15T14:28:02.882764Z","steps":["trace[1560802229] 'process raft request' (duration: 171.599907ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:02.883142Z","caller":"traceutil/trace.go:171","msg":"trace[1363860237] transaction","detail":"{read_only:false; number_of_response:0; response_revision:512; }","duration":"171.381271ms","start":"2026-07-15T14:28:02.711745Z","end":"2026-07-15T14:28:02.883126Z","steps":["trace[1363860237] 'process raft request' (duration: 169.90661ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:02.883532Z","caller":"traceutil/trace.go:171","msg":"trace[1020431149] transaction","detail":"{read_only:false; number_of_response:0; response_revision:512; }","duration":"168.041329ms","start":"2026-07-15T14:28:02.715476Z","end":"2026-07-15T14:28:02.883518Z","steps":["trace[1020431149] 'process raft request' (duration: 166.195618ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:02.88387Z","caller":"traceutil/trace.go:171","msg":"trace[882674375] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"108.919494ms","start":"2026-07-15T14:28:02.77493Z","end":"2026-07-15T14:28:02.883849Z","steps":["trace[882674375] 'process raft request' (duration: 107.393512ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:02.883899Z","caller":"traceutil/trace.go:171","msg":"trace[1680666964] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"156.135602ms","start":"2026-07-15T14:28:02.72774Z","end":"2026-07-15T14:28:02.883876Z","steps":["trace[1680666964] 'process raft request' (duration: 154.036644ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:02.884073Z","caller":"traceutil/trace.go:171","msg":"trace[197999075] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"152.276996ms","start":"2026-07-15T14:28:02.731782Z","end":"2026-07-15T14:28:02.884059Z","steps":["trace[197999075] 'process raft request' (duration: 150.140677ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:02.884189Z","caller":"traceutil/trace.go:171","msg":"trace[476622819] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"136.128932ms","start":"2026-07-15T14:28:02.74805Z","end":"2026-07-15T14:28:02.884179Z","steps":["trace[476622819] 'process raft request' (duration: 134.141557ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:13.412121Z","caller":"traceutil/trace.go:171","msg":"trace[675790313] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"436.528407ms","start":"2026-07-15T14:28:12.975571Z","end":"2026-07-15T14:28:13.412099Z","steps":["trace[675790313] 'read index received' (duration: 436.384933ms)","trace[675790313] 'applied index is now lower than readState.Index' (duration: 142.424µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T14:28:13.412244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.654981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:28:13.412285Z","caller":"traceutil/trace.go:171","msg":"trace[1847210188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"436.718642ms","start":"2026-07-15T14:28:12.975553Z","end":"2026-07-15T14:28:13.412271Z","steps":["trace[1847210188] 'agreement among raft nodes before linearized reading' (duration: 436.63784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:13.412325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:28:12.975544Z","time spent":"436.771713ms","remote":"127.0.0.1:49760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-15T14:28:13.412562Z","caller":"traceutil/trace.go:171","msg":"trace[25958438] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"443.772529ms","start":"2026-07-15T14:28:12.968777Z","end":"2026-07-15T14:28:13.41255Z","steps":["trace[25958438] 'process raft request' (duration: 443.204114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:13.412653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:28:12.968759Z","time spent":"443.83653ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T14:28:19.840938Z","caller":"traceutil/trace.go:171","msg":"trace[510671612] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"106.490839ms","start":"2026-07-15T14:28:19.734414Z","end":"2026-07-15T14:28:19.840905Z","steps":["trace[510671612] 'process raft request' (duration: 100.140594ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:20.738706Z","caller":"traceutil/trace.go:171","msg":"trace[1929142908] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"293.688446ms","start":"2026-07-15T14:28:20.444993Z","end":"2026-07-15T14:28:20.738681Z","steps":["trace[1929142908] 'process raft request' (duration: 293.47314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:23.262142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.565388611s","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-07-15T14:28:23.262366Z","caller":"traceutil/trace.go:171","msg":"trace[315569125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"1.565537575s","start":"2026-07-15T14:28:21.696683Z","end":"2026-07-15T14:28:23.262221Z","steps":["trace[315569125] 'range keys from in-memory index tree' (duration: 1.565219916s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:23.262534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.286754714s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:28:23.262602Z","caller":"traceutil/trace.go:171","msg":"trace[722438886] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"2.286852556s","start":"2026-07-15T14:28:20.975732Z","end":"2026-07-15T14:28:23.262585Z","steps":["trace[722438886] 'range keys from in-memory index tree' (duration: 2.286702012s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:23.262641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:28:20.975716Z","time spent":"2.286916418s","remote":"127.0.0.1:49760","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-07-15T14:28:23.262748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.803834269s","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-07-15T14:28:23.262774Z","caller":"traceutil/trace.go:171","msg":"trace[1275485281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"1.803916052s","start":"2026-07-15T14:28:21.45885Z","end":"2026-07-15T14:28:23.262766Z","steps":["trace[1275485281] 'range keys from in-memory index tree' (duration: 1.803714266s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:23.262799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:28:21.458818Z","time spent":"1.803975402s","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-15T14:28:23.262799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:28:21.696663Z","time spent":"1.566106929s","remote":"127.0.0.1:50028","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-07-15T14:28:23.26237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.188827962s","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-07-15T14:28:23.263072Z","caller":"traceutil/trace.go:171","msg":"trace[1012775568] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"1.189574831s","start":"2026-07-15T14:28:22.073479Z","end":"2026-07-15T14:28:23.263054Z","steps":["trace[1012775568] 'range keys from in-memory index tree' (duration: 1.188619077s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:23.263123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:28:22.073459Z","time spent":"1.189649883s","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-15T14:28:23.263251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.561733257s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-15T14:28:23.263315Z","caller":"traceutil/trace.go:171","msg":"trace[1122290536] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"1.561819018s","start":"2026-07-15T14:28:21.701479Z","end":"2026-07-15T14:28:23.263298Z","steps":["trace[1122290536] 'range keys from in-memory index tree' (duration: 1.561580323s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:23.263355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:28:21.701469Z","time spent":"1.56187646s","remote":"127.0.0.1:50028","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-07-15T14:28:23.453298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.634778ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-15T14:28:23.45355Z","caller":"traceutil/trace.go:171","msg":"trace[1904151336] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"186.366365ms","start":"2026-07-15T14:28:23.267172Z","end":"2026-07-15T14:28:23.453539Z","steps":["trace[1904151336] 'process raft request' (duration: 186.263413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:23.453669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.659631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:28:23.453707Z","caller":"traceutil/trace.go:171","msg":"trace[538920644] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"476.738604ms","start":"2026-07-15T14:28:22.97696Z","end":"2026-07-15T14:28:23.453699Z","steps":["trace[538920644] 'agreement among raft nodes before linearized reading' (duration: 476.624201ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:23.453729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:28:22.976943Z","time spent":"476.780604ms","remote":"127.0.0.1:49748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-15T14:28:23.453542Z","caller":"traceutil/trace.go:171","msg":"trace[578286963] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"476.524448ms","start":"2026-07-15T14:28:22.976995Z","end":"2026-07-15T14:28:23.45352Z","steps":["trace[578286963] 'read index received' (duration: 254.676465ms)","trace[578286963] 'applied index is now lower than readState.Index' (duration: 221.845553ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:28:23.517525Z","caller":"traceutil/trace.go:171","msg":"trace[67639713] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"250.237039ms","start":"2026-07-15T14:28:23.26727Z","end":"2026-07-15T14:28:23.517507Z","steps":["trace[67639713] 'process raft request' (duration: 250.045594ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:23.51787Z","caller":"traceutil/trace.go:171","msg":"trace[572146930] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"250.336811ms","start":"2026-07-15T14:28:23.267517Z","end":"2026-07-15T14:28:23.517854Z","steps":["trace[572146930] 'process raft request' (duration: 249.938161ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:23.517877Z","caller":"traceutil/trace.go:171","msg":"trace[1779163536] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"249.517481ms","start":"2026-07-15T14:28:23.268314Z","end":"2026-07-15T14:28:23.517831Z","steps":["trace[1779163536] 'process raft request' (duration: 249.358917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:23.737361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.641643ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-15T14:28:23.738504Z","caller":"traceutil/trace.go:171","msg":"trace[1295322754] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:660; }","duration":"279.334414ms","start":"2026-07-15T14:28:23.45914Z","end":"2026-07-15T14:28:23.738475Z","steps":["trace[1295322754] 'read index received' (duration: 58.314401ms)","trace[1295322754] 'applied index is now lower than readState.Index' (duration: 221.017393ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:28:23.738546Z","caller":"traceutil/trace.go:171","msg":"trace[289744469] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"281.452838ms","start":"2026-07-15T14:28:23.457062Z","end":"2026-07-15T14:28:23.738515Z","steps":["trace[289744469] 'process raft request' (duration: 163.529632ms)","trace[289744469] 'compare' (duration: 116.458728ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T14:28:23.738691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.53318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:28:23.738731Z","caller":"traceutil/trace.go:171","msg":"trace[190808324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"279.604941ms","start":"2026-07-15T14:28:23.459114Z","end":"2026-07-15T14:28:23.738719Z","steps":["trace[190808324] 'agreement among raft nodes before linearized reading' (duration: 279.435806ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:25.531383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.256691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2mnz2\" ","response":"range_response_count:1 size:17247"} {"level":"info","ts":"2026-07-15T14:28:25.53153Z","caller":"traceutil/trace.go:171","msg":"trace[772539067] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2mnz2; range_end:; response_count:1; response_revision:641; }","duration":"123.411535ms","start":"2026-07-15T14:28:25.408044Z","end":"2026-07-15T14:28:25.531455Z","steps":["trace[772539067] 'range keys from in-memory index tree' (duration: 123.063027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:26.933719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.918784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.100\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-15T14:28:26.933818Z","caller":"traceutil/trace.go:171","msg":"trace[232978665] range","detail":"{range_begin:/registry/masterleases/199.19.213.100; range_end:; response_count:1; response_revision:653; }","duration":"187.039717ms","start":"2026-07-15T14:28:26.746738Z","end":"2026-07-15T14:28:26.933778Z","steps":["trace[232978665] 'range keys from in-memory index tree' (duration: 186.77351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:29.741062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.371841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T14:28:29.741167Z","caller":"traceutil/trace.go:171","msg":"trace[788116748] linearizableReadLoop","detail":"{readStateIndex:735; appliedIndex:734; }","duration":"111.274161ms","start":"2026-07-15T14:28:29.629877Z","end":"2026-07-15T14:28:29.741151Z","steps":["trace[788116748] 'read index received' (duration: 22.911µs)","trace[788116748] 'applied index is now lower than readState.Index' (duration: 111.25004ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:28:29.741224Z","caller":"traceutil/trace.go:171","msg":"trace[587614175] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"163.751497ms","start":"2026-07-15T14:28:29.577444Z","end":"2026-07-15T14:28:29.741195Z","steps":["trace[587614175] 'process raft request' (duration: 23.162371ms)","trace[587614175] 'compare' (duration: 140.211857ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T14:28:29.741332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.449265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-15T14:28:29.741431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.366503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-15T14:28:29.741523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.06094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-15T14:28:29.741553Z","caller":"traceutil/trace.go:171","msg":"trace[1331253836] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:707; }","duration":"110.101702ms","start":"2026-07-15T14:28:29.631433Z","end":"2026-07-15T14:28:29.741535Z","steps":["trace[1331253836] 'agreement among raft nodes before linearized reading' (duration: 110.04527ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:29.741571Z","caller":"traceutil/trace.go:171","msg":"trace[2092731371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:707; }","duration":"111.505967ms","start":"2026-07-15T14:28:29.629847Z","end":"2026-07-15T14:28:29.741353Z","steps":["trace[2092731371] 'agreement among raft nodes before linearized reading' (duration: 111.386124ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:29.741643Z","caller":"traceutil/trace.go:171","msg":"trace[749704309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:707; }","duration":"111.487176ms","start":"2026-07-15T14:28:29.629989Z","end":"2026-07-15T14:28:29.741476Z","steps":["trace[749704309] 'agreement among raft nodes before linearized reading' (duration: 111.287901ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:30.538349Z","caller":"traceutil/trace.go:171","msg":"trace[1296454926] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"137.98128ms","start":"2026-07-15T14:28:30.400331Z","end":"2026-07-15T14:28:30.538312Z","steps":["trace[1296454926] 'process raft request' (duration: 137.670333ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:30.687839Z","caller":"traceutil/trace.go:171","msg":"trace[818419478] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"225.989429ms","start":"2026-07-15T14:28:30.461807Z","end":"2026-07-15T14:28:30.687796Z","steps":["trace[818419478] 'process raft request' (duration: 140.898752ms)","trace[818419478] 'compare' (duration: 84.863782ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:28:30.688473Z","caller":"traceutil/trace.go:171","msg":"trace[503047193] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:741; }","duration":"116.900233ms","start":"2026-07-15T14:28:30.571548Z","end":"2026-07-15T14:28:30.688449Z","steps":["trace[503047193] 'read index received' (duration: 31.168439ms)","trace[503047193] 'applied index is now lower than readState.Index' (duration: 85.730784ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T14:28:30.6887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.128549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-07-15T14:28:30.688737Z","caller":"traceutil/trace.go:171","msg":"trace[1089315471] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:715; }","duration":"117.21278ms","start":"2026-07-15T14:28:30.571512Z","end":"2026-07-15T14:28:30.688724Z","steps":["trace[1089315471] 'agreement among raft nodes before linearized reading' (duration: 117.036716ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:30.689099Z","caller":"traceutil/trace.go:171","msg":"trace[1017696133] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"122.074982ms","start":"2026-07-15T14:28:30.567005Z","end":"2026-07-15T14:28:30.68908Z","steps":["trace[1017696133] 'process raft request' (duration: 121.300013ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:30.783639Z","caller":"traceutil/trace.go:171","msg":"trace[356739753] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"135.725554ms","start":"2026-07-15T14:28:30.647877Z","end":"2026-07-15T14:28:30.783603Z","steps":["trace[356739753] 'process raft request' (duration: 135.457417ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:30.907914Z","caller":"traceutil/trace.go:171","msg":"trace[15439009] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"107.113748ms","start":"2026-07-15T14:28:30.800767Z","end":"2026-07-15T14:28:30.907881Z","steps":["trace[15439009] 'process raft request' (duration: 106.861252ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:37.890775Z","caller":"traceutil/trace.go:171","msg":"trace[1133491822] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"106.051145ms","start":"2026-07-15T14:28:37.784699Z","end":"2026-07-15T14:28:37.89075Z","steps":["trace[1133491822] 'process raft request' (duration: 105.900281ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:38.167737Z","caller":"traceutil/trace.go:171","msg":"trace[1130574701] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"368.963914ms","start":"2026-07-15T14:28:37.79875Z","end":"2026-07-15T14:28:38.167714Z","steps":["trace[1130574701] 'process raft request' (duration: 368.80361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:38.168051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:28:37.798715Z","time spent":"369.084877ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T14:28:38.168385Z","caller":"traceutil/trace.go:171","msg":"trace[355202248] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:812; }","duration":"192.2198ms","start":"2026-07-15T14:28:37.97615Z","end":"2026-07-15T14:28:38.16837Z","steps":["trace[355202248] 'read index received' (duration: 192.2127ms)","trace[355202248] 'applied index is now lower than readState.Index' (duration: 6.39µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T14:28:38.168498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.355874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:28:38.168628Z","caller":"traceutil/trace.go:171","msg":"trace[1967391496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:781; }","duration":"192.509398ms","start":"2026-07-15T14:28:37.976091Z","end":"2026-07-15T14:28:38.1686Z","steps":["trace[1967391496] 'agreement among raft nodes before linearized reading' (duration: 192.338413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:41.476462Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033912560175246562,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T14:28:41.977236Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033912560175246562,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T14:28:42.0274Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.508338978s","expected-duration":"1s"} {"level":"info","ts":"2026-07-15T14:28:42.027771Z","caller":"traceutil/trace.go:171","msg":"trace[574845211] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"1.508800119s","start":"2026-07-15T14:28:40.518958Z","end":"2026-07-15T14:28:42.027758Z","steps":["trace[574845211] 'process raft request' (duration: 1.508655586s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:42.027875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:28:40.518944Z","time spent":"1.508867322s","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T14:28:42.027871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.051661597s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:28:42.027763Z","caller":"traceutil/trace.go:171","msg":"trace[1313046850] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:827; }","duration":"1.051547505s","start":"2026-07-15T14:28:40.976192Z","end":"2026-07-15T14:28:42.027739Z","steps":["trace[1313046850] 'read index received' (duration: 1.051540945s)","trace[1313046850] 'applied index is now lower than readState.Index' (duration: 5.39µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:28:42.027941Z","caller":"traceutil/trace.go:171","msg":"trace[1633256278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:796; }","duration":"1.051740449s","start":"2026-07-15T14:28:40.976183Z","end":"2026-07-15T14:28:42.027924Z","steps":["trace[1633256278] 'agreement among raft nodes before linearized reading' (duration: 1.051616606s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:42.027979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:28:40.976111Z","time spent":"1.051857832s","remote":"127.0.0.1:49760","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-07-15T14:28:42.028135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.539274ms","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-07-15T14:28:42.028174Z","caller":"traceutil/trace.go:171","msg":"trace[263697979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:796; }","duration":"123.608126ms","start":"2026-07-15T14:28:41.904547Z","end":"2026-07-15T14:28:42.028155Z","steps":["trace[263697979] 'agreement among raft nodes before linearized reading' (duration: 123.509213ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:42.028196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.872708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-15T14:28:42.028223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.551124ms","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-07-15T14:28:42.028264Z","caller":"traceutil/trace.go:171","msg":"trace[1332373343] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:796; }","duration":"988.996071ms","start":"2026-07-15T14:28:41.039251Z","end":"2026-07-15T14:28:42.028247Z","steps":["trace[1332373343] 'agreement among raft nodes before linearized reading' (duration: 988.787996ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:28:42.028288Z","caller":"traceutil/trace.go:171","msg":"trace[2072336956] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:796; }","duration":"238.656147ms","start":"2026-07-15T14:28:41.789619Z","end":"2026-07-15T14:28:42.028275Z","steps":["trace[2072336956] 'agreement among raft nodes before linearized reading' (duration: 238.473402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:28:42.028323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:28:41.039192Z","time spent":"989.110594ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-15T14:28:42.028779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.559698ms","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-07-15T14:28:42.028954Z","caller":"traceutil/trace.go:171","msg":"trace[2119857607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:796; }","duration":"222.759123ms","start":"2026-07-15T14:28:41.806146Z","end":"2026-07-15T14:28:42.028905Z","steps":["trace[2119857607] 'agreement among raft nodes before linearized reading' (duration: 221.932843ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:29:32.680686Z","caller":"traceutil/trace.go:171","msg":"trace[691498712] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"204.179641ms","start":"2026-07-15T14:29:32.476473Z","end":"2026-07-15T14:29:32.680653Z","steps":["trace[691498712] 'process raft request' (duration: 201.7736ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:29:49.648046Z","caller":"traceutil/trace.go:171","msg":"trace[183167493] transaction","detail":"{read_only:false; response_revision:1690; number_of_response:1; }","duration":"159.060355ms","start":"2026-07-15T14:29:49.488954Z","end":"2026-07-15T14:29:49.648015Z","steps":["trace[183167493] 'process raft request' (duration: 112.161431ms)","trace[183167493] 'compare' (duration: 46.646679ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:29:50.819255Z","caller":"traceutil/trace.go:171","msg":"trace[678340433] transaction","detail":"{read_only:false; response_revision:1701; number_of_response:1; }","duration":"215.658587ms","start":"2026-07-15T14:29:50.60356Z","end":"2026-07-15T14:29:50.819219Z","steps":["trace[678340433] 'process raft request' (duration: 193.473797ms)","trace[678340433] 'compare' (duration: 21.918625ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T14:30:05.429917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.035277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-15T14:30:05.429992Z","caller":"traceutil/trace.go:171","msg":"trace[1674402220] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1891; }","duration":"446.130528ms","start":"2026-07-15T14:30:04.983845Z","end":"2026-07-15T14:30:05.429976Z","steps":["trace[1674402220] 'range keys from in-memory index tree' (duration: 445.876884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:05.430025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:04.983839Z","time spent":"446.178229ms","remote":"127.0.0.1:50028","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-07-15T14:30:05.429999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.066096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-08c56a9f-34cc-4efb-8cff-db5bc0c9bd24\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-07-15T14:30:05.430118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.284142ms","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-07-15T14:30:05.43013Z","caller":"traceutil/trace.go:171","msg":"trace[1901204107] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-08c56a9f-34cc-4efb-8cff-db5bc0c9bd24; range_end:; response_count:1; response_revision:1891; }","duration":"150.25863ms","start":"2026-07-15T14:30:05.279835Z","end":"2026-07-15T14:30:05.430094Z","steps":["trace[1901204107] 'range keys from in-memory index tree' (duration: 149.831921ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:30:05.430174Z","caller":"traceutil/trace.go:171","msg":"trace[1967794849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1891; }","duration":"445.340733ms","start":"2026-07-15T14:30:04.984822Z","end":"2026-07-15T14:30:05.430163Z","steps":["trace[1967794849] 'range keys from in-memory index tree' (duration: 445.19458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:05.4302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:04.984793Z","time spent":"445.401894ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-15T14:30:05.430339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.937372ms","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-07-15T14:30:05.430371Z","caller":"traceutil/trace.go:171","msg":"trace[1728226694] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1891; }","duration":"454.969303ms","start":"2026-07-15T14:30:04.975394Z","end":"2026-07-15T14:30:05.430364Z","steps":["trace[1728226694] 'range keys from in-memory index tree' (duration: 454.745679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:05.430394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:04.975309Z","time spent":"455.077165ms","remote":"127.0.0.1:50028","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":"warn","ts":"2026-07-15T14:30:05.430349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.569268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-15T14:30:05.430481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.894875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-08c56a9f-34cc-4efb-8cff-db5bc0c9bd24\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-07-15T14:30:05.430467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.003736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-15T14:30:05.430509Z","caller":"traceutil/trace.go:171","msg":"trace[486811211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1891; }","duration":"446.74192ms","start":"2026-07-15T14:30:04.983727Z","end":"2026-07-15T14:30:05.430469Z","steps":["trace[486811211] 'range keys from in-memory index tree' (duration: 446.382084ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:30:05.430534Z","caller":"traceutil/trace.go:171","msg":"trace[1660835902] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1891; }","duration":"448.146928ms","start":"2026-07-15T14:30:04.98237Z","end":"2026-07-15T14:30:05.430517Z","steps":["trace[1660835902] 'range keys from in-memory index tree' (duration: 447.803341ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:30:05.430625Z","caller":"traceutil/trace.go:171","msg":"trace[1737353180] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-08c56a9f-34cc-4efb-8cff-db5bc0c9bd24; range_end:; response_count:1; response_revision:1891; }","duration":"520.817733ms","start":"2026-07-15T14:30:04.909689Z","end":"2026-07-15T14:30:05.430507Z","steps":["trace[1737353180] 'range keys from in-memory index tree' (duration: 519.642459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:05.430655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:04.909658Z","time spent":"520.988076ms","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-08c56a9f-34cc-4efb-8cff-db5bc0c9bd24\" "} {"level":"warn","ts":"2026-07-15T14:30:05.430634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:04.983705Z","time spent":"446.902244ms","remote":"127.0.0.1:50028","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-07-15T14:30:05.430656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:04.982331Z","time spent":"448.311141ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-15T14:30:05.550001Z","caller":"traceutil/trace.go:171","msg":"trace[1392751851] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"109.946716ms","start":"2026-07-15T14:30:05.440024Z","end":"2026-07-15T14:30:05.54997Z","steps":["trace[1392751851] 'process raft request' (duration: 109.869814ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:30:05.550232Z","caller":"traceutil/trace.go:171","msg":"trace[911477105] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"113.867903ms","start":"2026-07-15T14:30:05.436347Z","end":"2026-07-15T14:30:05.550215Z","steps":["trace[911477105] 'process raft request' (duration: 113.498005ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:30:05.550407Z","caller":"traceutil/trace.go:171","msg":"trace[553108108] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"115.033366ms","start":"2026-07-15T14:30:05.435358Z","end":"2026-07-15T14:30:05.550391Z","steps":["trace[553108108] 'process raft request' (duration: 103.205873ms)","trace[553108108] 'compare' (duration: 10.957226ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:30:05.550479Z","caller":"traceutil/trace.go:171","msg":"trace[312019712] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"115.019976ms","start":"2026-07-15T14:30:05.435449Z","end":"2026-07-15T14:30:05.550469Z","steps":["trace[312019712] 'process raft request' (duration: 114.333862ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:30:05.550206Z","caller":"traceutil/trace.go:171","msg":"trace[112421365] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"114.798501ms","start":"2026-07-15T14:30:05.435386Z","end":"2026-07-15T14:30:05.550184Z","steps":["trace[112421365] 'process raft request' (duration: 114.327342ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:06.83434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.558825ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T14:30:06.834431Z","caller":"traceutil/trace.go:171","msg":"trace[460105257] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"1.15050027s","start":"2026-07-15T14:30:05.683916Z","end":"2026-07-15T14:30:06.834417Z","steps":["trace[460105257] 'process raft request' (duration: 427.798342ms)","trace[460105257] 'compare' (duration: 722.136907ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T14:30:06.834483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:05.683886Z","time spent":"1.150570601s","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T14:30:07.599242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.065773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T14:30:07.599606Z","caller":"traceutil/trace.go:171","msg":"trace[1191650697] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"1.857994564s","start":"2026-07-15T14:30:05.741575Z","end":"2026-07-15T14:30:07.59957Z","steps":["trace[1191650697] 'process raft request' (duration: 1.224469622s)","trace[1191650697] 'compare' (duration: 632.825759ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:30:07.599821Z","caller":"traceutil/trace.go:171","msg":"trace[2036911931] linearizableReadLoop","detail":"{readStateIndex:1976; appliedIndex:1973; }","duration":"1.624403553s","start":"2026-07-15T14:30:05.975371Z","end":"2026-07-15T14:30:07.599775Z","steps":["trace[2036911931] 'read index received' (duration: 136.29491ms)","trace[2036911931] 'applied index is now lower than readState.Index' (duration: 1.488107553s)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:30:07.599963Z","caller":"traceutil/trace.go:171","msg":"trace[758264148] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"1.208241131s","start":"2026-07-15T14:30:06.39171Z","end":"2026-07-15T14:30:07.599952Z","steps":["trace[758264148] 'process raft request' (duration: 1.20818354s)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:30:07.599956Z","caller":"traceutil/trace.go:171","msg":"trace[1840774970] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"1.674589732s","start":"2026-07-15T14:30:05.925354Z","end":"2026-07-15T14:30:07.599944Z","steps":["trace[1840774970] 'process raft request' (duration: 1.674234605s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:07.599827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:05.741546Z","time spent":"1.858151788s","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T14:30:07.600091Z","caller":"traceutil/trace.go:171","msg":"trace[1930604421] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"1.399755326s","start":"2026-07-15T14:30:06.200325Z","end":"2026-07-15T14:30:07.600081Z","steps":["trace[1930604421] 'process raft request' (duration: 1.399368539s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:07.600086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:05.925326Z","time spent":"1.674690104s","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T14:30:07.600174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:06.200292Z","time spent":"1.399837977s","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T14:30:07.600296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.250357169s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T14:30:07.600337Z","caller":"traceutil/trace.go:171","msg":"trace[921503131] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1902; }","duration":"1.250452861s","start":"2026-07-15T14:30:06.349875Z","end":"2026-07-15T14:30:07.600328Z","steps":["trace[921503131] 'agreement among raft nodes before linearized reading' (duration: 1.250234767s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:07.600364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:06.349854Z","time spent":"1.250501662s","remote":"127.0.0.1:50130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":90,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-07-15T14:30:07.600055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:06.391678Z","time spent":"1.208320353s","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T14:30:07.600489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.208619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-15T14:30:07.600524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040725907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-15T14:30:07.600545Z","caller":"traceutil/trace.go:171","msg":"trace[2044551276] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1902; }","duration":"1.040776048s","start":"2026-07-15T14:30:06.559761Z","end":"2026-07-15T14:30:07.600537Z","steps":["trace[2044551276] 'agreement among raft nodes before linearized reading' (duration: 1.040711867s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:07.600568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:06.559736Z","time spent":"1.0408281s","remote":"127.0.0.1:37624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-15T14:30:07.600533Z","caller":"traceutil/trace.go:171","msg":"trace[738465712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1902; }","duration":"146.317531ms","start":"2026-07-15T14:30:07.454204Z","end":"2026-07-15T14:30:07.600521Z","steps":["trace[738465712] 'agreement among raft nodes before linearized reading' (duration: 146.102577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:07.600725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.167573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.100\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-15T14:30:07.600763Z","caller":"traceutil/trace.go:171","msg":"trace[646730313] range","detail":"{range_begin:/registry/masterleases/199.19.213.100; range_end:; response_count:1; response_revision:1902; }","duration":"846.206593ms","start":"2026-07-15T14:30:06.754549Z","end":"2026-07-15T14:30:07.600756Z","steps":["trace[646730313] 'agreement among raft nodes before linearized reading' (duration: 845.795285ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:07.600785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:06.754486Z","time spent":"846.294565ms","remote":"127.0.0.1:49780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.100\" "} {"level":"warn","ts":"2026-07-15T14:30:07.600898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165256256s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-08c56a9f-34cc-4efb-8cff-db5bc0c9bd24\" ","response":"range_response_count:1 size:3747"} {"level":"warn","ts":"2026-07-15T14:30:07.600922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.946435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:30:07.600928Z","caller":"traceutil/trace.go:171","msg":"trace[718279171] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-08c56a9f-34cc-4efb-8cff-db5bc0c9bd24; range_end:; response_count:1; response_revision:1902; }","duration":"1.165360118s","start":"2026-07-15T14:30:06.435561Z","end":"2026-07-15T14:30:07.600921Z","steps":["trace[718279171] 'agreement among raft nodes before linearized reading' (duration: 1.165179804s)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:30:07.600942Z","caller":"traceutil/trace.go:171","msg":"trace[1540270830] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1902; }","duration":"432.994156ms","start":"2026-07-15T14:30:07.167941Z","end":"2026-07-15T14:30:07.600936Z","steps":["trace[1540270830] 'agreement among raft nodes before linearized reading' (duration: 432.963245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:07.601131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:06.435544Z","time spent":"1.165399578s","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3770,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-08c56a9f-34cc-4efb-8cff-db5bc0c9bd24\" "} {"level":"warn","ts":"2026-07-15T14:30:07.601139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:07.167923Z","time spent":"433.21156ms","remote":"127.0.0.1:47058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-15T14:30:07.601301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.5305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-07-15T14:30:07.600936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.625564226s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:30:07.601392Z","caller":"traceutil/trace.go:171","msg":"trace[1371412150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1902; }","duration":"1.626056006s","start":"2026-07-15T14:30:05.97533Z","end":"2026-07-15T14:30:07.601386Z","steps":["trace[1371412150] 'agreement among raft nodes before linearized reading' (duration: 1.625490765s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:07.601418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:05.975312Z","time spent":"1.626100056s","remote":"127.0.0.1:49760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-15T14:30:07.601469Z","caller":"traceutil/trace.go:171","msg":"trace[1809064356] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1902; }","duration":"398.679293ms","start":"2026-07-15T14:30:07.202693Z","end":"2026-07-15T14:30:07.601372Z","steps":["trace[1809064356] 'agreement among raft nodes before linearized reading' (duration: 398.201434ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:07.601573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:07.202647Z","time spent":"398.913708ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":580,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-07-15T14:30:08.103903Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033912560175248693,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T14:30:08.304992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.622411ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-07-15T14:30:08.305103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:07.60175Z","time spent":"703.3486ms","remote":"127.0.0.1:49780","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-15T14:30:08.347303Z","caller":"traceutil/trace.go:171","msg":"trace[2038844967] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"740.820924ms","start":"2026-07-15T14:30:07.606451Z","end":"2026-07-15T14:30:08.347272Z","steps":["trace[2038844967] 'process raft request' (duration: 740.544969ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:30:08.347505Z","caller":"traceutil/trace.go:171","msg":"trace[1752508392] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"741.008027ms","start":"2026-07-15T14:30:07.606478Z","end":"2026-07-15T14:30:08.347486Z","steps":["trace[1752508392] 'process raft request' (duration: 740.698081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:08.347629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:07.60646Z","time spent":"741.09448ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T14:30:08.34789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:07.606426Z","time spent":"740.999847ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T14:30:08.347956Z","caller":"traceutil/trace.go:171","msg":"trace[273966532] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"741.049278ms","start":"2026-07-15T14:30:07.606879Z","end":"2026-07-15T14:30:08.347928Z","steps":["trace[273966532] 'process raft request' (duration: 740.542998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:08.348158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:07.606871Z","time spent":"741.212332ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T14:30:08.348279Z","caller":"traceutil/trace.go:171","msg":"trace[602260720] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"741.087789ms","start":"2026-07-15T14:30:07.606537Z","end":"2026-07-15T14:30:08.347624Z","steps":["trace[602260720] 'process raft request' (duration: 740.843434ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:08.348421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:07.606532Z","time spent":"741.795733ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T14:30:08.348998Z","caller":"traceutil/trace.go:171","msg":"trace[1585695998] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"742.010987ms","start":"2026-07-15T14:30:07.606947Z","end":"2026-07-15T14:30:08.348958Z","steps":["trace[1585695998] 'process raft request' (duration: 740.509968ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:30:08.349037Z","caller":"traceutil/trace.go:171","msg":"trace[3466117] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"741.049408ms","start":"2026-07-15T14:30:07.607928Z","end":"2026-07-15T14:30:08.348977Z","steps":["trace[3466117] 'process raft request' (duration: 740.831894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:08.349142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:07.606942Z","time spent":"742.133239ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T14:30:08.349205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:07.60792Z","time spent":"741.160731ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T14:30:08.349675Z","caller":"traceutil/trace.go:171","msg":"trace[1183092023] linearizableReadLoop","detail":"{readStateIndex:1985; appliedIndex:1978; }","duration":"746.399304ms","start":"2026-07-15T14:30:07.603257Z","end":"2026-07-15T14:30:08.349657Z","steps":["trace[1183092023] 'read index received' (duration: 578.081488ms)","trace[1183092023] 'applied index is now lower than readState.Index' (duration: 168.315946ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T14:30:08.349972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.70377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:30:08.350036Z","caller":"traceutil/trace.go:171","msg":"trace[487631668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1909; }","duration":"746.784761ms","start":"2026-07-15T14:30:07.603234Z","end":"2026-07-15T14:30:08.350018Z","steps":["trace[487631668] 'agreement among raft nodes before linearized reading' (duration: 746.597177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:08.35008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:07.603219Z","time spent":"746.851872ms","remote":"127.0.0.1:49748","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-07-15T14:30:08.350092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.547308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:10328"} {"level":"warn","ts":"2026-07-15T14:30:08.350189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.874043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T14:30:08.350194Z","caller":"traceutil/trace.go:171","msg":"trace[1377914283] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1909; }","duration":"369.673291ms","start":"2026-07-15T14:30:07.980503Z","end":"2026-07-15T14:30:08.350176Z","steps":["trace[1377914283] 'agreement among raft nodes before linearized reading' (duration: 369.342524ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:30:08.350238Z","caller":"traceutil/trace.go:171","msg":"trace[2093633614] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:1909; }","duration":"323.987225ms","start":"2026-07-15T14:30:08.026238Z","end":"2026-07-15T14:30:08.350225Z","steps":["trace[2093633614] 'agreement among raft nodes before linearized reading' (duration: 323.649568ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:08.350301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:07.980487Z","time spent":"369.796993ms","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":10351,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-15T14:30:08.350541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:08.026215Z","time spent":"324.134978ms","remote":"127.0.0.1:43316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":2,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-07-15T14:30:21.192304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.042849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T14:30:21.192839Z","caller":"traceutil/trace.go:171","msg":"trace[222166814] linearizableReadLoop","detail":"{readStateIndex:2099; appliedIndex:2096; }","duration":"483.449659ms","start":"2026-07-15T14:30:20.709359Z","end":"2026-07-15T14:30:21.192809Z","steps":["trace[222166814] 'read index received' (duration: 33.627355ms)","trace[222166814] 'applied index is now lower than readState.Index' (duration: 449.820654ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:30:21.193012Z","caller":"traceutil/trace.go:171","msg":"trace[1983778845] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"755.994283ms","start":"2026-07-15T14:30:20.436995Z","end":"2026-07-15T14:30:21.192989Z","steps":["trace[1983778845] 'process raft request' (duration: 306.087287ms)","trace[1983778845] 'compare' (duration: 448.069981ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T14:30:21.193594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:20.436982Z","time spent":"756.551393ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T14:30:21.193988Z","caller":"traceutil/trace.go:171","msg":"trace[1349086619] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"756.87721ms","start":"2026-07-15T14:30:20.437083Z","end":"2026-07-15T14:30:21.19396Z","steps":["trace[1349086619] 'process raft request' (duration: 755.528794ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:30:21.193991Z","caller":"traceutil/trace.go:171","msg":"trace[126248990] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"755.433322ms","start":"2026-07-15T14:30:20.438526Z","end":"2026-07-15T14:30:21.193959Z","steps":["trace[126248990] 'process raft request' (duration: 754.207458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:21.194105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:20.437058Z","time spent":"756.990501ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T14:30:21.194192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.825866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-15T14:30:21.194253Z","caller":"traceutil/trace.go:171","msg":"trace[275862632] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2019; }","duration":"484.939848ms","start":"2026-07-15T14:30:20.709299Z","end":"2026-07-15T14:30:21.194239Z","steps":["trace[275862632] 'agreement among raft nodes before linearized reading' (duration: 484.727424ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:21.194245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:20.438507Z","time spent":"755.553715ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T14:30:21.194289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:20.709275Z","time spent":"485.005859ms","remote":"127.0.0.1:37624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-15T14:30:21.196466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.982436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:30:21.196541Z","caller":"traceutil/trace.go:171","msg":"trace[792941681] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2020; }","duration":"404.110229ms","start":"2026-07-15T14:30:20.792413Z","end":"2026-07-15T14:30:21.196523Z","steps":["trace[792941681] 'agreement among raft nodes before linearized reading' (duration: 403.970236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:21.196819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:20.792378Z","time spent":"404.418535ms","remote":"127.0.0.1:46902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-07-15T14:30:21.196625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.502348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:30:21.196923Z","caller":"traceutil/trace.go:171","msg":"trace[506624050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2020; }","duration":"222.830184ms","start":"2026-07-15T14:30:20.974075Z","end":"2026-07-15T14:30:21.196906Z","steps":["trace[506624050] 'agreement among raft nodes before linearized reading' (duration: 222.464197ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:30:21.196632Z","caller":"traceutil/trace.go:171","msg":"trace[1671820968] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"304.51702ms","start":"2026-07-15T14:30:20.892013Z","end":"2026-07-15T14:30:21.19653Z","steps":["trace[1671820968] 'process raft request' (duration: 304.234124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:21.197242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:20.891991Z","time spent":"305.190253ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T14:30:26.198266Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033912560175248994,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T14:30:26.699316Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033912560175248994,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T14:30:26.80791Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.115739424s","expected-duration":"1s"} {"level":"info","ts":"2026-07-15T14:30:26.808358Z","caller":"traceutil/trace.go:171","msg":"trace[1617704333] transaction","detail":"{read_only:false; response_revision:2044; number_of_response:1; }","duration":"1.116323245s","start":"2026-07-15T14:30:25.692002Z","end":"2026-07-15T14:30:26.808325Z","steps":["trace[1617704333] 'process raft request' (duration: 1.116156182s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:26.808553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:25.691971Z","time spent":"1.116450488s","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T14:30:26.88184Z","caller":"traceutil/trace.go:171","msg":"trace[1662157820] linearizableReadLoop","detail":"{readStateIndex:2127; appliedIndex:2125; }","duration":"1.184106941s","start":"2026-07-15T14:30:25.697643Z","end":"2026-07-15T14:30:26.88175Z","steps":["trace[1662157820] 'read index received' (duration: 1.110689228s)","trace[1662157820] 'applied index is now lower than readState.Index' (duration: 73.416843ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:30:26.882119Z","caller":"traceutil/trace.go:171","msg":"trace[1524541984] transaction","detail":"{read_only:false; response_revision:2045; number_of_response:1; }","duration":"1.185554378s","start":"2026-07-15T14:30:25.696542Z","end":"2026-07-15T14:30:26.882096Z","steps":["trace[1524541984] 'process raft request' (duration: 1.181330888s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:26.882159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.184463137s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T14:30:26.88224Z","caller":"traceutil/trace.go:171","msg":"trace[364909791] transaction","detail":"{read_only:false; response_revision:2046; number_of_response:1; }","duration":"1.184754113s","start":"2026-07-15T14:30:25.697455Z","end":"2026-07-15T14:30:26.88221Z","steps":["trace[364909791] 'process raft request' (duration: 1.184180533s)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:30:26.882261Z","caller":"traceutil/trace.go:171","msg":"trace[2083330186] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2046; }","duration":"1.184620311s","start":"2026-07-15T14:30:25.697619Z","end":"2026-07-15T14:30:26.882239Z","steps":["trace[2083330186] 'agreement among raft nodes before linearized reading' (duration: 1.184412517s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:26.882287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:25.696518Z","time spent":"1.185683111s","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T14:30:26.882287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.115567088s","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":"warn","ts":"2026-07-15T14:30:26.882321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:25.697611Z","time spent":"1.184698412s","remote":"127.0.0.1:50236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-07-15T14:30:26.882364Z","caller":"traceutil/trace.go:171","msg":"trace[496074592] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2046; }","duration":"1.115701341s","start":"2026-07-15T14:30:25.766644Z","end":"2026-07-15T14:30:26.882345Z","steps":["trace[496074592] 'agreement among raft nodes before linearized reading' (duration: 1.115545538s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:26.882385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:25.697446Z","time spent":"1.184862435s","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T14:30:26.882414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:25.766611Z","time spent":"1.115792173s","remote":"127.0.0.1:47128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-07-15T14:30:26.882531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.530655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:30:26.882566Z","caller":"traceutil/trace.go:171","msg":"trace[759578794] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2046; }","duration":"907.592887ms","start":"2026-07-15T14:30:25.974962Z","end":"2026-07-15T14:30:26.882555Z","steps":["trace[759578794] 'agreement among raft nodes before linearized reading' (duration: 907.534106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:26.882578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.719023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-15T14:30:26.882606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:25.974945Z","time spent":"907.654518ms","remote":"127.0.0.1:49760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-15T14:30:26.88262Z","caller":"traceutil/trace.go:171","msg":"trace[1712244140] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2046; }","duration":"427.809895ms","start":"2026-07-15T14:30:26.454798Z","end":"2026-07-15T14:30:26.882608Z","steps":["trace[1712244140] 'agreement among raft nodes before linearized reading' (duration: 427.665382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:26.88265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:26.454782Z","time spent":"427.861526ms","remote":"127.0.0.1:50028","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":"warn","ts":"2026-07-15T14:30:26.882752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.08086023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:30:26.882797Z","caller":"traceutil/trace.go:171","msg":"trace[1197018276] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:2046; }","duration":"1.08093545s","start":"2026-07-15T14:30:25.801847Z","end":"2026-07-15T14:30:26.882783Z","steps":["trace[1197018276] 'agreement among raft nodes before linearized reading' (duration: 1.080868019s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:26.882849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:25.801834Z","time spent":"1.081001641s","remote":"127.0.0.1:43284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"warn","ts":"2026-07-15T14:30:26.882889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.628621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.100\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-15T14:30:26.882948Z","caller":"traceutil/trace.go:171","msg":"trace[604073821] range","detail":"{range_begin:/registry/masterleases/199.19.213.100; range_end:; response_count:1; response_revision:2046; }","duration":"127.717992ms","start":"2026-07-15T14:30:26.755215Z","end":"2026-07-15T14:30:26.882933Z","steps":["trace[604073821] 'agreement among raft nodes before linearized reading' (duration: 127.60803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:26.88295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.502708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-15T14:30:26.882802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.516158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:30:26.882988Z","caller":"traceutil/trace.go:171","msg":"trace[269561942] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2046; }","duration":"612.58147ms","start":"2026-07-15T14:30:26.270396Z","end":"2026-07-15T14:30:26.882978Z","steps":["trace[269561942] 'agreement among raft nodes before linearized reading' (duration: 612.393476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:26.883011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:26.270375Z","time spent":"612.630821ms","remote":"127.0.0.1:37624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-15T14:30:26.883027Z","caller":"traceutil/trace.go:171","msg":"trace[525847761] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:2046; }","duration":"148.759661ms","start":"2026-07-15T14:30:26.734242Z","end":"2026-07-15T14:30:26.883001Z","steps":["trace[525847761] 'agreement among raft nodes before linearized reading' (duration: 148.525907ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:26.883214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.324762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-15T14:30:26.883263Z","caller":"traceutil/trace.go:171","msg":"trace[94031623] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2046; }","duration":"402.430613ms","start":"2026-07-15T14:30:26.480816Z","end":"2026-07-15T14:30:26.883247Z","steps":["trace[94031623] 'agreement among raft nodes before linearized reading' (duration: 402.25095ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:26.883293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:26.480802Z","time spent":"402.484175ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-15T14:30:27.003505Z","caller":"traceutil/trace.go:171","msg":"trace[590423169] transaction","detail":"{read_only:false; response_revision:2047; number_of_response:1; }","duration":"115.98401ms","start":"2026-07-15T14:30:26.887448Z","end":"2026-07-15T14:30:27.003432Z","steps":["trace[590423169] 'process raft request' (duration: 111.77697ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:30:27.003936Z","caller":"traceutil/trace.go:171","msg":"trace[1806967598] transaction","detail":"{read_only:false; response_revision:2048; number_of_response:1; }","duration":"116.236145ms","start":"2026-07-15T14:30:26.887652Z","end":"2026-07-15T14:30:27.003888Z","steps":["trace[1806967598] 'process raft request' (duration: 115.635753ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:30:35.368529Z","caller":"traceutil/trace.go:171","msg":"trace[1810738082] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"117.846993ms","start":"2026-07-15T14:30:35.250645Z","end":"2026-07-15T14:30:35.368492Z","steps":["trace[1810738082] 'process raft request' (duration: 117.66982ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:30:35.368976Z","caller":"traceutil/trace.go:171","msg":"trace[1042792289] linearizableReadLoop","detail":"{readStateIndex:2176; appliedIndex:2176; }","duration":"107.799756ms","start":"2026-07-15T14:30:35.261158Z","end":"2026-07-15T14:30:35.368958Z","steps":["trace[1042792289] 'read index received' (duration: 107.792345ms)","trace[1042792289] 'applied index is now lower than readState.Index' (duration: 5.791µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T14:30:35.369472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.271164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-15T14:30:35.369534Z","caller":"traceutil/trace.go:171","msg":"trace[1741016950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2092; }","duration":"108.406356ms","start":"2026-07-15T14:30:35.26111Z","end":"2026-07-15T14:30:35.369517Z","steps":["trace[1741016950] 'agreement among raft nodes before linearized reading' (duration: 108.229693ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:30:49.362097Z","caller":"traceutil/trace.go:171","msg":"trace[1202635113] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"372.209211ms","start":"2026-07-15T14:30:48.98986Z","end":"2026-07-15T14:30:49.362069Z","steps":["trace[1202635113] 'process raft request' (duration: 372.067058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:49.362257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:48.989841Z","time spent":"372.337893ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T14:30:49.392087Z","caller":"traceutil/trace.go:171","msg":"trace[253244831] linearizableReadLoop","detail":"{readStateIndex:2258; appliedIndex:2255; }","duration":"283.709051ms","start":"2026-07-15T14:30:49.108355Z","end":"2026-07-15T14:30:49.392064Z","steps":["trace[253244831] 'read index received' (duration: 253.513478ms)","trace[253244831] 'applied index is now lower than readState.Index' (duration: 30.194653ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:30:49.39213Z","caller":"traceutil/trace.go:171","msg":"trace[1405795163] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"320.345062ms","start":"2026-07-15T14:30:49.071756Z","end":"2026-07-15T14:30:49.392101Z","steps":["trace[1405795163] 'process raft request' (duration: 320.148298ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:30:49.392234Z","caller":"traceutil/trace.go:171","msg":"trace[1229851260] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"312.421177ms","start":"2026-07-15T14:30:49.0798Z","end":"2026-07-15T14:30:49.392221Z","steps":["trace[1229851260] 'process raft request' (duration: 312.225423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:49.392256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:49.071733Z","time spent":"320.464783ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T14:30:49.39234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:49.07978Z","time spent":"312.482897ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T14:30:49.392537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.492703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-15T14:30:49.392548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.185399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-15T14:30:49.392587Z","caller":"traceutil/trace.go:171","msg":"trace[750979001] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2171; }","duration":"121.577014ms","start":"2026-07-15T14:30:49.270995Z","end":"2026-07-15T14:30:49.392572Z","steps":["trace[750979001] 'agreement among raft nodes before linearized reading' (duration: 121.37034ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:30:49.392603Z","caller":"traceutil/trace.go:171","msg":"trace[1844394707] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2171; }","duration":"284.291971ms","start":"2026-07-15T14:30:49.108296Z","end":"2026-07-15T14:30:49.392588Z","steps":["trace[1844394707] 'agreement among raft nodes before linearized reading' (duration: 284.063067ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:30:49.516793Z","caller":"traceutil/trace.go:171","msg":"trace[412084892] linearizableReadLoop","detail":"{readStateIndex:2260; appliedIndex:2258; }","duration":"114.046497ms","start":"2026-07-15T14:30:49.402727Z","end":"2026-07-15T14:30:49.516773Z","steps":["trace[412084892] 'read index received' (duration: 24.261614ms)","trace[412084892] 'applied index is now lower than readState.Index' (duration: 89.783713ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:30:49.51688Z","caller":"traceutil/trace.go:171","msg":"trace[518318002] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"119.805952ms","start":"2026-07-15T14:30:49.397031Z","end":"2026-07-15T14:30:49.516837Z","steps":["trace[518318002] 'process raft request' (duration: 119.603488ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:30:49.517188Z","caller":"traceutil/trace.go:171","msg":"trace[1886270056] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"101.218192ms","start":"2026-07-15T14:30:49.415958Z","end":"2026-07-15T14:30:49.517176Z","steps":["trace[1886270056] 'process raft request' (duration: 100.783044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:49.517714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.963373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-07-15T14:30:49.517834Z","caller":"traceutil/trace.go:171","msg":"trace[761398567] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2174; }","duration":"115.125997ms","start":"2026-07-15T14:30:49.402693Z","end":"2026-07-15T14:30:49.517819Z","steps":["trace[761398567] 'agreement among raft nodes before linearized reading' (duration: 114.375013ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:30:50.261647Z","caller":"traceutil/trace.go:171","msg":"trace[454259860] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"209.492246ms","start":"2026-07-15T14:30:50.05213Z","end":"2026-07-15T14:30:50.261623Z","steps":["trace[454259860] 'process raft request' (duration: 197.970746ms)","trace[454259860] 'compare' (duration: 10.919019ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T14:30:50.749905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.798991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-15T14:30:50.749983Z","caller":"traceutil/trace.go:171","msg":"trace[1769551470] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2179; }","duration":"349.921513ms","start":"2026-07-15T14:30:50.400042Z","end":"2026-07-15T14:30:50.749963Z","steps":["trace[1769551470] 'range keys from in-memory index tree' (duration: 349.582637ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:30:50.750024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:30:50.400021Z","time spent":"349.993455ms","remote":"127.0.0.1:37624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-15T14:32:15.466885Z","caller":"traceutil/trace.go:171","msg":"trace[1854520199] linearizableReadLoop","detail":"{readStateIndex:2835; appliedIndex:2834; }","duration":"205.463297ms","start":"2026-07-15T14:32:15.261395Z","end":"2026-07-15T14:32:15.466858Z","steps":["trace[1854520199] 'read index received' (duration: 205.242973ms)","trace[1854520199] 'applied index is now lower than readState.Index' (duration: 219.364µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:32:15.466916Z","caller":"traceutil/trace.go:171","msg":"trace[1496467964] transaction","detail":"{read_only:false; response_revision:2729; number_of_response:1; }","duration":"249.062182ms","start":"2026-07-15T14:32:15.217836Z","end":"2026-07-15T14:32:15.466898Z","steps":["trace[1496467964] 'process raft request' (duration: 248.889199ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:32:15.46709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.67459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T14:32:15.46714Z","caller":"traceutil/trace.go:171","msg":"trace[637272672] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2729; }","duration":"205.780522ms","start":"2026-07-15T14:32:15.261349Z","end":"2026-07-15T14:32:15.46713Z","steps":["trace[637272672] 'agreement among raft nodes before linearized reading' (duration: 205.682711ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:32:47.998449Z","caller":"traceutil/trace.go:171","msg":"trace[848849045] transaction","detail":"{read_only:false; response_revision:2909; number_of_response:1; }","duration":"144.427386ms","start":"2026-07-15T14:32:47.853999Z","end":"2026-07-15T14:32:47.998426Z","steps":["trace[848849045] 'process raft request' (duration: 136.261153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:32:48.509638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.672222ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T14:32:48.509899Z","caller":"traceutil/trace.go:171","msg":"trace[337637181] linearizableReadLoop","detail":"{readStateIndex:3025; appliedIndex:3024; }","duration":"376.151736ms","start":"2026-07-15T14:32:48.13373Z","end":"2026-07-15T14:32:48.509882Z","steps":["trace[337637181] 'read index received' (duration: 245.20955ms)","trace[337637181] 'applied index is now lower than readState.Index' (duration: 130.940646ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:32:48.50993Z","caller":"traceutil/trace.go:171","msg":"trace[385016925] transaction","detail":"{read_only:false; response_revision:2912; number_of_response:1; }","duration":"455.938512ms","start":"2026-07-15T14:32:48.053968Z","end":"2026-07-15T14:32:48.509907Z","steps":["trace[385016925] 'process raft request' (duration: 324.917914ms)","trace[385016925] 'compare' (duration: 130.454039ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T14:32:48.510033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.302049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:32:48.510082Z","caller":"traceutil/trace.go:171","msg":"trace[650843206] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:2912; }","duration":"376.349999ms","start":"2026-07-15T14:32:48.133716Z","end":"2026-07-15T14:32:48.510066Z","steps":["trace[650843206] 'agreement among raft nodes before linearized reading' (duration: 376.257258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:32:48.510092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:32:48.053941Z","time spent":"456.046763ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T14:32:48.510138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:32:48.133653Z","time spent":"376.469631ms","remote":"127.0.0.1:37420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-15T14:32:48.510372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.727307ms","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-07-15T14:32:48.510405Z","caller":"traceutil/trace.go:171","msg":"trace[777240837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2912; }","duration":"333.791379ms","start":"2026-07-15T14:32:48.176605Z","end":"2026-07-15T14:32:48.510396Z","steps":["trace[777240837] 'agreement among raft nodes before linearized reading' (duration: 333.700967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:32:48.510432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:32:48.176588Z","time spent":"333.840309ms","remote":"127.0.0.1:50028","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-07-15T14:32:48.510477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.831979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-15T14:32:48.510522Z","caller":"traceutil/trace.go:171","msg":"trace[27399731] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2912; }","duration":"349.89157ms","start":"2026-07-15T14:32:48.160617Z","end":"2026-07-15T14:32:48.510509Z","steps":["trace[27399731] 'agreement among raft nodes before linearized reading' (duration: 349.807219ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:32:48.510576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.757108ms","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-07-15T14:32:48.510595Z","caller":"traceutil/trace.go:171","msg":"trace[2060283503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2912; }","duration":"333.786479ms","start":"2026-07-15T14:32:48.176803Z","end":"2026-07-15T14:32:48.510589Z","steps":["trace[2060283503] 'agreement among raft nodes before linearized reading' (duration: 333.738498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:32:48.510616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:32:48.176796Z","time spent":"333.817119ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-15T14:32:48.510395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.607045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c27d18de039527\" ","response":"range_response_count:1 size:781"} {"level":"warn","ts":"2026-07-15T14:32:48.510679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:32:48.160605Z","time spent":"349.982792ms","remote":"127.0.0.1:50028","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-07-15T14:32:48.510529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.42615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T14:32:48.510884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.874306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-15T14:32:48.510917Z","caller":"traceutil/trace.go:171","msg":"trace[1140565928] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:2912; }","duration":"318.836626ms","start":"2026-07-15T14:32:48.192064Z","end":"2026-07-15T14:32:48.510901Z","steps":["trace[1140565928] 'agreement among raft nodes before linearized reading' (duration: 318.441399ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:32:48.510695Z","caller":"traceutil/trace.go:171","msg":"trace[1305273405] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c27d18de039527; range_end:; response_count:1; response_revision:2912; }","duration":"357.948831ms","start":"2026-07-15T14:32:48.152741Z","end":"2026-07-15T14:32:48.51069Z","steps":["trace[1305273405] 'agreement among raft nodes before linearized reading' (duration: 357.547555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:32:48.511068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:32:48.192049Z","time spent":"318.989538ms","remote":"127.0.0.1:47086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-15T14:32:48.511128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:32:48.152725Z","time spent":"358.321027ms","remote":"127.0.0.1:49820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":804,"request content":"key:\"/registry/events/auth-system/keycloak-0.18c27d18de039527\" "} {"level":"info","ts":"2026-07-15T14:32:48.510985Z","caller":"traceutil/trace.go:171","msg":"trace[1677938841] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2912; }","duration":"335.037109ms","start":"2026-07-15T14:32:48.175924Z","end":"2026-07-15T14:32:48.510961Z","steps":["trace[1677938841] 'agreement among raft nodes before linearized reading' (duration: 334.456779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:32:48.511694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:32:48.175897Z","time spent":"335.78054ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-15T14:32:48.758706Z","caller":"traceutil/trace.go:171","msg":"trace[1512674871] transaction","detail":"{read_only:false; response_revision:2914; number_of_response:1; }","duration":"244.137644ms","start":"2026-07-15T14:32:48.51455Z","end":"2026-07-15T14:32:48.758688Z","steps":["trace[1512674871] 'process raft request' (duration: 240.737289ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:32:48.759Z","caller":"traceutil/trace.go:171","msg":"trace[1176195688] transaction","detail":"{read_only:false; response_revision:2917; number_of_response:1; }","duration":"242.67008ms","start":"2026-07-15T14:32:48.516241Z","end":"2026-07-15T14:32:48.758911Z","steps":["trace[1176195688] 'process raft request' (duration: 242.62576ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:32:48.759154Z","caller":"traceutil/trace.go:171","msg":"trace[1919752745] transaction","detail":"{read_only:false; response_revision:2916; number_of_response:1; }","duration":"242.792232ms","start":"2026-07-15T14:32:48.516133Z","end":"2026-07-15T14:32:48.758926Z","steps":["trace[1919752745] 'process raft request' (duration: 242.654179ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:32:48.75925Z","caller":"traceutil/trace.go:171","msg":"trace[2090257803] transaction","detail":"{read_only:false; response_revision:2915; number_of_response:1; }","duration":"242.867213ms","start":"2026-07-15T14:32:48.516071Z","end":"2026-07-15T14:32:48.758938Z","steps":["trace[2090257803] 'process raft request' (duration: 242.581158ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:33:42.475038Z","caller":"traceutil/trace.go:171","msg":"trace[1043534258] transaction","detail":"{read_only:false; response_revision:3193; number_of_response:1; }","duration":"176.324002ms","start":"2026-07-15T14:33:42.29869Z","end":"2026-07-15T14:33:42.475014Z","steps":["trace[1043534258] 'process raft request' (duration: 93.317582ms)","trace[1043534258] 'compare' (duration: 82.649774ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:33:42.478685Z","caller":"traceutil/trace.go:171","msg":"trace[781103217] transaction","detail":"{read_only:false; response_revision:3194; number_of_response:1; }","duration":"159.649098ms","start":"2026-07-15T14:33:42.319019Z","end":"2026-07-15T14:33:42.478668Z","steps":["trace[781103217] 'process raft request' (duration: 159.532827ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:33:42.478949Z","caller":"traceutil/trace.go:171","msg":"trace[572955985] transaction","detail":"{read_only:false; response_revision:3195; number_of_response:1; }","duration":"155.404712ms","start":"2026-07-15T14:33:42.323511Z","end":"2026-07-15T14:33:42.478916Z","steps":["trace[572955985] 'process raft request' (duration: 155.124038ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:34:08.49182Z","caller":"traceutil/trace.go:171","msg":"trace[341948677] transaction","detail":"{read_only:false; response_revision:3331; number_of_response:1; }","duration":"202.720377ms","start":"2026-07-15T14:34:08.289075Z","end":"2026-07-15T14:34:08.491796Z","steps":["trace[341948677] 'process raft request' (duration: 202.575802ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:34:09.46095Z","caller":"traceutil/trace.go:171","msg":"trace[1929468785] transaction","detail":"{read_only:false; response_revision:3339; number_of_response:1; }","duration":"139.483351ms","start":"2026-07-15T14:34:09.321439Z","end":"2026-07-15T14:34:09.460922Z","steps":["trace[1929468785] 'process raft request' (duration: 68.107285ms)","trace[1929468785] 'compare' (duration: 71.2431ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:34:09.461253Z","caller":"traceutil/trace.go:171","msg":"trace[204479388] transaction","detail":"{read_only:false; response_revision:3340; number_of_response:1; }","duration":"139.586744ms","start":"2026-07-15T14:34:09.321599Z","end":"2026-07-15T14:34:09.461186Z","steps":["trace[204479388] 'process raft request' (duration: 139.286602ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:35:50.091457Z","caller":"traceutil/trace.go:171","msg":"trace[1958994484] linearizableReadLoop","detail":"{readStateIndex:4108; appliedIndex:4107; }","duration":"115.711675ms","start":"2026-07-15T14:35:49.975718Z","end":"2026-07-15T14:35:50.091429Z","steps":["trace[1958994484] 'read index received' (duration: 97.048172ms)","trace[1958994484] 'applied index is now lower than readState.Index' (duration: 18.662483ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:35:50.091635Z","caller":"traceutil/trace.go:171","msg":"trace[496101095] transaction","detail":"{read_only:false; response_revision:3953; number_of_response:1; }","duration":"117.167073ms","start":"2026-07-15T14:35:49.97442Z","end":"2026-07-15T14:35:50.091587Z","steps":["trace[496101095] 'process raft request' (duration: 98.427097ms)","trace[496101095] 'compare' (duration: 18.480268ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T14:35:50.091757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.463154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-15T14:35:50.091804Z","caller":"traceutil/trace.go:171","msg":"trace[630454411] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3953; }","duration":"107.577727ms","start":"2026-07-15T14:35:49.984215Z","end":"2026-07-15T14:35:50.091792Z","steps":["trace[630454411] 'agreement among raft nodes before linearized reading' (duration: 107.415312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:35:50.091926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.15817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:35:50.092084Z","caller":"traceutil/trace.go:171","msg":"trace[14699252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3953; }","duration":"116.385168ms","start":"2026-07-15T14:35:49.975636Z","end":"2026-07-15T14:35:50.092021Z","steps":["trace[14699252] 'agreement among raft nodes before linearized reading' (duration: 115.983014ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:37:06.749515Z","caller":"traceutil/trace.go:171","msg":"trace[1802951853] transaction","detail":"{read_only:false; response_revision:4937; number_of_response:1; }","duration":"251.163836ms","start":"2026-07-15T14:37:06.498315Z","end":"2026-07-15T14:37:06.749479Z","steps":["trace[1802951853] 'process raft request' (duration: 250.884478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:37:12.379558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.697965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5221abc8-e85e-4652-bd31-3eaf59cc7a2c\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-15T14:37:12.379665Z","caller":"traceutil/trace.go:171","msg":"trace[1687657121] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5221abc8-e85e-4652-bd31-3eaf59cc7a2c; range_end:; response_count:1; response_revision:5053; }","duration":"140.847759ms","start":"2026-07-15T14:37:12.238794Z","end":"2026-07-15T14:37:12.379642Z","steps":["trace[1687657121] 'range keys from in-memory index tree' (duration: 140.558061ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:37:12.741825Z","caller":"traceutil/trace.go:171","msg":"trace[96781739] transaction","detail":"{read_only:false; response_revision:5054; number_of_response:1; }","duration":"359.388822ms","start":"2026-07-15T14:37:12.382416Z","end":"2026-07-15T14:37:12.741804Z","steps":["trace[96781739] 'process raft request' (duration: 359.244848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:37:12.741939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:37:12.382394Z","time spent":"359.485625ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T14:37:12.839337Z","caller":"traceutil/trace.go:171","msg":"trace[217351311] linearizableReadLoop","detail":"{readStateIndex:5253; appliedIndex:5251; }","duration":"336.434352ms","start":"2026-07-15T14:37:12.502879Z","end":"2026-07-15T14:37:12.839314Z","steps":["trace[217351311] 'read index received' (duration: 238.837656ms)","trace[217351311] 'applied index is now lower than readState.Index' (duration: 97.595346ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:37:12.83946Z","caller":"traceutil/trace.go:171","msg":"trace[640441171] transaction","detail":"{read_only:false; response_revision:5055; number_of_response:1; }","duration":"456.220516ms","start":"2026-07-15T14:37:12.383227Z","end":"2026-07-15T14:37:12.839448Z","steps":["trace[640441171] 'process raft request' (duration: 425.015799ms)","trace[640441171] 'compare' (duration: 29.691974ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T14:37:12.839562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:37:12.383167Z","time spent":"456.320169ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T14:37:12.839879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.976948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-15T14:37:12.842505Z","caller":"traceutil/trace.go:171","msg":"trace[480173153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5055; }","duration":"339.673805ms","start":"2026-07-15T14:37:12.502815Z","end":"2026-07-15T14:37:12.842489Z","steps":["trace[480173153] 'agreement among raft nodes before linearized reading' (duration: 336.945277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:37:12.842559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:37:12.502798Z","time spent":"339.747698ms","remote":"127.0.0.1:50028","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-07-15T14:37:13.678726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.977597ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T14:37:13.678898Z","caller":"traceutil/trace.go:171","msg":"trace[738268101] transaction","detail":"{read_only:false; response_revision:5062; number_of_response:1; }","duration":"414.230872ms","start":"2026-07-15T14:37:13.264654Z","end":"2026-07-15T14:37:13.678885Z","steps":["trace[738268101] 'process raft request' (duration: 414.153749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:37:13.678952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:37:13.264629Z","time spent":"414.299374ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T14:37:13.679078Z","caller":"traceutil/trace.go:171","msg":"trace[1348207656] linearizableReadLoop","detail":"{readStateIndex:5260; appliedIndex:5258; }","duration":"294.486254ms","start":"2026-07-15T14:37:13.384568Z","end":"2026-07-15T14:37:13.679054Z","steps":["trace[1348207656] 'read index received' (duration: 52.110566ms)","trace[1348207656] 'applied index is now lower than readState.Index' (duration: 242.373748ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:37:13.679152Z","caller":"traceutil/trace.go:171","msg":"trace[2093625697] transaction","detail":"{read_only:false; response_revision:5063; number_of_response:1; }","duration":"168.076915ms","start":"2026-07-15T14:37:13.511068Z","end":"2026-07-15T14:37:13.679145Z","steps":["trace[2093625697] 'process raft request' (duration: 167.780657ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:37:13.679133Z","caller":"traceutil/trace.go:171","msg":"trace[1354613403] transaction","detail":"{read_only:false; response_revision:5061; number_of_response:1; }","duration":"424.153116ms","start":"2026-07-15T14:37:13.254959Z","end":"2026-07-15T14:37:13.679112Z","steps":["trace[1354613403] 'process raft request' (duration: 181.717446ms)","trace[1354613403] 'compare' (duration: 241.780851ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T14:37:13.679275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:37:13.25493Z","time spent":"424.29314ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T14:37:13.679586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.004579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5221abc8-e85e-4652-bd31-3eaf59cc7a2c\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-07-15T14:37:13.679716Z","caller":"traceutil/trace.go:171","msg":"trace[1613335322] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5221abc8-e85e-4652-bd31-3eaf59cc7a2c; range_end:; response_count:1; response_revision:5063; }","duration":"295.163483ms","start":"2026-07-15T14:37:13.384535Z","end":"2026-07-15T14:37:13.679698Z","steps":["trace[1613335322] 'agreement among raft nodes before linearized reading' (duration: 294.741801ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:37:13.80847Z","caller":"traceutil/trace.go:171","msg":"trace[1827259617] linearizableReadLoop","detail":"{readStateIndex:5262; appliedIndex:5261; }","duration":"129.283561ms","start":"2026-07-15T14:37:13.679169Z","end":"2026-07-15T14:37:13.808452Z","steps":["trace[1827259617] 'read index received' (duration: 125.668967ms)","trace[1827259617] 'applied index is now lower than readState.Index' (duration: 3.613914ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:37:13.808599Z","caller":"traceutil/trace.go:171","msg":"trace[442856649] transaction","detail":"{read_only:false; response_revision:5064; number_of_response:1; }","duration":"198.250031ms","start":"2026-07-15T14:37:13.610318Z","end":"2026-07-15T14:37:13.808568Z","steps":["trace[442856649] 'process raft request' (duration: 194.568936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:37:13.808654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.956261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:37:13.808691Z","caller":"traceutil/trace.go:171","msg":"trace[950110095] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:5064; }","duration":"331.041374ms","start":"2026-07-15T14:37:13.477638Z","end":"2026-07-15T14:37:13.80868Z","steps":["trace[950110095] 'agreement among raft nodes before linearized reading' (duration: 330.91848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:37:13.808723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:37:13.477623Z","time spent":"331.089485ms","remote":"127.0.0.1:49994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-07-15T14:37:17.460638Z","caller":"traceutil/trace.go:171","msg":"trace[2053575324] transaction","detail":"{read_only:false; response_revision:5129; number_of_response:1; }","duration":"268.474771ms","start":"2026-07-15T14:37:17.192133Z","end":"2026-07-15T14:37:17.460607Z","steps":["trace[2053575324] 'process raft request' (duration: 268.183503ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:37:23.637389Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2781} {"level":"info","ts":"2026-07-15T14:37:23.707361Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2781,"took":"68.91855ms","hash":3808497291,"current-db-size-bytes":51806208,"current-db-size":"52 MB","current-db-size-in-use-bytes":27131904,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-15T14:37:23.707414Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3808497291,"revision":2781,"compact-revision":-1} {"level":"info","ts":"2026-07-15T14:37:33.409342Z","caller":"traceutil/trace.go:171","msg":"trace[823111858] linearizableReadLoop","detail":"{readStateIndex:5488; appliedIndex:5487; }","duration":"468.942302ms","start":"2026-07-15T14:37:32.940377Z","end":"2026-07-15T14:37:33.409319Z","steps":["trace[823111858] 'read index received' (duration: 468.733926ms)","trace[823111858] 'applied index is now lower than readState.Index' (duration: 207.456µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:37:33.4095Z","caller":"traceutil/trace.go:171","msg":"trace[1614374588] transaction","detail":"{read_only:false; response_revision:5282; number_of_response:1; }","duration":"574.239242ms","start":"2026-07-15T14:37:32.835243Z","end":"2026-07-15T14:37:33.409482Z","steps":["trace[1614374588] 'process raft request' (duration: 573.892403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:37:33.40962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:37:32.835229Z","time spent":"574.305774ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T14:37:33.409693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.088388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-15T14:37:33.409772Z","caller":"traceutil/trace.go:171","msg":"trace[518808570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5282; }","duration":"458.226872ms","start":"2026-07-15T14:37:32.951529Z","end":"2026-07-15T14:37:33.409756Z","steps":["trace[518808570] 'agreement among raft nodes before linearized reading' (duration: 458.034557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:37:33.409805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:37:32.951498Z","time spent":"458.299954ms","remote":"127.0.0.1:50028","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-07-15T14:37:33.409859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.534703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T14:37:33.409916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.526122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-15T14:37:33.409943Z","caller":"traceutil/trace.go:171","msg":"trace[2126929383] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5282; }","duration":"433.692887ms","start":"2026-07-15T14:37:32.976229Z","end":"2026-07-15T14:37:33.409921Z","steps":["trace[2126929383] 'agreement among raft nodes before linearized reading' (duration: 433.519772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:37:33.409947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.558339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-15T14:37:33.409984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:37:32.976202Z","time spent":"433.775689ms","remote":"127.0.0.1:49760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-15T14:37:33.409983Z","caller":"traceutil/trace.go:171","msg":"trace[1054506634] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5282; }","duration":"457.667657ms","start":"2026-07-15T14:37:32.952296Z","end":"2026-07-15T14:37:33.409964Z","steps":["trace[1054506634] 'agreement among raft nodes before linearized reading' (duration: 457.474091ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:37:33.410006Z","caller":"traceutil/trace.go:171","msg":"trace[1217914848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5282; }","duration":"469.651911ms","start":"2026-07-15T14:37:32.940338Z","end":"2026-07-15T14:37:33.40999Z","steps":["trace[1217914848] 'agreement among raft nodes before linearized reading' (duration: 469.499887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:37:33.410032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:37:32.952274Z","time spent":"457.744878ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-15T14:37:33.410052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:37:32.940317Z","time spent":"469.724693ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-15T14:37:33.410228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.81274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-15T14:37:33.410296Z","caller":"traceutil/trace.go:171","msg":"trace[905516286] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5282; }","duration":"258.005775ms","start":"2026-07-15T14:37:33.15227Z","end":"2026-07-15T14:37:33.410275Z","steps":["trace[905516286] 'agreement among raft nodes before linearized reading' (duration: 257.736138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:37:33.410464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.394332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16552"} {"level":"info","ts":"2026-07-15T14:37:33.410507Z","caller":"traceutil/trace.go:171","msg":"trace[499950436] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5282; }","duration":"279.469235ms","start":"2026-07-15T14:37:33.131027Z","end":"2026-07-15T14:37:33.410496Z","steps":["trace[499950436] 'agreement among raft nodes before linearized reading' (duration: 279.342751ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:37:33.585103Z","caller":"traceutil/trace.go:171","msg":"trace[1182319967] transaction","detail":"{read_only:false; response_revision:5284; number_of_response:1; }","duration":"166.498254ms","start":"2026-07-15T14:37:33.418578Z","end":"2026-07-15T14:37:33.585077Z","steps":["trace[1182319967] 'process raft request' (duration: 97.086668ms)","trace[1182319967] 'compare' (duration: 69.081527ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:37:33.585118Z","caller":"traceutil/trace.go:171","msg":"trace[1419463408] transaction","detail":"{read_only:false; response_revision:5285; number_of_response:1; }","duration":"166.434012ms","start":"2026-07-15T14:37:33.418654Z","end":"2026-07-15T14:37:33.585088Z","steps":["trace[1419463408] 'process raft request' (duration: 166.280658ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:37:33.585287Z","caller":"traceutil/trace.go:171","msg":"trace[185900470] linearizableReadLoop","detail":"{readStateIndex:5492; appliedIndex:5489; }","duration":"149.305485ms","start":"2026-07-15T14:37:33.435971Z","end":"2026-07-15T14:37:33.585277Z","steps":["trace[185900470] 'read index received' (duration: 79.527518ms)","trace[185900470] 'applied index is now lower than readState.Index' (duration: 69.776936ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T14:37:33.585556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.565252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-07a80f29-1bb6-4d4d-a97c-d5f267b19f54\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-07-15T14:37:33.5856Z","caller":"traceutil/trace.go:171","msg":"trace[725997279] range","detail":"{range_begin:/registry/persistentvolumes/pvc-07a80f29-1bb6-4d4d-a97c-d5f267b19f54; range_end:; response_count:1; response_revision:5286; }","duration":"149.648834ms","start":"2026-07-15T14:37:33.435938Z","end":"2026-07-15T14:37:33.585587Z","steps":["trace[725997279] 'agreement among raft nodes before linearized reading' (duration: 149.376887ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:37:33.585758Z","caller":"traceutil/trace.go:171","msg":"trace[1925190651] transaction","detail":"{read_only:false; response_revision:5286; number_of_response:1; }","duration":"161.504745ms","start":"2026-07-15T14:37:33.424244Z","end":"2026-07-15T14:37:33.585749Z","steps":["trace[1925190651] 'process raft request' (duration: 160.764324ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:38:16.249824Z","caller":"traceutil/trace.go:171","msg":"trace[645859848] transaction","detail":"{read_only:false; response_revision:5641; number_of_response:1; }","duration":"145.954624ms","start":"2026-07-15T14:38:16.103844Z","end":"2026-07-15T14:38:16.249799Z","steps":["trace[645859848] 'process raft request' (duration: 145.511892ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:38:16.443524Z","caller":"traceutil/trace.go:171","msg":"trace[1340377926] transaction","detail":"{read_only:false; response_revision:5643; number_of_response:1; }","duration":"101.377179ms","start":"2026-07-15T14:38:16.342077Z","end":"2026-07-15T14:38:16.443454Z","steps":["trace[1340377926] 'process raft request' (duration: 101.205475ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:41:10.792446Z","caller":"traceutil/trace.go:171","msg":"trace[1548763025] transaction","detail":"{read_only:false; response_revision:6858; number_of_response:1; }","duration":"124.776964ms","start":"2026-07-15T14:41:10.667645Z","end":"2026-07-15T14:41:10.792422Z","steps":["trace[1548763025] 'process raft request' (duration: 124.663021ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:41:10.820798Z","caller":"traceutil/trace.go:171","msg":"trace[2091851710] transaction","detail":"{read_only:false; response_revision:6859; number_of_response:1; }","duration":"152.738373ms","start":"2026-07-15T14:41:10.668038Z","end":"2026-07-15T14:41:10.820776Z","steps":["trace[2091851710] 'process raft request' (duration: 152.259882ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:41:10.820978Z","caller":"traceutil/trace.go:171","msg":"trace[1633258565] transaction","detail":"{read_only:false; response_revision:6860; number_of_response:1; }","duration":"151.181778ms","start":"2026-07-15T14:41:10.66979Z","end":"2026-07-15T14:41:10.820972Z","steps":["trace[1633258565] 'process raft request' (duration: 150.609455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:41:22.859089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.755701ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T14:41:22.859274Z","caller":"traceutil/trace.go:171","msg":"trace[1248804409] transaction","detail":"{read_only:false; response_revision:6922; number_of_response:1; }","duration":"113.512252ms","start":"2026-07-15T14:41:22.745744Z","end":"2026-07-15T14:41:22.859256Z","steps":["trace[1248804409] 'process raft request' (duration: 113.459281ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:41:22.859304Z","caller":"traceutil/trace.go:171","msg":"trace[447353377] transaction","detail":"{read_only:false; response_revision:6921; number_of_response:1; }","duration":"114.118475ms","start":"2026-07-15T14:41:22.74516Z","end":"2026-07-15T14:41:22.859278Z","steps":["trace[447353377] 'process raft request' (duration: 13.101618ms)","trace[447353377] 'compare' (duration: 100.618899ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:41:23.05071Z","caller":"traceutil/trace.go:171","msg":"trace[1666607337] transaction","detail":"{read_only:false; response_revision:6926; number_of_response:1; }","duration":"110.815412ms","start":"2026-07-15T14:41:22.93985Z","end":"2026-07-15T14:41:23.050666Z","steps":["trace[1666607337] 'process raft request' (duration: 26.160284ms)","trace[1666607337] 'compare' (duration: 84.427313ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:41:35.224575Z","caller":"traceutil/trace.go:171","msg":"trace[2110007004] transaction","detail":"{read_only:false; response_revision:7041; number_of_response:1; }","duration":"111.069886ms","start":"2026-07-15T14:41:35.113485Z","end":"2026-07-15T14:41:35.224555Z","steps":["trace[2110007004] 'process raft request' (duration: 110.80531ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:41:41.161314Z","caller":"traceutil/trace.go:171","msg":"trace[431183572] transaction","detail":"{read_only:false; response_revision:7068; number_of_response:1; }","duration":"198.137392ms","start":"2026-07-15T14:41:40.963138Z","end":"2026-07-15T14:41:41.161276Z","steps":["trace[431183572] 'process raft request' (duration: 197.993339ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:41:41.387177Z","caller":"traceutil/trace.go:171","msg":"trace[1147601576] linearizableReadLoop","detail":"{readStateIndex:7337; appliedIndex:7333; }","duration":"344.371249ms","start":"2026-07-15T14:41:41.04279Z","end":"2026-07-15T14:41:41.387161Z","steps":["trace[1147601576] 'read index received' (duration: 118.379064ms)","trace[1147601576] 'applied index is now lower than readState.Index' (duration: 225.985815ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T14:41:41.389781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.967215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-15T14:41:41.390066Z","caller":"traceutil/trace.go:171","msg":"trace[1748136184] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7072; }","duration":"347.346073ms","start":"2026-07-15T14:41:41.042686Z","end":"2026-07-15T14:41:41.390033Z","steps":["trace[1748136184] 'agreement among raft nodes before linearized reading' (duration: 346.506415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:41:41.390123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:41:41.042668Z","time spent":"347.436814ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-15T14:41:41.390196Z","caller":"traceutil/trace.go:171","msg":"trace[1941340904] transaction","detail":"{read_only:false; response_revision:7069; number_of_response:1; }","duration":"426.286643ms","start":"2026-07-15T14:41:40.963875Z","end":"2026-07-15T14:41:41.390162Z","steps":["trace[1941340904] 'process raft request' (duration: 419.197399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:41:41.401918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:41:40.963825Z","time spent":"437.881424ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T14:41:41.402087Z","caller":"traceutil/trace.go:171","msg":"trace[114821897] transaction","detail":"{read_only:false; response_revision:7070; number_of_response:1; }","duration":"425.25599ms","start":"2026-07-15T14:41:40.9768Z","end":"2026-07-15T14:41:41.402056Z","steps":["trace[114821897] 'process raft request' (duration: 410.220305ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:41:41.390221Z","caller":"traceutil/trace.go:171","msg":"trace[2031282408] transaction","detail":"{read_only:false; response_revision:7071; number_of_response:1; }","duration":"406.278729ms","start":"2026-07-15T14:41:40.983897Z","end":"2026-07-15T14:41:41.390176Z","steps":["trace[2031282408] 'process raft request' (duration: 403.166062ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:41:41.402229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:41:40.983877Z","time spent":"418.319481ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T14:41:41.402281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:41:40.976773Z","time spent":"425.368383ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T14:41:41.390465Z","caller":"traceutil/trace.go:171","msg":"trace[1975725048] transaction","detail":"{read_only:false; response_revision:7072; number_of_response:1; }","duration":"288.212732ms","start":"2026-07-15T14:41:41.102237Z","end":"2026-07-15T14:41:41.39045Z","steps":["trace[1975725048] 'process raft request' (duration: 284.8852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:41:41.40238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:41:41.102217Z","time spent":"300.144691ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T14:41:41.390754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.623907ms","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-07-15T14:41:41.402461Z","caller":"traceutil/trace.go:171","msg":"trace[959293589] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7072; }","duration":"147.364912ms","start":"2026-07-15T14:41:41.255082Z","end":"2026-07-15T14:41:41.402447Z","steps":["trace[959293589] 'agreement among raft nodes before linearized reading' (duration: 135.26959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:41:41.399992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.946122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-15T14:41:41.402514Z","caller":"traceutil/trace.go:171","msg":"trace[646379673] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7072; }","duration":"149.519298ms","start":"2026-07-15T14:41:41.25299Z","end":"2026-07-15T14:41:41.402509Z","steps":["trace[646379673] 'agreement among raft nodes before linearized reading' (duration: 136.328562ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:42:03.709499Z","caller":"traceutil/trace.go:171","msg":"trace[1694913687] linearizableReadLoop","detail":"{readStateIndex:7465; appliedIndex:7463; }","duration":"101.889285ms","start":"2026-07-15T14:42:03.607588Z","end":"2026-07-15T14:42:03.709478Z","steps":["trace[1694913687] 'read index received' (duration: 44.680924ms)","trace[1694913687] 'applied index is now lower than readState.Index' (duration: 57.207241ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T14:42:03.709688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.082349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-15T14:42:03.709573Z","caller":"traceutil/trace.go:171","msg":"trace[1790715891] transaction","detail":"{read_only:false; response_revision:7194; number_of_response:1; }","duration":"102.017808ms","start":"2026-07-15T14:42:03.607512Z","end":"2026-07-15T14:42:03.70953Z","steps":["trace[1790715891] 'process raft request' (duration: 101.892245ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:42:03.709719Z","caller":"traceutil/trace.go:171","msg":"trace[1433900093] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7194; }","duration":"102.14611ms","start":"2026-07-15T14:42:03.607562Z","end":"2026-07-15T14:42:03.709709Z","steps":["trace[1433900093] 'agreement among raft nodes before linearized reading' (duration: 102.019488ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:42:23.643269Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5215} {"level":"info","ts":"2026-07-15T14:42:23.734381Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5215,"took":"89.458706ms","hash":1300553278,"current-db-size-bytes":51806208,"current-db-size":"52 MB","current-db-size-in-use-bytes":18808832,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-07-15T14:42:23.73444Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1300553278,"revision":5215,"compact-revision":2781}