Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.10.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 15249964 kB
MemAvailable 30514048 kB
Buffers 0 kB
Cached 16442092 kB
SwapCached 2132 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-10-10 10:37:22
DTS_END                         2022-10-10 10:37:22.0140
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46568
TRN_ID                          228697
ATT_ID                          599
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: 9065.07 22123 1087808
customer order: creation 177.33 489 21280
customer order: refuse 15.17 380 1820
order to supplier: creation 46.52 1335 5582
order to supplier: removal 3.38 151422 405
invoice (draft): creation 47.27 3014 5672
invoice (draft): removal 50.13 916 6016
invoice accept: apply 43.41 24623 5209
invoice accept: cancel 44.53 1123 5344
customer reserve: creation 4008.12 236 480974
customer reserve: removal 4329.12 93 519494
realization accept: apply 85.26 263 10231
realization accept: cancel 80.93 176 9712
payment from customer: creation 61.65 183 7398
payment from customer: removal 5.70 114 684
payment to supplier: creation 50.18 205 6021
payment to supplier: removal 3.75 152 450
service: total inventory turnovers 4.02 3746 482
service: total monetary turnovers 7.58 352 910
service: aggregate perf. data 1.00 14918 120
service: refresh index statistics 0.03 238712 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-10-2022 10:38:36 to 10-10-2022 10:38:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6201
TEST_TIME 2 6361
TEST_TIME 3 5830
TEST_TIME 4 5826
TEST_TIME 5 5907
TEST_TIME 6 5892
TEST_TIME 7 6218
TEST_TIME 8 6168
TEST_TIME 9 6639
TEST_TIME 10 6647
TEST_TIME 11 6814
TEST_TIME 12 7014
TEST_TIME 13 7082
TEST_TIME 14 7068
TEST_TIME 15 7134
TEST_TIME 16 7091
TEST_TIME 17 7329
TEST_TIME 18 7714
TEST_TIME 19 7790
TEST_TIME 20 7893
TEST_TIME 21 8400
TEST_TIME 22 8546
TEST_TIME 23 8604
TEST_TIME 24 8755
TEST_TIME 25 8992
TEST_TIME 26 9009
TEST_TIME 27 9503
TEST_TIME 28 9625
TEST_TIME 29 9854
TEST_TIME 30 10389
TEST_TIME 31 10899
TEST_TIME 32 11005
TEST_TIME 33 10908
TEST_TIME 34 11062
TEST_TIME 35 10993
TEST_TIME 36 10916
TEST_TIME 37 10782
TEST_TIME 38 10795
TEST_TIME 39 10660
TEST_TIME 40 10471
TEST_TIME 41 10476
TEST_TIME 42 10327
TEST_TIME 43 10290
TEST_TIME 44 10180
TEST_TIME 45 10141
TEST_TIME 46 10137
TEST_TIME 47 10049
TEST_TIME 48 9983
TEST_TIME 49 10021
TEST_TIME 50 9959
TEST_TIME 51 9919
TEST_TIME 52 9936
TEST_TIME 53 9870
TEST_TIME 54 9941
TEST_TIME 55 9898
TEST_TIME 56 9858
TEST_TIME 57 9916
TEST_TIME 58 9909
TEST_TIME 59 9943
TEST_TIME 60 9902
TEST_TIME 61 9945
TEST_TIME 62 9998
TEST_TIME 63 9996
TEST_TIME 64 10013
TEST_TIME 65 10042
TEST_TIME 66 9996
TEST_TIME 67 9965
TEST_TIME 68 9842
TEST_TIME 69 9749
TEST_TIME 70 9870
TEST_TIME 71 9784
TEST_TIME 72 9750
TEST_TIME 73 9690
TEST_TIME 74 9674
TEST_TIME 75 9602
TEST_TIME 76 9503
TEST_TIME 77 9556
TEST_TIME 78 9493
TEST_TIME 79 9470
TEST_TIME 80 9518
TEST_TIME 81 9517
TEST_TIME 82 9547
TEST_TIME 83 9579
TEST_TIME 84 9580
TEST_TIME 85 9597
TEST_TIME 86 9578
TEST_TIME 87 9607
TEST_TIME 88 9700
TEST_TIME 89 9677
TEST_TIME 90 9592
TEST_TIME 91 9588
TEST_TIME 92 9517
TEST_TIME 93 9535
TEST_TIME 94 9465
TEST_TIME 95 9454
TEST_TIME 96 9406
TEST_TIME 97 9380
TEST_TIME 98 9359
TEST_TIME 99 9351
TEST_TIME 100 9323
TEST_TIME 101 9317
TEST_TIME 102 9352
TEST_TIME 103 9362
TEST_TIME 104 9393
TEST_TIME 105 9397
TEST_TIME 106 9433
TEST_TIME 107 9433
TEST_TIME 108 9436
TEST_TIME 109 9381
TEST_TIME 110 9344
TEST_TIME 111 9323
TEST_TIME 112 9283
TEST_TIME 113 9236
TEST_TIME 114 9208
TEST_TIME 115 9194
TEST_TIME 116 9144
TEST_TIME 117 9171
TEST_TIME 118 9125
TEST_TIME 119 9150
TEST_TIME 120 9095

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-10-2022 10:38:40 to 10-10-2022 10:38:43.

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 1530859 1530859 0 0.00 0 16997 13 0 0
doc_list_biud 1530855 1530855 0 0.00 0 39955 37 0 0
sp_add_invoice_to_stock 5355 5209 146 2.72 47 244561 24624 0 146
sp_cancel_adding_invoice 5344 5344 0 0.00 6 81957 1124 0 0
sp_cancel_client_order 1820 1820 0 0.00 6 19245 380 0 0
sp_cancel_customer_reserve 519494 519494 0 0.00 1 39956 94 0 0
sp_cancel_pay_from_customer 684 684 0 0.00 3 4070 115 0 0
sp_cancel_pay_to_supplier 450 450 0 0.00 4 7277 153 0 0
sp_cancel_supplier_invoice 6024 6016 8 0.13 3 16257 917 0 8
sp_cancel_supplier_order 405 405 0 0.00 9 1340407 151423 0 0
sp_cancel_write_off 9712 9712 0 0.00 2 13758 176 0 0
sp_client_order 21280 21280 0 0.00 5 28315 489 0 0
sp_customer_reserve 480974 480974 0 0.00 0 121584 237 0 0
sp_fill_shopping_cart 32521 32521 0 0.00 1 13476 76 0 0
sp_fill_shopping_cart_clo_res 480945 480945 0 0.00 0 20762 18 0 0
sp_get_clo_for_invoice 5170 5170 0 0.00 0 30848 277 0 0
sp_kill_cost_storno 16190 16190 0 0.00 0 5230 65 0 0
sp_kill_qstorno_ret_qs2qd 1055121 1055121 0 0.00 0 39953 41 0 0
sp_kill_qty_storno 558192 558192 0 0.00 0 39955 94 0 0
sp_lock_selected_doc 21415 21415 0 0.00 0 8640 8 0 0
sp_make_cost_storno 28820 28820 0 0.00 0 16977 79 0 0
sp_multiply_rows_for_pdistr 28820 28820 0 0.00 0 7407 36 0 0
sp_multiply_rows_for_qdistr 21280 21280 0 0.00 0 9702 185 0 0
sp_pay_from_customer 7398 7398 0 0.00 6 17925 184 0 0
sp_pay_to_supplier 6021 6021 0 0.00 7 16927 205 0 0
sp_payment_common 13419 13419 0 0.00 2 17508 45 0 0
sp_qd_handle_on_cancel_clo 1820 1820 0 0.00 0 19239 315 0 0
sp_qd_handle_on_invoice_upd_sts 10514 10514 0 0.00 0 12057 461 0 0
sp_qd_handle_on_reserve_upd_sts 10230 10230 0 0.00 0 13320 61 0 0
sp_reserve_write_off 10231 10231 0 0.00 9 15532 264 0 0
sp_supplier_invoice 5672 5672 0 0.00 8 83201 3014 0 0
sp_supplier_order 5582 5582 0 0.00 7 33209 1336 0 0
srv_aggregate_perf_data 120 120 0 0.00 2400 152321 14918 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 846 138723 33726 0 0
srv_find_qd_qs_mism 1037256 1037256 0 0.00 0 6303 10 0 0
srv_make_invnt_saldo 482 482 0 0.00 91 70448 3747 0 0
srv_make_money_saldo 910 910 0 0.00 1 12425 352 0 0
srv_recalc_idx_stat 4 4 0 0.00 91749 419144 238712 0 0
t$perf-abend:sp_add_invoice_to_stock 146 146 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 5216 5216 0 0.00 1 10656 818 0 0
t$perf-norm:sp_cancel_adding_invoice 423 423 0 0.00 2 7584 410 0 0
t$perf-norm:sp_cancel_client_order 1820 1820 0 0.00 0 971 24 0 0
t$perf-norm:sp_cancel_customer_reserve 729 729 0 0.00 0 1369 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 684 684 0 0.00 0 746 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 450 450 0 0.00 0 1648 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 429 429 0 0.00 0 685 27 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 84368 5900 0 0
t$perf-norm:sp_cancel_write_off 1081 1081 0 0.00 0 7162 24 0 0
t$perf-norm:sp_client_order 21281 21281 0 0.00 0 6976 33 0 0
t$perf-norm:sp_customer_reserve 1028 1028 0 0.00 0 4344 25 0 0
t$perf-norm:sp_pay_from_customer 7398 7398 0 0.00 0 10632 19 0 0
t$perf-norm:sp_pay_to_supplier 6021 6021 0 0.00 0 5852 21 0 0
t$perf-norm:sp_reserve_write_off 10231 10231 0 0.00 0 10844 22 0 0
t$perf-norm:sp_supplier_invoice 5672 5672 0 0.00 0 5083 29 0 0
t$perf-norm:sp_supplier_order 5583 5583 0 0.00 0 2541 29 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 283 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 363 8 0 0
t$perf-norm:srv_make_invnt_saldo 482 482 0 0.00 0 2742 15 0 0
t$perf-norm:srv_make_money_saldo 910 910 0 0.00 0 1163 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 172 84 0 0
v_add_invoice_to_stock 10620 10328 292 2.74 0 8386 25 0 292
v_all_customers 39026 39026 0 0.00 0 82 4 0 0
v_all_suppliers 31172 31172 0 0.00 0 68 4 0 0
v_all_wares 42550 42550 0 0.00 0 81 4 0 0
v_cancel_adding_invoice 846 846 0 0.00 0 1615 19 0 0
v_cancel_client_order 3640 3640 0 0.00 0 2032 9 0 0
v_cancel_customer_prepayment 1368 1368 0 0.00 0 3979 17 0 0
v_cancel_customer_reserve 1458 1458 0 0.00 0 394 9 0 0
v_cancel_payment_to_supplier 900 900 0 0.00 0 3382 15 0 0
v_cancel_supplier_invoice 874 858 16 1.83 0 1193 24 0 16
v_cancel_supplier_order 810 810 0 0.00 0 1364 11 0 0
v_cancel_write_off 2162 2162 0 0.00 0 4741 23 0 0
v_max_id_clo_ord 5579 5579 0 0.00 0 1328 5 0 0
v_max_id_clo_res 1028 1028 0 0.00 0 7982 18 0 0
v_max_id_ord_sup 5665 5665 0 0.00 0 3015 7 0 0
v_max_non_paid_invoice 1679 1679 0 0.00 0 54 7 0 0
v_max_non_paid_realizn 3838 3838 0 0.00 0 4228 8 0 0
v_min_id_clo_ord 5579 5579 0 0.00 0 1659 8 0 0
v_min_id_clo_res 1028 1028 0 0.00 0 2587 15 0 0
v_min_id_ord_sup 5665 5665 0 0.00 0 6616 8 0 0
v_min_non_paid_invoice 6021 6021 0 0.00 0 7394 16 0 0
v_min_non_paid_realizn 7398 7398 0 0.00 0 10813 18 0 0
v_our_firm 10644 10644 0 0.00 0 84 4 0 0
v_reserve_write_off 20460 20460 0 0.00 0 3885 12 0 0
x_lock_depdocs_on_canc_invoice 10931 10931 0 0.00 0 19086 124 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 2653 301 0 0
x_make_qstorno_1000_1200 5582 5582 0 0.00 2 33184 1184 0 0
x_make_qstorno_1200_2000 5672 5672 0 0.00 4 83145 2807 0 0
x_make_qstorno_2100_3300 480974 480974 0 0.00 0 121569 207 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-10-2022 10:38:44 to 10-10-2022 10:38:48.

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:25 1 8192000000 8797978752 8809009152 4149472 0.050 2 1 2 0 4248864 63616 35776
08:08:26 41595 8192000000 9729398352 10030374912 804378816 8.941 100 88 176 0 833312976 7430528 21503632
08:10:10 15396 8192000000 9754120592 10063302656 831010624 9.209 100 87 174 0 865275840 13458384 20806832
08:11:30 17154 8192000000 9808437776 10130604032 870788832 9.608 100 83 166 0 911911200 22275488 18846880
08:12:53 7132 8192000000 9881122848 10215763968 927815616 10.173 100 80 154 0 991264944 46570672 16878656
08:14:03 2576 8192000000 9933943952 10280599552 966426560 10.552 100 92 179 0 1042941344 58244096 18270688
08:15:08 1137 8192000000 9980106576 10336313344 1002322016 10.901 100 92 167 0 1099114624 80113856 16678752
08:16:11 8560 8192000000 10003532400 10367062016 1028211376 11.151 100 79 146 0 1121392368 79862432 13318560
08:17:21 926 8192000000 10029085520 10402353152 1056249728 11.421 100 93 182 0 1155763936 80631792 18882416
08:18:23 996 8192000000 10041032096 10424029184 1074441808 11.594 100 93 175 1 1165874640 73441536 17991296
08:19:25 923 8192000000 10029621344 10406555648 1077611920 11.625 100 95 182 1 1149409104 54420160 17377024
08:20:26 1176 8192000000 10039723488 10425942016 1082956336 11.676 100 84 164 2 1164517072 64551536 17009200
08:21:29 1260 8192000000 10045692400 10434867200 1091564400 11.758 100 84 156 1 1167963344 59098560 17300384
08:22:31 1245 8192000000 10059855536 10447446016 1098592560 11.824 100 92 175 2 1186973440 70191728 18189152
08:23:33 1029 8192000000 10076675056 10467307520 1100560176 11.843 100 85 161 0 1195376704 78228752 16587776
08:24:35 1255 8192000000 10070799712 10459463680 1103824176 11.874 100 95 186 1 1192154960 68943824 19386960
08:25:37 1000 8192000000 10059942608 10447855616 1103162016 11.868 100 94 177 1 1184394464 61525440 19707008
08:26:39 2347 8192000000 10033767200 10427801600 1106364880 11.898 100 63 120 2 1154385632 35913840 12106912
08:27:42 880 8192000000 10053720960 10442731520 1100150416 11.839 100 89 170 1 1176759488 58718048 17891024
08:28:44 1185 8192000000 10040430672 10425634816 1090537200 11.748 100 95 177 3 1170772960 61243040 18992720
08:29:46 1873 8192000000 10051878064 10441830400 1090939328 11.752 100 78 146 1 1177290976 71059808 15291840
08:30:49 1215 8192000000 10027233856 10408833024 1050578576 11.366 99 82 160 0 1150550624 83657872 16314176
08:31:51 1121 8192000000 9991994784 10366152704 1044928880 11.312 100 92 176 1 1117670464 55230336 17511248
08:32:53 1975 8192000000 10005265584 10381930496 1027298320 11.142 99 75 139 1 1131280320 90340544 13641456
08:33:55 5288 8192000000 9995968432 10381529088 1049891120 11.360 100 57 104 2 1129889504 69445152 10553232
08:35:01 5837 8192000000 9995582240 10376245248 1048578688 11.347 100 70 127 2 1115125216 53332416 13214112
08:36:08 9898 8192000000 10009723232 10398732288 1045878784 11.321 100 55 107 1 1133725744 77407664 10439296
08:37:20 1106 8192000000 10014215424 10392662016 1041790416 11.282 99 93 173 1 1132198976 73791456 16617104
08:38:26 2427 8192000000 10029258672 10412699648 1074224464 11.592 100 84 154 3 1140452176 50777360 15450352
08:39:36 2007 8192000000 10035572368 10419851264 1085230096 11.697 100 79 137 0 1157733840 59478656 13025088
08:40:49 7303 8192000000 10055512832 10451578880 1102771424 11.864 100 60 109 0 1178806192 64352208 11682560
08:42:10 3756 8192000000 10127467104 10524954624 1123995520 12.065 100 68 128 0 1250226000 111948704 14281776
08:43:20 17414 8192000000 10170823312 10570723328 1127841360 12.101 100 64 128 0 1279810432 138520016 13449056
08:44:45 1227 8192000000 10200379904 10600710144 1133561696 12.155 100 89 168 1 1313167568 160971952 18633920
08:45:49 1304 8192000000 10228304320 10622705664 1148208848 12.293 100 95 188 0 1342271072 173250464 20811760
08:46:54 1000 8192000000 10232503504 10630627328 1162058496 12.423 100 91 172 1 1347670016 166562160 19049360
08:47:58 1681 8192000000 10259969040 10663104512 1161188528 12.414 100 79 147 0 1374675552 197867936 15619088
08:49:03 3512 8192000000 10305149664 10719207424 1171759200 12.513 100 87 172 0 1414529648 222945920 19824528
08:50:12 2215 8192000000 10306548016 10728083456 1180977792 12.599 100 72 141 0 1425134896 228109520 16047584
08:51:17 1084 8192000000 10365303728 10771980288 1192207776 12.704 100 95 181 0 1475171280 262604560 20358944
08:52:20 1003 8192000000 10392414848 10798616576 1185869328 12.645 100 90 174 1 1502298896 296078224 20351344
08:53:25 1326 8192000000 10412746304 10839461888 1187572112 12.661 100 90 171 1 1522106176 314352912 20181152
08:54:32 1258 8192000000 10438873856 10869686272 1191060272 12.693 100 100 188 0 1548568032 334148704 23359056
08:55:35 1540 8192000000 10473484784 10900357120 1203874144 12.812 100 88 173 3 1583577344 359784544 19918656
08:56:39 1374 8192000000 10500211232 10918125568 1202037408 12.795 100 78 148 0 1615986736 396255648 17693680
08:57:43 839 8192000000 10516974976 10974404608 1209114816 12.861 100 94 183 1 1622361520 391253312 21993392
08:58:46 2590 8192000000 10492786928 10918907904 1185516448 12.642 99 75 150 0 1589223424 385542304 18164672
08:59:51 6490 8192000000 10487210672 10951249920 1180415232 12.594 100 55 110 0 1600217456 406120368 13681856
09:01:12 1097 8192000000 10529981536 10965344256 1190564288 12.689 100 84 165 0 1632667392 422783520 19319584
09:02:15 12648 8192000000 10477706192 10971471872 1171573296 12.512 99 45 88 0 1574229952 391176464 11480192
09:03:34 16741 8192000000 10484636064 10920476672 1168908256 12.487 100 37 74 0 1582323840 403532512 9883072
09:05:12 30281 8192000000 10410506320 10896195584 1156652880 12.372 99 24 48 0 1510286416 347731680 5901856
09:06:44 23756 8192000000 10322398048 10840739840 1138893296 12.205 100 20 40 0 1472209872 327986352 5330224
09:08:56 33606 8192000000 10108625872 10543058944 1143109360 12.245 99 26 51 1 1225211440 77208304 4893776
09:10:34 5907 8192000000 10110431680 10527252480 1107424768 11.908 97 68 119 1 1184946944 64523376 12998800
09:11:44 76831 8192000000 10061354640 10508771328 1094379408 11.784 96 6 10 0 1128755744 33516448 859888
09:14:06 103513 8192000000 10073108160 10531237888 1102680640 11.863 98 33 67 0 1163934688 54490608 6763440
09:16:58 80335 8192000000 10077473472 10543034368 1093109312 11.772 97 24 48 0 1158723328 60616608 4997408
09:19:19 2870 8192000000 10018668048 10443558912 1100396592 11.841 99 66 119 2 1118379440 4974896 13007952
09:20:26 138721 8192000000 10053663472 10479693824 1083842368 11.684 96 51 91 0 1161626208 67273792 10510048
09:23:45 15438 8192000000 10095092896 10533769216 1138156448 12.198 100 25 48 1 1213852480 70659456 5036576
09:25:02 23723 8192000000 10140117952 10581704704 1135078144 12.169 100 51 100 1 1236488192 89389536 12020512
09:26:33 43364 8192000000 10156386768 10624843776 1132609616 12.146 99 28 54 0 1261231776 122444080 6178080
09:28:35 50929 8192000000 10150873824 10611789824 1128186416 12.104 99 15 30 0 1231999520 100560688 3252416
09:30:30 36112 8192000000 10073713024 10542809088 1134040624 12.159 100 11 22 0 1182432336 46336192 2055520
09:32:12 50041 8192000000 10090034608 10558943232 1127778208 12.100 99 20 40 0 1197675280 65840608 4056464
09:34:06 49412 8192000000 10097813472 10547109888 1145098784 12.263 100 18 36 0 1225263856 76030000 4135072
09:35:59 35582 8192000000 10147523968 10589253632 1125360768 12.078 98 34 65 0 1202918800 70781088 6776944
09:37:38 44218 8192000000 10112109776 10541912064 1124792624 12.072 98 31 62 0 1196629552 65545376 6291552
09:39:23 114962 8192000000 10098179472 10567667712 1104756896 11.883 96 14 27 0 1161502608 54470880 2274832
09:42:18 57896 8192000000 10100388496 10580287488 1136597600 12.184 99 20 40 0 1193826064 52767984 4460480
09:44:17 21010 8192000000 10121692192 10562068480 1141387936 12.229 99 41 82 0 1208698432 59292640 8017856
09:46:14 36948 8192000000 10189343920 10627923968 1141852448 12.233 99 55 104 0 1265452096 111387056 12212592
09:47:54 41476 8192000000 10077866448 10556223488 1122111696 12.047 98 25 50 1 1193115488 65755936 5247856
09:49:35 37866 8192000000 10085273360 10568454144 1135851664 12.176 100 10 20 0 1200497296 62515808 2129824
09:51:18 57125 8192000000 10079702528 10531741696 1113948208 11.970 98 22 42 0 1175398256 56638448 4811600
09:53:40 71835 8192000000 10094032256 10604347392 1124496272 12.069 99 6 12 0 1177329632 51645520 1187840
09:55:54 36107 8192000000 10119440864 10556616704 1111966128 11.951 97 26 51 0 1208150944 90875904 5308912
09:57:36 47201 8192000000 10159580032 10629279744 1138666416 12.203 99 33 66 1 1244916368 99460800 6789152
09:59:23 55147 8192000000 10099390704 10567741440 1104670928 11.882 97 18 36 0 1199391296 90922096 3798272
10:01:21 23763 8192000000 10123650992 10552082432 1143638464 12.250 100 50 97 1 1207653248 54673472 9341312
10:02:46 14087 8192000000 10116475296 10551906304 1123956608 12.064 100 48 98 1 1198388848 64871904 9560336
10:04:03 27244 8192000000 10157889568 10623930368 1125072192 12.075 100 49 98 0 1251301056 116617696 9611168
10:05:33 63441 8192000000 10052332496 10478399488 1039106544 11.256 93 21 42 0 1093120416 49709872 4304000
10:07:37 79680 8192000000 10040768320 10469896192 1097969312 11.818 98 33 67 0 1120736288 16446656 6320320
10:09:59 15357 8192000000 10051069776 10494758912 1085513792 11.700 97 35 67 1 1144519616 51979920 7025904
10:11:21 53238 8192000000 10083270656 10552487936 1119677680 12.024 100 12 24 0 1190881216 68948016 2255520
10:13:15 45250 8192000000 10042841792 10483929088 1099289024 11.831 98 18 37 0 1138206960 34996992 3920944
10:15:00 24882 8192000000 10083821376 10514866176 1121606736 12.042 99 35 70 0 1168755216 39790912 7357568
10:16:28 23333 8192000000 10109681472 10567987200 1137335952 12.190 100 41 75 1 1223500176 78047872 8116352
10:18:18 31532 8192000000 10173814320 10614886400 1157637280 12.381 100 42 83 0 1268866144 102457488 8771376
10:19:53 27361 8192000000 10155701216 10601041920 1143986400 12.253 99 32 64 0 1248394560 97398960 7009200
10:21:25 56047 8192000000 10152382944 10596294656 1136568240 12.183 99 33 66 0 1219232768 76162304 6502224
10:23:33 53654 8192000000 10108434800 10566905856 1126536160 12.089 98 36 72 0 1172259312 37818432 7904720
10:25:29 13258 8192000000 10105077536 10532352000 1144453824 12.257 100 48 89 1 1199141824 45475776 9212224
10:26:44 77923 8192000000 10078303104 10545684480 1146576528 12.277 100 30 58 0 1201939696 49431392 5931776
10:29:21 86886 8192000000 10060344624 10522251264 1115981200 11.989 97 7 12 0 1141547072 24259568 1306304
10:32:01 72815 8192000000 10084555568 10557513728 1138787248 12.204 100 13 24 0 1193840384 52042192 3010944
10:34:19 79818 8192000000 10086178464 10530197504 1097491536 11.814 96 13 25 0 1152470816 52301248 2678032
10:36:40 44345 8192000000 9650135360 10043113472 751619776 8.403 66 25 48 0 809709024 52689824 5399424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 10-10-2022 10:38:50 to 10-10-2022 10:39:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 146 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 10-10-2022 10:39:08 to 10-10-2022 10:39:10.

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          228997
MON$OLDEST_ACTIVE               228998
MON$OLDEST_SNAPSHOT             228998
MON$NEXT_TRANSACTION            228998
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                       2060256
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             784
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2856 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2856 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2856 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2856 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 ---
:103: 
:104: mscs-test-161.budzdorov.ru    Mon Oct 10 10:37:33 2022
:105:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:106: 
:107: 
:108: mscs-test-161.budzdorov.ru    Mon Oct 10 10:37:43 2022
:109:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:110: 
:111: 
:112: mscs-test-161.budzdorov.ru    Mon Oct 10 10:37:43 2022
:113:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:114: 
--- end of diff output ---

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

10.10.22 10:39:11. 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-10-10 10:39:14 No severe PSQL-related problems occured
10.10.22 10:39:15. End of report.