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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-04-03 10:37:16
DTS_END                         2022-04-03 10:37:16.3930
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/49972
TRN_ID                          221048
ATT_ID                          622
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: 8830.97 19515 1059716
customer order: creation 173.08 448 20770
customer order: refuse 14.34 362 1721
order to supplier: creation 45.11 1284 5413
order to supplier: removal 3.52 135921 422
invoice (draft): creation 46.41 3174 5569
invoice (draft): removal 49.86 910 5983
invoice accept: apply 40.62 24835 4874
invoice accept: cancel 44.67 1115 5360
customer reserve: creation 3747.93 240 449751
customer reserve: removal 4375.30 86 525036
realization accept: apply 82.50 264 9900
realization accept: cancel 79.88 156 9586
payment from customer: creation 58.67 159 7040
payment from customer: removal 5.59 112 671
payment to supplier: creation 48.12 167 5774
payment to supplier: removal 3.20 127 384
service: total inventory turnovers 3.65 4045 438
service: total monetary turnovers 7.54 300 905
service: aggregate perf. data 0.96 14704 115
service: refresh index statistics 0.03 201882 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-04-2022 10:39:35 to 03-04-2022 10:39:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5147
TEST_TIME 2 4927
TEST_TIME 3 4698
TEST_TIME 4 4806
TEST_TIME 5 4725
TEST_TIME 6 4993
TEST_TIME 7 4979
TEST_TIME 8 5543
TEST_TIME 9 5531
TEST_TIME 10 5607
TEST_TIME 11 5832
TEST_TIME 12 5871
TEST_TIME 13 6031
TEST_TIME 14 6163
TEST_TIME 15 6371
TEST_TIME 16 6535
TEST_TIME 17 7057
TEST_TIME 18 7139
TEST_TIME 19 7696
TEST_TIME 20 7934
TEST_TIME 21 8360
TEST_TIME 22 8753
TEST_TIME 23 8835
TEST_TIME 24 9110
TEST_TIME 25 9132
TEST_TIME 26 9208
TEST_TIME 27 9938
TEST_TIME 28 9872
TEST_TIME 29 10180
TEST_TIME 30 10363
TEST_TIME 31 10198
TEST_TIME 32 10076
TEST_TIME 33 10341
TEST_TIME 34 10602
TEST_TIME 35 10632
TEST_TIME 36 10515
TEST_TIME 37 10351
TEST_TIME 38 10257
TEST_TIME 39 10166
TEST_TIME 40 9999
TEST_TIME 41 10223
TEST_TIME 42 10202
TEST_TIME 43 10167
TEST_TIME 44 10166
TEST_TIME 45 10026
TEST_TIME 46 9875
TEST_TIME 47 9794
TEST_TIME 48 9757
TEST_TIME 49 9645
TEST_TIME 50 9557
TEST_TIME 51 9555
TEST_TIME 52 9513
TEST_TIME 53 9545
TEST_TIME 54 9531
TEST_TIME 55 9430
TEST_TIME 56 9361
TEST_TIME 57 9294
TEST_TIME 58 9395
TEST_TIME 59 9595
TEST_TIME 60 9565
TEST_TIME 61 9497
TEST_TIME 62 9611
TEST_TIME 63 9645
TEST_TIME 64 9640
TEST_TIME 65 9667
TEST_TIME 66 9626
TEST_TIME 67 9694
TEST_TIME 68 9630
TEST_TIME 69 9647
TEST_TIME 70 9716
TEST_TIME 71 9840
TEST_TIME 72 9756
TEST_TIME 73 9673
TEST_TIME 74 9657
TEST_TIME 75 9559
TEST_TIME 76 9512
TEST_TIME 77 9503
TEST_TIME 78 9466
TEST_TIME 79 9421
TEST_TIME 80 9359
TEST_TIME 81 9279
TEST_TIME 82 9254
TEST_TIME 83 9299
TEST_TIME 84 9298
TEST_TIME 85 9279
TEST_TIME 86 9290
TEST_TIME 87 9357
TEST_TIME 88 9336
TEST_TIME 89 9337
TEST_TIME 90 9328
TEST_TIME 91 9385
TEST_TIME 92 9420
TEST_TIME 93 9376
TEST_TIME 94 9412
TEST_TIME 95 9362
TEST_TIME 96 9282
TEST_TIME 97 9244
TEST_TIME 98 9231
TEST_TIME 99 9196
TEST_TIME 100 9138
TEST_TIME 101 9108
TEST_TIME 102 9071
TEST_TIME 103 9061
TEST_TIME 104 9016
TEST_TIME 105 9008
TEST_TIME 106 8988
TEST_TIME 107 9003
TEST_TIME 108 8980
TEST_TIME 109 9019
TEST_TIME 110 9018
TEST_TIME 111 9040
TEST_TIME 112 9073
TEST_TIME 113 9051
TEST_TIME 114 9052
TEST_TIME 115 9050
TEST_TIME 116 8990
TEST_TIME 117 8966
TEST_TIME 118 8957
TEST_TIME 119 8918
TEST_TIME 120 8845

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-04-2022 10:39:39 to 03-04-2022 10:39:42.

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 1476927 1476927 0 0.00 0 16340 12 0 0
doc_list_biud 1476926 1476926 0 0.00 0 65401 37 0 0
sp_add_invoice_to_stock 4980 4874 106 2.12 30 190846 24835 0 106
sp_cancel_adding_invoice 5360 5360 0 0.00 7 75745 1115 0 0
sp_cancel_client_order 1721 1721 0 0.00 6 6688 363 0 0
sp_cancel_customer_reserve 525036 525036 0 0.00 1 65402 87 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 4 5545 113 0 0
sp_cancel_pay_to_supplier 384 384 0 0.00 4 4728 127 0 0
sp_cancel_supplier_invoice 5995 5983 12 0.20 3 33933 911 0 12
sp_cancel_supplier_order 422 422 0 0.00 10 1284282 135921 0 0
sp_cancel_write_off 9586 9586 0 0.00 2 13932 157 0 0
sp_client_order 20770 20770 0 0.00 6 33466 449 0 0
sp_customer_reserve 449751 449751 0 0.00 1 96093 241 0 0
sp_fill_shopping_cart 31747 31747 0 0.00 1 23272 75 0 0
sp_fill_shopping_cart_clo_res 449734 449734 0 0.00 0 13027 17 0 0
sp_get_clo_for_invoice 4845 4845 0 0.00 0 33024 307 0 0
sp_kill_cost_storno 16001 16001 0 0.00 0 13899 66 0 0
sp_kill_qstorno_ret_qs2qd 1066063 1066063 0 0.00 0 65399 39 0 0
sp_kill_qty_storno 562853 562853 0 0.00 0 65401 89 0 0
sp_lock_selected_doc 20370 20370 0 0.00 0 8654 7 0 0
sp_make_cost_storno 27559 27559 0 0.00 0 11361 70 0 0
sp_multiply_rows_for_pdistr 27559 27559 0 0.00 0 10460 36 0 0
sp_multiply_rows_for_qdistr 20770 20770 0 0.00 0 14638 176 0 0
sp_pay_from_customer 7040 7040 0 0.00 5 9517 159 0 0
sp_pay_to_supplier 5774 5774 0 0.00 6 11891 168 0 0
sp_payment_common 12814 12814 0 0.00 2 9121 41 0 0
sp_qd_handle_on_cancel_clo 1721 1721 0 0.00 1 6676 282 0 0
sp_qd_handle_on_invoice_upd_sts 10205 10205 0 0.00 0 22324 446 0 0
sp_qd_handle_on_reserve_upd_sts 9900 9900 0 0.00 0 33549 66 0 0
sp_reserve_write_off 9900 9900 0 0.00 9 34298 264 0 0
sp_supplier_invoice 5569 5569 0 0.00 11 89202 3174 0 0
sp_supplier_order 5413 5413 0 0.00 8 58558 1284 0 0
srv_aggregate_perf_data 115 115 0 0.00 2652 184602 14704 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 705 147014 37920 0 0
srv_find_qd_qs_mism 1012962 1012962 0 0.00 0 12506 9 0 0
srv_make_invnt_saldo 438 438 0 0.00 106 100158 4046 0 0
srv_make_money_saldo 905 905 0 0.00 1 8468 301 0 0
srv_recalc_idx_stat 4 4 0 0.00 100095 255734 201883 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 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 4874 4874 0 0.00 1 11204 761 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 1 7373 445 0 0
t$perf-norm:sp_cancel_client_order 1721 1721 0 0.00 0 4593 28 0 0
t$perf-norm:sp_cancel_customer_reserve 699 699 0 0.00 0 3262 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 1001 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 384 384 0 0.00 0 1429 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 3455 44 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 1 75807 5204 0 0
t$perf-norm:sp_cancel_write_off 918 918 0 0.00 0 6394 42 0 0
t$perf-norm:sp_client_order 20770 20770 0 0.00 0 8106 33 0 0
t$perf-norm:sp_customer_reserve 975 975 0 0.00 0 4590 15 0 0
t$perf-norm:sp_pay_from_customer 7040 7040 0 0.00 0 2430 15 0 0
t$perf-norm:sp_pay_to_supplier 5774 5774 0 0.00 0 5607 19 0 0
t$perf-norm:sp_reserve_write_off 9900 9900 0 0.00 0 8021 21 0 0
t$perf-norm:sp_supplier_invoice 5569 5569 0 0.00 0 6390 28 0 0
t$perf-norm:sp_supplier_order 5413 5413 0 0.00 0 8913 31 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 447 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 1453 24 0 0
t$perf-norm:srv_make_invnt_saldo 438 438 0 0.00 0 1045 18 0 0
t$perf-norm:srv_make_money_saldo 905 905 0 0.00 0 3850 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 82 29 0 0
v_add_invoice_to_stock 9902 9690 212 2.14 0 2973 19 0 212
v_all_customers 37928 37928 0 0.00 0 94 3 0 0
v_all_suppliers 30692 30692 0 0.00 0 106 3 0 0
v_all_wares 41540 41540 0 0.00 0 56 3 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 3068 19 0 0
v_cancel_client_order 3442 3442 0 0.00 0 4076 16 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 3430 13 0 0
v_cancel_customer_reserve 1398 1398 0 0.00 0 2706 12 0 0
v_cancel_payment_to_supplier 768 768 0 0.00 0 2836 13 0 0
v_cancel_supplier_invoice 796 772 24 3.01 0 716 19 0 24
v_cancel_supplier_order 844 844 0 0.00 0 160 7 0 0
v_cancel_write_off 1836 1836 0 0.00 0 3684 20 0 0
v_max_id_clo_ord 5412 5412 0 0.00 0 157 4 0 0
v_max_id_clo_res 975 975 0 0.00 0 5699 13 0 0
v_max_id_ord_sup 5563 5563 0 0.00 0 5808 6 0 0
v_max_non_paid_invoice 1403 1403 0 0.00 0 1169 8 0 0
v_max_non_paid_realizn 3616 3616 0 0.00 0 2317 8 0 0
v_min_id_clo_ord 5412 5412 0 0.00 0 3280 9 0 0
v_min_id_clo_res 975 975 0 0.00 0 10644 37 0 0
v_min_id_ord_sup 5563 5563 0 0.00 0 7199 18 0 0
v_min_non_paid_invoice 5774 5774 0 0.00 0 9092 15 0 0
v_min_non_paid_realizn 7040 7040 0 0.00 0 5075 19 0 0
v_our_firm 10460 10460 0 0.00 0 91 4 0 0
v_reserve_write_off 19800 19800 0 0.00 0 6470 11 0 0
x_lock_depdocs_on_canc_invoice 10957 10957 0 0.00 0 15013 129 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 8829 324 0 0
x_make_qstorno_1000_1200 5413 5413 0 0.00 2 58542 1149 0 0
x_make_qstorno_1200_2000 5569 5569 0 0.00 4 83842 2949 0 0
x_make_qstorno_2100_3300 449751 449751 0 0.00 0 96068 213 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-04-2022 10:39:43 to 03-04-2022 10:39:47.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:20 3 8192000000 8800558624 8812486656 6201776 0.075 2 2 3 0 6353728 90592 61360
08:08:21 24237 8192000000 9726669040 10024669184 804114976 8.938 100 92 183 0 833966624 7768080 22083568
08:09:48 19773 8192000000 9765309952 10074497024 837777808 9.277 100 89 178 0 871221296 11972800 21470688
08:11:11 20023 8192000000 9801281504 10122252288 866062352 9.561 100 81 162 0 906150192 21697808 18390032
08:12:33 9377 8192000000 9855413408 10193018880 914844912 10.045 100 82 162 1 971393904 38651280 17897712
08:13:46 4969 8192000000 9917402240 10261368832 964084992 10.529 100 84 166 0 1034122640 52600864 17436784
08:14:54 2047 8192000000 9976386480 10333278208 997825344 10.857 100 90 174 0 1097177664 81246624 18105696
08:15:59 986 8192000000 9998651600 10362040320 1027509488 11.144 100 92 169 1 1116902032 72186880 17205664
08:17:03 1073 8192000000 10020918480 10390835200 1050001392 11.361 100 97 181 3 1138966144 71700528 17264224
08:18:05 952 8192000000 10049357232 10430803968 1062711616 11.482 100 96 185 1 1168489872 87037776 18740480
08:19:08 1509 8192000000 10038245760 10420453376 1071513936 11.567 100 82 149 1 1161211104 75325712 14371456
08:20:10 988 8192000000 10044948080 10429456384 1076785088 11.617 100 84 166 2 1164530656 70847040 16898528
08:21:12 2689 8192000000 10024905264 10417954816 1081794880 11.665 100 62 121 0 1148365856 55064784 11506192
08:22:16 2329 8192000000 10042089200 10430967808 1089323664 11.736 100 73 146 0 1149521344 44584864 15612816
08:23:23 4821 8192000000 10046170464 10447675392 1091265056 11.755 100 63 125 2 1164064960 60525888 12274016
08:24:30 1128 8192000000 10067095408 10459439104 1096917632 11.808 100 85 164 0 1186390176 72724000 16748544
08:25:33 3235 8192000000 10057996368 10449842176 1098099760 11.820 100 80 154 2 1178130464 64394592 15636112
08:26:37 1217 8192000000 10081564240 10472402944 1105147920 11.886 100 94 180 3 1211304464 88091872 18064672
08:27:39 1270 8192000000 10059515520 10450481152 1099523024 11.833 100 94 179 2 1183348944 66275408 17550512
08:28:42 1305 8192000000 10030041936 10419929088 1079451936 11.642 99 84 161 3 1150713088 54526144 16735008
08:29:47 868 8192000000 10036176928 10418831360 1067645200 11.530 99 90 173 2 1155045040 69721168 17678672
08:30:49 5051 8192000000 10011032848 10390454272 1064415600 11.499 99 80 152 1 1136432352 57605968 14410784
08:31:54 997 8192000000 10010969696 10384752640 1047231232 11.334 100 95 183 1 1130870864 64837712 18801920
08:32:56 1454 8192000000 9990526656 10358026240 1033094288 11.198 99 94 181 4 1114071824 62590480 18387056
08:33:58 989 8192000000 9959815968 10330263552 1023518032 11.106 100 93 175 0 1085178240 43173648 18486560
08:35:01 1157 8192000000 10023369776 10397057024 1042708368 11.291 100 95 185 2 1143513904 82133216 18672320
08:36:03 695 8192000000 9986659376 10365034496 1047699408 11.339 100 82 155 2 1106639904 43494480 15446016
08:37:04 1292 8192000000 10011332144 10391441408 1057639008 11.434 100 90 172 0 1128407744 53283792 17484944
08:38:19 1883 8192000000 10027199408 10414706688 1079529952 11.643 100 68 126 1 1145046192 53636320 11879920
08:39:26 1335 8192000000 10045618096 10433351680 1094446928 11.785 100 79 151 0 1167650096 57335456 15867712
08:40:33 15561 8192000000 10074970512 10471063552 1099474048 11.833 100 64 127 1 1200477728 88252464 12751216
08:42:02 15182 8192000000 10108726400 10507014144 1120425904 12.031 100 48 96 0 1219379984 88782448 10171632
08:43:20 2150 8192000000 10139986320 10539487232 1136802912 12.185 100 82 152 0 1266153056 112095744 17254400
08:44:24 1989 8192000000 10206904976 10599608320 1149849232 12.308 100 87 167 1 1325804416 158071040 17884144
08:45:31 2902 8192000000 10251202128 10650075136 1162304656 12.425 100 88 169 0 1360974864 179782704 18887504
08:46:37 1746 8192000000 10256561760 10653872128 1168397376 12.482 100 87 166 1 1372355408 184558016 19400016
08:47:45 2140 8192000000 10287473712 10690617344 1168259536 12.481 100 80 158 1 1400720992 213928576 18532880
08:48:52 10256 8192000000 10325462272 10731520000 1181435744 12.604 100 74 141 0 1441835792 244267168 16132880
08:50:05 853 8192000000 10340844464 10752688128 1178596320 12.577 99 88 169 2 1451834416 253885792 19352304
08:51:10 826 8192000000 10402802080 10816569344 1188127520 12.666 100 93 187 1 1513838416 302765136 22945760
08:52:16 1589 8192000000 10434050192 10842337280 1187053296 12.656 100 96 182 2 1543546976 335234864 21258816
08:53:21 2001 8192000000 10454060000 10875043840 1187020832 12.656 100 92 178 1 1561428704 354231152 20176720
08:54:26 888 8192000000 10455616672 10888470528 1187366720 12.659 100 92 182 1 1567801472 358170736 22264016
08:55:31 1561 8192000000 10485736528 10908286976 1204563648 12.819 100 88 169 1 1595391104 369077600 21749856
08:56:35 700 8192000000 10445760144 10918047744 1196608864 12.745 100 82 161 0 1558816960 341575536 20632560
08:57:43 7379 8192000000 10432718928 10895769600 1166639424 12.465 99 52 101 0 1539566496 359655136 13271936
08:59:00 13716 8192000000 10374713792 10834452480 1180877120 12.598 100 43 84 1 1494801248 303550384 10373744
09:00:21 27174 8192000000 10338937088 10800545792 1175661072 12.550 100 28 52 0 1469688272 287332928 6694272
09:02:01 29990 8192000000 10278742320 10747666432 1137989968 12.197 98 20 40 1 1399964480 256704720 5269792
09:03:38 9443 8192000000 10217195088 10745880576 1152410608 12.332 99 54 99 3 1324653184 160217824 12024752
09:04:48 13650 8192000000 10207388512 10644377600 1155904352 12.365 100 31 58 2 1311128384 148142112 7081920
09:06:06 17496 8192000000 10203308800 10706141184 1144368816 12.257 99 21 42 0 1293369776 143604096 5396864
09:07:25 22920 8192000000 10154083744 10589626368 1156910960 12.374 99 33 60 1 1242619952 79015392 6693600
09:08:51 37434 8192000000 10228344576 10671202304 1117795520 12.006 95 28 52 1 1266119840 142524864 5799456
09:11:14 75022 8192000000 10098488288 10640703488 1127664768 12.099 99 9 18 1 1234874560 105409232 1800560
09:13:30 36359 8192000000 10095593376 10548350976 1115853632 11.988 96 26 50 2 1160397424 38947552 5596240
09:15:23 70295 8192000000 10125242992 10575519744 1129273776 12.115 98 17 34 0 1178771232 46050976 3446480
09:17:44 110107 8192000000 10094139760 10583355392 1127346480 12.096 99 14 27 0 1168080048 38255584 2477984
09:20:34 50488 8192000000 10072361840 10543800320 1134415472 12.163 99 13 26 0 1182021056 44819600 2785984
09:22:52 147012 8192000000 10088684224 10507911168 1067861936 11.532 94 37 72 2 1120614928 45281136 7471856
09:26:20 57097 8192000000 10079396560 10497056768 1097469184 11.814 97 60 109 3 1142816208 33299152 12047872
09:28:19 25837 8192000000 10112079840 10551205888 1113309360 11.964 98 31 62 1 1196179280 75924592 6945328
09:29:55 34727 8192000000 10172944704 10611486720 1134787200 12.166 100 54 106 2 1249589888 102806080 11996608
09:31:37 74814 8192000000 10180039520 10632704000 1125798160 12.082 99 23 46 1 1256351232 125421456 5131616
09:34:04 41949 8192000000 10181244976 10653917184 1139490128 12.211 100 15 31 2 1304363792 160968048 3905616
09:36:05 55436 8192000000 10093915056 10586865664 1121731312 12.043 99 12 24 0 1200795040 76128160 2935568
09:38:25 34750 8192000000 10165600464 10605330432 1114119776 11.971 97 38 74 1 1212470976 90824272 7526928
09:40:01 36700 8192000000 10121198912 10563645440 1133887264 12.158 100 26 50 1 1224283232 84370784 6025184
09:41:41 2695 8192000000 10085489280 10498289664 1114872112 11.979 99 81 158 2 1168990448 37366272 16752064
09:42:45 15965 8192000000 10067312640 10483404800 1107712960 11.911 99 65 130 1 1154812560 32727424 14372176
09:44:02 8446 8192000000 10118972368 10551840768 1134902320 12.168 100 53 104 0 1215742880 68659248 12181312
09:45:13 56428 8192000000 10154668240 10590941184 1123900752 12.064 98 23 45 1 1181712816 53234032 4578032
09:47:10 105546 8192000000 10096985552 10614439936 1123825168 12.063 98 18 36 0 1242325088 114692288 3807632
09:49:57 86871 8192000000 10065812240 10513981440 1120151952 12.028 98 16 32 2 1154714160 30681792 3880416
09:52:34 22953 8192000000 10067013408 10522177536 1127739392 12.100 99 25 45 2 1158616032 26384496 4492144
09:53:58 63822 8192000000 10107157856 10575949824 1127281632 12.096 99 37 74 0 1214996080 79508336 8206112
09:56:06 63042 8192000000 10109286032 10564861952 1119328752 12.021 98 20 39 3 1178921216 54783296 4809168
09:58:13 83079 8192000000 10121703024 10606501888 1112944432 11.960 99 14 28 0 1216395296 100023216 3427648
10:00:38 68924 8192000000 10109936368 10561409024 1105628144 11.891 98 15 30 0 1218950672 109804048 3518480
10:02:57 50562 8192000000 10122025664 10597281792 1107838240 11.912 98 25 50 1 1224742288 111439184 5464864
10:04:48 47830 8192000000 10084226304 10528083968 1104802880 11.883 98 34 64 1 1157983984 46055744 7125360
10:06:37 35271 8192000000 10112451952 10552991744 1125940512 12.083 100 37 74 1 1206150848 73086960 7123376
10:08:15 45358 8192000000 10097451056 10548252672 1130212736 12.123 100 35 70 0 1196044656 58758128 7073792
10:10:01 38562 8192000000 10075136864 10513670144 1102654432 11.863 98 33 65 1 1173079344 64297936 6126976
10:11:40 76706 8192000000 10079864912 10539819008 1123420560 12.059 100 21 40 0 1178013936 50837184 3756192
10:14:24 83038 8192000000 10096840848 10585284608 1141621984 12.231 100 25 50 1 1206776192 59627840 5526368
10:18:26 47583 8192000000 10061726464 10525282304 1131349184 12.134 99 22 44 0 1164969008 29095216 4524608
10:20:38 87666 8192000000 10107262224 10543120384 1108255392 11.916 97 18 36 0 1144365088 32179648 3930048
10:23:17 45345 8192000000 10115501824 10562392064 1134563264 12.164 100 23 45 1 1206830816 67694736 4572816
10:25:12 46030 8192000000 10117496816 10563891200 1132397040 12.144 100 30 60 1 1213515456 74392720 6725696
10:26:58 64696 8192000000 10109162800 10565079040 1115605312 11.985 98 13 26 0 1191548752 72988704 2954736
10:29:17 49409 8192000000 10072900240 10503798784 1109335696 11.926 98 27 53 0 1178387328 63723248 5328384
10:31:07 13376 8192000000 10098026768 10521993216 1101644240 11.853 97 58 114 1 1156696240 43334240 11717760
10:32:21 94615 8192000000 10067042256 10522234880 1127434112 12.097 100 33 66 0 1188266448 53939440 6892896
10:34:56 15758 8192000000 10030673200 10474074112 1077684032 11.625 96 22 45 0 1112188976 30053776 4451168
10:36:12 65390 8192000000 9637906992 10033639424 745858464 8.344 67 7 14 0 776660656 29528128 1274064

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 03-04-2022 10:39:49 to 03-04-2022 10:40:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 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 03-04-2022 10:40:06 to 03-04-2022 10:40:08.

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          221492
MON$OLDEST_ACTIVE               221770
MON$OLDEST_SNAPSHOT             221770
MON$NEXT_TRANSACTION            221770
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                       2061472
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2745 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2745 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2745 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2745 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 ---
:419: 
:420: mscs-test-161.budzdorov.ru    Sun Apr  3 10:37:45 2022
:421:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:422: 
--- end of diff output ---

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

03.04.22 10:40:09. 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-04-03 10:40:11 No severe PSQL-related problems occured
03.04.22 10:40:13. End of report.