Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.08.2022 04: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 15386472 kB
MemAvailable 30911328 kB
Buffers 20 kB
Cached 16282932 kB
SwapCached 2508 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.2
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-08-12 04:37:19
DTS_END                         2022-08-12 04:37:19.3020
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/41610
TRN_ID                          223347
ATT_ID                          602
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: 9184.78 25917 1102173
customer order: creation 170.69 478 20483
customer order: refuse 15.22 383 1826
order to supplier: creation 45.71 1427 5485
order to supplier: removal 3.41 159398 409
invoice (draft): creation 45.38 3200 5446
invoice (draft): removal 50.71 1001 6085
invoice accept: apply 42.42 24948 5090
invoice accept: cancel 45.68 1190 5481
customer reserve: creation 3974.24 235 476909
customer reserve: removal 4502.51 95 540301
realization accept: apply 82.08 270 9849
realization accept: cancel 76.95 154 9234
payment from customer: creation 57.93 166 6952
payment from customer: removal 6.01 112 721
payment to supplier: creation 49.84 182 5981
payment to supplier: removal 3.42 121 410
service: total inventory turnovers 4.13 3649 495
service: total monetary turnovers 7.47 354 896
service: aggregate perf. data 0.97 16750 116
service: refresh index statistics 0.03 304222 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-08-2022 04:39:13 to 12-08-2022 04:39:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9431
TEST_TIME 2 9204
TEST_TIME 3 8555
TEST_TIME 4 7784
TEST_TIME 5 7238
TEST_TIME 6 6727
TEST_TIME 7 6420
TEST_TIME 8 6377
TEST_TIME 9 6356
TEST_TIME 10 6428
TEST_TIME 11 6554
TEST_TIME 12 6840
TEST_TIME 13 6814
TEST_TIME 14 6764
TEST_TIME 15 6856
TEST_TIME 16 6856
TEST_TIME 17 6946
TEST_TIME 18 7122
TEST_TIME 19 7236
TEST_TIME 20 7372
TEST_TIME 21 7512
TEST_TIME 22 7794
TEST_TIME 23 7877
TEST_TIME 24 8339
TEST_TIME 25 8287
TEST_TIME 26 8445
TEST_TIME 27 8525
TEST_TIME 28 8951
TEST_TIME 29 9122
TEST_TIME 30 9473
TEST_TIME 31 9916
TEST_TIME 32 9879
TEST_TIME 33 10238
TEST_TIME 34 10970
TEST_TIME 35 11145
TEST_TIME 36 11033
TEST_TIME 37 10988
TEST_TIME 38 10989
TEST_TIME 39 10991
TEST_TIME 40 10797
TEST_TIME 41 10634
TEST_TIME 42 10441
TEST_TIME 43 10421
TEST_TIME 44 10466
TEST_TIME 45 10396
TEST_TIME 46 10278
TEST_TIME 47 10179
TEST_TIME 48 10125
TEST_TIME 49 10091
TEST_TIME 50 10009
TEST_TIME 51 10157
TEST_TIME 52 10231
TEST_TIME 53 10148
TEST_TIME 54 10064
TEST_TIME 55 9924
TEST_TIME 56 9886
TEST_TIME 57 9770
TEST_TIME 58 9780
TEST_TIME 59 9720
TEST_TIME 60 9588
TEST_TIME 61 9601
TEST_TIME 62 9594
TEST_TIME 63 9528
TEST_TIME 64 9478
TEST_TIME 65 9536
TEST_TIME 66 9602
TEST_TIME 67 9533
TEST_TIME 68 9608
TEST_TIME 69 9559
TEST_TIME 70 9687
TEST_TIME 71 9756
TEST_TIME 72 9774
TEST_TIME 73 9731
TEST_TIME 74 9705
TEST_TIME 75 9597
TEST_TIME 76 9500
TEST_TIME 77 9493
TEST_TIME 78 9462
TEST_TIME 79 9441
TEST_TIME 80 9388
TEST_TIME 81 9339
TEST_TIME 82 9274
TEST_TIME 83 9255
TEST_TIME 84 9232
TEST_TIME 85 9195
TEST_TIME 86 9207
TEST_TIME 87 9187
TEST_TIME 88 9227
TEST_TIME 89 9218
TEST_TIME 90 9201
TEST_TIME 91 9161
TEST_TIME 92 9273
TEST_TIME 93 9297
TEST_TIME 94 9401
TEST_TIME 95 9451
TEST_TIME 96 9383
TEST_TIME 97 9466
TEST_TIME 98 9443
TEST_TIME 99 9414
TEST_TIME 100 9431
TEST_TIME 101 9384
TEST_TIME 102 9374
TEST_TIME 103 9408
TEST_TIME 104 9410
TEST_TIME 105 9413
TEST_TIME 106 9411
TEST_TIME 107 9424
TEST_TIME 108 9401
TEST_TIME 109 9456
TEST_TIME 110 9466
TEST_TIME 111 9464
TEST_TIME 112 9473
TEST_TIME 113 9419
TEST_TIME 114 9365
TEST_TIME 115 9340
TEST_TIME 116 9321
TEST_TIME 117 9314
TEST_TIME 118 9282
TEST_TIME 119 9266
TEST_TIME 120 9201

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-08-2022 04:39:17 to 12-08-2022 04:39:20.

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 1541161 1541161 0 0.00 0 17254 12 0 0
doc_list_biud 1541161 1541161 0 0.00 0 28737 39 0 0
sp_add_invoice_to_stock 5216 5090 126 2.41 35 218398 24949 0 126
sp_cancel_adding_invoice 5481 5481 0 0.00 6 87217 1191 0 0
sp_cancel_client_order 1826 1826 0 0.00 6 13793 384 0 0
sp_cancel_customer_reserve 540301 540301 0 0.00 1 20017 95 0 0
sp_cancel_pay_from_customer 721 721 0 0.00 4 2199 113 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 4 9492 122 0 0
sp_cancel_supplier_invoice 6090 6085 5 0.08 2 28744 1001 0 5
sp_cancel_supplier_order 409 409 0 0.00 10 1503960 159399 0 0
sp_cancel_write_off 9234 9234 0 0.00 2 17271 155 0 0
sp_client_order 20483 20483 0 0.00 5 24202 479 0 0
sp_customer_reserve 476909 476909 0 0.00 0 104992 236 0 0
sp_fill_shopping_cart 31407 31407 0 0.00 1 10533 77 0 0
sp_fill_shopping_cart_clo_res 476881 476881 0 0.00 0 20923 18 0 0
sp_get_clo_for_invoice 5059 5059 0 0.00 0 30828 270 0 0
sp_kill_cost_storno 15846 15846 0 0.00 0 12063 66 0 0
sp_kill_qstorno_ret_qs2qd 1096330 1096330 0 0.00 0 28736 41 0 0
sp_kill_qty_storno 578240 578240 0 0.00 0 28737 96 0 0
sp_lock_selected_doc 20774 20774 0 0.00 0 9459 7 0 0
sp_make_cost_storno 27837 27837 0 0.00 0 13779 77 0 0
sp_multiply_rows_for_pdistr 27837 27837 0 0.00 0 13266 37 0 0
sp_multiply_rows_for_qdistr 20483 20483 0 0.00 0 12152 176 0 0
sp_pay_from_customer 6952 6952 0 0.00 6 14612 167 0 0
sp_pay_to_supplier 5981 5981 0 0.00 6 13296 182 0 0
sp_payment_common 12933 12933 0 0.00 2 6961 42 0 0
sp_qd_handle_on_cancel_clo 1826 1826 0 0.00 0 13690 313 0 0
sp_qd_handle_on_invoice_upd_sts 10536 10536 0 0.00 0 27082 513 0 0
sp_qd_handle_on_reserve_upd_sts 9849 9849 0 0.00 0 20072 70 0 0
sp_reserve_write_off 9849 9849 0 0.00 8 20408 270 0 0
sp_supplier_invoice 5446 5446 0 0.00 9 103482 3200 0 0
sp_supplier_order 5485 5485 0 0.00 7 46760 1428 0 0
srv_aggregate_perf_data 117 116 1 0.85 2240 339296 16750 0 1
srv_fill_mon_cache_memory 71 71 0 0.00 722 121450 32935 0 0
srv_find_qd_qs_mism 1052686 1052686 0 0.00 0 16978 9 0 0
srv_make_invnt_saldo 495 495 0 0.00 46 62556 3650 0 0
srv_make_money_saldo 896 896 0 0.00 1 12553 354 0 0
srv_recalc_idx_stat 4 4 0 0.00 167713 489378 304223 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 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 5090 5090 0 0.00 1 14203 877 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 1 6291 462 0 0
t$perf-norm:sp_cancel_client_order 1826 1826 0 0.00 0 8803 39 0 0
t$perf-norm:sp_cancel_customer_reserve 656 656 0 0.00 0 1081 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 721 721 0 0.00 0 5759 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 537 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 762 29 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 70658 6445 0 0
t$perf-norm:sp_cancel_write_off 1038 1038 0 0.00 0 3175 23 0 0
t$perf-norm:sp_client_order 20483 20483 0 0.00 0 10359 37 0 0
t$perf-norm:sp_customer_reserve 1044 1044 0 0.00 0 1506 19 0 0
t$perf-norm:sp_pay_from_customer 6952 6952 0 0.00 0 19724 22 0 0
t$perf-norm:sp_pay_to_supplier 5981 5981 0 0.00 0 7888 20 0 0
t$perf-norm:sp_reserve_write_off 9849 9849 0 0.00 0 9338 25 0 0
t$perf-norm:sp_supplier_invoice 5446 5446 0 0.00 0 13962 37 0 0
t$perf-norm:sp_supplier_order 5485 5485 0 0.00 0 12787 36 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 587 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 380 8 0 0
t$perf-norm:srv_make_invnt_saldo 495 495 0 0.00 0 537 9 0 0
t$perf-norm:srv_make_money_saldo 896 896 0 0.00 0 3187 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 189 73 0 0
v_add_invoice_to_stock 10358 10106 252 2.43 0 7999 24 0 252
v_all_customers 37224 37224 0 0.00 0 107 4 0 0
v_all_suppliers 30780 30780 0 0.00 0 110 4 0 0
v_all_wares 40966 40966 0 0.00 0 109 4 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 1967 15 0 0
v_cancel_client_order 3652 3652 0 0.00 0 2581 10 0 0
v_cancel_customer_prepayment 1442 1442 0 0.00 0 2193 12 0 0
v_cancel_customer_reserve 1312 1312 0 0.00 0 6696 22 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 275 9 0 0
v_cancel_supplier_invoice 816 806 10 1.22 0 542 22 0 10
v_cancel_supplier_order 818 818 0 0.00 0 2570 12 0 0
v_cancel_write_off 2076 2076 0 0.00 0 3464 25 0 0
v_max_id_clo_ord 5485 5485 0 0.00 0 4294 7 0 0
v_max_id_clo_res 1044 1044 0 0.00 0 1986 13 0 0
v_max_id_ord_sup 5439 5439 0 0.00 0 2301 6 0 0
v_max_non_paid_invoice 1515 1515 0 0.00 0 2508 9 0 0
v_max_non_paid_realizn 3673 3673 0 0.00 0 2706 9 0 0
v_min_id_clo_ord 5485 5485 0 0.00 0 3834 9 0 0
v_min_id_clo_res 1044 1044 0 0.00 0 8247 34 0 0
v_min_id_ord_sup 5439 5439 0 0.00 0 5369 9 0 0
v_min_non_paid_invoice 5981 5981 0 0.00 0 6955 14 0 0
v_min_non_paid_realizn 6952 6952 0 0.00 0 3314 13 0 0
v_our_firm 10300 10300 0 0.00 0 64 4 0 0
v_reserve_write_off 19698 19698 0 0.00 0 7976 13 0 0
x_lock_depdocs_on_canc_invoice 11163 11163 0 0.00 0 12297 113 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 2828 343 0 0
x_make_qstorno_1000_1200 5485 5485 0 0.00 3 46726 1257 0 0
x_make_qstorno_1200_2000 5446 5446 0 0.00 4 103404 3002 0 0
x_make_qstorno_2100_3300 476909 476909 0 0.00 0 104989 206 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-08-2022 04:39:21 to 12-08-2022 04:39:25.

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
02:07:23 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:24 21647 8192000000 9728265376 10029776896 807679600 8.974 100 82 164 0 835031648 7304160 20047888
02:09:48 22658 8192000000 9768531136 10078707712 839493248 9.295 100 79 156 1 870194256 12067968 18633040
02:11:15 18378 8192000000 9807349200 10127179776 866767440 9.568 100 83 166 1 907586048 21483856 19334752
02:12:41 8303 8192000000 9864074688 10196156416 907007760 9.968 100 80 157 0 974934752 50810048 17116944
02:14:02 3999 8192000000 9921070320 10261708800 955354208 10.444 100 88 174 1 1036831216 63051968 18425040
02:15:09 1305 8192000000 9964671680 10316709888 988475952 10.767 100 90 175 2 1082003488 75962000 17565536
02:16:13 1308 8192000000 9985123584 10343542784 1014808624 11.022 100 93 179 0 1102531984 69703872 18019488
02:17:16 1491 8192000000 10006141376 10373869568 1030581120 11.174 100 95 184 0 1122465184 73496848 18387216
02:18:19 1125 8192000000 10033655456 10411507712 1050184000 11.362 100 94 185 1 1149321696 80106160 19031536
02:19:21 818 8192000000 10041415888 10422120448 1061972560 11.475 100 96 193 1 1163400176 82422384 19005232
02:20:23 1017 8192000000 10044470064 10424950784 1070109328 11.553 100 96 178 0 1166484016 78436000 17938688
02:21:26 2422 8192000000 10028207520 10411081728 1074775696 11.598 100 80 147 1 1156865936 68818000 13272240
02:22:30 1164 8192000000 10053937856 10436579328 1077751008 11.626 100 96 181 2 1178809888 81696416 19362464
02:23:32 1026 8192000000 10050748576 10435108864 1083490368 11.681 100 90 172 2 1173214064 69539952 20183744
02:24:34 1055 8192000000 10077178672 10467647488 1090508800 11.747 100 93 177 1 1200474368 91881648 18083920
02:25:36 1058 8192000000 10070519488 10462691328 1092270656 11.764 100 92 177 1 1191603696 81562880 17770160
02:26:37 944 8192000000 10063117712 10455015424 1095387872 11.794 100 92 184 1 1180549776 67117344 18044560
02:27:39 1146 8192000000 10044770272 10431275008 1086189824 11.706 100 94 175 4 1166759424 63304560 17265040
02:28:41 1684 8192000000 10045053728 10434068480 1091459376 11.757 100 74 146 0 1164376096 58396176 14520544
02:29:43 975 8192000000 10031840304 10423263232 1085408160 11.699 100 77 148 0 1160135456 58984976 15742320
02:30:46 1584 8192000000 10024892336 10407624704 1075454256 11.604 100 87 164 2 1151751136 59305552 16991328
02:31:48 998 8192000000 10007708528 10386472960 1057987904 11.437 99 91 173 0 1133804064 57792400 18023760
02:32:52 852 8192000000 9999930208 10378883072 1044896528 11.312 100 84 165 1 1123311744 60930704 17484512
02:33:54 2525 8192000000 9997952240 10381582336 1042409760 11.288 100 79 157 2 1131950272 73474032 16066480
02:34:59 900 8192000000 9982197584 10358493184 1046730928 11.329 100 92 173 2 1105188688 40185664 18272096
02:36:01 1101 8192000000 9990773184 10368012288 1038926016 11.254 100 84 160 2 1113956992 56680672 18350304
02:37:02 5556 8192000000 9984070096 10368237568 1040821824 11.273 100 68 128 0 1102803840 48901120 13080896
02:38:14 1251 8192000000 9982936272 10356592640 1048867152 11.350 99 87 154 3 1100315968 34199008 17249808
02:39:18 1416 8192000000 10064590656 10451873792 1077832768 11.627 100 93 182 0 1178840128 83420112 17587248
02:40:25 837 8192000000 10068692384 10453921792 1084438304 11.690 100 95 182 0 1188892224 87954656 16499264
02:41:30 16939 8192000000 10038386208 10441650176 1091015216 11.752 100 73 138 1 1164022896 56873744 16133936
02:42:50 20367 8192000000 10077177408 10478927872 1098874592 11.827 100 47 94 0 1189552224 80894160 9783472
02:44:15 3364 8192000000 10085170336 10487406592 1118087952 12.009 100 63 118 1 1201026448 70403776 12534720
02:45:27 3060 8192000000 10136307776 10544058368 1129716560 12.119 100 70 131 1 1249778960 106389136 13673264
02:46:35 4958 8192000000 10185908528 10582466560 1137406656 12.191 100 88 164 0 1300307600 144791360 18109584
02:47:43 2273 8192000000 10224894192 10630799360 1155319952 12.359 100 94 185 1 1334882208 159407568 20154688
02:48:49 4664 8192000000 10250092512 10653528064 1168424592 12.482 100 82 164 0 1362581328 176053728 18103008
02:50:00 2458 8192000000 10251644208 10653913088 1166039296 12.460 100 91 176 1 1361421632 176952160 18430176
02:51:07 792 8192000000 10313338208 10714341376 1186778352 12.653 100 99 190 1 1420243728 212732192 20733184
02:52:12 1223 8192000000 10369434336 10773458944 1194547712 12.726 100 91 178 1 1476914048 261962608 20403728
02:53:15 969 8192000000 10405700352 10824179712 1208380736 12.854 100 92 176 2 1510975648 282640576 19954336
02:54:20 848 8192000000 10422274960 10830352384 1210258320 12.871 100 95 182 0 1529074688 295174880 23641488
02:55:24 794 8192000000 10452140000 10879590400 1212493312 12.892 100 99 189 0 1558810368 324099072 22217984
02:56:29 1038 8192000000 10504036720 10920026112 1204984656 12.823 100 97 192 2 1615199424 386502432 23712336
02:57:32 821 8192000000 10528148896 10956312576 1203812144 12.812 100 95 190 1 1638432144 412331824 22288176
02:58:36 1216 8192000000 10519479872 10953293824 1206181920 12.834 100 90 177 0 1624250320 397326720 20741680
02:59:40 1655 8192000000 10517073776 10951401472 1201942064 12.794 100 94 181 1 1621247312 397114400 22190848
03:00:44 8036 8192000000 10493730912 10944188416 1159473120 12.398 98 58 110 1 1595346352 421501184 14372048
03:01:55 8059 8192000000 10508866720 10931732480 1191969424 12.702 100 60 118 1 1615576784 409087200 14520160
03:03:28 6533 8192000000 10503359056 10958573568 1171956976 12.515 100 53 103 0 1612015264 426970896 13087392
03:04:37 3999 8192000000 10526187664 10974871552 1177246192 12.565 100 58 116 0 1624360368 432184416 14929760
03:05:42 10959 8192000000 10479270464 10948247552 1148069744 12.291 99 44 82 2 1583741376 424440688 11230944
03:06:55 16306 8192000000 10444616400 10950455296 1165155328 12.452 100 43 83 0 1578352688 402201840 10995520
03:08:13 36890 8192000000 10333393840 10824171520 1150650304 12.316 99 32 60 0 1435980944 277552384 7778256
03:09:58 40152 8192000000 10327562576 10870325248 1142491120 12.239 99 20 40 0 1418239104 270085472 5662512
03:11:39 43505 8192000000 10093786624 10686484480 1132380096 12.144 100 9 18 0 1248444512 113479504 2584912
03:13:23 67999 8192000000 10088826944 10544680960 1100478704 11.842 97 11 22 1 1150855648 47961072 2415872
03:16:56 25855 8192000000 10063518464 10485526528 1126677136 12.090 99 47 84 1 1149735280 14765920 8292224
03:18:47 82955 8192000000 10118220176 10570665984 1123848608 12.063 98 45 78 0 1193747584 61539696 8359280
03:21:22 30870 8192000000 10099115664 10549288960 1144020224 12.253 100 53 95 2 1184544464 30757568 9766672
03:22:54 41532 8192000000 10112668480 10581430272 1146501072 12.277 100 7 14 0 1219654672 71529808 1623792
03:24:37 711 8192000000 10138872704 10561396736 1153323248 12.341 100 91 175 5 1237186768 64268656 19594864
03:25:38 59992 8192000000 10198607824 10643763200 1158803584 12.392 100 36 72 0 1284761856 117430016 8528256
03:27:43 57553 8192000000 10166216672 10707701760 1143262464 12.246 99 16 32 0 1266499872 119456064 3781344
03:31:03 85305 8192000000 10064644416 10535104512 1133130768 12.151 99 5 10 0 1173999248 39760064 1108416
03:33:29 50653 8192000000 10055498080 10506383360 1105096576 11.886 96 20 38 0 1145229264 34208816 5923872
03:35:21 66599 8192000000 10059300864 10541973504 1123761600 12.063 99 8 16 0 1173158304 47810608 1586096
03:37:42 70580 8192000000 10080535040 10580750336 1136777456 12.185 99 14 28 0 1196407376 56781776 2848144
03:40:26 29504 8192000000 10103609488 10547429376 1142737088 12.241 100 28 53 0 1203902672 55784544 5381040
03:42:06 57062 8192000000 10161661648 10621890560 1143438512 12.248 99 26 52 0 1275670416 126216128 6015776
03:44:15 42535 8192000000 10191720176 10635968512 1151354256 12.322 99 27 50 1 1252430128 95790512 5285360
03:46:01 59480 8192000000 10163131888 10611298304 1089553104 11.738 95 24 45 0 1208819712 114549664 4716944
03:48:16 34368 8192000000 10079200576 10514968576 1122814448 12.054 99 20 38 1 1158588768 31987056 3787264
03:49:50 33979 8192000000 10095469712 10520100864 1113033168 11.961 97 57 111 2 1153667760 26922544 13712048
03:51:31 121449 8192000000 10099851712 10550329344 1114505152 11.975 97 51 98 0 1199169296 74467904 10196240
03:54:36 17538 8192000000 10028206576 10463514624 1082972128 11.676 97 44 80 5 1111323952 20600688 7751136
03:55:54 66095 8192000000 10034068144 10501115904 1101137216 11.848 98 13 24 0 1140103120 36607200 2358704
03:58:01 59355 8192000000 10055636432 10508189696 1093021152 11.771 97 13 26 1 1128415808 32571312 2823344
04:00:01 63545 8192000000 10036076096 10509545472 1107165760 11.906 99 16 28 1 1143171856 32344880 3661216
04:02:09 24620 8192000000 10066288176 10508083200 1097136544 11.810 97 37 66 0 1147660304 42805904 7717856
04:03:41 33160 8192000000 10110014896 10550292480 1125711536 12.081 99 34 68 0 1192719024 59677696 7329792
04:05:18 30460 8192000000 10117049536 10581467136 1097478160 11.814 97 47 92 1 1228884128 120936208 10469760
04:06:50 26132 8192000000 10154838624 10618384384 1132771872 12.147 100 41 82 0 1250438048 109106016 8560160
04:08:24 9701 8192000000 10214640544 10640801792 1124689728 12.071 98 61 121 1 1292366784 154229440 13447616
04:09:34 34160 8192000000 10164922768 10652860416 1128833440 12.110 99 28 56 0 1270463744 135452592 6177712
04:11:10 69324 8192000000 10107780992 10579853312 1127052880 12.094 99 21 40 0 1218652656 87294352 4305424
04:13:20 73913 8192000000 10155459056 10646913024 1142935264 12.243 100 41 82 0 1273661168 122154976 8570928
04:15:52 64805 8192000000 10081755152 10531430400 1104852912 11.884 97 15 28 1 1153721056 44969936 3898208
04:17:57 63864 8192000000 10071631904 10521817088 1121693904 12.043 98 26 50 0 1154433920 27703936 5036080
04:20:03 35112 8192000000 10093183504 10558726144 1153676288 12.344 100 29 56 0 1213974752 54807184 5491280
04:22:37 60902 8192000000 10142091696 10582552576 1108211648 11.915 96 23 46 0 1178670048 65741984 4716416
04:24:43 5061 8192000000 10087430192 10519547904 1135763328 12.176 100 56 104 0 1184807056 38137904 10905824
04:25:50 77990 8192000000 10103701056 10593828864 1146734512 12.279 100 32 64 0 1231507776 77938000 6835264
04:28:09 36406 8192000000 10091659664 10527404032 1133384576 12.153 99 38 74 2 1180346912 38977200 7985136
04:29:56 18243 8192000000 10088686304 10519724032 1129007488 12.112 98 44 74 2 1162747904 25549024 8191392
04:31:17 107193 8192000000 10089263584 10529284096 1102088992 11.857 96 35 68 0 1167517360 58632832 6795536
04:34:05 43241 8192000000 10102627328 10553925632 1115962352 11.989 98 34 68 0 1183108464 59838816 7307296
04:35:49 52848 8192000000 10096254496 10562859008 1119555072 12.023 98 14 28 0 1197328576 74518240 3255264

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-08-2022 04:39:27 to 12-08-2022 04:39:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 126 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 12-08-2022 04:39:44 to 12-08-2022 04:39:46.

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          218763
MON$OLDEST_ACTIVE               223481
MON$OLDEST_SNAPSHOT             223481
MON$NEXT_TRANSACTION            223481
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                       2089056
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.2.2816 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2816 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2816 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2816 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 ---
:137: 
:138: mscs-test-161.budzdorov.ru    Fri Aug 12 04:37:38 2022
:139:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:140: 
:141: 
:142: mscs-test-161.budzdorov.ru    Fri Aug 12 04:37:39 2022
:143:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:144: 
--- end of diff output ---

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

12.08.22 04:39:47. 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-08-12 04:39:50 No severe PSQL-related problems occured
12.08.22 04:39:51. End of report.