Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.04.2022 19: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 16594640 kB
MemAvailable 31528588 kB
Buffers 0 kB
Cached 15098672 kB
SwapCached 3188 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-04-27 19:38:19
DTS_END                         2022-04-27 19:38:19.3100
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/55292
TRN_ID                          237101
ATT_ID                          695
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: 9364.99 23229 1123799
customer order: creation 166.88 397 20026
customer order: refuse 14.63 388 1756
order to supplier: creation 44.64 1374 5357
order to supplier: removal 3.33 107126 399
invoice (draft): creation 44.98 3530 5398
invoice (draft): removal 55.15 826 6618
invoice accept: apply 40.30 17601 4836
invoice accept: cancel 50.08 776 6009
customer reserve: creation 3783.86 170 454063
customer reserve: removal 4875.14 57 585017
realization accept: apply 82.50 199 9900
realization accept: cancel 77.22 118 9266
payment from customer: creation 57.93 113 6952
payment from customer: removal 5.53 82 664
payment to supplier: creation 47.74 134 5729
payment to supplier: removal 3.23 94 387
service: total inventory turnovers 4.79 1112 575
service: total monetary turnovers 6.23 23 748
service: aggregate perf. data 0.79 21741 95
service: refresh index statistics 0.03 308725 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-04-2022 19:39:34 to 27-04-2022 19:39:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3487
TEST_TIME 2 2824
TEST_TIME 3 3961
TEST_TIME 4 5662
TEST_TIME 5 6394
TEST_TIME 6 6045
TEST_TIME 7 5979
TEST_TIME 8 5999
TEST_TIME 9 6253
TEST_TIME 10 6180
TEST_TIME 11 6088
TEST_TIME 12 6225
TEST_TIME 13 6332
TEST_TIME 14 6565
TEST_TIME 15 6723
TEST_TIME 16 6956
TEST_TIME 17 7134
TEST_TIME 18 7599
TEST_TIME 19 7942
TEST_TIME 20 8160
TEST_TIME 21 8715
TEST_TIME 22 9291
TEST_TIME 23 9314
TEST_TIME 24 9327
TEST_TIME 25 9242
TEST_TIME 26 9641
TEST_TIME 27 9702
TEST_TIME 28 10209
TEST_TIME 29 10498
TEST_TIME 30 10853
TEST_TIME 31 10616
TEST_TIME 32 10627
TEST_TIME 33 10424
TEST_TIME 34 10192
TEST_TIME 35 10430
TEST_TIME 36 10262
TEST_TIME 37 10130
TEST_TIME 38 10132
TEST_TIME 39 10092
TEST_TIME 40 10019
TEST_TIME 41 10069
TEST_TIME 42 9997
TEST_TIME 43 9934
TEST_TIME 44 9821
TEST_TIME 45 9807
TEST_TIME 46 9702
TEST_TIME 47 9674
TEST_TIME 48 9625
TEST_TIME 49 9527
TEST_TIME 50 9466
TEST_TIME 51 9477
TEST_TIME 52 9496
TEST_TIME 53 9444
TEST_TIME 54 9484
TEST_TIME 55 9453
TEST_TIME 56 9520
TEST_TIME 57 9624
TEST_TIME 58 9646
TEST_TIME 59 9643
TEST_TIME 60 9660
TEST_TIME 61 9697
TEST_TIME 62 9845
TEST_TIME 63 9924
TEST_TIME 64 9921
TEST_TIME 65 9814
TEST_TIME 66 9774
TEST_TIME 67 9667
TEST_TIME 68 9701
TEST_TIME 69 9627
TEST_TIME 70 9607
TEST_TIME 71 9585
TEST_TIME 72 9543
TEST_TIME 73 9487
TEST_TIME 74 9471
TEST_TIME 75 9397
TEST_TIME 76 9552
TEST_TIME 77 9548
TEST_TIME 78 9489
TEST_TIME 79 9624
TEST_TIME 80 9594
TEST_TIME 81 9533
TEST_TIME 82 9670
TEST_TIME 83 9742
TEST_TIME 84 9711
TEST_TIME 85 9720
TEST_TIME 86 9644
TEST_TIME 87 9596
TEST_TIME 88 9558
TEST_TIME 89 9586
TEST_TIME 90 9539
TEST_TIME 91 9477
TEST_TIME 92 9427
TEST_TIME 93 9432
TEST_TIME 94 9432
TEST_TIME 95 9478
TEST_TIME 96 9532
TEST_TIME 97 9481
TEST_TIME 98 9482
TEST_TIME 99 9535
TEST_TIME 100 9487
TEST_TIME 101 9550
TEST_TIME 102 9598
TEST_TIME 103 9561
TEST_TIME 104 9578
TEST_TIME 105 9648
TEST_TIME 106 9635
TEST_TIME 107 9576
TEST_TIME 108 9529
TEST_TIME 109 9479
TEST_TIME 110 9523
TEST_TIME 111 9510
TEST_TIME 112 9471
TEST_TIME 113 9447
TEST_TIME 114 9458
TEST_TIME 115 9414
TEST_TIME 116 9440
TEST_TIME 117 9436
TEST_TIME 118 9445
TEST_TIME 119 9433
TEST_TIME 120 9366

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-04-2022 19:39:38 to 27-04-2022 19:39:41.

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 1542572 1542572 0 0.00 0 37044 6 0 0
doc_list_biud 1542572 1542572 0 0.00 0 39434 28 0 0
sp_add_invoice_to_stock 4962 4836 126 2.53 24 215020 17601 0 126
sp_cancel_adding_invoice 6009 6009 0 0.00 4 77765 776 0 0
sp_cancel_client_order 1756 1756 0 0.00 4 22616 389 0 0
sp_cancel_customer_reserve 585017 585017 0 0.00 1 40463 57 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 2 6653 82 0 0
sp_cancel_pay_to_supplier 387 387 0 0.00 3 3725 95 0 0
sp_cancel_supplier_invoice 6629 6618 11 0.16 2 22678 827 0 11
sp_cancel_supplier_order 399 399 0 0.00 7 1137040 107126 0 0
sp_cancel_write_off 9266 9266 0 0.00 1 36095 119 0 0
sp_client_order 20026 20026 0 0.00 3 38670 397 0 0
sp_customer_reserve 454063 454063 0 0.00 0 123061 170 0 0
sp_fill_shopping_cart 30780 30780 0 0.00 1 17643 37 0 0
sp_fill_shopping_cart_clo_res 454061 454061 0 0.00 0 25373 16 0 0
sp_get_clo_for_invoice 4834 4834 0 0.00 0 35449 355 0 0
sp_kill_cost_storno 16326 16326 0 0.00 0 18923 55 0 0
sp_kill_qstorno_ret_qs2qd 1186317 1186317 0 0.00 0 39343 31 0 0
sp_kill_qty_storno 623799 623799 0 0.00 0 39434 68 0 0
sp_lock_selected_doc 20390 20390 0 0.00 0 9363 6 0 0
sp_make_cost_storno 27415 27415 0 0.00 0 18141 54 0 0
sp_multiply_rows_for_pdistr 27415 27415 0 0.00 0 9926 30 0 0
sp_multiply_rows_for_qdistr 20026 20026 0 0.00 0 38664 182 0 0
sp_pay_from_customer 6952 6952 0 0.00 4 14309 113 0 0
sp_pay_to_supplier 5729 5729 0 0.00 4 17851 135 0 0
sp_payment_common 12681 12681 0 0.00 1 15207 31 0 0
sp_qd_handle_on_cancel_clo 1756 1756 0 0.00 0 22608 362 0 0
sp_qd_handle_on_invoice_upd_sts 10843 10843 0 0.00 0 30565 316 0 0
sp_qd_handle_on_reserve_upd_sts 9900 9900 0 0.00 0 26552 75 0 0
sp_reserve_write_off 9900 9900 0 0.00 6 26640 199 0 0
sp_supplier_invoice 5398 5398 0 0.00 5 84328 3531 0 0
sp_supplier_order 5357 5357 0 0.00 5 51628 1375 0 0
srv_aggregate_perf_data 95 95 0 0.00 2512 356226 21741 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2011 168338 49644 0 0
srv_find_qd_qs_mism 1076320 1076320 0 0.00 0 37041 5 0 0
srv_make_invnt_saldo 575 575 0 0.00 35 53391 1112 0 0
srv_make_money_saldo 748 748 0 0.00 1 372 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 127531 446946 308725 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4836 4836 0 0.00 0 13860 174 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 0 8386 117 0 0
t$perf-norm:sp_cancel_client_order 1756 1756 0 0.00 0 3420 14 0 0
t$perf-norm:sp_cancel_customer_reserve 676 676 0 0.00 0 1823 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 4979 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 387 387 0 0.00 0 503 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 1599 20 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 16766 1524 0 0
t$perf-norm:sp_cancel_write_off 1011 1011 0 0.00 0 3287 11 0 0
t$perf-norm:sp_client_order 20026 20026 0 0.00 0 15053 19 0 0
t$perf-norm:sp_customer_reserve 982 982 0 0.00 0 2501 6 0 0
t$perf-norm:sp_pay_from_customer 6952 6952 0 0.00 0 14746 15 0 0
t$perf-norm:sp_pay_to_supplier 5729 5729 0 0.00 0 4699 11 0 0
t$perf-norm:sp_reserve_write_off 9900 9900 0 0.00 0 10195 12 0 0
t$perf-norm:sp_supplier_invoice 5398 5398 0 0.00 0 5537 16 0 0
t$perf-norm:sp_supplier_order 5357 5357 0 0.00 0 6418 17 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 400 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 27 1 0 0
t$perf-norm:srv_make_invnt_saldo 575 575 0 0.00 0 745 18 0 0
t$perf-norm:srv_make_money_saldo 748 748 0 0.00 0 4243 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 14 6 0 0
v_add_invoice_to_stock 9920 9668 252 2.54 0 11792 20 0 252
v_all_customers 36696 36696 0 0.00 0 37 1 0 0
v_all_suppliers 29698 29698 0 0.00 0 19 1 0 0
v_all_wares 40052 40052 0 0.00 0 36 1 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 978 9 0 0
v_cancel_client_order 3512 3512 0 0.00 0 2123 4 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 1333 4 0 0
v_cancel_customer_reserve 1352 1352 0 0.00 0 675 3 0 0
v_cancel_payment_to_supplier 774 774 0 0.00 0 3186 12 0 0
v_cancel_supplier_invoice 788 766 22 2.79 0 6963 29 0 22
v_cancel_supplier_order 798 798 0 0.00 0 5849 14 0 0
v_cancel_write_off 2022 2022 0 0.00 0 8815 19 0 0
v_max_id_clo_ord 5357 5357 0 0.00 0 588 1 0 0
v_max_id_clo_res 981 981 0 0.00 0 3235 17 0 0
v_max_id_ord_sup 5397 5397 0 0.00 0 4841 5 0 0
v_max_non_paid_invoice 1634 1634 0 0.00 0 1842 3 0 0
v_max_non_paid_realizn 3675 3675 0 0.00 0 1421 4 0 0
v_min_id_clo_ord 5357 5357 0 0.00 0 3939 7 0 0
v_min_id_clo_res 981 981 0 0.00 0 10513 22 0 0
v_min_id_ord_sup 5397 5397 0 0.00 0 4778 6 0 0
v_min_non_paid_invoice 5729 5729 0 0.00 0 15204 23 0 0
v_min_non_paid_realizn 6952 6952 0 0.00 0 9487 21 0 0
v_our_firm 9910 9910 0 0.00 0 16 1 0 0
v_reserve_write_off 19794 19794 0 0.00 0 9052 8 0 0
x_lock_depdocs_on_canc_invoice 12244 12244 0 0.00 0 13748 119 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 19092 535 0 0
x_make_qstorno_1000_1200 5357 5357 0 0.00 1 51608 1293 0 0
x_make_qstorno_1200_2000 5398 5398 0 0.00 2 84320 3404 0 0
x_make_qstorno_2100_3300 454063 454063 0 0.00 0 123060 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-04-2022 19:39:42 to 27-04-2022 19:39:46.

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:23 4 8192000000 8804884560 8818167808 10265472 0.125 3 3 3 0 10410192 69760 74960
17:09:24 26314 8192000000 9877499872 10184704000 962501488 10.513 100 82 164 0 989825856 5418096 21906272
17:10:54 18636 8192000000 9937888192 10254598144 1021855824 11.090 100 89 178 1 1052465808 7607376 23002608
17:12:20 15636 8192000000 9975071392 10302595072 1057706752 11.435 100 89 178 0 1089292112 9147504 22437856
17:13:44 9531 8192000000 10024896416 10371297280 1115287696 11.982 100 71 142 1 1142113216 9773456 17052064
17:15:01 7629 8192000000 10089228784 10448822272 1179452624 12.585 100 77 154 0 1209527680 12717392 17357664
17:16:13 4856 8192000000 10123994640 10492583936 1214303584 12.909 100 80 160 0 1245359776 13733840 17322352
17:17:21 6188 8192000000 10157888336 10538123264 1252035744 13.257 100 65 130 1 1278686640 12852480 13798416
17:18:29 1939 8192000000 10203537696 10587213824 1289072976 13.596 100 87 174 1 1322324000 13703104 19547920
17:19:33 1823 8192000000 10222168768 10606821376 1304907680 13.740 100 93 186 2 1338434640 12551920 20975040
17:20:36 50153 8192000000 10220819584 10619379712 1315547200 13.836 100 54 110 1 1336271088 8012496 12711392
17:22:26 12807 8192000000 10207891408 10626629632 1321823008 13.893 100 16 32 1 1330872304 5474064 3575232
17:23:42 9576 8192000000 10227030096 10640883712 1333353520 13.997 100 27 54 3 1345256880 5539616 6363744
17:24:54 16608 8192000000 10236678640 10651443200 1340812592 14.065 100 20 40 2 1350359952 5519424 4027936
17:26:14 19918 8192000000 10227393232 10653409280 1341608128 14.072 100 36 72 1 1356041600 6337440 8096032
17:27:41 4861 8192000000 10234642352 10652459008 1339798336 14.056 100 37 74 0 1355107936 6589632 8719968
17:28:47 11620 8192000000 10182760816 10599268352 1261765056 13.346 96 20 40 0 1271352848 4973360 4614432
17:29:59 6998 8192000000 10160581008 10569494528 1247311360 13.214 97 13 26 0 1255512944 5423776 2777808
17:31:07 20323 8192000000 10150098352 10548772864 1174792496 12.542 92 20 40 1 1184546672 5331984 4422192
17:32:28 4047 8192000000 10125291936 10515787776 1221153664 12.972 99 31 59 2 1234425392 6495088 6776640
17:33:32 7346 8192000000 10151168608 10556100608 1259678992 13.327 99 25 49 1 1271230624 5608496 5943136
17:34:40 63898 8192000000 10172101936 10586279936 1282116272 13.532 100 45 90 2 1298370112 5401840 10852000
17:36:45 9757 8192000000 10196033808 10608492544 1297444304 13.672 100 43 86 2 1319478368 11986544 10047520
17:37:55 13711 8192000000 10197713840 10617470976 1307230176 13.761 100 15 30 0 1315446032 5144672 3071184
17:39:16 33065 8192000000 10263433056 10669469696 1348151184 14.131 100 67 132 1 1373593040 10260080 15181776
17:40:51 63823 8192000000 10257995584 10678829056 1354433968 14.187 100 42 84 0 1377535648 13633728 9467952
17:42:57 6966 8192000000 10272187248 10694541312 1363210496 14.266 100 42 84 0 1388450704 15193968 10046240
17:44:07 9212 8192000000 10293937840 10713710592 1378454224 14.403 100 50 100 0 1405952896 16084896 11413776
17:45:21 18598 8192000000 10308947616 10735325184 1389854320 14.505 100 42 83 3 1417172224 17229424 10088480
17:46:49 8533 8192000000 10327913632 10746920960 1394756528 14.548 100 52 104 1 1435097520 27733040 12607952
17:48:00 13376 8192000000 10341011936 10771345408 1415795584 14.735 100 42 84 2 1453109376 26569584 10744208
17:49:16 8395 8192000000 10340045984 10763288576 1405072368 14.640 100 59 117 2 1449609472 29868832 14668272
17:50:29 10131 8192000000 10353954672 10773192704 1394219696 14.543 98 70 139 3 1443984768 32209808 17555264
17:51:43 4251 8192000000 10358029792 10769891328 1396757376 14.566 99 85 169 0 1455729552 37926256 21045920
17:52:52 3150 8192000000 10373237600 10782257152 1417108496 14.747 100 85 169 1 1474520896 37180032 20232368
17:54:00 4316 8192000000 10361116672 10769797120 1400505136 14.599 99 84 168 1 1461415520 40405424 20504960
17:55:09 2010 8192000000 10356928544 10774085632 1402942832 14.621 100 71 141 2 1463022288 42551024 17528432
17:56:14 33589 8192000000 10341796032 10764550144 1389633696 14.503 100 42 84 0 1449147296 47708848 11804752
17:57:54 8425 8192000000 10337998432 10755051520 1382527136 14.439 100 55 112 2 1442691344 45049552 15114656
17:59:05 9935 8192000000 10316541168 10737569792 1359980112 14.237 99 33 66 0 1413470688 44188352 9302224
18:00:19 13468 8192000000 10319887104 10741731328 1359473184 14.233 98 44 86 0 1410106864 38849008 11784672
18:01:36 10176 8192000000 10310816288 10742452224 1377657280 14.396 100 29 58 2 1416945248 30717136 8570832
18:02:48 17861 8192000000 10324065072 10758508544 1378577776 14.404 99 24 48 1 1421662736 36004592 7080368
18:04:18 29721 8192000000 10303076720 10745655296 1366538256 14.296 100 18 36 0 1418523584 46453696 5531632
18:06:05 35763 8192000000 10290039536 10733281280 1346490496 14.116 99 19 38 0 1390594768 38658272 5446000
18:08:21 91264 8192000000 10273118448 10705510400 1360987744 14.246 100 19 38 0 1378932912 13701264 4243904
18:10:57 103403 8192000000 10293329728 10728767488 1370318928 14.330 100 32 63 0 1392616656 14980416 7317312
18:13:41 94073 8192000000 10291413488 10718744576 1342616640 14.081 97 35 67 0 1363621744 12838848 8166256
18:16:17 55404 8192000000 10311145808 10744840192 1386732480 14.477 100 24 48 0 1407244544 15052608 5459456
18:18:16 168312 8192000000 10295807328 10749423616 1381405840 14.429 99 47 94 0 1402585360 9364720 11814800
18:22:14 32566 8192000000 10295347888 10755190784 1390056656 14.506 100 20 40 1 1406848960 11326960 5465344
18:24:06 84712 8192000000 10297217920 10759569408 1391948208 14.523 100 18 36 0 1414361056 17712496 4700352
18:26:36 33498 8192000000 10295897120 10755256320 1396745984 14.566 100 27 53 1 1412803856 9807120 6250752
18:28:48 41742 8192000000 10294493312 10773762048 1398916368 14.585 100 11 22 0 1411170768 9691760 2562640
18:30:30 50363 8192000000 10296714416 10759725056 1386785376 14.477 99 11 22 0 1396710256 7728032 2196848
18:32:21 59227 8192000000 10297235984 10756337664 1382369856 14.438 98 17 31 1 1394527120 9025888 3131376
18:34:24 65345 8192000000 10264586912 10725748736 1280588672 13.518 92 15 30 0 1299919056 16038112 3292272
18:36:32 30322 8192000000 10272652576 10721964032 1312968384 13.813 95 16 32 0 1326901296 10362384 3570528
18:38:04 39872 8192000000 10291639424 10750771200 1363431248 14.268 99 12 22 1 1380886448 15260832 2194368
18:40:00 23573 8192000000 10289020640 10745077760 1354715632 14.190 99 13 26 0 1378977600 21248528 3013440
18:42:05 92574 8192000000 10275570208 10729095168 1336308960 14.024 97 25 48 1 1351427552 9752272 5366320
18:45:43 14417 8192000000 10275727888 10719531008 1354497136 14.188 99 37 74 0 1379758752 16701184 8560432
18:46:58 44755 8192000000 10261915568 10720227328 1337485664 14.035 97 10 20 1 1350445584 10879248 2080672
18:49:06 74599 8192000000 10262405328 10715389952 1321860704 13.894 97 10 20 0 1341153520 17115744 2177072
18:51:26 43064 8192000000 10258981776 10707939328 1354213200 14.185 99 14 28 0 1365519760 7925408 3381152
18:53:10 52371 8192000000 10290876944 10724032512 1327682272 13.946 96 27 53 2 1347531136 13463824 6385040
18:55:04 82446 8192000000 10254964128 10700513280 1292743680 13.629 95 17 32 2 1307067456 11174272 3149504
18:57:29 95746 8192000000 10270456816 10712276992 1310659232 13.792 96 14 28 0 1326691104 12881744 3150128
19:00:09 116638 8192000000 10271167456 10727333888 1333251296 13.997 97 9 19 0 1358682000 23091248 2339456
19:03:06 46620 8192000000 10286841712 10735001600 1370406688 14.331 100 22 44 0 1385634928 10501904 4726336
19:04:56 116396 8192000000 10290734896 10742812672 1383030064 14.444 100 18 36 0 1402203904 14636304 4537536
19:07:57 61120 8192000000 10267882704 10733064192 1345623952 14.108 97 12 24 0 1355225920 6907280 2694688
19:10:42 58702 8192000000 10293012912 10749919232 1376832400 14.388 99 18 36 0 1395292336 14245056 4214880
19:12:43 69277 8192000000 10311911040 10777333760 1387837792 14.487 99 22 45 1 1407715360 15025728 4851840
19:14:52 60165 8192000000 10318242528 10766749696 1374428304 14.367 98 15 28 1 1392539168 15297792 2813072
19:16:53 67035 8192000000 10317365072 10782121984 1385168768 14.463 99 29 58 0 1415048016 23302576 6576672
19:19:28 74021 8192000000 10304351344 10764279808 1379361232 14.411 99 14 28 0 1391705312 9272016 3072064
19:21:42 39602 8192000000 10310360320 10753572864 1388990848 14.497 100 29 58 0 1407506400 11919392 6596160
19:23:26 71986 8192000000 10285846480 10740465664 1342584480 14.081 97 14 28 0 1359735056 14287856 2862720
19:25:47 122858 8192000000 10295814096 10752376832 1362276272 14.258 98 31 61 1 1381933872 13038400 6619200
19:28:52 49943 8192000000 10258303392 10739056640 1341188304 14.068 98 6 11 1 1360814752 17643184 1983264
19:30:59 116092 8192000000 10236600544 10698096640 1262597152 13.354 93 11 22 0 1274873328 9341072 2935104
19:33:55 33888 8192000000 10231030272 10700378112 1326358672 13.934 99 9 18 0 1342075136 13381280 2335184
19:35:39 80539 8192000000 10257738960 10724618240 1350716176 14.154 100 12 24 1 1368436544 15160224 2560144
19:38:00 24836 8192000000 9115631008 9357967360 272788160 3.222 21 10 20 0 278230992 3216512 2226320

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-04-2022 19:39:48 to 27-04-2022 19:40:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 126 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-04-2022 19:40:04 to 27-04-2022 19:40:06.

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          235137
MON$OLDEST_ACTIVE               237594
MON$OLDEST_SNAPSHOT             237594
MON$NEXT_TRANSACTION            237594
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                       2032288
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.33578 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33578 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33578 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33578 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'

27.04.22 19:40:07. 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-04-27 19:40:09 No severe PSQL-related problems occured
27.04.22 19:40:10. End of report.