Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.03.2022 13:37
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 16307252 kB
MemAvailable 31575972 kB
Buffers 0 kB
Cached 15365884 kB
SwapCached 2176 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 4.0.2
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-03-30 13:37:15
DTS_END                         2022-03-30 13:37:15.5770
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46696
TRN_ID                          226495
ATT_ID                          636
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: 9253.63 22137 1110436
customer order: creation 174.90 458 20988
customer order: refuse 15.02 392 1802
order to supplier: creation 46.75 1301 5610
order to supplier: removal 3.72 149393 446
invoice (draft): creation 45.98 2873 5517
invoice (draft): removal 50.88 943 6105
invoice accept: apply 43.85 24673 5262
invoice accept: cancel 46.81 1175 5617
customer reserve: creation 3997.04 239 479645
customer reserve: removal 4525.99 97 543119
realization accept: apply 84.65 284 10158
realization accept: cancel 86.12 170 10334
payment from customer: creation 60.15 164 7218
payment from customer: removal 5.79 116 695
payment to supplier: creation 49.83 187 5980
payment to supplier: removal 3.62 154 434
service: total inventory turnovers 3.76 4448 451
service: total monetary turnovers 7.81 332 937
service: aggregate perf. data 0.95 15268 114
service: refresh index statistics 0.03 240067 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 30-03-2022 13:38:01 to 30-03-2022 13:38:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9262
TEST_TIME 2 6021
TEST_TIME 3 5503
TEST_TIME 4 5364
TEST_TIME 5 5118
TEST_TIME 6 6196
TEST_TIME 7 6532
TEST_TIME 8 6689
TEST_TIME 9 7064
TEST_TIME 10 7335
TEST_TIME 11 7401
TEST_TIME 12 7499
TEST_TIME 13 7330
TEST_TIME 14 7554
TEST_TIME 15 7521
TEST_TIME 16 7500
TEST_TIME 17 7809
TEST_TIME 18 7985
TEST_TIME 19 7948
TEST_TIME 20 7959
TEST_TIME 21 8085
TEST_TIME 22 8089
TEST_TIME 23 8169
TEST_TIME 24 8546
TEST_TIME 25 8720
TEST_TIME 26 9045
TEST_TIME 27 9065
TEST_TIME 28 9262
TEST_TIME 29 9693
TEST_TIME 30 10700
TEST_TIME 31 10936
TEST_TIME 32 11101
TEST_TIME 33 10868
TEST_TIME 34 10799
TEST_TIME 35 10839
TEST_TIME 36 10796
TEST_TIME 37 10876
TEST_TIME 38 10680
TEST_TIME 39 10611
TEST_TIME 40 10509
TEST_TIME 41 10767
TEST_TIME 42 10610
TEST_TIME 43 10576
TEST_TIME 44 10481
TEST_TIME 45 10492
TEST_TIME 46 10350
TEST_TIME 47 10263
TEST_TIME 48 10122
TEST_TIME 49 10000
TEST_TIME 50 9983
TEST_TIME 51 9856
TEST_TIME 52 9823
TEST_TIME 53 9787
TEST_TIME 54 9666
TEST_TIME 55 9695
TEST_TIME 56 9685
TEST_TIME 57 9774
TEST_TIME 58 9698
TEST_TIME 59 9580
TEST_TIME 60 9741
TEST_TIME 61 9747
TEST_TIME 62 9691
TEST_TIME 63 9712
TEST_TIME 64 9740
TEST_TIME 65 9701
TEST_TIME 66 9839
TEST_TIME 67 9798
TEST_TIME 68 9793
TEST_TIME 69 9899
TEST_TIME 70 9886
TEST_TIME 71 9806
TEST_TIME 72 9825
TEST_TIME 73 9809
TEST_TIME 74 9701
TEST_TIME 75 9643
TEST_TIME 76 9635
TEST_TIME 77 9620
TEST_TIME 78 9570
TEST_TIME 79 9524
TEST_TIME 80 9501
TEST_TIME 81 9440
TEST_TIME 82 9397
TEST_TIME 83 9362
TEST_TIME 84 9443
TEST_TIME 85 9402
TEST_TIME 86 9408
TEST_TIME 87 9398
TEST_TIME 88 9408
TEST_TIME 89 9395
TEST_TIME 90 9416
TEST_TIME 91 9357
TEST_TIME 92 9421
TEST_TIME 93 9427
TEST_TIME 94 9442
TEST_TIME 95 9459
TEST_TIME 96 9532
TEST_TIME 97 9494
TEST_TIME 98 9462
TEST_TIME 99 9406
TEST_TIME 100 9373
TEST_TIME 101 9422
TEST_TIME 102 9370
TEST_TIME 103 9339
TEST_TIME 104 9329
TEST_TIME 105 9264
TEST_TIME 106 9224
TEST_TIME 107 9155
TEST_TIME 108 9174
TEST_TIME 109 9168
TEST_TIME 110 9158
TEST_TIME 111 9124
TEST_TIME 112 9103
TEST_TIME 113 9094
TEST_TIME 114 9086
TEST_TIME 115 9103
TEST_TIME 116 9077
TEST_TIME 117 9084
TEST_TIME 118 9153
TEST_TIME 119 9286
TEST_TIME 120 9274

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-03-2022 13:38:05 to 30-03-2022 13:38:08.

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 1552165 1552165 0 0.00 0 38392 14 0 0
doc_list_biud 1552150 1552150 0 0.00 0 47415 40 0 0
sp_add_invoice_to_stock 5375 5262 113 2.10 40 201406 24674 0 113
sp_cancel_adding_invoice 5617 5617 0 0.00 5 73625 1175 0 0
sp_cancel_client_order 1802 1802 0 0.00 6 13481 393 0 0
sp_cancel_customer_reserve 543119 543119 0 0.00 1 25668 97 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 4 4092 116 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 4 12395 154 0 0
sp_cancel_supplier_invoice 6117 6105 12 0.19 2 47424 943 0 12
sp_cancel_supplier_order 446 446 0 0.00 13 1475937 149393 0 0
sp_cancel_write_off 10334 10334 0 0.00 2 34696 171 0 0
sp_client_order 20988 20988 0 0.00 6 34501 459 0 0
sp_customer_reserve 479645 479645 0 0.00 1 113793 239 0 0
sp_fill_shopping_cart 32100 32100 0 0.00 1 19289 84 0 0
sp_fill_shopping_cart_clo_res 479616 479616 0 0.00 0 14054 18 0 0
sp_get_clo_for_invoice 5222 5222 0 0.00 0 23380 236 0 0
sp_kill_cost_storno 17080 17080 0 0.00 0 38383 71 0 0
sp_kill_qstorno_ret_qs2qd 1103123 1103123 0 0.00 0 47413 42 0 0
sp_kill_qty_storno 582799 582799 0 0.00 0 47414 98 0 0
sp_lock_selected_doc 21321 21321 0 0.00 0 8442 8 0 0
sp_make_cost_storno 28578 28578 0 0.00 0 21069 80 0 0
sp_multiply_rows_for_pdistr 28573 28573 0 0.00 0 6738 36 0 0
sp_multiply_rows_for_qdistr 20988 20988 0 0.00 0 10248 165 0 0
sp_pay_from_customer 7218 7218 0 0.00 6 19082 165 0 0
sp_pay_to_supplier 5980 5980 0 0.00 7 14984 188 0 0
sp_payment_common 13195 13195 0 0.00 2 19066 46 0 0
sp_qd_handle_on_cancel_clo 1802 1802 0 0.00 0 13460 320 0 0
sp_qd_handle_on_invoice_upd_sts 10837 10837 0 0.00 0 25477 503 0 0
sp_qd_handle_on_reserve_upd_sts 10156 10156 0 0.00 0 25231 66 0 0
sp_reserve_write_off 10158 10158 0 0.00 8 25270 284 0 0
sp_supplier_invoice 5517 5517 0 0.00 9 93440 2874 0 0
sp_supplier_order 5610 5610 0 0.00 8 21870 1301 0 0
srv_aggregate_perf_data 114 114 0 0.00 3058 159653 15269 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 633 142298 36730 0 0
srv_find_qd_qs_mism 1060329 1060329 0 0.00 0 20773 11 0 0
srv_make_invnt_saldo 451 451 0 0.00 91 107781 4448 0 0
srv_make_money_saldo 937 937 0 0.00 1 13511 332 0 0
srv_recalc_idx_stat 4 4 0 0.00 82200 413239 240068 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5266 5266 0 0.00 1 11344 936 0 0
t$perf-norm:sp_cancel_adding_invoice 456 456 0 0.00 1 6021 431 0 0
t$perf-norm:sp_cancel_client_order 1802 1802 0 0.00 0 2709 28 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 616 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 3202 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 1643 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 1727 33 0 0
t$perf-norm:sp_cancel_supplier_order 446 446 0 0.00 0 118616 5956 0 0
t$perf-norm:sp_cancel_write_off 1047 1047 0 0.00 0 2834 24 0 0
t$perf-norm:sp_client_order 20989 20989 0 0.00 0 20892 35 0 0
t$perf-norm:sp_customer_reserve 1026 1026 0 0.00 0 2025 14 0 0
t$perf-norm:sp_pay_from_customer 7218 7218 0 0.00 0 2169 15 0 0
t$perf-norm:sp_pay_to_supplier 5980 5980 0 0.00 0 9704 20 0 0
t$perf-norm:sp_reserve_write_off 10158 10158 0 0.00 0 16536 24 0 0
t$perf-norm:sp_supplier_invoice 5517 5517 0 0.00 0 4597 29 0 0
t$perf-norm:sp_supplier_order 5612 5612 0 0.00 0 3217 30 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 486 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 347 9 0 0
t$perf-norm:srv_make_invnt_saldo 451 451 0 0.00 0 813 21 0 0
t$perf-norm:srv_make_money_saldo 937 937 0 0.00 0 368 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 51 186 103 0 0
v_add_invoice_to_stock 10662 10436 226 2.11 0 19062 24 0 226
v_all_customers 38351 38351 0 0.00 0 187 4 0 0
v_all_suppliers 30968 30968 0 0.00 0 186 4 0 0
v_all_wares 41966 41966 0 0.00 0 174 4 0 0
v_cancel_adding_invoice 912 912 0 0.00 0 1619 14 0 0
v_cancel_client_order 3604 3604 0 0.00 0 3587 10 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 1914 12 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 1555 11 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 3584 14 0 0
v_cancel_supplier_invoice 822 798 24 2.91 0 983 20 0 24
v_cancel_supplier_order 892 892 0 0.00 0 492 10 0 0
v_cancel_write_off 2094 2094 0 0.00 0 1623 21 0 0
v_max_id_clo_ord 5604 5604 0 0.00 0 2505 5 0 0
v_max_id_clo_res 1026 1026 0 0.00 0 8250 18 0 0
v_max_id_ord_sup 5510 5510 0 0.00 0 4531 7 0 0
v_max_non_paid_invoice 1610 1610 0 0.00 0 331 7 0 0
v_max_non_paid_realizn 3784 3784 0 0.00 0 1764 8 0 0
v_min_id_clo_ord 5604 5604 0 0.00 0 7741 10 0 0
v_min_id_clo_res 1026 1026 0 0.00 0 926 9 0 0
v_min_id_ord_sup 5510 5510 0 0.00 0 8001 12 0 0
v_min_non_paid_invoice 5980 5980 0 0.00 0 6349 17 0 0
v_min_non_paid_realizn 7215 7215 0 0.00 0 19062 17 0 0
v_our_firm 10470 10470 0 0.00 0 59 5 0 0
v_reserve_write_off 20304 20304 0 0.00 0 18977 12 0 0
x_lock_depdocs_on_canc_invoice 11323 11323 0 0.00 0 17461 123 0 0
x_lock_depdocs_on_canc_sup_ord 446 446 0 0.00 0 3196 282 0 0
x_make_qstorno_1000_1200 5610 5610 0 0.00 3 21858 1131 0 0
x_make_qstorno_1200_2000 5517 5517 0 0.00 4 93428 2653 0 0
x_make_qstorno_2100_3300 479642 479642 0 0.00 0 113785 209 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 30-03-2022 13:38:09 to 30-03-2022 13:38:14.

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
11:07:19 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:19 35597 8192000000 9733635040 10038759424 808623744 8.984 100 86 174 1 837554000 8020448 20909808
11:10:00 26901 8192000000 9777724448 10089766912 847268480 9.373 100 89 178 0 881821152 13090656 21462016
11:11:32 24238 8192000000 9823438640 10144583680 877035488 9.670 100 86 172 0 927884528 31606464 19242576
11:12:58 6231 8192000000 9888561520 10223529984 921695136 10.113 100 82 162 0 1002007152 63146240 17165776
11:14:08 3779 8192000000 9925878768 10278989824 968119248 10.568 100 90 175 1 1047986928 61592912 18274768
11:15:14 1470 8192000000 9968725296 10326802432 1001910432 10.897 100 89 169 1 1091982544 74269216 15802896
11:16:18 1574 8192000000 10012144960 10378326016 1023828208 11.109 100 95 181 0 1128938672 86958448 18152016
11:17:21 1300 8192000000 10025147648 10402578432 1045874368 11.321 100 96 178 1 1147533712 82484848 19174496
11:18:23 1168 8192000000 10027994368 10403151872 1060237216 11.459 100 94 182 0 1146939568 68782416 17919936
11:19:26 1149 8192000000 10048619312 10431160320 1068014624 11.533 100 91 175 0 1166997440 81241072 17741744
11:20:29 936 8192000000 10053732400 10439589888 1072536832 11.576 100 90 178 1 1178363712 87399344 18427536
11:21:35 1217 8192000000 10048007568 10432716800 1079748272 11.645 100 98 189 0 1171369904 71602672 20018960
11:22:38 1321 8192000000 10051048752 10436472832 1085507376 11.700 100 97 187 1 1172367984 67089952 19770656
11:23:41 1329 8192000000 10084536688 10474213376 1086585280 11.710 100 99 191 1 1202829936 97454192 18790464
11:24:45 1248 8192000000 10065159344 10453323776 1092999600 11.771 100 90 176 2 1183054096 72332432 17722064
11:25:47 2061 8192000000 10034239696 10422968320 1096457776 11.804 100 72 137 1 1171302640 61064624 13780240
11:26:50 1022 8192000000 10053033152 10443026432 1097748736 11.816 100 88 172 0 1178238912 62449536 18040640
11:27:52 755 8192000000 10040162304 10428936192 1094642480 11.787 100 90 171 3 1160045664 45619792 19783392
11:28:54 2052 8192000000 10040471936 10429444096 1075352112 11.603 99 77 149 0 1160703088 71199328 14151648
11:29:57 968 8192000000 10061589360 10454638592 1095374128 11.794 100 84 164 0 1184912672 72181440 17357104
11:30:59 984 8192000000 10046360480 10432155648 1081461808 11.661 100 87 171 1 1167078544 66844944 18771792
11:32:01 4195 8192000000 10000004624 10380079104 1049798816 11.359 100 68 134 0 1122189984 59244848 13146320
11:33:08 1036 8192000000 9980201552 10347667456 1022230416 11.094 100 90 173 2 1101474528 58868256 20375856
11:34:10 1032 8192000000 9976798368 10354003968 1033093088 11.198 100 80 143 3 1099962944 51204704 15665152
11:35:12 1158 8192000000 9997708816 10376613888 1045456304 11.317 100 90 169 2 1118942000 56137776 17347920
11:36:14 936 8192000000 9964010096 10338865152 1030745808 11.176 99 87 168 1 1084960240 37035936 17178496
11:37:16 628 8192000000 9997336768 10372984832 1053026080 11.390 100 91 163 0 1117897168 48328416 16542672
11:38:23 1176 8192000000 10004626688 10386423808 1071657136 11.568 100 87 161 0 1133991312 43617840 18716336
11:39:30 11888 8192000000 10064594880 10450554880 1085277360 11.698 100 70 134 1 1185272704 86968640 13026704
11:40:46 30414 8192000000 10095366208 10502340608 1105732192 11.892 100 61 114 1 1223954048 105415184 12806672
11:42:25 11470 8192000000 10127728752 10526302208 1115012416 11.980 100 66 129 2 1250041504 121528384 13500704
11:43:47 993 8192000000 10159105920 10553024512 1131575488 12.136 100 97 188 1 1276758944 125649088 19534368
11:44:50 1354 8192000000 10173470784 10569629696 1138640128 12.203 100 81 155 1 1291939840 137301296 15998416
11:45:55 812 8192000000 10192684976 10596249600 1148010944 12.291 100 95 185 0 1305719520 137935136 19773440
11:46:59 960 8192000000 10234864528 10631077888 1156547104 12.371 100 95 184 2 1348445888 171937312 19961472
11:48:03 1347 8192000000 10225978656 10637651968 1164143600 12.442 100 90 178 1 1342236224 158492272 19600352
11:49:08 1051 8192000000 10268827136 10669015040 1173868240 12.533 100 90 175 2 1377043888 184597472 18578176
11:50:12 1510 8192000000 10310994192 10712834048 1186841712 12.654 100 82 153 1 1418896576 215420432 16634432
11:51:19 824 8192000000 10367888000 10774450176 1194900000 12.729 100 92 179 1 1472600176 257780528 19919648
11:52:23 2624 8192000000 10388625312 10802753536 1202240064 12.797 100 76 150 2 1501187232 281787104 17160064
11:53:32 2107 8192000000 10433916912 10857779200 1212971600 12.897 100 88 172 1 1539762560 307902432 18888528
11:54:37 918 8192000000 10456606400 10882711552 1210458176 12.873 100 96 190 1 1563934992 331557968 21918848
11:55:40 987 8192000000 10470636544 10878603264 1195668416 12.736 99 97 188 1 1576161280 358305456 22187408
11:56:45 886 8192000000 10527362768 10933608448 1205462208 12.827 100 96 184 1 1632446960 405188320 21796432
11:57:52 1646 8192000000 10531037904 10960031744 1201189136 12.787 100 96 187 0 1634870192 411982304 21698752
11:58:56 1072 8192000000 10567575312 10982694912 1200591200 12.782 100 99 195 1 1669375792 446551840 22232752
11:59:59 1020 8192000000 10602468704 11053817856 1201692128 12.792 100 95 188 0 1705305632 480712032 22901472
12:01:05 1513 8192000000 10594000384 11064307712 1193968896 12.720 100 73 146 1 1702323264 489475024 18879344
12:02:11 3663 8192000000 10572852048 11016937472 1173939408 12.534 100 55 106 0 1682617872 494552672 14125792
12:04:03 13814 8192000000 10528047504 10970861568 1173791264 12.532 100 38 74 2 1636695488 453319600 9584624
12:05:26 12950 8192000000 10488680848 10962882560 1149738480 12.307 100 35 69 0 1615788800 456542688 9507632
12:06:45 12675 8192000000 10311674720 10855829504 1136110032 12.179 99 29 58 1 1440145120 295644624 8390464
12:07:59 72306 8192000000 10075537088 10558382080 1128538560 12.108 97 12 24 0 1270378160 138642304 3197296
12:10:12 34715 8192000000 10097115872 10524139520 1134528000 12.164 99 34 68 1 1173374896 31841712 7005184
12:11:59 68019 8192000000 10144374704 10623041536 1143644176 12.250 100 11 22 1 1210032080 64097120 2290784
12:14:10 70219 8192000000 10090651600 10512449536 1101538880 11.852 96 31 58 0 1167248928 60178592 5531456
12:16:24 20069 8192000000 10131211488 10563956736 1133751456 12.157 100 38 66 2 1208260176 67826432 6682288
12:17:53 95473 8192000000 10075526944 10555973632 1077636048 11.625 95 12 24 0 1216460192 135882192 2941952
12:20:31 68382 8192000000 10065172832 10517680128 1128555984 12.108 99 13 26 1 1167528704 36512720 2460000
12:22:41 38509 8192000000 10066232608 10490429440 1120863488 12.035 98 30 57 4 1157888224 30376704 6648032
12:24:28 97663 8192000000 10054102112 10479161344 1101712352 11.854 97 54 103 3 1127954240 15577088 10664800
12:27:14 104015 8192000000 10063427536 10487394304 1084704224 11.692 95 72 138 4 1135596720 35802448 15090048
12:29:59 57134 8192000000 10122461008 10570678272 1128501824 12.107 99 24 48 1 1208215088 73926128 5787136
12:32:01 16264 8192000000 10180428960 10626043904 1141189488 12.227 99 47 91 1 1273194016 120493536 11510992
12:33:18 34861 8192000000 10165367216 10618793984 1149090624 12.301 100 21 42 2 1264834704 110723008 5021072
12:34:57 38665 8192000000 10145874000 10671206400 1148662096 12.297 100 9 18 1 1245580480 94864976 2053408
12:38:41 63710 8192000000 10096330128 10532716544 1128212784 12.105 98 20 39 2 1169671488 37840080 3618624
12:41:03 52447 8192000000 10102919952 10523136000 1109254160 11.925 97 32 64 0 1165844448 49786528 6803760
12:42:57 72694 8192000000 10074720784 10566283264 1063273776 11.488 94 17 34 1 1170580288 103766672 3539840
12:45:45 103234 8192000000 10076243216 10562355200 1125743936 12.081 99 22 45 0 1202909888 72493440 4672512
12:48:29 11346 8192000000 10093900848 10515738624 1136494240 12.183 100 67 131 1 1183750240 31832752 15423248
12:49:41 22895 8192000000 10100485472 10534068224 1113037216 11.961 98 39 76 0 1169904848 49508160 7359472
12:51:07 100696 8192000000 10088673664 10521329664 1105696848 11.892 98 31 62 2 1145215248 33579216 5939184
12:54:05 28017 8192000000 10114837440 10582966272 1127391568 12.097 100 45 90 1 1204393104 68418336 8583200
12:55:42 36381 8192000000 10096245232 10547810304 1120759408 12.034 99 25 49 2 1194965200 68541536 5664256
12:57:32 85649 8192000000 10102491664 10561318912 1110457024 11.937 98 11 22 0 1200777872 87394752 2926096
13:00:01 64041 8192000000 10129865008 10613075968 1121456400 12.041 99 18 36 1 1224056080 98200240 4399440
13:02:08 49089 8192000000 10069084176 10507567104 1096610624 11.805 97 22 44 1 1139341280 38213840 4516816
13:03:57 62951 8192000000 10085738432 10561761280 1137016032 12.187 100 32 64 0 1200659024 56900160 6742832
13:06:09 34146 8192000000 10087508432 10537267200 1114487696 11.975 99 39 74 3 1166135536 44212432 7435408
13:07:44 55966 8192000000 10111263216 10544369664 1107121392 11.905 98 26 52 1 1189925088 77660112 5143584
13:09:40 32626 8192000000 10056606992 10492456960 1081394704 11.661 96 42 84 0 1156310512 65970720 8945088
13:11:13 43239 8192000000 10168115696 10627608576 1128108640 12.104 100 46 91 0 1257375984 120127600 9139744
13:12:56 92883 8192000000 10102747808 10542747648 1049070480 11.352 93 24 46 1 1090769088 36692368 5006240
13:15:30 142298 8192000000 10123091456 10630176768 1132380128 12.144 100 19 37 0 1291299728 154639536 4280064
13:19:19 104317 8192000000 10085735456 10568798208 1132489200 12.145 99 11 22 0 1200562880 65647344 2426336
13:22:06 37297 8192000000 10095097152 10538160128 1147235776 12.284 100 14 27 1 1195018800 45212816 2570208
13:23:54 84959 8192000000 10091527632 10584428544 1131824464 12.139 99 7 14 0 1208366800 75147504 1394832
13:27:09 26972 8192000000 10096908048 10543644672 1132543856 12.145 99 26 52 2 1181048976 43220032 5285088
13:28:37 27146 8192000000 10134599168 10573697024 1149339152 12.303 100 43 76 3 1225353760 65977136 10037472
13:30:08 24622 8192000000 10203331136 10649391104 1166489312 12.464 100 49 98 0 1314127632 136776896 10861424
13:31:33 28162 8192000000 10226471312 10670899200 1147100080 12.282 98 38 75 1 1308977584 153129696 8747808
13:33:04 15364 8192000000 10229319632 10680537088 1167158368 12.470 100 42 81 0 1320423056 143524000 9740688
13:34:31 36482 8192000000 10292134448 10777337856 1168342224 12.481 100 40 80 1 1401016064 223327216 9346624
13:36:30 57866 8192000000 9312962432 9676664832 421200720 4.890 37 3 6 0 449842736 28276368 365648

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 30-03-2022 13:38:15 to 30-03-2022 13:38:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 113 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 30-03-2022 13:38:33 to 30-03-2022 13:38:34.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          226779
MON$OLDEST_ACTIVE               226780
MON$OLDEST_SNAPSHOT             226780
MON$NEXT_TRANSACTION            226780
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-30 07:30:08.6030 GMT
MON$PAGES                       2076448
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2745 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2745 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2745 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2745 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:232: 
:233: mscs-test-161.budzdorov.ru    Wed Mar 30 13:37:29 2022
:234:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:235: 
:236: 
:237: mscs-test-161.budzdorov.ru    Wed Mar 30 13:37:29 2022
:238:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:239: 
:240: 
:241: mscs-test-161.budzdorov.ru    Wed Mar 30 13:37:29 2022
:242:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:243: 
--- end of diff output ---

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

30.03.22 13:38:36. There are 3 logs in /var/tmp/logs-oltp40 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-03-30 13:38:38 No severe PSQL-related problems occured
30.03.22 13:38:39. End of report.