Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.06.2022 04: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 15217276 kB
MemAvailable 31130288 kB
Buffers 20 kB
Cached 16383112 kB
SwapCached 2492 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-06-29 04:37:17
DTS_END                         2022-06-29 04:37:17.5660
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/36016
TRN_ID                          212359
ATT_ID                          618
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: 8573.91 27500 1028869
customer order: creation 163.79 532 19655
customer order: refuse 13.91 514 1669
order to supplier: creation 42.86 1532 5143
order to supplier: removal 3.25 201875 390
invoice (draft): creation 42.96 3620 5155
invoice (draft): removal 47.57 1260 5708
invoice accept: apply 38.91 29649 4669
invoice accept: cancel 43.34 1544 5201
customer reserve: creation 3652.63 275 438316
customer reserve: removal 4246.13 122 509536
realization accept: apply 79.88 329 9586
realization accept: cancel 77.20 177 9264
payment from customer: creation 55.41 200 6649
payment from customer: removal 4.89 150 587
payment to supplier: creation 46.53 249 5583
payment to supplier: removal 3.05 127 366
service: total inventory turnovers 3.40 5674 408
service: total monetary turnovers 7.33 443 879
service: aggregate perf. data 0.83 18529 100
service: refresh index statistics 0.04 283192 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-06-2022 04:38:10 to 29-06-2022 04:38:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8153
TEST_TIME 2 5842
TEST_TIME 3 5916
TEST_TIME 4 5974
TEST_TIME 5 6023
TEST_TIME 6 6063
TEST_TIME 7 5759
TEST_TIME 8 5728
TEST_TIME 9 5889
TEST_TIME 10 6268
TEST_TIME 11 6356
TEST_TIME 12 6598
TEST_TIME 13 6660
TEST_TIME 14 6771
TEST_TIME 15 6916
TEST_TIME 16 7132
TEST_TIME 17 7260
TEST_TIME 18 7271
TEST_TIME 19 7546
TEST_TIME 20 7884
TEST_TIME 21 7967
TEST_TIME 22 8096
TEST_TIME 23 8140
TEST_TIME 24 8275
TEST_TIME 25 8124
TEST_TIME 26 8373
TEST_TIME 27 8388
TEST_TIME 28 8848
TEST_TIME 29 8892
TEST_TIME 30 8842
TEST_TIME 31 8839
TEST_TIME 32 8657
TEST_TIME 33 8670
TEST_TIME 34 8760
TEST_TIME 35 8639
TEST_TIME 36 9296
TEST_TIME 37 9559
TEST_TIME 38 9582
TEST_TIME 39 9830
TEST_TIME 40 10070
TEST_TIME 41 10064
TEST_TIME 42 10231
TEST_TIME 43 10227
TEST_TIME 44 10045
TEST_TIME 45 9941
TEST_TIME 46 9830
TEST_TIME 47 9660
TEST_TIME 48 9691
TEST_TIME 49 9609
TEST_TIME 50 9579
TEST_TIME 51 9798
TEST_TIME 52 9931
TEST_TIME 53 10030
TEST_TIME 54 9906
TEST_TIME 55 9921
TEST_TIME 56 9848
TEST_TIME 57 9764
TEST_TIME 58 9668
TEST_TIME 59 9724
TEST_TIME 60 9629
TEST_TIME 61 9568
TEST_TIME 62 9464
TEST_TIME 63 9387
TEST_TIME 64 9379
TEST_TIME 65 9312
TEST_TIME 66 9256
TEST_TIME 67 9183
TEST_TIME 68 9122
TEST_TIME 69 9079
TEST_TIME 70 9132
TEST_TIME 71 9079
TEST_TIME 72 9017
TEST_TIME 73 8992
TEST_TIME 74 8905
TEST_TIME 75 8977
TEST_TIME 76 8923
TEST_TIME 77 8855
TEST_TIME 78 8832
TEST_TIME 79 8824
TEST_TIME 80 8831
TEST_TIME 81 8904
TEST_TIME 82 8871
TEST_TIME 83 8867
TEST_TIME 84 8860
TEST_TIME 85 8818
TEST_TIME 86 8807
TEST_TIME 87 8918
TEST_TIME 88 8921
TEST_TIME 89 8984
TEST_TIME 90 8971
TEST_TIME 91 8984
TEST_TIME 92 8997
TEST_TIME 93 8983
TEST_TIME 94 8963
TEST_TIME 95 8923
TEST_TIME 96 8895
TEST_TIME 97 8885
TEST_TIME 98 8835
TEST_TIME 99 8817
TEST_TIME 100 8779
TEST_TIME 101 8783
TEST_TIME 102 8743
TEST_TIME 103 8740
TEST_TIME 104 8732
TEST_TIME 105 8739
TEST_TIME 106 8703
TEST_TIME 107 8643
TEST_TIME 108 8618
TEST_TIME 109 8571
TEST_TIME 110 8566
TEST_TIME 111 8581
TEST_TIME 112 8588
TEST_TIME 113 8594
TEST_TIME 114 8576
TEST_TIME 115 8563
TEST_TIME 116 8580
TEST_TIME 117 8614
TEST_TIME 118 8604
TEST_TIME 119 8636
TEST_TIME 120 8592

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-06-2022 04:38:14 to 29-06-2022 04:38:17.

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 1432476 1432476 0 0.00 0 24447 15 0 0
doc_list_biud 1432467 1432467 0 0.00 0 28098 51 0 0
sp_add_invoice_to_stock 4795 4669 126 2.62 53 221337 29649 0 126
sp_cancel_adding_invoice 5201 5201 0 0.00 7 86027 1545 0 0
sp_cancel_client_order 1669 1669 0 0.00 5 10046 514 0 0
sp_cancel_customer_reserve 509536 509536 0 0.00 1 28139 122 0 0
sp_cancel_pay_from_customer 587 587 0 0.00 4 1879 150 0 0
sp_cancel_pay_to_supplier 366 366 0 0.00 4 1302 127 0 0
sp_cancel_supplier_invoice 5717 5708 9 0.15 3 24768 1260 0 9
sp_cancel_supplier_order 390 390 0 0.00 15 1567377 201876 0 0
sp_cancel_write_off 9264 9264 0 0.00 2 7974 177 0 0
sp_client_order 19655 19655 0 0.00 6 35856 532 0 0
sp_customer_reserve 438316 438316 0 0.00 1 69365 275 0 0
sp_fill_shopping_cart 29943 29943 0 0.00 2 26906 102 0 0
sp_fill_shopping_cart_clo_res 438288 438288 0 0.00 0 13669 21 0 0
sp_get_clo_for_invoice 4634 4634 0 0.00 0 22698 330 0 0
sp_kill_cost_storno 15418 15418 0 0.00 0 6607 89 0 0
sp_kill_qstorno_ret_qs2qd 1034434 1034434 0 0.00 0 27979 53 0 0
sp_kill_qty_storno 545986 545986 0 0.00 0 28094 123 0 0
sp_lock_selected_doc 19575 19575 0 0.00 0 10685 8 0 0
sp_make_cost_storno 26452 26452 0 0.00 0 19144 100 0 0
sp_multiply_rows_for_pdistr 26452 26452 0 0.00 0 13384 44 0 0
sp_multiply_rows_for_qdistr 19655 19655 0 0.00 0 27563 189 0 0
sp_pay_from_customer 6649 6649 0 0.00 5 18099 201 0 0
sp_pay_to_supplier 5583 5583 0 0.00 6 16268 250 0 0
sp_payment_common 12232 12232 0 0.00 2 12649 58 0 0
sp_qd_handle_on_cancel_clo 1669 1669 0 0.00 1 10037 420 0 0
sp_qd_handle_on_invoice_upd_sts 9835 9835 0 0.00 0 18895 677 0 0
sp_qd_handle_on_reserve_upd_sts 9584 9584 0 0.00 0 10556 77 0 0
sp_reserve_write_off 9586 9586 0 0.00 8 19180 330 0 0
sp_supplier_invoice 5155 5155 0 0.00 10 78145 3620 0 0
sp_supplier_order 5143 5143 0 0.00 8 58925 1533 0 0
srv_aggregate_perf_data 101 100 1 0.99 2850 152229 18530 0 1
srv_fill_mon_cache_memory 73 73 0 0.00 834 153738 31846 0 0
srv_find_qd_qs_mism 982648 982648 0 0.00 0 24446 11 0 0
srv_make_invnt_saldo 408 408 0 0.00 84 135967 5675 0 0
srv_make_money_saldo 879 879 0 0.00 1 11280 443 0 0
srv_recalc_idx_stat 5 5 0 0.00 49098 491955 283192 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4673 4673 0 0.00 1 15214 1251 0 0
t$perf-norm:sp_cancel_adding_invoice 386 386 0 0.00 2 7373 721 0 0
t$perf-norm:sp_cancel_client_order 1669 1669 0 0.00 0 2500 35 0 0
t$perf-norm:sp_cancel_customer_reserve 648 648 0 0.00 0 2461 40 0 0
t$perf-norm:sp_cancel_pay_from_customer 587 587 0 0.00 0 5389 27 0 0
t$perf-norm:sp_cancel_pay_to_supplier 366 366 0 0.00 0 1305 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 369 369 0 0.00 0 1146 45 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 115651 9736 0 0
t$perf-norm:sp_cancel_write_off 946 946 0 0.00 0 1888 26 0 0
t$perf-norm:sp_client_order 19655 19655 0 0.00 0 4600 47 0 0
t$perf-norm:sp_customer_reserve 886 886 0 0.00 0 2381 20 0 0
t$perf-norm:sp_pay_from_customer 6649 6649 0 0.00 0 4643 25 0 0
t$perf-norm:sp_pay_to_supplier 5583 5583 0 0.00 0 7190 28 0 0
t$perf-norm:sp_reserve_write_off 9586 9586 0 0.00 0 15236 31 0 0
t$perf-norm:sp_supplier_invoice 5155 5155 0 0.00 0 10166 40 0 0
t$perf-norm:sp_supplier_order 5143 5143 0 0.00 0 5249 42 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 340 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 421 12 0 0
t$perf-norm:srv_make_invnt_saldo 408 408 0 0.00 0 714 16 0 0
t$perf-norm:srv_make_money_saldo 879 879 0 0.00 0 2658 15 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 180 66 0 0
v_add_invoice_to_stock 9512 9260 252 2.64 0 5320 29 0 252
v_all_customers 35896 35896 0 0.00 0 123 5 0 0
v_all_suppliers 28934 28934 0 0.00 0 126 5 0 0
v_all_wares 39305 39305 0 0.00 0 117 5 0 0
v_cancel_adding_invoice 772 772 0 0.00 0 1786 23 0 0
v_cancel_client_order 3338 3338 0 0.00 0 4578 15 0 0
v_cancel_customer_prepayment 1174 1174 0 0.00 0 1828 17 0 0
v_cancel_customer_reserve 1296 1296 0 0.00 1 1948 14 0 0
v_cancel_payment_to_supplier 732 732 0 0.00 0 246 11 0 0
v_cancel_supplier_invoice 756 738 18 2.38 0 729 27 0 18
v_cancel_supplier_order 780 780 0 0.00 0 835 13 0 0
v_cancel_write_off 1892 1892 0 0.00 0 1532 28 0 0
v_max_id_clo_ord 5139 5139 0 0.00 0 3013 7 0 0
v_max_id_clo_res 886 886 0 0.00 0 25850 47 0 0
v_max_id_ord_sup 5149 5149 0 0.00 0 5519 10 0 0
v_max_non_paid_invoice 1403 1403 0 0.00 0 136 10 0 0
v_max_non_paid_realizn 3425 3425 0 0.00 0 6087 11 0 0
v_min_id_clo_ord 5139 5139 0 0.00 0 4174 13 0 0
v_min_id_clo_res 886 886 0 0.00 0 1794 12 0 0
v_min_id_ord_sup 5149 5149 0 0.00 0 2008 7 0 0
v_min_non_paid_invoice 5583 5583 0 0.00 0 9530 22 0 0
v_min_non_paid_realizn 6649 6649 0 0.00 0 11347 24 0 0
v_our_firm 9856 9856 0 0.00 0 84 6 0 0
v_reserve_write_off 19165 19165 0 0.00 0 2718 12 0 0
x_lock_depdocs_on_canc_invoice 10540 10540 0 0.00 0 25596 161 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 15320 452 0 0
x_make_qstorno_1000_1200 5143 5143 0 0.00 2 58886 1312 0 0
x_make_qstorno_1200_2000 5155 5155 0 0.00 5 71163 3358 0 0
x_make_qstorno_2100_3300 438316 438316 0 0.00 0 69363 239 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-06-2022 04:38:18 to 29-06-2022 04:38:22.

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
02:07:21 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:22 21560 8192000000 9730150320 10032013312 810950672 9.007 100 86 172 0 839497216 7693424 20853120
02:09:51 20723 8192000000 9772157904 10084454400 846650272 9.366 100 91 180 0 880931376 13342176 20938928
02:11:17 17051 8192000000 9815978768 10137370624 879246752 9.692 100 85 170 0 920379968 21959120 19174096
02:12:46 6748 8192000000 9877477648 10208251904 920790432 10.104 100 88 176 0 984120528 44395680 18934416
02:13:54 4732 8192000000 9917122704 10260332544 951874976 10.409 100 87 173 0 1041610432 70552992 19182464
02:15:02 2775 8192000000 9967306032 10317123584 985242416 10.735 100 93 186 0 1076416208 71965296 19208496
02:16:06 1296 8192000000 9991763024 10357301248 1020242864 11.074 100 91 175 0 1111674160 72949024 18482272
02:17:09 1176 8192000000 10013210480 10377367552 1033365680 11.201 100 89 168 3 1130341984 79994768 16981536
02:18:12 1011 8192000000 10028402544 10404691968 1063873968 11.494 100 97 187 0 1152530400 70561456 18094976
02:19:15 1147 8192000000 10052777776 10433777664 1074422880 11.594 100 94 186 1 1178584928 84661872 19500176
02:20:18 1073 8192000000 10070866000 10449768448 1076208128 11.611 100 96 180 1 1181271840 87128256 17935456
02:21:22 1206 8192000000 10032810752 10416762880 1087534704 11.719 100 84 158 1 1162166912 58016016 16616192
02:22:24 3223 8192000000 10065487808 10449760256 1087756112 11.721 100 89 170 3 1187517904 82763024 16998768
02:23:28 1114 8192000000 10062409632 10446950400 1095092784 11.791 100 99 193 0 1183035264 68768944 19173536
02:24:31 1549 8192000000 10056126048 10443882496 1097161600 11.811 100 94 172 0 1176013792 61023232 17828960
02:25:34 1536 8192000000 10071403120 10465394688 1098707120 11.825 100 84 161 0 1190288576 75817984 15763472
02:26:37 1317 8192000000 10079752800 10470129664 1100046480 11.838 100 94 182 0 1203398656 84423232 18928944
02:27:40 1237 8192000000 10096587632 10487705600 1103275520 11.869 100 94 173 1 1218040064 97646944 17117600
02:28:43 1084 8192000000 10047541920 10443223040 1094972144 11.790 100 92 177 4 1172136960 58469216 18695600
02:29:45 1254 8192000000 10058718496 10451132416 1085617264 11.701 100 93 178 1 1184011472 80024480 18369728
02:30:47 847 8192000000 10049284496 10432729088 1078105744 11.629 99 90 168 2 1169534656 74698736 16730176
02:31:49 1337 8192000000 10038918320 10418581504 1059207904 11.449 100 98 190 2 1161089408 84028976 17852528
02:32:51 874 8192000000 10024555968 10399035392 1051244672 11.373 100 96 181 2 1148670032 78098256 19327104
02:33:53 1242 8192000000 9986008160 10359644160 1019337328 11.066 97 77 147 1 1107029904 72657600 15034976
02:34:55 3416 8192000000 9985524944 10368405504 1040831168 11.273 99 70 124 3 1099518240 46659552 12027520
02:35:59 4062 8192000000 10000544240 10379411456 1048268240 11.344 100 68 131 1 1122687264 61670144 12748880
02:37:04 1407 8192000000 9984935728 10362912768 1032440640 11.192 99 76 136 1 1102444592 56929536 13074416
02:38:11 4408 8192000000 10013079552 10402050048 1055740656 11.416 100 70 130 1 1127210512 58873760 12596096
02:39:24 14871 8192000000 10033155920 10419924992 1069766432 11.550 100 74 141 1 1146912480 63165216 13980832
02:40:58 16760 8192000000 10074111536 10463330304 1087865552 11.722 100 75 147 0 1184559232 81276976 15416704
02:42:20 1692 8192000000 10080620192 10469765120 1105904880 11.894 100 91 163 0 1198204144 75471728 16827536
02:43:32 1169 8192000000 10135097312 10523369472 1126412464 12.088 100 98 183 3 1256662176 109874608 20375104
02:44:37 3291 8192000000 10181822352 10580873216 1133140064 12.151 100 71 139 0 1297930816 150374208 14416544
02:45:54 1324 8192000000 10207126080 10612428800 1140974176 12.225 100 92 172 1 1320952528 161951536 18026816
02:46:58 944 8192000000 10221628384 10628681728 1145617536 12.268 100 97 187 1 1336191664 170248336 20325792
02:48:07 1188 8192000000 10233226864 10639749120 1159477200 12.398 100 87 163 1 1346418496 167563744 19377552
02:49:11 993 8192000000 10267965696 10667024384 1166114992 12.461 100 89 166 2 1381855072 196470864 19269216
02:50:16 1187 8192000000 10275011664 10683584512 1161658832 12.419 100 99 187 1 1387565184 205826656 20079696
02:51:19 1395 8192000000 10341198208 10746482688 1179838016 12.589 100 88 172 0 1451102064 251362080 19901968
02:52:23 984 8192000000 10364102544 10786340864 1188029520 12.665 100 94 183 1 1474923728 265108176 21786032
02:53:30 1667 8192000000 10371083856 10790830080 1188635296 12.671 100 99 195 0 1476929520 266039136 22255088
02:54:34 1438 8192000000 10384151360 10795409408 1189795824 12.681 100 88 164 1 1495354640 287045712 18513104
02:55:38 828 8192000000 10373651552 10815774720 1185352960 12.640 100 97 192 0 1501255536 293965056 21937520
02:56:41 1667 8192000000 10384772688 10819461120 1183004864 12.618 99 89 176 0 1493379056 290241696 20132496
02:57:45 1004 8192000000 10395776800 10819375104 1188633232 12.671 100 97 189 0 1500547808 290655488 21259088
02:58:50 3153 8192000000 10410329632 10841849856 1185092032 12.638 99 93 184 0 1510913952 302800080 23021840
02:59:55 1193 8192000000 10434716336 10861785088 1192237456 12.704 100 97 193 2 1537915264 322914336 22763472
03:01:05 3030 8192000000 10435155760 10852286464 1178737264 12.578 100 75 148 0 1538095136 341229536 18128336
03:02:13 1491 8192000000 10440850640 10860113920 1187310320 12.658 100 87 166 0 1550420832 341659296 21451216
03:03:19 1548 8192000000 10434622704 10917015552 1183475712 12.623 100 94 177 0 1540676528 336211408 20989408
03:04:23 1535 8192000000 10415429824 10903670784 1181622320 12.605 100 95 189 0 1517460384 312420880 23417184
03:05:27 4153 8192000000 10437985888 10879107072 1190432384 12.687 100 79 155 1 1541621488 332840288 18348816
03:06:45 7103 8192000000 10443103232 10879016960 1179139120 12.582 100 52 102 0 1542544928 350051216 13354592
03:07:57 3605 8192000000 10433801536 10876080128 1187451248 12.660 100 67 125 2 1535982672 332927840 15603584
03:09:03 16481 8192000000 10488841136 10928373760 1186049104 12.647 100 40 80 0 1598028512 401872592 10106816
03:10:29 14856 8192000000 10516627648 10997719040 1186386032 12.650 100 36 72 0 1617541936 421577120 9578784
03:11:57 19279 8192000000 10503860080 10974457856 1166648192 12.465 99 32 64 0 1600622048 425796752 8177104
03:13:19 16734 8192000000 10382040928 10921951232 1168157600 12.480 100 25 50 0 1500458512 325595248 6705664
03:14:36 38958 8192000000 10277468976 10750889984 1132481088 12.145 99 17 33 0 1392342768 255247152 4614528
03:16:25 5668 8192000000 10218148720 10675892224 1151727680 12.326 100 63 120 2 1329232080 162222400 15282000
03:17:32 5670 8192000000 10204567600 10684059648 1142456080 12.239 100 58 111 1 1308480816 153008176 13016560
03:18:40 1303 8192000000 10201593120 10647867392 1151705424 12.326 100 74 142 1 1294494144 127032368 15756352
03:19:45 929 8192000000 10213057040 10640773120 1152261952 12.331 100 87 174 1 1309533424 136817280 20454192
03:20:50 57263 8192000000 10192570128 10625265664 1155062704 12.357 100 53 100 2 1270041040 103383136 11595200
03:23:00 86750 8192000000 10268734784 10707628032 1126590128 12.089 97 29 56 0 1324472576 190953168 6929280
03:25:30 59845 8192000000 10282021488 10764107776 1126381440 12.087 98 23 46 0 1372533216 240440112 5711664
03:28:18 11661 8192000000 10238531904 10743521280 1138810224 12.204 100 31 59 0 1345318544 199776992 6731328
03:29:31 46767 8192000000 10142610688 10674376704 1129862464 12.120 100 15 30 0 1297935136 164226944 3845728
03:31:18 22152 8192000000 10112877328 10558644224 1125231440 12.076 99 21 42 0 1206285152 76149696 4904016
03:32:45 61512 8192000000 10077822208 10547396608 1131328608 12.134 100 15 30 0 1171596672 37396400 2871664
03:34:54 104179 8192000000 10055742768 10548727808 1110486816 11.937 98 18 34 1 1191503808 77061792 3955200
03:37:41 125154 8192000000 10057227344 10524499968 1137645136 12.193 100 30 60 0 1176800528 32960608 6194784
03:40:52 86048 8192000000 10054197616 10521559040 1105716496 11.892 96 18 36 0 1147060800 38021616 3322688
03:43:21 43596 8192000000 10048292256 10499780608 1109650976 11.929 98 17 32 0 1145630720 32281536 3698208
03:45:07 10463 8192000000 10060834848 10504179712 1122983952 12.055 100 36 69 0 1167857248 38023760 6849536
03:46:18 25655 8192000000 10118751168 10558443520 1143023008 12.244 100 55 106 0 1232171536 77204864 11943664
03:47:45 45011 8192000000 10125861280 10610470912 1149659008 12.306 100 17 34 0 1221712160 68738880 3314272
03:49:34 62314 8192000000 10152928704 10618548224 1157693680 12.382 100 19 38 0 1247608512 86083584 3831248
03:52:52 18761 8192000000 10082421696 10545795072 1149767504 12.307 100 35 71 0 1184818368 28722720 6328144
03:54:13 47738 8192000000 10099089552 10571612160 1135508256 12.173 99 22 42 1 1197577872 57420480 4649136
03:56:15 150224 8192000000 10112840752 10594721792 1098253056 11.821 96 16 30 1 1200821328 98889824 3678448
04:00:01 45149 8192000000 10067469488 10506809344 1108552048 11.919 98 22 44 0 1156098592 43149584 4396960
04:01:53 15926 8192000000 10096383264 10530340864 1138706304 12.203 100 64 124 2 1194423872 43727472 11990096
04:03:10 76745 8192000000 10109322896 10592530432 1107302912 11.907 97 24 48 0 1220379888 107779792 5297184
04:05:27 45843 8192000000 10103136928 10550853632 1119205488 12.019 99 29 56 0 1202913472 78153248 5554736
04:07:15 40322 8192000000 10079481888 10556022784 1096550064 11.805 98 18 36 0 1170584576 70222432 3812080
04:08:55 153738 8192000000 10107223328 10595450880 1120397680 12.031 99 19 38 0 1194124208 69742784 3983744
04:12:29 29195 8192000000 10075016912 10501398528 1133607616 12.155 100 53 98 0 1173862480 30842768 9412096
04:13:59 45714 8192000000 10080715296 10543636480 1135301760 12.171 100 29 58 1 1201048240 59241600 6504880
04:15:49 86996 8192000000 10094723712 10544091136 1109581312 11.928 97 17 33 0 1176321456 63089984 3650160
04:18:19 60063 8192000000 10061523856 10570665984 1106785840 11.902 98 15 30 0 1201698240 91423792 3488608
04:20:47 39607 8192000000 10071425696 10522099712 1101593824 11.853 99 17 34 1 1173213152 67422576 4196752
04:22:33 51224 8192000000 10042845552 10505068544 1113024688 11.961 99 27 52 0 1161553888 43451488 5077712
04:24:25 93999 8192000000 10061378640 10538258432 1120739200 12.034 99 9 18 0 1161644176 39426240 1478736
04:27:24 104305 8192000000 10081684320 10550767616 1133778672 12.157 100 13 26 0 1174914912 38602944 2533296
04:30:20 39508 8192000000 10055651168 10512977920 1111153392 11.943 98 30 60 0 1151555248 33983856 6418000
04:32:15 22367 8192000000 10134459408 10562117632 1133524896 12.155 100 49 98 0 1226388224 82924320 9939008
04:33:39 55481 8192000000 10069995024 10537500672 1101303536 11.850 97 38 76 0 1180151728 71151184 7697008
04:35:37 16733 8192000000 10119540400 10548195328 1129490448 12.117 100 48 94 0 1207979184 69331200 9157536
04:36:56 32948 8192000000 9275256304 9582419968 392075328 4.567 35 21 42 0 433454160 37631184 3747648

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 29-06-2022 04:38:24 to 29-06-2022 04:38:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 126 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 29-06-2022 04:38:41 to 29-06-2022 04:38:43.

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          212193
MON$OLDEST_ACTIVE               212615
MON$OLDEST_SNAPSHOT             212615
MON$NEXT_TRANSACTION            212615
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                       2146240
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             766
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2792 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2792 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2792 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2792 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 ---
:37: 
:38: mscs-test-161.budzdorov.ru    Wed Jun 29 04:37:30 2022
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
--- end of diff output ---

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

29.06.22 04:38:44. 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-06-29 04:38:46 No severe PSQL-related problems occured
29.06.22 04:38:47. End of report.