Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.04.2022 13: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 16296296 kB
MemAvailable 31548192 kB
Buffers 0 kB
Cached 15439784 kB
SwapCached 2688 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-04-15 13:37:11
DTS_END                         2022-04-15 13:37:11.2740
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/52672
TRN_ID                          217280
ATT_ID                          592
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: 9018.42 29821 1082210
customer order: creation 164.13 498 19695
customer order: refuse 14.34 482 1721
order to supplier: creation 45.26 1523 5431
order to supplier: removal 3.31 183461 397
invoice (draft): creation 45.46 3526 5455
invoice (draft): removal 50.19 1116 6023
invoice accept: apply 41.50 29863 4980
invoice accept: cancel 45.61 1387 5473
customer reserve: creation 3846.24 285 461549
customer reserve: removal 4475.02 106 537002
realization accept: apply 80.91 311 9709
realization accept: cancel 77.88 186 9345
payment from customer: creation 57.47 184 6896
payment from customer: removal 5.38 153 645
payment to supplier: creation 48.40 230 5808
payment to supplier: removal 3.65 182 438
service: total inventory turnovers 4.03 4280 483
service: total monetary turnovers 8.62 367 1034
service: aggregate perf. data 1.01 16102 121
service: refresh index statistics 0.04 352176 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-04-2022 13:40:02 to 15-04-2022 13:40:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4819
TEST_TIME 2 4617
TEST_TIME 3 5362
TEST_TIME 4 5167
TEST_TIME 5 5238
TEST_TIME 6 5432
TEST_TIME 7 5614
TEST_TIME 8 6101
TEST_TIME 9 6197
TEST_TIME 10 6072
TEST_TIME 11 6619
TEST_TIME 12 6765
TEST_TIME 13 6845
TEST_TIME 14 6977
TEST_TIME 15 7101
TEST_TIME 16 7319
TEST_TIME 17 7580
TEST_TIME 18 7612
TEST_TIME 19 7561
TEST_TIME 20 7670
TEST_TIME 21 7734
TEST_TIME 22 7988
TEST_TIME 23 7997
TEST_TIME 24 8161
TEST_TIME 25 8154
TEST_TIME 26 8198
TEST_TIME 27 8091
TEST_TIME 28 8492
TEST_TIME 29 8830
TEST_TIME 30 8976
TEST_TIME 31 9374
TEST_TIME 32 9690
TEST_TIME 33 9689
TEST_TIME 34 10015
TEST_TIME 35 10271
TEST_TIME 36 10515
TEST_TIME 37 10348
TEST_TIME 38 10711
TEST_TIME 39 10649
TEST_TIME 40 10717
TEST_TIME 41 10593
TEST_TIME 42 10537
TEST_TIME 43 10372
TEST_TIME 44 10296
TEST_TIME 45 10074
TEST_TIME 46 10009
TEST_TIME 47 10069
TEST_TIME 48 9931
TEST_TIME 49 9893
TEST_TIME 50 9838
TEST_TIME 51 9782
TEST_TIME 52 9813
TEST_TIME 53 9715
TEST_TIME 54 9645
TEST_TIME 55 9572
TEST_TIME 56 9486
TEST_TIME 57 9438
TEST_TIME 58 9548
TEST_TIME 59 9579
TEST_TIME 60 9598
TEST_TIME 61 9559
TEST_TIME 62 9854
TEST_TIME 63 9847
TEST_TIME 64 9794
TEST_TIME 65 9757
TEST_TIME 66 9796
TEST_TIME 67 9783
TEST_TIME 68 9811
TEST_TIME 69 9786
TEST_TIME 70 9812
TEST_TIME 71 9849
TEST_TIME 72 9776
TEST_TIME 73 9662
TEST_TIME 74 9623
TEST_TIME 75 9566
TEST_TIME 76 9512
TEST_TIME 77 9559
TEST_TIME 78 9523
TEST_TIME 79 9450
TEST_TIME 80 9379
TEST_TIME 81 9343
TEST_TIME 82 9301
TEST_TIME 83 9352
TEST_TIME 84 9347
TEST_TIME 85 9336
TEST_TIME 86 9313
TEST_TIME 87 9331
TEST_TIME 88 9343
TEST_TIME 89 9328
TEST_TIME 90 9311
TEST_TIME 91 9353
TEST_TIME 92 9350
TEST_TIME 93 9422
TEST_TIME 94 9414
TEST_TIME 95 9339
TEST_TIME 96 9347
TEST_TIME 97 9284
TEST_TIME 98 9256
TEST_TIME 99 9258
TEST_TIME 100 9215
TEST_TIME 101 9172
TEST_TIME 102 9151
TEST_TIME 103 9166
TEST_TIME 104 9143
TEST_TIME 105 9123
TEST_TIME 106 9103
TEST_TIME 107 9100
TEST_TIME 108 9090
TEST_TIME 109 9100
TEST_TIME 110 9091
TEST_TIME 111 9108
TEST_TIME 112 9146
TEST_TIME 113 9245
TEST_TIME 114 9227
TEST_TIME 115 9218
TEST_TIME 116 9162
TEST_TIME 117 9120
TEST_TIME 118 9130
TEST_TIME 119 9101
TEST_TIME 120 9035

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-04-2022 13:40:05 to 15-04-2022 13:40:09.

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 1505663 1505663 0 0.00 0 28605 16 0 0
doc_list_biud 1505657 1505657 0 0.00 0 29679 44 0 0
sp_add_invoice_to_stock 5086 4980 106 2.08 68 268656 29863 0 106
sp_cancel_adding_invoice 5473 5473 0 0.00 7 105214 1388 0 0
sp_cancel_client_order 1721 1721 0 0.00 6 12694 483 0 0
sp_cancel_customer_reserve 537002 537002 0 0.00 1 29684 106 0 0
sp_cancel_pay_from_customer 645 645 0 0.00 3 7336 153 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 4 9733 182 0 0
sp_cancel_supplier_invoice 6036 6023 13 0.21 3 26789 1117 0 13
sp_cancel_supplier_order 397 397 0 0.00 11 1537866 183461 0 0
sp_cancel_write_off 9345 9345 0 0.00 2 9166 186 0 0
sp_client_order 19695 19695 0 0.00 6 34608 499 0 0
sp_customer_reserve 461549 461549 0 0.00 0 51078 285 0 0
sp_fill_shopping_cart 30568 30568 0 0.00 2 24354 92 0 0
sp_fill_shopping_cart_clo_res 461523 461523 0 0.00 0 23176 22 0 0
sp_get_clo_for_invoice 4950 4950 0 0.00 0 18571 279 0 0
sp_kill_cost_storno 15901 15901 0 0.00 0 19975 82 0 0
sp_kill_qstorno_ret_qs2qd 1089769 1089769 0 0.00 0 29669 46 0 0
sp_kill_qty_storno 574620 574620 0 0.00 0 29679 107 0 0
sp_lock_selected_doc 20313 20313 0 0.00 0 9231 9 0 0
sp_make_cost_storno 27363 27363 0 0.00 0 25311 93 0 0
sp_multiply_rows_for_pdistr 27363 27363 0 0.00 0 6984 42 0 0
sp_multiply_rows_for_qdistr 19694 19694 0 0.00 0 18329 181 0 0
sp_pay_from_customer 6896 6896 0 0.00 6 12311 184 0 0
sp_pay_to_supplier 5808 5808 0 0.00 6 19005 231 0 0
sp_payment_common 12704 12704 0 0.00 2 8393 49 0 0
sp_qd_handle_on_cancel_clo 1721 1721 0 0.00 1 12664 399 0 0
sp_qd_handle_on_invoice_upd_sts 10423 10423 0 0.00 0 24367 573 0 0
sp_qd_handle_on_reserve_upd_sts 9709 9709 0 0.00 0 24155 69 0 0
sp_reserve_write_off 9709 9709 0 0.00 8 24212 311 0 0
sp_supplier_invoice 5455 5455 0 0.00 8 99734 3526 0 0
sp_supplier_order 5431 5431 0 0.00 7 33390 1523 0 0
srv_aggregate_perf_data 121 121 0 0.00 2856 157752 16103 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 784 137414 31652 0 0
srv_find_qd_qs_mism 1033263 1033263 0 0.00 0 22883 13 0 0
srv_make_invnt_saldo 483 483 0 0.00 69 68138 4281 0 0
srv_make_money_saldo 1034 1034 0 0.00 1 16220 367 0 0
srv_recalc_idx_stat 5 5 0 0.00 257737 405645 352176 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4980 4980 0 0.00 1 21199 1080 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 2 9076 549 0 0
t$perf-norm:sp_cancel_client_order 1721 1721 0 0.00 0 3628 29 0 0
t$perf-norm:sp_cancel_customer_reserve 669 669 0 0.00 0 3982 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 645 645 0 0.00 0 695 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 810 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 4811 57 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 128974 8107 0 0
t$perf-norm:sp_cancel_write_off 973 973 0 0.00 0 2805 21 0 0
t$perf-norm:sp_client_order 19697 19697 0 0.00 0 5571 39 0 0
t$perf-norm:sp_customer_reserve 997 997 0 0.00 0 648 16 0 0
t$perf-norm:sp_pay_from_customer 6896 6896 0 0.00 0 11617 21 0 0
t$perf-norm:sp_pay_to_supplier 5808 5808 0 0.00 0 4204 21 0 0
t$perf-norm:sp_reserve_write_off 9709 9709 0 0.00 0 11604 28 0 0
t$perf-norm:sp_supplier_invoice 5455 5455 0 0.00 0 12548 39 0 0
t$perf-norm:sp_supplier_order 5431 5431 0 0.00 0 3968 38 0 0
t$perf-norm:srv_aggregate_perf_data 121 121 0 0.00 0 683 27 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 1442 23 0 0
t$perf-norm:srv_make_invnt_saldo 483 483 0 0.00 0 1053 24 0 0
t$perf-norm:srv_make_money_saldo 1034 1034 0 0.00 0 810 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 196 108 0 0
v_add_invoice_to_stock 10110 9898 212 2.09 0 5054 27 0 212
v_all_customers 36050 36050 0 0.00 0 160 4 0 0
v_all_suppliers 30368 30368 0 0.00 0 63 4 0 0
v_all_wares 39386 39386 0 0.00 0 166 4 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 2454 20 0 0
v_cancel_client_order 3442 3442 0 0.00 0 3184 12 0 0
v_cancel_customer_prepayment 1290 1290 0 0.00 0 3746 18 0 0
v_cancel_customer_reserve 1338 1338 0 0.00 0 2506 13 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 1439 15 0 0
v_cancel_supplier_invoice 862 836 26 3.01 0 3145 29 0 26
v_cancel_supplier_order 794 794 0 0.00 0 471 10 0 0
v_cancel_write_off 1946 1946 0 0.00 0 4657 27 0 0
v_max_id_clo_ord 5429 5429 0 0.00 0 3463 7 0 0
v_max_id_clo_res 997 997 0 0.00 0 3657 22 0 0
v_max_id_ord_sup 5445 5445 0 0.00 0 11236 11 0 0
v_max_non_paid_invoice 1498 1498 0 0.00 0 208 8 0 0
v_max_non_paid_realizn 3446 3446 0 0.00 0 5142 9 0 0
v_min_id_clo_ord 5429 5429 0 0.00 0 13760 11 0 0
v_min_id_clo_res 997 997 0 0.00 0 9577 22 0 0
v_min_id_ord_sup 5445 5445 0 0.00 0 6918 11 0 0
v_min_non_paid_invoice 5808 5808 0 0.00 0 8387 21 0 0
v_min_non_paid_realizn 6895 6895 0 0.00 0 2518 14 0 0
v_our_firm 10236 10236 0 0.00 0 93 5 0 0
v_reserve_write_off 19418 19418 0 0.00 0 4517 12 0 0
x_lock_depdocs_on_canc_invoice 11078 11078 0 0.00 0 30588 137 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 15450 419 0 0
x_make_qstorno_1000_1200 5431 5431 0 0.00 3 33317 1320 0 0
x_make_qstorno_1200_2000 5454 5454 0 0.00 4 99615 3286 0 0
x_make_qstorno_2100_3300 461549 461549 0 0.00 0 48394 249 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-04-2022 13:40:10 to 15-04-2022 13:40:14.

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
11:07:15 10 8192000000 8798988320 8810450944 4478816 0.054 2 1 2 0 4578208 63616 35776
11:08:16 35532 8192000000 9730846384 10033119232 806348032 8.961 100 89 179 1 836035184 8149296 21537856
11:10:00 13536 8192000000 9764257856 10075222016 842555024 9.325 100 89 178 0 876921488 13498944 20867520
11:11:18 18571 8192000000 9815013200 10133135360 868495280 9.585 100 90 180 0 911840224 21978720 21366224
11:12:39 6958 8192000000 9858337664 10195644416 911900048 10.016 100 77 154 1 972660016 44130384 16629584
11:13:49 7190 8192000000 9911831872 10254069760 949056576 10.382 100 80 160 0 1029855584 64174912 16624096
11:15:01 3913 8192000000 9947413056 10295119872 980687840 10.691 100 91 177 1 1062611712 64554880 17368992
11:16:07 1284 8192000000 9991292384 10354032640 1011355072 10.988 100 97 188 1 1107942512 77848400 18739040
11:17:11 1454 8192000000 10009984192 10379251712 1032455328 11.192 100 90 179 0 1132839424 82231184 18152912
11:18:15 875 8192000000 10026290832 10400034816 1050810272 11.368 100 97 182 0 1146950464 77929808 18210384
11:19:17 1027 8192000000 10021011968 10399207424 1065350912 11.508 100 94 183 1 1142846016 59407728 18087376
11:20:20 1258 8192000000 10048239280 10426347520 1070528048 11.557 100 99 193 2 1168993312 79226592 19238672
11:21:22 1204 8192000000 10066353008 10448003072 1071637264 11.568 100 95 192 1 1182352816 91644736 19070816
11:22:24 2462 8192000000 10042825040 10430758912 1079233200 11.640 100 82 161 1 1160935216 66313632 15388384
11:23:31 1576 8192000000 10044381008 10431811584 1084787760 11.693 100 74 141 2 1168105424 70042224 13275440
11:24:33 1097 8192000000 10032642208 10416922624 1082585936 11.672 100 94 188 3 1148909664 47066416 19257312
11:25:37 2026 8192000000 10042881920 10434801664 1087449696 11.718 100 95 183 2 1168180000 60661408 20068896
11:26:40 1768 8192000000 10070935888 10462064640 1084524528 11.691 100 91 178 2 1196701664 94152544 18024592
11:27:43 1094 8192000000 10060807456 10450907136 1089990208 11.743 100 91 180 0 1181099664 72835824 18273632
11:28:46 1544 8192000000 10049458688 10430881792 1089508672 11.738 100 89 171 3 1166033328 61016864 15507792
11:29:48 5950 8192000000 10036520752 10437316608 1082852848 11.675 100 67 129 1 1164340864 67606128 13881888
11:30:55 7172 8192000000 10010773008 10404696064 1065505952 11.509 99 66 118 1 1132866720 56082864 11277904
11:32:04 1855 8192000000 10009125280 10392776704 1054797280 11.407 100 76 139 3 1139565088 71356032 13411776
11:33:06 2862 8192000000 10024543808 10405330944 1064359424 11.498 100 78 150 1 1142614944 63489792 14765728
11:34:10 1106 8192000000 9988927280 10366029824 1046763504 11.330 99 91 171 6 1111341856 46346624 18231728
11:35:13 1006 8192000000 10001873616 10376478720 1044780592 11.311 99 92 179 1 1128445264 64346560 19318112
11:36:15 945 8192000000 9987122352 10365034496 1040025200 11.265 100 93 169 4 1108490656 50823728 17641728
11:37:17 16090 8192000000 10000185696 10377973760 1054604544 11.405 100 76 136 2 1122008816 54804096 12600176
11:38:42 24037 8192000000 10047953008 10435534848 1068947072 11.542 100 65 127 1 1146930192 65050080 12933040
11:40:16 12614 8192000000 10070692976 10463404032 1084356720 11.689 100 67 132 0 1191282368 93292560 13633088
11:41:33 7754 8192000000 10083346800 10475470848 1085226624 11.697 99 72 137 2 1199346608 99787216 14332768
11:42:46 774 8192000000 10138980144 10521341952 1104310624 11.879 100 93 182 0 1254883008 131835568 18736816
11:43:53 2493 8192000000 10149542160 10529521664 1091541904 11.757 98 85 164 0 1265420656 156880656 16998096
11:45:08 1316 8192000000 10202240384 10590769152 1126296656 12.086 100 94 180 1 1317594928 171078368 20219904
11:46:11 2951 8192000000 10209930000 10607886336 1134517680 12.164 100 79 154 0 1323407616 172242816 16647120
11:47:18 3427 8192000000 10221929056 10633662464 1146438704 12.276 100 72 143 3 1345911616 183937744 15535168
11:48:26 930 8192000000 10256373456 10659397632 1150937632 12.318 100 93 181 2 1368226384 198191952 19096800
11:49:30 892 8192000000 10283677888 10692202496 1159518272 12.399 100 93 179 1 1395930848 216507936 19904640
11:50:34 975 8192000000 10289751504 10687586304 1163613504 12.437 100 95 185 2 1403050944 216822560 22614880
11:51:40 2381 8192000000 10301489008 10718674944 1172588432 12.521 100 88 164 1 1409288848 218759808 17940608
11:52:45 975 8192000000 10343516128 10758193152 1180869392 12.598 100 96 188 2 1447336240 245377792 21089056
11:53:48 1922 8192000000 10348838496 10775175168 1185068320 12.637 100 96 189 0 1457062288 251936000 20057968
11:54:54 944 8192000000 10387796864 10794610688 1191385552 12.696 100 85 167 1 1495243632 284560000 19298080
11:56:00 1601 8192000000 10426157424 10836594688 1205951488 12.832 100 83 157 1 1526143584 302039664 18152432
11:57:05 1046 8192000000 10464887504 10885627904 1202584912 12.800 100 99 187 1 1566426816 342378768 21463136
11:58:11 912 8192000000 10432248880 10875060224 1201173440 12.787 100 95 183 2 1535748864 313212032 21363392
11:59:15 1379 8192000000 10476552304 10897473536 1212365808 12.891 100 93 185 0 1580985632 346357616 22262208
12:00:19 793 8192000000 10501532208 10927054848 1212021904 12.888 100 93 182 0 1603488320 368989088 22477328
12:01:23 2119 8192000000 10527034560 10965184512 1208887744 12.859 100 97 192 2 1637101792 406670592 21543456
12:02:29 789 8192000000 10552636016 10964353024 1216325888 12.928 100 94 179 1 1655659056 418367488 20965680
12:03:32 1755 8192000000 10548977136 11000713216 1203447760 12.808 100 75 148 0 1654520000 432398704 18673536
12:04:40 842 8192000000 10595755264 11069149184 1210083840 12.870 100 98 194 0 1705088992 470098800 24906352
12:05:42 1507 8192000000 10583087952 11027910656 1201813136 12.793 100 81 159 1 1690931936 469600384 19518416
12:06:45 11135 8192000000 10502360672 11012677632 1189740960 12.681 99 44 88 0 1596170992 394679184 11750848
12:08:00 9958 8192000000 10481971200 11008401408 1197079152 12.749 100 47 93 0 1589018704 379926960 12012592
12:09:15 16235 8192000000 10422279440 10914279424 1175065136 12.544 100 37 74 0 1535928528 351290528 9572864
12:10:45 16726 8192000000 10316122080 10799898624 1158905776 12.393 100 24 48 2 1440492208 275260640 6325792
12:12:29 27410 8192000000 10197394752 10737557504 1141714720 12.232 99 11 22 0 1309721072 165133664 2872688
12:13:59 137413 8192000000 10087474624 10595856384 1064880720 11.503 93 14 28 1 1188443072 120948352 2614000
12:17:36 110840 8192000000 10042405856 10493116416 1088024000 11.724 97 15 29 1 1132689248 41118736 3546512
12:20:37 134860 8192000000 10117149808 10557317120 1108580544 11.919 99 26 52 0 1219726368 104778448 6367376
12:24:00 67850 8192000000 10073604112 10578259968 1128654544 12.109 100 17 34 0 1179458880 47690032 3114304
12:26:13 1956 8192000000 10116064144 10536062976 1143142240 12.245 100 68 137 0 1210706720 53584720 13979760
12:27:19 8022 8192000000 10137492128 10571026432 1142946944 12.243 99 52 102 0 1242440800 88379872 11113984
12:28:27 20520 8192000000 10171576688 10602594304 1158257568 12.387 100 42 83 2 1273906960 106768576 8880816
12:29:51 61342 8192000000 10138559536 10603130880 1122875680 12.054 97 15 30 0 1250598400 123820240 3902480
12:31:53 20157 8192000000 10198301104 10644897792 1150196960 12.311 99 46 84 2 1284067488 124952032 8918496
12:33:18 19061 8192000000 10222992912 10659241984 1159300864 12.397 100 40 77 0 1315417280 147120096 8996320
12:34:38 60246 8192000000 10234368272 10703568896 1162073296 12.423 100 19 38 1 1353403760 186056944 5273520
12:36:54 39690 8192000000 10242129184 10679345152 1160827696 12.411 99 20 40 0 1319530416 154221728 4480992
12:38:47 44670 8192000000 10121371360 10683215872 1145844512 12.270 99 13 26 0 1309977888 160801392 3331984
12:40:41 37693 8192000000 10085456944 10522378240 1126004304 12.084 98 39 74 3 1161268864 27355840 7908720
12:42:30 55670 8192000000 10158361104 10620862464 1128392704 12.106 98 33 66 0 1222814128 87605040 6816384
12:44:27 31751 8192000000 10115076848 10544754688 1128926080 12.111 98 37 74 1 1183240960 46506656 7808224
12:46:04 18662 8192000000 10132256432 10561593344 1142077552 12.235 99 31 60 1 1206143840 57820592 6245696
12:47:24 79737 8192000000 10104830400 10582802432 1132970256 12.149 98 17 31 1 1201147616 64935648 3241712
12:49:49 98212 8192000000 10094489504 10534633472 1124217152 12.067 98 39 78 2 1180416288 48453664 7745472
12:52:40 50578 8192000000 10132520720 10585153536 1143903344 12.252 100 52 100 0 1217664096 63617360 10143392
12:54:33 33279 8192000000 10060031840 10503561216 1123071088 12.056 98 24 46 1 1181469568 54133584 4264896
12:56:07 50578 8192000000 10081225296 10546499584 1116878576 11.997 98 10 20 0 1163222464 44239616 2104272
12:58:25 105882 8192000000 10081696032 10555305984 1036625568 11.232 90 14 28 0 1090831440 51498560 2707312
13:01:30 19747 8192000000 10076310784 10516914176 1118137120 12.009 98 37 72 1 1161743408 35240048 8366240
13:02:56 33474 8192000000 10129748064 10566709248 1133086224 12.150 99 51 101 0 1213091264 68688992 11316048
13:04:36 44746 8192000000 10097791280 10566344704 1107094128 11.905 97 32 62 1 1223174032 109551184 6528720
13:06:41 12728 8192000000 10102717600 10528571392 1134631744 12.165 100 54 108 0 1192600096 47545840 10422512
13:07:57 28152 8192000000 10128591232 10579480576 1116431536 11.993 99 45 90 0 1222603280 96892352 9279392
13:09:26 45212 8192000000 10117305552 10551336960 1088204976 11.726 96 20 40 0 1160225584 67905744 4114864
13:11:11 6273 8192000000 10072436800 10518609920 1115169280 11.981 99 51 90 4 1154287664 29094656 10023728
13:12:21 92141 8192000000 10097196960 10577006592 1117409344 12.003 99 37 71 0 1199146016 73089760 8646912
13:15:08 26035 8192000000 10071043920 10508570624 1129981392 12.121 100 20 40 1 1193229232 59043776 4204064
13:16:39 38740 8192000000 10075315536 10532196352 1124764880 12.072 100 15 30 1 1185263264 57352464 3145920
13:18:19 40521 8192000000 10099279696 10545025024 1136684432 12.184 100 27 54 0 1202438672 59866288 5887952
13:20:08 77066 8192000000 10039145104 10524217344 1098437728 11.823 97 7 14 0 1141895104 41997936 1459440
13:22:26 49388 8192000000 10096606688 10537742336 1099758688 11.835 97 29 58 1 1170849696 64942256 6148752
13:24:28 56364 8192000000 10143878928 10597838848 1118595456 12.014 98 28 56 0 1218684480 94529648 5559376
13:26:45 41196 8192000000 10168912768 10605858816 1124181552 12.066 98 24 48 1 1244628784 115131808 5315424
13:28:38 96563 8192000000 10069714912 10589540352 1107144784 11.905 97 9 18 0 1235814992 126547776 2122432
13:31:27 32105 8192000000 10081914112 10514235392 1125486176 12.079 99 46 81 1 1166122752 31287344 9349232
13:33:07 68347 8192000000 10141073744 10573225984 1135986048 12.178 100 47 94 0 1234604944 89446144 9172752
13:35:16 32235 8192000000 10071371072 10543054848 1120538528 12.032 99 35 70 1 1173763056 44456480 8768048
13:37:01 37124 8192000000 9284587936 9603207168 423101856 4.911 38 4 6 1 453049168 29082432 864880

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-04-2022 13:40:16 to 15-04-2022 13:40:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-04-2022 13:40:33 to 15-04-2022 13:40:35.

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          217554
MON$OLDEST_ACTIVE               217555
MON$OLDEST_SNAPSHOT             217555
MON$NEXT_TRANSACTION            217555
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                       2071904
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.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:124: 
:125: mscs-test-161.budzdorov.ru    Fri Apr 15 13:38:06 2022
:126:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:127: 
:128: 
:129: mscs-test-161.budzdorov.ru    Fri Apr 15 13:38:06 2022
:130:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:131: 
:132: 
:133: mscs-test-161.budzdorov.ru    Fri Apr 15 13:38:06 2022
:134:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:135: 
--- end of diff output ---

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

15.04.22 13:40:36. 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-04-15 13:40:38 No severe PSQL-related problems occured
15.04.22 13:40:40. End of report.