Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.05.2022 16: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 16236984 kB
MemAvailable 31438164 kB
Buffers 0 kB
Cached 15448728 kB
SwapCached 2652 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.10
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 2022-05-15 16:38:19
DTS_END                         2022-05-15 16:38:19.0690
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/43508
TRN_ID                          241750
ATT_ID                          707
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: 9306.70 22771 1116804
customer order: creation 178.31 305 21397
customer order: refuse 14.54 371 1745
order to supplier: creation 47.49 1284 5699
order to supplier: removal 3.34 119378 401
invoice (draft): creation 45.93 3740 5511
invoice (draft): removal 49.15 1022 5898
invoice accept: apply 43.67 18816 5240
invoice accept: cancel 45.08 947 5410
customer reserve: creation 4160.95 179 499314
customer reserve: removal 4417.68 70 530122
realization accept: apply 86.56 186 10387
realization accept: cancel 80.27 128 9632
payment from customer: creation 60.78 112 7293
payment from customer: removal 5.75 102 690
payment to supplier: creation 50.78 130 6094
payment to supplier: removal 3.63 68 436
service: total inventory turnovers 4.88 1306 585
service: total monetary turnovers 7.12 45 854
service: aggregate perf. data 0.77 22888 92
service: refresh index statistics 0.03 284336 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 15-05-2022 16:39:20 to 15-05-2022 16:39:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2885
TEST_TIME 2 5359
TEST_TIME 3 6358
TEST_TIME 4 5865
TEST_TIME 5 6018
TEST_TIME 6 6126
TEST_TIME 7 6604
TEST_TIME 8 6708
TEST_TIME 9 6970
TEST_TIME 10 6899
TEST_TIME 11 6665
TEST_TIME 12 6796
TEST_TIME 13 6890
TEST_TIME 14 6928
TEST_TIME 15 7013
TEST_TIME 16 7558
TEST_TIME 17 8259
TEST_TIME 18 8115
TEST_TIME 19 8150
TEST_TIME 20 8064
TEST_TIME 21 8082
TEST_TIME 22 8335
TEST_TIME 23 8608
TEST_TIME 24 8838
TEST_TIME 25 9304
TEST_TIME 26 9435
TEST_TIME 27 9270
TEST_TIME 28 9498
TEST_TIME 29 9433
TEST_TIME 30 10882
TEST_TIME 31 12053
TEST_TIME 32 11710
TEST_TIME 33 11499
TEST_TIME 34 11508
TEST_TIME 35 11357
TEST_TIME 36 11331
TEST_TIME 37 11172
TEST_TIME 38 10923
TEST_TIME 39 10872
TEST_TIME 40 10689
TEST_TIME 41 10661
TEST_TIME 42 10467
TEST_TIME 43 10356
TEST_TIME 44 10231
TEST_TIME 45 10260
TEST_TIME 46 10261
TEST_TIME 47 10205
TEST_TIME 48 10117
TEST_TIME 49 10139
TEST_TIME 50 10048
TEST_TIME 51 9974
TEST_TIME 52 9957
TEST_TIME 53 10110
TEST_TIME 54 9987
TEST_TIME 55 9964
TEST_TIME 56 9872
TEST_TIME 57 9753
TEST_TIME 58 9687
TEST_TIME 59 9637
TEST_TIME 60 9558
TEST_TIME 61 9560
TEST_TIME 62 9528
TEST_TIME 63 9505
TEST_TIME 64 9484
TEST_TIME 65 9483
TEST_TIME 66 9518
TEST_TIME 67 9543
TEST_TIME 68 9539
TEST_TIME 69 9575
TEST_TIME 70 9480
TEST_TIME 71 9572
TEST_TIME 72 9575
TEST_TIME 73 9560
TEST_TIME 74 9614
TEST_TIME 75 9644
TEST_TIME 76 9620
TEST_TIME 77 9633
TEST_TIME 78 9577
TEST_TIME 79 9496
TEST_TIME 80 9474
TEST_TIME 81 9477
TEST_TIME 82 9474
TEST_TIME 83 9471
TEST_TIME 84 9424
TEST_TIME 85 9409
TEST_TIME 86 9359
TEST_TIME 87 9327
TEST_TIME 88 9311
TEST_TIME 89 9326
TEST_TIME 90 9344
TEST_TIME 91 9311
TEST_TIME 92 9310
TEST_TIME 93 9405
TEST_TIME 94 9416
TEST_TIME 95 9413
TEST_TIME 96 9481
TEST_TIME 97 9453
TEST_TIME 98 9431
TEST_TIME 99 9401
TEST_TIME 100 9508
TEST_TIME 101 9534
TEST_TIME 102 9560
TEST_TIME 103 9565
TEST_TIME 104 9558
TEST_TIME 105 9511
TEST_TIME 106 9470
TEST_TIME 107 9476
TEST_TIME 108 9480
TEST_TIME 109 9441
TEST_TIME 110 9419
TEST_TIME 111 9441
TEST_TIME 112 9395
TEST_TIME 113 9386
TEST_TIME 114 9339
TEST_TIME 115 9328
TEST_TIME 116 9328
TEST_TIME 117 9326
TEST_TIME 118 9348
TEST_TIME 119 9369
TEST_TIME 120 9308

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-05-2022 16:39:24 to 15-05-2022 16:39:27.

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 1574003 1574003 0 0.00 0 15001 7 0 0
doc_list_biud 1574003 1574003 0 0.00 0 80433 30 0 0
sp_add_invoice_to_stock 5404 5240 164 3.03 33 146438 18817 0 164
sp_cancel_adding_invoice 5410 5410 0 0.00 6 70793 947 0 0
sp_cancel_client_order 1745 1745 0 0.00 4 11791 372 0 0
sp_cancel_customer_reserve 530122 530122 0 0.00 1 18338 71 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 2 6143 102 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 3 2158 69 0 0
sp_cancel_supplier_invoice 5911 5898 13 0.21 2 80448 1022 0 13
sp_cancel_supplier_order 401 401 0 0.00 9 1162185 119378 0 0
sp_cancel_write_off 9632 9632 0 0.00 1 12364 129 0 0
sp_client_order 21397 21397 0 0.00 3 15403 305 0 0
sp_customer_reserve 499314 499314 0 0.00 0 48164 179 0 0
sp_fill_shopping_cart 32597 32597 0 0.00 1 24652 46 0 0
sp_fill_shopping_cart_clo_res 499306 499306 0 0.00 0 14792 13 0 0
sp_get_clo_for_invoice 5232 5232 0 0.00 0 34261 345 0 0
sp_kill_cost_storno 16168 16168 0 0.00 0 8080 68 0 0
sp_kill_qstorno_ret_qs2qd 1076175 1076175 0 0.00 0 80425 38 0 0
sp_kill_qty_storno 568827 568827 0 0.00 0 80431 82 0 0
sp_lock_selected_doc 21494 21494 0 0.00 0 8469 6 0 0
sp_make_cost_storno 29006 29006 0 0.00 0 14865 61 0 0
sp_multiply_rows_for_pdistr 29006 29006 0 0.00 0 8063 31 0 0
sp_multiply_rows_for_qdistr 21397 21397 0 0.00 0 10655 127 0 0
sp_pay_from_customer 7293 7293 0 0.00 4 8570 112 0 0
sp_pay_to_supplier 6094 6094 0 0.00 5 14849 130 0 0
sp_payment_common 13387 13387 0 0.00 1 14793 33 0 0
sp_qd_handle_on_cancel_clo 1745 1745 0 0.00 0 11754 335 0 0
sp_qd_handle_on_invoice_upd_sts 10642 10642 0 0.00 0 25723 367 0 0
sp_qd_handle_on_reserve_upd_sts 10387 10387 0 0.00 0 12737 60 0 0
sp_reserve_write_off 10387 10387 0 0.00 6 14769 187 0 0
sp_supplier_invoice 5511 5511 0 0.00 6 105557 3740 0 0
sp_supplier_order 5699 5699 0 0.00 5 33533 1285 0 0
srv_aggregate_perf_data 93 92 1 1.07 2796 296085 22889 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1565 144882 48311 0 0
srv_find_qd_qs_mism 1064166 1064166 0 0.00 0 15000 6 0 0
srv_make_invnt_saldo 585 585 0 0.00 29 186713 1306 0 0
srv_make_money_saldo 854 854 0 0.00 1 7476 46 0 0
srv_recalc_idx_stat 4 4 0 0.00 145888 478927 284336 0 0
t$perf-abend:sp_add_invoice_to_stock 164 164 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5240 5240 0 0.00 0 17564 178 0 0
t$perf-norm:sp_cancel_adding_invoice 439 439 0 0.00 0 5249 109 0 0
t$perf-norm:sp_cancel_client_order 1745 1745 0 0.00 0 2823 16 0 0
t$perf-norm:sp_cancel_customer_reserve 701 701 0 0.00 0 2458 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 3161 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 2667 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 442 442 0 0.00 0 3516 25 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 20503 1656 0 0
t$perf-norm:sp_cancel_write_off 1021 1021 0 0.00 0 730 4 0 0
t$perf-norm:sp_client_order 21397 21397 0 0.00 0 11080 18 0 0
t$perf-norm:sp_customer_reserve 1084 1084 0 0.00 0 9562 14 0 0
t$perf-norm:sp_pay_from_customer 7293 7293 0 0.00 0 13977 14 0 0
t$perf-norm:sp_pay_to_supplier 6094 6094 0 0.00 0 8124 11 0 0
t$perf-norm:sp_reserve_write_off 10387 10387 0 0.00 0 5418 10 0 0
t$perf-norm:sp_supplier_invoice 5511 5511 0 0.00 0 4796 23 0 0
t$perf-norm:sp_supplier_order 5699 5699 0 0.00 0 4699 19 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 833 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 154 3 0 0
t$perf-norm:srv_make_invnt_saldo 585 585 0 0.00 0 4318 32 0 0
t$perf-norm:srv_make_money_saldo 854 854 0 0.00 0 2191 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10792 10464 328 3.03 0 5570 16 0 328
v_all_customers 39132 39132 0 0.00 0 15 1 0 0
v_all_suppliers 31000 31000 0 0.00 0 20 1 0 0
v_all_wares 42794 42794 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 878 878 0 0.00 0 1252 8 0 0
v_cancel_client_order 3490 3490 0 0.00 0 5217 6 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 4050 10 0 0
v_cancel_customer_reserve 1402 1402 0 0.00 0 2512 5 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 1324 6 0 0
v_cancel_supplier_invoice 910 884 26 2.85 0 268 13 0 26
v_cancel_supplier_order 802 802 0 0.00 0 1263 6 0 0
v_cancel_write_off 2042 2042 0 0.00 0 1654 13 0 0
v_max_id_clo_ord 5699 5699 0 0.00 0 13748 4 0 0
v_max_id_clo_res 1084 1084 0 0.00 0 1797 10 0 0
v_max_id_ord_sup 5501 5501 0 0.00 0 3442 4 0 0
v_max_non_paid_invoice 1794 1794 0 0.00 0 1979 4 0 0
v_max_non_paid_realizn 3901 3901 0 0.00 0 6609 5 0 0
v_min_id_clo_ord 5699 5699 0 0.00 0 3835 9 0 0
v_min_id_clo_res 1084 1084 0 0.00 0 8375 15 0 0
v_min_id_ord_sup 5501 5501 0 0.00 0 5436 7 0 0
v_min_non_paid_invoice 6094 6094 0 0.00 0 14787 28 0 0
v_min_non_paid_realizn 7293 7293 0 0.00 0 8112 23 0 0
v_our_firm 10446 10446 0 0.00 0 12 1 0 0
v_reserve_write_off 20774 20774 0 0.00 0 1886 5 0 0
x_lock_depdocs_on_canc_invoice 10866 10866 0 0.00 0 14111 133 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 7909 405 0 0
x_make_qstorno_1000_1200 5699 5699 0 0.00 1 33525 1197 0 0
x_make_qstorno_1200_2000 5511 5511 0 0.00 3 102206 3559 0 0
x_make_qstorno_2100_3300 499314 499314 0 0.00 0 48163 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-05-2022 16:39:29 to 15-05-2022 16:39:33.

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
14:08:22 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:23 25348 8192000000 9886683344 10190688256 973345952 10.619 100 88 176 0 1002255808 5490864 23418992
14:10:52 24101 8192000000 9934047648 10251517952 1014121808 11.015 100 91 182 0 1046303616 8476832 23704976
14:12:23 21231 8192000000 9966548816 10297438208 1054661136 11.405 100 79 158 0 1084411104 9740896 20009072
14:13:48 9908 8192000000 10035433456 10380238848 1118699792 12.015 100 81 163 0 1148914736 10911040 19303904
14:15:03 8559 8192000000 10080547936 10437275648 1168746640 12.485 100 79 156 1 1199180976 12163584 18270752
14:16:15 5385 8192000000 10123180512 10487844864 1209493200 12.864 100 82 165 0 1241924144 13869008 18561936
14:17:22 3542 8192000000 10158625424 10530066432 1246615568 13.207 100 86 169 1 1279300160 14079840 18604752
14:18:28 2383 8192000000 10193368720 10569953280 1273391920 13.453 100 90 180 1 1306186288 12674688 20119680
14:19:31 4607 8192000000 10213633328 10597416960 1295984064 13.659 100 95 187 1 1330330720 13169280 21177376
14:20:37 14147 8192000000 10207906448 10599260160 1301257664 13.707 100 60 122 1 1323107584 7476272 14373648
14:21:52 6143 8192000000 10195695440 10608914432 1310435584 13.790 100 33 67 1 1325354528 6581872 8337072
14:22:58 12653 8192000000 10205629264 10624598016 1322924896 13.903 100 19 38 2 1333885168 6958768 4001504
14:24:12 53104 8192000000 10217682384 10644013056 1330006416 13.967 100 30 60 0 1343439936 6341312 7092208
14:26:14 3924 8192000000 10246215440 10659979264 1337935984 14.039 100 29 58 3 1350483104 6513264 6033856
14:27:19 4769 8192000000 10237735920 10655522816 1340289344 14.060 100 21 42 3 1351074480 6194064 4591072
14:28:26 8087 8192000000 10206308160 10622697472 1285483360 13.563 96 13 26 0 1293064800 4617552 2963888
14:29:36 17005 8192000000 10229711616 10622115840 1283105808 13.541 97 73 148 3 1306377936 5307712 17964416
14:30:55 6155 8192000000 10161517584 10560368640 1231645216 13.069 95 36 72 1 1246705072 6319760 8740096
14:32:02 5233 8192000000 10136303696 10533601280 1231380176 13.067 99 33 66 0 1245416144 5917920 8118048
14:33:09 71486 8192000000 10148772032 10558840832 1234410608 13.095 97 31 63 2 1247876368 5171760 8294000
14:35:21 4792 8192000000 10173565216 10583556096 1277898912 13.494 100 24 48 1 1290248064 6675808 5673344
14:36:27 9175 8192000000 10190936160 10601234432 1297050432 13.668 100 17 34 1 1305932096 4772256 4109408
14:37:38 15416 8192000000 10211142304 10623270912 1308795312 13.775 100 27 54 1 1320052384 5117952 6139120
14:39:36 13428 8192000000 10246363488 10651275264 1331944576 13.985 100 47 94 1 1352884000 10991328 9948096
14:40:53 5814 8192000000 10277959712 10682392576 1355800432 14.200 100 63 125 0 1381255216 12063280 13391504
14:42:03 17658 8192000000 10275610816 10701152256 1371680720 14.342 100 36 72 1 1391220352 11367360 8172272
14:43:24 19704 8192000000 10302936256 10722148352 1384236144 14.454 100 61 119 2 1417535360 19795376 13503840
14:44:46 8949 8192000000 10316559312 10736566272 1391626736 14.520 100 59 115 3 1432241952 27326480 13288736
14:45:59 15083 8192000000 10325268576 10747678720 1398190336 14.579 100 60 119 0 1434049088 21980336 13878416
14:47:29 12206 8192000000 10332696720 10757038080 1408650672 14.672 100 50 100 0 1442037408 21435568 11951168
14:48:47 5121 8192000000 10321322544 10744893440 1387447168 14.483 99 59 118 0 1424013984 22063120 14503696
14:49:57 10252 8192000000 10339895648 10763128832 1407309232 14.660 100 60 118 0 1447958016 26561792 14086992
14:51:37 1586 8192000000 10363584432 10773536768 1398096800 14.578 99 82 164 2 1454848864 37324128 19427936
14:52:42 2850 8192000000 10369932880 10773794816 1395359552 14.554 99 89 178 1 1467191056 49619360 22212144
14:53:50 6046 8192000000 10373815600 10791911424 1411964224 14.701 100 70 138 0 1484890064 55257808 17668032
14:55:00 2121 8192000000 10368312304 10779705344 1400758304 14.602 100 77 154 1 1472694944 52629232 19307408
14:56:09 11840 8192000000 10354184944 10769125376 1399111072 14.587 100 68 135 1 1459585696 43760544 16714080
14:57:27 3325 8192000000 10375761056 10785902592 1403094256 14.623 99 73 144 2 1472319168 51006624 18218288
14:58:43 1563 8192000000 10414810320 10822217728 1430358960 14.864 100 86 172 0 1511244400 58892448 21992992
14:59:52 2868 8192000000 10420207552 10830217216 1430441328 14.865 100 86 168 2 1520129104 67765840 21921936
15:01:02 3558 8192000000 10392621840 10807971840 1406362240 14.652 99 64 128 1 1495425088 71195840 17867008
15:02:10 3844 8192000000 10415940720 10837454848 1425009280 14.817 100 59 118 2 1517751840 76691600 16050960
15:03:16 7247 8192000000 10385701824 10814521344 1402821200 14.620 99 49 98 2 1480554000 64008000 13724800
15:04:51 17087 8192000000 10391167136 10834690048 1414062448 14.720 100 33 65 1 1492888336 69151856 9674032
15:06:14 21127 8192000000 10378207696 10822410240 1385874560 14.469 100 30 60 0 1482663136 87418048 9370528
15:07:42 28281 8192000000 10360405632 10804830208 1344661792 14.099 98 29 57 1 1451104544 96858112 9584640
15:09:19 53914 8192000000 10259362848 10707353600 1323665328 13.910 96 16 31 2 1344739312 17424096 3649888
15:11:13 24155 8192000000 10299850512 10722496512 1367754704 14.307 100 79 155 4 1394302448 7857760 18689984
15:12:40 83525 8192000000 10294429376 10746593280 1371976400 14.345 99 30 60 0 1391557920 13672512 5909008
15:15:09 107997 8192000000 10300185488 10742960128 1374801936 14.370 99 17 34 1 1388378048 9625776 3950336
15:17:58 61220 8192000000 10306121744 10745851904 1368888160 14.317 98 35 71 3 1384387792 8154832 7344800
15:20:01 137578 8192000000 10316350112 10764726272 1382240096 14.437 99 29 58 3 1400018800 11236688 6542016
15:23:37 129147 8192000000 10321514432 10773204992 1383307456 14.446 99 14 28 0 1398383744 12031616 3044672
15:26:52 79615 8192000000 10316663360 10789277696 1407754064 14.664 100 8 16 0 1426616544 16865680 1996800
15:29:16 58679 8192000000 10338135024 10786349056 1404122688 14.632 99 36 72 2 1425853744 13306448 8424608
15:31:37 43766 8192000000 10312799872 10797891584 1405686768 14.646 100 7 14 0 1429424208 22297328 1440112
15:34:23 67738 8192000000 10301899600 10774945792 1398784944 14.584 100 21 42 2 1419363136 15613152 4965040
15:36:39 52269 8192000000 10271184832 10737442816 1372438928 14.349 99 10 20 0 1383448928 9042688 1967312
15:38:32 109437 8192000000 10277674336 10743123968 1338369392 14.043 96 13 26 1 1359468736 18674448 2424896
15:41:23 103474 8192000000 10273618480 10737643520 1326785936 13.938 96 11 23 0 1338764912 10078800 1900176
15:44:06 47298 8192000000 10283854336 10746003456 1383859728 14.451 100 14 28 1 1397007152 10118336 3029088
15:46:06 53757 8192000000 10282030272 10740817920 1339349392 14.052 96 39 78 0 1358000624 9133168 9518064
15:48:02 45602 8192000000 10293286288 10727661568 1329089360 13.959 96 37 74 2 1346535760 8993472 8452928
15:49:51 111474 8192000000 10293575200 10745425920 1348085312 14.130 97 13 26 1 1363718928 12644288 2989328
15:52:46 141190 8192000000 10279728368 10737037312 1291165360 13.615 93 19 38 0 1310812944 15443008 4204576
15:56:09 23745 8192000000 10260278224 10705989632 1320729152 13.883 97 27 54 0 1335971760 9581600 5661008
15:57:33 31758 8192000000 10244972800 10688237568 1322199920 13.897 98 32 64 1 1341173280 12088368 6884992
15:59:06 94432 8192000000 10276269568 10737057792 1368594208 14.314 100 28 54 0 1392053280 17539408 5919664
16:01:51 93311 8192000000 10262396848 10719666176 1341398944 14.070 98 12 23 0 1358575744 14565424 2611376
16:04:25 41980 8192000000 10242609280 10696822784 1350895856 14.156 100 8 16 0 1363032432 10176944 1959632
16:06:49 70894 8192000000 10238792656 10701168640 1309743440 13.784 96 6 12 0 1320655840 9517040 1395360
16:09:04 60123 8192000000 10267988944 10719047680 1341301168 14.069 98 17 34 0 1354857808 10049776 3506864
16:11:09 71835 8192000000 10258602752 10717937664 1326876816 13.939 97 12 24 0 1341026272 11674000 2475456
16:13:26 57954 8192000000 10274369600 10732040192 1346512160 14.116 98 29 58 1 1363544752 10282816 6749776
16:15:26 32007 8192000000 10272913248 10715144192 1331662912 13.982 97 14 28 1 1341778880 7119456 2996512
16:16:59 29680 8192000000 10284356704 10723741696 1358801264 14.227 100 42 84 3 1389434784 21505856 9127664
16:18:31 144882 8192000000 10259122912 10708701184 1281995872 13.531 94 10 20 1 1298131616 14039584 2096160
16:21:56 58241 8192000000 10274085664 10721562624 1335633936 14.018 98 15 30 1 1355644800 16968832 3042032
16:23:58 74108 8192000000 10269899824 10718076928 1345148544 14.104 99 8 16 0 1354613616 7757248 1707824
16:26:17 60127 8192000000 10264011792 10722480128 1326135168 13.932 97 10 20 0 1346702160 18266496 2300496
16:28:29 93073 8192000000 10257045936 10716889088 1309201504 13.779 96 12 24 0 1322637632 11076592 2359536
16:31:03 72250 8192000000 10247171568 10705903616 1344128704 14.095 99 11 23 3 1354710624 8036112 2545808
16:33:25 60092 8192000000 10256583856 10720161792 1361312400 14.249 100 17 34 0 1372429888 7880048 3237440
16:35:25 62040 8192000000 10264015216 10726309888 1360726896 14.244 99 28 56 0 1376974224 10237568 6009760
16:37:28 29256 8192000000 10268944096 10720067584 1344134752 14.095 98 27 54 2 1359662768 9586800 5941216

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-05-2022 16:39:34 to 15-05-2022 16:39:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 164 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-05-2022 16:39:50 to 15-05-2022 16:39:52.

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          241151
MON$OLDEST_ACTIVE               241881
MON$OLDEST_SNAPSHOT             241881
MON$NEXT_TRANSACTION            241881
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                       2067168
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33587 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33587 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33587 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33587 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 ---
:338: 
:339: mscs-test-161.budzdorov.ru    Sun May 15 16:38:23 2022
:340:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:341: 
--- end of diff output ---

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

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