Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.06.2022 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 15821676 kB
MemAvailable 31271016 kB
Buffers 0 kB
Cached 15903316 kB
SwapCached 2468 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.2
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-06-20 07:37:12
DTS_END                         2022-06-20 07:37:12.3180
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40896
TRN_ID                          215068
ATT_ID                          658
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: 9081.24 26299 1089749
customer order: creation 162.39 499 19487
customer order: refuse 13.89 466 1667
order to supplier: creation 42.07 1574 5048
order to supplier: removal 3.44 181879 413
invoice (draft): creation 43.11 3649 5173
invoice (draft): removal 53.29 1038 6395
invoice accept: apply 39.82 27970 4778
invoice accept: cancel 48.18 1283 5781
customer reserve: creation 3687.57 266 442508
customer reserve: removal 4694.15 104 563298
realization accept: apply 79.97 299 9597
realization accept: cancel 88.61 174 10633
payment from customer: creation 56.54 195 6785
payment from customer: removal 5.68 132 681
payment to supplier: creation 47.02 220 5642
payment to supplier: removal 3.38 144 405
service: total inventory turnovers 3.81 4561 457
service: total monetary turnovers 7.45 416 894
service: aggregate perf. data 0.86 19183 103
service: refresh index statistics 0.03 281930 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-06-2022 07:38:43 to 20-06-2022 07:38:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9762
TEST_TIME 2 6926
TEST_TIME 3 5943
TEST_TIME 4 5995
TEST_TIME 5 6526
TEST_TIME 6 6686
TEST_TIME 7 6307
TEST_TIME 8 6290
TEST_TIME 9 6499
TEST_TIME 10 6305
TEST_TIME 11 6356
TEST_TIME 12 6580
TEST_TIME 13 6749
TEST_TIME 14 6857
TEST_TIME 15 6841
TEST_TIME 16 6823
TEST_TIME 17 6955
TEST_TIME 18 7114
TEST_TIME 19 7136
TEST_TIME 20 7329
TEST_TIME 21 7425
TEST_TIME 22 7534
TEST_TIME 23 7498
TEST_TIME 24 7443
TEST_TIME 25 7616
TEST_TIME 26 7969
TEST_TIME 27 8100
TEST_TIME 28 8137
TEST_TIME 29 8121
TEST_TIME 30 8162
TEST_TIME 31 8465
TEST_TIME 32 8768
TEST_TIME 33 9004
TEST_TIME 34 9277
TEST_TIME 35 9881
TEST_TIME 36 10204
TEST_TIME 37 10696
TEST_TIME 38 10798
TEST_TIME 39 10807
TEST_TIME 40 10888
TEST_TIME 41 10702
TEST_TIME 42 10536
TEST_TIME 43 10528
TEST_TIME 44 10562
TEST_TIME 45 10550
TEST_TIME 46 10540
TEST_TIME 47 10612
TEST_TIME 48 10549
TEST_TIME 49 10538
TEST_TIME 50 10410
TEST_TIME 51 10214
TEST_TIME 52 10142
TEST_TIME 53 10171
TEST_TIME 54 10076
TEST_TIME 55 9963
TEST_TIME 56 9861
TEST_TIME 57 9900
TEST_TIME 58 9829
TEST_TIME 59 9893
TEST_TIME 60 9896
TEST_TIME 61 9932
TEST_TIME 62 9871
TEST_TIME 63 9831
TEST_TIME 64 9775
TEST_TIME 65 9862
TEST_TIME 66 9858
TEST_TIME 67 9934
TEST_TIME 68 9869
TEST_TIME 69 9813
TEST_TIME 70 9808
TEST_TIME 71 9888
TEST_TIME 72 9838
TEST_TIME 73 9799
TEST_TIME 74 9835
TEST_TIME 75 9939
TEST_TIME 76 9866
TEST_TIME 77 9858
TEST_TIME 78 9828
TEST_TIME 79 9846
TEST_TIME 80 9827
TEST_TIME 81 9758
TEST_TIME 82 9694
TEST_TIME 83 9598
TEST_TIME 84 9571
TEST_TIME 85 9534
TEST_TIME 86 9520
TEST_TIME 87 9520
TEST_TIME 88 9527
TEST_TIME 89 9457
TEST_TIME 90 9464
TEST_TIME 91 9442
TEST_TIME 92 9469
TEST_TIME 93 9444
TEST_TIME 94 9513
TEST_TIME 95 9525
TEST_TIME 96 9503
TEST_TIME 97 9484
TEST_TIME 98 9511
TEST_TIME 99 9503
TEST_TIME 100 9527
TEST_TIME 101 9533
TEST_TIME 102 9504
TEST_TIME 103 9495
TEST_TIME 104 9475
TEST_TIME 105 9457
TEST_TIME 106 9406
TEST_TIME 107 9394
TEST_TIME 108 9384
TEST_TIME 109 9338
TEST_TIME 110 9313
TEST_TIME 111 9299
TEST_TIME 112 9254
TEST_TIME 113 9244
TEST_TIME 114 9212
TEST_TIME 115 9192
TEST_TIME 116 9218
TEST_TIME 117 9213
TEST_TIME 118 9194
TEST_TIME 119 9158
TEST_TIME 120 9100

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-06-2022 07:38:47 to 20-06-2022 07:38:50.

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 1499115 1499115 0 0.00 0 23929 15 0 0
doc_list_biud 1499101 1499101 0 0.00 0 32094 45 0 0
sp_add_invoice_to_stock 4888 4778 110 2.25 59 278714 27971 0 110
sp_cancel_adding_invoice 5781 5781 0 0.00 5 114873 1283 0 0
sp_cancel_client_order 1667 1667 0 0.00 6 11357 467 0 0
sp_cancel_customer_reserve 563298 563298 0 0.00 1 32098 105 0 0
sp_cancel_pay_from_customer 681 681 0 0.00 4 2653 132 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 3797 144 0 0
sp_cancel_supplier_invoice 6405 6395 10 0.15 3 22411 1038 0 10
sp_cancel_supplier_order 413 413 0 0.00 9 1567324 181880 0 0
sp_cancel_write_off 10633 10633 0 0.00 2 9437 174 0 0
sp_client_order 19487 19487 0 0.00 6 19298 499 0 0
sp_customer_reserve 442508 442508 0 0.00 0 106842 266 0 0
sp_fill_shopping_cart 29688 29688 0 0.00 1 13292 84 0 0
sp_fill_shopping_cart_clo_res 442473 442473 0 0.00 0 19456 18 0 0
sp_get_clo_for_invoice 4741 4741 0 0.00 0 24652 256 0 0
sp_kill_cost_storno 17500 17500 0 0.00 0 16912 88 0 0
sp_kill_qstorno_ret_qs2qd 1144037 1144037 0 0.00 0 32085 45 0 0
sp_kill_qty_storno 602521 602521 0 0.00 0 32092 104 0 0
sp_lock_selected_doc 19877 19877 0 0.00 0 10720 9 0 0
sp_make_cost_storno 26763 26763 0 0.00 0 23835 93 0 0
sp_multiply_rows_for_pdistr 26761 26761 0 0.00 0 12308 42 0 0
sp_multiply_rows_for_qdistr 19487 19487 0 0.00 0 15921 179 0 0
sp_pay_from_customer 6785 6785 0 0.00 6 12404 196 0 0
sp_pay_to_supplier 5642 5642 0 0.00 6 15918 220 0 0
sp_payment_common 12427 12427 0 0.00 2 12303 52 0 0
sp_qd_handle_on_cancel_clo 1667 1667 0 0.00 0 9369 389 0 0
sp_qd_handle_on_invoice_upd_sts 10519 10519 0 0.00 0 19037 559 0 0
sp_qd_handle_on_reserve_upd_sts 9596 9596 0 0.00 0 12123 68 0 0
sp_reserve_write_off 9597 9597 0 0.00 9 24275 299 0 0
sp_supplier_invoice 5173 5173 0 0.00 11 105921 3650 0 0
sp_supplier_order 5048 5048 0 0.00 8 68462 1575 0 0
srv_aggregate_perf_data 103 103 0 0.00 2445 205167 19183 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 742 133433 35239 0 0
srv_find_qd_qs_mism 1044079 1044079 0 0.00 0 9716 11 0 0
srv_make_invnt_saldo 457 457 0 0.00 91 85463 4561 0 0
srv_make_money_saldo 894 894 0 0.00 1 12952 417 0 0
srv_recalc_idx_stat 4 4 0 0.00 107443 495607 281930 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4781 4781 0 0.00 1 19698 1097 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 2 9391 644 0 0
t$perf-norm:sp_cancel_client_order 1667 1667 0 0.00 0 2987 31 0 0
t$perf-norm:sp_cancel_customer_reserve 657 657 0 0.00 0 681 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 681 681 0 0.00 0 2055 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 522 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 1140 36 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 108340 7575 0 0
t$perf-norm:sp_cancel_write_off 930 930 0 0.00 0 1618 25 0 0
t$perf-norm:sp_client_order 19487 19487 0 0.00 0 16130 42 0 0
t$perf-norm:sp_customer_reserve 1024 1024 0 0.00 0 1871 21 0 0
t$perf-norm:sp_pay_from_customer 6785 6785 0 0.00 0 6913 23 0 0
t$perf-norm:sp_pay_to_supplier 5642 5642 0 0.00 0 5710 23 0 0
t$perf-norm:sp_reserve_write_off 9598 9598 0 0.00 0 17002 27 0 0
t$perf-norm:sp_supplier_invoice 5173 5173 0 0.00 0 4713 36 0 0
t$perf-norm:sp_supplier_order 5048 5048 0 0.00 0 7655 36 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 707 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 3686 83 0 0
t$perf-norm:srv_make_invnt_saldo 457 457 0 0.00 0 763 14 0 0
t$perf-norm:srv_make_money_saldo 894 894 0 0.00 0 1177 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 212 78 0 0
v_add_invoice_to_stock 9681 9461 220 2.27 0 2820 24 0 220
v_all_customers 35972 35972 0 0.00 0 83 4 0 0
v_all_suppliers 28766 28766 0 0.00 0 64 4 0 0
v_all_wares 38962 38962 0 0.00 0 54 4 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 1433 20 0 0
v_cancel_client_order 3334 3334 0 0.00 0 2688 11 0 0
v_cancel_customer_prepayment 1362 1362 0 0.00 0 2620 14 0 0
v_cancel_customer_reserve 1314 1314 0 0.00 1 1502 12 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 1535 13 0 0
v_cancel_supplier_invoice 816 796 20 2.45 0 890 22 0 20
v_cancel_supplier_order 826 826 0 0.00 0 3234 17 0 0
v_cancel_write_off 1860 1860 0 0.00 0 1390 24 0 0
v_max_id_clo_ord 5046 5046 0 0.00 0 765 5 0 0
v_max_id_clo_res 1024 1024 0 0.00 0 1002 11 0 0
v_max_id_ord_sup 5160 5160 0 0.00 0 2585 7 0 0
v_max_non_paid_invoice 1465 1465 0 0.00 0 3044 10 0 0
v_max_non_paid_realizn 3465 3465 0 0.00 0 1651 8 0 0
v_min_id_clo_ord 5046 5046 0 0.00 0 1441 8 0 0
v_min_id_clo_res 1024 1024 0 0.00 0 1766 10 0 0
v_min_id_ord_sup 5160 5160 0 0.00 0 6725 17 0 0
v_min_non_paid_invoice 5642 5642 0 0.00 0 8335 18 0 0
v_min_non_paid_realizn 6785 6785 0 0.00 0 12292 24 0 0
v_our_firm 9630 9630 0 0.00 0 68 5 0 0
v_reserve_write_off 19192 19192 0 0.00 0 2783 10 0 0
x_lock_depdocs_on_canc_invoice 11778 11778 0 0.00 0 12281 123 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 5751 378 0 0
x_make_qstorno_1000_1200 5048 5048 0 0.00 3 68449 1388 0 0
x_make_qstorno_1200_2000 5173 5173 0 0.00 6 105853 3431 0 0
x_make_qstorno_2100_3300 442507 442507 0 0.00 0 106825 235 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-06-2022 07:38:51 to 20-06-2022 07: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
05:07:16 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:16 19095 8192000000 9712397488 10010152960 796598368 8.862 100 90 180 0 825289872 7438112 21253392
05:09:44 21497 8192000000 9772047344 10081710080 849200512 9.392 100 87 174 0 880345984 11097312 20048160
05:11:10 18819 8192000000 9809119616 10127364096 871687696 9.617 100 86 172 1 908206400 17240592 19278112
05:12:33 10037 8192000000 9856306448 10190073856 909214512 9.990 100 80 160 0 964813168 37809904 17788752
05:13:46 6879 8192000000 9926248384 10266427392 949971056 10.391 100 89 173 0 1042728784 73700656 19057072
05:14:56 1740 8192000000 9960950592 10311147520 990806816 10.789 100 88 172 1 1077965744 70405296 16753632
05:16:01 1633 8192000000 9993641936 10351321088 1012407168 10.999 100 93 181 0 1114740032 84284848 18048016
05:17:05 1366 8192000000 9985128976 10351108096 1032561072 11.193 100 95 181 1 1113088352 61527936 18999344
05:18:08 888 8192000000 10049457408 10422747136 1053180944 11.391 100 94 179 1 1167330272 96302368 17846960
05:19:12 1191 8192000000 10058472896 10429681664 1064557952 11.500 100 96 186 2 1167919440 85190016 18171472
05:20:17 1256 8192000000 10030879824 10403807232 1067448560 11.528 100 89 168 0 1150567024 67171680 15946784
05:21:20 1154 8192000000 10050065584 10429165568 1073627168 11.587 100 96 185 0 1167614000 75540208 18446624
05:22:22 1377 8192000000 10059982784 10439659520 1079453760 11.642 100 95 184 0 1171660160 73744464 18461936
05:23:27 2593 8192000000 10067332688 10451746816 1080437808 11.652 100 81 157 0 1188017024 92791424 14787792
05:24:31 1342 8192000000 10048658848 10435284992 1086884656 11.713 100 95 182 0 1172256176 65340720 20030800
05:25:34 1365 8192000000 10072900608 10457817088 1091370112 11.756 100 95 186 0 1191676416 81605344 18700960
05:26:36 1130 8192000000 10063908560 10447884288 1092645824 11.768 100 96 185 0 1182390880 69221584 20523472
05:27:39 1036 8192000000 10053294192 10443345920 1097694032 11.816 100 93 174 2 1175139360 59135008 18310320
05:28:42 1250 8192000000 10040519696 10428329984 1088544592 11.729 100 78 155 1 1163427232 59635472 15247168
05:29:44 1259 8192000000 10072158416 10458112000 1092589248 11.767 100 96 186 0 1193734480 81776528 19368704
05:30:47 3693 8192000000 10031560416 10424836096 1068287760 11.536 99 78 152 0 1156866032 71798768 16779504
05:31:52 673 8192000000 10045592688 10429820928 1078451616 11.633 100 86 168 1 1162368336 66320288 17596432
05:32:54 1078 8192000000 10044857024 10425745408 1073386432 11.584 100 84 153 0 1167721696 80535296 13799968
05:33:56 1453 8192000000 10005657904 10383790080 1047020128 11.332 100 75 131 2 1124075792 63319664 13736000
05:35:00 1229 8192000000 9994679840 10367934464 1025587856 11.126 99 89 169 1 1120809984 77495040 17727088
05:36:03 1258 8192000000 10010528288 10380460032 1037757056 11.243 100 97 180 1 1132104800 73547728 20800016
05:37:05 1110 8192000000 9967901088 10344992768 1031326320 11.181 100 82 153 0 1090134848 42385856 16422672
05:38:11 1233 8192000000 10013710208 10390732800 1053014688 11.390 100 94 180 3 1136251760 63636672 19600400
05:39:20 13619 8192000000 10023960400 10409082880 1059859568 11.455 100 79 151 2 1151100864 76025808 15215488
05:40:37 16762 8192000000 10064364880 10453012480 1084872976 11.694 100 68 124 3 1180162752 82363792 12925984
05:42:02 1941 8192000000 10077084928 10460319744 1097734624 11.816 100 81 151 0 1191499136 79172880 14591632
05:43:08 1644 8192000000 10118894704 10505789440 1112384656 11.955 100 86 168 0 1239155600 108258288 18512656
05:44:15 7474 8192000000 10124669440 10515812352 1121828192 12.044 100 74 137 2 1244762064 108100304 14833568
05:45:35 6557 8192000000 10161254624 10561310720 1124970656 12.074 100 73 144 0 1284046016 143203808 15871552
05:46:47 13144 8192000000 10197257472 10609250304 1135750256 12.176 100 66 132 1 1305263136 155134656 14378224
05:48:06 3454 8192000000 10236535680 10635812864 1155369968 12.360 100 85 165 2 1349204304 175353824 18480512
05:49:12 3185 8192000000 10229167040 10643939328 1164523184 12.446 100 78 151 0 1339977200 159116752 16337264
05:50:22 953 8192000000 10303230368 10708242432 1178918704 12.580 100 97 184 0 1411983520 212190016 20874800
05:51:27 1051 8192000000 10319264336 10719535104 1181064016 12.600 100 94 179 0 1424498288 223707472 19726800
05:52:34 1871 8192000000 10342337152 10743468032 1190527216 12.688 100 93 174 2 1449107248 239648736 18931296
05:53:39 734 8192000000 10366803824 10778906624 1198357312 12.761 100 96 180 0 1476180640 257251712 20571616
05:54:44 843 8192000000 10361620032 10775678976 1186900896 12.655 99 96 188 1 1470277456 261573648 21802912
05:55:48 1734 8192000000 10414078832 10825625600 1202213104 12.797 100 95 186 2 1525350000 301232416 21904480
05:56:54 1328 8192000000 10458918000 10874376192 1202958128 12.804 100 94 181 2 1564138576 338733904 22446544
05:57:58 1009 8192000000 10485214224 10899570688 1215728400 12.922 100 98 190 3 1594982032 356833440 22420192
05:59:02 1820 8192000000 10515723072 10939277312 1217523296 12.939 100 91 178 1 1624978656 386629376 20825984
06:00:07 845 8192000000 10536758064 10942697472 1195787056 12.737 99 97 188 0 1640636848 422086000 22763792
06:01:11 1482 8192000000 10577669472 10988105728 1197286480 12.751 99 94 186 0 1681462336 462386272 21789584
06:02:14 825 8192000000 10579199808 11042308096 1191090928 12.694 98 95 185 1 1684541984 469696512 23754544
06:03:19 1017 8192000000 10604241072 11065622528 1210905792 12.877 100 94 187 0 1704340432 470636000 22798640
06:04:24 5822 8192000000 10624224064 11056451584 1220966768 12.971 100 70 139 0 1726002720 487741200 17294752
06:05:34 14077 8192000000 10629515552 11075969024 1201910880 12.794 99 45 89 0 1722914096 509130192 11873024
06:06:51 11290 8192000000 10665810192 11141345280 1197174048 12.750 99 48 96 1 1769783760 559988032 12621680
06:08:06 17156 8192000000 10661590608 11155353600 1176604656 12.559 99 52 103 0 1776741632 586490672 13646304
06:09:51 10435 8192000000 10545100496 11080867840 1177167504 12.564 100 40 80 1 1649192608 461693744 10331360
06:11:24 11396 8192000000 10494244976 10962055168 1161263808 12.415 99 36 72 0 1582271872 411187696 9820368
06:12:44 39353 8192000000 10310491168 10843099136 1157249024 12.377 100 25 50 1 1451253312 286309696 7694592
06:14:26 37604 8192000000 10134179824 10639454208 1130713968 12.128 99 9 18 1 1266763808 133747008 2302832
06:16:41 93038 8192000000 10065277984 10498568192 1125945504 12.083 98 39 63 3 1188387760 55508960 6933296
06:19:15 47966 8192000000 10135299952 10567077888 1131118336 12.132 99 39 77 0 1223328304 84265680 7944288
06:21:04 92571 8192000000 10153540992 10600615936 1113346672 11.964 97 20 37 1 1185364432 68731840 3285920
06:23:40 38109 8192000000 10096013328 10553036800 1111671056 11.948 97 31 63 1 1182014416 63309808 7033552
06:25:25 102985 8192000000 10051788848 10518159360 1103781600 11.874 97 6 12 2 1146028096 40822880 1423616
06:28:10 94067 8192000000 10109282688 10570686464 1117934176 12.007 98 27 47 0 1188149696 64883136 5332384
06:30:50 26633 8192000000 10120904704 10558578688 1134482960 12.164 99 58 112 1 1196238224 50154144 11601120
06:32:32 48361 8192000000 10134996976 10554179584 1116079360 11.990 97 37 66 0 1196267696 73207808 6980528
06:34:22 24777 8192000000 10127687072 10582253568 1091831136 11.760 95 42 83 1 1212571504 111319888 9420480
06:35:51 18871 8192000000 10203589088 10639417344 1132391632 12.144 100 51 100 3 1293179072 148797584 11989856
06:37:17 23992 8192000000 10154686608 10612912128 1114422896 11.974 98 20 40 1 1234921424 115032304 5466224
06:38:42 16240 8192000000 10118032368 10568830976 1134542720 12.164 100 27 50 1 1216848272 76972384 5333168
06:40:00 54507 8192000000 10123098672 10575527936 1119000480 12.018 98 15 30 0 1209368752 87140576 3227696
06:41:55 54217 8192000000 10075700976 10575814656 1096192112 11.801 97 15 30 0 1181853152 82029328 3631712
06:43:51 21977 8192000000 10089329504 10528788480 1122768928 12.053 99 47 82 2 1189339744 57229824 9340992
06:45:13 15948 8192000000 10048439776 10482356224 1115524768 11.985 99 52 90 1 1143771664 18159632 10087264
06:46:32 9223 8192000000 10076150688 10506063872 1132885888 12.149 100 49 98 0 1170463920 27897360 9680672
06:47:45 51032 8192000000 10114552352 10574536704 1140964704 12.225 100 24 48 0 1205484368 59971840 4547824
06:49:36 81145 8192000000 10139784928 10597957632 1141938592 12.234 99 21 42 2 1216166240 69999984 4227664
06:52:02 61305 8192000000 10085785744 10578677760 1133120448 12.151 99 31 60 1 1196035280 56340176 6574656
06:54:13 41211 8192000000 10108345616 10553106432 1151426288 12.323 100 29 58 0 1213006768 55884000 5696480
06:55:56 32378 8192000000 10145347840 10589884416 1160294000 12.406 100 46 88 1 1240306176 70958848 9053328
06:57:32 131040 8192000000 10095756112 10539945984 1117732112 12.006 97 25 50 3 1175253904 51772992 5748800
07:00:44 45906 8192000000 10113457808 10561413120 1132583632 12.146 99 34 67 1 1222661552 82909008 7168912
07:02:32 66521 8192000000 10123557360 10595041280 1120120288 12.028 98 17 34 0 1230741392 106312480 4308624
07:04:46 63412 8192000000 10108148752 10569936896 1119674736 12.024 98 13 26 0 1184084224 61224528 3184960
07:08:36 85875 8192000000 10140144256 10602831872 1132322560 12.143 99 22 44 0 1229400112 92180080 4897472
07:11:10 60046 8192000000 10092410096 10604535808 1086405936 11.708 95 14 28 0 1157775776 68341616 3028224
07:13:11 50881 8192000000 10122242176 10572509184 1114421520 11.974 98 28 56 0 1201978528 81868896 5688112
07:15:20 37707 8192000000 10102571904 10522345472 1116280736 11.992 98 38 76 0 1165702144 41630944 7790464
07:17:06 35308 8192000000 10105067136 10561818624 1108197952 11.915 97 41 82 4 1184860912 68973632 7689328
07:18:45 29691 8192000000 10119490992 10583547904 1123257008 12.058 98 32 64 0 1177960032 48284064 6418960
07:20:44 133432 8192000000 10078531504 10529988608 1152169536 12.330 100 37 74 0 1255557264 95373488 8014240
07:24:03 34858 8192000000 10146563088 10602196992 1150268416 12.312 99 38 76 2 1231444752 73499968 7676368
07:25:38 66936 8192000000 10088101280 10554179584 1122202560 12.048 98 8 16 0 1181535456 57396816 1936080
07:27:47 59530 8192000000 10095141408 10573287424 1113623664 11.967 97 18 36 0 1184580480 66632176 4324640
07:30:00 82203 8192000000 10084453968 10560618496 1139550544 12.211 100 19 38 1 1170458912 26465312 4443056
07:32:54 106111 8192000000 10059965632 10521440256 1119870384 12.026 99 16 30 2 1175234000 52067616 3296000
07:35:46 94802 8192000000 9169413648 9497612288 307289552 3.615 28 9 16 1 373837616 64565232 1982832

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-06-2022 07:38:57 to 20-06-2022 07:39:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 20-06-2022 07:39:14 to 20-06-2022 07:39:15.

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          212082
MON$OLDEST_ACTIVE               215336
MON$OLDEST_SNAPSHOT             215336
MON$NEXT_TRANSACTION            215336
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                       2087904
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             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2780 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2780 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2780 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2780 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 ---
:234: 
:235: mscs-test-161.budzdorov.ru    Mon Jun 20 07:37:24 2022
:236:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:237: 
--- end of diff output ---

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

20.06.22 07:39:16. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-06-20 07:39:19 No severe PSQL-related problems occured
20.06.22 07:39:20. End of report.