Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.08.2022 19:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15975872 kB
MemAvailable 30972004 kB
Buffers 0 kB
Cached 15765064 kB
SwapCached 2512 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-08-09 19:38:28
DTS_END                         2022-08-09 19:38:28.3170
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/46834
TRN_ID                          241588
ATT_ID                          665
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: 9453.82 16696 1134458
customer order: creation 176.70 360 21204
customer order: refuse 14.53 342 1743
order to supplier: creation 47.03 1194 5644
order to supplier: removal 3.48 100962 418
invoice (draft): creation 47.02 3154 5642
invoice (draft): removal 52.09 798 6251
invoice accept: apply 43.63 16645 5235
invoice accept: cancel 47.24 739 5669
customer reserve: creation 4066.48 163 487978
customer reserve: removal 4660.09 59 559211
realization accept: apply 86.20 179 10344
realization accept: cancel 76.78 110 9213
payment from customer: creation 60.58 129 7269
payment from customer: removal 6.19 102 743
payment to supplier: creation 49.63 171 5956
payment to supplier: removal 3.89 59 467
service: total inventory turnovers 4.70 1061 564
service: total monetary turnovers 6.61 24 793
service: aggregate perf. data 0.92 17572 110
service: refresh index statistics 0.03 190101 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-08-2022 19:39:53 to 09-08-2022 19:39:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5019
TEST_TIME 2 5154
TEST_TIME 3 4429
TEST_TIME 4 4853
TEST_TIME 5 5115
TEST_TIME 6 5762
TEST_TIME 7 5677
TEST_TIME 8 5534
TEST_TIME 9 5299
TEST_TIME 10 5322
TEST_TIME 11 5482
TEST_TIME 12 5527
TEST_TIME 13 5761
TEST_TIME 14 5856
TEST_TIME 15 6229
TEST_TIME 16 6471
TEST_TIME 17 6703
TEST_TIME 18 6897
TEST_TIME 19 7303
TEST_TIME 20 7715
TEST_TIME 21 8171
TEST_TIME 22 7983
TEST_TIME 23 8198
TEST_TIME 24 8412
TEST_TIME 25 8542
TEST_TIME 26 8849
TEST_TIME 27 9364
TEST_TIME 28 9660
TEST_TIME 29 10649
TEST_TIME 30 10586
TEST_TIME 31 10443
TEST_TIME 32 10506
TEST_TIME 33 10431
TEST_TIME 34 10165
TEST_TIME 35 10113
TEST_TIME 36 10114
TEST_TIME 37 10067
TEST_TIME 38 9892
TEST_TIME 39 9828
TEST_TIME 40 9765
TEST_TIME 41 9910
TEST_TIME 42 9977
TEST_TIME 43 9934
TEST_TIME 44 9876
TEST_TIME 45 9792
TEST_TIME 46 9734
TEST_TIME 47 9603
TEST_TIME 48 9554
TEST_TIME 49 9577
TEST_TIME 50 9495
TEST_TIME 51 9535
TEST_TIME 52 9568
TEST_TIME 53 9591
TEST_TIME 54 9557
TEST_TIME 55 9576
TEST_TIME 56 9642
TEST_TIME 57 9840
TEST_TIME 58 9939
TEST_TIME 59 9943
TEST_TIME 60 9988
TEST_TIME 61 10008
TEST_TIME 62 10001
TEST_TIME 63 10023
TEST_TIME 64 9888
TEST_TIME 65 9936
TEST_TIME 66 9859
TEST_TIME 67 9889
TEST_TIME 68 9837
TEST_TIME 69 9811
TEST_TIME 70 9814
TEST_TIME 71 9758
TEST_TIME 72 9692
TEST_TIME 73 9743
TEST_TIME 74 9709
TEST_TIME 75 9669
TEST_TIME 76 9631
TEST_TIME 77 9690
TEST_TIME 78 9645
TEST_TIME 79 9675
TEST_TIME 80 9768
TEST_TIME 81 9779
TEST_TIME 82 9797
TEST_TIME 83 9812
TEST_TIME 84 9760
TEST_TIME 85 9887
TEST_TIME 86 9856
TEST_TIME 87 9749
TEST_TIME 88 9672
TEST_TIME 89 9669
TEST_TIME 90 9599
TEST_TIME 91 9573
TEST_TIME 92 9608
TEST_TIME 93 9589
TEST_TIME 94 9560
TEST_TIME 95 9612
TEST_TIME 96 9638
TEST_TIME 97 9663
TEST_TIME 98 9667
TEST_TIME 99 9639
TEST_TIME 100 9683
TEST_TIME 101 9737
TEST_TIME 102 9742
TEST_TIME 103 9771
TEST_TIME 104 9742
TEST_TIME 105 9792
TEST_TIME 106 9824
TEST_TIME 107 9800
TEST_TIME 108 9731
TEST_TIME 109 9715
TEST_TIME 110 9694
TEST_TIME 111 9656
TEST_TIME 112 9662
TEST_TIME 113 9634
TEST_TIME 114 9608
TEST_TIME 115 9572
TEST_TIME 116 9504
TEST_TIME 117 9535
TEST_TIME 118 9495
TEST_TIME 119 9468
TEST_TIME 120 9454

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-08-2022 19:39:56 to 09-08-2022 19:40:00.

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 1584093 1584093 0 0.00 0 20634 6 0 0
doc_list_biud 1584093 1584093 0 0.00 0 46157 26 0 0
sp_add_invoice_to_stock 5349 5235 114 2.13 44 241131 16645 0 114
sp_cancel_adding_invoice 5669 5669 0 0.00 4 64614 739 0 0
sp_cancel_client_order 1743 1743 0 0.00 4 11908 342 0 0
sp_cancel_customer_reserve 559211 559211 0 0.00 0 46160 59 0 0
sp_cancel_pay_from_customer 743 743 0 0.00 2 15561 103 0 0
sp_cancel_pay_to_supplier 467 467 0 0.00 3 1992 59 0 0
sp_cancel_supplier_invoice 6264 6251 13 0.20 1 16600 798 0 13
sp_cancel_supplier_order 418 418 0 0.00 8 1123658 100963 0 0
sp_cancel_write_off 9213 9213 0 0.00 1 10481 111 0 0
sp_client_order 21204 21204 0 0.00 3 54180 360 0 0
sp_customer_reserve 487978 487978 0 0.00 0 125930 164 0 0
sp_fill_shopping_cart 32482 32482 0 0.00 1 10317 36 0 0
sp_fill_shopping_cart_clo_res 487977 487977 0 0.00 0 18623 13 0 0
sp_get_clo_for_invoice 5234 5234 0 0.00 0 19316 260 0 0
sp_kill_cost_storno 16092 16092 0 0.00 0 7887 54 0 0
sp_kill_qstorno_ret_qs2qd 1134304 1134304 0 0.00 0 46153 32 0 0
sp_kill_qty_storno 598083 598083 0 0.00 0 46157 69 0 0
sp_lock_selected_doc 21461 21461 0 0.00 0 8394 6 0 0
sp_make_cost_storno 28803 28803 0 0.00 0 20416 57 0 0
sp_multiply_rows_for_pdistr 28803 28803 0 0.00 0 19665 25 0 0
sp_multiply_rows_for_qdistr 21204 21204 0 0.00 0 54171 173 0 0
sp_pay_from_customer 7269 7269 0 0.00 4 23608 129 0 0
sp_pay_to_supplier 5956 5956 0 0.00 4 20223 171 0 0
sp_payment_common 13225 13225 0 0.00 1 19582 42 0 0
sp_qd_handle_on_cancel_clo 1743 1743 0 0.00 0 10133 311 0 0
sp_qd_handle_on_invoice_upd_sts 10903 10903 0 0.00 0 40030 305 0 0
sp_qd_handle_on_reserve_upd_sts 10344 10344 0 0.00 0 14598 60 0 0
sp_reserve_write_off 10344 10344 0 0.00 5 20368 179 0 0
sp_supplier_invoice 5642 5642 0 0.00 7 85556 3155 0 0
sp_supplier_order 5644 5644 0 0.00 5 19423 1195 0 0
srv_aggregate_perf_data 110 110 0 0.00 2629 189379 17572 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1298 161703 45059 0 0
srv_find_qd_qs_mism 1083875 1083875 0 0.00 0 12972 5 0 0
srv_make_invnt_saldo 564 564 0 0.00 51 67242 1061 0 0
srv_make_money_saldo 793 793 0 0.00 0 2873 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 103528 365184 190102 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 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 5235 5235 0 0.00 0 10996 161 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 0 4563 116 0 0
t$perf-norm:sp_cancel_client_order 1743 1743 0 0.00 0 5572 20 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 1621 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 743 743 0 0.00 0 663 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 467 467 0 0.00 0 3391 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 659 13 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 27328 1755 0 0
t$perf-norm:sp_cancel_write_off 1035 1035 0 0.00 0 3410 9 0 0
t$perf-norm:sp_client_order 21204 21204 0 0.00 0 11505 20 0 0
t$perf-norm:sp_customer_reserve 1081 1081 0 0.00 0 4512 13 0 0
t$perf-norm:sp_pay_from_customer 7269 7269 0 0.00 0 10469 9 0 0
t$perf-norm:sp_pay_to_supplier 5956 5956 0 0.00 0 7154 14 0 0
t$perf-norm:sp_reserve_write_off 10344 10344 0 0.00 0 14108 13 0 0
t$perf-norm:sp_supplier_invoice 5642 5642 0 0.00 0 4079 16 0 0
t$perf-norm:sp_supplier_order 5644 5644 0 0.00 0 4832 18 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 526 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 133 2 0 0
t$perf-norm:srv_make_invnt_saldo 564 564 0 0.00 0 1044 14 0 0
t$perf-norm:srv_make_money_saldo 793 793 0 0.00 0 3565 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 5 0 0
v_add_invoice_to_stock 10690 10462 228 2.13 0 15586 26 0 228
v_all_customers 38152 38152 0 0.00 0 140 1 0 0
v_all_suppliers 31026 31026 0 0.00 0 27 1 0 0
v_all_wares 42408 42408 0 0.00 0 39 1 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 287 5 0 0
v_cancel_client_order 3486 3486 0 0.00 0 11839 8 0 0
v_cancel_customer_prepayment 1486 1486 0 0.00 0 15551 16 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 4141 8 0 0
v_cancel_payment_to_supplier 934 934 0 0.00 0 1954 7 0 0
v_cancel_supplier_invoice 820 794 26 3.17 0 469 15 0 26
v_cancel_supplier_order 836 836 0 0.00 0 727 6 0 0
v_cancel_write_off 2070 2070 0 0.00 0 2355 14 0 0
v_max_id_clo_ord 5644 5644 0 0.00 0 410 1 0 0
v_max_id_clo_res 1081 1081 0 0.00 0 1583 11 0 0
v_max_id_ord_sup 5634 5634 0 0.00 0 6349 6 0 0
v_max_non_paid_invoice 1721 1721 0 0.00 0 557 3 0 0
v_max_non_paid_realizn 4040 4040 0 0.00 0 19261 8 0 0
v_min_id_clo_ord 5644 5644 0 0.00 0 2061 5 0 0
v_min_id_clo_res 1081 1081 0 0.00 0 1788 7 0 0
v_min_id_ord_sup 5634 5634 0 0.00 0 6943 7 0 0
v_min_non_paid_invoice 5956 5956 0 0.00 0 18901 31 0 0
v_min_non_paid_realizn 7269 7269 0 0.00 0 19563 23 0 0
v_our_firm 10714 10714 0 0.00 0 22 1 0 0
v_reserve_write_off 20688 20688 0 0.00 0 14067 8 0 0
x_lock_depdocs_on_canc_invoice 11523 11523 0 0.00 0 13827 114 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 13824 362 0 0
x_make_qstorno_1000_1200 5644 5644 0 0.00 1 19417 1120 0 0
x_make_qstorno_1200_2000 5642 5642 0 0.00 2 83897 3026 0 0
x_make_qstorno_2100_3300 487978 487978 0 0.00 0 125929 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-08-2022 19:40:01 to 09-08-2022 19:40:05.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:31 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:32 21887 8192000000 9895459088 10206777344 982434624 10.708 100 85 169 0 1010134256 5660800 22038832
17:11:02 20033 8192000000 9949224192 10272886784 1033142032 11.199 100 87 174 0 1063734544 8388864 22203648
17:12:27 18991 8192000000 9985881584 10318802944 1064778096 11.502 100 85 170 0 1096135680 10136768 21220816
17:13:49 9320 8192000000 10017464672 10363015168 1105226752 11.887 100 78 156 0 1134768640 11073456 18468432
17:15:01 6790 8192000000 10063528480 10419589120 1154628640 12.353 100 80 160 0 1185134208 12137712 18367856
17:16:10 3327 8192000000 10104294928 10470219776 1192985056 12.711 100 78 156 0 1222389152 12323712 17080384
17:17:17 2577 8192000000 10146582144 10516926464 1232293568 13.075 100 82 160 0 1263134016 12490416 18350032
17:18:21 2126 8192000000 10167415088 10545524736 1253969088 13.275 100 83 163 2 1286313536 14434912 17909536
17:19:25 1422 8192000000 10201464896 10580881408 1282167056 13.533 100 93 183 1 1315436800 13647536 19622208
17:20:28 39382 8192000000 10190951968 10590781440 1296239408 13.661 100 61 123 0 1319199328 8466128 14493792
17:22:08 10917 8192000000 10197401920 10610487296 1309440576 13.781 100 15 30 0 1318968272 6149264 3378432
17:23:22 6959 8192000000 10205062272 10618208256 1315723088 13.838 100 30 60 0 1327889232 5651440 6514704
17:24:30 8265 8192000000 10207666848 10624204800 1321212608 13.888 100 17 35 0 1330587920 5958176 3417136
17:25:42 55780 8192000000 10215975936 10639822848 1327805088 13.947 100 25 50 1 1338830864 5641536 5384240
17:27:38 12137 8192000000 10208183088 10631847936 1293804128 13.639 97 17 34 0 1302779760 5263392 3712240
17:28:55 7852 8192000000 10215265136 10640486400 1305956656 13.749 98 22 45 1 1321148416 10374272 4817488
17:30:06 65243 8192000000 10170322832 10563198976 1219632864 12.958 95 38 76 1 1233203888 4858768 8712256
17:32:12 7671 8192000000 10145243920 10544435200 1243108224 13.175 99 23 46 0 1254314448 6013680 5192544
17:33:20 9628 8192000000 10162798160 10565173248 1236185344 13.111 97 12 24 1 1244232688 5011872 3035472
17:34:32 40251 8192000000 10162965488 10575839232 1261305216 13.342 98 25 46 2 1272314720 5498960 5510544
17:36:37 4432 8192000000 10185415600 10595655680 1281489328 13.527 99 33 66 2 1295212000 6533040 7189632
17:37:42 11731 8192000000 10201754976 10617683968 1307754784 13.766 100 13 26 0 1316213888 5169184 3289920
17:38:57 1697 8192000000 10249657024 10656362496 1342017392 14.076 100 57 113 2 1361660848 6351456 13292000
17:40:03 50390 8192000000 10255659520 10678206464 1352986032 14.174 100 52 104 0 1376219776 10455056 12778688
17:42:03 5594 8192000000 10274431056 10695569408 1367916208 14.308 100 43 86 0 1386668752 9391088 9361456
17:43:14 5364 8192000000 10300336208 10719059968 1382580192 14.440 100 61 118 1 1408863840 13096048 13187600
17:44:25 7338 8192000000 10302675824 10726748160 1388661296 14.494 100 50 98 0 1416338944 16008624 11669024
17:45:37 18674 8192000000 10308105968 10735644672 1397919488 14.576 100 37 74 1 1422374160 15298896 9155776
17:47:03 12886 8192000000 10324132400 10748768256 1406129824 14.650 100 44 88 0 1433379280 16795472 10453984
17:48:21 17402 8192000000 10336558768 10766131200 1414648128 14.725 100 39 79 1 1443021536 18773616 9599792
17:49:43 10796 8192000000 10350692928 10778611712 1410791072 14.691 99 46 90 0 1445287408 23519776 10976560
17:50:57 3326 8192000000 10353558880 10772185088 1417964256 14.755 100 62 125 1 1460119584 27629232 14526096
17:52:07 6061 8192000000 10362919888 10783260672 1413062464 14.711 99 72 144 1 1464319808 32913888 18343456
17:53:18 10282 8192000000 10377436912 10794504192 1423286672 14.802 100 67 131 1 1481446800 40905264 17254864
17:54:35 2425 8192000000 10362013712 10784313344 1400331568 14.598 99 56 112 0 1458400960 42909568 15159824
17:55:43 7003 8192000000 10372388512 10791038976 1412297072 14.704 100 67 134 0 1475874720 46185488 17392160
17:56:55 1295 8192000000 10372529936 10782040064 1405276800 14.642 100 80 160 0 1472590352 46393696 20919856
17:58:02 2453 8192000000 10349969760 10758774784 1382288256 14.437 100 86 172 1 1450790400 46614864 21887280
17:59:09 9347 8192000000 10318968352 10751725568 1371597168 14.341 99 53 106 0 1427452336 42425840 13429328
18:00:35 3558 8192000000 10345096960 10766987264 1392839888 14.531 100 53 106 0 1449565520 43200976 13524656
18:01:42 21176 8192000000 10338307952 10770505728 1379779248 14.415 99 34 68 0 1434515056 44906016 9829792
18:03:05 16699 8192000000 10316847648 10753175552 1369924208 14.326 99 29 57 0 1421462544 43097584 8440752
18:04:31 11940 8192000000 10315718144 10744610816 1360867744 14.245 100 35 68 0 1420833328 50396576 9569008
18:05:50 35955 8192000000 10264012464 10700529664 1299073424 13.687 96 20 40 0 1347422160 42396224 5952512
18:07:31 13460 8192000000 10268512784 10711543808 1323776672 13.911 98 24 48 0 1367263856 36858496 6628688
18:08:50 47905 8192000000 10265459120 10701213696 1317133072 13.851 97 20 40 0 1328120400 6969632 4017696
18:10:51 161703 8192000000 10272664304 10710667264 1301403136 13.708 95 24 48 1 1318135824 12077360 4655328
18:14:37 58668 8192000000 10277596880 10729754624 1348061408 14.130 98 27 55 0 1368218416 13978416 6178592
18:16:40 140402 8192000000 10304770752 10747772928 1352836448 14.173 98 24 47 0 1368636208 9754608 6045152
18:20:03 20672 8192000000 10294571328 10758262784 1390081264 14.507 100 21 42 0 1405244832 10217664 4945904
18:21:27 91069 8192000000 10270171040 10730487808 1350391280 14.151 97 13 25 0 1361426592 8499328 2535984
18:23:59 101741 8192000000 10289830864 10749304832 1392800240 14.531 100 24 49 0 1408589488 10071664 5717584
18:26:43 48527 8192000000 10290402224 10756190208 1398776960 14.584 100 11 20 1 1412030384 10658800 2594624
18:28:37 57868 8192000000 10304874912 10772869120 1403445920 14.626 100 9 18 0 1416842352 11342864 2053568
18:30:39 50098 8192000000 10293058512 10756935680 1352417936 14.169 96 21 42 0 1364904896 7886672 4600288
18:32:55 39610 8192000000 10326571888 10780917760 1385399776 14.465 98 22 44 1 1413603488 23010752 5192960
18:34:39 45043 8192000000 10309680720 10764689408 1384674992 14.458 98 27 55 1 1412248192 20936000 6637200
18:36:25 82043 8192000000 10294940736 10755981312 1329905584 13.966 95 9 18 0 1349275632 17213344 2156704
18:38:47 60154 8192000000 10281779968 10737418240 1347493712 14.125 98 18 36 0 1361451888 9931088 4027088
18:40:47 31866 8192000000 10264279744 10718932992 1320802448 13.884 97 17 34 0 1340876576 16250768 3823360
18:42:20 116545 8192000000 10269829568 10714267648 1302188464 13.715 95 42 85 0 1322975952 11033856 9753632
18:45:17 27192 8192000000 10267111920 10731511808 1363054480 14.265 100 26 51 0 1388281424 19765168 5461776
18:46:44 57986 8192000000 10249763392 10707353600 1343954720 14.093 99 14 28 0 1355976672 8746976 3274976
18:48:50 76613 8192000000 10258449104 10712961024 1328941552 13.958 97 13 25 0 1341347024 9576256 2829216
18:51:10 61507 8192000000 10243605504 10705113088 1317512736 13.854 97 7 14 0 1329095328 10078416 1504176
18:53:16 12853 8192000000 10246945264 10685771776 1340583296 14.063 100 48 92 0 1356890368 6136832 10170240
18:54:32 20202 8192000000 10254504832 10686726144 1338255680 14.042 100 46 92 0 1359150096 10959584 9934832
18:55:57 37743 8192000000 10252042448 10697043968 1336581744 14.027 99 23 46 0 1353289616 11844128 4863744
18:57:35 50095 8192000000 10265081744 10717368320 1356781088 14.208 100 38 74 0 1377403376 12132336 8489952
18:59:26 54299 8192000000 10272430144 10726850560 1365281072 14.285 100 17 34 0 1378928688 9989584 3658032
19:01:20 58732 8192000000 10294164032 10734452736 1368109616 14.310 99 30 56 0 1382679808 8563008 6007184
19:03:19 110798 8192000000 10272247856 10739924992 1342621888 14.081 98 10 20 0 1361339840 16723760 1994192
19:06:19 23569 8192000000 10289889328 10733395968 1377199472 14.392 100 33 64 0 1391676320 7567936 6908912
19:07:45 67169 8192000000 10276039296 10732560384 1373871712 14.362 99 12 24 0 1386819024 10690256 2257056
19:09:52 64539 8192000000 10286839664 10763366400 1391975600 14.523 100 8 16 0 1403619776 9825136 1819040
19:11:58 45097 8192000000 10299976608 10760560640 1401934288 14.612 100 17 34 0 1413643104 8078576 3630240
19:13:43 83855 8192000000 10306079232 10785411072 1398266992 14.580 100 8 16 0 1410369568 10409200 1693376
19:16:16 55667 8192000000 10294019360 10745053184 1364546624 14.278 98 24 46 1 1382711024 13239872 4924528
19:18:27 52075 8192000000 10300561680 10770116608 1369536608 14.323 98 16 32 0 1388913776 16111760 3265408
19:20:51 74885 8192000000 10278131584 10729213952 1288518320 13.591 93 10 20 0 1304607216 13800000 2288896
19:23:10 84527 8192000000 10292414976 10743447552 1357541744 14.215 99 11 22 1 1382371888 22557232 2272912
19:26:02 104267 8192000000 10271637728 10723028992 1352324800 14.168 98 30 59 0 1366225376 7306368 6594208
19:28:47 50811 8192000000 10268883056 10730459136 1352957824 14.174 98 14 28 0 1365363616 9243760 3162032
19:30:39 33276 8192000000 10251323920 10719281152 1337067488 14.031 98 9 18 1 1346505088 7698832 1738768
19:32:13 59989 8192000000 10261514896 10717188096 1345282656 14.105 98 16 32 0 1356956160 8075168 3598336
19:34:32 65476 8192000000 10253415168 10716131328 1348760656 14.136 99 17 34 0 1365062832 12692256 3609920
19:36:40 66499 8192000000 10269836128 10730962944 1353660608 14.180 99 13 26 1 1369768176 13603136 2504432

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 09-08-2022 19:40:07 to 09-08-2022 19:40:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 114 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 09-08-2022 19:40:22 to 09-08-2022 19:40:24.

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          239635
MON$OLDEST_ACTIVE               241850
MON$OLDEST_SNAPSHOT             241850
MON$NEXT_TRANSACTION            241850
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                       2027872
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.33616 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33616 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33616 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33616 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Tue Aug  9 19:38:28 2022
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:18: 
--- end of diff output ---

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

09.08.22 19:40:25. 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-08-09 19:40:28 No severe PSQL-related problems occured
09.08.22 19:40:29. End of report.