Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.12.2022 10:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 14751916 kB
MemAvailable 30104404 kB
Buffers 0 kB
Cached 16955924 kB
SwapCached 3448 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.3
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 2022-12-22 10:37:23
DTS_END                         2022-12-22 10:37:23.6780
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/41058
TRN_ID                          225066
ATT_ID                          588
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: 9234.89 20074 1108187
customer order: creation 179.59 463 21551
customer order: refuse 14.80 362 1776
order to supplier: creation 47.81 1354 5737
order to supplier: removal 3.60 142613 432
invoice (draft): creation 48.33 3027 5800
invoice (draft): removal 50.62 955 6074
invoice accept: apply 43.47 25010 5216
invoice accept: cancel 45.01 1209 5401
customer reserve: creation 4097.85 235 491742
customer reserve: removal 4401.45 92 528174
realization accept: apply 85.53 248 10263
realization accept: cancel 82.24 165 9869
payment from customer: creation 61.13 167 7335
payment from customer: removal 6.06 97 727
payment to supplier: creation 51.17 180 6140
payment to supplier: removal 3.62 125 434
service: total inventory turnovers 4.08 3487 490
service: total monetary turnovers 7.54 354 905
service: aggregate perf. data 0.97 13731 116
service: refresh index statistics 0.04 207607 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-12-2022 10:38:49 to 22-12-2022 10:38:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6516
TEST_TIME 2 4865
TEST_TIME 3 4788
TEST_TIME 4 4337
TEST_TIME 5 4248
TEST_TIME 6 4022
TEST_TIME 7 4665
TEST_TIME 8 4791
TEST_TIME 9 5322
TEST_TIME 10 5659
TEST_TIME 11 5996
TEST_TIME 12 6345
TEST_TIME 13 6637
TEST_TIME 14 6694
TEST_TIME 15 7304
TEST_TIME 16 7563
TEST_TIME 17 7959
TEST_TIME 18 8072
TEST_TIME 19 8094
TEST_TIME 20 8382
TEST_TIME 21 8534
TEST_TIME 22 8765
TEST_TIME 23 8766
TEST_TIME 24 9096
TEST_TIME 25 9194
TEST_TIME 26 9438
TEST_TIME 27 9623
TEST_TIME 28 9600
TEST_TIME 29 9693
TEST_TIME 30 9867
TEST_TIME 31 10092
TEST_TIME 32 10585
TEST_TIME 33 10879
TEST_TIME 34 11384
TEST_TIME 35 11212
TEST_TIME 36 11040
TEST_TIME 37 10908
TEST_TIME 38 10841
TEST_TIME 39 11010
TEST_TIME 40 10944
TEST_TIME 41 10980
TEST_TIME 42 10869
TEST_TIME 43 10850
TEST_TIME 44 10614
TEST_TIME 45 10487
TEST_TIME 46 10400
TEST_TIME 47 10483
TEST_TIME 48 10327
TEST_TIME 49 10402
TEST_TIME 50 10337
TEST_TIME 51 10314
TEST_TIME 52 10252
TEST_TIME 53 10229
TEST_TIME 54 10122
TEST_TIME 55 10057
TEST_TIME 56 9978
TEST_TIME 57 9868
TEST_TIME 58 9874
TEST_TIME 59 9842
TEST_TIME 60 9761
TEST_TIME 61 9673
TEST_TIME 62 9658
TEST_TIME 63 9651
TEST_TIME 64 9640
TEST_TIME 65 9642
TEST_TIME 66 9685
TEST_TIME 67 9650
TEST_TIME 68 9659
TEST_TIME 69 9820
TEST_TIME 70 9799
TEST_TIME 71 9769
TEST_TIME 72 9867
TEST_TIME 73 9928
TEST_TIME 74 9925
TEST_TIME 75 9908
TEST_TIME 76 9809
TEST_TIME 77 9781
TEST_TIME 78 9710
TEST_TIME 79 9688
TEST_TIME 80 9639
TEST_TIME 81 9600
TEST_TIME 82 9557
TEST_TIME 83 9501
TEST_TIME 84 9446
TEST_TIME 85 9448
TEST_TIME 86 9400
TEST_TIME 87 9439
TEST_TIME 88 9465
TEST_TIME 89 9437
TEST_TIME 90 9521
TEST_TIME 91 9478
TEST_TIME 92 9554
TEST_TIME 93 9569
TEST_TIME 94 9574
TEST_TIME 95 9590
TEST_TIME 96 9601
TEST_TIME 97 9571
TEST_TIME 98 9535
TEST_TIME 99 9464
TEST_TIME 100 9457
TEST_TIME 101 9402
TEST_TIME 102 9351
TEST_TIME 103 9350
TEST_TIME 104 9329
TEST_TIME 105 9338
TEST_TIME 106 9295
TEST_TIME 107 9275
TEST_TIME 108 9275
TEST_TIME 109 9267
TEST_TIME 110 9239
TEST_TIME 111 9285
TEST_TIME 112 9408
TEST_TIME 113 9399
TEST_TIME 114 9386
TEST_TIME 115 9430
TEST_TIME 116 9357
TEST_TIME 117 9372
TEST_TIME 118 9344
TEST_TIME 119 9308
TEST_TIME 120 9255

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-12-2022 10:38:52 to 22-12-2022 10:38:56.

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 1560021 1560021 0 0.00 0 19880 13 0 0
doc_list_biud 1560019 1560019 0 0.00 0 27630 37 0 0
sp_add_invoice_to_stock 5289 5216 73 1.38 45 194740 25011 0 73
sp_cancel_adding_invoice 5401 5401 0 0.00 6 95496 1209 0 0
sp_cancel_client_order 1776 1776 0 0.00 5 6399 363 0 0
sp_cancel_customer_reserve 528174 528174 0 0.00 1 17544 92 0 0
sp_cancel_pay_from_customer 727 727 0 0.00 4 1349 97 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 4 5878 125 0 0
sp_cancel_supplier_invoice 6080 6074 6 0.09 2 26447 955 0 6
sp_cancel_supplier_order 432 432 0 0.00 5 1254753 142613 0 0
sp_cancel_write_off 9869 9869 0 0.00 2 13562 166 0 0
sp_client_order 21551 21551 0 0.00 6 16124 464 0 0
sp_customer_reserve 491742 491742 0 0.00 1 27421 236 0 0
sp_fill_shopping_cart 33085 33085 0 0.00 1 17150 78 0 0
sp_fill_shopping_cart_clo_res 491713 491713 0 0.00 0 14347 17 0 0
sp_get_clo_for_invoice 5183 5183 0 0.00 0 26187 254 0 0
sp_kill_cost_storno 16431 16431 0 0.00 0 13559 67 0 0
sp_kill_qstorno_ret_qs2qd 1072723 1072723 0 0.00 0 26436 41 0 0
sp_kill_qty_storno 567171 567171 0 0.00 0 27629 94 0 0
sp_lock_selected_doc 21411 21411 0 0.00 0 8006 7 0 0
sp_make_cost_storno 28920 28920 0 0.00 0 19862 75 0 0
sp_multiply_rows_for_pdistr 28920 28920 0 0.00 0 7623 35 0 0
sp_multiply_rows_for_qdistr 21551 21551 0 0.00 0 12849 171 0 0
sp_pay_from_customer 7335 7335 0 0.00 5 17221 167 0 0
sp_pay_to_supplier 6140 6140 0 0.00 7 15057 180 0 0
sp_payment_common 13475 13475 0 0.00 2 9066 41 0 0
sp_qd_handle_on_cancel_clo 1776 1776 0 0.00 0 6355 293 0 0
sp_qd_handle_on_invoice_upd_sts 10583 10583 0 0.00 0 27628 506 0 0
sp_qd_handle_on_reserve_upd_sts 10263 10263 0 0.00 0 13864 60 0 0
sp_reserve_write_off 10263 10263 0 0.00 9 17842 249 0 0
sp_supplier_invoice 5800 5800 0 0.00 7 102821 3028 0 0
sp_supplier_order 5737 5737 0 0.00 7 25509 1355 0 0
srv_aggregate_perf_data 117 116 1 0.85 2328 141643 13732 0 1
srv_fill_mon_cache_memory 76 76 0 0.00 1157 92297 30197 0 0
srv_find_qd_qs_mism 1056285 1056285 0 0.00 0 17877 10 0 0
srv_make_invnt_saldo 490 490 0 0.00 34 86279 3488 0 0
srv_make_money_saldo 905 905 0 0.00 1 14414 355 0 0
srv_recalc_idx_stat 5 5 0 0.00 110094 319372 207607 0 0
t$perf-abend:sp_add_invoice_to_stock 73 73 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5219 5219 0 0.00 1 11227 799 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 1 6971 479 0 0
t$perf-norm:sp_cancel_client_order 1776 1776 0 0.00 0 6392 26 0 0
t$perf-norm:sp_cancel_customer_reserve 706 706 0 0.00 0 1252 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 727 727 0 0.00 0 340 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 1886 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 1171 30 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 97541 6574 0 0
t$perf-norm:sp_cancel_write_off 1068 1068 0 0.00 0 6339 24 0 0
t$perf-norm:sp_client_order 21551 21551 0 0.00 0 10591 34 0 0
t$perf-norm:sp_customer_reserve 1063 1063 0 0.00 0 3182 15 0 0
t$perf-norm:sp_pay_from_customer 7335 7335 0 0.00 0 8738 19 0 0
t$perf-norm:sp_pay_to_supplier 6140 6140 0 0.00 0 6787 19 0 0
t$perf-norm:sp_reserve_write_off 10263 10263 0 0.00 0 6181 18 0 0
t$perf-norm:sp_supplier_invoice 5800 5800 0 0.00 0 4006 26 0 0
t$perf-norm:sp_supplier_order 5737 5737 0 0.00 0 13418 32 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 488 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 76 76 0 0.00 0 465 9 0 0
t$perf-norm:srv_make_invnt_saldo 490 490 0 0.00 0 409 8 0 0
t$perf-norm:srv_make_money_saldo 905 905 0 0.00 0 1925 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 257 90 0 0
v_add_invoice_to_stock 10510 10364 146 1.38 0 5995 24 0 146
v_all_customers 39778 39778 0 0.00 0 61 3 0 0
v_all_suppliers 32274 32274 0 0.00 0 75 4 0 0
v_all_wares 43102 43102 0 0.00 0 96 4 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 1099 14 0 0
v_cancel_client_order 3552 3552 0 0.00 0 6044 10 0 0
v_cancel_customer_prepayment 1454 1454 0 0.00 0 355 9 0 0
v_cancel_customer_reserve 1412 1412 0 0.00 0 2169 11 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 1705 11 0 0
v_cancel_supplier_invoice 866 854 12 1.38 0 1127 22 0 12
v_cancel_supplier_order 864 864 0 0.00 0 336 9 0 0
v_cancel_write_off 2136 2136 0 0.00 0 4620 26 0 0
v_max_id_clo_ord 5737 5737 0 0.00 0 838 5 0 0
v_max_id_clo_res 1063 1063 0 0.00 0 2237 17 0 0
v_max_id_ord_sup 5797 5797 0 0.00 0 6002 6 0 0
v_max_non_paid_invoice 1537 1537 0 0.00 0 826 8 0 0
v_max_non_paid_realizn 3613 3613 0 0.00 0 2332 9 0 0
v_min_id_clo_ord 5737 5737 0 0.00 0 8267 9 0 0
v_min_id_clo_res 1063 1063 0 0.00 0 1752 11 0 0
v_min_id_ord_sup 5797 5797 0 0.00 0 7850 11 0 0
v_min_non_paid_invoice 6140 6140 0 0.00 0 5073 14 0 0
v_min_non_paid_realizn 7335 7335 0 0.00 0 5487 16 0 0
v_our_firm 10768 10768 0 0.00 0 79 4 0 0
v_reserve_write_off 20526 20526 0 0.00 0 6847 10 0 0
x_lock_depdocs_on_canc_invoice 11048 11048 0 0.00 0 31795 122 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 14209 312 0 0
x_make_qstorno_1000_1200 5737 5737 0 0.00 3 25483 1198 0 0
x_make_qstorno_1200_2000 5800 5800 0 0.00 3 102798 2813 0 0
x_make_qstorno_2100_3300 491742 491742 0 0.00 0 27419 208 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-12-2022 10:38:57 to 22-12-2022 10:39:01.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:27 2 8192000000 8796921968 8807436288 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:27 27684 8192000000 9740109216 10045243392 815920512 9.057 100 89 178 0 844560688 7649648 20990528
08:10:01 21533 8192000000 9776387488 10086658048 847535120 9.375 100 91 182 0 883588320 14682016 21371184
08:11:26 13467 8192000000 9805021296 10123763712 873405168 9.634 100 85 170 0 915248640 22001536 19841936
08:12:52 8864 8192000000 9877330160 10210566144 910323888 10.001 100 88 176 0 981690528 52353504 19013136
08:14:06 4914 8192000000 9910096752 10251378688 950246368 10.394 100 88 174 0 1032326064 63378384 18701312
08:15:16 3244 8192000000 9973977168 10328637440 986541776 10.748 100 93 182 0 1089526272 84104352 18880144
08:16:23 1036 8192000000 9987694160 10349068288 1023137520 11.102 100 96 176 1 1107829584 67725040 16967024
08:17:27 4482 8192000000 10005059280 10375364608 1040419248 11.269 100 78 151 0 1124235328 69697632 14118448
08:18:33 826 8192000000 10045860704 10421456896 1061130128 11.467 100 98 193 0 1167630128 87989136 18510864
08:19:35 982 8192000000 10050541824 10428940288 1070892144 11.561 100 94 180 0 1169633120 80176768 18564208
08:20:38 958 8192000000 10074013760 10459865088 1081634448 11.663 100 93 177 2 1190730240 91584160 17511632
08:21:40 1211 8192000000 10063069952 10448990208 1086751424 11.712 100 95 184 1 1188271552 82932560 18587568
08:22:42 6645 8192000000 10030961792 10426032128 1088450784 11.728 100 74 143 1 1162063840 59178432 14434624
08:23:51 1040 8192000000 10046369408 10436149248 1097145936 11.811 100 85 165 1 1168318368 55279616 15892816
08:24:53 8002 8192000000 10071561392 10472062976 1098326736 11.822 100 55 111 0 1189993488 80463344 11203408
08:26:05 920 8192000000 10076112720 10469343232 1101668448 11.853 100 93 176 3 1200091456 80112944 18310064
08:27:07 1124 8192000000 10075141296 10466271232 1096070432 11.800 100 93 178 0 1198158288 83620688 18467168
08:28:09 1492 8192000000 10042230400 10434609152 1095715968 11.797 100 81 152 3 1161146176 50264512 15165696
08:29:11 1972 8192000000 10037475152 10439733248 1096414496 11.804 100 62 116 4 1165885712 57237040 12234176
08:30:13 1701 8192000000 10031093440 10425655296 1099488752 11.833 100 79 150 2 1152331968 37623616 15219600
08:31:16 1110 8192000000 10037186688 10420047872 1067013424 11.524 100 94 186 0 1162118112 74998144 20106544
08:32:18 1114 8192000000 10004097904 10387054592 1052031824 11.380 99 78 141 2 1126648144 60416592 14199728
08:33:21 2919 8192000000 9972943568 10357448704 1026439888 11.134 98 57 104 0 1079339408 41609120 11290400
08:34:25 12256 8192000000 9989225760 10375090176 1009486816 10.970 97 54 100 0 1084656416 64988544 10181056
08:35:38 2249 8192000000 9961304112 10331406336 1001178400 10.890 97 77 143 2 1083785504 66267360 16339744
08:36:42 932 8192000000 10002021664 10380685312 1048112896 11.343 100 93 176 2 1127183728 60068416 19002416
08:37:45 2910 8192000000 10001506416 10384449536 1058117872 11.438 100 61 118 1 1122293904 51064128 13111904
08:38:55 10286 8192000000 10007865120 10396725248 1074358672 11.594 100 65 111 6 1126153584 39366672 12428240
08:40:10 1496 8192000000 10038551152 10421624832 1077997376 11.628 100 89 171 0 1160556864 64853024 17706464
08:41:21 18101 8192000000 10078278880 10465988608 1102185120 11.858 100 64 120 1 1197009680 81594672 13229888
08:42:43 7380 8192000000 10117644848 10511368192 1108115056 11.915 100 62 114 0 1232977168 112415568 12446544
08:43:57 2005 8192000000 10206841568 10598367232 1126388144 12.087 100 91 175 0 1320871472 174637184 19846144
08:45:04 4169 8192000000 10217040912 10621927424 1136742400 12.185 100 69 134 0 1330985216 179267072 14975744
08:46:12 1564 8192000000 10253456304 10648297472 1142239440 12.237 100 97 177 1 1365939216 204448400 19251376
08:47:17 2018 8192000000 10261321472 10655793152 1157009488 12.375 100 90 176 1 1372624720 196478320 19136912
08:48:24 1150 8192000000 10292459600 10702430208 1171649856 12.512 100 93 184 0 1402187536 209215184 21322496
08:49:28 1423 8192000000 10325747120 10733875200 1182763952 12.616 100 98 196 0 1431077056 226682000 21631104
08:50:32 1538 8192000000 10372086224 10777223168 1182380912 12.612 100 94 181 1 1476510192 274086688 20042592
08:51:37 1317 8192000000 10370154272 10791096320 1172609040 12.521 99 90 177 1 1480701088 286417984 21674064
08:52:41 2135 8192000000 10372113552 10830598144 1187087008 12.656 100 88 168 1 1484961728 277723008 20151712
08:53:46 1399 8192000000 10379578416 10827395072 1187420672 12.659 100 75 142 0 1489741680 285438240 16882768
08:54:51 5734 8192000000 10350036480 10792271872 1196672320 12.745 100 49 92 0 1466329968 257471600 12186048
08:56:01 7428 8192000000 10354853808 10800005120 1184146896 12.629 99 59 105 1 1456024368 259937920 11939552
08:57:14 2218 8192000000 10402760256 10826960896 1192538896 12.707 100 88 168 1 1510436000 297920912 19976192
08:58:18 6204 8192000000 10410572288 10852204544 1179336736 12.584 99 57 114 0 1503545408 310110304 14098368
08:59:25 3624 8192000000 10409416448 10856394752 1177750640 12.569 100 73 146 1 1514696864 319198416 17747808
09:00:33 1322 8192000000 10345727968 10803908608 1168366752 12.482 100 79 152 1 1449964176 263172336 18425088
09:01:38 2412 8192000000 10377864704 10832752640 1175575744 12.549 100 82 158 0 1479508304 284652656 19279904
09:02:42 3875 8192000000 10376695088 10809520128 1181232848 12.602 100 60 118 0 1484257632 287998192 15026592
09:03:49 20214 8192000000 10381723392 10831343616 1166523776 12.464 99 41 78 0 1482506720 305751280 10231664
09:05:12 28107 8192000000 10392701232 10861207552 1149693760 12.307 98 37 72 0 1489371920 330221152 9457008
09:06:45 36074 8192000000 10401524080 10861359104 1154635200 12.353 99 32 64 0 1503781808 341072688 8073920
09:08:22 13358 8192000000 10342162384 10776473600 1147587568 12.287 100 32 60 2 1450023984 294134896 8301520
09:09:36 16363 8192000000 10224211184 10657677312 1124260016 12.067 99 19 38 0 1341748304 212527920 4960368
09:11:24 40189 8192000000 10067047952 10608787456 1125046624 12.075 100 12 24 0 1228386784 100720480 2619680
09:13:05 34607 8192000000 10106416512 10526924800 1113215760 11.963 97 43 85 1 1179464304 57262992 8985552
09:14:45 30429 8192000000 10115467632 10543599616 1125996240 12.084 100 30 59 1 1215201200 83062096 6142864
09:16:16 66107 8192000000 10135078976 10631995392 1134799456 12.167 100 14 28 0 1255111248 117296928 3014864
09:18:27 92296 8192000000 10096312208 10535387136 1107258512 11.906 97 17 34 0 1178762208 67303040 4200656
09:21:00 47246 8192000000 10089841152 10523013120 1142583824 12.240 100 35 58 2 1191153776 42002400 6567552
09:22:55 53627 8192000000 10161152784 10594365440 1115239456 11.982 98 44 85 0 1246273872 122105072 8929344
09:25:02 17841 8192000000 10097382304 10601975808 1118961248 12.017 100 9 18 0 1190031168 69010112 2059808
09:26:21 5704 8192000000 10073740656 10535088128 1122947024 12.055 100 45 83 0 1168347248 37540384 7859840
09:27:29 10483 8192000000 10086714224 10502762496 1129764480 12.119 100 68 130 0 1178091408 34979712 13347216
09:28:41 38724 8192000000 10076826880 10532614144 1122156240 12.047 99 16 32 0 1173370064 47992720 3221104
09:30:30 38907 8192000000 10060420400 10537930752 1134272560 12.162 100 11 22 0 1175315184 38510272 2532352
09:32:10 54639 8192000000 10105727888 10564042752 1145684624 12.269 100 26 51 0 1195797216 44687536 5425056
09:34:12 68864 8192000000 10107389792 10568110080 1149115136 12.301 100 29 56 0 1222529760 67159616 6255008
09:36:22 15573 8192000000 10065636592 10503151616 1122280160 12.049 98 39 76 0 1162334592 32362928 7691504
09:37:39 52916 8192000000 10086915296 10579357696 1130797600 12.129 99 16 32 0 1185754368 51244496 3712272
09:39:42 26924 8192000000 10064661504 10530439168 1149719600 12.307 100 20 35 1 1176506160 23406864 3379696
09:41:13 28045 8192000000 10120539664 10569170944 1138901888 12.205 99 24 48 0 1195033056 51348768 4782400
09:42:52 35695 8192000000 10099183296 10564173824 1126544576 12.089 98 28 54 0 1185892800 53586144 5762080
09:44:32 33514 8192000000 10181008016 10624798720 1135140000 12.170 99 43 86 0 1260971520 116607136 9224384
09:46:06 26355 8192000000 10135588048 10570330112 1130865184 12.130 100 52 104 0 1234326048 92034784 11426080
09:47:38 14154 8192000000 10131868880 10566418432 1133971520 12.159 100 42 78 0 1214707680 73069536 7666624
09:48:56 70045 8192000000 10100627760 10570178560 1075489520 11.604 95 19 38 0 1172100064 92232192 4378352
09:51:07 47924 8192000000 10073865168 10531655680 1107484752 11.909 98 34 64 1 1148076896 34417408 6174736
09:52:59 76625 8192000000 10076686000 10516738048 1108440960 11.918 98 46 91 2 1171258912 53535232 9282720
09:55:18 34933 8192000000 10049778016 10483068928 1115816592 11.987 99 34 67 0 1157527840 35040688 6670560
09:57:02 73638 8192000000 10068128784 10547363840 1121836976 12.044 99 7 14 0 1175203040 52004576 1361488
09:59:16 50191 8192000000 10029452768 10491260928 1100721552 11.844 98 9 18 0 1129167408 26605456 1840400
10:01:30 48569 8192000000 10070436560 10514608128 1070282432 11.555 96 23 44 0 1126177504 51362208 4532864
10:03:21 52759 8192000000 10103136528 10571128832 1087315792 11.717 96 28 56 0 1170294704 76704192 6274720
10:05:17 38971 8192000000 10079479936 10532773888 1091827968 11.760 97 25 50 0 1165863328 68763888 5271472
10:06:57 29570 8192000000 10091515696 10544930816 1080912384 11.656 97 37 74 0 1185026736 96624336 7490016
10:08:31 17815 8192000000 10101299024 10528055296 1121269504 12.039 100 45 90 0 1187310160 57562992 8477664
10:09:51 60221 8192000000 10083897280 10517159936 1130098128 12.122 100 19 38 0 1190516272 57049152 3368992
10:11:52 22797 8192000000 10112077776 10583588864 1139396736 12.210 100 33 64 0 1213241536 67405200 6439600
10:13:17 20966 8192000000 10104515072 10540986368 1134482448 12.164 100 50 100 0 1195337408 51192592 9662368
10:14:39 57919 8192000000 10102474464 10553708544 1132359216 12.144 100 47 92 1 1200342128 58696096 9286816
10:16:37 10834 8192000000 10050336384 10497421312 1122215744 12.048 99 38 73 0 1153870544 24182064 7472736
10:17:51 62646 8192000000 10063023680 10531282944 1093334416 11.774 97 11 22 0 1134135232 38451552 2349264
10:19:53 83887 8192000000 10074972096 10550996992 1122677104 12.052 99 13 26 0 1188356720 63043376 2636240
10:22:19 54949 8192000000 10108134768 10578046976 1147174048 12.283 100 17 34 0 1213015200 61787600 4053552
10:24:19 24076 8192000000 10145303664 10590199808 1148500736 12.295 100 42 84 0 1232319360 74629392 9189232
10:25:50 46936 8192000000 10163150608 10607878144 1122434736 12.050 97 27 54 0 1238399760 110265264 5699760
10:27:38 41941 8192000000 10186782928 10659790848 1115529712 11.985 97 33 66 0 1264201408 141640944 7030752
10:29:30 61743 8192000000 10086903616 10559238144 1134800800 12.167 100 21 42 0 1248276272 108926736 4548736
10:32:27 91315 8192000000 10074524560 10534879232 1105714464 11.892 98 20 40 0 1180515504 70490992 4310048
10:35:09 53038 8192000000 10070618608 10558734336 1120789424 12.034 99 10 20 1 1174071616 51036576 2245616
10:37:08 21794 8192000000 9122309856 9389715456 255857184 3.028 23 7 14 1 267275952 10256064 1162704

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 22-12-2022 10:39:03 to 22-12-2022 10:39:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 73 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 22-12-2022 10:39:20 to 22-12-2022 10:39:22.

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          225348
MON$OLDEST_ACTIVE               225349
MON$OLDEST_SNAPSHOT             225349
MON$NEXT_TRANSACTION            225349
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                       2068064
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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 ---
:323: 
:324: mscs-test-161.budzdorov.ru    Thu Dec 22 10:37:35 2022
:325:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:326: 
--- end of diff output ---

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

22.12.22 10:39:24. 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
2022-12-22 10:39:26 No severe PSQL-related problems occured
22.12.22 10:39:27. End of report.