Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.04.2022 13: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 15778528 kB
MemAvailable 31554996 kB
Buffers 0 kB
Cached 15938448 kB
SwapCached 2528 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.2
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-04-24 13:37:17
DTS_END                         2022-04-24 13:37:17.1400
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48532
TRN_ID                          222766
ATT_ID                          562
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: 9162.02 33484 1099442
customer order: creation 169.91 489 20389
customer order: refuse 14.54 493 1745
order to supplier: creation 46.09 1553 5531
order to supplier: removal 3.42 186888 410
invoice (draft): creation 45.43 3519 5451
invoice (draft): removal 51.39 1081 6167
invoice accept: apply 43.03 27954 5163
invoice accept: cancel 45.88 1409 5506
customer reserve: creation 3959.24 263 475109
customer reserve: removal 4485.50 111 538260
realization accept: apply 83.39 332 10007
realization accept: cancel 84.23 190 10107
payment from customer: creation 59.40 210 7128
payment from customer: removal 5.63 154 675
payment to supplier: creation 48.73 232 5847
payment to supplier: removal 3.49 139 419
service: total inventory turnovers 3.71 5258 445
service: total monetary turnovers 8.17 422 980
service: aggregate perf. data 0.82 20760 98
service: refresh index statistics 0.04 418223 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-04-2022 13:38:45 to 24-04-2022 13:38:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10864
TEST_TIME 2 7786
TEST_TIME 3 7770
TEST_TIME 4 7305
TEST_TIME 5 7013
TEST_TIME 6 6987
TEST_TIME 7 7001
TEST_TIME 8 7291
TEST_TIME 9 7263
TEST_TIME 10 7406
TEST_TIME 11 7773
TEST_TIME 12 7569
TEST_TIME 13 7645
TEST_TIME 14 7726
TEST_TIME 15 7595
TEST_TIME 16 7634
TEST_TIME 17 7766
TEST_TIME 18 7803
TEST_TIME 19 7736
TEST_TIME 20 7884
TEST_TIME 21 7848
TEST_TIME 22 7905
TEST_TIME 23 7874
TEST_TIME 24 8002
TEST_TIME 25 7988
TEST_TIME 26 7997
TEST_TIME 27 8209
TEST_TIME 28 8283
TEST_TIME 29 8535
TEST_TIME 30 8768
TEST_TIME 31 8923
TEST_TIME 32 8933
TEST_TIME 33 9052
TEST_TIME 34 9278
TEST_TIME 35 9526
TEST_TIME 36 9818
TEST_TIME 37 10089
TEST_TIME 38 10302
TEST_TIME 39 10367
TEST_TIME 40 10608
TEST_TIME 41 10400
TEST_TIME 42 10403
TEST_TIME 43 10458
TEST_TIME 44 10581
TEST_TIME 45 10760
TEST_TIME 46 10637
TEST_TIME 47 10518
TEST_TIME 48 10351
TEST_TIME 49 10320
TEST_TIME 50 10463
TEST_TIME 51 10469
TEST_TIME 52 10361
TEST_TIME 53 10295
TEST_TIME 54 10211
TEST_TIME 55 10233
TEST_TIME 56 10213
TEST_TIME 57 10114
TEST_TIME 58 10055
TEST_TIME 59 9966
TEST_TIME 60 9880
TEST_TIME 61 9814
TEST_TIME 62 9758
TEST_TIME 63 9731
TEST_TIME 64 9618
TEST_TIME 65 9574
TEST_TIME 66 9582
TEST_TIME 67 9518
TEST_TIME 68 9533
TEST_TIME 69 9573
TEST_TIME 70 9515
TEST_TIME 71 9457
TEST_TIME 72 9482
TEST_TIME 73 9440
TEST_TIME 74 9416
TEST_TIME 75 9363
TEST_TIME 76 9357
TEST_TIME 77 9379
TEST_TIME 78 9313
TEST_TIME 79 9298
TEST_TIME 80 9395
TEST_TIME 81 9373
TEST_TIME 82 9323
TEST_TIME 83 9310
TEST_TIME 84 9309
TEST_TIME 85 9317
TEST_TIME 86 9474
TEST_TIME 87 9531
TEST_TIME 88 9493
TEST_TIME 89 9483
TEST_TIME 90 9385
TEST_TIME 91 9412
TEST_TIME 92 9364
TEST_TIME 93 9311
TEST_TIME 94 9322
TEST_TIME 95 9286
TEST_TIME 96 9266
TEST_TIME 97 9246
TEST_TIME 98 9190
TEST_TIME 99 9200
TEST_TIME 100 9175
TEST_TIME 101 9197
TEST_TIME 102 9167
TEST_TIME 103 9149
TEST_TIME 104 9142
TEST_TIME 105 9128
TEST_TIME 106 9262
TEST_TIME 107 9275
TEST_TIME 108 9286
TEST_TIME 109 9317
TEST_TIME 110 9319
TEST_TIME 111 9347
TEST_TIME 112 9350
TEST_TIME 113 9353
TEST_TIME 114 9389
TEST_TIME 115 9358
TEST_TIME 116 9290
TEST_TIME 117 9286
TEST_TIME 118 9266
TEST_TIME 119 9265
TEST_TIME 120 9200

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-04-2022 13:38:49 to 24-04-2022 13: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 1534784 1534784 0 0.00 0 25196 15 0 0
doc_list_biud 1534759 1534759 0 0.00 0 36749 46 0 0
sp_add_invoice_to_stock 5319 5163 156 2.93 37 163883 27954 0 156
sp_cancel_adding_invoice 5506 5506 0 0.00 6 73629 1410 0 0
sp_cancel_client_order 1745 1745 0 0.00 6 12110 494 0 0
sp_cancel_customer_reserve 538260 538260 0 0.00 1 36323 112 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 3 4708 155 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 4 2390 140 0 0
sp_cancel_supplier_invoice 6191 6167 24 0.38 3 13923 1082 0 24
sp_cancel_supplier_order 410 410 0 0.00 16 1801481 186888 0 0
sp_cancel_write_off 10107 10107 0 0.00 2 14052 191 0 0
sp_client_order 20389 20389 0 0.00 6 36216 489 0 0
sp_customer_reserve 475109 475109 0 0.00 0 63979 264 0 0
sp_fill_shopping_cart 31367 31367 0 0.00 2 12940 94 0 0
sp_fill_shopping_cart_clo_res 475062 475062 0 0.00 0 17472 18 0 0
sp_get_clo_for_invoice 5100 5100 0 0.00 0 25971 268 0 0
sp_kill_cost_storno 16707 16707 0 0.00 0 9377 86 0 0
sp_kill_qstorno_ret_qs2qd 1093204 1093204 0 0.00 0 31734 48 0 0
sp_kill_qty_storno 577298 577298 0 0.00 0 36744 111 0 0
sp_lock_selected_doc 20906 20906 0 0.00 0 10016 8 0 0
sp_make_cost_storno 28082 28082 0 0.00 0 22135 99 0 0
sp_multiply_rows_for_pdistr 28077 28077 0 0.00 0 7453 41 0 0
sp_multiply_rows_for_qdistr 20388 20388 0 0.00 0 20678 157 0 0
sp_pay_from_customer 7128 7128 0 0.00 6 29197 211 0 0
sp_pay_to_supplier 5847 5847 0 0.00 7 14748 233 0 0
sp_payment_common 12974 12974 0 0.00 2 16190 56 0 0
sp_qd_handle_on_cancel_clo 1745 1745 0 0.00 0 8586 390 0 0
sp_qd_handle_on_invoice_upd_sts 10605 10605 0 0.00 0 36741 625 0 0
sp_qd_handle_on_reserve_upd_sts 10004 10004 0 0.00 0 25175 81 0 0
sp_reserve_write_off 10007 10007 0 0.00 7 25393 333 0 0
sp_supplier_invoice 5451 5451 0 0.00 8 86702 3520 0 0
sp_supplier_order 5531 5531 0 0.00 8 48510 1553 0 0
srv_aggregate_perf_data 98 98 0 0.00 3487 203790 20760 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 735 141083 31553 0 0
srv_find_qd_qs_mism 1049019 1049019 0 0.00 0 13310 11 0 0
srv_make_invnt_saldo 445 445 0 0.00 38 63008 5258 0 0
srv_make_money_saldo 980 980 0 0.00 1 11487 422 0 0
srv_recalc_idx_stat 5 5 0 0.00 294071 614830 418223 0 0
t$perf-abend:sp_add_invoice_to_stock 156 156 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 24 24 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5167 5167 0 0.00 1 11442 1127 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 1 7331 544 0 0
t$perf-norm:sp_cancel_client_order 1745 1745 0 0.00 0 3251 31 0 0
t$perf-norm:sp_cancel_customer_reserve 694 694 0 0.00 0 3175 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 564 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 2142 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 444 444 0 0.00 0 825 44 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 1 79694 8484 0 0
t$perf-norm:sp_cancel_write_off 1001 1001 0 0.00 0 1304 23 0 0
t$perf-norm:sp_client_order 20390 20390 0 0.00 0 11487 40 0 0
t$perf-norm:sp_customer_reserve 955 955 0 0.00 0 1786 19 0 0
t$perf-norm:sp_pay_from_customer 7128 7128 0 0.00 0 6416 24 0 0
t$perf-norm:sp_pay_to_supplier 5847 5847 0 0.00 0 6100 20 0 0
t$perf-norm:sp_reserve_write_off 10007 10007 0 0.00 0 4890 26 0 0
t$perf-norm:sp_supplier_invoice 5451 5451 0 0.00 0 2406 35 0 0
t$perf-norm:sp_supplier_order 5532 5532 0 0.00 0 3892 38 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 1123 32 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 270 11 0 0
t$perf-norm:srv_make_invnt_saldo 445 445 0 0.00 0 655 17 0 0
t$perf-norm:srv_make_money_saldo 980 980 0 0.00 0 3232 10 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 450 110 0 0
v_add_invoice_to_stock 10506 10194 312 2.96 0 4657 30 0 312
v_all_customers 37666 37666 0 0.00 0 114 4 0 0
v_all_suppliers 30421 30421 0 0.00 0 65 5 0 0
v_all_wares 40776 40776 0 0.00 0 194 4 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 1523 17 0 0
v_cancel_client_order 3490 3490 0 0.00 0 5622 15 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 4510 19 0 0
v_cancel_customer_reserve 1388 1388 0 0.00 1 8137 20 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 2322 17 0 0
v_cancel_supplier_invoice 936 888 48 5.12 0 2285 30 0 48
v_cancel_supplier_order 820 820 0 0.00 0 1144 13 0 0
v_cancel_write_off 2002 2002 0 0.00 0 1531 28 0 0
v_max_id_clo_ord 5530 5530 0 0.00 0 775 6 0 0
v_max_id_clo_res 955 955 0 0.00 0 411 9 0 0
v_max_id_ord_sup 5448 5448 0 0.00 0 3961 7 0 0
v_max_non_paid_invoice 1613 1613 0 0.00 0 1653 10 0 0
v_max_non_paid_realizn 3593 3593 0 0.00 0 2129 9 0 0
v_min_id_clo_ord 5530 5530 0 0.00 0 2170 10 0 0
v_min_id_clo_res 955 955 0 0.00 0 8659 31 0 0
v_min_id_ord_sup 5448 5448 0 0.00 0 6071 12 0 0
v_min_non_paid_invoice 5846 5846 0 0.00 0 7427 25 0 0
v_min_non_paid_realizn 7126 7126 0 0.00 0 10078 22 0 0
v_our_firm 10176 10176 0 0.00 0 59 5 0 0
v_reserve_write_off 20008 20008 0 0.00 0 5612 14 0 0
x_lock_depdocs_on_canc_invoice 11229 11229 0 0.00 0 18304 129 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 11847 479 0 0
x_make_qstorno_1000_1200 5531 5531 0 0.00 3 48463 1347 0 0
x_make_qstorno_1200_2000 5451 5451 0 0.00 4 86372 3271 0 0
x_make_qstorno_2100_3300 475106 475106 0 0.00 0 63971 231 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 24-04-2022 13:38:53 to 24-04-2022 13:38:58.

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
11:07:20 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:22 35321 8192000000 9732696768 10031505408 805573952 8.953 100 92 184 0 835580544 8262016 21744576
11:10:00 35641 8192000000 9781446256 10092208128 848318272 9.383 100 90 180 0 884314320 15042656 20953392
11:11:48 12771 8192000000 9823758976 10148413440 875861952 9.658 100 79 158 0 933114016 39253776 17998288
11:13:06 9243 8192000000 9879679808 10213761024 921611472 10.112 100 83 162 0 994482096 55103776 17766848
11:14:19 5332 8192000000 9923500464 10269347840 967368528 10.561 100 83 163 0 1039257088 54478400 17410160
11:15:26 1623 8192000000 9969456624 10322989056 998336880 10.862 100 84 165 0 1083027872 67970160 16720832
11:16:30 935 8192000000 9997772976 10363187200 1022823072 11.099 100 96 188 2 1116588896 75151072 18614752
11:17:33 1542 8192000000 10005786832 10374991872 1042355152 11.287 100 92 180 0 1126536080 66849712 17331216
11:18:37 1071 8192000000 10023295008 10401693696 1064448368 11.499 100 93 176 1 1145960512 63725840 17786304
11:19:41 1001 8192000000 10051692592 10432716800 1073696416 11.587 100 95 186 0 1171162560 78132944 19333200
11:20:43 1091 8192000000 10049546720 10435047424 1073192656 11.583 100 84 165 1 1172879504 83145312 16541536
11:21:46 1001 8192000000 10064003808 10449711104 1083431040 11.680 100 96 185 1 1182160864 80339968 18389856
11:22:49 1120 8192000000 10063385584 10446827520 1083264832 11.679 100 92 169 1 1185756064 85964400 16526832
11:23:52 997 8192000000 10040867808 10429243392 1086656528 11.711 100 89 178 1 1159755680 55176752 17922400
11:24:54 1101 8192000000 10053785968 10444169216 1091051424 11.753 100 94 179 3 1172844112 63546256 18246432
11:25:56 1209 8192000000 10054170048 10451017728 1101037536 11.847 100 77 147 0 1179368032 63648208 14682288
11:26:58 4692 8192000000 10059560704 10456776704 1100541616 11.843 100 65 127 1 1175146304 61554240 13050448
11:28:04 1660 8192000000 10067235616 10470612992 1099753952 11.835 100 72 136 3 1182439248 69297968 13387328
11:29:06 3786 8192000000 10033463088 10439651328 1101913472 11.856 100 54 96 2 1159954224 48197360 9843392
11:30:11 1151 8192000000 10030911728 10421231616 1085539936 11.700 100 79 154 1 1154962208 53959856 15462416
11:31:13 1101 8192000000 10027709760 10407370752 1062824480 11.484 100 92 178 2 1151243328 71032032 17386816
11:32:15 1600 8192000000 9997091728 10379866112 1049029952 11.351 99 82 161 1 1120653328 55567920 16055456
11:33:19 4933 8192000000 10002430336 10380480512 1056097616 11.419 100 83 152 0 1127653328 55434832 16120880
11:34:24 3638 8192000000 9979998352 10351562752 1018521584 11.058 98 80 152 3 1097045648 62829568 15694496
11:35:29 1611 8192000000 9977710912 10347999232 1022025984 11.092 98 81 148 2 1097055856 61156768 13873104
11:36:32 830 8192000000 9998060496 10375450624 1034387392 11.211 99 81 156 5 1119602128 69736224 15478512
11:37:37 13669 8192000000 10018122608 10393161728 1044939488 11.312 100 76 145 1 1144215504 84871600 14404416
11:38:58 1304 8192000000 10038065232 10415919104 1068841024 11.541 100 95 170 2 1158860608 71521776 18497808
11:40:04 7935 8192000000 10043417632 10428788736 1070282048 11.555 100 79 151 1 1165113376 79217008 15614320
11:41:15 6024 8192000000 10073163136 10457473024 1083293936 11.679 100 78 153 0 1190753312 90702880 16756496
11:42:26 3851 8192000000 10078760128 10472603648 1094277056 11.783 100 66 121 1 1194230304 87034192 12919056
11:43:35 1382 8192000000 10110255120 10504876032 1103352560 11.869 100 87 168 0 1229562784 108868592 17341632
11:44:40 5139 8192000000 10130171888 10519322624 1102844048 11.865 100 82 162 0 1247065616 127385744 16835824
11:45:51 1011 8192000000 10139633600 10542448640 1115157696 11.981 100 87 170 1 1252181152 118501648 18521808
11:46:56 1204 8192000000 10188822608 10584391680 1122537968 12.051 100 97 181 1 1300010000 156942080 20529952
11:48:04 2061 8192000000 10203468560 10611466240 1145641312 12.269 100 89 174 4 1310211456 146777584 17792560
11:49:13 4516 8192000000 10200361600 10600534016 1155129056 12.358 100 72 145 0 1313511792 142620752 15761984
11:50:23 1839 8192000000 10216830576 10627989504 1170026640 12.497 100 92 170 2 1320958928 132464832 18467456
11:51:28 2037 8192000000 10251931456 10654781440 1176297248 12.556 100 91 180 0 1366941280 171327760 19316272
11:52:35 967 8192000000 10288696096 10689515520 1190289344 12.686 100 88 174 0 1400165760 191271424 18604992
11:53:38 1222 8192000000 10315956352 10739085312 1194828528 12.728 100 94 181 4 1425288672 210434096 20026048
11:54:43 1008 8192000000 10354933728 10760769536 1196812032 12.747 100 97 183 0 1466877792 249390400 20675360
11:55:47 1543 8192000000 10374728256 10778550272 1191828560 12.700 100 94 180 1 1477603360 265093648 20681152
11:56:53 1321 8192000000 10432139312 10840522752 1202299856 12.798 100 95 187 0 1538889968 315112384 21477728
11:57:59 1288 8192000000 10437172160 10845855744 1208935248 12.859 100 97 185 2 1543526000 312755280 21835472
11:59:03 1639 8192000000 10470156976 10889289728 1209066992 12.860 100 94 184 0 1576721968 346507968 21147008
12:00:08 1439 8192000000 10477392976 10894761984 1196804224 12.747 100 97 183 1 1581885632 361835232 23246176
12:01:13 1939 8192000000 10523510224 10959482880 1189875952 12.682 100 86 170 0 1630300400 419178912 21245536
12:02:18 824 8192000000 10539433568 10946658304 1172670720 12.522 100 96 185 0 1642966400 446995136 23300544
12:03:24 1211 8192000000 10574745248 11007336448 1183358352 12.622 100 90 178 0 1679907392 474078064 22470976
12:04:28 870 8192000000 10567190032 11048140800 1184689680 12.634 100 86 161 0 1670776096 466341008 19745408
12:05:32 728 8192000000 10575370080 11009101824 1191522880 12.698 100 92 182 0 1677833232 464417840 21892512
12:06:37 2275 8192000000 10529432432 10982891520 1176028960 12.553 100 72 139 1 1638522384 445075680 17417744
12:07:43 13197 8192000000 10543752720 10978050048 1178733504 12.578 100 47 94 0 1646413216 454945776 12733936
12:08:59 4865 8192000000 10519345696 10968735744 1178988208 12.581 100 51 101 0 1623274288 432108096 12177984
12:10:27 5872 8192000000 10500712320 10951225344 1174875808 12.542 100 71 136 1 1605648208 413381920 17390480
12:11:35 2898 8192000000 10484829664 10932334592 1173238768 12.527 100 58 114 1 1589302064 401645376 14417920
12:12:40 14904 8192000000 10431902640 10914656256 1157032880 12.375 99 38 75 1 1545293952 377840096 10420976
12:14:03 18348 8192000000 10379818800 10879512576 1147924176 12.290 99 37 75 1 1463999072 306646352 9428544
12:15:39 51501 8192000000 10304373376 10784100352 1155748496 12.363 100 28 54 1 1442360064 279893424 6718144
12:17:39 41018 8192000000 10208057552 10639167488 1140993344 12.225 99 57 105 3 1304885072 151484928 12406800
12:19:21 10928 8192000000 10258147536 10697506816 1164196672 12.443 100 36 72 1 1355309856 182944096 8169088
12:20:38 61139 8192000000 10197521920 10757083136 1161008544 12.413 100 15 30 0 1378914144 213907968 3997632
12:22:41 111024 8192000000 10106867888 10569224192 1143802848 12.251 99 19 37 1 1189559440 42012992 3743600
12:26:40 102267 8192000000 10117510592 10616369152 1141220112 12.227 99 12 23 1 1247933680 104171136 2542432
12:29:23 85978 8192000000 10089055200 10535362560 1096712016 11.806 95 18 37 0 1156343248 55520096 4111136
12:31:52 141083 8192000000 10076913520 10540093440 1118787728 12.016 98 16 31 2 1186484048 64339552 3356768
12:35:53 97950 8192000000 10076198656 10545811456 1135497568 12.173 99 27 51 0 1202449504 61628000 5323936
12:38:32 21915 8192000000 10077294288 10495864832 1119199392 12.019 99 56 110 2 1168625328 36854384 12571552
12:40:29 49772 8192000000 10038209152 10473476096 1117466144 12.003 99 34 66 1 1152512368 28090672 6955552
12:42:36 9933 8192000000 10101598720 10518392832 1106044016 11.895 99 65 125 0 1179462688 60374896 13043776
12:43:50 26406 8192000000 10087648464 10516697088 1106051888 11.895 98 37 68 0 1173786288 60028336 7706064
12:45:20 50595 8192000000 10083393776 10544484352 1094302144 11.784 98 47 91 2 1152335792 48280336 9753312
12:47:18 66418 8192000000 10031369664 10475458560 1050441728 11.365 94 14 27 0 1071496896 18041888 3013280
12:49:31 28805 8192000000 10030943216 10508361728 1083352640 11.679 99 13 25 1 1158144240 72284016 2507584
12:51:01 54223 8192000000 10049775792 10500608000 1096808512 11.807 99 12 24 1 1138728352 39023936 2895904
12:52:59 16478 8192000000 10045068752 10478374912 1110335616 11.936 100 28 54 1 1138764848 23672288 4756944
12:54:18 45838 8192000000 10054523440 10501992448 1123837584 12.063 100 19 38 3 1176162160 48269872 4054704
12:56:09 29846 8192000000 10103830816 10541318144 1085055680 11.696 97 33 64 1 1169903872 78336000 6512192
12:57:40 61308 8192000000 10095955264 10547154944 1106394512 11.898 98 16 32 0 1173492064 63699600 3397952
12:59:44 41305 8192000000 10168833568 10603487232 1112168496 11.953 98 32 64 0 1240538768 120871232 7499040
13:01:31 36861 8192000000 10194899904 10622349312 1130962480 12.130 100 31 61 2 1273075840 134532496 7580864
13:03:09 68541 8192000000 10124985504 10678018048 1125395536 12.078 100 11 22 0 1231507520 103339280 2772704
13:05:20 38562 8192000000 10103160176 10533089280 1106464688 11.899 98 31 60 0 1167993264 54436592 7091984
13:07:00 113338 8192000000 10045216080 10481389568 1102266288 11.859 98 25 50 0 1169735872 62111184 5358400
13:10:19 19766 8192000000 10102723008 10538872832 1135985728 12.178 100 40 80 1 1211316208 67497728 7832752
13:11:41 54603 8192000000 10090980816 10533212160 1118542768 12.013 99 23 46 1 1191746496 68140448 5063280
13:14:08 65141 8192000000 10110485264 10581237760 1122355824 12.049 99 21 41 1 1199058304 71965120 4737360
13:16:13 50302 8192000000 10071012832 10578239488 1115703536 11.986 99 11 22 1 1180729600 62508064 2518000
13:18:09 53999 8192000000 10106155952 10567012352 1121819392 12.044 99 27 54 0 1190611664 63087056 5705216
13:20:05 42219 8192000000 10151513440 10584915968 1113039024 11.961 97 28 56 1 1211065456 91904592 6121840
13:21:51 34157 8192000000 10196580608 10625929216 1128252384 12.105 98 34 66 0 1253517760 118078800 7186576
13:23:36 50670 8192000000 10104649488 10608009216 1134034896 12.159 100 12 24 0 1246374448 109112144 3227408
13:25:29 16507 8192000000 10094958256 10527715328 1113226832 11.963 98 54 105 1 1174851488 50972336 10652320
13:26:47 58372 8192000000 10091190624 10516258816 1106471648 11.899 98 40 80 0 1160293600 46159280 7662672
13:28:46 47795 8192000000 10102950752 10567221248 1121924576 12.045 99 21 42 0 1189607680 63361392 4321712
13:30:39 84722 8192000000 10069241360 10541608960 1072287472 11.574 95 16 32 0 1132899136 57563904 3047760
13:33:07 98556 8192000000 10069380944 10553425920 1102953600 11.866 98 27 54 1 1169330592 60563024 5813968
13:35:59 32841 8192000000 10052328784 10534100992 1110329088 11.936 99 18 36 0 1150754336 36684288 3740960

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 24-04-2022 13:38:59 to 24-04-2022 13:39:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 156 v_add_invoice_to_stock 335544517
except 24 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 24-04-2022 13:39:17 to 24-04-2022 13: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          222416
MON$OLDEST_ACTIVE               223312
MON$OLDEST_SNAPSHOT             223312
MON$NEXT_TRANSACTION            223312
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                       2135040
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             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:596: 
:597: mscs-test-161.budzdorov.ru    Sun Apr 24 13:37:25 2022
:598:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:599: 
--- end of diff output ---

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

24.04.22 13:39:20. 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-04-24 13:39:22 No severe PSQL-related problems occured
24.04.22 13:39:23. End of report.