Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.05.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 16236092 kB
MemAvailable 31460724 kB
Buffers 20 kB
Cached 15441456 kB
SwapCached 2620 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-05-13 04:37:16
DTS_END                         2022-05-13 04:37:16.1060
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/48352
TRN_ID                          218849
ATT_ID                          634
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: 9084.64 23912 1090157
customer order: creation 168.52 485 20222
customer order: refuse 14.52 396 1742
order to supplier: creation 45.11 1337 5413
order to supplier: removal 3.43 157712 411
invoice (draft): creation 44.93 2968 5391
invoice (draft): removal 53.43 896 6411
invoice accept: apply 39.95 26197 4794
invoice accept: cancel 47.68 1032 5722
customer reserve: creation 3719.16 250 446299
customer reserve: removal 4655.71 89 558685
realization accept: apply 80.35 288 9642
realization accept: cancel 84.57 158 10148
payment from customer: creation 57.57 187 6908
payment from customer: removal 5.62 122 674
payment to supplier: creation 48.35 227 5802
payment to supplier: removal 3.39 102 407
service: total inventory turnovers 3.92 4000 470
service: total monetary turnovers 7.48 375 897
service: aggregate perf. data 0.96 17273 115
service: refresh index statistics 0.03 264141 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 13-05-2022 04:39:15 to 13-05-2022 04:39:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5518
TEST_TIME 2 3894
TEST_TIME 3 4812
TEST_TIME 4 5090
TEST_TIME 5 5092
TEST_TIME 6 5074
TEST_TIME 7 5301
TEST_TIME 8 5750
TEST_TIME 9 6003
TEST_TIME 10 6122
TEST_TIME 11 6305
TEST_TIME 12 6545
TEST_TIME 13 6656
TEST_TIME 14 6599
TEST_TIME 15 6583
TEST_TIME 16 6831
TEST_TIME 17 6938
TEST_TIME 18 7052
TEST_TIME 19 7128
TEST_TIME 20 7459
TEST_TIME 21 7561
TEST_TIME 22 7729
TEST_TIME 23 7835
TEST_TIME 24 8342
TEST_TIME 25 8611
TEST_TIME 26 9006
TEST_TIME 27 8932
TEST_TIME 28 9062
TEST_TIME 29 9382
TEST_TIME 30 9863
TEST_TIME 31 10326
TEST_TIME 32 10405
TEST_TIME 33 10705
TEST_TIME 34 10536
TEST_TIME 35 10574
TEST_TIME 36 10743
TEST_TIME 37 10726
TEST_TIME 38 10544
TEST_TIME 39 10421
TEST_TIME 40 10488
TEST_TIME 41 10540
TEST_TIME 42 10392
TEST_TIME 43 10440
TEST_TIME 44 10336
TEST_TIME 45 10435
TEST_TIME 46 10373
TEST_TIME 47 10157
TEST_TIME 48 10088
TEST_TIME 49 10006
TEST_TIME 50 9893
TEST_TIME 51 9836
TEST_TIME 52 9783
TEST_TIME 53 9761
TEST_TIME 54 9774
TEST_TIME 55 9717
TEST_TIME 56 9584
TEST_TIME 57 9553
TEST_TIME 58 9463
TEST_TIME 59 9558
TEST_TIME 60 9511
TEST_TIME 61 9456
TEST_TIME 62 9529
TEST_TIME 63 9525
TEST_TIME 64 9492
TEST_TIME 65 9452
TEST_TIME 66 9507
TEST_TIME 67 9512
TEST_TIME 68 9472
TEST_TIME 69 9443
TEST_TIME 70 9568
TEST_TIME 71 9520
TEST_TIME 72 9469
TEST_TIME 73 9490
TEST_TIME 74 9503
TEST_TIME 75 9606
TEST_TIME 76 9482
TEST_TIME 77 9398
TEST_TIME 78 9372
TEST_TIME 79 9319
TEST_TIME 80 9274
TEST_TIME 81 9294
TEST_TIME 82 9270
TEST_TIME 83 9266
TEST_TIME 84 9209
TEST_TIME 85 9164
TEST_TIME 86 9197
TEST_TIME 87 9235
TEST_TIME 88 9171
TEST_TIME 89 9163
TEST_TIME 90 9242
TEST_TIME 91 9270
TEST_TIME 92 9326
TEST_TIME 93 9316
TEST_TIME 94 9320
TEST_TIME 95 9385
TEST_TIME 96 9383
TEST_TIME 97 9359
TEST_TIME 98 9392
TEST_TIME 99 9331
TEST_TIME 100 9265
TEST_TIME 101 9229
TEST_TIME 102 9206
TEST_TIME 103 9163
TEST_TIME 104 9105
TEST_TIME 105 9059
TEST_TIME 106 9024
TEST_TIME 107 9092
TEST_TIME 108 9092
TEST_TIME 109 9155
TEST_TIME 110 9139
TEST_TIME 111 9133
TEST_TIME 112 9139
TEST_TIME 113 9146
TEST_TIME 114 9112
TEST_TIME 115 9128
TEST_TIME 116 9197
TEST_TIME 117 9234
TEST_TIME 118 9253
TEST_TIME 119 9183
TEST_TIME 120 9108

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-05-2022 04:39:19 to 13-05-2022 04:39:23.

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 1503451 1503451 0 0.00 0 32633 14 0 0
doc_list_biud 1503436 1503436 0 0.00 0 25669 39 0 0
sp_add_invoice_to_stock 4883 4794 89 1.82 40 214108 26197 0 89
sp_cancel_adding_invoice 5722 5722 0 0.00 6 76797 1032 0 0
sp_cancel_client_order 1742 1742 0 0.00 5 10497 397 0 0
sp_cancel_customer_reserve 558685 558685 0 0.00 1 24871 90 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 3 4012 123 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 4 1148 103 0 0
sp_cancel_supplier_invoice 6421 6411 10 0.15 2 15646 897 0 10
sp_cancel_supplier_order 411 411 0 0.00 7 1414079 157713 0 0
sp_cancel_write_off 10148 10148 0 0.00 2 14099 158 0 0
sp_client_order 20222 20222 0 0.00 6 26714 485 0 0
sp_customer_reserve 446299 446299 0 0.00 1 36238 250 0 0
sp_fill_shopping_cart 31016 31016 0 0.00 2 13463 79 0 0
sp_fill_shopping_cart_clo_res 446269 446269 0 0.00 0 13390 17 0 0
sp_get_clo_for_invoice 4754 4754 0 0.00 0 36775 301 0 0
sp_kill_cost_storno 16951 16951 0 0.00 0 8911 68 0 0
sp_kill_qstorno_ret_qs2qd 1134340 1134340 0 0.00 0 24867 39 0 0
sp_kill_qty_storno 597512 597512 0 0.00 0 25669 90 0 0
sp_lock_selected_doc 20128 20128 0 0.00 0 9232 9 0 0
sp_make_cost_storno 27104 27104 0 0.00 0 32612 86 0 0
sp_multiply_rows_for_pdistr 27101 27101 0 0.00 0 9012 39 0 0
sp_multiply_rows_for_qdistr 20222 20222 0 0.00 0 19078 169 0 0
sp_pay_from_customer 6908 6908 0 0.00 6 20218 187 0 0
sp_pay_to_supplier 5802 5802 0 0.00 6 32710 227 0 0
sp_payment_common 12707 12707 0 0.00 2 10570 47 0 0
sp_qd_handle_on_cancel_clo 1742 1742 0 0.00 1 10469 326 0 0
sp_qd_handle_on_invoice_upd_sts 10475 10475 0 0.00 0 25669 474 0 0
sp_qd_handle_on_reserve_upd_sts 9640 9640 0 0.00 0 19870 61 0 0
sp_reserve_write_off 9642 9642 0 0.00 8 21127 288 0 0
sp_supplier_invoice 5391 5391 0 0.00 9 73805 2969 0 0
sp_supplier_order 5413 5413 0 0.00 8 41337 1338 0 0
srv_aggregate_perf_data 115 115 0 0.00 2650 204562 17273 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 781 190892 38755 0 0
srv_find_qd_qs_mism 1043595 1043595 0 0.00 0 9263 11 0 0
srv_make_invnt_saldo 470 470 0 0.00 88 140738 4000 0 0
srv_make_money_saldo 897 897 0 0.00 1 33322 375 0 0
srv_recalc_idx_stat 4 4 0 0.00 106875 382844 264142 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4798 4798 0 0.00 1 13241 871 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 1 6757 403 0 0
t$perf-norm:sp_cancel_client_order 1742 1742 0 0.00 0 2841 34 0 0
t$perf-norm:sp_cancel_customer_reserve 668 668 0 0.00 0 1177 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 912 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 1369 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 2363 39 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 86386 7011 0 0
t$perf-norm:sp_cancel_write_off 1014 1014 0 0.00 0 837 14 0 0
t$perf-norm:sp_client_order 20222 20222 0 0.00 0 21548 36 0 0
t$perf-norm:sp_customer_reserve 1034 1034 0 0.00 0 2293 15 0 0
t$perf-norm:sp_pay_from_customer 6908 6908 0 0.00 0 6123 19 0 0
t$perf-norm:sp_pay_to_supplier 5802 5802 0 0.00 0 6946 19 0 0
t$perf-norm:sp_reserve_write_off 9643 9643 0 0.00 0 6012 22 0 0
t$perf-norm:sp_supplier_invoice 5391 5391 0 0.00 0 8151 31 0 0
t$perf-norm:sp_supplier_order 5414 5414 0 0.00 0 3711 30 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 222 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 19155 290 0 0
t$perf-norm:srv_make_invnt_saldo 470 470 0 0.00 0 3432 18 0 0
t$perf-norm:srv_make_money_saldo 897 897 0 0.00 0 973 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 120 36 0 0
v_add_invoice_to_stock 9679 9501 178 1.83 0 5867 23 0 178
v_all_customers 37030 37030 0 0.00 0 148 3 0 0
v_all_suppliers 30366 30366 0 0.00 0 66 4 0 0
v_all_wares 40438 40438 0 0.00 0 85 4 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 560 13 0 0
v_cancel_client_order 3484 3484 0 0.00 0 6600 12 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 3973 15 0 0
v_cancel_customer_reserve 1336 1336 0 0.00 0 753 9 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 422 9 0 0
v_cancel_supplier_invoice 874 854 20 2.28 0 2035 22 0 20
v_cancel_supplier_order 822 822 0 0.00 0 3560 21 0 0
v_cancel_write_off 2028 2028 0 0.00 0 2702 22 0 0
v_max_id_clo_ord 5408 5408 0 0.00 0 8077 6 0 0
v_max_id_clo_res 1033 1033 0 0.00 0 11259 25 0 0
v_max_id_ord_sup 5383 5383 0 0.00 0 2648 6 0 0
v_max_non_paid_invoice 1408 1408 0 0.00 0 4068 11 0 0
v_max_non_paid_realizn 3605 3605 0 0.00 0 2238 7 0 0
v_min_id_clo_ord 5408 5408 0 0.00 0 2476 10 0 0
v_min_id_clo_res 1033 1033 0 0.00 0 7213 28 0 0
v_min_id_ord_sup 5383 5383 0 0.00 0 7937 12 0 0
v_min_non_paid_invoice 5798 5798 0 0.00 0 8692 19 0 0
v_min_non_paid_realizn 6907 6907 0 0.00 0 8620 19 0 0
v_our_firm 10010 10010 0 0.00 0 149 4 0 0
v_reserve_write_off 19280 19280 0 0.00 0 5233 10 0 0
x_lock_depdocs_on_canc_invoice 11706 11706 0 0.00 0 10968 115 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 9177 385 0 0
x_make_qstorno_1000_1200 5413 5413 0 0.00 3 41307 1172 0 0
x_make_qstorno_1200_2000 5391 5391 0 0.00 4 70373 2761 0 0
x_make_qstorno_2100_3300 446298 446298 0 0.00 0 36237 221 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 13-05-2022 04:39:23 to 13-05-2022 04:39:28.

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:19 3 8192000000 8800418240 8812351488 6180928 0.075 2 2 3 0 6346208 103904 61376
02:08:20 21819 8192000000 9714773744 10015358976 803645568 8.933 100 87 174 1 832583376 7369936 21567872
02:09:46 18475 8192000000 9753570752 10061201408 830171648 9.201 100 91 182 0 864693248 12169088 22352512
02:11:11 8142 8192000000 9780506016 10099281920 854273984 9.443 100 86 172 0 894096752 19547728 20275040
02:12:37 6402 8192000000 9860775904 10193133568 907007024 9.968 100 83 165 0 969435776 44561552 17867200
02:13:49 4785 8192000000 9912594416 10259828736 961411552 10.503 100 80 151 1 1022483088 45748000 15323536
02:14:58 2147 8192000000 9973120096 10328813568 998471984 10.864 100 87 164 0 1098069184 83023440 16573760
02:16:02 986 8192000000 10004790528 10364903424 1014358400 11.018 100 94 182 0 1122797936 90610368 17829168
02:17:05 746 8192000000 10012914368 10381160448 1038782576 11.253 100 94 181 1 1130567488 72800048 18984864
02:18:07 1719 8192000000 10034190480 10406871040 1055508128 11.413 100 95 187 0 1151992160 77896592 18587440
02:19:11 1311 8192000000 10041189808 10420830208 1063406688 11.489 100 94 181 1 1159518208 77917488 18194032
02:20:13 990 8192000000 10039199824 10415747072 1073732992 11.588 100 94 183 1 1158241616 65709984 18798640
02:21:15 1228 8192000000 10067263200 10448568320 1078968336 11.638 100 94 178 1 1184361504 86874880 18518288
02:22:17 1192 8192000000 10086201664 10470006784 1087462816 11.719 100 97 185 3 1205120512 99283168 18374528
02:23:19 1272 8192000000 10058426048 10442903552 1093048672 11.772 100 95 184 1 1181228480 69459616 18720192
02:24:21 753 8192000000 10058245936 10448166912 1093188640 11.773 100 91 172 2 1182168416 70420224 18559552
02:25:24 1157 8192000000 10073346224 10460020736 1100259520 11.840 100 92 176 2 1190589936 73668560 16661856
02:26:26 1129 8192000000 10073143824 10461470720 1093554368 11.776 100 89 173 1 1194730352 82965360 18210624
02:27:28 857 8192000000 10045044368 10434568192 1094438688 11.785 99 83 165 0 1167470512 54929536 18102288
02:28:30 1271 8192000000 10082724064 10474311680 1083728000 11.683 100 97 190 0 1207563776 105184176 18651600
02:29:32 1252 8192000000 10035679552 10420981760 1083462560 11.680 100 96 185 0 1158730528 56907376 18360592
02:30:34 1220 8192000000 10018173952 10397175808 1056224896 11.420 100 92 175 0 1137338544 62832496 18281152
02:31:36 1420 8192000000 10010200016 10388434944 1055904864 11.417 100 89 169 2 1136246048 63180096 17161088
02:32:38 1985 8192000000 10027726000 10409566208 1056694208 11.425 100 91 184 0 1143984112 68235088 19054816
02:33:41 2543 8192000000 9946925248 10314711040 995060608 10.831 96 65 122 6 1065236272 56920528 13255136
02:34:45 1066 8192000000 10003689536 10377871360 1041921120 11.283 100 95 182 0 1125704000 64523360 19259520
02:35:47 11980 8192000000 9994613776 10389594112 1054798224 11.407 100 55 108 0 1105821728 40351152 10672352
02:37:01 2545 8192000000 10003477296 10380361728 1040661328 11.271 99 86 156 3 1127850848 71717504 15472016
02:38:11 15753 8192000000 10017684384 10402680832 1067374048 11.527 100 71 137 2 1128629568 47008032 14247488
02:39:32 5110 8192000000 10039748752 10426396672 1077795552 11.626 100 55 108 0 1156370000 67035024 11539424
02:40:47 6138 8192000000 10041982288 10438725632 1083138928 11.677 100 58 114 2 1157747040 62732896 11875216
02:41:58 4147 8192000000 10080101040 10474037248 1103322736 11.869 100 71 131 3 1197385328 80329184 13733408
02:43:07 6306 8192000000 10113821728 10503581696 1114433872 11.974 100 83 152 2 1234263072 102874432 16954768
02:44:17 2309 8192000000 10152764016 10551947264 1127847728 12.101 100 73 137 0 1265496896 123543712 14105456
02:45:25 2697 8192000000 10185884432 10583425024 1143663744 12.250 100 88 172 2 1296337840 134078976 18595120
02:46:29 765 8192000000 10218654400 10620006400 1154201744 12.349 100 95 170 0 1330586032 156999392 19384896
02:47:34 1071 8192000000 10232354976 10632794112 1154761152 12.354 100 93 178 1 1344372832 169385904 20225776
02:48:37 993 8192000000 10270899824 10673549312 1167630752 12.475 100 95 184 1 1382039760 194279312 20129696
02:49:40 909 8192000000 10296037600 10712948736 1174032528 12.535 100 91 180 0 1412988608 217207200 21748880
02:50:45 1261 8192000000 10351455104 10757713920 1187747600 12.662 100 91 177 0 1463662192 256341264 19573328
02:51:51 1134 8192000000 10381838176 10781184000 1194116768 12.722 100 93 185 0 1488006384 273135360 20754256
02:52:55 1194 8192000000 10443452832 10846941184 1193368240 12.715 100 96 189 2 1548453040 333396032 21688768
02:53:59 1289 8192000000 10451253232 10871910400 1199053552 12.768 100 96 185 0 1556402128 333965024 23383552
02:55:03 1136 8192000000 10472804768 10903064576 1194401552 12.724 99 89 176 2 1580055296 363815024 21838720
02:56:08 1818 8192000000 10477496240 10916954112 1203938464 12.813 100 91 177 1 1583880048 358407376 21534208
02:57:15 900 8192000000 10518336896 10959286272 1202688656 12.801 100 90 176 0 1620635936 396199552 21747728
02:58:20 984 8192000000 10528215008 10961698816 1202073504 12.796 100 97 185 1 1633279472 408963408 22242560
02:59:25 778 8192000000 10523710272 10967072768 1198078880 12.758 100 97 192 0 1630010912 407407824 24524208
03:00:28 985 8192000000 10523323200 10979893248 1175468800 12.548 100 85 164 0 1628378960 432016208 20893952
03:01:32 5752 8192000000 10490153056 10998927360 1166397568 12.463 100 64 126 0 1612182160 429010224 16774368
03:02:53 6995 8192000000 10439477600 10915135488 1168156448 12.480 100 49 93 0 1561587216 381564896 11865872
03:04:34 15150 8192000000 10439937952 10902302720 1149275184 12.303 99 31 62 2 1518657488 361136672 8245632
03:05:53 14609 8192000000 10391525728 10902253568 1158191200 12.386 100 31 62 0 1513052304 347002512 7858592
03:07:35 46070 8192000000 10246234640 10712494080 1134248448 12.161 100 23 45 0 1366827520 227554864 5024208
03:09:32 43970 8192000000 10162119568 10642223104 1155436144 12.360 100 36 70 1 1305750448 142011584 8302720
03:11:17 54409 8192000000 10175361200 10623868928 1156859248 12.374 100 21 40 0 1259483216 98712016 3911952
03:13:15 114459 8192000000 10066085728 10514825216 1120784864 12.034 97 16 30 2 1192165728 67961392 3419472
03:16:13 190892 8192000000 10113870800 10603544576 1127417696 12.097 98 20 40 0 1213532096 82420992 3693408
03:20:25 84726 8192000000 10089059264 10532343808 1122877872 12.054 98 13 26 0 1169128448 43556832 2693744
03:22:50 104859 8192000000 10080430560 10544357376 1118839808 12.016 98 13 26 1 1180779568 58713136 3226624
03:25:59 19145 8192000000 10095920992 10530668544 1140489040 12.220 100 60 118 1 1185034048 32783488 11761520
03:27:28 36734 8192000000 10081233760 10524438528 1135212224 12.170 99 27 54 1 1177034848 36119504 5703120
03:29:05 12778 8192000000 10109733392 10542997504 1149403264 12.304 100 59 108 1 1204403024 42659936 12339824
03:30:20 24002 8192000000 10087416640 10530713600 1118460736 12.012 98 52 99 2 1164300864 35176496 10663632
03:32:12 61977 8192000000 10090109264 10568691712 1122347984 12.049 99 10 20 1 1189318208 64798112 2172112
03:34:21 49100 8192000000 10123146224 10572115968 1132995984 12.150 99 18 36 1 1210171280 73181984 3993312
03:36:27 46402 8192000000 10076157952 10566610944 1134396912 12.163 100 12 24 0 1196946768 59689488 2860368
03:38:31 64462 8192000000 10070757904 10573348864 1118219248 12.010 97 12 24 1 1196219360 75621104 2379008
03:40:42 22888 8192000000 10079484544 10518286336 1138959856 12.206 99 39 76 0 1173883424 27569056 7354512
03:42:06 33740 8192000000 10119775440 10554798080 1159006000 12.394 100 52 103 1 1218005584 48065872 10933712
03:43:40 64237 8192000000 10138772336 10590511104 1154471248 12.351 100 20 40 1 1229287392 70655920 4160224
03:46:18 106550 8192000000 10087621168 10569437184 1092949296 11.771 94 14 28 0 1146788624 50688368 3150960
03:49:19 63084 8192000000 10109165824 10562715648 1116770336 11.996 97 22 42 1 1194716816 72930816 5015664
03:51:33 99116 8192000000 10080628224 10596192256 1112415504 11.955 97 13 26 0 1180316736 65304832 2596400
03:54:13 24153 8192000000 10069936816 10497470464 1141183056 12.227 100 60 108 3 1162341360 9038368 12119936
03:55:40 52332 8192000000 10069176400 10515050496 1098651392 11.825 97 28 56 0 1158204464 53799584 5753488
03:57:53 94864 8192000000 10111833680 10603601920 1135219904 12.171 100 26 53 1 1231948736 90434528 6294304
04:01:18 58255 8192000000 10090025552 10565525504 1132972496 12.149 100 11 22 0 1198512144 63081616 2458032
04:03:38 44086 8192000000 10081694672 10536910848 1104024176 11.876 97 31 60 0 1143356000 31637824 7694000
04:05:24 23799 8192000000 10107228848 10531983360 1131656624 12.137 100 57 109 0 1191896336 47005808 13233904
04:06:49 55490 8192000000 10168117184 10665033728 1147502528 12.286 100 27 54 1 1279674464 125959168 6212768
04:09:21 64433 8192000000 10111176576 10583281664 1138898064 12.205 99 22 44 0 1211486512 68192272 4396176
04:11:28 72883 8192000000 10118288656 10601541632 1133699168 12.156 99 32 64 1 1221100336 81051088 6350080
04:13:42 14662 8192000000 10105782048 10534281216 1122943552 12.055 99 53 104 1 1186214000 53144560 10125888
04:14:58 126844 8192000000 10075144768 10507862016 1096506992 11.804 96 32 63 1 1151127920 48128208 6492720
04:18:19 41824 8192000000 10126086592 10587037696 1132712032 12.147 99 44 87 0 1200002096 58494752 8795312
04:21:12 41868 8192000000 10109336432 10556112896 1124957040 12.074 98 18 35 0 1200952784 72151888 3843856
04:22:54 72458 8192000000 10189884624 10667184128 1140094736 12.216 99 16 32 0 1267778272 123735920 3947616
04:25:07 55985 8192000000 10117351136 10599841792 1124748160 12.072 99 12 24 0 1216969408 89406736 2814512
04:27:10 35522 8192000000 10077947808 10505449472 1112285888 11.954 98 28 56 0 1162131136 44007232 5838016
04:28:48 14418 8192000000 10113536016 10550247424 1136271584 12.180 100 54 102 0 1201810656 55413296 10125776
04:30:04 74702 8192000000 10126547568 10591285248 1126928384 12.092 98 33 64 1 1199088608 65566096 6594128
04:32:25 36143 8192000000 10156501152 10618281984 1109595280 11.929 98 39 78 3 1233488688 115944848 7948560
04:34:03 106490 8192000000 10082186640 10599944192 1095358032 11.794 97 18 36 1 1150366272 51858528 3149712
04:36:59 41295 8192000000 8984070720 9197793280 141094656 1.693 13 3 3 1 157275360 15742464 438240

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 13-05-2022 04:39:29 to 13-05-2022 04:39:45.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 89 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-05-2022 04:39:46 to 13-05-2022 04:39:48.

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          219584
MON$OLDEST_ACTIVE               219585
MON$OLDEST_SNAPSHOT             219585
MON$NEXT_TRANSACTION            219585
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             774
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

13.05.22 04:39:49. 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-05-13 04:39:52 No severe PSQL-related problems occured
13.05.22 04:39:53. End of report.