Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.01.2023 04: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 15165060 kB
MemAvailable 30400896 kB
Buffers 20 kB
Cached 16506024 kB
SwapCached 1640 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-01-24 04:37:27
DTS_END                         2023-01-24 04:37:27.0640
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/36218
TRN_ID                          220713
ATT_ID                          599
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: 8812.56 23797 1057507
customer order: creation 174.06 451 20887
customer order: refuse 14.43 376 1732
order to supplier: creation 45.01 1323 5401
order to supplier: removal 3.26 153380 391
invoice (draft): creation 45.13 3040 5415
invoice (draft): removal 48.08 917 5770
invoice accept: apply 41.14 24959 4937
invoice accept: cancel 43.18 1151 5181
customer reserve: creation 3853.59 236 462431
customer reserve: removal 4250.33 92 510039
realization accept: apply 84.02 257 10082
realization accept: cancel 77.78 163 9333
payment from customer: creation 60.28 163 7234
payment from customer: removal 6.18 140 742
payment to supplier: creation 49.70 176 5964
payment to supplier: removal 3.18 108 382
service: total inventory turnovers 4.48 3619 538
service: total monetary turnovers 7.68 360 922
service: aggregate perf. data 1.01 14850 121
service: refresh index statistics 0.04 270180 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-01-2023 04:38:48 to 24-01-2023 04:38:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10170
TEST_TIME 2 7940
TEST_TIME 3 6198
TEST_TIME 4 5432
TEST_TIME 5 6170
TEST_TIME 6 6144
TEST_TIME 7 6215
TEST_TIME 8 6100
TEST_TIME 9 6115
TEST_TIME 10 6267
TEST_TIME 11 6594
TEST_TIME 12 6791
TEST_TIME 13 6904
TEST_TIME 14 6935
TEST_TIME 15 7018
TEST_TIME 16 7111
TEST_TIME 17 7307
TEST_TIME 18 7354
TEST_TIME 19 7469
TEST_TIME 20 7765
TEST_TIME 21 7923
TEST_TIME 22 7985
TEST_TIME 23 8167
TEST_TIME 24 7981
TEST_TIME 25 8530
TEST_TIME 26 9112
TEST_TIME 27 9148
TEST_TIME 28 9281
TEST_TIME 29 9662
TEST_TIME 30 9827
TEST_TIME 31 10660
TEST_TIME 32 10879
TEST_TIME 33 10949
TEST_TIME 34 10690
TEST_TIME 35 10622
TEST_TIME 36 10387
TEST_TIME 37 10378
TEST_TIME 38 10148
TEST_TIME 39 10151
TEST_TIME 40 10151
TEST_TIME 41 10106
TEST_TIME 42 10059
TEST_TIME 43 9930
TEST_TIME 44 9932
TEST_TIME 45 9812
TEST_TIME 46 9675
TEST_TIME 47 9621
TEST_TIME 48 9682
TEST_TIME 49 9712
TEST_TIME 50 9646
TEST_TIME 51 9739
TEST_TIME 52 9666
TEST_TIME 53 9633
TEST_TIME 54 9492
TEST_TIME 55 9398
TEST_TIME 56 9429
TEST_TIME 57 9298
TEST_TIME 58 9235
TEST_TIME 59 9272
TEST_TIME 60 9224
TEST_TIME 61 9193
TEST_TIME 62 9194
TEST_TIME 63 9167
TEST_TIME 64 9138
TEST_TIME 65 9131
TEST_TIME 66 9148
TEST_TIME 67 9184
TEST_TIME 68 9219
TEST_TIME 69 9179
TEST_TIME 70 9199
TEST_TIME 71 9273
TEST_TIME 72 9246
TEST_TIME 73 9348
TEST_TIME 74 9309
TEST_TIME 75 9301
TEST_TIME 76 9379
TEST_TIME 77 9322
TEST_TIME 78 9344
TEST_TIME 79 9435
TEST_TIME 80 9357
TEST_TIME 81 9288
TEST_TIME 82 9250
TEST_TIME 83 9206
TEST_TIME 84 9206
TEST_TIME 85 9143
TEST_TIME 86 9102
TEST_TIME 87 9085
TEST_TIME 88 9055
TEST_TIME 89 8990
TEST_TIME 90 8998
TEST_TIME 91 9015
TEST_TIME 92 9006
TEST_TIME 93 9016
TEST_TIME 94 9022
TEST_TIME 95 9102
TEST_TIME 96 9077
TEST_TIME 97 9040
TEST_TIME 98 9029
TEST_TIME 99 9144
TEST_TIME 100 9144
TEST_TIME 101 9114
TEST_TIME 102 9151
TEST_TIME 103 9169
TEST_TIME 104 9110
TEST_TIME 105 9089
TEST_TIME 106 9076
TEST_TIME 107 9050
TEST_TIME 108 8993
TEST_TIME 109 8971
TEST_TIME 110 8928
TEST_TIME 111 8948
TEST_TIME 112 8919
TEST_TIME 113 8939
TEST_TIME 114 8913
TEST_TIME 115 8904
TEST_TIME 116 8899
TEST_TIME 117 8903
TEST_TIME 118 8889
TEST_TIME 119 8897
TEST_TIME 120 8839

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-01-2023 04:38:52 to 24-01-2023 04:38:55.

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 1483164 1483164 0 0.00 0 24275 13 0 0
doc_list_biud 1483151 1483151 0 0.00 0 28526 37 0 0
sp_add_invoice_to_stock 5102 4937 165 3.23 67 195477 24960 0 165
sp_cancel_adding_invoice 5181 5181 0 0.00 7 96229 1151 0 0
sp_cancel_client_order 1732 1732 0 0.00 5 7669 377 0 0
sp_cancel_customer_reserve 510039 510039 0 0.00 1 24519 93 0 0
sp_cancel_pay_from_customer 742 742 0 0.00 3 9054 141 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 4 1833 108 0 0
sp_cancel_supplier_invoice 5784 5770 14 0.24 3 22208 918 0 14
sp_cancel_supplier_order 391 391 0 0.00 15 1274618 153380 0 0
sp_cancel_write_off 9333 9333 0 0.00 2 28603 164 0 0
sp_client_order 20887 20887 0 0.00 5 27037 452 0 0
sp_customer_reserve 462431 462431 0 0.00 0 86576 237 0 0
sp_fill_shopping_cart 31692 31692 0 0.00 1 12591 78 0 0
sp_fill_shopping_cart_clo_res 462387 462387 0 0.00 0 14313 18 0 0
sp_get_clo_for_invoice 4884 4884 0 0.00 0 18617 260 0 0
sp_kill_cost_storno 15638 15638 0 0.00 0 7579 71 0 0
sp_kill_qstorno_ret_qs2qd 1035572 1035572 0 0.00 0 28511 41 0 0
sp_kill_qty_storno 547410 547410 0 0.00 0 28520 93 0 0
sp_lock_selected_doc 20792 20792 0 0.00 0 11991 7 0 0
sp_make_cost_storno 28164 28164 0 0.00 0 10529 70 0 0
sp_multiply_rows_for_pdistr 28162 28162 0 0.00 0 10604 37 0 0
sp_multiply_rows_for_qdistr 20887 20887 0 0.00 0 15958 174 0 0
sp_pay_from_customer 7234 7234 0 0.00 5 8693 164 0 0
sp_pay_to_supplier 5964 5964 0 0.00 7 11039 177 0 0
sp_payment_common 13198 13198 0 0.00 2 6143 38 0 0
sp_qd_handle_on_cancel_clo 1732 1732 0 0.00 0 7664 311 0 0
sp_qd_handle_on_invoice_upd_sts 10063 10063 0 0.00 0 25515 469 0 0
sp_qd_handle_on_reserve_upd_sts 10082 10082 0 0.00 0 12149 61 0 0
sp_reserve_write_off 10082 10082 0 0.00 9 12247 258 0 0
sp_supplier_invoice 5415 5415 0 0.00 9 91965 3040 0 0
sp_supplier_order 5401 5401 0 0.00 7 48667 1323 0 0
srv_aggregate_perf_data 121 121 0 0.00 2626 126928 14850 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 800 133537 37105 0 0
srv_find_qd_qs_mism 1008143 1008143 0 0.00 0 24273 10 0 0
srv_make_invnt_saldo 538 538 0 0.00 45 116802 3620 0 0
srv_make_money_saldo 922 922 0 0.00 1 35364 361 0 0
srv_recalc_idx_stat 5 5 0 0.00 32873 445526 270180 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 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4941 4941 0 0.00 1 13289 824 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 1 5940 424 0 0
t$perf-norm:sp_cancel_client_order 1732 1732 0 0.00 0 6441 28 0 0
t$perf-norm:sp_cancel_customer_reserve 729 729 0 0.00 0 954 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 742 742 0 0.00 0 1832 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 1520 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 3637 38 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 1 86048 5721 0 0
t$perf-norm:sp_cancel_write_off 1028 1028 0 0.00 0 1521 18 0 0
t$perf-norm:sp_client_order 20887 20887 0 0.00 0 6426 34 0 0
t$perf-norm:sp_customer_reserve 994 994 0 0.00 0 496 12 0 0
t$perf-norm:sp_pay_from_customer 7234 7234 0 0.00 0 4413 19 0 0
t$perf-norm:sp_pay_to_supplier 5964 5964 0 0.00 0 6255 18 0 0
t$perf-norm:sp_reserve_write_off 10083 10083 0 0.00 0 10833 25 0 0
t$perf-norm:sp_supplier_invoice 5415 5415 0 0.00 0 6230 27 0 0
t$perf-norm:sp_supplier_order 5402 5402 0 0.00 0 3955 31 0 0
t$perf-norm:srv_aggregate_perf_data 121 121 0 0.00 0 519 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 4255 70 0 0
t$perf-norm:srv_make_invnt_saldo 538 538 0 0.00 0 2498 18 0 0
t$perf-norm:srv_make_money_saldo 922 922 0 0.00 0 715 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 6 168 44 0 0
v_add_invoice_to_stock 10086 9756 330 3.27 0 7203 26 0 330
v_all_customers 37972 37972 0 0.00 0 129 4 0 0
v_all_suppliers 30490 30490 0 0.00 0 166 4 0 0
v_all_wares 41768 41768 0 0.00 0 83 4 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 1242 15 0 0
v_cancel_client_order 3464 3464 0 0.00 0 3843 12 0 0
v_cancel_customer_prepayment 1484 1484 0 0.00 0 2243 16 0 0
v_cancel_customer_reserve 1458 1458 0 0.00 0 2311 12 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 1747 15 0 0
v_cancel_supplier_invoice 846 818 28 3.30 0 1763 30 0 28
v_cancel_supplier_order 782 782 0 0.00 0 348 10 0 0
v_cancel_write_off 2056 2056 0 0.00 0 1163 21 0 0
v_max_id_clo_ord 5397 5397 0 0.00 0 1210 5 0 0
v_max_id_clo_res 994 994 0 0.00 0 2820 19 0 0
v_max_id_ord_sup 5409 5409 0 0.00 0 5995 8 0 0
v_max_non_paid_invoice 1525 1525 0 0.00 0 1156 8 0 0
v_max_non_paid_realizn 3799 3799 0 0.00 0 1464 7 0 0
v_min_id_clo_ord 5397 5397 0 0.00 0 5634 8 0 0
v_min_id_clo_res 994 994 0 0.00 0 138 6 0 0
v_min_id_ord_sup 5409 5409 0 0.00 0 6671 11 0 0
v_min_non_paid_invoice 5964 5964 0 0.00 0 6107 13 0 0
v_min_non_paid_realizn 7234 7234 0 0.00 0 2928 13 0 0
v_our_firm 10666 10666 0 0.00 0 111 4 0 0
v_reserve_write_off 20164 20164 0 0.00 0 7142 12 0 0
x_lock_depdocs_on_canc_invoice 10542 10542 0 0.00 0 13277 131 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 18053 344 0 0
x_make_qstorno_1000_1200 5401 5401 0 0.00 2 48660 1174 0 0
x_make_qstorno_1200_2000 5415 5415 0 0.00 4 80423 2813 0 0
x_make_qstorno_2100_3300 462429 462429 0 0.00 0 73516 207 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 24-01-2023 04:38:56 to 24-01-2023 04:39:01.

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 3 8192000000 8799508848 8811040768 5414928 0.066 2 1 2 0 5516528 65824 35776
02:08:31 22496 8192000000 9722885120 10023383040 803809664 8.935 100 90 180 0 833214480 7908944 21495872
02:09:59 17342 8192000000 9762697712 10071400448 834938176 9.249 100 91 182 0 869821264 12791920 22091168
02:11:22 15651 8192000000 9793437360 10111066112 860983952 9.510 100 86 171 0 902815776 21976576 19855248
02:12:43 5204 8192000000 9853737872 10184945664 907066576 9.968 100 81 156 0 966897808 43275840 16555392
02:14:07 5370 8192000000 9910733520 10251980800 948716384 10.379 100 89 168 0 1024707456 57344368 18646704
02:15:15 1511 8192000000 9950895184 10299219968 981509824 10.699 100 94 183 0 1065299312 63921552 19867936
02:16:21 1765 8192000000 9996279424 10355138560 1010419168 10.979 100 89 176 1 1112779824 84776896 17583760
02:17:26 1021 8192000000 10006133504 10370981888 1039788880 11.263 100 97 187 1 1128131024 69145136 19197008
02:18:28 1019 8192000000 10031915792 10407464960 1060524928 11.462 100 92 180 0 1153327296 74822592 17979776
02:19:31 1009 8192000000 10059037824 10438459392 1066958240 11.523 100 97 194 1 1180860992 95000832 18901920
02:20:34 1124 8192000000 10044802320 10422743040 1078060560 11.629 100 91 175 3 1169235248 72170832 19003856
02:21:36 1976 8192000000 10038442304 10430398464 1082773360 11.674 100 76 141 2 1162845920 65512928 14559632
02:22:40 1153 8192000000 10058335456 10441654272 1089044256 11.734 100 97 183 0 1178995280 70730752 19220272
02:23:42 4567 8192000000 10035613568 10421477376 1091206112 11.754 100 85 163 1 1167562032 61317152 15038768
02:24:48 1210 8192000000 10057417184 10446045184 1091598784 11.758 100 94 187 0 1177542912 68762480 17181648
02:25:51 1555 8192000000 10043317856 10432561152 1093642176 11.777 100 76 138 1 1165498128 57472176 14383776
02:26:54 1036 8192000000 10057125728 10449948672 1100275424 11.840 100 91 182 2 1187660192 67882208 19502560
02:27:57 2887 8192000000 10062864912 10460798976 1099622912 11.834 100 73 137 0 1192735808 79976464 13136432
02:29:01 1159 8192000000 10053151456 10443960320 1096222160 11.802 100 94 179 3 1175382272 59836864 19323248
02:30:03 1221 8192000000 10064737680 10450817024 1095456080 11.795 100 99 183 1 1190326928 74876192 19994656
02:31:05 1238 8192000000 10037553456 10425262080 1080487328 11.652 100 85 164 1 1163511760 66624112 16400320
02:32:07 1145 8192000000 10012836384 10392035328 1048689264 11.348 99 83 160 2 1141551440 76004720 16857456
02:33:09 975 8192000000 9992829600 10369617920 1047210064 11.334 100 89 164 1 1118000064 52569024 18220976
02:34:11 1049 8192000000 10030654512 10413912064 1062665600 11.482 100 86 162 0 1153502800 73515680 17321520
02:35:14 1022 8192000000 10009122880 10385514496 1049760768 11.358 99 90 176 3 1133370144 65089632 18519744
02:36:16 3247 8192000000 9978893440 10356711424 1040555216 11.270 99 68 131 4 1103805744 49538672 13711856
02:37:20 1255 8192000000 10011731936 10390171648 1056490448 11.423 100 86 162 2 1130347328 57433760 16423120
02:38:26 2737 8192000000 10027611056 10404327424 1053419392 11.393 100 83 150 3 1146689792 78657040 14613360
02:39:41 8432 8192000000 10025782928 10412589056 1075870432 11.608 100 73 134 0 1155128560 64614144 14643984
02:40:54 1450 8192000000 10054018528 10441203712 1088394208 11.727 100 76 134 4 1174222368 72663840 13164320
02:42:07 3673 8192000000 10103389680 10489393152 1098946752 11.828 100 74 140 1 1218925696 105590448 14388496
02:43:14 5153 8192000000 10134314816 10531344384 1109652896 11.929 99 75 151 2 1244336256 117857872 16825488
02:44:25 6358 8192000000 10158173008 10567958528 1124778576 12.072 100 52 102 0 1280270368 143922128 11569664
02:45:38 2627 8192000000 10161144768 10563534848 1136129744 12.179 100 61 115 0 1280901312 132220320 12551248
02:46:46 18985 8192000000 10238229872 10645250048 1149097328 12.301 100 74 146 0 1345155328 180334656 15723344
02:48:09 1201 8192000000 10273811888 10670972928 1160587312 12.409 100 96 181 1 1388582160 208815552 19179296
02:49:13 1212 8192000000 10297226752 10698911744 1160753088 12.410 99 94 184 2 1406824032 225428304 20642640
02:50:17 1018 8192000000 10342241856 10742288384 1177298768 12.565 100 97 187 3 1452387520 254458384 20630368
02:51:21 790 8192000000 10357812816 10774573056 1184770656 12.635 100 96 185 2 1470713200 265556256 20386288
02:52:25 2090 8192000000 10405180848 10818822144 1192051216 12.702 100 94 175 0 1516113792 302731808 21330768
02:53:30 1521 8192000000 10414762720 10829197312 1181277696 12.602 100 84 166 1 1525298784 324300944 19720144
02:54:36 844 8192000000 10491123232 10900779008 1198002256 12.758 100 96 187 1 1596935232 376182688 22750288
02:55:41 1076 8192000000 10494104880 10922295296 1192866928 12.710 100 96 185 1 1602763824 387471152 22425744
02:56:45 1192 8192000000 10500319360 10926542848 1192635856 12.708 100 91 176 0 1609644192 396367568 20640768
02:57:50 5782 8192000000 10498024176 10954219520 1189514112 12.679 100 63 121 2 1621668944 417200304 14954528
02:59:06 5745 8192000000 10529853584 10963468288 1185556832 12.642 100 57 112 0 1632278448 432341120 14380496
03:00:14 18229 8192000000 10483493920 10928885760 1168713744 12.485 99 38 73 0 1591887968 413242576 9931648
03:01:34 1966 8192000000 10548313920 10969915392 1160856992 12.411 100 75 144 1 1654372800 475734768 17781040
03:02:38 1079 8192000000 10493363728 10992984064 1157727664 12.382 100 68 128 1 1599759184 424948192 17083328
03:03:47 19910 8192000000 10427792112 10867933184 1164904208 12.449 100 46 90 1 1526001664 349676192 11421264
03:05:14 17129 8192000000 10397149920 10846142464 1130197632 12.123 99 34 68 0 1501825344 362816480 8811232
03:06:31 39706 8192000000 10361090112 10840068096 1146990768 12.281 100 23 46 0 1482218096 328996272 6231056
03:08:12 50440 8192000000 10101571312 10646253568 1123492528 12.060 98 21 35 1 1326572848 198872688 4207632
03:10:04 87217 8192000000 10078575152 10502418432 1104688512 11.882 97 33 66 0 1166960896 55322368 6950016
03:12:35 101176 8192000000 10108051920 10580496384 1106890480 11.903 97 13 26 0 1183143584 73348160 2904944
03:15:48 63623 8192000000 10105869360 10536747008 1122041376 12.046 99 33 64 0 1227533392 98710800 6781216
03:17:53 62604 8192000000 10076168896 10547666944 1127254000 12.095 99 12 26 0 1192283520 62320912 2708608
03:19:56 133537 8192000000 10062457520 10497581056 1049932768 11.360 92 8 17 0 1076421872 24209264 2279840
03:23:10 26372 8192000000 10087766320 10511331328 1135406352 12.172 100 36 71 1 1183053600 40159328 7487920
03:24:38 18216 8192000000 10132858512 10556059648 1147841552 12.289 100 44 82 1 1216632688 60782704 8008432
03:25:57 80238 8192000000 10094497808 10534961152 1113906592 11.969 97 16 32 0 1173119312 56075120 3137600
03:28:22 73863 8192000000 10054361504 10488897536 1074495936 11.595 94 16 32 0 1101883040 24171200 3215904
03:30:39 832 8192000000 10097869456 10512048128 1133702480 12.156 100 75 148 3 1188525440 39213040 15609920
03:31:41 124037 8192000000 10096227904 10563670016 1145079888 12.263 100 29 56 1 1210581136 59783760 5717488
03:34:54 26801 8192000000 10101441088 10542792704 1149026000 12.300 100 22 41 0 1194459744 40469136 4964608
03:36:28 55328 8192000000 10135952128 10575544320 1141296288 12.228 99 28 56 0 1214541088 66588576 6656224
03:38:32 28312 8192000000 10205801168 10677596160 1147242704 12.284 99 38 76 0 1295290384 139802672 8245008
03:40:12 75069 8192000000 10117673552 10609360896 1145721872 12.269 99 13 26 0 1228280544 79532528 3026144
03:42:27 59152 8192000000 10120416816 10607570944 1112156512 11.953 97 13 26 0 1210898352 95339968 3401872
03:44:27 48484 8192000000 10121595184 10561044480 1149923648 12.309 99 20 38 0 1175960128 22047520 3988960
03:46:26 43204 8192000000 10126564304 10591985664 1140813232 12.223 98 23 45 0 1230508544 85204464 4490848
03:48:10 42804 8192000000 10113614336 10569400320 1142635504 12.240 99 36 72 0 1212543616 62559968 7348144
03:50:32 39353 8192000000 10088661424 10520870912 1137632592 12.193 100 27 50 2 1176266384 33603136 5030656
03:52:12 76426 8192000000 10119192048 10615857152 1131247264 12.133 99 31 60 0 1246813232 109000176 6565792
03:54:30 16502 8192000000 10109781680 10529157120 1132900144 12.149 100 38 76 1 1190854000 50515936 7437920
03:55:51 82347 8192000000 10080231088 10563395584 1093399344 11.775 96 22 44 0 1175185264 77234928 4550992
03:58:26 94383 8192000000 10048426016 10513018880 1096836976 11.808 97 26 51 0 1131893056 30016800 5039280
04:01:01 52869 8192000000 10031686256 10512101376 1093461840 11.776 98 17 34 1 1152268976 55550160 3256976
04:03:11 46695 8192000000 10070114080 10511208448 1102152048 11.858 98 20 40 0 1151093344 44072736 4868560
04:05:32 71179 8192000000 10112987808 10573127680 1130471552 12.126 100 20 39 0 1218780656 83855360 4453744
04:07:43 57566 8192000000 10059380128 10514804736 1102970640 11.866 98 28 54 0 1138981568 30327856 5683072
04:09:43 37342 8192000000 10096893440 10527068160 1106654128 11.901 98 44 85 0 1173436208 58670800 8111280
04:11:22 24402 8192000000 10117395952 10562314240 1118766032 12.015 100 38 76 0 1197232944 70080464 8386448
04:12:48 42343 8192000000 10060804864 10532319232 1094501440 11.785 98 17 34 1 1162230112 64452160 3276512
04:14:30 24794 8192000000 10096238336 10529427456 1116988080 11.999 99 30 60 0 1164732944 41900464 5844400
04:15:57 61004 8192000000 10062682432 10561953792 1108553424 11.919 99 18 34 0 1212290144 100345008 3391712
04:18:06 12404 8192000000 10081456704 10505809920 1124432528 12.069 99 46 91 1 1163272896 30077344 8763024
04:19:20 47658 8192000000 10105617952 10601467904 1126257088 12.086 100 37 74 0 1230535664 96737584 7540992
04:21:11 8647 8192000000 10119576624 10541821952 1140266320 12.218 100 55 111 1 1210893824 59563440 11064064
04:22:21 87407 8192000000 10071911792 10568790016 1120106256 12.028 99 28 56 0 1202026864 76076656 5843952
04:24:49 56708 8192000000 10080725856 10533543936 1138678384 12.203 100 10 20 0 1186642240 45860896 2102960
04:26:52 61797 8192000000 10096373296 10548436992 1131667936 12.137 100 14 28 0 1189479680 54657056 3154688
04:28:57 102172 8192000000 10104660128 10555162624 1136451856 12.182 100 15 31 0 1189647552 49896800 3298896
04:31:40 22699 8192000000 10103547264 10538700800 1126020560 12.084 99 34 66 0 1175645856 42745216 6880080
04:33:07 29347 8192000000 10163160096 10599141376 1154216656 12.349 100 50 98 0 1250056656 85642560 10197440
04:34:40 24928 8192000000 10160599792 10591129600 1135904144 12.177 99 38 74 0 1253354624 109262096 8188384
04:36:06 44964 8192000000 10199395872 10660892672 1132236832 12.142 98 37 74 0 1284693168 144549520 7906816

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-01-2023 04:39:20 to 24-01-2023 04:39:22.

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          216167
MON$OLDEST_ACTIVE               221962
MON$OLDEST_SNAPSHOT             221962
MON$NEXT_TRANSACTION            221962
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                       2048416
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             773
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2890 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2890 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2890 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2890 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 ---
:1063: 
:1064: mscs-test-161.budzdorov.ru    Tue Jan 24 04:37:28 2023
:1065:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1066: 
:1067: 
:1068: mscs-test-161.budzdorov.ru    Tue Jan 24 04:37:30 2023
:1069:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1070: 
:1071: 
:1072: mscs-test-161.budzdorov.ru    Tue Jan 24 04:37:34 2023
:1073:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1074: 
--- end of diff output ---

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

24.01.23 04:39:23. 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-01-24 04:39:26 No severe PSQL-related problems occured
24.01.23 04:39:27. End of report.