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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15159376 kB
MemAvailable 30858932 kB
Buffers 20 kB
Cached 16459840 kB
SwapCached 2516 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-08-13 04:37:21
DTS_END                         2022-08-13 04:37:21.4780
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57160
TRN_ID                          220074
ATT_ID                          645
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: 8892.57 23538 1067108
customer order: creation 167.56 494 20107
customer order: refuse 14.19 441 1703
order to supplier: creation 44.01 1462 5281
order to supplier: removal 3.43 163313 411
invoice (draft): creation 44.05 3384 5286
invoice (draft): removal 49.96 997 5995
invoice accept: apply 40.53 26525 4864
invoice accept: cancel 45.15 1299 5418
customer reserve: creation 3748.38 251 449806
customer reserve: removal 4444.14 98 533297
realization accept: apply 81.22 310 9747
realization accept: cancel 81.93 169 9832
payment from customer: creation 58.43 172 7012
payment from customer: removal 5.82 170 698
payment to supplier: creation 48.16 225 5779
payment to supplier: removal 3.28 165 394
service: total inventory turnovers 3.68 4336 442
service: total monetary turnovers 7.84 406 941
service: aggregate perf. data 0.75 21456 90
service: refresh index statistics 0.04 245086 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-08-2022 04:38:37 to 13-08-2022 04:38:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8202
TEST_TIME 2 7135
TEST_TIME 3 6477
TEST_TIME 4 5981
TEST_TIME 5 5860
TEST_TIME 6 5911
TEST_TIME 7 6215
TEST_TIME 8 6513
TEST_TIME 9 6518
TEST_TIME 10 6554
TEST_TIME 11 6836
TEST_TIME 12 6716
TEST_TIME 13 6767
TEST_TIME 14 7094
TEST_TIME 15 7149
TEST_TIME 16 7217
TEST_TIME 17 7392
TEST_TIME 18 7767
TEST_TIME 19 7809
TEST_TIME 20 8023
TEST_TIME 21 7955
TEST_TIME 22 8297
TEST_TIME 23 8321
TEST_TIME 24 8795
TEST_TIME 25 8699
TEST_TIME 26 8906
TEST_TIME 27 8873
TEST_TIME 28 8907
TEST_TIME 29 9283
TEST_TIME 30 9443
TEST_TIME 31 9584
TEST_TIME 32 9541
TEST_TIME 33 9761
TEST_TIME 34 10057
TEST_TIME 35 10279
TEST_TIME 36 10546
TEST_TIME 37 10561
TEST_TIME 38 10938
TEST_TIME 39 10824
TEST_TIME 40 10975
TEST_TIME 41 10893
TEST_TIME 42 10842
TEST_TIME 43 10680
TEST_TIME 44 10544
TEST_TIME 45 10383
TEST_TIME 46 10312
TEST_TIME 47 10225
TEST_TIME 48 10069
TEST_TIME 49 10122
TEST_TIME 50 10050
TEST_TIME 51 9980
TEST_TIME 52 9884
TEST_TIME 53 9824
TEST_TIME 54 9755
TEST_TIME 55 9702
TEST_TIME 56 9633
TEST_TIME 57 9581
TEST_TIME 58 9516
TEST_TIME 59 9491
TEST_TIME 60 9420
TEST_TIME 61 9451
TEST_TIME 62 9353
TEST_TIME 63 9374
TEST_TIME 64 9337
TEST_TIME 65 9300
TEST_TIME 66 9273
TEST_TIME 67 9237
TEST_TIME 68 9198
TEST_TIME 69 9169
TEST_TIME 70 9185
TEST_TIME 71 9159
TEST_TIME 72 9238
TEST_TIME 73 9276
TEST_TIME 74 9249
TEST_TIME 75 9253
TEST_TIME 76 9330
TEST_TIME 77 9327
TEST_TIME 78 9376
TEST_TIME 79 9390
TEST_TIME 80 9422
TEST_TIME 81 9341
TEST_TIME 82 9298
TEST_TIME 83 9221
TEST_TIME 84 9159
TEST_TIME 85 9165
TEST_TIME 86 9121
TEST_TIME 87 9074
TEST_TIME 88 9055
TEST_TIME 89 9004
TEST_TIME 90 8919
TEST_TIME 91 9078
TEST_TIME 92 9031
TEST_TIME 93 9020
TEST_TIME 94 8995
TEST_TIME 95 8981
TEST_TIME 96 9021
TEST_TIME 97 9053
TEST_TIME 98 9042
TEST_TIME 99 9138
TEST_TIME 100 9124
TEST_TIME 101 9216
TEST_TIME 102 9242
TEST_TIME 103 9204
TEST_TIME 104 9211
TEST_TIME 105 9221
TEST_TIME 106 9190
TEST_TIME 107 9134
TEST_TIME 108 9084
TEST_TIME 109 9065
TEST_TIME 110 9054
TEST_TIME 111 9042
TEST_TIME 112 9003
TEST_TIME 113 8973
TEST_TIME 114 8943
TEST_TIME 115 8904
TEST_TIME 116 8886
TEST_TIME 117 8925
TEST_TIME 118 8946
TEST_TIME 119 8949
TEST_TIME 120 8913

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-08-2022 04:38:40 to 13-08-2022 04:38:44.

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 1482173 1482173 0 0.00 0 17478 14 0 0
doc_list_biud 1482173 1482173 0 0.00 0 23591 42 0 0
sp_add_invoice_to_stock 5035 4864 171 3.39 63 201127 26526 0 171
sp_cancel_adding_invoice 5418 5418 0 0.00 6 95777 1299 0 0
sp_cancel_client_order 1703 1703 0 0.00 6 8263 442 0 0
sp_cancel_customer_reserve 533297 533297 0 0.00 1 22035 99 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 3 7304 170 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 4 16773 166 0 0
sp_cancel_supplier_invoice 6010 5995 15 0.24 2 15518 997 0 15
sp_cancel_supplier_order 411 411 0 0.00 11 1432459 163314 0 0
sp_cancel_write_off 9832 9832 0 0.00 2 16448 170 0 0
sp_client_order 20107 20107 0 0.00 6 21751 494 0 0
sp_customer_reserve 449806 449806 0 0.00 0 130940 252 0 0
sp_fill_shopping_cart 30668 30668 0 0.00 1 30882 94 0 0
sp_fill_shopping_cart_clo_res 449780 449780 0 0.00 0 10271 18 0 0
sp_get_clo_for_invoice 4836 4836 0 0.00 0 28082 308 0 0
sp_kill_cost_storno 16342 16342 0 0.00 0 12204 74 0 0
sp_kill_qstorno_ret_qs2qd 1082832 1082832 0 0.00 0 21993 43 0 0
sp_kill_qty_storno 571239 571239 0 0.00 0 23589 100 0 0
sp_lock_selected_doc 20138 20138 0 0.00 0 12943 8 0 0
sp_make_cost_storno 27374 27374 0 0.00 0 14955 83 0 0
sp_multiply_rows_for_pdistr 27374 27374 0 0.00 0 17460 41 0 0
sp_multiply_rows_for_qdistr 20107 20107 0 0.00 0 21738 180 0 0
sp_pay_from_customer 7012 7012 0 0.00 5 15004 173 0 0
sp_pay_to_supplier 5779 5779 0 0.00 7 17483 225 0 0
sp_payment_common 12791 12791 0 0.00 2 12815 48 0 0
sp_qd_handle_on_cancel_clo 1703 1703 0 0.00 0 8230 361 0 0
sp_qd_handle_on_invoice_upd_sts 10254 10254 0 0.00 0 23589 563 0 0
sp_qd_handle_on_reserve_upd_sts 9747 9747 0 0.00 0 13037 80 0 0
sp_reserve_write_off 9747 9747 0 0.00 8 25234 310 0 0
sp_supplier_invoice 5286 5286 0 0.00 9 108978 3385 0 0
sp_supplier_order 5281 5281 0 0.00 9 49594 1462 0 0
srv_aggregate_perf_data 90 90 0 0.00 2339 320636 21456 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 835 108857 33419 0 0
srv_find_qd_qs_mism 1019773 1019773 0 0.00 0 12560 10 0 0
srv_make_invnt_saldo 442 442 0 0.00 54 80874 4337 0 0
srv_make_money_saldo 941 941 0 0.00 1 16888 406 0 0
srv_recalc_idx_stat 5 5 0 0.00 59615 447519 245086 0 0
t$perf-abend:sp_add_invoice_to_stock 171 171 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4865 4865 0 0.00 1 12490 1031 0 0
t$perf-norm:sp_cancel_adding_invoice 376 376 0 0.00 1 8914 571 0 0
t$perf-norm:sp_cancel_client_order 1703 1703 0 0.00 0 3191 31 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 1511 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 1667 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 333 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 353 353 0 0.00 0 1378 40 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 102702 7850 0 0
t$perf-norm:sp_cancel_write_off 948 948 0 0.00 0 603 19 0 0
t$perf-norm:sp_client_order 20107 20107 0 0.00 0 12856 43 0 0
t$perf-norm:sp_customer_reserve 941 941 0 0.00 0 1434 20 0 0
t$perf-norm:sp_pay_from_customer 7012 7012 0 0.00 0 4868 19 0 0
t$perf-norm:sp_pay_to_supplier 5779 5779 0 0.00 0 8989 27 0 0
t$perf-norm:sp_reserve_write_off 9747 9747 0 0.00 0 4557 25 0 0
t$perf-norm:sp_supplier_invoice 5286 5286 0 0.00 0 3804 33 0 0
t$perf-norm:sp_supplier_order 5281 5281 0 0.00 0 5027 36 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 473 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 5159 80 0 0
t$perf-norm:srv_make_invnt_saldo 442 442 0 0.00 0 757 19 0 0
t$perf-norm:srv_make_money_saldo 941 941 0 0.00 0 3680 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 6 178 90 0 0
v_add_invoice_to_stock 10014 9672 342 3.41 0 5037 30 0 342
v_all_customers 36492 36492 0 0.00 0 61 4 0 0
v_all_suppliers 29520 29520 0 0.00 0 102 5 0 0
v_all_wares 40214 40214 0 0.00 0 102 5 0 0
v_cancel_adding_invoice 752 752 0 0.00 0 516 16 0 0
v_cancel_client_order 3406 3406 0 0.00 0 5464 14 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 4001 18 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 4100 16 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 2277 13 0 0
v_cancel_supplier_invoice 736 706 30 4.07 0 2112 31 0 30
v_cancel_supplier_order 822 822 0 0.00 0 460 11 0 0
v_cancel_write_off 1896 1896 0 0.00 0 1312 25 0 0
v_max_id_clo_ord 5281 5281 0 0.00 0 4653 7 0 0
v_max_id_clo_res 941 941 0 0.00 0 1571 10 0 0
v_max_id_ord_sup 5280 5280 0 0.00 0 3815 9 0 0
v_max_non_paid_invoice 1580 1580 0 0.00 0 61 8 0 0
v_max_non_paid_realizn 3714 3714 0 0.00 0 1831 9 0 0
v_min_id_clo_ord 5281 5281 0 0.00 0 3378 11 0 0
v_min_id_clo_res 941 941 0 0.00 0 7035 27 0 0
v_min_id_ord_sup 5280 5280 0 0.00 0 6984 12 0 0
v_min_non_paid_invoice 5779 5779 0 0.00 0 12793 20 0 0
v_min_non_paid_realizn 7012 7012 0 0.00 0 3392 14 0 0
v_our_firm 10318 10318 0 0.00 0 93 5 0 0
v_reserve_write_off 19494 19494 0 0.00 0 5210 12 0 0
x_lock_depdocs_on_canc_invoice 11060 11060 0 0.00 0 21587 123 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 4660 354 0 0
x_make_qstorno_1000_1200 5281 5281 0 0.00 3 48773 1276 0 0
x_make_qstorno_1200_2000 5286 5286 0 0.00 4 108760 3125 0 0
x_make_qstorno_2100_3300 449806 449806 0 0.00 0 130930 220 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-08-2022 04:38:45 to 13-08-2022 04:38:49.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:25 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:26 26050 8192000000 9743308336 10043629568 826540064 9.164 100 93 186 0 861287536 12753040 21994432
02:09:59 29232 8192000000 9778307568 10086445056 848227184 9.382 100 88 176 0 883013216 13760976 21025056
02:11:33 9970 8192000000 9810132528 10130759680 874123008 9.641 100 82 164 0 919986976 27080048 18783920
02:12:48 7189 8192000000 9883593824 10221498368 930897872 10.203 100 76 150 0 994139040 47271312 15969856
02:13:57 3680 8192000000 9932954160 10275102720 961512480 10.504 100 94 184 2 1041869280 61251600 19105200
02:15:03 1888 8192000000 9957881712 10311929856 991996032 10.801 100 91 178 0 1077000592 66595872 18408688
02:16:08 1354 8192000000 10003838096 10367340544 1015534096 11.029 100 89 177 0 1120616896 87553312 17529488
02:17:11 1336 8192000000 9999165248 10362314752 1030993888 11.178 100 94 181 0 1118742336 69726048 18022400
02:18:15 1033 8192000000 10025073008 10399961088 1054007888 11.399 100 97 182 0 1148826672 75974704 18844080
02:19:17 10059 8192000000 10020980704 10397700096 1066398736 11.518 100 84 156 3 1150768032 68451184 15918112
02:20:29 893 8192000000 10046359488 10427928576 1074132688 11.592 100 84 165 1 1166118880 76223200 15762992
02:21:30 8890 8192000000 10030620048 10414977024 1078483168 11.633 100 63 122 2 1152204768 61116704 12604896
02:22:40 5480 8192000000 10053069504 10444681216 1082685472 11.673 100 76 150 0 1176795936 78805904 15304560
02:23:48 1120 8192000000 10074690720 10466553856 1086182256 11.706 100 99 182 0 1198205696 93603024 18420416
02:24:50 1139 8192000000 10070770896 10461032448 1093196992 11.773 100 97 191 0 1195780544 82599072 19984480
02:25:52 1263 8192000000 10076068544 10470084608 1098270016 11.821 100 93 178 3 1202083840 85229856 18583968
02:26:54 1154 8192000000 10055859984 10447626240 1101014592 11.847 100 90 170 1 1177140704 58532880 17593232
02:27:56 5875 8192000000 10059640144 10456301568 1096269136 11.802 100 70 136 0 1174654272 64707120 13678016
02:29:05 4961 8192000000 10033130800 10421964800 1082629616 11.673 100 74 136 5 1153895680 58140272 13125792
02:30:11 912 8192000000 10038988944 10427465728 1083352608 11.679 100 94 182 2 1165660112 63563056 18744448
02:31:13 1270 8192000000 9987487888 10364555264 1040759232 11.272 99 99 184 3 1112880448 52970848 19150368
02:32:15 1089 8192000000 10032675792 10409775104 1052774480 11.387 100 95 177 1 1153051632 82541536 17735616
02:33:17 1276 8192000000 9976467920 10347876352 1026462016 11.134 99 94 179 2 1098043936 54569952 17011968
02:34:19 1897 8192000000 9995617200 10380070912 1044470688 11.308 100 78 147 2 1119738000 60786080 14481232
02:35:22 1945 8192000000 9986822624 10369482752 1038538400 11.251 100 78 142 2 1101735664 48909280 14287984
02:36:27 3113 8192000000 9968639648 10340007936 1026301504 11.133 99 84 152 4 1089202320 49916384 12984432
02:37:32 945 8192000000 9979686848 10358128640 1047459520 11.336 100 77 137 3 1100565616 38832912 14273184
02:38:41 1203 8192000000 10012668704 10389536768 1063360016 11.489 100 95 179 2 1133433984 51177296 18896672
02:39:56 16642 8192000000 10056610048 10443583488 1090939184 11.752 100 74 129 1 1168310192 64583328 12787680
02:41:15 5658 8192000000 10086362944 10479505408 1105448832 11.889 100 63 124 0 1201072592 83121328 12502432
02:42:33 30559 8192000000 10145470544 10555363328 1119028304 12.018 100 66 130 2 1267495008 135081680 13385024
02:44:07 3643 8192000000 10156320496 10559373312 1128967072 12.112 100 73 138 3 1266610912 123493024 14150816
02:45:13 1296 8192000000 10190390064 10588168192 1141677680 12.231 100 84 164 1 1301768416 142252624 17838112
02:46:19 2757 8192000000 10211158160 10613927936 1151958320 12.328 100 82 156 1 1325534656 156794192 16782144
02:47:26 3965 8192000000 10219633488 10626564096 1166451024 12.464 100 74 143 1 1341135888 157994848 16690016
02:48:35 1178 8192000000 10292077104 10693148672 1181878336 12.608 100 97 191 2 1403132208 200680976 20572896
02:49:42 3990 8192000000 10302943440 10711588864 1192927472 12.711 100 73 142 1 1415406032 206351104 16127456
02:50:49 1491 8192000000 10346300000 10765705216 1192475264 12.706 100 94 181 1 1457142352 243636336 21030752
02:51:54 2967 8192000000 10362796992 10777042944 1187912368 12.664 100 82 164 2 1472017088 264798080 19306640
02:53:00 1931 8192000000 10387234448 10806550528 1184546720 12.633 100 91 170 0 1493123664 289431008 19145936
02:54:07 2017 8192000000 10396491680 10838822912 1180278704 12.593 100 92 180 1 1506777344 304316160 22182480
02:55:11 824 8192000000 10410823328 10847055872 1171517696 12.511 100 96 189 1 1515367056 321200912 22648448
02:56:15 1188 8192000000 10420384032 10828230656 1181417424 12.603 100 82 151 1 1526556640 327785776 17353440
02:57:21 3507 8192000000 10397641872 10823933952 1175948032 12.552 100 64 124 1 1508983136 317778384 15256720
02:58:30 1077 8192000000 10423400368 10838679552 1166164272 12.461 100 97 190 3 1527375440 338434224 22776944
02:59:32 4193 8192000000 10431823792 10876129280 1174896864 12.543 100 64 121 1 1536697792 346411824 15389104
03:00:43 2339 8192000000 10449072768 10879479808 1180472864 12.595 100 78 154 1 1551878320 353531936 17873520
03:01:52 7241 8192000000 10396612528 10820595712 1155565392 12.362 99 54 105 0 1498691664 330619376 12506896
03:03:05 5608 8192000000 10408967744 10862256128 1163313808 12.434 100 54 106 1 1512497600 336151632 13032160
03:04:15 1761 8192000000 10415133520 10844233728 1154438032 12.351 100 82 154 2 1515063840 343710528 16915280
03:05:20 5032 8192000000 10391441312 10879299584 1151594528 12.324 100 67 132 1 1519588032 352302624 15690880
03:06:31 1436 8192000000 10392959952 10869571584 1160261728 12.406 100 90 172 3 1493359856 313561072 19537056
03:07:34 1009 8192000000 10411648064 10833506304 1167681472 12.475 100 94 184 0 1510062992 320991328 21390192
03:08:36 3765 8192000000 10418332064 10870042624 1175982816 12.553 100 46 91 0 1525625216 338092064 11550336
03:09:42 10455 8192000000 10419000064 10885107712 1170345632 12.500 100 40 80 0 1525321552 344714096 10261824
03:10:53 13597 8192000000 10351672864 10853978112 1165053568 12.451 100 33 64 0 1518304304 344675712 8575024
03:12:08 25476 8192000000 10290495440 10771492864 1164884368 12.449 100 34 67 0 1397165952 224084464 8197120
03:13:35 41192 8192000000 10193015488 10622648320 1098603776 11.824 95 24 46 1 1290771248 186693616 5473856
03:15:20 34400 8192000000 10151060640 10621509632 1149792224 12.308 100 9 18 0 1266396352 114690448 1913680
03:16:55 30732 8192000000 10191858784 10639122432 1142092512 12.235 99 15 30 0 1283306192 136563616 4650064
03:18:58 70537 8192000000 10089662144 10563358720 1133362080 12.153 99 48 88 1 1164286640 22150352 8774208
03:21:12 28162 8192000000 10062870288 10514403328 1109459168 11.927 97 29 55 1 1146865456 32122784 5283504
03:22:47 13863 8192000000 10082163312 10513780736 1117990544 12.008 99 36 69 1 1171371248 46621424 6759280
03:24:06 26601 8192000000 10072160416 10520756224 1121870992 12.045 99 18 36 0 1171947168 45856208 4219968
03:25:40 31856 8192000000 10117755088 10551570432 1103310976 11.869 97 56 111 0 1184957776 70254256 11392544
03:27:21 108674 8192000000 10076135328 10550403072 1118375216 12.012 98 23 45 0 1170481552 47475504 4630832
03:30:34 58850 8192000000 10106170656 10564096000 1113191872 11.963 98 14 28 0 1179812832 63577792 3043168
03:32:40 105890 8192000000 10066959664 10545664000 1109326400 11.926 99 16 33 0 1189661312 76644544 3690368
03:35:43 108835 8192000000 10094326000 10557583360 1099923328 11.837 97 35 70 1 1200427552 92399184 8105040
03:38:33 76412 8192000000 10115097552 10593816576 1134729440 12.166 99 16 32 0 1206141408 67563968 3848000
03:41:30 65247 8192000000 10079476224 10563203072 1143987872 12.253 100 7 15 0 1195066496 49871520 1207104
03:45:41 8014 8192000000 10094604880 10535215104 1139491248 12.211 100 53 99 2 1193529728 42345680 11692800
03:46:51 71208 8192000000 10150906720 10627530752 1138312656 12.200 99 43 86 0 1248620784 101481744 8826384
03:49:05 40861 8192000000 10117002992 10605547520 1102071184 11.857 96 17 34 0 1231204592 124364352 4769056
03:50:49 66213 8192000000 10118415040 10573852672 1087623040 11.720 96 24 48 0 1154874272 62311200 4940032
03:53:03 36627 8192000000 10090871792 10588717056 1117496528 12.003 99 16 32 0 1210824000 89388688 3938784
03:54:40 49210 8192000000 10113140688 10562760704 1128637856 12.109 99 26 52 0 1221275520 86818848 5818816
03:57:20 80190 8192000000 10075417584 10597703680 1122401536 12.050 100 7 14 0 1188448608 64703808 1343264
04:00:04 76516 8192000000 10067944016 10503843840 1083824736 11.684 96 30 60 0 1127202096 37484336 5893024
04:02:23 66726 8192000000 10082577840 10545762304 1120818352 12.035 99 12 24 0 1186411440 63090768 2502320
04:04:30 40860 8192000000 10122550240 10574741504 1127857696 12.101 99 19 38 0 1216593632 84532256 4203680
04:06:11 89003 8192000000 10162007120 10613694464 1143391456 12.247 100 16 32 0 1262281632 115277360 3612816
04:08:41 58176 8192000000 10059330640 10508263424 1090164208 11.744 96 13 25 0 1128679952 36298656 2217088
04:10:40 42789 8192000000 10125127456 10565427200 1120862448 12.035 98 31 60 0 1187376320 60188288 6325584
04:12:26 52246 8192000000 10120488208 10594160640 1106204944 11.896 97 33 66 0 1225035760 111398672 7432144
04:14:26 38540 8192000000 10151557120 10596450304 1114266224 11.973 98 30 60 0 1238619168 118235104 6117840
04:16:33 56240 8192000000 10127204512 10564329472 1134394000 12.163 100 20 40 0 1238211824 99431872 4385952
04:19:15 52435 8192000000 10098162928 10570739712 1118137520 12.009 98 19 36 0 1165872848 44368080 3367248
04:21:08 19100 8192000000 10133914592 10590916608 1146864144 12.280 100 40 80 0 1212488064 57824112 7799808
04:22:29 81365 8192000000 10092943216 10611306496 1149587008 12.306 100 8 16 0 1202646800 51470272 1589520
04:25:20 13486 8192000000 10097980592 10536910848 1144791584 12.261 100 34 68 0 1197362976 46164528 6406864
04:26:34 75974 8192000000 10078829376 10552414208 1143054064 12.244 100 20 40 1 1216833776 69957424 3822288
04:28:55 73149 8192000000 10076181568 10530844672 1132449248 12.144 99 30 56 0 1159041456 20951136 5641072
04:33:13 92833 8192000000 10125947392 10634838016 1132030944 12.141 99 19 38 0 1227729952 91578496 4120512
04:36:00 53834 8192000000 10106121568 10561712128 1110169136 11.934 97 30 60 0 1174100944 57796224 6135584

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 13-08-2022 04:38:51 to 13-08-2022 04:39:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 171 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-08-2022 04:39:08 to 13-08-2022 04:39:10.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          218484
MON$OLDEST_ACTIVE               220201
MON$OLDEST_SNAPSHOT             220201
MON$NEXT_TRANSACTION            220201
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2112896
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             772
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2816 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2816 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2816 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2816 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:193: 
:194: mscs-test-161.budzdorov.ru    Sat Aug 13 04:37:36 2022
:195:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:196: 
--- end of diff output ---

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

13.08.22 04:39:11. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-08-13 04:39:13 No severe PSQL-related problems occured
13.08.22 04:39:14. End of report.