Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.05.2022 13: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 16055648 kB
MemAvailable 31448768 kB
Buffers 0 kB
Cached 15638052 kB
SwapCached 2648 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-05-15 13:37:15
DTS_END                         2022-05-15 13:37:15.6220
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56268
TRN_ID                          207897
ATT_ID                          623
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: 8511.00 23189 1021320
customer order: creation 161.86 529 19423
customer order: refuse 14.03 451 1683
order to supplier: creation 42.24 1487 5069
order to supplier: removal 3.19 183904 383
invoice (draft): creation 43.30 3530 5196
invoice (draft): removal 47.09 1127 5651
invoice accept: apply 39.71 27233 4765
invoice accept: cancel 42.65 1367 5118
customer reserve: creation 3658.62 258 439034
customer reserve: removal 4183.94 110 502073
realization accept: apply 77.22 307 9267
realization accept: cancel 75.18 192 9022
payment from customer: creation 56.43 183 6771
payment from customer: removal 5.48 148 658
payment to supplier: creation 45.60 195 5472
payment to supplier: removal 3.06 123 367
service: total inventory turnovers 3.33 4891 399
service: total monetary turnovers 7.23 442 867
service: aggregate perf. data 0.81 19144 97
service: refresh index statistics 0.04 218155 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-05-2022 13:38:39 to 15-05-2022 13:38:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8107
TEST_TIME 2 6700
TEST_TIME 3 6704
TEST_TIME 4 6271
TEST_TIME 5 6029
TEST_TIME 6 5893
TEST_TIME 7 6525
TEST_TIME 8 6725
TEST_TIME 9 6857
TEST_TIME 10 6774
TEST_TIME 11 6769
TEST_TIME 12 7018
TEST_TIME 13 7052
TEST_TIME 14 7201
TEST_TIME 15 7314
TEST_TIME 16 7243
TEST_TIME 17 7367
TEST_TIME 18 7623
TEST_TIME 19 7791
TEST_TIME 20 7814
TEST_TIME 21 8003
TEST_TIME 22 8115
TEST_TIME 23 8107
TEST_TIME 24 8169
TEST_TIME 25 8302
TEST_TIME 26 8586
TEST_TIME 27 8697
TEST_TIME 28 9093
TEST_TIME 29 9087
TEST_TIME 30 9135
TEST_TIME 31 9341
TEST_TIME 32 9453
TEST_TIME 33 9695
TEST_TIME 34 10270
TEST_TIME 35 10131
TEST_TIME 36 10305
TEST_TIME 37 10569
TEST_TIME 38 10428
TEST_TIME 39 10231
TEST_TIME 40 10079
TEST_TIME 41 9941
TEST_TIME 42 10047
TEST_TIME 43 9872
TEST_TIME 44 10071
TEST_TIME 45 10079
TEST_TIME 46 10074
TEST_TIME 47 10000
TEST_TIME 48 9924
TEST_TIME 49 9776
TEST_TIME 50 9692
TEST_TIME 51 9670
TEST_TIME 52 9547
TEST_TIME 53 9559
TEST_TIME 54 9528
TEST_TIME 55 9483
TEST_TIME 56 9403
TEST_TIME 57 9351
TEST_TIME 58 9248
TEST_TIME 59 9173
TEST_TIME 60 9138
TEST_TIME 61 9072
TEST_TIME 62 9066
TEST_TIME 63 9101
TEST_TIME 64 9084
TEST_TIME 65 9028
TEST_TIME 66 8995
TEST_TIME 67 8923
TEST_TIME 68 8907
TEST_TIME 69 8893
TEST_TIME 70 8879
TEST_TIME 71 8922
TEST_TIME 72 8891
TEST_TIME 73 8956
TEST_TIME 74 8920
TEST_TIME 75 8961
TEST_TIME 76 8931
TEST_TIME 77 8955
TEST_TIME 78 9034
TEST_TIME 79 8992
TEST_TIME 80 9025
TEST_TIME 81 8961
TEST_TIME 82 8985
TEST_TIME 83 8914
TEST_TIME 84 8841
TEST_TIME 85 8799
TEST_TIME 86 8779
TEST_TIME 87 8760
TEST_TIME 88 8742
TEST_TIME 89 8667
TEST_TIME 90 8644
TEST_TIME 91 8692
TEST_TIME 92 8689
TEST_TIME 93 8671
TEST_TIME 94 8662
TEST_TIME 95 8664
TEST_TIME 96 8648
TEST_TIME 97 8707
TEST_TIME 98 8700
TEST_TIME 99 8726
TEST_TIME 100 8769
TEST_TIME 101 8901
TEST_TIME 102 8889
TEST_TIME 103 8865
TEST_TIME 104 8839
TEST_TIME 105 8830
TEST_TIME 106 8764
TEST_TIME 107 8775
TEST_TIME 108 8778
TEST_TIME 109 8755
TEST_TIME 110 8727
TEST_TIME 111 8708
TEST_TIME 112 8674
TEST_TIME 113 8658
TEST_TIME 114 8598
TEST_TIME 115 8565
TEST_TIME 116 8568
TEST_TIME 117 8544
TEST_TIME 118 8529
TEST_TIME 119 8550
TEST_TIME 120 8533

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-05-2022 13:38:42 to 15-05-2022 13:38:46.

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 1425030 1425030 0 0.00 0 26702 14 0 0
doc_list_biud 1425014 1425014 0 0.00 0 82314 46 0 0
sp_add_invoice_to_stock 4900 4765 135 2.75 32 267866 27234 0 135
sp_cancel_adding_invoice 5118 5118 0 0.00 5 108374 1368 0 0
sp_cancel_client_order 1683 1683 0 0.00 7 8020 451 0 0
sp_cancel_customer_reserve 502073 502073 0 0.00 1 48402 111 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 4 6940 148 0 0
sp_cancel_pay_to_supplier 367 367 0 0.00 4 1938 124 0 0
sp_cancel_supplier_invoice 5660 5651 9 0.15 2 82332 1127 0 9
sp_cancel_supplier_order 383 383 0 0.00 14 1574724 183904 0 0
sp_cancel_write_off 9022 9022 0 0.00 2 9133 193 0 0
sp_client_order 19423 19423 0 0.00 6 35530 529 0 0
sp_customer_reserve 439034 439034 0 0.00 1 58319 259 0 0
sp_fill_shopping_cart 29675 29675 0 0.00 1 19919 93 0 0
sp_fill_shopping_cart_clo_res 438998 438998 0 0.00 0 21215 20 0 0
sp_get_clo_for_invoice 4722 4722 0 0.00 0 30176 332 0 0
sp_kill_cost_storno 15165 15165 0 0.00 0 14423 85 0 0
sp_kill_qstorno_ret_qs2qd 1019202 1019202 0 0.00 0 82308 49 0 0
sp_kill_qty_storno 537916 537916 0 0.00 0 82310 112 0 0
sp_lock_selected_doc 19409 19409 0 0.00 0 8364 7 0 0
sp_make_cost_storno 26232 26232 0 0.00 0 25403 83 0 0
sp_multiply_rows_for_pdistr 26229 26229 0 0.00 0 8456 41 0 0
sp_multiply_rows_for_qdistr 19422 19422 0 0.00 0 19336 200 0 0
sp_pay_from_customer 6771 6771 0 0.00 6 10920 184 0 0
sp_pay_to_supplier 5472 5472 0 0.00 7 14361 195 0 0
sp_payment_common 12242 12242 0 0.00 2 7878 49 0 0
sp_qd_handle_on_cancel_clo 1683 1683 0 0.00 0 7956 373 0 0
sp_qd_handle_on_invoice_upd_sts 9839 9839 0 0.00 0 19514 586 0 0
sp_qd_handle_on_reserve_upd_sts 9265 9265 0 0.00 0 19777 76 0 0
sp_reserve_write_off 9267 9267 0 0.00 8 20241 307 0 0
sp_supplier_invoice 5196 5196 0 0.00 11 68356 3531 0 0
sp_supplier_order 5069 5069 0 0.00 9 31288 1487 0 0
srv_aggregate_perf_data 97 97 0 0.00 2490 168245 19144 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 840 142982 33981 0 0
srv_find_qd_qs_mism 974854 974854 0 0.00 0 26700 10 0 0
srv_make_invnt_saldo 399 399 0 0.00 51 73700 4891 0 0
srv_make_money_saldo 867 867 0 0.00 1 11260 443 0 0
srv_recalc_idx_stat 5 5 0 0.00 82023 323647 218156 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4771 4771 0 0.00 1 16742 1109 0 0
t$perf-norm:sp_cancel_adding_invoice 372 372 0 0.00 2 7797 523 0 0
t$perf-norm:sp_cancel_client_order 1683 1683 0 0.00 0 22975 46 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 26073 74 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 5692 31 0 0
t$perf-norm:sp_cancel_pay_to_supplier 367 367 0 0.00 0 7718 34 0 0
t$perf-norm:sp_cancel_supplier_invoice 369 369 0 0.00 0 724 40 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 0 90158 7571 0 0
t$perf-norm:sp_cancel_write_off 941 941 0 0.00 0 1623 21 0 0
t$perf-norm:sp_client_order 19424 19424 0 0.00 0 6244 41 0 0
t$perf-norm:sp_customer_reserve 928 928 0 0.00 0 853 14 0 0
t$perf-norm:sp_pay_from_customer 6771 6771 0 0.00 0 8313 28 0 0
t$perf-norm:sp_pay_to_supplier 5472 5472 0 0.00 0 6357 22 0 0
t$perf-norm:sp_reserve_write_off 9267 9267 0 0.00 0 11036 32 0 0
t$perf-norm:sp_supplier_invoice 5196 5196 0 0.00 0 11121 41 0 0
t$perf-norm:sp_supplier_order 5069 5069 0 0.00 0 12360 41 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 501 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 55 5 0 0
t$perf-norm:srv_make_invnt_saldo 399 399 0 0.00 0 3533 32 0 0
t$perf-norm:srv_make_money_saldo 867 867 0 0.00 0 3867 16 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 15 183 96 0 0
v_add_invoice_to_stock 9702 9432 270 2.78 0 6559 24 0 270
v_all_customers 35638 35638 0 0.00 0 60 4 0 0
v_all_suppliers 28438 28438 0 0.00 0 160 4 0 0
v_all_wares 38836 38836 0 0.00 0 56 4 0 0
v_cancel_adding_invoice 744 744 0 0.00 0 159 12 0 0
v_cancel_client_order 3366 3366 0 0.00 0 2757 11 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 2850 16 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 0 1318 11 0 0
v_cancel_payment_to_supplier 734 734 0 0.00 0 375 11 0 0
v_cancel_supplier_invoice 756 738 18 2.38 0 527 22 0 18
v_cancel_supplier_order 766 766 0 0.00 0 6902 23 0 0
v_cancel_write_off 1882 1882 0 0.00 0 8222 32 0 0
v_max_id_clo_ord 5068 5068 0 0.00 0 870 5 0 0
v_max_id_clo_res 928 928 0 0.00 0 1960 13 0 0
v_max_id_ord_sup 5187 5187 0 0.00 0 5866 10 0 0
v_max_non_paid_invoice 1506 1506 0 0.00 0 497 8 0 0
v_max_non_paid_realizn 3585 3585 0 0.00 0 791 8 0 0
v_min_id_clo_ord 5068 5068 0 0.00 0 7389 9 0 0
v_min_id_clo_res 928 928 0 0.00 0 8824 22 0 0
v_min_id_ord_sup 5187 5187 0 0.00 0 8556 17 0 0
v_min_non_paid_invoice 5471 5471 0 0.00 0 6460 17 0 0
v_min_non_paid_realizn 6771 6771 0 0.00 0 7872 18 0 0
v_our_firm 9568 9568 0 0.00 0 57 5 0 0
v_reserve_write_off 18530 18530 0 0.00 0 6976 11 0 0
x_lock_depdocs_on_canc_invoice 10400 10400 0 0.00 0 25152 147 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 7759 407 0 0
x_make_qstorno_1000_1200 5069 5069 0 0.00 2 31210 1307 0 0
x_make_qstorno_1200_2000 5196 5196 0 0.00 6 68342 3268 0 0
x_make_qstorno_2100_3300 439033 439033 0 0.00 0 52347 226 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-05-2022 13:38:47 to 15-05-2022 13:38:51.

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:19 6 8192000000 8800248848 8812093440 5893808 0.071 2 2 3 0 6034624 79456 61360
11:08:19 25651 8192000000 9702649456 10000957440 781856704 8.712 100 85 170 0 810651984 7745840 21049440
11:09:51 19497 8192000000 9751086464 10057388032 824689712 9.146 100 94 188 0 860595872 13196720 22709440
11:11:14 23050 8192000000 9797137888 10117181440 858840592 9.489 100 84 166 1 904240032 26188496 19210944
11:12:42 7325 8192000000 9861094832 10192801792 911788176 10.015 100 84 168 0 972751408 42001808 18961424
11:13:56 5070 8192000000 9894728272 10245074944 952549328 10.416 100 78 154 0 1023921776 55422480 15949968
11:15:03 2185 8192000000 9981988208 10333634560 994657184 10.827 100 92 176 2 1096088208 82274704 19156320
11:16:08 1572 8192000000 10000673424 10363637760 1021669888 11.088 100 94 183 1 1120316944 80329056 18318000
11:17:12 1551 8192000000 10022877472 10392522752 1042479408 11.288 100 92 170 1 1142694320 83076240 17138672
11:18:15 957 8192000000 10009388784 10381606912 1049778016 11.359 100 97 179 1 1128428560 59582336 19068208
11:19:19 1370 8192000000 10040196832 10418769920 1059055376 11.447 100 97 188 3 1163631568 86110496 18465696
11:20:21 1207 8192000000 10044008400 10426146816 1072894896 11.580 100 95 184 2 1163538432 73054064 17589472
11:21:24 1142 8192000000 10055388448 10439049216 1079115440 11.639 100 94 186 0 1180569248 82907344 18546464
11:22:26 1447 8192000000 10038369648 10425233408 1087360272 11.718 100 87 168 0 1172662176 67397712 17904192
11:23:29 1094 8192000000 10023968176 10408841216 1087198944 11.716 100 91 177 1 1152566944 46271520 19096480
11:24:31 2957 8192000000 10028949872 10413207552 1089679184 11.740 100 79 148 3 1148176192 44798208 13698800
11:25:35 1407 8192000000 10052317008 10442465280 1091059840 11.753 100 89 170 0 1175576288 66624944 17891504
11:26:38 1813 8192000000 10066861744 10458984448 1094112736 11.782 100 88 162 1 1189770064 78594512 17062816
11:27:41 1357 8192000000 10093196272 10486493184 1098776064 11.826 100 99 198 2 1217347104 99478192 19092848
11:28:43 2040 8192000000 10064237536 10459279360 1099081232 11.829 100 78 152 0 1182913648 68542864 15289552
11:29:46 1029 8192000000 10041307280 10430877696 1083643632 11.682 99 87 165 1 1159792784 58485168 17663984
11:30:47 1095 8192000000 10024945216 10408747008 1071413872 11.566 99 85 166 2 1140396928 52094560 16888496
11:31:49 969 8192000000 10035719424 10418843648 1078253920 11.631 100 92 167 2 1157746528 62239376 17253232
11:32:51 1869 8192000000 9989104912 10361585664 1036351040 11.230 98 82 138 3 1104547184 55496624 12699520
11:33:54 1259 8192000000 10002788464 10383220736 1042473088 11.288 100 80 149 3 1127081264 69517824 15090352
11:34:57 1227 8192000000 9982156224 10346831872 1012334400 10.998 100 97 174 1 1098883584 67319424 19229760
11:35:59 1962 8192000000 9978557552 10353410048 1019014352 11.062 100 71 143 1 1099081504 65243776 14823376
11:37:02 5725 8192000000 9969962720 10345140224 1042113424 11.285 100 71 132 3 1092876096 37374704 13387968
11:38:15 7819 8192000000 10004133904 10381402112 1049271056 11.354 100 61 111 1 1135868176 76072144 10524976
11:39:29 1203 8192000000 10045159616 10422198272 1076397088 11.613 100 87 166 1 1162449936 68498608 17554240
11:40:36 945 8192000000 10060188624 10446082048 1092913744 11.770 100 79 141 3 1182043392 73290416 15839232
11:41:43 17444 8192000000 10125579360 10517139456 1114088144 11.971 100 71 139 0 1242602896 112940992 15573760
11:43:09 3263 8192000000 10146955920 10538729472 1119100096 12.018 100 84 165 1 1265083920 128251792 17732032
11:44:19 1362 8192000000 10186005712 10582908928 1133588240 12.155 100 85 160 1 1301895440 151549248 16757952
11:45:22 1958 8192000000 10194004224 10590674944 1143612208 12.249 100 92 179 1 1306793808 143558160 19623440
11:46:30 2825 8192000000 10232730256 10650234880 1149763216 12.307 100 83 156 0 1352869008 185859648 17246144
11:47:37 5173 8192000000 10226956496 10625540096 1155122480 12.358 100 95 178 1 1338099984 165223760 17753744
11:48:49 969 8192000000 10251420208 10659332096 1148789488 12.298 99 94 185 0 1357312944 188079232 20444224
11:49:53 834 8192000000 10289837920 10694004736 1173255248 12.527 100 95 182 3 1407802224 213625792 20921184
11:50:57 984 8192000000 10307411136 10721394688 1179035040 12.581 100 97 188 0 1420652288 218921408 22695840
11:52:01 1019 8192000000 10319925728 10733551616 1180201712 12.592 100 97 188 0 1434504704 231480560 22822432
11:53:05 1721 8192000000 10372383344 10778611712 1181061408 12.600 100 86 171 0 1476756992 276796768 18898816
11:54:13 1879 8192000000 10383451056 10799534080 1193386704 12.715 100 90 167 2 1486767472 273703344 19677424
11:55:19 1052 8192000000 10397284560 10825023488 1193336368 12.714 100 95 185 0 1498628880 283600784 21691728
11:56:23 1495 8192000000 10383429792 10806112256 1178414464 12.575 100 98 189 1 1489935904 289751232 21770208
11:57:27 1429 8192000000 10429283168 10836361216 1196246224 12.741 100 92 183 2 1531370848 313416064 21708560
11:58:33 836 8192000000 10447267872 10879885312 1204076784 12.814 100 96 190 1 1549258816 323779168 21402864
11:59:37 2618 8192000000 10460564496 10889760768 1197689728 12.755 100 91 172 0 1567244224 349193136 20361360
12:00:43 889 8192000000 10471035904 10901196800 1191668720 12.699 100 89 171 1 1576284736 363547792 21068224
12:01:48 1143 8192000000 10520929376 10933641216 1178012432 12.572 99 93 183 0 1625863808 425345408 22505968
12:02:52 4285 8192000000 10484995360 10941243392 1158302656 12.387 99 64 128 0 1585098096 410700432 16095008
12:04:09 4067 8192000000 10455164144 10928865280 1162335472 12.425 100 64 126 0 1560671872 382028960 16307440
12:05:17 1571 8192000000 10466899904 10910392320 1170646240 12.503 100 70 133 1 1571281744 384015952 16619552
12:06:22 9751 8192000000 10452086976 10915590144 1176925984 12.562 100 44 86 2 1562286416 374150512 11209920
12:07:42 14245 8192000000 10420962416 10891579392 1149226624 12.302 99 41 78 0 1532792320 373259344 10306352
12:09:08 21444 8192000000 10377350048 10859524096 1132000032 12.140 99 28 56 0 1477327552 338047200 7280320
12:10:48 26820 8192000000 10262972768 10759086080 1145302560 12.265 100 30 60 1 1384969184 232420304 7246320
12:12:17 28565 8192000000 10247823056 10687049728 1128129456 12.104 99 24 49 1 1325347552 191201456 6016640
12:13:48 17846 8192000000 10199316160 10632015872 1138861664 12.205 100 40 80 1 1294440528 145827424 9751440
12:15:06 2399 8192000000 10230918240 10647617536 1162739872 12.429 100 77 151 3 1325545728 144354064 18451792
12:16:10 53821 8192000000 10238736640 10669453312 1167368608 12.472 100 57 114 0 1349411120 169299008 12743504
12:18:08 18394 8192000000 10251579008 10713579520 1152948864 12.337 99 23 46 3 1355192992 196425584 5818544
12:20:04 29634 8192000000 10187456480 10622578688 1112416176 11.955 97 36 72 1 1275641984 153695216 9530592
12:21:54 36428 8192000000 10100493840 10585923584 1114915856 11.979 99 15 30 0 1193359632 74617344 3826432
12:23:31 75716 8192000000 10083899936 10534285312 1135605728 12.174 100 18 34 3 1186644656 47258416 3780512
12:25:49 67354 8192000000 10096044784 10529112064 1127063040 12.094 99 24 47 0 1178959984 46633824 5263120
12:27:59 77442 8192000000 10104326672 10589454336 1122160480 12.047 99 11 22 1 1187164368 62174176 2829712
12:30:16 89473 8192000000 10060709776 10517303296 1121992704 12.046 99 35 67 0 1174559680 45658768 6908208
12:32:49 78226 8192000000 10089678112 10551083008 1130441712 12.126 99 35 69 2 1176710416 39460608 6808096
12:35:11 28571 8192000000 10105238800 10551255040 1140978400 12.225 100 22 44 1 1197826928 52550000 4298528
12:36:46 44874 8192000000 10130176016 10589003776 1139616320 12.212 99 27 54 1 1224266752 78892144 5758288
12:38:39 17739 8192000000 10148684928 10591051776 1154640272 12.353 100 43 82 0 1254912624 91632752 8639600
12:40:01 40042 8192000000 10113815168 10629595136 1144040896 12.254 100 10 20 1 1253290352 107058544 2190912
12:41:41 50028 8192000000 10085986640 10587774976 1147753264 12.288 100 11 21 1 1204779232 54483360 2542608
12:43:50 76353 8192000000 10086402256 10566815744 1139797824 12.214 99 11 22 0 1168870336 26949232 2123280
12:46:26 32827 8192000000 10107283184 10552168448 1122563808 12.051 98 37 74 1 1198797376 68399888 7833680
12:47:59 78816 8192000000 10071988944 10535211008 1092116400 11.763 95 15 29 1 1154250640 58426464 3707776
12:50:21 82632 8192000000 10111912768 10553061376 1111125744 11.943 98 23 44 0 1202711456 86847776 4737936
12:52:47 36978 8192000000 10077838992 10499911680 1116585040 11.995 98 38 72 1 1148308112 24088352 7634720
12:54:25 22467 8192000000 10138684288 10570235904 1123089504 12.056 99 43 86 2 1194656560 62827776 8739280
12:55:55 105540 8192000000 10108161408 10558631936 1065883264 11.513 94 17 34 1 1125893552 56639264 3371024
12:58:40 133334 8192000000 10064379824 10492997632 1114719024 11.977 98 35 64 2 1172296240 49900720 7676496
13:01:55 1531 8192000000 10074733376 10501468160 1130618128 12.127 100 82 157 0 1180199408 31847312 17733968
13:02:59 38248 8192000000 10132573216 10579111936 1127411968 12.097 99 38 76 1 1228567760 93451136 7704656
13:05:30 102938 8192000000 10138270240 10620268544 1127605536 12.099 99 14 28 0 1228244432 97486992 3151904
13:09:47 55723 8192000000 10114383632 10555142144 1146222048 12.274 100 19 38 0 1200192512 49873424 4097040
13:11:58 45467 8192000000 10138246528 10592555008 1124978080 12.074 99 25 50 0 1227408896 97398800 5032016
13:13:48 34722 8192000000 10144918848 10597801984 1119100272 12.018 98 46 91 0 1217464512 88629440 9734800
13:15:32 67378 8192000000 10133734784 10611994624 1110938608 11.941 98 18 36 0 1183721440 69056288 3726544
13:17:39 30157 8192000000 10159471568 10637193216 1138150704 12.198 100 29 58 0 1271520672 127187648 6182320
13:19:19 80109 8192000000 10082606976 10513850368 1089990400 11.743 96 30 57 1 1135521824 39264096 6267328
13:21:43 20257 8192000000 10155938288 10584272896 1129463248 12.116 100 49 98 1 1221883872 82293840 10126784
13:23:11 142982 8192000000 10104178976 10562871296 1126051856 12.084 100 35 65 2 1223076240 89761504 7262880
13:26:35 49848 8192000000 10059029920 10513387520 1109071648 11.924 98 23 46 2 1155974320 42180432 4722240
13:28:27 42334 8192000000 10065200016 10532827136 1129735680 12.119 100 12 24 0 1164449024 32396976 2316368
13:30:14 63187 8192000000 10096775936 10562355200 1131023904 12.131 99 13 26 2 1206937728 72501696 3412128
13:32:17 86729 8192000000 10108370112 10585464832 1108927824 11.922 97 21 42 0 1194369680 80355328 5086528
13:34:44 34730 8192000000 10118326672 10576355328 1109700688 11.930 98 17 34 1 1205040736 91267904 4072144
13:36:41 49526 8192000000 9032269456 9312010240 174586496 2.086 16 4 8 0 211878464 36420400 871568

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 15-05-2022 13:38:53 to 15-05-2022 13:39:09.

Exceptions occured during test was in run

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

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

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          211793
MON$OLDEST_ACTIVE               211794
MON$OLDEST_SNAPSHOT             211794
MON$NEXT_TRANSACTION            211794
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                       2092032
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             761
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2764 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2764 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2764 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2764 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 ---
:180: 
:181: mscs-test-161.budzdorov.ru    Sun May 15 13:37:40 2022
:182:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:183: 
:184: 
:185: mscs-test-161.budzdorov.ru    Sun May 15 13:37:42 2022
:186:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:187: 
:188: 
:189: mscs-test-161.budzdorov.ru    Sun May 15 13:37:53 2022
:190:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:191: 
--- end of diff output ---

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

15.05.22 13:39:13. 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-05-15 13:39:15 No severe PSQL-related problems occured
15.05.22 13:39:16. End of report.