Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.12.2021 22: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 16740060 kB
MemAvailable 31466104 kB
Buffers 0 kB
Cached 14958492 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 3.0.9
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-13 22:37:58
DTS_END                         2021-12-13 22:37:58.4840
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36552
TRN_ID                          232621
ATT_ID                          652
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: 9155.79 25203 1098695
customer order: creation 160.57 377 19268
customer order: refuse 13.43 390 1612
order to supplier: creation 43.81 1271 5257
order to supplier: removal 3.60 106955 432
invoice (draft): creation 42.85 3668 5142
invoice (draft): removal 54.46 744 6535
invoice accept: apply 38.78 17135 4653
invoice accept: cancel 49.78 689 5974
customer reserve: creation 3624.70 166 434964
customer reserve: removal 4834.69 63 580163
realization accept: apply 77.76 204 9331
realization accept: cancel 88.35 114 10602
payment from customer: creation 55.99 121 6719
payment from customer: removal 5.27 76 632
payment to supplier: creation 47.40 149 5688
payment to supplier: removal 3.39 71 407
service: total inventory turnovers 4.04 1290 485
service: total monetary turnovers 6.14 25 737
service: aggregate perf. data 0.75 21607 90
service: refresh index statistics 0.03 348953 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-12-2021 22:39:48 to 13-12-2021 22:39:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1591
TEST_TIME 2 1708
TEST_TIME 3 2801
TEST_TIME 4 2619
TEST_TIME 5 2850
TEST_TIME 6 2769
TEST_TIME 7 2863
TEST_TIME 8 3289
TEST_TIME 9 3750
TEST_TIME 10 3967
TEST_TIME 11 4258
TEST_TIME 12 4534
TEST_TIME 13 4661
TEST_TIME 14 5172
TEST_TIME 15 5407
TEST_TIME 16 6197
TEST_TIME 17 6242
TEST_TIME 18 6616
TEST_TIME 19 7280
TEST_TIME 20 7323
TEST_TIME 21 7853
TEST_TIME 22 8165
TEST_TIME 23 7954
TEST_TIME 24 8405
TEST_TIME 25 8938
TEST_TIME 26 9746
TEST_TIME 27 10016
TEST_TIME 28 10579
TEST_TIME 29 10554
TEST_TIME 30 10485
TEST_TIME 31 10340
TEST_TIME 32 10314
TEST_TIME 33 10129
TEST_TIME 34 9961
TEST_TIME 35 9911
TEST_TIME 36 9783
TEST_TIME 37 9766
TEST_TIME 38 9651
TEST_TIME 39 9583
TEST_TIME 40 9542
TEST_TIME 41 9490
TEST_TIME 42 9510
TEST_TIME 43 9461
TEST_TIME 44 9360
TEST_TIME 45 9236
TEST_TIME 46 9189
TEST_TIME 47 9096
TEST_TIME 48 9153
TEST_TIME 49 9202
TEST_TIME 50 9088
TEST_TIME 51 9127
TEST_TIME 52 9120
TEST_TIME 53 9069
TEST_TIME 54 8976
TEST_TIME 55 9189
TEST_TIME 56 9108
TEST_TIME 57 9223
TEST_TIME 58 9249
TEST_TIME 59 9176
TEST_TIME 60 9208
TEST_TIME 61 9342
TEST_TIME 62 9373
TEST_TIME 63 9352
TEST_TIME 64 9474
TEST_TIME 65 9475
TEST_TIME 66 9465
TEST_TIME 67 9589
TEST_TIME 68 9457
TEST_TIME 69 9359
TEST_TIME 70 9460
TEST_TIME 71 9366
TEST_TIME 72 9344
TEST_TIME 73 9365
TEST_TIME 74 9314
TEST_TIME 75 9344
TEST_TIME 76 9340
TEST_TIME 77 9256
TEST_TIME 78 9176
TEST_TIME 79 9246
TEST_TIME 80 9336
TEST_TIME 81 9293
TEST_TIME 82 9288
TEST_TIME 83 9283
TEST_TIME 84 9315
TEST_TIME 85 9424
TEST_TIME 86 9395
TEST_TIME 87 9463
TEST_TIME 88 9407
TEST_TIME 89 9495
TEST_TIME 90 9473
TEST_TIME 91 9457
TEST_TIME 92 9420
TEST_TIME 93 9370
TEST_TIME 94 9313
TEST_TIME 95 9301
TEST_TIME 96 9258
TEST_TIME 97 9216
TEST_TIME 98 9259
TEST_TIME 99 9239
TEST_TIME 100 9222
TEST_TIME 101 9242
TEST_TIME 102 9228
TEST_TIME 103 9222
TEST_TIME 104 9196
TEST_TIME 105 9325
TEST_TIME 106 9309
TEST_TIME 107 9274
TEST_TIME 108 9365
TEST_TIME 109 9369
TEST_TIME 110 9412
TEST_TIME 111 9394
TEST_TIME 112 9318
TEST_TIME 113 9312
TEST_TIME 114 9303
TEST_TIME 115 9295
TEST_TIME 116 9242
TEST_TIME 117 9191
TEST_TIME 118 9164
TEST_TIME 119 9176
TEST_TIME 120 9156

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-12-2021 22:39:51 to 13-12-2021 22:39:55.

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 1501884 1501884 0 0.00 0 28042 6 0 0
doc_list_biud 1501884 1501884 0 0.00 0 50408 30 0 0
sp_add_invoice_to_stock 4759 4653 106 2.22 45 229312 17135 0 106
sp_cancel_adding_invoice 5974 5974 0 0.00 4 46730 690 0 0
sp_cancel_client_order 1612 1612 0 0.00 4 14068 391 0 0
sp_cancel_customer_reserve 580163 580163 0 0.00 1 50409 64 0 0
sp_cancel_pay_from_customer 632 632 0 0.00 3 8226 77 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 1694 71 0 0
sp_cancel_supplier_invoice 6547 6535 12 0.18 2 15584 745 0 12
sp_cancel_supplier_order 432 432 0 0.00 7 1155611 106956 0 0
sp_cancel_write_off 10602 10602 0 0.00 1 14874 114 0 0
sp_client_order 19268 19268 0 0.00 3 18259 378 0 0
sp_customer_reserve 434964 434964 0 0.00 0 115111 167 0 0
sp_fill_shopping_cart 29658 29658 0 0.00 1 30030 43 0 0
sp_fill_shopping_cart_clo_res 434963 434963 0 0.00 0 19536 15 0 0
sp_get_clo_for_invoice 4651 4651 0 0.00 0 19299 316 0 0
sp_kill_cost_storno 17615 17615 0 0.00 0 19364 58 0 0
sp_kill_qstorno_ret_qs2qd 1177895 1177895 0 0.00 0 50406 34 0 0
sp_kill_qty_storno 619300 619300 0 0.00 0 50408 73 0 0
sp_lock_selected_doc 19358 19358 0 0.00 0 8357 6 0 0
sp_make_cost_storno 26388 26388 0 0.00 0 28016 71 0 0
sp_multiply_rows_for_pdistr 26388 26388 0 0.00 0 8729 31 0 0
sp_multiply_rows_for_qdistr 19268 19268 0 0.00 0 12517 174 0 0
sp_pay_from_customer 6719 6719 0 0.00 4 32432 121 0 0
sp_pay_to_supplier 5688 5688 0 0.00 5 24791 149 0 0
sp_payment_common 12406 12406 0 0.00 1 32369 33 0 0
sp_qd_handle_on_cancel_clo 1612 1612 0 0.00 0 14060 365 0 0
sp_qd_handle_on_invoice_upd_sts 10625 10625 0 0.00 0 35085 313 0 0
sp_qd_handle_on_reserve_upd_sts 9331 9331 0 0.00 0 13396 70 0 0
sp_reserve_write_off 9331 9331 0 0.00 6 23971 205 0 0
sp_supplier_invoice 5142 5142 0 0.00 6 111588 3669 0 0
sp_supplier_order 5257 5257 0 0.00 5 27541 1271 0 0
srv_aggregate_perf_data 91 90 1 1.09 2875 197513 21608 0 1
srv_fill_mon_cache_memory 60 60 0 0.00 3884 155779 50210 0 0
srv_find_qd_qs_mism 1053874 1053874 0 0.00 0 10958 4 0 0
srv_make_invnt_saldo 485 485 0 0.00 56 62220 1290 0 0
srv_make_money_saldo 737 737 0 0.00 1 1649 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 234934 432175 348954 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 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 4653 4653 0 0.00 0 20845 181 0 0
t$perf-norm:sp_cancel_adding_invoice 362 362 0 0.00 0 22378 179 0 0
t$perf-norm:sp_cancel_client_order 1612 1612 0 0.00 0 5427 14 0 0
t$perf-norm:sp_cancel_customer_reserve 658 658 0 0.00 0 2336 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 632 632 0 0.00 0 5171 34 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 701 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 369 369 0 0.00 0 2986 26 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 39640 1529 0 0
t$perf-norm:sp_cancel_write_off 904 904 0 0.00 0 4852 17 0 0
t$perf-norm:sp_client_order 19268 19268 0 0.00 0 18123 19 0 0
t$perf-norm:sp_customer_reserve 924 924 0 0.00 0 1314 6 0 0
t$perf-norm:sp_pay_from_customer 6719 6719 0 0.00 0 9255 13 0 0
t$perf-norm:sp_pay_to_supplier 5688 5688 0 0.00 0 4513 11 0 0
t$perf-norm:sp_reserve_write_off 9331 9331 0 0.00 0 9920 15 0 0
t$perf-norm:sp_supplier_invoice 5142 5142 0 0.00 0 9165 19 0 0
t$perf-norm:sp_supplier_order 5257 5257 0 0.00 0 4870 21 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 613 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 599 15 0 0
t$perf-norm:srv_make_invnt_saldo 485 485 0 0.00 0 708 15 0 0
t$perf-norm:srv_make_money_saldo 737 737 0 0.00 0 2641 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 2 0 0
v_add_invoice_to_stock 9514 9302 212 2.22 0 7851 17 0 212
v_all_customers 35442 35442 0 0.00 0 104 0 0 0
v_all_suppliers 29044 29044 0 0.00 0 22 1 0 0
v_all_wares 38536 38536 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 724 724 0 0.00 0 198 5 0 0
v_cancel_client_order 3224 3224 0 0.00 0 3868 5 0 0
v_cancel_customer_prepayment 1264 1264 0 0.00 0 1426 4 0 0
v_cancel_customer_reserve 1316 1316 0 0.00 0 846 3 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 1016 4 0 0
v_cancel_supplier_invoice 762 738 24 3.14 0 3797 20 0 24
v_cancel_supplier_order 864 864 0 0.00 0 853 5 0 0
v_cancel_write_off 1808 1808 0 0.00 0 3340 13 0 0
v_max_id_clo_ord 5254 5254 0 0.00 0 350 1 0 0
v_max_id_clo_res 924 924 0 0.00 0 2996 11 0 0
v_max_id_ord_sup 5136 5136 0 0.00 0 4578 6 0 0
v_max_non_paid_invoice 1555 1555 0 0.00 0 5107 7 0 0
v_max_non_paid_realizn 3478 3478 0 0.00 0 1394 4 0 0
v_min_id_clo_ord 5254 5254 0 0.00 0 5793 9 0 0
v_min_id_clo_res 924 924 0 0.00 0 5055 9 0 0
v_min_id_ord_sup 5136 5136 0 0.00 0 8364 16 0 0
v_min_non_paid_invoice 5687 5687 0 0.00 0 6618 23 0 0
v_min_non_paid_realizn 6719 6719 0 0.00 0 32363 29 0 0
v_our_firm 9576 9576 0 0.00 0 12 1 0 0
v_reserve_write_off 18662 18662 0 0.00 0 3955 5 0 0
x_lock_depdocs_on_canc_invoice 12140 12140 0 0.00 0 17215 112 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 9648 372 0 0
x_make_qstorno_1000_1200 5257 5257 0 0.00 1 27519 1193 0 0
x_make_qstorno_1200_2000 5142 5142 0 0.00 3 104811 3503 0 0
x_make_qstorno_2100_3300 434964 434964 0 0.00 0 115109 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-12-2021 22:39:56 to 13-12-2021 22:40:00.

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
20:08:01 82 8192000000 8824555376 8844652544 22321200 0.271 6 5 6 0 22591984 99328 171456
20:09:03 25946 8192000000 9889055120 10199937024 971317296 10.600 100 84 168 0 999054304 5692368 22044640
20:10:40 18117 8192000000 9948254384 10269433856 1027666112 11.146 100 94 187 3 1060410288 8388704 24355472
20:12:03 3976 8192000000 9982575056 10312998912 1064500896 11.500 100 94 188 0 1098090064 9562736 24026432
20:13:14 15622 8192000000 10025449136 10371842048 1109121456 11.924 100 83 166 2 1140637440 11134256 20381728
20:14:34 9536 8192000000 10076697392 10439335936 1171317520 12.509 100 76 152 0 1201092208 11998528 17776160
20:15:46 5856 8192000000 10106666112 10482319360 1204125856 12.815 100 66 132 1 1232233536 12496336 15611344
20:16:56 6689 8192000000 10161324176 10542682112 1253254240 13.268 100 83 166 2 1285501216 13802192 18444784
20:18:04 2673 8192000000 10197811856 10582986752 1286028912 13.568 100 97 192 2 1321864496 14506624 21328960
20:19:08 6033 8192000000 10198664112 10598088704 1300674400 13.701 100 77 154 0 1330806336 12655520 17476416
20:20:18 33484 8192000000 10206211328 10611163136 1311192992 13.797 100 34 68 0 1325863520 6551696 8118832
20:21:52 4852 8192000000 10205640960 10621349888 1317342000 13.853 100 26 53 1 1329744400 6665504 5736896
20:22:58 8744 8192000000 10215473936 10630455296 1327014240 13.940 100 26 53 1 1338763776 6364720 5384816
20:24:08 11083 8192000000 10211514592 10635333632 1331303264 13.979 100 19 38 0 1341656752 5740000 4613488
20:25:20 45896 8192000000 10225606960 10654212096 1336702576 14.028 100 26 52 1 1347449200 5077632 5668992
20:27:07 9569 8192000000 10221944112 10641879040 1311523200 13.800 98 24 49 1 1322498208 5383520 5591488
20:28:20 6347 8192000000 10196012448 10608742400 1282248864 13.534 98 25 50 1 1293930672 5715984 5965824
20:29:30 64501 8192000000 10168264896 10563592192 1197999648 12.758 93 39 76 2 1212519360 4848960 9670752
20:31:38 6838 8192000000 10139678624 10541375488 1223122736 12.991 97 17 34 1 1233048576 5829456 4096384
20:32:48 7873 8192000000 10151213088 10555142144 1223043120 12.990 96 24 48 0 1234515984 5636160 5836704
20:33:57 8314 8192000000 10179855952 10587168768 1285373952 13.562 100 21 42 1 1295799392 5608096 4817344
20:35:07 24424 8192000000 10174517536 10591141888 1279262640 13.506 99 21 42 1 1289396416 5149088 4984688
20:36:33 7110 8192000000 10212613872 10623569920 1312646032 13.810 100 43 86 2 1331534976 9524256 9364688
20:37:41 9353 8192000000 10209231584 10623922176 1317355632 13.853 100 15 30 0 1326377440 5338640 3683168
20:39:01 8887 8192000000 10250413376 10660425728 1340359904 14.061 100 55 110 1 1362952288 9654512 12937872
20:40:14 30669 8192000000 10272486768 10688139264 1361679936 14.252 100 54 108 4 1390218288 16398768 12139584
20:41:56 48488 8192000000 10276748096 10704375808 1373906272 14.362 100 21 38 2 1393845328 14689472 5249584
20:43:55 24025 8192000000 10306158352 10732257280 1393040144 14.533 100 30 60 0 1420853344 20298608 7514592
20:45:22 10335 8192000000 10325369184 10748026880 1400094672 14.596 100 45 89 3 1435759664 24052864 11612128
20:46:39 12227 8192000000 10341978496 10768629760 1410839280 14.691 100 50 101 1 1449534992 26051984 12643728
20:47:56 4387 8192000000 10372574752 10793787392 1428885632 14.851 100 71 142 1 1479320928 33717088 16718208
20:49:04 3884 8192000000 10366590528 10785218560 1412733872 14.708 99 82 165 5 1469915056 36954864 20226320
20:50:12 5242 8192000000 10381914352 10800099328 1429139952 14.854 100 74 147 1 1489547712 42022864 18384896
20:51:25 11177 8192000000 10381117456 10806439936 1426929040 14.834 100 60 120 1 1490472720 47660032 15883648
20:52:44 6567 8192000000 10399969584 10814283776 1431068768 14.871 100 82 163 0 1503256832 51205024 20983040
20:53:57 12490 8192000000 10375960464 10805362688 1422290384 14.793 100 38 76 1 1479453024 46662432 10500208
20:55:16 9804 8192000000 10348246928 10773561344 1371565904 14.341 97 52 104 2 1431732656 45662640 14504112
20:56:31 7765 8192000000 10345422752 10767347712 1390111664 14.507 100 48 96 0 1448904480 45934448 12858368
20:57:47 5856 8192000000 10365329248 10792304640 1409480064 14.679 100 50 101 1 1472750640 50254752 13015824
20:58:55 7749 8192000000 10355915392 10793906176 1406037888 14.649 100 41 82 1 1462112688 45188272 10886528
21:00:12 16329 8192000000 10337887152 10773004288 1373510064 14.358 98 25 50 1 1427353840 46871040 6972736
21:01:39 13744 8192000000 10337124176 10761822208 1369423968 14.322 100 35 70 0 1435717696 56222592 10071136
21:03:13 17517 8192000000 10300570544 10746687488 1342354144 14.079 98 23 46 4 1397455584 48452544 6648896
21:05:14 31359 8192000000 10260491504 10715242496 1326446112 13.935 99 12 24 0 1362905632 33290560 3168960
21:06:47 51798 8192000000 10235284768 10679111680 1307139040 13.760 98 14 28 0 1328320448 17920992 3260416
21:09:47 64775 8192000000 10247566656 10680287232 1317568528 13.855 98 19 39 0 1329844800 8219120 4057152
21:11:59 77872 8192000000 10252258880 10694438912 1330756960 13.974 98 18 36 0 1347734896 12918144 4059792
21:14:18 82673 8192000000 10282497184 10718425088 1362160928 14.257 100 52 104 3 1381112448 6302464 12649056
21:16:49 39215 8192000000 10274093440 10717597696 1363287168 14.267 100 18 36 1 1376555376 8592896 4675312
21:18:38 76655 8192000000 10288425376 10742579200 1366512992 14.296 99 39 78 0 1386851936 11096272 9242672
21:21:06 112699 8192000000 10301398224 10734903296 1367720320 14.307 99 40 76 2 1384353616 7449680 9183616
21:23:59 36208 8192000000 10302201504 10750078976 1392274352 14.526 100 34 68 0 1414452224 13723168 8454704
21:25:35 53368 8192000000 10283520464 10750058496 1375819568 14.379 99 7 14 0 1392484336 14750976 1913792
21:27:37 90627 8192000000 10284372416 10751754240 1383225344 14.445 100 9 18 1 1400987328 15427648 2334336
21:30:28 61458 8192000000 10318479696 10772492288 1392768224 14.531 100 31 62 0 1411185904 11181616 7236064
21:32:32 54011 8192000000 10298057152 10748928000 1375644448 14.378 99 17 34 1 1394683312 15304320 3734544
21:34:28 69504 8192000000 10285709296 10740662272 1341486672 14.071 96 18 36 3 1354404672 8911488 4006512
21:36:40 44063 8192000000 10304714720 10752454656 1372801120 14.352 99 37 74 1 1396924928 15505632 8618176
21:38:28 59704 8192000000 10288248112 10747129856 1352859344 14.173 97 9 18 0 1368289664 13419232 2011088
21:41:14 86038 8192000000 10292451600 10743607296 1370194176 14.329 99 5 10 0 1384155296 13219312 741808
21:43:41 22673 8192000000 10305879840 10751172608 1388084176 14.489 100 36 72 1 1411348000 14974096 8289728
21:45:07 99094 8192000000 10303592128 10750361600 1376977920 14.390 99 32 62 3 1395050512 9965312 8107280
21:47:50 52819 8192000000 10286897360 10733875200 1367178272 14.302 99 23 45 1 1379676800 7830496 4668032
21:50:12 41949 8192000000 10269138688 10722316288 1344321616 14.096 98 25 50 0 1365691696 15688128 5681952
21:51:58 39898 8192000000 10274492272 10719895552 1337217488 14.032 98 14 28 0 1359131456 18574944 3339024
21:54:13 48650 8192000000 10276616368 10729697280 1371116912 14.337 100 9 18 0 1387002224 13826128 2059184
21:56:02 91086 8192000000 10248906480 10720899072 1324216608 13.915 97 11 22 0 1339376080 12710384 2449088
21:58:38 50820 8192000000 10260839328 10707365888 1308838272 13.776 96 13 26 0 1320612192 8703088 3070832
22:00:34 15054 8192000000 10259081696 10692722688 1334888512 14.011 99 39 78 1 1354338176 9444064 10005600
22:01:53 95204 8192000000 10268711504 10714341376 1299942848 13.695 96 14 28 2 1317331840 14196928 3192064
22:04:28 66060 8192000000 10266357120 10702360576 1337419168 14.034 98 23 46 0 1352795840 10311280 5065392
22:06:39 88088 8192000000 10287563264 10729455616 1361399248 14.250 99 15 30 0 1377271296 12735424 3136624
22:09:13 106803 8192000000 10285926592 10739593216 1377062544 14.390 99 41 79 3 1395363040 8922048 9378448
22:12:01 93972 8192000000 10282459136 10749140992 1389211408 14.499 100 12 24 0 1402784256 10403232 3169616
22:14:35 82917 8192000000 10273750432 10743513088 1329291920 13.961 95 9 18 0 1343562240 12143792 2126528
22:16:59 26356 8192000000 10298400224 10741604352 1361903568 14.254 98 38 77 2 1377261952 7395456 7962928
22:18:26 82006 8192000000 10286217424 10744631296 1359250752 14.231 98 18 36 2 1374018736 10258000 4509984
22:20:48 69850 8192000000 10316725392 10766450688 1385200352 14.463 99 18 36 1 1407693712 17685136 4808224
22:23:57 41946 8192000000 10339185872 10781790208 1388558864 14.493 99 30 60 0 1407559712 12501440 6499408
22:25:42 84268 8192000000 10308307360 10762756096 1359427008 14.232 97 11 22 0 1380275264 18641536 2206720
22:28:08 155779 8192000000 10297095728 10755407872 1353233520 14.177 97 19 38 1 1373644592 16187856 4223216
22:31:51 51653 8192000000 10278566912 10739490816 1371811648 14.343 99 21 42 0 1384516944 8131360 4573936
22:33:43 106314 8192000000 10276345152 10736959488 1366789696 14.298 99 10 20 0 1379063152 9759152 2514304
22:37:09 50109 8192000000 10239836736 10696359936 1332522528 13.990 98 14 28 0 1343827552 7717872 3587152

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 13-12-2021 22:40:02 to 13-12-2021 22:40:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 106 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-12-2021 22:40:17 to 13-12-2021 22:40:19.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          232914
MON$OLDEST_ACTIVE               232915
MON$OLDEST_SNAPSHOT             232915
MON$NEXT_TRANSACTION            232915
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-03 16:41:55.3470
MON$PAGES                       2021504
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33543 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33543 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33543 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33543 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:144: 
:145: mscs-test-161.budzdorov.ru    Mon Dec 13 22:38:10 2021
:146:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:147: 
:148: 
:149: mscs-test-161.budzdorov.ru    Mon Dec 13 22:38:10 2021
:150:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:151: 
--- end of diff output ---

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

13.12.21 22:40:20. There are 3 logs in /var/tmp/logs-oltp30 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-13 22:40:22 No severe PSQL-related problems occured
13.12.21 22:40:24. End of report.