Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.03.2023 22:39
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 15115360 kB
MemAvailable 30422952 kB
Buffers 0 kB
Cached 16568516 kB
SwapCached 3032 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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 2023-03-10 22:38:42
DTS_END                         2023-03-10 22:38:42.9810
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(57:17)
IP                              ::1/45502
TRN_ID                          241674
ATT_ID                          658
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: 9255.47 24609 1110656
customer order: creation 171.62 374 20594
customer order: refuse 14.93 350 1792
order to supplier: creation 47.19 1332 5663
order to supplier: removal 3.40 118832 408
invoice (draft): creation 45.64 3102 5477
invoice (draft): removal 53.64 897 6437
invoice accept: apply 41.71 18876 5005
invoice accept: cancel 48.28 793 5794
customer reserve: creation 3791.30 191 454956
customer reserve: removal 4738.39 66 568607
realization accept: apply 84.78 174 10173
realization accept: cancel 83.70 111 10044
payment from customer: creation 59.98 102 7198
payment from customer: removal 5.58 94 669
payment to supplier: creation 49.42 113 5930
payment to supplier: removal 3.27 64 392
service: total inventory turnovers 4.91 1234 589
service: total monetary turnovers 6.85 43 822
service: aggregate perf. data 0.85 21784 102
service: refresh index statistics 0.03 323642 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-03-2023 22:39:35 to 10-03-2023 22:39:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6979
TEST_TIME 2 5931
TEST_TIME 3 6715
TEST_TIME 4 7132
TEST_TIME 5 7000
TEST_TIME 6 6401
TEST_TIME 7 6071
TEST_TIME 8 5993
TEST_TIME 9 6361
TEST_TIME 10 6417
TEST_TIME 11 6315
TEST_TIME 12 6557
TEST_TIME 13 6895
TEST_TIME 14 6724
TEST_TIME 15 7206
TEST_TIME 16 7253
TEST_TIME 17 7767
TEST_TIME 18 7666
TEST_TIME 19 8008
TEST_TIME 20 8252
TEST_TIME 21 8197
TEST_TIME 22 8410
TEST_TIME 23 8874
TEST_TIME 24 8932
TEST_TIME 25 9478
TEST_TIME 26 9579
TEST_TIME 27 9695
TEST_TIME 28 9671
TEST_TIME 29 9668
TEST_TIME 30 9927
TEST_TIME 31 10701
TEST_TIME 32 10943
TEST_TIME 33 11500
TEST_TIME 34 11433
TEST_TIME 35 11136
TEST_TIME 36 11004
TEST_TIME 37 10819
TEST_TIME 38 10644
TEST_TIME 39 10528
TEST_TIME 40 10434
TEST_TIME 41 10265
TEST_TIME 42 10144
TEST_TIME 43 10105
TEST_TIME 44 10038
TEST_TIME 45 9909
TEST_TIME 46 9810
TEST_TIME 47 9824
TEST_TIME 48 9733
TEST_TIME 49 9855
TEST_TIME 50 9738
TEST_TIME 51 9693
TEST_TIME 52 9543
TEST_TIME 53 9540
TEST_TIME 54 9498
TEST_TIME 55 9397
TEST_TIME 56 9399
TEST_TIME 57 9301
TEST_TIME 58 9253
TEST_TIME 59 9261
TEST_TIME 60 9468
TEST_TIME 61 9503
TEST_TIME 62 9446
TEST_TIME 63 9473
TEST_TIME 64 9511
TEST_TIME 65 9547
TEST_TIME 66 9612
TEST_TIME 67 9597
TEST_TIME 68 9625
TEST_TIME 69 9587
TEST_TIME 70 9527
TEST_TIME 71 9587
TEST_TIME 72 9575
TEST_TIME 73 9620
TEST_TIME 74 9561
TEST_TIME 75 9449
TEST_TIME 76 9433
TEST_TIME 77 9445
TEST_TIME 78 9396
TEST_TIME 79 9358
TEST_TIME 80 9369
TEST_TIME 81 9325
TEST_TIME 82 9302
TEST_TIME 83 9268
TEST_TIME 84 9177
TEST_TIME 85 9227
TEST_TIME 86 9183
TEST_TIME 87 9271
TEST_TIME 88 9276
TEST_TIME 89 9302
TEST_TIME 90 9288
TEST_TIME 91 9323
TEST_TIME 92 9359
TEST_TIME 93 9371
TEST_TIME 94 9361
TEST_TIME 95 9427
TEST_TIME 96 9396
TEST_TIME 97 9399
TEST_TIME 98 9434
TEST_TIME 99 9435
TEST_TIME 100 9347
TEST_TIME 101 9321
TEST_TIME 102 9299
TEST_TIME 103 9256
TEST_TIME 104 9280
TEST_TIME 105 9248
TEST_TIME 106 9204
TEST_TIME 107 9176
TEST_TIME 108 9170
TEST_TIME 109 9163
TEST_TIME 110 9197
TEST_TIME 111 9205
TEST_TIME 112 9205
TEST_TIME 113 9196
TEST_TIME 114 9187
TEST_TIME 115 9240
TEST_TIME 116 9220
TEST_TIME 117 9216
TEST_TIME 118 9260
TEST_TIME 119 9255
TEST_TIME 120 9265

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-03-2023 22:39:39 to 10-03-2023 22:39: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 1532506 1532506 0 0.00 0 23388 7 0 0
doc_list_biud 1532506 1532506 0 0.00 0 25006 31 0 0
sp_add_invoice_to_stock 5138 5005 133 2.58 37 215223 18876 0 133
sp_cancel_adding_invoice 5794 5794 0 0.00 4 56964 793 0 0
sp_cancel_client_order 1792 1792 0 0.00 3 11281 350 0 0
sp_cancel_customer_reserve 568607 568607 0 0.00 0 18388 67 0 0
sp_cancel_pay_from_customer 669 669 0 0.00 3 6542 94 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 3 1669 65 0 0
sp_cancel_supplier_invoice 6449 6437 12 0.18 1 25008 898 0 12
sp_cancel_supplier_order 408 408 0 0.00 7 1062886 118832 0 0
sp_cancel_write_off 10044 10044 0 0.00 1 10586 112 0 0
sp_client_order 20594 20594 0 0.00 3 31669 374 0 0
sp_customer_reserve 454956 454956 0 0.00 0 55810 192 0 0
sp_fill_shopping_cart 31732 31732 0 0.00 1 28566 40 0 0
sp_fill_shopping_cart_clo_res 454948 454948 0 0.00 0 19115 13 0 0
sp_get_clo_for_invoice 4988 4988 0 0.00 0 27065 300 0 0
sp_kill_cost_storno 16899 16899 0 0.00 0 7755 55 0 0
sp_kill_qstorno_ret_qs2qd 1154103 1154103 0 0.00 0 25004 36 0 0
sp_kill_qty_storno 608242 608242 0 0.00 0 25005 77 0 0
sp_lock_selected_doc 20901 20901 0 0.00 0 10551 4 0 0
sp_make_cost_storno 28288 28288 0 0.00 0 12688 47 0 0
sp_multiply_rows_for_pdistr 28288 28288 0 0.00 0 14979 26 0 0
sp_multiply_rows_for_qdistr 20594 20594 0 0.00 0 10971 147 0 0
sp_pay_from_customer 7198 7198 0 0.00 4 11190 102 0 0
sp_pay_to_supplier 5930 5930 0 0.00 4 14986 113 0 0
sp_payment_common 13128 13128 0 0.00 1 10341 25 0 0
sp_qd_handle_on_cancel_clo 1792 1792 0 0.00 0 11273 314 0 0
sp_qd_handle_on_invoice_upd_sts 10781 10781 0 0.00 0 19197 338 0 0
sp_qd_handle_on_reserve_upd_sts 10173 10173 0 0.00 0 24669 69 0 0
sp_reserve_write_off 10173 10173 0 0.00 6 24680 175 0 0
sp_supplier_invoice 5477 5477 0 0.00 5 69647 3103 0 0
sp_supplier_order 5663 5663 0 0.00 4 29930 1332 0 0
srv_aggregate_perf_data 102 102 0 0.00 2958 195070 21784 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1281 122014 39961 0 0
srv_find_qd_qs_mism 1063283 1063283 0 0.00 0 23387 6 0 0
srv_make_invnt_saldo 589 589 0 0.00 21 150177 1234 0 0
srv_make_money_saldo 822 822 0 0.00 1 11039 44 0 0
srv_recalc_idx_stat 4 4 0 0.00 182811 412781 323642 0 0
t$perf-abend:sp_add_invoice_to_stock 133 133 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5005 5005 0 0.00 0 12310 198 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 0 3998 84 0 0
t$perf-norm:sp_cancel_client_order 1792 1792 0 0.00 0 5673 17 0 0
t$perf-norm:sp_cancel_customer_reserve 714 714 0 0.00 0 9476 38 0 0
t$perf-norm:sp_cancel_pay_from_customer 669 669 0 0.00 0 3357 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 1005 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 7936 47 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 34023 1839 0 0
t$perf-norm:sp_cancel_write_off 966 966 0 0.00 0 5843 12 0 0
t$perf-norm:sp_client_order 20594 20594 0 0.00 0 9920 20 0 0
t$perf-norm:sp_customer_reserve 981 981 0 0.00 0 1938 8 0 0
t$perf-norm:sp_pay_from_customer 7198 7198 0 0.00 0 10262 12 0 0
t$perf-norm:sp_pay_to_supplier 5930 5930 0 0.00 0 5986 13 0 0
t$perf-norm:sp_reserve_write_off 10173 10173 0 0.00 0 10305 11 0 0
t$perf-norm:sp_supplier_invoice 5477 5477 0 0.00 0 5245 19 0 0
t$perf-norm:sp_supplier_order 5663 5663 0 0.00 0 9820 23 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 486 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 9932 154 0 0
t$perf-norm:srv_make_invnt_saldo 589 589 0 0.00 0 761 18 0 0
t$perf-norm:srv_make_money_saldo 822 822 0 0.00 0 2129 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 10240 9974 266 2.59 0 6358 22 0 266
v_all_customers 37556 37556 0 0.00 0 155 1 0 0
v_all_suppliers 30954 30954 0 0.00 0 28 1 0 0
v_all_wares 41188 41188 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 1443 9 0 0
v_cancel_client_order 3584 3584 0 0.00 0 6436 10 0 0
v_cancel_customer_prepayment 1338 1338 0 0.00 0 5401 12 0 0
v_cancel_customer_reserve 1428 1428 0 0.00 0 5284 12 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 1617 6 0 0
v_cancel_supplier_invoice 856 832 24 2.80 0 1830 17 0 24
v_cancel_supplier_order 816 816 0 0.00 0 1681 10 0 0
v_cancel_write_off 1932 1932 0 0.00 0 2986 16 0 0
v_max_id_clo_ord 5662 5662 0 0.00 0 2478 2 0 0
v_max_id_clo_res 981 981 0 0.00 0 3030 16 0 0
v_max_id_ord_sup 5476 5476 0 0.00 0 4619 3 0 0
v_max_non_paid_invoice 1591 1591 0 0.00 0 938 3 0 0
v_max_non_paid_realizn 3899 3899 0 0.00 0 3969 6 0 0
v_min_id_clo_ord 5662 5662 0 0.00 0 6163 9 0 0
v_min_id_clo_res 981 981 0 0.00 0 3936 7 0 0
v_min_id_ord_sup 5476 5476 0 0.00 0 2986 4 0 0
v_min_non_paid_invoice 5930 5930 0 0.00 0 9630 18 0 0
v_min_non_paid_realizn 7198 7198 0 0.00 0 10338 18 0 0
v_our_firm 10230 10230 0 0.00 0 55 1 0 0
v_reserve_write_off 20346 20346 0 0.00 0 6817 7 0 0
x_lock_depdocs_on_canc_invoice 11815 11815 0 0.00 0 11722 139 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 5749 378 0 0
x_make_qstorno_1000_1200 5663 5663 0 0.00 1 29886 1240 0 0
x_make_qstorno_1200_2000 5477 5477 0 0.00 2 69599 2973 0 0
x_make_qstorno_2100_3300 454956 454956 0 0.00 0 38656 175 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-03-2023 22:39:44 to 10-03-2023 22:39: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
20:08:46 1 8192000000 8798320528 8809381888 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:47 32586 8192000000 9919401360 10232430592 998496416 10.864 100 84 168 1 1026604832 6508272 21600144
20:11:25 6682 8192000000 9949736624 10270171136 1033066592 11.198 100 93 187 0 1065102448 8587424 23448432
20:12:37 8341 8192000000 9983100048 10311356416 1062140848 11.477 100 87 174 0 1093804320 10177408 21486064
20:13:49 7916 8192000000 10009543552 10354089984 1101793168 11.855 100 82 164 0 1132918544 11011152 20114224
20:15:04 4482 8192000000 10066257472 10421972992 1153320976 12.341 100 83 164 0 1185306000 13160128 18824896
20:16:10 4505 8192000000 10115647280 10477346816 1194947088 12.729 100 85 169 0 1227909488 13712352 19250048
20:17:17 2238 8192000000 10143864688 10515517440 1233729728 13.088 100 80 154 0 1262512832 12508768 16274336
20:18:21 1538 8192000000 10175108128 10551533568 1262021152 13.349 100 87 174 0 1294979136 13956240 19001744
20:19:24 1303 8192000000 10202196896 10584080384 1284808016 13.557 100 86 170 0 1317482592 13670464 19004112
20:20:26 12348 8192000000 10176054768 10583670784 1289967312 13.604 100 44 87 2 1308449536 8535424 9946800
20:21:40 3354 8192000000 10192714464 10599374848 1301973552 13.713 100 32 64 0 1317342992 8364048 7005392
20:22:46 6649 8192000000 10192475616 10608295936 1307252640 13.761 100 17 34 0 1317387408 6111728 4023040
20:24:00 64468 8192000000 10206182880 10629615616 1320846064 13.884 100 34 67 0 1335521536 6884592 7790880
20:26:06 7734 8192000000 10214423728 10628538368 1318757040 13.865 99 31 58 0 1330003024 4799536 6446448
20:27:20 7009 8192000000 10212534464 10633330688 1315089776 13.832 99 20 40 0 1326012624 6494288 4428560
20:28:27 66450 8192000000 10192773424 10609225728 1275357568 13.471 97 25 48 0 1286082672 4859792 5865312
20:30:34 4145 8192000000 10165823312 10558394368 1249302672 13.232 99 46 90 1 1266536016 6866336 10367008
20:31:42 5222 8192000000 10140582304 10538528768 1219345232 12.956 97 19 38 1 1228929904 5684960 3899712
20:32:49 55771 8192000000 10147118784 10545758208 1228865056 13.044 97 25 50 0 1239506336 4506448 6134832
20:34:45 8355 8192000000 10172211840 10580590592 1281967552 13.531 100 34 67 0 1296558496 6808096 7782848
20:35:53 7467 8192000000 10192895904 10605137920 1300476208 13.700 100 21 42 0 1310732784 5196512 5060064
20:37:02 12769 8192000000 10200195920 10622656512 1314478096 13.827 100 18 38 1 1323867312 5425264 3963952
20:38:16 35029 8192000000 10224885520 10637537280 1326768976 13.938 100 30 56 0 1338420640 5436448 6215216
20:39:55 4953 8192000000 10258394304 10673684480 1350709824 14.154 100 51 99 0 1370775760 9377936 10688000
20:41:06 8932 8192000000 10281602912 10696261632 1366547552 14.296 100 58 112 0 1391700144 12367344 12785248
20:42:24 12182 8192000000 10293594688 10715795456 1380142384 14.418 100 50 98 0 1406397008 15128880 11125744
20:43:40 6861 8192000000 10295515200 10729336832 1389316080 14.500 100 34 67 1 1408836384 12038592 7481712
20:44:49 7831 8192000000 10298663936 10722570240 1366604576 14.297 98 50 95 0 1394822224 17373440 10844208
20:46:05 10791 8192000000 10317617728 10744594432 1395667504 14.556 100 57 114 0 1429672208 21070576 12934128
20:47:20 6759 8192000000 10330737520 10756370432 1403316128 14.625 100 47 90 2 1440676992 26377264 10983600
20:48:32 4769 8192000000 10326336480 10755932160 1402014320 14.613 99 55 109 0 1437759616 23680736 12064560
20:49:40 1279 8192000000 10344089408 10761801728 1413442896 14.715 100 69 131 1 1454791600 25302624 16046080
20:50:43 1690 8192000000 10333302704 10743705600 1392900288 14.532 100 80 153 0 1439476752 28195168 18381296
20:51:50 3444 8192000000 10334325760 10743304192 1383373008 14.447 99 71 141 0 1435261808 34123168 17765632
20:52:58 3259 8192000000 10334027456 10740621312 1373149024 14.355 98 70 136 0 1434332480 44242768 16940688
20:54:10 8158 8192000000 10324512480 10730455040 1370116352 14.328 100 72 141 1 1430061680 42303776 17641552
20:55:29 1601 8192000000 10348831168 10761445376 1393622544 14.538 100 69 139 1 1457801024 45570272 18608208
20:56:35 4650 8192000000 10300794528 10712084480 1353555952 14.179 99 49 98 1 1406753792 40192688 13005152
20:57:46 5569 8192000000 10298701584 10713444352 1344787184 14.101 99 59 118 0 1401734304 41476064 15471056
20:59:11 6976 8192000000 10319407056 10740355072 1360948128 14.246 99 46 92 1 1415650784 42175840 12526816
21:00:31 4297 8192000000 10343022336 10756091904 1381572656 14.431 100 71 144 1 1448278016 47909808 18795552
21:01:41 3784 8192000000 10340862464 10759565312 1390740896 14.512 99 44 88 0 1443481280 40825968 11914416
21:02:48 4145 8192000000 10383861104 10804117504 1417966192 14.755 100 57 114 0 1486804448 53313280 15524976
21:03:54 18280 8192000000 10370604464 10811895808 1421714496 14.788 100 41 79 1 1478686912 46381344 10591072
21:05:14 21065 8192000000 10356466080 10785218560 1393662896 14.539 99 31 62 0 1445651344 43368576 8619872
21:06:45 15247 8192000000 10356004400 10787123200 1407560080 14.662 100 29 56 0 1459821664 44642896 7618688
21:08:08 19200 8192000000 10344750880 10777636864 1378827408 14.406 99 34 68 0 1451612928 62582016 10203504
21:10:22 22956 8192000000 10326895376 10752454656 1376011344 14.381 100 44 90 1 1424756352 37429296 11315712
21:11:46 93012 8192000000 10288553392 10750767104 1375230432 14.374 99 14 28 0 1390692080 12226992 3234656
21:14:39 53999 8192000000 10297725392 10750959616 1396057360 14.560 100 11 20 0 1405728464 7757344 1913760
21:16:37 50893 8192000000 10287297040 10734309376 1363102640 14.265 98 28 56 0 1378472272 9048976 6320656
21:18:32 52958 8192000000 10293919376 10729885696 1360845904 14.245 98 26 52 1 1373824544 7637312 5341328
21:20:27 53100 8192000000 10286257792 10742784000 1387554672 14.484 100 13 24 0 1401373424 11025488 2793264
21:22:27 90745 8192000000 10306230432 10762665984 1393839664 14.540 100 12 24 0 1411164416 14261760 3062992
21:25:04 48024 8192000000 10303381664 10777423872 1403647248 14.627 100 19 38 2 1426687168 18083600 4956320
21:26:53 107678 8192000000 10271668048 10727043072 1303241232 13.725 93 21 42 0 1323328944 14765040 5322672
21:30:03 42863 8192000000 10291112336 10744877056 1384738208 14.459 100 38 73 2 1402509968 9284800 8486960
21:31:46 49306 8192000000 10297744144 10767433728 1397818608 14.576 100 15 30 0 1413678144 12489488 3370048
21:33:41 20443 8192000000 10299511840 10765180928 1401461040 14.608 100 48 94 2 1427366704 14450608 11455056
21:35:10 85529 8192000000 10304810976 10776530944 1398050832 14.578 100 18 36 0 1425149120 22744016 4354272
21:38:03 55364 8192000000 10306855312 10796511232 1398960400 14.586 100 8 16 0 1425404640 24712336 1731904
21:40:06 55184 8192000000 10300503488 10751451136 1345580848 14.108 96 26 51 1 1361089680 9545680 5963152
21:42:10 43594 8192000000 10290199040 10749399040 1362394016 14.259 98 12 25 0 1381565968 16418880 2753072
21:44:03 49340 8192000000 10276992160 10738708480 1342215680 14.077 97 20 41 0 1359335152 12918352 4201120
21:45:54 81907 8192000000 10268288800 10722136064 1307138208 13.760 95 21 42 0 1323430368 11911584 4380576
21:48:16 23632 8192000000 10263794928 10703818752 1333145888 13.996 98 30 60 1 1350552368 10353152 7053328
21:49:44 65920 8192000000 10262654736 10705203200 1300799696 13.703 96 14 29 0 1316466416 12785872 2880848
21:51:50 122014 8192000000 10264903600 10705293312 1344624912 14.099 99 25 50 0 1361472528 11596384 5251232
21:54:53 52250 8192000000 10264106784 10705154048 1329495856 13.963 98 38 75 1 1350953744 13455792 8002096
21:56:49 11128 8192000000 10248619616 10695012352 1338270000 14.042 99 27 51 1 1354654848 10514224 5870624
21:58:00 11596 8192000000 10256895408 10701938688 1354383792 14.187 100 37 74 2 1371083088 8854256 7845040
21:59:20 69908 8192000000 10243157152 10696347648 1308290144 13.771 96 18 36 0 1326633200 14064672 4278384
22:01:31 67274 8192000000 10277149584 10736513024 1367186992 14.302 100 12 24 0 1390382528 20161328 3034208
22:04:54 46700 8192000000 10226124384 10683392000 1294666480 13.647 95 19 38 0 1312650112 13498304 4485328
22:06:43 79396 8192000000 10269350080 10724945920 1362482304 14.260 100 15 28 0 1379141408 13219536 3439568
22:09:05 63328 8192000000 10271130416 10729979904 1335788032 14.019 96 17 34 0 1347772368 8211952 3772384
22:11:54 104583 8192000000 10288382096 10751414272 1339366160 14.052 96 21 41 0 1357451248 13414624 4670464
22:15:25 10364 8192000000 10312524816 10746970112 1377948960 14.398 99 56 113 0 1405443984 14930512 12564512
22:16:36 108278 8192000000 10294994464 10750709760 1337017056 14.031 96 23 46 0 1357179968 15206112 4956800
22:20:08 76302 8192000000 10285377888 10749165568 1376045824 14.381 99 13 24 0 1390320608 11493872 2780912
22:24:21 52850 8192000000 10262296640 10716991488 1358366048 14.223 98 11 20 0 1372006320 11012480 2627792
22:26:15 107245 8192000000 10263700992 10725789696 1328404336 13.953 96 12 23 0 1350104896 18730624 2969936
22:29:10 36539 8192000000 10263824320 10712567808 1340923232 14.066 98 23 46 0 1354869952 8287904 5658816
22:31:11 95604 8192000000 10266312256 10718388224 1306864176 13.758 95 14 28 0 1323249216 13174304 3210736
22:33:47 60534 8192000000 10256483936 10709057536 1316187248 13.842 97 20 39 0 1339013536 18925216 3901072
22:35:53 26390 8192000000 10294056592 10740367360 1364789136 14.280 99 36 72 0 1385425328 12920256 7715936
22:37:21 27897 8192000000 10276519968 10719985664 1349950144 14.147 98 21 43 0 1364793040 9962800 4880096

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-03-2023 22:39:50 to 10-03-2023 22:40:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 133 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-03-2023 22:40:06 to 10-03-2023 22:40:08.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          240594
MON$OLDEST_ACTIVE               242008
MON$OLDEST_SNAPSHOT             242008
MON$NEXT_TRANSACTION            242008
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-03 16:41:55.3470
MON$PAGES                       2049632
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:1300: 
:1301: mscs-test-161.budzdorov.ru    Fri Mar 10 22:38:44 2023
:1302:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1303: 
:1304: 
:1305: mscs-test-161.budzdorov.ru    Fri Mar 10 22:38:51 2023
:1306:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1307: 
--- end of diff output ---

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

10.03.23 22:40:09. There are 3 logs in /var/tmp/logs-oltp30 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
2023-03-10 22:40:12 No severe PSQL-related problems occured
10.03.23 22:40:13. End of report.