Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.10.2022 16: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 15285204 kB
MemAvailable 30561880 kB
Buffers 0 kB
Cached 16426540 kB
SwapCached 2136 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-04 16:38:29
DTS_END                         2022-10-04 16:38:29.3760
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/56172
TRN_ID                          243856
ATT_ID                          670
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: 9464.64 21894 1135757
customer order: creation 172.20 347 20664
customer order: refuse 15.13 350 1815
order to supplier: creation 46.65 1233 5598
order to supplier: removal 3.28 98960 393
invoice (draft): creation 45.94 3262 5513
invoice (draft): removal 53.48 824 6417
invoice accept: apply 43.22 16293 5186
invoice accept: cancel 48.86 762 5863
customer reserve: creation 3937.91 163 472549
customer reserve: removal 4791.49 51 574979
realization accept: apply 86.23 196 10347
realization accept: cancel 89.16 105 10699
payment from customer: creation 60.63 136 7275
payment from customer: removal 6.10 90 732
payment to supplier: creation 49.18 132 5901
payment to supplier: removal 3.19 62 383
service: total inventory turnovers 4.65 1125 558
service: total monetary turnovers 6.52 34 782
service: aggregate perf. data 0.83 20913 99
service: refresh index statistics 0.03 292851 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 04-10-2022 16:39:54 to 04-10-2022 16:39:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3662
TEST_TIME 2 3605
TEST_TIME 3 3529
TEST_TIME 4 3611
TEST_TIME 5 3874
TEST_TIME 6 4014
TEST_TIME 7 4258
TEST_TIME 8 4899
TEST_TIME 9 5678
TEST_TIME 10 6134
TEST_TIME 11 6433
TEST_TIME 12 6915
TEST_TIME 13 7067
TEST_TIME 14 7277
TEST_TIME 15 7585
TEST_TIME 16 7591
TEST_TIME 17 7959
TEST_TIME 18 8186
TEST_TIME 19 8314
TEST_TIME 20 8262
TEST_TIME 21 8196
TEST_TIME 22 8418
TEST_TIME 23 8600
TEST_TIME 24 8817
TEST_TIME 25 8787
TEST_TIME 26 8805
TEST_TIME 27 8956
TEST_TIME 28 9260
TEST_TIME 29 9864
TEST_TIME 30 10266
TEST_TIME 31 10144
TEST_TIME 32 10329
TEST_TIME 33 10332
TEST_TIME 34 10325
TEST_TIME 35 10300
TEST_TIME 36 10134
TEST_TIME 37 9996
TEST_TIME 38 10045
TEST_TIME 39 9927
TEST_TIME 40 9906
TEST_TIME 41 9879
TEST_TIME 42 9902
TEST_TIME 43 9845
TEST_TIME 44 9792
TEST_TIME 45 9841
TEST_TIME 46 9768
TEST_TIME 47 9820
TEST_TIME 48 9975
TEST_TIME 49 9877
TEST_TIME 50 9762
TEST_TIME 51 9650
TEST_TIME 52 9727
TEST_TIME 53 9780
TEST_TIME 54 9635
TEST_TIME 55 9541
TEST_TIME 56 9551
TEST_TIME 57 9529
TEST_TIME 58 9582
TEST_TIME 59 9661
TEST_TIME 60 9586
TEST_TIME 61 9451
TEST_TIME 62 9420
TEST_TIME 63 9390
TEST_TIME 64 9397
TEST_TIME 65 9437
TEST_TIME 66 9402
TEST_TIME 67 9472
TEST_TIME 68 9556
TEST_TIME 69 9519
TEST_TIME 70 9531
TEST_TIME 71 9598
TEST_TIME 72 9670
TEST_TIME 73 9655
TEST_TIME 74 9706
TEST_TIME 75 9676
TEST_TIME 76 9652
TEST_TIME 77 9680
TEST_TIME 78 9850
TEST_TIME 79 9757
TEST_TIME 80 9654
TEST_TIME 81 9697
TEST_TIME 82 9632
TEST_TIME 83 9576
TEST_TIME 84 9590
TEST_TIME 85 9565
TEST_TIME 86 9519
TEST_TIME 87 9523
TEST_TIME 88 9486
TEST_TIME 89 9458
TEST_TIME 90 9496
TEST_TIME 91 9434
TEST_TIME 92 9473
TEST_TIME 93 9475
TEST_TIME 94 9510
TEST_TIME 95 9490
TEST_TIME 96 9486
TEST_TIME 97 9510
TEST_TIME 98 9523
TEST_TIME 99 9555
TEST_TIME 100 9532
TEST_TIME 101 9615
TEST_TIME 102 9655
TEST_TIME 103 9644
TEST_TIME 104 9666
TEST_TIME 105 9629
TEST_TIME 106 9675
TEST_TIME 107 9607
TEST_TIME 108 9595
TEST_TIME 109 9573
TEST_TIME 110 9553
TEST_TIME 111 9558
TEST_TIME 112 9505
TEST_TIME 113 9489
TEST_TIME 114 9511
TEST_TIME 115 9455
TEST_TIME 116 9410
TEST_TIME 117 9442
TEST_TIME 118 9457
TEST_TIME 119 9437
TEST_TIME 120 9465

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-10-2022 16:39:58 to 04-10-2022 16:40:01.

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 1573141 1573141 0 0.00 0 21833 6 0 0
doc_list_biud 1573141 1573141 0 0.00 0 91515 25 0 0
sp_add_invoice_to_stock 5290 5186 104 1.96 27 200316 16294 0 104
sp_cancel_adding_invoice 5863 5863 0 0.00 5 64146 762 0 0
sp_cancel_client_order 1815 1815 0 0.00 4 9569 350 0 0
sp_cancel_customer_reserve 574979 574979 0 0.00 0 30874 52 0 0
sp_cancel_pay_from_customer 732 732 0 0.00 2 8127 90 0 0
sp_cancel_pay_to_supplier 383 383 0 0.00 3 2586 63 0 0
sp_cancel_supplier_invoice 6424 6417 7 0.10 2 91520 825 0 7
sp_cancel_supplier_order 393 393 0 0.00 5 1071639 98960 0 0
sp_cancel_write_off 10699 10699 0 0.00 1 18101 106 0 0
sp_client_order 20664 20664 0 0.00 3 17822 347 0 0
sp_customer_reserve 472549 472549 0 0.00 0 91811 164 0 0
sp_fill_shopping_cart 31766 31766 0 0.00 1 33672 42 0 0
sp_fill_shopping_cart_clo_res 472548 472548 0 0.00 0 27119 14 0 0
sp_get_clo_for_invoice 5185 5185 0 0.00 0 22399 260 0 0
sp_kill_cost_storno 17677 17677 0 0.00 0 7908 52 0 0
sp_kill_qstorno_ret_qs2qd 1167467 1167467 0 0.00 0 91513 29 0 0
sp_kill_qty_storno 615698 615698 0 0.00 0 91514 63 0 0
sp_lock_selected_doc 21391 21391 0 0.00 0 8085 4 0 0
sp_make_cost_storno 28708 28708 0 0.00 0 10177 54 0 0
sp_multiply_rows_for_pdistr 28708 28708 0 0.00 0 6160 26 0 0
sp_multiply_rows_for_qdistr 20664 20664 0 0.00 0 13247 149 0 0
sp_pay_from_customer 7275 7275 0 0.00 4 17930 136 0 0
sp_pay_to_supplier 5901 5901 0 0.00 5 13571 132 0 0
sp_payment_common 13176 13176 0 0.00 1 7176 35 0 0
sp_qd_handle_on_cancel_clo 1815 1815 0 0.00 0 9563 320 0 0
sp_qd_handle_on_invoice_upd_sts 11048 11048 0 0.00 0 17896 313 0 0
sp_qd_handle_on_reserve_upd_sts 10347 10347 0 0.00 0 22577 74 0 0
sp_reserve_write_off 10347 10347 0 0.00 6 22661 197 0 0
sp_supplier_invoice 5513 5513 0 0.00 6 99196 3262 0 0
sp_supplier_order 5598 5598 0 0.00 4 25587 1234 0 0
srv_aggregate_perf_data 99 99 0 0.00 2939 372085 20913 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1689 122391 47110 0 0
srv_find_qd_qs_mism 1087278 1087278 0 0.00 0 21832 5 0 0
srv_make_invnt_saldo 558 558 0 0.00 28 92815 1126 0 0
srv_make_money_saldo 782 782 0 0.00 1 5233 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 172580 358402 292852 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 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 5186 5186 0 0.00 0 54870 157 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 0 4646 91 0 0
t$perf-norm:sp_cancel_client_order 1815 1815 0 0.00 0 893 11 0 0
t$perf-norm:sp_cancel_customer_reserve 660 660 0 0.00 0 53831 97 0 0
t$perf-norm:sp_cancel_pay_from_customer 732 732 0 0.00 0 3346 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 383 383 0 0.00 0 5032 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 678 11 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 21892 1713 0 0
t$perf-norm:sp_cancel_write_off 1041 1041 0 0.00 0 3612 13 0 0
t$perf-norm:sp_client_order 20664 20664 0 0.00 0 7940 13 0 0
t$perf-norm:sp_customer_reserve 962 962 0 0.00 0 3377 9 0 0
t$perf-norm:sp_pay_from_customer 7275 7275 0 0.00 0 3856 9 0 0
t$perf-norm:sp_pay_to_supplier 5901 5901 0 0.00 0 5968 12 0 0
t$perf-norm:sp_reserve_write_off 10347 10347 0 0.00 0 19412 12 0 0
t$perf-norm:sp_supplier_invoice 5513 5513 0 0.00 0 5576 16 0 0
t$perf-norm:sp_supplier_order 5598 5598 0 0.00 0 7496 17 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 198 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 75 2 0 0
t$perf-norm:srv_make_invnt_saldo 558 558 0 0.00 0 1609 29 0 0
t$perf-norm:srv_make_money_saldo 782 782 0 0.00 0 5026 29 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 21 7 0 0
v_add_invoice_to_stock 10578 10370 208 1.96 0 10561 23 0 208
v_all_customers 37574 37574 0 0.00 0 109 1 0 0
v_all_suppliers 30436 30436 0 0.00 0 135 1 0 0
v_all_wares 41328 41328 0 0.00 0 37 1 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 2897 12 0 0
v_cancel_client_order 3630 3630 0 0.00 0 7296 8 0 0
v_cancel_customer_prepayment 1464 1464 0 0.00 0 8105 12 0 0
v_cancel_customer_reserve 1320 1320 0 0.00 0 1589 5 0 0
v_cancel_payment_to_supplier 766 766 0 0.00 0 1060 4 0 0
v_cancel_supplier_invoice 816 802 14 1.71 0 6724 27 0 14
v_cancel_supplier_order 786 786 0 0.00 0 1716 8 0 0
v_cancel_write_off 2082 2082 0 0.00 0 2045 13 0 0
v_max_id_clo_ord 5598 5598 0 0.00 0 2026 2 0 0
v_max_id_clo_res 962 962 0 0.00 0 2234 16 0 0
v_max_id_ord_sup 5504 5504 0 0.00 0 5022 6 0 0
v_max_non_paid_invoice 1785 1785 0 0.00 0 828 2 0 0
v_max_non_paid_realizn 3972 3972 0 0.00 0 5702 10 0 0
v_min_id_clo_ord 5598 5598 0 0.00 0 5372 8 0 0
v_min_id_clo_res 962 962 0 0.00 0 3654 7 0 0
v_min_id_ord_sup 5504 5504 0 0.00 0 11217 18 0 0
v_min_non_paid_invoice 5901 5901 0 0.00 0 7168 22 0 0
v_min_non_paid_realizn 7275 7275 0 0.00 0 6274 23 0 0
v_our_firm 10360 10360 0 0.00 0 16 1 0 0
v_reserve_write_off 20694 20694 0 0.00 0 10565 9 0 0
x_lock_depdocs_on_canc_invoice 11879 11879 0 0.00 0 26356 129 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 20611 429 0 0
x_make_qstorno_1000_1200 5598 5598 0 0.00 1 25527 1155 0 0
x_make_qstorno_1200_2000 5513 5513 0 0.00 3 97512 3102 0 0
x_make_qstorno_2100_3300 472549 472549 0 0.00 0 91809 147 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 04-10-2022 16:40:02 to 04-10-2022 16:40:07.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:32 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:33 30911 8192000000 9906499136 10215542784 986017264 10.743 100 88 176 0 1015555376 6358112 23180000
14:11:13 17127 8192000000 9941135808 10260066304 1025310160 11.123 100 91 182 0 1057655248 8970592 23374496
14:12:44 12853 8192000000 9975363152 10305028096 1060396064 11.460 100 82 164 0 1090665184 9847904 20421216
14:14:10 6584 8192000000 10039906928 10385883136 1123088944 12.056 100 78 156 0 1151940064 10702784 18148336
14:15:20 6273 8192000000 10084412320 10440790016 1169448416 12.492 100 80 160 0 1199401408 11558144 18394848
14:16:31 4418 8192000000 10131088000 10497130496 1213878304 12.905 100 82 164 0 1246130016 13699664 18552048
14:17:38 1346 8192000000 10167658960 10541355008 1252860544 13.264 100 89 176 0 1285109808 13397136 18852128
14:18:41 1655 8192000000 10182808288 10567909376 1275587328 13.473 100 84 167 0 1307015584 12910928 18517328
14:19:44 2772 8192000000 10190495856 10585976832 1289864272 13.603 100 59 118 0 1313992624 11416640 12711712
14:20:48 14688 8192000000 10184308128 10596564992 1300135408 13.696 100 16 32 0 1309885456 6385264 3364784
14:22:04 47721 8192000000 10213675568 10619162624 1313411648 13.817 100 52 102 1 1332172624 4969760 13791216
14:23:53 5487 8192000000 10206748208 10622353408 1317609632 13.855 100 37 74 0 1333406352 7322928 8473792
14:24:59 8890 8192000000 10215115728 10633723904 1323105440 13.905 100 19 38 0 1333662944 6423904 4133600
14:26:13 61654 8192000000 10209030144 10631938048 1312640576 13.810 99 21 40 0 1322106016 4653296 4812144
14:28:19 6220 8192000000 10213091536 10629304320 1311663888 13.801 99 24 48 0 1323506448 6416880 5425680
14:29:26 6395 8192000000 10164442416 10575065088 1280528352 13.518 99 18 37 2 1290105536 5430624 4146560
14:30:34 59304 8192000000 10121321472 10501517312 1134928592 12.168 90 39 79 0 1153592000 9606320 9057088
14:32:34 4842 8192000000 10127609024 10521989120 1231857920 13.071 100 36 72 1 1247535728 6783072 8894736
14:33:40 11618 8192000000 10155881552 10553274368 1237273696 13.121 98 27 53 0 1248255584 5248784 5733104
14:34:56 13735 8192000000 10171003264 10579771392 1281875840 13.530 100 19 38 0 1292069040 6168992 4024208
14:36:11 16455 8192000000 10172623504 10585280512 1285498480 13.563 100 16 32 1 1294152672 4849184 3805008
14:37:31 6126 8192000000 10186217536 10601369600 1300609168 13.701 100 24 49 0 1311143248 5390208 5143872
14:38:44 15498 8192000000 10230538080 10635751424 1325367696 13.925 100 42 82 0 1340243856 5484816 9391344
14:40:07 17784 8192000000 10258539840 10667884544 1346929264 14.120 100 51 98 0 1368443088 10529648 10984176
14:41:30 79281 8192000000 10260024816 10685071360 1360620720 14.243 100 40 78 1 1383906768 13977232 9308816
14:43:52 32864 8192000000 10281896880 10700021760 1371249088 14.338 100 35 69 0 1395165024 15826912 8089024
14:45:58 19695 8192000000 10280443888 10699739136 1364978000 14.282 99 46 92 0 1395121552 18809952 11333600
14:47:21 4204 8192000000 10320266944 10731855872 1383462080 14.447 100 64 127 1 1424622704 25355488 15805136
14:48:28 19561 8192000000 10296271216 10715860992 1364033952 14.274 99 61 121 1 1394747904 16222560 14491392
14:49:59 1819 8192000000 10332429840 10746351616 1394333152 14.545 100 90 174 2 1438539424 23510496 20695776
14:51:04 7536 8192000000 10310025600 10734264320 1379060672 14.408 99 45 89 1 1416332464 26938544 10333248
14:52:16 3288 8192000000 10333513136 10752544768 1385790464 14.468 99 75 151 2 1428155104 24906832 17457808
14:53:21 4960 8192000000 10314483872 10743959552 1394001472 14.542 100 51 102 1 1432571104 25966224 12603408
14:54:29 3422 8192000000 10307593424 10718527488 1363220256 14.266 100 64 126 1 1414203600 34599920 16383424
14:55:36 7106 8192000000 10296890992 10703138816 1332513408 13.990 98 59 118 0 1384311120 36885744 14911968
14:56:48 1688 8192000000 10325029920 10723315712 1367636272 14.306 100 86 173 0 1423331664 34677680 21017712
14:57:52 16900 8192000000 10287265232 10705801216 1346979440 14.120 99 47 94 1 1392808048 34084864 11743744
14:59:16 7570 8192000000 10305377744 10727301120 1372480080 14.349 100 45 90 1 1418104800 34416880 11207840
15:00:26 9781 8192000000 10288416256 10708422656 1349943232 14.147 99 38 77 0 1394764336 34276144 10544960
15:01:44 5119 8192000000 10315872432 10731864064 1371341728 14.339 100 62 123 2 1425448720 38381280 15725712
15:02:54 14341 8192000000 10310908192 10730348544 1372352272 14.348 100 40 78 0 1415497072 33136208 10008592
15:04:20 20737 8192000000 10288603696 10708963328 1343231792 14.087 99 30 58 0 1391444624 39653904 8558928
15:05:43 21253 8192000000 10280739392 10710429696 1331263584 13.979 99 21 42 1 1381480944 43485360 6732000
15:07:09 11136 8192000000 10296489376 10722344960 1346058400 14.112 100 36 71 1 1399042640 43558448 9425792
15:08:34 37747 8192000000 10261370928 10691506176 1331869200 13.984 98 19 38 0 1353045248 16313552 4862496
15:10:14 40057 8192000000 10262785296 10697932800 1355747136 14.199 100 37 73 1 1375153360 11822000 7584224
15:12:02 93163 8192000000 10263303648 10715496448 1334671520 14.009 98 5 10 0 1344105520 8584304 849696
15:15:32 65598 8192000000 10299263664 10735747072 1382911872 14.443 100 30 60 0 1404064736 14485264 6667600
15:17:39 48398 8192000000 10295531312 10734469120 1358677584 14.225 98 30 61 0 1372347264 7027904 6641776
15:19:32 70666 8192000000 10296746256 10749161472 1386169120 14.472 100 6 12 0 1397704976 10161040 1374816
15:21:44 44907 8192000000 10296361680 10746167296 1389986736 14.506 100 23 44 1 1402087472 7050816 5049920
15:23:39 38951 8192000000 10300098144 10745765888 1375671296 14.378 99 19 37 0 1387128416 7285232 4171888
15:25:19 118936 8192000000 10294870896 10751184896 1362057296 14.256 98 5 8 0 1377755712 14812688 885728
15:28:19 122391 8192000000 10312013552 10762522624 1383899008 14.451 99 27 54 2 1402782272 11962656 6920608
15:31:30 16006 8192000000 10295164368 10740428800 1372220304 14.347 99 46 91 0 1392565552 9887664 10457584
15:32:51 23219 8192000000 10289955056 10738630656 1357558448 14.215 98 11 20 0 1368520496 7914048 3048000
15:34:15 51052 8192000000 10286191424 10738274304 1347942368 14.129 97 24 48 1 1371192192 17448368 5801456
15:36:08 69469 8192000000 10238625232 10696376320 1309092288 13.778 95 17 32 0 1328349680 15529136 3728256
15:38:18 37737 8192000000 10255150016 10710933504 1343583152 14.090 99 7 14 0 1354209520 9231392 1394976
15:39:56 41448 8192000000 10257052512 10718167040 1352555056 14.170 99 13 26 0 1364956656 9661408 2740192
15:41:46 51701 8192000000 10265207408 10724626432 1336893424 14.029 98 12 24 0 1356515216 17049568 2572224
15:43:40 58793 8192000000 10253339296 10703601664 1250571568 13.243 91 22 44 0 1265351792 9702048 5078176
15:45:40 67677 8192000000 10248845616 10700578816 1305228224 13.743 96 17 34 0 1326031696 16685312 4118160
15:47:48 29109 8192000000 10252807424 10686480384 1265673296 13.382 93 42 85 1 1287852208 11880944 10297968
15:49:18 94434 8192000000 10275758384 10726535168 1344424880 14.097 99 13 26 1 1364335360 16794608 3115872
15:51:57 85068 8192000000 10265943040 10712317952 1295379392 13.653 95 22 42 0 1320159024 19878240 4901392
15:54:23 17817 8192000000 10273252080 10712649728 1347145760 14.122 99 27 54 0 1362916272 9818928 5951584
15:55:41 71640 8192000000 10275813888 10750177280 1354162032 14.185 99 10 20 0 1378530784 21870448 2498304
15:58:03 112022 8192000000 10263985328 10713632768 1341256016 14.069 98 19 38 0 1357430544 11806400 4368128
16:00:57 56553 8192000000 10280658224 10730696704 1360607296 14.243 99 28 56 0 1376478816 9315168 6556352
16:03:00 100144 8192000000 10262897424 10717581312 1312950064 13.813 95 14 28 0 1324260576 8218016 3092496
16:05:41 59119 8192000000 10273820112 10734239744 1369970848 14.327 100 18 36 0 1389306384 14905280 4430256
16:08:02 66967 8192000000 10279157328 10750492672 1374481264 14.367 100 13 26 0 1390147968 12871840 2794864
16:10:20 76690 8192000000 10288984720 10752036864 1387306128 14.482 100 21 42 0 1409056480 16929968 4820384
16:12:37 81191 8192000000 10323335008 10764062720 1391067312 14.515 99 28 57 1 1406555824 8318176 7170336
16:15:01 117865 8192000000 10301666080 10763169792 1335845936 14.020 95 15 30 0 1351945280 12626400 3472944
16:17:59 63213 8192000000 10317833264 10773577728 1385477424 14.465 99 17 34 0 1410616896 21104880 4034592
16:20:03 100628 8192000000 10281003808 10738655232 1336079760 14.022 96 12 25 0 1350997296 12437024 2480512
16:22:44 43337 8192000000 10306725120 10753830912 1389737936 14.504 100 31 62 0 1409204896 12429264 7037696
16:24:32 33772 8192000000 10292667072 10748059648 1373767904 14.361 100 43 86 0 1399507840 15844256 9895680
16:27:15 56475 8192000000 10290030160 10745565184 1358397712 14.223 98 26 52 1 1379549744 15100688 6051344
16:29:14 40506 8192000000 10275989968 10743877632 1353905296 14.183 98 8 16 0 1371713184 15415904 2391984
16:30:58 81068 8192000000 10259563280 10728054784 1334293136 14.006 97 13 26 0 1350635344 13010352 3331856
16:33:21 83596 8192000000 10293429168 10749648896 1372621664 14.351 99 16 32 0 1389946016 13339920 3984432
16:36:23 73141 8192000000 10284169888 10732675072 1344822688 14.101 98 15 30 1 1364979504 16677712 3479104

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-10-2022 16:40:09 to 04-10-2022 16:40:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 104 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 04-10-2022 16:40:25 to 04-10-2022 16:40:27.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          242859
MON$OLDEST_ACTIVE               243968
MON$OLDEST_SNAPSHOT             243968
MON$NEXT_TRANSACTION            243968
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                       2059296
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.33632 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33632 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33632 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33632 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 ---
:186: 
:187: mscs-test-161.budzdorov.ru    Tue Oct  4 16:38:41 2022
:188:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:189: 
--- end of diff output ---

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

04.10.22 16:40:28. 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-04 16:40:30 No severe PSQL-related problems occured
04.10.22 16:40:32. End of report.