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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-04-22 10:37:17
DTS_END                         2022-04-22 10:37:17.1640
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/44286
TRN_ID                          223647
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: 9185.86 20970 1102303
customer order: creation 176.55 439 21186
customer order: refuse 14.74 396 1769
order to supplier: creation 46.33 1361 5559
order to supplier: removal 3.67 167559 440
invoice (draft): creation 46.55 3063 5586
invoice (draft): removal 52.48 993 6297
invoice accept: apply 42.03 24126 5044
invoice accept: cancel 46.90 1166 5628
customer reserve: creation 3898.10 229 467772
customer reserve: removal 4555.43 105 546651
realization accept: apply 83.43 265 10012
realization accept: cancel 87.89 170 10547
payment from customer: creation 59.42 175 7130
payment from customer: removal 5.53 112 664
payment to supplier: creation 50.25 183 6030
payment to supplier: removal 3.63 110 435
service: total inventory turnovers 4.03 4177 483
service: total monetary turnovers 7.98 312 958
service: aggregate perf. data 0.89 16501 107
service: refresh index statistics 0.04 197951 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-04-2022 10:39:30 to 22-04-2022 10:39:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5875
TEST_TIME 2 4978
TEST_TIME 3 5985
TEST_TIME 4 5531
TEST_TIME 5 5968
TEST_TIME 6 5878
TEST_TIME 7 6120
TEST_TIME 8 6076
TEST_TIME 9 6053
TEST_TIME 10 6186
TEST_TIME 11 6638
TEST_TIME 12 7133
TEST_TIME 13 7524
TEST_TIME 14 7573
TEST_TIME 15 7556
TEST_TIME 16 7753
TEST_TIME 17 7672
TEST_TIME 18 7930
TEST_TIME 19 7871
TEST_TIME 20 7832
TEST_TIME 21 7941
TEST_TIME 22 8012
TEST_TIME 23 8281
TEST_TIME 24 8136
TEST_TIME 25 8314
TEST_TIME 26 8717
TEST_TIME 27 8848
TEST_TIME 28 9411
TEST_TIME 29 9872
TEST_TIME 30 10208
TEST_TIME 31 10905
TEST_TIME 32 11383
TEST_TIME 33 11238
TEST_TIME 34 11303
TEST_TIME 35 11143
TEST_TIME 36 11139
TEST_TIME 37 11043
TEST_TIME 38 11007
TEST_TIME 39 11086
TEST_TIME 40 11066
TEST_TIME 41 10969
TEST_TIME 42 10768
TEST_TIME 43 10565
TEST_TIME 44 10526
TEST_TIME 45 10434
TEST_TIME 46 10299
TEST_TIME 47 10232
TEST_TIME 48 10170
TEST_TIME 49 10099
TEST_TIME 50 10061
TEST_TIME 51 9962
TEST_TIME 52 9995
TEST_TIME 53 10141
TEST_TIME 54 10069
TEST_TIME 55 10044
TEST_TIME 56 9962
TEST_TIME 57 10015
TEST_TIME 58 9951
TEST_TIME 59 9949
TEST_TIME 60 10030
TEST_TIME 61 9974
TEST_TIME 62 10012
TEST_TIME 63 9987
TEST_TIME 64 9968
TEST_TIME 65 9983
TEST_TIME 66 9946
TEST_TIME 67 10016
TEST_TIME 68 10044
TEST_TIME 69 10000
TEST_TIME 70 9876
TEST_TIME 71 9872
TEST_TIME 72 9848
TEST_TIME 73 9781
TEST_TIME 74 9760
TEST_TIME 75 9734
TEST_TIME 76 9690
TEST_TIME 77 9656
TEST_TIME 78 9567
TEST_TIME 79 9514
TEST_TIME 80 9511
TEST_TIME 81 9519
TEST_TIME 82 9530
TEST_TIME 83 9535
TEST_TIME 84 9512
TEST_TIME 85 9513
TEST_TIME 86 9470
TEST_TIME 87 9522
TEST_TIME 88 9536
TEST_TIME 89 9603
TEST_TIME 90 9600
TEST_TIME 91 9602
TEST_TIME 92 9657
TEST_TIME 93 9570
TEST_TIME 94 9559
TEST_TIME 95 9554
TEST_TIME 96 9558
TEST_TIME 97 9503
TEST_TIME 98 9472
TEST_TIME 99 9429
TEST_TIME 100 9400
TEST_TIME 101 9379
TEST_TIME 102 9399
TEST_TIME 103 9380
TEST_TIME 104 9380
TEST_TIME 105 9346
TEST_TIME 106 9372
TEST_TIME 107 9383
TEST_TIME 108 9551
TEST_TIME 109 9517
TEST_TIME 110 9582
TEST_TIME 111 9568
TEST_TIME 112 9498
TEST_TIME 113 9462
TEST_TIME 114 9435
TEST_TIME 115 9396
TEST_TIME 116 9366
TEST_TIME 117 9351
TEST_TIME 118 9311
TEST_TIME 119 9274
TEST_TIME 120 9204

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-04-2022 10:39:33 to 22-04-2022 10:39:37.

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 1534051 1534051 0 0.00 0 33956 13 0 0
doc_list_biud 1534043 1534043 0 0.00 0 56017 43 0 0
sp_add_invoice_to_stock 5212 5044 168 3.22 69 187469 24126 0 168
sp_cancel_adding_invoice 5628 5628 0 0.00 6 65300 1167 0 0
sp_cancel_client_order 1769 1769 0 0.00 6 13837 397 0 0
sp_cancel_customer_reserve 546651 546651 0 0.00 1 32836 106 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 4 7151 112 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 4 1843 110 0 0
sp_cancel_supplier_invoice 6308 6297 11 0.17 3 56020 994 0 11
sp_cancel_supplier_order 440 440 0 0.00 11 1477039 167560 0 0
sp_cancel_write_off 10547 10547 0 0.00 2 12129 170 0 0
sp_client_order 21186 21186 0 0.00 6 23846 439 0 0
sp_customer_reserve 467772 467772 0 0.00 1 118859 230 0 0
sp_fill_shopping_cart 32319 32319 0 0.00 1 12342 78 0 0
sp_fill_shopping_cart_clo_res 467751 467751 0 0.00 0 18062 18 0 0
sp_get_clo_for_invoice 5016 5016 0 0.00 0 29001 310 0 0
sp_kill_cost_storno 17274 17274 0 0.00 0 33946 74 0 0
sp_kill_qstorno_ret_qs2qd 1110586 1110586 0 0.00 0 56015 45 0 0
sp_kill_qty_storno 586360 586360 0 0.00 0 56017 104 0 0
sp_lock_selected_doc 20983 20983 0 0.00 0 7659 9 0 0
sp_make_cost_storno 28188 28188 0 0.00 0 14244 74 0 0
sp_multiply_rows_for_pdistr 28187 28187 0 0.00 0 11665 36 0 0
sp_multiply_rows_for_qdistr 21186 21186 0 0.00 0 11955 172 0 0
sp_pay_from_customer 7130 7130 0 0.00 5 15319 176 0 0
sp_pay_to_supplier 6030 6030 0 0.00 6 13786 184 0 0
sp_payment_common 13159 13159 0 0.00 2 8176 44 0 0
sp_qd_handle_on_cancel_clo 1769 1769 0 0.00 1 13824 318 0 0
sp_qd_handle_on_invoice_upd_sts 10644 10644 0 0.00 0 36142 502 0 0
sp_qd_handle_on_reserve_upd_sts 10012 10012 0 0.00 0 14597 62 0 0
sp_reserve_write_off 10012 10012 0 0.00 9 21490 266 0 0
sp_supplier_invoice 5586 5586 0 0.00 8 91340 3064 0 0
sp_supplier_order 5559 5559 0 0.00 7 30283 1361 0 0
srv_aggregate_perf_data 107 107 0 0.00 2790 185812 16502 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 801 172984 34529 0 0
srv_find_qd_qs_mism 1053083 1053083 0 0.00 0 14222 10 0 0
srv_make_invnt_saldo 483 483 0 0.00 87 98899 4177 0 0
srv_make_money_saldo 958 958 0 0.00 1 10175 313 0 0
srv_recalc_idx_stat 5 5 0 0.00 94590 391522 197952 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 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5046 5046 0 0.00 1 15581 864 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 1 7732 438 0 0
t$perf-norm:sp_cancel_client_order 1769 1769 0 0.00 0 3928 32 0 0
t$perf-norm:sp_cancel_customer_reserve 728 728 0 0.00 0 1363 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 1010 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 1278 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 450 450 0 0.00 0 967 30 0 0
t$perf-norm:sp_cancel_supplier_order 440 440 0 0.00 0 80653 6930 0 0
t$perf-norm:sp_cancel_write_off 1044 1044 0 0.00 0 2912 18 0 0
t$perf-norm:sp_client_order 21186 21186 0 0.00 0 9803 36 0 0
t$perf-norm:sp_customer_reserve 1014 1014 0 0.00 0 2251 18 0 0
t$perf-norm:sp_pay_from_customer 7130 7130 0 0.00 0 8334 19 0 0
t$perf-norm:sp_pay_to_supplier 6030 6030 0 0.00 0 7237 20 0 0
t$perf-norm:sp_reserve_write_off 10012 10012 0 0.00 0 5068 20 0 0
t$perf-norm:sp_supplier_invoice 5586 5586 0 0.00 0 4854 29 0 0
t$perf-norm:sp_supplier_order 5560 5560 0 0.00 0 3114 30 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 239 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 360 10 0 0
t$perf-norm:srv_make_invnt_saldo 483 483 0 0.00 0 940 17 0 0
t$perf-norm:srv_make_money_saldo 958 958 0 0.00 0 1091 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 24 205 109 0 0
v_add_invoice_to_stock 10363 10027 336 3.24 0 7145 28 0 336
v_all_customers 39320 39320 0 0.00 0 93 3 0 0
v_all_suppliers 31581 31581 0 0.00 0 80 4 0 0
v_all_wares 42364 42364 0 0.00 0 65 4 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 3627 19 0 0
v_cancel_client_order 3538 3538 0 0.00 0 3845 13 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 7112 16 0 0
v_cancel_customer_reserve 1456 1456 0 0.00 0 1744 12 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 1733 16 0 0
v_cancel_supplier_invoice 922 900 22 2.38 0 681 25 0 22
v_cancel_supplier_order 880 880 0 0.00 0 4250 16 0 0
v_cancel_write_off 2088 2088 0 0.00 0 7472 29 0 0
v_max_id_clo_ord 5558 5558 0 0.00 0 1068 5 0 0
v_max_id_clo_res 1014 1014 0 0.00 0 2197 18 0 0
v_max_id_ord_sup 5579 5579 0 0.00 0 4613 9 0 0
v_max_non_paid_invoice 1375 1375 0 0.00 0 408 8 0 0
v_max_non_paid_realizn 3461 3461 0 0.00 0 786 7 0 0
v_min_id_clo_ord 5558 5558 0 0.00 0 3379 8 0 0
v_min_id_clo_res 1014 1014 0 0.00 0 1846 12 0 0
v_min_id_ord_sup 5579 5579 0 0.00 0 7014 12 0 0
v_min_non_paid_invoice 6028 6028 0 0.00 0 8167 16 0 0
v_min_non_paid_realizn 7130 7130 0 0.00 0 6533 16 0 0
v_our_firm 10380 10380 0 0.00 0 62 4 0 0
v_reserve_write_off 20022 20022 0 0.00 0 5570 12 0 0
x_lock_depdocs_on_canc_invoice 11475 11475 0 0.00 0 19140 134 0 0
x_lock_depdocs_on_canc_sup_ord 440 440 0 0.00 0 16178 383 0 0
x_make_qstorno_1000_1200 5559 5559 0 0.00 2 30273 1198 0 0
x_make_qstorno_1200_2000 5586 5586 0 0.00 4 91307 2857 0 0
x_make_qstorno_2100_3300 467772 467772 0 0.00 0 118851 200 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-04-2022 10:39:38 to 22-04-2022 10:39: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
08:07:20 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:21 21875 8192000000 9719255840 10017128448 793717200 8.833 100 83 165 0 821123936 7356896 20049840
08:09:49 20014 8192000000 9749176112 10053902336 823722688 9.136 100 88 175 1 857489904 12362976 21404240
08:11:14 8513 8192000000 9787386016 10102124544 855082656 9.451 100 80 160 1 897916992 23962800 18871536
08:12:31 12464 8192000000 9842378992 10174160896 897690000 9.875 100 83 161 0 953136640 37835088 17611552
08:13:48 5433 8192000000 9901106544 10241536000 949160448 10.383 100 76 150 0 1015278448 50424528 15693472
08:14:56 2132 8192000000 9958200720 10310025216 990075392 10.782 100 86 166 0 1076937760 69977040 16885328
08:16:01 1743 8192000000 10014552816 10374426624 1009721648 10.973 100 92 178 0 1128971376 101265600 17984128
08:17:05 1144 8192000000 9986971504 10352087040 1033661712 11.204 100 88 171 0 1101655040 51100848 16892480
08:18:08 1204 8192000000 10019924208 10390319104 1054893088 11.408 100 93 185 1 1137861424 62718832 20249504
08:19:10 1402 8192000000 10015222064 10392645632 1064123936 11.496 100 95 180 1 1132136816 49823184 18189696
08:20:14 858 8192000000 10035109488 10417995776 1071575136 11.567 100 93 184 0 1152654000 62443344 18635520
08:21:16 6091 8192000000 10023820416 10409283584 1072795680 11.579 100 69 134 0 1141246944 54995136 13456128
08:22:23 1073 8192000000 10042292400 10424999936 1078583600 11.634 100 88 170 1 1163552000 66541264 18427136
08:23:25 1341 8192000000 10044195504 10431025152 1082144496 11.668 100 82 162 0 1165517808 66975120 16398192
08:24:27 5114 8192000000 10048573456 10435784704 1077839920 11.627 99 78 151 2 1160156832 68157312 14159600
08:25:34 6396 8192000000 10042128464 10440114176 1090712976 11.749 100 54 108 1 1168799872 67260320 10826576
08:26:43 4620 8192000000 10018008528 10412085248 1091831424 11.760 100 71 120 2 1143164816 36445280 14888112
08:27:48 1058 8192000000 10033673152 10422542336 1098404176 11.822 100 90 171 0 1151290608 35083680 17802752
08:28:50 3214 8192000000 10056120544 10449690624 1094398576 11.784 100 75 136 0 1180498528 73228912 12871040
08:29:54 1109 8192000000 10076626672 10463363072 1097699536 11.816 100 94 183 1 1196113824 80476768 17937520
08:30:56 1139 8192000000 10063143664 10443497472 1075492864 11.605 100 96 188 0 1184980784 90757152 18730768
08:31:58 952 8192000000 10022413936 10405384192 1062245536 11.478 100 79 151 2 1144660944 66876512 15538896
08:33:00 1216 8192000000 10031820880 10410315776 1067193600 11.525 100 93 174 1 1150770288 65466304 18110384
08:34:02 891 8192000000 9978681296 10353405952 1045751968 11.320 99 90 175 1 1100591488 35782048 19057472
08:35:03 1212 8192000000 9981615920 10354982912 1026798064 11.138 100 81 152 1 1102710544 59539280 16373200
08:36:05 939 8192000000 9975396608 10349453312 1046298112 11.325 100 88 166 2 1098740304 33527568 18914624
08:37:08 10245 8192000000 9987395440 10372395008 1052113632 11.381 100 58 98 2 1128829984 65192128 11524224
08:38:26 20453 8192000000 10031856096 10415292416 1075308928 11.603 100 59 110 2 1145920176 59290528 11320720
08:39:49 1451 8192000000 10062850032 10446565376 1078391584 11.632 100 79 149 1 1178790720 85458096 14941040
08:40:56 8627 8192000000 10075265920 10463162368 1098091264 11.820 100 70 134 0 1197718624 86232944 13394416
08:42:08 1451 8192000000 10143680432 10534256640 1114032848 11.971 100 94 177 2 1258502224 124904336 19565040
08:43:17 24628 8192000000 10143655456 10548228096 1123062080 12.056 100 56 107 0 1250103504 115009280 12032144
08:44:46 2417 8192000000 10176133840 10571661312 1140459824 12.220 100 82 155 0 1293768320 136389072 16919424
08:45:53 1589 8192000000 10217654880 10614411264 1152326848 12.331 100 82 161 0 1333837264 162982848 18527568
08:46:57 1095 8192000000 10283334720 10682204160 1162490496 12.427 100 95 188 0 1400739568 215818544 22430528
08:48:02 1077 8192000000 10295509104 10725437440 1174150016 12.536 100 91 174 0 1406838064 211176528 21511520
08:49:06 1520 8192000000 10305505520 10732654592 1182547936 12.614 100 92 177 0 1420291824 216301424 21442464
08:50:09 859 8192000000 10351405408 10777239552 1193328480 12.714 100 90 167 2 1457184320 244047200 19808640
08:51:11 798 8192000000 10362143920 10776809472 1210508464 12.874 100 96 192 1 1467236064 235104656 21622944
08:52:15 850 8192000000 10389146256 10792288256 1207240896 12.844 100 93 178 1 1494177920 267130128 19806896
08:53:19 1120 8192000000 10422949280 10845069312 1215404352 12.919 100 97 191 0 1530981568 293589712 21987504
08:54:24 3072 8192000000 10463991728 10898833408 1221010976 12.971 100 65 128 0 1570338592 332810560 16517056
08:55:30 3327 8192000000 10461471232 10880294912 1212585184 12.893 100 72 144 0 1575087872 345153744 17348944
08:56:41 1374 8192000000 10499699808 10920382464 1204866688 12.822 100 95 185 0 1607589840 380056112 22667040
08:57:46 1346 8192000000 10547675360 10988929024 1225211824 13.010 100 93 182 2 1656078368 408388128 22478416
08:58:51 928 8192000000 10580914304 11007340544 1230338320 13.057 100 98 192 1 1684895168 431241568 23315280
08:59:53 847 8192000000 10584267328 11035766784 1214024960 12.906 100 94 181 0 1689541888 453187040 22329888
09:01:00 917 8192000000 10629606208 11041914880 1214187632 12.908 100 92 182 1 1729740352 492306928 23245792
09:02:04 1695 8192000000 10674690032 11112374272 1209615136 12.866 100 85 166 0 1783696144 553192544 20888464
09:03:11 5759 8192000000 10612245856 11094564864 1193114688 12.712 99 58 116 2 1728593488 519629920 15848880
09:04:34 11310 8192000000 10601771136 11087613952 1188630224 12.671 99 49 95 2 1724749856 522779296 13340336
09:05:54 14249 8192000000 10484837104 10968600576 1185380464 12.640 100 35 70 0 1617045024 422829504 8835056
09:07:14 20718 8192000000 10401351664 10901417984 1141486736 12.230 98 24 48 1 1497150208 349241088 6422384
09:09:16 45268 8192000000 10093717760 10622357504 1131282464 12.133 98 22 44 2 1263969632 127938688 4748480
09:11:02 43957 8192000000 10102493216 10564407296 1132259984 12.143 99 29 58 0 1181860672 42684448 6916240
09:12:46 59805 8192000000 10093884304 10565804032 1140209968 12.218 99 24 48 1 1207571840 62589856 4772016
09:14:47 87389 8192000000 10106963808 10601226240 1116130128 11.990 97 15 27 0 1172258656 53408256 2720272
09:17:18 172978 8192000000 10073745472 10536562688 1092307552 11.765 96 9 18 0 1153170880 59049008 1814320
09:21:12 34779 8192000000 10117838576 10576084992 1131968944 12.140 100 38 74 2 1224754208 84371648 8413616
09:23:08 71899 8192000000 10118318240 10559156224 1078559264 11.634 94 48 93 1 1159235856 70595968 10080624
09:25:24 27710 8192000000 10152823216 10600747008 1122506256 12.051 99 26 52 0 1234892768 106419328 5967184
09:26:58 38751 8192000000 10176288304 10641887232 1120096720 12.028 99 37 74 1 1274135392 145446544 8592128
09:29:03 32924 8192000000 10132077008 10630406144 1102984640 11.866 98 20 40 0 1220508512 112786816 4737056
09:30:38 32007 8192000000 10089449920 10587684864 1126323904 12.087 100 14 28 0 1195320800 66260432 2736464
09:32:12 65337 8192000000 10075902352 10521804800 1133697712 12.156 100 8 16 1 1186910224 51501360 1711152
09:34:18 60237 8192000000 10096665184 10564186112 1126843088 12.092 99 17 34 1 1167910336 37528352 3538896
09:36:19 11276 8192000000 10112105664 10537508864 1121542304 12.042 99 52 102 1 1187577728 56136112 9899312
09:37:33 52370 8192000000 10062519648 10491674624 1084485280 11.690 95 19 38 0 1148603808 60065296 4053232
09:39:26 14708 8192000000 10092561568 10532253696 1124975200 12.074 100 52 104 0 1182393152 47047824 10370128
09:40:43 8769 8192000000 10074296896 10514075648 1114580336 11.976 99 55 103 1 1174449616 49244480 10624800
09:41:53 40510 8192000000 10067310432 10516336640 1081374320 11.661 96 30 60 0 1137868976 50612352 5882304
09:43:35 17354 8192000000 10094921776 10517725184 1104881152 11.884 99 49 96 3 1169868000 54507760 10479088
09:44:54 64716 8192000000 10072604096 10543017984 1112090240 11.952 98 24 48 2 1176651504 59290928 5270336
09:46:59 116446 8192000000 10140060928 10609647616 1115949568 11.989 98 27 54 0 1201999072 80161168 5888336
09:49:56 20218 8192000000 10042450736 10481655808 1103973312 11.875 98 57 92 4 1137016112 23491152 9551648
09:51:18 51221 8192000000 10055260240 10533154816 1114501616 11.975 99 20 40 1 1164477520 45832128 4143776
09:53:11 65281 8192000000 10074080240 10519789568 1108550400 11.919 97 22 42 3 1144823136 32050240 4222496
09:55:32 85362 8192000000 10088955056 10548514816 1111510480 11.947 98 15 30 1 1178923664 64235184 3178000
09:57:58 58034 8192000000 10063508752 10526556160 1086251952 11.707 96 18 36 0 1126245600 36009040 3984608
09:59:58 45054 8192000000 10102839200 10552967168 1136030144 12.178 100 41 77 1 1170982512 27368496 7583872
10:01:44 24350 8192000000 10123664704 10590527488 1145107248 12.264 100 37 74 2 1218534896 65557344 7870304
10:03:10 26600 8192000000 10152850176 10589257728 1131132752 12.132 99 38 76 1 1217483696 78629008 7721936
10:04:37 37476 8192000000 10114658112 10577227776 1113063648 11.961 98 36 70 0 1210607984 90324256 7220080
10:06:18 39849 8192000000 10082297184 10523115520 1086394400 11.708 96 33 66 0 1141414928 48620000 6400528
10:08:00 31231 8192000000 10122346816 10558013440 1117789632 12.006 99 40 80 1 1199739280 74033824 7915824
10:09:33 105292 8192000000 10096335856 10594795520 1123013776 12.055 99 15 30 1 1202652624 76719680 2919168
10:12:45 31612 8192000000 10042285760 10525618176 1119667456 12.024 99 13 26 1 1183300080 60783536 2849088
10:14:30 52965 8192000000 10042514480 10496786432 1118193824 12.010 99 13 27 0 1138491296 17884544 2412928
10:16:23 52880 8192000000 10113530496 10557276160 1126596368 12.089 99 27 54 0 1172017856 39722352 5699136
10:18:24 41969 8192000000 10147550608 10590789632 1129184032 12.114 98 29 58 1 1229178400 93299536 6694832
10:20:33 19732 8192000000 10168207152 10607890432 1152699712 12.335 100 58 112 1 1259343968 93452048 13192208
10:22:02 14342 8192000000 10189312656 10617012224 1141451440 12.229 99 48 95 1 1272551280 120689760 10410080
10:23:26 80151 8192000000 10169688032 10637758464 1117209440 12.001 97 27 54 0 1288776288 165155360 6411488
10:25:53 36408 8192000000 10089760848 10523344896 1114124768 11.971 99 32 61 1 1157295680 37269824 5901088
10:27:30 154824 8192000000 10090410992 10555047936 1140554480 12.221 100 20 40 1 1256711024 111527424 4629120
10:31:16 47535 8192000000 10092575040 10560270336 1108527648 11.918 97 27 54 1 1177732688 63969440 5235600
10:33:10 64147 8192000000 10064050496 10553552896 1117461888 12.003 99 8 17 0 1168606784 49226608 1918288
10:35:14 56301 8192000000 10085530128 10544267264 1129554752 12.117 99 15 30 0 1170029088 36573504 3900832

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 22-04-2022 10:39:44 to 22-04-2022 10:40:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 168 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 22-04-2022 10:40:01 to 22-04-2022 10:40: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          221407
MON$OLDEST_ACTIVE               223770
MON$OLDEST_SNAPSHOT             223770
MON$NEXT_TRANSACTION            223770
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                       2063776
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:487: 
:488: mscs-test-161.budzdorov.ru    Fri Apr 22 10:37:22 2022
:489:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:490: 
:491: 
:492: mscs-test-161.budzdorov.ru    Fri Apr 22 10:37:37 2022
:493:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:494: 
:495: 
:496: mscs-test-161.budzdorov.ru    Fri Apr 22 10:37:37 2022
:497:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:498: 
:499: 
:500: mscs-test-161.budzdorov.ru    Fri Apr 22 10:37:45 2022
:501:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:502: 
:503: 
:504: mscs-test-161.budzdorov.ru    Fri Apr 22 10:37:45 2022
:505:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:506: 
:507: 
:508: mscs-test-161.budzdorov.ru    Fri Apr 22 10:37:45 2022
:509:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:510: 
--- end of diff output ---

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

22.04.22 10:40: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
2022-04-22 10:40:06 No severe PSQL-related problems occured
22.04.22 10:40:08. End of report.