Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.09.2022 13:37
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 15455624 kB
MemAvailable 30591616 kB
Buffers 0 kB
Cached 16242368 kB
SwapCached 2048 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-24 13:37:21
DTS_END                         2022-09-24 13:37:21.8250
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56656
TRN_ID                          214224
ATT_ID                          638
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: 8819.27 26257 1058312
customer order: creation 161.73 494 19407
customer order: refuse 14.12 399 1694
order to supplier: creation 42.61 1380 5113
order to supplier: removal 3.26 169945 391
invoice (draft): creation 43.72 3327 5246
invoice (draft): removal 49.40 1047 5928
invoice accept: apply 39.88 28982 4785
invoice accept: cancel 44.81 1171 5377
customer reserve: creation 3745.30 274 449436
customer reserve: removal 4398.93 97 527872
realization accept: apply 78.20 295 9384
realization accept: cancel 75.68 167 9081
payment from customer: creation 55.68 174 6682
payment from customer: removal 5.41 118 649
payment to supplier: creation 45.48 208 5457
payment to supplier: removal 3.26 131 391
service: total inventory turnovers 4.14 3662 497
service: total monetary turnovers 6.83 367 819
service: aggregate perf. data 0.83 16098 99
service: refresh index statistics 0.03 296807 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-09-2022 13:38:20 to 24-09-2022 13:38:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5333
TEST_TIME 2 5482
TEST_TIME 3 6344
TEST_TIME 4 6301
TEST_TIME 5 6686
TEST_TIME 6 6698
TEST_TIME 7 6259
TEST_TIME 8 6502
TEST_TIME 9 6592
TEST_TIME 10 6791
TEST_TIME 11 6968
TEST_TIME 12 7090
TEST_TIME 13 7146
TEST_TIME 14 7370
TEST_TIME 15 7492
TEST_TIME 16 7797
TEST_TIME 17 7784
TEST_TIME 18 7712
TEST_TIME 19 7681
TEST_TIME 20 7802
TEST_TIME 21 8040
TEST_TIME 22 8311
TEST_TIME 23 8356
TEST_TIME 24 8369
TEST_TIME 25 8525
TEST_TIME 26 9093
TEST_TIME 27 9565
TEST_TIME 28 9810
TEST_TIME 29 9946
TEST_TIME 30 10139
TEST_TIME 31 10431
TEST_TIME 32 10990
TEST_TIME 33 11166
TEST_TIME 34 10930
TEST_TIME 35 10772
TEST_TIME 36 10625
TEST_TIME 37 10456
TEST_TIME 38 10428
TEST_TIME 39 10259
TEST_TIME 40 10128
TEST_TIME 41 9962
TEST_TIME 42 9914
TEST_TIME 43 9894
TEST_TIME 44 9913
TEST_TIME 45 9936
TEST_TIME 46 9810
TEST_TIME 47 9611
TEST_TIME 48 9552
TEST_TIME 49 9497
TEST_TIME 50 9601
TEST_TIME 51 9476
TEST_TIME 52 9359
TEST_TIME 53 9302
TEST_TIME 54 9414
TEST_TIME 55 9319
TEST_TIME 56 9258
TEST_TIME 57 9167
TEST_TIME 58 9034
TEST_TIME 59 8984
TEST_TIME 60 8878
TEST_TIME 61 8825
TEST_TIME 62 8834
TEST_TIME 63 8798
TEST_TIME 64 8760
TEST_TIME 65 8790
TEST_TIME 66 8739
TEST_TIME 67 8718
TEST_TIME 68 8879
TEST_TIME 69 8835
TEST_TIME 70 8983
TEST_TIME 71 8924
TEST_TIME 72 8940
TEST_TIME 73 8983
TEST_TIME 74 8946
TEST_TIME 75 9013
TEST_TIME 76 9041
TEST_TIME 77 9043
TEST_TIME 78 8965
TEST_TIME 79 8989
TEST_TIME 80 8975
TEST_TIME 81 8936
TEST_TIME 82 8883
TEST_TIME 83 8809
TEST_TIME 84 8781
TEST_TIME 85 8703
TEST_TIME 86 8827
TEST_TIME 87 8779
TEST_TIME 88 8785
TEST_TIME 89 8823
TEST_TIME 90 8776
TEST_TIME 91 8755
TEST_TIME 92 8799
TEST_TIME 93 8911
TEST_TIME 94 8893
TEST_TIME 95 8877
TEST_TIME 96 8946
TEST_TIME 97 8926
TEST_TIME 98 8953
TEST_TIME 99 9009
TEST_TIME 100 8989
TEST_TIME 101 8937
TEST_TIME 102 8928
TEST_TIME 103 8897
TEST_TIME 104 8849
TEST_TIME 105 8820
TEST_TIME 106 8783
TEST_TIME 107 8775
TEST_TIME 108 8776
TEST_TIME 109 8753
TEST_TIME 110 8767
TEST_TIME 111 8786
TEST_TIME 112 8774
TEST_TIME 113 8766
TEST_TIME 114 8781
TEST_TIME 115 8808
TEST_TIME 116 8863
TEST_TIME 117 8848
TEST_TIME 118 8845
TEST_TIME 119 8881
TEST_TIME 120 8838

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-09-2022 13:38:24 to 24-09-2022 13:38:27.

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 1471862 1471862 0 0.00 0 19063 14 0 0
doc_list_biud 1471854 1471854 0 0.00 0 63767 41 0 0
sp_add_invoice_to_stock 4877 4785 92 1.88 26 246854 28983 0 92
sp_cancel_adding_invoice 5377 5377 0 0.00 6 122514 1172 0 0
sp_cancel_client_order 1694 1694 0 0.00 7 10626 400 0 0
sp_cancel_customer_reserve 527872 527872 0 0.00 1 63771 98 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 4 4398 118 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 3 4498 131 0 0
sp_cancel_supplier_invoice 5934 5928 6 0.10 2 13171 1047 0 6
sp_cancel_supplier_order 391 391 0 0.00 12 1330888 169946 0 0
sp_cancel_write_off 9081 9081 0 0.00 2 8762 168 0 0
sp_client_order 19407 19407 0 0.00 5 17838 495 0 0
sp_customer_reserve 449436 449436 0 0.00 0 51016 275 0 0
sp_fill_shopping_cart 29757 29757 0 0.00 1 13441 83 0 0
sp_fill_shopping_cart_clo_res 449409 449409 0 0.00 0 21828 22 0 0
sp_get_clo_for_invoice 4744 4744 0 0.00 0 22245 354 0 0
sp_kill_cost_storno 15498 15498 0 0.00 0 13540 73 0 0
sp_kill_qstorno_ret_qs2qd 1071144 1071144 0 0.00 0 63759 43 0 0
sp_kill_qty_storno 564469 564469 0 0.00 0 63767 99 0 0
sp_lock_selected_doc 19616 19616 0 0.00 0 9318 9 0 0
sp_make_cost_storno 26267 26267 0 0.00 0 19055 88 0 0
sp_multiply_rows_for_pdistr 26265 26265 0 0.00 0 10624 38 0 0
sp_multiply_rows_for_qdistr 19406 19406 0 0.00 0 11250 203 0 0
sp_pay_from_customer 6682 6682 0 0.00 6 10896 175 0 0
sp_pay_to_supplier 5457 5457 0 0.00 7 16226 208 0 0
sp_payment_common 12139 12139 0 0.00 2 6283 47 0 0
sp_qd_handle_on_cancel_clo 1694 1694 0 0.00 0 10603 320 0 0
sp_qd_handle_on_invoice_upd_sts 10120 10120 0 0.00 0 30529 530 0 0
sp_qd_handle_on_reserve_upd_sts 9383 9383 0 0.00 0 9979 69 0 0
sp_reserve_write_off 9384 9384 0 0.00 9 19095 295 0 0
sp_supplier_invoice 5246 5246 0 0.00 8 63481 3328 0 0
sp_supplier_order 5113 5113 0 0.00 7 51912 1381 0 0
srv_aggregate_perf_data 100 99 1 1.00 2275 187939 16098 0 1
srv_fill_mon_cache_memory 68 68 0 0.00 762 137664 38184 0 0
srv_find_qd_qs_mism 1011584 1011584 0 0.00 0 15334 12 0 0
srv_make_invnt_saldo 497 497 0 0.00 45 92205 3663 0 0
srv_make_money_saldo 819 819 0 0.00 1 10073 368 0 0
srv_recalc_idx_stat 4 4 0 0.00 66071 540271 296808 0 0
t$perf-abend:sp_add_invoice_to_stock 92 92 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4788 4788 0 0.00 1 13550 967 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 1 6781 352 0 0
t$perf-norm:sp_cancel_client_order 1694 1694 0 0.00 0 3191 27 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 3521 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 920 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 5570 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 1926 45 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 90671 7483 0 0
t$perf-norm:sp_cancel_write_off 944 944 0 0.00 0 1012 17 0 0
t$perf-norm:sp_client_order 19407 19407 0 0.00 0 24301 39 0 0
t$perf-norm:sp_customer_reserve 942 942 0 0.00 0 532 14 0 0
t$perf-norm:sp_pay_from_customer 6682 6682 0 0.00 0 10814 20 0 0
t$perf-norm:sp_pay_to_supplier 5457 5457 0 0.00 0 16892 30 0 0
t$perf-norm:sp_reserve_write_off 9384 9384 0 0.00 0 6421 24 0 0
t$perf-norm:sp_supplier_invoice 5246 5246 0 0.00 0 23981 40 0 0
t$perf-norm:sp_supplier_order 5113 5113 0 0.00 0 9323 37 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 34 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 40 3 0 0
t$perf-norm:srv_make_invnt_saldo 497 497 0 0.00 0 2549 20 0 0
t$perf-norm:srv_make_money_saldo 819 819 0 0.00 0 4456 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 196 55 0 0
v_add_invoice_to_stock 9666 9482 184 1.90 0 10079 28 0 184
v_all_customers 35710 35710 0 0.00 0 232 4 0 0
v_all_suppliers 28960 28960 0 0.00 0 233 4 0 0
v_all_wares 38808 38808 0 0.00 0 63 4 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 385 13 0 0
v_cancel_client_order 3388 3388 0 0.00 0 10092 14 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 4365 15 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 1 1166 10 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 438 10 0 0
v_cancel_supplier_invoice 762 750 12 1.57 0 1352 27 0 12
v_cancel_supplier_order 782 782 0 0.00 0 1573 14 0 0
v_cancel_write_off 1888 1888 0 0.00 0 2850 28 0 0
v_max_id_clo_ord 5112 5112 0 0.00 0 66 5 0 0
v_max_id_clo_res 942 942 0 0.00 0 2432 17 0 0
v_max_id_ord_sup 5238 5238 0 0.00 0 4258 9 0 0
v_max_non_paid_invoice 1326 1326 0 0.00 0 92 8 0 0
v_max_non_paid_realizn 3430 3430 0 0.00 0 3757 8 0 0
v_min_id_clo_ord 5112 5112 0 0.00 0 2007 9 0 0
v_min_id_clo_res 942 942 0 0.00 0 1930 15 0 0
v_min_id_ord_sup 5238 5238 0 0.00 0 2538 8 0 0
v_min_non_paid_invoice 5457 5457 0 0.00 0 6235 17 0 0
v_min_non_paid_realizn 6682 6682 0 0.00 0 5849 17 0 0
v_our_firm 9598 9598 0 0.00 0 71 5 0 0
v_reserve_write_off 18758 18758 0 0.00 0 6113 12 0 0
x_lock_depdocs_on_canc_invoice 10930 10930 0 0.00 0 16595 131 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 12803 436 0 0
x_make_qstorno_1000_1200 5113 5113 0 0.00 3 51851 1225 0 0
x_make_qstorno_1200_2000 5246 5246 0 0.00 4 59373 3092 0 0
x_make_qstorno_2100_3300 449435 449435 0 0.00 0 51016 240 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-09-2022 13:38:28 to 24-09-2022 13:38:32.

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
11:07:25 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:26 23978 8192000000 9715998384 10013753344 793629728 8.832 100 85 170 0 821014864 7192176 20192960
11:09:58 20167 8192000000 9765552112 10071695360 837486096 9.275 100 89 178 0 870399200 11872032 21041072
11:11:26 13653 8192000000 9800857184 10120065024 869708752 9.597 100 85 170 0 910317104 21306240 19302112
11:12:49 6342 8192000000 9868552672 10201264128 915694560 10.054 100 81 158 0 978635680 46013200 16927920
11:13:58 3706 8192000000 9920282608 10268602368 959916944 10.488 100 77 149 0 1037878080 62119024 15842112
11:15:04 1774 8192000000 9966158304 10321903616 998077232 10.860 100 91 171 0 1080215840 64647792 17490816
11:16:09 1382 8192000000 10007295920 10370449408 1022282192 11.094 100 92 177 2 1122779472 83370224 17127056
11:17:11 737 8192000000 10005427840 10372104192 1038631040 11.252 100 95 181 1 1127886064 70779472 18475552
11:18:15 948 8192000000 10027980688 10400698368 1058957712 11.447 100 98 196 1 1150085120 71052528 20074880
11:19:17 1070 8192000000 10032037056 10409463808 1067954592 11.533 100 95 178 4 1159951952 73881888 18115472
11:20:20 1220 8192000000 10069903808 10453262336 1073144016 11.582 100 82 159 1 1187492816 98486064 15862736
11:21:22 1256 8192000000 10045962096 10430603264 1078748624 11.636 100 97 188 0 1168616928 71270304 18598000
11:22:24 968 8192000000 10042910064 10429399040 1084236992 11.688 100 91 180 1 1168101232 63459584 20404656
11:23:26 951 8192000000 10031387152 10420752384 1090334416 11.746 100 84 161 0 1155146912 47345632 17466864
11:24:28 999 8192000000 10054483248 10442706944 1096013072 11.800 100 95 181 0 1176949248 62466768 18469408
11:25:30 1576 8192000000 10069073152 10457915392 1098640416 11.825 100 95 184 0 1190629408 73560320 18428672
11:26:35 1027 8192000000 10063636016 10453323776 1098191616 11.820 100 93 173 1 1184296064 68114336 17990112
11:27:36 1190 8192000000 10057364656 10446938112 1103891920 11.875 100 97 181 1 1181561920 59599984 18070016
11:28:38 913 8192000000 10044612496 10433003520 1097045552 11.810 100 87 166 1 1168526240 54818016 16662672
11:29:40 1393 8192000000 10044629840 10431959040 1079393712 11.642 100 86 162 3 1168472224 71153456 17925056
11:30:43 1167 8192000000 10037148496 10416885760 1067014848 11.524 100 93 180 3 1160057376 74743920 18298608
11:31:45 2213 8192000000 10000809776 10385285120 1043259568 11.296 98 81 150 3 1121813920 61737504 16816848
11:32:48 1021 8192000000 9970850496 10346512384 1036224944 11.228 99 86 163 0 1096999776 43150880 17623952
11:33:51 850 8192000000 9999354000 10375778304 1036668352 11.233 100 84 157 1 1127215168 72892112 17654704
11:34:52 3823 8192000000 9983997696 10361389056 1035443040 11.221 100 67 125 2 1118771248 71038880 12289328
11:35:59 3997 8192000000 9966770960 10345869312 1023520896 11.106 99 64 124 2 1085906752 49767440 12618416
11:37:04 3023 8192000000 9976096656 10360274944 1043087744 11.294 100 64 116 2 1100673712 45687888 11898080
11:38:12 25626 8192000000 10021205664 10405818368 1070275440 11.555 100 74 140 1 1143894192 58998864 14619888
11:39:45 25891 8192000000 10070625488 10462339072 1089474816 11.738 100 71 136 0 1186643328 83583472 13585040
11:41:17 10427 8192000000 10098319680 10491834368 1104999008 11.885 100 80 153 1 1215329264 95507376 14822880
11:42:34 1034 8192000000 10133389040 10519691264 1118570944 12.013 100 94 184 2 1251350416 112527664 20251808
11:43:38 25086 8192000000 10151747632 10559770624 1130302400 12.124 100 61 115 0 1266734864 124130608 12301856
11:45:06 2868 8192000000 10183259600 10594181120 1142745520 12.241 100 69 134 0 1292458080 135092160 14620400
11:46:13 1763 8192000000 10229075616 10625781760 1157335584 12.378 100 95 188 2 1347364384 169183952 20844848
11:47:19 5038 8192000000 10247139600 10658115584 1158955856 12.393 100 81 156 1 1365463536 188466128 18041552
11:48:29 1340 8192000000 10294415904 10703220736 1175863216 12.552 100 97 188 0 1406029744 208683440 21483088
11:49:33 1438 8192000000 10314179488 10719064064 1175387040 12.547 100 94 176 3 1425579248 229370784 20821424
11:50:38 1683 8192000000 10312404256 10736861184 1181473168 12.604 100 87 173 1 1428192608 225574816 21144624
11:51:43 1921 8192000000 10341697168 10762084352 1184836336 12.635 100 83 164 0 1452502464 247669312 19996816
11:52:50 1055 8192000000 10392818896 10810261504 1195047792 12.730 100 93 182 0 1498030288 280992960 21989536
11:53:55 881 8192000000 10401568496 10821881856 1202837024 12.803 100 95 180 2 1511309040 286287792 22184224
11:54:59 752 8192000000 10436589952 10846056448 1203629264 12.810 100 93 182 1 1541351728 317461984 20260480
11:56:07 1516 8192000000 10488045456 10897805312 1205680736 12.829 100 93 183 1 1594446256 366280000 22485520
11:57:12 2231 8192000000 10513092896 10953281536 1205162256 12.824 100 92 182 0 1623050272 395941984 21946032
11:58:18 2026 8192000000 10489755184 10953940992 1201442816 12.790 100 79 154 3 1593583184 372980144 19160224
11:59:21 2356 8192000000 10492523040 10921746432 1189638912 12.680 100 80 159 0 1598542240 389409472 19493856
12:00:26 4827 8192000000 10530494160 10968154112 1175269824 12.546 100 65 124 0 1636906336 445369568 16266944
12:01:37 1617 8192000000 10562345344 11006922752 1182382416 12.612 100 87 171 0 1663993616 460493680 21117520
12:02:42 1445 8192000000 10493347616 11012349952 1189190624 12.676 100 79 149 1 1601553760 392429664 19933472
12:03:46 6622 8192000000 10423890016 10901086208 1174319600 12.537 99 46 92 0 1525236176 338865280 12051296
12:05:05 8399 8192000000 10394732752 10874753024 1172832896 12.523 100 37 66 1 1503243216 322057248 8353072
12:06:18 13499 8192000000 10365711040 10819854336 1140355056 12.219 98 50 99 0 1467153360 314970768 11827536
12:08:01 31776 8192000000 10283539600 10790117376 1148842800 12.299 100 26 50 0 1428458592 273588576 6027216
12:09:35 58626 8192000000 10090957280 10618634240 1113228992 11.963 98 10 20 0 1208886624 93570592 2087040
12:11:35 10965 8192000000 10093936304 10515574784 1134023520 12.159 100 72 114 4 1185743120 41224480 10495120
12:12:47 62395 8192000000 10122550960 10573152256 1130744048 12.128 99 58 104 3 1222767792 81297680 10726064
12:15:08 65881 8192000000 10116233600 10596392960 1136422576 12.182 100 11 22 0 1212499056 73862496 2213984
12:17:15 75037 8192000000 10163498400 10633052160 1144406704 12.257 100 17 32 0 1241820592 93804224 3609664
12:19:44 100779 8192000000 10133753664 10588352512 1110032016 11.933 97 22 43 0 1236295552 122016160 4247376
12:22:58 103847 8192000000 10080662672 10533965824 1125501472 12.079 100 27 54 0 1163174256 31928928 5743856
12:26:01 117209 8192000000 10046836400 10516094976 1055900736 11.417 93 27 53 0 1146509808 84888720 5720352
12:29:07 27186 8192000000 10095039408 10525945856 1125599920 12.080 100 48 95 0 1186250688 50564896 10085872
12:30:40 85640 8192000000 10038248960 10566332416 1094222096 11.783 98 14 28 0 1191714080 94335536 3156448
12:33:08 60748 8192000000 10027022784 10480721920 1099097408 11.829 99 12 24 0 1139469872 38123936 2248528
12:35:12 57898 8192000000 10029020640 10481963008 1107653344 11.910 99 17 34 0 1137590800 26294816 3642640
12:37:10 94480 8192000000 10089790672 10543165440 1123378608 12.059 100 21 42 0 1182885744 54912800 4594336
12:39:45 67971 8192000000 10062273200 10535505920 1117099664 12.000 99 16 30 0 1175240480 54969824 3170992
12:41:53 40851 8192000000 10090891360 10528047104 1084684112 11.692 96 23 44 1 1156503232 67308576 4510544
12:43:34 22795 8192000000 10137408832 10567917568 1122067952 12.047 99 49 95 0 1237284032 104968768 10247312
12:44:59 52384 8192000000 10091553120 10564829184 1110736096 11.939 98 11 22 0 1189432896 76367216 2329584
12:47:08 45671 8192000000 10089155312 10570645504 1113928528 11.970 98 33 66 0 1213526896 92908000 6690368
12:48:56 21954 8192000000 10123457664 10546974720 1109747888 11.930 98 59 112 0 1201696352 80008688 11939776
12:50:30 12389 8192000000 10129609392 10568273920 1132549872 12.145 100 54 108 0 1226636368 83059776 11026720
12:52:11 32289 8192000000 10126974208 10596769792 1119897712 12.026 99 25 50 0 1215994784 91262192 4834880
12:53:44 137664 8192000000 10096435216 10598985728 1120937456 12.036 99 19 38 0 1224029648 99316080 3776112
12:57:33 62200 8192000000 10080448240 10558451712 1121388400 12.040 99 15 30 0 1175924736 51322896 3213440
12:59:43 84714 8192000000 10079363216 10548121600 1118521216 12.013 99 20 40 0 1197466320 74787680 4157424
13:02:17 80670 8192000000 10072672304 10603331584 1097626528 11.815 97 8 16 0 1184095296 84778944 1689824
13:04:43 15760 8192000000 10066912192 10494705664 1110806272 11.940 99 49 94 3 1152191072 30458432 10926368
13:06:05 47325 8192000000 10048774608 10522808320 1056418720 11.422 94 29 54 0 1110161536 48061296 5681520
13:07:56 15611 8192000000 10113245120 10551312384 1123494640 12.060 99 38 75 0 1205543072 74543872 7504560
13:09:13 43860 8192000000 10135748800 10597109760 1112009440 11.951 98 33 66 0 1223619664 105077520 6532704
13:11:34 44856 8192000000 10114470480 10540240896 1123530880 12.060 98 40 79 0 1189666896 58091920 8044096
13:13:21 52845 8192000000 10098609504 10556215296 1129379808 12.116 99 30 60 0 1190440832 55283904 5777120
13:15:14 39113 8192000000 10110797200 10572750848 1121732640 12.043 98 42 84 0 1210373568 80211792 8429136
13:16:55 44685 8192000000 10046711904 10481025024 1105440016 11.889 97 32 57 0 1123325376 11632256 6253104
13:18:47 132619 8192000000 10052229952 10536546304 1110425264 11.936 98 26 51 0 1164299728 47671984 6202480
13:22:00 76272 8192000000 10076653296 10534051840 1103992320 11.876 97 17 35 0 1170294320 62642464 3659536
13:24:17 39167 8192000000 10104679120 10570543104 1129341488 12.115 100 19 38 0 1201945536 68446416 4157632
13:25:59 57684 8192000000 10102833440 10549104640 1119292176 12.020 99 21 40 1 1193897632 70307248 4298208
13:28:07 22693 8192000000 10121910384 10562113536 1119629824 12.023 98 41 81 0 1199104672 70818784 8656064
13:29:30 65963 8192000000 10130999824 10572095488 1093403792 11.775 97 32 62 0 1169341952 69586016 6352144
13:31:42 49647 8192000000 10086156704 10557480960 1095398352 11.794 98 21 42 0 1152428784 52786848 4243584
13:33:33 38045 8192000000 10088456928 10584981504 1115502688 11.984 100 39 76 0 1227833568 104008944 8321936
13:35:34 44650 8192000000 10138467200 10598588416 1101828896 11.855 98 50 100 1 1205160848 92715760 10616192

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 24-09-2022 13:38:34 to 24-09-2022 13:38:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 92 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-09-2022 13:38:51 to 24-09-2022 13:38:53.

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          212511
MON$OLDEST_ACTIVE               215434
MON$OLDEST_SNAPSHOT             215434
MON$NEXT_TRANSACTION            215434
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                       2049952
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             768
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 ---
:44: 
:45: mscs-test-161.budzdorov.ru    Sat Sep 24 13:37:23 2022
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:47: 
:48: 
:49: mscs-test-161.budzdorov.ru    Sat Sep 24 13:37:33 2022
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:51: 
:52: 
:53: mscs-test-161.budzdorov.ru    Sat Sep 24 13:37:33 2022
:54:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:55: 
--- end of diff output ---

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

24.09.22 13:38:54. 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-24 13:38:57 No severe PSQL-related problems occured
24.09.22 13:38:58. End of report.