Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.04.2022 07:38
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 16690348 kB
MemAvailable 31591888 kB
Buffers 0 kB
Cached 15058120 kB
SwapCached 3208 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-04-27 07:37:14
DTS_END                         2022-04-27 07:37:14.7650
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/34254
TRN_ID                          202691
ATT_ID                          593
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: 8432.39 23238 1011887
customer order: creation 156.64 499 18797
customer order: refuse 13.08 470 1570
order to supplier: creation 40.49 1486 4859
order to supplier: removal 3.03 200562 363
invoice (draft): creation 40.63 3248 4876
invoice (draft): removal 48.49 1040 5819
invoice accept: apply 37.09 30704 4451
invoice accept: cancel 44.00 1443 5280
customer reserve: creation 3470.48 290 416458
customer reserve: removal 4304.83 111 516580
realization accept: apply 73.91 300 8869
realization accept: cancel 82.38 173 9886
payment from customer: creation 52.85 179 6342
payment from customer: removal 5.22 169 626
payment to supplier: creation 44.87 203 5384
payment to supplier: removal 3.25 128 390
service: total inventory turnovers 3.55 4414 426
service: total monetary turnovers 6.78 439 813
service: aggregate perf. data 0.78 15882 94
service: refresh index statistics 0.03 203020 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 27-04-2022 07:39:03 to 27-04-2022 07:39:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 12106
TEST_TIME 2 9782
TEST_TIME 3 8119
TEST_TIME 4 7172
TEST_TIME 5 6719
TEST_TIME 6 6263
TEST_TIME 7 6250
TEST_TIME 8 6161
TEST_TIME 9 6097
TEST_TIME 10 6092
TEST_TIME 11 6009
TEST_TIME 12 6058
TEST_TIME 13 5976
TEST_TIME 14 6036
TEST_TIME 15 6327
TEST_TIME 16 6560
TEST_TIME 17 6672
TEST_TIME 18 6717
TEST_TIME 19 6880
TEST_TIME 20 7177
TEST_TIME 21 7345
TEST_TIME 22 7640
TEST_TIME 23 7888
TEST_TIME 24 7873
TEST_TIME 25 7869
TEST_TIME 26 7906
TEST_TIME 27 7888
TEST_TIME 28 8123
TEST_TIME 29 9060
TEST_TIME 30 9025
TEST_TIME 31 9185
TEST_TIME 32 9269
TEST_TIME 33 9489
TEST_TIME 34 9912
TEST_TIME 35 10254
TEST_TIME 36 10488
TEST_TIME 37 10361
TEST_TIME 38 10262
TEST_TIME 39 10029
TEST_TIME 40 9961
TEST_TIME 41 9844
TEST_TIME 42 9801
TEST_TIME 43 9814
TEST_TIME 44 9700
TEST_TIME 45 9599
TEST_TIME 46 9529
TEST_TIME 47 9388
TEST_TIME 48 9287
TEST_TIME 49 9326
TEST_TIME 50 9432
TEST_TIME 51 9299
TEST_TIME 52 9246
TEST_TIME 53 9175
TEST_TIME 54 9092
TEST_TIME 55 9240
TEST_TIME 56 9197
TEST_TIME 57 9190
TEST_TIME 58 9073
TEST_TIME 59 9024
TEST_TIME 60 9094
TEST_TIME 61 9224
TEST_TIME 62 9147
TEST_TIME 63 9165
TEST_TIME 64 9127
TEST_TIME 65 9157
TEST_TIME 66 9166
TEST_TIME 67 9207
TEST_TIME 68 9125
TEST_TIME 69 9004
TEST_TIME 70 9007
TEST_TIME 71 8942
TEST_TIME 72 8928
TEST_TIME 73 8887
TEST_TIME 74 8828
TEST_TIME 75 8779
TEST_TIME 76 8751
TEST_TIME 77 8700
TEST_TIME 78 8639
TEST_TIME 79 8696
TEST_TIME 80 8752
TEST_TIME 81 8696
TEST_TIME 82 8691
TEST_TIME 83 8664
TEST_TIME 84 8683
TEST_TIME 85 8702
TEST_TIME 86 8790
TEST_TIME 87 8852
TEST_TIME 88 8860
TEST_TIME 89 8895
TEST_TIME 90 8902
TEST_TIME 91 8877
TEST_TIME 92 8865
TEST_TIME 93 8822
TEST_TIME 94 8822
TEST_TIME 95 8787
TEST_TIME 96 8737
TEST_TIME 97 8703
TEST_TIME 98 8690
TEST_TIME 99 8659
TEST_TIME 100 8655
TEST_TIME 101 8630
TEST_TIME 102 8614
TEST_TIME 103 8627
TEST_TIME 104 8645
TEST_TIME 105 8671
TEST_TIME 106 8696
TEST_TIME 107 8687
TEST_TIME 108 8663
TEST_TIME 109 8737
TEST_TIME 110 8711
TEST_TIME 111 8682
TEST_TIME 112 8700
TEST_TIME 113 8774
TEST_TIME 114 8711
TEST_TIME 115 8688
TEST_TIME 116 8652
TEST_TIME 117 8602
TEST_TIME 118 8567
TEST_TIME 119 8527
TEST_TIME 120 8461

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-04-2022 07:39:07 to 27-04-2022 07:39:10.

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 1396463 1396463 0 0.00 0 26615 15 0 0
doc_list_biud 1396456 1396456 0 0.00 0 34197 47 0 0
sp_add_invoice_to_stock 4553 4451 102 2.24 36 221622 30704 0 102
sp_cancel_adding_invoice 5280 5280 0 0.00 8 96195 1444 0 0
sp_cancel_client_order 1570 1570 0 0.00 6 9057 471 0 0
sp_cancel_customer_reserve 516580 516580 0 0.00 1 32886 111 0 0
sp_cancel_pay_from_customer 626 626 0 0.00 3 5346 169 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 4 5074 129 0 0
sp_cancel_supplier_invoice 5830 5819 11 0.18 2 16829 1041 0 11
sp_cancel_supplier_order 363 363 0 0.00 10 1454126 200562 0 0
sp_cancel_write_off 9886 9886 0 0.00 2 15346 174 0 0
sp_client_order 18797 18797 0 0.00 6 49851 499 0 0
sp_customer_reserve 416458 416458 0 0.00 1 146503 290 0 0
sp_fill_shopping_cart 28531 28531 0 0.00 1 14404 86 0 0
sp_fill_shopping_cart_clo_res 416427 416427 0 0.00 0 23022 21 0 0
sp_get_clo_for_invoice 4402 4402 0 0.00 0 36767 339 0 0
sp_kill_cost_storno 16182 16182 0 0.00 0 26600 82 0 0
sp_kill_qstorno_ret_qs2qd 1049228 1049228 0 0.00 0 32803 49 0 0
sp_kill_qty_storno 552769 552769 0 0.00 0 34196 111 0 0
sp_lock_selected_doc 18575 18575 0 0.00 0 8704 8 0 0
sp_make_cost_storno 24997 24997 0 0.00 0 11195 88 0 0
sp_multiply_rows_for_pdistr 24997 24997 0 0.00 0 8921 42 0 0
sp_multiply_rows_for_qdistr 18797 18797 0 0.00 0 49830 206 0 0
sp_pay_from_customer 6342 6342 0 0.00 5 9680 180 0 0
sp_pay_to_supplier 5384 5384 0 0.00 6 9509 204 0 0
sp_payment_common 11726 11726 0 0.00 2 7959 48 0 0
sp_qd_handle_on_cancel_clo 1570 1570 0 0.00 1 9048 400 0 0
sp_qd_handle_on_invoice_upd_sts 9682 9682 0 0.00 0 34194 568 0 0
sp_qd_handle_on_reserve_upd_sts 8869 8869 0 0.00 0 18356 70 0 0
sp_reserve_write_off 8869 8869 0 0.00 8 18565 300 0 0
sp_supplier_invoice 4876 4876 0 0.00 11 122153 3249 0 0
sp_supplier_order 4859 4859 0 0.00 7 129118 1486 0 0
srv_aggregate_perf_data 94 94 0 0.00 2395 100263 15882 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 671 165266 38995 0 0
srv_find_qd_qs_mism 968252 968252 0 0.00 0 14627 12 0 0
srv_make_invnt_saldo 426 426 0 0.00 48 104944 4415 0 0
srv_make_money_saldo 813 813 0 0.00 2 24039 440 0 0
srv_recalc_idx_stat 4 4 0 0.00 61099 402743 203021 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4453 4453 0 0.00 0 10087 1051 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 1 10176 543 0 0
t$perf-norm:sp_cancel_client_order 1570 1570 0 0.00 0 11398 35 0 0
t$perf-norm:sp_cancel_customer_reserve 662 662 0 0.00 0 1850 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 626 626 0 0.00 0 485 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 5487 31 0 0
t$perf-norm:sp_cancel_supplier_invoice 365 365 0 0.00 0 1833 44 0 0
t$perf-norm:sp_cancel_supplier_order 363 363 0 0.00 0 99365 9033 0 0
t$perf-norm:sp_cancel_write_off 910 910 0 0.00 0 1748 21 0 0
t$perf-norm:sp_client_order 18797 18797 0 0.00 0 29889 43 0 0
t$perf-norm:sp_customer_reserve 831 831 0 0.00 0 716 17 0 0
t$perf-norm:sp_pay_from_customer 6342 6342 0 0.00 0 4932 22 0 0
t$perf-norm:sp_pay_to_supplier 5384 5384 0 0.00 0 4615 22 0 0
t$perf-norm:sp_reserve_write_off 8869 8869 0 0.00 0 10874 26 0 0
t$perf-norm:sp_supplier_invoice 4876 4876 0 0.00 0 7232 38 0 0
t$perf-norm:sp_supplier_order 4859 4859 0 0.00 0 11579 39 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 501 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 703 15 0 0
t$perf-norm:srv_make_invnt_saldo 426 426 0 0.00 0 13548 52 0 0
t$perf-norm:srv_make_money_saldo 813 813 0 0.00 0 490 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 161 63 0 0
v_add_invoice_to_stock 9008 8804 204 2.26 0 8331 28 0 204
v_all_customers 34258 34258 0 0.00 0 64 4 0 0
v_all_suppliers 27640 27640 0 0.00 0 145 4 0 0
v_all_wares 37592 37592 0 0.00 0 188 4 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 133 12 0 0
v_cancel_client_order 3140 3140 0 0.00 0 1367 9 0 0
v_cancel_customer_prepayment 1252 1252 0 0.00 0 4120 16 0 0
v_cancel_customer_reserve 1324 1324 0 0.00 1 1711 12 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 5056 16 0 0
v_cancel_supplier_invoice 752 730 22 2.92 0 1077 30 0 22
v_cancel_supplier_order 726 726 0 0.00 0 662 13 0 0
v_cancel_write_off 1820 1820 0 0.00 0 4107 27 0 0
v_max_id_clo_ord 4859 4859 0 0.00 0 279 5 0 0
v_max_id_clo_res 831 831 0 0.00 0 5489 19 0 0
v_max_id_ord_sup 4875 4875 0 0.00 0 4401 9 0 0
v_max_non_paid_invoice 1298 1298 0 0.00 0 60 8 0 0
v_max_non_paid_realizn 3308 3308 0 0.00 0 5181 11 0 0
v_min_id_clo_ord 4859 4859 0 0.00 0 2876 9 0 0
v_min_id_clo_res 831 831 0 0.00 0 3384 18 0 0
v_min_id_ord_sup 4875 4875 0 0.00 0 6661 11 0 0
v_min_non_paid_invoice 5384 5384 0 0.00 0 4616 15 0 0
v_min_non_paid_realizn 6342 6342 0 0.00 0 7940 22 0 0
v_our_firm 9402 9402 0 0.00 0 50 5 0 0
v_reserve_write_off 17738 17738 0 0.00 0 4194 11 0 0
x_lock_depdocs_on_canc_invoice 10734 10734 0 0.00 0 53624 166 0 0
x_lock_depdocs_on_canc_sup_ord 363 363 0 0.00 0 6671 440 0 0
x_make_qstorno_1000_1200 4859 4859 0 0.00 3 129019 1307 0 0
x_make_qstorno_1200_2000 4876 4876 0 0.00 5 114072 3005 0 0
x_make_qstorno_2100_3300 416458 416458 0 0.00 0 146501 255 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-04-2022 07:39:11 to 27-04-2022 07:39:15.

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
05:07:17 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:18 23201 8192000000 9727736016 10028224512 810559328 9.003 100 88 176 0 839042448 7648928 20834192
05:09:56 17871 8192000000 9775420640 10089955328 850137696 9.401 100 87 173 0 883752208 13172048 20442464
05:11:19 21672 8192000000 9838260448 10164494336 901187920 9.910 100 80 160 0 938561936 20046880 17327136
05:12:45 8782 8192000000 9877227120 10211885056 931396096 10.208 100 85 170 0 991622720 41878512 18348112
05:14:00 5726 8192000000 9933832624 10280062976 967171392 10.559 100 91 171 1 1057017664 71372544 18473728
05:15:08 4742 8192000000 9965396048 10317410304 991671312 10.798 100 88 172 0 1085713120 76957664 17084144
05:16:16 1740 8192000000 9990079040 10351357952 1018707120 11.060 100 96 182 1 1105830480 68449136 18674224
05:17:20 895 8192000000 10019982112 10387152896 1040741168 11.272 100 93 179 0 1141698688 82387216 18570304
05:18:22 1354 8192000000 10018735568 10390540288 1054349568 11.402 100 93 174 0 1137744816 66613712 16781536
05:19:25 948 8192000000 10059812368 10437484544 1068737984 11.540 100 98 190 0 1183483248 95532064 19213200
05:20:29 11363 8192000000 10047190064 10422579200 1071527696 11.567 100 88 170 0 1165485536 77837952 16119888
05:21:42 1103 8192000000 10056411856 10439753728 1081078112 11.658 100 95 182 1 1176156576 76941232 18137232
05:22:45 2105 8192000000 10074743152 10456498176 1084529568 11.691 100 87 171 2 1195912800 94214352 17168880
05:23:48 1049 8192000000 10055159472 10440728576 1089505024 11.738 100 80 156 1 1171714592 65405856 16803712
05:24:50 2642 8192000000 10057412688 10447773696 1094969712 11.790 100 75 148 1 1175522288 66023936 14528640
05:25:54 2393 8192000000 10053912736 10448007168 1100382256 11.841 100 79 140 4 1182919472 68571824 13965392
05:26:58 2089 8192000000 10048395808 10433474560 1101524208 11.852 100 82 151 1 1166015984 48816816 15674960
05:28:02 1199 8192000000 10064486400 10454941696 1098044016 11.819 100 99 186 3 1185888032 68620512 19223504
05:29:04 1197 8192000000 10062594480 10451156992 1098346816 11.822 100 97 188 0 1182250448 64803600 19100032
05:30:06 3826 8192000000 10039396960 10430455808 1079611024 11.644 99 65 116 2 1163676320 72945824 11119472
05:31:10 8621 8192000000 10022999616 10414706688 1069252288 11.545 99 61 109 2 1135265360 53496544 12516528
05:32:20 1035 8192000000 10040600832 10419712000 1061908352 11.475 100 85 160 3 1160971520 82206416 16856752
05:33:23 1347 8192000000 9996307072 10369241088 1047568672 11.337 100 91 164 1 1118585632 54744240 16272720
05:34:26 1809 8192000000 10004795328 10376638464 1042205968 11.286 99 94 178 0 1119954048 59313760 18434320
05:35:29 5794 8192000000 9987975728 10364928000 1047849024 11.340 100 73 134 2 1109242784 48665424 12728336
05:36:36 6579 8192000000 9960609776 10327257088 1022994368 11.101 99 83 152 3 1076592432 38616752 14981312
05:37:46 7719 8192000000 10018105504 10390020096 1056568000 11.424 100 79 149 0 1128652144 57137920 14946224
05:39:01 15646 8192000000 10021583472 10416590848 1056767616 11.426 99 67 129 0 1143655680 73603024 13285040
05:40:27 20011 8192000000 10020852352 10411913216 1072155664 11.573 99 59 115 0 1140656864 56792704 11708496
05:41:50 1835 8192000000 10063631952 10448891904 1086254816 11.707 100 74 137 3 1183240064 82886848 14098400
05:42:59 10914 8192000000 10112159488 10506252288 1107422448 11.908 100 58 116 0 1224754672 105147248 12184976
05:44:33 3948 8192000000 10149428480 10543665152 1128513808 12.107 100 70 133 2 1263118448 120182480 14422160
05:45:43 4909 8192000000 10184482240 10594197504 1133703664 12.156 100 76 150 0 1295891056 145887264 16300128
05:46:59 17440 8192000000 10208023456 10613370880 1138742176 12.204 100 63 126 0 1327241792 174466688 14032928
05:48:25 1055 8192000000 10235563008 10642026496 1145515072 12.267 100 92 174 3 1351284832 185387920 20381840
05:49:29 1606 8192000000 10298315856 10697994240 1159996816 12.403 100 96 188 2 1411794704 230430256 21367632
05:50:36 1040 8192000000 10340722752 10741559296 1164461728 12.445 100 94 186 0 1450104864 264372832 21270304
05:51:46 2595 8192000000 10346304880 10765508608 1172992672 12.525 100 82 161 0 1463962416 271547920 19421824
05:52:51 936 8192000000 10379945792 10797694976 1175619984 12.549 100 96 186 2 1489207360 291997136 21590240
05:53:55 1850 8192000000 10407912272 10813988864 1178102736 12.572 100 89 177 0 1518035376 319769632 20163008
05:54:59 1019 8192000000 10437802960 10845741056 1185519520 12.642 100 95 186 0 1545577296 337656416 22401360
05:56:04 2360 8192000000 10455209632 10890170368 1197150448 12.750 100 96 187 0 1560699632 341406192 22142992
05:57:11 979 8192000000 10453930880 10884247552 1194291136 12.723 100 93 176 0 1559683200 343451072 21940992
05:58:15 2633 8192000000 10469856656 10908553216 1195905840 12.738 100 96 189 1 1580549184 361460992 23182352
05:59:21 849 8192000000 10467378224 10906845184 1198105712 12.759 100 94 179 0 1571456816 352676384 20674720
06:00:26 2217 8192000000 10477863696 10924556288 1191363792 12.696 100 70 134 1 1587337232 379833072 16140368
06:01:33 2033 8192000000 10475958240 10900172800 1182278688 12.611 100 86 170 1 1577929248 374446768 21203792
06:02:40 1035 8192000000 10566140368 10984407040 1202880528 12.803 100 95 184 0 1670642624 445524976 22237120
06:03:46 1268 8192000000 10556910784 10984476672 1180973968 12.599 100 84 157 0 1664764944 464497792 19293184
06:04:50 4356 8192000000 10564936896 11034976256 1189830528 12.682 100 87 171 2 1663001312 452291664 20879120
06:05:58 666 8192000000 10530358752 11041681408 1193718256 12.718 100 89 177 0 1632234832 416225856 22290720
06:07:04 18209 8192000000 10426213216 10858217472 1189328432 12.677 100 47 93 0 1517866016 316826720 11710864
06:08:27 27297 8192000000 10433920720 10901282816 1190835168 12.691 100 31 62 3 1524612608 325802768 7974672
06:10:08 18426 8192000000 10391701120 10891522048 1175744688 12.550 100 28 56 0 1501886848 318948384 7193776
06:11:38 30552 8192000000 10276794960 10747379712 1166486752 12.464 100 18 36 0 1399895184 228793040 4615392
06:13:11 62496 8192000000 10093543712 10532290560 1091650208 11.758 95 19 35 0 1160448912 65074544 3724160
06:15:14 165264 8192000000 10118466048 10606100480 1124673984 12.071 98 27 53 2 1205023408 73962480 6386944
06:19:05 60463 8192000000 10108617712 10585845760 1118552608 12.013 98 17 34 1 1238430688 115556688 4321392
06:21:09 97344 8192000000 10103274496 10552999936 1096154784 11.801 96 24 48 1 1177920608 76625904 5139920
06:24:21 39446 8192000000 10139046128 10578345984 1117158752 12.000 98 27 54 1 1216421040 93871312 5390976
06:26:15 126796 8192000000 10052085200 10520215552 1110515984 11.937 99 17 34 0 1208701024 94394480 3790560
06:29:24 40382 8192000000 10106351296 10575998976 1122645072 12.052 99 19 38 1 1204279904 77341456 4293376
06:31:09 70390 8192000000 10081406352 10577965056 1099771152 11.835 98 13 24 2 1196027424 93399392 2856880
06:33:20 29889 8192000000 10050135856 10485067776 1115745488 11.987 100 42 78 3 1158252320 34508272 7998560
06:34:55 33996 8192000000 10066741376 10498412544 1091404336 11.756 97 53 105 2 1125817024 23587808 10824880
06:36:32 22580 8192000000 10136653328 10562490368 1120849520 12.035 100 65 128 0 1238172224 103763120 13559584
06:37:56 102270 8192000000 10045454160 10482839552 1048906400 11.350 94 17 33 2 1112682256 60617232 3158624
06:40:38 28691 8192000000 10070704672 10503315456 1116136768 11.990 100 47 94 0 1160060352 35093376 8830208
06:42:07 15371 8192000000 10124179568 10537517056 1115594688 11.985 99 72 139 2 1206381952 76082464 14704800
06:43:28 101654 8192000000 10077449888 10540457984 1093608304 11.777 97 22 44 0 1172724960 75027024 4089632
06:46:09 81515 8192000000 10089888144 10551443456 1121621088 12.042 99 37 74 1 1185056912 55952752 7483072
06:48:34 6338 8192000000 10084886640 10541436928 1116068304 11.990 98 28 55 1 1183794816 62031136 5695376
06:49:41 67560 8192000000 10064522064 10533629952 1134156640 12.161 100 16 33 0 1179287408 41948688 3182080
06:51:49 85390 8192000000 10097167984 10567245824 1140103280 12.217 100 17 34 0 1201218000 57156720 3958000
06:54:15 54801 8192000000 10122021456 10595405824 1122897200 12.054 98 13 26 0 1209530448 83742784 2890464
06:56:10 54663 8192000000 10126903072 10653192192 1146935152 12.281 100 21 42 0 1276448624 124517104 4996368
06:58:12 41042 8192000000 10159869760 10609573888 1149301440 12.303 99 21 42 0 1244850560 90718128 4830992
06:59:54 21059 8192000000 10196204720 10637672448 1140824832 12.223 98 27 52 2 1278257536 131063248 6369456
07:01:26 55737 8192000000 10212694752 10672111616 1150621072 12.315 100 26 50 3 1322177184 165981440 5574672
07:03:22 88915 8192000000 10142931680 10607456256 1103347168 11.869 96 17 34 0 1238995552 131814096 3834288
07:05:55 41527 8192000000 10098168800 10537967616 1098521424 11.824 97 39 76 1 1202525408 95997984 8006000
07:07:40 23651 8192000000 10133117296 10555338752 1135709584 12.175 100 56 112 1 1228019024 81328432 10981008
07:09:06 90976 8192000000 10108284320 10564571136 1143907632 12.252 100 41 82 1 1212291872 59902800 8481440
07:11:38 88228 8192000000 10070995056 10536321024 1127215360 12.095 98 25 48 2 1184052192 51546992 5289840
07:14:06 59894 8192000000 10101597776 10563043328 1122510176 12.051 98 15 30 0 1192308576 66367936 3430464
07:16:30 54043 8192000000 10091973888 10543505408 1138061104 12.197 100 30 58 0 1200795792 56728192 6006496
07:18:25 71324 8192000000 10106120896 10578415616 1142779632 12.242 100 15 29 1 1201011728 54728608 3503488
07:20:47 55928 8192000000 10107630704 10566131712 1095401344 11.794 96 26 52 1 1180440592 79501296 5537952
07:22:52 77146 8192000000 10065235040 10493620224 1091901408 11.761 97 29 49 0 1144780304 47262352 5616544
07:25:09 36816 8192000000 10118749408 10550935552 1116424480 11.993 99 35 70 2 1200188016 76216976 7546560
07:27:12 62486 8192000000 10088044400 10514251776 1090234160 11.745 96 37 72 0 1173154880 75292736 7627984
07:29:17 28805 8192000000 10170425376 10626752512 1107765536 11.911 99 44 88 1 1249344352 132558064 9020752
07:30:48 111119 8192000000 10025355616 10483879936 1092714544 11.768 99 24 46 3 1163391584 65752416 4924624
07:33:54 54447 8192000000 10041549344 10506821632 1111562096 11.947 100 8 16 0 1160680864 46916768 2202000
07:35:57 74502 8192000000 10064212256 10508152832 1102186592 11.858 98 9 18 0 1141118560 37116784 1815184

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 27-04-2022 07:39:17 to 27-04-2022 07:39:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-04-2022 07:39:34 to 27-04-2022 07:39:36.

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          201048
MON$OLDEST_ACTIVE               202891
MON$OLDEST_SNAPSHOT             202891
MON$NEXT_TRANSACTION            202891
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                       2033696
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             750
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:1432: 
:1433: mscs-test-161.budzdorov.ru    Wed Apr 27 07:37:25 2022
:1434:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1435: 
--- end of diff output ---

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

27.04.22 07:39:37. 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-04-27 07:39:40 No severe PSQL-related problems occured
27.04.22 07:39:41. End of report.