Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.11.2021 22:39
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 16803540 kB
MemAvailable 31613696 kB
Buffers 0 kB
Cached 14902828 kB
SwapCached 0 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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 2021-11-19 22:37:54
DTS_END                         2021-11-19 22:37:54.5450
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/56614
TRN_ID                          242954
ATT_ID                          729
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: 9277.83 20159 1113340
customer order: creation 179.06 340 21487
customer order: refuse 15.33 329 1839
order to supplier: creation 46.58 1258 5590
order to supplier: removal 3.36 84913 403
invoice (draft): creation 47.11 3185 5653
invoice (draft): removal 50.81 683 6097
invoice accept: apply 43.78 18221 5253
invoice accept: cancel 46.33 679 5559
customer reserve: creation 3986.98 184 478437
customer reserve: removal 4553.87 49 546464
realization accept: apply 84.37 162 10124
realization accept: cancel 89.37 99 10724
payment from customer: creation 59.82 108 7178
payment from customer: removal 5.28 69 634
payment to supplier: creation 49.92 123 5990
payment to supplier: removal 3.43 92 412
service: total inventory turnovers 5.03 943 603
service: total monetary turnovers 6.49 23 779
service: aggregate perf. data 0.92 18493 110
service: refresh index statistics 0.03 273219 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-11-2021 22:39:55 to 19-11-2021 22:39:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2087
TEST_TIME 2 2465
TEST_TIME 3 3610
TEST_TIME 4 4333
TEST_TIME 5 4553
TEST_TIME 6 4492
TEST_TIME 7 5139
TEST_TIME 8 5929
TEST_TIME 9 6365
TEST_TIME 10 6472
TEST_TIME 11 6988
TEST_TIME 12 7409
TEST_TIME 13 7930
TEST_TIME 14 8090
TEST_TIME 15 8465
TEST_TIME 16 8356
TEST_TIME 17 8622
TEST_TIME 18 8565
TEST_TIME 19 8635
TEST_TIME 20 8578
TEST_TIME 21 8536
TEST_TIME 22 8712
TEST_TIME 23 8888
TEST_TIME 24 8832
TEST_TIME 25 8908
TEST_TIME 26 9420
TEST_TIME 27 9366
TEST_TIME 28 9723
TEST_TIME 29 9912
TEST_TIME 30 10024
TEST_TIME 31 9882
TEST_TIME 32 9922
TEST_TIME 33 9793
TEST_TIME 34 10148
TEST_TIME 35 10043
TEST_TIME 36 9919
TEST_TIME 37 9782
TEST_TIME 38 10011
TEST_TIME 39 10042
TEST_TIME 40 10036
TEST_TIME 41 9951
TEST_TIME 42 9790
TEST_TIME 43 9607
TEST_TIME 44 9728
TEST_TIME 45 9676
TEST_TIME 46 9552
TEST_TIME 47 9548
TEST_TIME 48 9481
TEST_TIME 49 9419
TEST_TIME 50 9493
TEST_TIME 51 9483
TEST_TIME 52 9460
TEST_TIME 53 9403
TEST_TIME 54 9391
TEST_TIME 55 9382
TEST_TIME 56 9346
TEST_TIME 57 9337
TEST_TIME 58 9224
TEST_TIME 59 9177
TEST_TIME 60 9184
TEST_TIME 61 9146
TEST_TIME 62 9089
TEST_TIME 63 9200
TEST_TIME 64 9225
TEST_TIME 65 9262
TEST_TIME 66 9352
TEST_TIME 67 9417
TEST_TIME 68 9398
TEST_TIME 69 9426
TEST_TIME 70 9428
TEST_TIME 71 9510
TEST_TIME 72 9547
TEST_TIME 73 9627
TEST_TIME 74 9595
TEST_TIME 75 9606
TEST_TIME 76 9546
TEST_TIME 77 9513
TEST_TIME 78 9468
TEST_TIME 79 9409
TEST_TIME 80 9409
TEST_TIME 81 9420
TEST_TIME 82 9339
TEST_TIME 83 9326
TEST_TIME 84 9300
TEST_TIME 85 9251
TEST_TIME 86 9292
TEST_TIME 87 9264
TEST_TIME 88 9264
TEST_TIME 89 9261
TEST_TIME 90 9280
TEST_TIME 91 9367
TEST_TIME 92 9373
TEST_TIME 93 9346
TEST_TIME 94 9433
TEST_TIME 95 9439
TEST_TIME 96 9414
TEST_TIME 97 9408
TEST_TIME 98 9404
TEST_TIME 99 9390
TEST_TIME 100 9332
TEST_TIME 101 9339
TEST_TIME 102 9316
TEST_TIME 103 9280
TEST_TIME 104 9218
TEST_TIME 105 9220
TEST_TIME 106 9171
TEST_TIME 107 9204
TEST_TIME 108 9205
TEST_TIME 109 9223
TEST_TIME 110 9195
TEST_TIME 111 9189
TEST_TIME 112 9194
TEST_TIME 113 9212
TEST_TIME 114 9228
TEST_TIME 115 9265
TEST_TIME 116 9238
TEST_TIME 117 9312
TEST_TIME 118 9285
TEST_TIME 119 9337
TEST_TIME 120 9282

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-11-2021 22:39:58 to 19-11-2021 22:40:02.

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 1555726 1555726 0 0.00 0 29401 6 0 0
doc_list_biud 1555725 1555725 0 0.00 0 46242 22 0 0
sp_add_invoice_to_stock 5376 5253 123 2.28 20 167220 18222 0 123
sp_cancel_adding_invoice 5559 5559 0 0.00 5 50854 680 0 0
sp_cancel_client_order 1839 1839 0 0.00 4 7786 329 0 0
sp_cancel_customer_reserve 546464 546464 0 0.00 1 46244 49 0 0
sp_cancel_pay_from_customer 634 634 0 0.00 3 6758 70 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 3 7592 93 0 0
sp_cancel_supplier_invoice 6105 6097 8 0.13 2 19572 683 0 8
sp_cancel_supplier_order 403 403 0 0.00 7 795819 84914 0 0
sp_cancel_write_off 10724 10724 0 0.00 1 12016 99 0 0
sp_client_order 21487 21487 0 0.00 3 57366 340 0 0
sp_customer_reserve 478437 478437 0 0.00 0 111542 184 0 0
sp_fill_shopping_cart 32724 32724 0 0.00 1 8290 35 0 0
sp_fill_shopping_cart_clo_res 478434 478434 0 0.00 0 22560 14 0 0
sp_get_clo_for_invoice 5250 5250 0 0.00 0 22512 285 0 0
sp_kill_cost_storno 17329 17329 0 0.00 0 16892 48 0 0
sp_kill_qstorno_ret_qs2qd 1110152 1110152 0 0.00 0 46238 27 0 0
sp_kill_qty_storno 586460 586460 0 0.00 0 46242 58 0 0
sp_lock_selected_doc 21154 21154 0 0.00 0 10742 8 0 0
sp_make_cost_storno 28542 28542 0 0.00 0 29341 54 0 0
sp_multiply_rows_for_pdistr 28542 28542 0 0.00 0 5352 24 0 0
sp_multiply_rows_for_qdistr 21487 21487 0 0.00 0 57357 152 0 0
sp_pay_from_customer 7178 7178 0 0.00 4 18810 109 0 0
sp_pay_to_supplier 5990 5990 0 0.00 4 15640 124 0 0
sp_payment_common 13168 13168 0 0.00 2 9185 23 0 0
sp_qd_handle_on_cancel_clo 1839 1839 0 0.00 0 7778 302 0 0
sp_qd_handle_on_invoice_upd_sts 10809 10809 0 0.00 0 15266 295 0 0
sp_qd_handle_on_reserve_upd_sts 10124 10124 0 0.00 0 9658 50 0 0
sp_reserve_write_off 10124 10124 0 0.00 6 15957 163 0 0
sp_supplier_invoice 5653 5653 0 0.00 8 81205 3186 0 0
sp_supplier_order 5590 5590 0 0.00 6 47220 1259 0 0
srv_aggregate_perf_data 110 110 0 0.00 2684 229691 18494 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1368 121042 44070 0 0
srv_find_qd_qs_mism 1063989 1063989 0 0.00 0 16831 5 0 0
srv_make_invnt_saldo 603 603 0 0.00 37 18508 943 0 0
srv_make_money_saldo 779 779 0 0.00 0 3138 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 81933 376926 273220 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5254 5254 0 0.00 0 14650 173 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 0 2344 88 0 0
t$perf-norm:sp_cancel_client_order 1839 1839 0 0.00 0 3086 13 0 0
t$perf-norm:sp_cancel_customer_reserve 669 669 0 0.00 0 1452 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 634 634 0 0.00 0 2549 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 1190 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 431 431 0 0.00 0 699 10 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 13449 1352 0 0
t$perf-norm:sp_cancel_write_off 991 991 0 0.00 0 3989 15 0 0
t$perf-norm:sp_client_order 21487 21487 0 0.00 0 7540 17 0 0
t$perf-norm:sp_customer_reserve 1070 1070 0 0.00 0 4596 11 0 0
t$perf-norm:sp_pay_from_customer 7178 7178 0 0.00 0 4834 9 0 0
t$perf-norm:sp_pay_to_supplier 5990 5990 0 0.00 0 9357 9 0 0
t$perf-norm:sp_reserve_write_off 10124 10124 0 0.00 0 4373 10 0 0
t$perf-norm:sp_supplier_invoice 5653 5653 0 0.00 0 8419 21 0 0
t$perf-norm:sp_supplier_order 5590 5590 0 0.00 0 3696 12 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 464 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 241 9 0 0
t$perf-norm:srv_make_invnt_saldo 603 603 0 0.00 0 6016 28 0 0
t$perf-norm:srv_make_money_saldo 779 779 0 0.00 0 4582 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 4 0 0
v_add_invoice_to_stock 10746 10500 246 2.28 0 8255 17 0 246
v_all_customers 39094 39094 0 0.00 0 18 0 0 0
v_all_suppliers 30640 30640 0 0.00 0 54 1 0 0
v_all_wares 42974 42974 0 0.00 0 172 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 706 5 0 0
v_cancel_client_order 3678 3678 0 0.00 0 1693 4 0 0
v_cancel_customer_prepayment 1268 1268 0 0.00 0 1713 6 0 0
v_cancel_customer_reserve 1338 1338 0 0.00 0 2258 4 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 1406 5 0 0
v_cancel_supplier_invoice 878 862 16 1.82 0 1896 17 0 16
v_cancel_supplier_order 806 806 0 0.00 0 1708 5 0 0
v_cancel_write_off 1982 1982 0 0.00 0 5294 15 0 0
v_max_id_clo_ord 5590 5590 0 0.00 0 2333 2 0 0
v_max_id_clo_res 1070 1070 0 0.00 0 3211 14 0 0
v_max_id_ord_sup 5647 5647 0 0.00 0 5191 3 0 0
v_max_non_paid_invoice 1907 1907 0 0.00 0 894 2 0 0
v_max_non_paid_realizn 3752 3752 0 0.00 0 3293 6 0 0
v_min_id_clo_ord 5590 5590 0 0.00 0 4388 6 0 0
v_min_id_clo_res 1070 1070 0 0.00 0 4054 12 0 0
v_min_id_ord_sup 5647 5647 0 0.00 0 2599 5 0 0
v_min_non_paid_invoice 5990 5990 0 0.00 0 4984 13 0 0
v_min_non_paid_realizn 7178 7178 0 0.00 0 5266 16 0 0
v_our_firm 10732 10732 0 0.00 0 11 1 0 0
v_reserve_write_off 20248 20248 0 0.00 0 13964 6 0 0
x_lock_depdocs_on_canc_invoice 11225 11225 0 0.00 0 11483 99 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 10047 311 0 0
x_make_qstorno_1000_1200 5590 5590 0 0.00 1 47209 1180 0 0
x_make_qstorno_1200_2000 5653 5653 0 0.00 4 81198 3063 0 0
x_make_qstorno_2100_3300 478437 478437 0 0.00 0 111538 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-11-2021 22:40:03 to 19-11-2021 22:40:07.

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
20:07:58 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:08:59 28994 8192000000 9905403696 10221780992 993801168 10.818 100 85 170 0 1021853328 6130896 21921264
20:10:31 20677 8192000000 9951914336 10276540416 1035869248 11.225 100 90 180 1 1067989392 9494672 22625472
20:11:57 20153 8192000000 10001701952 10337460224 1077159632 11.620 100 84 167 1 1107677712 10933968 19584112
20:13:22 14713 8192000000 10059459872 10409320448 1138933120 12.205 100 88 177 1 1172534752 12415392 21186240
20:14:40 8768 8192000000 10096933440 10457448448 1175134240 12.545 100 78 154 1 1204825760 12561904 17129616
20:15:50 4725 8192000000 10134535584 10503094272 1219237968 12.955 100 82 160 1 1251089408 13854480 17996960
20:16:57 2633 8192000000 10165006528 10541441024 1248414432 13.224 100 88 173 4 1279705968 12257168 19034368
20:18:01 2804 8192000000 10194357088 10576072704 1279963936 13.513 100 93 184 2 1315429952 14302912 21163104
20:19:06 1733 8192000000 10220987312 10608054272 1303212416 13.724 100 93 184 1 1337568352 14217232 20138704
20:20:08 10650 8192000000 10215694128 10611826688 1306968928 13.759 100 73 147 1 1331236992 7587248 16680816
20:21:20 5625 8192000000 10210815392 10625847296 1321687632 13.892 100 35 68 4 1336385552 6941232 7756688
20:22:28 48879 8192000000 10219331472 10635280384 1326807584 13.938 100 39 79 0 1342435872 6413632 9214656
20:24:18 4739 8192000000 10223780624 10643697664 1334187680 14.005 100 23 46 0 1345361696 6049568 5124448
20:25:28 5697 8192000000 10229990016 10646740992 1335804800 14.020 100 24 47 2 1347592240 6968896 4818544
20:26:38 42561 8192000000 10250602848 10652389376 1337635280 14.036 100 52 103 1 1356245264 5479504 13130480
20:28:21 5906 8192000000 10196309584 10614734848 1281816144 13.530 97 29 59 2 1294595952 6217904 6561904
20:29:27 8196 8192000000 10158843200 10564001792 1232980384 13.082 96 23 46 3 1244319536 5937856 5401296
20:30:37 51485 8192000000 10129561696 10528608256 1184094304 12.628 94 36 72 3 1198548496 6261344 8192848
20:32:36 3351 8192000000 10155356448 10543173632 1248882128 13.228 99 53 108 1 1267383008 6321744 12179136
20:33:41 10178 8192000000 10176338608 10577137664 1278198736 13.497 100 23 46 1 1289125376 4981216 5945424
20:34:53 6413 8192000000 10196909840 10602270720 1291244400 13.616 100 26 52 3 1306715024 9918400 5552224
20:36:05 36693 8192000000 10192538096 10611060736 1305512656 13.745 100 23 46 0 1320925648 9844400 5568592
20:37:42 8875 8192000000 10214178688 10626965504 1312935664 13.813 99 21 42 0 1322241008 4522336 4783008
20:39:01 16313 8192000000 10247257984 10658852864 1344589936 14.099 100 45 88 0 1362902448 8339968 9972544
20:40:22 24370 8192000000 10263170864 10679832576 1361362480 14.250 100 34 68 1 1379126576 9964832 7799264
20:41:49 3437 8192000000 10273268160 10679885824 1355175376 14.194 99 75 150 1 1384947616 12060064 17712176
20:43:11 21458 8192000000 10276985968 10705141760 1376894192 14.389 100 26 52 3 1396996080 13450672 6651216
20:44:36 2534 8192000000 10324243312 10735792128 1392399376 14.527 100 86 171 4 1427079328 14927088 19752864
20:45:43 14381 8192000000 10306614784 10735120384 1392493536 14.528 100 41 81 1 1421084928 18518848 10072544
20:47:08 6799 8192000000 10321219904 10746974208 1402513696 14.617 100 60 119 2 1429091440 11698496 14879248
20:48:25 1746 8192000000 10325223744 10742775808 1397276896 14.571 100 81 162 3 1430731600 14779376 18675328
20:49:31 6620 8192000000 10318431248 10737889280 1390726816 14.512 100 68 136 2 1422470992 16302000 15442176
20:50:41 11983 8192000000 10318733696 10741198848 1390610528 14.511 100 62 124 0 1424184400 19558096 14015776
20:51:56 1785 8192000000 10331916304 10752458752 1402195744 14.615 100 76 152 0 1441260016 21322464 17741808
20:53:03 23331 8192000000 10289721616 10718412800 1332134608 13.986 96 17 34 2 1358175408 21842560 4198240
20:54:36 12461 8192000000 10279956512 10707316736 1362707168 14.262 100 27 54 0 1389974064 19963744 7303152
20:55:53 1366 8192000000 10281221632 10690756608 1344240640 14.096 100 57 114 2 1383065248 24965632 13858976
20:56:58 14152 8192000000 10272136256 10687762432 1332514128 13.990 99 35 70 0 1369987760 28454592 9019040
20:58:22 3114 8192000000 10290889664 10703777792 1352234448 14.168 100 61 122 0 1397451248 30517600 14699200
20:59:29 2877 8192000000 10298969600 10707210240 1338658336 14.045 99 66 130 0 1393481344 38099776 16723232
21:00:39 11891 8192000000 10308612288 10730749952 1357443664 14.214 99 35 70 0 1404714048 37952432 9317952
21:02:00 16940 8192000000 10322414384 10731397120 1359953008 14.237 99 69 139 2 1415682112 37172976 18556128
21:03:23 46135 8192000000 10277524848 10713501696 1353978416 14.183 100 30 58 2 1391622224 30394000 7249808
21:05:13 20663 8192000000 10267673824 10711572480 1337089008 14.031 99 25 50 3 1375167248 31667040 6411200
21:06:44 62830 8192000000 10258758416 10709495808 1320460992 13.881 98 9 18 0 1344977472 22729040 1787440
21:08:52 46375 8192000000 10289093376 10727383040 1368430528 14.313 100 19 38 0 1390379584 17747568 4201488
21:10:39 105266 8192000000 10286627904 10744864768 1360785264 14.244 99 10 20 0 1382893152 20342928 1764960
21:13:24 37667 8192000000 10298034752 10738032640 1371531376 14.341 99 20 40 0 1392482112 16373264 4577472
21:15:23 63520 8192000000 10275026656 10730250240 1356177808 14.203 99 9 18 0 1379719264 21266416 2275040
21:17:31 13157 8192000000 10293768016 10729811968 1380413664 14.420 100 42 84 0 1397077216 6676112 9987440
21:18:44 13793 8192000000 10308533440 10737909760 1385459104 14.465 100 45 89 0 1403902512 7794032 10649376
21:20:01 95256 8192000000 10303137008 10751275008 1369708368 14.324 98 36 71 2 1390663168 12887424 8067376
21:23:29 61505 8192000000 10299069072 10749784064 1384611488 14.458 100 15 29 0 1400628560 12766912 3250160
21:25:31 17578 8192000000 10317038864 10762821632 1400684448 14.601 100 48 94 0 1424423328 13458384 10280496
21:26:55 29700 8192000000 10306644240 10762944512 1396346544 14.562 100 22 44 0 1416953664 15717072 4890048
21:28:31 60827 8192000000 10306879312 10774679552 1400593808 14.600 100 14 28 1 1419776688 15637872 3545008
21:30:33 54834 8192000000 10296036528 10752978944 1384565280 14.457 99 19 37 0 1401022736 11478832 4978624
21:32:42 52864 8192000000 10284534304 10747830272 1379474976 14.412 99 22 43 0 1393751472 9559904 4716592
21:35:00 69913 8192000000 10293917632 10750857216 1390860608 14.514 100 12 24 1 1399880384 6496720 2523056
21:37:28 69061 8192000000 10283445072 10753830912 1332509920 13.990 95 6 12 0 1343453280 10001840 941520
21:39:38 54012 8192000000 10283186560 10745176064 1346699184 14.118 96 18 36 0 1361286768 10682896 3904688
21:41:39 34373 8192000000 10269602288 10723930112 1347721232 14.127 98 27 54 1 1366994496 13502512 5770752
21:43:14 23649 8192000000 10273105120 10727997440 1338260864 14.042 98 34 68 3 1362680800 16520768 7899168
21:44:39 107929 8192000000 10271209312 10718232576 1290049856 13.605 95 18 36 1 1308532240 14183168 4299216
21:47:27 87177 8192000000 10269218848 10725326848 1292694800 13.629 95 29 57 2 1314660160 14549968 7415392
21:49:55 82408 8192000000 10249338160 10699923456 1280651280 13.519 94 7 14 1 1299057248 16744320 1661648
21:52:18 63448 8192000000 10278528112 10708312064 1317293520 13.852 97 38 76 0 1337106816 10802880 9010416
21:54:26 74169 8192000000 10256982832 10692960256 1329420464 13.962 98 20 40 0 1343461728 9674928 4366336
21:56:41 22790 8192000000 10282402016 10721312768 1362250240 14.258 100 51 102 0 1386131632 12441696 11439696
21:58:07 68589 8192000000 10268590480 10732990464 1371553184 14.341 100 10 20 0 1385247680 11382256 2312240
22:00:16 59437 8192000000 10268436288 10728226816 1339946288 14.057 97 12 24 0 1354976608 12377248 2653072
22:02:16 46018 8192000000 10266195632 10732838912 1356708352 14.208 99 19 38 1 1374222352 13130192 4383808
22:04:03 69962 8192000000 10278342832 10732953600 1333612208 14.000 97 15 30 0 1346197328 9570592 3014528
22:06:14 52215 8192000000 10289577904 10737913856 1372642608 14.351 100 28 56 0 1393270080 14463776 6163696
22:08:19 88993 8192000000 10295397216 10757591040 1359558992 14.233 98 12 24 0 1378642720 16486896 2596832
22:10:53 104297 8192000000 10287142800 10749673472 1349103152 14.139 97 14 28 1 1373429168 21365216 2960800
22:13:38 42968 8192000000 10317151456 10752905216 1364546016 14.278 98 36 71 0 1381263552 8861248 7856288
22:15:23 32189 8192000000 10303000320 10752466944 1374328064 14.366 99 39 78 0 1395584816 12935024 8321728
22:17:01 121042 8192000000 10292406464 10757459968 1389869936 14.505 100 30 60 1 1411447168 14540448 7036784
22:20:09 60189 8192000000 10282027136 10751598592 1365366240 14.286 98 6 12 1 1378309136 11674336 1268560
22:22:09 50581 8192000000 10287688096 10756214784 1387907440 14.487 100 14 25 1 1401809904 10758880 3143584
22:24:26 60693 8192000000 10274439056 10751406080 1351691968 14.163 98 16 32 0 1373188496 18037328 3459200
22:26:37 34337 8192000000 10272533472 10733584384 1366740272 14.298 99 12 24 0 1378996288 9449696 2806320
22:28:12 108472 8192000000 10292883856 10752778240 1323967328 13.913 95 15 30 0 1340965952 13307440 3691184
22:31:01 83531 8192000000 10295085168 10739605504 1316662336 13.846 95 16 32 0 1334263072 13987824 3612912
22:33:25 41107 8192000000 10275504448 10727948288 1325880144 13.930 97 28 56 0 1352132992 19959088 6293760
22:35:07 28120 8192000000 10261322944 10700443648 1290981936 13.613 95 20 40 1 1302716864 7414736 4320192
22:36:44 43258 8192000000 10271726224 10706792448 1317948112 13.858 97 26 55 0 1335221600 11127152 6146336

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 19-11-2021 22:40:09 to 19-11-2021 22:40:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-11-2021 22:40:24 to 19-11-2021 22:40:26.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          242384
MON$OLDEST_ACTIVE               244185
MON$OLDEST_SNAPSHOT             244185
MON$NEXT_TRANSACTION            244185
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-03 16:41:55.3470
MON$PAGES                       2035168
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:28: 
:29: mscs-test-161.budzdorov.ru    Fri Nov 19 22:38:35 2021
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:31: 
:32: 
:33: mscs-test-161.budzdorov.ru    Fri Nov 19 22:38:36 2021
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:35: 
--- end of diff output ---

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

19.11.21 22:40:27. There are 3 logs in /var/tmp/logs-oltp30 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
2021-11-19 22:40:30 No severe PSQL-related problems occured
19.11.21 22:40:31. End of report.