Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.12.2021 19: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 16479724 kB
MemAvailable 31353020 kB
Buffers 0 kB
Cached 15151296 kB
SwapCached 444 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.9
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-12-22 19:38:02
DTS_END                         2021-12-22 19:38:02.0860
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(57:17)
IP                              ::1/33982
TRN_ID                          239511
ATT_ID                          708
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: 9257.28 23991 1110873
customer order: creation 170.23 367 20428
customer order: refuse 14.33 355 1719
order to supplier: creation 46.39 1256 5567
order to supplier: removal 3.46 110785 415
invoice (draft): creation 46.32 3630 5558
invoice (draft): removal 52.36 880 6283
invoice accept: apply 41.36 18346 4963
invoice accept: cancel 47.57 847 5708
customer reserve: creation 3859.97 179 463196
customer reserve: removal 4676.11 65 561133
realization accept: apply 83.07 195 9968
realization accept: cancel 84.88 120 10186
payment from customer: creation 59.94 128 7193
payment from customer: removal 5.61 115 673
payment to supplier: creation 49.99 129 5999
payment to supplier: removal 3.30 95 396
service: total inventory turnovers 4.58 1013 549
service: total monetary turnovers 7.01 37 841
service: aggregate perf. data 0.78 23105 94
service: refresh index statistics 0.03 318172 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-12-2021 19:38:56 to 22-12-2021 19:38:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5697
TEST_TIME 2 6286
TEST_TIME 3 5323
TEST_TIME 4 5047
TEST_TIME 5 5169
TEST_TIME 6 5230
TEST_TIME 7 5523
TEST_TIME 8 5892
TEST_TIME 9 6255
TEST_TIME 10 6492
TEST_TIME 11 6487
TEST_TIME 12 6563
TEST_TIME 13 6975
TEST_TIME 14 7496
TEST_TIME 15 7616
TEST_TIME 16 7908
TEST_TIME 17 8714
TEST_TIME 18 8951
TEST_TIME 19 9084
TEST_TIME 20 9237
TEST_TIME 21 9070
TEST_TIME 22 9068
TEST_TIME 23 9135
TEST_TIME 24 9102
TEST_TIME 25 8985
TEST_TIME 26 9231
TEST_TIME 27 9161
TEST_TIME 28 9778
TEST_TIME 29 10234
TEST_TIME 30 10999
TEST_TIME 31 11203
TEST_TIME 32 10942
TEST_TIME 33 10846
TEST_TIME 34 10741
TEST_TIME 35 10773
TEST_TIME 36 10621
TEST_TIME 37 10489
TEST_TIME 38 10368
TEST_TIME 39 10401
TEST_TIME 40 10303
TEST_TIME 41 10228
TEST_TIME 42 10151
TEST_TIME 43 10084
TEST_TIME 44 9985
TEST_TIME 45 9866
TEST_TIME 46 9868
TEST_TIME 47 9830
TEST_TIME 48 9803
TEST_TIME 49 9751
TEST_TIME 50 9716
TEST_TIME 51 9643
TEST_TIME 52 9544
TEST_TIME 53 9501
TEST_TIME 54 9502
TEST_TIME 55 9567
TEST_TIME 56 9511
TEST_TIME 57 9479
TEST_TIME 58 9519
TEST_TIME 59 9498
TEST_TIME 60 9445
TEST_TIME 61 9461
TEST_TIME 62 9553
TEST_TIME 63 9527
TEST_TIME 64 9469
TEST_TIME 65 9501
TEST_TIME 66 9508
TEST_TIME 67 9542
TEST_TIME 68 9522
TEST_TIME 69 9574
TEST_TIME 70 9530
TEST_TIME 71 9532
TEST_TIME 72 9699
TEST_TIME 73 9697
TEST_TIME 74 9780
TEST_TIME 75 9748
TEST_TIME 76 9636
TEST_TIME 77 9630
TEST_TIME 78 9538
TEST_TIME 79 9501
TEST_TIME 80 9493
TEST_TIME 81 9431
TEST_TIME 82 9415
TEST_TIME 83 9345
TEST_TIME 84 9391
TEST_TIME 85 9370
TEST_TIME 86 9416
TEST_TIME 87 9415
TEST_TIME 88 9452
TEST_TIME 89 9426
TEST_TIME 90 9403
TEST_TIME 91 9396
TEST_TIME 92 9403
TEST_TIME 93 9452
TEST_TIME 94 9525
TEST_TIME 95 9527
TEST_TIME 96 9557
TEST_TIME 97 9517
TEST_TIME 98 9517
TEST_TIME 99 9540
TEST_TIME 100 9572
TEST_TIME 101 9592
TEST_TIME 102 9563
TEST_TIME 103 9480
TEST_TIME 104 9428
TEST_TIME 105 9442
TEST_TIME 106 9409
TEST_TIME 107 9376
TEST_TIME 108 9339
TEST_TIME 109 9326
TEST_TIME 110 9274
TEST_TIME 111 9260
TEST_TIME 112 9243
TEST_TIME 113 9266
TEST_TIME 114 9246
TEST_TIME 115 9228
TEST_TIME 116 9316
TEST_TIME 117 9290
TEST_TIME 118 9301
TEST_TIME 119 9337
TEST_TIME 120 9261

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-12-2021 19:39:00 to 22-12-2021 19:39:03.

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 1539556 1539556 0 0.00 0 18019 6 0 0
doc_list_biud 1539556 1539556 0 0.00 0 33861 30 0 0
sp_add_invoice_to_stock 5083 4963 120 2.36 36 173878 18347 0 120
sp_cancel_adding_invoice 5708 5708 0 0.00 6 65972 847 0 0
sp_cancel_client_order 1719 1719 0 0.00 3 13028 356 0 0
sp_cancel_customer_reserve 561133 561133 0 0.00 1 33864 65 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 3 11380 115 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 3 4915 96 0 0
sp_cancel_supplier_invoice 6297 6283 14 0.22 2 14595 881 0 14
sp_cancel_supplier_order 415 415 0 0.00 6 935003 110786 0 0
sp_cancel_write_off 10186 10186 0 0.00 1 20422 120 0 0
sp_client_order 20428 20428 0 0.00 3 63421 367 0 0
sp_customer_reserve 463196 463196 0 0.00 0 46157 179 0 0
sp_fill_shopping_cart 31533 31533 0 0.00 1 25105 41 0 0
sp_fill_shopping_cart_clo_res 463186 463186 0 0.00 0 11974 14 0 0
sp_get_clo_for_invoice 4950 4950 0 0.00 0 25330 351 0 0
sp_kill_cost_storno 16963 16963 0 0.00 0 15600 59 0 0
sp_kill_qstorno_ret_qs2qd 1139150 1139150 0 0.00 0 33860 35 0 0
sp_kill_qty_storno 600361 600361 0 0.00 0 33861 76 0 0
sp_lock_selected_doc 20578 20578 0 0.00 0 7392 5 0 0
sp_make_cost_storno 28109 28109 0 0.00 0 12859 53 0 0
sp_multiply_rows_for_pdistr 28109 28109 0 0.00 0 9556 28 0 0
sp_multiply_rows_for_qdistr 20428 20428 0 0.00 0 63413 179 0 0
sp_pay_from_customer 7193 7193 0 0.00 4 14185 129 0 0
sp_pay_to_supplier 5999 5999 0 0.00 4 16691 130 0 0
sp_payment_common 13192 13192 0 0.00 1 16669 27 0 0
sp_qd_handle_on_cancel_clo 1719 1719 0 0.00 0 13023 325 0 0
sp_qd_handle_on_invoice_upd_sts 10657 10657 0 0.00 0 32702 343 0 0
sp_qd_handle_on_reserve_upd_sts 9968 9968 0 0.00 0 32673 71 0 0
sp_reserve_write_off 9968 9968 0 0.00 6 32689 196 0 0
sp_supplier_invoice 5558 5558 0 0.00 7 107279 3630 0 0
sp_supplier_order 5567 5567 0 0.00 5 81477 1257 0 0
srv_aggregate_perf_data 94 94 0 0.00 2771 222301 23105 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2752 142501 43841 0 0
srv_find_qd_qs_mism 1062839 1062839 0 0.00 0 17671 5 0 0
srv_make_invnt_saldo 549 549 0 0.00 46 83556 1014 0 0
srv_make_money_saldo 841 841 0 0.00 0 11186 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 172758 605924 318173 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4963 4963 0 0.00 0 5466 152 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 0 12176 150 0 0
t$perf-norm:sp_cancel_client_order 1719 1719 0 0.00 0 4725 19 0 0
t$perf-norm:sp_cancel_customer_reserve 674 674 0 0.00 0 1483 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 2184 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 2242 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 3762 24 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 16516 1608 0 0
t$perf-norm:sp_cancel_write_off 961 961 0 0.00 0 5505 17 0 0
t$perf-norm:sp_client_order 20428 20428 0 0.00 0 5686 17 0 0
t$perf-norm:sp_customer_reserve 1033 1033 0 0.00 0 1036 4 0 0
t$perf-norm:sp_pay_from_customer 7193 7193 0 0.00 0 4054 10 0 0
t$perf-norm:sp_pay_to_supplier 5999 5999 0 0.00 0 5216 7 0 0
t$perf-norm:sp_reserve_write_off 9968 9968 0 0.00 0 4702 11 0 0
t$perf-norm:sp_supplier_invoice 5558 5558 0 0.00 0 10616 20 0 0
t$perf-norm:sp_supplier_order 5567 5567 0 0.00 0 4731 17 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 717 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 194 6 0 0
t$perf-norm:srv_make_invnt_saldo 549 549 0 0.00 0 6099 32 0 0
t$perf-norm:srv_make_money_saldo 841 841 0 0.00 0 695 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 10132 9892 240 2.36 0 3942 19 0 240
v_all_customers 37674 37674 0 0.00 0 18 1 0 0
v_all_suppliers 31132 31132 0 0.00 0 15 1 0 0
v_all_wares 40856 40856 0 0.00 0 103 1 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 2567 13 0 0
v_cancel_client_order 3438 3438 0 0.00 0 5655 8 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 7383 13 0 0
v_cancel_customer_reserve 1348 1348 0 0.00 0 1657 5 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 2787 17 0 0
v_cancel_supplier_invoice 872 844 28 3.21 0 4295 28 0 28
v_cancel_supplier_order 830 830 0 0.00 0 1170 9 0 0
v_cancel_write_off 1922 1922 0 0.00 0 2292 15 0 0
v_max_id_clo_ord 5567 5567 0 0.00 0 477 1 0 0
v_max_id_clo_res 1033 1033 0 0.00 0 2048 10 0 0
v_max_id_ord_sup 5538 5538 0 0.00 0 3960 3 0 0
v_max_non_paid_invoice 1538 1538 0 0.00 0 424 3 0 0
v_max_non_paid_realizn 3667 3667 0 0.00 0 9891 7 0 0
v_min_id_clo_ord 5567 5567 0 0.00 0 1049 4 0 0
v_min_id_clo_res 1033 1033 0 0.00 0 5381 11 0 0
v_min_id_ord_sup 5538 5538 0 0.00 0 5760 10 0 0
v_min_non_paid_invoice 5999 5999 0 0.00 0 16664 16 0 0
v_min_non_paid_realizn 7193 7193 0 0.00 0 12962 22 0 0
v_our_firm 10234 10234 0 0.00 0 104 1 0 0
v_reserve_write_off 19936 19936 0 0.00 0 4963 7 0 0
x_lock_depdocs_on_canc_invoice 11569 11569 0 0.00 0 14937 122 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 6572 370 0 0
x_make_qstorno_1000_1200 5567 5567 0 0.00 1 81364 1176 0 0
x_make_qstorno_1200_2000 5558 5558 0 0.00 3 107243 3478 0 0
x_make_qstorno_2100_3300 463196 463196 0 0.00 0 46013 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-12-2021 19:39:04 to 22-12-2021 19:39:08.

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
17:08:05 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:06 30242 8192000000 9922953456 10236084224 1002041120 10.898 100 89 178 0 1032790896 7248192 23501584
17:10:42 22791 8192000000 9945138208 10270793728 1027584896 11.145 100 87 175 0 1059085744 9117600 22383248
17:12:10 14884 8192000000 9984598752 10324459520 1070276032 11.555 100 78 156 0 1099868496 9983728 19608736
17:13:29 14806 8192000000 10038673456 10388312064 1123146000 12.057 100 79 158 0 1153691088 11526160 19018928
17:14:47 7886 8192000000 10096853152 10461388800 1183370512 12.622 100 73 146 0 1213188288 13201040 16616736
17:15:56 4565 8192000000 10140841312 10514903040 1227601312 13.032 100 81 162 1 1258585744 12541456 18442976
17:17:03 3742 8192000000 10172708992 10553618432 1257276480 13.305 100 79 157 1 1288152400 12773808 18102112
17:18:08 1212 8192000000 10193119664 10576461824 1281361328 13.525 100 84 168 2 1313292080 13163328 18767424
17:19:11 1158 8192000000 10221266464 10605092864 1301347072 13.707 100 95 192 0 1337204880 14001824 21855984
17:20:14 2711 8192000000 10210115280 10608910336 1303046336 13.723 100 60 119 2 1326895040 9790112 14058592
17:21:18 10491 8192000000 10197187152 10610794496 1307248896 13.761 100 22 42 3 1319096800 7055856 4792048
17:22:32 14497 8192000000 10206204160 10618191872 1316834240 13.848 100 27 55 2 1329109552 5639552 6635760
17:23:48 12638 8192000000 10210200720 10627424256 1319467600 13.872 100 39 77 2 1334386992 6436816 8482576
17:25:11 8555 8192000000 10204769680 10630606848 1323204432 13.906 100 17 34 0 1332977248 5329760 4443056
17:26:22 16013 8192000000 10218835728 10640715776 1307258224 13.761 98 22 43 1 1317949056 5641600 5049232
17:27:40 75023 8192000000 10160665072 10571997184 1168519072 12.483 89 30 60 0 1180538736 4801296 7218368
17:29:56 7372 8192000000 10165395104 10572914688 1278018768 13.495 100 25 50 1 1289156592 5706608 5431216
17:31:04 6973 8192000000 10138198864 10543620096 1238822848 13.135 98 21 42 0 1250265408 5949136 5493424
17:32:11 4213 8192000000 10157242048 10562875392 1255407248 13.288 99 20 40 1 1269940384 10204480 4328656
17:33:15 32403 8192000000 10162885280 10572161024 1255799184 13.291 98 27 54 0 1267068160 5285904 5983072
17:34:49 5034 8192000000 10189858272 10597646336 1291704944 13.620 100 34 68 2 1305779056 6450448 7623664
17:35:58 6781 8192000000 10196295968 10610999296 1307426288 13.763 100 24 48 1 1318887264 5522240 5938736
17:37:06 45882 8192000000 10206063120 10620911616 1314052176 13.823 100 28 56 0 1325689488 5641792 5995520
17:39:10 15558 8192000000 10254634336 10664144896 1346077936 14.112 100 51 102 0 1366117040 9118480 10920624
17:40:33 11631 8192000000 10264156992 10683654144 1365695984 14.288 100 43 85 1 1384934912 9191024 10047904
17:41:50 12730 8192000000 10278808800 10700095488 1375577296 14.377 100 46 92 1 1397011440 10579632 10854512
17:43:17 17173 8192000000 10302772848 10725335040 1393073152 14.533 100 46 92 1 1416968768 12814880 11080736
17:44:41 4087 8192000000 10322490080 10741739520 1403788352 14.629 100 61 120 3 1433493440 15593600 14111488
17:45:59 6947 8192000000 10336598976 10759864320 1408017616 14.666 100 55 109 2 1439695216 18905168 12772432
17:47:10 5486 8192000000 10328924736 10757226496 1413982128 14.719 100 54 106 2 1449406240 22546336 12877776
17:48:23 4976 8192000000 10328019984 10751205376 1399550384 14.591 99 55 109 1 1432169712 19599952 13019376
17:49:33 5889 8192000000 10332646624 10754269184 1399659888 14.592 99 72 140 2 1437429008 20978848 16790272
17:50:46 2751 8192000000 10340946960 10752237568 1397700080 14.575 100 84 170 1 1449043712 29223392 22120240
17:51:53 3951 8192000000 10344840736 10761920512 1400479136 14.599 100 72 144 3 1452302272 33875312 17947824
17:53:03 4663 8192000000 10341448352 10754965504 1382828640 14.442 99 81 160 2 1440179120 37244816 20105664
17:54:14 12680 8192000000 10342334352 10766282752 1395711648 14.557 100 67 132 1 1452745072 40627824 16405600
17:55:32 3277 8192000000 10340294384 10763476992 1395649632 14.556 100 58 116 0 1449160960 39103376 14407952
17:56:41 6964 8192000000 10316930560 10741432320 1378661632 14.405 100 44 87 1 1426378496 35860608 11856256
17:57:56 15440 8192000000 10340989696 10773696512 1398847520 14.585 100 48 96 0 1455579008 44686480 12045008
17:59:28 4161 8192000000 10295248048 10719825920 1359646336 14.234 100 35 70 0 1406141024 37090496 9404192
18:00:43 3254 8192000000 10346373024 10764394496 1381477104 14.430 99 58 116 0 1443873136 47042512 15353520
18:01:56 17695 8192000000 10350945312 10780196864 1396730464 14.566 100 40 78 1 1455689280 47866784 11092032
18:03:29 14872 8192000000 10341042544 10770292736 1359652352 14.234 98 37 74 0 1429545440 59088064 10805024
18:05:01 30798 8192000000 10340519792 10782470144 1364588736 14.279 99 31 62 1 1438833520 65148528 9096256
18:06:43 31984 8192000000 10300198704 10766704640 1348674800 14.136 99 18 36 0 1408713600 54648128 5390672
18:08:16 38899 8192000000 10290702320 10738769920 1306847632 13.757 96 14 28 0 1358143408 47099792 4195984
18:09:57 85060 8192000000 10284581856 10730913792 1368782432 14.316 100 34 67 0 1393760016 16596336 8381248
18:12:23 11135 8192000000 10296659408 10727571456 1375238640 14.374 100 56 112 1 1401523088 13057360 13227088
18:13:49 30494 8192000000 10288841472 10721849344 1374287792 14.365 100 18 28 1 1382954512 5688656 2978064
18:15:24 36388 8192000000 10292703344 10743656448 1392417088 14.527 100 21 40 1 1405119328 8406768 4295472
18:17:10 50595 8192000000 10289111232 10750943232 1382569168 14.440 99 19 38 0 1400172192 13143472 4459552
18:19:02 106882 8192000000 10292624656 10729455616 1339510896 14.053 96 30 60 1 1355951568 9846160 6594512
18:21:55 61910 8192000000 10293858304 10749050880 1391329200 14.518 100 22 44 0 1408776912 11993456 5454256
18:23:57 125862 8192000000 10297855344 10749415424 1360147120 14.239 98 25 49 0 1377335104 11473504 5714480
18:27:11 44483 8192000000 10328854976 10775703552 1402599600 14.618 100 37 74 0 1424067040 13024912 8442528
18:28:57 28492 8192000000 10318838304 10773741568 1411590304 14.698 100 20 40 1 1430611216 14199440 4821472
18:30:45 67641 8192000000 10311468288 10765266944 1362898640 14.263 96 34 68 1 1391698368 20212624 8587104
18:33:09 60922 8192000000 10292617952 10762756096 1380712336 14.423 99 7 14 1 1403930912 21329280 1889296
18:35:15 58732 8192000000 10309998512 10758799360 1398220944 14.579 100 34 69 1 1416466448 10483984 7761520
18:37:25 73746 8192000000 10303126544 10758897664 1371775296 14.343 98 14 28 0 1383065216 8454384 2835536
18:39:43 81305 8192000000 10278503584 10747494400 1337830336 14.038 96 6 12 0 1351500512 12393296 1276880
18:42:13 31597 8192000000 10287968512 10735501312 1344623152 14.099 97 34 68 0 1360018032 7849760 7545120
18:43:53 102987 8192000000 10266762400 10723336192 1306513488 13.754 95 6 12 0 1319797632 12101776 1182368
18:46:37 76180 8192000000 10275125808 10725023744 1320459088 13.881 96 19 38 1 1334892912 10721136 3712688
18:48:56 66308 8192000000 10289938784 10744229888 1360930928 14.246 99 34 67 1 1389499664 21240016 7328720
18:51:03 84198 8192000000 10277884048 10717306880 1293574288 13.637 94 20 40 0 1308610352 10841232 4194832
18:53:28 123303 8192000000 10259111504 10712952832 1319292672 13.870 96 19 38 0 1337885440 14206384 4386384
18:56:33 30005 8192000000 10271709984 10706976768 1350320704 14.150 99 33 66 0 1368999712 11637200 7041808
18:58:05 49083 8192000000 10263710416 10715312128 1348253520 14.132 99 30 60 0 1374403440 19506832 6643088
18:59:57 68493 8192000000 10295818160 10747695104 1371938368 14.344 100 20 40 0 1399495200 23087824 4469008
19:02:22 58865 8192000000 10271189104 10717335552 1353220768 14.176 100 14 28 1 1380129216 22742800 4165648
19:04:44 47671 8192000000 10275849696 10728652800 1365504896 14.287 100 22 44 0 1382676240 11957808 5213536
19:06:46 47348 8192000000 10264951360 10704932864 1315372816 13.835 96 42 86 1 1340867120 15339360 10154944
19:08:37 22688 8192000000 10293417184 10733965312 1366319296 14.294 99 53 106 1 1387569952 9411232 11839424
19:10:00 37604 8192000000 10293885824 10741723136 1368054864 14.310 99 31 62 0 1388661792 13557904 7049024
19:12:06 127513 8192000000 10272786528 10717900800 1281007968 13.522 93 11 22 0 1299050960 15508624 2534368
19:15:59 12523 8192000000 10307210544 10751942656 1385621760 14.467 100 25 50 0 1403049872 11476800 5951312
19:17:20 38665 8192000000 10312730720 10763468800 1385243696 14.463 100 35 70 0 1404424592 11727952 7452944
19:19:03 142501 8192000000 10246871120 10692493312 1277090672 13.486 92 9 18 0 1291256656 11721120 2444864
19:22:48 27977 8192000000 10258115344 10703970304 1352422096 14.169 99 19 38 0 1364514016 7883664 4208256
19:24:26 62126 8192000000 10254679616 10713358336 1328389616 13.953 97 8 16 0 1337622128 7548944 1683568
19:26:29 66156 8192000000 10274176320 10729566208 1363181968 14.266 99 20 41 0 1378255296 9431264 5642064
19:28:58 47020 8192000000 10271538928 10727600128 1366256544 14.293 100 14 28 0 1382099248 12117008 3725696
19:30:51 50696 8192000000 10271457856 10724995072 1348346784 14.133 98 21 42 0 1372463824 18786208 5330832
19:32:45 68096 8192000000 10278950672 10726768640 1339954432 14.057 98 20 40 1 1365259232 20870240 4434560
19:34:54 45771 8192000000 10270435072 10712866816 1311698992 13.801 96 12 24 0 1322087824 8004960 2383872
19:36:59 70758 8192000000 9066115120 9297014784 220897760 2.625 17 4 8 0 224768368 2909328 961280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-12-2021 19:39:10 to 22-12-2021 19:39:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 120 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 22-12-2021 19:39:26 to 22-12-2021 19:39:27.

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          240312
MON$OLDEST_ACTIVE               240313
MON$OLDEST_SNAPSHOT             240313
MON$NEXT_TRANSACTION            240313
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                       2038496
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33546 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33546 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33546 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33546 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 ---
--- end of diff output ---

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

22.12.21 19:39:29. 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-12-22 19:39:31 No severe PSQL-related problems occured
22.12.21 19:39:32. End of report.