Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.10.2021 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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15772896 kB
MemAvailable 30720932 kB
Buffers 20 kB
Cached 15899520 kB
SwapCached 4780 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.1
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 2021-10-06 04:37:07
DTS_END                         2021-10-06 04:37:07.2710
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/55086
TRN_ID                          243494
ATT_ID                          709
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: 9220.51 22747 1106461
customer order: creation 182.10 372 21852
customer order: refuse 15.33 293 1840
order to supplier: creation 47.78 1395 5734
order to supplier: removal 3.28 106852 394
invoice (draft): creation 48.35 3372 5802
invoice (draft): removal 50.14 932 6017
invoice accept: apply 43.93 19134 5272
invoice accept: cancel 45.02 901 5402
customer reserve: creation 4085.49 188 490259
customer reserve: removal 4398.66 63 527839
realization accept: apply 86.41 162 10369
realization accept: cancel 80.28 115 9633
payment from customer: creation 62.67 122 7520
payment from customer: removal 5.84 59 701
payment to supplier: creation 50.50 130 6060
payment to supplier: removal 3.37 143 404
service: total inventory turnovers 4.15 3311 498
service: total monetary turnovers 6.41 530 769
service: aggregate perf. data 0.76 21836 91
service: refresh index statistics 0.04 295038 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 06-10-2021 04:39:03 to 06-10-2021 04:39:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3723
TEST_TIME 2 4837
TEST_TIME 3 4595
TEST_TIME 4 5626
TEST_TIME 5 5457
TEST_TIME 6 6194
TEST_TIME 7 5845
TEST_TIME 8 6383
TEST_TIME 9 6263
TEST_TIME 10 6394
TEST_TIME 11 6305
TEST_TIME 12 6620
TEST_TIME 13 6727
TEST_TIME 14 7028
TEST_TIME 15 7474
TEST_TIME 16 7579
TEST_TIME 17 7816
TEST_TIME 18 8191
TEST_TIME 19 8369
TEST_TIME 20 8549
TEST_TIME 21 8976
TEST_TIME 22 9030
TEST_TIME 23 9350
TEST_TIME 24 9940
TEST_TIME 25 10164
TEST_TIME 26 10530
TEST_TIME 27 11756
TEST_TIME 28 11753
TEST_TIME 29 11737
TEST_TIME 30 11483
TEST_TIME 31 11206
TEST_TIME 32 11188
TEST_TIME 33 11049
TEST_TIME 34 11006
TEST_TIME 35 10925
TEST_TIME 36 10818
TEST_TIME 37 10666
TEST_TIME 38 10615
TEST_TIME 39 10573
TEST_TIME 40 10489
TEST_TIME 41 10325
TEST_TIME 42 10373
TEST_TIME 43 10257
TEST_TIME 44 10241
TEST_TIME 45 10376
TEST_TIME 46 10201
TEST_TIME 47 10122
TEST_TIME 48 10022
TEST_TIME 49 10010
TEST_TIME 50 9973
TEST_TIME 51 9943
TEST_TIME 52 9897
TEST_TIME 53 9871
TEST_TIME 54 9870
TEST_TIME 55 9816
TEST_TIME 56 9844
TEST_TIME 57 9811
TEST_TIME 58 9872
TEST_TIME 59 9930
TEST_TIME 60 9906
TEST_TIME 61 9915
TEST_TIME 62 9780
TEST_TIME 63 9711
TEST_TIME 64 9656
TEST_TIME 65 9655
TEST_TIME 66 9578
TEST_TIME 67 9487
TEST_TIME 68 9489
TEST_TIME 69 9439
TEST_TIME 70 9452
TEST_TIME 71 9425
TEST_TIME 72 9505
TEST_TIME 73 9466
TEST_TIME 74 9459
TEST_TIME 75 9544
TEST_TIME 76 9567
TEST_TIME 77 9600
TEST_TIME 78 9580
TEST_TIME 79 9701
TEST_TIME 80 9702
TEST_TIME 81 9693
TEST_TIME 82 9676
TEST_TIME 83 9574
TEST_TIME 84 9564
TEST_TIME 85 9541
TEST_TIME 86 9510
TEST_TIME 87 9455
TEST_TIME 88 9430
TEST_TIME 89 9467
TEST_TIME 90 9443
TEST_TIME 91 9452
TEST_TIME 92 9478
TEST_TIME 93 9499
TEST_TIME 94 9489
TEST_TIME 95 9507
TEST_TIME 96 9488
TEST_TIME 97 9504
TEST_TIME 98 9545
TEST_TIME 99 9492
TEST_TIME 100 9409
TEST_TIME 101 9451
TEST_TIME 102 9380
TEST_TIME 103 9347
TEST_TIME 104 9333
TEST_TIME 105 9311
TEST_TIME 106 9322
TEST_TIME 107 9304
TEST_TIME 108 9326
TEST_TIME 109 9319
TEST_TIME 110 9307
TEST_TIME 111 9339
TEST_TIME 112 9357
TEST_TIME 113 9369
TEST_TIME 114 9413
TEST_TIME 115 9389
TEST_TIME 116 9345
TEST_TIME 117 9274
TEST_TIME 118 9332
TEST_TIME 119 9297
TEST_TIME 120 9223

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-10-2021 04:39:07 to 06-10-2021 04:39:10.

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 1557991 1557991 0 0.00 0 25163 7 0 0
doc_list_biud 1557991 1557991 0 0.00 0 53047 27 0 0
sp_add_invoice_to_stock 5390 5272 118 2.18 52 175267 19134 0 118
sp_cancel_adding_invoice 5402 5402 0 0.00 4 65286 902 0 0
sp_cancel_client_order 1840 1840 0 0.00 4 7169 293 0 0
sp_cancel_customer_reserve 527839 527839 0 0.00 1 24182 64 0 0
sp_cancel_pay_from_customer 701 701 0 0.00 3 1275 59 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 8374 143 0 0
sp_cancel_supplier_invoice 6025 6017 8 0.13 1 60244 932 0 8
sp_cancel_supplier_order 394 394 0 0.00 6 1334340 106852 0 0
sp_cancel_write_off 9633 9633 0 0.00 1 7586 116 0 0
sp_client_order 21852 21852 0 0.00 4 19136 373 0 0
sp_customer_reserve 490259 490259 0 0.00 0 155725 188 0 0
sp_fill_shopping_cart 33366 33366 0 0.00 1 12023 40 0 0
sp_fill_shopping_cart_clo_res 490255 490255 0 0.00 0 24560 17 0 0
sp_get_clo_for_invoice 5264 5264 0 0.00 0 32713 389 0 0
sp_kill_cost_storno 16140 16140 0 0.00 0 6929 55 0 0
sp_kill_qstorno_ret_qs2qd 1071722 1071722 0 0.00 0 53045 35 0 0
sp_kill_qty_storno 566756 566756 0 0.00 0 53047 74 0 0
sp_lock_selected_doc 21625 21625 0 0.00 0 11925 5 0 0
sp_make_cost_storno 29211 29211 0 0.00 0 18247 53 0 0
sp_multiply_rows_for_pdistr 29211 29211 0 0.00 0 8979 33 0 0
sp_multiply_rows_for_qdistr 21852 21852 0 0.00 0 12886 193 0 0
sp_pay_from_customer 7520 7520 0 0.00 4 16975 122 0 0
sp_pay_to_supplier 6060 6060 0 0.00 5 18308 131 0 0
sp_payment_common 13580 13580 0 0.00 1 7983 16 0 0
sp_qd_handle_on_cancel_clo 1840 1840 0 0.00 0 7163 267 0 0
sp_qd_handle_on_invoice_upd_sts 10664 10664 0 0.00 0 27383 352 0 0
sp_qd_handle_on_reserve_upd_sts 10369 10369 0 0.00 0 8395 47 0 0
sp_reserve_write_off 10369 10369 0 0.00 7 15486 162 0 0
sp_supplier_invoice 5802 5802 0 0.00 6 88647 3373 0 0
sp_supplier_order 5734 5734 0 0.00 6 30091 1395 0 0
srv_aggregate_perf_data 91 91 0 0.00 3172 394068 21836 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1796 139885 41530 0 0
srv_find_qd_qs_mism 1055042 1055042 0 0.00 0 25163 6 0 0
srv_make_invnt_saldo 498 498 0 0.00 26 146717 3312 0 0
srv_make_money_saldo 769 769 0 0.00 1 11351 531 0 0
srv_recalc_idx_stat 5 5 0 0.00 246354 369646 295038 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5272 5272 0 0.00 0 25176 159 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 0 9478 114 0 0
t$perf-norm:sp_cancel_client_order 1840 1840 0 0.00 0 1247 9 0 0
t$perf-norm:sp_cancel_customer_reserve 701 701 0 0.00 0 2153 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 701 701 0 0.00 0 3386 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 2727 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 445 445 0 0.00 0 2618 23 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 18920 1391 0 0
t$perf-norm:sp_cancel_write_off 1073 1073 0 0.00 0 3054 5 0 0
t$perf-norm:sp_client_order 21852 21852 0 0.00 0 10469 16 0 0
t$perf-norm:sp_customer_reserve 1079 1079 0 0.00 0 1362 6 0 0
t$perf-norm:sp_pay_from_customer 7520 7520 0 0.00 0 7827 8 0 0
t$perf-norm:sp_pay_to_supplier 6060 6060 0 0.00 0 2723 8 0 0
t$perf-norm:sp_reserve_write_off 10369 10369 0 0.00 0 5803 10 0 0
t$perf-norm:sp_supplier_invoice 5802 5802 0 0.00 0 3672 14 0 0
t$perf-norm:sp_supplier_order 5734 5734 0 0.00 0 6200 15 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 95 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 1553 27 0 0
t$perf-norm:srv_make_invnt_saldo 498 498 0 0.00 0 1015 13 0 0
t$perf-norm:srv_make_money_saldo 769 769 0 0.00 0 721 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10756 10520 236 2.19 0 7075 20 0 236
v_all_customers 40360 40360 0 0.00 0 18 1 0 0
v_all_suppliers 31680 31680 0 0.00 0 23 1 0 0
v_all_wares 43704 43704 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 3958 14 0 0
v_cancel_client_order 3680 3680 0 0.00 0 5350 8 0 0
v_cancel_customer_prepayment 1402 1402 0 0.00 0 1217 5 0 0
v_cancel_customer_reserve 1402 1402 0 0.00 0 2743 7 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 5073 15 0 0
v_cancel_supplier_invoice 906 890 16 1.76 0 1452 15 0 16
v_cancel_supplier_order 788 788 0 0.00 0 2128 8 0 0
v_cancel_write_off 2146 2146 0 0.00 0 5249 18 0 0
v_max_id_clo_ord 5734 5734 0 0.00 0 269 1 0 0
v_max_id_clo_res 1079 1079 0 0.00 0 6728 32 0 0
v_max_id_ord_sup 5780 5780 0 0.00 0 8369 8 0 0
v_max_non_paid_invoice 1734 1734 0 0.00 0 1384 3 0 0
v_max_non_paid_realizn 3708 3708 0 0.00 0 5565 4 0 0
v_min_id_clo_ord 5734 5734 0 0.00 0 1760 5 0 0
v_min_id_clo_res 1079 1079 0 0.00 0 5825 12 0 0
v_min_id_ord_sup 5780 5780 0 0.00 0 6422 7 0 0
v_min_non_paid_invoice 6060 6060 0 0.00 0 2881 8 0 0
v_min_non_paid_realizn 7520 7520 0 0.00 0 7975 12 0 0
v_our_firm 10968 10968 0 0.00 0 17 1 0 0
v_reserve_write_off 20738 20738 0 0.00 0 5409 6 0 0
x_lock_depdocs_on_canc_invoice 10974 10974 0 0.00 0 24062 97 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 13913 347 0 0
x_make_qstorno_1000_1200 5734 5734 0 0.00 2 30078 1320 0 0
x_make_qstorno_1200_2000 5802 5802 0 0.00 3 82542 3215 0 0
x_make_qstorno_2100_3300 490259 490259 0 0.00 0 155723 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-10-2021 04:39:12 to 06-10-2021 04:39:16.

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:11 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:11 32826 8192000000 9714298336 10012340224 801020304 8.907 100 88 176 0 830724128 7709856 21993968
02:09:51 17513 8192000000 9764307024 10069684224 834138496 9.241 100 88 178 0 868533856 13289776 21105584
02:11:14 12229 8192000000 9802798192 10121285632 873019168 9.630 100 83 164 0 916168912 24281424 18868320
02:12:34 17320 8192000000 9878487456 10215198720 923568000 10.131 100 79 158 0 990383104 49130480 17684624
02:13:54 9870 8192000000 9922979456 10271264768 965714912 10.545 100 79 157 3 1039175104 56599664 16860528
02:15:06 4517 8192000000 9966944240 10323881984 998580384 10.865 100 79 156 2 1080991136 66684768 15725984
02:16:13 2455 8192000000 10012692736 10374709248 1023667392 11.107 100 86 173 0 1118044176 76469600 17907184
02:17:19 1535 8192000000 10012779408 10383343616 1050196064 11.363 100 93 187 0 1132398272 63435888 18766320
02:18:22 1553 8192000000 10028739936 10399793152 1065305200 11.507 100 85 171 1 1141992736 58650000 18037536
02:19:25 6907 8192000000 10012030784 10405052416 1075140608 11.601 100 44 86 0 1131576432 48321184 8114640
02:20:32 5988 8192000000 10000923696 10393513984 1084748656 11.693 100 43 87 0 1128505120 34101680 9654784
02:21:39 3724 8192000000 10001326624 10393481216 1087876448 11.722 100 52 102 1 1121234096 22095072 11262576
02:22:43 49015 8192000000 10016086080 10429771776 1090472688 11.747 100 35 70 0 1129884784 31763888 7648208
02:24:37 7388 8192000000 10024110256 10423193600 1096671936 11.806 100 47 94 0 1128028272 21370528 9985808
02:25:46 7158 8192000000 10012766288 10417434624 1104369904 11.879 100 43 84 2 1127510064 13895360 9244800
02:26:55 13343 8192000000 9990557664 10402455552 1056459952 11.423 97 15 30 0 1081805936 22260176 3085808
02:28:10 23438 8192000000 9969391136 10380488704 1014133744 11.015 94 18 36 0 1042766544 24884784 3748016
02:29:34 8540 8192000000 9968504400 10340016128 1006110416 10.938 96 48 94 1 1036509008 19838496 10560096
02:30:43 8382 8192000000 9926689904 10318118912 999108624 10.870 98 35 69 2 1029430736 22448992 7873120
02:31:52 66428 8192000000 9942688816 10333429760 1009847456 10.974 98 26 52 1 1027746624 12624320 5274848
02:33:59 6674 8192000000 9957329520 10352742400 1047542128 11.337 100 38 75 1 1076948416 20478976 8927312
02:35:07 3951 8192000000 9987522720 10374393856 1064929392 11.504 100 51 100 0 1089989888 13555072 11505424
02:36:13 6130 8192000000 9998182896 10389991424 1072629024 11.577 100 43 82 5 1105192048 22511840 10051184
02:37:22 63876 8192000000 10034110496 10433323008 1092041360 11.762 100 35 69 1 1122637520 23930864 6665296
02:39:29 20820 8192000000 10057144496 10470375424 1109891136 11.931 100 35 70 2 1174073232 57087904 7094192
02:40:55 11445 8192000000 10122046880 10526420992 1130181968 12.123 100 56 111 0 1226188016 84098128 11907920
02:42:11 11035 8192000000 10108269744 10520522752 1135152112 12.170 100 51 101 1 1217816000 71532896 11130992
02:43:26 4036 8192000000 10162510928 10574172160 1147340000 12.285 100 58 116 0 1281342864 120841888 13160976
02:44:41 25520 8192000000 10206718880 10621919232 1163111488 12.432 100 57 112 1 1309057088 133900768 12044832
02:46:09 8519 8192000000 10230735424 10642259968 1174896784 12.543 100 62 123 2 1332477776 144006864 13574128
02:47:21 8254 8192000000 10233686080 10657869824 1163109872 12.432 100 55 109 0 1342866992 167509184 12247936
02:48:37 15309 8192000000 10287952160 10705731584 1161804176 12.420 99 57 114 0 1383828704 209367648 12656880
02:49:55 1795 8192000000 10328480752 10737926144 1169130224 12.489 100 74 146 1 1429390704 244169456 16091024
02:51:12 3840 8192000000 10309541744 10713620480 1140710976 12.222 99 86 172 0 1395418384 236062016 18645392
02:52:22 2353 8192000000 10317571488 10738659328 1144573024 12.259 100 87 174 0 1410925792 246657376 19695392
02:53:32 2178 8192000000 10366972032 10758115328 1142249600 12.237 99 92 184 0 1457428624 293455008 21724016
02:54:40 3437 8192000000 10382376432 10780319744 1136296448 12.181 99 91 183 0 1475349392 318791888 20261056
02:55:46 4932 8192000000 10357824416 10785923072 1139297472 12.209 100 36 72 2 1463896896 315336640 9262784
02:56:54 2072 8192000000 10397250096 10810925056 1139113904 12.207 100 91 177 1 1485521648 325177312 21230432
02:57:58 8187 8192000000 10412761536 10844741632 1144559616 12.258 100 34 68 2 1506452224 352370320 9522288
02:59:08 3070 8192000000 10391139840 10805473280 1143099168 12.245 100 56 111 0 1484643968 328223120 13321680
03:00:14 20395 8192000000 10422435648 10876350464 1143851136 12.252 100 31 62 0 1506885968 355128224 7906608
03:01:40 16839 8192000000 10338502720 10775420928 1135380032 12.172 100 20 40 1 1437179648 296241840 5557776
03:02:59 27588 8192000000 10320228880 10801807360 1124415232 12.069 100 20 40 3 1466559680 336214816 5929632
03:04:46 47330 8192000000 10050427248 10588614656 1085962176 11.704 98 8 16 1 1164506160 76800544 1743440
03:06:34 74108 8192000000 10077825248 10503401472 1103675408 11.872 98 15 31 0 1160313696 53458704 3179584
03:08:49 103744 8192000000 10088053424 10554580992 1131559232 12.136 100 24 45 0 1190224464 54514560 4150672
03:11:44 139885 8192000000 10068249408 10498678784 1060690128 11.463 93 31 60 0 1114534096 47744592 6099376
03:15:05 25550 8192000000 10103824064 10563252224 1138369344 12.200 100 18 37 0 1217874144 75815712 3689088
03:17:33 19980 8192000000 10114448384 10587312128 1148074160 12.291 100 18 36 0 1225320256 72994496 4251600
03:18:53 34698 8192000000 10153410720 10638536704 1142197456 12.236 99 19 39 0 1255394112 108846192 4350464
03:20:34 73923 8192000000 10146889616 10646413312 1147322528 12.284 100 17 34 0 1248300064 97504000 3473536
03:22:53 72175 8192000000 10086191632 10557140992 1144924336 12.262 100 13 27 0 1192163760 44282944 2956480
03:25:06 31904 8192000000 10054554480 10513281024 1136727520 12.185 99 16 32 1 1150802752 11011024 3064208
03:26:38 40678 8192000000 10073795072 10525540352 1129996880 12.121 99 13 26 1 1158291872 25449776 2845216
03:28:19 54523 8192000000 10089604288 10548199424 1129891472 12.120 99 21 42 0 1183992384 49878464 4222448
03:30:14 56469 8192000000 10099988288 10599100416 1144142128 12.254 100 25 50 0 1210420944 61337728 4941088
03:32:40 62702 8192000000 10046666704 10496983040 1093998032 11.781 97 23 46 0 1150736832 51730896 5007904
03:34:43 58628 8192000000 10062979088 10514726912 1079908864 11.647 95 30 60 1 1160695792 74467440 6319488
03:36:43 32595 8192000000 10072966832 10520395776 1129487008 12.117 100 27 54 0 1169113920 34397936 5228976
03:38:16 17933 8192000000 10067443216 10530070528 1108903920 11.922 98 34 68 3 1157048272 41307360 6836992
03:39:42 115221 8192000000 10081403488 10534137856 1081340304 11.660 96 31 62 2 1164869856 76666656 6862896
03:42:38 38898 8192000000 10025657200 10473500672 1041425680 11.278 94 25 50 1 1077058128 30031104 5601344
03:44:27 64930 8192000000 10034059376 10490159104 1074905920 11.599 98 12 24 0 1122396736 45332928 2157888
03:46:38 59734 8192000000 10066544672 10516242432 1051803168 11.378 94 19 38 0 1131966208 76216272 3946768
03:48:38 45320 8192000000 10064604832 10523885568 1101214320 11.849 99 23 46 0 1172698832 66641568 4842944
03:50:24 43080 8192000000 10112439312 10548170752 1099615776 11.834 98 36 70 0 1178380640 71174816 7590048
03:52:07 57583 8192000000 10079269920 10554396672 1110111712 11.933 99 10 20 0 1212466592 100097856 2257024
03:54:05 28397 8192000000 10107622128 10533986304 1108827008 11.921 98 34 68 0 1178462528 62873184 6762336
03:55:57 37435 8192000000 10140701152 10580008960 1110814608 11.940 99 44 88 1 1233480992 113999408 8666976
03:57:39 18049 8192000000 10058429904 10505003008 1101564544 11.852 98 32 64 1 1139627328 31530784 6532000
03:59:03 50217 8192000000 10065605904 10536480768 1109108624 11.924 98 29 58 1 1169632608 54222688 6301296
04:00:59 24579 8192000000 10072461888 10520555520 1122474464 12.050 100 42 83 0 1182654000 50795488 9384048
04:02:25 44234 8192000000 10051855888 10506543104 1110442704 11.937 98 14 28 0 1157008400 43432016 3133680
04:04:16 73029 8192000000 10070300992 10541285376 1113110672 11.962 98 14 28 0 1159435536 43464704 2860160
04:06:29 72151 8192000000 10081881280 10517643264 1131627712 12.137 99 14 28 1 1174414624 39741056 3045856
04:08:43 75047 8192000000 10100652992 10570493952 1141146720 12.226 99 19 38 4 1225566560 80549744 3870096
04:11:10 30417 8192000000 10082602992 10518056960 1141478448 12.229 100 32 63 0 1178683392 30266416 6938528
04:12:46 36738 8192000000 10082052784 10530988032 1135587296 12.174 99 28 56 0 1176099264 34717856 5794112
04:14:30 26460 8192000000 10072694160 10529820672 1094385488 11.784 96 37 74 1 1146529312 45185760 6958064
04:15:58 123826 8192000000 10089801472 10541867008 1087779376 11.722 96 26 52 0 1144093632 50869312 5444944
04:19:16 54762 8192000000 10071847376 10549809152 1124285760 12.067 100 10 20 0 1178276192 51988800 2001632
04:21:53 86288 8192000000 10069663872 10543017984 1112548832 11.957 98 19 38 0 1161029552 43793440 4687280
04:24:20 33645 8192000000 10095350896 10557587456 1124246608 12.067 100 18 36 0 1184676064 56441616 3987840
04:25:59 55182 8192000000 10077278800 10527514624 1122394192 12.050 99 16 33 0 1148737056 22998080 3344784
04:28:10 36108 8192000000 10082898576 10519511040 1099632768 11.834 98 19 38 1 1173100912 69685920 3782224
04:30:00 59668 8192000000 10082397552 10532814848 1096152544 11.801 97 28 56 1 1150253328 48549296 5551488
04:32:02 55721 8192000000 10098643008 10541498368 1089053184 11.734 96 25 50 0 1156885088 62503152 5328752
04:34:05 96794 8192000000 10046731728 10534760448 1076784736 11.617 96 19 38 0 1182972464 102254480 3933248
04:36:47 26518 8192000000 9292378576 9609519104 430067248 4.987 39 3 5 0 445319424 14728480 523696

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 06-10-2021 04:39:17 to 06-10-2021 04:39:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-10-2021 04:39:34 to 06-10-2021 04:39:36.

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          243493
MON$OLDEST_ACTIVE               243749
MON$OLDEST_SNAPSHOT             243749
MON$NEXT_TRANSACTION            243749
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                       2054496
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             816
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

06.10.21 04:39:37. 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
2021-10-06 04:39:39 No severe PSQL-related problems occured
06.10.21 04:39:40. End of report.