Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.10.2021 19: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16109908 kB
MemAvailable 30694680 kB
Buffers 0 kB
Cached 15610284 kB
SwapCached 3876 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.8
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 2021-10-12 19:38:17
DTS_END                         2021-10-12 19:37:54.2070
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/37298
TRN_ID                          235600
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: 8812.80 20629 1057536
customer order: creation 167.87 379 20144
customer order: refuse 14.22 335 1706
order to supplier: creation 44.68 1291 5362
order to supplier: removal 3.50 101734 420
invoice (draft): creation 44.87 3349 5384
invoice (draft): removal 49.92 881 5990
invoice accept: apply 39.44 20075 4733
invoice accept: cancel 45.43 749 5451
customer reserve: creation 3683.53 198 442024
customer reserve: removal 4432.71 62 531925
realization accept: apply 80.38 179 9645
realization accept: cancel 82.37 113 9884
payment from customer: creation 56.22 117 6746
payment from customer: removal 5.59 70 671
payment to supplier: creation 47.06 126 5647
payment to supplier: removal 3.35 72 402
service: total inventory turnovers 4.62 1155 554
service: total monetary turnovers 6.23 38 747
service: aggregate perf. data 0.81 23691 97
service: refresh index statistics 0.03 257971 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-10-2021 19:40:04 to 12-10-2021 19:40:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3572
TEST_TIME 2 3408
TEST_TIME 3 5149
TEST_TIME 4 5343
TEST_TIME 5 5417
TEST_TIME 6 5355
TEST_TIME 7 5996
TEST_TIME 8 5940
TEST_TIME 9 6004
TEST_TIME 10 6258
TEST_TIME 11 6515
TEST_TIME 12 6558
TEST_TIME 13 6822
TEST_TIME 14 6873
TEST_TIME 15 7456
TEST_TIME 16 7465
TEST_TIME 17 7554
TEST_TIME 18 7527
TEST_TIME 19 8225
TEST_TIME 20 8287
TEST_TIME 21 8555
TEST_TIME 22 8570
TEST_TIME 23 8679
TEST_TIME 24 8853
TEST_TIME 25 8834
TEST_TIME 26 9449
TEST_TIME 27 9979
TEST_TIME 28 10889
TEST_TIME 29 10946
TEST_TIME 30 10729
TEST_TIME 31 10636
TEST_TIME 32 10637
TEST_TIME 33 10497
TEST_TIME 34 10328
TEST_TIME 35 10154
TEST_TIME 36 10073
TEST_TIME 37 10093
TEST_TIME 38 10010
TEST_TIME 39 9829
TEST_TIME 40 9738
TEST_TIME 41 9785
TEST_TIME 42 9818
TEST_TIME 43 9714
TEST_TIME 44 9575
TEST_TIME 45 9468
TEST_TIME 46 9411
TEST_TIME 47 9548
TEST_TIME 48 9521
TEST_TIME 49 9454
TEST_TIME 50 9306
TEST_TIME 51 9197
TEST_TIME 52 9230
TEST_TIME 53 9264
TEST_TIME 54 9261
TEST_TIME 55 9183
TEST_TIME 56 9153
TEST_TIME 57 9232
TEST_TIME 58 9181
TEST_TIME 59 9182
TEST_TIME 60 9158
TEST_TIME 61 9298
TEST_TIME 62 9232
TEST_TIME 63 9291
TEST_TIME 64 9276
TEST_TIME 65 9243
TEST_TIME 66 9285
TEST_TIME 67 9251
TEST_TIME 68 9254
TEST_TIME 69 9231
TEST_TIME 70 9197
TEST_TIME 71 9104
TEST_TIME 72 9136
TEST_TIME 73 9097
TEST_TIME 74 9080
TEST_TIME 75 9024
TEST_TIME 76 8981
TEST_TIME 77 8950
TEST_TIME 78 8891
TEST_TIME 79 8849
TEST_TIME 80 8786
TEST_TIME 81 8743
TEST_TIME 82 8907
TEST_TIME 83 8919
TEST_TIME 84 8979
TEST_TIME 85 8966
TEST_TIME 86 8961
TEST_TIME 87 9013
TEST_TIME 88 9007
TEST_TIME 89 8991
TEST_TIME 90 9014
TEST_TIME 91 9013
TEST_TIME 92 9044
TEST_TIME 93 9155
TEST_TIME 94 9071
TEST_TIME 95 9011
TEST_TIME 96 8977
TEST_TIME 97 8974
TEST_TIME 98 8951
TEST_TIME 99 8908
TEST_TIME 100 8889
TEST_TIME 101 8859
TEST_TIME 102 8825
TEST_TIME 103 8840
TEST_TIME 104 8846
TEST_TIME 105 8860
TEST_TIME 106 8842
TEST_TIME 107 8844
TEST_TIME 108 8852
TEST_TIME 109 8876
TEST_TIME 110 8903
TEST_TIME 111 8932
TEST_TIME 112 8904
TEST_TIME 113 8943
TEST_TIME 114 8996
TEST_TIME 115 8982
TEST_TIME 116 8928
TEST_TIME 117 8902
TEST_TIME 118 8883
TEST_TIME 119 8862
TEST_TIME 120 8820

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-10-2021 19:40:07 to 12-10-2021 19:40:11.

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 1467164 1467164 0 0.00 0 29114 6 0 0
doc_list_biud 1467161 1467161 0 0.00 0 75940 29 0 0
sp_add_invoice_to_stock 4887 4733 154 3.15 23 186279 20076 0 154
sp_cancel_adding_invoice 5451 5451 0 0.00 5 57671 749 0 0
sp_cancel_client_order 1706 1706 0 0.00 5 10489 336 0 0
sp_cancel_customer_reserve 531925 531925 0 0.00 1 19902 63 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 3 4671 71 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 3257 73 0 0
sp_cancel_supplier_invoice 6001 5990 11 0.18 2 75961 881 0 11
sp_cancel_supplier_order 420 420 0 0.00 7 960282 101735 0 0
sp_cancel_write_off 9884 9884 0 0.00 1 18806 114 0 0
sp_client_order 20144 20144 0 0.00 3 34144 380 0 0
sp_customer_reserve 442024 442024 0 0.00 0 44198 198 0 0
sp_fill_shopping_cart 30873 30873 0 0.00 1 19372 38 0 0
sp_fill_shopping_cart_clo_res 442003 442003 0 0.00 0 16836 17 0 0
sp_get_clo_for_invoice 4710 4710 0 0.00 0 19396 317 0 0
sp_kill_cost_storno 16408 16408 0 0.00 0 11398 58 0 0
sp_kill_qstorno_ret_qs2qd 1080144 1080144 0 0.00 0 75937 34 0 0
sp_kill_qty_storno 569731 569731 0 0.00 0 75939 73 0 0
sp_lock_selected_doc 19954 19954 0 0.00 0 9638 7 0 0
sp_make_cost_storno 26748 26748 0 0.00 0 29101 55 0 0
sp_multiply_rows_for_pdistr 26748 26748 0 0.00 0 11646 27 0 0
sp_multiply_rows_for_qdistr 20144 20144 0 0.00 0 12873 162 0 0
sp_pay_from_customer 6746 6746 0 0.00 4 13930 117 0 0
sp_pay_to_supplier 5647 5647 0 0.00 4 23800 127 0 0
sp_payment_common 12393 12393 0 0.00 1 11748 34 0 0
sp_qd_handle_on_cancel_clo 1706 1706 0 0.00 0 10479 315 0 0
sp_qd_handle_on_invoice_upd_sts 10161 10161 0 0.00 0 25316 321 0 0
sp_qd_handle_on_reserve_upd_sts 9645 9645 0 0.00 0 14738 64 0 0
sp_reserve_write_off 9645 9645 0 0.00 6 29125 180 0 0
sp_supplier_invoice 5384 5384 0 0.00 6 78203 3349 0 0
sp_supplier_order 5362 5362 0 0.00 4 37733 1291 0 0
srv_aggregate_perf_data 97 97 0 0.00 2763 576073 23692 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1536 139707 46008 0 0
srv_find_qd_qs_mism 1011726 1011726 0 0.00 0 13960 6 0 0
srv_make_invnt_saldo 554 554 0 0.00 33 113261 1156 0 0
srv_make_money_saldo 747 747 0 0.00 1 11605 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 54842 476679 257971 0 0
t$perf-abend:sp_add_invoice_to_stock 154 154 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 4733 4733 0 0.00 0 16408 181 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 0 4728 74 0 0
t$perf-norm:sp_cancel_client_order 1706 1706 0 0.00 0 1817 10 0 0
t$perf-norm:sp_cancel_customer_reserve 642 642 0 0.00 0 3272 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 3611 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 3787 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 1215 18 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 25280 1625 0 0
t$perf-norm:sp_cancel_write_off 956 956 0 0.00 0 3379 11 0 0
t$perf-norm:sp_client_order 20144 20144 0 0.00 0 8338 17 0 0
t$perf-norm:sp_customer_reserve 987 987 0 0.00 0 13459 21 0 0
t$perf-norm:sp_pay_from_customer 6746 6746 0 0.00 0 13278 12 0 0
t$perf-norm:sp_pay_to_supplier 5647 5647 0 0.00 0 8357 13 0 0
t$perf-norm:sp_reserve_write_off 9645 9645 0 0.00 0 7927 12 0 0
t$perf-norm:sp_supplier_invoice 5384 5384 0 0.00 0 13041 25 0 0
t$perf-norm:sp_supplier_order 5362 5362 0 0.00 0 4208 17 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 546 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 127 2 0 0
t$perf-norm:srv_make_invnt_saldo 554 554 0 0.00 0 1097 22 0 0
t$perf-norm:srv_make_money_saldo 747 747 0 0.00 0 1305 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 17 7 0 0
v_add_invoice_to_stock 9724 9416 308 3.16 0 5407 18 0 308
v_all_customers 36388 36388 0 0.00 0 15 1 0 0
v_all_suppliers 29954 29954 0 0.00 0 27 1 0 0
v_all_wares 40288 40288 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 2014 9 0 0
v_cancel_client_order 3412 3412 0 0.00 0 2457 5 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 1281 4 0 0
v_cancel_customer_reserve 1284 1284 0 0.00 0 3117 12 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 2380 8 0 0
v_cancel_supplier_invoice 784 762 22 2.80 0 1970 15 0 22
v_cancel_supplier_order 840 840 0 0.00 0 441 4 0 0
v_cancel_write_off 1912 1912 0 0.00 0 3077 16 0 0
v_max_id_clo_ord 5360 5360 0 0.00 0 1604 2 0 0
v_max_id_clo_res 987 987 0 0.00 0 2319 15 0 0
v_max_id_ord_sup 5369 5369 0 0.00 0 4596 3 0 0
v_max_non_paid_invoice 1399 1399 0 0.00 0 3414 5 0 0
v_max_non_paid_realizn 3629 3629 0 0.00 0 4678 8 0 0
v_min_id_clo_ord 5360 5360 0 0.00 0 2792 6 0 0
v_min_id_clo_res 987 987 0 0.00 0 2365 5 0 0
v_min_id_ord_sup 5369 5369 0 0.00 0 6296 7 0 0
v_min_non_paid_invoice 5647 5647 0 0.00 0 9853 23 0 0
v_min_non_paid_realizn 6746 6746 0 0.00 0 11277 27 0 0
v_our_firm 10134 10134 0 0.00 0 19 1 0 0
v_reserve_write_off 19290 19290 0 0.00 0 3593 6 0 0
x_lock_depdocs_on_canc_invoice 11060 11060 0 0.00 0 16176 128 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 8784 396 0 0
x_make_qstorno_1000_1200 5362 5362 0 0.00 1 37696 1214 0 0
x_make_qstorno_1200_2000 5384 5384 0 0.00 3 78153 3215 0 0
x_make_qstorno_2100_3300 442024 442024 0 0.00 0 44150 179 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-10-2021 19:40:12 to 12-10-2021 19:40:16.

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
17:07:57 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:08:59 26504 8192000000 9911672656 10225983488 994545664 10.826 100 87 174 0 1023065312 6231616 22288032
17:10:35 17206 8192000000 9964224176 10291331072 1045142512 11.314 100 90 180 0 1076528960 8701360 22685088
17:11:59 21515 8192000000 10007255120 10345172992 1086713120 11.711 100 85 170 0 1117864896 10230816 20920960
17:13:25 12140 8192000000 10047112752 10398334976 1132144752 12.142 100 83 166 2 1163975376 11588528 20242096
17:14:40 8708 8192000000 10090915248 10453237760 1176676448 12.559 100 81 163 0 1207700752 12145200 18879104
17:15:50 4944 8192000000 10146195504 10514587648 1222360576 12.983 100 81 164 0 1253044224 12832128 17851520
17:16:57 8079 8192000000 10168643056 10546434048 1257057184 13.303 100 84 168 2 1289585328 13818464 18709680
17:18:06 2068 8192000000 10202503136 10586394624 1286372672 13.571 100 93 183 1 1319530864 13017936 20140256
17:19:10 21083 8192000000 10218085072 10606645248 1301740288 13.711 100 80 156 0 1330470016 10615344 18114384
17:20:31 6389 8192000000 10197955712 10609807360 1311641120 13.801 100 38 76 1 1327981424 8347168 7993136
17:21:41 55633 8192000000 10217159808 10635284480 1323305024 13.907 100 36 70 0 1337806880 5450592 9051264
17:24:01 10010 8192000000 10227520592 10651021312 1331787040 13.983 100 16 32 1 1340397424 4977120 3633264
17:25:12 5553 8192000000 10226566512 10648424448 1334819696 14.011 100 23 46 1 1346115232 5824336 5471200
17:26:18 6638 8192000000 10227287600 10646335488 1329492032 13.963 100 26 52 0 1345114800 9848624 5774144
17:27:27 19747 8192000000 10210573088 10631204864 1280832432 13.521 96 42 83 2 1300673696 10875328 8965936
17:28:49 9509 8192000000 10198159456 10612166656 1295867456 13.658 99 16 32 0 1305318320 5832608 3618256
17:29:59 7797 8192000000 10127254016 10523451392 1171291712 12.509 92 22 44 1 1181595344 5353600 4950032
17:31:07 66353 8192000000 10128266256 10528030720 1183224928 12.620 94 20 38 1 1191983632 4502224 4256480
17:33:14 6092 8192000000 10157732192 10561507328 1255155760 13.286 99 33 65 1 1274227664 9638000 9433904
17:34:21 12700 8192000000 10178659888 10593521664 1292257904 13.625 100 18 36 0 1302083360 5782672 4042784
17:35:34 9513 8192000000 10199844736 10609872896 1303461968 13.727 100 15 29 1 1311734592 4889280 3383344
17:36:45 12646 8192000000 10197632752 10613325824 1306848800 13.757 100 48 94 2 1323253648 5324080 11080768
17:38:01 10194 8192000000 10251896448 10650386432 1333206208 13.996 100 68 132 2 1355363200 7293584 14863408
17:39:16 23813 8192000000 10267410928 10676973568 1350082672 14.148 100 68 135 3 1378083680 12710256 15290752
17:40:45 39020 8192000000 10272040480 10698604544 1363157776 14.266 100 33 65 1 1383602784 13163536 7281472
17:42:41 13029 8192000000 10281841632 10709094400 1363287664 14.267 99 39 78 1 1388276192 15806096 9182432
17:43:57 5594 8192000000 10310068528 10730835968 1391276720 14.517 100 53 100 1 1420691664 17853184 11561760
17:45:16 7874 8192000000 10309743136 10729058304 1387475104 14.483 100 64 128 0 1421440128 19154576 14810448
17:46:28 4674 8192000000 10315487248 10736644096 1393472928 14.537 100 60 120 0 1427829520 20046032 14310560
17:47:39 19708 8192000000 10326342784 10753466368 1387753616 14.486 99 55 110 2 1424427248 23462960 13210672
17:49:04 8619 8192000000 10350505408 10770071552 1414888848 14.727 100 71 142 1 1461778528 29433792 17455888
17:50:19 6241 8192000000 10375958192 10797002752 1427702848 14.841 100 73 141 0 1483254864 37680192 17871824
17:51:30 2922 8192000000 10377564976 10795474944 1420448672 14.777 100 75 150 1 1478979024 39261840 19268512
17:52:38 3453 8192000000 10364139808 10790551552 1414416976 14.723 100 54 107 0 1472937248 44419072 14101200
17:53:49 4492 8192000000 10365801104 10776530944 1401881648 14.612 100 84 168 1 1466989056 44313056 20794352
17:55:01 6234 8192000000 10370227040 10781728768 1392266768 14.526 98 73 145 0 1463262736 52548240 18447728
17:56:12 1534 8192000000 10382022400 10791854080 1402605408 14.618 100 83 164 0 1481707536 57511664 21590464
17:57:17 11856 8192000000 10359491824 10782752768 1397097136 14.569 100 55 108 2 1465050992 52855760 15098096
17:58:34 8913 8192000000 10344513728 10774552576 1378896160 14.407 99 40 80 1 1442476288 53154896 10425232
17:59:59 23020 8192000000 10333939552 10766950400 1369180224 14.320 99 29 58 0 1433160256 55817616 8162416
18:01:36 25294 8192000000 10332101808 10766147584 1358649088 14.225 98 34 68 2 1427331072 59531424 9150560
18:03:03 17606 8192000000 10323417408 10764632064 1368792768 14.316 100 23 46 0 1429558672 53969040 6796864
18:04:44 57567 8192000000 10292876448 10743967744 1341519520 14.071 98 15 30 2 1397485280 51672160 4293600
18:06:42 58146 8192000000 10303585632 10740289536 1384765776 14.459 100 48 96 2 1405664240 8479936 12418528
18:09:25 63556 8192000000 10267114736 10718740480 1343061408 14.085 97 9 18 0 1353437456 8608736 1767312
18:11:31 34468 8192000000 10278756560 10726113280 1367797664 14.307 99 21 43 0 1378137664 5583440 4756560
18:13:15 50461 8192000000 10286683504 10746376192 1385226128 14.463 100 18 37 0 1399224080 10023120 3974832
18:15:06 110180 8192000000 10293195808 10745573376 1372964928 14.354 99 16 31 0 1384050320 7799552 3285840
18:18:06 85190 8192000000 10294308464 10769211392 1400937696 14.603 100 20 40 0 1419687424 14432432 4317296
18:20:38 65323 8192000000 10289958256 10740293632 1347039216 14.121 96 17 34 0 1361677888 9797072 4841600
18:22:52 33868 8192000000 10299110064 10746601472 1378979424 14.407 99 32 64 0 1402615136 15904080 7731632
18:24:41 72886 8192000000 10289543248 10754805760 1394680112 14.548 100 14 28 0 1406977872 9206032 3091728
18:27:00 56007 8192000000 10289421632 10754809856 1386721472 14.477 99 12 24 1 1398750368 9205360 2823536
18:28:56 61297 8192000000 10302701840 10767818752 1392325536 14.527 99 27 54 0 1410889328 12361424 6202368
18:31:13 92094 8192000000 10314214368 10777030656 1408923728 14.674 100 8 16 0 1419336160 8600912 1811520
18:33:46 80591 8192000000 10294293744 10760638464 1370742592 14.334 97 18 36 1 1385065136 10448032 3874512
18:36:09 127035 8192000000 10312082224 10770522112 1340898672 14.066 95 14 28 0 1354049936 10038464 3112800
18:39:16 139707 8192000000 10315500528 10774642688 1351049776 14.157 96 14 28 0 1372904576 19196720 2658080
18:42:37 44543 8192000000 10318493696 10755854336 1374348448 14.366 98 29 58 0 1390097632 9469488 6279696
18:44:26 61169 8192000000 10300036928 10738532352 1325180128 13.924 95 31 62 0 1342380336 10622496 6577712
18:46:29 29305 8192000000 10295769216 10737545216 1347329984 14.123 97 27 54 0 1367483120 14416960 5736176
18:47:59 106540 8192000000 10286345280 10730106880 1335244032 14.015 97 27 53 0 1351991504 10773392 5974080
18:50:51 3018 8192000000 10265459696 10715279360 1347678336 14.127 99 29 57 1 1365454896 11551024 6225536
18:51:55 42444 8192000000 10281480752 10721013760 1357087312 14.211 99 23 46 0 1369486624 7626496 4772816
18:53:38 102456 8192000000 10260315552 10711445504 1295322320 13.653 94 13 26 0 1310748080 12241680 3184080
18:56:28 41260 8192000000 10252840096 10708668416 1338491856 14.044 99 12 24 1 1359481216 17652416 3336944
18:58:10 74933 8192000000 10288234016 10745958400 1375128224 14.373 100 18 36 1 1407681712 28735072 3818416
19:01:20 66834 8192000000 10271305968 10713309184 1329418864 13.962 97 11 22 0 1349895904 17892112 2584928
19:03:35 59009 8192000000 10272169520 10725855232 1352358896 14.169 99 24 48 1 1371714880 13891760 5464224
19:05:35 54680 8192000000 10282126368 10722553856 1349397216 14.142 98 26 51 1 1368056320 12295632 6363472
19:07:32 55828 8192000000 10303962896 10744578048 1386413232 14.474 100 47 94 0 1408696752 12134320 10149200
19:09:35 51213 8192000000 10303974960 10764357632 1390545936 14.511 100 22 44 0 1414457664 19140752 4770976
19:11:29 97231 8192000000 10286250720 10726617088 1339299936 14.051 97 47 94 0 1358956896 8818560 10838400
19:14:18 45951 8192000000 10275000624 10727002112 1341837520 14.074 97 19 38 0 1354226256 7917536 4471200
19:16:08 48000 8192000000 10284743664 10744688640 1386995712 14.479 100 8 16 0 1397033616 7888448 2149456
19:17:56 65768 8192000000 10282027600 10733232128 1351841728 14.164 97 14 28 0 1364506560 9330800 3334032
19:20:03 52879 8192000000 10279035184 10739691520 1348567984 14.135 97 17 34 1 1366243616 13311792 4363840
19:22:00 21624 8192000000 10292164560 10751225856 1380771488 14.423 99 14 28 0 1396552240 12804880 2975872
19:23:33 27033 8192000000 10295786336 10742722560 1362807424 14.263 98 29 58 1 1381437184 12033040 6596720
19:25:03 37588 8192000000 10290806288 10732986368 1369056656 14.319 98 43 84 1 1394609312 15180864 10371792
19:26:42 65505 8192000000 10296707376 10749808640 1367283104 14.303 98 16 32 0 1384083712 12859808 3940800
19:29:00 30527 8192000000 10290776368 10736906240 1346871024 14.119 97 13 24 1 1360126544 10678224 2577296
19:30:37 39216 8192000000 10293321088 10753507328 1378347920 14.402 100 10 20 0 1398866944 18249488 2269536
19:32:21 36407 8192000000 10291945920 10732720128 1378415248 14.402 100 19 38 0 1397074192 14530816 4128128
19:33:59 95816 8192000000 10275302448 10722598912 1326386672 13.934 96 33 66 0 1343776976 10213616 7176688
19:36:38 55173 8192000000 10266445392 10721107968 1351173168 14.158 98 10 20 1 1365007648 11781072 2053408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 12-10-2021 19:40:18 to 12-10-2021 19:40:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 154 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 12-10-2021 19:40:33 to 12-10-2021 19:40:35.

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          235641
MON$OLDEST_ACTIVE               237320
MON$OLDEST_SNAPSHOT             237320
MON$NEXT_TRANSACTION            237320
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                       2013248
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 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 ---
:219: 
:220: mscs-test-161.budzdorov.ru    Tue Oct 12 19:38:23 2021
:221:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:222: 
--- end of diff output ---

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

12.10.21 19:40:36. 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
2021-10-12 19:40:39 No severe PSQL-related problems occured
12.10.21 19:40:40. End of report.