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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14716372 kB
MemAvailable 30376804 kB
Buffers 0 kB
Cached 16948624 kB
SwapCached 10252 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-01 13:37:22
DTS_END                         2023-01-01 13:37:22.5670
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/54882
TRN_ID                          218561
ATT_ID                          625
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: 9216.14 27377 1105937
customer order: creation 172.68 522 20721
customer order: refuse 15.18 487 1822
order to supplier: creation 45.75 1449 5490
order to supplier: removal 3.55 179157 426
invoice (draft): creation 45.88 3320 5505
invoice (draft): removal 51.09 1053 6131
invoice accept: apply 42.60 29178 5112
invoice accept: cancel 46.06 1460 5527
customer reserve: creation 3987.63 273 478515
customer reserve: removal 4504.90 111 540588
realization accept: apply 84.95 328 10194
realization accept: cancel 85.22 198 10226
payment from customer: creation 59.13 210 7096
payment from customer: removal 5.83 143 699
payment to supplier: creation 49.02 214 5882
payment to supplier: removal 3.53 158 424
service: total inventory turnovers 4.18 4446 502
service: total monetary turnovers 7.89 384 947
service: aggregate perf. data 1.05 16363 126
service: refresh index statistics 0.03 308077 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 01-01-2023 13:38:29 to 01-01-2023 13:38:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8108
TEST_TIME 2 5812
TEST_TIME 3 5770
TEST_TIME 4 5871
TEST_TIME 5 5816
TEST_TIME 6 5715
TEST_TIME 7 6068
TEST_TIME 8 6397
TEST_TIME 9 6163
TEST_TIME 10 6130
TEST_TIME 11 6458
TEST_TIME 12 6647
TEST_TIME 13 6824
TEST_TIME 14 6839
TEST_TIME 15 6850
TEST_TIME 16 7028
TEST_TIME 17 7430
TEST_TIME 18 7401
TEST_TIME 19 7757
TEST_TIME 20 7667
TEST_TIME 21 7813
TEST_TIME 22 8072
TEST_TIME 23 8310
TEST_TIME 24 8410
TEST_TIME 25 8551
TEST_TIME 26 8584
TEST_TIME 27 8887
TEST_TIME 28 8923
TEST_TIME 29 8953
TEST_TIME 30 8906
TEST_TIME 31 9010
TEST_TIME 32 9364
TEST_TIME 33 9304
TEST_TIME 34 9255
TEST_TIME 35 9301
TEST_TIME 36 9632
TEST_TIME 37 9946
TEST_TIME 38 10196
TEST_TIME 39 10356
TEST_TIME 40 10809
TEST_TIME 41 10675
TEST_TIME 42 10682
TEST_TIME 43 10697
TEST_TIME 44 10646
TEST_TIME 45 10748
TEST_TIME 46 10574
TEST_TIME 47 10449
TEST_TIME 48 10415
TEST_TIME 49 10269
TEST_TIME 50 10215
TEST_TIME 51 10272
TEST_TIME 52 10125
TEST_TIME 53 9964
TEST_TIME 54 9961
TEST_TIME 55 9902
TEST_TIME 56 9925
TEST_TIME 57 9876
TEST_TIME 58 9856
TEST_TIME 59 9812
TEST_TIME 60 9756
TEST_TIME 61 9810
TEST_TIME 62 9750
TEST_TIME 63 10021
TEST_TIME 64 9941
TEST_TIME 65 9898
TEST_TIME 66 9858
TEST_TIME 67 9902
TEST_TIME 68 9899
TEST_TIME 69 9898
TEST_TIME 70 9919
TEST_TIME 71 9901
TEST_TIME 72 9811
TEST_TIME 73 9880
TEST_TIME 74 9931
TEST_TIME 75 10023
TEST_TIME 76 10002
TEST_TIME 77 9958
TEST_TIME 78 9932
TEST_TIME 79 9851
TEST_TIME 80 9844
TEST_TIME 81 9772
TEST_TIME 82 9682
TEST_TIME 83 9693
TEST_TIME 84 9679
TEST_TIME 85 9648
TEST_TIME 86 9630
TEST_TIME 87 9588
TEST_TIME 88 9610
TEST_TIME 89 9596
TEST_TIME 90 9572
TEST_TIME 91 9548
TEST_TIME 92 9526
TEST_TIME 93 9505
TEST_TIME 94 9478
TEST_TIME 95 9477
TEST_TIME 96 9492
TEST_TIME 97 9501
TEST_TIME 98 9513
TEST_TIME 99 9517
TEST_TIME 100 9495
TEST_TIME 101 9517
TEST_TIME 102 9479
TEST_TIME 103 9574
TEST_TIME 104 9568
TEST_TIME 105 9539
TEST_TIME 106 9468
TEST_TIME 107 9448
TEST_TIME 108 9387
TEST_TIME 109 9402
TEST_TIME 110 9365
TEST_TIME 111 9317
TEST_TIME 112 9293
TEST_TIME 113 9263
TEST_TIME 114 9242
TEST_TIME 115 9241
TEST_TIME 116 9263
TEST_TIME 117 9253
TEST_TIME 118 9251
TEST_TIME 119 9254
TEST_TIME 120 9242

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-01-2023 13:38:33 to 01-01-2023 13:38:36.

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 1545191 1545191 0 0.00 0 37868 16 0 0
doc_list_biud 1545180 1545180 0 0.00 0 35751 45 0 0
sp_add_invoice_to_stock 5198 5112 86 1.65 64 209761 29179 0 86
sp_cancel_adding_invoice 5527 5527 0 0.00 6 96066 1461 0 0
sp_cancel_client_order 1822 1822 0 0.00 5 18351 487 0 0
sp_cancel_customer_reserve 540588 540588 0 0.00 1 35753 112 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 4 4139 143 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 4 9586 159 0 0
sp_cancel_supplier_invoice 6142 6131 11 0.17 2 21082 1053 0 11
sp_cancel_supplier_order 426 426 0 0.00 9 1715613 179158 0 0
sp_cancel_write_off 10226 10226 0 0.00 2 15904 199 0 0
sp_client_order 20721 20721 0 0.00 6 18245 522 0 0
sp_customer_reserve 478515 478515 0 0.00 1 110987 274 0 0
sp_fill_shopping_cart 31709 31709 0 0.00 1 16935 91 0 0
sp_fill_shopping_cart_clo_res 478479 478479 0 0.00 0 18853 19 0 0
sp_get_clo_for_invoice 5069 5069 0 0.00 0 33354 264 0 0
sp_kill_cost_storno 16876 16876 0 0.00 0 37863 94 0 0
sp_kill_qstorno_ret_qs2qd 1097959 1097959 0 0.00 0 35747 48 0 0
sp_kill_qty_storno 579981 579981 0 0.00 0 35751 110 0 0
sp_lock_selected_doc 21198 21198 0 0.00 0 7837 8 0 0
sp_make_cost_storno 28241 28241 0 0.00 0 19109 91 0 0
sp_multiply_rows_for_pdistr 28240 28240 0 0.00 0 10089 43 0 0
sp_multiply_rows_for_qdistr 20721 20721 0 0.00 0 15760 184 0 0
sp_pay_from_customer 7096 7096 0 0.00 5 16125 210 0 0
sp_pay_to_supplier 5882 5882 0 0.00 6 9659 214 0 0
sp_payment_common 12977 12977 0 0.00 2 8009 53 0 0
sp_qd_handle_on_cancel_clo 1822 1822 0 0.00 1 18343 393 0 0
sp_qd_handle_on_invoice_upd_sts 10595 10595 0 0.00 0 10787 606 0 0
sp_qd_handle_on_reserve_upd_sts 10193 10193 0 0.00 0 14538 67 0 0
sp_reserve_write_off 10194 10194 0 0.00 8 15593 328 0 0
sp_supplier_invoice 5505 5505 0 0.00 11 107622 3321 0 0
sp_supplier_order 5490 5490 0 0.00 9 27014 1450 0 0
srv_aggregate_perf_data 126 126 0 0.00 2799 102140 16363 0 0
srv_fill_mon_cache_memory 76 76 0 0.00 756 122125 28098 0 0
srv_find_qd_qs_mism 1055548 1055548 0 0.00 0 16382 12 0 0
srv_make_invnt_saldo 502 502 0 0.00 76 48424 4447 0 0
srv_make_money_saldo 947 947 0 0.00 1 16762 385 0 0
srv_recalc_idx_stat 4 4 0 0.00 40578 522766 308077 0 0
t$perf-abend:sp_add_invoice_to_stock 86 86 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5120 5120 0 0.00 1 9801 1117 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 1 10137 652 0 0
t$perf-norm:sp_cancel_client_order 1822 1822 0 0.00 0 4131 36 0 0
t$perf-norm:sp_cancel_customer_reserve 741 741 0 0.00 0 1062 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 2400 28 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 919 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 1220 47 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 106029 8081 0 0
t$perf-norm:sp_cancel_write_off 993 993 0 0.00 0 2335 23 0 0
t$perf-norm:sp_client_order 20722 20722 0 0.00 0 4797 43 0 0
t$perf-norm:sp_customer_reserve 1037 1037 0 0.00 0 8501 24 0 0
t$perf-norm:sp_pay_from_customer 7096 7096 0 0.00 0 8499 24 0 0
t$perf-norm:sp_pay_to_supplier 5882 5882 0 0.00 0 2224 21 0 0
t$perf-norm:sp_reserve_write_off 10195 10195 0 0.00 0 4154 24 0 0
t$perf-norm:sp_supplier_invoice 5506 5506 0 0.00 0 3524 37 0 0
t$perf-norm:sp_supplier_order 5490 5490 0 0.00 0 4809 35 0 0
t$perf-norm:srv_aggregate_perf_data 126 126 0 0.00 0 381 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 76 76 0 0.00 0 89 5 0 0
t$perf-norm:srv_make_invnt_saldo 502 502 0 0.00 0 2155 35 0 0
t$perf-norm:srv_make_money_saldo 947 947 0 0.00 0 618 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 191 66 0 0
v_add_invoice_to_stock 10294 10122 172 1.67 0 9249 35 0 172
v_all_customers 37852 37852 0 0.00 0 68 5 0 0
v_all_suppliers 30598 30598 0 0.00 0 202 5 0 0
v_all_wares 41440 41440 0 0.00 0 69 5 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 1075 19 0 0
v_cancel_client_order 3644 3644 0 0.00 0 8487 15 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 903 13 0 0
v_cancel_customer_reserve 1482 1482 0 0.00 0 1191 13 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 825 13 0 0
v_cancel_supplier_invoice 874 852 22 2.51 0 2236 24 0 22
v_cancel_supplier_order 852 852 0 0.00 0 2407 19 0 0
v_cancel_write_off 1986 1986 0 0.00 0 3486 29 0 0
v_max_id_clo_ord 5488 5488 0 0.00 0 224 6 0 0
v_max_id_clo_res 1037 1037 0 0.00 0 4982 20 0 0
v_max_id_ord_sup 5499 5499 0 0.00 0 5779 9 0 0
v_max_non_paid_invoice 1569 1569 0 0.00 0 362 9 0 0
v_max_non_paid_realizn 3613 3613 0 0.00 0 2292 9 0 0
v_min_id_clo_ord 5488 5488 0 0.00 0 2877 10 0 0
v_min_id_clo_res 1037 1037 0 0.00 0 10473 23 0 0
v_min_id_ord_sup 5499 5499 0 0.00 0 4772 9 0 0
v_min_non_paid_invoice 5881 5881 0 0.00 0 8005 17 0 0
v_min_non_paid_realizn 7095 7095 0 0.00 0 6124 21 0 0
v_our_firm 10554 10554 0 0.00 0 71 6 0 0
v_reserve_write_off 20386 20386 0 0.00 0 4610 14 0 0
x_lock_depdocs_on_canc_invoice 11232 11232 0 0.00 0 27111 146 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 12754 413 0 0
x_make_qstorno_1000_1200 5490 5490 0 0.00 3 26993 1252 0 0
x_make_qstorno_1200_2000 5505 5505 0 0.00 5 90669 3093 0 0
x_make_qstorno_2100_3300 478514 478514 0 0.00 0 110985 239 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 01-01-2023 13:38:37 to 01-01-2023 13:38:42.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:27 32411 8192000000 9728110320 10027892736 795668912 8.852 100 86 172 0 825007328 7850992 21487424
11:10:03 15969 8192000000 9760096960 10067300352 836221184 9.262 100 87 174 0 869567440 12118208 21228048
11:11:25 10273 8192000000 9790405088 10109583360 865099312 9.551 100 79 158 0 900822928 17429664 18293952
11:12:41 12718 8192000000 9849718240 10180251648 909199728 9.989 100 81 162 0 962026464 34797136 18029600
11:14:00 5022 8192000000 9900139136 10240802816 939464368 10.288 100 85 164 0 1010398800 53076128 17858304
11:15:09 2071 8192000000 9951530320 10300362752 982733488 10.711 100 90 170 0 1061685456 61569632 17382336
11:16:13 2203 8192000000 9980112640 10335223808 1007726576 10.953 100 94 187 0 1101768112 74899872 19141664
11:17:17 1448 8192000000 10012953584 10378158080 1032327968 11.191 100 93 184 0 1134716848 83414304 18974576
11:18:21 810 8192000000 10010684816 10380644352 1049892320 11.360 100 94 173 1 1130781376 63674272 17214784
11:19:23 1124 8192000000 10067933696 10444652544 1064708112 11.502 100 97 193 0 1186864976 103272016 18884848
11:20:25 1107 8192000000 10040281952 10418814976 1071425824 11.566 100 95 187 2 1161337232 70359056 19552352
11:21:27 1306 8192000000 10049200992 10435067904 1079017920 11.638 100 79 155 0 1174669136 79976528 15674688
11:22:29 4120 8192000000 10041546368 10433232896 1080431664 11.652 100 61 122 1 1167983824 75854640 11697520
11:23:35 2813 8192000000 10041253472 10433273856 1085990096 11.705 100 64 123 2 1162770272 64468176 12312000
11:24:39 4136 8192000000 10055681408 10450706432 1088530416 11.729 100 74 133 1 1174506048 72404256 13571376
11:25:45 1463 8192000000 10060317888 10449080320 1094841136 11.789 100 82 157 0 1182072144 71078736 16152272
11:26:48 1406 8192000000 10050581056 10437447680 1097125792 11.810 100 95 183 1 1174576864 58923792 18527280
11:27:50 908 8192000000 10070104816 10456571904 1094517184 11.786 100 92 183 1 1191314400 77954912 18842304
11:28:53 1535 8192000000 10055394752 10450554880 1090815312 11.750 100 82 158 0 1179789344 72848448 16125584
11:29:56 882 8192000000 10060399200 10453618688 1092448464 11.766 100 84 160 5 1183312272 76469072 14394736
11:30:58 1101 8192000000 10013424176 10400231424 1074832000 11.598 99 80 147 2 1137233984 47037168 15364816
11:32:00 3881 8192000000 9998969728 10379956224 1049751616 11.358 99 63 125 0 1104191952 42206336 12234000
11:33:05 1413 8192000000 10010386688 10390732800 1059619680 11.453 100 83 155 6 1139868496 63208864 17039952
11:34:08 1107 8192000000 9974799328 10346311680 1037256928 11.238 100 82 158 0 1094526160 41009936 16259296
11:35:10 15496 8192000000 9994572064 10387116032 1029640272 11.165 99 56 110 1 1112222672 71070672 11511728
11:36:27 1052 8192000000 9991906464 10370879488 1039918432 11.264 100 79 147 2 1114550496 58730784 15901280
11:37:30 11140 8192000000 10016944816 10408198144 1070671648 11.558 100 59 115 2 1140378400 58430832 11275920
11:38:48 30159 8192000000 10048798304 10442932224 1082488080 11.671 100 60 119 0 1146133552 51489168 12156304
11:40:23 4457 8192000000 10070584256 10468139008 1109826496 11.931 100 68 128 2 1190091248 67800832 12463920
11:41:46 1496 8192000000 10130151264 10514788352 1113090736 11.962 100 98 187 1 1248878496 115060816 20726944
11:42:52 999 8192000000 10150157456 10536235008 1131032256 12.131 100 89 166 1 1263974208 115115280 17826672
11:43:59 1254 8192000000 10198385184 10590355456 1135993328 12.178 100 89 173 0 1314202144 160938016 17270800
11:45:05 1483 8192000000 10205478640 10601734144 1138461472 12.201 100 88 167 3 1312100320 155226048 18412800
11:46:11 1946 8192000000 10205368384 10600976384 1148679120 12.297 100 91 169 1 1316645904 151033760 16933024
11:47:23 2929 8192000000 10234763568 10635452416 1150271312 12.312 100 81 156 0 1345518304 178432096 16814896
11:48:28 1068 8192000000 10274007776 10671357952 1167555376 12.474 100 93 183 2 1383054576 194657568 20841632
11:49:32 1074 8192000000 10269165936 10689052672 1167471712 12.473 100 93 184 1 1378691280 189463280 21756288
11:50:37 1796 8192000000 10308250464 10717782016 1183556576 12.623 100 90 169 2 1424955584 222135648 19263360
11:51:44 2141 8192000000 10357531056 10763329536 1180142752 12.592 100 96 186 2 1459970880 259918640 19909488
11:52:50 1095 8192000000 10379636304 10802724864 1185482672 12.641 100 95 186 3 1493077296 285107568 22487056
11:53:56 748 8192000000 10413164720 10827579392 1181643936 12.606 100 98 188 1 1523257216 319203616 22409664
11:55:00 1232 8192000000 10368931888 10802544640 1180218448 12.592 100 89 169 1 1470727776 271570112 18939216
11:56:06 1169 8192000000 10385854864 10807967744 1178678864 12.578 100 84 162 0 1496348928 299018192 18651872
11:57:10 890 8192000000 10390568400 10810679296 1177620176 12.568 100 97 192 3 1496850800 297514736 21715888
11:58:15 1037 8192000000 10409957200 10839961600 1176335200 12.556 100 87 170 1 1519252912 322813136 20104576
11:59:20 759 8192000000 10400509600 10850930688 1174851488 12.542 100 97 185 0 1505282832 308016544 22414800
12:00:24 829 8192000000 10438710928 10881912832 1182171920 12.610 100 96 188 1 1542646576 338500400 21974256
12:01:28 1840 8192000000 10432672064 10880012288 1188904352 12.673 100 87 172 0 1539213760 329880496 20428912
12:02:35 809 8192000000 10440571952 10854125568 1192741936 12.709 100 95 187 2 1545840368 330715472 22382960
12:03:39 1068 8192000000 10451241184 10891513856 1184000784 12.627 100 95 192 0 1556518128 349818816 22698528
12:04:43 1565 8192000000 10418822816 10878529536 1180938016 12.599 100 72 138 1 1519605504 320947792 17719696
12:05:48 2883 8192000000 10457785488 10900594688 1181200464 12.601 100 69 136 1 1563127136 364917632 17009040
12:06:59 1087 8192000000 10498112192 10914349056 1178624928 12.577 100 88 176 2 1594755120 394667680 21462512
12:08:02 3405 8192000000 10481255472 10979803136 1194925504 12.729 100 67 132 1 1580080912 369370816 15784592
12:09:09 8316 8192000000 10475522048 10909118464 1199256048 12.769 100 53 106 0 1584924352 373116144 12552160
12:10:21 13432 8192000000 10500682000 10953236480 1192958128 12.711 100 41 81 0 1596677296 393463568 10255600
12:11:36 12814 8192000000 10529005232 10975993856 1197544032 12.754 100 38 76 0 1632506496 425288960 9673504
12:14:17 18893 8192000000 10409972704 10966048768 1137304544 12.190 98 19 38 0 1494174624 351563840 5306240
12:15:42 47760 8192000000 10245392896 10712444928 1142961840 12.243 99 20 40 0 1355639888 207940128 4737920
12:17:57 50172 8192000000 10134559072 10556645376 1137516688 12.192 99 45 84 2 1222616704 74183536 10916480
12:19:48 57506 8192000000 10141642256 10644312064 1124866048 12.073 98 21 43 0 1255468320 125847184 4755088
12:22:05 74320 8192000000 10047316800 10510360576 1113601168 11.966 98 18 36 2 1166283168 49552688 3129312
12:24:23 103390 8192000000 10054140704 10529124352 1124285584 12.067 100 25 48 0 1183347488 53983568 5078336
12:27:10 23304 8192000000 10124701504 10558537728 1131533360 12.136 100 55 103 2 1203685984 61242400 10910224
12:28:34 91624 8192000000 10088727760 10533597184 1105235552 11.887 97 24 46 0 1174254320 63782512 5236256
12:31:09 934 8192000000 10151021856 10573471744 1146022944 12.272 100 90 161 0 1238300016 75785872 16491200
12:32:14 37197 8192000000 10148831760 10582327296 1107510672 11.909 97 30 55 1 1224328928 110510432 6307824
12:33:59 8146 8192000000 10187122736 10615324672 1141684512 12.231 99 70 132 3 1263479328 106664416 15130400
12:35:09 14037 8192000000 10214754064 10660044800 1150488752 12.314 100 38 76 0 1313924912 154791440 8644720
12:36:48 41661 8192000000 10212670432 10685308928 1135144384 12.170 99 30 58 1 1322951728 180976240 6831104
12:38:30 52776 8192000000 10210177600 10661949440 1140784288 12.223 100 22 44 1 1336815328 190516128 5514912
12:40:24 38931 8192000000 10095102304 10669932544 1132670512 12.147 100 17 34 0 1248572560 111537776 4364272
12:42:52 69504 8192000000 10137148064 10590576640 1115307488 11.983 97 20 40 0 1170650384 51442800 3900096
12:45:01 21005 8192000000 10101127648 10567610368 1141973728 12.234 99 16 32 0 1191427712 46522320 2931664
12:46:25 12804 8192000000 10122586400 10583863296 1140258624 12.218 99 48 96 2 1214705088 64254064 10192400
12:47:43 51392 8192000000 10102342384 10554798080 1139591520 12.212 99 17 28 3 1196136016 53536880 3007616
12:49:35 15201 8192000000 10159762672 10580623360 1129604080 12.118 98 67 128 0 1227291344 83727968 13959296
12:51:10 56964 8192000000 10110126848 10597601280 1140904864 12.224 100 17 34 1 1243144080 98426032 3813184
12:53:08 51146 8192000000 10076559168 10543849472 1082510400 11.671 95 32 63 0 1130282656 41129792 6642464
12:54:59 122125 8192000000 10103262272 10587746304 1127902624 12.102 99 30 60 0 1193650848 59615520 6132704
12:58:15 89904 8192000000 10120439808 10570670080 1138840208 12.205 99 19 38 1 1230252048 87374576 4037264
13:00:47 69034 8192000000 10112473536 10594881536 1120545344 12.032 98 24 44 1 1214433600 89520992 4367264
13:03:06 35949 8192000000 10110268928 10562293760 1133066736 12.150 99 37 74 0 1195530704 53727744 8736224
13:04:44 81923 8192000000 10116475248 10628440064 1136465040 12.182 99 19 39 0 1245405696 104531472 4409184
13:07:13 57865 8192000000 10069592064 10572832768 1130566144 12.127 99 16 32 0 1214622128 80527792 3528192
13:09:28 63290 8192000000 10101924288 10568949760 1108696480 11.920 97 18 32 1 1158867120 46439936 3730704
13:11:32 37481 8192000000 10110357776 10551865344 1126360176 12.087 98 38 76 0 1178525424 44505536 7659712
13:13:12 22349 8192000000 10073832224 10535763968 1118103680 12.009 99 38 76 0 1175271232 49251248 7916304
13:14:36 23585 8192000000 10102379104 10545917952 1120595856 12.033 100 48 96 0 1193594608 63456848 9541904
13:16:02 30493 8192000000 10102370800 10547757056 1094478048 11.785 97 46 91 1 1150614160 46819520 9316592
13:17:40 14578 8192000000 10127344224 10549497856 1130220368 12.123 100 60 119 0 1215351616 73448704 11682544
13:18:57 15729 8192000000 10077930672 10517475328 1085976736 11.704 97 36 70 1 1140282640 47429312 6876592
13:20:13 31275 8192000000 10099402816 10597269504 1116353056 11.993 100 40 81 1 1242633504 117799440 8481008
13:21:45 62183 8192000000 10089932960 10555551744 1095253328 11.793 97 39 66 3 1141866720 40034368 6579024
13:23:48 43412 8192000000 10084219056 10527055872 1130146016 12.123 100 28 53 0 1167888624 32232800 5509808
13:25:38 12071 8192000000 10095582032 10534572032 1138212400 12.199 100 46 89 2 1200265264 52859872 9192992
13:26:52 36674 8192000000 10064493904 10550693888 1122163968 12.047 99 18 35 0 1190247600 64493456 3590176
13:29:02 31428 8192000000 10082486416 10534301696 1128375472 12.106 100 21 42 0 1191965664 59350912 4239280
13:30:34 53180 8192000000 10085328944 10564612096 1102134048 11.858 97 10 20 0 1175110336 70496288 2480000
13:32:28 53760 8192000000 10101968768 10564808704 1119544176 12.023 98 23 44 0 1165086240 40717792 4824272
13:34:23 37100 8192000000 10115621568 10570739712 1147536464 12.286 100 45 90 0 1222518432 65085264 9896704
13:36:01 54177 8192000000 10148151600 10623139840 1126108960 12.085 98 25 50 0 1199904656 68390912 5404784

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 01-01-2023 13:38:43 to 01-01-2023 13:39:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 86 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

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

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          218560
MON$OLDEST_ACTIVE               221354
MON$OLDEST_SNAPSHOT             221354
MON$NEXT_TRANSACTION            221354
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                       2100288
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             774
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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 ---
:808: 
:809: mscs-test-161.budzdorov.ru    Sun Jan  1 13:37:23 2023
:810:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:811: 
:812: 
:813: mscs-test-161.budzdorov.ru    Sun Jan  1 13:37:24 2023
:814:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:815: 
:816: 
:817: mscs-test-161.budzdorov.ru    Sun Jan  1 13:37:33 2023
:818:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:819: 
:820: 
:821: mscs-test-161.budzdorov.ru    Sun Jan  1 13:37:33 2023
:822:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:823: 
--- end of diff output ---

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

01.01.23 13:39:04. 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-01 13:39:07 No severe PSQL-related problems occured
01.01.23 13:39:08. End of report.