Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.01.2022 07: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 15823896 kB
MemAvailable 31328868 kB
Buffers 0 kB
Cached 15888544 kB
SwapCached 2332 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.1
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-01-12 07:37:07
DTS_END                         2022-01-12 07:37:07.7250
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/56604
TRN_ID                          219767
ATT_ID                          590
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: 9266.85 26699 1112022
customer order: creation 174.08 465 20890
customer order: refuse 14.71 447 1765
order to supplier: creation 44.87 1441 5384
order to supplier: removal 3.68 183216 441
invoice (draft): creation 45.44 3438 5453
invoice (draft): removal 51.42 1153 6170
invoice accept: apply 42.61 28707 5113
invoice accept: cancel 46.38 1405 5565
customer reserve: creation 3965.09 272 475811
customer reserve: removal 4582.40 115 549888
realization accept: apply 84.47 301 10136
realization accept: cancel 79.30 186 9516
payment from customer: creation 60.66 185 7279
payment from customer: removal 5.95 131 714
payment to supplier: creation 48.94 205 5873
payment to supplier: removal 3.73 149 447
service: total inventory turnovers 3.98 4639 477
service: total monetary turnovers 8.22 445 986
service: aggregate perf. data 0.91 18682 109
service: refresh index statistics 0.04 288391 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-01-2022 07:39:12 to 12-01-2022 07:39:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7755
TEST_TIME 2 7212
TEST_TIME 3 7014
TEST_TIME 4 6649
TEST_TIME 5 6482
TEST_TIME 6 6223
TEST_TIME 7 5965
TEST_TIME 8 6168
TEST_TIME 9 6323
TEST_TIME 10 6509
TEST_TIME 11 6441
TEST_TIME 12 6597
TEST_TIME 13 6866
TEST_TIME 14 6921
TEST_TIME 15 7038
TEST_TIME 16 7062
TEST_TIME 17 7012
TEST_TIME 18 7349
TEST_TIME 19 7295
TEST_TIME 20 7250
TEST_TIME 21 7144
TEST_TIME 22 7311
TEST_TIME 23 7382
TEST_TIME 24 7659
TEST_TIME 25 7776
TEST_TIME 26 7846
TEST_TIME 27 7975
TEST_TIME 28 8162
TEST_TIME 29 8352
TEST_TIME 30 8963
TEST_TIME 31 8963
TEST_TIME 32 9090
TEST_TIME 33 9180
TEST_TIME 34 9454
TEST_TIME 35 9467
TEST_TIME 36 9637
TEST_TIME 37 10033
TEST_TIME 38 9906
TEST_TIME 39 10107
TEST_TIME 40 10284
TEST_TIME 41 10579
TEST_TIME 42 10641
TEST_TIME 43 10735
TEST_TIME 44 10626
TEST_TIME 45 10527
TEST_TIME 46 10425
TEST_TIME 47 10299
TEST_TIME 48 10238
TEST_TIME 49 10301
TEST_TIME 50 10253
TEST_TIME 51 10150
TEST_TIME 52 9996
TEST_TIME 53 9891
TEST_TIME 54 9886
TEST_TIME 55 9869
TEST_TIME 56 9942
TEST_TIME 57 9921
TEST_TIME 58 9909
TEST_TIME 59 9817
TEST_TIME 60 9762
TEST_TIME 61 9758
TEST_TIME 62 9692
TEST_TIME 63 9594
TEST_TIME 64 9577
TEST_TIME 65 9574
TEST_TIME 66 9525
TEST_TIME 67 9507
TEST_TIME 68 9488
TEST_TIME 69 9457
TEST_TIME 70 9475
TEST_TIME 71 9523
TEST_TIME 72 9532
TEST_TIME 73 9498
TEST_TIME 74 9564
TEST_TIME 75 9541
TEST_TIME 76 9557
TEST_TIME 77 9497
TEST_TIME 78 9563
TEST_TIME 79 9658
TEST_TIME 80 9636
TEST_TIME 81 9579
TEST_TIME 82 9479
TEST_TIME 83 9463
TEST_TIME 84 9395
TEST_TIME 85 9406
TEST_TIME 86 9386
TEST_TIME 87 9322
TEST_TIME 88 9303
TEST_TIME 89 9314
TEST_TIME 90 9253
TEST_TIME 91 9220
TEST_TIME 92 9195
TEST_TIME 93 9161
TEST_TIME 94 9130
TEST_TIME 95 9105
TEST_TIME 96 9149
TEST_TIME 97 9110
TEST_TIME 98 9177
TEST_TIME 99 9254
TEST_TIME 100 9288
TEST_TIME 101 9295
TEST_TIME 102 9485
TEST_TIME 103 9464
TEST_TIME 104 9439
TEST_TIME 105 9429
TEST_TIME 106 9415
TEST_TIME 107 9371
TEST_TIME 108 9336
TEST_TIME 109 9353
TEST_TIME 110 9367
TEST_TIME 111 9334
TEST_TIME 112 9335
TEST_TIME 113 9369
TEST_TIME 114 9342
TEST_TIME 115 9303
TEST_TIME 116 9321
TEST_TIME 117 9363
TEST_TIME 118 9368
TEST_TIME 119 9370
TEST_TIME 120 9297

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-01-2022 07:39:15 to 12-01-2022 07:39:19.

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 1550752 1550752 0 0.00 0 25659 15 0 0
doc_list_biud 1550741 1550741 0 0.00 0 24712 47 0 0
sp_add_invoice_to_stock 5247 5113 134 2.55 49 195548 28708 0 134
sp_cancel_adding_invoice 5565 5565 0 0.00 6 87622 1405 0 0
sp_cancel_client_order 1765 1765 0 0.00 6 4375 448 0 0
sp_cancel_customer_reserve 549888 549888 0 0.00 1 24725 116 0 0
sp_cancel_pay_from_customer 714 714 0 0.00 4 2938 131 0 0
sp_cancel_pay_to_supplier 447 447 0 0.00 3 4365 149 0 0
sp_cancel_supplier_invoice 6175 6170 5 0.08 3 24672 1153 0 5
sp_cancel_supplier_order 441 441 0 0.00 7 1418388 183217 0 0
sp_cancel_write_off 9516 9516 0 0.00 2 19809 186 0 0
sp_client_order 20890 20890 0 0.00 6 26973 465 0 0
sp_customer_reserve 475811 475811 0 0.00 1 48549 273 0 0
sp_fill_shopping_cart 31716 31716 0 0.00 1 10311 83 0 0
sp_fill_shopping_cart_clo_res 475778 475778 0 0.00 0 39131 20 0 0
sp_get_clo_for_invoice 5073 5073 0 0.00 0 30840 280 0 0
sp_kill_cost_storno 16242 16242 0 0.00 0 19799 85 0 0
sp_kill_qstorno_ret_qs2qd 1115903 1115903 0 0.00 0 24669 50 0 0
sp_kill_qty_storno 588554 588554 0 0.00 0 24711 115 0 0
sp_lock_selected_doc 21072 21072 0 0.00 0 10009 7 0 0
sp_make_cost_storno 28360 28360 0 0.00 0 13496 84 0 0
sp_multiply_rows_for_pdistr 28360 28360 0 0.00 0 8804 43 0 0
sp_multiply_rows_for_qdistr 20889 20889 0 0.00 0 14812 157 0 0
sp_pay_from_customer 7279 7279 0 0.00 5 8000 186 0 0
sp_pay_to_supplier 5873 5873 0 0.00 6 11662 205 0 0
sp_payment_common 13150 13150 0 0.00 2 6700 44 0 0
sp_qd_handle_on_cancel_clo 1765 1765 0 0.00 0 4369 365 0 0
sp_qd_handle_on_invoice_upd_sts 10638 10638 0 0.00 0 21566 588 0 0
sp_qd_handle_on_reserve_upd_sts 10136 10136 0 0.00 0 11265 71 0 0
sp_reserve_write_off 10136 10136 0 0.00 9 15251 301 0 0
sp_supplier_invoice 5453 5453 0 0.00 11 94824 3438 0 0
sp_supplier_order 5384 5384 0 0.00 8 34074 1441 0 0
srv_aggregate_perf_data 109 109 0 0.00 2881 198201 18682 0 0
srv_fill_mon_cache_memory 75 75 0 0.00 865 121670 27747 0 0
srv_find_qd_qs_mism 1062510 1062510 0 0.00 0 25655 11 0 0
srv_make_invnt_saldo 477 477 0 0.00 82 74155 4640 0 0
srv_make_money_saldo 986 986 0 0.00 1 15950 445 0 0
srv_recalc_idx_stat 5 5 0 0.00 125137 388321 288391 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5123 5123 0 0.00 0 12962 1033 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 1 15712 529 0 0
t$perf-norm:sp_cancel_client_order 1765 1765 0 0.00 0 2644 33 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 3699 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 714 714 0 0.00 0 1239 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 447 447 0 0.00 0 1083 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 624 27 0 0
t$perf-norm:sp_cancel_supplier_order 441 441 0 0.00 0 115060 8398 0 0
t$perf-norm:sp_cancel_write_off 993 993 0 0.00 0 1698 24 0 0
t$perf-norm:sp_client_order 20890 20890 0 0.00 0 8138 42 0 0
t$perf-norm:sp_customer_reserve 946 946 0 0.00 0 1144 17 0 0
t$perf-norm:sp_pay_from_customer 7279 7279 0 0.00 0 6057 24 0 0
t$perf-norm:sp_pay_to_supplier 5873 5873 0 0.00 0 9689 22 0 0
t$perf-norm:sp_reserve_write_off 10137 10137 0 0.00 0 14753 28 0 0
t$perf-norm:sp_supplier_invoice 5454 5454 0 0.00 0 2103 31 0 0
t$perf-norm:sp_supplier_order 5384 5384 0 0.00 0 8956 40 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 1340 30 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 1101 36 0 0
t$perf-norm:srv_make_invnt_saldo 477 477 0 0.00 0 979 21 0 0
t$perf-norm:srv_make_money_saldo 986 986 0 0.00 0 1157 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 17 251 100 0 0
v_add_invoice_to_stock 10412 10144 268 2.57 0 8459 26 0 268
v_all_customers 38867 38867 0 0.00 0 114 4 0 0
v_all_suppliers 30224 30224 0 0.00 0 99 4 0 0
v_all_wares 41772 41772 0 0.00 0 136 4 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 1601 16 0 0
v_cancel_client_order 3530 3530 0 0.00 0 1904 11 0 0
v_cancel_customer_prepayment 1428 1428 0 0.00 0 1367 13 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 1827 14 0 0
v_cancel_payment_to_supplier 894 894 0 0.00 0 3868 20 0 0
v_cancel_supplier_invoice 864 854 10 1.15 0 2915 27 0 10
v_cancel_supplier_order 882 882 0 0.00 0 2235 13 0 0
v_cancel_write_off 1986 1986 0 0.00 0 2820 25 0 0
v_max_id_clo_ord 5382 5382 0 0.00 0 3583 6 0 0
v_max_id_clo_res 946 946 0 0.00 0 2725 19 0 0
v_max_id_ord_sup 5448 5448 0 0.00 0 4135 8 0 0
v_max_non_paid_invoice 1589 1589 0 0.00 0 659 8 0 0
v_max_non_paid_realizn 3653 3653 0 0.00 0 971 8 0 0
v_min_id_clo_ord 5382 5382 0 0.00 0 1195 8 0 0
v_min_id_clo_res 946 946 0 0.00 0 1321 10 0 0
v_min_id_ord_sup 5448 5448 0 0.00 0 9802 16 0 0
v_min_non_paid_invoice 5871 5871 0 0.00 0 6695 13 0 0
v_min_non_paid_realizn 7279 7279 0 0.00 0 6672 14 0 0
v_our_firm 10154 10154 0 0.00 0 64 5 0 0
v_reserve_write_off 20272 20272 0 0.00 0 3659 12 0 0
x_lock_depdocs_on_canc_invoice 11308 11308 0 0.00 0 14143 131 0 0
x_lock_depdocs_on_canc_sup_ord 441 441 0 0.00 0 4736 341 0 0
x_make_qstorno_1000_1200 5384 5384 0 0.00 3 34056 1259 0 0
x_make_qstorno_1200_2000 5453 5453 0 0.00 5 90064 3219 0 0
x_make_qstorno_2100_3300 475811 475811 0 0.00 0 48547 239 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-01-2022 07:39:20 to 12-01-2022 07:39:24.

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:07:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:12 23637 8192000000 9714501600 10008272896 794010192 8.836 100 95 190 0 825668848 8227392 23431264
05:09:43 23230 8192000000 9746347360 10048839680 818639504 9.085 100 93 186 0 854506752 13260304 22606944
05:11:15 21122 8192000000 9789591856 10111377408 850059184 9.401 100 80 160 0 897635456 29252240 18324032
05:12:42 11101 8192000000 9856335712 10187292672 905980432 9.958 100 77 149 2 968899888 46378032 16541424
05:13:56 5305 8192000000 9903587072 10245763072 951710176 10.408 100 82 157 0 1017994960 50196304 16088480
05:15:04 3726 8192000000 9970514528 10320728064 982873904 10.712 100 83 166 1 1077502880 77811904 16817072
05:16:10 1205 8192000000 9990838352 10347663360 1016325056 11.037 100 88 174 0 1108213952 74619520 17269376
05:17:13 751 8192000000 10021321776 10385768448 1037918736 11.245 100 96 185 2 1140026736 84570832 17537168
05:18:16 1423 8192000000 10003742832 10374782976 1051641952 11.376 100 92 180 0 1124406256 55065760 17698544
05:19:21 4535 8192000000 10026132288 10397319168 1060983152 11.466 100 83 158 1 1150629632 73981920 15664560
05:20:28 1287 8192000000 10034401696 10411302912 1064916016 11.504 100 97 189 1 1156975536 71729920 20329600
05:21:31 1192 8192000000 10043837728 10424442880 1074326448 11.593 100 80 151 2 1165908752 75444384 16137920
05:22:34 1140 8192000000 10045532688 10426912768 1081804944 11.665 100 92 180 1 1166791792 66725040 18261808
05:23:38 3407 8192000000 10048865424 10433331200 1083912112 11.685 100 81 156 3 1170648464 70908112 15828240
05:24:43 1467 8192000000 10073496320 10455683072 1089025216 11.733 100 95 184 0 1192212400 84683856 18503328
05:25:45 4714 8192000000 10034908256 10425679872 1090350464 11.746 100 78 138 1 1162096864 57126832 14619568
05:26:51 4497 8192000000 10033767856 10431750144 1091936288 11.761 100 70 129 0 1155977424 51802128 12239008
05:27:56 2113 8192000000 10039361104 10422886400 1080354816 11.651 99 84 148 10 1161171280 65984176 14832288
05:28:58 1312 8192000000 10070620400 10456879104 1099134560 11.829 100 98 188 2 1189366096 70735776 19495760
05:30:01 1268 8192000000 10070545712 10456932352 1096820608 11.807 100 87 174 0 1192590272 77083072 18686592
05:31:03 1287 8192000000 10064968432 10447056896 1089211536 11.735 100 98 193 1 1186370912 77386832 19772544
05:32:05 918 8192000000 10010450240 10382409728 1039409296 11.259 98 97 187 0 1134991216 75416144 20165776
05:33:08 1100 8192000000 9999971968 10373267456 1042022336 11.284 100 89 172 1 1118505632 56423536 20059760
05:34:10 919 8192000000 10017085760 10392186880 1051344384 11.374 99 89 165 2 1136241600 67609904 17287312
05:35:12 1249 8192000000 10000411392 10379100160 1052154336 11.381 100 84 149 2 1124602336 55645824 16802176
05:36:15 1814 8192000000 10024733888 10402770944 1048059696 11.342 99 79 153 2 1140298080 76837904 15400480
05:37:19 3313 8192000000 9982850288 10358312960 1038935936 11.254 99 72 136 3 1101871312 48230864 14704512
05:38:32 2455 8192000000 10028380256 10405072896 1068901872 11.542 100 81 154 1 1149883664 65237376 15744416
05:40:10 1491 8192000000 10071333920 10451931136 1090050784 11.743 100 86 170 2 1196406880 86734784 19621312
05:41:16 3489 8192000000 10094866592 10477346816 1099043616 11.829 100 80 150 1 1205801232 90921760 15835856
05:42:25 4754 8192000000 10118807168 10515464192 1115770976 11.987 100 67 127 0 1237980272 108936080 13273216
05:43:32 11774 8192000000 10134883840 10529542144 1120220896 12.029 100 67 126 1 1251908560 118830304 12857360
05:44:49 5128 8192000000 10185393456 10578751488 1134582656 12.165 100 77 148 0 1297977296 147594880 15799760
05:45:58 1009 8192000000 10221053520 10611699712 1140906800 12.224 100 96 184 1 1333354512 171621232 20826480
05:47:05 2509 8192000000 10252617168 10656808960 1148677552 12.297 100 84 162 2 1362888240 196691312 17519376
05:48:11 7803 8192000000 10259097600 10658246656 1157020752 12.375 100 83 155 1 1364372768 190823648 16528368
05:49:24 2885 8192000000 10257747648 10664419328 1170587856 12.502 100 85 165 0 1372612240 183367984 18656400
05:50:30 884 8192000000 10311110928 10717507584 1177196176 12.564 100 94 176 0 1422818544 226023568 19598800
05:51:35 1464 8192000000 10333699312 10746044416 1178477792 12.576 100 86 166 2 1451002432 253897600 18627040
05:52:42 1115 8192000000 10362888352 10797862912 1182357984 12.612 100 88 167 2 1472302848 270453680 19491184
05:53:47 2022 8192000000 10363680960 10780585984 1184398160 12.631 100 89 174 1 1468181120 264009920 19773040
05:54:53 981 8192000000 10399159936 10819317760 1185701152 12.643 100 99 193 0 1504324848 296265552 22358144
05:55:58 996 8192000000 10413740368 10823004160 1187732576 12.662 100 97 190 0 1519196192 309449840 22013776
05:57:09 856 8192000000 10435341632 10837155840 1178714080 12.578 99 84 168 1 1544286592 345896784 19675728
05:58:16 1633 8192000000 10494342720 10902204416 1199294784 12.770 100 93 186 0 1604459616 382971392 22193440
05:59:21 1217 8192000000 10498114432 10926727168 1184563472 12.633 100 94 179 0 1604922576 396940944 23418160
06:00:27 1262 8192000000 10508995664 10943909888 1197558256 12.754 100 95 180 1 1609010560 390061936 21390368
06:01:31 968 8192000000 10530220784 10968883200 1210066992 12.870 100 96 190 0 1638814144 405077376 23669776
06:02:39 2071 8192000000 10556302624 11001331712 1204761648 12.821 100 92 179 2 1659212944 430548576 23902720
06:03:43 3531 8192000000 10567040992 11005259776 1193772608 12.718 99 68 130 0 1667318432 457120480 16425344
06:04:55 3503 8192000000 10527786816 10993803264 1192168912 12.704 100 59 117 0 1638568928 430947584 15452432
06:06:05 5965 8192000000 10534316992 10996441088 1171442320 12.510 99 57 112 0 1631280864 445457264 14381280
06:07:20 8883 8192000000 10478161840 10965512192 1181453696 12.604 100 52 104 0 1587781744 393597680 12730368
06:08:30 19321 8192000000 10438650848 10877222912 1168097664 12.479 100 33 65 1 1540808960 364953088 7758208
06:10:05 13446 8192000000 10386573296 10821398528 1159959648 12.403 100 41 82 2 1504413424 334950064 9503712
06:11:28 3636 8192000000 10355613616 10804924416 1147890880 12.290 100 57 111 1 1452352784 291776288 12685616
06:12:36 871 8192000000 10369412368 10816823296 1155523616 12.361 100 87 168 3 1462017792 287090432 19403744
06:13:38 1362 8192000000 10339093248 10803576832 1155405280 12.360 100 69 132 0 1435990064 265425824 15158960
06:14:44 38836 8192000000 10328002720 10767794176 1154920608 12.356 100 29 58 0 1425729552 263246368 7562576
06:16:38 30725 8192000000 10296823024 10780848128 1146765680 12.279 100 24 48 1 1400098800 247070176 6262944
06:18:53 28823 8192000000 10095802272 10542784512 1127588144 12.099 100 38 73 2 1200927952 64672672 8667136
06:20:22 19719 8192000000 10067224080 10519990272 1116426384 11.993 100 13 26 0 1174795904 55044352 3325168
06:21:54 119528 8192000000 10085875808 10562830336 1125403424 12.078 100 32 64 2 1216302176 84071840 6826912
06:24:56 23425 8192000000 10119195424 10540544000 1123206208 12.057 99 54 104 2 1177856128 43902976 10746944
06:26:20 97074 8192000000 10034628784 10486792192 1065927968 11.513 94 21 42 2 1117724848 47446320 4350560
06:28:58 55932 8192000000 10074356016 10501767168 1098293248 11.821 98 42 80 2 1144360080 37115408 8951424
06:31:08 1578 8192000000 10148122624 10566823936 1137183296 12.189 100 88 174 1 1238224832 81988064 19053472
06:32:13 16692 8192000000 10162424736 10610393088 1140171488 12.217 100 44 87 1 1251116816 100827424 10117904
06:33:32 20422 8192000000 10094267392 10580275200 1131552000 12.136 100 38 76 1 1195143120 56490416 7100704
06:34:55 34128 8192000000 10055945584 10520686592 1123555472 12.061 100 23 47 1 1174203552 45726400 4921680
06:36:43 32975 8192000000 10061734784 10521677824 1110666944 11.939 99 23 43 1 1153326320 38395840 4263536
06:38:17 65150 8192000000 10081640496 10557054976 1128465904 12.107 100 23 47 1 1211541232 77844336 5230992
06:40:22 121670 8192000000 10060813760 10557427712 1089674000 11.740 96 21 42 0 1171227088 76846144 4706944
06:43:44 10114 8192000000 10049411824 10487595008 1112510464 11.956 99 45 88 0 1163702992 40823872 10368656
06:44:54 77188 8192000000 10070774512 10565050368 1102037600 11.857 98 10 20 0 1164959584 60591632 2330352
06:47:12 33082 8192000000 10109120432 10583240704 1140175376 12.217 100 22 44 0 1213299104 68439920 4683808
06:48:52 39633 8192000000 10074434720 10549846016 1112049504 11.952 98 19 37 0 1165038768 49160192 3829072
06:50:36 24735 8192000000 10110630864 10543661056 1148854000 12.299 100 56 109 3 1213493824 54105936 10533888
06:52:01 27324 8192000000 10098287920 10541002752 1120968032 12.036 98 38 76 0 1175256672 46663312 7625328
06:53:31 16829 8192000000 10109306192 10550235136 1124979792 12.074 99 35 70 0 1196242736 64071168 7191776
06:55:03 67506 8192000000 10102757936 10601349120 1114307088 11.973 99 30 58 1 1201516128 81299424 5909616
06:58:02 18159 8192000000 10065591200 10495406080 1111253008 11.944 99 51 96 0 1147187408 26885584 9048816
06:59:22 62357 8192000000 10093226080 10535940096 1085915808 11.704 96 47 92 2 1144787200 49426912 9444480
07:01:42 14448 8192000000 10080908064 10511900672 1121995792 12.046 100 44 79 1 1190763504 60586336 8181376
07:02:58 59198 8192000000 10074520800 10530402304 1127294064 12.096 100 7 12 0 1178046304 49460784 1291456
07:04:57 60275 8192000000 10067598752 10526711808 1102131728 11.858 98 31 62 0 1159902272 50821024 6949520
07:07:43 31894 8192000000 10063353696 10502782976 1113012416 11.961 99 21 36 3 1153231952 36728704 3490832
07:09:16 32933 8192000000 10133244960 10576855040 1115034144 11.980 98 24 48 2 1199665920 79631808 4999968
07:10:49 25750 8192000000 10150838928 10584973312 1133211488 12.152 99 34 68 0 1240273488 99390944 7671056
07:12:19 15038 8192000000 10211799008 10638979072 1128307024 12.105 99 44 88 0 1301543280 163434224 9802032
07:13:41 23226 8192000000 10221127168 10676097024 1137898528 12.196 99 43 85 0 1300884704 153340096 9646080
07:15:06 38930 8192000000 10258721520 10730450944 1148494688 12.295 100 37 74 3 1356019408 199317760 8206960
07:16:46 41704 8192000000 10211444336 10708168704 1149547392 12.305 100 12 24 0 1317094096 164629248 2917456
07:18:40 38721 8192000000 10165962128 10626367488 1112237456 11.954 98 16 32 0 1284554112 167661392 4655264
07:20:19 105092 8192000000 10125928784 10615701504 1129648288 12.118 99 31 62 0 1196014144 59763264 6602592
07:23:47 50418 8192000000 10068878640 10521669632 1138455664 12.201 100 15 30 1 1171689280 29898528 3335088
07:25:39 26143 8192000000 10092862288 10524508160 1116695488 11.996 98 59 113 1 1168395872 40106960 11593424
07:28:02 60196 8192000000 10119539568 10581225472 1148666832 12.297 100 18 36 1 1218575168 65996160 3912176
07:30:11 96632 8192000000 10078075040 10518327296 1117662416 12.005 97 22 44 2 1180979840 58546672 4770752
07:32:49 48195 8192000000 10139671872 10573848576 1106991248 11.904 97 43 86 0 1207384768 91860208 8533312
07:34:45 105664 8192000000 10087146992 10538803200 1120633328 12.033 98 20 40 1 1158392624 33815392 3943904

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-01-2022 07:39:26 to 12-01-2022 07:39:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-01-2022 07:39:43 to 12-01-2022 07:39:45.

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          219081
MON$OLDEST_ACTIVE               220403
MON$OLDEST_SNAPSHOT             220403
MON$NEXT_TRANSACTION            220403
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                       2114176
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             773
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2704 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2704 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2704 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2704 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 ---
:350: 
:351: mscs-test-161.budzdorov.ru    Wed Jan 12 07:37:08 2022
:352:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:353: 
:354: 
:355: mscs-test-161.budzdorov.ru    Wed Jan 12 07:37:09 2022
:356:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:357: 
:358: 
:359: mscs-test-161.budzdorov.ru    Wed Jan 12 07:37:19 2022
:360:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:361: 
--- end of diff output ---

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

12.01.22 07:39:46. 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-01-12 07:39:49 No severe PSQL-related problems occured
12.01.22 07:39:50. End of report.