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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15017292 kB
MemAvailable 30387664 kB
Buffers 20 kB
Cached 16660404 kB
SwapCached 2496 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-01-18 04:37:24
DTS_END                         2023-01-18 04:37:24.4440
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/56266
TRN_ID                          225418
ATT_ID                          630
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: 9003.76 25651 1080451
customer order: creation 170.52 445 20462
customer order: refuse 14.59 399 1751
order to supplier: creation 45.95 1324 5514
order to supplier: removal 3.42 156503 410
invoice (draft): creation 45.98 3154 5517
invoice (draft): removal 49.51 951 5941
invoice accept: apply 42.69 27085 5123
invoice accept: cancel 44.81 1272 5377
customer reserve: creation 3923.63 261 470835
customer reserve: removal 4367.26 97 524071
realization accept: apply 82.90 300 9948
realization accept: cancel 81.76 175 9811
payment from customer: creation 59.89 204 7187
payment from customer: removal 5.35 124 642
payment to supplier: creation 49.49 213 5939
payment to supplier: removal 3.52 120 422
service: total inventory turnovers 3.98 4260 477
service: total monetary turnovers 7.58 383 909
service: aggregate perf. data 0.92 17991 110
service: refresh index statistics 0.04 297763 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 18-01-2023 04:39:20 to 18-01-2023 04:39:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8496
TEST_TIME 2 6866
TEST_TIME 3 6488
TEST_TIME 4 6360
TEST_TIME 5 5926
TEST_TIME 6 5898
TEST_TIME 7 5990
TEST_TIME 8 6183
TEST_TIME 9 6347
TEST_TIME 10 6734
TEST_TIME 11 6925
TEST_TIME 12 6931
TEST_TIME 13 7168
TEST_TIME 14 7047
TEST_TIME 15 7007
TEST_TIME 16 7284
TEST_TIME 17 7361
TEST_TIME 18 7420
TEST_TIME 19 7543
TEST_TIME 20 7624
TEST_TIME 21 7685
TEST_TIME 22 8098
TEST_TIME 23 8314
TEST_TIME 24 8385
TEST_TIME 25 8340
TEST_TIME 26 8490
TEST_TIME 27 8814
TEST_TIME 28 8778
TEST_TIME 29 9451
TEST_TIME 30 9486
TEST_TIME 31 10317
TEST_TIME 32 10535
TEST_TIME 33 11228
TEST_TIME 34 11180
TEST_TIME 35 11128
TEST_TIME 36 10902
TEST_TIME 37 10791
TEST_TIME 38 10722
TEST_TIME 39 10629
TEST_TIME 40 10530
TEST_TIME 41 10396
TEST_TIME 42 10335
TEST_TIME 43 10165
TEST_TIME 44 10091
TEST_TIME 45 10138
TEST_TIME 46 10056
TEST_TIME 47 10045
TEST_TIME 48 10088
TEST_TIME 49 9975
TEST_TIME 50 9883
TEST_TIME 51 9822
TEST_TIME 52 10022
TEST_TIME 53 10024
TEST_TIME 54 10025
TEST_TIME 55 9951
TEST_TIME 56 9893
TEST_TIME 57 10017
TEST_TIME 58 9999
TEST_TIME 59 9908
TEST_TIME 60 9913
TEST_TIME 61 9965
TEST_TIME 62 9919
TEST_TIME 63 10061
TEST_TIME 64 10014
TEST_TIME 65 9944
TEST_TIME 66 9992
TEST_TIME 67 9874
TEST_TIME 68 9842
TEST_TIME 69 9744
TEST_TIME 70 9707
TEST_TIME 71 9698
TEST_TIME 72 9643
TEST_TIME 73 9568
TEST_TIME 74 9570
TEST_TIME 75 9539
TEST_TIME 76 9422
TEST_TIME 77 9469
TEST_TIME 78 9464
TEST_TIME 79 9448
TEST_TIME 80 9470
TEST_TIME 81 9435
TEST_TIME 82 9421
TEST_TIME 83 9469
TEST_TIME 84 9434
TEST_TIME 85 9433
TEST_TIME 86 9455
TEST_TIME 87 9532
TEST_TIME 88 9537
TEST_TIME 89 9527
TEST_TIME 90 9644
TEST_TIME 91 9611
TEST_TIME 92 9557
TEST_TIME 93 9504
TEST_TIME 94 9434
TEST_TIME 95 9468
TEST_TIME 96 9397
TEST_TIME 97 9352
TEST_TIME 98 9301
TEST_TIME 99 9330
TEST_TIME 100 9303
TEST_TIME 101 9301
TEST_TIME 102 9279
TEST_TIME 103 9284
TEST_TIME 104 9275
TEST_TIME 105 9243
TEST_TIME 106 9275
TEST_TIME 107 9312
TEST_TIME 108 9312
TEST_TIME 109 9332
TEST_TIME 110 9309
TEST_TIME 111 9310
TEST_TIME 112 9341
TEST_TIME 113 9289
TEST_TIME 114 9225
TEST_TIME 115 9211
TEST_TIME 116 9161
TEST_TIME 117 9158
TEST_TIME 118 9108
TEST_TIME 119 9096
TEST_TIME 120 9040

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-01-2023 04:39:24 to 18-01-2023 04:39:27.

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 1513691 1513691 0 0.00 0 18215 14 0 0
doc_list_biud 1513677 1513677 0 0.00 0 35357 39 0 0
sp_add_invoice_to_stock 5291 5123 168 3.17 58 201589 27085 0 168
sp_cancel_adding_invoice 5377 5377 0 0.00 6 83948 1273 0 0
sp_cancel_client_order 1751 1751 0 0.00 6 11431 400 0 0
sp_cancel_customer_reserve 524071 524071 0 0.00 1 24759 98 0 0
sp_cancel_pay_from_customer 642 642 0 0.00 4 5083 124 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 3 5195 120 0 0
sp_cancel_supplier_invoice 5953 5941 12 0.20 2 18382 952 0 12
sp_cancel_supplier_order 410 410 0 0.00 7 1629260 156504 0 0
sp_cancel_write_off 9811 9811 0 0.00 2 21101 175 0 0
sp_client_order 20462 20462 0 0.00 5 23522 445 0 0
sp_customer_reserve 470835 470835 0 0.00 0 47422 262 0 0
sp_fill_shopping_cart 31475 31475 0 0.00 1 31052 82 0 0
sp_fill_shopping_cart_clo_res 470809 470809 0 0.00 0 17823 17 0 0
sp_get_clo_for_invoice 5082 5082 0 0.00 0 21411 283 0 0
sp_kill_cost_storno 16252 16252 0 0.00 0 12243 73 0 0
sp_kill_qstorno_ret_qs2qd 1064304 1064304 0 0.00 0 24720 42 0 0
sp_kill_qty_storno 562387 562387 0 0.00 0 35356 98 0 0
sp_lock_selected_doc 20902 20902 0 0.00 0 5883 7 0 0
sp_make_cost_storno 28156 28156 0 0.00 0 12825 86 0 0
sp_multiply_rows_for_pdistr 28152 28152 0 0.00 0 8840 41 0 0
sp_multiply_rows_for_qdistr 20462 20462 0 0.00 0 10662 158 0 0
sp_pay_from_customer 7187 7187 0 0.00 5 19000 205 0 0
sp_pay_to_supplier 5939 5939 0 0.00 6 15063 214 0 0
sp_payment_common 13126 13126 0 0.00 2 18989 56 0 0
sp_qd_handle_on_cancel_clo 1751 1751 0 0.00 0 11424 321 0 0
sp_qd_handle_on_invoice_upd_sts 10455 10455 0 0.00 0 35355 518 0 0
sp_qd_handle_on_reserve_upd_sts 9948 9948 0 0.00 0 20839 70 0 0
sp_reserve_write_off 9948 9948 0 0.00 8 28636 301 0 0
sp_supplier_invoice 5517 5517 0 0.00 10 111082 3155 0 0
sp_supplier_order 5514 5514 0 0.00 7 32414 1325 0 0
srv_aggregate_perf_data 110 110 0 0.00 2304 166402 17992 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 780 105331 33464 0 0
srv_find_qd_qs_mism 1030960 1030960 0 0.00 0 11287 11 0 0
srv_make_invnt_saldo 477 477 0 0.00 90 198128 4261 0 0
srv_make_money_saldo 909 909 0 0.00 0 16197 384 0 0
srv_recalc_idx_stat 5 5 0 0.00 204567 471116 297763 0 0
t$perf-abend:sp_add_invoice_to_stock 168 168 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5136 5136 0 0.00 1 16297 935 0 0
t$perf-norm:sp_cancel_adding_invoice 446 446 0 0.00 1 9882 451 0 0
t$perf-norm:sp_cancel_client_order 1751 1751 0 0.00 0 4319 26 0 0
t$perf-norm:sp_cancel_customer_reserve 748 748 0 0.00 0 1022 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 642 642 0 0.00 0 957 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 190 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 768 32 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 91861 6338 0 0
t$perf-norm:sp_cancel_write_off 1046 1046 0 0.00 0 5932 23 0 0
t$perf-norm:sp_client_order 20465 20465 0 0.00 0 13752 34 0 0
t$perf-norm:sp_customer_reserve 1111 1111 0 0.00 0 674 13 0 0
t$perf-norm:sp_pay_from_customer 7187 7187 0 0.00 0 4368 19 0 0
t$perf-norm:sp_pay_to_supplier 5939 5939 0 0.00 0 11372 22 0 0
t$perf-norm:sp_reserve_write_off 9948 9948 0 0.00 0 9014 23 0 0
t$perf-norm:sp_supplier_invoice 5517 5517 0 0.00 0 7323 29 0 0
t$perf-norm:sp_supplier_order 5514 5514 0 0.00 0 12982 30 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 456 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 297 8 0 0
t$perf-norm:srv_make_invnt_saldo 477 477 0 0.00 0 2473 21 0 0
t$perf-norm:srv_make_money_saldo 909 909 0 0.00 0 324 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 180 76 0 0
v_add_invoice_to_stock 10492 10156 336 3.20 0 9956 32 0 336
v_all_customers 37680 37680 0 0.00 0 112 4 0 0
v_all_suppliers 30810 30810 0 0.00 0 99 4 0 0
v_all_wares 40924 40924 0 0.00 0 77 4 0 0
v_cancel_adding_invoice 892 892 0 0.00 0 540 15 0 0
v_cancel_client_order 3502 3502 0 0.00 0 2554 12 0 0
v_cancel_customer_prepayment 1284 1284 0 0.00 0 3020 14 0 0
v_cancel_customer_reserve 1496 1496 0 0.00 0 15091 22 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 2851 17 0 0
v_cancel_supplier_invoice 846 822 24 2.83 0 504 26 0 24
v_cancel_supplier_order 820 820 0 0.00 0 1353 12 0 0
v_cancel_write_off 2092 2092 0 0.00 0 2317 25 0 0
v_max_id_clo_ord 5506 5506 0 0.00 0 1072 5 0 0
v_max_id_clo_res 1111 1111 0 0.00 0 7018 20 0 0
v_max_id_ord_sup 5507 5507 0 0.00 0 9242 11 0 0
v_max_non_paid_invoice 1547 1547 0 0.00 0 3552 10 0 0
v_max_non_paid_realizn 3581 3581 0 0.00 0 2934 10 0 0
v_min_id_clo_ord 5506 5506 0 0.00 0 8174 12 0 0
v_min_id_clo_res 1111 1111 0 0.00 0 2584 10 0 0
v_min_id_ord_sup 5507 5507 0 0.00 0 5092 10 0 0
v_min_non_paid_invoice 5939 5939 0 0.00 0 14969 21 0 0
v_min_non_paid_realizn 7187 7187 0 0.00 0 11766 28 0 0
v_our_firm 10456 10456 0 0.00 0 96 4 0 0
v_reserve_write_off 19896 19896 0 0.00 0 15085 15 0 0
x_lock_depdocs_on_canc_invoice 10907 10907 0 0.00 0 16663 125 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 7183 381 0 0
x_make_qstorno_1000_1200 5514 5514 0 0.00 3 32399 1154 0 0
x_make_qstorno_1200_2000 5517 5517 0 0.00 4 110906 2931 0 0
x_make_qstorno_2100_3300 470833 470833 0 0.00 0 47090 231 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 18-01-2023 04:39:28 to 18-01-2023 04:39:33.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:28 926 8192000000 8928715216 8985853952 10960672 0.133 4 2 3 0 11089648 65936 63040
02:08:30 27037 8192000000 9722465872 10020679680 804711808 8.944 100 93 186 0 834236352 7407904 22116640
02:10:03 14855 8192000000 9754035792 10057486336 827500720 9.174 100 92 184 0 863020960 13733152 21787088
02:11:25 15900 8192000000 9798068208 10115989504 856741216 9.468 100 85 170 0 903979792 27482320 19756256
02:12:46 10855 8192000000 9858856752 10194096128 910419264 10.001 100 79 156 1 967399712 39867520 17112928
02:13:59 2701 8192000000 9914554000 10257833984 948911984 10.380 100 84 168 2 1034114992 66959200 18243808
02:15:09 2503 8192000000 9962855040 10311016448 985510096 10.738 100 83 161 0 1074964864 73224320 16230448
02:16:14 1659 8192000000 9987533408 10352717824 1018114784 11.054 100 90 173 4 1103446288 67934672 17396832
02:17:17 932 8192000000 10026020192 10397999104 1051599760 11.376 100 98 190 1 1142178000 72264336 18313904
02:18:19 1040 8192000000 10073329968 10449956864 1061767712 11.473 100 98 194 1 1192905952 111675200 19463040
02:19:22 7009 8192000000 10033421904 10408988672 1070025824 11.552 100 99 189 1 1157129232 67441808 19661600
02:20:30 909 8192000000 10025615024 10407505920 1078192464 11.630 100 93 177 1 1147127872 50596128 18339280
02:21:32 10090 8192000000 10044000128 10430996480 1078775312 11.636 100 70 136 2 1163165712 70456272 13934128
02:22:48 2538 8192000000 10021645168 10420875264 1083659760 11.682 100 68 134 0 1151098192 53664320 13774112
02:23:59 1153 8192000000 10041923968 10428805120 1085431104 11.699 100 94 175 2 1169868272 66280448 18156720
02:25:01 994 8192000000 10072068032 10465136640 1089406976 11.737 100 95 182 0 1191793824 84103168 18283680
02:26:03 1103 8192000000 10067151328 10457645056 1094569104 11.786 100 97 189 0 1189423184 75755808 19098272
02:27:05 1335 8192000000 10074979200 10469810176 1102302832 11.859 100 96 181 0 1198783744 77793776 18687136
02:28:07 1234 8192000000 10060041696 10451607552 1102697328 11.863 100 94 182 3 1182367600 59506480 20163792
02:29:09 1106 8192000000 10018190752 10400288768 1065701584 11.511 99 96 182 2 1144670224 59813680 19154960
02:30:11 1068 8192000000 10033823776 10411819008 1065091008 11.505 100 91 174 0 1153725312 71064032 17570272
02:31:14 1092 8192000000 9999204416 10382970880 1062298160 11.478 100 92 176 4 1124318000 41464640 20555200
02:32:16 859 8192000000 10031741184 10408620032 1045759024 11.320 99 90 179 0 1154621376 90431024 18431328
02:33:18 1224 8192000000 10017298672 10397650944 1056942176 11.427 100 96 186 2 1140702496 64276496 19483824
02:34:20 1301 8192000000 10016204128 10393034752 1056744336 11.425 100 95 184 4 1139876960 63747376 19385248
02:35:22 1565 8192000000 9997150032 10378997760 1041903120 11.283 100 72 140 2 1118209792 61984112 14322560
02:36:24 1930 8192000000 9986973584 10363355136 1040050784 11.265 100 86 162 1 1110865472 54579888 16234800
02:37:27 968 8192000000 9998053008 10375057408 1038424528 11.250 100 84 157 0 1120923456 65296512 17202416
02:38:39 15399 8192000000 10010850400 10388488192 1044859728 11.311 100 72 134 1 1123910272 65846352 13204192
02:40:08 2844 8192000000 10011678336 10400038912 1066610256 11.520 100 80 140 4 1131534688 51294800 13629632
02:41:13 4199 8192000000 10073127664 10461184000 1089604016 11.739 100 80 148 3 1191622240 86637168 15381056
02:42:22 1394 8192000000 10109125824 10492055552 1103671568 11.872 100 92 173 2 1225147776 102840752 18635456
02:43:28 11277 8192000000 10128314720 10527830016 1111194768 11.944 100 60 114 1 1257404064 132985616 13223680
02:44:46 922 8192000000 10178986144 10580639744 1124786064 12.072 100 87 156 1 1292580896 150615440 17179392
02:45:53 6620 8192000000 10188105856 10589593600 1123786576 12.063 100 71 134 0 1302107184 162969360 15351248
02:47:02 1033 8192000000 10200417744 10603061248 1136792176 12.185 100 94 180 2 1314048368 157346528 19909664
02:48:05 771 8192000000 10254098928 10648297472 1154329744 12.350 100 97 187 0 1365060496 190413024 20317728
02:49:08 839 8192000000 10281916336 10680406016 1161462848 12.417 100 97 191 1 1395908272 213630208 20815216
02:50:14 1030 8192000000 10301766048 10714451968 1177053424 12.563 100 85 165 1 1416926736 221388656 18484656
02:51:17 1379 8192000000 10339628432 10740715520 1182756000 12.616 100 97 187 3 1446017296 242114240 21147056
02:52:23 1013 8192000000 10362108992 10776711168 1186383648 12.650 100 93 180 1 1469588624 263017296 20187680
02:53:27 2465 8192000000 10393614688 10813366272 1185029024 12.637 100 93 176 1 1499897936 294095024 20773888
02:54:32 915 8192000000 10438672896 10855407616 1202457152 12.799 100 98 189 1 1550340256 325857776 22025328
02:55:36 1213 8192000000 10468490464 10886447104 1200470112 12.781 100 99 196 0 1575789376 351927440 23391824
02:56:39 888 8192000000 10487456944 10906918912 1195078800 12.731 100 97 190 0 1594572448 376366896 23126752
02:57:43 1406 8192000000 10494356800 10928291840 1187672032 12.662 100 89 174 1 1611726768 403524400 20530336
02:58:47 1127 8192000000 10504541024 10956918784 1185467296 12.641 100 95 182 2 1611190368 404275568 21447504
02:59:49 1046 8192000000 10529523936 10967031808 1190652784 12.689 100 98 189 1 1636847920 423752464 22442672
03:00:53 1018 8192000000 10523452080 10943545344 1187629824 12.661 100 81 160 0 1633263824 425557056 20076944
03:01:55 3122 8192000000 10543337024 10957885440 1174304352 12.537 100 71 129 0 1645178688 454416016 16458320
03:03:00 1244 8192000000 10574516416 10991169536 1172664544 12.522 100 95 178 2 1676680896 480640976 23375376
03:04:06 6022 8192000000 10574874768 11025293312 1159930144 12.403 99 59 116 0 1665080064 489860448 15289472
03:05:18 22048 8192000000 10497535552 10993557504 1162586944 12.427 100 36 72 1 1642465216 470423104 9455168
03:06:53 28051 8192000000 10398456144 10872311808 1140646832 12.222 100 23 46 0 1516812896 369965920 6200144
03:08:23 22928 8192000000 10269478976 10745696256 1126485152 12.088 99 28 55 0 1357612304 224000960 7126192
03:09:47 39676 8192000000 10163357376 10707611648 1139985776 12.215 100 13 26 0 1305082784 161591392 3505616
03:11:43 51311 8192000000 10082602752 10554359808 1131263792 12.133 99 20 41 0 1174623616 39468608 3891216
03:13:40 102898 8192000000 10128657104 10610315264 1140126608 12.217 100 48 93 2 1232618464 82846240 9645616
03:16:41 56502 8192000000 10092220640 10560548864 1131508080 12.136 99 27 50 1 1179959904 42779824 5672000
03:18:49 92689 8192000000 10118514880 10564820992 1130958256 12.130 99 23 46 0 1190347344 54553680 4835408
03:21:23 20524 8192000000 10110261040 10579468288 1138487936 12.201 100 33 64 3 1223383248 77824640 7070672
03:22:44 29168 8192000000 10134653888 10581626880 1129241568 12.114 98 34 64 0 1215644528 79331280 7071680
03:24:20 71553 8192000000 10161778256 10646507520 1155544016 12.362 99 36 72 0 1303429952 140077840 7808096
03:26:35 27193 8192000000 10186240416 10645176320 1154626912 12.353 100 20 40 0 1292917360 134279136 4011312
03:28:22 70590 8192000000 10159560736 10691260416 1152288032 12.331 100 17 33 0 1322139408 166141728 3709648
03:30:50 14848 8192000000 10081964704 10519117824 1125677216 12.081 99 50 91 0 1170582960 35594656 9311088
03:32:06 28862 8192000000 10046667264 10478702592 1106113024 11.896 97 30 55 0 1132388864 20517072 5758768
03:33:37 26677 8192000000 10146698800 10607087616 1149619328 12.306 100 43 86 0 1237629424 79331568 8678528
03:35:12 93188 8192000000 10118612640 10587029504 1118126432 12.009 98 21 42 1 1169927328 47860064 3940832
03:37:46 48634 8192000000 10112942624 10597736448 1102908048 11.865 97 25 50 0 1210327568 102321984 5097536
03:40:29 77720 8192000000 10081852112 10530447360 1069192432 11.544 95 28 56 0 1153032352 77760032 6079888
03:43:00 51079 8192000000 10047454736 10519035904 1095541776 11.795 98 23 46 0 1160968416 60631712 4794928
03:44:52 98571 8192000000 10027756848 10480381952 1047270528 11.334 94 22 44 1 1101368240 49736320 4361392
03:47:35 6832 8192000000 10033296560 10450702336 1091090576 11.753 99 62 114 1 1130340928 25606752 13643600
03:48:48 73103 8192000000 10045876096 10494410752 1063667696 11.492 97 19 34 1 1109033920 41900576 3465648
03:51:11 70626 8192000000 10031482336 10516037632 1072352416 11.575 98 13 26 0 1158119280 82741408 3025456
03:53:34 43539 8192000000 10076260752 10523324416 1082701440 11.673 99 32 62 1 1166404016 76947408 6755168
03:55:59 48268 8192000000 10059876800 10503544832 1085244224 11.697 98 24 44 0 1141846480 51597520 5004736
03:57:48 34038 8192000000 10089111936 10521636864 1122424320 12.050 100 52 101 0 1173158912 40095120 10639472
03:59:26 40080 8192000000 10121772656 10573729792 1110446416 11.937 98 39 78 1 1204784832 86688416 7650000
04:01:07 32755 8192000000 10121703968 10553626624 1130330720 12.124 100 46 89 0 1215197536 75372768 9494048
04:02:46 38165 8192000000 10119487152 10550837248 1127170336 12.095 100 39 78 0 1215878688 80673680 8034672
04:04:27 13789 8192000000 10107412064 10550468608 1116939728 11.998 99 51 102 0 1191023568 63732256 10351584
04:05:51 25895 8192000000 10131607600 10560061440 1119803312 12.025 99 51 100 0 1219362304 88657248 10901744
04:07:19 105331 8192000000 10065810192 10551476224 1101577088 11.853 97 13 24 1 1151368544 47715024 2076432
04:10:07 98839 8192000000 10087410944 10538147840 1101448096 11.851 97 28 56 1 1174593200 67169152 5975952
04:12:47 44958 8192000000 10062602560 10567958528 1107418352 11.908 98 7 14 0 1177256496 68324192 1513952
04:14:32 66363 8192000000 10100058880 10559885312 1113977648 11.970 98 27 54 0 1187276032 66808704 6489680
04:16:39 46834 8192000000 10072974464 10548453376 1124079856 12.066 99 21 42 1 1169614016 41424752 4109408
04:18:27 83577 8192000000 10087141120 10543263744 1118694256 12.015 98 21 42 0 1162763888 39639312 4430320
04:21:08 44476 8192000000 10098570640 10557960192 1117465904 12.003 98 18 36 0 1192111952 70347088 4298960
04:22:53 41854 8192000000 10133678912 10566860800 1105045920 11.885 97 38 75 1 1208615696 95794592 7775184
04:24:36 41051 8192000000 10061079664 10507378688 1100156464 11.839 97 23 46 0 1134091520 29640592 4294464
04:26:18 30845 8192000000 10082993264 10546667520 1116108768 11.990 98 38 72 1 1189086096 65966512 7010816
04:27:50 7801 8192000000 10097511136 10524200960 1137840240 12.195 100 49 98 0 1187892432 40533360 9518832
04:29:01 28815 8192000000 10150594064 10595594240 1120294848 12.030 98 45 90 0 1203497712 74375520 8827344
04:30:36 82922 8192000000 10039777200 10477023232 1106261232 11.897 98 34 65 0 1150642416 37268080 7113104
04:32:59 74362 8192000000 10086067920 10571104256 1111213296 11.944 98 9 18 0 1182111200 69084592 1813312
04:36:56 39097 8192000000 9233415440 9546244096 367809280 4.296 33 6 12 0 406608192 37943120 855792

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 18-01-2023 04:39:35 to 18-01-2023 04:39:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 168 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-01-2023 04:39:52 to 18-01-2023 04:39:54.

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          225418
MON$OLDEST_ACTIVE               225724
MON$OLDEST_SNAPSHOT             225724
MON$NEXT_TRANSACTION            225724
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                       2062752
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             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2890 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2890 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2890 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2890 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 ---
:807: 
:808: mscs-test-161.budzdorov.ru    Wed Jan 18 04:37:50 2023
:809:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:810: 
--- end of diff output ---

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

18.01.23 04:39:55. 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-01-18 04:39:58 No severe PSQL-related problems occured
18.01.23 04:39:59. End of report.