Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.03.2023 22:40
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 16146392 kB
MemAvailable 30410752 kB
Buffers 0 kB
Cached 15567156 kB
SwapCached 3172 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.11
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 2023-03-17 22:38:50
DTS_END                         2023-03-17 22:38:50.2230
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40276
TRN_ID                          237282
ATT_ID                          624
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: 9487.69 20456 1138523
customer order: creation 170.94 339 20513
customer order: refuse 14.43 347 1732
order to supplier: creation 45.18 1205 5421
order to supplier: removal 3.48 119209 418
invoice (draft): creation 45.10 2972 5412
invoice (draft): removal 56.31 825 6757
invoice accept: apply 40.83 18721 4899
invoice accept: cancel 50.95 736 6114
customer reserve: creation 3783.99 186 454079
customer reserve: removal 4970.97 66 596516
realization accept: apply 83.52 170 10022
realization accept: cancel 93.23 100 11188
payment from customer: creation 60.10 127 7212
payment from customer: removal 5.61 118 673
payment to supplier: creation 47.38 125 5685
payment to supplier: removal 3.25 81 390
service: total inventory turnovers 4.90 914 588
service: total monetary turnovers 6.74 28 809
service: aggregate perf. data 0.76 25407 91
service: refresh index statistics 0.03 237444 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-03-2023 22:40:34 to 17-03-2023 22:40:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2748
TEST_TIME 2 2279
TEST_TIME 3 3134
TEST_TIME 4 3242
TEST_TIME 5 3219
TEST_TIME 6 3267
TEST_TIME 7 3381
TEST_TIME 8 3464
TEST_TIME 9 3764
TEST_TIME 10 4116
TEST_TIME 11 4956
TEST_TIME 12 5297
TEST_TIME 13 5455
TEST_TIME 14 6014
TEST_TIME 15 6235
TEST_TIME 16 6408
TEST_TIME 17 6710
TEST_TIME 18 6663
TEST_TIME 19 6902
TEST_TIME 20 7296
TEST_TIME 21 7551
TEST_TIME 22 7981
TEST_TIME 23 8033
TEST_TIME 24 8444
TEST_TIME 25 8872
TEST_TIME 26 9732
TEST_TIME 27 9896
TEST_TIME 28 9896
TEST_TIME 29 11251
TEST_TIME 30 11460
TEST_TIME 31 11159
TEST_TIME 32 10967
TEST_TIME 33 10845
TEST_TIME 34 10884
TEST_TIME 35 10699
TEST_TIME 36 10543
TEST_TIME 37 10512
TEST_TIME 38 10453
TEST_TIME 39 10509
TEST_TIME 40 10672
TEST_TIME 41 10518
TEST_TIME 42 10479
TEST_TIME 43 10351
TEST_TIME 44 10367
TEST_TIME 45 10245
TEST_TIME 46 10251
TEST_TIME 47 10136
TEST_TIME 48 10038
TEST_TIME 49 9897
TEST_TIME 50 9906
TEST_TIME 51 9945
TEST_TIME 52 9868
TEST_TIME 53 9789
TEST_TIME 54 9697
TEST_TIME 55 9598
TEST_TIME 56 9531
TEST_TIME 57 9489
TEST_TIME 58 9411
TEST_TIME 59 9373
TEST_TIME 60 9497
TEST_TIME 61 9491
TEST_TIME 62 9522
TEST_TIME 63 9622
TEST_TIME 64 9655
TEST_TIME 65 9658
TEST_TIME 66 9583
TEST_TIME 67 9688
TEST_TIME 68 9669
TEST_TIME 69 9750
TEST_TIME 70 9817
TEST_TIME 71 9910
TEST_TIME 72 9827
TEST_TIME 73 9762
TEST_TIME 74 9660
TEST_TIME 75 9696
TEST_TIME 76 9698
TEST_TIME 77 9649
TEST_TIME 78 9619
TEST_TIME 79 9558
TEST_TIME 80 9485
TEST_TIME 81 9502
TEST_TIME 82 9459
TEST_TIME 83 9520
TEST_TIME 84 9518
TEST_TIME 85 9506
TEST_TIME 86 9468
TEST_TIME 87 9550
TEST_TIME 88 9553
TEST_TIME 89 9618
TEST_TIME 90 9643
TEST_TIME 91 9674
TEST_TIME 92 9706
TEST_TIME 93 9678
TEST_TIME 94 9644
TEST_TIME 95 9733
TEST_TIME 96 9714
TEST_TIME 97 9621
TEST_TIME 98 9591
TEST_TIME 99 9558
TEST_TIME 100 9647
TEST_TIME 101 9599
TEST_TIME 102 9577
TEST_TIME 103 9544
TEST_TIME 104 9517
TEST_TIME 105 9470
TEST_TIME 106 9447
TEST_TIME 107 9461
TEST_TIME 108 9445
TEST_TIME 109 9426
TEST_TIME 110 9392
TEST_TIME 111 9431
TEST_TIME 112 9455
TEST_TIME 113 9440
TEST_TIME 114 9452
TEST_TIME 115 9463
TEST_TIME 116 9457
TEST_TIME 117 9478
TEST_TIME 118 9487
TEST_TIME 119 9489
TEST_TIME 120 9487
TEST_TIME 121 9409

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-03-2023 22:40:38 to 17-03-2023 22:40: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 1560778 1560778 0 0.00 0 35654 6 0 0
doc_list_biud 1560777 1560777 0 0.00 0 64506 31 0 0
sp_add_invoice_to_stock 4984 4899 85 1.70 28 244839 18721 0 85
sp_cancel_adding_invoice 6114 6114 0 0.00 4 61773 737 0 0
sp_cancel_client_order 1732 1732 0 0.00 4 15751 348 0 0
sp_cancel_customer_reserve 596516 596516 0 0.00 0 35942 66 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 3 7789 119 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 3 1969 81 0 0
sp_cancel_supplier_invoice 6764 6757 7 0.10 2 64513 825 0 7
sp_cancel_supplier_order 418 418 0 0.00 9 1019426 119209 0 0
sp_cancel_write_off 11188 11188 0 0.00 1 11906 100 0 0
sp_client_order 20513 20513 0 0.00 3 21292 340 0 0
sp_customer_reserve 454079 454079 0 0.00 0 122269 187 0 0
sp_fill_shopping_cart 31338 31338 0 0.00 1 12877 38 0 0
sp_fill_shopping_cart_clo_res 454078 454078 0 0.00 0 16562 14 0 0
sp_get_clo_for_invoice 4898 4898 0 0.00 0 25072 302 0 0
sp_kill_cost_storno 18365 18365 0 0.00 0 10927 53 0 0
sp_kill_qstorno_ret_qs2qd 1211395 1211395 0 0.00 0 64504 35 0 0
sp_kill_qty_storno 637644 637644 0 0.00 0 64506 75 0 0
sp_lock_selected_doc 20634 20634 0 0.00 0 7850 6 0 0
sp_make_cost_storno 27817 27817 0 0.00 0 35652 51 0 0
sp_multiply_rows_for_pdistr 27816 27816 0 0.00 0 11797 29 0 0
sp_multiply_rows_for_qdistr 20513 20513 0 0.00 0 21284 143 0 0
sp_pay_from_customer 7212 7212 0 0.00 4 23146 127 0 0
sp_pay_to_supplier 5685 5685 0 0.00 4 35666 125 0 0
sp_payment_common 12897 12897 0 0.00 1 23087 35 0 0
sp_qd_handle_on_cancel_clo 1732 1732 0 0.00 0 15742 317 0 0
sp_qd_handle_on_invoice_upd_sts 11012 11012 0 0.00 0 29695 309 0 0
sp_qd_handle_on_reserve_upd_sts 10021 10021 0 0.00 0 15116 62 0 0
sp_reserve_write_off 10022 10022 0 0.00 6 16570 171 0 0
sp_supplier_invoice 5412 5412 0 0.00 5 92337 2973 0 0
sp_supplier_order 5421 5421 0 0.00 5 35856 1206 0 0
srv_aggregate_perf_data 91 91 0 0.00 2502 547320 25407 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1004 150745 47549 0 0
srv_find_qd_qs_mism 1092235 1092235 0 0.00 0 14507 6 0 0
srv_make_invnt_saldo 588 588 0 0.00 29 37838 915 0 0
srv_make_money_saldo 809 809 0 0.00 1 2856 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 61806 396140 237445 0 0
t$perf-abend:sp_add_invoice_to_stock 85 85 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4900 4900 0 0.00 0 19400 183 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 5527 121 0 0
t$perf-norm:sp_cancel_client_order 1732 1732 0 0.00 0 16606 35 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 4398 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 1019 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 3424 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 1404 14 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 24242 1763 0 0
t$perf-norm:sp_cancel_write_off 1001 1001 0 0.00 0 3374 7 0 0
t$perf-norm:sp_client_order 20513 20513 0 0.00 0 10411 24 0 0
t$perf-norm:sp_customer_reserve 979 979 0 0.00 0 5622 19 0 0
t$perf-norm:sp_pay_from_customer 7212 7212 0 0.00 0 13019 20 0 0
t$perf-norm:sp_pay_to_supplier 5685 5685 0 0.00 0 11661 17 0 0
t$perf-norm:sp_reserve_write_off 10022 10022 0 0.00 0 11563 19 0 0
t$perf-norm:sp_supplier_invoice 5412 5412 0 0.00 0 11899 27 0 0
t$perf-norm:sp_supplier_order 5421 5421 0 0.00 0 10451 19 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 536 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 7858 195 0 0
t$perf-norm:srv_make_invnt_saldo 588 588 0 0.00 0 2919 18 0 0
t$perf-norm:srv_make_money_saldo 809 809 0 0.00 0 1256 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9966 9796 170 1.70 0 4777 17 0 170
v_all_customers 37930 37930 0 0.00 0 19 1 0 0
v_all_suppliers 30254 30254 0 0.00 0 16 1 0 0
v_all_wares 41026 41026 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 3233 12 0 0
v_cancel_client_order 3464 3464 0 0.00 0 2363 3 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 7776 18 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 809 4 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 1706 5 0 0
v_cancel_supplier_invoice 840 826 14 1.66 0 2315 23 0 14
v_cancel_supplier_order 836 836 0 0.00 0 1963 11 0 0
v_cancel_write_off 2002 2002 0 0.00 0 3088 13 0 0
v_max_id_clo_ord 5421 5421 0 0.00 0 737 1 0 0
v_max_id_clo_res 979 979 0 0.00 0 676 5 0 0
v_max_id_ord_sup 5404 5404 0 0.00 0 4900 4 0 0
v_max_non_paid_invoice 1383 1383 0 0.00 0 542 3 0 0
v_max_non_paid_realizn 3555 3555 0 0.00 0 922 3 0 0
v_min_id_clo_ord 5421 5421 0 0.00 0 1808 5 0 0
v_min_id_clo_res 979 979 0 0.00 0 2863 7 0 0
v_min_id_ord_sup 5404 5404 0 0.00 0 3980 5 0 0
v_min_non_paid_invoice 5685 5685 0 0.00 0 14532 24 0 0
v_min_non_paid_realizn 7212 7212 0 0.00 0 20672 27 0 0
v_our_firm 10410 10410 0 0.00 0 27 1 0 0
v_reserve_write_off 20042 20042 0 0.00 0 5829 5 0 0
x_lock_depdocs_on_canc_invoice 12458 12458 0 0.00 0 21830 138 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 10720 438 0 0
x_make_qstorno_1000_1200 5421 5421 0 0.00 1 35850 1129 0 0
x_make_qstorno_1200_2000 5412 5412 0 0.00 2 86968 2837 0 0
x_make_qstorno_2100_3300 454079 454079 0 0.00 0 122268 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-03-2023 22:40:42 to 17-03-2023 22:40: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
20:08:53 7 8192000000 8809952672 8824918016 14078736 0.171 4 3 4 0 14264016 78160 107120
20:09:55 32895 8192000000 9892380576 10197458944 961653184 10.505 100 92 184 0 992781568 5908400 25219984
20:11:31 16279 8192000000 9935700560 10253037568 1014729648 11.021 100 92 184 0 1046988000 8257008 24001344
20:12:55 19399 8192000000 9961854240 10290610176 1047108432 11.333 100 80 160 0 1076366048 9651392 19606224
20:14:20 10077 8192000000 10020403568 10365100032 1109176368 11.925 100 81 162 0 1139335536 10795760 19363408
20:15:32 3442 8192000000 10074307856 10429698048 1160927392 12.412 100 85 166 1 1193564848 13384160 19253296
20:16:38 4353 8192000000 10117972464 10483785728 1208320048 12.854 100 81 162 0 1239768896 13420640 18028208
20:17:44 1926 8192000000 10151582608 10524344320 1240132656 13.147 100 79 156 0 1269248000 12179664 16935680
20:18:48 1309 8192000000 10186594016 10565386240 1269345648 13.416 100 87 170 1 1302332816 14225136 18762032
20:19:50 1523 8192000000 10201949216 10585284608 1288673312 13.592 100 89 177 1 1322985328 14452288 19859728
20:20:55 4758 8192000000 10205260928 10600669184 1299376000 13.690 100 59 118 0 1324776064 12588224 12811840
20:22:01 53339 8192000000 10204904480 10607390720 1307516896 13.764 100 49 97 3 1324307728 5427200 11363632
20:23:55 6700 8192000000 10198165712 10610610176 1308944448 13.776 100 24 47 0 1320081872 5863168 5274256
20:25:06 9615 8192000000 10205623184 10623422464 1322154416 13.896 100 21 42 0 1332525648 5723616 4647616
20:26:17 6887 8192000000 10212866128 10633637888 1329700960 13.964 100 20 41 1 1340437904 5882544 4854400
20:27:27 8079 8192000000 10238324352 10645614592 1334379712 14.007 100 62 120 2 1355125104 6852192 13893200
20:28:36 8469 8192000000 10210216048 10626211840 1302269984 13.716 98 14 26 0 1309685072 4774368 2640720
20:29:45 8619 8192000000 10181454592 10587328512 1268746080 13.410 98 21 43 0 1279055392 5851664 4457648
20:30:56 65406 8192000000 10145372416 10545991680 1129162608 12.113 88 28 56 1 1140438928 4859856 6416464
20:33:03 5343 8192000000 10141393936 10547392512 1246363872 13.205 99 29 56 1 1259299888 6822800 6113216
20:34:09 10677 8192000000 10159620224 10562318336 1266308720 13.388 100 13 26 0 1274293680 4876288 3108672
20:35:20 5954 8192000000 10166589856 10575052800 1276328048 13.479 100 18 37 0 1286105072 5885584 3891440
20:36:26 26643 8192000000 10175671920 10588073984 1285720416 13.565 100 16 32 0 1294624608 5576176 3328016
20:37:56 6742 8192000000 10204524016 10612695040 1307890544 13.767 100 28 56 0 1320003184 6139120 5973520
20:39:06 12688 8192000000 10246675856 10652880896 1343370064 14.088 100 63 128 2 1364323744 6610160 14343520
20:40:22 44192 8192000000 10277228544 10689200128 1360279904 14.240 100 58 116 1 1383663056 10486976 12896176
20:42:12 36127 8192000000 10285246000 10706804736 1378245680 14.401 100 34 68 0 1398596032 12620688 7729664
20:43:51 32449 8192000000 10316265104 10737778688 1396493968 14.564 100 44 86 1 1427644608 20911792 10238848
20:45:35 5264 8192000000 10320194192 10744811520 1405805264 14.647 100 36 70 0 1434312800 19931200 8576336
20:46:42 8125 8192000000 10346666336 10769469440 1419922512 14.772 100 48 96 0 1457874192 26150592 11801088
20:47:58 15730 8192000000 10359271472 10788777984 1426601856 14.831 100 50 99 0 1473988800 34409232 12977712
20:49:21 1000 8192000000 10368104384 10790146048 1421431728 14.785 100 70 138 2 1476883632 38145168 17306736
20:50:25 5081 8192000000 10379396800 10806153216 1433508624 14.892 100 60 120 1 1489732400 41896080 14327696
20:51:36 2592 8192000000 10386881104 10803195904 1422189488 14.792 100 81 158 0 1489528128 47783808 19554832
20:52:43 5173 8192000000 10351743968 10778013696 1388575152 14.493 98 49 99 1 1447646032 46779776 12291104
20:53:55 3713 8192000000 10397403248 10820358144 1416600672 14.743 99 77 154 0 1500831024 64887328 19343024
20:55:12 1175 8192000000 10386998768 10798837760 1396307248 14.562 98 86 172 0 1477888000 60262976 21317776
20:56:21 1948 8192000000 10392879984 10803580928 1410876544 14.692 100 79 152 1 1492586656 62586064 19124048
20:57:29 3007 8192000000 10396399440 10805682176 1415559760 14.733 100 77 155 0 1504658256 68385920 20712576
20:58:39 2828 8192000000 10408157072 10826080256 1424222272 14.810 100 65 126 0 1511934736 70658560 17053904
20:59:49 8680 8192000000 10411878160 10832297984 1420538176 14.777 99 52 104 0 1507015552 71577568 14899808
21:01:05 11717 8192000000 10388373632 10809880576 1400229712 14.597 99 46 90 1 1478141040 65571552 12339776
21:02:20 23581 8192000000 10371742240 10803367936 1392995984 14.533 100 38 76 0 1485797888 81780880 11021024
21:03:57 31829 8192000000 10346276576 10794934272 1384461888 14.456 100 30 60 1 1466334448 72863392 9009168
21:06:24 34855 8192000000 10350030928 10802413568 1372843008 14.353 100 21 40 0 1443573200 64904752 5825440
21:08:01 63120 8192000000 10274124320 10725978112 1308523360 13.773 95 9 18 1 1329548416 19502736 1522320
21:10:07 63798 8192000000 10268715872 10708553728 1329499536 13.963 97 21 42 0 1342590384 8555568 4535280
21:12:17 104606 8192000000 10284326496 10732318720 1359705904 14.235 99 27 52 0 1388619216 23024896 5888416
21:15:03 56923 8192000000 10301955248 10754023424 1389887152 14.505 100 19 38 0 1412600240 18188352 4524736
21:17:01 48970 8192000000 10290715680 10752651264 1368834624 14.317 99 19 38 0 1393720208 19892032 4993552
21:18:55 109338 8192000000 10267950560 10727313408 1327623552 13.946 96 7 14 0 1343423184 14112384 1687248
21:21:50 90661 8192000000 10276636512 10742161408 1355851280 14.200 98 7 14 1 1371950512 14342400 1756832
21:24:59 88403 8192000000 10284628016 10746814464 1370436848 14.331 99 8 16 0 1384939760 12232864 2270048
21:27:30 33797 8192000000 10296403056 10745217024 1388759552 14.495 100 48 91 1 1409757504 11012464 9985488
21:29:15 91037 8192000000 10287191040 10760957952 1382637792 14.440 99 19 37 0 1398992512 12411488 3943232
21:31:47 33771 8192000000 10297185280 10754985984 1395330192 14.553 100 36 72 2 1410533728 7607536 7596000
21:33:25 64801 8192000000 10283230176 10728353792 1353843728 14.182 97 46 89 0 1370144240 6852096 9448416
21:35:31 69447 8192000000 10288638544 10755481600 1388436976 14.492 100 12 24 1 1398617664 7476176 2704512
21:37:47 60669 8192000000 10295136960 10764345344 1352505568 14.170 97 16 32 0 1374031216 17421728 4103920
21:39:50 63284 8192000000 10298936672 10758426624 1376229264 14.383 98 12 24 0 1390522096 11621952 2670880
21:42:06 70033 8192000000 10282688464 10736046080 1301514688 13.709 93 16 33 1 1320807184 15919504 3372992
21:44:17 24092 8192000000 10305233232 10736353280 1344187680 14.095 97 40 78 1 1365943616 13133856 8622080
21:45:45 42180 8192000000 10280216560 10721980416 1350871760 14.155 99 24 48 0 1368317072 12221280 5224032
21:47:29 55979 8192000000 10284528976 10728894464 1339256608 14.051 97 14 28 1 1362950352 20607600 3086144
21:49:29 150745 8192000000 10272835920 10719064064 1279365568 13.507 93 17 34 0 1297754544 14543072 3845904
21:53:37 37118 8192000000 10269241424 10724671488 1354257952 14.186 99 19 38 1 1373299056 14017984 5023120
21:55:22 54316 8192000000 10241348368 10697314304 1311627424 13.801 96 8 16 0 1319968096 6821120 1519552
21:57:20 58500 8192000000 10262059264 10708066304 1339535376 14.053 98 14 28 1 1351990864 8722496 3732992
22:00:22 61521 8192000000 10278887088 10738462720 1346153856 14.113 98 8 16 0 1372870608 24637264 2079488
22:02:23 24027 8192000000 10263158496 10703577088 1356126192 14.203 100 27 53 1 1371615936 9342400 6147344
22:03:48 31844 8192000000 10289955632 10724192256 1367478368 14.304 100 35 70 0 1389735968 14645472 7612128
22:05:22 93759 8192000000 10276792560 10718662656 1337877952 14.038 98 11 22 0 1358052352 17442720 2731680
22:07:57 95236 8192000000 10286134912 10729893888 1360033296 14.238 99 17 34 0 1380556768 16835216 3688256
22:10:34 104663 8192000000 10278467120 10720788480 1323341408 13.907 96 20 40 0 1338492864 10934576 4216880
22:13:27 129163 8192000000 10289133328 10745778176 1356849776 14.209 98 17 34 0 1378193376 17831712 3511888
22:16:38 48623 8192000000 10295977712 10742489088 1379033984 14.408 99 39 79 0 1397995952 10264080 8697888
22:19:01 52783 8192000000 10280827760 10758197248 1386634512 14.476 100 6 12 0 1398389200 10088816 1665872
22:20:54 80742 8192000000 10294375360 10750820352 1388300672 14.491 100 22 44 1 1402873744 9234736 5338336
22:23:20 78719 8192000000 10293174672 10751016960 1381897280 14.434 99 21 43 0 1397967152 11286672 4783200
22:25:39 98929 8192000000 10281273632 10739720192 1337714544 14.037 96 9 18 0 1349648480 10243232 1690704
22:28:23 23566 8192000000 10299245616 10744418304 1374941216 14.371 99 40 81 0 1396215968 12480544 8794208
22:29:51 22163 8192000000 10284628752 10725326848 1363012656 14.264 99 44 88 1 1382182144 9858944 9310544
22:31:21 27525 8192000000 10267377216 10711633920 1342014560 14.076 98 25 50 0 1356376048 8955536 5405952
22:32:51 19773 8192000000 10271429808 10711695360 1343671904 14.091 98 38 76 0 1362124992 9792032 8661056
22:34:17 11323 8192000000 10278670192 10714443776 1351264016 14.159 99 41 82 0 1370661824 10384592 9013216
22:35:29 42915 8192000000 10271953136 10719055872 1359357040 14.232 100 45 90 0 1381927536 12524768 10045728
22:37:13 120878 8192000000 9586163664 9937645568 716791888 8.045 52 11 22 0 729626896 10795536 2039472

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-03-2023 22:40:48 to 17-03-2023 22:41:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 85 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-03-2023 22:41:04 to 17-03-2023 22:41: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          237596
MON$OLDEST_ACTIVE               237597
MON$OLDEST_SNAPSHOT             237597
MON$NEXT_TRANSACTION            237597
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                       2025120
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


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

17.03.23 22:41: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
2023-03-17 22:41:10 No severe PSQL-related problems occured
17.03.23 22:41:11. End of report.