Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.06.2022 01: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 16042272 kB
MemAvailable 31267728 kB
Buffers 0 kB
Cached 15645724 kB
SwapCached 2452 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-06-14 01:38:15
DTS_END                         2022-06-14 01:38:15.4730
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42542
TRN_ID                          249684
ATT_ID                          686
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: 9477.13 25783 1137255
customer order: creation 179.50 358 21540
customer order: refuse 15.29 369 1835
order to supplier: creation 47.47 1303 5696
order to supplier: removal 3.52 101452 422
invoice (draft): creation 47.68 3556 5722
invoice (draft): removal 53.29 857 6395
invoice accept: apply 44.21 16401 5305
invoice accept: cancel 47.86 848 5743
customer reserve: creation 4078.68 160 489441
customer reserve: removal 4652.16 59 558259
realization accept: apply 87.06 205 10447
realization accept: cancel 84.23 108 10107
payment from customer: creation 61.97 136 7436
payment from customer: removal 5.93 75 712
payment to supplier: creation 51.59 142 6191
payment to supplier: removal 3.45 94 414
service: total inventory turnovers 5.02 1200 602
service: total monetary turnovers 7.25 37 870
service: aggregate perf. data 0.95 19046 114
service: refresh index statistics 0.03 369262 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-06-2022 01:39:08 to 14-06-2022 01:39:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3024
TEST_TIME 2 1989
TEST_TIME 3 3265
TEST_TIME 4 4260
TEST_TIME 5 4084
TEST_TIME 6 4283
TEST_TIME 7 4784
TEST_TIME 8 5152
TEST_TIME 9 5543
TEST_TIME 10 6074
TEST_TIME 11 6170
TEST_TIME 12 6167
TEST_TIME 13 6349
TEST_TIME 14 6572
TEST_TIME 15 6711
TEST_TIME 16 6791
TEST_TIME 17 7183
TEST_TIME 18 7509
TEST_TIME 19 7839
TEST_TIME 20 7935
TEST_TIME 21 8532
TEST_TIME 22 8973
TEST_TIME 23 9512
TEST_TIME 24 9534
TEST_TIME 25 9983
TEST_TIME 26 10586
TEST_TIME 27 11068
TEST_TIME 28 10731
TEST_TIME 29 10498
TEST_TIME 30 10462
TEST_TIME 31 10617
TEST_TIME 32 10435
TEST_TIME 33 10293
TEST_TIME 34 10197
TEST_TIME 35 10082
TEST_TIME 36 10147
TEST_TIME 37 10118
TEST_TIME 38 10074
TEST_TIME 39 9946
TEST_TIME 40 9858
TEST_TIME 41 10014
TEST_TIME 42 10188
TEST_TIME 43 10061
TEST_TIME 44 10076
TEST_TIME 45 10044
TEST_TIME 46 9974
TEST_TIME 47 9854
TEST_TIME 48 9708
TEST_TIME 49 9666
TEST_TIME 50 9690
TEST_TIME 51 9567
TEST_TIME 52 9551
TEST_TIME 53 9479
TEST_TIME 54 9430
TEST_TIME 55 9649
TEST_TIME 56 9617
TEST_TIME 57 9549
TEST_TIME 58 9482
TEST_TIME 59 9355
TEST_TIME 60 9263
TEST_TIME 61 9268
TEST_TIME 62 9260
TEST_TIME 63 9219
TEST_TIME 64 9282
TEST_TIME 65 9397
TEST_TIME 66 9441
TEST_TIME 67 9407
TEST_TIME 68 9469
TEST_TIME 69 9485
TEST_TIME 70 9499
TEST_TIME 71 9549
TEST_TIME 72 9519
TEST_TIME 73 9607
TEST_TIME 74 9569
TEST_TIME 75 9645
TEST_TIME 76 9543
TEST_TIME 77 9470
TEST_TIME 78 9474
TEST_TIME 79 9517
TEST_TIME 80 9452
TEST_TIME 81 9439
TEST_TIME 82 9431
TEST_TIME 83 9376
TEST_TIME 84 9320
TEST_TIME 85 9312
TEST_TIME 86 9272
TEST_TIME 87 9289
TEST_TIME 88 9284
TEST_TIME 89 9367
TEST_TIME 90 9357
TEST_TIME 91 9412
TEST_TIME 92 9414
TEST_TIME 93 9442
TEST_TIME 94 9515
TEST_TIME 95 9558
TEST_TIME 96 9548
TEST_TIME 97 9631
TEST_TIME 98 9628
TEST_TIME 99 9670
TEST_TIME 100 9625
TEST_TIME 101 9590
TEST_TIME 102 9505
TEST_TIME 103 9556
TEST_TIME 104 9564
TEST_TIME 105 9553
TEST_TIME 106 9530
TEST_TIME 107 9507
TEST_TIME 108 9452
TEST_TIME 109 9434
TEST_TIME 110 9423
TEST_TIME 111 9412
TEST_TIME 112 9482
TEST_TIME 113 9487
TEST_TIME 114 9477
TEST_TIME 115 9491
TEST_TIME 116 9492
TEST_TIME 117 9496
TEST_TIME 118 9525
TEST_TIME 119 9535
TEST_TIME 120 9482

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-06-2022 01:39:11 to 14-06-2022 01:39:15.

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 1588987 1588987 0 0.00 0 17785 6 0 0
doc_list_biud 1588982 1588982 0 0.00 0 69351 27 0 0
sp_add_invoice_to_stock 5374 5305 69 1.28 40 178720 16402 0 69
sp_cancel_adding_invoice 5743 5743 0 0.00 4 106080 848 0 0
sp_cancel_client_order 1835 1835 0 0.00 4 9723 369 0 0
sp_cancel_customer_reserve 558259 558259 0 0.00 1 45197 60 0 0
sp_cancel_pay_from_customer 712 712 0 0.00 2 3150 75 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 9108 95 0 0
sp_cancel_supplier_invoice 6397 6395 2 0.03 2 69360 857 0 2
sp_cancel_supplier_order 422 422 0 0.00 5 1048810 101453 0 0
sp_cancel_write_off 10107 10107 0 0.00 1 13585 109 0 0
sp_client_order 21540 21540 0 0.00 3 37133 359 0 0
sp_customer_reserve 489441 489441 0 0.00 0 90004 160 0 0
sp_fill_shopping_cart 32957 32957 0 0.00 1 15373 39 0 0
sp_fill_shopping_cart_clo_res 489441 489441 0 0.00 0 11997 12 0 0
sp_get_clo_for_invoice 5299 5299 0 0.00 0 35279 238 0 0
sp_kill_cost_storno 16976 16976 0 0.00 0 13583 56 0 0
sp_kill_qstorno_ret_qs2qd 1133442 1133442 0 0.00 0 69344 32 0 0
sp_kill_qty_storno 598503 598503 0 0.00 0 69346 71 0 0
sp_lock_selected_doc 21847 21847 0 0.00 0 9518 8 0 0
sp_make_cost_storno 29373 29373 0 0.00 0 17251 71 0 0
sp_multiply_rows_for_pdistr 29371 29371 0 0.00 0 16735 32 0 0
sp_multiply_rows_for_qdistr 21540 21540 0 0.00 0 36803 138 0 0
sp_pay_from_customer 7436 7436 0 0.00 4 21170 136 0 0
sp_pay_to_supplier 6191 6191 0 0.00 4 16629 142 0 0
sp_payment_common 13626 13626 0 0.00 1 21167 28 0 0
sp_qd_handle_on_cancel_clo 1835 1835 0 0.00 0 9713 332 0 0
sp_qd_handle_on_invoice_upd_sts 11042 11042 0 0.00 0 19953 347 0 0
sp_qd_handle_on_reserve_upd_sts 10443 10443 0 0.00 0 12361 60 0 0
sp_reserve_write_off 10447 10447 0 0.00 6 17667 206 0 0
sp_supplier_invoice 5722 5722 0 0.00 6 105644 3556 0 0
sp_supplier_order 5696 5696 0 0.00 5 35265 1304 0 0
srv_aggregate_perf_data 114 114 0 0.00 2433 170947 19047 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1507 147008 45495 0 0
srv_find_qd_qs_mism 1086367 1086367 0 0.00 0 9718 5 0 0
srv_make_invnt_saldo 602 602 0 0.00 38 137785 1201 0 0
srv_make_money_saldo 870 870 0 0.00 1 10415 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 246983 523841 369262 0 0
t$perf-abend:sp_add_invoice_to_stock 69 69 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5305 5305 0 0.00 0 11570 171 0 0
t$perf-norm:sp_cancel_adding_invoice 433 433 0 0.00 0 2149 86 0 0
t$perf-norm:sp_cancel_client_order 1835 1835 0 0.00 0 4563 16 0 0
t$perf-norm:sp_cancel_customer_reserve 741 741 0 0.00 0 4544 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 712 712 0 0.00 0 655 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 792 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 467 467 0 0.00 0 1521 14 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 25391 1651 0 0
t$perf-norm:sp_cancel_write_off 1081 1081 0 0.00 0 2031 6 0 0
t$perf-norm:sp_client_order 21540 21540 0 0.00 0 9942 19 0 0
t$perf-norm:sp_customer_reserve 1035 1035 0 0.00 0 949 4 0 0
t$perf-norm:sp_pay_from_customer 7436 7436 0 0.00 0 4680 12 0 0
t$perf-norm:sp_pay_to_supplier 6191 6191 0 0.00 0 11453 13 0 0
t$perf-norm:sp_reserve_write_off 10447 10447 0 0.00 0 15828 15 0 0
t$perf-norm:sp_supplier_invoice 5722 5722 0 0.00 0 4124 15 0 0
t$perf-norm:sp_supplier_order 5696 5696 0 0.00 0 3826 14 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 387 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1645 26 0 0
t$perf-norm:srv_make_invnt_saldo 602 602 0 0.00 0 877 19 0 0
t$perf-norm:srv_make_money_saldo 870 870 0 0.00 0 1012 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10732 10594 138 1.28 0 6991 20 0 138
v_all_customers 39132 39132 0 0.00 0 21 1 0 0
v_all_suppliers 31690 31690 0 0.00 0 30 1 0 0
v_all_wares 43080 43080 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 866 866 0 0.00 0 1269 7 0 0
v_cancel_client_order 3670 3670 0 0.00 0 6542 6 0 0
v_cancel_customer_prepayment 1424 1424 0 0.00 0 817 4 0 0
v_cancel_customer_reserve 1482 1482 0 0.00 0 1872 4 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 2064 8 0 0
v_cancel_supplier_invoice 938 934 4 0.42 0 5124 18 0 4
v_cancel_supplier_order 844 844 0 0.00 0 1022 4 0 0
v_cancel_write_off 2162 2162 0 0.00 0 4825 16 0 0
v_max_id_clo_ord 5696 5696 0 0.00 0 7914 3 0 0
v_max_id_clo_res 1035 1035 0 0.00 0 2201 10 0 0
v_max_id_ord_sup 5721 5721 0 0.00 0 3432 4 0 0
v_max_non_paid_invoice 1763 1763 0 0.00 0 4584 6 0 0
v_max_non_paid_realizn 4013 4013 0 0.00 0 13970 7 0 0
v_min_id_clo_ord 5696 5696 0 0.00 0 4459 7 0 0
v_min_id_clo_res 1035 1035 0 0.00 0 6852 23 0 0
v_min_id_ord_sup 5721 5721 0 0.00 0 4005 5 0 0
v_min_non_paid_invoice 6191 6191 0 0.00 0 10224 16 0 0
v_min_non_paid_realizn 7435 7435 0 0.00 0 21164 21 0 0
v_our_firm 10792 10792 0 0.00 0 18 1 0 0
v_reserve_write_off 20884 20884 0 0.00 0 7184 6 0 0
x_lock_depdocs_on_canc_invoice 11671 11671 0 0.00 0 27644 129 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 28625 503 0 0
x_make_qstorno_1000_1200 5696 5696 0 0.00 1 35252 1208 0 0
x_make_qstorno_1200_2000 5722 5722 0 0.00 2 102534 3430 0 0
x_make_qstorno_2100_3300 489441 489441 0 0.00 0 90003 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-06-2022 01:39:16 to 14-06-2022 01:39:20.

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:19 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:20 18595 8192000000 9888258528 10193854464 972299248 10.609 100 86 172 0 1000840672 5683856 22857568
23:10:42 17931 8192000000 9932460896 10250072064 1015972768 11.033 100 91 182 0 1047474864 7503904 23998192
23:12:04 18781 8192000000 9974684080 10301165568 1045870304 11.321 100 86 172 0 1076479952 8679488 21930160
23:13:32 12797 8192000000 10023123184 10370842624 1115158848 11.981 100 75 149 0 1142814704 9692832 17963024
23:14:48 8205 8192000000 10085135568 10444062720 1167665552 12.475 100 74 149 0 1197346928 12461296 17220080
23:16:00 6137 8192000000 10122981520 10492604416 1214754208 12.913 100 76 152 1 1244385504 13019488 16611808
23:17:08 4628 8192000000 10158526336 10530684928 1240584160 13.152 100 91 182 2 1275500592 15134816 19781616
23:18:14 3869 8192000000 10188484272 10570362880 1271598160 13.436 100 88 175 1 1304450608 13131200 19721248
23:19:20 2074 8192000000 10199071456 10590396416 1295389568 13.653 100 78 156 1 1324772080 12112608 17269904
23:20:25 8904 8192000000 10216228176 10603692032 1300833776 13.703 100 85 161 2 1332280816 12225600 19221440
23:21:35 3161 8192000000 10200913920 10609774592 1308873872 13.776 100 40 79 2 1324838624 7273984 8690768
23:22:41 9233 8192000000 10196421792 10618122240 1318131680 13.860 100 25 50 0 1330627600 7043248 5452672
23:23:56 16340 8192000000 10216534400 10629177344 1326244784 13.933 100 34 68 1 1339960288 6025840 7689664
23:25:17 2082 8192000000 10253485968 10649239552 1329174832 13.960 100 92 184 0 1358560064 7030544 22354688
23:26:20 4953 8192000000 10232931952 10648162304 1335825328 14.020 100 27 54 0 1348178944 6203568 6150048
23:27:26 7081 8192000000 10200446032 10620776448 1313648128 13.819 99 15 30 0 1322301680 5072624 3580928
23:28:37 6170 8192000000 10189710144 10606231552 1308681488 13.774 100 47 96 2 1325741088 5792624 11266976
23:29:46 7961 8192000000 10171032720 10582982656 1250208944 13.240 96 23 46 1 1260185408 5317456 4659008
23:30:55 7462 8192000000 10158180512 10552414208 1214577520 12.912 95 32 64 0 1228668976 6506032 7585424
23:32:02 8628 8192000000 10127807904 10518695936 1209677184 12.866 96 27 54 1 1221867792 5663040 6527568
23:33:11 11873 8192000000 10167108736 10576265216 1276300784 13.479 100 23 46 3 1287359136 5136448 5921904
23:34:24 21720 8192000000 10180815824 10588729344 1276609952 13.482 99 22 44 1 1286360768 5100288 4650528
23:35:47 8288 8192000000 10179509360 10596655104 1295119600 13.651 100 25 49 1 1307024784 5857936 6047248
23:36:59 10611 8192000000 10200426544 10614169600 1306428080 13.754 100 12 24 1 1314409424 5278480 2702864
23:38:10 46758 8192000000 10233085328 10644017152 1329861840 13.966 100 33 63 1 1343277072 4813872 8601360
23:40:04 77108 8192000000 10261451968 10675761152 1349681760 14.145 100 38 74 1 1375815920 18036816 8097344
23:42:34 21790 8192000000 10285970496 10703085568 1369499344 14.323 100 44 89 0 1398511312 19031920 9980048
23:43:58 8372 8192000000 10299826720 10716672000 1376717536 14.387 100 48 96 0 1410949920 22990240 11242144
23:45:13 9691 8192000000 10316116032 10738212864 1393711664 14.539 100 40 79 0 1426344256 23203648 9428944
23:46:25 4025 8192000000 10330878560 10750263296 1400241232 14.597 100 55 108 2 1444716352 30637104 13838016
23:47:39 16568 8192000000 10335551392 10762756096 1405384848 14.643 100 55 111 0 1452395280 33435648 13574784
23:49:00 10057 8192000000 10344068528 10769448960 1401992448 14.613 99 46 92 2 1442323408 28381568 11949392
23:50:21 11150 8192000000 10348126368 10767716352 1399570944 14.591 99 62 124 2 1448628800 33602288 15455568
23:51:36 2888 8192000000 10372966640 10788691968 1421639904 14.787 100 81 160 2 1478431840 36922176 19869760
23:52:43 6286 8192000000 10364465584 10781298688 1408761616 14.673 100 76 150 1 1466488240 39494384 18232240
23:53:57 2960 8192000000 10380932176 10791530496 1414724000 14.726 100 94 187 0 1482036512 44387776 22924736
23:55:07 5131 8192000000 10369503072 10782863360 1384681328 14.458 98 72 143 2 1451243008 48214688 18346992
23:56:16 1507 8192000000 10360390656 10774458368 1398678448 14.583 100 79 158 1 1464298592 45450576 20169568
23:57:21 4106 8192000000 10331997904 10742243328 1366582464 14.296 100 63 124 0 1431094736 48518256 15994016
23:58:32 4589 8192000000 10330043200 10742018048 1365389232 14.286 100 79 159 1 1433581920 46466160 21726528
23:59:42 9610 8192000000 10310366048 10736402432 1352052384 14.166 99 45 89 0 1410792624 46290208 12450032
00:00:56 11130 8192000000 10314319424 10741035008 1368850016 14.317 100 37 74 2 1413911168 34855424 10205728
00:02:11 8395 8192000000 10292226208 10721193984 1347614960 14.126 98 42 83 0 1385308384 26584752 11108672
00:03:22 19068 8192000000 10282968256 10726326272 1363243728 14.266 100 21 42 2 1398180704 29209568 5727408
00:05:00 27066 8192000000 10283226624 10729009152 1344909856 14.102 99 14 28 0 1368272864 19334176 4028832
00:06:28 50482 8192000000 10270133232 10714779648 1349555008 14.143 99 24 48 1 1364985648 9996128 5434512
00:08:32 27044 8192000000 10272791888 10724962304 1355235920 14.195 99 28 55 0 1375830064 14119920 6474224
00:10:08 47639 8192000000 10288406624 10733883392 1373661888 14.360 100 14 26 0 1385781856 9002512 3117456
00:12:01 84108 8192000000 10299434288 10747908096 1372530176 14.350 99 19 38 0 1390211104 13993824 3687104
00:14:47 135946 8192000000 10293504400 10733080576 1331924640 13.985 96 15 30 0 1350811216 15693168 3193408
00:18:12 32603 8192000000 10319954624 10759839744 1387273040 14.482 99 30 60 0 1410252800 15747824 7231936
00:19:52 100170 8192000000 10282658288 10730237952 1359376928 14.232 98 4 8 0 1376406768 16127536 902304
00:22:38 108032 8192000000 10310335200 10755387392 1381525856 14.430 99 8 16 0 1394216752 10875088 1815808
00:25:28 147008 8192000000 10307047984 10756444160 1374364512 14.366 98 35 70 0 1389953248 7765488 7823248
00:28:55 16403 8192000000 10323985360 10765279232 1398946000 14.586 100 57 112 0 1419955840 8425200 12584640
00:30:17 58716 8192000000 10313305776 10768834560 1402795280 14.620 100 25 50 2 1418412512 9503600 6113632
00:32:17 30494 8192000000 10327547376 10771292160 1407629392 14.663 100 38 76 2 1428310064 11722576 8958096
00:33:48 29312 8192000000 10281161504 10744233984 1364289312 14.276 98 16 32 0 1377903968 9411744 4202912
00:35:26 33134 8192000000 10288752576 10744287232 1373315872 14.357 99 12 24 1 1382183696 6489360 2378464
00:37:00 117886 8192000000 10280312560 10743992320 1373332976 14.357 99 10 20 1 1385502784 9789072 2380736
00:39:59 57390 8192000000 10251917776 10715484160 1319060976 13.868 96 19 38 0 1330469392 7507424 3900992
00:41:57 30602 8192000000 10284630448 10731765760 1343156336 14.086 98 30 59 1 1365844384 15553872 7134176
00:43:30 32227 8192000000 10258997104 10713903104 1326461232 13.935 97 10 20 0 1338088304 9331328 2295744
00:45:03 43206 8192000000 10264726288 10708115456 1307852656 13.767 96 30 60 0 1325428976 10549696 7026624
00:47:38 57000 8192000000 10284483728 10739204096 1363929632 14.273 99 19 38 0 1381657904 13331088 4397184
00:49:47 53253 8192000000 10280216832 10715951104 1275313216 13.470 92 25 49 3 1289855136 8853728 5688192
00:51:43 100605 8192000000 10269052896 10716356608 1315468208 13.836 96 14 28 0 1334190288 15313424 3408656
00:54:29 110195 8192000000 10291628784 10745303040 1355266064 14.195 98 25 50 0 1377668848 16511408 5891376
00:57:29 16317 8192000000 10273635504 10734813184 1360286896 14.240 99 13 26 1 1376081888 12246112 3548880
00:58:56 61427 8192000000 10253268832 10718625792 1343272848 14.087 98 7 14 1 1354303040 9543744 1486448
01:01:00 53691 8192000000 10247515408 10706591744 1336949472 14.030 98 13 26 0 1349399760 9801920 2648368
01:02:54 82295 8192000000 10254832960 10711310336 1345280896 14.105 98 5 10 0 1353442496 7078976 1082624
01:05:16 62034 8192000000 10288925120 10751352832 1372579824 14.350 99 17 34 0 1384760016 8224320 3955872
01:07:30 60766 8192000000 10299473168 10752839680 1357030336 14.211 97 17 34 0 1372048528 11018480 3999712
01:09:33 67740 8192000000 10295828352 10761560064 1384624176 14.458 99 12 24 1 1401131696 13252144 3255376
01:11:41 85611 8192000000 10302442224 10756177920 1363342032 14.267 98 15 30 1 1381208320 14805872 3060416
01:14:09 37246 8192000000 10316302960 10766839808 1398831360 14.585 100 17 34 0 1409520704 7266944 3422400
01:15:50 76675 8192000000 10310452848 10754576384 1336236912 14.023 95 27 54 0 1352859328 10681536 5940880
01:18:08 43291 8192000000 10302027488 10748481536 1347091744 14.121 96 39 77 1 1365801200 9665104 9044352
01:19:58 107989 8192000000 10305645936 10764152832 1360025344 14.238 97 35 70 2 1385978896 17373184 8580368
01:22:47 6974 8192000000 10285075264 10756063232 1380870128 14.424 100 5 10 0 1397072784 15409824 792832
01:23:54 48106 8192000000 10263161696 10711797760 1332742352 13.992 97 17 32 3 1343791568 7189472 3859744
01:25:43 72163 8192000000 10278427792 10733404160 1341098192 14.067 97 17 34 1 1358698160 13569632 4030336
01:27:56 64578 8192000000 10282697200 10746920960 1344947200 14.102 97 16 32 0 1365356144 16225216 4183728
01:30:10 43607 8192000000 10271700640 10734313472 1365384480 14.286 99 11 22 0 1380140720 11801504 2954736
01:32:15 54295 8192000000 10261499792 10709757952 1313273712 13.816 96 37 74 0 1333639280 11577776 8787792
01:34:14 30551 8192000000 10270803712 10711867392 1337379168 14.034 98 39 78 0 1355488128 8488128 9620832
01:36:06 42377 8192000000 10277715920 10722066432 1337182800 14.032 98 13 26 0 1353717424 13347680 3186944
01:37:49 27617 8192000000 9898611808 10293870592 1004188672 10.919 74 28 56 0 1017806480 7762416 5855392

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-06-2022 01:39:22 to 14-06-2022 01:39:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 69 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-06-2022 01:39:38 to 14-06-2022 01:39:40.

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          248966
MON$OLDEST_ACTIVE               249877
MON$OLDEST_SNAPSHOT             249877
MON$NEXT_TRANSACTION            249877
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                       2062752
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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 ---
:537: 
:538: mscs-test-161.budzdorov.ru    Tue Jun 14 01:38:26 2022
:539:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:540: 
:541: 
:542: mscs-test-161.budzdorov.ru    Tue Jun 14 01:38:26 2022
:543:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:544: 
:545: 
:546: mscs-test-161.budzdorov.ru    Tue Jun 14 01:38:26 2022
:547:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:548: 
:549: 
:550: mscs-test-161.budzdorov.ru    Tue Jun 14 01:38:26 2022
:551:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:552: 
:553: 
:554: mscs-test-161.budzdorov.ru    Tue Jun 14 01:38:26 2022
:555:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:556: 
:557: 
:558: mscs-test-161.budzdorov.ru    Tue Jun 14 01:38:26 2022
:559:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:560: 
:561: 
:562: mscs-test-161.budzdorov.ru    Tue Jun 14 01:38:26 2022
:563:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:564: 
--- end of diff output ---

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

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