Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.10.2022 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 15608780 kB
MemAvailable 30466212 kB
Buffers 0 kB
Cached 16074032 kB
SwapCached 2184 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 2022-10-15 10:37:21
DTS_END                         2022-10-15 10:37:21.7670
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/38930
TRN_ID                          208673
ATT_ID                          597
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: 8481.70 25414 1017804
customer order: creation 158.83 478 19060
customer order: refuse 12.76 403 1531
order to supplier: creation 41.69 1377 5003
order to supplier: removal 3.34 149984 401
invoice (draft): creation 41.24 3355 4949
invoice (draft): removal 47.13 1043 5656
invoice accept: apply 39.51 27086 4741
invoice accept: cancel 42.60 1156 5112
customer reserve: creation 3644.38 261 437326
customer reserve: removal 4182.65 92 501918
realization accept: apply 75.92 271 9110
realization accept: cancel 73.28 157 8793
payment from customer: creation 54.06 187 6487
payment from customer: removal 4.88 212 585
payment to supplier: creation 44.75 180 5370
payment to supplier: removal 3.22 109 386
service: total inventory turnovers 3.50 4007 420
service: total monetary turnovers 6.94 355 833
service: aggregate perf. data 0.98 14481 118
service: refresh index statistics 0.04 303084 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 15-10-2022 10:38:35 to 15-10-2022 10:38:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6152
TEST_TIME 2 5577
TEST_TIME 3 5380
TEST_TIME 4 5637
TEST_TIME 5 5901
TEST_TIME 6 6112
TEST_TIME 7 6251
TEST_TIME 8 6690
TEST_TIME 9 6719
TEST_TIME 10 6790
TEST_TIME 11 7052
TEST_TIME 12 7277
TEST_TIME 13 7459
TEST_TIME 14 7444
TEST_TIME 15 7423
TEST_TIME 16 7347
TEST_TIME 17 7312
TEST_TIME 18 7425
TEST_TIME 19 7497
TEST_TIME 20 7599
TEST_TIME 21 7719
TEST_TIME 22 7977
TEST_TIME 23 8503
TEST_TIME 24 8642
TEST_TIME 25 9014
TEST_TIME 26 9234
TEST_TIME 27 9596
TEST_TIME 28 9582
TEST_TIME 29 10061
TEST_TIME 30 10586
TEST_TIME 31 10538
TEST_TIME 32 10352
TEST_TIME 33 10436
TEST_TIME 34 10164
TEST_TIME 35 10064
TEST_TIME 36 10223
TEST_TIME 37 10077
TEST_TIME 38 9906
TEST_TIME 39 9846
TEST_TIME 40 9778
TEST_TIME 41 9632
TEST_TIME 42 9482
TEST_TIME 43 9409
TEST_TIME 44 9222
TEST_TIME 45 9281
TEST_TIME 46 9346
TEST_TIME 47 9294
TEST_TIME 48 9313
TEST_TIME 49 9206
TEST_TIME 50 9140
TEST_TIME 51 9136
TEST_TIME 52 9164
TEST_TIME 53 9117
TEST_TIME 54 9078
TEST_TIME 55 9114
TEST_TIME 56 9065
TEST_TIME 57 8986
TEST_TIME 58 9167
TEST_TIME 59 9295
TEST_TIME 60 9152
TEST_TIME 61 9018
TEST_TIME 62 8917
TEST_TIME 63 8953
TEST_TIME 64 8867
TEST_TIME 65 8823
TEST_TIME 66 8758
TEST_TIME 67 8768
TEST_TIME 68 8780
TEST_TIME 69 8763
TEST_TIME 70 8763
TEST_TIME 71 8733
TEST_TIME 72 8704
TEST_TIME 73 8816
TEST_TIME 74 8744
TEST_TIME 75 8684
TEST_TIME 76 8738
TEST_TIME 77 8766
TEST_TIME 78 8870
TEST_TIME 79 8940
TEST_TIME 80 8943
TEST_TIME 81 8921
TEST_TIME 82 8915
TEST_TIME 83 8870
TEST_TIME 84 8873
TEST_TIME 85 8774
TEST_TIME 86 8839
TEST_TIME 87 8768
TEST_TIME 88 8755
TEST_TIME 89 8719
TEST_TIME 90 8657
TEST_TIME 91 8607
TEST_TIME 92 8561
TEST_TIME 93 8646
TEST_TIME 94 8627
TEST_TIME 95 8613
TEST_TIME 96 8571
TEST_TIME 97 8584
TEST_TIME 98 8582
TEST_TIME 99 8613
TEST_TIME 100 8660
TEST_TIME 101 8685
TEST_TIME 102 8670
TEST_TIME 103 8712
TEST_TIME 104 8748
TEST_TIME 105 8766
TEST_TIME 106 8777
TEST_TIME 107 8779
TEST_TIME 108 8714
TEST_TIME 109 8674
TEST_TIME 110 8670
TEST_TIME 111 8627
TEST_TIME 112 8607
TEST_TIME 113 8576
TEST_TIME 114 8523
TEST_TIME 115 8483
TEST_TIME 116 8505
TEST_TIME 117 8528
TEST_TIME 118 8522
TEST_TIME 119 8540
TEST_TIME 120 8504

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-10-2022 10:38:39 to 15-10-2022 10:38:42.

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 1420521 1420521 0 0.00 0 28340 13 0 0
doc_list_biud 1420510 1420510 0 0.00 0 42305 39 0 0
sp_add_invoice_to_stock 4815 4741 74 1.53 47 268040 27086 0 74
sp_cancel_adding_invoice 5112 5112 0 0.00 6 69377 1157 0 0
sp_cancel_client_order 1531 1531 0 0.00 6 15485 403 0 0
sp_cancel_customer_reserve 501918 501918 0 0.00 1 29097 93 0 0
sp_cancel_pay_from_customer 585 585 0 0.00 4 24742 212 0 0
sp_cancel_pay_to_supplier 386 386 0 0.00 4 3592 110 0 0
sp_cancel_supplier_invoice 5661 5656 5 0.08 3 42314 1043 0 5
sp_cancel_supplier_order 401 401 0 0.00 8 1238825 149985 0 0
sp_cancel_write_off 8793 8793 0 0.00 2 13487 158 0 0
sp_client_order 19060 19060 0 0.00 5 50981 479 0 0
sp_customer_reserve 437326 437326 0 0.00 1 48129 261 0 0
sp_fill_shopping_cart 28996 28996 0 0.00 2 25071 85 0 0
sp_fill_shopping_cart_clo_res 437295 437295 0 0.00 0 18636 20 0 0
sp_get_clo_for_invoice 4699 4699 0 0.00 0 24301 363 0 0
sp_kill_cost_storno 14876 14876 0 0.00 0 28329 77 0 0
sp_kill_qstorno_ret_qs2qd 1018686 1018686 0 0.00 0 42303 41 0 0
sp_kill_qty_storno 537218 537218 0 0.00 0 42305 95 0 0
sp_lock_selected_doc 18948 18948 0 0.00 0 10775 9 0 0
sp_make_cost_storno 25665 25665 0 0.00 0 15563 73 0 0
sp_multiply_rows_for_pdistr 25665 25665 0 0.00 0 8429 37 0 0
sp_multiply_rows_for_qdistr 19058 19058 0 0.00 0 13555 193 0 0
sp_pay_from_customer 6487 6487 0 0.00 5 28193 187 0 0
sp_pay_to_supplier 5370 5370 0 0.00 7 16512 180 0 0
sp_payment_common 11856 11856 0 0.00 2 9800 43 0 0
sp_qd_handle_on_cancel_clo 1531 1531 0 0.00 0 10214 311 0 0
sp_qd_handle_on_invoice_upd_sts 9811 9811 0 0.00 0 25314 485 0 0
sp_qd_handle_on_reserve_upd_sts 9108 9108 0 0.00 0 12811 59 0 0
sp_reserve_write_off 9110 9110 0 0.00 8 17310 271 0 0
sp_supplier_invoice 4949 4949 0 0.00 8 90343 3356 0 0
sp_supplier_order 5003 5003 0 0.00 7 39880 1377 0 0
srv_aggregate_perf_data 118 118 0 0.00 2384 232334 14481 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 955 137284 42692 0 0
srv_find_qd_qs_mism 972467 972467 0 0.00 0 13823 11 0 0
srv_make_invnt_saldo 420 420 0 0.00 65 76025 4007 0 0
srv_make_money_saldo 833 833 0 0.00 1 16734 356 0 0
srv_recalc_idx_stat 5 5 0 0.00 73638 532899 303084 0 0
t$perf-abend:sp_add_invoice_to_stock 74 74 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4747 4747 0 0.00 1 12511 883 0 0
t$perf-norm:sp_cancel_adding_invoice 362 362 0 0.00 1 6185 474 0 0
t$perf-norm:sp_cancel_client_order 1531 1531 0 0.00 0 3453 31 0 0
t$perf-norm:sp_cancel_customer_reserve 674 674 0 0.00 0 2009 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 585 585 0 0.00 0 753 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 386 386 0 0.00 0 1624 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 352 352 0 0.00 0 823 28 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 104391 6097 0 0
t$perf-norm:sp_cancel_write_off 857 857 0 0.00 0 3851 21 0 0
t$perf-norm:sp_client_order 19061 19061 0 0.00 0 9317 35 0 0
t$perf-norm:sp_customer_reserve 914 914 0 0.00 0 2628 15 0 0
t$perf-norm:sp_pay_from_customer 6487 6487 0 0.00 0 9202 18 0 0
t$perf-norm:sp_pay_to_supplier 5370 5370 0 0.00 0 8940 19 0 0
t$perf-norm:sp_reserve_write_off 9110 9110 0 0.00 0 9608 25 0 0
t$perf-norm:sp_supplier_invoice 4949 4949 0 0.00 0 5075 31 0 0
t$perf-norm:sp_supplier_order 5003 5003 0 0.00 0 4294 30 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 576 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 258 13 0 0
t$perf-norm:srv_make_invnt_saldo 420 420 0 0.00 0 10568 49 0 0
t$perf-norm:srv_make_money_saldo 833 833 0 0.00 0 1052 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 59 19 0 0
v_add_invoice_to_stock 9534 9386 148 1.55 0 11561 26 0 148
v_all_customers 34820 34820 0 0.00 0 95 3 0 0
v_all_suppliers 27518 27518 0 0.00 0 86 4 0 0
v_all_wares 38107 38107 0 0.00 0 86 4 0 0
v_cancel_adding_invoice 724 724 0 0.00 0 5245 21 0 0
v_cancel_client_order 3062 3062 0 0.00 0 7183 15 0 0
v_cancel_customer_prepayment 1170 1170 0 0.00 0 24682 50 0 0
v_cancel_customer_reserve 1348 1348 0 0.00 0 9419 21 0 0
v_cancel_payment_to_supplier 772 772 0 0.00 0 348 9 0 0
v_cancel_supplier_invoice 714 704 10 1.40 0 1979 25 0 10
v_cancel_supplier_order 802 802 0 0.00 0 1520 12 0 0
v_cancel_write_off 1714 1714 0 0.00 0 1528 22 0 0
v_max_id_clo_ord 4996 4996 0 0.00 0 404 4 0 0
v_max_id_clo_res 914 914 0 0.00 0 10792 20 0 0
v_max_id_ord_sup 4940 4940 0 0.00 0 9985 12 0 0
v_max_non_paid_invoice 1544 1544 0 0.00 0 522 8 0 0
v_max_non_paid_realizn 3255 3255 0 0.00 0 1876 7 0 0
v_min_id_clo_ord 4996 4996 0 0.00 0 1307 7 0 0
v_min_id_clo_res 914 914 0 0.00 0 6815 21 0 0
v_min_id_ord_sup 4940 4940 0 0.00 0 6090 10 0 0
v_min_non_paid_invoice 5369 5369 0 0.00 0 9792 13 0 0
v_min_non_paid_realizn 6487 6487 0 0.00 0 6139 18 0 0
v_our_firm 9746 9746 0 0.00 0 51 4 0 0
v_reserve_write_off 18214 18214 0 0.00 0 17097 12 0 0
x_lock_depdocs_on_canc_invoice 10416 10416 0 0.00 0 11973 124 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 2539 333 0 0
x_make_qstorno_1000_1200 5003 5003 0 0.00 2 39872 1224 0 0
x_make_qstorno_1200_2000 4949 4949 0 0.00 4 90330 3093 0 0
x_make_qstorno_2100_3300 437326 437326 0 0.00 0 46973 229 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 15-10-2022 10:38:43 to 15-10-2022 10:38:48.

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:25 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:26 21654 8192000000 9707893808 10004193280 785024720 8.744 100 82 164 0 812225360 6969328 20231312
08:09:54 25198 8192000000 9761782336 10068525056 832115072 9.221 100 87 174 0 865714720 12465824 21133824
08:11:24 15461 8192000000 9801050896 10121625600 866041968 9.561 100 80 160 0 910487392 26199792 18245632
08:12:43 7919 8192000000 9858726832 10191749120 917867296 10.075 100 79 156 0 970174944 35462928 16844720
08:13:56 3360 8192000000 9920581632 10267062272 965961904 10.547 100 83 164 0 1036561168 53322384 17276880
08:15:02 1870 8192000000 9963571600 10317307904 994449936 10.825 100 84 164 2 1083352096 72035712 16866448
08:16:06 989 8192000000 9992188384 10351173632 1017447424 11.047 100 90 178 1 1113853904 78183888 18222592
08:17:09 888 8192000000 10016862064 10388148224 1044538704 11.308 100 96 189 1 1142975408 79745408 18691296
08:18:11 1022 8192000000 10034321200 10410106880 1061286224 11.469 100 97 184 3 1157706896 76942368 19478304
08:19:13 1506 8192000000 10043548496 10420707328 1070044016 11.553 100 91 177 1 1163391824 75561776 17786032
08:20:17 1218 8192000000 10066877408 10446774272 1074547904 11.595 100 100 193 0 1188441152 95000624 18892624
08:21:20 1352 8192000000 10032911216 10416873472 1079140240 11.639 100 73 143 2 1158070752 63749840 15180672
08:22:22 1016 8192000000 10029558384 10414977024 1082645744 11.673 100 91 173 2 1153291264 52423776 18221744
08:23:25 1150 8192000000 10068610640 10457280512 1090165584 11.744 100 92 174 2 1188079408 80316624 17597200
08:24:28 1397 8192000000 10070417376 10455838720 1098809136 11.826 100 97 186 1 1191424400 73583520 19031744
08:25:30 1005 8192000000 10097676288 10486452224 1100165104 11.839 100 87 166 0 1218847456 101405728 17276624
08:26:32 1426 8192000000 10059704384 10459447296 1100984624 11.847 100 72 142 0 1185316368 69820736 14511008
08:27:34 9163 8192000000 10055217904 10462339072 1102417920 11.861 100 69 131 0 1177694240 61821248 13455072
08:28:45 1070 8192000000 10063658576 10455396352 1097286048 11.812 100 87 156 1 1190228224 75419408 17522768
08:29:48 1315 8192000000 10069849376 10460151808 1100427792 11.842 100 92 173 0 1191206752 74029808 16749152
08:30:51 1029 8192000000 10040394304 10418925568 1071431808 11.566 100 98 187 1 1163856416 72169696 20254912
08:31:52 4263 8192000000 10016240160 10399346688 1049155520 11.353 98 61 113 2 1129649072 69439264 11054288
08:32:58 2751 8192000000 9996041920 10374467584 1057087632 11.429 100 81 148 3 1120500448 50080480 13332336
08:34:01 5298 8192000000 9974106944 10348339200 1015957520 11.033 98 72 141 2 1088613616 58358032 14298064
08:35:07 1142 8192000000 9977462160 10348568576 1027590848 11.145 100 86 170 2 1100650000 56824912 16234240
08:36:10 3483 8192000000 10014662496 10400292864 1034472976 11.212 100 64 126 0 1135778496 88782288 12523232
08:37:15 908 8192000000 9985834576 10362302464 1045992720 11.322 99 85 158 3 1107511296 45306608 16211968
08:38:22 2004 8192000000 10011406064 10395250688 1075784208 11.607 100 85 152 1 1133836016 42627904 15423904
08:39:42 1564 8192000000 10065205216 10445066240 1083707904 11.683 100 88 166 4 1184853072 83016256 18128912
08:40:47 4616 8192000000 10081790624 10472144896 1090522592 11.748 100 76 143 0 1204068464 97905696 15640176
08:42:01 5722 8192000000 10105320096 10501246976 1099018960 11.828 100 67 125 2 1224798352 112670448 13108944
08:43:14 1476 8192000000 10148628240 10543435776 1115287040 11.982 100 89 172 2 1257107632 125079376 16741216
08:44:21 4811 8192000000 10157784752 10567032832 1122620416 12.052 100 68 133 0 1273155536 135141200 15393920
08:45:30 3052 8192000000 10135169296 10538737664 1133620144 12.155 100 75 148 0 1256325616 106712336 15993136
08:46:37 2724 8192000000 10198242944 10595446784 1145291120 12.265 100 88 167 2 1314104672 150749856 18063696
08:47:44 947 8192000000 10213007168 10604912640 1151055328 12.319 100 97 180 1 1325650928 154658992 19936608
08:48:48 1105 8192000000 10259002928 10660081664 1165933200 12.459 100 96 192 0 1372696816 185648976 21114640
08:49:51 1233 8192000000 10278387488 10681978880 1179064416 12.581 100 88 168 0 1385627312 187179920 19382976
08:50:54 1416 8192000000 10306278368 10707333120 1176179008 12.555 100 80 150 1 1411547072 218722816 16645248
08:52:01 1075 8192000000 10350459056 10748928000 1180290832 12.593 100 96 181 2 1457915936 256990208 20634896
08:53:07 2158 8192000000 10388603280 10792189952 1190042048 12.684 100 92 183 0 1500983216 289269808 21671360
08:54:14 1344 8192000000 10420775536 10844753920 1193093536 12.712 100 90 171 0 1529231200 315336352 20801312
08:55:20 2737 8192000000 10449530064 10897768448 1193232224 12.713 100 91 173 0 1553954896 339495936 21226736
08:56:25 1971 8192000000 10490318640 10911764480 1195998048 12.739 100 96 188 1 1593577584 374991680 22587856
08:57:31 3096 8192000000 10508549792 10921074688 1187328048 12.658 100 84 168 1 1612832528 404789040 20715440
08:58:38 1858 8192000000 10552393120 10978811904 1181168336 12.601 100 88 173 0 1658794864 455138368 22488160
08:59:49 2100 8192000000 10530435328 10993041408 1171548736 12.511 99 81 152 0 1630570208 438962048 20059424
09:00:56 9098 8192000000 10427379328 10886889472 1161968336 12.422 99 47 94 0 1526468736 351301264 13199136
09:02:07 5732 8192000000 10415345024 10884677632 1180045904 12.591 100 58 111 0 1517954400 324570224 13338272
09:03:18 16587 8192000000 10363104672 10816069632 1168910240 12.487 100 44 88 0 1471164128 292007200 10246688
09:04:36 19060 8192000000 10340079136 10806505472 1171267408 12.509 100 39 76 1 1465008864 284362720 9378736
09:06:03 50061 8192000000 10265202976 10720948224 1150464576 12.314 99 20 40 0 1391532624 235883584 5184464
09:07:56 40586 8192000000 10155312208 10601414656 1107431104 11.908 96 36 68 1 1219747872 104461648 7855120
09:09:51 25685 8192000000 10106645216 10564382720 1120817408 12.035 97 27 49 0 1192394064 65970176 5606480
09:11:17 93625 8192000000 10165868992 10647322624 1144001392 12.253 99 22 44 0 1247927264 98253440 5672432
09:13:54 54072 8192000000 10073096560 10555768832 1124858144 12.073 98 11 20 1 1173384048 46452576 2073328
09:15:49 87570 8192000000 10086435968 10555637760 1127055760 12.094 98 27 53 0 1192622400 60196720 5369920
09:18:26 69902 8192000000 10091689760 10549301248 1127082704 12.094 98 18 36 0 1169959040 39212048 3664288
09:20:41 69556 8192000000 10116600752 10572140544 1131300432 12.134 99 16 32 1 1228868144 93864304 3703408
09:22:54 122636 8192000000 10073893664 10582822912 1106492368 11.899 98 10 20 0 1164738896 56535904 1710624
09:25:57 56516 8192000000 10080562448 10521600000 1068512272 11.538 94 16 30 0 1116314560 45074960 2727328
09:28:01 30996 8192000000 10095201328 10530627584 1121509008 12.041 99 51 99 1 1168353888 36425584 10419296
09:29:35 64823 8192000000 10070148608 10515025920 1035860000 11.225 92 33 66 0 1096594944 54147744 6587200
09:31:42 30926 8192000000 10099638384 10529763328 1114166592 11.972 100 36 71 0 1180338784 59264496 6907696
09:33:16 52072 8192000000 10113502512 10542039040 1129532272 12.117 100 39 77 2 1181328208 43149360 8646576
09:35:23 50289 8192000000 10102854352 10656182272 1125307136 12.077 99 24 48 0 1259560496 128822784 5430576
09:37:14 117013 8192000000 10051443056 10523758592 1124074704 12.065 99 23 41 0 1152561280 23703488 4783088
09:40:17 68787 8192000000 10112241040 10613886976 1140740208 12.222 100 16 32 0 1235146912 90690224 3716480
09:42:26 57583 8192000000 10105197456 10566774784 1111978896 11.951 97 21 43 0 1182421472 65962336 4480240
09:44:25 76799 8192000000 10075085504 10566025216 1111226768 11.944 98 11 21 0 1181108816 67784640 2097408
09:46:48 69214 8192000000 10085891024 10532298752 1104761952 11.883 97 16 29 0 1161596992 53463168 3371872
09:48:59 100068 8192000000 10139955216 10604343296 1102317024 11.860 96 17 34 0 1193625264 87519536 3788704
09:51:42 37431 8192000000 10158620208 10607702016 1153052336 12.338 100 26 51 0 1249289472 91035904 5201232
09:53:20 65585 8192000000 10158511728 10605260800 1156697376 12.372 100 26 53 0 1280173040 117922384 5553280
09:55:27 72274 8192000000 10114137488 10649825280 1159282640 12.397 100 20 38 0 1275403504 111666128 4454736
09:57:39 68946 8192000000 10099325616 10541793280 1146498528 12.277 99 22 44 0 1181387536 30055440 4833568
09:59:49 26175 8192000000 10125930176 10556694528 1107738640 11.911 97 46 92 0 1210581440 93776256 9066544
10:01:16 137283 8192000000 10104003616 10580045824 1125731376 12.081 99 43 86 0 1207843456 73054384 9057696
10:05:58 40505 8192000000 10083832320 10555002880 1146039008 12.272 100 13 25 0 1197160128 48678048 2443072
10:07:41 104255 8192000000 10141576656 10599596032 1139596304 12.212 99 18 36 0 1240582832 96963872 4022656
10:10:25 62095 8192000000 10084098736 10585862144 1134619424 12.165 99 10 16 0 1192901648 56841888 1440336
10:12:27 62973 8192000000 10113474736 10556338176 1107869904 11.912 96 21 42 0 1163561312 51209040 4482368
10:14:39 55993 8192000000 10101143568 10562613248 1095409712 11.794 96 26 47 1 1180834352 80488016 4936624
10:16:35 62765 8192000000 10101204256 10543640576 1084553952 11.691 96 14 28 0 1169701616 82124992 3022672
10:18:38 32033 8192000000 10080507392 10508980224 1077264960 11.621 96 29 57 0 1147160816 64493856 5402000
10:20:12 46025 8192000000 10093144480 10548436992 1107797168 11.912 99 33 66 0 1173984048 59765888 6420992
10:21:59 77268 8192000000 10081293472 10504806400 1091769152 11.759 97 43 85 1 1136722208 36006464 8946592
10:24:22 94479 8192000000 10098717040 10534252544 1127790880 12.101 99 50 100 0 1199922560 61123680 11008000
10:27:27 95829 8192000000 10071282480 10544115712 1125083440 12.075 99 10 20 1 1184418128 57300688 2034000
10:30:24 45139 8192000000 10092029920 10564231168 1128617664 12.108 99 23 44 0 1182060720 48741696 4701360
10:32:40 73428 8192000000 10117384304 10634018816 1104804736 11.883 97 19 38 0 1225046176 115767680 4473760
10:35:15 49895 8192000000 10120666928 10576441344 1119717360 12.024 98 22 44 0 1204700128 80263904 4718864
10:37:15 20769 8192000000 9069671200 9340813312 219867104 2.613 20 2 4 0 244320432 24137264 316064

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-10-2022 10:38:50 to 15-10-2022 10:39:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 74 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-10-2022 10:39:06 to 15-10-2022 10:39:08.

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          208944
MON$OLDEST_ACTIVE               208945
MON$OLDEST_SNAPSHOT             208945
MON$NEXT_TRANSACTION            208945
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                       2019136
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             758
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2859 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2859 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2859 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2859 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 ---
:43: 
:44: mscs-test-161.budzdorov.ru    Sat Oct 15 10:37:38 2022
:45:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:46: 
--- end of diff output ---

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

15.10.22 10:39:09. 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-10-15 10:39:12 No severe PSQL-related problems occured
15.10.22 10:39:13. End of report.