Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.08.2022 10:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15409432 kB
MemAvailable 30922148 kB
Buffers 0 kB
Cached 16276724 kB
SwapCached 2508 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-08-10 10:37:27
DTS_END                         2022-08-10 10:37:27.2560
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/43026
TRN_ID                          212634
ATT_ID                          627
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: 8566.78 32114 1028013
customer order: creation 158.04 510 18965
customer order: refuse 13.59 478 1631
order to supplier: creation 42.42 1535 5090
order to supplier: removal 3.27 209815 392
invoice (draft): creation 42.45 3682 5094
invoice (draft): removal 47.04 1163 5645
invoice accept: apply 39.45 30438 4734
invoice accept: cancel 42.22 1346 5066
customer reserve: creation 3726.34 285 447161
customer reserve: removal 4171.29 128 500555
realization accept: apply 78.16 326 9379
realization accept: cancel 78.13 207 9376
payment from customer: creation 56.15 196 6738
payment from customer: removal 5.48 177 658
payment to supplier: creation 46.60 208 5592
payment to supplier: removal 3.22 139 386
service: total inventory turnovers 4.02 4187 482
service: total monetary turnovers 7.90 430 948
service: aggregate perf. data 0.96 16482 115
service: refresh index statistics 0.05 370547 6

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7088
TEST_TIME 2 5170
TEST_TIME 3 5565
TEST_TIME 4 5401
TEST_TIME 5 5299
TEST_TIME 6 5700
TEST_TIME 7 5841
TEST_TIME 8 6289
TEST_TIME 9 6219
TEST_TIME 10 6164
TEST_TIME 11 6586
TEST_TIME 12 6756
TEST_TIME 13 6884
TEST_TIME 14 6892
TEST_TIME 15 6882
TEST_TIME 16 6860
TEST_TIME 17 6876
TEST_TIME 18 6868
TEST_TIME 19 7100
TEST_TIME 20 7130
TEST_TIME 21 7014
TEST_TIME 22 6987
TEST_TIME 23 7072
TEST_TIME 24 7332
TEST_TIME 25 7327
TEST_TIME 26 7436
TEST_TIME 27 7634
TEST_TIME 28 7862
TEST_TIME 29 7960
TEST_TIME 30 7922
TEST_TIME 31 7937
TEST_TIME 32 8022
TEST_TIME 33 8393
TEST_TIME 34 8948
TEST_TIME 35 9182
TEST_TIME 36 9336
TEST_TIME 37 9476
TEST_TIME 38 9958
TEST_TIME 39 10657
TEST_TIME 40 10852
TEST_TIME 41 10663
TEST_TIME 42 10595
TEST_TIME 43 10445
TEST_TIME 44 10373
TEST_TIME 45 10392
TEST_TIME 46 10193
TEST_TIME 47 10051
TEST_TIME 48 10056
TEST_TIME 49 9907
TEST_TIME 50 9755
TEST_TIME 51 9692
TEST_TIME 52 9718
TEST_TIME 53 9624
TEST_TIME 54 9561
TEST_TIME 55 9527
TEST_TIME 56 9430
TEST_TIME 57 9467
TEST_TIME 58 9566
TEST_TIME 59 9478
TEST_TIME 60 9359
TEST_TIME 61 9275
TEST_TIME 62 9251
TEST_TIME 63 9257
TEST_TIME 64 9177
TEST_TIME 65 9116
TEST_TIME 66 9102
TEST_TIME 67 9047
TEST_TIME 68 9033
TEST_TIME 69 8938
TEST_TIME 70 8866
TEST_TIME 71 8842
TEST_TIME 72 8786
TEST_TIME 73 8786
TEST_TIME 74 8838
TEST_TIME 75 8794
TEST_TIME 76 8786
TEST_TIME 77 8771
TEST_TIME 78 8780
TEST_TIME 79 8842
TEST_TIME 80 8852
TEST_TIME 81 8897
TEST_TIME 82 8901
TEST_TIME 83 8949
TEST_TIME 84 8981
TEST_TIME 85 8926
TEST_TIME 86 8993
TEST_TIME 87 8966
TEST_TIME 88 8999
TEST_TIME 89 8956
TEST_TIME 90 8888
TEST_TIME 91 8870
TEST_TIME 92 8842
TEST_TIME 93 8823
TEST_TIME 94 8789
TEST_TIME 95 8756
TEST_TIME 96 8699
TEST_TIME 97 8662
TEST_TIME 98 8635
TEST_TIME 99 8637
TEST_TIME 100 8687
TEST_TIME 101 8650
TEST_TIME 102 8653
TEST_TIME 103 8649
TEST_TIME 104 8696
TEST_TIME 105 8724
TEST_TIME 106 8702
TEST_TIME 107 8771
TEST_TIME 108 8811
TEST_TIME 109 8809
TEST_TIME 110 8754
TEST_TIME 111 8703
TEST_TIME 112 8729
TEST_TIME 113 8692
TEST_TIME 114 8695
TEST_TIME 115 8671
TEST_TIME 116 8651
TEST_TIME 117 8624
TEST_TIME 118 8636
TEST_TIME 119 8605
TEST_TIME 120 8585

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-08-2022 10:38:46 to 10-08-2022 10:38:49.

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 1438519 1438519 0 0.00 0 18438 15 0 0
doc_list_biud 1438504 1438504 0 0.00 0 80727 50 0 0
sp_add_invoice_to_stock 4836 4734 102 2.10 12 236526 30438 0 102
sp_cancel_adding_invoice 5066 5066 0 0.00 6 75845 1346 0 0
sp_cancel_client_order 1631 1631 0 0.00 5 9709 478 0 0
sp_cancel_customer_reserve 500555 500555 0 0.00 1 22655 129 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 3 8304 178 0 0
sp_cancel_pay_to_supplier 386 386 0 0.00 4 6193 139 0 0
sp_cancel_supplier_invoice 5656 5645 11 0.19 3 80742 1164 0 11
sp_cancel_supplier_order 392 392 0 0.00 21 1914089 209816 0 0
sp_cancel_write_off 9376 9376 0 0.00 2 9371 208 0 0
sp_client_order 18965 18965 0 0.00 6 28975 510 0 0
sp_customer_reserve 447161 447161 0 0.00 1 66147 286 0 0
sp_fill_shopping_cart 29135 29135 0 0.00 1 12840 90 0 0
sp_fill_shopping_cart_clo_res 447122 447122 0 0.00 0 20501 22 0 0
sp_get_clo_for_invoice 4686 4686 0 0.00 0 25524 342 0 0
sp_kill_cost_storno 15486 15486 0 0.00 0 10285 93 0 0
sp_kill_qstorno_ret_qs2qd 1016523 1016523 0 0.00 0 80726 55 0 0
sp_kill_qty_storno 536727 536727 0 0.00 0 80727 125 0 0
sp_lock_selected_doc 19534 19534 0 0.00 0 6541 7 0 0
sp_make_cost_storno 26394 26394 0 0.00 0 16777 92 0 0
sp_multiply_rows_for_pdistr 26393 26393 0 0.00 0 9364 43 0 0
sp_multiply_rows_for_qdistr 18965 18965 0 0.00 0 12209 188 0 0
sp_pay_from_customer 6738 6738 0 0.00 5 10242 197 0 0
sp_pay_to_supplier 5592 5592 0 0.00 6 10320 208 0 0
sp_payment_common 12329 12329 0 0.00 2 8578 48 0 0
sp_qd_handle_on_cancel_clo 1631 1631 0 0.00 0 9698 389 0 0
sp_qd_handle_on_invoice_upd_sts 9751 9751 0 0.00 0 17100 630 0 0
sp_qd_handle_on_reserve_upd_sts 9377 9377 0 0.00 0 18559 77 0 0
sp_reserve_write_off 9379 9379 0 0.00 8 18610 327 0 0
sp_supplier_invoice 5094 5094 0 0.00 9 85547 3682 0 0
sp_supplier_order 5090 5090 0 0.00 6 37143 1536 0 0
srv_aggregate_perf_data 115 115 0 0.00 2317 150107 16483 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 760 142075 31312 0 0
srv_find_qd_qs_mism 980948 980948 0 0.00 0 9129 11 0 0
srv_make_invnt_saldo 482 482 0 0.00 36 67469 4187 0 0
srv_make_money_saldo 948 948 0 0.00 1 18668 430 0 0
srv_recalc_idx_stat 6 6 0 0.00 101267 540342 370547 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4736 4736 0 0.00 0 13138 1079 0 0
t$perf-norm:sp_cancel_adding_invoice 381 381 0 0.00 1 12153 468 0 0
t$perf-norm:sp_cancel_client_order 1631 1631 0 0.00 0 2095 30 0 0
t$perf-norm:sp_cancel_customer_reserve 639 639 0 0.00 0 1277 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 661 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 386 386 0 0.00 0 366 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 1276 44 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 118471 8157 0 0
t$perf-norm:sp_cancel_write_off 1006 1006 0 0.00 0 2729 21 0 0
t$perf-norm:sp_client_order 18965 18965 0 0.00 0 11647 38 0 0
t$perf-norm:sp_customer_reserve 931 931 0 0.00 0 1198 17 0 0
t$perf-norm:sp_pay_from_customer 6739 6739 0 0.00 0 26657 26 0 0
t$perf-norm:sp_pay_to_supplier 5593 5593 0 0.00 0 3404 21 0 0
t$perf-norm:sp_reserve_write_off 9379 9379 0 0.00 0 8578 25 0 0
t$perf-norm:sp_supplier_invoice 5094 5094 0 0.00 0 4495 39 0 0
t$perf-norm:sp_supplier_order 5090 5090 0 0.00 0 2557 32 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 407 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 148 7 0 0
t$perf-norm:srv_make_invnt_saldo 482 482 0 0.00 0 8686 40 0 0
t$perf-norm:srv_make_money_saldo 948 948 0 0.00 0 894 7 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 3 182 36 0 0
v_add_invoice_to_stock 9570 9366 204 2.13 0 6031 31 0 204
v_all_customers 35084 35084 0 0.00 0 128 4 0 0
v_all_suppliers 28904 28904 0 0.00 0 97 5 0 0
v_all_wares 37918 37918 0 0.00 0 55 4 0 0
v_cancel_adding_invoice 762 762 0 0.00 0 5066 38 0 0
v_cancel_client_order 3262 3262 0 0.00 0 5253 16 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 3427 27 0 0
v_cancel_customer_reserve 1278 1278 0 0.00 0 4915 15 0 0
v_cancel_payment_to_supplier 772 772 0 0.00 0 2711 13 0 0
v_cancel_supplier_invoice 788 766 22 2.79 0 543 26 0 22
v_cancel_supplier_order 784 784 0 0.00 0 2788 28 0 0
v_cancel_write_off 2012 2012 0 0.00 0 5058 34 0 0
v_max_id_clo_ord 5088 5088 0 0.00 0 1605 6 0 0
v_max_id_clo_res 931 931 0 0.00 0 3525 18 0 0
v_max_id_ord_sup 5087 5087 0 0.00 0 2467 6 0 0
v_max_non_paid_invoice 1314 1314 0 0.00 0 406 9 0 0
v_max_non_paid_realizn 3444 3444 0 0.00 0 455 8 0 0
v_min_id_clo_ord 5088 5088 0 0.00 0 3517 13 0 0
v_min_id_clo_res 931 931 0 0.00 0 1646 13 0 0
v_min_id_ord_sup 5087 5087 0 0.00 0 5466 8 0 0
v_min_non_paid_invoice 5591 5591 0 0.00 0 7964 17 0 0
v_min_non_paid_realizn 6738 6738 0 0.00 0 8567 19 0 0
v_our_firm 9422 9422 0 0.00 0 61 5 0 0
v_reserve_write_off 18750 18750 0 0.00 0 4906 13 0 0
x_lock_depdocs_on_canc_invoice 10328 10328 0 0.00 0 11249 163 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 10240 498 0 0
x_make_qstorno_1000_1200 5090 5090 0 0.00 2 36993 1347 0 0
x_make_qstorno_1200_2000 5094 5094 0 0.00 3 85524 3434 0 0
x_make_qstorno_2100_3300 447158 447158 0 0.00 0 66145 250 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-08-2022 10:38:50 to 10-08-2022 10:38:54.

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:31 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:32 15586 8192000000 9725855440 10024460288 806428944 8.961 100 86 172 0 834139744 7236848 20473952
08:09:54 19639 8192000000 9760398192 10069536768 840160896 9.301 100 87 173 0 872677296 11835600 20680800
08:11:24 17405 8192000000 9795621792 10114035712 863038144 9.531 100 83 166 0 900382496 17973744 19370608
08:12:52 14711 8192000000 9858357584 10191671296 902922656 9.927 100 75 149 0 967598976 48590336 16085984
08:14:12 4810 8192000000 9903534464 10245681152 949373584 10.385 100 88 163 0 1022106384 54580992 18151808
08:15:20 1777 8192000000 9958687424 10306863104 990101856 10.782 100 97 190 0 1079188336 70218048 18868432
08:16:23 1384 8192000000 10000766016 10358407168 1019160096 11.064 100 92 180 1 1114569664 77034368 18375200
08:17:27 1746 8192000000 10003351744 10367885312 1032253936 11.190 100 90 168 3 1127768928 77471472 18043520
08:18:30 951 8192000000 10030933952 10404155392 1054561680 11.404 100 96 186 2 1154192448 79827792 19802976
08:19:32 970 8192000000 10029710272 10409832448 1066368736 11.517 100 92 175 3 1150746848 66550624 17827488
08:20:33 916 8192000000 10029770944 10408374272 1073650352 11.587 100 93 181 0 1152964272 60670272 18643648
08:21:36 947 8192000000 10049295072 10437468160 1076109120 11.610 100 79 155 0 1171048576 78531888 16407568
08:22:38 1099 8192000000 10043565360 10425856000 1087731792 11.721 100 91 174 1 1163530496 57374816 18423888
08:23:40 982 8192000000 10053251568 10437189632 1088464112 11.728 100 97 176 0 1176006800 68032992 19509696
08:24:42 1070 8192000000 10065287616 10455322624 1092683488 11.768 100 96 180 2 1192040080 80087344 19269248
08:25:43 1157 8192000000 10057396976 10446213120 1092352240 11.765 100 95 184 0 1175599808 64058704 19188864
08:26:46 4265 8192000000 10053870400 10459648000 1098676432 11.825 100 63 124 0 1185222256 73944304 12601520
08:27:51 4552 8192000000 10047748112 10442244096 1100412304 11.842 100 70 133 1 1167456240 52931872 14112064
08:28:57 835 8192000000 10062534560 10449076224 1098859856 11.827 100 84 164 2 1180884416 65092960 16931600
08:29:58 1177 8192000000 10037091872 10425716736 1079371072 11.641 99 86 163 2 1157842192 61446032 17025088
08:31:00 3898 8192000000 10014292432 10409082880 1079596208 11.644 100 57 112 1 1134231968 44031632 10604128
08:32:06 903 8192000000 10019928544 10403995648 1076292768 11.612 100 92 166 1 1142383424 48219712 17870944
08:33:08 1226 8192000000 10017334736 10391658496 1052361088 11.383 100 95 175 5 1140083296 68457872 19264336
08:34:11 1203 8192000000 10039282864 10417156096 1049063376 11.352 100 98 192 0 1163653504 96287952 18302176
08:35:14 1424 8192000000 9970538080 10343022592 1026420624 11.134 100 80 142 4 1090807344 50593408 13793312
08:36:16 2577 8192000000 9977881392 10362089472 1029227888 11.161 100 59 100 0 1103842976 63866112 10748976
08:37:21 1223 8192000000 9968563984 10338840576 1037274880 11.238 100 92 176 6 1085887456 30564416 18048160
08:38:31 4336 8192000000 9985972320 10371461120 1054905168 11.408 100 60 117 1 1105466688 38343408 12218112
08:39:40 8742 8192000000 10049998208 10441420800 1069386944 11.546 100 61 118 0 1168861632 87158880 12315808
08:40:57 3636 8192000000 10032368272 10411450368 1069851520 11.551 100 78 142 2 1149704512 65432912 14420080
08:42:07 7600 8192000000 10083611840 10473537536 1086611744 11.710 100 70 132 0 1195813296 95831152 13370400
08:43:21 1069 8192000000 10125671344 10511020032 1103279760 11.869 100 92 169 3 1238853232 117997216 17576256
08:44:27 3898 8192000000 10149779744 10552123392 1116850176 11.997 100 77 147 1 1268743488 135905296 15988016
08:45:38 861 8192000000 10168570128 10568998912 1134457504 12.163 100 91 173 1 1285806080 132310848 19037728
08:46:45 12103 8192000000 10192488848 10594672640 1141709648 12.232 100 66 129 1 1292951552 137119568 14122336
08:48:01 873 8192000000 10223325280 10638180352 1163627904 12.437 100 92 173 2 1335931568 152907104 19396560
08:49:10 1152 8192000000 10222575280 10631962624 1167291648 12.472 100 93 167 2 1333138880 148506336 17340896
08:50:19 1130 8192000000 10250257232 10648969216 1167804352 12.476 100 95 183 3 1361946256 174364992 19776912
08:51:25 1778 8192000000 10273601744 10676568064 1185123152 12.638 100 84 166 2 1379434320 176687040 17624128
08:52:31 921 8192000000 10324370688 10725347328 1185636352 12.643 100 93 172 0 1430876992 226706032 18534608
08:53:35 1299 8192000000 10340971280 10746208256 1193814496 12.719 100 92 170 2 1448438144 236601648 18022000
08:54:41 980 8192000000 10407463360 10810990592 1204627840 12.819 100 96 185 0 1518632112 292832224 21172048
08:55:46 2446 8192000000 10452956352 10878382080 1215076432 12.916 100 90 173 0 1558166304 322956656 20133216
08:56:55 944 8192000000 10453404096 10864889856 1211168992 12.880 99 97 191 2 1560913536 326653024 23091520
08:57:59 1464 8192000000 10505800336 10915037184 1224902544 13.007 100 97 193 2 1613649984 364706288 24041152
08:59:05 1290 8192000000 10557339120 10982862848 1223365216 12.993 100 96 187 1 1662370688 416364544 22640928
09:00:11 896 8192000000 10608547280 11026145280 1226296432 13.020 100 99 190 2 1711316400 461262880 23757088
09:01:14 1442 8192000000 10622049328 11075420160 1227090672 13.027 100 92 185 1 1729750480 479332544 23327264
09:02:19 1462 8192000000 10634083216 11054370816 1207733984 12.848 100 89 174 0 1742371376 511873808 22763584
09:03:24 1319 8192000000 10662350448 11131797504 1214739440 12.913 100 97 192 0 1761519264 522807072 23972752
09:04:28 816 8192000000 10671258848 11118505984 1217927520 12.943 100 96 185 1 1775891712 534657024 23307168
09:05:30 752 8192000000 10680296544 11113287680 1216925568 12.933 100 96 189 1 1782284544 542892384 22466592
09:06:33 8740 8192000000 10675139232 11136614400 1212028128 12.888 100 60 118 0 1783234176 555873680 15332368
09:07:48 848 8192000000 10731672864 11156246528 1208583616 12.856 100 94 186 0 1830886720 598333712 23969392
09:08:52 3316 8192000000 10762096976 11213557760 1211005984 12.878 100 64 127 0 1862876288 634377776 17492528
09:09:59 1241 8192000000 10750680256 11211726848 1199618784 12.773 100 64 127 0 1855200992 638322096 17260112
09:11:28 8100 8192000000 10622746272 11138043904 1197077104 12.749 100 50 101 0 1734630016 524720640 12832272
09:12:43 10204 8192000000 10535208912 11020898304 1175333248 12.547 99 49 97 0 1643404880 456592752 11478880
09:13:54 18369 8192000000 10525595680 11022098432 1170888848 12.505 100 29 58 0 1623801376 444989760 7922768
09:15:57 28067 8192000000 10287754752 10840780800 1140313456 12.218 99 20 37 1 1480404512 334880976 5210080
09:17:32 21199 8192000000 10112761936 10540507136 1157688768 12.382 100 53 92 4 1210505536 43995184 8821584
09:18:54 74458 8192000000 10154794496 10610503680 1134649424 12.165 98 30 59 0 1225067008 84443728 5973856
09:22:59 67581 8192000000 10107573728 10553819136 1114035440 11.971 97 43 83 0 1161172816 38752000 8385376
09:25:24 109032 8192000000 10114917648 10569650176 1144167344 12.255 99 21 42 0 1247752208 99141552 4443312
09:28:45 34978 8192000000 10146561920 10598203392 1150798016 12.317 100 21 42 0 1239901152 84432288 4670848
09:30:21 23750 8192000000 10147118544 10588319744 1125226704 12.076 98 27 54 0 1225964688 94551616 6186368
09:31:56 58140 8192000000 10188735984 10655322112 1157718112 12.382 100 40 75 0 1282808560 117154000 7936448
09:33:58 82081 8192000000 10106719520 10655682560 1147540784 12.286 100 28 56 0 1286696336 132432608 6722944
09:36:25 89343 8192000000 10107775952 10561085440 1121854976 12.045 98 22 43 0 1152910400 26873776 4181648
09:39:04 69095 8192000000 10106631136 10618650624 1148848480 12.299 100 55 107 0 1236340176 75690576 11801120
09:41:14 26440 8192000000 10085737376 10521292800 1141878624 12.233 99 68 119 5 1183874880 30300224 11696032
09:42:42 54920 8192000000 10109531216 10575003648 1149588192 12.306 100 27 54 0 1213449920 58327152 5534576
09:45:34 61952 8192000000 10047872464 10510860288 1110660016 11.939 97 17 32 1 1177177152 62119408 4397728
09:47:44 65929 8192000000 10054455328 10553708544 1122899712 12.054 99 13 25 0 1158331856 32794848 2637296
09:49:50 83183 8192000000 10067316032 10550329344 1066140512 11.515 94 6 12 0 1121109056 53742512 1226032
09:52:14 20757 8192000000 10036196608 10484248576 1116133536 11.990 99 35 64 2 1135584560 12475440 6975584
09:53:36 27286 8192000000 10078095376 10513256448 1122928448 12.055 98 45 85 1 1161451152 30244800 8277904
09:55:07 79741 8192000000 10126145232 10596614144 1093738752 11.778 95 18 35 0 1183216592 86119440 3358400
09:57:27 26711 8192000000 10105275792 10552266752 1136696736 12.184 99 41 77 2 1172536544 28444672 7395136
09:58:55 83140 8192000000 10090282928 10568548352 1094754464 11.788 97 35 70 0 1168478416 66464176 7259776
10:01:38 44790 8192000000 10061064560 10475577344 1062047824 11.476 94 49 97 1 1097360400 26053184 9259392
10:03:24 31891 8192000000 10086442128 10568335360 1107182064 11.906 99 36 72 0 1177392544 63032832 7177648
10:04:57 10350 8192000000 10101557760 10523631616 1110572592 11.938 99 56 108 0 1180039168 58786768 10679808
10:06:08 142075 8192000000 10059188768 10518245376 1098297616 11.821 98 26 52 0 1159280656 55812208 5170832
10:09:51 26674 8192000000 10053809456 10509709312 1098914736 11.827 98 34 68 0 1153275376 47261696 7098944
10:11:32 23805 8192000000 10051706960 10510958592 1128453536 12.107 100 26 49 1 1158191232 24809760 4927936
10:13:22 59966 8192000000 10095596160 10560528384 1115341520 11.983 99 11 22 0 1176780496 59108336 2330640
10:15:31 73992 8192000000 10094718240 10570719232 1085648016 11.701 96 15 30 0 1178895632 89385456 3862160
10:17:49 26465 8192000000 10099938432 10541182976 1127074128 12.094 100 42 84 0 1192925856 56804528 9047200
10:19:16 20173 8192000000 10146173504 10584260608 1122473760 12.050 99 39 78 0 1228900208 97975632 8450816
10:20:44 67984 8192000000 10134494416 10625286144 1122856016 12.054 99 32 62 0 1270232880 140054208 7322656
10:22:53 28787 8192000000 10114555888 10551357440 1115019248 11.980 98 31 62 0 1194944368 73536880 6388240
10:24:24 43854 8192000000 10092545760 10564050944 1100282160 11.840 98 39 78 0 1177860832 69769008 7809664
10:26:09 40830 8192000000 10120221328 10551304192 1121492944 12.041 99 50 99 1 1195744096 64304672 9946480
10:27:54 127181 8192000000 10070581440 10556133376 1113110112 11.962 98 27 54 0 1210945952 92228800 5607040
10:31:03 73349 8192000000 10049347136 10537828352 1098065024 11.819 97 7 14 0 1129655824 30531984 1058816
10:33:21 38846 8192000000 10095589152 10546892800 1146694320 12.278 100 30 60 0 1192219360 38970672 6554368
10:35:02 40545 8192000000 10110051664 10562060288 1135064512 12.169 99 29 58 0 1214353504 73213952 6075040
10:37:00 30722 8192000000 9639543344 10034204672 717257072 8.050 63 24 46 2 808917424 86101840 5558512

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-08-2022 10:38:56 to 10-08-2022 10:39:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-08-2022 10:39:13 to 10-08-2022 10:39:15.

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          212988
MON$OLDEST_ACTIVE               212989
MON$OLDEST_SNAPSHOT             212989
MON$NEXT_TRANSACTION            212989
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                       2097408
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             760
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2816 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2816 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2816 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2816 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 ---
:59: 
:60: mscs-test-161.budzdorov.ru    Wed Aug 10 10:37:56 2022
:61:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:62: 
:63: 
:64: mscs-test-161.budzdorov.ru    Wed Aug 10 10:37:58 2022
:65:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:66: 
--- end of diff output ---

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

10.08.22 10:39:16. 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-08-10 10:39:19 No severe PSQL-related problems occured
10.08.22 10:39:20. End of report.