Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.12.2021 10: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 16403996 kB
MemAvailable 31468832 kB
Buffers 0 kB
Cached 15312684 kB
SwapCached 0 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-12-18 10:37:10
DTS_END                         2021-12-18 10:37:10.2140
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/37602
TRN_ID                          246314
ATT_ID                          701
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: 9354.46 24706 1122535
customer order: creation 180.38 360 21646
customer order: refuse 15.65 314 1878
order to supplier: creation 47.04 1375 5645
order to supplier: removal 3.43 108657 412
invoice (draft): creation 48.83 3308 5859
invoice (draft): removal 52.17 895 6260
invoice accept: apply 44.20 18589 5304
invoice accept: cancel 46.50 761 5580
customer reserve: creation 4039.98 187 484797
customer reserve: removal 4576.13 64 549135
realization accept: apply 87.69 164 10523
realization accept: cancel 76.15 118 9138
payment from customer: creation 63.41 95 7609
payment from customer: removal 5.88 96 706
payment to supplier: creation 51.88 136 6225
payment to supplier: removal 3.64 79 437
service: total inventory turnovers 4.16 3372 499
service: total monetary turnovers 6.38 484 766
service: aggregate perf. data 0.93 18035 112
service: refresh index statistics 0.03 337027 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-12-2021 10:39:59 to 18-12-2021 10:40:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5093
TEST_TIME 2 4439
TEST_TIME 3 4318
TEST_TIME 4 5145
TEST_TIME 5 5228
TEST_TIME 6 5422
TEST_TIME 7 5415
TEST_TIME 8 5757
TEST_TIME 9 5964
TEST_TIME 10 6085
TEST_TIME 11 6073
TEST_TIME 12 6279
TEST_TIME 13 6336
TEST_TIME 14 6910
TEST_TIME 15 7115
TEST_TIME 16 7624
TEST_TIME 17 7653
TEST_TIME 18 8131
TEST_TIME 19 8050
TEST_TIME 20 8252
TEST_TIME 21 8755
TEST_TIME 22 9159
TEST_TIME 23 9350
TEST_TIME 24 9561
TEST_TIME 25 9939
TEST_TIME 26 10176
TEST_TIME 27 10260
TEST_TIME 28 11440
TEST_TIME 29 11232
TEST_TIME 30 11089
TEST_TIME 31 11048
TEST_TIME 32 10902
TEST_TIME 33 10866
TEST_TIME 34 10846
TEST_TIME 35 11022
TEST_TIME 36 10731
TEST_TIME 37 10502
TEST_TIME 38 10343
TEST_TIME 39 10262
TEST_TIME 40 10336
TEST_TIME 41 10361
TEST_TIME 42 10234
TEST_TIME 43 10085
TEST_TIME 44 10076
TEST_TIME 45 9987
TEST_TIME 46 10006
TEST_TIME 47 10101
TEST_TIME 48 10037
TEST_TIME 49 10008
TEST_TIME 50 10057
TEST_TIME 51 10006
TEST_TIME 52 10048
TEST_TIME 53 9996
TEST_TIME 54 10072
TEST_TIME 55 10236
TEST_TIME 56 10204
TEST_TIME 57 10256
TEST_TIME 58 10140
TEST_TIME 59 10017
TEST_TIME 60 10004
TEST_TIME 61 9909
TEST_TIME 62 9864
TEST_TIME 63 9908
TEST_TIME 64 9809
TEST_TIME 65 9821
TEST_TIME 66 9720
TEST_TIME 67 9714
TEST_TIME 68 9673
TEST_TIME 69 9650
TEST_TIME 70 9578
TEST_TIME 71 9679
TEST_TIME 72 9742
TEST_TIME 73 9834
TEST_TIME 74 9813
TEST_TIME 75 9831
TEST_TIME 76 9801
TEST_TIME 77 9777
TEST_TIME 78 9826
TEST_TIME 79 9838
TEST_TIME 80 9836
TEST_TIME 81 9867
TEST_TIME 82 9769
TEST_TIME 83 9724
TEST_TIME 84 9706
TEST_TIME 85 9712
TEST_TIME 86 9665
TEST_TIME 87 9625
TEST_TIME 88 9574
TEST_TIME 89 9516
TEST_TIME 90 9483
TEST_TIME 91 9442
TEST_TIME 92 9453
TEST_TIME 93 9494
TEST_TIME 94 9479
TEST_TIME 95 9515
TEST_TIME 96 9531
TEST_TIME 97 9509
TEST_TIME 98 9527
TEST_TIME 99 9577
TEST_TIME 100 9652
TEST_TIME 101 9660
TEST_TIME 102 9623
TEST_TIME 103 9539
TEST_TIME 104 9510
TEST_TIME 105 9495
TEST_TIME 106 9457
TEST_TIME 107 9474
TEST_TIME 108 9433
TEST_TIME 109 9406
TEST_TIME 110 9382
TEST_TIME 111 9372
TEST_TIME 112 9369
TEST_TIME 113 9436
TEST_TIME 114 9418
TEST_TIME 115 9385
TEST_TIME 116 9450
TEST_TIME 117 9429
TEST_TIME 118 9458
TEST_TIME 119 9432
TEST_TIME 120 9356

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-12-2021 10:40:03 to 18-12-2021 10:40:06.

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 1570524 1570524 0 0.00 0 18932 7 0 0
doc_list_biud 1570521 1570521 0 0.00 0 36672 28 0 0
sp_add_invoice_to_stock 5387 5304 83 1.54 29 201786 18589 0 83
sp_cancel_adding_invoice 5580 5580 0 0.00 6 97152 761 0 0
sp_cancel_client_order 1878 1878 0 0.00 5 11714 314 0 0
sp_cancel_customer_reserve 549135 549135 0 0.00 1 36682 65 0 0
sp_cancel_pay_from_customer 706 706 0 0.00 3 8568 96 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 3 6046 79 0 0
sp_cancel_supplier_invoice 6263 6260 3 0.04 2 19792 896 0 3
sp_cancel_supplier_order 412 412 0 0.00 8 1065758 108658 0 0
sp_cancel_write_off 9138 9138 0 0.00 1 12479 118 0 0
sp_client_order 21646 21646 0 0.00 4 27610 361 0 0
sp_customer_reserve 484797 484797 0 0.00 0 94413 188 0 0
sp_fill_shopping_cart 33141 33141 0 0.00 1 35939 41 0 0
sp_fill_shopping_cart_clo_res 484794 484794 0 0.00 0 38041 14 0 0
sp_get_clo_for_invoice 5301 5301 0 0.00 0 44768 338 0 0
sp_kill_cost_storno 15861 15861 0 0.00 0 12477 56 0 0
sp_kill_qstorno_ret_qs2qd 1114080 1114080 0 0.00 0 36669 35 0 0
sp_kill_qty_storno 588227 588227 0 0.00 0 36672 75 0 0
sp_lock_selected_doc 21934 21934 0 0.00 0 9642 5 0 0
sp_make_cost_storno 29658 29658 0 0.00 0 18930 52 0 0
sp_multiply_rows_for_pdistr 29658 29658 0 0.00 0 8337 28 0 0
sp_multiply_rows_for_qdistr 21646 21646 0 0.00 0 14758 179 0 0
sp_pay_from_customer 7609 7609 0 0.00 4 15340 96 0 0
sp_pay_to_supplier 6225 6225 0 0.00 5 18938 136 0 0
sp_payment_common 13834 13834 0 0.00 1 10815 21 0 0
sp_qd_handle_on_cancel_clo 1878 1878 0 0.00 0 11707 285 0 0
sp_qd_handle_on_invoice_upd_sts 10881 10881 0 0.00 0 15982 314 0 0
sp_qd_handle_on_reserve_upd_sts 10523 10523 0 0.00 0 16816 45 0 0
sp_reserve_write_off 10523 10523 0 0.00 7 16880 164 0 0
sp_supplier_invoice 5859 5859 0 0.00 7 96503 3308 0 0
sp_supplier_order 5645 5645 0 0.00 5 40930 1375 0 0
srv_aggregate_perf_data 112 112 0 0.00 3125 289504 18036 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1909 135026 42694 0 0
srv_find_qd_qs_mism 1071893 1071893 0 0.00 0 10862 7 0 0
srv_make_invnt_saldo 499 499 0 0.00 43 88165 3372 0 0
srv_make_money_saldo 766 766 0 0.00 1 21447 485 0 0
srv_recalc_idx_stat 4 4 0 0.00 261583 470750 337028 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5304 5304 0 0.00 0 21975 168 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 4278 87 0 0
t$perf-norm:sp_cancel_client_order 1878 1878 0 0.00 0 8157 17 0 0
t$perf-norm:sp_cancel_customer_reserve 759 759 0 0.00 0 3323 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 706 706 0 0.00 0 1455 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 3489 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 429 429 0 0.00 0 461 10 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 22920 1460 0 0
t$perf-norm:sp_cancel_write_off 1077 1077 0 0.00 0 4567 8 0 0
t$perf-norm:sp_client_order 21646 21646 0 0.00 0 6096 16 0 0
t$perf-norm:sp_customer_reserve 1071 1071 0 0.00 0 3838 6 0 0
t$perf-norm:sp_pay_from_customer 7609 7609 0 0.00 0 4163 10 0 0
t$perf-norm:sp_pay_to_supplier 6225 6225 0 0.00 0 18095 13 0 0
t$perf-norm:sp_reserve_write_off 10523 10523 0 0.00 0 7421 13 0 0
t$perf-norm:sp_supplier_invoice 5859 5859 0 0.00 0 8923 21 0 0
t$perf-norm:sp_supplier_order 5645 5645 0 0.00 0 7638 15 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 2985 30 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 39 1 0 0
t$perf-norm:srv_make_invnt_saldo 499 499 0 0.00 0 1321 12 0 0
t$perf-norm:srv_make_money_saldo 766 766 0 0.00 0 970 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 179 55 0 0
v_add_invoice_to_stock 10764 10598 166 1.54 0 5077 19 0 166
v_all_customers 39650 39650 0 0.00 0 78 1 0 0
v_all_suppliers 31988 31988 0 0.00 0 40 1 0 0
v_all_wares 43292 43292 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 4106 14 0 0
v_cancel_client_order 3756 3756 0 0.00 0 5043 9 0 0
v_cancel_customer_prepayment 1412 1412 0 0.00 0 5638 13 0 0
v_cancel_customer_reserve 1518 1518 0 0.00 0 3016 10 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 747 6 0 0
v_cancel_supplier_invoice 864 858 6 0.69 0 2835 18 0 6
v_cancel_supplier_order 824 824 0 0.00 0 1705 6 0 0
v_cancel_write_off 2154 2154 0 0.00 0 1827 16 0 0
v_max_id_clo_ord 5644 5644 0 0.00 0 684 1 0 0
v_max_id_clo_res 1071 1071 0 0.00 0 1990 10 0 0
v_max_id_ord_sup 5851 5851 0 0.00 0 5724 6 0 0
v_max_non_paid_invoice 1726 1726 0 0.00 0 2437 3 0 0
v_max_non_paid_realizn 4009 4009 0 0.00 0 1652 3 0 0
v_min_id_clo_ord 5644 5644 0 0.00 0 4369 7 0 0
v_min_id_clo_res 1071 1071 0 0.00 0 6520 17 0 0
v_min_id_ord_sup 5851 5851 0 0.00 0 5233 7 0 0
v_min_non_paid_invoice 6225 6225 0 0.00 0 10810 15 0 0
v_min_non_paid_realizn 7609 7609 0 0.00 0 6527 15 0 0
v_our_firm 10842 10842 0 0.00 0 16 1 0 0
v_reserve_write_off 21046 21046 0 0.00 0 5086 9 0 0
x_lock_depdocs_on_canc_invoice 11411 11411 0 0.00 0 13634 115 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 3454 314 0 0
x_make_qstorno_1000_1200 5645 5645 0 0.00 2 40923 1301 0 0
x_make_qstorno_1200_2000 5859 5859 0 0.00 3 96475 3151 0 0
x_make_qstorno_2100_3300 484797 484797 0 0.00 0 94412 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-12-2021 10:40:07 to 18-12-2021 10:40:11.

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
08:07:13 1027 8192000000 8962267056 9025433600 3901568 0.047 2 1 2 0 4000880 63536 35776
08:08:18 33105 8192000000 9719968304 10017927168 790841088 8.803 100 83 166 0 818844496 7732112 20271296
08:09:52 19183 8192000000 9752716784 10056978432 819029200 9.089 100 90 179 0 853751280 13039360 21682720
08:11:20 8574 8192000000 9797766816 10114256896 861748592 9.518 100 83 166 0 899855744 18922832 19184320
08:12:35 13040 8192000000 9878520576 10210885632 913338368 10.030 100 82 164 0 984287552 52985248 17963936
08:13:54 7281 8192000000 9926351104 10272120832 953846416 10.429 100 78 156 0 1026613808 56932224 15835168
08:15:04 7683 8192000000 9958133472 10315247616 989694384 10.778 100 77 154 0 1068426464 63129248 15602832
08:16:14 3005 8192000000 9979539168 10342023168 1014508816 11.019 100 78 153 3 1080060416 49515008 16036592
08:17:18 2545 8192000000 10021390736 10386472960 1034459968 11.211 100 87 174 0 1122361632 69915040 17986624
08:18:22 1462 8192000000 10049352080 10417700864 1053765088 11.397 100 91 177 0 1146872144 74663888 18443168
08:19:25 3886 8192000000 10033763088 10426671104 1055093680 11.410 100 48 96 0 1133260336 68646384 9520272
08:20:32 5694 8192000000 9992796032 10392682496 1067884016 11.532 100 35 70 1 1123427152 48730672 6812464
08:21:39 8221 8192000000 9985629888 10385657856 1076141792 11.611 100 35 71 1 1100782880 17599104 7041984
08:22:49 21468 8192000000 10011285648 10427830272 1076407648 11.613 100 44 89 1 1110473072 23591072 10474352
08:24:11 6730 8192000000 10019078032 10413752320 1083037584 11.676 100 33 67 3 1107942368 18146864 6757920
08:25:18 5803 8192000000 10012710352 10402316288 1083674592 11.682 100 44 86 4 1100828112 7272992 9880528
08:26:26 6715 8192000000 10016636080 10424905728 1074587712 11.596 99 27 54 0 1099545696 19419200 5538784
08:27:39 29807 8192000000 10031222544 10446843904 1064922944 11.504 98 36 71 1 1089292656 16578992 7790720
08:29:15 6636 8192000000 9972377872 10377080832 1039717392 11.262 99 32 64 3 1094874688 48087728 7069568
08:30:23 5250 8192000000 9940193952 10319142912 988079648 10.763 97 43 82 1 1014035280 16582112 9373520
08:31:30 7031 8192000000 9957668624 10339270656 1028338368 11.152 100 35 70 1 1065144384 29065888 7740128
08:32:38 49565 8192000000 9939510576 10335404032 1038305392 11.248 100 32 64 1 1056525808 10480288 7740128
08:34:31 6726 8192000000 9960812080 10349776896 1045557584 11.318 99 36 73 0 1076568288 23639328 7371376
08:35:38 7734 8192000000 9987682784 10378223616 1063816400 11.493 100 50 99 1 1086741888 11205792 11719696
08:36:47 7963 8192000000 9996118848 10396786688 1074410992 11.594 100 38 76 1 1092169376 8697152 9061232
08:38:02 54091 8192000000 10052136736 10439434240 1088279072 11.726 100 46 92 0 1137387904 38872544 10236288
08:40:50 14258 8192000000 10051467152 10466185216 1103703536 11.873 100 49 98 1 1166820816 52239168 10878112
08:42:12 12950 8192000000 10089524720 10494369792 1122506880 12.051 100 52 103 1 1195374048 61876256 10990912
08:43:27 11170 8192000000 10114435616 10523389952 1131011744 12.131 100 53 103 2 1205568352 62627552 11929056
08:44:42 5678 8192000000 10193927440 10591305728 1149484864 12.305 100 75 148 1 1293525392 128762096 15278432
08:45:53 3252 8192000000 10199943712 10619392000 1158278752 12.387 100 55 110 1 1317308800 146840096 12189952
08:47:01 13358 8192000000 10241245360 10648805376 1178418016 12.575 100 50 100 1 1334543120 144972752 11152352
08:48:18 6057 8192000000 10260762432 10668658688 1188433744 12.669 100 51 100 2 1368047264 168236000 11377520
08:49:28 1906 8192000000 10333475472 10737864704 1179647760 12.587 99 76 151 2 1429131120 232274512 17208848
08:50:34 3791 8192000000 10390420416 10801549312 1181296080 12.602 100 75 149 0 1493802096 295365776 17140240
08:51:44 4080 8192000000 10351876464 10766495744 1166695152 12.466 100 56 112 0 1462755296 282882496 13177648
08:52:58 8119 8192000000 10388982368 10797404160 1163841616 12.439 100 60 118 1 1485891568 308223760 13826192
08:54:15 4005 8192000000 10392647472 10794418176 1167459408 12.473 100 74 147 0 1489096272 304563920 17072944
08:55:22 11479 8192000000 10392140464 10816729088 1150381904 12.313 99 38 76 1 1485215312 325275984 9557424
08:56:46 4258 8192000000 10437162608 10852855808 1155686496 12.363 100 60 119 0 1537871856 367976368 14208992
08:57:54 4494 8192000000 10451626896 10863063040 1159426688 12.398 100 56 113 3 1551501872 377806112 14269072
08:59:02 15681 8192000000 10400077840 10857443328 1145120848 12.264 100 39 78 0 1507664800 352463472 10080480
09:00:24 24199 8192000000 10381559968 10835832832 1140506208 12.220 100 26 51 0 1509777616 362212304 7059104
09:01:51 14917 8192000000 10380817008 10810359808 1142998688 12.244 100 30 60 0 1470415536 319818048 7598800
09:03:11 28072 8192000000 10335856672 10799890432 1131632512 12.137 99 22 44 1 1437432880 299747472 6052896
09:05:13 29050 8192000000 10088766048 10566381568 1147657024 12.287 100 14 28 1 1235033456 84609872 2766560
09:07:06 23908 8192000000 10071207312 10500812800 1124522000 12.070 99 31 61 1 1145731392 14891856 6317536
09:08:36 31511 8192000000 10044664192 10470359040 1091559024 11.757 97 24 48 2 1116013184 19080800 5373360
09:10:08 43936 8192000000 10100197440 10558660608 1117890192 12.007 99 19 38 1 1192402816 70137040 4375584
09:11:57 70885 8192000000 10061164064 10555936768 1117203856 12.001 99 10 20 0 1166183760 46932880 2047024
09:14:08 135026 8192000000 10105408912 10542448640 1121984560 12.046 99 47 90 0 1170141376 38137280 10019536
09:17:24 36184 8192000000 10096762112 10566590464 1124323904 12.068 98 20 39 2 1183798400 55210224 4264272
09:19:01 19601 8192000000 10062472960 10508550144 1144309536 12.256 100 14 26 1 1176508128 29779872 2418720
09:20:21 63753 8192000000 10125570496 10596040704 1145607168 12.268 100 19 38 0 1216278432 66680368 3990896
09:22:31 87814 8192000000 10122694320 10600423424 1152729840 12.335 100 23 46 1 1239913600 82016880 5166880
09:25:00 40351 8192000000 10077624384 10522288128 1123974112 12.065 98 15 30 1 1156737632 29618848 3144672
09:26:45 9551 8192000000 10071024592 10509500416 1136456080 12.182 99 45 90 1 1165294752 19037184 9801488
09:27:58 45719 8192000000 10082411712 10533314560 1146672080 12.278 100 20 40 1 1184286288 33102000 4512208
09:29:48 110472 8192000000 10117004192 10613297152 1126441920 12.088 98 18 35 0 1221022336 90839648 3740768
09:32:42 83213 8192000000 10091466640 10553487360 1125106816 12.075 98 15 30 1 1199596448 71437984 3051648
09:35:17 95449 8192000000 10081788752 10530893824 1126822000 12.091 99 18 35 2 1188671792 58206416 3643376
09:38:15 47590 8192000000 10077260368 10519252992 1086927568 11.713 97 39 78 0 1142277472 47264736 8085168
09:40:03 38732 8192000000 10104531168 10578829312 1097206448 11.811 97 17 34 0 1167587536 66422976 3958112
09:42:09 53630 8192000000 10063991152 10548649984 1119253072 12.020 100 11 22 0 1188118816 65906768 2958976
09:44:07 54437 8192000000 10015219904 10480873472 1073873712 11.589 96 5 10 1 1110451952 35697520 880720
09:46:08 29796 8192000000 10054386688 10496618496 1070942224 11.561 96 20 40 1 1120075584 45095760 4037600
09:47:43 41880 8192000000 10148254096 10584076288 1108012992 11.914 98 40 80 1 1206433680 89342176 9078512
09:49:26 55024 8192000000 10086067296 10571956224 1125053328 12.075 100 14 27 0 1220261168 92205808 3002032
09:52:43 38844 8192000000 10065120480 10507243520 1071747296 11.569 95 21 42 0 1114376000 38636576 3992128
09:54:22 15797 8192000000 10091433552 10516140032 1111584144 11.947 99 41 82 0 1149452720 29951584 7916992
09:55:47 47841 8192000000 10090982352 10561077248 1112755376 11.958 99 34 69 0 1196106112 76415664 6935072
09:57:46 22071 8192000000 10111100112 10535276544 1112959824 11.960 98 43 86 3 1176642912 54692864 8990224
09:59:09 102788 8192000000 10066392368 10501476352 1092339184 11.765 97 24 48 1 1130247600 33065728 4842688
10:01:54 10913 8192000000 10069101872 10544455680 1121269376 12.039 99 21 42 0 1176196384 50271184 4655824
10:03:20 29754 8192000000 10038674128 10495217664 1134633904 12.165 100 6 12 0 1157967984 22540304 793776
10:04:50 66150 8192000000 10041414224 10495934464 1106176272 11.896 98 9 18 0 1135532704 27545744 1810688
10:06:56 77491 8192000000 10073903088 10547494912 1106307776 11.897 98 14 28 0 1143682848 34559248 2815824
10:09:14 51332 8192000000 10128925040 10598735872 1131186640 12.133 99 23 43 2 1199377680 63136704 5054336
10:11:44 59627 8192000000 10091161296 10560794624 1135929008 12.177 99 22 41 0 1232206160 92034192 4242960
10:13:44 48464 8192000000 10129509872 10576818176 1115203024 11.982 97 31 62 0 1183370208 61870464 6296720
10:15:33 47434 8192000000 10112356608 10640838656 1102832608 11.865 97 24 47 1 1216835856 109038992 4964256
10:17:23 98733 8192000000 10084737760 10543939584 1090257216 11.745 96 17 34 0 1174837968 81383952 3196800
10:20:05 96372 8192000000 10140593856 10595303424 1128702240 12.109 98 48 96 0 1209267088 70856560 9708288
10:23:04 77126 8192000000 10087686896 10572709888 1121326048 12.040 99 11 22 0 1184924304 61283920 2314336
10:25:28 79748 8192000000 10077011712 10534072320 1071413312 11.566 94 21 40 1 1133144128 56634496 5096320
10:28:04 126839 8192000000 10107940464 10618466304 1089084112 11.734 96 21 41 0 1192210128 98459712 4666304
10:31:14 27947 8192000000 10060337152 10489487360 1079283024 11.641 95 27 54 0 1106658160 21898032 5477104
10:32:42 56340 8192000000 10110715408 10589294592 1097280688 11.812 97 34 67 1 1191073792 87113184 6679920
10:34:40 42087 8192000000 10113291840 10547175424 1110387472 11.936 98 35 70 0 1188228224 70631232 7209520
10:36:32 58010 8192000000 9356090704 9694728192 490217184 5.646 44 5 10 0 511938880 20757280 964416

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-12-2021 10:40:13 to 18-12-2021 10:40:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 83 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-12-2021 10:40:29 to 18-12-2021 10:40:31.

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          246853
MON$OLDEST_ACTIVE               246854
MON$OLDEST_SNAPSHOT             246854
MON$NEXT_TRANSACTION            246854
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                       2060832
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             821
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

18.12.21 10:40:32. 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-12-18 10:40:35 No severe PSQL-related problems occured
18.12.21 10:40:36. End of report.