Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.12.2020 13:28
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16767256 kB
MemAvailable 31408344 kB
Buffers 0 kB
Cached 14843212 kB
SwapCached 3700 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.0
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 2020-12-23 13:26:55
DTS_END                         2020-12-23 13:26:55.4480
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(127:13) ==> X_MAKE_QSTORNO_2100_3300(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51698
TRN_ID                          242149
ATT_ID                          714
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: 8963.57 20697 1075628
customer order: creation 178.58 390 21429
customer order: refuse 15.37 270 1844
order to supplier: creation 46.66 1317 5599
order to supplier: removal 3.12 115677 374
invoice (draft): creation 47.65 3463 5718
invoice (draft): removal 46.84 904 5621
invoice accept: apply 42.93 15897 5151
invoice accept: cancel 43.09 963 5171
customer reserve: creation 3974.52 155 476942
customer reserve: removal 4271.02 68 512523
realization accept: apply 85.21 158 10225
realization accept: cancel 77.27 120 9272
payment from customer: creation 60.69 96 7283
payment from customer: removal 5.61 85 673
payment to supplier: creation 49.92 115 5990
payment to supplier: removal 3.73 66 447
service: total inventory turnovers 4.19 2874 503
service: total monetary turnovers 6.19 432 743
service: aggregate perf. data 0.97 11371 116
service: refresh index statistics 0.03 259528 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-12-2020 13:28:38 to 23-12-2020 13:28:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 629
TEST_TIME 2 2619
TEST_TIME 3 4465
TEST_TIME 4 4843
TEST_TIME 5 4896
TEST_TIME 6 5498
TEST_TIME 7 5396
TEST_TIME 8 5513
TEST_TIME 9 5729
TEST_TIME 10 5825
TEST_TIME 11 5854
TEST_TIME 12 6229
TEST_TIME 13 6370
TEST_TIME 14 6905
TEST_TIME 15 7064
TEST_TIME 16 7232
TEST_TIME 17 7555
TEST_TIME 18 7964
TEST_TIME 19 8717
TEST_TIME 20 8713
TEST_TIME 21 8663
TEST_TIME 22 9119
TEST_TIME 23 9371
TEST_TIME 24 9383
TEST_TIME 25 9842
TEST_TIME 26 11035
TEST_TIME 27 11123
TEST_TIME 28 11279
TEST_TIME 29 11146
TEST_TIME 30 11055
TEST_TIME 31 10881
TEST_TIME 32 10915
TEST_TIME 33 10661
TEST_TIME 34 10599
TEST_TIME 35 10326
TEST_TIME 36 10286
TEST_TIME 37 10184
TEST_TIME 38 10351
TEST_TIME 39 10265
TEST_TIME 40 10134
TEST_TIME 41 10039
TEST_TIME 42 9892
TEST_TIME 43 9927
TEST_TIME 44 9905
TEST_TIME 45 9902
TEST_TIME 46 10096
TEST_TIME 47 10307
TEST_TIME 48 10378
TEST_TIME 49 10281
TEST_TIME 50 10251
TEST_TIME 51 10296
TEST_TIME 52 10265
TEST_TIME 53 10195
TEST_TIME 54 10287
TEST_TIME 55 10148
TEST_TIME 56 9992
TEST_TIME 57 9897
TEST_TIME 58 9823
TEST_TIME 59 9735
TEST_TIME 60 9674
TEST_TIME 61 9602
TEST_TIME 62 9558
TEST_TIME 63 9534
TEST_TIME 64 9474
TEST_TIME 65 9491
TEST_TIME 66 9506
TEST_TIME 67 9487
TEST_TIME 68 9476
TEST_TIME 69 9484
TEST_TIME 70 9481
TEST_TIME 71 9486
TEST_TIME 72 9471
TEST_TIME 73 9509
TEST_TIME 74 9529
TEST_TIME 75 9595
TEST_TIME 76 9551
TEST_TIME 77 9486
TEST_TIME 78 9411
TEST_TIME 79 9390
TEST_TIME 80 9392
TEST_TIME 81 9341
TEST_TIME 82 9304
TEST_TIME 83 9282
TEST_TIME 84 9337
TEST_TIME 85 9339
TEST_TIME 86 9312
TEST_TIME 87 9288
TEST_TIME 88 9320
TEST_TIME 89 9352
TEST_TIME 90 9338
TEST_TIME 91 9387
TEST_TIME 92 9385
TEST_TIME 93 9439
TEST_TIME 94 9450
TEST_TIME 95 9367
TEST_TIME 96 9345
TEST_TIME 97 9291
TEST_TIME 98 9270
TEST_TIME 99 9257
TEST_TIME 100 9238
TEST_TIME 101 9211
TEST_TIME 102 9184
TEST_TIME 103 9161
TEST_TIME 104 9182
TEST_TIME 105 9239
TEST_TIME 106 9228
TEST_TIME 107 9196
TEST_TIME 108 9230
TEST_TIME 109 9224
TEST_TIME 110 9261
TEST_TIME 111 9189
TEST_TIME 112 9114
TEST_TIME 113 9080
TEST_TIME 114 9046
TEST_TIME 115 9052
TEST_TIME 116 9010
TEST_TIME 117 8976
TEST_TIME 118 8975
TEST_TIME 119 8998
TEST_TIME 120 8964

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-12-2020 13:28:41 to 23-12-2020 13:28:45.

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 1516432 1516432 0 0.00 0 26461 6 0 0
doc_list_biud 1516432 1516432 0 0.00 0 51261 29 0 0
sp_add_invoice_to_stock 5216 5151 65 1.24 44 202909 15898 0 65
sp_cancel_adding_invoice 5171 5171 0 0.00 5 87226 963 0 0
sp_cancel_client_order 1844 1844 0 0.00 4 8974 270 0 0
sp_cancel_customer_reserve 512523 512523 0 0.00 1 44146 69 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 3 5488 85 0 0
sp_cancel_pay_to_supplier 447 447 0 0.00 3 4696 67 0 0
sp_cancel_supplier_invoice 5626 5621 5 0.08 3 22840 904 0 5
sp_cancel_supplier_order 374 374 0 0.00 6 1027451 115677 0 0
sp_cancel_write_off 9272 9272 0 0.00 1 23451 120 0 0
sp_client_order 21429 21429 0 0.00 4 30979 391 0 0
sp_customer_reserve 476942 476942 0 0.00 0 75021 156 0 0
sp_fill_shopping_cart 32733 32733 0 0.00 1 17630 44 0 0
sp_fill_shopping_cart_clo_res 476938 476938 0 0.00 0 30167 17 0 0
sp_get_clo_for_invoice 5146 5146 0 0.00 0 57005 459 0 0
sp_kill_cost_storno 15563 15563 0 0.00 0 19502 59 0 0
sp_kill_qstorno_ret_qs2qd 1040313 1040313 0 0.00 0 44119 37 0 0
sp_kill_qty_storno 550170 550170 0 0.00 0 51260 79 0 0
sp_lock_selected_doc 21310 21310 0 0.00 0 7271 6 0 0
sp_make_cost_storno 28638 28638 0 0.00 0 13155 46 0 0
sp_multiply_rows_for_pdistr 28638 28638 0 0.00 0 8835 26 0 0
sp_multiply_rows_for_qdistr 21429 21429 0 0.00 0 14625 212 0 0
sp_pay_from_customer 7283 7283 0 0.00 4 13073 96 0 0
sp_pay_to_supplier 5990 5990 0 0.00 5 12908 116 0 0
sp_payment_common 13273 13273 0 0.00 1 8382 16 0 0
sp_qd_handle_on_cancel_clo 1844 1844 0 0.00 0 8945 252 0 0
sp_qd_handle_on_invoice_upd_sts 10311 10311 0 0.00 0 51259 349 0 0
sp_qd_handle_on_reserve_upd_sts 10225 10225 0 0.00 0 10066 48 0 0
sp_reserve_write_off 10225 10225 0 0.00 7 14328 159 0 0
sp_supplier_invoice 5718 5718 0 0.00 6 129901 3463 0 0
sp_supplier_order 5599 5599 0 0.00 5 38780 1318 0 0
srv_aggregate_perf_data 117 116 1 0.85 2342 165024 11372 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1916 187716 47558 0 0
srv_find_qd_qs_mism 1026109 1026109 0 0.00 0 26460 6 0 0
srv_make_invnt_saldo 503 503 0 0.00 40 105033 2874 0 0
srv_make_money_saldo 743 743 0 0.00 1 14909 433 0 0
srv_recalc_idx_stat 4 4 0 0.00 131336 392408 259529 0 0
t$perf-abend:sp_add_invoice_to_stock 65 65 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5151 5151 0 0.00 0 11459 127 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 0 2448 84 0 0
t$perf-norm:sp_cancel_client_order 1844 1844 0 0.00 0 4380 11 0 0
t$perf-norm:sp_cancel_customer_reserve 731 731 0 0.00 0 2853 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 3074 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 447 447 0 0.00 0 3544 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 837 13 0 0
t$perf-norm:sp_cancel_supplier_order 374 374 0 0.00 0 16662 1598 0 0
t$perf-norm:sp_cancel_write_off 1054 1054 0 0.00 0 670 5 0 0
t$perf-norm:sp_client_order 21429 21429 0 0.00 0 6722 13 0 0
t$perf-norm:sp_customer_reserve 1004 1004 0 0.00 0 1276 5 0 0
t$perf-norm:sp_pay_from_customer 7283 7283 0 0.00 0 7484 9 0 0
t$perf-norm:sp_pay_to_supplier 5990 5990 0 0.00 0 7852 10 0 0
t$perf-norm:sp_reserve_write_off 10225 10225 0 0.00 0 6518 11 0 0
t$perf-norm:sp_supplier_invoice 5718 5718 0 0.00 0 10156 14 0 0
t$perf-norm:sp_supplier_order 5599 5599 0 0.00 0 3648 13 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 238 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 17 1 0 0
t$perf-norm:srv_make_invnt_saldo 503 503 0 0.00 0 505 6 0 0
t$perf-norm:srv_make_money_saldo 743 743 0 0.00 0 923 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 10410 10280 130 1.24 0 9929 14 0 130
v_all_customers 38992 38992 0 0.00 0 21 1 0 0
v_all_suppliers 31294 31294 0 0.00 0 17 1 0 0
v_all_wares 42856 42856 0 0.00 0 113 1 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 4461 10 0 0
v_cancel_client_order 3688 3688 0 0.00 0 1716 4 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 3335 6 0 0
v_cancel_customer_reserve 1462 1462 0 0.00 0 3188 6 0 0
v_cancel_payment_to_supplier 894 894 0 0.00 0 1148 6 0 0
v_cancel_supplier_invoice 792 782 10 1.26 0 374 9 0 10
v_cancel_supplier_order 748 748 0 0.00 0 1056 5 0 0
v_cancel_write_off 2108 2108 0 0.00 0 3141 14 0 0
v_max_id_clo_ord 5598 5598 0 0.00 0 2482 2 0 0
v_max_id_clo_res 1004 1004 0 0.00 0 1465 12 0 0
v_max_id_ord_sup 5703 5703 0 0.00 0 6291 8 0 0
v_max_non_paid_invoice 1643 1643 0 0.00 0 285 2 0 0
v_max_non_paid_realizn 3740 3740 0 0.00 0 852 2 0 0
v_min_id_clo_ord 5598 5598 0 0.00 0 1799 4 0 0
v_min_id_clo_res 1004 1004 0 0.00 0 7536 19 0 0
v_min_id_ord_sup 5703 5703 0 0.00 0 9210 10 0 0
v_min_non_paid_invoice 5990 5990 0 0.00 0 8377 11 0 0
v_min_non_paid_realizn 7283 7283 0 0.00 0 6022 10 0 0
v_our_firm 10950 10950 0 0.00 0 90 1 0 0
v_reserve_write_off 20450 20450 0 0.00 0 14244 6 0 0
x_lock_depdocs_on_canc_invoice 10401 10401 0 0.00 0 16045 103 0 0
x_lock_depdocs_on_canc_sup_ord 374 374 0 0.00 0 26994 437 0 0
x_make_qstorno_1000_1200 5599 5599 0 0.00 2 38758 1252 0 0
x_make_qstorno_1200_2000 5718 5718 0 0.00 3 125899 3280 0 0
x_make_qstorno_2100_3300 476942 476942 0 0.00 0 75018 136 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-12-2020 13:28:46 to 23-12-2020 13:28:50.

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
10:56:59 2 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
10:57:59 18934 8192000000 9709738656 10005221376 788822768 8.783 100 81 161 0 816145216 7477504 19844944
10:59:25 28899 8192000000 9766165104 10072674304 837851120 9.278 100 89 178 0 870380784 11249616 21280048
11:00:57 13028 8192000000 9803111936 10117857280 870705472 9.607 100 83 165 0 910259872 20409936 19144464
11:02:19 8239 8192000000 9857920832 10189496320 905862720 9.956 100 81 162 1 967139168 42850480 18425968
11:03:33 8633 8192000000 9905179872 10246488064 947928912 10.371 100 73 146 0 1013779952 49829072 16021968
11:04:45 5330 8192000000 9942615408 10294374400 986031216 10.743 100 66 130 0 1057509872 58631984 12846672
11:05:53 4143 8192000000 10004359520 10369298432 1021199104 11.084 100 86 171 0 1121553776 82735968 17618704
11:06:59 1177 8192000000 10011310224 10377252864 1038198256 11.247 100 86 172 0 1129819040 74390064 17230720
11:08:03 33533 8192000000 9991256688 10375499776 1057539840 11.433 100 58 118 0 1129921024 59596352 12784832
11:09:38 3214 8192000000 10008692448 10405285888 1066018288 11.514 100 38 76 1 1117996496 44289664 7688544
11:10:43 11132 8192000000 9984444736 10382479360 1073440448 11.585 100 32 63 2 1101418576 21265040 6713088
11:11:55 7223 8192000000 9998750736 10398236672 1084335744 11.689 100 33 66 3 1114174512 22550976 7287792
11:13:04 26478 8192000000 10010755552 10402955264 1086228816 11.707 100 43 83 1 1111828176 17012320 8587040
11:14:31 6692 8192000000 10017358528 10413334528 1092928928 11.770 100 44 89 2 1126731216 23764192 10038096
11:15:38 8426 8192000000 10021936208 10430738432 1096255200 11.802 100 29 55 2 1122937296 20164240 6517856
11:16:48 10835 8192000000 10018519808 10427052032 1072354448 11.575 98 34 67 3 1098277264 18552208 7370608
11:18:00 24646 8192000000 9983771728 10372063232 1052282448 11.383 98 44 86 3 1077992288 16229248 9480592
11:19:26 6994 8192000000 9958199040 10345148416 1036082928 11.227 98 46 92 2 1067615152 20848656 10683568
11:20:34 10277 8192000000 9941686480 10337243136 1002839136 10.906 98 24 46 1 1034167680 26151328 5177216
11:21:47 15182 8192000000 9939853568 10337447936 1026901040 11.139 99 25 51 1 1058204096 25351296 5951760
11:23:03 20694 8192000000 9961035792 10364035072 1024805472 11.118 99 32 63 3 1065758944 34994432 5959040
11:24:24 8965 8192000000 9962572336 10362052608 1055228016 11.411 100 41 80 2 1084704944 19936192 9540736
11:25:35 9550 8192000000 9984996080 10384650240 1059555024 11.452 100 36 72 3 1090766720 23088368 8123328
11:26:48 78747 8192000000 10025709936 10422173696 1085286080 11.698 100 47 93 2 1109622496 14702512 9633904
11:29:17 14760 8192000000 10069925456 10472890368 1108034160 11.914 100 43 85 3 1176720832 59261600 9425072
11:30:37 12176 8192000000 10091082992 10497757184 1126120160 12.085 100 48 96 2 1194583936 58211504 10252272
11:31:53 2893 8192000000 10165928368 10561302528 1138516384 12.202 100 70 138 1 1266191872 113426464 14249024
11:33:01 6816 8192000000 10157373488 10572107776 1147210528 12.283 100 42 83 3 1268931568 112026640 9694400
11:34:15 17698 8192000000 10188086512 10607734784 1167095568 12.470 100 43 86 3 1304496496 127016576 10384352
11:35:40 4643 8192000000 10236068768 10649264128 1183119088 12.619 100 59 115 0 1339592128 143441696 13031344
11:36:51 10679 8192000000 10293578448 10710294528 1185518864 12.642 100 55 110 2 1397446864 199344032 12583968
11:38:15 3240 8192000000 10359342096 10766581760 1189595536 12.680 100 80 158 3 1461449680 253560208 18293936
11:39:23 8794 8192000000 10352653584 10779873280 1176384528 12.556 100 62 124 0 1459919792 269050832 14484432
11:40:48 1914 8192000000 10348342608 10761404416 1172611216 12.521 100 72 143 1 1449498416 260907728 15979472
11:41:56 3497 8192000000 10356945264 10772635648 1159217120 12.396 100 69 138 0 1453784400 278094432 16472848
11:43:03 12971 8192000000 10313323552 10753716224 1114025872 11.971 97 36 72 1 1396717264 273188976 9502416
11:44:22 6420 8192000000 10332085424 10763288576 1106888848 11.903 97 38 75 3 1417897744 301899056 9109840
11:45:32 5600 8192000000 10345698464 10777702400 1122363840 12.049 100 55 110 0 1449119744 313239936 13515968
11:46:41 6158 8192000000 10331814880 10741932032 1129854896 12.120 100 46 91 0 1424889776 283936128 11098752
11:48:03 22393 8192000000 10356497056 10799841280 1133950000 12.159 100 36 72 1 1459100384 315537744 9612640
11:49:33 20959 8192000000 10352127040 10824302592 1138088256 12.198 100 30 60 1 1441554016 295945504 7520256
11:50:55 35199 8192000000 10300279296 10807869440 1099102192 11.829 99 20 40 1 1397426032 293131440 5192400
11:52:35 20013 8192000000 10075044880 10556104704 1070767616 11.559 98 19 37 3 1153195488 78098640 4329232
11:54:09 38900 8192000000 10031678752 10514649088 1086803488 11.712 99 13 25 1 1167845824 78306064 2736272
11:55:53 6493 8192000000 10028444352 10439811072 1099609664 11.834 100 49 95 3 1119767392 9770416 10387312
11:57:08 40504 8192000000 10103399552 10517377024 1121816608 12.044 100 35 70 1 1182186032 52725616 7643808
11:58:50 187716 8192000000 10144149072 10627432448 1115198320 11.982 99 40 79 1 1213905952 90274864 8432768
12:03:02 40010 8192000000 10115097536 10556723200 1101342832 11.850 98 18 36 0 1208415760 102930320 4142608
12:04:46 31226 8192000000 10068463568 10554998784 1125096208 12.075 100 13 27 2 1179383440 51526864 2760368
12:06:43 61757 8192000000 10122522288 10571943936 1135779232 12.176 100 15 29 2 1216687056 77501056 3406768
12:08:48 53128 8192000000 10147648384 10640044032 1119875712 12.026 98 17 34 1 1244353856 120422800 4055344
12:10:56 87583 8192000000 10212367984 10702680064 1149859360 12.308 100 20 40 1 1309080208 154414848 4806000
12:13:35 82672 8192000000 10076906448 10569740288 1137754544 12.194 99 17 36 2 1217005360 75787856 3462960
12:15:58 83920 8192000000 10094457952 10559000576 1154069536 12.348 100 37 73 1 1214489808 52800800 7619472
12:18:24 73507 8192000000 10111579744 10570608640 1106559232 11.900 96 13 26 1 1150957808 41542208 2856368
12:20:41 138562 8192000000 10057406896 10500435968 1116804480 11.997 98 19 38 1 1149418672 28540224 4073968
12:24:20 59056 8192000000 10062941440 10536067072 1128740064 12.109 99 10 20 0 1175209648 44176400 2293184
12:26:19 44689 8192000000 10056603472 10513149952 1116266176 11.992 98 11 21 1 1155683344 37434800 1982368
12:28:05 59114 8192000000 10043218064 10487369728 1099084720 11.829 97 16 32 2 1125859056 23855264 2919072
12:30:09 29463 8192000000 10066492688 10498469888 1104478208 11.880 97 31 60 0 1138744576 28497616 5768752
12:31:38 19176 8192000000 10053045520 10492215296 1111762160 11.949 98 38 75 2 1147268816 27222544 8284112
12:33:00 53166 8192000000 10043001888 10463236096 1069494000 11.547 96 35 70 0 1101143120 24028880 7620240
12:34:56 24823 8192000000 10048445232 10469269504 1086841024 11.713 99 39 78 2 1146500576 51752880 7906672
12:36:28 41236 8192000000 10060269408 10489438208 1093405360 11.775 99 45 91 1 1150164416 47681344 9077712
12:38:19 28503 8192000000 10065259952 10495287296 1086222624 11.707 98 42 84 1 1141323424 46423072 8677728
12:39:54 54388 8192000000 10064112896 10511204352 1081251280 11.659 97 12 24 0 1155664064 72052336 2360448
12:41:49 38147 8192000000 10044778064 10471526400 1109257216 11.925 100 28 56 0 1141408032 26778912 5371904
12:43:32 102303 8192000000 10097553376 10551857152 1107446176 11.908 100 26 52 0 1191326080 78502800 5377104
12:48:01 63337 8192000000 10074173120 10521657344 1114715664 11.977 100 12 24 1 1155419936 37851696 2852576
12:50:21 45790 8192000000 10043797968 10514825216 1094076624 11.781 98 22 43 3 1165043424 65934736 5032064
12:52:07 35921 8192000000 10100455136 10528038912 1098397904 11.822 98 30 60 1 1137660928 32860384 6402640
12:53:43 141773 8192000000 10063081344 10526224384 1074389216 11.594 96 21 42 0 1165148992 86310272 4449504
12:57:06 70975 8192000000 10103579728 10591547392 1140746864 12.223 100 15 30 0 1212519200 68789792 2982544
12:59:44 69954 8192000000 10123958432 10572357632 1139140864 12.207 99 34 67 3 1205753648 59523616 7089168
13:01:56 94942 8192000000 10099059104 10579759104 1146135184 12.273 100 18 36 0 1208608640 58386336 4087120
13:04:34 75593 8192000000 10084496784 10575839232 1134191120 12.161 99 3 6 1 1166778192 32047712 539360
13:06:50 34247 8192000000 10093030144 10529222656 1152457744 12.333 100 22 45 1 1180444688 22818272 5168672
13:08:36 64581 8192000000 10117957280 10553475072 1101297664 11.850 96 20 40 1 1153458928 48004336 4156928
13:10:41 86233 8192000000 10067035968 10530103296 1125523328 12.079 99 16 32 1 1195854928 66630304 3701296
13:13:07 83284 8192000000 10124448704 10560696320 1108623696 11.919 97 24 46 0 1159548976 46102544 4822736
13:15:39 119801 8192000000 10058109632 10533851136 1065808896 11.512 94 10 20 0 1130551936 62405744 2337296
13:19:08 52462 8192000000 10048216768 10506862592 1093760576 11.778 98 8 16 0 1165325664 69292240 2272848
13:22:41 36817 8192000000 10108933472 10550779904 1129427328 12.116 100 18 36 3 1204832672 71478640 3926704
13:24:19 65221 8192000000 10167357280 10652463104 1138637904 12.203 100 20 40 1 1277257872 133947600 4672368
13:26:24 31957 8192000000 9928845344 10323582976 890405120 9.803 78 22 44 1 1035545632 140421584 4718928

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-12-2020 13:28:52 to 23-12-2020 13:29:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 65 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-12-2020 13:29:08 to 23-12-2020 13:29:10.

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          237233
MON$OLDEST_ACTIVE               242647
MON$OLDEST_SNAPSHOT             242647
MON$NEXT_TRANSACTION            242647
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                       2098880
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             815
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2302 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2302 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2302 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2302 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:24: 
:25: mscs-test-161.budzdorov.ru    Wed Dec 23 13:27:01 2020
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
:28: 
:29: mscs-test-161.budzdorov.ru    Wed Dec 23 13:27:02 2020
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:31: 
:32: 
:33: mscs-test-161.budzdorov.ru    Wed Dec 23 13:27:04 2020
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
--- end of diff output ---

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

23.12.20 13:29:11. 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
2020-12-23 13:29:13 No severe PSQL-related problems occured
23.12.20 13:29:14. End of report.