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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16073228 kB
MemAvailable 31100816 kB
Buffers 0 kB
Cached 15664304 kB
SwapCached 2496 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-07-19 16:38:22
DTS_END                         2022-07-19 16:38:22.4560
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/48024
TRN_ID                          236405
ATT_ID                          624
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: 9329.85 19387 1119582
customer order: creation 169.18 345 20301
customer order: refuse 14.18 351 1702
order to supplier: creation 44.16 1279 5299
order to supplier: removal 3.51 106209 421
invoice (draft): creation 44.37 3282 5324
invoice (draft): removal 53.13 852 6375
invoice accept: apply 40.28 17508 4834
invoice accept: cancel 48.28 677 5793
customer reserve: creation 3835.30 168 460236
customer reserve: removal 4798.76 59 575851
realization accept: apply 82.33 159 9879
realization accept: cancel 70.35 116 8442
payment from customer: creation 57.50 108 6900
payment from customer: removal 5.51 83 661
payment to supplier: creation 47.90 124 5748
payment to supplier: removal 3.44 72 413
service: total inventory turnovers 4.51 1206 541
service: total monetary turnovers 6.29 30 755
service: aggregate perf. data 0.86 24116 103
service: refresh index statistics 0.03 231005 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-07-2022 16:39:55 to 19-07-2022 16:39:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5583
TEST_TIME 2 3608
TEST_TIME 3 5644
TEST_TIME 4 5802
TEST_TIME 5 5398
TEST_TIME 6 5357
TEST_TIME 7 5850
TEST_TIME 8 5714
TEST_TIME 9 6273
TEST_TIME 10 6176
TEST_TIME 11 5888
TEST_TIME 12 5955
TEST_TIME 13 5848
TEST_TIME 14 6240
TEST_TIME 15 6304
TEST_TIME 16 6455
TEST_TIME 17 6438
TEST_TIME 18 7339
TEST_TIME 19 7517
TEST_TIME 20 7691
TEST_TIME 21 7978
TEST_TIME 22 8948
TEST_TIME 23 9001
TEST_TIME 24 9251
TEST_TIME 25 9577
TEST_TIME 26 9795
TEST_TIME 27 9710
TEST_TIME 28 10616
TEST_TIME 29 10658
TEST_TIME 30 10448
TEST_TIME 31 10484
TEST_TIME 32 10716
TEST_TIME 33 10588
TEST_TIME 34 10673
TEST_TIME 35 10443
TEST_TIME 36 10216
TEST_TIME 37 10145
TEST_TIME 38 10163
TEST_TIME 39 10040
TEST_TIME 40 9939
TEST_TIME 41 10039
TEST_TIME 42 9961
TEST_TIME 43 9923
TEST_TIME 44 9781
TEST_TIME 45 9813
TEST_TIME 46 9780
TEST_TIME 47 9626
TEST_TIME 48 9618
TEST_TIME 49 9608
TEST_TIME 50 9591
TEST_TIME 51 9551
TEST_TIME 52 9478
TEST_TIME 53 9412
TEST_TIME 54 9406
TEST_TIME 55 9378
TEST_TIME 56 9490
TEST_TIME 57 9451
TEST_TIME 58 9418
TEST_TIME 59 9426
TEST_TIME 60 9549
TEST_TIME 61 9529
TEST_TIME 62 9622
TEST_TIME 63 9565
TEST_TIME 64 9759
TEST_TIME 65 9769
TEST_TIME 66 9803
TEST_TIME 67 9838
TEST_TIME 68 9761
TEST_TIME 69 9688
TEST_TIME 70 9740
TEST_TIME 71 9661
TEST_TIME 72 9613
TEST_TIME 73 9536
TEST_TIME 74 9528
TEST_TIME 75 9548
TEST_TIME 76 9503
TEST_TIME 77 9458
TEST_TIME 78 9388
TEST_TIME 79 9430
TEST_TIME 80 9427
TEST_TIME 81 9380
TEST_TIME 82 9446
TEST_TIME 83 9477
TEST_TIME 84 9433
TEST_TIME 85 9487
TEST_TIME 86 9534
TEST_TIME 87 9525
TEST_TIME 88 9578
TEST_TIME 89 9566
TEST_TIME 90 9616
TEST_TIME 91 9605
TEST_TIME 92 9616
TEST_TIME 93 9531
TEST_TIME 94 9516
TEST_TIME 95 9461
TEST_TIME 96 9475
TEST_TIME 97 9436
TEST_TIME 98 9442
TEST_TIME 99 9443
TEST_TIME 100 9484
TEST_TIME 101 9497
TEST_TIME 102 9511
TEST_TIME 103 9483
TEST_TIME 104 9508
TEST_TIME 105 9540
TEST_TIME 106 9582
TEST_TIME 107 9585
TEST_TIME 108 9624
TEST_TIME 109 9647
TEST_TIME 110 9624
TEST_TIME 111 9550
TEST_TIME 112 9496
TEST_TIME 113 9521
TEST_TIME 114 9475
TEST_TIME 115 9425
TEST_TIME 116 9446
TEST_TIME 117 9416
TEST_TIME 118 9405
TEST_TIME 119 9368
TEST_TIME 120 9332

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-07-2022 16:39:58 to 19-07-2022 16:40:01.

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 1545128 1545128 0 0.00 0 21325 6 0 0
doc_list_biud 1545128 1545128 0 0.00 0 34643 28 0 0
sp_add_invoice_to_stock 4969 4834 135 2.71 27 192790 17508 0 135
sp_cancel_adding_invoice 5793 5793 0 0.00 4 55310 678 0 0
sp_cancel_client_order 1702 1702 0 0.00 4 10083 351 0 0
sp_cancel_customer_reserve 575851 575851 0 0.00 0 34645 60 0 0
sp_cancel_pay_from_customer 661 661 0 0.00 2 3619 84 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 3 3504 72 0 0
sp_cancel_supplier_invoice 6382 6375 7 0.10 2 18980 853 0 7
sp_cancel_supplier_order 421 421 0 0.00 9 1061054 106210 0 0
sp_cancel_write_off 8442 8442 0 0.00 1 17281 116 0 0
sp_client_order 20301 20301 0 0.00 3 19804 346 0 0
sp_customer_reserve 460236 460236 0 0.00 0 51540 169 0 0
sp_fill_shopping_cart 30924 30924 0 0.00 1 11610 37 0 0
sp_fill_shopping_cart_clo_res 460234 460234 0 0.00 0 22603 15 0 0
sp_get_clo_for_invoice 4832 4832 0 0.00 0 25216 294 0 0
sp_kill_cost_storno 15309 15309 0 0.00 0 17279 60 0 0
sp_kill_qstorno_ret_qs2qd 1166940 1166940 0 0.00 0 34617 33 0 0
sp_kill_qty_storno 613295 613295 0 0.00 0 34642 70 0 0
sp_lock_selected_doc 20264 20264 0 0.00 0 5407 5 0 0
sp_make_cost_storno 27359 27359 0 0.00 0 21306 48 0 0
sp_multiply_rows_for_pdistr 27359 27359 0 0.00 0 6495 27 0 0
sp_multiply_rows_for_qdistr 20301 20301 0 0.00 0 12035 148 0 0
sp_pay_from_customer 6900 6900 0 0.00 4 15709 108 0 0
sp_pay_to_supplier 5748 5748 0 0.00 5 13866 125 0 0
sp_payment_common 12648 12648 0 0.00 1 13593 28 0 0
sp_qd_handle_on_cancel_clo 1702 1702 0 0.00 0 10064 319 0 0
sp_qd_handle_on_invoice_upd_sts 10625 10625 0 0.00 0 17687 322 0 0
sp_qd_handle_on_reserve_upd_sts 9879 9879 0 0.00 0 18550 49 0 0
sp_reserve_write_off 9879 9879 0 0.00 6 18564 159 0 0
sp_supplier_invoice 5324 5324 0 0.00 6 93071 3282 0 0
sp_supplier_order 5299 5299 0 0.00 5 33365 1280 0 0
srv_aggregate_perf_data 103 103 0 0.00 2957 630223 24117 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 951 122806 43422 0 0
srv_find_qd_qs_mism 1072351 1072351 0 0.00 0 17515 5 0 0
srv_make_invnt_saldo 541 541 0 0.00 35 85545 1207 0 0
srv_make_money_saldo 755 755 0 0.00 1 5863 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 100071 333734 231005 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4837 4837 0 0.00 0 14820 212 0 0
t$perf-norm:sp_cancel_adding_invoice 372 372 0 0.00 0 1789 61 0 0
t$perf-norm:sp_cancel_client_order 1702 1702 0 0.00 0 7227 25 0 0
t$perf-norm:sp_cancel_customer_reserve 664 664 0 0.00 0 5729 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 661 661 0 0.00 0 5890 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 320 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 364 364 0 0.00 0 1539 23 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 25252 1668 0 0
t$perf-norm:sp_cancel_write_off 956 956 0 0.00 0 2623 13 0 0
t$perf-norm:sp_client_order 20301 20301 0 0.00 0 7253 17 0 0
t$perf-norm:sp_customer_reserve 990 990 0 0.00 0 4025 18 0 0
t$perf-norm:sp_pay_from_customer 6900 6900 0 0.00 0 4978 11 0 0
t$perf-norm:sp_pay_to_supplier 5748 5748 0 0.00 0 5994 11 0 0
t$perf-norm:sp_reserve_write_off 9879 9879 0 0.00 0 5973 12 0 0
t$perf-norm:sp_supplier_invoice 5324 5324 0 0.00 0 7165 19 0 0
t$perf-norm:sp_supplier_order 5299 5299 0 0.00 0 5351 16 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 344 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 74 2 0 0
t$perf-norm:srv_make_invnt_saldo 541 541 0 0.00 0 2452 26 0 0
t$perf-norm:srv_make_money_saldo 755 755 0 0.00 0 5954 32 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 13 5 0 0
v_add_invoice_to_stock 9934 9664 270 2.71 0 5299 17 0 270
v_all_customers 36702 36702 0 0.00 0 20 1 0 0
v_all_suppliers 29814 29814 0 0.00 0 21 1 0 0
v_all_wares 40602 40602 0 0.00 0 92 1 0 0
v_cancel_adding_invoice 744 744 0 0.00 0 793 8 0 0
v_cancel_client_order 3404 3404 0 0.00 0 5351 8 0 0
v_cancel_customer_prepayment 1322 1322 0 0.00 0 3260 8 0 0
v_cancel_customer_reserve 1328 1328 0 0.00 0 2166 5 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 122 2 0 0
v_cancel_supplier_invoice 742 728 14 1.88 0 1951 20 0 14
v_cancel_supplier_order 842 842 0 0.00 0 5249 16 0 0
v_cancel_write_off 1912 1912 0 0.00 0 1835 14 0 0
v_max_id_clo_ord 5299 5299 0 0.00 0 1834 2 0 0
v_max_id_clo_res 990 990 0 0.00 0 1545 9 0 0
v_max_id_ord_sup 5324 5324 0 0.00 0 4894 4 0 0
v_max_non_paid_invoice 1464 1464 0 0.00 0 3952 5 0 0
v_max_non_paid_realizn 3703 3703 0 0.00 0 5702 6 0 0
v_min_id_clo_ord 5299 5299 0 0.00 0 3925 11 0 0
v_min_id_clo_res 990 990 0 0.00 0 2907 12 0 0
v_min_id_ord_sup 5324 5324 0 0.00 0 7233 9 0 0
v_min_non_paid_invoice 5748 5748 0 0.00 0 13588 20 0 0
v_min_non_paid_realizn 6900 6900 0 0.00 0 6450 20 0 0
v_our_firm 10294 10294 0 0.00 0 12 1 0 0
v_reserve_write_off 19758 19758 0 0.00 0 6624 6 0 0
x_lock_depdocs_on_canc_invoice 11804 11804 0 0.00 0 11660 117 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 20227 413 0 0
x_make_qstorno_1000_1200 5299 5299 0 0.00 1 33353 1203 0 0
x_make_qstorno_1200_2000 5324 5324 0 0.00 3 88121 3149 0 0
x_make_qstorno_2100_3300 460236 460236 0 0.00 0 51529 151 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 19-07-2022 16:40:02 to 19-07-2022 16:40:07.

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
14:08:26 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:26 11801 8192000000 9883068352 10189713408 969891808 10.586 100 86 172 0 997903056 5337056 22674192
14:10:44 37016 8192000000 9944710288 10261225472 1018629056 11.059 100 91 182 0 1049945168 7609632 23706480
14:12:29 11975 8192000000 9975937664 10302169088 1056324080 11.421 100 83 166 0 1086356560 9467424 20565056
14:13:43 9329 8192000000 10040616672 10385432576 1126336832 12.087 100 79 156 0 1155056016 10354800 18364384
14:14:56 6561 8192000000 10097441296 10453753856 1173393744 12.529 100 85 168 0 1205228368 13716272 18118352
14:16:06 3311 8192000000 10119308928 10483208192 1209216160 12.862 100 87 175 1 1242766576 14224208 19326208
14:17:11 2136 8192000000 10150968560 10527444992 1244922784 13.192 100 85 169 4 1275606496 11989088 18694624
14:18:15 1783 8192000000 10177631056 10557071360 1268540016 13.408 100 89 177 0 1302172160 13243152 20388992
14:19:19 854 8192000000 10205970656 10589523968 1291629616 13.619 100 89 178 1 1324564752 12114400 20820736
14:20:20 1507 8192000000 10205770048 10600079360 1301507760 13.709 100 64 128 1 1326388688 11177472 13703456
14:21:24 6049 8192000000 10193583984 10601803776 1307465760 13.763 100 18 38 0 1316774160 5353296 3955104
14:22:32 24127 8192000000 10217765856 10615595008 1313981568 13.822 100 56 111 0 1334070624 6855104 13233952
14:23:56 7055 8192000000 10213550032 10624176128 1318224304 13.861 100 32 65 0 1332890752 7818608 6847840
14:25:04 3806 8192000000 10223191360 10631249920 1330048416 13.968 100 30 58 0 1341652400 4741200 6862784
14:26:12 42505 8192000000 10227456032 10634788864 1327117808 13.941 100 46 90 0 1342094800 4368976 10608016
14:27:55 4996 8192000000 10218284448 10636058624 1326059536 13.932 100 39 75 2 1340260592 5067376 9133680
14:29:02 5554 8192000000 10180170080 10589192192 1262767344 13.355 97 22 45 0 1273240512 5646256 4826912
14:30:08 57117 8192000000 10145107200 10547822592 1179717600 12.588 91 23 46 2 1188935696 4395248 4822848
14:32:13 5552 8192000000 10134625328 10535305216 1207256288 12.844 96 20 40 0 1217754288 5818960 4679040
14:33:21 10775 8192000000 10149441488 10552573952 1250426160 13.242 99 23 44 0 1261048704 4640304 5982240
14:34:36 17885 8192000000 10174629792 10576687104 1260677376 13.336 98 27 54 0 1271629072 5267280 5684416
14:35:54 27987 8192000000 10173041616 10592575488 1287987824 13.586 100 23 48 0 1303289392 10373184 4928384
14:37:24 7431 8192000000 10192334256 10610827264 1304312944 13.734 100 16 32 0 1313448624 5719344 3416336
14:38:34 30646 8192000000 10240430480 10650632192 1333956544 14.003 100 47 92 1 1351272944 6369088 10947312
14:40:15 81459 8192000000 10263527328 10686504960 1358787216 14.226 100 47 92 0 1382858880 13360976 10710688
14:42:49 32453 8192000000 10275448832 10703904768 1372965344 14.354 100 30 56 2 1393710640 14407120 6338176
14:44:32 949 8192000000 10308727136 10718322688 1372710304 14.351 99 73 145 1 1408468960 18906416 16852240
14:45:39 11413 8192000000 10320734144 10745012224 1397719712 14.575 100 60 118 0 1432886560 20678544 14488304
14:46:58 14136 8192000000 10319736112 10746634240 1402412784 14.616 100 47 93 0 1437954480 24407088 11134608
14:48:21 8592 8192000000 10318991680 10749362176 1395024064 14.551 99 40 79 0 1422667328 18104224 9539040
14:49:33 6205 8192000000 10337561408 10767683584 1416887872 14.745 100 50 95 0 1450521328 22105392 11528064
14:50:59 6235 8192000000 10357538112 10783584256 1430267472 14.864 100 48 94 0 1466787712 24541744 11978496
14:52:11 2514 8192000000 10369639792 10786054144 1427054976 14.835 100 75 148 0 1477988976 32557136 18376864
14:53:19 1876 8192000000 10363571312 10777485312 1420648160 14.778 100 84 166 1 1474963840 33546688 20768992
14:54:28 2426 8192000000 10364149248 10777255936 1413649888 14.716 99 68 135 0 1470885824 39671968 17563968
14:55:36 1131 8192000000 10362957456 10769616896 1397798768 14.575 100 81 162 2 1465173056 45930288 21444000
14:56:43 4321 8192000000 10353863376 10771386368 1407250720 14.660 100 72 144 1 1464185712 37697632 19237360
14:57:54 3192 8192000000 10350599584 10759069696 1391206176 14.517 99 77 149 1 1449173808 38450592 19517040
14:59:02 8731 8192000000 10350105728 10770300928 1386520272 14.475 100 56 112 0 1452113552 49867104 15726176
15:00:14 9787 8192000000 10327186544 10755006464 1368383760 14.313 99 37 74 0 1422073408 42608080 11081568
15:01:28 5384 8192000000 10322493392 10734821376 1368572272 14.314 100 59 116 2 1419041280 35396928 15072080
15:02:36 9575 8192000000 10309452656 10744733696 1372718384 14.351 100 34 68 0 1414705360 32159264 9827712
15:04:32 40533 8192000000 10294953504 10747817984 1353507040 14.179 99 17 34 0 1395153776 36480816 5165920
15:06:15 37111 8192000000 10265277248 10717294592 1327069504 13.941 98 11 22 0 1362541232 32105168 3366560
15:08:01 53404 8192000000 10278410112 10710425600 1347463424 14.125 99 32 64 0 1365442688 10473056 7506208
15:09:56 76642 8192000000 10288753552 10749497344 1360391024 14.241 99 15 30 0 1383021984 19553728 3077232
15:12:13 87225 8192000000 10259560208 10697519104 1293180864 13.633 94 12 24 2 1307699568 12334848 2183856
15:14:40 67828 8192000000 10284341680 10725609472 1347761248 14.127 98 32 64 0 1368292400 13533632 6997520
15:16:54 41296 8192000000 10276499168 10725556224 1373623184 14.359 100 14 28 1 1390454832 12844448 3987200
15:18:44 9946 8192000000 10279838640 10725736448 1361914464 14.255 99 43 83 5 1382568848 11152816 9501568
15:20:00 34780 8192000000 10287098192 10738606080 1371380576 14.339 99 11 22 0 1384586304 10410784 2794944
15:21:41 21502 8192000000 10289946128 10752737280 1389924512 14.505 100 6 12 0 1403739712 12432080 1383120
15:23:13 38398 8192000000 10274952224 10738089984 1345115360 14.104 97 8 16 1 1359807888 12732656 1959872
15:24:59 60829 8192000000 10302318656 10756726784 1394499520 14.546 100 19 38 1 1410989872 11950528 4539824
15:27:01 26179 8192000000 10311342432 10764849152 1398347632 14.580 100 39 77 0 1420947744 13884032 8716080
15:28:31 62009 8192000000 10301601488 10764345344 1393597408 14.538 99 17 33 0 1406085712 8895056 3593248
15:30:52 68754 8192000000 10298156816 10772189184 1373027360 14.354 98 14 28 0 1390133600 13909376 3196864
15:33:06 43131 8192000000 10304286768 10766041088 1403973024 14.630 100 7 14 0 1412708336 7221856 1513456
15:34:53 36581 8192000000 10312333744 10769154048 1406300416 14.651 100 13 26 0 1418497456 9448784 2748256
15:36:32 16693 8192000000 10334628528 10783973376 1409553856 14.680 100 48 96 0 1432687104 12729840 10403408
15:37:50 29577 8192000000 10318905712 10777825280 1397737456 14.575 99 23 44 0 1418898592 15963920 5197216
15:39:20 47469 8192000000 10315796768 10773250048 1390479552 14.510 99 19 38 0 1406228736 11583328 4165856
15:41:40 72776 8192000000 10268506384 10743582720 1286001264 13.568 93 17 34 0 1314816144 24758160 4056720
15:43:54 78118 8192000000 10287302288 10742939648 1302874496 13.721 94 12 24 0 1320201664 14630736 2696432
15:46:13 90141 8192000000 10298644560 10735923200 1364305536 14.276 99 32 64 0 1383531904 12010384 7215984
15:48:46 42527 8192000000 10258286032 10714222592 1348200992 14.131 98 23 47 0 1366564368 13499600 4863776
15:50:30 50219 8192000000 10248538368 10694955008 1312099856 13.805 96 26 53 0 1327317600 9104464 6113280
15:52:21 55155 8192000000 10254674880 10711470080 1339881664 14.056 99 11 22 1 1358063776 15487200 2694912
15:54:21 80134 8192000000 10247840400 10697580544 1312858464 13.812 97 13 26 0 1322001760 6453952 2689344
15:56:51 47893 8192000000 10228914304 10684563456 1300619440 13.701 96 13 26 0 1316548448 12947616 2981392
15:58:40 46516 8192000000 10254690560 10705416192 1342909664 14.084 100 29 58 1 1358420416 8755648 6755104
16:00:27 57112 8192000000 10261697232 10710593536 1342822048 14.083 99 14 27 1 1359319040 14168432 2328560
16:02:37 29688 8192000000 10276033120 10716573696 1343615648 14.090 98 45 90 0 1366088784 12109568 10363568
16:04:11 85014 8192000000 10270759408 10722463744 1325941552 13.930 97 15 28 1 1345803856 16942288 2920016
16:08:03 39352 8192000000 10273394992 10725793792 1347051712 14.121 98 19 36 0 1362191088 11588688 3550688
16:09:51 56869 8192000000 10285057600 10742079488 1362217808 14.257 98 25 50 0 1380473040 12987968 5267264
16:12:01 3420 8192000000 10310211552 10746400768 1388018128 14.488 100 68 136 2 1410336512 7623120 14695264
16:13:06 82692 8192000000 10288911472 10757337088 1394099200 14.542 100 7 14 0 1404813424 8916448 1797776
16:15:29 74788 8192000000 10282333280 10743226368 1325174160 13.924 95 9 18 0 1337391056 9818128 2398768
16:17:57 121890 8192000000 10291794000 10757115904 1372506128 14.349 99 12 24 0 1395960880 20804992 2649760
16:20:59 75665 8192000000 10304299920 10755502080 1356136544 14.203 97 14 26 0 1366677888 7522272 3019072
16:23:15 96358 8192000000 10307989056 10780905472 1370980560 14.336 98 12 24 0 1394230064 20457152 2792352
16:26:01 40440 8192000000 10310208832 10762752000 1378736352 14.405 99 29 56 0 1400314720 15127904 6450464
16:27:42 122806 8192000000 10273062192 10725109760 1271193424 13.433 92 15 30 0 1289811088 15616608 3001056
16:30:49 72285 8192000000 10265788608 10737713152 1354130080 14.185 99 21 42 0 1382625232 23627984 4867168
16:33:20 61985 8192000000 10265949888 10724753408 1367904688 14.308 100 11 22 0 1382802528 11916544 2981296
16:35:23 43949 8192000000 10245178976 10700918784 1337857552 14.038 98 13 26 0 1349104976 8085504 3161920
16:37:38 53661 8192000000 9244171088 9520627712 396534096 4.617 30 6 12 0 403756176 5723232 1498848

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-07-2022 16:40:08 to 19-07-2022 16:40:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 135 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-07-2022 16:40:24 to 19-07-2022 16:40:26.

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          236679
MON$OLDEST_ACTIVE               236680
MON$OLDEST_SNAPSHOT             236680
MON$NEXT_TRANSACTION            236680
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                       2035232
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33609 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33609 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33609 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33609 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 ---
:186: 
:187: mscs-test-161.budzdorov.ru    Tue Jul 19 16:38:36 2022
:188:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:189: 
:190: 
:191: mscs-test-161.budzdorov.ru    Tue Jul 19 16:38:36 2022
:192:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:193: 
--- end of diff output ---

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

19.07.22 16:40:27. 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-07-19 16:40:30 No severe PSQL-related problems occured
19.07.22 16:40:31. End of report.