Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.09.2022 04: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 14895212 kB
MemAvailable 30592896 kB
Buffers 20 kB
Cached 16773848 kB
SwapCached 2028 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-09-25 04:37:20
DTS_END                         2022-09-25 04:37:20.8500
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38206
TRN_ID                          229808
ATT_ID                          647
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: 9121.32 26896 1094558
customer order: creation 178.93 444 21471
customer order: refuse 15.92 421 1910
order to supplier: creation 48.05 1269 5766
order to supplier: removal 3.47 165134 416
invoice (draft): creation 48.77 3010 5852
invoice (draft): removal 50.36 956 6043
invoice accept: apply 43.18 24489 5182
invoice accept: cancel 45.74 1191 5489
customer reserve: creation 3977.31 231 477277
customer reserve: removal 4401.28 101 528154
realization accept: apply 87.62 305 10514
realization accept: cancel 84.99 177 10199
payment from customer: creation 62.53 206 7503
payment from customer: removal 5.69 148 683
payment to supplier: creation 50.73 238 6088
payment to supplier: removal 3.46 118 415
service: total inventory turnovers 4.28 4050 513
service: total monetary turnovers 8.00 330 960
service: aggregate perf. data 0.98 17526 118
service: refresh index statistics 0.04 317584 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-09-2022 04:38:54 to 25-09-2022 04:38:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5127
TEST_TIME 2 5136
TEST_TIME 3 5629
TEST_TIME 4 6863
TEST_TIME 5 7447
TEST_TIME 6 7201
TEST_TIME 7 7097
TEST_TIME 8 6983
TEST_TIME 9 7161
TEST_TIME 10 7350
TEST_TIME 11 7472
TEST_TIME 12 7630
TEST_TIME 13 7630
TEST_TIME 14 8060
TEST_TIME 15 8068
TEST_TIME 16 8174
TEST_TIME 17 8087
TEST_TIME 18 8031
TEST_TIME 19 8171
TEST_TIME 20 8093
TEST_TIME 21 8013
TEST_TIME 22 8022
TEST_TIME 23 7910
TEST_TIME 24 8020
TEST_TIME 25 8052
TEST_TIME 26 8245
TEST_TIME 27 8447
TEST_TIME 28 8640
TEST_TIME 29 9000
TEST_TIME 30 9297
TEST_TIME 31 9596
TEST_TIME 32 10410
TEST_TIME 33 10742
TEST_TIME 34 11019
TEST_TIME 35 11011
TEST_TIME 36 11335
TEST_TIME 37 11184
TEST_TIME 38 11267
TEST_TIME 39 11067
TEST_TIME 40 10957
TEST_TIME 41 10881
TEST_TIME 42 10954
TEST_TIME 43 10902
TEST_TIME 44 10908
TEST_TIME 45 10888
TEST_TIME 46 10772
TEST_TIME 47 10690
TEST_TIME 48 10606
TEST_TIME 49 10735
TEST_TIME 50 10612
TEST_TIME 51 10430
TEST_TIME 52 10274
TEST_TIME 53 10189
TEST_TIME 54 10138
TEST_TIME 55 10016
TEST_TIME 56 9917
TEST_TIME 57 9874
TEST_TIME 58 9816
TEST_TIME 59 9781
TEST_TIME 60 9691
TEST_TIME 61 9700
TEST_TIME 62 9620
TEST_TIME 63 9549
TEST_TIME 64 9517
TEST_TIME 65 9578
TEST_TIME 66 9662
TEST_TIME 67 9736
TEST_TIME 68 9697
TEST_TIME 69 9646
TEST_TIME 70 9668
TEST_TIME 71 9624
TEST_TIME 72 9706
TEST_TIME 73 9716
TEST_TIME 74 9748
TEST_TIME 75 9697
TEST_TIME 76 9669
TEST_TIME 77 9737
TEST_TIME 78 9699
TEST_TIME 79 9654
TEST_TIME 80 9719
TEST_TIME 81 9842
TEST_TIME 82 9746
TEST_TIME 83 9664
TEST_TIME 84 9617
TEST_TIME 85 9550
TEST_TIME 86 9527
TEST_TIME 87 9509
TEST_TIME 88 9455
TEST_TIME 89 9437
TEST_TIME 90 9417
TEST_TIME 91 9439
TEST_TIME 92 9416
TEST_TIME 93 9385
TEST_TIME 94 9386
TEST_TIME 95 9348
TEST_TIME 96 9347
TEST_TIME 97 9330
TEST_TIME 98 9396
TEST_TIME 99 9373
TEST_TIME 100 9382
TEST_TIME 101 9372
TEST_TIME 102 9513
TEST_TIME 103 9467
TEST_TIME 104 9477
TEST_TIME 105 9520
TEST_TIME 106 9532
TEST_TIME 107 9526
TEST_TIME 108 9498
TEST_TIME 109 9510
TEST_TIME 110 9445
TEST_TIME 111 9438
TEST_TIME 112 9383
TEST_TIME 113 9372
TEST_TIME 114 9357
TEST_TIME 115 9339
TEST_TIME 116 9304
TEST_TIME 117 9253
TEST_TIME 118 9222
TEST_TIME 119 9200
TEST_TIME 120 9136

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 25-09-2022 04:38:57 to 25-09-2022 04:39:01.

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 1533321 1533321 0 0.00 0 27495 13 0 0
doc_list_biud 1533316 1533316 0 0.00 0 37604 41 0 0
sp_add_invoice_to_stock 5317 5182 135 2.53 29 193523 24490 0 135
sp_cancel_adding_invoice 5489 5489 0 0.00 6 140515 1192 0 0
sp_cancel_client_order 1910 1910 0 0.00 6 36430 422 0 0
sp_cancel_customer_reserve 528154 528154 0 0.00 1 37614 102 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 4 7436 149 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 4 6706 118 0 0
sp_cancel_supplier_invoice 6050 6043 7 0.11 2 41525 956 0 7
sp_cancel_supplier_order 416 416 0 0.00 8 1568592 165135 0 0
sp_cancel_write_off 10199 10199 0 0.00 2 14725 178 0 0
sp_client_order 21471 21471 0 0.00 5 36213 445 0 0
sp_customer_reserve 477277 477277 0 0.00 1 64579 232 0 0
sp_fill_shopping_cart 33085 33085 0 0.00 1 15934 89 0 0
sp_fill_shopping_cart_clo_res 477263 477263 0 0.00 0 20591 19 0 0
sp_get_clo_for_invoice 5155 5155 0 0.00 0 34832 296 0 0
sp_kill_cost_storno 16786 16786 0 0.00 0 15907 73 0 0
sp_kill_qstorno_ret_qs2qd 1072966 1072966 0 0.00 0 37529 43 0 0
sp_kill_qty_storno 567880 567880 0 0.00 0 37602 100 0 0
sp_lock_selected_doc 21654 21654 0 0.00 0 13188 9 0 0
sp_make_cost_storno 29260 29260 0 0.00 0 27462 87 0 0
sp_multiply_rows_for_pdistr 29260 29260 0 0.00 0 7861 41 0 0
sp_multiply_rows_for_qdistr 21471 21471 0 0.00 0 10424 162 0 0
sp_pay_from_customer 7503 7503 0 0.00 5 25737 207 0 0
sp_pay_to_supplier 6088 6088 0 0.00 6 27600 238 0 0
sp_payment_common 13590 13590 0 0.00 2 15682 60 0 0
sp_qd_handle_on_cancel_clo 1910 1910 0 0.00 0 11038 326 0 0
sp_qd_handle_on_invoice_upd_sts 10644 10644 0 0.00 0 23208 525 0 0
sp_qd_handle_on_reserve_upd_sts 10514 10514 0 0.00 0 18941 74 0 0
sp_reserve_write_off 10514 10514 0 0.00 9 36438 306 0 0
sp_supplier_invoice 5852 5852 0 0.00 9 102716 3011 0 0
sp_supplier_order 5766 5766 0 0.00 7 28466 1269 0 0
srv_aggregate_perf_data 118 118 0 0.00 2693 211070 17527 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 738 171586 37387 0 0
srv_find_qd_qs_mism 1043262 1043262 0 0.00 0 23808 9 0 0
srv_make_invnt_saldo 513 513 0 0.00 37 94877 4050 0 0
srv_make_money_saldo 960 960 0 0.00 1 9118 330 0 0
srv_recalc_idx_stat 5 5 0 0.00 118227 509421 317584 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5182 5182 0 0.00 0 15891 1009 0 0
t$perf-norm:sp_cancel_adding_invoice 439 439 0 0.00 1 18719 499 0 0
t$perf-norm:sp_cancel_client_order 1910 1910 0 0.00 0 4955 29 0 0
t$perf-norm:sp_cancel_customer_reserve 703 703 0 0.00 0 2343 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 623 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 2854 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 2431 41 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 67571 6109 0 0
t$perf-norm:sp_cancel_write_off 1026 1026 0 0.00 0 3762 22 0 0
t$perf-norm:sp_client_order 21471 21471 0 0.00 0 8441 39 0 0
t$perf-norm:sp_customer_reserve 992 992 0 0.00 0 5350 18 0 0
t$perf-norm:sp_pay_from_customer 7503 7503 0 0.00 0 3401 19 0 0
t$perf-norm:sp_pay_to_supplier 6088 6088 0 0.00 0 8176 21 0 0
t$perf-norm:sp_reserve_write_off 10514 10514 0 0.00 0 6163 26 0 0
t$perf-norm:sp_supplier_invoice 5852 5852 0 0.00 0 5617 32 0 0
t$perf-norm:sp_supplier_order 5766 5766 0 0.00 0 4952 34 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 1682 40 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 306 10 0 0
t$perf-norm:srv_make_invnt_saldo 513 513 0 0.00 0 4626 27 0 0
t$perf-norm:srv_make_money_saldo 960 960 0 0.00 0 796 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 14 179 64 0 0
v_add_invoice_to_stock 10580 10310 270 2.55 0 25675 33 0 270
v_all_customers 39764 39764 0 0.00 0 102 4 0 0
v_all_suppliers 32334 32334 0 0.00 0 128 5 0 0
v_all_wares 42942 42942 0 0.00 0 122 4 0 0
v_cancel_adding_invoice 878 878 0 0.00 0 330 15 0 0
v_cancel_client_order 3820 3820 0 0.00 0 36206 21 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 2401 14 0 0
v_cancel_customer_reserve 1406 1406 0 0.00 0 4332 13 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 732 11 0 0
v_cancel_supplier_invoice 800 786 14 1.75 0 426 24 0 14
v_cancel_supplier_order 832 832 0 0.00 0 923 12 0 0
v_cancel_write_off 2052 2052 0 0.00 0 4609 28 0 0
v_max_id_clo_ord 5766 5766 0 0.00 0 1265 6 0 0
v_max_id_clo_res 992 992 0 0.00 0 1671 9 0 0
v_max_id_ord_sup 5848 5848 0 0.00 0 7543 10 0 0
v_max_non_paid_invoice 1535 1535 0 0.00 0 6586 13 0 0
v_max_non_paid_realizn 3737 3737 0 0.00 0 5720 11 0 0
v_min_id_clo_ord 5766 5766 0 0.00 0 7275 10 0 0
v_min_id_clo_res 992 992 0 0.00 0 1826 11 0 0
v_min_id_ord_sup 5848 5848 0 0.00 0 7799 13 0 0
v_min_non_paid_invoice 6088 6088 0 0.00 0 11499 31 0 0
v_min_non_paid_realizn 7502 7502 0 0.00 0 10404 25 0 0
v_our_firm 10708 10708 0 0.00 0 62 5 0 0
v_reserve_write_off 21028 21028 0 0.00 0 36331 14 0 0
x_lock_depdocs_on_canc_invoice 11139 11139 0 0.00 0 17737 117 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 6296 358 0 0
x_make_qstorno_1000_1200 5766 5766 0 0.00 2 28439 1090 0 0
x_make_qstorno_1200_2000 5852 5852 0 0.00 4 102699 2772 0 0
x_make_qstorno_2100_3300 477277 477277 0 0.00 0 64577 199 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-09-2022 04:39:02 to 25-09-2022 04:39:06.

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
02:07:24 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:25 15347 8192000000 9737751056 10041901056 817206448 9.070 100 81 162 1 844008128 7242976 19558704
02:09:46 12775 8192000000 9771703984 10080927744 846876224 9.369 100 85 170 0 878254672 11259728 20118720
02:11:05 20748 8192000000 9802814848 10119180288 870619632 9.606 100 89 178 0 909297520 18479472 20198416
02:12:31 8068 8192000000 9847569808 10176835584 898695344 9.885 100 83 162 1 960308432 43962688 17650400
02:13:47 4563 8192000000 9915335312 10255708160 945538656 10.347 100 92 181 1 1032345136 67968928 18837552
02:14:56 1453 8192000000 9958067056 10309320704 980766064 10.692 100 88 169 2 1075991680 78540288 16685328
02:16:01 1021 8192000000 9993516848 10351779840 1012593904 11.000 100 98 188 0 1109736000 78139344 19002752
02:17:03 1520 8192000000 10031352272 10398785536 1037884704 11.244 100 92 180 0 1149290624 93387344 18018576
02:18:06 1468 8192000000 10011482208 10387337216 1054750352 11.406 100 93 178 1 1128725744 56475696 17499696
02:19:10 3228 8192000000 10009446928 10384035840 1062221632 11.478 100 73 135 0 1127525744 52267008 13037104
02:20:15 1023 8192000000 10039136640 10417418240 1078427520 11.632 100 98 190 0 1160388496 63397504 18563472
02:21:17 1750 8192000000 10048050192 10435862528 1082588080 11.672 100 84 161 2 1175083584 76626448 15869056
02:22:21 3176 8192000000 10043550208 10425118720 1084701760 11.692 100 89 170 2 1159959744 59466256 15791728
02:23:25 1053 8192000000 10052231312 10438787072 1092380144 11.765 100 93 180 0 1176449264 65341200 18727920
02:24:27 1594 8192000000 10079166736 10469208064 1096327616 11.803 100 86 158 1 1197297696 85256480 15713600
02:25:29 1013 8192000000 10074545024 10464755712 1098398720 11.822 100 89 169 1 1192864480 76516544 17949216
02:26:31 971 8192000000 10058973216 10450481152 1101481312 11.852 100 92 169 2 1183695472 62807168 19406992
02:27:33 1911 8192000000 10076178144 10467921920 1094795648 11.788 100 78 152 1 1198066432 87204800 16065984
02:28:36 1872 8192000000 10053370992 10446761984 1099586304 11.834 100 82 150 2 1177026864 62988944 14451616
02:29:39 1867 8192000000 10025110304 10406031360 1073785920 11.588 100 79 144 2 1144556096 57347312 13422864
02:30:42 1440 8192000000 10035194800 10422460416 1067148624 11.525 99 81 161 1 1158773888 74649744 16975520
02:31:45 1208 8192000000 10025776672 10403770368 1059805856 11.455 100 95 168 1 1149688016 70133776 19748384
02:32:47 4497 8192000000 9987061056 10375036928 1050582720 11.366 100 72 138 1 1124153008 59018496 14551792
02:33:57 7396 8192000000 9996802384 10377740288 1049855744 11.359 100 64 122 1 1124775760 62593408 12326608
02:35:05 1420 8192000000 9973102752 10348064768 1043647168 11.300 99 82 155 1 1096848384 37538624 15662592
02:36:07 1532 8192000000 9945365024 10319708160 1010917760 10.984 99 77 145 5 1069854144 44453696 14482688
02:37:09 6016 8192000000 9985965840 10366746624 1047160272 11.333 99 67 123 5 1108916304 49445488 12310544
02:38:21 7773 8192000000 10003840464 10390622208 1073027760 11.581 100 63 125 2 1125171872 38749248 13394864
02:39:32 7016 8192000000 10017471168 10404528128 1070832416 11.560 99 68 127 1 1143194928 59494976 12867536
02:40:43 4456 8192000000 10058547472 10445729792 1095601072 11.796 100 76 141 2 1178995728 68695728 14698928
02:41:54 1397 8192000000 10083063552 10476646400 1109801280 11.931 100 91 181 1 1201766720 72554608 19410832
02:42:59 17512 8192000000 10102632096 10505465856 1122037264 12.046 100 71 140 1 1222408240 85189728 15181248
02:44:22 4115 8192000000 10141111216 10540445696 1137133216 12.189 100 73 142 1 1257217968 105473824 14610928
02:45:31 3744 8192000000 10133487792 10534461440 1139660240 12.212 100 70 132 0 1252587408 98665040 14262128
02:46:44 1573 8192000000 10181813440 10578051072 1148521872 12.296 100 92 167 5 1293245472 126218176 18505424
02:47:48 1472 8192000000 10210056816 10608848896 1147699328 12.288 99 97 183 1 1322009728 154408736 19901664
02:48:53 887 8192000000 10245058112 10642407424 1154986192 12.356 100 92 180 0 1359140208 182581072 21572944
02:49:56 4626 8192000000 10271395904 10678771712 1175981136 12.553 100 78 149 1 1376787440 184042464 16763840
02:51:02 1198 8192000000 10293319936 10727907328 1183768464 12.625 100 91 173 6 1403637776 199749712 20119600
02:52:05 1344 8192000000 10320265328 10734833664 1195787680 12.737 100 94 174 1 1429823536 214055392 19980464
02:53:10 930 8192000000 10330864720 10748551168 1186471952 12.651 99 95 180 1 1439243936 233183808 19588176
02:54:15 885 8192000000 10387462304 10792239104 1209249152 12.862 100 91 178 1 1490305712 261057744 19998816
02:55:18 1351 8192000000 10440960688 10849902592 1206058752 12.833 99 92 180 2 1542247232 314308336 21880144
02:56:24 866 8192000000 10473919120 10900258816 1220390736 12.965 100 99 195 0 1581289984 338337984 22561264
02:57:27 1705 8192000000 10497980912 10906693632 1195410576 12.734 100 92 181 0 1603337008 386888096 21038336
02:58:33 1550 8192000000 10559951200 10981580800 1214670640 12.912 100 86 170 1 1660335968 424736272 20929056
02:59:37 727 8192000000 10604979104 11013656576 1205444992 12.827 100 95 186 0 1708512464 481060512 22006960
03:00:43 1793 8192000000 10638234768 11049869312 1196926080 12.748 99 89 178 0 1750148672 531713056 21509536
03:01:48 1349 8192000000 10668693424 11097120768 1213177776 12.899 100 97 186 1 1769386992 532612816 23596400
03:02:50 2160 8192000000 10670572592 11116851200 1205977872 12.832 100 87 173 0 1772249744 545590672 20681200
03:03:54 5369 8192000000 10668092848 11118809088 1197383280 12.752 100 72 140 0 1774553408 559192560 17977568
03:05:07 5338 8192000000 10664218464 11123802112 1177785840 12.570 100 50 100 0 1764837168 574066864 12984464
03:07:02 19280 8192000000 10489880000 10986242048 1140031136 12.216 100 40 80 0 1644042304 493751984 10259184
03:09:04 29870 8192000000 10317986496 10875404288 1145975232 12.272 100 29 58 0 1517760080 364082192 7702656
03:10:43 32351 8192000000 10153053808 10653212672 1132114016 12.141 99 25 50 0 1273618800 135894832 5609952
03:12:16 74273 8192000000 10136266640 10587926528 1112095904 11.952 97 19 37 0 1259959712 143136240 4727568
03:14:33 43393 8192000000 10114595200 10613321728 1155105504 12.357 100 17 34 0 1230342240 71998224 3238512
03:16:17 65008 8192000000 10112851232 10562916352 1149231520 12.302 99 34 58 0 1202260624 46303408 6725696
03:18:26 47369 8192000000 10129517040 10606309376 1169362432 12.491 100 35 68 0 1270565264 93578288 7624544
03:20:19 171586 8192000000 10098458256 10607575040 1138755280 12.204 98 8 16 0 1215757696 74997280 2005136
03:24:11 65923 8192000000 10096506096 10543480832 1096803520 11.807 95 17 32 0 1133369120 32947120 3618480
03:26:17 103548 8192000000 10061035376 10489708544 1108150896 11.915 96 27 52 2 1151372016 37276048 5945072
03:29:02 150173 8192000000 10086322480 10517962752 1133386016 12.153 99 30 56 1 1155750400 16404064 5960320
03:32:33 73509 8192000000 10076608944 10524487680 1151819888 12.327 100 28 53 0 1189874624 32547216 5507520
03:34:48 28087 8192000000 10107954000 10565828608 1155563888 12.362 100 24 49 1 1220923232 60317632 5041712
03:36:16 22270 8192000000 10128959824 10573627392 1153704656 12.344 99 26 53 1 1221184944 61301712 6178576
03:37:46 45295 8192000000 10194339024 10660274176 1157518464 12.380 99 62 113 1 1273887728 105118608 11250656
03:39:34 36655 8192000000 10204522272 10681577472 1147196288 12.283 98 22 44 0 1297295760 145074496 5024976
03:41:25 81060 8192000000 10241753152 10717114368 1138632368 12.203 98 24 48 1 1321579440 177124448 5822624
03:43:57 69776 8192000000 10060978656 10529255424 1101649728 11.853 97 9 18 0 1159418384 55813296 1955360
03:46:18 40066 8192000000 10114985008 10558996480 1125212224 12.076 99 28 57 1 1187623024 56928416 5482384
03:47:59 17296 8192000000 10099577856 10531786752 1129278688 12.115 100 48 93 0 1191804512 53291776 9234048
03:49:19 39861 8192000000 10095507984 10562633728 1102838144 11.865 97 20 40 0 1177045760 69807856 4399760
03:51:00 84658 8192000000 10101444064 10576031744 1111810912 11.950 99 18 36 1 1187949648 72613728 3525008
03:53:30 61770 8192000000 10103031488 10551758848 1099779152 11.836 97 25 50 0 1163531856 58691152 5061552
03:55:32 38985 8192000000 10118411824 10539126784 1099061952 11.829 98 51 98 0 1181890624 72451952 10376720
03:57:11 52395 8192000000 10129878880 10624086016 1111249440 11.944 100 20 40 0 1247880400 132328208 4302752
03:59:51 71566 8192000000 10030191840 10456211456 1067896704 11.532 96 24 46 1 1092269664 19853280 4519680
04:02:05 23329 8192000000 10062471488 10503704576 1101429952 11.851 99 40 68 0 1149144400 40772208 6942240
04:03:31 52209 8192000000 10080421648 10521563136 1089558640 11.738 97 32 63 1 1155900128 59812272 6529216
04:05:30 45450 8192000000 10111719920 10552455168 1108039408 11.914 99 35 68 0 1193684688 77609568 8035712
04:07:20 79125 8192000000 10078737600 10579349504 1121016176 12.037 99 12 24 0 1219842272 95679120 3146976
04:09:39 54313 8192000000 10043943472 10511167488 1106018464 11.895 98 10 18 1 1131751616 23634208 2098944
04:11:34 39177 8192000000 10046865072 10491105280 1086820256 11.712 96 27 50 3 1121271632 30529104 3922272
04:13:16 46104 8192000000 10114542112 10584993792 1139625584 12.212 100 33 66 0 1211391216 64905552 6860080
04:15:05 65914 8192000000 10113934272 10577002496 1115646336 11.986 98 29 56 1 1219256336 97535376 6074624
04:17:20 61510 8192000000 10148549744 10579566592 1117839024 12.007 98 37 74 1 1218145376 92012128 8294224
04:19:22 51276 8192000000 10078868112 10563092480 1101619104 11.853 97 20 40 0 1198163616 92017328 4527184
04:21:14 56719 8192000000 10129850304 10603954176 1100376384 11.841 97 39 78 0 1172567648 64061952 8129312
04:23:16 26294 8192000000 10121304592 10553540608 1133449648 12.154 100 49 94 0 1206461184 64082768 8928768
04:24:50 36505 8192000000 10078286944 10506149888 1096165360 11.801 97 34 68 0 1133207184 30045520 6996304
04:26:33 88262 8192000000 10082109856 10544226304 1109973216 11.932 98 27 54 0 1173507104 58105456 5428432
04:29:01 60650 8192000000 10062159024 10514153472 1116435360 11.993 98 23 42 0 1151304832 30337312 4532160
04:31:03 65690 8192000000 10071531488 10552713216 1139032544 12.206 100 11 21 0 1185167216 43950512 2184160
04:33:09 38841 8192000000 10035271872 10486517760 1071784544 11.569 94 16 32 0 1089483456 14358304 3340608
04:34:51 37662 8192000000 10054595488 10499223552 1127618448 12.099 100 20 40 0 1158878704 27295888 3964368
04:36:29 40578 8192000000 10052672400 10508988416 1103040432 11.866 98 10 15 0 1141438656 37391424 1006800

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 25-09-2022 04:39:08 to 25-09-2022 04:39:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 135 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-09-2022 04:39:25 to 25-09-2022 04:39:27.

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          227796
MON$OLDEST_ACTIVE               230289
MON$OLDEST_SNAPSHOT             230289
MON$NEXT_TRANSACTION            230289
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                       2107136
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             789
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2849 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2849 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2849 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2849 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 ---
:61: 
:62: mscs-test-161.budzdorov.ru    Sun Sep 25 04:37:27 2022
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:64: 
:65: 
:66: mscs-test-161.budzdorov.ru    Sun Sep 25 04:37:30 2022
:67:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:68: 
:69: 
:70: mscs-test-161.budzdorov.ru    Sun Sep 25 04:37:30 2022
:71:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:72: 
--- end of diff output ---

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

25.09.22 04:39:28. 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-09-25 04:39:31 No severe PSQL-related problems occured
25.09.22 04:39:32. End of report.