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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-11-22 19:37:53
DTS_END                         2021-11-22 19:37:53.6700
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45200
TRN_ID                          239060
ATT_ID                          668
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: 9346.33 17040 1121559
customer order: creation 174.16 361 20899
customer order: refuse 14.36 371 1723
order to supplier: creation 45.67 1300 5480
order to supplier: removal 3.55 92501 426
invoice (draft): creation 46.71 3334 5605
invoice (draft): removal 55.18 765 6622
invoice accept: apply 41.99 16821 5039
invoice accept: cancel 49.18 721 5902
customer reserve: creation 3874.83 166 464980
customer reserve: removal 4741.73 53 569008
realization accept: apply 86.14 161 10337
realization accept: cancel 81.89 112 9827
payment from customer: creation 59.81 106 7177
payment from customer: removal 5.83 66 700
payment to supplier: creation 49.98 117 5997
payment to supplier: removal 3.38 51 405
service: total inventory turnovers 4.28 1154 513
service: total monetary turnovers 6.70 19 804
service: aggregate perf. data 0.93 13432 111
service: refresh index statistics 0.03 209179 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-11-2021 19:39:05 to 22-11-2021 19:39:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2418
TEST_TIME 2 1894
TEST_TIME 3 2946
TEST_TIME 4 4610
TEST_TIME 5 4286
TEST_TIME 6 4357
TEST_TIME 7 4549
TEST_TIME 8 4677
TEST_TIME 9 4756
TEST_TIME 10 5126
TEST_TIME 11 5474
TEST_TIME 12 5654
TEST_TIME 13 5957
TEST_TIME 14 6437
TEST_TIME 15 6990
TEST_TIME 16 7307
TEST_TIME 17 8214
TEST_TIME 18 7990
TEST_TIME 19 8399
TEST_TIME 20 8580
TEST_TIME 21 8631
TEST_TIME 22 9126
TEST_TIME 23 9481
TEST_TIME 24 9395
TEST_TIME 25 9416
TEST_TIME 26 9841
TEST_TIME 27 10120
TEST_TIME 28 10228
TEST_TIME 29 10153
TEST_TIME 30 10090
TEST_TIME 31 10167
TEST_TIME 32 10509
TEST_TIME 33 10534
TEST_TIME 34 10334
TEST_TIME 35 10086
TEST_TIME 36 10215
TEST_TIME 37 10052
TEST_TIME 38 9941
TEST_TIME 39 9809
TEST_TIME 40 9811
TEST_TIME 41 9940
TEST_TIME 42 9974
TEST_TIME 43 9908
TEST_TIME 44 9774
TEST_TIME 45 9752
TEST_TIME 46 9764
TEST_TIME 47 9656
TEST_TIME 48 9571
TEST_TIME 49 9654
TEST_TIME 50 9534
TEST_TIME 51 9537
TEST_TIME 52 9416
TEST_TIME 53 9352
TEST_TIME 54 9450
TEST_TIME 55 9532
TEST_TIME 56 9463
TEST_TIME 57 9569
TEST_TIME 58 9603
TEST_TIME 59 9656
TEST_TIME 60 9712
TEST_TIME 61 9705
TEST_TIME 62 9742
TEST_TIME 63 9760
TEST_TIME 64 9865
TEST_TIME 65 9808
TEST_TIME 66 9910
TEST_TIME 67 9882
TEST_TIME 68 9772
TEST_TIME 69 9734
TEST_TIME 70 9670
TEST_TIME 71 9613
TEST_TIME 72 9706
TEST_TIME 73 9679
TEST_TIME 74 9628
TEST_TIME 75 9634
TEST_TIME 76 9566
TEST_TIME 77 9493
TEST_TIME 78 9530
TEST_TIME 79 9513
TEST_TIME 80 9516
TEST_TIME 81 9533
TEST_TIME 82 9552
TEST_TIME 83 9517
TEST_TIME 84 9596
TEST_TIME 85 9603
TEST_TIME 86 9598
TEST_TIME 87 9704
TEST_TIME 88 9688
TEST_TIME 89 9722
TEST_TIME 90 9717
TEST_TIME 91 9639
TEST_TIME 92 9559
TEST_TIME 93 9560
TEST_TIME 94 9543
TEST_TIME 95 9525
TEST_TIME 96 9476
TEST_TIME 97 9493
TEST_TIME 98 9462
TEST_TIME 99 9475
TEST_TIME 100 9451
TEST_TIME 101 9472
TEST_TIME 102 9443
TEST_TIME 103 9452
TEST_TIME 104 9475
TEST_TIME 105 9577
TEST_TIME 106 9570
TEST_TIME 107 9551
TEST_TIME 108 9563
TEST_TIME 109 9577
TEST_TIME 110 9521
TEST_TIME 111 9466
TEST_TIME 112 9466
TEST_TIME 113 9449
TEST_TIME 114 9404
TEST_TIME 115 9359
TEST_TIME 116 9347
TEST_TIME 117 9285
TEST_TIME 118 9389
TEST_TIME 119 9362
TEST_TIME 120 9355

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-11-2021 19:39:08 to 22-11-2021 19:39:12.

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 1551726 1551726 0 0.00 0 12572 5 0 0
doc_list_biud 1551726 1551726 0 0.00 0 43322 26 0 0
sp_add_invoice_to_stock 5090 5039 51 1.00 34 156689 16822 0 51
sp_cancel_adding_invoice 5902 5902 0 0.00 5 76763 721 0 0
sp_cancel_client_order 1723 1723 0 0.00 4 18851 371 0 0
sp_cancel_customer_reserve 569008 569008 0 0.00 1 37750 54 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 3 3488 66 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 2 1301 52 0 0
sp_cancel_supplier_invoice 6623 6622 1 0.01 1 39575 765 0 1
sp_cancel_supplier_order 426 426 0 0.00 4 949867 92501 0 0
sp_cancel_write_off 9827 9827 0 0.00 1 43350 113 0 0
sp_client_order 20899 20899 0 0.00 3 20718 361 0 0
sp_customer_reserve 464980 464980 0 0.00 0 48285 167 0 0
sp_fill_shopping_cart 31968 31968 0 0.00 1 11363 35 0 0
sp_fill_shopping_cart_clo_res 464970 464970 0 0.00 0 15138 13 0 0
sp_get_clo_for_invoice 5029 5029 0 0.00 0 22657 288 0 0
sp_kill_cost_storno 16834 16834 0 0.00 0 6156 51 0 0
sp_kill_qstorno_ret_qs2qd 1154891 1154891 0 0.00 0 43302 30 0 0
sp_kill_qty_storno 608874 608874 0 0.00 0 43304 65 0 0
sp_lock_selected_doc 21051 21051 0 0.00 0 10850 5 0 0
sp_make_cost_storno 28540 28540 0 0.00 0 11282 45 0 0
sp_multiply_rows_for_pdistr 28540 28540 0 0.00 0 6634 25 0 0
sp_multiply_rows_for_qdistr 20899 20899 0 0.00 0 20651 163 0 0
sp_pay_from_customer 7177 7177 0 0.00 4 11800 106 0 0
sp_pay_to_supplier 5997 5997 0 0.00 4 12576 117 0 0
sp_payment_common 13174 13174 0 0.00 1 12145 22 0 0
sp_qd_handle_on_cancel_clo 1723 1723 0 0.00 0 18845 342 0 0
sp_qd_handle_on_invoice_upd_sts 10931 10931 0 0.00 0 21621 310 0 0
sp_qd_handle_on_reserve_upd_sts 10337 10337 0 0.00 0 14053 60 0 0
sp_reserve_write_off 10337 10337 0 0.00 6 16534 161 0 0
sp_supplier_invoice 5605 5605 0 0.00 6 106075 3335 0 0
sp_supplier_order 5480 5480 0 0.00 4 65676 1301 0 0
srv_aggregate_perf_data 111 111 0 0.00 2371 96666 13433 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2546 111826 45386 0 0
srv_find_qd_qs_mism 1073172 1073172 0 0.00 0 10830 5 0 0
srv_make_invnt_saldo 513 513 0 0.00 51 26733 1154 0 0
srv_make_money_saldo 804 804 0 0.00 1 635 20 0 0
srv_recalc_idx_stat 4 4 0 0.00 115024 350606 209180 0 0
t$perf-abend:sp_add_invoice_to_stock 51 51 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5039 5039 0 0.00 0 15544 191 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 17025 132 0 0
t$perf-norm:sp_cancel_client_order 1723 1723 0 0.00 0 7535 18 0 0
t$perf-norm:sp_cancel_customer_reserve 634 634 0 0.00 0 2181 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 1976 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 2141 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 13858 67 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 22465 1356 0 0
t$perf-norm:sp_cancel_write_off 970 970 0 0.00 0 6060 19 0 0
t$perf-norm:sp_client_order 20899 20899 0 0.00 0 16759 20 0 0
t$perf-norm:sp_customer_reserve 1047 1047 0 0.00 0 585 4 0 0
t$perf-norm:sp_pay_from_customer 7177 7177 0 0.00 0 6154 10 0 0
t$perf-norm:sp_pay_to_supplier 5997 5997 0 0.00 0 8504 14 0 0
t$perf-norm:sp_reserve_write_off 10337 10337 0 0.00 0 8218 13 0 0
t$perf-norm:sp_supplier_invoice 5605 5605 0 0.00 0 6988 16 0 0
t$perf-norm:sp_supplier_order 5480 5480 0 0.00 0 10162 18 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 243 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 2 0 0 0
t$perf-norm:srv_make_invnt_saldo 513 513 0 0.00 0 797 15 0 0
t$perf-norm:srv_make_money_saldo 804 804 0 0.00 0 3481 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 135 37 0 0
v_add_invoice_to_stock 10158 10056 102 1.00 0 12196 19 0 102
v_all_customers 37884 37884 0 0.00 0 27 0 0 0
v_all_suppliers 30748 30748 0 0.00 0 15 1 0 0
v_all_wares 41794 41794 0 0.00 0 81 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 74 5 0 0
v_cancel_client_order 3446 3446 0 0.00 0 10323 7 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 2475 8 0 0
v_cancel_customer_reserve 1268 1268 0 0.00 0 148 2 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 473 4 0 0
v_cancel_supplier_invoice 836 834 2 0.23 0 4556 21 0 2
v_cancel_supplier_order 852 852 0 0.00 0 7432 27 0 0
v_cancel_write_off 1940 1940 0 0.00 0 11472 23 0 0
v_max_id_clo_ord 5478 5478 0 0.00 0 2922 2 0 0
v_max_id_clo_res 1047 1047 0 0.00 0 4572 23 0 0
v_max_id_ord_sup 5593 5593 0 0.00 0 3088 3 0 0
v_max_non_paid_invoice 1694 1694 0 0.00 0 1046 3 0 0
v_max_non_paid_realizn 3876 3876 0 0.00 0 1662 3 0 0
v_min_id_clo_ord 5478 5478 0 0.00 0 2218 5 0 0
v_min_id_clo_res 1047 1047 0 0.00 0 10277 26 0 0
v_min_id_ord_sup 5593 5593 0 0.00 0 2263 3 0 0
v_min_non_paid_invoice 5997 5997 0 0.00 0 12140 16 0 0
v_min_non_paid_realizn 7177 7177 0 0.00 0 8262 16 0 0
v_our_firm 10512 10512 0 0.00 0 26 1 0 0
v_reserve_write_off 20670 20670 0 0.00 0 10459 7 0 0
x_lock_depdocs_on_canc_invoice 12107 12107 0 0.00 0 27420 103 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 16752 359 0 0
x_make_qstorno_1000_1200 5480 5480 0 0.00 1 65665 1225 0 0
x_make_qstorno_1200_2000 5605 5605 0 0.00 3 102675 3202 0 0
x_make_qstorno_2100_3300 464980 464980 0 0.00 0 47379 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-11-2021 19:39:13 to 22-11-2021 19:39:17.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:07:57 4 8192000000 8798384544 8809578496 4918528 0.060 2 1 2 0 5018128 56816 42784
17:08:58 32681 8192000000 9906362896 10217213952 983011360 10.714 100 89 178 0 1013159872 6550688 23597824
17:10:37 11248 8192000000 9951971504 10275487744 1031058416 11.179 100 90 183 0 1062566416 8632144 22875856
17:11:53 16055 8192000000 9984271856 10320330752 1065039824 11.505 100 80 160 0 1095157808 10349376 19768608
17:13:14 12404 8192000000 10042109760 10391330816 1121306144 12.039 100 85 170 2 1154210992 11968304 20936544
17:14:29 11763 8192000000 10081749392 10444423168 1169576144 12.493 100 78 156 2 1199410880 12437888 17396848
17:15:45 5008 8192000000 10128442224 10500349952 1218451392 12.947 100 81 161 1 1249146496 13011088 17684016
17:16:52 3482 8192000000 10170449936 10546434048 1256163536 13.295 100 82 164 2 1287044496 12995632 17885328
17:17:58 2967 8192000000 10203281648 10586501120 1288518528 13.591 100 89 176 3 1320792784 12217088 20057168
17:19:02 2223 8192000000 10215385472 10604056576 1300815696 13.703 100 90 180 1 1335717536 13105680 21796160
17:20:05 4885 8192000000 10194187472 10606391296 1304770432 13.739 100 39 77 0 1322610784 9413792 8426560
17:21:15 7884 8192000000 10206637696 10615320576 1311571360 13.800 100 39 78 4 1327947040 7714656 8661024
17:22:25 13636 8192000000 10214354832 10623635456 1320325312 13.880 100 20 40 0 1331009376 5946064 4738000
17:23:43 13500 8192000000 10219307312 10636730368 1323925472 13.912 100 25 50 0 1335641952 5994368 5722112
17:24:58 4749 8192000000 10233326144 10647343104 1334321920 14.006 100 44 88 1 1350924016 5801088 10801008
17:26:09 6269 8192000000 10220231552 10646016000 1333108960 13.995 100 19 38 1 1344217312 6541488 4566864
17:27:16 57855 8192000000 10208987248 10633662464 1300041024 13.696 97 27 53 0 1311489856 5239232 6209600
17:29:16 7872 8192000000 10195497296 10610520064 1305722704 13.747 100 26 53 2 1318092768 6365424 6004640
17:30:25 6362 8192000000 10125425280 10524536832 1226009744 13.017 98 26 52 1 1237738048 5403712 6324592
17:31:33 7505 8192000000 10143294896 10548867072 1239910144 13.145 98 16 32 0 1249336848 5914320 3512384
17:32:46 43008 8192000000 10148857584 10559987712 1254963536 13.284 99 36 71 0 1269239232 5709248 8566448
17:34:31 8164 8192000000 10187783008 10595856384 1296720848 13.665 100 21 42 0 1306941600 5031600 5189152
17:35:43 9140 8192000000 10200954640 10614525952 1308184848 13.770 100 21 42 0 1319314416 6338656 4790912
17:36:53 17010 8192000000 10209732112 10629300224 1317693216 13.856 100 13 26 2 1326289696 5565216 3031264
17:38:15 68094 8192000000 10243002960 10660323328 1342993520 14.084 100 43 86 2 1359596816 6641248 9962048
17:40:28 14793 8192000000 10273349296 10689024000 1365192960 14.284 100 44 86 0 1387484288 12634368 9656960
17:41:44 39875 8192000000 10280819712 10710974464 1377661856 14.396 100 30 58 2 1398437056 14033872 6741328
17:43:32 16433 8192000000 10305775184 10729746432 1393866144 14.540 100 47 94 0 1422586848 17412960 11307744
17:44:57 7067 8192000000 10321554208 10743660544 1403792560 14.629 100 56 112 0 1436495776 19212880 13490336
17:46:10 8433 8192000000 10328175776 10752221184 1407296896 14.660 100 55 110 2 1442383856 21868544 13218416
17:47:23 4306 8192000000 10353989328 10771025920 1417247024 14.748 100 64 125 0 1457795424 25261312 15287088
17:48:33 3093 8192000000 10358127376 10775777280 1406976528 14.657 99 71 137 1 1456419024 31331600 18110896
17:49:40 2545 8192000000 10364207664 10780823552 1416346736 14.740 100 74 148 1 1468999520 34241344 18411440
17:50:49 6282 8192000000 10363867008 10788859904 1422006560 14.790 100 59 118 1 1473717344 35604192 16106592
17:52:00 3936 8192000000 10361877680 10779193344 1407690480 14.663 99 73 145 0 1462526640 37019552 17816608
17:53:09 14639 8192000000 10329020960 10759061504 1397577904 14.573 100 46 91 1 1442539968 32249424 12712640
17:54:27 24173 8192000000 10310804768 10752163840 1381382096 14.429 99 39 76 3 1421012736 29084736 10545904
17:56:00 6660 8192000000 10318364672 10742530048 1366792752 14.298 99 54 108 1 1411680624 31387888 13499984
17:57:11 5225 8192000000 10312208768 10733359104 1369638464 14.324 100 44 87 3 1410512656 30067472 10806720
17:58:23 11063 8192000000 10307093488 10732703744 1369038208 14.318 100 37 75 0 1411320800 32075648 10206944
17:59:39 23792 8192000000 10293641328 10738126848 1353897952 14.183 99 31 62 0 1398655328 36619584 8137792
18:01:08 27741 8192000000 10281509312 10713985024 1336685328 14.028 98 15 30 0 1362847520 22260144 3902048
18:02:38 14247 8192000000 10280966688 10720100352 1354772176 14.190 100 32 65 1 1395411808 31826240 8813392
18:03:55 39329 8192000000 10279035440 10713186304 1338507088 14.044 99 22 44 0 1373166320 28579424 6079808
18:05:41 34971 8192000000 10264186640 10697461760 1329729568 13.965 98 25 50 1 1346939536 11453248 5756720
18:07:33 17737 8192000000 10298648528 10734374912 1377956656 14.398 100 35 70 1 1406613008 20341024 8315328
18:08:52 65590 8192000000 10281777664 10739273728 1328178960 13.951 97 9 18 0 1356673808 26539456 1955392
18:10:59 99801 8192000000 10268247936 10698420224 1316203824 13.842 97 42 82 0 1339759712 13731760 9824128
18:13:54 74917 8192000000 10286809840 10740408320 1376268864 14.383 100 21 42 1 1400246000 18834800 5142336
18:16:11 49675 8192000000 10308684336 10749243392 1389208672 14.499 100 37 73 0 1415421024 16906080 9306272
18:18:16 37231 8192000000 10293420256 10751926272 1385214272 14.463 100 17 34 0 1405526640 16074688 4237680
18:19:53 61354 8192000000 10274339632 10734174208 1351784240 14.164 97 14 29 0 1367124400 11966448 3373712
18:21:59 35613 8192000000 10289693056 10756829184 1387644816 14.485 100 17 34 0 1406249760 14497184 4107760
18:24:15 29189 8192000000 10308201936 10754056192 1381820048 14.433 99 29 57 0 1398627328 9660704 7146576
18:25:48 88433 8192000000 10287232432 10757783552 1378452256 14.403 99 18 36 0 1393592144 11194208 3945680
18:28:24 71006 8192000000 10305069232 10775842816 1399961536 14.595 100 11 22 0 1422797584 20030784 2805264
18:30:36 67273 8192000000 10311345872 10771329024 1380732736 14.423 99 16 32 0 1407896992 22675104 4489152
18:32:45 4867 8192000000 10325259808 10764267520 1399875136 14.594 100 57 113 2 1423008608 9561344 13572128
18:34:50 64964 8192000000 10309478048 10766921728 1391625680 14.520 99 26 53 0 1408215584 10611040 5978864
18:37:03 29017 8192000000 10321431952 10777706496 1403872208 14.629 100 36 72 0 1427372384 15771472 7728704
18:38:32 47326 8192000000 10290818800 10742067200 1364157072 14.275 98 34 68 1 1381604112 9931552 7515488
18:40:23 62539 8192000000 10302736128 10767421440 1364451648 14.277 98 4 8 0 1379266448 14072944 741856
18:42:27 51337 8192000000 10279058064 10721857536 1365450144 14.286 100 37 74 0 1387231184 13548288 8232752
18:44:20 94810 8192000000 10261016208 10706018304 1343884400 14.092 99 20 40 0 1357487888 8612832 4990656
18:47:01 53397 8192000000 10281680560 10728144896 1371949968 14.345 100 30 60 0 1388144640 9295264 6899408
18:48:57 78409 8192000000 10256266496 10716545024 1327395504 13.944 97 8 16 0 1339687600 10331552 1960544
18:51:16 47785 8192000000 10235426960 10683088896 1310436352 13.790 96 13 26 1 1320966112 7520768 3008992
18:53:04 52919 8192000000 10252582672 10715185152 1336767888 14.028 98 10 19 0 1347571264 8278752 2524624
18:54:57 80409 8192000000 10242703536 10707632128 1300195920 13.697 96 5 10 0 1309305472 7906864 1202688
18:57:22 30269 8192000000 10228309600 10672816128 1286227168 13.570 96 30 61 0 1303544864 10654352 6663344
18:58:54 34239 8192000000 10234680752 10684469248 1315009136 13.831 99 16 32 3 1328904384 9741792 4153456
19:00:29 56695 8192000000 10257787616 10712891392 1338532704 14.044 99 22 44 0 1356049088 12715232 4801152
19:02:28 77057 8192000000 10262806032 10706960384 1346203872 14.113 99 26 52 0 1367404944 14933120 6267952
19:04:46 69407 8192000000 10273922208 10719014912 1330278624 13.970 97 25 50 2 1347190928 11580672 5331632
19:07:00 56735 8192000000 10280346048 10718130176 1342285376 14.078 98 15 30 1 1355152480 9581712 3285392
19:08:58 111825 8192000000 10295065408 10746208256 1385103216 14.462 100 23 46 0 1401729248 11348688 5277344
19:11:50 71240 8192000000 10277741200 10750484480 1370955680 14.336 99 7 14 0 1386311232 13777360 1578192
19:14:02 43038 8192000000 10275252112 10734661632 1341545840 14.071 97 20 39 1 1359362752 13457520 4359392
19:15:51 79883 8192000000 10298685648 10764599296 1376744112 14.387 99 24 48 0 1399728192 17111744 5872336
19:18:11 47672 8192000000 10301066976 10754334720 1376885920 14.389 99 17 34 1 1394462912 14089136 3487856
19:20:02 82565 8192000000 10307819616 10774368256 1369472288 14.322 97 18 36 0 1387585904 13744352 4369264
19:22:24 45491 8192000000 10299952144 10767872000 1386837200 14.478 99 15 31 0 1399864192 9590944 3436048
19:24:48 14626 8192000000 10312195952 10758643712 1395531744 14.555 100 34 68 2 1411970672 9228176 7210752
19:26:04 64127 8192000000 10311376592 10762186752 1380031856 14.417 99 22 44 0 1394967184 10223232 4712096
19:28:12 85533 8192000000 10283553680 10745827328 1347642080 14.126 97 19 38 1 1365853696 14249904 3961712
19:30:39 48422 8192000000 10241671056 10701807616 1329431760 13.962 97 6 12 0 1338079344 7151216 1496368
19:33:26 84442 8192000000 10281869008 10742112256 1374959200 14.371 100 14 27 1 1390964576 12638928 3366448
19:35:59 59120 8192000000 10281762288 10735558656 1338397600 14.043 97 14 28 0 1353888672 12711952 2779120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 22-11-2021 19:39:19 to 22-11-2021 19:39:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 51 v_add_invoice_to_stock 335544517
except 1 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-11-2021 19:39:34 to 22-11-2021 19:39:36.

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          237920
MON$OLDEST_ACTIVE               239233
MON$OLDEST_SNAPSHOT             239233
MON$NEXT_TRANSACTION            239233
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                       2021632
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 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 ---
:191: 
:192: mscs-test-161.budzdorov.ru    Mon Nov 22 19:37:55 2021
:193:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:194: 
:195: 
:196: mscs-test-161.budzdorov.ru    Mon Nov 22 19:37:56 2021
:197:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:198: 
--- end of diff output ---

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

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