Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.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 15991340 kB
MemAvailable 31231500 kB
Buffers 0 kB
Cached 15692336 kB
SwapCached 2460 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-18 01:38:20
DTS_END                         2022-06-18 01:38:20.1100
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(108:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/47960
TRN_ID                          251195
ATT_ID                          699
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: 9471.28 19009 1136554
customer order: creation 183.83 325 22060
customer order: refuse 15.41 337 1849
order to supplier: creation 49.09 1215 5891
order to supplier: removal 3.54 97646 425
invoice (draft): creation 48.52 3465 5822
invoice (draft): removal 52.13 844 6256
invoice accept: apply 44.42 16480 5330
invoice accept: cancel 46.98 863 5638
customer reserve: creation 4074.70 163 488964
customer reserve: removal 4639.48 58 556737
realization accept: apply 88.35 163 10602
realization accept: cancel 86.47 99 10376
payment from customer: creation 63.25 111 7590
payment from customer: removal 5.95 62 714
payment to supplier: creation 53.17 148 6380
payment to supplier: removal 3.46 63 415
service: total inventory turnovers 4.65 1278 558
service: total monetary turnovers 7.05 45 846
service: aggregate perf. data 0.81 25460 97
service: refresh index statistics 0.03 231359 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-06-2022 01:39:21 to 18-06-2022 01:39:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8512
TEST_TIME 2 4357
TEST_TIME 3 4068
TEST_TIME 4 5177
TEST_TIME 5 5216
TEST_TIME 6 5607
TEST_TIME 7 5664
TEST_TIME 8 5619
TEST_TIME 9 5609
TEST_TIME 10 5822
TEST_TIME 11 5855
TEST_TIME 12 5823
TEST_TIME 13 6001
TEST_TIME 14 6166
TEST_TIME 15 6660
TEST_TIME 16 6877
TEST_TIME 17 7093
TEST_TIME 18 7226
TEST_TIME 19 7633
TEST_TIME 20 7607
TEST_TIME 21 8016
TEST_TIME 22 8350
TEST_TIME 23 8405
TEST_TIME 24 8366
TEST_TIME 25 8582
TEST_TIME 26 8705
TEST_TIME 27 8753
TEST_TIME 28 8961
TEST_TIME 29 9770
TEST_TIME 30 10661
TEST_TIME 31 10487
TEST_TIME 32 10466
TEST_TIME 33 10629
TEST_TIME 34 10500
TEST_TIME 35 10356
TEST_TIME 36 10283
TEST_TIME 37 10274
TEST_TIME 38 10124
TEST_TIME 39 10136
TEST_TIME 40 10088
TEST_TIME 41 10015
TEST_TIME 42 9966
TEST_TIME 43 10043
TEST_TIME 44 10037
TEST_TIME 45 10147
TEST_TIME 46 10089
TEST_TIME 47 10037
TEST_TIME 48 10080
TEST_TIME 49 10113
TEST_TIME 50 9969
TEST_TIME 51 9810
TEST_TIME 52 9804
TEST_TIME 53 9829
TEST_TIME 54 9700
TEST_TIME 55 9671
TEST_TIME 56 9617
TEST_TIME 57 9669
TEST_TIME 58 9648
TEST_TIME 59 9579
TEST_TIME 60 9549
TEST_TIME 61 9549
TEST_TIME 62 9478
TEST_TIME 63 9376
TEST_TIME 64 9412
TEST_TIME 65 9378
TEST_TIME 66 9383
TEST_TIME 67 9381
TEST_TIME 68 9464
TEST_TIME 69 9443
TEST_TIME 70 9507
TEST_TIME 71 9519
TEST_TIME 72 9461
TEST_TIME 73 9511
TEST_TIME 74 9604
TEST_TIME 75 9611
TEST_TIME 76 9654
TEST_TIME 77 9692
TEST_TIME 78 9681
TEST_TIME 79 9663
TEST_TIME 80 9588
TEST_TIME 81 9594
TEST_TIME 82 9538
TEST_TIME 83 9546
TEST_TIME 84 9523
TEST_TIME 85 9486
TEST_TIME 86 9451
TEST_TIME 87 9453
TEST_TIME 88 9392
TEST_TIME 89 9362
TEST_TIME 90 9383
TEST_TIME 91 9490
TEST_TIME 92 9480
TEST_TIME 93 9548
TEST_TIME 94 9528
TEST_TIME 95 9565
TEST_TIME 96 9615
TEST_TIME 97 9593
TEST_TIME 98 9718
TEST_TIME 99 9759
TEST_TIME 100 9741
TEST_TIME 101 9796
TEST_TIME 102 9748
TEST_TIME 103 9704
TEST_TIME 104 9656
TEST_TIME 105 9624
TEST_TIME 106 9631
TEST_TIME 107 9619
TEST_TIME 108 9589
TEST_TIME 109 9547
TEST_TIME 110 9522
TEST_TIME 111 9500
TEST_TIME 112 9465
TEST_TIME 113 9437
TEST_TIME 114 9485
TEST_TIME 115 9450
TEST_TIME 116 9454
TEST_TIME 117 9524
TEST_TIME 118 9533
TEST_TIME 119 9521
TEST_TIME 120 9473

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-06-2022 01:39:25 to 18-06-2022 01:39:28.

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 1588495 1588495 0 0.00 0 30744 6 0 0
doc_list_biud 1588495 1588495 0 0.00 0 58271 26 0 0
sp_add_invoice_to_stock 5414 5330 84 1.55 16 199011 16481 0 84
sp_cancel_adding_invoice 5638 5638 0 0.00 5 59383 864 0 0
sp_cancel_client_order 1849 1849 0 0.00 4 8736 338 0 0
sp_cancel_customer_reserve 556737 556737 0 0.00 1 22229 58 0 0
sp_cancel_pay_from_customer 714 714 0 0.00 2 3917 63 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 3 3206 63 0 0
sp_cancel_supplier_invoice 6263 6256 7 0.11 2 58282 844 0 7
sp_cancel_supplier_order 425 425 0 0.00 6 1113762 97647 0 0
sp_cancel_write_off 10376 10376 0 0.00 1 13923 100 0 0
sp_client_order 22060 22060 0 0.00 3 23664 325 0 0
sp_customer_reserve 488964 488964 0 0.00 0 55913 163 0 0
sp_fill_shopping_cart 33762 33762 0 0.00 1 14470 40 0 0
sp_fill_shopping_cart_clo_res 488961 488961 0 0.00 0 44044 12 0 0
sp_get_clo_for_invoice 5327 5327 0 0.00 0 20579 255 0 0
sp_kill_cost_storno 17143 17143 0 0.00 0 12539 57 0 0
sp_kill_qstorno_ret_qs2qd 1130531 1130531 0 0.00 0 58267 32 0 0
sp_kill_qty_storno 597209 597209 0 0.00 0 58269 69 0 0
sp_lock_selected_doc 21959 21959 0 0.00 0 11615 5 0 0
sp_make_cost_storno 29898 29898 0 0.00 0 30742 55 0 0
sp_multiply_rows_for_pdistr 29898 29898 0 0.00 0 22775 31 0 0
sp_multiply_rows_for_qdistr 22060 22060 0 0.00 0 19793 148 0 0
sp_pay_from_customer 7590 7590 0 0.00 4 20830 111 0 0
sp_pay_to_supplier 6380 6380 0 0.00 5 30768 149 0 0
sp_payment_common 13970 13970 0 0.00 1 13477 28 0 0
sp_qd_handle_on_cancel_clo 1849 1849 0 0.00 0 8729 319 0 0
sp_qd_handle_on_invoice_upd_sts 10964 10964 0 0.00 0 38726 332 0 0
sp_qd_handle_on_reserve_upd_sts 10602 10602 0 0.00 0 14361 51 0 0
sp_reserve_write_off 10602 10602 0 0.00 6 14372 164 0 0
sp_supplier_invoice 5822 5822 0 0.00 5 95796 3466 0 0
sp_supplier_order 5891 5891 0 0.00 5 33993 1216 0 0
srv_aggregate_perf_data 97 97 0 0.00 2974 732473 25460 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2101 170578 48450 0 0
srv_find_qd_qs_mism 1085308 1085308 0 0.00 0 15553 5 0 0
srv_make_invnt_saldo 558 558 0 0.00 37 56587 1278 0 0
srv_make_money_saldo 846 846 0 0.00 0 14295 46 0 0
srv_recalc_idx_stat 4 4 0 0.00 109544 293216 231359 0 0
t$perf-abend:sp_add_invoice_to_stock 84 84 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 5330 5330 0 0.00 0 19001 155 0 0
t$perf-norm:sp_cancel_adding_invoice 445 445 0 0.00 0 7053 122 0 0
t$perf-norm:sp_cancel_client_order 1849 1849 0 0.00 0 2222 12 0 0
t$perf-norm:sp_cancel_customer_reserve 735 735 0 0.00 0 402 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 714 714 0 0.00 0 665 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 796 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 6093 46 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 23504 1736 0 0
t$perf-norm:sp_cancel_write_off 1022 1022 0 0.00 0 1821 9 0 0
t$perf-norm:sp_client_order 22060 22060 0 0.00 0 10777 19 0 0
t$perf-norm:sp_customer_reserve 1049 1049 0 0.00 0 2133 7 0 0
t$perf-norm:sp_pay_from_customer 7590 7590 0 0.00 0 6941 8 0 0
t$perf-norm:sp_pay_to_supplier 6380 6380 0 0.00 0 8554 11 0 0
t$perf-norm:sp_reserve_write_off 10602 10602 0 0.00 0 11173 12 0 0
t$perf-norm:sp_supplier_invoice 5822 5822 0 0.00 0 8274 19 0 0
t$perf-norm:sp_supplier_order 5891 5891 0 0.00 0 6914 19 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 689 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1769 44 0 0
t$perf-norm:srv_make_invnt_saldo 558 558 0 0.00 0 1307 26 0 0
t$perf-norm:srv_make_money_saldo 846 846 0 0.00 0 3954 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10820 10652 168 1.55 0 5397 15 0 168
v_all_customers 40270 40270 0 0.00 0 108 1 0 0
v_all_suppliers 32672 32672 0 0.00 0 55 1 0 0
v_all_wares 44120 44120 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 890 890 0 0.00 0 1621 7 0 0
v_cancel_client_order 3698 3698 0 0.00 0 2396 4 0 0
v_cancel_customer_prepayment 1428 1428 0 0.00 0 1211 6 0 0
v_cancel_customer_reserve 1470 1470 0 0.00 0 891 4 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 1354 5 0 0
v_cancel_supplier_invoice 866 852 14 1.61 0 4762 18 0 14
v_cancel_supplier_order 850 850 0 0.00 0 2928 9 0 0
v_cancel_write_off 2044 2044 0 0.00 0 1130 13 0 0
v_max_id_clo_ord 5890 5890 0 0.00 0 823 2 0 0
v_max_id_clo_res 1049 1049 0 0.00 0 1906 17 0 0
v_max_id_ord_sup 5810 5810 0 0.00 0 5882 4 0 0
v_max_non_paid_invoice 1744 1744 0 0.00 0 536 2 0 0
v_max_non_paid_realizn 3991 3991 0 0.00 0 1255 3 0 0
v_min_id_clo_ord 5890 5890 0 0.00 0 7004 9 0 0
v_min_id_clo_res 1049 1049 0 0.00 0 1476 9 0 0
v_min_id_ord_sup 5810 5810 0 0.00 0 5942 7 0 0
v_min_non_paid_invoice 6380 6380 0 0.00 0 13465 22 0 0
v_min_non_paid_realizn 7590 7590 0 0.00 0 11077 19 0 0
v_our_firm 11048 11048 0 0.00 0 16 1 0 0
v_reserve_write_off 21204 21204 0 0.00 0 5251 4 0 0
x_lock_depdocs_on_canc_invoice 11468 11468 0 0.00 0 20306 132 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 20267 361 0 0
x_make_qstorno_1000_1200 5891 5891 0 0.00 1 33974 1127 0 0
x_make_qstorno_1200_2000 5822 5822 0 0.00 2 95788 3323 0 0
x_make_qstorno_2100_3300 488964 488964 0 0.00 0 55752 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-06-2022 01:39:29 to 18-06-2022 01:39:33.

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:23 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:25 33737 8192000000 9916990512 10229497856 995642176 10.836 100 87 174 0 1024575552 6380528 22552848
23:11:01 28936 8192000000 9954957760 10277515264 1035252560 11.219 100 92 184 0 1067414928 8546416 23615952
23:12:40 21227 8192000000 9982102336 10322116608 1071818448 11.569 100 74 148 1 1099616464 9689104 18108912
23:14:04 14538 8192000000 10049942224 10401599488 1137964688 12.196 100 81 162 0 1167978896 11370880 18643328
23:15:22 6921 8192000000 10110045760 10475794432 1194797616 12.728 100 71 142 1 1222188976 11585504 15805856
23:16:31 4526 8192000000 10155378736 10526879744 1236635296 13.115 100 86 170 1 1268291824 13785856 17870672
23:17:38 8282 8192000000 10184363712 10567680000 1269680480 13.419 100 71 141 0 1298188656 13541808 14966368
23:18:48 2199 8192000000 10203379616 10589667328 1296025840 13.659 100 83 166 3 1327048496 12291840 18730816
23:19:52 1873 8192000000 10220886752 10612035584 1307673104 13.765 100 88 176 2 1340629808 12679456 20277248
23:20:55 11241 8192000000 10225798672 10622693376 1320148752 13.878 100 70 145 1 1343687600 6219920 17318928
23:22:07 7986 8192000000 10207506464 10627604480 1323045248 13.904 100 18 36 1 1333356640 6146448 4164944
23:23:21 5930 8192000000 10214718560 10634915840 1329111040 13.959 100 20 40 2 1340190944 6369072 4710832
23:24:31 62473 8192000000 10234064800 10657153024 1338941552 14.048 100 39 77 2 1353571392 4695856 9933984
23:26:34 3226 8192000000 10240183520 10654441472 1330788928 13.974 99 38 76 0 1345675344 5454944 9431472
23:27:39 8047 8192000000 10231719344 10660667392 1343080528 14.085 100 17 34 1 1353364016 5907104 4376384
23:28:47 27674 8192000000 10161448224 10574450688 1214680432 12.912 92 20 40 1 1224008848 4500464 4827952
23:30:26 5717 8192000000 10148886240 10548875264 1185886272 12.645 93 33 66 0 1199694960 5143184 8665504
23:31:33 4794 8192000000 10142399856 10541256704 1229845504 13.053 98 35 71 1 1243460048 5118176 8496368
23:32:41 6753 8192000000 10163502128 10566459392 1267725184 13.401 100 33 66 1 1281614608 6031008 7858416
23:33:49 57239 8192000000 10182727376 10588774400 1272603568 13.445 99 39 78 1 1287782800 6299808 8879424
23:35:47 4431 8192000000 10198084176 10608398336 1300648096 13.701 100 36 72 1 1316005232 7207216 8149920
23:36:53 6811 8192000000 10206070624 10619871232 1310359360 13.789 100 19 38 0 1319517952 5302112 3856480
23:38:00 12851 8192000000 10222135008 10637246464 1323348480 13.907 100 18 35 1 1332243472 4634688 4260304
23:39:23 10259 8192000000 10252668512 10667200512 1348755440 14.136 100 44 89 1 1366856208 7934368 10166400
23:40:34 84341 8192000000 10265039920 10694111232 1362151984 14.257 100 34 68 1 1381668448 11107808 8408656
23:43:05 2245 8192000000 10294391664 10715381760 1380423872 14.420 100 58 116 0 1406645968 12927840 13294256
23:44:13 16101 8192000000 10304362240 10729406464 1389558656 14.502 100 52 103 1 1416474560 14768512 12147392
23:45:31 5965 8192000000 10321154256 10743316480 1399606544 14.591 100 55 109 1 1429112448 16755280 12750624
23:46:41 12118 8192000000 10303587488 10730237952 1382992256 14.443 99 56 112 1 1415003696 18839664 13171776
23:47:57 8554 8192000000 10320186608 10748297216 1402215440 14.615 100 50 100 2 1433326880 18783312 12328128
23:49:11 11107 8192000000 10322373904 10743341056 1394273424 14.544 99 56 109 2 1427827696 20549584 13004688
23:50:27 11698 8192000000 10343689200 10772471808 1407415088 14.661 99 40 78 0 1440221504 23423792 9382624
23:51:44 4520 8192000000 10349575472 10759016448 1392325728 14.527 99 73 145 1 1443455328 33914160 17215440
23:52:55 2677 8192000000 10352175008 10758565888 1393944384 14.541 100 92 180 3 1454388832 37969504 22474944
23:54:04 8290 8192000000 10298923904 10713198592 1356819712 14.209 99 51 102 0 1407003328 37052144 13131472
23:55:19 2100 8192000000 10324961952 10724724736 1364159728 14.275 100 85 169 0 1427100576 42521616 20419232
23:56:30 3903 8192000000 10342701424 10754420736 1376895968 14.389 100 78 157 0 1447988928 51013792 20079168
23:57:38 4791 8192000000 10351865504 10763182080 1384174080 14.454 100 68 135 0 1453540992 52385296 16981616
23:58:49 2101 8192000000 10369577280 10770468864 1383413824 14.447 99 86 167 1 1459402352 55468880 20519648
23:59:56 5732 8192000000 10363158512 10783731712 1399174368 14.588 100 58 117 0 1468401600 53691280 15535952
00:01:10 14154 8192000000 10338786496 10765393920 1369602304 14.323 98 34 66 2 1431049360 52147888 9299168
00:02:31 12797 8192000000 10350884432 10780000256 1388044032 14.488 100 34 67 1 1456015120 58285776 9685312
00:03:53 21388 8192000000 10354695280 10785619968 1389112384 14.498 100 35 70 3 1454590752 55704112 9774256
00:05:16 22847 8192000000 10333066768 10771931136 1367338112 14.303 100 20 40 0 1436958144 63301664 6318368
00:06:55 27555 8192000000 10329291792 10754789376 1338883552 14.047 99 33 65 1 1415319616 66645520 9790544
00:08:23 30891 8192000000 10294134688 10742452224 1370811472 14.334 100 21 42 2 1413253600 37176480 5265648
00:09:54 52807 8192000000 10309924448 10735579136 1362325504 14.258 99 48 95 3 1397582544 22773488 12483552
00:11:49 18707 8192000000 10291727344 10744217600 1386628256 14.476 100 20 40 0 1402434976 10824976 4981744
00:13:08 59788 8192000000 10308932400 10755174400 1391441104 14.519 100 45 90 0 1417087088 15085408 10560576
00:15:26 146540 8192000000 10305230768 10759950336 1368465936 14.313 98 11 23 0 1390525200 19758240 2301024
00:18:59 26984 8192000000 10306066464 10755928064 1394784944 14.549 100 14 28 0 1405451088 7611168 3054976
00:20:27 160589 8192000000 10322018400 10789785600 1372744768 14.352 98 13 27 1 1386967968 11337488 2885712
00:24:09 170578 8192000000 10311192768 10773639168 1359711312 14.235 97 7 13 1 1379036784 18345408 980064
00:28:06 104836 8192000000 10304951392 10755592192 1391246144 14.517 100 22 44 0 1406580736 10584160 4750432
00:31:00 63117 8192000000 10298826512 10763378688 1383042480 14.444 99 25 49 1 1401912864 12938912 5931472
00:33:27 33655 8192000000 10298984448 10754109440 1377828304 14.397 99 24 49 0 1395769616 12693312 5248000
00:35:37 34935 8192000000 10273619808 10724421632 1327191296 13.942 96 38 77 1 1345018544 8868368 8958880
00:37:23 33105 8192000000 10263542592 10720464896 1344553792 14.098 98 11 22 0 1354000240 7083568 2362880
00:39:02 25374 8192000000 10255598928 10713821184 1333066848 13.995 98 13 26 1 1349575568 13895792 2612928
00:40:29 82089 8192000000 10260398448 10726780928 1362710880 14.262 100 9 18 0 1374884544 10155792 2017872
00:42:52 62230 8192000000 10247751360 10699358208 1298351664 13.680 95 14 28 2 1309663536 8207264 3104608
00:44:55 53607 8192000000 10263457536 10717241344 1325882944 13.930 98 21 42 1 1340752544 10270944 4598656
00:46:49 43567 8192000000 10249924512 10694852608 1289972416 13.604 95 16 32 0 1301581504 8045792 3563296
00:48:33 66810 8192000000 10255473136 10712100864 1300865744 13.703 96 25 50 1 1317446912 11028544 5552624
00:50:58 54863 8192000000 10261431184 10712285184 1340983152 14.066 99 33 66 0 1362759680 14619232 7157296
00:53:22 86207 8192000000 10262889760 10707230720 1324310864 13.916 98 22 44 0 1345337104 16119888 4906352
00:55:49 37225 8192000000 10284793712 10718179328 1344740624 14.100 99 30 59 0 1362781664 11703936 6337104
00:57:34 100334 8192000000 10255698096 10711896064 1340798096 14.065 98 18 36 2 1352055408 6451792 4805520
01:00:19 43067 8192000000 10270401840 10721808384 1369551408 14.323 100 18 36 0 1380975120 7447632 3976080
01:02:03 62098 8192000000 10254140688 10710937600 1343489408 14.089 98 18 35 1 1358112272 10843904 3778960
01:04:14 46889 8192000000 10267862912 10727710720 1360946976 14.246 99 13 26 0 1377550464 13344064 3259424
01:06:02 57509 8192000000 10277576368 10732236800 1347907360 14.129 98 5 10 0 1361632240 12711152 1013728
01:08:04 86725 8192000000 10296909024 10756591616 1362975344 14.264 98 22 44 1 1379075712 10998128 5102240
01:10:46 71720 8192000000 10307082144 10773868544 1395702896 14.557 99 20 40 0 1409130096 9154080 4273120
01:13:02 89480 8192000000 10309518352 10763964416 1336973952 14.030 95 20 40 0 1362779776 21876784 3929040
01:15:33 59307 8192000000 10295464224 10766180352 1369969952 14.327 99 9 18 0 1393543056 21412448 2160656
01:17:39 105300 8192000000 10305290432 10756653056 1358815904 14.227 97 19 37 1 1372175520 8827920 4531696
01:20:26 29990 8192000000 10310198560 10741911552 1374342784 14.366 99 50 101 2 1393128256 6820080 11965392
01:22:05 151148 8192000000 10277750144 10736259072 1318006160 13.859 95 27 54 1 1331980432 8099488 5874784
01:25:56 39892 8192000000 10277861872 10734981120 1379622688 14.413 100 12 25 0 1388527360 6177088 2727584
01:27:37 65050 8192000000 10278199472 10722152448 1337127424 14.032 97 20 40 1 1353118448 10607216 5383808
01:29:56 91564 8192000000 10271057072 10721882112 1330964352 13.976 97 18 36 1 1349770688 14343520 4462816
01:32:27 65094 8192000000 10265406528 10715844608 1317153920 13.851 96 16 32 0 1329985696 9255840 3575936
01:34:33 50486 8192000000 10258729936 10710482944 1334016352 14.003 98 14 28 1 1351234016 13532368 3685296
01:36:23 44464 8192000000 10234014752 10687438848 1250835072 13.246 93 25 50 2 1271594864 14860288 5899504

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-06-2022 01:39:35 to 18-06-2022 01:39:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 84 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 18-06-2022 01:39:51 to 18-06-2022 01:39:53.

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          250147
MON$OLDEST_ACTIVE               251635
MON$OLDEST_SNAPSHOT             251635
MON$NEXT_TRANSACTION            251635
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                       2063264
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.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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 ---
:37: 
:38: mscs-test-161.budzdorov.ru    Sat Jun 18 01:38:23 2022
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Sat Jun 18 01:38:26 2022
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Sat Jun 18 01:38:26 2022
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:48: 
--- end of diff output ---

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

18.06.22 01:39:54. 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-18 01:39:56 No severe PSQL-related problems occured
18.06.22 01:39:57. End of report.