Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.01.2023 07:37
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 15168400 kB
MemAvailable 30382968 kB
Buffers 0 kB
Cached 16503244 kB
SwapCached 4408 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-09 07:37:23
DTS_END                         2023-01-09 07:37:23.0080
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/33702
TRN_ID                          218216
ATT_ID                          657
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: 9165.93 25275 1099912
customer order: creation 168.98 470 20278
customer order: refuse 14.53 401 1743
order to supplier: creation 45.65 1371 5478
order to supplier: removal 3.28 167492 393
invoice (draft): creation 44.28 3087 5314
invoice (draft): removal 50.29 968 6035
invoice accept: apply 41.39 26408 4967
invoice accept: cancel 46.08 1146 5530
customer reserve: creation 3941.80 245 473016
customer reserve: removal 4522.22 95 542666
realization accept: apply 83.29 307 9995
realization accept: cancel 76.99 168 9239
payment from customer: creation 57.33 176 6879
payment from customer: removal 5.85 135 702
payment to supplier: creation 47.88 213 5745
payment to supplier: removal 3.40 142 408
service: total inventory turnovers 4.05 4086 486
service: total monetary turnovers 7.73 336 927
service: aggregate perf. data 0.89 16648 107
service: refresh index statistics 0.03 281611 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-01-2023 07:38:20 to 09-01-2023 07:38:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7650
TEST_TIME 2 5958
TEST_TIME 3 5937
TEST_TIME 4 5300
TEST_TIME 5 5574
TEST_TIME 6 5925
TEST_TIME 7 5659
TEST_TIME 8 6042
TEST_TIME 9 6231
TEST_TIME 10 6402
TEST_TIME 11 6532
TEST_TIME 12 6542
TEST_TIME 13 7047
TEST_TIME 14 7155
TEST_TIME 15 7672
TEST_TIME 16 7842
TEST_TIME 17 7933
TEST_TIME 18 7910
TEST_TIME 19 7886
TEST_TIME 20 8109
TEST_TIME 21 8238
TEST_TIME 22 8218
TEST_TIME 23 8414
TEST_TIME 24 8571
TEST_TIME 25 8966
TEST_TIME 26 9288
TEST_TIME 27 9533
TEST_TIME 28 9631
TEST_TIME 29 10107
TEST_TIME 30 10077
TEST_TIME 31 10537
TEST_TIME 32 11237
TEST_TIME 33 11486
TEST_TIME 34 11236
TEST_TIME 35 11060
TEST_TIME 36 10893
TEST_TIME 37 10709
TEST_TIME 38 10658
TEST_TIME 39 10497
TEST_TIME 40 10299
TEST_TIME 41 10320
TEST_TIME 42 10257
TEST_TIME 43 10119
TEST_TIME 44 10289
TEST_TIME 45 10186
TEST_TIME 46 10137
TEST_TIME 47 10137
TEST_TIME 48 9985
TEST_TIME 49 10115
TEST_TIME 50 10074
TEST_TIME 51 10103
TEST_TIME 52 10127
TEST_TIME 53 10154
TEST_TIME 54 10205
TEST_TIME 55 10096
TEST_TIME 56 10122
TEST_TIME 57 10079
TEST_TIME 58 10129
TEST_TIME 59 10140
TEST_TIME 60 10078
TEST_TIME 61 10204
TEST_TIME 62 10054
TEST_TIME 63 9963
TEST_TIME 64 9888
TEST_TIME 65 9838
TEST_TIME 66 9777
TEST_TIME 67 9754
TEST_TIME 68 9691
TEST_TIME 69 9594
TEST_TIME 70 9560
TEST_TIME 71 9468
TEST_TIME 72 9400
TEST_TIME 73 9329
TEST_TIME 74 9370
TEST_TIME 75 9361
TEST_TIME 76 9348
TEST_TIME 77 9382
TEST_TIME 78 9501
TEST_TIME 79 9520
TEST_TIME 80 9501
TEST_TIME 81 9474
TEST_TIME 82 9560
TEST_TIME 83 9531
TEST_TIME 84 9587
TEST_TIME 85 9613
TEST_TIME 86 9541
TEST_TIME 87 9517
TEST_TIME 88 9440
TEST_TIME 89 9443
TEST_TIME 90 9390
TEST_TIME 91 9331
TEST_TIME 92 9293
TEST_TIME 93 9331
TEST_TIME 94 9312
TEST_TIME 95 9277
TEST_TIME 96 9256
TEST_TIME 97 9276
TEST_TIME 98 9306
TEST_TIME 99 9317
TEST_TIME 100 9336
TEST_TIME 101 9361
TEST_TIME 102 9375
TEST_TIME 103 9420
TEST_TIME 104 9405
TEST_TIME 105 9458
TEST_TIME 106 9457
TEST_TIME 107 9376
TEST_TIME 108 9365
TEST_TIME 109 9308
TEST_TIME 110 9299
TEST_TIME 111 9256
TEST_TIME 112 9214
TEST_TIME 113 9223
TEST_TIME 114 9210
TEST_TIME 115 9223
TEST_TIME 116 9215
TEST_TIME 117 9197
TEST_TIME 118 9186
TEST_TIME 119 9242
TEST_TIME 120 9193

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-01-2023 07:38:24 to 09-01-2023 07:38:27.

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 1534153 1534153 0 0.00 0 29666 14 0 0
doc_list_biud 1534143 1534143 0 0.00 0 51065 40 0 0
sp_add_invoice_to_stock 5102 4967 135 2.64 66 192898 26408 0 135
sp_cancel_adding_invoice 5530 5530 0 0.00 7 93045 1146 0 0
sp_cancel_client_order 1743 1743 0 0.00 5 11742 401 0 0
sp_cancel_customer_reserve 542666 542666 0 0.00 1 51068 96 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 3 7832 135 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 4 11143 142 0 0
sp_cancel_supplier_invoice 6051 6035 16 0.26 3 21452 969 0 16
sp_cancel_supplier_order 393 393 0 0.00 18 1507196 167492 0 0
sp_cancel_write_off 9239 9239 0 0.00 1 7957 168 0 0
sp_client_order 20278 20278 0 0.00 5 56258 471 0 0
sp_customer_reserve 473016 473016 0 0.00 1 170574 245 0 0
sp_fill_shopping_cart 31057 31057 0 0.00 1 29495 82 0 0
sp_fill_shopping_cart_clo_res 472982 472982 0 0.00 0 13867 19 0 0
sp_get_clo_for_invoice 4919 4919 0 0.00 0 37174 343 0 0
sp_kill_cost_storno 15879 15879 0 0.00 0 7822 68 0 0
sp_kill_qstorno_ret_qs2qd 1100999 1100999 0 0.00 0 51062 42 0 0
sp_kill_qty_storno 580519 580519 0 0.00 0 51065 96 0 0
sp_lock_selected_doc 20592 20592 0 0.00 0 8866 8 0 0
sp_make_cost_storno 27538 27538 0 0.00 0 29647 99 0 0
sp_multiply_rows_for_pdistr 27538 27538 0 0.00 0 8397 40 0 0
sp_multiply_rows_for_qdistr 20277 20277 0 0.00 0 43697 196 0 0
sp_pay_from_customer 6879 6879 0 0.00 5 13684 177 0 0
sp_pay_to_supplier 5745 5745 0 0.00 6 29673 214 0 0
sp_payment_common 12624 12624 0 0.00 2 8403 51 0 0
sp_qd_handle_on_cancel_clo 1743 1743 0 0.00 0 11728 335 0 0
sp_qd_handle_on_invoice_upd_sts 10449 10449 0 0.00 0 30116 507 0 0
sp_qd_handle_on_reserve_upd_sts 9994 9994 0 0.00 0 24944 69 0 0
sp_reserve_write_off 9995 9995 0 0.00 8 25008 307 0 0
sp_supplier_invoice 5314 5314 0 0.00 11 94930 3087 0 0
sp_supplier_order 5478 5478 0 0.00 6 47588 1371 0 0
srv_aggregate_perf_data 107 107 0 0.00 2883 261511 16649 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 809 201799 37998 0 0
srv_find_qd_qs_mism 1050485 1050485 0 0.00 0 10813 11 0 0
srv_make_invnt_saldo 486 486 0 0.00 49 75155 4086 0 0
srv_make_money_saldo 927 927 0 0.00 1 16280 336 0 0
srv_recalc_idx_stat 4 4 0 0.00 69316 432011 281612 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 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4970 4970 0 0.00 1 16090 941 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 5998 378 0 0
t$perf-norm:sp_cancel_client_order 1743 1743 0 0.00 0 4241 26 0 0
t$perf-norm:sp_cancel_customer_reserve 674 674 0 0.00 0 4737 39 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 11229 31 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 1599 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 605 23 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 105573 7019 0 0
t$perf-norm:sp_cancel_write_off 962 962 0 0.00 0 6182 28 0 0
t$perf-norm:sp_client_order 20278 20278 0 0.00 0 12486 34 0 0
t$perf-norm:sp_customer_reserve 968 968 0 0.00 0 1074 14 0 0
t$perf-norm:sp_pay_from_customer 6879 6879 0 0.00 0 4767 18 0 0
t$perf-norm:sp_pay_to_supplier 5745 5745 0 0.00 0 12100 20 0 0
t$perf-norm:sp_reserve_write_off 9996 9996 0 0.00 0 11218 25 0 0
t$perf-norm:sp_supplier_invoice 5314 5314 0 0.00 0 4172 28 0 0
t$perf-norm:sp_supplier_order 5478 5478 0 0.00 0 4168 27 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 352 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 100 7 0 0
t$perf-norm:srv_make_invnt_saldo 486 486 0 0.00 0 461 14 0 0
t$perf-norm:srv_make_money_saldo 927 927 0 0.00 0 264 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 83 36 0 0
v_add_invoice_to_stock 10104 9834 270 2.67 0 6632 28 0 270
v_all_customers 36576 36576 0 0.00 0 121 4 0 0
v_all_suppliers 30258 30258 0 0.00 0 123 4 0 0
v_all_wares 40548 40548 0 0.00 0 196 4 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 3647 19 0 0
v_cancel_client_order 3486 3486 0 0.00 0 1452 9 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 3649 18 0 0
v_cancel_customer_reserve 1348 1348 0 0.00 0 2843 13 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 11054 27 0 0
v_cancel_supplier_invoice 788 756 32 4.06 0 1204 20 0 32
v_cancel_supplier_order 786 786 0 0.00 0 10463 23 0 0
v_cancel_write_off 1924 1924 0 0.00 0 6302 28 0 0
v_max_id_clo_ord 5477 5477 0 0.00 0 2148 5 0 0
v_max_id_clo_res 968 968 0 0.00 0 1681 13 0 0
v_max_id_ord_sup 5306 5306 0 0.00 0 2758 7 0 0
v_max_non_paid_invoice 1399 1399 0 0.00 0 1871 10 0 0
v_max_non_paid_realizn 3683 3683 0 0.00 0 4309 8 0 0
v_min_id_clo_ord 5477 5477 0 0.00 0 6472 10 0 0
v_min_id_clo_res 968 968 0 0.00 0 1925 13 0 0
v_min_id_ord_sup 5306 5306 0 0.00 0 7251 13 0 0
v_min_non_paid_invoice 5745 5745 0 0.00 0 7788 23 0 0
v_min_non_paid_realizn 6879 6879 0 0.00 0 7508 23 0 0
v_our_firm 10359 10359 0 0.00 0 123 5 0 0
v_reserve_write_off 19988 19988 0 0.00 0 11635 12 0 0
x_lock_depdocs_on_canc_invoice 11187 11187 0 0.00 0 17843 133 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 5607 351 0 0
x_make_qstorno_1000_1200 5478 5478 0 0.00 3 47552 1207 0 0
x_make_qstorno_1200_2000 5314 5314 0 0.00 5 94687 2869 0 0
x_make_qstorno_2100_3300 473014 473014 0 0.00 0 170571 214 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 09-01-2023 07:38:28 to 09-01-2023 07:38:33.

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:27 11 8192000000 8814519888 8831627264 16858096 0.205 8 3 4 0 17103792 158736 86960
05:08:27 27548 8192000000 9714396880 10012717056 795121520 8.847 100 85 170 0 824105568 7881120 21102928
05:10:01 21359 8192000000 9761661696 10067656704 835708928 9.257 100 88 176 0 869631856 12717520 21205408
05:11:26 13973 8192000000 9802057744 10117922816 864249696 9.543 100 81 162 0 905145920 22089472 18806752
05:12:44 9205 8192000000 9846006112 10179416064 912851952 10.025 100 78 148 0 960913536 31727200 16334384
05:13:56 4031 8192000000 9903625296 10242830336 957632336 10.466 100 89 176 0 1017262688 40336544 19293808
05:15:01 2376 8192000000 9961114032 10313105408 990049376 10.782 100 89 176 0 1078515024 70088592 18377056
05:16:07 1194 8192000000 10001700368 10360193024 1013740784 11.012 100 94 185 2 1122971968 90163696 19067488
05:17:10 1573 8192000000 10011929104 10373251072 1034164896 11.209 100 93 179 1 1123646352 71760528 17720928
05:18:12 962 8192000000 10024004864 10394722304 1055726480 11.416 100 97 183 1 1148412272 74552528 18133264
05:19:14 8454 8192000000 10024994240 10399604736 1065524256 11.509 100 80 154 2 1150001552 69316816 15160480
05:20:24 1032 8192000000 10052929568 10434297856 1069029936 11.543 100 93 178 1 1172143232 85435104 17678192
05:21:26 1068 8192000000 10047457040 10433769472 1077118352 11.620 100 88 171 1 1165606000 70077072 18410576
05:22:28 1542 8192000000 10038104192 10422763520 1075767328 11.607 100 90 175 1 1160385568 67307776 17310464
05:23:31 1084 8192000000 10067449600 10452254720 1080418736 11.651 100 98 191 3 1188088688 88396784 19273168
05:24:33 1175 8192000000 10051341984 10440941568 1081908032 11.666 100 88 173 2 1173336416 75289088 16139296
05:25:35 1113 8192000000 10043950928 10430918656 1090323392 11.746 100 94 183 1 1167473312 58020080 19129840
05:26:37 1112 8192000000 10045104224 10435076096 1090538384 11.748 100 85 154 1 1170212096 63592832 16080880
05:27:39 1120 8192000000 10054344240 10443452416 1081905200 11.666 100 95 181 2 1176713664 75658720 19149744
05:28:41 1211 8192000000 10039700112 10429661184 1087222976 11.716 100 84 159 4 1162958896 58698864 17037056
05:29:44 1006 8192000000 10038527776 10425839616 1082619904 11.672 100 94 183 4 1158275056 56970848 18684304
05:30:46 946 8192000000 10047288112 10431201280 1078137472 11.630 100 96 182 0 1169646688 71621456 19887760
05:31:47 1035 8192000000 9981294816 10365292544 1056167808 11.420 100 73 137 1 1100713536 30455168 14090560
05:32:49 1779 8192000000 10029876000 10413625344 1057207744 11.430 100 74 137 2 1157497920 86625648 13664528
05:33:52 1148 8192000000 9989524176 10361356288 1044538832 11.308 100 97 184 2 1113470768 49967760 18964176
05:34:55 3690 8192000000 9978394944 10357194752 1043385584 11.297 100 67 122 2 1110992432 56642576 10964272
05:35:59 1583 8192000000 9975177296 10349469696 1020544992 11.077 99 70 127 3 1090196128 57262960 12388176
05:37:01 1311 8192000000 9972947760 10352435200 1049574416 11.357 100 76 140 2 1097013008 32779472 14659120
05:38:09 13893 8192000000 10028486160 10420084736 1063180816 11.487 100 62 119 0 1143486496 68062912 12242768
05:39:32 7158 8192000000 10041773616 10432786432 1080139648 11.649 100 61 118 1 1153757504 61120000 12497856
05:40:45 1196 8192000000 10057308480 10436460544 1090676304 11.749 99 83 148 2 1165351312 59566576 15108432
05:41:54 988 8192000000 10099089808 10484404224 1097100944 11.810 99 87 161 1 1223932512 108635200 18196368
05:42:58 9566 8192000000 10143449360 10547294208 1114369712 11.974 100 77 149 0 1281218080 150748608 16099760
05:44:13 7067 8192000000 10164980304 10569306112 1120964320 12.036 100 64 124 1 1283034432 148199696 13870416
05:45:30 1171 8192000000 10178779024 10575368192 1114750496 11.977 99 91 179 0 1296449408 161542720 20156192
05:46:33 1568 8192000000 10237569248 10631352320 1135393856 12.172 100 90 174 1 1348637984 193964752 19279376
05:47:42 1097 8192000000 10265535472 10671644672 1150571664 12.315 100 90 174 1 1379674992 207614128 21489200
05:48:46 6051 8192000000 10271372032 10672173056 1151831424 12.327 100 72 141 2 1381909504 213652608 16425472
05:49:56 800 8192000000 10323757280 10735706112 1164253584 12.443 100 97 192 0 1434499920 248332496 21913840
05:50:59 841 8192000000 10348817184 10753576960 1175937232 12.552 100 96 188 0 1461254240 263475984 21841024
05:52:02 1147 8192000000 10347827520 10793893888 1168812912 12.486 100 96 190 0 1459732528 268339088 22580528
05:53:06 2412 8192000000 10358242224 10776932352 1182616608 12.615 100 91 179 0 1471856640 268949984 20290048
05:54:12 1548 8192000000 10369573584 10794688512 1186180432 12.648 100 91 178 0 1478873808 271202928 21490448
05:55:17 1061 8192000000 10428511232 10838917120 1197507936 12.753 100 96 186 1 1538280768 318836512 21936320
05:56:21 1060 8192000000 10450593024 10873708544 1206266480 12.834 100 92 180 0 1555757328 328676976 20813872
05:57:23 871 8192000000 10484286016 10921037824 1204622368 12.819 100 97 189 1 1585988112 360072896 21292848
05:58:26 2107 8192000000 10515206832 10928168960 1194765776 12.728 100 75 148 1 1623859136 411085760 18007600
05:59:31 2515 8192000000 10553151728 10998857728 1205865168 12.831 100 79 152 2 1668546592 442895712 19785712
06:00:37 840 8192000000 10596670352 11027369984 1200744624 12.783 100 93 174 1 1702403120 478723184 22935312
06:01:40 2081 8192000000 10559632064 11023052800 1186696768 12.653 100 77 150 0 1661288256 455824048 18767440
06:02:43 8888 8192000000 10495616352 10971955200 1171095072 12.507 99 58 116 0 1618685952 432225392 15365488
06:03:54 11158 8192000000 10483907552 10949332992 1173714736 12.532 100 48 97 1 1588662240 402208768 12738736
06:05:13 20710 8192000000 10483868544 10961141760 1161792800 12.420 100 41 82 0 1580507568 407979376 10735392
06:06:36 54435 8192000000 10415607488 10865905664 1145462016 12.267 99 25 50 0 1494761424 343138720 6160688
06:08:46 44959 8192000000 10175902992 10672852992 1143985744 12.253 100 21 42 0 1273151984 124234528 4931712
06:10:40 40591 8192000000 10061587760 10488692736 1107064464 11.905 97 38 72 1 1131568960 17054240 7450256
06:12:22 120806 8192000000 10076185504 10519453696 1093530208 11.776 95 38 74 1 1134667520 33238720 7898592
06:15:32 49127 8192000000 10131677296 10570379264 1117619376 12.004 98 34 66 1 1206436832 81920880 6896576
06:17:27 104633 8192000000 10124347168 10585452544 1076699552 11.616 95 27 52 0 1167154608 84626256 5828800
06:20:13 37996 8192000000 10202688928 10656493568 1132403632 12.144 99 41 80 1 1279680512 138243824 9033056
06:21:53 25357 8192000000 10147373424 10618720256 1133998672 12.159 100 16 30 0 1253979424 116330960 3649792
06:23:20 201799 8192000000 10111554416 10628329472 1098464832 11.823 96 24 47 0 1239417952 134744848 6208272
06:27:42 68572 8192000000 10087657760 10576568320 1100619680 11.844 97 7 14 0 1167249152 65019136 1610336
06:29:51 19368 8192000000 10113535584 10541531136 1143758112 12.251 100 47 86 2 1198135584 45077792 9299680
06:31:16 66768 8192000000 10082103744 10535698432 1118955200 12.017 98 19 38 0 1183024976 59630000 4439776
06:33:24 25199 8192000000 10088000960 10564165632 1129725248 12.119 99 30 60 0 1182802784 46608176 6469360
06:34:54 100399 8192000000 10129060448 10601312256 1088300016 11.726 96 29 58 0 1161449552 67480160 5669376
06:37:35 116776 8192000000 10062272336 10534686720 1109249408 11.925 97 20 40 0 1189734656 75798944 4686304
06:41:01 61157 8192000000 10067322256 10522595328 1123584272 12.061 99 27 52 0 1169103744 40780048 4739424
06:43:05 52069 8192000000 10077893056 10554769408 1143442512 12.248 100 13 26 0 1198082832 51466544 3173776
06:44:59 35944 8192000000 10051080176 10526167040 1130595952 12.127 99 22 33 1 1158213888 24175376 3442560
06:46:35 75284 8192000000 10072411552 10552799232 1122269488 12.048 98 18 36 0 1182863488 56615760 3978240
06:50:03 44828 8192000000 10188612928 10638557184 1154480336 12.352 99 24 49 0 1256800928 96772240 5548352
06:51:49 71193 8192000000 10171805568 10623123456 1117359376 12.002 96 16 32 0 1222180352 101066128 3754848
06:54:01 48724 8192000000 10150083824 10621394944 1115677008 11.986 97 26 52 0 1240385184 118531632 6176544
06:55:51 40731 8192000000 10091083616 10554134528 1092852224 11.770 96 22 45 0 1168149936 70677216 4620496
06:57:35 20514 8192000000 10107889376 10557042688 1133392496 12.153 100 43 85 1 1224071152 81705456 8973200
06:59:01 35799 8192000000 10106500976 10564739072 1095589744 11.796 98 45 90 0 1195495824 90408464 9497616
07:00:39 11429 8192000000 10141535152 10550710272 1120024000 12.027 100 80 154 4 1223981120 88453472 15503648
07:01:52 14286 8192000000 10091149376 10522161152 1124885088 12.073 100 35 68 2 1177963776 46361600 6717088
07:03:07 81144 8192000000 10093374944 10537127936 1132606976 12.146 100 44 86 1 1196456576 54743216 9106384
07:05:29 12629 8192000000 10051135248 10488848384 1106418560 11.898 98 42 80 0 1144546208 29897568 8230080
07:06:43 57286 8192000000 10052550432 10531414016 1130945424 12.130 100 7 14 0 1167718336 35632944 1139968
07:09:10 72296 8192000000 10106575440 10594951168 1127896064 12.102 99 11 22 0 1203886400 73330688 2659648
07:13:15 9919 8192000000 10115219216 10547326976 1140243632 12.218 100 42 81 0 1215639088 66963920 8431536
07:14:28 20356 8192000000 10131837632 10574036992 1136240736 12.180 99 48 92 0 1241533984 94699984 10593264
07:15:50 64989 8192000000 10103400272 10545934336 1116573696 11.995 98 23 45 0 1177249792 56001104 4674992
07:17:59 48390 8192000000 10098601216 10573922304 1110642928 11.939 98 39 78 0 1204532960 86054800 7835232
07:19:53 40201 8192000000 10131669456 10581995520 1114562160 11.976 98 48 95 0 1214446064 90207120 9676784
07:21:47 86002 8192000000 10085150224 10550173696 1103296208 11.869 98 28 57 0 1175714800 66595200 5823392
07:24:16 116586 8192000000 10064105888 10544644096 1137099568 12.188 100 25 47 0 1179275680 37029232 5146880
07:27:12 100080 8192000000 10087173296 10582003712 1133762640 12.157 99 9 18 0 1187021488 51432768 1826080
07:29:53 69873 8192000000 10117655680 10615435264 1130571440 12.127 99 24 48 0 1215153280 78763392 5818448
07:32:03 72553 8192000000 10098873584 10554806272 1148004112 12.291 100 26 52 0 1187035632 33672912 5358608
07:34:16 13505 8192000000 10093281824 10520772608 1125381504 12.078 99 46 90 0 1171151152 36123328 9646320
07:35:33 38645 8192000000 10095703568 10564837376 1126613840 12.089 98 31 60 1 1218261040 85399472 6247728
07:37:13 20633 8192000000 8990196400 9205977088 152345872 1.825 14 4 8 0 158948656 5910928 691856

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-01-2023 07:38:35 to 09-01-2023 07:38:51.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-01-2023 07:38:52 to 09-01-2023 07:38:54.

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          218559
MON$OLDEST_ACTIVE               218560
MON$OLDEST_SNAPSHOT             218560
MON$NEXT_TRANSACTION            218560
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                       2048928
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             771
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2887 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2887 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2887 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2887 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 ---
--- end of diff output ---

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

09.01.23 07:38:55. 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-09 07:38:58 No severe PSQL-related problems occured
09.01.23 07:38:59. End of report.