Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.02.2023 07: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 14679872 kB
MemAvailable 30460760 kB
Buffers 0 kB
Cached 17033312 kB
SwapCached 3176 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 2023-02-18 07:37:25
DTS_END                         2023-02-18 07:37:25.1430
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/54216
TRN_ID                          224103
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: 9080.60 28291 1089672
customer order: creation 171.23 436 20548
customer order: refuse 15.51 485 1861
order to supplier: creation 45.73 1408 5488
order to supplier: removal 3.53 162092 424
invoice (draft): creation 47.27 3338 5672
invoice (draft): removal 49.80 1099 5976
invoice accept: apply 43.28 25973 5193
invoice accept: cancel 44.43 1438 5331
customer reserve: creation 4021.10 241 482532
customer reserve: removal 4347.43 102 521691
realization accept: apply 84.07 318 10088
realization accept: cancel 74.01 187 8881
payment from customer: creation 61.08 193 7330
payment from customer: removal 6.04 130 725
payment to supplier: creation 49.13 211 5895
payment to supplier: removal 3.42 138 410
service: total inventory turnovers 4.02 4778 482
service: total monetary turnovers 8.48 387 1017
service: aggregate perf. data 1.03 16935 123
service: refresh index statistics 0.04 345922 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 18-02-2023 07:39:04 to 18-02-2023 07:39:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9469
TEST_TIME 2 6646
TEST_TIME 3 7298
TEST_TIME 4 7520
TEST_TIME 5 7159
TEST_TIME 6 6681
TEST_TIME 7 6474
TEST_TIME 8 6243
TEST_TIME 9 6370
TEST_TIME 10 6702
TEST_TIME 11 6603
TEST_TIME 12 6804
TEST_TIME 13 6939
TEST_TIME 14 7108
TEST_TIME 15 7001
TEST_TIME 16 7309
TEST_TIME 17 7532
TEST_TIME 18 7537
TEST_TIME 19 7587
TEST_TIME 20 7703
TEST_TIME 21 7965
TEST_TIME 22 8046
TEST_TIME 23 8110
TEST_TIME 24 8149
TEST_TIME 25 8208
TEST_TIME 26 8320
TEST_TIME 27 8406
TEST_TIME 28 8370
TEST_TIME 29 8358
TEST_TIME 30 8309
TEST_TIME 31 8501
TEST_TIME 32 8927
TEST_TIME 33 9423
TEST_TIME 34 9699
TEST_TIME 35 9831
TEST_TIME 36 10288
TEST_TIME 37 10297
TEST_TIME 38 10529
TEST_TIME 39 10577
TEST_TIME 40 10426
TEST_TIME 41 10503
TEST_TIME 42 10691
TEST_TIME 43 10577
TEST_TIME 44 10484
TEST_TIME 45 10430
TEST_TIME 46 10252
TEST_TIME 47 10199
TEST_TIME 48 10161
TEST_TIME 49 10084
TEST_TIME 50 10101
TEST_TIME 51 10121
TEST_TIME 52 10104
TEST_TIME 53 10009
TEST_TIME 54 10013
TEST_TIME 55 10076
TEST_TIME 56 9983
TEST_TIME 57 9896
TEST_TIME 58 9800
TEST_TIME 59 9718
TEST_TIME 60 9643
TEST_TIME 61 9615
TEST_TIME 62 9552
TEST_TIME 63 9503
TEST_TIME 64 9538
TEST_TIME 65 9519
TEST_TIME 66 9577
TEST_TIME 67 9583
TEST_TIME 68 9516
TEST_TIME 69 9431
TEST_TIME 70 9418
TEST_TIME 71 9419
TEST_TIME 72 9350
TEST_TIME 73 9285
TEST_TIME 74 9275
TEST_TIME 75 9264
TEST_TIME 76 9251
TEST_TIME 77 9222
TEST_TIME 78 9230
TEST_TIME 79 9273
TEST_TIME 80 9272
TEST_TIME 81 9324
TEST_TIME 82 9319
TEST_TIME 83 9285
TEST_TIME 84 9284
TEST_TIME 85 9337
TEST_TIME 86 9421
TEST_TIME 87 9468
TEST_TIME 88 9425
TEST_TIME 89 9430
TEST_TIME 90 9536
TEST_TIME 91 9474
TEST_TIME 92 9407
TEST_TIME 93 9353
TEST_TIME 94 9301
TEST_TIME 95 9297
TEST_TIME 96 9261
TEST_TIME 97 9212
TEST_TIME 98 9166
TEST_TIME 99 9179
TEST_TIME 100 9147
TEST_TIME 101 9133
TEST_TIME 102 9113
TEST_TIME 103 9110
TEST_TIME 104 9114
TEST_TIME 105 9095
TEST_TIME 106 9094
TEST_TIME 107 9166
TEST_TIME 108 9149
TEST_TIME 109 9162
TEST_TIME 110 9144
TEST_TIME 111 9168
TEST_TIME 112 9191
TEST_TIME 113 9196
TEST_TIME 114 9183
TEST_TIME 115 9209
TEST_TIME 116 9259
TEST_TIME 117 9264
TEST_TIME 118 9191
TEST_TIME 119 9147
TEST_TIME 120 9119

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-02-2023 07:39:08 to 18-02-2023 07:39:11.

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 1530944 1530944 0 0.00 0 30901 14 0 0
doc_list_biud 1530929 1530929 0 0.00 0 33278 42 0 0
sp_add_invoice_to_stock 5328 5193 135 2.53 59 188809 25973 0 135
sp_cancel_adding_invoice 5331 5331 0 0.00 7 80754 1439 0 0
sp_cancel_client_order 1861 1861 0 0.00 6 20579 486 0 0
sp_cancel_customer_reserve 521691 521691 0 0.00 1 33287 102 0 0
sp_cancel_pay_from_customer 725 725 0 0.00 4 7114 130 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 4 3443 139 0 0
sp_cancel_supplier_invoice 5983 5976 7 0.11 3 24877 1099 0 7
sp_cancel_supplier_order 424 424 0 0.00 9 1712243 162093 0 0
sp_cancel_write_off 8881 8881 0 0.00 2 20129 187 0 0
sp_client_order 20548 20548 0 0.00 5 20480 437 0 0
sp_customer_reserve 482532 482532 0 0.00 1 112957 241 0 0
sp_fill_shopping_cart 31698 31698 0 0.00 2 13870 92 0 0
sp_fill_shopping_cart_clo_res 482498 482498 0 0.00 0 16206 17 0 0
sp_get_clo_for_invoice 5144 5144 0 0.00 0 35548 308 0 0
sp_kill_cost_storno 15347 15347 0 0.00 0 30891 89 0 0
sp_kill_qstorno_ret_qs2qd 1058663 1058663 0 0.00 0 33200 44 0 0
sp_kill_qty_storno 559393 559393 0 0.00 0 33276 104 0 0
sp_lock_selected_doc 21189 21189 0 0.00 0 11505 11 0 0
sp_make_cost_storno 28457 28457 0 0.00 0 10893 91 0 0
sp_multiply_rows_for_pdistr 28454 28454 0 0.00 0 8577 44 0 0
sp_multiply_rows_for_qdistr 20548 20548 0 0.00 0 11833 151 0 0
sp_pay_from_customer 7330 7330 0 0.00 5 14199 194 0 0
sp_pay_to_supplier 5895 5895 0 0.00 6 12704 212 0 0
sp_payment_common 13224 13224 0 0.00 2 11398 56 0 0
sp_qd_handle_on_cancel_clo 1861 1861 0 0.00 0 20566 393 0 0
sp_qd_handle_on_invoice_upd_sts 10473 10473 0 0.00 0 20752 620 0 0
sp_qd_handle_on_reserve_upd_sts 10087 10087 0 0.00 0 14538 66 0 0
sp_reserve_write_off 10088 10088 0 0.00 9 15288 319 0 0
sp_supplier_invoice 5672 5672 0 0.00 12 98067 3339 0 0
sp_supplier_order 5488 5488 0 0.00 8 53095 1409 0 0
srv_aggregate_perf_data 124 123 1 0.80 2429 184339 16936 0 1
srv_fill_mon_cache_memory 74 74 0 0.00 747 127738 31488 0 0
srv_find_qd_qs_mism 1037998 1037998 0 0.00 0 20106 11 0 0
srv_make_invnt_saldo 482 482 0 0.00 56 75606 4778 0 0
srv_make_money_saldo 1017 1017 0 0.00 1 18321 388 0 0
srv_recalc_idx_stat 5 5 0 0.00 148959 470699 345922 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 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 5201 5201 0 0.00 1 28181 1096 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 1 6239 547 0 0
t$perf-norm:sp_cancel_client_order 1861 1861 0 0.00 0 6486 39 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 3555 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 725 725 0 0.00 0 768 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 925 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 1068 39 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 118174 7792 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 1914 22 0 0
t$perf-norm:sp_client_order 20548 20548 0 0.00 0 29700 42 0 0
t$perf-norm:sp_customer_reserve 1036 1036 0 0.00 0 1103 17 0 0
t$perf-norm:sp_pay_from_customer 7330 7330 0 0.00 0 8101 20 0 0
t$perf-norm:sp_pay_to_supplier 5895 5895 0 0.00 0 2775 20 0 0
t$perf-norm:sp_reserve_write_off 10088 10088 0 0.00 0 14872 26 0 0
t$perf-norm:sp_supplier_invoice 5673 5673 0 0.00 0 8022 38 0 0
t$perf-norm:sp_supplier_order 5488 5488 0 0.00 0 2787 36 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 526 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 5230 75 0 0
t$perf-norm:srv_make_invnt_saldo 482 482 0 0.00 0 3710 24 0 0
t$perf-norm:srv_make_money_saldo 1017 1017 0 0.00 0 2035 11 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 6 361 117 0 0
v_add_invoice_to_stock 10544 10274 270 2.56 0 17970 31 0 270
v_all_customers 37736 37736 0 0.00 0 125 4 0 0
v_all_suppliers 30948 30948 0 0.00 0 76 5 0 0
v_all_wares 41088 41088 0 0.00 0 78 5 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 1067 18 0 0
v_cancel_client_order 3722 3722 0 0.00 0 1533 11 0 0
v_cancel_customer_prepayment 1450 1450 0 0.00 0 819 11 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 84 11 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 261 12 0 0
v_cancel_supplier_invoice 862 848 14 1.62 0 1244 29 0 14
v_cancel_supplier_order 848 848 0 0.00 0 901 13 0 0
v_cancel_write_off 2006 2006 0 0.00 0 2140 27 0 0
v_max_id_clo_ord 5488 5488 0 0.00 0 516 6 0 0
v_max_id_clo_res 1036 1036 0 0.00 0 3207 18 0 0
v_max_id_ord_sup 5664 5664 0 0.00 0 1162 6 0 0
v_max_non_paid_invoice 1573 1573 0 0.00 0 410 9 0 0
v_max_non_paid_realizn 3877 3877 0 0.00 0 2629 10 0 0
v_min_id_clo_ord 5488 5488 0 0.00 0 4040 12 0 0
v_min_id_clo_res 1036 1036 0 0.00 0 7367 25 0 0
v_min_id_ord_sup 5664 5664 0 0.00 0 9078 18 0 0
v_min_non_paid_invoice 5895 5895 0 0.00 0 6497 19 0 0
v_min_non_paid_realizn 7329 7329 0 0.00 0 8321 22 0 0
v_our_firm 10256 10256 0 0.00 0 71 5 0 0
v_reserve_write_off 20174 20174 0 0.00 0 4512 13 0 0
x_lock_depdocs_on_canc_invoice 10883 10883 0 0.00 0 18227 130 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 6586 357 0 0
x_make_qstorno_1000_1200 5488 5488 0 0.00 2 50228 1213 0 0
x_make_qstorno_1200_2000 5672 5672 0 0.00 7 90321 3099 0 0
x_make_qstorno_2100_3300 482531 482531 0 0.00 0 112953 211 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 18-02-2023 07:39:12 to 18-02-2023 07:39:17.

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
05:07:29 910 8192000000 8928406464 8984756224 20989696 0.255 5 4 8 0 21738048 168560 579792
05:08:31 21005 8192000000 9720076896 10019049472 797577328 8.872 100 88 176 0 826625104 7792112 21255664
05:10:01 19573 8192000000 9766956496 10076508160 841792272 9.318 100 93 187 0 875590736 11747936 22050528
05:11:31 14963 8192000000 9804797024 10122313728 869367440 9.594 100 84 168 0 908184608 19223360 19593808
05:12:48 5251 8192000000 9848285376 10179039232 913808752 10.035 100 77 152 0 960789648 30679232 16301664
05:14:03 2561 8192000000 9913340240 10257866752 956456784 10.454 100 89 178 0 1032241568 57044832 18739952
05:15:11 3128 8192000000 9978591488 10329112576 985161728 10.734 100 88 171 1 1086446848 83874832 17410288
05:16:17 2436 8192000000 9988751520 10347274240 1011681024 10.992 100 93 184 0 1109365072 80005328 17678720
05:17:22 804 8192000000 9983090720 10348515328 1029660864 11.165 100 96 180 1 1101415152 54162272 17592016
05:18:25 820 8192000000 10016272976 10388099072 1048399904 11.345 100 94 172 1 1139090064 72444320 18245840
05:19:27 1159 8192000000 10023208608 10395181056 1061887792 11.475 100 94 177 1 1139100288 59727968 17484528
05:20:32 1554 8192000000 10047577664 10422796288 1070532192 11.557 100 95 186 0 1164242848 75611760 18098896
05:21:37 985 8192000000 10042913072 10424508416 1069646304 11.549 100 93 180 2 1166347696 77336304 19365088
05:22:40 1175 8192000000 10075550336 10463014912 1075963568 11.609 100 91 179 1 1194336480 100342416 18030496
05:23:44 4492 8192000000 10024645872 10409836544 1077737552 11.626 100 85 160 1 1147905616 54638336 15529728
05:24:50 1088 8192000000 10056720864 10443673600 1083491616 11.681 100 87 168 1 1178742528 77606992 17643920
05:25:52 4769 8192000000 10053515920 10440663040 1086211952 11.707 100 81 149 2 1180188336 80152432 13823952
05:26:59 1851 8192000000 10060022688 10447970304 1090534384 11.748 100 89 172 0 1177435840 69930672 16970784
05:28:02 1375 8192000000 10045647952 10433331200 1094464320 11.785 100 95 177 6 1173628464 61556288 17607856
05:29:05 2407 8192000000 10035373968 10418974720 1091592848 11.758 100 83 159 0 1150349792 42953440 15803504
05:30:09 7766 8192000000 10002650880 10386862080 1056272656 11.421 98 53 100 0 1118486192 52873776 9339760
05:31:18 1125 8192000000 10010536240 10383564800 1049893456 11.360 100 96 181 1 1134821072 67355184 17572432
05:32:20 940 8192000000 10032308096 10410885120 1069234256 11.545 100 95 173 0 1152390240 65465856 17690128
05:33:24 1334 8192000000 9993362608 10376015872 1045793744 11.320 99 66 122 1 1108098608 48803920 13500944
05:34:28 1047 8192000000 10003111024 10379931648 1058845088 11.445 100 92 176 1 1124859520 46676448 19337984
05:35:30 3373 8192000000 9993416800 10373038080 1043540096 11.299 100 69 137 1 1113117168 55410112 14166960
05:36:36 3369 8192000000 10004701504 10389262336 1041723264 11.281 100 71 137 2 1131689728 76323072 13643392
05:37:45 7593 8192000000 10000419024 10383228928 1036178624 11.228 99 77 142 2 1116750928 65724832 14847472
05:38:58 1252 8192000000 10030568448 10408808448 1053976240 11.399 100 96 182 2 1154775408 80938064 19861104
05:40:06 2478 8192000000 10049733552 10429095936 1069456576 11.547 100 68 128 1 1160737008 79466864 11813568
05:41:10 1235 8192000000 10084827504 10467532800 1092687552 11.768 100 96 182 1 1206153776 94324528 19141696
05:42:16 2758 8192000000 10098322096 10487902208 1097729088 11.816 100 71 138 0 1211976960 99966688 14281184
05:43:29 9523 8192000000 10136564256 10530742272 1115041376 11.980 100 76 143 1 1255898176 125779024 15077776
05:44:43 2454 8192000000 10167068448 10563420160 1111876944 11.950 99 74 146 1 1283494960 154984240 16633776
05:45:49 2781 8192000000 10192854912 10586615808 1127748144 12.100 100 81 160 0 1307147072 161226256 18172672
05:46:56 4685 8192000000 10226487744 10638667776 1129367264 12.115 100 72 144 1 1343808464 198513376 15927824
05:48:06 2387 8192000000 10240030048 10636939264 1152662400 12.334 100 86 165 0 1355001856 184973088 17366368
05:49:12 1064 8192000000 10255414032 10650337280 1163698704 12.438 100 91 169 1 1363904400 181424144 18781552
05:50:16 1087 8192000000 10326964736 10725498880 1169668992 12.494 100 94 180 1 1441876624 250865248 21342384
05:51:21 954 8192000000 10348523312 10749341696 1173875888 12.533 100 95 183 0 1462890336 268097120 20917328
05:52:24 984 8192000000 10335911584 10755313664 1173602112 12.530 100 93 182 2 1444889712 250638576 20649024
05:53:28 733 8192000000 10364974752 10784501760 1192967904 12.711 100 97 191 1 1475457760 259878000 22611856
05:54:30 1842 8192000000 10389239072 10793459712 1191837456 12.700 100 95 187 1 1502140576 287953920 22349200
05:55:37 1056 8192000000 10438573360 10849214464 1189294912 12.677 100 96 190 0 1545704560 333591440 22818208
05:56:43 1066 8192000000 10436935632 10851733504 1184986176 12.637 100 99 192 0 1542899584 335125104 22788304
05:57:50 1035 8192000000 10430127824 10867798016 1186602448 12.652 100 96 180 0 1531152192 323299872 21249872
05:58:53 1307 8192000000 10476950416 10892795904 1193464384 12.716 100 96 193 1 1580919488 366381568 21073536
05:59:57 874 8192000000 10500923104 10918154240 1197047376 12.749 100 98 194 0 1609610704 390502880 22060448
06:01:00 1003 8192000000 10502743072 10929463296 1190393728 12.687 100 96 187 0 1611398144 398605008 22399408
06:02:06 921 8192000000 10519076480 10954240000 1194491808 12.725 100 98 187 0 1624170944 406305440 23373696
06:03:10 1922 8192000000 10547095536 10953760768 1184061280 12.628 99 96 183 0 1651016224 444729056 22225888
06:04:15 4060 8192000000 10533202464 10989215744 1175585008 12.549 100 65 130 0 1636544912 444600464 16359440
06:05:26 7594 8192000000 10566372832 10996928512 1180490944 12.595 100 55 106 0 1669959088 475735232 13732912
06:06:38 3515 8192000000 10592564400 11039748096 1173184304 12.527 100 68 134 0 1696217248 506371168 16661776
06:07:49 2124 8192000000 10626339136 11045199872 1165825472 12.458 100 75 143 0 1730071312 546756144 17489696
06:08:52 5751 8192000000 10525310272 11076083712 1142594096 12.240 99 57 112 0 1629365712 471707088 15064528
06:10:04 4309 8192000000 10482168144 10962857984 1158747904 12.392 100 48 95 0 1593959920 423306928 11905088
06:11:14 26102 8192000000 10374249680 10882105344 1138062368 12.197 99 35 70 0 1480387504 332956784 9368352
06:13:14 17793 8192000000 10258278560 10753810432 1147983968 12.291 100 32 64 0 1373620704 217328768 8307968
06:14:34 26029 8192000000 10226154352 10694479872 1134519488 12.164 99 26 48 1 1350347760 209833040 5995232
06:16:07 5937 8192000000 10167686944 10602110976 1147351408 12.285 100 42 83 1 1277114000 120245952 9516640
06:17:15 18557 8192000000 10162213808 10607058944 1117924944 12.007 98 59 113 2 1264215744 132920672 13370128
06:18:44 26200 8192000000 10159523392 10609332224 1144703072 12.260 100 22 44 0 1248684752 99466144 4515536
06:20:11 90063 8192000000 10074262336 10555109376 1122368512 12.049 98 13 24 0 1179008928 54986992 1653424
06:22:41 58499 8192000000 10079115328 10511953920 1132892544 12.149 99 38 71 1 1160467152 19789328 7785280
06:24:42 50380 8192000000 10092270064 10545922048 1125367024 12.078 98 17 34 0 1181764928 53229536 3168368
06:27:37 120866 8192000000 10081781200 10604998656 1094289872 11.783 95 16 32 1 1164514576 66226288 3998416
06:30:41 104745 8192000000 10097231264 10623746048 1124866240 12.073 98 10 20 0 1235552816 108604048 2082528
06:33:49 54274 8192000000 10069821200 10515357696 1114567168 11.976 98 28 55 0 1143354368 23199408 5587792
06:35:46 67911 8192000000 10091418544 10518859776 1115626016 11.986 98 33 55 2 1155735632 34006176 6103440
06:37:57 60161 8192000000 10110830992 10567659520 1123544048 12.060 98 22 44 0 1200785392 72669712 4571632
06:39:58 43013 8192000000 10135730144 10569998336 1132144624 12.142 99 49 87 1 1207191008 65879408 9166976
06:41:42 25157 8192000000 10123713616 10572607488 1149805680 12.308 100 35 68 1 1226157168 67984560 8366928
06:43:11 19167 8192000000 10134634480 10572410880 1129848560 12.120 99 61 122 0 1225711440 82715104 13147776
06:44:42 90302 8192000000 10077855872 10513436672 1120284656 12.030 98 19 38 0 1146501248 21881856 4334736
06:47:41 18230 8192000000 10067141408 10511540224 1126561600 12.089 99 20 35 0 1170336112 40196544 3577968
06:49:05 57475 8192000000 10091448080 10572541952 1136749904 12.185 99 11 22 0 1205192944 66494512 1948528
06:51:10 17874 8192000000 10102126848 10558058496 1136774160 12.185 99 47 86 1 1194166384 49069504 8322720
06:52:31 46015 8192000000 10055317648 10532732928 1096196208 11.802 96 15 24 0 1131058032 32297136 2564688
06:54:18 20068 8192000000 10072161024 10505506816 1107673408 11.910 98 48 92 2 1167196416 49426560 10096448
06:55:43 22659 8192000000 10085354032 10522218496 1106300432 11.897 98 35 68 0 1171814496 58637936 6876128
06:57:08 9184 8192000000 10096922672 10530144256 1121967472 12.046 99 51 96 2 1187034720 55036496 10030752
06:58:22 65051 8192000000 10093605280 10546864128 1112715472 11.958 98 20 40 0 1172950032 56010032 4224528
07:00:34 35314 8192000000 10157856608 10612342784 1102898208 11.865 97 31 63 0 1204108400 94446272 6763920
07:02:16 67695 8192000000 10141602096 10582908928 1120267216 12.030 99 19 38 0 1266127136 141201424 4658496
07:04:38 41435 8192000000 10110492256 10559488000 1126973104 12.093 99 23 46 0 1194276224 62306944 4996176
07:06:32 60923 8192000000 10118757568 10580807680 1149872240 12.308 100 21 42 0 1225635680 71801488 3961952
07:08:37 108993 8192000000 10066710192 10539372544 1122295472 12.049 98 17 34 3 1159269024 33509600 3463952
07:11:33 50467 8192000000 10068246944 10553630720 1114874416 11.979 98 18 34 0 1161197424 42549120 3773888
07:13:48 55864 8192000000 10116323952 10575544320 1150130576 12.311 100 19 35 1 1214040576 59771808 4138192
07:15:46 54332 8192000000 10128360528 10596163584 1124181824 12.066 98 26 52 0 1231461728 101342016 5937888
07:17:41 30450 8192000000 10112855504 10580525056 1140511152 12.220 100 11 22 0 1220630864 77683888 2435824
07:19:12 93107 8192000000 10074314288 10561495040 1139599344 12.212 100 15 30 2 1173448800 31015248 2834208
07:21:46 48022 8192000000 10099933920 10563166208 1117884128 12.007 97 34 68 0 1158859920 33870064 7105728
07:23:51 61923 8192000000 10085281120 10559983616 1133698256 12.156 98 20 38 0 1202632048 65303904 3629888
07:25:55 78075 8192000000 10117306656 10594844672 1089514816 11.738 95 31 62 0 1165763184 69896928 6351440
07:28:13 28100 8192000000 10090165584 10533609472 1129419552 12.116 100 35 70 0 1192442592 56155424 6867616
07:29:45 47777 8192000000 10093973104 10561437696 1140781312 12.223 100 22 43 0 1197362144 52589360 3991472
07:31:35 27850 8192000000 10133225344 10587504640 1152017696 12.328 100 48 92 1 1233272656 71147184 10107776
07:33:06 69712 8192000000 10101458736 10558418944 1140008256 12.216 99 22 42 1 1233727440 89333056 4386128
07:35:22 127738 8192000000 9589975120 9995571200 699922976 7.871 61 12 24 0 741448064 38901584 2623504

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 18-02-2023 07:39:19 to 18-02-2023 07:39:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 135 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-02-2023 07:39:36 to 18-02-2023 07:39:38.

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          224305
MON$OLDEST_ACTIVE               224306
MON$OLDEST_SNAPSHOT             224306
MON$NEXT_TRANSACTION            224306
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                       2110848
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2902 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2902 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2902 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2902 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 ---
:174: 
:175: mscs-test-161.budzdorov.ru    Sat Feb 18 07:37:43 2023
:176:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:177: 
--- end of diff output ---

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

18.02.23 07:39:40. 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
2023-02-18 07:39:42 No severe PSQL-related problems occured
18.02.23 07:39:44. End of report.