Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.06.2022 10:37
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 15713784 kB
MemAvailable 31254592 kB
Buffers 0 kB
Cached 15959860 kB
SwapCached 2448 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-06-14 10:37:18
DTS_END                         2022-06-14 10:37:18.5240
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/34710
TRN_ID                          214813
ATT_ID                          633
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: 9210.01 26778 1105201
customer order: creation 167.37 494 20084
customer order: refuse 14.13 488 1696
order to supplier: creation 43.73 1428 5248
order to supplier: removal 3.53 195231 424
invoice (draft): creation 45.29 3257 5435
invoice (draft): removal 51.79 1190 6215
invoice accept: apply 41.21 30256 4945
invoice accept: cancel 46.98 1397 5638
customer reserve: creation 3868.08 285 464170
customer reserve: removal 4647.30 115 557676
realization accept: apply 81.30 324 9756
realization accept: cancel 73.41 186 8809
payment from customer: creation 57.60 191 6912
payment from customer: removal 5.02 131 602
payment to supplier: creation 47.49 216 5699
payment to supplier: removal 3.27 169 392
service: total inventory turnovers 3.63 5183 436
service: total monetary turnovers 8.03 492 963
service: aggregate perf. data 0.81 18811 97
service: refresh index statistics 0.03 275722 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-06-2022 10:38:11 to 14-06-2022 10:38:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8095
TEST_TIME 2 5962
TEST_TIME 3 6202
TEST_TIME 4 6232
TEST_TIME 5 6212
TEST_TIME 6 6529
TEST_TIME 7 6626
TEST_TIME 8 6290
TEST_TIME 9 6207
TEST_TIME 10 6553
TEST_TIME 11 6687
TEST_TIME 12 6991
TEST_TIME 13 7272
TEST_TIME 14 7175
TEST_TIME 15 7208
TEST_TIME 16 7295
TEST_TIME 17 7660
TEST_TIME 18 7764
TEST_TIME 19 7814
TEST_TIME 20 7728
TEST_TIME 21 7780
TEST_TIME 22 7727
TEST_TIME 23 7737
TEST_TIME 24 7976
TEST_TIME 25 7965
TEST_TIME 26 7983
TEST_TIME 27 8341
TEST_TIME 28 8288
TEST_TIME 29 8215
TEST_TIME 30 8239
TEST_TIME 31 8661
TEST_TIME 32 8724
TEST_TIME 33 8777
TEST_TIME 34 8851
TEST_TIME 35 8795
TEST_TIME 36 8874
TEST_TIME 37 9211
TEST_TIME 38 10106
TEST_TIME 39 10306
TEST_TIME 40 10726
TEST_TIME 41 10683
TEST_TIME 42 10505
TEST_TIME 43 10403
TEST_TIME 44 10427
TEST_TIME 45 10522
TEST_TIME 46 10369
TEST_TIME 47 10297
TEST_TIME 48 10175
TEST_TIME 49 10113
TEST_TIME 50 10121
TEST_TIME 51 10002
TEST_TIME 52 9888
TEST_TIME 53 9816
TEST_TIME 54 9789
TEST_TIME 55 9764
TEST_TIME 56 9789
TEST_TIME 57 9733
TEST_TIME 58 9721
TEST_TIME 59 9696
TEST_TIME 60 9677
TEST_TIME 61 9613
TEST_TIME 62 9659
TEST_TIME 63 9613
TEST_TIME 64 9722
TEST_TIME 65 9689
TEST_TIME 66 9707
TEST_TIME 67 9709
TEST_TIME 68 9729
TEST_TIME 69 9659
TEST_TIME 70 9688
TEST_TIME 71 9715
TEST_TIME 72 9718
TEST_TIME 73 9669
TEST_TIME 74 9735
TEST_TIME 75 9695
TEST_TIME 76 9637
TEST_TIME 77 9526
TEST_TIME 78 9585
TEST_TIME 79 9510
TEST_TIME 80 9480
TEST_TIME 81 9475
TEST_TIME 82 9438
TEST_TIME 83 9385
TEST_TIME 84 9326
TEST_TIME 85 9283
TEST_TIME 86 9315
TEST_TIME 87 9307
TEST_TIME 88 9374
TEST_TIME 89 9332
TEST_TIME 90 9305
TEST_TIME 91 9318
TEST_TIME 92 9348
TEST_TIME 93 9317
TEST_TIME 94 9341
TEST_TIME 95 9389
TEST_TIME 96 9468
TEST_TIME 97 9439
TEST_TIME 98 9433
TEST_TIME 99 9541
TEST_TIME 100 9524
TEST_TIME 101 9536
TEST_TIME 102 9512
TEST_TIME 103 9481
TEST_TIME 104 9419
TEST_TIME 105 9394
TEST_TIME 106 9362
TEST_TIME 107 9312
TEST_TIME 108 9324
TEST_TIME 109 9322
TEST_TIME 110 9286
TEST_TIME 111 9264
TEST_TIME 112 9240
TEST_TIME 113 9223
TEST_TIME 114 9247
TEST_TIME 115 9223
TEST_TIME 116 9264
TEST_TIME 117 9281
TEST_TIME 118 9268
TEST_TIME 119 9283
TEST_TIME 120 9242

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-06-2022 10:38:14 to 14-06-2022 10:38:18.

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 1531437 1531437 0 0.00 0 37053 16 0 0
doc_list_biud 1531428 1531428 0 0.00 0 27975 48 0 0
sp_add_invoice_to_stock 5093 4945 148 2.90 27 278363 30256 0 148
sp_cancel_adding_invoice 5638 5638 0 0.00 8 104937 1397 0 0
sp_cancel_client_order 1696 1696 0 0.00 6 15062 489 0 0
sp_cancel_customer_reserve 557676 557676 0 0.00 1 24590 116 0 0
sp_cancel_pay_from_customer 602 602 0 0.00 4 1455 132 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 4 7895 169 0 0
sp_cancel_supplier_invoice 6223 6215 8 0.12 3 27998 1191 0 8
sp_cancel_supplier_order 424 424 0 0.00 10 1410586 195232 0 0
sp_cancel_write_off 8809 8809 0 0.00 2 16556 187 0 0
sp_client_order 20084 20084 0 0.00 6 29973 495 0 0
sp_customer_reserve 464170 464170 0 0.00 1 92169 285 0 0
sp_fill_shopping_cart 30762 30762 0 0.00 2 14641 85 0 0
sp_fill_shopping_cart_clo_res 464139 464139 0 0.00 0 17527 21 0 0
sp_get_clo_for_invoice 4904 4904 0 0.00 0 17299 252 0 0
sp_kill_cost_storno 15441 15441 0 0.00 0 27262 90 0 0
sp_kill_qstorno_ret_qs2qd 1130800 1130800 0 0.00 0 27973 50 0 0
sp_kill_qty_storno 595117 595117 0 0.00 0 27974 115 0 0
sp_lock_selected_doc 20247 20247 0 0.00 0 8244 8 0 0
sp_make_cost_storno 27270 27270 0 0.00 0 22722 87 0 0
sp_multiply_rows_for_pdistr 27269 27269 0 0.00 0 8116 43 0 0
sp_multiply_rows_for_qdistr 20084 20084 0 0.00 0 15710 176 0 0
sp_pay_from_customer 6912 6912 0 0.00 5 18436 192 0 0
sp_pay_to_supplier 5699 5699 0 0.00 7 22267 217 0 0
sp_payment_common 12609 12609 0 0.00 2 8262 49 0 0
sp_qd_handle_on_cancel_clo 1696 1696 0 0.00 0 15054 402 0 0
sp_qd_handle_on_invoice_upd_sts 10541 10541 0 0.00 0 19197 584 0 0
sp_qd_handle_on_reserve_upd_sts 9756 9756 0 0.00 0 11459 72 0 0
sp_reserve_write_off 9756 9756 0 0.00 8 20584 325 0 0
sp_supplier_invoice 5435 5435 0 0.00 9 121298 3258 0 0
sp_supplier_order 5248 5248 0 0.00 6 43267 1429 0 0
srv_aggregate_perf_data 97 97 0 0.00 2372 141792 18811 0 0
srv_fill_mon_cache_memory 75 75 0 0.00 766 138230 30544 0 0
srv_find_qd_qs_mism 1056483 1056483 0 0.00 0 37053 13 0 0
srv_make_invnt_saldo 436 436 0 0.00 133 58848 5183 0 0
srv_make_money_saldo 963 963 0 0.00 1 27606 493 0 0
srv_recalc_idx_stat 4 4 0 0.00 73542 435639 275723 0 0
t$perf-abend:sp_add_invoice_to_stock 148 148 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4951 4951 0 0.00 2 11132 1120 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 1 8299 530 0 0
t$perf-norm:sp_cancel_client_order 1696 1696 0 0.00 0 2803 35 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 3148 39 0 0
t$perf-norm:sp_cancel_pay_from_customer 602 602 0 0.00 0 1606 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 737 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 923 45 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 124935 8678 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 1466 22 0 0
t$perf-norm:sp_client_order 20084 20084 0 0.00 0 9428 45 0 0
t$perf-norm:sp_customer_reserve 986 986 0 0.00 0 818 18 0 0
t$perf-norm:sp_pay_from_customer 6912 6912 0 0.00 0 11083 20 0 0
t$perf-norm:sp_pay_to_supplier 5699 5699 0 0.00 0 1942 18 0 0
t$perf-norm:sp_reserve_write_off 9756 9756 0 0.00 0 4031 24 0 0
t$perf-norm:sp_supplier_invoice 5435 5435 0 0.00 0 10072 39 0 0
t$perf-norm:sp_supplier_order 5248 5248 0 0.00 0 4542 41 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 580 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 46 4 0 0
t$perf-norm:srv_make_invnt_saldo 436 436 0 0.00 0 2724 27 0 0
t$perf-norm:srv_make_money_saldo 963 963 0 0.00 0 1943 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 9 185 66 0 0
v_add_invoice_to_stock 10092 9796 296 2.93 0 19888 30 0 296
v_all_customers 36876 36876 0 0.00 0 136 4 0 0
v_all_suppliers 29930 29930 0 0.00 0 134 5 0 0
v_all_wares 40162 40162 0 0.00 0 84 4 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 1241 16 0 0
v_cancel_client_order 3392 3392 0 0.00 0 3942 13 0 0
v_cancel_customer_prepayment 1204 1204 0 0.00 0 1026 12 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 5252 16 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 1948 16 0 0
v_cancel_supplier_invoice 806 790 16 1.98 0 2002 32 0 16
v_cancel_supplier_order 848 848 0 0.00 0 4489 19 0 0
v_cancel_write_off 1968 1968 0 0.00 0 8688 29 0 0
v_max_id_clo_ord 5248 5248 0 0.00 0 3305 6 0 0
v_max_id_clo_res 986 986 0 0.00 0 1849 15 0 0
v_max_id_ord_sup 5432 5432 0 0.00 0 5617 10 0 0
v_max_non_paid_invoice 1412 1412 0 0.00 0 980 9 0 0
v_max_non_paid_realizn 3579 3579 0 0.00 0 1139 8 0 0
v_min_id_clo_ord 5248 5248 0 0.00 0 2119 8 0 0
v_min_id_clo_res 986 986 0 0.00 0 3303 11 0 0
v_min_id_ord_sup 5432 5432 0 0.00 0 3132 7 0 0
v_min_non_paid_invoice 5697 5697 0 0.00 0 7031 18 0 0
v_min_non_paid_realizn 6912 6912 0 0.00 0 6416 16 0 0
v_our_firm 9952 9952 0 0.00 0 61 5 0 0
v_reserve_write_off 19512 19512 0 0.00 0 20268 18 0 0
x_lock_depdocs_on_canc_invoice 11458 11458 0 0.00 0 21149 143 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 9042 394 0 0
x_make_qstorno_1000_1200 5248 5248 0 0.00 2 43258 1236 0 0
x_make_qstorno_1200_2000 5435 5435 0 0.00 4 120599 3045 0 0
x_make_qstorno_2100_3300 464169 464169 0 0.00 0 92144 250 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-06-2022 10:38:19 to 14-06-2022 10:38:23.

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
08:07:22 1 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:23 43761 8192000000 9735442048 10035097600 796582432 8.862 100 88 177 1 826331344 7835520 21913392
08:10:09 27500 8192000000 9774474416 10082234368 844897744 9.349 100 93 186 1 880693360 13448816 22346800
08:11:43 16292 8192000000 9814993600 10136637440 873060640 9.631 100 83 166 0 925165728 32787760 19317328
08:13:03 9840 8192000000 9867820992 10202923008 920201472 10.098 100 85 167 1 985817280 47828880 17786928
08:14:18 6777 8192000000 9914774112 10261426176 966346416 10.551 100 87 170 0 1039690368 54903664 18440288
08:15:29 1305 8192000000 9974972016 10333175808 1003635008 10.914 100 92 180 0 1094832576 72212048 18985520
08:16:33 3930 8192000000 9982588336 10343301120 1025080608 11.121 100 84 159 0 1103494544 62656480 15757456
08:17:39 1182 8192000000 10036708992 10408316928 1053177856 11.391 100 98 191 1 1156769472 84286288 19305328
08:18:42 1298 8192000000 10026688064 10399424512 1062568560 11.481 100 92 178 2 1144055856 63836080 17651216
08:19:45 1164 8192000000 10042085456 10418372608 1064030752 11.495 100 94 184 1 1160101696 76879712 19191232
08:20:48 8789 8192000000 10029162944 10415611904 1065499248 11.509 100 76 144 1 1153148560 72966304 14683008
08:21:58 1295 8192000000 10058836960 10441883648 1075035536 11.600 100 96 185 0 1181620480 87697888 18887056
08:23:01 1151 8192000000 10048999280 10439655424 1077544736 11.624 100 93 178 2 1173298880 78298608 17455536
08:24:03 1281 8192000000 10048808160 10429673472 1082342176 11.670 100 92 181 0 1165238096 65126416 17769504
08:25:05 1508 8192000000 10066169936 10452148224 1084727872 11.693 100 98 190 1 1183977392 80073872 19175648
08:26:08 1284 8192000000 10054175792 10438930432 1085633184 11.701 100 98 192 0 1173778816 67962384 20183248
08:27:11 1304 8192000000 10054572144 10444431360 1089909328 11.742 100 95 183 0 1178719552 70365296 18444928
08:28:13 1627 8192000000 10068637728 10457186304 1084454864 11.690 100 95 180 0 1188210592 84417952 19337776
08:29:17 1190 8192000000 10043664128 10430803968 1077968992 11.628 99 94 182 1 1174609936 79150768 17490176
08:30:20 1239 8192000000 10042696800 10437865472 1090996896 11.752 100 83 158 0 1168437008 61504416 15935696
08:31:22 880 8192000000 10051264224 10431025152 1061772416 11.473 99 94 176 1 1180929568 100709616 18447536
08:32:27 1618 8192000000 10007718880 10390994944 1072964416 11.580 100 83 144 1 1131605696 43511904 15129376
08:33:30 1332 8192000000 10021113136 10400653312 1068756656 11.540 100 93 183 1 1144382000 56013040 19612304
08:34:33 1429 8192000000 9997506688 10368573440 1038085952 11.246 100 95 182 2 1120663824 62731856 19846016
08:35:35 1123 8192000000 10003466144 10375249920 1033313024 11.200 99 94 182 1 1129477136 76361120 19802992
08:36:38 1198 8192000000 9970810576 10333933568 1012045488 10.995 100 97 186 3 1087540288 57977904 17516896
08:37:44 3485 8192000000 9956109440 10323046400 1015760368 11.031 98 79 135 6 1068802288 40594272 12447648
08:38:56 5465 8192000000 10023761184 10407948288 1055548576 11.414 100 79 148 2 1149150352 78210896 15390880
08:40:24 1307 8192000000 10031396480 10412007424 1076550848 11.615 100 83 155 2 1154023104 61432656 16039600
08:41:30 1220 8192000000 10081778176 10463879168 1087309536 11.717 100 85 164 0 1198273024 94333536 16629952
08:42:36 6016 8192000000 10095222624 10483142656 1084856272 11.694 99 67 131 2 1198526320 100058176 13611872
08:43:47 11966 8192000000 10106885968 10502475776 1105026608 11.885 100 69 135 1 1235895760 116704304 14164848
08:45:03 1967 8192000000 10143194080 10533806080 1118936512 12.017 100 77 147 0 1263143376 127894576 16312288
08:46:08 4343 8192000000 10173389056 10564317184 1125234432 12.076 100 80 155 1 1286461984 144689936 16537616
08:47:15 1724 8192000000 10198013440 10589646848 1127830672 12.101 100 86 164 3 1312223024 165963328 18429024
08:48:20 873 8192000000 10237164624 10640830464 1142521296 12.239 100 96 190 0 1352079648 188407024 21151328
08:49:25 1745 8192000000 10221240400 10626072576 1146198912 12.274 100 78 140 0 1336296848 174293760 15804176
08:50:31 997 8192000000 10247738128 10654777344 1154945120 12.356 100 94 178 2 1353563968 178313680 20305168
08:51:36 1048 8192000000 10269198928 10683678720 1160574944 12.409 100 93 180 1 1374754592 194527104 19652544
08:52:44 2580 8192000000 10279754752 10683850752 1168296192 12.481 100 83 159 0 1392370032 206642672 17431168
08:53:51 939 8192000000 10316203248 10751483904 1171508656 12.511 100 96 191 0 1424702144 230112016 23081472
08:54:55 2995 8192000000 10306444464 10751537152 1173631488 12.531 100 86 170 0 1423538800 230649072 19258240
08:56:02 1140 8192000000 10341796976 10747293696 1182390560 12.612 100 93 182 1 1451690976 246124544 23175872
08:57:06 1378 8192000000 10379156528 10785091584 1185813888 12.644 100 93 181 1 1484512624 277969488 20729248
08:58:14 1257 8192000000 10385496848 10803621888 1192165344 12.704 100 96 180 1 1495714272 281691712 21857216
08:59:19 893 8192000000 10430867792 10851557376 1192361728 12.705 100 92 180 0 1535854432 321023536 22469168
09:00:27 1849 8192000000 10460787456 10869284864 1193539888 12.716 100 95 182 1 1565366448 350385072 21441488
09:01:34 996 8192000000 10471585952 10926481408 1194099920 12.722 100 97 181 0 1574764400 357419392 23245088
09:02:39 926 8192000000 10463802736 10910633984 1203630256 12.810 100 99 194 1 1566929088 339515248 23783584
09:03:44 1072 8192000000 10504583088 10957815808 1203784688 12.811 100 93 185 0 1601942336 375706816 22450832
09:04:52 756 8192000000 10507099296 10941665280 1201287200 12.788 100 98 190 0 1608099152 382048672 24763280
09:05:56 6429 8192000000 10534148160 10959032320 1194723776 12.727 100 76 149 0 1633238416 420256352 18258288
09:07:07 4905 8192000000 10564683568 11005882368 1187775792 12.663 100 68 134 0 1673149280 467959040 17414448
09:08:18 8052 8192000000 10539154256 11047731200 1194838112 12.728 100 60 118 0 1646636928 436101184 15697632
09:09:33 4518 8192000000 10564861328 10995896320 1202276512 12.797 100 63 123 0 1671239568 454357440 14605616
09:10:49 10533 8192000000 10554246304 11049013248 1203959824 12.813 100 53 102 0 1663679344 446564336 13155184
09:12:03 3537 8192000000 10569412400 11003195392 1194259088 12.723 100 56 109 0 1673571840 466134816 13177936
09:13:19 19647 8192000000 10554658576 11048935424 1168900576 12.487 100 41 80 1 1667921392 487763424 11257392
09:14:40 18765 8192000000 10420287104 10941992960 1133466176 12.154 98 37 74 0 1522407168 378619648 10321344
09:16:11 69298 8192000000 10167276800 10728341504 1093034912 11.772 97 11 22 0 1300700192 204853248 2812032
09:18:25 49145 8192000000 10050783424 10475851776 1096755296 11.807 97 42 79 1 1133088224 28565008 7767920
09:20:28 94408 8192000000 10115018144 10607063040 1102132240 11.858 98 17 35 1 1196416048 90300992 3982816
09:23:03 32845 8192000000 10050475040 10477604864 1110686544 11.939 100 56 109 2 1147261856 23982112 12593200
09:24:44 138229 8192000000 10068781376 10528444416 1112530096 11.956 99 38 75 0 1172852624 52488448 7834080
09:28:09 123183 8192000000 10090783584 10522054656 1110151184 11.934 98 57 114 0 1149460272 26921840 12387248
09:31:15 5616 8192000000 10113140736 10545184768 1134834672 12.167 100 54 106 1 1207136672 60573488 11728512
09:32:24 13309 8192000000 10110660960 10563850240 1133467296 12.154 99 36 65 2 1215698640 74415776 7815568
09:33:40 54203 8192000000 10174542432 10657603584 1141221072 12.227 99 27 52 0 1255511648 108349744 5940832
09:35:45 72538 8192000000 10136569696 10611621888 1126658512 12.090 98 13 26 1 1209590896 80108224 2824160
09:37:58 27169 8192000000 10178190416 10671046656 1142187552 12.236 100 8 16 1 1275209488 131295408 1726528
09:39:26 67789 8192000000 10139852272 10617110528 1119427536 12.022 98 18 34 0 1225683296 101688080 4567680
09:41:34 64481 8192000000 10098594384 10551242752 1135914320 12.177 99 18 36 0 1178585216 39372960 3297936
09:43:41 89363 8192000000 10097433456 10550562816 1098335392 11.822 96 25 50 0 1161374480 57602992 5436096
09:46:14 7405 8192000000 10096078192 10532089856 1139564896 12.211 100 50 101 0 1188920768 39593568 9762304
09:47:26 30862 8192000000 10110984000 10560811008 1129922944 12.121 99 28 56 1 1178832256 43877056 5032256
09:49:02 37383 8192000000 10083849536 10539819008 1138735424 12.204 100 24 43 0 1183313472 40195440 4382608
09:50:42 26591 8192000000 10106470960 10567127040 1139094336 12.207 99 34 65 0 1216095872 69779648 7221888
09:52:13 22795 8192000000 10085075632 10529271808 1131436128 12.135 100 37 71 1 1172777440 34028432 7312880
09:53:36 81002 8192000000 10147622112 10586370048 1127110016 12.094 99 40 79 3 1234772176 98483280 9178880
09:56:23 32494 8192000000 10108156640 10550833152 1143853568 12.252 100 40 81 1 1188201872 36758448 7589856
09:57:58 57919 8192000000 10114819728 10588971008 1147617632 12.287 100 17 34 1 1217754480 65967168 4169680
09:59:56 60873 8192000000 10121656928 10568499200 1126996288 12.093 98 23 46 0 1203727200 72264032 4466880
10:01:57 65060 8192000000 10136879536 10609643520 1133173584 12.151 99 16 32 0 1240099024 102692272 4233168
10:04:20 51839 8192000000 10110747488 10581413888 1098015616 11.819 96 20 40 1 1188655904 85887312 4752976
10:06:43 36276 8192000000 10104871216 10547843072 1125138752 12.076 99 47 93 1 1172266224 37756368 9371104
10:08:23 7259 8192000000 10105866048 10534313984 1119790320 12.025 99 58 106 1 1206969840 76601520 10578000
10:09:31 12082 8192000000 10069893648 10495729664 1084634208 11.692 96 46 91 2 1131362032 37068864 9658960
10:10:52 13951 8192000000 10160049136 10581958656 1133788784 12.157 100 60 120 0 1243971488 97756592 12426112
10:12:07 19370 8192000000 10167017600 10672869376 1120592272 12.033 99 39 76 1 1285959632 157448816 7918544
10:13:33 51645 8192000000 10129174592 10553888768 1113596320 11.966 98 45 87 0 1186789008 63545600 9647088
10:15:29 71688 8192000000 10137442256 10621485056 1122750528 12.053 99 13 26 2 1248172384 122201248 3220608
10:18:17 91310 8192000000 10117135376 10594660352 1137784640 12.195 100 24 46 1 1221981056 78843104 5353312
10:20:48 97503 8192000000 10086509232 10540826624 1129144544 12.113 99 9 18 0 1172472816 41702944 1625328
10:23:30 85554 8192000000 10123598640 10597683200 1110378736 11.936 97 12 23 1 1188656608 75819040 2458832
10:25:56 51652 8192000000 10067882656 10569302016 1126498768 12.088 99 21 40 4 1179884544 48937920 4447856
10:27:48 63891 8192000000 10096724736 10545152000 1120023072 12.027 98 15 28 2 1174472848 51720800 2728976
10:30:00 50687 8192000000 10140489056 10583801856 1117185104 12.000 98 23 46 2 1216318112 94039056 5093952
10:31:51 43009 8192000000 10132474640 10577321984 1107447184 11.908 97 27 54 0 1209707088 96542656 5717248
10:33:35 49750 8192000000 10071640656 10511777792 1100649728 11.844 97 33 64 1 1146737040 39983264 6104048
10:35:25 15852 8192000000 10131211248 10567516160 1135023056 12.169 100 59 113 0 1212984880 66939984 11021840
10:36:42 17956 8192000000 10102618640 10531663872 1112654624 11.958 99 48 96 0 1176237360 53846720 9736016

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 14-06-2022 10:38:25 to 14-06-2022 10:38:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 148 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-06-2022 10:38:42 to 14-06-2022 10:38:44.

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          210490
MON$OLDEST_ACTIVE               215161
MON$OLDEST_SNAPSHOT             215161
MON$NEXT_TRANSACTION            215161
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                       2103104
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             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2776 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2776 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2776 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2776 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 ---
:219: 
:220: mscs-test-161.budzdorov.ru    Tue Jun 14 10:37:28 2022
:221:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:222: 
:223: 
:224: mscs-test-161.budzdorov.ru    Tue Jun 14 10:37:30 2022
:225:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:226: 
:227: 
:228: mscs-test-161.budzdorov.ru    Tue Jun 14 10:37:30 2022
:229:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:230: 
--- end of diff output ---

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

14.06.22 10:38:45. 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-06-14 10:38:48 No severe PSQL-related problems occured
14.06.22 10:38:49. End of report.