Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.08.2022 04:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15692636 kB
MemAvailable 30949496 kB
Buffers 20 kB
Cached 15939620 kB
SwapCached 2512 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.2
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 2022-08-01 04:37:20
DTS_END                         2022-08-01 04:37:20.5810
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/58462
TRN_ID                          213145
ATT_ID                          603
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: 8571.33 35042 1028559
customer order: creation 154.88 448 18585
customer order: refuse 13.72 456 1646
order to supplier: creation 42.09 1295 5051
order to supplier: removal 3.18 178558 381
invoice (draft): creation 42.47 3144 5096
invoice (draft): removal 49.03 1016 5883
invoice accept: apply 38.25 28646 4590
invoice accept: cancel 43.98 1193 5277
customer reserve: creation 3585.22 273 430226
customer reserve: removal 4326.19 101 519143
realization accept: apply 75.75 279 9090
realization accept: cancel 74.95 182 8994
payment from customer: creation 56.40 169 6768
payment from customer: removal 5.20 151 624
payment to supplier: creation 44.78 192 5374
payment to supplier: removal 3.45 113 414
service: total inventory turnovers 3.73 4229 448
service: total monetary turnovers 7.10 367 852
service: aggregate perf. data 0.94 12731 113
service: refresh index statistics 0.03 467292 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-08-2022 04:38:36 to 01-08-2022 04:38:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4796
TEST_TIME 2 5244
TEST_TIME 3 4638
TEST_TIME 4 4753
TEST_TIME 5 5194
TEST_TIME 6 5383
TEST_TIME 7 5148
TEST_TIME 8 5419
TEST_TIME 9 5709
TEST_TIME 10 5650
TEST_TIME 11 5775
TEST_TIME 12 6194
TEST_TIME 13 6559
TEST_TIME 14 6720
TEST_TIME 15 6947
TEST_TIME 16 6891
TEST_TIME 17 7116
TEST_TIME 18 7330
TEST_TIME 19 7427
TEST_TIME 20 7639
TEST_TIME 21 7548
TEST_TIME 22 7526
TEST_TIME 23 7523
TEST_TIME 24 7833
TEST_TIME 25 8129
TEST_TIME 26 8098
TEST_TIME 27 8458
TEST_TIME 28 8614
TEST_TIME 29 8899
TEST_TIME 30 8963
TEST_TIME 31 9417
TEST_TIME 32 10107
TEST_TIME 33 10528
TEST_TIME 34 11013
TEST_TIME 35 11128
TEST_TIME 36 11015
TEST_TIME 37 10971
TEST_TIME 38 10762
TEST_TIME 39 10592
TEST_TIME 40 10413
TEST_TIME 41 10338
TEST_TIME 42 10317
TEST_TIME 43 10141
TEST_TIME 44 10030
TEST_TIME 45 9946
TEST_TIME 46 9783
TEST_TIME 47 9662
TEST_TIME 48 9733
TEST_TIME 49 9667
TEST_TIME 50 9565
TEST_TIME 51 9533
TEST_TIME 52 9463
TEST_TIME 53 9460
TEST_TIME 54 9362
TEST_TIME 55 9430
TEST_TIME 56 9471
TEST_TIME 57 9443
TEST_TIME 58 9461
TEST_TIME 59 9372
TEST_TIME 60 9326
TEST_TIME 61 9344
TEST_TIME 62 9274
TEST_TIME 63 9212
TEST_TIME 64 9102
TEST_TIME 65 9022
TEST_TIME 66 8975
TEST_TIME 67 8991
TEST_TIME 68 8932
TEST_TIME 69 8889
TEST_TIME 70 8798
TEST_TIME 71 8752
TEST_TIME 72 8808
TEST_TIME 73 8753
TEST_TIME 74 8847
TEST_TIME 75 8813
TEST_TIME 76 8805
TEST_TIME 77 8843
TEST_TIME 78 8813
TEST_TIME 79 8816
TEST_TIME 80 8850
TEST_TIME 81 8850
TEST_TIME 82 8920
TEST_TIME 83 8965
TEST_TIME 84 8941
TEST_TIME 85 8947
TEST_TIME 86 8953
TEST_TIME 87 8854
TEST_TIME 88 8929
TEST_TIME 89 8863
TEST_TIME 90 8892
TEST_TIME 91 8820
TEST_TIME 92 8791
TEST_TIME 93 8752
TEST_TIME 94 8683
TEST_TIME 95 8727
TEST_TIME 96 8698
TEST_TIME 97 8715
TEST_TIME 98 8769
TEST_TIME 99 8772
TEST_TIME 100 8764
TEST_TIME 101 8740
TEST_TIME 102 8839
TEST_TIME 103 8804
TEST_TIME 104 8800
TEST_TIME 105 8799
TEST_TIME 106 8862
TEST_TIME 107 8889
TEST_TIME 108 8883
TEST_TIME 109 8858
TEST_TIME 110 8791
TEST_TIME 111 8743
TEST_TIME 112 8730
TEST_TIME 113 8698
TEST_TIME 114 8694
TEST_TIME 115 8661
TEST_TIME 116 8646
TEST_TIME 117 8608
TEST_TIME 118 8624
TEST_TIME 119 8639
TEST_TIME 120 8590

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-08-2022 04:38:39 to 01-08-2022 04:38:42.

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 1424405 1424405 0 0.00 0 53717 14 0 0
doc_list_biud 1424390 1424390 0 0.00 0 53353 43 0 0
sp_add_invoice_to_stock 4713 4590 123 2.60 61 234032 28647 0 123
sp_cancel_adding_invoice 5277 5277 0 0.00 5 94911 1194 0 0
sp_cancel_client_order 1646 1646 0 0.00 6 22484 457 0 0
sp_cancel_customer_reserve 519143 519143 0 0.00 1 53394 102 0 0
sp_cancel_pay_from_customer 624 624 0 0.00 3 9671 151 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 4 3130 114 0 0
sp_cancel_supplier_invoice 5894 5883 11 0.18 1 22178 1016 0 11
sp_cancel_supplier_order 381 381 0 0.00 13 1641606 178558 0 0
sp_cancel_write_off 8994 8994 0 0.00 2 8928 183 0 0
sp_client_order 18585 18585 0 0.00 5 52814 448 0 0
sp_customer_reserve 430226 430226 0 0.00 0 109839 273 0 0
sp_fill_shopping_cart 28720 28720 0 0.00 1 14406 79 0 0
sp_fill_shopping_cart_clo_res 430194 430194 0 0.00 0 19863 18 0 0
sp_get_clo_for_invoice 4551 4551 0 0.00 0 24988 284 0 0
sp_kill_cost_storno 15309 15309 0 0.00 0 53706 90 0 0
sp_kill_qstorno_ret_qs2qd 1053544 1053544 0 0.00 0 53300 44 0 0
sp_kill_qty_storno 554962 554962 0 0.00 0 53348 101 0 0
sp_lock_selected_doc 19041 19041 0 0.00 0 7844 7 0 0
sp_make_cost_storno 25783 25783 0 0.00 0 53077 83 0 0
sp_multiply_rows_for_pdistr 25781 25781 0 0.00 0 13831 36 0 0
sp_multiply_rows_for_qdistr 18585 18585 0 0.00 0 12024 169 0 0
sp_pay_from_customer 6768 6768 0 0.00 5 12840 169 0 0
sp_pay_to_supplier 5374 5374 0 0.00 6 22845 193 0 0
sp_payment_common 12142 12142 0 0.00 2 8077 47 0 0
sp_qd_handle_on_cancel_clo 1646 1646 0 0.00 0 22475 366 0 0
sp_qd_handle_on_invoice_upd_sts 9827 9827 0 0.00 0 26128 527 0 0
sp_qd_handle_on_reserve_upd_sts 9088 9088 0 0.00 0 36225 73 0 0
sp_reserve_write_off 9090 9090 0 0.00 9 36398 280 0 0
sp_supplier_invoice 5096 5096 0 0.00 9 92429 3144 0 0
sp_supplier_order 5051 5051 0 0.00 8 62236 1295 0 0
srv_aggregate_perf_data 113 113 0 0.00 2263 158818 12731 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 688 166767 37590 0 0
srv_find_qd_qs_mism 983104 983104 0 0.00 0 13618 11 0 0
srv_make_invnt_saldo 448 448 0 0.00 82 118813 4229 0 0
srv_make_money_saldo 852 852 0 0.00 1 10026 367 0 0
srv_recalc_idx_stat 4 4 0 0.00 247671 636203 467292 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 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4592 4592 0 0.00 1 33830 936 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 1 6382 403 0 0
t$perf-norm:sp_cancel_client_order 1646 1646 0 0.00 0 1633 29 0 0
t$perf-norm:sp_cancel_customer_reserve 638 638 0 0.00 0 869 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 624 624 0 0.00 0 404 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 218 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 3632 38 0 0
t$perf-norm:sp_cancel_supplier_order 381 381 0 0.00 0 98059 7542 0 0
t$perf-norm:sp_cancel_write_off 931 931 0 0.00 0 3423 22 0 0
t$perf-norm:sp_client_order 18586 18586 0 0.00 0 16306 36 0 0
t$perf-norm:sp_customer_reserve 984 984 0 0.00 0 1008 13 0 0
t$perf-norm:sp_pay_from_customer 6768 6768 0 0.00 0 7949 21 0 0
t$perf-norm:sp_pay_to_supplier 5374 5374 0 0.00 0 6258 20 0 0
t$perf-norm:sp_reserve_write_off 9090 9090 0 0.00 0 20130 23 0 0
t$perf-norm:sp_supplier_invoice 5096 5096 0 0.00 0 6182 30 0 0
t$perf-norm:sp_supplier_order 5051 5051 0 0.00 0 5986 31 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 603 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 293 8 0 0
t$perf-norm:srv_make_invnt_saldo 448 448 0 0.00 0 23271 79 0 0
t$perf-norm:srv_make_money_saldo 852 852 0 0.00 0 639 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 207 69 0 0
v_add_invoice_to_stock 9338 9092 246 2.63 0 5562 26 0 246
v_all_customers 34384 34384 0 0.00 0 81 3 0 0
v_all_suppliers 28360 28360 0 0.00 0 74 4 0 0
v_all_wares 37163 37163 0 0.00 0 54 4 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 4876 26 0 0
v_cancel_client_order 3292 3292 0 0.00 0 5975 15 0 0
v_cancel_customer_prepayment 1248 1248 0 0.00 0 4070 23 0 0
v_cancel_customer_reserve 1276 1276 0 0.00 0 4885 20 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 2628 13 0 0
v_cancel_supplier_invoice 800 778 22 2.75 0 4844 27 0 22
v_cancel_supplier_order 762 762 0 0.00 0 1099 12 0 0
v_cancel_write_off 1862 1862 0 0.00 0 1082 21 0 0
v_max_id_clo_ord 5049 5049 0 0.00 0 4083 6 0 0
v_max_id_clo_res 984 984 0 0.00 0 2148 15 0 0
v_max_id_ord_sup 5086 5086 0 0.00 0 3336 6 0 0
v_max_non_paid_invoice 1327 1327 0 0.00 0 157 8 0 0
v_max_non_paid_realizn 3454 3454 0 0.00 0 272 7 0 0
v_min_id_clo_ord 5049 5049 0 0.00 0 2998 8 0 0
v_min_id_clo_res 984 984 0 0.00 0 6879 20 0 0
v_min_id_ord_sup 5086 5086 0 0.00 0 11641 12 0 0
v_min_non_paid_invoice 5374 5374 0 0.00 0 6918 18 0 0
v_min_non_paid_realizn 6768 6768 0 0.00 0 7388 21 0 0
v_our_firm 9406 9406 0 0.00 0 91 4 0 0
v_reserve_write_off 18174 18174 0 0.00 0 5946 13 0 0
x_lock_depdocs_on_canc_invoice 10771 10771 0 0.00 0 13192 128 0 0
x_lock_depdocs_on_canc_sup_ord 381 381 0 0.00 0 8229 402 0 0
x_make_qstorno_1000_1200 5051 5051 0 0.00 2 62200 1138 0 0
x_make_qstorno_1200_2000 5096 5096 0 0.00 3 92415 2925 0 0
x_make_qstorno_2100_3300 430226 430226 0 0.00 0 109837 242 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-08-2022 04:38:43 to 01-08-2022 04:38:47.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:24 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:25 33400 8192000000 9739425936 10040168448 816018064 9.058 100 96 191 0 847444960 8756144 22670752
02:10:01 20356 8192000000 9775610016 10087686144 850270960 9.403 100 94 188 0 886472208 14035536 22165712
02:11:25 15801 8192000000 9813750352 10133733376 875265968 9.653 100 82 164 0 918805328 24771808 18767552
02:12:42 8964 8192000000 9880795520 10215174144 924470592 10.140 100 82 164 0 990614640 48332592 17811456
02:13:55 4863 8192000000 9909927920 10256670720 964945808 10.537 100 79 156 1 1025960896 44472128 16542960
02:15:02 1575 8192000000 9957694656 10314584064 996053968 10.840 100 82 158 0 1081517232 69215120 16248144
02:16:06 1415 8192000000 10006500352 10366988288 1024244624 11.113 100 95 188 3 1128090016 85442224 18403168
02:17:10 947 8192000000 10009009248 10377928704 1047488768 11.337 100 94 181 1 1131876784 66067776 18320240
02:18:12 1125 8192000000 10023615024 10398142464 1057013872 11.428 100 94 175 0 1143015232 68103120 17898240
02:19:15 1051 8192000000 10022646048 10402357248 1064510528 11.500 100 96 177 2 1143735952 60610656 18614768
02:20:17 1627 8192000000 10037898832 10427129856 1069483536 11.547 100 85 170 0 1159700160 73245744 16970880
02:21:20 2533 8192000000 10040359424 10430840832 1076205952 11.611 100 76 151 0 1163618880 71994912 15418016
02:22:25 4711 8192000000 10042792912 10428813312 1083031184 11.676 100 78 152 1 1176040272 77341840 15667248
02:23:32 10179 8192000000 10053947808 10453618688 1085199904 11.697 100 68 125 1 1175078912 75821264 14057744
02:24:42 921 8192000000 10056599872 10448429056 1090909776 11.751 100 90 174 2 1179503840 69323088 19270976
02:25:44 911 8192000000 10058775408 10448220160 1093103296 11.772 100 93 179 1 1184326416 70834160 20388960
02:26:46 1082 8192000000 10048174032 10439233536 1093476960 11.776 100 95 174 2 1173350016 61561568 18311488
02:27:48 951 8192000000 10061630128 10450509824 1098330224 11.822 100 92 184 0 1180865312 63182384 19352704
02:28:50 1271 8192000000 10078889088 10467962880 1092689488 11.768 100 92 169 0 1198331312 89767840 15873984
02:29:52 854 8192000000 10046856096 10431918080 1085609776 11.701 100 87 168 0 1164813328 61196336 18007216
02:30:53 978 8192000000 10022895856 10408095744 1083275456 11.679 100 97 187 2 1146335440 43326672 19733312
02:31:55 2952 8192000000 9987176288 10369822720 1039938320 11.264 98 72 131 2 1106113360 51736544 14438496
02:32:58 5740 8192000000 9957928032 10332917760 1014903280 11.023 97 67 122 2 1077326432 49557648 12865504
02:34:05 801 8192000000 9997013568 10374819840 1034290496 11.210 99 72 137 0 1115705296 65814608 15600192
02:35:06 5102 8192000000 9979546528 10358472704 1044705120 11.310 100 76 138 2 1104607952 45201648 14701184
02:36:14 1917 8192000000 9984135824 10367217664 1044208208 11.305 100 72 136 3 1107558192 48950448 14399536
02:37:16 5550 8192000000 9985610336 10367819776 1056347808 11.422 100 72 136 1 1113627152 42649520 14629824
02:38:29 1925 8192000000 10023433760 10401333248 1066566128 11.519 100 89 162 5 1128876960 45938512 16372320
02:40:00 1398 8192000000 10051125152 10433675264 1087984976 11.723 100 89 155 2 1169489664 65526192 15978496
02:41:04 1211 8192000000 10102646096 10485944320 1083330592 11.679 99 95 173 1 1221533904 118920320 19282992
02:42:20 1161 8192000000 10114550928 10495496192 1095404512 11.794 100 88 170 1 1232134128 119339664 17389952
02:43:25 5431 8192000000 10138371696 10537656320 1109013504 11.923 100 69 139 1 1263856256 139153664 15689088
02:44:37 6732 8192000000 10179567616 10574589952 1117674864 12.005 100 62 118 0 1291407504 160467808 13264832
02:45:49 2980 8192000000 10219807024 10619326464 1131613872 12.137 100 79 151 1 1330546848 182769216 16163760
02:46:57 5524 8192000000 10269354816 10677473280 1150784048 12.317 100 75 150 0 1382369680 214654304 16931328
02:48:07 2827 8192000000 10311336896 10712412160 1161874912 12.421 100 77 152 1 1424296912 244783536 17638464
02:49:15 864 8192000000 10349179040 10755604480 1181726944 12.606 100 91 176 2 1462766416 259953760 21085712
02:50:18 679 8192000000 10387706544 10809057280 1188171152 12.666 100 96 188 2 1490485824 280960160 21354512
02:51:23 741 8192000000 10383664656 10787819520 1188863552 12.673 100 85 164 1 1493148224 286056480 18228192
02:52:26 984 8192000000 10416723456 10837135360 1202729600 12.802 100 93 178 0 1526866848 302504768 21632480
02:53:31 1941 8192000000 10431170784 10858692608 1207084304 12.842 100 84 156 0 1537469600 312176000 18209296
02:54:36 878 8192000000 10477434848 10914131968 1188293600 12.667 100 98 193 0 1584664912 374059392 22311920
02:55:40 868 8192000000 10475695664 10898079744 1200276928 12.779 100 94 181 0 1581502784 358849872 22375984
02:56:43 850 8192000000 10536429216 10967027712 1216816736 12.932 100 97 195 0 1643442512 402966720 23659056
02:57:47 1745 8192000000 10583965616 11015483392 1218012048 12.943 100 94 187 2 1687009392 445100080 23897264
02:58:57 1119 8192000000 10582531008 11010486272 1213133936 12.898 100 86 169 0 1688323024 454069152 21119936
03:00:02 921 8192000000 10594645424 11034947584 1198527792 12.763 100 85 169 2 1698258576 478947376 20783408
03:01:08 1428 8192000000 10593365728 11067650048 1194229856 12.723 100 81 163 0 1698855584 484427440 20198288
03:02:16 5209 8192000000 10639019984 11091693568 1198540768 12.763 100 66 126 1 1740279744 525085008 16653968
03:03:26 8095 8192000000 10621519168 11057848320 1194995824 12.730 100 46 92 0 1726090848 519056576 12038448
03:04:36 8674 8192000000 10602616848 11075162112 1181832240 12.607 99 56 110 5 1696519472 500439296 14247936
03:05:46 6389 8192000000 10588576864 11036942336 1177900080 12.571 100 49 91 2 1688394464 498775952 11718432
03:07:02 10805 8192000000 10531735264 10981928960 1164180624 12.442 100 48 98 0 1639899056 462603472 13114960
03:08:15 21816 8192000000 10388654832 10940129280 1138897504 12.205 98 37 74 1 1573724128 425157760 9668864
03:09:38 15636 8192000000 10295327856 10751516672 1116053616 11.990 98 36 70 0 1386679904 262222224 8404064
03:10:59 59009 8192000000 10103866176 10542342144 1094416880 11.785 96 21 39 1 1196338240 97550992 4370368
03:12:59 34888 8192000000 10120106176 10535481344 1137242400 12.190 100 60 105 1 1210007744 62835888 9929456
03:14:35 74833 8192000000 10098671360 10580328448 1135895056 12.177 100 40 78 0 1195641504 51198976 8547472
03:17:07 166767 8192000000 10078014624 10602328064 1114123056 11.971 97 11 22 0 1160907088 44591088 2192944
03:20:54 73140 8192000000 10119860592 10572378112 1123854944 12.063 97 20 36 1 1198199056 71086800 3257312
03:23:07 123834 8192000000 10114137376 10597216256 1123612208 12.061 98 15 30 0 1223572368 96571936 3388224
03:26:21 63260 8192000000 10075486544 10539724800 1127628400 12.099 98 11 21 0 1158011536 28135072 2248064
03:28:27 34160 8192000000 10097607664 10536964096 1135362944 12.172 99 31 61 0 1179936400 38296224 6277232
03:30:03 75477 8192000000 10098030064 10558160896 1097527296 11.814 96 26 51 0 1174119568 71295440 5296832
03:32:21 76099 8192000000 10125329264 10572943360 1111576320 11.947 98 26 52 0 1178152528 61354192 5222016
03:34:37 13752 8192000000 10097390272 10533580800 1152308560 12.331 100 48 87 0 1180626688 19254912 9063216
03:35:51 42193 8192000000 10100230624 10553753600 1108794720 11.921 96 30 58 1 1159684928 45356176 5534032
03:37:36 23651 8192000000 10135461664 10580205568 1146694352 12.278 100 45 86 1 1213046528 57793152 8559024
03:39:04 114403 8192000000 10085095216 10541486080 1090169200 11.744 95 31 61 0 1143881088 47131696 6580192
03:42:34 32097 8192000000 10105420400 10542510080 1115612272 11.986 98 48 89 0 1196244848 71444192 9188384
03:44:07 29723 8192000000 10093195056 10574221312 1136561664 12.183 100 34 67 1 1200697360 57534432 6601264
03:45:37 88177 8192000000 10081376384 10547294208 1108437968 11.918 97 10 20 0 1158011664 47543120 2030576
03:48:24 76617 8192000000 10155520576 10648715264 1144902976 12.262 100 20 38 0 1262892176 113898608 4090592
03:50:42 99786 8192000000 10082390864 10543169536 1142912304 12.243 99 17 34 0 1209601184 63498608 3190272
03:53:22 46922 8192000000 10101787264 10550243328 1104894896 11.884 96 22 44 0 1170315136 61028992 4391248
03:55:09 30274 8192000000 10112377344 10552283136 1138307344 12.200 100 25 49 0 1207487024 64116992 5062688
03:56:40 30237 8192000000 10150051120 10587488256 1131204640 12.133 99 34 68 0 1216663984 78475552 6983792
03:58:25 16652 8192000000 10138713712 10563657728 1125032688 12.075 99 39 74 0 1213931552 80357328 8541536
03:59:42 27052 8192000000 10129992144 10569424896 1114235408 11.972 98 48 96 0 1201996640 78441568 9319664
04:01:35 7929 8192000000 10099284368 10518917120 1128998944 12.112 100 64 128 0 1187835312 45831536 13004832
04:02:45 46103 8192000000 10174763616 10599075840 1115371984 11.983 99 47 90 0 1204571072 80029360 9169728
04:04:36 59548 8192000000 10075560768 10521370624 1111313648 11.945 99 40 78 1 1239391568 118745920 9332000
04:06:44 3405 8192000000 10084676880 10513440768 1126482032 12.088 100 67 133 0 1187329120 46353136 14493952
04:07:48 79655 8192000000 10090560912 10565894144 1123466320 12.060 99 9 18 0 1192523712 67144832 1912560
04:10:12 68463 8192000000 10101400688 10549116928 1101815136 11.855 98 13 24 0 1165365600 60652000 2898464
04:12:21 73281 8192000000 10104164176 10563461120 1127392976 12.097 100 11 22 0 1208607040 78461056 2753008
04:14:39 110503 8192000000 10067298976 10542768128 1090684176 11.749 96 18 36 0 1181319168 86885712 3749280
04:17:30 47386 8192000000 10121040560 10588971008 1140652256 12.222 100 32 62 0 1185553680 39018336 5883088
04:19:17 82430 8192000000 10079140528 10533888000 1110623360 11.938 97 23 43 1 1172623232 57578736 4421136
04:21:43 48354 8192000000 10148446144 10588057600 1129963472 12.121 99 25 48 1 1230548272 95718880 4865920
04:23:34 99244 8192000000 10107760496 10577686528 1131290848 12.134 98 24 48 0 1177862576 41485232 5086496
04:26:33 105071 8192000000 10096035264 10545324032 1130711776 12.128 99 39 70 2 1182729712 44215312 7802624
04:29:36 28169 8192000000 10086319632 10563162112 1127195728 12.095 99 7 12 0 1182685184 53786608 1702848
04:31:06 61123 8192000000 10082083504 10570694656 1121865952 12.045 98 11 22 0 1181510608 57181568 2463088
04:33:12 35071 8192000000 10081467600 10575155200 1133716144 12.156 100 9 16 1 1197687008 62098576 1872288
04:35:15 97898 8192000000 10096689232 10578653184 1135937776 12.177 99 25 50 0 1199931520 58495552 5498192

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 01-08-2022 04:38:49 to 01-08-2022 04:39:05.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-08-2022 04:39:06 to 01-08-2022 04:39:08.

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          210899
MON$OLDEST_ACTIVE               213287
MON$OLDEST_SNAPSHOT             213287
MON$NEXT_TRANSACTION            213287
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                       2062816
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             760
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2811 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2811 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2811 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2811 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 ---
:373: 
:374: mscs-test-161.budzdorov.ru    Mon Aug  1 04:37:25 2022
:375:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:376: 
--- end of diff output ---

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

01.08.22 04:39:09. 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
2022-08-01 04:39:12 No severe PSQL-related problems occured
01.08.22 04:39:13. End of report.