Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.05.2022 16:43
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 16698348 kB
MemAvailable 31510116 kB
Buffers 0 kB
Cached 14993816 kB
SwapCached 3224 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-05-01 16:42:28
DTS_END                         2022-05-01 16:42:28.3230
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/57746
TRN_ID                          237920
ATT_ID                          689
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: 9530.23 22446 1143628
customer order: creation 172.16 388 20659
customer order: refuse 14.18 341 1702
order to supplier: creation 45.20 1250 5424
order to supplier: removal 3.74 106793 449
invoice (draft): creation 46.80 3226 5616
invoice (draft): removal 56.28 861 6753
invoice accept: apply 42.13 16971 5056
invoice accept: cancel 50.28 824 6033
customer reserve: creation 3894.12 166 467294
customer reserve: removal 4904.99 64 588599
realization accept: apply 81.61 180 9793
realization accept: cancel 89.93 109 10792
payment from customer: creation 58.57 122 7028
payment from customer: removal 5.73 93 688
payment to supplier: creation 48.73 134 5848
payment to supplier: removal 3.31 86 397
service: total inventory turnovers 4.94 1015 593
service: total monetary turnovers 6.64 24 797
service: aggregate perf. data 0.86 19146 103
service: refresh index statistics 0.03 297129 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-05-2022 16:43:24 to 01-05-2022 16:43:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4816
TEST_TIME 2 4266
TEST_TIME 3 4660
TEST_TIME 4 4369
TEST_TIME 5 4899
TEST_TIME 6 4864
TEST_TIME 7 5089
TEST_TIME 8 5290
TEST_TIME 9 5353
TEST_TIME 10 5561
TEST_TIME 11 5541
TEST_TIME 12 5401
TEST_TIME 13 5445
TEST_TIME 14 5697
TEST_TIME 15 6114
TEST_TIME 16 6496
TEST_TIME 17 6491
TEST_TIME 18 6930
TEST_TIME 19 7193
TEST_TIME 20 7363
TEST_TIME 21 7824
TEST_TIME 22 8625
TEST_TIME 23 8996
TEST_TIME 24 9488
TEST_TIME 25 9598
TEST_TIME 26 10706
TEST_TIME 27 10704
TEST_TIME 28 10623
TEST_TIME 29 10586
TEST_TIME 30 10553
TEST_TIME 31 10544
TEST_TIME 32 10333
TEST_TIME 33 10155
TEST_TIME 34 10082
TEST_TIME 35 9925
TEST_TIME 36 9815
TEST_TIME 37 9899
TEST_TIME 38 9832
TEST_TIME 39 9779
TEST_TIME 40 9850
TEST_TIME 41 9697
TEST_TIME 42 9874
TEST_TIME 43 9809
TEST_TIME 44 9840
TEST_TIME 45 9723
TEST_TIME 46 9729
TEST_TIME 47 9738
TEST_TIME 48 9815
TEST_TIME 49 9734
TEST_TIME 50 9797
TEST_TIME 51 9725
TEST_TIME 52 9746
TEST_TIME 53 9999
TEST_TIME 54 9946
TEST_TIME 55 9922
TEST_TIME 56 10053
TEST_TIME 57 10118
TEST_TIME 58 10121
TEST_TIME 59 10074
TEST_TIME 60 9924
TEST_TIME 61 9912
TEST_TIME 62 9810
TEST_TIME 63 9802
TEST_TIME 64 9814
TEST_TIME 65 9739
TEST_TIME 66 9713
TEST_TIME 67 9650
TEST_TIME 68 9586
TEST_TIME 69 9566
TEST_TIME 70 9509
TEST_TIME 71 9470
TEST_TIME 72 9421
TEST_TIME 73 9445
TEST_TIME 74 9403
TEST_TIME 75 9475
TEST_TIME 76 9480
TEST_TIME 77 9506
TEST_TIME 78 9496
TEST_TIME 79 9574
TEST_TIME 80 9607
TEST_TIME 81 9638
TEST_TIME 82 9642
TEST_TIME 83 9735
TEST_TIME 84 9749
TEST_TIME 85 9712
TEST_TIME 86 9612
TEST_TIME 87 9623
TEST_TIME 88 9555
TEST_TIME 89 9519
TEST_TIME 90 9508
TEST_TIME 91 9532
TEST_TIME 92 9506
TEST_TIME 93 9564
TEST_TIME 94 9549
TEST_TIME 95 9556
TEST_TIME 96 9600
TEST_TIME 97 9591
TEST_TIME 98 9567
TEST_TIME 99 9618
TEST_TIME 100 9692
TEST_TIME 101 9696
TEST_TIME 102 9738
TEST_TIME 103 9743
TEST_TIME 104 9740
TEST_TIME 105 9715
TEST_TIME 106 9648
TEST_TIME 107 9662
TEST_TIME 108 9635
TEST_TIME 109 9602
TEST_TIME 110 9607
TEST_TIME 111 9569
TEST_TIME 112 9545
TEST_TIME 113 9509
TEST_TIME 114 9469
TEST_TIME 115 9516
TEST_TIME 116 9545
TEST_TIME 117 9518
TEST_TIME 118 9557
TEST_TIME 119 9535
TEST_TIME 120 9535

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-05-2022 16:43:28 to 01-05-2022 16:43:31.

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 1576037 1576037 0 0.00 0 32163 6 0 0
doc_list_biud 1576037 1576037 0 0.00 0 47322 30 0 0
sp_add_invoice_to_stock 5126 5056 70 1.36 49 142933 16972 0 70
sp_cancel_adding_invoice 6033 6033 0 0.00 3 75087 824 0 0
sp_cancel_client_order 1702 1702 0 0.00 4 9530 342 0 0
sp_cancel_customer_reserve 588599 588599 0 0.00 1 21689 64 0 0
sp_cancel_pay_from_customer 688 688 0 0.00 2 9915 94 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 2 5829 86 0 0
sp_cancel_supplier_invoice 6758 6753 5 0.07 1 47599 862 0 5
sp_cancel_supplier_order 449 449 0 0.00 5 1250000 106793 0 0
sp_cancel_write_off 10792 10792 0 0.00 1 23852 109 0 0
sp_client_order 20659 20659 0 0.00 3 100598 389 0 0
sp_customer_reserve 467294 467294 0 0.00 0 83970 166 0 0
sp_fill_shopping_cart 31669 31669 0 0.00 1 32476 39 0 0
sp_fill_shopping_cart_clo_res 467287 467287 0 0.00 0 15322 14 0 0
sp_get_clo_for_invoice 5045 5045 0 0.00 0 25647 314 0 0
sp_kill_cost_storno 17910 17910 0 0.00 0 6159 56 0 0
sp_kill_qstorno_ret_qs2qd 1195192 1195192 0 0.00 0 47312 35 0 0
sp_kill_qty_storno 629163 629163 0 0.00 0 47319 75 0 0
sp_lock_selected_doc 20656 20656 0 0.00 0 9519 6 0 0
sp_make_cost_storno 27711 27711 0 0.00 0 32161 57 0 0
sp_multiply_rows_for_pdistr 27711 27711 0 0.00 0 12218 30 0 0
sp_multiply_rows_for_qdistr 20659 20659 0 0.00 0 97387 194 0 0
sp_pay_from_customer 7028 7028 0 0.00 4 23644 122 0 0
sp_pay_to_supplier 5848 5848 0 0.00 4 21284 134 0 0
sp_payment_common 12876 12876 0 0.00 1 10290 28 0 0
sp_qd_handle_on_cancel_clo 1702 1702 0 0.00 0 7819 304 0 0
sp_qd_handle_on_invoice_upd_sts 11075 11075 0 0.00 0 20170 347 0 0
sp_qd_handle_on_reserve_upd_sts 9793 9793 0 0.00 0 11010 56 0 0
sp_reserve_write_off 9793 9793 0 0.00 6 32198 181 0 0
sp_supplier_invoice 5616 5616 0 0.00 6 76325 3227 0 0
sp_supplier_order 5424 5424 0 0.00 5 37337 1251 0 0
srv_aggregate_perf_data 103 103 0 0.00 2705 236544 19147 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2187 172853 46767 0 0
srv_find_qd_qs_mism 1095633 1095633 0 0.00 0 23323 5 0 0
srv_make_invnt_saldo 593 593 0 0.00 31 74691 1015 0 0
srv_make_money_saldo 797 797 0 0.00 1 2101 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 237891 415286 297129 0 0
t$perf-abend:sp_add_invoice_to_stock 70 70 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5056 5056 0 0.00 0 34617 164 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 0 8076 130 0 0
t$perf-norm:sp_cancel_client_order 1702 1702 0 0.00 0 1726 11 0 0
t$perf-norm:sp_cancel_customer_reserve 707 707 0 0.00 0 1042 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 688 688 0 0.00 0 5196 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 423 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 1781 26 0 0
t$perf-norm:sp_cancel_supplier_order 449 449 0 0.00 0 20094 1457 0 0
t$perf-norm:sp_cancel_write_off 1014 1014 0 0.00 0 755 6 0 0
t$perf-norm:sp_client_order 20659 20659 0 0.00 0 6904 19 0 0
t$perf-norm:sp_customer_reserve 967 967 0 0.00 0 3127 12 0 0
t$perf-norm:sp_pay_from_customer 7028 7028 0 0.00 0 4989 9 0 0
t$perf-norm:sp_pay_to_supplier 5848 5848 0 0.00 0 6607 9 0 0
t$perf-norm:sp_reserve_write_off 9793 9793 0 0.00 0 15106 12 0 0
t$perf-norm:sp_supplier_invoice 5616 5616 0 0.00 0 6403 15 0 0
t$perf-norm:sp_supplier_order 5424 5424 0 0.00 0 6177 18 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 554 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 33 1 0 0
t$perf-norm:srv_make_invnt_saldo 593 593 0 0.00 0 8017 39 0 0
t$perf-norm:srv_make_money_saldo 797 797 0 0.00 0 1303 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 17 7 0 0
v_add_invoice_to_stock 10224 10084 140 1.36 0 8345 16 0 140
v_all_customers 37928 37928 0 0.00 0 16 0 0 0
v_all_suppliers 31092 31092 0 0.00 0 75 1 0 0
v_all_wares 41302 41302 0 0.00 0 61 1 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 4160 10 0 0
v_cancel_client_order 3404 3404 0 0.00 0 8074 10 0 0
v_cancel_customer_prepayment 1376 1376 0 0.00 0 8756 15 0 0
v_cancel_customer_reserve 1414 1414 0 0.00 0 2451 6 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 1918 9 0 0
v_cancel_supplier_invoice 858 848 10 1.16 0 1385 14 0 10
v_cancel_supplier_order 898 898 0 0.00 0 3798 8 0 0
v_cancel_write_off 2028 2028 0 0.00 0 4777 17 0 0
v_max_id_clo_ord 5423 5423 0 0.00 0 572 1 0 0
v_max_id_clo_res 967 967 0 0.00 0 2000 11 0 0
v_max_id_ord_sup 5594 5594 0 0.00 0 5415 6 0 0
v_max_non_paid_invoice 1319 1319 0 0.00 0 240 2 0 0
v_max_non_paid_realizn 3579 3579 0 0.00 0 4855 4 0 0
v_min_id_clo_ord 5423 5423 0 0.00 0 5725 7 0 0
v_min_id_clo_res 967 967 0 0.00 0 976 5 0 0
v_min_id_ord_sup 5594 5594 0 0.00 0 3578 4 0 0
v_min_non_paid_invoice 5848 5848 0 0.00 0 9574 20 0 0
v_min_non_paid_realizn 7028 7028 0 0.00 0 10284 20 0 0
v_our_firm 10272 10272 0 0.00 0 12 1 0 0
v_reserve_write_off 19586 19586 0 0.00 0 7580 7 0 0
x_lock_depdocs_on_canc_invoice 12362 12362 0 0.00 0 17156 109 0 0
x_lock_depdocs_on_canc_sup_ord 449 449 0 0.00 0 14264 349 0 0
x_make_qstorno_1000_1200 5424 5424 0 0.00 1 37254 1172 0 0
x_make_qstorno_1200_2000 5616 5616 0 0.00 1 70062 3085 0 0
x_make_qstorno_2100_3300 467294 467294 0 0.00 0 83969 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-05-2022 16:43:32 to 01-05-2022 16:43:36.

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:12:31 2 8192000000 8801737536 8813907968 7763408 0.094 2 2 3 0 7904496 66144 74944
14:13:32 27052 8192000000 9895679104 10204635136 978877744 10.673 100 84 168 0 1007751824 6289840 22584240
14:15:04 25438 8192000000 9949625904 10269118464 1027947456 11.149 100 91 180 2 1059399376 8134096 23317824
14:16:32 23914 8192000000 9987296752 10322096128 1064123232 11.496 100 85 170 0 1094871824 9028496 21720096
14:18:01 16971 8192000000 10041492528 10390044672 1126982400 12.093 100 73 146 1 1154001648 10044320 16974928
14:19:29 7087 8192000000 10100549248 10460340224 1183525568 12.623 100 82 165 0 1214725760 12755728 18444464
14:20:38 5957 8192000000 10141996256 10514776064 1231185840 13.065 100 75 149 0 1260971840 13340896 16445104
14:21:46 5315 8192000000 10170367696 10546016256 1254731376 13.282 100 79 158 1 1286186896 14173440 17282080
14:22:54 2622 8192000000 10196770672 10576707584 1286701568 13.574 100 84 168 1 1318296432 12503648 19091216
14:23:58 3035 8192000000 10212095824 10602160128 1301493920 13.709 100 67 134 2 1328239584 11850976 14894688
14:25:03 5857 8192000000 10199593488 10606911488 1310221856 13.788 100 36 72 0 1327289440 9278560 7789024
14:26:09 16124 8192000000 10197565664 10617978880 1318818880 13.866 100 12 24 1 1326670080 5211280 2639920
14:27:26 18220 8192000000 10216158032 10642227200 1332065360 13.986 100 35 70 1 1346975584 7047136 7863088
14:28:57 6198 8192000000 10218057152 10645721088 1334073632 14.004 100 28 55 2 1347249600 6355056 6820912
14:30:06 6227 8192000000 10235344112 10652192768 1336153984 14.023 100 22 43 3 1347086304 5675072 5257248
14:31:14 11688 8192000000 10218846368 10638696448 1307112672 13.760 98 17 34 0 1317336384 5968048 4255664
14:32:26 63580 8192000000 10209216640 10605277184 1250352384 13.241 95 38 77 2 1264185328 4428272 9404672
14:34:30 5566 8192000000 10161291200 10571075584 1262840928 13.356 99 31 62 1 1276302304 6620160 6841216
14:35:38 9436 8192000000 10144232768 10544250880 1240881936 13.154 99 20 40 0 1251538192 6206800 4449456
14:36:49 59357 8192000000 10155996608 10562895872 1242439520 13.169 98 32 64 1 1256112944 6273040 7400384
14:38:49 7211 8192000000 10163231232 10567409664 1270135696 13.423 100 24 48 3 1281179120 5293472 5749952
14:39:57 9370 8192000000 10178057744 10589966336 1287537168 13.582 100 17 34 0 1297443600 6180816 3725616
14:41:07 6197 8192000000 10188835616 10600710144 1297971904 13.677 100 19 38 0 1307500624 5065696 4463024
14:42:18 54019 8192000000 10217265088 10625495040 1311942432 13.804 100 30 58 1 1323990704 5855456 6192816
14:44:22 46553 8192000000 10240551584 10661797888 1337182224 14.032 100 37 72 2 1355630880 10556864 7891792
14:46:12 11038 8192000000 10267185664 10683793408 1358241200 14.222 100 63 125 2 1387231904 15231248 13759456
14:47:28 5268 8192000000 10278830976 10699575296 1369661776 14.324 100 41 82 0 1395150832 15883040 9606016
14:48:42 11050 8192000000 10305058928 10720915456 1380274576 14.419 100 50 100 0 1412533424 20731104 11527744
14:49:57 13875 8192000000 10311535072 10734809088 1390474784 14.510 100 49 98 2 1424811072 21415936 12920352
14:51:19 7405 8192000000 10335318848 10752667648 1403215840 14.624 100 58 117 1 1445871904 28769504 13886560
14:52:31 9577 8192000000 10346658272 10767024128 1415021680 14.729 100 62 124 3 1455848736 26074256 14752800
14:53:44 10058 8192000000 10378607760 10797563904 1435104720 14.906 100 64 128 0 1487133440 35540432 16488288
14:54:57 6183 8192000000 10391823792 10811420672 1437394912 14.927 100 70 140 1 1495348192 39689136 18264144
14:56:08 7315 8192000000 10395476224 10811088896 1437950576 14.932 100 73 145 1 1499321584 42391984 18979024
14:57:19 2280 8192000000 10415410496 10831220736 1449275216 15.031 100 87 173 1 1519621056 47137216 23208624
14:58:26 3146 8192000000 10397796112 10809581568 1424722112 14.815 100 83 165 2 1497891136 52290032 20878992
14:59:35 2752 8192000000 10400581696 10810748928 1420526240 14.777 100 84 168 1 1500778288 58235520 22016528
15:00:42 4885 8192000000 10394006144 10814984192 1419687408 14.770 100 64 127 0 1502519248 65677520 17154320
15:01:53 2185 8192000000 10391557376 10798252032 1397494960 14.573 99 88 176 1 1489267616 68581696 23190960
15:02:58 16485 8192000000 10353723856 10779189248 1356613808 14.207 97 42 80 1 1438270256 69197920 12458528
15:04:23 5030 8192000000 10357894960 10784702464 1384730176 14.459 100 61 117 1 1469442336 69260112 15452048
15:05:36 39889 8192000000 10320498576 10767986688 1368762336 14.316 99 23 46 0 1425964624 50350576 6851712
15:07:22 30247 8192000000 10298660864 10740596736 1338686912 14.046 98 16 32 1 1388561088 45151392 4722784
15:09:03 58308 8192000000 10245759680 10696466432 1313877968 13.821 97 25 49 0 1328048400 8688944 5481488
15:11:03 80214 8192000000 10281196992 10713677824 1339827424 14.056 98 23 46 2 1357341536 11900096 5614016
15:13:24 69507 8192000000 10268292544 10705965056 1316608624 13.846 96 14 28 0 1329206736 9640224 2957888
15:16:20 83789 8192000000 10278771360 10727854080 1348717520 14.136 98 17 34 1 1360233328 7713344 3802464
15:18:46 19946 8192000000 10301180384 10733146112 1375641104 14.378 100 61 121 0 1403410176 13260736 14508336
15:20:08 47174 8192000000 10288162544 10739470336 1363105504 14.265 98 31 62 2 1383325936 12689184 7531248
15:22:15 84382 8192000000 10303208784 10754727936 1379495264 14.412 99 18 36 0 1396219584 12257088 4467232
15:25:20 57663 8192000000 10291798288 10765541376 1388006384 14.488 100 14 28 0 1406238880 15216816 3015680
15:27:18 54649 8192000000 10312450048 10786201600 1394917824 14.550 100 13 26 0 1422821984 24755840 3148320
15:29:13 63974 8192000000 10292776432 10763833344 1380985104 14.425 99 11 22 0 1393975456 10519312 2471040
15:31:22 35571 8192000000 10297876544 10746355712 1359548896 14.233 98 34 67 1 1385072784 17625632 7898256
15:33:01 86359 8192000000 10312548224 10769342464 1383803824 14.451 99 19 38 0 1405857264 17637424 4416016
15:36:17 74417 8192000000 10302368480 10762424320 1395248048 14.553 100 17 34 0 1414390368 15689152 3453168
15:38:32 129077 8192000000 10302381888 10767687680 1338824144 14.047 96 9 18 0 1357908912 17144272 1940496
15:41:42 126560 8192000000 10309945968 10771353600 1397727872 14.575 100 28 57 1 1417645520 13485488 6432160
15:44:51 11488 8192000000 10304643552 10748157952 1386250912 14.472 100 49 96 0 1407569520 10484448 10834160
15:46:15 83812 8192000000 10289658256 10762792960 1380107888 14.418 99 16 32 0 1396535552 12362496 4065168
15:48:40 55862 8192000000 10286494128 10750349312 1373940320 14.362 98 13 26 0 1384893072 7660896 3291856
15:50:43 76515 8192000000 10287207904 10754502656 1380696016 14.423 99 14 28 1 1391561712 7526224 3339472
15:53:01 13359 8192000000 10296054128 10746445824 1381283904 14.428 99 43 86 1 1398690448 7736256 9670288
15:54:17 54739 8192000000 10286027120 10746261504 1380358368 14.420 100 9 18 0 1391880352 9003072 2518912
15:56:12 45917 8192000000 10279961200 10729381888 1313457600 13.817 95 18 36 0 1326966512 9253344 4255568
15:58:13 40766 8192000000 10261149440 10713489408 1340512560 14.062 98 16 32 1 1355632752 10925248 4194944
15:59:55 54864 8192000000 10283685856 10723590144 1343912704 14.093 98 31 62 0 1363496048 12558976 7024368
16:01:54 64462 8192000000 10248043840 10684334080 1281722848 13.529 95 13 26 0 1300989760 16019264 3247648
16:04:06 32183 8192000000 10249935520 10685243392 1297711632 13.674 97 33 66 1 1316857248 11926000 7219616
16:05:47 50439 8192000000 10273340016 10709012480 1348284880 14.132 100 30 59 1 1366573808 11240000 7048928
16:07:43 85421 8192000000 10263707120 10704920576 1352265152 14.168 100 31 62 3 1366993904 7812256 6916496
16:10:09 25445 8192000000 10247887408 10711416832 1360487776 14.242 100 5 10 0 1368590288 7054768 1047744
16:11:38 69216 8192000000 10239316736 10692943872 1299829824 13.694 96 8 16 1 1310294480 9001376 1463280
16:13:47 27044 8192000000 10252570992 10697416704 1337224432 14.032 99 16 30 0 1350804576 10298512 3281632
16:15:15 75001 8192000000 10286108416 10739507200 1362878704 14.263 99 17 34 1 1376818880 10549632 3390544
16:17:37 30284 8192000000 10290152464 10734379008 1372638304 14.351 100 34 68 0 1389536816 10005360 6893152
16:19:09 33738 8192000000 10285739680 10727772160 1351714496 14.163 98 41 82 1 1370396832 9603568 9078768
16:20:44 60169 8192000000 10286782624 10749091840 1351870272 14.164 98 19 38 0 1378088400 21601200 4616928
16:22:54 63164 8192000000 10320327104 10772881408 1377201680 14.392 99 19 36 2 1401135792 20241200 3692912
16:24:59 28779 8192000000 10309093760 10766917632 1389922864 14.505 100 17 34 0 1410611808 16962512 3726432
16:26:38 172853 8192000000 10310197792 10763399168 1360008832 14.237 97 10 20 0 1379492304 17395296 2088176
16:30:38 43243 8192000000 10285064848 10735546368 1350209248 14.149 97 13 26 0 1361570656 8107392 3254016
16:32:24 76268 8192000000 10272511232 10739572736 1324889520 13.921 95 8 16 0 1334431520 7636272 1905728
16:34:45 80825 8192000000 10294015536 10756640768 1367078416 14.301 98 16 32 0 1381904624 11023616 3802592
16:37:06 60124 8192000000 10290407792 10748772352 1376597360 14.386 99 24 48 0 1394719424 12562624 5559440
16:39:30 66314 8192000000 10269744400 10739179520 1337763376 14.037 97 21 42 0 1353047632 10521104 4763152
16:41:47 49817 8192000000 9166900432 9424375808 322513968 3.787 24 2 4 0 325515152 2747968 253216

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-05-2022 16:43:38 to 01-05-2022 16:43:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 70 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 01-05-2022 16:43:54 to 01-05-2022 16:43:55.

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          238197
MON$OLDEST_ACTIVE               238198
MON$OLDEST_SNAPSHOT             238198
MON$NEXT_TRANSACTION            238198
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                       2017024
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


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

01.05.22 16:43:56. 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-05-01 16:43:59 No severe PSQL-related problems occured
01.05.22 16:44:00. End of report.