Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.12.2022 07:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14665792 kB
MemAvailable 30103444 kB
Buffers 0 kB
Cached 17038536 kB
SwapCached 3292 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-12-21 07:37:20
DTS_END                         2022-12-21 07:37:20.8190
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51950
TRN_ID                          226458
ATT_ID                          601
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9022.39 24937 1082687
customer order: creation 173.24 461 20789
customer order: refuse 14.68 401 1761
order to supplier: creation 46.04 1348 5525
order to supplier: removal 3.39 156809 407
invoice (draft): creation 46.31 2963 5557
invoice (draft): removal 51.02 923 6122
invoice accept: apply 41.54 23125 4985
invoice accept: cancel 46.32 1308 5558
customer reserve: creation 3804.68 220 456562
customer reserve: removal 4494.43 93 539331
realization accept: apply 84.05 276 10086
realization accept: cancel 84.81 156 10177
payment from customer: creation 60.15 168 7218
payment from customer: removal 6.08 143 729
payment to supplier: creation 49.36 192 5923
payment to supplier: removal 3.58 140 429
service: total inventory turnovers 3.98 4094 478
service: total monetary turnovers 7.88 356 945
service: aggregate perf. data 0.84 18519 101
service: refresh index statistics 0.03 287048 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-12-2022 07:38:45 to 21-12-2022 07:38:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9291
TEST_TIME 2 6742
TEST_TIME 3 6713
TEST_TIME 4 6150
TEST_TIME 5 6235
TEST_TIME 6 6373
TEST_TIME 7 6294
TEST_TIME 8 6497
TEST_TIME 9 6607
TEST_TIME 10 6585
TEST_TIME 11 6840
TEST_TIME 12 7221
TEST_TIME 13 7131
TEST_TIME 14 7169
TEST_TIME 15 7400
TEST_TIME 16 7529
TEST_TIME 17 7726
TEST_TIME 18 7878
TEST_TIME 19 8300
TEST_TIME 20 8444
TEST_TIME 21 8628
TEST_TIME 22 8940
TEST_TIME 23 8840
TEST_TIME 24 8788
TEST_TIME 25 9015
TEST_TIME 26 9428
TEST_TIME 27 9539
TEST_TIME 28 9458
TEST_TIME 29 9662
TEST_TIME 30 10116
TEST_TIME 31 10162
TEST_TIME 32 10544
TEST_TIME 33 10508
TEST_TIME 34 10936
TEST_TIME 35 11185
TEST_TIME 36 10978
TEST_TIME 37 10931
TEST_TIME 38 10766
TEST_TIME 39 10577
TEST_TIME 40 10773
TEST_TIME 41 10638
TEST_TIME 42 10520
TEST_TIME 43 10472
TEST_TIME 44 10656
TEST_TIME 45 10548
TEST_TIME 46 10678
TEST_TIME 47 10472
TEST_TIME 48 10314
TEST_TIME 49 10205
TEST_TIME 50 10172
TEST_TIME 51 10049
TEST_TIME 52 10057
TEST_TIME 53 9950
TEST_TIME 54 9858
TEST_TIME 55 9834
TEST_TIME 56 9989
TEST_TIME 57 9918
TEST_TIME 58 9872
TEST_TIME 59 9807
TEST_TIME 60 9746
TEST_TIME 61 9653
TEST_TIME 62 9550
TEST_TIME 63 9552
TEST_TIME 64 9474
TEST_TIME 65 9389
TEST_TIME 66 9413
TEST_TIME 67 9470
TEST_TIME 68 9392
TEST_TIME 69 9390
TEST_TIME 70 9413
TEST_TIME 71 9425
TEST_TIME 72 9513
TEST_TIME 73 9561
TEST_TIME 74 9555
TEST_TIME 75 9566
TEST_TIME 76 9712
TEST_TIME 77 9717
TEST_TIME 78 9719
TEST_TIME 79 9714
TEST_TIME 80 9755
TEST_TIME 81 9697
TEST_TIME 82 9682
TEST_TIME 83 9662
TEST_TIME 84 9572
TEST_TIME 85 9571
TEST_TIME 86 9525
TEST_TIME 87 9502
TEST_TIME 88 9444
TEST_TIME 89 9392
TEST_TIME 90 9400
TEST_TIME 91 9404
TEST_TIME 92 9380
TEST_TIME 93 9399
TEST_TIME 94 9372
TEST_TIME 95 9344
TEST_TIME 96 9387
TEST_TIME 97 9443
TEST_TIME 98 9519
TEST_TIME 99 9467
TEST_TIME 100 9480
TEST_TIME 101 9487
TEST_TIME 102 9550
TEST_TIME 103 9495
TEST_TIME 104 9446
TEST_TIME 105 9396
TEST_TIME 106 9365
TEST_TIME 107 9346
TEST_TIME 108 9305
TEST_TIME 109 9269
TEST_TIME 110 9258
TEST_TIME 111 9208
TEST_TIME 112 9181
TEST_TIME 113 9174
TEST_TIME 114 9141
TEST_TIME 115 9108
TEST_TIME 116 9095
TEST_TIME 117 9122
TEST_TIME 118 9115
TEST_TIME 119 9084
TEST_TIME 120 9050

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-12-2022 07:38:49 to 21-12-2022 07:38:52.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1506098 1506098 0 0.00 0 15300 12 0 0
doc_list_biud 1506088 1506088 0 0.00 0 42436 39 0 0
sp_add_invoice_to_stock 5151 4985 166 3.22 28 179630 23126 0 166
sp_cancel_adding_invoice 5558 5558 0 0.00 6 107260 1309 0 0
sp_cancel_client_order 1761 1761 0 0.00 6 10325 402 0 0
sp_cancel_customer_reserve 539331 539331 0 0.00 1 42473 94 0 0
sp_cancel_pay_from_customer 729 729 0 0.00 3 5870 144 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 4 3410 140 0 0
sp_cancel_supplier_invoice 6128 6122 6 0.09 3 17777 924 0 6
sp_cancel_supplier_order 407 407 0 0.00 12 1062284 156810 0 0
sp_cancel_write_off 10177 10177 0 0.00 2 14151 157 0 0
sp_client_order 20789 20789 0 0.00 6 40494 462 0 0
sp_customer_reserve 456562 456562 0 0.00 0 81948 221 0 0
sp_fill_shopping_cart 31853 31853 0 0.00 1 10929 87 0 0
sp_fill_shopping_cart_clo_res 456532 456532 0 0.00 0 14974 16 0 0
sp_get_clo_for_invoice 4940 4940 0 0.00 0 15631 288 0 0
sp_kill_cost_storno 16893 16893 0 0.00 0 12443 70 0 0
sp_kill_qstorno_ret_qs2qd 1095368 1095368 0 0.00 0 42331 41 0 0
sp_kill_qty_storno 578382 578382 0 0.00 0 42434 94 0 0
sp_lock_selected_doc 20908 20908 0 0.00 0 6544 7 0 0
sp_make_cost_storno 28167 28167 0 0.00 0 12078 77 0 0
sp_multiply_rows_for_pdistr 28167 28167 0 0.00 0 8113 39 0 0
sp_multiply_rows_for_qdistr 20789 20789 0 0.00 0 19494 177 0 0
sp_pay_from_customer 7218 7218 0 0.00 5 13953 169 0 0
sp_pay_to_supplier 5923 5923 0 0.00 6 15679 192 0 0
sp_payment_common 13141 13141 0 0.00 2 10293 47 0 0
sp_qd_handle_on_cancel_clo 1761 1761 0 0.00 0 10318 335 0 0
sp_qd_handle_on_invoice_upd_sts 10498 10498 0 0.00 0 29707 516 0 0
sp_qd_handle_on_reserve_upd_sts 10086 10086 0 0.00 0 13956 59 0 0
sp_reserve_write_off 10086 10086 0 0.00 8 17120 277 0 0
sp_supplier_invoice 5557 5557 0 0.00 9 82201 2963 0 0
sp_supplier_order 5525 5525 0 0.00 7 38288 1349 0 0
srv_aggregate_perf_data 101 101 0 0.00 2415 183448 18520 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 761 119259 35969 0 0
srv_find_qd_qs_mism 1035102 1035102 0 0.00 0 15299 9 0 0
srv_make_invnt_saldo 478 478 0 0.00 48 64119 4094 0 0
srv_make_money_saldo 945 945 0 0.00 1 12287 357 0 0
srv_recalc_idx_stat 4 4 0 0.00 167011 403018 287048 0 0
t$perf-abend:sp_add_invoice_to_stock 166 166 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4989 4989 0 0.00 1 23932 934 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 1 6298 519 0 0
t$perf-norm:sp_cancel_client_order 1761 1761 0 0.00 0 1502 27 0 0
t$perf-norm:sp_cancel_customer_reserve 716 716 0 0.00 0 3296 37 0 0
t$perf-norm:sp_cancel_pay_from_customer 729 729 0 0.00 0 1113 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 300 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 2665 40 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 98435 7268 0 0
t$perf-norm:sp_cancel_write_off 1020 1020 0 0.00 0 5618 23 0 0
t$perf-norm:sp_client_order 20789 20789 0 0.00 0 15704 38 0 0
t$perf-norm:sp_customer_reserve 1036 1036 0 0.00 0 8240 27 0 0
t$perf-norm:sp_pay_from_customer 7218 7218 0 0.00 0 11593 25 0 0
t$perf-norm:sp_pay_to_supplier 5923 5923 0 0.00 0 18112 28 0 0
t$perf-norm:sp_reserve_write_off 10086 10086 0 0.00 0 19610 26 0 0
t$perf-norm:sp_supplier_invoice 5557 5557 0 0.00 0 6283 33 0 0
t$perf-norm:sp_supplier_order 5525 5525 0 0.00 0 19199 41 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 280 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 245 11 0 0
t$perf-norm:srv_make_invnt_saldo 478 478 0 0.00 0 606 12 0 0
t$perf-norm:srv_make_money_saldo 945 945 0 0.00 0 21097 29 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 199 62 0 0
v_add_invoice_to_stock 10202 9870 332 3.25 0 8844 26 0 332
v_all_customers 37970 37970 0 0.00 0 90 4 0 0
v_all_suppliers 30880 30880 0 0.00 0 173 4 0 0
v_all_wares 41564 41564 0 0.00 0 89 4 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 198 13 0 0
v_cancel_client_order 3522 3522 0 0.00 0 2125 10 0 0
v_cancel_customer_prepayment 1458 1458 0 0.00 0 1701 14 0 0
v_cancel_customer_reserve 1432 1432 0 0.00 0 1377 13 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 606 11 0 0
v_cancel_supplier_invoice 818 806 12 1.46 0 5056 32 0 12
v_cancel_supplier_order 814 814 0 0.00 0 1298 14 0 0
v_cancel_write_off 2040 2040 0 0.00 0 855 21 0 0
v_max_id_clo_ord 5518 5518 0 0.00 0 776 5 0 0
v_max_id_clo_res 1035 1035 0 0.00 0 1340 9 0 0
v_max_id_ord_sup 5551 5551 0 0.00 0 8516 11 0 0
v_max_non_paid_invoice 1551 1551 0 0.00 0 1897 9 0 0
v_max_non_paid_realizn 3792 3792 0 0.00 0 4129 9 0 0
v_min_id_clo_ord 5518 5518 0 0.00 0 9379 14 0 0
v_min_id_clo_res 1035 1035 0 0.00 0 3184 13 0 0
v_min_id_ord_sup 5551 5551 0 0.00 0 3763 9 0 0
v_min_non_paid_invoice 5923 5923 0 0.00 0 10279 16 0 0
v_min_non_paid_realizn 7218 7218 0 0.00 0 8263 17 0 0
v_our_firm 10444 10444 0 0.00 0 77 5 0 0
v_reserve_write_off 20171 20171 0 0.00 0 4601 11 0 0
x_lock_depdocs_on_canc_invoice 11277 11277 0 0.00 0 30009 141 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 2727 347 0 0
x_make_qstorno_1000_1200 5525 5525 0 0.00 2 38220 1168 0 0
x_make_qstorno_1200_2000 5557 5557 0 0.00 4 75998 2725 0 0
x_make_qstorno_2100_3300 456562 456562 0 0.00 0 81925 192 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-12-2022 07:38:53 to 21-12-2022 07:38:57.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:24 8 8192000000 8804120240 8817401856 7817584 0.095 4 1 2 0 7920592 67232 35776
05:08:26 15109 8192000000 9718186560 10017509376 802634336 8.923 100 87 175 0 831234352 7382752 21217264
05:09:44 15195 8192000000 9762898832 10071277568 838221664 9.282 100 88 176 0 870423664 10923136 21278864
05:11:07 14946 8192000000 9803114080 10118926336 871800064 9.618 100 87 172 0 908964032 17355056 19808912
05:12:27 8845 8192000000 9863639552 10195480576 903946512 9.937 100 75 148 0 964863104 44997216 15919376
05:13:42 6059 8192000000 9907123424 10252619776 944517072 10.337 100 81 159 1 1026588032 65590192 16480768
05:14:51 2624 8192000000 9943312368 10297999360 981511088 10.699 100 84 165 0 1064243024 65328288 17403648
05:15:56 811 8192000000 9979026800 10336342016 1012136560 10.996 100 95 185 0 1099580304 69205248 18238496
05:17:00 880 8192000000 10005211872 10376601600 1036744656 11.233 100 97 186 1 1127722320 72313824 18663840
05:18:02 904 8192000000 10033207824 10408747008 1053228976 11.392 100 92 179 0 1155242624 84119696 17893952
05:19:04 3509 8192000000 10024710096 10414784512 1065098000 11.505 100 97 186 5 1156829440 71647632 20083808
05:20:15 934 8192000000 10050805920 10429833216 1074578032 11.596 100 90 175 1 1174016256 81652256 17785968
05:21:18 1209 8192000000 10035311952 10421055488 1079127024 11.639 100 81 158 2 1163745168 68063024 16555120
05:22:21 1018 8192000000 10051842848 10440876032 1083264672 11.679 100 95 177 2 1174596800 73210752 18121376
05:23:23 1381 8192000000 10044153840 10428895232 1087628000 11.720 100 96 185 2 1171610240 64096528 19885712
05:24:25 1242 8192000000 10075315232 10463215616 1093022048 11.771 100 98 186 0 1198485216 86937056 18526112
05:25:27 1243 8192000000 10073005760 10458984448 1096781664 11.807 100 92 169 1 1190612128 76819328 17011136
05:26:29 1036 8192000000 10068132896 10457944064 1095955184 11.799 100 94 190 1 1193401072 78810752 18635136
05:27:34 1175 8192000000 10058992304 10448990208 1095420592 11.794 100 96 187 0 1179560704 65312816 18827296
05:28:35 1151 8192000000 10063926208 10446032896 1085621088 11.701 100 98 185 4 1190888304 84941008 20326208
05:29:38 1204 8192000000 10047114928 10433994752 1073978592 11.590 99 85 159 2 1170877888 80549360 16349936
05:30:40 1256 8192000000 10046166848 10430291968 1079571984 11.643 100 95 183 2 1169434880 71153504 18709392
05:31:42 1270 8192000000 10008443296 10393681920 1058047792 11.438 99 88 163 0 1130535056 53499056 18988208
05:32:45 963 8192000000 9987608320 10359992320 1040918320 11.273 100 94 176 2 1110591840 51843216 17830304
05:33:47 1177 8192000000 9972551280 10342821888 1029958560 11.168 100 89 165 1 1095702400 46777648 18966192
05:34:49 1144 8192000000 9990871600 10362159104 1041515008 11.279 99 92 174 0 1113828928 55464336 16849584
05:35:51 1001 8192000000 9984208480 10364030976 1042306016 11.287 100 75 137 1 1108482784 51467904 14708864
05:36:52 3562 8192000000 9967679024 10350968832 1045805072 11.320 100 53 99 1 1088899120 32653440 10440608
05:38:04 6576 8192000000 10003063280 10389737472 1050614624 11.367 100 56 108 1 1109177104 47708944 10853536
05:39:16 1704 8192000000 10021690496 10403135488 1067717600 11.530 100 79 149 1 1144934880 62003392 15213888
05:40:24 3699 8192000000 10054387792 10441252864 1082252608 11.669 100 67 123 3 1172415232 76682032 13480592
05:41:33 14300 8192000000 10076854560 10471796736 1092790960 11.769 100 68 136 0 1193751184 86570224 14390000
05:42:53 1109 8192000000 10112037040 10505076736 1107216192 11.906 100 73 136 3 1228388672 106255136 14917344
05:43:58 933 8192000000 10149220272 10550878208 1119954528 12.027 100 95 174 0 1271449184 129727328 21767328
05:45:02 1477 8192000000 10179634160 10579595264 1127458144 12.097 100 84 163 0 1297564720 152494272 17612304
05:46:07 848 8192000000 10203241216 10599194624 1143695024 12.250 100 79 150 2 1317242288 157835328 15711936
05:47:14 1501 8192000000 10218922960 10617757696 1163431312 12.435 100 82 160 1 1336801200 156139888 17230000
05:48:18 1028 8192000000 10295027792 10696466432 1175485792 12.548 100 95 182 3 1407533712 212922032 19125888
05:49:21 1317 8192000000 10317050512 10723311616 1177807184 12.570 100 94 185 1 1429477056 231281008 20388864
05:50:25 2070 8192000000 10332592864 10736123904 1176329984 12.556 100 88 160 1 1440950160 247222704 17397472
05:51:31 2384 8192000000 10355637696 10785128448 1181399152 12.603 100 85 163 0 1467715344 267113776 19202416
05:52:37 1358 8192000000 10371943824 10781122560 1175966336 12.553 100 91 173 0 1478099328 282784656 19348336
05:53:41 1238 8192000000 10386165280 10801639424 1178920512 12.580 100 94 182 1 1493736368 294260816 20555040
05:54:46 920 8192000000 10417803744 10850267136 1180487552 12.595 100 95 184 0 1525157952 324262704 20407696
05:55:51 900 8192000000 10372503376 10825781248 1182472352 12.613 100 90 178 0 1481539216 275773680 23293184
05:56:53 943 8192000000 10411735888 10815983616 1171081520 12.507 100 95 179 1 1515552448 322816832 21654096
05:57:57 2405 8192000000 10393422928 10828443648 1171646752 12.512 100 61 114 1 1501170624 315559696 13964176
05:59:16 4751 8192000000 10317258048 10733088768 1155596528 12.362 99 61 119 0 1425535312 255249680 14689104
06:00:25 3824 8192000000 10391725712 10815082496 1173041184 12.525 100 70 136 1 1500809344 310971952 16796208
06:01:35 5235 8192000000 10404451776 10852380672 1173551488 12.530 100 74 146 0 1512286528 320656336 18078704
06:02:43 750 8192000000 10422591024 10873679872 1172664768 12.522 100 91 178 1 1526494944 332599232 21230944
06:03:48 957 8192000000 10376990944 10816942080 1162238992 12.424 100 74 143 1 1479684880 299411296 18034592
06:04:52 10171 8192000000 10387527568 10837585920 1160884864 12.412 100 43 86 0 1506264912 333382576 11997472
06:06:22 9207 8192000000 10389149552 10855620608 1167609936 12.474 100 46 92 1 1494979488 315803344 11566208
06:07:39 32782 8192000000 10328180320 10782650368 1158967776 12.394 100 26 52 1 1428134208 262368496 6797936
06:09:17 38948 8192000000 10259613776 10742919168 1137979408 12.197 99 33 64 0 1354373376 207388176 9005792
06:11:10 22054 8192000000 10233988832 10735124480 1136028272 12.178 100 18 36 0 1326287248 185570704 4688272
06:12:42 49818 8192000000 10067011888 10496172032 1135336544 12.172 100 28 55 1 1169526304 28356928 5832832
06:14:33 95945 8192000000 10125724064 10574655488 1136670976 12.184 99 7 14 0 1185172816 46990032 1511808
06:17:20 73149 8192000000 10062128288 10503094272 1117149536 12.000 97 23 44 0 1158308752 36737776 4421440
06:19:53 13073 8192000000 10141049648 10583982080 1161652192 12.419 100 28 45 0 1235297216 68328080 5316944
06:21:07 104024 8192000000 10120922096 10604707840 1122992592 12.055 97 12 24 0 1201095648 75488080 2614976
06:23:52 91309 8192000000 10061913920 10493108224 1106988160 11.904 97 42 72 1 1139040544 24691024 7361360
06:26:25 37051 8192000000 10054236336 10502467584 1060914864 11.465 94 52 99 0 1087953744 15785568 11253312
06:28:09 49890 8192000000 10045355408 10490707968 1075870896 11.608 96 13 26 0 1117959024 39526864 2561264
06:30:04 38728 8192000000 10114851040 10555547648 1142284432 12.237 100 19 38 0 1202141344 55417216 4439696
06:31:50 44570 8192000000 10185573744 10640236544 1152549872 12.333 100 47 89 0 1273466032 111339648 9576512
06:35:12 104269 8192000000 10078438256 10530672640 1080758224 11.655 94 28 50 1 1124324720 39100560 4465936
06:38:34 105171 8192000000 10067128144 10566725632 1117426672 12.003 98 13 26 0 1173048512 53105088 2516752
06:42:44 65987 8192000000 10084849952 10555076608 1120153600 12.028 98 12 24 0 1161981872 39395088 2433184
06:44:50 30642 8192000000 10068471744 10507653120 1101739776 11.854 97 24 45 0 1148423168 40718176 5965216
06:46:38 32783 8192000000 10164203776 10622500864 1144135552 12.254 100 22 44 0 1258711488 109685760 4890176
06:48:32 41059 8192000000 10105883520 10593226752 1120802992 12.035 99 22 45 0 1227999728 102082256 5114480
06:50:14 70078 8192000000 10145205552 10592043008 1125117984 12.075 99 24 48 0 1239328784 109075680 5135120
06:52:27 42705 8192000000 10182515072 10626379776 1127446688 12.097 99 16 32 0 1257603152 126457312 3699152
06:54:23 16650 8192000000 10111997296 10543738880 1138373664 12.200 100 43 83 0 1206654736 60044256 8236816
06:56:34 64442 8192000000 10093195552 10554114048 1114141456 11.972 98 38 76 0 1176723072 55303472 7278144
06:58:42 119253 8192000000 10051958640 10504892416 1102797024 11.864 98 26 52 0 1207925776 99606416 5522336
07:01:47 10260 8192000000 10069994096 10519900160 1108109696 11.915 99 26 50 0 1164986448 51589648 5287104
07:02:58 52604 8192000000 10063919024 10537340928 1096996992 11.809 98 13 24 0 1168145568 68485344 2663232
07:05:05 58660 8192000000 10095466592 10542964736 1120911760 12.036 100 16 32 0 1200607744 75997072 3698912
07:07:04 63078 8192000000 10084508000 10560761856 1094231584 11.783 97 16 31 0 1181251888 83378208 3642096
07:09:50 55972 8192000000 10133269088 10578653184 1112238160 11.954 98 29 56 0 1224028496 105460528 6329808
07:11:46 41394 8192000000 10140673392 10581303296 1118095504 12.009 98 36 68 0 1221643968 95690240 7858224
07:13:28 110537 8192000000 10129252608 10623950848 1094097056 11.782 96 20 40 1 1185895248 87538848 4259344
07:16:23 11808 8192000000 10093510704 10519937024 1110495504 11.937 98 47 92 0 1152292336 32483664 9313168
07:17:38 15817 8192000000 10103214544 10536218624 1107576080 11.909 98 48 96 0 1170841424 53316864 9948480
07:18:54 79340 8192000000 10089815856 10604109824 1088103728 11.725 97 21 40 0 1163093504 71290848 3698928
07:21:14 100867 8192000000 10053326944 10492014592 1113062192 11.961 99 30 54 3 1145596064 26899152 5634720
07:23:55 54856 8192000000 10038220272 10493710336 1116946704 11.998 99 17 33 0 1168202912 48175744 3080464
07:25:54 75074 8192000000 10079227792 10557669376 1111798016 11.949 98 12 26 0 1154299728 39915616 2586096
07:28:37 83690 8192000000 10074828160 10558062592 1106659376 11.901 98 19 36 0 1155407984 44931376 3817232
07:31:07 54705 8192000000 10058197616 10523729920 1121867712 12.045 100 11 22 0 1153747632 29633344 2246576
07:33:02 43027 8192000000 10074614608 10538266624 1107809120 11.912 98 17 34 1 1163703936 52069488 3825328
07:34:47 36274 8192000000 10096568048 10564284416 1127276832 12.096 99 32 63 0 1197780336 63631488 6872016
07:36:28 31710 8192000000 10163123056 10606952448 1133405920 12.153 99 40 79 1 1238641552 97050064 8185568

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 21-12-2022 07:38:59 to 21-12-2022 07:39:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 166 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-12-2022 07:39:16 to 21-12-2022 07:39:18.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          223275
MON$OLDEST_ACTIVE               227486
MON$OLDEST_SNAPSHOT             227486
MON$NEXT_TRANSACTION            227486
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2075488
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             784
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:282: 
:283: mscs-test-161.budzdorov.ru    Wed Dec 21 07:37:26 2022
:284:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:285: 
:286: 
:287: mscs-test-161.budzdorov.ru    Wed Dec 21 07:37:32 2022
:288:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:289: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp40 according to config parameter 'remove_isql_logs'

21.12.22 07:39:19. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-12-21 07:39:22 No severe PSQL-related problems occured
21.12.22 07:39:23. End of report.