Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.06.2022 16:44
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 16537344 kB
MemAvailable 31371428 kB
Buffers 0 kB
Cached 15204140 kB
SwapCached 2444 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 3.0.10
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-06-02 16:42:51
DTS_END                         2022-06-02 16:42:37.0680
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35360
TRN_ID                          239933
ATT_ID                          274
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: 9283.72 23788 1114046
customer order: creation 167.39 360 20087
customer order: refuse 14.75 368 1770
order to supplier: creation 45.68 1236 5481
order to supplier: removal 3.35 109002 402
invoice (draft): creation 44.31 3306 5317
invoice (draft): removal 53.67 772 6440
invoice accept: apply 40.89 17979 4907
invoice accept: cancel 48.64 831 5837
customer reserve: creation 3786.49 176 454379
customer reserve: removal 4779.21 60 573505
realization accept: apply 82.91 205 9949
realization accept: cancel 88.30 110 10596
payment from customer: creation 57.88 137 6946
payment from customer: removal 5.64 94 677
payment to supplier: creation 48.43 169 5811
payment to supplier: removal 3.82 93 458
service: total inventory turnovers 4.87 954 584
service: total monetary turnovers 6.64 23 797
service: aggregate perf. data 0.83 22445 99
service: refresh index statistics 0.03 317435 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-06-2022 16:44:26 to 02-06-2022 16:44:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2058
TEST_TIME 2 2634
TEST_TIME 3 3566
TEST_TIME 4 4166
TEST_TIME 5 4091
TEST_TIME 6 3951
TEST_TIME 7 4127
TEST_TIME 8 4000
TEST_TIME 9 4339
TEST_TIME 10 4820
TEST_TIME 11 5095
TEST_TIME 12 5327
TEST_TIME 13 5634
TEST_TIME 14 5604
TEST_TIME 15 5764
TEST_TIME 16 6197
TEST_TIME 17 6519
TEST_TIME 18 6870
TEST_TIME 19 7166
TEST_TIME 20 8000
TEST_TIME 21 8787
TEST_TIME 22 8636
TEST_TIME 23 9195
TEST_TIME 24 9364
TEST_TIME 25 10082
TEST_TIME 26 10807
TEST_TIME 27 10468
TEST_TIME 28 10245
TEST_TIME 29 10526
TEST_TIME 30 10300
TEST_TIME 31 10192
TEST_TIME 32 9979
TEST_TIME 33 10184
TEST_TIME 34 10096
TEST_TIME 35 10002
TEST_TIME 36 9780
TEST_TIME 37 9755
TEST_TIME 38 9789
TEST_TIME 39 9640
TEST_TIME 40 9540
TEST_TIME 41 9486
TEST_TIME 42 9631
TEST_TIME 43 9505
TEST_TIME 44 9487
TEST_TIME 45 9412
TEST_TIME 46 9437
TEST_TIME 47 9422
TEST_TIME 48 9398
TEST_TIME 49 9258
TEST_TIME 50 9204
TEST_TIME 51 9208
TEST_TIME 52 9199
TEST_TIME 53 9238
TEST_TIME 54 9189
TEST_TIME 55 9256
TEST_TIME 56 9292
TEST_TIME 57 9369
TEST_TIME 58 9364
TEST_TIME 59 9420
TEST_TIME 60 9497
TEST_TIME 61 9612
TEST_TIME 62 9610
TEST_TIME 63 9711
TEST_TIME 64 9764
TEST_TIME 65 9831
TEST_TIME 66 9719
TEST_TIME 67 9605
TEST_TIME 68 9598
TEST_TIME 69 9557
TEST_TIME 70 9554
TEST_TIME 71 9494
TEST_TIME 72 9544
TEST_TIME 73 9503
TEST_TIME 74 9521
TEST_TIME 75 9436
TEST_TIME 76 9418
TEST_TIME 77 9345
TEST_TIME 78 9325
TEST_TIME 79 9298
TEST_TIME 80 9272
TEST_TIME 81 9339
TEST_TIME 82 9364
TEST_TIME 83 9402
TEST_TIME 84 9418
TEST_TIME 85 9492
TEST_TIME 86 9529
TEST_TIME 87 9489
TEST_TIME 88 9611
TEST_TIME 89 9597
TEST_TIME 90 9661
TEST_TIME 91 9611
TEST_TIME 92 9515
TEST_TIME 93 9493
TEST_TIME 94 9471
TEST_TIME 95 9468
TEST_TIME 96 9436
TEST_TIME 97 9404
TEST_TIME 98 9365
TEST_TIME 99 9441
TEST_TIME 100 9390
TEST_TIME 101 9388
TEST_TIME 102 9375
TEST_TIME 103 9480
TEST_TIME 104 9448
TEST_TIME 105 9469
TEST_TIME 106 9550
TEST_TIME 107 9550
TEST_TIME 108 9516
TEST_TIME 109 9524
TEST_TIME 110 9551
TEST_TIME 111 9481
TEST_TIME 112 9442
TEST_TIME 113 9418
TEST_TIME 114 9418
TEST_TIME 115 9383
TEST_TIME 116 9345
TEST_TIME 117 9323
TEST_TIME 118 9317
TEST_TIME 119 9336
TEST_TIME 120 9285

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-06-2022 16:44:29 to 02-06-2022 16:44:33.

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 1533616 1533616 0 0.00 0 15293 6 0 0
doc_list_biud 1533616 1533616 0 0.00 0 27576 29 0 0
sp_add_invoice_to_stock 5035 4907 128 2.54 24 203394 17979 0 128
sp_cancel_adding_invoice 5837 5837 0 0.00 4 75804 831 0 0
sp_cancel_client_order 1770 1770 0 0.00 4 13633 369 0 0
sp_cancel_customer_reserve 573505 573505 0 0.00 1 21993 61 0 0
sp_cancel_pay_from_customer 677 677 0 0.00 3 4438 94 0 0
sp_cancel_pay_to_supplier 458 458 0 0.00 2 3869 93 0 0
sp_cancel_supplier_invoice 6453 6440 13 0.20 1 15245 773 0 13
sp_cancel_supplier_order 402 402 0 0.00 6 1220089 109002 0 0
sp_cancel_write_off 10596 10596 0 0.00 1 11442 111 0 0
sp_client_order 20087 20087 0 0.00 3 15963 360 0 0
sp_customer_reserve 454379 454379 0 0.00 0 61212 176 0 0
sp_fill_shopping_cart 30876 30876 0 0.00 1 16976 39 0 0
sp_fill_shopping_cart_clo_res 454373 454373 0 0.00 0 17516 16 0 0
sp_get_clo_for_invoice 4901 4901 0 0.00 0 30148 325 0 0
sp_kill_cost_storno 17568 17568 0 0.00 0 7618 59 0 0
sp_kill_qstorno_ret_qs2qd 1164448 1164448 0 0.00 0 21989 33 0 0
sp_kill_qty_storno 613399 613399 0 0.00 0 27576 71 0 0
sp_lock_selected_doc 20603 20603 0 0.00 0 7962 7 0 0
sp_make_cost_storno 27606 27606 0 0.00 0 14610 63 0 0
sp_multiply_rows_for_pdistr 27606 27606 0 0.00 0 7774 35 0 0
sp_multiply_rows_for_qdistr 20087 20087 0 0.00 0 15956 174 0 0
sp_pay_from_customer 6946 6946 0 0.00 4 30056 137 0 0
sp_pay_to_supplier 5811 5811 0 0.00 5 15539 169 0 0
sp_payment_common 12757 12757 0 0.00 1 30019 49 0 0
sp_qd_handle_on_cancel_clo 1770 1770 0 0.00 0 13626 346 0 0
sp_qd_handle_on_invoice_upd_sts 10737 10737 0 0.00 0 27575 339 0 0
sp_qd_handle_on_reserve_upd_sts 9949 9949 0 0.00 0 19730 67 0 0
sp_reserve_write_off 9949 9949 0 0.00 6 19739 206 0 0
sp_supplier_invoice 5317 5317 0 0.00 6 91125 3306 0 0
sp_supplier_order 5481 5481 0 0.00 5 66409 1237 0 0
srv_aggregate_perf_data 99 99 0 0.00 2925 518291 22445 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1702 114692 47983 0 0
srv_find_qd_qs_mism 1066566 1066566 0 0.00 0 11645 5 0 0
srv_make_invnt_saldo 584 584 0 0.00 29 57096 955 0 0
srv_make_money_saldo 797 797 0 0.00 1 1156 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 206427 406656 317436 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4907 4907 0 0.00 0 9623 175 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 11862 139 0 0
t$perf-norm:sp_cancel_client_order 1770 1770 0 0.00 0 2662 17 0 0
t$perf-norm:sp_cancel_customer_reserve 658 658 0 0.00 0 12809 41 0 0
t$perf-norm:sp_cancel_pay_from_customer 677 677 0 0.00 0 3250 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 458 458 0 0.00 0 2179 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 3501 22 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 40695 1800 0 0
t$perf-norm:sp_cancel_write_off 1009 1009 0 0.00 0 1347 9 0 0
t$perf-norm:sp_client_order 20087 20087 0 0.00 0 7499 18 0 0
t$perf-norm:sp_customer_reserve 983 983 0 0.00 0 1654 6 0 0
t$perf-norm:sp_pay_from_customer 6946 6946 0 0.00 0 7579 13 0 0
t$perf-norm:sp_pay_to_supplier 5811 5811 0 0.00 0 10271 12 0 0
t$perf-norm:sp_reserve_write_off 9949 9949 0 0.00 0 10888 14 0 0
t$perf-norm:sp_supplier_invoice 5317 5317 0 0.00 0 5231 15 0 0
t$perf-norm:sp_supplier_order 5481 5481 0 0.00 0 7677 18 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 753 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 2371 41 0 0
t$perf-norm:srv_make_invnt_saldo 584 584 0 0.00 0 1629 24 0 0
t$perf-norm:srv_make_money_saldo 797 797 0 0.00 0 6279 30 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 4 0 0
v_add_invoice_to_stock 10056 9800 256 2.54 0 7293 19 0 256
v_all_customers 36408 36408 0 0.00 0 25 0 0 0
v_all_suppliers 30072 30072 0 0.00 0 18 1 0 0
v_all_wares 40174 40174 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 956 6 0 0
v_cancel_client_order 3540 3540 0 0.00 0 2082 4 0 0
v_cancel_customer_prepayment 1354 1354 0 0.00 0 4373 9 0 0
v_cancel_customer_reserve 1316 1316 0 0.00 0 492 4 0 0
v_cancel_payment_to_supplier 916 916 0 0.00 0 467 4 0 0
v_cancel_supplier_invoice 776 750 26 3.35 0 690 15 0 26
v_cancel_supplier_order 804 804 0 0.00 0 691 4 0 0
v_cancel_write_off 2018 2018 0 0.00 0 2162 12 0 0
v_max_id_clo_ord 5481 5481 0 0.00 0 768 1 0 0
v_max_id_clo_res 983 983 0 0.00 0 1880 11 0 0
v_max_id_ord_sup 5308 5308 0 0.00 0 7543 6 0 0
v_max_non_paid_invoice 1564 1564 0 0.00 0 3820 6 0 0
v_max_non_paid_realizn 3748 3748 0 0.00 0 5136 7 0 0
v_min_id_clo_ord 5481 5481 0 0.00 0 3172 8 0 0
v_min_id_clo_res 983 983 0 0.00 0 5640 22 0 0
v_min_id_ord_sup 5308 5308 0 0.00 0 5491 8 0 0
v_min_non_paid_invoice 5811 5811 0 0.00 0 9754 39 0 0
v_min_non_paid_realizn 6946 6946 0 0.00 0 30011 36 0 0
v_our_firm 10162 10162 0 0.00 0 24 1 0 0
v_reserve_write_off 19898 19898 0 0.00 0 3602 5 0 0
x_lock_depdocs_on_canc_invoice 11902 11902 0 0.00 0 21751 117 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 22879 428 0 0
x_make_qstorno_1000_1200 5481 5481 0 0.00 2 66394 1161 0 0
x_make_qstorno_1200_2000 5317 5317 0 0.00 2 89579 3159 0 0
x_make_qstorno_2100_3300 454379 454379 0 0.00 0 61210 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-06-2022 16:44:34 to 02-06-2022 16:44:38.

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
14:12:40 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:13:41 36085 8192000000 9914358528 10224910336 992349104 10.804 100 85 170 0 1020630416 6291760 21989552
14:15:27 14953 8192000000 9949855568 10272534528 1033731984 11.204 100 90 180 0 1065669584 8698544 23239056
14:16:51 14786 8192000000 9979878720 10311360512 1060570368 11.462 100 90 180 1 1093589616 10564304 22454944
14:18:09 11843 8192000000 10022235712 10365587456 1110237872 11.935 100 78 156 0 1140464720 11781280 18445568
14:19:23 9155 8192000000 10081737024 10441138176 1166978240 12.469 100 80 160 1 1198222864 12711216 18533408
14:20:41 3312 8192000000 10136443968 10505728000 1222424816 12.984 100 83 165 1 1253652128 12804016 18423296
14:21:46 2655 8192000000 10178671776 10553847808 1260263904 13.332 100 90 179 0 1292180384 12052720 19863760
14:22:51 2022 8192000000 10206563872 10590097408 1288175344 13.588 100 84 166 2 1319995056 13669616 18150096
14:23:54 30157 8192000000 10213463232 10605600768 1304938672 13.740 100 65 127 1 1328420064 8681888 14799504
14:25:25 4265 8192000000 10204081664 10613227520 1311913328 13.803 100 34 68 0 1327719184 8295360 7510496
14:26:32 11273 8192000000 10209026912 10621820928 1320351888 13.880 100 18 36 1 1330172736 5759792 4061056
14:27:44 7771 8192000000 10210242592 10635243520 1328404928 13.953 100 17 34 0 1341847024 10106192 3335904
14:28:53 40702 8192000000 10234897424 10646536192 1333827504 14.002 100 47 94 2 1350452624 5481168 11143952
14:30:34 5540 8192000000 10226729552 10647576576 1332385616 13.989 100 33 67 2 1350379904 10398080 7596208
14:31:40 9503 8192000000 10222713376 10641317888 1319704016 13.874 99 17 34 0 1332992496 9686368 3602112
14:32:50 61224 8192000000 10161799248 10569068544 1234758480 13.098 95 31 62 1 1251681424 9658912 7264032
14:34:51 6824 8192000000 10154315808 10553397248 1237768064 13.126 97 26 51 1 1249193216 5771904 5653248
14:35:58 8871 8192000000 10124403424 10521518080 1209385168 12.863 98 24 48 0 1220811456 5876672 5549616
14:37:10 6154 8192000000 10158235008 10571112448 1275125104 13.468 100 20 40 1 1285934080 5890848 4918128
14:38:17 28009 8192000000 10168209968 10579451904 1268240640 13.406 99 23 46 0 1279007344 5531840 5234864
14:39:48 7863 8192000000 10197121696 10607693824 1301874960 13.712 100 23 46 0 1313124704 5624256 5625488
14:40:58 10498 8192000000 10198710864 10614841344 1309819856 13.784 100 21 41 1 1319803280 4773776 5209648
14:42:10 12764 8192000000 10219023632 10626711552 1310374608 13.789 99 26 53 3 1320658864 4828224 5456032
14:43:27 31511 8192000000 10240413456 10654167040 1337794208 14.038 100 46 91 0 1357287008 7637008 11855792
14:45:05 36550 8192000000 10285652144 10701746176 1368569456 14.314 100 43 85 1 1397314816 19080816 9664544
14:46:46 40215 8192000000 10290391760 10720161792 1381957104 14.434 100 32 65 1 1408604080 18381232 8265744
14:48:38 10680 8192000000 10329381440 10747371520 1400276592 14.597 100 53 106 1 1436453008 23848544 12327872
14:49:58 8328 8192000000 10338353696 10759229440 1409216416 14.677 100 61 120 1 1451275808 27565408 14493984
14:51:16 12481 8192000000 10349336496 10776006656 1414393968 14.723 100 49 98 2 1460959872 32923280 13642624
14:52:35 6578 8192000000 10346618768 10774016000 1417055872 14.747 100 49 98 0 1464070896 35118016 11897008
14:53:44 7398 8192000000 10362462800 10787753984 1421615568 14.787 100 54 108 2 1474720048 39582528 13521952
14:54:59 5933 8192000000 10381493232 10796929024 1427470592 14.839 100 78 156 0 1491148448 44476448 19201408
14:56:09 5902 8192000000 10361478816 10770587648 1390602624 14.511 99 77 152 4 1458227040 48968240 18656176
14:57:18 2696 8192000000 10367428880 10772418560 1376357840 14.384 98 80 160 1 1451979328 54179568 21441920
14:58:26 3590 8192000000 10382139472 10794631168 1410578016 14.689 100 75 149 0 1488913328 58434704 19900608
14:59:34 3507 8192000000 10380993712 10790789120 1394737616 14.548 99 86 171 0 1479774672 62377776 22659280
15:00:45 1701 8192000000 10376158112 10787823616 1394872576 14.549 100 77 155 0 1478641072 64223168 19545328
15:01:51 3427 8192000000 10375402528 10785685504 1378286384 14.401 99 87 171 2 1465186896 65712832 21187680
15:02:57 12965 8192000000 10349254544 10782793728 1376447280 14.385 99 40 80 4 1451650912 63639184 11564448
15:04:12 17984 8192000000 10341155264 10774638592 1392531232 14.528 100 31 62 1 1445254896 44125856 8597808
15:05:32 20891 8192000000 10316526976 10756431872 1352035696 14.166 98 26 52 2 1413105344 53303408 7766240
15:07:05 20338 8192000000 10286943088 10738933760 1347439904 14.124 100 20 40 1 1403829792 50459088 5930800
15:08:46 11419 8192000000 10265835680 10699223040 1321227200 13.888 97 37 76 3 1339553184 9450912 8875072
15:10:00 76410 8192000000 10274383936 10721398784 1359444736 14.232 100 31 63 0 1381157920 14481664 7231520
15:12:18 45944 8192000000 10259198768 10717990912 1349809664 14.146 99 17 35 0 1371377904 17464496 4103744
15:14:06 93169 8192000000 10298346288 10747727872 1385815920 14.469 100 23 46 1 1407669472 16494624 5358928
15:16:45 64640 8192000000 10275397760 10728407040 1342666432 14.081 97 22 44 0 1355737872 7695424 5376016
15:18:50 46371 8192000000 10284910320 10734374912 1368407456 14.313 99 18 36 1 1383625232 10429136 4788640
15:20:47 75654 8192000000 10284808704 10732675072 1357873344 14.218 98 26 53 0 1371951520 7643616 6434560
15:23:49 95522 8192000000 10290087520 10749685760 1388722576 14.494 100 28 56 1 1411532176 15895456 6914144
15:26:26 35346 8192000000 10296305728 10753146880 1394371920 14.545 100 23 46 2 1413293824 13377696 5544208
15:28:04 84017 8192000000 10297372944 10754433024 1366750080 14.298 98 8 16 0 1387129104 18747488 1631536
15:31:23 62757 8192000000 10295452336 10753110016 1384455168 14.456 99 19 40 0 1398082512 9370224 4257120
15:33:26 52223 8192000000 10293604064 10763444224 1396026112 14.560 100 12 24 0 1410703984 12065648 2612224
15:35:19 71418 8192000000 10300976688 10768162816 1403140160 14.623 100 10 20 0 1415242112 10134304 1967648
15:37:32 62036 8192000000 10306475616 10763890688 1368286032 14.312 97 23 46 0 1387226752 13628704 5312016
15:39:34 110569 8192000000 10310472032 10775089152 1382243648 14.437 98 15 30 0 1403964992 18301680 3419664
15:42:25 98084 8192000000 10282484480 10734247936 1292281760 13.625 92 12 26 1 1315341920 20527840 2532320
15:45:12 53653 8192000000 10282309920 10739621888 1350385616 14.151 98 22 44 0 1368193920 13382160 4426144
15:47:06 86944 8192000000 10227632432 10663006208 1256596752 13.299 92 15 30 0 1271580640 11815920 3167968
15:49:36 63375 8192000000 10273510928 10720038912 1341006672 14.066 99 34 68 0 1359499280 11116720 7375888
15:51:41 5010 8192000000 10269877520 10722435072 1362420848 14.259 100 29 58 1 1383223664 14642096 6160720
15:52:47 62102 8192000000 10264434208 10711756800 1349416624 14.142 98 19 38 0 1364070288 9946288 4707376
15:54:53 42639 8192000000 10247163616 10700963840 1335314640 14.015 98 17 34 1 1347318736 8099760 3904336
15:57:41 40406 8192000000 10243499152 10691395584 1328867152 13.957 98 31 62 1 1348181216 11682544 7631520
15:59:26 68659 8192000000 10259277488 10709045248 1303030496 13.723 96 16 32 0 1312953104 6039584 3883024
16:01:37 26439 8192000000 10281243232 10730909696 1358956192 14.228 99 18 35 2 1373762192 10515440 4290560
16:03:09 54983 8192000000 10271576784 10726301696 1356292320 14.204 99 24 48 1 1378614576 16679872 5642384
16:05:06 16463 8192000000 10260862592 10703482880 1329165424 13.960 98 29 58 4 1351602400 15526528 6910448
16:06:28 77731 8192000000 10258567984 10717872128 1323135568 13.905 97 26 52 1 1347276240 18488512 5652160
16:09:01 47582 8192000000 10295324672 10729189376 1362175680 14.257 99 36 70 0 1385049568 15309408 7564480
16:10:53 96343 8192000000 10285234704 10726739968 1328756176 13.956 97 8 16 1 1344675232 14207984 1711072
16:13:36 114692 8192000000 10298540384 10744270848 1385280752 14.464 100 29 58 0 1398865264 7290080 6294432
16:16:31 44305 8192000000 10290473312 10757406720 1394031232 14.542 100 9 18 0 1410584592 14871808 1681552
16:18:37 90670 8192000000 10303044768 10758389760 1364808928 14.281 98 14 29 1 1376713264 8665120 3239216
16:21:08 94437 8192000000 10319427392 10781310976 1396884928 14.567 99 21 41 1 1415242592 13309456 5048208
16:23:51 41932 8192000000 10312289536 10766614528 1367561904 14.305 97 16 32 0 1382821632 11462384 3797344
16:25:33 62160 8192000000 10303905760 10757484544 1352473600 14.170 97 11 22 0 1366665664 11517760 2674304
16:27:39 31579 8192000000 10320920288 10765942784 1387266912 14.481 99 25 51 2 1408613392 15392912 5953568
16:29:18 70361 8192000000 10288916704 10740105216 1321446288 13.890 95 5 10 0 1330797392 8589952 761152
16:31:28 52221 8192000000 10279324544 10742661120 1340528944 14.062 97 36 72 1 1368324448 19684480 8111024
16:33:21 62949 8192000000 10246775744 10686164992 1258761744 13.319 92 26 52 1 1272563488 7920048 5881696
16:35:25 54868 8192000000 10242296880 10698625024 1321033856 13.886 98 13 26 0 1333134048 9542896 2557296
16:37:40 80172 8192000000 10259987984 10713862144 1358523472 14.224 100 17 34 0 1368904992 6516464 3865056
16:40:04 93738 8192000000 10261440000 10716024832 1351952384 14.165 99 12 24 0 1366588688 11222192 3414112

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 02-06-2022 16:44:40 to 02-06-2022 16:44:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 128 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-06-2022 16:44:55 to 02-06-2022 16:44:57.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          245429
MON$OLDEST_ACTIVE               245430
MON$OLDEST_SNAPSHOT             245430
MON$NEXT_TRANSACTION            245430
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-03 16:41:55.3470
MON$PAGES                       2014272
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33601 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33601 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33601 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33601 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Jun  2 16:42:57 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
--- end of diff output ---

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

02.06.22 16:44:58. There are 3 logs in /var/tmp/logs-oltp30 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-06-02 16:45:01 No severe PSQL-related problems occured
02.06.22 16:45:02. End of report.