Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.04.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 16302920 kB
MemAvailable 31591644 kB
Buffers 0 kB
Cached 15424836 kB
SwapCached 2260 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-04-06 07:46:56
DTS_END                         2022-04-06 07:46:56.6490
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/52236
TRN_ID                          220202
ATT_ID                          572
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: 9078.80 23075 1089456
customer order: creation 169.93 443 20392
customer order: refuse 15.01 373 1801
order to supplier: creation 45.14 1321 5417
order to supplier: removal 3.50 160106 420
invoice (draft): creation 46.01 3222 5521
invoice (draft): removal 51.56 948 6187
invoice accept: apply 41.87 24949 5024
invoice accept: cancel 46.55 1105 5586
customer reserve: creation 3842.73 240 461127
customer reserve: removal 4520.38 96 542446
realization accept: apply 82.34 274 9881
realization accept: cancel 83.22 165 9986
payment from customer: creation 59.39 170 7127
payment from customer: removal 5.73 130 688
payment to supplier: creation 49.58 201 5950
payment to supplier: removal 3.47 119 416
service: total inventory turnovers 4.08 4155 490
service: total monetary turnovers 7.37 394 884
service: aggregate perf. data 0.91 17587 109
service: refresh index statistics 0.03 245506 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 06-04-2022 07:48:25 to 06-04-2022 07:48:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2113
TEST_TIME 2 2726
TEST_TIME 3 4230
TEST_TIME 4 4300
TEST_TIME 5 4520
TEST_TIME 6 4992
TEST_TIME 7 5016
TEST_TIME 8 5269
TEST_TIME 9 5964
TEST_TIME 10 6245
TEST_TIME 11 6396
TEST_TIME 12 6553
TEST_TIME 13 6667
TEST_TIME 14 7024
TEST_TIME 15 7103
TEST_TIME 16 7145
TEST_TIME 17 7080
TEST_TIME 18 7145
TEST_TIME 19 7112
TEST_TIME 20 7381
TEST_TIME 21 7577
TEST_TIME 22 7531
TEST_TIME 23 7796
TEST_TIME 24 7712
TEST_TIME 25 8017
TEST_TIME 26 8115
TEST_TIME 27 8258
TEST_TIME 28 8341
TEST_TIME 29 8706
TEST_TIME 30 9309
TEST_TIME 31 9812
TEST_TIME 32 10381
TEST_TIME 33 10690
TEST_TIME 34 10559
TEST_TIME 35 10548
TEST_TIME 36 10689
TEST_TIME 37 10467
TEST_TIME 38 10506
TEST_TIME 39 10414
TEST_TIME 40 10283
TEST_TIME 41 10309
TEST_TIME 42 10215
TEST_TIME 43 10293
TEST_TIME 44 10253
TEST_TIME 45 10270
TEST_TIME 46 10156
TEST_TIME 47 10239
TEST_TIME 48 10223
TEST_TIME 49 10122
TEST_TIME 50 9954
TEST_TIME 51 9852
TEST_TIME 52 9868
TEST_TIME 53 9758
TEST_TIME 54 9646
TEST_TIME 55 9667
TEST_TIME 56 9640
TEST_TIME 57 9578
TEST_TIME 58 9657
TEST_TIME 59 9551
TEST_TIME 60 9538
TEST_TIME 61 9555
TEST_TIME 62 9523
TEST_TIME 63 9470
TEST_TIME 64 9499
TEST_TIME 65 9552
TEST_TIME 66 9571
TEST_TIME 67 9497
TEST_TIME 68 9500
TEST_TIME 69 9574
TEST_TIME 70 9551
TEST_TIME 71 9479
TEST_TIME 72 9471
TEST_TIME 73 9619
TEST_TIME 74 9734
TEST_TIME 75 9723
TEST_TIME 76 9707
TEST_TIME 77 9679
TEST_TIME 78 9573
TEST_TIME 79 9579
TEST_TIME 80 9655
TEST_TIME 81 9559
TEST_TIME 82 9529
TEST_TIME 83 9480
TEST_TIME 84 9446
TEST_TIME 85 9403
TEST_TIME 86 9360
TEST_TIME 87 9310
TEST_TIME 88 9293
TEST_TIME 89 9239
TEST_TIME 90 9204
TEST_TIME 91 9281
TEST_TIME 92 9290
TEST_TIME 93 9261
TEST_TIME 94 9309
TEST_TIME 95 9278
TEST_TIME 96 9271
TEST_TIME 97 9331
TEST_TIME 98 9408
TEST_TIME 99 9386
TEST_TIME 100 9391
TEST_TIME 101 9392
TEST_TIME 102 9442
TEST_TIME 103 9398
TEST_TIME 104 9431
TEST_TIME 105 9347
TEST_TIME 106 9288
TEST_TIME 107 9278
TEST_TIME 108 9292
TEST_TIME 109 9235
TEST_TIME 110 9206
TEST_TIME 111 9163
TEST_TIME 112 9131
TEST_TIME 113 9120
TEST_TIME 114 9110
TEST_TIME 115 9144
TEST_TIME 116 9102
TEST_TIME 117 9093
TEST_TIME 118 9083
TEST_TIME 119 9107
TEST_TIME 120 9095

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-04-2022 07:48:29 to 06-04-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 1516692 1516692 0 0.00 0 16108 13 0 0
doc_list_biud 1516692 1516692 0 0.00 0 65983 40 0 0
sp_add_invoice_to_stock 5092 5024 68 1.33 47 191967 24950 0 68
sp_cancel_adding_invoice 5586 5586 0 0.00 8 74395 1105 0 0
sp_cancel_client_order 1801 1801 0 0.00 5 6170 374 0 0
sp_cancel_customer_reserve 542446 542446 0 0.00 1 65990 97 0 0
sp_cancel_pay_from_customer 688 688 0 0.00 4 8734 131 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 4 3473 119 0 0
sp_cancel_supplier_invoice 6193 6187 6 0.09 2 18685 949 0 6
sp_cancel_supplier_order 420 420 0 0.00 11 1502531 160106 0 0
sp_cancel_write_off 9986 9986 0 0.00 2 11983 166 0 0
sp_client_order 20392 20392 0 0.00 5 25707 443 0 0
sp_customer_reserve 461127 461127 0 0.00 1 44615 241 0 0
sp_fill_shopping_cart 31318 31318 0 0.00 1 23056 84 0 0
sp_fill_shopping_cart_clo_res 461121 461121 0 0.00 0 21530 19 0 0
sp_get_clo_for_invoice 4986 4986 0 0.00 0 44608 285 0 0
sp_kill_cost_storno 16676 16676 0 0.00 0 8724 73 0 0
sp_kill_qstorno_ret_qs2qd 1101485 1101485 0 0.00 0 65973 42 0 0
sp_kill_qty_storno 581291 581291 0 0.00 0 65982 96 0 0
sp_lock_selected_doc 20660 20660 0 0.00 0 6000 5 0 0
sp_make_cost_storno 27942 27942 0 0.00 0 14784 85 0 0
sp_multiply_rows_for_pdistr 27942 27942 0 0.00 0 8999 36 0 0
sp_multiply_rows_for_qdistr 20392 20392 0 0.00 0 17883 165 0 0
sp_pay_from_customer 7127 7127 0 0.00 5 12165 170 0 0
sp_pay_to_supplier 5950 5950 0 0.00 7 14123 201 0 0
sp_payment_common 13077 13077 0 0.00 2 12145 44 0 0
sp_qd_handle_on_cancel_clo 1801 1801 0 0.00 0 6154 304 0 0
sp_qd_handle_on_invoice_upd_sts 10570 10570 0 0.00 0 16556 493 0 0
sp_qd_handle_on_reserve_upd_sts 9881 9881 0 0.00 0 15871 58 0 0
sp_reserve_write_off 9881 9881 0 0.00 8 16146 274 0 0
sp_supplier_invoice 5521 5521 0 0.00 7 109846 3222 0 0
sp_supplier_order 5417 5417 0 0.00 9 18122 1322 0 0
srv_aggregate_perf_data 109 109 0 0.00 2706 225028 17587 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 813 154459 36034 0 0
srv_find_qd_qs_mism 1041910 1041910 0 0.00 0 13304 10 0 0
srv_make_invnt_saldo 490 490 0 0.00 41 95865 4156 0 0
srv_make_money_saldo 884 884 0 0.00 1 17617 394 0 0
srv_recalc_idx_stat 4 4 0 0.00 136591 294671 245507 0 0
t$perf-abend:sp_add_invoice_to_stock 68 68 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5024 5024 0 0.00 1 10470 850 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 1 7308 395 0 0
t$perf-norm:sp_cancel_client_order 1801 1801 0 0.00 0 8435 33 0 0
t$perf-norm:sp_cancel_customer_reserve 664 664 0 0.00 0 1828 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 688 688 0 0.00 0 777 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 828 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 794 26 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 107635 6327 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 3471 21 0 0
t$perf-norm:sp_client_order 20392 20392 0 0.00 0 21881 37 0 0
t$perf-norm:sp_customer_reserve 1014 1014 0 0.00 0 3707 18 0 0
t$perf-norm:sp_pay_from_customer 7127 7127 0 0.00 0 4597 20 0 0
t$perf-norm:sp_pay_to_supplier 5950 5950 0 0.00 0 6584 22 0 0
t$perf-norm:sp_reserve_write_off 9881 9881 0 0.00 0 7188 22 0 0
t$perf-norm:sp_supplier_invoice 5521 5521 0 0.00 0 9241 37 0 0
t$perf-norm:sp_supplier_order 5417 5417 0 0.00 0 3274 29 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 316 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 214 6 0 0
t$perf-norm:srv_make_invnt_saldo 490 490 0 0.00 0 1270 17 0 0
t$perf-norm:srv_make_money_saldo 884 884 0 0.00 0 288 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 6 179 65 0 0
v_add_invoice_to_stock 10104 9968 136 1.34 0 4729 22 0 136
v_all_customers 37724 37724 0 0.00 0 93 3 0 0
v_all_suppliers 30516 30516 0 0.00 0 67 4 0 0
v_all_wares 40784 40784 0 0.00 0 65 4 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 1710 14 0 0
v_cancel_client_order 3602 3602 0 0.00 0 3249 10 0 0
v_cancel_customer_prepayment 1376 1376 0 0.00 0 1676 12 0 0
v_cancel_customer_reserve 1328 1328 0 0.00 0 459 9 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 2014 14 0 0
v_cancel_supplier_invoice 778 766 12 1.54 0 3865 25 0 12
v_cancel_supplier_order 840 840 0 0.00 0 2005 11 0 0
v_cancel_write_off 1998 1998 0 0.00 0 3232 26 0 0
v_max_id_clo_ord 5417 5417 0 0.00 0 729 5 0 0
v_max_id_clo_res 1014 1014 0 0.00 0 4480 15 0 0
v_max_id_ord_sup 5509 5509 0 0.00 0 6781 9 0 0
v_max_non_paid_invoice 1618 1618 0 0.00 0 5185 10 0 0
v_max_non_paid_realizn 3613 3613 0 0.00 0 5184 10 0 0
v_min_id_clo_ord 5417 5417 0 0.00 0 1604 9 0 0
v_min_id_clo_res 1014 1014 0 0.00 0 1389 12 0 0
v_min_id_ord_sup 5509 5509 0 0.00 0 8072 13 0 0
v_min_non_paid_invoice 5950 5950 0 0.00 0 5200 17 0 0
v_min_non_paid_realizn 7127 7127 0 0.00 0 8185 16 0 0
v_our_firm 10088 10088 0 0.00 0 59 4 0 0
v_reserve_write_off 19762 19762 0 0.00 0 3605 11 0 0
x_lock_depdocs_on_canc_invoice 11390 11390 0 0.00 0 11598 127 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 3595 326 0 0
x_make_qstorno_1000_1200 5417 5417 0 0.00 3 18111 1155 0 0
x_make_qstorno_1200_2000 5521 5521 0 0.00 3 109739 2988 0 0
x_make_qstorno_2100_3300 461127 461127 0 0.00 0 36816 211 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-04-2022 07:48:34 to 06-04-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:00 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:18:01 22906 8192000000 9714179392 10009419776 792985152 8.825 100 90 181 0 823711120 8322048 22403920
05:19:29 13463 8192000000 9757550720 10061103104 829993872 9.199 100 93 186 0 864276512 12071072 22211568
05:20:49 25877 8192000000 9782593696 10098884608 852945216 9.430 100 86 172 0 893660704 20481072 20234416
05:22:20 9548 8192000000 9847337904 10180702208 904671872 9.945 100 83 166 1 960492256 38067760 17752624
05:23:31 5568 8192000000 9897533952 10240516096 943461808 10.327 100 85 163 0 1010813120 50417280 16934032
05:24:41 2284 8192000000 9963084912 10314964992 981274848 10.697 100 89 174 1 1075662448 75733088 18654512
05:25:46 1579 8192000000 9994619216 10353836032 1014488864 11.019 100 88 169 0 1110118400 79076864 16552672
05:26:51 1025 8192000000 10017965888 10386374656 1043483936 11.298 100 93 174 0 1139146608 77999696 17662976
05:27:54 1258 8192000000 10037788688 10410848256 1050096000 11.362 100 90 178 1 1158397104 90966416 17334688
05:28:56 961 8192000000 10028834032 10407149568 1060305472 11.459 100 92 172 0 1152540848 74915424 17319952
05:29:59 1192 8192000000 10048032736 10428305408 1068229216 11.535 100 96 178 1 1169674048 82037840 19406992
05:31:03 1639 8192000000 10057207696 10441011200 1071079984 11.562 100 95 185 2 1174653328 84157536 19415808
05:32:07 1383 8192000000 10052277856 10436972544 1077667360 11.625 100 92 176 1 1173792736 76798112 19327264
05:33:09 1256 8192000000 10055605728 10439512064 1086465456 11.709 100 95 178 3 1177079328 72050080 18563792
05:34:11 1010 8192000000 10059031584 10447548416 1089779856 11.741 100 90 175 0 1182897184 74641968 18475360
05:35:14 1570 8192000000 10057347664 10449760256 1093571216 11.777 100 85 166 1 1181328848 71222704 16534928
05:36:16 1072 8192000000 10060149728 10452496384 1099620352 11.834 100 93 176 1 1180880288 64780848 16479088
05:37:18 1265 8192000000 10067809328 10457309184 1095276976 11.793 100 99 193 1 1190267424 75266736 19723712
05:38:20 1167 8192000000 10077623984 10468118528 1092993824 11.771 100 97 185 2 1196896304 85728096 18174384
05:39:22 1027 8192000000 10056626144 10444546048 1092485168 11.766 100 91 174 1 1179803136 68764448 18553520
05:40:25 1190 8192000000 10069484096 10454945792 1071430576 11.566 100 93 184 0 1190179920 100303968 18445376
05:41:28 1010 8192000000 10047348992 10429566976 1072006576 11.571 100 86 165 1 1170709616 81773760 16929280
05:42:32 1249 8192000000 9994725488 10368450560 1034201440 11.209 100 92 178 2 1115339664 63119616 18018608
05:43:34 1167 8192000000 10003946592 10380308480 1051007568 11.370 100 90 163 3 1125827200 57583744 17235888
05:44:37 1188 8192000000 10023852944 10400964608 1048509728 11.346 100 92 171 2 1141458016 75299312 17648976
05:45:39 1216 8192000000 9945701616 10316582912 1010786928 10.983 98 89 172 3 1071540944 42268576 18485440
05:46:43 5751 8192000000 9969389888 10347991040 1037341696 11.239 100 79 152 3 1097428784 44257968 15829120
05:47:55 1036 8192000000 10024422352 10401685504 1065815392 11.512 100 91 171 0 1140855744 58414144 16626208
05:49:13 1198 8192000000 10049675616 10433744896 1076201888 11.611 100 74 145 0 1165984272 74621776 15160608
05:50:18 8563 8192000000 10077051456 10467831808 1075492752 11.605 99 68 130 1 1192972336 103202512 14277072
05:51:44 4148 8192000000 10089192176 10482720768 1092855584 11.770 100 72 126 3 1202722576 97323024 12543968
05:52:50 1101 8192000000 10131568496 10524585984 1106007808 11.895 100 95 184 2 1249695840 123402784 20285248
05:53:55 9730 8192000000 10174444176 10575446016 1120348224 12.030 100 79 154 1 1299770096 162369088 17052784
05:55:08 1755 8192000000 10173212288 10588086272 1133164368 12.151 100 94 178 1 1286208992 132414128 20630496
05:56:15 1324 8192000000 10174502192 10567512064 1132708080 12.147 100 95 174 1 1289119552 136889504 19521968
05:57:23 1083 8192000000 10233645072 10634264576 1156758928 12.373 100 94 185 2 1333285600 155265136 21261536
05:58:28 1157 8192000000 10273682832 10670632960 1171773200 12.513 100 96 189 1 1384349696 191387280 21189216
05:59:32 1343 8192000000 10302350928 10705854464 1178524048 12.576 100 95 187 2 1412551200 211861344 22165808
06:00:36 804 8192000000 10325571568 10740752384 1194197904 12.722 100 91 176 0 1434386992 220647824 19541264
06:01:39 996 8192000000 10375732592 10785411072 1198652928 12.764 100 98 193 1 1483511232 262656512 22201792
06:02:44 1512 8192000000 10389597856 10801106944 1206380160 12.836 100 93 179 2 1501194448 273762608 21051680
06:03:49 1421 8192000000 10405976096 10829410304 1191843472 12.701 100 93 172 2 1513685216 300936480 20905264
06:04:54 1681 8192000000 10458068960 10871005184 1200976928 12.785 100 92 174 0 1573249344 350293344 21979072
06:06:00 873 8192000000 10458999328 10920263680 1204958192 12.822 100 95 188 0 1564801712 336250208 23593312
06:07:03 1894 8192000000 10508198528 10940391424 1202938064 12.804 100 88 170 1 1615215280 391534608 20742608
06:08:09 1293 8192000000 10529708800 10947104768 1194750416 12.728 99 97 185 0 1633810560 416562656 22497488
06:09:13 2648 8192000000 10518454112 10987450368 1187766480 12.663 99 63 125 0 1614926304 411188160 15971664
06:10:24 8185 8192000000 10555697824 10981748736 1180400080 12.594 100 51 96 0 1662773920 468700128 13673712
06:11:37 11261 8192000000 10536730304 10978992128 1164276496 12.443 98 57 114 1 1629916512 451247904 14392112
06:13:00 6383 8192000000 10544126384 10978553856 1176677648 12.559 100 47 92 0 1650356208 462079888 11598672
06:14:11 2781 8192000000 10569787376 11013369856 1169586512 12.493 100 54 107 1 1679904224 496613760 13703952
06:15:25 16696 8192000000 10443769712 10973806592 1142091008 12.235 99 39 78 0 1588551600 435879120 10581472
06:16:51 57082 8192000000 10255880912 10757120000 1109732736 11.930 96 30 59 0 1398620208 281851984 7035488
06:19:26 6161 8192000000 10112288352 10592247808 1149035792 12.300 100 42 80 2 1218160784 60295616 8829376
06:20:33 18220 8192000000 10109367088 10529292288 1128091536 12.103 99 53 99 0 1214591664 76526752 9973376
06:21:53 34596 8192000000 10099302016 10603540480 1127322576 12.096 100 9 18 0 1240570992 111175024 2073392
06:23:30 19527 8192000000 10091183792 10515578880 1133103072 12.151 100 64 126 0 1189174656 42281520 13790064
06:24:52 58983 8192000000 10080411296 10521370624 1121755504 12.044 99 9 18 0 1170476176 47128976 1591696
06:26:52 72616 8192000000 10078100544 10520096768 1092344896 11.765 96 14 28 0 1138699408 43176272 3178240
06:29:06 154458 8192000000 10074298448 10575818752 1096360560 11.803 97 10 20 1 1139339696 40794336 2184800
06:32:44 144945 8192000000 10088581456 10543792128 1128978464 12.112 99 9 18 1 1186467424 55714688 1774272
06:37:17 64309 8192000000 10115504160 10572566528 1109994400 11.932 97 42 77 1 1156108256 37553216 8560640
06:39:38 57029 8192000000 10098284432 10547408896 1139536112 12.211 99 17 34 1 1205424304 61776928 4111264
06:41:36 34692 8192000000 10084305840 10521358336 1092421680 11.766 96 27 54 2 1162245920 63672672 6151568
06:43:12 11338 8192000000 10126504608 10546577408 1123328096 12.058 99 47 90 0 1210927344 77736944 9862304
06:44:32 56763 8192000000 10114007584 10564182016 1114166864 11.972 98 20 40 0 1203526464 84920032 4439568
06:46:29 41777 8192000000 10124629984 10624843776 1121444864 12.041 99 14 28 1 1236247968 111487856 3315248
06:48:11 46512 8192000000 10105218656 10573606912 1113213392 11.963 98 23 46 2 1205314528 86669728 5431408
06:49:58 58939 8192000000 10094017744 10537177088 1121627408 12.042 98 9 18 0 1168258672 44474000 2157264
06:51:57 59961 8192000000 10070342512 10529722368 1119141280 12.019 98 28 56 1 1159335088 34379552 5814256
06:54:04 9916 8192000000 10132371504 10556858368 1141658000 12.231 100 67 123 2 1220333520 66520192 12155328
06:55:19 28879 8192000000 10094456832 10558337024 1097994688 11.819 95 26 49 3 1152662416 49685008 4982720
06:57:58 49261 8192000000 10248931856 10698285056 1147917808 12.290 99 25 50 1 1306653616 153286944 5448864
06:59:58 65196 8192000000 10080220944 10515148800 1088595296 11.729 95 18 35 0 1152919136 60253008 4070832
07:02:08 20389 8192000000 10085648688 10517274624 1129856416 12.120 100 41 81 2 1185419216 47600096 7962704
07:03:30 122428 8192000000 10102239088 10582917120 1130076304 12.122 99 37 74 1 1208091136 69384320 8630512
07:06:38 63007 8192000000 10068902496 10542563328 1128517232 12.107 99 17 33 0 1173544784 41440912 3586640
07:08:42 35514 8192000000 10067444688 10515369984 1134241504 12.161 100 33 63 1 1166887392 25939056 6706832
07:10:17 62123 8192000000 10085901392 10534121472 1114920992 11.979 98 26 51 1 1158393840 38207056 5265792
07:12:35 68322 8192000000 10131664704 10607013888 1136407824 12.182 99 18 36 2 1219131168 78801920 3921424
07:14:43 73477 8192000000 10127867200 10586341376 1127758576 12.100 99 25 49 0 1237617072 104706192 5152304
07:17:31 85239 8192000000 10092693920 10542575616 1104021088 11.876 97 16 32 0 1149296384 41853840 3421456
07:19:57 53945 8192000000 10083857632 10566574080 1093094288 11.772 96 21 42 3 1160528480 62827776 4606416
07:21:52 35079 8192000000 10147496480 10593538048 1133001312 12.150 99 27 54 0 1223213264 84077840 6134112
07:23:36 78401 8192000000 10077396208 10576187392 1120981664 12.036 99 13 26 0 1198844816 74616960 3246192
07:25:58 8972 8192000000 10109899696 10542624768 1143042192 12.244 100 52 104 0 1192035984 38784368 10209424
07:27:12 7012 8192000000 10118760224 10540253184 1129091152 12.113 100 65 130 1 1193020000 51019440 12909408
07:28:25 57457 8192000000 10108638688 10576113664 1143482912 12.248 100 24 48 1 1210233280 61985648 4764720
07:31:04 96329 8192000000 10128926736 10597482496 1130884416 12.130 98 34 68 0 1210079120 72241344 6953360
07:33:44 61476 8192000000 10086443968 10590339072 1104228000 11.878 97 11 22 0 1190917648 84069424 2620224
07:35:47 105309 8192000000 10086988304 10559176704 1120707680 12.034 98 23 44 1 1162994528 37955472 4331376
07:38:36 73942 8192000000 10106361616 10595217408 1151543952 12.324 100 14 28 1 1223958816 69137056 3277808
07:40:50 42265 8192000000 10061090384 10534555648 1106466864 11.899 98 22 44 3 1169666176 58542928 4656384
07:42:44 12262 8192000000 10084586304 10514677760 1137301344 12.190 100 55 108 1 1180270336 32101728 10867264
07:43:59 32832 8192000000 10144578480 10590941184 1118192384 12.010 97 31 62 3 1214363920 89432688 6738848
07:45:38 48215 8192000000 10166125824 10607480832 1122231952 12.048 98 24 48 1 1246394064 118886784 5275328

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 68 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-04-2022 07:48:57 to 06-04-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          214875
MON$OLDEST_ACTIVE               220563
MON$OLDEST_SNAPSHOT             220563
MON$NEXT_TRANSACTION            220563
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                       2076320
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             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2750 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2750 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2750 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2750 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    Wed Apr  6 07:46:59 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Wed Apr  6 07:47:08 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Wed Apr  6 07:47:09 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'

06.04.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-04-06 07:49:02 No severe PSQL-related problems occured
06.04.22 07:49:04. End of report.