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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16258592 kB
MemAvailable 31114300 kB
Buffers 0 kB
Cached 15432340 kB
SwapCached 2516 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-07-07 16:38:30
DTS_END                         2022-07-07 16:38:30.6570
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35840
TRN_ID                          243929
ATT_ID                          679
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: 9092.64 21270 1091117
customer order: creation 175.52 368 21062
customer order: refuse 14.74 377 1769
order to supplier: creation 46.73 1353 5607
order to supplier: removal 3.48 109798 418
invoice (draft): creation 46.43 3677 5571
invoice (draft): removal 52.38 851 6286
invoice accept: apply 41.43 16872 4971
invoice accept: cancel 47.13 854 5656
customer reserve: creation 3745.59 170 449471
customer reserve: removal 4626.81 66 555217
realization accept: apply 82.88 168 9946
realization accept: cancel 80.43 110 9651
payment from customer: creation 58.39 102 7007
payment from customer: removal 5.83 83 700
payment to supplier: creation 48.86 140 5863
payment to supplier: removal 3.50 56 420
service: total inventory turnovers 4.76 1053 571
service: total monetary turnovers 6.83 26 819
service: aggregate perf. data 0.90 20017 108
service: refresh index statistics 0.03 269249 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-07-2022 16:39:38 to 07-07-2022 16:39:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1760
TEST_TIME 2 3886
TEST_TIME 3 4343
TEST_TIME 4 4510
TEST_TIME 5 4993
TEST_TIME 6 4875
TEST_TIME 7 5103
TEST_TIME 8 5201
TEST_TIME 9 5395
TEST_TIME 10 5638
TEST_TIME 11 5785
TEST_TIME 12 5940
TEST_TIME 13 6102
TEST_TIME 14 6364
TEST_TIME 15 6409
TEST_TIME 16 6380
TEST_TIME 17 6441
TEST_TIME 18 6470
TEST_TIME 19 6566
TEST_TIME 20 6936
TEST_TIME 21 7249
TEST_TIME 22 7934
TEST_TIME 23 8087
TEST_TIME 24 8557
TEST_TIME 25 8861
TEST_TIME 26 9036
TEST_TIME 27 10517
TEST_TIME 28 10512
TEST_TIME 29 10523
TEST_TIME 30 10184
TEST_TIME 31 10000
TEST_TIME 32 9893
TEST_TIME 33 9868
TEST_TIME 34 9894
TEST_TIME 35 9781
TEST_TIME 36 9740
TEST_TIME 37 9593
TEST_TIME 38 9447
TEST_TIME 39 9514
TEST_TIME 40 9487
TEST_TIME 41 9662
TEST_TIME 42 9468
TEST_TIME 43 9432
TEST_TIME 44 9432
TEST_TIME 45 9305
TEST_TIME 46 9258
TEST_TIME 47 9112
TEST_TIME 48 9331
TEST_TIME 49 9279
TEST_TIME 50 9322
TEST_TIME 51 9268
TEST_TIME 52 9306
TEST_TIME 53 9334
TEST_TIME 54 9278
TEST_TIME 55 9360
TEST_TIME 56 9387
TEST_TIME 57 9337
TEST_TIME 58 9376
TEST_TIME 59 9397
TEST_TIME 60 9462
TEST_TIME 61 9533
TEST_TIME 62 9397
TEST_TIME 63 9335
TEST_TIME 64 9287
TEST_TIME 65 9261
TEST_TIME 66 9215
TEST_TIME 67 9184
TEST_TIME 68 9133
TEST_TIME 69 9139
TEST_TIME 70 9145
TEST_TIME 71 9114
TEST_TIME 72 9077
TEST_TIME 73 9160
TEST_TIME 74 9146
TEST_TIME 75 9277
TEST_TIME 76 9273
TEST_TIME 77 9257
TEST_TIME 78 9266
TEST_TIME 79 9267
TEST_TIME 80 9355
TEST_TIME 81 9339
TEST_TIME 82 9243
TEST_TIME 83 9241
TEST_TIME 84 9222
TEST_TIME 85 9233
TEST_TIME 86 9183
TEST_TIME 87 9132
TEST_TIME 88 9112
TEST_TIME 89 9052
TEST_TIME 90 9039
TEST_TIME 91 9004
TEST_TIME 92 9106
TEST_TIME 93 9091
TEST_TIME 94 9078
TEST_TIME 95 9179
TEST_TIME 96 9190
TEST_TIME 97 9217
TEST_TIME 98 9296
TEST_TIME 99 9249
TEST_TIME 100 9315
TEST_TIME 101 9352
TEST_TIME 102 9325
TEST_TIME 103 9335
TEST_TIME 104 9385
TEST_TIME 105 9379
TEST_TIME 106 9295
TEST_TIME 107 9288
TEST_TIME 108 9262
TEST_TIME 109 9237
TEST_TIME 110 9167
TEST_TIME 111 9185
TEST_TIME 112 9169
TEST_TIME 113 9126
TEST_TIME 114 9100
TEST_TIME 115 9099
TEST_TIME 116 9064
TEST_TIME 117 9042
TEST_TIME 118 9128
TEST_TIME 119 9139
TEST_TIME 120 9094

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-07-2022 16:39:42 to 07-07-2022 16:39:45.

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 1510554 1510554 0 0.00 0 21568 6 0 0
doc_list_biud 1510554 1510554 0 0.00 0 42205 30 0 0
sp_add_invoice_to_stock 5077 4971 106 2.08 59 150906 16873 0 106
sp_cancel_adding_invoice 5656 5656 0 0.00 4 79194 854 0 0
sp_cancel_client_order 1769 1769 0 0.00 4 14973 378 0 0
sp_cancel_customer_reserve 555217 555217 0 0.00 0 42469 66 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 2 7495 84 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 2 1289 57 0 0
sp_cancel_supplier_invoice 6293 6286 7 0.11 1 19149 852 0 7
sp_cancel_supplier_order 418 418 0 0.00 6 1132310 109798 0 0
sp_cancel_write_off 9651 9651 0 0.00 1 21761 110 0 0
sp_client_order 21062 21062 0 0.00 3 22198 368 0 0
sp_customer_reserve 449471 449471 0 0.00 0 50260 170 0 0
sp_fill_shopping_cart 32229 32229 0 0.00 1 21334 44 0 0
sp_fill_shopping_cart_clo_res 449464 449464 0 0.00 0 17345 14 0 0
sp_get_clo_for_invoice 4962 4962 0 0.00 0 26932 303 0 0
sp_kill_cost_storno 16427 16427 0 0.00 0 9697 52 0 0
sp_kill_qstorno_ret_qs2qd 1126789 1126789 0 0.00 0 41112 36 0 0
sp_kill_qty_storno 593905 593905 0 0.00 0 42203 76 0 0
sp_lock_selected_doc 20834 20834 0 0.00 0 7977 4 0 0
sp_make_cost_storno 27778 27778 0 0.00 0 21210 49 0 0
sp_multiply_rows_for_pdistr 27778 27778 0 0.00 0 10353 26 0 0
sp_multiply_rows_for_qdistr 21062 21062 0 0.00 0 16557 179 0 0
sp_pay_from_customer 7007 7007 0 0.00 4 10762 102 0 0
sp_pay_to_supplier 5863 5863 0 0.00 4 16213 140 0 0
sp_payment_common 12870 12870 0 0.00 1 9803 27 0 0
sp_qd_handle_on_cancel_clo 1769 1769 0 0.00 0 14965 343 0 0
sp_qd_handle_on_invoice_upd_sts 10618 10618 0 0.00 0 26448 325 0 0
sp_qd_handle_on_reserve_upd_sts 9946 9946 0 0.00 0 13340 57 0 0
sp_reserve_write_off 9946 9946 0 0.00 6 16509 168 0 0
sp_supplier_invoice 5571 5571 0 0.00 6 107726 3678 0 0
sp_supplier_order 5607 5607 0 0.00 5 44718 1353 0 0
srv_aggregate_perf_data 108 108 0 0.00 2807 311108 20017 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 684 112675 43733 0 0
srv_find_qd_qs_mism 1045240 1045240 0 0.00 0 21567 5 0 0
srv_make_invnt_saldo 571 571 0 0.00 41 36973 1053 0 0
srv_make_money_saldo 819 819 0 0.00 0 1195 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 166457 386566 269249 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 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 4971 4971 0 0.00 0 22877 199 0 0
t$perf-norm:sp_cancel_adding_invoice 459 459 0 0.00 0 8251 129 0 0
t$perf-norm:sp_cancel_client_order 1769 1769 0 0.00 0 3717 22 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 4509 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 7643 43 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 2564 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 392 10 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 35391 1747 0 0
t$perf-norm:sp_cancel_write_off 1038 1038 0 0.00 0 1254 5 0 0
t$perf-norm:sp_client_order 21062 21062 0 0.00 0 10366 20 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 21064 42 0 0
t$perf-norm:sp_pay_from_customer 7007 7007 0 0.00 0 8491 10 0 0
t$perf-norm:sp_pay_to_supplier 5863 5863 0 0.00 0 6251 12 0 0
t$perf-norm:sp_reserve_write_off 9946 9946 0 0.00 0 7623 15 0 0
t$perf-norm:sp_supplier_invoice 5571 5571 0 0.00 0 7587 23 0 0
t$perf-norm:sp_supplier_order 5608 5608 0 0.00 0 21330 22 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 198 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 2756 43 0 0
t$perf-norm:srv_make_invnt_saldo 571 571 0 0.00 0 2617 27 0 0
t$perf-norm:srv_make_money_saldo 819 819 0 0.00 0 3436 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 48 14 0 0
v_add_invoice_to_stock 10136 9924 212 2.09 0 4913 17 0 212
v_all_customers 38112 38112 0 0.00 0 20 1 0 0
v_all_suppliers 30766 30766 0 0.00 0 16 1 0 0
v_all_wares 42124 42124 0 0.00 0 28 1 0 0
v_cancel_adding_invoice 918 918 0 0.00 0 4029 9 0 0
v_cancel_client_order 3538 3538 0 0.00 0 4758 11 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 6935 11 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 1919 5 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 111 2 0 0
v_cancel_supplier_invoice 866 852 14 1.61 0 1295 14 0 14
v_cancel_supplier_order 836 836 0 0.00 0 1249 6 0 0
v_cancel_write_off 2076 2076 0 0.00 0 2297 14 0 0
v_max_id_clo_ord 5605 5605 0 0.00 0 672 1 0 0
v_max_id_clo_res 1014 1014 0 0.00 0 5693 14 0 0
v_max_id_ord_sup 5562 5562 0 0.00 0 7054 6 0 0
v_max_non_paid_invoice 1647 1647 0 0.00 0 587 3 0 0
v_max_non_paid_realizn 3791 3791 0 0.00 0 1914 3 0 0
v_min_id_clo_ord 5605 5605 0 0.00 0 3491 8 0 0
v_min_id_clo_res 1014 1014 0 0.00 0 7646 21 0 0
v_min_id_ord_sup 5562 5562 0 0.00 0 8254 11 0 0
v_min_non_paid_invoice 5863 5863 0 0.00 0 9796 23 0 0
v_min_non_paid_realizn 7007 7007 0 0.00 0 9745 19 0 0
v_our_firm 10444 10444 0 0.00 0 15 1 0 0
v_reserve_write_off 19892 19892 0 0.00 0 9575 9 0 0
x_lock_depdocs_on_canc_invoice 11516 11516 0 0.00 0 12193 115 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 15341 401 0 0
x_make_qstorno_1000_1200 5607 5607 0 0.00 2 44713 1276 0 0
x_make_qstorno_1200_2000 5571 5571 0 0.00 3 106201 3494 0 0
x_make_qstorno_2100_3300 449471 449471 0 0.00 0 50259 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-07-2022 16:39:46 to 07-07-2022 16:39:50.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:34 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:35 20286 8192000000 9895833824 10206728192 982447216 10.708 100 83 167 0 1009850032 5945632 21457184
14:11:06 22491 8192000000 9950992144 10274619392 1032159504 11.189 100 85 170 0 1062057184 8057632 21840048
14:12:40 19137 8192000000 9991083424 10327511040 1072380592 11.575 100 74 148 0 1100753072 9641904 18730576
14:14:08 13106 8192000000 10049187632 10399490048 1130367072 12.125 100 81 162 0 1161212160 11443088 19402000
14:15:24 3147 8192000000 10083318912 10447192064 1178040928 12.572 100 75 148 0 1208229520 13172512 17016080
14:16:31 3325 8192000000 10126891312 10498424832 1223477184 12.994 100 76 152 1 1252980176 13029632 16473360
14:17:36 1593 8192000000 10169394112 10547310592 1254720544 13.282 100 85 169 0 1291786128 17585568 19480016
14:18:40 1460 8192000000 10196292928 10579181568 1282504272 13.536 100 80 158 0 1313803040 13056848 18241920
14:19:42 24728 8192000000 10199902640 10592645120 1296482832 13.663 100 70 138 0 1322031488 9950928 15597728
14:21:08 5765 8192000000 10187909312 10597036032 1301301776 13.707 100 28 56 1 1313563552 6176832 6084944
14:22:20 8595 8192000000 10201083008 10617434112 1316895312 13.849 100 25 50 0 1329946784 7409008 5642464
14:23:30 9006 8192000000 10211924048 10628530176 1325492736 13.926 100 17 35 1 1335660128 6067968 4099424
14:24:40 13877 8192000000 10240064304 10645749760 1328057424 13.950 100 65 128 0 1349283072 6756832 14468816
14:25:54 9237 8192000000 10215618032 10638880768 1317415376 13.853 99 22 44 2 1327500816 5175328 4910112
14:27:09 8772 8192000000 10237763616 10653888512 1335679984 14.018 100 24 48 1 1351646928 9836064 6130880
14:28:19 31807 8192000000 10194174512 10604883968 1241569040 13.161 94 26 52 2 1252811680 5511264 5731376
14:30:16 4710 8192000000 10146294656 10543853568 1225814560 13.015 97 26 53 1 1246438928 15072800 5551568
14:31:23 6661 8192000000 10151357120 10555686912 1238917760 13.136 98 14 26 0 1247023872 5046032 3060080
14:32:31 14810 8192000000 10158556592 10564493312 1256046208 13.294 99 18 36 1 1265656336 5535136 4074992
14:33:49 41933 8192000000 10179776752 10588827648 1287308704 13.580 100 40 79 1 1302650912 5991376 9350832
14:35:34 4884 8192000000 10194581568 10605969408 1299043824 13.687 100 35 70 0 1313349824 5549136 8756864
14:36:39 9802 8192000000 10199594096 10613207040 1302337664 13.716 100 17 35 0 1311452240 5277552 3837024
14:37:49 16791 8192000000 10207901040 10619707392 1309402480 13.781 100 8 16 0 1316678368 5523536 1752352
14:39:08 58700 8192000000 10224803008 10638344192 1320358400 13.880 99 38 74 2 1337380160 8222912 8798848
14:41:11 5008 8192000000 10252573360 10672709632 1352528560 14.170 100 45 90 0 1374018768 11203712 10286496
14:42:22 15141 8192000000 10278250672 10690830336 1363866400 14.272 100 51 102 0 1388550416 12982416 11701600
14:43:41 16750 8192000000 10280380480 10706694144 1376269488 14.383 100 39 77 0 1398933104 12789920 9873696
14:45:01 21884 8192000000 10300212000 10728112128 1387743280 14.486 100 42 84 0 1415336720 17508656 10084784
14:46:27 18792 8192000000 10317302288 10738102272 1393025392 14.533 100 55 110 1 1424364304 17792944 13545968
14:47:51 3272 8192000000 10328728656 10744655872 1396903584 14.567 100 68 133 0 1435388912 22102208 16383120
14:48:59 4441 8192000000 10323104304 10739646464 1389986816 14.506 100 63 125 3 1431758400 26437760 15333824
14:50:07 7416 8192000000 10327576368 10748637184 1397253392 14.571 100 53 105 0 1435464480 25382320 12828768
14:51:21 1697 8192000000 10326778432 10735001600 1382562496 14.439 100 78 153 2 1431938176 29121824 20253856
14:52:27 681 8192000000 10334625280 10743861248 1388681408 14.494 99 84 158 2 1439161664 31258880 19221376
14:53:32 1371 8192000000 10350278096 10763718656 1399249344 14.588 100 80 153 2 1449657840 31600816 18807680
14:54:40 8123 8192000000 10329315584 10733109248 1367311072 14.303 99 74 149 1 1425174064 39732240 18130752
14:55:55 4653 8192000000 10354309424 10760835072 1391064912 14.515 100 73 144 1 1457813872 48568160 18180800
14:57:06 3763 8192000000 10361975872 10772148224 1400090816 14.596 100 69 137 1 1465671552 48051472 17529264
14:58:16 1688 8192000000 10362731936 10763739136 1384392880 14.456 100 87 175 3 1461984272 53776720 23814672
14:59:22 2341 8192000000 10380369456 10786988032 1399454032 14.590 100 85 170 1 1483511840 61177744 22880064
15:00:28 7362 8192000000 10361931824 10789683200 1406429632 14.652 100 44 88 0 1468305584 49221200 12654752
15:02:11 3526 8192000000 10361883632 10785669120 1404403120 14.634 100 76 149 1 1468600512 44600016 19597376
15:03:18 19556 8192000000 10332709088 10767220736 1391691776 14.521 100 28 56 0 1439841760 39914320 8235664
15:04:38 29376 8192000000 10312014816 10757173248 1356698992 14.208 99 30 60 0 1414570656 48469152 9402512
15:06:10 41997 8192000000 10291579184 10743103488 1352117504 14.167 98 11 22 0 1377402688 22598208 2686976
15:08:02 32135 8192000000 10292282000 10731593728 1376834368 14.388 100 53 103 0 1393829648 5333296 11661984
15:09:53 49467 8192000000 10300368928 10751078400 1384923008 14.461 99 28 57 1 1397800096 6668448 6208640
15:11:46 39567 8192000000 10285210928 10738442240 1376737216 14.387 99 15 29 0 1388029280 8142064 3150000
15:13:27 88228 8192000000 10295095856 10754830336 1381997536 14.434 99 21 42 0 1395889056 8718576 5172944
15:15:59 52227 8192000000 10303154048 10758410240 1392181808 14.525 99 11 21 1 1404553152 9380576 2990768
15:17:52 68520 8192000000 10307197728 10771177472 1383720112 14.450 98 23 46 0 1401601568 12452144 5429312
15:20:11 85672 8192000000 10296711136 10757844992 1373568864 14.359 98 13 26 0 1385308064 8834320 2904880
15:22:52 32536 8192000000 10299399712 10755547136 1392222016 14.526 99 33 62 1 1408736208 9314128 7200064
15:25:10 104560 8192000000 10281969808 10757988352 1367334912 14.303 98 12 24 0 1388742224 18099440 3307872
15:27:56 62244 8192000000 10300046416 10767712256 1399013808 14.586 100 15 30 0 1413151840 10593920 3544112
15:30:06 56145 8192000000 10288726720 10752786432 1340335872 14.060 95 17 34 0 1355988080 11822288 3829920
15:32:03 31152 8192000000 10303824512 10746830848 1377288096 14.392 99 28 53 2 1392608736 9439872 5880768
15:33:49 73716 8192000000 10300669680 10761342976 1369959072 14.327 98 11 22 0 1383851984 11377056 2515856
15:36:05 19309 8192000000 10286216080 10729811968 1357620240 14.216 99 37 74 0 1379165392 13078304 8466848
15:37:27 51923 8192000000 10275534672 10728554496 1345246048 14.105 98 15 30 0 1361431296 13140736 3044512
15:39:24 82471 8192000000 10274413024 10729803776 1358340384 14.222 99 16 32 0 1375525792 13838592 3346816
15:42:08 27174 8192000000 10275219760 10730143744 1370431136 14.331 100 29 52 1 1383253616 6680784 6141696
15:43:37 49372 8192000000 10247577680 10706223104 1354930096 14.192 100 9 17 0 1365477536 8789728 1757712
15:45:28 10696 8192000000 10230688448 10696548352 1340539696 14.062 99 10 21 0 1350272672 7948400 1784576
15:46:39 85335 8192000000 10272237776 10733629440 1368170128 14.311 100 11 22 0 1383349680 12694832 2484720
15:49:08 61282 8192000000 10260769008 10712784896 1310292048 13.789 95 17 32 0 1324635136 10558688 3784400
15:51:10 51740 8192000000 10284110496 10736918528 1369425168 14.322 100 20 38 0 1394138096 20173200 4539728
15:53:06 86910 8192000000 10271279728 10726285312 1333966112 14.003 97 10 20 0 1351198176 14887296 2344768
15:55:37 79310 8192000000 10264500512 10717270016 1311715904 13.802 96 15 30 0 1323300192 8470544 3113744
15:57:57 44565 8192000000 10262605920 10718806016 1341099904 14.067 98 20 40 0 1360349488 14742224 4507360
15:59:51 107744 8192000000 10280053120 10727419904 1350378736 14.151 98 34 68 0 1370050112 11447184 8224192
16:02:56 46956 8192000000 10271772640 10742407168 1377598288 14.395 100 9 18 0 1391996208 12430768 1967152
16:04:46 58209 8192000000 10271512848 10733391872 1369998560 14.327 99 13 24 3 1381868896 8628016 3242320
16:06:44 52267 8192000000 10249008944 10705649664 1343416432 14.088 98 11 22 0 1353441296 7141952 2882912
16:08:40 45333 8192000000 10287033760 10740248576 1382047152 14.435 100 9 18 0 1394447728 10597728 1802848
16:10:25 70081 8192000000 10294623920 10750664704 1366213296 14.293 98 12 25 0 1384240128 15911328 2115504
16:12:36 72747 8192000000 10307403600 10775433216 1393488656 14.537 99 14 28 0 1412363744 15424736 3450352
16:15:18 47104 8192000000 10305024144 10759757824 1361367792 14.250 97 36 71 1 1386476464 16965504 8143168
16:17:13 31894 8192000000 10306471744 10744553472 1369825168 14.325 99 44 89 0 1391733776 11925632 9982976
16:18:46 58353 8192000000 10285803424 10754068480 1370825104 14.334 99 21 42 0 1390563024 15126896 4611024
16:20:44 11611 8192000000 10294952784 10729873408 1356164192 14.203 98 41 81 2 1372193248 6894400 9134656
16:21:57 112675 8192000000 10288928848 10761449472 1361000000 14.246 98 18 36 0 1379435200 14459376 3975824
16:25:22 60083 8192000000 10289231344 10762010624 1389138384 14.498 100 15 30 0 1406102000 13487168 3476448
16:27:31 72124 8192000000 10254924784 10706272256 1341716880 14.073 98 6 12 0 1352004512 9053744 1233888
16:29:43 74797 8192000000 10267742512 10729033728 1372254336 14.347 100 7 14 0 1385006384 11255792 1496256
16:32:05 77587 8192000000 10258272880 10708877312 1302779808 13.721 95 13 26 0 1313066080 7694816 2591456
16:34:26 55700 8192000000 10285911472 10734899200 1353951792 14.183 98 21 42 0 1371417904 12160592 5305520
16:36:22 83467 8192000000 10281777136 10746245120 1362067280 14.256 99 21 42 0 1380926992 13914080 4945632

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 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 07-07-2022 16:40:08 to 07-07-2022 16:40:10.

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          241564
MON$OLDEST_ACTIVE               244061
MON$OLDEST_SNAPSHOT             244061
MON$NEXT_TRANSACTION            244061
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                       2015808
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33608 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33608 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33608 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 ---
:80: 
:81: mscs-test-161.budzdorov.ru    Thu Jul  7 16:38:34 2022
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:83: 
:84: 
:85: mscs-test-161.budzdorov.ru    Thu Jul  7 16:38:38 2022
:86:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:87: 
--- end of diff output ---

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

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