Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.09.2021 13:37
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 16059868 kB
MemAvailable 30870720 kB
Buffers 0 kB
Cached 15627052 kB
SwapCached 340 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-09-08 13:37:05
DTS_END                         2021-09-08 13:37:05.2870
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47730
TRN_ID                          233084
ATT_ID                          700
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: 8985.38 22466 1078246
customer order: creation 169.43 409 20331
customer order: refuse 14.98 351 1797
order to supplier: creation 43.98 1563 5278
order to supplier: removal 3.20 130165 384
invoice (draft): creation 44.74 3574 5369
invoice (draft): removal 51.30 957 6156
invoice accept: apply 40.27 18937 4832
invoice accept: cancel 45.84 856 5501
customer reserve: creation 3765.28 184 451834
customer reserve: removal 4518.18 72 542182
realization accept: apply 81.57 167 9788
realization accept: cancel 80.68 129 9682
payment from customer: creation 58.75 122 7050
payment from customer: removal 5.72 88 686
payment to supplier: creation 47.83 142 5739
payment to supplier: removal 3.57 103 428
service: total inventory turnovers 3.39 4148 407
service: total monetary turnovers 5.83 596 699
service: aggregate perf. data 0.83 17594 99
service: refresh index statistics 0.03 269168 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-09-2021 13:37:42 to 08-09-2021 13:37:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5601
TEST_TIME 2 6649
TEST_TIME 3 6882
TEST_TIME 4 6303
TEST_TIME 5 5972
TEST_TIME 6 6004
TEST_TIME 7 5926
TEST_TIME 8 6077
TEST_TIME 9 6346
TEST_TIME 10 6926
TEST_TIME 11 7187
TEST_TIME 12 7785
TEST_TIME 13 7581
TEST_TIME 14 8046
TEST_TIME 15 8179
TEST_TIME 16 8542
TEST_TIME 17 8475
TEST_TIME 18 8475
TEST_TIME 19 8664
TEST_TIME 20 8697
TEST_TIME 21 8600
TEST_TIME 22 8843
TEST_TIME 23 8664
TEST_TIME 24 8960
TEST_TIME 25 9101
TEST_TIME 26 9591
TEST_TIME 27 10423
TEST_TIME 28 11543
TEST_TIME 29 11724
TEST_TIME 30 11532
TEST_TIME 31 11236
TEST_TIME 32 10995
TEST_TIME 33 10768
TEST_TIME 34 10531
TEST_TIME 35 10568
TEST_TIME 36 10480
TEST_TIME 37 10374
TEST_TIME 38 10221
TEST_TIME 39 10200
TEST_TIME 40 10088
TEST_TIME 41 10056
TEST_TIME 42 9900
TEST_TIME 43 9939
TEST_TIME 44 9823
TEST_TIME 45 9629
TEST_TIME 46 9550
TEST_TIME 47 9457
TEST_TIME 48 9397
TEST_TIME 49 9436
TEST_TIME 50 9403
TEST_TIME 51 9358
TEST_TIME 52 9294
TEST_TIME 53 9340
TEST_TIME 54 9455
TEST_TIME 55 9435
TEST_TIME 56 9511
TEST_TIME 57 9465
TEST_TIME 58 9580
TEST_TIME 59 9517
TEST_TIME 60 9501
TEST_TIME 61 9593
TEST_TIME 62 9597
TEST_TIME 63 9463
TEST_TIME 64 9439
TEST_TIME 65 9416
TEST_TIME 66 9422
TEST_TIME 67 9354
TEST_TIME 68 9313
TEST_TIME 69 9217
TEST_TIME 70 9129
TEST_TIME 71 9100
TEST_TIME 72 9129
TEST_TIME 73 9123
TEST_TIME 74 9123
TEST_TIME 75 9119
TEST_TIME 76 9192
TEST_TIME 77 9156
TEST_TIME 78 9145
TEST_TIME 79 9131
TEST_TIME 80 9209
TEST_TIME 81 9309
TEST_TIME 82 9326
TEST_TIME 83 9291
TEST_TIME 84 9192
TEST_TIME 85 9141
TEST_TIME 86 9120
TEST_TIME 87 9044
TEST_TIME 88 9013
TEST_TIME 89 8972
TEST_TIME 90 8996
TEST_TIME 91 9068
TEST_TIME 92 9139
TEST_TIME 93 9128
TEST_TIME 94 9154
TEST_TIME 95 9114
TEST_TIME 96 9123
TEST_TIME 97 9156
TEST_TIME 98 9200
TEST_TIME 99 9188
TEST_TIME 100 9234
TEST_TIME 101 9214
TEST_TIME 102 9240
TEST_TIME 103 9163
TEST_TIME 104 9112
TEST_TIME 105 9080
TEST_TIME 106 9065
TEST_TIME 107 9018
TEST_TIME 108 8974
TEST_TIME 109 8970
TEST_TIME 110 8973
TEST_TIME 111 8956
TEST_TIME 112 8975
TEST_TIME 113 8972
TEST_TIME 114 8999
TEST_TIME 115 9007
TEST_TIME 116 9018
TEST_TIME 117 9051
TEST_TIME 118 9088
TEST_TIME 119 9062
TEST_TIME 120 8988

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-09-2021 13:37:45 to 08-09-2021 13:37:49.

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 1496580 1496580 0 0.00 0 18106 7 0 0
doc_list_biud 1496579 1496579 0 0.00 0 35761 33 0 0
sp_add_invoice_to_stock 4924 4832 92 1.86 37 197125 18937 0 92
sp_cancel_adding_invoice 5501 5501 0 0.00 6 78044 857 0 0
sp_cancel_client_order 1797 1797 0 0.00 4 9824 351 0 0
sp_cancel_customer_reserve 542182 542182 0 0.00 1 24615 73 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 3 13116 88 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 3 5048 103 0 0
sp_cancel_supplier_invoice 6162 6156 6 0.09 3 35774 958 0 6
sp_cancel_supplier_order 384 384 0 0.00 7 1150310 130165 0 0
sp_cancel_write_off 9682 9682 0 0.00 1 18107 129 0 0
sp_client_order 20331 20331 0 0.00 4 20007 410 0 0
sp_customer_reserve 451834 451834 0 0.00 0 95150 185 0 0
sp_fill_shopping_cart 30961 30961 0 0.00 1 27319 49 0 0
sp_fill_shopping_cart_clo_res 451826 451826 0 0.00 0 13573 16 0 0
sp_get_clo_for_invoice 4824 4824 0 0.00 0 22982 369 0 0
sp_kill_cost_storno 16297 16297 0 0.00 0 18106 66 0 0
sp_kill_qstorno_ret_qs2qd 1100586 1100586 0 0.00 0 35760 39 0 0
sp_kill_qty_storno 580313 580313 0 0.00 0 35761 84 0 0
sp_lock_selected_doc 20356 20356 0 0.00 0 8668 4 0 0
sp_make_cost_storno 27401 27401 0 0.00 0 15198 57 0 0
sp_multiply_rows_for_pdistr 27401 27401 0 0.00 0 8503 31 0 0
sp_multiply_rows_for_qdistr 20331 20331 0 0.00 0 15250 209 0 0
sp_pay_from_customer 7050 7050 0 0.00 4 15203 122 0 0
sp_pay_to_supplier 5739 5739 0 0.00 5 16672 142 0 0
sp_payment_common 12789 12789 0 0.00 1 11259 24 0 0
sp_qd_handle_on_cancel_clo 1797 1797 0 0.00 0 9796 322 0 0
sp_qd_handle_on_invoice_upd_sts 10324 10324 0 0.00 0 26122 382 0 0
sp_qd_handle_on_reserve_upd_sts 9788 9788 0 0.00 0 8757 50 0 0
sp_reserve_write_off 9788 9788 0 0.00 7 18351 167 0 0
sp_supplier_invoice 5369 5369 0 0.00 6 64830 3574 0 0
sp_supplier_order 5278 5278 0 0.00 6 40381 1563 0 0
srv_aggregate_perf_data 99 99 0 0.00 3109 189273 17595 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1282 162697 45181 0 0
srv_find_qd_qs_mism 1031506 1031506 0 0.00 0 12748 6 0 0
srv_make_invnt_saldo 407 407 0 0.00 44 102065 4148 0 0
srv_make_money_saldo 699 699 0 0.00 1 13607 596 0 0
srv_recalc_idx_stat 4 4 0 0.00 157056 477463 269169 0 0
t$perf-abend:sp_add_invoice_to_stock 92 92 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4833 4833 0 0.00 0 12727 174 0 0
t$perf-norm:sp_cancel_adding_invoice 378 378 0 0.00 0 7330 157 0 0
t$perf-norm:sp_cancel_client_order 1797 1797 0 0.00 0 1408 12 0 0
t$perf-norm:sp_cancel_customer_reserve 706 706 0 0.00 0 1055 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 3429 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 1068 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 3147 28 0 0
t$perf-norm:sp_cancel_supplier_order 384 384 0 0.00 0 46617 1806 0 0
t$perf-norm:sp_cancel_write_off 962 962 0 0.00 0 1865 9 0 0
t$perf-norm:sp_client_order 20331 20331 0 0.00 0 11394 20 0 0
t$perf-norm:sp_customer_reserve 939 939 0 0.00 0 5347 12 0 0
t$perf-norm:sp_pay_from_customer 7050 7050 0 0.00 0 7478 15 0 0
t$perf-norm:sp_pay_to_supplier 5739 5739 0 0.00 0 4992 12 0 0
t$perf-norm:sp_reserve_write_off 9788 9788 0 0.00 0 8858 11 0 0
t$perf-norm:sp_supplier_invoice 5369 5369 0 0.00 0 4367 17 0 0
t$perf-norm:sp_supplier_order 5278 5278 0 0.00 0 12945 26 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 69 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 3360 62 0 0
t$perf-norm:srv_make_invnt_saldo 407 407 0 0.00 0 4180 18 0 0
t$perf-norm:srv_make_money_saldo 699 699 0 0.00 0 489 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9830 9646 184 1.87 0 5436 17 0 184
v_all_customers 36964 36964 0 0.00 0 41 1 0 0
v_all_suppliers 29654 29654 0 0.00 0 72 1 0 0
v_all_wares 40662 40662 0 0.00 0 61 1 0 0
v_cancel_adding_invoice 756 756 0 0.00 0 994 9 0 0
v_cancel_client_order 3594 3594 0 0.00 0 5967 8 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 1577 7 0 0
v_cancel_customer_reserve 1412 1412 0 0.00 0 3268 6 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 2124 9 0 0
v_cancel_supplier_invoice 820 808 12 1.46 0 4095 24 0 12
v_cancel_supplier_order 768 768 0 0.00 0 3807 10 0 0
v_cancel_write_off 1924 1924 0 0.00 0 1303 14 0 0
v_max_id_clo_ord 5271 5271 0 0.00 0 1466 2 0 0
v_max_id_clo_res 939 939 0 0.00 0 5183 19 0 0
v_max_id_ord_sup 5359 5359 0 0.00 0 5006 6 0 0
v_max_non_paid_invoice 1542 1542 0 0.00 0 682 2 0 0
v_max_non_paid_realizn 3774 3774 0 0.00 0 6568 4 0 0
v_min_id_clo_ord 5271 5271 0 0.00 0 3539 8 0 0
v_min_id_clo_res 939 939 0 0.00 0 2792 7 0 0
v_min_id_ord_sup 5359 5359 0 0.00 0 7535 14 0 0
v_min_non_paid_invoice 5739 5739 0 0.00 0 8139 18 0 0
v_min_non_paid_realizn 7050 7050 0 0.00 0 7771 14 0 0
v_our_firm 10250 10250 0 0.00 0 13 1 0 0
v_reserve_write_off 19576 19576 0 0.00 0 5441 5 0 0
x_lock_depdocs_on_canc_invoice 11253 11253 0 0.00 0 18641 119 0 0
x_lock_depdocs_on_canc_sup_ord 384 384 0 0.00 0 9970 396 0 0
x_make_qstorno_1000_1200 5278 5278 0 0.00 2 40373 1475 0 0
x_make_qstorno_1200_2000 5369 5369 0 0.00 3 64749 3383 0 0
x_make_qstorno_2100_3300 451834 451834 0 0.00 0 95138 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-09-2021 13:37:50 to 08-09-2021 13:37:54.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:09 3 8192000000 8798446192 8809598976 4478816 0.054 2 1 2 0 4578208 63616 35776
11:08:09 36437 8192000000 9724476416 10026319872 807052240 8.968 100 91 183 1 837707024 8575088 22079696
11:09:49 6140 8192000000 9775696272 10085396480 852423680 9.424 100 89 178 0 885934896 13056080 20455136
11:11:03 16328 8192000000 9810258800 10129113088 873061984 9.631 100 81 162 1 911811632 20155824 18593824
11:12:27 16143 8192000000 9887887472 10224476160 922411088 10.120 100 79 158 0 989371568 49694048 17266432
11:13:49 9027 8192000000 9928044944 10277195776 962585984 10.514 100 77 155 0 1041773504 63332320 15855200
11:15:00 4744 8192000000 9961774992 10319478784 997055472 10.850 100 79 157 0 1070823360 58238960 15528928
11:16:07 2246 8192000000 9985285008 10348990464 1027871536 11.148 100 73 145 1 1101016704 57559968 15585200
11:17:12 1651 8192000000 10020745152 10388389888 1052394000 11.384 100 88 176 0 1129726656 58949936 18382720
11:18:15 27505 8192000000 10026276400 10416562176 1062055328 11.476 100 66 132 1 1144876256 68869904 13951024
11:19:44 3862 8192000000 10017192288 10409996288 1071497456 11.566 100 52 105 1 1130311680 47703984 11110240
11:20:48 4096 8192000000 10005374288 10402725888 1079977936 11.647 100 42 83 2 1117949232 28870432 9100864
11:21:53 25445 8192000000 10011109264 10415415296 1086139488 11.706 100 50 101 1 1113647040 16425744 11081808
11:23:19 5249 8192000000 10001927968 10408620032 1090303984 11.746 100 22 44 0 1112680192 17247904 5128304
11:24:27 6887 8192000000 10003581152 10410237952 1095976256 11.799 100 32 64 2 1124336896 20364128 7996512
11:25:34 6768 8192000000 10017711440 10417430528 1100900464 11.846 100 34 64 1 1128208848 19883072 7425312
11:26:44 30821 8192000000 9999628352 10412519424 1067747792 11.531 97 21 42 1 1098554816 26456960 4350064
11:28:22 5742 8192000000 9972381408 10367332352 1029216320 11.161 96 44 86 0 1056443520 17276512 9950688
11:29:28 5333 8192000000 9942142560 10333212672 1026749744 11.137 98 45 88 3 1049610528 12720656 10140128
11:30:36 21029 8192000000 9906701648 10298150912 971031776 10.597 95 35 69 0 992701072 14116976 7552320
11:32:02 13876 8192000000 9937414576 10332221440 1005094192 10.928 97 27 54 0 1045773952 34591040 6088720
11:33:20 4605 8192000000 9973929904 10360213504 1043277536 11.296 100 31 61 2 1082016768 32223056 6516176
11:34:28 9805 8192000000 9975756912 10374881280 1053549984 11.395 100 34 68 0 1077689280 16458464 7680832
11:35:43 50244 8192000000 9994566704 10416381952 1063259744 11.488 100 33 64 2 1102680480 32640736 6780000
11:37:35 21140 8192000000 9996317008 10397655040 1083541184 11.681 100 33 64 2 1105227648 14546704 7139760
11:39:14 9078 8192000000 10071725248 10470735872 1108602768 11.919 100 56 111 0 1175070192 54571840 11895584
11:40:26 29379 8192000000 10105047456 10522148864 1124514608 12.070 100 43 85 0 1210012816 76388176 9110032
11:42:15 12208 8192000000 10133086848 10542821376 1135066688 12.169 100 54 106 1 1241821888 95103568 11651632
11:43:39 17044 8192000000 10158086304 10583384064 1133094544 12.151 99 46 92 1 1259354368 116275568 9984256
11:45:05 8986 8192000000 10183878944 10595483648 1147449856 12.286 100 53 105 0 1289050448 129911200 11689392
11:46:17 11719 8192000000 10200134736 10626875392 1154249440 12.349 100 55 105 1 1303013552 137080144 11683968
11:47:35 2391 8192000000 10235689680 10641420288 1164966208 12.450 100 77 154 0 1338719840 157302112 16451520
11:48:41 1505 8192000000 10260360576 10660683776 1162405536 12.426 100 81 160 0 1359000576 179068160 17526880
11:49:46 2884 8192000000 10236853968 10645999616 1160536320 12.408 100 86 168 2 1346060688 165312512 20211856
11:50:53 4373 8192000000 10282549824 10696372224 1164025808 12.441 100 76 152 1 1383981536 202597824 17357904
11:52:03 1280 8192000000 10274152272 10674126848 1154872816 12.355 100 90 178 1 1365971424 190135504 20963104
11:53:10 1512 8192000000 10259857200 10661883904 1140839248 12.223 98 84 170 1 1357789456 197713920 19236288
11:54:15 5770 8192000000 10298598912 10717421568 1155110352 12.357 100 39 78 0 1397736528 233095536 9530640
11:55:28 5529 8192000000 10336099584 10736001024 1139831264 12.214 99 80 160 3 1431293824 272813744 18648816
11:56:36 6413 8192000000 10389869008 10801037312 1158732144 12.391 100 58 116 1 1495182368 322554000 13896224
11:57:49 7642 8192000000 10409444464 10830106624 1151133808 12.320 99 48 95 0 1503078736 340084848 11860080
11:59:01 10779 8192000000 10479804096 10911711232 1171172080 12.508 100 47 94 1 1589169968 405865648 12132240
12:00:33 5342 8192000000 10530090672 10943455232 1139207792 12.208 98 65 130 3 1612206784 455686848 17312144
12:01:40 13202 8192000000 10556956080 11013763072 1150695232 12.316 99 38 76 0 1643020448 482125776 10199440
12:03:04 8497 8192000000 10585008656 11022540800 1148924944 12.299 100 35 70 0 1688162992 529602960 9635088
12:04:31 21826 8192000000 10387536608 10923880448 1116789472 11.997 99 16 32 0 1507394624 385859792 4745360
12:06:33 34238 8192000000 10049526560 10533523456 1102225456 11.859 98 17 34 1 1142755168 37066384 3463328
12:08:08 148433 8192000000 10058680960 10500276224 1101191200 11.849 98 25 48 0 1143086592 37328704 4566688
12:11:43 22336 8192000000 10124432640 10572263424 1133707264 12.156 100 29 59 1 1219813200 79549008 6556928
12:13:56 32594 8192000000 10077048016 10512723968 1132095248 12.141 99 40 78 1 1163925312 23441968 8388096
12:15:31 72829 8192000000 10118558288 10577907712 1139892512 12.215 99 39 78 0 1220822608 72423504 8506592
12:17:49 136947 8192000000 10092868320 10550837248 1126397808 12.087 98 14 28 1 1164681312 35568608 2714896
12:21:10 77523 8192000000 10066716272 10525446144 1131287568 12.133 99 11 23 0 1164521296 30945296 2288432
12:23:35 31334 8192000000 10084232800 10522185728 1141298176 12.228 100 38 76 2 1195245920 46305040 7642704
12:25:16 76894 8192000000 10144589184 10648698880 1126700912 12.090 99 13 26 1 1225732224 96272912 2758400
12:27:44 41379 8192000000 10070595248 10522251264 1135127824 12.170 100 16 32 0 1164746464 26390544 3228096
12:29:25 17470 8192000000 10116541168 10573213696 1146770320 12.279 100 38 75 3 1202646560 47235856 8640384
12:30:45 84308 8192000000 10100381440 10578288640 1138004256 12.197 99 13 26 0 1192467552 51731040 2732256
12:33:10 60803 8192000000 10079770400 10533675008 1119289808 12.020 98 23 44 1 1202599648 78662704 4647136
12:35:17 120877 8192000000 10145668672 10629013504 1131713232 12.138 98 15 30 0 1227510224 92522992 3274000
12:38:18 79001 8192000000 10087754560 10537086976 1117091952 12.000 98 9 19 0 1171740736 53213552 1435232
12:40:37 74880 8192000000 10118138320 10563526656 1102341936 11.860 96 30 59 2 1167614112 58947664 6324512
12:42:54 37562 8192000000 10108359504 10550861824 1105253168 11.887 98 52 104 0 1170879712 54522592 11103952
12:44:42 52842 8192000000 10029596512 10473738240 1078346928 11.632 96 14 28 0 1099449664 18418064 2684672
12:46:43 76920 8192000000 10085283904 10543976448 1113999056 11.970 100 22 44 0 1184633296 65947024 4687216
12:49:10 59522 8192000000 10053154880 10496528384 1088237888 11.726 98 14 28 0 1155417424 63940832 3238704
12:51:28 41906 8192000000 10065890032 10512121856 1099350224 11.831 99 29 57 1 1155328960 49432752 6545984
12:53:10 78253 8192000000 10056400144 10521788416 1090470432 11.747 98 23 46 0 1186035584 90898320 4666832
12:55:29 47879 8192000000 10118174224 10548899840 1092709472 11.768 97 28 56 1 1178967600 80489712 5768416
12:57:21 162697 8192000000 10047736688 10494345216 1051376464 11.374 94 22 44 0 1099154224 43548688 4229072
13:01:07 79191 8192000000 10055672880 10485854208 1094137424 11.782 99 40 79 0 1139879008 37816528 7925056
13:03:36 91575 8192000000 10051031792 10497355776 1074993856 11.600 97 9 18 1 1111585744 34793856 1798032
13:06:08 83162 8192000000 10152877472 10605649920 1097354224 11.813 98 18 36 0 1183881584 82571696 3955664
13:08:42 67288 8192000000 10058578448 10598055936 1115764288 11.987 99 13 26 1 1251851024 133317552 2769184
13:10:52 42571 8192000000 10084533104 10542006272 1138905808 12.205 100 21 43 0 1185591136 42655296 4030032
13:12:39 26070 8192000000 10111909440 10559545344 1128577184 12.108 99 49 98 0 1201179536 62130816 10471536
13:14:07 10980 8192000000 10125756304 10558685184 1129755184 12.119 99 38 75 1 1215246752 77751120 7740448
13:15:19 38971 8192000000 10064112656 10503471104 1135666384 12.175 99 35 71 3 1166043984 23483504 6894096
13:17:05 39079 8192000000 10094979888 10536333312 1133053056 12.150 99 23 45 1 1185241088 47742272 4445760
13:18:47 22960 8192000000 10122698064 10580955136 1142530176 12.239 99 28 56 1 1220182576 71463536 6188864
13:20:11 79021 8192000000 10110741744 10603724800 1148923248 12.299 100 26 52 0 1204585552 50159312 5502992
13:22:44 47488 8192000000 10069248272 10558029824 1149422944 12.304 100 11 20 1 1178081216 26296592 2361680
13:24:41 52051 8192000000 10081233680 10530127872 1133555632 12.155 98 17 33 1 1164284656 27098672 3630352
13:26:38 64810 8192000000 10090781968 10572242944 1140291888 12.218 99 24 48 0 1195129184 49312960 5524336
13:28:44 66745 8192000000 10111083840 10593255424 1095970016 11.799 95 23 46 0 1172529392 71731008 4828368
13:30:51 79233 8192000000 10090686224 10560196608 1131884736 12.139 100 20 39 2 1218159440 81669616 4605088
13:33:11 85504 8192000000 10087809824 10565218304 1129018448 12.112 99 14 28 0 1202285232 69992880 3273904
13:36:04 36908 8192000000 10055284272 10476376064 1090262608 11.745 97 28 57 2 1124588080 28956016 5369456

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 08-09-2021 13:37:56 to 08-09-2021 13:38:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 92 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-09-2021 13:38:11 to 08-09-2021 13:38:13.

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          230825
MON$OLDEST_ACTIVE               233216
MON$OLDEST_SNAPSHOT             233216
MON$NEXT_TRANSACTION            233216
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                       2045600
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             800
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2585 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2585 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2585 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2585 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 ---
:20: 
:21: mscs-test-161.budzdorov.ru    Wed Sep  8 13:37:08 2021
:22:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:23: 
:24: 
:25: mscs-test-161.budzdorov.ru    Wed Sep  8 13:37:09 2021
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
--- end of diff output ---

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

08.09.21 13:38:14. 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-09-08 13:38:17 No severe PSQL-related problems occured
08.09.21 13:38:18. End of report.