Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.10.2022 22: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 15479080 kB
MemAvailable 30428260 kB
Buffers 0 kB
Cached 16221416 kB
SwapCached 2004 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 2022-10-26 22:38:32
DTS_END                         2022-10-26 22:38:32.9040
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/57426
TRN_ID                          250636
ATT_ID                          728
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: 9543.71 20555 1145245
customer order: creation 182.18 358 21862
customer order: refuse 14.92 329 1790
order to supplier: creation 47.99 1166 5759
order to supplier: removal 3.55 101975 426
invoice (draft): creation 47.20 3209 5664
invoice (draft): removal 54.34 723 6521
invoice accept: apply 42.95 17591 5154
invoice accept: cancel 49.01 725 5881
customer reserve: creation 3982.45 174 477894
customer reserve: removal 4810.19 60 577223
realization accept: apply 87.92 153 10550
realization accept: cancel 83.92 112 10070
payment from customer: creation 63.18 105 7581
payment from customer: removal 6.11 49 733
payment to supplier: creation 51.13 118 6136
payment to supplier: removal 3.56 55 427
service: total inventory turnovers 5.26 924 631
service: total monetary turnovers 7.01 25 841
service: aggregate perf. data 0.82 19136 98
service: refresh index statistics 0.03 264119 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-10-2022 22:39:19 to 26-10-2022 22:39:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4607
TEST_TIME 2 2479
TEST_TIME 3 2126
TEST_TIME 4 2064
TEST_TIME 5 2344
TEST_TIME 6 2890
TEST_TIME 7 3821
TEST_TIME 8 4033
TEST_TIME 9 4195
TEST_TIME 10 4502
TEST_TIME 11 4432
TEST_TIME 12 4529
TEST_TIME 13 4925
TEST_TIME 14 5045
TEST_TIME 15 5389
TEST_TIME 16 5786
TEST_TIME 17 6215
TEST_TIME 18 6311
TEST_TIME 19 6271
TEST_TIME 20 7534
TEST_TIME 21 8570
TEST_TIME 22 9163
TEST_TIME 23 9665
TEST_TIME 24 9990
TEST_TIME 25 10383
TEST_TIME 26 10370
TEST_TIME 27 10250
TEST_TIME 28 10311
TEST_TIME 29 10252
TEST_TIME 30 10022
TEST_TIME 31 10026
TEST_TIME 32 9843
TEST_TIME 33 9958
TEST_TIME 34 9830
TEST_TIME 35 9683
TEST_TIME 36 9719
TEST_TIME 37 9989
TEST_TIME 38 9834
TEST_TIME 39 9747
TEST_TIME 40 9754
TEST_TIME 41 9723
TEST_TIME 42 9568
TEST_TIME 43 9356
TEST_TIME 44 9455
TEST_TIME 45 9533
TEST_TIME 46 9457
TEST_TIME 47 9462
TEST_TIME 48 9558
TEST_TIME 49 9665
TEST_TIME 50 9619
TEST_TIME 51 9678
TEST_TIME 52 9724
TEST_TIME 53 9676
TEST_TIME 54 9795
TEST_TIME 55 9848
TEST_TIME 56 9850
TEST_TIME 57 9854
TEST_TIME 58 9799
TEST_TIME 59 9721
TEST_TIME 60 9736
TEST_TIME 61 9657
TEST_TIME 62 9660
TEST_TIME 63 9624
TEST_TIME 64 9642
TEST_TIME 65 9598
TEST_TIME 66 9611
TEST_TIME 67 9602
TEST_TIME 68 9639
TEST_TIME 69 9562
TEST_TIME 70 9723
TEST_TIME 71 9754
TEST_TIME 72 9726
TEST_TIME 73 9749
TEST_TIME 74 9802
TEST_TIME 75 9851
TEST_TIME 76 9881
TEST_TIME 77 9893
TEST_TIME 78 9785
TEST_TIME 79 9749
TEST_TIME 80 9681
TEST_TIME 81 9652
TEST_TIME 82 9780
TEST_TIME 83 9752
TEST_TIME 84 9724
TEST_TIME 85 9678
TEST_TIME 86 9616
TEST_TIME 87 9566
TEST_TIME 88 9592
TEST_TIME 89 9534
TEST_TIME 90 9571
TEST_TIME 91 9556
TEST_TIME 92 9600
TEST_TIME 93 9610
TEST_TIME 94 9586
TEST_TIME 95 9707
TEST_TIME 96 9670
TEST_TIME 97 9695
TEST_TIME 98 9737
TEST_TIME 99 9685
TEST_TIME 100 9748
TEST_TIME 101 9788
TEST_TIME 102 9743
TEST_TIME 103 9679
TEST_TIME 104 9633
TEST_TIME 105 9614
TEST_TIME 106 9588
TEST_TIME 107 9568
TEST_TIME 108 9524
TEST_TIME 109 9498
TEST_TIME 110 9461
TEST_TIME 111 9475
TEST_TIME 112 9458
TEST_TIME 113 9495
TEST_TIME 114 9484
TEST_TIME 115 9470
TEST_TIME 116 9504
TEST_TIME 117 9542
TEST_TIME 118 9543
TEST_TIME 119 9507
TEST_TIME 120 9545

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-10-2022 22:39:23 to 26-10-2022 22:39:26.

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 1587193 1587193 0 0.00 0 26880 6 0 0
doc_list_biud 1587193 1587193 0 0.00 0 30671 27 0 0
sp_add_invoice_to_stock 5295 5154 141 2.66 38 161133 17591 0 141
sp_cancel_adding_invoice 5881 5881 0 0.00 3 92675 725 0 0
sp_cancel_client_order 1790 1790 0 0.00 4 9483 329 0 0
sp_cancel_customer_reserve 577223 577223 0 0.00 0 24678 60 0 0
sp_cancel_pay_from_customer 733 733 0 0.00 2 1485 49 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 3 2731 55 0 0
sp_cancel_supplier_invoice 6534 6521 13 0.19 1 19679 724 0 13
sp_cancel_supplier_order 426 426 0 0.00 5 1128677 101975 0 0
sp_cancel_write_off 10070 10070 0 0.00 1 28581 113 0 0
sp_client_order 21862 21862 0 0.00 3 18410 359 0 0
sp_customer_reserve 477894 477894 0 0.00 0 48531 174 0 0
sp_fill_shopping_cart 33264 33264 0 0.00 1 21744 35 0 0
sp_fill_shopping_cart_clo_res 477892 477892 0 0.00 0 19684 15 0 0
sp_get_clo_for_invoice 5151 5151 0 0.00 0 28651 325 0 0
sp_kill_cost_storno 17111 17111 0 0.00 0 15741 52 0 0
sp_kill_qstorno_ret_qs2qd 1171463 1171463 0 0.00 0 30670 32 0 0
sp_kill_qty_storno 617612 617612 0 0.00 0 30671 70 0 0
sp_lock_selected_doc 21666 21666 0 0.00 0 10717 7 0 0
sp_make_cost_storno 29418 29418 0 0.00 0 26496 44 0 0
sp_multiply_rows_for_pdistr 29418 29418 0 0.00 0 9147 25 0 0
sp_multiply_rows_for_qdistr 21862 21862 0 0.00 0 13705 178 0 0
sp_pay_from_customer 7581 7581 0 0.00 4 12606 105 0 0
sp_pay_to_supplier 6136 6136 0 0.00 5 10091 119 0 0
sp_payment_common 13717 13717 0 0.00 1 10062 29 0 0
sp_qd_handle_on_cancel_clo 1790 1790 0 0.00 0 9471 302 0 0
sp_qd_handle_on_invoice_upd_sts 11032 11032 0 0.00 0 26271 330 0 0
sp_qd_handle_on_reserve_upd_sts 10550 10550 0 0.00 0 9993 47 0 0
sp_reserve_write_off 10550 10550 0 0.00 6 13486 153 0 0
sp_supplier_invoice 5664 5664 0 0.00 6 103817 3210 0 0
sp_supplier_order 5759 5759 0 0.00 5 33943 1166 0 0
srv_aggregate_perf_data 98 98 0 0.00 2767 221197 19136 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2250 113922 43793 0 0
srv_find_qd_qs_mism 1094975 1094975 0 0.00 0 19826 5 0 0
srv_make_invnt_saldo 631 631 0 0.00 23 59659 924 0 0
srv_make_money_saldo 841 841 0 0.00 1 2512 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 189045 339866 264120 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5154 5154 0 0.00 0 33594 140 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 0 2160 55 0 0
t$perf-norm:sp_cancel_client_order 1790 1790 0 0.00 0 9413 13 0 0
t$perf-norm:sp_cancel_customer_reserve 693 693 0 0.00 0 1962 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 733 733 0 0.00 0 2763 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 1467 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 445 445 0 0.00 0 642 7 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 38944 1463 0 0
t$perf-norm:sp_cancel_write_off 1020 1020 0 0.00 0 1508 7 0 0
t$perf-norm:sp_client_order 21862 21862 0 0.00 0 12507 15 0 0
t$perf-norm:sp_customer_reserve 1031 1031 0 0.00 0 696 5 0 0
t$perf-norm:sp_pay_from_customer 7581 7581 0 0.00 0 4468 7 0 0
t$perf-norm:sp_pay_to_supplier 6136 6136 0 0.00 0 10281 10 0 0
t$perf-norm:sp_reserve_write_off 10550 10550 0 0.00 0 16877 11 0 0
t$perf-norm:sp_supplier_invoice 5664 5664 0 0.00 0 4768 16 0 0
t$perf-norm:sp_supplier_order 5759 5759 0 0.00 0 17006 18 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 108 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 319 7 0 0
t$perf-norm:srv_make_invnt_saldo 631 631 0 0.00 0 1199 17 0 0
t$perf-norm:srv_make_money_saldo 841 841 0 0.00 0 1032 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 13 5 0 0
v_add_invoice_to_stock 10584 10302 282 2.66 0 2708 17 0 282
v_all_customers 39856 39856 0 0.00 0 141 0 0 0
v_all_suppliers 31744 31744 0 0.00 0 141 1 0 0
v_all_wares 43724 43724 0 0.00 0 42 1 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 1742 10 0 0
v_cancel_client_order 3580 3580 0 0.00 0 3100 5 0 0
v_cancel_customer_prepayment 1466 1466 0 0.00 0 1429 4 0 0
v_cancel_customer_reserve 1386 1386 0 0.00 0 1408 4 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 1109 6 0 0
v_cancel_supplier_invoice 916 890 26 2.83 0 3298 25 0 26
v_cancel_supplier_order 852 852 0 0.00 0 1241 4 0 0
v_cancel_write_off 2040 2040 0 0.00 0 7457 17 0 0
v_max_id_clo_ord 5747 5747 0 0.00 0 542 1 0 0
v_max_id_clo_res 1031 1031 0 0.00 0 3043 18 0 0
v_max_id_ord_sup 5655 5655 0 0.00 0 4447 4 0 0
v_max_non_paid_invoice 1666 1666 0 0.00 0 1138 4 0 0
v_max_non_paid_realizn 3968 3968 0 0.00 0 4303 5 0 0
v_min_id_clo_ord 5747 5747 0 0.00 0 568 4 0 0
v_min_id_clo_res 1031 1031 0 0.00 0 5341 8 0 0
v_min_id_ord_sup 5655 5655 0 0.00 0 6562 9 0 0
v_min_non_paid_invoice 6136 6136 0 0.00 0 8179 20 0 0
v_min_non_paid_realizn 7581 7581 0 0.00 0 9986 20 0 0
v_our_firm 11094 11094 0 0.00 0 18 1 0 0
v_reserve_write_off 21100 21100 0 0.00 0 4970 7 0 0
x_lock_depdocs_on_canc_invoice 11957 11957 0 0.00 0 12665 94 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 6316 294 0 0
x_make_qstorno_1000_1200 5759 5759 0 0.00 1 33915 1097 0 0
x_make_qstorno_1200_2000 5664 5664 0 0.00 2 98891 3081 0 0
x_make_qstorno_2100_3300 477894 477894 0 0.00 0 47690 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-10-2022 22:39:27 to 26-10-2022 22:39:31.

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:36 27 8192000000 8819658320 8838418432 20577136 0.250 9 2 3 0 20738448 86352 74960
20:09:37 29027 8192000000 9925990160 10241622016 1003790784 10.915 100 85 170 0 1031891680 6519136 21581760
20:11:15 18909 8192000000 9969037424 10296569856 1047562144 11.337 100 87 174 0 1078497568 8933056 22002368
20:12:37 12876 8192000000 9993389744 10328018944 1075485152 11.604 100 87 174 0 1107357664 10448528 21423984
20:13:53 9034 8192000000 10035688256 10384072704 1116085008 11.990 100 74 147 0 1144356416 10895904 17375504
20:15:09 5153 8192000000 10072756864 10428280832 1160009488 12.403 100 86 169 0 1192376960 13411680 18955792
20:16:16 3216 8192000000 10109879344 10474913792 1194849104 12.728 100 85 170 0 1227434528 12928944 19656480
20:17:21 2180 8192000000 10140223360 10514350080 1227826944 13.034 100 71 142 0 1255765552 12378176 15560432
20:18:25 1402 8192000000 10173408320 10555936768 1260773504 13.337 100 78 155 1 1289686384 12289344 16623536
20:19:28 1496 8192000000 10212695296 10595414016 1294822640 13.648 100 89 179 2 1328032160 13576736 19632784
20:20:32 852 8192000000 10213273024 10606981120 1304417104 13.735 100 68 136 0 1330585648 10655136 15513408
20:21:35 4167 8192000000 10198008592 10607763456 1311563184 13.800 100 37 73 0 1326271280 6583264 8124832
20:22:40 7390 8192000000 10206782944 10619973632 1320291888 13.879 100 20 40 0 1334822208 10009424 4520896
20:23:48 10329 8192000000 10215743632 10634743808 1327036320 13.940 100 19 38 1 1341605872 10319936 4249616
20:25:00 65454 8192000000 10230234720 10650210304 1331725888 13.983 100 24 48 0 1346081472 9175296 5180288
20:27:06 6493 8192000000 10233251952 10653892608 1335246304 14.015 100 24 46 1 1345691984 5767664 4678016
20:28:14 8000 8192000000 10204625984 10619695104 1278893136 13.503 97 23 46 0 1290544976 6142848 5508992
20:29:25 12800 8192000000 10197923456 10597662720 1275267376 13.470 98 25 50 1 1286402192 5188928 5945888
20:30:42 19608 8192000000 10146860064 10560397312 1164524560 12.446 90 14 26 1 1171750032 4872000 2353472
20:32:06 6462 8192000000 10143011536 10540965888 1234826512 13.099 99 22 44 1 1245240768 4906896 5507360
20:33:13 9088 8192000000 10165987312 10565513216 1255865088 13.292 99 26 53 1 1266526112 4498096 6162928
20:34:28 61125 8192000000 10170581040 10581196800 1282405040 13.535 100 33 66 0 1296086752 6585360 7096352
20:36:30 7205 8192000000 10185450880 10598809600 1297191600 13.670 100 24 49 0 1308597312 5668496 5737216
20:37:37 6622 8192000000 10193066256 10613211136 1309393120 13.781 100 18 34 0 1318940992 5520592 4027280
20:38:46 13242 8192000000 10239471312 10647801856 1339890832 14.056 100 41 83 1 1355372880 5327888 10154160
20:40:14 101338 8192000000 10261220096 10685296640 1357878336 14.218 100 37 69 0 1379621408 12423712 9319360
20:43:13 16610 8192000000 10304928112 10721320960 1380125232 14.418 100 37 75 1 1412965552 23918992 8921328
20:44:33 23911 8192000000 10326809408 10739671040 1379334544 14.411 99 59 113 1 1422623456 29606880 13682032
20:46:06 8291 8192000000 10330234768 10752811008 1402504912 14.617 100 42 83 1 1441720352 28461504 10753936
20:47:17 7976 8192000000 10343531136 10765766656 1411751792 14.700 100 55 108 2 1458252848 32839488 13661568
20:48:31 5556 8192000000 10327426784 10755952640 1380425824 14.420 98 45 90 0 1426111328 33969728 11715776
20:49:41 4608 8192000000 10354170464 10768744448 1409465600 14.679 100 64 125 0 1462510608 37613632 15431376
20:50:52 6540 8192000000 10361647920 10782646272 1415789344 14.735 100 63 126 0 1473498384 40929808 16779232
20:52:05 8420 8192000000 10367289840 10781106176 1416715296 14.744 100 61 122 0 1471814864 39054912 16044656
20:53:19 2249 8192000000 10385121856 10798346240 1423689648 14.805 100 85 166 2 1491283568 46775008 20818912
20:54:28 6580 8192000000 10392674688 10810589184 1430424528 14.865 100 74 147 1 1500522848 51396528 18701792
20:55:40 3649 8192000000 10387499760 10800340992 1417443408 14.750 100 75 143 1 1488882240 52730368 18708464
20:56:48 7364 8192000000 10360210336 10778578944 1390352592 14.509 100 53 106 1 1463970288 58903344 14714352
20:58:05 3911 8192000000 10361789072 10790555648 1385260352 14.464 100 69 138 1 1479305040 73677840 20366848
20:59:11 16118 8192000000 10339668112 10778595328 1373629616 14.360 100 35 70 0 1449148992 65547152 9972224
21:00:35 15896 8192000000 10313668224 10751148032 1366656736 14.297 100 24 48 0 1416264896 42338800 7269360
21:01:59 30086 8192000000 10274806496 10729005056 1347541440 14.125 99 14 29 0 1378137616 26380464 4215712
21:03:30 42129 8192000000 10265812752 10714730496 1336152208 14.023 98 8 16 0 1353939456 15904096 1883152
21:05:12 12028 8192000000 10272847424 10705747968 1347755840 14.127 99 60 120 3 1369114272 7756416 13602016
21:06:29 49165 8192000000 10268994624 10709454848 1347511696 14.125 99 20 41 1 1363478368 11878048 4088624
21:09:05 45063 8192000000 10269631744 10705305600 1339905072 14.057 98 39 74 0 1361991648 12817296 9269280
21:10:51 39079 8192000000 10278347968 10733379584 1366474224 14.295 99 24 49 0 1384434320 12458576 5501520
21:12:41 21945 8192000000 10290155216 10734989312 1374603856 14.368 99 25 48 0 1388410080 8185216 5621008
21:14:04 27718 8192000000 10286501120 10732847104 1370986800 14.336 99 51 102 0 1396647552 13558176 12102576
21:15:57 10753 8192000000 10286288464 10744631296 1385278144 14.464 100 17 35 0 1397255600 8182656 3794800
21:17:18 73169 8192000000 10274903856 10728660992 1367428832 14.304 98 14 28 1 1379170736 8273280 3468624
21:19:32 69100 8192000000 10285602000 10747781120 1364849248 14.281 98 20 40 0 1379992400 10595424 4547728
21:22:03 9810 8192000000 10285539376 10745208832 1387217792 14.481 100 39 77 0 1411176576 15146480 8812304
21:23:22 48406 8192000000 10288579008 10762104832 1398718416 14.584 100 12 24 0 1413864160 12101744 3044000
21:25:33 45306 8192000000 10289086416 10746388480 1353250064 14.177 96 21 42 0 1369923264 11403424 5269776
21:27:21 41024 8192000000 10301839520 10749415424 1379855152 14.415 99 16 32 0 1391013520 7734256 3424112
21:29:04 15590 8192000000 10296908816 10739093504 1383354480 14.447 100 46 92 0 1405828464 12417280 10056704
21:30:20 47610 8192000000 10270173408 10712563712 1309801040 13.784 94 27 54 0 1324591072 8838272 5951760
21:32:13 24061 8192000000 10284624560 10722066432 1337234240 14.032 97 43 86 0 1359633712 12692608 9706864
21:34:40 43908 8192000000 10286032464 10732601344 1348667584 14.135 98 15 30 0 1360450464 8809104 2973776
21:36:24 81380 8192000000 10280662800 10737778688 1370718032 14.333 99 21 42 0 1388269664 12908240 4643392
21:38:50 62845 8192000000 10280945808 10729996288 1340541424 14.062 96 22 44 0 1359924192 14579344 4803424
21:40:53 62975 8192000000 10288825696 10745888768 1376166608 14.382 99 13 26 0 1393371824 14524656 2680560
21:42:58 35785 8192000000 10291068816 10736279552 1371762032 14.343 99 23 43 0 1386361024 8824160 5774832
21:44:37 42147 8192000000 10299044272 10747781120 1379674848 14.414 99 29 58 0 1405182224 18976288 6531088
21:46:19 51947 8192000000 10290144928 10733699072 1352864496 14.173 98 25 50 0 1377809104 19179040 5765568
21:48:21 113899 8192000000 10280288240 10729037824 1292360992 13.626 94 12 24 0 1316281968 20918032 3002944
21:51:20 31593 8192000000 10292452368 10721366016 1364049104 14.274 100 34 66 0 1382817136 11530816 7237216
21:52:52 24765 8192000000 10287511104 10720104448 1338483872 14.044 98 30 58 2 1361022576 16273360 6265344
21:54:20 96667 8192000000 10262692496 10708004864 1323313040 13.907 97 17 34 1 1340018784 13377168 3328576
21:56:57 73826 8192000000 10312886416 10749460480 1377536032 14.395 100 38 76 0 1401567632 15727152 8304448
21:59:17 73599 8192000000 10291877408 10746793984 1376391440 14.384 99 24 42 0 1401579936 19869168 5319328
22:02:41 47006 8192000000 10280620416 10738282496 1381937872 14.434 100 15 30 0 1395970496 10742160 3290464
22:04:28 60456 8192000000 10295742784 10750578688 1379395408 14.411 99 17 34 0 1392587568 9814544 3377616
22:06:31 74399 8192000000 10289030480 10745847808 1378813504 14.406 99 7 14 0 1391750432 11393808 1543120
22:08:52 31263 8192000000 10294667472 10750259200 1370540288 14.332 98 15 30 0 1381755104 7937136 3277680
22:10:24 72264 8192000000 10302580848 10751123456 1375540064 14.377 98 16 32 0 1388913312 9580048 3793200
22:12:41 60568 8192000000 10292448448 10759872512 1372470272 14.349 99 13 26 0 1394381344 18397968 3513104
22:14:47 28277 8192000000 10296122176 10728361984 1354731616 14.190 98 49 98 0 1375285760 10222128 10332016
22:16:21 110244 8192000000 10298135872 10744205312 1318701904 13.865 95 17 34 0 1337971488 15275760 3993824
22:19:21 89318 8192000000 10246991552 10671996928 1228718496 13.042 90 15 31 0 1240265248 8088640 3458112
22:21:53 92980 8192000000 10232121504 10672123904 1309609440 13.783 97 37 74 0 1333356144 15950608 7796096
22:24:28 42047 8192000000 10251961488 10708348928 1360022912 14.238 100 13 26 0 1370293520 6927520 3343088
22:26:11 69086 8192000000 10268251552 10727026688 1363018192 14.264 99 13 23 0 1376149520 10875504 2255824
22:28:24 99226 8192000000 10265269968 10713530368 1339959904 14.057 98 8 16 0 1348912016 7404208 1547904
22:31:11 63989 8192000000 10248522336 10693488640 1314741968 13.829 97 16 32 0 1329689936 11295184 3652784
22:33:15 68410 8192000000 10276183072 10721390592 1332581248 13.990 97 18 36 0 1349671472 12524016 4566208
22:35:26 82566 8192000000 10246152224 10686754816 1262353664 13.352 93 10 20 0 1274502448 10019648 2129136
22:37:49 54672 8192000000 9088257136 9371783168 221215504 2.629 17 7 14 0 224853440 2238288 1399648

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 26-10-2022 22:39:33 to 26-10-2022 22:39:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 141 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-10-2022 22:39:49 to 26-10-2022 22:39:51.

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

26.10.22 22:39:53. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-10-26 22:39:55 No severe PSQL-related problems occured
26.10.22 22:39:56. End of report.