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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15751016 kB
MemAvailable 31053184 kB
Buffers 20 kB
Cached 15884456 kB
SwapCached 2528 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-07-14 04:37:18
DTS_END                         2022-07-14 04:37:18.0390
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35072
TRN_ID                          222963
ATT_ID                          574
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: 9156.64 24572 1098797
customer order: creation 172.43 473 20692
customer order: refuse 15.06 363 1807
order to supplier: creation 46.09 1329 5531
order to supplier: removal 3.23 179728 388
invoice (draft): creation 46.32 2885 5558
invoice (draft): removal 49.60 1009 5952
invoice accept: apply 42.63 27782 5115
invoice accept: cancel 44.60 1213 5352
customer reserve: creation 4052.11 261 486253
customer reserve: removal 4389.43 104 526732
realization accept: apply 83.69 271 10043
realization accept: cancel 81.34 175 9761
payment from customer: creation 59.06 170 7087
payment from customer: removal 5.58 128 669
payment to supplier: creation 48.90 188 5868
payment to supplier: removal 3.41 125 409
service: total inventory turnovers 4.31 3594 517
service: total monetary turnovers 7.82 315 938
service: aggregate perf. data 1.01 15294 121
service: refresh index statistics 0.03 256027 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-07-2022 04:38:36 to 14-07-2022 04:38:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 13209
TEST_TIME 2 8677
TEST_TIME 3 7320
TEST_TIME 4 6612
TEST_TIME 5 6757
TEST_TIME 6 6503
TEST_TIME 7 6643
TEST_TIME 8 6811
TEST_TIME 9 6754
TEST_TIME 10 6703
TEST_TIME 11 6781
TEST_TIME 12 6791
TEST_TIME 13 6680
TEST_TIME 14 6816
TEST_TIME 15 6849
TEST_TIME 16 6853
TEST_TIME 17 6898
TEST_TIME 18 7179
TEST_TIME 19 7312
TEST_TIME 20 7381
TEST_TIME 21 7376
TEST_TIME 22 7506
TEST_TIME 23 7740
TEST_TIME 24 8197
TEST_TIME 25 8526
TEST_TIME 26 8386
TEST_TIME 27 8419
TEST_TIME 28 9163
TEST_TIME 29 9549
TEST_TIME 30 9574
TEST_TIME 31 10625
TEST_TIME 32 11221
TEST_TIME 33 11360
TEST_TIME 34 11272
TEST_TIME 35 11409
TEST_TIME 36 11289
TEST_TIME 37 11190
TEST_TIME 38 11125
TEST_TIME 39 11010
TEST_TIME 40 10892
TEST_TIME 41 10633
TEST_TIME 42 10660
TEST_TIME 43 10536
TEST_TIME 44 10387
TEST_TIME 45 10380
TEST_TIME 46 10279
TEST_TIME 47 10279
TEST_TIME 48 10238
TEST_TIME 49 10226
TEST_TIME 50 10199
TEST_TIME 51 10133
TEST_TIME 52 10030
TEST_TIME 53 9960
TEST_TIME 54 9996
TEST_TIME 55 10198
TEST_TIME 56 10092
TEST_TIME 57 10180
TEST_TIME 58 10173
TEST_TIME 59 10190
TEST_TIME 60 10202
TEST_TIME 61 10201
TEST_TIME 62 10194
TEST_TIME 63 10178
TEST_TIME 64 10193
TEST_TIME 65 10215
TEST_TIME 66 10141
TEST_TIME 67 10084
TEST_TIME 68 10010
TEST_TIME 69 9941
TEST_TIME 70 9912
TEST_TIME 71 9830
TEST_TIME 72 9794
TEST_TIME 73 9708
TEST_TIME 74 9631
TEST_TIME 75 9576
TEST_TIME 76 9547
TEST_TIME 77 9523
TEST_TIME 78 9474
TEST_TIME 79 9476
TEST_TIME 80 9446
TEST_TIME 81 9486
TEST_TIME 82 9509
TEST_TIME 83 9633
TEST_TIME 84 9563
TEST_TIME 85 9654
TEST_TIME 86 9622
TEST_TIME 87 9646
TEST_TIME 88 9699
TEST_TIME 89 9608
TEST_TIME 90 9542
TEST_TIME 91 9572
TEST_TIME 92 9491
TEST_TIME 93 9446
TEST_TIME 94 9469
TEST_TIME 95 9453
TEST_TIME 96 9436
TEST_TIME 97 9400
TEST_TIME 98 9408
TEST_TIME 99 9396
TEST_TIME 100 9428
TEST_TIME 101 9408
TEST_TIME 102 9397
TEST_TIME 103 9487
TEST_TIME 104 9520
TEST_TIME 105 9488
TEST_TIME 106 9462
TEST_TIME 107 9477
TEST_TIME 108 9426
TEST_TIME 109 9392
TEST_TIME 110 9392
TEST_TIME 111 9374
TEST_TIME 112 9357
TEST_TIME 113 9319
TEST_TIME 114 9254
TEST_TIME 115 9229
TEST_TIME 116 9228
TEST_TIME 117 9272
TEST_TIME 118 9260
TEST_TIME 119 9242
TEST_TIME 120 9184

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-07-2022 04:38:40 to 14-07-2022 04:38:43.

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 1543817 1543817 0 0.00 0 14285 15 0 0
doc_list_biud 1543802 1543802 0 0.00 0 59542 41 0 0
sp_add_invoice_to_stock 5208 5115 93 1.78 54 205834 27782 0 93
sp_cancel_adding_invoice 5352 5352 0 0.00 6 78486 1213 0 0
sp_cancel_client_order 1807 1807 0 0.00 6 6754 364 0 0
sp_cancel_customer_reserve 526732 526732 0 0.00 1 18100 105 0 0
sp_cancel_pay_from_customer 669 669 0 0.00 3 7944 128 0 0
sp_cancel_pay_to_supplier 409 409 0 0.00 3 3906 126 0 0
sp_cancel_supplier_invoice 5958 5952 6 0.10 3 59550 1010 0 6
sp_cancel_supplier_order 388 388 0 0.00 15 1583163 179729 0 0
sp_cancel_write_off 9761 9761 0 0.00 2 10865 176 0 0
sp_client_order 20692 20692 0 0.00 5 27553 473 0 0
sp_customer_reserve 486253 486253 0 0.00 0 102215 261 0 0
sp_fill_shopping_cart 31767 31767 0 0.00 1 9374 77 0 0
sp_fill_shopping_cart_clo_res 486215 486215 0 0.00 0 19343 18 0 0
sp_get_clo_for_invoice 5068 5068 0 0.00 0 24258 269 0 0
sp_kill_cost_storno 16191 16191 0 0.00 0 5640 70 0 0
sp_kill_qstorno_ret_qs2qd 1069565 1069565 0 0.00 0 59540 45 0 0
sp_kill_qty_storno 565103 565103 0 0.00 0 59542 103 0 0
sp_lock_selected_doc 20973 20973 0 0.00 0 8690 9 0 0
sp_make_cost_storno 28066 28066 0 0.00 0 10720 78 0 0
sp_multiply_rows_for_pdistr 28066 28066 0 0.00 0 10547 36 0 0
sp_multiply_rows_for_qdistr 20692 20692 0 0.00 0 11508 189 0 0
sp_pay_from_customer 7087 7087 0 0.00 5 10242 170 0 0
sp_pay_to_supplier 5868 5868 0 0.00 6 13748 188 0 0
sp_payment_common 12955 12955 0 0.00 2 7577 42 0 0
sp_qd_handle_on_cancel_clo 1807 1807 0 0.00 0 6742 296 0 0
sp_qd_handle_on_invoice_upd_sts 10420 10420 0 0.00 0 20954 488 0 0
sp_qd_handle_on_reserve_upd_sts 10043 10043 0 0.00 0 14999 59 0 0
sp_reserve_write_off 10043 10043 0 0.00 8 17012 271 0 0
sp_supplier_invoice 5558 5558 0 0.00 10 79049 2885 0 0
sp_supplier_order 5531 5531 0 0.00 8 33124 1329 0 0
srv_aggregate_perf_data 121 121 0 0.00 2407 185421 15295 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 841 125566 32460 0 0
srv_find_qd_qs_mism 1047455 1047455 0 0.00 0 14283 12 0 0
srv_make_invnt_saldo 517 517 0 0.00 60 114808 3594 0 0
srv_make_money_saldo 938 938 0 0.00 1 16240 316 0 0
srv_recalc_idx_stat 4 4 0 0.00 41886 375591 256028 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5119 5119 0 0.00 1 10971 866 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 2 5514 428 0 0
t$perf-norm:sp_cancel_client_order 1807 1807 0 0.00 0 2529 25 0 0
t$perf-norm:sp_cancel_customer_reserve 736 736 0 0.00 0 3090 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 669 669 0 0.00 0 1955 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 409 409 0 0.00 0 2524 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 429 429 0 0.00 0 1897 37 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 90398 6280 0 0
t$perf-norm:sp_cancel_write_off 1024 1024 0 0.00 0 2072 20 0 0
t$perf-norm:sp_client_order 20692 20692 0 0.00 0 10077 36 0 0
t$perf-norm:sp_customer_reserve 1032 1032 0 0.00 0 2941 16 0 0
t$perf-norm:sp_pay_from_customer 7087 7087 0 0.00 0 6714 19 0 0
t$perf-norm:sp_pay_to_supplier 5868 5868 0 0.00 0 4628 18 0 0
t$perf-norm:sp_reserve_write_off 10045 10045 0 0.00 0 5308 21 0 0
t$perf-norm:sp_supplier_invoice 5558 5558 0 0.00 0 3273 26 0 0
t$perf-norm:sp_supplier_order 5533 5533 0 0.00 0 2362 29 0 0
t$perf-norm:srv_aggregate_perf_data 121 121 0 0.00 0 1467 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 173 9 0 0
t$perf-norm:srv_make_invnt_saldo 517 517 0 0.00 0 476 11 0 0
t$perf-norm:srv_make_money_saldo 938 938 0 0.00 0 532 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 177 69 0 0
v_add_invoice_to_stock 10314 10128 186 1.80 0 6876 28 0 186
v_all_customers 37924 37924 0 0.00 0 112 4 0 0
v_all_suppliers 30918 30918 0 0.00 0 262 4 0 0
v_all_wares 41380 41380 0 0.00 0 113 4 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 1269 17 0 0
v_cancel_client_order 3614 3614 0 0.00 0 1844 10 0 0
v_cancel_customer_prepayment 1338 1338 0 0.00 0 2769 15 0 0
v_cancel_customer_reserve 1472 1472 0 0.00 0 1939 13 0 0
v_cancel_payment_to_supplier 818 818 0 0.00 0 3828 15 0 0
v_cancel_supplier_invoice 870 858 12 1.37 0 1929 25 0 12
v_cancel_supplier_order 776 776 0 0.00 0 4591 23 0 0
v_cancel_write_off 2048 2048 0 0.00 0 3753 25 0 0
v_max_id_clo_ord 5528 5528 0 0.00 0 768 5 0 0
v_max_id_clo_res 1032 1032 0 0.00 0 2436 12 0 0
v_max_id_ord_sup 5549 5549 0 0.00 0 5111 8 0 0
v_max_non_paid_invoice 1486 1486 0 0.00 0 2687 10 0 0
v_max_non_paid_realizn 3679 3679 0 0.00 0 2900 8 0 0
v_min_id_clo_ord 5528 5528 0 0.00 0 2575 8 0 0
v_min_id_clo_res 1032 1032 0 0.00 0 4136 13 0 0
v_min_id_ord_sup 5549 5549 0 0.00 0 7617 12 0 0
v_min_non_paid_invoice 5868 5868 0 0.00 0 5105 14 0 0
v_min_non_paid_realizn 7087 7087 0 0.00 0 4163 13 0 0
v_our_firm 10270 10270 0 0.00 0 61 4 0 0
v_reserve_write_off 20086 20086 0 0.00 0 3812 12 0 0
x_lock_depdocs_on_canc_invoice 10875 10875 0 0.00 0 15676 129 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 29015 429 0 0
x_make_qstorno_1000_1200 5531 5531 0 0.00 3 32760 1170 0 0
x_make_qstorno_1200_2000 5557 5557 0 0.00 4 74308 2679 0 0
x_make_qstorno_2100_3300 486252 486252 0 0.00 0 102213 231 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-07-2022 04:38:44 to 14-07-2022 04: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
02:07:21 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:22 11157 8192000000 9721162288 10022932480 806263232 8.960 100 82 164 0 833884960 7802848 19818880
02:09:37 18350 8192000000 9770561440 10079723520 842722848 9.327 100 85 168 0 874086352 11242096 20121408
02:11:07 13608 8192000000 9807412400 10126778368 871383152 9.614 100 89 176 0 910234704 18394000 20457552
02:12:30 14117 8192000000 9854509808 10188902400 909893632 9.996 100 76 150 1 963328304 37091456 16343216
02:13:50 3373 8192000000 9901890832 10243846144 947848096 10.370 100 83 161 0 1020618080 56529712 16240272
02:15:04 1932 8192000000 9956370176 10307620864 986260288 10.745 100 92 180 2 1074541216 69696816 18584112
02:16:09 1317 8192000000 10016621472 10378997760 1015164384 11.025 100 93 184 2 1133169120 98331136 19673600
02:17:12 2503 8192000000 9989038448 10359357440 1039232016 11.257 100 81 158 2 1113573040 58802832 15538192
02:18:16 946 8192000000 10041523856 10419777536 1056437152 11.422 100 94 185 0 1164812080 89210128 19164800
02:19:19 1170 8192000000 10046998544 10429739008 1066908368 11.523 100 89 174 0 1163763088 79323024 17531696
02:20:21 1253 8192000000 10052622608 10431848448 1073538480 11.586 100 96 188 1 1171076496 78480336 19057680
02:21:23 1103 8192000000 10024291072 10411335680 1076853344 11.617 100 81 161 0 1145820928 52907520 16060064
02:22:25 1132 8192000000 10051542544 10437234688 1086207392 11.707 100 83 156 0 1175127072 71993392 16926288
02:23:27 1073 8192000000 10064815280 10451091456 1088244832 11.726 100 97 180 1 1187729728 80104464 19380432
02:24:29 1163 8192000000 10048825872 10438606848 1092908496 11.770 100 91 172 0 1172861344 62237712 17715136
02:25:31 930 8192000000 10042405328 10436882432 1097837264 11.817 100 70 136 1 1165177600 53961744 13378592
02:26:33 1763 8192000000 10048564304 10439753728 1098666176 11.825 100 79 147 3 1173254864 59335952 15252736
02:27:35 1521 8192000000 10046815888 10435264512 1091836720 11.760 99 70 128 0 1165055520 61112336 12106464
02:28:40 1195 8192000000 10060274432 10446942208 1095686016 11.797 100 94 175 4 1178389280 65925152 16778112
02:29:42 1117 8192000000 10044502896 10430488576 1093827360 11.779 100 91 177 1 1163063216 49720400 19515456
02:30:44 1017 8192000000 10009764736 10395774976 1056255200 11.421 99 79 148 2 1134846064 61861104 16729760
02:31:46 1722 8192000000 10016466656 10396237824 1054276496 11.402 100 80 147 4 1141125200 71916432 14932272
02:32:48 3039 8192000000 9996322704 10377650176 1043969488 11.303 99 69 129 3 1111370848 54722272 12679088
02:33:51 1135 8192000000 9990610272 10372358144 1053813824 11.397 100 84 160 4 1114699280 43951344 16934112
02:34:53 1145 8192000000 10002185168 10374115328 1039048736 11.256 100 92 177 1 1125232816 67321024 18863056
02:35:55 1251 8192000000 10004229056 10376642560 1043693296 11.300 100 96 183 4 1128211488 66397664 18120528
02:36:57 1512 8192000000 9965323344 10344267776 1037695264 11.243 100 79 141 4 1084401168 34319520 12386384
02:38:01 4819 8192000000 10009788096 10386161664 1065451984 11.509 100 87 165 1 1126387024 45504208 15430832
02:39:13 11730 8192000000 10041650240 10428178432 1078004800 11.628 100 77 147 1 1163056224 70586000 14465424
02:40:30 7222 8192000000 10064179360 10457063424 1096821936 11.807 100 67 128 3 1180713664 70743264 13148464
02:41:43 6321 8192000000 10094970672 10499190784 1106364176 11.898 100 65 124 1 1208124400 88559024 13201200
02:42:55 10978 8192000000 10118909136 10518802432 1120798640 12.035 100 62 122 1 1237507024 103878432 12829952
02:44:08 1344 8192000000 10177616272 10564595712 1134847968 12.167 100 92 173 1 1291636960 139428608 17360384
02:45:14 11676 8192000000 10198008416 10608160768 1150414048 12.313 100 66 128 0 1310682672 146712000 13556624
02:46:28 1745 8192000000 10232488944 10629668864 1163021168 12.432 100 80 146 3 1341915792 161704480 17190144
02:47:32 1103 8192000000 10298806688 10703024128 1181214288 12.602 100 83 167 1 1415211296 214731888 19265120
02:48:37 1050 8192000000 10351115248 10749181952 1191697872 12.699 100 96 179 0 1462882992 250524400 20660720
02:49:44 1604 8192000000 10385334736 10785693696 1192182128 12.704 100 89 173 1 1494486528 282866768 19437632
02:50:49 1298 8192000000 10442445440 10843238400 1195566736 12.735 100 96 186 0 1552974272 336345280 21062256
02:51:54 1008 8192000000 10465169152 10869551104 1202829152 12.803 100 94 180 2 1571347888 347601408 20917328
02:53:00 837 8192000000 10500514544 10911404032 1210047792 12.870 100 92 178 0 1610507152 378536720 21922640
02:54:05 909 8192000000 10544540368 10956705792 1212084624 12.888 100 93 180 2 1654818496 420300816 22433056
02:55:09 1193 8192000000 10576547952 10998337536 1205787568 12.830 100 94 188 0 1682845536 453564512 23493456
02:56:13 910 8192000000 10609849520 11018907648 1204956160 12.822 100 97 194 0 1715026704 487131856 22938688
02:57:17 2063 8192000000 10631916384 11059605504 1206086000 12.833 100 91 175 0 1735942816 509316352 20540464
02:58:23 1349 8192000000 10655250816 11090731008 1215108704 12.916 100 93 184 0 1762872928 526767696 20996528
02:59:27 909 8192000000 10672258992 11132387328 1211723568 12.885 100 95 185 0 1774160064 538797472 23639024
03:00:31 832 8192000000 10657994832 11096875008 1204850480 12.821 100 91 178 1 1766203456 538372576 22980400
03:01:34 4399 8192000000 10660376352 11152179200 1208598784 12.856 100 74 143 0 1766361712 538260928 19502000
03:02:41 23351 8192000000 10630687088 11119054848 1201951264 12.794 100 43 86 1 1733845120 520454208 11439648
03:04:09 11740 8192000000 10645189248 11124543488 1165548592 12.455 99 40 80 0 1747806800 570941904 11316304
03:05:21 12325 8192000000 10560452448 11113287680 1149276416 12.303 99 40 80 0 1662775600 502166464 11332720
03:07:02 30784 8192000000 10428218672 10865991680 1114596528 11.976 98 20 38 2 1545584816 425867536 5120752
03:08:35 28516 8192000000 10185264432 10703552512 1120476288 12.031 99 17 32 0 1321141120 196550640 4114192
03:10:04 101212 8192000000 10081281040 10534588416 1064008640 11.495 94 11 22 0 1133278768 67052880 2217248
03:12:46 39582 8192000000 10065923376 10495229952 1129873936 12.120 99 31 63 1 1153315984 16681440 6760608
03:14:34 51874 8192000000 10094385248 10520641536 1111180976 11.944 98 26 52 0 1171973664 55651008 5141680
03:17:10 85575 8192000000 10068380912 10546802688 1122882448 12.054 99 22 44 0 1188201056 60882528 4436080
03:20:01 125564 8192000000 10090567216 10524360704 1106068768 11.895 97 42 78 0 1148622096 34454784 8098544
03:23:09 1914 8192000000 10098957840 10530410496 1139660304 12.212 100 78 143 2 1208874400 53392960 15821136
03:24:12 5045 8192000000 10145950000 10566414336 1149935328 12.309 100 74 138 1 1243446128 79044544 14466256
03:25:19 32466 8192000000 10129809600 10573963264 1136544768 12.183 99 34 69 0 1234824880 90510160 7769952
03:27:28 42570 8192000000 10134947792 10608168960 1136464576 12.182 99 14 28 1 1241566032 101559920 3541536
03:29:36 52815 8192000000 10166130496 10641039360 1146894592 12.280 100 13 26 0 1268837920 118978528 2964800
03:31:36 37919 8192000000 10130958656 10650136576 1134585008 12.165 100 15 30 0 1268472112 129884512 4002592
03:33:19 20397 8192000000 10114138384 10543882240 1132276272 12.143 99 54 107 1 1196986336 53854480 10855584
03:34:40 26279 8192000000 10089177760 10523717632 1129177344 12.114 99 57 114 0 1230732656 89017808 12537504
03:36:44 64325 8192000000 10109975840 10589163520 1103720224 11.873 97 18 36 0 1174048496 66762256 3566016
03:39:18 8722 8192000000 10070451840 10496532480 1129557072 12.117 99 56 106 0 1165187680 24816272 10814336
03:40:30 50626 8192000000 10121658672 10595373056 1147893968 12.290 100 37 74 0 1211199904 56423824 6882112
03:42:21 31726 8192000000 10121089744 10558099456 1144588240 12.259 99 39 71 3 1209416352 57512976 7315136
03:44:02 62451 8192000000 10083316464 10525261824 1130495200 12.126 98 38 76 0 1186196752 47771744 7929808
03:46:10 8629 8192000000 10106931488 10540113920 1140495968 12.220 99 53 104 0 1205467952 54714416 10257568
03:47:23 81140 8192000000 10071723760 10536763392 1137115520 12.188 100 12 22 0 1200956128 61664896 2175712
03:50:02 76827 8192000000 10059660816 10527330304 1133148160 12.151 100 10 21 1 1183499568 48288768 2062640
03:52:28 52299 8192000000 10100796496 10561626112 1113016928 11.961 98 12 22 0 1171775920 56289040 2469952
03:54:20 37117 8192000000 10091446544 10533863424 1086476784 11.709 96 22 45 0 1158140688 66461392 5202512
03:56:00 22406 8192000000 10148170560 10576506880 1113390672 11.965 99 38 74 0 1233588992 111766016 8432304
03:57:24 42800 8192000000 10169210208 10610413568 1126881216 12.092 99 30 60 1 1253590864 119762416 6947232
03:59:32 48847 8192000000 10097447760 10576879616 1101545808 11.852 98 34 67 0 1224331888 115429824 7356256
04:01:23 22611 8192000000 10099475328 10519216128 1109977056 11.932 99 41 82 0 1174246704 56031872 8237776
04:02:48 53989 8192000000 10104689600 10535952384 1127358384 12.096 100 27 53 0 1192477776 60394560 4724832
04:04:50 108852 8192000000 10102459840 10580467712 1124553360 12.070 99 25 50 1 1233397840 103551840 5292640
04:07:39 80624 8192000000 10045323440 10546749440 1110637232 11.938 99 10 20 0 1154792544 42081616 2073696
04:10:00 56127 8192000000 10076431120 10536321024 1114776960 11.978 99 11 23 0 1167422000 50410576 2234464
04:11:57 64509 8192000000 10083586432 10541068288 1139485872 12.211 100 18 33 1 1186501776 43953488 3062416
04:14:02 24519 8192000000 10112569376 10550620160 1150815408 12.317 100 28 56 0 1207922656 50987984 6119264
04:15:28 36205 8192000000 10151046848 10601197568 1156164368 12.367 100 28 56 0 1252943248 91131440 5647440
04:17:08 26853 8192000000 10099219536 10537566208 1138637920 12.203 99 35 69 0 1223424688 77663616 7123152
04:18:39 22427 8192000000 10166176016 10595684352 1151940848 12.328 100 53 106 0 1242981264 80393904 10646512
04:20:07 24723 8192000000 10182452992 10652446720 1147031904 12.282 100 49 98 0 1250242992 93419984 9791104
04:21:32 54967 8192000000 10071051152 10494726144 1099917216 11.837 96 37 71 1 1121703856 15326992 6459648
04:23:31 36246 8192000000 10114119408 10559459328 1131759520 12.138 100 50 98 0 1222657792 81355264 9543008
04:25:09 78436 8192000000 10082423312 10529026048 1132729072 12.147 99 36 70 0 1182995248 43389840 6876336
04:28:36 58515 8192000000 10053880672 10525450240 1123852448 12.063 99 9 18 1 1171837568 46301808 1683312
04:30:35 55605 8192000000 10081398928 10534260736 1139608176 12.212 100 26 52 0 1178553888 32870160 6075552
04:32:37 75001 8192000000 10123556480 10621698048 1119598960 12.023 98 8 15 0 1196878528 75852592 1426976
04:34:52 40587 8192000000 10041729408 10479300608 1082713040 11.673 95 38 69 1 1134298112 43674400 7910672
04:36:34 22936 8192000000 10112150848 10551103488 1099844800 11.836 97 35 70 0 1185896528 78811760 7239968

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 14-07-2022 04:38:50 to 14-07-2022 04:39:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 93 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-07-2022 04:39:07 to 14-07-2022 04:39:09.

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          219966
MON$OLDEST_ACTIVE               223623
MON$OLDEST_SNAPSHOT             223623
MON$NEXT_TRANSACTION            223623
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                       2067296
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             780
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2798 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2798 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2798 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2798 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 ---
:177: 
:178: mscs-test-161.budzdorov.ru    Thu Jul 14 04:37:20 2022
:179:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:180: 
:181: 
:182: mscs-test-161.budzdorov.ru    Thu Jul 14 04:37:21 2022
:183:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:184: 
:185: 
:186: mscs-test-161.budzdorov.ru    Thu Jul 14 04:37:25 2022
:187:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:188: 
:189: 
:190: mscs-test-161.budzdorov.ru    Thu Jul 14 04:37:25 2022
:191:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:192: 
--- end of diff output ---

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

14.07.22 04:39:10. 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-07-14 04:39:13 No severe PSQL-related problems occured
14.07.22 04:39:14. End of report.