Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.03.2022 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 16984592 kB
MemAvailable 31575564 kB
Buffers 0 kB
Cached 14705892 kB
SwapCached 2188 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-03-29 19:38:08
DTS_END                         2022-03-29 19:38:08.1750
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/48724
TRN_ID                          229365
ATT_ID                          688
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: 8954.88 21430 1074586
customer order: creation 160.94 389 19313
customer order: refuse 13.88 363 1665
order to supplier: creation 43.08 1294 5169
order to supplier: removal 3.48 86884 418
invoice (draft): creation 43.18 3636 5182
invoice (draft): removal 52.34 735 6281
invoice accept: apply 39.20 16620 4704
invoice accept: cancel 46.65 710 5598
customer reserve: creation 3658.17 160 438980
customer reserve: removal 4614.28 51 553714
realization accept: apply 78.39 211 9407
realization accept: cancel 78.80 117 9456
payment from customer: creation 56.22 120 6746
payment from customer: removal 5.18 97 622
payment to supplier: creation 46.55 136 5586
payment to supplier: removal 2.96 96 355
service: total inventory turnovers 4.40 1082 528
service: total monetary turnovers 6.34 41 761
service: aggregate perf. data 0.81 17688 97
service: refresh index statistics 0.03 298167 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-03-2022 19:39:13 to 29-03-2022 19:39:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4731
TEST_TIME 2 4576
TEST_TIME 3 4110
TEST_TIME 4 3623
TEST_TIME 5 3695
TEST_TIME 6 4189
TEST_TIME 7 4336
TEST_TIME 8 4262
TEST_TIME 9 4406
TEST_TIME 10 4954
TEST_TIME 11 5246
TEST_TIME 12 5549
TEST_TIME 13 5652
TEST_TIME 14 6332
TEST_TIME 15 6726
TEST_TIME 16 6984
TEST_TIME 17 7501
TEST_TIME 18 7686
TEST_TIME 19 8542
TEST_TIME 20 9056
TEST_TIME 21 9233
TEST_TIME 22 9404
TEST_TIME 23 9894
TEST_TIME 24 9765
TEST_TIME 25 9763
TEST_TIME 26 9808
TEST_TIME 27 9620
TEST_TIME 28 9544
TEST_TIME 29 9521
TEST_TIME 30 9343
TEST_TIME 31 9289
TEST_TIME 32 9243
TEST_TIME 33 9160
TEST_TIME 34 9069
TEST_TIME 35 8970
TEST_TIME 36 8869
TEST_TIME 37 8992
TEST_TIME 38 8977
TEST_TIME 39 9095
TEST_TIME 40 9091
TEST_TIME 41 9020
TEST_TIME 42 9030
TEST_TIME 43 8891
TEST_TIME 44 8829
TEST_TIME 45 8781
TEST_TIME 46 8654
TEST_TIME 47 8678
TEST_TIME 48 8805
TEST_TIME 49 8727
TEST_TIME 50 8791
TEST_TIME 51 8753
TEST_TIME 52 8937
TEST_TIME 53 9006
TEST_TIME 54 9033
TEST_TIME 55 9006
TEST_TIME 56 8985
TEST_TIME 57 9097
TEST_TIME 58 9180
TEST_TIME 59 9152
TEST_TIME 60 9179
TEST_TIME 61 9200
TEST_TIME 62 9082
TEST_TIME 63 8994
TEST_TIME 64 8972
TEST_TIME 65 8975
TEST_TIME 66 8919
TEST_TIME 67 8870
TEST_TIME 68 8879
TEST_TIME 69 8786
TEST_TIME 70 8870
TEST_TIME 71 8847
TEST_TIME 72 8845
TEST_TIME 73 8913
TEST_TIME 74 8873
TEST_TIME 75 8929
TEST_TIME 76 8976
TEST_TIME 77 9044
TEST_TIME 78 9019
TEST_TIME 79 9060
TEST_TIME 80 9042
TEST_TIME 81 9100
TEST_TIME 82 9120
TEST_TIME 83 9103
TEST_TIME 84 9014
TEST_TIME 85 9028
TEST_TIME 86 8967
TEST_TIME 87 8949
TEST_TIME 88 8925
TEST_TIME 89 8871
TEST_TIME 90 8870
TEST_TIME 91 8841
TEST_TIME 92 8882
TEST_TIME 93 8869
TEST_TIME 94 8887
TEST_TIME 95 8965
TEST_TIME 96 8965
TEST_TIME 97 8976
TEST_TIME 98 8959
TEST_TIME 99 9032
TEST_TIME 100 9036
TEST_TIME 101 9058
TEST_TIME 102 9087
TEST_TIME 103 9165
TEST_TIME 104 9185
TEST_TIME 105 9160
TEST_TIME 106 9199
TEST_TIME 107 9129
TEST_TIME 108 9085
TEST_TIME 109 9100
TEST_TIME 110 9086
TEST_TIME 111 9061
TEST_TIME 112 9019
TEST_TIME 113 8993
TEST_TIME 114 8961
TEST_TIME 115 8994
TEST_TIME 116 9005
TEST_TIME 117 9000
TEST_TIME 118 8979
TEST_TIME 119 9033
TEST_TIME 120 8961

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-03-2022 19:39:17 to 29-03-2022 19:39:20.

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 1481881 1481881 0 0.00 0 17758 6 0 0
doc_list_biud 1481879 1481879 0 0.00 0 32992 25 0 0
sp_add_invoice_to_stock 4829 4704 125 2.58 53 162193 16620 0 125
sp_cancel_adding_invoice 5598 5598 0 0.00 5 64728 710 0 0
sp_cancel_client_order 1665 1665 0 0.00 4 9927 364 0 0
sp_cancel_customer_reserve 553714 553714 0 0.00 1 25267 51 0 0
sp_cancel_pay_from_customer 622 622 0 0.00 3 6217 98 0 0
sp_cancel_pay_to_supplier 355 355 0 0.00 3 5195 96 0 0
sp_cancel_supplier_invoice 6292 6281 11 0.17 1 20340 735 0 11
sp_cancel_supplier_order 418 418 0 0.00 7 850514 86884 0 0
sp_cancel_write_off 9456 9456 0 0.00 1 12298 117 0 0
sp_client_order 19313 19313 0 0.00 3 43428 390 0 0
sp_customer_reserve 438980 438980 0 0.00 0 47334 161 0 0
sp_fill_shopping_cart 29647 29647 0 0.00 1 12046 41 0 0
sp_fill_shopping_cart_clo_res 438972 438972 0 0.00 0 15516 15 0 0
sp_get_clo_for_invoice 4696 4696 0 0.00 0 37599 355 0 0
sp_kill_cost_storno 16031 16031 0 0.00 0 12219 57 0 0
sp_kill_qstorno_ret_qs2qd 1123583 1123583 0 0.00 0 21562 28 0 0
sp_kill_qty_storno 591235 591235 0 0.00 0 32992 62 0 0
sp_lock_selected_doc 19490 19490 0 0.00 0 5755 6 0 0
sp_make_cost_storno 26435 26435 0 0.00 0 16916 60 0 0
sp_multiply_rows_for_pdistr 26435 26435 0 0.00 0 6904 29 0 0
sp_multiply_rows_for_qdistr 19311 19311 0 0.00 0 19104 188 0 0
sp_pay_from_customer 6746 6746 0 0.00 4 36527 120 0 0
sp_pay_to_supplier 5586 5586 0 0.00 4 22584 137 0 0
sp_payment_common 12332 12332 0 0.00 1 36518 36 0 0
sp_qd_handle_on_cancel_clo 1665 1665 0 0.00 0 9918 338 0 0
sp_qd_handle_on_invoice_upd_sts 10294 10294 0 0.00 0 32992 332 0 0
sp_qd_handle_on_reserve_upd_sts 9407 9407 0 0.00 0 18781 68 0 0
sp_reserve_write_off 9407 9407 0 0.00 6 18910 211 0 0
sp_supplier_invoice 5182 5182 0 0.00 6 86277 3636 0 0
sp_supplier_order 5169 5169 0 0.00 4 48968 1295 0 0
srv_aggregate_perf_data 97 97 0 0.00 2635 112044 17689 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 2118 141604 55206 0 0
srv_find_qd_qs_mism 1029912 1029912 0 0.00 0 17757 5 0 0
srv_make_invnt_saldo 528 528 0 0.00 39 81230 1082 0 0
srv_make_money_saldo 761 761 0 0.00 0 16442 42 0 0
srv_recalc_idx_stat 4 4 0 0.00 230767 346293 298167 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4704 4704 0 0.00 0 19485 165 0 0
t$perf-norm:sp_cancel_adding_invoice 355 355 0 0.00 0 5264 85 0 0
t$perf-norm:sp_cancel_client_order 1665 1665 0 0.00 0 3604 13 0 0
t$perf-norm:sp_cancel_customer_reserve 659 659 0 0.00 0 1220 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 622 622 0 0.00 0 4470 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 355 355 0 0.00 0 202 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 361 361 0 0.00 0 4319 26 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 21310 1386 0 0
t$perf-norm:sp_cancel_write_off 953 953 0 0.00 0 3327 15 0 0
t$perf-norm:sp_client_order 19313 19313 0 0.00 0 9934 18 0 0
t$perf-norm:sp_customer_reserve 882 882 0 0.00 0 1033 3 0 0
t$perf-norm:sp_pay_from_customer 6746 6746 0 0.00 0 5287 11 0 0
t$perf-norm:sp_pay_to_supplier 5587 5587 0 0.00 0 9308 11 0 0
t$perf-norm:sp_reserve_write_off 9407 9407 0 0.00 0 10173 12 0 0
t$perf-norm:sp_supplier_invoice 5182 5182 0 0.00 0 8777 16 0 0
t$perf-norm:sp_supplier_order 5169 5169 0 0.00 0 10179 18 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 46 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 103 2 0 0
t$perf-norm:srv_make_invnt_saldo 528 528 0 0.00 0 10276 41 0 0
t$perf-norm:srv_make_money_saldo 761 761 0 0.00 0 4009 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 9640 9390 250 2.59 0 6629 22 0 250
v_all_customers 35254 35254 0 0.00 0 19 0 0 0
v_all_suppliers 28704 28704 0 0.00 0 24 1 0 0
v_all_wares 38614 38614 0 0.00 0 165 1 0 0
v_cancel_adding_invoice 710 710 0 0.00 0 120 5 0 0
v_cancel_client_order 3330 3330 0 0.00 0 1889 5 0 0
v_cancel_customer_prepayment 1244 1244 0 0.00 0 4754 12 0 0
v_cancel_customer_reserve 1318 1318 0 0.00 0 1866 5 0 0
v_cancel_payment_to_supplier 710 710 0 0.00 0 5183 16 0 0
v_cancel_supplier_invoice 744 722 22 2.95 0 1619 18 0 22
v_cancel_supplier_order 836 836 0 0.00 0 2062 7 0 0
v_cancel_write_off 1906 1906 0 0.00 0 2696 21 0 0
v_max_id_clo_ord 5166 5166 0 0.00 0 4782 2 0 0
v_max_id_clo_res 882 882 0 0.00 0 7862 13 0 0
v_max_id_ord_sup 5172 5172 0 0.00 0 7581 7 0 0
v_max_non_paid_invoice 1572 1572 0 0.00 0 97 2 0 0
v_max_non_paid_realizn 3550 3550 0 0.00 0 15563 9 0 0
v_min_id_clo_ord 5166 5166 0 0.00 0 10950 9 0 0
v_min_id_clo_res 882 882 0 0.00 0 1040 4 0 0
v_min_id_ord_sup 5172 5172 0 0.00 0 4491 5 0 0
v_min_non_paid_invoice 5586 5586 0 0.00 0 18050 26 0 0
v_min_non_paid_realizn 6746 6746 0 0.00 0 36513 29 0 0
v_our_firm 9752 9752 0 0.00 0 47 1 0 0
v_reserve_write_off 18814 18814 0 0.00 0 5185 7 0 0
x_lock_depdocs_on_canc_invoice 11518 11518 0 0.00 0 21361 103 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 24660 369 0 0
x_make_qstorno_1000_1200 5169 5169 0 0.00 1 48914 1210 0 0
x_make_qstorno_1200_2000 5182 5182 0 0.00 3 85848 3490 0 0
x_make_qstorno_2100_3300 438980 438980 0 0.00 0 47331 144 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-03-2022 19:39:21 to 29-03-2022 19:39:25.

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:08:11 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:12 20470 8192000000 9897418672 10208489472 985071376 10.734 100 83 166 0 1012649664 6042320 21535968
17:10:43 21324 8192000000 9952294304 10273320960 1029267568 11.161 100 91 182 0 1061121120 8555616 23297936
17:12:08 15735 8192000000 9970913056 10304466944 1057360528 11.431 100 83 166 0 1087909600 10049776 20499296
17:13:41 14593 8192000000 10038168544 10383736832 1120691872 12.034 100 84 168 0 1151474864 10829584 19953408
17:15:04 6894 8192000000 10076493872 10437328896 1171347440 12.509 100 79 157 1 1201210864 12337808 17525616
17:16:15 4081 8192000000 10133045392 10504056832 1218140304 12.944 100 72 140 4 1246732912 13306272 15286336
17:17:20 2561 8192000000 10159923024 10534817792 1248274368 13.222 100 87 175 0 1282761248 15804128 18682752
17:18:25 1419 8192000000 10201566032 10587258880 1288332720 13.589 100 91 182 2 1321675088 13505024 19837344
17:19:27 2513 8192000000 10226001216 10614013952 1305118816 13.742 100 96 191 3 1339504096 12669232 21716048
17:20:30 3576 8192000000 10227375776 10618425344 1310365920 13.789 100 86 167 1 1340600384 9655680 20578784
17:21:34 4288 8192000000 10201501120 10619047936 1315860016 13.839 100 25 50 0 1328248448 6974048 5414384
17:22:42 5394 8192000000 10207971968 10626183168 1321639872 13.892 100 24 48 1 1333729008 6900512 5188624
17:23:49 8949 8192000000 10222210256 10638045184 1329325424 13.961 100 25 50 0 1341593392 6682016 5585952
17:25:01 27487 8192000000 10236868368 10645151744 1331483200 13.981 100 55 107 1 1349925168 5968400 12473568
17:26:29 4177 8192000000 10238369056 10649960448 1333970944 14.003 100 38 76 0 1349773120 6598336 9203840
17:27:34 11517 8192000000 10206757360 10626228224 1320689600 13.883 99 22 44 1 1330939616 5138528 5111488
17:28:48 11064 8192000000 10187012016 10603421696 1282551040 13.536 98 8 16 1 1289672192 5211872 1909280
17:30:04 37286 8192000000 10204514960 10592874496 1281993792 13.531 99 59 119 2 1302271776 5863856 14414128
17:31:42 5515 8192000000 10144812080 10552381440 1256993536 13.302 100 24 48 0 1268401200 6136944 5270720
17:32:48 9628 8192000000 10149905760 10545831936 1233289936 13.084 98 25 50 0 1244567328 5325808 5951584
17:34:00 7649 8192000000 10159479776 10566946816 1272870496 13.448 100 30 60 0 1285685936 5816000 6999440
17:35:08 50233 8192000000 10181812384 10597113856 1292782464 13.630 100 44 88 1 1309598320 6255728 10560128
17:37:03 6533 8192000000 10195660400 10608734208 1303140192 13.724 100 30 60 0 1316558800 6416480 7002128
17:38:11 5830 8192000000 10223535408 10631368704 1322285360 13.897 100 44 88 1 1338297424 5683648 10328416
17:39:34 30631 8192000000 10258434592 10670059520 1348900240 14.138 100 41 80 1 1369707440 11008800 9798400
17:41:14 84236 8192000000 10275471728 10698842112 1368211552 14.311 100 36 72 3 1389200880 12324096 8665232
17:43:51 42228 8192000000 10290329696 10722852864 1382402128 14.438 100 24 48 1 1411917984 22416896 7098960
17:45:38 12808 8192000000 10322733984 10742136832 1400809072 14.602 100 56 112 0 1435598576 21050160 13739344
17:46:59 23147 8192000000 10341292384 10762829824 1410854336 14.692 100 56 112 1 1449887152 25750800 13282016
17:48:29 4539 8192000000 10340453328 10766274560 1414266512 14.722 100 55 110 0 1455093248 26770464 14056272
17:49:40 10764 8192000000 10363865824 10782187520 1423872704 14.807 100 59 119 1 1469680032 31418320 14389008
17:50:58 4051 8192000000 10356464880 10774675456 1406750288 14.655 99 72 142 1 1458374368 33844688 17779392
17:52:11 2116 8192000000 10366874512 10773569536 1409786880 14.682 100 85 168 1 1466822608 35889616 21146112
17:53:18 4149 8192000000 10391657360 10805682176 1428635200 14.849 100 88 177 0 1490445792 39452800 22357792
17:54:30 3883 8192000000 10369234192 10792939520 1421587600 14.787 100 69 138 0 1475086672 35948544 17550528
17:55:37 25174 8192000000 10328528208 10768875520 1381992720 14.434 98 32 62 3 1427711344 36755136 8963488
17:57:12 19780 8192000000 10293810848 10725224448 1357694416 14.217 99 31 60 0 1402784576 34890912 10199248
17:58:44 28423 8192000000 10247862464 10679648256 1287081344 13.578 95 18 36 1 1313267344 21178032 5007968
18:00:18 24404 8192000000 10268527392 10707472384 1337035232 14.031 98 17 34 0 1362675040 21197536 4442272
18:01:42 87321 8192000000 10227980544 10665250816 1279073392 13.505 96 23 46 1 1304981616 20115296 5792928
18:04:13 19104 8192000000 10256426272 10681208832 1329623648 13.964 99 48 95 3 1358440112 17682320 11134144
18:05:36 108518 8192000000 10239344208 10684325888 1316077728 13.841 98 33 68 2 1339031040 14650368 8302944
18:08:49 73777 8192000000 10259552064 10692366336 1339643936 14.054 99 17 34 0 1349632064 6590928 3397200
18:11:04 17583 8192000000 10263781584 10702303232 1354812592 14.191 99 42 85 1 1371417152 6521872 10082688
18:12:45 38378 8192000000 10277787968 10720301056 1372481760 14.349 100 16 32 0 1388489408 11967072 4040576
18:14:26 52677 8192000000 10288185408 10733039616 1383900784 14.451 100 32 62 1 1405613504 14028368 7684352
18:16:19 24036 8192000000 10272980944 10744414208 1369795344 14.325 100 7 14 0 1392071328 20394816 1881168
18:17:46 29626 8192000000 10281363648 10719211520 1335357088 14.016 97 19 38 1 1351443680 11586816 4499776
18:19:17 67388 8192000000 10263018000 10717675520 1361761536 14.253 100 20 38 2 1378728240 12233136 4733568
18:21:26 44127 8192000000 10273631024 10722787328 1367352944 14.303 100 19 38 1 1385942976 14766656 3823376
18:23:12 69289 8192000000 10286846352 10744623104 1387395984 14.483 100 19 38 1 1408518416 16158736 4963696
18:26:12 72386 8192000000 10266334288 10724814848 1348602640 14.135 97 7 13 0 1360381280 10388112 1390528
18:28:33 64526 8192000000 10319141040 10768072704 1404367456 14.634 100 16 32 0 1425715360 17546096 3801808
18:30:39 106013 8192000000 10302308512 10762346496 1396811824 14.567 100 6 12 0 1419623040 21523968 1287248
18:33:25 14067 8192000000 10310088640 10758324224 1397853632 14.576 100 45 91 0 1416954224 9019856 10080736
18:34:40 35344 8192000000 10319975680 10758381568 1390456032 14.510 99 34 68 1 1412445312 14468240 7521040
18:36:21 57992 8192000000 10290191376 10749120512 1375204000 14.374 99 17 34 1 1389327552 10653264 3470288
18:38:19 54762 8192000000 10295855936 10747990016 1366595264 14.297 98 21 42 2 1389077920 18304496 4178160
18:40:14 107255 8192000000 10302367488 10755256320 1382446960 14.438 99 22 44 0 1400045216 12320032 5278224
18:43:03 71187 8192000000 10280680528 10751320064 1354735440 14.190 97 7 14 0 1366547328 10363376 1448512
18:45:15 48440 8192000000 10278027312 10736197632 1363909856 14.272 99 21 43 1 1383100384 14223552 4966976
18:47:05 45595 8192000000 10252446272 10703867904 1348333792 14.132 98 20 38 1 1361065216 8707168 4024256
18:48:58 73704 8192000000 10261855504 10712055808 1264813840 13.374 92 15 30 0 1279148480 11390064 2944576
18:51:19 50447 8192000000 10256721648 10694369280 1303940784 13.731 96 18 36 1 1317913360 10026912 3945664
18:53:12 58811 8192000000 10270716688 10722869248 1354195536 14.185 100 13 26 1 1368856512 11639696 3021280
18:55:15 96062 8192000000 10274672128 10715267072 1303312016 13.725 95 17 33 0 1319844688 12819472 3713200
18:57:56 47035 8192000000 10240711808 10676867072 1257778192 13.310 93 16 31 0 1273005472 11820768 3406512
18:59:50 129122 8192000000 10260589536 10694971392 1310355472 13.789 97 25 50 1 1327079840 11048480 5675888
19:03:00 80070 8192000000 10236321008 10674827264 1330605168 13.973 99 20 40 0 1341937600 6797568 4534864
19:05:21 56681 8192000000 10243163856 10691854336 1337620912 14.036 99 10 20 1 1354272656 14155072 2496672
19:07:24 57066 8192000000 10268204304 10721349632 1368858512 14.317 100 24 48 0 1387736000 12923936 5953552
19:09:23 50163 8192000000 10275906336 10738335744 1376776832 14.388 100 17 32 0 1392358528 11217072 4364624
19:11:24 73068 8192000000 10304839232 10751078400 1352049616 14.166 97 18 36 0 1369163184 13186096 3927472
19:13:37 58568 8192000000 10292625248 10746667008 1377154752 14.391 99 10 20 0 1388187248 8666480 2366016
19:15:37 68122 8192000000 10317589664 10757189632 1369915792 14.326 98 19 38 1 1387967536 12891536 5160208
19:17:50 141604 8192000000 10303740672 10764894208 1346053744 14.112 96 21 42 0 1362176784 11649792 4473248
19:21:13 131336 8192000000 10312001520 10765025280 1347262080 14.123 96 8 16 0 1367030976 18045616 1723280
19:24:26 125338 8192000000 10312654336 10764623872 1389029872 14.497 99 18 36 1 1402075280 9123184 3922224
19:27:37 57299 8192000000 10305200608 10779951104 1391316768 14.518 99 12 23 0 1409101104 15469792 2314544
19:29:44 62426 8192000000 10308462576 10771808256 1393637216 14.538 99 9 18 0 1411228608 15614304 1977088
19:31:47 107186 8192000000 10322540112 10786426880 1404557264 14.636 99 12 24 0 1425848416 18797664 2493488
19:34:35 103713 8192000000 10316576464 10774814720 1380663344 14.422 98 9 18 0 1399743584 17000192 2080048
19:37:23 48896 8192000000 9588467648 9927442432 713213744 8.008 51 10 18 0 726980912 11826832 1940336

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 29-03-2022 19:39:27 to 29-03-2022 19:39:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-03-2022 19:39:42 to 29-03-2022 19:39:44.

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          228949
MON$OLDEST_ACTIVE               229577
MON$OLDEST_SNAPSHOT             229577
MON$NEXT_TRANSACTION            229577
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                       1998912
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.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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 ---
:683: 
:684: mscs-test-161.budzdorov.ru    Tue Mar 29 19:38:16 2022
:685:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:686: 
:687: 
:688: mscs-test-161.budzdorov.ru    Tue Mar 29 19:38:16 2022
:689:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:690: 
:691: 
:692: mscs-test-161.budzdorov.ru    Tue Mar 29 19:38:16 2022
:693:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:694: 
--- end of diff output ---

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

29.03.22 19:39:45. 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-03-29 19:39:47 No severe PSQL-related problems occured
29.03.22 19:39:49. End of report.