Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.06.2022 16:43
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 16476448 kB
MemAvailable 31325160 kB
Buffers 0 kB
Cached 15212440 kB
SwapCached 2444 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-06-01 16:42:18
DTS_END                         2022-06-01 16:42:18.7630
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/50040
TRN_ID                          236719
ATT_ID                          663
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: 9223.11 21599 1106773
customer order: creation 167.53 337 20104
customer order: refuse 14.58 341 1750
order to supplier: creation 44.57 1338 5348
order to supplier: removal 3.48 102302 417
invoice (draft): creation 44.51 3883 5341
invoice (draft): removal 51.36 871 6163
invoice accept: apply 41.18 18229 4941
invoice accept: cancel 46.61 815 5593
customer reserve: creation 3871.56 177 464587
customer reserve: removal 4642.78 60 557134
realization accept: apply 83.02 177 9962
realization accept: cancel 85.42 96 10250
payment from customer: creation 57.38 106 6885
payment from customer: removal 5.62 70 674
payment to supplier: creation 47.85 116 5742
payment to supplier: removal 3.36 57 403
service: total inventory turnovers 4.68 1281 562
service: total monetary turnovers 6.88 34 825
service: aggregate perf. data 0.73 27687 88
service: refresh index statistics 0.03 273995 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-06-2022 16:43:26 to 01-06-2022 16:43:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3112
TEST_TIME 2 4132
TEST_TIME 3 5117
TEST_TIME 4 5575
TEST_TIME 5 5742
TEST_TIME 6 5924
TEST_TIME 7 5947
TEST_TIME 8 5992
TEST_TIME 9 6004
TEST_TIME 10 6325
TEST_TIME 11 6289
TEST_TIME 12 6447
TEST_TIME 13 6423
TEST_TIME 14 6783
TEST_TIME 15 6701
TEST_TIME 16 6772
TEST_TIME 17 7336
TEST_TIME 18 7594
TEST_TIME 19 8135
TEST_TIME 20 8378
TEST_TIME 21 8468
TEST_TIME 22 8519
TEST_TIME 23 8817
TEST_TIME 24 9328
TEST_TIME 25 9553
TEST_TIME 26 9559
TEST_TIME 27 10029
TEST_TIME 28 11076
TEST_TIME 29 11003
TEST_TIME 30 11085
TEST_TIME 31 11051
TEST_TIME 32 10882
TEST_TIME 33 10741
TEST_TIME 34 10523
TEST_TIME 35 10536
TEST_TIME 36 10374
TEST_TIME 37 10255
TEST_TIME 38 10153
TEST_TIME 39 10365
TEST_TIME 40 10235
TEST_TIME 41 10038
TEST_TIME 42 10014
TEST_TIME 43 9947
TEST_TIME 44 9866
TEST_TIME 45 9819
TEST_TIME 46 9691
TEST_TIME 47 9571
TEST_TIME 48 9620
TEST_TIME 49 9713
TEST_TIME 50 9617
TEST_TIME 51 9573
TEST_TIME 52 9422
TEST_TIME 53 9349
TEST_TIME 54 9314
TEST_TIME 55 9200
TEST_TIME 56 9121
TEST_TIME 57 9145
TEST_TIME 58 9221
TEST_TIME 59 9246
TEST_TIME 60 9253
TEST_TIME 61 9252
TEST_TIME 62 9301
TEST_TIME 63 9337
TEST_TIME 64 9457
TEST_TIME 65 9396
TEST_TIME 66 9419
TEST_TIME 67 9517
TEST_TIME 68 9533
TEST_TIME 69 9541
TEST_TIME 70 9572
TEST_TIME 71 9538
TEST_TIME 72 9503
TEST_TIME 73 9645
TEST_TIME 74 9535
TEST_TIME 75 9474
TEST_TIME 76 9440
TEST_TIME 77 9415
TEST_TIME 78 9426
TEST_TIME 79 9370
TEST_TIME 80 9349
TEST_TIME 81 9316
TEST_TIME 82 9233
TEST_TIME 83 9240
TEST_TIME 84 9273
TEST_TIME 85 9277
TEST_TIME 86 9251
TEST_TIME 87 9299
TEST_TIME 88 9276
TEST_TIME 89 9298
TEST_TIME 90 9319
TEST_TIME 91 9321
TEST_TIME 92 9317
TEST_TIME 93 9397
TEST_TIME 94 9355
TEST_TIME 95 9419
TEST_TIME 96 9422
TEST_TIME 97 9441
TEST_TIME 98 9480
TEST_TIME 99 9529
TEST_TIME 100 9512
TEST_TIME 101 9449
TEST_TIME 102 9462
TEST_TIME 103 9401
TEST_TIME 104 9396
TEST_TIME 105 9362
TEST_TIME 106 9366
TEST_TIME 107 9345
TEST_TIME 108 9300
TEST_TIME 109 9265
TEST_TIME 110 9271
TEST_TIME 111 9239
TEST_TIME 112 9245
TEST_TIME 113 9214
TEST_TIME 114 9190
TEST_TIME 115 9218
TEST_TIME 116 9245
TEST_TIME 117 9202
TEST_TIME 118 9221
TEST_TIME 119 9288
TEST_TIME 120 9223

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-06-2022 16:43:30 to 01-06-2022 16:43:33.

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 1535976 1535976 0 0.00 0 24574 6 0 0
doc_list_biud 1535976 1535976 0 0.00 0 40124 28 0 0
sp_add_invoice_to_stock 5052 4941 111 2.19 28 211442 18230 0 111
sp_cancel_adding_invoice 5593 5593 0 0.00 4 63800 816 0 0
sp_cancel_client_order 1750 1750 0 0.00 4 11481 341 0 0
sp_cancel_customer_reserve 557134 557134 0 0.00 1 26376 60 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 3 5089 71 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 2 4208 58 0 0
sp_cancel_supplier_invoice 6176 6163 13 0.21 1 27942 871 0 13
sp_cancel_supplier_order 417 417 0 0.00 9 1077449 102303 0 0
sp_cancel_write_off 10250 10250 0 0.00 1 10250 96 0 0
sp_client_order 20104 20104 0 0.00 3 27699 337 0 0
sp_customer_reserve 464587 464587 0 0.00 0 177943 178 0 0
sp_fill_shopping_cart 30772 30772 0 0.00 1 20291 41 0 0
sp_fill_shopping_cart_clo_res 464582 464582 0 0.00 0 18216 12 0 0
sp_get_clo_for_invoice 4936 4936 0 0.00 0 17729 292 0 0
sp_kill_cost_storno 16920 16920 0 0.00 0 5096 50 0 0
sp_kill_qstorno_ret_qs2qd 1131098 1131098 0 0.00 0 27919 33 0 0
sp_kill_qty_storno 596205 596205 0 0.00 0 40122 71 0 0
sp_lock_selected_doc 20637 20637 0 0.00 0 7151 6 0 0
sp_make_cost_storno 27525 27525 0 0.00 0 24564 44 0 0
sp_multiply_rows_for_pdistr 27525 27525 0 0.00 0 15260 27 0 0
sp_multiply_rows_for_qdistr 20104 20104 0 0.00 0 25227 146 0 0
sp_pay_from_customer 6885 6885 0 0.00 4 15335 107 0 0
sp_pay_to_supplier 5742 5742 0 0.00 5 14999 116 0 0
sp_payment_common 12627 12627 0 0.00 1 11352 26 0 0
sp_qd_handle_on_cancel_clo 1750 1750 0 0.00 0 11471 313 0 0
sp_qd_handle_on_invoice_upd_sts 10529 10529 0 0.00 0 40119 338 0 0
sp_qd_handle_on_reserve_upd_sts 9962 9962 0 0.00 0 14384 69 0 0
sp_reserve_write_off 9962 9962 0 0.00 6 14430 178 0 0
sp_supplier_invoice 5341 5341 0 0.00 6 108439 3884 0 0
sp_supplier_order 5348 5348 0 0.00 5 46720 1339 0 0
srv_aggregate_perf_data 88 88 0 0.00 2717 600017 27688 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1623 259600 53259 0 0
srv_find_qd_qs_mism 1059247 1059247 0 0.00 0 13938 5 0 0
srv_make_invnt_saldo 562 562 0 0.00 18 136309 1282 0 0
srv_make_money_saldo 825 825 0 0.00 0 9675 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 144423 465817 273996 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 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 4941 4941 0 0.00 0 16108 172 0 0
t$perf-norm:sp_cancel_adding_invoice 423 423 0 0.00 0 3945 90 0 0
t$perf-norm:sp_cancel_client_order 1750 1750 0 0.00 0 18138 26 0 0
t$perf-norm:sp_cancel_customer_reserve 726 726 0 0.00 0 7257 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 4224 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 454 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 815 16 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 29791 1373 0 0
t$perf-norm:sp_cancel_write_off 963 963 0 0.00 0 5714 17 0 0
t$perf-norm:sp_client_order 20104 20104 0 0.00 0 19979 18 0 0
t$perf-norm:sp_customer_reserve 976 976 0 0.00 0 403 3 0 0
t$perf-norm:sp_pay_from_customer 6885 6885 0 0.00 0 5786 11 0 0
t$perf-norm:sp_pay_to_supplier 5742 5742 0 0.00 0 11742 14 0 0
t$perf-norm:sp_reserve_write_off 9962 9962 0 0.00 0 7714 12 0 0
t$perf-norm:sp_supplier_invoice 5341 5341 0 0.00 0 6093 20 0 0
t$perf-norm:sp_supplier_order 5348 5348 0 0.00 0 4036 15 0 0
t$perf-norm:srv_aggregate_perf_data 88 88 0 0.00 0 585 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 1762 30 0 0
t$perf-norm:srv_make_invnt_saldo 562 562 0 0.00 0 831 23 0 0
t$perf-norm:srv_make_money_saldo 825 825 0 0.00 0 1260 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10092 9870 222 2.19 0 7614 19 0 222
v_all_customers 36668 36668 0 0.00 0 16 1 0 0
v_all_suppliers 29594 29594 0 0.00 0 79 1 0 0
v_all_wares 40208 40208 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 846 846 0 0.00 0 453 6 0 0
v_cancel_client_order 3500 3500 0 0.00 0 5000 6 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 4051 6 0 0
v_cancel_customer_reserve 1452 1452 0 0.00 0 414 3 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 4199 8 0 0
v_cancel_supplier_invoice 794 768 26 3.27 0 7117 23 0 26
v_cancel_supplier_order 834 834 0 0.00 0 514 3 0 0
v_cancel_write_off 1926 1926 0 0.00 0 5205 15 0 0
v_max_id_clo_ord 5348 5348 0 0.00 0 220 1 0 0
v_max_id_clo_res 976 976 0 0.00 0 3304 13 0 0
v_max_id_ord_sup 5320 5320 0 0.00 0 6478 5 0 0
v_max_non_paid_invoice 1613 1613 0 0.00 0 1145 2 0 0
v_max_non_paid_realizn 3797 3797 0 0.00 0 3689 3 0 0
v_min_id_clo_ord 5348 5348 0 0.00 0 5217 10 0 0
v_min_id_clo_res 976 976 0 0.00 0 2040 5 0 0
v_min_id_ord_sup 5320 5320 0 0.00 0 6174 9 0 0
v_min_non_paid_invoice 5742 5742 0 0.00 0 11347 22 0 0
v_min_non_paid_realizn 6885 6885 0 0.00 0 9528 17 0 0
v_our_firm 9716 9716 0 0.00 0 18 1 0 0
v_reserve_write_off 19924 19924 0 0.00 0 8377 6 0 0
x_lock_depdocs_on_canc_invoice 11372 11372 0 0.00 0 28016 128 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 7915 372 0 0
x_make_qstorno_1000_1200 5348 5348 0 0.00 2 45020 1259 0 0
x_make_qstorno_1200_2000 5341 5341 0 0.00 2 103704 3718 0 0
x_make_qstorno_2100_3300 464587 464587 0 0.00 0 177943 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-06-2022 16:43:34 to 01-06-2022 16:43:38.

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:12:22 22 8192000000 8803759264 8816726016 8756224 0.106 3 1 2 0 8855856 56848 42784
14:13:23 34366 8192000000 9893312896 10199019520 972685824 10.613 100 90 180 0 1007042720 10625328 23731568
14:15:04 20639 8192000000 9930993840 10250280960 1013531840 11.010 100 85 170 0 1043703120 8266448 21904832
14:16:29 12213 8192000000 9974535104 10308386816 1058012016 11.437 100 80 160 0 1087119392 9477568 19629808
14:17:45 14866 8192000000 10037922288 10387607552 1122416224 12.050 100 75 150 0 1151033536 11038784 17578528
14:19:03 10917 8192000000 10083475472 10440916992 1169419024 12.491 100 83 166 1 1202246496 13943504 18883968
14:20:16 6404 8192000000 10117402432 10491486208 1215488672 12.920 100 71 141 1 1242624256 11835808 15299776
14:21:25 1578 8192000000 10169301168 10541285376 1249495184 13.234 100 88 174 0 1281982000 13395792 19091024
14:22:28 1335 8192000000 10199413616 10579136512 1285896768 13.567 100 93 187 1 1320092320 13030192 21165360
14:23:31 1881 8192000000 10221054992 10602921984 1300871616 13.703 100 87 172 3 1331490432 10911568 19707248
14:24:33 3509 8192000000 10199608176 10600370176 1308188464 13.770 100 56 112 1 1328964640 8448496 12327680
14:25:40 15058 8192000000 10190742848 10606182400 1310937360 13.795 100 13 26 0 1320416464 5818800 3660304
14:26:56 19152 8192000000 10221998320 10627907584 1317566224 13.855 100 31 62 1 1331033824 6190112 7277488
14:28:16 27316 8192000000 10230517088 10640506880 1324681008 13.919 100 36 73 1 1339836032 7021024 8134000
14:29:44 6977 8192000000 10220260048 10641371136 1330928416 13.976 100 22 44 1 1342034208 6221952 4883840
14:30:53 4861 8192000000 10209740096 10632364032 1323608624 13.909 100 23 46 2 1334864624 6169872 5086128
14:32:03 11794 8192000000 10190125408 10606546944 1258862448 13.320 95 5 10 1 1264813392 4914464 1036480
14:33:16 11573 8192000000 10199184672 10619703296 1311516496 13.800 100 44 86 4 1326927856 5082160 10329200
14:34:32 3255 8192000000 10152436768 10553409536 1217087008 12.935 95 29 58 0 1230182208 6089856 7005344
14:35:38 7793 8192000000 10144770832 10537517056 1191443664 12.697 95 39 78 0 1206372128 6077936 8850528
14:36:46 9563 8192000000 10160134928 10560970752 1255148304 13.286 99 28 55 1 1267245392 5387424 6709664
14:37:56 18424 8192000000 10168774256 10579087360 1267988288 13.403 99 32 61 3 1281171216 6138384 7044544
14:39:15 6703 8192000000 10196524208 10599202816 1292148528 13.624 100 37 72 1 1306913872 6607984 8157360
14:40:26 11893 8192000000 10192631904 10605379584 1299312896 13.689 100 17 34 0 1308670944 5483024 3875024
14:41:39 101096 8192000000 10228261136 10635976704 1319459680 13.872 100 55 110 1 1338229232 5328768 13440784
14:44:41 30211 8192000000 10262194032 10680852480 1356298544 14.204 100 55 110 1 1381788944 12953472 12536928
14:46:19 9401 8192000000 10286240416 10706984960 1370648240 14.333 100 51 101 0 1399921504 17662000 11611264
14:47:32 17998 8192000000 10295130560 10719092736 1380904944 14.425 100 53 106 0 1407851072 14449136 12496992
14:48:57 11790 8192000000 10310202912 10732306432 1388430704 14.492 100 55 110 1 1415194608 13859984 12903920
14:50:13 4416 8192000000 10318619936 10744111104 1403869024 14.629 100 59 117 2 1432850592 14622496 14359072
14:51:21 8669 8192000000 10337097840 10759270400 1412568912 14.707 100 62 122 2 1446067296 19257232 14241152
14:52:33 5925 8192000000 10337988816 10765500416 1417664416 14.752 100 57 115 0 1448795680 16981328 14149936
14:53:45 5778 8192000000 10338740992 10767884288 1416947872 14.746 100 56 111 1 1454452752 23086736 14418144
14:54:56 10489 8192000000 10343326000 10773090304 1413757152 14.717 100 44 87 2 1454670688 29528704 11384832
14:56:09 1622 8192000000 10375662816 10795028480 1425422768 14.821 100 75 148 0 1479693200 35588016 18682416
14:57:14 4508 8192000000 10367664912 10788982784 1422736160 14.797 100 65 129 3 1472684336 34011536 15936640
14:58:25 4847 8192000000 10346153840 10754101248 1383724528 14.450 100 80 159 1 1449409824 45354880 20330416
14:59:35 3222 8192000000 10369672160 10778636288 1400170352 14.597 100 89 179 1 1474645920 51191824 23283744
15:00:42 2958 8192000000 10368616704 10780348416 1401755120 14.611 100 84 165 1 1469883488 47307328 20821040
15:01:50 21427 8192000000 10333231968 10754510848 1362701776 14.262 98 38 76 1 1416504688 42902000 10900912
15:03:22 33956 8192000000 10323675824 10753339392 1351154160 14.158 97 27 54 1 1405615168 46846816 7614192
15:05:06 9501 8192000000 10353322528 10781995008 1387941920 14.487 100 48 96 0 1456776224 55020192 13814112
15:06:17 13937 8192000000 10319066800 10750418944 1354730848 14.190 99 33 66 2 1413048144 48889776 9427520
15:07:35 20692 8192000000 10309651280 10741739520 1355191120 14.194 99 29 58 1 1411982912 48423824 8367968
15:09:14 21975 8192000000 10299770432 10742083584 1320614256 13.882 98 24 48 0 1391636896 63972816 7049824
15:10:44 80607 8192000000 10271242016 10700304384 1322193696 13.897 99 17 34 2 1358102208 31646928 4261584
15:13:07 62951 8192000000 10259042704 10705338368 1343706144 14.091 99 15 29 0 1358790320 11470352 3613824
15:15:15 259600 8192000000 10283561264 10730078208 1286882560 13.576 93 19 38 1 1297376976 6851712 3642704
15:20:34 124690 8192000000 10279729216 10737258496 1369195200 14.320 99 16 32 0 1383584064 11247008 3141856
15:24:17 135609 8192000000 10290761984 10740514816 1338833440 14.047 96 19 38 0 1356896352 14023840 4039072
15:27:38 99237 8192000000 10294749216 10745282560 1374842400 14.370 99 19 37 2 1392393136 13530624 4020112
15:30:19 41877 8192000000 10309887088 10753589248 1394310496 14.544 100 28 56 0 1410748192 9614000 6823696
15:32:02 59170 8192000000 10289077920 10747363328 1381234464 14.428 99 21 42 1 1393515216 7486416 4794336
15:34:05 63804 8192000000 10294683216 10761392128 1397127920 14.569 100 15 30 0 1408105120 7420512 3556688
15:36:16 48384 8192000000 10273283200 10740592640 1363294096 14.267 98 5 10 1 1372773024 8353680 1125248
15:38:30 72679 8192000000 10306942176 10774405120 1386451776 14.474 99 13 26 0 1406221152 17105424 2663952
15:40:49 86103 8192000000 10298975376 10759450624 1373371840 14.357 98 13 26 0 1389119904 12626928 3121136
15:43:28 6977 8192000000 10306844240 10758545408 1387873504 14.487 99 31 61 1 1401267744 6913616 6480624
15:44:39 95084 8192000000 10297976192 10757005312 1352762624 14.172 97 14 28 1 1371894000 16305504 2825872
15:47:22 15616 8192000000 10328313808 10770411520 1403279808 14.624 100 64 128 1 1430239952 12963008 13997136
15:48:38 57153 8192000000 10303884704 10767892480 1396904544 14.567 100 25 50 1 1420268528 17551952 5812032
15:50:43 38797 8192000000 10293668000 10746159104 1358381568 14.223 98 30 61 1 1376621856 11548512 6691776
15:52:24 43261 8192000000 10279177360 10728882176 1330392464 13.971 96 36 72 0 1353592640 15232096 7968080
15:54:08 69522 8192000000 10253380784 10692640768 1245887296 13.200 92 25 50 1 1270279744 18012688 6379760
15:56:24 104769 8192000000 10228245952 10664562688 1268035712 13.404 94 25 50 0 1281624880 8198880 5390288
15:59:37 46307 8192000000 10224849936 10675388416 1301484320 13.709 97 24 49 0 1317736176 10680864 5570992
16:01:24 74769 8192000000 10224038384 10681806848 1299164736 13.688 97 8 16 1 1310788256 9731136 1892384
16:03:41 105514 8192000000 10227052240 10677354496 1301650528 13.710 96 20 41 0 1321194880 14154256 5390096
16:06:55 66133 8192000000 10222609584 10681905152 1310842224 13.794 98 13 26 0 1322180304 8397520 2940560
16:09:01 61822 8192000000 10240693024 10686562304 1298133248 13.678 96 20 40 0 1313703872 10836512 4734112
16:11:03 41321 8192000000 10269547696 10718261248 1357021632 14.211 100 25 50 0 1374599072 12090224 5487216
16:12:48 33431 8192000000 10279351232 10717863936 1358794256 14.227 99 24 48 0 1371872832 8178272 4900304
16:14:22 36505 8192000000 10299919984 10743463936 1383614816 14.449 100 37 74 1 1402369408 10615712 8138880
16:16:01 62957 8192000000 10314218592 10755436544 1366227392 14.293 98 27 54 0 1382562896 10632064 5703440
16:18:04 108590 8192000000 10312885440 10769981440 1371333712 14.339 98 15 30 0 1386290368 11932224 3024432
16:21:01 113255 8192000000 10302408272 10770821120 1380099104 14.417 99 8 16 0 1403671136 21971072 1600960
16:23:56 59920 8192000000 10288650048 10752172032 1362990944 14.264 98 19 38 2 1379498928 10797936 5710048
16:25:57 52455 8192000000 10302595456 10769580032 1396074576 14.560 100 14 28 1 1412680320 13347776 3257968
16:27:50 49896 8192000000 10288650336 10758234112 1389468800 14.501 99 9 18 0 1406271584 14903952 1898832
16:29:40 89310 8192000000 10316369680 10775367680 1395037600 14.551 99 10 19 0 1405259712 8151008 2071104
16:32:24 75700 8192000000 10297987760 10763440128 1377840720 14.397 98 18 36 0 1389210064 7505168 3864176
16:34:54 95127 8192000000 10300037664 10769735680 1383907872 14.451 98 8 16 0 1399846176 14416144 1522160
16:37:40 74251 8192000000 10286719744 10755280896 1362804880 14.263 97 11 22 0 1376369568 10921408 2643280
16:39:56 68889 8192000000 10305480384 10755944448 1363721088 14.271 98 22 44 1 1386390848 17054944 5614816
16:42:08 22265 8192000000 9092252400 9326727168 255216304 3.021 19 3 6 0 262663360 7051808 395248

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-06-2022 16:43:40 to 01-06-2022 16:43:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 111 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 01-06-2022 16:43:56 to 01-06-2022 16:43:58.

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          237027
MON$OLDEST_ACTIVE               237028
MON$OLDEST_SNAPSHOT             237028
MON$NEXT_TRANSACTION            237028
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                       2019200
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.33598 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33598 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33598 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33598 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Wed Jun  1 16:42:35 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
--- end of diff output ---

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

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