Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.12.2021 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 16594564 kB
MemAvailable 31362176 kB
Buffers 0 kB
Cached 15049140 kB
SwapCached 444 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.9
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-12-22 01:37:56
DTS_END                         2021-12-22 01:37:56.1500
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/50296
TRN_ID                          242439
ATT_ID                          682
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: 9178.37 24961 1101404
customer order: creation 173.61 336 20833
customer order: refuse 15.34 312 1841
order to supplier: creation 47.08 1288 5649
order to supplier: removal 3.32 101957 398
invoice (draft): creation 47.29 3518 5675
invoice (draft): removal 51.78 820 6213
invoice accept: apply 42.69 17523 5123
invoice accept: cancel 46.60 853 5592
customer reserve: creation 3912.14 174 469457
customer reserve: removal 4536.45 58 544374
realization accept: apply 83.98 179 10077
realization accept: cancel 85.67 107 10280
payment from customer: creation 59.88 97 7185
payment from customer: removal 5.68 79 682
payment to supplier: creation 50.72 119 6086
payment to supplier: removal 3.66 101 439
service: total inventory turnovers 4.71 1352 565
service: total monetary turnovers 7.03 25 843
service: aggregate perf. data 0.73 26497 88
service: refresh index statistics 0.03 343833 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-12-2021 01:38:46 to 22-12-2021 01:38:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3147
TEST_TIME 2 3641
TEST_TIME 3 4848
TEST_TIME 4 4656
TEST_TIME 5 5449
TEST_TIME 6 6032
TEST_TIME 7 5916
TEST_TIME 8 6776
TEST_TIME 9 6687
TEST_TIME 10 6731
TEST_TIME 11 6779
TEST_TIME 12 6854
TEST_TIME 13 6944
TEST_TIME 14 7167
TEST_TIME 15 7177
TEST_TIME 16 7577
TEST_TIME 17 8134
TEST_TIME 18 8368
TEST_TIME 19 8865
TEST_TIME 20 8714
TEST_TIME 21 8688
TEST_TIME 22 8890
TEST_TIME 23 8996
TEST_TIME 24 8962
TEST_TIME 25 9340
TEST_TIME 26 9108
TEST_TIME 27 9467
TEST_TIME 28 9793
TEST_TIME 29 9794
TEST_TIME 30 10076
TEST_TIME 31 10309
TEST_TIME 32 10119
TEST_TIME 33 10814
TEST_TIME 34 10756
TEST_TIME 35 10557
TEST_TIME 36 10646
TEST_TIME 37 10542
TEST_TIME 38 10352
TEST_TIME 39 10165
TEST_TIME 40 9991
TEST_TIME 41 9929
TEST_TIME 42 9798
TEST_TIME 43 9836
TEST_TIME 44 9735
TEST_TIME 45 9636
TEST_TIME 46 9470
TEST_TIME 47 9444
TEST_TIME 48 9469
TEST_TIME 49 9447
TEST_TIME 50 9509
TEST_TIME 51 9564
TEST_TIME 52 9398
TEST_TIME 53 9324
TEST_TIME 54 9256
TEST_TIME 55 9170
TEST_TIME 56 9070
TEST_TIME 57 9069
TEST_TIME 58 9017
TEST_TIME 59 8954
TEST_TIME 60 8946
TEST_TIME 61 8968
TEST_TIME 62 9000
TEST_TIME 63 9009
TEST_TIME 64 9055
TEST_TIME 65 9127
TEST_TIME 66 9162
TEST_TIME 67 9161
TEST_TIME 68 9261
TEST_TIME 69 9243
TEST_TIME 70 9209
TEST_TIME 71 9290
TEST_TIME 72 9313
TEST_TIME 73 9311
TEST_TIME 74 9352
TEST_TIME 75 9267
TEST_TIME 76 9185
TEST_TIME 77 9182
TEST_TIME 78 9211
TEST_TIME 79 9265
TEST_TIME 80 9220
TEST_TIME 81 9170
TEST_TIME 82 9133
TEST_TIME 83 9089
TEST_TIME 84 9028
TEST_TIME 85 9009
TEST_TIME 86 9011
TEST_TIME 87 9025
TEST_TIME 88 9056
TEST_TIME 89 9051
TEST_TIME 90 9054
TEST_TIME 91 9096
TEST_TIME 92 9075
TEST_TIME 93 9119
TEST_TIME 94 9164
TEST_TIME 95 9198
TEST_TIME 96 9193
TEST_TIME 97 9179
TEST_TIME 98 9297
TEST_TIME 99 9312
TEST_TIME 100 9288
TEST_TIME 101 9318
TEST_TIME 102 9274
TEST_TIME 103 9214
TEST_TIME 104 9192
TEST_TIME 105 9180
TEST_TIME 106 9134
TEST_TIME 107 9136
TEST_TIME 108 9117
TEST_TIME 109 9084
TEST_TIME 110 9037
TEST_TIME 111 9043
TEST_TIME 112 9066
TEST_TIME 113 9073
TEST_TIME 114 9060
TEST_TIME 115 9083
TEST_TIME 116 9117
TEST_TIME 117 9157
TEST_TIME 118 9125
TEST_TIME 119 9112
TEST_TIME 120 9183

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-12-2021 01:38:49 to 22-12-2021 01:38:53.

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 1534793 1534793 0 0.00 0 12885 6 0 0
doc_list_biud 1534793 1534793 0 0.00 0 71862 27 0 0
sp_add_invoice_to_stock 5281 5123 158 2.99 34 199378 17524 0 158
sp_cancel_adding_invoice 5592 5592 0 0.00 5 58862 854 0 0
sp_cancel_client_order 1841 1841 0 0.00 4 6888 312 0 0
sp_cancel_customer_reserve 544374 544374 0 0.00 1 52144 58 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 2 7498 79 0 0
sp_cancel_pay_to_supplier 439 439 0 0.00 3 6711 101 0 0
sp_cancel_supplier_invoice 6226 6213 13 0.20 2 71871 821 0 13
sp_cancel_supplier_order 398 398 0 0.00 5 891900 101958 0 0
sp_cancel_write_off 10280 10280 0 0.00 1 13013 108 0 0
sp_client_order 20833 20833 0 0.00 3 35509 337 0 0
sp_customer_reserve 469457 469457 0 0.00 0 41090 174 0 0
sp_fill_shopping_cart 32144 32144 0 0.00 1 11129 41 0 0
sp_fill_shopping_cart_clo_res 469452 469452 0 0.00 0 11168 14 0 0
sp_get_clo_for_invoice 5116 5116 0 0.00 0 28021 361 0 0
sp_kill_cost_storno 16993 16993 0 0.00 0 7964 56 0 0
sp_kill_qstorno_ret_qs2qd 1105639 1105639 0 0.00 0 71856 32 0 0
sp_kill_qty_storno 583891 583891 0 0.00 0 71859 69 0 0
sp_lock_selected_doc 21136 21136 0 0.00 0 7625 6 0 0
sp_make_cost_storno 28464 28464 0 0.00 0 11019 48 0 0
sp_multiply_rows_for_pdistr 28464 28464 0 0.00 0 9044 29 0 0
sp_multiply_rows_for_qdistr 20833 20833 0 0.00 0 35502 151 0 0
sp_pay_from_customer 7185 7185 0 0.00 4 8223 98 0 0
sp_pay_to_supplier 6086 6086 0 0.00 5 11055 120 0 0
sp_payment_common 13271 13271 0 0.00 1 9013 23 0 0
sp_qd_handle_on_cancel_clo 1841 1841 0 0.00 0 6879 292 0 0
sp_qd_handle_on_invoice_upd_sts 10708 10708 0 0.00 0 15483 337 0 0
sp_qd_handle_on_reserve_upd_sts 10077 10077 0 0.00 0 11798 62 0 0
sp_reserve_write_off 10077 10077 0 0.00 6 13073 180 0 0
sp_supplier_invoice 5675 5675 0 0.00 5 75696 3518 0 0
sp_supplier_order 5649 5649 0 0.00 5 53174 1288 0 0
srv_aggregate_perf_data 88 88 0 0.00 3163 469108 26498 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1111 183846 43704 0 0
srv_find_qd_qs_mism 1052154 1052154 0 0.00 0 12885 5 0 0
srv_make_invnt_saldo 565 565 0 0.00 23 84621 1353 0 0
srv_make_money_saldo 843 843 0 0.00 0 1392 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 214267 580515 343833 0 0
t$perf-abend:sp_add_invoice_to_stock 158 158 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5123 5123 0 0.00 0 10463 191 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 4281 99 0 0
t$perf-norm:sp_cancel_client_order 1841 1841 0 0.00 0 3943 20 0 0
t$perf-norm:sp_cancel_customer_reserve 703 703 0 0.00 0 567 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 1539 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 439 439 0 0.00 0 1683 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 3389 24 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 21143 1730 0 0
t$perf-norm:sp_cancel_write_off 1027 1027 0 0.00 0 4364 11 0 0
t$perf-norm:sp_client_order 20833 20833 0 0.00 0 8609 18 0 0
t$perf-norm:sp_customer_reserve 1016 1016 0 0.00 0 7387 13 0 0
t$perf-norm:sp_pay_from_customer 7185 7185 0 0.00 0 14532 13 0 0
t$perf-norm:sp_pay_to_supplier 6086 6086 0 0.00 0 7964 11 0 0
t$perf-norm:sp_reserve_write_off 10077 10077 0 0.00 0 8208 12 0 0
t$perf-norm:sp_supplier_invoice 5675 5675 0 0.00 0 9568 20 0 0
t$perf-norm:sp_supplier_order 5649 5649 0 0.00 0 5355 15 0 0
t$perf-norm:srv_aggregate_perf_data 88 88 0 0.00 0 344 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 95 3 0 0
t$perf-norm:srv_make_invnt_saldo 565 565 0 0.00 0 1093 22 0 0
t$perf-norm:srv_make_money_saldo 843 843 0 0.00 0 1596 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10546 10230 316 2.99 0 8232 20 0 316
v_all_customers 38042 38042 0 0.00 0 20 1 0 0
v_all_suppliers 31434 31434 0 0.00 0 19 1 0 0
v_all_wares 41664 41664 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 2819 9 0 0
v_cancel_client_order 3682 3682 0 0.00 0 2782 4 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 2602 8 0 0
v_cancel_customer_reserve 1406 1406 0 0.00 0 3508 9 0 0
v_cancel_payment_to_supplier 878 878 0 0.00 0 3083 11 0 0
v_cancel_supplier_invoice 874 848 26 2.97 0 3687 22 0 26
v_cancel_supplier_order 796 796 0 0.00 0 3587 10 0 0
v_cancel_write_off 2054 2054 0 0.00 0 4421 18 0 0
v_max_id_clo_ord 5649 5649 0 0.00 0 1348 2 0 0
v_max_id_clo_res 1016 1016 0 0.00 0 2272 8 0 0
v_max_id_ord_sup 5663 5663 0 0.00 0 3326 4 0 0
v_max_non_paid_invoice 1681 1681 0 0.00 0 1042 3 0 0
v_max_non_paid_realizn 3709 3709 0 0.00 0 494 3 0 0
v_min_id_clo_ord 5649 5649 0 0.00 0 3139 6 0 0
v_min_id_clo_res 1016 1016 0 0.00 0 1950 4 0 0
v_min_id_ord_sup 5663 5663 0 0.00 0 3583 5 0 0
v_min_non_paid_invoice 6086 6086 0 0.00 0 9009 18 0 0
v_min_non_paid_realizn 7185 7185 0 0.00 0 8199 16 0 0
v_our_firm 10574 10574 0 0.00 0 13 1 0 0
v_reserve_write_off 20154 20154 0 0.00 0 3467 6 0 0
x_lock_depdocs_on_canc_invoice 11381 11381 0 0.00 0 27140 124 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 13975 385 0 0
x_make_qstorno_1000_1200 5649 5649 0 0.00 2 53108 1203 0 0
x_make_qstorno_1200_2000 5675 5675 0 0.00 2 75688 3376 0 0
x_make_qstorno_2100_3300 469457 469457 0 0.00 0 41089 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-12-2021 01:38:54 to 22-12-2021 01:38:58.

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:07:59 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:00 14741 8192000000 9887645504 10197893120 978174976 10.666 100 88 176 0 1007321328 5463776 23682576
23:10:21 11256 8192000000 9932346176 10250027008 1015404624 11.028 100 94 188 0 1047608256 7560512 24643120
23:11:49 16345 8192000000 9979612480 10311102464 1060503744 11.461 100 86 173 1 1092175056 9840128 21831184
23:13:08 9351 8192000000 10020731024 10366017536 1109898000 11.931 100 79 159 1 1140062736 11262336 18902400
23:14:28 9503 8192000000 10068520480 10425741312 1153802176 12.345 100 77 154 1 1184155088 12575920 17776992
23:15:40 7439 8192000000 10118117248 10485882880 1204696704 12.820 100 78 153 2 1233917232 12679056 16541472
23:16:50 4502 8192000000 10151905712 10523709440 1238164688 13.129 100 86 170 0 1270999040 14711104 18123248
23:18:03 1562 8192000000 10183050736 10559873024 1268594896 13.409 100 97 195 0 1304042016 13821216 21625904
23:19:07 1740 8192000000 10208027728 10591092736 1290930384 13.613 100 97 193 2 1326168512 13381536 21856592
23:20:10 1659 8192000000 10220894528 10608214016 1304063376 13.732 100 96 190 2 1337592944 11713680 21815888
23:21:13 7990 8192000000 10193040304 10602971136 1304032448 13.732 100 25 49 0 1316912544 7454000 5426096
23:22:23 35195 8192000000 10207820416 10623430656 1312839296 13.812 100 35 69 4 1326968032 5410704 8718032
23:24:00 4618 8192000000 10218523600 10635894784 1326421776 13.935 100 36 70 3 1341521632 6928688 8171168
23:25:05 5775 8192000000 10220541632 10639384576 1330337104 13.970 100 16 32 1 1340549456 5950736 4261616
23:26:11 12671 8192000000 10205116384 10628460544 1312860160 13.812 99 19 38 1 1322665168 5784592 4020416
23:27:32 22098 8192000000 10211873104 10633011200 1312966688 13.813 99 15 31 0 1321942000 5508496 3466816
23:28:56 8361 8192000000 10152218704 10559791104 1217855744 12.942 94 25 50 0 1229848992 5234048 6759200
23:30:08 10078 8192000000 10160562112 10571153408 1234634176 13.097 96 21 42 2 1244960096 6085760 4240160
23:31:21 44911 8192000000 10128524752 10524721152 1208376592 12.854 96 46 93 1 1229327024 9214416 11736016
23:33:15 8430 8192000000 10142955472 10545811456 1237594928 13.124 98 16 31 0 1246501776 5250640 3656208
23:34:25 6973 8192000000 10178557232 10587807744 1286154640 13.569 100 30 60 5 1298729120 5621136 6953344
23:35:34 10235 8192000000 10192016560 10608451584 1298317152 13.680 100 17 34 0 1312224416 10386272 3520992
23:36:48 40010 8192000000 10189411344 10610999296 1305389168 13.744 100 14 28 0 1314081504 5584432 3107904
23:38:38 18217 8192000000 10220051344 10631503872 1320094576 13.878 100 39 77 1 1339731952 10390128 9247248
23:39:59 33717 8192000000 10251784320 10671669248 1350491728 14.152 100 46 92 0 1372389904 10609392 11288784
23:41:39 22222 8192000000 10280909904 10695757824 1366237712 14.293 100 45 90 2 1387246544 9994064 11014768
23:43:09 9758 8192000000 10292235792 10714759168 1379063776 14.408 100 59 118 0 1406467120 13100816 14302528
23:44:28 5192 8192000000 10303291840 10722709504 1383450912 14.447 100 66 132 0 1416077424 17841888 14784624
23:45:36 1111 8192000000 10321394720 10743791616 1396136544 14.561 100 64 128 3 1430791440 19656960 14997936
23:46:41 12894 8192000000 10316783200 10741858304 1388475760 14.492 99 65 131 2 1423129728 19825664 14828304
23:47:58 8049 8192000000 10332383088 10754244608 1403551040 14.627 100 64 127 1 1436743744 18376512 14816192
23:49:12 3811 8192000000 10318033728 10733625344 1381737328 14.432 98 64 128 0 1423968112 26916432 15314352
23:50:19 2599 8192000000 10347706144 10763259904 1407869712 14.665 100 81 164 2 1456283632 28825664 19588256
23:51:26 2180 8192000000 10350369216 10763771904 1403463168 14.626 100 88 174 1 1455560240 30282992 21814080
23:52:33 4726 8192000000 10315993568 10724630528 1362379504 14.259 99 74 149 1 1414247968 33574592 18293872
23:53:41 4416 8192000000 10344628336 10756214784 1390571456 14.511 100 76 152 3 1448620608 38514480 19534672
23:54:49 3889 8192000000 10320719664 10733568000 1370179904 14.329 100 75 150 1 1422069616 33649680 18240032
23:55:56 2538 8192000000 10321681296 10728845312 1370015248 14.327 100 82 164 0 1424728736 34736768 19976720
23:57:04 19384 8192000000 10294862704 10720563200 1359174960 14.230 99 31 62 1 1396606896 28626352 8805584
23:58:26 12160 8192000000 10292262272 10713124864 1356535856 14.206 100 51 103 0 1402206704 32475584 13195264
23:59:58 5205 8192000000 10290431216 10708746240 1342819424 14.083 99 40 79 1 1385373312 32593504 9960384
00:01:09 12961 8192000000 10285612080 10714222592 1349848224 14.146 99 24 48 1 1386933440 30520704 6564512
00:02:27 23359 8192000000 10303872528 10737274880 1366305392 14.294 100 32 64 2 1419768112 44770048 8692672
00:04:06 13917 8192000000 10324430320 10755829760 1361972656 14.255 98 51 100 0 1421889152 47131952 12784544
00:05:21 33247 8192000000 10320667408 10762616832 1382515120 14.439 100 25 50 4 1424925744 35373072 7037552
00:06:55 14869 8192000000 10320834912 10756247552 1371699248 14.342 99 24 49 0 1413209680 34669520 6840912
00:08:23 36366 8192000000 10299648592 10756112384 1365034912 14.283 99 12 24 2 1402550192 34252192 3263088
00:10:04 32368 8192000000 10308613696 10768306176 1358423792 14.223 99 10 20 0 1403972912 42727376 2821744
00:11:46 35900 8192000000 10291519904 10729586688 1374166448 14.364 100 47 95 1 1395229728 10362784 10700496
00:13:23 22291 8192000000 10292657040 10743197696 1378989600 14.408 100 20 41 1 1399313664 15663168 4660896
00:14:50 44986 8192000000 10279704432 10741866496 1367985744 14.309 99 4 8 0 1381249568 12884880 378944
00:16:37 103239 8192000000 10279974528 10727542784 1365374064 14.286 99 28 54 2 1378287584 6223344 6690176
00:19:26 52838 8192000000 10296736176 10750754816 1389692832 14.503 100 27 54 1 1405403856 9676480 6034544
00:21:20 53652 8192000000 10319837584 10760957952 1380956384 14.425 99 41 81 0 1399486000 8771904 9757712
00:23:20 28427 8192000000 10312013984 10760994816 1394382944 14.545 100 27 54 2 1421205968 20148608 6674416
00:24:51 43938 8192000000 10292121072 10753277952 1380893440 14.425 99 19 38 2 1402771856 16840512 5037904
00:26:49 47486 8192000000 10290572896 10748973056 1378375728 14.402 99 30 61 3 1400825024 15277392 7171904
00:28:37 66121 8192000000 10279672576 10755293184 1372816240 14.352 99 8 16 0 1390002128 15626448 1559440
00:30:43 46839 8192000000 10266802800 10721976320 1340278112 14.060 97 15 30 0 1348969936 5318848 3372976
00:32:44 103609 8192000000 10269973696 10744881152 1367543744 14.305 99 19 35 2 1385931872 14323632 4064496
00:35:30 36038 8192000000 10267290336 10729459712 1368322480 14.312 99 16 32 0 1379293200 7302304 3668416
00:37:18 62985 8192000000 10277267040 10736513024 1354509840 14.188 98 19 38 0 1373934736 15412240 4012656
00:39:24 67952 8192000000 10280248288 10741837824 1361528928 14.251 99 21 40 2 1378113520 12088288 4496304
00:41:32 88813 8192000000 10280255376 10747719680 1332410528 13.989 96 20 40 0 1355129552 18450848 4268176
00:44:01 64357 8192000000 10275600032 10728472576 1345478368 14.107 98 24 48 0 1366387280 15653248 5255664
00:46:08 87104 8192000000 10279628112 10725883904 1308703968 13.774 95 18 36 0 1326609216 13771024 4134224
00:48:35 59253 8192000000 10275260816 10724663296 1306542768 13.755 95 16 32 1 1323720896 13622512 3555616
00:50:43 75507 8192000000 10269720816 10717949952 1289708128 13.602 94 25 49 2 1307653312 12483920 5461264
00:52:59 79118 8192000000 10288064832 10736197632 1358280096 14.222 99 25 50 0 1375874640 12226496 5368048
00:55:25 28362 8192000000 10285464608 10739433472 1349355312 14.142 98 36 72 0 1384841888 27528336 7958240
00:57:00 34043 8192000000 10258217136 10711523328 1364266272 14.276 100 17 32 0 1382962400 15052784 3643344
00:59:41 107102 8192000000 10262176192 10719678464 1341633600 14.072 98 10 20 1 1353512768 9596720 2282448
01:02:29 48629 8192000000 10275574832 10744127488 1373140304 14.355 100 15 30 0 1395249856 18516624 3592928
01:04:18 53681 8192000000 10270219584 10734575616 1371508144 14.341 100 7 14 1 1380935456 8111024 1316288
01:06:12 48442 8192000000 10260152368 10714185728 1321707520 13.892 96 25 50 2 1334337536 7048016 5582000
01:08:00 48408 8192000000 10271816144 10731716608 1362585264 14.261 99 23 46 0 1377838224 10242192 5010768
01:09:51 35296 8192000000 10301445680 10756362240 1385031040 14.462 100 25 50 0 1403151856 12153520 5967296
01:11:26 107743 8192000000 10303496288 10758176768 1384500816 14.457 99 13 25 0 1404796896 17538528 2757552
01:14:14 35648 8192000000 10306917184 10757963776 1361228880 14.248 97 27 54 2 1380686176 12680304 6776992
01:15:51 183846 8192000000 10295929600 10756845568 1302251632 13.716 93 18 36 3 1324016992 17878096 3887264
01:19:55 101085 8192000000 10288248912 10743799808 1391455296 14.519 100 32 63 0 1407615344 8440176 7719872
01:22:40 48310 8192000000 10289649504 10744352768 1381198384 14.427 99 19 38 0 1395844256 10571584 4074288
01:24:32 52399 8192000000 10303736848 10761494528 1396637072 14.565 100 17 34 0 1410887680 10319840 3930768
01:26:24 64292 8192000000 10295550496 10751217664 1346835568 14.119 96 15 30 0 1367379504 16810976 3732960
01:28:29 75345 8192000000 10273478832 10743590912 1365808784 14.289 99 18 36 1 1397346640 26970272 4567584
01:30:45 45960 8192000000 10294625056 10747133952 1355871152 14.200 97 21 42 0 1373221616 12372528 4977936
01:32:33 48227 8192000000 10290351728 10753826816 1375014944 14.372 99 9 17 1 1390826128 13450176 2361008
01:34:22 68972 8192000000 10287946736 10738556928 1345257376 14.105 97 10 20 0 1358319520 11018640 2043504
01:36:46 39008 8192000000 10275127264 10730274816 1339844384 14.056 97 14 28 1 1361022656 17923632 3254640

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-12-2021 01:39:00 to 22-12-2021 01:39:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 158 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-12-2021 01:39:16 to 22-12-2021 01:39:18.

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          242735
MON$OLDEST_ACTIVE               242736
MON$OLDEST_SNAPSHOT             242736
MON$NEXT_TRANSACTION            242736
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                       2025120
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33546 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33546 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33546 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33546 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 ---
:261: 
:262: mscs-test-161.budzdorov.ru    Wed Dec 22 01:38:06 2021
:263:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:264: 
--- end of diff output ---

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

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