Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.01.2023 01:40
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 15144664 kB
MemAvailable 30422560 kB
Buffers 0 kB
Cached 16576860 kB
SwapCached 10744 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.11
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 2023-01-01 01:38:32
DTS_END                         2023-01-01 01:38:32.4270
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/32892
TRN_ID                          246029
ATT_ID                          715
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: 9741.21 19387 1168945
customer order: creation 174.38 393 20925
customer order: refuse 15.07 365 1808
order to supplier: creation 46.83 1257 5620
order to supplier: removal 3.55 96041 426
invoice (draft): creation 47.16 3540 5659
invoice (draft): removal 55.98 719 6718
invoice accept: apply 42.49 17656 5099
invoice accept: cancel 50.54 702 6065
customer reserve: creation 3979.43 172 477531
customer reserve: removal 5023.03 52 602764
realization accept: apply 84.06 183 10087
realization accept: cancel 86.13 103 10335
payment from customer: creation 60.90 108 7308
payment from customer: removal 5.97 78 716
payment to supplier: creation 49.85 127 5982
payment to supplier: removal 3.38 59 406
service: total inventory turnovers 4.83 1099 579
service: total monetary turnovers 6.83 33 819
service: aggregate perf. data 0.78 23973 94
service: refresh index statistics 0.03 241072 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 01-01-2023 01:40:25 to 01-01-2023 01:40:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4429
TEST_TIME 2 3407
TEST_TIME 3 3441
TEST_TIME 4 3737
TEST_TIME 5 3446
TEST_TIME 6 3648
TEST_TIME 7 4199
TEST_TIME 8 4838
TEST_TIME 9 5249
TEST_TIME 10 5325
TEST_TIME 11 5280
TEST_TIME 12 5220
TEST_TIME 13 5666
TEST_TIME 14 5837
TEST_TIME 15 6546
TEST_TIME 16 6547
TEST_TIME 17 7000
TEST_TIME 18 7086
TEST_TIME 19 7571
TEST_TIME 20 7777
TEST_TIME 21 8135
TEST_TIME 22 8096
TEST_TIME 23 8679
TEST_TIME 24 8578
TEST_TIME 25 8962
TEST_TIME 26 9794
TEST_TIME 27 9926
TEST_TIME 28 10272
TEST_TIME 29 10332
TEST_TIME 30 10865
TEST_TIME 31 10766
TEST_TIME 32 10597
TEST_TIME 33 10655
TEST_TIME 34 10621
TEST_TIME 35 10635
TEST_TIME 36 10581
TEST_TIME 37 10425
TEST_TIME 38 10470
TEST_TIME 39 10308
TEST_TIME 40 10209
TEST_TIME 41 10258
TEST_TIME 42 10178
TEST_TIME 43 10095
TEST_TIME 44 10026
TEST_TIME 45 10080
TEST_TIME 46 10032
TEST_TIME 47 10082
TEST_TIME 48 10023
TEST_TIME 49 10040
TEST_TIME 50 9996
TEST_TIME 51 9908
TEST_TIME 52 9837
TEST_TIME 53 9786
TEST_TIME 54 9738
TEST_TIME 55 9641
TEST_TIME 56 9621
TEST_TIME 57 9588
TEST_TIME 58 9572
TEST_TIME 59 9635
TEST_TIME 60 9666
TEST_TIME 61 9835
TEST_TIME 62 9946
TEST_TIME 63 9925
TEST_TIME 64 10056
TEST_TIME 65 10070
TEST_TIME 66 10078
TEST_TIME 67 10179
TEST_TIME 68 10126
TEST_TIME 69 10284
TEST_TIME 70 10293
TEST_TIME 71 10231
TEST_TIME 72 10114
TEST_TIME 73 10060
TEST_TIME 74 10026
TEST_TIME 75 10077
TEST_TIME 76 10026
TEST_TIME 77 9976
TEST_TIME 78 9903
TEST_TIME 79 9837
TEST_TIME 80 9804
TEST_TIME 81 9764
TEST_TIME 82 9742
TEST_TIME 83 9768
TEST_TIME 84 9827
TEST_TIME 85 9875
TEST_TIME 86 9864
TEST_TIME 87 9861
TEST_TIME 88 9837
TEST_TIME 89 9862
TEST_TIME 90 9882
TEST_TIME 91 9898
TEST_TIME 92 9942
TEST_TIME 93 9948
TEST_TIME 94 9923
TEST_TIME 95 9983
TEST_TIME 96 9976
TEST_TIME 97 9972
TEST_TIME 98 9891
TEST_TIME 99 9856
TEST_TIME 100 9855
TEST_TIME 101 9841
TEST_TIME 102 9798
TEST_TIME 103 9754
TEST_TIME 104 9739
TEST_TIME 105 9682
TEST_TIME 106 9613
TEST_TIME 107 9682
TEST_TIME 108 9697
TEST_TIME 109 9679
TEST_TIME 110 9654
TEST_TIME 111 9747
TEST_TIME 112 9740
TEST_TIME 113 9797
TEST_TIME 114 9811
TEST_TIME 115 9779
TEST_TIME 116 9826
TEST_TIME 117 9871
TEST_TIME 118 9839
TEST_TIME 119 9819
TEST_TIME 120 9745

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 01-01-2023 01:40:29 to 01-01-2023 01:40: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 1609569 1609569 0 0.00 0 24011 6 0 0
doc_list_biud 1609569 1609569 0 0.00 0 48318 25 0 0
sp_add_invoice_to_stock 5214 5099 115 2.20 29 177144 17657 0 115
sp_cancel_adding_invoice 6065 6065 0 0.00 5 73429 702 0 0
sp_cancel_client_order 1808 1808 0 0.00 4 19109 366 0 0
sp_cancel_customer_reserve 602764 602764 0 0.00 0 48319 53 0 0
sp_cancel_pay_from_customer 716 716 0 0.00 2 4986 79 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 3699 59 0 0
sp_cancel_supplier_invoice 6729 6718 11 0.16 1 16665 719 0 11
sp_cancel_supplier_order 426 426 0 0.00 6 1037109 96042 0 0
sp_cancel_write_off 10335 10335 0 0.00 1 8056 103 0 0
sp_client_order 20925 20925 0 0.00 3 44335 394 0 0
sp_customer_reserve 477531 477531 0 0.00 0 34382 172 0 0
sp_fill_shopping_cart 32188 32188 0 0.00 1 34393 39 0 0
sp_fill_shopping_cart_clo_res 477528 477528 0 0.00 0 32121 14 0 0
sp_get_clo_for_invoice 5094 5094 0 0.00 0 57889 345 0 0
sp_kill_cost_storno 17522 17522 0 0.00 0 9174 51 0 0
sp_kill_qstorno_ret_qs2qd 1223007 1223007 0 0.00 0 48316 29 0 0
sp_kill_qty_storno 643297 643297 0 0.00 0 48318 62 0 0
sp_lock_selected_doc 20988 20988 0 0.00 0 8680 6 0 0
sp_make_cost_storno 28471 28471 0 0.00 0 13205 52 0 0
sp_multiply_rows_for_pdistr 28471 28471 0 0.00 0 10665 25 0 0
sp_multiply_rows_for_qdistr 20925 20925 0 0.00 0 42824 179 0 0
sp_pay_from_customer 7308 7308 0 0.00 4 12505 109 0 0
sp_pay_to_supplier 5982 5982 0 0.00 4 12263 128 0 0
sp_payment_common 13290 13290 0 0.00 2 9148 31 0 0
sp_qd_handle_on_cancel_clo 1808 1808 0 0.00 0 19103 330 0 0
sp_qd_handle_on_invoice_upd_sts 11159 11159 0 0.00 0 15190 302 0 0
sp_qd_handle_on_reserve_upd_sts 10087 10087 0 0.00 0 16680 61 0 0
sp_reserve_write_off 10087 10087 0 0.00 6 16701 183 0 0
sp_supplier_invoice 5659 5659 0 0.00 7 107116 3540 0 0
sp_supplier_order 5620 5620 0 0.00 4 34138 1257 0 0
srv_aggregate_perf_data 94 94 0 0.00 3079 319508 23974 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2071 111458 40937 0 0
srv_find_qd_qs_mism 1119236 1119236 0 0.00 0 24010 5 0 0
srv_make_invnt_saldo 579 579 0 0.00 37 34186 1099 0 0
srv_make_money_saldo 819 819 0 0.00 1 2221 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 77614 391849 241072 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 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 5100 5100 0 0.00 0 19681 196 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 20229 152 0 0
t$perf-norm:sp_cancel_client_order 1808 1808 0 0.00 0 10532 18 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 556 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 716 716 0 0.00 0 2162 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 1654 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 376 376 0 0.00 0 5989 28 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 38162 1524 0 0
t$perf-norm:sp_cancel_write_off 978 978 0 0.00 0 781 5 0 0
t$perf-norm:sp_client_order 20925 20925 0 0.00 0 18975 22 0 0
t$perf-norm:sp_customer_reserve 1001 1001 0 0.00 0 1106 5 0 0
t$perf-norm:sp_pay_from_customer 7308 7308 0 0.00 0 15095 15 0 0
t$perf-norm:sp_pay_to_supplier 5982 5982 0 0.00 0 17795 15 0 0
t$perf-norm:sp_reserve_write_off 10087 10087 0 0.00 0 10532 16 0 0
t$perf-norm:sp_supplier_invoice 5659 5659 0 0.00 0 20601 25 0 0
t$perf-norm:sp_supplier_order 5620 5620 0 0.00 0 20249 24 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 239 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 395 12 0 0
t$perf-norm:srv_make_invnt_saldo 579 579 0 0.00 0 549 15 0 0
t$perf-norm:srv_make_money_saldo 819 819 0 0.00 0 948 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10418 10188 230 2.20 0 5994 18 0 230
v_all_customers 38680 38680 0 0.00 0 28 1 0 0
v_all_suppliers 31396 31396 0 0.00 0 32 1 0 0
v_all_wares 41850 41850 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 1024 7 0 0
v_cancel_client_order 3616 3616 0 0.00 0 8496 9 0 0
v_cancel_customer_prepayment 1432 1432 0 0.00 0 4659 11 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 2225 7 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 168 3 0 0
v_cancel_supplier_invoice 774 752 22 2.84 0 669 16 0 22
v_cancel_supplier_order 852 852 0 0.00 0 1347 4 0 0
v_cancel_write_off 1956 1956 0 0.00 0 3042 15 0 0
v_max_id_clo_ord 5618 5618 0 0.00 0 1039 2 0 0
v_max_id_clo_res 1001 1001 0 0.00 0 12172 31 0 0
v_max_id_ord_sup 5645 5645 0 0.00 0 5820 6 0 0
v_max_non_paid_invoice 1547 1547 0 0.00 0 8792 8 0 0
v_max_non_paid_realizn 3696 3696 0 0.00 0 1233 3 0 0
v_min_id_clo_ord 5618 5618 0 0.00 0 14055 9 0 0
v_min_id_clo_res 1001 1001 0 0.00 0 3985 10 0 0
v_min_id_ord_sup 5645 5645 0 0.00 0 8824 14 0 0
v_min_non_paid_invoice 5982 5982 0 0.00 0 9128 20 0 0
v_min_non_paid_realizn 7308 7308 0 0.00 0 9127 26 0 0
v_our_firm 10394 10394 0 0.00 0 39 1 0 0
v_reserve_write_off 20174 20174 0 0.00 0 6056 7 0 0
x_lock_depdocs_on_canc_invoice 12407 12407 0 0.00 0 15676 118 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 8609 378 0 0
x_make_qstorno_1000_1200 5620 5620 0 0.00 1 34110 1179 0 0
x_make_qstorno_1200_2000 5659 5659 0 0.00 3 104304 3399 0 0
x_make_qstorno_2100_3300 477531 477531 0 0.00 0 33713 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-01-2023 01:40:34 to 01-01-2023 01:40: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
23:08:36 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:37 26781 8192000000 9890162976 10195947520 967432448 10.562 100 89 178 0 996828976 5850480 23546048
23:11:08 15073 8192000000 9928725744 10247921664 1010735888 10.982 100 82 164 0 1040279360 7867872 21675600
23:12:32 11930 8192000000 9968719024 10297643008 1054312480 11.402 100 88 174 0 1085383632 9146512 21924640
23:13:48 9361 8192000000 10020288528 10365198336 1104105392 11.877 100 75 146 0 1131844176 10149984 17588800
23:15:02 7928 8192000000 10080963840 10438017024 1171152752 12.508 100 85 165 0 1202364000 12547440 18663808
23:16:14 1510 8192000000 10126550064 10489970688 1207962336 12.850 100 92 182 0 1242505344 13867648 20675360
23:17:18 2611 8192000000 10167614528 10540019712 1247814896 13.218 100 84 167 0 1279523408 13806016 17902496
23:18:23 1189 8192000000 10201725504 10580824064 1283368960 13.544 100 88 175 0 1314667472 12392368 18906144
23:19:25 1837 8192000000 10217264288 10601443328 1300764048 13.702 100 87 174 0 1338357808 18468432 19125328
23:20:27 21852 8192000000 10195490208 10601889792 1305058192 13.741 100 31 63 0 1317857936 5953408 6846336
23:21:54 8083 8192000000 10206964752 10622488576 1320859696 13.884 100 21 41 0 1331584032 6076320 4648016
23:23:03 9724 8192000000 10212071984 10628886528 1328517440 13.954 100 21 42 0 1339398736 6338272 4543024
23:24:15 5915 8192000000 10226174656 10642251776 1337418560 14.034 100 23 47 1 1348480896 5942624 5119712
23:25:22 30313 8192000000 10229595600 10648358912 1338052768 14.040 100 38 76 0 1353442400 6300032 9089600
23:26:58 4963 8192000000 10226606800 10648584192 1338325584 14.042 100 24 48 0 1350079920 6508080 5246256
23:28:04 9675 8192000000 10212039760 10625585152 1311726576 13.802 99 20 38 1 1321608992 5176112 4706304
23:29:14 8566 8192000000 10170784624 10580852736 1275354704 13.471 99 16 32 1 1284212976 5207920 3650352
23:30:28 24868 8192000000 10145783040 10550222848 1228100544 13.037 96 40 81 1 1243529872 5298992 10130336
23:31:56 7746 8192000000 10123419408 10522378240 1180662816 12.596 94 28 55 0 1192076304 4741744 6671744
23:33:08 6550 8192000000 10155190688 10552446976 1235211328 13.102 98 23 46 0 1250320448 10096608 5012512
23:34:15 10025 8192000000 10171454528 10577387520 1268276000 13.406 99 17 34 1 1277269744 5203344 3790400
23:35:26 30745 8192000000 10178012832 10588938240 1287691280 13.583 100 45 90 1 1304483008 6305632 10486096
23:37:01 6130 8192000000 10193473888 10607419392 1300536208 13.700 100 33 68 0 1315313808 7548416 7229184
23:38:08 11912 8192000000 10212906208 10630971392 1318992880 13.868 100 23 46 1 1329372144 5122240 5257024
23:39:28 58895 8192000000 10250966464 10667945984 1339936672 14.057 100 45 90 0 1358889312 8497312 10455328
23:41:36 54423 8192000000 10260326128 10689409024 1357996752 14.219 100 21 42 0 1375052096 11934576 5120768
23:43:35 9324 8192000000 10284253232 10706554880 1371921568 14.344 100 42 84 1 1402364816 21453760 8989488
23:44:50 6899 8192000000 10310845120 10722033664 1383597040 14.449 100 73 146 1 1420587968 20003536 16987392
23:46:02 15265 8192000000 10298854352 10723909632 1378618960 14.404 100 55 109 1 1414182352 22580224 12983168
23:47:23 4912 8192000000 10315733824 10740617216 1394846064 14.549 100 46 92 1 1428507216 23302976 10358176
23:48:34 7410 8192000000 10318818000 10743042048 1378317472 14.402 98 51 102 0 1415300752 24801712 12181568
23:49:45 12158 8192000000 10336728128 10754555904 1397904016 14.576 100 61 120 1 1445724336 33606176 14214144
23:51:03 6740 8192000000 10347153232 10764173312 1403805056 14.629 100 63 127 0 1454763072 35101328 15856688
23:52:19 2520 8192000000 10321528176 10724929536 1371119328 14.337 98 83 163 0 1426334928 34611904 20603696
23:53:26 2064 8192000000 10355409424 10764791808 1400816016 14.602 100 93 180 0 1463763984 41033952 21914016
23:54:32 2158 8192000000 10325215360 10738839552 1364277696 14.276 99 66 128 2 1421694544 40666592 16750256
23:55:38 6797 8192000000 10315982192 10727890944 1347516176 14.125 97 73 142 2 1407818208 42137248 18164784
23:56:49 5998 8192000000 10329723440 10742054912 1367855968 14.308 100 64 126 0 1431281200 46932016 16493216
23:58:02 4041 8192000000 10325511504 10734977024 1368087264 14.310 100 63 125 0 1429242992 45624432 15531296
23:59:13 26082 8192000000 10317759712 10750590976 1361048384 14.247 99 40 78 1 1418835344 47842384 9944576
00:00:54 10243 8192000000 10318122112 10737913856 1360894736 14.245 100 56 112 0 1424067392 48632416 14540240
00:02:14 28198 8192000000 10320981952 10755670016 1367108592 14.301 100 26 52 0 1425294912 50539536 7646784
00:03:45 23597 8192000000 10297721840 10728923136 1339365696 14.052 99 29 58 1 1397515776 50122832 8027248
00:05:10 57164 8192000000 10280366224 10713481216 1324068656 13.914 98 15 30 0 1367334208 39318688 3946864
00:07:26 39070 8192000000 10285273360 10724638720 1342848592 14.083 99 10 21 0 1376254800 30284416 3121792
00:09:47 35672 8192000000 10288606976 10723188736 1369431616 14.322 100 17 36 0 1380937424 7793776 3712032
00:11:24 43669 8192000000 10281974352 10729422848 1349409056 14.142 98 14 28 0 1359970208 7700624 2860528
00:13:12 75012 8192000000 10288732640 10742018048 1348132976 14.131 98 15 30 0 1368659536 17574944 2951616
00:15:38 13576 8192000000 10302846208 10732224512 1379625120 14.413 100 41 81 0 1399977088 10943600 9408368
00:16:59 52468 8192000000 10295448624 10742001664 1378242080 14.401 100 33 66 0 1401998016 15516704 8239232
00:18:55 40583 8192000000 10287060256 10746974208 1389434816 14.501 100 18 37 0 1405977344 12451184 4091344
00:20:37 32130 8192000000 10297907568 10757738496 1398248336 14.579 100 39 78 0 1419161040 11430944 9481760
00:22:12 33196 8192000000 10296705648 10747047936 1376777056 14.388 99 30 61 0 1397283968 13155760 7351152
00:23:53 36546 8192000000 10311596688 10766753792 1402743984 14.619 100 27 52 0 1421652496 12927392 5981120
00:25:35 38871 8192000000 10302507360 10766753792 1402003312 14.613 100 6 12 0 1414075360 10674352 1397696
00:27:19 34963 8192000000 10304535168 10768949248 1404192480 14.632 100 10 20 0 1416299632 9635536 2471616
00:29:59 79871 8192000000 10286144352 10756001792 1348083584 14.130 96 14 27 0 1360598448 9358080 3156784
00:33:37 83290 8192000000 10286439280 10765033472 1392870752 14.531 100 4 8 0 1402361488 8705760 784976
00:36:14 38775 8192000000 10304506160 10769571840 1401230768 14.606 100 19 38 0 1419834272 13807952 4795552
00:37:53 42379 8192000000 10312337200 10766225408 1367473664 14.304 97 27 54 0 1392013968 17873408 6666896
00:39:51 47277 8192000000 10314814848 10782597120 1375512368 14.376 99 12 24 1 1406949952 28050576 3387008
00:41:49 48172 8192000000 10282640112 10741170176 1320668336 13.883 95 15 29 0 1343883744 20088800 3126608
00:43:38 74659 8192000000 10260578544 10699960320 1254392464 13.279 91 20 40 0 1273442704 14531856 4518384
00:45:53 39185 8192000000 10288782528 10721050624 1330761280 13.974 97 45 90 0 1353998448 13182272 10054896
00:47:39 47709 8192000000 10266402864 10740256768 1329628032 13.964 98 10 20 0 1351433648 19755856 2049760
00:49:37 97387 8192000000 10240695136 10680827904 1286553376 13.573 95 28 56 0 1301399760 8298960 6547424
00:52:22 25263 8192000000 10253618880 10683568128 1325920320 13.930 99 47 92 0 1345938096 9888608 10129168
00:54:07 48886 8192000000 10234733920 10691993600 1338519792 14.044 100 11 22 0 1348901024 7870544 2510688
00:57:40 61551 8192000000 10231022272 10681679872 1309254752 13.779 97 16 33 0 1329344544 15985024 4104768
00:59:44 54091 8192000000 10242199696 10693120000 1343388480 14.088 100 18 36 0 1356933952 9907008 3638464
01:01:42 80883 8192000000 10262661328 10737053696 1345687008 14.109 99 8 16 0 1365171216 17581568 1902640
01:04:04 74245 8192000000 10263197792 10720276480 1347861952 14.128 99 16 32 3 1362839008 11038240 3938816
01:06:33 46791 8192000000 10278188720 10723311616 1353248832 14.177 99 31 62 0 1371703168 11890960 6563376
01:08:24 70847 8192000000 10275867472 10722975744 1356540992 14.206 99 15 28 1 1377074160 16856208 3676960
01:10:35 59406 8192000000 10292978960 10750902272 1381580688 14.431 100 10 20 0 1396957264 13426496 1950080
01:12:35 36538 8192000000 10297901312 10752888832 1386567920 14.475 100 27 55 0 1408824240 16703808 5552512
01:14:17 19685 8192000000 10313801696 10763280384 1395317920 14.553 100 32 62 0 1409849792 7973936 6557936
01:15:42 72748 8192000000 10295580032 10741305344 1375577248 14.377 99 50 101 0 1395516992 8556144 11383600
01:18:10 48851 8192000000 10280745088 10745282560 1363694128 14.271 98 22 42 0 1378234272 9528624 5011520
01:20:00 50947 8192000000 10278738912 10741075968 1376831520 14.388 99 12 24 0 1386665744 6907280 2926944
01:21:56 56513 8192000000 10271797248 10728513536 1351114544 14.158 97 17 35 1 1361831616 6794272 3922800
01:23:52 79018 8192000000 10260247200 10727952384 1340234896 14.060 96 10 20 0 1355936560 13178976 2522688
01:26:40 35790 8192000000 10304751424 10757484544 1377774048 14.397 99 31 62 0 1396255984 11296928 7185008
01:28:19 36748 8192000000 10281691200 10733977600 1369681088 14.324 99 19 39 1 1388304576 14098864 4524624
01:29:56 40454 8192000000 10253020688 10699022336 1300334144 13.698 94 20 40 0 1316166800 11190512 4642144
01:31:37 111458 8192000000 10277683808 10722676736 1316781680 13.848 96 13 26 0 1337075552 17135280 3158592
01:34:32 57974 8192000000 10275017024 10728579072 1338180576 14.041 98 15 30 0 1352999456 11629264 3189616
01:36:35 33553 8192000000 10261899936 10698141696 1280926160 13.521 94 26 52 0 1295339424 8869872 5543392
01:38:11 39236 8192000000 9471901920 9796558848 619377696 7.029 46 8 16 0 626241488 5481888 1381904

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-01-2023 01:40:40 to 01-01-2023 01:40:55.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-01-2023 01:40:56 to 01-01-2023 01:40:58.

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          245926
MON$OLDEST_ACTIVE               246276
MON$OLDEST_SNAPSHOT             246276
MON$NEXT_TRANSACTION            246276
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                       2054176
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
:1503: 
:1504: mscs-test-161.budzdorov.ru    Sun Jan  1 01:38:58 2023
:1505:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1506: 
--- end of diff output ---

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

01.01.23 01:40:59. 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
2023-01-01 01:41:02 No severe PSQL-related problems occured
01.01.23 01:41:03. End of report.