Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.05.2022 10: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 16529464 kB
MemAvailable 31514600 kB
Buffers 0 kB
Cached 15214108 kB
SwapCached 2596 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-05-10 10:37:16
DTS_END                         2022-05-10 10:37:16.6770
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/34626
TRN_ID                          219694
ATT_ID                          644
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: 8921.02 26715 1070522
customer order: creation 171.37 480 20564
customer order: refuse 14.43 359 1732
order to supplier: creation 45.45 1392 5454
order to supplier: removal 3.17 179031 380
invoice (draft): creation 46.28 3159 5553
invoice (draft): removal 49.62 989 5954
invoice accept: apply 42.43 25450 5091
invoice accept: cancel 44.60 1142 5352
customer reserve: creation 3867.52 248 464102
customer reserve: removal 4340.89 104 520907
realization accept: apply 83.23 254 9988
realization accept: cancel 82.55 168 9906
payment from customer: creation 58.81 157 7057
payment from customer: removal 5.53 102 663
payment to supplier: creation 49.06 182 5887
payment to supplier: removal 3.50 99 420
service: total inventory turnovers 4.23 3629 508
service: total monetary turnovers 7.37 308 884
service: aggregate perf. data 0.96 15449 115
service: refresh index statistics 0.04 301597 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-05-2022 10:38:47 to 10-05-2022 10:38:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7731
TEST_TIME 2 7509
TEST_TIME 3 7047
TEST_TIME 4 7205
TEST_TIME 5 7021
TEST_TIME 6 6605
TEST_TIME 7 6530
TEST_TIME 8 6715
TEST_TIME 9 6950
TEST_TIME 10 6747
TEST_TIME 11 6640
TEST_TIME 12 7100
TEST_TIME 13 6932
TEST_TIME 14 7014
TEST_TIME 15 6868
TEST_TIME 16 7090
TEST_TIME 17 7202
TEST_TIME 18 7524
TEST_TIME 19 7630
TEST_TIME 20 7751
TEST_TIME 21 7970
TEST_TIME 22 7957
TEST_TIME 23 8471
TEST_TIME 24 8601
TEST_TIME 25 8734
TEST_TIME 26 8742
TEST_TIME 27 8716
TEST_TIME 28 9436
TEST_TIME 29 9991
TEST_TIME 30 10469
TEST_TIME 31 10872
TEST_TIME 32 11548
TEST_TIME 33 11413
TEST_TIME 34 11245
TEST_TIME 35 11159
TEST_TIME 36 11055
TEST_TIME 37 10936
TEST_TIME 38 10794
TEST_TIME 39 10634
TEST_TIME 40 10536
TEST_TIME 41 10419
TEST_TIME 42 10392
TEST_TIME 43 10209
TEST_TIME 44 10140
TEST_TIME 45 10006
TEST_TIME 46 9931
TEST_TIME 47 9961
TEST_TIME 48 9987
TEST_TIME 49 9869
TEST_TIME 50 9779
TEST_TIME 51 9646
TEST_TIME 52 9556
TEST_TIME 53 9546
TEST_TIME 54 9481
TEST_TIME 55 9489
TEST_TIME 56 9448
TEST_TIME 57 9482
TEST_TIME 58 9462
TEST_TIME 59 9446
TEST_TIME 60 9473
TEST_TIME 61 9434
TEST_TIME 62 9518
TEST_TIME 63 9488
TEST_TIME 64 9536
TEST_TIME 65 9561
TEST_TIME 66 9568
TEST_TIME 67 9598
TEST_TIME 68 9546
TEST_TIME 69 9434
TEST_TIME 70 9347
TEST_TIME 71 9401
TEST_TIME 72 9359
TEST_TIME 73 9314
TEST_TIME 74 9256
TEST_TIME 75 9224
TEST_TIME 76 9180
TEST_TIME 77 9168
TEST_TIME 78 9193
TEST_TIME 79 9254
TEST_TIME 80 9216
TEST_TIME 81 9285
TEST_TIME 82 9306
TEST_TIME 83 9377
TEST_TIME 84 9384
TEST_TIME 85 9411
TEST_TIME 86 9371
TEST_TIME 87 9342
TEST_TIME 88 9263
TEST_TIME 89 9246
TEST_TIME 90 9293
TEST_TIME 91 9247
TEST_TIME 92 9181
TEST_TIME 93 9152
TEST_TIME 94 9103
TEST_TIME 95 9103
TEST_TIME 96 9108
TEST_TIME 97 9060
TEST_TIME 98 9066
TEST_TIME 99 9035
TEST_TIME 100 9034
TEST_TIME 101 9053
TEST_TIME 102 9114
TEST_TIME 103 9108
TEST_TIME 104 9280
TEST_TIME 105 9271
TEST_TIME 106 9253
TEST_TIME 107 9314
TEST_TIME 108 9317
TEST_TIME 109 9260
TEST_TIME 110 9205
TEST_TIME 111 9178
TEST_TIME 112 9131
TEST_TIME 113 9125
TEST_TIME 114 9082
TEST_TIME 115 9064
TEST_TIME 116 9018
TEST_TIME 117 9041
TEST_TIME 118 9023
TEST_TIME 119 9009
TEST_TIME 120 8950

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-05-2022 10:38:51 to 10-05-2022 10:38:55.

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 1497102 1497102 0 0.00 0 23269 13 0 0
doc_list_biud 1497087 1497087 0 0.00 0 25113 41 0 0
sp_add_invoice_to_stock 5272 5091 181 3.43 52 244837 25451 0 181
sp_cancel_adding_invoice 5352 5352 0 0.00 7 99841 1143 0 0
sp_cancel_client_order 1732 1732 0 0.00 6 10372 360 0 0
sp_cancel_customer_reserve 520907 520907 0 0.00 1 25118 104 0 0
sp_cancel_pay_from_customer 663 663 0 0.00 4 2329 102 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 4 1760 99 0 0
sp_cancel_supplier_invoice 5973 5954 19 0.31 3 18164 990 0 19
sp_cancel_supplier_order 380 380 0 0.00 14 1319272 179031 0 0
sp_cancel_write_off 9906 9906 0 0.00 2 11384 169 0 0
sp_client_order 20564 20564 0 0.00 5 32839 480 0 0
sp_customer_reserve 464102 464102 0 0.00 1 65854 249 0 0
sp_fill_shopping_cart 31558 31558 0 0.00 1 19997 71 0 0
sp_fill_shopping_cart_clo_res 464066 464066 0 0.00 0 17218 20 0 0
sp_get_clo_for_invoice 5048 5048 0 0.00 0 35309 308 0 0
sp_kill_cost_storno 16341 16341 0 0.00 0 23266 69 0 0
sp_kill_qstorno_ret_qs2qd 1058054 1058054 0 0.00 0 25109 46 0 0
sp_kill_qty_storno 559263 559263 0 0.00 0 25112 103 0 0
sp_lock_selected_doc 20746 20746 0 0.00 0 11922 8 0 0
sp_make_cost_storno 27979 27979 0 0.00 0 18391 79 0 0
sp_multiply_rows_for_pdistr 27977 27977 0 0.00 0 6891 34 0 0
sp_multiply_rows_for_qdistr 20563 20563 0 0.00 0 12851 178 0 0
sp_pay_from_customer 7057 7057 0 0.00 5 16376 157 0 0
sp_pay_to_supplier 5887 5887 0 0.00 7 15141 182 0 0
sp_payment_common 12943 12943 0 0.00 2 5568 39 0 0
sp_qd_handle_on_cancel_clo 1732 1732 0 0.00 1 10364 293 0 0
sp_qd_handle_on_invoice_upd_sts 10397 10397 0 0.00 0 19307 466 0 0
sp_qd_handle_on_reserve_upd_sts 9987 9987 0 0.00 0 23122 66 0 0
sp_reserve_write_off 9988 9988 0 0.00 8 23146 255 0 0
sp_supplier_invoice 5553 5553 0 0.00 11 97856 3160 0 0
sp_supplier_order 5454 5454 0 0.00 8 30837 1393 0 0
srv_aggregate_perf_data 115 115 0 0.00 2544 115347 15450 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 703 151171 38441 0 0
srv_find_qd_qs_mism 1021180 1021180 0 0.00 0 11572 11 0 0
srv_make_invnt_saldo 508 508 0 0.00 71 77490 3630 0 0
srv_make_money_saldo 884 884 0 0.00 1 11691 309 0 0
srv_recalc_idx_stat 5 5 0 0.00 166846 454739 301597 0 0
t$perf-abend:sp_add_invoice_to_stock 181 181 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 19 19 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5097 5097 0 0.00 1 15226 788 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 1 7729 346 0 0
t$perf-norm:sp_cancel_client_order 1732 1732 0 0.00 0 23427 42 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 923 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 663 663 0 0.00 0 1260 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 5212 29 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 578 22 0 0
t$perf-norm:sp_cancel_supplier_order 380 380 0 0.00 0 67446 6999 0 0
t$perf-norm:sp_cancel_write_off 1014 1014 0 0.00 0 4002 22 0 0
t$perf-norm:sp_client_order 20566 20566 0 0.00 0 10666 29 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 2237 14 0 0
t$perf-norm:sp_pay_from_customer 7057 7057 0 0.00 0 5572 18 0 0
t$perf-norm:sp_pay_to_supplier 5888 5888 0 0.00 0 11945 18 0 0
t$perf-norm:sp_reserve_write_off 9988 9988 0 0.00 0 4286 20 0 0
t$perf-norm:sp_supplier_invoice 5553 5553 0 0.00 0 11821 30 0 0
t$perf-norm:sp_supplier_order 5454 5454 0 0.00 0 3555 26 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 782 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 603 12 0 0
t$perf-norm:srv_make_invnt_saldo 508 508 0 0.00 0 732 11 0 0
t$perf-norm:srv_make_money_saldo 884 884 0 0.00 0 609 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 189 46 0 0
v_add_invoice_to_stock 10442 10080 362 3.46 0 3686 23 0 362
v_all_customers 37666 37666 0 0.00 0 75 3 0 0
v_all_suppliers 30892 30892 0 0.00 0 61 3 0 0
v_all_wares 41122 41122 0 0.00 0 59 3 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 519 11 0 0
v_cancel_client_order 3464 3464 0 0.00 0 1747 9 0 0
v_cancel_customer_prepayment 1326 1326 0 0.00 0 2212 13 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 585 9 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 1648 13 0 0
v_cancel_supplier_invoice 860 822 38 4.41 0 380 20 0 38
v_cancel_supplier_order 760 760 0 0.00 0 5129 17 0 0
v_cancel_write_off 2028 2028 0 0.00 0 1905 21 0 0
v_max_id_clo_ord 5451 5451 0 0.00 0 2629 5 0 0
v_max_id_clo_res 989 989 0 0.00 0 2375 16 0 0
v_max_id_ord_sup 5546 5546 0 0.00 0 3731 6 0 0
v_max_non_paid_invoice 1438 1438 0 0.00 0 3035 10 0 0
v_max_non_paid_realizn 3561 3561 0 0.00 0 996 7 0 0
v_min_id_clo_ord 5451 5451 0 0.00 0 4459 8 0 0
v_min_id_clo_res 989 989 0 0.00 0 3309 11 0 0
v_min_id_ord_sup 5546 5546 0 0.00 0 4862 9 0 0
v_min_non_paid_invoice 5887 5887 0 0.00 0 3815 15 0 0
v_min_non_paid_realizn 7056 7056 0 0.00 0 5142 14 0 0
v_our_firm 10446 10446 0 0.00 0 77 4 0 0
v_reserve_write_off 19974 19974 0 0.00 0 2881 8 0 0
x_lock_depdocs_on_canc_invoice 10895 10895 0 0.00 0 10295 120 0 0
x_lock_depdocs_on_canc_sup_ord 380 380 0 0.00 0 11205 430 0 0
x_make_qstorno_1000_1200 5454 5454 0 0.00 2 30823 1241 0 0
x_make_qstorno_1200_2000 5553 5553 0 0.00 5 97819 2967 0 0
x_make_qstorno_2100_3300 464102 464102 0 0.00 0 57701 218 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-05-2022 10:38:56 to 10-05-2022 10:39:00.

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:20 2 8192000000 8797182624 8807829504 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:21 22693 8192000000 9725536144 10024267776 807313216 8.970 100 88 176 0 836663776 8317840 21032720
08:09:48 25476 8192000000 9763674304 10072203264 836171552 9.261 100 89 176 1 870268288 13284496 20812240
08:11:20 14341 8192000000 9802925360 10120523776 865879200 9.559 100 86 172 0 910372960 24608912 19884848
08:12:46 13266 8192000000 9881504128 10218438656 923607824 10.132 100 84 166 0 991831312 50205008 18018480
08:14:02 4453 8192000000 9934361744 10279686144 966061184 10.548 100 90 180 1 1052993520 67939280 18993056
08:15:08 2796 8192000000 9962431296 10318700544 1002891344 10.907 100 96 181 1 1084747040 62616832 19238864
08:16:13 1497 8192000000 10017724752 10384154624 1028155216 11.151 100 93 181 0 1131973552 85763968 18054368
08:17:17 1659 8192000000 10020187952 10396352512 1052023360 11.380 100 95 183 0 1140965856 70371632 18570864
08:18:21 1254 8192000000 10034211840 10407821312 1064289232 11.498 100 91 177 1 1145981648 64346608 17345808
08:19:24 12271 8192000000 10029653248 10408538112 1071339136 11.565 100 85 166 0 1153119568 66169200 15611232
08:20:39 1226 8192000000 10059148496 10447028224 1084337888 11.689 100 86 167 0 1184148528 82643248 17167392
08:21:41 767 8192000000 10071157616 10460057600 1088348976 11.727 100 93 183 1 1182042272 75635280 18058016
08:22:44 961 8192000000 10064012496 10457317376 1091934944 11.761 100 96 185 1 1181830656 71051008 18844704
08:23:46 1323 8192000000 10067816944 10455916544 1098585120 11.824 100 96 181 3 1186392000 69330896 18475984
08:24:48 1511 8192000000 10089915664 10482671616 1102028432 11.857 100 78 154 0 1208741424 91205440 15507552
08:25:50 1195 8192000000 10069281200 10458664960 1105022784 11.885 100 97 189 1 1189833968 65963040 18848144
08:26:52 1961 8192000000 10074695504 10467733504 1100280224 11.840 100 89 171 1 1194645184 76782096 17582864
08:27:55 1239 8192000000 10065592224 10457952256 1099889168 11.837 100 79 153 2 1189625264 73998064 15738032
08:28:58 1745 8192000000 10044398256 10439065600 1094527936 11.786 100 79 152 0 1165101952 55908208 14665808
08:30:00 1014 8192000000 10038353728 10422243328 1084056272 11.686 100 96 183 2 1161579120 59427632 18095216
08:31:02 1230 8192000000 10022135056 10404605952 1075344016 11.603 100 93 171 1 1146274336 52589696 18340624
08:32:04 1257 8192000000 9983733280 10358214656 1047479280 11.336 100 95 181 2 1105964064 40884048 17600736
08:33:07 852 8192000000 10023170688 10402050048 1056334944 11.421 100 97 190 1 1148245856 71718352 20192560
08:34:09 1030 8192000000 9990293200 10362445824 1036101872 11.227 99 96 181 0 1112891088 57901888 18887328
08:35:11 1286 8192000000 9991548416 10365120512 1027346288 11.143 99 93 174 3 1114095872 68471600 18277984
08:36:13 866 8192000000 9973501424 10345209856 1022110336 11.092 99 90 167 0 1095849824 56295424 17444064
08:37:15 1129 8192000000 10013342944 10390675456 1043851632 11.302 100 82 159 2 1139494176 77924400 17718144
08:38:27 14691 8192000000 10009311568 10390601728 1056375312 11.422 100 70 133 0 1128209296 58650016 13183968
08:39:46 1046 8192000000 10021795120 10402967552 1079192640 11.640 100 89 175 1 1140620400 43845280 17582480
08:40:53 3989 8192000000 10081587216 10470338560 1092914128 11.770 100 73 142 0 1203496704 95810112 14772464
08:42:01 3899 8192000000 10107456960 10495676416 1104409120 11.879 100 76 139 1 1223724768 104887920 14427728
08:43:09 3975 8192000000 10133918944 10528722944 1123075904 12.056 100 65 125 4 1252003424 116311648 12615872
08:44:17 1887 8192000000 10174364064 10569900032 1124474656 12.069 99 80 158 0 1288144064 146352000 17317408
08:45:21 1317 8192000000 10213065328 10607005696 1129383312 12.116 99 88 171 1 1332662592 184627008 18652272
08:46:25 1091 8192000000 10271087744 10668761088 1154299312 12.350 100 85 169 0 1387516896 215158896 18058688
08:47:28 1910 8192000000 10285829296 10690666496 1171429072 12.510 100 76 144 0 1404170528 215796304 16945152
08:48:35 771 8192000000 10349854480 10750844928 1180240080 12.592 100 96 189 2 1461614096 260690400 20683616
08:49:38 2660 8192000000 10375608416 10796703744 1185129552 12.638 100 91 173 0 1484974832 280208576 19636704
08:50:45 1067 8192000000 10403600448 10800762880 1194118528 12.722 100 95 185 2 1513499888 298533504 20847856
08:51:51 4536 8192000000 10428818032 10835152896 1194754400 12.728 100 81 153 1 1537218384 325244000 17219984
08:52:58 692 8192000000 10505775152 10929541120 1212987520 12.897 100 96 187 1 1611992576 376378976 22626080
08:54:02 853 8192000000 10545752016 10969718784 1226006976 13.017 100 96 192 1 1657166288 408559296 22600016
08:55:06 959 8192000000 10561853792 11001008128 1230758720 13.061 100 79 157 1 1669513776 419181024 19574032
08:56:13 10387 8192000000 10572554080 11005554688 1214615504 12.912 100 53 104 1 1700862144 472507888 13738752
08:57:30 6912 8192000000 10586022720 11039358976 1183758832 12.625 98 62 119 1 1675884208 476439728 15685648
08:58:40 2053 8192000000 10606573312 11027816448 1204629424 12.819 100 73 140 2 1711942368 490189440 17123504
08:59:45 1871 8192000000 10642981552 11081728000 1201783344 12.793 100 86 170 2 1745515120 523410624 20321152
09:00:50 6850 8192000000 10567476144 11052486656 1174984304 12.543 99 49 96 0 1672844736 485805776 12054656
09:02:11 26699 8192000000 10569717552 11037863936 1174853440 12.542 99 37 70 1 1682043376 497805600 9384336
09:03:38 15113 8192000000 10599412144 11043635200 1175115536 12.545 100 40 80 1 1703187776 517652976 10419264
09:05:02 16360 8192000000 10500465792 10981822464 1160860240 12.411 100 50 100 1 1640375664 466299184 13216240
09:06:23 14366 8192000000 10355387792 10898124800 1151053248 12.319 99 48 94 1 1471074112 308248320 11772544
09:07:45 26771 8192000000 10288367456 10788589568 1139191008 12.208 100 29 58 0 1400416448 253741648 7483792
09:09:13 64669 8192000000 10080726704 10567348224 1118546992 12.013 99 19 36 4 1228631280 104849920 5234368
09:11:21 56894 8192000000 10122269968 10571128832 1102564864 11.862 97 25 48 2 1183417936 76093104 4759968
09:13:20 50352 8192000000 10103119072 10550370304 1123192448 12.057 99 36 72 2 1212466064 81424208 7849408
09:15:10 82100 8192000000 10068378608 10541449216 1113887216 11.969 98 45 88 1 1162229728 38161808 10180704
09:17:33 60401 8192000000 10098402944 10554552320 1146004112 12.272 100 41 81 2 1179344400 24734384 8605904
09:19:42 60817 8192000000 10076880112 10524745728 1131142336 12.132 99 56 103 1 1161860608 19598304 11119968
09:21:45 42911 8192000000 10089007088 10518626304 1104010464 11.876 97 28 56 2 1152277376 41757184 6509728
09:23:30 15114 8192000000 10138196256 10558091264 1134802544 12.167 100 77 136 1 1243067632 93557296 14707792
09:24:46 45047 8192000000 10047800864 10550902784 1095771344 11.798 97 6 11 1 1149922336 52887184 1263808
09:26:31 46674 8192000000 10070680336 10528956416 1120588992 12.033 99 17 34 0 1150811088 26493088 3729008
09:28:27 70750 8192000000 10097422384 10568835072 1111610656 11.948 98 21 38 0 1189898288 74313680 3973952
09:31:04 85765 8192000000 10068635568 10589265920 1115176976 11.981 99 11 22 0 1204964672 87531168 2256528
09:33:30 74117 8192000000 10044744944 10524647424 1104908464 11.884 98 16 33 0 1178899152 70906336 3084352
09:35:56 48028 8192000000 10089412224 10539417600 1122626336 12.052 100 31 59 1 1189488720 60403504 6458880
09:38:05 16949 8192000000 10093219504 10533507072 1117931200 12.007 99 51 100 1 1178728304 50685952 10111152
09:39:28 151171 8192000000 10094126480 10563117056 1105727904 11.892 97 28 56 0 1178216384 67274800 5213680
09:43:00 71362 8192000000 10115908832 10597543936 1143218096 12.246 100 30 60 2 1217245072 68039024 5987952
09:45:12 35647 8192000000 10083801008 10515423232 1094315888 11.784 97 35 69 2 1152455200 50700976 7438336
09:46:51 56793 8192000000 10092746976 10524688384 1109078864 11.924 98 50 96 0 1185179536 67201504 8899168
09:48:52 51529 8192000000 10040152416 10539917312 1092541424 11.767 97 11 23 0 1127258240 32665728 2051088
09:50:52 80496 8192000000 10075183040 10534608896 1076943136 11.618 95 22 43 0 1120307600 38731184 4633280
09:53:13 31824 8192000000 10106732912 10542043136 1096250576 11.802 97 36 70 0 1180128272 76619392 7258304
09:54:47 31580 8192000000 10151699584 10605146112 1123000800 12.055 99 44 88 2 1248878464 116045936 9831728
09:56:23 47115 8192000000 10130350000 10586640384 1111271008 11.944 98 37 73 1 1201669824 82312992 8085824
09:58:12 61932 8192000000 10116493008 10587922432 1116821088 11.997 99 20 40 2 1217625856 96755456 4049312
10:00:23 64889 8192000000 10105931824 10547044352 1094794352 11.788 97 33 63 1 1155476880 53487120 7195408
10:02:29 41982 8192000000 10070505472 10516275200 1116072864 11.990 99 26 51 0 1164039664 43121824 4844976
10:04:11 133273 8192000000 10089312688 10558840832 1111112064 11.943 99 33 66 0 1187443136 69492848 6838224
10:09:04 80363 8192000000 10076095824 10569555968 1129568656 12.117 100 21 42 1 1195348768 60934848 4845264
10:11:25 89829 8192000000 10073877216 10521628672 1094278832 11.783 97 11 22 0 1162245760 65786592 2180336
10:13:55 21651 8192000000 10090421808 10524930048 1098483744 11.823 97 24 48 1 1149249184 45228272 5537168
10:15:18 25105 8192000000 10147296336 10595012608 1135414192 12.172 100 49 96 1 1244226816 97377120 11435504
10:16:48 20422 8192000000 10195735344 10632339456 1140340224 12.219 99 39 76 1 1276988176 128562784 8085168
10:18:19 24970 8192000000 10236754656 10675429376 1141596336 12.231 100 56 110 1 1323439184 170112768 11730080
10:19:51 24830 8192000000 10182633888 10602172416 1117686640 12.005 99 53 101 4 1254757648 126356192 10714816
10:21:24 65556 8192000000 10066417920 10560131072 1081353568 11.660 96 11 22 1 1197019808 113544768 2121472
10:23:31 66696 8192000000 10125882480 10610470912 1104020800 11.876 97 37 74 0 1199055600 87648400 7386400
10:25:41 50163 8192000000 10074542672 10496675840 1121016256 12.037 99 46 88 0 1160424000 30661392 8746352
10:27:33 113527 8192000000 10064164176 10504822784 1127317408 12.096 99 39 74 1 1175593008 40120608 8154992
10:30:55 65020 8192000000 10094279168 10552954880 1141617376 12.231 100 11 22 1 1202855632 59110352 2127904
10:33:12 79039 8192000000 10111616240 10579607552 1149778224 12.307 100 20 38 1 1215752672 61477216 4497232
10:35:34 33606 8192000000 10113745296 10555375616 1131616240 12.137 98 48 95 1 1214602256 72126384 10859632

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-05-2022 10:39:02 to 10-05-2022 10:39:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 181 v_add_invoice_to_stock 335544517
except 19 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-05-2022 10:39:18 to 10-05-2022 10:39:20.

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          218111
MON$OLDEST_ACTIVE               223133
MON$OLDEST_SNAPSHOT             223133
MON$NEXT_TRANSACTION            223133
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                       2041440
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             774
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2763 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2763 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2763 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2763 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 ---
:243: 
:244: mscs-test-161.budzdorov.ru    Tue May 10 10:37:20 2022
:245:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:246: 
:247: 
:248: mscs-test-161.budzdorov.ru    Tue May 10 10:37:22 2022
:249:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:250: 
:251: 
:252: mscs-test-161.budzdorov.ru    Tue May 10 10:37:23 2022
:253:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:254: 
--- end of diff output ---

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

10.05.22 10:39:22. 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-05-10 10:39:24 No severe PSQL-related problems occured
10.05.22 10:39:25. End of report.