Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.01.2022 13: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 16517904 kB
MemAvailable 31366272 kB
Buffers 0 kB
Cached 15211684 kB
SwapCached 1220 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.1
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-01-06 13:37:12
DTS_END                         2022-01-06 13:37:12.3840
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/53316
TRN_ID                          213634
ATT_ID                          576
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: 8467.99 23148 1016159
customer order: creation 166.28 494 19953
customer order: refuse 14.14 512 1697
order to supplier: creation 43.71 1462 5245
order to supplier: removal 3.27 180650 392
invoice (draft): creation 43.19 3463 5183
invoice (draft): removal 46.81 1109 5617
invoice accept: apply 39.67 29428 4761
invoice accept: cancel 42.02 1289 5042
customer reserve: creation 3703.23 279 444388
customer reserve: removal 4082.77 112 489932
realization accept: apply 80.03 282 9604
realization accept: cancel 76.62 180 9194
payment from customer: creation 57.86 193 6943
payment from customer: removal 5.66 138 679
payment to supplier: creation 47.16 212 5659
payment to supplier: removal 3.29 135 395
service: total inventory turnovers 4.09 4089 491
service: total monetary turnovers 7.28 427 874
service: aggregate perf. data 0.88 14245 106
service: refresh index statistics 0.03 224261 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 06-01-2022 13:39:39 to 06-01-2022 13:39:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6852
TEST_TIME 2 6702
TEST_TIME 3 6380
TEST_TIME 4 6161
TEST_TIME 5 6156
TEST_TIME 6 5818
TEST_TIME 7 5516
TEST_TIME 8 5653
TEST_TIME 9 5968
TEST_TIME 10 6105
TEST_TIME 11 6074
TEST_TIME 12 5989
TEST_TIME 13 6041
TEST_TIME 14 6109
TEST_TIME 15 6282
TEST_TIME 16 6284
TEST_TIME 17 6442
TEST_TIME 18 6494
TEST_TIME 19 6726
TEST_TIME 20 6740
TEST_TIME 21 6973
TEST_TIME 22 7159
TEST_TIME 23 7126
TEST_TIME 24 7457
TEST_TIME 25 7611
TEST_TIME 26 7788
TEST_TIME 27 8249
TEST_TIME 28 8726
TEST_TIME 29 9138
TEST_TIME 30 9468
TEST_TIME 31 9551
TEST_TIME 32 9957
TEST_TIME 33 9929
TEST_TIME 34 10079
TEST_TIME 35 10000
TEST_TIME 36 10166
TEST_TIME 37 10104
TEST_TIME 38 10196
TEST_TIME 39 9937
TEST_TIME 40 9705
TEST_TIME 41 9614
TEST_TIME 42 9755
TEST_TIME 43 9680
TEST_TIME 44 9590
TEST_TIME 45 9466
TEST_TIME 46 9451
TEST_TIME 47 9429
TEST_TIME 48 9295
TEST_TIME 49 9421
TEST_TIME 50 9324
TEST_TIME 51 9243
TEST_TIME 52 9096
TEST_TIME 53 9057
TEST_TIME 54 8957
TEST_TIME 55 8898
TEST_TIME 56 8861
TEST_TIME 57 8768
TEST_TIME 58 8751
TEST_TIME 59 8658
TEST_TIME 60 8573
TEST_TIME 61 8732
TEST_TIME 62 8710
TEST_TIME 63 8653
TEST_TIME 64 8631
TEST_TIME 65 8603
TEST_TIME 66 8744
TEST_TIME 67 8806
TEST_TIME 68 8765
TEST_TIME 69 8837
TEST_TIME 70 8925
TEST_TIME 71 8892
TEST_TIME 72 8910
TEST_TIME 73 8913
TEST_TIME 74 8961
TEST_TIME 75 8880
TEST_TIME 76 8826
TEST_TIME 77 8815
TEST_TIME 78 8757
TEST_TIME 79 8759
TEST_TIME 80 8720
TEST_TIME 81 8679
TEST_TIME 82 8670
TEST_TIME 83 8664
TEST_TIME 84 8608
TEST_TIME 85 8640
TEST_TIME 86 8692
TEST_TIME 87 8676
TEST_TIME 88 8655
TEST_TIME 89 8734
TEST_TIME 90 8723
TEST_TIME 91 8774
TEST_TIME 92 8783
TEST_TIME 93 8763
TEST_TIME 94 8810
TEST_TIME 95 8790
TEST_TIME 96 8836
TEST_TIME 97 8759
TEST_TIME 98 8705
TEST_TIME 99 8676
TEST_TIME 100 8693
TEST_TIME 101 8630
TEST_TIME 102 8587
TEST_TIME 103 8560
TEST_TIME 104 8567
TEST_TIME 105 8560
TEST_TIME 106 8568
TEST_TIME 107 8553
TEST_TIME 108 8548
TEST_TIME 109 8533
TEST_TIME 110 8560
TEST_TIME 111 8566
TEST_TIME 112 8575
TEST_TIME 113 8624
TEST_TIME 114 8633
TEST_TIME 115 8653
TEST_TIME 116 8614
TEST_TIME 117 8562
TEST_TIME 118 8526
TEST_TIME 119 8558
TEST_TIME 120 8495

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-01-2022 13:39:43 to 06-01-2022 13:39:46.

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 1424040 1424040 0 0.00 0 15097 14 0 0
doc_list_biud 1424031 1424031 0 0.00 0 48374 45 0 0
sp_add_invoice_to_stock 4956 4761 195 3.93 74 290702 29428 0 195
sp_cancel_adding_invoice 5042 5042 0 0.00 7 92084 1290 0 0
sp_cancel_client_order 1697 1697 0 0.00 7 16313 513 0 0
sp_cancel_customer_reserve 489932 489932 0 0.00 1 48378 112 0 0
sp_cancel_pay_from_customer 679 679 0 0.00 3 7447 138 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 4 2930 135 0 0
sp_cancel_supplier_invoice 5624 5617 7 0.12 3 14806 1109 0 7
sp_cancel_supplier_order 392 392 0 0.00 19 1567972 180651 0 0
sp_cancel_write_off 9194 9194 0 0.00 2 18431 180 0 0
sp_client_order 19953 19953 0 0.00 6 63307 494 0 0
sp_customer_reserve 444388 444388 0 0.00 1 149977 279 0 0
sp_fill_shopping_cart 30365 30365 0 0.00 1 19948 84 0 0
sp_fill_shopping_cart_clo_res 444356 444356 0 0.00 0 23375 22 0 0
sp_get_clo_for_invoice 4722 4722 0 0.00 0 24310 316 0 0
sp_kill_cost_storno 15310 15310 0 0.00 0 7074 85 0 0
sp_kill_qstorno_ret_qs2qd 995067 995067 0 0.00 0 48370 50 0 0
sp_kill_qty_storno 526199 526199 0 0.00 0 48373 113 0 0
sp_lock_selected_doc 19906 19906 0 0.00 0 7119 7 0 0
sp_make_cost_storno 26928 26928 0 0.00 0 10134 83 0 0
sp_multiply_rows_for_pdistr 26928 26928 0 0.00 0 7840 42 0 0
sp_multiply_rows_for_qdistr 19953 19953 0 0.00 0 55217 194 0 0
sp_pay_from_customer 6943 6943 0 0.00 5 12697 194 0 0
sp_pay_to_supplier 5659 5659 0 0.00 7 15424 212 0 0
sp_payment_common 12602 12602 0 0.00 2 11421 47 0 0
sp_qd_handle_on_cancel_clo 1697 1697 0 0.00 0 15654 429 0 0
sp_qd_handle_on_invoice_upd_sts 9764 9764 0 0.00 0 15251 561 0 0
sp_qd_handle_on_reserve_upd_sts 9603 9603 0 0.00 0 29912 67 0 0
sp_reserve_write_off 9604 9604 0 0.00 8 30119 283 0 0
sp_supplier_invoice 5183 5183 0 0.00 9 133597 3463 0 0
sp_supplier_order 5245 5245 0 0.00 8 85787 1462 0 0
srv_aggregate_perf_data 106 106 0 0.00 2179 134704 14245 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 870 163447 35512 0 0
srv_find_qd_qs_mism 968296 968296 0 0.00 0 15091 11 0 0
srv_make_invnt_saldo 491 491 0 0.00 50 111758 4089 0 0
srv_make_money_saldo 874 874 0 0.00 1 18591 427 0 0
srv_recalc_idx_stat 4 4 0 0.00 103648 284081 224261 0 0
t$perf-abend:sp_add_invoice_to_stock 195 195 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4766 4766 0 0.00 1 40835 976 0 0
t$perf-norm:sp_cancel_adding_invoice 386 386 0 0.00 1 29980 486 0 0
t$perf-norm:sp_cancel_client_order 1697 1697 0 0.00 0 4085 29 0 0
t$perf-norm:sp_cancel_customer_reserve 686 686 0 0.00 0 10883 48 0 0
t$perf-norm:sp_cancel_pay_from_customer 679 679 0 0.00 0 1638 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 1155 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 362 362 0 0.00 0 3712 58 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 117651 7872 0 0
t$perf-norm:sp_cancel_write_off 991 991 0 0.00 0 2033 21 0 0
t$perf-norm:sp_client_order 19953 19953 0 0.00 0 11472 41 0 0
t$perf-norm:sp_customer_reserve 957 957 0 0.00 0 608 13 0 0
t$perf-norm:sp_pay_from_customer 6943 6943 0 0.00 0 3615 19 0 0
t$perf-norm:sp_pay_to_supplier 5659 5659 0 0.00 0 13163 26 0 0
t$perf-norm:sp_reserve_write_off 9604 9604 0 0.00 0 18073 25 0 0
t$perf-norm:sp_supplier_invoice 5183 5183 0 0.00 0 4782 32 0 0
t$perf-norm:sp_supplier_order 5245 5245 0 0.00 0 2514 34 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 588 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 1464 24 0 0
t$perf-norm:srv_make_invnt_saldo 491 491 0 0.00 0 1094 17 0 0
t$perf-norm:srv_make_money_saldo 874 874 0 0.00 0 933 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 8 151 82 0 0
v_add_invoice_to_stock 9820 9430 390 3.97 0 19527 29 0 390
v_all_customers 36820 36820 0 0.00 0 120 4 0 0
v_all_suppliers 29392 29392 0 0.00 0 67 4 0 0
v_all_wares 39894 39894 0 0.00 0 52 4 0 0
v_cancel_adding_invoice 772 772 0 0.00 0 422 14 0 0
v_cancel_client_order 3394 3394 0 0.00 0 15878 19 0 0
v_cancel_customer_prepayment 1358 1358 0 0.00 0 2584 14 0 0
v_cancel_customer_reserve 1372 1372 0 0.00 0 1850 12 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 820 11 0 0
v_cancel_supplier_invoice 738 724 14 1.89 0 2044 28 0 14
v_cancel_supplier_order 784 784 0 0.00 0 2582 16 0 0
v_cancel_write_off 1982 1982 0 0.00 0 2081 21 0 0
v_max_id_clo_ord 5244 5244 0 0.00 0 4526 6 0 0
v_max_id_clo_res 957 957 0 0.00 0 3419 21 0 0
v_max_id_ord_sup 5174 5174 0 0.00 0 8190 8 0 0
v_max_non_paid_invoice 1381 1381 0 0.00 0 2767 10 0 0
v_max_non_paid_realizn 3452 3452 0 0.00 0 1671 7 0 0
v_min_id_clo_ord 5244 5244 0 0.00 0 2271 7 0 0
v_min_id_clo_res 957 957 0 0.00 0 4375 14 0 0
v_min_id_ord_sup 5174 5174 0 0.00 0 2563 7 0 0
v_min_non_paid_invoice 5659 5659 0 0.00 0 11411 19 0 0
v_min_non_paid_realizn 6943 6943 0 0.00 0 9299 17 0 0
v_our_firm 10054 10054 0 0.00 0 60 4 0 0
v_reserve_write_off 19205 19205 0 0.00 0 12312 12 0 0
x_lock_depdocs_on_canc_invoice 10297 10297 0 0.00 0 18899 131 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 7745 415 0 0
x_make_qstorno_1000_1200 5245 5245 0 0.00 3 85747 1294 0 0
x_make_qstorno_1200_2000 5183 5183 0 0.00 4 133489 3216 0 0
x_make_qstorno_2100_3300 444387 444387 0 0.00 0 149973 244 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 06-01-2022 13:39:47 to 06-01-2022 13:39:52.

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:16 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:17 26839 8192000000 9724217360 10023555072 794065856 8.836 100 82 163 1 825443072 11399520 19977696
11:09:53 30290 8192000000 9761745968 10068598784 838766928 9.287 100 91 182 0 872095712 11562832 21765952
11:11:35 19314 8192000000 9812116384 10133045248 876298992 9.663 100 74 148 0 911377696 18227200 16851504
11:13:01 7523 8192000000 9894536240 10229223424 930051888 10.195 100 78 158 3 996905520 49856032 16997600
11:14:16 7948 8192000000 9941214896 10284638208 970226848 10.589 100 90 178 0 1051542880 62831296 18484736
11:15:33 1937 8192000000 9983849904 10335637504 1001031392 10.889 100 96 188 1 1098593376 79237104 18324880
11:16:37 5070 8192000000 10009496848 10370453504 1015119312 11.025 100 80 154 0 1127375616 97342128 14914176
11:17:45 1307 8192000000 9998258528 10366226432 1041404240 11.278 100 92 177 2 1120931680 61606016 17921424
11:18:49 926 8192000000 10017756368 10391339008 1055850336 11.417 100 95 181 1 1138610448 65770176 16989936
11:19:52 1617 8192000000 10027342512 10402988032 1064181632 11.496 100 95 180 2 1144844176 62939152 17723392
11:20:55 1158 8192000000 10068065760 10445348864 1074745904 11.597 100 96 184 0 1191445152 97670112 19029136
11:21:59 1966 8192000000 10050777264 10438225920 1080825920 11.655 100 90 174 1 1171966976 73159952 17981104
11:23:02 1041 8192000000 10054569776 10443874304 1086526752 11.710 100 83 154 1 1181780096 78140688 17112656
11:24:05 923 8192000000 10052373536 10434801664 1093018832 11.771 100 96 189 1 1171020496 58429248 19572416
11:25:07 1569 8192000000 10042255616 10427482112 1092295952 11.764 100 83 154 1 1159407840 50374800 16737088
11:26:10 1280 8192000000 10081929424 10471071744 1097893536 11.818 100 95 188 2 1206529376 89855248 18780592
11:27:12 1249 8192000000 10075818064 10466381824 1102823680 11.864 100 95 180 0 1195592704 75413328 17355696
11:28:14 1982 8192000000 10071146832 10459238400 1101977728 11.856 100 89 168 0 1195532432 76519040 17035664
11:29:18 1047 8192000000 10050532096 10438381568 1099009680 11.828 100 91 175 1 1174664352 56753216 18901456
11:30:22 1103 8192000000 10014546400 10397863936 1078885328 11.637 100 82 151 2 1143959552 49423120 15651104
11:31:23 6201 8192000000 10016788688 10399162368 1058338896 11.441 98 67 129 0 1133064944 62205648 12520400
11:32:31 1469 8192000000 10019525392 10402357248 1049787280 11.359 99 76 149 1 1141865200 77581008 14496912
11:33:34 1298 8192000000 10002457456 10373955584 1034066032 11.208 100 87 167 1 1126703872 73793008 18844832
11:34:36 1840 8192000000 10009068560 10383089664 1049291152 11.354 100 85 159 2 1132437088 66978048 16167888
11:35:40 1411 8192000000 10022040976 10395811840 1052564672 11.385 100 95 174 3 1146065248 74766096 18734480
11:36:42 1169 8192000000 10016280080 10394005504 1050305184 11.364 100 88 167 0 1134752096 65744144 18702768
11:37:49 13670 8192000000 9995172304 10375794688 1051701488 11.377 100 57 112 2 1110278304 47212080 11364736
11:39:07 7630 8192000000 10023858176 10413223936 1079475568 11.642 100 62 120 2 1153138080 61528912 12133600
11:40:20 2460 8192000000 10062367472 10438860800 1089798400 11.741 100 80 155 1 1178084864 72089824 16196640
11:41:26 1291 8192000000 10120466032 10498117632 1106763936 11.902 100 96 182 1 1232925792 107664320 18497536
11:42:32 5493 8192000000 10136351776 10527739904 1114728352 11.977 100 79 152 1 1252780176 122831632 15220192
11:43:42 3226 8192000000 10175606496 10571313152 1126179616 12.085 100 62 117 1 1295550400 155817872 13552912
11:44:51 3750 8192000000 10221639952 10620653568 1131818352 12.139 100 64 124 1 1336654160 190381136 14454672
11:45:59 1642 8192000000 10260106176 10657951744 1144846864 12.261 100 86 169 0 1380421968 216949136 18625968
11:47:05 2341 8192000000 10266418928 10665234432 1148624576 12.297 100 87 156 1 1374049056 208241456 17183024
11:48:13 2977 8192000000 10320555584 10719154176 1163590256 12.437 100 90 176 0 1435064592 250264256 21210080
11:49:21 2883 8192000000 10359875088 10759946240 1167939584 12.478 100 80 155 1 1473639040 287775584 17923872
11:50:29 1487 8192000000 10387406336 10790002688 1175614944 12.549 100 85 166 0 1497595104 302813072 19167088
11:51:35 1408 8192000000 10431197824 10849320960 1187522496 12.660 100 91 178 0 1542452528 333820400 21109632
11:52:42 865 8192000000 10437022400 10839908352 1187689584 12.662 100 89 170 1 1542000544 333300320 21010640
11:53:50 2868 8192000000 10448510032 10878730240 1197356352 12.752 100 88 175 0 1559654320 340719184 21578784
11:54:57 942 8192000000 10498706928 10909499392 1196755680 12.746 100 90 175 0 1610510608 392021712 21733216
11:56:04 1488 8192000000 10492492720 10935750656 1190476896 12.688 100 90 175 0 1597235792 385128816 21630080
11:57:12 1986 8192000000 10543387312 10958147584 1204229856 12.816 100 96 190 0 1649195760 421969552 22996352
11:58:17 1281 8192000000 10552367600 10996162560 1192055552 12.702 100 88 171 2 1657632544 444752272 20824720
11:59:24 877 8192000000 10561433104 10988953600 1185187728 12.639 99 96 190 0 1667769472 459970800 22610944
12:00:29 1063 8192000000 10569220656 11021082624 1193812320 12.719 100 100 195 2 1672953376 453633600 25507456
12:01:32 904 8192000000 10574751056 11030585344 1180122608 12.591 100 97 179 4 1677600992 476272544 21205840
12:02:37 4341 8192000000 10535148464 11013206016 1169028432 12.488 100 63 120 1 1637087136 452479520 15579184
12:04:15 6433 8192000000 10486888512 10951348224 1171977024 12.515 100 65 122 1 1601879008 416641728 13260256
12:05:24 18910 8192000000 10364435920 10840485888 1158156240 12.386 100 34 68 1 1506725424 339780656 8788528
12:06:50 12215 8192000000 10318845056 10808733696 1160855776 12.411 100 30 60 0 1446702496 278543072 7303648
12:08:03 28973 8192000000 10242942464 10728996864 1133800256 12.157 98 25 50 3 1340804560 200610432 6393872
12:09:38 15019 8192000000 10154981136 10612170752 1133349136 12.153 99 45 90 2 1275342800 131894352 10099312
12:10:55 48036 8192000000 10126895056 10579906560 1106288160 11.897 97 26 48 1 1248039856 136277360 5474336
12:12:46 12184 8192000000 10172520080 10616918016 1135262976 12.171 99 30 57 1 1286831712 144634016 6934720
12:13:59 6226 8192000000 10158333968 10583171072 1144232560 12.255 100 55 99 3 1259621856 102966608 12422688
12:15:06 163446 8192000000 10156970608 10596560896 1138190592 12.199 99 29 58 2 1269106672 124601696 6314384
12:18:51 60966 8192000000 10114217216 10583293952 1122170624 12.047 98 42 83 1 1231740400 100739216 8830560
12:21:05 156891 8192000000 10098906096 10580885504 1095785728 11.798 96 9 19 0 1133881008 36129056 1966224
12:24:42 48895 8192000000 10090908896 10545733632 1087882944 11.723 96 17 34 0 1173880496 82239728 3757824
12:26:48 69615 8192000000 10045909184 10502537216 1121580592 12.042 100 12 24 0 1164528816 40639392 2308832
12:29:02 124379 8192000000 10056781824 10524782592 1104487984 11.880 97 27 52 0 1155827136 45919440 5419712
12:32:08 26984 8192000000 10075304960 10516844544 1135821632 12.176 100 37 72 2 1189326768 46058064 7447072
12:33:44 55880 8192000000 10074594880 10524073984 1127430384 12.097 99 18 36 0 1164815984 33289056 4096544
12:35:47 89293 8192000000 10133301600 10601222144 1158443552 12.389 100 19 38 3 1244164032 81788784 3931696
12:38:17 69642 8192000000 10067958864 10513416192 1129442496 12.116 98 11 22 0 1160962960 29717088 1803376
12:40:27 31808 8192000000 10113802256 10541158400 1152720528 12.335 100 46 84 0 1200687856 40330176 7637152
12:42:00 32683 8192000000 10188700960 10644152320 1152074944 12.329 99 40 79 0 1263248992 103296352 7877696
12:43:34 34507 8192000000 10140116512 10599272448 1154875136 12.355 100 21 42 1 1239542112 80093952 4573024
12:45:13 44125 8192000000 10190454848 10643398656 1166284240 12.462 100 46 92 1 1267672304 92254432 9133632
12:47:08 15619 8192000000 10109558208 10562195456 1137271280 12.190 98 49 94 2 1210353360 63967904 9114176
12:48:27 13512 8192000000 10120685424 10553188352 1145294064 12.265 99 46 90 3 1206152736 51547008 9311664
12:50:03 17806 8192000000 10123197760 10549374976 1083341552 11.679 95 45 89 0 1171961072 80246592 8372928
12:51:21 18819 8192000000 10098439536 10522984448 1116318608 11.992 99 40 77 0 1169546160 45389056 7838496
12:52:45 90083 8192000000 10096859872 10542530560 1090608592 11.748 97 41 82 1 1173325488 73895408 8821488
12:55:23 8804 8192000000 10068349328 10487447552 1110173008 11.934 99 73 137 1 1166204352 42217792 13813552
12:56:34 39989 8192000000 10072750672 10518712320 1108319408 11.917 98 28 56 0 1182709040 68215872 6173760
12:59:29 80118 8192000000 10085030240 10581557248 1101480848 11.852 97 18 33 1 1182026736 76507040 4038848
13:01:49 52507 8192000000 10120110656 10567684096 1108016720 11.914 98 18 36 1 1200955728 88255648 4683360
13:03:58 50465 8192000000 10092207264 10528993280 1094936208 11.790 97 42 82 1 1165036320 61669392 8430720
13:05:54 55665 8192000000 10072129600 10529763328 1082665488 11.673 97 17 34 1 1150701984 64107648 3928848
13:07:52 65362 8192000000 10069450704 10521423872 1128671680 12.109 100 29 56 1 1181567376 47352288 5543408
13:10:08 32597 8192000000 10082433200 10522972160 1112531728 11.956 99 35 70 0 1177484496 58359904 6592864
13:12:12 51169 8192000000 10103515568 10581348352 1122159872 12.047 99 23 46 0 1205713536 79016640 4537024
13:14:07 124445 8192000000 10115037760 10550202368 1129084032 12.113 100 49 97 0 1207121664 68629824 9407808
13:17:12 96281 8192000000 10080756736 10581336064 1123357264 12.059 99 10 19 1 1168886176 43432400 2096512
13:19:48 78382 8192000000 10049159392 10488983552 1068336272 11.536 94 14 27 0 1107467296 36616624 2514400
13:22:11 52220 8192000000 10069730688 10531835904 1121515888 12.041 99 28 57 2 1165934768 38380416 6038464
13:24:09 22126 8192000000 10114353328 10561814528 1130878160 12.130 99 33 66 0 1200903056 63020704 7004192
13:25:35 44554 8192000000 10108063120 10575527936 1141729120 12.232 100 25 50 1 1224457504 77288224 5440160
13:27:26 32419 8192000000 10114354000 10549780480 1128409440 12.106 98 22 44 1 1195688208 62505296 4773472
13:29:06 81360 8192000000 10109637008 10623856640 1103739760 11.873 97 19 38 0 1204517984 96662736 4115488
13:31:31 39868 8192000000 10091182192 10525286400 1110125296 11.934 97 32 63 1 1182645104 66292768 6227040
13:33:11 13637 8192000000 10075070640 10495799296 1107530496 11.909 99 51 98 0 1156901040 39848368 9522176
13:34:28 93674 8192000000 10106296240 10559033344 1118797632 12.016 99 50 100 0 1220761760 92234368 9729760
13:37:03 25653 8192000000 9426516464 9796157440 541557088 6.200 49 4 8 0 578759760 36561024 641648

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 06-01-2022 13:39:53 to 06-01-2022 13:40:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 195 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-01-2022 13:40:10 to 06-01-2022 13:40:12.

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          213633
MON$OLDEST_ACTIVE               213846
MON$OLDEST_SNAPSHOT             213846
MON$NEXT_TRANSACTION            213846
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                       2039264
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             764
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2704 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2704 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2704 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2704 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 ---
:88: 
:89: mscs-test-161.budzdorov.ru    Thu Jan  6 13:37:54 2022
:90:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:91: 
:92: 
:93: mscs-test-161.budzdorov.ru    Thu Jan  6 13:37:54 2022
:94:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:95: 
--- end of diff output ---

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

06.01.22 13:40:13. 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-01-06 13:40:16 No severe PSQL-related problems occured
06.01.22 13:40:17. End of report.