Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.06.2022 22: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 15969492 kB
MemAvailable 31182804 kB
Buffers 0 kB
Cached 15706280 kB
SwapCached 2508 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-06-24 22:38:18
DTS_END                         2022-06-24 22:38:18.9930
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40542
TRN_ID                          229573
ATT_ID                          679
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: 9335.19 24517 1120223
customer order: creation 159.19 357 19103
customer order: refuse 12.68 383 1522
order to supplier: creation 43.09 1313 5171
order to supplier: removal 3.48 112437 417
invoice (draft): creation 43.03 3620 5163
invoice (draft): removal 55.09 813 6611
invoice accept: apply 39.56 19089 4747
invoice accept: cancel 50.06 787 6007
customer reserve: creation 3712.82 183 445538
customer reserve: removal 4933.71 62 592045
realization accept: apply 76.97 207 9236
realization accept: cancel 85.75 111 10290
payment from customer: creation 54.57 140 6548
payment from customer: removal 5.09 111 611
payment to supplier: creation 45.50 150 5460
payment to supplier: removal 3.18 116 382
service: total inventory turnovers 4.28 1453 514
service: total monetary turnovers 6.43 52 772
service: aggregate perf. data 0.68 27473 82
service: refresh index statistics 0.03 321479 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-06-2022 22:39:35 to 24-06-2022 22:39:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10748
TEST_TIME 2 7911
TEST_TIME 3 7294
TEST_TIME 4 6841
TEST_TIME 5 6759
TEST_TIME 6 6443
TEST_TIME 7 6295
TEST_TIME 8 6556
TEST_TIME 9 6760
TEST_TIME 10 6551
TEST_TIME 11 6573
TEST_TIME 12 7012
TEST_TIME 13 7054
TEST_TIME 14 7414
TEST_TIME 15 7765
TEST_TIME 16 7701
TEST_TIME 17 7918
TEST_TIME 18 8202
TEST_TIME 19 8641
TEST_TIME 20 8698
TEST_TIME 21 8985
TEST_TIME 22 8948
TEST_TIME 23 9748
TEST_TIME 24 9607
TEST_TIME 25 9626
TEST_TIME 26 9975
TEST_TIME 27 10028
TEST_TIME 28 10405
TEST_TIME 29 11240
TEST_TIME 30 11416
TEST_TIME 31 11295
TEST_TIME 32 11675
TEST_TIME 33 11539
TEST_TIME 34 11288
TEST_TIME 35 11106
TEST_TIME 36 10943
TEST_TIME 37 10838
TEST_TIME 38 10753
TEST_TIME 39 10613
TEST_TIME 40 10602
TEST_TIME 41 10453
TEST_TIME 42 10336
TEST_TIME 43 10222
TEST_TIME 44 10089
TEST_TIME 45 10005
TEST_TIME 46 10092
TEST_TIME 47 9968
TEST_TIME 48 9935
TEST_TIME 49 9858
TEST_TIME 50 9784
TEST_TIME 51 9710
TEST_TIME 52 9599
TEST_TIME 53 9574
TEST_TIME 54 9524
TEST_TIME 55 9543
TEST_TIME 56 9518
TEST_TIME 57 9646
TEST_TIME 58 9594
TEST_TIME 59 9581
TEST_TIME 60 9599
TEST_TIME 61 9593
TEST_TIME 62 9645
TEST_TIME 63 9616
TEST_TIME 64 9708
TEST_TIME 65 9732
TEST_TIME 66 9705
TEST_TIME 67 9774
TEST_TIME 68 9740
TEST_TIME 69 9685
TEST_TIME 70 9751
TEST_TIME 71 9797
TEST_TIME 72 9801
TEST_TIME 73 9806
TEST_TIME 74 9832
TEST_TIME 75 9721
TEST_TIME 76 9740
TEST_TIME 77 9766
TEST_TIME 78 9684
TEST_TIME 79 9733
TEST_TIME 80 9676
TEST_TIME 81 9618
TEST_TIME 82 9608
TEST_TIME 83 9535
TEST_TIME 84 9517
TEST_TIME 85 9496
TEST_TIME 86 9484
TEST_TIME 87 9503
TEST_TIME 88 9447
TEST_TIME 89 9420
TEST_TIME 90 9374
TEST_TIME 91 9402
TEST_TIME 92 9369
TEST_TIME 93 9413
TEST_TIME 94 9432
TEST_TIME 95 9401
TEST_TIME 96 9560
TEST_TIME 97 9600
TEST_TIME 98 9588
TEST_TIME 99 9587
TEST_TIME 100 9585
TEST_TIME 101 9580
TEST_TIME 102 9636
TEST_TIME 103 9615
TEST_TIME 104 9612
TEST_TIME 105 9647
TEST_TIME 106 9572
TEST_TIME 107 9555
TEST_TIME 108 9489
TEST_TIME 109 9519
TEST_TIME 110 9476
TEST_TIME 111 9453
TEST_TIME 112 9459
TEST_TIME 113 9410
TEST_TIME 114 9381
TEST_TIME 115 9380
TEST_TIME 116 9355
TEST_TIME 117 9367
TEST_TIME 118 9366
TEST_TIME 119 9380
TEST_TIME 120 9350

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-06-2022 22:39:39 to 24-06-2022 22:39:42.

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 1532581 1532581 0 0.00 0 22884 6 0 0
doc_list_biud 1532578 1532578 0 0.00 0 23210 30 0 0
sp_add_invoice_to_stock 4834 4747 87 1.79 43 146319 19090 0 87
sp_cancel_adding_invoice 6007 6007 0 0.00 5 61938 788 0 0
sp_cancel_client_order 1522 1522 0 0.00 4 19873 384 0 0
sp_cancel_customer_reserve 592045 592045 0 0.00 1 17265 62 0 0
sp_cancel_pay_from_customer 611 611 0 0.00 3 7269 112 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 3 6871 116 0 0
sp_cancel_supplier_invoice 6620 6611 9 0.13 1 17170 813 0 9
sp_cancel_supplier_order 417 417 0 0.00 7 999055 112437 0 0
sp_cancel_write_off 10290 10290 0 0.00 1 10057 112 0 0
sp_client_order 19103 19103 0 0.00 3 26491 358 0 0
sp_customer_reserve 445538 445538 0 0.00 0 36474 183 0 0
sp_fill_shopping_cart 29434 29434 0 0.00 1 22911 43 0 0
sp_fill_shopping_cart_clo_res 445522 445522 0 0.00 0 11856 13 0 0
sp_get_clo_for_invoice 4723 4723 0 0.00 0 23848 321 0 0
sp_kill_cost_storno 17290 17290 0 0.00 0 6277 60 0 0
sp_kill_qstorno_ret_qs2qd 1201408 1201408 0 0.00 0 17261 33 0 0
sp_kill_qty_storno 630850 630850 0 0.00 0 23209 72 0 0
sp_lock_selected_doc 19238 19238 0 0.00 0 9374 6 0 0
sp_make_cost_storno 25967 25967 0 0.00 0 17394 70 0 0
sp_multiply_rows_for_pdistr 25966 25966 0 0.00 0 12196 32 0 0
sp_multiply_rows_for_qdistr 19103 19103 0 0.00 0 9997 142 0 0
sp_pay_from_customer 6548 6548 0 0.00 4 15298 140 0 0
sp_pay_to_supplier 5460 5460 0 0.00 4 15394 150 0 0
sp_payment_common 12008 12008 0 0.00 2 11248 35 0 0
sp_qd_handle_on_cancel_clo 1522 1522 0 0.00 0 19863 346 0 0
sp_qd_handle_on_invoice_upd_sts 10729 10729 0 0.00 0 23206 361 0 0
sp_qd_handle_on_reserve_upd_sts 9236 9236 0 0.00 0 12806 65 0 0
sp_reserve_write_off 9236 9236 0 0.00 6 17407 208 0 0
sp_supplier_invoice 5163 5163 0 0.00 7 88411 3620 0 0
sp_supplier_order 5171 5171 0 0.00 5 41556 1314 0 0
srv_aggregate_perf_data 82 82 0 0.00 3026 418414 27473 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1680 176809 47145 0 0
srv_find_qd_qs_mism 1075219 1075219 0 0.00 0 14537 5 0 0
srv_make_invnt_saldo 514 514 0 0.00 40 139639 1453 0 0
srv_make_money_saldo 772 772 0 0.00 1 12890 52 0 0
srv_recalc_idx_stat 4 4 0 0.00 84652 578599 321479 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4747 4747 0 0.00 0 15962 194 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 8662 109 0 0
t$perf-norm:sp_cancel_client_order 1522 1522 0 0.00 0 17756 32 0 0
t$perf-norm:sp_cancel_customer_reserve 645 645 0 0.00 0 5609 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 611 611 0 0.00 0 346 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 423 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 2509 22 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 32963 1967 0 0
t$perf-norm:sp_cancel_write_off 915 915 0 0.00 0 7559 18 0 0
t$perf-norm:sp_client_order 19103 19103 0 0.00 0 17218 20 0 0
t$perf-norm:sp_customer_reserve 935 935 0 0.00 0 8430 16 0 0
t$perf-norm:sp_pay_from_customer 6548 6548 0 0.00 0 7136 11 0 0
t$perf-norm:sp_pay_to_supplier 5460 5460 0 0.00 0 6473 12 0 0
t$perf-norm:sp_reserve_write_off 9236 9236 0 0.00 0 17948 15 0 0
t$perf-norm:sp_supplier_invoice 5163 5163 0 0.00 0 13184 22 0 0
t$perf-norm:sp_supplier_order 5171 5171 0 0.00 0 12397 18 0 0
t$perf-norm:srv_aggregate_perf_data 82 82 0 0.00 0 30 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 142 3 0 0
t$perf-norm:srv_make_invnt_saldo 514 514 0 0.00 0 1395 24 0 0
t$perf-norm:srv_make_money_saldo 772 772 0 0.00 0 933 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 121 36 0 0
v_add_invoice_to_stock 9608 9434 174 1.81 0 7676 20 0 174
v_all_customers 34932 34932 0 0.00 0 17 1 0 0
v_all_suppliers 28382 28382 0 0.00 0 22 1 0 0
v_all_wares 38206 38206 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 4214 12 0 0
v_cancel_client_order 3044 3044 0 0.00 0 2511 7 0 0
v_cancel_customer_prepayment 1222 1222 0 0.00 0 4364 13 0 0
v_cancel_customer_reserve 1290 1290 0 0.00 0 1040 6 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 5172 18 0 0
v_cancel_supplier_invoice 788 770 18 2.28 0 3081 18 0 18
v_cancel_supplier_order 834 834 0 0.00 0 5513 11 0 0
v_cancel_write_off 1830 1830 0 0.00 0 3356 18 0 0
v_max_id_clo_ord 5171 5171 0 0.00 0 375 1 0 0
v_max_id_clo_res 935 935 0 0.00 0 1691 9 0 0
v_max_id_ord_sup 5160 5160 0 0.00 0 2618 3 0 0
v_max_non_paid_invoice 1600 1600 0 0.00 0 959 4 0 0
v_max_non_paid_realizn 3519 3519 0 0.00 0 6389 7 0 0
v_min_id_clo_ord 5171 5171 0 0.00 0 4056 6 0 0
v_min_id_clo_res 935 935 0 0.00 0 4861 9 0 0
v_min_id_ord_sup 5160 5160 0 0.00 0 7723 7 0 0
v_min_non_paid_invoice 5460 5460 0 0.00 0 7104 24 0 0
v_min_non_paid_realizn 6548 6548 0 0.00 0 11245 29 0 0
v_our_firm 9332 9332 0 0.00 0 14 1 0 0
v_reserve_write_off 18472 18472 0 0.00 0 6470 7 0 0
x_lock_depdocs_on_canc_invoice 12233 12233 0 0.00 0 22253 132 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 18050 468 0 0
x_make_qstorno_1000_1200 5171 5171 0 0.00 1 41473 1221 0 0
x_make_qstorno_1200_2000 5163 5163 0 0.00 3 87356 3473 0 0
x_make_qstorno_2100_3300 445538 445538 0 0.00 0 36438 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-06-2022 22:39:43 to 24-06-2022 22:39:47.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:22 6 8192000000 8799019312 8810369024 5315664 0.064 2 1 2 0 5415264 56816 42784
20:09:23 16564 8192000000 9906763952 10222526464 998974976 10.869 100 86 172 0 1027682096 6083344 22623776
20:10:46 30777 8192000000 9957470224 10283036672 1039331856 11.258 100 92 184 0 1072028720 9408544 23288320
20:12:25 14319 8192000000 9996510960 10330165248 1075301232 11.603 100 86 172 0 1107346832 10643984 21401616
20:13:46 11220 8192000000 10034440752 10384601088 1118840144 12.016 100 78 156 1 1149294384 11128160 19326080
20:15:04 8501 8192000000 10085134400 10439045120 1164498880 12.445 100 81 162 1 1195969424 12353408 19117136
20:16:15 4455 8192000000 10124275360 10488614912 1204943248 12.822 100 86 172 3 1237928720 13629792 19355680
20:17:22 1992 8192000000 10155772832 10530480128 1242923088 13.173 100 88 176 1 1275650144 13003056 19724000
20:18:26 1474 8192000000 10199462912 10579898368 1278996608 13.504 100 94 188 0 1313367040 13736336 20634096
20:19:28 11402 8192000000 10215003248 10601877504 1298406320 13.681 100 71 144 1 1323336736 8092032 16838384
20:20:40 5984 8192000000 10194439648 10602762240 1304115664 13.733 100 21 43 2 1314699584 5503088 5080832
20:21:49 7832 8192000000 10198815712 10617823232 1314272256 13.825 100 19 38 0 1325123376 6864496 3986624
20:22:57 63988 8192000000 10204616192 10629410816 1317304896 13.852 100 26 52 1 1328929104 5402112 6222096
20:25:05 7372 8192000000 10220754160 10640625664 1327079136 13.941 100 27 54 0 1340139264 6518048 6542080
20:26:13 9306 8192000000 10225665008 10648018944 1331459440 13.980 100 31 62 2 1345190400 6366256 7364704
20:27:24 19688 8192000000 10206215216 10626973696 1279265744 13.506 96 22 44 1 1289321024 5238080 4817200
20:28:45 10388 8192000000 10204063936 10628378624 1310062448 13.787 99 28 56 0 1322644192 5310464 7271280
20:29:57 8107 8192000000 10183311776 10589044736 1252358160 13.260 96 23 46 1 1262980960 5347536 5275264
20:31:07 4923 8192000000 10140827952 10534371328 1188406416 12.669 94 25 49 4 1198965824 4885744 5673664
20:32:13 11742 8192000000 10141740272 10542174208 1232741728 13.079 98 30 60 1 1245776576 5012992 8021856
20:33:25 12234 8192000000 10140740272 10547486720 1239507184 13.142 98 22 44 1 1250252544 6105360 4640000
20:34:39 6046 8192000000 10174878256 10583420928 1281821184 13.530 100 22 44 1 1296688832 9537632 5330016
20:35:45 9231 8192000000 10191741520 10603536384 1299823376 13.694 100 25 48 2 1311290240 5657728 5809136
20:36:55 18487 8192000000 10213678192 10624299008 1309883344 13.785 100 23 47 0 1321258192 5169888 6204960
20:38:56 2714 8192000000 10246799840 10653224960 1339352720 14.052 100 52 104 2 1357600864 6102304 12145840
20:40:07 11840 8192000000 10271730192 10681896960 1357707184 14.217 100 62 122 2 1382661872 10575696 14378992
20:41:24 19251 8192000000 10286498624 10703241216 1376941104 14.389 100 48 96 0 1399282928 11375648 10966176
20:42:58 26169 8192000000 10305049136 10728300544 1389874192 14.505 100 48 96 1 1417194080 15842832 11477056
20:44:31 16987 8192000000 10316176480 10738696192 1399282560 14.589 100 46 86 2 1427020576 17099264 10638752
20:45:57 12443 8192000000 10329712496 10755043328 1405967712 14.648 100 57 114 1 1446054544 26033520 14053312
20:47:18 25424 8192000000 10329719888 10766053376 1407710528 14.664 100 38 76 0 1445294544 28002432 9581584
20:48:49 16345 8192000000 10319516400 10749026304 1391793456 14.522 100 36 72 1 1430879344 29764448 9321440
20:50:08 4584 8192000000 10356804304 10780745728 1415601520 14.734 100 61 120 2 1468002464 37854880 14546064
20:51:18 2958 8192000000 10391374432 10803310592 1432210288 14.881 100 90 181 0 1494550864 41281120 21059456
20:52:25 1680 8192000000 10379114528 10789818368 1414816032 14.727 100 90 178 2 1480544880 43645616 22083232
20:53:31 4414 8192000000 10387431264 10805997568 1413705152 14.717 99 78 156 2 1479421552 46827664 18888736
20:54:43 4986 8192000000 10356250672 10773155840 1395174336 14.552 100 58 115 0 1458306464 48076896 15055232
20:55:57 4915 8192000000 10366103216 10772459520 1396846528 14.567 100 85 170 0 1468350192 50377904 21125760
20:57:09 4771 8192000000 10371874224 10779017216 1398315568 14.580 100 76 152 0 1471090336 53377792 19396976
20:58:21 7370 8192000000 10345104080 10761601024 1384649392 14.458 99 53 105 0 1445355008 47315152 13390464
20:59:34 4299 8192000000 10362743824 10781982720 1392214544 14.526 99 52 105 0 1458624800 52172448 14237808
21:00:44 7664 8192000000 10346582448 10767523840 1380572080 14.422 99 49 96 1 1448366000 54838816 12955104
21:01:54 6414 8192000000 10343273456 10784329728 1386197632 14.472 99 41 82 1 1456801424 59000032 11603760
21:03:06 17724 8192000000 10347970656 10775212032 1377802272 14.397 99 38 75 1 1440991056 52870640 10318144
21:04:25 5216 8192000000 10344868992 10780516352 1388733168 14.495 100 34 67 1 1452726544 53717936 10275440
21:05:36 27155 8192000000 10324062912 10762588160 1354357792 14.187 99 25 50 2 1415041792 53228480 7455520
21:07:33 8775 8192000000 10304816000 10740768768 1359180352 14.230 100 23 46 0 1413767408 48091664 6495392
21:08:42 48861 8192000000 10288975152 10718433280 1350597728 14.153 98 27 56 0 1371557488 14704624 6255136
21:10:34 160052 8192000000 10289219680 10734776320 1355750992 14.199 98 17 33 0 1372381440 13156256 3474192
21:14:44 32700 8192000000 10280886544 10737045504 1377182064 14.391 99 16 32 0 1389236352 8562080 3492208
21:16:19 46731 8192000000 10283655616 10737987584 1379468416 14.412 99 22 46 1 1397365152 12514256 5382480
21:18:09 46637 8192000000 10281804800 10737405952 1357045264 14.211 98 17 34 1 1376798032 15870432 3882336
21:19:57 74919 8192000000 10294808960 10744426496 1379560080 14.413 100 31 60 0 1398233824 12044528 6629216
21:22:13 40721 8192000000 10293908800 10736062464 1371026016 14.336 99 21 42 0 1392674080 16533904 5114160
21:24:07 75880 8192000000 10290094768 10743836672 1368930944 14.317 99 14 28 0 1386878528 14344304 3603280
21:26:47 88382 8192000000 10300404656 10748719104 1395775584 14.557 100 27 54 0 1410982256 8812400 6394272
21:29:17 53634 8192000000 10285490160 10733723648 1353708368 14.181 97 17 34 0 1368137552 10490272 3938912
21:31:11 67928 8192000000 10305723344 10769817600 1401717024 14.610 100 28 55 0 1427650752 19122112 6811616
21:33:30 75843 8192000000 10316139552 10789904384 1404115488 14.632 100 13 26 0 1437045104 29510320 3419296
21:35:46 47790 8192000000 10306816704 10789679104 1397129440 14.569 100 9 18 0 1421705120 22451616 2124064
21:37:35 67644 8192000000 10284870656 10753396736 1361167536 14.248 97 14 28 1 1374535520 10599472 2768512
21:39:46 176809 8192000000 10295750432 10758615040 1331869280 13.984 95 9 18 0 1352708480 18937584 1901616
21:43:55 54313 8192000000 10290926656 10741727232 1337983104 14.039 96 7 14 1 1348500816 9260752 1256960
21:46:10 96610 8192000000 10279727248 10727686144 1301425776 13.708 94 16 33 0 1317131904 12272640 3433488
21:48:59 55501 8192000000 10278796944 10716729344 1331390384 13.980 97 30 60 0 1351690416 13618256 6681776
21:51:41 124624 8192000000 10276407216 10716884992 1329762368 13.965 97 35 69 0 1356084976 17964160 8358448
21:54:46 82923 8192000000 10240637520 10696249344 1285535264 13.564 95 23 46 1 1307745696 17035328 5175104
21:57:18 84842 8192000000 10249616112 10704396288 1320225984 13.879 98 5 9 0 1338030048 17071264 732800
22:00:10 55195 8192000000 10255296512 10709000192 1336454528 14.025 98 10 19 0 1346390784 7889744 2046512
22:03:08 109650 8192000000 10260775936 10716053504 1330124256 13.968 97 21 42 2 1349069648 13800752 5144640
22:06:03 39309 8192000000 10272480272 10732576768 1364365824 14.277 100 5 10 0 1376325184 10856912 1102448
22:07:55 79683 8192000000 10281886272 10739212288 1354336576 14.186 98 9 18 0 1370416192 14065904 2013712
22:10:24 83295 8192000000 10280403824 10741657600 1358168944 14.221 98 17 34 2 1373359408 11557664 3632800
22:12:47 58090 8192000000 10306684240 10755375104 1385733568 14.468 99 16 32 0 1402170912 13136592 3300752
22:14:51 65790 8192000000 10302620064 10772582400 1374556432 14.368 98 11 20 2 1392867616 16361312 1949872
22:17:04 77047 8192000000 10290842400 10747277312 1339313664 14.051 96 18 36 2 1352745632 9391104 4040864
22:19:21 23493 8192000000 10305578016 10738696192 1348195792 14.131 98 45 90 2 1375020752 16488912 10336048
22:20:48 41514 8192000000 10272888560 10722869248 1347591520 14.126 98 19 38 0 1362997728 11301264 4104944
22:22:30 16126 8192000000 10285980320 10727587840 1366157024 14.293 100 37 74 1 1384682784 10236448 8289312
22:23:55 113797 8192000000 10281291552 10747916288 1366519216 14.296 99 21 42 0 1385866016 14775488 4571312
22:26:51 1982 8192000000 10314998032 10750902272 1393830992 14.540 100 74 146 0 1418294672 8518544 15945136
22:27:55 73247 8192000000 10280555872 10751836160 1374601488 14.368 99 10 20 0 1389094608 12247504 2245616
22:30:22 71636 8192000000 10285511744 10751352832 1373331840 14.357 99 9 18 0 1387045296 11757312 1956144
22:32:34 57335 8192000000 10269780080 10741055488 1345389648 14.106 97 7 14 1 1356337504 9549568 1398288
22:34:32 73967 8192000000 10297968448 10755854336 1379009568 14.408 99 11 22 2 1395020848 13303040 2708240
22:36:47 34287 8192000000 10291401344 10737655808 1373718048 14.360 100 44 89 1 1398946464 14349744 10878672

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-06-2022 22:39:49 to 24-06-2022 22:40:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 87 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-06-2022 22:40:05 to 24-06-2022 22:40:07.

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          226737
MON$OLDEST_ACTIVE               229681
MON$OLDEST_SNAPSHOT             229681
MON$NEXT_TRANSACTION            229681
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                       2062304
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.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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 ---
:392: 
:393: mscs-test-161.budzdorov.ru    Fri Jun 24 22:38:25 2022
:394:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:395: 
:396: 
:397: mscs-test-161.budzdorov.ru    Fri Jun 24 22:38:35 2022
:398:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:399: 
--- end of diff output ---

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

24.06.22 22:40:08. 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-06-24 22:40:10 No severe PSQL-related problems occured
24.06.22 22:40:11. End of report.