Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.01.2023 07: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 15314816 kB
MemAvailable 30413768 kB
Buffers 0 kB
Cached 16419156 kB
SwapCached 4148 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-15 07:37:22
DTS_END                         2023-01-15 07:37:22.7710
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40936
TRN_ID                          217342
ATT_ID                          614
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: 8457.52 21021 1014902
customer order: creation 166.06 488 19927
customer order: refuse 14.10 428 1692
order to supplier: creation 44.48 1349 5338
order to supplier: removal 3.20 159720 384
invoice (draft): creation 43.60 3379 5232
invoice (draft): removal 46.85 1023 5622
invoice accept: apply 39.63 27873 4756
invoice accept: cancel 42.04 1293 5045
customer reserve: creation 3699.89 266 443987
customer reserve: removal 4082.21 99 489865
realization accept: apply 80.78 295 9693
realization accept: cancel 67.66 189 8119
payment from customer: creation 57.20 184 6864
payment from customer: removal 5.38 139 645
payment to supplier: creation 48.43 196 5811
payment to supplier: removal 3.23 98 387
service: total inventory turnovers 4.28 3637 513
service: total monetary turnovers 7.46 347 895
service: aggregate perf. data 1.03 13463 123
service: refresh index statistics 0.03 205962 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 15-01-2023 07:39:37 to 15-01-2023 07:39:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9628
TEST_TIME 2 6827
TEST_TIME 3 6626
TEST_TIME 4 6220
TEST_TIME 5 5909
TEST_TIME 6 5904
TEST_TIME 7 5982
TEST_TIME 8 6143
TEST_TIME 9 6476
TEST_TIME 10 6496
TEST_TIME 11 6545
TEST_TIME 12 7190
TEST_TIME 13 7265
TEST_TIME 14 7462
TEST_TIME 15 7601
TEST_TIME 16 7918
TEST_TIME 17 7724
TEST_TIME 18 7722
TEST_TIME 19 7907
TEST_TIME 20 7950
TEST_TIME 21 7864
TEST_TIME 22 7821
TEST_TIME 23 7946
TEST_TIME 24 8119
TEST_TIME 25 8232
TEST_TIME 26 8585
TEST_TIME 27 9350
TEST_TIME 28 10076
TEST_TIME 29 10174
TEST_TIME 30 10575
TEST_TIME 31 10690
TEST_TIME 32 10865
TEST_TIME 33 10825
TEST_TIME 34 10602
TEST_TIME 35 10317
TEST_TIME 36 10191
TEST_TIME 37 10033
TEST_TIME 38 10093
TEST_TIME 39 9999
TEST_TIME 40 9893
TEST_TIME 41 9889
TEST_TIME 42 9738
TEST_TIME 43 9742
TEST_TIME 44 9712
TEST_TIME 45 9598
TEST_TIME 46 9551
TEST_TIME 47 9387
TEST_TIME 48 9322
TEST_TIME 49 9272
TEST_TIME 50 9288
TEST_TIME 51 9234
TEST_TIME 52 9165
TEST_TIME 53 9105
TEST_TIME 54 9046
TEST_TIME 55 9029
TEST_TIME 56 8952
TEST_TIME 57 8900
TEST_TIME 58 8977
TEST_TIME 59 9012
TEST_TIME 60 9110
TEST_TIME 61 9207
TEST_TIME 62 9140
TEST_TIME 63 9128
TEST_TIME 64 9059
TEST_TIME 65 9267
TEST_TIME 66 9213
TEST_TIME 67 9179
TEST_TIME 68 9296
TEST_TIME 69 9238
TEST_TIME 70 9127
TEST_TIME 71 9060
TEST_TIME 72 9029
TEST_TIME 73 9013
TEST_TIME 74 8947
TEST_TIME 75 8882
TEST_TIME 76 8866
TEST_TIME 77 8800
TEST_TIME 78 8805
TEST_TIME 79 8753
TEST_TIME 80 8749
TEST_TIME 81 8708
TEST_TIME 82 8730
TEST_TIME 83 8751
TEST_TIME 84 8788
TEST_TIME 85 8831
TEST_TIME 86 8820
TEST_TIME 87 8773
TEST_TIME 88 8827
TEST_TIME 89 8826
TEST_TIME 90 8852
TEST_TIME 91 8828
TEST_TIME 92 8784
TEST_TIME 93 8746
TEST_TIME 94 8714
TEST_TIME 95 8692
TEST_TIME 96 8681
TEST_TIME 97 8629
TEST_TIME 98 8596
TEST_TIME 99 8592
TEST_TIME 100 8582
TEST_TIME 101 8566
TEST_TIME 102 8556
TEST_TIME 103 8543
TEST_TIME 104 8528
TEST_TIME 105 8532
TEST_TIME 106 8553
TEST_TIME 107 8550
TEST_TIME 108 8584
TEST_TIME 109 8662
TEST_TIME 110 8719
TEST_TIME 111 8776
TEST_TIME 112 8711
TEST_TIME 113 8686
TEST_TIME 114 8654
TEST_TIME 115 8642
TEST_TIME 116 8607
TEST_TIME 117 8568
TEST_TIME 118 8554
TEST_TIME 119 8509
TEST_TIME 120 8476

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-01-2023 07:39:41 to 15-01-2023 07:39:45.

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 1423043 1423043 0 0.00 0 17818 14 0 0
doc_list_biud 1423027 1423027 0 0.00 0 79190 41 0 0
sp_add_invoice_to_stock 4879 4756 123 2.52 78 246111 27874 0 123
sp_cancel_adding_invoice 5045 5045 0 0.00 6 101756 1293 0 0
sp_cancel_client_order 1692 1692 0 0.00 6 20596 429 0 0
sp_cancel_customer_reserve 489865 489865 0 0.00 1 30239 99 0 0
sp_cancel_pay_from_customer 645 645 0 0.00 3 7624 139 0 0
sp_cancel_pay_to_supplier 387 387 0 0.00 4 1984 99 0 0
sp_cancel_supplier_invoice 5634 5622 12 0.21 2 24632 1024 0 12
sp_cancel_supplier_order 384 384 0 0.00 10 1444538 159720 0 0
sp_cancel_write_off 8119 8119 0 0.00 2 27087 189 0 0
sp_client_order 19927 19927 0 0.00 6 27631 489 0 0
sp_customer_reserve 443987 443987 0 0.00 0 60366 267 0 0
sp_fill_shopping_cart 30487 30487 0 0.00 1 19420 89 0 0
sp_fill_shopping_cart_clo_res 443960 443960 0 0.00 0 20418 20 0 0
sp_get_clo_for_invoice 4717 4717 0 0.00 0 23879 317 0 0
sp_kill_cost_storno 14196 14196 0 0.00 0 17812 72 0 0
sp_kill_qstorno_ret_qs2qd 993855 993855 0 0.00 0 40961 44 0 0
sp_kill_qty_storno 525136 525136 0 0.00 0 79184 102 0 0
sp_lock_selected_doc 19875 19875 0 0.00 0 7698 7 0 0
sp_make_cost_storno 27084 27084 0 0.00 0 11879 76 0 0
sp_multiply_rows_for_pdistr 27082 27082 0 0.00 0 9412 37 0 0
sp_multiply_rows_for_qdistr 19927 19927 0 0.00 0 12244 189 0 0
sp_pay_from_customer 6864 6864 0 0.00 6 15210 185 0 0
sp_pay_to_supplier 5811 5811 0 0.00 6 23297 196 0 0
sp_payment_common 12673 12673 0 0.00 2 23280 51 0 0
sp_qd_handle_on_cancel_clo 1692 1692 0 0.00 0 20565 337 0 0
sp_qd_handle_on_invoice_upd_sts 9761 9761 0 0.00 0 79177 523 0 0
sp_qd_handle_on_reserve_upd_sts 9693 9693 0 0.00 0 22201 86 0 0
sp_reserve_write_off 9693 9693 0 0.00 9 22307 296 0 0
sp_supplier_invoice 5232 5232 0 0.00 11 110134 3379 0 0
sp_supplier_order 5338 5338 0 0.00 7 27861 1350 0 0
srv_aggregate_perf_data 123 123 0 0.00 2653 147030 13463 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 696 109225 33575 0 0
srv_find_qd_qs_mism 967244 967244 0 0.00 0 16484 11 0 0
srv_make_invnt_saldo 513 513 0 0.00 39 80880 3638 0 0
srv_make_money_saldo 895 895 0 0.00 1 13451 348 0 0
srv_recalc_idx_stat 4 4 0 0.00 40306 363989 205963 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4759 4759 0 0.00 1 19861 908 0 0
t$perf-norm:sp_cancel_adding_invoice 377 377 0 0.00 1 6905 517 0 0
t$perf-norm:sp_cancel_client_order 1692 1692 0 0.00 0 6253 30 0 0
t$perf-norm:sp_cancel_customer_reserve 668 668 0 0.00 0 1042 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 645 645 0 0.00 0 2372 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 387 387 0 0.00 0 170 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 618 33 0 0
t$perf-norm:sp_cancel_supplier_order 384 384 0 0.00 0 72965 6580 0 0
t$perf-norm:sp_cancel_write_off 947 947 0 0.00 0 1003 16 0 0
t$perf-norm:sp_client_order 19927 19927 0 0.00 0 15087 36 0 0
t$perf-norm:sp_customer_reserve 973 973 0 0.00 0 1255 16 0 0
t$perf-norm:sp_pay_from_customer 6864 6864 0 0.00 0 5487 18 0 0
t$perf-norm:sp_pay_to_supplier 5811 5811 0 0.00 0 6063 18 0 0
t$perf-norm:sp_reserve_write_off 9694 9694 0 0.00 0 6885 26 0 0
t$perf-norm:sp_supplier_invoice 5232 5232 0 0.00 0 19988 32 0 0
t$perf-norm:sp_supplier_order 5338 5338 0 0.00 0 7908 31 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 1212 27 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 74 5 0 0
t$perf-norm:srv_make_invnt_saldo 513 513 0 0.00 0 594 12 0 0
t$perf-norm:srv_make_money_saldo 895 895 0 0.00 0 420 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 11 1670 497 0 0
v_add_invoice_to_stock 9670 9424 246 2.54 0 10229 28 0 246
v_all_customers 36539 36539 0 0.00 0 106 4 0 0
v_all_suppliers 29908 29908 0 0.00 0 208 4 0 0
v_all_wares 39846 39846 0 0.00 0 60 4 0 0
v_cancel_adding_invoice 754 754 0 0.00 0 136 13 0 0
v_cancel_client_order 3384 3384 0 0.00 0 8805 20 0 0
v_cancel_customer_prepayment 1290 1290 0 0.00 0 7549 24 0 0
v_cancel_customer_reserve 1336 1336 0 0.00 1 10145 18 0 0
v_cancel_payment_to_supplier 774 774 0 0.00 0 617 11 0 0
v_cancel_supplier_invoice 766 742 24 3.13 0 10533 37 0 24
v_cancel_supplier_order 768 768 0 0.00 0 2105 16 0 0
v_cancel_write_off 1894 1894 0 0.00 0 5247 29 0 0
v_max_id_clo_ord 5335 5335 0 0.00 0 259 5 0 0
v_max_id_clo_res 973 973 0 0.00 0 3640 17 0 0
v_max_id_ord_sup 5228 5228 0 0.00 0 7053 10 0 0
v_max_non_paid_invoice 1417 1417 0 0.00 0 344 8 0 0
v_max_non_paid_realizn 3571 3571 0 0.00 0 4728 10 0 0
v_min_id_clo_ord 5335 5335 0 0.00 0 3917 10 0 0
v_min_id_clo_res 973 973 0 0.00 0 7038 26 0 0
v_min_id_ord_sup 5228 5228 0 0.00 0 6486 13 0 0
v_min_non_paid_invoice 5810 5810 0 0.00 0 23258 22 0 0
v_min_non_paid_realizn 6862 6862 0 0.00 0 8475 22 0 0
v_our_firm 9888 9888 0 0.00 0 67 5 0 0
v_reserve_write_off 19384 19384 0 0.00 0 10587 13 0 0
x_lock_depdocs_on_canc_invoice 10296 10296 0 0.00 0 9968 120 0 0
x_lock_depdocs_on_canc_sup_ord 384 384 0 0.00 0 5941 321 0 0
x_make_qstorno_1000_1200 5338 5338 0 0.00 3 27845 1178 0 0
x_make_qstorno_1200_2000 5232 5232 0 0.00 5 110123 3122 0 0
x_make_qstorno_2100_3300 443986 443986 0 0.00 0 60322 234 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-01-2023 07:39:46 to 15-01-2023 07:39:50.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:27 49210 8192000000 9728060976 10026942464 799702848 8.893 100 90 178 0 829009328 7667536 21638944
05:10:19 24742 8192000000 9761214352 10070110208 834033872 9.240 100 89 178 0 870318064 15006960 21277232
05:11:52 7886 8192000000 9795934368 10118516736 860290224 9.503 100 75 148 0 905800512 28334160 17176128
05:13:02 7728 8192000000 9875885072 10207961088 917475200 10.071 100 83 164 0 985295632 49777568 18042864
05:14:13 4466 8192000000 9922469248 10268311552 954808560 10.438 100 76 150 1 1033601344 63135888 15656896
05:15:19 1554 8192000000 9984483968 10339762176 995039024 10.830 100 94 181 0 1097966832 84929360 17998448
05:16:23 1568 8192000000 9989381536 10353180672 1024187088 11.112 100 91 176 0 1109311184 67846128 17277968
05:17:26 907 8192000000 10003873632 10372689920 1042789040 11.291 100 95 178 1 1127143616 64366880 19987696
05:18:28 1541 8192000000 10019066624 10399412224 1049466224 11.356 100 85 163 1 1141606080 75287968 16851888
05:19:31 3143 8192000000 10035587776 10418819072 1062785728 11.483 100 82 149 0 1156418352 78315920 15316704
05:20:35 1656 8192000000 10040438704 10416447488 1066392352 11.518 100 84 163 0 1160126208 78090656 15643200
05:21:38 995 8192000000 10055998768 10440200192 1074126384 11.591 100 81 157 0 1173063056 82240224 16696448
05:22:40 962 8192000000 10053662592 10444496896 1080963248 11.657 100 86 164 0 1177440976 78669888 17807840
05:23:42 1193 8192000000 10058316768 10445520896 1082655616 11.673 100 97 184 2 1180982480 79508416 18818448
05:24:44 1039 8192000000 10043453744 10428628992 1090637904 11.749 100 97 179 2 1164035808 54803088 18594816
05:25:47 1021 8192000000 10056330608 10447138816 1090700720 11.749 100 89 168 1 1177526736 69496960 17329056
05:26:48 5000 8192000000 10071859520 10471088128 1094553984 11.786 100 72 137 0 1194277216 86719632 13003600
05:27:54 957 8192000000 10048420080 10436620288 1094134144 11.782 100 89 168 1 1170493264 58019152 18339968
05:28:56 1019 8192000000 10055533360 10447769600 1099069136 11.829 100 92 176 4 1180339136 62734496 18535504
05:30:01 1207 8192000000 10048685360 10435448832 1082121968 11.668 100 93 182 3 1168107440 67524320 18461152
05:31:03 5561 8192000000 9999911168 10385723392 1057360368 11.431 99 58 115 0 1114691664 46173072 11158224
05:32:14 1498 8192000000 9995417408 10367660032 1026057696 11.130 98 88 161 3 1118160944 75875264 16227984
05:33:16 911 8192000000 9982454544 10351853568 1021630800 11.088 99 93 180 0 1109508672 68727024 19150848
05:34:18 870 8192000000 10001999456 10375540736 1040389808 11.268 100 87 174 0 1122995328 63934944 18670576
05:35:19 1386 8192000000 9984388304 10369613824 1034823728 11.215 99 75 142 2 1107328128 57559680 14944720
05:36:22 1200 8192000000 9977330960 10350133248 1026289552 11.133 100 93 169 6 1100228544 56364736 17574256
05:37:24 1985 8192000000 9983292432 10359689216 1047417904 11.336 100 90 170 3 1104222336 39006224 17798208
05:38:34 1056 8192000000 10027012512 10407174144 1067269680 11.526 100 95 179 1 1152328816 67055072 18004064
05:39:42 5313 8192000000 10037260992 10425163776 1079294880 11.641 100 78 151 0 1161184064 65977440 15911744
05:40:54 4482 8192000000 10075652432 10460971008 1090462192 11.747 100 73 137 1 1196622816 90893696 15266928
05:42:04 11509 8192000000 10089307344 10479034368 1102970880 11.866 100 64 123 3 1202632640 86239776 13421984
05:43:22 1173 8192000000 10122868720 10519834624 1112703776 11.958 100 90 176 2 1247218176 112969968 21544432
05:44:27 3604 8192000000 10158948000 10552938496 1122335888 12.049 100 78 139 2 1272597760 135900928 14360944
05:45:34 1167 8192000000 10213386128 10609467392 1134553088 12.164 100 95 176 2 1328694816 173500624 20641104
05:46:37 1466 8192000000 10217456480 10614738944 1144295024 12.256 100 95 184 1 1325596736 162430160 18871552
05:47:46 842 8192000000 10233727776 10630967296 1144968384 12.262 100 96 182 1 1346984656 181956384 20059888
05:48:50 1291 8192000000 10265501376 10693890048 1156763840 12.373 100 99 193 1 1378481840 199811312 21906688
05:49:55 2069 8192000000 10284448224 10697207808 1157654928 12.381 100 77 149 2 1386301536 211558336 17088272
05:51:00 1177 8192000000 10312182112 10732236800 1172382240 12.519 100 92 177 1 1421392016 229014784 19994992
05:52:06 904 8192000000 10323703040 10725990400 1170909888 12.505 100 92 178 0 1435452368 244765504 19776976
05:53:17 2585 8192000000 10325324768 10750554112 1172760528 12.523 100 68 132 0 1434944528 246519344 15664656
05:54:27 4316 8192000000 10354680384 10762395648 1181034288 12.600 100 66 126 2 1453094128 257149632 14910208
05:55:40 984 8192000000 10420408288 10827800576 1189177392 12.676 100 97 189 0 1526881584 316131984 21572208
05:56:43 3313 8192000000 10422151152 10838052864 1180408336 12.594 99 66 123 1 1521922976 326758160 14756480
05:57:51 1291 8192000000 10438063072 10854416384 1180690288 12.597 100 87 164 1 1538145888 336889216 20566384
05:58:57 1011 8192000000 10496171584 10916839424 1191160352 12.694 100 78 140 0 1602549328 393378832 18010144
06:00:01 2047 8192000000 10542434976 10985725952 1190807792 12.691 100 91 182 0 1650471936 437075152 22588992
06:01:05 2253 8192000000 10525439728 10972303360 1170690864 12.503 99 67 133 0 1629945808 442480160 16774784
06:02:13 686 8192000000 10582233920 11017940992 1175315120 12.546 100 87 172 1 1685044368 488314960 21414288
06:03:18 2748 8192000000 10535539344 11047567360 1174227968 12.536 100 62 124 1 1652321696 461524160 16569568
06:04:23 5326 8192000000 10424689264 10906595328 1155186672 12.358 100 46 90 0 1529229584 362543120 11499792
06:05:30 26707 8192000000 10289241424 10752032768 1141095776 12.226 99 34 65 0 1415135536 265810368 8229392
06:06:58 27967 8192000000 10251843344 10727763968 1149231376 12.302 100 22 44 0 1372683104 218290944 5160784
06:08:29 47735 8192000000 10083997456 10596196352 1113616352 11.967 97 10 20 0 1191687984 76041568 2030064
06:10:18 8299 8192000000 10110371968 10525773824 1136092656 12.179 100 60 116 0 1204851040 56343888 12414496
06:11:29 87135 8192000000 10095146464 10518306816 1103674896 11.872 98 44 75 3 1183269344 71470512 8123936
06:13:57 104942 8192000000 10112396656 10621489152 1124103808 12.066 99 28 56 0 1193870704 64283488 5483408
06:17:34 62666 8192000000 10124139040 10622242816 1135650944 12.175 99 15 30 0 1229054480 89969152 3434384
06:20:20 61712 8192000000 10137325296 10598465536 1132546224 12.145 98 22 42 0 1216032224 78606080 4879920
06:22:31 109224 8192000000 10078254832 10540769280 1114655696 11.976 97 14 28 0 1179725120 61776176 3293248
06:25:41 44928 8192000000 10136180144 10592063488 1152744128 12.335 100 30 60 0 1236944992 77152640 7048224
06:27:30 50547 8192000000 10119271264 10615738368 1144641968 12.259 100 11 22 0 1225846240 78874992 2329280
06:29:54 25984 8192000000 10101610976 10561159168 1151809392 12.326 100 38 75 0 1193763536 34543808 7410336
06:31:26 62229 8192000000 10084435472 10574000128 1143840848 12.252 99 5 10 0 1194532160 49513280 1178032
06:33:36 34072 8192000000 10141777408 10593796096 1152952368 12.337 100 25 50 2 1228163888 68879856 6331664
06:35:11 43663 8192000000 10156689984 10632196096 1161479712 12.417 100 27 54 0 1272675984 104945376 6250896
06:37:03 50805 8192000000 10131287360 10616467456 1128200080 12.104 98 26 52 0 1235817168 102362720 5254368
06:38:55 57988 8192000000 10103461376 10543853568 1132413104 12.144 98 22 44 0 1170532016 34078576 4040336
06:41:25 23750 8192000000 10190079424 10623000576 1137317360 12.190 99 55 108 1 1264380160 115999440 11063360
06:43:15 28913 8192000000 10122925104 10558529536 1124019808 12.065 98 45 89 0 1196731840 64070240 8641792
06:44:48 26375 8192000000 10137389680 10614075392 1136443856 12.182 100 47 94 0 1249395552 103423136 9528560
06:46:28 95491 8192000000 10078374448 10523041792 1113552512 11.966 98 46 91 0 1168242384 45244912 9444960
06:49:06 15574 8192000000 10097122672 10531651584 1121414704 12.040 99 54 105 0 1178955152 46622208 10918240
06:50:26 63694 8192000000 10080846192 10555490304 1134049792 12.160 100 13 26 0 1186367056 49466816 2850448
06:52:48 76763 8192000000 10089904272 10561540096 1126536640 12.089 99 20 41 0 1187683264 57440016 3706608
06:55:07 68183 8192000000 10036469856 10508029952 1100418656 11.842 98 18 36 0 1126997440 22585776 3993008
06:57:29 21375 8192000000 10055229360 10508939264 1114646368 11.976 99 22 44 0 1154667696 35315296 4706032
06:58:52 63919 8192000000 10100300288 10543964160 1097488464 11.814 97 22 41 0 1161866176 60203824 4173888
07:00:57 84034 8192000000 10095582272 10573709312 1115158144 11.981 98 22 44 0 1199557952 79438832 4960976
07:03:27 41583 8192000000 10059111936 10485485568 1077377952 11.622 96 35 70 0 1128237680 43329472 7530256
07:05:11 29855 8192000000 10152460000 10583568384 1123296048 12.058 100 43 86 0 1229235904 97276736 8663120
07:06:50 34386 8192000000 10115324368 10547494912 1113026000 11.961 100 46 90 0 1188416160 66122448 9267712
07:08:25 9590 8192000000 10074976544 10482429952 1097652112 11.815 98 57 103 3 1153325440 45466288 10207040
07:09:35 72477 8192000000 10066833040 10507603968 1131047472 12.131 100 29 56 0 1168747936 32140736 5559728
07:11:54 42608 8192000000 10057248480 10500886528 1100017904 11.838 97 35 68 1 1168200736 61188352 6994480
07:13:37 60242 8192000000 10052656192 10539905024 1126157872 12.085 100 10 20 0 1162921664 34179248 2584544
07:16:02 61854 8192000000 10072577856 10532917248 1113314928 11.964 98 18 36 1 1141290000 24422144 3552928
07:18:05 48017 8192000000 10092201200 10534752256 1116706480 11.996 98 24 47 0 1174622000 53344304 4571216
07:19:56 13909 8192000000 10124842848 10558681088 1130484256 12.126 98 41 81 0 1215632752 76615664 8532832
07:21:13 33082 8192000000 10159308528 10605236224 1141731504 12.232 99 32 64 0 1240285600 91266752 7287344
07:22:48 38652 8192000000 10196573312 10650112000 1125252720 12.077 97 36 71 0 1255263696 121693936 8317040
07:24:37 55360 8192000000 10170003440 10626461696 1103668080 11.872 96 33 66 0 1239127696 128010960 7448656
07:26:34 58521 8192000000 10144219328 10618093568 1114825344 11.978 98 23 47 0 1221970448 102255728 4889376
07:30:07 84226 8192000000 10063340560 10528440320 1113510240 11.966 98 35 69 0 1165884048 45295504 7078304
07:32:32 55559 8192000000 10068945456 10532442112 1103645504 11.872 97 16 32 1 1160426192 53363728 3416960
07:35:29 70759 8192000000 10085070256 10538098688 1109660640 11.929 98 25 50 0 1160295728 45056304 5578784

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 15-01-2023 07:39:52 to 15-01-2023 07:40:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-01-2023 07:40:09 to 15-01-2023 07:40:11.

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          215587
MON$OLDEST_ACTIVE               217684
MON$OLDEST_SNAPSHOT             217684
MON$NEXT_TRANSACTION            217684
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                       2037920
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             770
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 ---
:66: 
:67: mscs-test-161.budzdorov.ru    Sun Jan 15 07:37:39 2023
:68:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:69: 
--- end of diff output ---

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

15.01.23 07:40:13. 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-15 07:40:15 No severe PSQL-related problems occured
15.01.23 07:40:16. End of report.