Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.10.2022 07:48
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 15301328 kB
MemAvailable 30540532 kB
Buffers 0 kB
Cached 16387048 kB
SwapCached 2076 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-10-02 07:47:32
DTS_END                         2022-10-02 07:47:32.7720
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/34480
TRN_ID                          216342
ATT_ID                          580
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: 8529.11 22201 1023493
customer order: creation 165.68 507 19882
customer order: refuse 14.11 434 1693
order to supplier: creation 44.77 1459 5372
order to supplier: removal 3.25 163967 390
invoice (draft): creation 44.18 3573 5301
invoice (draft): removal 46.71 1008 5605
invoice accept: apply 40.38 27285 4846
invoice accept: cancel 42.09 1302 5051
customer reserve: creation 3721.99 260 446639
customer reserve: removal 4133.55 101 496026
realization accept: apply 80.68 303 9681
realization accept: cancel 67.80 181 8136
payment from customer: creation 56.17 183 6740
payment from customer: removal 5.37 133 644
payment to supplier: creation 47.08 200 5650
payment to supplier: removal 3.18 133 382
service: total inventory turnovers 3.87 4824 464
service: total monetary turnovers 7.41 374 889
service: aggregate perf. data 0.82 16263 98
service: refresh index statistics 0.03 221526 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-10-2022 07:48:26 to 02-10-2022 07:48:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7096
TEST_TIME 2 5623
TEST_TIME 3 5769
TEST_TIME 4 5714
TEST_TIME 5 5588
TEST_TIME 6 5860
TEST_TIME 7 5711
TEST_TIME 8 5308
TEST_TIME 9 5308
TEST_TIME 10 5550
TEST_TIME 11 5548
TEST_TIME 12 5758
TEST_TIME 13 5905
TEST_TIME 14 6013
TEST_TIME 15 6354
TEST_TIME 16 6533
TEST_TIME 17 6887
TEST_TIME 18 7198
TEST_TIME 19 7585
TEST_TIME 20 7943
TEST_TIME 21 8246
TEST_TIME 22 8324
TEST_TIME 23 8133
TEST_TIME 24 8233
TEST_TIME 25 8504
TEST_TIME 26 8439
TEST_TIME 27 8492
TEST_TIME 28 8634
TEST_TIME 29 8805
TEST_TIME 30 8822
TEST_TIME 31 9075
TEST_TIME 32 9392
TEST_TIME 33 9375
TEST_TIME 34 9638
TEST_TIME 35 9934
TEST_TIME 36 10261
TEST_TIME 37 10289
TEST_TIME 38 10273
TEST_TIME 39 10268
TEST_TIME 40 10095
TEST_TIME 41 9982
TEST_TIME 42 9840
TEST_TIME 43 9772
TEST_TIME 44 9731
TEST_TIME 45 9600
TEST_TIME 46 9523
TEST_TIME 47 9491
TEST_TIME 48 9345
TEST_TIME 49 9230
TEST_TIME 50 9198
TEST_TIME 51 9085
TEST_TIME 52 9143
TEST_TIME 53 9063
TEST_TIME 54 9109
TEST_TIME 55 9096
TEST_TIME 56 8984
TEST_TIME 57 8875
TEST_TIME 58 8770
TEST_TIME 59 8932
TEST_TIME 60 9023
TEST_TIME 61 8963
TEST_TIME 62 8945
TEST_TIME 63 8941
TEST_TIME 64 8875
TEST_TIME 65 8905
TEST_TIME 66 8872
TEST_TIME 67 8865
TEST_TIME 68 8909
TEST_TIME 69 8885
TEST_TIME 70 8850
TEST_TIME 71 8900
TEST_TIME 72 8869
TEST_TIME 73 8968
TEST_TIME 74 9008
TEST_TIME 75 8970
TEST_TIME 76 8864
TEST_TIME 77 8846
TEST_TIME 78 8842
TEST_TIME 79 8800
TEST_TIME 80 8791
TEST_TIME 81 8763
TEST_TIME 82 8730
TEST_TIME 83 8676
TEST_TIME 84 8612
TEST_TIME 85 8594
TEST_TIME 86 8516
TEST_TIME 87 8517
TEST_TIME 88 8678
TEST_TIME 89 8688
TEST_TIME 90 8629
TEST_TIME 91 8650
TEST_TIME 92 8707
TEST_TIME 93 8701
TEST_TIME 94 8702
TEST_TIME 95 8766
TEST_TIME 96 8750
TEST_TIME 97 8771
TEST_TIME 98 8759
TEST_TIME 99 8791
TEST_TIME 100 8754
TEST_TIME 101 8797
TEST_TIME 102 8786
TEST_TIME 103 8728
TEST_TIME 104 8693
TEST_TIME 105 8669
TEST_TIME 106 8642
TEST_TIME 107 8641
TEST_TIME 108 8602
TEST_TIME 109 8587
TEST_TIME 110 8525
TEST_TIME 111 8489
TEST_TIME 112 8489
TEST_TIME 113 8497
TEST_TIME 114 8526
TEST_TIME 115 8556
TEST_TIME 116 8564
TEST_TIME 117 8624
TEST_TIME 118 8579
TEST_TIME 119 8585
TEST_TIME 120 8562

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-10-2022 07:48:29 to 02-10-2022 07:48:33.

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 1434914 1434914 0 0.00 0 28182 13 0 0
doc_list_biud 1434897 1434897 0 0.00 0 25957 42 0 0
sp_add_invoice_to_stock 4959 4846 113 2.27 34 180049 27286 0 113
sp_cancel_adding_invoice 5051 5051 0 0.00 7 81832 1302 0 0
sp_cancel_client_order 1693 1693 0 0.00 6 6679 434 0 0
sp_cancel_customer_reserve 496026 496026 0 0.00 1 23580 102 0 0
sp_cancel_pay_from_customer 644 644 0 0.00 4 6133 133 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 4 4624 134 0 0
sp_cancel_supplier_invoice 5615 5605 10 0.17 3 13406 1009 0 10
sp_cancel_supplier_order 390 390 0 0.00 9 1361027 163967 0 0
sp_cancel_write_off 8136 8136 0 0.00 2 20548 182 0 0
sp_client_order 19882 19882 0 0.00 5 31382 508 0 0
sp_customer_reserve 446639 446639 0 0.00 0 159540 260 0 0
sp_fill_shopping_cart 30546 30546 0 0.00 1 31372 95 0 0
sp_fill_shopping_cart_clo_res 446602 446602 0 0.00 0 14703 19 0 0
sp_get_clo_for_invoice 4801 4801 0 0.00 0 15761 319 0 0
sp_kill_cost_storno 14213 14213 0 0.00 0 10432 76 0 0
sp_kill_qstorno_ret_qs2qd 1006183 1006183 0 0.00 0 23423 45 0 0
sp_kill_qty_storno 531380 531380 0 0.00 0 25956 104 0 0
sp_lock_selected_doc 20035 20035 0 0.00 0 7217 7 0 0
sp_make_cost_storno 26871 26871 0 0.00 0 28067 85 0 0
sp_multiply_rows_for_pdistr 26870 26870 0 0.00 0 7659 39 0 0
sp_multiply_rows_for_qdistr 19881 19881 0 0.00 0 31361 173 0 0
sp_pay_from_customer 6740 6740 0 0.00 5 13026 183 0 0
sp_pay_to_supplier 5650 5650 0 0.00 6 9813 200 0 0
sp_payment_common 12389 12389 0 0.00 2 7435 47 0 0
sp_qd_handle_on_cancel_clo 1693 1693 0 0.00 1 6650 349 0 0
sp_qd_handle_on_invoice_upd_sts 9850 9850 0 0.00 0 25955 596 0 0
sp_qd_handle_on_reserve_upd_sts 9680 9680 0 0.00 0 16423 75 0 0
sp_reserve_write_off 9681 9681 0 0.00 8 16519 304 0 0
sp_supplier_invoice 5301 5301 0 0.00 8 117871 3573 0 0
sp_supplier_order 5372 5372 0 0.00 7 48383 1460 0 0
srv_aggregate_perf_data 98 98 0 0.00 2358 215560 16264 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 839 193639 37933 0 0
srv_find_qd_qs_mism 976455 976455 0 0.00 0 21719 10 0 0
srv_make_invnt_saldo 464 464 0 0.00 72 222168 4824 0 0
srv_make_money_saldo 889 889 0 0.00 1 12453 375 0 0
srv_recalc_idx_stat 4 4 0 0.00 35367 368514 221527 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4852 4852 0 0.00 0 10131 976 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 1 7076 460 0 0
t$perf-norm:sp_cancel_client_order 1693 1693 0 0.00 0 1070 27 0 0
t$perf-norm:sp_cancel_customer_reserve 669 669 0 0.00 0 962 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 644 644 0 0.00 0 1565 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 7718 38 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 904 36 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 87088 7490 0 0
t$perf-norm:sp_cancel_write_off 955 955 0 0.00 0 2118 21 0 0
t$perf-norm:sp_client_order 19882 19882 0 0.00 0 17098 39 0 0
t$perf-norm:sp_customer_reserve 966 966 0 0.00 0 2321 19 0 0
t$perf-norm:sp_pay_from_customer 6740 6740 0 0.00 0 14384 21 0 0
t$perf-norm:sp_pay_to_supplier 5651 5651 0 0.00 0 17106 27 0 0
t$perf-norm:sp_reserve_write_off 9681 9681 0 0.00 0 4200 22 0 0
t$perf-norm:sp_supplier_invoice 5301 5301 0 0.00 0 11388 31 0 0
t$perf-norm:sp_supplier_order 5372 5372 0 0.00 0 11778 34 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 229 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 51 4 0 0
t$perf-norm:srv_make_invnt_saldo 464 464 0 0.00 0 1261 16 0 0
t$perf-norm:srv_make_money_saldo 889 889 0 0.00 0 414 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 521 138 0 0
v_add_invoice_to_stock 9814 9588 226 2.30 0 4144 25 0 226
v_all_customers 36262 36262 0 0.00 0 126 4 0 0
v_all_suppliers 29542 29542 0 0.00 0 143 4 0 0
v_all_wares 39750 39750 0 0.00 0 118 4 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 3494 19 0 0
v_cancel_client_order 3386 3386 0 0.00 0 5880 19 0 0
v_cancel_customer_prepayment 1288 1288 0 0.00 0 6032 20 0 0
v_cancel_customer_reserve 1338 1338 0 0.00 0 3046 15 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 431 12 0 0
v_cancel_supplier_invoice 830 810 20 2.40 0 3111 25 0 20
v_cancel_supplier_order 780 780 0 0.00 0 5628 25 0 0
v_cancel_write_off 1910 1910 0 0.00 0 6513 33 0 0
v_max_id_clo_ord 5371 5371 0 0.00 0 1906 5 0 0
v_max_id_clo_res 966 966 0 0.00 0 1401 12 0 0
v_max_id_ord_sup 5299 5299 0 0.00 0 7638 9 0 0
v_max_non_paid_invoice 1547 1547 0 0.00 0 3558 10 0 0
v_max_non_paid_realizn 3534 3534 0 0.00 0 530 7 0 0
v_min_id_clo_ord 5371 5371 0 0.00 0 4583 8 0 0
v_min_id_clo_res 966 966 0 0.00 0 4322 21 0 0
v_min_id_ord_sup 5299 5299 0 0.00 0 6343 12 0 0
v_min_non_paid_invoice 5648 5648 0 0.00 0 7427 16 0 0
v_min_non_paid_realizn 6738 6738 0 0.00 0 6436 19 0 0
v_our_firm 9894 9894 0 0.00 0 143 5 0 0
v_reserve_write_off 19360 19360 0 0.00 0 6401 14 0 0
x_lock_depdocs_on_canc_invoice 10251 10251 0 0.00 0 18479 144 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 3395 305 0 0
x_make_qstorno_1000_1200 5372 5372 0 0.00 3 48370 1283 0 0
x_make_qstorno_1200_2000 5301 5301 0 0.00 3 111350 3288 0 0
x_make_qstorno_2100_3300 446638 446638 0 0.00 0 159538 229 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-10-2022 07:48:34 to 02-10-2022 07:48:38.

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
05:17:36 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:18:37 12870 8192000000 9693385328 9986564096 774336608 8.636 100 89 178 0 803562064 6876960 22348496
05:19:53 14885 8192000000 9748964288 10053881856 824038288 9.139 100 91 182 0 856544256 10409200 22096768
05:21:11 17955 8192000000 9789663280 10106638336 858918544 9.489 100 89 176 0 896569024 16734192 20916288
05:22:34 8543 8192000000 9841129152 10171392000 904009264 9.938 100 78 154 0 948764784 27624864 17130656
05:23:48 5886 8192000000 9902035312 10243584000 948306768 10.374 100 90 180 0 1022041312 54570704 19163840
05:24:57 1432 8192000000 9969135296 10319269888 991414592 10.795 100 96 189 1 1088001568 77219152 19367824
05:26:01 1165 8192000000 9989379728 10348261376 1013264160 11.007 100 91 173 2 1104979488 74295824 17419504
05:27:05 1406 8192000000 10012389936 10380861440 1040082656 11.265 100 91 177 1 1127031856 68689888 18259312
05:28:09 1181 8192000000 10043406416 10414260224 1058952400 11.446 100 92 178 2 1158702208 81874496 17875312
05:29:12 1252 8192000000 10041766336 10418753536 1072884784 11.580 100 93 174 1 1164370832 72704960 18781088
05:30:14 1588 8192000000 10044903488 10425315328 1074913968 11.599 100 84 161 0 1166676112 75358656 16403488
05:31:19 1322 8192000000 10055343504 10434768896 1078810480 11.636 100 99 193 0 1173551744 74523968 20217296
05:32:21 877 8192000000 10047657152 10431188992 1082843312 11.675 100 96 177 2 1169702752 68563792 18295648
05:33:24 1116 8192000000 10054396736 10435997696 1086135776 11.706 100 98 192 1 1179159568 73513008 19510784
05:34:26 1018 8192000000 10042642832 10427871232 1092501760 11.766 100 95 171 1 1162642848 52058144 18082944
05:35:29 931 8192000000 10040162288 10428289024 1095654256 11.796 100 87 165 1 1164262592 51733920 16874416
05:36:30 1059 8192000000 10067356688 10457071616 1090539728 11.748 100 91 167 4 1181602960 72136512 18926720
05:37:34 1172 8192000000 10045233488 10431193088 1091414192 11.756 100 96 189 0 1168041728 57546032 19081504
05:38:36 1196 8192000000 10081954816 10471063552 1095907872 11.799 100 94 184 1 1210138384 96488464 17742048
05:39:38 1026 8192000000 10066460544 10453553152 1082975312 11.676 100 98 190 0 1189187584 86192224 20020048
05:40:40 1192 8192000000 10035747840 10422136832 1079232976 11.640 100 85 160 1 1162348384 66406624 16708784
05:41:43 1116 8192000000 10015708176 10396962816 1067015280 11.524 100 89 165 2 1138849184 55091504 16742400
05:42:45 4739 8192000000 9985172032 10359300096 1046009904 11.322 99 80 144 5 1100927856 40985072 13932880
05:43:51 1116 8192000000 10002365824 10380857344 1052220960 11.382 100 82 154 1 1122756480 54259904 16275616
05:44:54 1088 8192000000 9994167920 10367901696 1041279136 11.277 100 86 166 0 1107337616 48852144 17206336
05:45:57 1303 8192000000 10004958144 10381582336 1042234720 11.286 100 97 179 1 1124649840 63985568 18429552
05:46:59 1511 8192000000 9970223216 10344996864 1031420528 11.182 100 93 170 5 1095740224 45990096 18329600
05:48:05 2755 8192000000 10004949360 10379051008 1039566976 11.261 99 88 165 1 1131099904 76257728 15275200
05:49:11 15088 8192000000 10012581808 10399821824 1064709536 11.502 100 64 124 1 1132280752 55327056 12244160
05:50:29 1773 8192000000 10031091024 10410905600 1070866656 11.560 99 84 155 3 1149487056 63080880 15539520
05:51:35 14481 8192000000 10081200720 10472173568 1096388208 11.803 100 70 131 1 1202220560 92449456 13382896
05:52:58 1259 8192000000 10121777216 10508746752 1113811088 11.968 100 91 170 0 1232105888 100774016 17520784
05:54:05 13840 8192000000 10136248032 10532233216 1121368496 12.040 100 69 129 1 1253725584 119755712 12601376
05:55:24 4562 8192000000 10141092288 10540572672 1116196000 11.991 99 61 115 0 1257561712 129208304 12157408
05:56:42 855 8192000000 10249985712 10642223104 1136303584 12.181 100 91 165 1 1360152864 206364448 17484832
05:57:47 4558 8192000000 10262891088 10667442176 1146468352 12.276 100 78 152 1 1371157760 207433040 17256368
05:58:57 2422 8192000000 10291563968 10697129984 1157696960 12.382 100 74 147 0 1406924144 232281376 16945808
06:00:04 921 8192000000 10329072704 10723561472 1163257824 12.434 100 95 187 3 1436648560 252413344 20977392
06:01:10 1373 8192000000 10358860816 10761060352 1172615024 12.521 100 95 183 1 1465201152 272151712 20434416
06:02:16 1257 8192000000 10361433328 10781155328 1178600464 12.577 100 85 167 0 1474983632 276583216 19799952
06:03:22 834 8192000000 10361521936 10793140224 1177906304 12.571 100 97 190 2 1472433520 271500320 23026896
06:04:27 950 8192000000 10386068688 10802720768 1169167376 12.489 100 96 185 0 1491670912 299988240 22515296
06:05:30 908 8192000000 10365906240 10790006784 1165846016 12.458 100 97 191 1 1477023936 290348624 20829296
06:06:34 867 8192000000 10328310992 10786635776 1165150304 12.451 100 94 173 0 1436497344 250459808 20887232
06:07:38 1521 8192000000 10356599280 10806226944 1176608112 12.559 100 98 196 0 1456782832 257852928 22321792
06:08:43 2887 8192000000 10332495760 10768629760 1182734368 12.616 100 74 144 1 1438749680 239440432 16574880
06:09:49 26939 8192000000 10304779504 10745425920 1159733184 12.401 99 43 85 0 1421003056 250800832 10469040
06:11:23 4092 8192000000 10359351040 10782838784 1170409696 12.501 100 60 120 1 1472844464 287613456 14821312
06:12:39 2955 8192000000 10382740800 10814930944 1169065888 12.488 100 64 125 0 1490345792 305819760 15460144
06:13:51 4599 8192000000 10379246928 10821439488 1167745216 12.476 100 74 149 0 1487720464 303019312 16955936
06:14:59 879 8192000000 10427631568 10859741184 1173090560 12.526 100 79 158 1 1528128384 336722544 18315280
06:16:08 6556 8192000000 10409005968 10869993472 1159886240 12.402 99 49 97 1 1501969520 329774176 12309104
06:17:16 9014 8192000000 10407831600 10839179264 1142970480 12.243 98 48 92 0 1498582656 344392448 11219728
06:18:30 12080 8192000000 10362171792 10827599872 1151756144 12.326 99 38 75 0 1487594688 326490768 9347776
06:19:50 20986 8192000000 10338974960 10799640576 1151311296 12.322 100 29 56 0 1443148784 284891776 6945712
06:21:14 56689 8192000000 10317244640 10823139328 1160963216 12.412 100 25 44 1 1457604464 291323920 5317328
06:23:12 35162 8192000000 10203877776 10679472128 1124687088 12.071 99 43 73 3 1284834912 151166368 8981456
06:24:48 11585 8192000000 10079696704 10548801536 1107627440 11.910 99 26 49 1 1204882016 91810416 5444160
06:26:02 77178 8192000000 10071656880 10519982080 1112063984 11.952 98 37 71 0 1184716448 64266912 8385552
06:28:23 98433 8192000000 10083190800 10553278464 1101113648 11.848 98 45 85 1 1168330656 56436416 10780592
06:31:22 96321 8192000000 10096281776 10529193984 1118101840 12.009 99 31 61 1 1190340720 65816848 6422032
06:34:01 109552 8192000000 10076818912 10538594304 1076798704 11.617 95 13 26 0 1134683808 55038336 2846768
06:36:54 39505 8192000000 10080914096 10537828352 1089437120 11.737 98 17 34 0 1163064112 69310256 4316736
06:38:38 16739 8192000000 10147727232 10570051584 1121249152 12.039 100 59 119 2 1245035984 111052352 12734480
06:39:59 193639 8192000000 10095826080 10616213504 1127901456 12.102 100 14 28 0 1206159232 75137584 3120192
06:44:32 52914 8192000000 10172215904 10607325184 1133576976 12.155 99 39 77 0 1253165552 111655968 7932608
06:46:32 90059 8192000000 10079550976 10620764160 1121613552 12.042 99 13 26 0 1225441936 101106240 2722144
06:49:08 72118 8192000000 10073583952 10532429824 1117096752 12.000 98 21 42 1 1186101072 65080368 3923952
06:51:21 21784 8192000000 10108061248 10539139072 1139381440 12.210 100 54 104 1 1193861920 44498928 9981552
06:52:56 59719 8192000000 10073740880 10514690048 1089632928 11.739 96 31 62 1 1132457920 35743840 7081152
06:55:01 67053 8192000000 10094583568 10550480896 1127312448 12.096 99 31 59 1 1198629280 64541760 6775072
06:57:08 37494 8192000000 10115575472 10558623744 1122369536 12.049 98 33 61 1 1192422752 63882192 6171024
06:58:46 78613 8192000000 10161903184 10606055424 1137859504 12.195 99 22 43 0 1225210960 82703168 4648288
07:01:06 97820 8192000000 10084749648 10525630464 1107444496 11.908 97 10 20 0 1151364800 41974560 1945744
07:03:51 75399 8192000000 10094061488 10527383552 1150045120 12.310 100 29 54 0 1199407776 44102912 5259744
07:06:11 54393 8192000000 10075272144 10539675648 1120317808 12.030 98 22 44 0 1161754592 36772224 4664560
07:08:06 42256 8192000000 10059155808 10522685440 1108267648 11.916 98 23 46 2 1144406832 31430336 4708848
07:09:48 58122 8192000000 10112896016 10573262848 1136507696 12.183 100 21 42 0 1204976160 63473392 4995072
07:11:48 61395 8192000000 10159220048 10632712192 1114593648 11.976 97 16 33 0 1224686992 106119744 3973600
07:13:54 66648 8192000000 10173180640 10637230080 1143028208 12.244 100 40 73 2 1281175616 129474720 8672688
07:16:38 71209 8192000000 10109883488 10535190528 1114604032 11.976 97 29 58 0 1165807792 45741264 5462496
07:19:17 54090 8192000000 10107221120 10581422080 1122498864 12.051 98 24 48 1 1178741520 51707056 4535600
07:21:13 35941 8192000000 10124387856 10579599360 1097848960 11.817 96 27 52 0 1186423536 83104816 5469760
07:24:07 26834 8192000000 10089986208 10540158976 1113854304 11.969 98 50 98 0 1171920256 48188288 9877664
07:25:39 13289 8192000000 10103464176 10544947200 1131404848 12.135 100 48 94 0 1200324288 59190272 9729168
07:28:13 67016 8192000000 10076517952 10575409152 1112410736 11.955 98 30 60 1 1201236384 82412112 6413536
07:30:22 96980 8192000000 10056184032 10513264640 1089636800 11.739 96 28 55 1 1141482592 46459168 5386624
07:33:00 39108 8192000000 10064746704 10504318976 1109986848 11.932 99 38 76 0 1158609440 41244464 7378128
07:34:50 60099 8192000000 10054556896 10495197184 1106914144 11.903 98 31 58 1 1146565200 33412528 6238528
07:36:50 78637 8192000000 10084130080 10531364864 1134501008 12.164 100 20 40 0 1196848384 57997984 4349392
07:39:09 99615 8192000000 10113958224 10571730944 1103911120 11.875 97 14 28 0 1202741200 95814912 3015168
07:41:50 37189 8192000000 10099849632 10540318720 1136279392 12.181 100 26 52 0 1201545088 59503136 5762560
07:43:29 33000 8192000000 10100662768 10549653504 1116396784 11.993 98 31 62 0 1182178608 59766592 6015232
07:45:06 17390 8192000000 10065672320 10498691072 1131496032 12.135 99 46 87 2 1158164288 17318192 9350064
07:46:24 16908 8192000000 10076497968 10503307264 1084445280 11.690 96 50 98 0 1140745696 46258352 10042064

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 02-10-2022 07:48:40 to 02-10-2022 07:48:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 113 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-10-2022 07:48:57 to 02-10-2022 07:48:59.

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          214139
MON$OLDEST_ACTIVE               216486
MON$OLDEST_SNAPSHOT             216486
MON$NEXT_TRANSACTION            216486
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                       2061856
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             772
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2854 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2854 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2854 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2854 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sun Oct  2 07:47:33 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sun Oct  2 07:47:40 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Sun Oct  2 07:47:41 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

02.10.22 07:49:00. 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-10-02 07:49:03 No severe PSQL-related problems occured
02.10.22 07:49:04. End of report.