Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.03.2023 04: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 15039600 kB
MemAvailable 30410760 kB
Buffers 20 kB
Cached 16624812 kB
SwapCached 3000 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.3
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 2023-03-04 04:37:27
DTS_END                         2023-03-04 04:37:27.3220
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/41548
TRN_ID                          223631
ATT_ID                          666
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: 9425.24 22683 1131029
customer order: creation 173.63 442 20836
customer order: refuse 15.18 369 1822
order to supplier: creation 46.78 1243 5614
order to supplier: removal 3.45 162884 414
invoice (draft): creation 46.62 3024 5594
invoice (draft): removal 53.36 1018 6403
invoice accept: apply 42.18 24610 5061
invoice accept: cancel 47.81 1151 5737
customer reserve: creation 3958.06 234 474967
customer reserve: removal 4745.08 93 569409
realization accept: apply 83.48 258 10018
realization accept: cancel 79.33 159 9520
payment from customer: creation 59.86 164 7183
payment from customer: removal 5.42 127 650
payment to supplier: creation 48.88 169 5866
payment to supplier: removal 3.33 128 399
service: total inventory turnovers 4.08 3724 490
service: total monetary turnovers 7.81 314 937
service: aggregate perf. data 0.87 18055 104
service: refresh index statistics 0.04 235501 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 04-03-2023 04:39:24 to 04-03-2023 04:39:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6203
TEST_TIME 2 6079
TEST_TIME 3 5973
TEST_TIME 4 5805
TEST_TIME 5 5487
TEST_TIME 6 5393
TEST_TIME 7 5434
TEST_TIME 8 5665
TEST_TIME 9 5813
TEST_TIME 10 6039
TEST_TIME 11 6156
TEST_TIME 12 6278
TEST_TIME 13 6468
TEST_TIME 14 6613
TEST_TIME 15 6826
TEST_TIME 16 6931
TEST_TIME 17 7015
TEST_TIME 18 7459
TEST_TIME 19 7701
TEST_TIME 20 8124
TEST_TIME 21 8348
TEST_TIME 22 8570
TEST_TIME 23 8640
TEST_TIME 24 8457
TEST_TIME 25 8970
TEST_TIME 26 9424
TEST_TIME 27 9985
TEST_TIME 28 10232
TEST_TIME 29 10470
TEST_TIME 30 10511
TEST_TIME 31 10722
TEST_TIME 32 10775
TEST_TIME 33 11017
TEST_TIME 34 10793
TEST_TIME 35 10603
TEST_TIME 36 10435
TEST_TIME 37 10275
TEST_TIME 38 10446
TEST_TIME 39 10345
TEST_TIME 40 10152
TEST_TIME 41 10129
TEST_TIME 42 10107
TEST_TIME 43 10019
TEST_TIME 44 9961
TEST_TIME 45 9980
TEST_TIME 46 9908
TEST_TIME 47 9833
TEST_TIME 48 9736
TEST_TIME 49 9597
TEST_TIME 50 9776
TEST_TIME 51 9722
TEST_TIME 52 9759
TEST_TIME 53 9734
TEST_TIME 54 9714
TEST_TIME 55 9728
TEST_TIME 56 9721
TEST_TIME 57 9776
TEST_TIME 58 9811
TEST_TIME 59 9861
TEST_TIME 60 9866
TEST_TIME 61 9810
TEST_TIME 62 9732
TEST_TIME 63 9646
TEST_TIME 64 9704
TEST_TIME 65 9672
TEST_TIME 66 9593
TEST_TIME 67 9530
TEST_TIME 68 9533
TEST_TIME 69 9483
TEST_TIME 70 9437
TEST_TIME 71 9503
TEST_TIME 72 9469
TEST_TIME 73 9418
TEST_TIME 74 9413
TEST_TIME 75 9425
TEST_TIME 76 9419
TEST_TIME 77 9549
TEST_TIME 78 9626
TEST_TIME 79 9673
TEST_TIME 80 9712
TEST_TIME 81 9695
TEST_TIME 82 9620
TEST_TIME 83 9564
TEST_TIME 84 9542
TEST_TIME 85 9534
TEST_TIME 86 9481
TEST_TIME 87 9457
TEST_TIME 88 9432
TEST_TIME 89 9375
TEST_TIME 90 9305
TEST_TIME 91 9352
TEST_TIME 92 9326
TEST_TIME 93 9291
TEST_TIME 94 9280
TEST_TIME 95 9241
TEST_TIME 96 9244
TEST_TIME 97 9296
TEST_TIME 98 9306
TEST_TIME 99 9279
TEST_TIME 100 9318
TEST_TIME 101 9357
TEST_TIME 102 9341
TEST_TIME 103 9406
TEST_TIME 104 9544
TEST_TIME 105 9505
TEST_TIME 106 9510
TEST_TIME 107 9459
TEST_TIME 108 9415
TEST_TIME 109 9435
TEST_TIME 110 9402
TEST_TIME 111 9397
TEST_TIME 112 9466
TEST_TIME 113 9463
TEST_TIME 114 9464
TEST_TIME 115 9466
TEST_TIME 116 9484
TEST_TIME 117 9468
TEST_TIME 118 9523
TEST_TIME 119 9508
TEST_TIME 120 9447

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-03-2023 04:39:28 to 04-03-2023 04:39:32.

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 1569391 1569391 0 0.00 0 35272 12 0 0
doc_list_biud 1569387 1569387 0 0.00 0 85974 40 0 0
sp_add_invoice_to_stock 5226 5061 165 3.15 84 170104 24611 0 165
sp_cancel_adding_invoice 5737 5737 0 0.00 6 88648 1152 0 0
sp_cancel_client_order 1822 1822 0 0.00 6 10767 369 0 0
sp_cancel_customer_reserve 569409 569409 0 0.00 1 26728 94 0 0
sp_cancel_pay_from_customer 650 650 0 0.00 3 6677 127 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 4 5399 129 0 0
sp_cancel_supplier_invoice 6423 6403 20 0.31 3 86001 1019 0 20
sp_cancel_supplier_order 414 414 0 0.00 15 1259969 162884 0 0
sp_cancel_write_off 9520 9520 0 0.00 2 12587 159 0 0
sp_client_order 20836 20836 0 0.00 6 36151 443 0 0
sp_customer_reserve 474967 474967 0 0.00 0 64518 235 0 0
sp_fill_shopping_cart 32043 32043 0 0.00 1 29052 75 0 0
sp_fill_shopping_cart_clo_res 474948 474948 0 0.00 0 15615 17 0 0
sp_get_clo_for_invoice 5037 5037 0 0.00 0 21934 256 0 0
sp_kill_cost_storno 16306 16306 0 0.00 0 35252 69 0 0
sp_kill_qstorno_ret_qs2qd 1155155 1155155 0 0.00 0 85970 42 0 0
sp_kill_qty_storno 608360 608360 0 0.00 0 85972 95 0 0
sp_lock_selected_doc 20866 20866 0 0.00 0 6130 7 0 0
sp_make_cost_storno 28104 28104 0 0.00 0 27971 79 0 0
sp_multiply_rows_for_pdistr 28104 28104 0 0.00 0 7723 36 0 0
sp_multiply_rows_for_qdistr 20836 20836 0 0.00 0 13837 177 0 0
sp_pay_from_customer 7183 7183 0 0.00 5 14752 164 0 0
sp_pay_to_supplier 5866 5866 0 0.00 7 27986 169 0 0
sp_payment_common 13049 13049 0 0.00 2 8856 43 0 0
sp_qd_handle_on_cancel_clo 1822 1822 0 0.00 1 10723 306 0 0
sp_qd_handle_on_invoice_upd_sts 10774 10774 0 0.00 0 44750 474 0 0
sp_qd_handle_on_reserve_upd_sts 10018 10018 0 0.00 0 11254 57 0 0
sp_reserve_write_off 10018 10018 0 0.00 8 19308 258 0 0
sp_supplier_invoice 5594 5594 0 0.00 10 142266 3025 0 0
sp_supplier_order 5614 5614 0 0.00 8 52918 1243 0 0
srv_aggregate_perf_data 104 104 0 0.00 3160 169827 18056 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 760 130529 31270 0 0
srv_find_qd_qs_mism 1081829 1081829 0 0.00 0 27052 9 0 0
srv_make_invnt_saldo 490 490 0 0.00 79 90850 3725 0 0
srv_make_money_saldo 937 937 0 0.00 1 11767 314 0 0
srv_recalc_idx_stat 5 5 0 0.00 94914 366206 235502 0 0
t$perf-abend:sp_add_invoice_to_stock 165 165 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 20 20 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5064 5064 0 0.00 1 11438 751 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 1 5274 376 0 0
t$perf-norm:sp_cancel_client_order 1822 1822 0 0.00 0 3333 27 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 1949 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 650 650 0 0.00 0 2370 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 479 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 430 430 0 0.00 0 669 33 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 96585 6072 0 0
t$perf-norm:sp_cancel_write_off 968 968 0 0.00 0 1689 16 0 0
t$perf-norm:sp_client_order 20836 20836 0 0.00 0 13886 31 0 0
t$perf-norm:sp_customer_reserve 1000 1000 0 0.00 0 2644 18 0 0
t$perf-norm:sp_pay_from_customer 7183 7183 0 0.00 0 17898 20 0 0
t$perf-norm:sp_pay_to_supplier 5866 5866 0 0.00 0 7693 20 0 0
t$perf-norm:sp_reserve_write_off 10018 10018 0 0.00 0 6417 22 0 0
t$perf-norm:sp_supplier_invoice 5594 5594 0 0.00 0 7871 27 0 0
t$perf-norm:sp_supplier_order 5614 5614 0 0.00 0 4543 27 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 234 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 327 8 0 0
t$perf-norm:srv_make_invnt_saldo 490 490 0 0.00 0 2681 15 0 0
t$perf-norm:srv_make_money_saldo 937 937 0 0.00 0 762 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 9 177 70 0 0
v_add_invoice_to_stock 10402 10072 330 3.17 0 6824 24 0 330
v_all_customers 38424 38424 0 0.00 0 125 3 0 0
v_all_suppliers 31350 31350 0 0.00 0 282 4 0 0
v_all_wares 41672 41672 0 0.00 0 129 4 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 365 13 0 0
v_cancel_client_order 3644 3644 0 0.00 0 1315 9 0 0
v_cancel_customer_prepayment 1300 1300 0 0.00 0 6662 20 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 1 1129 11 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 2173 14 0 0
v_cancel_supplier_invoice 900 860 40 4.44 0 1279 26 0 40
v_cancel_supplier_order 828 828 0 0.00 0 859 10 0 0
v_cancel_write_off 1936 1936 0 0.00 0 5612 30 0 0
v_max_id_clo_ord 5614 5614 0 0.00 0 3853 5 0 0
v_max_id_clo_res 1000 1000 0 0.00 0 8509 27 0 0
v_max_id_ord_sup 5592 5592 0 0.00 0 3031 5 0 0
v_max_non_paid_invoice 1397 1397 0 0.00 0 113 7 0 0
v_max_non_paid_realizn 3647 3647 0 0.00 0 3115 7 0 0
v_min_id_clo_ord 5614 5614 0 0.00 0 3081 8 0 0
v_min_id_clo_res 1000 1000 0 0.00 0 1403 8 0 0
v_min_id_ord_sup 5592 5592 0 0.00 0 9748 17 0 0
v_min_non_paid_invoice 5866 5866 0 0.00 0 5694 14 0 0
v_min_non_paid_realizn 7183 7183 0 0.00 0 8851 20 0 0
v_our_firm 10320 10320 0 0.00 0 107 4 0 0
v_reserve_write_off 20036 20036 0 0.00 0 3022 10 0 0
x_lock_depdocs_on_canc_invoice 11710 11710 0 0.00 0 22229 113 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 21244 400 0 0
x_make_qstorno_1000_1200 5614 5614 0 0.00 2 52876 1089 0 0
x_make_qstorno_1200_2000 5594 5594 0 0.00 5 137080 2819 0 0
x_make_qstorno_2100_3300 474967 474967 0 0.00 0 62515 207 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-03-2023 04:39:33 to 04-03-2023 04:39:37.

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:31 929 8192000000 8925575824 8981630976 11819488 0.144 3 2 4 0 11982864 90384 72992
02:08:33 25647 8192000000 9726272944 10025549824 800223456 8.899 100 87 172 1 829008544 7556480 21228608
02:10:07 11395 8192000000 9776189600 10085183488 846516048 9.365 100 90 180 0 879011248 11507680 20987520
02:11:27 9205 8192000000 9794294752 10112688128 868196864 9.582 100 86 168 0 905233632 17827776 19208992
02:12:41 7125 8192000000 9849090048 10184003584 918067616 10.077 100 72 141 0 957180928 24407152 14706160
02:13:52 3317 8192000000 9916779168 10262323200 961101584 10.500 100 83 161 1 1035097728 57145344 16850800
02:14:58 1400 8192000000 9983464384 10336796672 1003607168 10.913 100 96 187 1 1101671744 79243968 18820608
02:16:03 1264 8192000000 9991254960 10349948928 1020628096 11.078 100 90 173 0 1100053456 62814192 16611168
02:17:07 4410 8192000000 10018031008 10389901312 1042134288 11.285 100 81 162 0 1139119488 81087168 15898032
02:18:13 912 8192000000 10031537424 10405457920 1063096064 11.486 100 93 179 2 1149401376 68475472 17829840
02:19:15 1422 8192000000 10046451232 10424406016 1074956192 11.599 100 88 171 2 1169393728 77496080 16941456
02:20:17 1232 8192000000 10057591712 10444140544 1074817664 11.598 100 97 192 2 1183004032 89034096 19152272
02:21:19 1133 8192000000 10059128928 10444693504 1084725680 11.692 100 89 167 2 1173057520 71537872 16793968
02:22:21 3622 8192000000 10036358304 10425946112 1085543840 11.700 100 81 151 3 1151069424 51054000 14471584
02:23:26 951 8192000000 10063401840 10450870272 1091480384 11.757 100 93 182 1 1186652768 76483888 18688496
02:24:28 1132 8192000000 10046971792 10435461120 1090983376 11.752 100 88 155 1 1162624016 55462512 16178128
02:25:29 1040 8192000000 10068225408 10461417472 1094756656 11.788 100 96 180 0 1188738992 75440352 18541984
02:26:32 1147 8192000000 10059619456 10453819392 1100309280 11.841 100 82 159 2 1177944160 61672032 15962848
02:27:34 5033 8192000000 10044198608 10439475200 1094420192 11.785 100 70 131 2 1163511984 55938432 13153360
02:28:40 2598 8192000000 10036996016 10434646016 1095644336 11.796 100 62 106 2 1157919568 51604512 10670720
02:29:44 811 8192000000 10030828832 10419056640 1086137200 11.706 100 87 167 3 1153155568 49626080 17392288
02:30:46 830 8192000000 10037756928 10424766464 1079319248 11.641 100 87 171 1 1162500320 65912560 17268512
02:31:48 1076 8192000000 10014159984 10398081024 1065785728 11.512 100 78 147 1 1139664032 56562272 17316032
02:32:50 1048 8192000000 9974155840 10348511232 1034308896 11.210 98 94 168 3 1096724640 44582160 17833584
02:33:52 1099 8192000000 10003006496 10378055680 1028017536 11.149 100 90 174 0 1128268000 83327312 16923152
02:34:53 965 8192000000 10000074976 10371297280 1030332544 11.172 100 92 176 2 1122661728 73983904 18345280
02:35:55 1156 8192000000 9992578576 10364571648 1023145616 11.102 100 95 179 1 1113640432 72324832 18169984
02:36:58 980 8192000000 9981679024 10356879360 1044683984 11.310 100 92 175 2 1099826544 37230304 17912256
02:38:04 2463 8192000000 9985311440 10362863616 1064318672 11.498 100 84 148 1 1103354128 24855792 14179664
02:39:11 1531 8192000000 10032658752 10416955392 1080870640 11.656 100 80 150 1 1153969920 58199792 14899488
02:40:18 813 8192000000 10067529136 10449690624 1095790480 11.798 100 87 155 1 1183930048 73443456 14696112
02:41:21 1191 8192000000 10094621328 10481725440 1110870672 11.941 100 77 143 1 1208935088 82772128 15292288
02:42:28 2320 8192000000 10124461904 10513719296 1120715952 12.034 100 88 171 2 1238031296 99314064 18001280
02:43:35 5448 8192000000 10165529536 10557812736 1126642352 12.090 100 87 166 1 1274902656 131370576 16889728
02:44:46 5461 8192000000 10181681968 10590797824 1140451536 12.220 100 76 148 1 1298048304 139912976 17683792
02:45:55 8357 8192000000 10217186240 10621349888 1148467664 12.295 100 69 132 0 1329138592 166407520 14263408
02:47:10 1106 8192000000 10261128864 10665938944 1155200736 12.358 100 83 157 0 1363809728 191249712 17359280
02:48:14 3731 8192000000 10281179344 10682830848 1166092272 12.460 100 76 141 1 1391244608 209246784 15905552
02:49:22 903 8192000000 10328981168 10743005184 1178526144 12.576 100 94 178 2 1438895920 241160288 19209488
02:50:26 2370 8192000000 10360457056 10763030528 1181944832 12.608 100 90 176 2 1468374768 265759840 20670096
02:51:31 1108 8192000000 10387604960 10814316544 1190209312 12.685 100 96 188 0 1498007456 284746128 23052016
02:52:37 1900 8192000000 10411171696 10810769408 1179907552 12.589 99 93 179 2 1516743600 316065664 20770384
02:53:41 2018 8192000000 10429940944 10853425152 1184091696 12.628 100 96 190 1 1537545600 331379328 22074576
02:54:45 1081 8192000000 10503616864 10941980672 1181440192 12.604 100 99 190 0 1609667248 404816896 23410160
02:55:49 5037 8192000000 10450559296 10908778496 1168714112 12.485 100 65 126 0 1562711440 378364944 15632384
02:57:02 749 8192000000 10442089760 10897055744 1174115248 12.535 100 91 174 2 1548785408 352707600 21962560
02:58:05 1183 8192000000 10434238080 10885844992 1179284384 12.584 100 70 134 0 1541866208 346472432 16109392
02:59:10 11155 8192000000 10460477872 10917638144 1199938192 12.776 100 50 100 1 1564686576 351703712 13044672
03:00:24 23286 8192000000 10460984928 10902396928 1179567360 12.586 100 36 72 0 1553540944 364295728 9677856
03:01:56 8766 8192000000 10457175664 10950488064 1159909648 12.402 99 37 73 0 1577249216 406923856 10415712
03:03:35 43829 8192000000 10297047744 10794618880 1161091728 12.413 100 30 60 0 1436447088 267471264 7884096
03:05:35 7416 8192000000 10255847440 10708795392 1151311088 12.322 100 48 89 0 1348845536 186894368 10640080
03:06:43 19341 8192000000 10255294240 10734358528 1146660976 12.278 99 22 44 0 1351656368 199139200 5856192
03:08:08 55819 8192000000 10164990992 10689036288 1111937632 11.951 97 17 32 1 1293369824 177449056 3983136
03:10:06 26192 8192000000 10075758240 10523930624 1135860944 12.177 99 48 85 4 1178366064 34107840 8397280
03:11:37 98951 8192000000 10171807296 10621325312 1152530032 12.333 100 26 53 0 1231584688 73508912 5545744
03:14:18 38665 8192000000 10146922144 10623791104 1122462000 12.050 98 48 93 1 1227676608 94645424 10569184
03:16:04 40409 8192000000 10093733296 10528587776 1122000576 12.046 99 28 55 0 1176070512 48502640 5567296
03:17:45 44458 8192000000 10078900800 10549358592 1123100976 12.056 99 21 38 1 1166549712 39646160 3802576
03:19:30 15676 8192000000 10065999840 10512293888 1131054656 12.131 99 37 74 1 1166424080 26835440 8533984
03:21:02 8947 8192000000 10124887776 10574606336 1149057296 12.301 100 42 84 0 1233178000 74719536 9401168
03:22:17 35501 8192000000 10087744176 10573193216 1113354880 11.964 98 19 38 1 1174131632 56456272 4320480
03:23:55 14950 8192000000 10082420896 10542456832 1119269328 12.020 99 16 32 1 1176991456 54469200 3252928
03:25:11 34760 8192000000 10120026000 10577977344 1125635936 12.080 99 20 41 0 1207774416 77704528 4433952
03:26:48 53825 8192000000 10074905472 10609274880 1124746608 12.072 99 30 60 2 1245571616 114600944 6224064
03:28:45 36835 8192000000 10108205760 10562252800 1130524112 12.126 99 25 46 1 1197834928 62061040 5249776
03:30:24 43711 8192000000 10078476896 10519445504 1110276432 11.935 98 20 40 1 1168543392 53870944 4396016
03:32:12 30274 8192000000 10136684912 10592653312 1139844064 12.214 100 41 82 1 1226646000 78562608 8239328
03:33:47 31103 8192000000 10127921088 10585165824 1098194208 11.821 97 31 62 0 1202125776 97716464 6215104
03:35:21 46761 8192000000 10098777584 10566885376 1135366896 12.172 100 19 38 0 1199349648 59964112 4018640
03:37:09 32895 8192000000 10087972208 10503991296 1107152992 11.905 97 32 58 3 1151216288 36484096 7579200
03:38:42 73645 8192000000 10157862512 10607255552 1135991904 12.178 99 25 50 1 1246003120 104249616 5761600
03:41:28 1676 8192000000 10088562144 10565058560 1140118880 12.217 100 78 130 2 1193546800 38157184 15270736
03:42:30 40055 8192000000 10089761504 10562703360 1125112288 12.075 99 17 34 0 1194453696 66058416 3282992
03:45:48 50827 8192000000 10106916160 10569838592 1137158320 12.189 100 17 32 0 1194540064 54082608 3299136
03:47:39 20617 8192000000 10137531488 10573500416 1133912784 12.158 99 40 78 0 1220181184 78565600 7702800
03:49:04 39233 8192000000 10120256544 10561126400 1143209936 12.246 99 23 46 0 1226739104 78430064 5099104
03:50:44 29353 8192000000 10217143744 10658914304 1154998112 12.356 100 29 58 0 1303275792 141662352 6615328
03:52:21 53606 8192000000 10244148448 10713022464 1145698880 12.269 99 30 60 0 1343352752 190667536 6986336
03:54:15 65846 8192000000 10094203168 10602627072 1104877088 11.884 97 18 36 0 1242461296 132861392 4722816
03:56:21 49529 8192000000 10100824784 10581098496 1123321328 12.058 99 32 64 0 1250941440 121297312 6322800
03:58:16 33768 8192000000 10077163376 10509438976 1093479600 11.776 97 25 51 0 1135963248 37587440 4896208
03:59:50 130529 8192000000 10055921248 10516283392 1094239392 11.783 97 30 60 0 1191259536 90631408 6388736
04:03:07 24514 8192000000 10037281120 10467135488 1091296624 11.755 97 38 74 3 1126193504 26373568 8523312
04:04:39 68507 8192000000 10081549520 10544959488 1125323648 12.077 100 19 38 0 1180740224 51209776 4206800
04:06:52 58736 8192000000 10065149088 10522075136 1123072368 12.056 100 13 26 0 1181389024 55432192 2884464
04:08:51 45133 8192000000 10074532160 10536251392 1100975216 11.847 98 11 22 0 1143431264 40152864 2303184
04:10:41 18153 8192000000 10120792192 10558529536 1142022688 12.235 100 29 54 0 1215505712 67724416 5758608
04:12:07 18844 8192000000 10171850048 10605125632 1144476752 12.258 100 34 64 0 1261811488 110153152 7181584
04:13:34 24946 8192000000 10206544672 10650607616 1139538576 12.211 100 34 68 0 1296361888 149129520 7693792
04:15:06 28804 8192000000 10172480688 10608664576 1123920128 12.064 99 32 64 0 1266505200 135622032 6963040
04:16:35 4194 8192000000 10202580704 10630017024 1137107792 12.188 100 48 90 0 1301814064 154121648 10584624
04:17:40 12389 8192000000 10202446288 10629173248 1139116432 12.207 100 53 105 0 1297051744 146921360 11013952
04:18:53 30502 8192000000 10200094000 10629378048 1141388432 12.229 100 46 90 2 1293259312 141937024 9933856
04:20:24 85996 8192000000 10129629728 10620469248 1140162896 12.217 100 23 46 0 1283064848 137653248 5248704
04:22:54 92190 8192000000 10057665776 10516885504 1113577504 11.966 98 18 32 0 1171593952 55337600 2678848
04:25:27 37198 8192000000 10147213664 10577129472 1139454768 12.210 100 47 93 0 1234457600 85914320 9088512
04:27:14 46418 8192000000 10144021392 10594156544 1123181360 12.057 98 39 78 0 1191146368 59437328 8527680
04:29:01 87866 8192000000 10085501776 10535899136 1095195392 11.792 96 13 25 0 1136684592 39090320 2398880
04:31:30 90308 8192000000 10079869872 10540630016 1063722736 11.492 93 12 25 0 1110917232 44748448 2446048
04:34:02 45383 8192000000 10131987504 10580152320 1120886160 12.035 99 26 52 0 1195293152 69193424 5213568
04:35:50 52689 8192000000 10096986640 10540486656 1122645200 12.052 99 19 38 0 1168437296 42404800 3387296

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 04-03-2023 04:39:39 to 04-03-2023 04:39:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 165 v_add_invoice_to_stock 335544517
except 20 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-03-2023 04:39:56 to 04-03-2023 04:39:58.

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          223251
MON$OLDEST_ACTIVE               224426
MON$OLDEST_SNAPSHOT             224426
MON$NEXT_TRANSACTION            224426
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                       2058144
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2904 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2904 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2904 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2904 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 ---
:169: 
:170: mscs-test-161.budzdorov.ru    Sat Mar  4 04:37:38 2023
:171:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:172: 
:173: 
:174: mscs-test-161.budzdorov.ru    Sat Mar  4 04:37:45 2023
:175:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:176: 
:177: 
:178: mscs-test-161.budzdorov.ru    Sat Mar  4 04:37:50 2023
:179:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:180: 
:181: 
:182: mscs-test-161.budzdorov.ru    Sat Mar  4 04:37:56 2023
:183:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:184: 
:185: 
:186: mscs-test-161.budzdorov.ru    Sat Mar  4 04:38:01 2023
:187:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:188: 
--- end of diff output ---

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

04.03.23 04:40:00. 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
2023-03-04 04:40:02 No severe PSQL-related problems occured
04.03.23 04:40:04. End of report.