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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15067008 kB
MemAvailable 30395164 kB
Buffers 0 kB
Cached 16605284 kB
SwapCached 3240 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-03-15 10:37:26
DTS_END                         2023-03-15 10:37:26.5580
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/44528
TRN_ID                          215638
ATT_ID                          587
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: 9134.92 20576 1096190
customer order: creation 165.44 471 19853
customer order: refuse 14.11 400 1693
order to supplier: creation 44.24 1325 5309
order to supplier: removal 3.40 175845 408
invoice (draft): creation 42.89 3058 5147
invoice (draft): removal 51.56 1056 6187
invoice accept: apply 40.21 24711 4825
invoice accept: cancel 46.49 1039 5579
customer reserve: creation 3796.04 230 455525
customer reserve: removal 4650.39 101 558047
realization accept: apply 78.59 279 9431
realization accept: cancel 77.68 161 9321
payment from customer: creation 56.80 173 6816
payment from customer: removal 5.34 102 641
payment to supplier: creation 46.66 195 5599
payment to supplier: removal 3.22 160 386
service: total inventory turnovers 4.17 3419 500
service: total monetary turnovers 6.80 401 816
service: aggregate perf. data 0.85 13942 102
service: refresh index statistics 0.04 184456 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-03-2023 10:38:42 to 15-03-2023 10:38:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4446
TEST_TIME 2 4233
TEST_TIME 3 4877
TEST_TIME 4 5545
TEST_TIME 5 5119
TEST_TIME 6 5043
TEST_TIME 7 5275
TEST_TIME 8 5518
TEST_TIME 9 5899
TEST_TIME 10 6415
TEST_TIME 11 6744
TEST_TIME 12 6855
TEST_TIME 13 6843
TEST_TIME 14 6969
TEST_TIME 15 7159
TEST_TIME 16 7113
TEST_TIME 17 7243
TEST_TIME 18 7518
TEST_TIME 19 7434
TEST_TIME 20 7453
TEST_TIME 21 7719
TEST_TIME 22 7650
TEST_TIME 23 7996
TEST_TIME 24 8166
TEST_TIME 25 8614
TEST_TIME 26 8524
TEST_TIME 27 9105
TEST_TIME 28 9905
TEST_TIME 29 10297
TEST_TIME 30 10529
TEST_TIME 31 11197
TEST_TIME 32 11232
TEST_TIME 33 11335
TEST_TIME 34 11221
TEST_TIME 35 11113
TEST_TIME 36 10894
TEST_TIME 37 10813
TEST_TIME 38 10649
TEST_TIME 39 10544
TEST_TIME 40 10506
TEST_TIME 41 10435
TEST_TIME 42 10483
TEST_TIME 43 10384
TEST_TIME 44 10314
TEST_TIME 45 10306
TEST_TIME 46 10281
TEST_TIME 47 10197
TEST_TIME 48 10158
TEST_TIME 49 10111
TEST_TIME 50 10059
TEST_TIME 51 9968
TEST_TIME 52 9977
TEST_TIME 53 9959
TEST_TIME 54 9944
TEST_TIME 55 9920
TEST_TIME 56 9916
TEST_TIME 57 9941
TEST_TIME 58 9972
TEST_TIME 59 10053
TEST_TIME 60 10114
TEST_TIME 61 10054
TEST_TIME 62 10056
TEST_TIME 63 10042
TEST_TIME 64 10096
TEST_TIME 65 9974
TEST_TIME 66 9902
TEST_TIME 67 9811
TEST_TIME 68 9809
TEST_TIME 69 9727
TEST_TIME 70 9645
TEST_TIME 71 9580
TEST_TIME 72 9537
TEST_TIME 73 9524
TEST_TIME 74 9522
TEST_TIME 75 9525
TEST_TIME 76 9480
TEST_TIME 77 9421
TEST_TIME 78 9380
TEST_TIME 79 9427
TEST_TIME 80 9471
TEST_TIME 81 9430
TEST_TIME 82 9532
TEST_TIME 83 9660
TEST_TIME 84 9716
TEST_TIME 85 9680
TEST_TIME 86 9581
TEST_TIME 87 9542
TEST_TIME 88 9540
TEST_TIME 89 9513
TEST_TIME 90 9463
TEST_TIME 91 9390
TEST_TIME 92 9370
TEST_TIME 93 9332
TEST_TIME 94 9351
TEST_TIME 95 9344
TEST_TIME 96 9320
TEST_TIME 97 9292
TEST_TIME 98 9280
TEST_TIME 99 9248
TEST_TIME 100 9365
TEST_TIME 101 9365
TEST_TIME 102 9416
TEST_TIME 103 9414
TEST_TIME 104 9444
TEST_TIME 105 9455
TEST_TIME 106 9510
TEST_TIME 107 9513
TEST_TIME 108 9471
TEST_TIME 109 9395
TEST_TIME 110 9421
TEST_TIME 111 9359
TEST_TIME 112 9352
TEST_TIME 113 9301
TEST_TIME 114 9261
TEST_TIME 115 9225
TEST_TIME 116 9200
TEST_TIME 117 9230
TEST_TIME 118 9214
TEST_TIME 119 9215
TEST_TIME 120 9158

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-03-2023 10:38:46 to 15-03-2023 10:38:49.

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 1516494 1516494 0 0.00 0 22245 12 0 0
doc_list_biud 1516489 1516489 0 0.00 0 39690 43 0 0
sp_add_invoice_to_stock 4948 4825 123 2.48 27 210668 24712 0 123
sp_cancel_adding_invoice 5579 5579 0 0.00 5 82051 1040 0 0
sp_cancel_client_order 1693 1693 0 0.00 5 11487 400 0 0
sp_cancel_customer_reserve 558047 558047 0 0.00 1 39694 101 0 0
sp_cancel_pay_from_customer 641 641 0 0.00 3 2688 102 0 0
sp_cancel_pay_to_supplier 386 386 0 0.00 4 7980 161 0 0
sp_cancel_supplier_invoice 6195 6187 8 0.12 3 28811 1056 0 8
sp_cancel_supplier_order 408 408 0 0.00 10 1425997 175846 0 0
sp_cancel_write_off 9321 9321 0 0.00 2 34243 162 0 0
sp_client_order 19853 19853 0 0.00 6 23448 472 0 0
sp_customer_reserve 455525 455525 0 0.00 1 67359 231 0 0
sp_fill_shopping_cart 30305 30305 0 0.00 1 19201 72 0 0
sp_fill_shopping_cart_clo_res 455492 455492 0 0.00 0 23826 18 0 0
sp_get_clo_for_invoice 4792 4792 0 0.00 0 18509 278 0 0
sp_kill_cost_storno 15927 15927 0 0.00 0 10076 67 0 0
sp_kill_qstorno_ret_qs2qd 1132010 1132010 0 0.00 0 39677 45 0 0
sp_kill_qty_storno 595457 595457 0 0.00 0 39689 101 0 0
sp_lock_selected_doc 19701 19701 0 0.00 0 6969 9 0 0
sp_make_cost_storno 26638 26638 0 0.00 0 20405 83 0 0
sp_multiply_rows_for_pdistr 26637 26637 0 0.00 0 9031 36 0 0
sp_multiply_rows_for_qdistr 19853 19853 0 0.00 0 14159 193 0 0
sp_pay_from_customer 6816 6816 0 0.00 5 17817 174 0 0
sp_pay_to_supplier 5599 5599 0 0.00 7 18369 196 0 0
sp_payment_common 12415 12415 0 0.00 2 10014 48 0 0
sp_qd_handle_on_cancel_clo 1693 1693 0 0.00 1 11470 340 0 0
sp_qd_handle_on_invoice_upd_sts 10370 10370 0 0.00 0 26448 486 0 0
sp_qd_handle_on_reserve_upd_sts 9431 9431 0 0.00 0 19596 68 0 0
sp_reserve_write_off 9431 9431 0 0.00 9 19619 279 0 0
sp_supplier_invoice 5147 5147 0 0.00 9 75856 3058 0 0
sp_supplier_order 5309 5309 0 0.00 7 28246 1325 0 0
srv_aggregate_perf_data 102 102 0 0.00 2764 156911 13943 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 790 127331 34765 0 0
srv_find_qd_qs_mism 1049264 1049264 0 0.00 0 22241 9 0 0
srv_make_invnt_saldo 500 500 0 0.00 80 86963 3419 0 0
srv_make_money_saldo 816 816 0 0.00 1 20715 401 0 0
srv_recalc_idx_stat 5 5 0 0.00 116084 308308 184457 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4829 4829 0 0.00 0 10606 830 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 2 5174 327 0 0
t$perf-norm:sp_cancel_client_order 1693 1693 0 0.00 0 1770 26 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 3957 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 641 641 0 0.00 0 3998 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 386 386 0 0.00 0 487 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 613 26 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 114874 6154 0 0
t$perf-norm:sp_cancel_write_off 881 881 0 0.00 0 2117 18 0 0
t$perf-norm:sp_client_order 19853 19853 0 0.00 0 6938 33 0 0
t$perf-norm:sp_customer_reserve 962 962 0 0.00 0 1952 15 0 0
t$perf-norm:sp_pay_from_customer 6816 6816 0 0.00 0 7566 18 0 0
t$perf-norm:sp_pay_to_supplier 5599 5599 0 0.00 0 7559 20 0 0
t$perf-norm:sp_reserve_write_off 9431 9431 0 0.00 0 6684 21 0 0
t$perf-norm:sp_supplier_invoice 5147 5147 0 0.00 0 6520 28 0 0
t$perf-norm:sp_supplier_order 5309 5309 0 0.00 0 8294 28 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 794 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 652 13 0 0
t$perf-norm:srv_make_invnt_saldo 500 500 0 0.00 0 3835 33 0 0
t$perf-norm:srv_make_money_saldo 816 816 0 0.00 0 2959 10 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 265 102 0 0
v_add_invoice_to_stock 9828 9582 246 2.50 0 5811 23 0 246
v_all_customers 36704 36704 0 0.00 0 116 3 0 0
v_all_suppliers 29446 29446 0 0.00 0 73 4 0 0
v_all_wares 39706 39706 0 0.00 0 310 3 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 1019 14 0 0
v_cancel_client_order 3386 3386 0 0.00 0 1322 8 0 0
v_cancel_customer_prepayment 1282 1282 0 0.00 0 492 10 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 1592 11 0 0
v_cancel_payment_to_supplier 772 772 0 0.00 0 2578 13 0 0
v_cancel_supplier_invoice 828 812 16 1.93 0 2711 29 0 16
v_cancel_supplier_order 816 816 0 0.00 0 5471 18 0 0
v_cancel_write_off 1762 1762 0 0.00 0 7381 27 0 0
v_max_id_clo_ord 5308 5308 0 0.00 0 97 4 0 0
v_max_id_clo_res 962 962 0 0.00 0 2364 19 0 0
v_max_id_ord_sup 5144 5144 0 0.00 0 5289 8 0 0
v_max_non_paid_invoice 1328 1328 0 0.00 0 183 7 0 0
v_max_non_paid_realizn 3383 3383 0 0.00 0 4846 8 0 0
v_min_id_clo_ord 5308 5308 0 0.00 0 3261 7 0 0
v_min_id_clo_res 962 962 0 0.00 0 6051 17 0 0
v_min_id_ord_sup 5144 5144 0 0.00 0 5106 8 0 0
v_min_non_paid_invoice 5599 5599 0 0.00 0 7979 21 0 0
v_min_non_paid_realizn 6816 6816 0 0.00 0 6080 17 0 0
v_our_firm 9868 9868 0 0.00 0 103 4 0 0
v_reserve_write_off 18862 18862 0 0.00 0 6014 10 0 0
x_lock_depdocs_on_canc_invoice 11360 11360 0 0.00 0 24419 121 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 14343 380 0 0
x_make_qstorno_1000_1200 5309 5309 0 0.00 2 28215 1187 0 0
x_make_qstorno_1200_2000 5147 5147 0 0.00 4 75831 2855 0 0
x_make_qstorno_2100_3300 455525 455525 0 0.00 0 67356 202 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 15-03-2023 10:38:50 to 15-03-2023 10:38:55.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:30 859 8192000000 8936736384 8994918400 42362544 0.514 8 5 9 0 43262048 478624 420880
08:08:33 37727 8192000000 9738149472 10037628928 798953440 8.886 100 83 166 0 825940560 7240256 19746864
08:10:18 24269 8192000000 9765031408 10073477120 838523264 9.285 100 82 165 1 870569760 12406272 19640224
08:11:48 17883 8192000000 9805787024 10131718144 876003888 9.660 100 79 156 1 918079632 24521888 17553856
08:13:11 6376 8192000000 9878687424 10216296448 928899120 10.184 100 76 152 0 986314016 41078800 16336096
08:14:23 2679 8192000000 9944333200 10289553408 970288176 10.590 100 92 183 0 1055541152 65934224 19318752
08:15:27 1414 8192000000 9983804400 10337882112 1000842704 10.887 100 95 183 1 1104017712 85049216 18125792
08:16:31 1248 8192000000 10015089840 10380263424 1028078992 11.150 100 93 180 1 1124954928 79130608 17745328
08:17:34 965 8192000000 10022164704 10395742208 1050450224 11.365 100 94 183 1 1144073680 74503392 19120064
08:18:35 994 8192000000 10041046032 10419085312 1068977216 11.542 100 97 190 3 1161906768 74025888 18903664
08:19:37 1362 8192000000 10043110896 10419519488 1074135984 11.592 100 93 182 2 1161820896 69702112 17982800
08:20:39 1250 8192000000 10036929104 10421301248 1083594256 11.682 100 95 182 4 1157560432 54956768 19009408
08:21:41 1075 8192000000 10046957456 10432540672 1089197616 11.735 100 94 186 0 1168941920 60698688 19045616
08:22:43 946 8192000000 10060896512 10447659008 1094319200 11.784 100 92 181 1 1184405584 71678224 18408160
08:23:48 1051 8192000000 10056429824 10448338944 1089916416 11.742 99 93 180 1 1179448112 71249696 18282000
08:24:50 3507 8192000000 10038492592 10432974848 1102160256 11.858 100 78 142 1 1165743504 48416464 15166784
08:25:54 1982 8192000000 10080456160 10481278976 1102719424 11.863 100 77 153 0 1199102432 81154640 15228368
08:26:57 1213 8192000000 10051809456 10444939264 1104130208 11.877 100 97 180 2 1172874128 49959200 18784720
08:27:59 987 8192000000 10048291456 10439835648 1093475760 11.776 100 84 159 0 1170090208 59625568 16988880
08:29:01 1584 8192000000 10040126592 10430533632 1101304560 11.850 100 84 159 0 1162577264 45341904 15930800
08:30:04 2530 8192000000 10032660384 10433810432 1101852944 11.855 100 68 128 3 1168885744 53364128 13668672
08:31:07 1082 8192000000 10041858288 10434293760 1087690080 11.721 100 81 156 2 1167623712 62429600 17504032
08:32:10 1124 8192000000 9995540064 10369798144 1047878496 11.340 100 85 163 2 1117261200 51224224 18158480
08:33:11 1918 8192000000 9972124720 10345947136 1033365024 11.201 99 81 157 1 1102878240 53434016 16079200
08:34:14 1062 8192000000 9958395904 10326765568 1011483184 10.990 98 88 163 2 1080148928 51866592 16799152
08:35:17 973 8192000000 9969763056 10333814784 1020003344 11.072 99 95 174 3 1095118368 56650352 18464672
08:36:19 2744 8192000000 9987840624 10365521920 1036481616 11.231 100 77 148 1 1118551776 66204416 15865744
08:37:22 6225 8192000000 9997216000 10381516800 1045561328 11.318 99 61 108 3 1111356416 55271728 10523360
08:38:39 11901 8192000000 10005228000 10398945280 1071788800 11.569 100 51 100 1 1125406640 43100432 10517408
08:39:58 2871 8192000000 10051731328 10433462272 1084574688 11.691 100 89 169 0 1173905648 71616576 17714384
08:41:07 1041 8192000000 10087989952 10474967040 1100523888 11.843 100 92 177 1 1207036544 85775056 20737600
08:42:11 3082 8192000000 10095314288 10490675200 1107431616 11.908 100 65 124 0 1215083552 94979840 12672096
08:43:16 17562 8192000000 10137433008 10537824256 1111953712 11.951 99 66 130 0 1239799712 114048048 13797952
08:44:39 1194 8192000000 10201705312 10598674432 1134486928 12.164 100 87 165 1 1321338592 169155760 17695904
08:45:44 1162 8192000000 10239738816 10638016512 1145566416 12.268 100 91 177 1 1345235088 179115024 20553648
08:46:49 1070 8192000000 10255481792 10651369472 1158054928 12.385 100 91 168 0 1372636608 196781696 17799984
08:47:52 4037 8192000000 10283737712 10691395584 1170557920 12.502 100 76 151 0 1396197680 209090736 16549024
08:49:00 1354 8192000000 10312647968 10712555520 1179438016 12.585 100 92 182 0 1424676224 224964272 20273936
08:50:04 1254 8192000000 10352378432 10760036352 1188956592 12.674 100 83 164 0 1464915776 256720368 19238816
08:51:09 1056 8192000000 10380405376 10795569152 1187981776 12.665 100 93 180 1 1488802960 279584720 21236464
08:52:13 783 8192000000 10419009984 10839334912 1203218080 12.806 100 92 174 1 1529456576 305672624 20565872
08:53:18 1134 8192000000 10440689168 10858160128 1200951152 12.785 100 87 159 0 1548133328 327379200 19802976
08:54:23 1233 8192000000 10523322896 10939424768 1213354080 12.900 100 98 196 0 1631511824 395330992 22826752
08:55:27 1011 8192000000 10522768848 10961440768 1211115184 12.879 100 89 172 0 1632169936 399364608 21690144
08:56:31 838 8192000000 10549941632 10964041728 1210784640 12.876 100 86 172 0 1658923888 428304512 19834736
08:57:36 1470 8192000000 10615807312 11037274112 1210422992 12.873 100 88 172 0 1723894464 492505600 20965872
08:58:39 2180 8192000000 10601721424 11034157056 1200959424 12.785 100 75 146 1 1705633936 485357296 19317216
08:59:54 1608 8192000000 10615338320 11066032128 1203865280 12.812 100 86 170 0 1722829776 498303712 20660784
09:00:57 4152 8192000000 10614619168 11075420160 1194534192 12.726 100 58 116 1 1717451008 507292560 15624256
09:02:08 19411 8192000000 10567285552 11038474240 1178169424 12.573 100 42 84 1 1712540928 522577040 11794464
09:03:43 12449 8192000000 10540091520 11042684928 1138703648 12.203 100 40 80 0 1680904480 530569840 11630992
09:04:57 16829 8192000000 10485346640 10999996416 1157244448 12.377 100 37 74 1 1607095600 439703552 10147600
09:06:16 38048 8192000000 10246702336 10745618432 1110986480 11.942 98 32 62 1 1399535680 281049168 7500032
09:07:55 30444 8192000000 10148359632 10704879616 1106444240 11.899 99 11 22 0 1317262896 208030176 2788480
09:09:27 50909 8192000000 10073215152 10550919168 1115989376 11.989 99 14 28 1 1195930464 77036176 2904912
09:11:18 45825 8192000000 10068758432 10524868608 1115398432 11.983 98 26 48 0 1146058960 25412624 5247904
09:13:04 29469 8192000000 10115985616 10540773376 1135802864 12.176 100 54 96 0 1209026896 63078016 10146016
09:14:34 127330 8192000000 10120679936 10575872000 1102437168 11.861 97 24 45 0 1169289040 61743120 5108752
09:17:42 73999 8192000000 10113010656 10595811328 1140910944 12.224 100 31 62 0 1231744288 83778544 7054800
09:20:03 30870 8192000000 10079646080 10523115520 1131984960 12.140 99 17 34 0 1169997024 33979408 4032656
09:21:34 27478 8192000000 10116170288 10581106688 1129562544 12.117 99 43 85 0 1205410864 67475888 8372432
09:23:04 51119 8192000000 10067344912 10534424576 1108087360 11.914 98 11 22 0 1155052720 44582784 2382576
09:24:56 29406 8192000000 10111053968 10574172160 1116838880 11.997 98 11 20 1 1192769504 73605392 2325232
09:26:27 37318 8192000000 10110283264 10570326016 1117621696 12.005 99 27 52 0 1193981472 70876848 5482928
09:28:04 68507 8192000000 10108983872 10583949312 1120105696 12.028 99 20 38 1 1198761504 74435168 4220640
09:30:13 53576 8192000000 10094306112 10562023424 1139746304 12.213 100 13 26 0 1220247200 77505536 2995360
09:32:07 35654 8192000000 10125524912 10574708736 1148839808 12.299 100 30 60 0 1219601472 64577280 6184384
09:33:48 41872 8192000000 10164373728 10615554048 1142884944 12.243 99 43 86 0 1258769952 106541584 9343424
09:35:31 36664 8192000000 10127537168 10612654080 1132026144 12.140 99 34 67 1 1232904832 93137568 7741120
09:37:11 41717 8192000000 10084185488 10567962624 1110124944 11.934 97 27 52 0 1179211472 63211200 5875328
09:38:53 32669 8192000000 10105999792 10562371584 1130331328 12.124 99 32 64 0 1204488000 67898064 6258608
09:40:31 40084 8192000000 10129206480 10567696384 1135149776 12.170 99 30 58 1 1190642784 49747120 5745888
09:42:14 104124 8192000000 10069813648 10547326976 1143641760 12.250 100 21 42 0 1180089088 31942688 4504640
09:45:07 31733 8192000000 10112140400 10568015872 1147844032 12.289 100 37 73 0 1202888304 47784416 7259856
09:46:51 41044 8192000000 10071562000 10527629312 1124791392 12.072 98 15 30 0 1161249968 32667328 3791248
09:48:38 70763 8192000000 10119152512 10579841024 1129924512 12.121 99 17 34 0 1236082064 102456128 3701424
09:50:58 70147 8192000000 10100779472 10572136448 1128355248 12.106 99 17 34 0 1182437312 50287248 3794816
09:53:09 56201 8192000000 10109414784 10548469760 1075830064 11.608 94 25 49 1 1170004592 88601200 5573328
09:55:22 23272 8192000000 10173170672 10617434112 1113329312 11.964 97 30 56 1 1245366928 125721440 6316176
09:56:46 45207 8192000000 10156611344 10637971456 1120443840 12.031 98 28 54 0 1282003664 155049744 6510080
09:58:36 44017 8192000000 10198609168 10651283456 1122547984 12.051 98 27 54 0 1284550480 155798784 6203712
10:00:50 67599 8192000000 10070220272 10540867584 1093482496 11.776 96 18 35 0 1158196240 61019664 3694080
10:03:19 95123 8192000000 10100305088 10545426432 1095242288 11.792 97 60 113 2 1198264768 91708032 11314448
10:05:55 33897 8192000000 10053604736 10520051712 1115150096 11.981 99 23 45 0 1153191712 33309472 4732144
10:07:30 81378 8192000000 10091855168 10551971840 1101709360 11.854 97 7 13 0 1165167776 62322672 1135744
10:09:52 71493 8192000000 10080674896 10579234816 1123883456 12.064 100 12 24 0 1181779600 55709552 2186592
10:12:04 25680 8192000000 10060110544 10510364672 1122891424 12.054 100 21 41 1 1149393376 22659904 3842048
10:13:37 13145 8192000000 10093817440 10536886272 1138309312 12.200 99 33 61 2 1189861840 44572608 6979920
10:14:55 29563 8192000000 10159740224 10606166016 1148761856 12.298 100 27 54 0 1258223072 103165584 6295632
10:16:25 43376 8192000000 10209687488 10681950208 1129415712 12.116 98 50 100 1 1295452928 155281056 10756160
10:18:13 69326 8192000000 10123906240 10564243456 1102579728 11.862 97 23 44 2 1195062000 87657904 4824368
10:20:25 38353 8192000000 10107662080 10541105152 1116159424 11.991 99 34 66 0 1194936624 71916384 6860816
10:22:03 39617 8192000000 10149964512 10620170240 1132147296 12.142 100 37 74 0 1232769328 93226784 7395248
10:23:46 66328 8192000000 10098673312 10553040896 1138803744 12.204 100 20 41 0 1196811696 54135216 3872736
10:25:55 115294 8192000000 10092499776 10614411264 1125735152 12.081 99 30 59 0 1191120144 59290224 6094768
10:29:21 61533 8192000000 10056381296 10557100032 1122058528 12.046 99 15 29 0 1157835472 32534720 3242224
10:31:38 49081 8192000000 10066770912 10548576256 1121709472 12.043 99 15 31 1 1174055168 49393792 2951904
10:33:28 60019 8192000000 10082316448 10540224512 1078077424 11.629 95 16 32 1 1141473840 60282752 3113664
10:35:28 25235 8192000000 10067813472 10513260544 1123549056 12.061 99 32 60 0 1167147344 37637264 5961024
10:36:54 25165 8192000000 10078718976 10514989056 1114825936 11.978 98 35 69 0 1179245680 56946704 7473040

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 15-03-2023 10:38:57 to 15-03-2023 10:39:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-03-2023 10:39:14 to 15-03-2023 10:39:16.

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          212397
MON$OLDEST_ACTIVE               215803
MON$OLDEST_SNAPSHOT             215803
MON$NEXT_TRANSACTION            215803
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                       2052128
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             768
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2909 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2909 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2909 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2909 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 ---
:23: 
:24: mscs-test-161.budzdorov.ru    Wed Mar 15 10:37:33 2023
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Wed Mar 15 10:37:33 2023
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
--- end of diff output ---

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

15.03.23 10:39:17. 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-03-15 10:39:20 No severe PSQL-related problems occured
15.03.23 10:39:21. End of report.