Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.02.2023 07:48
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 14940740 kB
MemAvailable 30415808 kB
Buffers 0 kB
Cached 16735364 kB
SwapCached 3600 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-02-09 07:47:33
DTS_END                         2023-02-09 07:47:33.9790
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56598
TRN_ID                          222213
ATT_ID                          661
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: 8844.10 25751 1061292
customer order: creation 171.06 438 20527
customer order: refuse 15.02 426 1802
order to supplier: creation 45.70 1311 5484
order to supplier: removal 3.32 162916 398
invoice (draft): creation 45.59 3009 5471
invoice (draft): removal 46.99 987 5639
invoice accept: apply 42.60 25588 5112
invoice accept: cancel 42.65 1252 5118
customer reserve: creation 3939.85 243 472782
customer reserve: removal 4202.06 101 504247
realization accept: apply 83.30 289 9996
realization accept: cancel 77.01 184 9241
payment from customer: creation 58.54 182 7025
payment from customer: removal 5.63 121 675
payment to supplier: creation 48.62 213 5834
payment to supplier: removal 3.54 109 425
service: total inventory turnovers 4.09 3995 491
service: total monetary turnovers 7.68 339 922
service: aggregate perf. data 0.83 19041 99
service: refresh index statistics 0.03 294269 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-02-2023 07:48:49 to 09-02-2023 07:48:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6048
TEST_TIME 2 6908
TEST_TIME 3 6127
TEST_TIME 4 6135
TEST_TIME 5 5702
TEST_TIME 6 5547
TEST_TIME 7 5614
TEST_TIME 8 5821
TEST_TIME 9 6080
TEST_TIME 10 5981
TEST_TIME 11 6032
TEST_TIME 12 5998
TEST_TIME 13 5970
TEST_TIME 14 6141
TEST_TIME 15 6334
TEST_TIME 16 6424
TEST_TIME 17 6745
TEST_TIME 18 7106
TEST_TIME 19 7311
TEST_TIME 20 7570
TEST_TIME 21 7651
TEST_TIME 22 7860
TEST_TIME 23 8165
TEST_TIME 24 8099
TEST_TIME 25 8342
TEST_TIME 26 8778
TEST_TIME 27 8962
TEST_TIME 28 9100
TEST_TIME 29 9348
TEST_TIME 30 9436
TEST_TIME 31 10116
TEST_TIME 32 10183
TEST_TIME 33 10459
TEST_TIME 34 10776
TEST_TIME 35 10626
TEST_TIME 36 10734
TEST_TIME 37 10629
TEST_TIME 38 10618
TEST_TIME 39 10394
TEST_TIME 40 10373
TEST_TIME 41 10253
TEST_TIME 42 10132
TEST_TIME 43 10040
TEST_TIME 44 10024
TEST_TIME 45 9943
TEST_TIME 46 9866
TEST_TIME 47 9848
TEST_TIME 48 9872
TEST_TIME 49 9808
TEST_TIME 50 9846
TEST_TIME 51 9905
TEST_TIME 52 9868
TEST_TIME 53 9731
TEST_TIME 54 9562
TEST_TIME 55 9492
TEST_TIME 56 9452
TEST_TIME 57 9440
TEST_TIME 58 9306
TEST_TIME 59 9325
TEST_TIME 60 9309
TEST_TIME 61 9270
TEST_TIME 62 9308
TEST_TIME 63 9292
TEST_TIME 64 9224
TEST_TIME 65 9131
TEST_TIME 66 9036
TEST_TIME 67 9072
TEST_TIME 68 9011
TEST_TIME 69 9025
TEST_TIME 70 9009
TEST_TIME 71 8995
TEST_TIME 72 8995
TEST_TIME 73 8999
TEST_TIME 74 9060
TEST_TIME 75 9061
TEST_TIME 76 9042
TEST_TIME 77 9069
TEST_TIME 78 9092
TEST_TIME 79 9086
TEST_TIME 80 9273
TEST_TIME 81 9218
TEST_TIME 82 9380
TEST_TIME 83 9274
TEST_TIME 84 9218
TEST_TIME 85 9158
TEST_TIME 86 9143
TEST_TIME 87 9113
TEST_TIME 88 9061
TEST_TIME 89 9017
TEST_TIME 90 8981
TEST_TIME 91 8959
TEST_TIME 92 8907
TEST_TIME 93 8913
TEST_TIME 94 8903
TEST_TIME 95 8932
TEST_TIME 96 8888
TEST_TIME 97 8924
TEST_TIME 98 8950
TEST_TIME 99 8947
TEST_TIME 100 8945
TEST_TIME 101 8958
TEST_TIME 102 9029
TEST_TIME 103 9013
TEST_TIME 104 9044
TEST_TIME 105 9061
TEST_TIME 106 9073
TEST_TIME 107 9136
TEST_TIME 108 9064
TEST_TIME 109 9004
TEST_TIME 110 8985
TEST_TIME 111 9027
TEST_TIME 112 8979
TEST_TIME 113 8946
TEST_TIME 114 8908
TEST_TIME 115 8897
TEST_TIME 116 8892
TEST_TIME 117 8874
TEST_TIME 118 8908
TEST_TIME 119 8908
TEST_TIME 120 8872

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-02-2023 07:48:53 to 09-02-2023 07:48:56.

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 1495231 1495231 0 0.00 0 32751 14 0 0
doc_list_biud 1495226 1495226 0 0.00 0 32264 40 0 0
sp_add_invoice_to_stock 5247 5112 135 2.57 31 211551 25589 0 135
sp_cancel_adding_invoice 5118 5118 0 0.00 6 100836 1252 0 0
sp_cancel_client_order 1802 1802 0 0.00 6 8676 427 0 0
sp_cancel_customer_reserve 504247 504247 0 0.00 1 25929 101 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 4 6395 122 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 4 2165 109 0 0
sp_cancel_supplier_invoice 5646 5639 7 0.12 3 14621 988 0 7
sp_cancel_supplier_order 398 398 0 0.00 8 1523216 162916 0 0
sp_cancel_write_off 9241 9241 0 0.00 2 32322 184 0 0
sp_client_order 20527 20527 0 0.00 5 22302 439 0 0
sp_customer_reserve 472782 472782 0 0.00 0 94098 243 0 0
sp_fill_shopping_cart 31477 31477 0 0.00 1 12511 85 0 0
sp_fill_shopping_cart_clo_res 472736 472736 0 0.00 0 16320 19 0 0
sp_get_clo_for_invoice 5060 5060 0 0.00 0 22560 335 0 0
sp_kill_cost_storno 15459 15459 0 0.00 0 15544 76 0 0
sp_kill_qstorno_ret_qs2qd 1023772 1023772 0 0.00 0 32263 43 0 0
sp_kill_qty_storno 541496 541496 0 0.00 0 32263 100 0 0
sp_lock_selected_doc 20911 20911 0 0.00 0 9030 8 0 0
sp_make_cost_storno 27915 27915 0 0.00 0 13677 86 0 0
sp_multiply_rows_for_pdistr 27912 27912 0 0.00 0 10957 37 0 0
sp_multiply_rows_for_qdistr 20527 20527 0 0.00 0 10428 152 0 0
sp_pay_from_customer 7025 7025 0 0.00 5 15548 182 0 0
sp_pay_to_supplier 5834 5834 0 0.00 5 11401 214 0 0
sp_payment_common 12859 12859 0 0.00 2 15478 52 0 0
sp_qd_handle_on_cancel_clo 1802 1802 0 0.00 1 8665 341 0 0
sp_qd_handle_on_invoice_upd_sts 10175 10175 0 0.00 0 22555 531 0 0
sp_qd_handle_on_reserve_upd_sts 9994 9994 0 0.00 0 18094 58 0 0
sp_reserve_write_off 9996 9996 0 0.00 8 18116 289 0 0
sp_supplier_invoice 5471 5471 0 0.00 8 88805 3010 0 0
sp_supplier_order 5484 5484 0 0.00 8 32072 1312 0 0
srv_aggregate_perf_data 99 99 0 0.00 2561 211377 19041 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 765 272855 36192 0 0
srv_find_qd_qs_mism 1011395 1011395 0 0.00 0 32750 10 0 0
srv_make_invnt_saldo 491 491 0 0.00 30 96859 3995 0 0
srv_make_money_saldo 922 922 0 0.00 1 15087 339 0 0
srv_recalc_idx_stat 4 4 0 0.00 67390 517619 294270 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5117 5117 0 0.00 1 12598 922 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 1 5979 472 0 0
t$perf-norm:sp_cancel_client_order 1802 1802 0 0.00 0 3235 29 0 0
t$perf-norm:sp_cancel_customer_reserve 724 724 0 0.00 0 1240 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 10825 35 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 283 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 815 25 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 85702 7750 0 0
t$perf-norm:sp_cancel_write_off 1018 1018 0 0.00 0 622 20 0 0
t$perf-norm:sp_client_order 20528 20528 0 0.00 0 10855 37 0 0
t$perf-norm:sp_customer_reserve 977 977 0 0.00 0 1132 16 0 0
t$perf-norm:sp_pay_from_customer 7025 7025 0 0.00 0 10912 23 0 0
t$perf-norm:sp_pay_to_supplier 5835 5835 0 0.00 0 5843 22 0 0
t$perf-norm:sp_reserve_write_off 9996 9996 0 0.00 0 10887 25 0 0
t$perf-norm:sp_supplier_invoice 5471 5471 0 0.00 0 5417 30 0 0
t$perf-norm:sp_supplier_order 5484 5484 0 0.00 0 11187 32 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 553 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 1566 41 0 0
t$perf-norm:srv_make_invnt_saldo 491 491 0 0.00 0 958 21 0 0
t$perf-norm:srv_make_money_saldo 922 922 0 0.00 0 1065 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 183 60 0 0
v_add_invoice_to_stock 10378 10108 270 2.60 0 8548 28 0 270
v_all_customers 37546 37546 0 0.00 0 176 4 0 0
v_all_suppliers 30366 30366 0 0.00 0 85 4 0 0
v_all_wares 41054 41054 0 0.00 0 176 4 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 3609 22 0 0
v_cancel_client_order 3604 3604 0 0.00 0 4459 16 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 1632 13 0 0
v_cancel_customer_reserve 1448 1448 0 0.00 1 3968 17 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 2106 15 0 0
v_cancel_supplier_invoice 840 826 14 1.66 0 3329 31 0 14
v_cancel_supplier_order 796 796 0 0.00 0 2110 15 0 0
v_cancel_write_off 2036 2036 0 0.00 0 5384 33 0 0
v_max_id_clo_ord 5482 5482 0 0.00 0 267 5 0 0
v_max_id_clo_res 977 977 0 0.00 0 2582 20 0 0
v_max_id_ord_sup 5468 5468 0 0.00 0 8504 9 0 0
v_max_non_paid_invoice 1601 1601 0 0.00 0 139 8 0 0
v_max_non_paid_realizn 3624 3624 0 0.00 0 8241 11 0 0
v_min_id_clo_ord 5482 5482 0 0.00 0 3024 10 0 0
v_min_id_clo_res 977 977 0 0.00 0 4343 13 0 0
v_min_id_ord_sup 5468 5468 0 0.00 0 4833 10 0 0
v_min_non_paid_invoice 5834 5834 0 0.00 0 11189 21 0 0
v_min_non_paid_realizn 7025 7025 0 0.00 0 10589 22 0 0
v_our_firm 10310 10310 0 0.00 0 122 5 0 0
v_reserve_write_off 19988 19988 0 0.00 0 13333 15 0 0
x_lock_depdocs_on_canc_invoice 10344 10344 0 0.00 0 11583 134 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 28033 501 0 0
x_make_qstorno_1000_1200 5484 5484 0 0.00 2 32060 1145 0 0
x_make_qstorno_1200_2000 5471 5471 0 0.00 4 88186 2774 0 0
x_make_qstorno_2100_3300 472782 472782 0 0.00 0 94090 212 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-02-2023 07:48:58 to 09-02-2023 07:49:02.

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:17:37 6 8192000000 8811988416 8828084224 14046464 0.171 7 3 2 0 14178256 96016 35776
05:18:38 32917 8192000000 9722550336 10022617088 796563376 8.861 100 90 178 0 826675376 7989168 22122832
05:20:21 19644 8192000000 9760416144 10068688896 836050688 9.260 100 84 168 0 868280912 12182496 20047728
05:21:46 13611 8192000000 9798370320 10118053888 866585536 9.566 100 87 174 0 906672288 20057344 20029408
05:23:05 10909 8192000000 9861219920 10198151168 911566400 10.013 100 68 132 0 966787504 41875472 13345632
05:24:22 4599 8192000000 9929679904 10274701312 968515968 10.572 100 89 178 0 1039870208 52835392 18518848
05:25:28 1982 8192000000 9965946736 10323726336 997983168 10.859 100 93 176 0 1089481440 74064704 17433568
05:26:32 1750 8192000000 10009185632 10370469888 1021371200 11.085 100 95 171 1 1126968992 88789808 16807984
05:27:36 1117 8192000000 9996936304 10367258624 1042436592 11.288 100 94 178 0 1116315248 54611328 19267328
05:28:38 1490 8192000000 10006098864 10379608064 1054180512 11.401 100 91 169 1 1127974336 55714000 18079824
05:29:41 1131 8192000000 10054881728 10430865408 1063460960 11.490 100 93 184 1 1174229552 92453232 18315360
05:30:44 977 8192000000 10032544944 10409951232 1073321584 11.584 100 96 190 1 1156381616 63848432 19211600
05:31:47 1561 8192000000 10027881232 10408890368 1073839296 11.589 100 85 163 1 1147856064 58590976 15425792
05:32:49 992 8192000000 10035184192 10416631808 1079098352 11.639 100 92 178 1 1158826528 60660064 19068112
05:33:51 1344 8192000000 10048289120 10432745472 1085132064 11.696 100 84 153 2 1165096336 64460048 15504224
05:34:54 2380 8192000000 10052425008 10441510912 1095381696 11.794 100 76 138 1 1181545184 73004080 13159408
05:35:57 3115 8192000000 10048042000 10435608576 1093406272 11.775 100 81 157 0 1174515248 65212032 15896944
05:37:01 1252 8192000000 10070420224 10460508160 1096018208 11.800 100 99 192 2 1194516528 79378448 19119872
05:38:03 1158 8192000000 10032933552 10415775744 1091782320 11.760 100 86 162 3 1152091056 43787200 16521536
05:39:05 835 8192000000 10058248160 10450124800 1093590448 11.777 100 93 175 1 1183245488 70912528 18742512
05:40:07 1386 8192000000 10039501056 10420461568 1057803376 11.435 98 95 181 2 1162860912 87741792 17315744
05:41:09 1184 8192000000 10034558032 10414489600 1068927184 11.542 100 92 173 1 1160532368 72076432 19528752
05:42:11 1736 8192000000 10016084688 10397188096 1050480512 11.365 100 79 158 0 1134124592 68230560 15413520
05:43:14 1285 8192000000 10018499024 10396782592 1063076208 11.486 100 96 179 4 1143367232 62061328 18229696
05:44:16 1017 8192000000 9997851600 10373836800 1046477952 11.327 99 94 172 1 1121513920 55567568 19468400
05:45:18 3902 8192000000 9999295184 10373394432 1032371712 11.191 98 74 135 4 1111962848 65076800 14514336
05:46:26 659 8192000000 10008584272 10385805312 1053811424 11.397 100 92 180 1 1126183712 53893632 18478656
05:47:29 1409 8192000000 10013176752 10391146496 1054234816 11.401 100 91 173 1 1134561984 61810736 18516432
05:48:34 2243 8192000000 10026290672 10407845888 1071884704 11.570 100 90 175 1 1154320720 65501088 16934928
05:49:43 1121 8192000000 10035661648 10410553344 1062367440 11.479 99 94 175 5 1159070400 75656432 21046528
05:50:46 16859 8192000000 10062360720 10460389376 1092090064 11.763 100 60 113 0 1184020096 79779056 12150976
05:52:07 1716 8192000000 10101815040 10492735488 1110855392 11.941 100 69 134 1 1213857456 89119280 13882784
05:53:13 10942 8192000000 10134801232 10532753408 1123389264 12.059 100 63 116 0 1256816768 120806496 12621008
05:54:29 2492 8192000000 10192822672 10585272320 1139656800 12.212 100 83 150 3 1309799216 153287984 16854432
05:55:34 1049 8192000000 10248120848 10642329600 1145001248 12.263 100 89 168 2 1358370416 194186368 19182800
05:56:40 7407 8192000000 10239227152 10640564224 1152878368 12.337 100 83 156 0 1356430736 185352768 18199600
05:57:51 1578 8192000000 10285350624 10683056128 1165541520 12.455 100 96 172 3 1397889520 213113344 19234656
05:58:57 1131 8192000000 10334152384 10735484928 1176157584 12.554 100 93 179 0 1446889872 249837520 20894768
06:00:05 2072 8192000000 10359400048 10759790592 1182206816 12.611 100 93 178 3 1471598128 269197488 20193824
06:01:11 1997 8192000000 10402035328 10817007616 1186348912 12.649 100 94 184 0 1511371312 302621440 22400960
06:02:17 1139 8192000000 10429747392 10850025472 1192042336 12.702 100 91 176 2 1541680384 327796032 21842016
06:03:23 2090 8192000000 10439871296 10879893504 1190346208 12.687 100 82 158 1 1552105488 342665216 19094064
06:04:29 1310 8192000000 10462741344 10891317248 1189916896 12.683 100 97 191 0 1573303504 360810816 22575792
06:05:34 960 8192000000 10474006560 10918260736 1191805104 12.700 100 96 184 1 1587932352 373464400 22662848
06:06:37 1536 8192000000 10459413616 10884935680 1178736624 12.578 100 94 178 0 1566592656 367894000 19962032
06:07:44 749 8192000000 10516515280 10950402048 1182846384 12.617 100 97 190 0 1623893824 418034496 23012944
06:08:49 1139 8192000000 10560846368 10970804224 1190619360 12.689 100 98 194 2 1664472528 450809808 23043360
06:09:52 1257 8192000000 10517080560 10994802688 1200768176 12.783 100 77 152 0 1620436784 401172176 18496432
06:11:03 2320 8192000000 10548233520 10973421568 1188909536 12.673 100 66 132 1 1655323312 450400048 16013728
06:12:25 1463 8192000000 10526206112 11028475904 1179372800 12.584 100 77 146 3 1631922304 434641040 17908464
06:13:31 13648 8192000000 10521876224 11013906432 1182904752 12.617 100 40 78 1 1632038000 439144944 9988304
06:14:47 15575 8192000000 10493804736 10944606208 1187739024 12.662 100 35 70 0 1599733088 402900288 9093776
06:16:11 52900 8192000000 10416763808 10892189696 1147895152 12.290 98 28 56 1 1515459136 360390512 7173472
06:18:05 40026 8192000000 10294291968 10807992320 1136087248 12.179 98 21 41 0 1420043232 278780176 5175808
06:20:50 2337 8192000000 10169335344 10698215424 1149163712 12.302 100 81 159 0 1263982064 97190608 17627744
06:21:56 53217 8192000000 10131133056 10560282624 1092311744 11.765 96 39 75 1 1213448816 112331808 8805264
06:23:55 42804 8192000000 10099436864 10552184832 1121581168 12.042 100 21 41 0 1193800304 66948960 5270176
06:25:50 57163 8192000000 10069815184 10504151040 1112623120 11.957 98 27 48 0 1161558208 43741792 5193296
06:27:49 42259 8192000000 10063138448 10520150016 1103362992 11.870 98 38 67 0 1141409248 31616928 6429328
06:29:31 8663 8192000000 10079422048 10499420160 1140025088 12.216 100 66 125 1 1170233728 15454240 14754400
06:30:41 61889 8192000000 10089926608 10557349888 1122971504 12.055 98 26 53 3 1174749872 46755872 5022496
06:32:55 88158 8192000000 10088907728 10540314624 1110358592 11.936 97 19 38 0 1146760032 32840032 3561408
06:35:30 272855 8192000000 10052951856 10533076992 1029983104 11.168 90 7 14 0 1082380400 51193584 1203712
06:41:03 112308 8192000000 10071193632 10513731584 1115254928 11.982 99 35 70 2 1146521856 24051472 7215456
06:44:02 72649 8192000000 10079690544 10541195264 1102548480 11.862 97 24 46 0 1165291744 57726320 5016944
06:47:03 31769 8192000000 10091537664 10533933056 1124034880 12.065 99 32 59 1 1191088336 61410288 5643168
06:48:43 26826 8192000000 10116513328 10574815232 1123388240 12.059 100 51 90 3 1221847440 88878400 9580800
06:50:11 29341 8192000000 10061498176 10524553216 1121311696 12.039 100 6 12 0 1171602608 49087936 1202976
06:52:08 48816 8192000000 10054049456 10505678848 1119506896 12.022 99 11 22 0 1145755632 23942176 2306560
06:54:08 53852 8192000000 10052019360 10558226432 1101789872 11.855 98 11 22 0 1147987056 44031520 2165664
06:56:06 49455 8192000000 10064187392 10499788800 1107781184 11.911 98 36 71 0 1155916288 40047296 8087808
06:58:00 26198 8192000000 10077055168 10513113088 1101158656 11.849 98 37 73 0 1148724320 40031392 7534272
06:59:27 30648 8192000000 10103803408 10541056000 1121729120 12.043 99 26 51 0 1192383200 65529424 5124656
07:00:59 42222 8192000000 10123791696 10566348800 1114588544 11.976 98 31 60 0 1208581968 87667952 6325472
07:02:43 53812 8192000000 10116594720 10547167232 1132091696 12.141 99 28 54 0 1209253840 71182016 5980128
07:04:48 42934 8192000000 10191595136 10643652608 1148936368 12.300 100 33 64 1 1299809344 143746784 7126192
07:06:39 41020 8192000000 10202106208 10683531264 1138277984 12.199 99 17 34 0 1298497936 156236784 3983168
07:08:27 133598 8192000000 10066402464 10514075648 1123045776 12.056 98 16 32 0 1196526000 70053504 3426720
07:11:44 20456 8192000000 10073821312 10508156928 1128940912 12.111 99 52 99 2 1166125056 27181376 10002768
07:13:05 14262 8192000000 10070299184 10509225984 1111734592 11.949 98 33 65 1 1155284416 37281632 6268192
07:14:24 36680 8192000000 10053237168 10502262784 1124667744 12.071 99 19 38 0 1157340752 28666144 4006864
07:16:18 78889 8192000000 10099762048 10549694464 1108040560 11.914 97 14 29 0 1168973568 58102160 2830848
07:18:37 100955 8192000000 10061455744 10525609984 1110302272 11.935 99 13 25 0 1193071584 80019808 2749504
07:21:21 87717 8192000000 10074477984 10566488064 1093191680 11.773 96 13 24 1 1144661792 49076144 2393968
07:24:02 25076 8192000000 10108525088 10546806784 1097079072 11.810 97 47 90 0 1167255840 61234912 8941856
07:25:28 9312 8192000000 10067372848 10492874752 1114753008 11.977 100 46 89 1 1164854288 41471056 8630224
07:26:39 15219 8192000000 10114668064 10539114496 1129862096 12.120 100 57 109 0 1198485072 57434320 11188656
07:27:55 31539 8192000000 10134838400 10564710400 1137707168 12.194 100 46 92 0 1242641472 95369216 9565088
07:29:32 43499 8192000000 10085844240 10528178176 1099839120 11.836 98 33 66 1 1163400560 57048176 6513264
07:31:16 8803 8192000000 10093380912 10534486016 1127612176 12.099 100 60 114 1 1184423776 44972160 11839440
07:32:30 50008 8192000000 10091229568 10531209216 1116947904 11.998 99 46 86 1 1186709104 61036448 8724752
07:34:20 80626 8192000000 10090691360 10588155904 1123900688 12.064 99 16 32 1 1191045520 63962512 3182320
07:37:21 33584 8192000000 10098520992 10569662464 1111937936 11.951 98 21 42 0 1202469488 86000240 4531312
07:38:55 56974 8192000000 10055512944 10512969728 1121594480 12.042 99 6 12 0 1159236272 36392112 1249680
07:40:52 96414 8192000000 10087652704 10550902784 1102606336 11.862 97 9 19 0 1154120720 49438752 2075632
07:43:54 55687 8192000000 10109910720 10544996352 1121139696 12.038 99 28 56 0 1204942272 77569360 6233216
07:46:15 39662 8192000000 10085210848 10511196160 1122259216 12.048 99 34 66 0 1161788752 32333136 7196400

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 09-02-2023 07:49:04 to 09-02-2023 07:49:20.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-02-2023 07:49:21 to 09-02-2023 07:49:23.

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          219857
MON$OLDEST_ACTIVE               222327
MON$OLDEST_SNAPSHOT             222327
MON$NEXT_TRANSACTION            222327
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                       2076512
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             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2899 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2899 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2899 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2899 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Feb  9 07:47:39 2023
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Thu Feb  9 07:47:39 2023
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Thu Feb  9 07:47:40 2023
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Thu Feb  9 07:47:40 2023
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
--- end of diff output ---

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

09.02.23 07:49:24. 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-02-09 07:49:27 No severe PSQL-related problems occured
09.02.23 07:49:28. End of report.