Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.02.2023 13: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 15351904 kB
MemAvailable 30453232 kB
Buffers 0 kB
Cached 16377972 kB
SwapCached 3140 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-20 13:37:27
DTS_END                         2023-02-20 13:37:27.2270
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/60222
TRN_ID                          223301
ATT_ID                          612
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: 8966.02 21716 1075922
customer order: creation 172.13 478 20656
customer order: refuse 13.93 391 1671
order to supplier: creation 46.27 1358 5552
order to supplier: removal 3.42 161025 410
invoice (draft): creation 45.87 3248 5504
invoice (draft): removal 50.17 952 6020
invoice accept: apply 41.32 24737 4958
invoice accept: cancel 45.30 1056 5436
customer reserve: creation 3843.10 236 461172
customer reserve: removal 4410.68 98 529282
realization accept: apply 82.75 265 9930
realization accept: cancel 80.52 170 9662
payment from customer: creation 59.48 175 7138
payment from customer: removal 5.45 116 654
payment to supplier: creation 48.64 193 5837
payment to supplier: removal 3.77 117 452
service: total inventory turnovers 4.53 3419 544
service: total monetary turnovers 7.73 328 927
service: aggregate perf. data 0.94 14808 113
service: refresh index statistics 0.03 221145 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-02-2023 13:38:56 to 20-02-2023 13:38:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4765
TEST_TIME 2 4660
TEST_TIME 3 5027
TEST_TIME 4 5021
TEST_TIME 5 5599
TEST_TIME 6 5630
TEST_TIME 7 5698
TEST_TIME 8 6069
TEST_TIME 9 6115
TEST_TIME 10 6232
TEST_TIME 11 6260
TEST_TIME 12 6399
TEST_TIME 13 6550
TEST_TIME 14 6668
TEST_TIME 15 6768
TEST_TIME 16 6740
TEST_TIME 17 6847
TEST_TIME 18 6971
TEST_TIME 19 7225
TEST_TIME 20 8085
TEST_TIME 21 7896
TEST_TIME 22 7764
TEST_TIME 23 7652
TEST_TIME 24 8026
TEST_TIME 25 8776
TEST_TIME 26 8780
TEST_TIME 27 9363
TEST_TIME 28 9853
TEST_TIME 29 10285
TEST_TIME 30 10602
TEST_TIME 31 10939
TEST_TIME 32 10733
TEST_TIME 33 10543
TEST_TIME 34 10447
TEST_TIME 35 10432
TEST_TIME 36 10632
TEST_TIME 37 10423
TEST_TIME 38 10471
TEST_TIME 39 10301
TEST_TIME 40 10166
TEST_TIME 41 10089
TEST_TIME 42 9938
TEST_TIME 43 9837
TEST_TIME 44 9841
TEST_TIME 45 9732
TEST_TIME 46 9680
TEST_TIME 47 9584
TEST_TIME 48 9518
TEST_TIME 49 9461
TEST_TIME 50 9466
TEST_TIME 51 9410
TEST_TIME 52 9392
TEST_TIME 53 9358
TEST_TIME 54 9486
TEST_TIME 55 9635
TEST_TIME 56 9554
TEST_TIME 57 9477
TEST_TIME 58 9447
TEST_TIME 59 9564
TEST_TIME 60 9708
TEST_TIME 61 9625
TEST_TIME 62 9654
TEST_TIME 63 9668
TEST_TIME 64 9695
TEST_TIME 65 9675
TEST_TIME 66 9608
TEST_TIME 67 9533
TEST_TIME 68 9482
TEST_TIME 69 9425
TEST_TIME 70 9402
TEST_TIME 71 9331
TEST_TIME 72 9271
TEST_TIME 73 9182
TEST_TIME 74 9170
TEST_TIME 75 9186
TEST_TIME 76 9239
TEST_TIME 77 9208
TEST_TIME 78 9154
TEST_TIME 79 9144
TEST_TIME 80 9126
TEST_TIME 81 9128
TEST_TIME 82 9161
TEST_TIME 83 9241
TEST_TIME 84 9293
TEST_TIME 85 9372
TEST_TIME 86 9376
TEST_TIME 87 9280
TEST_TIME 88 9353
TEST_TIME 89 9319
TEST_TIME 90 9259
TEST_TIME 91 9210
TEST_TIME 92 9199
TEST_TIME 93 9214
TEST_TIME 94 9260
TEST_TIME 95 9252
TEST_TIME 96 9245
TEST_TIME 97 9206
TEST_TIME 98 9180
TEST_TIME 99 9254
TEST_TIME 100 9251
TEST_TIME 101 9250
TEST_TIME 102 9328
TEST_TIME 103 9316
TEST_TIME 104 9302
TEST_TIME 105 9305
TEST_TIME 106 9224
TEST_TIME 107 9230
TEST_TIME 108 9175
TEST_TIME 109 9167
TEST_TIME 110 9153
TEST_TIME 111 9111
TEST_TIME 112 9070
TEST_TIME 113 9069
TEST_TIME 114 9013
TEST_TIME 115 9026
TEST_TIME 116 9007
TEST_TIME 117 9009
TEST_TIME 118 9029
TEST_TIME 119 9047
TEST_TIME 120 8995

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-02-2023 13:38:59 to 20-02-2023 13:39:03.

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 1500739 1500739 0 0.00 0 26826 12 0 0
doc_list_biud 1500717 1500717 0 0.00 0 21421 40 0 0
sp_add_invoice_to_stock 5058 4958 100 1.97 53 202078 24738 0 100
sp_cancel_adding_invoice 5436 5436 0 0.00 6 76013 1056 0 0
sp_cancel_client_order 1671 1671 0 0.00 6 14431 392 0 0
sp_cancel_customer_reserve 529282 529282 0 0.00 1 19245 98 0 0
sp_cancel_pay_from_customer 654 654 0 0.00 3 5272 117 0 0
sp_cancel_pay_to_supplier 452 452 0 0.00 4 3866 118 0 0
sp_cancel_supplier_invoice 6029 6020 9 0.14 3 19933 953 0 9
sp_cancel_supplier_order 410 410 0 0.00 20 1424694 161025 0 0
sp_cancel_write_off 9662 9662 0 0.00 1 14522 170 0 0
sp_client_order 20656 20656 0 0.00 5 16828 478 0 0
sp_customer_reserve 461172 461172 0 0.00 1 82261 237 0 0
sp_fill_shopping_cart 31701 31701 0 0.00 1 18573 81 0 0
sp_fill_shopping_cart_clo_res 461140 461140 0 0.00 0 18268 19 0 0
sp_get_clo_for_invoice 4920 4920 0 0.00 0 22009 295 0 0
sp_kill_cost_storno 16204 16204 0 0.00 0 26809 72 0 0
sp_kill_qstorno_ret_qs2qd 1074656 1074656 0 0.00 0 19785 43 0 0
sp_kill_qty_storno 567323 567323 0 0.00 0 21417 98 0 0
sp_lock_selected_doc 20532 20532 0 0.00 0 7119 8 0 0
sp_make_cost_storno 27822 27822 0 0.00 0 16870 78 0 0
sp_multiply_rows_for_pdistr 27819 27819 0 0.00 0 9582 37 0 0
sp_multiply_rows_for_qdistr 20656 20656 0 0.00 0 16770 186 0 0
sp_pay_from_customer 7138 7138 0 0.00 5 14513 176 0 0
sp_pay_to_supplier 5837 5837 0 0.00 6 18417 194 0 0
sp_payment_common 12975 12975 0 0.00 2 8716 43 0 0
sp_qd_handle_on_cancel_clo 1671 1671 0 0.00 0 9985 301 0 0
sp_qd_handle_on_invoice_upd_sts 10352 10352 0 0.00 0 19757 471 0 0
sp_qd_handle_on_reserve_upd_sts 9926 9926 0 0.00 0 21413 61 0 0
sp_reserve_write_off 9930 9930 0 0.00 8 21497 265 0 0
sp_supplier_invoice 5504 5504 0 0.00 10 112233 3249 0 0
sp_supplier_order 5552 5552 0 0.00 8 37733 1359 0 0
srv_aggregate_perf_data 113 113 0 0.00 2877 157951 14809 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 954 137193 37309 0 0
srv_find_qd_qs_mism 1026998 1026998 0 0.00 0 12665 9 0 0
srv_make_invnt_saldo 544 544 0 0.00 53 78374 3419 0 0
srv_make_money_saldo 927 927 0 0.00 0 15040 328 0 0
srv_recalc_idx_stat 4 4 0 0.00 98074 393781 221146 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4964 4964 0 0.00 1 15725 791 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 1 6538 356 0 0
t$perf-norm:sp_cancel_client_order 1671 1671 0 0.00 0 2307 22 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 2322 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 654 654 0 0.00 0 347 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 452 452 0 0.00 0 1398 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 1093 35 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 114709 5664 0 0
t$perf-norm:sp_cancel_write_off 1025 1025 0 0.00 0 1730 16 0 0
t$perf-norm:sp_client_order 20656 20656 0 0.00 0 15663 32 0 0
t$perf-norm:sp_customer_reserve 1026 1026 0 0.00 0 3238 16 0 0
t$perf-norm:sp_pay_from_customer 7138 7138 0 0.00 0 3990 17 0 0
t$perf-norm:sp_pay_to_supplier 5837 5837 0 0.00 0 6681 18 0 0
t$perf-norm:sp_reserve_write_off 9931 9931 0 0.00 0 2686 19 0 0
t$perf-norm:sp_supplier_invoice 5504 5504 0 0.00 0 4336 29 0 0
t$perf-norm:sp_supplier_order 5552 5552 0 0.00 0 2952 27 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 301 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 81 4 0 0
t$perf-norm:srv_make_invnt_saldo 544 544 0 0.00 0 1581 11 0 0
t$perf-norm:srv_make_money_saldo 927 927 0 0.00 0 3095 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 131 59 0 0
v_add_invoice_to_stock 10028 9828 200 1.99 0 13815 27 0 200
v_all_customers 38038 38038 0 0.00 0 79 3 0 0
v_all_suppliers 30764 30764 0 0.00 0 85 4 0 0
v_all_wares 41306 41306 0 0.00 0 189 3 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 3292 16 0 0
v_cancel_client_order 3342 3342 0 0.00 0 13637 21 0 0
v_cancel_customer_prepayment 1308 1308 0 0.00 0 2918 13 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 1 1492 11 0 0
v_cancel_payment_to_supplier 904 904 0 0.00 0 2880 19 0 0
v_cancel_supplier_invoice 826 808 18 2.17 0 2917 29 0 18
v_cancel_supplier_order 820 820 0 0.00 0 6572 19 0 0
v_cancel_write_off 2050 2050 0 0.00 0 1499 23 0 0
v_max_id_clo_ord 5548 5548 0 0.00 0 675 5 0 0
v_max_id_clo_res 1026 1026 0 0.00 0 7764 32 0 0
v_max_id_ord_sup 5500 5500 0 0.00 0 4443 8 0 0
v_max_non_paid_invoice 1503 1503 0 0.00 0 8619 13 0 0
v_max_non_paid_realizn 3696 3696 0 0.00 0 4150 8 0 0
v_min_id_clo_ord 5548 5548 0 0.00 0 3135 8 0 0
v_min_id_clo_res 1026 1026 0 0.00 0 2680 14 0 0
v_min_id_ord_sup 5500 5500 0 0.00 0 8968 11 0 0
v_min_non_paid_invoice 5837 5837 0 0.00 0 6039 13 0 0
v_min_non_paid_realizn 7138 7138 0 0.00 0 5163 17 0 0
v_our_firm 10152 10152 0 0.00 0 57 4 0 0
v_reserve_write_off 19852 19852 0 0.00 0 8843 11 0 0
x_lock_depdocs_on_canc_invoice 11052 11052 0 0.00 0 21484 123 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 8359 346 0 0
x_make_qstorno_1000_1200 5552 5552 0 0.00 3 37721 1203 0 0
x_make_qstorno_1200_2000 5504 5504 0 0.00 4 112104 3016 0 0
x_make_qstorno_2100_3300 461171 461171 0 0.00 0 82258 208 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-02-2023 13:39:04 to 20-02-2023 13:39:08.

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
11:07:31 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:32 25367 8192000000 9719833632 10019086336 801482032 8.911 100 87 175 0 829993328 7334016 21177280
11:10:03 13903 8192000000 9758866816 10063896576 830472384 9.204 100 87 174 0 864067232 12717728 20877120
11:11:20 15751 8192000000 9779347072 10096406528 850931888 9.409 100 76 152 0 885268768 16236560 18100320
11:12:41 9599 8192000000 9851774480 10184388608 903277440 9.931 100 82 162 0 961831344 41082640 17471264
11:13:57 5011 8192000000 9899625184 10242850816 948595040 10.377 100 85 168 1 1015539664 49763024 17181600
11:15:06 2885 8192000000 9971495264 10322280448 992853888 10.809 100 92 178 0 1086350800 75466112 18030800
11:16:11 1498 8192000000 10011112704 10369990656 1014869392 11.022 100 97 187 2 1120328752 87242336 18217024
11:17:14 1423 8192000000 10003812976 10371735552 1037158960 11.237 100 89 176 1 1112224768 58080576 16985232
11:18:17 1039 8192000000 10020466640 10395488256 1059583840 11.452 100 97 185 1 1146286768 67800816 18902112
11:19:19 1101 8192000000 10039520720 10416742400 1068159200 11.534 100 94 179 0 1160190080 74803200 17227680
11:20:22 5860 8192000000 10026577152 10402426880 1069997344 11.552 100 90 179 2 1149628800 61662864 17968592
11:21:28 4952 8192000000 10027017280 10409398272 1077164240 11.620 100 82 150 1 1146664496 53606352 15893904
11:22:34 1793 8192000000 10042913040 10436636672 1082394752 11.670 100 75 139 3 1167733312 70950304 14388256
11:23:36 919 8192000000 10049460672 10437808128 1087574464 11.720 100 89 167 2 1171089856 65936272 17579120
11:24:39 1070 8192000000 10047410352 10436886528 1090621616 11.749 100 92 182 1 1170199440 60693360 18884464
11:25:41 1339 8192000000 10063541296 10452180992 1097041088 11.810 100 97 187 0 1187152048 70340544 19770416
11:26:43 1091 8192000000 10053895520 10442481664 1097071040 11.810 100 98 182 0 1175734768 60262976 18400752
11:27:45 969 8192000000 10082842336 10475048960 1099095424 11.829 100 93 175 0 1206575280 87681936 19797920
11:28:47 1172 8192000000 10037189040 10424164352 1080228560 11.650 100 89 163 2 1159873264 62993200 16651504
11:29:49 1046 8192000000 10041953040 10428231680 1075399856 11.604 100 97 181 1 1160811008 66644160 18766992
11:30:52 4490 8192000000 10042077712 10430054400 1082315136 11.670 100 79 154 3 1163986176 66482640 15188400
11:31:57 853 8192000000 9999001664 10375258112 1044881664 11.312 99 86 160 1 1117129696 55232064 17015968
11:33:00 945 8192000000 9985985072 10366402560 1037695776 11.243 99 86 154 2 1110356416 56000032 16660608
11:34:01 1717 8192000000 9973311856 10361180160 1036806848 11.234 100 68 124 3 1101703792 50925536 13971408
11:35:04 1037 8192000000 9986952768 10360926208 1032717056 11.195 100 82 151 5 1108445104 59876912 15851136
11:36:07 1088 8192000000 10010908576 10384019456 1033473584 11.202 100 91 160 0 1127626576 78214688 15938304
11:37:09 2042 8192000000 10001292032 10382544896 1046052112 11.323 100 71 134 3 1125394432 66084304 13258016
11:38:17 11891 8192000000 10021166784 10404564992 1069422912 11.547 100 70 135 1 1143248208 60595616 13229680
11:39:33 8758 8192000000 10056013392 10444197888 1081489680 11.662 100 83 162 0 1177502400 77164656 18848064
11:40:44 1892 8192000000 10088757504 10477240320 1093810240 11.779 100 82 157 3 1210465264 99731680 16923344
11:41:47 1222 8192000000 10145723952 10526908416 1111701696 11.949 100 89 168 4 1261821744 131878560 18241488
11:42:52 15095 8192000000 10145940640 10550075392 1124481456 12.069 100 57 108 1 1266083360 130009744 11592160
11:44:18 1048 8192000000 10181846928 10575347712 1143018656 12.244 100 98 195 0 1301803488 137109744 21675088
11:45:27 965 8192000000 10250135888 10651549696 1154478336 12.352 100 97 189 0 1361076976 185641824 20956816
11:46:31 1816 8192000000 10274165184 10675273728 1167807856 12.476 100 90 171 2 1390084832 203082992 19193984
11:47:38 4784 8192000000 10297484048 10700816384 1173398544 12.529 100 79 152 0 1409593104 219103824 17090736
11:48:47 4659 8192000000 10353679184 10761859072 1185558848 12.642 100 76 150 0 1467902256 264994704 17348704
11:49:56 1246 8192000000 10388190528 10800848896 1193315104 12.714 100 98 190 1 1496814384 281189632 22309648
11:51:02 1059 8192000000 10391703040 10809688064 1196984208 12.748 100 93 187 3 1503605584 284644368 21977008
11:52:06 2433 8192000000 10446090560 10852339712 1202631568 12.801 100 95 184 0 1553035488 329418400 20985520
11:53:12 1261 8192000000 10476648688 10890911744 1204829200 12.821 100 91 178 3 1589796992 362716784 22251008
11:54:15 942 8192000000 10518057392 10933465088 1193472240 12.716 100 100 193 0 1631689968 415698368 22519360
11:55:17 2824 8192000000 10543023152 10981515264 1203309632 12.807 100 86 170 0 1671626656 447754480 20562544
11:56:24 1146 8192000000 10552240928 11016953856 1209147648 12.861 100 94 182 0 1657122048 425756320 22218080
11:57:27 3648 8192000000 10494253488 10959515648 1190013024 12.683 100 56 112 0 1621555088 416948000 14594064
11:58:37 12235 8192000000 10521783952 10960572416 1197638928 12.754 100 37 72 0 1636040320 428810368 9591024
11:59:57 3551 8192000000 10602156384 11030536192 1195646112 12.736 100 65 125 0 1703335392 491900880 15788400
12:01:17 2991 8192000000 10598145664 11073282048 1184317312 12.630 100 68 132 1 1701828272 499407968 18102992
12:02:21 12888 8192000000 10522431184 11003367424 1172362720 12.519 99 50 96 0 1639572720 455054848 12155152
12:03:35 18319 8192000000 10494785792 10976546816 1158158224 12.386 99 29 58 0 1585261520 419195088 7908208
12:04:55 24719 8192000000 10350459040 10865176576 1164278608 12.443 100 26 52 0 1464185568 293313840 6593120
12:06:25 38769 8192000000 10203307536 10697654272 1138579104 12.202 99 16 32 0 1363866976 221696576 3591296
12:08:05 15683 8192000000 10107342976 10602102784 1133347680 12.153 98 31 54 2 1194433648 55217536 5868432
12:09:21 35091 8192000000 10154142416 10579353600 1131805632 12.138 98 65 126 0 1243788736 98511376 13471728
12:11:08 31551 8192000000 10151062784 10590265344 1134203200 12.161 99 19 36 0 1243726992 105410016 4113776
12:12:39 53545 8192000000 10180244080 10651111424 1142828624 12.242 100 15 30 0 1292957568 146698320 3430624
12:15:04 108480 8192000000 10074595440 10517311488 1097578832 11.815 96 21 43 1 1156651632 54726528 4346272
12:17:53 57283 8192000000 10093509344 10576637952 1128453712 12.107 99 20 41 0 1196157792 63233680 4470400
12:19:50 65335 8192000000 10067014704 10494787584 1112914256 11.960 98 58 99 2 1156008096 32281184 10812656
12:22:09 45721 8192000000 10103654192 10549329920 1126440096 12.088 99 33 64 0 1189360384 56366768 6553520
12:23:57 33157 8192000000 10117785840 10549182464 1144900304 12.262 100 46 84 0 1227691312 74121968 8669040
12:25:30 37375 8192000000 10164673728 10618200064 1157978304 12.384 100 22 42 1 1278259120 115519312 4761504
12:27:14 69036 8192000000 10162198640 10624507904 1151207472 12.321 100 23 46 0 1279308208 122430352 5670384
12:29:32 55874 8192000000 10199634032 10711531520 1140905296 12.224 99 11 22 0 1295331408 151737920 2688192
12:31:28 65072 8192000000 10078256064 10592911360 1094507264 11.785 96 17 34 0 1180351792 82135808 3708720
12:33:40 34465 8192000000 10139538880 10571599872 1123170800 12.057 98 54 104 0 1219065888 84305152 11589936
12:35:16 72741 8192000000 10142513232 10633588736 1120167696 12.029 99 23 47 0 1254797616 129240096 5389824
12:37:32 29895 8192000000 10069264528 10521141248 1124651456 12.071 99 22 44 0 1166385808 37291136 4443216
12:39:05 67145 8192000000 10124833696 10617208832 1125008928 12.074 98 30 60 1 1225031008 93769008 6253072
12:41:16 85821 8192000000 10107193056 10591563776 1146272256 12.274 100 26 52 0 1257930592 105960064 5698272
12:43:55 32313 8192000000 10043320448 10490056704 1111318032 11.945 99 22 43 0 1150484784 34911552 4255200
12:45:28 26140 8192000000 10063549584 10507395072 1131734992 12.138 100 33 64 0 1168194032 30436352 6022688
12:47:00 34835 8192000000 10070024512 10520035328 1109028224 11.923 98 23 47 0 1153375504 39700160 4647120
12:48:37 57521 8192000000 10077581280 10529566720 1105729344 11.892 98 13 26 0 1156274048 47948384 2596320
12:51:11 50207 8192000000 10099138368 10555613184 1123994128 12.065 99 31 58 0 1219518208 88698080 6826000
12:53:01 73910 8192000000 10074715520 10511192064 1101157152 11.849 97 19 39 0 1156535600 51419328 3959120
12:55:17 38508 8192000000 10147898048 10585604096 1129601264 12.118 98 36 72 0 1217258000 79724496 7932240
12:56:58 24604 8192000000 10218347504 10663723008 1141912880 12.234 99 41 80 0 1304147280 153051696 9182704
12:58:25 19942 8192000000 10225664336 10668797952 1142357200 12.238 99 41 81 0 1313054752 161605360 9092192
12:59:45 44378 8192000000 10167248496 10644647936 1123972752 12.065 98 29 58 0 1276673776 145389952 7311072
13:01:32 60361 8192000000 10116582304 10579374080 1096378016 11.803 97 23 44 1 1166677360 65292736 5006608
13:03:33 55589 8192000000 10115060064 10543431680 1105193168 11.887 98 42 84 0 1186911104 73267600 8450336
13:05:45 56405 8192000000 10039198464 10497134592 1091146048 11.754 97 15 23 0 1139567120 45620432 2800640
13:07:42 48866 8192000000 10073275760 10524561408 1117549520 12.004 98 32 64 1 1156931232 32910832 6470880
13:09:36 24108 8192000000 10119735664 10561081344 1134604576 12.165 100 41 80 0 1199489728 57465936 7419216
13:11:03 137193 8192000000 10073901408 10517106688 1123758800 12.062 98 13 26 0 1177265664 51196640 2310224
13:14:26 44266 8192000000 10120136928 10539921408 1099405552 11.832 97 45 88 0 1167792336 59592016 8794768
13:16:17 26911 8192000000 10077023392 10511814656 1134891248 12.167 100 25 50 0 1197382224 56911504 5579472
13:17:48 70312 8192000000 10059178032 10513981440 1083839344 11.684 96 24 45 1 1139395408 50530256 5025808
13:20:05 83490 8192000000 10061914624 10512187392 1047829808 11.340 94 16 32 0 1079082944 28198704 3054432
13:22:35 125013 8192000000 10034262272 10503712768 1078876640 11.637 97 23 46 1 1108495824 24741888 4877296
13:25:46 45645 8192000000 10040639280 10499158016 1106381920 11.898 99 26 52 0 1167587760 55405696 5800144
13:27:34 65199 8192000000 10068395232 10523648000 1086179040 11.706 97 9 18 0 1125444928 37118496 2147392
13:29:40 61808 8192000000 10085811680 10549776384 1111084304 11.943 99 11 20 1 1180319120 67029440 2205376
13:31:43 18378 8192000000 10083038224 10523234304 1123838336 12.063 100 25 41 0 1194578832 65919312 4821184
13:33:02 79148 8192000000 10104057728 10573824000 1135422416 12.172 100 16 32 0 1216309568 77102064 3785088
13:35:34 62713 8192000000 10121854304 10606669824 1122506560 12.051 98 23 46 0 1244227248 116355328 5365360

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 20-02-2023 13:39:10 to 20-02-2023 13:39:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:03, from 20-02-2023 13:39:27 to 20-02-2023 13:39:30.

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          217392
MON$OLDEST_ACTIVE               223463
MON$OLDEST_SNAPSHOT             223463
MON$NEXT_TRANSACTION            223463
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                       2030688
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             775
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2903 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2903 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2903 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2903 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 ---
:68: 
:69: mscs-test-161.budzdorov.ru    Mon Feb 20 13:37:31 2023
:70:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:71: 
--- end of diff output ---

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

20.02.23 13:39:31. 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-20 13:39:33 No severe PSQL-related problems occured
20.02.23 13:39:35. End of report.